builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0023 starttime: 1487037847.64 results: success (0) buildid: 20170213165024 builduid: 337ea0d2281b4aa3b4e693e835b2399d revision: bca0824417677907d47771bf12f293ae7909d9c3 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-02-13 18:04:07.637292) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-02-13 18:04:07.638203) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-13 18:04:07.638471) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-13 18:04:07.733162) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-13 18:04:07.733514) ========= 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.wofsCotJDI/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.egbzV7svf5/Listeners TMPDIR=/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/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-13 18:04:07-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 22.7M=0.001s 2017-02-13 18:04:08 (22.7 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.553841 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-13 18:04:08.311572) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-13 18:04:08.311885) ========= 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.wofsCotJDI/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.egbzV7svf5/Listeners TMPDIR=/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/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.027305 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-13 18:04:08.367435) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-02-13 18:04:08.367764) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev bca0824417677907d47771bf12f293ae7909d9c3 --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 bca0824417677907d47771bf12f293ae7909d9c3 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.wofsCotJDI/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.egbzV7svf5/Listeners TMPDIR=/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/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-13 18:04:08,445 truncating revision to first 12 chars 2017-02-13 18:04:08,445 Setting DEBUG logging. 2017-02-13 18:04:08,445 attempt 1/10 2017-02-13 18:04:08,445 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/bca082441767?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-02-13 18:04:09,241 unpacking tar archive at: mozilla-central-bca082441767/testing/mozharness/ program finished with exit code 0 elapsedTime=1.073522 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-02-13 18:04:09.464542) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-13 18:04:09.465111) ========= script_repo_revision: bca0824417677907d47771bf12f293ae7909d9c3 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-13 18:04:09.465733) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-13 18:04:09.466239) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-13 18:04:09.486361) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 24 secs) (at 2017-02-13 18:04:09.486670) ========= /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.wofsCotJDI/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.egbzV7svf5/Listeners TMPDIR=/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/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 18:04:09 INFO - MultiFileLogger online at 20170213 18:04:09 in /builds/slave/test 18:04:09 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 18:04:09 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 18:04:09 INFO - {'append_to_log': False, 18:04:09 INFO - 'base_work_dir': '/builds/slave/test', 18:04:09 INFO - 'blob_upload_branch': 'Firefox', 18:04:09 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 18:04:09 INFO - 'branch': 'Firefox', 18:04:09 INFO - 'buildbot_json_path': 'buildprops.json', 18:04:09 INFO - 'config_files': ('talos/mac_config.py',), 18:04:09 INFO - 'default_actions': ('clobber', 18:04:09 INFO - 'read-buildbot-config', 18:04:09 INFO - 'download-and-extract', 18:04:09 INFO - 'populate-webroot', 18:04:09 INFO - 'create-virtualenv', 18:04:09 INFO - 'install', 18:04:09 INFO - 'run-tests'), 18:04:09 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 18:04:09 INFO - 'download_minidump_stackwalk': True, 18:04:09 INFO - 'download_symbols': 'ondemand', 18:04:09 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 18:04:09 INFO - 'tooltool.py': '/tools/tooltool.py', 18:04:09 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 18:04:09 INFO - '/tools/misc-python/virtualenv.py')}, 18:04:09 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 18:04:09 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 18:04:09 INFO - 'installer_path': 'installer.exe', 18:04:09 INFO - 'log_level': 'info', 18:04:09 INFO - 'log_name': 'talos', 18:04:09 INFO - 'log_to_console': True, 18:04:09 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 18:04:09 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 18:04:09 INFO - 'opt_config_files': (), 18:04:09 INFO - 'pip_index': False, 18:04:09 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 18:04:09 INFO - 'cmd': ('bash', 18:04:09 INFO - '-c', 18:04:09 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 18:04:09 INFO - 'enabled': True, 18:04:09 INFO - 'halt_on_failure': False, 18:04:09 INFO - 'name': 'check_screen_resolution'},), 18:04:09 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 18:04:09 INFO - 'cmd': ('bash', 18:04:09 INFO - '-c', 18:04:09 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 18:04:09 INFO - 'enabled': True, 18:04:09 INFO - 'halt_on_failure': False, 18:04:09 INFO - 'name': 'check_screen_resolution'},), 18:04:09 INFO - 'run_cmd_checks_enabled': True, 18:04:09 INFO - 'sps_profile': False, 18:04:09 INFO - 'sps_profile_interval': 0, 18:04:09 INFO - 'suite': 'dromaeojs-e10s', 18:04:09 INFO - 'system_bits': '32', 18:04:09 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 18:04:09 INFO - 'title': 't-yosemite-r7-0023', 18:04:09 INFO - 'tooltool_cache': '/builds/tooltool_cache', 18:04:09 INFO - 'use_talos_json': True, 18:04:09 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 18:04:09 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 18:04:09 INFO - 'work_dir': 'build'} 18:04:09 INFO - [mozharness: 2017-02-14 02:04:09.678940Z] Running clobber step. 18:04:09 INFO - Running pre-action listener: _resource_record_pre_action 18:04:09 INFO - Running main action method: clobber 18:04:09 INFO - rmtree: /builds/slave/test/build 18:04:09 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 18:04:12 INFO - Running post-action listener: _resource_record_post_action 18:04:12 INFO - [mozharness: 2017-02-14 02:04:12.426731Z] Finished clobber step (success) 18:04:12 INFO - [mozharness: 2017-02-14 02:04:12.426928Z] Running read-buildbot-config step. 18:04:12 INFO - Running pre-action listener: _resource_record_pre_action 18:04:12 INFO - Running main action method: read_buildbot_config 18:04:12 INFO - Using buildbot properties: 18:04:12 INFO - { 18:04:12 INFO - "project": "", 18:04:12 INFO - "product": "firefox", 18:04:12 INFO - "script_repo_revision": "production", 18:04:12 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 18:04:12 INFO - "repository": "", 18:04:12 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 18:04:12 INFO - "buildid": "20170213165024", 18:04:12 INFO - "pgo_build": "False", 18:04:12 INFO - "basedir": "/builds/slave/test", 18:04:12 INFO - "buildnumber": 85, 18:04:12 INFO - "slavename": "t-yosemite-r7-0023", 18:04:12 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 18:04:12 INFO - "platform": "yosemite_r7", 18:04:12 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 18:04:12 INFO - "branch": "mozilla-central", 18:04:12 INFO - "repo_path": "mozilla-central", 18:04:12 INFO - "revision": "bca0824417677907d47771bf12f293ae7909d9c3", 18:04:12 INFO - "stage_platform": "macosx64", 18:04:12 INFO - "builduid": "337ea0d2281b4aa3b4e693e835b2399d", 18:04:12 INFO - "slavebuilddir": "test" 18:04:12 INFO - } 18:04:12 INFO - Found installer url https://queue.taskcluster.net/v1/task/VPAj2JKaQ3qrk2OvX5jJYQ/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg. 18:04:12 INFO - Running post-action listener: _resource_record_post_action 18:04:12 INFO - [mozharness: 2017-02-14 02:04:12.430314Z] Finished read-buildbot-config step (success) 18:04:12 INFO - [mozharness: 2017-02-14 02:04:12.430427Z] Running download-and-extract step. 18:04:12 INFO - Running pre-action listener: _resource_record_pre_action 18:04:12 INFO - Running main action method: download_and_extract 18:04:12 INFO - mkdir: /builds/slave/test/build/tests 18:04:12 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')]} 18:04:12 INFO - trying https://queue.taskcluster.net/v1/task/VPAj2JKaQ3qrk2OvX5jJYQ/artifacts/public/build/firefox-54.0a1.en-US.mac.test_packages.json 18:04:12 INFO - Downloading https://queue.taskcluster.net/v1/task/VPAj2JKaQ3qrk2OvX5jJYQ/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 18:04:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/VPAj2JKaQ3qrk2OvX5jJYQ/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 18:04:13 INFO - Downloaded 1199 bytes. 18:04:13 INFO - Reading from file /builds/slave/test/build/firefox-54.0a1.en-US.mac.test_packages.json 18:04:13 INFO - Using the following test package requirements: 18:04:13 INFO - {u'common': [u'firefox-54.0a1.en-US.mac.common.tests.zip'], 18:04:13 INFO - u'cppunittest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 18:04:13 INFO - u'firefox-54.0a1.en-US.mac.cppunittest.tests.zip'], 18:04:13 INFO - u'gtest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 18:04:13 INFO - u'firefox-54.0a1.en-US.mac.gtest.tests.zip'], 18:04:13 INFO - u'jittest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 18:04:13 INFO - u'jsshell-mac.zip'], 18:04:13 INFO - u'mochitest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 18:04:13 INFO - u'firefox-54.0a1.en-US.mac.mochitest.tests.zip'], 18:04:13 INFO - u'mozbase': [u'firefox-54.0a1.en-US.mac.common.tests.zip'], 18:04:13 INFO - u'reftest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 18:04:13 INFO - u'firefox-54.0a1.en-US.mac.reftest.tests.zip'], 18:04:13 INFO - u'talos': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 18:04:13 INFO - u'firefox-54.0a1.en-US.mac.talos.tests.zip'], 18:04:13 INFO - u'web-platform': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 18:04:13 INFO - u'firefox-54.0a1.en-US.mac.web-platform.tests.zip'], 18:04:13 INFO - u'xpcshell': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 18:04:13 INFO - u'firefox-54.0a1.en-US.mac.xpcshell.tests.zip']} 18:04:13 INFO - Downloading packages: [u'firefox-54.0a1.en-US.mac.common.tests.zip'] for test suite category: common 18:04:13 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/VPAj2JKaQ3qrk2OvX5jJYQ/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip 18:04:13 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/VPAj2JKaQ3qrk2OvX5jJYQ/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip'}, attempt #1 18:04:13 INFO - Fetch https://queue.taskcluster.net/v1/task/VPAj2JKaQ3qrk2OvX5jJYQ/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip into memory 18:04:14 INFO - Expected file size: 51504283 18:04:14 INFO - Obtained file size: 51504283 18:04:31 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 18:04:31 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/VPAj2JKaQ3qrk2OvX5jJYQ/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip 18:04:31 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/VPAj2JKaQ3qrk2OvX5jJYQ/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip'}, attempt #1 18:04:31 INFO - Fetch https://queue.taskcluster.net/v1/task/VPAj2JKaQ3qrk2OvX5jJYQ/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip into memory 18:04:33 INFO - Expected file size: 51504283 18:04:33 INFO - Obtained file size: 51504283 18:04:48 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/VPAj2JKaQ3qrk2OvX5jJYQ/artifacts/public/build/firefox-54.0a1.en-US.mac.talos.tests.zip 18:04:48 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/VPAj2JKaQ3qrk2OvX5jJYQ/artifacts/public/build/firefox-54.0a1.en-US.mac.talos.tests.zip'}, attempt #1 18:04:48 INFO - Fetch https://queue.taskcluster.net/v1/task/VPAj2JKaQ3qrk2OvX5jJYQ/artifacts/public/build/firefox-54.0a1.en-US.mac.talos.tests.zip into memory 18:04:49 INFO - Expected file size: 14403647 18:04:49 INFO - Obtained file size: 14403647 18:04:50 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')]} 18:04:50 INFO - trying https://queue.taskcluster.net/v1/task/VPAj2JKaQ3qrk2OvX5jJYQ/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg 18:04:50 INFO - Downloading https://queue.taskcluster.net/v1/task/VPAj2JKaQ3qrk2OvX5jJYQ/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-54.0a1.en-US.mac.dmg 18:04:50 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/VPAj2JKaQ3qrk2OvX5jJYQ/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 18:04:52 INFO - Downloaded 70379040 bytes. 18:04:52 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/VPAj2JKaQ3qrk2OvX5jJYQ/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg 18:04:52 INFO - mkdir: /builds/slave/test/properties 18:04:52 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 18:04:52 INFO - Writing to file /builds/slave/test/properties/build_url 18:04:52 INFO - Contents: 18:04:52 INFO - build_url:https://queue.taskcluster.net/v1/task/VPAj2JKaQ3qrk2OvX5jJYQ/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg 18:04:52 INFO - Running post-action listener: _resource_record_post_action 18:04:52 INFO - Running post-action listener: set_extra_try_arguments 18:04:52 INFO - [mozharness: 2017-02-14 02:04:52.812412Z] Finished download-and-extract step (success) 18:04:52 INFO - [mozharness: 2017-02-14 02:04:52.812537Z] Running populate-webroot step. 18:04:52 INFO - Running pre-action listener: _resource_record_pre_action 18:04:52 INFO - Running main action method: populate_webroot 18:04:52 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 18:04:52 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 18:04:52 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 18:04:52 INFO - '--tppagecycles', 18:04:52 INFO - '7'], 18:04:52 INFO - 'tests': ['tsvgm']}}, 18:04:52 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 18:04:52 INFO - 'tests': ['tresize', 'tcanvasmark']}, 18:04:52 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 18:04:52 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 18:04:52 INFO - 'tests': ['dromaeo_css', 'kraken']}, 18:04:52 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 18:04:52 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 18:04:52 INFO - 'talos_options': ['--disable-e10s'], 18:04:52 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:04:52 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 18:04:52 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:04:52 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 18:04:52 INFO - 'talos_options': ['--disable-e10s'], 18:04:52 INFO - 'tests': ['damp', 'tps']}, 18:04:52 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 18:04:52 INFO - 'tests': ['damp', 'tps']}, 18:04:52 INFO - 'g3': {'talos_options': ['--disable-e10s'], 18:04:52 INFO - 'tests': ['dromaeo_dom']}, 18:04:52 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 18:04:52 INFO - 'g4': {'talos_options': ['--disable-e10s'], 18:04:52 INFO - 'tests': ['basic_compositor_video']}, 18:04:52 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 18:04:52 INFO - 'other': {'talos_options': ['--disable-e10s'], 18:04:52 INFO - 'tests': ['a11yr', 18:04:52 INFO - 'ts_paint', 18:04:52 INFO - 'tpaint', 18:04:52 INFO - 'sessionrestore', 18:04:52 INFO - 'sessionrestore_no_auto_restore', 18:04:52 INFO - 'tabpaint']}, 18:04:52 INFO - 'other-e10s': {'tests': ['a11yr', 18:04:52 INFO - 'ts_paint', 18:04:52 INFO - 'tpaint', 18:04:52 INFO - 'sessionrestore', 18:04:52 INFO - 'sessionrestore_no_auto_restore', 18:04:52 INFO - 'tabpaint']}, 18:04:52 INFO - 'other-e10s_l64': {'tests': ['a11yr', 18:04:52 INFO - 'ts_paint', 18:04:52 INFO - 'tpaint', 18:04:52 INFO - 'sessionrestore', 18:04:52 INFO - 'sessionrestore_no_auto_restore', 18:04:52 INFO - 'tabpaint']}, 18:04:52 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 18:04:52 INFO - 'ts_paint', 18:04:52 INFO - 'tpaint', 18:04:52 INFO - 'sessionrestore', 18:04:52 INFO - 'sessionrestore_no_auto_restore', 18:04:52 INFO - 'tabpaint']}, 18:04:52 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 18:04:52 INFO - 'tests': ['a11yr', 18:04:52 INFO - 'ts_paint', 18:04:52 INFO - 'tpaint', 18:04:52 INFO - 'sessionrestore', 18:04:52 INFO - 'sessionrestore_no_auto_restore', 18:04:52 INFO - 'tabpaint']}, 18:04:52 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 18:04:52 INFO - 'tests': ['a11yr', 18:04:52 INFO - 'ts_paint', 18:04:52 INFO - 'tpaint', 18:04:52 INFO - 'sessionrestore', 18:04:52 INFO - 'sessionrestore_no_auto_restore', 18:04:52 INFO - 'tabpaint']}, 18:04:52 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 18:04:52 INFO - 'tests': ['tsvgx', 18:04:52 INFO - 'tsvgr_opacity', 18:04:52 INFO - 'tart', 18:04:52 INFO - 'tscrollx', 18:04:52 INFO - 'cart', 18:04:52 INFO - 'tsvg_static']}, 18:04:52 INFO - 'svgr-e10s': {'tests': ['tsvgx', 18:04:52 INFO - 'tsvgr_opacity', 18:04:52 INFO - 'tart', 18:04:52 INFO - 'tscrollx', 18:04:52 INFO - 'cart', 18:04:52 INFO - 'tsvg_static']}, 18:04:52 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 18:04:52 INFO - 'talos_options': ['--disable-e10s'], 18:04:52 INFO - 'tests': ['tp5o']}, 18:04:52 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 18:04:52 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 18:04:52 INFO - 'talos_options': ['--disable-e10s', 18:04:52 INFO - '--xperf_path', 18:04:52 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:04:52 INFO - 'tests': ['tp5n']}, 18:04:52 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 18:04:52 INFO - 'talos_options': ['--xperf_path', 18:04:52 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:04:52 INFO - 'tests': ['tp5n']}}, 18:04:52 INFO - 'talos.zip': {'path': '', 18:04:52 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 18:04:52 INFO - Running post-action listener: _resource_record_post_action 18:04:52 INFO - [mozharness: 2017-02-14 02:04:52.830982Z] Finished populate-webroot step (success) 18:04:52 INFO - [mozharness: 2017-02-14 02:04:52.831112Z] Running create-virtualenv step. 18:04:52 INFO - Running pre-action listener: _resource_record_pre_action 18:04:52 INFO - Running main action method: create_virtualenv 18:04:52 INFO - Creating virtualenv /builds/slave/test/build/venv 18:04:52 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 18:04:52 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 18:04:52 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 18:04:52 INFO - Using real prefix '/tools/python27' 18:04:52 INFO - New python executable in /builds/slave/test/build/venv/bin/python 18:04:53 INFO - Installing distribute.............................................................................................................................................................................................done. 18:04:56 INFO - Installing pip.................done. 18:04:56 INFO - Return code: 0 18:04:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 18:04:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 18:04:56 INFO - Reading from file tmpfile_stdout 18:04:56 INFO - Output received: 18:04:56 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) 18:04:56 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 18:04:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:04:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:04:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:04:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:04:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:04:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:04:56 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10982cb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10982f768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffc1856c420>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10960ad30>, '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 0x109531db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1095ffbe8>, '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.wofsCotJDI/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.egbzV7svf5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/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 18:04:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 18:04:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 18:04:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wofsCotJDI/Render', 18:04:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:04:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:04:56 INFO - 'HOME': '/Users/cltbld', 18:04:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:04:56 INFO - 'LOGNAME': 'cltbld', 18:04:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:04:56 INFO - 'MOZ_NO_REMOTE': '1', 18:04:56 INFO - 'NO_EM_RESTART': '1', 18:04:56 INFO - 'PAGER': '/bin/cat', 18:04:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:04:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:04:56 INFO - 'PWD': '/builds/slave/test', 18:04:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:04:56 INFO - 'SHELL': '/bin/bash', 18:04:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.egbzV7svf5/Listeners', 18:04:56 INFO - 'TMPDIR': '/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/', 18:04:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:04:56 INFO - 'USER': 'cltbld', 18:04:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:04:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:04:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:04:56 INFO - 'XPC_FLAGS': '0x0', 18:04:56 INFO - 'XPC_SERVICE_NAME': '0', 18:04:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:04:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:04:57 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 18:04:57 INFO - Cleaning up... 18:04:57 INFO - Return code: 0 18:04:57 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 18:04: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')]} 18:04:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:04:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:04: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')]} 18:04:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:04:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:04:57 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 0x10982cb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10982f768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffc1856c420>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10960ad30>, '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 0x109531db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1095ffbe8>, '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.wofsCotJDI/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.egbzV7svf5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/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 18:04:57 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 18:04:57 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 18:04:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wofsCotJDI/Render', 18:04:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:04:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:04:57 INFO - 'HOME': '/Users/cltbld', 18:04:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:04:57 INFO - 'LOGNAME': 'cltbld', 18:04:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:04:57 INFO - 'MOZ_NO_REMOTE': '1', 18:04:57 INFO - 'NO_EM_RESTART': '1', 18:04:57 INFO - 'PAGER': '/bin/cat', 18:04:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:04:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:04:57 INFO - 'PWD': '/builds/slave/test', 18:04:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:04:57 INFO - 'SHELL': '/bin/bash', 18:04:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.egbzV7svf5/Listeners', 18:04:57 INFO - 'TMPDIR': '/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/', 18:04:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:04:57 INFO - 'USER': 'cltbld', 18:04:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:04:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:04:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:04:57 INFO - 'XPC_FLAGS': '0x0', 18:04:57 INFO - 'XPC_SERVICE_NAME': '0', 18:04:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:04:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:04:57 INFO - Downloading/unpacking psutil>=3.1.1 18:04:57 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 18:04:57 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 18:04:57 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 18:04:57 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 18:04:57 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 18:04:57 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 18:05:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 18:05:01 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:05:01 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:05:01 INFO - Installing collected packages: psutil 18:05:01 INFO - Running setup.py install for psutil 18:05:01 INFO - building 'psutil._psutil_osx' extension 18:05:01 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 18:05:01 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 18:05:01 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 18:05:02 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 18:05:02 INFO - building 'psutil._psutil_posix' extension 18:05:02 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 18:05:02 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 18:05:02 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 18:05:02 INFO - ^ 18:05:02 INFO - 1 warning generated. 18:05:02 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 18:05:02 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:05:02 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:05:02 INFO - Successfully installed psutil 18:05:02 INFO - Cleaning up... 18:05:02 INFO - Return code: 0 18:05:02 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 18:05: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')]} 18:05:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:05:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:05: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')]} 18:05:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:05:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:05:02 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 0x10982cb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10982f768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffc1856c420>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10960ad30>, '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 0x109531db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1095ffbe8>, '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.wofsCotJDI/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.egbzV7svf5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/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 18:05:02 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 18:05:02 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 18:05:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wofsCotJDI/Render', 18:05:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:05:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:05:02 INFO - 'HOME': '/Users/cltbld', 18:05:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:05:02 INFO - 'LOGNAME': 'cltbld', 18:05:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:05:02 INFO - 'MOZ_NO_REMOTE': '1', 18:05:02 INFO - 'NO_EM_RESTART': '1', 18:05:02 INFO - 'PAGER': '/bin/cat', 18:05:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:05:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:05:02 INFO - 'PWD': '/builds/slave/test', 18:05:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:05:02 INFO - 'SHELL': '/bin/bash', 18:05:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.egbzV7svf5/Listeners', 18:05:02 INFO - 'TMPDIR': '/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/', 18:05:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:05:02 INFO - 'USER': 'cltbld', 18:05:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:05:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:05:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:05:02 INFO - 'XPC_FLAGS': '0x0', 18:05:02 INFO - 'XPC_SERVICE_NAME': '0', 18:05:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:05:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:05:02 INFO - Downloading/unpacking mozsystemmonitor==0.3 18:05:02 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 18:05:02 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 18:05:02 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 18:05:02 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 18:05:02 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 18:05:02 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 18:05:05 INFO - Downloading mozsystemmonitor-0.3.tar.gz 18:05:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 18:05:05 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 18:05:05 INFO - Installing collected packages: mozsystemmonitor 18:05:05 INFO - Running setup.py install for mozsystemmonitor 18:05:05 INFO - Successfully installed mozsystemmonitor 18:05:05 INFO - Cleaning up... 18:05:05 INFO - Return code: 0 18:05:05 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 18:05:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:05:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:05:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:05:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:05:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:05:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:05:05 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10982cb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10982f768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffc1856c420>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10960ad30>, '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 0x109531db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1095ffbe8>, '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.wofsCotJDI/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.egbzV7svf5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/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 18:05:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 18:05:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 18:05:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wofsCotJDI/Render', 18:05:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:05:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:05:05 INFO - 'HOME': '/Users/cltbld', 18:05:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:05:05 INFO - 'LOGNAME': 'cltbld', 18:05:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:05:05 INFO - 'MOZ_NO_REMOTE': '1', 18:05:05 INFO - 'NO_EM_RESTART': '1', 18:05:05 INFO - 'PAGER': '/bin/cat', 18:05:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:05:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:05:05 INFO - 'PWD': '/builds/slave/test', 18:05:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:05:05 INFO - 'SHELL': '/bin/bash', 18:05:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.egbzV7svf5/Listeners', 18:05:05 INFO - 'TMPDIR': '/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/', 18:05:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:05:05 INFO - 'USER': 'cltbld', 18:05:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:05:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:05:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:05:05 INFO - 'XPC_FLAGS': '0x0', 18:05:05 INFO - 'XPC_SERVICE_NAME': '0', 18:05:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:05:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:05:05 INFO - Downloading/unpacking jsonschema==2.5.1 18:05:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:05:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:05:05 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:05:05 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:05:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:05:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:05:08 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 18:05:08 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 18:05:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:05:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:05:08 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:05:08 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:05:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:05:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:05:09 INFO - Downloading functools32-3.2.3-2.tar.gz 18:05:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 18:05:09 INFO - warning: no files found matching '*.txt' 18:05:09 INFO - no previously-included directories found matching 'build' 18:05:09 INFO - no previously-included directories found matching 'dist' 18:05:09 INFO - no previously-included directories found matching '.git*' 18:05:09 INFO - Installing collected packages: jsonschema, functools32 18:05:09 INFO - Running setup.py install for functools32 18:05:09 INFO - warning: no files found matching '*.txt' 18:05:09 INFO - no previously-included directories found matching 'build' 18:05:09 INFO - no previously-included directories found matching 'dist' 18:05:09 INFO - no previously-included directories found matching '.git*' 18:05:09 INFO - Successfully installed jsonschema functools32 18:05:09 INFO - Cleaning up... 18:05:09 INFO - Return code: 0 18:05:09 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 18:05:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:05:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:05:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:05:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:05:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:05:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:05:09 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 0x10982cb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10982f768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffc1856c420>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10960ad30>, '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 0x109531db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1095ffbe8>, '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.wofsCotJDI/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.egbzV7svf5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/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 18:05:09 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 18:05:09 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 18:05:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wofsCotJDI/Render', 18:05:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:05:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:05:09 INFO - 'HOME': '/Users/cltbld', 18:05:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:05:09 INFO - 'LOGNAME': 'cltbld', 18:05:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:05:09 INFO - 'MOZ_NO_REMOTE': '1', 18:05:09 INFO - 'NO_EM_RESTART': '1', 18:05:09 INFO - 'PAGER': '/bin/cat', 18:05:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:05:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:05:09 INFO - 'PWD': '/builds/slave/test', 18:05:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:05:09 INFO - 'SHELL': '/bin/bash', 18:05:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.egbzV7svf5/Listeners', 18:05:09 INFO - 'TMPDIR': '/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/', 18:05:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:05:09 INFO - 'USER': 'cltbld', 18:05:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:05:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:05:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:05:09 INFO - 'XPC_FLAGS': '0x0', 18:05:09 INFO - 'XPC_SERVICE_NAME': '0', 18:05:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:05:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:05:09 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 18:05:09 INFO - Cleaning up... 18:05:09 INFO - Return code: 0 18:05:09 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 18:05:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:05:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:05:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:05:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:05:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:05:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:05:09 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 0x10982cb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10982f768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffc1856c420>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10960ad30>, '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 0x109531db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1095ffbe8>, '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.wofsCotJDI/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.egbzV7svf5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/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 18:05:09 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 18:05:09 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 18:05:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wofsCotJDI/Render', 18:05:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:05:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:05:09 INFO - 'HOME': '/Users/cltbld', 18:05:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:05:09 INFO - 'LOGNAME': 'cltbld', 18:05:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:05:09 INFO - 'MOZ_NO_REMOTE': '1', 18:05:09 INFO - 'NO_EM_RESTART': '1', 18:05:09 INFO - 'PAGER': '/bin/cat', 18:05:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:05:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:05:09 INFO - 'PWD': '/builds/slave/test', 18:05:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:05:09 INFO - 'SHELL': '/bin/bash', 18:05:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.egbzV7svf5/Listeners', 18:05:09 INFO - 'TMPDIR': '/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/', 18:05:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:05:09 INFO - 'USER': 'cltbld', 18:05:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:05:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:05:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:05:09 INFO - 'XPC_FLAGS': '0x0', 18:05:09 INFO - 'XPC_SERVICE_NAME': '0', 18:05:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:05:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:05:09 INFO - Downloading/unpacking blobuploader==1.2.4 18:05:09 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 18:05:09 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 18:05:09 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 18:05:09 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 18:05:09 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 18:05:09 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 18:05:12 INFO - Downloading blobuploader-1.2.4.tar.gz 18:05:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 18:05:12 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 18:05:12 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 18:05:12 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 18:05:12 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 18:05:12 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 18:05:12 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 18:05:12 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 18:05:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 18:05:13 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 18:05:13 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 18:05:13 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 18:05:13 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 18:05:13 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 18:05:13 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 18:05:13 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 18:05:13 INFO - Downloading docopt-0.6.1.tar.gz 18:05:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 18:05:13 INFO - Installing collected packages: blobuploader, requests, docopt 18:05:13 INFO - Running setup.py install for blobuploader 18:05:14 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 18:05:14 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 18:05:14 INFO - Running setup.py install for requests 18:05:14 INFO - Running setup.py install for docopt 18:05:14 INFO - Successfully installed blobuploader requests docopt 18:05:14 INFO - Cleaning up... 18:05:14 INFO - Return code: 0 18:05:14 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:05:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:05:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:05:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:05:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:05:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:05:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:05:14 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 0x10982cb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10982f768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffc1856c420>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10960ad30>, '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 0x109531db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1095ffbe8>, '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.wofsCotJDI/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.egbzV7svf5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/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 18:05:14 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 18:05:14 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 18:05:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wofsCotJDI/Render', 18:05:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:05:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:05:14 INFO - 'HOME': '/Users/cltbld', 18:05:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:05:14 INFO - 'LOGNAME': 'cltbld', 18:05:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:05:14 INFO - 'MOZ_NO_REMOTE': '1', 18:05:14 INFO - 'NO_EM_RESTART': '1', 18:05:14 INFO - 'PAGER': '/bin/cat', 18:05:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:05:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:05:14 INFO - 'PWD': '/builds/slave/test', 18:05:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:05:14 INFO - 'SHELL': '/bin/bash', 18:05:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.egbzV7svf5/Listeners', 18:05:14 INFO - 'TMPDIR': '/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/', 18:05:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:05:14 INFO - 'USER': 'cltbld', 18:05:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:05:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:05:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:05:14 INFO - 'XPC_FLAGS': '0x0', 18:05:14 INFO - 'XPC_SERVICE_NAME': '0', 18:05:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:05:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:05:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:05:15 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-9h7G57-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:05:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:05:15 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-AvFEMK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:05:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:05:15 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-uUj5L4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:05:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:05:15 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-AKjC0z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:05:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:05:15 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-sYR_fo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:05:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:05:15 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-y1gLgy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:05:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:05:15 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-lSATIN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:05:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:05:15 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-qlzL4D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:05:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:05:15 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-mDC1m5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:05:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:05:16 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-wm8V1z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:05:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:05:16 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-jn6bAZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:05:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:05:16 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-3Cq0Uo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:05:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:05:16 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-GhGELv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:05:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:05:16 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-gSr0S4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:05:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:05:16 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-qVUdVB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:05:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:05:16 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-ltMeyK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:05:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:05:16 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-SFWXud-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:05:16 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 18:05:16 INFO - Running setup.py install for manifestparser 18:05:17 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 18:05:17 INFO - Running setup.py install for mozcrash 18:05:17 INFO - Running setup.py install for mozdebug 18:05:17 INFO - Running setup.py install for mozdevice 18:05:17 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 18:05:17 INFO - Installing dm script to /builds/slave/test/build/venv/bin 18:05:17 INFO - Running setup.py install for mozfile 18:05:17 INFO - Running setup.py install for mozhttpd 18:05:17 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 18:05:17 INFO - Running setup.py install for mozinfo 18:05:17 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 18:05:17 INFO - Running setup.py install for mozInstall 18:05:18 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 18:05:18 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 18:05:18 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 18:05:18 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 18:05:18 INFO - Running setup.py install for mozleak 18:05:18 INFO - Running setup.py install for mozlog 18:05:18 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 18:05:18 INFO - Running setup.py install for moznetwork 18:05:18 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 18:05:18 INFO - Running setup.py install for mozprocess 18:05:18 INFO - Running setup.py install for mozprofile 18:05:18 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 18:05:18 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 18:05:18 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 18:05:18 INFO - Running setup.py install for mozrunner 18:05:18 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 18:05:19 INFO - Running setup.py install for mozscreenshot 18:05:19 INFO - Running setup.py install for moztest 18:05:19 INFO - Running setup.py install for mozversion 18:05:19 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 18:05:19 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 18:05:19 INFO - Cleaning up... 18:05:19 INFO - Return code: 0 18:05:19 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:05:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:05:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:05:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:05:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:05:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:05:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:05:19 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 0x10982cb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10982f768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffc1856c420>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10960ad30>, '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 0x109531db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1095ffbe8>, '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.wofsCotJDI/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.egbzV7svf5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/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 18:05:19 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 18:05:19 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 18:05:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wofsCotJDI/Render', 18:05:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:05:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:05:19 INFO - 'HOME': '/Users/cltbld', 18:05:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:05:19 INFO - 'LOGNAME': 'cltbld', 18:05:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:05:19 INFO - 'MOZ_NO_REMOTE': '1', 18:05:19 INFO - 'NO_EM_RESTART': '1', 18:05:19 INFO - 'PAGER': '/bin/cat', 18:05:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:05:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:05:19 INFO - 'PWD': '/builds/slave/test', 18:05:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:05:19 INFO - 'SHELL': '/bin/bash', 18:05:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.egbzV7svf5/Listeners', 18:05:19 INFO - 'TMPDIR': '/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/', 18:05:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:05:19 INFO - 'USER': 'cltbld', 18:05:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:05:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:05:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:05:19 INFO - 'XPC_FLAGS': '0x0', 18:05:19 INFO - 'XPC_SERVICE_NAME': '0', 18:05:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:05:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:05:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:05:19 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-cmX2TK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:05:19 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)) 18:05:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:05:19 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-BfyoSf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:05:19 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)) 18:05:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:05:19 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-X3XB08-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:05:20 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)) 18:05:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:05:20 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-5DfBdR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:05:20 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)) 18:05:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:05:20 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-EZek13-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:05:20 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)) 18:05:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:05:20 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-QN60J9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:05:20 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)) 18:05:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:05:20 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-rdAuJl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:05:20 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)) 18:05:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:05:20 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-vRhqFM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:05:20 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)) 18:05:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:05:20 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-QmXytg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:05:20 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)) 18:05:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:05:20 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-_NLIou-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:05:20 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)) 18:05:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:05:20 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-yHWV0X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:05:20 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)) 18:05:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:05:20 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-jNFCar-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:05:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.24 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 18:05:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:05:21 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-YnQ9Eu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:05:21 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)) 18:05:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:05:21 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-hVfAll-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:05:21 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)) 18:05:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:05:21 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-3wSJVE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:05:21 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)) 18:05:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:05:21 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-7Y2eC3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:05:21 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)) 18:05:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:05:21 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-OSXUyg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:05:21 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)) 18:05:21 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)) 18:05:21 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)) 18:05:21 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)) 18:05:21 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)) 18:05:21 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)) 18:05:21 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 18:05:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:05:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:05:21 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:05:21 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:05:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:05:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:05:24 INFO - Downloading blessings-1.6.tar.gz 18:05:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 18:05:24 INFO - Installing collected packages: blessings 18:05:24 INFO - Running setup.py install for blessings 18:05:24 INFO - Successfully installed blessings 18:05:24 INFO - Cleaning up... 18:05:24 INFO - Return code: 0 18:05:24 INFO - Done creating virtualenv /builds/slave/test/build/venv. 18:05:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:05:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:05:24 INFO - Reading from file tmpfile_stdout 18:05:24 INFO - Current package versions: 18:05:24 INFO - blessings == 1.6 18:05:24 INFO - blobuploader == 1.2.4 18:05:24 INFO - docopt == 0.6.1 18:05:24 INFO - functools32 == 3.2.3-2 18:05:24 INFO - jsonschema == 2.5.1 18:05:24 INFO - manifestparser == 1.1 18:05:24 INFO - mozInstall == 1.12 18:05:24 INFO - mozcrash == 1.0 18:05:24 INFO - mozdebug == 0.1 18:05:24 INFO - mozdevice == 0.49 18:05:24 INFO - mozfile == 1.2 18:05:24 INFO - mozhttpd == 0.7 18:05:24 INFO - mozinfo == 0.9 18:05:24 INFO - mozleak == 0.1 18:05:24 INFO - mozlog == 3.4 18:05:24 INFO - moznetwork == 0.27 18:05:24 INFO - mozprocess == 0.24 18:05:24 INFO - mozprofile == 0.28 18:05:24 INFO - mozrunner == 6.13 18:05:24 INFO - mozscreenshot == 0.1 18:05:24 INFO - mozsystemmonitor == 0.3 18:05:24 INFO - moztest == 0.8 18:05:24 INFO - mozversion == 1.4 18:05:24 INFO - psutil == 3.1.1 18:05:24 INFO - requests == 1.2.3 18:05:24 INFO - wsgiref == 0.1.2 18:05:24 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:05:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:05:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:05:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:05:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:05:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:05:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:05:24 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 0x10982cb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10982f768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffc1856c420>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10960ad30>, '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 0x109531db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1095ffbe8>, '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.wofsCotJDI/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.egbzV7svf5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/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 18:05:24 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 18:05:24 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 18:05:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wofsCotJDI/Render', 18:05:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:05:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:05:24 INFO - 'HOME': '/Users/cltbld', 18:05:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:05:24 INFO - 'LOGNAME': 'cltbld', 18:05:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:05:24 INFO - 'MOZ_NO_REMOTE': '1', 18:05:24 INFO - 'NO_EM_RESTART': '1', 18:05:24 INFO - 'PAGER': '/bin/cat', 18:05:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:05:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:05:24 INFO - 'PWD': '/builds/slave/test', 18:05:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:05:24 INFO - 'SHELL': '/bin/bash', 18:05:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.egbzV7svf5/Listeners', 18:05:24 INFO - 'TMPDIR': '/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/', 18:05:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:05:24 INFO - 'USER': 'cltbld', 18:05:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:05:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:05:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:05:24 INFO - 'XPC_FLAGS': '0x0', 18:05:24 INFO - 'XPC_SERVICE_NAME': '0', 18:05:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:05:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:05:25 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)) 18:05:25 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)) 18:05:25 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)) 18:05:25 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)) 18:05:25 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)) 18:05:25 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)) 18:05:25 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)) 18:05:25 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)) 18:05:25 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)) 18:05:25 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)) 18:05:25 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)) 18:05:25 INFO - Cleaning up... 18:05:25 INFO - Return code: 0 18:05:25 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 18:05:25 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')]} 18:05:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:05:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:05:25 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')]} 18:05:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:05:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:05:25 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 0x10982cb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10982f768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffc1856c420>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10960ad30>, '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 0x109531db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1095ffbe8>, '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.wofsCotJDI/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.egbzV7svf5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/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 18:05:25 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 18:05:25 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 18:05:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wofsCotJDI/Render', 18:05:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:05:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:05:25 INFO - 'HOME': '/Users/cltbld', 18:05:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:05:25 INFO - 'LOGNAME': 'cltbld', 18:05:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:05:25 INFO - 'MOZ_NO_REMOTE': '1', 18:05:25 INFO - 'NO_EM_RESTART': '1', 18:05:25 INFO - 'PAGER': '/bin/cat', 18:05:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:05:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:05:25 INFO - 'PWD': '/builds/slave/test', 18:05:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:05:25 INFO - 'SHELL': '/bin/bash', 18:05:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.egbzV7svf5/Listeners', 18:05:25 INFO - 'TMPDIR': '/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/', 18:05:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:05:25 INFO - 'USER': 'cltbld', 18:05:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:05:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:05:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:05:25 INFO - 'XPC_FLAGS': '0x0', 18:05:25 INFO - 'XPC_SERVICE_NAME': '0', 18:05:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:05:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:05:25 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 18:05:25 INFO - Cleaning up... 18:05:25 INFO - Return code: 0 18:05:25 INFO - Running post-action listener: _resource_record_post_action 18:05:25 INFO - Running post-action listener: _start_resource_monitoring 18:05:25 INFO - Starting resource monitoring. 18:05:25 INFO - [mozharness: 2017-02-14 02:05:25.421841Z] Finished create-virtualenv step (success) 18:05:25 INFO - [mozharness: 2017-02-14 02:05:25.422810Z] Running install step. 18:05:25 INFO - Running pre-action listener: _resource_record_pre_action 18:05:25 INFO - Running main action method: install 18:05:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:05:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:05:25 INFO - Reading from file tmpfile_stdout 18:05:25 INFO - Detecting whether we're running mozinstall >=1.0... 18:05:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 18:05:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 18:05:25 INFO - Reading from file tmpfile_stdout 18:05:25 INFO - Output received: 18:05:25 INFO - Usage: mozinstall [options] installer 18:05:25 INFO - Options: 18:05:25 INFO - -h, --help show this help message and exit 18:05:25 INFO - -d DEST, --destination=DEST 18:05:25 INFO - Directory to install application into. [default: 18:05:25 INFO - "/builds/slave/test"] 18:05:25 INFO - --app=APP Application being installed. [default: firefox] 18:05:25 INFO - mkdir: /builds/slave/test/build/application 18:05:25 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'] 18:05:25 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 18:05:48 INFO - Reading from file tmpfile_stdout 18:05:48 INFO - Output received: 18:05:48 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 18:05:48 INFO - Running post-action listener: _resource_record_post_action 18:05:48 INFO - [mozharness: 2017-02-14 02:05:48.460024Z] Finished install step (success) 18:05:48 INFO - [mozharness: 2017-02-14 02:05:48.460184Z] Running run-tests step. 18:05:48 INFO - Running pre-action listener: _resource_record_pre_action 18:05:48 INFO - Running main action method: run_tests 18:05:48 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 18:05:48 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 18:05:48 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 18:05:48 INFO - 2017-02-13 18:05:48.474 screenresolution[1628:7468] starting screenresolution argv=screenresolution get 18:05:48 INFO - 2017-02-13 18:05:48.477 screenresolution[1628:7468] Display 0: 1600x1200x32@60 18:05:48 INFO - 2017-02-13 18:05:48.483 screenresolution[1629:7470] starting screenresolution argv=screenresolution list 18:05:48 INFO - Available Modes on Display 0 18:05:48 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 18:05:48 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 18:05:48 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 18:05:48 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 18:05:48 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 18:05:48 INFO - Intel Iris: 18:05:48 INFO - Chipset Model: Intel Iris 18:05:48 INFO - Type: GPU 18:05:48 INFO - Bus: Built-In 18:05:48 INFO - VRAM (Dynamic, Max): 1536 MB 18:05:48 INFO - Vendor: Intel (0x8086) 18:05:48 INFO - Device ID: 0x0a2e 18:05:48 INFO - Revision ID: 0x0009 18:05:48 INFO - Displays: 18:05:48 INFO - Display: 18:05:48 INFO - Resolution: 1600 x 1200 @ 60 Hz 18:05:48 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 18:05:48 INFO - Display Serial Number: 1600x1200 60 18:05:48 INFO - Main Display: Yes 18:05:48 INFO - Mirror: Off 18:05:48 INFO - Online: Yes 18:05:48 INFO - Rotation: Supported 18:05:48 INFO - Return code: 0 18:05:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 18:05:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 18:05:48 INFO - Python 2.7.3 18:05:48 INFO - Return code: 0 18:05:48 INFO - grabbing minidump binary from tooltool 18:05: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')]} 18:05:48 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 0x10960ad30>, '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 0x109531db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1095ffbe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 18:05:48 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 18:05:48 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 18:05:48 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 18:05:48 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 18:05:48 INFO - Return code: 0 18:05:48 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 18:05:48 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 18:05:48 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 18:05:48 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 18:05:48 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 18:05:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/VPAj2JKaQ3qrk2OvX5jJYQ/artifacts/public/build/firefox-54.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0023', '--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 18:05:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/VPAj2JKaQ3qrk2OvX5jJYQ/artifacts/public/build/firefox-54.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0023 --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 18:05:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wofsCotJDI/Render', 18:05:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:05:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:05:48 INFO - 'HOME': '/Users/cltbld', 18:05:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:05:48 INFO - 'LOGNAME': 'cltbld', 18:05:48 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 18:05:48 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 18:05:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:05:48 INFO - 'MOZ_NO_REMOTE': '1', 18:05:48 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 18:05:48 INFO - 'NO_EM_RESTART': '1', 18:05:48 INFO - 'PAGER': '/bin/cat', 18:05:48 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:05:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:05:48 INFO - 'PWD': '/builds/slave/test', 18:05:48 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 18:05:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:05:48 INFO - 'SHELL': '/bin/bash', 18:05:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.egbzV7svf5/Listeners', 18:05:48 INFO - 'TMPDIR': '/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/', 18:05:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:05:48 INFO - 'USER': 'cltbld', 18:05:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:05:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:05:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:05:48 INFO - 'XPC_FLAGS': '0x0', 18:05:48 INFO - 'XPC_SERVICE_NAME': '0', 18:05:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:05:48 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/VPAj2JKaQ3qrk2OvX5jJYQ/artifacts/public/build/firefox-54.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0023', '--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 18:05:49 INFO - mozversion application_buildid: 20170213165024 18:05:49 INFO - mozversion application_changeset: bca0824417677907d47771bf12f293ae7909d9c3 18:05:49 INFO - mozversion application_display_name: Nightly 18:05:49 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 18:05:49 INFO - mozversion application_name: Firefox 18:05:49 INFO - mozversion application_remotingname: firefox 18:05:49 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 18:05:49 INFO - mozversion application_vendor: Mozilla 18:05:49 INFO - mozversion application_version: 54.0a1 18:05:49 INFO - mozversion platform_buildid: 20170213165024 18:05:49 INFO - mozversion platform_changeset: bca0824417677907d47771bf12f293ae7909d9c3 18:05:49 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 18:05:49 INFO - mozversion platform_version: 54.0a1 18:05:49 INFO - using testdate: 1487037949 18:05:49 INFO - actual date: 1487037949 18:05:49 INFO - starting webserver on 'localhost:49240' 18:05:49 INFO - SUITE-START | Running 2 tests 18:05:49 INFO - TEST-START | dromaeo_css 18:05:49 INFO - operating with platform_type : mac_ 18:05:49 INFO - Initialising browser for dromaeo_css test... 18:05:49 INFO - TEST-INFO | started process 1639 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/tmpEbXyH1/profile http://localhost:49240/getInfo.html) 18:05:51 INFO - PROCESS | 1639 | JavaScript error: chrome://webcompat/content/lib/ua_overrider.jsm, line 109: NS_ERROR_INSUFFICIENT_DOMAIN_LEVELS: Component returned failure code: 0x804b0050 (NS_ERROR_INSUFFICIENT_DOMAIN_LEVELS) [nsIEffectiveTLDService.getBaseDomain] 18:05:52 INFO - PROCESS | 1639 | 2017-02-13 18:05:52.043 plugin-container[1641:7635] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8e3b, name = 'com.apple.tsm.portname' 18:05:52 INFO - PROCESS | 1639 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:05:52 INFO - PROCESS | 1639 | 2017-02-13 18:05:52.047 plugin-container[1641:7635] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4b07, name = 'com.apple.CFPasteboardClient' 18:05:52 INFO - PROCESS | 1639 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:05:52 INFO - PROCESS | 1639 | 2017-02-13 18:05:52.047 plugin-container[1641:7635] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 18:05:52 INFO - PROCESS | 1639 | __metrics Screen width/height:1600/1200 18:05:52 INFO - PROCESS | 1639 | colorDepth:24 18:05:52 INFO - PROCESS | 1639 | Browser inner width/height: 1024/689 18:05:52 INFO - PROCESS | 1639 | __metrics 18:05:52 ERROR - PROCESS | 1639 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3283: TypeError: this.tabs is undefined 18:05:52 INFO - TEST-INFO | 1639: exit 0 18:05:52 INFO - Browser initialized. 18:05:52 INFO - Running cycle 1/1 for dromaeo_css test... 18:05:52 INFO - TEST-INFO | started process 1642 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/tmpEbXyH1/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:05:55 INFO - PROCESS | 1642 | 2017-02-13 18:05:55.630 plugin-container[1643:7759] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4637, name = 'com.apple.tsm.portname' 18:05:55 INFO - PROCESS | 1642 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:05:55 INFO - PROCESS | 1642 | 2017-02-13 18:05:55.631 plugin-container[1643:7759] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x900b, name = 'com.apple.CFPasteboardClient' 18:05:55 INFO - PROCESS | 1642 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:05:55 INFO - PROCESS | 1642 | 2017-02-13 18:05:55.631 plugin-container[1643:7759] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 18:06:05 INFO - PROCESS | 1642 | RSS: Main: 233910272 18:06:05 INFO - PROCESS | 1642 | 18:09:07 INFO - PROCESS | 1642 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49240/tests/dromaeo/cssquery-ext.html) 18:09:09 INFO - PROCESS | 1642 | RSS: Main: 260345856 18:09:09 INFO - PROCESS | 1642 | 18:10:53 INFO - PROCESS | 1642 | ************************* 18:10:53 INFO - PROCESS | 1642 | A coding exception was thrown and uncaught in a Task. 18:10:53 INFO - PROCESS | 1642 | 18:10:53 ERROR - PROCESS | 1642 | Full message: TypeError: NetworkError when attempting to fetch resource. 18:10:53 INFO - PROCESS | 1642 | Full stack: 18:10:53 INFO - PROCESS | 1642 | ************************* 18:12:12 INFO - PROCESS | 1642 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-ext.html (next: http://localhost:49240/tests/dromaeo/cssquery-jquery.html) 18:12:12 INFO - PROCESS | 1642 | RSS: Main: 263770112 18:12:12 INFO - PROCESS | 1642 | 18:15:14 INFO - PROCESS | 1642 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49240/tests/dromaeo/cssquery-mootools.html) 18:15:15 INFO - PROCESS | 1642 | RSS: Main: 274870272 18:15:15 INFO - PROCESS | 1642 | 18:18:17 INFO - PROCESS | 1642 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49240/tests/dromaeo/cssquery-prototype.html) 18:18:17 INFO - PROCESS | 1642 | RSS: Main: 267677696 18:18:17 INFO - PROCESS | 1642 | 18:21:20 INFO - PROCESS | 1642 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49240/tests/dromaeo/cssquery-yui.html) 18:21:21 INFO - PROCESS | 1642 | RSS: Main: 271622144 18:21:21 INFO - PROCESS | 1642 | 18:24:24 INFO - PROCESS | 1642 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-yui.html (next: http://localhost:49240/tests/dromaeo/cssquery-dojo.html) 18:24:24 INFO - PROCESS | 1642 | RSS: Main: 273342464 18:24:24 INFO - PROCESS | 1642 | 18:24:24 INFO - PROCESS | 1642 | __start_tp_report 18:24:24 INFO - PROCESS | 1642 | _x_x_mozilla_page_load 18:24:24 INFO - PROCESS | 1642 | _x_x_mozilla_page_load_details 18:24:24 INFO - PROCESS | 1642 | |i|pagename|runs| 18:24:24 INFO - PROCESS | 1642 | |0;dojo.html;2915.810472319299;3325.7505687073476;3332.1503016730762;3336.266021475276;3398.6091599466054;5388.110961691326;5556.611037227391;5558.166275058738;5594.272744543214;5710.600257981943;5408.2428460015535;5501.174823776436;5550.306211723535;5594.86012849679;5626.240457538231;5143.665661731994;5144.794208231666;5190.558802501791;5212.452692467293;5256.684598924076;5185.714785686786;5242.056429842646;5246.7901283948595;5249.553787928031;5259.079661059319;41.12926396328671;5136.614753893473;5142.305788718531;5196.558292545138;5267.420583735786;4664.416947881515;5135.589152867761;5140.897182056376;5143.588512918957;5194.68831870089;5109.284700141983;5143.099957507458;5154.433012368637;5160.741962901878;5163.974180129113;5200.24596433515;5485.835424937259;5508.6970216638065;5555.3611334696625;5667.716614169275;6022.427869352405;6103.519504250422;6111.572189946705;6123.099607468082;6137.6624285664275;5188.11801993662;5232.73836308183;5510;5998.490128339053;6216.285127210338;5763.106718458645;5766.163906233573;5768;5780.2774653167935;5787.363390027093;6044.24446944132;6102.580142287592;6110.103144028831;6113.541484388688;6223.813285601439;5144.8713782155;6042.154098426224;6079.331273559905;6127.220070483952;6145.832291864532;5191.117510023305;6040.335563088057;6155.507559395238;6173.536984726164;6205.627662340274;5455.8090466834265;5951;6016.69916504173;6174.228221472316;6175.536834737323;6101.731934812904;6123.816285511352;6131.294901086343;6135.32511423743;6139.785107521216;4215.430916826189;4708.4820669726305;4870.6103511718975;4964.60283177345;4965.981973695384;4346.413234213404;4358.302671572596;4399.38408622793;4423.646108311392;4433.1577000369825;5745.253117094834;5794.478496935296;5796.478316951495;5800.564957628195;5859.941400586024;4963.610189147044;6057.182280392179;6063.9090413643835;6129.908051379233;6188.752449901954;5631.324241091095;5669;5671.6597004179885;5683.77264909399;5687.800926967537;4503.437070366204;5412.648177868426;5428.240046393508;5455.18172274162;5473.28847249855;6217.378262173836;6222.751089956532;6239.064140378889;6241.001439769615;6244.12582238487;6018.157457955802;6040.456358927718;6052.606580572248;6083.6349819011;6092.360302168208;6032.155498230163;6129.3564175762685;6146.754129834757;6153.13856060143;6171;6071.483923866521;6073.635581865102;6090.451859332681;6094.268687757498;6139.539534534839;5969.522438204846;6064.757409703562;6091.238595175603;6094.725737341919;6105.206323178136;5885.058390657474;5909.497692695997;6023.548233882389;6025.517958563392;6050.6369617823075;6048.84877878039;6051.818445446644;6060.2424696912885;6072.30168530607;6114.938850611615;5777.364489906023;5997.610155340063;6013.398660133952;6015.0676645120075;6030.095485677183;5878.647281163144;5941.168236446814;5955.5235581154275;5995.730192141451;6036.69816509164;4780.378550788375;5799.536037117105;5823.039198532195;5897.70511474416;5969.91045134332 18:24:24 INFO - PROCESS | 1642 | |1;ext.html;459.2330807551389;492.1805194351405;492.254234834226;505.78106762701884;565.1466285908277;31619.841900790492;34483.827580862126;37328.44007339889;39208.21583568334;39269.60730392699;36927.63072369282;40694;40724.389134162935;40765;40890.18219635613;7828.960855195744;9117.817643647122;9339.906600933971;9355.438673679568;9379.24966002718;7785.377169826429;10836.458177091154;11081.501332440059;11081.667549973474;11294.322340659586;11140.33158010522;11166.553337866517;11199.66401007971;11215;11232.719182020433;9729.902700973042;11117.888821111766;11159.107271418266;11189.944050279737;11221.326720396803;9628.566714497865;10990.395528245943;11014.284071535405;11085.778284434347;11122.332660040423;38783.61216387828;39090.60909390927;39186.21627567433;39207.41188882169;39410.408843867364;3502.2995400919663;3745.831437585288;3748.1566647504364;3763.5860055393887;3912.39357899526;28971;29961;30015.249618759488;30065.549016764766;30297.394052118834;2998;3122.1414111119398;3153.4323821712082;3184.8248346340943;3187.075748033091;449036.75481622707;452946;454154;454200.7289963562;456553;57932.710336448894;58091.70954145202;58390.7080464595;58442;58477.12284315738;61565.38434615686;61661;61803.38196617976;61925.690371547855;61954.69022654858;14678.779818302733;14797.112173269637;14801.925990369979;14825.70348593022;14826.703465930621;11324.490397931948;11401.429928503541;11430.485628146753;11441.542338306375;11485.598004069985;14237.85762142386;16122.952008119435;16132.919335403249;16137.757933631005;16196.919015404848;6687.732490700318;7117.7152913882865;7155.7495487658725;7189.568625882359;7224.819379515554;768.5849641193804;798.8122791144076;813.4265342933218;828.9141224995276;836.0719601242616;24270.51458970846;26201.606975894996;28259.0109346176;28552.71447285542;28871.5669264957;982.2191357870367;1021.5913634546241;1025.3898930136556;1041.265907535186;1149.008284588273;11421.972022517846;13205.867941320656;14588.978771485894;14859.2570371481;14885.255737213096;561139;562057.1897140488;564921;566266;566864;56003.439965600635;56242;56428;56506.15240771392;56793.71603142041;1178.6169494914186;1277.9424925878359;1340.550915443318;1342.2550484481208;1351.797230415426;23276.88361558249;30279.545806812475;30446.695533044385;31027.844860775553;32294.515582266755;51284;51616.74191629018;51903.2214516775;51924.74037629788;52018.73990630022;518525;523608;523699;523936;524194;23841.64237536473;24556.386090347885;31310.843445782626;32725.34549309048;37329.25341493101;40692;41532.1693566133;41574.792126039174;41796.58203418049;41946.741597752116;32296.515552267203;33547.83226083854;33883.83058084694;34515.82742086273;35193.82403088071;20850;27119;27285.90856365723;27948.16155515338;28003;32826;33738.662613373555;33758.3248335027;33979.64081436715;34014.149646259044;23463.41341466477;26444.735552644226;30574.777008920282;30737.53893691641;30747.53878691777;23107.09227584699;23464.413389665395;23788.643170352112;23796.643050354603;23932.28203153908 18:24:24 INFO - PROCESS | 1642 | |2;jquery.html;369.7818287210547;385.2314632308544;385.3814627522826;396.1127075351214;416.88118886117445;15504.92247538762;18040.819591804076;19082;19792.802071979277;19883.00584970751;19857.900710496426;20150.798492015114;20468.795312046874;20722.171113155495;20760;4525.343825145352;5672.773089076442;5760.991826430379;5815.069588865782;5824.068149096145;4883.584895283905;5128.358955130609;5846.4445877641765;5847.970760146193;5852.12218167274;5521.641093328941;5647.717614119299;5756.165356023374;5795.449432303924;5797.275340582427;5668.121441176603;5691.402402747714;5716.28546431696;5720.942790572082;5725.08398656213;4466.905608125998;4857.854264372039;4991.5258050485145;5085;5104.2088476286235;24391;24464.776761162047;24481.755182448127;24522.386940326454;24595.262142135765;2226.3543572363997;2588.158848374267;2600.739926007403;2609.178108895692;2611.973880261192;12379.938100309531;14590.124592524482;14709.779353309708;14923.552293431214;15023;1736.513776142682;1748.510417083206;1765.4438851761781;1770.9557261068448;1822.6445843060608;83323.5833820833;92124.02387689558;114217;114368;115095.84904150935;9722.2708296878;10153.949230253802;10179.7455063624;11379.43102844871;11585.652430426931;10989.340639561651;12558.623241302776;12673.112882098349;12673.556425525247;12705.428255728515;7546.622668866645;7699.576523291273;7729.729459468892;7745.380369570421;7783.182765809508;7638.961805190938;7692.576908269987;7698.692052317844;7870.763877083697;7871.960640196762;4001.6598589119667;4002.579729128458;4019.658329042064;4025.4163146343617;4103.4460347852655;1980.6731889238401;2022.5954809037958;2027.9391618251475;2030.1371916935243;2043.3767700851085;986.9703908882746;995.7012896131132;996.2577879479834;998.2164001258977;1038.9428581427942;17864.46406607804;18490.70565060433;19320.341375578104;20412.795872041388;20831.37505874826;1020.5560581147224;1022.1873610479687;1052.5105825791045;1055.3351388625117;1058.2380336739243;10428.270021098602;10888.510017049251;10896.073833724222;10899.618513351994;10936.179786516042;662492.1258661543;663655;664942;667455.662721693;680123;126888.36555817162;138556;146548;151393.24303378412;152486;834.8956380452444;1002.4085789384299;1017.4709151241314;1031.8297480915564;1037.5331101004426;17596.560085997953;18355.908220458812;18627.627447450704;18730.438086857415;18784.436466906016;53985.46014539804;54269.45730542802;54808.72595636996;56185.876282474936;56566;79523;79758;80283.1971680299;81241.18758812573;82100.58949705213;7771.650275737492;8035.357171426389;8056.154103819014;8444.915550844413;8466;9125.634974600942;11665.883341166818;11883.346415947206;12228.432029273952;12736.23582585048;10075.596976120873;10687.732806679895;10954.178436617445;10973;11069.505616741637;8594.871076933727;10073.093421592092;10377.740556486147;10938.94530527374;11108.111351092055;10812.783744325227;11231.326120432801;11380.03269722083;11398.316101033965;11430.028447582048;6684;6983.127109111361;7307.561546307238;7440.851182976418;8464.772607971687;9162.434518334878;10797.488351630935;10800.595922530336;10876.129909607054;11085.50115244832 18:24:24 INFO - PROCESS | 1642 | |3;mootools.html;1233.759416913702;1398.79717440971;1402.3128666953196;1425.7453440971942;1428.4857451317523;8050.8792368114455;8194.877076843852;8474.576271186446;8522.914770852298;8541.017782954954;8283.25450709436;8496;8511.148885111486;8520;8520.78698032548;2217.057750456058;2263.3436303472054;2267.3991392280977;2270.6139956207485;2273.1248469339257;2232.0513781642817;2271.9886400567975;2274.124462082094;2275.1468199425217;2285.2915596165158;2233.508628101815;2240.350298413458;2260.0959616153586;2261.4911644879935;2267.648514480258;2158.125958986597;2221.1004543160047;2244.6857439958417;2247.921322753712;2259.604569200392;1901.3250296144945;1983.4644645945514;2206.823454123666;2255.819534437241;2270.364297996547;8205.343572514184;8409.495430274143;8413.326933845277;8509.021462531764;8534.914650853474;5013.724245166514;5186.766595503212;5198.012377648272;5201.609879259033;5206;3053.8090144843527;3381.7463690223085;3411;3413.4879768034743;3478.843452044664;1180.0913296761446;1180.415694231361;1196.8803119687961;1213.6783752305646;1230.4585982167816;10792;10795.676129716121;10853.620123295726;10926.617568385147;10928;4063.39049142631;4299.548547402478;4370.847020354272;4374.168907907551;4448.377227188196;8699.999500057447;8704.477731336014;8730.214280714767;8740.169683880022;8769.605367758466;4955.909699866023;5079.009593129304;5084.8220312288895;5099.235114732745;5112.182050871917;3646.580643226063;4119.670426365884;4709.246520556763;4740.312654665055;4742.099001189832;3574;3608.1701208722143;3617.276544691072;3648.908777280589;3661.6931333259877;1134.3817619397448;1137.4824454873012;1144.9313041217388;1146.9197156199152;1152.8962393384468;856.6830272799104;875.991240087591;880.097899652861;920.6133423961952;927.8608208768738;5404.972975135099;5596.916046259309;5636.239107720406;5822.097574875901;5850.3564607893095;858.914108589136;904.3217586809892;946.7869729310987;974.5176137811828;1012.7468132966758;3676.356637588411;3699.8345521161286;3733.8879833605033;3774.245150969762;3856.652901238902;8065.879011814827;9561.330706850593;9944.900550994398;9965.35225210359;10198;9832.459214743114;9936.205103591696;9955;9971.102600765966;9981.900180998242;7640.159582445926;7846.3330616897065;7864.056313242448;7876.684932602746;7877.7636670899965;4514.751688657024;4744.881377965578;5125.412211451924;5200.817971370984;5362.51737343641;7635.5800430974605;8795.47227166372;9215.308851836005;9232.723018309462;9333;9179.586918588813;9408.106229908148;9461.668841590512;9477.241820654193;9543.093406126407;7078.398336141486;7108.218096009331;7218.783436496913;7244.529105608119;7265.382442492447;7759.146493885781;7825.726099586487;7871.527708337518;7898.881516777368;7899.565523896241;6316.399942005502;6660.700268487833;6959.1996920352785;6982.476314276551;7113;6865.862682746218;6878.449724021966;6893.379595836399;6913.308669133046;7007.6846541904715;6296.275928268308;6297.464715499051;6332.52506062038;6358.713857876314;6388.361163883575;4588.977055114837;4632.76836158184;4665.2302370108555;4686.062787442594;4718.103560323665;4735.076660051257;4795.908759793324;4902.485239049991;4924.532169443897;5066.797328106834 18:24:24 INFO - PROCESS | 1642 | |4;prototype.html;346.8647227581244;352.4748125293313;361.0792479178096;362.7859562857913;366.7561071887195;4769.403824521931;4813.796550862285;4836.258946779157;5145.434002259748;5338.172583249598;4296.914061718771;4685.85942421727;5136.280920671109;5188.792448302072;5194.298769666094;3490.933062981623;3568.161482051722;4124.216398884202;4151.149014452045;4164.500259968793;3680.871169509068;3994.920101597966;4108.5686002969705;4156.5635608261155;4167.020750123734;3603.3155299302057;4061.24866899623;4067.068142632241;4097.037196258877;4112.97943510282;3861.3242682530727;4076;4078.5921407858978;4096.897577560585;4106.07613287008;3194.9009460822963;3424.3151369725906;3467.2545402738533;3641.526601541809;3653.2328211075705;4816.879578010543;5011.747063234191;5026.246063090529;5080.898382032338;5157.277981082651;4735.976320118378;4742.905141897178;4746.958807712682;4752.833650822205;4763.618910487153;4608.354830323758;5536;5541.251930989305;5547.72261386929;5578.326502049369;3486.7099173305787;3601.4777857210556;3797.810109494542;3970.6896724080984;4005.2590270799706;4444.911101777946;5554.972225138929;5623.437656234385;5694.202811606378;5784.971075144597;3595.460680897886;3752.605976372496;3794.392897136445;3932.8230229639735;4060.2894493463527;4092.283850326181;4106.815193316308;4122.7732474713575;4128.256913755493;4179.770112643773;4526.275795872644;4642.883927901829;4699.553542413465;4706.505816889246;4729.763511824395;5270.420253772082;5479.53423958968;5544.891021795657;5581.492996890816;5592.888142237132;1187.5784096645739;1276.6233960981533;1352.702405470795;1358.9932050339685;1360.1501957338012;863.1614660772515;947.6020071569819;948.786523032326;960.0639376607752;962.4225464721111;4602.516735742699;4903.975480122648;4917.795285031319;4926.768307923019;4938.43208031081;4702.623790096784;4882.584980276645;5252.9737351313;5420.4850539198715;5447.4552544746;4200.873973780792;4405.118976204772;4637.930431043537;4793.142912801019;4928.901421971541;2427.235420842429;2809.817361871472;2837.943241135166;2847.757940575033;2864.3125649843887;5151.9484805152215;5241.899201167721;5656.6888821115235;5689.3216501132265;5706.489144776285;4499.572540608637;4768.380110585602;5241.344831896013;5314.565067431772;5537.944620553743;5147.382314122329;5288.101022826128;5318.255444237887;5587.38538760736;5637.379888212376;4594.425696787902;4602.907941841211;5395;5452.781888724407;5468.726563671721;4320.975590689655;4654.976725116353;4777.402824646919;5361.115415956324;5384.407715151408;4779.211430114131;5319.654222475527;5632.464915832989;5675.347335056521;5871.412858714094;3578.516900218437;4017.337139371971;4038.2529232092156;4056.8174432150035;4059.878203653895;5291.417944026231;5380.838574842761;5628.943710562842;5669.93047537654;5674.631148975303;4356.823657612364;4442.800073996613;5377.650452720717;5429.538489228302;5455.809046683506;4684.765761711969;4746.0745154694505;4872.6832755870755;5510.586705997146;5523.944760552504;4307.93538096935;4778.627555786416;5161.976399884901;5389.380221274603;5445.727713614383;4378.08060307339;4513.413256276663;4827.903441931212;5384.838454846361;5491.560675146058;5302.469753024821;5329.09405401075;5345.305110335631;5371.865703357291;5378.569714422759 18:24:24 INFO - PROCESS | 1642 | |5;yui.html;646.189032763881;660.9867802643944;739.3530660671912;770.9807254818629;775.1171573764657;14034.789478157836;14936.879734019965;15033.924830375832;15050.548483545484;15086.698266034673;14816.333265003073;15547.06717596945;15608.609784755386;15978;16102.114383708891;738.752517906502;867.839449701804;868.8175483148547;870.0603348383762;871.3813192633238;831.7130589946489;850.8340873529664;857.8370109679141;873.8558137907238;877.7015814623027;906.1980147569383;911.6444586611194;912.5300470257822;913.0413066280412;914.1726737302719;903.8011637439753;908.609298001859;908.9227415669676;911.4895658431267;912.1790388650235;664.1532046640524;667.8897981832991;673.0140344395484;673.3434900971514;674.8785218660639;14484;15013.24933753308;15208.239588020555;15209.08745475264;15248.39006439741;13739.656508587266;13966.58100256994;14044.789328160086;14072.507462238875;14083.788743168861;11290.548378064868;11301.717457063556;11429;11472.942635286854;12001.399930003465;3571.2500374921296;3578.3737845876867;3578.602152145425;3635.94546081809;3730.7015438764834;88251.55874220587;104597;104902.47548762284;107316;108744.4562777189;10455.52670586239;10466.581336746445;10510.789784204273;10864.619738309122;10876.29304095247;19621;21783.891080544498;22067.11731530721;22093.226737064448;22098.116075357128;6458.38645328693;6676.065350850885;6750.561213521106;7517.774466766005;7672.232776722283;4137.275976704064;4216.430781844411;4228.76741779199;4235.343521754133;4237.766922819274;3497.3529896969144;3573.356795776785;3594.8202589870402;3598.8200589970397;3603.72972027099;663.2107791727828;670.8423520472685;671.5567725301278;672.7376323233942;680.0886811672383;629.2574761781142;644.5842431631571;654.2868273581723;681.3493114076045;685.2154283345542;9108.681196158102;9218.81562368749;9645.807083858284;9647.855282170773;9966.80066398668;601.5548494114412;631.2992578238152;663.2472144116366;665.374547924949;698.9899595085053;4133.118625872233;4258.382534532538;4432;4495.696248088083;4819.996714996032;86481;95002;98366.50816746014;98466;100853.49573252087;88798;89672.55163724271;90582.54708726415;91064.5446772762;93190;68181;69631.95552066724;78461.21538784767;79375.20624793794;79688;9154.725358239264;9202;9231.215346695606;9249.768755781159;9277.860832087388;50546.7472662649;50774.98450031053;52437.7378113107;52540;53625;59322.11016834665;64138.39654008501;68453.3154668447;70605.64697176481;70729.64635176791;10905.345679258953;11059.004689577976;11166.888331116585;11279.718007049889;11501.137414694098;11877;12125.575604853788;12153.45309461094;12186.451609677408;12288.631341059783;10938.800586626463;11201.159913006393;11271.154663400443;11500.25248358854;11507.712307192387;13284.867151328363;13288.667783305495;13343.332833358487;13581;13646;12795.296258705861;12841.936251189078;13029.348532573144;13084.672883177996;13222.272774997467;10233.437160956219;10281.434521101412;10306.33008854422;10493.020493020433;10858.2399232053;9148.771280718036;9810.607575696893;10068.546915388679;10550.138581484029;10959.671209863716 18:24:24 INFO - PROCESS | 1642 | __end_tp_report 18:24:24 INFO - PROCESS | 1642 | __start_cc_report 18:24:24 INFO - PROCESS | 1642 | _x_x_mozilla_cycle_collect,420 18:24:24 INFO - PROCESS | 1642 | __end_cc_report 18:24:24 INFO - PROCESS | 1642 | __startTimestamp1487039064696__endTimestamp 18:24:24 INFO - PROCESS | 1642 | 18:24:24 INFO - PROCESS | 1642 | ------- Summary: start ------- 18:24:24 INFO - PROCESS | 1642 | Number of tests: 6 18:24:24 INFO - PROCESS | 1642 | 18:24:24 INFO - PROCESS | 1642 | [#0] dojo.html Cycles:165 Average:5579.94 Median:5799.54 stddev:770.76 (13.3%) stddev-sans-first:744.25 18:24:24 INFO - PROCESS | 1642 | Values: 2915.8 3325.8 3332.2 3336.3 3398.6 5388.1 5556.6 5558.2 5594.3 5710.6 5408.2 5501.2 5550.3 5594.9 5626.2 5143.7 5144.8 5190.6 5212.5 5256.7 5185.7 5242.1 5246.8 5249.6 5259.1 41.1 5136.6 5142.3 5196.6 5267.4 4664.4 5135.6 5140.9 5143.6 5194.7 5109.3 5143.1 5154.4 5160.7 5164.0 5200.2 5485.8 5508.7 5555.4 5667.7 6022.4 6103.5 6111.6 6123.1 6137.7 5188.1 5232.7 5510.0 5998.5 6216.3 5763.1 5766.2 5768.0 5780.3 5787.4 6044.2 6102.6 6110.1 6113.5 6223.8 5144.9 6042.2 6079.3 6127.2 6145.8 5191.1 6040.3 6155.5 6173.5 6205.6 5455.8 5951.0 6016.7 6174.2 6175.5 6101.7 6123.8 6131.3 6135.3 6139.8 4215.4 4708.5 4870.6 4964.6 4966.0 4346.4 4358.3 4399.4 4423.6 4433.2 5745.3 5794.5 5796.5 5800.6 5859.9 4963.6 6057.2 6063.9 6129.9 6188.8 5631.3 5669.0 5671.7 5683.8 5687.8 4503.4 5412.6 5428.2 5455.2 5473.3 6217.4 6222.8 6239.1 6241.0 6244.1 6018.2 6040.5 6052.6 6083.6 6092.4 6032.2 6129.4 6146.8 6153.1 6171.0 6071.5 6073.6 6090.5 6094.3 6139.5 5969.5 6064.8 6091.2 6094.7 6105.2 5885.1 5909.5 6023.5 6025.5 6050.6 6048.8 6051.8 6060.2 6072.3 6114.9 5777.4 5997.6 6013.4 6015.1 6030.1 5878.6 5941.2 5955.5 5995.7 6036.7 4780.4 5799.5 5823.0 5897.7 5969.9 18:24:24 INFO - PROCESS | 1642 | 18:24:24 INFO - PROCESS | 1642 | [#1] ext.html Cycles:180 Average:64265.84 Median:33821.08 stddev:137520.21 (406.6%) stddev-sans-first:137822.55 18:24:24 INFO - PROCESS | 1642 | Values: 459.2 492.2 492.3 505.8 565.1 31619.8 34483.8 37328.4 39208.2 39269.6 36927.6 40694.0 40724.4 40765.0 40890.2 7829.0 9117.8 9339.9 9355.4 9379.2 7785.4 10836.5 11081.5 11081.7 11294.3 11140.3 11166.6 11199.7 11215.0 11232.7 9729.9 11117.9 11159.1 11189.9 11221.3 9628.6 10990.4 11014.3 11085.8 11122.3 38783.6 39090.6 39186.2 39207.4 39410.4 3502.3 3745.8 3748.2 3763.6 3912.4 28971.0 29961.0 30015.2 30065.5 30297.4 2998.0 3122.1 3153.4 3184.8 3187.1 449036.8 452946.0 454154.0 454200.7 456553.0 57932.7 58091.7 58390.7 58442.0 58477.1 61565.4 61661.0 61803.4 61925.7 61954.7 14678.8 14797.1 14801.9 14825.7 14826.7 11324.5 11401.4 11430.5 11441.5 11485.6 14237.9 16123.0 16132.9 16137.8 16196.9 6687.7 7117.7 7155.7 7189.6 7224.8 768.6 798.8 813.4 828.9 836.1 24270.5 26201.6 28259.0 28552.7 28871.6 982.2 1021.6 1025.4 1041.3 1149.0 11422.0 13205.9 14589.0 14859.3 14885.3 561139.0 562057.2 564921.0 566266.0 566864.0 56003.4 56242.0 56428.0 56506.2 56793.7 1178.6 1277.9 1340.6 1342.3 1351.8 23276.9 30279.5 30446.7 31027.8 32294.5 51284.0 51616.7 51903.2 51924.7 52018.7 518525.0 523608.0 523699.0 523936.0 524194.0 23841.6 24556.4 31310.8 32725.3 37329.3 40692.0 41532.2 41574.8 41796.6 41946.7 32296.5 33547.8 33883.8 34515.8 35193.8 20850.0 27119.0 27285.9 27948.2 28003.0 32826.0 33738.7 33758.3 33979.6 34014.1 23463.4 26444.7 30574.8 30737.5 30747.5 23107.1 23464.4 23788.6 23796.6 23932.3 18:24:24 INFO - PROCESS | 1642 | 18:24:24 INFO - PROCESS | 1642 | [#2] jquery.html Cycles:180 Average:36835.33 Median:24427.89 stddev:111093.21 (454.8%) stddev-sans-first:111371.11 18:24:24 INFO - PROCESS | 1642 | Values: 369.8 385.2 385.4 396.1 416.9 15504.9 18040.8 19082.0 19792.8 19883.0 19857.9 20150.8 20468.8 20722.2 20760.0 4525.3 5672.8 5761.0 5815.1 5824.1 4883.6 5128.4 5846.4 5848.0 5852.1 5521.6 5647.7 5756.2 5795.4 5797.3 5668.1 5691.4 5716.3 5720.9 5725.1 4466.9 4857.9 4991.5 5085.0 5104.2 24391.0 24464.8 24481.8 24522.4 24595.3 2226.4 2588.2 2600.7 2609.2 2612.0 12379.9 14590.1 14709.8 14923.6 15023.0 1736.5 1748.5 1765.4 1771.0 1822.6 83323.6 92124.0 114217.0 114368.0 115095.8 9722.3 10153.9 10179.7 11379.4 11585.7 10989.3 12558.6 12673.1 12673.6 12705.4 7546.6 7699.6 7729.7 7745.4 7783.2 7639.0 7692.6 7698.7 7870.8 7872.0 4001.7 4002.6 4019.7 4025.4 4103.4 1980.7 2022.6 2027.9 2030.1 2043.4 987.0 995.7 996.3 998.2 1038.9 17864.5 18490.7 19320.3 20412.8 20831.4 1020.6 1022.2 1052.5 1055.3 1058.2 10428.3 10888.5 10896.1 10899.6 10936.2 662492.1 663655.0 664942.0 667455.7 680123.0 126888.4 138556.0 146548.0 151393.2 152486.0 834.9 1002.4 1017.5 1031.8 1037.5 17596.6 18355.9 18627.6 18730.4 18784.4 53985.5 54269.5 54808.7 56185.9 56566.0 79523.0 79758.0 80283.2 81241.2 82100.6 7771.7 8035.4 8056.2 8444.9 8466.0 9125.6 11665.9 11883.3 12228.4 12736.2 10075.6 10687.7 10954.2 10973.0 11069.5 8594.9 10073.1 10377.7 10938.9 11108.1 10812.8 11231.3 11380.0 11398.3 11430.0 6684.0 6983.1 7307.6 7440.9 8464.8 9162.4 10797.5 10800.6 10876.1 11085.5 18:24:24 INFO - PROCESS | 1642 | 18:24:24 INFO - PROCESS | 1642 | [#3] mootools.html Cycles:180 Average:5333.25 Median:5156.09 stddev:2949.94 (57.2%) stddev-sans-first:2942.12 18:24:24 INFO - PROCESS | 1642 | Values: 1233.8 1398.8 1402.3 1425.7 1428.5 8050.9 8194.9 8474.6 8522.9 8541.0 8283.3 8496.0 8511.1 8520.0 8520.8 2217.1 2263.3 2267.4 2270.6 2273.1 2232.1 2272.0 2274.1 2275.1 2285.3 2233.5 2240.4 2260.1 2261.5 2267.6 2158.1 2221.1 2244.7 2247.9 2259.6 1901.3 1983.5 2206.8 2255.8 2270.4 8205.3 8409.5 8413.3 8509.0 8534.9 5013.7 5186.8 5198.0 5201.6 5206.0 3053.8 3381.7 3411.0 3413.5 3478.8 1180.1 1180.4 1196.9 1213.7 1230.5 10792.0 10795.7 10853.6 10926.6 10928.0 4063.4 4299.5 4370.8 4374.2 4448.4 8700.0 8704.5 8730.2 8740.2 8769.6 4955.9 5079.0 5084.8 5099.2 5112.2 3646.6 4119.7 4709.2 4740.3 4742.1 3574.0 3608.2 3617.3 3648.9 3661.7 1134.4 1137.5 1144.9 1146.9 1152.9 856.7 876.0 880.1 920.6 927.9 5405.0 5596.9 5636.2 5822.1 5850.4 858.9 904.3 946.8 974.5 1012.7 3676.4 3699.8 3733.9 3774.2 3856.7 8065.9 9561.3 9944.9 9965.4 10198.0 9832.5 9936.2 9955.0 9971.1 9981.9 7640.2 7846.3 7864.1 7876.7 7877.8 4514.8 4744.9 5125.4 5200.8 5362.5 7635.6 8795.5 9215.3 9232.7 9333.0 9179.6 9408.1 9461.7 9477.2 9543.1 7078.4 7108.2 7218.8 7244.5 7265.4 7759.1 7825.7 7871.5 7898.9 7899.6 6316.4 6660.7 6959.2 6982.5 7113.0 6865.9 6878.4 6893.4 6913.3 7007.7 6296.3 6297.5 6332.5 6358.7 6388.4 4589.0 4632.8 4665.2 4686.1 4718.1 4735.1 4795.9 4902.5 4924.5 5066.8 18:24:24 INFO - PROCESS | 1642 | 18:24:24 INFO - PROCESS | 1642 | [#4] prototype.html Cycles:180 Average:4387.46 Median:4766.00 stddev:1283.87 (26.9%) stddev-sans-first:1251.14 18:24:24 INFO - PROCESS | 1642 | Values: 346.9 352.5 361.1 362.8 366.8 4769.4 4813.8 4836.3 5145.4 5338.2 4296.9 4685.9 5136.3 5188.8 5194.3 3490.9 3568.2 4124.2 4151.1 4164.5 3680.9 3994.9 4108.6 4156.6 4167.0 3603.3 4061.2 4067.1 4097.0 4113.0 3861.3 4076.0 4078.6 4096.9 4106.1 3194.9 3424.3 3467.3 3641.5 3653.2 4816.9 5011.7 5026.2 5080.9 5157.3 4736.0 4742.9 4747.0 4752.8 4763.6 4608.4 5536.0 5541.3 5547.7 5578.3 3486.7 3601.5 3797.8 3970.7 4005.3 4444.9 5555.0 5623.4 5694.2 5785.0 3595.5 3752.6 3794.4 3932.8 4060.3 4092.3 4106.8 4122.8 4128.3 4179.8 4526.3 4642.9 4699.6 4706.5 4729.8 5270.4 5479.5 5544.9 5581.5 5592.9 1187.6 1276.6 1352.7 1359.0 1360.2 863.2 947.6 948.8 960.1 962.4 4602.5 4904.0 4917.8 4926.8 4938.4 4702.6 4882.6 5253.0 5420.5 5447.5 4200.9 4405.1 4637.9 4793.1 4928.9 2427.2 2809.8 2837.9 2847.8 2864.3 5151.9 5241.9 5656.7 5689.3 5706.5 4499.6 4768.4 5241.3 5314.6 5537.9 5147.4 5288.1 5318.3 5587.4 5637.4 4594.4 4602.9 5395.0 5452.8 5468.7 4321.0 4655.0 4777.4 5361.1 5384.4 4779.2 5319.7 5632.5 5675.3 5871.4 3578.5 4017.3 4038.3 4056.8 4059.9 5291.4 5380.8 5628.9 5669.9 5674.6 4356.8 4442.8 5377.7 5429.5 5455.8 4684.8 4746.1 4872.7 5510.6 5523.9 4307.9 4778.6 5162.0 5389.4 5445.7 4378.1 4513.4 4827.9 5384.8 5491.6 5302.5 5329.1 5345.3 5371.9 5378.6 18:24:24 INFO - PROCESS | 1642 | 18:24:24 INFO - PROCESS | 1642 | [#5] yui.html Cycles:180 Average:19892.19 Median:4463.85 stddev:28694.40 (642.8%) stddev-sans-first:28738.50 18:24:24 INFO - PROCESS | 1642 | Values: 646.2 661.0 739.4 771.0 775.1 14034.8 14936.9 15033.9 15050.5 15086.7 14816.3 15547.1 15608.6 15978.0 16102.1 738.8 867.8 868.8 870.1 871.4 831.7 850.8 857.8 873.9 877.7 906.2 911.6 912.5 913.0 914.2 903.8 908.6 908.9 911.5 912.2 664.2 667.9 673.0 673.3 674.9 14484.0 15013.2 15208.2 15209.1 15248.4 13739.7 13966.6 14044.8 14072.5 14083.8 11290.5 11301.7 11429.0 11472.9 12001.4 3571.3 3578.4 3578.6 3635.9 3730.7 88251.6 104597.0 104902.5 107316.0 108744.5 10455.5 10466.6 10510.8 10864.6 10876.3 19621.0 21783.9 22067.1 22093.2 22098.1 6458.4 6676.1 6750.6 7517.8 7672.2 4137.3 4216.4 4228.8 4235.3 4237.8 3497.4 3573.4 3594.8 3598.8 3603.7 663.2 670.8 671.6 672.7 680.1 629.3 644.6 654.3 681.3 685.2 9108.7 9218.8 9645.8 9647.9 9966.8 601.6 631.3 663.2 665.4 699.0 4133.1 4258.4 4432.0 4495.7 4820.0 86481.0 95002.0 98366.5 98466.0 100853.5 88798.0 89672.6 90582.5 91064.5 93190.0 68181.0 69632.0 78461.2 79375.2 79688.0 9154.7 9202.0 9231.2 9249.8 9277.9 50546.7 50775.0 52437.7 52540.0 53625.0 59322.1 64138.4 68453.3 70605.6 70729.6 10905.3 11059.0 11166.9 11279.7 11501.1 11877.0 12125.6 12153.5 12186.5 12288.6 10938.8 11201.2 11271.2 11500.3 11507.7 13284.9 13288.7 13343.3 13581.0 13646.0 12795.3 12841.9 13029.3 13084.7 13222.3 10233.4 10281.4 10306.3 10493.0 10858.2 9148.8 9810.6 10068.5 10550.1 10959.7 18:24:24 INFO - PROCESS | 1642 | -------- Summary: end -------- 18:24:24 INFO - PROCESS | 1642 | 18:24:25 INFO - TEST-INFO | 1642: exit 0 18:24:25 INFO - TEST-OK | dromaeo_css | took 1116340ms 18:24:25 INFO - TEST-START | kraken 18:24:25 INFO - operating with platform_type : mac_ 18:24:25 INFO - Initialising browser for kraken test... 18:24:25 INFO - TEST-INFO | started process 1651 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/tmpTopJul/profile http://localhost:49240/getInfo.html) 18:24:28 INFO - PROCESS | 1651 | JavaScript error: chrome://webcompat/content/lib/ua_overrider.jsm, line 109: NS_ERROR_INSUFFICIENT_DOMAIN_LEVELS: Component returned failure code: 0x804b0050 (NS_ERROR_INSUFFICIENT_DOMAIN_LEVELS) [nsIEffectiveTLDService.getBaseDomain] 18:24:28 INFO - PROCESS | 1651 | 2017-02-13 18:24:28.242 plugin-container[1652:10068] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4343, name = 'com.apple.tsm.portname' 18:24:28 INFO - PROCESS | 1651 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:24:28 INFO - PROCESS | 1651 | 2017-02-13 18:24:28.248 plugin-container[1652:10068] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x910b, name = 'com.apple.CFPasteboardClient' 18:24:28 INFO - PROCESS | 1651 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:24:28 INFO - PROCESS | 1651 | 2017-02-13 18:24:28.248 plugin-container[1652:10068] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 18:24:28 INFO - PROCESS | 1651 | __metrics Screen width/height:1600/1200 18:24:28 INFO - PROCESS | 1651 | colorDepth:24 18:24:28 INFO - PROCESS | 1651 | Browser inner width/height: 1024/689 18:24:28 INFO - PROCESS | 1651 | __metrics 18:24:28 ERROR - PROCESS | 1651 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3283: TypeError: this.tabs is undefined 18:24:29 INFO - TEST-INFO | 1651: exit 0 18:24:29 INFO - Browser initialized. 18:24:29 INFO - Running cycle 1/1 for kraken test... 18:24:29 INFO - TEST-INFO | started process 1653 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/tmpTopJul/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:24:31 INFO - PROCESS | 1653 | 2017-02-13 18:24:31.620 plugin-container[1654:10186] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x883f, name = 'com.apple.tsm.portname' 18:24:31 INFO - PROCESS | 1653 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:24:31 INFO - PROCESS | 1653 | 2017-02-13 18:24:31.621 plugin-container[1654:10186] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8e13, name = 'com.apple.CFPasteboardClient' 18:24:31 INFO - PROCESS | 1653 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:24:31 INFO - PROCESS | 1653 | 2017-02-13 18:24:31.621 plugin-container[1654:10186] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 18:24:41 INFO - PROCESS | 1653 | RSS: Main: 232878080 18:24:41 INFO - PROCESS | 1653 | 18:24:44 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49240/tests/kraken/driver.html?testName=audio-beat-detection) 18:24:45 INFO - PROCESS | 1653 | RSS: Main: 247390208 18:24:45 INFO - PROCESS | 1653 | 18:24:48 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49240/tests/kraken/driver.html?testName=audio-dft) 18:24:48 INFO - PROCESS | 1653 | RSS: Main: 255291392 18:24:48 INFO - PROCESS | 1653 | 18:24:52 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49240/tests/kraken/driver.html?testName=audio-fft) 18:24:52 INFO - PROCESS | 1653 | RSS: Main: 258277376 18:24:52 INFO - PROCESS | 1653 | 18:24:54 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49240/tests/kraken/driver.html?testName=audio-oscillator) 18:24:55 INFO - PROCESS | 1653 | RSS: Main: 260382720 18:24:55 INFO - PROCESS | 1653 | 18:24:57 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49240/tests/kraken/driver.html?testName=imaging-gaussian-blur) 18:24:58 INFO - PROCESS | 1653 | RSS: Main: 260915200 18:24:58 INFO - PROCESS | 1653 | 18:25:01 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49240/tests/kraken/driver.html?testName=imaging-darkroom) 18:25:01 INFO - PROCESS | 1653 | RSS: Main: 266424320 18:25:01 INFO - PROCESS | 1653 | 18:25:04 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49240/tests/kraken/driver.html?testName=imaging-desaturate) 18:25:05 INFO - PROCESS | 1653 | RSS: Main: 265875456 18:25:05 INFO - PROCESS | 1653 | 18:25:08 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49240/tests/kraken/driver.html?testName=json-parse-financial) 18:25:08 INFO - PROCESS | 1653 | RSS: Main: 265773056 18:25:08 INFO - PROCESS | 1653 | 18:25:10 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49240/tests/kraken/driver.html?testName=json-stringify-tinderbox) 18:25:10 INFO - PROCESS | 1653 | RSS: Main: 265531392 18:25:10 INFO - PROCESS | 1653 | 18:25:12 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-aes) 18:25:13 INFO - PROCESS | 1653 | RSS: Main: 265555968 18:25:13 INFO - PROCESS | 1653 | 18:25:15 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-ccm) 18:25:16 INFO - PROCESS | 1653 | RSS: Main: 265551872 18:25:16 INFO - PROCESS | 1653 | 18:25:19 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 18:25:20 INFO - PROCESS | 1653 | RSS: Main: 265887744 18:25:20 INFO - PROCESS | 1653 | 18:25:23 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 18:25:24 INFO - PROCESS | 1653 | RSS: Main: 265650176 18:25:24 INFO - PROCESS | 1653 | 18:25:25 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49240/tests/kraken/driver.html?testName=ai-astar) 18:25:26 INFO - PROCESS | 1653 | RSS: Main: 267591680 18:25:26 INFO - PROCESS | 1653 | 18:25:26 INFO - PROCESS | 1653 | __start_tp_report 18:25:26 INFO - PROCESS | 1653 | _x_x_mozilla_page_load 18:25:26 INFO - PROCESS | 1653 | _x_x_mozilla_page_load_details 18:25:26 INFO - PROCESS | 1653 | |i|pagename|runs| 18:25:26 INFO - PROCESS | 1653 | |0;ai-astar;107;119;121;120;119;118;130;170;163;119 18:25:26 INFO - PROCESS | 1653 | |1;audio-beat-detection;147;113;116;156;117;117;118;115;153;147 18:25:26 INFO - PROCESS | 1653 | |2;audio-dft;205;216;206;256;275;196;273;275;197;199 18:25:26 INFO - PROCESS | 1653 | |3;audio-fft;82;58;60;87;60;60;63;73;90;82 18:25:26 INFO - PROCESS | 1653 | |4;audio-oscillator;89;72;83;71;73;74;84;71;71;81 18:25:26 INFO - PROCESS | 1653 | |5;imaging-gaussian-blur;90;91;89;127;125;90;111;92;91;93 18:25:26 INFO - PROCESS | 1653 | |6;imaging-darkroom;99;100;99;99;108;100;100;100;100;98 18:25:26 INFO - PROCESS | 1653 | |7;imaging-desaturate;95;97;94;93;94;90;94;96;95;84 18:25:26 INFO - PROCESS | 1653 | |8;json-parse-financial;64;65;64;65;65;64;66;66;65;65 18:25:26 INFO - PROCESS | 1653 | |9;json-stringify-tinderbox;98;69;79;86;79;68;71;71;73;73 18:25:26 INFO - PROCESS | 1653 | |10;stanford-crypto-aes;91;98;106;104;92;96;108;105;109;94 18:25:26 INFO - PROCESS | 1653 | |11;stanford-crypto-ccm;155;161;149;160;146;163;156;151;190;146 18:25:26 INFO - PROCESS | 1653 | |12;stanford-crypto-pbkdf2;181;176;171;215;222;201;180;179;179;178 18:25:26 INFO - PROCESS | 1653 | |13;stanford-crypto-sha256-iterative;68;70;68;71;70;71;66;69;76;85 18:25:26 INFO - PROCESS | 1653 | __end_tp_report 18:25:26 INFO - PROCESS | 1653 | __start_cc_report 18:25:26 INFO - PROCESS | 1653 | _x_x_mozilla_cycle_collect,1008 18:25:26 INFO - PROCESS | 1653 | __end_cc_report 18:25:26 INFO - PROCESS | 1653 | __startTimestamp1487039126062__endTimestamp 18:25:26 INFO - PROCESS | 1653 | 18:25:26 INFO - PROCESS | 1653 | ------- Summary: start ------- 18:25:26 INFO - PROCESS | 1653 | Number of tests: 14 18:25:26 INFO - PROCESS | 1653 | 18:25:26 INFO - PROCESS | 1653 | [#0] ai-astar Cycles:10 Average:128.60 Median:120.50 stddev:20.78 (17.2%) stddev-sans-first:20.52 18:25:26 INFO - PROCESS | 1653 | Values: 107.0 119.0 121.0 120.0 119.0 118.0 130.0 170.0 163.0 119.0 18:25:26 INFO - PROCESS | 1653 | 18:25:26 INFO - PROCESS | 1653 | [#1] audio-beat-detection Cycles:10 Average:129.90 Median:132.50 stddev:18.18 (13.7%) stddev-sans-first:18.20 18:25:26 INFO - PROCESS | 1653 | Values: 147.0 113.0 116.0 156.0 117.0 117.0 118.0 115.0 153.0 147.0 18:25:26 INFO - PROCESS | 1653 | 18:25:26 INFO - PROCESS | 1653 | [#2] audio-dft Cycles:10 Average:229.80 Median:236.00 stddev:35.24 (14.9%) stddev-sans-first:36.22 18:25:26 INFO - PROCESS | 1653 | Values: 205.0 216.0 206.0 256.0 275.0 196.0 273.0 275.0 197.0 199.0 18:25:26 INFO - PROCESS | 1653 | 18:25:26 INFO - PROCESS | 1653 | [#3] audio-fft Cycles:10 Average:71.50 Median:77.50 stddev:12.72 (16.4%) stddev-sans-first:12.91 18:25:26 INFO - PROCESS | 1653 | Values: 82.0 58.0 60.0 87.0 60.0 60.0 63.0 73.0 90.0 82.0 18:25:26 INFO - PROCESS | 1653 | 18:25:26 INFO - PROCESS | 1653 | [#4] audio-oscillator Cycles:10 Average:76.90 Median:77.50 stddev:6.69 (8.6%) stddev-sans-first:5.48 18:25:26 INFO - PROCESS | 1653 | Values: 89.0 72.0 83.0 71.0 73.0 74.0 84.0 71.0 71.0 81.0 18:25:26 INFO - PROCESS | 1653 | 18:25:26 INFO - PROCESS | 1653 | [#5] imaging-gaussian-blur Cycles:10 Average:99.90 Median:90.50 stddev:15.17 (16.8%) stddev-sans-first:15.66 18:25:26 INFO - PROCESS | 1653 | Values: 90.0 91.0 89.0 127.0 125.0 90.0 111.0 92.0 91.0 93.0 18:25:26 INFO - PROCESS | 1653 | 18:25:26 INFO - PROCESS | 1653 | [#6] imaging-darkroom Cycles:10 Average:100.30 Median:103.00 stddev:2.79 (2.7%) stddev-sans-first:2.92 18:25:26 INFO - PROCESS | 1653 | Values: 99.0 100.0 99.0 99.0 108.0 100.0 100.0 100.0 100.0 98.0 18:25:26 INFO - PROCESS | 1653 | 18:25:26 INFO - PROCESS | 1653 | [#7] imaging-desaturate Cycles:10 Average:93.20 Median:94.50 stddev:3.74 (4.0%) stddev-sans-first:3.91 18:25:26 INFO - PROCESS | 1653 | Values: 95.0 97.0 94.0 93.0 94.0 90.0 94.0 96.0 95.0 84.0 18:25:26 INFO - PROCESS | 1653 | 18:25:26 INFO - PROCESS | 1653 | [#8] json-parse-financial Cycles:10 Average:64.90 Median:65.00 stddev:0.74 (1.1%) stddev-sans-first:0.71 18:25:26 INFO - PROCESS | 1653 | Values: 64.0 65.0 64.0 65.0 65.0 64.0 66.0 66.0 65.0 65.0 18:25:26 INFO - PROCESS | 1653 | 18:25:26 INFO - PROCESS | 1653 | [#9] json-stringify-tinderbox Cycles:10 Average:76.70 Median:76.00 stddev:9.30 (12.2%) stddev-sans-first:5.85 18:25:26 INFO - PROCESS | 1653 | Values: 98.0 69.0 79.0 86.0 79.0 68.0 71.0 71.0 73.0 73.0 18:25:26 INFO - PROCESS | 1653 | 18:25:26 INFO - PROCESS | 1653 | [#10] stanford-crypto-aes Cycles:10 Average:100.30 Median:91.50 stddev:6.85 (7.5%) stddev-sans-first:6.38 18:25:26 INFO - PROCESS | 1653 | Values: 91.0 98.0 106.0 104.0 92.0 96.0 108.0 105.0 109.0 94.0 18:25:26 INFO - PROCESS | 1653 | 18:25:26 INFO - PROCESS | 1653 | [#11] stanford-crypto-ccm Cycles:10 Average:157.70 Median:158.00 stddev:12.88 (8.1%) stddev-sans-first:13.62 18:25:26 INFO - PROCESS | 1653 | Values: 155.0 161.0 149.0 160.0 146.0 163.0 156.0 151.0 190.0 146.0 18:25:26 INFO - PROCESS | 1653 | 18:25:26 INFO - PROCESS | 1653 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:188.20 Median:180.50 stddev:17.83 (9.9%) stddev-sans-first:18.72 18:25:26 INFO - PROCESS | 1653 | Values: 181.0 176.0 171.0 215.0 222.0 201.0 180.0 179.0 179.0 178.0 18:25:26 INFO - PROCESS | 1653 | 18:25:26 INFO - PROCESS | 1653 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:71.40 Median:70.50 stddev:5.46 (7.7%) stddev-sans-first:5.65 18:25:26 INFO - PROCESS | 1653 | Values: 68.0 70.0 68.0 71.0 70.0 71.0 66.0 69.0 76.0 85.0 18:25:26 INFO - PROCESS | 1653 | -------- Summary: end -------- 18:25:26 INFO - PROCESS | 1653 | 18:25:26 INFO - TEST-INFO | 1653: exit 0 18:25:26 INFO - TEST-OK | kraken | took 61559ms 18:25:26 INFO - SUITE-END | took 1177s 18:25:27 INFO - Completed test suite (00:19:38) 18:25:27 INFO - javascript benchmark 18:25:27 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7061.664425527737, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2915.810472319299, 3325.7505687073476, 3332.1503016730762, 3336.266021475276, 3398.6091599466054, 5388.110961691326, 5556.611037227391, 5558.166275058738, 5594.272744543214, 5710.600257981943, 5408.2428460015535, 5501.174823776436, 5550.306211723535, 5594.86012849679, 5626.240457538231, 5143.665661731994, 5144.794208231666, 5190.558802501791, 5212.452692467293, 5256.684598924076, 5185.714785686786, 5242.056429842646, 5246.7901283948595, 5249.553787928031, 5259.079661059319, 41.12926396328671, 5136.614753893473, 5142.305788718531, 5196.558292545138, 5267.420583735786, 4664.416947881515, 5135.589152867761, 5140.897182056376, 5143.588512918957, 5194.68831870089, 5109.284700141983, 5143.099957507458, 5154.433012368637, 5160.741962901878, 5163.974180129113, 5200.24596433515, 5485.835424937259, 5508.6970216638065, 5555.3611334696625, 5667.716614169275, 6022.427869352405, 6103.519504250422, 6111.572189946705, 6123.099607468082, 6137.6624285664275, 5188.11801993662, 5232.73836308183, 5510.0, 5998.490128339053, 6216.285127210338, 5763.106718458645, 5766.163906233573, 5768.0, 5780.2774653167935, 5787.363390027093, 6044.24446944132, 6102.580142287592, 6110.103144028831, 6113.541484388688, 6223.813285601439, 5144.8713782155, 6042.154098426224, 6079.331273559905, 6127.220070483952, 6145.832291864532, 5191.117510023305, 6040.335563088057, 6155.507559395238, 6173.536984726164, 6205.627662340274, 5455.8090466834265, 5951.0, 6016.69916504173, 6174.228221472316, 6175.536834737323, 6101.731934812904, 6123.816285511352, 6131.294901086343, 6135.32511423743, 6139.785107521216, 4215.430916826189, 4708.4820669726305, 4870.6103511718975, 4964.60283177345, 4965.981973695384, 4346.413234213404, 4358.302671572596, 4399.38408622793, 4423.646108311392, 4433.1577000369825, 5745.253117094834, 5794.478496935296, 5796.478316951495, 5800.564957628195, 5859.941400586024, 4963.610189147044, 6057.182280392179, 6063.9090413643835, 6129.908051379233, 6188.752449901954, 5631.324241091095, 5669.0, 5671.6597004179885, 5683.77264909399, 5687.800926967537, 4503.437070366204, 5412.648177868426, 5428.240046393508, 5455.18172274162, 5473.28847249855, 6217.378262173836, 6222.751089956532, 6239.064140378889, 6241.001439769615, 6244.12582238487, 6018.157457955802, 6040.456358927718, 6052.606580572248, 6083.6349819011, 6092.360302168208, 6032.155498230163, 6129.3564175762685, 6146.754129834757, 6153.13856060143, 6171.0, 6071.483923866521, 6073.635581865102, 6090.451859332681, 6094.268687757498, 6139.539534534839, 5969.522438204846, 6064.757409703562, 6091.238595175603, 6094.725737341919, 6105.206323178136, 5885.058390657474, 5909.497692695997, 6023.548233882389, 6025.517958563392, 6050.6369617823075, 6048.84877878039, 6051.818445446644, 6060.2424696912885, 6072.30168530607, 6114.938850611615, 5777.364489906023, 5997.610155340063, 6013.398660133952, 6015.0676645120075, 6030.095485677183, 5878.647281163144, 5941.168236446814, 5955.5235581154275, 5995.730192141451, 6036.69816509164, 4780.378550788375, 5799.536037117105, 5823.039198532195, 5897.70511474416, 5969.91045134332], "value": 5534.714730546933, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [459.2330807551389, 492.1805194351405, 492.254234834226, 505.78106762701884, 565.1466285908277, 31619.841900790492, 34483.827580862126, 37328.44007339889, 39208.21583568334, 39269.60730392699, 36927.63072369282, 40694.0, 40724.389134162935, 40765.0, 40890.18219635613, 7828.960855195744, 9117.817643647122, 9339.906600933971, 9355.438673679568, 9379.24966002718, 7785.377169826429, 10836.458177091154, 11081.501332440059, 11081.667549973474, 11294.322340659586, 11140.33158010522, 11166.553337866517, 11199.66401007971, 11215.0, 11232.719182020433, 9729.902700973042, 11117.888821111766, 11159.107271418266, 11189.944050279737, 11221.326720396803, 9628.566714497865, 10990.395528245943, 11014.284071535405, 11085.778284434347, 11122.332660040423, 38783.61216387828, 39090.60909390927, 39186.21627567433, 39207.41188882169, 39410.408843867364, 3502.2995400919663, 3745.831437585288, 3748.1566647504364, 3763.5860055393887, 3912.39357899526, 28971.0, 29961.0, 30015.249618759488, 30065.549016764766, 30297.394052118834, 2998.0, 3122.1414111119398, 3153.4323821712082, 3184.8248346340943, 3187.075748033091, 449036.75481622707, 452946.0, 454154.0, 454200.7289963562, 456553.0, 57932.710336448894, 58091.70954145202, 58390.7080464595, 58442.0, 58477.12284315738, 61565.38434615686, 61661.0, 61803.38196617976, 61925.690371547855, 61954.69022654858, 14678.779818302733, 14797.112173269637, 14801.925990369979, 14825.70348593022, 14826.703465930621, 11324.490397931948, 11401.429928503541, 11430.485628146753, 11441.542338306375, 11485.598004069985, 14237.85762142386, 16122.952008119435, 16132.919335403249, 16137.757933631005, 16196.919015404848, 6687.732490700318, 7117.7152913882865, 7155.7495487658725, 7189.568625882359, 7224.819379515554, 768.5849641193804, 798.8122791144076, 813.4265342933218, 828.9141224995276, 836.0719601242616, 24270.51458970846, 26201.606975894996, 28259.0109346176, 28552.71447285542, 28871.5669264957, 982.2191357870367, 1021.5913634546241, 1025.3898930136556, 1041.265907535186, 1149.008284588273, 11421.972022517846, 13205.867941320656, 14588.978771485894, 14859.2570371481, 14885.255737213096, 561139.0, 562057.1897140488, 564921.0, 566266.0, 566864.0, 56003.439965600635, 56242.0, 56428.0, 56506.15240771392, 56793.71603142041, 1178.6169494914186, 1277.9424925878359, 1340.550915443318, 1342.2550484481208, 1351.797230415426, 23276.88361558249, 30279.545806812475, 30446.695533044385, 31027.844860775553, 32294.515582266755, 51284.0, 51616.74191629018, 51903.2214516775, 51924.74037629788, 52018.73990630022, 518525.0, 523608.0, 523699.0, 523936.0, 524194.0, 23841.64237536473, 24556.386090347885, 31310.843445782626, 32725.34549309048, 37329.25341493101, 40692.0, 41532.1693566133, 41574.792126039174, 41796.58203418049, 41946.741597752116, 32296.515552267203, 33547.83226083854, 33883.83058084694, 34515.82742086273, 35193.82403088071, 20850.0, 27119.0, 27285.90856365723, 27948.16155515338, 28003.0, 32826.0, 33738.662613373555, 33758.3248335027, 33979.64081436715, 34014.149646259044, 23463.41341466477, 26444.735552644226, 30574.777008920282, 30737.53893691641, 30747.53878691777, 23107.09227584699, 23464.413389665395, 23788.643170352112, 23796.643050354603, 23932.28203153908], "value": 18952.13988513723, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [369.7818287210547, 385.2314632308544, 385.3814627522826, 396.1127075351214, 416.88118886117445, 15504.92247538762, 18040.819591804076, 19082.0, 19792.802071979277, 19883.00584970751, 19857.900710496426, 20150.798492015114, 20468.795312046874, 20722.171113155495, 20760.0, 4525.343825145352, 5672.773089076442, 5760.991826430379, 5815.069588865782, 5824.068149096145, 4883.584895283905, 5128.358955130609, 5846.4445877641765, 5847.970760146193, 5852.12218167274, 5521.641093328941, 5647.717614119299, 5756.165356023374, 5795.449432303924, 5797.275340582427, 5668.121441176603, 5691.402402747714, 5716.28546431696, 5720.942790572082, 5725.08398656213, 4466.905608125998, 4857.854264372039, 4991.5258050485145, 5085.0, 5104.2088476286235, 24391.0, 24464.776761162047, 24481.755182448127, 24522.386940326454, 24595.262142135765, 2226.3543572363997, 2588.158848374267, 2600.739926007403, 2609.178108895692, 2611.973880261192, 12379.938100309531, 14590.124592524482, 14709.779353309708, 14923.552293431214, 15023.0, 1736.513776142682, 1748.510417083206, 1765.4438851761781, 1770.9557261068448, 1822.6445843060608, 83323.5833820833, 92124.02387689558, 114217.0, 114368.0, 115095.84904150935, 9722.2708296878, 10153.949230253802, 10179.7455063624, 11379.43102844871, 11585.652430426931, 10989.340639561651, 12558.623241302776, 12673.112882098349, 12673.556425525247, 12705.428255728515, 7546.622668866645, 7699.576523291273, 7729.729459468892, 7745.380369570421, 7783.182765809508, 7638.961805190938, 7692.576908269987, 7698.692052317844, 7870.763877083697, 7871.960640196762, 4001.6598589119667, 4002.579729128458, 4019.658329042064, 4025.4163146343617, 4103.4460347852655, 1980.6731889238401, 2022.5954809037958, 2027.9391618251475, 2030.1371916935243, 2043.3767700851085, 986.9703908882746, 995.7012896131132, 996.2577879479834, 998.2164001258977, 1038.9428581427942, 17864.46406607804, 18490.70565060433, 19320.341375578104, 20412.795872041388, 20831.37505874826, 1020.5560581147224, 1022.1873610479687, 1052.5105825791045, 1055.3351388625117, 1058.2380336739243, 10428.270021098602, 10888.510017049251, 10896.073833724222, 10899.618513351994, 10936.179786516042, 662492.1258661543, 663655.0, 664942.0, 667455.662721693, 680123.0, 126888.36555817162, 138556.0, 146548.0, 151393.24303378412, 152486.0, 834.8956380452444, 1002.4085789384299, 1017.4709151241314, 1031.8297480915564, 1037.5331101004426, 17596.560085997953, 18355.908220458812, 18627.627447450704, 18730.438086857415, 18784.436466906016, 53985.46014539804, 54269.45730542802, 54808.72595636996, 56185.876282474936, 56566.0, 79523.0, 79758.0, 80283.1971680299, 81241.18758812573, 82100.58949705213, 7771.650275737492, 8035.357171426389, 8056.154103819014, 8444.915550844413, 8466.0, 9125.634974600942, 11665.883341166818, 11883.346415947206, 12228.432029273952, 12736.23582585048, 10075.596976120873, 10687.732806679895, 10954.178436617445, 10973.0, 11069.505616741637, 8594.871076933727, 10073.093421592092, 10377.740556486147, 10938.94530527374, 11108.111351092055, 10812.783744325227, 11231.326120432801, 11380.03269722083, 11398.316101033965, 11430.028447582048, 6684.0, 6983.127109111361, 7307.561546307238, 7440.851182976418, 8464.772607971687, 9162.434518334878, 10797.488351630935, 10800.595922530336, 10876.129909607054, 11085.50115244832], "value": 9413.039560689753, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1233.759416913702, 1398.79717440971, 1402.3128666953196, 1425.7453440971942, 1428.4857451317523, 8050.8792368114455, 8194.877076843852, 8474.576271186446, 8522.914770852298, 8541.017782954954, 8283.25450709436, 8496.0, 8511.148885111486, 8520.0, 8520.78698032548, 2217.057750456058, 2263.3436303472054, 2267.3991392280977, 2270.6139956207485, 2273.1248469339257, 2232.0513781642817, 2271.9886400567975, 2274.124462082094, 2275.1468199425217, 2285.2915596165158, 2233.508628101815, 2240.350298413458, 2260.0959616153586, 2261.4911644879935, 2267.648514480258, 2158.125958986597, 2221.1004543160047, 2244.6857439958417, 2247.921322753712, 2259.604569200392, 1901.3250296144945, 1983.4644645945514, 2206.823454123666, 2255.819534437241, 2270.364297996547, 8205.343572514184, 8409.495430274143, 8413.326933845277, 8509.021462531764, 8534.914650853474, 5013.724245166514, 5186.766595503212, 5198.012377648272, 5201.609879259033, 5206.0, 3053.8090144843527, 3381.7463690223085, 3411.0, 3413.4879768034743, 3478.843452044664, 1180.0913296761446, 1180.415694231361, 1196.8803119687961, 1213.6783752305646, 1230.4585982167816, 10792.0, 10795.676129716121, 10853.620123295726, 10926.617568385147, 10928.0, 4063.39049142631, 4299.548547402478, 4370.847020354272, 4374.168907907551, 4448.377227188196, 8699.999500057447, 8704.477731336014, 8730.214280714767, 8740.169683880022, 8769.605367758466, 4955.909699866023, 5079.009593129304, 5084.8220312288895, 5099.235114732745, 5112.182050871917, 3646.580643226063, 4119.670426365884, 4709.246520556763, 4740.312654665055, 4742.099001189832, 3574.0, 3608.1701208722143, 3617.276544691072, 3648.908777280589, 3661.6931333259877, 1134.3817619397448, 1137.4824454873012, 1144.9313041217388, 1146.9197156199152, 1152.8962393384468, 856.6830272799104, 875.991240087591, 880.097899652861, 920.6133423961952, 927.8608208768738, 5404.972975135099, 5596.916046259309, 5636.239107720406, 5822.097574875901, 5850.3564607893095, 858.914108589136, 904.3217586809892, 946.7869729310987, 974.5176137811828, 1012.7468132966758, 3676.356637588411, 3699.8345521161286, 3733.8879833605033, 3774.245150969762, 3856.652901238902, 8065.879011814827, 9561.330706850593, 9944.900550994398, 9965.35225210359, 10198.0, 9832.459214743114, 9936.205103591696, 9955.0, 9971.102600765966, 9981.900180998242, 7640.159582445926, 7846.3330616897065, 7864.056313242448, 7876.684932602746, 7877.7636670899965, 4514.751688657024, 4744.881377965578, 5125.412211451924, 5200.817971370984, 5362.51737343641, 7635.5800430974605, 8795.47227166372, 9215.308851836005, 9232.723018309462, 9333.0, 9179.586918588813, 9408.106229908148, 9461.668841590512, 9477.241820654193, 9543.093406126407, 7078.398336141486, 7108.218096009331, 7218.783436496913, 7244.529105608119, 7265.382442492447, 7759.146493885781, 7825.726099586487, 7871.527708337518, 7898.881516777368, 7899.565523896241, 6316.399942005502, 6660.700268487833, 6959.1996920352785, 6982.476314276551, 7113.0, 6865.862682746218, 6878.449724021966, 6893.379595836399, 6913.308669133046, 7007.6846541904715, 6296.275928268308, 6297.464715499051, 6332.52506062038, 6358.713857876314, 6388.361163883575, 4588.977055114837, 4632.76836158184, 4665.2302370108555, 4686.062787442594, 4718.103560323665, 4735.076660051257, 4795.908759793324, 4902.485239049991, 4924.532169443897, 5066.797328106834], "value": 4316.823206068548, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [346.8647227581244, 352.4748125293313, 361.0792479178096, 362.7859562857913, 366.7561071887195, 4769.403824521931, 4813.796550862285, 4836.258946779157, 5145.434002259748, 5338.172583249598, 4296.914061718771, 4685.85942421727, 5136.280920671109, 5188.792448302072, 5194.298769666094, 3490.933062981623, 3568.161482051722, 4124.216398884202, 4151.149014452045, 4164.500259968793, 3680.871169509068, 3994.920101597966, 4108.5686002969705, 4156.5635608261155, 4167.020750123734, 3603.3155299302057, 4061.24866899623, 4067.068142632241, 4097.037196258877, 4112.97943510282, 3861.3242682530727, 4076.0, 4078.5921407858978, 4096.897577560585, 4106.07613287008, 3194.9009460822963, 3424.3151369725906, 3467.2545402738533, 3641.526601541809, 3653.2328211075705, 4816.879578010543, 5011.747063234191, 5026.246063090529, 5080.898382032338, 5157.277981082651, 4735.976320118378, 4742.905141897178, 4746.958807712682, 4752.833650822205, 4763.618910487153, 4608.354830323758, 5536.0, 5541.251930989305, 5547.72261386929, 5578.326502049369, 3486.7099173305787, 3601.4777857210556, 3797.810109494542, 3970.6896724080984, 4005.2590270799706, 4444.911101777946, 5554.972225138929, 5623.437656234385, 5694.202811606378, 5784.971075144597, 3595.460680897886, 3752.605976372496, 3794.392897136445, 3932.8230229639735, 4060.2894493463527, 4092.283850326181, 4106.815193316308, 4122.7732474713575, 4128.256913755493, 4179.770112643773, 4526.275795872644, 4642.883927901829, 4699.553542413465, 4706.505816889246, 4729.763511824395, 5270.420253772082, 5479.53423958968, 5544.891021795657, 5581.492996890816, 5592.888142237132, 1187.5784096645739, 1276.6233960981533, 1352.702405470795, 1358.9932050339685, 1360.1501957338012, 863.1614660772515, 947.6020071569819, 948.786523032326, 960.0639376607752, 962.4225464721111, 4602.516735742699, 4903.975480122648, 4917.795285031319, 4926.768307923019, 4938.43208031081, 4702.623790096784, 4882.584980276645, 5252.9737351313, 5420.4850539198715, 5447.4552544746, 4200.873973780792, 4405.118976204772, 4637.930431043537, 4793.142912801019, 4928.901421971541, 2427.235420842429, 2809.817361871472, 2837.943241135166, 2847.757940575033, 2864.3125649843887, 5151.9484805152215, 5241.899201167721, 5656.6888821115235, 5689.3216501132265, 5706.489144776285, 4499.572540608637, 4768.380110585602, 5241.344831896013, 5314.565067431772, 5537.944620553743, 5147.382314122329, 5288.101022826128, 5318.255444237887, 5587.38538760736, 5637.379888212376, 4594.425696787902, 4602.907941841211, 5395.0, 5452.781888724407, 5468.726563671721, 4320.975590689655, 4654.976725116353, 4777.402824646919, 5361.115415956324, 5384.407715151408, 4779.211430114131, 5319.654222475527, 5632.464915832989, 5675.347335056521, 5871.412858714094, 3578.516900218437, 4017.337139371971, 4038.2529232092156, 4056.8174432150035, 4059.878203653895, 5291.417944026231, 5380.838574842761, 5628.943710562842, 5669.93047537654, 5674.631148975303, 4356.823657612364, 4442.800073996613, 5377.650452720717, 5429.538489228302, 5455.809046683506, 4684.765761711969, 4746.0745154694505, 4872.6832755870755, 5510.586705997146, 5523.944760552504, 4307.93538096935, 4778.627555786416, 5161.976399884901, 5389.380221274603, 5445.727713614383, 4378.08060307339, 4513.413256276663, 4827.903441931212, 5384.838454846361, 5491.560675146058, 5302.469753024821, 5329.09405401075, 5345.305110335631, 5371.865703357291, 5378.569714422759], "value": 4000.1150442667195, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [646.189032763881, 660.9867802643944, 739.3530660671912, 770.9807254818629, 775.1171573764657, 14034.789478157836, 14936.879734019965, 15033.924830375832, 15050.548483545484, 15086.698266034673, 14816.333265003073, 15547.06717596945, 15608.609784755386, 15978.0, 16102.114383708891, 738.752517906502, 867.839449701804, 868.8175483148547, 870.0603348383762, 871.3813192633238, 831.7130589946489, 850.8340873529664, 857.8370109679141, 873.8558137907238, 877.7015814623027, 906.1980147569383, 911.6444586611194, 912.5300470257822, 913.0413066280412, 914.1726737302719, 903.8011637439753, 908.609298001859, 908.9227415669676, 911.4895658431267, 912.1790388650235, 664.1532046640524, 667.8897981832991, 673.0140344395484, 673.3434900971514, 674.8785218660639, 14484.0, 15013.24933753308, 15208.239588020555, 15209.08745475264, 15248.39006439741, 13739.656508587266, 13966.58100256994, 14044.789328160086, 14072.507462238875, 14083.788743168861, 11290.548378064868, 11301.717457063556, 11429.0, 11472.942635286854, 12001.399930003465, 3571.2500374921296, 3578.3737845876867, 3578.602152145425, 3635.94546081809, 3730.7015438764834, 88251.55874220587, 104597.0, 104902.47548762284, 107316.0, 108744.4562777189, 10455.52670586239, 10466.581336746445, 10510.789784204273, 10864.619738309122, 10876.29304095247, 19621.0, 21783.891080544498, 22067.11731530721, 22093.226737064448, 22098.116075357128, 6458.38645328693, 6676.065350850885, 6750.561213521106, 7517.774466766005, 7672.232776722283, 4137.275976704064, 4216.430781844411, 4228.76741779199, 4235.343521754133, 4237.766922819274, 3497.3529896969144, 3573.356795776785, 3594.8202589870402, 3598.8200589970397, 3603.72972027099, 663.2107791727828, 670.8423520472685, 671.5567725301278, 672.7376323233942, 680.0886811672383, 629.2574761781142, 644.5842431631571, 654.2868273581723, 681.3493114076045, 685.2154283345542, 9108.681196158102, 9218.81562368749, 9645.807083858284, 9647.855282170773, 9966.80066398668, 601.5548494114412, 631.2992578238152, 663.2472144116366, 665.374547924949, 698.9899595085053, 4133.118625872233, 4258.382534532538, 4432.0, 4495.696248088083, 4819.996714996032, 86481.0, 95002.0, 98366.50816746014, 98466.0, 100853.49573252087, 88798.0, 89672.55163724271, 90582.54708726415, 91064.5446772762, 93190.0, 68181.0, 69631.95552066724, 78461.21538784767, 79375.20624793794, 79688.0, 9154.725358239264, 9202.0, 9231.215346695606, 9249.768755781159, 9277.860832087388, 50546.7472662649, 50774.98450031053, 52437.7378113107, 52540.0, 53625.0, 59322.11016834665, 64138.39654008501, 68453.3154668447, 70605.64697176481, 70729.64635176791, 10905.345679258953, 11059.004689577976, 11166.888331116585, 11279.718007049889, 11501.137414694098, 11877.0, 12125.575604853788, 12153.45309461094, 12186.451609677408, 12288.631341059783, 10938.800586626463, 11201.159913006393, 11271.154663400443, 11500.25248358854, 11507.712307192387, 13284.867151328363, 13288.667783305495, 13343.332833358487, 13581.0, 13646.0, 12795.296258705861, 12841.936251189078, 13029.348532573144, 13084.672883177996, 13222.272774997467, 10233.437160956219, 10281.434521101412, 10306.33008854422, 10493.020493020433, 10858.2399232053, 9148.771280718036, 9810.607575696893, 10068.546915388679, 10550.138581484029, 10959.671209863716], "value": 7272.3526800792515, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1589.3, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [107.0, 119.0, 121.0, 120.0, 119.0, 118.0, 130.0, 170.0, 163.0, 119.0], "value": 128.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 113.0, 116.0, 156.0, 117.0, 117.0, 118.0, 115.0, 153.0, 147.0], "value": 129.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [205.0, 216.0, 206.0, 256.0, 275.0, 196.0, 273.0, 275.0, 197.0, 199.0], "value": 229.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 58.0, 60.0, 87.0, 60.0, 60.0, 63.0, 73.0, 90.0, 82.0], "value": 71.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 72.0, 83.0, 71.0, 73.0, 74.0, 84.0, 71.0, 71.0, 81.0], "value": 76.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 91.0, 89.0, 127.0, 125.0, 90.0, 111.0, 92.0, 91.0, 93.0], "value": 99.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [99.0, 100.0, 99.0, 99.0, 108.0, 100.0, 100.0, 100.0, 100.0, 98.0], "value": 100.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 97.0, 94.0, 93.0, 94.0, 90.0, 94.0, 96.0, 95.0, 84.0], "value": 93.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 65.0, 64.0, 65.0, 65.0, 64.0, 66.0, 66.0, 65.0, 65.0], "value": 64.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [98.0, 69.0, 79.0, 86.0, 79.0, 68.0, 71.0, 71.0, 73.0, 73.0], "value": 76.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 98.0, 106.0, 104.0, 92.0, 96.0, 108.0, 105.0, 109.0, 94.0], "value": 100.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [155.0, 161.0, 149.0, 160.0, 146.0, 163.0, 156.0, 151.0, 190.0, 146.0], "value": 157.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [181.0, 176.0, 171.0, 215.0, 222.0, 201.0, 180.0, 179.0, 179.0, 178.0], "value": 188.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 70.0, 68.0, 71.0, 70.0, 71.0, 66.0, 69.0, 76.0, 85.0], "value": 71.4, "unit": "score"}]}]} 18:25:27 INFO - Return code: 0 18:25:27 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 18:25:27 ERROR - # TBPL SUCCESS # 18:25:27 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 18:25:27 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 18:25:27 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 18:25:27 INFO - 2017-02-13 18:25:27.367 screenresolution[1658:10572] starting screenresolution argv=screenresolution get 18:25:27 INFO - 2017-02-13 18:25:27.370 screenresolution[1658:10572] Display 0: 1600x1200x32@60 18:25:27 INFO - 2017-02-13 18:25:27.376 screenresolution[1659:10575] starting screenresolution argv=screenresolution list 18:25:27 INFO - Available Modes on Display 0 18:25:27 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 18:25:27 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 18:25:27 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 18:25:27 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 18:25:27 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 18:25:27 INFO - Intel Iris: 18:25:27 INFO - Chipset Model: Intel Iris 18:25:27 INFO - Type: GPU 18:25:27 INFO - Bus: Built-In 18:25:27 INFO - VRAM (Dynamic, Max): 1536 MB 18:25:27 INFO - Vendor: Intel (0x8086) 18:25:27 INFO - Device ID: 0x0a2e 18:25:27 INFO - Revision ID: 0x0009 18:25:27 INFO - Displays: 18:25:27 INFO - Display: 18:25:27 INFO - Resolution: 1600 x 1200 @ 60 Hz 18:25:27 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 18:25:27 INFO - Display Serial Number: 1600x1200 60 18:25:27 INFO - Main Display: Yes 18:25:27 INFO - Mirror: Off 18:25:27 INFO - Online: Yes 18:25:27 INFO - Rotation: Supported 18:25:27 INFO - Return code: 0 18:25:27 INFO - Running post-action listener: _resource_record_post_action 18:25:27 INFO - [mozharness: 2017-02-14 02:25:27.613599Z] Finished run-tests step (success) 18:25:27 INFO - Running post-run listener: _resource_record_post_run 18:25:27 INFO - Total resource usage - Wall time: 1201s; CPU: 55.0%; Read bytes: 40200704; Write bytes: 559334912; Read time: 391; Write time: 1803 18:25:27 INFO - TinderboxPrint: CPU usage
54.7% 18:25:27 INFO - TinderboxPrint: I/O read bytes / time
40,200,704 / 391 18:25:27 INFO - TinderboxPrint: I/O write bytes / time
559,334,912 / 1,803 18:25:27 INFO - TinderboxPrint: CPU idle
2,176.3 (45.3%) 18:25:27 INFO - TinderboxPrint: CPU system
970.6 (20.2%) 18:25:27 INFO - TinderboxPrint: CPU user
1,658.9 (34.5%) 18:25:27 INFO - TinderboxPrint: Swap in / out
293,208,064 / 0 18:25:27 INFO - install - Wall time: 23s; CPU: 55.0%; Read bytes: 259336704; Write bytes: 257300480; Read time: 18832; Write time: 622 18:25:27 INFO - run-tests - Wall time: 1179s; CPU: 55.0%; Read bytes: 30044672; Write bytes: 300428800; Read time: 288; Write time: 1172 18:25:28 INFO - Running post-run listener: _upload_blobber_files 18:25:28 INFO - Blob upload gear active. 18:25:28 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 18:25:28 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 18:25:28 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'] 18:25:28 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 18:25:28 INFO - (blobuploader) - INFO - Open directory for files ... 18:25:28 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 18:25:28 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:25:28 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:25:29 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 18:25:29 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:25:29 INFO - (blobuploader) - INFO - Done attempting. 18:25:29 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 18:25:29 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:25:29 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:25:30 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 18:25:30 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:25:30 INFO - (blobuploader) - INFO - Done attempting. 18:25:30 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 18:25:30 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:25:30 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:25:31 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 18:25:31 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:25:31 INFO - (blobuploader) - INFO - Done attempting. 18:25:31 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 18:25:31 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:25:31 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:25:34 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 18:25:34 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:25:34 INFO - (blobuploader) - INFO - Done attempting. 18:25:34 INFO - (blobuploader) - INFO - Iteration through files over. 18:25:34 INFO - Return code: 0 18:25:34 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 18:25:34 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 18:25:34 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3117e3777ddeb006ad37d22d288797310c43b7c7ae331e17e8db8e1a3ab04010e9e8237cb340c7a08ddd38ae6d4a44c5df89fb1c16a8aba67fc91781a069d14b", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d1d5ec4b8ad9399e0ab1b2598ad00b292d27c607a90d94fd6577dc5818872f7df2fc0f281d0ba4ce784d39526e2a2ac6e64840b482fe8817a1f7b90459a99e1d", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a80205710fd233b8297ea3a3da0a428558edbef1604b1165126f1daf40903ada2710a92d2e0d6944bc0b53f55b5152561786a351b157491c1607781224768e18", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c2f27add9001d91b831c933c3c3707a88f9bea18e08cfa6e19dcd6e900b6f659e5af57e8afb8ab851e9aa8b45f08366a109e9a143a5584b6cfc97e4b47577948"} 18:25:34 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 18:25:34 INFO - Writing to file /builds/slave/test/properties/blobber_files 18:25:34 INFO - Contents: 18:25:34 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3117e3777ddeb006ad37d22d288797310c43b7c7ae331e17e8db8e1a3ab04010e9e8237cb340c7a08ddd38ae6d4a44c5df89fb1c16a8aba67fc91781a069d14b", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d1d5ec4b8ad9399e0ab1b2598ad00b292d27c607a90d94fd6577dc5818872f7df2fc0f281d0ba4ce784d39526e2a2ac6e64840b482fe8817a1f7b90459a99e1d", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a80205710fd233b8297ea3a3da0a428558edbef1604b1165126f1daf40903ada2710a92d2e0d6944bc0b53f55b5152561786a351b157491c1607781224768e18", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c2f27add9001d91b831c933c3c3707a88f9bea18e08cfa6e19dcd6e900b6f659e5af57e8afb8ab851e9aa8b45f08366a109e9a143a5584b6cfc97e4b47577948"} 18:25:34 INFO - Running post-run listener: copy_logs_to_upload_dir 18:25:34 INFO - Copying logs to upload dir... 18:25:34 INFO - mkdir: /builds/slave/test/build/upload/logs 18:25:34 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1284.749192 ========= master_lag: 0.06 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 24 secs) (at 2017-02-13 18:25:34.291191) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-13 18:25:34.294991) ========= 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.wofsCotJDI/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.egbzV7svf5/Listeners TMPDIR=/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/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/3117e3777ddeb006ad37d22d288797310c43b7c7ae331e17e8db8e1a3ab04010e9e8237cb340c7a08ddd38ae6d4a44c5df89fb1c16a8aba67fc91781a069d14b", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d1d5ec4b8ad9399e0ab1b2598ad00b292d27c607a90d94fd6577dc5818872f7df2fc0f281d0ba4ce784d39526e2a2ac6e64840b482fe8817a1f7b90459a99e1d", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a80205710fd233b8297ea3a3da0a428558edbef1604b1165126f1daf40903ada2710a92d2e0d6944bc0b53f55b5152561786a351b157491c1607781224768e18", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c2f27add9001d91b831c933c3c3707a88f9bea18e08cfa6e19dcd6e900b6f659e5af57e8afb8ab851e9aa8b45f08366a109e9a143a5584b6cfc97e4b47577948"} build_url:https://queue.taskcluster.net/v1/task/VPAj2JKaQ3qrk2OvX5jJYQ/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011105 build_url: 'https://queue.taskcluster.net/v1/task/VPAj2JKaQ3qrk2OvX5jJYQ/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/3117e3777ddeb006ad37d22d288797310c43b7c7ae331e17e8db8e1a3ab04010e9e8237cb340c7a08ddd38ae6d4a44c5df89fb1c16a8aba67fc91781a069d14b", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d1d5ec4b8ad9399e0ab1b2598ad00b292d27c607a90d94fd6577dc5818872f7df2fc0f281d0ba4ce784d39526e2a2ac6e64840b482fe8817a1f7b90459a99e1d", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a80205710fd233b8297ea3a3da0a428558edbef1604b1165126f1daf40903ada2710a92d2e0d6944bc0b53f55b5152561786a351b157491c1607781224768e18", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c2f27add9001d91b831c933c3c3707a88f9bea18e08cfa6e19dcd6e900b6f659e5af57e8afb8ab851e9aa8b45f08366a109e9a143a5584b6cfc97e4b47577948"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-13 18:25:34.330400) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-13 18:25:34.330763) ========= 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.wofsCotJDI/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.egbzV7svf5/Listeners TMPDIR=/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/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.005368 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-13 18:25:34.379682) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-13 18:25:34.379993) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-13 18:25:34.380359) ========= ========= Total master_lag: 0.20 =========