builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0289 starttime: 1491903524.93 results: success (0) buildid: 20170411011827 builduid: 1638bd6c86fd48f88b8da4f76aeb68e2 revision: 7031c811659d90275e7b8e1557027791081767c5 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-04-11 02:38:44.930071) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-04-11 02:38:44.930530) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-11 02:38:44.930822) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-11 02:38:44.957737) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-11 02:38:44.958121) ========= 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.rdlV1Avf4l/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.eVQPp8suJy/Listeners TMPDIR=/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/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-04-11 02:38:44-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 18.4M=0.001s 2017-04-11 02:38:45 (18.4 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.129963 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-11 02:38:45.112098) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-11 02:38:45.112407) ========= 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.rdlV1Avf4l/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.eVQPp8suJy/Listeners TMPDIR=/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/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.026877 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-11 02:38:45.161526) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-04-11 02:38:45.161890) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 7031c811659d90275e7b8e1557027791081767c5 --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 7031c811659d90275e7b8e1557027791081767c5 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.rdlV1Avf4l/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.eVQPp8suJy/Listeners TMPDIR=/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/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-04-11 02:38:45,258 truncating revision to first 12 chars 2017-04-11 02:38:45,258 Setting DEBUG logging. 2017-04-11 02:38:45,258 attempt 1/10 2017-04-11 02:38:45,258 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/7031c811659d?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-04-11 02:38:46,296 unpacking tar archive at: mozilla-central-7031c811659d/testing/mozharness/ program finished with exit code 0 elapsedTime=1.335955 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-04-11 02:38:46.521392) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-11 02:38:46.521727) ========= script_repo_revision: 7031c811659d90275e7b8e1557027791081767c5 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-11 02:38:46.522104) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-11 02:38:46.522365) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-11 02:38:46.542573) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 29 secs) (at 2017-04-11 02:38:46.543153) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs --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', '--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.rdlV1Avf4l/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.eVQPp8suJy/Listeners TMPDIR=/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/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 02:38:46 INFO - MultiFileLogger online at 20170411 02:38:46 in /builds/slave/test 02:38:46 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 02:38:46 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 02:38:46 INFO - {'append_to_log': False, 02:38:46 INFO - 'base_work_dir': '/builds/slave/test', 02:38:46 INFO - 'blob_upload_branch': 'Firefox', 02:38:46 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 02:38:46 INFO - 'branch': 'Firefox', 02:38:46 INFO - 'buildbot_json_path': 'buildprops.json', 02:38:46 INFO - 'config_files': ('talos/mac_config.py',), 02:38:46 INFO - 'default_actions': ('clobber', 02:38:46 INFO - 'read-buildbot-config', 02:38:46 INFO - 'download-and-extract', 02:38:46 INFO - 'populate-webroot', 02:38:46 INFO - 'create-virtualenv', 02:38:46 INFO - 'install', 02:38:46 INFO - 'run-tests'), 02:38:46 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 02:38:46 INFO - 'download_minidump_stackwalk': True, 02:38:46 INFO - 'download_symbols': 'ondemand', 02:38:46 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 02:38:46 INFO - 'tooltool.py': '/tools/tooltool.py', 02:38:46 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 02:38:46 INFO - '/tools/misc-python/virtualenv.py')}, 02:38:46 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 02:38:46 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 02:38:46 INFO - 'gecko_profile': False, 02:38:46 INFO - 'gecko_profile_interval': 0, 02:38:46 INFO - 'installer_path': 'installer.exe', 02:38:46 INFO - 'log_level': 'info', 02:38:46 INFO - 'log_name': 'talos', 02:38:46 INFO - 'log_to_console': True, 02:38:46 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 02:38:46 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 02:38:46 INFO - 'opt_config_files': (), 02:38:46 INFO - 'pip_index': False, 02:38:46 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 02:38:46 INFO - 'cmd': ('bash', 02:38:46 INFO - '-c', 02:38:46 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 02:38:46 INFO - 'enabled': True, 02:38:46 INFO - 'halt_on_failure': False, 02:38:46 INFO - 'name': 'check_screen_resolution'},), 02:38:46 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 02:38:46 INFO - 'cmd': ('bash', 02:38:46 INFO - '-c', 02:38:46 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 02:38:46 INFO - 'enabled': True, 02:38:46 INFO - 'halt_on_failure': False, 02:38:46 INFO - 'name': 'check_screen_resolution'},), 02:38:46 INFO - 'run_cmd_checks_enabled': True, 02:38:46 INFO - 'suite': 'dromaeojs', 02:38:46 INFO - 'system_bits': '32', 02:38:46 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 02:38:46 INFO - 'title': 't-yosemite-r7-0289', 02:38:46 INFO - 'tooltool_cache': '/builds/tooltool_cache', 02:38:46 INFO - 'use_talos_json': True, 02:38:46 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 02:38:46 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 02:38:46 INFO - 'work_dir': 'build'} 02:38:46 INFO - [mozharness: 2017-04-11 09:38:46.745034Z] Running clobber step. 02:38:46 INFO - Running pre-action listener: _resource_record_pre_action 02:38:46 INFO - Running main action method: clobber 02:38:46 INFO - rmtree: /builds/slave/test/build 02:38:46 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 02:38:49 INFO - Running post-action listener: _resource_record_post_action 02:38:49 INFO - [mozharness: 2017-04-11 09:38:49.297257Z] Finished clobber step (success) 02:38:49 INFO - [mozharness: 2017-04-11 09:38:49.297391Z] Running read-buildbot-config step. 02:38:49 INFO - Running pre-action listener: _resource_record_pre_action 02:38:49 INFO - Running main action method: read_buildbot_config 02:38:49 INFO - Using buildbot properties: 02:38:49 INFO - { 02:38:49 INFO - "project": "", 02:38:49 INFO - "product": "firefox", 02:38:49 INFO - "script_repo_revision": "production", 02:38:49 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 02:38:49 INFO - "repository": "", 02:38:49 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 02:38:49 INFO - "buildid": "20170411011827", 02:38:49 INFO - "pgo_build": "False", 02:38:49 INFO - "basedir": "/builds/slave/test", 02:38:49 INFO - "buildnumber": 96, 02:38:49 INFO - "slavename": "t-yosemite-r7-0289", 02:38:49 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 02:38:49 INFO - "platform": "yosemite_r7", 02:38:49 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 02:38:49 INFO - "branch": "mozilla-central", 02:38:49 INFO - "repo_path": "mozilla-central", 02:38:49 INFO - "revision": "7031c811659d90275e7b8e1557027791081767c5", 02:38:49 INFO - "stage_platform": "macosx64", 02:38:49 INFO - "builduid": "1638bd6c86fd48f88b8da4f76aeb68e2", 02:38:49 INFO - "slavebuilddir": "test" 02:38:49 INFO - } 02:38:49 INFO - Found installer url https://queue.taskcluster.net/v1/task/MOM3fqXJQJi-IQ3ENfFJIA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg. 02:38:49 INFO - Running post-action listener: _resource_record_post_action 02:38:49 INFO - [mozharness: 2017-04-11 09:38:49.300805Z] Finished read-buildbot-config step (success) 02:38:49 INFO - [mozharness: 2017-04-11 09:38:49.300931Z] Running download-and-extract step. 02:38:49 INFO - Running pre-action listener: _resource_record_pre_action 02:38:49 INFO - Running main action method: download_and_extract 02:38:49 INFO - mkdir: /builds/slave/test/build/tests 02:38: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')]} 02:38:49 INFO - trying https://queue.taskcluster.net/v1/task/MOM3fqXJQJi-IQ3ENfFJIA/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 02:38:49 INFO - Downloading https://queue.taskcluster.net/v1/task/MOM3fqXJQJi-IQ3ENfFJIA/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 02:38:49 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/MOM3fqXJQJi-IQ3ENfFJIA/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 02:38:50 INFO - Downloaded 1325 bytes. 02:38:50 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 02:38:50 INFO - Using the following test package requirements: 02:38:50 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 02:38:50 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 02:38:50 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 02:38:50 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 02:38:50 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 02:38:50 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 02:38:50 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 02:38:50 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 02:38:50 INFO - u'jsshell-mac.zip'], 02:38:50 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 02:38:50 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 02:38:50 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 02:38:50 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 02:38:50 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 02:38:50 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 02:38:50 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 02:38:50 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 02:38:50 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 02:38:50 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 02:38:50 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 02:38:50 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 02:38:50 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/MOM3fqXJQJi-IQ3ENfFJIA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 02:38:50 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/MOM3fqXJQJi-IQ3ENfFJIA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 02:38:50 INFO - Fetch https://queue.taskcluster.net/v1/task/MOM3fqXJQJi-IQ3ENfFJIA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 02:38:51 INFO - Expected file size: 52582329 02:38:51 INFO - Obtained file size: 52582329 02:39:08 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 02:39:08 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/MOM3fqXJQJi-IQ3ENfFJIA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 02:39:08 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/MOM3fqXJQJi-IQ3ENfFJIA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 02:39:08 INFO - Fetch https://queue.taskcluster.net/v1/task/MOM3fqXJQJi-IQ3ENfFJIA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 02:39:10 INFO - Expected file size: 52582329 02:39:10 INFO - Obtained file size: 52582329 02:39:26 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/MOM3fqXJQJi-IQ3ENfFJIA/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 02:39:26 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/MOM3fqXJQJi-IQ3ENfFJIA/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 02:39:26 INFO - Fetch https://queue.taskcluster.net/v1/task/MOM3fqXJQJi-IQ3ENfFJIA/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 02:39:27 INFO - Expected file size: 14405596 02:39:27 INFO - Obtained file size: 14405596 02:39:27 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')]} 02:39:27 INFO - trying https://queue.taskcluster.net/v1/task/MOM3fqXJQJi-IQ3ENfFJIA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 02:39:27 INFO - Downloading https://queue.taskcluster.net/v1/task/MOM3fqXJQJi-IQ3ENfFJIA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 02:39:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/MOM3fqXJQJi-IQ3ENfFJIA/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 02:39:30 INFO - Downloaded 70926108 bytes. 02:39:30 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/MOM3fqXJQJi-IQ3ENfFJIA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 02:39:30 INFO - mkdir: /builds/slave/test/properties 02:39:30 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 02:39:30 INFO - Writing to file /builds/slave/test/properties/build_url 02:39:30 INFO - Contents: 02:39:30 INFO - build_url:https://queue.taskcluster.net/v1/task/MOM3fqXJQJi-IQ3ENfFJIA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 02:39:31 INFO - Running post-action listener: _resource_record_post_action 02:39:31 INFO - Running post-action listener: set_extra_try_arguments 02:39:31 INFO - [mozharness: 2017-04-11 09:39:31.090072Z] Finished download-and-extract step (success) 02:39:31 INFO - [mozharness: 2017-04-11 09:39:31.090200Z] Running populate-webroot step. 02:39:31 INFO - Running pre-action listener: _resource_record_pre_action 02:39:31 INFO - Running main action method: populate_webroot 02:39:31 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 02:39:31 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 02:39:31 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 02:39:31 INFO - '--tppagecycles', 02:39:31 INFO - '7'], 02:39:31 INFO - 'tests': ['tsvgm']}}, 02:39:31 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 02:39:31 INFO - 'tests': ['tresize', 'tcanvasmark']}, 02:39:31 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 02:39:31 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 02:39:31 INFO - 'tests': ['dromaeo_css', 'kraken']}, 02:39:31 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 02:39:31 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 02:39:31 INFO - 'talos_options': ['--disable-e10s'], 02:39:31 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:39:31 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 02:39:31 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:39:31 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 02:39:31 INFO - 'talos_options': ['--disable-e10s'], 02:39:31 INFO - 'tests': ['damp', 'tps']}, 02:39:31 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 02:39:31 INFO - 'tests': ['damp', 'tps']}, 02:39:31 INFO - 'g3': {'talos_options': ['--disable-e10s'], 02:39:31 INFO - 'tests': ['dromaeo_dom']}, 02:39:31 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 02:39:31 INFO - 'g4': {'talos_options': ['--disable-e10s'], 02:39:31 INFO - 'tests': ['basic_compositor_video']}, 02:39:31 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 02:39:31 INFO - 'other': {'talos_options': ['--disable-e10s'], 02:39:31 INFO - 'tests': ['a11yr', 02:39:31 INFO - 'ts_paint', 02:39:31 INFO - 'tpaint', 02:39:31 INFO - 'sessionrestore', 02:39:31 INFO - 'sessionrestore_no_auto_restore', 02:39:31 INFO - 'tabpaint']}, 02:39:31 INFO - 'other-e10s': {'tests': ['a11yr', 02:39:31 INFO - 'ts_paint', 02:39:31 INFO - 'tpaint', 02:39:31 INFO - 'sessionrestore', 02:39:31 INFO - 'sessionrestore_no_auto_restore', 02:39:31 INFO - 'tabpaint']}, 02:39:31 INFO - 'other-e10s_l64': {'tests': ['a11yr', 02:39:31 INFO - 'ts_paint', 02:39:31 INFO - 'tpaint', 02:39:31 INFO - 'sessionrestore', 02:39:31 INFO - 'sessionrestore_no_auto_restore', 02:39:31 INFO - 'tabpaint']}, 02:39:31 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 02:39:31 INFO - 'ts_paint', 02:39:31 INFO - 'tpaint', 02:39:31 INFO - 'sessionrestore', 02:39:31 INFO - 'sessionrestore_no_auto_restore', 02:39:31 INFO - 'tabpaint']}, 02:39:31 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 02:39:31 INFO - 'tests': ['a11yr', 02:39:31 INFO - 'ts_paint', 02:39:31 INFO - 'tpaint', 02:39:31 INFO - 'sessionrestore', 02:39:31 INFO - 'sessionrestore_no_auto_restore', 02:39:31 INFO - 'tabpaint']}, 02:39:31 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 02:39:31 INFO - 'tests': ['a11yr', 02:39:31 INFO - 'ts_paint', 02:39:31 INFO - 'tpaint', 02:39:31 INFO - 'sessionrestore', 02:39:31 INFO - 'sessionrestore_no_auto_restore', 02:39:31 INFO - 'tabpaint']}, 02:39:31 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 02:39:31 INFO - 'tests': ['tsvgx', 02:39:31 INFO - 'tsvgr_opacity', 02:39:31 INFO - 'tart', 02:39:31 INFO - 'tscrollx', 02:39:31 INFO - 'cart', 02:39:31 INFO - 'tsvg_static']}, 02:39:31 INFO - 'svgr-e10s': {'tests': ['tsvgx', 02:39:31 INFO - 'tsvgr_opacity', 02:39:31 INFO - 'tart', 02:39:31 INFO - 'tscrollx', 02:39:31 INFO - 'cart', 02:39:31 INFO - 'tsvg_static']}, 02:39:31 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 02:39:31 INFO - 'talos_options': ['--disable-e10s'], 02:39:31 INFO - 'tests': ['tp5o']}, 02:39:31 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 02:39:31 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 02:39:31 INFO - 'talos_options': ['--disable-e10s', 02:39:31 INFO - '--xperf_path', 02:39:31 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:39:31 INFO - 'tests': ['tp5n']}, 02:39:31 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 02:39:31 INFO - 'talos_options': ['--xperf_path', 02:39:31 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:39:31 INFO - 'tests': ['tp5n']}}, 02:39:31 INFO - 'talos.zip': {'path': '', 02:39:31 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 02:39:31 INFO - Running post-action listener: _resource_record_post_action 02:39:31 INFO - [mozharness: 2017-04-11 09:39:31.109149Z] Finished populate-webroot step (success) 02:39:31 INFO - [mozharness: 2017-04-11 09:39:31.109250Z] Running create-virtualenv step. 02:39:31 INFO - Running pre-action listener: _resource_record_pre_action 02:39:31 INFO - Running main action method: create_virtualenv 02:39:31 INFO - Creating virtualenv /builds/slave/test/build/venv 02:39:31 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 02:39:31 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 02:39:31 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 02:39:31 INFO - Using real prefix '/tools/python27' 02:39:31 INFO - New python executable in /builds/slave/test/build/venv/bin/python 02:39:31 INFO - Installing distribute.............................................................................................................................................................................................done. 02:39:34 INFO - Installing pip.................done. 02:39:34 INFO - Return code: 0 02:39:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 02:39:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 02:39:35 INFO - Reading from file tmpfile_stdout 02:39:35 INFO - Output received: 02:39:35 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) 02:39:35 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 02:39: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')]} 02:39:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:39:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:39: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')]} 02:39:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:39:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:39: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10689bb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10689e768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb303db36d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106679d30>, '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 0x1065a0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10666ebe8>, '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.rdlV1Avf4l/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.eVQPp8suJy/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/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 02:39: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', 'pip>=1.5'] in /builds/slave/test/build 02:39: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 pip>=1.5 02:39:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rdlV1Avf4l/Render', 02:39:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:39:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:39:35 INFO - 'HOME': '/Users/cltbld', 02:39:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:39:35 INFO - 'LOGNAME': 'cltbld', 02:39:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:39:35 INFO - 'MOZ_NO_REMOTE': '1', 02:39:35 INFO - 'NO_EM_RESTART': '1', 02:39:35 INFO - 'PAGER': '/bin/cat', 02:39:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:39:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:39:35 INFO - 'PWD': '/builds/slave/test', 02:39:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:39:35 INFO - 'SHELL': '/bin/bash', 02:39:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.eVQPp8suJy/Listeners', 02:39:35 INFO - 'TMPDIR': '/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/', 02:39:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:39:35 INFO - 'USER': 'cltbld', 02:39:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:39:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:39:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:39:35 INFO - 'XPC_FLAGS': '0x0', 02:39:35 INFO - 'XPC_SERVICE_NAME': '0', 02:39:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:39:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:39:35 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 02:39:35 INFO - Cleaning up... 02:39:35 INFO - Return code: 0 02:39:35 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 02:39: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')]} 02:39:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:39:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:39: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')]} 02:39:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:39:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:39: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', '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 0x10689bb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10689e768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb303db36d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106679d30>, '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 0x1065a0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10666ebe8>, '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.rdlV1Avf4l/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.eVQPp8suJy/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/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 02:39: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', 'psutil>=3.1.1'] in /builds/slave/test/build 02:39: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 psutil>=3.1.1 02:39:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rdlV1Avf4l/Render', 02:39:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:39:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:39:35 INFO - 'HOME': '/Users/cltbld', 02:39:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:39:35 INFO - 'LOGNAME': 'cltbld', 02:39:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:39:35 INFO - 'MOZ_NO_REMOTE': '1', 02:39:35 INFO - 'NO_EM_RESTART': '1', 02:39:35 INFO - 'PAGER': '/bin/cat', 02:39:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:39:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:39:35 INFO - 'PWD': '/builds/slave/test', 02:39:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:39:35 INFO - 'SHELL': '/bin/bash', 02:39:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.eVQPp8suJy/Listeners', 02:39:35 INFO - 'TMPDIR': '/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/', 02:39:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:39:35 INFO - 'USER': 'cltbld', 02:39:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:39:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:39:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:39:35 INFO - 'XPC_FLAGS': '0x0', 02:39:35 INFO - 'XPC_SERVICE_NAME': '0', 02:39:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:39:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:39:35 INFO - Downloading/unpacking psutil>=3.1.1 02:39:35 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 02:39:35 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 02:39:35 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 02:39:35 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 02:39:35 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 02:39:35 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 02:39:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 02:39:39 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:39:39 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:39:39 INFO - Installing collected packages: psutil 02:39:39 INFO - Running setup.py install for psutil 02:39:39 INFO - building 'psutil._psutil_osx' extension 02:39:39 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 02:39:39 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 02:39:39 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 02:39:39 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 02:39:40 INFO - building 'psutil._psutil_posix' extension 02:39:40 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 02:39:40 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 02:39:40 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 02:39:40 INFO - ^ 02:39:40 INFO - 1 warning generated. 02:39:40 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 02:39:40 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:39:40 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:39:40 INFO - Successfully installed psutil 02:39:40 INFO - Cleaning up... 02:39:40 INFO - Return code: 0 02:39:40 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 02:39:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:39:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:39:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:39:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:39:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:39:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:39:40 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 0x10689bb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10689e768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb303db36d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106679d30>, '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 0x1065a0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10666ebe8>, '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.rdlV1Avf4l/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.eVQPp8suJy/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/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 02:39:40 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 02:39:40 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 02:39:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rdlV1Avf4l/Render', 02:39:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:39:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:39:40 INFO - 'HOME': '/Users/cltbld', 02:39:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:39:40 INFO - 'LOGNAME': 'cltbld', 02:39:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:39:40 INFO - 'MOZ_NO_REMOTE': '1', 02:39:40 INFO - 'NO_EM_RESTART': '1', 02:39:40 INFO - 'PAGER': '/bin/cat', 02:39:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:39:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:39:40 INFO - 'PWD': '/builds/slave/test', 02:39:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:39:40 INFO - 'SHELL': '/bin/bash', 02:39:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.eVQPp8suJy/Listeners', 02:39:40 INFO - 'TMPDIR': '/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/', 02:39:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:39:40 INFO - 'USER': 'cltbld', 02:39:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:39:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:39:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:39:40 INFO - 'XPC_FLAGS': '0x0', 02:39:40 INFO - 'XPC_SERVICE_NAME': '0', 02:39:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:39:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:39:40 INFO - Downloading/unpacking mozsystemmonitor==0.3 02:39:40 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 02:39:40 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 02:39:40 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 02:39:40 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 02:39:40 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 02:39:40 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 02:39:43 INFO - Downloading mozsystemmonitor-0.3.tar.gz 02:39:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 02:39:43 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 02:39:43 INFO - Installing collected packages: mozsystemmonitor 02:39:43 INFO - Running setup.py install for mozsystemmonitor 02:39:44 INFO - Successfully installed mozsystemmonitor 02:39:44 INFO - Cleaning up... 02:39:44 INFO - Return code: 0 02:39:44 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 02:39:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:39:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:39:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:39:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:39:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:39:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:39:44 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10689bb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10689e768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb303db36d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106679d30>, '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 0x1065a0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10666ebe8>, '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.rdlV1Avf4l/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.eVQPp8suJy/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/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 02:39:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 02:39:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 02:39:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rdlV1Avf4l/Render', 02:39:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:39:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:39:44 INFO - 'HOME': '/Users/cltbld', 02:39:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:39:44 INFO - 'LOGNAME': 'cltbld', 02:39:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:39:44 INFO - 'MOZ_NO_REMOTE': '1', 02:39:44 INFO - 'NO_EM_RESTART': '1', 02:39:44 INFO - 'PAGER': '/bin/cat', 02:39:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:39:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:39:44 INFO - 'PWD': '/builds/slave/test', 02:39:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:39:44 INFO - 'SHELL': '/bin/bash', 02:39:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.eVQPp8suJy/Listeners', 02:39:44 INFO - 'TMPDIR': '/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/', 02:39:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:39:44 INFO - 'USER': 'cltbld', 02:39:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:39:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:39:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:39:44 INFO - 'XPC_FLAGS': '0x0', 02:39:44 INFO - 'XPC_SERVICE_NAME': '0', 02:39:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:39:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:39:44 INFO - Downloading/unpacking jsonschema==2.5.1 02:39: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 02:39: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 02:39: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 02:39: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 02:39: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 02:39: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 02:39:47 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 02:39:47 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 02:39: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 02:39: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 02:39: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 02:39: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 02:39: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 02:39: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 02:39:47 INFO - Downloading functools32-3.2.3-2.tar.gz 02:39:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 02:39:47 INFO - warning: no files found matching '*.txt' 02:39:47 INFO - no previously-included directories found matching 'build' 02:39:47 INFO - no previously-included directories found matching 'dist' 02:39:47 INFO - no previously-included directories found matching '.git*' 02:39:47 INFO - Installing collected packages: jsonschema, functools32 02:39:47 INFO - Running setup.py install for functools32 02:39:48 INFO - warning: no files found matching '*.txt' 02:39:48 INFO - no previously-included directories found matching 'build' 02:39:48 INFO - no previously-included directories found matching 'dist' 02:39:48 INFO - no previously-included directories found matching '.git*' 02:39:48 INFO - Successfully installed jsonschema functools32 02:39:48 INFO - Cleaning up... 02:39:48 INFO - Return code: 0 02:39:48 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 02:39:48 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')]} 02:39:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:39:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:39:48 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')]} 02:39:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:39:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:39:48 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 0x10689bb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10689e768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb303db36d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106679d30>, '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 0x1065a0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10666ebe8>, '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.rdlV1Avf4l/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.eVQPp8suJy/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/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 02:39:48 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 02:39:48 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 02:39:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rdlV1Avf4l/Render', 02:39:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:39:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:39:48 INFO - 'HOME': '/Users/cltbld', 02:39:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:39:48 INFO - 'LOGNAME': 'cltbld', 02:39:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:39:48 INFO - 'MOZ_NO_REMOTE': '1', 02:39:48 INFO - 'NO_EM_RESTART': '1', 02:39:48 INFO - 'PAGER': '/bin/cat', 02:39:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:39:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:39:48 INFO - 'PWD': '/builds/slave/test', 02:39:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:39:48 INFO - 'SHELL': '/bin/bash', 02:39:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.eVQPp8suJy/Listeners', 02:39:48 INFO - 'TMPDIR': '/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/', 02:39:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:39:48 INFO - 'USER': 'cltbld', 02:39:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:39:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:39:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:39:48 INFO - 'XPC_FLAGS': '0x0', 02:39:48 INFO - 'XPC_SERVICE_NAME': '0', 02:39:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:39:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:39:48 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 02:39:48 INFO - Cleaning up... 02:39:48 INFO - Return code: 0 02:39:48 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 02:39:48 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')]} 02:39:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:39:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:39:48 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')]} 02:39:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:39:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:39:48 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 0x10689bb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10689e768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb303db36d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106679d30>, '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 0x1065a0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10666ebe8>, '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.rdlV1Avf4l/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.eVQPp8suJy/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/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 02:39:48 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 02:39:48 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 02:39:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rdlV1Avf4l/Render', 02:39:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:39:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:39:48 INFO - 'HOME': '/Users/cltbld', 02:39:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:39:48 INFO - 'LOGNAME': 'cltbld', 02:39:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:39:48 INFO - 'MOZ_NO_REMOTE': '1', 02:39:48 INFO - 'NO_EM_RESTART': '1', 02:39:48 INFO - 'PAGER': '/bin/cat', 02:39:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:39:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:39:48 INFO - 'PWD': '/builds/slave/test', 02:39:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:39:48 INFO - 'SHELL': '/bin/bash', 02:39:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.eVQPp8suJy/Listeners', 02:39:48 INFO - 'TMPDIR': '/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/', 02:39:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:39:48 INFO - 'USER': 'cltbld', 02:39:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:39:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:39:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:39:48 INFO - 'XPC_FLAGS': '0x0', 02:39:48 INFO - 'XPC_SERVICE_NAME': '0', 02:39:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:39:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:39:48 INFO - Downloading/unpacking blobuploader==1.2.4 02:39:48 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 02:39:48 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 02:39:48 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 02:39:48 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 02:39:48 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 02:39:48 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 02:39:51 INFO - Downloading blobuploader-1.2.4.tar.gz 02:39:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 02:39:51 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 02:39:51 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 02:39:51 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 02:39:51 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 02:39:51 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 02:39:51 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 02:39:51 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 02:39:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 02:39:52 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 02:39:52 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 02:39:52 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 02:39:52 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 02:39:52 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 02:39:52 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 02:39:52 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 02:39:52 INFO - Downloading docopt-0.6.1.tar.gz 02:39:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 02:39:52 INFO - Installing collected packages: blobuploader, requests, docopt 02:39:52 INFO - Running setup.py install for blobuploader 02:39:52 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 02:39:52 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 02:39:52 INFO - Running setup.py install for requests 02:39:53 INFO - Running setup.py install for docopt 02:39:53 INFO - Successfully installed blobuploader requests docopt 02:39:53 INFO - Cleaning up... 02:39:53 INFO - Return code: 0 02:39:53 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:39: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')]} 02:39:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:39:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:39: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')]} 02:39:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:39:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:39:53 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 0x10689bb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10689e768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb303db36d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106679d30>, '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 0x1065a0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10666ebe8>, '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.rdlV1Avf4l/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.eVQPp8suJy/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/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 02:39:53 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 02:39:53 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 02:39:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rdlV1Avf4l/Render', 02:39:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:39:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:39:53 INFO - 'HOME': '/Users/cltbld', 02:39:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:39:53 INFO - 'LOGNAME': 'cltbld', 02:39:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:39:53 INFO - 'MOZ_NO_REMOTE': '1', 02:39:53 INFO - 'NO_EM_RESTART': '1', 02:39:53 INFO - 'PAGER': '/bin/cat', 02:39:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:39:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:39:53 INFO - 'PWD': '/builds/slave/test', 02:39:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:39:53 INFO - 'SHELL': '/bin/bash', 02:39:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.eVQPp8suJy/Listeners', 02:39:53 INFO - 'TMPDIR': '/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/', 02:39:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:39:53 INFO - 'USER': 'cltbld', 02:39:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:39:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:39:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:39:53 INFO - 'XPC_FLAGS': '0x0', 02:39:53 INFO - 'XPC_SERVICE_NAME': '0', 02:39:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:39:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:39:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:39:53 INFO - Running setup.py (path:/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/pip-EPd6f4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:39:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:39:53 INFO - Running setup.py (path:/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/pip-UnzeMS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:39:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:39:54 INFO - Running setup.py (path:/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/pip-hM_gt0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:39:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:39:54 INFO - Running setup.py (path:/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/pip-yzqmiV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:39:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:39:54 INFO - Running setup.py (path:/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/pip-EY4FC8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:39:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:39:54 INFO - Running setup.py (path:/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/pip-FIDjlH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:39:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:39:54 INFO - Running setup.py (path:/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/pip-bT0J25-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:39:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:39:54 INFO - Running setup.py (path:/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/pip-YZTRwB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:39:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:39:54 INFO - Running setup.py (path:/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/pip-bzuysa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:39:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:39:54 INFO - Running setup.py (path:/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/pip-TCy2Vw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:39:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:39:54 INFO - Running setup.py (path:/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/pip-9cKqTH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:39:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:39:54 INFO - Running setup.py (path:/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/pip-podXCy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:39:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:39:55 INFO - Running setup.py (path:/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/pip-BC4lXv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:39:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:39:55 INFO - Running setup.py (path:/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/pip-woPBSc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:39:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:39:55 INFO - Running setup.py (path:/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/pip-j2RwcV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:39:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:39:55 INFO - Running setup.py (path:/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/pip-uhJBiq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:39:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:39:55 INFO - Running setup.py (path:/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/pip-kWWIWy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:39:55 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 02:39:55 INFO - Running setup.py install for manifestparser 02:39:55 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 02:39:55 INFO - Running setup.py install for mozcrash 02:39:55 INFO - Running setup.py install for mozdebug 02:39:55 INFO - Running setup.py install for mozdevice 02:39:56 INFO - Installing dm script to /builds/slave/test/build/venv/bin 02:39:56 INFO - Running setup.py install for mozfile 02:39:56 INFO - Running setup.py install for mozhttpd 02:39:56 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 02:39:56 INFO - Running setup.py install for mozinfo 02:39:56 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 02:39:56 INFO - Running setup.py install for mozInstall 02:39:56 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 02:39:56 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 02:39:56 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 02:39:56 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 02:39:56 INFO - Running setup.py install for mozleak 02:39:56 INFO - Running setup.py install for mozlog 02:39:57 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 02:39:57 INFO - Running setup.py install for moznetwork 02:39:57 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 02:39:57 INFO - Running setup.py install for mozprocess 02:39:57 INFO - Running setup.py install for mozprofile 02:39:57 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 02:39:57 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 02:39:57 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 02:39:57 INFO - Running setup.py install for mozrunner 02:39:57 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 02:39:57 INFO - Running setup.py install for mozscreenshot 02:39:57 INFO - Running setup.py install for moztest 02:39:57 INFO - Running setup.py install for mozversion 02:39:58 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 02:39:58 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 02:39:58 INFO - Cleaning up... 02:39:58 INFO - Return code: 0 02:39:58 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:39:58 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')]} 02:39:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:39:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:39:58 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')]} 02:39:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:39:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:39:58 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 0x10689bb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10689e768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb303db36d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106679d30>, '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 0x1065a0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10666ebe8>, '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.rdlV1Avf4l/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.eVQPp8suJy/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/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 02:39:58 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 02:39:58 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 02:39:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rdlV1Avf4l/Render', 02:39:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:39:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:39:58 INFO - 'HOME': '/Users/cltbld', 02:39:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:39:58 INFO - 'LOGNAME': 'cltbld', 02:39:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:39:58 INFO - 'MOZ_NO_REMOTE': '1', 02:39:58 INFO - 'NO_EM_RESTART': '1', 02:39:58 INFO - 'PAGER': '/bin/cat', 02:39:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:39:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:39:58 INFO - 'PWD': '/builds/slave/test', 02:39:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:39:58 INFO - 'SHELL': '/bin/bash', 02:39:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.eVQPp8suJy/Listeners', 02:39:58 INFO - 'TMPDIR': '/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/', 02:39:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:39:58 INFO - 'USER': 'cltbld', 02:39:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:39:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:39:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:39:58 INFO - 'XPC_FLAGS': '0x0', 02:39:58 INFO - 'XPC_SERVICE_NAME': '0', 02:39:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:39:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:39:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:39:58 INFO - Running setup.py (path:/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/pip-ACQHcD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:39:58 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)) 02:39:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:39:58 INFO - Running setup.py (path:/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/pip-nbw8uc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:39:58 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)) 02:39:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:39:58 INFO - Running setup.py (path:/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/pip-1roSLT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:39:58 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)) 02:39:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:39:58 INFO - Running setup.py (path:/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/pip-Khd8HA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:39:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.50 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)) 02:39:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:39:58 INFO - Running setup.py (path:/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/pip-QTB8Nw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:39:58 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)) 02:39:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:39:58 INFO - Running setup.py (path:/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/pip-llYn3B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:39:59 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)) 02:39:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:39:59 INFO - Running setup.py (path:/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/pip-UhcD3f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:39:59 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)) 02:39:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:39:59 INFO - Running setup.py (path:/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/pip-dFedQG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:39:59 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)) 02:39:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:39:59 INFO - Running setup.py (path:/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/pip-iYPQOT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:39:59 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)) 02:39:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:39:59 INFO - Running setup.py (path:/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/pip-XWCF5O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:39:59 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)) 02:39:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:39:59 INFO - Running setup.py (path:/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/pip-E6754L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:39:59 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)) 02:39:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:39:59 INFO - Running setup.py (path:/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/pip-oKzQiQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:39:59 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)) 02:39:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:39:59 INFO - Running setup.py (path:/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/pip-d0pWXj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:39:59 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)) 02:39:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:39:59 INFO - Running setup.py (path:/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/pip-rZOTWN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:39:59 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)) 02:39:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:39:59 INFO - Running setup.py (path:/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/pip-na0pQs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:40:00 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)) 02:40:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:40:00 INFO - Running setup.py (path:/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/pip-CJTHV6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:40:00 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)) 02:40:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:40:00 INFO - Running setup.py (path:/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/pip-jbJF7V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:40:00 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)) 02:40:00 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)) 02:40:00 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)) 02:40:00 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)) 02:40:00 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.50->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 02:40:00 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.50->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 02:40:00 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 02:40:00 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 02:40:00 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 02:40:00 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 02:40:00 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 02:40:00 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 02:40:00 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 02:40:02 INFO - Downloading blessings-1.6.tar.gz 02:40:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 02:40:03 INFO - Installing collected packages: blessings 02:40:03 INFO - Running setup.py install for blessings 02:40:03 INFO - Successfully installed blessings 02:40:03 INFO - Cleaning up... 02:40:03 INFO - Return code: 0 02:40:03 INFO - Done creating virtualenv /builds/slave/test/build/venv. 02:40:03 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:40:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:40:03 INFO - Reading from file tmpfile_stdout 02:40:03 INFO - Current package versions: 02:40:03 INFO - blessings == 1.6 02:40:03 INFO - blobuploader == 1.2.4 02:40:03 INFO - docopt == 0.6.1 02:40:03 INFO - functools32 == 3.2.3-2 02:40:03 INFO - jsonschema == 2.5.1 02:40:03 INFO - manifestparser == 1.1 02:40:03 INFO - mozInstall == 1.12 02:40:03 INFO - mozcrash == 1.0 02:40:03 INFO - mozdebug == 0.1 02:40:03 INFO - mozdevice == 0.50 02:40:03 INFO - mozfile == 1.2 02:40:03 INFO - mozhttpd == 0.7 02:40:03 INFO - mozinfo == 0.9 02:40:03 INFO - mozleak == 0.1 02:40:03 INFO - mozlog == 3.4 02:40:03 INFO - moznetwork == 0.27 02:40:03 INFO - mozprocess == 0.25 02:40:03 INFO - mozprofile == 0.28 02:40:03 INFO - mozrunner == 6.13 02:40:03 INFO - mozscreenshot == 0.1 02:40:03 INFO - mozsystemmonitor == 0.3 02:40:03 INFO - moztest == 0.8 02:40:03 INFO - mozversion == 1.4 02:40:03 INFO - psutil == 3.1.1 02:40:03 INFO - requests == 1.2.3 02:40:03 INFO - wsgiref == 0.1.2 02:40:03 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:40:03 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')]} 02:40:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:40:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:40:03 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')]} 02:40:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:40:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:40:03 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 0x10689bb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10689e768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb303db36d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106679d30>, '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 0x1065a0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10666ebe8>, '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.rdlV1Avf4l/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.eVQPp8suJy/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/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 02:40:03 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 02:40:03 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 02:40:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rdlV1Avf4l/Render', 02:40:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:40:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:40:03 INFO - 'HOME': '/Users/cltbld', 02:40:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:40:03 INFO - 'LOGNAME': 'cltbld', 02:40:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:40:03 INFO - 'MOZ_NO_REMOTE': '1', 02:40:03 INFO - 'NO_EM_RESTART': '1', 02:40:03 INFO - 'PAGER': '/bin/cat', 02:40:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:40:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:40:03 INFO - 'PWD': '/builds/slave/test', 02:40:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:40:03 INFO - 'SHELL': '/bin/bash', 02:40:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.eVQPp8suJy/Listeners', 02:40:03 INFO - 'TMPDIR': '/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/', 02:40:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:40:03 INFO - 'USER': 'cltbld', 02:40:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:40:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:40:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:40:03 INFO - 'XPC_FLAGS': '0x0', 02:40:03 INFO - 'XPC_SERVICE_NAME': '0', 02:40:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:40:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:40:03 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)) 02:40:03 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)) 02:40:03 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)) 02:40:03 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)) 02:40:03 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)) 02:40:03 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)) 02:40:03 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)) 02:40:03 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)) 02:40:03 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)) 02:40:03 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)) 02:40:03 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)) 02:40:03 INFO - Cleaning up... 02:40:03 INFO - Return code: 0 02:40:03 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 02:40:03 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')]} 02:40:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:40:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:40:03 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')]} 02:40:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:40:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:40:03 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 0x10689bb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10689e768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb303db36d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106679d30>, '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 0x1065a0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10666ebe8>, '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.rdlV1Avf4l/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.eVQPp8suJy/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/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 02:40:03 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 02:40:03 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 02:40:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rdlV1Avf4l/Render', 02:40:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:40:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:40:03 INFO - 'HOME': '/Users/cltbld', 02:40:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:40:03 INFO - 'LOGNAME': 'cltbld', 02:40:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:40:03 INFO - 'MOZ_NO_REMOTE': '1', 02:40:03 INFO - 'NO_EM_RESTART': '1', 02:40:03 INFO - 'PAGER': '/bin/cat', 02:40:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:40:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:40:03 INFO - 'PWD': '/builds/slave/test', 02:40:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:40:03 INFO - 'SHELL': '/bin/bash', 02:40:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.eVQPp8suJy/Listeners', 02:40:03 INFO - 'TMPDIR': '/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/', 02:40:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:40:03 INFO - 'USER': 'cltbld', 02:40:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:40:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:40:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:40:03 INFO - 'XPC_FLAGS': '0x0', 02:40:03 INFO - 'XPC_SERVICE_NAME': '0', 02:40:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:40:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:40:04 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 02:40:04 INFO - Cleaning up... 02:40:04 INFO - Return code: 0 02:40:04 INFO - Running post-action listener: _resource_record_post_action 02:40:04 INFO - Running post-action listener: _start_resource_monitoring 02:40:04 INFO - Starting resource monitoring. 02:40:04 INFO - [mozharness: 2017-04-11 09:40:04.169080Z] Finished create-virtualenv step (success) 02:40:04 INFO - [mozharness: 2017-04-11 09:40:04.170043Z] Running install step. 02:40:04 INFO - Running pre-action listener: _resource_record_pre_action 02:40:04 INFO - Running main action method: install 02:40:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:40:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:40:04 INFO - Reading from file tmpfile_stdout 02:40:04 INFO - Detecting whether we're running mozinstall >=1.0... 02:40:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 02:40:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 02:40:04 INFO - Reading from file tmpfile_stdout 02:40:04 INFO - Output received: 02:40:04 INFO - Usage: mozinstall [options] installer 02:40:04 INFO - Options: 02:40:04 INFO - -h, --help show this help message and exit 02:40:04 INFO - -d DEST, --destination=DEST 02:40:04 INFO - Directory to install application into. [default: 02:40:04 INFO - "/builds/slave/test"] 02:40:04 INFO - --app=APP Application being installed. [default: firefox] 02:40:04 INFO - mkdir: /builds/slave/test/build/application 02:40:04 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'] 02:40:04 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 02:40:24 INFO - Reading from file tmpfile_stdout 02:40:24 INFO - Output received: 02:40:24 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 02:40:24 INFO - Running post-action listener: _resource_record_post_action 02:40:24 INFO - [mozharness: 2017-04-11 09:40:24.382068Z] Finished install step (success) 02:40:24 INFO - [mozharness: 2017-04-11 09:40:24.382198Z] Running run-tests step. 02:40:24 INFO - Running pre-action listener: _resource_record_pre_action 02:40:24 INFO - Running main action method: run_tests 02:40:24 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 02:40:24 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 02:40:24 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 02:40:24 INFO - 2017-04-11 02:40:24.396 screenresolution[1622:7282] starting screenresolution argv=screenresolution get 02:40:24 INFO - 2017-04-11 02:40:24.399 screenresolution[1622:7282] Display 0: 1600x1200x32@60 02:40:24 INFO - 2017-04-11 02:40:24.405 screenresolution[1623:7285] starting screenresolution argv=screenresolution list 02:40:24 INFO - Available Modes on Display 0 02:40:24 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 02:40:24 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 02:40:24 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 02:40:24 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 02:40:24 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 02:40:24 INFO - Intel Iris: 02:40:24 INFO - Chipset Model: Intel Iris 02:40:24 INFO - Type: GPU 02:40:24 INFO - Bus: Built-In 02:40:24 INFO - VRAM (Dynamic, Max): 1536 MB 02:40:24 INFO - Vendor: Intel (0x8086) 02:40:24 INFO - Device ID: 0x0a2e 02:40:24 INFO - Revision ID: 0x0009 02:40:24 INFO - Displays: 02:40:24 INFO - Display: 02:40:24 INFO - Resolution: 1600 x 1200 @ 60 Hz 02:40:24 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 02:40:24 INFO - Display Serial Number: 1600x1200 60 02:40:24 INFO - Main Display: Yes 02:40:24 INFO - Mirror: Off 02:40:24 INFO - Online: Yes 02:40:24 INFO - Rotation: Supported 02:40:24 INFO - Return code: 0 02:40:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 02:40:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 02:40:24 INFO - Python 2.7.3 02:40:24 INFO - Return code: 0 02:40:24 INFO - grabbing minidump binary from tooltool 02:40:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:40:24 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 0x106679d30>, '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 0x1065a0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10666ebe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 02:40:24 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 02:40:24 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 02:40:24 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 02:40:24 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 02:40:24 INFO - Return code: 0 02:40:24 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 02:40:24 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 02:40:24 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 02:40:24 INFO - ENV: RUST_BACKTRACE is now 1 02:40:24 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 02:40:24 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 02:40:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/MOM3fqXJQJi-IQ3ENfFJIA/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0289', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 02:40:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/MOM3fqXJQJi-IQ3ENfFJIA/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0289 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 02:40:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rdlV1Avf4l/Render', 02:40:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:40:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:40:24 INFO - 'HOME': '/Users/cltbld', 02:40:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:40:24 INFO - 'LOGNAME': 'cltbld', 02:40:24 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 02:40:24 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 02:40:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:40:24 INFO - 'MOZ_NO_REMOTE': '1', 02:40:24 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 02:40:24 INFO - 'NO_EM_RESTART': '1', 02:40:24 INFO - 'PAGER': '/bin/cat', 02:40:24 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:40:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:40:24 INFO - 'PWD': '/builds/slave/test', 02:40:24 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 02:40:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:40:24 INFO - 'RUST_BACKTRACE': '1', 02:40:24 INFO - 'SHELL': '/bin/bash', 02:40:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.eVQPp8suJy/Listeners', 02:40:24 INFO - 'TMPDIR': '/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/', 02:40:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:40:24 INFO - 'USER': 'cltbld', 02:40:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:40:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:40:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:40:24 INFO - 'XPC_FLAGS': '0x0', 02:40:24 INFO - 'XPC_SERVICE_NAME': '0', 02:40:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:40:24 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/MOM3fqXJQJi-IQ3ENfFJIA/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0289', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 02:40:24 INFO - mozversion application_buildid: 20170411011827 02:40:24 INFO - mozversion application_changeset: 7031c811659d90275e7b8e1557027791081767c5 02:40:24 INFO - mozversion application_display_name: Nightly 02:40:24 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 02:40:24 INFO - mozversion application_name: Firefox 02:40:24 INFO - mozversion application_remotingname: firefox 02:40:24 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 02:40:24 INFO - mozversion application_vendor: Mozilla 02:40:24 INFO - mozversion application_version: 55.0a1 02:40:24 INFO - mozversion platform_buildid: 20170411011827 02:40:24 INFO - mozversion platform_changeset: 7031c811659d90275e7b8e1557027791081767c5 02:40:24 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 02:40:24 INFO - mozversion platform_version: 55.0a1 02:40:24 INFO - using testdate: 1491903624 02:40:24 INFO - actual date: 1491903624 02:40:24 INFO - starting webserver on 'localhost:49241' 02:40:24 INFO - SUITE-START | Running 2 tests 02:40:24 INFO - TEST-START | dromaeo_css 02:40:24 INFO - operating with platform_type : mac_ 02:40:24 INFO - Initialising browser for dromaeo_css test... 02:40:25 INFO - TEST-INFO | started process 1633 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/tmpJS1Bcz/profile http://localhost:49241/getInfo.html) 02:40:27 INFO - PID 1633 | __metrics Screen width/height:1600/1200 02:40:27 INFO - PID 1633 | colorDepth:24 02:40:27 INFO - PID 1633 | Browser inner width/height: 1024/689 02:40:27 INFO - PID 1633 | __metrics 02:40:27 INFO - PID 1633 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 673: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 02:40:27 INFO - PID 1633 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 304: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 02:40:28 INFO - TEST-INFO | 1633: exit 0 02:40:28 INFO - Browser initialized. 02:40:28 INFO - Running cycle 1/1 for dromaeo_css test... 02:40:28 INFO - TEST-INFO | started process 1635 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/tmpJS1Bcz/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 02:40:41 INFO - PID 1635 | RSS: Main: 238440448 02:40:41 INFO - PID 1635 | 02:43:45 INFO - PID 1635 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49241/tests/dromaeo/cssquery-ext.html) 02:43:47 INFO - PID 1635 | RSS: Main: 374628352 02:43:47 INFO - PID 1635 | 02:45:31 INFO - PID 1635 | ************************* 02:45:31 INFO - PID 1635 | A coding exception was thrown and uncaught in a Task. 02:45:31 INFO - PID 1635 | 02:45:31 ERROR - PID 1635 | Full message: TypeError: NetworkError when attempting to fetch resource. 02:45:31 INFO - PID 1635 | Full stack: 02:45:31 INFO - PID 1635 | ************************* 02:45:31 INFO - PID 1635 | ************************* 02:45:31 INFO - PID 1635 | A coding exception was thrown and uncaught in a Task. 02:45:31 INFO - PID 1635 | 02:45:31 ERROR - PID 1635 | Full message: TypeError: NetworkError when attempting to fetch resource. 02:45:31 INFO - PID 1635 | Full stack: 02:45:31 INFO - PID 1635 | ************************* 02:45:31 INFO - PID 1635 | ************************* 02:45:31 INFO - PID 1635 | A coding exception was thrown and uncaught in a Task. 02:45:31 INFO - PID 1635 | 02:45:31 ERROR - PID 1635 | Full message: TypeError: NetworkError when attempting to fetch resource. 02:45:31 INFO - PID 1635 | Full stack: 02:45:31 INFO - PID 1635 | ************************* 02:45:31 INFO - PID 1635 | ************************* 02:45:31 INFO - PID 1635 | A coding exception was thrown and uncaught in a Task. 02:45:31 INFO - PID 1635 | 02:45:31 ERROR - PID 1635 | Full message: TypeError: NetworkError when attempting to fetch resource. 02:45:31 INFO - PID 1635 | Full stack: 02:45:31 INFO - PID 1635 | ************************* 02:46:50 INFO - PID 1635 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-ext.html (next: http://localhost:49241/tests/dromaeo/cssquery-jquery.html) 02:46:50 INFO - PID 1635 | RSS: Main: 367566848 02:46:50 INFO - PID 1635 | 02:49:53 INFO - PID 1635 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49241/tests/dromaeo/cssquery-mootools.html) 02:49:53 INFO - PID 1635 | RSS: Main: 355553280 02:49:53 INFO - PID 1635 | 02:52:56 INFO - PID 1635 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49241/tests/dromaeo/cssquery-prototype.html) 02:52:56 INFO - PID 1635 | RSS: Main: 323997696 02:52:56 INFO - PID 1635 | 02:56:00 INFO - PID 1635 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49241/tests/dromaeo/cssquery-yui.html) 02:56:00 INFO - PID 1635 | RSS: Main: 331468800 02:56:00 INFO - PID 1635 | 02:59:03 INFO - PID 1635 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-yui.html (next: http://localhost:49241/tests/dromaeo/cssquery-dojo.html) 02:59:03 INFO - PID 1635 | RSS: Main: 377135104 02:59:03 INFO - PID 1635 | 02:59:03 INFO - PID 1635 | __start_tp_report 02:59:03 INFO - PID 1635 | _x_x_mozilla_page_load 02:59:03 INFO - PID 1635 | _x_x_mozilla_page_load_details 02:59:03 INFO - PID 1635 | |i|pagename|runs| 02:59:03 INFO - PID 1635 | |0;dojo.html;2964.970350296497;3405.710514606258;3439.0370696205073;3469.5316132322146;3472.6006509251442;5070.873228169297;5247.947520524798;5840.0947853082735;6086.482648974837;6098.3291837897905;6030.185924900137;6072.08918662202;6082.209312789342;6105.429421494179;6179.907301390483;4761.523847615228;5173.637116165407;5261.579073674096;5273.578113750891;5275.498827611371;5392.838214853561;5397.946020539784;5405.756740946647;5407.675539467625;5428.75570599322;5201.479852014768;5272.525472707436;5279.472052794728;5349.304590403242;5353.598480113968;5277.894442111175;5286.894262114774;5327.46725327468;5331;5373.919391209135;5216.400113986902;5243.86890327741;5253.894922101575;5263.499967503081;5273.3935597406025;5738.42615738427;6086.730667412572;6110.194261626863;6113.480354169901;6120.914202612733;6324.146240257553;6332.208473940757;6348.587341822813;6402.423781859608;6413.1662883825275;6516.446102081329;6526.77156299532;6527.053577231275;6528.804135875931;6529.771457998947;6057.394260573996;6179.165812615284;6184.412480814315;6197.597156184834;6201.658908759971;6400.135981642418;6473.741050358027;6568.704408301638;6634.23706273775;6652.83367915806;6328.905066424008;6341.619502829845;6356.682165891687;6357.904631430532;6362.554621176566;4945.5293113686885;6435.646039467781;6490.707918143695;6495.545311828221;6499.1876015498065;5483.204935284361;6273.247210334789;6358.904616430757;6364.077208804697;6445.9355406446275;6334.208223972004;6364.968175159094;6413.3266007069515;6419.165508483868;6427.903581446282;5201.869953251124;5203.583713302926;5209.244659524347;5211.7915283389;5226.817061402832;4637.118947399983;4669.976650116796;4671.672982891165;4688.889999500238;4730.59789917854;5960.403959604005;5993.580449368503;6111.602745821508;6135.44703110649;6166.784162554379;6377.585456945284;6442.516811239082;6447.25856526496;6453.515986301046;6465.224173099165;6052.182955301066;6057.091436284592;6070.605410648293;6079.118527813443;6084.30030546484;4840.491748366441;5589.161625756088;5651.660900345993;5652.406497317805;5729.5702822288495;6557.245916719543;6634.369734875179;6639.800805975829;6644.202695676549;6651.301613330531;6293.842653933688;6342.7780027700655;6347.143135676624;6416.743330266924;6464.903026454513;6565.606063636197;6677.43241824431;6693.2972037935315;6697.497687673542;6700.765473208415;6397;6405.807825765048;6408.391202835724;6412.294647588855;6413.262474815456;6275.529335299962;6333.145025421509;6366.4588509977175;6512.544121911421;6548.148740663683;5551.417101204315;5778.248827652378;6308.747650094128;6310.1796766420075;6326.810195694136;6301.338359472189;6309.526785491014;6314;6329.5252856036905;6334.398232167937;6016.8796624068145;6132.542037586462;6145.125736650364;6179.876402472015;6218.53360997936;6250.531210159347;6282.811515654537;6304.148939893239;6332.430081292706;6341.0805433212845;6204.658743769136;6213.316535181217;6215.378462153749;6218.849512840139;6220.409061139185 02:59:03 INFO - PID 1635 | |1;ext.html;791.6121100660677;1114.470626452435;1143.0626885953523;1143.3825734103584;1293.236990175796;29118.417631647353;31216.907408240717;35277.94166175019;35570.46644300331;35602.75390361338;36406.63593364065;36709;36780.44829327562;36787;36798.81600592;6145.170401995718;6299.590526615755;7879.133295337494;8046.839063218761;8051.194880511961;8316.501009939393;8525.062243153248;8525.786855328604;8526.019507756595;8532.189442003031;8546.786330341729;8576.571171441403;8585.18440748131;8591.613377398;8617.267532259766;6090.634561926255;6231.719572619243;8509.319254459659;8573.314134869195;8584.399092063526;8294.958525207336;8470.872936905951;8478.576071196478;8479.194476524783;8482.745517634481;36728.632713672785;37093.81453092744;37145;37154.81422592897;37177;3593.694535964446;3638.3814751492046;3648.1609229877013;3651.087228192952;3651.6896063834606;30457.086287411414;30527.626256818276;30565.847170764006;30584.2353941151;30590.84704576485;3123.2972581169283;3183.697548732867;3185.0604071798643;3188.0276515662713;3197.488401855692;209543.9999999985;285628;286380;286577;287375;39088.80455597704;39595.40606890899;39607.80196099059;47840;48271.034579308216;48316.5168348312;48963.75518122387;49020.75489622529;49027;49168.77078073077;14086.71826563463;14206.502772402917;14220.573382798533;14237.715245695028;14240.572782816533;11566.363849988331;11576.710582235342;11582.88417115835;11591.76816463666;11616.186866919235;12284.0786940978;12324.383780810924;12330.383480825923;12357.505699771908;12439.253644781342;4473.485549161823;5028.969061342416;5497.340319161726;5527.53015993645;5538.723063846792;953.6137864165024;991.2862738828032;1008.7881544875512;1040.7086015915556;1060.8939106089329;20188.596228075356;22077.889610552167;23558.646620300366;23645.527089458115;23754;1000.8148492528906;1098.8846171152074;1126.2510430563748;1131.1629394248198;1146.7534480086822;12066.577669781516;12099.274043557414;12106.636800895987;12119.515219391125;12139.453724582416;339126;340508;340600;340637;341011;51725;51748.48251517512;51748.74125629348;51788;52247;1493.0966765106955;1528.831828498842;1552.7049860526463;1555.3001149482777;1571.8035245594301;18207.088255733488;22974.655380169646;23040.078396863937;23151.88424057869;23502.61649638674;36861;36923.63076369202;36972;37011.62988370082;37054.703085391884;306090;306420.4678976591;307059;307156;307219.46390267904;29144.462552115878;29621.407571848013;29644.84972103864;33231.833840830644;34102.31795364029;40409.59590404058;42027.57972420237;43197.56802431936;43347.349789752545;43509.78245108881;23866.164684236664;29106.417871642872;30093.99726337057;30202.24494387658;30589;29022;29114.27214319659;29743.405131897674;29844;30206.697933021267;35312.58749649985;35400;35454;35468.11329716778;35494.29011419809;28422.85788571044;28595.714042859305;29129.980450684125;29277.414451711273;30263.546046809755;23524.411889702893;23558;23673.28980130599;23678.88160559186;24039.63940540928 02:59:03 INFO - PID 1635 | |2;jquery.html;431.18936399568804;436.87112301870945;438.4628829683638;443.85130981121324;465.32295510032884;16583.922045067076;20718.689219661715;21387.67918481222;22226.88886555569;22264;17296.308147674077;23035.508075925158;23225.419364515896;23616.173433929816;23704.76295237047;5995.640261584308;6246.593971391868;6254.249490061176;6271.404216599439;6272.749090036393;5761.654300741948;6059.3637668044885;6104.572679912408;6105.358937311563;6124.112003759452;5800.680962547058;6069.180660610805;6072.544559158058;6086.969565152191;6089.543284253677;6004.669743164124;6146.6004709693725;6148.262208534982;6157.846053848645;6159.8768024639485;5319.255304257407;5398.6220964532495;5460.563154947595;5461.836144915619;5479.1781232815;27154.913803448035;27584.586231206547;27625.309367265778;28756.712432875614;28832.71167288321;2024.7266619006393;2229.2420577003895;2303.55080759252;2307.2962746362355;2330.265966220635;17725.7341139883;17799.555011124696;18048.639027219513;18293.817061829344;18526.722099168524;2132.9573408531896;2133.5412886229537;2178.869267843919;2183.0831050958636;2193.2762188477764;161921.19039404846;163148;164808;165589;166983.165084175;10431.739206519898;12180.939095304468;12287.692807679701;12391.75216495665;12439.440225189888;16625.168741562873;16641.6671666566;16659.33362665504;16672.916635416746;16674.83325166733;8502.045651850665;8847.380683352232;8874.201321880933;8979.730608081769;9000.729978100668;6510.958246680509;8378.329733621418;8400.218264794905;8555;8568.843206167212;6650.96909978954;7608.815151666561;7764.534127952341;7792.922070779219;7793.493422927491;1765.496833402474;1831.9084045797656;2004.8997550122435;2082.333653230982;2246.225052356934;799.7600719784042;967.6371360739722;967.6419724701906;1000.1598657128047;1010.6462738041627;19986.677536736344;20018;20987.58024839495;21060.894695526425;21147.577048459254;1000.4547521600599;1012.7822518158631;1024.9282550221412;1025.8358662613946;1038.99480502597;10555.102816260704;10910.672679819618;10919.781604367869;10960.013598776148;10990.615328463626;645971;719075;726117;737930;747889;80123.39753205019;125719.3714031424;132740;133242;158264.17358264312;1072.5297079831316;1156.9132315076404;1205.25274329916;1208.6072026591394;1212.1333246728454;21282.680759788924;21364.038618261904;21617.459563511038;21810.454738631663;21840.01719922639;57996;58362.832743345745;59535.70232148811;59749.70125149346;59904;83888;84319.73520397322;85070;86150;86800;7238.52949558277;8500.064992850905;8628.482291062557;8844.380893337404;8896.2882969361;10955.726106847074;13543.525976590772;13761.931190344385;14045.929770351082;14052.789208161681;11792.823107653563;11876.643700688994;11960.461779219782;12140.96801771824;12143.817842732542;7075.646217688992;8276.08963014056;9845.950770246389;9917.801643967225;10014.849777253492;10969.177311701815;11572.94213528927;12108;12127;12133.088058865986;8615.778268648442;8734.519601421729;8782.936010037509;8855.380123391558;9288.34981551285;10613.522391492246;11881.990030847475;12129.69675758113;12215.877841221474;12281.01751859867 02:59:03 INFO - PID 1635 | |3;mootools.html;1355.9322033898304;1426.7717165253562;1446.0528353928173;1446.4792674637135;1451.5355086372365;9306.488143152124;11636.534538618456;11640.126990475706;11640.592579259717;11642;11412.828807567872;11418.429078546082;11537.134714896374;11791.233569817956;11809.11431642626;2490.67621209243;2540.720520742717;2543.0972004938167;2543.2878793937725;2545.7199708032103;2145.2920536223087;2458.704955405345;2462.692163479565;2519.6346529753173;2530.3927057506157;2140.785921407862;2421.092090466075;2523.129520315488;2530.9493810123786;2532.3795670060863;2484.3664865459373;2498.850068995857;2500.9749902500926;2534.3157347516253;2534.657821194143;2265.8527195732227;2379.3456799380137;2501.137107697841;2513.9818373558737;2515.4717509323063;9554.761130971712;11822.763544729058;11838;11859.40702964857;11875.703107422298;6408.679566021727;6638.435732962704;6656.101426307435;6663.400293973517;6664.666766661648;3441.4493681011168;4249.150169965988;4329.458817647794;4330.761808100553;4334.154839806239;1251.6683078984076;1269.2447993443886;1321.4449931028992;1323.3383308345826;1327.1174668845304;14899.70200595982;15355.462558810388;15403.84471164656;16047.197640117947;16164.434244801376;5348.9465105348445;5469.972650136724;5667.914981275284;5673.347565030074;5678.375378708339;12165.635030949086;12184.08619353552;12187.207831490925;12194;12195.08536859739;6493.79864725018;6534.542582019308;6543.9345606544275;6545.770898018546;6546.738130474823;6055.636661800306;6111.8472038198515;6140.662263575547;6144.324124346318;6210.099535567318;3723.9813800930824;3934.390169523728;3983.960160398417;4273.358996150602;4377.321515165155;1165.1377980294521;1267.5436842736608;1298.8571257161705;1302.7199152182327;1321.7752981993085;782.2724865874791;802.9690951423936;804.7223707820792;810.5136917849243;825.636719843279;6097.451229389376;6101.816945491642;6157.5529750508595;6179.671739184614;6392.20097487814;581.2269681323829;583.988320233593;592.6207227374484;596.3023262782555;597.4981015946626;3907.433422153772;4014.337634290368;4245.681573881971;4345.695801293942;4378.6278166356215;13414.262215578041;15388.923055384652;15402.152881591395;15417.768733469007;15482.5355239343;13654.658633534242;15133.319000644777;15166.848331516543;15283.770743438858;15299.235038248265;10566.52450639723;10878.292910960921;10882.347059176474;10883.727906802234;10899.455027248607;4264.57354264571;6909.965450172717;7091.60996145217;7096.361327480551;7155.3202445767565;12947.158434701714;12965.805512917124;13657.453701851815;13682.742347512947;13718.38267277955;13767.518136865161;13801;13905.096168749;13929.024968252124;14061.718765624835;9296.209822165192;9580.856287155837;9583.712488625353;9589.856152157583;9604.375715578464;10568.841467377842;10585.9470702646;10624.946875265574;10624.946875265574;10679.14566834667;7726.652300646372;8558.486490810572;9156.450612963243;9274.907250927405;9314.627414903329;8985.326100542356;8988.730338089868;9053.185213330831;9075.773105672411;9112.635494580143;8061.919380806117;8183.754487365389;8205.302549283379;8206.138355472829;8220.05469371006;5770.913436298375;5934.851628709144;5938.287405511366;6133.681258529437;6174.38256174379;5975.55183361241;6095.146679464967;6165.290991535851;6251.906221406592;6322.374351899418 02:59:03 INFO - PID 1635 | |4;prototype.html;390.41632759025254;399.55050568110875;399.5974425763674;405.5781986733797;410.93425051991704;4882.243252295895;5877.14781356703;5916.437938395851;5948.7620495180145;5963.224780778503;4943.530364615365;5573.693446860421;5760.5022694099525;5805.419458054193;5876.179433871734;4454.710443821157;4469.88825279369;4485.461744590653;4507.909841803162;4512.210363186454;4496.348029535732;4504.896300406405;4512.232920403523;4518.5481451854885;4530.275155975045;4332.97833510832;4408.58118478744;4430.591561396415;4462.598366147063;4481.775911204427;4061.1065565575527;4421.977890110529;4426.690131690767;4442.089371678798;4454.643628509744;3486.8430920608635;3617.366960781853;3954.4463775071513;3979.4030895365636;3986.362182050858;5584.413636568183;5700.030994730906;5722.456366645162;5784.855378615526;5790.855228619276;4740.834070807539;5342.98483288174;5344.91982620261;5402.6758394496455;5416.756245968902;6187.659678717669;6227.159333489966;6280.497560195174;6299.5275354348605;6303.936960630381;4392.802323895432;4519.525449827754;4528.524504926968;4534.911621210886;4544.2047641662575;5504.559635229172;6514.706838192293;6525.804225873231;6573.1454910861285;6612.239592446882;3697.9815100924325;4089.1003979124544;4626.21354369758;4654.86035418938;4671.8364857229835;4176.955761059735;4288.5711428857485;4479.260921947907;4501.909961800746;4717.5046620104395;4491.3487544306545;4871.707697538088;5103.540681338697;5203.713795741271;5358.732063396815;6211.223597050369;6280.340564240689;6289.622622642656;6300.495960323163;6325.082862984934;1384.8615138486273;1454.6581553334959;1553.9433185434013;1594.2666373468103;1616.8625666818216;1016.9440680762481;1123.8145705958425;1138.9373584452935;1140.5779861451317;1147.2370873369116;4996.526024822686;5657.151427285858;5870.706464676749;5900.734466948998;5924.7333869975955;5177.385420362768;6025;6054.243219597551;6485.416312531895;6506.3818937200895;5132.56033530993;5368.436314187033;5583.302087239095;5874.941250587525;5921.289445266596;2571;2698.9595156072673;3329.8345579047623;3336;3346.4645656695297;6336.873262534724;6530.183727034121;6585.440237579764;6619.172603424572;6629.502787290876;6546.312637173123;6594.736210551524;6640.203175618956;6645.634490102994;6784.3894049535775;5626.6802586475;6226.12834203221;6517.706703198368;6533.869322613521;6572.4084832365315;5167.741612919414;6289.465395441439;6344.731053789168;6385.584936979081;6472.611643301417;5530.944690553043;5862.94137058624;6302.873942521215;6357.523185761179;6373.3945275198785;6111.419415155553;6159.5688301818445;6585.506087043586;6682.732690692513;6799.184097908283;4056.6310891899434;4648.953510464852;4683.344331793483;4762.499937506512;4766.97510035344;6241.458990732301;6461.870762584816;6481.5138864584405;6497.480201583768;6585.078089067439;6216.72024758896;6259.843503912439;6303.936960630335;6327.873442531216;6329.430351268408;6205.720742566505;6257.937420625735;6258.718357673824;6272.8118156455375;6353.714082866374;5127.3850336328;6059.394060593905;6097.908531371944;6106.572539922163;6107.694615269308;6205.441510264098;6208.4722798562625;6215.782447614311;6227.688615569113;6308.306086330408;5852.531797456279;6048.455638992512;6093.421124993119;6113.51091912637;6131.264248290234 02:59:03 INFO - PID 1635 | |5;yui.html;713.4827250243571;814.7922279818646;874.3398733955862;877.0746862060528;889.6396959231512;13321.400536975847;17815.28738850446;17952.640947181048;18484.168212430457;18542;15316.923415382907;17377.826221737778;17950.192241349137;17981.730274045898;17987.280508779666;993.8409854423276;1021.724134483689;1024.0169437340162;1025.8922813104627;1028.3778314119925;669.5112567825466;687.8761822871882;988.0218583602253;1025.9281850270484;1029.0532709906904;1074.8226542620457;1077.8006068877244;1079.3955384984395;1080.2546243092281;1080.6325849211266;1072.1958531101673;1073.3345325897915;1074.2426589254596;1075.3225467955178;1075.3386667199616;793.0087390761548;795;796.0487217774762;796.3549524884862;798.2576204130156;16341.754873676904;16468.505944821674;16656.417025404175;16907.577310567212;17215.311387544483;16665.25006374716;16745.83254167455;16971;17108;17209;13910.304484775821;13925.094868833494;14002;14102.083364581269;14128.576142715736;3396.3886500429912;3417.7607567470286;3425.8287085645866;3428.708559772397;3431.725461963037;111795;113864.4306778469;113952;113999.86000139975;115163.42418287939;11253.437328133561;11613.59352422678;12047.578334758417;12207.084468664885;12221.205621634565;17658.735118973225;20283.797162028484;25275.11537096193;25372.238832835046;25438.491230175292;8876.156765107304;8974.41666291689;9099.771530843384;9105.726828195166;9112.134347237134;4765.356676848651;4839.467658557556;4882.609391248691;4884.413870335582;4889.168841296988;4218.114196018809;4277;4289.571042895685;4292.377605247284;4302.483701955723;845.0746432656232;869.9695510657097;879.6102158589414;880.9779755506164;882.6160620130136;632.6804963493406;642.489221069251;650.6291413894035;668.638934975108;678.5385937562406;10231.641892533726;10440.321379110334;10842.945785271024;11683.948444639853;12017.939910300393;611.9694015299217;644.4328990488434;649.7336092202174;666.2338310942358;682.9385355317946;5254.500822421864;5465.726713664301;5481.835544933659;5578.944210557843;5619.775208991677;100874;101020;101259.49370253102;101307.49346253222;102079.48960255152;72758;82761.3447731042;86946;90665.54667226753;91984.08015919755;74489.25510744931;79156;79605;79655;81604.59197703973;10253.384796912209;12034.939825300817;12603.74792504163;12635.620931372074;12775.700373932781;39756.006099847735;40481.39277910892;40754.18491630092;41784;42305.365419518115;56752;69400;69877;70221;71082;14166.645833853823;15822.920885395959;16381.754273686143;16452;16454.917725411295;17316.740248896527;17576.648467030333;17931.641367172324;18247.908760456114;18440.723389148905;16756.413525526546;17081.31674732997;17150.142492875057;17444.73832892482;17532.386366477112;15219;16440.260188291315;16840.494785156465;17129.229184686967;17691.107956249605;15632;15845.683086337978;16295.85928985007;16540.92483988537;16680.16599170061;11635.47640356169;11720.73926063939;11760.58837940668;12126.818097728717;12348;11373;13400.329983500591;13406.32968351559;13474.93262533681;13476.932615337253 02:59:03 INFO - PID 1635 | __end_tp_report 02:59:03 INFO - PID 1635 | __start_cc_report 02:59:03 INFO - PID 1635 | _x_x_mozilla_cycle_collect,1774 02:59:03 INFO - PID 1635 | __end_cc_report 02:59:03 INFO - PID 1635 | __startTimestamp1491904743623__endTimestamp 02:59:03 INFO - PID 1635 | 02:59:03 INFO - PID 1635 | ------- Summary: start ------- 02:59:03 INFO - PID 1635 | Number of tests: 6 02:59:03 INFO - PID 1635 | 02:59:03 INFO - PID 1635 | [#0] dojo.html Cycles:165 Average:5931.33 Median:6201.66 stddev:700.87 (11.3%) stddev-sans-first:663.27 02:59:03 INFO - PID 1635 | Values: 2965.0 3405.7 3439.0 3469.5 3472.6 5070.9 5247.9 5840.1 6086.5 6098.3 6030.2 6072.1 6082.2 6105.4 6179.9 4761.5 5173.6 5261.6 5273.6 5275.5 5392.8 5397.9 5405.8 5407.7 5428.8 5201.5 5272.5 5279.5 5349.3 5353.6 5277.9 5286.9 5327.5 5331.0 5373.9 5216.4 5243.9 5253.9 5263.5 5273.4 5738.4 6086.7 6110.2 6113.5 6120.9 6324.1 6332.2 6348.6 6402.4 6413.2 6516.4 6526.8 6527.1 6528.8 6529.8 6057.4 6179.2 6184.4 6197.6 6201.7 6400.1 6473.7 6568.7 6634.2 6652.8 6328.9 6341.6 6356.7 6357.9 6362.6 4945.5 6435.6 6490.7 6495.5 6499.2 5483.2 6273.2 6358.9 6364.1 6445.9 6334.2 6365.0 6413.3 6419.2 6427.9 5201.9 5203.6 5209.2 5211.8 5226.8 4637.1 4670.0 4671.7 4688.9 4730.6 5960.4 5993.6 6111.6 6135.4 6166.8 6377.6 6442.5 6447.3 6453.5 6465.2 6052.2 6057.1 6070.6 6079.1 6084.3 4840.5 5589.2 5651.7 5652.4 5729.6 6557.2 6634.4 6639.8 6644.2 6651.3 6293.8 6342.8 6347.1 6416.7 6464.9 6565.6 6677.4 6693.3 6697.5 6700.8 6397.0 6405.8 6408.4 6412.3 6413.3 6275.5 6333.1 6366.5 6512.5 6548.1 5551.4 5778.2 6308.7 6310.2 6326.8 6301.3 6309.5 6314.0 6329.5 6334.4 6016.9 6132.5 6145.1 6179.9 6218.5 6250.5 6282.8 6304.1 6332.4 6341.1 6204.7 6213.3 6215.4 6218.8 6220.4 02:59:03 INFO - PID 1635 | 02:59:03 INFO - PID 1635 | [#1] ext.html Cycles:180 Average:44694.72 Median:3192.76 stddev:80980.44 (2536.4%) stddev-sans-first:81140.52 02:59:03 INFO - PID 1635 | Values: 791.6 1114.5 1143.1 1143.4 1293.2 29118.4 31216.9 35277.9 35570.5 35602.8 36406.6 36709.0 36780.4 36787.0 36798.8 6145.2 6299.6 7879.1 8046.8 8051.2 8316.5 8525.1 8525.8 8526.0 8532.2 8546.8 8576.6 8585.2 8591.6 8617.3 6090.6 6231.7 8509.3 8573.3 8584.4 8295.0 8470.9 8478.6 8479.2 8482.7 36728.6 37093.8 37145.0 37154.8 37177.0 3593.7 3638.4 3648.2 3651.1 3651.7 30457.1 30527.6 30565.8 30584.2 30590.8 3123.3 3183.7 3185.1 3188.0 3197.5 209544.0 285628.0 286380.0 286577.0 287375.0 39088.8 39595.4 39607.8 47840.0 48271.0 48316.5 48963.8 49020.8 49027.0 49168.8 14086.7 14206.5 14220.6 14237.7 14240.6 11566.4 11576.7 11582.9 11591.8 11616.2 12284.1 12324.4 12330.4 12357.5 12439.3 4473.5 5029.0 5497.3 5527.5 5538.7 953.6 991.3 1008.8 1040.7 1060.9 20188.6 22077.9 23558.6 23645.5 23754.0 1000.8 1098.9 1126.3 1131.2 1146.8 12066.6 12099.3 12106.6 12119.5 12139.5 339126.0 340508.0 340600.0 340637.0 341011.0 51725.0 51748.5 51748.7 51788.0 52247.0 1493.1 1528.8 1552.7 1555.3 1571.8 18207.1 22974.7 23040.1 23151.9 23502.6 36861.0 36923.6 36972.0 37011.6 37054.7 306090.0 306420.5 307059.0 307156.0 307219.5 29144.5 29621.4 29644.8 33231.8 34102.3 40409.6 42027.6 43197.6 43347.3 43509.8 23866.2 29106.4 30094.0 30202.2 30589.0 29022.0 29114.3 29743.4 29844.0 30206.7 35312.6 35400.0 35454.0 35468.1 35494.3 28422.9 28595.7 29130.0 29277.4 30263.5 23524.4 23558.0 23673.3 23678.9 24039.6 02:59:03 INFO - PID 1635 | 02:59:03 INFO - PID 1635 | [#2] jquery.html Cycles:180 Average:40561.84 Median:12669.53 stddev:119696.54 (944.8%) stddev-sans-first:119994.39 02:59:03 INFO - PID 1635 | Values: 431.2 436.9 438.5 443.9 465.3 16583.9 20718.7 21387.7 22226.9 22264.0 17296.3 23035.5 23225.4 23616.2 23704.8 5995.6 6246.6 6254.2 6271.4 6272.7 5761.7 6059.4 6104.6 6105.4 6124.1 5800.7 6069.2 6072.5 6087.0 6089.5 6004.7 6146.6 6148.3 6157.8 6159.9 5319.3 5398.6 5460.6 5461.8 5479.2 27154.9 27584.6 27625.3 28756.7 28832.7 2024.7 2229.2 2303.6 2307.3 2330.3 17725.7 17799.6 18048.6 18293.8 18526.7 2133.0 2133.5 2178.9 2183.1 2193.3 161921.2 163148.0 164808.0 165589.0 166983.2 10431.7 12180.9 12287.7 12391.8 12439.4 16625.2 16641.7 16659.3 16672.9 16674.8 8502.0 8847.4 8874.2 8979.7 9000.7 6511.0 8378.3 8400.2 8555.0 8568.8 6651.0 7608.8 7764.5 7792.9 7793.5 1765.5 1831.9 2004.9 2082.3 2246.2 799.8 967.6 967.6 1000.2 1010.6 19986.7 20018.0 20987.6 21060.9 21147.6 1000.5 1012.8 1024.9 1025.8 1039.0 10555.1 10910.7 10919.8 10960.0 10990.6 645971.0 719075.0 726117.0 737930.0 747889.0 80123.4 125719.4 132740.0 133242.0 158264.2 1072.5 1156.9 1205.3 1208.6 1212.1 21282.7 21364.0 21617.5 21810.5 21840.0 57996.0 58362.8 59535.7 59749.7 59904.0 83888.0 84319.7 85070.0 86150.0 86800.0 7238.5 8500.1 8628.5 8844.4 8896.3 10955.7 13543.5 13761.9 14045.9 14052.8 11792.8 11876.6 11960.5 12141.0 12143.8 7075.6 8276.1 9846.0 9917.8 10014.8 10969.2 11572.9 12108.0 12127.0 12133.1 8615.8 8734.5 8782.9 8855.4 9288.3 10613.5 11882.0 12129.7 12215.9 12281.0 02:59:03 INFO - PID 1635 | 02:59:03 INFO - PID 1635 | [#3] mootools.html Cycles:180 Average:7108.45 Median:2543.19 stddev:4516.80 (177.6%) stddev-sans-first:4508.79 02:59:03 INFO - PID 1635 | Values: 1355.9 1426.8 1446.1 1446.5 1451.5 9306.5 11636.5 11640.1 11640.6 11642.0 11412.8 11418.4 11537.1 11791.2 11809.1 2490.7 2540.7 2543.1 2543.3 2545.7 2145.3 2458.7 2462.7 2519.6 2530.4 2140.8 2421.1 2523.1 2530.9 2532.4 2484.4 2498.9 2501.0 2534.3 2534.7 2265.9 2379.3 2501.1 2514.0 2515.5 9554.8 11822.8 11838.0 11859.4 11875.7 6408.7 6638.4 6656.1 6663.4 6664.7 3441.4 4249.2 4329.5 4330.8 4334.2 1251.7 1269.2 1321.4 1323.3 1327.1 14899.7 15355.5 15403.8 16047.2 16164.4 5348.9 5470.0 5667.9 5673.3 5678.4 12165.6 12184.1 12187.2 12194.0 12195.1 6493.8 6534.5 6543.9 6545.8 6546.7 6055.6 6111.8 6140.7 6144.3 6210.1 3724.0 3934.4 3984.0 4273.4 4377.3 1165.1 1267.5 1298.9 1302.7 1321.8 782.3 803.0 804.7 810.5 825.6 6097.5 6101.8 6157.6 6179.7 6392.2 581.2 584.0 592.6 596.3 597.5 3907.4 4014.3 4245.7 4345.7 4378.6 13414.3 15388.9 15402.2 15417.8 15482.5 13654.7 15133.3 15166.8 15283.8 15299.2 10566.5 10878.3 10882.3 10883.7 10899.5 4264.6 6910.0 7091.6 7096.4 7155.3 12947.2 12965.8 13657.5 13682.7 13718.4 13767.5 13801.0 13905.1 13929.0 14061.7 9296.2 9580.9 9583.7 9589.9 9604.4 10568.8 10585.9 10624.9 10624.9 10679.1 7726.7 8558.5 9156.5 9274.9 9314.6 8985.3 8988.7 9053.2 9075.8 9112.6 8061.9 8183.8 8205.3 8206.1 8220.1 5770.9 5934.9 5938.3 6133.7 6174.4 5975.6 6095.1 6165.3 6251.9 6322.4 02:59:03 INFO - PID 1635 | 02:59:03 INFO - PID 1635 | [#4] prototype.html Cycles:180 Average:5121.32 Median:5711.24 stddev:1552.35 (27.2%) stddev-sans-first:1515.55 02:59:03 INFO - PID 1635 | Values: 390.4 399.6 399.6 405.6 410.9 4882.2 5877.1 5916.4 5948.8 5963.2 4943.5 5573.7 5760.5 5805.4 5876.2 4454.7 4469.9 4485.5 4507.9 4512.2 4496.3 4504.9 4512.2 4518.5 4530.3 4333.0 4408.6 4430.6 4462.6 4481.8 4061.1 4422.0 4426.7 4442.1 4454.6 3486.8 3617.4 3954.4 3979.4 3986.4 5584.4 5700.0 5722.5 5784.9 5790.9 4740.8 5343.0 5344.9 5402.7 5416.8 6187.7 6227.2 6280.5 6299.5 6303.9 4392.8 4519.5 4528.5 4534.9 4544.2 5504.6 6514.7 6525.8 6573.1 6612.2 3698.0 4089.1 4626.2 4654.9 4671.8 4177.0 4288.6 4479.3 4501.9 4717.5 4491.3 4871.7 5103.5 5203.7 5358.7 6211.2 6280.3 6289.6 6300.5 6325.1 1384.9 1454.7 1553.9 1594.3 1616.9 1016.9 1123.8 1138.9 1140.6 1147.2 4996.5 5657.2 5870.7 5900.7 5924.7 5177.4 6025.0 6054.2 6485.4 6506.4 5132.6 5368.4 5583.3 5874.9 5921.3 2571.0 2699.0 3329.8 3336.0 3346.5 6336.9 6530.2 6585.4 6619.2 6629.5 6546.3 6594.7 6640.2 6645.6 6784.4 5626.7 6226.1 6517.7 6533.9 6572.4 5167.7 6289.5 6344.7 6385.6 6472.6 5530.9 5862.9 6302.9 6357.5 6373.4 6111.4 6159.6 6585.5 6682.7 6799.2 4056.6 4649.0 4683.3 4762.5 4767.0 6241.5 6461.9 6481.5 6497.5 6585.1 6216.7 6259.8 6303.9 6327.9 6329.4 6205.7 6257.9 6258.7 6272.8 6353.7 5127.4 6059.4 6097.9 6106.6 6107.7 6205.4 6208.5 6215.8 6227.7 6308.3 5852.5 6048.5 6093.4 6113.5 6131.3 02:59:03 INFO - PID 1635 | 02:59:03 INFO - PID 1635 | [#5] yui.html Cycles:180 Average:21420.71 Median:17674.92 stddev:29263.52 (165.6%) stddev-sans-first:29304.31 02:59:03 INFO - PID 1635 | Values: 713.5 814.8 874.3 877.1 889.6 13321.4 17815.3 17952.6 18484.2 18542.0 15316.9 17377.8 17950.2 17981.7 17987.3 993.8 1021.7 1024.0 1025.9 1028.4 669.5 687.9 988.0 1025.9 1029.1 1074.8 1077.8 1079.4 1080.3 1080.6 1072.2 1073.3 1074.2 1075.3 1075.3 793.0 795.0 796.0 796.4 798.3 16341.8 16468.5 16656.4 16907.6 17215.3 16665.3 16745.8 16971.0 17108.0 17209.0 13910.3 13925.1 14002.0 14102.1 14128.6 3396.4 3417.8 3425.8 3428.7 3431.7 111795.0 113864.4 113952.0 113999.9 115163.4 11253.4 11613.6 12047.6 12207.1 12221.2 17658.7 20283.8 25275.1 25372.2 25438.5 8876.2 8974.4 9099.8 9105.7 9112.1 4765.4 4839.5 4882.6 4884.4 4889.2 4218.1 4277.0 4289.6 4292.4 4302.5 845.1 870.0 879.6 881.0 882.6 632.7 642.5 650.6 668.6 678.5 10231.6 10440.3 10842.9 11683.9 12017.9 612.0 644.4 649.7 666.2 682.9 5254.5 5465.7 5481.8 5578.9 5619.8 100874.0 101020.0 101259.5 101307.5 102079.5 72758.0 82761.3 86946.0 90665.5 91984.1 74489.3 79156.0 79605.0 79655.0 81604.6 10253.4 12034.9 12603.7 12635.6 12775.7 39756.0 40481.4 40754.2 41784.0 42305.4 56752.0 69400.0 69877.0 70221.0 71082.0 14166.6 15822.9 16381.8 16452.0 16454.9 17316.7 17576.6 17931.6 18247.9 18440.7 16756.4 17081.3 17150.1 17444.7 17532.4 15219.0 16440.3 16840.5 17129.2 17691.1 15632.0 15845.7 16295.9 16540.9 16680.2 11635.5 11720.7 11760.6 12126.8 12348.0 11373.0 13400.3 13406.3 13474.9 13476.9 02:59:03 INFO - PID 1635 | -------- Summary: end -------- 02:59:03 INFO - PID 1635 | 02:59:04 INFO - TEST-INFO | 1635: exit 0 02:59:04 INFO - TEST-OK | dromaeo_css | took 1119345ms 02:59:04 INFO - TEST-START | kraken 02:59:04 INFO - operating with platform_type : mac_ 02:59:04 INFO - Initialising browser for kraken test... 02:59:04 INFO - TEST-INFO | started process 1644 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/tmprvAXYH/profile http://localhost:49241/getInfo.html) 02:59:06 INFO - PID 1644 | __metrics Screen width/height:1600/1200 02:59:06 INFO - PID 1644 | colorDepth:24 02:59:06 INFO - PID 1644 | Browser inner width/height: 1024/689 02:59:06 INFO - PID 1644 | __metrics 02:59:06 INFO - PID 1644 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 673: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 02:59:06 INFO - PID 1644 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 304: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 02:59:07 INFO - TEST-INFO | 1644: exit 0 02:59:07 INFO - Browser initialized. 02:59:07 INFO - Running cycle 1/1 for kraken test... 02:59:07 INFO - TEST-INFO | started process 1645 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/tmprvAXYH/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 02:59:20 INFO - PID 1645 | RSS: Main: 238534656 02:59:20 INFO - PID 1645 | 02:59:23 INFO - PID 1645 | 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) 02:59:24 INFO - PID 1645 | RSS: Main: 292331520 02:59:24 INFO - PID 1645 | 02:59:27 INFO - PID 1645 | 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) 02:59:27 INFO - PID 1645 | RSS: Main: 292864000 02:59:27 INFO - PID 1645 | 02:59:31 INFO - PID 1645 | 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) 02:59:32 INFO - PID 1645 | RSS: Main: 384827392 02:59:32 INFO - PID 1645 | 02:59:34 INFO - PID 1645 | 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) 02:59:35 INFO - PID 1645 | RSS: Main: 288538624 02:59:35 INFO - PID 1645 | 02:59:37 INFO - PID 1645 | 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) 02:59:37 INFO - PID 1645 | RSS: Main: 289357824 02:59:37 INFO - PID 1645 | 02:59:41 INFO - PID 1645 | 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) 02:59:41 INFO - PID 1645 | RSS: Main: 332488704 02:59:41 INFO - PID 1645 | 02:59:44 INFO - PID 1645 | 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) 02:59:45 INFO - PID 1645 | RSS: Main: 344784896 02:59:45 INFO - PID 1645 | 02:59:48 INFO - PID 1645 | 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) 02:59:49 INFO - PID 1645 | RSS: Main: 351866880 02:59:49 INFO - PID 1645 | 02:59:50 INFO - PID 1645 | 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) 02:59:51 INFO - PID 1645 | RSS: Main: 311062528 02:59:51 INFO - PID 1645 | 02:59:53 INFO - PID 1645 | 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) 02:59:54 INFO - PID 1645 | RSS: Main: 314212352 02:59:54 INFO - PID 1645 | 02:59:56 INFO - PID 1645 | 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) 02:59:57 INFO - PID 1645 | RSS: Main: 315092992 02:59:57 INFO - PID 1645 | 03:00:00 INFO - PID 1645 | 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) 03:00:00 INFO - PID 1645 | RSS: Main: 334192640 03:00:00 INFO - PID 1645 | 03:00:04 INFO - PID 1645 | 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) 03:00:04 INFO - PID 1645 | RSS: Main: 328192000 03:00:04 INFO - PID 1645 | 03:00:06 INFO - PID 1645 | 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) 03:00:07 INFO - PID 1645 | RSS: Main: 391622656 03:00:07 INFO - PID 1645 | 03:00:07 INFO - PID 1645 | __start_tp_report 03:00:07 INFO - PID 1645 | _x_x_mozilla_page_load 03:00:07 INFO - PID 1645 | _x_x_mozilla_page_load_details 03:00:07 INFO - PID 1645 | |i|pagename|runs| 03:00:07 INFO - PID 1645 | |0;ai-astar;113;172;135;131;178;158;120;121;122;119 03:00:07 INFO - PID 1645 | |1;audio-beat-detection;158;123;153;176;142;156;117;120;141;118 03:00:07 INFO - PID 1645 | |2;audio-dft;212;217;213;219;242;282;214;213;236;232 03:00:07 INFO - PID 1645 | |3;audio-fft;86;63;95;125;89;63;64;62;101;61 03:00:07 INFO - PID 1645 | |4;audio-oscillator;97;74;94;111;110;75;92;73;75;92 03:00:07 INFO - PID 1645 | |5;imaging-gaussian-blur;104;137;95;90;135;91;93;92;93;92 03:00:07 INFO - PID 1645 | |6;imaging-darkroom;100;98;123;99;100;100;102;101;100;100 03:00:07 INFO - PID 1645 | |7;imaging-desaturate;102;102;103;103;102;143;157;116;101;103 03:00:07 INFO - PID 1645 | |8;json-parse-financial;54;51;51;52;52;52;52;51;51;51 03:00:07 INFO - PID 1645 | |9;json-stringify-tinderbox;59;64;67;66;70;71;63;65;64;66 03:00:07 INFO - PID 1645 | |10;stanford-crypto-aes;90;90;92;92;89;89;89;88;90;90 03:00:07 INFO - PID 1645 | |11;stanford-crypto-ccm;148;148;153;146;149;152;150;155;200;182 03:00:07 INFO - PID 1645 | |12;stanford-crypto-pbkdf2;203;208;194;191;203;211;227;203;202;201 03:00:07 INFO - PID 1645 | |13;stanford-crypto-sha256-iterative;72;69;70;74;75;75;99;96;101;99 03:00:07 INFO - PID 1645 | __end_tp_report 03:00:07 INFO - PID 1645 | __start_cc_report 03:00:07 INFO - PID 1645 | _x_x_mozilla_cycle_collect,2146 03:00:07 INFO - PID 1645 | __end_cc_report 03:00:07 INFO - PID 1645 | __startTimestamp1491904807010__endTimestamp 03:00:07 INFO - PID 1645 | 03:00:07 INFO - PID 1645 | ------- Summary: start ------- 03:00:07 INFO - PID 1645 | Number of tests: 14 03:00:07 INFO - PID 1645 | 03:00:07 INFO - PID 1645 | [#0] ai-astar Cycles:10 Average:136.90 Median:133.00 stddev:23.70 (17.8%) stddev-sans-first:23.51 03:00:07 INFO - PID 1645 | Values: 113.0 172.0 135.0 131.0 178.0 158.0 120.0 121.0 122.0 119.0 03:00:07 INFO - PID 1645 | 03:00:07 INFO - PID 1645 | [#1] audio-beat-detection Cycles:10 Average:140.40 Median:147.50 stddev:20.41 (13.8%) stddev-sans-first:20.63 03:00:07 INFO - PID 1645 | Values: 158.0 123.0 153.0 176.0 142.0 156.0 117.0 120.0 141.0 118.0 03:00:07 INFO - PID 1645 | 03:00:07 INFO - PID 1645 | [#2] audio-dft Cycles:10 Average:228.00 Median:225.50 stddev:21.85 (9.7%) stddev-sans-first:22.39 03:00:07 INFO - PID 1645 | Values: 212.0 217.0 213.0 219.0 242.0 282.0 214.0 213.0 236.0 232.0 03:00:07 INFO - PID 1645 | 03:00:07 INFO - PID 1645 | [#3] audio-fft Cycles:10 Average:80.90 Median:63.50 stddev:21.91 (34.5%) stddev-sans-first:23.16 03:00:07 INFO - PID 1645 | Values: 86.0 63.0 95.0 125.0 89.0 63.0 64.0 62.0 101.0 61.0 03:00:07 INFO - PID 1645 | 03:00:07 INFO - PID 1645 | [#4] audio-oscillator Cycles:10 Average:89.30 Median:83.50 stddev:14.55 (17.4%) stddev-sans-first:15.16 03:00:07 INFO - PID 1645 | Values: 97.0 74.0 94.0 111.0 110.0 75.0 92.0 73.0 75.0 92.0 03:00:07 INFO - PID 1645 | 03:00:07 INFO - PID 1645 | [#5] imaging-gaussian-blur Cycles:10 Average:102.20 Median:92.00 stddev:18.24 (19.8%) stddev-sans-first:19.33 03:00:07 INFO - PID 1645 | Values: 104.0 137.0 95.0 90.0 135.0 91.0 93.0 92.0 93.0 92.0 03:00:07 INFO - PID 1645 | 03:00:07 INFO - PID 1645 | [#6] imaging-darkroom Cycles:10 Average:102.30 Median:101.50 stddev:7.35 (7.2%) stddev-sans-first:7.75 03:00:07 INFO - PID 1645 | Values: 100.0 98.0 123.0 99.0 100.0 100.0 102.0 101.0 100.0 100.0 03:00:07 INFO - PID 1645 | 03:00:07 INFO - PID 1645 | [#7] imaging-desaturate Cycles:10 Average:113.20 Median:103.00 stddev:20.14 (19.6%) stddev-sans-first:20.95 03:00:07 INFO - PID 1645 | Values: 102.0 102.0 103.0 103.0 102.0 143.0 157.0 116.0 101.0 103.0 03:00:07 INFO - PID 1645 | 03:00:07 INFO - PID 1645 | [#8] json-parse-financial Cycles:10 Average:51.70 Median:52.00 stddev:0.95 (1.8%) stddev-sans-first:0.53 03:00:07 INFO - PID 1645 | Values: 54.0 51.0 51.0 52.0 52.0 52.0 52.0 51.0 51.0 51.0 03:00:07 INFO - PID 1645 | 03:00:07 INFO - PID 1645 | [#9] json-stringify-tinderbox Cycles:10 Average:65.50 Median:66.00 stddev:3.44 (5.2%) stddev-sans-first:2.73 03:00:07 INFO - PID 1645 | Values: 59.0 64.0 67.0 66.0 70.0 71.0 63.0 65.0 64.0 66.0 03:00:07 INFO - PID 1645 | 03:00:07 INFO - PID 1645 | [#10] stanford-crypto-aes Cycles:10 Average:89.90 Median:90.00 stddev:1.29 (1.4%) stddev-sans-first:1.36 03:00:07 INFO - PID 1645 | Values: 90.0 90.0 92.0 92.0 89.0 89.0 89.0 88.0 90.0 90.0 03:00:07 INFO - PID 1645 | 03:00:07 INFO - PID 1645 | [#11] stanford-crypto-ccm Cycles:10 Average:158.30 Median:152.50 stddev:17.94 (11.8%) stddev-sans-first:18.64 03:00:07 INFO - PID 1645 | Values: 148.0 148.0 153.0 146.0 149.0 152.0 150.0 155.0 200.0 182.0 03:00:07 INFO - PID 1645 | 03:00:07 INFO - PID 1645 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:204.30 Median:203.00 stddev:9.88 (4.9%) stddev-sans-first:10.47 03:00:07 INFO - PID 1645 | Values: 203.0 208.0 194.0 191.0 203.0 211.0 227.0 203.0 202.0 201.0 03:00:07 INFO - PID 1645 | 03:00:07 INFO - PID 1645 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:83.00 Median:75.00 stddev:13.74 (18.3%) stddev-sans-first:13.99 03:00:07 INFO - PID 1645 | Values: 72.0 69.0 70.0 74.0 75.0 75.0 99.0 96.0 101.0 99.0 03:00:07 INFO - PID 1645 | -------- Summary: end -------- 03:00:07 INFO - PID 1645 | 03:00:08 INFO - TEST-INFO | 1645: exit 0 03:00:08 INFO - TEST-OK | kraken | took 63776ms 03:00:08 INFO - SUITE-END | took 1183s 03:00:08 INFO - Completed test suite (00:19:43) 03:00:08 INFO - javascript benchmark 03:00:08 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7749.600206048118, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2964.970350296497, 3405.710514606258, 3439.0370696205073, 3469.5316132322146, 3472.6006509251442, 5070.873228169297, 5247.947520524798, 5840.0947853082735, 6086.482648974837, 6098.3291837897905, 6030.185924900137, 6072.08918662202, 6082.209312789342, 6105.429421494179, 6179.907301390483, 4761.523847615228, 5173.637116165407, 5261.579073674096, 5273.578113750891, 5275.498827611371, 5392.838214853561, 5397.946020539784, 5405.756740946647, 5407.675539467625, 5428.75570599322, 5201.479852014768, 5272.525472707436, 5279.472052794728, 5349.304590403242, 5353.598480113968, 5277.894442111175, 5286.894262114774, 5327.46725327468, 5331.0, 5373.919391209135, 5216.400113986902, 5243.86890327741, 5253.894922101575, 5263.499967503081, 5273.3935597406025, 5738.42615738427, 6086.730667412572, 6110.194261626863, 6113.480354169901, 6120.914202612733, 6324.146240257553, 6332.208473940757, 6348.587341822813, 6402.423781859608, 6413.1662883825275, 6516.446102081329, 6526.77156299532, 6527.053577231275, 6528.804135875931, 6529.771457998947, 6057.394260573996, 6179.165812615284, 6184.412480814315, 6197.597156184834, 6201.658908759971, 6400.135981642418, 6473.741050358027, 6568.704408301638, 6634.23706273775, 6652.83367915806, 6328.905066424008, 6341.619502829845, 6356.682165891687, 6357.904631430532, 6362.554621176566, 4945.5293113686885, 6435.646039467781, 6490.707918143695, 6495.545311828221, 6499.1876015498065, 5483.204935284361, 6273.247210334789, 6358.904616430757, 6364.077208804697, 6445.9355406446275, 6334.208223972004, 6364.968175159094, 6413.3266007069515, 6419.165508483868, 6427.903581446282, 5201.869953251124, 5203.583713302926, 5209.244659524347, 5211.7915283389, 5226.817061402832, 4637.118947399983, 4669.976650116796, 4671.672982891165, 4688.889999500238, 4730.59789917854, 5960.403959604005, 5993.580449368503, 6111.602745821508, 6135.44703110649, 6166.784162554379, 6377.585456945284, 6442.516811239082, 6447.25856526496, 6453.515986301046, 6465.224173099165, 6052.182955301066, 6057.091436284592, 6070.605410648293, 6079.118527813443, 6084.30030546484, 4840.491748366441, 5589.161625756088, 5651.660900345993, 5652.406497317805, 5729.5702822288495, 6557.245916719543, 6634.369734875179, 6639.800805975829, 6644.202695676549, 6651.301613330531, 6293.842653933688, 6342.7780027700655, 6347.143135676624, 6416.743330266924, 6464.903026454513, 6565.606063636197, 6677.43241824431, 6693.2972037935315, 6697.497687673542, 6700.765473208415, 6397.0, 6405.807825765048, 6408.391202835724, 6412.294647588855, 6413.262474815456, 6275.529335299962, 6333.145025421509, 6366.4588509977175, 6512.544121911421, 6548.148740663683, 5551.417101204315, 5778.248827652378, 6308.747650094128, 6310.1796766420075, 6326.810195694136, 6301.338359472189, 6309.526785491014, 6314.0, 6329.5252856036905, 6334.398232167937, 6016.8796624068145, 6132.542037586462, 6145.125736650364, 6179.876402472015, 6218.53360997936, 6250.531210159347, 6282.811515654537, 6304.148939893239, 6332.430081292706, 6341.0805433212845, 6204.658743769136, 6213.316535181217, 6215.378462153749, 6218.849512840139, 6220.409061139185], "value": 5884.911122963182, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [791.6121100660677, 1114.470626452435, 1143.0626885953523, 1143.3825734103584, 1293.236990175796, 29118.417631647353, 31216.907408240717, 35277.94166175019, 35570.46644300331, 35602.75390361338, 36406.63593364065, 36709.0, 36780.44829327562, 36787.0, 36798.81600592, 6145.170401995718, 6299.590526615755, 7879.133295337494, 8046.839063218761, 8051.194880511961, 8316.501009939393, 8525.062243153248, 8525.786855328604, 8526.019507756595, 8532.189442003031, 8546.786330341729, 8576.571171441403, 8585.18440748131, 8591.613377398, 8617.267532259766, 6090.634561926255, 6231.719572619243, 8509.319254459659, 8573.314134869195, 8584.399092063526, 8294.958525207336, 8470.872936905951, 8478.576071196478, 8479.194476524783, 8482.745517634481, 36728.632713672785, 37093.81453092744, 37145.0, 37154.81422592897, 37177.0, 3593.694535964446, 3638.3814751492046, 3648.1609229877013, 3651.087228192952, 3651.6896063834606, 30457.086287411414, 30527.626256818276, 30565.847170764006, 30584.2353941151, 30590.84704576485, 3123.2972581169283, 3183.697548732867, 3185.0604071798643, 3188.0276515662713, 3197.488401855692, 209543.9999999985, 285628.0, 286380.0, 286577.0, 287375.0, 39088.80455597704, 39595.40606890899, 39607.80196099059, 47840.0, 48271.034579308216, 48316.5168348312, 48963.75518122387, 49020.75489622529, 49027.0, 49168.77078073077, 14086.71826563463, 14206.502772402917, 14220.573382798533, 14237.715245695028, 14240.572782816533, 11566.363849988331, 11576.710582235342, 11582.88417115835, 11591.76816463666, 11616.186866919235, 12284.0786940978, 12324.383780810924, 12330.383480825923, 12357.505699771908, 12439.253644781342, 4473.485549161823, 5028.969061342416, 5497.340319161726, 5527.53015993645, 5538.723063846792, 953.6137864165024, 991.2862738828032, 1008.7881544875512, 1040.7086015915556, 1060.8939106089329, 20188.596228075356, 22077.889610552167, 23558.646620300366, 23645.527089458115, 23754.0, 1000.8148492528906, 1098.8846171152074, 1126.2510430563748, 1131.1629394248198, 1146.7534480086822, 12066.577669781516, 12099.274043557414, 12106.636800895987, 12119.515219391125, 12139.453724582416, 339126.0, 340508.0, 340600.0, 340637.0, 341011.0, 51725.0, 51748.48251517512, 51748.74125629348, 51788.0, 52247.0, 1493.0966765106955, 1528.831828498842, 1552.7049860526463, 1555.3001149482777, 1571.8035245594301, 18207.088255733488, 22974.655380169646, 23040.078396863937, 23151.88424057869, 23502.61649638674, 36861.0, 36923.63076369202, 36972.0, 37011.62988370082, 37054.703085391884, 306090.0, 306420.4678976591, 307059.0, 307156.0, 307219.46390267904, 29144.462552115878, 29621.407571848013, 29644.84972103864, 33231.833840830644, 34102.31795364029, 40409.59590404058, 42027.57972420237, 43197.56802431936, 43347.349789752545, 43509.78245108881, 23866.164684236664, 29106.417871642872, 30093.99726337057, 30202.24494387658, 30589.0, 29022.0, 29114.27214319659, 29743.405131897674, 29844.0, 30206.697933021267, 35312.58749649985, 35400.0, 35454.0, 35468.11329716778, 35494.29011419809, 28422.85788571044, 28595.714042859305, 29129.980450684125, 29277.414451711273, 30263.546046809755, 23524.411889702893, 23558.0, 23673.28980130599, 23678.88160559186, 24039.63940540928], "value": 17022.643049288647, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [431.18936399568804, 436.87112301870945, 438.4628829683638, 443.85130981121324, 465.32295510032884, 16583.922045067076, 20718.689219661715, 21387.67918481222, 22226.88886555569, 22264.0, 17296.308147674077, 23035.508075925158, 23225.419364515896, 23616.173433929816, 23704.76295237047, 5995.640261584308, 6246.593971391868, 6254.249490061176, 6271.404216599439, 6272.749090036393, 5761.654300741948, 6059.3637668044885, 6104.572679912408, 6105.358937311563, 6124.112003759452, 5800.680962547058, 6069.180660610805, 6072.544559158058, 6086.969565152191, 6089.543284253677, 6004.669743164124, 6146.6004709693725, 6148.262208534982, 6157.846053848645, 6159.8768024639485, 5319.255304257407, 5398.6220964532495, 5460.563154947595, 5461.836144915619, 5479.1781232815, 27154.913803448035, 27584.586231206547, 27625.309367265778, 28756.712432875614, 28832.71167288321, 2024.7266619006393, 2229.2420577003895, 2303.55080759252, 2307.2962746362355, 2330.265966220635, 17725.7341139883, 17799.555011124696, 18048.639027219513, 18293.817061829344, 18526.722099168524, 2132.9573408531896, 2133.5412886229537, 2178.869267843919, 2183.0831050958636, 2193.2762188477764, 161921.19039404846, 163148.0, 164808.0, 165589.0, 166983.165084175, 10431.739206519898, 12180.939095304468, 12287.692807679701, 12391.75216495665, 12439.440225189888, 16625.168741562873, 16641.6671666566, 16659.33362665504, 16672.916635416746, 16674.83325166733, 8502.045651850665, 8847.380683352232, 8874.201321880933, 8979.730608081769, 9000.729978100668, 6510.958246680509, 8378.329733621418, 8400.218264794905, 8555.0, 8568.843206167212, 6650.96909978954, 7608.815151666561, 7764.534127952341, 7792.922070779219, 7793.493422927491, 1765.496833402474, 1831.9084045797656, 2004.8997550122435, 2082.333653230982, 2246.225052356934, 799.7600719784042, 967.6371360739722, 967.6419724701906, 1000.1598657128047, 1010.6462738041627, 19986.677536736344, 20018.0, 20987.58024839495, 21060.894695526425, 21147.577048459254, 1000.4547521600599, 1012.7822518158631, 1024.9282550221412, 1025.8358662613946, 1038.99480502597, 10555.102816260704, 10910.672679819618, 10919.781604367869, 10960.013598776148, 10990.615328463626, 645971.0, 719075.0, 726117.0, 737930.0, 747889.0, 80123.39753205019, 125719.3714031424, 132740.0, 133242.0, 158264.17358264312, 1072.5297079831316, 1156.9132315076404, 1205.25274329916, 1208.6072026591394, 1212.1333246728454, 21282.680759788924, 21364.038618261904, 21617.459563511038, 21810.454738631663, 21840.01719922639, 57996.0, 58362.832743345745, 59535.70232148811, 59749.70125149346, 59904.0, 83888.0, 84319.73520397322, 85070.0, 86150.0, 86800.0, 7238.52949558277, 8500.064992850905, 8628.482291062557, 8844.380893337404, 8896.2882969361, 10955.726106847074, 13543.525976590772, 13761.931190344385, 14045.929770351082, 14052.789208161681, 11792.823107653563, 11876.643700688994, 11960.461779219782, 12140.96801771824, 12143.817842732542, 7075.646217688992, 8276.08963014056, 9845.950770246389, 9917.801643967225, 10014.849777253492, 10969.177311701815, 11572.94213528927, 12108.0, 12127.0, 12133.088058865986, 8615.778268648442, 8734.519601421729, 8782.936010037509, 8855.380123391558, 9288.34981551285, 10613.522391492246, 11881.990030847475, 12129.69675758113, 12215.877841221474, 12281.01751859867], "value": 10449.548129618288, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1355.9322033898304, 1426.7717165253562, 1446.0528353928173, 1446.4792674637135, 1451.5355086372365, 9306.488143152124, 11636.534538618456, 11640.126990475706, 11640.592579259717, 11642.0, 11412.828807567872, 11418.429078546082, 11537.134714896374, 11791.233569817956, 11809.11431642626, 2490.67621209243, 2540.720520742717, 2543.0972004938167, 2543.2878793937725, 2545.7199708032103, 2145.2920536223087, 2458.704955405345, 2462.692163479565, 2519.6346529753173, 2530.3927057506157, 2140.785921407862, 2421.092090466075, 2523.129520315488, 2530.9493810123786, 2532.3795670060863, 2484.3664865459373, 2498.850068995857, 2500.9749902500926, 2534.3157347516253, 2534.657821194143, 2265.8527195732227, 2379.3456799380137, 2501.137107697841, 2513.9818373558737, 2515.4717509323063, 9554.761130971712, 11822.763544729058, 11838.0, 11859.40702964857, 11875.703107422298, 6408.679566021727, 6638.435732962704, 6656.101426307435, 6663.400293973517, 6664.666766661648, 3441.4493681011168, 4249.150169965988, 4329.458817647794, 4330.761808100553, 4334.154839806239, 1251.6683078984076, 1269.2447993443886, 1321.4449931028992, 1323.3383308345826, 1327.1174668845304, 14899.70200595982, 15355.462558810388, 15403.84471164656, 16047.197640117947, 16164.434244801376, 5348.9465105348445, 5469.972650136724, 5667.914981275284, 5673.347565030074, 5678.375378708339, 12165.635030949086, 12184.08619353552, 12187.207831490925, 12194.0, 12195.08536859739, 6493.79864725018, 6534.542582019308, 6543.9345606544275, 6545.770898018546, 6546.738130474823, 6055.636661800306, 6111.8472038198515, 6140.662263575547, 6144.324124346318, 6210.099535567318, 3723.9813800930824, 3934.390169523728, 3983.960160398417, 4273.358996150602, 4377.321515165155, 1165.1377980294521, 1267.5436842736608, 1298.8571257161705, 1302.7199152182327, 1321.7752981993085, 782.2724865874791, 802.9690951423936, 804.7223707820792, 810.5136917849243, 825.636719843279, 6097.451229389376, 6101.816945491642, 6157.5529750508595, 6179.671739184614, 6392.20097487814, 581.2269681323829, 583.988320233593, 592.6207227374484, 596.3023262782555, 597.4981015946626, 3907.433422153772, 4014.337634290368, 4245.681573881971, 4345.695801293942, 4378.6278166356215, 13414.262215578041, 15388.923055384652, 15402.152881591395, 15417.768733469007, 15482.5355239343, 13654.658633534242, 15133.319000644777, 15166.848331516543, 15283.770743438858, 15299.235038248265, 10566.52450639723, 10878.292910960921, 10882.347059176474, 10883.727906802234, 10899.455027248607, 4264.57354264571, 6909.965450172717, 7091.60996145217, 7096.361327480551, 7155.3202445767565, 12947.158434701714, 12965.805512917124, 13657.453701851815, 13682.742347512947, 13718.38267277955, 13767.518136865161, 13801.0, 13905.096168749, 13929.024968252124, 14061.718765624835, 9296.209822165192, 9580.856287155837, 9583.712488625353, 9589.856152157583, 9604.375715578464, 10568.841467377842, 10585.9470702646, 10624.946875265574, 10624.946875265574, 10679.14566834667, 7726.652300646372, 8558.486490810572, 9156.450612963243, 9274.907250927405, 9314.627414903329, 8985.326100542356, 8988.730338089868, 9053.185213330831, 9075.773105672411, 9112.635494580143, 8061.919380806117, 8183.754487365389, 8205.302549283379, 8206.138355472829, 8220.05469371006, 5770.913436298375, 5934.851628709144, 5938.287405511366, 6133.681258529437, 6174.38256174379, 5975.55183361241, 6095.146679464967, 6165.290991535851, 6251.906221406592, 6322.374351899418], "value": 5314.393662872996, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [390.41632759025254, 399.55050568110875, 399.5974425763674, 405.5781986733797, 410.93425051991704, 4882.243252295895, 5877.14781356703, 5916.437938395851, 5948.7620495180145, 5963.224780778503, 4943.530364615365, 5573.693446860421, 5760.5022694099525, 5805.419458054193, 5876.179433871734, 4454.710443821157, 4469.88825279369, 4485.461744590653, 4507.909841803162, 4512.210363186454, 4496.348029535732, 4504.896300406405, 4512.232920403523, 4518.5481451854885, 4530.275155975045, 4332.97833510832, 4408.58118478744, 4430.591561396415, 4462.598366147063, 4481.775911204427, 4061.1065565575527, 4421.977890110529, 4426.690131690767, 4442.089371678798, 4454.643628509744, 3486.8430920608635, 3617.366960781853, 3954.4463775071513, 3979.4030895365636, 3986.362182050858, 5584.413636568183, 5700.030994730906, 5722.456366645162, 5784.855378615526, 5790.855228619276, 4740.834070807539, 5342.98483288174, 5344.91982620261, 5402.6758394496455, 5416.756245968902, 6187.659678717669, 6227.159333489966, 6280.497560195174, 6299.5275354348605, 6303.936960630381, 4392.802323895432, 4519.525449827754, 4528.524504926968, 4534.911621210886, 4544.2047641662575, 5504.559635229172, 6514.706838192293, 6525.804225873231, 6573.1454910861285, 6612.239592446882, 3697.9815100924325, 4089.1003979124544, 4626.21354369758, 4654.86035418938, 4671.8364857229835, 4176.955761059735, 4288.5711428857485, 4479.260921947907, 4501.909961800746, 4717.5046620104395, 4491.3487544306545, 4871.707697538088, 5103.540681338697, 5203.713795741271, 5358.732063396815, 6211.223597050369, 6280.340564240689, 6289.622622642656, 6300.495960323163, 6325.082862984934, 1384.8615138486273, 1454.6581553334959, 1553.9433185434013, 1594.2666373468103, 1616.8625666818216, 1016.9440680762481, 1123.8145705958425, 1138.9373584452935, 1140.5779861451317, 1147.2370873369116, 4996.526024822686, 5657.151427285858, 5870.706464676749, 5900.734466948998, 5924.7333869975955, 5177.385420362768, 6025.0, 6054.243219597551, 6485.416312531895, 6506.3818937200895, 5132.56033530993, 5368.436314187033, 5583.302087239095, 5874.941250587525, 5921.289445266596, 2571.0, 2698.9595156072673, 3329.8345579047623, 3336.0, 3346.4645656695297, 6336.873262534724, 6530.183727034121, 6585.440237579764, 6619.172603424572, 6629.502787290876, 6546.312637173123, 6594.736210551524, 6640.203175618956, 6645.634490102994, 6784.3894049535775, 5626.6802586475, 6226.12834203221, 6517.706703198368, 6533.869322613521, 6572.4084832365315, 5167.741612919414, 6289.465395441439, 6344.731053789168, 6385.584936979081, 6472.611643301417, 5530.944690553043, 5862.94137058624, 6302.873942521215, 6357.523185761179, 6373.3945275198785, 6111.419415155553, 6159.5688301818445, 6585.506087043586, 6682.732690692513, 6799.184097908283, 4056.6310891899434, 4648.953510464852, 4683.344331793483, 4762.499937506512, 4766.97510035344, 6241.458990732301, 6461.870762584816, 6481.5138864584405, 6497.480201583768, 6585.078089067439, 6216.72024758896, 6259.843503912439, 6303.936960630335, 6327.873442531216, 6329.430351268408, 6205.720742566505, 6257.937420625735, 6258.718357673824, 6272.8118156455375, 6353.714082866374, 5127.3850336328, 6059.394060593905, 6097.908531371944, 6106.572539922163, 6107.694615269308, 6205.441510264098, 6208.4722798562625, 6215.782447614311, 6227.688615569113, 6308.306086330408, 5852.531797456279, 6048.455638992512, 6093.421124993119, 6113.51091912637, 6131.264248290234], "value": 4648.35244767161, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [713.4827250243571, 814.7922279818646, 874.3398733955862, 877.0746862060528, 889.6396959231512, 13321.400536975847, 17815.28738850446, 17952.640947181048, 18484.168212430457, 18542.0, 15316.923415382907, 17377.826221737778, 17950.192241349137, 17981.730274045898, 17987.280508779666, 993.8409854423276, 1021.724134483689, 1024.0169437340162, 1025.8922813104627, 1028.3778314119925, 669.5112567825466, 687.8761822871882, 988.0218583602253, 1025.9281850270484, 1029.0532709906904, 1074.8226542620457, 1077.8006068877244, 1079.3955384984395, 1080.2546243092281, 1080.6325849211266, 1072.1958531101673, 1073.3345325897915, 1074.2426589254596, 1075.3225467955178, 1075.3386667199616, 793.0087390761548, 795.0, 796.0487217774762, 796.3549524884862, 798.2576204130156, 16341.754873676904, 16468.505944821674, 16656.417025404175, 16907.577310567212, 17215.311387544483, 16665.25006374716, 16745.83254167455, 16971.0, 17108.0, 17209.0, 13910.304484775821, 13925.094868833494, 14002.0, 14102.083364581269, 14128.576142715736, 3396.3886500429912, 3417.7607567470286, 3425.8287085645866, 3428.708559772397, 3431.725461963037, 111795.0, 113864.4306778469, 113952.0, 113999.86000139975, 115163.42418287939, 11253.437328133561, 11613.59352422678, 12047.578334758417, 12207.084468664885, 12221.205621634565, 17658.735118973225, 20283.797162028484, 25275.11537096193, 25372.238832835046, 25438.491230175292, 8876.156765107304, 8974.41666291689, 9099.771530843384, 9105.726828195166, 9112.134347237134, 4765.356676848651, 4839.467658557556, 4882.609391248691, 4884.413870335582, 4889.168841296988, 4218.114196018809, 4277.0, 4289.571042895685, 4292.377605247284, 4302.483701955723, 845.0746432656232, 869.9695510657097, 879.6102158589414, 880.9779755506164, 882.6160620130136, 632.6804963493406, 642.489221069251, 650.6291413894035, 668.638934975108, 678.5385937562406, 10231.641892533726, 10440.321379110334, 10842.945785271024, 11683.948444639853, 12017.939910300393, 611.9694015299217, 644.4328990488434, 649.7336092202174, 666.2338310942358, 682.9385355317946, 5254.500822421864, 5465.726713664301, 5481.835544933659, 5578.944210557843, 5619.775208991677, 100874.0, 101020.0, 101259.49370253102, 101307.49346253222, 102079.48960255152, 72758.0, 82761.3447731042, 86946.0, 90665.54667226753, 91984.08015919755, 74489.25510744931, 79156.0, 79605.0, 79655.0, 81604.59197703973, 10253.384796912209, 12034.939825300817, 12603.74792504163, 12635.620931372074, 12775.700373932781, 39756.006099847735, 40481.39277910892, 40754.18491630092, 41784.0, 42305.365419518115, 56752.0, 69400.0, 69877.0, 70221.0, 71082.0, 14166.645833853823, 15822.920885395959, 16381.754273686143, 16452.0, 16454.917725411295, 17316.740248896527, 17576.648467030333, 17931.641367172324, 18247.908760456114, 18440.723389148905, 16756.413525526546, 17081.31674732997, 17150.142492875057, 17444.73832892482, 17532.386366477112, 15219.0, 16440.260188291315, 16840.494785156465, 17129.229184686967, 17691.107956249605, 15632.0, 15845.683086337978, 16295.85928985007, 16540.92483988537, 16680.16599170061, 11635.47640356169, 11720.73926063939, 11760.58837940668, 12126.818097728717, 12348.0, 11373.0, 13400.329983500591, 13406.32968351559, 13474.93262533681, 13476.932615337253], "value": 8375.813285993503, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1645.9, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 172.0, 135.0, 131.0, 178.0, 158.0, 120.0, 121.0, 122.0, 119.0], "value": 136.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [158.0, 123.0, 153.0, 176.0, 142.0, 156.0, 117.0, 120.0, 141.0, 118.0], "value": 140.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [212.0, 217.0, 213.0, 219.0, 242.0, 282.0, 214.0, 213.0, 236.0, 232.0], "value": 228.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 63.0, 95.0, 125.0, 89.0, 63.0, 64.0, 62.0, 101.0, 61.0], "value": 80.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 74.0, 94.0, 111.0, 110.0, 75.0, 92.0, 73.0, 75.0, 92.0], "value": 89.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 137.0, 95.0, 90.0, 135.0, 91.0, 93.0, 92.0, 93.0, 92.0], "value": 102.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [100.0, 98.0, 123.0, 99.0, 100.0, 100.0, 102.0, 101.0, 100.0, 100.0], "value": 102.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [102.0, 102.0, 103.0, 103.0, 102.0, 143.0, 157.0, 116.0, 101.0, 103.0], "value": 113.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 51.0, 51.0, 52.0, 52.0, 52.0, 52.0, 51.0, 51.0, 51.0], "value": 51.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 64.0, 67.0, 66.0, 70.0, 71.0, 63.0, 65.0, 64.0, 66.0], "value": 65.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 90.0, 92.0, 92.0, 89.0, 89.0, 89.0, 88.0, 90.0, 90.0], "value": 89.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 148.0, 153.0, 146.0, 149.0, 152.0, 150.0, 155.0, 200.0, 182.0], "value": 158.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [203.0, 208.0, 194.0, 191.0, 203.0, 211.0, 227.0, 203.0, 202.0, 201.0], "value": 204.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 69.0, 70.0, 74.0, 75.0, 75.0, 99.0, 96.0, 101.0, 99.0], "value": 83.0, "unit": "score"}]}]} 03:00:08 INFO - Return code: 0 03:00:08 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 03:00:08 ERROR - # TBPL SUCCESS # 03:00:08 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 03:00:08 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 03:00:08 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 03:00:08 INFO - 2017-04-11 03:00:08.308 screenresolution[1649:9824] starting screenresolution argv=screenresolution get 03:00:08 INFO - 2017-04-11 03:00:08.310 screenresolution[1649:9824] Display 0: 1600x1200x32@60 03:00:08 INFO - 2017-04-11 03:00:08.316 screenresolution[1650:9827] starting screenresolution argv=screenresolution list 03:00:08 INFO - Available Modes on Display 0 03:00:08 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 03:00:08 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 03:00:08 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 03:00:08 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 03:00:08 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 03:00:08 INFO - Intel Iris: 03:00:08 INFO - Chipset Model: Intel Iris 03:00:08 INFO - Type: GPU 03:00:08 INFO - Bus: Built-In 03:00:08 INFO - VRAM (Dynamic, Max): 1536 MB 03:00:08 INFO - Vendor: Intel (0x8086) 03:00:08 INFO - Device ID: 0x0a2e 03:00:08 INFO - Revision ID: 0x0009 03:00:08 INFO - Displays: 03:00:08 INFO - Display: 03:00:08 INFO - Resolution: 1600 x 1200 @ 60 Hz 03:00:08 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 03:00:08 INFO - Display Serial Number: 1600x1200 60 03:00:08 INFO - Main Display: Yes 03:00:08 INFO - Mirror: Off 03:00:08 INFO - Online: Yes 03:00:08 INFO - Rotation: Supported 03:00:08 INFO - Return code: 0 03:00:08 INFO - Running post-action listener: _resource_record_post_action 03:00:08 INFO - [mozharness: 2017-04-11 10:00:08.570764Z] Finished run-tests step (success) 03:00:08 INFO - Running post-run listener: _resource_record_post_run 03:00:08 INFO - Total resource usage - Wall time: 1203s; CPU: 53.0%; Read bytes: 38433792; Write bytes: 517307904; Read time: 242; Write time: 1624 03:00:08 INFO - TinderboxPrint: CPU usage
53.4% 03:00:08 INFO - TinderboxPrint: I/O read bytes / time
38,433,792 / 242 03:00:08 INFO - TinderboxPrint: I/O write bytes / time
517,307,904 / 1,624 03:00:08 INFO - TinderboxPrint: CPU idle
2,242.0 (46.6%) 03:00:08 INFO - TinderboxPrint: CPU system
956.4 (19.9%) 03:00:08 INFO - TinderboxPrint: CPU user
1,615.4 (33.6%) 03:00:08 INFO - TinderboxPrint: Swap in / out
245,719,040 / 0 03:00:08 INFO - install - Wall time: 20s; CPU: 54.0%; Read bytes: 207354368; Write bytes: 206009856; Read time: 15885; Write time: 446 03:00:08 INFO - run-tests - Wall time: 1184s; CPU: 53.0%; Read bytes: 34518016; Write bytes: 304068608; Read time: 217; Write time: 1149 03:00:09 INFO - Running post-run listener: _upload_blobber_files 03:00:09 INFO - Blob upload gear active. 03:00:09 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 03:00:09 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 03:00:09 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'] 03:00:09 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 03:00:09 INFO - (blobuploader) - INFO - Open directory for files ... 03:00:09 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 03:00:09 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:00:09 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:00:10 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 03:00:10 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:00:10 INFO - (blobuploader) - INFO - Done attempting. 03:00:10 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 03:00:10 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:00:10 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:00:11 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 03:00:11 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:00:11 INFO - (blobuploader) - INFO - Done attempting. 03:00:11 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 03:00:11 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:00:11 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:00:13 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 03:00:13 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:00:13 INFO - (blobuploader) - INFO - Done attempting. 03:00:13 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 03:00:13 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:00:13 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:00:16 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 03:00:16 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:00:16 INFO - (blobuploader) - INFO - Done attempting. 03:00:16 INFO - (blobuploader) - INFO - Iteration through files over. 03:00:16 INFO - Return code: 0 03:00:16 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 03:00:16 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 03:00:16 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/596de14747c63de7a750e09531945eeb114895bf2c9249beefd73f0c95126b8775a4574242d048496fbe5822df2631f92531dbe8a801e3aae5bb9a23f4cc8c4c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/629ea9a19485994222c58dcb52f3419868ac1917826b6a2f57998702537d26eb90a41ad6db33dc711c65395f22bbfdd262142c9ed6d5b655e49938d28eea517b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3abf98fb17a10e45503ebdfe1f52110f84bc3e15fc32e0bb4f5555638ec04350072b140db95056ef14814b331e8d7646f4bcd291b6603c7b921acccb983139e2", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/207a9de68ac08c1207ad1c59acd533928e308f3f9bab8f31221c3a2312c7baf6a1a73ee40506324ac74f99fa2d1c5252ffe545617b54b1cd1cbfd537d11cdf7d"} 03:00:16 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 03:00:16 INFO - Writing to file /builds/slave/test/properties/blobber_files 03:00:16 INFO - Contents: 03:00:16 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/596de14747c63de7a750e09531945eeb114895bf2c9249beefd73f0c95126b8775a4574242d048496fbe5822df2631f92531dbe8a801e3aae5bb9a23f4cc8c4c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/629ea9a19485994222c58dcb52f3419868ac1917826b6a2f57998702537d26eb90a41ad6db33dc711c65395f22bbfdd262142c9ed6d5b655e49938d28eea517b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3abf98fb17a10e45503ebdfe1f52110f84bc3e15fc32e0bb4f5555638ec04350072b140db95056ef14814b331e8d7646f4bcd291b6603c7b921acccb983139e2", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/207a9de68ac08c1207ad1c59acd533928e308f3f9bab8f31221c3a2312c7baf6a1a73ee40506324ac74f99fa2d1c5252ffe545617b54b1cd1cbfd537d11cdf7d"} 03:00:16 INFO - Running post-run listener: copy_logs_to_upload_dir 03:00:16 INFO - Copying logs to upload dir... 03:00:16 INFO - mkdir: /builds/slave/test/build/upload/logs 03:00:16 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1289.530867 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 29 secs) (at 2017-04-11 03:00:16.116843) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-11 03:00:16.119971) ========= 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.rdlV1Avf4l/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.eVQPp8suJy/Listeners TMPDIR=/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/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_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/596de14747c63de7a750e09531945eeb114895bf2c9249beefd73f0c95126b8775a4574242d048496fbe5822df2631f92531dbe8a801e3aae5bb9a23f4cc8c4c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/629ea9a19485994222c58dcb52f3419868ac1917826b6a2f57998702537d26eb90a41ad6db33dc711c65395f22bbfdd262142c9ed6d5b655e49938d28eea517b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3abf98fb17a10e45503ebdfe1f52110f84bc3e15fc32e0bb4f5555638ec04350072b140db95056ef14814b331e8d7646f4bcd291b6603c7b921acccb983139e2", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/207a9de68ac08c1207ad1c59acd533928e308f3f9bab8f31221c3a2312c7baf6a1a73ee40506324ac74f99fa2d1c5252ffe545617b54b1cd1cbfd537d11cdf7d"} build_url:https://queue.taskcluster.net/v1/task/MOM3fqXJQJi-IQ3ENfFJIA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011885 build_url: 'https://queue.taskcluster.net/v1/task/MOM3fqXJQJi-IQ3ENfFJIA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/596de14747c63de7a750e09531945eeb114895bf2c9249beefd73f0c95126b8775a4574242d048496fbe5822df2631f92531dbe8a801e3aae5bb9a23f4cc8c4c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/629ea9a19485994222c58dcb52f3419868ac1917826b6a2f57998702537d26eb90a41ad6db33dc711c65395f22bbfdd262142c9ed6d5b655e49938d28eea517b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3abf98fb17a10e45503ebdfe1f52110f84bc3e15fc32e0bb4f5555638ec04350072b140db95056ef14814b331e8d7646f4bcd291b6603c7b921acccb983139e2", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/207a9de68ac08c1207ad1c59acd533928e308f3f9bab8f31221c3a2312c7baf6a1a73ee40506324ac74f99fa2d1c5252ffe545617b54b1cd1cbfd537d11cdf7d"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-11 03:00:16.155473) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-11 03:00:16.155825) ========= 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.rdlV1Avf4l/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.eVQPp8suJy/Listeners TMPDIR=/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/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.005238 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-11 03:00:16.204534) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-11 03:00:16.205840) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-11 03:00:16.206200) ========= ========= Total master_lag: 0.18 =========