builder: larch_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0237 starttime: 1486100434.69 results: success (0) buildid: 20170202171409 builduid: c967aa5d53f54635a4860429e0f9e250 revision: c8102da699dba43d85eb80b70956e80c6793feca ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-02-02 21:40:34.693553) ========= master: http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-02-02 21:40:34.693949) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-02 21:40:34.694232) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-02 21:40:34.718434) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-02 21:40:34.718769) ========= 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.FDZteo8hlx/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.MX62N04iGn/Listeners TMPDIR=/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-02-02 21:40:34-- 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% 9.75M=0.001s 2017-02-02 21:40:34 (9.75 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.128612 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-02 21:40:34.870880) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-02 21:40:34.871194) ========= 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.FDZteo8hlx/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.MX62N04iGn/Listeners TMPDIR=/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/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.021869 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-02 21:40:34.919847) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-02-02 21:40:34.920169) ========= bash -c 'python archiver_client.py mozharness --repo projects/larch --rev c8102da699dba43d85eb80b70956e80c6793feca --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo projects/larch --rev c8102da699dba43d85eb80b70956e80c6793feca --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.FDZteo8hlx/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.MX62N04iGn/Listeners TMPDIR=/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-02-02 21:40:35,014 truncating revision to first 12 chars 2017-02-02 21:40:35,014 Setting DEBUG logging. 2017-02-02 21:40:35,014 attempt 1/10 2017-02-02 21:40:35,014 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/projects/larch/c8102da699db?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-02-02 21:40:36,043 unpacking tar archive at: larch-c8102da699db/testing/mozharness/ program finished with exit code 0 elapsedTime=1.296513 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-02-02 21:40:36.239906) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-02 21:40:36.240238) ========= script_repo_revision: c8102da699dba43d85eb80b70956e80c6793feca ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-02 21:40:36.240639) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-02 21:40:36.240904) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-02 21:40:36.260238) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 12 secs) (at 2017-02-02 21:40:36.260507) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Larch --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Larch 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', 'Larch', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Larch'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.FDZteo8hlx/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.MX62N04iGn/Listeners TMPDIR=/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/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 21:40:36 INFO - MultiFileLogger online at 20170202 21:40:36 in /builds/slave/test 21:40:36 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Larch --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Larch 21:40:36 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 21:40:36 INFO - {'append_to_log': False, 21:40:36 INFO - 'base_work_dir': '/builds/slave/test', 21:40:36 INFO - 'blob_upload_branch': 'Larch', 21:40:36 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 21:40:36 INFO - 'branch': 'Larch', 21:40:36 INFO - 'buildbot_json_path': 'buildprops.json', 21:40:36 INFO - 'config_files': ('talos/mac_config.py',), 21:40:36 INFO - 'default_actions': ('clobber', 21:40:36 INFO - 'read-buildbot-config', 21:40:36 INFO - 'download-and-extract', 21:40:36 INFO - 'populate-webroot', 21:40:36 INFO - 'create-virtualenv', 21:40:36 INFO - 'install', 21:40:36 INFO - 'run-tests'), 21:40:36 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 21:40:36 INFO - 'download_minidump_stackwalk': True, 21:40:36 INFO - 'download_symbols': 'ondemand', 21:40:36 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 21:40:36 INFO - 'tooltool.py': '/tools/tooltool.py', 21:40:36 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 21:40:36 INFO - '/tools/misc-python/virtualenv.py')}, 21:40:36 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 21:40:36 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 21:40:36 INFO - 'installer_path': 'installer.exe', 21:40:36 INFO - 'log_level': 'info', 21:40:36 INFO - 'log_name': 'talos', 21:40:36 INFO - 'log_to_console': True, 21:40:36 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 21:40:36 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 21:40:36 INFO - 'opt_config_files': (), 21:40:36 INFO - 'pip_index': False, 21:40:36 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 21:40:36 INFO - 'cmd': ('bash', 21:40:36 INFO - '-c', 21:40:36 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 21:40:36 INFO - 'enabled': True, 21:40:36 INFO - 'halt_on_failure': False, 21:40:36 INFO - 'name': 'check_screen_resolution'},), 21:40:36 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 21:40:36 INFO - 'cmd': ('bash', 21:40:36 INFO - '-c', 21:40:36 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 21:40:36 INFO - 'enabled': True, 21:40:36 INFO - 'halt_on_failure': False, 21:40:36 INFO - 'name': 'check_screen_resolution'},), 21:40:36 INFO - 'run_cmd_checks_enabled': True, 21:40:36 INFO - 'sps_profile': False, 21:40:36 INFO - 'sps_profile_interval': 0, 21:40:36 INFO - 'suite': 'dromaeojs-e10s', 21:40:36 INFO - 'system_bits': '32', 21:40:36 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 21:40:36 INFO - 'title': 't-yosemite-r7-0237', 21:40:36 INFO - 'tooltool_cache': '/builds/tooltool_cache', 21:40:36 INFO - 'use_talos_json': True, 21:40:36 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 21:40:36 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 21:40:36 INFO - 'work_dir': 'build'} 21:40:36 INFO - [mozharness: 2017-02-03 05:40:36.475938Z] Running clobber step. 21:40:36 INFO - Running pre-action listener: _resource_record_pre_action 21:40:36 INFO - Running main action method: clobber 21:40:36 INFO - rmtree: /builds/slave/test/build 21:40:36 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 21:40:38 INFO - Running post-action listener: _resource_record_post_action 21:40:38 INFO - [mozharness: 2017-02-03 05:40:38.279307Z] Finished clobber step (success) 21:40:38 INFO - [mozharness: 2017-02-03 05:40:38.279533Z] Running read-buildbot-config step. 21:40:38 INFO - Running pre-action listener: _resource_record_pre_action 21:40:38 INFO - Running main action method: read_buildbot_config 21:40:38 INFO - Using buildbot properties: 21:40:38 INFO - { 21:40:38 INFO - "project": "", 21:40:38 INFO - "product": "firefox", 21:40:38 INFO - "script_repo_revision": "production", 21:40:38 INFO - "builddir": "larch_yosemite_r7_test-dromaeojs-e10s", 21:40:38 INFO - "repository": "", 21:40:38 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 larch talos dromaeojs-e10s", 21:40:38 INFO - "buildid": "20170202171409", 21:40:38 INFO - "pgo_build": "False", 21:40:38 INFO - "basedir": "/builds/slave/test", 21:40:38 INFO - "buildnumber": 0, 21:40:38 INFO - "slavename": "t-yosemite-r7-0237", 21:40:38 INFO - "master": "http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/", 21:40:38 INFO - "platform": "yosemite_r7", 21:40:38 INFO - "scheduler": "tests-larch-macosx64-talos", 21:40:38 INFO - "branch": "larch", 21:40:38 INFO - "repo_path": "projects/larch", 21:40:38 INFO - "revision": "c8102da699dba43d85eb80b70956e80c6793feca", 21:40:38 INFO - "stage_platform": "macosx64", 21:40:38 INFO - "builduid": "c967aa5d53f54635a4860429e0f9e250", 21:40:38 INFO - "slavebuilddir": "test" 21:40:38 INFO - } 21:40:38 INFO - Found installer url https://queue.taskcluster.net/v1/task/PSBt1TV9Ry2T6mPkN0c8Fw/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg. 21:40:38 INFO - Running post-action listener: _resource_record_post_action 21:40:38 INFO - [mozharness: 2017-02-03 05:40:38.285356Z] Finished read-buildbot-config step (success) 21:40:38 INFO - [mozharness: 2017-02-03 05:40:38.285591Z] Running download-and-extract step. 21:40:38 INFO - Running pre-action listener: _resource_record_pre_action 21:40:38 INFO - Running main action method: download_and_extract 21:40:38 INFO - mkdir: /builds/slave/test/build/tests 21:40:38 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')]} 21:40:38 INFO - trying https://queue.taskcluster.net/v1/task/PSBt1TV9Ry2T6mPkN0c8Fw/artifacts/public/build/firefox-54.0a1.en-US.mac.test_packages.json 21:40:38 INFO - Downloading https://queue.taskcluster.net/v1/task/PSBt1TV9Ry2T6mPkN0c8Fw/artifacts/public/build/firefox-54.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-54.0a1.en-US.mac.test_packages.json 21:40:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/PSBt1TV9Ry2T6mPkN0c8Fw/artifacts/public/build/firefox-54.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-54.0a1.en-US.mac.test_packages.json'}, attempt #1 21:40:39 INFO - Downloaded 1199 bytes. 21:40:39 INFO - Reading from file /builds/slave/test/build/firefox-54.0a1.en-US.mac.test_packages.json 21:40:39 INFO - Using the following test package requirements: 21:40:39 INFO - {u'common': [u'firefox-54.0a1.en-US.mac.common.tests.zip'], 21:40:39 INFO - u'cppunittest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 21:40:39 INFO - u'firefox-54.0a1.en-US.mac.cppunittest.tests.zip'], 21:40:39 INFO - u'gtest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 21:40:39 INFO - u'firefox-54.0a1.en-US.mac.gtest.tests.zip'], 21:40:39 INFO - u'jittest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 21:40:39 INFO - u'jsshell-mac.zip'], 21:40:39 INFO - u'mochitest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 21:40:39 INFO - u'firefox-54.0a1.en-US.mac.mochitest.tests.zip'], 21:40:39 INFO - u'mozbase': [u'firefox-54.0a1.en-US.mac.common.tests.zip'], 21:40:39 INFO - u'reftest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 21:40:39 INFO - u'firefox-54.0a1.en-US.mac.reftest.tests.zip'], 21:40:39 INFO - u'talos': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 21:40:39 INFO - u'firefox-54.0a1.en-US.mac.talos.tests.zip'], 21:40:39 INFO - u'web-platform': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 21:40:39 INFO - u'firefox-54.0a1.en-US.mac.web-platform.tests.zip'], 21:40:39 INFO - u'xpcshell': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 21:40:39 INFO - u'firefox-54.0a1.en-US.mac.xpcshell.tests.zip']} 21:40:39 INFO - Downloading packages: [u'firefox-54.0a1.en-US.mac.common.tests.zip'] for test suite category: common 21:40:39 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/PSBt1TV9Ry2T6mPkN0c8Fw/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip 21:40:39 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/PSBt1TV9Ry2T6mPkN0c8Fw/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip'}, attempt #1 21:40:39 INFO - Fetch https://queue.taskcluster.net/v1/task/PSBt1TV9Ry2T6mPkN0c8Fw/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip into memory 21:40:40 INFO - Expected file size: 34853121 21:40:40 INFO - Obtained file size: 34853121 21:40:47 INFO - Downloading packages: [u'firefox-54.0a1.en-US.mac.common.tests.zip', u'firefox-54.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 21:40:47 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/PSBt1TV9Ry2T6mPkN0c8Fw/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip 21:40:47 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/PSBt1TV9Ry2T6mPkN0c8Fw/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip'}, attempt #1 21:40:47 INFO - Fetch https://queue.taskcluster.net/v1/task/PSBt1TV9Ry2T6mPkN0c8Fw/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip into memory 21:40:48 INFO - Expected file size: 34853121 21:40:48 INFO - Obtained file size: 34853121 21:40:55 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/PSBt1TV9Ry2T6mPkN0c8Fw/artifacts/public/build/firefox-54.0a1.en-US.mac.talos.tests.zip 21:40:55 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/PSBt1TV9Ry2T6mPkN0c8Fw/artifacts/public/build/firefox-54.0a1.en-US.mac.talos.tests.zip'}, attempt #1 21:40:55 INFO - Fetch https://queue.taskcluster.net/v1/task/PSBt1TV9Ry2T6mPkN0c8Fw/artifacts/public/build/firefox-54.0a1.en-US.mac.talos.tests.zip into memory 21:40:55 INFO - Expected file size: 14403454 21:40:55 INFO - Obtained file size: 14403454 21:40: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')]} 21:40:56 INFO - trying https://queue.taskcluster.net/v1/task/PSBt1TV9Ry2T6mPkN0c8Fw/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg 21:40:56 INFO - Downloading https://queue.taskcluster.net/v1/task/PSBt1TV9Ry2T6mPkN0c8Fw/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-54.0a1.en-US.mac.dmg 21:40:56 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/PSBt1TV9Ry2T6mPkN0c8Fw/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-54.0a1.en-US.mac.dmg'}, attempt #1 21:40:58 INFO - Downloaded 70183674 bytes. 21:40:58 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/PSBt1TV9Ry2T6mPkN0c8Fw/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg 21:40:58 INFO - mkdir: /builds/slave/test/properties 21:40:58 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 21:40:58 INFO - Writing to file /builds/slave/test/properties/build_url 21:40:58 INFO - Contents: 21:40:58 INFO - build_url:https://queue.taskcluster.net/v1/task/PSBt1TV9Ry2T6mPkN0c8Fw/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg 21:40:58 INFO - Running post-action listener: _resource_record_post_action 21:40:58 INFO - Running post-action listener: set_extra_try_arguments 21:40:58 INFO - [mozharness: 2017-02-03 05:40:58.986840Z] Finished download-and-extract step (success) 21:40:58 INFO - [mozharness: 2017-02-03 05:40:58.986985Z] Running populate-webroot step. 21:40:58 INFO - Running pre-action listener: _resource_record_pre_action 21:40:58 INFO - Running main action method: populate_webroot 21:40:58 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 21:40:58 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 21:40:58 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 21:40:58 INFO - '--tppagecycles', 21:40:58 INFO - '7'], 21:40:58 INFO - 'tests': ['tsvgm']}}, 21:40:58 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 21:40:58 INFO - 'tests': ['tresize', 'tcanvasmark']}, 21:40:58 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 21:40:58 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 21:40:58 INFO - 'tests': ['dromaeo_css', 'kraken']}, 21:40:58 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 21:40:58 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 21:40:58 INFO - 'talos_options': ['--disable-e10s'], 21:40:58 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 21:40:58 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 21:40:58 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 21:40:58 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 21:40:58 INFO - 'talos_options': ['--disable-e10s'], 21:40:58 INFO - 'tests': ['damp', 'tps']}, 21:40:58 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 21:40:58 INFO - 'tests': ['damp', 'tps']}, 21:40:58 INFO - 'g3': {'talos_options': ['--disable-e10s'], 21:40:58 INFO - 'tests': ['dromaeo_dom']}, 21:40:58 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 21:40:58 INFO - 'g4': {'talos_options': ['--disable-e10s'], 21:40:58 INFO - 'tests': ['basic_compositor_video']}, 21:40:58 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 21:40:58 INFO - 'other': {'talos_options': ['--disable-e10s'], 21:40:58 INFO - 'tests': ['a11yr', 21:40:58 INFO - 'ts_paint', 21:40:58 INFO - 'tpaint', 21:40:58 INFO - 'sessionrestore', 21:40:58 INFO - 'sessionrestore_no_auto_restore', 21:40:58 INFO - 'tabpaint']}, 21:40:58 INFO - 'other-e10s': {'tests': ['a11yr', 21:40:58 INFO - 'ts_paint', 21:40:58 INFO - 'tpaint', 21:40:58 INFO - 'sessionrestore', 21:40:58 INFO - 'sessionrestore_no_auto_restore', 21:40:58 INFO - 'tabpaint']}, 21:40:58 INFO - 'other-e10s_l64': {'tests': ['a11yr', 21:40:58 INFO - 'ts_paint', 21:40:58 INFO - 'tpaint', 21:40:58 INFO - 'sessionrestore', 21:40:59 INFO - 'sessionrestore_no_auto_restore', 21:40:59 INFO - 'tabpaint']}, 21:40:59 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 21:40:59 INFO - 'ts_paint', 21:40:59 INFO - 'tpaint', 21:40:59 INFO - 'sessionrestore', 21:40:59 INFO - 'sessionrestore_no_auto_restore', 21:40:59 INFO - 'tabpaint']}, 21:40:59 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 21:40:59 INFO - 'tests': ['a11yr', 21:40:59 INFO - 'ts_paint', 21:40:59 INFO - 'tpaint', 21:40:59 INFO - 'sessionrestore', 21:40:59 INFO - 'sessionrestore_no_auto_restore', 21:40:59 INFO - 'tabpaint']}, 21:40:59 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 21:40:59 INFO - 'tests': ['a11yr', 21:40:59 INFO - 'ts_paint', 21:40:59 INFO - 'tpaint', 21:40:59 INFO - 'sessionrestore', 21:40:59 INFO - 'sessionrestore_no_auto_restore', 21:40:59 INFO - 'tabpaint']}, 21:40:59 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 21:40:59 INFO - 'tests': ['tsvgx', 21:40:59 INFO - 'tsvgr_opacity', 21:40:59 INFO - 'tart', 21:40:59 INFO - 'tscrollx', 21:40:59 INFO - 'cart', 21:40:59 INFO - 'tsvg_static']}, 21:40:59 INFO - 'svgr-e10s': {'tests': ['tsvgx', 21:40:59 INFO - 'tsvgr_opacity', 21:40:59 INFO - 'tart', 21:40:59 INFO - 'tscrollx', 21:40:59 INFO - 'cart', 21:40:59 INFO - 'tsvg_static']}, 21:40:59 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 21:40:59 INFO - 'talos_options': ['--disable-e10s'], 21:40:59 INFO - 'tests': ['tp5o']}, 21:40:59 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 21:40:59 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 21:40:59 INFO - 'talos_options': ['--disable-e10s', 21:40:59 INFO - '--xperf_path', 21:40:59 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 21:40:59 INFO - 'tests': ['tp5n']}, 21:40:59 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 21:40:59 INFO - 'talos_options': ['--xperf_path', 21:40:59 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 21:40:59 INFO - 'tests': ['tp5n']}}, 21:40:59 INFO - 'talos.zip': {'path': '', 21:40:59 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 21:40:59 INFO - Running post-action listener: _resource_record_post_action 21:40:59 INFO - [mozharness: 2017-02-03 05:40:59.005223Z] Finished populate-webroot step (success) 21:40:59 INFO - [mozharness: 2017-02-03 05:40:59.005350Z] Running create-virtualenv step. 21:40:59 INFO - Running pre-action listener: _resource_record_pre_action 21:40:59 INFO - Running main action method: create_virtualenv 21:40:59 INFO - Creating virtualenv /builds/slave/test/build/venv 21:40:59 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 21:40:59 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 21:40:59 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 21:40:59 INFO - Using real prefix '/tools/python27' 21:40:59 INFO - New python executable in /builds/slave/test/build/venv/bin/python 21:40:59 INFO - Installing distribute.............................................................................................................................................................................................done. 21:41:02 INFO - Installing pip.................done. 21:41:02 INFO - Return code: 0 21:41:02 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 21:41:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 21:41:02 INFO - Reading from file tmpfile_stdout 21:41:02 INFO - Output received: 21:41:02 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) 21:41:02 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 21:41:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:41:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:41:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:41:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:41:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:41:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:41:02 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10b2e7b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b2ea768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fefcad60330>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b0c5d30>, '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 0x10afecdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b0babe8>, '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.FDZteo8hlx/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.MX62N04iGn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/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 21:41:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 21:41:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 21:41:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FDZteo8hlx/Render', 21:41:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:41:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:41:02 INFO - 'HOME': '/Users/cltbld', 21:41:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:41:02 INFO - 'LOGNAME': 'cltbld', 21:41:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:41:02 INFO - 'MOZ_NO_REMOTE': '1', 21:41:02 INFO - 'NO_EM_RESTART': '1', 21:41:02 INFO - 'PAGER': '/bin/cat', 21:41:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:41:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:41:02 INFO - 'PWD': '/builds/slave/test', 21:41:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:41:02 INFO - 'SHELL': '/bin/bash', 21:41:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MX62N04iGn/Listeners', 21:41:02 INFO - 'TMPDIR': '/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/T/', 21:41:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:41:02 INFO - 'USER': 'cltbld', 21:41:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:41:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:41:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:41:02 INFO - 'XPC_FLAGS': '0x0', 21:41:02 INFO - 'XPC_SERVICE_NAME': '0', 21:41:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:41:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:41:03 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 21:41:03 INFO - Cleaning up... 21:41:03 INFO - Return code: 0 21:41:03 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 21:41:03 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')]} 21:41:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:41:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:41:03 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')]} 21:41:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:41:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:41:03 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 0x10b2e7b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b2ea768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fefcad60330>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b0c5d30>, '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 0x10afecdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b0babe8>, '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.FDZteo8hlx/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.MX62N04iGn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/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 21:41:03 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 21:41:03 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 21:41:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FDZteo8hlx/Render', 21:41:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:41:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:41:03 INFO - 'HOME': '/Users/cltbld', 21:41:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:41:03 INFO - 'LOGNAME': 'cltbld', 21:41:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:41:03 INFO - 'MOZ_NO_REMOTE': '1', 21:41:03 INFO - 'NO_EM_RESTART': '1', 21:41:03 INFO - 'PAGER': '/bin/cat', 21:41:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:41:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:41:03 INFO - 'PWD': '/builds/slave/test', 21:41:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:41:03 INFO - 'SHELL': '/bin/bash', 21:41:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MX62N04iGn/Listeners', 21:41:03 INFO - 'TMPDIR': '/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/T/', 21:41:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:41:03 INFO - 'USER': 'cltbld', 21:41:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:41:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:41:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:41:03 INFO - 'XPC_FLAGS': '0x0', 21:41:03 INFO - 'XPC_SERVICE_NAME': '0', 21:41:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:41:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:41:03 INFO - Downloading/unpacking psutil>=3.1.1 21:41: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 21:41: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 21:41: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 21:41: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 21:41: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 21:41: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 21:41:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 21:41:06 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 21:41:06 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 21:41:06 INFO - Installing collected packages: psutil 21:41:06 INFO - Running setup.py install for psutil 21:41:07 INFO - building 'psutil._psutil_osx' extension 21:41:07 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 21:41:07 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 21:41:07 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 21:41:07 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 21:41:07 INFO - building 'psutil._psutil_posix' extension 21:41:07 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 21:41:07 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 21:41:07 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 21:41:07 INFO - ^ 21:41:07 INFO - 1 warning generated. 21:41:07 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 21:41:07 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 21:41:07 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 21:41:07 INFO - Successfully installed psutil 21:41:07 INFO - Cleaning up... 21:41:07 INFO - Return code: 0 21:41:07 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 21:41:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:41:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:41:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:41:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:41:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:41:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:41:07 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10b2e7b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b2ea768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fefcad60330>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b0c5d30>, '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 0x10afecdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b0babe8>, '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.FDZteo8hlx/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.MX62N04iGn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/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 21:41:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 21:41:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 21:41:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FDZteo8hlx/Render', 21:41:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:41:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:41:07 INFO - 'HOME': '/Users/cltbld', 21:41:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:41:07 INFO - 'LOGNAME': 'cltbld', 21:41:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:41:07 INFO - 'MOZ_NO_REMOTE': '1', 21:41:07 INFO - 'NO_EM_RESTART': '1', 21:41:07 INFO - 'PAGER': '/bin/cat', 21:41:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:41:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:41:07 INFO - 'PWD': '/builds/slave/test', 21:41:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:41:07 INFO - 'SHELL': '/bin/bash', 21:41:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MX62N04iGn/Listeners', 21:41:07 INFO - 'TMPDIR': '/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/T/', 21:41:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:41:07 INFO - 'USER': 'cltbld', 21:41:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:41:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:41:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:41:07 INFO - 'XPC_FLAGS': '0x0', 21:41:07 INFO - 'XPC_SERVICE_NAME': '0', 21:41:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:41:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:41:08 INFO - Downloading/unpacking mozsystemmonitor==0.3 21:41: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 21:41: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 21:41: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 21:41: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 21:41: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 21:41: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 21:41:10 INFO - Downloading mozsystemmonitor-0.3.tar.gz 21:41:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 21:41:10 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 21:41:10 INFO - Installing collected packages: mozsystemmonitor 21:41:10 INFO - Running setup.py install for mozsystemmonitor 21:41:11 INFO - Successfully installed mozsystemmonitor 21:41:11 INFO - Cleaning up... 21:41:11 INFO - Return code: 0 21:41:11 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 21:41: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')]} 21:41:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:41:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:41: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')]} 21:41:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:41:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:41:11 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 0x10b2e7b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b2ea768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fefcad60330>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b0c5d30>, '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 0x10afecdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b0babe8>, '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.FDZteo8hlx/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.MX62N04iGn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/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 21:41:11 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 21:41:11 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 21:41:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FDZteo8hlx/Render', 21:41:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:41:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:41:11 INFO - 'HOME': '/Users/cltbld', 21:41:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:41:11 INFO - 'LOGNAME': 'cltbld', 21:41:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:41:11 INFO - 'MOZ_NO_REMOTE': '1', 21:41:11 INFO - 'NO_EM_RESTART': '1', 21:41:11 INFO - 'PAGER': '/bin/cat', 21:41:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:41:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:41:11 INFO - 'PWD': '/builds/slave/test', 21:41:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:41:11 INFO - 'SHELL': '/bin/bash', 21:41:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MX62N04iGn/Listeners', 21:41:11 INFO - 'TMPDIR': '/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/T/', 21:41:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:41:11 INFO - 'USER': 'cltbld', 21:41:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:41:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:41:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:41:11 INFO - 'XPC_FLAGS': '0x0', 21:41:11 INFO - 'XPC_SERVICE_NAME': '0', 21:41:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:41:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:41:11 INFO - Downloading/unpacking jsonschema==2.5.1 21:41:11 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 21:41:11 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 21:41:11 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 21:41:11 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 21:41:11 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 21:41:11 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 21:41:14 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 21:41:14 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 21:41:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:41:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:41:14 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:41:14 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:41:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:41:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:41:14 INFO - Downloading functools32-3.2.3-2.tar.gz 21:41:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 21:41:14 INFO - warning: no files found matching '*.txt' 21:41:14 INFO - no previously-included directories found matching 'build' 21:41:14 INFO - no previously-included directories found matching 'dist' 21:41:14 INFO - no previously-included directories found matching '.git*' 21:41:14 INFO - Installing collected packages: jsonschema, functools32 21:41:14 INFO - Running setup.py install for functools32 21:41:14 INFO - warning: no files found matching '*.txt' 21:41:14 INFO - no previously-included directories found matching 'build' 21:41:14 INFO - no previously-included directories found matching 'dist' 21:41:14 INFO - no previously-included directories found matching '.git*' 21:41:14 INFO - Successfully installed jsonschema functools32 21:41:14 INFO - Cleaning up... 21:41:14 INFO - Return code: 0 21:41:14 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 21:41:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:41:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:41:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:41:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:41:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:41:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:41:14 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 0x10b2e7b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b2ea768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fefcad60330>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b0c5d30>, '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 0x10afecdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b0babe8>, '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.FDZteo8hlx/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.MX62N04iGn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/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 21:41:14 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 21:41:14 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 21:41:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FDZteo8hlx/Render', 21:41:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:41:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:41:14 INFO - 'HOME': '/Users/cltbld', 21:41:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:41:14 INFO - 'LOGNAME': 'cltbld', 21:41:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:41:14 INFO - 'MOZ_NO_REMOTE': '1', 21:41:14 INFO - 'NO_EM_RESTART': '1', 21:41:14 INFO - 'PAGER': '/bin/cat', 21:41:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:41:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:41:14 INFO - 'PWD': '/builds/slave/test', 21:41:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:41:14 INFO - 'SHELL': '/bin/bash', 21:41:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MX62N04iGn/Listeners', 21:41:14 INFO - 'TMPDIR': '/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/T/', 21:41:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:41:14 INFO - 'USER': 'cltbld', 21:41:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:41:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:41:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:41:14 INFO - 'XPC_FLAGS': '0x0', 21:41:14 INFO - 'XPC_SERVICE_NAME': '0', 21:41:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:41:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:41:15 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 21:41:15 INFO - Cleaning up... 21:41:15 INFO - Return code: 0 21:41:15 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 21:41:15 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')]} 21:41:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:41:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:41:15 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')]} 21:41:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:41:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:41:15 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 0x10b2e7b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b2ea768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fefcad60330>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b0c5d30>, '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 0x10afecdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b0babe8>, '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.FDZteo8hlx/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.MX62N04iGn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/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 21:41:15 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 21:41:15 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 21:41:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FDZteo8hlx/Render', 21:41:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:41:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:41:15 INFO - 'HOME': '/Users/cltbld', 21:41:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:41:15 INFO - 'LOGNAME': 'cltbld', 21:41:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:41:15 INFO - 'MOZ_NO_REMOTE': '1', 21:41:15 INFO - 'NO_EM_RESTART': '1', 21:41:15 INFO - 'PAGER': '/bin/cat', 21:41:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:41:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:41:15 INFO - 'PWD': '/builds/slave/test', 21:41:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:41:15 INFO - 'SHELL': '/bin/bash', 21:41:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MX62N04iGn/Listeners', 21:41:15 INFO - 'TMPDIR': '/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/T/', 21:41:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:41:15 INFO - 'USER': 'cltbld', 21:41:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:41:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:41:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:41:15 INFO - 'XPC_FLAGS': '0x0', 21:41:15 INFO - 'XPC_SERVICE_NAME': '0', 21:41:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:41:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:41:15 INFO - Downloading/unpacking blobuploader==1.2.4 21:41:15 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 21:41:15 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 21:41:15 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 21:41:15 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 21:41:15 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 21:41:15 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 21:41:17 INFO - Downloading blobuploader-1.2.4.tar.gz 21:41:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 21:41:18 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 21:41: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 21:41: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 21:41: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 21:41: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 21:41: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 21:41: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 21:41:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 21:41:18 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 21:41: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 21:41: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 21:41: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 21:41: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 21:41: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 21:41: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 21:41:19 INFO - Downloading docopt-0.6.1.tar.gz 21:41:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 21:41:19 INFO - Installing collected packages: blobuploader, requests, docopt 21:41:19 INFO - Running setup.py install for blobuploader 21:41:19 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 21:41:19 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 21:41:19 INFO - Running setup.py install for requests 21:41:19 INFO - Running setup.py install for docopt 21:41:20 INFO - Successfully installed blobuploader requests docopt 21:41:20 INFO - Cleaning up... 21:41:20 INFO - Return code: 0 21:41:20 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:41:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:41:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:41:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:41:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:41:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:41:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:41:20 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 0x10b2e7b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b2ea768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fefcad60330>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b0c5d30>, '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 0x10afecdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b0babe8>, '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.FDZteo8hlx/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.MX62N04iGn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/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 21:41:20 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 21:41:20 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 21:41:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FDZteo8hlx/Render', 21:41:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:41:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:41:20 INFO - 'HOME': '/Users/cltbld', 21:41:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:41:20 INFO - 'LOGNAME': 'cltbld', 21:41:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:41:20 INFO - 'MOZ_NO_REMOTE': '1', 21:41:20 INFO - 'NO_EM_RESTART': '1', 21:41:20 INFO - 'PAGER': '/bin/cat', 21:41:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:41:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:41:20 INFO - 'PWD': '/builds/slave/test', 21:41:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:41:20 INFO - 'SHELL': '/bin/bash', 21:41:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MX62N04iGn/Listeners', 21:41:20 INFO - 'TMPDIR': '/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/T/', 21:41:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:41:20 INFO - 'USER': 'cltbld', 21:41:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:41:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:41:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:41:20 INFO - 'XPC_FLAGS': '0x0', 21:41:20 INFO - 'XPC_SERVICE_NAME': '0', 21:41:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:41:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:41:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 21:41:20 INFO - Running setup.py (path:/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/T/pip-rsrztP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 21:41:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 21:41:20 INFO - Running setup.py (path:/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/T/pip-B_GvN_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 21:41:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 21:41:20 INFO - Running setup.py (path:/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/T/pip-P_z_Ao-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 21:41:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 21:41:20 INFO - Running setup.py (path:/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/T/pip-_171J_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 21:41:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 21:41:20 INFO - Running setup.py (path:/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/T/pip-7FDWgq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 21:41:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 21:41:20 INFO - Running setup.py (path:/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/T/pip-qYwPDB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 21:41:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 21:41:21 INFO - Running setup.py (path:/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/T/pip-bDhcTV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 21:41:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 21:41:21 INFO - Running setup.py (path:/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/T/pip-qwjSXI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 21:41:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 21:41:21 INFO - Running setup.py (path:/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/T/pip-cewLu7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 21:41:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 21:41:21 INFO - Running setup.py (path:/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/T/pip-a8JMiq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 21:41:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 21:41:21 INFO - Running setup.py (path:/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/T/pip-Qrf3kD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 21:41:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 21:41:21 INFO - Running setup.py (path:/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/T/pip-IJ5_fT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 21:41:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 21:41:21 INFO - Running setup.py (path:/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/T/pip-fKkoSq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 21:41:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 21:41:21 INFO - Running setup.py (path:/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/T/pip-ngeIRV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 21:41:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 21:41:21 INFO - Running setup.py (path:/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/T/pip-AkQFkB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 21:41:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 21:41:22 INFO - Running setup.py (path:/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/T/pip-4vduqx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 21:41:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 21:41:22 INFO - Running setup.py (path:/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/T/pip-i2fIxs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 21:41:22 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 21:41:22 INFO - Running setup.py install for manifestparser 21:41:22 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 21:41:22 INFO - Running setup.py install for mozcrash 21:41:22 INFO - Running setup.py install for mozdebug 21:41:22 INFO - Running setup.py install for mozdevice 21:41:22 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 21:41:22 INFO - Installing dm script to /builds/slave/test/build/venv/bin 21:41:22 INFO - Running setup.py install for mozfile 21:41:23 INFO - Running setup.py install for mozhttpd 21:41:23 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 21:41:23 INFO - Running setup.py install for mozinfo 21:41:23 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 21:41:23 INFO - Running setup.py install for mozInstall 21:41:23 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 21:41:23 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 21:41:23 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 21:41:23 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 21:41:23 INFO - Running setup.py install for mozleak 21:41:23 INFO - Running setup.py install for mozlog 21:41:23 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 21:41:23 INFO - Running setup.py install for moznetwork 21:41:23 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 21:41:23 INFO - Running setup.py install for mozprocess 21:41:24 INFO - Running setup.py install for mozprofile 21:41:24 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 21:41:24 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 21:41:24 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 21:41:24 INFO - Running setup.py install for mozrunner 21:41:24 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 21:41:24 INFO - Running setup.py install for mozscreenshot 21:41:24 INFO - Running setup.py install for moztest 21:41:24 INFO - Running setup.py install for mozversion 21:41:24 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 21:41:24 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 21:41:24 INFO - Cleaning up... 21:41:24 INFO - Return code: 0 21:41:24 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:41:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:41:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:41:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:41:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:41:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:41:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:41:24 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 0x10b2e7b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b2ea768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fefcad60330>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b0c5d30>, '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 0x10afecdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b0babe8>, '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.FDZteo8hlx/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.MX62N04iGn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/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 21:41:24 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 21:41:24 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 21:41:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FDZteo8hlx/Render', 21:41:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:41:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:41:24 INFO - 'HOME': '/Users/cltbld', 21:41:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:41:24 INFO - 'LOGNAME': 'cltbld', 21:41:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:41:24 INFO - 'MOZ_NO_REMOTE': '1', 21:41:24 INFO - 'NO_EM_RESTART': '1', 21:41:24 INFO - 'PAGER': '/bin/cat', 21:41:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:41:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:41:24 INFO - 'PWD': '/builds/slave/test', 21:41:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:41:24 INFO - 'SHELL': '/bin/bash', 21:41:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MX62N04iGn/Listeners', 21:41:24 INFO - 'TMPDIR': '/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/T/', 21:41:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:41:24 INFO - 'USER': 'cltbld', 21:41:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:41:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:41:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:41:24 INFO - 'XPC_FLAGS': '0x0', 21:41:24 INFO - 'XPC_SERVICE_NAME': '0', 21:41:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:41:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:41:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 21:41:25 INFO - Running setup.py (path:/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/T/pip-sBpcLB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 21:41:25 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)) 21:41:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 21:41:25 INFO - Running setup.py (path:/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/T/pip-sm2AZT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 21:41:25 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)) 21:41:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 21:41:25 INFO - Running setup.py (path:/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/T/pip-AOdXKL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 21:41:25 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)) 21:41:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 21:41:25 INFO - Running setup.py (path:/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/T/pip-yTbSfJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 21:41:25 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)) 21:41:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 21:41:25 INFO - Running setup.py (path:/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/T/pip-YM6pZI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 21:41:25 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)) 21:41:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 21:41:25 INFO - Running setup.py (path:/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/T/pip-T1maty-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 21:41:25 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)) 21:41:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 21:41:25 INFO - Running setup.py (path:/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/T/pip-fJltbn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 21:41:25 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)) 21:41:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 21:41:25 INFO - Running setup.py (path:/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/T/pip-hiG51D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 21:41:25 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)) 21:41:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 21:41:25 INFO - Running setup.py (path:/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/T/pip-WPxP4K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 21:41:26 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)) 21:41:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 21:41:26 INFO - Running setup.py (path:/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/T/pip-UE99Re-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 21:41:26 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)) 21:41:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 21:41:26 INFO - Running setup.py (path:/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/T/pip-yZa13k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 21:41:26 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)) 21:41:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 21:41:26 INFO - Running setup.py (path:/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/T/pip-ORlHVB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 21:41:26 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)) 21:41:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 21:41:26 INFO - Running setup.py (path:/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/T/pip-j1wS54-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 21:41:26 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)) 21:41:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 21:41:26 INFO - Running setup.py (path:/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/T/pip-3ThTu1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 21:41:26 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)) 21:41:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 21:41:26 INFO - Running setup.py (path:/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/T/pip-igw_p1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 21:41:26 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)) 21:41:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 21:41:26 INFO - Running setup.py (path:/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/T/pip-6JXeGB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 21:41:26 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)) 21:41:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 21:41:26 INFO - Running setup.py (path:/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/T/pip-1Jxqkx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 21:41:26 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)) 21:41:26 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)) 21:41:26 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)) 21:41:26 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)) 21:41:26 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)) 21:41:26 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)) 21:41:26 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 21:41:26 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 21:41:26 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 21:41:26 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 21:41:26 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 21:41:26 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 21:41:26 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 21:41:29 INFO - Downloading blessings-1.6.tar.gz 21:41:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 21:41:29 INFO - Installing collected packages: blessings 21:41:29 INFO - Running setup.py install for blessings 21:41:29 INFO - Successfully installed blessings 21:41:29 INFO - Cleaning up... 21:41:29 INFO - Return code: 0 21:41:29 INFO - Done creating virtualenv /builds/slave/test/build/venv. 21:41:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 21:41:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 21:41:30 INFO - Reading from file tmpfile_stdout 21:41:30 INFO - Current package versions: 21:41:30 INFO - blessings == 1.6 21:41:30 INFO - blobuploader == 1.2.4 21:41:30 INFO - docopt == 0.6.1 21:41:30 INFO - functools32 == 3.2.3-2 21:41:30 INFO - jsonschema == 2.5.1 21:41:30 INFO - manifestparser == 1.1 21:41:30 INFO - mozInstall == 1.12 21:41:30 INFO - mozcrash == 1.0 21:41:30 INFO - mozdebug == 0.1 21:41:30 INFO - mozdevice == 0.49 21:41:30 INFO - mozfile == 1.2 21:41:30 INFO - mozhttpd == 0.7 21:41:30 INFO - mozinfo == 0.9 21:41:30 INFO - mozleak == 0.1 21:41:30 INFO - mozlog == 3.4 21:41:30 INFO - moznetwork == 0.27 21:41:30 INFO - mozprocess == 0.24 21:41:30 INFO - mozprofile == 0.28 21:41:30 INFO - mozrunner == 6.13 21:41:30 INFO - mozscreenshot == 0.1 21:41:30 INFO - mozsystemmonitor == 0.3 21:41:30 INFO - moztest == 0.8 21:41:30 INFO - mozversion == 1.4 21:41:30 INFO - psutil == 3.1.1 21:41:30 INFO - requests == 1.2.3 21:41:30 INFO - wsgiref == 0.1.2 21:41:30 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:41:30 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')]} 21:41:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:41:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:41:30 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')]} 21:41:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:41:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:41:30 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 0x10b2e7b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b2ea768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fefcad60330>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b0c5d30>, '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 0x10afecdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b0babe8>, '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.FDZteo8hlx/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.MX62N04iGn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/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 21:41:30 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 21:41:30 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 21:41:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FDZteo8hlx/Render', 21:41:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:41:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:41:30 INFO - 'HOME': '/Users/cltbld', 21:41:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:41:30 INFO - 'LOGNAME': 'cltbld', 21:41:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:41:30 INFO - 'MOZ_NO_REMOTE': '1', 21:41:30 INFO - 'NO_EM_RESTART': '1', 21:41:30 INFO - 'PAGER': '/bin/cat', 21:41:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:41:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:41:30 INFO - 'PWD': '/builds/slave/test', 21:41:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:41:30 INFO - 'SHELL': '/bin/bash', 21:41:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MX62N04iGn/Listeners', 21:41:30 INFO - 'TMPDIR': '/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/T/', 21:41:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:41:30 INFO - 'USER': 'cltbld', 21:41:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:41:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:41:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:41:30 INFO - 'XPC_FLAGS': '0x0', 21:41:30 INFO - 'XPC_SERVICE_NAME': '0', 21:41:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:41:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:41:30 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)) 21:41:30 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)) 21:41:30 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)) 21:41:30 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)) 21:41:30 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)) 21:41:30 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)) 21:41:30 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)) 21:41:30 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)) 21:41:30 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)) 21:41:30 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)) 21:41:30 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)) 21:41:30 INFO - Cleaning up... 21:41:30 INFO - Return code: 0 21:41:30 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 21:41:30 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')]} 21:41:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:41:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:41:30 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')]} 21:41:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:41:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:41:30 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 0x10b2e7b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b2ea768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fefcad60330>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b0c5d30>, '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 0x10afecdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b0babe8>, '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.FDZteo8hlx/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.MX62N04iGn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/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 21:41:30 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 21:41:30 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 21:41:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FDZteo8hlx/Render', 21:41:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:41:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:41:30 INFO - 'HOME': '/Users/cltbld', 21:41:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:41:30 INFO - 'LOGNAME': 'cltbld', 21:41:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:41:30 INFO - 'MOZ_NO_REMOTE': '1', 21:41:30 INFO - 'NO_EM_RESTART': '1', 21:41:30 INFO - 'PAGER': '/bin/cat', 21:41:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:41:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:41:30 INFO - 'PWD': '/builds/slave/test', 21:41:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:41:30 INFO - 'SHELL': '/bin/bash', 21:41:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MX62N04iGn/Listeners', 21:41:30 INFO - 'TMPDIR': '/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/T/', 21:41:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:41:30 INFO - 'USER': 'cltbld', 21:41:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:41:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:41:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:41:30 INFO - 'XPC_FLAGS': '0x0', 21:41:30 INFO - 'XPC_SERVICE_NAME': '0', 21:41:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:41:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:41:30 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 21:41:30 INFO - Cleaning up... 21:41:30 INFO - Return code: 0 21:41:30 INFO - Running post-action listener: _resource_record_post_action 21:41:30 INFO - Running post-action listener: _start_resource_monitoring 21:41:30 INFO - Starting resource monitoring. 21:41:30 INFO - [mozharness: 2017-02-03 05:41:30.824779Z] Finished create-virtualenv step (success) 21:41:30 INFO - [mozharness: 2017-02-03 05:41:30.825762Z] Running install step. 21:41:30 INFO - Running pre-action listener: _resource_record_pre_action 21:41:30 INFO - Running main action method: install 21:41:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 21:41:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 21:41:31 INFO - Reading from file tmpfile_stdout 21:41:31 INFO - Detecting whether we're running mozinstall >=1.0... 21:41:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 21:41:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 21:41:31 INFO - Reading from file tmpfile_stdout 21:41:31 INFO - Output received: 21:41:31 INFO - Usage: mozinstall [options] installer 21:41:31 INFO - Options: 21:41:31 INFO - -h, --help show this help message and exit 21:41:31 INFO - -d DEST, --destination=DEST 21:41:31 INFO - Directory to install application into. [default: 21:41:31 INFO - "/builds/slave/test"] 21:41:31 INFO - --app=APP Application being installed. [default: firefox] 21:41:31 INFO - mkdir: /builds/slave/test/build/application 21:41:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-54.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 21:41:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-54.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 21:41:52 INFO - Reading from file tmpfile_stdout 21:41:52 INFO - Output received: 21:41:52 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 21:41:52 INFO - Running post-action listener: _resource_record_post_action 21:41:52 INFO - [mozharness: 2017-02-03 05:41:52.836727Z] Finished install step (success) 21:41:52 INFO - [mozharness: 2017-02-03 05:41:52.836882Z] Running run-tests step. 21:41:52 INFO - Running pre-action listener: _resource_record_pre_action 21:41:52 INFO - Running main action method: run_tests 21:41:52 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 21:41:52 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 21:41:52 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 21:41:52 INFO - 2017-02-02 21:41:52.851 screenresolution[7126:132520] starting screenresolution argv=screenresolution get 21:41:52 INFO - 2017-02-02 21:41:52.854 screenresolution[7126:132520] Display 0: 1600x1200x32@60 21:41:52 INFO - 2017-02-02 21:41:52.860 screenresolution[7127:132522] starting screenresolution argv=screenresolution list 21:41:52 INFO - Available Modes on Display 0 21:41:52 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 21:41:52 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 21:41:52 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 21:41:52 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 21:41:53 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 21:41:53 INFO - Intel Iris: 21:41:53 INFO - Chipset Model: Intel Iris 21:41:53 INFO - Type: GPU 21:41:53 INFO - Bus: Built-In 21:41:53 INFO - VRAM (Dynamic, Max): 1536 MB 21:41:53 INFO - Vendor: Intel (0x8086) 21:41:53 INFO - Device ID: 0x0a2e 21:41:53 INFO - Revision ID: 0x0009 21:41:53 INFO - Displays: 21:41:53 INFO - Display: 21:41:53 INFO - Resolution: 1600 x 1200 @ 60 Hz 21:41:53 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 21:41:53 INFO - Display Serial Number: 1600x1200 60 21:41:53 INFO - Main Display: Yes 21:41:53 INFO - Mirror: Off 21:41:53 INFO - Online: Yes 21:41:53 INFO - Rotation: Supported 21:41:53 INFO - Return code: 0 21:41:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 21:41:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 21:41:53 INFO - Python 2.7.3 21:41:53 INFO - Return code: 0 21:41:53 INFO - grabbing minidump binary from tooltool 21:41:53 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')]} 21:41:53 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 0x10b0c5d30>, '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 0x10afecdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b0babe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 21:41:53 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 21:41:53 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 21:41:53 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 21:41:53 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 21:41:53 INFO - Return code: 0 21:41:53 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 21:41:53 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 21:41:53 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 21:41:53 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 21:41:53 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 21:41:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Larch', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/PSBt1TV9Ry2T6mPkN0c8Fw/artifacts/public/build/firefox-54.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0237', '--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 21:41:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Larch --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/PSBt1TV9Ry2T6mPkN0c8Fw/artifacts/public/build/firefox-54.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0237 --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 21:41:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FDZteo8hlx/Render', 21:41:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:41:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:41:53 INFO - 'HOME': '/Users/cltbld', 21:41:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:41:53 INFO - 'LOGNAME': 'cltbld', 21:41:53 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 21:41:53 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 21:41:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:41:53 INFO - 'MOZ_NO_REMOTE': '1', 21:41:53 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 21:41:53 INFO - 'NO_EM_RESTART': '1', 21:41:53 INFO - 'PAGER': '/bin/cat', 21:41:53 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:41:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:41:53 INFO - 'PWD': '/builds/slave/test', 21:41:53 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 21:41:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:41:53 INFO - 'SHELL': '/bin/bash', 21:41:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MX62N04iGn/Listeners', 21:41:53 INFO - 'TMPDIR': '/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/T/', 21:41:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:41:53 INFO - 'USER': 'cltbld', 21:41:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:41:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:41:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:41:53 INFO - 'XPC_FLAGS': '0x0', 21:41:53 INFO - 'XPC_SERVICE_NAME': '0', 21:41:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:41:53 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Larch', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/PSBt1TV9Ry2T6mPkN0c8Fw/artifacts/public/build/firefox-54.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0237', '--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 21:41:53 INFO - mozversion application_buildid: 20170202171409 21:41:53 INFO - mozversion application_changeset: c8102da699dba43d85eb80b70956e80c6793feca 21:41:53 INFO - mozversion application_display_name: Nightly 21:41:53 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 21:41:53 INFO - mozversion application_name: Firefox 21:41:53 INFO - mozversion application_remotingname: firefox 21:41:53 INFO - mozversion application_repository: https://hg.mozilla.org/projects/larch 21:41:53 INFO - mozversion application_vendor: Mozilla 21:41:53 INFO - mozversion application_version: 54.0a1 21:41:53 INFO - mozversion platform_buildid: 20170202171409 21:41:53 INFO - mozversion platform_changeset: c8102da699dba43d85eb80b70956e80c6793feca 21:41:53 INFO - mozversion platform_repository: https://hg.mozilla.org/projects/larch 21:41:53 INFO - mozversion platform_version: 54.0a1 21:41:53 INFO - using testdate: 1486100513 21:41:53 INFO - actual date: 1486100513 21:41:53 INFO - starting webserver on 'localhost:63918' 21:41:53 INFO - SUITE-START | Running 2 tests 21:41:53 INFO - TEST-START | dromaeo_css 21:41:53 INFO - operating with platform_type : mac_ 21:41:53 INFO - Initialising browser for dromaeo_css test... 21:41:53 INFO - TEST-INFO | started process 7137 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/T/tmpz72AK8/profile http://localhost:63918/getInfo.html) 21:41:56 INFO - PROCESS | 7137 | JavaScript error: chrome://webcompat/content/lib/ua_overrider.jsm, line 109: NS_ERROR_INSUFFICIENT_DOMAIN_LEVELS: Component returned failure code: 0x804b0050 (NS_ERROR_INSUFFICIENT_DOMAIN_LEVELS) [nsIEffectiveTLDService.getBaseDomain] 21:41:56 INFO - PROCESS | 7137 | 2017-02-02 21:41:56.285 plugin-container[7139:132678] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8e43, name = 'com.apple.tsm.portname' 21:41:56 INFO - PROCESS | 7137 | See /usr/include/servers/bootstrap_defs.h for the error codes. 21:41:56 INFO - PROCESS | 7137 | 2017-02-02 21:41:56.287 plugin-container[7139:132678] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4c07, name = 'com.apple.CFPasteboardClient' 21:41:56 INFO - PROCESS | 7137 | See /usr/include/servers/bootstrap_defs.h for the error codes. 21:41:56 INFO - PROCESS | 7137 | 2017-02-02 21:41:56.287 plugin-container[7139:132678] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 21:41:56 INFO - PROCESS | 7137 | __metrics Screen width/height:1600/1200 21:41:56 INFO - PROCESS | 7137 | colorDepth:24 21:41:56 INFO - PROCESS | 7137 | Browser inner width/height: 1024/689 21:41:56 INFO - PROCESS | 7137 | __metrics 21:41:56 ERROR - PROCESS | 7137 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3283: TypeError: this.tabs is undefined 21:41:56 INFO - PROCESS | 7137 | 1486100516442 addons.xpi WARN Exception running bootstrap method shutdown on shield-recipe-client@mozilla.org: ReferenceError: LogManager is not defined (resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:65:3) JS Stack trace: this.shutdown@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@bootstrap.js:65:3 < callBootstrapMethod@XPIProvider.jsm:4903:11 < observe@XPIProvider.jsm:2884:15 < forceQuit@jar:file:///var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/T/tmpz72AK8/profile/extensions/talos-powers@TalosPowersService.js:238:7 < whenDone@jar:file:///var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/T/tmpz72AK8/profile/extensions/talos-powers@TalosPowersService.js:223:9 < promise callback*forceQuit@jar:file:///var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/T/tmpz72AK8/profile/extensions/talos-powers@TalosPowersService.js:225:7 < receiveMessage@jar:file:///var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/T/tmpz72AK8/profile/extensions/talos-powers@TalosPowersService.js:55:9 21:41:57 INFO - TEST-INFO | 7137: exit 0 21:41:57 INFO - Browser initialized. 21:41:57 INFO - Running cycle 1/1 for dromaeo_css test... 21:41:57 INFO - TEST-INFO | started process 7140 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/T/tmpz72AK8/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 21:41:59 INFO - PROCESS | 7140 | 2017-02-02 21:41:59.804 plugin-container[7141:132808] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4747, name = 'com.apple.tsm.portname' 21:41:59 INFO - PROCESS | 7140 | See /usr/include/servers/bootstrap_defs.h for the error codes. 21:41:59 INFO - PROCESS | 7140 | 2017-02-02 21:41:59.805 plugin-container[7141:132808] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x900b, name = 'com.apple.CFPasteboardClient' 21:41:59 INFO - PROCESS | 7140 | See /usr/include/servers/bootstrap_defs.h for the error codes. 21:41:59 INFO - PROCESS | 7140 | 2017-02-02 21:41:59.805 plugin-container[7141:132808] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 21:42:09 INFO - PROCESS | 7140 | RSS: Main: 233902080 21:42:09 INFO - PROCESS | 7140 | 21:45:12 INFO - PROCESS | 7140 | Cycle 1(1): loaded http://localhost:63918/tests/dromaeo/cssquery-dojo.html (next: http://localhost:63918/tests/dromaeo/cssquery-ext.html) 21:45:13 INFO - PROCESS | 7140 | RSS: Main: 254787584 21:45:13 INFO - PROCESS | 7140 | 21:46:57 INFO - PROCESS | 7140 | ************************* 21:46:57 INFO - PROCESS | 7140 | A coding exception was thrown and uncaught in a Task. 21:46:57 INFO - PROCESS | 7140 | 21:46:57 ERROR - PROCESS | 7140 | Full message: TypeError: NetworkError when attempting to fetch resource. 21:46:57 INFO - PROCESS | 7140 | Full stack: 21:46:57 INFO - PROCESS | 7140 | ************************* 21:48:16 INFO - PROCESS | 7140 | Cycle 1(1): loaded http://localhost:63918/tests/dromaeo/cssquery-ext.html (next: http://localhost:63918/tests/dromaeo/cssquery-jquery.html) 21:48:16 INFO - PROCESS | 7140 | RSS: Main: 262950912 21:48:16 INFO - PROCESS | 7140 | 21:51:18 INFO - PROCESS | 7140 | Cycle 1(1): loaded http://localhost:63918/tests/dromaeo/cssquery-jquery.html (next: http://localhost:63918/tests/dromaeo/cssquery-mootools.html) 21:51:19 INFO - PROCESS | 7140 | RSS: Main: 275058688 21:51:19 INFO - PROCESS | 7140 | 21:54:20 INFO - PROCESS | 7140 | Cycle 1(1): loaded http://localhost:63918/tests/dromaeo/cssquery-mootools.html (next: http://localhost:63918/tests/dromaeo/cssquery-prototype.html) 21:54:21 INFO - PROCESS | 7140 | RSS: Main: 269283328 21:54:21 INFO - PROCESS | 7140 | 21:57:24 INFO - PROCESS | 7140 | Cycle 1(1): loaded http://localhost:63918/tests/dromaeo/cssquery-prototype.html (next: http://localhost:63918/tests/dromaeo/cssquery-yui.html) 21:57:25 INFO - PROCESS | 7140 | RSS: Main: 274292736 21:57:25 INFO - PROCESS | 7140 | 22:00:27 INFO - PROCESS | 7140 | Cycle 1(1): loaded http://localhost:63918/tests/dromaeo/cssquery-yui.html (next: http://localhost:63918/tests/dromaeo/cssquery-dojo.html) 22:00:28 INFO - PROCESS | 7140 | RSS: Main: 275955712 22:00:28 INFO - PROCESS | 7140 | 22:00:28 INFO - PROCESS | 7140 | __start_tp_report 22:00:28 INFO - PROCESS | 7140 | _x_x_mozilla_page_load 22:00:28 INFO - PROCESS | 7140 | _x_x_mozilla_page_load_details 22:00:28 INFO - PROCESS | 7140 | |i|pagename|runs| 22:00:28 INFO - PROCESS | 7140 | |0;dojo.html;2741.109139529653;2897.521908885034;3224.935501289974;3328.6338502764697;3366.394049071168;4540.160070386981;5636.689982050991;5691.800786972457;5704.344000439951;5721.284839395075;5572.4706152915405;5677.659340439566;5720.570957178207;5726.68503232322;5801.506871915893;5118.795248190068;5153.690778553289;5198.116320225571;5297.311349524575;5300.151975683892;5235.345581802275;5319.122344813101;5332.840014799563;5339.946600533984;5384.488473595002;5113.951639913808;5150.150225212873;5292.100342941709;5294.549963253128;5304.973475132639;5169.147090730025;5216.478352164791;5249.055170069386;5289.814856480005;5324.094903866313;5176.974115129438;5235.528802407764;5247.1866860636665;5259.921101183485;5287.47125287472;4183.518895327046;4867.8317203871175;5716.399778023289;5747.252857128547;5770.394108618577;5947.643141411486;6174.351693072208;6207.441330280251;6209.658468784215;6295.905561416583;6257.374262573751;6259.029850373199;6264.843378915518;6269.2476902771505;6273.404026617464;5675.7445914933105;5892.705364731789;5916.3787802281;5930.8813823723285;5960.5231581474245;4973.701577905337;5649.265595472562;6224.408681175281;6227.159333490012;6249.7187626556915;4761.500042495557;6184.134221209034;6226.40849119333;6246.031865060922;6259.812205633838;6180.783672571529;6281.937180628227;6294.181756371642;6296.024116261987;6301.653409062454;6099.146119543268;6115.240341084353;6163.537734669918;6175.62946223228;6177.227846519185;5344;6185.103160041769;6187.474064704461;6257.624542527463;6263.467605253604;5013.248012798036;5024.824131155465;5028.245763135559;5028.748562571856;5041.59667226621;4376.606105450524;4392.253316936129;4395.406620106309;4395.692301538926;4479.260921947907;5832.504237139806;5868.295804503401;5888.617239879394;5891.823245302648;5938.376470470625;6235.438810507076;6255.843603909939;6255.937440625627;6290.402411770874;6294.307626161119;5744.310682718101;5783.508401785895;5821.679807610537;5893.941060589425;5911;4958.578520825698;5416.160495123262;5500.22996780443;5501.257330260363;5547.11246200606;6345.809625711236;6351.555391122577;6353.237611486651;6354.809355719336;6355.5551111421755;6111.318268824315;6137.376985335611;6286.402791734778;6288.811335659938;6289.716962736779;6240.53196010292;6266.185395898504;6302.275238347649;6329.398707122816;6371.299157092809;6223.3465486123305;6255.812325630239;6264.780732674334;6266.905996409966;6274.592151510137;5447.836564903059;6140.17107690456;6274.749010039547;6292.685365731787;6314.989601663712;6066.393360663898;6102.511799056154;6116.031445915052;6123.877522449615;6202;5611.971940140436;6148.569600127948;6183.134361189347;6193.287771906288;6206.751729930752;5772.797952071657;5809.477147056617;6046.183765191643;6051.667158306335;6096.786612468542;6141;6153.876922461615;6160.907586386118;6187.226596675416;6196.659183744757;6018.080154687997;6063.484603808725;6098;6119.602225855305;6120.724567394567 22:00:28 INFO - PROCESS | 7140 | |1;ext.html;428.05613621963573;451.0640421126163;498.34467675007375;521.5202014146987;565.7312776431193;38454.807725961364;40362.59637403625;40421.78734637966;40434.59565404345;40439.989000274974;34476.44855981481;41325.17349653013;41955.37066943998;42099.57900420995;42168;8884.955575222148;9173.312001599874;9547.95226023869;10971.23201375904;11349.489272982695;9289.814203715921;11138;11198.104151667887;11206.495707693173;11211.215214934959;9316.347855650074;9555.665551705657;11251.324920504754;11254.155938304619;11272.380019098946;11089.889101108967;11155;11215.495302711397;11232.831507527393;11255.718607034807;11123.666290011313;11232.10143188543;11233.494492747846;11241.044511216556;11266.380349080797;30907.690923090708;38056.23887522234;38058.61941380606;38363.23273534514;38445.80777096125;3404.795712257248;3952.032282531636;3960.128771670228;3962.167944731581;3993.1614360984227;28652.71347286542;29171.854140729374;29233;29384.824607015908;30128.849355753082;3128.7184153426074;3129.7809153359276;3150.6061742282213;3152.810831350126;3245.269814291789;459024;488098;488129.5593522045;489754.55122724513;491199;49432.25851612229;50661;56631;58143.709281453324;58429.12356314574;54327.64180895509;54339.72830135824;54488.72755636196;54524.18213726797;54777;15175.544733658007;15313.004654697632;15313.846861531465;15316.310766015333;15376.538703838904;9314.48770317639;11648;11716.472758725879;11893.940530297294;12039.337836418907;14238.786418203736;17777.111144442984;17780;17800.46598602044;17830.37593684215;6785.5250132490255;7944.44388892772;8020.799480013046;8076;8130.552819594861;844.2064459408136;855.1918437077027;857.6140736668524;864.8572985457455;870.2073728120093;26889.596656050177;29141.56287655687;29231.56152657712;30063.69936300609;30081.849590751906;969.8205831920976;1077.3966578715945;1087.7661302819931;1093.907017903487;1175.1949914308725;12816.42326095328;14492.275386230645;14826;15431.459898903484;15526.9223653881;512960;516129;516647;518221;518305;59563.40436595665;60218.79562408727;60299;60336.396636033955;60403.09395359073;1355.6610847288177;1402.8667276608705;1405.676694360303;1413.7384583851815;1418.290854572693;24393.390165245302;28618.85690571534;28851;29159.85420072886;29700.405991880474;51496.48503514917;51780.964380712925;51828.74085629699;51852.74073629608;52084;456493;456822;457018;458626;458910;24034.51930961406;24641;26197.476050479265;26417.471650567266;26802.32994175083;47741.761291193325;48245.276320854515;48751.02497950092;48794;49013;29575.704242958156;38332.61667383402;38600.227995440495;39968.80015599904;40147.397789032606;38961;39320.41019384655;39421.80289098651;40437.393439099025;40573.594264056985;37395.62604373921;37471;37643.05892352713;38824.805875970436;38836.6116338833;24911.87544062268;34404.65595344115;34488.65511344854;34712;34730.41436997602;19356.32252871199;23818.64272035886;25302.240932772045;25454.109106181935;25790.09734659353 22:00:28 INFO - PROCESS | 7140 | |2;jquery.html;337.56622739779374;345.2335814491828;395.778364116095;397.1005672152573;425.41293015638416;15985.76021359679;19778.802211977876;20315.187392504282;20482.2831200908;20666.173353065897;20768;20897.582048359025;20986.580268394624;21275.893620531875;21631.918404079774;4738.620910327166;5239.318888544484;5471.890562188753;5618.719064046802;6014.278286605613;5898.262717160355;5903.88192236155;6015.308239552442;6026.939730602704;6059.606125601822;4595.333676616889;5272.657277276984;5886.440788125143;5909.852253693671;5979.133025711291;5880.852978675524;5931.169636250928;5938.554608404387;5941.4355636214705;5947.4052594740615;5234.659747116463;5466.945330546723;5517.420670829545;5525.806596769134;5536.2249285100115;13308.66913308671;13511.500372431132;16969.3212271509;25221;25417;2563.577009793382;2808.8595570221405;2839.801213915027;2860.728230818069;2889.3643398452305;9915.61495785715;9958.25313101513;10043.89956100437;10400.687979360631;10523.84214236787;1452.898297119202;1786.330126202674;1807.3674214025118;1817.581956150093;1835.7888842783118;103908;126278;129317.35341323422;136313;149374;11885.881141188478;11946.044316454663;11958.342291173894;12079.939600301943;12298.20061695987;15494.612634683997;15867.920660396625;15872.285747141406;15907.0455772654;15924.840751592335;8502.192291732275;8663.610137543825;8697.26073283778;8737.956310218407;8835.55822208887;8298.8340233195;8340.29107525855;8466.788330291793;8503.872441913376;8514.10601886793;4784.784684689197;4860.4896485869185;4868.805247790119;4889.511048895082;4917.336159618406;1810.8098649641597;1966.2823069579786;2067.5010617302637;2079.3346129238803;2080.8543401961715;973.7516933181993;993.572763711611;996.3573495095768;996.6611352140308;1043.9478026098664;21459.141634334763;21778.564428711656;21823.781762182494;21828.672569911465;21919.452013699465;881.3566096749408;958.2525630008604;995.2137811129127;1019.9694009179736;1042.838360054193;10852.348859068481;10940.23418360723;11186.664400068012;11270.267432616854;11363.943180284046;737910;745135;745791.2710436413;750704;754840;111037.88962110276;112716.43641781739;153907;154346;156091.70196860205;767.1982777997007;954.5275088831212;958.91849192818;994.4778991029767;995.2237254941158;19897.204111835363;20009.399718008484;20212.696809548164;20274.391768246976;20415.4896127598;46016.309755353024;52377.690557736365;53135.46864531305;54272;56936.43063569311;73823;75637;77922.61038694771;79228;80030.19969800228;8177.713780017909;8357.623906924082;8527.616257268563;8625.525596092024;8654.04805471385;11509.906558876894;11584.189106762782;13154.539591114266;13180;13336.19982801035;10445.738856528344;10778.784424311627;10986.082764884108;11333.716657083638;11458.796826333115;11123.777524449628;11319.547218111184;11398.94300528492;11669.474873630537;11891.345975971059;11039;11373.772524549628;11787.823182652439;11873.346965916957;12018.399080045789;8344.541050242295;8364.498130112213;8502.872456913276;8614.913850861662;8619.396642235235;10344.948275258575;11269;11521.020713239468;11530.711732206762;11556 22:00:28 INFO - PROCESS | 7140 | |3;mootools.html;1406.128200515681;1413.1238632048132;1422.7581311177098;1429.7140571885625;1431.6492459347457;6928.445724342053;8032.477888937222;8350.958245208772;8433;8434.24091831735;8161.143079976606;8397.832043359129;8401.579921003957;8402.621882015308;8426.747197584082;2282.2126366403645;2283.9657405138937;2330.3940975346445;2330.8718020508863;2330.8951097200584;2284.1776960294283;2326.8720220387872;2332.055517515409;2339.3215967369442;2339.6607491913664;2273.761255068219;2275;2299.9885000574973;2302.3783578433813;2304.6543018547186;1888.3673969220258;2211.1045026764186;2262.886855657219;2300.6779050932882;2306.457982374149;1773.4590949760318;1991.621591897535;2311.4914718761847;2318.04959966413;2339.4034521196986;8048.959755201245;8297.83404331916;8304.83390332196;8396.916030839675;8410.705625303084;5063.620228482879;5190.584753219733;5224.268602395668;5232.476752324776;5261.158214685632;3454.3091381723757;3481.738869584766;3558.982205088984;3592.8203589820405;3632.836522356474;1228.103484456343;1229.7786398448275;1242.5772490498418;1254.1095821966414;1283.8844503994592;8955.731328060168;9086.409383390059;9298.209652179505;9780.924098349176;10701.464926753632;4429.844955426609;4659.254519276899;4712.128256272601;4712.5994290485005;4752.976235118802;9390.483523406278;9407.247420206368;9471.289653276031;9484.099010593982;9490.952545237229;5186.662866913639;5240.764165612481;5245.868853278622;5247.842564723064;5248.3701955765555;4228.1966426378885;4453.3320001999955;4684.695494792828;4755.9286610700865;4808.471068182497;3906.882793516199;3943.7042221833476;3966.861159859391;4014.6186112319283;4077.2864748668862;1277.9808302875465;1293.6636474516692;1302.2121616422119;1302.6222395505388;1310.5609620777147;847.1993965702352;884.2174675412307;895.4895709445553;905.3707673167218;938.6808485115093;5334.199870019451;5636.2954630671165;5674.12051132067;5692.715364231772;5786.218860453869;887.894571258787;977.5405559387077;978.83849164887;980.5881529757519;987.5483039931289;3722.3299806035175;3742.94385584211;3783.39465685489;3853.3641949078087;3856.8650097246464;9525;9721.611135554498;9801.11789938909;9808.558614862348;9821.705348839547;9756.21950243993;9763.170130538841;9764.121229089274;9804.65683701067;9839.065288797552;7351;7698.884516732253;7700.499467534704;7774.30031297186;7833.412494062968;5509.338879334506;5648.689322087324;5669.121286200645;5697.6296540724725;5738.512226460798;8772.166644168794;8954.373193876367;9061.77345566366;9099.999000110114;9136.451812891248;8804.603792829466;8908.510031948304;9025.638974441212;9113.270938325051;9295.442273463614;6294.590851594632;6886.896696549456;7091.1490621125795;7096.467764917548;7105.715771369294;7636.8472630548185;7702;7715.382769378549;7740.41946853977;7864.72473463426;7047.929520704727;7136.4290856730295;7140.178879428932;7143.285671432815;7194.172670143001;6893.93106068953;6971.128608923885;6976.19773726028;7001.404880585207;7016;6290.49676025926;6386.489080873426;6390.872182556416;6393.073004414427;6415.230172379344;4849.927251091166;4870;4924.581410580141;4963.181075122564;5115.616328775282;3994.8801535953967;4972.129877271535;4995.675281106716;5033.521815427529;5084.9830033994085 22:00:28 INFO - PROCESS | 7140 | |4;prototype.html;366.22726048038635;367.09877251347945;373.8130934532734;373.9083924438513;384.23153692614756;4961.454240033593;5163.741812909354;5237.607179461536;5265.815191581893;5564.638298510594;4318.417013703149;4787.066522028206;5267.26258323835;5404.675989324589;5437.592180586462;3187.1713354527756;3397.740502565791;4106.445629839978;4216.620504154625;4273.743575385472;3289.9636614466367;3519.69771184662;3852.7880966546827;4279.422277992478;4296.355546667994;3298.752593555481;4170.143451735556;4173.686973476986;4238.682098842583;4246.851360202393;3833.4058220975794;3858.7298889077497;4205.810738516774;4214.262504061792;4221.662267018646;3461.7191639093444;3751.4747935289083;3763.7741735495956;3771.641694039062;3792.886213413602;5107.540321371094;5165.9225111623355;5237.450067742909;5271.28837606922;5303.63219355708;4474.6420286377015;4911.656184067117;4932.087563800673;4938.753062346868;4939.58013568847;5780.393058728857;5802.332731735863;5808.738606762706;5829.271341082364;5857.238558987347;4008.2985477541606;4122.896927576805;4160.271952408317;4171.666266698657;4179.561146079648;5816.302043754734;5819.883602327972;5975.760969561213;6100.481459075984;6147.385261473862;3509.2104276538084;4219.282721937278;4230.957690423056;4232.788360581959;4242.00312926462;4260.829566817354;4302.634276086567;4305.634021108241;4306.332518459643;4323.740575565476;3755.6995440364703;3892.9416058759143;4113.856015039519;4136.338185890243;4935.457099719028;5110.437851836295;5314.123169677037;5712.828615141552;5789.855253618609;5812.709364531756;1237.1649136832602;1321.709223970725;1348.9325533723274;1392.1229625335973;1405.9789103163462;911.7310393433953;974.6880998080688;988.2390559269322;989.1888651306004;997.7904640025531;4653.231327360215;4700.529947005341;5109.335786347751;5135.666181698178;5155.32980712505;4905.828296009622;5305.840824775263;5531.980826998197;5703;5750.482456578928;4574.382458368144;4927.581155601814;4980.655223089818;5027.874303142451;5467.972660136674;2560.607195466015;2664.8134630575673;2668.7197844226466;2933.427981543621;2961.7926745128066;4812.614990800727;4970.8757281068265;5499.212755854363;5828.464617891146;5928.377520360386;4965.201620760122;5125.820596279113;5345.518903298722;5849.239598852122;5884.823455296348;5093.694378337312;5887;5928.377520360386;5961.582689211714;5980.893534696008;4850.466448690711;5280;5605.747741351567;5691.658500489983;5727.742251598771;4566.383538222298;4589.059242855274;5585.553155747449;5630.465105814941;5640.0693885508435;5297.682139071668;5921.822345329647;6036.607620504653;6143;6186.690665466619;3075.338802157547;3276.00163900016;3606.5852426971232;3782.8955583191446;3885.630865067814;5045.5529068401065;5702.714864256854;5751.798687045934;5975.19165991983;6022.277326720821;5494.450554944474;5703.62926409782;5770.769169233352;5784.479396854135;5785.652860828363;5005.649604527648;5519.420460851553;5767.250257466502;5789.2763404574425;5792.5655575832825;4611.884702882455;5313.654612450178;5569.387367389503;5637.267155269953;5659.801906933238;5137.662363624617;5741.4832665058675;5792.884142317214;5810.883782324246;5823.941760582509;5632.2678051853;5635.887282254414;5646.887062258814;5667.4615911488345;5691 22:00:28 INFO - PROCESS | 7140 | |5;yui.html;689.6965335252488;695.3185877839717;703.6270776488462;782.7143092771137;795.753316471894;14567.198804065772;15509.20834249836;15542.53372398827;15761;15876.36494540217;14127.152370857755;14331.71336573268;14436.206008669518;14563.781543276833;14619;870.8737233101212;876;881.3742243007472;881.951492667903;884.5046773806657;849.1381248033251;874.1302404107921;876.2070326354627;880.6565439478609;884.9867251991193;914.9679761208359;922.9893266872764;925.3846192282124;927.9025702301262;928.4197376639601;903.6746771162376;912.7216199059284;912.794621210229;917.4174399256486;919.4942781470198;669.8593295407921;670.2325836916751;690.7237105157927;691.9584824910521;693.9618320992344;14574.781378279335;15164.848351516453;15275.312610932535;15563.221838908123;15615.843841561666;14016.509422170277;14374.20941848198;14423.639409014753;14424.134551926918;14426.855731442656;11921.94039029808;11942.820857687142;12025;12078.456469458895;12124.393780311037;3636.61815509373;3702.092987218122;3715.2379391356576;3723.515942927429;3745.382011968022;109275.45362273138;111704.32443513355;113202;113682.15794605202;113896.86103138946;10658.573657053632;10678.092362149304;10770.138388929023;10770.24608277413;11120.11039116885;22242.110315587517;22386.328410147722;22394.88802555977;22395.664065039036;22459.213927512785;7512.661930213044;7613.314801667766;7617.961910190414;7687.769366919001;7798.376129909594;4273.316269396882;4289.849855255051;4377.4528183977;4389.5075674270265;4426.667999899956;3608.530890984208;3685.336639404933;3721.5906250312446;3800.5819359870943;4035.7982100895424;557.8214971209175;706.4207349973076;709.5919846088519;715.2704241673464;727.9917314519323;646.6863571167979;662.6448911975023;682.2120450879243;691.5193940211504;694.7151667816171;10230.181585473145;10236.118318629911;10392.272540922062;10439.686809395731;10575.312604680825;586.5659412035063;670.7484693240035;693.8300116471396;706.9328413800681;720.9423246140296;4205.621494065549;4846.878828029327;5080.872978175576;5083.38999320084;5089.160288552422;105470;107876.46061769797;107989;109027;109311;67028.99456508156;71323;72965;73135.63432182805;75969.62015189888;66250.66874665595;67088;67396.98904516436;69906.60186796337;70903.58192836115;10653.680389588324;10907.509162087566;11013.118950484102;11142.72143196394;11145.721356965816;48657;49886.50113498818;50065;51555.93776248908;52782.472175277755;61145;65007;70860;71000.64499677469;71877.64061179661;13147.671308217372;13150.473981041034;13260.403281852146;13374.598762037154;13388.464461421434;14823.555293341216;14826.110433374031;14867.628309291922;14900.478483253035;15243.847561524242;13985.300734963008;14025.3688584016;14200.360983755962;14246.928765356108;14323.639254270827;12781;13076.542321018718;13720.245386503839;14108.58914108581;14349;10895.727606809893;13345.599632010666;13524.729505410034;13982.790258146339;13994.230317332644;9740;10986.39523534421;11243.212975091665;11272.210945233755;11898.22661526998;9526;10268.921763215054;11058.502367393647;11216.429699841865;11298.774024519627 22:00:28 INFO - PROCESS | 7140 | __end_tp_report 22:00:28 INFO - PROCESS | 7140 | __start_cc_report 22:00:28 INFO - PROCESS | 7140 | _x_x_mozilla_cycle_collect,395 22:00:28 INFO - PROCESS | 7140 | __end_cc_report 22:00:28 INFO - PROCESS | 7140 | __startTimestamp1486101628147__endTimestamp 22:00:28 INFO - PROCESS | 7140 | 22:00:28 INFO - PROCESS | 7140 | ------- Summary: start ------- 22:00:28 INFO - PROCESS | 7140 | Number of tests: 6 22:00:28 INFO - PROCESS | 7140 | 22:00:28 INFO - PROCESS | 7140 | [#0] dojo.html Cycles:165 Average:5720.76 Median:5947.64 stddev:694.24 (11.7%) stddev-sans-first:655.84 22:00:28 INFO - PROCESS | 7140 | Values: 2741.1 2897.5 3224.9 3328.6 3366.4 4540.2 5636.7 5691.8 5704.3 5721.3 5572.5 5677.7 5720.6 5726.7 5801.5 5118.8 5153.7 5198.1 5297.3 5300.2 5235.3 5319.1 5332.8 5339.9 5384.5 5114.0 5150.2 5292.1 5294.5 5305.0 5169.1 5216.5 5249.1 5289.8 5324.1 5177.0 5235.5 5247.2 5259.9 5287.5 4183.5 4867.8 5716.4 5747.3 5770.4 5947.6 6174.4 6207.4 6209.7 6295.9 6257.4 6259.0 6264.8 6269.2 6273.4 5675.7 5892.7 5916.4 5930.9 5960.5 4973.7 5649.3 6224.4 6227.2 6249.7 4761.5 6184.1 6226.4 6246.0 6259.8 6180.8 6281.9 6294.2 6296.0 6301.7 6099.1 6115.2 6163.5 6175.6 6177.2 5344.0 6185.1 6187.5 6257.6 6263.5 5013.2 5024.8 5028.2 5028.7 5041.6 4376.6 4392.3 4395.4 4395.7 4479.3 5832.5 5868.3 5888.6 5891.8 5938.4 6235.4 6255.8 6255.9 6290.4 6294.3 5744.3 5783.5 5821.7 5893.9 5911.0 4958.6 5416.2 5500.2 5501.3 5547.1 6345.8 6351.6 6353.2 6354.8 6355.6 6111.3 6137.4 6286.4 6288.8 6289.7 6240.5 6266.2 6302.3 6329.4 6371.3 6223.3 6255.8 6264.8 6266.9 6274.6 5447.8 6140.2 6274.7 6292.7 6315.0 6066.4 6102.5 6116.0 6123.9 6202.0 5612.0 6148.6 6183.1 6193.3 6206.8 5772.8 5809.5 6046.2 6051.7 6096.8 6141.0 6153.9 6160.9 6187.2 6196.7 6018.1 6063.5 6098.0 6119.6 6120.7 22:00:28 INFO - PROCESS | 7140 | 22:00:28 INFO - PROCESS | 7140 | [#1] ext.html Cycles:180 Average:62740.17 Median:38195.62 stddev:129253.89 (338.4%) stddev-sans-first:129531.81 22:00:28 INFO - PROCESS | 7140 | Values: 428.1 451.1 498.3 521.5 565.7 38454.8 40362.6 40421.8 40434.6 40440.0 34476.4 41325.2 41955.4 42099.6 42168.0 8885.0 9173.3 9548.0 10971.2 11349.5 9289.8 11138.0 11198.1 11206.5 11211.2 9316.3 9555.7 11251.3 11254.2 11272.4 11089.9 11155.0 11215.5 11232.8 11255.7 11123.7 11232.1 11233.5 11241.0 11266.4 30907.7 38056.2 38058.6 38363.2 38445.8 3404.8 3952.0 3960.1 3962.2 3993.2 28652.7 29171.9 29233.0 29384.8 30128.8 3128.7 3129.8 3150.6 3152.8 3245.3 459024.0 488098.0 488129.6 489754.6 491199.0 49432.3 50661.0 56631.0 58143.7 58429.1 54327.6 54339.7 54488.7 54524.2 54777.0 15175.5 15313.0 15313.8 15316.3 15376.5 9314.5 11648.0 11716.5 11893.9 12039.3 14238.8 17777.1 17780.0 17800.5 17830.4 6785.5 7944.4 8020.8 8076.0 8130.6 844.2 855.2 857.6 864.9 870.2 26889.6 29141.6 29231.6 30063.7 30081.8 969.8 1077.4 1087.8 1093.9 1175.2 12816.4 14492.3 14826.0 15431.5 15526.9 512960.0 516129.0 516647.0 518221.0 518305.0 59563.4 60218.8 60299.0 60336.4 60403.1 1355.7 1402.9 1405.7 1413.7 1418.3 24393.4 28618.9 28851.0 29159.9 29700.4 51496.5 51781.0 51828.7 51852.7 52084.0 456493.0 456822.0 457018.0 458626.0 458910.0 24034.5 24641.0 26197.5 26417.5 26802.3 47741.8 48245.3 48751.0 48794.0 49013.0 29575.7 38332.6 38600.2 39968.8 40147.4 38961.0 39320.4 39421.8 40437.4 40573.6 37395.6 37471.0 37643.1 38824.8 38836.6 24911.9 34404.7 34488.7 34712.0 34730.4 19356.3 23818.6 25302.2 25454.1 25790.1 22:00:28 INFO - PROCESS | 7140 | 22:00:28 INFO - PROCESS | 7140 | [#2] jquery.html Cycles:180 Average:39691.22 Median:13990.29 stddev:123952.19 (886.0%) stddev-sans-first:124264.68 22:00:28 INFO - PROCESS | 7140 | Values: 337.6 345.2 395.8 397.1 425.4 15985.8 19778.8 20315.2 20482.3 20666.2 20768.0 20897.6 20986.6 21275.9 21631.9 4738.6 5239.3 5471.9 5618.7 6014.3 5898.3 5903.9 6015.3 6026.9 6059.6 4595.3 5272.7 5886.4 5909.9 5979.1 5880.9 5931.2 5938.6 5941.4 5947.4 5234.7 5466.9 5517.4 5525.8 5536.2 13308.7 13511.5 16969.3 25221.0 25417.0 2563.6 2808.9 2839.8 2860.7 2889.4 9915.6 9958.3 10043.9 10400.7 10523.8 1452.9 1786.3 1807.4 1817.6 1835.8 103908.0 126278.0 129317.4 136313.0 149374.0 11885.9 11946.0 11958.3 12079.9 12298.2 15494.6 15867.9 15872.3 15907.0 15924.8 8502.2 8663.6 8697.3 8738.0 8835.6 8298.8 8340.3 8466.8 8503.9 8514.1 4784.8 4860.5 4868.8 4889.5 4917.3 1810.8 1966.3 2067.5 2079.3 2080.9 973.8 993.6 996.4 996.7 1043.9 21459.1 21778.6 21823.8 21828.7 21919.5 881.4 958.3 995.2 1020.0 1042.8 10852.3 10940.2 11186.7 11270.3 11363.9 737910.0 745135.0 745791.3 750704.0 754840.0 111037.9 112716.4 153907.0 154346.0 156091.7 767.2 954.5 958.9 994.5 995.2 19897.2 20009.4 20212.7 20274.4 20415.5 46016.3 52377.7 53135.5 54272.0 56936.4 73823.0 75637.0 77922.6 79228.0 80030.2 8177.7 8357.6 8527.6 8625.5 8654.0 11509.9 11584.2 13154.5 13180.0 13336.2 10445.7 10778.8 10986.1 11333.7 11458.8 11123.8 11319.5 11398.9 11669.5 11891.3 11039.0 11373.8 11787.8 11873.3 12018.4 8344.5 8364.5 8502.9 8614.9 8619.4 10344.9 11269.0 11521.0 11530.7 11556.0 22:00:28 INFO - PROCESS | 7140 | 22:00:28 INFO - PROCESS | 7140 | [#3] mootools.html Cycles:180 Average:5365.18 Median:5671.62 stddev:2855.68 (50.4%) stddev-sans-first:2848.19 22:00:28 INFO - PROCESS | 7140 | Values: 1406.1 1413.1 1422.8 1429.7 1431.6 6928.4 8032.5 8351.0 8433.0 8434.2 8161.1 8397.8 8401.6 8402.6 8426.7 2282.2 2284.0 2330.4 2330.9 2330.9 2284.2 2326.9 2332.1 2339.3 2339.7 2273.8 2275.0 2300.0 2302.4 2304.7 1888.4 2211.1 2262.9 2300.7 2306.5 1773.5 1991.6 2311.5 2318.0 2339.4 8049.0 8297.8 8304.8 8396.9 8410.7 5063.6 5190.6 5224.3 5232.5 5261.2 3454.3 3481.7 3559.0 3592.8 3632.8 1228.1 1229.8 1242.6 1254.1 1283.9 8955.7 9086.4 9298.2 9780.9 10701.5 4429.8 4659.3 4712.1 4712.6 4753.0 9390.5 9407.2 9471.3 9484.1 9491.0 5186.7 5240.8 5245.9 5247.8 5248.4 4228.2 4453.3 4684.7 4755.9 4808.5 3906.9 3943.7 3966.9 4014.6 4077.3 1278.0 1293.7 1302.2 1302.6 1310.6 847.2 884.2 895.5 905.4 938.7 5334.2 5636.3 5674.1 5692.7 5786.2 887.9 977.5 978.8 980.6 987.5 3722.3 3742.9 3783.4 3853.4 3856.9 9525.0 9721.6 9801.1 9808.6 9821.7 9756.2 9763.2 9764.1 9804.7 9839.1 7351.0 7698.9 7700.5 7774.3 7833.4 5509.3 5648.7 5669.1 5697.6 5738.5 8772.2 8954.4 9061.8 9100.0 9136.5 8804.6 8908.5 9025.6 9113.3 9295.4 6294.6 6886.9 7091.1 7096.5 7105.7 7636.8 7702.0 7715.4 7740.4 7864.7 7047.9 7136.4 7140.2 7143.3 7194.2 6893.9 6971.1 6976.2 7001.4 7016.0 6290.5 6386.5 6390.9 6393.1 6415.2 4849.9 4870.0 4924.6 4963.2 5115.6 3994.9 4972.1 4995.7 5033.5 5085.0 22:00:28 INFO - PROCESS | 7140 | 22:00:28 INFO - PROCESS | 7140 | [#4] prototype.html Cycles:180 Average:4583.77 Median:5108.44 stddev:1402.26 (27.4%) stddev-sans-first:1370.00 22:00:28 INFO - PROCESS | 7140 | Values: 366.2 367.1 373.8 373.9 384.2 4961.5 5163.7 5237.6 5265.8 5564.6 4318.4 4787.1 5267.3 5404.7 5437.6 3187.2 3397.7 4106.4 4216.6 4273.7 3290.0 3519.7 3852.8 4279.4 4296.4 3298.8 4170.1 4173.7 4238.7 4246.9 3833.4 3858.7 4205.8 4214.3 4221.7 3461.7 3751.5 3763.8 3771.6 3792.9 5107.5 5165.9 5237.5 5271.3 5303.6 4474.6 4911.7 4932.1 4938.8 4939.6 5780.4 5802.3 5808.7 5829.3 5857.2 4008.3 4122.9 4160.3 4171.7 4179.6 5816.3 5819.9 5975.8 6100.5 6147.4 3509.2 4219.3 4231.0 4232.8 4242.0 4260.8 4302.6 4305.6 4306.3 4323.7 3755.7 3892.9 4113.9 4136.3 4935.5 5110.4 5314.1 5712.8 5789.9 5812.7 1237.2 1321.7 1348.9 1392.1 1406.0 911.7 974.7 988.2 989.2 997.8 4653.2 4700.5 5109.3 5135.7 5155.3 4905.8 5305.8 5532.0 5703.0 5750.5 4574.4 4927.6 4980.7 5027.9 5468.0 2560.6 2664.8 2668.7 2933.4 2961.8 4812.6 4970.9 5499.2 5828.5 5928.4 4965.2 5125.8 5345.5 5849.2 5884.8 5093.7 5887.0 5928.4 5961.6 5980.9 4850.5 5280.0 5605.7 5691.7 5727.7 4566.4 4589.1 5585.6 5630.5 5640.1 5297.7 5921.8 6036.6 6143.0 6186.7 3075.3 3276.0 3606.6 3782.9 3885.6 5045.6 5702.7 5751.8 5975.2 6022.3 5494.5 5703.6 5770.8 5784.5 5785.7 5005.6 5519.4 5767.3 5789.3 5792.6 4611.9 5313.7 5569.4 5637.3 5659.8 5137.7 5741.5 5792.9 5810.9 5823.9 5632.3 5635.9 5646.9 5667.5 5691.0 22:00:28 INFO - PROCESS | 7140 | 22:00:28 INFO - PROCESS | 7140 | [#5] yui.html Cycles:180 Average:20205.89 Median:3693.71 stddev:28879.73 (781.9%) stddev-sans-first:28923.57 22:00:28 INFO - PROCESS | 7140 | Values: 689.7 695.3 703.6 782.7 795.8 14567.2 15509.2 15542.5 15761.0 15876.4 14127.2 14331.7 14436.2 14563.8 14619.0 870.9 876.0 881.4 882.0 884.5 849.1 874.1 876.2 880.7 885.0 915.0 923.0 925.4 927.9 928.4 903.7 912.7 912.8 917.4 919.5 669.9 670.2 690.7 692.0 694.0 14574.8 15164.8 15275.3 15563.2 15615.8 14016.5 14374.2 14423.6 14424.1 14426.9 11921.9 11942.8 12025.0 12078.5 12124.4 3636.6 3702.1 3715.2 3723.5 3745.4 109275.5 111704.3 113202.0 113682.2 113896.9 10658.6 10678.1 10770.1 10770.2 11120.1 22242.1 22386.3 22394.9 22395.7 22459.2 7512.7 7613.3 7618.0 7687.8 7798.4 4273.3 4289.8 4377.5 4389.5 4426.7 3608.5 3685.3 3721.6 3800.6 4035.8 557.8 706.4 709.6 715.3 728.0 646.7 662.6 682.2 691.5 694.7 10230.2 10236.1 10392.3 10439.7 10575.3 586.6 670.7 693.8 706.9 720.9 4205.6 4846.9 5080.9 5083.4 5089.2 105470.0 107876.5 107989.0 109027.0 109311.0 67029.0 71323.0 72965.0 73135.6 75969.6 66250.7 67088.0 67397.0 69906.6 70903.6 10653.7 10907.5 11013.1 11142.7 11145.7 48657.0 49886.5 50065.0 51555.9 52782.5 61145.0 65007.0 70860.0 71000.6 71877.6 13147.7 13150.5 13260.4 13374.6 13388.5 14823.6 14826.1 14867.6 14900.5 15243.8 13985.3 14025.4 14200.4 14246.9 14323.6 12781.0 13076.5 13720.2 14108.6 14349.0 10895.7 13345.6 13524.7 13982.8 13994.2 9740.0 10986.4 11243.2 11272.2 11898.2 9526.0 10268.9 11058.5 11216.4 11298.8 22:00:28 INFO - PROCESS | 7140 | -------- Summary: end -------- 22:00:28 INFO - PROCESS | 7140 | 22:00:28 INFO - TEST-INFO | 7140: exit 0 22:00:28 INFO - TEST-OK | dromaeo_css | took 1115351ms 22:00:28 INFO - TEST-START | kraken 22:00:28 INFO - operating with platform_type : mac_ 22:00:28 INFO - Initialising browser for kraken test... 22:00:28 INFO - TEST-INFO | started process 7145 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/T/tmpuWKLZu/profile http://localhost:63918/getInfo.html) 22:00:31 INFO - PROCESS | 7145 | JavaScript error: chrome://webcompat/content/lib/ua_overrider.jsm, line 109: NS_ERROR_INSUFFICIENT_DOMAIN_LEVELS: Component returned failure code: 0x804b0050 (NS_ERROR_INSUFFICIENT_DOMAIN_LEVELS) [nsIEffectiveTLDService.getBaseDomain] 22:00:31 INFO - PROCESS | 7145 | 2017-02-02 22:00:31.601 plugin-container[7146:134725] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x873f, name = 'com.apple.tsm.portname' 22:00:31 INFO - PROCESS | 7145 | See /usr/include/servers/bootstrap_defs.h for the error codes. 22:00:31 INFO - PROCESS | 7145 | 2017-02-02 22:00:31.603 plugin-container[7146:134725] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8d0b, name = 'com.apple.CFPasteboardClient' 22:00:31 INFO - PROCESS | 7145 | See /usr/include/servers/bootstrap_defs.h for the error codes. 22:00:31 INFO - PROCESS | 7145 | 2017-02-02 22:00:31.603 plugin-container[7146:134725] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 22:00:31 INFO - PROCESS | 7145 | __metrics Screen width/height:1600/1200 22:00:31 INFO - PROCESS | 7145 | colorDepth:24 22:00:31 INFO - PROCESS | 7145 | Browser inner width/height: 1024/689 22:00:31 INFO - PROCESS | 7145 | __metrics 22:00:31 ERROR - PROCESS | 7145 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3283: TypeError: this.tabs is undefined 22:00:31 INFO - PROCESS | 7145 | 1486101631782 addons.xpi WARN Exception running bootstrap method shutdown on shield-recipe-client@mozilla.org: ReferenceError: LogManager is not defined (resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:65:3) JS Stack trace: this.shutdown@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@bootstrap.js:65:3 < callBootstrapMethod@XPIProvider.jsm:4903:11 < observe@XPIProvider.jsm:2884:15 < forceQuit@jar:file:///var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/T/tmpuWKLZu/profile/extensions/talos-powers@TalosPowersService.js:238:7 < whenDone@jar:file:///var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/T/tmpuWKLZu/profile/extensions/talos-powers@TalosPowersService.js:223:9 < promise callback*forceQuit@jar:file:///var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/T/tmpuWKLZu/profile/extensions/talos-powers@TalosPowersService.js:225:7 < receiveMessage@jar:file:///var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/T/tmpuWKLZu/profile/extensions/talos-powers@TalosPowersService.js:55:9 22:00:32 INFO - TEST-INFO | 7145: exit 0 22:00:32 INFO - Browser initialized. 22:00:32 INFO - Running cycle 1/1 for kraken test... 22:00:32 INFO - TEST-INFO | started process 7147 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/T/tmpuWKLZu/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:00:35 INFO - PROCESS | 7147 | 2017-02-02 22:00:35.192 plugin-container[7148:134840] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8b4f, name = 'com.apple.tsm.portname' 22:00:35 INFO - PROCESS | 7147 | See /usr/include/servers/bootstrap_defs.h for the error codes. 22:00:35 INFO - PROCESS | 7147 | 2017-02-02 22:00:35.192 plugin-container[7148:134840] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9113, name = 'com.apple.CFPasteboardClient' 22:00:35 INFO - PROCESS | 7147 | See /usr/include/servers/bootstrap_defs.h for the error codes. 22:00:35 INFO - PROCESS | 7147 | 2017-02-02 22:00:35.192 plugin-container[7148:134840] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 22:00:44 INFO - PROCESS | 7147 | RSS: Main: 234250240 22:00:44 INFO - PROCESS | 7147 | 22:00:47 INFO - PROCESS | 7147 | Cycle 1(1): loaded http://localhost:63918/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:63918/tests/kraken/driver.html?testName=audio-beat-detection) 22:00:48 INFO - PROCESS | 7147 | RSS: Main: 247660544 22:00:48 INFO - PROCESS | 7147 | 22:00:51 INFO - PROCESS | 7147 | Cycle 1(1): loaded http://localhost:63918/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:63918/tests/kraken/driver.html?testName=audio-dft) 22:00:52 INFO - PROCESS | 7147 | RSS: Main: 255549440 22:00:52 INFO - PROCESS | 7147 | 22:00:55 INFO - PROCESS | 7147 | Cycle 1(1): loaded http://localhost:63918/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:63918/tests/kraken/driver.html?testName=audio-fft) 22:00:56 INFO - PROCESS | 7147 | RSS: Main: 258330624 22:00:56 INFO - PROCESS | 7147 | 22:00:58 INFO - PROCESS | 7147 | Cycle 1(1): loaded http://localhost:63918/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:63918/tests/kraken/driver.html?testName=audio-oscillator) 22:00:59 INFO - PROCESS | 7147 | RSS: Main: 260452352 22:00:59 INFO - PROCESS | 7147 | 22:01:01 INFO - PROCESS | 7147 | Cycle 1(1): loaded http://localhost:63918/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:63918/tests/kraken/driver.html?testName=imaging-gaussian-blur) 22:01:01 INFO - PROCESS | 7147 | RSS: Main: 261132288 22:01:01 INFO - PROCESS | 7147 | 22:01:04 INFO - PROCESS | 7147 | Cycle 1(1): loaded http://localhost:63918/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:63918/tests/kraken/driver.html?testName=imaging-darkroom) 22:01:05 INFO - PROCESS | 7147 | RSS: Main: 266104832 22:01:05 INFO - PROCESS | 7147 | 22:01:08 INFO - PROCESS | 7147 | Cycle 1(1): loaded http://localhost:63918/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:63918/tests/kraken/driver.html?testName=imaging-desaturate) 22:01:08 INFO - PROCESS | 7147 | RSS: Main: 265236480 22:01:08 INFO - PROCESS | 7147 | 22:01:11 INFO - PROCESS | 7147 | Cycle 1(1): loaded http://localhost:63918/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:63918/tests/kraken/driver.html?testName=json-parse-financial) 22:01:12 INFO - PROCESS | 7147 | RSS: Main: 265367552 22:01:12 INFO - PROCESS | 7147 | 22:01:14 INFO - PROCESS | 7147 | Cycle 1(1): loaded http://localhost:63918/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:63918/tests/kraken/driver.html?testName=json-stringify-tinderbox) 22:01:14 INFO - PROCESS | 7147 | RSS: Main: 265052160 22:01:14 INFO - PROCESS | 7147 | 22:01:16 INFO - PROCESS | 7147 | Cycle 1(1): loaded http://localhost:63918/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:63918/tests/kraken/driver.html?testName=stanford-crypto-aes) 22:01:17 INFO - PROCESS | 7147 | RSS: Main: 264990720 22:01:17 INFO - PROCESS | 7147 | 22:01:19 INFO - PROCESS | 7147 | Cycle 1(1): loaded http://localhost:63918/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:63918/tests/kraken/driver.html?testName=stanford-crypto-ccm) 22:01:20 INFO - PROCESS | 7147 | RSS: Main: 264974336 22:01:20 INFO - PROCESS | 7147 | 22:01:23 INFO - PROCESS | 7147 | Cycle 1(1): loaded http://localhost:63918/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:63918/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 22:01:23 INFO - PROCESS | 7147 | RSS: Main: 265318400 22:01:23 INFO - PROCESS | 7147 | 22:01:27 INFO - PROCESS | 7147 | Cycle 1(1): loaded http://localhost:63918/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:63918/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 22:01:27 INFO - PROCESS | 7147 | RSS: Main: 265121792 22:01:27 INFO - PROCESS | 7147 | 22:01:29 INFO - PROCESS | 7147 | Cycle 1(1): loaded http://localhost:63918/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:63918/tests/kraken/driver.html?testName=ai-astar) 22:01:29 INFO - PROCESS | 7147 | RSS: Main: 267022336 22:01:29 INFO - PROCESS | 7147 | 22:01:29 INFO - PROCESS | 7147 | __start_tp_report 22:01:29 INFO - PROCESS | 7147 | _x_x_mozilla_page_load 22:01:29 INFO - PROCESS | 7147 | _x_x_mozilla_page_load_details 22:01:29 INFO - PROCESS | 7147 | |i|pagename|runs| 22:01:29 INFO - PROCESS | 7147 | |0;ai-astar;102;113;118;117;117;115;117;118;139;119 22:01:29 INFO - PROCESS | 7147 | |1;audio-beat-detection;189;129;116;152;113;116;133;144;141;147 22:01:29 INFO - PROCESS | 7147 | |2;audio-dft;211;208;261;183;191;278;277;224;195;203 22:01:29 INFO - PROCESS | 7147 | |3;audio-fft;83;57;58;85;57;58;64;60;90;87 22:01:29 INFO - PROCESS | 7147 | |4;audio-oscillator;89;74;85;75;71;75;103;101;104;95 22:01:29 INFO - PROCESS | 7147 | |5;imaging-gaussian-blur;90;90;105;90;90;132;136;88;89;87 22:01:29 INFO - PROCESS | 7147 | |6;imaging-darkroom;95;96;96;95;100;95;107;157;115;104 22:01:29 INFO - PROCESS | 7147 | |7;imaging-desaturate;92;98;91;94;95;92;91;95;98;137 22:01:29 INFO - PROCESS | 7147 | |8;json-parse-financial;64;64;64;65;66;64;66;65;65;65 22:01:29 INFO - PROCESS | 7147 | |9;json-stringify-tinderbox;70;74;78;73;71;69;105;75;76;74 22:01:29 INFO - PROCESS | 7147 | |10;stanford-crypto-aes;88;91;88;86;86;90;91;98;87;87 22:01:29 INFO - PROCESS | 7147 | |11;stanford-crypto-ccm;137;137;139;142;143;166;142;131;199;153 22:01:29 INFO - PROCESS | 7147 | |12;stanford-crypto-pbkdf2;175;172;184;216;173;237;248;175;181;175 22:01:29 INFO - PROCESS | 7147 | |13;stanford-crypto-sha256-iterative;92;61;65;76;90;93;89;80;71;66 22:01:29 INFO - PROCESS | 7147 | __end_tp_report 22:01:29 INFO - PROCESS | 7147 | __start_cc_report 22:01:29 INFO - PROCESS | 7147 | _x_x_mozilla_cycle_collect,920 22:01:29 INFO - PROCESS | 7147 | __end_cc_report 22:01:29 INFO - PROCESS | 7147 | __startTimestamp1486101689766__endTimestamp 22:01:29 INFO - PROCESS | 7147 | 22:01:29 INFO - PROCESS | 7147 | ------- Summary: start ------- 22:01:29 INFO - PROCESS | 7147 | Number of tests: 14 22:01:29 INFO - PROCESS | 7147 | 22:01:29 INFO - PROCESS | 7147 | [#0] ai-astar Cycles:10 Average:117.50 Median:117.50 stddev:9.02 (7.7%) stddev-sans-first:7.63 22:01:29 INFO - PROCESS | 7147 | Values: 102.0 113.0 118.0 117.0 117.0 115.0 117.0 118.0 139.0 119.0 22:01:29 INFO - PROCESS | 7147 | 22:01:29 INFO - PROCESS | 7147 | [#1] audio-beat-detection Cycles:10 Average:138.00 Median:142.50 stddev:22.66 (15.9%) stddev-sans-first:14.71 22:01:29 INFO - PROCESS | 7147 | Values: 189.0 129.0 116.0 152.0 113.0 116.0 133.0 144.0 141.0 147.0 22:01:29 INFO - PROCESS | 7147 | 22:01:29 INFO - PROCESS | 7147 | [#2] audio-dft Cycles:10 Average:223.10 Median:217.50 stddev:35.84 (16.5%) stddev-sans-first:37.75 22:01:29 INFO - PROCESS | 7147 | Values: 211.0 208.0 261.0 183.0 191.0 278.0 277.0 224.0 195.0 203.0 22:01:29 INFO - PROCESS | 7147 | 22:01:29 INFO - PROCESS | 7147 | [#3] audio-fft Cycles:10 Average:69.90 Median:73.50 stddev:14.32 (19.5%) stddev-sans-first:14.38 22:01:29 INFO - PROCESS | 7147 | Values: 83.0 57.0 58.0 85.0 57.0 58.0 64.0 60.0 90.0 87.0 22:01:29 INFO - PROCESS | 7147 | 22:01:29 INFO - PROCESS | 7147 | [#4] audio-oscillator Cycles:10 Average:87.20 Median:75.00 stddev:13.02 (17.4%) stddev-sans-first:13.79 22:01:29 INFO - PROCESS | 7147 | Values: 89.0 74.0 85.0 75.0 71.0 75.0 103.0 101.0 104.0 95.0 22:01:29 INFO - PROCESS | 7147 | 22:01:29 INFO - PROCESS | 7147 | [#5] imaging-gaussian-blur Cycles:10 Average:99.70 Median:89.50 stddev:18.79 (21.0%) stddev-sans-first:19.60 22:01:29 INFO - PROCESS | 7147 | Values: 90.0 90.0 105.0 90.0 90.0 132.0 136.0 88.0 89.0 87.0 22:01:29 INFO - PROCESS | 7147 | 22:01:29 INFO - PROCESS | 7147 | [#6] imaging-darkroom Cycles:10 Average:106.00 Median:95.00 stddev:19.11 (20.1%) stddev-sans-first:19.85 22:01:29 INFO - PROCESS | 7147 | Values: 95.0 96.0 96.0 95.0 100.0 95.0 107.0 157.0 115.0 104.0 22:01:29 INFO - PROCESS | 7147 | 22:01:29 INFO - PROCESS | 7147 | [#7] imaging-desaturate Cycles:10 Average:98.30 Median:94.50 stddev:13.84 (14.6%) stddev-sans-first:14.49 22:01:29 INFO - PROCESS | 7147 | Values: 92.0 98.0 91.0 94.0 95.0 92.0 91.0 95.0 98.0 137.0 22:01:29 INFO - PROCESS | 7147 | 22:01:29 INFO - PROCESS | 7147 | [#8] json-parse-financial Cycles:10 Average:64.80 Median:65.00 stddev:0.79 (1.2%) stddev-sans-first:0.78 22:01:29 INFO - PROCESS | 7147 | Values: 64.0 64.0 64.0 65.0 66.0 64.0 66.0 65.0 65.0 65.0 22:01:29 INFO - PROCESS | 7147 | 22:01:29 INFO - PROCESS | 7147 | [#9] json-stringify-tinderbox Cycles:10 Average:76.50 Median:74.00 stddev:10.38 (14.0%) stddev-sans-first:10.74 22:01:29 INFO - PROCESS | 7147 | Values: 70.0 74.0 78.0 73.0 71.0 69.0 105.0 75.0 76.0 74.0 22:01:29 INFO - PROCESS | 7147 | 22:01:29 INFO - PROCESS | 7147 | [#10] stanford-crypto-aes Cycles:10 Average:89.20 Median:89.00 stddev:3.61 (4.1%) stddev-sans-first:3.81 22:01:29 INFO - PROCESS | 7147 | Values: 88.0 91.0 88.0 86.0 86.0 90.0 91.0 98.0 87.0 87.0 22:01:29 INFO - PROCESS | 7147 | 22:01:29 INFO - PROCESS | 7147 | [#11] stanford-crypto-ccm Cycles:10 Average:148.90 Median:142.50 stddev:20.14 (14.1%) stddev-sans-first:20.90 22:01:29 INFO - PROCESS | 7147 | Values: 137.0 137.0 139.0 142.0 143.0 166.0 142.0 131.0 199.0 153.0 22:01:29 INFO - PROCESS | 7147 | 22:01:29 INFO - PROCESS | 7147 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:193.60 Median:182.50 stddev:28.91 (15.8%) stddev-sans-first:29.87 22:01:29 INFO - PROCESS | 7147 | Values: 175.0 172.0 184.0 216.0 173.0 237.0 248.0 175.0 181.0 175.0 22:01:29 INFO - PROCESS | 7147 | 22:01:29 INFO - PROCESS | 7147 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:78.30 Median:84.50 stddev:12.22 (14.5%) stddev-sans-first:11.91 22:01:29 INFO - PROCESS | 7147 | Values: 92.0 61.0 65.0 76.0 90.0 93.0 89.0 80.0 71.0 66.0 22:01:29 INFO - PROCESS | 7147 | -------- Summary: end -------- 22:01:29 INFO - PROCESS | 7147 | 22:01:30 INFO - TEST-INFO | 7147: exit 0 22:01:30 INFO - TEST-OK | kraken | took 61897ms 22:01:30 INFO - SUITE-END | took 1177s 22:01:30 INFO - Completed test suite (00:19:37) 22:01:30 INFO - javascript benchmark 22:01:30 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7288.877017355852, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2741.109139529653, 2897.521908885034, 3224.935501289974, 3328.6338502764697, 3366.394049071168, 4540.160070386981, 5636.689982050991, 5691.800786972457, 5704.344000439951, 5721.284839395075, 5572.4706152915405, 5677.659340439566, 5720.570957178207, 5726.68503232322, 5801.506871915893, 5118.795248190068, 5153.690778553289, 5198.116320225571, 5297.311349524575, 5300.151975683892, 5235.345581802275, 5319.122344813101, 5332.840014799563, 5339.946600533984, 5384.488473595002, 5113.951639913808, 5150.150225212873, 5292.100342941709, 5294.549963253128, 5304.973475132639, 5169.147090730025, 5216.478352164791, 5249.055170069386, 5289.814856480005, 5324.094903866313, 5176.974115129438, 5235.528802407764, 5247.1866860636665, 5259.921101183485, 5287.47125287472, 4183.518895327046, 4867.8317203871175, 5716.399778023289, 5747.252857128547, 5770.394108618577, 5947.643141411486, 6174.351693072208, 6207.441330280251, 6209.658468784215, 6295.905561416583, 6257.374262573751, 6259.029850373199, 6264.843378915518, 6269.2476902771505, 6273.404026617464, 5675.7445914933105, 5892.705364731789, 5916.3787802281, 5930.8813823723285, 5960.5231581474245, 4973.701577905337, 5649.265595472562, 6224.408681175281, 6227.159333490012, 6249.7187626556915, 4761.500042495557, 6184.134221209034, 6226.40849119333, 6246.031865060922, 6259.812205633838, 6180.783672571529, 6281.937180628227, 6294.181756371642, 6296.024116261987, 6301.653409062454, 6099.146119543268, 6115.240341084353, 6163.537734669918, 6175.62946223228, 6177.227846519185, 5344.0, 6185.103160041769, 6187.474064704461, 6257.624542527463, 6263.467605253604, 5013.248012798036, 5024.824131155465, 5028.245763135559, 5028.748562571856, 5041.59667226621, 4376.606105450524, 4392.253316936129, 4395.406620106309, 4395.692301538926, 4479.260921947907, 5832.504237139806, 5868.295804503401, 5888.617239879394, 5891.823245302648, 5938.376470470625, 6235.438810507076, 6255.843603909939, 6255.937440625627, 6290.402411770874, 6294.307626161119, 5744.310682718101, 5783.508401785895, 5821.679807610537, 5893.941060589425, 5911.0, 4958.578520825698, 5416.160495123262, 5500.22996780443, 5501.257330260363, 5547.11246200606, 6345.809625711236, 6351.555391122577, 6353.237611486651, 6354.809355719336, 6355.5551111421755, 6111.318268824315, 6137.376985335611, 6286.402791734778, 6288.811335659938, 6289.716962736779, 6240.53196010292, 6266.185395898504, 6302.275238347649, 6329.398707122816, 6371.299157092809, 6223.3465486123305, 6255.812325630239, 6264.780732674334, 6266.905996409966, 6274.592151510137, 5447.836564903059, 6140.17107690456, 6274.749010039547, 6292.685365731787, 6314.989601663712, 6066.393360663898, 6102.511799056154, 6116.031445915052, 6123.877522449615, 6202.0, 5611.971940140436, 6148.569600127948, 6183.134361189347, 6193.287771906288, 6206.751729930752, 5772.797952071657, 5809.477147056617, 6046.183765191643, 6051.667158306335, 6096.786612468542, 6141.0, 6153.876922461615, 6160.907586386118, 6187.226596675416, 6196.659183744757, 6018.080154687997, 6063.484603808725, 6098.0, 6119.602225855305, 6120.724567394567], "value": 5674.430316791178, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [428.05613621963573, 451.0640421126163, 498.34467675007375, 521.5202014146987, 565.7312776431193, 38454.807725961364, 40362.59637403625, 40421.78734637966, 40434.59565404345, 40439.989000274974, 34476.44855981481, 41325.17349653013, 41955.37066943998, 42099.57900420995, 42168.0, 8884.955575222148, 9173.312001599874, 9547.95226023869, 10971.23201375904, 11349.489272982695, 9289.814203715921, 11138.0, 11198.104151667887, 11206.495707693173, 11211.215214934959, 9316.347855650074, 9555.665551705657, 11251.324920504754, 11254.155938304619, 11272.380019098946, 11089.889101108967, 11155.0, 11215.495302711397, 11232.831507527393, 11255.718607034807, 11123.666290011313, 11232.10143188543, 11233.494492747846, 11241.044511216556, 11266.380349080797, 30907.690923090708, 38056.23887522234, 38058.61941380606, 38363.23273534514, 38445.80777096125, 3404.795712257248, 3952.032282531636, 3960.128771670228, 3962.167944731581, 3993.1614360984227, 28652.71347286542, 29171.854140729374, 29233.0, 29384.824607015908, 30128.849355753082, 3128.7184153426074, 3129.7809153359276, 3150.6061742282213, 3152.810831350126, 3245.269814291789, 459024.0, 488098.0, 488129.5593522045, 489754.55122724513, 491199.0, 49432.25851612229, 50661.0, 56631.0, 58143.709281453324, 58429.12356314574, 54327.64180895509, 54339.72830135824, 54488.72755636196, 54524.18213726797, 54777.0, 15175.544733658007, 15313.004654697632, 15313.846861531465, 15316.310766015333, 15376.538703838904, 9314.48770317639, 11648.0, 11716.472758725879, 11893.940530297294, 12039.337836418907, 14238.786418203736, 17777.111144442984, 17780.0, 17800.46598602044, 17830.37593684215, 6785.5250132490255, 7944.44388892772, 8020.799480013046, 8076.0, 8130.552819594861, 844.2064459408136, 855.1918437077027, 857.6140736668524, 864.8572985457455, 870.2073728120093, 26889.596656050177, 29141.56287655687, 29231.56152657712, 30063.69936300609, 30081.849590751906, 969.8205831920976, 1077.3966578715945, 1087.7661302819931, 1093.907017903487, 1175.1949914308725, 12816.42326095328, 14492.275386230645, 14826.0, 15431.459898903484, 15526.9223653881, 512960.0, 516129.0, 516647.0, 518221.0, 518305.0, 59563.40436595665, 60218.79562408727, 60299.0, 60336.396636033955, 60403.09395359073, 1355.6610847288177, 1402.8667276608705, 1405.676694360303, 1413.7384583851815, 1418.290854572693, 24393.390165245302, 28618.85690571534, 28851.0, 29159.85420072886, 29700.405991880474, 51496.48503514917, 51780.964380712925, 51828.74085629699, 51852.74073629608, 52084.0, 456493.0, 456822.0, 457018.0, 458626.0, 458910.0, 24034.51930961406, 24641.0, 26197.476050479265, 26417.471650567266, 26802.32994175083, 47741.761291193325, 48245.276320854515, 48751.02497950092, 48794.0, 49013.0, 29575.704242958156, 38332.61667383402, 38600.227995440495, 39968.80015599904, 40147.397789032606, 38961.0, 39320.41019384655, 39421.80289098651, 40437.393439099025, 40573.594264056985, 37395.62604373921, 37471.0, 37643.05892352713, 38824.805875970436, 38836.6116338833, 24911.87544062268, 34404.65595344115, 34488.65511344854, 34712.0, 34730.41436997602, 19356.32252871199, 23818.64272035886, 25302.240932772045, 25454.109106181935, 25790.09734659353], "value": 19566.900920999327, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [337.56622739779374, 345.2335814491828, 395.778364116095, 397.1005672152573, 425.41293015638416, 15985.76021359679, 19778.802211977876, 20315.187392504282, 20482.2831200908, 20666.173353065897, 20768.0, 20897.582048359025, 20986.580268394624, 21275.893620531875, 21631.918404079774, 4738.620910327166, 5239.318888544484, 5471.890562188753, 5618.719064046802, 6014.278286605613, 5898.262717160355, 5903.88192236155, 6015.308239552442, 6026.939730602704, 6059.606125601822, 4595.333676616889, 5272.657277276984, 5886.440788125143, 5909.852253693671, 5979.133025711291, 5880.852978675524, 5931.169636250928, 5938.554608404387, 5941.4355636214705, 5947.4052594740615, 5234.659747116463, 5466.945330546723, 5517.420670829545, 5525.806596769134, 5536.2249285100115, 13308.66913308671, 13511.500372431132, 16969.3212271509, 25221.0, 25417.0, 2563.577009793382, 2808.8595570221405, 2839.801213915027, 2860.728230818069, 2889.3643398452305, 9915.61495785715, 9958.25313101513, 10043.89956100437, 10400.687979360631, 10523.84214236787, 1452.898297119202, 1786.330126202674, 1807.3674214025118, 1817.581956150093, 1835.7888842783118, 103908.0, 126278.0, 129317.35341323422, 136313.0, 149374.0, 11885.881141188478, 11946.044316454663, 11958.342291173894, 12079.939600301943, 12298.20061695987, 15494.612634683997, 15867.920660396625, 15872.285747141406, 15907.0455772654, 15924.840751592335, 8502.192291732275, 8663.610137543825, 8697.26073283778, 8737.956310218407, 8835.55822208887, 8298.8340233195, 8340.29107525855, 8466.788330291793, 8503.872441913376, 8514.10601886793, 4784.784684689197, 4860.4896485869185, 4868.805247790119, 4889.511048895082, 4917.336159618406, 1810.8098649641597, 1966.2823069579786, 2067.5010617302637, 2079.3346129238803, 2080.8543401961715, 973.7516933181993, 993.572763711611, 996.3573495095768, 996.6611352140308, 1043.9478026098664, 21459.141634334763, 21778.564428711656, 21823.781762182494, 21828.672569911465, 21919.452013699465, 881.3566096749408, 958.2525630008604, 995.2137811129127, 1019.9694009179736, 1042.838360054193, 10852.348859068481, 10940.23418360723, 11186.664400068012, 11270.267432616854, 11363.943180284046, 737910.0, 745135.0, 745791.2710436413, 750704.0, 754840.0, 111037.88962110276, 112716.43641781739, 153907.0, 154346.0, 156091.70196860205, 767.1982777997007, 954.5275088831212, 958.91849192818, 994.4778991029767, 995.2237254941158, 19897.204111835363, 20009.399718008484, 20212.696809548164, 20274.391768246976, 20415.4896127598, 46016.309755353024, 52377.690557736365, 53135.46864531305, 54272.0, 56936.43063569311, 73823.0, 75637.0, 77922.61038694771, 79228.0, 80030.19969800228, 8177.713780017909, 8357.623906924082, 8527.616257268563, 8625.525596092024, 8654.04805471385, 11509.906558876894, 11584.189106762782, 13154.539591114266, 13180.0, 13336.19982801035, 10445.738856528344, 10778.784424311627, 10986.082764884108, 11333.716657083638, 11458.796826333115, 11123.777524449628, 11319.547218111184, 11398.94300528492, 11669.474873630537, 11891.345975971059, 11039.0, 11373.772524549628, 11787.823182652439, 11873.346965916957, 12018.399080045789, 8344.541050242295, 8364.498130112213, 8502.872456913276, 8614.913850861662, 8619.396642235235, 10344.948275258575, 11269.0, 11521.020713239468, 11530.711732206762, 11556.0], "value": 9757.123315570785, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1406.128200515681, 1413.1238632048132, 1422.7581311177098, 1429.7140571885625, 1431.6492459347457, 6928.445724342053, 8032.477888937222, 8350.958245208772, 8433.0, 8434.24091831735, 8161.143079976606, 8397.832043359129, 8401.579921003957, 8402.621882015308, 8426.747197584082, 2282.2126366403645, 2283.9657405138937, 2330.3940975346445, 2330.8718020508863, 2330.8951097200584, 2284.1776960294283, 2326.8720220387872, 2332.055517515409, 2339.3215967369442, 2339.6607491913664, 2273.761255068219, 2275.0, 2299.9885000574973, 2302.3783578433813, 2304.6543018547186, 1888.3673969220258, 2211.1045026764186, 2262.886855657219, 2300.6779050932882, 2306.457982374149, 1773.4590949760318, 1991.621591897535, 2311.4914718761847, 2318.04959966413, 2339.4034521196986, 8048.959755201245, 8297.83404331916, 8304.83390332196, 8396.916030839675, 8410.705625303084, 5063.620228482879, 5190.584753219733, 5224.268602395668, 5232.476752324776, 5261.158214685632, 3454.3091381723757, 3481.738869584766, 3558.982205088984, 3592.8203589820405, 3632.836522356474, 1228.103484456343, 1229.7786398448275, 1242.5772490498418, 1254.1095821966414, 1283.8844503994592, 8955.731328060168, 9086.409383390059, 9298.209652179505, 9780.924098349176, 10701.464926753632, 4429.844955426609, 4659.254519276899, 4712.128256272601, 4712.5994290485005, 4752.976235118802, 9390.483523406278, 9407.247420206368, 9471.289653276031, 9484.099010593982, 9490.952545237229, 5186.662866913639, 5240.764165612481, 5245.868853278622, 5247.842564723064, 5248.3701955765555, 4228.1966426378885, 4453.3320001999955, 4684.695494792828, 4755.9286610700865, 4808.471068182497, 3906.882793516199, 3943.7042221833476, 3966.861159859391, 4014.6186112319283, 4077.2864748668862, 1277.9808302875465, 1293.6636474516692, 1302.2121616422119, 1302.6222395505388, 1310.5609620777147, 847.1993965702352, 884.2174675412307, 895.4895709445553, 905.3707673167218, 938.6808485115093, 5334.199870019451, 5636.2954630671165, 5674.12051132067, 5692.715364231772, 5786.218860453869, 887.894571258787, 977.5405559387077, 978.83849164887, 980.5881529757519, 987.5483039931289, 3722.3299806035175, 3742.94385584211, 3783.39465685489, 3853.3641949078087, 3856.8650097246464, 9525.0, 9721.611135554498, 9801.11789938909, 9808.558614862348, 9821.705348839547, 9756.21950243993, 9763.170130538841, 9764.121229089274, 9804.65683701067, 9839.065288797552, 7351.0, 7698.884516732253, 7700.499467534704, 7774.30031297186, 7833.412494062968, 5509.338879334506, 5648.689322087324, 5669.121286200645, 5697.6296540724725, 5738.512226460798, 8772.166644168794, 8954.373193876367, 9061.77345566366, 9099.999000110114, 9136.451812891248, 8804.603792829466, 8908.510031948304, 9025.638974441212, 9113.270938325051, 9295.442273463614, 6294.590851594632, 6886.896696549456, 7091.1490621125795, 7096.467764917548, 7105.715771369294, 7636.8472630548185, 7702.0, 7715.382769378549, 7740.41946853977, 7864.72473463426, 7047.929520704727, 7136.4290856730295, 7140.178879428932, 7143.285671432815, 7194.172670143001, 6893.93106068953, 6971.128608923885, 6976.19773726028, 7001.404880585207, 7016.0, 6290.49676025926, 6386.489080873426, 6390.872182556416, 6393.073004414427, 6415.230172379344, 4849.927251091166, 4870.0, 4924.581410580141, 4963.181075122564, 5115.616328775282, 3994.8801535953967, 4972.129877271535, 4995.675281106716, 5033.521815427529, 5084.9830033994085], "value": 4392.007942786513, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [366.22726048038635, 367.09877251347945, 373.8130934532734, 373.9083924438513, 384.23153692614756, 4961.454240033593, 5163.741812909354, 5237.607179461536, 5265.815191581893, 5564.638298510594, 4318.417013703149, 4787.066522028206, 5267.26258323835, 5404.675989324589, 5437.592180586462, 3187.1713354527756, 3397.740502565791, 4106.445629839978, 4216.620504154625, 4273.743575385472, 3289.9636614466367, 3519.69771184662, 3852.7880966546827, 4279.422277992478, 4296.355546667994, 3298.752593555481, 4170.143451735556, 4173.686973476986, 4238.682098842583, 4246.851360202393, 3833.4058220975794, 3858.7298889077497, 4205.810738516774, 4214.262504061792, 4221.662267018646, 3461.7191639093444, 3751.4747935289083, 3763.7741735495956, 3771.641694039062, 3792.886213413602, 5107.540321371094, 5165.9225111623355, 5237.450067742909, 5271.28837606922, 5303.63219355708, 4474.6420286377015, 4911.656184067117, 4932.087563800673, 4938.753062346868, 4939.58013568847, 5780.393058728857, 5802.332731735863, 5808.738606762706, 5829.271341082364, 5857.238558987347, 4008.2985477541606, 4122.896927576805, 4160.271952408317, 4171.666266698657, 4179.561146079648, 5816.302043754734, 5819.883602327972, 5975.760969561213, 6100.481459075984, 6147.385261473862, 3509.2104276538084, 4219.282721937278, 4230.957690423056, 4232.788360581959, 4242.00312926462, 4260.829566817354, 4302.634276086567, 4305.634021108241, 4306.332518459643, 4323.740575565476, 3755.6995440364703, 3892.9416058759143, 4113.856015039519, 4136.338185890243, 4935.457099719028, 5110.437851836295, 5314.123169677037, 5712.828615141552, 5789.855253618609, 5812.709364531756, 1237.1649136832602, 1321.709223970725, 1348.9325533723274, 1392.1229625335973, 1405.9789103163462, 911.7310393433953, 974.6880998080688, 988.2390559269322, 989.1888651306004, 997.7904640025531, 4653.231327360215, 4700.529947005341, 5109.335786347751, 5135.666181698178, 5155.32980712505, 4905.828296009622, 5305.840824775263, 5531.980826998197, 5703.0, 5750.482456578928, 4574.382458368144, 4927.581155601814, 4980.655223089818, 5027.874303142451, 5467.972660136674, 2560.607195466015, 2664.8134630575673, 2668.7197844226466, 2933.427981543621, 2961.7926745128066, 4812.614990800727, 4970.8757281068265, 5499.212755854363, 5828.464617891146, 5928.377520360386, 4965.201620760122, 5125.820596279113, 5345.518903298722, 5849.239598852122, 5884.823455296348, 5093.694378337312, 5887.0, 5928.377520360386, 5961.582689211714, 5980.893534696008, 4850.466448690711, 5280.0, 5605.747741351567, 5691.658500489983, 5727.742251598771, 4566.383538222298, 4589.059242855274, 5585.553155747449, 5630.465105814941, 5640.0693885508435, 5297.682139071668, 5921.822345329647, 6036.607620504653, 6143.0, 6186.690665466619, 3075.338802157547, 3276.00163900016, 3606.5852426971232, 3782.8955583191446, 3885.630865067814, 5045.5529068401065, 5702.714864256854, 5751.798687045934, 5975.19165991983, 6022.277326720821, 5494.450554944474, 5703.62926409782, 5770.769169233352, 5784.479396854135, 5785.652860828363, 5005.649604527648, 5519.420460851553, 5767.250257466502, 5789.2763404574425, 5792.5655575832825, 4611.884702882455, 5313.654612450178, 5569.387367389503, 5637.267155269953, 5659.801906933238, 5137.662363624617, 5741.4832665058675, 5792.884142317214, 5810.883782324246, 5823.941760582509, 5632.2678051853, 5635.887282254414, 5646.887062258814, 5667.4615911488345, 5691.0], "value": 4161.264743515875, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [689.6965335252488, 695.3185877839717, 703.6270776488462, 782.7143092771137, 795.753316471894, 14567.198804065772, 15509.20834249836, 15542.53372398827, 15761.0, 15876.36494540217, 14127.152370857755, 14331.71336573268, 14436.206008669518, 14563.781543276833, 14619.0, 870.8737233101212, 876.0, 881.3742243007472, 881.951492667903, 884.5046773806657, 849.1381248033251, 874.1302404107921, 876.2070326354627, 880.6565439478609, 884.9867251991193, 914.9679761208359, 922.9893266872764, 925.3846192282124, 927.9025702301262, 928.4197376639601, 903.6746771162376, 912.7216199059284, 912.794621210229, 917.4174399256486, 919.4942781470198, 669.8593295407921, 670.2325836916751, 690.7237105157927, 691.9584824910521, 693.9618320992344, 14574.781378279335, 15164.848351516453, 15275.312610932535, 15563.221838908123, 15615.843841561666, 14016.509422170277, 14374.20941848198, 14423.639409014753, 14424.134551926918, 14426.855731442656, 11921.94039029808, 11942.820857687142, 12025.0, 12078.456469458895, 12124.393780311037, 3636.61815509373, 3702.092987218122, 3715.2379391356576, 3723.515942927429, 3745.382011968022, 109275.45362273138, 111704.32443513355, 113202.0, 113682.15794605202, 113896.86103138946, 10658.573657053632, 10678.092362149304, 10770.138388929023, 10770.24608277413, 11120.11039116885, 22242.110315587517, 22386.328410147722, 22394.88802555977, 22395.664065039036, 22459.213927512785, 7512.661930213044, 7613.314801667766, 7617.961910190414, 7687.769366919001, 7798.376129909594, 4273.316269396882, 4289.849855255051, 4377.4528183977, 4389.5075674270265, 4426.667999899956, 3608.530890984208, 3685.336639404933, 3721.5906250312446, 3800.5819359870943, 4035.7982100895424, 557.8214971209175, 706.4207349973076, 709.5919846088519, 715.2704241673464, 727.9917314519323, 646.6863571167979, 662.6448911975023, 682.2120450879243, 691.5193940211504, 694.7151667816171, 10230.181585473145, 10236.118318629911, 10392.272540922062, 10439.686809395731, 10575.312604680825, 586.5659412035063, 670.7484693240035, 693.8300116471396, 706.9328413800681, 720.9423246140296, 4205.621494065549, 4846.878828029327, 5080.872978175576, 5083.38999320084, 5089.160288552422, 105470.0, 107876.46061769797, 107989.0, 109027.0, 109311.0, 67028.99456508156, 71323.0, 72965.0, 73135.63432182805, 75969.62015189888, 66250.66874665595, 67088.0, 67396.98904516436, 69906.60186796337, 70903.58192836115, 10653.680389588324, 10907.509162087566, 11013.118950484102, 11142.72143196394, 11145.721356965816, 48657.0, 49886.50113498818, 50065.0, 51555.93776248908, 52782.472175277755, 61145.0, 65007.0, 70860.0, 71000.64499677469, 71877.64061179661, 13147.671308217372, 13150.473981041034, 13260.403281852146, 13374.598762037154, 13388.464461421434, 14823.555293341216, 14826.110433374031, 14867.628309291922, 14900.478483253035, 15243.847561524242, 13985.300734963008, 14025.3688584016, 14200.360983755962, 14246.928765356108, 14323.639254270827, 12781.0, 13076.542321018718, 13720.245386503839, 14108.58914108581, 14349.0, 10895.727606809893, 13345.599632010666, 13524.729505410034, 13982.790258146339, 13994.230317332644, 9740.0, 10986.39523534421, 11243.212975091665, 11272.210945233755, 11898.22661526998, 9526.0, 10268.921763215054, 11058.502367393647, 11216.429699841865, 11298.774024519627], "value": 7572.910743015116, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1591.0, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [102.0, 113.0, 118.0, 117.0, 117.0, 115.0, 117.0, 118.0, 139.0, 119.0], "value": 117.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [189.0, 129.0, 116.0, 152.0, 113.0, 116.0, 133.0, 144.0, 141.0, 147.0], "value": 138.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [211.0, 208.0, 261.0, 183.0, 191.0, 278.0, 277.0, 224.0, 195.0, 203.0], "value": 223.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 57.0, 58.0, 85.0, 57.0, 58.0, 64.0, 60.0, 90.0, 87.0], "value": 69.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 74.0, 85.0, 75.0, 71.0, 75.0, 103.0, 101.0, 104.0, 95.0], "value": 87.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 90.0, 105.0, 90.0, 90.0, 132.0, 136.0, 88.0, 89.0, 87.0], "value": 99.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 96.0, 96.0, 95.0, 100.0, 95.0, 107.0, 157.0, 115.0, 104.0], "value": 106.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 98.0, 91.0, 94.0, 95.0, 92.0, 91.0, 95.0, 98.0, 137.0], "value": 98.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 64.0, 64.0, 65.0, 66.0, 64.0, 66.0, 65.0, 65.0, 65.0], "value": 64.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 74.0, 78.0, 73.0, 71.0, 69.0, 105.0, 75.0, 76.0, 74.0], "value": 76.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 91.0, 88.0, 86.0, 86.0, 90.0, 91.0, 98.0, 87.0, 87.0], "value": 89.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [137.0, 137.0, 139.0, 142.0, 143.0, 166.0, 142.0, 131.0, 199.0, 153.0], "value": 148.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [175.0, 172.0, 184.0, 216.0, 173.0, 237.0, 248.0, 175.0, 181.0, 175.0], "value": 193.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 61.0, 65.0, 76.0, 90.0, 93.0, 89.0, 80.0, 71.0, 66.0], "value": 78.3, "unit": "score"}]}]} 22:01:31 INFO - Return code: 0 22:01:31 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 22:01:31 ERROR - # TBPL SUCCESS # 22:01:31 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 22:01:31 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 22:01:31 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 22:01:31 INFO - 2017-02-02 22:01:31.093 screenresolution[7152:135234] starting screenresolution argv=screenresolution get 22:01:31 INFO - 2017-02-02 22:01:31.096 screenresolution[7152:135234] Display 0: 1600x1200x32@60 22:01:31 INFO - 2017-02-02 22:01:31.103 screenresolution[7153:135237] starting screenresolution argv=screenresolution list 22:01:31 INFO - Available Modes on Display 0 22:01:31 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 22:01:31 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 22:01:31 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 22:01:31 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 22:01:31 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 22:01:31 INFO - Intel Iris: 22:01:31 INFO - Chipset Model: Intel Iris 22:01:31 INFO - Type: GPU 22:01:31 INFO - Bus: Built-In 22:01:31 INFO - VRAM (Dynamic, Max): 1536 MB 22:01:31 INFO - Vendor: Intel (0x8086) 22:01:31 INFO - Device ID: 0x0a2e 22:01:31 INFO - Revision ID: 0x0009 22:01:31 INFO - Displays: 22:01:31 INFO - Display: 22:01:31 INFO - Resolution: 1600 x 1200 @ 60 Hz 22:01:31 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 22:01:31 INFO - Display Serial Number: 1600x1200 60 22:01:31 INFO - Main Display: Yes 22:01:31 INFO - Mirror: Off 22:01:31 INFO - Online: Yes 22:01:31 INFO - Rotation: Supported 22:01:31 INFO - Return code: 0 22:01:31 INFO - Running post-action listener: _resource_record_post_action 22:01:31 INFO - [mozharness: 2017-02-03 06:01:31.340260Z] Finished run-tests step (success) 22:01:31 INFO - Running post-run listener: _resource_record_post_run 22:01:31 INFO - Total resource usage - Wall time: 1200s; CPU: 55.0%; Read bytes: 180224; Write bytes: 556145152; Read time: 1; Write time: 1702 22:01:31 INFO - TinderboxPrint: CPU usage
54.6% 22:01:31 INFO - TinderboxPrint: I/O read bytes / time
180,224 / 1 22:01:31 INFO - TinderboxPrint: I/O write bytes / time
556,145,152 / 1,702 22:01:31 INFO - TinderboxPrint: CPU idle
2,182.2 (45.4%) 22:01:31 INFO - TinderboxPrint: CPU system
972.1 (20.2%) 22:01:31 INFO - TinderboxPrint: CPU user
1,647.4 (34.3%) 22:01:31 INFO - TinderboxPrint: Swap in / out
251,904,000 / 0 22:01:31 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 229642752; Write bytes: 223260672; Read time: 16456; Write time: 466 22:01:31 INFO - run-tests - Wall time: 1179s; CPU: 55.0%; Read bytes: 180224; Write bytes: 302877184; Read time: 1; Write time: 1163 22:01:31 INFO - Running post-run listener: _upload_blobber_files 22:01:31 INFO - Blob upload gear active. 22:01:31 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 22:01:31 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 22:01:31 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', 'Larch', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 22:01:31 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 Larch -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 22:01:48 INFO - Traceback (most recent call last): 22:01:48 INFO - File "/builds/slave/test/build/venv/bin/blobberc.py", line 253, in 22:01:48 INFO - main() 22:01:48 INFO - File "/builds/slave/test/build/venv/bin/blobberc.py", line 235, in main 22:01:48 INFO - filetype_whitelist = get_server_whitelist(args['--url']) 22:01:48 INFO - File "/builds/slave/test/build/venv/bin/blobberc.py", line 68, in get_server_whitelist 22:01:48 INFO - response = requests.get(url, verify=cert.where()) 22:01:48 INFO - File "/builds/slave/test/build/venv/lib/python2.7/site-packages/requests/api.py", line 55, in get 22:01:48 INFO - return request('get', url, **kwargs) 22:01:48 INFO - File "/builds/slave/test/build/venv/lib/python2.7/site-packages/requests/api.py", line 44, in request 22:01:48 INFO - return session.request(method=method, url=url, **kwargs) 22:01:48 INFO - File "/builds/slave/test/build/venv/lib/python2.7/site-packages/requests/sessions.py", line 335, in request 22:01:48 INFO - resp = self.send(prep, **send_kwargs) 22:01:48 INFO - File "/builds/slave/test/build/venv/lib/python2.7/site-packages/requests/sessions.py", line 438, in send 22:01:48 INFO - r = adapter.send(request, **kwargs) 22:01:48 INFO - File "/builds/slave/test/build/venv/lib/python2.7/site-packages/requests/adapters.py", line 327, in send 22:01:48 INFO - raise ConnectionError(e) 22:01:48 INFO - requests.exceptions.ConnectionError: HTTPSConnectionPool(host='blobupload.elasticbeanstalk.com', port=443): Max retries exceeded with url: /blobs/whitelist (Caused by : [Errno 60] Operation timed out) 22:01:48 ERROR - Return code: 1 22:01:48 INFO - Setting buildbot property blobber_files to {} 22:01:48 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 22:01:48 INFO - Writing to file /builds/slave/test/properties/blobber_files 22:01:48 INFO - Contents: 22:01:48 INFO - blobber_files:{} 22:01:48 INFO - Running post-run listener: copy_logs_to_upload_dir 22:01:48 INFO - Copying logs to upload dir... 22:01:48 INFO - mkdir: /builds/slave/test/build/upload/logs 22:01:48 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1272.164624 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 12 secs) (at 2017-02-02 22:01:48.463743) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-02 22:01:48.467791) ========= 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.FDZteo8hlx/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.MX62N04iGn/Listeners TMPDIR=/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/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:{} build_url:https://queue.taskcluster.net/v1/task/PSBt1TV9Ry2T6mPkN0c8Fw/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011486 build_url: 'https://queue.taskcluster.net/v1/task/PSBt1TV9Ry2T6mPkN0c8Fw/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg' blobber_files: '{}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-02 22:01:48.502727) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-02 22:01:48.503060) ========= 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.FDZteo8hlx/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.MX62N04iGn/Listeners TMPDIR=/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/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.004741 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-02 22:01:48.552192) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-02 22:01:48.552520) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-02 22:01:48.552844) ========= ========= Total master_lag: 0.18 =========