builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0220 starttime: 1490200323.13 results: success (0) buildid: 20170322075834 builduid: cac01b2237094fc3bb488dc4344e7037 revision: e03e0c60462c775c7558a1dc9d5cf2076c3cd1f9 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-03-22 09:32:03.133491) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-03-22 09:32:03.134008) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-22 09:32:03.134297) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-22 09:32:03.159547) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-22 09:32:03.159938) ========= 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.IgM4sT17Gd/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.YW1Wrlxywz/Listeners TMPDIR=/var/folders/xt/zmqpr3490qxfv07nrs0f850r00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-03-22 09:32:03-- 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% 176M=0s 2017-03-22 09:32:03 (176 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.172108 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-22 09:32:03.355743) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-22 09:32:03.356055) ========= 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.IgM4sT17Gd/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.YW1Wrlxywz/Listeners TMPDIR=/var/folders/xt/zmqpr3490qxfv07nrs0f850r00000w/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.020770 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-22 09:32:03.405126) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-03-22 09:32:03.405482) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev e03e0c60462c775c7558a1dc9d5cf2076c3cd1f9 --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 e03e0c60462c775c7558a1dc9d5cf2076c3cd1f9 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.IgM4sT17Gd/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.YW1Wrlxywz/Listeners TMPDIR=/var/folders/xt/zmqpr3490qxfv07nrs0f850r00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-03-22 09:32:03,483 truncating revision to first 12 chars 2017-03-22 09:32:03,484 Setting DEBUG logging. 2017-03-22 09:32:03,484 attempt 1/10 2017-03-22 09:32:03,484 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/e03e0c60462c?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-03-22 09:32:05,214 unpacking tar archive at: mozilla-central-e03e0c60462c/testing/mozharness/ program finished with exit code 0 elapsedTime=1.994266 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-03-22 09:32:05.423610) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-22 09:32:05.423933) ========= script_repo_revision: e03e0c60462c775c7558a1dc9d5cf2076c3cd1f9 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-22 09:32:05.424349) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-22 09:32:05.424617) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-22 09:32:05.444520) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 24 secs) (at 2017-03-22 09:32:05.444910) ========= /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.IgM4sT17Gd/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.YW1Wrlxywz/Listeners TMPDIR=/var/folders/xt/zmqpr3490qxfv07nrs0f850r00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 09:32:05 INFO - MultiFileLogger online at 20170322 09:32:05 in /builds/slave/test 09:32:05 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 09:32:05 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:32:05 INFO - {'append_to_log': False, 09:32:05 INFO - 'base_work_dir': '/builds/slave/test', 09:32:05 INFO - 'blob_upload_branch': 'Firefox', 09:32:05 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:32:05 INFO - 'branch': 'Firefox', 09:32:05 INFO - 'buildbot_json_path': 'buildprops.json', 09:32:05 INFO - 'config_files': ('talos/mac_config.py',), 09:32:05 INFO - 'default_actions': ('clobber', 09:32:05 INFO - 'read-buildbot-config', 09:32:05 INFO - 'download-and-extract', 09:32:05 INFO - 'populate-webroot', 09:32:05 INFO - 'create-virtualenv', 09:32:05 INFO - 'install', 09:32:05 INFO - 'run-tests'), 09:32:05 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:32:05 INFO - 'download_minidump_stackwalk': True, 09:32:05 INFO - 'download_symbols': 'ondemand', 09:32:05 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:32:05 INFO - 'tooltool.py': '/tools/tooltool.py', 09:32:05 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:32:05 INFO - '/tools/misc-python/virtualenv.py')}, 09:32:05 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:32:05 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:32:05 INFO - 'gecko_profile': False, 09:32:05 INFO - 'gecko_profile_interval': 0, 09:32:05 INFO - 'installer_path': 'installer.exe', 09:32:05 INFO - 'log_level': 'info', 09:32:05 INFO - 'log_name': 'talos', 09:32:05 INFO - 'log_to_console': True, 09:32:05 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 09:32:05 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 09:32:05 INFO - 'opt_config_files': (), 09:32:05 INFO - 'pip_index': False, 09:32:05 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:32:05 INFO - 'cmd': ('bash', 09:32:05 INFO - '-c', 09:32:05 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:32:05 INFO - 'enabled': True, 09:32:05 INFO - 'halt_on_failure': False, 09:32:05 INFO - 'name': 'check_screen_resolution'},), 09:32:05 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:32:05 INFO - 'cmd': ('bash', 09:32:05 INFO - '-c', 09:32:05 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:32:05 INFO - 'enabled': True, 09:32:05 INFO - 'halt_on_failure': False, 09:32:05 INFO - 'name': 'check_screen_resolution'},), 09:32:05 INFO - 'run_cmd_checks_enabled': True, 09:32:05 INFO - 'suite': 'dromaeojs', 09:32:05 INFO - 'system_bits': '32', 09:32:05 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:32:05 INFO - 'title': 't-yosemite-r7-0220', 09:32:05 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:32:05 INFO - 'use_talos_json': True, 09:32:05 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:32:05 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:32:05 INFO - 'work_dir': 'build'} 09:32:05 INFO - [mozharness: 2017-03-22 16:32:05.643791Z] Running clobber step. 09:32:05 INFO - Running pre-action listener: _resource_record_pre_action 09:32:05 INFO - Running main action method: clobber 09:32:05 INFO - rmtree: /builds/slave/test/build 09:32:05 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:32:06 INFO - Running post-action listener: _resource_record_post_action 09:32:06 INFO - [mozharness: 2017-03-22 16:32:06.340626Z] Finished clobber step (success) 09:32:06 INFO - [mozharness: 2017-03-22 16:32:06.340731Z] Running read-buildbot-config step. 09:32:06 INFO - Running pre-action listener: _resource_record_pre_action 09:32:06 INFO - Running main action method: read_buildbot_config 09:32:06 INFO - Using buildbot properties: 09:32:06 INFO - { 09:32:06 INFO - "project": "", 09:32:06 INFO - "product": "firefox", 09:32:06 INFO - "script_repo_revision": "production", 09:32:06 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 09:32:06 INFO - "repository": "", 09:32:06 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 09:32:06 INFO - "buildid": "20170322075834", 09:32:06 INFO - "pgo_build": "False", 09:32:06 INFO - "basedir": "/builds/slave/test", 09:32:06 INFO - "buildnumber": 31, 09:32:06 INFO - "slavename": "t-yosemite-r7-0220", 09:32:06 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 09:32:06 INFO - "platform": "yosemite_r7", 09:32:06 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 09:32:06 INFO - "branch": "mozilla-central", 09:32:06 INFO - "repo_path": "mozilla-central", 09:32:06 INFO - "revision": "e03e0c60462c775c7558a1dc9d5cf2076c3cd1f9", 09:32:06 INFO - "stage_platform": "macosx64", 09:32:06 INFO - "builduid": "cac01b2237094fc3bb488dc4344e7037", 09:32:06 INFO - "slavebuilddir": "test" 09:32:06 INFO - } 09:32:06 INFO - Found installer url https://queue.taskcluster.net/v1/task/L4ScK0lXS2yNhIhzFHjDGw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg. 09:32:06 INFO - Running post-action listener: _resource_record_post_action 09:32:06 INFO - [mozharness: 2017-03-22 16:32:06.343528Z] Finished read-buildbot-config step (success) 09:32:06 INFO - [mozharness: 2017-03-22 16:32:06.343638Z] Running download-and-extract step. 09:32:06 INFO - Running pre-action listener: _resource_record_pre_action 09:32:06 INFO - Running main action method: download_and_extract 09:32:06 INFO - mkdir: /builds/slave/test/build/tests 09:32:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:32:06 INFO - trying https://queue.taskcluster.net/v1/task/L4ScK0lXS2yNhIhzFHjDGw/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 09:32:06 INFO - Downloading https://queue.taskcluster.net/v1/task/L4ScK0lXS2yNhIhzFHjDGw/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 09:32:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/L4ScK0lXS2yNhIhzFHjDGw/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 09:32:07 INFO - Downloaded 1325 bytes. 09:32:07 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 09:32:07 INFO - Using the following test package requirements: 09:32:07 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:32:07 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 09:32:07 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 09:32:07 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:32:07 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 09:32:07 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:32:07 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 09:32:07 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:32:07 INFO - u'jsshell-mac.zip'], 09:32:07 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:32:07 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 09:32:07 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 09:32:07 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:32:07 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 09:32:07 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:32:07 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 09:32:07 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:32:07 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 09:32:07 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:32:07 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 09:32:07 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 09:32:07 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/L4ScK0lXS2yNhIhzFHjDGw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 09:32:07 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/L4ScK0lXS2yNhIhzFHjDGw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 09:32:07 INFO - Fetch https://queue.taskcluster.net/v1/task/L4ScK0lXS2yNhIhzFHjDGw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 09:32:08 INFO - Expected file size: 52403853 09:32:08 INFO - Obtained file size: 52403853 09:32:25 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 09:32:25 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/L4ScK0lXS2yNhIhzFHjDGw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 09:32:25 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/L4ScK0lXS2yNhIhzFHjDGw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 09:32:25 INFO - Fetch https://queue.taskcluster.net/v1/task/L4ScK0lXS2yNhIhzFHjDGw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 09:32:26 INFO - Expected file size: 52403853 09:32:26 INFO - Obtained file size: 52403853 09:32:42 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/L4ScK0lXS2yNhIhzFHjDGw/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 09:32:42 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/L4ScK0lXS2yNhIhzFHjDGw/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 09:32:42 INFO - Fetch https://queue.taskcluster.net/v1/task/L4ScK0lXS2yNhIhzFHjDGw/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 09:32:44 INFO - Expected file size: 14404171 09:32:44 INFO - Obtained file size: 14404171 09:32: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')]} 09:32:44 INFO - trying https://queue.taskcluster.net/v1/task/L4ScK0lXS2yNhIhzFHjDGw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 09:32:44 INFO - Downloading https://queue.taskcluster.net/v1/task/L4ScK0lXS2yNhIhzFHjDGw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 09:32:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/L4ScK0lXS2yNhIhzFHjDGw/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 09:32:46 INFO - Downloaded 75372888 bytes. 09:32:46 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/L4ScK0lXS2yNhIhzFHjDGw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 09:32:46 INFO - mkdir: /builds/slave/test/properties 09:32:46 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:32:46 INFO - Writing to file /builds/slave/test/properties/build_url 09:32:46 INFO - Contents: 09:32:46 INFO - build_url:https://queue.taskcluster.net/v1/task/L4ScK0lXS2yNhIhzFHjDGw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 09:32:47 INFO - Running post-action listener: _resource_record_post_action 09:32:47 INFO - Running post-action listener: set_extra_try_arguments 09:32:47 INFO - [mozharness: 2017-03-22 16:32:47.421356Z] Finished download-and-extract step (success) 09:32:47 INFO - [mozharness: 2017-03-22 16:32:47.421520Z] Running populate-webroot step. 09:32:47 INFO - Running pre-action listener: _resource_record_pre_action 09:32:47 INFO - Running main action method: populate_webroot 09:32:47 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:32:47 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:32:47 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:32:47 INFO - '--tppagecycles', 09:32:47 INFO - '7'], 09:32:47 INFO - 'tests': ['tsvgm']}}, 09:32:47 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:32:47 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:32:47 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:32:47 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:32:47 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:32:47 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:32:47 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 09:32:47 INFO - 'talos_options': ['--disable-e10s'], 09:32:47 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:32:47 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 09:32:47 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:32:47 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 09:32:47 INFO - 'talos_options': ['--disable-e10s'], 09:32:47 INFO - 'tests': ['damp', 'tps']}, 09:32:47 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 09:32:47 INFO - 'tests': ['damp', 'tps']}, 09:32:47 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:32:47 INFO - 'tests': ['dromaeo_dom']}, 09:32:47 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:32:47 INFO - 'g4': {'talos_options': ['--disable-e10s'], 09:32:47 INFO - 'tests': ['basic_compositor_video']}, 09:32:47 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 09:32:47 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:32:47 INFO - 'tests': ['a11yr', 09:32:47 INFO - 'ts_paint', 09:32:47 INFO - 'tpaint', 09:32:47 INFO - 'sessionrestore', 09:32:47 INFO - 'sessionrestore_no_auto_restore', 09:32:47 INFO - 'tabpaint']}, 09:32:47 INFO - 'other-e10s': {'tests': ['a11yr', 09:32:47 INFO - 'ts_paint', 09:32:47 INFO - 'tpaint', 09:32:47 INFO - 'sessionrestore', 09:32:47 INFO - 'sessionrestore_no_auto_restore', 09:32:47 INFO - 'tabpaint']}, 09:32:47 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:32:47 INFO - 'ts_paint', 09:32:47 INFO - 'tpaint', 09:32:47 INFO - 'sessionrestore', 09:32:47 INFO - 'sessionrestore_no_auto_restore', 09:32:47 INFO - 'tabpaint']}, 09:32:47 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:32:47 INFO - 'ts_paint', 09:32:47 INFO - 'tpaint', 09:32:47 INFO - 'sessionrestore', 09:32:47 INFO - 'sessionrestore_no_auto_restore', 09:32:47 INFO - 'tabpaint']}, 09:32:47 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:32:47 INFO - 'tests': ['a11yr', 09:32:47 INFO - 'ts_paint', 09:32:47 INFO - 'tpaint', 09:32:47 INFO - 'sessionrestore', 09:32:47 INFO - 'sessionrestore_no_auto_restore', 09:32:47 INFO - 'tabpaint']}, 09:32:47 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:32:47 INFO - 'tests': ['a11yr', 09:32:47 INFO - 'ts_paint', 09:32:47 INFO - 'tpaint', 09:32:47 INFO - 'sessionrestore', 09:32:47 INFO - 'sessionrestore_no_auto_restore', 09:32:47 INFO - 'tabpaint']}, 09:32:47 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:32:47 INFO - 'tests': ['tsvgx', 09:32:47 INFO - 'tsvgr_opacity', 09:32:47 INFO - 'tart', 09:32:47 INFO - 'tscrollx', 09:32:47 INFO - 'cart', 09:32:47 INFO - 'tsvg_static']}, 09:32:47 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:32:47 INFO - 'tsvgr_opacity', 09:32:47 INFO - 'tart', 09:32:47 INFO - 'tscrollx', 09:32:47 INFO - 'cart', 09:32:47 INFO - 'tsvg_static']}, 09:32:47 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 09:32:47 INFO - 'talos_options': ['--disable-e10s'], 09:32:47 INFO - 'tests': ['tp5o']}, 09:32:47 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 09:32:47 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 09:32:47 INFO - 'talos_options': ['--disable-e10s', 09:32:47 INFO - '--xperf_path', 09:32:47 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:32:47 INFO - 'tests': ['tp5n']}, 09:32:47 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 09:32:47 INFO - 'talos_options': ['--xperf_path', 09:32:47 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:32:47 INFO - 'tests': ['tp5n']}}, 09:32:47 INFO - 'talos.zip': {'path': '', 09:32:47 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:32:47 INFO - Running post-action listener: _resource_record_post_action 09:32:47 INFO - [mozharness: 2017-03-22 16:32:47.440235Z] Finished populate-webroot step (success) 09:32:47 INFO - [mozharness: 2017-03-22 16:32:47.440370Z] Running create-virtualenv step. 09:32:47 INFO - Running pre-action listener: _resource_record_pre_action 09:32:47 INFO - Running main action method: create_virtualenv 09:32:47 INFO - Creating virtualenv /builds/slave/test/build/venv 09:32:47 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 09:32:47 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:32:47 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:32:47 INFO - Using real prefix '/tools/python27' 09:32:47 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:32:48 INFO - Installing distribute.............................................................................................................................................................................................done. 09:32:51 INFO - Installing pip.................done. 09:32:51 INFO - Return code: 0 09:32:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 09:32:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 09:32:51 INFO - Reading from file tmpfile_stdout 09:32:51 INFO - Output received: 09:32:51 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 09:32:51 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:32:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:32:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:32:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:32:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:32:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:32:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:32:51 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 0x10d4e5b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d4e8768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f882946c420>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d2c3d30>, '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 0x10d1eadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d2b8be8>, '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.IgM4sT17Gd/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.YW1Wrlxywz/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xt/zmqpr3490qxfv07nrs0f850r00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:32:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 09:32:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 09:32:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.IgM4sT17Gd/Render', 09:32:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:32:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:32:51 INFO - 'HOME': '/Users/cltbld', 09:32:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:32:51 INFO - 'LOGNAME': 'cltbld', 09:32:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:32:51 INFO - 'MOZ_NO_REMOTE': '1', 09:32:51 INFO - 'NO_EM_RESTART': '1', 09:32:51 INFO - 'PAGER': '/bin/cat', 09:32:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:32:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:32:51 INFO - 'PWD': '/builds/slave/test', 09:32:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:32:51 INFO - 'SHELL': '/bin/bash', 09:32:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YW1Wrlxywz/Listeners', 09:32:51 INFO - 'TMPDIR': '/var/folders/xt/zmqpr3490qxfv07nrs0f850r00000w/T/', 09:32:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:32:51 INFO - 'USER': 'cltbld', 09:32:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:32:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:32:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:32:51 INFO - 'XPC_FLAGS': '0x0', 09:32:51 INFO - 'XPC_SERVICE_NAME': '0', 09:32:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:32:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:32:51 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 09:32:51 INFO - Cleaning up... 09:32:51 INFO - Return code: 0 09:32:51 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:32:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:32:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:32:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:32:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:32:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:32:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:32:51 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 0x10d4e5b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d4e8768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f882946c420>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d2c3d30>, '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 0x10d1eadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d2b8be8>, '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.IgM4sT17Gd/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.YW1Wrlxywz/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xt/zmqpr3490qxfv07nrs0f850r00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:32:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 09:32:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 09:32:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.IgM4sT17Gd/Render', 09:32:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:32:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:32:51 INFO - 'HOME': '/Users/cltbld', 09:32:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:32:51 INFO - 'LOGNAME': 'cltbld', 09:32:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:32:51 INFO - 'MOZ_NO_REMOTE': '1', 09:32:51 INFO - 'NO_EM_RESTART': '1', 09:32:51 INFO - 'PAGER': '/bin/cat', 09:32:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:32:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:32:51 INFO - 'PWD': '/builds/slave/test', 09:32:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:32:51 INFO - 'SHELL': '/bin/bash', 09:32:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YW1Wrlxywz/Listeners', 09:32:51 INFO - 'TMPDIR': '/var/folders/xt/zmqpr3490qxfv07nrs0f850r00000w/T/', 09:32:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:32:51 INFO - 'USER': 'cltbld', 09:32:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:32:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:32:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:32:51 INFO - 'XPC_FLAGS': '0x0', 09:32:51 INFO - 'XPC_SERVICE_NAME': '0', 09:32:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:32:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:32:51 INFO - Downloading/unpacking psutil>=3.1.1 09:32: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 09:32: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 09:32: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 09:32: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 09:32: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 09:32: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 09:32:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:32:55 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:32:55 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:32:55 INFO - Installing collected packages: psutil 09:32:55 INFO - Running setup.py install for psutil 09:32:55 INFO - building 'psutil._psutil_osx' extension 09:32:55 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 09:32:55 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 09:32:55 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 09:32:55 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 09:32:55 INFO - building 'psutil._psutil_posix' extension 09:32:55 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 09:32:55 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 09:32:55 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 09:32:55 INFO - ^ 09:32:56 INFO - 1 warning generated. 09:32:56 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 09:32:56 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:32:56 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:32:56 INFO - Successfully installed psutil 09:32:56 INFO - Cleaning up... 09:32:56 INFO - Return code: 0 09:32:56 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:32:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:32:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:32:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:32:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:32:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:32:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:32:56 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 0x10d4e5b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d4e8768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f882946c420>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d2c3d30>, '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 0x10d1eadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d2b8be8>, '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.IgM4sT17Gd/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.YW1Wrlxywz/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xt/zmqpr3490qxfv07nrs0f850r00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:32:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 09:32:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 09:32:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.IgM4sT17Gd/Render', 09:32:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:32:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:32:56 INFO - 'HOME': '/Users/cltbld', 09:32:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:32:56 INFO - 'LOGNAME': 'cltbld', 09:32:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:32:56 INFO - 'MOZ_NO_REMOTE': '1', 09:32:56 INFO - 'NO_EM_RESTART': '1', 09:32:56 INFO - 'PAGER': '/bin/cat', 09:32:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:32:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:32:56 INFO - 'PWD': '/builds/slave/test', 09:32:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:32:56 INFO - 'SHELL': '/bin/bash', 09:32:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YW1Wrlxywz/Listeners', 09:32:56 INFO - 'TMPDIR': '/var/folders/xt/zmqpr3490qxfv07nrs0f850r00000w/T/', 09:32:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:32:56 INFO - 'USER': 'cltbld', 09:32:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:32:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:32:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:32:56 INFO - 'XPC_FLAGS': '0x0', 09:32:56 INFO - 'XPC_SERVICE_NAME': '0', 09:32:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:32:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:32:56 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:32:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:32:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:32:56 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:32:56 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:32:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:32:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:32:59 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:32:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:32:59 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:32:59 INFO - Installing collected packages: mozsystemmonitor 09:32:59 INFO - Running setup.py install for mozsystemmonitor 09:32:59 INFO - Successfully installed mozsystemmonitor 09:32:59 INFO - Cleaning up... 09:32:59 INFO - Return code: 0 09:32:59 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:32:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:32:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:33:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:33:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:33:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:33:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:33:00 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 0x10d4e5b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d4e8768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f882946c420>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d2c3d30>, '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 0x10d1eadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d2b8be8>, '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.IgM4sT17Gd/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.YW1Wrlxywz/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xt/zmqpr3490qxfv07nrs0f850r00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:33:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 09:33:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 09:33:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.IgM4sT17Gd/Render', 09:33:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:33:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:33:00 INFO - 'HOME': '/Users/cltbld', 09:33:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:33:00 INFO - 'LOGNAME': 'cltbld', 09:33:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:33:00 INFO - 'MOZ_NO_REMOTE': '1', 09:33:00 INFO - 'NO_EM_RESTART': '1', 09:33:00 INFO - 'PAGER': '/bin/cat', 09:33:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:33:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:33:00 INFO - 'PWD': '/builds/slave/test', 09:33:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:33:00 INFO - 'SHELL': '/bin/bash', 09:33:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YW1Wrlxywz/Listeners', 09:33:00 INFO - 'TMPDIR': '/var/folders/xt/zmqpr3490qxfv07nrs0f850r00000w/T/', 09:33:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:33:00 INFO - 'USER': 'cltbld', 09:33:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:33:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:33:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:33:00 INFO - 'XPC_FLAGS': '0x0', 09:33:00 INFO - 'XPC_SERVICE_NAME': '0', 09:33:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:33:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:33:00 INFO - Downloading/unpacking jsonschema==2.5.1 09:33: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 09:33: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 09:33: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 09:33: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 09:33: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 09:33: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 09:33:03 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:33:03 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:33:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:33:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:33:03 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:33:03 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:33:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:33:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:33:03 INFO - Downloading functools32-3.2.3-2.tar.gz 09:33:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:33:04 INFO - warning: no files found matching '*.txt' 09:33:04 INFO - no previously-included directories found matching 'build' 09:33:04 INFO - no previously-included directories found matching 'dist' 09:33:04 INFO - no previously-included directories found matching '.git*' 09:33:04 INFO - Installing collected packages: jsonschema, functools32 09:33:04 INFO - Running setup.py install for functools32 09:33:04 INFO - warning: no files found matching '*.txt' 09:33:04 INFO - no previously-included directories found matching 'build' 09:33:04 INFO - no previously-included directories found matching 'dist' 09:33:04 INFO - no previously-included directories found matching '.git*' 09:33:04 INFO - Successfully installed jsonschema functools32 09:33:04 INFO - Cleaning up... 09:33:04 INFO - Return code: 0 09:33:04 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 09:33:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:33:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:33:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:33:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:33:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:33:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:33:04 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10d4e5b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d4e8768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f882946c420>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d2c3d30>, '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 0x10d1eadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d2b8be8>, '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.IgM4sT17Gd/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.YW1Wrlxywz/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xt/zmqpr3490qxfv07nrs0f850r00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:33:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 09:33:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 09:33:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.IgM4sT17Gd/Render', 09:33:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:33:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:33:04 INFO - 'HOME': '/Users/cltbld', 09:33:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:33:04 INFO - 'LOGNAME': 'cltbld', 09:33:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:33:04 INFO - 'MOZ_NO_REMOTE': '1', 09:33:04 INFO - 'NO_EM_RESTART': '1', 09:33:04 INFO - 'PAGER': '/bin/cat', 09:33:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:33:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:33:04 INFO - 'PWD': '/builds/slave/test', 09:33:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:33:04 INFO - 'SHELL': '/bin/bash', 09:33:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YW1Wrlxywz/Listeners', 09:33:04 INFO - 'TMPDIR': '/var/folders/xt/zmqpr3490qxfv07nrs0f850r00000w/T/', 09:33:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:33:04 INFO - 'USER': 'cltbld', 09:33:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:33:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:33:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:33:04 INFO - 'XPC_FLAGS': '0x0', 09:33:04 INFO - 'XPC_SERVICE_NAME': '0', 09:33:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:33:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:33:04 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 09:33:04 INFO - Cleaning up... 09:33:04 INFO - Return code: 0 09:33:04 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:33:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:33:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:33:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:33:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:33:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:33:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:33:04 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10d4e5b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d4e8768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f882946c420>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d2c3d30>, '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 0x10d1eadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d2b8be8>, '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.IgM4sT17Gd/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.YW1Wrlxywz/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xt/zmqpr3490qxfv07nrs0f850r00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:33:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 09:33:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 09:33:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.IgM4sT17Gd/Render', 09:33:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:33:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:33:04 INFO - 'HOME': '/Users/cltbld', 09:33:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:33:04 INFO - 'LOGNAME': 'cltbld', 09:33:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:33:04 INFO - 'MOZ_NO_REMOTE': '1', 09:33:04 INFO - 'NO_EM_RESTART': '1', 09:33:04 INFO - 'PAGER': '/bin/cat', 09:33:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:33:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:33:04 INFO - 'PWD': '/builds/slave/test', 09:33:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:33:04 INFO - 'SHELL': '/bin/bash', 09:33:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YW1Wrlxywz/Listeners', 09:33:04 INFO - 'TMPDIR': '/var/folders/xt/zmqpr3490qxfv07nrs0f850r00000w/T/', 09:33:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:33:04 INFO - 'USER': 'cltbld', 09:33:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:33:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:33:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:33:04 INFO - 'XPC_FLAGS': '0x0', 09:33:04 INFO - 'XPC_SERVICE_NAME': '0', 09:33:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:33:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:33:04 INFO - Downloading/unpacking blobuploader==1.2.4 09:33:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:33:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:33:04 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:33:04 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:33:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:33:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:33:07 INFO - Downloading blobuploader-1.2.4.tar.gz 09:33:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:33:07 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:33:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:33:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:33:07 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:33:07 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:33:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:33:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:33:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:33:08 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:33:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:33:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:33:08 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:33:08 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:33:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:33:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:33:08 INFO - Downloading docopt-0.6.1.tar.gz 09:33:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:33:08 INFO - Installing collected packages: blobuploader, requests, docopt 09:33:08 INFO - Running setup.py install for blobuploader 09:33:09 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:33:09 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:33:09 INFO - Running setup.py install for requests 09:33:09 INFO - Running setup.py install for docopt 09:33:09 INFO - Successfully installed blobuploader requests docopt 09:33:09 INFO - Cleaning up... 09:33:09 INFO - Return code: 0 09:33:09 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:33:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:33:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:33:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:33:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:33:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:33:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:33:09 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 0x10d4e5b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d4e8768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f882946c420>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d2c3d30>, '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 0x10d1eadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d2b8be8>, '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.IgM4sT17Gd/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.YW1Wrlxywz/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xt/zmqpr3490qxfv07nrs0f850r00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:33:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 09:33:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:33:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.IgM4sT17Gd/Render', 09:33:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:33:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:33:09 INFO - 'HOME': '/Users/cltbld', 09:33:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:33:09 INFO - 'LOGNAME': 'cltbld', 09:33:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:33:09 INFO - 'MOZ_NO_REMOTE': '1', 09:33:09 INFO - 'NO_EM_RESTART': '1', 09:33:09 INFO - 'PAGER': '/bin/cat', 09:33:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:33:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:33:09 INFO - 'PWD': '/builds/slave/test', 09:33:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:33:09 INFO - 'SHELL': '/bin/bash', 09:33:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YW1Wrlxywz/Listeners', 09:33:09 INFO - 'TMPDIR': '/var/folders/xt/zmqpr3490qxfv07nrs0f850r00000w/T/', 09:33:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:33:09 INFO - 'USER': 'cltbld', 09:33:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:33:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:33:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:33:09 INFO - 'XPC_FLAGS': '0x0', 09:33:09 INFO - 'XPC_SERVICE_NAME': '0', 09:33:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:33:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:33:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:33:10 INFO - Running setup.py (path:/var/folders/xt/zmqpr3490qxfv07nrs0f850r00000w/T/pip-eYkwCX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:33:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:33:10 INFO - Running setup.py (path:/var/folders/xt/zmqpr3490qxfv07nrs0f850r00000w/T/pip-U0RUJg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:33:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:33:10 INFO - Running setup.py (path:/var/folders/xt/zmqpr3490qxfv07nrs0f850r00000w/T/pip-THILzf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:33:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:33:10 INFO - Running setup.py (path:/var/folders/xt/zmqpr3490qxfv07nrs0f850r00000w/T/pip-b4RVp0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:33:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:33:10 INFO - Running setup.py (path:/var/folders/xt/zmqpr3490qxfv07nrs0f850r00000w/T/pip-R4QjTR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:33:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:33:10 INFO - Running setup.py (path:/var/folders/xt/zmqpr3490qxfv07nrs0f850r00000w/T/pip-LrtqOn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:33:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:33:10 INFO - Running setup.py (path:/var/folders/xt/zmqpr3490qxfv07nrs0f850r00000w/T/pip-1psVYQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:33:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:33:10 INFO - Running setup.py (path:/var/folders/xt/zmqpr3490qxfv07nrs0f850r00000w/T/pip-cBELq9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:33:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:33:10 INFO - Running setup.py (path:/var/folders/xt/zmqpr3490qxfv07nrs0f850r00000w/T/pip-5IzwDM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:33:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:33:11 INFO - Running setup.py (path:/var/folders/xt/zmqpr3490qxfv07nrs0f850r00000w/T/pip-O1JLq4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:33:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:33:11 INFO - Running setup.py (path:/var/folders/xt/zmqpr3490qxfv07nrs0f850r00000w/T/pip-kB3dcR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:33:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:33:11 INFO - Running setup.py (path:/var/folders/xt/zmqpr3490qxfv07nrs0f850r00000w/T/pip-3uPgWg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:33:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:33:11 INFO - Running setup.py (path:/var/folders/xt/zmqpr3490qxfv07nrs0f850r00000w/T/pip-quBKrv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:33:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:33:11 INFO - Running setup.py (path:/var/folders/xt/zmqpr3490qxfv07nrs0f850r00000w/T/pip-dRKvbt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:33:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:33:11 INFO - Running setup.py (path:/var/folders/xt/zmqpr3490qxfv07nrs0f850r00000w/T/pip-uSpz4d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:33:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:33:11 INFO - Running setup.py (path:/var/folders/xt/zmqpr3490qxfv07nrs0f850r00000w/T/pip-LxQizS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:33:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:33:11 INFO - Running setup.py (path:/var/folders/xt/zmqpr3490qxfv07nrs0f850r00000w/T/pip-eYJQTQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:33:11 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:33:11 INFO - Running setup.py install for manifestparser 09:33:11 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:33:12 INFO - Running setup.py install for mozcrash 09:33:12 INFO - Running setup.py install for mozdebug 09:33:12 INFO - Running setup.py install for mozdevice 09:33:12 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:33:12 INFO - Running setup.py install for mozfile 09:33:12 INFO - Running setup.py install for mozhttpd 09:33:12 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:33:12 INFO - Running setup.py install for mozinfo 09:33:12 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:33:12 INFO - Running setup.py install for mozInstall 09:33:12 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:33:12 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:33:12 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:33:12 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:33:12 INFO - Running setup.py install for mozleak 09:33:13 INFO - Running setup.py install for mozlog 09:33:13 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:33:13 INFO - Running setup.py install for moznetwork 09:33:13 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:33:13 INFO - Running setup.py install for mozprocess 09:33:13 INFO - Running setup.py install for mozprofile 09:33:13 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:33:13 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:33:13 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:33:13 INFO - Running setup.py install for mozrunner 09:33:13 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:33:13 INFO - Running setup.py install for mozscreenshot 09:33:14 INFO - Running setup.py install for moztest 09:33:14 INFO - Running setup.py install for mozversion 09:33:14 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:33:14 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:33:14 INFO - Cleaning up... 09:33:14 INFO - Return code: 0 09:33:14 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:33:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:33:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:33:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:33:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:33:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:33:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:33:14 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 0x10d4e5b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d4e8768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f882946c420>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d2c3d30>, '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 0x10d1eadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d2b8be8>, '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.IgM4sT17Gd/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.YW1Wrlxywz/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xt/zmqpr3490qxfv07nrs0f850r00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:33:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 09:33:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:33:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.IgM4sT17Gd/Render', 09:33:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:33:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:33:14 INFO - 'HOME': '/Users/cltbld', 09:33:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:33:14 INFO - 'LOGNAME': 'cltbld', 09:33:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:33:14 INFO - 'MOZ_NO_REMOTE': '1', 09:33:14 INFO - 'NO_EM_RESTART': '1', 09:33:14 INFO - 'PAGER': '/bin/cat', 09:33:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:33:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:33:14 INFO - 'PWD': '/builds/slave/test', 09:33:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:33:14 INFO - 'SHELL': '/bin/bash', 09:33:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YW1Wrlxywz/Listeners', 09:33:14 INFO - 'TMPDIR': '/var/folders/xt/zmqpr3490qxfv07nrs0f850r00000w/T/', 09:33:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:33:14 INFO - 'USER': 'cltbld', 09:33:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:33:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:33:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:33:14 INFO - 'XPC_FLAGS': '0x0', 09:33:14 INFO - 'XPC_SERVICE_NAME': '0', 09:33:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:33:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:33:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:33:14 INFO - Running setup.py (path:/var/folders/xt/zmqpr3490qxfv07nrs0f850r00000w/T/pip-DnJbpx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:33:14 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 09:33:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:33:14 INFO - Running setup.py (path:/var/folders/xt/zmqpr3490qxfv07nrs0f850r00000w/T/pip-02O3Yg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:33:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:33:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:33:14 INFO - Running setup.py (path:/var/folders/xt/zmqpr3490qxfv07nrs0f850r00000w/T/pip-6IFU7L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:33:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 09:33:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:33:15 INFO - Running setup.py (path:/var/folders/xt/zmqpr3490qxfv07nrs0f850r00000w/T/pip-HULVVB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:33:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:33:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:33:15 INFO - Running setup.py (path:/var/folders/xt/zmqpr3490qxfv07nrs0f850r00000w/T/pip-wp4dqQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:33:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 09:33:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:33:15 INFO - Running setup.py (path:/var/folders/xt/zmqpr3490qxfv07nrs0f850r00000w/T/pip-JgIn4L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:33:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 09:33:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:33:15 INFO - Running setup.py (path:/var/folders/xt/zmqpr3490qxfv07nrs0f850r00000w/T/pip-ndJgYy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:33:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 09:33:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:33:15 INFO - Running setup.py (path:/var/folders/xt/zmqpr3490qxfv07nrs0f850r00000w/T/pip-MlbNCy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:33:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 09:33:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:33:15 INFO - Running setup.py (path:/var/folders/xt/zmqpr3490qxfv07nrs0f850r00000w/T/pip-fHq2H1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:33:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 09:33:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:33:15 INFO - Running setup.py (path:/var/folders/xt/zmqpr3490qxfv07nrs0f850r00000w/T/pip-2AfNKx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:33:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:33:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:33:15 INFO - Running setup.py (path:/var/folders/xt/zmqpr3490qxfv07nrs0f850r00000w/T/pip-qqFINY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:33:15 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 09:33:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:33:15 INFO - Running setup.py (path:/var/folders/xt/zmqpr3490qxfv07nrs0f850r00000w/T/pip-OxEU4b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:33:15 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)) 09:33:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:33:15 INFO - Running setup.py (path:/var/folders/xt/zmqpr3490qxfv07nrs0f850r00000w/T/pip-v5ryKh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:33:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 09:33:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:33:16 INFO - Running setup.py (path:/var/folders/xt/zmqpr3490qxfv07nrs0f850r00000w/T/pip-Fvavtb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:33:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 09:33:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:33:16 INFO - Running setup.py (path:/var/folders/xt/zmqpr3490qxfv07nrs0f850r00000w/T/pip-58NU_i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:33:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 09:33:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:33:16 INFO - Running setup.py (path:/var/folders/xt/zmqpr3490qxfv07nrs0f850r00000w/T/pip-f3gLSr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:33:16 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 09:33:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:33:16 INFO - Running setup.py (path:/var/folders/xt/zmqpr3490qxfv07nrs0f850r00000w/T/pip-V21R_9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:33:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 09:33:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:33:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:33:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 09:33:16 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:33:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:33:16 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:33:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:33:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:33:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:33:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:33:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:33:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:33:19 INFO - Downloading blessings-1.6.tar.gz 09:33:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:33:19 INFO - Installing collected packages: blessings 09:33:19 INFO - Running setup.py install for blessings 09:33:19 INFO - Successfully installed blessings 09:33:19 INFO - Cleaning up... 09:33:19 INFO - Return code: 0 09:33:19 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:33:19 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:33:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:33:19 INFO - Reading from file tmpfile_stdout 09:33:19 INFO - Current package versions: 09:33:19 INFO - blessings == 1.6 09:33:19 INFO - blobuploader == 1.2.4 09:33:19 INFO - docopt == 0.6.1 09:33:19 INFO - functools32 == 3.2.3-2 09:33:19 INFO - jsonschema == 2.5.1 09:33:19 INFO - manifestparser == 1.1 09:33:19 INFO - mozInstall == 1.12 09:33:19 INFO - mozcrash == 1.0 09:33:19 INFO - mozdebug == 0.1 09:33:19 INFO - mozdevice == 0.49 09:33:19 INFO - mozfile == 1.2 09:33:19 INFO - mozhttpd == 0.7 09:33:19 INFO - mozinfo == 0.9 09:33:19 INFO - mozleak == 0.1 09:33:19 INFO - mozlog == 3.4 09:33:19 INFO - moznetwork == 0.27 09:33:19 INFO - mozprocess == 0.25 09:33:19 INFO - mozprofile == 0.28 09:33:19 INFO - mozrunner == 6.13 09:33:19 INFO - mozscreenshot == 0.1 09:33:19 INFO - mozsystemmonitor == 0.3 09:33:19 INFO - moztest == 0.8 09:33:19 INFO - mozversion == 1.4 09:33:19 INFO - psutil == 3.1.1 09:33:19 INFO - requests == 1.2.3 09:33:19 INFO - wsgiref == 0.1.2 09:33:19 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:33:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:33:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:33:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:33:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:33:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:33:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:33:19 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 0x10d4e5b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d4e8768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f882946c420>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d2c3d30>, '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 0x10d1eadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d2b8be8>, '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.IgM4sT17Gd/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.YW1Wrlxywz/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xt/zmqpr3490qxfv07nrs0f850r00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:33:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 09:33:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:33:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.IgM4sT17Gd/Render', 09:33:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:33:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:33:19 INFO - 'HOME': '/Users/cltbld', 09:33:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:33:19 INFO - 'LOGNAME': 'cltbld', 09:33:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:33:19 INFO - 'MOZ_NO_REMOTE': '1', 09:33:19 INFO - 'NO_EM_RESTART': '1', 09:33:19 INFO - 'PAGER': '/bin/cat', 09:33:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:33:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:33:19 INFO - 'PWD': '/builds/slave/test', 09:33:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:33:19 INFO - 'SHELL': '/bin/bash', 09:33:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YW1Wrlxywz/Listeners', 09:33:19 INFO - 'TMPDIR': '/var/folders/xt/zmqpr3490qxfv07nrs0f850r00000w/T/', 09:33:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:33:19 INFO - 'USER': 'cltbld', 09:33:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:33:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:33:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:33:19 INFO - 'XPC_FLAGS': '0x0', 09:33:19 INFO - 'XPC_SERVICE_NAME': '0', 09:33:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:33:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:33:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 09:33:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 09:33:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 09:33:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 09:33:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 09:33:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 09:33:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 09:33:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 09:33:20 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 09:33:20 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 09:33:20 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 09:33:20 INFO - Cleaning up... 09:33:20 INFO - Return code: 0 09:33:20 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:33:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:33:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:33:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:33:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:33:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:33:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:33:20 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10d4e5b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d4e8768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f882946c420>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d2c3d30>, '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 0x10d1eadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d2b8be8>, '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.IgM4sT17Gd/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.YW1Wrlxywz/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xt/zmqpr3490qxfv07nrs0f850r00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:33:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 09:33:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 09:33:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.IgM4sT17Gd/Render', 09:33:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:33:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:33:20 INFO - 'HOME': '/Users/cltbld', 09:33:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:33:20 INFO - 'LOGNAME': 'cltbld', 09:33:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:33:20 INFO - 'MOZ_NO_REMOTE': '1', 09:33:20 INFO - 'NO_EM_RESTART': '1', 09:33:20 INFO - 'PAGER': '/bin/cat', 09:33:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:33:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:33:20 INFO - 'PWD': '/builds/slave/test', 09:33:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:33:20 INFO - 'SHELL': '/bin/bash', 09:33:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YW1Wrlxywz/Listeners', 09:33:20 INFO - 'TMPDIR': '/var/folders/xt/zmqpr3490qxfv07nrs0f850r00000w/T/', 09:33:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:33:20 INFO - 'USER': 'cltbld', 09:33:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:33:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:33:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:33:20 INFO - 'XPC_FLAGS': '0x0', 09:33:20 INFO - 'XPC_SERVICE_NAME': '0', 09:33:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:33:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:33:20 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:33:20 INFO - Cleaning up... 09:33:20 INFO - Return code: 0 09:33:20 INFO - Running post-action listener: _resource_record_post_action 09:33:20 INFO - Running post-action listener: _start_resource_monitoring 09:33:20 INFO - Starting resource monitoring. 09:33:20 INFO - [mozharness: 2017-03-22 16:33:20.323506Z] Finished create-virtualenv step (success) 09:33:20 INFO - [mozharness: 2017-03-22 16:33:20.324466Z] Running install step. 09:33:20 INFO - Running pre-action listener: _resource_record_pre_action 09:33:20 INFO - Running main action method: install 09:33:20 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:33:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:33:20 INFO - Reading from file tmpfile_stdout 09:33:20 INFO - Detecting whether we're running mozinstall >=1.0... 09:33:20 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:33:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:33:20 INFO - Reading from file tmpfile_stdout 09:33:20 INFO - Output received: 09:33:20 INFO - Usage: mozinstall [options] installer 09:33:20 INFO - Options: 09:33:20 INFO - -h, --help show this help message and exit 09:33:20 INFO - -d DEST, --destination=DEST 09:33:20 INFO - Directory to install application into. [default: 09:33:20 INFO - "/builds/slave/test"] 09:33:20 INFO - --app=APP Application being installed. [default: firefox] 09:33:20 INFO - mkdir: /builds/slave/test/build/application 09:33:20 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'] 09:33:20 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 09:33:40 INFO - Reading from file tmpfile_stdout 09:33:40 INFO - Output received: 09:33:40 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 09:33:40 INFO - Running post-action listener: _resource_record_post_action 09:33:40 INFO - [mozharness: 2017-03-22 16:33:40.957456Z] Finished install step (success) 09:33:40 INFO - [mozharness: 2017-03-22 16:33:40.957579Z] Running run-tests step. 09:33:40 INFO - Running pre-action listener: _resource_record_pre_action 09:33:40 INFO - Running main action method: run_tests 09:33:40 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:33:40 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:33:40 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:33:40 INFO - 2017-03-22 09:33:40.972 screenresolution[29933:276371] starting screenresolution argv=screenresolution get 09:33:40 INFO - 2017-03-22 09:33:40.975 screenresolution[29933:276371] Display 0: 1600x1200x32@60 09:33:40 INFO - 2017-03-22 09:33:40.980 screenresolution[29934:276373] starting screenresolution argv=screenresolution list 09:33:40 INFO - Available Modes on Display 0 09:33:40 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:33:40 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:33:40 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:33:40 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:33:41 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:33:41 INFO - Intel Iris: 09:33:41 INFO - Chipset Model: Intel Iris 09:33:41 INFO - Type: GPU 09:33:41 INFO - Bus: Built-In 09:33:41 INFO - VRAM (Dynamic, Max): 1536 MB 09:33:41 INFO - Vendor: Intel (0x8086) 09:33:41 INFO - Device ID: 0x0a2e 09:33:41 INFO - Revision ID: 0x0009 09:33:41 INFO - Displays: 09:33:41 INFO - Display: 09:33:41 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:33:41 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:33:41 INFO - Display Serial Number: 1600x1200 60 09:33:41 INFO - Main Display: Yes 09:33:41 INFO - Mirror: Off 09:33:41 INFO - Online: Yes 09:33:41 INFO - Rotation: Supported 09:33:41 INFO - Return code: 0 09:33:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:33:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:33:41 INFO - Python 2.7.3 09:33:41 INFO - Return code: 0 09:33:41 INFO - grabbing minidump binary from tooltool 09:33:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:33:41 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 0x10d2c3d30>, '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 0x10d1eadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d2b8be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:33:41 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 09:33:41 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 09:33:41 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 09:33:41 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:33:41 INFO - Return code: 0 09:33:41 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 09:33:41 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:33:41 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:33:41 INFO - ENV: RUST_BACKTRACE is now 1 09:33:41 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 09:33:41 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:33:41 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/L4ScK0lXS2yNhIhzFHjDGw/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0220', '--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 09:33:41 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/L4ScK0lXS2yNhIhzFHjDGw/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0220 --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 09:33:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.IgM4sT17Gd/Render', 09:33:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:33:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:33:41 INFO - 'HOME': '/Users/cltbld', 09:33:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:33:41 INFO - 'LOGNAME': 'cltbld', 09:33:41 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:33:41 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 09:33:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:33:41 INFO - 'MOZ_NO_REMOTE': '1', 09:33:41 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:33:41 INFO - 'NO_EM_RESTART': '1', 09:33:41 INFO - 'PAGER': '/bin/cat', 09:33:41 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:33:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:33:41 INFO - 'PWD': '/builds/slave/test', 09:33:41 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:33:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:33:41 INFO - 'RUST_BACKTRACE': '1', 09:33:41 INFO - 'SHELL': '/bin/bash', 09:33:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YW1Wrlxywz/Listeners', 09:33:41 INFO - 'TMPDIR': '/var/folders/xt/zmqpr3490qxfv07nrs0f850r00000w/T/', 09:33:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:33:41 INFO - 'USER': 'cltbld', 09:33:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:33:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:33:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:33:41 INFO - 'XPC_FLAGS': '0x0', 09:33:41 INFO - 'XPC_SERVICE_NAME': '0', 09:33:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:33:41 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/L4ScK0lXS2yNhIhzFHjDGw/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0220', '--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 09:33:41 INFO - mozversion application_buildid: 20170322075834 09:33:41 INFO - mozversion application_changeset: e03e0c60462c775c7558a1dc9d5cf2076c3cd1f9 09:33:41 INFO - mozversion application_display_name: Nightly 09:33:41 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:33:41 INFO - mozversion application_name: Firefox 09:33:41 INFO - mozversion application_remotingname: firefox 09:33:41 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 09:33:41 INFO - mozversion application_vendor: Mozilla 09:33:41 INFO - mozversion application_version: 55.0a1 09:33:41 INFO - mozversion platform_buildid: 20170322075834 09:33:41 INFO - mozversion platform_changeset: e03e0c60462c775c7558a1dc9d5cf2076c3cd1f9 09:33:41 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 09:33:41 INFO - mozversion platform_version: 55.0a1 09:33:41 INFO - using testdate: 1490200421 09:33:41 INFO - actual date: 1490200421 09:33:41 INFO - starting webserver on 'localhost:49307' 09:33:41 INFO - SUITE-START | Running 2 tests 09:33:41 INFO - TEST-START | dromaeo_css 09:33:41 INFO - operating with platform_type : mac_ 09:33:41 INFO - Initialising browser for dromaeo_css test... 09:33:41 INFO - TEST-INFO | started process 29944 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/xt/zmqpr3490qxfv07nrs0f850r00000w/T/tmpCtyyMN/profile http://localhost:49307/getInfo.html) 09:33:44 INFO - PID 29944 | __metrics Screen width/height:1600/1200 09:33:44 INFO - PID 29944 | colorDepth:24 09:33:44 INFO - PID 29944 | Browser inner width/height: 1024/689 09:33:44 INFO - PID 29944 | __metrics 09:33:44 INFO - PID 29944 | 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" 09:33:44 INFO - PID 29944 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:33:45 INFO - TEST-INFO | 29944: exit 0 09:33:45 INFO - Browser initialized. 09:33:45 INFO - Running cycle 1/1 for dromaeo_css test... 09:33:45 INFO - TEST-INFO | started process 29947 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/xt/zmqpr3490qxfv07nrs0f850r00000w/T/tmpCtyyMN/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:33:57 INFO - PID 29947 | RSS: Main: 233619456 09:33:57 INFO - PID 29947 | 09:37:02 INFO - PID 29947 | Cycle 1(1): loaded http://localhost:49307/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49307/tests/dromaeo/cssquery-ext.html) 09:37:04 INFO - PID 29947 | RSS: Main: 383528960 09:37:04 INFO - PID 29947 | 09:38:47 INFO - PID 29947 | ************************* 09:38:47 INFO - PID 29947 | A coding exception was thrown and uncaught in a Task. 09:38:47 INFO - PID 29947 | 09:38:47 ERROR - PID 29947 | Full message: TypeError: NetworkError when attempting to fetch resource. 09:38:47 INFO - PID 29947 | Full stack: 09:38:47 INFO - PID 29947 | ************************* 09:40:07 INFO - PID 29947 | Cycle 1(1): loaded http://localhost:49307/tests/dromaeo/cssquery-ext.html (next: http://localhost:49307/tests/dromaeo/cssquery-jquery.html) 09:40:07 INFO - PID 29947 | RSS: Main: 371441664 09:40:07 INFO - PID 29947 | 09:43:10 INFO - PID 29947 | Cycle 1(1): loaded http://localhost:49307/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49307/tests/dromaeo/cssquery-mootools.html) 09:43:10 INFO - PID 29947 | RSS: Main: 333991936 09:43:10 INFO - PID 29947 | 09:46:13 INFO - PID 29947 | Cycle 1(1): loaded http://localhost:49307/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49307/tests/dromaeo/cssquery-prototype.html) 09:46:13 INFO - PID 29947 | RSS: Main: 336285696 09:46:13 INFO - PID 29947 | 09:49:17 INFO - PID 29947 | Cycle 1(1): loaded http://localhost:49307/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49307/tests/dromaeo/cssquery-yui.html) 09:49:18 INFO - PID 29947 | RSS: Main: 344125440 09:49:18 INFO - PID 29947 | 09:52:21 INFO - PID 29947 | Cycle 1(1): loaded http://localhost:49307/tests/dromaeo/cssquery-yui.html (next: http://localhost:49307/tests/dromaeo/cssquery-dojo.html) 09:52:22 INFO - PID 29947 | RSS: Main: 507924480 09:52:22 INFO - PID 29947 | 09:52:22 INFO - PID 29947 | __start_tp_report 09:52:22 INFO - PID 29947 | _x_x_mozilla_page_load 09:52:22 INFO - PID 29947 | _x_x_mozilla_page_load_details 09:52:22 INFO - PID 29947 | |i|pagename|runs| 09:52:22 INFO - PID 29947 | |0;dojo.html;3158.905232843014;3460.4809278608204;3479.3389256041364;3513.4729790531424;3526.118470382404;5971.432713892184;6143.907841382372;6169.691515424228;6188.288346840115;6209.068639704041;6095.891769653453;6164.722587483563;6217.533684973634;6227.564070515065;6240.063990401431;5032.093195044768;5407.540359069484;5550.583706222029;5553.8333849984565;5571.303587051619;5716.971415142919;5746.770129194827;5762.308522977228;5773.336066352381;5796.333421656522;5449.5640348772;5510.8071217507595;5537.086380747212;5549.056660367747;5677.602567820254;5546.528545073674;5564.4991950724225;5566.666000040011;5636.774529018834;5664.291963504562;5477.698726570037;5524.944750552483;5534.059209934321;5561.082421400464;5574.247476590649;6058.939410605925;6103.640733310809;6211.596246244025;6228.37716228378;6230.376962303779;6399.8080057598345;6507.154069970921;6565.212174539038;6578.539502234846;6583.473322134218;6628.966855165693;6668.699908504129;6678.465722742169;6699.631520266384;6700.497462690318;6234.750609975641;6247.7188526515965;6319.052142178664;6331.96834015827;6351.237851457855;6533.346665333429;6603.933960660428;6614.603123812587;6752.054712340277;6824.41992430639;6344.365563443711;6365.013422919455;6406.8718625627225;6425.807225783234;6435.195600549931;6534.346565343522;6555.2133743951035;6602.14172157626;6602.7689030883685;6622.966885165543;6522.739090436329;6530.771423000172;6531.3142120077655;6533.542652014408;6535.411812936859;6315.621062736251;6375.171227740457;6460.224773027267;6535.575187612885;6557.082008518812;5314.65454745448;5321.36143662763;5323.1749078892835;5333.386660534011;5338.519533241949;4057.8173982170874;4647.18674232008;4703.294505824154;4704.717716936994;4713.222318317507;6237;6264.091706702503;6288.339724328971;6302.590331628429;6314.778982735582;6392.616443013434;6475.967620161869;6492.707828147744;6572.047053177263;6655.667216639149;6240.594361366588;6258.530610204253;6259.0924315973925;6276.089966954766;6282.968585157045;5838.328592211865;5879.029960056629;5904.3941185568265;5908.14331921869;5936.7328470218945;5596.86007849807;6574.76988305407;6628.668566571556;6658.1344425224415;6836.453083753388;6586.077949087035;6627.1716035495565;6660.467162627075;6668.299828517936;6668.866622667617;6734.831629209309;6802.251752307341;6816.147981502312;6817.897731533932;6822.522423430312;6309.1167236587835;6453.612783233021;6515.250746164252;6531.706073226811;6543.051257567721;6462.289148193601;6517.641529715911;6554.442872355903;6596.373344532261;6605.372489613479;6285.2457705075685;6428.389303016206;6436.517261205522;6439.356064393523;6449.451796597342;6274.498040156868;6359.427651511386;6374.362563743774;6422.486201103807;6436.581622194542;6181.072839074175;6337.302896681454;6340.873182536231;6355.300916899045;6379.904301435575;4887;5025.919427323143;5299;6277.96861015692;6307.653079080511;6252.249730032425;6298.244210694746;6303.338149494237;6309.810705678837;6314.242290925118 09:52:22 INFO - PID 29947 | |1;ext.html;743.9776806695797;788.9921100788993;806.4354951533928;1126.78027784582;1148.1331594646042;26993;27379.45241095177;27436.17691469254;27445;27547.17358479244;36558;37031.814840925756;37062.51749930006;37092;37128.443073353854;8463.915360846406;8624.698135565257;8651.134886511361;8656.394052416334;8661.783455413602;6091.604045737035;8462.15378462155;8577.39958202926;8581.914180858173;8586.356023298247;6270.843228919268;8331.875021874677;8507.191816777427;8509.446885952424;8519;8475.83048339033;8542.658293668246;8544.78638034048;8565.571721413904;8567.528785916773;6071.696415179224;8380.161983801632;8492.872606910902;8509.446885952455;8526.360522960802;26434;35614.821925890465;36073.278534429424;36231.63768362309;36263.63736362629;3004.8647810848347;3014.4121896230245;3022.8337441440713;3031.5755794188613;3058.617672790901;26351.604725929126;26395.868020659775;26529;26968;27317.726822731718;2341.8477798943186;2745.6156138140677;2773.334399744047;2817.2393453767572;2879.2513946373883;294227;294557;294650;294829.0517094844;295471;48224;48889;48917.7770555739;48956;48970.26544601834;40622;50175.24737128946;50203.74898125486;50213.24680129801;50229.74885125551;12631.92628626577;13491.123076999964;14106.576802695936;14271.286435678174;14338.068025578305;11617.53529858813;11633.53465861373;11644;11663.300201987739;11689.590864319707;9915.454649994319;10528.947105289562;10622.415767132881;11186.776264474665;12696.301703406216;4632.62938964882;5055.368078990126;5279.498447647467;5281.630285879952;5283.894322113536;962.5379817687549;971.6599190283345;972.9978122533802;976.2190247801731;999.8650182225307;21456.4635884101;24070.39824004379;24093.638595420733;24104.638430423558;24106.638400424006;1042.8175069362983;1086.6631344283298;1089.4661615808197;1115.576081089181;1129.271619805221;10423;12766.80849787254;14699.412023519153;15071.171085590178;15210.543683689286;341849;342167.2891635526;342457;342984;343184;53616.73191634095;53752.924941500954;53828.73085634625;53858.46141538535;53894.46105538973;1279.5457612547411;1310.1549500572078;1316.9341532923315;1451.9128852268898;1503.9398424062915;23876;24384.878075609508;27709.891604336408;28171.295717607223;28624.57063144013;36732.632673673994;36742;36776.81611592032;36835;37036.814815926824;343979;344520;344545;344996;345710;31869.68130318667;31945.36109277848;32048;32140.67859321377;32256;39935.70833146158;40907;41133.58866411417;41580;41635;23900.415763193905;31444.52833207458;33236.335273294884;33605;34582;34661.826690866386;35086.29827403489;35724.821375892956;35864.8206758975;36438.08904777402;35256.471152932216;35501;35601.82199088988;36064.27871442609;36319.455208172425;29132;29887.206767594012;29907.70092299049;31748;31860.840695796374;19832.801671983674;23715.577065376132;23994.800259986583;24049.63925541083;24130.87934560316 09:52:22 INFO - PID 29947 | |2;jquery.html;386.77007117366765;412.8286760994188;430.40174157920484;430.82336242140707;445.0853496065584;12860.742785144303;21016.789832101673;21287.68068478974;22242.221522246724;22442.887785561048;21273.68089478659;22382.440438989033;22673.319800405974;22915.541689166206;23179.536409271805;4300.591443812833;6079.969600151993;6139.38606139387;6329.68351582419;6331.081993110996;6096.116063170837;6198.566100372976;6201.906971395432;6204.503639708812;6210.441060304572;5392.946070539284;5830.3586605473365;6023.849403764897;6119.3574674659185;6122.724477398506;5997.100434934751;6077.331493535729;6127.693615319261;6129.325774164839;6130.693465326716;5451.536619387357;5561.304836895388;5564.4991950724225;5566.109422492423;5569.582281328917;26636.200913972418;26733.331666708295;27257.86371068132;27914.720852791415;28077.43845123086;1956.745623068992;2007.207153174488;2021.353166986565;2056.1158701758236;2100.7584127825344;15150.393984240618;16240.918795406065;16360.427385041585;16653.16734163299;17134.228959696844;1599.1364663081922;1623.6022174567188;1632.6326576520307;1688.3584237989608;1690.0028982900149;122023;128422;128792.712072879;129301;129667;10407.635732749317;11135.164862635173;11786.882131178578;12184.329861857506;12226.144169908199;14948.626284342978;14980.775288370683;14988.025778324374;14988.850111498963;14988.925055374653;5743.655380677173;7316.670749816271;7613.276738709814;8537.231649151607;8974.28205743539;8420.915790842135;8421.368397370221;8425.36809739272;8640.654373825102;8670.046294907555;6669.933300666931;7251.56490610565;7494.362979146725;7618.390528757686;7678.308952194329;2017.0923084611984;2017.8083082107175;2172.9565408691738;2189.8467107302654;2323.697919270484;905.2305540290832;926.5737760630186;927.8840144981878;930.8543559527668;957.2246480350938;19384.903075484533;20262.290819821235;20315.8984205081;20325.983700815195;20363.898180509004;842.9536375499284;944.1691311645708;950.1686024728363;983.5082458770614;989.302541708094;9924.553395097239;10265.281430299956;10358;10486.37081775096;10562.947185264025;590595;602752;612407;613423;622746;111356;117273;144909.5509044917;145422;153458;1146.5585749486509;1162.4536467860091;1163.8536041998625;1186.727052777866;1240.138104017699;19842.50393740168;20139.79860201379;20465.590688185854;20470.897645511675;21018;35303.64696353004;58333.91662583579;59048.40951590429;59326;59463.70268148631;80233.59883200546;80567;81533.62363328293;81592.41781972165;82941;6301.684915754286;7123;8426.562250279725;8462.576871156542;8802.735917922473;13624.04631675773;13639.249841258827;13962.511312104027;14097.647558811112;14211;11491.54033838637;11592.188546801643;11642.53429862796;11803.23278986863;11827;5759.934412133769;9833.810888252148;11306.547738090385;11441.14191435629;11509.331146983473;10991.670249892515;11675.124365672438;11828.053755699695;11943.462544185706;12024.579139730067;7388.335049845539;8644.049154593116;8682.78293042679;8747;8788.121187881416;11517.53929842797;11722.94138529302;12053.57812476559;12060.939695301468;12101 09:52:22 INFO - PID 29947 | |3;mootools.html;1401.1242973141784;1456.3009755317441;1456.963575910602;1477.3499660149528;1482.2440555316791;10372.688819335423;10753.30103543269;10753.956748217834;10776.73058173546;10782.676519704402;10367.222458315618;10720.08879245265;10758.623448179314;10767.62313319034;10778;2409.204962362416;2461.655368248447;2462.827602067856;2462.9753702463013;2464.778169964703;2415.6228638310636;2464.950700985979;2467.247489515697;2472.765087316711;2480.255923223035;2386.7374588795305;2392.150786470795;2402.195264586357;2403.029515797047;2416.9848663561347;2394.9161779337815;2407.9759202408013;2418.5404773093233;2422.4065104049446;2423.6122220444645;2355.7526459721826;2356.1282325539487;2420.5037967216854;2420.5642984262827;2422.6002709552904;10812.189085818594;10900;10917.945410272978;10932.508037138268;10935;6111.2055432793895;6345.143405640226;6360.14138091361;6362.618242905441;6372.808815735535;4007.4189242559955;4014.0566966762785;4066.430699702044;4073.429719839225;4090.0797320602924;1153.6885041038956;1172.6306213542791;1180.811070228768;1221.291650842518;1231.378154032217;13527.797083043763;13902.16587004773;13915.930420347935;14862.108273503625;15020.324085416074;4977;5157.097507936096;5193.091209038404;5205.609579281569;5214.739263036833;10974.231803773659;10978.725531861768;10989.505472253768;10998.835017474745;10999.890001100046;5147.202183661539;6085.208922840003;6090.725917333731;6093.908591371133;6100.755969761249;5382.730863456812;5746.712664366765;5780.913286300624;5787.334456537552;5811.941880581225;3601.873934412283;3972.33900436129;4133.904515303447;4143.969454967105;4212.410262563244;1262.217425196384;1262.3309645887693;1263.6145975477568;1279.4945996331396;1284.8843604075678;755.6637296403158;756.2022066719663;778.0896351268916;779.4894344204555;790.7351037402534;4774.4509381420885;6230.532710046765;6296.905546416808;6522.282548919615;6678.265390807007;579.8144593729966;580.274656679151;581.4040608376364;595.0122796158356;600.7744201828222;3544.556930383702;3607.2785442911522;4310.956890431118;4526.502084770673;4537.750423726727;13064.477420903057;13879.86120138806;13929.23389213603;13937.58187254384;13961.232132232824;12490.625281241577;13502.594922152352;13729.519466818812;13853.722925541433;13854.584362469142;9948.452835093995;10240.692779216635;10355.223358248011;10369.844452333222;10375.118114960162;6389.137466441971;6745.325467453215;6764.58388513817;6799.150106236721;6825.488088393489;12201.20692155007;12596;12606.163613187326;12608.180468269533;12636.178648388193;9175.587098580714;11847.585334513251;12608.180468269533;13035.934820325838;13088.476460941456;8872.955635221782;8879.511626860327;9080.636774528944;9094.909050909406;9136.406133601295;9785.31502794826;10158.28891977584;10160.847587286344;10172.694819155437;10182.643607473703;8646.654133834827;8797.560121993747;8811.559422028744;8909.1536304051;8928.910710893068;8540.14598540166;8607.440516366416;8631.352648551258;8634.913650863662;8839.602217900081;7645.503042302233;7820.413468989735;7820.843583128191;7825.530468171928;7830.843383132192;4859.854204373874;5053.545180933734;5642.407547207648;5863.970680146572;5936.465718085393;5903.616264942765;6005.519558435228;6008.579399441997;6041.577089603686;6103.542234332532 09:52:22 INFO - PID 29947 | |4;prototype.html;389.5909295239997;391.3288709862585;397.38008706417975;398.68503881933276;402.76639549061537;4780.725203235782;6040.516758659308;6104.816855494342;6116.235470566179;6139.631622102677;4577.816887324503;5502.202180683798;5988.311344195419;6030.788922387717;6050.60671056381;4177.644900183488;4785.976070119645;4790.592799612037;4799.5680388765;4829.323894654743;4806.855794326176;4817.397825271841;4823.155947709154;4823.662343635947;4850.5877000455;4554.766116057603;4724.787384567703;4779.832705855296;4781.474037855833;4812.831550895719;4725.22033864412;4737;4744.501827308152;4770.809167633291;4774.976125119387;4208.600182982643;4224.260754367973;4226;4234;4244.278472659625;4656.41794775653;5855.297364316267;5883.764649414061;5961.761529538857;6007.849803754941;5504.862378440531;5521.67479804848;5526.171074338881;5529.263014807656;5530.5022547970475;6201.689915504252;6554.410103090697;6571;6581.21025476941;6587.341265873422;3908.530976282836;4650.86047418578;4673.696209746389;4694.413198350207;4710.387649605564;5233.801803242647;5877.972099625622;6813.501029773442;6861.416779573742;6871.419573498073;3976.284268831638;4210.494740631144;4359;4379.189849877783;4685.953140468551;4890.58430033444;4894.779734911937;4898.9712160446;4908.263760435963;4939.53074457926;5312.309399778004;5440.537554307849;5483.916111877461;5514.195205308324;5525.668459892419;5803.593748437569;6331.556791024584;6565.967170164118;6573.04690819838;6591.802245932629;1361.9931900340434;1621.0435842852773;1674.5980964568416;1682.2261759590726;1684.8146703862565;1142.31461123327;1145.4043897173424;1147.5983405808067;1157.479134389528;1164.7204670879098;5448.482394172547;6140.631562106288;6154.78458253959;6182.28903676074;6201.565890387629;5607.747651355618;6080.175947215906;6144.354842741448;6281.403266689657;6765.391114799691;5493.9286839066;5744.712764361849;6178.443940045418;6199.690015499207;6273.730066743957;2989.7309242168308;3114.6262448506322;3234.6280177779386;3513.3675938330834;3535.603436642512;5795.739191736382;6009.338972712998;6458.927474415507;6902.5858448492245;6957;6061.727222275008;6131.325554189036;6884.65576721162;6901.171859376806;6929.376356127973;6030.276366836008;6666.9995506066425;6854.34883686059;6873.347032031949;6938.410235130071;6074.507158383859;6261.874762504815;6369.617822930639;6688.331166883273;6756.324367563205;6309.49524038085;6331.556791024768;6581.2102547694585;6617.187695229412;6674.466042716474;6836.965815171091;6955.443564514928;6971.257185703574;6976.5465244758925;6988.623867884943;4035.3543433050572;4705.576498115186;4754.334393184888;4858.63560232991;4906.484819093944;6404.797601199401;6431.324710905287;6741.22475915276;6752.93247067523;6781.762638307636;5677.489025987681;6504.60972341661;6507.577007494498;6528.21661400635;6531.281559028408;5373.516383525502;6324.798288325203;6469.45009674183;6502.349765023459;6505.349465053457;5651.4348565143155;6072.939270607238;6387.808365749035;6419.005054216604;6425.678716064272;5123;5658.773649053992;5693.5445164387575;6358.205224346957;6559.4752419807255;6358.918983772863;6373.490120790419;6381.840453988501;6394.456471199814;6444.452221561032 09:52:22 INFO - PID 29947 | |5;yui.html;715.8961950517179;721.7473884140549;727.6107282603806;731.104397113536;764.5603777827747;14634.926825365872;15566.066036037832;15696.293666785008;17318.307267709275;18378.264869405208;16337.34650613974;16390.26243819028;16732.749008764877;16750;16879.1560421979;934.5374039850284;964.1033838530165;966.7438128895814;970.1026550440849;971.6842026341467;927.9536023198847;946.3470205558143;959.1846930109386;960.7694153403168;961.6922584772875;1013.1185868294594;1013.3413281367096;1013.5388398278766;1014.7767491151972;1021.8927012663675;1005.5123265216364;1006.3559322033905;1006.5520843224768;1007.1187710753092;1016.6441745389128;748.5359077372065;748.5845355827513;750.2085300008489;750.4896670264218;754.7886591754262;15565.766513502307;15886.443974460954;16019.91990040054;16085.276162572596;16356.591085222846;14423.495177668836;14637;14654.560363189015;14800.851991480054;14850;11602.187846850724;11682;11912.702182445508;11945.343006134659;12182.329971851545;3092.9536056959164;3093.47210591378;3135;3156.068959656907;3166.5725127107776;87308.56345718294;101240.49379753128;107341.46329268382;109537;110198;8525;10865.945670271598;11228.270162439416;11279.32324060559;11703.122265830098;24329.63505547418;24759.38101547476;24810.875945620155;24822.627660585105;24842.130525431523;7514.0607424071995;7534.886976695354;7602.201768814305;7657.961710191414;7806.687732490636;3992.680585553149;4013.779242141652;4039.3738970459626;4046.4132700758223;4065.329220678555;4112.876613701594;4169.187008533368;4224.556421575752;4314.158739045856;4575.885602859955;838.8316202616305;842.0863363250819;858.9957050214834;861.1990848510819;884.9955750221336;537.8144540133648;548.9011977844026;559.2059275828334;589.0869152813123;598.1058317814894;9584.424934503952;10066.748331291776;10069.446180459998;10201;10305.227107966932;526.3783976684714;566.9943300566941;584.386394285998;602.6805792929754;611.1596554737237;4622.86131416058;4652.930206046912;4827.758612069382;4831.178699621073;4840.515948405131;97420;98046;102238;103163.96836031694;104626.95373046173;83936;85983.57008214919;88133.55933220421;89331.55334223417;89854;48552.97441375217;49990.25014624783;54470.45529544654;66902.66548667323;70901;10677.9466102669;10687.30532515384;10729.195310351599;10893.782124357627;10935.17986151026;36329.091772704836;36707.81646091752;36925.63074369222;37333.879983600535;37822.81088594539;48903.75548122237;49121.263181051596;49209;49265.75367123285;49816;13175.67060823487;13203.66990825237;13259.470517089456;13672.453101876212;13797.448102075805;13838.792418113937;14191.07757995727;14470.20413877247;14709.779353309495;14913.92543037278;13476.32618369058;13606.965174129353;13635.181889086687;13688.863111368759;13729.382177802223;13451.058425910483;13641.522546710818;13696.794548081985;14150;14156.575302740934;13472.39031581675;13632.454701811816;13641.522546710818;13812.895157714422;13894.374753135931;10941.83587246208;11170.385628790495;11341.659750207507;11655.708607284885;11894.405279736151;9598.396960573562;9605.832718278156;11225.494852731483;11378.943105284752;11390.487428065922 09:52:22 INFO - PID 29947 | __end_tp_report 09:52:22 INFO - PID 29947 | __start_cc_report 09:52:22 INFO - PID 29947 | _x_x_mozilla_cycle_collect,1730 09:52:22 INFO - PID 29947 | __end_cc_report 09:52:22 INFO - PID 29947 | __startTimestamp1490201542060__endTimestamp 09:52:22 INFO - PID 29947 | 09:52:22 INFO - PID 29947 | ------- Summary: start ------- 09:52:22 INFO - PID 29947 | Number of tests: 6 09:52:22 INFO - PID 29947 | 09:52:22 INFO - PID 29947 | [#0] dojo.html Cycles:165 Average:6079.41 Median:6307.65 stddev:696.46 (11.0%) stddev-sans-first:659.84 09:52:22 INFO - PID 29947 | Values: 3158.9 3460.5 3479.3 3513.5 3526.1 5971.4 6143.9 6169.7 6188.3 6209.1 6095.9 6164.7 6217.5 6227.6 6240.1 5032.1 5407.5 5550.6 5553.8 5571.3 5717.0 5746.8 5762.3 5773.3 5796.3 5449.6 5510.8 5537.1 5549.1 5677.6 5546.5 5564.5 5566.7 5636.8 5664.3 5477.7 5524.9 5534.1 5561.1 5574.2 6058.9 6103.6 6211.6 6228.4 6230.4 6399.8 6507.2 6565.2 6578.5 6583.5 6629.0 6668.7 6678.5 6699.6 6700.5 6234.8 6247.7 6319.1 6332.0 6351.2 6533.3 6603.9 6614.6 6752.1 6824.4 6344.4 6365.0 6406.9 6425.8 6435.2 6534.3 6555.2 6602.1 6602.8 6623.0 6522.7 6530.8 6531.3 6533.5 6535.4 6315.6 6375.2 6460.2 6535.6 6557.1 5314.7 5321.4 5323.2 5333.4 5338.5 4057.8 4647.2 4703.3 4704.7 4713.2 6237.0 6264.1 6288.3 6302.6 6314.8 6392.6 6476.0 6492.7 6572.0 6655.7 6240.6 6258.5 6259.1 6276.1 6283.0 5838.3 5879.0 5904.4 5908.1 5936.7 5596.9 6574.8 6628.7 6658.1 6836.5 6586.1 6627.2 6660.5 6668.3 6668.9 6734.8 6802.3 6816.1 6817.9 6822.5 6309.1 6453.6 6515.3 6531.7 6543.1 6462.3 6517.6 6554.4 6596.4 6605.4 6285.2 6428.4 6436.5 6439.4 6449.5 6274.5 6359.4 6374.4 6422.5 6436.6 6181.1 6337.3 6340.9 6355.3 6379.9 4887.0 5025.9 5299.0 6278.0 6307.7 6252.2 6298.2 6303.3 6309.8 6314.2 09:52:22 INFO - PID 29947 | 09:52:22 INFO - PID 29947 | [#1] ext.html Cycles:180 Average:46691.54 Median:343581.50 stddev:86452.77 (25.2%) stddev-sans-first:86626.46 09:52:22 INFO - PID 29947 | Values: 744.0 789.0 806.4 1126.8 1148.1 26993.0 27379.5 27436.2 27445.0 27547.2 36558.0 37031.8 37062.5 37092.0 37128.4 8463.9 8624.7 8651.1 8656.4 8661.8 6091.6 8462.2 8577.4 8581.9 8586.4 6270.8 8331.9 8507.2 8509.4 8519.0 8475.8 8542.7 8544.8 8565.6 8567.5 6071.7 8380.2 8492.9 8509.4 8526.4 26434.0 35614.8 36073.3 36231.6 36263.6 3004.9 3014.4 3022.8 3031.6 3058.6 26351.6 26395.9 26529.0 26968.0 27317.7 2341.8 2745.6 2773.3 2817.2 2879.3 294227.0 294557.0 294650.0 294829.1 295471.0 48224.0 48889.0 48917.8 48956.0 48970.3 40622.0 50175.2 50203.7 50213.2 50229.7 12631.9 13491.1 14106.6 14271.3 14338.1 11617.5 11633.5 11644.0 11663.3 11689.6 9915.5 10528.9 10622.4 11186.8 12696.3 4632.6 5055.4 5279.5 5281.6 5283.9 962.5 971.7 973.0 976.2 999.9 21456.5 24070.4 24093.6 24104.6 24106.6 1042.8 1086.7 1089.5 1115.6 1129.3 10423.0 12766.8 14699.4 15071.2 15210.5 341849.0 342167.3 342457.0 342984.0 343184.0 53616.7 53752.9 53828.7 53858.5 53894.5 1279.5 1310.2 1316.9 1451.9 1503.9 23876.0 24384.9 27709.9 28171.3 28624.6 36732.6 36742.0 36776.8 36835.0 37036.8 343979.0 344520.0 344545.0 344996.0 345710.0 31869.7 31945.4 32048.0 32140.7 32256.0 39935.7 40907.0 41133.6 41580.0 41635.0 23900.4 31444.5 33236.3 33605.0 34582.0 34661.8 35086.3 35724.8 35864.8 36438.1 35256.5 35501.0 35601.8 36064.3 36319.5 29132.0 29887.2 29907.7 31748.0 31860.8 19832.8 23715.6 23994.8 24049.6 24130.9 09:52:22 INFO - PID 29947 | 09:52:22 INFO - PID 29947 | [#2] jquery.html Cycles:180 Average:36315.93 Median:12751.62 stddev:101873.92 (798.9%) stddev-sans-first:102123.98 09:52:22 INFO - PID 29947 | Values: 386.8 412.8 430.4 430.8 445.1 12860.7 21016.8 21287.7 22242.2 22442.9 21273.7 22382.4 22673.3 22915.5 23179.5 4300.6 6080.0 6139.4 6329.7 6331.1 6096.1 6198.6 6201.9 6204.5 6210.4 5392.9 5830.4 6023.8 6119.4 6122.7 5997.1 6077.3 6127.7 6129.3 6130.7 5451.5 5561.3 5564.5 5566.1 5569.6 26636.2 26733.3 27257.9 27914.7 28077.4 1956.7 2007.2 2021.4 2056.1 2100.8 15150.4 16240.9 16360.4 16653.2 17134.2 1599.1 1623.6 1632.6 1688.4 1690.0 122023.0 128422.0 128792.7 129301.0 129667.0 10407.6 11135.2 11786.9 12184.3 12226.1 14948.6 14980.8 14988.0 14988.9 14988.9 5743.7 7316.7 7613.3 8537.2 8974.3 8420.9 8421.4 8425.4 8640.7 8670.0 6669.9 7251.6 7494.4 7618.4 7678.3 2017.1 2017.8 2173.0 2189.8 2323.7 905.2 926.6 927.9 930.9 957.2 19384.9 20262.3 20315.9 20326.0 20363.9 843.0 944.2 950.2 983.5 989.3 9924.6 10265.3 10358.0 10486.4 10562.9 590595.0 602752.0 612407.0 613423.0 622746.0 111356.0 117273.0 144909.6 145422.0 153458.0 1146.6 1162.5 1163.9 1186.7 1240.1 19842.5 20139.8 20465.6 20470.9 21018.0 35303.6 58333.9 59048.4 59326.0 59463.7 80233.6 80567.0 81533.6 81592.4 82941.0 6301.7 7123.0 8426.6 8462.6 8802.7 13624.0 13639.2 13962.5 14097.6 14211.0 11491.5 11592.2 11642.5 11803.2 11827.0 5759.9 9833.8 11306.5 11441.1 11509.3 10991.7 11675.1 11828.1 11943.5 12024.6 7388.3 8644.0 8682.8 8747.0 8788.1 11517.5 11722.9 12053.6 12060.9 12101.0 09:52:22 INFO - PID 29947 | 09:52:22 INFO - PID 29947 | [#3] mootools.html Cycles:180 Average:6687.86 Median:2476.51 stddev:4107.30 (165.9%) stddev-sans-first:4099.61 09:52:22 INFO - PID 29947 | Values: 1401.1 1456.3 1457.0 1477.3 1482.2 10372.7 10753.3 10754.0 10776.7 10782.7 10367.2 10720.1 10758.6 10767.6 10778.0 2409.2 2461.7 2462.8 2463.0 2464.8 2415.6 2465.0 2467.2 2472.8 2480.3 2386.7 2392.2 2402.2 2403.0 2417.0 2394.9 2408.0 2418.5 2422.4 2423.6 2355.8 2356.1 2420.5 2420.6 2422.6 10812.2 10900.0 10917.9 10932.5 10935.0 6111.2 6345.1 6360.1 6362.6 6372.8 4007.4 4014.1 4066.4 4073.4 4090.1 1153.7 1172.6 1180.8 1221.3 1231.4 13527.8 13902.2 13915.9 14862.1 15020.3 4977.0 5157.1 5193.1 5205.6 5214.7 10974.2 10978.7 10989.5 10998.8 10999.9 5147.2 6085.2 6090.7 6093.9 6100.8 5382.7 5746.7 5780.9 5787.3 5811.9 3601.9 3972.3 4133.9 4144.0 4212.4 1262.2 1262.3 1263.6 1279.5 1284.9 755.7 756.2 778.1 779.5 790.7 4774.5 6230.5 6296.9 6522.3 6678.3 579.8 580.3 581.4 595.0 600.8 3544.6 3607.3 4311.0 4526.5 4537.8 13064.5 13879.9 13929.2 13937.6 13961.2 12490.6 13502.6 13729.5 13853.7 13854.6 9948.5 10240.7 10355.2 10369.8 10375.1 6389.1 6745.3 6764.6 6799.2 6825.5 12201.2 12596.0 12606.2 12608.2 12636.2 9175.6 11847.6 12608.2 13035.9 13088.5 8873.0 8879.5 9080.6 9094.9 9136.4 9785.3 10158.3 10160.8 10172.7 10182.6 8646.7 8797.6 8811.6 8909.2 8928.9 8540.1 8607.4 8631.4 8634.9 8839.6 7645.5 7820.4 7820.8 7825.5 7830.8 4859.9 5053.5 5642.4 5864.0 5936.5 5903.6 6005.5 6008.6 6041.6 6103.5 09:52:22 INFO - PID 29947 | 09:52:22 INFO - PID 29947 | [#4] prototype.html Cycles:180 Average:5299.28 Median:5799.67 stddev:1578.45 (27.2%) stddev-sans-first:1539.26 09:52:22 INFO - PID 29947 | Values: 389.6 391.3 397.4 398.7 402.8 4780.7 6040.5 6104.8 6116.2 6139.6 4577.8 5502.2 5988.3 6030.8 6050.6 4177.6 4786.0 4790.6 4799.6 4829.3 4806.9 4817.4 4823.2 4823.7 4850.6 4554.8 4724.8 4779.8 4781.5 4812.8 4725.2 4737.0 4744.5 4770.8 4775.0 4208.6 4224.3 4226.0 4234.0 4244.3 4656.4 5855.3 5883.8 5961.8 6007.8 5504.9 5521.7 5526.2 5529.3 5530.5 6201.7 6554.4 6571.0 6581.2 6587.3 3908.5 4650.9 4673.7 4694.4 4710.4 5233.8 5878.0 6813.5 6861.4 6871.4 3976.3 4210.5 4359.0 4379.2 4686.0 4890.6 4894.8 4899.0 4908.3 4939.5 5312.3 5440.5 5483.9 5514.2 5525.7 5803.6 6331.6 6566.0 6573.0 6591.8 1362.0 1621.0 1674.6 1682.2 1684.8 1142.3 1145.4 1147.6 1157.5 1164.7 5448.5 6140.6 6154.8 6182.3 6201.6 5607.7 6080.2 6144.4 6281.4 6765.4 5493.9 5744.7 6178.4 6199.7 6273.7 2989.7 3114.6 3234.6 3513.4 3535.6 5795.7 6009.3 6458.9 6902.6 6957.0 6061.7 6131.3 6884.7 6901.2 6929.4 6030.3 6667.0 6854.3 6873.3 6938.4 6074.5 6261.9 6369.6 6688.3 6756.3 6309.5 6331.6 6581.2 6617.2 6674.5 6837.0 6955.4 6971.3 6976.5 6988.6 4035.4 4705.6 4754.3 4858.6 4906.5 6404.8 6431.3 6741.2 6752.9 6781.8 5677.5 6504.6 6507.6 6528.2 6531.3 5373.5 6324.8 6469.5 6502.3 6505.3 5651.4 6072.9 6387.8 6419.0 6425.7 5123.0 5658.8 5693.5 6358.2 6559.5 6358.9 6373.5 6381.8 6394.5 6444.5 09:52:22 INFO - PID 29947 | 09:52:22 INFO - PID 29947 | [#5] yui.html Cycles:180 Average:19169.63 Median:24816.75 stddev:26975.58 (108.7%) stddev-sans-first:27015.66 09:52:22 INFO - PID 29947 | Values: 715.9 721.7 727.6 731.1 764.6 14634.9 15566.1 15696.3 17318.3 18378.3 16337.3 16390.3 16732.7 16750.0 16879.2 934.5 964.1 966.7 970.1 971.7 928.0 946.3 959.2 960.8 961.7 1013.1 1013.3 1013.5 1014.8 1021.9 1005.5 1006.4 1006.6 1007.1 1016.6 748.5 748.6 750.2 750.5 754.8 15565.8 15886.4 16019.9 16085.3 16356.6 14423.5 14637.0 14654.6 14800.9 14850.0 11602.2 11682.0 11912.7 11945.3 12182.3 3093.0 3093.5 3135.0 3156.1 3166.6 87308.6 101240.5 107341.5 109537.0 110198.0 8525.0 10865.9 11228.3 11279.3 11703.1 24329.6 24759.4 24810.9 24822.6 24842.1 7514.1 7534.9 7602.2 7658.0 7806.7 3992.7 4013.8 4039.4 4046.4 4065.3 4112.9 4169.2 4224.6 4314.2 4575.9 838.8 842.1 859.0 861.2 885.0 537.8 548.9 559.2 589.1 598.1 9584.4 10066.7 10069.4 10201.0 10305.2 526.4 567.0 584.4 602.7 611.2 4622.9 4652.9 4827.8 4831.2 4840.5 97420.0 98046.0 102238.0 103164.0 104627.0 83936.0 85983.6 88133.6 89331.6 89854.0 48553.0 49990.3 54470.5 66902.7 70901.0 10677.9 10687.3 10729.2 10893.8 10935.2 36329.1 36707.8 36925.6 37333.9 37822.8 48903.8 49121.3 49209.0 49265.8 49816.0 13175.7 13203.7 13259.5 13672.5 13797.4 13838.8 14191.1 14470.2 14709.8 14913.9 13476.3 13607.0 13635.2 13688.9 13729.4 13451.1 13641.5 13696.8 14150.0 14156.6 13472.4 13632.5 13641.5 13812.9 13894.4 10941.8 11170.4 11341.7 11655.7 11894.4 9598.4 9605.8 11225.5 11378.9 11390.5 09:52:22 INFO - PID 29947 | -------- Summary: end -------- 09:52:22 INFO - PID 29947 | 09:52:22 INFO - TEST-INFO | 29947: exit 0 09:52:22 INFO - TEST-OK | dromaeo_css | took 1121259ms 09:52:22 INFO - TEST-START | kraken 09:52:22 INFO - operating with platform_type : mac_ 09:52:22 INFO - Initialising browser for kraken test... 09:52:22 INFO - TEST-INFO | started process 29953 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/xt/zmqpr3490qxfv07nrs0f850r00000w/T/tmpA_tnTl/profile http://localhost:49307/getInfo.html) 09:52:25 INFO - PID 29953 | __metrics Screen width/height:1600/1200 09:52:25 INFO - PID 29953 | colorDepth:24 09:52:25 INFO - PID 29953 | Browser inner width/height: 1024/689 09:52:25 INFO - PID 29953 | __metrics 09:52:25 INFO - PID 29953 | 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" 09:52:25 INFO - PID 29953 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:52:26 INFO - TEST-INFO | 29953: exit 0 09:52:26 INFO - Browser initialized. 09:52:26 INFO - Running cycle 1/1 for kraken test... 09:52:26 INFO - TEST-INFO | started process 29954 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/xt/zmqpr3490qxfv07nrs0f850r00000w/T/tmpA_tnTl/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:52:38 INFO - PID 29954 | RSS: Main: 233992192 09:52:38 INFO - PID 29954 | 09:52:41 INFO - PID 29954 | Cycle 1(1): loaded http://localhost:49307/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49307/tests/kraken/driver.html?testName=audio-beat-detection) 09:52:42 INFO - PID 29954 | RSS: Main: 284938240 09:52:42 INFO - PID 29954 | 09:52:44 INFO - PID 29954 | Cycle 1(1): loaded http://localhost:49307/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49307/tests/kraken/driver.html?testName=audio-dft) 09:52:45 INFO - PID 29954 | RSS: Main: 286265344 09:52:45 INFO - PID 29954 | 09:52:48 INFO - PID 29954 | Cycle 1(1): loaded http://localhost:49307/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49307/tests/kraken/driver.html?testName=audio-fft) 09:52:49 INFO - PID 29954 | RSS: Main: 378236928 09:52:49 INFO - PID 29954 | 09:52:51 INFO - PID 29954 | Cycle 1(1): loaded http://localhost:49307/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49307/tests/kraken/driver.html?testName=audio-oscillator) 09:52:51 INFO - PID 29954 | RSS: Main: 281788416 09:52:51 INFO - PID 29954 | 09:52:53 INFO - PID 29954 | Cycle 1(1): loaded http://localhost:49307/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49307/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:52:54 INFO - PID 29954 | RSS: Main: 283590656 09:52:54 INFO - PID 29954 | 09:52:57 INFO - PID 29954 | Cycle 1(1): loaded http://localhost:49307/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49307/tests/kraken/driver.html?testName=imaging-darkroom) 09:52:58 INFO - PID 29954 | RSS: Main: 326848512 09:52:58 INFO - PID 29954 | 09:53:01 INFO - PID 29954 | Cycle 1(1): loaded http://localhost:49307/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49307/tests/kraken/driver.html?testName=imaging-desaturate) 09:53:01 INFO - PID 29954 | RSS: Main: 332218368 09:53:01 INFO - PID 29954 | 09:53:04 INFO - PID 29954 | Cycle 1(1): loaded http://localhost:49307/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49307/tests/kraken/driver.html?testName=json-parse-financial) 09:53:05 INFO - PID 29954 | RSS: Main: 335679488 09:53:05 INFO - PID 29954 | 09:53:07 INFO - PID 29954 | Cycle 1(1): loaded http://localhost:49307/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49307/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:53:07 INFO - PID 29954 | RSS: Main: 304205824 09:53:07 INFO - PID 29954 | 09:53:09 INFO - PID 29954 | Cycle 1(1): loaded http://localhost:49307/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49307/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:53:10 INFO - PID 29954 | RSS: Main: 306864128 09:53:10 INFO - PID 29954 | 09:53:12 INFO - PID 29954 | Cycle 1(1): loaded http://localhost:49307/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49307/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:53:13 INFO - PID 29954 | RSS: Main: 308629504 09:53:13 INFO - PID 29954 | 09:53:16 INFO - PID 29954 | Cycle 1(1): loaded http://localhost:49307/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49307/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:53:16 INFO - PID 29954 | RSS: Main: 327938048 09:53:16 INFO - PID 29954 | 09:53:20 INFO - PID 29954 | Cycle 1(1): loaded http://localhost:49307/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49307/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:53:20 INFO - PID 29954 | RSS: Main: 321646592 09:53:20 INFO - PID 29954 | 09:53:22 INFO - PID 29954 | Cycle 1(1): loaded http://localhost:49307/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49307/tests/kraken/driver.html?testName=ai-astar) 09:53:22 INFO - PID 29954 | RSS: Main: 383275008 09:53:22 INFO - PID 29954 | 09:53:22 INFO - PID 29954 | __start_tp_report 09:53:22 INFO - PID 29954 | _x_x_mozilla_page_load 09:53:22 INFO - PID 29954 | _x_x_mozilla_page_load_details 09:53:22 INFO - PID 29954 | |i|pagename|runs| 09:53:22 INFO - PID 29954 | |0;ai-astar;113;113;112;111;112;113;112;112;111;112 09:53:22 INFO - PID 29954 | |1;audio-beat-detection;152;124;113;153;110;112;111;112;131;110 09:53:22 INFO - PID 29954 | |2;audio-dft;185;185;187;184;185;185;183;184;185;185 09:53:22 INFO - PID 29954 | |3;audio-fft;82;62;60;93;60;59;59;58;78;60 09:53:22 INFO - PID 29954 | |4;audio-oscillator;91;70;88;69;69;69;84;70;70;85 09:53:22 INFO - PID 29954 | |5;imaging-gaussian-blur;88;88;89;88;90;88;89;88;88;87 09:53:22 INFO - PID 29954 | |6;imaging-darkroom;94;108;151;100;95;95;96;99;95;94 09:53:22 INFO - PID 29954 | |7;imaging-desaturate;83;93;130;83;85;83;86;108;128;128 09:53:22 INFO - PID 29954 | |8;json-parse-financial;62;60;60;64;63;62;61;62;62;62 09:53:22 INFO - PID 29954 | |9;json-stringify-tinderbox;57;62;67;69;61;58;58;59;62;84 09:53:22 INFO - PID 29954 | |10;stanford-crypto-aes;83;83;82;83;82;83;83;83;84;81 09:53:22 INFO - PID 29954 | |11;stanford-crypto-ccm;141;137;137;136;138;138;136;145;183;159 09:53:22 INFO - PID 29954 | |12;stanford-crypto-pbkdf2;225;187;186;183;187;186;186;195;185;180 09:53:22 INFO - PID 29954 | |13;stanford-crypto-sha256-iterative;68;67;68;68;67;68;68;67;68;68 09:53:22 INFO - PID 29954 | __end_tp_report 09:53:22 INFO - PID 29954 | __start_cc_report 09:53:22 INFO - PID 29954 | _x_x_mozilla_cycle_collect,2178 09:53:22 INFO - PID 29954 | __end_cc_report 09:53:22 INFO - PID 29954 | __startTimestamp1490201602690__endTimestamp 09:53:22 INFO - PID 29954 | 09:53:22 INFO - PID 29954 | ------- Summary: start ------- 09:53:22 INFO - PID 29954 | Number of tests: 14 09:53:22 INFO - PID 29954 | 09:53:22 INFO - PID 29954 | [#0] ai-astar Cycles:10 Average:112.10 Median:112.00 stddev:0.74 (0.7%) stddev-sans-first:0.71 09:53:22 INFO - PID 29954 | Values: 113.0 113.0 112.0 111.0 112.0 113.0 112.0 112.0 111.0 112.0 09:53:22 INFO - PID 29954 | 09:53:22 INFO - PID 29954 | [#1] audio-beat-detection Cycles:10 Average:122.80 Median:118.50 stddev:17.09 (14.4%) stddev-sans-first:14.50 09:53:22 INFO - PID 29954 | Values: 152.0 124.0 113.0 153.0 110.0 112.0 111.0 112.0 131.0 110.0 09:53:22 INFO - PID 29954 | 09:53:22 INFO - PID 29954 | [#2] audio-dft Cycles:10 Average:184.80 Median:185.00 stddev:1.03 (0.6%) stddev-sans-first:1.09 09:53:22 INFO - PID 29954 | Values: 185.0 185.0 187.0 184.0 185.0 185.0 183.0 184.0 185.0 185.0 09:53:22 INFO - PID 29954 | 09:53:22 INFO - PID 29954 | [#3] audio-fft Cycles:10 Average:67.10 Median:61.00 stddev:12.49 (20.5%) stddev-sans-first:12.02 09:53:22 INFO - PID 29954 | Values: 82.0 62.0 60.0 93.0 60.0 59.0 59.0 58.0 78.0 60.0 09:53:22 INFO - PID 29954 | 09:53:22 INFO - PID 29954 | [#4] audio-oscillator Cycles:10 Average:76.50 Median:77.00 stddev:9.23 (12.0%) stddev-sans-first:8.16 09:53:22 INFO - PID 29954 | Values: 91.0 70.0 88.0 69.0 69.0 69.0 84.0 70.0 70.0 85.0 09:53:22 INFO - PID 29954 | 09:53:22 INFO - PID 29954 | [#5] imaging-gaussian-blur Cycles:10 Average:88.30 Median:88.00 stddev:0.82 (0.9%) stddev-sans-first:0.87 09:53:22 INFO - PID 29954 | Values: 88.0 88.0 89.0 88.0 90.0 88.0 89.0 88.0 88.0 87.0 09:53:22 INFO - PID 29954 | 09:53:22 INFO - PID 29954 | [#6] imaging-darkroom Cycles:10 Average:102.70 Median:95.00 stddev:17.50 (18.4%) stddev-sans-first:18.28 09:53:22 INFO - PID 29954 | Values: 94.0 108.0 151.0 100.0 95.0 95.0 96.0 99.0 95.0 94.0 09:53:22 INFO - PID 29954 | 09:53:22 INFO - PID 29954 | [#7] imaging-desaturate Cycles:10 Average:100.70 Median:83.00 stddev:20.72 (25.0%) stddev-sans-first:20.96 09:53:22 INFO - PID 29954 | Values: 83.0 93.0 130.0 83.0 85.0 83.0 86.0 108.0 128.0 128.0 09:53:22 INFO - PID 29954 | 09:53:22 INFO - PID 29954 | [#8] json-parse-financial Cycles:10 Average:61.80 Median:62.00 stddev:1.23 (2.0%) stddev-sans-first:1.30 09:53:22 INFO - PID 29954 | Values: 62.0 60.0 60.0 64.0 63.0 62.0 61.0 62.0 62.0 62.0 09:53:22 INFO - PID 29954 | 09:53:22 INFO - PID 29954 | [#9] json-stringify-tinderbox Cycles:10 Average:63.70 Median:62.00 stddev:8.14 (13.1%) stddev-sans-first:8.26 09:53:22 INFO - PID 29954 | Values: 57.0 62.0 67.0 69.0 61.0 58.0 58.0 59.0 62.0 84.0 09:53:22 INFO - PID 29954 | 09:53:22 INFO - PID 29954 | [#10] stanford-crypto-aes Cycles:10 Average:82.70 Median:83.00 stddev:0.82 (1.0%) stddev-sans-first:0.87 09:53:22 INFO - PID 29954 | Values: 83.0 83.0 82.0 83.0 82.0 83.0 83.0 83.0 84.0 81.0 09:53:22 INFO - PID 29954 | 09:53:22 INFO - PID 29954 | [#11] stanford-crypto-ccm Cycles:10 Average:145.00 Median:139.50 stddev:15.07 (10.8%) stddev-sans-first:15.91 09:53:22 INFO - PID 29954 | Values: 141.0 137.0 137.0 136.0 138.0 138.0 136.0 145.0 183.0 159.0 09:53:22 INFO - PID 29954 | 09:53:22 INFO - PID 29954 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:190.00 Median:186.50 stddev:12.87 (6.9%) stddev-sans-first:4.01 09:53:22 INFO - PID 29954 | Values: 225.0 187.0 186.0 183.0 187.0 186.0 186.0 195.0 185.0 180.0 09:53:22 INFO - PID 29954 | 09:53:22 INFO - PID 29954 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.70 Median:68.00 stddev:0.48 (0.7%) stddev-sans-first:0.50 09:53:22 INFO - PID 29954 | Values: 68.0 67.0 68.0 68.0 67.0 68.0 68.0 67.0 68.0 68.0 09:53:22 INFO - PID 29954 | -------- Summary: end -------- 09:53:22 INFO - PID 29954 | 09:53:23 INFO - TEST-INFO | 29954: exit 0 09:53:23 INFO - TEST-OK | kraken | took 60806ms 09:53:23 INFO - SUITE-END | took 1182s 09:53:23 INFO - Completed test suite (00:19:42) 09:53:23 INFO - javascript benchmark 09:53:23 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7572.782595559646, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3158.905232843014, 3460.4809278608204, 3479.3389256041364, 3513.4729790531424, 3526.118470382404, 5971.432713892184, 6143.907841382372, 6169.691515424228, 6188.288346840115, 6209.068639704041, 6095.891769653453, 6164.722587483563, 6217.533684973634, 6227.564070515065, 6240.063990401431, 5032.093195044768, 5407.540359069484, 5550.583706222029, 5553.8333849984565, 5571.303587051619, 5716.971415142919, 5746.770129194827, 5762.308522977228, 5773.336066352381, 5796.333421656522, 5449.5640348772, 5510.8071217507595, 5537.086380747212, 5549.056660367747, 5677.602567820254, 5546.528545073674, 5564.4991950724225, 5566.666000040011, 5636.774529018834, 5664.291963504562, 5477.698726570037, 5524.944750552483, 5534.059209934321, 5561.082421400464, 5574.247476590649, 6058.939410605925, 6103.640733310809, 6211.596246244025, 6228.37716228378, 6230.376962303779, 6399.8080057598345, 6507.154069970921, 6565.212174539038, 6578.539502234846, 6583.473322134218, 6628.966855165693, 6668.699908504129, 6678.465722742169, 6699.631520266384, 6700.497462690318, 6234.750609975641, 6247.7188526515965, 6319.052142178664, 6331.96834015827, 6351.237851457855, 6533.346665333429, 6603.933960660428, 6614.603123812587, 6752.054712340277, 6824.41992430639, 6344.365563443711, 6365.013422919455, 6406.8718625627225, 6425.807225783234, 6435.195600549931, 6534.346565343522, 6555.2133743951035, 6602.14172157626, 6602.7689030883685, 6622.966885165543, 6522.739090436329, 6530.771423000172, 6531.3142120077655, 6533.542652014408, 6535.411812936859, 6315.621062736251, 6375.171227740457, 6460.224773027267, 6535.575187612885, 6557.082008518812, 5314.65454745448, 5321.36143662763, 5323.1749078892835, 5333.386660534011, 5338.519533241949, 4057.8173982170874, 4647.18674232008, 4703.294505824154, 4704.717716936994, 4713.222318317507, 6237.0, 6264.091706702503, 6288.339724328971, 6302.590331628429, 6314.778982735582, 6392.616443013434, 6475.967620161869, 6492.707828147744, 6572.047053177263, 6655.667216639149, 6240.594361366588, 6258.530610204253, 6259.0924315973925, 6276.089966954766, 6282.968585157045, 5838.328592211865, 5879.029960056629, 5904.3941185568265, 5908.14331921869, 5936.7328470218945, 5596.86007849807, 6574.76988305407, 6628.668566571556, 6658.1344425224415, 6836.453083753388, 6586.077949087035, 6627.1716035495565, 6660.467162627075, 6668.299828517936, 6668.866622667617, 6734.831629209309, 6802.251752307341, 6816.147981502312, 6817.897731533932, 6822.522423430312, 6309.1167236587835, 6453.612783233021, 6515.250746164252, 6531.706073226811, 6543.051257567721, 6462.289148193601, 6517.641529715911, 6554.442872355903, 6596.373344532261, 6605.372489613479, 6285.2457705075685, 6428.389303016206, 6436.517261205522, 6439.356064393523, 6449.451796597342, 6274.498040156868, 6359.427651511386, 6374.362563743774, 6422.486201103807, 6436.581622194542, 6181.072839074175, 6337.302896681454, 6340.873182536231, 6355.300916899045, 6379.904301435575, 4887.0, 5025.919427323143, 5299.0, 6277.96861015692, 6307.653079080511, 6252.249730032425, 6298.244210694746, 6303.338149494237, 6309.810705678837, 6314.242290925118], "value": 6033.108101923124, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [743.9776806695797, 788.9921100788993, 806.4354951533928, 1126.78027784582, 1148.1331594646042, 26993.0, 27379.45241095177, 27436.17691469254, 27445.0, 27547.17358479244, 36558.0, 37031.814840925756, 37062.51749930006, 37092.0, 37128.443073353854, 8463.915360846406, 8624.698135565257, 8651.134886511361, 8656.394052416334, 8661.783455413602, 6091.604045737035, 8462.15378462155, 8577.39958202926, 8581.914180858173, 8586.356023298247, 6270.843228919268, 8331.875021874677, 8507.191816777427, 8509.446885952424, 8519.0, 8475.83048339033, 8542.658293668246, 8544.78638034048, 8565.571721413904, 8567.528785916773, 6071.696415179224, 8380.161983801632, 8492.872606910902, 8509.446885952455, 8526.360522960802, 26434.0, 35614.821925890465, 36073.278534429424, 36231.63768362309, 36263.63736362629, 3004.8647810848347, 3014.4121896230245, 3022.8337441440713, 3031.5755794188613, 3058.617672790901, 26351.604725929126, 26395.868020659775, 26529.0, 26968.0, 27317.726822731718, 2341.8477798943186, 2745.6156138140677, 2773.334399744047, 2817.2393453767572, 2879.2513946373883, 294227.0, 294557.0, 294650.0, 294829.0517094844, 295471.0, 48224.0, 48889.0, 48917.7770555739, 48956.0, 48970.26544601834, 40622.0, 50175.24737128946, 50203.74898125486, 50213.24680129801, 50229.74885125551, 12631.92628626577, 13491.123076999964, 14106.576802695936, 14271.286435678174, 14338.068025578305, 11617.53529858813, 11633.53465861373, 11644.0, 11663.300201987739, 11689.590864319707, 9915.454649994319, 10528.947105289562, 10622.415767132881, 11186.776264474665, 12696.301703406216, 4632.62938964882, 5055.368078990126, 5279.498447647467, 5281.630285879952, 5283.894322113536, 962.5379817687549, 971.6599190283345, 972.9978122533802, 976.2190247801731, 999.8650182225307, 21456.4635884101, 24070.39824004379, 24093.638595420733, 24104.638430423558, 24106.638400424006, 1042.8175069362983, 1086.6631344283298, 1089.4661615808197, 1115.576081089181, 1129.271619805221, 10423.0, 12766.80849787254, 14699.412023519153, 15071.171085590178, 15210.543683689286, 341849.0, 342167.2891635526, 342457.0, 342984.0, 343184.0, 53616.73191634095, 53752.924941500954, 53828.73085634625, 53858.46141538535, 53894.46105538973, 1279.5457612547411, 1310.1549500572078, 1316.9341532923315, 1451.9128852268898, 1503.9398424062915, 23876.0, 24384.878075609508, 27709.891604336408, 28171.295717607223, 28624.57063144013, 36732.632673673994, 36742.0, 36776.81611592032, 36835.0, 37036.814815926824, 343979.0, 344520.0, 344545.0, 344996.0, 345710.0, 31869.68130318667, 31945.36109277848, 32048.0, 32140.67859321377, 32256.0, 39935.70833146158, 40907.0, 41133.58866411417, 41580.0, 41635.0, 23900.415763193905, 31444.52833207458, 33236.335273294884, 33605.0, 34582.0, 34661.826690866386, 35086.29827403489, 35724.821375892956, 35864.8206758975, 36438.08904777402, 35256.471152932216, 35501.0, 35601.82199088988, 36064.27871442609, 36319.455208172425, 29132.0, 29887.206767594012, 29907.70092299049, 31748.0, 31860.840695796374, 19832.801671983674, 23715.577065376132, 23994.800259986583, 24049.63925541083, 24130.87934560316], "value": 16956.6519899754, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [386.77007117366765, 412.8286760994188, 430.40174157920484, 430.82336242140707, 445.0853496065584, 12860.742785144303, 21016.789832101673, 21287.68068478974, 22242.221522246724, 22442.887785561048, 21273.68089478659, 22382.440438989033, 22673.319800405974, 22915.541689166206, 23179.536409271805, 4300.591443812833, 6079.969600151993, 6139.38606139387, 6329.68351582419, 6331.081993110996, 6096.116063170837, 6198.566100372976, 6201.906971395432, 6204.503639708812, 6210.441060304572, 5392.946070539284, 5830.3586605473365, 6023.849403764897, 6119.3574674659185, 6122.724477398506, 5997.100434934751, 6077.331493535729, 6127.693615319261, 6129.325774164839, 6130.693465326716, 5451.536619387357, 5561.304836895388, 5564.4991950724225, 5566.109422492423, 5569.582281328917, 26636.200913972418, 26733.331666708295, 27257.86371068132, 27914.720852791415, 28077.43845123086, 1956.745623068992, 2007.207153174488, 2021.353166986565, 2056.1158701758236, 2100.7584127825344, 15150.393984240618, 16240.918795406065, 16360.427385041585, 16653.16734163299, 17134.228959696844, 1599.1364663081922, 1623.6022174567188, 1632.6326576520307, 1688.3584237989608, 1690.0028982900149, 122023.0, 128422.0, 128792.712072879, 129301.0, 129667.0, 10407.635732749317, 11135.164862635173, 11786.882131178578, 12184.329861857506, 12226.144169908199, 14948.626284342978, 14980.775288370683, 14988.025778324374, 14988.850111498963, 14988.925055374653, 5743.655380677173, 7316.670749816271, 7613.276738709814, 8537.231649151607, 8974.28205743539, 8420.915790842135, 8421.368397370221, 8425.36809739272, 8640.654373825102, 8670.046294907555, 6669.933300666931, 7251.56490610565, 7494.362979146725, 7618.390528757686, 7678.308952194329, 2017.0923084611984, 2017.8083082107175, 2172.9565408691738, 2189.8467107302654, 2323.697919270484, 905.2305540290832, 926.5737760630186, 927.8840144981878, 930.8543559527668, 957.2246480350938, 19384.903075484533, 20262.290819821235, 20315.8984205081, 20325.983700815195, 20363.898180509004, 842.9536375499284, 944.1691311645708, 950.1686024728363, 983.5082458770614, 989.302541708094, 9924.553395097239, 10265.281430299956, 10358.0, 10486.37081775096, 10562.947185264025, 590595.0, 602752.0, 612407.0, 613423.0, 622746.0, 111356.0, 117273.0, 144909.5509044917, 145422.0, 153458.0, 1146.5585749486509, 1162.4536467860091, 1163.8536041998625, 1186.727052777866, 1240.138104017699, 19842.50393740168, 20139.79860201379, 20465.590688185854, 20470.897645511675, 21018.0, 35303.64696353004, 58333.91662583579, 59048.40951590429, 59326.0, 59463.70268148631, 80233.59883200546, 80567.0, 81533.62363328293, 81592.41781972165, 82941.0, 6301.684915754286, 7123.0, 8426.562250279725, 8462.576871156542, 8802.735917922473, 13624.04631675773, 13639.249841258827, 13962.511312104027, 14097.647558811112, 14211.0, 11491.54033838637, 11592.188546801643, 11642.53429862796, 11803.23278986863, 11827.0, 5759.934412133769, 9833.810888252148, 11306.547738090385, 11441.14191435629, 11509.331146983473, 10991.670249892515, 11675.124365672438, 11828.053755699695, 11943.462544185706, 12024.579139730067, 7388.335049845539, 8644.049154593116, 8682.78293042679, 8747.0, 8788.121187881416, 11517.53929842797, 11722.94138529302, 12053.57812476559, 12060.939695301468, 12101.0], "value": 10021.976766272805, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1401.1242973141784, 1456.3009755317441, 1456.963575910602, 1477.3499660149528, 1482.2440555316791, 10372.688819335423, 10753.30103543269, 10753.956748217834, 10776.73058173546, 10782.676519704402, 10367.222458315618, 10720.08879245265, 10758.623448179314, 10767.62313319034, 10778.0, 2409.204962362416, 2461.655368248447, 2462.827602067856, 2462.9753702463013, 2464.778169964703, 2415.6228638310636, 2464.950700985979, 2467.247489515697, 2472.765087316711, 2480.255923223035, 2386.7374588795305, 2392.150786470795, 2402.195264586357, 2403.029515797047, 2416.9848663561347, 2394.9161779337815, 2407.9759202408013, 2418.5404773093233, 2422.4065104049446, 2423.6122220444645, 2355.7526459721826, 2356.1282325539487, 2420.5037967216854, 2420.5642984262827, 2422.6002709552904, 10812.189085818594, 10900.0, 10917.945410272978, 10932.508037138268, 10935.0, 6111.2055432793895, 6345.143405640226, 6360.14138091361, 6362.618242905441, 6372.808815735535, 4007.4189242559955, 4014.0566966762785, 4066.430699702044, 4073.429719839225, 4090.0797320602924, 1153.6885041038956, 1172.6306213542791, 1180.811070228768, 1221.291650842518, 1231.378154032217, 13527.797083043763, 13902.16587004773, 13915.930420347935, 14862.108273503625, 15020.324085416074, 4977.0, 5157.097507936096, 5193.091209038404, 5205.609579281569, 5214.739263036833, 10974.231803773659, 10978.725531861768, 10989.505472253768, 10998.835017474745, 10999.890001100046, 5147.202183661539, 6085.208922840003, 6090.725917333731, 6093.908591371133, 6100.755969761249, 5382.730863456812, 5746.712664366765, 5780.913286300624, 5787.334456537552, 5811.941880581225, 3601.873934412283, 3972.33900436129, 4133.904515303447, 4143.969454967105, 4212.410262563244, 1262.217425196384, 1262.3309645887693, 1263.6145975477568, 1279.4945996331396, 1284.8843604075678, 755.6637296403158, 756.2022066719663, 778.0896351268916, 779.4894344204555, 790.7351037402534, 4774.4509381420885, 6230.532710046765, 6296.905546416808, 6522.282548919615, 6678.265390807007, 579.8144593729966, 580.274656679151, 581.4040608376364, 595.0122796158356, 600.7744201828222, 3544.556930383702, 3607.2785442911522, 4310.956890431118, 4526.502084770673, 4537.750423726727, 13064.477420903057, 13879.86120138806, 13929.23389213603, 13937.58187254384, 13961.232132232824, 12490.625281241577, 13502.594922152352, 13729.519466818812, 13853.722925541433, 13854.584362469142, 9948.452835093995, 10240.692779216635, 10355.223358248011, 10369.844452333222, 10375.118114960162, 6389.137466441971, 6745.325467453215, 6764.58388513817, 6799.150106236721, 6825.488088393489, 12201.20692155007, 12596.0, 12606.163613187326, 12608.180468269533, 12636.178648388193, 9175.587098580714, 11847.585334513251, 12608.180468269533, 13035.934820325838, 13088.476460941456, 8872.955635221782, 8879.511626860327, 9080.636774528944, 9094.909050909406, 9136.406133601295, 9785.31502794826, 10158.28891977584, 10160.847587286344, 10172.694819155437, 10182.643607473703, 8646.654133834827, 8797.560121993747, 8811.559422028744, 8909.1536304051, 8928.910710893068, 8540.14598540166, 8607.440516366416, 8631.352648551258, 8634.913650863662, 8839.602217900081, 7645.503042302233, 7820.413468989735, 7820.843583128191, 7825.530468171928, 7830.843383132192, 4859.854204373874, 5053.545180933734, 5642.407547207648, 5863.970680146572, 5936.465718085393, 5903.616264942765, 6005.519558435228, 6008.579399441997, 6041.577089603686, 6103.542234332532], "value": 5074.16202613526, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [389.5909295239997, 391.3288709862585, 397.38008706417975, 398.68503881933276, 402.76639549061537, 4780.725203235782, 6040.516758659308, 6104.816855494342, 6116.235470566179, 6139.631622102677, 4577.816887324503, 5502.202180683798, 5988.311344195419, 6030.788922387717, 6050.60671056381, 4177.644900183488, 4785.976070119645, 4790.592799612037, 4799.5680388765, 4829.323894654743, 4806.855794326176, 4817.397825271841, 4823.155947709154, 4823.662343635947, 4850.5877000455, 4554.766116057603, 4724.787384567703, 4779.832705855296, 4781.474037855833, 4812.831550895719, 4725.22033864412, 4737.0, 4744.501827308152, 4770.809167633291, 4774.976125119387, 4208.600182982643, 4224.260754367973, 4226.0, 4234.0, 4244.278472659625, 4656.41794775653, 5855.297364316267, 5883.764649414061, 5961.761529538857, 6007.849803754941, 5504.862378440531, 5521.67479804848, 5526.171074338881, 5529.263014807656, 5530.5022547970475, 6201.689915504252, 6554.410103090697, 6571.0, 6581.21025476941, 6587.341265873422, 3908.530976282836, 4650.86047418578, 4673.696209746389, 4694.413198350207, 4710.387649605564, 5233.801803242647, 5877.972099625622, 6813.501029773442, 6861.416779573742, 6871.419573498073, 3976.284268831638, 4210.494740631144, 4359.0, 4379.189849877783, 4685.953140468551, 4890.58430033444, 4894.779734911937, 4898.9712160446, 4908.263760435963, 4939.53074457926, 5312.309399778004, 5440.537554307849, 5483.916111877461, 5514.195205308324, 5525.668459892419, 5803.593748437569, 6331.556791024584, 6565.967170164118, 6573.04690819838, 6591.802245932629, 1361.9931900340434, 1621.0435842852773, 1674.5980964568416, 1682.2261759590726, 1684.8146703862565, 1142.31461123327, 1145.4043897173424, 1147.5983405808067, 1157.479134389528, 1164.7204670879098, 5448.482394172547, 6140.631562106288, 6154.78458253959, 6182.28903676074, 6201.565890387629, 5607.747651355618, 6080.175947215906, 6144.354842741448, 6281.403266689657, 6765.391114799691, 5493.9286839066, 5744.712764361849, 6178.443940045418, 6199.690015499207, 6273.730066743957, 2989.7309242168308, 3114.6262448506322, 3234.6280177779386, 3513.3675938330834, 3535.603436642512, 5795.739191736382, 6009.338972712998, 6458.927474415507, 6902.5858448492245, 6957.0, 6061.727222275008, 6131.325554189036, 6884.65576721162, 6901.171859376806, 6929.376356127973, 6030.276366836008, 6666.9995506066425, 6854.34883686059, 6873.347032031949, 6938.410235130071, 6074.507158383859, 6261.874762504815, 6369.617822930639, 6688.331166883273, 6756.324367563205, 6309.49524038085, 6331.556791024768, 6581.2102547694585, 6617.187695229412, 6674.466042716474, 6836.965815171091, 6955.443564514928, 6971.257185703574, 6976.5465244758925, 6988.623867884943, 4035.3543433050572, 4705.576498115186, 4754.334393184888, 4858.63560232991, 4906.484819093944, 6404.797601199401, 6431.324710905287, 6741.22475915276, 6752.93247067523, 6781.762638307636, 5677.489025987681, 6504.60972341661, 6507.577007494498, 6528.21661400635, 6531.281559028408, 5373.516383525502, 6324.798288325203, 6469.45009674183, 6502.349765023459, 6505.349465053457, 5651.4348565143155, 6072.939270607238, 6387.808365749035, 6419.005054216604, 6425.678716064272, 5123.0, 5658.773649053992, 5693.5445164387575, 6358.205224346957, 6559.4752419807255, 6358.918983772863, 6373.490120790419, 6381.840453988501, 6394.456471199814, 6444.452221561032], "value": 4815.860371687762, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [715.8961950517179, 721.7473884140549, 727.6107282603806, 731.104397113536, 764.5603777827747, 14634.926825365872, 15566.066036037832, 15696.293666785008, 17318.307267709275, 18378.264869405208, 16337.34650613974, 16390.26243819028, 16732.749008764877, 16750.0, 16879.1560421979, 934.5374039850284, 964.1033838530165, 966.7438128895814, 970.1026550440849, 971.6842026341467, 927.9536023198847, 946.3470205558143, 959.1846930109386, 960.7694153403168, 961.6922584772875, 1013.1185868294594, 1013.3413281367096, 1013.5388398278766, 1014.7767491151972, 1021.8927012663675, 1005.5123265216364, 1006.3559322033905, 1006.5520843224768, 1007.1187710753092, 1016.6441745389128, 748.5359077372065, 748.5845355827513, 750.2085300008489, 750.4896670264218, 754.7886591754262, 15565.766513502307, 15886.443974460954, 16019.91990040054, 16085.276162572596, 16356.591085222846, 14423.495177668836, 14637.0, 14654.560363189015, 14800.851991480054, 14850.0, 11602.187846850724, 11682.0, 11912.702182445508, 11945.343006134659, 12182.329971851545, 3092.9536056959164, 3093.47210591378, 3135.0, 3156.068959656907, 3166.5725127107776, 87308.56345718294, 101240.49379753128, 107341.46329268382, 109537.0, 110198.0, 8525.0, 10865.945670271598, 11228.270162439416, 11279.32324060559, 11703.122265830098, 24329.63505547418, 24759.38101547476, 24810.875945620155, 24822.627660585105, 24842.130525431523, 7514.0607424071995, 7534.886976695354, 7602.201768814305, 7657.961710191414, 7806.687732490636, 3992.680585553149, 4013.779242141652, 4039.3738970459626, 4046.4132700758223, 4065.329220678555, 4112.876613701594, 4169.187008533368, 4224.556421575752, 4314.158739045856, 4575.885602859955, 838.8316202616305, 842.0863363250819, 858.9957050214834, 861.1990848510819, 884.9955750221336, 537.8144540133648, 548.9011977844026, 559.2059275828334, 589.0869152813123, 598.1058317814894, 9584.424934503952, 10066.748331291776, 10069.446180459998, 10201.0, 10305.227107966932, 526.3783976684714, 566.9943300566941, 584.386394285998, 602.6805792929754, 611.1596554737237, 4622.86131416058, 4652.930206046912, 4827.758612069382, 4831.178699621073, 4840.515948405131, 97420.0, 98046.0, 102238.0, 103163.96836031694, 104626.95373046173, 83936.0, 85983.57008214919, 88133.55933220421, 89331.55334223417, 89854.0, 48552.97441375217, 49990.25014624783, 54470.45529544654, 66902.66548667323, 70901.0, 10677.9466102669, 10687.30532515384, 10729.195310351599, 10893.782124357627, 10935.17986151026, 36329.091772704836, 36707.81646091752, 36925.63074369222, 37333.879983600535, 37822.81088594539, 48903.75548122237, 49121.263181051596, 49209.0, 49265.75367123285, 49816.0, 13175.67060823487, 13203.66990825237, 13259.470517089456, 13672.453101876212, 13797.448102075805, 13838.792418113937, 14191.07757995727, 14470.20413877247, 14709.779353309495, 14913.92543037278, 13476.32618369058, 13606.965174129353, 13635.181889086687, 13688.863111368759, 13729.382177802223, 13451.058425910483, 13641.522546710818, 13696.794548081985, 14150.0, 14156.575302740934, 13472.39031581675, 13632.454701811816, 13641.522546710818, 13812.895157714422, 13894.374753135931, 10941.83587246208, 11170.385628790495, 11341.659750207507, 11655.708607284885, 11894.405279736151, 9598.396960573562, 9605.832718278156, 11225.494852731483, 11378.943105284752, 11390.487428065922], "value": 7527.1369326219055, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1465.9, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 113.0, 112.0, 111.0, 112.0, 113.0, 112.0, 112.0, 111.0, 112.0], "value": 112.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 124.0, 113.0, 153.0, 110.0, 112.0, 111.0, 112.0, 131.0, 110.0], "value": 122.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 185.0, 187.0, 184.0, 185.0, 185.0, 183.0, 184.0, 185.0, 185.0], "value": 184.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 62.0, 60.0, 93.0, 60.0, 59.0, 59.0, 58.0, 78.0, 60.0], "value": 67.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 70.0, 88.0, 69.0, 69.0, 69.0, 84.0, 70.0, 70.0, 85.0], "value": 76.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 88.0, 89.0, 88.0, 90.0, 88.0, 89.0, 88.0, 88.0, 87.0], "value": 88.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 108.0, 151.0, 100.0, 95.0, 95.0, 96.0, 99.0, 95.0, 94.0], "value": 102.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 93.0, 130.0, 83.0, 85.0, 83.0, 86.0, 108.0, 128.0, 128.0], "value": 100.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 60.0, 60.0, 64.0, 63.0, 62.0, 61.0, 62.0, 62.0, 62.0], "value": 61.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 62.0, 67.0, 69.0, 61.0, 58.0, 58.0, 59.0, 62.0, 84.0], "value": 63.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 83.0, 82.0, 83.0, 82.0, 83.0, 83.0, 83.0, 84.0, 81.0], "value": 82.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 137.0, 137.0, 136.0, 138.0, 138.0, 136.0, 145.0, 183.0, 159.0], "value": 145.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [225.0, 187.0, 186.0, 183.0, 187.0, 186.0, 186.0, 195.0, 185.0, 180.0], "value": 190.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 67.0, 68.0, 68.0, 67.0, 68.0, 68.0, 67.0, 68.0, 68.0], "value": 67.7, "unit": "score"}]}]} 09:53:23 INFO - Return code: 0 09:53:23 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:53:23 ERROR - # TBPL SUCCESS # 09:53:24 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:53:24 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:53:24 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:53:24 INFO - 2017-03-22 09:53:24.014 screenresolution[29958:278460] starting screenresolution argv=screenresolution get 09:53:24 INFO - 2017-03-22 09:53:24.017 screenresolution[29958:278460] Display 0: 1600x1200x32@60 09:53:24 INFO - 2017-03-22 09:53:24.023 screenresolution[29959:278463] starting screenresolution argv=screenresolution list 09:53:24 INFO - Available Modes on Display 0 09:53:24 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:53:24 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:53:24 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:53:24 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:53:24 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:53:24 INFO - Intel Iris: 09:53:24 INFO - Chipset Model: Intel Iris 09:53:24 INFO - Type: GPU 09:53:24 INFO - Bus: Built-In 09:53:24 INFO - VRAM (Dynamic, Max): 1536 MB 09:53:24 INFO - Vendor: Intel (0x8086) 09:53:24 INFO - Device ID: 0x0a2e 09:53:24 INFO - Revision ID: 0x0009 09:53:24 INFO - Displays: 09:53:24 INFO - Display: 09:53:24 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:53:24 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:53:24 INFO - Display Serial Number: 1600x1200 60 09:53:24 INFO - Main Display: Yes 09:53:24 INFO - Mirror: Off 09:53:24 INFO - Online: Yes 09:53:24 INFO - Rotation: Supported 09:53:24 INFO - Return code: 0 09:53:24 INFO - Running post-action listener: _resource_record_post_action 09:53:24 INFO - [mozharness: 2017-03-22 16:53:24.259821Z] Finished run-tests step (success) 09:53:24 INFO - Running post-run listener: _resource_record_post_run 09:53:24 INFO - Total resource usage - Wall time: 1203s; CPU: 53.0%; Read bytes: 30448640; Write bytes: 515003904; Read time: 180; Write time: 1570 09:53:24 INFO - TinderboxPrint: CPU usage
53.3% 09:53:24 INFO - TinderboxPrint: I/O read bytes / time
30,448,640 / 180 09:53:24 INFO - TinderboxPrint: I/O write bytes / time
515,003,904 / 1,570 09:53:24 INFO - TinderboxPrint: CPU idle
2,245.9 (46.7%) 09:53:24 INFO - TinderboxPrint: CPU system
959.5 (19.9%) 09:53:24 INFO - TinderboxPrint: CPU user
1,608.3 (33.4%) 09:53:24 INFO - TinderboxPrint: Swap in / out
235,257,856 / 0 09:53:24 INFO - install - Wall time: 21s; CPU: 54.0%; Read bytes: 203966976; Write bytes: 205576192; Read time: 15817; Write time: 443 09:53:24 INFO - run-tests - Wall time: 1183s; CPU: 53.0%; Read bytes: 30448640; Write bytes: 298614272; Read time: 180; Write time: 1090 09:53:24 INFO - Running post-run listener: _upload_blobber_files 09:53:24 INFO - Blob upload gear active. 09:53:24 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:53:24 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 09:53:24 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'] 09:53:24 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 09:53:25 INFO - (blobuploader) - INFO - Open directory for files ... 09:53:25 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 09:53:25 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:53:25 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:53:26 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 09:53:26 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:53:26 INFO - (blobuploader) - INFO - Done attempting. 09:53:26 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 09:53:26 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:53:26 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:53:27 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 09:53:27 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:53:27 INFO - (blobuploader) - INFO - Done attempting. 09:53:27 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 09:53:27 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:53:27 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:53:28 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 09:53:28 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:53:28 INFO - (blobuploader) - INFO - Done attempting. 09:53:28 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:53:28 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:53:28 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:53:30 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:53:30 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:53:30 INFO - (blobuploader) - INFO - Done attempting. 09:53:30 INFO - (blobuploader) - INFO - Iteration through files over. 09:53:30 INFO - Return code: 0 09:53:30 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:53:30 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:53:30 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7c6e5e69bfa452d986517046c1d936e8eff118c7e36ee3195d0331060e1ea237dea5cd7163ccf720285b19b34f0470ec9907989d00d5aa16e77a71b6e75d6e0f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3f714d893c81a6291e8e373d44b1c2b5afa4b2096018e3e3001c1b501eb18e5939ef237f533c93430eeb31146ac88fdcdb8d0ead37d008540ef367f40960bb19", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6a6d5eb125f6558a461b52dcf21859db85a02a7208ed5dbff3837f682a50ebd242f285ac7f612ed8667307304f8e05bbb96fcdf0369b931097ff667c75fa95e5", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/97143efe91560e1b1160a8fb77c37be29a46f3a0599cddf681b9595aa5ef32e260a067f422e6bbc18a4ebd12b7bd38b6194709e2a35228f837e7dc6849e2b4d7"} 09:53:30 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:53:30 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:53:30 INFO - Contents: 09:53:30 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7c6e5e69bfa452d986517046c1d936e8eff118c7e36ee3195d0331060e1ea237dea5cd7163ccf720285b19b34f0470ec9907989d00d5aa16e77a71b6e75d6e0f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3f714d893c81a6291e8e373d44b1c2b5afa4b2096018e3e3001c1b501eb18e5939ef237f533c93430eeb31146ac88fdcdb8d0ead37d008540ef367f40960bb19", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6a6d5eb125f6558a461b52dcf21859db85a02a7208ed5dbff3837f682a50ebd242f285ac7f612ed8667307304f8e05bbb96fcdf0369b931097ff667c75fa95e5", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/97143efe91560e1b1160a8fb77c37be29a46f3a0599cddf681b9595aa5ef32e260a067f422e6bbc18a4ebd12b7bd38b6194709e2a35228f837e7dc6849e2b4d7"} 09:53:30 INFO - Running post-run listener: copy_logs_to_upload_dir 09:53:30 INFO - Copying logs to upload dir... 09:53:30 INFO - mkdir: /builds/slave/test/build/upload/logs 09:53:30 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1284.730026 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 24 secs) (at 2017-03-22 09:53:30.225641) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 1 secs) (at 2017-03-22 09:53:30.230243) ========= 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.IgM4sT17Gd/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.YW1Wrlxywz/Listeners TMPDIR=/var/folders/xt/zmqpr3490qxfv07nrs0f850r00000w/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/7c6e5e69bfa452d986517046c1d936e8eff118c7e36ee3195d0331060e1ea237dea5cd7163ccf720285b19b34f0470ec9907989d00d5aa16e77a71b6e75d6e0f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3f714d893c81a6291e8e373d44b1c2b5afa4b2096018e3e3001c1b501eb18e5939ef237f533c93430eeb31146ac88fdcdb8d0ead37d008540ef367f40960bb19", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6a6d5eb125f6558a461b52dcf21859db85a02a7208ed5dbff3837f682a50ebd242f285ac7f612ed8667307304f8e05bbb96fcdf0369b931097ff667c75fa95e5", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/97143efe91560e1b1160a8fb77c37be29a46f3a0599cddf681b9595aa5ef32e260a067f422e6bbc18a4ebd12b7bd38b6194709e2a35228f837e7dc6849e2b4d7"} build_url:https://queue.taskcluster.net/v1/task/L4ScK0lXS2yNhIhzFHjDGw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010636 build_url: 'https://queue.taskcluster.net/v1/task/L4ScK0lXS2yNhIhzFHjDGw/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/7c6e5e69bfa452d986517046c1d936e8eff118c7e36ee3195d0331060e1ea237dea5cd7163ccf720285b19b34f0470ec9907989d00d5aa16e77a71b6e75d6e0f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3f714d893c81a6291e8e373d44b1c2b5afa4b2096018e3e3001c1b501eb18e5939ef237f533c93430eeb31146ac88fdcdb8d0ead37d008540ef367f40960bb19", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6a6d5eb125f6558a461b52dcf21859db85a02a7208ed5dbff3837f682a50ebd242f285ac7f612ed8667307304f8e05bbb96fcdf0369b931097ff667c75fa95e5", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/97143efe91560e1b1160a8fb77c37be29a46f3a0599cddf681b9595aa5ef32e260a067f422e6bbc18a4ebd12b7bd38b6194709e2a35228f837e7dc6849e2b4d7"}' ========= master_lag: 1.92 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 1 secs) (at 2017-03-22 09:53:32.163823) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-22 09:53:32.164169) ========= 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.IgM4sT17Gd/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.YW1Wrlxywz/Listeners TMPDIR=/var/folders/xt/zmqpr3490qxfv07nrs0f850r00000w/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.005438 ========= master_lag: 0.03 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-22 09:53:32.201137) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-22 09:53:32.201461) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-22 09:53:32.201775) ========= ========= Total master_lag: 2.08 =========