builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0052 starttime: 1490366862.08 results: success (0) buildid: 20170324062518 builduid: b0aaae32dd1e40c0a7c7193a94de07e0 revision: 4c987b7ed54a630a7de76adcc2eb00dab49d5dfd ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-03-24 07:47:42.076404) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-03-24 07:47:42.076865) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-24 07:47:42.077139) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-24 07:47:42.104066) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-24 07:47:42.104425) ========= 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.1v0VTUJrq7/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.7RvTLMKB34/Listeners TMPDIR=/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-03-24 07:47:42-- 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% 22.4M=0.001s 2017-03-24 07:47:42 (22.4 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.124905 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-24 07:47:42.255169) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-24 07:47:42.255686) ========= 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.1v0VTUJrq7/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.7RvTLMKB34/Listeners TMPDIR=/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/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.027828 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-24 07:47:42.305810) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-03-24 07:47:42.306176) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 4c987b7ed54a630a7de76adcc2eb00dab49d5dfd --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 4c987b7ed54a630a7de76adcc2eb00dab49d5dfd --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.1v0VTUJrq7/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.7RvTLMKB34/Listeners TMPDIR=/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-03-24 07:47:42,417 truncating revision to first 12 chars 2017-03-24 07:47:42,417 Setting DEBUG logging. 2017-03-24 07:47:42,417 attempt 1/10 2017-03-24 07:47:42,417 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/4c987b7ed54a?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-03-24 07:47:43,400 unpacking tar archive at: mozilla-central-4c987b7ed54a/testing/mozharness/ program finished with exit code 0 elapsedTime=1.264026 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-03-24 07:47:43.593980) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-24 07:47:43.594305) ========= script_repo_revision: 4c987b7ed54a630a7de76adcc2eb00dab49d5dfd ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-24 07:47:43.594721) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-24 07:47:43.594981) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-24 07:47:43.614888) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 21 secs) (at 2017-03-24 07:47:43.615238) ========= /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.1v0VTUJrq7/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.7RvTLMKB34/Listeners TMPDIR=/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/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 07:47:43 INFO - MultiFileLogger online at 20170324 07:47:43 in /builds/slave/test 07:47:43 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 07:47:43 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 07:47:43 INFO - {'append_to_log': False, 07:47:43 INFO - 'base_work_dir': '/builds/slave/test', 07:47:43 INFO - 'blob_upload_branch': 'Firefox', 07:47:43 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 07:47:43 INFO - 'branch': 'Firefox', 07:47:43 INFO - 'buildbot_json_path': 'buildprops.json', 07:47:43 INFO - 'config_files': ('talos/mac_config.py',), 07:47:43 INFO - 'default_actions': ('clobber', 07:47:43 INFO - 'read-buildbot-config', 07:47:43 INFO - 'download-and-extract', 07:47:43 INFO - 'populate-webroot', 07:47:43 INFO - 'create-virtualenv', 07:47:43 INFO - 'install', 07:47:43 INFO - 'run-tests'), 07:47:43 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 07:47:43 INFO - 'download_minidump_stackwalk': True, 07:47:43 INFO - 'download_symbols': 'ondemand', 07:47:43 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 07:47:43 INFO - 'tooltool.py': '/tools/tooltool.py', 07:47:43 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 07:47:43 INFO - '/tools/misc-python/virtualenv.py')}, 07:47:43 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 07:47:43 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 07:47:43 INFO - 'gecko_profile': False, 07:47:43 INFO - 'gecko_profile_interval': 0, 07:47:43 INFO - 'installer_path': 'installer.exe', 07:47:43 INFO - 'log_level': 'info', 07:47:43 INFO - 'log_name': 'talos', 07:47:43 INFO - 'log_to_console': True, 07:47:43 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 07:47:43 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 07:47:43 INFO - 'opt_config_files': (), 07:47:43 INFO - 'pip_index': False, 07:47:43 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 07:47:43 INFO - 'cmd': ('bash', 07:47:43 INFO - '-c', 07:47:43 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 07:47:43 INFO - 'enabled': True, 07:47:43 INFO - 'halt_on_failure': False, 07:47:43 INFO - 'name': 'check_screen_resolution'},), 07:47:43 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 07:47:43 INFO - 'cmd': ('bash', 07:47:43 INFO - '-c', 07:47:43 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 07:47:43 INFO - 'enabled': True, 07:47:43 INFO - 'halt_on_failure': False, 07:47:43 INFO - 'name': 'check_screen_resolution'},), 07:47:43 INFO - 'run_cmd_checks_enabled': True, 07:47:43 INFO - 'suite': 'dromaeojs-e10s', 07:47:43 INFO - 'system_bits': '32', 07:47:43 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 07:47:43 INFO - 'title': 't-yosemite-r7-0052', 07:47:43 INFO - 'tooltool_cache': '/builds/tooltool_cache', 07:47:43 INFO - 'use_talos_json': True, 07:47:43 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 07:47:43 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 07:47:43 INFO - 'work_dir': 'build'} 07:47:43 INFO - [mozharness: 2017-03-24 14:47:43.828884Z] Running clobber step. 07:47:43 INFO - Running pre-action listener: _resource_record_pre_action 07:47:43 INFO - Running main action method: clobber 07:47:43 INFO - rmtree: /builds/slave/test/build 07:47:43 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 07:47:46 INFO - Running post-action listener: _resource_record_post_action 07:47:46 INFO - [mozharness: 2017-03-24 14:47:46.351682Z] Finished clobber step (success) 07:47:46 INFO - [mozharness: 2017-03-24 14:47:46.351820Z] Running read-buildbot-config step. 07:47:46 INFO - Running pre-action listener: _resource_record_pre_action 07:47:46 INFO - Running main action method: read_buildbot_config 07:47:46 INFO - Using buildbot properties: 07:47:46 INFO - { 07:47:46 INFO - "project": "", 07:47:46 INFO - "product": "firefox", 07:47:46 INFO - "script_repo_revision": "production", 07:47:46 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 07:47:46 INFO - "repository": "", 07:47:46 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 07:47:46 INFO - "buildid": "20170324062518", 07:47:46 INFO - "pgo_build": "False", 07:47:46 INFO - "basedir": "/builds/slave/test", 07:47:46 INFO - "buildnumber": 24, 07:47:46 INFO - "slavename": "t-yosemite-r7-0052", 07:47:46 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 07:47:46 INFO - "platform": "yosemite_r7", 07:47:46 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 07:47:46 INFO - "branch": "mozilla-central", 07:47:46 INFO - "repo_path": "mozilla-central", 07:47:46 INFO - "revision": "4c987b7ed54a630a7de76adcc2eb00dab49d5dfd", 07:47:46 INFO - "stage_platform": "macosx64", 07:47:46 INFO - "builduid": "b0aaae32dd1e40c0a7c7193a94de07e0", 07:47:46 INFO - "slavebuilddir": "test" 07:47:46 INFO - } 07:47:46 INFO - Found installer url https://queue.taskcluster.net/v1/task/bEymYBzvTWGCX6P5gG7g7g/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg. 07:47:46 INFO - Running post-action listener: _resource_record_post_action 07:47:46 INFO - [mozharness: 2017-03-24 14:47:46.355182Z] Finished read-buildbot-config step (success) 07:47:46 INFO - [mozharness: 2017-03-24 14:47:46.355291Z] Running download-and-extract step. 07:47:46 INFO - Running pre-action listener: _resource_record_pre_action 07:47:46 INFO - Running main action method: download_and_extract 07:47:46 INFO - mkdir: /builds/slave/test/build/tests 07:47:46 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')]} 07:47:46 INFO - trying https://queue.taskcluster.net/v1/task/bEymYBzvTWGCX6P5gG7g7g/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 07:47:46 INFO - Downloading https://queue.taskcluster.net/v1/task/bEymYBzvTWGCX6P5gG7g7g/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 07:47:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/bEymYBzvTWGCX6P5gG7g7g/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json'}, attempt #1 07:47:47 INFO - Downloaded 1325 bytes. 07:47:47 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 07:47:47 INFO - Using the following test package requirements: 07:47:47 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 07:47:47 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 07:47:47 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 07:47:47 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 07:47:47 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 07:47:47 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 07:47:47 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 07:47:47 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 07:47:47 INFO - u'jsshell-mac.zip'], 07:47:47 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 07:47:47 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 07:47:47 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 07:47:47 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 07:47:47 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 07:47:47 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 07:47:47 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 07:47:47 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 07:47:47 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 07:47:47 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 07:47:47 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 07:47:47 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 07:47:47 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/bEymYBzvTWGCX6P5gG7g7g/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 07:47:47 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/bEymYBzvTWGCX6P5gG7g7g/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 07:47:47 INFO - Fetch https://queue.taskcluster.net/v1/task/bEymYBzvTWGCX6P5gG7g7g/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 07:47:48 INFO - Expected file size: 52412020 07:47:48 INFO - Obtained file size: 52412020 07:48:05 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip', u'firefox-55.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 07:48:05 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/bEymYBzvTWGCX6P5gG7g7g/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 07:48:05 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/bEymYBzvTWGCX6P5gG7g7g/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 07:48:05 INFO - Fetch https://queue.taskcluster.net/v1/task/bEymYBzvTWGCX6P5gG7g7g/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 07:48:06 INFO - Expected file size: 52412020 07:48:06 INFO - Obtained file size: 52412020 07:48:22 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/bEymYBzvTWGCX6P5gG7g7g/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 07:48:22 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/bEymYBzvTWGCX6P5gG7g7g/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 07:48:22 INFO - Fetch https://queue.taskcluster.net/v1/task/bEymYBzvTWGCX6P5gG7g7g/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 07:48:23 INFO - Expected file size: 14404171 07:48:23 INFO - Obtained file size: 14404171 07:48:23 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')]} 07:48:23 INFO - trying https://queue.taskcluster.net/v1/task/bEymYBzvTWGCX6P5gG7g7g/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 07:48:23 INFO - Downloading https://queue.taskcluster.net/v1/task/bEymYBzvTWGCX6P5gG7g7g/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 07:48:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/bEymYBzvTWGCX6P5gG7g7g/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg'}, attempt #1 07:48:25 INFO - Downloaded 75438177 bytes. 07:48:25 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/bEymYBzvTWGCX6P5gG7g7g/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 07:48:25 INFO - mkdir: /builds/slave/test/properties 07:48:25 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 07:48:25 INFO - Writing to file /builds/slave/test/properties/build_url 07:48:25 INFO - Contents: 07:48:25 INFO - build_url:https://queue.taskcluster.net/v1/task/bEymYBzvTWGCX6P5gG7g7g/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 07:48:26 INFO - Running post-action listener: _resource_record_post_action 07:48:26 INFO - Running post-action listener: set_extra_try_arguments 07:48:26 INFO - [mozharness: 2017-03-24 14:48:26.409294Z] Finished download-and-extract step (success) 07:48:26 INFO - [mozharness: 2017-03-24 14:48:26.409417Z] Running populate-webroot step. 07:48:26 INFO - Running pre-action listener: _resource_record_pre_action 07:48:26 INFO - Running main action method: populate_webroot 07:48:26 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 07:48:26 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 07:48:26 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 07:48:26 INFO - '--tppagecycles', 07:48:26 INFO - '7'], 07:48:26 INFO - 'tests': ['tsvgm']}}, 07:48:26 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 07:48:26 INFO - 'tests': ['tresize', 'tcanvasmark']}, 07:48:26 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 07:48:26 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 07:48:26 INFO - 'tests': ['dromaeo_css', 'kraken']}, 07:48:26 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 07:48:26 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 07:48:26 INFO - 'talos_options': ['--disable-e10s'], 07:48:26 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:48:26 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 07:48:26 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:48:26 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 07:48:26 INFO - 'talos_options': ['--disable-e10s'], 07:48:26 INFO - 'tests': ['damp', 'tps']}, 07:48:26 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 07:48:26 INFO - 'tests': ['damp', 'tps']}, 07:48:26 INFO - 'g3': {'talos_options': ['--disable-e10s'], 07:48:26 INFO - 'tests': ['dromaeo_dom']}, 07:48:26 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 07:48:26 INFO - 'g4': {'talos_options': ['--disable-e10s'], 07:48:26 INFO - 'tests': ['basic_compositor_video']}, 07:48:26 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 07:48:26 INFO - 'other': {'talos_options': ['--disable-e10s'], 07:48:26 INFO - 'tests': ['a11yr', 07:48:26 INFO - 'ts_paint', 07:48:26 INFO - 'tpaint', 07:48:26 INFO - 'sessionrestore', 07:48:26 INFO - 'sessionrestore_no_auto_restore', 07:48:26 INFO - 'tabpaint']}, 07:48:26 INFO - 'other-e10s': {'tests': ['a11yr', 07:48:26 INFO - 'ts_paint', 07:48:26 INFO - 'tpaint', 07:48:26 INFO - 'sessionrestore', 07:48:26 INFO - 'sessionrestore_no_auto_restore', 07:48:26 INFO - 'tabpaint']}, 07:48:26 INFO - 'other-e10s_l64': {'tests': ['a11yr', 07:48:26 INFO - 'ts_paint', 07:48:26 INFO - 'tpaint', 07:48:26 INFO - 'sessionrestore', 07:48:26 INFO - 'sessionrestore_no_auto_restore', 07:48:26 INFO - 'tabpaint']}, 07:48:26 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 07:48:26 INFO - 'ts_paint', 07:48:26 INFO - 'tpaint', 07:48:26 INFO - 'sessionrestore', 07:48:26 INFO - 'sessionrestore_no_auto_restore', 07:48:26 INFO - 'tabpaint']}, 07:48:26 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 07:48:26 INFO - 'tests': ['a11yr', 07:48:26 INFO - 'ts_paint', 07:48:26 INFO - 'tpaint', 07:48:26 INFO - 'sessionrestore', 07:48:26 INFO - 'sessionrestore_no_auto_restore', 07:48:26 INFO - 'tabpaint']}, 07:48:26 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 07:48:26 INFO - 'tests': ['a11yr', 07:48:26 INFO - 'ts_paint', 07:48:26 INFO - 'tpaint', 07:48:26 INFO - 'sessionrestore', 07:48:26 INFO - 'sessionrestore_no_auto_restore', 07:48:26 INFO - 'tabpaint']}, 07:48:26 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 07:48:26 INFO - 'tests': ['tsvgx', 07:48:26 INFO - 'tsvgr_opacity', 07:48:26 INFO - 'tart', 07:48:26 INFO - 'tscrollx', 07:48:26 INFO - 'cart', 07:48:26 INFO - 'tsvg_static']}, 07:48:26 INFO - 'svgr-e10s': {'tests': ['tsvgx', 07:48:26 INFO - 'tsvgr_opacity', 07:48:26 INFO - 'tart', 07:48:26 INFO - 'tscrollx', 07:48:26 INFO - 'cart', 07:48:26 INFO - 'tsvg_static']}, 07:48:26 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 07:48:26 INFO - 'talos_options': ['--disable-e10s'], 07:48:26 INFO - 'tests': ['tp5o']}, 07:48:26 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 07:48:26 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 07:48:26 INFO - 'talos_options': ['--disable-e10s', 07:48:26 INFO - '--xperf_path', 07:48:26 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:48:26 INFO - 'tests': ['tp5n']}, 07:48:26 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 07:48:26 INFO - 'talos_options': ['--xperf_path', 07:48:26 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:48:26 INFO - 'tests': ['tp5n']}}, 07:48:26 INFO - 'talos.zip': {'path': '', 07:48:26 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 07:48:26 INFO - Running post-action listener: _resource_record_post_action 07:48:26 INFO - [mozharness: 2017-03-24 14:48:26.427860Z] Finished populate-webroot step (success) 07:48:26 INFO - [mozharness: 2017-03-24 14:48:26.427973Z] Running create-virtualenv step. 07:48:26 INFO - Running pre-action listener: _resource_record_pre_action 07:48:26 INFO - Running main action method: create_virtualenv 07:48:26 INFO - Creating virtualenv /builds/slave/test/build/venv 07:48:26 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 07:48:26 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 07:48:26 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 07:48:26 INFO - Using real prefix '/tools/python27' 07:48:26 INFO - New python executable in /builds/slave/test/build/venv/bin/python 07:48:27 INFO - Installing distribute.............................................................................................................................................................................................done. 07:48:30 INFO - Installing pip.................done. 07:48:30 INFO - Return code: 0 07:48:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 07:48:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 07:48:30 INFO - Reading from file tmpfile_stdout 07:48:30 INFO - Output received: 07:48:30 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) 07:48:30 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 07:48:30 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')]} 07:48:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:48:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:48:30 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')]} 07:48:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:48:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:48:30 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 0x1073e2b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1073e5768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9b7248eea0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1071c0d30>, '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 0x1070e7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1071b5be8>, '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.1v0VTUJrq7/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.7RvTLMKB34/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/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 07:48:30 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 07:48:30 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 07:48:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1v0VTUJrq7/Render', 07:48:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:48:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:48:30 INFO - 'HOME': '/Users/cltbld', 07:48:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:48:30 INFO - 'LOGNAME': 'cltbld', 07:48:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:48:30 INFO - 'MOZ_NO_REMOTE': '1', 07:48:30 INFO - 'NO_EM_RESTART': '1', 07:48:30 INFO - 'PAGER': '/bin/cat', 07:48:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:48:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:48:30 INFO - 'PWD': '/builds/slave/test', 07:48:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:48:30 INFO - 'SHELL': '/bin/bash', 07:48:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7RvTLMKB34/Listeners', 07:48:30 INFO - 'TMPDIR': '/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/T/', 07:48:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:48:30 INFO - 'USER': 'cltbld', 07:48:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:48:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:48:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:48:30 INFO - 'XPC_FLAGS': '0x0', 07:48:30 INFO - 'XPC_SERVICE_NAME': '0', 07:48:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:48:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:48:30 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 07:48:30 INFO - Cleaning up... 07:48:30 INFO - Return code: 0 07:48:30 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 07:48:30 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')]} 07:48:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:48:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:48:30 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')]} 07:48:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:48:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:48:30 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 0x1073e2b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1073e5768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9b7248eea0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1071c0d30>, '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 0x1070e7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1071b5be8>, '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.1v0VTUJrq7/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.7RvTLMKB34/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/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 07:48:30 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 07:48:30 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 07:48:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1v0VTUJrq7/Render', 07:48:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:48:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:48:30 INFO - 'HOME': '/Users/cltbld', 07:48:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:48:30 INFO - 'LOGNAME': 'cltbld', 07:48:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:48:30 INFO - 'MOZ_NO_REMOTE': '1', 07:48:30 INFO - 'NO_EM_RESTART': '1', 07:48:30 INFO - 'PAGER': '/bin/cat', 07:48:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:48:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:48:30 INFO - 'PWD': '/builds/slave/test', 07:48:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:48:30 INFO - 'SHELL': '/bin/bash', 07:48:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7RvTLMKB34/Listeners', 07:48:30 INFO - 'TMPDIR': '/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/T/', 07:48:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:48:30 INFO - 'USER': 'cltbld', 07:48:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:48:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:48:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:48:30 INFO - 'XPC_FLAGS': '0x0', 07:48:30 INFO - 'XPC_SERVICE_NAME': '0', 07:48:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:48:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:48:30 INFO - Downloading/unpacking psutil>=3.1.1 07:48:30 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 07:48:30 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 07:48:30 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 07:48:30 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 07:48:30 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 07:48:30 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 07:48:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 07:48:34 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:48:34 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:48:34 INFO - Installing collected packages: psutil 07:48:34 INFO - Running setup.py install for psutil 07:48:34 INFO - building 'psutil._psutil_osx' extension 07:48:34 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 07:48:35 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 07:48:35 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 07:48:35 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 07:48:35 INFO - building 'psutil._psutil_posix' extension 07:48:35 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 07:48:35 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 07:48:35 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 07:48:35 INFO - ^ 07:48:35 INFO - 1 warning generated. 07:48:35 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 07:48:35 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:48:35 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:48:35 INFO - Successfully installed psutil 07:48:35 INFO - Cleaning up... 07:48:35 INFO - Return code: 0 07:48:35 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 07:48:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:48:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:48:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:48:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:48:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:48:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:48:35 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 0x1073e2b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1073e5768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9b7248eea0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1071c0d30>, '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 0x1070e7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1071b5be8>, '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.1v0VTUJrq7/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.7RvTLMKB34/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/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 07:48:35 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 07:48:35 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 07:48:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1v0VTUJrq7/Render', 07:48:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:48:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:48:35 INFO - 'HOME': '/Users/cltbld', 07:48:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:48:35 INFO - 'LOGNAME': 'cltbld', 07:48:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:48:35 INFO - 'MOZ_NO_REMOTE': '1', 07:48:35 INFO - 'NO_EM_RESTART': '1', 07:48:35 INFO - 'PAGER': '/bin/cat', 07:48:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:48:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:48:35 INFO - 'PWD': '/builds/slave/test', 07:48:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:48:35 INFO - 'SHELL': '/bin/bash', 07:48:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7RvTLMKB34/Listeners', 07:48:35 INFO - 'TMPDIR': '/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/T/', 07:48:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:48:35 INFO - 'USER': 'cltbld', 07:48:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:48:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:48:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:48:35 INFO - 'XPC_FLAGS': '0x0', 07:48:35 INFO - 'XPC_SERVICE_NAME': '0', 07:48:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:48:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:48:36 INFO - Downloading/unpacking mozsystemmonitor==0.3 07:48:36 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 07:48:36 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 07:48:36 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 07:48:36 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 07:48:36 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 07:48:36 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 07:48:38 INFO - Downloading mozsystemmonitor-0.3.tar.gz 07:48:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 07:48:38 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 07:48:38 INFO - Installing collected packages: mozsystemmonitor 07:48:38 INFO - Running setup.py install for mozsystemmonitor 07:48:38 INFO - Successfully installed mozsystemmonitor 07:48:38 INFO - Cleaning up... 07:48:38 INFO - Return code: 0 07:48:38 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 07:48: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')]} 07:48:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:48:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:48:39 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')]} 07:48:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:48:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:48:39 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 0x1073e2b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1073e5768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9b7248eea0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1071c0d30>, '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 0x1070e7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1071b5be8>, '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.1v0VTUJrq7/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.7RvTLMKB34/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/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 07:48:39 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 07:48:39 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 07:48:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1v0VTUJrq7/Render', 07:48:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:48:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:48:39 INFO - 'HOME': '/Users/cltbld', 07:48:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:48:39 INFO - 'LOGNAME': 'cltbld', 07:48:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:48:39 INFO - 'MOZ_NO_REMOTE': '1', 07:48:39 INFO - 'NO_EM_RESTART': '1', 07:48:39 INFO - 'PAGER': '/bin/cat', 07:48:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:48:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:48:39 INFO - 'PWD': '/builds/slave/test', 07:48:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:48:39 INFO - 'SHELL': '/bin/bash', 07:48:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7RvTLMKB34/Listeners', 07:48:39 INFO - 'TMPDIR': '/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/T/', 07:48:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:48:39 INFO - 'USER': 'cltbld', 07:48:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:48:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:48:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:48:39 INFO - 'XPC_FLAGS': '0x0', 07:48:39 INFO - 'XPC_SERVICE_NAME': '0', 07:48:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:48:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:48:39 INFO - Downloading/unpacking jsonschema==2.5.1 07:48:39 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 07:48:39 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 07:48:39 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 07:48:39 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 07:48:39 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 07:48:39 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 07:48:42 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 07:48:42 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 07:48:42 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 07:48:42 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 07:48:42 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 07:48:42 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 07:48:42 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 07:48:42 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 07:48:43 INFO - Downloading functools32-3.2.3-2.tar.gz 07:48:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 07:48:43 INFO - warning: no files found matching '*.txt' 07:48:43 INFO - no previously-included directories found matching 'build' 07:48:43 INFO - no previously-included directories found matching 'dist' 07:48:43 INFO - no previously-included directories found matching '.git*' 07:48:43 INFO - Installing collected packages: jsonschema, functools32 07:48:43 INFO - Running setup.py install for functools32 07:48:43 INFO - warning: no files found matching '*.txt' 07:48:43 INFO - no previously-included directories found matching 'build' 07:48:43 INFO - no previously-included directories found matching 'dist' 07:48:43 INFO - no previously-included directories found matching '.git*' 07:48:43 INFO - Successfully installed jsonschema functools32 07:48:43 INFO - Cleaning up... 07:48:43 INFO - Return code: 0 07:48:43 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 07:48:43 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')]} 07:48:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:48:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:48:43 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')]} 07:48:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:48:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:48:43 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 0x1073e2b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1073e5768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9b7248eea0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1071c0d30>, '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 0x1070e7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1071b5be8>, '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.1v0VTUJrq7/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.7RvTLMKB34/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/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 07:48:43 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 07:48:43 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 07:48:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1v0VTUJrq7/Render', 07:48:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:48:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:48:43 INFO - 'HOME': '/Users/cltbld', 07:48:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:48:43 INFO - 'LOGNAME': 'cltbld', 07:48:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:48:43 INFO - 'MOZ_NO_REMOTE': '1', 07:48:43 INFO - 'NO_EM_RESTART': '1', 07:48:43 INFO - 'PAGER': '/bin/cat', 07:48:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:48:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:48:43 INFO - 'PWD': '/builds/slave/test', 07:48:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:48:43 INFO - 'SHELL': '/bin/bash', 07:48:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7RvTLMKB34/Listeners', 07:48:43 INFO - 'TMPDIR': '/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/T/', 07:48:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:48:43 INFO - 'USER': 'cltbld', 07:48:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:48:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:48:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:48:43 INFO - 'XPC_FLAGS': '0x0', 07:48:43 INFO - 'XPC_SERVICE_NAME': '0', 07:48:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:48:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:48:43 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 07:48:43 INFO - Cleaning up... 07:48:43 INFO - Return code: 0 07:48:43 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 07:48:43 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')]} 07:48:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:48:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:48:43 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')]} 07:48:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:48:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:48:43 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 0x1073e2b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1073e5768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9b7248eea0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1071c0d30>, '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 0x1070e7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1071b5be8>, '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.1v0VTUJrq7/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.7RvTLMKB34/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/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 07:48:43 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 07:48:43 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 07:48:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1v0VTUJrq7/Render', 07:48:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:48:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:48:43 INFO - 'HOME': '/Users/cltbld', 07:48:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:48:43 INFO - 'LOGNAME': 'cltbld', 07:48:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:48:43 INFO - 'MOZ_NO_REMOTE': '1', 07:48:43 INFO - 'NO_EM_RESTART': '1', 07:48:43 INFO - 'PAGER': '/bin/cat', 07:48:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:48:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:48:43 INFO - 'PWD': '/builds/slave/test', 07:48:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:48:43 INFO - 'SHELL': '/bin/bash', 07:48:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7RvTLMKB34/Listeners', 07:48:43 INFO - 'TMPDIR': '/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/T/', 07:48:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:48:43 INFO - 'USER': 'cltbld', 07:48:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:48:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:48:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:48:43 INFO - 'XPC_FLAGS': '0x0', 07:48:43 INFO - 'XPC_SERVICE_NAME': '0', 07:48:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:48:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:48:44 INFO - Downloading/unpacking blobuploader==1.2.4 07:48:44 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 07:48:44 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 07:48:44 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 07:48:44 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 07:48:44 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 07:48:44 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 07:48:46 INFO - Downloading blobuploader-1.2.4.tar.gz 07:48:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 07:48:46 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 07:48:46 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 07:48:46 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 07:48:46 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 07:48:46 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 07:48:46 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 07:48:46 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 07:48:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 07:48:47 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 07:48:47 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 07:48:47 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 07:48:47 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 07:48:47 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 07:48:47 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 07:48:47 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 07:48:48 INFO - Downloading docopt-0.6.1.tar.gz 07:48:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 07:48:48 INFO - Installing collected packages: blobuploader, requests, docopt 07:48:48 INFO - Running setup.py install for blobuploader 07:48:48 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 07:48:48 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 07:48:48 INFO - Running setup.py install for requests 07:48:48 INFO - Running setup.py install for docopt 07:48:48 INFO - Successfully installed blobuploader requests docopt 07:48:48 INFO - Cleaning up... 07:48:49 INFO - Return code: 0 07:48:49 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:48:49 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')]} 07:48:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:48:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:48:49 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')]} 07:48:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:48:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:48:49 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 0x1073e2b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1073e5768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9b7248eea0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1071c0d30>, '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 0x1070e7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1071b5be8>, '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.1v0VTUJrq7/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.7RvTLMKB34/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/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 07:48:49 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 07:48:49 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 07:48:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1v0VTUJrq7/Render', 07:48:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:48:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:48:49 INFO - 'HOME': '/Users/cltbld', 07:48:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:48:49 INFO - 'LOGNAME': 'cltbld', 07:48:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:48:49 INFO - 'MOZ_NO_REMOTE': '1', 07:48:49 INFO - 'NO_EM_RESTART': '1', 07:48:49 INFO - 'PAGER': '/bin/cat', 07:48:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:48:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:48:49 INFO - 'PWD': '/builds/slave/test', 07:48:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:48:49 INFO - 'SHELL': '/bin/bash', 07:48:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7RvTLMKB34/Listeners', 07:48:49 INFO - 'TMPDIR': '/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/T/', 07:48:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:48:49 INFO - 'USER': 'cltbld', 07:48:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:48:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:48:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:48:49 INFO - 'XPC_FLAGS': '0x0', 07:48:49 INFO - 'XPC_SERVICE_NAME': '0', 07:48:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:48:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:48:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:48:49 INFO - Running setup.py (path:/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/T/pip-eTlSn7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:48:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:48:49 INFO - Running setup.py (path:/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/T/pip-gQ8G5o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:48:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:48:49 INFO - Running setup.py (path:/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/T/pip-PfHVQ_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:48:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:48:49 INFO - Running setup.py (path:/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/T/pip-MrWJeB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:48:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:48:49 INFO - Running setup.py (path:/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/T/pip-Nr7B9T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:48:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:48:49 INFO - Running setup.py (path:/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/T/pip-DREbyG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:48:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:48:49 INFO - Running setup.py (path:/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/T/pip-3ac0Bu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:48:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:48:50 INFO - Running setup.py (path:/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/T/pip-nbMgbj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:48:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:48:50 INFO - Running setup.py (path:/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/T/pip-_NJW6f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:48:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:48:50 INFO - Running setup.py (path:/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/T/pip-WsRcWW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:48:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:48:50 INFO - Running setup.py (path:/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/T/pip-7hAS3i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:48:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:48:50 INFO - Running setup.py (path:/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/T/pip-VxA7J8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:48:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:48:50 INFO - Running setup.py (path:/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/T/pip-YeihdD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:48:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:48:50 INFO - Running setup.py (path:/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/T/pip-cpNIbZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:48:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:48:50 INFO - Running setup.py (path:/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/T/pip-axEn9T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:48:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:48:50 INFO - Running setup.py (path:/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/T/pip-b1Ssp4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:48:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:48:50 INFO - Running setup.py (path:/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/T/pip-6OR2UC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:48:51 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 07:48:51 INFO - Running setup.py install for manifestparser 07:48:51 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 07:48:51 INFO - Running setup.py install for mozcrash 07:48:51 INFO - Running setup.py install for mozdebug 07:48:51 INFO - Running setup.py install for mozdevice 07:48:51 INFO - Installing dm script to /builds/slave/test/build/venv/bin 07:48:51 INFO - Running setup.py install for mozfile 07:48:51 INFO - Running setup.py install for mozhttpd 07:48:51 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 07:48:51 INFO - Running setup.py install for mozinfo 07:48:52 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 07:48:52 INFO - Running setup.py install for mozInstall 07:48:52 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 07:48:52 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 07:48:52 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 07:48:52 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 07:48:52 INFO - Running setup.py install for mozleak 07:48:52 INFO - Running setup.py install for mozlog 07:48:52 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 07:48:52 INFO - Running setup.py install for moznetwork 07:48:52 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 07:48:52 INFO - Running setup.py install for mozprocess 07:48:52 INFO - Running setup.py install for mozprofile 07:48:52 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 07:48:52 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 07:48:52 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 07:48:52 INFO - Running setup.py install for mozrunner 07:48:53 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 07:48:53 INFO - Running setup.py install for mozscreenshot 07:48:53 INFO - Running setup.py install for moztest 07:48:53 INFO - Running setup.py install for mozversion 07:48:53 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 07:48:53 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 07:48:53 INFO - Cleaning up... 07:48:53 INFO - Return code: 0 07:48:53 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:48:53 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')]} 07:48:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:48:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:48:53 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')]} 07:48:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:48:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:48:53 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 0x1073e2b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1073e5768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9b7248eea0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1071c0d30>, '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 0x1070e7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1071b5be8>, '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.1v0VTUJrq7/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.7RvTLMKB34/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/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 07:48:53 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 07:48:53 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 07:48:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1v0VTUJrq7/Render', 07:48:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:48:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:48:53 INFO - 'HOME': '/Users/cltbld', 07:48:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:48:53 INFO - 'LOGNAME': 'cltbld', 07:48:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:48:53 INFO - 'MOZ_NO_REMOTE': '1', 07:48:53 INFO - 'NO_EM_RESTART': '1', 07:48:53 INFO - 'PAGER': '/bin/cat', 07:48:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:48:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:48:53 INFO - 'PWD': '/builds/slave/test', 07:48:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:48:53 INFO - 'SHELL': '/bin/bash', 07:48:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7RvTLMKB34/Listeners', 07:48:53 INFO - 'TMPDIR': '/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/T/', 07:48:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:48:53 INFO - 'USER': 'cltbld', 07:48:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:48:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:48:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:48:53 INFO - 'XPC_FLAGS': '0x0', 07:48:53 INFO - 'XPC_SERVICE_NAME': '0', 07:48:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:48:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:48:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:48:53 INFO - Running setup.py (path:/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/T/pip-gvD9LT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:48:53 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)) 07:48:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:48:53 INFO - Running setup.py (path:/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/T/pip-doBots-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:48:54 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)) 07:48:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:48:54 INFO - Running setup.py (path:/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/T/pip-EKkdlo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:48:54 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)) 07:48:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:48:54 INFO - Running setup.py (path:/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/T/pip-Z0n3IZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:48:54 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)) 07:48:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:48:54 INFO - Running setup.py (path:/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/T/pip-_suWnQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:48:54 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)) 07:48:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:48:54 INFO - Running setup.py (path:/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/T/pip-kEyTtm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:48:54 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)) 07:48:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:48:54 INFO - Running setup.py (path:/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/T/pip-IfxC9J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:48:54 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)) 07:48:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:48:54 INFO - Running setup.py (path:/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/T/pip-jWA863-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:48:54 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)) 07:48:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:48:54 INFO - Running setup.py (path:/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/T/pip-o0B9eW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:48:54 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)) 07:48:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:48:54 INFO - Running setup.py (path:/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/T/pip-rsGnbD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:48:54 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)) 07:48:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:48:54 INFO - Running setup.py (path:/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/T/pip-65alRe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:48:55 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)) 07:48:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:48:55 INFO - Running setup.py (path:/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/T/pip-KdVB1M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:48:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 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)) 07:48:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:48:55 INFO - Running setup.py (path:/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/T/pip-ZHXRP7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:48:55 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)) 07:48:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:48:55 INFO - Running setup.py (path:/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/T/pip-NZDebz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:48:55 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)) 07:48:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:48:55 INFO - Running setup.py (path:/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/T/pip-ntXvJL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:48:55 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)) 07:48:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:48:55 INFO - Running setup.py (path:/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/T/pip-HA_263-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:48:55 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 07:48:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:48:55 INFO - Running setup.py (path:/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/T/pip-6bJikf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:48:55 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)) 07:48:55 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)) 07:48:55 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)) 07:48:55 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)) 07:48:55 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)) 07:48:55 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)) 07:48:55 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 07:48:55 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 07:48:55 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 07:48:55 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 07:48:55 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 07:48:55 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 07:48:55 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 07:48:58 INFO - Downloading blessings-1.6.tar.gz 07:48:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 07:48:58 INFO - Installing collected packages: blessings 07:48:58 INFO - Running setup.py install for blessings 07:48:58 INFO - Successfully installed blessings 07:48:58 INFO - Cleaning up... 07:48:58 INFO - Return code: 0 07:48:58 INFO - Done creating virtualenv /builds/slave/test/build/venv. 07:48:58 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:48:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:48:59 INFO - Reading from file tmpfile_stdout 07:48:59 INFO - Current package versions: 07:48:59 INFO - blessings == 1.6 07:48:59 INFO - blobuploader == 1.2.4 07:48:59 INFO - docopt == 0.6.1 07:48:59 INFO - functools32 == 3.2.3-2 07:48:59 INFO - jsonschema == 2.5.1 07:48:59 INFO - manifestparser == 1.1 07:48:59 INFO - mozInstall == 1.12 07:48:59 INFO - mozcrash == 1.0 07:48:59 INFO - mozdebug == 0.1 07:48:59 INFO - mozdevice == 0.49 07:48:59 INFO - mozfile == 1.2 07:48:59 INFO - mozhttpd == 0.7 07:48:59 INFO - mozinfo == 0.9 07:48:59 INFO - mozleak == 0.1 07:48:59 INFO - mozlog == 3.4 07:48:59 INFO - moznetwork == 0.27 07:48:59 INFO - mozprocess == 0.25 07:48:59 INFO - mozprofile == 0.28 07:48:59 INFO - mozrunner == 6.13 07:48:59 INFO - mozscreenshot == 0.1 07:48:59 INFO - mozsystemmonitor == 0.3 07:48:59 INFO - moztest == 0.8 07:48:59 INFO - mozversion == 1.4 07:48:59 INFO - psutil == 3.1.1 07:48:59 INFO - requests == 1.2.3 07:48:59 INFO - wsgiref == 0.1.2 07:48:59 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:48:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:48:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:48:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:48:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:48:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:48:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:48:59 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 0x1073e2b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1073e5768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9b7248eea0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1071c0d30>, '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 0x1070e7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1071b5be8>, '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.1v0VTUJrq7/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.7RvTLMKB34/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/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 07:48:59 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 07:48:59 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 07:48:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1v0VTUJrq7/Render', 07:48:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:48:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:48:59 INFO - 'HOME': '/Users/cltbld', 07:48:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:48:59 INFO - 'LOGNAME': 'cltbld', 07:48:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:48:59 INFO - 'MOZ_NO_REMOTE': '1', 07:48:59 INFO - 'NO_EM_RESTART': '1', 07:48:59 INFO - 'PAGER': '/bin/cat', 07:48:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:48:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:48:59 INFO - 'PWD': '/builds/slave/test', 07:48:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:48:59 INFO - 'SHELL': '/bin/bash', 07:48:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7RvTLMKB34/Listeners', 07:48:59 INFO - 'TMPDIR': '/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/T/', 07:48:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:48:59 INFO - 'USER': 'cltbld', 07:48:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:48:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:48:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:48:59 INFO - 'XPC_FLAGS': '0x0', 07:48:59 INFO - 'XPC_SERVICE_NAME': '0', 07:48:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:48:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:48:59 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)) 07:48:59 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)) 07:48:59 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)) 07:48:59 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)) 07:48:59 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)) 07:48:59 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)) 07:48:59 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)) 07:48:59 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)) 07:48:59 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)) 07:48:59 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)) 07:48:59 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)) 07:48:59 INFO - Cleaning up... 07:48:59 INFO - Return code: 0 07:48:59 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 07:48:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:48:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:48:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:48:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:48:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:48:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:48:59 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 0x1073e2b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1073e5768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9b7248eea0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1071c0d30>, '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 0x1070e7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1071b5be8>, '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.1v0VTUJrq7/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.7RvTLMKB34/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/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 07:48:59 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 07:48:59 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 07:48:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1v0VTUJrq7/Render', 07:48:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:48:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:48:59 INFO - 'HOME': '/Users/cltbld', 07:48:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:48:59 INFO - 'LOGNAME': 'cltbld', 07:48:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:48:59 INFO - 'MOZ_NO_REMOTE': '1', 07:48:59 INFO - 'NO_EM_RESTART': '1', 07:48:59 INFO - 'PAGER': '/bin/cat', 07:48:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:48:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:48:59 INFO - 'PWD': '/builds/slave/test', 07:48:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:48:59 INFO - 'SHELL': '/bin/bash', 07:48:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7RvTLMKB34/Listeners', 07:48:59 INFO - 'TMPDIR': '/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/T/', 07:48:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:48:59 INFO - 'USER': 'cltbld', 07:48:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:48:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:48:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:48:59 INFO - 'XPC_FLAGS': '0x0', 07:48:59 INFO - 'XPC_SERVICE_NAME': '0', 07:48:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:48:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:48:59 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 07:48:59 INFO - Cleaning up... 07:48:59 INFO - Return code: 0 07:48:59 INFO - Running post-action listener: _resource_record_post_action 07:48:59 INFO - Running post-action listener: _start_resource_monitoring 07:48:59 INFO - Starting resource monitoring. 07:48:59 INFO - [mozharness: 2017-03-24 14:48:59.594012Z] Finished create-virtualenv step (success) 07:48:59 INFO - [mozharness: 2017-03-24 14:48:59.595075Z] Running install step. 07:48:59 INFO - Running pre-action listener: _resource_record_pre_action 07:48:59 INFO - Running main action method: install 07:48:59 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:48:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:48:59 INFO - Reading from file tmpfile_stdout 07:48:59 INFO - Detecting whether we're running mozinstall >=1.0... 07:48:59 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 07:48:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 07:48:59 INFO - Reading from file tmpfile_stdout 07:48:59 INFO - Output received: 07:48:59 INFO - Usage: mozinstall [options] installer 07:48:59 INFO - Options: 07:48:59 INFO - -h, --help show this help message and exit 07:48:59 INFO - -d DEST, --destination=DEST 07:48:59 INFO - Directory to install application into. [default: 07:48:59 INFO - "/builds/slave/test"] 07:48:59 INFO - --app=APP Application being installed. [default: firefox] 07:48:59 INFO - mkdir: /builds/slave/test/build/application 07:48:59 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 07:48:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 07:49:20 INFO - Reading from file tmpfile_stdout 07:49:20 INFO - Output received: 07:49:20 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 07:49:20 INFO - Running post-action listener: _resource_record_post_action 07:49:20 INFO - [mozharness: 2017-03-24 14:49:20.168401Z] Finished install step (success) 07:49:20 INFO - [mozharness: 2017-03-24 14:49:20.168531Z] Running run-tests step. 07:49:20 INFO - Running pre-action listener: _resource_record_pre_action 07:49:20 INFO - Running main action method: run_tests 07:49:20 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 07:49:20 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 07:49:20 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 07:49:20 INFO - 2017-03-24 07:49:20.183 screenresolution[1627:7273] starting screenresolution argv=screenresolution get 07:49:20 INFO - 2017-03-24 07:49:20.185 screenresolution[1627:7273] Display 0: 1600x1200x32@60 07:49:20 INFO - 2017-03-24 07:49:20.191 screenresolution[1628:7276] starting screenresolution argv=screenresolution list 07:49:20 INFO - Available Modes on Display 0 07:49:20 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 07:49:20 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 07:49:20 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 07:49:20 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 07:49:20 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 07:49:20 INFO - Intel Iris: 07:49:20 INFO - Chipset Model: Intel Iris 07:49:20 INFO - Type: GPU 07:49:20 INFO - Bus: Built-In 07:49:20 INFO - VRAM (Dynamic, Max): 1536 MB 07:49:20 INFO - Vendor: Intel (0x8086) 07:49:20 INFO - Device ID: 0x0a2e 07:49:20 INFO - Revision ID: 0x0009 07:49:20 INFO - Displays: 07:49:20 INFO - Display: 07:49:20 INFO - Resolution: 1600 x 1200 @ 60 Hz 07:49:20 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 07:49:20 INFO - Display Serial Number: 1600x1200 60 07:49:20 INFO - Main Display: Yes 07:49:20 INFO - Mirror: Off 07:49:20 INFO - Online: Yes 07:49:20 INFO - Rotation: Supported 07:49:20 INFO - Return code: 0 07:49:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 07:49:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 07:49:20 INFO - Python 2.7.3 07:49:20 INFO - Return code: 0 07:49:20 INFO - grabbing minidump binary from tooltool 07:49:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:49:20 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 0x1071c0d30>, '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 0x1070e7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1071b5be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 07:49:20 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 07:49:20 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 07:49:20 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 07:49:20 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 07:49:20 INFO - Return code: 0 07:49:20 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 07:49:20 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 07:49:20 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 07:49:20 INFO - ENV: RUST_BACKTRACE is now 1 07:49:20 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 07:49:20 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 07:49:20 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/bEymYBzvTWGCX6P5gG7g7g/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0052', '--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 07:49:20 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/bEymYBzvTWGCX6P5gG7g7g/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0052 --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 07:49:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1v0VTUJrq7/Render', 07:49:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:49:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:49:20 INFO - 'HOME': '/Users/cltbld', 07:49:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:49:20 INFO - 'LOGNAME': 'cltbld', 07:49:20 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 07:49:20 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 07:49:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:49:20 INFO - 'MOZ_NO_REMOTE': '1', 07:49:20 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 07:49:20 INFO - 'NO_EM_RESTART': '1', 07:49:20 INFO - 'PAGER': '/bin/cat', 07:49:20 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:49:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:49:20 INFO - 'PWD': '/builds/slave/test', 07:49:20 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 07:49:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:49:20 INFO - 'RUST_BACKTRACE': '1', 07:49:20 INFO - 'SHELL': '/bin/bash', 07:49:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7RvTLMKB34/Listeners', 07:49:20 INFO - 'TMPDIR': '/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/T/', 07:49:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:49:20 INFO - 'USER': 'cltbld', 07:49:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:49:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:49:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:49:20 INFO - 'XPC_FLAGS': '0x0', 07:49:20 INFO - 'XPC_SERVICE_NAME': '0', 07:49:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:49:20 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/bEymYBzvTWGCX6P5gG7g7g/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0052', '--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 07:49:20 INFO - mozversion application_buildid: 20170324062518 07:49:20 INFO - mozversion application_changeset: 4c987b7ed54a630a7de76adcc2eb00dab49d5dfd 07:49:20 INFO - mozversion application_display_name: Nightly 07:49:20 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 07:49:20 INFO - mozversion application_name: Firefox 07:49:20 INFO - mozversion application_remotingname: firefox 07:49:20 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 07:49:20 INFO - mozversion application_vendor: Mozilla 07:49:20 INFO - mozversion application_version: 55.0a1 07:49:20 INFO - mozversion platform_buildid: 20170324062518 07:49:20 INFO - mozversion platform_changeset: 4c987b7ed54a630a7de76adcc2eb00dab49d5dfd 07:49:20 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 07:49:20 INFO - mozversion platform_version: 55.0a1 07:49:20 INFO - using testdate: 1490366960 07:49:20 INFO - actual date: 1490366960 07:49:20 INFO - starting webserver on 'localhost:49241' 07:49:20 INFO - SUITE-START | Running 2 tests 07:49:20 INFO - TEST-START | dromaeo_css 07:49:20 INFO - operating with platform_type : mac_ 07:49:20 INFO - Initialising browser for dromaeo_css test... 07:49:20 INFO - TEST-INFO | started process 1638 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/T/tmpouURSc/profile http://localhost:49241/getInfo.html) 07:49:23 INFO - PID 1638 | 2017-03-24 07:49:23.717 plugin-container[1640:7462] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4943, name = 'com.apple.tsm.portname' 07:49:23 INFO - PID 1638 | See /usr/include/servers/bootstrap_defs.h for the error codes. 07:49:23 INFO - PID 1638 | 2017-03-24 07:49:23.720 plugin-container[1640:7462] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4c0b, name = 'com.apple.CFPasteboardClient' 07:49:23 INFO - PID 1638 | See /usr/include/servers/bootstrap_defs.h for the error codes. 07:49:23 INFO - PID 1638 | 2017-03-24 07:49:23.720 plugin-container[1640:7462] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 07:49:23 INFO - PID 1638 | __metrics Screen width/height:1600/1200 07:49:23 INFO - PID 1638 | colorDepth:24 07:49:23 INFO - PID 1638 | Browser inner width/height: 1024/689 07:49:23 INFO - PID 1638 | __metrics 07:49:23 ERROR - PID 1638 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3392: TypeError: this.tabs is undefined 07:49:24 INFO - TEST-INFO | 1638: exit 0 07:49:24 INFO - Browser initialized. 07:49:24 INFO - Running cycle 1/1 for dromaeo_css test... 07:49:24 INFO - TEST-INFO | started process 1641 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/T/tmpouURSc/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:49:27 INFO - PID 1641 | 2017-03-24 07:49:27.310 plugin-container[1642:7587] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4647, name = 'com.apple.tsm.portname' 07:49:27 INFO - PID 1641 | See /usr/include/servers/bootstrap_defs.h for the error codes. 07:49:27 INFO - PID 1641 | 2017-03-24 07:49:27.311 plugin-container[1642:7587] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8d17, name = 'com.apple.CFPasteboardClient' 07:49:27 INFO - PID 1641 | See /usr/include/servers/bootstrap_defs.h for the error codes. 07:49:27 INFO - PID 1641 | 2017-03-24 07:49:27.311 plugin-container[1642:7587] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 07:49:36 INFO - PID 1641 | RSS: Main: 227463168 07:49:36 INFO - PID 1641 | 07:52:40 INFO - PID 1641 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49241/tests/dromaeo/cssquery-ext.html) 07:52:41 INFO - PID 1641 | RSS: Main: 250859520 07:52:41 INFO - PID 1641 | 07:54:25 INFO - PID 1641 | ************************* 07:54:25 INFO - PID 1641 | A coding exception was thrown and uncaught in a Task. 07:54:25 INFO - PID 1641 | 07:54:25 ERROR - PID 1641 | Full message: TypeError: NetworkError when attempting to fetch resource. 07:54:25 INFO - PID 1641 | Full stack: 07:54:25 INFO - PID 1641 | ************************* 07:55:43 INFO - PID 1641 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-ext.html (next: http://localhost:49241/tests/dromaeo/cssquery-jquery.html) 07:55:44 INFO - PID 1641 | RSS: Main: 258613248 07:55:44 INFO - PID 1641 | 07:58:46 INFO - PID 1641 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49241/tests/dromaeo/cssquery-mootools.html) 07:58:46 INFO - PID 1641 | RSS: Main: 270491648 07:58:46 INFO - PID 1641 | 08:01:48 INFO - PID 1641 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49241/tests/dromaeo/cssquery-prototype.html) 08:01:49 INFO - PID 1641 | RSS: Main: 263376896 08:01:49 INFO - PID 1641 | 08:04:52 INFO - PID 1641 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49241/tests/dromaeo/cssquery-yui.html) 08:04:53 INFO - PID 1641 | RSS: Main: 272936960 08:04:53 INFO - PID 1641 | 08:07:56 INFO - PID 1641 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-yui.html (next: http://localhost:49241/tests/dromaeo/cssquery-dojo.html) 08:07:56 INFO - PID 1641 | RSS: Main: 266805248 08:07:56 INFO - PID 1641 | 08:07:56 INFO - PID 1641 | __start_tp_report 08:07:56 INFO - PID 1641 | _x_x_mozilla_page_load 08:07:56 INFO - PID 1641 | _x_x_mozilla_page_load_details 08:07:56 INFO - PID 1641 | |i|pagename|runs| 08:07:56 INFO - PID 1641 | |0;dojo.html;2803.761680257179;3004.6694863565003;3535.840887160079;3543.681068703818;3560.8397622107004;5636.830895073144;6136.355682653319;6176.227971503562;6187.381261873816;6216.502679785618;6048.788292409767;6100.853682747669;6129.203203583528;6141.355157708444;6180.629162250269;5487;5504.284443022401;5539.556835453154;5668.008098582752;5681.573881958849;5436.782528698867;5568.415316391782;5629.099344104944;5664.603477756558;5669.489745922866;4694.530546945312;5551.694656793875;5555.527780138693;5632.35227948787;5665.518430933375;5503.532199763025;5521.868017056494;5562.694051827148;5581.358143813432;5646.407127251661;5526.557875369961;5530.253415788898;5535.6955367454775;5560.471755183251;5585.497305242507;4748;4794.688345257547;5958.225430693982;6135.785247516315;6240.407161319713;5393.865153371197;6383.648899310536;6485.967570162166;6569.540132190748;6576.375244351779;5311.707856067915;6664.300248473937;6668.733250669968;6692.59844409337;6694.564853284521;5207.583393328525;6259.530535209878;6298.4331410172845;6310.6213627182515;6323.5573509854785;6524.151860258136;6550.8362290943105;6706.865862682719;6772.085768421299;6799.014142949245;6425.293217746044;6456.386643268882;6498.642574658439;6505.739770409131;6512.869742605121;6354.872902541923;6402.551821372553;6427.871442571216;6440.388163124496;6472.579282346726;6401.9039714404325;6462.38607332312;6471.611703297723;6473.255575608771;6489.7404103835315;6503.739850406026;6523.3150519194805;6532.346765323429;6548.3124271950755;6549.083128362033;5051.9390927904815;5212.452692467236;5222.2427747976335;5230;5282.6302158849285;4689.1559519286175;4700.341952126705;4738.881527961828;4744.620430365562;4780.976095119572;6268.686565671789;6271.592346497463;6287.842803929939;6289.496840252768;6308.526860485482;5526.917096243559;6395.2645445773405;6480.740770369227;6635.236947750974;6681.398674119256;4981.726005069756;6223.222097237845;6299.622022678654;6302.369763023661;6358.364163583605;5888.558358123158;5893.852653683606;5902.439268269508;5908.734106965197;5948.078047902582;6561.934380656323;6784.559003664746;6799.3200679931615;6827.69275382609;6837.6239306836615;6501.642409667514;6615.17310336208;6684.364985326386;6693.698783554654;6712.194536655633;5652.915206271991;6789.287124852017;6838.897416538855;6840.281770414035;6853.897191542031;6274.811755647338;6522.34776522344;6534.313897040933;6540.084388185753;6548.934510654833;6442.355764423708;6523.445507131757;6535.836604084936;6652.600843949185;6664.433523150587;6363.840903977438;6432.935670643234;6445.323241059621;6447.2263328400895;6454;6239.875202496016;6264.561480696308;6395.360463953567;6396.104545363746;6396.392342727433;6141.293751218488;6268.717907694074;6269.373062693694;6292.528060395397;6320.5575609706875;6361.268454127835;6383.170187875547;6395.840103997251;6404.583702059351;6404.775832845828;6147.569670123048;6299.401556852092;6305.8108256752375;6319.8104056878365;6320.115183874169 08:07:56 INFO - PID 1641 | |1;ext.html;629.2763322179493;677.3801971196355;820.1265652080535;1153.8615366156062;1270.0728468218206;37698.81150594247;38123.61876381235;38213.426798597975;38221.617783822156;38237.23525529488;37799.62200377995;38076;38204.80897595508;38269.617303826955;38270.61729382713;7996.800079998017;8489.915100849004;8672;8693.347998900108;8724.825503489927;8659.177378149097;8759.737207883742;8769.429987050822;8772.429792063527;8776.69281575145;8716.956415217915;8748.212660860521;8796.296296296281;8799.868001979943;8803.603837827313;8701.651933922636;8763.737087887373;8769.254613357873;8770.693025744133;8793.648254069862;8598.828023439559;8617;8655;8679.956600216958;8720.956395217983;38101.809490952364;38336;38372.232555349015;38479.61520384788;38501.614983850086;2877.0176940071237;2986.417648558532;3003.459377312083;3003.819770813758;3051.862666180005;26818.731812681817;26879;27077.916883324644;27437.451250974867;27815.860920695468;2231.8884055797143;2751.9036833710725;2763.2124844419245;2781.3185769486404;2887.422515496888;335495;336861;338443;340262.29868850746;340297.29851350834;52472.475275247525;52744.472555273955;52827.20759188615;52853.94292114136;53089;54985;55067.72466137644;55300.44699552953;55395.892082158134;55886.72056639772;14717.55847324582;14784.556463305902;14860.851391485947;14867.330970106372;14882.702345953021;10160.441175735405;11935.403229838646;11987.700307492383;12010.279383237033;12026.098042646836;13337.333133343294;14172.716545669029;14184.936129790307;14221.786673199911;14257.21585312797;6121.602095863755;6125.2649682037545;6181.103739957681;6185.876282474415;6197.5351848611535;937.6999360204669;964.4936408385654;966.5264020629982;989.4063561862969;997.3118548201718;24186.790660466908;24234.394140146636;24463.38841528976;24483.265502034967;24900;963.6723514005272;1054.4622242656146;1095.1074873977682;1101.1136035491347;1101.2236373356764;10256.794864102676;11775.411229438494;11899.762004759856;12337.383130843422;12452;467134;468393;470210;470238;472807;40879.59120408758;53876.46123538793;54158.7292063545;54193.39356154692;54489;1336.4921329894578;1488.6129606302438;1488.7320282349065;1507.5025241670442;1513.9243037848285;24664;27053.91784328605;27685;28442.146735597966;28504.714952850205;35808;35885.820570896976;35912.102197445274;35979.64020359763;36057.737979170604;280430.5978470095;284623;351927;353469;354632;25793.742062579135;30814.921477748172;32877;34299;34804.9558513245;38164.809175953946;42624.36063458989;42664.360034600126;43276;44107;29505.557416639196;30016;30433.087007389815;30439.543406848472;30760.923367682022;29241.415171696874;30631.54052689167;30660;30673.693263067977;30710;31911.521327179646;33945.151371215914;35408;36305.72929947439;36646.633533664324;29805.70194298116;36906.63093369139;37210.441843372915;37704.245915080995;38429.80785096057;28584.857075714488;29973.50132493323;30039.94860179883;30211.546826798054;30963.84518077395 08:07:56 INFO - PID 1641 | |2;jquery.html;424.2987696324722;427.8649538801378;434.4157108688813;445.98439054633087;456.1287940034535;19025.33411330602;20629.27797527085;21213.469663258427;21422.785772142273;21448.9623485769;21513.247036353732;21754.673679894815;21785.564288714217;22031.889840550815;22087;6027.336992930785;6170.907436388436;6213.720382582772;6214.8135555933395;6229.501639868799;6047.183630209909;6356.936430635704;6370.649614271213;6428.453581445583;6447.677616119198;6070.1501789749245;6414.9679251603675;6418.775342863;6443.323451037644;6450.258220304678;6252.718627661766;6304.495640348784;6345.365463453664;6364.904526432061;6365.077063825743;5554.1391084381985;5650.434956504358;5675.9432405675825;5737.627054241461;5744.971275143639;27287.727122728917;27726.5841012385;27829.886804527796;27981.58027629587;28358.858205709046;1946.4549926020736;1985.8411327093797;2028.5131568423471;2044.6217449771839;2047.8668886522328;15942.688310194957;16909;16965.491035268962;17223.655526889394;17379.913100434544;1701.4895531340549;1705.0962989615523;1706.7183914654067;1710.2560386232028;1760.5950631354733;147434.52565474316;158529;160576.19711901483;161716;164259;12984.610461686165;13059.804102938464;13200.339983001004;13211.933940330237;13250.204987700768;16513.422030228885;16548.58628534272;16567.834321656628;16580.088095154642;16607.41874034403;8374.330053595697;8806.383553151347;9203.677871274473;9288.164065234161;10120.330145525906;8768.824623507495;8771.692990745294;8822.823543529094;8953.328500362499;8974.14745599167;7500.399968002546;7963.544971694292;8311.293540049042;8363.118298382937;8899.02110767815;2208.216083290441;2212.5524012466676;2220.1452440810403;2226.855254408458;2399.064364897691;926.4441335198961;930.2093220762298;932.6316105138433;964.5225613321451;971.8882328532168;20022.799772002385;21361.57276854454;21393.46516337073;21530.46173845635;21623;964.1659964130977;977.9559919803626;1009.9394036357842;1012.9088382045651;1013.9898601014042;9535.47554884488;10081.294309398418;10597.4701264938;10989.395583242998;10995.065419439437;565779;677433;697114.2308615298;699649;708965;127626.36186819007;163532;164660;167132.4628725202;168406.15796920937;1183.7810005149074;1186.5846953566183;1241.9689507762378;1245.9377031148406;1255.6421419895287;20740.58518829585;21741.891290543444;21834;22058.227962021254;22148.2248121315;62660.68669656623;63117.36882631115;64265.67867160634;64718.02922956253;65001;79860.80208796989;89850;92144;92540;92654;8064.395170362129;8197.83604327922;8469.57652117404;8520.786980325542;8944.910550894409;11001.834972475259;13638.93180534091;13742.381592828548;14169.574912752634;14265.928670356581;10344.965503449595;11102.722431939266;11455.418626341927;11891.108166887345;12168.817467738168;11292;11388.259763115371;11488.310701357945;11657.76684466289;11779.234349767237;11851.348175850411;12148.817767733668;12297.50809967627;12348.444320005441;12399.442025109072;8617.741467755977;8659.653613855375;8883;8955.59699813497;9100.772480688036;11799.469023894117;11899.881001190224;12084.335361555199;12177.391130443266;12205.023598111951 08:07:56 INFO - PID 1641 | |3;mootools.html;1293.4438191577622;1385.224274406333;1396.1413730555712;1399;1406.7467855785958;10522.894771052288;10784.029437350637;10953.34279943204;10978.505967231475;11143.944280278587;10663.840042399352;10851.6744497665;10910.181736369765;10914;10961.56153753851;1836.8163183681593;2352.8353015288935;2420.3465064432594;2425.963610545843;2461.163204510466;2338.649202619604;2411.5056413435204;2455.165243817102;2455.5702752018415;2456.238566044532;2383.0467812874904;2437.926862194137;2445.4619983603666;2449.1428000199876;2449.9265022049367;2026.2401599400225;2284.554511870186;2318.8260880434036;2411.986965474496;2415.927522174338;1998.6502362086576;2063.4222417723063;2339.9181028663916;2417;2422;10824.458777061116;10909.563617455371;10918.454077296103;10929.289596176222;10941.23411361205;6126.264848218198;6419.197600299963;6435.581687190364;6444.581102228341;6449.12936753537;3940.4089386591954;3993.3610622300293;4011.2178125265586;4043.1509383029597;4077.8164982575854;1002.4937406609615;1156.3639998001065;1162.738383863624;1184.0882520459284;1215.7264615461538;12269.631911042772;15020;15082.170480623561;15204.239788010555;15204.543863684106;5242.868928276824;5336.306280183629;5364.946350536523;5368.248445217672;5416.620836541404;11352.48913798881;11561.826572601418;11566.016888564398;11576.594819181288;11594.130440216848;6232.501399887998;6260.937390626126;6284;6286.214223222098;6299.370062993664;5527.419620939743;5649.12438572022;5763.366029736726;5776.133579962955;5839.357670656309;3559.8042107684328;4109.3425051991535;4259.893502662459;4291.399204111427;4349;1220.3227208898968;1221.9022478201723;1228.975420491585;1229.7171650520431;1246.3020708403324;746.4476287547285;751.7894989402976;767.9308862202429;773.5668025905511;800.0672491630992;5911.586188966817;6013.128096425994;6076.483498902554;6151.907721384183;6158.753649853956;578.6788332475433;585.4175095779726;811.290121143999;897.6723495924072;905.3933864310927;3617.76995821422;4145.7719825409895;4255.553166917491;4382.802773875183;4395.472543294825;12917.612471625866;13957.092788968685;13965.371558279901;14031.368588413547;14037.78943315851;13176.538821141407;13646.727065458635;13695.589132326046;13712.3829427676;13717.314134293245;10019.699409017741;10238.97610238985;10247.692569222787;10264.384136951658;10268.537915793808;6508.186476690414;6550.344965503412;6713.865722685421;6926.722931082701;6944.062551555476;11639.41802909868;12291.69270768238;12439.067069969607;12601.306928119042;12647.683807904876;12449.626511204679;12682.048846336376;12741.10812243134;12805;13038.804417933548;9040;9282.257419406296;9320.673776417792;9335.159835614555;9375.484348360633;9723.027697230504;10131.037551432602;10191.490425478845;10199.694009179737;10211.540480678239;8587.110835616155;8592.742217733477;8685.174908383693;8864.778380540538;8880.555972201493;8324.125966773401;8494.830103397773;8499.235068843835;8502.489850608983;8611.311095112502;7601.809954751176;7835.49069310493;7868.6459109339075;7924.564148971862;7935.960320198362;5618.297712785902;5658.434156584309;5685.914711279417;5860.970695146497;5866.325372582208;4781.880452988703;5976.9103463448955;6021.909671355021;6026;6107.267127944675 08:07:56 INFO - PID 1641 | |4;prototype.html;404.565092525535;408.4547129582007;410.65504975820306;414.55642462565066;422.08407755171294;6205.84485387865;6346.238451385829;6353.777617783378;6383.776567820121;6394.968025159879;4849.248366503189;5556.222128901947;5841.445062719045;5845.8830823383505;6223.719932603032;4222.740833653996;4805.279208118793;4837.581209395303;4838.838678717118;4868;4424.911501769979;4854.150523658363;4860.951390486103;4890.706557606537;4896.755162241873;4612.700174488664;4626.709778659569;4629.051044535862;4629.189891768944;4644.60520855726;3850.7497012694084;4618.699784514012;4645.094206629708;4646.349511068419;4656.952185758211;3984.183242435294;3999.7800120993334;4003.739756915791;4008.398740188966;4019.216252830699;5200.699825043739;5859.970700146472;6102.20671312731;6109.14471973924;6189.87620247597;4557.316402539613;5039.874003149914;5416.970775552632;5422.267993820823;5437.483439073281;5645.2096706461125;6025.517958563304;6665.666716664147;6681.966590167018;6688.899666504958;4421.248387774086;4435.844745433894;4444.644383463043;4444.755538445384;4448.644108471314;5835.416458354131;6400.655862268929;6976.720941157597;7145.535540189871;7155.856882862314;3801.7338786284695;4353.934690979638;4471.977640111779;4526.185286648369;4530.77346132692;4032.354823228269;4458.9554104459185;4593.127305811885;4595.2188128018315;4599.862004139881;5174.482551744871;5384.454012367268;5438.619296649196;5458.972705136449;5495.230667706524;6428.228612566429;6535.967320163369;6747.334802752984;6783.660816959133;6801.4558835293055;1565.997761432684;1574.3073047858904;1575.708493928627;1575.8818088643397;1580.5021418253214;887.3522328700085;1011.1910471622673;1032.9896701033044;1036.6009086501751;1037.7561273100816;5419.512243897989;5562.805301814498;5658.151277308437;6109.419605137505;6345.49236061114;6198.349173336735;6929.3070692930305;6930.584164950018;6987.8602427951155;7026;6016.067509536029;6358.7456501740335;6428.132202152649;6454.903176452357;6456.5787829563915;3622.565292164957;3641.5812181598926;3645.54430696163;3673.412254039394;3676.3750162472443;6848.6918088686125;7067.081279433641;7091.822704432328;7164;7180.856382872314;6233.659763150944;6865.828354291183;7145.499815013003;7146.92853071473;7181.820454488575;6820.249772524919;6834.931650683529;7096.254893236239;7135.393491553173;7162.247963963813;6960.443164546723;6978.267281935324;6979.4765392594745;7010.824729381806;7020.824479388056;6547.476201903741;6598.208215014229;6665.833354166185;6895.069165662571;6916.619585922823;5469.699166545878;6710.16122984627;6921.688524016331;7150.570965742072;7170.462215333975;3799.6010418905616;4439.733615983051;4450.087732014865;4452.688311818141;4503.482099558592;6018.398160183947;6049.485793707408;6652;6837.974303854462;6945.853934101019;5961.791337303173;6089.056196289582;6606.9008964866525;6631.436327911989;6671.199456065303;5749.540036796962;6303.842403939938;6636.435902948304;6639;6663.766768163091;6431.29255781873;6513.772017979348;6523.597426553314;6523.8042858712415;6532.346765323429;5689.7439615218245;6308.463780578702;6355.046742988404;6680.799576012728;6685.8997115044285;5696.601237913306;6348.936510634834;6458.22501299847;6477.4170324671;6489.902651460137 08:07:56 INFO - PID 1641 | |5;yui.html;599.6971529377664;704.1128178495098;710.3571268002459;720.1178556268575;746.231381676873;14660.413583456662;14813.481528146516;15687.764683529727;15920.442784502544;16377.91811040946;14956.252187390613;15204.695906081872;15277.847221527782;15432.3827046918;15476.458323958665;952.728472385372;974.1816873825985;977.1596427072711;977.8728765260506;981.4601968917103;941.1623654947102;965.2663975378728;974.0843607009424;979.4221409368471;983.4836710726889;1011.1960991012127;1028.5371582787775;1031.7987992341496;1032.2825636182856;1037.0303765978833;1019.2050200843317;1023.4422239879284;1024.1499555369026;1027.547878933267;1028.8508166315842;764.552736649061;765.3226894198618;766.624354066509;766.651173715958;773.443120952913;16286.429974950819;16734.665306693918;16762.748558771505;16853.91573042139;16983;14569.344379502947;14803.162110257004;14812.851871481254;14931.850681493155;14933;11708.414579271002;11838.644840654793;11911.702207444796;12102.697432564168;12206.511739530497;3039.8176109433507;3052.2216834707006;3066.953995690066;3095.2880837407297;3167.748739248007;109697.45151274273;111761;111910;112022;116297.4185129069;9187.586558604879;9838.557264923096;11781.52873885035;11797.29216247028;11801.05591552691;22333.888330558242;22799.886000570223;22858.542829143324;22908.885455572614;22971;7794.844103117905;8059.677612895536;8115.675372985014;8185.263326300662;8289.58552072394;4092.263392589303;4208.978955105266;4210.263203939298;4211.7472951623;4361.214981303396;4119.155573107506;4185.937210941838;4211.831526738957;4214.7471151731;4215.810288537024;830.0620299062022;835.1564919431297;845.3152946113668;859.2181115185151;871.2986046232843;502.53264464048783;516.2540129512818;523.602062432554;546.1780021068267;573.6357413042665;9549.534284532481;10037.849432258521;10216.233782466345;10283.331583447052;10288.141762356661;538.526097034607;553.3802141601432;561.9100943848964;571.9571032172604;572.9028909637997;3973.562908080109;4451.821927122944;4868.561829435368;4871.084319431314;4878.170710979142;98877;103942;104089.47955260176;105103.47448262863;105107.47446262873;86045.56977215073;86822.56588717143;87455.56272218598;87929.56035219911;89022;78011;78374.21625783783;79314;79569;79872;8737.868931966153;8904.020557738773;9347.953260233655;9493.382930109521;9544.522773861141;37929.051773705876;38154.61845381511;39272.018199544094;39687.80156099201;39813.60186398215;47952.52047479481;48583;48711.75644121898;49023.754881225366;50101.749491253766;11840.940795295968;12385.876141238472;13385.464581416634;13615.25116118623;14031.859681403055;14657.706845863237;14963.700725985636;14979.925100374428;15178.468753593572;15244.3902243909;13961.930190348983;14078.929605351908;14086.295685215493;14103.365348559544;14113.08264962774;14004;14017.5093871714;14027.50903718365;14289.642758930693;14464.20446875432;13547.661308467366;13876.236806975714;13976.860231397555;14357.21035343066;14527.636809079857;8314.750557483285;8896.911030889609;9119.179273865384;9124.635014599342;9361.578728957076;7596.126445458843;8279.54462504568;8946.642134314554;9998.20014398861;10430.947845260725 08:07:56 INFO - PID 1641 | __end_tp_report 08:07:56 INFO - PID 1641 | __start_cc_report 08:07:56 INFO - PID 1641 | _x_x_mozilla_cycle_collect,441 08:07:56 INFO - PID 1641 | __end_cc_report 08:07:56 INFO - PID 1641 | __startTimestamp1490368076277__endTimestamp 08:07:56 INFO - PID 1641 | 08:07:56 INFO - PID 1641 | ------- Summary: start ------- 08:07:56 INFO - PID 1641 | Number of tests: 6 08:07:56 INFO - PID 1641 | 08:07:56 INFO - PID 1641 | [#0] dojo.html Cycles:165 Average:6040.23 Median:6299.62 stddev:726.45 (11.5%) stddev-sans-first:682.87 08:07:56 INFO - PID 1641 | Values: 2803.8 3004.7 3535.8 3543.7 3560.8 5636.8 6136.4 6176.2 6187.4 6216.5 6048.8 6100.9 6129.2 6141.4 6180.6 5487.0 5504.3 5539.6 5668.0 5681.6 5436.8 5568.4 5629.1 5664.6 5669.5 4694.5 5551.7 5555.5 5632.4 5665.5 5503.5 5521.9 5562.7 5581.4 5646.4 5526.6 5530.3 5535.7 5560.5 5585.5 4748.0 4794.7 5958.2 6135.8 6240.4 5393.9 6383.6 6486.0 6569.5 6576.4 5311.7 6664.3 6668.7 6692.6 6694.6 5207.6 6259.5 6298.4 6310.6 6323.6 6524.2 6550.8 6706.9 6772.1 6799.0 6425.3 6456.4 6498.6 6505.7 6512.9 6354.9 6402.6 6427.9 6440.4 6472.6 6401.9 6462.4 6471.6 6473.3 6489.7 6503.7 6523.3 6532.3 6548.3 6549.1 5051.9 5212.5 5222.2 5230.0 5282.6 4689.2 4700.3 4738.9 4744.6 4781.0 6268.7 6271.6 6287.8 6289.5 6308.5 5526.9 6395.3 6480.7 6635.2 6681.4 4981.7 6223.2 6299.6 6302.4 6358.4 5888.6 5893.9 5902.4 5908.7 5948.1 6561.9 6784.6 6799.3 6827.7 6837.6 6501.6 6615.2 6684.4 6693.7 6712.2 5652.9 6789.3 6838.9 6840.3 6853.9 6274.8 6522.3 6534.3 6540.1 6548.9 6442.4 6523.4 6535.8 6652.6 6664.4 6363.8 6432.9 6445.3 6447.2 6454.0 6239.9 6264.6 6395.4 6396.1 6396.4 6141.3 6268.7 6269.4 6292.5 6320.6 6361.3 6383.2 6395.8 6404.6 6404.8 6147.6 6299.4 6305.8 6319.8 6320.1 08:07:56 INFO - PID 1641 | 08:07:56 INFO - PID 1641 | [#1] ext.html Cycles:180 Average:51926.50 Median:35846.91 stddev:101709.93 (283.7%) stddev-sans-first:101922.33 08:07:56 INFO - PID 1641 | Values: 629.3 677.4 820.1 1153.9 1270.1 37698.8 38123.6 38213.4 38221.6 38237.2 37799.6 38076.0 38204.8 38269.6 38270.6 7996.8 8489.9 8672.0 8693.3 8724.8 8659.2 8759.7 8769.4 8772.4 8776.7 8717.0 8748.2 8796.3 8799.9 8803.6 8701.7 8763.7 8769.3 8770.7 8793.6 8598.8 8617.0 8655.0 8680.0 8721.0 38101.8 38336.0 38372.2 38479.6 38501.6 2877.0 2986.4 3003.5 3003.8 3051.9 26818.7 26879.0 27077.9 27437.5 27815.9 2231.9 2751.9 2763.2 2781.3 2887.4 335495.0 336861.0 338443.0 340262.3 340297.3 52472.5 52744.5 52827.2 52853.9 53089.0 54985.0 55067.7 55300.4 55395.9 55886.7 14717.6 14784.6 14860.9 14867.3 14882.7 10160.4 11935.4 11987.7 12010.3 12026.1 13337.3 14172.7 14184.9 14221.8 14257.2 6121.6 6125.3 6181.1 6185.9 6197.5 937.7 964.5 966.5 989.4 997.3 24186.8 24234.4 24463.4 24483.3 24900.0 963.7 1054.5 1095.1 1101.1 1101.2 10256.8 11775.4 11899.8 12337.4 12452.0 467134.0 468393.0 470210.0 470238.0 472807.0 40879.6 53876.5 54158.7 54193.4 54489.0 1336.5 1488.6 1488.7 1507.5 1513.9 24664.0 27053.9 27685.0 28442.1 28504.7 35808.0 35885.8 35912.1 35979.6 36057.7 280430.6 284623.0 351927.0 353469.0 354632.0 25793.7 30814.9 32877.0 34299.0 34805.0 38164.8 42624.4 42664.4 43276.0 44107.0 29505.6 30016.0 30433.1 30439.5 30760.9 29241.4 30631.5 30660.0 30673.7 30710.0 31911.5 33945.2 35408.0 36305.7 36646.6 29805.7 36906.6 37210.4 37704.2 38429.8 28584.9 29973.5 30039.9 30211.5 30963.8 08:07:56 INFO - PID 1641 | 08:07:56 INFO - PID 1641 | [#2] jquery.html Cycles:180 Average:40409.25 Median:12133.22 stddev:113349.64 (934.2%) stddev-sans-first:113627.85 08:07:56 INFO - PID 1641 | Values: 424.3 427.9 434.4 446.0 456.1 19025.3 20629.3 21213.5 21422.8 21449.0 21513.2 21754.7 21785.6 22031.9 22087.0 6027.3 6170.9 6213.7 6214.8 6229.5 6047.2 6356.9 6370.6 6428.5 6447.7 6070.2 6415.0 6418.8 6443.3 6450.3 6252.7 6304.5 6345.4 6364.9 6365.1 5554.1 5650.4 5675.9 5737.6 5745.0 27287.7 27726.6 27829.9 27981.6 28358.9 1946.5 1985.8 2028.5 2044.6 2047.9 15942.7 16909.0 16965.5 17223.7 17379.9 1701.5 1705.1 1706.7 1710.3 1760.6 147434.5 158529.0 160576.2 161716.0 164259.0 12984.6 13059.8 13200.3 13211.9 13250.2 16513.4 16548.6 16567.8 16580.1 16607.4 8374.3 8806.4 9203.7 9288.2 10120.3 8768.8 8771.7 8822.8 8953.3 8974.1 7500.4 7963.5 8311.3 8363.1 8899.0 2208.2 2212.6 2220.1 2226.9 2399.1 926.4 930.2 932.6 964.5 971.9 20022.8 21361.6 21393.5 21530.5 21623.0 964.2 978.0 1009.9 1012.9 1014.0 9535.5 10081.3 10597.5 10989.4 10995.1 565779.0 677433.0 697114.2 699649.0 708965.0 127626.4 163532.0 164660.0 167132.5 168406.2 1183.8 1186.6 1242.0 1245.9 1255.6 20740.6 21741.9 21834.0 22058.2 22148.2 62660.7 63117.4 64265.7 64718.0 65001.0 79860.8 89850.0 92144.0 92540.0 92654.0 8064.4 8197.8 8469.6 8520.8 8944.9 11001.8 13638.9 13742.4 14169.6 14265.9 10345.0 11102.7 11455.4 11891.1 12168.8 11292.0 11388.3 11488.3 11657.8 11779.2 11851.3 12148.8 12297.5 12348.4 12399.4 8617.7 8659.7 8883.0 8955.6 9100.8 11799.5 11899.9 12084.3 12177.4 12205.0 08:07:56 INFO - PID 1641 | 08:07:56 INFO - PID 1641 | [#3] mootools.html Cycles:180 Average:6751.39 Median:2455.37 stddev:4178.49 (170.2%) stddev-sans-first:4170.08 08:07:56 INFO - PID 1641 | Values: 1293.4 1385.2 1396.1 1399.0 1406.7 10522.9 10784.0 10953.3 10978.5 11143.9 10663.8 10851.7 10910.2 10914.0 10961.6 1836.8 2352.8 2420.3 2426.0 2461.2 2338.6 2411.5 2455.2 2455.6 2456.2 2383.0 2437.9 2445.5 2449.1 2449.9 2026.2 2284.6 2318.8 2412.0 2415.9 1998.7 2063.4 2339.9 2417.0 2422.0 10824.5 10909.6 10918.5 10929.3 10941.2 6126.3 6419.2 6435.6 6444.6 6449.1 3940.4 3993.4 4011.2 4043.2 4077.8 1002.5 1156.4 1162.7 1184.1 1215.7 12269.6 15020.0 15082.2 15204.2 15204.5 5242.9 5336.3 5364.9 5368.2 5416.6 11352.5 11561.8 11566.0 11576.6 11594.1 6232.5 6260.9 6284.0 6286.2 6299.4 5527.4 5649.1 5763.4 5776.1 5839.4 3559.8 4109.3 4259.9 4291.4 4349.0 1220.3 1221.9 1229.0 1229.7 1246.3 746.4 751.8 767.9 773.6 800.1 5911.6 6013.1 6076.5 6151.9 6158.8 578.7 585.4 811.3 897.7 905.4 3617.8 4145.8 4255.6 4382.8 4395.5 12917.6 13957.1 13965.4 14031.4 14037.8 13176.5 13646.7 13695.6 13712.4 13717.3 10019.7 10239.0 10247.7 10264.4 10268.5 6508.2 6550.3 6713.9 6926.7 6944.1 11639.4 12291.7 12439.1 12601.3 12647.7 12449.6 12682.0 12741.1 12805.0 13038.8 9040.0 9282.3 9320.7 9335.2 9375.5 9723.0 10131.0 10191.5 10199.7 10211.5 8587.1 8592.7 8685.2 8864.8 8880.6 8324.1 8494.8 8499.2 8502.5 8611.3 7601.8 7835.5 7868.6 7924.6 7936.0 5618.3 5658.4 5685.9 5861.0 5866.3 4781.9 5976.9 6021.9 6026.0 6107.3 08:07:56 INFO - PID 1641 | 08:07:56 INFO - PID 1641 | [#4] prototype.html Cycles:180 Average:5382.84 Median:6017.23 stddev:1681.25 (27.9%) stddev-sans-first:1643.92 08:07:56 INFO - PID 1641 | Values: 404.6 408.5 410.7 414.6 422.1 6205.8 6346.2 6353.8 6383.8 6395.0 4849.2 5556.2 5841.4 5845.9 6223.7 4222.7 4805.3 4837.6 4838.8 4868.0 4424.9 4854.2 4861.0 4890.7 4896.8 4612.7 4626.7 4629.1 4629.2 4644.6 3850.7 4618.7 4645.1 4646.3 4657.0 3984.2 3999.8 4003.7 4008.4 4019.2 5200.7 5860.0 6102.2 6109.1 6189.9 4557.3 5039.9 5417.0 5422.3 5437.5 5645.2 6025.5 6665.7 6682.0 6688.9 4421.2 4435.8 4444.6 4444.8 4448.6 5835.4 6400.7 6976.7 7145.5 7155.9 3801.7 4353.9 4472.0 4526.2 4530.8 4032.4 4459.0 4593.1 4595.2 4599.9 5174.5 5384.5 5438.6 5459.0 5495.2 6428.2 6536.0 6747.3 6783.7 6801.5 1566.0 1574.3 1575.7 1575.9 1580.5 887.4 1011.2 1033.0 1036.6 1037.8 5419.5 5562.8 5658.2 6109.4 6345.5 6198.3 6929.3 6930.6 6987.9 7026.0 6016.1 6358.7 6428.1 6454.9 6456.6 3622.6 3641.6 3645.5 3673.4 3676.4 6848.7 7067.1 7091.8 7164.0 7180.9 6233.7 6865.8 7145.5 7146.9 7181.8 6820.2 6834.9 7096.3 7135.4 7162.2 6960.4 6978.3 6979.5 7010.8 7020.8 6547.5 6598.2 6665.8 6895.1 6916.6 5469.7 6710.2 6921.7 7150.6 7170.5 3799.6 4439.7 4450.1 4452.7 4503.5 6018.4 6049.5 6652.0 6838.0 6945.9 5961.8 6089.1 6606.9 6631.4 6671.2 5749.5 6303.8 6636.4 6639.0 6663.8 6431.3 6513.8 6523.6 6523.8 6532.3 5689.7 6308.5 6355.0 6680.8 6685.9 5696.6 6348.9 6458.2 6477.4 6489.9 08:07:56 INFO - PID 1641 | 08:07:56 INFO - PID 1641 | [#5] yui.html Cycles:180 Average:19922.76 Median:21830.68 stddev:28984.54 (132.8%) stddev-sans-first:29029.54 08:07:56 INFO - PID 1641 | Values: 599.7 704.1 710.4 720.1 746.2 14660.4 14813.5 15687.8 15920.4 16377.9 14956.3 15204.7 15277.8 15432.4 15476.5 952.7 974.2 977.2 977.9 981.5 941.2 965.3 974.1 979.4 983.5 1011.2 1028.5 1031.8 1032.3 1037.0 1019.2 1023.4 1024.1 1027.5 1028.9 764.6 765.3 766.6 766.7 773.4 16286.4 16734.7 16762.7 16853.9 16983.0 14569.3 14803.2 14812.9 14931.9 14933.0 11708.4 11838.6 11911.7 12102.7 12206.5 3039.8 3052.2 3067.0 3095.3 3167.7 109697.5 111761.0 111910.0 112022.0 116297.4 9187.6 9838.6 11781.5 11797.3 11801.1 22333.9 22799.9 22858.5 22908.9 22971.0 7794.8 8059.7 8115.7 8185.3 8289.6 4092.3 4209.0 4210.3 4211.7 4361.2 4119.2 4185.9 4211.8 4214.7 4215.8 830.1 835.2 845.3 859.2 871.3 502.5 516.3 523.6 546.2 573.6 9549.5 10037.8 10216.2 10283.3 10288.1 538.5 553.4 561.9 572.0 572.9 3973.6 4451.8 4868.6 4871.1 4878.2 98877.0 103942.0 104089.5 105103.5 105107.5 86045.6 86822.6 87455.6 87929.6 89022.0 78011.0 78374.2 79314.0 79569.0 79872.0 8737.9 8904.0 9348.0 9493.4 9544.5 37929.1 38154.6 39272.0 39687.8 39813.6 47952.5 48583.0 48711.8 49023.8 50101.7 11840.9 12385.9 13385.5 13615.3 14031.9 14657.7 14963.7 14979.9 15178.5 15244.4 13961.9 14078.9 14086.3 14103.4 14113.1 14004.0 14017.5 14027.5 14289.6 14464.2 13547.7 13876.2 13976.9 14357.2 14527.6 8314.8 8896.9 9119.2 9124.6 9361.6 7596.1 8279.5 8946.6 9998.2 10430.9 08:07:56 INFO - PID 1641 | -------- Summary: end -------- 08:07:56 INFO - PID 1641 | 08:07:56 INFO - TEST-INFO | 1641: exit 0 08:07:56 INFO - TEST-OK | dromaeo_css | took 1116223ms 08:07:56 INFO - TEST-START | kraken 08:07:56 INFO - operating with platform_type : mac_ 08:07:56 INFO - Initialising browser for kraken test... 08:07:57 INFO - TEST-INFO | started process 1652 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/T/tmpfH6g9T/profile http://localhost:49241/getInfo.html) 08:07:59 INFO - PID 1652 | 2017-03-24 08:07:59.744 plugin-container[1653:10035] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8d43, name = 'com.apple.tsm.portname' 08:07:59 INFO - PID 1652 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:07:59 INFO - PID 1652 | 2017-03-24 08:07:59.747 plugin-container[1653:10035] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x910f, name = 'com.apple.CFPasteboardClient' 08:07:59 INFO - PID 1652 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:07:59 INFO - PID 1652 | 2017-03-24 08:07:59.747 plugin-container[1653:10035] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 08:07:59 INFO - PID 1652 | __metrics Screen width/height:1600/1200 08:07:59 INFO - PID 1652 | colorDepth:24 08:07:59 INFO - PID 1652 | Browser inner width/height: 1024/689 08:07:59 INFO - PID 1652 | __metrics 08:07:59 ERROR - PID 1652 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3392: TypeError: this.tabs is undefined 08:08:00 INFO - TEST-INFO | 1652: exit 0 08:08:00 INFO - Browser initialized. 08:08:00 INFO - Running cycle 1/1 for kraken test... 08:08:00 INFO - TEST-INFO | started process 1654 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/T/tmpfH6g9T/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:08:03 INFO - PID 1654 | 2017-03-24 08:08:03.068 plugin-container[1655:10161] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4747, name = 'com.apple.tsm.portname' 08:08:03 INFO - PID 1654 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:08:03 INFO - PID 1654 | 2017-03-24 08:08:03.070 plugin-container[1655:10161] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8d17, name = 'com.apple.CFPasteboardClient' 08:08:03 INFO - PID 1654 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:08:03 INFO - PID 1654 | 2017-03-24 08:08:03.070 plugin-container[1655:10161] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 08:08:12 INFO - PID 1654 | RSS: Main: 226922496 08:08:12 INFO - PID 1654 | 08:08:15 INFO - PID 1654 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-beat-detection) 08:08:16 INFO - PID 1654 | RSS: Main: 241451008 08:08:16 INFO - PID 1654 | 08:08:19 INFO - PID 1654 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-dft) 08:08:19 INFO - PID 1654 | RSS: Main: 250036224 08:08:19 INFO - PID 1654 | 08:08:22 INFO - PID 1654 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-fft) 08:08:23 INFO - PID 1654 | RSS: Main: 252776448 08:08:23 INFO - PID 1654 | 08:08:25 INFO - PID 1654 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-oscillator) 08:08:26 INFO - PID 1654 | RSS: Main: 255025152 08:08:26 INFO - PID 1654 | 08:08:28 INFO - PID 1654 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49241/tests/kraken/driver.html?testName=imaging-gaussian-blur) 08:08:28 INFO - PID 1654 | RSS: Main: 255442944 08:08:28 INFO - PID 1654 | 08:08:31 INFO - PID 1654 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49241/tests/kraken/driver.html?testName=imaging-darkroom) 08:08:32 INFO - PID 1654 | RSS: Main: 261275648 08:08:32 INFO - PID 1654 | 08:08:35 INFO - PID 1654 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49241/tests/kraken/driver.html?testName=imaging-desaturate) 08:08:36 INFO - PID 1654 | RSS: Main: 262094848 08:08:36 INFO - PID 1654 | 08:08:39 INFO - PID 1654 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49241/tests/kraken/driver.html?testName=json-parse-financial) 08:08:40 INFO - PID 1654 | RSS: Main: 262414336 08:08:40 INFO - PID 1654 | 08:08:42 INFO - PID 1654 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49241/tests/kraken/driver.html?testName=json-stringify-tinderbox) 08:08:42 INFO - PID 1654 | RSS: Main: 261664768 08:08:42 INFO - PID 1654 | 08:08:44 INFO - PID 1654 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-aes) 08:08:45 INFO - PID 1654 | RSS: Main: 262033408 08:08:45 INFO - PID 1654 | 08:08:47 INFO - PID 1654 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-ccm) 08:08:47 INFO - PID 1654 | RSS: Main: 261505024 08:08:47 INFO - PID 1654 | 08:08:50 INFO - PID 1654 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 08:08:51 INFO - PID 1654 | RSS: Main: 261963776 08:08:51 INFO - PID 1654 | 08:08:54 INFO - PID 1654 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 08:08:55 INFO - PID 1654 | RSS: Main: 259371008 08:08:55 INFO - PID 1654 | 08:08:57 INFO - PID 1654 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49241/tests/kraken/driver.html?testName=ai-astar) 08:08:57 INFO - PID 1654 | RSS: Main: 261005312 08:08:57 INFO - PID 1654 | 08:08:57 INFO - PID 1654 | __start_tp_report 08:08:57 INFO - PID 1654 | _x_x_mozilla_page_load 08:08:57 INFO - PID 1654 | _x_x_mozilla_page_load_details 08:08:57 INFO - PID 1654 | |i|pagename|runs| 08:08:57 INFO - PID 1654 | |0;ai-astar;103;121;115;122;124;118;116;116;117;114 08:08:57 INFO - PID 1654 | |1;audio-beat-detection;148;114;116;153;116;117;112;117;134;114 08:08:57 INFO - PID 1654 | |2;audio-dft;194;206;208;220;221;200;197;200;195;197 08:08:57 INFO - PID 1654 | |3;audio-fft;73;60;62;85;58;58;70;62;77;70 08:08:57 INFO - PID 1654 | |4;audio-oscillator;79;69;84;71;72;70;86;103;95;83 08:08:57 INFO - PID 1654 | |5;imaging-gaussian-blur;88;89;88;88;83;117;97;108;136;89 08:08:57 INFO - PID 1654 | |6;imaging-darkroom;139;96;96;129;126;155;95;97;97;95 08:08:57 INFO - PID 1654 | |7;imaging-desaturate;147;91;89;92;90;93;135;93;148;145 08:08:57 INFO - PID 1654 | |8;json-parse-financial;63;64;60;60;76;104;102;98;66;65 08:08:57 INFO - PID 1654 | |9;json-stringify-tinderbox;58;58;63;64;63;57;56;59;58;59 08:08:57 INFO - PID 1654 | |10;stanford-crypto-aes;84;85;84;84;82;85;86;85;84;85 08:08:57 INFO - PID 1654 | |11;stanford-crypto-ccm;136;159;130;153;153;142;141;146;186;140 08:08:57 INFO - PID 1654 | |12;stanford-crypto-pbkdf2;189;233;231;229;214;238;235;186;184;188 08:08:57 INFO - PID 1654 | |13;stanford-crypto-sha256-iterative;68;69;74;93;104;64;63;94;67;103 08:08:57 INFO - PID 1654 | __end_tp_report 08:08:57 INFO - PID 1654 | __start_cc_report 08:08:57 INFO - PID 1654 | _x_x_mozilla_cycle_collect,1015 08:08:57 INFO - PID 1654 | __end_cc_report 08:08:57 INFO - PID 1654 | __startTimestamp1490368137803__endTimestamp 08:08:57 INFO - PID 1654 | 08:08:57 INFO - PID 1654 | ------- Summary: start ------- 08:08:57 INFO - PID 1654 | Number of tests: 14 08:08:57 INFO - PID 1654 | 08:08:57 INFO - PID 1654 | [#0] ai-astar Cycles:10 Average:116.60 Median:117.50 stddev:5.78 (4.9%) stddev-sans-first:3.44 08:08:57 INFO - PID 1654 | Values: 103.0 121.0 115.0 122.0 124.0 118.0 116.0 116.0 117.0 114.0 08:08:57 INFO - PID 1654 | 08:08:57 INFO - PID 1654 | [#1] audio-beat-detection Cycles:10 Average:124.10 Median:117.00 stddev:15.23 (13.0%) stddev-sans-first:13.47 08:08:57 INFO - PID 1654 | Values: 148.0 114.0 116.0 153.0 116.0 117.0 112.0 117.0 134.0 114.0 08:08:57 INFO - PID 1654 | 08:08:57 INFO - PID 1654 | [#2] audio-dft Cycles:10 Average:203.80 Median:203.00 stddev:9.86 (4.9%) stddev-sans-first:9.80 08:08:57 INFO - PID 1654 | Values: 194.0 206.0 208.0 220.0 221.0 200.0 197.0 200.0 195.0 197.0 08:08:57 INFO - PID 1654 | 08:08:57 INFO - PID 1654 | [#3] audio-fft Cycles:10 Average:67.50 Median:70.00 stddev:9.05 (12.9%) stddev-sans-first:9.37 08:08:57 INFO - PID 1654 | Values: 73.0 60.0 62.0 85.0 58.0 58.0 70.0 62.0 77.0 70.0 08:08:57 INFO - PID 1654 | 08:08:57 INFO - PID 1654 | [#4] audio-oscillator Cycles:10 Average:81.20 Median:81.00 stddev:11.39 (14.1%) stddev-sans-first:12.05 08:08:57 INFO - PID 1654 | Values: 79.0 69.0 84.0 71.0 72.0 70.0 86.0 103.0 95.0 83.0 08:08:57 INFO - PID 1654 | 08:08:57 INFO - PID 1654 | [#5] imaging-gaussian-blur Cycles:10 Average:98.30 Median:88.00 stddev:16.97 (19.3%) stddev-sans-first:17.59 08:08:57 INFO - PID 1654 | Values: 88.0 89.0 88.0 88.0 83.0 117.0 97.0 108.0 136.0 89.0 08:08:57 INFO - PID 1654 | 08:08:57 INFO - PID 1654 | [#6] imaging-darkroom Cycles:10 Average:112.50 Median:95.50 stddev:22.61 (23.7%) stddev-sans-first:21.85 08:08:57 INFO - PID 1654 | Values: 139.0 96.0 96.0 129.0 126.0 155.0 95.0 97.0 97.0 95.0 08:08:57 INFO - PID 1654 | 08:08:57 INFO - PID 1654 | [#7] imaging-desaturate Cycles:10 Average:112.30 Median:90.50 stddev:27.31 (30.2%) stddev-sans-first:25.92 08:08:57 INFO - PID 1654 | Values: 147.0 91.0 89.0 92.0 90.0 93.0 135.0 93.0 148.0 145.0 08:08:57 INFO - PID 1654 | 08:08:57 INFO - PID 1654 | [#8] json-parse-financial Cycles:10 Average:75.80 Median:64.50 stddev:18.23 (28.3%) stddev-sans-first:18.73 08:08:57 INFO - PID 1654 | Values: 63.0 64.0 60.0 60.0 76.0 104.0 102.0 98.0 66.0 65.0 08:08:57 INFO - PID 1654 | 08:08:57 INFO - PID 1654 | [#9] json-stringify-tinderbox Cycles:10 Average:59.50 Median:59.00 stddev:2.80 (4.7%) stddev-sans-first:2.92 08:08:57 INFO - PID 1654 | Values: 58.0 58.0 63.0 64.0 63.0 57.0 56.0 59.0 58.0 59.0 08:08:57 INFO - PID 1654 | 08:08:57 INFO - PID 1654 | [#10] stanford-crypto-aes Cycles:10 Average:84.40 Median:85.00 stddev:1.07 (1.3%) stddev-sans-first:1.13 08:08:57 INFO - PID 1654 | Values: 84.0 85.0 84.0 84.0 82.0 85.0 86.0 85.0 84.0 85.0 08:08:57 INFO - PID 1654 | 08:08:57 INFO - PID 1654 | [#11] stanford-crypto-ccm Cycles:10 Average:148.60 Median:149.50 stddev:15.75 (10.5%) stddev-sans-first:16.03 08:08:57 INFO - PID 1654 | Values: 136.0 159.0 130.0 153.0 153.0 142.0 141.0 146.0 186.0 140.0 08:08:57 INFO - PID 1654 | 08:08:57 INFO - PID 1654 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:212.70 Median:230.00 stddev:23.24 (10.1%) stddev-sans-first:23.01 08:08:57 INFO - PID 1654 | Values: 189.0 233.0 231.0 229.0 214.0 238.0 235.0 186.0 184.0 188.0 08:08:57 INFO - PID 1654 | 08:08:57 INFO - PID 1654 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:79.90 Median:68.50 stddev:16.62 (24.3%) stddev-sans-first:17.06 08:08:57 INFO - PID 1654 | Values: 68.0 69.0 74.0 93.0 104.0 64.0 63.0 94.0 67.0 103.0 08:08:57 INFO - PID 1654 | -------- Summary: end -------- 08:08:57 INFO - PID 1654 | 08:08:58 INFO - TEST-INFO | 1654: exit 0 08:08:58 INFO - TEST-OK | kraken | took 61854ms 08:08:58 INFO - SUITE-END | took 1178s 08:08:58 INFO - Completed test suite (00:19:38) 08:08:58 INFO - javascript benchmark 08:08:58 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7723.419350673033, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2803.761680257179, 3004.6694863565003, 3535.840887160079, 3543.681068703818, 3560.8397622107004, 5636.830895073144, 6136.355682653319, 6176.227971503562, 6187.381261873816, 6216.502679785618, 6048.788292409767, 6100.853682747669, 6129.203203583528, 6141.355157708444, 6180.629162250269, 5487.0, 5504.284443022401, 5539.556835453154, 5668.008098582752, 5681.573881958849, 5436.782528698867, 5568.415316391782, 5629.099344104944, 5664.603477756558, 5669.489745922866, 4694.530546945312, 5551.694656793875, 5555.527780138693, 5632.35227948787, 5665.518430933375, 5503.532199763025, 5521.868017056494, 5562.694051827148, 5581.358143813432, 5646.407127251661, 5526.557875369961, 5530.253415788898, 5535.6955367454775, 5560.471755183251, 5585.497305242507, 4748.0, 4794.688345257547, 5958.225430693982, 6135.785247516315, 6240.407161319713, 5393.865153371197, 6383.648899310536, 6485.967570162166, 6569.540132190748, 6576.375244351779, 5311.707856067915, 6664.300248473937, 6668.733250669968, 6692.59844409337, 6694.564853284521, 5207.583393328525, 6259.530535209878, 6298.4331410172845, 6310.6213627182515, 6323.5573509854785, 6524.151860258136, 6550.8362290943105, 6706.865862682719, 6772.085768421299, 6799.014142949245, 6425.293217746044, 6456.386643268882, 6498.642574658439, 6505.739770409131, 6512.869742605121, 6354.872902541923, 6402.551821372553, 6427.871442571216, 6440.388163124496, 6472.579282346726, 6401.9039714404325, 6462.38607332312, 6471.611703297723, 6473.255575608771, 6489.7404103835315, 6503.739850406026, 6523.3150519194805, 6532.346765323429, 6548.3124271950755, 6549.083128362033, 5051.9390927904815, 5212.452692467236, 5222.2427747976335, 5230.0, 5282.6302158849285, 4689.1559519286175, 4700.341952126705, 4738.881527961828, 4744.620430365562, 4780.976095119572, 6268.686565671789, 6271.592346497463, 6287.842803929939, 6289.496840252768, 6308.526860485482, 5526.917096243559, 6395.2645445773405, 6480.740770369227, 6635.236947750974, 6681.398674119256, 4981.726005069756, 6223.222097237845, 6299.622022678654, 6302.369763023661, 6358.364163583605, 5888.558358123158, 5893.852653683606, 5902.439268269508, 5908.734106965197, 5948.078047902582, 6561.934380656323, 6784.559003664746, 6799.3200679931615, 6827.69275382609, 6837.6239306836615, 6501.642409667514, 6615.17310336208, 6684.364985326386, 6693.698783554654, 6712.194536655633, 5652.915206271991, 6789.287124852017, 6838.897416538855, 6840.281770414035, 6853.897191542031, 6274.811755647338, 6522.34776522344, 6534.313897040933, 6540.084388185753, 6548.934510654833, 6442.355764423708, 6523.445507131757, 6535.836604084936, 6652.600843949185, 6664.433523150587, 6363.840903977438, 6432.935670643234, 6445.323241059621, 6447.2263328400895, 6454.0, 6239.875202496016, 6264.561480696308, 6395.360463953567, 6396.104545363746, 6396.392342727433, 6141.293751218488, 6268.717907694074, 6269.373062693694, 6292.528060395397, 6320.5575609706875, 6361.268454127835, 6383.170187875547, 6395.840103997251, 6404.583702059351, 6404.775832845828, 6147.569670123048, 6299.401556852092, 6305.8108256752375, 6319.8104056878365, 6320.115183874169], "value": 5992.926189997209, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [629.2763322179493, 677.3801971196355, 820.1265652080535, 1153.8615366156062, 1270.0728468218206, 37698.81150594247, 38123.61876381235, 38213.426798597975, 38221.617783822156, 38237.23525529488, 37799.62200377995, 38076.0, 38204.80897595508, 38269.617303826955, 38270.61729382713, 7996.800079998017, 8489.915100849004, 8672.0, 8693.347998900108, 8724.825503489927, 8659.177378149097, 8759.737207883742, 8769.429987050822, 8772.429792063527, 8776.69281575145, 8716.956415217915, 8748.212660860521, 8796.296296296281, 8799.868001979943, 8803.603837827313, 8701.651933922636, 8763.737087887373, 8769.254613357873, 8770.693025744133, 8793.648254069862, 8598.828023439559, 8617.0, 8655.0, 8679.956600216958, 8720.956395217983, 38101.809490952364, 38336.0, 38372.232555349015, 38479.61520384788, 38501.614983850086, 2877.0176940071237, 2986.417648558532, 3003.459377312083, 3003.819770813758, 3051.862666180005, 26818.731812681817, 26879.0, 27077.916883324644, 27437.451250974867, 27815.860920695468, 2231.8884055797143, 2751.9036833710725, 2763.2124844419245, 2781.3185769486404, 2887.422515496888, 335495.0, 336861.0, 338443.0, 340262.29868850746, 340297.29851350834, 52472.475275247525, 52744.472555273955, 52827.20759188615, 52853.94292114136, 53089.0, 54985.0, 55067.72466137644, 55300.44699552953, 55395.892082158134, 55886.72056639772, 14717.55847324582, 14784.556463305902, 14860.851391485947, 14867.330970106372, 14882.702345953021, 10160.441175735405, 11935.403229838646, 11987.700307492383, 12010.279383237033, 12026.098042646836, 13337.333133343294, 14172.716545669029, 14184.936129790307, 14221.786673199911, 14257.21585312797, 6121.602095863755, 6125.2649682037545, 6181.103739957681, 6185.876282474415, 6197.5351848611535, 937.6999360204669, 964.4936408385654, 966.5264020629982, 989.4063561862969, 997.3118548201718, 24186.790660466908, 24234.394140146636, 24463.38841528976, 24483.265502034967, 24900.0, 963.6723514005272, 1054.4622242656146, 1095.1074873977682, 1101.1136035491347, 1101.2236373356764, 10256.794864102676, 11775.411229438494, 11899.762004759856, 12337.383130843422, 12452.0, 467134.0, 468393.0, 470210.0, 470238.0, 472807.0, 40879.59120408758, 53876.46123538793, 54158.7292063545, 54193.39356154692, 54489.0, 1336.4921329894578, 1488.6129606302438, 1488.7320282349065, 1507.5025241670442, 1513.9243037848285, 24664.0, 27053.91784328605, 27685.0, 28442.146735597966, 28504.714952850205, 35808.0, 35885.820570896976, 35912.102197445274, 35979.64020359763, 36057.737979170604, 280430.5978470095, 284623.0, 351927.0, 353469.0, 354632.0, 25793.742062579135, 30814.921477748172, 32877.0, 34299.0, 34804.9558513245, 38164.809175953946, 42624.36063458989, 42664.360034600126, 43276.0, 44107.0, 29505.557416639196, 30016.0, 30433.087007389815, 30439.543406848472, 30760.923367682022, 29241.415171696874, 30631.54052689167, 30660.0, 30673.693263067977, 30710.0, 31911.521327179646, 33945.151371215914, 35408.0, 36305.72929947439, 36646.633533664324, 29805.70194298116, 36906.63093369139, 37210.441843372915, 37704.245915080995, 38429.80785096057, 28584.857075714488, 29973.50132493323, 30039.94860179883, 30211.546826798054, 30963.84518077395], "value": 17894.292182096924, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [424.2987696324722, 427.8649538801378, 434.4157108688813, 445.98439054633087, 456.1287940034535, 19025.33411330602, 20629.27797527085, 21213.469663258427, 21422.785772142273, 21448.9623485769, 21513.247036353732, 21754.673679894815, 21785.564288714217, 22031.889840550815, 22087.0, 6027.336992930785, 6170.907436388436, 6213.720382582772, 6214.8135555933395, 6229.501639868799, 6047.183630209909, 6356.936430635704, 6370.649614271213, 6428.453581445583, 6447.677616119198, 6070.1501789749245, 6414.9679251603675, 6418.775342863, 6443.323451037644, 6450.258220304678, 6252.718627661766, 6304.495640348784, 6345.365463453664, 6364.904526432061, 6365.077063825743, 5554.1391084381985, 5650.434956504358, 5675.9432405675825, 5737.627054241461, 5744.971275143639, 27287.727122728917, 27726.5841012385, 27829.886804527796, 27981.58027629587, 28358.858205709046, 1946.4549926020736, 1985.8411327093797, 2028.5131568423471, 2044.6217449771839, 2047.8668886522328, 15942.688310194957, 16909.0, 16965.491035268962, 17223.655526889394, 17379.913100434544, 1701.4895531340549, 1705.0962989615523, 1706.7183914654067, 1710.2560386232028, 1760.5950631354733, 147434.52565474316, 158529.0, 160576.19711901483, 161716.0, 164259.0, 12984.610461686165, 13059.804102938464, 13200.339983001004, 13211.933940330237, 13250.204987700768, 16513.422030228885, 16548.58628534272, 16567.834321656628, 16580.088095154642, 16607.41874034403, 8374.330053595697, 8806.383553151347, 9203.677871274473, 9288.164065234161, 10120.330145525906, 8768.824623507495, 8771.692990745294, 8822.823543529094, 8953.328500362499, 8974.14745599167, 7500.399968002546, 7963.544971694292, 8311.293540049042, 8363.118298382937, 8899.02110767815, 2208.216083290441, 2212.5524012466676, 2220.1452440810403, 2226.855254408458, 2399.064364897691, 926.4441335198961, 930.2093220762298, 932.6316105138433, 964.5225613321451, 971.8882328532168, 20022.799772002385, 21361.57276854454, 21393.46516337073, 21530.46173845635, 21623.0, 964.1659964130977, 977.9559919803626, 1009.9394036357842, 1012.9088382045651, 1013.9898601014042, 9535.47554884488, 10081.294309398418, 10597.4701264938, 10989.395583242998, 10995.065419439437, 565779.0, 677433.0, 697114.2308615298, 699649.0, 708965.0, 127626.36186819007, 163532.0, 164660.0, 167132.4628725202, 168406.15796920937, 1183.7810005149074, 1186.5846953566183, 1241.9689507762378, 1245.9377031148406, 1255.6421419895287, 20740.58518829585, 21741.891290543444, 21834.0, 22058.227962021254, 22148.2248121315, 62660.68669656623, 63117.36882631115, 64265.67867160634, 64718.02922956253, 65001.0, 79860.80208796989, 89850.0, 92144.0, 92540.0, 92654.0, 8064.395170362129, 8197.83604327922, 8469.57652117404, 8520.786980325542, 8944.910550894409, 11001.834972475259, 13638.93180534091, 13742.381592828548, 14169.574912752634, 14265.928670356581, 10344.965503449595, 11102.722431939266, 11455.418626341927, 11891.108166887345, 12168.817467738168, 11292.0, 11388.259763115371, 11488.310701357945, 11657.76684466289, 11779.234349767237, 11851.348175850411, 12148.817767733668, 12297.50809967627, 12348.444320005441, 12399.442025109072, 8617.741467755977, 8659.653613855375, 8883.0, 8955.59699813497, 9100.772480688036, 11799.469023894117, 11899.881001190224, 12084.335361555199, 12177.391130443266, 12205.023598111951], "value": 10648.986553688426, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1293.4438191577622, 1385.224274406333, 1396.1413730555712, 1399.0, 1406.7467855785958, 10522.894771052288, 10784.029437350637, 10953.34279943204, 10978.505967231475, 11143.944280278587, 10663.840042399352, 10851.6744497665, 10910.181736369765, 10914.0, 10961.56153753851, 1836.8163183681593, 2352.8353015288935, 2420.3465064432594, 2425.963610545843, 2461.163204510466, 2338.649202619604, 2411.5056413435204, 2455.165243817102, 2455.5702752018415, 2456.238566044532, 2383.0467812874904, 2437.926862194137, 2445.4619983603666, 2449.1428000199876, 2449.9265022049367, 2026.2401599400225, 2284.554511870186, 2318.8260880434036, 2411.986965474496, 2415.927522174338, 1998.6502362086576, 2063.4222417723063, 2339.9181028663916, 2417.0, 2422.0, 10824.458777061116, 10909.563617455371, 10918.454077296103, 10929.289596176222, 10941.23411361205, 6126.264848218198, 6419.197600299963, 6435.581687190364, 6444.581102228341, 6449.12936753537, 3940.4089386591954, 3993.3610622300293, 4011.2178125265586, 4043.1509383029597, 4077.8164982575854, 1002.4937406609615, 1156.3639998001065, 1162.738383863624, 1184.0882520459284, 1215.7264615461538, 12269.631911042772, 15020.0, 15082.170480623561, 15204.239788010555, 15204.543863684106, 5242.868928276824, 5336.306280183629, 5364.946350536523, 5368.248445217672, 5416.620836541404, 11352.48913798881, 11561.826572601418, 11566.016888564398, 11576.594819181288, 11594.130440216848, 6232.501399887998, 6260.937390626126, 6284.0, 6286.214223222098, 6299.370062993664, 5527.419620939743, 5649.12438572022, 5763.366029736726, 5776.133579962955, 5839.357670656309, 3559.8042107684328, 4109.3425051991535, 4259.893502662459, 4291.399204111427, 4349.0, 1220.3227208898968, 1221.9022478201723, 1228.975420491585, 1229.7171650520431, 1246.3020708403324, 746.4476287547285, 751.7894989402976, 767.9308862202429, 773.5668025905511, 800.0672491630992, 5911.586188966817, 6013.128096425994, 6076.483498902554, 6151.907721384183, 6158.753649853956, 578.6788332475433, 585.4175095779726, 811.290121143999, 897.6723495924072, 905.3933864310927, 3617.76995821422, 4145.7719825409895, 4255.553166917491, 4382.802773875183, 4395.472543294825, 12917.612471625866, 13957.092788968685, 13965.371558279901, 14031.368588413547, 14037.78943315851, 13176.538821141407, 13646.727065458635, 13695.589132326046, 13712.3829427676, 13717.314134293245, 10019.699409017741, 10238.97610238985, 10247.692569222787, 10264.384136951658, 10268.537915793808, 6508.186476690414, 6550.344965503412, 6713.865722685421, 6926.722931082701, 6944.062551555476, 11639.41802909868, 12291.69270768238, 12439.067069969607, 12601.306928119042, 12647.683807904876, 12449.626511204679, 12682.048846336376, 12741.10812243134, 12805.0, 13038.804417933548, 9040.0, 9282.257419406296, 9320.673776417792, 9335.159835614555, 9375.484348360633, 9723.027697230504, 10131.037551432602, 10191.490425478845, 10199.694009179737, 10211.540480678239, 8587.110835616155, 8592.742217733477, 8685.174908383693, 8864.778380540538, 8880.555972201493, 8324.125966773401, 8494.830103397773, 8499.235068843835, 8502.489850608983, 8611.311095112502, 7601.809954751176, 7835.49069310493, 7868.6459109339075, 7924.564148971862, 7935.960320198362, 5618.297712785902, 5658.434156584309, 5685.914711279417, 5860.970695146497, 5866.325372582208, 4781.880452988703, 5976.9103463448955, 6021.909671355021, 6026.0, 6107.267127944675], "value": 5108.2722833995285, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [404.565092525535, 408.4547129582007, 410.65504975820306, 414.55642462565066, 422.08407755171294, 6205.84485387865, 6346.238451385829, 6353.777617783378, 6383.776567820121, 6394.968025159879, 4849.248366503189, 5556.222128901947, 5841.445062719045, 5845.8830823383505, 6223.719932603032, 4222.740833653996, 4805.279208118793, 4837.581209395303, 4838.838678717118, 4868.0, 4424.911501769979, 4854.150523658363, 4860.951390486103, 4890.706557606537, 4896.755162241873, 4612.700174488664, 4626.709778659569, 4629.051044535862, 4629.189891768944, 4644.60520855726, 3850.7497012694084, 4618.699784514012, 4645.094206629708, 4646.349511068419, 4656.952185758211, 3984.183242435294, 3999.7800120993334, 4003.739756915791, 4008.398740188966, 4019.216252830699, 5200.699825043739, 5859.970700146472, 6102.20671312731, 6109.14471973924, 6189.87620247597, 4557.316402539613, 5039.874003149914, 5416.970775552632, 5422.267993820823, 5437.483439073281, 5645.2096706461125, 6025.517958563304, 6665.666716664147, 6681.966590167018, 6688.899666504958, 4421.248387774086, 4435.844745433894, 4444.644383463043, 4444.755538445384, 4448.644108471314, 5835.416458354131, 6400.655862268929, 6976.720941157597, 7145.535540189871, 7155.856882862314, 3801.7338786284695, 4353.934690979638, 4471.977640111779, 4526.185286648369, 4530.77346132692, 4032.354823228269, 4458.9554104459185, 4593.127305811885, 4595.2188128018315, 4599.862004139881, 5174.482551744871, 5384.454012367268, 5438.619296649196, 5458.972705136449, 5495.230667706524, 6428.228612566429, 6535.967320163369, 6747.334802752984, 6783.660816959133, 6801.4558835293055, 1565.997761432684, 1574.3073047858904, 1575.708493928627, 1575.8818088643397, 1580.5021418253214, 887.3522328700085, 1011.1910471622673, 1032.9896701033044, 1036.6009086501751, 1037.7561273100816, 5419.512243897989, 5562.805301814498, 5658.151277308437, 6109.419605137505, 6345.49236061114, 6198.349173336735, 6929.3070692930305, 6930.584164950018, 6987.8602427951155, 7026.0, 6016.067509536029, 6358.7456501740335, 6428.132202152649, 6454.903176452357, 6456.5787829563915, 3622.565292164957, 3641.5812181598926, 3645.54430696163, 3673.412254039394, 3676.3750162472443, 6848.6918088686125, 7067.081279433641, 7091.822704432328, 7164.0, 7180.856382872314, 6233.659763150944, 6865.828354291183, 7145.499815013003, 7146.92853071473, 7181.820454488575, 6820.249772524919, 6834.931650683529, 7096.254893236239, 7135.393491553173, 7162.247963963813, 6960.443164546723, 6978.267281935324, 6979.4765392594745, 7010.824729381806, 7020.824479388056, 6547.476201903741, 6598.208215014229, 6665.833354166185, 6895.069165662571, 6916.619585922823, 5469.699166545878, 6710.16122984627, 6921.688524016331, 7150.570965742072, 7170.462215333975, 3799.6010418905616, 4439.733615983051, 4450.087732014865, 4452.688311818141, 4503.482099558592, 6018.398160183947, 6049.485793707408, 6652.0, 6837.974303854462, 6945.853934101019, 5961.791337303173, 6089.056196289582, 6606.9008964866525, 6631.436327911989, 6671.199456065303, 5749.540036796962, 6303.842403939938, 6636.435902948304, 6639.0, 6663.766768163091, 6431.29255781873, 6513.772017979348, 6523.597426553314, 6523.8042858712415, 6532.346765323429, 5689.7439615218245, 6308.463780578702, 6355.046742988404, 6680.799576012728, 6685.8997115044285, 5696.601237913306, 6348.936510634834, 6458.22501299847, 6477.4170324671, 6489.902651460137], "value": 4858.300786913095, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [599.6971529377664, 704.1128178495098, 710.3571268002459, 720.1178556268575, 746.231381676873, 14660.413583456662, 14813.481528146516, 15687.764683529727, 15920.442784502544, 16377.91811040946, 14956.252187390613, 15204.695906081872, 15277.847221527782, 15432.3827046918, 15476.458323958665, 952.728472385372, 974.1816873825985, 977.1596427072711, 977.8728765260506, 981.4601968917103, 941.1623654947102, 965.2663975378728, 974.0843607009424, 979.4221409368471, 983.4836710726889, 1011.1960991012127, 1028.5371582787775, 1031.7987992341496, 1032.2825636182856, 1037.0303765978833, 1019.2050200843317, 1023.4422239879284, 1024.1499555369026, 1027.547878933267, 1028.8508166315842, 764.552736649061, 765.3226894198618, 766.624354066509, 766.651173715958, 773.443120952913, 16286.429974950819, 16734.665306693918, 16762.748558771505, 16853.91573042139, 16983.0, 14569.344379502947, 14803.162110257004, 14812.851871481254, 14931.850681493155, 14933.0, 11708.414579271002, 11838.644840654793, 11911.702207444796, 12102.697432564168, 12206.511739530497, 3039.8176109433507, 3052.2216834707006, 3066.953995690066, 3095.2880837407297, 3167.748739248007, 109697.45151274273, 111761.0, 111910.0, 112022.0, 116297.4185129069, 9187.586558604879, 9838.557264923096, 11781.52873885035, 11797.29216247028, 11801.05591552691, 22333.888330558242, 22799.886000570223, 22858.542829143324, 22908.885455572614, 22971.0, 7794.844103117905, 8059.677612895536, 8115.675372985014, 8185.263326300662, 8289.58552072394, 4092.263392589303, 4208.978955105266, 4210.263203939298, 4211.7472951623, 4361.214981303396, 4119.155573107506, 4185.937210941838, 4211.831526738957, 4214.7471151731, 4215.810288537024, 830.0620299062022, 835.1564919431297, 845.3152946113668, 859.2181115185151, 871.2986046232843, 502.53264464048783, 516.2540129512818, 523.602062432554, 546.1780021068267, 573.6357413042665, 9549.534284532481, 10037.849432258521, 10216.233782466345, 10283.331583447052, 10288.141762356661, 538.526097034607, 553.3802141601432, 561.9100943848964, 571.9571032172604, 572.9028909637997, 3973.562908080109, 4451.821927122944, 4868.561829435368, 4871.084319431314, 4878.170710979142, 98877.0, 103942.0, 104089.47955260176, 105103.47448262863, 105107.47446262873, 86045.56977215073, 86822.56588717143, 87455.56272218598, 87929.56035219911, 89022.0, 78011.0, 78374.21625783783, 79314.0, 79569.0, 79872.0, 8737.868931966153, 8904.020557738773, 9347.953260233655, 9493.382930109521, 9544.522773861141, 37929.051773705876, 38154.61845381511, 39272.018199544094, 39687.80156099201, 39813.60186398215, 47952.52047479481, 48583.0, 48711.75644121898, 49023.754881225366, 50101.749491253766, 11840.940795295968, 12385.876141238472, 13385.464581416634, 13615.25116118623, 14031.859681403055, 14657.706845863237, 14963.700725985636, 14979.925100374428, 15178.468753593572, 15244.3902243909, 13961.930190348983, 14078.929605351908, 14086.295685215493, 14103.365348559544, 14113.08264962774, 14004.0, 14017.5093871714, 14027.50903718365, 14289.642758930693, 14464.20446875432, 13547.661308467366, 13876.236806975714, 13976.860231397555, 14357.21035343066, 14527.636809079857, 8314.750557483285, 8896.911030889609, 9119.179273865384, 9124.635014599342, 9361.578728957076, 7596.126445458843, 8279.54462504568, 8946.642134314554, 9998.20014398861, 10430.947845260725], "value": 7488.67521085118, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1577.2, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [103.0, 121.0, 115.0, 122.0, 124.0, 118.0, 116.0, 116.0, 117.0, 114.0], "value": 116.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 114.0, 116.0, 153.0, 116.0, 117.0, 112.0, 117.0, 134.0, 114.0], "value": 124.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [194.0, 206.0, 208.0, 220.0, 221.0, 200.0, 197.0, 200.0, 195.0, 197.0], "value": 203.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 60.0, 62.0, 85.0, 58.0, 58.0, 70.0, 62.0, 77.0, 70.0], "value": 67.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 69.0, 84.0, 71.0, 72.0, 70.0, 86.0, 103.0, 95.0, 83.0], "value": 81.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 89.0, 88.0, 88.0, 83.0, 117.0, 97.0, 108.0, 136.0, 89.0], "value": 98.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [139.0, 96.0, 96.0, 129.0, 126.0, 155.0, 95.0, 97.0, 97.0, 95.0], "value": 112.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 91.0, 89.0, 92.0, 90.0, 93.0, 135.0, 93.0, 148.0, 145.0], "value": 112.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 64.0, 60.0, 60.0, 76.0, 104.0, 102.0, 98.0, 66.0, 65.0], "value": 75.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 58.0, 63.0, 64.0, 63.0, 57.0, 56.0, 59.0, 58.0, 59.0], "value": 59.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 85.0, 84.0, 84.0, 82.0, 85.0, 86.0, 85.0, 84.0, 85.0], "value": 84.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [136.0, 159.0, 130.0, 153.0, 153.0, 142.0, 141.0, 146.0, 186.0, 140.0], "value": 148.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [189.0, 233.0, 231.0, 229.0, 214.0, 238.0, 235.0, 186.0, 184.0, 188.0], "value": 212.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 69.0, 74.0, 93.0, 104.0, 64.0, 63.0, 94.0, 67.0, 103.0], "value": 79.9, "unit": "score"}]}]} 08:08:59 INFO - Return code: 0 08:08:59 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 08:08:59 ERROR - # TBPL SUCCESS # 08:08:59 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:08:59 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:08:59 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:08:59 INFO - 2017-03-24 08:08:59.125 screenresolution[1659:10556] starting screenresolution argv=screenresolution get 08:08:59 INFO - 2017-03-24 08:08:59.128 screenresolution[1659:10556] Display 0: 1600x1200x32@60 08:08:59 INFO - 2017-03-24 08:08:59.134 screenresolution[1660:10559] starting screenresolution argv=screenresolution list 08:08:59 INFO - Available Modes on Display 0 08:08:59 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:08:59 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:08:59 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:08:59 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:08:59 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:08:59 INFO - Intel Iris: 08:08:59 INFO - Chipset Model: Intel Iris 08:08:59 INFO - Type: GPU 08:08:59 INFO - Bus: Built-In 08:08:59 INFO - VRAM (Dynamic, Max): 1536 MB 08:08:59 INFO - Vendor: Intel (0x8086) 08:08:59 INFO - Device ID: 0x0a2e 08:08:59 INFO - Revision ID: 0x0009 08:08:59 INFO - Displays: 08:08:59 INFO - Display: 08:08:59 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:08:59 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:08:59 INFO - Display Serial Number: 1600x1200 60 08:08:59 INFO - Main Display: Yes 08:08:59 INFO - Mirror: Off 08:08:59 INFO - Online: Yes 08:08:59 INFO - Rotation: Supported 08:08:59 INFO - Return code: 0 08:08:59 INFO - Running post-action listener: _resource_record_post_action 08:08:59 INFO - [mozharness: 2017-03-24 15:08:59.372049Z] Finished run-tests step (success) 08:08:59 INFO - Running post-run listener: _resource_record_post_run 08:08:59 INFO - Total resource usage - Wall time: 1199s; CPU: 54.0%; Read bytes: 37771776; Write bytes: 517349888; Read time: 399; Write time: 1740 08:08:59 INFO - TinderboxPrint: CPU usage
54.4% 08:08:59 INFO - TinderboxPrint: I/O read bytes / time
37,771,776 / 399 08:08:59 INFO - TinderboxPrint: I/O write bytes / time
517,349,888 / 1,740 08:08:59 INFO - TinderboxPrint: CPU idle
2,188.5 (45.6%) 08:08:59 INFO - TinderboxPrint: CPU system
964.1 (20.1%) 08:08:59 INFO - TinderboxPrint: CPU user
1,645.1 (34.3%) 08:08:59 INFO - TinderboxPrint: Swap in / out
249,266,176 / 0 08:08:59 INFO - install - Wall time: 21s; CPU: 54.0%; Read bytes: 204835328; Write bytes: 205108736; Read time: 15729; Write time: 455 08:08:59 INFO - run-tests - Wall time: 1179s; CPU: 54.0%; Read bytes: 35842560; Write bytes: 301423616; Read time: 374; Write time: 1248 08:08:59 INFO - Running post-run listener: _upload_blobber_files 08:08:59 INFO - Blob upload gear active. 08:08:59 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 08:08:59 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 08:08:59 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'] 08:08:59 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 08:09:00 INFO - (blobuploader) - INFO - Open directory for files ... 08:09:00 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 08:09:00 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:09:00 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:09:00 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 08:09:00 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:09:00 INFO - (blobuploader) - INFO - Done attempting. 08:09:00 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 08:09:01 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:09:01 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:09:01 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 08:09:01 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:09:01 INFO - (blobuploader) - INFO - Done attempting. 08:09:01 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 08:09:01 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:09:01 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:09:02 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 08:09:02 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:09:02 INFO - (blobuploader) - INFO - Done attempting. 08:09:02 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 08:09:02 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:09:02 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:09:04 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 08:09:04 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:09:04 INFO - (blobuploader) - INFO - Done attempting. 08:09:04 INFO - (blobuploader) - INFO - Iteration through files over. 08:09:04 INFO - Return code: 0 08:09:04 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 08:09:04 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 08:09:04 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/264a477e00794b32db3d36f9caebb891d1c88ded3904df10ee84342bfdacccc9e1d222d374fcd240fca08e0b7aa652541f05b21cefc73fee47564924e34bb6d5", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f896be81e85b5dc74ae9166334f656c99d84b1add56ed569c73f96fe2c93e04bee8afa458821b17e71b556a5a720a3d525f90a73c156e4ea92bc28e2ec757127", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e152025e07ed7d301e9f022b8f054821f77ede456deb2f4f7d9140f91d74ccf222356c7eb4483a7c2a7d85f72745807015f247bea2046eec0fdc2cd9e2d16e3f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/859d5d871f68e4d9b078cdafd50479c8830e72b167b2023962093ab65f3cb8774d5df13a7c10df71bd1d53a9df1be03c20250a29be0325918fe91c66397e37c4"} 08:09:04 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 08:09:04 INFO - Writing to file /builds/slave/test/properties/blobber_files 08:09:04 INFO - Contents: 08:09:04 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/264a477e00794b32db3d36f9caebb891d1c88ded3904df10ee84342bfdacccc9e1d222d374fcd240fca08e0b7aa652541f05b21cefc73fee47564924e34bb6d5", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f896be81e85b5dc74ae9166334f656c99d84b1add56ed569c73f96fe2c93e04bee8afa458821b17e71b556a5a720a3d525f90a73c156e4ea92bc28e2ec757127", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e152025e07ed7d301e9f022b8f054821f77ede456deb2f4f7d9140f91d74ccf222356c7eb4483a7c2a7d85f72745807015f247bea2046eec0fdc2cd9e2d16e3f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/859d5d871f68e4d9b078cdafd50479c8830e72b167b2023962093ab65f3cb8774d5df13a7c10df71bd1d53a9df1be03c20250a29be0325918fe91c66397e37c4"} 08:09:04 INFO - Running post-run listener: copy_logs_to_upload_dir 08:09:04 INFO - Copying logs to upload dir... 08:09:04 INFO - mkdir: /builds/slave/test/build/upload/logs 08:09:04 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1281.055713 ========= master_lag: 0.07 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 21 secs) (at 2017-03-24 08:09:04.744946) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-24 08:09:04.750061) ========= 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.1v0VTUJrq7/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.7RvTLMKB34/Listeners TMPDIR=/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/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/264a477e00794b32db3d36f9caebb891d1c88ded3904df10ee84342bfdacccc9e1d222d374fcd240fca08e0b7aa652541f05b21cefc73fee47564924e34bb6d5", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f896be81e85b5dc74ae9166334f656c99d84b1add56ed569c73f96fe2c93e04bee8afa458821b17e71b556a5a720a3d525f90a73c156e4ea92bc28e2ec757127", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e152025e07ed7d301e9f022b8f054821f77ede456deb2f4f7d9140f91d74ccf222356c7eb4483a7c2a7d85f72745807015f247bea2046eec0fdc2cd9e2d16e3f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/859d5d871f68e4d9b078cdafd50479c8830e72b167b2023962093ab65f3cb8774d5df13a7c10df71bd1d53a9df1be03c20250a29be0325918fe91c66397e37c4"} build_url:https://queue.taskcluster.net/v1/task/bEymYBzvTWGCX6P5gG7g7g/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011237 build_url: 'https://queue.taskcluster.net/v1/task/bEymYBzvTWGCX6P5gG7g7g/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/264a477e00794b32db3d36f9caebb891d1c88ded3904df10ee84342bfdacccc9e1d222d374fcd240fca08e0b7aa652541f05b21cefc73fee47564924e34bb6d5", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f896be81e85b5dc74ae9166334f656c99d84b1add56ed569c73f96fe2c93e04bee8afa458821b17e71b556a5a720a3d525f90a73c156e4ea92bc28e2ec757127", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e152025e07ed7d301e9f022b8f054821f77ede456deb2f4f7d9140f91d74ccf222356c7eb4483a7c2a7d85f72745807015f247bea2046eec0fdc2cd9e2d16e3f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/859d5d871f68e4d9b078cdafd50479c8830e72b167b2023962093ab65f3cb8774d5df13a7c10df71bd1d53a9df1be03c20250a29be0325918fe91c66397e37c4"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-24 08:09:04.788866) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-24 08:09:04.789266) ========= 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.1v0VTUJrq7/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.7RvTLMKB34/Listeners TMPDIR=/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/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.005099 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-24 08:09:04.838483) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-24 08:09:04.841956) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-24 08:09:04.842336) ========= ========= Total master_lag: 0.22 =========