builder: mozilla-aurora_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0259 starttime: 1488647015.53 results: success (0) buildid: 20170304080023 builduid: aa8c5190747341328a0d4aa07cef1678 revision: 14cf0c8a70e142e1416aa091f11b94619de1dcba ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-03-04 09:03:35.531808) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-03-04 09:03:35.532242) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-04 09:03:35.532518) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-04 09:03:35.561392) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-04 09:03:35.561776) ========= 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.R8phzQO2wB/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.5W5zUYVmgE/Listeners TMPDIR=/var/folders/sw/s26whz2n6pbfv14p7p8hkdxw00000w/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-04 09:03:35-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 23.6M=0s 2017-03-04 09:03:36 (23.6 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.567017 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-04 09:03:36.154566) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-04 09:03:36.155040) ========= 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.R8phzQO2wB/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.5W5zUYVmgE/Listeners TMPDIR=/var/folders/sw/s26whz2n6pbfv14p7p8hkdxw00000w/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.041206 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-04 09:03:36.220433) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-03-04 09:03:36.221040) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 14cf0c8a70e142e1416aa091f11b94619de1dcba --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 14cf0c8a70e142e1416aa091f11b94619de1dcba --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.R8phzQO2wB/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.5W5zUYVmgE/Listeners TMPDIR=/var/folders/sw/s26whz2n6pbfv14p7p8hkdxw00000w/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-04 09:03:36,355 truncating revision to first 12 chars 2017-03-04 09:03:36,355 Setting DEBUG logging. 2017-03-04 09:03:36,355 attempt 1/10 2017-03-04 09:03:36,355 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/14cf0c8a70e1?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-03-04 09:03:37,362 unpacking tar archive at: mozilla-aurora-14cf0c8a70e1/testing/mozharness/ program finished with exit code 0 elapsedTime=1.283581 ========= master_lag: 0.05 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-03-04 09:03:37.555178) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-04 09:03:37.555499) ========= script_repo_revision: 14cf0c8a70e142e1416aa091f11b94619de1dcba ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-04 09:03:37.555897) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-04 09:03:37.556179) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-04 09:03:37.576535) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 1 secs) (at 2017-03-04 09:03:37.576932) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.R8phzQO2wB/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.5W5zUYVmgE/Listeners TMPDIR=/var/folders/sw/s26whz2n6pbfv14p7p8hkdxw00000w/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:03:37 INFO - MultiFileLogger online at 20170304 09:03:37 in /builds/slave/test 09:03:37 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 09:03:37 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:03:37 INFO - {'append_to_log': False, 09:03:37 INFO - 'base_work_dir': '/builds/slave/test', 09:03:37 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 09:03:37 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:03:37 INFO - 'branch': 'Mozilla-Aurora', 09:03:37 INFO - 'buildbot_json_path': 'buildprops.json', 09:03:37 INFO - 'config_files': ('talos/mac_config.py',), 09:03:37 INFO - 'default_actions': ('clobber', 09:03:37 INFO - 'read-buildbot-config', 09:03:37 INFO - 'download-and-extract', 09:03:37 INFO - 'populate-webroot', 09:03:37 INFO - 'create-virtualenv', 09:03:37 INFO - 'install', 09:03:37 INFO - 'run-tests'), 09:03:37 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:03:37 INFO - 'download_minidump_stackwalk': True, 09:03:37 INFO - 'download_symbols': 'ondemand', 09:03:37 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:03:37 INFO - 'tooltool.py': '/tools/tooltool.py', 09:03:37 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:03:37 INFO - '/tools/misc-python/virtualenv.py')}, 09:03:37 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:03:37 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:03:37 INFO - 'installer_path': 'installer.exe', 09:03:37 INFO - 'log_level': 'info', 09:03:37 INFO - 'log_name': 'talos', 09:03:37 INFO - 'log_to_console': True, 09:03:37 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 09:03:37 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 09:03:37 INFO - 'opt_config_files': (), 09:03:37 INFO - 'pip_index': False, 09:03:37 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:03:37 INFO - 'cmd': ('bash', 09:03:37 INFO - '-c', 09:03:37 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:03:37 INFO - 'enabled': True, 09:03:37 INFO - 'halt_on_failure': False, 09:03:37 INFO - 'name': 'check_screen_resolution'},), 09:03:37 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:03:37 INFO - 'cmd': ('bash', 09:03:37 INFO - '-c', 09:03:37 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:03:37 INFO - 'enabled': True, 09:03:37 INFO - 'halt_on_failure': False, 09:03:37 INFO - 'name': 'check_screen_resolution'},), 09:03:37 INFO - 'run_cmd_checks_enabled': True, 09:03:37 INFO - 'sps_profile': False, 09:03:37 INFO - 'sps_profile_interval': 0, 09:03:37 INFO - 'suite': 'dromaeojs-e10s', 09:03:37 INFO - 'system_bits': '32', 09:03:37 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:03:37 INFO - 'title': 't-yosemite-r7-0259', 09:03:37 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:03:37 INFO - 'use_talos_json': True, 09:03:37 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:03:37 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:03:37 INFO - 'work_dir': 'build'} 09:03:37 INFO - [mozharness: 2017-03-04 17:03:37.788265Z] Running clobber step. 09:03:37 INFO - Running pre-action listener: _resource_record_pre_action 09:03:37 INFO - Running main action method: clobber 09:03:37 INFO - rmtree: /builds/slave/test/build 09:03:37 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:03:40 INFO - Running post-action listener: _resource_record_post_action 09:03:40 INFO - [mozharness: 2017-03-04 17:03:40.334911Z] Finished clobber step (success) 09:03:40 INFO - [mozharness: 2017-03-04 17:03:40.335056Z] Running read-buildbot-config step. 09:03:40 INFO - Running pre-action listener: _resource_record_pre_action 09:03:40 INFO - Running main action method: read_buildbot_config 09:03:40 INFO - Using buildbot properties: 09:03:40 INFO - { 09:03:40 INFO - "project": "", 09:03:40 INFO - "product": "firefox", 09:03:40 INFO - "script_repo_revision": "production", 09:03:40 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs-e10s", 09:03:40 INFO - "repository": "", 09:03:40 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs-e10s", 09:03:40 INFO - "buildid": "20170304080023", 09:03:40 INFO - "pgo_build": "False", 09:03:40 INFO - "basedir": "/builds/slave/test", 09:03:40 INFO - "buildnumber": 3, 09:03:40 INFO - "slavename": "t-yosemite-r7-0259", 09:03:40 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 09:03:40 INFO - "platform": "yosemite_r7", 09:03:40 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 09:03:40 INFO - "branch": "mozilla-aurora", 09:03:40 INFO - "repo_path": "releases/mozilla-aurora", 09:03:40 INFO - "revision": "14cf0c8a70e142e1416aa091f11b94619de1dcba", 09:03:40 INFO - "stage_platform": "macosx64", 09:03:40 INFO - "builduid": "aa8c5190747341328a0d4aa07cef1678", 09:03:40 INFO - "slavebuilddir": "test" 09:03:40 INFO - } 09:03:40 INFO - Found installer url https://queue.taskcluster.net/v1/task/HzAZHv9-SFeVYZjHeZyyyQ/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg. 09:03:40 INFO - Running post-action listener: _resource_record_post_action 09:03:40 INFO - [mozharness: 2017-03-04 17:03:40.338505Z] Finished read-buildbot-config step (success) 09:03:40 INFO - [mozharness: 2017-03-04 17:03:40.338614Z] Running download-and-extract step. 09:03:40 INFO - Running pre-action listener: _resource_record_pre_action 09:03:40 INFO - Running main action method: download_and_extract 09:03:40 INFO - mkdir: /builds/slave/test/build/tests 09:03:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:03:40 INFO - trying https://queue.taskcluster.net/v1/task/HzAZHv9-SFeVYZjHeZyyyQ/artifacts/public/build/firefox-53.0a2.en-US.mac.test_packages.json 09:03:40 INFO - Downloading https://queue.taskcluster.net/v1/task/HzAZHv9-SFeVYZjHeZyyyQ/artifacts/public/build/firefox-53.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-53.0a2.en-US.mac.test_packages.json 09:03:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/HzAZHv9-SFeVYZjHeZyyyQ/artifacts/public/build/firefox-53.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-53.0a2.en-US.mac.test_packages.json'}, attempt #1 09:03:41 INFO - Downloaded 1199 bytes. 09:03:41 INFO - Reading from file /builds/slave/test/build/firefox-53.0a2.en-US.mac.test_packages.json 09:03:41 INFO - Using the following test package requirements: 09:03:41 INFO - {u'common': [u'firefox-53.0a2.en-US.mac.common.tests.zip'], 09:03:41 INFO - u'cppunittest': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 09:03:41 INFO - u'firefox-53.0a2.en-US.mac.cppunittest.tests.zip'], 09:03:41 INFO - u'gtest': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 09:03:41 INFO - u'firefox-53.0a2.en-US.mac.gtest.tests.zip'], 09:03:41 INFO - u'jittest': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 09:03:41 INFO - u'jsshell-mac.zip'], 09:03:41 INFO - u'mochitest': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 09:03:41 INFO - u'firefox-53.0a2.en-US.mac.mochitest.tests.zip'], 09:03:41 INFO - u'mozbase': [u'firefox-53.0a2.en-US.mac.common.tests.zip'], 09:03:41 INFO - u'reftest': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 09:03:41 INFO - u'firefox-53.0a2.en-US.mac.reftest.tests.zip'], 09:03:41 INFO - u'talos': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 09:03:41 INFO - u'firefox-53.0a2.en-US.mac.talos.tests.zip'], 09:03:41 INFO - u'web-platform': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 09:03:41 INFO - u'firefox-53.0a2.en-US.mac.web-platform.tests.zip'], 09:03:41 INFO - u'xpcshell': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 09:03:41 INFO - u'firefox-53.0a2.en-US.mac.xpcshell.tests.zip']} 09:03:41 INFO - Downloading packages: [u'firefox-53.0a2.en-US.mac.common.tests.zip'] for test suite category: common 09:03:41 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/HzAZHv9-SFeVYZjHeZyyyQ/artifacts/public/build/firefox-53.0a2.en-US.mac.common.tests.zip 09:03:41 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/HzAZHv9-SFeVYZjHeZyyyQ/artifacts/public/build/firefox-53.0a2.en-US.mac.common.tests.zip'}, attempt #1 09:03:41 INFO - Fetch https://queue.taskcluster.net/v1/task/HzAZHv9-SFeVYZjHeZyyyQ/artifacts/public/build/firefox-53.0a2.en-US.mac.common.tests.zip into memory 09:03:42 INFO - Expected file size: 16357658 09:03:42 INFO - Obtained file size: 16357658 09:03:49 INFO - Downloading packages: [u'firefox-53.0a2.en-US.mac.common.tests.zip', u'firefox-53.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 09:03:49 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/HzAZHv9-SFeVYZjHeZyyyQ/artifacts/public/build/firefox-53.0a2.en-US.mac.common.tests.zip 09:03:49 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/HzAZHv9-SFeVYZjHeZyyyQ/artifacts/public/build/firefox-53.0a2.en-US.mac.common.tests.zip'}, attempt #1 09:03:49 INFO - Fetch https://queue.taskcluster.net/v1/task/HzAZHv9-SFeVYZjHeZyyyQ/artifacts/public/build/firefox-53.0a2.en-US.mac.common.tests.zip into memory 09:03:50 INFO - Expected file size: 16357658 09:03:50 INFO - Obtained file size: 16357658 09:03:56 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/HzAZHv9-SFeVYZjHeZyyyQ/artifacts/public/build/firefox-53.0a2.en-US.mac.talos.tests.zip 09:03:56 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/HzAZHv9-SFeVYZjHeZyyyQ/artifacts/public/build/firefox-53.0a2.en-US.mac.talos.tests.zip'}, attempt #1 09:03:56 INFO - Fetch https://queue.taskcluster.net/v1/task/HzAZHv9-SFeVYZjHeZyyyQ/artifacts/public/build/firefox-53.0a2.en-US.mac.talos.tests.zip into memory 09:03:57 INFO - Expected file size: 14403650 09:03:57 INFO - Obtained file size: 14403650 09:03:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:03:58 INFO - trying https://queue.taskcluster.net/v1/task/HzAZHv9-SFeVYZjHeZyyyQ/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg 09:03:58 INFO - Downloading https://queue.taskcluster.net/v1/task/HzAZHv9-SFeVYZjHeZyyyQ/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-53.0a2.en-US.mac.dmg 09:03:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/HzAZHv9-SFeVYZjHeZyyyQ/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-53.0a2.en-US.mac.dmg'}, attempt #1 09:04:00 INFO - Downloaded 73931318 bytes. 09:04:00 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/HzAZHv9-SFeVYZjHeZyyyQ/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg 09:04:00 INFO - mkdir: /builds/slave/test/properties 09:04:00 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:04:00 INFO - Writing to file /builds/slave/test/properties/build_url 09:04:00 INFO - Contents: 09:04:00 INFO - build_url:https://queue.taskcluster.net/v1/task/HzAZHv9-SFeVYZjHeZyyyQ/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg 09:04:00 INFO - Running post-action listener: _resource_record_post_action 09:04:00 INFO - Running post-action listener: set_extra_try_arguments 09:04:00 INFO - [mozharness: 2017-03-04 17:04:00.837570Z] Finished download-and-extract step (success) 09:04:00 INFO - [mozharness: 2017-03-04 17:04:00.837691Z] Running populate-webroot step. 09:04:00 INFO - Running pre-action listener: _resource_record_pre_action 09:04:00 INFO - Running main action method: populate_webroot 09:04:00 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:04:00 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:04:00 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:04:00 INFO - '--tppagecycles', 09:04:00 INFO - '7'], 09:04:00 INFO - 'tests': ['tsvgm']}}, 09:04:00 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:04:00 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:04:00 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:04:00 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:04:00 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:04:00 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:04:00 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 09:04:00 INFO - 'talos_options': ['--disable-e10s'], 09:04:00 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:04:00 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 09:04:00 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:04:00 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 09:04:00 INFO - 'talos_options': ['--disable-e10s'], 09:04:00 INFO - 'tests': ['damp', 'tps']}, 09:04:00 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 09:04:00 INFO - 'tests': ['damp', 'tps']}, 09:04:00 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:04:00 INFO - 'tests': ['dromaeo_dom']}, 09:04:00 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:04:00 INFO - 'g4': {'talos_options': ['--disable-e10s'], 09:04:00 INFO - 'tests': ['basic_compositor_video']}, 09:04:00 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 09:04:00 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:04:00 INFO - 'tests': ['a11yr', 09:04:00 INFO - 'ts_paint', 09:04:00 INFO - 'tpaint', 09:04:00 INFO - 'sessionrestore', 09:04:00 INFO - 'sessionrestore_no_auto_restore', 09:04:00 INFO - 'tabpaint']}, 09:04:00 INFO - 'other-e10s': {'tests': ['a11yr', 09:04:00 INFO - 'ts_paint', 09:04:00 INFO - 'tpaint', 09:04:00 INFO - 'sessionrestore', 09:04:00 INFO - 'sessionrestore_no_auto_restore', 09:04:00 INFO - 'tabpaint']}, 09:04:00 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:04:00 INFO - 'ts_paint', 09:04:00 INFO - 'tpaint', 09:04:00 INFO - 'sessionrestore', 09:04:00 INFO - 'sessionrestore_no_auto_restore', 09:04:00 INFO - 'tabpaint']}, 09:04:00 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:04:00 INFO - 'ts_paint', 09:04:00 INFO - 'tpaint', 09:04:00 INFO - 'sessionrestore', 09:04:00 INFO - 'sessionrestore_no_auto_restore', 09:04:00 INFO - 'tabpaint']}, 09:04:00 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:04:00 INFO - 'tests': ['a11yr', 09:04:00 INFO - 'ts_paint', 09:04:00 INFO - 'tpaint', 09:04:00 INFO - 'sessionrestore', 09:04:00 INFO - 'sessionrestore_no_auto_restore', 09:04:00 INFO - 'tabpaint']}, 09:04:00 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:04:00 INFO - 'tests': ['a11yr', 09:04:00 INFO - 'ts_paint', 09:04:00 INFO - 'tpaint', 09:04:00 INFO - 'sessionrestore', 09:04:00 INFO - 'sessionrestore_no_auto_restore', 09:04:00 INFO - 'tabpaint']}, 09:04:00 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:04:00 INFO - 'tests': ['tsvgx', 09:04:00 INFO - 'tsvgr_opacity', 09:04:00 INFO - 'tart', 09:04:00 INFO - 'tscrollx', 09:04:00 INFO - 'cart', 09:04:00 INFO - 'tsvg_static']}, 09:04:00 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:04:00 INFO - 'tsvgr_opacity', 09:04:00 INFO - 'tart', 09:04:00 INFO - 'tscrollx', 09:04:00 INFO - 'cart', 09:04:00 INFO - 'tsvg_static']}, 09:04:00 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 09:04:00 INFO - 'talos_options': ['--disable-e10s'], 09:04:00 INFO - 'tests': ['tp5o']}, 09:04:00 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 09:04:00 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 09:04:00 INFO - 'talos_options': ['--disable-e10s', 09:04:00 INFO - '--xperf_path', 09:04:00 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:04:00 INFO - 'tests': ['tp5n']}, 09:04:00 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 09:04:00 INFO - 'talos_options': ['--xperf_path', 09:04:00 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:04:00 INFO - 'tests': ['tp5n']}}, 09:04:00 INFO - 'talos.zip': {'path': '', 09:04:00 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:04:00 INFO - Running post-action listener: _resource_record_post_action 09:04:00 INFO - [mozharness: 2017-03-04 17:04:00.856357Z] Finished populate-webroot step (success) 09:04:00 INFO - [mozharness: 2017-03-04 17:04:00.856486Z] Running create-virtualenv step. 09:04:00 INFO - Running pre-action listener: _resource_record_pre_action 09:04:00 INFO - Running main action method: create_virtualenv 09:04:00 INFO - Creating virtualenv /builds/slave/test/build/venv 09:04:00 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:04:00 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:04:00 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:04:00 INFO - Using real prefix '/tools/python27' 09:04:00 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:04:01 INFO - Installing distribute.............................................................................................................................................................................................done. 09:04:04 INFO - Installing pip.................done. 09:04:04 INFO - Return code: 0 09:04:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 09:04:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 09:04:04 INFO - Reading from file tmpfile_stdout 09:04:04 INFO - Output received: 09:04:04 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:04:04 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:04: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:04:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:04:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:04: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:04:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:04:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:04: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x108504b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108507768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fafe255f670>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1082e2d30>, '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 0x108209db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1082d7be8>, '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.R8phzQO2wB/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.5W5zUYVmgE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sw/s26whz2n6pbfv14p7p8hkdxw00000w/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:04: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', 'pip>=1.5'] in /builds/slave/test/build 09:04: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 pip>=1.5 09:04:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.R8phzQO2wB/Render', 09:04:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:04:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:04:04 INFO - 'HOME': '/Users/cltbld', 09:04:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:04:04 INFO - 'LOGNAME': 'cltbld', 09:04:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:04:04 INFO - 'MOZ_NO_REMOTE': '1', 09:04:04 INFO - 'NO_EM_RESTART': '1', 09:04:04 INFO - 'PAGER': '/bin/cat', 09:04:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:04:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:04:04 INFO - 'PWD': '/builds/slave/test', 09:04:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:04:04 INFO - 'SHELL': '/bin/bash', 09:04:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5W5zUYVmgE/Listeners', 09:04:04 INFO - 'TMPDIR': '/var/folders/sw/s26whz2n6pbfv14p7p8hkdxw00000w/T/', 09:04:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:04:04 INFO - 'USER': 'cltbld', 09:04:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:04:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:04:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:04:04 INFO - 'XPC_FLAGS': '0x0', 09:04:04 INFO - 'XPC_SERVICE_NAME': '0', 09:04:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:04:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:04:05 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:04:05 INFO - Cleaning up... 09:04:05 INFO - Return code: 0 09:04:05 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:04:05 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:04:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:04:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:04:05 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:04:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:04:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:04:05 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 0x108504b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108507768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fafe255f670>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1082e2d30>, '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 0x108209db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1082d7be8>, '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.R8phzQO2wB/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.5W5zUYVmgE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sw/s26whz2n6pbfv14p7p8hkdxw00000w/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:04:05 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:04:05 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:04:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.R8phzQO2wB/Render', 09:04:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:04:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:04:05 INFO - 'HOME': '/Users/cltbld', 09:04:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:04:05 INFO - 'LOGNAME': 'cltbld', 09:04:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:04:05 INFO - 'MOZ_NO_REMOTE': '1', 09:04:05 INFO - 'NO_EM_RESTART': '1', 09:04:05 INFO - 'PAGER': '/bin/cat', 09:04:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:04:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:04:05 INFO - 'PWD': '/builds/slave/test', 09:04:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:04:05 INFO - 'SHELL': '/bin/bash', 09:04:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5W5zUYVmgE/Listeners', 09:04:05 INFO - 'TMPDIR': '/var/folders/sw/s26whz2n6pbfv14p7p8hkdxw00000w/T/', 09:04:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:04:05 INFO - 'USER': 'cltbld', 09:04:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:04:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:04:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:04:05 INFO - 'XPC_FLAGS': '0x0', 09:04:05 INFO - 'XPC_SERVICE_NAME': '0', 09:04:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:04:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:04:05 INFO - Downloading/unpacking psutil>=3.1.1 09:04:05 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:04:05 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:04:05 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:04:05 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:04:05 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:04:05 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:04:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:04:09 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:04:09 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:04:09 INFO - Installing collected packages: psutil 09:04:09 INFO - Running setup.py install for psutil 09:04:09 INFO - building 'psutil._psutil_osx' extension 09:04:09 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:04:10 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:04:10 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:04:10 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:04:10 INFO - building 'psutil._psutil_posix' extension 09:04:10 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:04:10 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 09:04:10 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 09:04:10 INFO - ^ 09:04:10 INFO - 1 warning generated. 09:04:10 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:04:10 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:04:10 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:04:10 INFO - Successfully installed psutil 09:04:10 INFO - Cleaning up... 09:04:10 INFO - Return code: 0 09:04:10 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:04:10 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:04:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:04:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:04:10 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:04:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:04:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:04:10 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 0x108504b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108507768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fafe255f670>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1082e2d30>, '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 0x108209db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1082d7be8>, '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.R8phzQO2wB/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.5W5zUYVmgE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sw/s26whz2n6pbfv14p7p8hkdxw00000w/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:04:10 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:04:10 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:04:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.R8phzQO2wB/Render', 09:04:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:04:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:04:10 INFO - 'HOME': '/Users/cltbld', 09:04:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:04:10 INFO - 'LOGNAME': 'cltbld', 09:04:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:04:10 INFO - 'MOZ_NO_REMOTE': '1', 09:04:10 INFO - 'NO_EM_RESTART': '1', 09:04:10 INFO - 'PAGER': '/bin/cat', 09:04:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:04:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:04:10 INFO - 'PWD': '/builds/slave/test', 09:04:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:04:10 INFO - 'SHELL': '/bin/bash', 09:04:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5W5zUYVmgE/Listeners', 09:04:10 INFO - 'TMPDIR': '/var/folders/sw/s26whz2n6pbfv14p7p8hkdxw00000w/T/', 09:04:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:04:10 INFO - 'USER': 'cltbld', 09:04:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:04:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:04:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:04:10 INFO - 'XPC_FLAGS': '0x0', 09:04:10 INFO - 'XPC_SERVICE_NAME': '0', 09:04:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:04:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:04:10 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:04:10 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:04:10 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:04:10 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:04:10 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:04:10 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:04:10 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:04:13 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:04:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:04:13 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:04:13 INFO - Installing collected packages: mozsystemmonitor 09:04:13 INFO - Running setup.py install for mozsystemmonitor 09:04:13 INFO - Successfully installed mozsystemmonitor 09:04:13 INFO - Cleaning up... 09:04:13 INFO - Return code: 0 09:04:13 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:04:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:04:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:04:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:04:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:04:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:04:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:04:13 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x108504b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108507768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fafe255f670>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1082e2d30>, '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 0x108209db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1082d7be8>, '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.R8phzQO2wB/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.5W5zUYVmgE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sw/s26whz2n6pbfv14p7p8hkdxw00000w/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:04:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 09:04:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 09:04:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.R8phzQO2wB/Render', 09:04:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:04:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:04:13 INFO - 'HOME': '/Users/cltbld', 09:04:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:04:13 INFO - 'LOGNAME': 'cltbld', 09:04:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:04:13 INFO - 'MOZ_NO_REMOTE': '1', 09:04:13 INFO - 'NO_EM_RESTART': '1', 09:04:13 INFO - 'PAGER': '/bin/cat', 09:04:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:04:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:04:13 INFO - 'PWD': '/builds/slave/test', 09:04:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:04:13 INFO - 'SHELL': '/bin/bash', 09:04:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5W5zUYVmgE/Listeners', 09:04:13 INFO - 'TMPDIR': '/var/folders/sw/s26whz2n6pbfv14p7p8hkdxw00000w/T/', 09:04:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:04:13 INFO - 'USER': 'cltbld', 09:04:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:04:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:04:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:04:13 INFO - 'XPC_FLAGS': '0x0', 09:04:13 INFO - 'XPC_SERVICE_NAME': '0', 09:04:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:04:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:04:14 INFO - Downloading/unpacking jsonschema==2.5.1 09:04:14 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:04:14 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:04:14 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:04:14 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:04:14 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:04:14 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:04:16 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:04:16 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:04: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:04: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:04: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:04: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:04: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:04: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:04:17 INFO - Downloading functools32-3.2.3-2.tar.gz 09:04:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:04:17 INFO - warning: no files found matching '*.txt' 09:04:17 INFO - no previously-included directories found matching 'build' 09:04:17 INFO - no previously-included directories found matching 'dist' 09:04:17 INFO - no previously-included directories found matching '.git*' 09:04:17 INFO - Installing collected packages: jsonschema, functools32 09:04:17 INFO - Running setup.py install for functools32 09:04:17 INFO - warning: no files found matching '*.txt' 09:04:17 INFO - no previously-included directories found matching 'build' 09:04:17 INFO - no previously-included directories found matching 'dist' 09:04:17 INFO - no previously-included directories found matching '.git*' 09:04:17 INFO - Successfully installed jsonschema functools32 09:04:17 INFO - Cleaning up... 09:04:17 INFO - Return code: 0 09:04:17 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 09:04:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:04:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:04:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:04:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:04:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:04:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:04:17 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x108504b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108507768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fafe255f670>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1082e2d30>, '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 0x108209db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1082d7be8>, '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.R8phzQO2wB/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.5W5zUYVmgE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sw/s26whz2n6pbfv14p7p8hkdxw00000w/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:04:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 09:04:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 09:04:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.R8phzQO2wB/Render', 09:04:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:04:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:04:17 INFO - 'HOME': '/Users/cltbld', 09:04:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:04:17 INFO - 'LOGNAME': 'cltbld', 09:04:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:04:17 INFO - 'MOZ_NO_REMOTE': '1', 09:04:17 INFO - 'NO_EM_RESTART': '1', 09:04:17 INFO - 'PAGER': '/bin/cat', 09:04:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:04:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:04:17 INFO - 'PWD': '/builds/slave/test', 09:04:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:04:17 INFO - 'SHELL': '/bin/bash', 09:04:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5W5zUYVmgE/Listeners', 09:04:17 INFO - 'TMPDIR': '/var/folders/sw/s26whz2n6pbfv14p7p8hkdxw00000w/T/', 09:04:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:04:17 INFO - 'USER': 'cltbld', 09:04:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:04:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:04:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:04:17 INFO - 'XPC_FLAGS': '0x0', 09:04:17 INFO - 'XPC_SERVICE_NAME': '0', 09:04:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:04:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:04:17 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 09:04:17 INFO - Cleaning up... 09:04:17 INFO - Return code: 0 09:04:17 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:04:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:04:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:04:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:04:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:04:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:04:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:04:17 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x108504b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108507768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fafe255f670>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1082e2d30>, '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 0x108209db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1082d7be8>, '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.R8phzQO2wB/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.5W5zUYVmgE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sw/s26whz2n6pbfv14p7p8hkdxw00000w/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:04:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 09:04:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 09:04:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.R8phzQO2wB/Render', 09:04:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:04:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:04:17 INFO - 'HOME': '/Users/cltbld', 09:04:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:04:17 INFO - 'LOGNAME': 'cltbld', 09:04:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:04:17 INFO - 'MOZ_NO_REMOTE': '1', 09:04:17 INFO - 'NO_EM_RESTART': '1', 09:04:17 INFO - 'PAGER': '/bin/cat', 09:04:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:04:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:04:17 INFO - 'PWD': '/builds/slave/test', 09:04:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:04:17 INFO - 'SHELL': '/bin/bash', 09:04:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5W5zUYVmgE/Listeners', 09:04:17 INFO - 'TMPDIR': '/var/folders/sw/s26whz2n6pbfv14p7p8hkdxw00000w/T/', 09:04:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:04:17 INFO - 'USER': 'cltbld', 09:04:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:04:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:04:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:04:17 INFO - 'XPC_FLAGS': '0x0', 09:04:17 INFO - 'XPC_SERVICE_NAME': '0', 09:04:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:04:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:04:18 INFO - Downloading/unpacking blobuploader==1.2.4 09:04:18 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:04:18 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:04:18 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:04:18 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:04:18 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:04:18 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:04:20 INFO - Downloading blobuploader-1.2.4.tar.gz 09:04:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:04:20 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:04:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:04:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:04:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:04:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:04:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:04:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:04:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:04:21 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:04:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:04:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:04:21 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:04:21 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:04:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:04:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:04:22 INFO - Downloading docopt-0.6.1.tar.gz 09:04:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:04:22 INFO - Installing collected packages: blobuploader, requests, docopt 09:04:22 INFO - Running setup.py install for blobuploader 09:04:22 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:04:22 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:04:22 INFO - Running setup.py install for requests 09:04:22 INFO - Running setup.py install for docopt 09:04:22 INFO - Successfully installed blobuploader requests docopt 09:04:22 INFO - Cleaning up... 09:04:23 INFO - Return code: 0 09:04:23 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:04:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:04:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:04:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:04:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:04:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:04:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:04:23 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 0x108504b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108507768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fafe255f670>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1082e2d30>, '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 0x108209db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1082d7be8>, '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.R8phzQO2wB/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.5W5zUYVmgE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sw/s26whz2n6pbfv14p7p8hkdxw00000w/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:04:23 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:04:23 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:04:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.R8phzQO2wB/Render', 09:04:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:04:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:04:23 INFO - 'HOME': '/Users/cltbld', 09:04:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:04:23 INFO - 'LOGNAME': 'cltbld', 09:04:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:04:23 INFO - 'MOZ_NO_REMOTE': '1', 09:04:23 INFO - 'NO_EM_RESTART': '1', 09:04:23 INFO - 'PAGER': '/bin/cat', 09:04:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:04:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:04:23 INFO - 'PWD': '/builds/slave/test', 09:04:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:04:23 INFO - 'SHELL': '/bin/bash', 09:04:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5W5zUYVmgE/Listeners', 09:04:23 INFO - 'TMPDIR': '/var/folders/sw/s26whz2n6pbfv14p7p8hkdxw00000w/T/', 09:04:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:04:23 INFO - 'USER': 'cltbld', 09:04:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:04:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:04:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:04:23 INFO - 'XPC_FLAGS': '0x0', 09:04:23 INFO - 'XPC_SERVICE_NAME': '0', 09:04:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:04:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:04:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:04:23 INFO - Running setup.py (path:/var/folders/sw/s26whz2n6pbfv14p7p8hkdxw00000w/T/pip-TKf44B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:04:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:04:23 INFO - Running setup.py (path:/var/folders/sw/s26whz2n6pbfv14p7p8hkdxw00000w/T/pip-CQFxPc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:04:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:04:23 INFO - Running setup.py (path:/var/folders/sw/s26whz2n6pbfv14p7p8hkdxw00000w/T/pip-CQUvIQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:04:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:04:23 INFO - Running setup.py (path:/var/folders/sw/s26whz2n6pbfv14p7p8hkdxw00000w/T/pip-us1P9N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:04:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:04:23 INFO - Running setup.py (path:/var/folders/sw/s26whz2n6pbfv14p7p8hkdxw00000w/T/pip-osK0w9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:04:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:04:23 INFO - Running setup.py (path:/var/folders/sw/s26whz2n6pbfv14p7p8hkdxw00000w/T/pip-dPWq_d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:04:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:04:23 INFO - Running setup.py (path:/var/folders/sw/s26whz2n6pbfv14p7p8hkdxw00000w/T/pip-nOX7wX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:04:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:04:24 INFO - Running setup.py (path:/var/folders/sw/s26whz2n6pbfv14p7p8hkdxw00000w/T/pip-6lP5IF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:04:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:04:24 INFO - Running setup.py (path:/var/folders/sw/s26whz2n6pbfv14p7p8hkdxw00000w/T/pip-31tfRI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:04:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:04:24 INFO - Running setup.py (path:/var/folders/sw/s26whz2n6pbfv14p7p8hkdxw00000w/T/pip-5goLRK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:04:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:04:24 INFO - Running setup.py (path:/var/folders/sw/s26whz2n6pbfv14p7p8hkdxw00000w/T/pip-ubrY1u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:04:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:04:24 INFO - Running setup.py (path:/var/folders/sw/s26whz2n6pbfv14p7p8hkdxw00000w/T/pip-jKx0m4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:04:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:04:24 INFO - Running setup.py (path:/var/folders/sw/s26whz2n6pbfv14p7p8hkdxw00000w/T/pip-5D2be3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:04:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:04:24 INFO - Running setup.py (path:/var/folders/sw/s26whz2n6pbfv14p7p8hkdxw00000w/T/pip-YKH4XI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:04:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:04:24 INFO - Running setup.py (path:/var/folders/sw/s26whz2n6pbfv14p7p8hkdxw00000w/T/pip-UxxKHh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:04:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:04:24 INFO - Running setup.py (path:/var/folders/sw/s26whz2n6pbfv14p7p8hkdxw00000w/T/pip-Y9U2cy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:04:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:04:25 INFO - Running setup.py (path:/var/folders/sw/s26whz2n6pbfv14p7p8hkdxw00000w/T/pip-mN24y6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:04:25 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:04:25 INFO - Running setup.py install for manifestparser 09:04:25 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:04:25 INFO - Running setup.py install for mozcrash 09:04:25 INFO - Running setup.py install for mozdebug 09:04:25 INFO - Running setup.py install for mozdevice 09:04:25 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:04:25 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:04:25 INFO - Running setup.py install for mozfile 09:04:25 INFO - Running setup.py install for mozhttpd 09:04:26 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:04:26 INFO - Running setup.py install for mozinfo 09:04:26 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:04:26 INFO - Running setup.py install for mozInstall 09:04:26 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:04:26 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:04:26 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:04:26 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:04:26 INFO - Running setup.py install for mozleak 09:04:26 INFO - Running setup.py install for mozlog 09:04:26 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:04:26 INFO - Running setup.py install for moznetwork 09:04:26 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:04:26 INFO - Running setup.py install for mozprocess 09:04:26 INFO - Running setup.py install for mozprofile 09:04:27 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:04:27 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:04:27 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:04:27 INFO - Running setup.py install for mozrunner 09:04:27 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:04:27 INFO - Running setup.py install for mozscreenshot 09:04:27 INFO - Running setup.py install for moztest 09:04:27 INFO - Running setup.py install for mozversion 09:04:27 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:04:27 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:04:27 INFO - Cleaning up... 09:04:27 INFO - Return code: 0 09:04:27 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:04:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:04:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:04:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:04:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:04:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:04:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:04:27 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 0x108504b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108507768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fafe255f670>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1082e2d30>, '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 0x108209db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1082d7be8>, '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.R8phzQO2wB/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.5W5zUYVmgE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sw/s26whz2n6pbfv14p7p8hkdxw00000w/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:04:27 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:04:27 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:04:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.R8phzQO2wB/Render', 09:04:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:04:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:04:27 INFO - 'HOME': '/Users/cltbld', 09:04:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:04:27 INFO - 'LOGNAME': 'cltbld', 09:04:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:04:27 INFO - 'MOZ_NO_REMOTE': '1', 09:04:27 INFO - 'NO_EM_RESTART': '1', 09:04:27 INFO - 'PAGER': '/bin/cat', 09:04:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:04:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:04:27 INFO - 'PWD': '/builds/slave/test', 09:04:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:04:27 INFO - 'SHELL': '/bin/bash', 09:04:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5W5zUYVmgE/Listeners', 09:04:27 INFO - 'TMPDIR': '/var/folders/sw/s26whz2n6pbfv14p7p8hkdxw00000w/T/', 09:04:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:04:27 INFO - 'USER': 'cltbld', 09:04:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:04:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:04:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:04:27 INFO - 'XPC_FLAGS': '0x0', 09:04:27 INFO - 'XPC_SERVICE_NAME': '0', 09:04:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:04:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:04:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:04:28 INFO - Running setup.py (path:/var/folders/sw/s26whz2n6pbfv14p7p8hkdxw00000w/T/pip-TWxD74-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:04:28 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:04:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:04:28 INFO - Running setup.py (path:/var/folders/sw/s26whz2n6pbfv14p7p8hkdxw00000w/T/pip-539yH9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:04:28 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:04:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:04:28 INFO - Running setup.py (path:/var/folders/sw/s26whz2n6pbfv14p7p8hkdxw00000w/T/pip-_4baU1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:04:28 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:04:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:04:28 INFO - Running setup.py (path:/var/folders/sw/s26whz2n6pbfv14p7p8hkdxw00000w/T/pip-LNUNP4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:04:28 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:04:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:04:28 INFO - Running setup.py (path:/var/folders/sw/s26whz2n6pbfv14p7p8hkdxw00000w/T/pip-dKpyAq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:04:28 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:04:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:04:28 INFO - Running setup.py (path:/var/folders/sw/s26whz2n6pbfv14p7p8hkdxw00000w/T/pip-ig17cU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:04:28 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:04:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:04:28 INFO - Running setup.py (path:/var/folders/sw/s26whz2n6pbfv14p7p8hkdxw00000w/T/pip-VH13i7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:04:28 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:04:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:04:28 INFO - Running setup.py (path:/var/folders/sw/s26whz2n6pbfv14p7p8hkdxw00000w/T/pip-Vqv_9C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:04:28 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:04:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:04:28 INFO - Running setup.py (path:/var/folders/sw/s26whz2n6pbfv14p7p8hkdxw00000w/T/pip-o47Z_E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:04:28 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:04:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:04:28 INFO - Running setup.py (path:/var/folders/sw/s26whz2n6pbfv14p7p8hkdxw00000w/T/pip-No0_vf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:04:29 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:04:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:04:29 INFO - Running setup.py (path:/var/folders/sw/s26whz2n6pbfv14p7p8hkdxw00000w/T/pip-CGg6mM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:04:29 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:04:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:04:29 INFO - Running setup.py (path:/var/folders/sw/s26whz2n6pbfv14p7p8hkdxw00000w/T/pip-mTNkt3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:04:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.24 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:04:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:04:29 INFO - Running setup.py (path:/var/folders/sw/s26whz2n6pbfv14p7p8hkdxw00000w/T/pip-sxkdIM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:04:29 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:04:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:04:29 INFO - Running setup.py (path:/var/folders/sw/s26whz2n6pbfv14p7p8hkdxw00000w/T/pip-B0kxX7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:04:29 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:04:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:04:29 INFO - Running setup.py (path:/var/folders/sw/s26whz2n6pbfv14p7p8hkdxw00000w/T/pip-mEYXs9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:04:29 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:04:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:04:29 INFO - Running setup.py (path:/var/folders/sw/s26whz2n6pbfv14p7p8hkdxw00000w/T/pip-rtC2za-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:04:29 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:04:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:04:29 INFO - Running setup.py (path:/var/folders/sw/s26whz2n6pbfv14p7p8hkdxw00000w/T/pip-LH66GQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:04:29 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:04:29 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:04:29 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:04:29 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:04:29 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:04:29 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:04:29 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:04:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:04:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:04:29 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:04:29 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:04:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:04:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:04:32 INFO - Downloading blessings-1.6.tar.gz 09:04:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:04:32 INFO - Installing collected packages: blessings 09:04:32 INFO - Running setup.py install for blessings 09:04:32 INFO - Successfully installed blessings 09:04:32 INFO - Cleaning up... 09:04:32 INFO - Return code: 0 09:04:32 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:04:32 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:04:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:04:33 INFO - Reading from file tmpfile_stdout 09:04:33 INFO - Current package versions: 09:04:33 INFO - blessings == 1.6 09:04:33 INFO - blobuploader == 1.2.4 09:04:33 INFO - docopt == 0.6.1 09:04:33 INFO - functools32 == 3.2.3-2 09:04:33 INFO - jsonschema == 2.5.1 09:04:33 INFO - manifestparser == 1.1 09:04:33 INFO - mozInstall == 1.12 09:04:33 INFO - mozcrash == 1.0 09:04:33 INFO - mozdebug == 0.1 09:04:33 INFO - mozdevice == 0.49 09:04:33 INFO - mozfile == 1.2 09:04:33 INFO - mozhttpd == 0.7 09:04:33 INFO - mozinfo == 0.9 09:04:33 INFO - mozleak == 0.1 09:04:33 INFO - mozlog == 3.4 09:04:33 INFO - moznetwork == 0.27 09:04:33 INFO - mozprocess == 0.24 09:04:33 INFO - mozprofile == 0.28 09:04:33 INFO - mozrunner == 6.13 09:04:33 INFO - mozscreenshot == 0.1 09:04:33 INFO - mozsystemmonitor == 0.3 09:04:33 INFO - moztest == 0.8 09:04:33 INFO - mozversion == 1.4 09:04:33 INFO - psutil == 3.1.1 09:04:33 INFO - requests == 1.2.3 09:04:33 INFO - wsgiref == 0.1.2 09:04:33 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:04:33 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:04:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:04:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:04:33 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:04:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:04:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:04:33 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 0x108504b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108507768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fafe255f670>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1082e2d30>, '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 0x108209db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1082d7be8>, '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.R8phzQO2wB/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.5W5zUYVmgE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sw/s26whz2n6pbfv14p7p8hkdxw00000w/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:04:33 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:04:33 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:04:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.R8phzQO2wB/Render', 09:04:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:04:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:04:33 INFO - 'HOME': '/Users/cltbld', 09:04:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:04:33 INFO - 'LOGNAME': 'cltbld', 09:04:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:04:33 INFO - 'MOZ_NO_REMOTE': '1', 09:04:33 INFO - 'NO_EM_RESTART': '1', 09:04:33 INFO - 'PAGER': '/bin/cat', 09:04:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:04:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:04:33 INFO - 'PWD': '/builds/slave/test', 09:04:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:04:33 INFO - 'SHELL': '/bin/bash', 09:04:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5W5zUYVmgE/Listeners', 09:04:33 INFO - 'TMPDIR': '/var/folders/sw/s26whz2n6pbfv14p7p8hkdxw00000w/T/', 09:04:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:04:33 INFO - 'USER': 'cltbld', 09:04:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:04:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:04:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:04:33 INFO - 'XPC_FLAGS': '0x0', 09:04:33 INFO - 'XPC_SERVICE_NAME': '0', 09:04:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:04:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:04:33 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:04:33 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:04:33 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:04:33 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:04:33 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:04:33 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:04:33 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:04:33 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:04:33 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:04:33 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:04:33 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:04:33 INFO - Cleaning up... 09:04:33 INFO - Return code: 0 09:04:33 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:04:33 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:04:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:04:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:04:33 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:04:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:04:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:04:33 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 0x108504b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108507768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fafe255f670>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1082e2d30>, '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 0x108209db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1082d7be8>, '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.R8phzQO2wB/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.5W5zUYVmgE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sw/s26whz2n6pbfv14p7p8hkdxw00000w/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:04:33 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:04:33 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:04:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.R8phzQO2wB/Render', 09:04:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:04:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:04:33 INFO - 'HOME': '/Users/cltbld', 09:04:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:04:33 INFO - 'LOGNAME': 'cltbld', 09:04:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:04:33 INFO - 'MOZ_NO_REMOTE': '1', 09:04:33 INFO - 'NO_EM_RESTART': '1', 09:04:33 INFO - 'PAGER': '/bin/cat', 09:04:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:04:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:04:33 INFO - 'PWD': '/builds/slave/test', 09:04:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:04:33 INFO - 'SHELL': '/bin/bash', 09:04:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5W5zUYVmgE/Listeners', 09:04:33 INFO - 'TMPDIR': '/var/folders/sw/s26whz2n6pbfv14p7p8hkdxw00000w/T/', 09:04:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:04:33 INFO - 'USER': 'cltbld', 09:04:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:04:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:04:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:04:33 INFO - 'XPC_FLAGS': '0x0', 09:04:33 INFO - 'XPC_SERVICE_NAME': '0', 09:04:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:04:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:04:33 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:04:33 INFO - Cleaning up... 09:04:33 INFO - Return code: 0 09:04:33 INFO - Running post-action listener: _resource_record_post_action 09:04:33 INFO - Running post-action listener: _start_resource_monitoring 09:04:33 INFO - Starting resource monitoring. 09:04:33 INFO - [mozharness: 2017-03-04 17:04:33.703951Z] Finished create-virtualenv step (success) 09:04:33 INFO - [mozharness: 2017-03-04 17:04:33.704865Z] Running install step. 09:04:33 INFO - Running pre-action listener: _resource_record_pre_action 09:04:33 INFO - Running main action method: install 09:04:33 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:04:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:04:33 INFO - Reading from file tmpfile_stdout 09:04:33 INFO - Detecting whether we're running mozinstall >=1.0... 09:04:33 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:04:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:04:34 INFO - Reading from file tmpfile_stdout 09:04:34 INFO - Output received: 09:04:34 INFO - Usage: mozinstall [options] installer 09:04:34 INFO - Options: 09:04:34 INFO - -h, --help show this help message and exit 09:04:34 INFO - -d DEST, --destination=DEST 09:04:34 INFO - Directory to install application into. [default: 09:04:34 INFO - "/builds/slave/test"] 09:04:34 INFO - --app=APP Application being installed. [default: firefox] 09:04:34 INFO - mkdir: /builds/slave/test/build/application 09:04:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-53.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 09:04:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-53.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 09:04:54 INFO - Reading from file tmpfile_stdout 09:04:54 INFO - Output received: 09:04:54 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 09:04:54 INFO - Running post-action listener: _resource_record_post_action 09:04:54 INFO - [mozharness: 2017-03-04 17:04:54.592143Z] Finished install step (success) 09:04:54 INFO - [mozharness: 2017-03-04 17:04:54.592317Z] Running run-tests step. 09:04:54 INFO - Running pre-action listener: _resource_record_pre_action 09:04:54 INFO - Running main action method: run_tests 09:04:54 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:04:54 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:04:54 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:04:54 INFO - 2017-03-04 09:04:54.606 screenresolution[1629:8331] starting screenresolution argv=screenresolution get 09:04:54 INFO - 2017-03-04 09:04:54.609 screenresolution[1629:8331] Display 0: 1600x1200x32@60 09:04:54 INFO - 2017-03-04 09:04:54.615 screenresolution[1630:8333] starting screenresolution argv=screenresolution list 09:04:54 INFO - Available Modes on Display 0 09:04:54 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:04:54 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:04:54 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:04:54 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:04:54 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:04:54 INFO - Intel Iris: 09:04:54 INFO - Chipset Model: Intel Iris 09:04:54 INFO - Type: GPU 09:04:54 INFO - Bus: Built-In 09:04:54 INFO - VRAM (Dynamic, Max): 1536 MB 09:04:54 INFO - Vendor: Intel (0x8086) 09:04:54 INFO - Device ID: 0x0a2e 09:04:54 INFO - Revision ID: 0x0009 09:04:54 INFO - Displays: 09:04:54 INFO - Display: 09:04:54 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:04:54 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:04:54 INFO - Display Serial Number: 1600x1200 60 09:04:54 INFO - Main Display: Yes 09:04:54 INFO - Mirror: Off 09:04:54 INFO - Online: Yes 09:04:54 INFO - Rotation: Supported 09:04:54 INFO - Return code: 0 09:04:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:04:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:04:54 INFO - Python 2.7.3 09:04:54 INFO - Return code: 0 09:04:54 INFO - grabbing minidump binary from tooltool 09:04:54 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:04:54 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 0x1082e2d30>, '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 0x108209db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1082d7be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:04:54 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:04:54 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:04:54 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:04:54 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:04:54 INFO - Return code: 0 09:04:54 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 09:04:54 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:04:54 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:04:54 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 09:04:54 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:04:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/HzAZHv9-SFeVYZjHeZyyyQ/artifacts/public/build/firefox-53.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0259', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 09:04:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/HzAZHv9-SFeVYZjHeZyyyQ/artifacts/public/build/firefox-53.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0259 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 09:04:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.R8phzQO2wB/Render', 09:04:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:04:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:04:54 INFO - 'HOME': '/Users/cltbld', 09:04:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:04:54 INFO - 'LOGNAME': 'cltbld', 09:04:54 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:04:54 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 09:04:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:04:54 INFO - 'MOZ_NO_REMOTE': '1', 09:04:54 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:04:54 INFO - 'NO_EM_RESTART': '1', 09:04:54 INFO - 'PAGER': '/bin/cat', 09:04:54 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:04:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:04:54 INFO - 'PWD': '/builds/slave/test', 09:04:54 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:04:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:04:54 INFO - 'SHELL': '/bin/bash', 09:04:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5W5zUYVmgE/Listeners', 09:04:54 INFO - 'TMPDIR': '/var/folders/sw/s26whz2n6pbfv14p7p8hkdxw00000w/T/', 09:04:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:04:54 INFO - 'USER': 'cltbld', 09:04:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:04:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:04:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:04:54 INFO - 'XPC_FLAGS': '0x0', 09:04:54 INFO - 'XPC_SERVICE_NAME': '0', 09:04:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:04:54 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/HzAZHv9-SFeVYZjHeZyyyQ/artifacts/public/build/firefox-53.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0259', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 09:04:55 INFO - mozversion application_buildid: 20170304080023 09:04:55 INFO - mozversion application_changeset: 14cf0c8a70e142e1416aa091f11b94619de1dcba 09:04:55 INFO - mozversion application_display_name: FirefoxDeveloperEdition 09:04:55 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:04:55 INFO - mozversion application_name: Firefox 09:04:55 INFO - mozversion application_remotingname: firefox-dev 09:04:55 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 09:04:55 INFO - mozversion application_vendor: Mozilla 09:04:55 INFO - mozversion application_version: 53.0a2 09:04:55 INFO - mozversion platform_buildid: 20170304080023 09:04:55 INFO - mozversion platform_changeset: 14cf0c8a70e142e1416aa091f11b94619de1dcba 09:04:55 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 09:04:55 INFO - mozversion platform_version: 53.0a2 09:04:55 INFO - using testdate: 1488647095 09:04:55 INFO - actual date: 1488647095 09:04:55 INFO - starting webserver on 'localhost:49241' 09:04:55 INFO - SUITE-START | Running 2 tests 09:04:55 INFO - TEST-START | dromaeo_css 09:04:55 INFO - operating with platform_type : mac_ 09:04:55 INFO - Initialising browser for dromaeo_css test... 09:04:55 INFO - TEST-INFO | started process 1640 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/sw/s26whz2n6pbfv14p7p8hkdxw00000w/T/tmpvk4IVp/profile http://localhost:49241/getInfo.html) 09:04:58 INFO - PROCESS | 1640 | 2017-03-04 09:04:58.061 plugin-container[1642:8510] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8e43, name = 'com.apple.tsm.portname' 09:04:58 INFO - PROCESS | 1640 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:04:58 INFO - PROCESS | 1640 | 2017-03-04 09:04:58.064 plugin-container[1642:8510] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4c07, name = 'com.apple.CFPasteboardClient' 09:04:58 INFO - PROCESS | 1640 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:04:58 INFO - PROCESS | 1640 | 2017-03-04 09:04:58.064 plugin-container[1642:8510] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:04:58 INFO - PROCESS | 1640 | __metrics Screen width/height:1600/1200 09:04:58 INFO - PROCESS | 1640 | colorDepth:24 09:04:58 INFO - PROCESS | 1640 | Browser inner width/height: 1024/689 09:04:58 INFO - PROCESS | 1640 | __metrics 09:04:58 ERROR - PROCESS | 1640 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3277: TypeError: this.tabs is undefined 09:04:58 INFO - TEST-INFO | 1640: exit 0 09:04:58 INFO - Browser initialized. 09:04:58 INFO - Running cycle 1/1 for dromaeo_css test... 09:04:58 INFO - TEST-INFO | started process 1644 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/sw/s26whz2n6pbfv14p7p8hkdxw00000w/T/tmpvk4IVp/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:05:01 INFO - PROCESS | 1644 | 2017-03-04 09:05:01.513 plugin-container[1645:8653] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x873f, name = 'com.apple.tsm.portname' 09:05:01 INFO - PROCESS | 1644 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:05:01 INFO - PROCESS | 1644 | 2017-03-04 09:05:01.516 plugin-container[1645:8653] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8d0b, name = 'com.apple.CFPasteboardClient' 09:05:01 INFO - PROCESS | 1644 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:05:01 INFO - PROCESS | 1644 | 2017-03-04 09:05:01.516 plugin-container[1645:8653] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:05:11 INFO - PROCESS | 1644 | RSS: Main: 219009024 09:05:11 INFO - PROCESS | 1644 | 09:08:13 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49241/tests/dromaeo/cssquery-ext.html) 09:08:15 INFO - PROCESS | 1644 | RSS: Main: 239259648 09:08:15 INFO - PROCESS | 1644 | 09:09:59 INFO - PROCESS | 1644 | ************************* 09:09:59 INFO - PROCESS | 1644 | A coding exception was thrown and uncaught in a Task. 09:09:59 INFO - PROCESS | 1644 | 09:09:59 ERROR - PROCESS | 1644 | Full message: TypeError: NetworkError when attempting to fetch resource. 09:09:59 INFO - PROCESS | 1644 | Full stack: 09:09:59 INFO - PROCESS | 1644 | ************************* 09:11:17 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-ext.html (next: http://localhost:49241/tests/dromaeo/cssquery-jquery.html) 09:11:18 INFO - PROCESS | 1644 | RSS: Main: 248397824 09:11:18 INFO - PROCESS | 1644 | 09:14:20 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49241/tests/dromaeo/cssquery-mootools.html) 09:14:21 INFO - PROCESS | 1644 | RSS: Main: 255840256 09:14:21 INFO - PROCESS | 1644 | 09:17:23 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49241/tests/dromaeo/cssquery-prototype.html) 09:17:23 INFO - PROCESS | 1644 | RSS: Main: 256184320 09:17:23 INFO - PROCESS | 1644 | 09:20:26 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49241/tests/dromaeo/cssquery-yui.html) 09:20:27 INFO - PROCESS | 1644 | RSS: Main: 260059136 09:20:27 INFO - PROCESS | 1644 | 09:23:29 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-yui.html (next: http://localhost:49241/tests/dromaeo/cssquery-dojo.html) 09:23:30 INFO - PROCESS | 1644 | RSS: Main: 261906432 09:23:30 INFO - PROCESS | 1644 | 09:23:30 INFO - PROCESS | 1644 | __start_tp_report 09:23:30 INFO - PROCESS | 1644 | _x_x_mozilla_page_load 09:23:30 INFO - PROCESS | 1644 | _x_x_mozilla_page_load_details 09:23:30 INFO - PROCESS | 1644 | |i|pagename|runs| 09:23:30 INFO - PROCESS | 1644 | |0;dojo.html;3413.3343997920406;3430.2796412753337;3446.5519482467284;3446.9138271543206;3459.135216195951;5731.684757338345;5880.970830104725;5883.794067207648;5889.7349619267125;5903.616264942786;5890.087036509337;5951.970240148793;5967.194428752117;5971.701414929247;5971.731272092756;5184.196449550307;5328.680279183242;5377.516023557879;5393.865153371177;5423.159410291391;5400.91898621521;5405.18922161675;5406.864828379283;5417.918731219035;5431.918521222185;4421.336799480087;5211.86970325743;5271.841844744664;5410.5401040911565;5414.35027796665;5283.022640811462;5326.201069839536;5348.304720386325;5382.865428364283;5409.675419474844;5215.582753379758;5318.813841515528;5346;5350.090484617585;5358.035553600351;5560.332760068818;5833.300003999505;5896.646201227897;5928.911066334009;6004.42957918997;6160.353162917874;6195.907061394083;6203.193584833943;6272.372762723737;6278.434940855345;6250.187475628185;6252.562320637558;6261.624302541816;6267.905981410283;6290.716917738713;5964.045752679551;5967.940320596782;5968.104784282393;5982.9401705983255;6012.579119461596;6166.7224974876235;6206.441420272107;6214.875702486015;6249.25008998923;6254.906176407358;6200.534959877937;6203.565750397429;6254.655993920287;6265.780697675651;6272.717727702265;6268.780592679234;6277.7175027123885;6284.371562843679;6286.245650521967;6286.8428289292215;6135.601185922901;6180.227471566054;6190.41191086846;6191.071339299141;6216;6241.095041218998;6268.310485846553;6277.1525844010475;6295.968520157461;6296.5277604178955;5071.985602879439;5075.695458272516;5079.238114282887;5083.212102124176;5103.362079740033;3795.5445346558035;4372.956270437255;4380.824767009348;4437.112577484516;4512.075024619945;4224.936625950613;5929.73316200772;5953.28560572734;5992.43071908168;6053.606515576474;5266.8156614519075;6243.7814676486105;6272.968635156795;6277.18396608438;6289.24529056507;5720.456556627114;5844.824655260349;5900.144479050514;6012.038073908241;6022.5483088768515;5504;5526.115821468546;5528.612997090165;5537.36320323168;5555.083411237181;5580.553555715452;6170.352113028068;6391.744330226832;6401.551891367652;6428.871422571616;5050.924236136534;5464.453554644663;6192.164057852313;6244.469220116341;6261.060840873905;6349.587276826992;6364.968175159094;6389;6398.93601064002;6431.710573024318;6212.099245609452;6217.751289948352;6217.875642487215;6234.719437625227;6286.874262514815;6133.79375043651;6196.969015154896;6197.163382943245;6226.501879849692;6244.219472565929;6116.29662588808;6210.161628180138;6224.06639004153;6231.875362492816;6233.127362169209;6094.9085763714465;6207.782727604512;6224.875502489835;6240.594361366497;6252.187215661935;5852;5890.410958904075;5937.910931335947;6054.182685337423;6077.848053798513;6129.478994285358;6129.756146212572;6144.53915956314;6236.875262494634;6246.406591373812;6085.57400981927;6097.115918191748;6097.215386221507;6110.450059494667;6110.7861224856915 09:23:30 INFO - PROCESS | 1644 | |1;ext.html;451.6071018214152;456.59135074108667;497.2615665736381;499.8575406009287;541.6398095266647;38985;41623.16753664925;41658.583414165885;41672.58327416725;41913.79043104788;42771.78614106933;43098.56901430985;43232.56767432325;43303.56696433035;43310;8294.958525207365;8532.488050716946;9005.594748236312;9227.446353218795;9257.675981340655;11099.278546894426;11417.942910285437;11422.600208992686;11566.01688856444;11578;11288.887111128906;11320;11356.716082097932;11365;11366.659000230007;11503.309801411942;11527.654170374903;11560.653180404559;11561.017313528277;11568.595099171489;11342.829857552144;11365.545378184863;11396.715082123013;11443.485043173077;11445.08439324852;41595.7920210397;41767;41820.95447613804;41939.37090943638;42033;3921.372580387153;3933.1543718100747;3990.4213888986224;3999.8400063997406;4038.2125485530332;25907.870460647766;30329.393412131856;30397.544036839467;30732.924347647724;31193.688063119305;2471.8764061796837;3108.875644974198;3132.530120481923;3165.7150856422804;3274.345130973815;268103;308869.91130088863;330501;338748;341197;66126.00810987838;66401.66799165973;66736;66814.66592667006;66858;67352.6632366835;67922.32077679259;67964.6601766988;68028.31971680319;68124.6593767028;16551.503454896374;16678.58303542397;16746.74879876803;16781.993080415214;16803.663926721398;11516.827247591293;11717.414129293502;12803.743925121445;12872.420741066675;13131.803022954664;20796.79203207979;22152.335429937128;22239.88880055622;22254.99852506641;22271;7647.694092236248;8896.822063558822;9116.270698344117;9121.589528471235;9155.359124861328;691.3915754136328;821.6959724901825;859.2181115185151;862.6721845698594;871.9171678690515;28896.277593060342;29420.264493387407;30446;30544.389112217632;31029.534556981664;983.188869182927;1014.452195814252;1023;1033.1837848100067;1036.849656799775;13715.245661488714;14497.347619357155;14816.925915370353;14947.70104597902;15084.773728394082;669129.6543517251;674269;675582;676326;679864;55065.72467137639;55575.72212138913;55608.21958902039;55670.44329556733;55787.16319255214;1295.2811189789757;1342.2617560341773;1352.8376594808883;1355.2884735513935;1381.8134551835574;27628.447431051667;27855;28185.859070704515;28604.71395286104;28658.14025579236;41403.378949316386;49061.75469122631;49181.754091230745;49182.75408622934;49890;616347;620872;621616;621848;622065;25848.612270815578;26205.73794262033;26845.463090737685;26860.328491787863;27237.54862062424;43938.56061439345;44145.77927110344;45234.54765452435;45293.77353113213;45361;35576;36099.819500902326;36192.638073618924;36788.08029799276;37211;36613.63386366102;36829.63170368369;37114.628853712195;37120;37427.2514549702;28936.56595151032;35195.648043519235;35291.64708352986;35433;36228.63771362253;31106.066817995496;32537.837310813295;32704.345913081128;32721.509177361884;32866.83566582152;21256.893715531325;23142.76857231406;23976.672206685897;24673.50652986966;24781.752182477943 09:23:30 INFO - PROCESS | 1644 | |2;jquery.html;388.9027743064234;391.1180288449548;401.0915276897826;407.6005514595696;422.7526896765392;16004.519864404057;19445.12496937638;20377.592448151026;20818.583628327444;20846.583068338645;21273.361799146012;21292.574148517022;21864.89067554664;22690.092396304128;22966.966486508107;5137.691738495702;5156.226566015109;5701.25883635129;5967.134765459005;5992.310884248323;5061.19020956647;5682.346530149024;5791.21818554494;5853.8829223415505;5880.500157486619;5803.448672376117;5812.854678633025;5817.941820581782;5819.592628516006;5825.8834823303505;4909.950900490985;4945.752712364385;4967.801287948478;6831.726730930757;7167.390771784405;7414.184439711627;7668.156502784689;7706.998090248288;7725.188855170239;7748.031496062992;25264;28252.858735706395;29086.854565727248;29136.41727165445;29786;2808.255812209766;2848.786341024411;2858.1568437311043;2878.0070875547904;2883.2503549077182;15726.606834829221;16345.855790094698;16853.747193792104;16870.325186992504;17509.299628014865;1894;1921.798211187819;1979.3962841333387;1987.0462178154423;1988.2941555747645;143546;153795;159722;161722.1913890423;163731;10206.285560010729;10717.303375280582;10734.892651073545;10782.838257426145;10881.564737410574;12854.9357253215;12929.288889111189;12963.416646250942;14149.71700565983;14657.633559161106;8434.451760635662;8687.91312086871;8753.168448997336;8793;8924.241439477588;8554.486730796172;8581.01318348398;8586.484810911365;8718.82562348762;8726.781830454163;4760.143174228672;4843.660943733976;4858.562729354375;4861.562459378673;5006.6495345325475;1812.7734033245845;2016.102834238779;2043.6321462136957;2086.0612724274138;2089.937301880946;991.5240684471349;996.6013594562088;1001.7495626093477;1036.2021243642353;1052.9894701052892;20857.165713371294;21271.36185914425;21293.68059479109;21471.88123331606;21488.892555537434;1028.681108856257;1031.3141760729181;1046.7278507588082;1065.8454524094118;1071.9249652524404;10853;11083.612073577386;11093.168012399103;11114.777704445865;11256.380899050631;668335;673425;675900;677281;690897;142752;146556.53443465428;147994;149233;150003;981.5043403081398;1017.1913328903391;1039.1167507618616;1042.3641578637025;1045.487711021594;18754.437366879014;19198.232070717015;19620.705689414386;19628;19652.803471965668;57772;58711;60665.090023648794;61406.77186456157;61887.69056154691;86304.56847715973;86311.56844215738;87867;89283.10716893007;89386.65920011075;6858.759943402157;7282.380997615051;7497.250274972459;8044.155363686728;8480.15198480147;9015.602581599862;11659.941700291443;12195.695107622381;12596.811047834473;12763.361831908553;10598.841017384899;10616.469176540988;10682.679519614425;10708.785824283626;10897.836532451862;10534;10705.304155229886;10728.034476897117;11012.449377530931;11030.889691103308;10754.462276886281;10884.292520986111;10907.836382454429;11238.269512481662;11453.828192577284;5765.3946335634155;7819.491733037335;8085.434019618806;8280.461769984931;8375.832483350177;10931.508082136164;11193.384363860067;11226.831597526207;11331.830022549504;11371.374574398487 09:23:30 INFO - PROCESS | 1644 | |3;mootools.html;1234.8950339221167;1317.5520323090147;1401.3063533550887;1441.2937660546336;1456.970860582788;7447.069116360455;7684.96157519213;8067.878981815277;8533.232009119185;8534.658613655462;6607.834804129887;6977.616231107287;8146.2261085196815;8280.296174825147;8367.079621241659;2272.125231785766;2281.555096756133;2315.235972129193;2321.245595181564;2326.9418264543438;2074.1184996376555;2314.8611083335054;2359.752226016269;2359.976400235993;2362.6692263083182;2079.552896127332;2234.72065991751;2310.9884450577724;2316.2587971849016;2317.142657216824;2275.0444813178506;2280.486890449652;2297.356740112763;2302.4243939015246;2308.68832707584;2281.3498153026308;2315.3979965209;2335.077644330497;2341.285907798121;2347.119830063726;8350.457220280663;8443.535605541692;8456.577171141418;8462.449940753893;8494.150584941519;5093.974530127362;5230.947690523084;5250.553702935255;5254.290670759475;5263.421023687391;3447.293304872495;3448.8448019839166;3471.5313432686517;3482.9303413931834;3550.0769799852224;1168;1172.9882701173049;1194.75507520958;1205.9698507537382;1228.969275768104;9742.95128524353;9991.85093714225;10798;10816.72958176044;10825.458727063615;4312.935305970413;4608.239640459286;4646.883827904262;4681.133990211822;4684.156851766715;9378.5310734463;9482.241420686329;9484.810303793885;9493.050694930589;9539.856902146474;5106.693598384109;5205.323307969939;5215.217717342429;5219.1388420910125;5225.399079105951;4529.660275479352;4574.725516469022;4761.262004389325;4772.5227477252;4798.184308667535;3868.574456809749;3887.5918028607157;3930.9213815723524;3959.208158368338;3970.1265721541213;1173.8708742038368;1176.664650574582;1184.2716729211472;1187.6793265818353;1218.2690385768644;673.8888083466271;704.8519810839679;790.4506368074133;808.7290757596154;853.1425916953422;5438.864028399322;5454.509094181542;5496.395396506381;5712.343080545707;5761.222234998306;959.5442164971582;994.9801003979879;1006.2754816532085;1017.1455976979373;1018.760591261053;3420.572428446444;3665.138692407282;3711.2763011212555;3725.4970578972034;3834.9233015340096;9659;9759.26805489591;9960.252981026451;9965.850512242178;9971.950140249251;9730.513474326255;9796.167325777245;9838.3113182078;9932.80134397308;9933.205343572497;7801.336886364609;7868.606569671608;7876.094249161305;7890.461360034853;7914.010748656418;4347;5306.8938621228135;5352.812651557177;5412.702301373305;5527.391986881359;9129.634814607341;9174.770630734285;9181.678641247525;9266.953665231631;9275.21160701348;7794.493357931717;7899.565523896241;8186.345092392475;9151.816963660822;9461.952690236505;7043.929560704328;7213.71145154209;7234.782956511313;7255.854882902207;7263.745768898063;6559.573627714183;7740.496867703581;7764.22357764219;7850.293473587404;7857.2928436441;6299.370062993848;7092;7114.395276401564;7121.786346409616;7164.605946672983;5876.559258055578;7013.2986701329455;7050.541714788523;7110.7511237106455;7119.323664251888;5151.484851514969;6396.200474940632;6453.064305675557;6469.77355792545;6479.319671434431;4809.182438985451;4858.392700912386;4990.201567749143;5024.874378140576;5128.53843154103;4902.926456103232;4959.008198360414;5006.824761133343;5026.497350265091;5052.938882834499 09:23:30 INFO - PROCESS | 1644 | |4;prototype.html;354.3161697923009;356.3123172277505;365.6307129798903;368.554049599984;370.9888703338899;5015.8212819987275;5272.472752724726;5295.894082118355;5407.864803379913;5580.414056524062;5330.387005494369;5503.504684578387;5524;5550.2507161533185;5554.444555544444;3827.2875310948334;3973.8609148679802;4152.647025002879;4270.786460676969;4311.698181127323;3363.1423986883433;4183;4199.286121359361;4293.484781826185;4315.352697095429;4125.236831186226;4145.419641250227;4149.730267532601;4170.666346692273;4171.749695018293;4127.926739047839;4133;4140.91718165638;4145.233131870598;4158.8336466541305;2905.738483536492;3463.79217246966;3572.3034008368377;3575.4804208211667;3615.385384484644;4796.872734907294;5235.476452354772;5246.24250875957;5375.838724838261;5518.641288316287;4772;4784.258439941815;4790.736509491977;4795.904081918342;4847;4911.754412279408;5784.392638772911;5837.970810145965;5863.325717542494;5872.618279811799;3344.698977092049;3682.6133256008;3910.9689990257493;3940.290747665419;3963.9405408918888;5229.738513074331;5411.078017870345;5917.378675239081;6007.596961215505;6102.908456373158;3401.4727717203873;3678.2643471305846;3995.1809878975323;4031.637152656275;4066.9389959150635;3195.0890116635974;3701.6298370162767;3763.814398464475;3952.2885880541203;4051.2910240707756;4693.193120648507;4709;4716.716996980193;4725.480197178307;4733.414306207459;4999.375078115236;5483.561315094783;5894.469497745224;5925.644461332247;5972.492338151219;1145.9083273338113;1187.5487314820314;1208.8368070310396;1302.9543965961145;1307.7253776706998;828.7596596986926;854.1842540373927;881.2333270055093;887.2990337633327;889.8309321228945;4738.50245724194;5184.9740751296;5222.791088356423;5269.420363759983;5291.656042357235;5724.341700704473;5732.197492351074;5741.913871291934;5802.448767367094;5910.290765108129;4415.211839205127;4415.558444155623;4831.347768051268;5172.689478733613;5176.767045482962;2510.661060756811;2671.597411359052;2966.777491688089;2998.4902566563737;3024.228821650465;5257;5324.787008519694;5914.9112763308585;5946.048632218911;5964.522838172936;5074.644774865797;5217;5346.786128554814;5848.607807471656;5897.252711567229;5030.673006254582;5346.652467589594;5611.186377975171;5779.7230049762875;6062.636241825593;4500.052465635014;5237.8428647140645;5411.918821217763;5500.697461639648;5788.855278618068;4579.5878370946775;4765.999140180601;5020.1716716742785;5586.273784408001;5673.035583950656;5325.707086110301;5771.826845194651;5904.940950590611;5932.49573786233;5997.322088770766;3006.5339872319823;3780.9432858507694;3819.8854034379015;3847.5190601174854;3882.844686212633;5105.540501354896;5635.8591035224445;5656.971715141398;5690.687012214368;5691.0365923756235;4498.740352701249;4924.926126108039;4937.629677774253;5687.488126068673;5792.913106303325;4657.254839225708;4887.0451819272575;5231.267622532772;5557.944420555905;5803.970980145073;4801.399825021872;4847.660663753644;5106.621212272591;5307.814226502054;5676.3538573814785;5830.941690583039;5868.882622347614;5872.150272664097;5876.382979787061;5917.615355001911;4843.95156048435;5725.885482290248;5768.94231057684;5843;5874.17761513397 09:23:30 INFO - PROCESS | 1644 | |5;yui.html;701.7159692638456;717.9389751871091;728.2499026003215;795.6538905576072;819.8565251081061;14518.491852785142;15513.224338783071;15536.766948495753;15590.220488975534;15613.219339033045;14312.856871431282;14371.209583472904;14373.281335933214;14376.856231437683;14460.638484037878;664.4883439751388;685.7428464325877;823.7199352220244;832.5587438657521;834.2783492279165;812.3379445751693;842.4313588327885;849.2399302624148;852.6248450681711;853.5902766672001;895.4582477601056;899.5502248875562;899.5637115998768;900.5317235037777;901.8557030875062;862.3015357560396;885.8494056010494;889.89321281446;893.2362829780509;893.3389291923929;643.5881036136876;653.5719103986896;663.5355251324045;666.9795213323623;667.2026927821256;14578.271086445635;14979.550613481613;15048.32282547288;15094.924525377413;15146.01550899199;13931.791023134661;14001.5099471518;14070.929645351707;14278.857211427856;14284.57146285613;11475.655730328104;11575.826362604568;11688.298702077902;11933.34366609836;12016.339101349422;3595.8022308773006;3627.7460577759566;3667.8716244931297;3698.0939669780805;3827.483289755903;111857;113576;114955;116420.41789790997;117002.41498792451;10630.787384252271;10634.361938283728;10649.787004259872;10928.890711092787;10941.28881622692;22142.003609837273;22226.555468890532;22276;22295.665565016214;22445;7675.385969122457;7713.807154821175;7741.574213418204;7753.263439973193;7866.881996770053;3653.791825062069;3744.2324323513612;4275.337322714984;4287.335463003239;4384.210842048462;3491.319192757388;3574.3566158091794;3591.5151454553825;3592.1019745063736;3613.4218525035867;537.4088502647135;591.73667717866;597.172915512019;624.9281332646713;643.793985924509;664.4053572052985;664.9002649602598;685.9725610975505;699.576756062585;726.3136336162381;10092.394456332644;10113.190944724405;10229;10434.634787782543;10446.529906154241;548.6104865545508;682.3654001778294;682.6484360554318;687.9380855723009;688.6246995387526;4451.310046942729;4958.6776859504735;4964.826231081895;4978.377702787152;4989.800407983713;100980;106305.46847265714;108782.91217087727;114486;115482;91358.08641913629;99320;100401;101688.47467287997;102828.48585757024;72910.27089729141;73400.63299683467;74752.50494990179;75046.24953750502;75209.6239518799;10485.790284194425;10631.255812093079;10837.987159914504;11136.830632783442;11143.498542565767;41890.79054604707;51179.744101279255;51717.70705732236;51724.22413663873;52435.73782131218;75983.24016759983;76728;76784;77913.61043194974;77982.22017779977;12753.74492510126;12895.677608059874;13131.409086591317;13216.471341146247;13312.201267923956;12052.758944820878;14743.262836857899;14813.259337033322;14943.327550260046;15026.924865375604;13500.235948637552;13591.728165436438;13667.248301343125;13844.792328114885;13996.790048149081;12555.246685198917;12963.546275880299;12973.156744811557;13145.934270328587;13319.933400332937;10594.31136976094;13529.323533823466;13620.18278903269;13646.795298070736;13780.65548361299;9086.863697044417;11112.22214444981;11117.555297788322;11223.428719979373;11345.149113816662;8911.420757650732;9659.46872921996;10952.890471095187;11018.50416731265;11246.437678116225 09:23:30 INFO - PROCESS | 1644 | __end_tp_report 09:23:30 INFO - PROCESS | 1644 | __start_cc_report 09:23:30 INFO - PROCESS | 1644 | _x_x_mozilla_cycle_collect,394 09:23:30 INFO - PROCESS | 1644 | __end_cc_report 09:23:30 INFO - PROCESS | 1644 | __startTimestamp1488648210051__endTimestamp 09:23:30 INFO - PROCESS | 1644 | 09:23:30 INFO - PROCESS | 1644 | ------- Summary: start ------- 09:23:30 INFO - PROCESS | 1644 | Number of tests: 6 09:23:30 INFO - PROCESS | 1644 | 09:23:30 INFO - PROCESS | 1644 | [#0] dojo.html Cycles:165 Average:5798.23 Median:6054.18 stddev:650.86 (10.8%) stddev-sans-first:625.38 09:23:30 INFO - PROCESS | 1644 | Values: 3413.3 3430.3 3446.6 3446.9 3459.1 5731.7 5881.0 5883.8 5889.7 5903.6 5890.1 5952.0 5967.2 5971.7 5971.7 5184.2 5328.7 5377.5 5393.9 5423.2 5400.9 5405.2 5406.9 5417.9 5431.9 4421.3 5211.9 5271.8 5410.5 5414.4 5283.0 5326.2 5348.3 5382.9 5409.7 5215.6 5318.8 5346.0 5350.1 5358.0 5560.3 5833.3 5896.6 5928.9 6004.4 6160.4 6195.9 6203.2 6272.4 6278.4 6250.2 6252.6 6261.6 6267.9 6290.7 5964.0 5967.9 5968.1 5982.9 6012.6 6166.7 6206.4 6214.9 6249.3 6254.9 6200.5 6203.6 6254.7 6265.8 6272.7 6268.8 6277.7 6284.4 6286.2 6286.8 6135.6 6180.2 6190.4 6191.1 6216.0 6241.1 6268.3 6277.2 6296.0 6296.5 5072.0 5075.7 5079.2 5083.2 5103.4 3795.5 4373.0 4380.8 4437.1 4512.1 4224.9 5929.7 5953.3 5992.4 6053.6 5266.8 6243.8 6273.0 6277.2 6289.2 5720.5 5844.8 5900.1 6012.0 6022.5 5504.0 5526.1 5528.6 5537.4 5555.1 5580.6 6170.4 6391.7 6401.6 6428.9 5050.9 5464.5 6192.2 6244.5 6261.1 6349.6 6365.0 6389.0 6398.9 6431.7 6212.1 6217.8 6217.9 6234.7 6286.9 6133.8 6197.0 6197.2 6226.5 6244.2 6116.3 6210.2 6224.1 6231.9 6233.1 6094.9 6207.8 6224.9 6240.6 6252.2 5852.0 5890.4 5937.9 6054.2 6077.8 6129.5 6129.8 6144.5 6236.9 6246.4 6085.6 6097.1 6097.2 6110.5 6110.8 09:23:30 INFO - PROCESS | 1644 | 09:23:30 INFO - PROCESS | 1644 | [#1] ext.html Cycles:180 Average:67766.60 Median:35243.65 stddev:150355.32 (426.6%) stddev-sans-first:150692.16 09:23:30 INFO - PROCESS | 1644 | Values: 451.6 456.6 497.3 499.9 541.6 38985.0 41623.2 41658.6 41672.6 41913.8 42771.8 43098.6 43232.6 43303.6 43310.0 8295.0 8532.5 9005.6 9227.4 9257.7 11099.3 11417.9 11422.6 11566.0 11578.0 11288.9 11320.0 11356.7 11365.0 11366.7 11503.3 11527.7 11560.7 11561.0 11568.6 11342.8 11365.5 11396.7 11443.5 11445.1 41595.8 41767.0 41821.0 41939.4 42033.0 3921.4 3933.2 3990.4 3999.8 4038.2 25907.9 30329.4 30397.5 30732.9 31193.7 2471.9 3108.9 3132.5 3165.7 3274.3 268103.0 308869.9 330501.0 338748.0 341197.0 66126.0 66401.7 66736.0 66814.7 66858.0 67352.7 67922.3 67964.7 68028.3 68124.7 16551.5 16678.6 16746.7 16782.0 16803.7 11516.8 11717.4 12803.7 12872.4 13131.8 20796.8 22152.3 22239.9 22255.0 22271.0 7647.7 8896.8 9116.3 9121.6 9155.4 691.4 821.7 859.2 862.7 871.9 28896.3 29420.3 30446.0 30544.4 31029.5 983.2 1014.5 1023.0 1033.2 1036.8 13715.2 14497.3 14816.9 14947.7 15084.8 669129.7 674269.0 675582.0 676326.0 679864.0 55065.7 55575.7 55608.2 55670.4 55787.2 1295.3 1342.3 1352.8 1355.3 1381.8 27628.4 27855.0 28185.9 28604.7 28658.1 41403.4 49061.8 49181.8 49182.8 49890.0 616347.0 620872.0 621616.0 621848.0 622065.0 25848.6 26205.7 26845.5 26860.3 27237.5 43938.6 44145.8 45234.5 45293.8 45361.0 35576.0 36099.8 36192.6 36788.1 37211.0 36613.6 36829.6 37114.6 37120.0 37427.3 28936.6 35195.6 35291.6 35433.0 36228.6 31106.1 32537.8 32704.3 32721.5 32866.8 21256.9 23142.8 23976.7 24673.5 24781.8 09:23:30 INFO - PROCESS | 1644 | 09:23:30 INFO - PROCESS | 1644 | [#2] jquery.html Cycles:180 Average:39583.23 Median:21382.78 stddev:113858.78 (532.5%) stddev-sans-first:114140.15 09:23:30 INFO - PROCESS | 1644 | Values: 388.9 391.1 401.1 407.6 422.8 16004.5 19445.1 20377.6 20818.6 20846.6 21273.4 21292.6 21864.9 22690.1 22967.0 5137.7 5156.2 5701.3 5967.1 5992.3 5061.2 5682.3 5791.2 5853.9 5880.5 5803.4 5812.9 5817.9 5819.6 5825.9 4910.0 4945.8 4967.8 6831.7 7167.4 7414.2 7668.2 7707.0 7725.2 7748.0 25264.0 28252.9 29086.9 29136.4 29786.0 2808.3 2848.8 2858.2 2878.0 2883.3 15726.6 16345.9 16853.7 16870.3 17509.3 1894.0 1921.8 1979.4 1987.0 1988.3 143546.0 153795.0 159722.0 161722.2 163731.0 10206.3 10717.3 10734.9 10782.8 10881.6 12854.9 12929.3 12963.4 14149.7 14657.6 8434.5 8687.9 8753.2 8793.0 8924.2 8554.5 8581.0 8586.5 8718.8 8726.8 4760.1 4843.7 4858.6 4861.6 5006.6 1812.8 2016.1 2043.6 2086.1 2089.9 991.5 996.6 1001.7 1036.2 1053.0 20857.2 21271.4 21293.7 21471.9 21488.9 1028.7 1031.3 1046.7 1065.8 1071.9 10853.0 11083.6 11093.2 11114.8 11256.4 668335.0 673425.0 675900.0 677281.0 690897.0 142752.0 146556.5 147994.0 149233.0 150003.0 981.5 1017.2 1039.1 1042.4 1045.5 18754.4 19198.2 19620.7 19628.0 19652.8 57772.0 58711.0 60665.1 61406.8 61887.7 86304.6 86311.6 87867.0 89283.1 89386.7 6858.8 7282.4 7497.3 8044.2 8480.2 9015.6 11659.9 12195.7 12596.8 12763.4 10598.8 10616.5 10682.7 10708.8 10897.8 10534.0 10705.3 10728.0 11012.4 11030.9 10754.5 10884.3 10907.8 11238.3 11453.8 5765.4 7819.5 8085.4 8280.5 8375.8 10931.5 11193.4 11226.8 11331.8 11371.4 09:23:30 INFO - PROCESS | 1644 | 09:23:30 INFO - PROCESS | 1644 | [#3] mootools.html Cycles:180 Average:5349.03 Median:5252.42 stddev:2895.48 (55.1%) stddev-sans-first:2887.09 09:23:30 INFO - PROCESS | 1644 | Values: 1234.9 1317.6 1401.3 1441.3 1457.0 7447.1 7685.0 8067.9 8533.2 8534.7 6607.8 6977.6 8146.2 8280.3 8367.1 2272.1 2281.6 2315.2 2321.2 2326.9 2074.1 2314.9 2359.8 2360.0 2362.7 2079.6 2234.7 2311.0 2316.3 2317.1 2275.0 2280.5 2297.4 2302.4 2308.7 2281.3 2315.4 2335.1 2341.3 2347.1 8350.5 8443.5 8456.6 8462.4 8494.2 5094.0 5230.9 5250.6 5254.3 5263.4 3447.3 3448.8 3471.5 3482.9 3550.1 1168.0 1173.0 1194.8 1206.0 1229.0 9743.0 9991.9 10798.0 10816.7 10825.5 4312.9 4608.2 4646.9 4681.1 4684.2 9378.5 9482.2 9484.8 9493.1 9539.9 5106.7 5205.3 5215.2 5219.1 5225.4 4529.7 4574.7 4761.3 4772.5 4798.2 3868.6 3887.6 3930.9 3959.2 3970.1 1173.9 1176.7 1184.3 1187.7 1218.3 673.9 704.9 790.5 808.7 853.1 5438.9 5454.5 5496.4 5712.3 5761.2 959.5 995.0 1006.3 1017.1 1018.8 3420.6 3665.1 3711.3 3725.5 3834.9 9659.0 9759.3 9960.3 9965.9 9972.0 9730.5 9796.2 9838.3 9932.8 9933.2 7801.3 7868.6 7876.1 7890.5 7914.0 4347.0 5306.9 5352.8 5412.7 5527.4 9129.6 9174.8 9181.7 9267.0 9275.2 7794.5 7899.6 8186.3 9151.8 9462.0 7043.9 7213.7 7234.8 7255.9 7263.7 6559.6 7740.5 7764.2 7850.3 7857.3 6299.4 7092.0 7114.4 7121.8 7164.6 5876.6 7013.3 7050.5 7110.8 7119.3 5151.5 6396.2 6453.1 6469.8 6479.3 4809.2 4858.4 4990.2 5024.9 5128.5 4902.9 4959.0 5006.8 5026.5 5052.9 09:23:30 INFO - PROCESS | 1644 | 09:23:30 INFO - PROCESS | 1644 | [#4] prototype.html Cycles:180 Average:4556.40 Median:5052.66 stddev:1406.84 (27.8%) stddev-sans-first:1374.97 09:23:30 INFO - PROCESS | 1644 | Values: 354.3 356.3 365.6 368.6 371.0 5015.8 5272.5 5295.9 5407.9 5580.4 5330.4 5503.5 5524.0 5550.3 5554.4 3827.3 3973.9 4152.6 4270.8 4311.7 3363.1 4183.0 4199.3 4293.5 4315.4 4125.2 4145.4 4149.7 4170.7 4171.7 4127.9 4133.0 4140.9 4145.2 4158.8 2905.7 3463.8 3572.3 3575.5 3615.4 4796.9 5235.5 5246.2 5375.8 5518.6 4772.0 4784.3 4790.7 4795.9 4847.0 4911.8 5784.4 5838.0 5863.3 5872.6 3344.7 3682.6 3911.0 3940.3 3963.9 5229.7 5411.1 5917.4 6007.6 6102.9 3401.5 3678.3 3995.2 4031.6 4066.9 3195.1 3701.6 3763.8 3952.3 4051.3 4693.2 4709.0 4716.7 4725.5 4733.4 4999.4 5483.6 5894.5 5925.6 5972.5 1145.9 1187.5 1208.8 1303.0 1307.7 828.8 854.2 881.2 887.3 889.8 4738.5 5185.0 5222.8 5269.4 5291.7 5724.3 5732.2 5741.9 5802.4 5910.3 4415.2 4415.6 4831.3 5172.7 5176.8 2510.7 2671.6 2966.8 2998.5 3024.2 5257.0 5324.8 5914.9 5946.0 5964.5 5074.6 5217.0 5346.8 5848.6 5897.3 5030.7 5346.7 5611.2 5779.7 6062.6 4500.1 5237.8 5411.9 5500.7 5788.9 4579.6 4766.0 5020.2 5586.3 5673.0 5325.7 5771.8 5904.9 5932.5 5997.3 3006.5 3780.9 3819.9 3847.5 3882.8 5105.5 5635.9 5657.0 5690.7 5691.0 4498.7 4924.9 4937.6 5687.5 5792.9 4657.3 4887.0 5231.3 5557.9 5804.0 4801.4 4847.7 5106.6 5307.8 5676.4 5830.9 5868.9 5872.2 5876.4 5917.6 4844.0 5725.9 5768.9 5843.0 5874.2 09:23:30 INFO - PROCESS | 1644 | 09:23:30 INFO - PROCESS | 1644 | [#5] yui.html Cycles:180 Average:21329.79 Median:3532.84 stddev:31554.25 (893.2%) stddev-sans-first:31604.75 09:23:30 INFO - PROCESS | 1644 | Values: 701.7 717.9 728.2 795.7 819.9 14518.5 15513.2 15536.8 15590.2 15613.2 14312.9 14371.2 14373.3 14376.9 14460.6 664.5 685.7 823.7 832.6 834.3 812.3 842.4 849.2 852.6 853.6 895.5 899.6 899.6 900.5 901.9 862.3 885.8 889.9 893.2 893.3 643.6 653.6 663.5 667.0 667.2 14578.3 14979.6 15048.3 15094.9 15146.0 13931.8 14001.5 14070.9 14278.9 14284.6 11475.7 11575.8 11688.3 11933.3 12016.3 3595.8 3627.7 3667.9 3698.1 3827.5 111857.0 113576.0 114955.0 116420.4 117002.4 10630.8 10634.4 10649.8 10928.9 10941.3 22142.0 22226.6 22276.0 22295.7 22445.0 7675.4 7713.8 7741.6 7753.3 7866.9 3653.8 3744.2 4275.3 4287.3 4384.2 3491.3 3574.4 3591.5 3592.1 3613.4 537.4 591.7 597.2 624.9 643.8 664.4 664.9 686.0 699.6 726.3 10092.4 10113.2 10229.0 10434.6 10446.5 548.6 682.4 682.6 687.9 688.6 4451.3 4958.7 4964.8 4978.4 4989.8 100980.0 106305.5 108782.9 114486.0 115482.0 91358.1 99320.0 100401.0 101688.5 102828.5 72910.3 73400.6 74752.5 75046.2 75209.6 10485.8 10631.3 10838.0 11136.8 11143.5 41890.8 51179.7 51717.7 51724.2 52435.7 75983.2 76728.0 76784.0 77913.6 77982.2 12753.7 12895.7 13131.4 13216.5 13312.2 12052.8 14743.3 14813.3 14943.3 15026.9 13500.2 13591.7 13667.2 13844.8 13996.8 12555.2 12963.5 12973.2 13145.9 13319.9 10594.3 13529.3 13620.2 13646.8 13780.7 9086.9 11112.2 11117.6 11223.4 11345.1 8911.4 9659.5 10952.9 11018.5 11246.4 09:23:30 INFO - PROCESS | 1644 | -------- Summary: end -------- 09:23:30 INFO - PROCESS | 1644 | 09:23:30 INFO - TEST-INFO | 1644: exit 0 09:23:30 INFO - TEST-OK | dromaeo_css | took 1115456ms 09:23:30 INFO - TEST-START | kraken 09:23:30 INFO - operating with platform_type : mac_ 09:23:30 INFO - Initialising browser for kraken test... 09:23:30 INFO - TEST-INFO | started process 1651 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/sw/s26whz2n6pbfv14p7p8hkdxw00000w/T/tmptn1RId/profile http://localhost:49241/getInfo.html) 09:23:33 INFO - PROCESS | 1651 | 2017-03-04 09:23:33.307 plugin-container[1652:10695] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4747, name = 'com.apple.tsm.portname' 09:23:33 INFO - PROCESS | 1651 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:23:33 INFO - PROCESS | 1651 | 2017-03-04 09:23:33.310 plugin-container[1652:10695] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x900b, name = 'com.apple.CFPasteboardClient' 09:23:33 INFO - PROCESS | 1651 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:23:33 INFO - PROCESS | 1651 | 2017-03-04 09:23:33.310 plugin-container[1652:10695] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:23:33 INFO - PROCESS | 1651 | __metrics Screen width/height:1600/1200 09:23:33 INFO - PROCESS | 1651 | colorDepth:24 09:23:33 INFO - PROCESS | 1651 | Browser inner width/height: 1024/689 09:23:33 INFO - PROCESS | 1651 | __metrics 09:23:33 ERROR - PROCESS | 1651 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3277: TypeError: this.tabs is undefined 09:23:34 INFO - TEST-INFO | 1651: exit 0 09:23:34 INFO - Browser initialized. 09:23:34 INFO - Running cycle 1/1 for kraken test... 09:23:34 INFO - TEST-INFO | started process 1653 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/sw/s26whz2n6pbfv14p7p8hkdxw00000w/T/tmptn1RId/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:23:36 INFO - PROCESS | 1653 | 2017-03-04 09:23:36.619 plugin-container[1654:10821] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x863f, name = 'com.apple.tsm.portname' 09:23:36 INFO - PROCESS | 1653 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:23:36 INFO - PROCESS | 1653 | 2017-03-04 09:23:36.619 plugin-container[1654:10821] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4623, name = 'com.apple.CFPasteboardClient' 09:23:36 INFO - PROCESS | 1653 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:23:36 INFO - PROCESS | 1653 | 2017-03-04 09:23:36.619 plugin-container[1654:10821] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:23:46 INFO - PROCESS | 1653 | RSS: Main: 218914816 09:23:46 INFO - PROCESS | 1653 | 09:23:49 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-beat-detection) 09:23:50 INFO - PROCESS | 1653 | RSS: Main: 233308160 09:23:50 INFO - PROCESS | 1653 | 09:23:53 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-dft) 09:23:53 INFO - PROCESS | 1653 | RSS: Main: 241442816 09:23:53 INFO - PROCESS | 1653 | 09:23:57 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-fft) 09:23:58 INFO - PROCESS | 1653 | RSS: Main: 245100544 09:23:58 INFO - PROCESS | 1653 | 09:23:59 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-oscillator) 09:24:00 INFO - PROCESS | 1653 | RSS: Main: 246329344 09:24:00 INFO - PROCESS | 1653 | 09:24:02 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49241/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:24:03 INFO - PROCESS | 1653 | RSS: Main: 246702080 09:24:03 INFO - PROCESS | 1653 | 09:24:05 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49241/tests/kraken/driver.html?testName=imaging-darkroom) 09:24:06 INFO - PROCESS | 1653 | RSS: Main: 251146240 09:24:06 INFO - PROCESS | 1653 | 09:24:09 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49241/tests/kraken/driver.html?testName=imaging-desaturate) 09:24:10 INFO - PROCESS | 1653 | RSS: Main: 249991168 09:24:10 INFO - PROCESS | 1653 | 09:24:13 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49241/tests/kraken/driver.html?testName=json-parse-financial) 09:24:14 INFO - PROCESS | 1653 | RSS: Main: 250191872 09:24:14 INFO - PROCESS | 1653 | 09:24:15 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49241/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:24:16 INFO - PROCESS | 1653 | RSS: Main: 249065472 09:24:16 INFO - PROCESS | 1653 | 09:24:18 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:24:19 INFO - PROCESS | 1653 | RSS: Main: 249122816 09:24:19 INFO - PROCESS | 1653 | 09:24:21 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:24:21 INFO - PROCESS | 1653 | RSS: Main: 249126912 09:24:21 INFO - PROCESS | 1653 | 09:24:24 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:24:25 INFO - PROCESS | 1653 | RSS: Main: 249417728 09:24:25 INFO - PROCESS | 1653 | 09:24:28 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:24:29 INFO - PROCESS | 1653 | RSS: Main: 249323520 09:24:29 INFO - PROCESS | 1653 | 09:24:30 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49241/tests/kraken/driver.html?testName=ai-astar) 09:24:30 INFO - PROCESS | 1653 | RSS: Main: 251260928 09:24:30 INFO - PROCESS | 1653 | 09:24:30 INFO - PROCESS | 1653 | __start_tp_report 09:24:30 INFO - PROCESS | 1653 | _x_x_mozilla_page_load 09:24:30 INFO - PROCESS | 1653 | _x_x_mozilla_page_load_details 09:24:30 INFO - PROCESS | 1653 | |i|pagename|runs| 09:24:30 INFO - PROCESS | 1653 | |0;ai-astar;104;143;163;117;119;120;117;126;177;163 09:24:30 INFO - PROCESS | 1653 | |1;audio-beat-detection;154;147;143;182;122;119;134;151;161;146 09:24:30 INFO - PROCESS | 1653 | |2;audio-dft;194;202;203;207;183;241;278;226;194;195 09:24:30 INFO - PROCESS | 1653 | |3;audio-fft;82;58;60;88;53;77;50;52;78;68 09:24:30 INFO - PROCESS | 1653 | |4;audio-oscillator;81;72;88;71;70;71;80;74;56;82 09:24:30 INFO - PROCESS | 1653 | |5;imaging-gaussian-blur;89;90;92;93;105;89;92;91;91;89 09:24:30 INFO - PROCESS | 1653 | |6;imaging-darkroom;101;147;173;133;102;103;105;173;164;104 09:24:30 INFO - PROCESS | 1653 | |7;imaging-desaturate;77;155;144;95;94;76;146;150;93;93 09:24:30 INFO - PROCESS | 1653 | |8;json-parse-financial;61;61;59;73;79;62;62;63;62;62 09:24:30 INFO - PROCESS | 1653 | |9;json-stringify-tinderbox;69;73;73;76;72;67;69;69;69;70 09:24:30 INFO - PROCESS | 1653 | |10;stanford-crypto-aes;86;87;88;86;85;81;91;104;70;98 09:24:30 INFO - PROCESS | 1653 | |11;stanford-crypto-ccm;140;142;135;138;137;152;133;142;183;146 09:24:30 INFO - PROCESS | 1653 | |12;stanford-crypto-pbkdf2;173;169;168;168;170;216;210;172;170;175 09:24:30 INFO - PROCESS | 1653 | |13;stanford-crypto-sha256-iterative;65;68;66;67;66;66;64;66;66;64 09:24:30 INFO - PROCESS | 1653 | __end_tp_report 09:24:30 INFO - PROCESS | 1653 | __start_cc_report 09:24:30 INFO - PROCESS | 1653 | _x_x_mozilla_cycle_collect,985 09:24:30 INFO - PROCESS | 1653 | __end_cc_report 09:24:30 INFO - PROCESS | 1653 | __startTimestamp1488648270951__endTimestamp 09:24:30 INFO - PROCESS | 1653 | 09:24:30 INFO - PROCESS | 1653 | ------- Summary: start ------- 09:24:30 INFO - PROCESS | 1653 | Number of tests: 14 09:24:30 INFO - PROCESS | 1653 | 09:24:30 INFO - PROCESS | 1653 | [#0] ai-astar Cycles:10 Average:134.90 Median:134.50 stddev:24.87 (18.5%) stddev-sans-first:23.73 09:24:30 INFO - PROCESS | 1653 | Values: 104.0 143.0 163.0 117.0 119.0 120.0 117.0 126.0 177.0 163.0 09:24:30 INFO - PROCESS | 1653 | 09:24:30 INFO - PROCESS | 1653 | [#1] audio-beat-detection Cycles:10 Average:145.90 Median:149.00 stddev:18.47 (12.4%) stddev-sans-first:19.35 09:24:30 INFO - PROCESS | 1653 | Values: 154.0 147.0 143.0 182.0 122.0 119.0 134.0 151.0 161.0 146.0 09:24:30 INFO - PROCESS | 1653 | 09:24:30 INFO - PROCESS | 1653 | [#2] audio-dft Cycles:10 Average:212.30 Median:205.00 stddev:28.63 (14.0%) stddev-sans-first:29.59 09:24:30 INFO - PROCESS | 1653 | Values: 194.0 202.0 203.0 207.0 183.0 241.0 278.0 226.0 194.0 195.0 09:24:30 INFO - PROCESS | 1653 | 09:24:30 INFO - PROCESS | 1653 | [#3] audio-fft Cycles:10 Average:66.60 Median:72.50 stddev:13.85 (19.1%) stddev-sans-first:13.52 09:24:30 INFO - PROCESS | 1653 | Values: 82.0 58.0 60.0 88.0 53.0 77.0 50.0 52.0 78.0 68.0 09:24:30 INFO - PROCESS | 1653 | 09:24:30 INFO - PROCESS | 1653 | [#4] audio-oscillator Cycles:10 Average:74.50 Median:77.00 stddev:8.85 (11.5%) stddev-sans-first:9.07 09:24:30 INFO - PROCESS | 1653 | Values: 81.0 72.0 88.0 71.0 70.0 71.0 80.0 74.0 56.0 82.0 09:24:30 INFO - PROCESS | 1653 | 09:24:30 INFO - PROCESS | 1653 | [#5] imaging-gaussian-blur Cycles:10 Average:92.10 Median:91.00 stddev:4.75 (5.2%) stddev-sans-first:4.90 09:24:30 INFO - PROCESS | 1653 | Values: 89.0 90.0 92.0 93.0 105.0 89.0 92.0 91.0 91.0 89.0 09:24:30 INFO - PROCESS | 1653 | 09:24:30 INFO - PROCESS | 1653 | [#6] imaging-darkroom Cycles:10 Average:130.50 Median:140.00 stddev:31.28 (22.3%) stddev-sans-first:31.30 09:24:30 INFO - PROCESS | 1653 | Values: 101.0 147.0 173.0 133.0 102.0 103.0 105.0 173.0 164.0 104.0 09:24:30 INFO - PROCESS | 1653 | 09:24:30 INFO - PROCESS | 1653 | [#7] imaging-desaturate Cycles:10 Average:112.30 Median:85.00 stddev:32.19 (37.9%) stddev-sans-first:31.51 09:24:30 INFO - PROCESS | 1653 | Values: 77.0 155.0 144.0 95.0 94.0 76.0 146.0 150.0 93.0 93.0 09:24:30 INFO - PROCESS | 1653 | 09:24:30 INFO - PROCESS | 1653 | [#8] json-parse-financial Cycles:10 Average:64.40 Median:62.00 stddev:6.36 (10.3%) stddev-sans-first:6.63 09:24:30 INFO - PROCESS | 1653 | Values: 61.0 61.0 59.0 73.0 79.0 62.0 62.0 63.0 62.0 62.0 09:24:30 INFO - PROCESS | 1653 | 09:24:30 INFO - PROCESS | 1653 | [#9] json-stringify-tinderbox Cycles:10 Average:70.70 Median:71.00 stddev:2.71 (3.8%) stddev-sans-first:2.80 09:24:30 INFO - PROCESS | 1653 | Values: 69.0 73.0 73.0 76.0 72.0 67.0 69.0 69.0 69.0 70.0 09:24:30 INFO - PROCESS | 1653 | 09:24:30 INFO - PROCESS | 1653 | [#10] stanford-crypto-aes Cycles:10 Average:87.60 Median:86.50 stddev:9.16 (10.6%) stddev-sans-first:9.69 09:24:30 INFO - PROCESS | 1653 | Values: 86.0 87.0 88.0 86.0 85.0 81.0 91.0 104.0 70.0 98.0 09:24:30 INFO - PROCESS | 1653 | 09:24:30 INFO - PROCESS | 1653 | [#11] stanford-crypto-ccm Cycles:10 Average:144.80 Median:142.00 stddev:14.51 (10.2%) stddev-sans-first:15.28 09:24:30 INFO - PROCESS | 1653 | Values: 140.0 142.0 135.0 138.0 137.0 152.0 133.0 142.0 183.0 146.0 09:24:30 INFO - PROCESS | 1653 | 09:24:30 INFO - PROCESS | 1653 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:179.10 Median:172.50 stddev:18.06 (10.5%) stddev-sans-first:19.02 09:24:30 INFO - PROCESS | 1653 | Values: 173.0 169.0 168.0 168.0 170.0 216.0 210.0 172.0 170.0 175.0 09:24:30 INFO - PROCESS | 1653 | 09:24:30 INFO - PROCESS | 1653 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.80 Median:66.00 stddev:1.23 (1.9%) stddev-sans-first:1.27 09:24:30 INFO - PROCESS | 1653 | Values: 65.0 68.0 66.0 67.0 66.0 66.0 64.0 66.0 66.0 64.0 09:24:30 INFO - PROCESS | 1653 | -------- Summary: end -------- 09:24:30 INFO - PROCESS | 1653 | 09:24:31 INFO - TEST-INFO | 1653: exit 0 09:24:31 INFO - TEST-OK | kraken | took 61165ms 09:24:31 INFO - SUITE-END | took 1176s 09:24:32 INFO - Completed test suite (00:19:36) 09:24:32 INFO - javascript benchmark 09:24:32 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7349.057875992826, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3413.3343997920406, 3430.2796412753337, 3446.5519482467284, 3446.9138271543206, 3459.135216195951, 5731.684757338345, 5880.970830104725, 5883.794067207648, 5889.7349619267125, 5903.616264942786, 5890.087036509337, 5951.970240148793, 5967.194428752117, 5971.701414929247, 5971.731272092756, 5184.196449550307, 5328.680279183242, 5377.516023557879, 5393.865153371177, 5423.159410291391, 5400.91898621521, 5405.18922161675, 5406.864828379283, 5417.918731219035, 5431.918521222185, 4421.336799480087, 5211.86970325743, 5271.841844744664, 5410.5401040911565, 5414.35027796665, 5283.022640811462, 5326.201069839536, 5348.304720386325, 5382.865428364283, 5409.675419474844, 5215.582753379758, 5318.813841515528, 5346.0, 5350.090484617585, 5358.035553600351, 5560.332760068818, 5833.300003999505, 5896.646201227897, 5928.911066334009, 6004.42957918997, 6160.353162917874, 6195.907061394083, 6203.193584833943, 6272.372762723737, 6278.434940855345, 6250.187475628185, 6252.562320637558, 6261.624302541816, 6267.905981410283, 6290.716917738713, 5964.045752679551, 5967.940320596782, 5968.104784282393, 5982.9401705983255, 6012.579119461596, 6166.7224974876235, 6206.441420272107, 6214.875702486015, 6249.25008998923, 6254.906176407358, 6200.534959877937, 6203.565750397429, 6254.655993920287, 6265.780697675651, 6272.717727702265, 6268.780592679234, 6277.7175027123885, 6284.371562843679, 6286.245650521967, 6286.8428289292215, 6135.601185922901, 6180.227471566054, 6190.41191086846, 6191.071339299141, 6216.0, 6241.095041218998, 6268.310485846553, 6277.1525844010475, 6295.968520157461, 6296.5277604178955, 5071.985602879439, 5075.695458272516, 5079.238114282887, 5083.212102124176, 5103.362079740033, 3795.5445346558035, 4372.956270437255, 4380.824767009348, 4437.112577484516, 4512.075024619945, 4224.936625950613, 5929.73316200772, 5953.28560572734, 5992.43071908168, 6053.606515576474, 5266.8156614519075, 6243.7814676486105, 6272.968635156795, 6277.18396608438, 6289.24529056507, 5720.456556627114, 5844.824655260349, 5900.144479050514, 6012.038073908241, 6022.5483088768515, 5504.0, 5526.115821468546, 5528.612997090165, 5537.36320323168, 5555.083411237181, 5580.553555715452, 6170.352113028068, 6391.744330226832, 6401.551891367652, 6428.871422571616, 5050.924236136534, 5464.453554644663, 6192.164057852313, 6244.469220116341, 6261.060840873905, 6349.587276826992, 6364.968175159094, 6389.0, 6398.93601064002, 6431.710573024318, 6212.099245609452, 6217.751289948352, 6217.875642487215, 6234.719437625227, 6286.874262514815, 6133.79375043651, 6196.969015154896, 6197.163382943245, 6226.501879849692, 6244.219472565929, 6116.29662588808, 6210.161628180138, 6224.06639004153, 6231.875362492816, 6233.127362169209, 6094.9085763714465, 6207.782727604512, 6224.875502489835, 6240.594361366497, 6252.187215661935, 5852.0, 5890.410958904075, 5937.910931335947, 6054.182685337423, 6077.848053798513, 6129.478994285358, 6129.756146212572, 6144.53915956314, 6236.875262494634, 6246.406591373812, 6085.57400981927, 6097.115918191748, 6097.215386221507, 6110.450059494667, 6110.7861224856915], "value": 5758.457999248094, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [451.6071018214152, 456.59135074108667, 497.2615665736381, 499.8575406009287, 541.6398095266647, 38985.0, 41623.16753664925, 41658.583414165885, 41672.58327416725, 41913.79043104788, 42771.78614106933, 43098.56901430985, 43232.56767432325, 43303.56696433035, 43310.0, 8294.958525207365, 8532.488050716946, 9005.594748236312, 9227.446353218795, 9257.675981340655, 11099.278546894426, 11417.942910285437, 11422.600208992686, 11566.01688856444, 11578.0, 11288.887111128906, 11320.0, 11356.716082097932, 11365.0, 11366.659000230007, 11503.309801411942, 11527.654170374903, 11560.653180404559, 11561.017313528277, 11568.595099171489, 11342.829857552144, 11365.545378184863, 11396.715082123013, 11443.485043173077, 11445.08439324852, 41595.7920210397, 41767.0, 41820.95447613804, 41939.37090943638, 42033.0, 3921.372580387153, 3933.1543718100747, 3990.4213888986224, 3999.8400063997406, 4038.2125485530332, 25907.870460647766, 30329.393412131856, 30397.544036839467, 30732.924347647724, 31193.688063119305, 2471.8764061796837, 3108.875644974198, 3132.530120481923, 3165.7150856422804, 3274.345130973815, 268103.0, 308869.91130088863, 330501.0, 338748.0, 341197.0, 66126.00810987838, 66401.66799165973, 66736.0, 66814.66592667006, 66858.0, 67352.6632366835, 67922.32077679259, 67964.6601766988, 68028.31971680319, 68124.6593767028, 16551.503454896374, 16678.58303542397, 16746.74879876803, 16781.993080415214, 16803.663926721398, 11516.827247591293, 11717.414129293502, 12803.743925121445, 12872.420741066675, 13131.803022954664, 20796.79203207979, 22152.335429937128, 22239.88880055622, 22254.99852506641, 22271.0, 7647.694092236248, 8896.822063558822, 9116.270698344117, 9121.589528471235, 9155.359124861328, 691.3915754136328, 821.6959724901825, 859.2181115185151, 862.6721845698594, 871.9171678690515, 28896.277593060342, 29420.264493387407, 30446.0, 30544.389112217632, 31029.534556981664, 983.188869182927, 1014.452195814252, 1023.0, 1033.1837848100067, 1036.849656799775, 13715.245661488714, 14497.347619357155, 14816.925915370353, 14947.70104597902, 15084.773728394082, 669129.6543517251, 674269.0, 675582.0, 676326.0, 679864.0, 55065.72467137639, 55575.72212138913, 55608.21958902039, 55670.44329556733, 55787.16319255214, 1295.2811189789757, 1342.2617560341773, 1352.8376594808883, 1355.2884735513935, 1381.8134551835574, 27628.447431051667, 27855.0, 28185.859070704515, 28604.71395286104, 28658.14025579236, 41403.378949316386, 49061.75469122631, 49181.754091230745, 49182.75408622934, 49890.0, 616347.0, 620872.0, 621616.0, 621848.0, 622065.0, 25848.612270815578, 26205.73794262033, 26845.463090737685, 26860.328491787863, 27237.54862062424, 43938.56061439345, 44145.77927110344, 45234.54765452435, 45293.77353113213, 45361.0, 35576.0, 36099.819500902326, 36192.638073618924, 36788.08029799276, 37211.0, 36613.63386366102, 36829.63170368369, 37114.628853712195, 37120.0, 37427.2514549702, 28936.56595151032, 35195.648043519235, 35291.64708352986, 35433.0, 36228.63771362253, 31106.066817995496, 32537.837310813295, 32704.345913081128, 32721.509177361884, 32866.83566582152, 21256.893715531325, 23142.76857231406, 23976.672206685897, 24673.50652986966, 24781.752182477943], "value": 20037.734012850553, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [388.9027743064234, 391.1180288449548, 401.0915276897826, 407.6005514595696, 422.7526896765392, 16004.519864404057, 19445.12496937638, 20377.592448151026, 20818.583628327444, 20846.583068338645, 21273.361799146012, 21292.574148517022, 21864.89067554664, 22690.092396304128, 22966.966486508107, 5137.691738495702, 5156.226566015109, 5701.25883635129, 5967.134765459005, 5992.310884248323, 5061.19020956647, 5682.346530149024, 5791.21818554494, 5853.8829223415505, 5880.500157486619, 5803.448672376117, 5812.854678633025, 5817.941820581782, 5819.592628516006, 5825.8834823303505, 4909.950900490985, 4945.752712364385, 4967.801287948478, 6831.726730930757, 7167.390771784405, 7414.184439711627, 7668.156502784689, 7706.998090248288, 7725.188855170239, 7748.031496062992, 25264.0, 28252.858735706395, 29086.854565727248, 29136.41727165445, 29786.0, 2808.255812209766, 2848.786341024411, 2858.1568437311043, 2878.0070875547904, 2883.2503549077182, 15726.606834829221, 16345.855790094698, 16853.747193792104, 16870.325186992504, 17509.299628014865, 1894.0, 1921.798211187819, 1979.3962841333387, 1987.0462178154423, 1988.2941555747645, 143546.0, 153795.0, 159722.0, 161722.1913890423, 163731.0, 10206.285560010729, 10717.303375280582, 10734.892651073545, 10782.838257426145, 10881.564737410574, 12854.9357253215, 12929.288889111189, 12963.416646250942, 14149.71700565983, 14657.633559161106, 8434.451760635662, 8687.91312086871, 8753.168448997336, 8793.0, 8924.241439477588, 8554.486730796172, 8581.01318348398, 8586.484810911365, 8718.82562348762, 8726.781830454163, 4760.143174228672, 4843.660943733976, 4858.562729354375, 4861.562459378673, 5006.6495345325475, 1812.7734033245845, 2016.102834238779, 2043.6321462136957, 2086.0612724274138, 2089.937301880946, 991.5240684471349, 996.6013594562088, 1001.7495626093477, 1036.2021243642353, 1052.9894701052892, 20857.165713371294, 21271.36185914425, 21293.68059479109, 21471.88123331606, 21488.892555537434, 1028.681108856257, 1031.3141760729181, 1046.7278507588082, 1065.8454524094118, 1071.9249652524404, 10853.0, 11083.612073577386, 11093.168012399103, 11114.777704445865, 11256.380899050631, 668335.0, 673425.0, 675900.0, 677281.0, 690897.0, 142752.0, 146556.53443465428, 147994.0, 149233.0, 150003.0, 981.5043403081398, 1017.1913328903391, 1039.1167507618616, 1042.3641578637025, 1045.487711021594, 18754.437366879014, 19198.232070717015, 19620.705689414386, 19628.0, 19652.803471965668, 57772.0, 58711.0, 60665.090023648794, 61406.77186456157, 61887.69056154691, 86304.56847715973, 86311.56844215738, 87867.0, 89283.10716893007, 89386.65920011075, 6858.759943402157, 7282.380997615051, 7497.250274972459, 8044.155363686728, 8480.15198480147, 9015.602581599862, 11659.941700291443, 12195.695107622381, 12596.811047834473, 12763.361831908553, 10598.841017384899, 10616.469176540988, 10682.679519614425, 10708.785824283626, 10897.836532451862, 10534.0, 10705.304155229886, 10728.034476897117, 11012.449377530931, 11030.889691103308, 10754.462276886281, 10884.292520986111, 10907.836382454429, 11238.269512481662, 11453.828192577284, 5765.3946335634155, 7819.491733037335, 8085.434019618806, 8280.461769984931, 8375.832483350177, 10931.508082136164, 11193.384363860067, 11226.831597526207, 11331.830022549504, 11371.374574398487], "value": 10127.203743876662, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1234.8950339221167, 1317.5520323090147, 1401.3063533550887, 1441.2937660546336, 1456.970860582788, 7447.069116360455, 7684.96157519213, 8067.878981815277, 8533.232009119185, 8534.658613655462, 6607.834804129887, 6977.616231107287, 8146.2261085196815, 8280.296174825147, 8367.079621241659, 2272.125231785766, 2281.555096756133, 2315.235972129193, 2321.245595181564, 2326.9418264543438, 2074.1184996376555, 2314.8611083335054, 2359.752226016269, 2359.976400235993, 2362.6692263083182, 2079.552896127332, 2234.72065991751, 2310.9884450577724, 2316.2587971849016, 2317.142657216824, 2275.0444813178506, 2280.486890449652, 2297.356740112763, 2302.4243939015246, 2308.68832707584, 2281.3498153026308, 2315.3979965209, 2335.077644330497, 2341.285907798121, 2347.119830063726, 8350.457220280663, 8443.535605541692, 8456.577171141418, 8462.449940753893, 8494.150584941519, 5093.974530127362, 5230.947690523084, 5250.553702935255, 5254.290670759475, 5263.421023687391, 3447.293304872495, 3448.8448019839166, 3471.5313432686517, 3482.9303413931834, 3550.0769799852224, 1168.0, 1172.9882701173049, 1194.75507520958, 1205.9698507537382, 1228.969275768104, 9742.95128524353, 9991.85093714225, 10798.0, 10816.72958176044, 10825.458727063615, 4312.935305970413, 4608.239640459286, 4646.883827904262, 4681.133990211822, 4684.156851766715, 9378.5310734463, 9482.241420686329, 9484.810303793885, 9493.050694930589, 9539.856902146474, 5106.693598384109, 5205.323307969939, 5215.217717342429, 5219.1388420910125, 5225.399079105951, 4529.660275479352, 4574.725516469022, 4761.262004389325, 4772.5227477252, 4798.184308667535, 3868.574456809749, 3887.5918028607157, 3930.9213815723524, 3959.208158368338, 3970.1265721541213, 1173.8708742038368, 1176.664650574582, 1184.2716729211472, 1187.6793265818353, 1218.2690385768644, 673.8888083466271, 704.8519810839679, 790.4506368074133, 808.7290757596154, 853.1425916953422, 5438.864028399322, 5454.509094181542, 5496.395396506381, 5712.343080545707, 5761.222234998306, 959.5442164971582, 994.9801003979879, 1006.2754816532085, 1017.1455976979373, 1018.760591261053, 3420.572428446444, 3665.138692407282, 3711.2763011212555, 3725.4970578972034, 3834.9233015340096, 9659.0, 9759.26805489591, 9960.252981026451, 9965.850512242178, 9971.950140249251, 9730.513474326255, 9796.167325777245, 9838.3113182078, 9932.80134397308, 9933.205343572497, 7801.336886364609, 7868.606569671608, 7876.094249161305, 7890.461360034853, 7914.010748656418, 4347.0, 5306.8938621228135, 5352.812651557177, 5412.702301373305, 5527.391986881359, 9129.634814607341, 9174.770630734285, 9181.678641247525, 9266.953665231631, 9275.21160701348, 7794.493357931717, 7899.565523896241, 8186.345092392475, 9151.816963660822, 9461.952690236505, 7043.929560704328, 7213.71145154209, 7234.782956511313, 7255.854882902207, 7263.745768898063, 6559.573627714183, 7740.496867703581, 7764.22357764219, 7850.293473587404, 7857.2928436441, 6299.370062993848, 7092.0, 7114.395276401564, 7121.786346409616, 7164.605946672983, 5876.559258055578, 7013.2986701329455, 7050.541714788523, 7110.7511237106455, 7119.323664251888, 5151.484851514969, 6396.200474940632, 6453.064305675557, 6469.77355792545, 6479.319671434431, 4809.182438985451, 4858.392700912386, 4990.201567749143, 5024.874378140576, 5128.53843154103, 4902.926456103232, 4959.008198360414, 5006.824761133343, 5026.497350265091, 5052.938882834499], "value": 4351.394089573777, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [354.3161697923009, 356.3123172277505, 365.6307129798903, 368.554049599984, 370.9888703338899, 5015.8212819987275, 5272.472752724726, 5295.894082118355, 5407.864803379913, 5580.414056524062, 5330.387005494369, 5503.504684578387, 5524.0, 5550.2507161533185, 5554.444555544444, 3827.2875310948334, 3973.8609148679802, 4152.647025002879, 4270.786460676969, 4311.698181127323, 3363.1423986883433, 4183.0, 4199.286121359361, 4293.484781826185, 4315.352697095429, 4125.236831186226, 4145.419641250227, 4149.730267532601, 4170.666346692273, 4171.749695018293, 4127.926739047839, 4133.0, 4140.91718165638, 4145.233131870598, 4158.8336466541305, 2905.738483536492, 3463.79217246966, 3572.3034008368377, 3575.4804208211667, 3615.385384484644, 4796.872734907294, 5235.476452354772, 5246.24250875957, 5375.838724838261, 5518.641288316287, 4772.0, 4784.258439941815, 4790.736509491977, 4795.904081918342, 4847.0, 4911.754412279408, 5784.392638772911, 5837.970810145965, 5863.325717542494, 5872.618279811799, 3344.698977092049, 3682.6133256008, 3910.9689990257493, 3940.290747665419, 3963.9405408918888, 5229.738513074331, 5411.078017870345, 5917.378675239081, 6007.596961215505, 6102.908456373158, 3401.4727717203873, 3678.2643471305846, 3995.1809878975323, 4031.637152656275, 4066.9389959150635, 3195.0890116635974, 3701.6298370162767, 3763.814398464475, 3952.2885880541203, 4051.2910240707756, 4693.193120648507, 4709.0, 4716.716996980193, 4725.480197178307, 4733.414306207459, 4999.375078115236, 5483.561315094783, 5894.469497745224, 5925.644461332247, 5972.492338151219, 1145.9083273338113, 1187.5487314820314, 1208.8368070310396, 1302.9543965961145, 1307.7253776706998, 828.7596596986926, 854.1842540373927, 881.2333270055093, 887.2990337633327, 889.8309321228945, 4738.50245724194, 5184.9740751296, 5222.791088356423, 5269.420363759983, 5291.656042357235, 5724.341700704473, 5732.197492351074, 5741.913871291934, 5802.448767367094, 5910.290765108129, 4415.211839205127, 4415.558444155623, 4831.347768051268, 5172.689478733613, 5176.767045482962, 2510.661060756811, 2671.597411359052, 2966.777491688089, 2998.4902566563737, 3024.228821650465, 5257.0, 5324.787008519694, 5914.9112763308585, 5946.048632218911, 5964.522838172936, 5074.644774865797, 5217.0, 5346.786128554814, 5848.607807471656, 5897.252711567229, 5030.673006254582, 5346.652467589594, 5611.186377975171, 5779.7230049762875, 6062.636241825593, 4500.052465635014, 5237.8428647140645, 5411.918821217763, 5500.697461639648, 5788.855278618068, 4579.5878370946775, 4765.999140180601, 5020.1716716742785, 5586.273784408001, 5673.035583950656, 5325.707086110301, 5771.826845194651, 5904.940950590611, 5932.49573786233, 5997.322088770766, 3006.5339872319823, 3780.9432858507694, 3819.8854034379015, 3847.5190601174854, 3882.844686212633, 5105.540501354896, 5635.8591035224445, 5656.971715141398, 5690.687012214368, 5691.0365923756235, 4498.740352701249, 4924.926126108039, 4937.629677774253, 5687.488126068673, 5792.913106303325, 4657.254839225708, 4887.0451819272575, 5231.267622532772, 5557.944420555905, 5803.970980145073, 4801.399825021872, 4847.660663753644, 5106.621212272591, 5307.814226502054, 5676.3538573814785, 5830.941690583039, 5868.882622347614, 5872.150272664097, 5876.382979787061, 5917.615355001911, 4843.95156048435, 5725.885482290248, 5768.94231057684, 5843.0, 5874.17761513397], "value": 4118.058890313167, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [701.7159692638456, 717.9389751871091, 728.2499026003215, 795.6538905576072, 819.8565251081061, 14518.491852785142, 15513.224338783071, 15536.766948495753, 15590.220488975534, 15613.219339033045, 14312.856871431282, 14371.209583472904, 14373.281335933214, 14376.856231437683, 14460.638484037878, 664.4883439751388, 685.7428464325877, 823.7199352220244, 832.5587438657521, 834.2783492279165, 812.3379445751693, 842.4313588327885, 849.2399302624148, 852.6248450681711, 853.5902766672001, 895.4582477601056, 899.5502248875562, 899.5637115998768, 900.5317235037777, 901.8557030875062, 862.3015357560396, 885.8494056010494, 889.89321281446, 893.2362829780509, 893.3389291923929, 643.5881036136876, 653.5719103986896, 663.5355251324045, 666.9795213323623, 667.2026927821256, 14578.271086445635, 14979.550613481613, 15048.32282547288, 15094.924525377413, 15146.01550899199, 13931.791023134661, 14001.5099471518, 14070.929645351707, 14278.857211427856, 14284.57146285613, 11475.655730328104, 11575.826362604568, 11688.298702077902, 11933.34366609836, 12016.339101349422, 3595.8022308773006, 3627.7460577759566, 3667.8716244931297, 3698.0939669780805, 3827.483289755903, 111857.0, 113576.0, 114955.0, 116420.41789790997, 117002.41498792451, 10630.787384252271, 10634.361938283728, 10649.787004259872, 10928.890711092787, 10941.28881622692, 22142.003609837273, 22226.555468890532, 22276.0, 22295.665565016214, 22445.0, 7675.385969122457, 7713.807154821175, 7741.574213418204, 7753.263439973193, 7866.881996770053, 3653.791825062069, 3744.2324323513612, 4275.337322714984, 4287.335463003239, 4384.210842048462, 3491.319192757388, 3574.3566158091794, 3591.5151454553825, 3592.1019745063736, 3613.4218525035867, 537.4088502647135, 591.73667717866, 597.172915512019, 624.9281332646713, 643.793985924509, 664.4053572052985, 664.9002649602598, 685.9725610975505, 699.576756062585, 726.3136336162381, 10092.394456332644, 10113.190944724405, 10229.0, 10434.634787782543, 10446.529906154241, 548.6104865545508, 682.3654001778294, 682.6484360554318, 687.9380855723009, 688.6246995387526, 4451.310046942729, 4958.6776859504735, 4964.826231081895, 4978.377702787152, 4989.800407983713, 100980.0, 106305.46847265714, 108782.91217087727, 114486.0, 115482.0, 91358.08641913629, 99320.0, 100401.0, 101688.47467287997, 102828.48585757024, 72910.27089729141, 73400.63299683467, 74752.50494990179, 75046.24953750502, 75209.6239518799, 10485.790284194425, 10631.255812093079, 10837.987159914504, 11136.830632783442, 11143.498542565767, 41890.79054604707, 51179.744101279255, 51717.70705732236, 51724.22413663873, 52435.73782131218, 75983.24016759983, 76728.0, 76784.0, 77913.61043194974, 77982.22017779977, 12753.74492510126, 12895.677608059874, 13131.409086591317, 13216.471341146247, 13312.201267923956, 12052.758944820878, 14743.262836857899, 14813.259337033322, 14943.327550260046, 15026.924865375604, 13500.235948637552, 13591.728165436438, 13667.248301343125, 13844.792328114885, 13996.790048149081, 12555.246685198917, 12963.546275880299, 12973.156744811557, 13145.934270328587, 13319.933400332937, 10594.31136976094, 13529.323533823466, 13620.18278903269, 13646.795298070736, 13780.65548361299, 9086.863697044417, 11112.22214444981, 11117.555297788322, 11223.428719979373, 11345.149113816662, 8911.420757650732, 9659.46872921996, 10952.890471095187, 11018.50416731265, 11246.437678116225], "value": 7522.744079196116, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1581.4999999999998, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 143.0, 163.0, 117.0, 119.0, 120.0, 117.0, 126.0, 177.0, 163.0], "value": 134.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [154.0, 147.0, 143.0, 182.0, 122.0, 119.0, 134.0, 151.0, 161.0, 146.0], "value": 145.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [194.0, 202.0, 203.0, 207.0, 183.0, 241.0, 278.0, 226.0, 194.0, 195.0], "value": 212.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 58.0, 60.0, 88.0, 53.0, 77.0, 50.0, 52.0, 78.0, 68.0], "value": 66.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 72.0, 88.0, 71.0, 70.0, 71.0, 80.0, 74.0, 56.0, 82.0], "value": 74.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 90.0, 92.0, 93.0, 105.0, 89.0, 92.0, 91.0, 91.0, 89.0], "value": 92.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [101.0, 147.0, 173.0, 133.0, 102.0, 103.0, 105.0, 173.0, 164.0, 104.0], "value": 130.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 155.0, 144.0, 95.0, 94.0, 76.0, 146.0, 150.0, 93.0, 93.0], "value": 112.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 61.0, 59.0, 73.0, 79.0, 62.0, 62.0, 63.0, 62.0, 62.0], "value": 64.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 73.0, 73.0, 76.0, 72.0, 67.0, 69.0, 69.0, 69.0, 70.0], "value": 70.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 87.0, 88.0, 86.0, 85.0, 81.0, 91.0, 104.0, 70.0, 98.0], "value": 87.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 142.0, 135.0, 138.0, 137.0, 152.0, 133.0, 142.0, 183.0, 146.0], "value": 144.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [173.0, 169.0, 168.0, 168.0, 170.0, 216.0, 210.0, 172.0, 170.0, 175.0], "value": 179.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 68.0, 66.0, 67.0, 66.0, 66.0, 64.0, 66.0, 66.0, 64.0], "value": 65.8, "unit": "score"}]}]} 09:24:32 INFO - Return code: 0 09:24:32 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:24:32 ERROR - # TBPL SUCCESS # 09:24:32 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:24:32 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:24:32 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:24:32 INFO - 2017-03-04 09:24:32.279 screenresolution[1661:11319] starting screenresolution argv=screenresolution get 09:24:32 INFO - 2017-03-04 09:24:32.281 screenresolution[1661:11319] Display 0: 1600x1200x32@60 09:24:32 INFO - 2017-03-04 09:24:32.287 screenresolution[1662:11321] starting screenresolution argv=screenresolution list 09:24:32 INFO - Available Modes on Display 0 09:24:32 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:24:32 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:24:32 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:24:32 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:24:32 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:24:32 INFO - Intel Iris: 09:24:32 INFO - Chipset Model: Intel Iris 09:24:32 INFO - Type: GPU 09:24:32 INFO - Bus: Built-In 09:24:32 INFO - VRAM (Dynamic, Max): 1536 MB 09:24:32 INFO - Vendor: Intel (0x8086) 09:24:32 INFO - Device ID: 0x0a2e 09:24:32 INFO - Revision ID: 0x0009 09:24:32 INFO - Displays: 09:24:32 INFO - Display: 09:24:32 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:24:32 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:24:32 INFO - Display Serial Number: 1600x1200 60 09:24:32 INFO - Main Display: Yes 09:24:32 INFO - Mirror: Off 09:24:32 INFO - Online: Yes 09:24:32 INFO - Rotation: Supported 09:24:32 INFO - Return code: 0 09:24:32 INFO - Running post-action listener: _resource_record_post_action 09:24:32 INFO - [mozharness: 2017-03-04 17:24:32.522722Z] Finished run-tests step (success) 09:24:32 INFO - Running post-run listener: _resource_record_post_run 09:24:32 INFO - Total resource usage - Wall time: 1198s; CPU: 54.0%; Read bytes: 33567232; Write bytes: 515415040; Read time: 336; Write time: 1603 09:24:32 INFO - TinderboxPrint: CPU usage
54.4% 09:24:32 INFO - TinderboxPrint: I/O read bytes / time
33,567,232 / 336 09:24:32 INFO - TinderboxPrint: I/O write bytes / time
515,415,040 / 1,603 09:24:32 INFO - TinderboxPrint: CPU idle
2,184.2 (45.6%) 09:24:32 INFO - TinderboxPrint: CPU system
967.1 (20.2%) 09:24:32 INFO - TinderboxPrint: CPU user
1,642.4 (34.3%) 09:24:32 INFO - TinderboxPrint: Swap in / out
238,526,464 / 0 09:24:32 INFO - install - Wall time: 21s; CPU: 54.0%; Read bytes: 199666176; Write bytes: 201365504; Read time: 15522; Write time: 425 09:24:32 INFO - run-tests - Wall time: 1178s; CPU: 54.0%; Read bytes: 32637440; Write bytes: 304219136; Read time: 324; Write time: 1144 09:24:32 INFO - Running post-run listener: _upload_blobber_files 09:24:32 INFO - Blob upload gear active. 09:24:32 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:24:32 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:24:32 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', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 09:24:32 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 Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 09:24:33 INFO - (blobuploader) - INFO - Open directory for files ... 09:24:33 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 09:24:33 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:24:33 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:24:34 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 09:24:34 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:24:34 INFO - (blobuploader) - INFO - Done attempting. 09:24:34 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 09:24:34 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:24:34 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:24:35 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 09:24:35 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:24:35 INFO - (blobuploader) - INFO - Done attempting. 09:24:35 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 09:24:35 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:24:35 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:24:36 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 09:24:36 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:24:36 INFO - (blobuploader) - INFO - Done attempting. 09:24:36 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:24:36 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:24:36 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:24:38 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:24:38 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:24:38 INFO - (blobuploader) - INFO - Done attempting. 09:24:38 INFO - (blobuploader) - INFO - Iteration through files over. 09:24:38 INFO - Return code: 0 09:24:38 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:24:38 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:24:38 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e4b02874a49fbac8f2a5b2c7ff9166f525d59f49977a4d7ef1d8bad855f2d73e3e376f833ca78624aa4cd8886eadc2002f3bd8ffc88263e5bcb912c34c4928c5", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/bf8d370415db177ef01b9136c2a96ae1de3ff7da6074d6c8293f77e67bac75df65c340da1292149aaf7938866ef2f79fa58717bfd50bc81830a9959d31d704c9", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/af299c477f8579c47ce58441dbee0a73092960acc49140a089107a34a0037e25da1fe86b9bd2e82ec7c04ceac2a5f7956ab66819730c07f55c7118c4bf27680c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/acc5fca98132d16a7a71c9f3e36da2e600111ffed9ec7f6b741d4ca31a0b0f9012d7aa5f5ca177519f748021e512bd3015383de7fbeb30f7355150ae329406c2"} 09:24:38 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:24:38 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:24:38 INFO - Contents: 09:24:38 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e4b02874a49fbac8f2a5b2c7ff9166f525d59f49977a4d7ef1d8bad855f2d73e3e376f833ca78624aa4cd8886eadc2002f3bd8ffc88263e5bcb912c34c4928c5", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/bf8d370415db177ef01b9136c2a96ae1de3ff7da6074d6c8293f77e67bac75df65c340da1292149aaf7938866ef2f79fa58717bfd50bc81830a9959d31d704c9", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/af299c477f8579c47ce58441dbee0a73092960acc49140a089107a34a0037e25da1fe86b9bd2e82ec7c04ceac2a5f7956ab66819730c07f55c7118c4bf27680c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/acc5fca98132d16a7a71c9f3e36da2e600111ffed9ec7f6b741d4ca31a0b0f9012d7aa5f5ca177519f748021e512bd3015383de7fbeb30f7355150ae329406c2"} 09:24:38 INFO - Running post-run listener: copy_logs_to_upload_dir 09:24:38 INFO - Copying logs to upload dir... 09:24:38 INFO - mkdir: /builds/slave/test/build/upload/logs 09:24:38 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1261.084020 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 1 secs) (at 2017-03-04 09:24:38.700663) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-04 09:24:38.704347) ========= 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.R8phzQO2wB/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.5W5zUYVmgE/Listeners TMPDIR=/var/folders/sw/s26whz2n6pbfv14p7p8hkdxw00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e4b02874a49fbac8f2a5b2c7ff9166f525d59f49977a4d7ef1d8bad855f2d73e3e376f833ca78624aa4cd8886eadc2002f3bd8ffc88263e5bcb912c34c4928c5", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/bf8d370415db177ef01b9136c2a96ae1de3ff7da6074d6c8293f77e67bac75df65c340da1292149aaf7938866ef2f79fa58717bfd50bc81830a9959d31d704c9", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/af299c477f8579c47ce58441dbee0a73092960acc49140a089107a34a0037e25da1fe86b9bd2e82ec7c04ceac2a5f7956ab66819730c07f55c7118c4bf27680c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/acc5fca98132d16a7a71c9f3e36da2e600111ffed9ec7f6b741d4ca31a0b0f9012d7aa5f5ca177519f748021e512bd3015383de7fbeb30f7355150ae329406c2"} build_url:https://queue.taskcluster.net/v1/task/HzAZHv9-SFeVYZjHeZyyyQ/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010896 build_url: 'https://queue.taskcluster.net/v1/task/HzAZHv9-SFeVYZjHeZyyyQ/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e4b02874a49fbac8f2a5b2c7ff9166f525d59f49977a4d7ef1d8bad855f2d73e3e376f833ca78624aa4cd8886eadc2002f3bd8ffc88263e5bcb912c34c4928c5", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/bf8d370415db177ef01b9136c2a96ae1de3ff7da6074d6c8293f77e67bac75df65c340da1292149aaf7938866ef2f79fa58717bfd50bc81830a9959d31d704c9", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/af299c477f8579c47ce58441dbee0a73092960acc49140a089107a34a0037e25da1fe86b9bd2e82ec7c04ceac2a5f7956ab66819730c07f55c7118c4bf27680c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/acc5fca98132d16a7a71c9f3e36da2e600111ffed9ec7f6b741d4ca31a0b0f9012d7aa5f5ca177519f748021e512bd3015383de7fbeb30f7355150ae329406c2"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-04 09:24:38.737947) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-04 09:24:38.738263) ========= 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.R8phzQO2wB/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.5W5zUYVmgE/Listeners TMPDIR=/var/folders/sw/s26whz2n6pbfv14p7p8hkdxw00000w/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.004843 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-04 09:24:38.786810) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-04 09:24:38.787125) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-04 09:24:38.787453) ========= ========= Total master_lag: 0.21 =========