builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0012 starttime: 1488026569.89 results: success (0) buildid: 20170225030208 builduid: 9ac0de2cc84b42a0a1a9c0116c3ce79c revision: f36062d04d165f6f6e781cf0633ffcbbebe6c273 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-02-25 04:42:49.890865) ========= master: http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-02-25 04:42:49.891287) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-25 04:42:49.891555) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-25 04:42:49.917477) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-25 04:42:49.917852) ========= 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.7lyFfe1O9i/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.xSoWRdYIKb/Listeners TMPDIR=/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/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-02-25 04:42:49-- 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% 18.4M=0.001s 2017-02-25 04:42:50 (18.4 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.623669 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-25 04:42:50.572364) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-25 04:42:50.572675) ========= 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.7lyFfe1O9i/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.xSoWRdYIKb/Listeners TMPDIR=/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/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.020388 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-25 04:42:50.621334) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-02-25 04:42:50.621691) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev f36062d04d165f6f6e781cf0633ffcbbebe6c273 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev f36062d04d165f6f6e781cf0633ffcbbebe6c273 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.7lyFfe1O9i/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.xSoWRdYIKb/Listeners TMPDIR=/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/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-02-25 04:42:50,733 truncating revision to first 12 chars 2017-02-25 04:42:50,733 Setting DEBUG logging. 2017-02-25 04:42:50,733 attempt 1/10 2017-02-25 04:42:50,733 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/f36062d04d16?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-02-25 04:42:51,914 unpacking tar archive at: mozilla-central-f36062d04d16/testing/mozharness/ program finished with exit code 0 elapsedTime=1.436780 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-02-25 04:42:52.081684) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-25 04:42:52.082002) ========= script_repo_revision: f36062d04d165f6f6e781cf0633ffcbbebe6c273 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-25 04:42:52.082373) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-25 04:42:52.082654) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-25 04:42:52.102382) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 25 secs) (at 2017-02-25 04:42:52.102792) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.7lyFfe1O9i/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.xSoWRdYIKb/Listeners TMPDIR=/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/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 04:42:52 INFO - MultiFileLogger online at 20170225 04:42:52 in /builds/slave/test 04:42:52 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 04:42:52 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 04:42:52 INFO - {'append_to_log': False, 04:42:52 INFO - 'base_work_dir': '/builds/slave/test', 04:42:52 INFO - 'blob_upload_branch': 'Firefox', 04:42:52 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 04:42:52 INFO - 'branch': 'Firefox', 04:42:52 INFO - 'buildbot_json_path': 'buildprops.json', 04:42:52 INFO - 'config_files': ('talos/mac_config.py',), 04:42:52 INFO - 'default_actions': ('clobber', 04:42:52 INFO - 'read-buildbot-config', 04:42:52 INFO - 'download-and-extract', 04:42:52 INFO - 'populate-webroot', 04:42:52 INFO - 'create-virtualenv', 04:42:52 INFO - 'install', 04:42:52 INFO - 'run-tests'), 04:42:52 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 04:42:52 INFO - 'download_minidump_stackwalk': True, 04:42:52 INFO - 'download_symbols': 'ondemand', 04:42:52 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 04:42:52 INFO - 'tooltool.py': '/tools/tooltool.py', 04:42:52 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 04:42:52 INFO - '/tools/misc-python/virtualenv.py')}, 04:42:52 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 04:42:52 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 04:42:52 INFO - 'gecko_profile': False, 04:42:52 INFO - 'gecko_profile_interval': 0, 04:42:52 INFO - 'installer_path': 'installer.exe', 04:42:52 INFO - 'log_level': 'info', 04:42:52 INFO - 'log_name': 'talos', 04:42:52 INFO - 'log_to_console': True, 04:42:52 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 04:42:52 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 04:42:52 INFO - 'opt_config_files': (), 04:42:52 INFO - 'pip_index': False, 04:42:52 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:42:52 INFO - 'cmd': ('bash', 04:42:52 INFO - '-c', 04:42:52 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:42:52 INFO - 'enabled': True, 04:42:52 INFO - 'halt_on_failure': False, 04:42:52 INFO - 'name': 'check_screen_resolution'},), 04:42:52 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:42:52 INFO - 'cmd': ('bash', 04:42:52 INFO - '-c', 04:42:52 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:42:52 INFO - 'enabled': True, 04:42:52 INFO - 'halt_on_failure': False, 04:42:52 INFO - 'name': 'check_screen_resolution'},), 04:42:52 INFO - 'run_cmd_checks_enabled': True, 04:42:52 INFO - 'suite': 'dromaeojs-e10s', 04:42:52 INFO - 'system_bits': '32', 04:42:52 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 04:42:52 INFO - 'title': 't-yosemite-r7-0012', 04:42:52 INFO - 'tooltool_cache': '/builds/tooltool_cache', 04:42:52 INFO - 'use_talos_json': True, 04:42:52 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 04:42:52 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 04:42:52 INFO - 'work_dir': 'build'} 04:42:52 INFO - [mozharness: 2017-02-25 12:42:52.338014Z] Running clobber step. 04:42:52 INFO - Running pre-action listener: _resource_record_pre_action 04:42:52 INFO - Running main action method: clobber 04:42:52 INFO - rmtree: /builds/slave/test/build 04:42:52 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 04:42:54 INFO - Running post-action listener: _resource_record_post_action 04:42:54 INFO - [mozharness: 2017-02-25 12:42:54.135783Z] Finished clobber step (success) 04:42:54 INFO - [mozharness: 2017-02-25 12:42:54.135928Z] Running read-buildbot-config step. 04:42:54 INFO - Running pre-action listener: _resource_record_pre_action 04:42:54 INFO - Running main action method: read_buildbot_config 04:42:54 INFO - Using buildbot properties: 04:42:54 INFO - { 04:42:54 INFO - "project": "", 04:42:54 INFO - "product": "firefox", 04:42:54 INFO - "script_repo_revision": "production", 04:42:54 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 04:42:54 INFO - "repository": "", 04:42:54 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 04:42:54 INFO - "buildid": "20170225030208", 04:42:54 INFO - "stage_platform": "macosx64", 04:42:54 INFO - "basedir": "/builds/slave/test", 04:42:54 INFO - "nightly_build": "True", 04:42:54 INFO - "buildnumber": 155, 04:42:54 INFO - "slavename": "t-yosemite-r7-0012", 04:42:54 INFO - "master": "http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/", 04:42:54 INFO - "platform": "yosemite_r7", 04:42:54 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 04:42:54 INFO - "branch": "mozilla-central", 04:42:54 INFO - "repo_path": "mozilla-central", 04:42:54 INFO - "revision": "f36062d04d165f6f6e781cf0633ffcbbebe6c273", 04:42:54 INFO - "pgo_build": "False", 04:42:54 INFO - "builduid": "9ac0de2cc84b42a0a1a9c0116c3ce79c", 04:42:54 INFO - "slavebuilddir": "test" 04:42:54 INFO - } 04:42:54 INFO - Found installer url https://queue.taskcluster.net/v1/task/GzKaH1wOT52yWevZhcA6qg/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg. 04:42:54 INFO - Running post-action listener: _resource_record_post_action 04:42:54 INFO - [mozharness: 2017-02-25 12:42:54.139490Z] Finished read-buildbot-config step (success) 04:42:54 INFO - [mozharness: 2017-02-25 12:42:54.139629Z] Running download-and-extract step. 04:42:54 INFO - Running pre-action listener: _resource_record_pre_action 04:42:54 INFO - Running main action method: download_and_extract 04:42:54 INFO - mkdir: /builds/slave/test/build/tests 04:42: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')]} 04:42:54 INFO - trying https://queue.taskcluster.net/v1/task/GzKaH1wOT52yWevZhcA6qg/artifacts/public/build/firefox-54.0a1.en-US.mac.test_packages.json 04:42:54 INFO - Downloading https://queue.taskcluster.net/v1/task/GzKaH1wOT52yWevZhcA6qg/artifacts/public/build/firefox-54.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-54.0a1.en-US.mac.test_packages.json 04:42:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/GzKaH1wOT52yWevZhcA6qg/artifacts/public/build/firefox-54.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-54.0a1.en-US.mac.test_packages.json'}, attempt #1 04:42:55 INFO - Downloaded 1199 bytes. 04:42:55 INFO - Reading from file /builds/slave/test/build/firefox-54.0a1.en-US.mac.test_packages.json 04:42:55 INFO - Using the following test package requirements: 04:42:55 INFO - {u'common': [u'firefox-54.0a1.en-US.mac.common.tests.zip'], 04:42:55 INFO - u'cppunittest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 04:42:55 INFO - u'firefox-54.0a1.en-US.mac.cppunittest.tests.zip'], 04:42:55 INFO - u'gtest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 04:42:55 INFO - u'firefox-54.0a1.en-US.mac.gtest.tests.zip'], 04:42:55 INFO - u'jittest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 04:42:55 INFO - u'jsshell-mac.zip'], 04:42:55 INFO - u'mochitest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 04:42:55 INFO - u'firefox-54.0a1.en-US.mac.mochitest.tests.zip'], 04:42:55 INFO - u'mozbase': [u'firefox-54.0a1.en-US.mac.common.tests.zip'], 04:42:55 INFO - u'reftest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 04:42:55 INFO - u'firefox-54.0a1.en-US.mac.reftest.tests.zip'], 04:42:55 INFO - u'talos': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 04:42:55 INFO - u'firefox-54.0a1.en-US.mac.talos.tests.zip'], 04:42:55 INFO - u'web-platform': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 04:42:55 INFO - u'firefox-54.0a1.en-US.mac.web-platform.tests.zip'], 04:42:55 INFO - u'xpcshell': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 04:42:55 INFO - u'firefox-54.0a1.en-US.mac.xpcshell.tests.zip']} 04:42:55 INFO - Downloading packages: [u'firefox-54.0a1.en-US.mac.common.tests.zip'] for test suite category: common 04:42:55 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/GzKaH1wOT52yWevZhcA6qg/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip 04:42:55 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/GzKaH1wOT52yWevZhcA6qg/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip'}, attempt #1 04:42:55 INFO - Fetch https://queue.taskcluster.net/v1/task/GzKaH1wOT52yWevZhcA6qg/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip into memory 04:42:57 INFO - Expected file size: 51742995 04:42:57 INFO - Obtained file size: 51742995 04:43:13 INFO - Downloading packages: [u'firefox-54.0a1.en-US.mac.common.tests.zip', u'firefox-54.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 04:43:13 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/GzKaH1wOT52yWevZhcA6qg/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip 04:43:13 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/GzKaH1wOT52yWevZhcA6qg/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip'}, attempt #1 04:43:13 INFO - Fetch https://queue.taskcluster.net/v1/task/GzKaH1wOT52yWevZhcA6qg/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip into memory 04:43:15 INFO - Expected file size: 51742995 04:43:15 INFO - Obtained file size: 51742995 04:43:30 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/GzKaH1wOT52yWevZhcA6qg/artifacts/public/build/firefox-54.0a1.en-US.mac.talos.tests.zip 04:43:30 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/GzKaH1wOT52yWevZhcA6qg/artifacts/public/build/firefox-54.0a1.en-US.mac.talos.tests.zip'}, attempt #1 04:43:30 INFO - Fetch https://queue.taskcluster.net/v1/task/GzKaH1wOT52yWevZhcA6qg/artifacts/public/build/firefox-54.0a1.en-US.mac.talos.tests.zip into memory 04:43:32 INFO - Expected file size: 14404421 04:43:32 INFO - Obtained file size: 14404421 04:43:32 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')]} 04:43:32 INFO - trying https://queue.taskcluster.net/v1/task/GzKaH1wOT52yWevZhcA6qg/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg 04:43:32 INFO - Downloading https://queue.taskcluster.net/v1/task/GzKaH1wOT52yWevZhcA6qg/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-54.0a1.en-US.mac.dmg 04:43:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/GzKaH1wOT52yWevZhcA6qg/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-54.0a1.en-US.mac.dmg'}, attempt #1 04:43:34 INFO - Downloaded 75010692 bytes. 04:43:34 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/GzKaH1wOT52yWevZhcA6qg/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg 04:43:34 INFO - mkdir: /builds/slave/test/properties 04:43:34 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 04:43:34 INFO - Writing to file /builds/slave/test/properties/build_url 04:43:34 INFO - Contents: 04:43:34 INFO - build_url:https://queue.taskcluster.net/v1/task/GzKaH1wOT52yWevZhcA6qg/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg 04:43:35 INFO - Running post-action listener: _resource_record_post_action 04:43:35 INFO - Running post-action listener: set_extra_try_arguments 04:43:35 INFO - [mozharness: 2017-02-25 12:43:35.571808Z] Finished download-and-extract step (success) 04:43:35 INFO - [mozharness: 2017-02-25 12:43:35.571962Z] Running populate-webroot step. 04:43:35 INFO - Running pre-action listener: _resource_record_pre_action 04:43:35 INFO - Running main action method: populate_webroot 04:43:35 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 04:43:35 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 04:43:35 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 04:43:35 INFO - '--tppagecycles', 04:43:35 INFO - '7'], 04:43:35 INFO - 'tests': ['tsvgm']}}, 04:43:35 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 04:43:35 INFO - 'tests': ['tresize', 'tcanvasmark']}, 04:43:35 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 04:43:35 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 04:43:35 INFO - 'tests': ['dromaeo_css', 'kraken']}, 04:43:35 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 04:43:35 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 04:43:35 INFO - 'talos_options': ['--disable-e10s'], 04:43:35 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:43:35 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 04:43:35 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:43:35 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 04:43:35 INFO - 'talos_options': ['--disable-e10s'], 04:43:35 INFO - 'tests': ['damp', 'tps']}, 04:43:35 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 04:43:35 INFO - 'tests': ['damp', 'tps']}, 04:43:35 INFO - 'g3': {'talos_options': ['--disable-e10s'], 04:43:35 INFO - 'tests': ['dromaeo_dom']}, 04:43:35 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 04:43:35 INFO - 'g4': {'talos_options': ['--disable-e10s'], 04:43:35 INFO - 'tests': ['basic_compositor_video']}, 04:43:35 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 04:43:35 INFO - 'other': {'talos_options': ['--disable-e10s'], 04:43:35 INFO - 'tests': ['a11yr', 04:43:35 INFO - 'ts_paint', 04:43:35 INFO - 'tpaint', 04:43:35 INFO - 'sessionrestore', 04:43:35 INFO - 'sessionrestore_no_auto_restore', 04:43:35 INFO - 'tabpaint']}, 04:43:35 INFO - 'other-e10s': {'tests': ['a11yr', 04:43:35 INFO - 'ts_paint', 04:43:35 INFO - 'tpaint', 04:43:35 INFO - 'sessionrestore', 04:43:35 INFO - 'sessionrestore_no_auto_restore', 04:43:35 INFO - 'tabpaint']}, 04:43:35 INFO - 'other-e10s_l64': {'tests': ['a11yr', 04:43:35 INFO - 'ts_paint', 04:43:35 INFO - 'tpaint', 04:43:35 INFO - 'sessionrestore', 04:43:35 INFO - 'sessionrestore_no_auto_restore', 04:43:35 INFO - 'tabpaint']}, 04:43:35 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 04:43:35 INFO - 'ts_paint', 04:43:35 INFO - 'tpaint', 04:43:35 INFO - 'sessionrestore', 04:43:35 INFO - 'sessionrestore_no_auto_restore', 04:43:35 INFO - 'tabpaint']}, 04:43:35 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 04:43:35 INFO - 'tests': ['a11yr', 04:43:35 INFO - 'ts_paint', 04:43:35 INFO - 'tpaint', 04:43:35 INFO - 'sessionrestore', 04:43:35 INFO - 'sessionrestore_no_auto_restore', 04:43:35 INFO - 'tabpaint']}, 04:43:35 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 04:43:35 INFO - 'tests': ['a11yr', 04:43:35 INFO - 'ts_paint', 04:43:35 INFO - 'tpaint', 04:43:35 INFO - 'sessionrestore', 04:43:35 INFO - 'sessionrestore_no_auto_restore', 04:43:35 INFO - 'tabpaint']}, 04:43:35 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 04:43:35 INFO - 'tests': ['tsvgx', 04:43:35 INFO - 'tsvgr_opacity', 04:43:35 INFO - 'tart', 04:43:35 INFO - 'tscrollx', 04:43:35 INFO - 'cart', 04:43:35 INFO - 'tsvg_static']}, 04:43:35 INFO - 'svgr-e10s': {'tests': ['tsvgx', 04:43:35 INFO - 'tsvgr_opacity', 04:43:35 INFO - 'tart', 04:43:35 INFO - 'tscrollx', 04:43:35 INFO - 'cart', 04:43:35 INFO - 'tsvg_static']}, 04:43:35 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 04:43:35 INFO - 'talos_options': ['--disable-e10s'], 04:43:35 INFO - 'tests': ['tp5o']}, 04:43:35 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 04:43:35 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 04:43:35 INFO - 'talos_options': ['--disable-e10s', 04:43:35 INFO - '--xperf_path', 04:43:35 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:43:35 INFO - 'tests': ['tp5n']}, 04:43:35 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 04:43:35 INFO - 'talos_options': ['--xperf_path', 04:43:35 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:43:35 INFO - 'tests': ['tp5n']}}, 04:43:35 INFO - 'talos.zip': {'path': '', 04:43:35 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 04:43:35 INFO - Running post-action listener: _resource_record_post_action 04:43:35 INFO - [mozharness: 2017-02-25 12:43:35.590509Z] Finished populate-webroot step (success) 04:43:35 INFO - [mozharness: 2017-02-25 12:43:35.590642Z] Running create-virtualenv step. 04:43:35 INFO - Running pre-action listener: _resource_record_pre_action 04:43:35 INFO - Running main action method: create_virtualenv 04:43:35 INFO - Creating virtualenv /builds/slave/test/build/venv 04:43:35 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 04:43:35 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 04:43:35 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 04:43:35 INFO - Using real prefix '/tools/python27' 04:43:35 INFO - New python executable in /builds/slave/test/build/venv/bin/python 04:43:36 INFO - Installing distribute.............................................................................................................................................................................................done. 04:43:39 INFO - Installing pip.................done. 04:43:39 INFO - Return code: 0 04:43:39 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 04:43:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 04:43:39 INFO - Reading from file tmpfile_stdout 04:43:39 INFO - Output received: 04:43:39 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) 04:43:39 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 04:43:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:43:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:43:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:43:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:43:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:43:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:43:39 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10a8e0b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a8e3768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff5f3cb6280>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a6bed30>, '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 0x10a5e5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a6b3be8>, '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.7lyFfe1O9i/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.xSoWRdYIKb/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/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 04:43:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 04:43:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 04:43:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7lyFfe1O9i/Render', 04:43:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:43:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:43:39 INFO - 'HOME': '/Users/cltbld', 04:43:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:43:39 INFO - 'LOGNAME': 'cltbld', 04:43:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:43:39 INFO - 'MOZ_NO_REMOTE': '1', 04:43:39 INFO - 'NO_EM_RESTART': '1', 04:43:39 INFO - 'PAGER': '/bin/cat', 04:43:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:43:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:43:39 INFO - 'PWD': '/builds/slave/test', 04:43:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:43:39 INFO - 'SHELL': '/bin/bash', 04:43:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xSoWRdYIKb/Listeners', 04:43:39 INFO - 'TMPDIR': '/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/', 04:43:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:43:39 INFO - 'USER': 'cltbld', 04:43:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:43:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:43:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:43:39 INFO - 'XPC_FLAGS': '0x0', 04:43:39 INFO - 'XPC_SERVICE_NAME': '0', 04:43:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:43:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:43:39 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 04:43:39 INFO - Cleaning up... 04:43:39 INFO - Return code: 0 04:43:39 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 04:43:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:43:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:43:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:43:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:43:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:43:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:43:39 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10a8e0b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a8e3768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff5f3cb6280>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a6bed30>, '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 0x10a5e5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a6b3be8>, '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.7lyFfe1O9i/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.xSoWRdYIKb/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/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 04:43:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 04:43:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 04:43:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7lyFfe1O9i/Render', 04:43:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:43:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:43:39 INFO - 'HOME': '/Users/cltbld', 04:43:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:43:39 INFO - 'LOGNAME': 'cltbld', 04:43:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:43:39 INFO - 'MOZ_NO_REMOTE': '1', 04:43:39 INFO - 'NO_EM_RESTART': '1', 04:43:39 INFO - 'PAGER': '/bin/cat', 04:43:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:43:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:43:39 INFO - 'PWD': '/builds/slave/test', 04:43:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:43:39 INFO - 'SHELL': '/bin/bash', 04:43:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xSoWRdYIKb/Listeners', 04:43:39 INFO - 'TMPDIR': '/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/', 04:43:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:43:39 INFO - 'USER': 'cltbld', 04:43:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:43:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:43:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:43:39 INFO - 'XPC_FLAGS': '0x0', 04:43:39 INFO - 'XPC_SERVICE_NAME': '0', 04:43:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:43:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:43:40 INFO - Downloading/unpacking psutil>=3.1.1 04:43:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:43:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:43:40 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:43:40 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:43:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:43:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:43:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 04:43:44 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:43:44 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:43:44 INFO - Installing collected packages: psutil 04:43:44 INFO - Running setup.py install for psutil 04:43:44 INFO - building 'psutil._psutil_osx' extension 04:43:44 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 04:43:44 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 04:43:44 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 04:43:44 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 04:43:44 INFO - building 'psutil._psutil_posix' extension 04:43:44 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 04:43:44 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 04:43:44 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 04:43:44 INFO - ^ 04:43:44 INFO - 1 warning generated. 04:43:44 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 04:43:44 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:43:44 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:43:44 INFO - Successfully installed psutil 04:43:44 INFO - Cleaning up... 04:43:45 INFO - Return code: 0 04:43:45 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 04:43:45 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')]} 04:43:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:43:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:43:45 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')]} 04:43:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:43:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:43:45 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 0x10a8e0b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a8e3768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff5f3cb6280>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a6bed30>, '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 0x10a5e5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a6b3be8>, '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.7lyFfe1O9i/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.xSoWRdYIKb/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/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 04:43:45 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 04:43:45 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 04:43:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7lyFfe1O9i/Render', 04:43:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:43:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:43:45 INFO - 'HOME': '/Users/cltbld', 04:43:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:43:45 INFO - 'LOGNAME': 'cltbld', 04:43:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:43:45 INFO - 'MOZ_NO_REMOTE': '1', 04:43:45 INFO - 'NO_EM_RESTART': '1', 04:43:45 INFO - 'PAGER': '/bin/cat', 04:43:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:43:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:43:45 INFO - 'PWD': '/builds/slave/test', 04:43:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:43:45 INFO - 'SHELL': '/bin/bash', 04:43:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xSoWRdYIKb/Listeners', 04:43:45 INFO - 'TMPDIR': '/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/', 04:43:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:43:45 INFO - 'USER': 'cltbld', 04:43:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:43:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:43:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:43:45 INFO - 'XPC_FLAGS': '0x0', 04:43:45 INFO - 'XPC_SERVICE_NAME': '0', 04:43:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:43:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:43:45 INFO - Downloading/unpacking mozsystemmonitor==0.3 04:43:45 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 04:43:45 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 04:43:45 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 04:43:45 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 04:43:45 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 04:43:45 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 04:43:47 INFO - Downloading mozsystemmonitor-0.3.tar.gz 04:43:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 04:43:48 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 04:43:48 INFO - Installing collected packages: mozsystemmonitor 04:43:48 INFO - Running setup.py install for mozsystemmonitor 04:43:48 INFO - Successfully installed mozsystemmonitor 04:43:48 INFO - Cleaning up... 04:43:48 INFO - Return code: 0 04:43:48 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 04:43:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:43:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:43:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:43:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:43:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:43:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:43:48 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10a8e0b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a8e3768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff5f3cb6280>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a6bed30>, '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 0x10a5e5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a6b3be8>, '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.7lyFfe1O9i/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.xSoWRdYIKb/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/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 04:43:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 04:43:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 04:43:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7lyFfe1O9i/Render', 04:43:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:43:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:43:48 INFO - 'HOME': '/Users/cltbld', 04:43:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:43:48 INFO - 'LOGNAME': 'cltbld', 04:43:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:43:48 INFO - 'MOZ_NO_REMOTE': '1', 04:43:48 INFO - 'NO_EM_RESTART': '1', 04:43:48 INFO - 'PAGER': '/bin/cat', 04:43:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:43:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:43:48 INFO - 'PWD': '/builds/slave/test', 04:43:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:43:48 INFO - 'SHELL': '/bin/bash', 04:43:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xSoWRdYIKb/Listeners', 04:43:48 INFO - 'TMPDIR': '/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/', 04:43:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:43:48 INFO - 'USER': 'cltbld', 04:43:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:43:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:43:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:43:48 INFO - 'XPC_FLAGS': '0x0', 04:43:48 INFO - 'XPC_SERVICE_NAME': '0', 04:43:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:43:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:43:48 INFO - Downloading/unpacking jsonschema==2.5.1 04:43:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:43:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:43:48 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:43:48 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:43:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:43:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:43:51 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 04:43:51 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 04:43:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:43:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:43:51 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:43:51 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:43:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:43:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:43:51 INFO - Downloading functools32-3.2.3-2.tar.gz 04:43:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 04:43:51 INFO - warning: no files found matching '*.txt' 04:43:51 INFO - no previously-included directories found matching 'build' 04:43:51 INFO - no previously-included directories found matching 'dist' 04:43:51 INFO - no previously-included directories found matching '.git*' 04:43:51 INFO - Installing collected packages: jsonschema, functools32 04:43:51 INFO - Running setup.py install for functools32 04:43:52 INFO - warning: no files found matching '*.txt' 04:43:52 INFO - no previously-included directories found matching 'build' 04:43:52 INFO - no previously-included directories found matching 'dist' 04:43:52 INFO - no previously-included directories found matching '.git*' 04:43:52 INFO - Successfully installed jsonschema functools32 04:43:52 INFO - Cleaning up... 04:43:52 INFO - Return code: 0 04:43:52 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 04:43:52 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')]} 04:43:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:43:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:43:52 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')]} 04:43:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:43:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:43:52 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 0x10a8e0b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a8e3768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff5f3cb6280>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a6bed30>, '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 0x10a5e5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a6b3be8>, '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.7lyFfe1O9i/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.xSoWRdYIKb/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/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 04:43:52 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 04:43:52 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 04:43:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7lyFfe1O9i/Render', 04:43:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:43:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:43:52 INFO - 'HOME': '/Users/cltbld', 04:43:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:43:52 INFO - 'LOGNAME': 'cltbld', 04:43:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:43:52 INFO - 'MOZ_NO_REMOTE': '1', 04:43:52 INFO - 'NO_EM_RESTART': '1', 04:43:52 INFO - 'PAGER': '/bin/cat', 04:43:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:43:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:43:52 INFO - 'PWD': '/builds/slave/test', 04:43:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:43:52 INFO - 'SHELL': '/bin/bash', 04:43:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xSoWRdYIKb/Listeners', 04:43:52 INFO - 'TMPDIR': '/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/', 04:43:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:43:52 INFO - 'USER': 'cltbld', 04:43:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:43:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:43:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:43:52 INFO - 'XPC_FLAGS': '0x0', 04:43:52 INFO - 'XPC_SERVICE_NAME': '0', 04:43:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:43:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:43:52 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 04:43:52 INFO - Cleaning up... 04:43:52 INFO - Return code: 0 04:43:52 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 04:43:52 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')]} 04:43:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:43:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:43:52 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')]} 04:43:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:43:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:43:52 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 0x10a8e0b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a8e3768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff5f3cb6280>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a6bed30>, '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 0x10a5e5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a6b3be8>, '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.7lyFfe1O9i/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.xSoWRdYIKb/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/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 04:43:52 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 04:43:52 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 04:43:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7lyFfe1O9i/Render', 04:43:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:43:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:43:52 INFO - 'HOME': '/Users/cltbld', 04:43:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:43:52 INFO - 'LOGNAME': 'cltbld', 04:43:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:43:52 INFO - 'MOZ_NO_REMOTE': '1', 04:43:52 INFO - 'NO_EM_RESTART': '1', 04:43:52 INFO - 'PAGER': '/bin/cat', 04:43:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:43:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:43:52 INFO - 'PWD': '/builds/slave/test', 04:43:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:43:52 INFO - 'SHELL': '/bin/bash', 04:43:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xSoWRdYIKb/Listeners', 04:43:52 INFO - 'TMPDIR': '/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/', 04:43:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:43:52 INFO - 'USER': 'cltbld', 04:43:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:43:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:43:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:43:52 INFO - 'XPC_FLAGS': '0x0', 04:43:52 INFO - 'XPC_SERVICE_NAME': '0', 04:43:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:43:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:43:52 INFO - Downloading/unpacking blobuploader==1.2.4 04:43:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:43:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:43:52 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:43:52 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:43:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:43:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:43:55 INFO - Downloading blobuploader-1.2.4.tar.gz 04:43:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 04:43:55 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 04:43:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:43:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:43:55 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:43:55 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:43:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:43:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:43:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 04:43:56 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 04:43:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:43:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:43:56 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:43:56 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:43:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:43:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:43:56 INFO - Downloading docopt-0.6.1.tar.gz 04:43:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 04:43:56 INFO - Installing collected packages: blobuploader, requests, docopt 04:43:56 INFO - Running setup.py install for blobuploader 04:43:56 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 04:43:56 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 04:43:56 INFO - Running setup.py install for requests 04:43:57 INFO - Running setup.py install for docopt 04:43:57 INFO - Successfully installed blobuploader requests docopt 04:43:57 INFO - Cleaning up... 04:43:57 INFO - Return code: 0 04:43:57 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:43:57 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')]} 04:43:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:43:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:43:57 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')]} 04:43:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:43:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:43:57 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 0x10a8e0b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a8e3768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff5f3cb6280>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a6bed30>, '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 0x10a5e5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a6b3be8>, '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.7lyFfe1O9i/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.xSoWRdYIKb/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/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 04:43:57 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 04:43:57 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 04:43:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7lyFfe1O9i/Render', 04:43:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:43:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:43:57 INFO - 'HOME': '/Users/cltbld', 04:43:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:43:57 INFO - 'LOGNAME': 'cltbld', 04:43:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:43:57 INFO - 'MOZ_NO_REMOTE': '1', 04:43:57 INFO - 'NO_EM_RESTART': '1', 04:43:57 INFO - 'PAGER': '/bin/cat', 04:43:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:43:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:43:57 INFO - 'PWD': '/builds/slave/test', 04:43:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:43:57 INFO - 'SHELL': '/bin/bash', 04:43:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xSoWRdYIKb/Listeners', 04:43:57 INFO - 'TMPDIR': '/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/', 04:43:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:43:57 INFO - 'USER': 'cltbld', 04:43:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:43:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:43:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:43:57 INFO - 'XPC_FLAGS': '0x0', 04:43:57 INFO - 'XPC_SERVICE_NAME': '0', 04:43:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:43:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:43:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:43:57 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-sgmAQs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:43:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:43:57 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-l1AczE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:43:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:43:57 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-izDlDx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:43:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:43:58 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-550DVN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:43:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:43:58 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-PA8KG3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:43:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:43:58 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-lrJaOU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:43:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:43:58 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-hlgTlZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:43:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:43:58 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-KSJAGf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:43:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:43:58 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-N4SDrr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:43:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:43:58 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-RP3Iqi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:43:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:43:58 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-nTwPX4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:43:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:43:58 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-Q5_IjU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:43:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:43:58 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-IPzXnE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:43:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:43:59 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-4FeBS9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:43:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:43:59 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-R6eeKL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:43:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:43:59 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-D8DMc_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:43:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:43:59 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-FhgUdH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:43:59 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 04:43:59 INFO - Running setup.py install for manifestparser 04:43:59 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 04:43:59 INFO - Running setup.py install for mozcrash 04:43:59 INFO - Running setup.py install for mozdebug 04:43:59 INFO - Running setup.py install for mozdevice 04:44:00 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 04:44:00 INFO - Installing dm script to /builds/slave/test/build/venv/bin 04:44:00 INFO - Running setup.py install for mozfile 04:44:00 INFO - Running setup.py install for mozhttpd 04:44:00 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 04:44:00 INFO - Running setup.py install for mozinfo 04:44:00 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 04:44:00 INFO - Running setup.py install for mozInstall 04:44:00 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 04:44:00 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 04:44:00 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 04:44:00 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 04:44:00 INFO - Running setup.py install for mozleak 04:44:00 INFO - Running setup.py install for mozlog 04:44:00 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 04:44:00 INFO - Running setup.py install for moznetwork 04:44:01 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 04:44:01 INFO - Running setup.py install for mozprocess 04:44:01 INFO - Running setup.py install for mozprofile 04:44:01 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 04:44:01 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 04:44:01 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 04:44:01 INFO - Running setup.py install for mozrunner 04:44:01 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 04:44:01 INFO - Running setup.py install for mozscreenshot 04:44:01 INFO - Running setup.py install for moztest 04:44:01 INFO - Running setup.py install for mozversion 04:44:02 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 04:44:02 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 04:44:02 INFO - Cleaning up... 04:44:02 INFO - Return code: 0 04:44:02 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:44:02 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')]} 04:44:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:44:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:44:02 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')]} 04:44:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:44:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:44:02 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 0x10a8e0b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a8e3768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff5f3cb6280>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a6bed30>, '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 0x10a5e5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a6b3be8>, '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.7lyFfe1O9i/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.xSoWRdYIKb/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/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 04:44:02 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 04:44:02 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 04:44:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7lyFfe1O9i/Render', 04:44:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:44:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:44:02 INFO - 'HOME': '/Users/cltbld', 04:44:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:44:02 INFO - 'LOGNAME': 'cltbld', 04:44:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:44:02 INFO - 'MOZ_NO_REMOTE': '1', 04:44:02 INFO - 'NO_EM_RESTART': '1', 04:44:02 INFO - 'PAGER': '/bin/cat', 04:44:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:44:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:44:02 INFO - 'PWD': '/builds/slave/test', 04:44:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:44:02 INFO - 'SHELL': '/bin/bash', 04:44:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xSoWRdYIKb/Listeners', 04:44:02 INFO - 'TMPDIR': '/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/', 04:44:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:44:02 INFO - 'USER': 'cltbld', 04:44:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:44:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:44:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:44:02 INFO - 'XPC_FLAGS': '0x0', 04:44:02 INFO - 'XPC_SERVICE_NAME': '0', 04:44:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:44:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:44:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:44:02 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-40RnjJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:44:02 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)) 04:44:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:44:02 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-anj5Jx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:44:02 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)) 04:44:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:44:02 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-wBsu1W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:44:02 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)) 04:44:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:44:02 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-xf9rxH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:44:02 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)) 04:44:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:44:02 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-o3sqyC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:44:02 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)) 04:44:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:44:02 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-pCs3UA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:44:02 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)) 04:44:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:44:02 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-l1KPnd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:44:03 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)) 04:44:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:44:03 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-hNZhlv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:44:03 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)) 04:44:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:44:03 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-wi7y1O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:44:03 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)) 04:44:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:44:03 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-BOY8Tv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:44:03 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)) 04:44:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:44:03 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-f80KHr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:44:03 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)) 04:44:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:44:03 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-V7CzNQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:44:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 04:44:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:44:03 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-gSza28-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:44:03 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)) 04:44:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:44:03 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-h1V6jX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:44:03 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)) 04:44:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:44:03 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-ED352L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:44:03 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)) 04:44:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:44:03 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-zHg_jo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:44:04 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)) 04:44:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:44:04 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-F39LoJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:44:04 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)) 04:44:04 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)) 04:44:04 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)) 04:44:04 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)) 04:44:04 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)) 04:44:04 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)) 04:44:04 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 04:44:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:44:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:44:04 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:44:04 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:44:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:44:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:44:06 INFO - Downloading blessings-1.6.tar.gz 04:44:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 04:44:06 INFO - Installing collected packages: blessings 04:44:06 INFO - Running setup.py install for blessings 04:44:06 INFO - Successfully installed blessings 04:44:06 INFO - Cleaning up... 04:44:07 INFO - Return code: 0 04:44:07 INFO - Done creating virtualenv /builds/slave/test/build/venv. 04:44:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:44:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:44:07 INFO - Reading from file tmpfile_stdout 04:44:07 INFO - Current package versions: 04:44:07 INFO - blessings == 1.6 04:44:07 INFO - blobuploader == 1.2.4 04:44:07 INFO - docopt == 0.6.1 04:44:07 INFO - functools32 == 3.2.3-2 04:44:07 INFO - jsonschema == 2.5.1 04:44:07 INFO - manifestparser == 1.1 04:44:07 INFO - mozInstall == 1.12 04:44:07 INFO - mozcrash == 1.0 04:44:07 INFO - mozdebug == 0.1 04:44:07 INFO - mozdevice == 0.49 04:44:07 INFO - mozfile == 1.2 04:44:07 INFO - mozhttpd == 0.7 04:44:07 INFO - mozinfo == 0.9 04:44:07 INFO - mozleak == 0.1 04:44:07 INFO - mozlog == 3.4 04:44:07 INFO - moznetwork == 0.27 04:44:07 INFO - mozprocess == 0.25 04:44:07 INFO - mozprofile == 0.28 04:44:07 INFO - mozrunner == 6.13 04:44:07 INFO - mozscreenshot == 0.1 04:44:07 INFO - mozsystemmonitor == 0.3 04:44:07 INFO - moztest == 0.8 04:44:07 INFO - mozversion == 1.4 04:44:07 INFO - psutil == 3.1.1 04:44:07 INFO - requests == 1.2.3 04:44:07 INFO - wsgiref == 0.1.2 04:44:07 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:44:07 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')]} 04:44:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:44:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:44:07 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')]} 04:44:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:44:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:44:07 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 0x10a8e0b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a8e3768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff5f3cb6280>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a6bed30>, '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 0x10a5e5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a6b3be8>, '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.7lyFfe1O9i/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.xSoWRdYIKb/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/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 04:44:07 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 04:44:07 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 04:44:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7lyFfe1O9i/Render', 04:44:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:44:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:44:07 INFO - 'HOME': '/Users/cltbld', 04:44:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:44:07 INFO - 'LOGNAME': 'cltbld', 04:44:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:44:07 INFO - 'MOZ_NO_REMOTE': '1', 04:44:07 INFO - 'NO_EM_RESTART': '1', 04:44:07 INFO - 'PAGER': '/bin/cat', 04:44:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:44:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:44:07 INFO - 'PWD': '/builds/slave/test', 04:44:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:44:07 INFO - 'SHELL': '/bin/bash', 04:44:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xSoWRdYIKb/Listeners', 04:44:07 INFO - 'TMPDIR': '/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/', 04:44:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:44:07 INFO - 'USER': 'cltbld', 04:44:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:44:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:44:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:44:07 INFO - 'XPC_FLAGS': '0x0', 04:44:07 INFO - 'XPC_SERVICE_NAME': '0', 04:44:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:44:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:44:07 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)) 04:44:07 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)) 04:44:07 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)) 04:44:07 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)) 04:44:07 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)) 04:44:07 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)) 04:44:07 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)) 04:44:07 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)) 04:44:07 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)) 04:44:07 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)) 04:44:07 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)) 04:44:07 INFO - Cleaning up... 04:44:07 INFO - Return code: 0 04:44:07 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 04:44:07 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')]} 04:44:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:44:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:44:07 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')]} 04:44:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:44:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:44:07 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 0x10a8e0b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a8e3768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff5f3cb6280>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a6bed30>, '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 0x10a5e5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a6b3be8>, '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.7lyFfe1O9i/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.xSoWRdYIKb/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/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 04:44:07 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 04:44:07 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 04:44:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7lyFfe1O9i/Render', 04:44:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:44:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:44:07 INFO - 'HOME': '/Users/cltbld', 04:44:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:44:07 INFO - 'LOGNAME': 'cltbld', 04:44:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:44:07 INFO - 'MOZ_NO_REMOTE': '1', 04:44:07 INFO - 'NO_EM_RESTART': '1', 04:44:07 INFO - 'PAGER': '/bin/cat', 04:44:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:44:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:44:07 INFO - 'PWD': '/builds/slave/test', 04:44:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:44:07 INFO - 'SHELL': '/bin/bash', 04:44:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xSoWRdYIKb/Listeners', 04:44:07 INFO - 'TMPDIR': '/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/', 04:44:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:44:07 INFO - 'USER': 'cltbld', 04:44:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:44:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:44:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:44:07 INFO - 'XPC_FLAGS': '0x0', 04:44:07 INFO - 'XPC_SERVICE_NAME': '0', 04:44:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:44:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:44:07 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 04:44:07 INFO - Cleaning up... 04:44:07 INFO - Return code: 0 04:44:07 INFO - Running post-action listener: _resource_record_post_action 04:44:07 INFO - Running post-action listener: _start_resource_monitoring 04:44:07 INFO - Starting resource monitoring. 04:44:07 INFO - [mozharness: 2017-02-25 12:44:07.952889Z] Finished create-virtualenv step (success) 04:44:07 INFO - [mozharness: 2017-02-25 12:44:07.953907Z] Running install step. 04:44:07 INFO - Running pre-action listener: _resource_record_pre_action 04:44:07 INFO - Running main action method: install 04:44:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:44:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:44:08 INFO - Reading from file tmpfile_stdout 04:44:08 INFO - Detecting whether we're running mozinstall >=1.0... 04:44:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 04:44:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 04:44:08 INFO - Reading from file tmpfile_stdout 04:44:08 INFO - Output received: 04:44:08 INFO - Usage: mozinstall [options] installer 04:44:08 INFO - Options: 04:44:08 INFO - -h, --help show this help message and exit 04:44:08 INFO - -d DEST, --destination=DEST 04:44:08 INFO - Directory to install application into. [default: 04:44:08 INFO - "/builds/slave/test"] 04:44:08 INFO - --app=APP Application being installed. [default: firefox] 04:44:08 INFO - mkdir: /builds/slave/test/build/application 04:44:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-54.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 04:44:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-54.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 04:44:28 INFO - Reading from file tmpfile_stdout 04:44:28 INFO - Output received: 04:44:28 INFO - /builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox 04:44:28 INFO - Running post-action listener: _resource_record_post_action 04:44:28 INFO - [mozharness: 2017-02-25 12:44:28.938926Z] Finished install step (success) 04:44:28 INFO - [mozharness: 2017-02-25 12:44:28.939092Z] Running run-tests step. 04:44:28 INFO - Running pre-action listener: _resource_record_pre_action 04:44:28 INFO - Running main action method: run_tests 04:44:28 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 04:44:28 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 04:44:28 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 04:44:28 INFO - 2017-02-25 04:44:28.953 screenresolution[1938:25858] starting screenresolution argv=screenresolution get 04:44:28 INFO - 2017-02-25 04:44:28.956 screenresolution[1938:25858] Display 0: 1600x1200x32@60 04:44:28 INFO - 2017-02-25 04:44:28.962 screenresolution[1939:25861] starting screenresolution argv=screenresolution list 04:44:28 INFO - Available Modes on Display 0 04:44:28 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 04:44:28 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 04:44:28 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 04:44:28 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 04:44:29 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 04:44:29 INFO - Intel Iris: 04:44:29 INFO - Chipset Model: Intel Iris 04:44:29 INFO - Type: GPU 04:44:29 INFO - Bus: Built-In 04:44:29 INFO - VRAM (Dynamic, Max): 1536 MB 04:44:29 INFO - Vendor: Intel (0x8086) 04:44:29 INFO - Device ID: 0x0a2e 04:44:29 INFO - Revision ID: 0x0009 04:44:29 INFO - Displays: 04:44:29 INFO - Display: 04:44:29 INFO - Resolution: 1600 x 1200 @ 60 Hz 04:44:29 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 04:44:29 INFO - Display Serial Number: 1600x1200 60 04:44:29 INFO - Main Display: Yes 04:44:29 INFO - Mirror: Off 04:44:29 INFO - Online: Yes 04:44:29 INFO - Rotation: Supported 04:44:29 INFO - Return code: 0 04:44:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 04:44:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 04:44:29 INFO - Python 2.7.3 04:44:29 INFO - Return code: 0 04:44:29 INFO - grabbing minidump binary from tooltool 04:44:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:44:29 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 0x10a6bed30>, '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 0x10a5e5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a6b3be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 04:44:29 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 04:44:29 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 04:44:29 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 04:44:29 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 04:44:29 INFO - Return code: 0 04:44:29 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 04:44:29 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 04:44:29 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 04:44:29 INFO - ENV: RUST_BACKTRACE is now 1 04:44:29 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 04:44:29 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 04:44:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/GzKaH1wOT52yWevZhcA6qg/artifacts/public/build/firefox-54.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0012', '--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 04:44:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/GzKaH1wOT52yWevZhcA6qg/artifacts/public/build/firefox-54.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0012 --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 04:44:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7lyFfe1O9i/Render', 04:44:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:44:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:44:29 INFO - 'HOME': '/Users/cltbld', 04:44:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:44:29 INFO - 'LOGNAME': 'cltbld', 04:44:29 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 04:44:29 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 04:44:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:44:29 INFO - 'MOZ_NO_REMOTE': '1', 04:44:29 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 04:44:29 INFO - 'NO_EM_RESTART': '1', 04:44:29 INFO - 'PAGER': '/bin/cat', 04:44:29 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:44:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:44:29 INFO - 'PWD': '/builds/slave/test', 04:44:29 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 04:44:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:44:29 INFO - 'RUST_BACKTRACE': '1', 04:44:29 INFO - 'SHELL': '/bin/bash', 04:44:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xSoWRdYIKb/Listeners', 04:44:29 INFO - 'TMPDIR': '/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/', 04:44:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:44:29 INFO - 'USER': 'cltbld', 04:44:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:44:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:44:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:44:29 INFO - 'XPC_FLAGS': '0x0', 04:44:29 INFO - 'XPC_SERVICE_NAME': '0', 04:44:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:44:29 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/GzKaH1wOT52yWevZhcA6qg/artifacts/public/build/firefox-54.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0012', '--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 04:44:29 INFO - mozversion application_buildid: 20170225030208 04:44:29 INFO - mozversion application_changeset: f36062d04d165f6f6e781cf0633ffcbbebe6c273 04:44:29 INFO - mozversion application_display_name: Nightly 04:44:29 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 04:44:29 INFO - mozversion application_name: Firefox 04:44:29 INFO - mozversion application_remotingname: firefox 04:44:29 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 04:44:29 INFO - mozversion application_vendor: Mozilla 04:44:29 INFO - mozversion application_version: 54.0a1 04:44:29 INFO - mozversion platform_buildid: 20170225030208 04:44:29 INFO - mozversion platform_changeset: f36062d04d165f6f6e781cf0633ffcbbebe6c273 04:44:29 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 04:44:29 INFO - mozversion platform_version: 54.0a1 04:44:29 INFO - using testdate: 1488026669 04:44:29 INFO - actual date: 1488026669 04:44:29 INFO - starting webserver on 'localhost:57137' 04:44:29 INFO - SUITE-START | Running 2 tests 04:44:29 INFO - TEST-START | dromaeo_css 04:44:29 INFO - operating with platform_type : mac_ 04:44:29 INFO - Initialising browser for dromaeo_css test... 04:44:29 INFO - TEST-INFO | started process 1949 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/tmpn_L8yf/profile http://localhost:57137/getInfo.html) 04:44:32 INFO - PROCESS | 1949 | 2017-02-25 04:44:32.559 plugin-container[1952:26037] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x933f, name = 'com.apple.tsm.portname' 04:44:32 INFO - PROCESS | 1949 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:44:32 INFO - PROCESS | 1949 | 2017-02-25 04:44:32.559 plugin-container[1952:26037] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x970f, name = 'com.apple.CFPasteboardClient' 04:44:32 INFO - PROCESS | 1949 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:44:32 INFO - PROCESS | 1949 | 2017-02-25 04:44:32.559 plugin-container[1952:26037] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 04:44:32 INFO - PROCESS | 1949 | __metrics Screen width/height:1600/1200 04:44:32 INFO - PROCESS | 1949 | colorDepth:24 04:44:32 INFO - PROCESS | 1949 | Browser inner width/height: 1024/689 04:44:32 INFO - PROCESS | 1949 | __metrics 04:44:32 ERROR - PROCESS | 1949 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3354: TypeError: this.tabs is undefined 04:44:33 INFO - TEST-INFO | 1949: exit 0 04:44:33 INFO - Browser initialized. 04:44:33 INFO - Running cycle 1/1 for dromaeo_css test... 04:44:33 INFO - TEST-INFO | started process 1954 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/tmpn_L8yf/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:44:36 INFO - PROCESS | 1954 | 2017-02-25 04:44:36.001 plugin-container[1955:26301] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8937, name = 'com.apple.tsm.portname' 04:44:36 INFO - PROCESS | 1954 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:44:36 INFO - PROCESS | 1954 | 2017-02-25 04:44:36.002 plugin-container[1955:26301] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4707, name = 'com.apple.CFPasteboardClient' 04:44:36 INFO - PROCESS | 1954 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:44:36 INFO - PROCESS | 1954 | 2017-02-25 04:44:36.002 plugin-container[1955:26301] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 04:44:45 INFO - PROCESS | 1954 | RSS: Main: 226004992 04:44:45 INFO - PROCESS | 1954 | 04:47:49 INFO - PROCESS | 1954 | Cycle 1(1): loaded http://localhost:57137/tests/dromaeo/cssquery-dojo.html (next: http://localhost:57137/tests/dromaeo/cssquery-ext.html) 04:47:51 INFO - PROCESS | 1954 | RSS: Main: 252047360 04:47:51 INFO - PROCESS | 1954 | 04:49:33 INFO - PROCESS | 1954 | ************************* 04:49:33 INFO - PROCESS | 1954 | A coding exception was thrown and uncaught in a Task. 04:49:33 INFO - PROCESS | 1954 | 04:49:33 ERROR - PROCESS | 1954 | Full message: TypeError: NetworkError when attempting to fetch resource. 04:49:33 INFO - PROCESS | 1954 | Full stack: 04:49:33 INFO - PROCESS | 1954 | ************************* 04:50:53 INFO - PROCESS | 1954 | Cycle 1(1): loaded http://localhost:57137/tests/dromaeo/cssquery-ext.html (next: http://localhost:57137/tests/dromaeo/cssquery-jquery.html) 04:50:54 INFO - PROCESS | 1954 | RSS: Main: 259796992 04:50:54 INFO - PROCESS | 1954 | 04:53:56 INFO - PROCESS | 1954 | Cycle 1(1): loaded http://localhost:57137/tests/dromaeo/cssquery-jquery.html (next: http://localhost:57137/tests/dromaeo/cssquery-mootools.html) 04:53:57 INFO - PROCESS | 1954 | RSS: Main: 270716928 04:53:57 INFO - PROCESS | 1954 | 04:56:59 INFO - PROCESS | 1954 | Cycle 1(1): loaded http://localhost:57137/tests/dromaeo/cssquery-mootools.html (next: http://localhost:57137/tests/dromaeo/cssquery-prototype.html) 04:56:59 INFO - PROCESS | 1954 | RSS: Main: 263716864 04:56:59 INFO - PROCESS | 1954 | 05:00:02 INFO - PROCESS | 1954 | Cycle 1(1): loaded http://localhost:57137/tests/dromaeo/cssquery-prototype.html (next: http://localhost:57137/tests/dromaeo/cssquery-yui.html) 05:00:03 INFO - PROCESS | 1954 | RSS: Main: 273461248 05:00:03 INFO - PROCESS | 1954 | 05:03:06 INFO - PROCESS | 1954 | Cycle 1(1): loaded http://localhost:57137/tests/dromaeo/cssquery-yui.html (next: http://localhost:57137/tests/dromaeo/cssquery-dojo.html) 05:03:07 INFO - PROCESS | 1954 | RSS: Main: 267272192 05:03:07 INFO - PROCESS | 1954 | 05:03:07 INFO - PROCESS | 1954 | __start_tp_report 05:03:07 INFO - PROCESS | 1954 | _x_x_mozilla_page_load 05:03:07 INFO - PROCESS | 1954 | _x_x_mozilla_page_load_details 05:03:07 INFO - PROCESS | 1954 | |i|pagename|runs| 05:03:07 INFO - PROCESS | 1954 | |0;dojo.html;3312.53624492571;3328.667133286671;3329.1510664780462;3335.199552107495;3352.4133276676575;4377.737335759856;5421.644588852781;5543.861403464915;5567.109262517993;5637.661740295585;5110.667806592569;5261.973690131544;5699.629524080932;5704.3154821421385;5706.457886500786;3931.7247792654525;4139.875803725886;5077.289179514871;5238.05714971305;5334.359876814788;5124.231365295217;5176.870578235536;5283.920741188886;5287.339082614673;5298.6555873868265;5185.481451854822;5230;5249.527542521191;5269.894602107936;5296.364436267634;5221.895562088737;5225.399079105876;5247.47525247476;5257.7108259045735;5279.366476022863;4113.670906327487;5004.099262132815;5200.947990520122;5201.349831271091;5224.817131400383;5371.113766228567;5553.583481238883;5617.185508101303;5675.517581005578;5693.946619875295;6262.530310226751;6264.310925798159;6279.560430769848;6280.371962803683;6286.4342209201395;5215.608829337815;6240.254273174254;6281.623102613812;6305.432511074026;6312.084747711556;5055.469175736568;5590.580706447033;5604.887902241973;5658.74535645897;5877.089051197071;5758.481736643722;5862.091375836752;6209.472194863397;6233.283172435137;6244.03217501279;5441.183822426668;5924.555658325643;6017.909731354033;6018.2176317079375;6112.633242005405;6138.602983404986;6222.502199824004;6260.937390626126;6276.46650034743;6276.780312689034;6229.781957631551;6230.940740074198;6244.625322480666;6272.466840318624;6274.717637706223;6045.848853778691;6110.114033465123;6280.246370435486;6290.308066112724;6305.96847015762;4942.728149951715;4952.034353301144;4960.553550180429;4964.975175124174;4968.403791545038;4512.368268442355;4519.231730605805;4536;4547.636189104864;4581.633469322446;5833.620814647034;5838.883222335615;5851.531877449794;5852.268466441695;5872.794452194153;6137.324894261627;6190;6260.029695397129;6278.340774218733;6374.043893416025;5737.913931291034;5742.569307301969;5747.770089196469;5750.568707346965;5753.769849206069;5401.675899446046;5419.430959749248;5421.403645598981;5424.457554244544;5436.456354364611;6153.692315384303;6216.285127210429;6220.409061139185;6269.811905642838;6295.370462953668;6239.188905442264;6253.906191407042;6272.466840318624;6277.435030847246;6282.497400207881;6280.309165991646;6305.337939516285;6308.905366419599;6313.305536391085;6317.368263173646;5954.702264886825;6010.699465026643;6053.334133245251;6098.359672234352;6235.875282494416;6117.857853872152;6120.081455037999;6207.565470417027;6210.254769427697;6234.594751341148;6151.108089327112;6158.507319414526;6168;6169;6184.845378865564;5695.572832037531;5977.252843394575;6134.4172303631085;6162.075688646739;6188.8762224754355;4022.4971878515184;4837.7421870313265;5388.838334849961;6077.270727512727;6112.235970503687;6152.769446110707;6173.876522469435;6194.504439644727;6214.223222097238;6214.751409943552;5774.422557744192;6054.636721796706;6057.303410107717;6074.422855326462;6116.761878222105 05:03:07 INFO - PROCESS | 1954 | |1;ext.html;455.99908201497703;494.50055444001595;500.00249502243025;511.3837825420369;554.7669978608984;37241.627583724156;39465.605343946554;39498.61754838577;39667.80166099165;39689.412423503025;40631.39052914209;41508.37737433941;41643.37534936971;41667.58332416675;41751.79124104375;9278.211352035069;9524.42853428793;10865.34807911524;11304.208705390625;11448.427578621076;10920.836187457155;11053.723656908562;11065;11074.723131921726;11077.335359878392;11067.280626759275;11111.444427778619;11134.220604557684;11140.888591114106;11165.050970667462;11051.778964420748;11112.05547528453;11117.666470005914;11119.332840029543;11160.888391116066;11104.389258590774;11175.72060698489;11177.83233251502;11181.72045698856;11191.608293709682;36141;36165.27669446596;36447;36464;36588.0852978675;3713.9628603713886;3714.461403096536;3717.5724791648768;3721.925561488755;3722.3671975764187;21260.468488287763;22787.54424911509;26548.33629159267;26761.598576021373;28540.571891421645;3049.564134495641;3062.540618907182;3087.259057826128;3102.4570700127615;3214.324991751735;393365;393726;395186;396472;397854;43368.91577710519;43524.56475435269;43845.561544384145;52131.73934130381;52404;42644.57355426468;52301;53855.730721346925;54073.51117865881;54075.45924540783;13897.652558686113;14001.579952601438;14115.929420352833;14132.15207087578;14143.787843182361;10865.402402867918;10878.347299162075;10926.890731092588;10983.450827458595;11004.009639132357;17130.571735706708;17226.483205503853;17343.826561734222;17436.91281543584;17452.738208926876;7883.921160788434;8051.315638170821;8064.112947575762;8066.516009039359;8084.676612935416;833.491380601114;841.2554888923347;852.7356519478982;872.8079822439054;878.3298771733985;26054.86972565125;26500.337491562866;26829.463410731674;27645.03242386507;28844.42311153807;977.8093271812071;1044.738815296176;1058.9364638121738;1060.8143574874366;1070.5985255529176;14478.782818257734;15156.31796569157;15233.918420763364;15428.537143885702;15609;530929;535518.7377514723;535971;536353;536745;57077.71461142751;57294.42705572974;57697;57726.42273577295;57948.130778038365;1343.2612063365111;1406.9929650351887;1412.4844431782326;1414.5119933622884;1415.2357726827397;26661.733382665923;27554.0356087536;27928.30179245454;28160.43679126365;28226.870925162762;46755.53244467648;46869;47240.055198896516;47376.7631161842;47541.81145471391;443895;444691;446546;448668.75665621465;449197;24420.877895610407;25142.74857251404;25195.370115747253;25210.36974075663;25818.35454113662;39258.75289781828;39753.602463976145;39858;40531.79734101311;40688;32538.34923301568;33331.166720832174;34167.65832341645;34311.31377372389;34827.65172348244;27632.309192270353;32773;32886.342273153925;32994.835025824716;34142;29020.854895725388;32605.510917336735;32885.17787055343;33910.83044584761;34146;27592.586111208748;30065.849670751508;31007.379852403275;31492.212694682818;31579.73681052732;22824;22878.542429151657;23229.883850580638;23645.763542364355;23795.40511487227 05:03:07 INFO - PROCESS | 1954 | |2;jquery.html;320.6328753577156;390.65817409766447;393.3293734183218;399.9280129576679;413.7186713035137;17239.65520689587;19903.905285209326;20952.580948381023;21305.680414793773;21335.93320333985;18492.075396230168;21037.263695770653;21384.823834689087;21423.357299281048;21617.243396481128;5789.189513468118;5904.7342869570975;6066.605670631395;6152.538559608047;6180.629162250257;5701.0878259478495;5999.700014999255;6032.306284777241;6103.962326404499;6110.175126357929;5303.416624171338;5650.830475085733;6097.634141951475;6099.390060993887;6102.81691549252;5111.667741596803;5957.927573036852;6026.969865150668;6052.273727152769;6130.233720784902;5938.317093534256;5955.196048533479;5958.433948774859;5958.821235362902;5964.403559644044;21183.788162118337;22272.443188920406;25545.105921292852;26152.476950461074;26239.606405903927;2513.7486251374717;2521.924342269735;2573.61395790631;2595.688517377927;2626.96234987179;9731.41611503312;10127.594896204144;10340.017698318648;10538.894611053867;10542.841857372145;1541.745611974023;1774.325756212644;1784.4379020608467;1840.7699037620298;1853.4254381141889;128786;134663;136943;137656;138311;11249.212555121227;11778.528858845722;11884.465199066062;11967.760644787055;11978;15495.612609684622;15539.5338139856;15559.066456012675;15562.22188890551;15597.844021559866;8224.62989165489;8376.329893608498;8423.747287581382;8466.872996905051;8488.533130677748;7861.331786798072;7959;8001.15987821282;8114.107448180695;8153.143919888326;4727.905441891143;4797.136515427256;4833.10587541306;4900.779464924087;4909.263610458388;1967.9016049197483;2012.2353505667752;2034.6134234495398;2037.2869495676691;2038.2152871144715;1000.9749756256006;1005.1456262177237;1014.2443879309961;1024.1499555369026;1060.9734756630992;20827;20858.26996055161;21376.57246855054;21468;21524.923753812247;999.9100979931764;1029.5984566019258;1049.506731836036;1069.4866464097122;1074.854894589236;9050.773730656654;10359.22305827066;11263.380514071641;11271;11281.774364512663;456183;456868;457429;559336;573795;108844;113820.86179138269;114176.85823141829;119539;146357;939.8026414453042;995.7709726762885;1044.12815299226;1047.4762618690654;1050.2858056521638;16017.718582513604;16040.716742660792;17582.91208543949;19591;19881.30415435453;51948.96102078013;57265;58009.4199058004;58064.12903806531;58314.416855830896;74840;81354.59322703349;85139.5743021281;85417.1458285434;86546;7056.470764692566;8102.959485202537;8191.344692424711;8438.915610844058;8661.52361620117;10598.364098154136;11182.832257515982;12072.3360215189;13003;13056.543020994219;10662.755668305108;11104.333739975627;11128.165387596124;11175.882411759085;11349.205555611028;10952.45237738094;11005.064569511682;11247.493862776031;11400.714982125513;11444.542218311175;11017.283876548;11027.944860275647;11192.265198894185;11495.195336326376;11567.942160289145;7983.1617680142745;8250.38122140854;8304.958475207586;8424.536650484282;8555.272801811916;10324;10873.673789786319;11088.501017454073;11089.33463992163;11209.55161793519 05:03:07 INFO - PROCESS | 1954 | |3;mootools.html;1437.4753215076496;1439.5609339151554;1440.7406666799984;1449.536148432502;1470.9411623535057;7325.230850760673;7573.204813494586;7936.246056624619;8493.278071363942;8494;7929.92070079299;8256.50460972342;8256.83486330273;8351.206635369645;8355.37334699897;2211.999999999996;2255.548890221954;2257.3566533537946;2257.8758168300737;2258.108047321307;2255.605269077913;2284.543091381722;2286.4398222435443;2291.495870908406;2299.793018628323;2237.2505210754416;2248.550289942018;2272.488690044735;2275.4311422144465;2278.316505048487;2219.622664147099;2219.9445013874783;2246.921357752487;2247.8538894971775;2251.0995601759264;1902.6955687090137;2197.747259065212;2262.502249505106;2268.4669102760836;2282.052948026567;8382.832343353099;8425.283850872684;8445.986481622245;8462.02686691026;8469.576521173916;4977.751112444364;5145.125328694131;5146.459621739701;5163.7159956202395;5175.223716442526;3192.728618067467;3286.2441638423297;3426.794392336468;3477.669621385964;3518.2787528556582;1080.9027187553158;1171.37916904041;1180.262336039975;1201.0871737479579;1224.3878060969516;9405.952970235174;9864.210863130933;10527.420991845445;10658.253922225369;10681.732956676067;4355.80398882051;4540.046590216026;4642.65180111493;4646;4656.580907718321;7984.760457186294;9462.479563623929;9502.904970950201;9533.237341012702;9547.093026162504;4391.3412988051405;5158.587313014949;5176.5858731301405;5179.741012949337;5191.273221748958;4106.568810274938;4466.441694788152;4617.284320930261;4665.696729712626;4705.811767529329;3736.2527494500664;3765.8493660253284;3840.846366145379;3855.5951625079524;3878.45701601776;1041.692700653294;1234.9073819463576;1240.2868350698384;1251.4368534159664;1252.5177806544364;870.9331069439885;877.7586163804878;883.7834730490943;893.6827426263712;919.1865199298669;5343.5457986071615;5550.8057217997175;5625.831225063254;5741.339745929188;5810.1865738796605;554.603458527155;557.225456615305;570.1504757910683;579.3395529096833;587.1515659871445;2930.7508861746564;3581.4985901973746;3810.3903375459795;3834.731568790158;3870.9806450968126;9542.188913942255;9900.900990990142;9920.057594528509;9922.950385248028;9948.104670579683;9790.902090979142;9889.45607991553;9892.25808064398;9895.851562226428;9918.652847150459;7580.924190758132;7648.694052237959;7737;7827.608619568999;7837.09873364559;5294.993951149269;5486.917696234639;5503.944960550343;5527.281453411031;5567.137093750475;9047.592858321523;9171.312151588523;9197.862032069657;9206.447613143233;9226.815463690555;8941.642334306554;9323.766905827408;9363.906360936304;9367.85948210791;9385.343025988115;6344.746210151542;6505.739770409131;6839.93160068393;7027.754028608974;7103;7723.227677232457;7727.574983375744;7872.291493765589;7935.880961785693;7949.364050876032;6850.45196384298;7032.824179395556;7053.788386348418;7111.217766045835;7172.103487064117;6786.898196527155;6948.687309071205;6968.7212511501;7021.368076873106;7040.788776336718;6117.449429551361;6311.715972781144;6417.358264173732;6435.324290949383;6456.644884531396;4987.326710894128;5052;5069.087564238473;5079.517445842639;5153.948460515497;4914.5822605078965;4988.72562009093;4989;5094.592432605456;5215.6609820361555 05:03:07 INFO - PROCESS | 1954 | |4;prototype.html;341.08248810699223;345.7596970105777;352.35870715298165;352.858856457417;358.743498398645;4282.678799090065;5182.352205974254;5410.540104091152;5462.617616766828;5496.4503549645015;5370.946290537094;5383.380911195214;5388.91916621251;5454.890902181954;5464.316960379952;3901.804909754515;3983.462232598591;4086.6322031017203;4090.4886889138857;4101.200265948141;3989.4015897615445;4068.084680946793;4082.7958602069925;4105.425240466322;4113.403556484308;3699.778013319196;3749.700023998087;4036.253293140778;4066.2883995300854;4071.9185616287655;3904.4338570907057;3996.86010989614;4014.879553613396;4103.322951712964;4141.420201171838;3027.031349968011;3446.965910226947;3472.6353732858192;3490.458978858281;3498.5801703795455;4550.749708766017;5114.488551144893;5209.557187639018;5238.214267859814;5286.352416832373;4349.391085248068;4401.779911004469;4620.191466493384;4648.372469716613;4667.603253723438;5117.8464646060675;5544.584156188302;5574.191742197399;5665.507176842177;5691.48776610103;3209.935801283961;3850.9614903850884;3857.40210267409;3871.5741268460447;3882.864099756523;4452.287633978548;4998.450170481244;5910.079224252092;5985.910211346833;6037.516998640098;3158.952615710766;3393.694567488938;3702.926151416113;3852.9036774080873;3865.9226815464094;3572.4284114541547;3851.807409629507;3900.180961998012;3916.706247031427;3952.802359882052;4426.623736982328;4527.977360113245;4631.309572006194;4638.721676699409;4666.789994450258;4409.581089796464;4936.3216506388;5250.448702886219;5676.545876329884;5733.617165872072;1113.209621168963;1179.1156632525606;1205.1744554979866;1261.9305938173488;1262.9328217656066;798.4051881348432;830.2195935820309;842.7050532313641;860.8622620380709;865.3380164174412;4500.909981800346;4989.600831933437;4995.325631039763;5024.346834911438;5065.594752419871;4700.811967521261;4803.279508073747;5286.735663216824;5495.697736624444;5592.741633132594;4103.876883693434;4210.389493523483;4458.286674132123;4662.930056049162;5001.974990125026;2481.838680485763;2807.6663584797057;2887.422515496867;2918.83946382951;2939.2064142681356;4789.928151077736;5229.398619158846;5557.44425557441;5792.696643255318;5846.064629659234;4603.401557797465;5030.4718004608985;5191.480851914854;5691.8616276744815;5715.571332150105;5069.8732531687;5494.615376923657;5575.553955683617;5853.536615846038;5891.558133140032;4767.854465490474;5687.7724891005555;5802.47777700009;5806.4193580641595;5877.706114694333;4394.780260987001;5244.632875698664;5777.884442311046;5789.33422656383;5823.534996676971;6086.573939824169;6088.6042407243385;6159.844054580861;6161.6919154041225;6179.115369812251;3865.9613403866724;3897.279003384383;3898.96101038986;3912.2175564887707;3930.5479869815335;4943.826966056315;5709.571782116441;5779.422057794187;5787.999077531487;5894.970525147518;5442.482964118402;5596.38439771633;5666.546676265806;5705.516565693033;5708.286464191976;4768.427788665383;5699.287589051369;5705.885882282248;5725.541956643542;5758.769649213985;4326.653867690514;5541.806036788693;5545;5561.857550483964;5569.331680198402;4767.332573439652;5655.2648155741135;5678;5702.146802289356;5740.942590574041;4452.50297199824;4940.728259945738;5187.481251874782;5563.471470210324;5582.888342233214 05:03:07 INFO - PROCESS | 1954 | |5;yui.html;693.9791806245811;733.9669714862831;761.6610608279318;791.1337085890949;824.5588610093599;14344.71310573788;14758.926205368985;15358.155301458415;15373.461928832494;15398.307076181556;14142.505012324546;14230.359633816477;14256.073355231903;14267.643308917282;14282.357293921772;879.5162660536711;904.023654453189;904.0733248420356;904.2404380320529;904.7150147703485;863.8315528471951;894.4365050018479;894.5840184314293;900.4102312984973;902.4765635931177;933.3653115881198;937.1846493550621;941.5668792355491;943.1511639524414;943.9244860411185;927.638221093774;934.4486752815837;935.7660584853752;936.2509992006436;938.957746901391;625.9843503912393;687.1239170058216;691.4295706042535;692.5117791956709;692.9618870962095;14568.562943111723;15235.542933712006;15402.537923862303;15556.844431555654;15578.610534736723;13536;13579.864201358057;13855.099418537762;13889.861101388959;13934.512292069729;11206.271592346553;11294.717632059183;11498.540058397653;11513.194076414655;11693.415329233505;3584.211473475857;3623.836927338276;3677.0807298175455;3749.343220549021;3776.9244615107546;97639;102330.48834755854;103091.4845425768;104529.47735261275;105326.99999999923;10500.842487362695;10519.158467322595;10609.893901061045;10722.142228621848;11136.33182009082;20586.353091752622;22233.666495002588;22262.77737222607;22315;22338.66492002621;5922.289325280908;6469.611823290617;6952.826179345557;7206.711731530785;7227.819304517429;4024.5170579530645;4135.60711732385;4136.586341365839;4138.668906487474;4142.399352093929;3601.5858176309534;3657;3680.5399325084363;3712.238991006891;3747.606501317377;654.5385503220218;657.4181849063613;659.9144407449814;671.4896678524357;680.1328306409268;627.9403456671608;635.9284605439756;650.187265917603;651.9445847102954;682.3892616108556;10004.54979525923;10370.222233332379;10598.629047983284;10811.56753729842;11182.217244792786;527.9625535822128;545.3374150407195;567.1747607231468;598.1207624791549;672.3108813466237;4817.13291607514;4860.1737704590305;4898.779554919965;4898.90202195954;4950.084234416644;76081.61959190169;79769.80345294825;81154.59422702849;82761;85860;74779.5044099115;77950;79496;79534.602326988;80752;44998;63097.68451157715;63454.36545634577;63594;67070;7336.596487193042;7693;8667;10216.846747298945;10515.894841051491;42757.78621106874;44504.33243501285;45171.774141129084;45904.622861314216;48398.51601484081;43104.78447607742;47033.29450058183;57234.14148787855;57550.27349179531;62082;12227.266364018187;12316.101703322336;12330.383480826102;12332.81500777507;12727.109102362745;13688.110272832235;13739.313034348443;13760.31198440054;13799.2410417424;14023.439062437388;9894.851577226204;12505.56230531927;12566.680498547521;12792;13031.413586388824;12654.304013279358;12658.177218480769;12765.255470073978;12781.936090319488;13097.25322188421;12243;12486.687832804253;13061.477540898257;13125.015623828442;13189.868101318863;9676.806463870824;9687.70936871895;9910.752231194278;9947.452890090824;9956.701298961043;9278.232299814947;9918.851217231891;9949.900500994898;10494.317869338469;10529.157667386438 05:03:07 INFO - PROCESS | 1954 | __end_tp_report 05:03:07 INFO - PROCESS | 1954 | __start_cc_report 05:03:07 INFO - PROCESS | 1954 | _x_x_mozilla_cycle_collect,426 05:03:07 INFO - PROCESS | 1954 | __end_cc_report 05:03:07 INFO - PROCESS | 1954 | __startTimestamp1488027787121__endTimestamp 05:03:07 INFO - PROCESS | 1954 | 05:03:07 INFO - PROCESS | 1954 | ------- Summary: start ------- 05:03:07 INFO - PROCESS | 1954 | Number of tests: 6 05:03:07 INFO - PROCESS | 1954 | 05:03:07 INFO - PROCESS | 1954 | [#0] dojo.html Cycles:165 Average:5672.81 Median:5872.79 stddev:693.57 (11.8%) stddev-sans-first:670.52 05:03:07 INFO - PROCESS | 1954 | Values: 3312.5 3328.7 3329.2 3335.2 3352.4 4377.7 5421.6 5543.9 5567.1 5637.7 5110.7 5262.0 5699.6 5704.3 5706.5 3931.7 4139.9 5077.3 5238.1 5334.4 5124.2 5176.9 5283.9 5287.3 5298.7 5185.5 5230.0 5249.5 5269.9 5296.4 5221.9 5225.4 5247.5 5257.7 5279.4 4113.7 5004.1 5200.9 5201.3 5224.8 5371.1 5553.6 5617.2 5675.5 5693.9 6262.5 6264.3 6279.6 6280.4 6286.4 5215.6 6240.3 6281.6 6305.4 6312.1 5055.5 5590.6 5604.9 5658.7 5877.1 5758.5 5862.1 6209.5 6233.3 6244.0 5441.2 5924.6 6017.9 6018.2 6112.6 6138.6 6222.5 6260.9 6276.5 6276.8 6229.8 6230.9 6244.6 6272.5 6274.7 6045.8 6110.1 6280.2 6290.3 6306.0 4942.7 4952.0 4960.6 4965.0 4968.4 4512.4 4519.2 4536.0 4547.6 4581.6 5833.6 5838.9 5851.5 5852.3 5872.8 6137.3 6190.0 6260.0 6278.3 6374.0 5737.9 5742.6 5747.8 5750.6 5753.8 5401.7 5419.4 5421.4 5424.5 5436.5 6153.7 6216.3 6220.4 6269.8 6295.4 6239.2 6253.9 6272.5 6277.4 6282.5 6280.3 6305.3 6308.9 6313.3 6317.4 5954.7 6010.7 6053.3 6098.4 6235.9 6117.9 6120.1 6207.6 6210.3 6234.6 6151.1 6158.5 6168.0 6169.0 6184.8 5695.6 5977.3 6134.4 6162.1 6188.9 4022.5 4837.7 5388.8 6077.3 6112.2 6152.8 6173.9 6194.5 6214.2 6214.8 5774.4 6054.6 6057.3 6074.4 6116.8 05:03:07 INFO - PROCESS | 1954 | 05:03:07 INFO - PROCESS | 1954 | [#1] ext.html Cycles:180 Average:59040.19 Median:32689.26 stddev:123068.60 (376.5%) stddev-sans-first:123335.23 05:03:07 INFO - PROCESS | 1954 | Values: 456.0 494.5 500.0 511.4 554.8 37241.6 39465.6 39498.6 39667.8 39689.4 40631.4 41508.4 41643.4 41667.6 41751.8 9278.2 9524.4 10865.3 11304.2 11448.4 10920.8 11053.7 11065.0 11074.7 11077.3 11067.3 11111.4 11134.2 11140.9 11165.1 11051.8 11112.1 11117.7 11119.3 11160.9 11104.4 11175.7 11177.8 11181.7 11191.6 36141.0 36165.3 36447.0 36464.0 36588.1 3714.0 3714.5 3717.6 3721.9 3722.4 21260.5 22787.5 26548.3 26761.6 28540.6 3049.6 3062.5 3087.3 3102.5 3214.3 393365.0 393726.0 395186.0 396472.0 397854.0 43368.9 43524.6 43845.6 52131.7 52404.0 42644.6 52301.0 53855.7 54073.5 54075.5 13897.7 14001.6 14115.9 14132.2 14143.8 10865.4 10878.3 10926.9 10983.5 11004.0 17130.6 17226.5 17343.8 17436.9 17452.7 7883.9 8051.3 8064.1 8066.5 8084.7 833.5 841.3 852.7 872.8 878.3 26054.9 26500.3 26829.5 27645.0 28844.4 977.8 1044.7 1058.9 1060.8 1070.6 14478.8 15156.3 15233.9 15428.5 15609.0 530929.0 535518.7 535971.0 536353.0 536745.0 57077.7 57294.4 57697.0 57726.4 57948.1 1343.3 1407.0 1412.5 1414.5 1415.2 26661.7 27554.0 27928.3 28160.4 28226.9 46755.5 46869.0 47240.1 47376.8 47541.8 443895.0 444691.0 446546.0 448668.8 449197.0 24420.9 25142.7 25195.4 25210.4 25818.4 39258.8 39753.6 39858.0 40531.8 40688.0 32538.3 33331.2 34167.7 34311.3 34827.7 27632.3 32773.0 32886.3 32994.8 34142.0 29020.9 32605.5 32885.2 33910.8 34146.0 27592.6 30065.8 31007.4 31492.2 31579.7 22824.0 22878.5 23229.9 23645.8 23795.4 05:03:07 INFO - PROCESS | 1954 | 05:03:07 INFO - PROCESS | 1954 | [#2] jquery.html Cycles:180 Average:32832.48 Median:21380.70 stddev:85523.85 (400.0%) stddev-sans-first:85728.92 05:03:07 INFO - PROCESS | 1954 | Values: 320.6 390.7 393.3 399.9 413.7 17239.7 19903.9 20952.6 21305.7 21335.9 18492.1 21037.3 21384.8 21423.4 21617.2 5789.2 5904.7 6066.6 6152.5 6180.6 5701.1 5999.7 6032.3 6104.0 6110.2 5303.4 5650.8 6097.6 6099.4 6102.8 5111.7 5957.9 6027.0 6052.3 6130.2 5938.3 5955.2 5958.4 5958.8 5964.4 21183.8 22272.4 25545.1 26152.5 26239.6 2513.7 2521.9 2573.6 2595.7 2627.0 9731.4 10127.6 10340.0 10538.9 10542.8 1541.7 1774.3 1784.4 1840.8 1853.4 128786.0 134663.0 136943.0 137656.0 138311.0 11249.2 11778.5 11884.5 11967.8 11978.0 15495.6 15539.5 15559.1 15562.2 15597.8 8224.6 8376.3 8423.7 8466.9 8488.5 7861.3 7959.0 8001.2 8114.1 8153.1 4727.9 4797.1 4833.1 4900.8 4909.3 1967.9 2012.2 2034.6 2037.3 2038.2 1001.0 1005.1 1014.2 1024.1 1061.0 20827.0 20858.3 21376.6 21468.0 21524.9 999.9 1029.6 1049.5 1069.5 1074.9 9050.8 10359.2 11263.4 11271.0 11281.8 456183.0 456868.0 457429.0 559336.0 573795.0 108844.0 113820.9 114176.9 119539.0 146357.0 939.8 995.8 1044.1 1047.5 1050.3 16017.7 16040.7 17582.9 19591.0 19881.3 51949.0 57265.0 58009.4 58064.1 58314.4 74840.0 81354.6 85139.6 85417.1 86546.0 7056.5 8103.0 8191.3 8438.9 8661.5 10598.4 11182.8 12072.3 13003.0 13056.5 10662.8 11104.3 11128.2 11175.9 11349.2 10952.5 11005.1 11247.5 11400.7 11444.5 11017.3 11027.9 11192.3 11495.2 11567.9 7983.2 8250.4 8305.0 8424.5 8555.3 10324.0 10873.7 11088.5 11089.3 11209.6 05:03:07 INFO - PROCESS | 1954 | 05:03:07 INFO - PROCESS | 1954 | [#3] mootools.html Cycles:180 Average:5350.88 Median:5495.43 stddev:2931.84 (53.4%) stddev-sans-first:2925.31 05:03:07 INFO - PROCESS | 1954 | Values: 1437.5 1439.6 1440.7 1449.5 1470.9 7325.2 7573.2 7936.2 8493.3 8494.0 7929.9 8256.5 8256.8 8351.2 8355.4 2212.0 2255.5 2257.4 2257.9 2258.1 2255.6 2284.5 2286.4 2291.5 2299.8 2237.3 2248.6 2272.5 2275.4 2278.3 2219.6 2219.9 2246.9 2247.9 2251.1 1902.7 2197.7 2262.5 2268.5 2282.1 8382.8 8425.3 8446.0 8462.0 8469.6 4977.8 5145.1 5146.5 5163.7 5175.2 3192.7 3286.2 3426.8 3477.7 3518.3 1080.9 1171.4 1180.3 1201.1 1224.4 9406.0 9864.2 10527.4 10658.3 10681.7 4355.8 4540.0 4642.7 4646.0 4656.6 7984.8 9462.5 9502.9 9533.2 9547.1 4391.3 5158.6 5176.6 5179.7 5191.3 4106.6 4466.4 4617.3 4665.7 4705.8 3736.3 3765.8 3840.8 3855.6 3878.5 1041.7 1234.9 1240.3 1251.4 1252.5 870.9 877.8 883.8 893.7 919.2 5343.5 5550.8 5625.8 5741.3 5810.2 554.6 557.2 570.2 579.3 587.2 2930.8 3581.5 3810.4 3834.7 3871.0 9542.2 9900.9 9920.1 9923.0 9948.1 9790.9 9889.5 9892.3 9895.9 9918.7 7580.9 7648.7 7737.0 7827.6 7837.1 5295.0 5486.9 5503.9 5527.3 5567.1 9047.6 9171.3 9197.9 9206.4 9226.8 8941.6 9323.8 9363.9 9367.9 9385.3 6344.7 6505.7 6839.9 7027.8 7103.0 7723.2 7727.6 7872.3 7935.9 7949.4 6850.5 7032.8 7053.8 7111.2 7172.1 6786.9 6948.7 6968.7 7021.4 7040.8 6117.4 6311.7 6417.4 6435.3 6456.6 4987.3 5052.0 5069.1 5079.5 5153.9 4914.6 4988.7 4989.0 5094.6 5215.7 05:03:07 INFO - PROCESS | 1954 | 05:03:07 INFO - PROCESS | 1954 | [#4] prototype.html Cycles:180 Average:4483.54 Median:4942.28 stddev:1395.35 (28.2%) stddev-sans-first:1364.18 05:03:07 INFO - PROCESS | 1954 | Values: 341.1 345.8 352.4 352.9 358.7 4282.7 5182.4 5410.5 5462.6 5496.5 5370.9 5383.4 5388.9 5454.9 5464.3 3901.8 3983.5 4086.6 4090.5 4101.2 3989.4 4068.1 4082.8 4105.4 4113.4 3699.8 3749.7 4036.3 4066.3 4071.9 3904.4 3996.9 4014.9 4103.3 4141.4 3027.0 3447.0 3472.6 3490.5 3498.6 4550.7 5114.5 5209.6 5238.2 5286.4 4349.4 4401.8 4620.2 4648.4 4667.6 5117.8 5544.6 5574.2 5665.5 5691.5 3209.9 3851.0 3857.4 3871.6 3882.9 4452.3 4998.5 5910.1 5985.9 6037.5 3159.0 3393.7 3702.9 3852.9 3865.9 3572.4 3851.8 3900.2 3916.7 3952.8 4426.6 4528.0 4631.3 4638.7 4666.8 4409.6 4936.3 5250.4 5676.5 5733.6 1113.2 1179.1 1205.2 1261.9 1262.9 798.4 830.2 842.7 860.9 865.3 4500.9 4989.6 4995.3 5024.3 5065.6 4700.8 4803.3 5286.7 5495.7 5592.7 4103.9 4210.4 4458.3 4662.9 5002.0 2481.8 2807.7 2887.4 2918.8 2939.2 4789.9 5229.4 5557.4 5792.7 5846.1 4603.4 5030.5 5191.5 5691.9 5715.6 5069.9 5494.6 5575.6 5853.5 5891.6 4767.9 5687.8 5802.5 5806.4 5877.7 4394.8 5244.6 5777.9 5789.3 5823.5 6086.6 6088.6 6159.8 6161.7 6179.1 3866.0 3897.3 3899.0 3912.2 3930.5 4943.8 5709.6 5779.4 5788.0 5895.0 5442.5 5596.4 5666.5 5705.5 5708.3 4768.4 5699.3 5705.9 5725.5 5758.8 4326.7 5541.8 5545.0 5561.9 5569.3 4767.3 5655.3 5678.0 5702.1 5740.9 4452.5 4940.7 5187.5 5563.5 5582.9 05:03:07 INFO - PROCESS | 1954 | 05:03:07 INFO - PROCESS | 1954 | [#5] yui.html Cycles:180 Average:18256.29 Median:45084.89 stddev:25279.88 (56.1%) stddev-sans-first:25316.40 05:03:07 INFO - PROCESS | 1954 | Values: 694.0 734.0 761.7 791.1 824.6 14344.7 14758.9 15358.2 15373.5 15398.3 14142.5 14230.4 14256.1 14267.6 14282.4 879.5 904.0 904.1 904.2 904.7 863.8 894.4 894.6 900.4 902.5 933.4 937.2 941.6 943.2 943.9 927.6 934.4 935.8 936.3 939.0 626.0 687.1 691.4 692.5 693.0 14568.6 15235.5 15402.5 15556.8 15578.6 13536.0 13579.9 13855.1 13889.9 13934.5 11206.3 11294.7 11498.5 11513.2 11693.4 3584.2 3623.8 3677.1 3749.3 3776.9 97639.0 102330.5 103091.5 104529.5 105327.0 10500.8 10519.2 10609.9 10722.1 11136.3 20586.4 22233.7 22262.8 22315.0 22338.7 5922.3 6469.6 6952.8 7206.7 7227.8 4024.5 4135.6 4136.6 4138.7 4142.4 3601.6 3657.0 3680.5 3712.2 3747.6 654.5 657.4 659.9 671.5 680.1 627.9 635.9 650.2 651.9 682.4 10004.5 10370.2 10598.6 10811.6 11182.2 528.0 545.3 567.2 598.1 672.3 4817.1 4860.2 4898.8 4898.9 4950.1 76081.6 79769.8 81154.6 82761.0 85860.0 74779.5 77950.0 79496.0 79534.6 80752.0 44998.0 63097.7 63454.4 63594.0 67070.0 7336.6 7693.0 8667.0 10216.8 10515.9 42757.8 44504.3 45171.8 45904.6 48398.5 43104.8 47033.3 57234.1 57550.3 62082.0 12227.3 12316.1 12330.4 12332.8 12727.1 13688.1 13739.3 13760.3 13799.2 14023.4 9894.9 12505.6 12566.7 12792.0 13031.4 12654.3 12658.2 12765.3 12781.9 13097.3 12243.0 12486.7 13061.5 13125.0 13189.9 9676.8 9687.7 9910.8 9947.5 9956.7 9278.2 9918.9 9949.9 10494.3 10529.2 05:03:07 INFO - PROCESS | 1954 | -------- Summary: end -------- 05:03:07 INFO - PROCESS | 1954 | 05:03:07 INFO - TEST-INFO | 1954: exit 0 05:03:07 INFO - TEST-OK | dromaeo_css | took 1118296ms 05:03:07 INFO - TEST-START | kraken 05:03:07 INFO - operating with platform_type : mac_ 05:03:07 INFO - Initialising browser for kraken test... 05:03:07 INFO - TEST-INFO | started process 1958 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/tmpjf8yUw/profile http://localhost:57137/getInfo.html) 05:03:10 INFO - PROCESS | 1958 | 2017-02-25 05:03:10.905 plugin-container[1960:28444] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8a43, name = 'com.apple.tsm.portname' 05:03:10 INFO - PROCESS | 1958 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:03:10 INFO - PROCESS | 1958 | 2017-02-25 05:03:10.906 plugin-container[1960:28444] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8e13, name = 'com.apple.CFPasteboardClient' 05:03:10 INFO - PROCESS | 1958 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:03:10 INFO - PROCESS | 1958 | 2017-02-25 05:03:10.906 plugin-container[1960:28444] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 05:03:10 INFO - PROCESS | 1958 | __metrics Screen width/height:1600/1200 05:03:10 INFO - PROCESS | 1958 | colorDepth:24 05:03:10 INFO - PROCESS | 1958 | Browser inner width/height: 1024/689 05:03:10 INFO - PROCESS | 1958 | __metrics 05:03:11 ERROR - PROCESS | 1958 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3354: TypeError: this.tabs is undefined 05:03:11 INFO - TEST-INFO | 1958: exit 0 05:03:11 INFO - Browser initialized. 05:03:11 INFO - Running cycle 1/1 for kraken test... 05:03:11 INFO - TEST-INFO | started process 1961 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/tmpjf8yUw/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:03:14 INFO - PROCESS | 1961 | 2017-02-25 05:03:14.415 plugin-container[1962:28577] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x892f, name = 'com.apple.tsm.portname' 05:03:14 INFO - PROCESS | 1961 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:03:14 INFO - PROCESS | 1961 | 2017-02-25 05:03:14.416 plugin-container[1962:28577] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x430f, name = 'com.apple.CFPasteboardClient' 05:03:14 INFO - PROCESS | 1961 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:03:14 INFO - PROCESS | 1961 | 2017-02-25 05:03:14.416 plugin-container[1962:28577] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 05:03:24 INFO - PROCESS | 1961 | RSS: Main: 226676736 05:03:24 INFO - PROCESS | 1961 | 05:03:27 INFO - PROCESS | 1961 | Cycle 1(1): loaded http://localhost:57137/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:57137/tests/kraken/driver.html?testName=audio-beat-detection) 05:03:28 INFO - PROCESS | 1961 | RSS: Main: 240873472 05:03:28 INFO - PROCESS | 1961 | 05:03:30 INFO - PROCESS | 1961 | Cycle 1(1): loaded http://localhost:57137/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:57137/tests/kraken/driver.html?testName=audio-dft) 05:03:31 INFO - PROCESS | 1961 | RSS: Main: 248770560 05:03:31 INFO - PROCESS | 1961 | 05:03:34 INFO - PROCESS | 1961 | Cycle 1(1): loaded http://localhost:57137/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:57137/tests/kraken/driver.html?testName=audio-fft) 05:03:35 INFO - PROCESS | 1961 | RSS: Main: 251506688 05:03:35 INFO - PROCESS | 1961 | 05:03:37 INFO - PROCESS | 1961 | Cycle 1(1): loaded http://localhost:57137/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:57137/tests/kraken/driver.html?testName=audio-oscillator) 05:03:38 INFO - PROCESS | 1961 | RSS: Main: 252665856 05:03:38 INFO - PROCESS | 1961 | 05:03:40 INFO - PROCESS | 1961 | Cycle 1(1): loaded http://localhost:57137/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:57137/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:03:40 INFO - PROCESS | 1961 | RSS: Main: 252940288 05:03:40 INFO - PROCESS | 1961 | 05:03:43 INFO - PROCESS | 1961 | Cycle 1(1): loaded http://localhost:57137/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:57137/tests/kraken/driver.html?testName=imaging-darkroom) 05:03:44 INFO - PROCESS | 1961 | RSS: Main: 258412544 05:03:44 INFO - PROCESS | 1961 | 05:03:47 INFO - PROCESS | 1961 | Cycle 1(1): loaded http://localhost:57137/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:57137/tests/kraken/driver.html?testName=imaging-desaturate) 05:03:48 INFO - PROCESS | 1961 | RSS: Main: 259493888 05:03:48 INFO - PROCESS | 1961 | 05:03:51 INFO - PROCESS | 1961 | Cycle 1(1): loaded http://localhost:57137/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:57137/tests/kraken/driver.html?testName=json-parse-financial) 05:03:51 INFO - PROCESS | 1961 | RSS: Main: 259354624 05:03:51 INFO - PROCESS | 1961 | 05:03:53 INFO - PROCESS | 1961 | Cycle 1(1): loaded http://localhost:57137/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:57137/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:03:54 INFO - PROCESS | 1961 | RSS: Main: 259055616 05:03:54 INFO - PROCESS | 1961 | 05:03:56 INFO - PROCESS | 1961 | Cycle 1(1): loaded http://localhost:57137/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:57137/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:03:56 INFO - PROCESS | 1961 | RSS: Main: 259006464 05:03:56 INFO - PROCESS | 1961 | 05:03:59 INFO - PROCESS | 1961 | Cycle 1(1): loaded http://localhost:57137/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:57137/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:03:59 INFO - PROCESS | 1961 | RSS: Main: 259072000 05:03:59 INFO - PROCESS | 1961 | 05:04:02 INFO - PROCESS | 1961 | Cycle 1(1): loaded http://localhost:57137/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:57137/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:04:03 INFO - PROCESS | 1961 | RSS: Main: 259424256 05:04:03 INFO - PROCESS | 1961 | 05:04:06 INFO - PROCESS | 1961 | Cycle 1(1): loaded http://localhost:57137/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:57137/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:04:07 INFO - PROCESS | 1961 | RSS: Main: 259203072 05:04:07 INFO - PROCESS | 1961 | 05:04:09 INFO - PROCESS | 1961 | Cycle 1(1): loaded http://localhost:57137/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:57137/tests/kraken/driver.html?testName=ai-astar) 05:04:09 INFO - PROCESS | 1961 | RSS: Main: 262631424 05:04:09 INFO - PROCESS | 1961 | 05:04:09 INFO - PROCESS | 1961 | __start_tp_report 05:04:09 INFO - PROCESS | 1961 | _x_x_mozilla_page_load 05:04:09 INFO - PROCESS | 1961 | _x_x_mozilla_page_load_details 05:04:09 INFO - PROCESS | 1961 | |i|pagename|runs| 05:04:09 INFO - PROCESS | 1961 | |0;ai-astar;110;118;119;121;118;118;119;120;103;182 05:04:09 INFO - PROCESS | 1961 | |1;audio-beat-detection;147;119;116;164;132;143;149;121;137;117 05:04:09 INFO - PROCESS | 1961 | |2;audio-dft;208;215;214;280;268;204;197;202;194;196 05:04:09 INFO - PROCESS | 1961 | |3;audio-fft;86;60;63;86;61;61;63;81;93;63 05:04:09 INFO - PROCESS | 1961 | |4;audio-oscillator;92;71;86;73;72;74;87;73;74;109 05:04:09 INFO - PROCESS | 1961 | |5;imaging-gaussian-blur;90;98;91;91;97;90;91;91;110;128 05:04:09 INFO - PROCESS | 1961 | |6;imaging-darkroom;98;104;157;99;98;100;111;155;112;100 05:04:09 INFO - PROCESS | 1961 | |7;imaging-desaturate;91;96;91;93;94;90;92;96;141;146 05:04:09 INFO - PROCESS | 1961 | |8;json-parse-financial;67;67;67;67;68;67;67;66;69;67 05:04:09 INFO - PROCESS | 1961 | |9;json-stringify-tinderbox;71;73;78;107;100;69;70;74;100;98 05:04:09 INFO - PROCESS | 1961 | |10;stanford-crypto-aes;92;90;91;111;117;108;100;89;89;92 05:04:09 INFO - PROCESS | 1961 | |11;stanford-crypto-ccm;165;152;165;136;153;156;162;149;188;159 05:04:09 INFO - PROCESS | 1961 | |12;stanford-crypto-pbkdf2;178;177;192;239;176;176;211;182;222;179 05:04:09 INFO - PROCESS | 1961 | |13;stanford-crypto-sha256-iterative;72;81;91;89;96;73;69;66;68;70 05:04:09 INFO - PROCESS | 1961 | __end_tp_report 05:04:09 INFO - PROCESS | 1961 | __start_cc_report 05:04:09 INFO - PROCESS | 1961 | _x_x_mozilla_cycle_collect,1021 05:04:09 INFO - PROCESS | 1961 | __end_cc_report 05:04:09 INFO - PROCESS | 1961 | __startTimestamp1488027849512__endTimestamp 05:04:09 INFO - PROCESS | 1961 | 05:04:09 INFO - PROCESS | 1961 | ------- Summary: start ------- 05:04:09 INFO - PROCESS | 1961 | Number of tests: 14 05:04:09 INFO - PROCESS | 1961 | 05:04:09 INFO - PROCESS | 1961 | [#0] ai-astar Cycles:10 Average:122.80 Median:119.00 stddev:21.52 (18.1%) stddev-sans-first:22.33 05:04:09 INFO - PROCESS | 1961 | Values: 110.0 118.0 119.0 121.0 118.0 118.0 119.0 120.0 103.0 182.0 05:04:09 INFO - PROCESS | 1961 | 05:04:09 INFO - PROCESS | 1961 | [#1] audio-beat-detection Cycles:10 Average:134.50 Median:140.00 stddev:16.30 (11.6%) stddev-sans-first:16.65 05:04:09 INFO - PROCESS | 1961 | Values: 147.0 119.0 116.0 164.0 132.0 143.0 149.0 121.0 137.0 117.0 05:04:09 INFO - PROCESS | 1961 | 05:04:09 INFO - PROCESS | 1961 | [#2] audio-dft Cycles:10 Average:217.80 Median:211.00 stddev:30.59 (14.5%) stddev-sans-first:32.24 05:04:09 INFO - PROCESS | 1961 | Values: 208.0 215.0 214.0 280.0 268.0 204.0 197.0 202.0 194.0 196.0 05:04:09 INFO - PROCESS | 1961 | 05:04:09 INFO - PROCESS | 1961 | [#3] audio-fft Cycles:10 Average:71.70 Median:72.00 stddev:13.09 (18.2%) stddev-sans-first:12.82 05:04:09 INFO - PROCESS | 1961 | Values: 86.0 60.0 63.0 86.0 61.0 61.0 63.0 81.0 93.0 63.0 05:04:09 INFO - PROCESS | 1961 | 05:04:09 INFO - PROCESS | 1961 | [#4] audio-oscillator Cycles:10 Average:81.10 Median:74.00 stddev:12.35 (16.7%) stddev-sans-first:12.45 05:04:09 INFO - PROCESS | 1961 | Values: 92.0 71.0 86.0 73.0 72.0 74.0 87.0 73.0 74.0 109.0 05:04:09 INFO - PROCESS | 1961 | 05:04:09 INFO - PROCESS | 1961 | [#5] imaging-gaussian-blur Cycles:10 Average:97.70 Median:91.00 stddev:12.33 (13.5%) stddev-sans-first:12.76 05:04:09 INFO - PROCESS | 1961 | Values: 90.0 98.0 91.0 91.0 97.0 90.0 91.0 91.0 110.0 128.0 05:04:09 INFO - PROCESS | 1961 | 05:04:09 INFO - PROCESS | 1961 | [#6] imaging-darkroom Cycles:10 Average:113.40 Median:156.00 stddev:23.02 (14.8%) stddev-sans-first:23.73 05:04:09 INFO - PROCESS | 1961 | Values: 98.0 104.0 157.0 99.0 98.0 100.0 111.0 155.0 112.0 100.0 05:04:09 INFO - PROCESS | 1961 | 05:04:09 INFO - PROCESS | 1961 | [#7] imaging-desaturate Cycles:10 Average:103.00 Median:92.50 stddev:21.47 (23.2%) stddev-sans-first:22.33 05:04:09 INFO - PROCESS | 1961 | Values: 91.0 96.0 91.0 93.0 94.0 90.0 92.0 96.0 141.0 146.0 05:04:09 INFO - PROCESS | 1961 | 05:04:09 INFO - PROCESS | 1961 | [#8] json-parse-financial Cycles:10 Average:67.20 Median:67.00 stddev:0.79 (1.2%) stddev-sans-first:0.83 05:04:09 INFO - PROCESS | 1961 | Values: 67.0 67.0 67.0 67.0 68.0 67.0 67.0 66.0 69.0 67.0 05:04:09 INFO - PROCESS | 1961 | 05:04:09 INFO - PROCESS | 1961 | [#9] json-stringify-tinderbox Cycles:10 Average:84.00 Median:72.00 stddev:15.22 (21.1%) stddev-sans-first:15.40 05:04:09 INFO - PROCESS | 1961 | Values: 71.0 73.0 78.0 107.0 100.0 69.0 70.0 74.0 100.0 98.0 05:04:09 INFO - PROCESS | 1961 | 05:04:09 INFO - PROCESS | 1961 | [#10] stanford-crypto-aes Cycles:10 Average:97.90 Median:89.50 stddev:10.44 (11.7%) stddev-sans-first:10.85 05:04:09 INFO - PROCESS | 1961 | Values: 92.0 90.0 91.0 111.0 117.0 108.0 100.0 89.0 89.0 92.0 05:04:09 INFO - PROCESS | 1961 | 05:04:09 INFO - PROCESS | 1961 | [#11] stanford-crypto-ccm Cycles:10 Average:158.50 Median:160.50 stddev:13.51 (8.4%) stddev-sans-first:14.12 05:04:09 INFO - PROCESS | 1961 | Values: 165.0 152.0 165.0 136.0 153.0 156.0 162.0 149.0 188.0 159.0 05:04:09 INFO - PROCESS | 1961 | 05:04:09 INFO - PROCESS | 1961 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:193.20 Median:187.00 stddev:22.75 (12.2%) stddev-sans-first:23.45 05:04:09 INFO - PROCESS | 1961 | Values: 178.0 177.0 192.0 239.0 176.0 176.0 211.0 182.0 222.0 179.0 05:04:09 INFO - PROCESS | 1961 | 05:04:09 INFO - PROCESS | 1961 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:77.50 Median:77.00 stddev:10.91 (14.2%) stddev-sans-first:11.38 05:04:09 INFO - PROCESS | 1961 | Values: 72.0 81.0 91.0 89.0 96.0 73.0 69.0 66.0 68.0 70.0 05:04:09 INFO - PROCESS | 1961 | -------- Summary: end -------- 05:04:09 INFO - PROCESS | 1961 | 05:04:10 INFO - TEST-INFO | 1961: exit 0 05:04:10 INFO - TEST-OK | kraken | took 62558ms 05:04:10 INFO - SUITE-END | took 1180s 05:04:10 INFO - Completed test suite (00:19:41) 05:04:10 INFO - javascript benchmark 05:04:10 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7094.856869394896, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3312.53624492571, 3328.667133286671, 3329.1510664780462, 3335.199552107495, 3352.4133276676575, 4377.737335759856, 5421.644588852781, 5543.861403464915, 5567.109262517993, 5637.661740295585, 5110.667806592569, 5261.973690131544, 5699.629524080932, 5704.3154821421385, 5706.457886500786, 3931.7247792654525, 4139.875803725886, 5077.289179514871, 5238.05714971305, 5334.359876814788, 5124.231365295217, 5176.870578235536, 5283.920741188886, 5287.339082614673, 5298.6555873868265, 5185.481451854822, 5230.0, 5249.527542521191, 5269.894602107936, 5296.364436267634, 5221.895562088737, 5225.399079105876, 5247.47525247476, 5257.7108259045735, 5279.366476022863, 4113.670906327487, 5004.099262132815, 5200.947990520122, 5201.349831271091, 5224.817131400383, 5371.113766228567, 5553.583481238883, 5617.185508101303, 5675.517581005578, 5693.946619875295, 6262.530310226751, 6264.310925798159, 6279.560430769848, 6280.371962803683, 6286.4342209201395, 5215.608829337815, 6240.254273174254, 6281.623102613812, 6305.432511074026, 6312.084747711556, 5055.469175736568, 5590.580706447033, 5604.887902241973, 5658.74535645897, 5877.089051197071, 5758.481736643722, 5862.091375836752, 6209.472194863397, 6233.283172435137, 6244.03217501279, 5441.183822426668, 5924.555658325643, 6017.909731354033, 6018.2176317079375, 6112.633242005405, 6138.602983404986, 6222.502199824004, 6260.937390626126, 6276.46650034743, 6276.780312689034, 6229.781957631551, 6230.940740074198, 6244.625322480666, 6272.466840318624, 6274.717637706223, 6045.848853778691, 6110.114033465123, 6280.246370435486, 6290.308066112724, 6305.96847015762, 4942.728149951715, 4952.034353301144, 4960.553550180429, 4964.975175124174, 4968.403791545038, 4512.368268442355, 4519.231730605805, 4536.0, 4547.636189104864, 4581.633469322446, 5833.620814647034, 5838.883222335615, 5851.531877449794, 5852.268466441695, 5872.794452194153, 6137.324894261627, 6190.0, 6260.029695397129, 6278.340774218733, 6374.043893416025, 5737.913931291034, 5742.569307301969, 5747.770089196469, 5750.568707346965, 5753.769849206069, 5401.675899446046, 5419.430959749248, 5421.403645598981, 5424.457554244544, 5436.456354364611, 6153.692315384303, 6216.285127210429, 6220.409061139185, 6269.811905642838, 6295.370462953668, 6239.188905442264, 6253.906191407042, 6272.466840318624, 6277.435030847246, 6282.497400207881, 6280.309165991646, 6305.337939516285, 6308.905366419599, 6313.305536391085, 6317.368263173646, 5954.702264886825, 6010.699465026643, 6053.334133245251, 6098.359672234352, 6235.875282494416, 6117.857853872152, 6120.081455037999, 6207.565470417027, 6210.254769427697, 6234.594751341148, 6151.108089327112, 6158.507319414526, 6168.0, 6169.0, 6184.845378865564, 5695.572832037531, 5977.252843394575, 6134.4172303631085, 6162.075688646739, 6188.8762224754355, 4022.4971878515184, 4837.7421870313265, 5388.838334849961, 6077.270727512727, 6112.235970503687, 6152.769446110707, 6173.876522469435, 6194.504439644727, 6214.223222097238, 6214.751409943552, 5774.422557744192, 6054.636721796706, 6057.303410107717, 6074.422855326462, 6116.761878222105], "value": 5629.469262782341, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [455.99908201497703, 494.50055444001595, 500.00249502243025, 511.3837825420369, 554.7669978608984, 37241.627583724156, 39465.605343946554, 39498.61754838577, 39667.80166099165, 39689.412423503025, 40631.39052914209, 41508.37737433941, 41643.37534936971, 41667.58332416675, 41751.79124104375, 9278.211352035069, 9524.42853428793, 10865.34807911524, 11304.208705390625, 11448.427578621076, 10920.836187457155, 11053.723656908562, 11065.0, 11074.723131921726, 11077.335359878392, 11067.280626759275, 11111.444427778619, 11134.220604557684, 11140.888591114106, 11165.050970667462, 11051.778964420748, 11112.05547528453, 11117.666470005914, 11119.332840029543, 11160.888391116066, 11104.389258590774, 11175.72060698489, 11177.83233251502, 11181.72045698856, 11191.608293709682, 36141.0, 36165.27669446596, 36447.0, 36464.0, 36588.0852978675, 3713.9628603713886, 3714.461403096536, 3717.5724791648768, 3721.925561488755, 3722.3671975764187, 21260.468488287763, 22787.54424911509, 26548.33629159267, 26761.598576021373, 28540.571891421645, 3049.564134495641, 3062.540618907182, 3087.259057826128, 3102.4570700127615, 3214.324991751735, 393365.0, 393726.0, 395186.0, 396472.0, 397854.0, 43368.91577710519, 43524.56475435269, 43845.561544384145, 52131.73934130381, 52404.0, 42644.57355426468, 52301.0, 53855.730721346925, 54073.51117865881, 54075.45924540783, 13897.652558686113, 14001.579952601438, 14115.929420352833, 14132.15207087578, 14143.787843182361, 10865.402402867918, 10878.347299162075, 10926.890731092588, 10983.450827458595, 11004.009639132357, 17130.571735706708, 17226.483205503853, 17343.826561734222, 17436.91281543584, 17452.738208926876, 7883.921160788434, 8051.315638170821, 8064.112947575762, 8066.516009039359, 8084.676612935416, 833.491380601114, 841.2554888923347, 852.7356519478982, 872.8079822439054, 878.3298771733985, 26054.86972565125, 26500.337491562866, 26829.463410731674, 27645.03242386507, 28844.42311153807, 977.8093271812071, 1044.738815296176, 1058.9364638121738, 1060.8143574874366, 1070.5985255529176, 14478.782818257734, 15156.31796569157, 15233.918420763364, 15428.537143885702, 15609.0, 530929.0, 535518.7377514723, 535971.0, 536353.0, 536745.0, 57077.71461142751, 57294.42705572974, 57697.0, 57726.42273577295, 57948.130778038365, 1343.2612063365111, 1406.9929650351887, 1412.4844431782326, 1414.5119933622884, 1415.2357726827397, 26661.733382665923, 27554.0356087536, 27928.30179245454, 28160.43679126365, 28226.870925162762, 46755.53244467648, 46869.0, 47240.055198896516, 47376.7631161842, 47541.81145471391, 443895.0, 444691.0, 446546.0, 448668.75665621465, 449197.0, 24420.877895610407, 25142.74857251404, 25195.370115747253, 25210.36974075663, 25818.35454113662, 39258.75289781828, 39753.602463976145, 39858.0, 40531.79734101311, 40688.0, 32538.34923301568, 33331.166720832174, 34167.65832341645, 34311.31377372389, 34827.65172348244, 27632.309192270353, 32773.0, 32886.342273153925, 32994.835025824716, 34142.0, 29020.854895725388, 32605.510917336735, 32885.17787055343, 33910.83044584761, 34146.0, 27592.586111208748, 30065.849670751508, 31007.379852403275, 31492.212694682818, 31579.73681052732, 22824.0, 22878.542429151657, 23229.883850580638, 23645.763542364355, 23795.40511487227], "value": 18736.72300029441, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [320.6328753577156, 390.65817409766447, 393.3293734183218, 399.9280129576679, 413.7186713035137, 17239.65520689587, 19903.905285209326, 20952.580948381023, 21305.680414793773, 21335.93320333985, 18492.075396230168, 21037.263695770653, 21384.823834689087, 21423.357299281048, 21617.243396481128, 5789.189513468118, 5904.7342869570975, 6066.605670631395, 6152.538559608047, 6180.629162250257, 5701.0878259478495, 5999.700014999255, 6032.306284777241, 6103.962326404499, 6110.175126357929, 5303.416624171338, 5650.830475085733, 6097.634141951475, 6099.390060993887, 6102.81691549252, 5111.667741596803, 5957.927573036852, 6026.969865150668, 6052.273727152769, 6130.233720784902, 5938.317093534256, 5955.196048533479, 5958.433948774859, 5958.821235362902, 5964.403559644044, 21183.788162118337, 22272.443188920406, 25545.105921292852, 26152.476950461074, 26239.606405903927, 2513.7486251374717, 2521.924342269735, 2573.61395790631, 2595.688517377927, 2626.96234987179, 9731.41611503312, 10127.594896204144, 10340.017698318648, 10538.894611053867, 10542.841857372145, 1541.745611974023, 1774.325756212644, 1784.4379020608467, 1840.7699037620298, 1853.4254381141889, 128786.0, 134663.0, 136943.0, 137656.0, 138311.0, 11249.212555121227, 11778.528858845722, 11884.465199066062, 11967.760644787055, 11978.0, 15495.612609684622, 15539.5338139856, 15559.066456012675, 15562.22188890551, 15597.844021559866, 8224.62989165489, 8376.329893608498, 8423.747287581382, 8466.872996905051, 8488.533130677748, 7861.331786798072, 7959.0, 8001.15987821282, 8114.107448180695, 8153.143919888326, 4727.905441891143, 4797.136515427256, 4833.10587541306, 4900.779464924087, 4909.263610458388, 1967.9016049197483, 2012.2353505667752, 2034.6134234495398, 2037.2869495676691, 2038.2152871144715, 1000.9749756256006, 1005.1456262177237, 1014.2443879309961, 1024.1499555369026, 1060.9734756630992, 20827.0, 20858.26996055161, 21376.57246855054, 21468.0, 21524.923753812247, 999.9100979931764, 1029.5984566019258, 1049.506731836036, 1069.4866464097122, 1074.854894589236, 9050.773730656654, 10359.22305827066, 11263.380514071641, 11271.0, 11281.774364512663, 456183.0, 456868.0, 457429.0, 559336.0, 573795.0, 108844.0, 113820.86179138269, 114176.85823141829, 119539.0, 146357.0, 939.8026414453042, 995.7709726762885, 1044.12815299226, 1047.4762618690654, 1050.2858056521638, 16017.718582513604, 16040.716742660792, 17582.91208543949, 19591.0, 19881.30415435453, 51948.96102078013, 57265.0, 58009.4199058004, 58064.12903806531, 58314.416855830896, 74840.0, 81354.59322703349, 85139.5743021281, 85417.1458285434, 86546.0, 7056.470764692566, 8102.959485202537, 8191.344692424711, 8438.915610844058, 8661.52361620117, 10598.364098154136, 11182.832257515982, 12072.3360215189, 13003.0, 13056.543020994219, 10662.755668305108, 11104.333739975627, 11128.165387596124, 11175.882411759085, 11349.205555611028, 10952.45237738094, 11005.064569511682, 11247.493862776031, 11400.714982125513, 11444.542218311175, 11017.283876548, 11027.944860275647, 11192.265198894185, 11495.195336326376, 11567.942160289145, 7983.1617680142745, 8250.38122140854, 8304.958475207586, 8424.536650484282, 8555.272801811916, 10324.0, 10873.673789786319, 11088.501017454073, 11089.33463992163, 11209.55161793519], "value": 9696.423472168359, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1437.4753215076496, 1439.5609339151554, 1440.7406666799984, 1449.536148432502, 1470.9411623535057, 7325.230850760673, 7573.204813494586, 7936.246056624619, 8493.278071363942, 8494.0, 7929.92070079299, 8256.50460972342, 8256.83486330273, 8351.206635369645, 8355.37334699897, 2211.999999999996, 2255.548890221954, 2257.3566533537946, 2257.8758168300737, 2258.108047321307, 2255.605269077913, 2284.543091381722, 2286.4398222435443, 2291.495870908406, 2299.793018628323, 2237.2505210754416, 2248.550289942018, 2272.488690044735, 2275.4311422144465, 2278.316505048487, 2219.622664147099, 2219.9445013874783, 2246.921357752487, 2247.8538894971775, 2251.0995601759264, 1902.6955687090137, 2197.747259065212, 2262.502249505106, 2268.4669102760836, 2282.052948026567, 8382.832343353099, 8425.283850872684, 8445.986481622245, 8462.02686691026, 8469.576521173916, 4977.751112444364, 5145.125328694131, 5146.459621739701, 5163.7159956202395, 5175.223716442526, 3192.728618067467, 3286.2441638423297, 3426.794392336468, 3477.669621385964, 3518.2787528556582, 1080.9027187553158, 1171.37916904041, 1180.262336039975, 1201.0871737479579, 1224.3878060969516, 9405.952970235174, 9864.210863130933, 10527.420991845445, 10658.253922225369, 10681.732956676067, 4355.80398882051, 4540.046590216026, 4642.65180111493, 4646.0, 4656.580907718321, 7984.760457186294, 9462.479563623929, 9502.904970950201, 9533.237341012702, 9547.093026162504, 4391.3412988051405, 5158.587313014949, 5176.5858731301405, 5179.741012949337, 5191.273221748958, 4106.568810274938, 4466.441694788152, 4617.284320930261, 4665.696729712626, 4705.811767529329, 3736.2527494500664, 3765.8493660253284, 3840.846366145379, 3855.5951625079524, 3878.45701601776, 1041.692700653294, 1234.9073819463576, 1240.2868350698384, 1251.4368534159664, 1252.5177806544364, 870.9331069439885, 877.7586163804878, 883.7834730490943, 893.6827426263712, 919.1865199298669, 5343.5457986071615, 5550.8057217997175, 5625.831225063254, 5741.339745929188, 5810.1865738796605, 554.603458527155, 557.225456615305, 570.1504757910683, 579.3395529096833, 587.1515659871445, 2930.7508861746564, 3581.4985901973746, 3810.3903375459795, 3834.731568790158, 3870.9806450968126, 9542.188913942255, 9900.900990990142, 9920.057594528509, 9922.950385248028, 9948.104670579683, 9790.902090979142, 9889.45607991553, 9892.25808064398, 9895.851562226428, 9918.652847150459, 7580.924190758132, 7648.694052237959, 7737.0, 7827.608619568999, 7837.09873364559, 5294.993951149269, 5486.917696234639, 5503.944960550343, 5527.281453411031, 5567.137093750475, 9047.592858321523, 9171.312151588523, 9197.862032069657, 9206.447613143233, 9226.815463690555, 8941.642334306554, 9323.766905827408, 9363.906360936304, 9367.85948210791, 9385.343025988115, 6344.746210151542, 6505.739770409131, 6839.93160068393, 7027.754028608974, 7103.0, 7723.227677232457, 7727.574983375744, 7872.291493765589, 7935.880961785693, 7949.364050876032, 6850.45196384298, 7032.824179395556, 7053.788386348418, 7111.217766045835, 7172.103487064117, 6786.898196527155, 6948.687309071205, 6968.7212511501, 7021.368076873106, 7040.788776336718, 6117.449429551361, 6311.715972781144, 6417.358264173732, 6435.324290949383, 6456.644884531396, 4987.326710894128, 5052.0, 5069.087564238473, 5079.517445842639, 5153.948460515497, 4914.5822605078965, 4988.72562009093, 4989.0, 5094.592432605456, 5215.6609820361555], "value": 4297.6172434351365, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [341.08248810699223, 345.7596970105777, 352.35870715298165, 352.858856457417, 358.743498398645, 4282.678799090065, 5182.352205974254, 5410.540104091152, 5462.617616766828, 5496.4503549645015, 5370.946290537094, 5383.380911195214, 5388.91916621251, 5454.890902181954, 5464.316960379952, 3901.804909754515, 3983.462232598591, 4086.6322031017203, 4090.4886889138857, 4101.200265948141, 3989.4015897615445, 4068.084680946793, 4082.7958602069925, 4105.425240466322, 4113.403556484308, 3699.778013319196, 3749.700023998087, 4036.253293140778, 4066.2883995300854, 4071.9185616287655, 3904.4338570907057, 3996.86010989614, 4014.879553613396, 4103.322951712964, 4141.420201171838, 3027.031349968011, 3446.965910226947, 3472.6353732858192, 3490.458978858281, 3498.5801703795455, 4550.749708766017, 5114.488551144893, 5209.557187639018, 5238.214267859814, 5286.352416832373, 4349.391085248068, 4401.779911004469, 4620.191466493384, 4648.372469716613, 4667.603253723438, 5117.8464646060675, 5544.584156188302, 5574.191742197399, 5665.507176842177, 5691.48776610103, 3209.935801283961, 3850.9614903850884, 3857.40210267409, 3871.5741268460447, 3882.864099756523, 4452.287633978548, 4998.450170481244, 5910.079224252092, 5985.910211346833, 6037.516998640098, 3158.952615710766, 3393.694567488938, 3702.926151416113, 3852.9036774080873, 3865.9226815464094, 3572.4284114541547, 3851.807409629507, 3900.180961998012, 3916.706247031427, 3952.802359882052, 4426.623736982328, 4527.977360113245, 4631.309572006194, 4638.721676699409, 4666.789994450258, 4409.581089796464, 4936.3216506388, 5250.448702886219, 5676.545876329884, 5733.617165872072, 1113.209621168963, 1179.1156632525606, 1205.1744554979866, 1261.9305938173488, 1262.9328217656066, 798.4051881348432, 830.2195935820309, 842.7050532313641, 860.8622620380709, 865.3380164174412, 4500.909981800346, 4989.600831933437, 4995.325631039763, 5024.346834911438, 5065.594752419871, 4700.811967521261, 4803.279508073747, 5286.735663216824, 5495.697736624444, 5592.741633132594, 4103.876883693434, 4210.389493523483, 4458.286674132123, 4662.930056049162, 5001.974990125026, 2481.838680485763, 2807.6663584797057, 2887.422515496867, 2918.83946382951, 2939.2064142681356, 4789.928151077736, 5229.398619158846, 5557.44425557441, 5792.696643255318, 5846.064629659234, 4603.401557797465, 5030.4718004608985, 5191.480851914854, 5691.8616276744815, 5715.571332150105, 5069.8732531687, 5494.615376923657, 5575.553955683617, 5853.536615846038, 5891.558133140032, 4767.854465490474, 5687.7724891005555, 5802.47777700009, 5806.4193580641595, 5877.706114694333, 4394.780260987001, 5244.632875698664, 5777.884442311046, 5789.33422656383, 5823.534996676971, 6086.573939824169, 6088.6042407243385, 6159.844054580861, 6161.6919154041225, 6179.115369812251, 3865.9613403866724, 3897.279003384383, 3898.96101038986, 3912.2175564887707, 3930.5479869815335, 4943.826966056315, 5709.571782116441, 5779.422057794187, 5787.999077531487, 5894.970525147518, 5442.482964118402, 5596.38439771633, 5666.546676265806, 5705.516565693033, 5708.286464191976, 4768.427788665383, 5699.287589051369, 5705.885882282248, 5725.541956643542, 5758.769649213985, 4326.653867690514, 5541.806036788693, 5545.0, 5561.857550483964, 5569.331680198402, 4767.332573439652, 5655.2648155741135, 5678.0, 5702.146802289356, 5740.942590574041, 4452.50297199824, 4940.728259945738, 5187.481251874782, 5563.471470210324, 5582.888342233214], "value": 4048.0862776623917, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [693.9791806245811, 733.9669714862831, 761.6610608279318, 791.1337085890949, 824.5588610093599, 14344.71310573788, 14758.926205368985, 15358.155301458415, 15373.461928832494, 15398.307076181556, 14142.505012324546, 14230.359633816477, 14256.073355231903, 14267.643308917282, 14282.357293921772, 879.5162660536711, 904.023654453189, 904.0733248420356, 904.2404380320529, 904.7150147703485, 863.8315528471951, 894.4365050018479, 894.5840184314293, 900.4102312984973, 902.4765635931177, 933.3653115881198, 937.1846493550621, 941.5668792355491, 943.1511639524414, 943.9244860411185, 927.638221093774, 934.4486752815837, 935.7660584853752, 936.2509992006436, 938.957746901391, 625.9843503912393, 687.1239170058216, 691.4295706042535, 692.5117791956709, 692.9618870962095, 14568.562943111723, 15235.542933712006, 15402.537923862303, 15556.844431555654, 15578.610534736723, 13536.0, 13579.864201358057, 13855.099418537762, 13889.861101388959, 13934.512292069729, 11206.271592346553, 11294.717632059183, 11498.540058397653, 11513.194076414655, 11693.415329233505, 3584.211473475857, 3623.836927338276, 3677.0807298175455, 3749.343220549021, 3776.9244615107546, 97639.0, 102330.48834755854, 103091.4845425768, 104529.47735261275, 105326.99999999923, 10500.842487362695, 10519.158467322595, 10609.893901061045, 10722.142228621848, 11136.33182009082, 20586.353091752622, 22233.666495002588, 22262.77737222607, 22315.0, 22338.66492002621, 5922.289325280908, 6469.611823290617, 6952.826179345557, 7206.711731530785, 7227.819304517429, 4024.5170579530645, 4135.60711732385, 4136.586341365839, 4138.668906487474, 4142.399352093929, 3601.5858176309534, 3657.0, 3680.5399325084363, 3712.238991006891, 3747.606501317377, 654.5385503220218, 657.4181849063613, 659.9144407449814, 671.4896678524357, 680.1328306409268, 627.9403456671608, 635.9284605439756, 650.187265917603, 651.9445847102954, 682.3892616108556, 10004.54979525923, 10370.222233332379, 10598.629047983284, 10811.56753729842, 11182.217244792786, 527.9625535822128, 545.3374150407195, 567.1747607231468, 598.1207624791549, 672.3108813466237, 4817.13291607514, 4860.1737704590305, 4898.779554919965, 4898.90202195954, 4950.084234416644, 76081.61959190169, 79769.80345294825, 81154.59422702849, 82761.0, 85860.0, 74779.5044099115, 77950.0, 79496.0, 79534.602326988, 80752.0, 44998.0, 63097.68451157715, 63454.36545634577, 63594.0, 67070.0, 7336.596487193042, 7693.0, 8667.0, 10216.846747298945, 10515.894841051491, 42757.78621106874, 44504.33243501285, 45171.774141129084, 45904.622861314216, 48398.51601484081, 43104.78447607742, 47033.29450058183, 57234.14148787855, 57550.27349179531, 62082.0, 12227.266364018187, 12316.101703322336, 12330.383480826102, 12332.81500777507, 12727.109102362745, 13688.110272832235, 13739.313034348443, 13760.31198440054, 13799.2410417424, 14023.439062437388, 9894.851577226204, 12505.56230531927, 12566.680498547521, 12792.0, 13031.413586388824, 12654.304013279358, 12658.177218480769, 12765.255470073978, 12781.936090319488, 13097.25322188421, 12243.0, 12486.687832804253, 13061.477540898257, 13125.015623828442, 13189.868101318863, 9676.806463870824, 9687.70936871895, 9910.752231194278, 9947.452890090824, 9956.701298961043, 9278.232299814947, 9918.851217231891, 9949.900500994898, 10494.317869338469, 10529.157667386438], "value": 7167.4540505651175, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1620.3000000000004, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 118.0, 119.0, 121.0, 118.0, 118.0, 119.0, 120.0, 103.0, 182.0], "value": 122.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 119.0, 116.0, 164.0, 132.0, 143.0, 149.0, 121.0, 137.0, 117.0], "value": 134.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [208.0, 215.0, 214.0, 280.0, 268.0, 204.0, 197.0, 202.0, 194.0, 196.0], "value": 217.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 60.0, 63.0, 86.0, 61.0, 61.0, 63.0, 81.0, 93.0, 63.0], "value": 71.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 71.0, 86.0, 73.0, 72.0, 74.0, 87.0, 73.0, 74.0, 109.0], "value": 81.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 98.0, 91.0, 91.0, 97.0, 90.0, 91.0, 91.0, 110.0, 128.0], "value": 97.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [98.0, 104.0, 157.0, 99.0, 98.0, 100.0, 111.0, 155.0, 112.0, 100.0], "value": 113.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 96.0, 91.0, 93.0, 94.0, 90.0, 92.0, 96.0, 141.0, 146.0], "value": 103.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 67.0, 67.0, 67.0, 68.0, 67.0, 67.0, 66.0, 69.0, 67.0], "value": 67.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 73.0, 78.0, 107.0, 100.0, 69.0, 70.0, 74.0, 100.0, 98.0], "value": 84.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 90.0, 91.0, 111.0, 117.0, 108.0, 100.0, 89.0, 89.0, 92.0], "value": 97.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [165.0, 152.0, 165.0, 136.0, 153.0, 156.0, 162.0, 149.0, 188.0, 159.0], "value": 158.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [178.0, 177.0, 192.0, 239.0, 176.0, 176.0, 211.0, 182.0, 222.0, 179.0], "value": 193.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 81.0, 91.0, 89.0, 96.0, 73.0, 69.0, 66.0, 68.0, 70.0], "value": 77.5, "unit": "score"}]}]} 05:04:10 INFO - Return code: 0 05:04:10 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 05:04:10 ERROR - # TBPL SUCCESS # 05:04:10 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:04:10 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:04:10 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:04:10 INFO - 2017-02-25 05:04:10.827 screenresolution[1966:28961] starting screenresolution argv=screenresolution get 05:04:10 INFO - 2017-02-25 05:04:10.830 screenresolution[1966:28961] Display 0: 1600x1200x32@60 05:04:10 INFO - 2017-02-25 05:04:10.837 screenresolution[1967:28964] starting screenresolution argv=screenresolution list 05:04:10 INFO - Available Modes on Display 0 05:04:10 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:04:10 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:04:10 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:04:10 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:04:11 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:04:11 INFO - Intel Iris: 05:04:11 INFO - Chipset Model: Intel Iris 05:04:11 INFO - Type: GPU 05:04:11 INFO - Bus: Built-In 05:04:11 INFO - VRAM (Dynamic, Max): 1536 MB 05:04:11 INFO - Vendor: Intel (0x8086) 05:04:11 INFO - Device ID: 0x0a2e 05:04:11 INFO - Revision ID: 0x0009 05:04:11 INFO - Displays: 05:04:11 INFO - Display: 05:04:11 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:04:11 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:04:11 INFO - Display Serial Number: 1600x1200 60 05:04:11 INFO - Main Display: Yes 05:04:11 INFO - Mirror: Off 05:04:11 INFO - Online: Yes 05:04:11 INFO - Rotation: Supported 05:04:11 INFO - Return code: 0 05:04:11 INFO - Running post-action listener: _resource_record_post_action 05:04:11 INFO - [mozharness: 2017-02-25 13:04:11.073093Z] Finished run-tests step (success) 05:04:11 INFO - Running post-run listener: _resource_record_post_run 05:04:11 INFO - Total resource usage - Wall time: 1202s; CPU: 54.0%; Read bytes: 1704960; Write bytes: 519950336; Read time: 17; Write time: 2058 05:04:11 INFO - TinderboxPrint: CPU usage
54.4% 05:04:11 INFO - TinderboxPrint: I/O read bytes / time
1,704,960 / 17 05:04:11 INFO - TinderboxPrint: I/O write bytes / time
519,950,336 / 2,058 05:04:11 INFO - TinderboxPrint: CPU idle
2,191.3 (45.6%) 05:04:11 INFO - TinderboxPrint: CPU system
967.8 (20.1%) 05:04:11 INFO - TinderboxPrint: CPU user
1,650.6 (34.3%) 05:04:11 INFO - TinderboxPrint: Swap in / out
212,119,552 / 0 05:04:11 INFO - install - Wall time: 21s; CPU: 54.0%; Read bytes: 198519296; Write bytes: 194996224; Read time: 15620; Write time: 467 05:04:11 INFO - run-tests - Wall time: 1182s; CPU: 54.0%; Read bytes: 1704960; Write bytes: 304486400; Read time: 17; Write time: 1533 05:04:11 INFO - Running post-run listener: _upload_blobber_files 05:04:11 INFO - Blob upload gear active. 05:04:11 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:04:11 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 05:04:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 05:04:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 05:04:11 INFO - (blobuploader) - INFO - Open directory for files ... 05:04:11 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 05:04:11 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:04:11 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:04:13 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 05:04:13 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:04:13 INFO - (blobuploader) - INFO - Done attempting. 05:04:13 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 05:04:13 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:04:13 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:04:14 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 05:04:14 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:04:14 INFO - (blobuploader) - INFO - Done attempting. 05:04:14 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 05:04:14 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:04:14 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:04:15 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 05:04:15 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:04:15 INFO - (blobuploader) - INFO - Done attempting. 05:04:15 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 05:04:15 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:04:15 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:04:17 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 05:04:17 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:04:17 INFO - (blobuploader) - INFO - Done attempting. 05:04:17 INFO - (blobuploader) - INFO - Iteration through files over. 05:04:17 INFO - Return code: 0 05:04:17 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:04:17 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:04:17 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/27d52194d67ebe360f793442112ac6970497163db0dc5e570738c5756efbef93fd78846ab11aa8de05b07a6ff1e1e7fce85d3d6365b1dd0b0c6cc1721a47fc74", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/777b2de6830eac79381017c9294cd237d3b6982d2f372ace634935a96c1cd458b06c3d2888e92bdf15ffb041f021c88d21f95e3830d3410921a6fb4ee156ebc5", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b447eb68e26994b88bdb8ab200e36c8b29a679492a424a601656a11a4f6edef521f1c187ecf997e05bd8cb0bd3c4271bec16697f5e674f5114613bf99e0b262e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9797ce9f12bdbdcd67e61153ee6bd0e49a712b56cc11d8fd5102cf0b037d14d81656ae3c7d659fbf03df452eb5c739658b0dc3f8c04080feb3edc8802d78ac2f"} 05:04:17 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:04:17 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:04:17 INFO - Contents: 05:04:17 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/27d52194d67ebe360f793442112ac6970497163db0dc5e570738c5756efbef93fd78846ab11aa8de05b07a6ff1e1e7fce85d3d6365b1dd0b0c6cc1721a47fc74", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/777b2de6830eac79381017c9294cd237d3b6982d2f372ace634935a96c1cd458b06c3d2888e92bdf15ffb041f021c88d21f95e3830d3410921a6fb4ee156ebc5", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b447eb68e26994b88bdb8ab200e36c8b29a679492a424a601656a11a4f6edef521f1c187ecf997e05bd8cb0bd3c4271bec16697f5e674f5114613bf99e0b262e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9797ce9f12bdbdcd67e61153ee6bd0e49a712b56cc11d8fd5102cf0b037d14d81656ae3c7d659fbf03df452eb5c739658b0dc3f8c04080feb3edc8802d78ac2f"} 05:04:17 INFO - Running post-run listener: copy_logs_to_upload_dir 05:04:17 INFO - Copying logs to upload dir... 05:04:17 INFO - mkdir: /builds/slave/test/build/upload/logs 05:04:17 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1285.625813 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 25 secs) (at 2017-02-25 05:04:17.746591) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-25 05:04:17.750001) ========= 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.7lyFfe1O9i/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.xSoWRdYIKb/Listeners TMPDIR=/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/27d52194d67ebe360f793442112ac6970497163db0dc5e570738c5756efbef93fd78846ab11aa8de05b07a6ff1e1e7fce85d3d6365b1dd0b0c6cc1721a47fc74", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/777b2de6830eac79381017c9294cd237d3b6982d2f372ace634935a96c1cd458b06c3d2888e92bdf15ffb041f021c88d21f95e3830d3410921a6fb4ee156ebc5", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b447eb68e26994b88bdb8ab200e36c8b29a679492a424a601656a11a4f6edef521f1c187ecf997e05bd8cb0bd3c4271bec16697f5e674f5114613bf99e0b262e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9797ce9f12bdbdcd67e61153ee6bd0e49a712b56cc11d8fd5102cf0b037d14d81656ae3c7d659fbf03df452eb5c739658b0dc3f8c04080feb3edc8802d78ac2f"} build_url:https://queue.taskcluster.net/v1/task/GzKaH1wOT52yWevZhcA6qg/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011110 build_url: 'https://queue.taskcluster.net/v1/task/GzKaH1wOT52yWevZhcA6qg/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/27d52194d67ebe360f793442112ac6970497163db0dc5e570738c5756efbef93fd78846ab11aa8de05b07a6ff1e1e7fce85d3d6365b1dd0b0c6cc1721a47fc74", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/777b2de6830eac79381017c9294cd237d3b6982d2f372ace634935a96c1cd458b06c3d2888e92bdf15ffb041f021c88d21f95e3830d3410921a6fb4ee156ebc5", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b447eb68e26994b88bdb8ab200e36c8b29a679492a424a601656a11a4f6edef521f1c187ecf997e05bd8cb0bd3c4271bec16697f5e674f5114613bf99e0b262e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9797ce9f12bdbdcd67e61153ee6bd0e49a712b56cc11d8fd5102cf0b037d14d81656ae3c7d659fbf03df452eb5c739658b0dc3f8c04080feb3edc8802d78ac2f"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-25 05:04:17.784424) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-25 05:04:17.784780) ========= 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.7lyFfe1O9i/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.xSoWRdYIKb/Listeners TMPDIR=/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/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.004892 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-25 05:04:17.834889) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-25 05:04:17.835212) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-25 05:04:17.835572) ========= ========= Total master_lag: 0.17 =========