builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0249 starttime: 1484915772.74 results: success (0) buildid: 20170120030214 builduid: 30d5a42f80f7437d8819583e8b041c08 revision: aa3e49299a3aa5cb0db570532e3df9e75d30c2d1 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-01-20 04:36:12.742856) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-01-20 04:36:12.743381) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-20 04:36:12.743710) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-20 04:36:12.771727) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-20 04:36:12.772470) ========= 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.KJfA1Iak7d/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.KfyCe3RwKA/Listeners TMPDIR=/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/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-01-20 04:36:12-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 18.1M=0.001s 2017-01-20 04:36:13 (18.1 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.606605 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-20 04:36:13.407055) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-20 04:36:13.407576) ========= 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.KJfA1Iak7d/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.KfyCe3RwKA/Listeners TMPDIR=/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/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.027427 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-20 04:36:13.458803) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 6 secs) (at 2017-01-20 04:36:13.459332) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev aa3e49299a3aa5cb0db570532e3df9e75d30c2d1 --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 aa3e49299a3aa5cb0db570532e3df9e75d30c2d1 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.KJfA1Iak7d/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.KfyCe3RwKA/Listeners TMPDIR=/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/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-01-20 04:36:13,586 truncating revision to first 12 chars 2017-01-20 04:36:13,586 Setting DEBUG logging. 2017-01-20 04:36:13,586 attempt 1/10 2017-01-20 04:36:13,586 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/aa3e49299a3a?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-01-20 04:36:15,248 unpacking tar archive at: mozilla-central-aa3e49299a3a/testing/mozharness/ program finished with exit code 0 elapsedTime=2.529653 ========= master_lag: 3.82 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 6 secs) (at 2017-01-20 04:36:19.806647) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-20 04:36:19.807046) ========= script_repo_revision: aa3e49299a3aa5cb0db570532e3df9e75d30c2d1 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-20 04:36:19.807519) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-20 04:36:19.807866) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-20 04:36:19.829913) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 9 secs) (at 2017-01-20 04:36:19.830319) ========= /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.KJfA1Iak7d/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.KfyCe3RwKA/Listeners TMPDIR=/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 04:36:20 INFO - MultiFileLogger online at 20170120 04:36:20 in /builds/slave/test 04:36:20 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 04:36:20 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 04:36:20 INFO - {'append_to_log': False, 04:36:20 INFO - 'base_work_dir': '/builds/slave/test', 04:36:20 INFO - 'blob_upload_branch': 'Firefox', 04:36:20 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 04:36:20 INFO - 'branch': 'Firefox', 04:36:20 INFO - 'buildbot_json_path': 'buildprops.json', 04:36:20 INFO - 'config_files': ('talos/mac_config.py',), 04:36:20 INFO - 'default_actions': ('clobber', 04:36:20 INFO - 'read-buildbot-config', 04:36:20 INFO - 'download-and-extract', 04:36:20 INFO - 'populate-webroot', 04:36:20 INFO - 'create-virtualenv', 04:36:20 INFO - 'install', 04:36:20 INFO - 'run-tests'), 04:36:20 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 04:36:20 INFO - 'download_minidump_stackwalk': True, 04:36:20 INFO - 'download_symbols': 'ondemand', 04:36:20 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 04:36:20 INFO - 'tooltool.py': '/tools/tooltool.py', 04:36:20 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 04:36:20 INFO - '/tools/misc-python/virtualenv.py')}, 04:36:20 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 04:36:20 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 04:36:20 INFO - 'installer_path': 'installer.exe', 04:36:20 INFO - 'log_level': 'info', 04:36:20 INFO - 'log_name': 'talos', 04:36:20 INFO - 'log_to_console': True, 04:36:20 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 04:36:20 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 04:36:20 INFO - 'opt_config_files': (), 04:36:20 INFO - 'pip_index': False, 04:36:20 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:36:20 INFO - 'cmd': ('bash', 04:36:20 INFO - '-c', 04:36:20 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:36:20 INFO - 'enabled': True, 04:36:20 INFO - 'halt_on_failure': False, 04:36:20 INFO - 'name': 'check_screen_resolution'},), 04:36:20 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:36:20 INFO - 'cmd': ('bash', 04:36:20 INFO - '-c', 04:36:20 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:36:20 INFO - 'enabled': True, 04:36:20 INFO - 'halt_on_failure': False, 04:36:20 INFO - 'name': 'check_screen_resolution'},), 04:36:20 INFO - 'run_cmd_checks_enabled': True, 04:36:20 INFO - 'sps_profile': False, 04:36:20 INFO - 'sps_profile_interval': 0, 04:36:20 INFO - 'suite': 'dromaeojs-e10s', 04:36:20 INFO - 'system_bits': '32', 04:36:20 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 04:36:20 INFO - 'title': 't-yosemite-r7-0249', 04:36:20 INFO - 'tooltool_cache': '/builds/tooltool_cache', 04:36:20 INFO - 'use_talos_json': True, 04:36:20 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 04:36:20 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 04:36:20 INFO - 'work_dir': 'build'} 04:36:20 INFO - [mozharness: 2017-01-20 12:36:20.062899Z] Running clobber step. 04:36:20 INFO - Running pre-action listener: _resource_record_pre_action 04:36:20 INFO - Running main action method: clobber 04:36:20 INFO - rmtree: /builds/slave/test/build 04:36:20 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 04:36:22 INFO - Running post-action listener: _resource_record_post_action 04:36:22 INFO - [mozharness: 2017-01-20 12:36:22.335156Z] Finished clobber step (success) 04:36:22 INFO - [mozharness: 2017-01-20 12:36:22.335345Z] Running read-buildbot-config step. 04:36:22 INFO - Running pre-action listener: _resource_record_pre_action 04:36:22 INFO - Running main action method: read_buildbot_config 04:36:22 INFO - Using buildbot properties: 04:36:22 INFO - { 04:36:22 INFO - "project": "", 04:36:22 INFO - "product": "firefox", 04:36:22 INFO - "script_repo_revision": "production", 04:36:22 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 04:36:22 INFO - "repository": "", 04:36:22 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 04:36:22 INFO - "buildid": "20170120030214", 04:36:22 INFO - "stage_platform": "macosx64", 04:36:22 INFO - "basedir": "/builds/slave/test", 04:36:22 INFO - "nightly_build": "True", 04:36:22 INFO - "buildnumber": 61, 04:36:22 INFO - "slavename": "t-yosemite-r7-0249", 04:36:22 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 04:36:22 INFO - "platform": "yosemite_r7", 04:36:22 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 04:36:22 INFO - "branch": "mozilla-central", 04:36:22 INFO - "repo_path": "mozilla-central", 04:36:22 INFO - "revision": "aa3e49299a3aa5cb0db570532e3df9e75d30c2d1", 04:36:22 INFO - "pgo_build": "False", 04:36:22 INFO - "builduid": "30d5a42f80f7437d8819583e8b041c08", 04:36:22 INFO - "slavebuilddir": "test" 04:36:22 INFO - } 04:36:22 INFO - Found installer url https://queue.taskcluster.net/v1/task/Sy2_jHf2QdaPvKwW2eO5Hg/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg. 04:36:22 INFO - Running post-action listener: _resource_record_post_action 04:36:22 INFO - [mozharness: 2017-01-20 12:36:22.339720Z] Finished read-buildbot-config step (success) 04:36:22 INFO - [mozharness: 2017-01-20 12:36:22.339873Z] Running download-and-extract step. 04:36:22 INFO - Running pre-action listener: _resource_record_pre_action 04:36:22 INFO - Running main action method: download_and_extract 04:36:22 INFO - mkdir: /builds/slave/test/build/tests 04:36:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:36:22 INFO - trying https://queue.taskcluster.net/v1/task/Sy2_jHf2QdaPvKwW2eO5Hg/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json 04:36:22 INFO - Downloading https://queue.taskcluster.net/v1/task/Sy2_jHf2QdaPvKwW2eO5Hg/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json 04:36:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Sy2_jHf2QdaPvKwW2eO5Hg/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json'}, attempt #1 04:36:23 INFO - Downloaded 1199 bytes. 04:36:23 INFO - Reading from file /builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json 04:36:23 INFO - Using the following test package requirements: 04:36:23 INFO - {u'common': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 04:36:23 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 04:36:23 INFO - u'firefox-53.0a1.en-US.mac.cppunittest.tests.zip'], 04:36:23 INFO - u'gtest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 04:36:23 INFO - u'firefox-53.0a1.en-US.mac.gtest.tests.zip'], 04:36:23 INFO - u'jittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 04:36:23 INFO - u'jsshell-mac.zip'], 04:36:23 INFO - u'mochitest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 04:36:23 INFO - u'firefox-53.0a1.en-US.mac.mochitest.tests.zip'], 04:36:23 INFO - u'mozbase': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 04:36:23 INFO - u'reftest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 04:36:23 INFO - u'firefox-53.0a1.en-US.mac.reftest.tests.zip'], 04:36:23 INFO - u'talos': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 04:36:23 INFO - u'firefox-53.0a1.en-US.mac.talos.tests.zip'], 04:36:23 INFO - u'web-platform': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 04:36:23 INFO - u'firefox-53.0a1.en-US.mac.web-platform.tests.zip'], 04:36:23 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 04:36:23 INFO - u'firefox-53.0a1.en-US.mac.xpcshell.tests.zip']} 04:36:23 INFO - Downloading packages: [u'firefox-53.0a1.en-US.mac.common.tests.zip'] for test suite category: common 04:36:23 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Sy2_jHf2QdaPvKwW2eO5Hg/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 04:36:23 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Sy2_jHf2QdaPvKwW2eO5Hg/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 04:36:23 INFO - Fetch https://queue.taskcluster.net/v1/task/Sy2_jHf2QdaPvKwW2eO5Hg/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 04:36:25 INFO - Expected file size: 16344909 04:36:25 INFO - Obtained file size: 16344909 04:36:31 INFO - Downloading packages: [u'firefox-53.0a1.en-US.mac.common.tests.zip', u'firefox-53.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 04:36:31 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Sy2_jHf2QdaPvKwW2eO5Hg/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 04:36:31 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Sy2_jHf2QdaPvKwW2eO5Hg/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 04:36:31 INFO - Fetch https://queue.taskcluster.net/v1/task/Sy2_jHf2QdaPvKwW2eO5Hg/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 04:36:32 INFO - Expected file size: 16344909 04:36:32 INFO - Obtained file size: 16344909 04:36:39 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Sy2_jHf2QdaPvKwW2eO5Hg/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip 04:36:39 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Sy2_jHf2QdaPvKwW2eO5Hg/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip'}, attempt #1 04:36:39 INFO - Fetch https://queue.taskcluster.net/v1/task/Sy2_jHf2QdaPvKwW2eO5Hg/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip into memory 04:36:40 INFO - Expected file size: 14403476 04:36:40 INFO - Obtained file size: 14403476 04:36:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:36:41 INFO - trying https://queue.taskcluster.net/v1/task/Sy2_jHf2QdaPvKwW2eO5Hg/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 04:36:41 INFO - Downloading https://queue.taskcluster.net/v1/task/Sy2_jHf2QdaPvKwW2eO5Hg/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg 04:36:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Sy2_jHf2QdaPvKwW2eO5Hg/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg'}, attempt #1 04:36:46 INFO - Downloaded 70004128 bytes. 04:36:46 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Sy2_jHf2QdaPvKwW2eO5Hg/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 04:36:46 INFO - mkdir: /builds/slave/test/properties 04:36:46 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 04:36:46 INFO - Writing to file /builds/slave/test/properties/build_url 04:36:46 INFO - Contents: 04:36:46 INFO - build_url:https://queue.taskcluster.net/v1/task/Sy2_jHf2QdaPvKwW2eO5Hg/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 04:36:47 INFO - Running post-action listener: _resource_record_post_action 04:36:47 INFO - Running post-action listener: set_extra_try_arguments 04:36:47 INFO - [mozharness: 2017-01-20 12:36:47.560753Z] Finished download-and-extract step (success) 04:36:47 INFO - [mozharness: 2017-01-20 12:36:47.560878Z] Running populate-webroot step. 04:36:47 INFO - Running pre-action listener: _resource_record_pre_action 04:36:47 INFO - Running main action method: populate_webroot 04:36:47 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 04:36:47 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 04:36:47 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 04:36:47 INFO - '--tppagecycles', 04:36:47 INFO - '7'], 04:36:47 INFO - 'tests': ['tsvgm']}}, 04:36:47 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 04:36:47 INFO - 'tests': ['tresize', 'tcanvasmark']}, 04:36:47 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 04:36:47 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 04:36:47 INFO - 'tests': ['dromaeo_css', 'kraken']}, 04:36:47 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 04:36:47 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 04:36:47 INFO - 'talos_options': ['--disable-e10s'], 04:36:47 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:36:47 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 04:36:47 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:36:47 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 04:36:47 INFO - 'talos_options': ['--disable-e10s'], 04:36:47 INFO - 'tests': ['damp', 'tps']}, 04:36:47 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 04:36:47 INFO - 'tests': ['damp', 'tps']}, 04:36:47 INFO - 'g3': {'talos_options': ['--disable-e10s'], 04:36:47 INFO - 'tests': ['dromaeo_dom']}, 04:36:47 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 04:36:47 INFO - 'g4': {'talos_options': ['--disable-e10s'], 04:36:47 INFO - 'tests': ['basic_compositor_video']}, 04:36:47 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 04:36:47 INFO - 'other': {'talos_options': ['--disable-e10s'], 04:36:47 INFO - 'tests': ['a11yr', 04:36:47 INFO - 'ts_paint', 04:36:47 INFO - 'tpaint', 04:36:47 INFO - 'sessionrestore', 04:36:47 INFO - 'sessionrestore_no_auto_restore', 04:36:47 INFO - 'tabpaint']}, 04:36:47 INFO - 'other-e10s': {'tests': ['a11yr', 04:36:47 INFO - 'ts_paint', 04:36:47 INFO - 'tpaint', 04:36:47 INFO - 'sessionrestore', 04:36:47 INFO - 'sessionrestore_no_auto_restore', 04:36:47 INFO - 'tabpaint']}, 04:36:47 INFO - 'other-e10s_l64': {'tests': ['a11yr', 04:36:47 INFO - 'ts_paint', 04:36:47 INFO - 'tpaint', 04:36:47 INFO - 'sessionrestore', 04:36:47 INFO - 'sessionrestore_no_auto_restore', 04:36:47 INFO - 'tabpaint']}, 04:36:47 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 04:36:47 INFO - 'ts_paint', 04:36:47 INFO - 'tpaint', 04:36:47 INFO - 'sessionrestore', 04:36:47 INFO - 'sessionrestore_no_auto_restore', 04:36:47 INFO - 'tabpaint']}, 04:36:47 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 04:36:47 INFO - 'tests': ['a11yr', 04:36:47 INFO - 'ts_paint', 04:36:47 INFO - 'tpaint', 04:36:47 INFO - 'sessionrestore', 04:36:47 INFO - 'sessionrestore_no_auto_restore', 04:36:47 INFO - 'tabpaint']}, 04:36:47 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 04:36:47 INFO - 'tests': ['a11yr', 04:36:47 INFO - 'ts_paint', 04:36:47 INFO - 'tpaint', 04:36:47 INFO - 'sessionrestore', 04:36:47 INFO - 'sessionrestore_no_auto_restore', 04:36:47 INFO - 'tabpaint']}, 04:36:47 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 04:36:47 INFO - 'tests': ['tsvgx', 04:36:47 INFO - 'tsvgr_opacity', 04:36:47 INFO - 'tart', 04:36:47 INFO - 'tscrollx', 04:36:47 INFO - 'cart', 04:36:47 INFO - 'tsvg_static']}, 04:36:47 INFO - 'svgr-e10s': {'tests': ['tsvgx', 04:36:47 INFO - 'tsvgr_opacity', 04:36:47 INFO - 'tart', 04:36:47 INFO - 'tscrollx', 04:36:47 INFO - 'cart', 04:36:47 INFO - 'tsvg_static']}, 04:36:47 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 04:36:47 INFO - 'talos_options': ['--disable-e10s'], 04:36:47 INFO - 'tests': ['tp5o']}, 04:36:47 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 04:36:47 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 04:36:47 INFO - 'talos_options': ['--disable-e10s', 04:36:47 INFO - '--xperf_path', 04:36:47 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:36:47 INFO - 'tests': ['tp5n']}, 04:36:47 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 04:36:47 INFO - 'talos_options': ['--xperf_path', 04:36:47 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:36:47 INFO - 'tests': ['tp5n']}}, 04:36:47 INFO - 'talos.zip': {'path': '', 04:36:47 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 04:36:47 INFO - Running post-action listener: _resource_record_post_action 04:36:47 INFO - [mozharness: 2017-01-20 12:36:47.580092Z] Finished populate-webroot step (success) 04:36:47 INFO - [mozharness: 2017-01-20 12:36:47.580227Z] Running create-virtualenv step. 04:36:47 INFO - Running pre-action listener: _resource_record_pre_action 04:36:47 INFO - Running main action method: create_virtualenv 04:36:47 INFO - Creating virtualenv /builds/slave/test/build/venv 04:36:47 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 04:36:47 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 04:36:47 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 04:36:47 INFO - Using real prefix '/tools/python27' 04:36:47 INFO - New python executable in /builds/slave/test/build/venv/bin/python 04:36:48 INFO - Installing distribute.............................................................................................................................................................................................done. 04:36:51 INFO - Installing pip.................done. 04:36:51 INFO - Return code: 0 04:36:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 04:36:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 04:36:51 INFO - Reading from file tmpfile_stdout 04:36:51 INFO - Output received: 04:36:51 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 04:36:51 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 04:36:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:36:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:36:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:36:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:36:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:36:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:36:51 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x105323b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105326768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f984a5ce100>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105101d30>, '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 0x105028db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1050f6be8>, '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.KJfA1Iak7d/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.KfyCe3RwKA/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:36:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 04:36:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 04:36:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KJfA1Iak7d/Render', 04:36:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:36:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:36:51 INFO - 'HOME': '/Users/cltbld', 04:36:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:36:51 INFO - 'LOGNAME': 'cltbld', 04:36:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:36:51 INFO - 'MOZ_NO_REMOTE': '1', 04:36:51 INFO - 'NO_EM_RESTART': '1', 04:36:51 INFO - 'PAGER': '/bin/cat', 04:36:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:36:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:36:51 INFO - 'PWD': '/builds/slave/test', 04:36:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:36:51 INFO - 'SHELL': '/bin/bash', 04:36:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KfyCe3RwKA/Listeners', 04:36:51 INFO - 'TMPDIR': '/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/', 04:36:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:36:51 INFO - 'USER': 'cltbld', 04:36:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:36:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:36:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:36:51 INFO - 'XPC_FLAGS': '0x0', 04:36:51 INFO - 'XPC_SERVICE_NAME': '0', 04:36:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:36:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:36:51 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 04:36:51 INFO - Cleaning up... 04:36:51 INFO - Return code: 0 04:36:51 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 04:36:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:36:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:36:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:36:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:36:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:36:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:36:51 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x105323b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105326768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f984a5ce100>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105101d30>, '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 0x105028db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1050f6be8>, '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.KJfA1Iak7d/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.KfyCe3RwKA/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:36:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 04:36:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 04:36:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KJfA1Iak7d/Render', 04:36:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:36:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:36:51 INFO - 'HOME': '/Users/cltbld', 04:36:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:36:51 INFO - 'LOGNAME': 'cltbld', 04:36:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:36:51 INFO - 'MOZ_NO_REMOTE': '1', 04:36:51 INFO - 'NO_EM_RESTART': '1', 04:36:51 INFO - 'PAGER': '/bin/cat', 04:36:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:36:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:36:51 INFO - 'PWD': '/builds/slave/test', 04:36:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:36:51 INFO - 'SHELL': '/bin/bash', 04:36:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KfyCe3RwKA/Listeners', 04:36:51 INFO - 'TMPDIR': '/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/', 04:36:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:36:51 INFO - 'USER': 'cltbld', 04:36:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:36:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:36:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:36:51 INFO - 'XPC_FLAGS': '0x0', 04:36:51 INFO - 'XPC_SERVICE_NAME': '0', 04:36:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:36:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:36:52 INFO - Downloading/unpacking psutil>=3.1.1 04:36:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:36:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:36:52 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:36:52 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:36:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:36:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:36:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 04:36:55 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:36:55 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:36:55 INFO - Installing collected packages: psutil 04:36:55 INFO - Running setup.py install for psutil 04:36:55 INFO - building 'psutil._psutil_osx' extension 04:36:55 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 04:36:56 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 04:36:56 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 04:36:56 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 04:36:56 INFO - building 'psutil._psutil_posix' extension 04:36:56 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 04:36:56 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 04:36:56 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 04:36:56 INFO - ^ 04:36:56 INFO - 1 warning generated. 04:36:56 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 04:36:56 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:36:56 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:36:56 INFO - Successfully installed psutil 04:36:56 INFO - Cleaning up... 04:36:56 INFO - Return code: 0 04:36:56 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 04:36: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')]} 04:36:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:36:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:36: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')]} 04:36:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:36:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:36:56 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x105323b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105326768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f984a5ce100>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105101d30>, '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 0x105028db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1050f6be8>, '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.KJfA1Iak7d/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.KfyCe3RwKA/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:36:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 04:36:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 04:36:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KJfA1Iak7d/Render', 04:36:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:36:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:36:56 INFO - 'HOME': '/Users/cltbld', 04:36:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:36:56 INFO - 'LOGNAME': 'cltbld', 04:36:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:36:56 INFO - 'MOZ_NO_REMOTE': '1', 04:36:56 INFO - 'NO_EM_RESTART': '1', 04:36:56 INFO - 'PAGER': '/bin/cat', 04:36:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:36:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:36:56 INFO - 'PWD': '/builds/slave/test', 04:36:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:36:56 INFO - 'SHELL': '/bin/bash', 04:36:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KfyCe3RwKA/Listeners', 04:36:56 INFO - 'TMPDIR': '/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/', 04:36:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:36:56 INFO - 'USER': 'cltbld', 04:36:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:36:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:36:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:36:56 INFO - 'XPC_FLAGS': '0x0', 04:36:56 INFO - 'XPC_SERVICE_NAME': '0', 04:36:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:36:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:36:57 INFO - Downloading/unpacking mozsystemmonitor==0.3 04:36: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 04:36: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 04:36: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 04:36: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 04:36: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 04:36: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 04:36:59 INFO - Downloading mozsystemmonitor-0.3.tar.gz 04:36:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 04:36:59 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 04:36:59 INFO - Installing collected packages: mozsystemmonitor 04:36:59 INFO - Running setup.py install for mozsystemmonitor 04:37:00 INFO - Successfully installed mozsystemmonitor 04:37:00 INFO - Cleaning up... 04:37:00 INFO - Return code: 0 04:37:00 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 04:37:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:37:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:37:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:37:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:37:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:37:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:37:00 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x105323b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105326768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f984a5ce100>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105101d30>, '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 0x105028db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1050f6be8>, '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.KJfA1Iak7d/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.KfyCe3RwKA/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:37:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 04:37:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 04:37:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KJfA1Iak7d/Render', 04:37:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:37:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:37:00 INFO - 'HOME': '/Users/cltbld', 04:37:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:37:00 INFO - 'LOGNAME': 'cltbld', 04:37:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:37:00 INFO - 'MOZ_NO_REMOTE': '1', 04:37:00 INFO - 'NO_EM_RESTART': '1', 04:37:00 INFO - 'PAGER': '/bin/cat', 04:37:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:37:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:37:00 INFO - 'PWD': '/builds/slave/test', 04:37:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:37:00 INFO - 'SHELL': '/bin/bash', 04:37:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KfyCe3RwKA/Listeners', 04:37:00 INFO - 'TMPDIR': '/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/', 04:37:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:37:00 INFO - 'USER': 'cltbld', 04:37:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:37:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:37:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:37:00 INFO - 'XPC_FLAGS': '0x0', 04:37:00 INFO - 'XPC_SERVICE_NAME': '0', 04:37:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:37:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:37:00 INFO - Downloading/unpacking jsonschema==2.5.1 04:37:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:37:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:37:00 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:37:00 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:37:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:37:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:37:03 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 04:37:03 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 04:37:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:37:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:37:03 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:37:03 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:37:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:37:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:37:03 INFO - Downloading functools32-3.2.3-2.tar.gz 04:37:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 04:37:04 INFO - warning: no files found matching '*.txt' 04:37:04 INFO - no previously-included directories found matching 'build' 04:37:04 INFO - no previously-included directories found matching 'dist' 04:37:04 INFO - no previously-included directories found matching '.git*' 04:37:04 INFO - Installing collected packages: jsonschema, functools32 04:37:04 INFO - Running setup.py install for functools32 04:37:04 INFO - warning: no files found matching '*.txt' 04:37:04 INFO - no previously-included directories found matching 'build' 04:37:04 INFO - no previously-included directories found matching 'dist' 04:37:04 INFO - no previously-included directories found matching '.git*' 04:37:04 INFO - Successfully installed jsonschema functools32 04:37:04 INFO - Cleaning up... 04:37:04 INFO - Return code: 0 04:37:04 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 04:37:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:37:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:37:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:37:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:37:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:37:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:37:04 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x105323b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105326768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f984a5ce100>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105101d30>, '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 0x105028db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1050f6be8>, '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.KJfA1Iak7d/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.KfyCe3RwKA/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:37:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 04:37:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 04:37:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KJfA1Iak7d/Render', 04:37:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:37:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:37:04 INFO - 'HOME': '/Users/cltbld', 04:37:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:37:04 INFO - 'LOGNAME': 'cltbld', 04:37:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:37:04 INFO - 'MOZ_NO_REMOTE': '1', 04:37:04 INFO - 'NO_EM_RESTART': '1', 04:37:04 INFO - 'PAGER': '/bin/cat', 04:37:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:37:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:37:04 INFO - 'PWD': '/builds/slave/test', 04:37:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:37:04 INFO - 'SHELL': '/bin/bash', 04:37:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KfyCe3RwKA/Listeners', 04:37:04 INFO - 'TMPDIR': '/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/', 04:37:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:37:04 INFO - 'USER': 'cltbld', 04:37:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:37:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:37:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:37:04 INFO - 'XPC_FLAGS': '0x0', 04:37:04 INFO - 'XPC_SERVICE_NAME': '0', 04:37:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:37:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:37:04 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 04:37:04 INFO - Cleaning up... 04:37:04 INFO - Return code: 0 04:37:04 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 04:37:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:37:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:37:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:37:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:37:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:37:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:37: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', '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 0x105323b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105326768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f984a5ce100>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105101d30>, '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 0x105028db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1050f6be8>, '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.KJfA1Iak7d/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.KfyCe3RwKA/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:37: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', 'blobuploader==1.2.4'] in /builds/slave/test/build 04:37: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 blobuploader==1.2.4 04:37:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KJfA1Iak7d/Render', 04:37:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:37:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:37:05 INFO - 'HOME': '/Users/cltbld', 04:37:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:37:05 INFO - 'LOGNAME': 'cltbld', 04:37:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:37:05 INFO - 'MOZ_NO_REMOTE': '1', 04:37:05 INFO - 'NO_EM_RESTART': '1', 04:37:05 INFO - 'PAGER': '/bin/cat', 04:37:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:37:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:37:05 INFO - 'PWD': '/builds/slave/test', 04:37:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:37:05 INFO - 'SHELL': '/bin/bash', 04:37:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KfyCe3RwKA/Listeners', 04:37:05 INFO - 'TMPDIR': '/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/', 04:37:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:37:05 INFO - 'USER': 'cltbld', 04:37:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:37:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:37:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:37:05 INFO - 'XPC_FLAGS': '0x0', 04:37:05 INFO - 'XPC_SERVICE_NAME': '0', 04:37:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:37:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:37:05 INFO - Downloading/unpacking blobuploader==1.2.4 04:37: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 04:37: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 04:37: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 04:37: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 04:37: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 04:37: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 04:37:08 INFO - Downloading blobuploader-1.2.4.tar.gz 04:37:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 04:37:08 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 04:37: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 04:37: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 04:37: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 04:37: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 04:37: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 04:37: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 04:37:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 04:37:09 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 04:37: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 04:37: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 04:37: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 04:37: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 04:37: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 04:37: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 04:37:10 INFO - Downloading docopt-0.6.1.tar.gz 04:37:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 04:37:10 INFO - Installing collected packages: blobuploader, requests, docopt 04:37:10 INFO - Running setup.py install for blobuploader 04:37:10 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 04:37:10 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 04:37:10 INFO - Running setup.py install for requests 04:37:10 INFO - Running setup.py install for docopt 04:37:11 INFO - Successfully installed blobuploader requests docopt 04:37:11 INFO - Cleaning up... 04:37:11 INFO - Return code: 0 04:37:11 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:37:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:37:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:37:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:37:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:37:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:37:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:37:11 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 0x105323b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105326768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f984a5ce100>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105101d30>, '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 0x105028db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1050f6be8>, '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.KJfA1Iak7d/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.KfyCe3RwKA/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:37:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 04:37:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 04:37:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KJfA1Iak7d/Render', 04:37:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:37:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:37:11 INFO - 'HOME': '/Users/cltbld', 04:37:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:37:11 INFO - 'LOGNAME': 'cltbld', 04:37:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:37:11 INFO - 'MOZ_NO_REMOTE': '1', 04:37:11 INFO - 'NO_EM_RESTART': '1', 04:37:11 INFO - 'PAGER': '/bin/cat', 04:37:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:37:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:37:11 INFO - 'PWD': '/builds/slave/test', 04:37:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:37:11 INFO - 'SHELL': '/bin/bash', 04:37:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KfyCe3RwKA/Listeners', 04:37:11 INFO - 'TMPDIR': '/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/', 04:37:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:37:11 INFO - 'USER': 'cltbld', 04:37:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:37:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:37:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:37:11 INFO - 'XPC_FLAGS': '0x0', 04:37:11 INFO - 'XPC_SERVICE_NAME': '0', 04:37:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:37:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:37:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:37:11 INFO - Running setup.py (path:/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/pip-OI3k5o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:37:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:37:11 INFO - Running setup.py (path:/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/pip-g73jov-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:37:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:37:12 INFO - Running setup.py (path:/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/pip-ylKfUp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:37:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:37:12 INFO - Running setup.py (path:/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/pip-buoEQc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:37:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:37:12 INFO - Running setup.py (path:/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/pip-U8WT1z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:37:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:37:12 INFO - Running setup.py (path:/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/pip-96cnXP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:37:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:37:12 INFO - Running setup.py (path:/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/pip-_rC22H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:37:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:37:12 INFO - Running setup.py (path:/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/pip-sRDwhl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:37:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:37:12 INFO - Running setup.py (path:/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/pip-4CdeVf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:37:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:37:12 INFO - Running setup.py (path:/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/pip-76WQXJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:37:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:37:12 INFO - Running setup.py (path:/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/pip-NYDvZ_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:37:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:37:13 INFO - Running setup.py (path:/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/pip-esRxWA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:37:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:37:13 INFO - Running setup.py (path:/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/pip-UqeTRe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:37:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:37:13 INFO - Running setup.py (path:/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/pip-HObFnS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:37:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:37:13 INFO - Running setup.py (path:/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/pip-ToiDZH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:37:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:37:13 INFO - Running setup.py (path:/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/pip-goV3J_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:37:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:37:13 INFO - Running setup.py (path:/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/pip-DAWP6l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:37:13 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 04:37:13 INFO - Running setup.py install for manifestparser 04:37:13 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 04:37:13 INFO - Running setup.py install for mozcrash 04:37:13 INFO - Running setup.py install for mozdebug 04:37:14 INFO - Running setup.py install for mozdevice 04:37:14 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 04:37:14 INFO - Installing dm script to /builds/slave/test/build/venv/bin 04:37:14 INFO - Running setup.py install for mozfile 04:37:14 INFO - Running setup.py install for mozhttpd 04:37:14 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 04:37:14 INFO - Running setup.py install for mozinfo 04:37:14 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 04:37:14 INFO - Running setup.py install for mozInstall 04:37:14 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 04:37:14 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 04:37:14 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 04:37:14 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 04:37:14 INFO - Running setup.py install for mozleak 04:37:14 INFO - Running setup.py install for mozlog 04:37:15 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 04:37:15 INFO - Running setup.py install for moznetwork 04:37:15 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 04:37:15 INFO - Running setup.py install for mozprocess 04:37:15 INFO - Running setup.py install for mozprofile 04:37:15 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 04:37:15 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 04:37:15 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 04:37:15 INFO - Running setup.py install for mozrunner 04:37:15 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 04:37:15 INFO - Running setup.py install for mozscreenshot 04:37:15 INFO - Running setup.py install for moztest 04:37:16 INFO - Running setup.py install for mozversion 04:37:16 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 04:37:16 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 04:37:16 INFO - Cleaning up... 04:37:16 INFO - Return code: 0 04:37:16 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:37:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:37:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:37:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:37:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:37:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:37:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:37:16 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 0x105323b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105326768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f984a5ce100>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105101d30>, '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 0x105028db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1050f6be8>, '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.KJfA1Iak7d/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.KfyCe3RwKA/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:37:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 04:37:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 04:37:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KJfA1Iak7d/Render', 04:37:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:37:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:37:16 INFO - 'HOME': '/Users/cltbld', 04:37:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:37:16 INFO - 'LOGNAME': 'cltbld', 04:37:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:37:16 INFO - 'MOZ_NO_REMOTE': '1', 04:37:16 INFO - 'NO_EM_RESTART': '1', 04:37:16 INFO - 'PAGER': '/bin/cat', 04:37:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:37:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:37:16 INFO - 'PWD': '/builds/slave/test', 04:37:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:37:16 INFO - 'SHELL': '/bin/bash', 04:37:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KfyCe3RwKA/Listeners', 04:37:16 INFO - 'TMPDIR': '/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/', 04:37:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:37:16 INFO - 'USER': 'cltbld', 04:37:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:37:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:37:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:37:16 INFO - 'XPC_FLAGS': '0x0', 04:37:16 INFO - 'XPC_SERVICE_NAME': '0', 04:37:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:37:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:37:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:37:16 INFO - Running setup.py (path:/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/pip-NTOk4D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:37:16 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 04:37:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:37:16 INFO - Running setup.py (path:/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/pip-l8R0lL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:37:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 04:37:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:37:16 INFO - Running setup.py (path:/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/pip-752Fbk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:37:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 04:37:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:37:16 INFO - Running setup.py (path:/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/pip-OCv1CY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:37:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 04:37:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:37:16 INFO - Running setup.py (path:/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/pip-bU05dB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:37:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 04:37:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:37:17 INFO - Running setup.py (path:/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/pip-PnTatu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:37:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 04:37:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:37:17 INFO - Running setup.py (path:/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/pip-VmRlc_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:37:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 04:37:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:37:17 INFO - Running setup.py (path:/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/pip-2_JJWq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:37:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 04:37:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:37:17 INFO - Running setup.py (path:/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/pip-LAyPCw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:37:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 04:37:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:37:17 INFO - Running setup.py (path:/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/pip-Zs85Nb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:37:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 04:37:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:37:17 INFO - Running setup.py (path:/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/pip-YYVx2z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:37:17 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 04:37:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:37:17 INFO - Running setup.py (path:/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/pip-QSEIff-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:37:17 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)) 04:37:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:37:17 INFO - Running setup.py (path:/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/pip-Y4kJ4B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:37:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 04:37:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:37:17 INFO - Running setup.py (path:/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/pip-LXl_cV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:37:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 04:37:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:37:17 INFO - Running setup.py (path:/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/pip-2zCxCh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:37:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 04:37:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:37:18 INFO - Running setup.py (path:/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/pip-GOvCRc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:37:18 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 04:37:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:37:18 INFO - Running setup.py (path:/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/pip-2HJHhl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:37:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 04:37:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 04:37:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 04:37:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 04:37:18 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 04:37:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 04:37:18 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 04:37:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:37:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:37:18 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:37:18 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:37:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:37:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:37:20 INFO - Downloading blessings-1.6.tar.gz 04:37:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 04:37:20 INFO - Installing collected packages: blessings 04:37:20 INFO - Running setup.py install for blessings 04:37:21 INFO - Successfully installed blessings 04:37:21 INFO - Cleaning up... 04:37:21 INFO - Return code: 0 04:37:21 INFO - Done creating virtualenv /builds/slave/test/build/venv. 04:37:21 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:37:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:37:21 INFO - Reading from file tmpfile_stdout 04:37:21 INFO - Current package versions: 04:37:21 INFO - blessings == 1.6 04:37:21 INFO - blobuploader == 1.2.4 04:37:21 INFO - docopt == 0.6.1 04:37:21 INFO - functools32 == 3.2.3-2 04:37:21 INFO - jsonschema == 2.5.1 04:37:21 INFO - manifestparser == 1.1 04:37:21 INFO - mozInstall == 1.12 04:37:21 INFO - mozcrash == 1.0 04:37:21 INFO - mozdebug == 0.1 04:37:21 INFO - mozdevice == 0.49 04:37:21 INFO - mozfile == 1.2 04:37:21 INFO - mozhttpd == 0.7 04:37:21 INFO - mozinfo == 0.9 04:37:21 INFO - mozleak == 0.1 04:37:21 INFO - mozlog == 3.4 04:37:21 INFO - moznetwork == 0.27 04:37:21 INFO - mozprocess == 0.24 04:37:21 INFO - mozprofile == 0.28 04:37:21 INFO - mozrunner == 6.13 04:37:21 INFO - mozscreenshot == 0.1 04:37:21 INFO - mozsystemmonitor == 0.3 04:37:21 INFO - moztest == 0.8 04:37:21 INFO - mozversion == 1.4 04:37:21 INFO - psutil == 3.1.1 04:37:21 INFO - requests == 1.2.3 04:37:21 INFO - wsgiref == 0.1.2 04:37:21 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:37:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:37:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:37:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:37:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:37:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:37:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:37:21 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 0x105323b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105326768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f984a5ce100>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105101d30>, '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 0x105028db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1050f6be8>, '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.KJfA1Iak7d/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.KfyCe3RwKA/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:37:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 04:37:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 04:37:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KJfA1Iak7d/Render', 04:37:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:37:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:37:21 INFO - 'HOME': '/Users/cltbld', 04:37:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:37:21 INFO - 'LOGNAME': 'cltbld', 04:37:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:37:21 INFO - 'MOZ_NO_REMOTE': '1', 04:37:21 INFO - 'NO_EM_RESTART': '1', 04:37:21 INFO - 'PAGER': '/bin/cat', 04:37:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:37:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:37:21 INFO - 'PWD': '/builds/slave/test', 04:37:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:37:21 INFO - 'SHELL': '/bin/bash', 04:37:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KfyCe3RwKA/Listeners', 04:37:21 INFO - 'TMPDIR': '/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/', 04:37:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:37:21 INFO - 'USER': 'cltbld', 04:37:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:37:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:37:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:37:21 INFO - 'XPC_FLAGS': '0x0', 04:37:21 INFO - 'XPC_SERVICE_NAME': '0', 04:37:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:37:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:37:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 04:37:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 04:37:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 04:37:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 04:37:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 04:37:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 04:37:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 04:37:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 04:37:21 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 04:37:21 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 04:37:21 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 04:37:21 INFO - Cleaning up... 04:37:21 INFO - Return code: 0 04:37:21 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 04:37:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:37:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:37:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:37:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:37:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:37:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:37:21 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 0x105323b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105326768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f984a5ce100>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105101d30>, '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 0x105028db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1050f6be8>, '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.KJfA1Iak7d/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.KfyCe3RwKA/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:37:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 04:37:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 04:37:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KJfA1Iak7d/Render', 04:37:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:37:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:37:21 INFO - 'HOME': '/Users/cltbld', 04:37:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:37:21 INFO - 'LOGNAME': 'cltbld', 04:37:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:37:21 INFO - 'MOZ_NO_REMOTE': '1', 04:37:21 INFO - 'NO_EM_RESTART': '1', 04:37:21 INFO - 'PAGER': '/bin/cat', 04:37:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:37:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:37:21 INFO - 'PWD': '/builds/slave/test', 04:37:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:37:21 INFO - 'SHELL': '/bin/bash', 04:37:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KfyCe3RwKA/Listeners', 04:37:21 INFO - 'TMPDIR': '/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/', 04:37:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:37:21 INFO - 'USER': 'cltbld', 04:37:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:37:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:37:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:37:21 INFO - 'XPC_FLAGS': '0x0', 04:37:21 INFO - 'XPC_SERVICE_NAME': '0', 04:37:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:37:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:37:22 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 04:37:22 INFO - Cleaning up... 04:37:22 INFO - Return code: 0 04:37:22 INFO - Running post-action listener: _resource_record_post_action 04:37:22 INFO - Running post-action listener: _start_resource_monitoring 04:37:22 INFO - Starting resource monitoring. 04:37:22 INFO - [mozharness: 2017-01-20 12:37:22.102460Z] Finished create-virtualenv step (success) 04:37:22 INFO - [mozharness: 2017-01-20 12:37:22.103381Z] Running install step. 04:37:22 INFO - Running pre-action listener: _resource_record_pre_action 04:37:22 INFO - Running main action method: install 04:37:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:37:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:37:22 INFO - Reading from file tmpfile_stdout 04:37:22 INFO - Detecting whether we're running mozinstall >=1.0... 04:37:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 04:37:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 04:37:22 INFO - Reading from file tmpfile_stdout 04:37:22 INFO - Output received: 04:37:22 INFO - Usage: mozinstall [options] installer 04:37:22 INFO - Options: 04:37:22 INFO - -h, --help show this help message and exit 04:37:22 INFO - -d DEST, --destination=DEST 04:37:22 INFO - Directory to install application into. [default: 04:37:22 INFO - "/builds/slave/test"] 04:37:22 INFO - --app=APP Application being installed. [default: firefox] 04:37:22 INFO - mkdir: /builds/slave/test/build/application 04:37:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 04:37:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 04:37:44 INFO - Reading from file tmpfile_stdout 04:37:44 INFO - Output received: 04:37:44 INFO - /builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox 04:37:44 INFO - Running post-action listener: _resource_record_post_action 04:37:44 INFO - [mozharness: 2017-01-20 12:37:44.409180Z] Finished install step (success) 04:37:44 INFO - [mozharness: 2017-01-20 12:37:44.409352Z] Running run-tests step. 04:37:44 INFO - Running pre-action listener: _resource_record_pre_action 04:37:44 INFO - Running main action method: run_tests 04:37:44 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 04:37:44 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 04:37:44 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 04:37:44 INFO - 2017-01-20 04:37:44.423 screenresolution[1625:7295] starting screenresolution argv=screenresolution get 04:37:44 INFO - 2017-01-20 04:37:44.426 screenresolution[1625:7295] Display 0: 1600x1200x32@60 04:37:44 INFO - 2017-01-20 04:37:44.432 screenresolution[1626:7297] starting screenresolution argv=screenresolution list 04:37:44 INFO - Available Modes on Display 0 04:37:44 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 04:37:44 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 04:37:44 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 04:37:44 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 04:37:44 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 04:37:44 INFO - Intel Iris: 04:37:44 INFO - Chipset Model: Intel Iris 04:37:44 INFO - Type: GPU 04:37:44 INFO - Bus: Built-In 04:37:44 INFO - VRAM (Dynamic, Max): 1536 MB 04:37:44 INFO - Vendor: Intel (0x8086) 04:37:44 INFO - Device ID: 0x0a2e 04:37:44 INFO - Revision ID: 0x0009 04:37:44 INFO - Displays: 04:37:44 INFO - Display: 04:37:44 INFO - Resolution: 1600 x 1200 @ 60 Hz 04:37:44 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 04:37:44 INFO - Display Serial Number: 1600x1200 60 04:37:44 INFO - Main Display: Yes 04:37:44 INFO - Mirror: Off 04:37:44 INFO - Online: Yes 04:37:44 INFO - Rotation: Supported 04:37:44 INFO - Return code: 0 04:37:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 04:37:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 04:37:44 INFO - Python 2.7.3 04:37:44 INFO - Return code: 0 04:37:44 INFO - grabbing minidump binary from tooltool 04:37:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:37:44 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 0x105101d30>, '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 0x105028db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1050f6be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 04:37:44 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 04:37:44 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 04:37:44 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 04:37:44 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 04:37:44 INFO - Return code: 0 04:37:44 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 04:37:44 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 04:37:44 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 04:37:44 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 04:37:44 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 04:37:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Sy2_jHf2QdaPvKwW2eO5Hg/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0249', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 04:37:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Sy2_jHf2QdaPvKwW2eO5Hg/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0249 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 04:37:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KJfA1Iak7d/Render', 04:37:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:37:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:37:44 INFO - 'HOME': '/Users/cltbld', 04:37:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:37:44 INFO - 'LOGNAME': 'cltbld', 04:37:44 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 04:37:44 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 04:37:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:37:44 INFO - 'MOZ_NO_REMOTE': '1', 04:37:44 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 04:37:44 INFO - 'NO_EM_RESTART': '1', 04:37:44 INFO - 'PAGER': '/bin/cat', 04:37:44 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:37:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:37:44 INFO - 'PWD': '/builds/slave/test', 04:37:44 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 04:37:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:37:44 INFO - 'SHELL': '/bin/bash', 04:37:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KfyCe3RwKA/Listeners', 04:37:44 INFO - 'TMPDIR': '/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/', 04:37:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:37:44 INFO - 'USER': 'cltbld', 04:37:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:37:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:37:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:37:44 INFO - 'XPC_FLAGS': '0x0', 04:37:44 INFO - 'XPC_SERVICE_NAME': '0', 04:37:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:37:44 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Sy2_jHf2QdaPvKwW2eO5Hg/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0249', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 04:37:45 INFO - mozversion application_buildid: 20170120030214 04:37:45 INFO - mozversion application_changeset: aa3e49299a3aa5cb0db570532e3df9e75d30c2d1 04:37:45 INFO - mozversion application_display_name: Nightly 04:37:45 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 04:37:45 INFO - mozversion application_name: Firefox 04:37:45 INFO - mozversion application_remotingname: firefox 04:37:45 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 04:37:45 INFO - mozversion application_vendor: Mozilla 04:37:45 INFO - mozversion application_version: 53.0a1 04:37:45 INFO - mozversion platform_buildid: 20170120030214 04:37:45 INFO - mozversion platform_changeset: aa3e49299a3aa5cb0db570532e3df9e75d30c2d1 04:37:45 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 04:37:45 INFO - mozversion platform_version: 53.0a1 04:37:45 INFO - using testdate: 1484915865 04:37:45 INFO - actual date: 1484915865 04:37:45 INFO - starting webserver on 'localhost:49238' 04:37:45 INFO - SUITE-START | Running 2 tests 04:37:45 INFO - TEST-START | dromaeo_css 04:37:45 INFO - operating with platform_type : mac_ 04:37:45 INFO - Initialising browser for dromaeo_css test... 04:37:45 INFO - TEST-INFO | started process 1636 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/tmpwx2mwL/profile http://localhost:49238/getInfo.html) 04:37:48 INFO - PROCESS | 1636 | 2017-01-20 04:37:48.136 plugin-container[1639:7474] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8f3f, name = 'com.apple.tsm.portname' 04:37:48 INFO - PROCESS | 1636 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:37:48 INFO - PROCESS | 1636 | 2017-01-20 04:37:48.139 plugin-container[1639:7474] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x950b, name = 'com.apple.CFPasteboardClient' 04:37:48 INFO - PROCESS | 1636 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:37:48 INFO - PROCESS | 1636 | 2017-01-20 04:37:48.139 plugin-container[1639:7474] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 04:37:48 INFO - PROCESS | 1636 | __metrics Screen width/height:1600/1200 04:37:48 INFO - PROCESS | 1636 | colorDepth:24 04:37:48 INFO - PROCESS | 1636 | Browser inner width/height: 1024/689 04:37:48 INFO - PROCESS | 1636 | __metrics 04:37:48 ERROR - PROCESS | 1636 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3269: TypeError: this.tabs is undefined 04:37:48 INFO - PROCESS | 1636 | JavaScript error: resource://app/modules/webrtcUI.jsm, line 66: NS_ERROR_XPC_NOT_ENOUGH_ARGS: Not enough arguments [nsIMessageListenerManager.removeMessageListener] 04:37:49 INFO - TEST-INFO | 1636: exit 0 04:37:49 INFO - Browser initialized. 04:37:49 INFO - Running cycle 1/1 for dromaeo_css test... 04:37:49 INFO - TEST-INFO | started process 1640 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/tmpwx2mwL/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:37:51 INFO - PROCESS | 1640 | 2017-01-20 04:37:51.782 plugin-container[1641:7593] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x863f, name = 'com.apple.tsm.portname' 04:37:51 INFO - PROCESS | 1640 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:37:51 INFO - PROCESS | 1640 | 2017-01-20 04:37:51.783 plugin-container[1641:7593] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8c0b, name = 'com.apple.CFPasteboardClient' 04:37:51 INFO - PROCESS | 1640 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:37:51 INFO - PROCESS | 1640 | 2017-01-20 04:37:51.783 plugin-container[1641:7593] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 04:38:01 INFO - PROCESS | 1640 | RSS: Main: 232255488 04:38:01 INFO - PROCESS | 1640 | 04:41:04 INFO - PROCESS | 1640 | Cycle 1(1): loaded http://localhost:49238/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49238/tests/dromaeo/cssquery-ext.html) 04:41:05 INFO - PROCESS | 1640 | RSS: Main: 261083136 04:41:05 INFO - PROCESS | 1640 | 04:42:49 INFO - PROCESS | 1640 | ************************* 04:42:49 INFO - PROCESS | 1640 | A coding exception was thrown and uncaught in a Task. 04:42:49 INFO - PROCESS | 1640 | 04:42:49 ERROR - PROCESS | 1640 | Full message: TypeError: NetworkError when attempting to fetch resource. 04:42:49 INFO - PROCESS | 1640 | Full stack: 04:42:49 INFO - PROCESS | 1640 | ************************* 04:44:08 INFO - PROCESS | 1640 | Cycle 1(1): loaded http://localhost:49238/tests/dromaeo/cssquery-ext.html (next: http://localhost:49238/tests/dromaeo/cssquery-jquery.html) 04:44:08 INFO - PROCESS | 1640 | RSS: Main: 261951488 04:44:08 INFO - PROCESS | 1640 | 04:47:10 INFO - PROCESS | 1640 | Cycle 1(1): loaded http://localhost:49238/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49238/tests/dromaeo/cssquery-mootools.html) 04:47:11 INFO - PROCESS | 1640 | RSS: Main: 270336000 04:47:11 INFO - PROCESS | 1640 | 04:50:13 INFO - PROCESS | 1640 | Cycle 1(1): loaded http://localhost:49238/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49238/tests/dromaeo/cssquery-prototype.html) 04:50:14 INFO - PROCESS | 1640 | RSS: Main: 266715136 04:50:14 INFO - PROCESS | 1640 | 04:53:16 INFO - PROCESS | 1640 | Cycle 1(1): loaded http://localhost:49238/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49238/tests/dromaeo/cssquery-yui.html) 04:53:17 INFO - PROCESS | 1640 | RSS: Main: 271593472 04:53:17 INFO - PROCESS | 1640 | 04:56:20 INFO - PROCESS | 1640 | Cycle 1(1): loaded http://localhost:49238/tests/dromaeo/cssquery-yui.html (next: http://localhost:49238/tests/dromaeo/cssquery-dojo.html) 04:56:20 INFO - PROCESS | 1640 | RSS: Main: 273346560 04:56:20 INFO - PROCESS | 1640 | 04:56:20 INFO - PROCESS | 1640 | __start_tp_report 04:56:20 INFO - PROCESS | 1640 | _x_x_mozilla_page_load 04:56:20 INFO - PROCESS | 1640 | _x_x_mozilla_page_load_details 04:56:20 INFO - PROCESS | 1640 | |i|pagename|runs| 04:56:20 INFO - PROCESS | 1640 | |0;dojo.html;3019.927925586418;3416.572928383952;3426.486027095936;3429.2112814052766;3444.914851821676;5615.1857980592895;5623.943760562393;5646.943530564693;5662.660240385575;5736.254286942692;5611.719414029292;5649;5695.373508914016;5697.1169468732305;5802.535797136219;5136.306598609177;5144.639875208737;5201.791928322863;5236.842894713165;5292.470752924715;5170.508801663855;5248.5538729208065;5280.356553977762;5297.788088476456;5337.70642614656;5057.140286151363;5122.20605806097;5171.198464238011;5172.767225474862;5202.92195617066;5113.258577506278;5148.304978827848;5157.071727089123;5173.430922598511;5177.767000484987;5137.717425541594;5161.329027226437;5180.611454140954;5189.455107213726;5203.375594928595;5413.810516631899;5582.665040097607;5623.297087864017;5638.04153293941;5645.717714114319;6122.969385153046;6132.662703551303;6204.534659900525;6242.87514249717;6243.968780156116;6213.347598502138;6224.968875155595;6235.064740288947;6238.719257633417;6291.591046581958;5778.162166485878;5821.708914554255;5838.67887266196;5853.5317174625925;5894.646321220741;6252.874942501125;6314.116023756677;6350.33321501245;6359.20509936258;6364.077208804697;6002.069679199731;6048.576599637983;6066.636001839904;6120.877582448326;6127.92262824669;5213.296205012275;6148.815535533941;6156.230471191101;6156.969215153896;6212.844678882973;6129.63387754696;6143.72682107623;6146.2175968969395;6163.84590385235;6178.227721534809;5184.9740751296;6189.257289125244;6232.781852635136;6243.625382477066;6267.2479302484;4873.049755297754;4928.753562321869;4941.407031156285;4956.950430495649;4963.429205641398;4398.64810815134;4404.757738324423;4418.7127836690515;4429;4458.7324760514475;5802.65184088956;5825.0679891217205;5839.211706419664;5894.440028197314;5923.822285331447;6271.247450305992;6283.02613094971;6293.842653933688;6299.6535190564955;6304.401081897213;5670.461306175906;5676.631018983835;5730.054541000688;5823.4467725566;5875.970620146957;5391.083515802245;5416.620836541483;5421.159720243369;5448.645838020516;5449.918251226235;5357.4106848246665;5839.124131380327;5962.999999999914;6400.743970241138;6409.4872410206135;5308.522232999048;6272.310045895039;6278.246610406598;6292.182016337847;6323.462505687068;5171.560417364566;6259.123722678738;6261.467775239155;6365.35837627398;6412.166418365583;6116.396640261158;6133.186900958431;6136.610985192662;6146.569740118149;6147.262328520606;6083.695815209133;6188.381161883775;6193.876122477435;6199.535034872493;6203.193584833943;5987.5808693391045;5988.710706877287;6045;6118.449339559461;6174.567780255339;6039.607425517327;6133.351973717037;6139.338817383011;6139.6454669416935;6149.815505534841;5717.199592057199;5843.620164689281;5918.230630018243;5940.673262970578;6022.668753218615;6113.327533971171;6136.447719705248;6143.938560614337;6145.84635384119;6189.381061893775;5826.242588463472;5851.616512808141;5897.882042359214;5903;6041.305249896318 04:56:20 INFO - PROCESS | 1640 | |1;ext.html;445.7414699474305;457.39852094495717;509.06332348478804;514.5240652396534;518.1166111779416;38444.42333364998;40836.59163408365;40937.18125637485;41029.79485102578;41100.794496027556;42021.789891050576;42518.57481425185;42586.78706606463;42641.360379594254;42664;9206.769830754234;9420.858687119699;9430.387024843363;10665.680029599125;11028.393438360887;10077.589137520754;10251.692449226497;11009.669709908716;11054.336739795597;11119.054880335181;9407.952960235189;11045.171612129083;11095.72260693485;11121.610743623974;11130.38782866942;10975.2866063706;11044.502997365138;11050.668479945614;11056.391898445583;11056.447177641086;10889.945550272198;10960.506777195045;10981.945090274578;10986.615468458565;10995.120390368751;36452;36463;36710.08224794402;36900.815495922616;36958.63041369605;3985.4021896715435;4017.27689015977;4025.9597404025876;4037.7173597848164;4043.3934909763475;27754.861225693945;28217;28416.28959276014;28419.71580284212;28460;3246.9188270293193;3272.181954511372;3287.5068739689;3288.0300311407955;3349.6936194884015;433084;463840;466235;468327;468618.6569067167;46676.7666161667;54518.90962180813;55258;55576.72211638916;58294.7085264571;57284.71357643268;57905.420945790844;58134.12798808021;58256.708716456145;58290.708546457;12146.696332591757;12377.690557736129;13935.88512918964;15058.247087645574;15183.316750746242;8968.730938071869;10580.470976451146;11522.539098436137;12018.699532511757;12031.578894738643;14093.436262549589;14142.787858182135;17656.911715441598;17730;17743.73384399235;6813.581178681596;7952.324052455606;7969.242921922408;8036.598170091472;8053.15441878606;744.3524134003452;754.4680999895061;860.6901515454431;876.6493402638868;883.5317281840635;23105;25454.872725636254;28363;29765.553516696833;29859;1061.8619579454776;1066.6053560182781;1100.5377741348652;1101.2236373356764;1221.9450124744408;12557.686057848445;13936.372863221179;14028.737413632822;15318.463853765064;15824;568420;570240;570520;572121;572520;59060;59285.70357148186;59730.80538389208;59759.70120149371;59855.102173467436;1405.4097279142784;1451.6951440197677;1462.6782107936237;1463.0782606957548;1470.0885451020222;29066.837326506702;29148.7085129146;29158.27104322409;29858.552121717756;29881.5517767238;49447.50552494573;49992.50022499331;49996.50003499918;50019.74990125026;50080.499195007586;411610;440755;519013;524770;524976;24224.273271801874;24396.878015609807;24837.751622483545;24958.37604059913;25440.363990900376;35748.821255894596;42128.78935605302;42563;43090.353644694726;43250.78374608233;27472.862635686695;35336.29327413386;35412;36275.81862090673;36369.636303636624;34912.30175396529;34916.82541587276;35705.92882213538;35956.640433596374;36027.459588105674;28010.71989280081;33451.49822752612;34084.659153408145;34126.48810267897;34208.657913420546;26263.868680656473;29560.852195738884;31187.629236717403;31216.74918602557;31371;23196.88401558049;23677.17129900445;23700.4074898122;23728.050877964688;23824.047038118973 04:56:20 INFO - PROCESS | 1640 | |2;jquery.html;389.30120433821287;393.44327776196684;401.9316716158253;403.97189153604086;425.8969269591757;17927.10364481774;17951.102444877768;20189.293374731886;21412.57174856502;21481;17115.657686846254;21050.684239736416;21612.351629451103;21756;21789.564208715856;5078.4413714491375;5457.536109430694;5637.4644408781305;5849.532037437016;5914.408559144073;5759.10733836254;5967.776605795823;5982.8803423931495;6043.153958445799;6049.848753781147;5045.445001049881;5830.271216097988;5848.122781582754;5922.289325280973;5930.347661757225;5005.874853128665;5015.297858299841;5669.971650141764;5892.086726557391;5904.2324497815225;5194.428612852583;5214.243934629458;5216.243644671539;5218.13900706383;5236.764345604457;19623.80376196234;20944.895275523526;25786.74213257881;25906.352341191432;26176.607350889753;2719.4017316190407;2825.265430987937;2826.858657067138;2830.915072547806;2842.9431411371866;11619.535218591247;15641.217939102999;15799.447019354382;15809.941243129653;16316.673666526722;1510.4260381055128;1833.6332733453362;1836.192075486795;1893.9621207575908;1904.114586717183;106824.46587767011;119832.60334793256;120541.7945820548;120931.3953430236;120960;11565.421728913521;11641.359725215025;11696.649100526998;11751.529938802523;11846.170768046153;14671.486497972519;14683.265836708335;14711.485098021518;14714.705705886037;14735.263236838115;8401.747947561584;8600.39797214189;8675.089115642893;8746.081661425585;8762;8435.072142064368;8435.662573496991;8442;8495.575221239036;8599.61301741423;7204.991301217834;7285.417166626763;7330.8900366494545;7336.413086953031;7336.853262934711;1910.5701217226012;1951.2292644405381;1952.1800843645703;1952.8144826241485;1953.4335042837702;972.3242346569067;1032.5750464359137;1037.7924415117006;1061.8107719354373;1089.210074697202;21798.324579284985;21901.33090173447;21973.681579105305;22123.225687100876;22140;1064.4092528646675;1069.9090577301017;1075.1184029096223;1115.8047341715167;1118.3290025984506;11147.275427097213;11430.314181149157;11512.30926144434;11560.132990025782;12152.939235303944;510922;681240;682529;699157;708802;131412.3429382847;134840;150555;152577;155922;434.3528143066871;461.72758072737247;464.0834352154535;464.4589053752408;469.0782612167108;10927.781444370909;11055.72360690989;11231.719207019563;11496.885031149915;11555.88444115548;59872;60244.397556023876;62102;62127;62530.124096277184;86429;91853;93275.53362233417;93922.06077939134;95568;5832.066869300891;6972.44220462372;7858.720970918598;8370.832583348178;8791.296696264155;12642.620721378373;13290.401931912893;13312.267825269702;13488.662783430493;13725.862741372459;9374.296927730311;11295.3787541686;11299.548018079513;11330.320180789178;11560.034794085193;9306;11077;11214.66356009321;11552.826707599224;11769.352685602375;11502;11513.481893314989;11754.353510557;11759.05927525813;11838.348890811088;8342.874856877273;8371.497710137412;8634.159683715941;8728.690696395592;8865.202131808168;8341.24928756415;9976.301658883809;11468.713282168013;11559.711007224887;11824.290542567473 04:56:20 INFO - PROCESS | 1640 | |3;mootools.html;1323.8808507234346;1442.8557144285573;1455.1850963460463;1465.6702241995545;1488.293060796122;7535.962320188405;7767.067951845772;8102.797430064252;8540.743777686672;8594.269487093588;7990.400719946013;8212.507249565031;8286.08853026168;8370.53962032088;8399.11809260028;2257.762934891833;2304.225746175444;2317.733460652021;2318.1886339781026;2319.7912187903084;2059.1351632314463;2145.839062070343;2306.411864974429;2332.9650055249185;2348.131191459154;2225.276785044859;2298.689676893623;2301.838871279011;2305.8155347572156;2306.1006207579057;2282.8173746100274;2290.965635515452;2308.7114110736156;2309.3533810533077;2314.109068008812;2256.165218869029;2287.748347681754;2293.520679161948;2328.871912044837;2337.0885354711677;6816.795496135124;7648.623247815391;7679.886416469581;8417.200365965286;8523.659053637846;4899.755012249373;5135.537801597874;5157.948420515821;5248.081585722483;5286.497782710637;3310.536524886518;3490.7381946354126;3496.580410350749;3571.053670777246;3585.533880595532;1016.7407311135687;1147.833564133196;1230.5262473947505;1248.1699669719626;1259.4521383198337;9075.682351117744;10741.248112632118;10824;10835.67492975212;10850.891491085067;4349.217140914602;4632.189366860786;4705.529447055267;4709.62323014158;4714.175019371596;9541.379810312446;9568.952155239318;9582.568784404719;9586.568604412818;9609.90390096104;4779.757263111616;4912.38595175603;5206.661566998133;5260.0531904257605;5279.894402112013;4662.860114196579;4751.738654374042;4817.662763606514;4883.291922671264;4888.315635810989;3779.7732136071922;3807.333716599584;3843.26977874203;3886.028492876781;3898.044978980168;1161.7444162284453;1209.625016244967;1220.6521141474636;1264.0330147437219;1269.7841366967639;878.3895192840914;879.9032106468284;895.9462432254086;908.7909780750465;920.1718453392001;5411.269478620415;5667.319921609434;5679.488846003879;5709.885802284014;5717.456841600125;961.9663311784054;968.733598260484;977.3647129365969;977.7115750853516;1000.5997600959674;3743.6817870481314;3793.019693762707;3830.616938306147;3847.5382954045135;3858.344081506151;9741.561629726537;9999.800003999879;10039.849402258971;10090.394576325443;10093.444860532594;9918.371631517653;10013.949930250448;10020.348677336096;10035.397876127456;10036.799264014679;7614.428917831185;7654.349380302737;7804.3756499479905;7837.647305871249;7877.960610197027;5329;5397.490998371564;5480.177973304037;5555.444455554574;5657.773689052392;9213.309001824757;9366.06339366058;9372.95313523428;9384.530773461163;9401.529923503935;8958.283337333129;9289.071092890657;9337.813243734952;9386.108319709616;9390.812183756423;5700.800471983461;6049.790041991531;7098.25468325839;7250.637468126467;7287.089113860768;7120.928790712027;7310.305520975498;7724.652390642322;7751.263629955145;7820.413468989962;6212.844678883064;6373.7131829066875;6476.643784591704;6502.317256687979;6883.586984780929;6742.73029078851;7017.473689473412;7149.53528020677;7149.749758758627;7198.424126070006;5491.450854914477;6227.252729672468;6283.874322513616;6399;6407.423331900151;4054.8580799671868;4976.154053810788;4988.775505102351;5072.873178170576;5086.9745651271505;4226.64073553739;5010.1421905133575;5011.5489605935645;5208.9739551302;5221.869453263699 04:56:20 INFO - PROCESS | 1640 | |4;prototype.html;276.6141232979993;349.95275637788893;363.1194353692296;371.89586915663625;373.28515892066696;4334.978325108378;4656.462228085693;5104.821331253412;5303.893922121555;5394.271773310602;5315.73421328933;5369.382521010085;5370.462953704628;5388.838334849961;5407.242985981956;3844.2888065707793;4172.895677608061;4236.665450383135;4244.236037513247;4271.594198551148;3454.2573346730446;3587.856485740567;3817.618238176174;4056.2293164298835;4128.442660240859;3279.8688052477873;3655.305491956533;4041.9191616167805;4058.715889887709;4070.743405275782;3477.7739446935866;3913.9823645852;4038.737482063671;4059.5128584569748;4067.5525692173837;3189.378071276102;3374.7806392584403;3503.807290599016;3505.141240396094;3506.6318036606044;5088.974555127201;5090.1855703087695;5090.778213228798;5093.592512598983;5174.31834653739;4102.897427564305;4471.8434854779925;4592.88517787058;4628.310666606698;4640.236710050193;5012.323336349583;5015.498450154992;5042.218456139304;5689.260396148474;5717.742701578397;3672.676228013652;3721.5162028936065;3837.865674701372;3840.8463661453507;3872.6320999505283;5425.674459532401;5656.105204756412;5777.826665200051;5923.77154450821;6043.274807023141;3848.0956975110826;3887.61123887609;3940.842366305316;3948.861789837342;3974.7813870236837;3983.780892050907;4015.5582885883114;4020.8287524736897;4034.536028356718;4044.2315959967514;4486.394336764561;4691.247002398068;4700.647451441156;4711.505291944297;4720.103180395714;4696.8121275148615;5123.487651234846;5756.798512052058;5815.941840581625;5824.932149087098;1097.7914196302677;1194.3431112887877;1271.9951238521649;1276.259769333784;1295.404114107521;775.8913752074714;830.588858515039;870.7170169694748;884.9867251991225;887.4292502270944;4333.739975601411;4854;4904.94543673112;5103.8724031898755;5113.71874546896;5293.168155037037;5299.894002119936;5423.403425623178;5663.575231857627;5731.971340143273;4439.489458712224;4530.411046563926;4851.296561998491;5097.6941383517105;5145.430643653722;2723.945521089567;2751.857978938718;2763.632002158944;2977.985110074436;2980.344324248662;5135.435102138762;5369.140937449989;5475.112670004341;5614.550835933197;5925.644461332334;5128.769205385766;5372.892542149135;5396.406395296514;5696.487316141484;5766.509846662997;5176.8964620707375;5207.682581251307;5286.959998001242;5827.388124246978;5854.531637468992;5259.368875734936;5291.470852914677;5356.517913387658;5485.286912701324;5682.886342273048;4868.951310486991;5418.756155972912;5752.626079304831;5809.414810409327;5925.52595792327;4999.45006049327;5808.344621782771;5920.141579471038;6007.279126504847;6221.262644436614;3481.5299934508516;3969.0430629181446;3973.344398174269;3983.5618082011533;3984.900377490586;5480.808171713971;5938.257717785277;6006;6020.458158765732;6038.305594856472;5595.399751947531;5626.803061892814;5695.373508914099;5734.168545560954;5786.33457152416;4989.827390563288;5584.347434968357;5670.716464176857;5724.026915424304;5800.216970708901;5304.628675992643;5621.775128994795;5647.181158731879;5688.886222275614;5689.971550142388;5154.587632989277;5415.945840541544;5568.774869528697;5759.971200143972;5842;4835.419749630067;5682.448469706434;5702.372738998624;5719.170720245458;5738.540916726735 04:56:20 INFO - PROCESS | 1640 | |5;yui.html;703.8627467643805;715.749487679312;768.69636493585;826.128434501601;834.103338910671;15102.093874367545;15796.684066318667;15818.683626327467;15863.920680396597;15876.682466350667;14529.709405811904;14651;14689.559313220594;14689.779653305182;14692.265386730674;692.0760784352894;714.3927661487746;854.4147259127486;867.7830542364425;883.2845395229884;872.2978002707849;876.9517676527788;887.1970866365917;889.4529864133543;889.6219106879582;945.8297506448837;947.2280091725256;948.2129832239225;948.5257371314343;950.771814764455;936.9484678342689;938.2962777916563;939.7321763297462;939.802641445294;942.0202988891544;686.7012849410472;695.1935754524777;697.5814511293183;705.4180301251488;706.8268274272784;14961.925190374088;15151.393944242218;15336.463223787225;15577.532674019683;15937.521874343789;13214.33928303581;13414.26221557814;13554.118982266122;13590.864091359059;13676;11732.53069877204;11751.588694395741;11763.58827441044;11880.524779008829;12269.509219631203;3150.5116706910467;3750.8124593770203;3757.1358587525024;3781.2437512497336;3811.6188381161937;84933.57533212357;98125.5281170783;101148.49425752898;101539.99999999926;103085.14520830126;9453.243740500744;11225.71935701614;11266.7183320416;11299.322040677585;11668.533258669728;17554.122293885255;21912.342629721134;23093.769062309162;23225.535489290458;23260.06959721626;7385.889211661829;7854.685812567547;7925.445218834626;7943.364530837519;8140.308073813792;4237.512686041083;4319.850194726929;4362.476502819619;4392.846250381222;4417.337399390053;3550.201204728967;3592.586852511942;3648.1244501319493;3652.41561350188;3660.743747937619;640.7981485831948;648.0344287012412;653.323809856796;654.4175683641563;669.5547460938519;598.7036416973539;679.0798468075785;681.0159319782965;694.399344566946;706.1561434086218;9921.404715716935;10267.127294179929;10310.701499133409;10355.17158627308;10576.471176441148;680.1633990191992;683.6274230544366;687.4500399680336;692.8441100752291;709.3225969199402;4635.675502714845;4968.552830245295;4980.551750342486;4987.276844857475;4994.80020799164;90908;95515.52242238744;96461.51769241109;98141.50929245308;100378.49810750899;90873.54563227274;91648;92073;92596;93195.53402232945;80348;81441.59279203684;81534;81939;83149.58425207835;9597.712068637951;9617.374870633386;9618.086281803218;9639.373440726331;9646.131848133702;54708.72645636746;54829.72585137049;54853;54945.1758223635;55447;68430.65784671044;69987;70420.23949401306;70611.9408208867;72379;9062.682806101755;11287.887121129012;11303.039241664223;11931.985781208346;12199.329036903055;12552.246865188117;12852.614421567368;13091.149075310075;13901.652458688613;15510.84489155094;13700.177989320673;13903.652408689864;14177.291135443393;14295.71408571802;14718.337674804445;11578.768424631291;13414.664633384244;13775.86224137786;13902.096363736326;13953.302334883418;13218.140820846615;13518;13826.170429774245;13847.861521384657;13946.790798137834;9152.359334846496;10072.787156027052;10827.187960902806;11335.886641133484;11610.825837612612;9881.752956176153;10265.332753371007;10843.240973131806;11021.283616564897;11083.944580277048 04:56:20 INFO - PROCESS | 1640 | __end_tp_report 04:56:20 INFO - PROCESS | 1640 | __start_cc_report 04:56:20 INFO - PROCESS | 1640 | _x_x_mozilla_cycle_collect,403 04:56:20 INFO - PROCESS | 1640 | __end_cc_report 04:56:20 INFO - PROCESS | 1640 | __startTimestamp1484916980274__endTimestamp 04:56:20 INFO - PROCESS | 1640 | 04:56:20 INFO - PROCESS | 1640 | ------- Summary: start ------- 04:56:20 INFO - PROCESS | 1640 | Number of tests: 6 04:56:20 INFO - PROCESS | 1640 | 04:56:20 INFO - PROCESS | 1640 | [#0] dojo.html Cycles:165 Average:5707.24 Median:5894.44 stddev:638.01 (10.8%) stddev-sans-first:604.14 04:56:20 INFO - PROCESS | 1640 | Values: 3019.9 3416.6 3426.5 3429.2 3444.9 5615.2 5623.9 5646.9 5662.7 5736.3 5611.7 5649.0 5695.4 5697.1 5802.5 5136.3 5144.6 5201.8 5236.8 5292.5 5170.5 5248.6 5280.4 5297.8 5337.7 5057.1 5122.2 5171.2 5172.8 5202.9 5113.3 5148.3 5157.1 5173.4 5177.8 5137.7 5161.3 5180.6 5189.5 5203.4 5413.8 5582.7 5623.3 5638.0 5645.7 6123.0 6132.7 6204.5 6242.9 6244.0 6213.3 6225.0 6235.1 6238.7 6291.6 5778.2 5821.7 5838.7 5853.5 5894.6 6252.9 6314.1 6350.3 6359.2 6364.1 6002.1 6048.6 6066.6 6120.9 6127.9 5213.3 6148.8 6156.2 6157.0 6212.8 6129.6 6143.7 6146.2 6163.8 6178.2 5185.0 6189.3 6232.8 6243.6 6267.2 4873.0 4928.8 4941.4 4957.0 4963.4 4398.6 4404.8 4418.7 4429.0 4458.7 5802.7 5825.1 5839.2 5894.4 5923.8 6271.2 6283.0 6293.8 6299.7 6304.4 5670.5 5676.6 5730.1 5823.4 5876.0 5391.1 5416.6 5421.2 5448.6 5449.9 5357.4 5839.1 5963.0 6400.7 6409.5 5308.5 6272.3 6278.2 6292.2 6323.5 5171.6 6259.1 6261.5 6365.4 6412.2 6116.4 6133.2 6136.6 6146.6 6147.3 6083.7 6188.4 6193.9 6199.5 6203.2 5987.6 5988.7 6045.0 6118.4 6174.6 6039.6 6133.4 6139.3 6139.6 6149.8 5717.2 5843.6 5918.2 5940.7 6022.7 6113.3 6136.4 6143.9 6145.8 6189.4 5826.2 5851.6 5897.9 5903.0 6041.3 04:56:20 INFO - PROCESS | 1640 | 04:56:20 INFO - PROCESS | 1640 | [#1] ext.html Cycles:180 Average:63638.73 Median:34560.48 stddev:135458.58 (391.9%) stddev-sans-first:135755.49 04:56:20 INFO - PROCESS | 1640 | Values: 445.7 457.4 509.1 514.5 518.1 38444.4 40836.6 40937.2 41029.8 41100.8 42021.8 42518.6 42586.8 42641.4 42664.0 9206.8 9420.9 9430.4 10665.7 11028.4 10077.6 10251.7 11009.7 11054.3 11119.1 9408.0 11045.2 11095.7 11121.6 11130.4 10975.3 11044.5 11050.7 11056.4 11056.4 10889.9 10960.5 10981.9 10986.6 10995.1 36452.0 36463.0 36710.1 36900.8 36958.6 3985.4 4017.3 4026.0 4037.7 4043.4 27754.9 28217.0 28416.3 28419.7 28460.0 3246.9 3272.2 3287.5 3288.0 3349.7 433084.0 463840.0 466235.0 468327.0 468618.7 46676.8 54518.9 55258.0 55576.7 58294.7 57284.7 57905.4 58134.1 58256.7 58290.7 12146.7 12377.7 13935.9 15058.2 15183.3 8968.7 10580.5 11522.5 12018.7 12031.6 14093.4 14142.8 17656.9 17730.0 17743.7 6813.6 7952.3 7969.2 8036.6 8053.2 744.4 754.5 860.7 876.6 883.5 23105.0 25454.9 28363.0 29765.6 29859.0 1061.9 1066.6 1100.5 1101.2 1221.9 12557.7 13936.4 14028.7 15318.5 15824.0 568420.0 570240.0 570520.0 572121.0 572520.0 59060.0 59285.7 59730.8 59759.7 59855.1 1405.4 1451.7 1462.7 1463.1 1470.1 29066.8 29148.7 29158.3 29858.6 29881.6 49447.5 49992.5 49996.5 50019.7 50080.5 411610.0 440755.0 519013.0 524770.0 524976.0 24224.3 24396.9 24837.8 24958.4 25440.4 35748.8 42128.8 42563.0 43090.4 43250.8 27472.9 35336.3 35412.0 36275.8 36369.6 34912.3 34916.8 35705.9 35956.6 36027.5 28010.7 33451.5 34084.7 34126.5 34208.7 26263.9 29560.9 31187.6 31216.7 31371.0 23196.9 23677.2 23700.4 23728.1 23824.0 04:56:20 INFO - PROCESS | 1640 | 04:56:20 INFO - PROCESS | 1640 | [#2] jquery.html Cycles:180 Average:37750.62 Median:22131.61 stddev:110403.75 (498.9%) stddev-sans-first:110677.82 04:56:20 INFO - PROCESS | 1640 | Values: 389.3 393.4 401.9 404.0 425.9 17927.1 17951.1 20189.3 21412.6 21481.0 17115.7 21050.7 21612.4 21756.0 21789.6 5078.4 5457.5 5637.5 5849.5 5914.4 5759.1 5967.8 5982.9 6043.2 6049.8 5045.4 5830.3 5848.1 5922.3 5930.3 5005.9 5015.3 5670.0 5892.1 5904.2 5194.4 5214.2 5216.2 5218.1 5236.8 19623.8 20944.9 25786.7 25906.4 26176.6 2719.4 2825.3 2826.9 2830.9 2842.9 11619.5 15641.2 15799.4 15809.9 16316.7 1510.4 1833.6 1836.2 1894.0 1904.1 106824.5 119832.6 120541.8 120931.4 120960.0 11565.4 11641.4 11696.6 11751.5 11846.2 14671.5 14683.3 14711.5 14714.7 14735.3 8401.7 8600.4 8675.1 8746.1 8762.0 8435.1 8435.7 8442.0 8495.6 8599.6 7205.0 7285.4 7330.9 7336.4 7336.9 1910.6 1951.2 1952.2 1952.8 1953.4 972.3 1032.6 1037.8 1061.8 1089.2 21798.3 21901.3 21973.7 22123.2 22140.0 1064.4 1069.9 1075.1 1115.8 1118.3 11147.3 11430.3 11512.3 11560.1 12152.9 510922.0 681240.0 682529.0 699157.0 708802.0 131412.3 134840.0 150555.0 152577.0 155922.0 434.4 461.7 464.1 464.5 469.1 10927.8 11055.7 11231.7 11496.9 11555.9 59872.0 60244.4 62102.0 62127.0 62530.1 86429.0 91853.0 93275.5 93922.1 95568.0 5832.1 6972.4 7858.7 8370.8 8791.3 12642.6 13290.4 13312.3 13488.7 13725.9 9374.3 11295.4 11299.5 11330.3 11560.0 9306.0 11077.0 11214.7 11552.8 11769.4 11502.0 11513.5 11754.4 11759.1 11838.3 8342.9 8371.5 8634.2 8728.7 8865.2 8341.2 9976.3 11468.7 11559.7 11824.3 04:56:20 INFO - PROCESS | 1640 | 04:56:20 INFO - PROCESS | 1640 | [#3] mootools.html Cycles:180 Average:5371.28 Median:5207.82 stddev:2920.86 (56.1%) stddev-sans-first:2913.21 04:56:20 INFO - PROCESS | 1640 | Values: 1323.9 1442.9 1455.2 1465.7 1488.3 7536.0 7767.1 8102.8 8540.7 8594.3 7990.4 8212.5 8286.1 8370.5 8399.1 2257.8 2304.2 2317.7 2318.2 2319.8 2059.1 2145.8 2306.4 2333.0 2348.1 2225.3 2298.7 2301.8 2305.8 2306.1 2282.8 2291.0 2308.7 2309.4 2314.1 2256.2 2287.7 2293.5 2328.9 2337.1 6816.8 7648.6 7679.9 8417.2 8523.7 4899.8 5135.5 5157.9 5248.1 5286.5 3310.5 3490.7 3496.6 3571.1 3585.5 1016.7 1147.8 1230.5 1248.2 1259.5 9075.7 10741.2 10824.0 10835.7 10850.9 4349.2 4632.2 4705.5 4709.6 4714.2 9541.4 9569.0 9582.6 9586.6 9609.9 4779.8 4912.4 5206.7 5260.1 5279.9 4662.9 4751.7 4817.7 4883.3 4888.3 3779.8 3807.3 3843.3 3886.0 3898.0 1161.7 1209.6 1220.7 1264.0 1269.8 878.4 879.9 895.9 908.8 920.2 5411.3 5667.3 5679.5 5709.9 5717.5 962.0 968.7 977.4 977.7 1000.6 3743.7 3793.0 3830.6 3847.5 3858.3 9741.6 9999.8 10039.8 10090.4 10093.4 9918.4 10013.9 10020.3 10035.4 10036.8 7614.4 7654.3 7804.4 7837.6 7878.0 5329.0 5397.5 5480.2 5555.4 5657.8 9213.3 9366.1 9373.0 9384.5 9401.5 8958.3 9289.1 9337.8 9386.1 9390.8 5700.8 6049.8 7098.3 7250.6 7287.1 7120.9 7310.3 7724.7 7751.3 7820.4 6212.8 6373.7 6476.6 6502.3 6883.6 6742.7 7017.5 7149.5 7149.7 7198.4 5491.5 6227.3 6283.9 6399.0 6407.4 4054.9 4976.2 4988.8 5072.9 5087.0 4226.6 5010.1 5011.5 5209.0 5221.9 04:56:20 INFO - PROCESS | 1640 | 04:56:20 INFO - PROCESS | 1640 | [#4] prototype.html Cycles:180 Average:4534.75 Median:5100.78 stddev:1398.93 (27.4%) stddev-sans-first:1365.86 04:56:20 INFO - PROCESS | 1640 | Values: 276.6 350.0 363.1 371.9 373.3 4335.0 4656.5 5104.8 5303.9 5394.3 5315.7 5369.4 5370.5 5388.8 5407.2 3844.3 4172.9 4236.7 4244.2 4271.6 3454.3 3587.9 3817.6 4056.2 4128.4 3279.9 3655.3 4041.9 4058.7 4070.7 3477.8 3914.0 4038.7 4059.5 4067.6 3189.4 3374.8 3503.8 3505.1 3506.6 5089.0 5090.2 5090.8 5093.6 5174.3 4102.9 4471.8 4592.9 4628.3 4640.2 5012.3 5015.5 5042.2 5689.3 5717.7 3672.7 3721.5 3837.9 3840.8 3872.6 5425.7 5656.1 5777.8 5923.8 6043.3 3848.1 3887.6 3940.8 3948.9 3974.8 3983.8 4015.6 4020.8 4034.5 4044.2 4486.4 4691.2 4700.6 4711.5 4720.1 4696.8 5123.5 5756.8 5815.9 5824.9 1097.8 1194.3 1272.0 1276.3 1295.4 775.9 830.6 870.7 885.0 887.4 4333.7 4854.0 4904.9 5103.9 5113.7 5293.2 5299.9 5423.4 5663.6 5732.0 4439.5 4530.4 4851.3 5097.7 5145.4 2723.9 2751.9 2763.6 2978.0 2980.3 5135.4 5369.1 5475.1 5614.6 5925.6 5128.8 5372.9 5396.4 5696.5 5766.5 5176.9 5207.7 5287.0 5827.4 5854.5 5259.4 5291.5 5356.5 5485.3 5682.9 4869.0 5418.8 5752.6 5809.4 5925.5 4999.5 5808.3 5920.1 6007.3 6221.3 3481.5 3969.0 3973.3 3983.6 3984.9 5480.8 5938.3 6006.0 6020.5 6038.3 5595.4 5626.8 5695.4 5734.2 5786.3 4989.8 5584.3 5670.7 5724.0 5800.2 5304.6 5621.8 5647.2 5688.9 5690.0 5154.6 5415.9 5568.8 5760.0 5842.0 4835.4 5682.4 5702.4 5719.2 5738.5 04:56:20 INFO - PROCESS | 1640 | 04:56:20 INFO - PROCESS | 1640 | [#5] yui.html Cycles:180 Average:20430.17 Median:4974.55 stddev:28968.49 (582.3%) stddev-sans-first:29011.88 04:56:20 INFO - PROCESS | 1640 | Values: 703.9 715.7 768.7 826.1 834.1 15102.1 15796.7 15818.7 15863.9 15876.7 14529.7 14651.0 14689.6 14689.8 14692.3 692.1 714.4 854.4 867.8 883.3 872.3 877.0 887.2 889.5 889.6 945.8 947.2 948.2 948.5 950.8 936.9 938.3 939.7 939.8 942.0 686.7 695.2 697.6 705.4 706.8 14961.9 15151.4 15336.5 15577.5 15937.5 13214.3 13414.3 13554.1 13590.9 13676.0 11732.5 11751.6 11763.6 11880.5 12269.5 3150.5 3750.8 3757.1 3781.2 3811.6 84933.6 98125.5 101148.5 101540.0 103085.1 9453.2 11225.7 11266.7 11299.3 11668.5 17554.1 21912.3 23093.8 23225.5 23260.1 7385.9 7854.7 7925.4 7943.4 8140.3 4237.5 4319.9 4362.5 4392.8 4417.3 3550.2 3592.6 3648.1 3652.4 3660.7 640.8 648.0 653.3 654.4 669.6 598.7 679.1 681.0 694.4 706.2 9921.4 10267.1 10310.7 10355.2 10576.5 680.2 683.6 687.5 692.8 709.3 4635.7 4968.6 4980.6 4987.3 4994.8 90908.0 95515.5 96461.5 98141.5 100378.5 90873.5 91648.0 92073.0 92596.0 93195.5 80348.0 81441.6 81534.0 81939.0 83149.6 9597.7 9617.4 9618.1 9639.4 9646.1 54708.7 54829.7 54853.0 54945.2 55447.0 68430.7 69987.0 70420.2 70611.9 72379.0 9062.7 11287.9 11303.0 11932.0 12199.3 12552.2 12852.6 13091.1 13901.7 15510.8 13700.2 13903.7 14177.3 14295.7 14718.3 11578.8 13414.7 13775.9 13902.1 13953.3 13218.1 13518.0 13826.2 13847.9 13946.8 9152.4 10072.8 10827.2 11335.9 11610.8 9881.8 10265.3 10843.2 11021.3 11083.9 04:56:20 INFO - PROCESS | 1640 | -------- Summary: end -------- 04:56:20 INFO - PROCESS | 1640 | 04:56:20 INFO - TEST-INFO | 1640: exit 0 04:56:20 INFO - TEST-OK | dromaeo_css | took 1115855ms 04:56:20 INFO - TEST-START | kraken 04:56:20 INFO - operating with platform_type : mac_ 04:56:20 INFO - Initialising browser for kraken test... 04:56:20 INFO - TEST-INFO | started process 1652 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/tmpasdcn1/profile http://localhost:49238/getInfo.html) 04:56:23 INFO - PROCESS | 1652 | 2017-01-20 04:56:23.712 plugin-container[1654:9954] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4647, name = 'com.apple.tsm.portname' 04:56:23 INFO - PROCESS | 1652 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:56:23 INFO - PROCESS | 1652 | 2017-01-20 04:56:23.713 plugin-container[1654:9954] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9103, name = 'com.apple.CFPasteboardClient' 04:56:23 INFO - PROCESS | 1652 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:56:23 INFO - PROCESS | 1652 | 2017-01-20 04:56:23.713 plugin-container[1654:9954] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 04:56:23 INFO - PROCESS | 1652 | __metrics Screen width/height:1600/1200 04:56:23 INFO - PROCESS | 1652 | colorDepth:24 04:56:23 INFO - PROCESS | 1652 | Browser inner width/height: 1024/689 04:56:23 INFO - PROCESS | 1652 | __metrics 04:56:23 ERROR - PROCESS | 1652 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3269: TypeError: this.tabs is undefined 04:56:23 INFO - PROCESS | 1652 | JavaScript error: resource://app/modules/webrtcUI.jsm, line 66: NS_ERROR_XPC_NOT_ENOUGH_ARGS: Not enough arguments [nsIMessageListenerManager.removeMessageListener] 04:56:24 INFO - TEST-INFO | 1652: exit 0 04:56:24 INFO - Browser initialized. 04:56:24 INFO - Running cycle 1/1 for kraken test... 04:56:24 INFO - TEST-INFO | started process 1655 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/tmpasdcn1/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:56:26 INFO - PROCESS | 1655 | 2017-01-20 04:56:26.900 plugin-container[1656:10075] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4347, name = 'com.apple.tsm.portname' 04:56:26 INFO - PROCESS | 1655 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:56:26 INFO - PROCESS | 1655 | 2017-01-20 04:56:26.901 plugin-container[1656:10075] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x434b, name = 'com.apple.CFPasteboardClient' 04:56:26 INFO - PROCESS | 1655 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:56:26 INFO - PROCESS | 1655 | 2017-01-20 04:56:26.901 plugin-container[1656:10075] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 04:56:36 INFO - PROCESS | 1655 | RSS: Main: 233086976 04:56:36 INFO - PROCESS | 1655 | 04:56:39 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49238/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49238/tests/kraken/driver.html?testName=audio-beat-detection) 04:56:40 INFO - PROCESS | 1655 | RSS: Main: 246734848 04:56:40 INFO - PROCESS | 1655 | 04:56:43 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49238/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49238/tests/kraken/driver.html?testName=audio-dft) 04:56:43 INFO - PROCESS | 1655 | RSS: Main: 254455808 04:56:43 INFO - PROCESS | 1655 | 04:56:47 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49238/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49238/tests/kraken/driver.html?testName=audio-fft) 04:56:47 INFO - PROCESS | 1655 | RSS: Main: 256999424 04:56:47 INFO - PROCESS | 1655 | 04:56:49 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49238/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49238/tests/kraken/driver.html?testName=audio-oscillator) 04:56:50 INFO - PROCESS | 1655 | RSS: Main: 259211264 04:56:50 INFO - PROCESS | 1655 | 04:56:52 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49238/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49238/tests/kraken/driver.html?testName=imaging-gaussian-blur) 04:56:52 INFO - PROCESS | 1655 | RSS: Main: 259510272 04:56:52 INFO - PROCESS | 1655 | 04:56:55 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49238/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49238/tests/kraken/driver.html?testName=imaging-darkroom) 04:56:56 INFO - PROCESS | 1655 | RSS: Main: 265138176 04:56:56 INFO - PROCESS | 1655 | 04:56:59 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49238/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49238/tests/kraken/driver.html?testName=imaging-desaturate) 04:57:00 INFO - PROCESS | 1655 | RSS: Main: 264945664 04:57:00 INFO - PROCESS | 1655 | 04:57:03 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49238/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49238/tests/kraken/driver.html?testName=json-parse-financial) 04:57:03 INFO - PROCESS | 1655 | RSS: Main: 264736768 04:57:03 INFO - PROCESS | 1655 | 04:57:05 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49238/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49238/tests/kraken/driver.html?testName=json-stringify-tinderbox) 04:57:05 INFO - PROCESS | 1655 | RSS: Main: 264212480 04:57:05 INFO - PROCESS | 1655 | 04:57:07 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49238/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49238/tests/kraken/driver.html?testName=stanford-crypto-aes) 04:57:08 INFO - PROCESS | 1655 | RSS: Main: 264265728 04:57:08 INFO - PROCESS | 1655 | 04:57:10 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49238/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49238/tests/kraken/driver.html?testName=stanford-crypto-ccm) 04:57:11 INFO - PROCESS | 1655 | RSS: Main: 264204288 04:57:11 INFO - PROCESS | 1655 | 04:57:14 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49238/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49238/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 04:57:15 INFO - PROCESS | 1655 | RSS: Main: 264560640 04:57:15 INFO - PROCESS | 1655 | 04:57:18 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49238/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49238/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 04:57:18 INFO - PROCESS | 1655 | RSS: Main: 264646656 04:57:18 INFO - PROCESS | 1655 | 04:57:20 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49238/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49238/tests/kraken/driver.html?testName=ai-astar) 04:57:20 INFO - PROCESS | 1655 | RSS: Main: 266235904 04:57:20 INFO - PROCESS | 1655 | 04:57:20 INFO - PROCESS | 1655 | __start_tp_report 04:57:20 INFO - PROCESS | 1655 | _x_x_mozilla_page_load 04:57:20 INFO - PROCESS | 1655 | _x_x_mozilla_page_load_details 04:57:20 INFO - PROCESS | 1655 | |i|pagename|runs| 04:57:20 INFO - PROCESS | 1655 | |0;ai-astar;108;170;170;117;115;137;157;118;115;116 04:57:20 INFO - PROCESS | 1655 | |1;audio-beat-detection;142;113;113;146;114;114;112;113;133;113 04:57:20 INFO - PROCESS | 1655 | |2;audio-dft;199;210;237;197;195;195;197;193;200;238 04:57:20 INFO - PROCESS | 1655 | |3;audio-fft;91;84;83;83;60;61;58;59;72;59 04:57:20 INFO - PROCESS | 1655 | |4;audio-oscillator;81;71;86;62;85;97;68;84;94;109 04:57:20 INFO - PROCESS | 1655 | |5;imaging-gaussian-blur;89;87;89;86;90;87;96;86;88;85 04:57:20 INFO - PROCESS | 1655 | |6;imaging-darkroom;100;113;99;103;127;173;101;139;169;148 04:57:20 INFO - PROCESS | 1655 | |7;imaging-desaturate;92;92;92;90;94;92;91;94;90;91 04:57:20 INFO - PROCESS | 1655 | |8;json-parse-financial;60;60;59;59;62;62;61;62;62;62 04:57:20 INFO - PROCESS | 1655 | |9;json-stringify-tinderbox;67;114;71;66;100;58;66;80;105;107 04:57:20 INFO - PROCESS | 1655 | |10;stanford-crypto-aes;88;89;92;102;87;91;84;90;91;89 04:57:20 INFO - PROCESS | 1655 | |11;stanford-crypto-ccm;144;142;141;147;153;147;155;166;218;168 04:57:20 INFO - PROCESS | 1655 | |12;stanford-crypto-pbkdf2;180;180;188;236;208;178;228;173;175;177 04:57:20 INFO - PROCESS | 1655 | |13;stanford-crypto-sha256-iterative;67;67;68;67;72;65;86;87;96;77 04:57:20 INFO - PROCESS | 1655 | __end_tp_report 04:57:20 INFO - PROCESS | 1655 | __start_cc_report 04:57:20 INFO - PROCESS | 1655 | _x_x_mozilla_cycle_collect,915 04:57:20 INFO - PROCESS | 1655 | __end_cc_report 04:57:20 INFO - PROCESS | 1655 | __startTimestamp1484917040893__endTimestamp 04:57:20 INFO - PROCESS | 1655 | 04:57:20 INFO - PROCESS | 1655 | ------- Summary: start ------- 04:57:20 INFO - PROCESS | 1655 | Number of tests: 14 04:57:20 INFO - PROCESS | 1655 | 04:57:20 INFO - PROCESS | 1655 | [#0] ai-astar Cycles:10 Average:132.30 Median:127.50 stddev:24.42 (19.2%) stddev-sans-first:24.27 04:57:20 INFO - PROCESS | 1655 | Values: 108.0 170.0 170.0 117.0 115.0 137.0 157.0 118.0 115.0 116.0 04:57:20 INFO - PROCESS | 1655 | 04:57:20 INFO - PROCESS | 1655 | [#1] audio-beat-detection Cycles:10 Average:121.30 Median:114.00 stddev:13.52 (11.9%) stddev-sans-first:12.08 04:57:20 INFO - PROCESS | 1655 | Values: 142.0 113.0 113.0 146.0 114.0 114.0 112.0 113.0 133.0 113.0 04:57:20 INFO - PROCESS | 1655 | 04:57:20 INFO - PROCESS | 1655 | [#2] audio-dft Cycles:10 Average:206.10 Median:199.50 stddev:17.19 (8.6%) stddev-sans-first:18.04 04:57:20 INFO - PROCESS | 1655 | Values: 199.0 210.0 237.0 197.0 195.0 195.0 197.0 193.0 200.0 238.0 04:57:20 INFO - PROCESS | 1655 | 04:57:20 INFO - PROCESS | 1655 | [#3] audio-fft Cycles:10 Average:71.00 Median:77.50 stddev:13.06 (16.9%) stddev-sans-first:11.68 04:57:20 INFO - PROCESS | 1655 | Values: 91.0 84.0 83.0 83.0 60.0 61.0 58.0 59.0 72.0 59.0 04:57:20 INFO - PROCESS | 1655 | 04:57:20 INFO - PROCESS | 1655 | [#4] audio-oscillator Cycles:10 Average:83.70 Median:84.50 stddev:14.21 (16.8%) stddev-sans-first:15.03 04:57:20 INFO - PROCESS | 1655 | Values: 81.0 71.0 86.0 62.0 85.0 97.0 68.0 84.0 94.0 109.0 04:57:20 INFO - PROCESS | 1655 | 04:57:20 INFO - PROCESS | 1655 | [#5] imaging-gaussian-blur Cycles:10 Average:88.30 Median:88.50 stddev:3.13 (3.5%) stddev-sans-first:3.31 04:57:20 INFO - PROCESS | 1655 | Values: 89.0 87.0 89.0 86.0 90.0 87.0 96.0 86.0 88.0 85.0 04:57:20 INFO - PROCESS | 1655 | 04:57:20 INFO - PROCESS | 1655 | [#6] imaging-darkroom Cycles:10 Average:127.20 Median:143.50 stddev:28.72 (20.0%) stddev-sans-first:28.73 04:57:20 INFO - PROCESS | 1655 | Values: 100.0 113.0 99.0 103.0 127.0 173.0 101.0 139.0 169.0 148.0 04:57:20 INFO - PROCESS | 1655 | 04:57:20 INFO - PROCESS | 1655 | [#7] imaging-desaturate Cycles:10 Average:91.80 Median:92.00 stddev:1.40 (1.5%) stddev-sans-first:1.48 04:57:20 INFO - PROCESS | 1655 | Values: 92.0 92.0 92.0 90.0 94.0 92.0 91.0 94.0 90.0 91.0 04:57:20 INFO - PROCESS | 1655 | 04:57:20 INFO - PROCESS | 1655 | [#8] json-parse-financial Cycles:10 Average:60.90 Median:62.00 stddev:1.29 (2.1%) stddev-sans-first:1.32 04:57:20 INFO - PROCESS | 1655 | Values: 60.0 60.0 59.0 59.0 62.0 62.0 61.0 62.0 62.0 62.0 04:57:20 INFO - PROCESS | 1655 | 04:57:20 INFO - PROCESS | 1655 | [#9] json-stringify-tinderbox Cycles:10 Average:83.40 Median:66.00 stddev:20.87 (31.6%) stddev-sans-first:21.28 04:57:20 INFO - PROCESS | 1655 | Values: 67.0 114.0 71.0 66.0 100.0 58.0 66.0 80.0 105.0 107.0 04:57:20 INFO - PROCESS | 1655 | 04:57:20 INFO - PROCESS | 1655 | [#10] stanford-crypto-aes Cycles:10 Average:90.30 Median:89.50 stddev:4.72 (5.3%) stddev-sans-first:4.93 04:57:20 INFO - PROCESS | 1655 | Values: 88.0 89.0 92.0 102.0 87.0 91.0 84.0 90.0 91.0 89.0 04:57:20 INFO - PROCESS | 1655 | 04:57:20 INFO - PROCESS | 1655 | [#11] stanford-crypto-ccm Cycles:10 Average:158.10 Median:154.00 stddev:23.05 (15.0%) stddev-sans-first:23.87 04:57:20 INFO - PROCESS | 1655 | Values: 144.0 142.0 141.0 147.0 153.0 147.0 155.0 166.0 218.0 168.0 04:57:20 INFO - PROCESS | 1655 | 04:57:20 INFO - PROCESS | 1655 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:192.30 Median:184.00 stddev:23.24 (12.6%) stddev-sans-first:24.22 04:57:20 INFO - PROCESS | 1655 | Values: 180.0 180.0 188.0 236.0 208.0 178.0 228.0 173.0 175.0 177.0 04:57:20 INFO - PROCESS | 1655 | 04:57:20 INFO - PROCESS | 1655 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:75.20 Median:74.50 stddev:10.85 (14.6%) stddev-sans-first:11.10 04:57:20 INFO - PROCESS | 1655 | Values: 67.0 67.0 68.0 67.0 72.0 65.0 86.0 87.0 96.0 77.0 04:57:20 INFO - PROCESS | 1655 | -------- Summary: end -------- 04:57:20 INFO - PROCESS | 1655 | 04:57:21 INFO - TEST-INFO | 1655: exit 0 04:57:21 INFO - TEST-OK | kraken | took 60875ms 04:57:21 INFO - SUITE-END | took 1176s 04:57:22 INFO - Completed test suite (00:19:37) 04:57:22 INFO - javascript benchmark 04:57:22 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7230.30042404065, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3019.927925586418, 3416.572928383952, 3426.486027095936, 3429.2112814052766, 3444.914851821676, 5615.1857980592895, 5623.943760562393, 5646.943530564693, 5662.660240385575, 5736.254286942692, 5611.719414029292, 5649.0, 5695.373508914016, 5697.1169468732305, 5802.535797136219, 5136.306598609177, 5144.639875208737, 5201.791928322863, 5236.842894713165, 5292.470752924715, 5170.508801663855, 5248.5538729208065, 5280.356553977762, 5297.788088476456, 5337.70642614656, 5057.140286151363, 5122.20605806097, 5171.198464238011, 5172.767225474862, 5202.92195617066, 5113.258577506278, 5148.304978827848, 5157.071727089123, 5173.430922598511, 5177.767000484987, 5137.717425541594, 5161.329027226437, 5180.611454140954, 5189.455107213726, 5203.375594928595, 5413.810516631899, 5582.665040097607, 5623.297087864017, 5638.04153293941, 5645.717714114319, 6122.969385153046, 6132.662703551303, 6204.534659900525, 6242.87514249717, 6243.968780156116, 6213.347598502138, 6224.968875155595, 6235.064740288947, 6238.719257633417, 6291.591046581958, 5778.162166485878, 5821.708914554255, 5838.67887266196, 5853.5317174625925, 5894.646321220741, 6252.874942501125, 6314.116023756677, 6350.33321501245, 6359.20509936258, 6364.077208804697, 6002.069679199731, 6048.576599637983, 6066.636001839904, 6120.877582448326, 6127.92262824669, 5213.296205012275, 6148.815535533941, 6156.230471191101, 6156.969215153896, 6212.844678882973, 6129.63387754696, 6143.72682107623, 6146.2175968969395, 6163.84590385235, 6178.227721534809, 5184.9740751296, 6189.257289125244, 6232.781852635136, 6243.625382477066, 6267.2479302484, 4873.049755297754, 4928.753562321869, 4941.407031156285, 4956.950430495649, 4963.429205641398, 4398.64810815134, 4404.757738324423, 4418.7127836690515, 4429.0, 4458.7324760514475, 5802.65184088956, 5825.0679891217205, 5839.211706419664, 5894.440028197314, 5923.822285331447, 6271.247450305992, 6283.02613094971, 6293.842653933688, 6299.6535190564955, 6304.401081897213, 5670.461306175906, 5676.631018983835, 5730.054541000688, 5823.4467725566, 5875.970620146957, 5391.083515802245, 5416.620836541483, 5421.159720243369, 5448.645838020516, 5449.918251226235, 5357.4106848246665, 5839.124131380327, 5962.999999999914, 6400.743970241138, 6409.4872410206135, 5308.522232999048, 6272.310045895039, 6278.246610406598, 6292.182016337847, 6323.462505687068, 5171.560417364566, 6259.123722678738, 6261.467775239155, 6365.35837627398, 6412.166418365583, 6116.396640261158, 6133.186900958431, 6136.610985192662, 6146.569740118149, 6147.262328520606, 6083.695815209133, 6188.381161883775, 6193.876122477435, 6199.535034872493, 6203.193584833943, 5987.5808693391045, 5988.710706877287, 6045.0, 6118.449339559461, 6174.567780255339, 6039.607425517327, 6133.351973717037, 6139.338817383011, 6139.6454669416935, 6149.815505534841, 5717.199592057199, 5843.620164689281, 5918.230630018243, 5940.673262970578, 6022.668753218615, 6113.327533971171, 6136.447719705248, 6143.938560614337, 6145.84635384119, 6189.381061893775, 5826.242588463472, 5851.616512808141, 5897.882042359214, 5903.0, 6041.305249896318], "value": 5667.678470251026, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [445.7414699474305, 457.39852094495717, 509.06332348478804, 514.5240652396534, 518.1166111779416, 38444.42333364998, 40836.59163408365, 40937.18125637485, 41029.79485102578, 41100.794496027556, 42021.789891050576, 42518.57481425185, 42586.78706606463, 42641.360379594254, 42664.0, 9206.769830754234, 9420.858687119699, 9430.387024843363, 10665.680029599125, 11028.393438360887, 10077.589137520754, 10251.692449226497, 11009.669709908716, 11054.336739795597, 11119.054880335181, 9407.952960235189, 11045.171612129083, 11095.72260693485, 11121.610743623974, 11130.38782866942, 10975.2866063706, 11044.502997365138, 11050.668479945614, 11056.391898445583, 11056.447177641086, 10889.945550272198, 10960.506777195045, 10981.945090274578, 10986.615468458565, 10995.120390368751, 36452.0, 36463.0, 36710.08224794402, 36900.815495922616, 36958.63041369605, 3985.4021896715435, 4017.27689015977, 4025.9597404025876, 4037.7173597848164, 4043.3934909763475, 27754.861225693945, 28217.0, 28416.28959276014, 28419.71580284212, 28460.0, 3246.9188270293193, 3272.181954511372, 3287.5068739689, 3288.0300311407955, 3349.6936194884015, 433084.0, 463840.0, 466235.0, 468327.0, 468618.6569067167, 46676.7666161667, 54518.90962180813, 55258.0, 55576.72211638916, 58294.7085264571, 57284.71357643268, 57905.420945790844, 58134.12798808021, 58256.708716456145, 58290.708546457, 12146.696332591757, 12377.690557736129, 13935.88512918964, 15058.247087645574, 15183.316750746242, 8968.730938071869, 10580.470976451146, 11522.539098436137, 12018.699532511757, 12031.578894738643, 14093.436262549589, 14142.787858182135, 17656.911715441598, 17730.0, 17743.73384399235, 6813.581178681596, 7952.324052455606, 7969.242921922408, 8036.598170091472, 8053.15441878606, 744.3524134003452, 754.4680999895061, 860.6901515454431, 876.6493402638868, 883.5317281840635, 23105.0, 25454.872725636254, 28363.0, 29765.553516696833, 29859.0, 1061.8619579454776, 1066.6053560182781, 1100.5377741348652, 1101.2236373356764, 1221.9450124744408, 12557.686057848445, 13936.372863221179, 14028.737413632822, 15318.463853765064, 15824.0, 568420.0, 570240.0, 570520.0, 572121.0, 572520.0, 59060.0, 59285.70357148186, 59730.80538389208, 59759.70120149371, 59855.102173467436, 1405.4097279142784, 1451.6951440197677, 1462.6782107936237, 1463.0782606957548, 1470.0885451020222, 29066.837326506702, 29148.7085129146, 29158.27104322409, 29858.552121717756, 29881.5517767238, 49447.50552494573, 49992.50022499331, 49996.50003499918, 50019.74990125026, 50080.499195007586, 411610.0, 440755.0, 519013.0, 524770.0, 524976.0, 24224.273271801874, 24396.878015609807, 24837.751622483545, 24958.37604059913, 25440.363990900376, 35748.821255894596, 42128.78935605302, 42563.0, 43090.353644694726, 43250.78374608233, 27472.862635686695, 35336.29327413386, 35412.0, 36275.81862090673, 36369.636303636624, 34912.30175396529, 34916.82541587276, 35705.92882213538, 35956.640433596374, 36027.459588105674, 28010.71989280081, 33451.49822752612, 34084.659153408145, 34126.48810267897, 34208.657913420546, 26263.868680656473, 29560.852195738884, 31187.629236717403, 31216.74918602557, 31371.0, 23196.88401558049, 23677.17129900445, 23700.4074898122, 23728.050877964688, 23824.047038118973], "value": 19207.407432449934, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [389.30120433821287, 393.44327776196684, 401.9316716158253, 403.97189153604086, 425.8969269591757, 17927.10364481774, 17951.102444877768, 20189.293374731886, 21412.57174856502, 21481.0, 17115.657686846254, 21050.684239736416, 21612.351629451103, 21756.0, 21789.564208715856, 5078.4413714491375, 5457.536109430694, 5637.4644408781305, 5849.532037437016, 5914.408559144073, 5759.10733836254, 5967.776605795823, 5982.8803423931495, 6043.153958445799, 6049.848753781147, 5045.445001049881, 5830.271216097988, 5848.122781582754, 5922.289325280973, 5930.347661757225, 5005.874853128665, 5015.297858299841, 5669.971650141764, 5892.086726557391, 5904.2324497815225, 5194.428612852583, 5214.243934629458, 5216.243644671539, 5218.13900706383, 5236.764345604457, 19623.80376196234, 20944.895275523526, 25786.74213257881, 25906.352341191432, 26176.607350889753, 2719.4017316190407, 2825.265430987937, 2826.858657067138, 2830.915072547806, 2842.9431411371866, 11619.535218591247, 15641.217939102999, 15799.447019354382, 15809.941243129653, 16316.673666526722, 1510.4260381055128, 1833.6332733453362, 1836.192075486795, 1893.9621207575908, 1904.114586717183, 106824.46587767011, 119832.60334793256, 120541.7945820548, 120931.3953430236, 120960.0, 11565.421728913521, 11641.359725215025, 11696.649100526998, 11751.529938802523, 11846.170768046153, 14671.486497972519, 14683.265836708335, 14711.485098021518, 14714.705705886037, 14735.263236838115, 8401.747947561584, 8600.39797214189, 8675.089115642893, 8746.081661425585, 8762.0, 8435.072142064368, 8435.662573496991, 8442.0, 8495.575221239036, 8599.61301741423, 7204.991301217834, 7285.417166626763, 7330.8900366494545, 7336.413086953031, 7336.853262934711, 1910.5701217226012, 1951.2292644405381, 1952.1800843645703, 1952.8144826241485, 1953.4335042837702, 972.3242346569067, 1032.5750464359137, 1037.7924415117006, 1061.8107719354373, 1089.210074697202, 21798.324579284985, 21901.33090173447, 21973.681579105305, 22123.225687100876, 22140.0, 1064.4092528646675, 1069.9090577301017, 1075.1184029096223, 1115.8047341715167, 1118.3290025984506, 11147.275427097213, 11430.314181149157, 11512.30926144434, 11560.132990025782, 12152.939235303944, 510922.0, 681240.0, 682529.0, 699157.0, 708802.0, 131412.3429382847, 134840.0, 150555.0, 152577.0, 155922.0, 434.3528143066871, 461.72758072737247, 464.0834352154535, 464.4589053752408, 469.0782612167108, 10927.781444370909, 11055.72360690989, 11231.719207019563, 11496.885031149915, 11555.88444115548, 59872.0, 60244.397556023876, 62102.0, 62127.0, 62530.124096277184, 86429.0, 91853.0, 93275.53362233417, 93922.06077939134, 95568.0, 5832.066869300891, 6972.44220462372, 7858.720970918598, 8370.832583348178, 8791.296696264155, 12642.620721378373, 13290.401931912893, 13312.267825269702, 13488.662783430493, 13725.862741372459, 9374.296927730311, 11295.3787541686, 11299.548018079513, 11330.320180789178, 11560.034794085193, 9306.0, 11077.0, 11214.66356009321, 11552.826707599224, 11769.352685602375, 11502.0, 11513.481893314989, 11754.353510557, 11759.05927525813, 11838.348890811088, 8342.874856877273, 8371.497710137412, 8634.159683715941, 8728.690696395592, 8865.202131808168, 8341.24928756415, 9976.301658883809, 11468.713282168013, 11559.711007224887, 11824.290542567473], "value": 9715.855539416698, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1323.8808507234346, 1442.8557144285573, 1455.1850963460463, 1465.6702241995545, 1488.293060796122, 7535.962320188405, 7767.067951845772, 8102.797430064252, 8540.743777686672, 8594.269487093588, 7990.400719946013, 8212.507249565031, 8286.08853026168, 8370.53962032088, 8399.11809260028, 2257.762934891833, 2304.225746175444, 2317.733460652021, 2318.1886339781026, 2319.7912187903084, 2059.1351632314463, 2145.839062070343, 2306.411864974429, 2332.9650055249185, 2348.131191459154, 2225.276785044859, 2298.689676893623, 2301.838871279011, 2305.8155347572156, 2306.1006207579057, 2282.8173746100274, 2290.965635515452, 2308.7114110736156, 2309.3533810533077, 2314.109068008812, 2256.165218869029, 2287.748347681754, 2293.520679161948, 2328.871912044837, 2337.0885354711677, 6816.795496135124, 7648.623247815391, 7679.886416469581, 8417.200365965286, 8523.659053637846, 4899.755012249373, 5135.537801597874, 5157.948420515821, 5248.081585722483, 5286.497782710637, 3310.536524886518, 3490.7381946354126, 3496.580410350749, 3571.053670777246, 3585.533880595532, 1016.7407311135687, 1147.833564133196, 1230.5262473947505, 1248.1699669719626, 1259.4521383198337, 9075.682351117744, 10741.248112632118, 10824.0, 10835.67492975212, 10850.891491085067, 4349.217140914602, 4632.189366860786, 4705.529447055267, 4709.62323014158, 4714.175019371596, 9541.379810312446, 9568.952155239318, 9582.568784404719, 9586.568604412818, 9609.90390096104, 4779.757263111616, 4912.38595175603, 5206.661566998133, 5260.0531904257605, 5279.894402112013, 4662.860114196579, 4751.738654374042, 4817.662763606514, 4883.291922671264, 4888.315635810989, 3779.7732136071922, 3807.333716599584, 3843.26977874203, 3886.028492876781, 3898.044978980168, 1161.7444162284453, 1209.625016244967, 1220.6521141474636, 1264.0330147437219, 1269.7841366967639, 878.3895192840914, 879.9032106468284, 895.9462432254086, 908.7909780750465, 920.1718453392001, 5411.269478620415, 5667.319921609434, 5679.488846003879, 5709.885802284014, 5717.456841600125, 961.9663311784054, 968.733598260484, 977.3647129365969, 977.7115750853516, 1000.5997600959674, 3743.6817870481314, 3793.019693762707, 3830.616938306147, 3847.5382954045135, 3858.344081506151, 9741.561629726537, 9999.800003999879, 10039.849402258971, 10090.394576325443, 10093.444860532594, 9918.371631517653, 10013.949930250448, 10020.348677336096, 10035.397876127456, 10036.799264014679, 7614.428917831185, 7654.349380302737, 7804.3756499479905, 7837.647305871249, 7877.960610197027, 5329.0, 5397.490998371564, 5480.177973304037, 5555.444455554574, 5657.773689052392, 9213.309001824757, 9366.06339366058, 9372.95313523428, 9384.530773461163, 9401.529923503935, 8958.283337333129, 9289.071092890657, 9337.813243734952, 9386.108319709616, 9390.812183756423, 5700.800471983461, 6049.790041991531, 7098.25468325839, 7250.637468126467, 7287.089113860768, 7120.928790712027, 7310.305520975498, 7724.652390642322, 7751.263629955145, 7820.413468989962, 6212.844678883064, 6373.7131829066875, 6476.643784591704, 6502.317256687979, 6883.586984780929, 6742.73029078851, 7017.473689473412, 7149.53528020677, 7149.749758758627, 7198.424126070006, 5491.450854914477, 6227.252729672468, 6283.874322513616, 6399.0, 6407.423331900151, 4054.8580799671868, 4976.154053810788, 4988.775505102351, 5072.873178170576, 5086.9745651271505, 4226.64073553739, 5010.1421905133575, 5011.5489605935645, 5208.9739551302, 5221.869453263699], "value": 4381.18385727115, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [276.6141232979993, 349.95275637788893, 363.1194353692296, 371.89586915663625, 373.28515892066696, 4334.978325108378, 4656.462228085693, 5104.821331253412, 5303.893922121555, 5394.271773310602, 5315.73421328933, 5369.382521010085, 5370.462953704628, 5388.838334849961, 5407.242985981956, 3844.2888065707793, 4172.895677608061, 4236.665450383135, 4244.236037513247, 4271.594198551148, 3454.2573346730446, 3587.856485740567, 3817.618238176174, 4056.2293164298835, 4128.442660240859, 3279.8688052477873, 3655.305491956533, 4041.9191616167805, 4058.715889887709, 4070.743405275782, 3477.7739446935866, 3913.9823645852, 4038.737482063671, 4059.5128584569748, 4067.5525692173837, 3189.378071276102, 3374.7806392584403, 3503.807290599016, 3505.141240396094, 3506.6318036606044, 5088.974555127201, 5090.1855703087695, 5090.778213228798, 5093.592512598983, 5174.31834653739, 4102.897427564305, 4471.8434854779925, 4592.88517787058, 4628.310666606698, 4640.236710050193, 5012.323336349583, 5015.498450154992, 5042.218456139304, 5689.260396148474, 5717.742701578397, 3672.676228013652, 3721.5162028936065, 3837.865674701372, 3840.8463661453507, 3872.6320999505283, 5425.674459532401, 5656.105204756412, 5777.826665200051, 5923.77154450821, 6043.274807023141, 3848.0956975110826, 3887.61123887609, 3940.842366305316, 3948.861789837342, 3974.7813870236837, 3983.780892050907, 4015.5582885883114, 4020.8287524736897, 4034.536028356718, 4044.2315959967514, 4486.394336764561, 4691.247002398068, 4700.647451441156, 4711.505291944297, 4720.103180395714, 4696.8121275148615, 5123.487651234846, 5756.798512052058, 5815.941840581625, 5824.932149087098, 1097.7914196302677, 1194.3431112887877, 1271.9951238521649, 1276.259769333784, 1295.404114107521, 775.8913752074714, 830.588858515039, 870.7170169694748, 884.9867251991225, 887.4292502270944, 4333.739975601411, 4854.0, 4904.94543673112, 5103.8724031898755, 5113.71874546896, 5293.168155037037, 5299.894002119936, 5423.403425623178, 5663.575231857627, 5731.971340143273, 4439.489458712224, 4530.411046563926, 4851.296561998491, 5097.6941383517105, 5145.430643653722, 2723.945521089567, 2751.857978938718, 2763.632002158944, 2977.985110074436, 2980.344324248662, 5135.435102138762, 5369.140937449989, 5475.112670004341, 5614.550835933197, 5925.644461332334, 5128.769205385766, 5372.892542149135, 5396.406395296514, 5696.487316141484, 5766.509846662997, 5176.8964620707375, 5207.682581251307, 5286.959998001242, 5827.388124246978, 5854.531637468992, 5259.368875734936, 5291.470852914677, 5356.517913387658, 5485.286912701324, 5682.886342273048, 4868.951310486991, 5418.756155972912, 5752.626079304831, 5809.414810409327, 5925.52595792327, 4999.45006049327, 5808.344621782771, 5920.141579471038, 6007.279126504847, 6221.262644436614, 3481.5299934508516, 3969.0430629181446, 3973.344398174269, 3983.5618082011533, 3984.900377490586, 5480.808171713971, 5938.257717785277, 6006.0, 6020.458158765732, 6038.305594856472, 5595.399751947531, 5626.803061892814, 5695.373508914099, 5734.168545560954, 5786.33457152416, 4989.827390563288, 5584.347434968357, 5670.716464176857, 5724.026915424304, 5800.216970708901, 5304.628675992643, 5621.775128994795, 5647.181158731879, 5688.886222275614, 5689.971550142388, 5154.587632989277, 5415.945840541544, 5568.774869528697, 5759.971200143972, 5842.0, 4835.419749630067, 5682.448469706434, 5702.372738998624, 5719.170720245458, 5738.540916726735], "value": 4092.342494808309, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [703.8627467643805, 715.749487679312, 768.69636493585, 826.128434501601, 834.103338910671, 15102.093874367545, 15796.684066318667, 15818.683626327467, 15863.920680396597, 15876.682466350667, 14529.709405811904, 14651.0, 14689.559313220594, 14689.779653305182, 14692.265386730674, 692.0760784352894, 714.3927661487746, 854.4147259127486, 867.7830542364425, 883.2845395229884, 872.2978002707849, 876.9517676527788, 887.1970866365917, 889.4529864133543, 889.6219106879582, 945.8297506448837, 947.2280091725256, 948.2129832239225, 948.5257371314343, 950.771814764455, 936.9484678342689, 938.2962777916563, 939.7321763297462, 939.802641445294, 942.0202988891544, 686.7012849410472, 695.1935754524777, 697.5814511293183, 705.4180301251488, 706.8268274272784, 14961.925190374088, 15151.393944242218, 15336.463223787225, 15577.532674019683, 15937.521874343789, 13214.33928303581, 13414.26221557814, 13554.118982266122, 13590.864091359059, 13676.0, 11732.53069877204, 11751.588694395741, 11763.58827441044, 11880.524779008829, 12269.509219631203, 3150.5116706910467, 3750.8124593770203, 3757.1358587525024, 3781.2437512497336, 3811.6188381161937, 84933.57533212357, 98125.5281170783, 101148.49425752898, 101539.99999999926, 103085.14520830126, 9453.243740500744, 11225.71935701614, 11266.7183320416, 11299.322040677585, 11668.533258669728, 17554.122293885255, 21912.342629721134, 23093.769062309162, 23225.535489290458, 23260.06959721626, 7385.889211661829, 7854.685812567547, 7925.445218834626, 7943.364530837519, 8140.308073813792, 4237.512686041083, 4319.850194726929, 4362.476502819619, 4392.846250381222, 4417.337399390053, 3550.201204728967, 3592.586852511942, 3648.1244501319493, 3652.41561350188, 3660.743747937619, 640.7981485831948, 648.0344287012412, 653.323809856796, 654.4175683641563, 669.5547460938519, 598.7036416973539, 679.0798468075785, 681.0159319782965, 694.399344566946, 706.1561434086218, 9921.404715716935, 10267.127294179929, 10310.701499133409, 10355.17158627308, 10576.471176441148, 680.1633990191992, 683.6274230544366, 687.4500399680336, 692.8441100752291, 709.3225969199402, 4635.675502714845, 4968.552830245295, 4980.551750342486, 4987.276844857475, 4994.80020799164, 90908.0, 95515.52242238744, 96461.51769241109, 98141.50929245308, 100378.49810750899, 90873.54563227274, 91648.0, 92073.0, 92596.0, 93195.53402232945, 80348.0, 81441.59279203684, 81534.0, 81939.0, 83149.58425207835, 9597.712068637951, 9617.374870633386, 9618.086281803218, 9639.373440726331, 9646.131848133702, 54708.72645636746, 54829.72585137049, 54853.0, 54945.1758223635, 55447.0, 68430.65784671044, 69987.0, 70420.23949401306, 70611.9408208867, 72379.0, 9062.682806101755, 11287.887121129012, 11303.039241664223, 11931.985781208346, 12199.329036903055, 12552.246865188117, 12852.614421567368, 13091.149075310075, 13901.652458688613, 15510.84489155094, 13700.177989320673, 13903.652408689864, 14177.291135443393, 14295.71408571802, 14718.337674804445, 11578.768424631291, 13414.664633384244, 13775.86224137786, 13902.096363736326, 13953.302334883418, 13218.140820846615, 13518.0, 13826.170429774245, 13847.861521384657, 13946.790798137834, 9152.359334846496, 10072.787156027052, 10827.187960902806, 11335.886641133484, 11610.825837612612, 9881.752956176153, 10265.332753371007, 10843.240973131806, 11021.283616564897, 11083.944580277048], "value": 7533.090273791574, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1581.8999999999999, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [108.0, 170.0, 170.0, 117.0, 115.0, 137.0, 157.0, 118.0, 115.0, 116.0], "value": 132.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 113.0, 113.0, 146.0, 114.0, 114.0, 112.0, 113.0, 133.0, 113.0], "value": 121.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [199.0, 210.0, 237.0, 197.0, 195.0, 195.0, 197.0, 193.0, 200.0, 238.0], "value": 206.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 84.0, 83.0, 83.0, 60.0, 61.0, 58.0, 59.0, 72.0, 59.0], "value": 71.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 71.0, 86.0, 62.0, 85.0, 97.0, 68.0, 84.0, 94.0, 109.0], "value": 83.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 87.0, 89.0, 86.0, 90.0, 87.0, 96.0, 86.0, 88.0, 85.0], "value": 88.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [100.0, 113.0, 99.0, 103.0, 127.0, 173.0, 101.0, 139.0, 169.0, 148.0], "value": 127.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 92.0, 92.0, 90.0, 94.0, 92.0, 91.0, 94.0, 90.0, 91.0], "value": 91.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 60.0, 59.0, 59.0, 62.0, 62.0, 61.0, 62.0, 62.0, 62.0], "value": 60.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 114.0, 71.0, 66.0, 100.0, 58.0, 66.0, 80.0, 105.0, 107.0], "value": 83.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 89.0, 92.0, 102.0, 87.0, 91.0, 84.0, 90.0, 91.0, 89.0], "value": 90.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 142.0, 141.0, 147.0, 153.0, 147.0, 155.0, 166.0, 218.0, 168.0], "value": 158.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [180.0, 180.0, 188.0, 236.0, 208.0, 178.0, 228.0, 173.0, 175.0, 177.0], "value": 192.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 67.0, 68.0, 67.0, 72.0, 65.0, 86.0, 87.0, 96.0, 77.0], "value": 75.2, "unit": "score"}]}]} 04:57:22 INFO - Return code: 0 04:57:22 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 04:57:22 ERROR - # TBPL SUCCESS # 04:57:22 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 04:57:22 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 04:57:22 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 04:57:22 INFO - 2017-01-20 04:57:22.190 screenresolution[1660:10448] starting screenresolution argv=screenresolution get 04:57:22 INFO - 2017-01-20 04:57:22.193 screenresolution[1660:10448] Display 0: 1600x1200x32@60 04:57:22 INFO - 2017-01-20 04:57:22.199 screenresolution[1661:10451] starting screenresolution argv=screenresolution list 04:57:22 INFO - Available Modes on Display 0 04:57:22 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 04:57:22 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 04:57:22 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 04:57:22 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 04:57:22 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 04:57:22 INFO - Intel Iris: 04:57:22 INFO - Chipset Model: Intel Iris 04:57:22 INFO - Type: GPU 04:57:22 INFO - Bus: Built-In 04:57:22 INFO - VRAM (Dynamic, Max): 1536 MB 04:57:22 INFO - Vendor: Intel (0x8086) 04:57:22 INFO - Device ID: 0x0a2e 04:57:22 INFO - Revision ID: 0x0009 04:57:22 INFO - Displays: 04:57:22 INFO - Display: 04:57:22 INFO - Resolution: 1600 x 1200 @ 60 Hz 04:57:22 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 04:57:22 INFO - Display Serial Number: 1600x1200 60 04:57:22 INFO - Main Display: Yes 04:57:22 INFO - Mirror: Off 04:57:22 INFO - Online: Yes 04:57:22 INFO - Rotation: Supported 04:57:22 INFO - Return code: 0 04:57:22 INFO - Running post-action listener: _resource_record_post_action 04:57:22 INFO - [mozharness: 2017-01-20 12:57:22.436661Z] Finished run-tests step (success) 04:57:22 INFO - Running post-run listener: _resource_record_post_run 04:57:22 INFO - Total resource usage - Wall time: 1199s; CPU: 54.0%; Read bytes: 38746112; Write bytes: 553480192; Read time: 261; Write time: 1665 04:57:22 INFO - TinderboxPrint: CPU usage
54.3% 04:57:22 INFO - TinderboxPrint: I/O read bytes / time
38,746,112 / 261 04:57:22 INFO - TinderboxPrint: I/O write bytes / time
553,480,192 / 1,665 04:57:22 INFO - TinderboxPrint: CPU idle
2,192.1 (45.7%) 04:57:22 INFO - TinderboxPrint: CPU system
963.0 (20.1%) 04:57:22 INFO - TinderboxPrint: CPU user
1,642.5 (34.2%) 04:57:22 INFO - TinderboxPrint: Swap in / out
290,209,792 / 0 04:57:22 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 247147008; Write bytes: 241072128; Read time: 17972; Write time: 505 04:57:22 INFO - run-tests - Wall time: 1178s; CPU: 54.0%; Read bytes: 36620288; Write bytes: 298854400; Read time: 240; Write time: 1123 04:57:22 INFO - Running post-run listener: _upload_blobber_files 04:57:22 INFO - Blob upload gear active. 04:57:22 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 04:57:22 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 04:57:22 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'] 04:57:22 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 04:57:23 INFO - (blobuploader) - INFO - Open directory for files ... 04:57:23 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 04:57:23 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:57:23 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:57:23 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 04:57:23 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:57:23 INFO - (blobuploader) - INFO - Done attempting. 04:57:23 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 04:57:23 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:57:23 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:57:25 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 04:57:25 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:57:25 INFO - (blobuploader) - INFO - Done attempting. 04:57:25 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 04:57:25 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:57:25 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:57:26 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 04:57:26 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:57:26 INFO - (blobuploader) - INFO - Done attempting. 04:57:26 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 04:57:26 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:57:26 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:57:28 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 04:57:28 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:57:28 INFO - (blobuploader) - INFO - Done attempting. 04:57:28 INFO - (blobuploader) - INFO - Iteration through files over. 04:57:28 INFO - Return code: 0 04:57:28 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 04:57:28 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 04:57:28 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/cb2d6cc8e25d5edcb2cc6b2594a96d6502b621308922e887bd306259b7c88ffa96acd8bdd3cb14d136195a56f4c9c5aecf11451079e63314b813136a5728ae23", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f29a1bb9e5b68dcf0348bccd724c1deeb201201dddf8eab70b0937ef92f114673ebc790e5a052c80ed5b21ee809ded65fc6ec6366e15e98add5573e6cd68df9a", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/841c4b2f2f650801f72da42bdb622899401af5b8c2f178d5a586027e6beb619d7a427bed9226472fe461aa548f58d45a385366d0e28406610430338c6e8bde81", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b459f8a6f19b7b0474345c0328fb72a735b2c59396b9ab77f1162cf7482e5f6426d468f29a98c61fd4557a3ec0b3ce1dac7c7aca29bd3155f8ef4303b73326f7"} 04:57:28 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 04:57:28 INFO - Writing to file /builds/slave/test/properties/blobber_files 04:57:28 INFO - Contents: 04:57:28 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/cb2d6cc8e25d5edcb2cc6b2594a96d6502b621308922e887bd306259b7c88ffa96acd8bdd3cb14d136195a56f4c9c5aecf11451079e63314b813136a5728ae23", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f29a1bb9e5b68dcf0348bccd724c1deeb201201dddf8eab70b0937ef92f114673ebc790e5a052c80ed5b21ee809ded65fc6ec6366e15e98add5573e6cd68df9a", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/841c4b2f2f650801f72da42bdb622899401af5b8c2f178d5a586027e6beb619d7a427bed9226472fe461aa548f58d45a385366d0e28406610430338c6e8bde81", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b459f8a6f19b7b0474345c0328fb72a735b2c59396b9ab77f1162cf7482e5f6426d468f29a98c61fd4557a3ec0b3ce1dac7c7aca29bd3155f8ef4303b73326f7"} 04:57:28 INFO - Running post-run listener: copy_logs_to_upload_dir 04:57:28 INFO - Copying logs to upload dir... 04:57:28 INFO - mkdir: /builds/slave/test/build/upload/logs 04:57:28 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1269.031637 ========= master_lag: 0.08 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 9 secs) (at 2017-01-20 04:57:28.945433) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-20 04:57:28.949197) ========= 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.KJfA1Iak7d/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.KfyCe3RwKA/Listeners TMPDIR=/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/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/cb2d6cc8e25d5edcb2cc6b2594a96d6502b621308922e887bd306259b7c88ffa96acd8bdd3cb14d136195a56f4c9c5aecf11451079e63314b813136a5728ae23", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f29a1bb9e5b68dcf0348bccd724c1deeb201201dddf8eab70b0937ef92f114673ebc790e5a052c80ed5b21ee809ded65fc6ec6366e15e98add5573e6cd68df9a", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/841c4b2f2f650801f72da42bdb622899401af5b8c2f178d5a586027e6beb619d7a427bed9226472fe461aa548f58d45a385366d0e28406610430338c6e8bde81", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b459f8a6f19b7b0474345c0328fb72a735b2c59396b9ab77f1162cf7482e5f6426d468f29a98c61fd4557a3ec0b3ce1dac7c7aca29bd3155f8ef4303b73326f7"} build_url:https://queue.taskcluster.net/v1/task/Sy2_jHf2QdaPvKwW2eO5Hg/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010976 build_url: 'https://queue.taskcluster.net/v1/task/Sy2_jHf2QdaPvKwW2eO5Hg/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/cb2d6cc8e25d5edcb2cc6b2594a96d6502b621308922e887bd306259b7c88ffa96acd8bdd3cb14d136195a56f4c9c5aecf11451079e63314b813136a5728ae23", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f29a1bb9e5b68dcf0348bccd724c1deeb201201dddf8eab70b0937ef92f114673ebc790e5a052c80ed5b21ee809ded65fc6ec6366e15e98add5573e6cd68df9a", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/841c4b2f2f650801f72da42bdb622899401af5b8c2f178d5a586027e6beb619d7a427bed9226472fe461aa548f58d45a385366d0e28406610430338c6e8bde81", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b459f8a6f19b7b0474345c0328fb72a735b2c59396b9ab77f1162cf7482e5f6426d468f29a98c61fd4557a3ec0b3ce1dac7c7aca29bd3155f8ef4303b73326f7"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-20 04:57:28.985062) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-20 04:57:28.985451) ========= 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.KJfA1Iak7d/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.KfyCe3RwKA/Listeners TMPDIR=/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/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.004984 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-20 04:57:29.034898) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-20 04:57:29.035298) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-20 04:57:29.035663) ========= ========= Total master_lag: 4.02 =========