builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0246 starttime: 1490200368.63 results: success (0) buildid: 20170322075834 builduid: cac01b2237094fc3bb488dc4344e7037 revision: e03e0c60462c775c7558a1dc9d5cf2076c3cd1f9 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-03-22 09:32:48.628414) ========= master: http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-03-22 09:32:48.628891) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-22 09:32:48.629170) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-22 09:32:48.655640) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-22 09:32:48.656014) ========= 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.qQhUA9mFfc/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.wFX9LpraDu/Listeners TMPDIR=/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-03-22 09:32:48-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 61.1M=0s 2017-03-22 09:32:48 (61.1 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.148087 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-22 09:32:48.827769) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-22 09:32:48.828082) ========= 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.qQhUA9mFfc/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.wFX9LpraDu/Listeners TMPDIR=/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/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.037647 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-22 09:32:48.887388) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-03-22 09:32:48.887737) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev e03e0c60462c775c7558a1dc9d5cf2076c3cd1f9 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev e03e0c60462c775c7558a1dc9d5cf2076c3cd1f9 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.qQhUA9mFfc/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.wFX9LpraDu/Listeners TMPDIR=/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-03-22 09:32:49,007 truncating revision to first 12 chars 2017-03-22 09:32:49,007 Setting DEBUG logging. 2017-03-22 09:32:49,007 attempt 1/10 2017-03-22 09:32:49,008 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/e03e0c60462c?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-03-22 09:32:50,521 unpacking tar archive at: mozilla-central-e03e0c60462c/testing/mozharness/ program finished with exit code 0 elapsedTime=1.794646 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-03-22 09:32:50.706989) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-22 09:32:50.707517) ========= script_repo_revision: e03e0c60462c775c7558a1dc9d5cf2076c3cd1f9 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-22 09:32:50.708150) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-22 09:32:50.708599) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-22 09:32:50.729503) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 24 secs) (at 2017-03-22 09:32:50.729915) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.qQhUA9mFfc/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.wFX9LpraDu/Listeners TMPDIR=/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 09:32:50 INFO - MultiFileLogger online at 20170322 09:32:50 in /builds/slave/test 09:32:50 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 09:32:50 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:32:50 INFO - {'append_to_log': False, 09:32:50 INFO - 'base_work_dir': '/builds/slave/test', 09:32:50 INFO - 'blob_upload_branch': 'Firefox', 09:32:50 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:32:50 INFO - 'branch': 'Firefox', 09:32:50 INFO - 'buildbot_json_path': 'buildprops.json', 09:32:50 INFO - 'config_files': ('talos/mac_config.py',), 09:32:50 INFO - 'default_actions': ('clobber', 09:32:50 INFO - 'read-buildbot-config', 09:32:50 INFO - 'download-and-extract', 09:32:50 INFO - 'populate-webroot', 09:32:50 INFO - 'create-virtualenv', 09:32:50 INFO - 'install', 09:32:50 INFO - 'run-tests'), 09:32:50 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:32:50 INFO - 'download_minidump_stackwalk': True, 09:32:50 INFO - 'download_symbols': 'ondemand', 09:32:50 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:32:50 INFO - 'tooltool.py': '/tools/tooltool.py', 09:32:50 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:32:50 INFO - '/tools/misc-python/virtualenv.py')}, 09:32:50 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:32:50 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:32:50 INFO - 'gecko_profile': False, 09:32:50 INFO - 'gecko_profile_interval': 0, 09:32:50 INFO - 'installer_path': 'installer.exe', 09:32:50 INFO - 'log_level': 'info', 09:32:50 INFO - 'log_name': 'talos', 09:32:50 INFO - 'log_to_console': True, 09:32:50 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 09:32:50 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 09:32:50 INFO - 'opt_config_files': (), 09:32:50 INFO - 'pip_index': False, 09:32:50 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:32:50 INFO - 'cmd': ('bash', 09:32:50 INFO - '-c', 09:32:50 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:32:50 INFO - 'enabled': True, 09:32:50 INFO - 'halt_on_failure': False, 09:32:50 INFO - 'name': 'check_screen_resolution'},), 09:32:50 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:32:50 INFO - 'cmd': ('bash', 09:32:50 INFO - '-c', 09:32:50 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:32:50 INFO - 'enabled': True, 09:32:50 INFO - 'halt_on_failure': False, 09:32:50 INFO - 'name': 'check_screen_resolution'},), 09:32:50 INFO - 'run_cmd_checks_enabled': True, 09:32:50 INFO - 'suite': 'dromaeojs-e10s', 09:32:50 INFO - 'system_bits': '32', 09:32:50 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:32:50 INFO - 'title': 't-yosemite-r7-0246', 09:32:50 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:32:50 INFO - 'use_talos_json': True, 09:32:50 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:32:50 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:32:50 INFO - 'work_dir': 'build'} 09:32:50 INFO - [mozharness: 2017-03-22 16:32:50.959363Z] Running clobber step. 09:32:50 INFO - Running pre-action listener: _resource_record_pre_action 09:32:50 INFO - Running main action method: clobber 09:32:50 INFO - rmtree: /builds/slave/test/build 09:32:50 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:32:53 INFO - Running post-action listener: _resource_record_post_action 09:32:53 INFO - [mozharness: 2017-03-22 16:32:53.369105Z] Finished clobber step (success) 09:32:53 INFO - [mozharness: 2017-03-22 16:32:53.369330Z] Running read-buildbot-config step. 09:32:53 INFO - Running pre-action listener: _resource_record_pre_action 09:32:53 INFO - Running main action method: read_buildbot_config 09:32:53 INFO - Using buildbot properties: 09:32:53 INFO - { 09:32:53 INFO - "project": "", 09:32:53 INFO - "product": "firefox", 09:32:53 INFO - "script_repo_revision": "production", 09:32:53 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 09:32:53 INFO - "repository": "", 09:32:53 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 09:32:53 INFO - "buildid": "20170322075834", 09:32:53 INFO - "pgo_build": "False", 09:32:53 INFO - "basedir": "/builds/slave/test", 09:32:53 INFO - "buildnumber": 22, 09:32:53 INFO - "slavename": "t-yosemite-r7-0246", 09:32:53 INFO - "master": "http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/", 09:32:53 INFO - "platform": "yosemite_r7", 09:32:53 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 09:32:53 INFO - "branch": "mozilla-central", 09:32:53 INFO - "repo_path": "mozilla-central", 09:32:53 INFO - "revision": "e03e0c60462c775c7558a1dc9d5cf2076c3cd1f9", 09:32:53 INFO - "stage_platform": "macosx64", 09:32:53 INFO - "builduid": "cac01b2237094fc3bb488dc4344e7037", 09:32:53 INFO - "slavebuilddir": "test" 09:32:53 INFO - } 09:32:53 INFO - Found installer url https://queue.taskcluster.net/v1/task/L4ScK0lXS2yNhIhzFHjDGw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg. 09:32:53 INFO - Running post-action listener: _resource_record_post_action 09:32:53 INFO - [mozharness: 2017-03-22 16:32:53.375069Z] Finished read-buildbot-config step (success) 09:32:53 INFO - [mozharness: 2017-03-22 16:32:53.375266Z] Running download-and-extract step. 09:32:53 INFO - Running pre-action listener: _resource_record_pre_action 09:32:53 INFO - Running main action method: download_and_extract 09:32:53 INFO - mkdir: /builds/slave/test/build/tests 09:32: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')]} 09:32:53 INFO - trying https://queue.taskcluster.net/v1/task/L4ScK0lXS2yNhIhzFHjDGw/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 09:32:53 INFO - Downloading https://queue.taskcluster.net/v1/task/L4ScK0lXS2yNhIhzFHjDGw/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 09:32:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/L4ScK0lXS2yNhIhzFHjDGw/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json'}, attempt #1 09:32:54 INFO - Downloaded 1325 bytes. 09:32:54 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 09:32:54 INFO - Using the following test package requirements: 09:32:54 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:32:54 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 09:32:54 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 09:32:54 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:32:54 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 09:32:54 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:32:54 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 09:32:54 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:32:54 INFO - u'jsshell-mac.zip'], 09:32:54 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:32:54 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 09:32:54 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 09:32:54 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:32:54 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 09:32:54 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:32:54 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 09:32:54 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:32:54 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 09:32:54 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:32:54 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 09:32:54 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 09:32:54 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/L4ScK0lXS2yNhIhzFHjDGw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 09:32:54 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/L4ScK0lXS2yNhIhzFHjDGw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 09:32:54 INFO - Fetch https://queue.taskcluster.net/v1/task/L4ScK0lXS2yNhIhzFHjDGw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 09:32:55 INFO - Expected file size: 52403853 09:32:55 INFO - Obtained file size: 52403853 09:33:12 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip', u'firefox-55.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 09:33:12 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/L4ScK0lXS2yNhIhzFHjDGw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 09:33:12 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/L4ScK0lXS2yNhIhzFHjDGw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 09:33:12 INFO - Fetch https://queue.taskcluster.net/v1/task/L4ScK0lXS2yNhIhzFHjDGw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 09:33:14 INFO - Expected file size: 52403853 09:33:14 INFO - Obtained file size: 52403853 09:33:29 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/L4ScK0lXS2yNhIhzFHjDGw/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 09:33:29 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/L4ScK0lXS2yNhIhzFHjDGw/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 09:33:29 INFO - Fetch https://queue.taskcluster.net/v1/task/L4ScK0lXS2yNhIhzFHjDGw/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 09:33:29 INFO - Expected file size: 14404171 09:33:29 INFO - Obtained file size: 14404171 09:33: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')]} 09:33:30 INFO - trying https://queue.taskcluster.net/v1/task/L4ScK0lXS2yNhIhzFHjDGw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 09:33:30 INFO - Downloading https://queue.taskcluster.net/v1/task/L4ScK0lXS2yNhIhzFHjDGw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 09:33:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/L4ScK0lXS2yNhIhzFHjDGw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg'}, attempt #1 09:33:32 INFO - Downloaded 75372888 bytes. 09:33:32 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/L4ScK0lXS2yNhIhzFHjDGw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 09:33:32 INFO - mkdir: /builds/slave/test/properties 09:33:32 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:33:32 INFO - Writing to file /builds/slave/test/properties/build_url 09:33:32 INFO - Contents: 09:33:32 INFO - build_url:https://queue.taskcluster.net/v1/task/L4ScK0lXS2yNhIhzFHjDGw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 09:33:33 INFO - Running post-action listener: _resource_record_post_action 09:33:33 INFO - Running post-action listener: set_extra_try_arguments 09:33:33 INFO - [mozharness: 2017-03-22 16:33:33.996820Z] Finished download-and-extract step (success) 09:33:33 INFO - [mozharness: 2017-03-22 16:33:33.996992Z] Running populate-webroot step. 09:33:33 INFO - Running pre-action listener: _resource_record_pre_action 09:33:33 INFO - Running main action method: populate_webroot 09:33:34 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:33:34 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:33:34 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:33:34 INFO - '--tppagecycles', 09:33:34 INFO - '7'], 09:33:34 INFO - 'tests': ['tsvgm']}}, 09:33:34 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:33:34 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:33:34 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:33:34 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:33:34 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:33:34 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:33:34 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 09:33:34 INFO - 'talos_options': ['--disable-e10s'], 09:33:34 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:33:34 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 09:33:34 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:33:34 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 09:33:34 INFO - 'talos_options': ['--disable-e10s'], 09:33:34 INFO - 'tests': ['damp', 'tps']}, 09:33:34 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 09:33:34 INFO - 'tests': ['damp', 'tps']}, 09:33:34 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:33:34 INFO - 'tests': ['dromaeo_dom']}, 09:33:34 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:33:34 INFO - 'g4': {'talos_options': ['--disable-e10s'], 09:33:34 INFO - 'tests': ['basic_compositor_video']}, 09:33:34 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 09:33:34 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:33:34 INFO - 'tests': ['a11yr', 09:33:34 INFO - 'ts_paint', 09:33:34 INFO - 'tpaint', 09:33:34 INFO - 'sessionrestore', 09:33:34 INFO - 'sessionrestore_no_auto_restore', 09:33:34 INFO - 'tabpaint']}, 09:33:34 INFO - 'other-e10s': {'tests': ['a11yr', 09:33:34 INFO - 'ts_paint', 09:33:34 INFO - 'tpaint', 09:33:34 INFO - 'sessionrestore', 09:33:34 INFO - 'sessionrestore_no_auto_restore', 09:33:34 INFO - 'tabpaint']}, 09:33:34 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:33:34 INFO - 'ts_paint', 09:33:34 INFO - 'tpaint', 09:33:34 INFO - 'sessionrestore', 09:33:34 INFO - 'sessionrestore_no_auto_restore', 09:33:34 INFO - 'tabpaint']}, 09:33:34 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:33:34 INFO - 'ts_paint', 09:33:34 INFO - 'tpaint', 09:33:34 INFO - 'sessionrestore', 09:33:34 INFO - 'sessionrestore_no_auto_restore', 09:33:34 INFO - 'tabpaint']}, 09:33:34 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:33:34 INFO - 'tests': ['a11yr', 09:33:34 INFO - 'ts_paint', 09:33:34 INFO - 'tpaint', 09:33:34 INFO - 'sessionrestore', 09:33:34 INFO - 'sessionrestore_no_auto_restore', 09:33:34 INFO - 'tabpaint']}, 09:33:34 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:33:34 INFO - 'tests': ['a11yr', 09:33:34 INFO - 'ts_paint', 09:33:34 INFO - 'tpaint', 09:33:34 INFO - 'sessionrestore', 09:33:34 INFO - 'sessionrestore_no_auto_restore', 09:33:34 INFO - 'tabpaint']}, 09:33:34 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:33:34 INFO - 'tests': ['tsvgx', 09:33:34 INFO - 'tsvgr_opacity', 09:33:34 INFO - 'tart', 09:33:34 INFO - 'tscrollx', 09:33:34 INFO - 'cart', 09:33:34 INFO - 'tsvg_static']}, 09:33:34 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:33:34 INFO - 'tsvgr_opacity', 09:33:34 INFO - 'tart', 09:33:34 INFO - 'tscrollx', 09:33:34 INFO - 'cart', 09:33:34 INFO - 'tsvg_static']}, 09:33:34 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 09:33:34 INFO - 'talos_options': ['--disable-e10s'], 09:33:34 INFO - 'tests': ['tp5o']}, 09:33:34 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 09:33:34 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 09:33:34 INFO - 'talos_options': ['--disable-e10s', 09:33:34 INFO - '--xperf_path', 09:33:34 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:33:34 INFO - 'tests': ['tp5n']}, 09:33:34 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 09:33:34 INFO - 'talos_options': ['--xperf_path', 09:33:34 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:33:34 INFO - 'tests': ['tp5n']}}, 09:33:34 INFO - 'talos.zip': {'path': '', 09:33:34 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:33:34 INFO - Running post-action listener: _resource_record_post_action 09:33:34 INFO - [mozharness: 2017-03-22 16:33:34.015383Z] Finished populate-webroot step (success) 09:33:34 INFO - [mozharness: 2017-03-22 16:33:34.015501Z] Running create-virtualenv step. 09:33:34 INFO - Running pre-action listener: _resource_record_pre_action 09:33:34 INFO - Running main action method: create_virtualenv 09:33:34 INFO - Creating virtualenv /builds/slave/test/build/venv 09:33:34 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 09:33:34 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:33:34 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:33:34 INFO - Using real prefix '/tools/python27' 09:33:34 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:33:34 INFO - Installing distribute.............................................................................................................................................................................................done. 09:33:37 INFO - Installing pip.................done. 09:33:37 INFO - Return code: 0 09:33:37 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 09:33:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 09:33:37 INFO - Reading from file tmpfile_stdout 09:33:37 INFO - Output received: 09:33:37 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 09:33:37 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:33:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:33:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:33:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:33:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:33:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:33:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:33:38 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 0x10caffb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cb02768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f96a9562e40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c8ddd30>, '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 0x10c804db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c8d2be8>, '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.qQhUA9mFfc/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.wFX9LpraDu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:33:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 09:33:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 09:33:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qQhUA9mFfc/Render', 09:33:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:33:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:33:38 INFO - 'HOME': '/Users/cltbld', 09:33:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:33:38 INFO - 'LOGNAME': 'cltbld', 09:33:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:33:38 INFO - 'MOZ_NO_REMOTE': '1', 09:33:38 INFO - 'NO_EM_RESTART': '1', 09:33:38 INFO - 'PAGER': '/bin/cat', 09:33:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:33:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:33:38 INFO - 'PWD': '/builds/slave/test', 09:33:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:33:38 INFO - 'SHELL': '/bin/bash', 09:33:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wFX9LpraDu/Listeners', 09:33:38 INFO - 'TMPDIR': '/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/', 09:33:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:33:38 INFO - 'USER': 'cltbld', 09:33:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:33:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:33:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:33:38 INFO - 'XPC_FLAGS': '0x0', 09:33:38 INFO - 'XPC_SERVICE_NAME': '0', 09:33:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:33:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:33:38 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 09:33:38 INFO - Cleaning up... 09:33:38 INFO - Return code: 0 09:33:38 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:33: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')]} 09:33:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:33:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:33: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')]} 09:33:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:33:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:33:38 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 0x10caffb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cb02768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f96a9562e40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c8ddd30>, '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 0x10c804db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c8d2be8>, '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.qQhUA9mFfc/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.wFX9LpraDu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:33:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 09:33:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 09:33:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qQhUA9mFfc/Render', 09:33:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:33:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:33:38 INFO - 'HOME': '/Users/cltbld', 09:33:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:33:38 INFO - 'LOGNAME': 'cltbld', 09:33:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:33:38 INFO - 'MOZ_NO_REMOTE': '1', 09:33:38 INFO - 'NO_EM_RESTART': '1', 09:33:38 INFO - 'PAGER': '/bin/cat', 09:33:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:33:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:33:38 INFO - 'PWD': '/builds/slave/test', 09:33:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:33:38 INFO - 'SHELL': '/bin/bash', 09:33:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wFX9LpraDu/Listeners', 09:33:38 INFO - 'TMPDIR': '/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/', 09:33:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:33:38 INFO - 'USER': 'cltbld', 09:33:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:33:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:33:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:33:38 INFO - 'XPC_FLAGS': '0x0', 09:33:38 INFO - 'XPC_SERVICE_NAME': '0', 09:33:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:33:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:33:38 INFO - Downloading/unpacking psutil>=3.1.1 09:33:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:33:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:33:38 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:33:38 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:33:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:33:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:33:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:33:42 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:33:42 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:33:42 INFO - Installing collected packages: psutil 09:33:42 INFO - Running setup.py install for psutil 09:33:42 INFO - building 'psutil._psutil_osx' extension 09:33:42 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 09:33:42 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 09:33:42 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 09:33:42 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 09:33:42 INFO - building 'psutil._psutil_posix' extension 09:33:42 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 09:33:43 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 09:33:43 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 09:33:43 INFO - ^ 09:33:43 INFO - 1 warning generated. 09:33:43 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 09:33:43 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:33:43 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:33:43 INFO - Successfully installed psutil 09:33:43 INFO - Cleaning up... 09:33:43 INFO - Return code: 0 09:33:43 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:33:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:33:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:33:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:33:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:33:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:33:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:33:43 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 0x10caffb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cb02768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f96a9562e40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c8ddd30>, '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 0x10c804db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c8d2be8>, '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.qQhUA9mFfc/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.wFX9LpraDu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:33:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 09:33:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 09:33:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qQhUA9mFfc/Render', 09:33:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:33:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:33:43 INFO - 'HOME': '/Users/cltbld', 09:33:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:33:43 INFO - 'LOGNAME': 'cltbld', 09:33:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:33:43 INFO - 'MOZ_NO_REMOTE': '1', 09:33:43 INFO - 'NO_EM_RESTART': '1', 09:33:43 INFO - 'PAGER': '/bin/cat', 09:33:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:33:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:33:43 INFO - 'PWD': '/builds/slave/test', 09:33:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:33:43 INFO - 'SHELL': '/bin/bash', 09:33:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wFX9LpraDu/Listeners', 09:33:43 INFO - 'TMPDIR': '/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/', 09:33:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:33:43 INFO - 'USER': 'cltbld', 09:33:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:33:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:33:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:33:43 INFO - 'XPC_FLAGS': '0x0', 09:33:43 INFO - 'XPC_SERVICE_NAME': '0', 09:33:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:33:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:33:43 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:33:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:33:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:33:43 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:33:43 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:33:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:33:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:33:46 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:33:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:33:46 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:33:46 INFO - Installing collected packages: mozsystemmonitor 09:33:46 INFO - Running setup.py install for mozsystemmonitor 09:33:46 INFO - Successfully installed mozsystemmonitor 09:33:46 INFO - Cleaning up... 09:33:46 INFO - Return code: 0 09:33:46 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:33:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:33:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:33:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:33:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:33:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:33:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:33:47 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 0x10caffb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cb02768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f96a9562e40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c8ddd30>, '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 0x10c804db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c8d2be8>, '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.qQhUA9mFfc/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.wFX9LpraDu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:33:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 09:33:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 09:33:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qQhUA9mFfc/Render', 09:33:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:33:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:33:47 INFO - 'HOME': '/Users/cltbld', 09:33:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:33:47 INFO - 'LOGNAME': 'cltbld', 09:33:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:33:47 INFO - 'MOZ_NO_REMOTE': '1', 09:33:47 INFO - 'NO_EM_RESTART': '1', 09:33:47 INFO - 'PAGER': '/bin/cat', 09:33:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:33:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:33:47 INFO - 'PWD': '/builds/slave/test', 09:33:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:33:47 INFO - 'SHELL': '/bin/bash', 09:33:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wFX9LpraDu/Listeners', 09:33:47 INFO - 'TMPDIR': '/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/', 09:33:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:33:47 INFO - 'USER': 'cltbld', 09:33:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:33:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:33:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:33:47 INFO - 'XPC_FLAGS': '0x0', 09:33:47 INFO - 'XPC_SERVICE_NAME': '0', 09:33:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:33:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:33:47 INFO - Downloading/unpacking jsonschema==2.5.1 09:33:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:33:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:33:47 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:33:47 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:33:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:33:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:33:50 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:33:50 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:33:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:33:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:33:50 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:33:50 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:33:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:33:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:33:50 INFO - Downloading functools32-3.2.3-2.tar.gz 09:33:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:33:50 INFO - warning: no files found matching '*.txt' 09:33:50 INFO - no previously-included directories found matching 'build' 09:33:50 INFO - no previously-included directories found matching 'dist' 09:33:50 INFO - no previously-included directories found matching '.git*' 09:33:50 INFO - Installing collected packages: jsonschema, functools32 09:33:50 INFO - Running setup.py install for functools32 09:33:51 INFO - warning: no files found matching '*.txt' 09:33:51 INFO - no previously-included directories found matching 'build' 09:33:51 INFO - no previously-included directories found matching 'dist' 09:33:51 INFO - no previously-included directories found matching '.git*' 09:33:51 INFO - Successfully installed jsonschema functools32 09:33:51 INFO - Cleaning up... 09:33:51 INFO - Return code: 0 09:33:51 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 09:33:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:33:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:33:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:33:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:33:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:33:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:33:51 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10caffb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cb02768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f96a9562e40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c8ddd30>, '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 0x10c804db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c8d2be8>, '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.qQhUA9mFfc/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.wFX9LpraDu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:33:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 09:33:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 09:33:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qQhUA9mFfc/Render', 09:33:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:33:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:33:51 INFO - 'HOME': '/Users/cltbld', 09:33:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:33:51 INFO - 'LOGNAME': 'cltbld', 09:33:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:33:51 INFO - 'MOZ_NO_REMOTE': '1', 09:33:51 INFO - 'NO_EM_RESTART': '1', 09:33:51 INFO - 'PAGER': '/bin/cat', 09:33:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:33:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:33:51 INFO - 'PWD': '/builds/slave/test', 09:33:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:33:51 INFO - 'SHELL': '/bin/bash', 09:33:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wFX9LpraDu/Listeners', 09:33:51 INFO - 'TMPDIR': '/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/', 09:33:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:33:51 INFO - 'USER': 'cltbld', 09:33:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:33:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:33:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:33:51 INFO - 'XPC_FLAGS': '0x0', 09:33:51 INFO - 'XPC_SERVICE_NAME': '0', 09:33:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:33:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:33:51 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 09:33:51 INFO - Cleaning up... 09:33:51 INFO - Return code: 0 09:33:51 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:33:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:33:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:33:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:33:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:33:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:33:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:33:51 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10caffb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cb02768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f96a9562e40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c8ddd30>, '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 0x10c804db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c8d2be8>, '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.qQhUA9mFfc/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.wFX9LpraDu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:33:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 09:33:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 09:33:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qQhUA9mFfc/Render', 09:33:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:33:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:33:51 INFO - 'HOME': '/Users/cltbld', 09:33:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:33:51 INFO - 'LOGNAME': 'cltbld', 09:33:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:33:51 INFO - 'MOZ_NO_REMOTE': '1', 09:33:51 INFO - 'NO_EM_RESTART': '1', 09:33:51 INFO - 'PAGER': '/bin/cat', 09:33:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:33:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:33:51 INFO - 'PWD': '/builds/slave/test', 09:33:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:33:51 INFO - 'SHELL': '/bin/bash', 09:33:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wFX9LpraDu/Listeners', 09:33:51 INFO - 'TMPDIR': '/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/', 09:33:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:33:51 INFO - 'USER': 'cltbld', 09:33:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:33:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:33:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:33:51 INFO - 'XPC_FLAGS': '0x0', 09:33:51 INFO - 'XPC_SERVICE_NAME': '0', 09:33:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:33:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:33:51 INFO - Downloading/unpacking blobuploader==1.2.4 09:33:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:33:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:33:51 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:33:51 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:33:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:33:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:33:54 INFO - Downloading blobuploader-1.2.4.tar.gz 09:33:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:33:54 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:33:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:33:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:33:54 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:33:54 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:33:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:33:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:33:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:33:55 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:33:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:33:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:33:55 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:33:55 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:33:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:33:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:33:55 INFO - Downloading docopt-0.6.1.tar.gz 09:33:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:33:55 INFO - Installing collected packages: blobuploader, requests, docopt 09:33:55 INFO - Running setup.py install for blobuploader 09:33:55 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:33:55 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:33:55 INFO - Running setup.py install for requests 09:33:56 INFO - Running setup.py install for docopt 09:33:56 INFO - Successfully installed blobuploader requests docopt 09:33:56 INFO - Cleaning up... 09:33:56 INFO - Return code: 0 09:33:56 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:33: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')]} 09:33:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:33:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:33: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')]} 09:33:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:33:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:33:56 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 0x10caffb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cb02768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f96a9562e40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c8ddd30>, '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 0x10c804db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c8d2be8>, '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.qQhUA9mFfc/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.wFX9LpraDu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:33:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 09:33:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:33:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qQhUA9mFfc/Render', 09:33:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:33:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:33:56 INFO - 'HOME': '/Users/cltbld', 09:33:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:33:56 INFO - 'LOGNAME': 'cltbld', 09:33:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:33:56 INFO - 'MOZ_NO_REMOTE': '1', 09:33:56 INFO - 'NO_EM_RESTART': '1', 09:33:56 INFO - 'PAGER': '/bin/cat', 09:33:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:33:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:33:56 INFO - 'PWD': '/builds/slave/test', 09:33:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:33:56 INFO - 'SHELL': '/bin/bash', 09:33:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wFX9LpraDu/Listeners', 09:33:56 INFO - 'TMPDIR': '/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/', 09:33:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:33:56 INFO - 'USER': 'cltbld', 09:33:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:33:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:33:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:33:56 INFO - 'XPC_FLAGS': '0x0', 09:33:56 INFO - 'XPC_SERVICE_NAME': '0', 09:33:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:33:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:33:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:33:56 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-ClUEpU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:33:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:33:56 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-uZHeY9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:33:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:33:57 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-QJeSUv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:33:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:33:57 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-X6uc2G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:33:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:33:57 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-0wzvet-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:33:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:33:57 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-W9XyTx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:33:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:33:57 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-_9KFyU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:33:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:33:57 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-ZwouYD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:33:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:33:57 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-FmrDlr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:33:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:33:57 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-KIyhrG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:33:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:33:57 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-N1sc4f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:33:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:33:58 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-69qM1u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:33:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:33:58 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-H4ijT_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:33:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:33:58 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-1VuRSQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:33:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:33:58 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-26FexM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:33:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:33:58 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-9fOYDP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:33:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:33:58 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-FOWgLi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:33:58 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:33:58 INFO - Running setup.py install for manifestparser 09:33:58 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:33:58 INFO - Running setup.py install for mozcrash 09:33:58 INFO - Running setup.py install for mozdebug 09:33:59 INFO - Running setup.py install for mozdevice 09:33:59 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:33:59 INFO - Running setup.py install for mozfile 09:33:59 INFO - Running setup.py install for mozhttpd 09:33:59 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:33:59 INFO - Running setup.py install for mozinfo 09:33:59 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:33:59 INFO - Running setup.py install for mozInstall 09:33:59 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:33:59 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:33:59 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:33:59 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:33:59 INFO - Running setup.py install for mozleak 09:33:59 INFO - Running setup.py install for mozlog 09:34:00 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:34:00 INFO - Running setup.py install for moznetwork 09:34:00 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:34:00 INFO - Running setup.py install for mozprocess 09:34:00 INFO - Running setup.py install for mozprofile 09:34:00 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:34:00 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:34:00 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:34:00 INFO - Running setup.py install for mozrunner 09:34:00 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:34:00 INFO - Running setup.py install for mozscreenshot 09:34:00 INFO - Running setup.py install for moztest 09:34:01 INFO - Running setup.py install for mozversion 09:34:01 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:34:01 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:34:01 INFO - Cleaning up... 09:34:01 INFO - Return code: 0 09:34:01 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:34:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:34:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:34:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:34:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:34:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:34:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:34:01 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 0x10caffb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cb02768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f96a9562e40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c8ddd30>, '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 0x10c804db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c8d2be8>, '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.qQhUA9mFfc/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.wFX9LpraDu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:34:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 09:34:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:34:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qQhUA9mFfc/Render', 09:34:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:34:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:34:01 INFO - 'HOME': '/Users/cltbld', 09:34:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:34:01 INFO - 'LOGNAME': 'cltbld', 09:34:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:34:01 INFO - 'MOZ_NO_REMOTE': '1', 09:34:01 INFO - 'NO_EM_RESTART': '1', 09:34:01 INFO - 'PAGER': '/bin/cat', 09:34:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:34:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:34:01 INFO - 'PWD': '/builds/slave/test', 09:34:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:34:01 INFO - 'SHELL': '/bin/bash', 09:34:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wFX9LpraDu/Listeners', 09:34:01 INFO - 'TMPDIR': '/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/', 09:34:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:34:01 INFO - 'USER': 'cltbld', 09:34:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:34:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:34:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:34:01 INFO - 'XPC_FLAGS': '0x0', 09:34:01 INFO - 'XPC_SERVICE_NAME': '0', 09:34:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:34:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:34:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:34:01 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-Ovip4W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:34:01 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 09:34:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:34:01 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-XDh3Tp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:34:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:34:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:34:01 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-u3x30t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:34:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 09:34:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:34:01 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-0mb859-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:34:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:34:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:34:01 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-XZvblW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:34:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 09:34:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:34:01 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-5iigJL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:34:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 09:34:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:34:02 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-39pKlF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:34:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 09:34:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:34:02 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-nUGexm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:34:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 09:34:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:34:02 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-n48sTq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:34:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 09:34:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:34:02 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-uRSjmc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:34:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:34:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:34:02 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-WveODV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:34:02 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 09:34:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:34:02 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-VGNAkp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:34:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 09:34:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:34:02 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-E70MRs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:34:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 09:34:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:34:02 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-F2swme-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:34:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 09:34:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:34:02 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-Ui8t12-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:34:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 09:34:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:34:03 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-xs0SPJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:34:03 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 09:34:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:34:03 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-_RssMz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:34:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 09:34:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:34:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:34:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 09:34:03 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:34:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:34:03 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:34: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 09:34: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 09:34: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 09:34: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 09:34: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 09:34: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 09:34:05 INFO - Downloading blessings-1.6.tar.gz 09:34:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:34:05 INFO - Installing collected packages: blessings 09:34:05 INFO - Running setup.py install for blessings 09:34:06 INFO - Successfully installed blessings 09:34:06 INFO - Cleaning up... 09:34:06 INFO - Return code: 0 09:34:06 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:34:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:34:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:34:06 INFO - Reading from file tmpfile_stdout 09:34:06 INFO - Current package versions: 09:34:06 INFO - blessings == 1.6 09:34:06 INFO - blobuploader == 1.2.4 09:34:06 INFO - docopt == 0.6.1 09:34:06 INFO - functools32 == 3.2.3-2 09:34:06 INFO - jsonschema == 2.5.1 09:34:06 INFO - manifestparser == 1.1 09:34:06 INFO - mozInstall == 1.12 09:34:06 INFO - mozcrash == 1.0 09:34:06 INFO - mozdebug == 0.1 09:34:06 INFO - mozdevice == 0.49 09:34:06 INFO - mozfile == 1.2 09:34:06 INFO - mozhttpd == 0.7 09:34:06 INFO - mozinfo == 0.9 09:34:06 INFO - mozleak == 0.1 09:34:06 INFO - mozlog == 3.4 09:34:06 INFO - moznetwork == 0.27 09:34:06 INFO - mozprocess == 0.25 09:34:06 INFO - mozprofile == 0.28 09:34:06 INFO - mozrunner == 6.13 09:34:06 INFO - mozscreenshot == 0.1 09:34:06 INFO - mozsystemmonitor == 0.3 09:34:06 INFO - moztest == 0.8 09:34:06 INFO - mozversion == 1.4 09:34:06 INFO - psutil == 3.1.1 09:34:06 INFO - requests == 1.2.3 09:34:06 INFO - wsgiref == 0.1.2 09:34:06 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:34:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:34:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:34:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:34:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:34:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:34:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:34:06 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 0x10caffb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cb02768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f96a9562e40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c8ddd30>, '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 0x10c804db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c8d2be8>, '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.qQhUA9mFfc/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.wFX9LpraDu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:34:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 09:34:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:34:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qQhUA9mFfc/Render', 09:34:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:34:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:34:06 INFO - 'HOME': '/Users/cltbld', 09:34:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:34:06 INFO - 'LOGNAME': 'cltbld', 09:34:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:34:06 INFO - 'MOZ_NO_REMOTE': '1', 09:34:06 INFO - 'NO_EM_RESTART': '1', 09:34:06 INFO - 'PAGER': '/bin/cat', 09:34:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:34:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:34:06 INFO - 'PWD': '/builds/slave/test', 09:34:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:34:06 INFO - 'SHELL': '/bin/bash', 09:34:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wFX9LpraDu/Listeners', 09:34:06 INFO - 'TMPDIR': '/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/', 09:34:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:34:06 INFO - 'USER': 'cltbld', 09:34:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:34:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:34:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:34:06 INFO - 'XPC_FLAGS': '0x0', 09:34:06 INFO - 'XPC_SERVICE_NAME': '0', 09:34:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:34:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:34:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 09:34:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 09:34:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 09:34:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 09:34:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 09:34:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 09:34:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 09:34:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 09:34:06 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 09:34:06 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 09:34:06 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 09:34:06 INFO - Cleaning up... 09:34:06 INFO - Return code: 0 09:34:06 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:34:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:34:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:34:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:34:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:34:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:34:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:34:06 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 0x10caffb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cb02768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f96a9562e40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c8ddd30>, '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 0x10c804db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c8d2be8>, '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.qQhUA9mFfc/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.wFX9LpraDu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:34:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 09:34:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 09:34:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qQhUA9mFfc/Render', 09:34:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:34:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:34:06 INFO - 'HOME': '/Users/cltbld', 09:34:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:34:06 INFO - 'LOGNAME': 'cltbld', 09:34:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:34:06 INFO - 'MOZ_NO_REMOTE': '1', 09:34:06 INFO - 'NO_EM_RESTART': '1', 09:34:06 INFO - 'PAGER': '/bin/cat', 09:34:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:34:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:34:06 INFO - 'PWD': '/builds/slave/test', 09:34:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:34:06 INFO - 'SHELL': '/bin/bash', 09:34:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wFX9LpraDu/Listeners', 09:34:06 INFO - 'TMPDIR': '/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/', 09:34:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:34:06 INFO - 'USER': 'cltbld', 09:34:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:34:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:34:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:34:06 INFO - 'XPC_FLAGS': '0x0', 09:34:06 INFO - 'XPC_SERVICE_NAME': '0', 09:34:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:34:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:34:06 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:34:06 INFO - Cleaning up... 09:34:06 INFO - Return code: 0 09:34:06 INFO - Running post-action listener: _resource_record_post_action 09:34:06 INFO - Running post-action listener: _start_resource_monitoring 09:34:07 INFO - Starting resource monitoring. 09:34:07 INFO - [mozharness: 2017-03-22 16:34:07.024791Z] Finished create-virtualenv step (success) 09:34:07 INFO - [mozharness: 2017-03-22 16:34:07.025760Z] Running install step. 09:34:07 INFO - Running pre-action listener: _resource_record_pre_action 09:34:07 INFO - Running main action method: install 09:34:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:34:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:34:07 INFO - Reading from file tmpfile_stdout 09:34:07 INFO - Detecting whether we're running mozinstall >=1.0... 09:34:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:34:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:34:07 INFO - Reading from file tmpfile_stdout 09:34:07 INFO - Output received: 09:34:07 INFO - Usage: mozinstall [options] installer 09:34:07 INFO - Options: 09:34:07 INFO - -h, --help show this help message and exit 09:34:07 INFO - -d DEST, --destination=DEST 09:34:07 INFO - Directory to install application into. [default: 09:34:07 INFO - "/builds/slave/test"] 09:34:07 INFO - --app=APP Application being installed. [default: firefox] 09:34:07 INFO - mkdir: /builds/slave/test/build/application 09:34:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 09:34:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 09:34:27 INFO - Reading from file tmpfile_stdout 09:34:27 INFO - Output received: 09:34:27 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 09:34:27 INFO - Running post-action listener: _resource_record_post_action 09:34:27 INFO - [mozharness: 2017-03-22 16:34:27.574525Z] Finished install step (success) 09:34:27 INFO - [mozharness: 2017-03-22 16:34:27.574651Z] Running run-tests step. 09:34:27 INFO - Running pre-action listener: _resource_record_pre_action 09:34:27 INFO - Running main action method: run_tests 09:34:27 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:34:27 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:34:27 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:34:27 INFO - 2017-03-22 09:34:27.588 screenresolution[1630:7369] starting screenresolution argv=screenresolution get 09:34:27 INFO - 2017-03-22 09:34:27.591 screenresolution[1630:7369] Display 0: 1600x1200x32@60 09:34:27 INFO - 2017-03-22 09:34:27.597 screenresolution[1631:7371] starting screenresolution argv=screenresolution list 09:34:27 INFO - Available Modes on Display 0 09:34:27 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:34:27 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:34:27 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:34:27 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:34:27 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:34:27 INFO - Intel Iris: 09:34:27 INFO - Chipset Model: Intel Iris 09:34:27 INFO - Type: GPU 09:34:27 INFO - Bus: Built-In 09:34:27 INFO - VRAM (Dynamic, Max): 1536 MB 09:34:27 INFO - Vendor: Intel (0x8086) 09:34:27 INFO - Device ID: 0x0a2e 09:34:27 INFO - Revision ID: 0x0009 09:34:27 INFO - Displays: 09:34:27 INFO - Display: 09:34:27 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:34:27 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:34:27 INFO - Display Serial Number: 1600x1200 60 09:34:27 INFO - Main Display: Yes 09:34:27 INFO - Mirror: Off 09:34:27 INFO - Online: Yes 09:34:27 INFO - Rotation: Supported 09:34:27 INFO - Return code: 0 09:34:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:34:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:34:27 INFO - Python 2.7.3 09:34:27 INFO - Return code: 0 09:34:27 INFO - grabbing minidump binary from tooltool 09:34:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:34:27 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 0x10c8ddd30>, '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 0x10c804db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c8d2be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:34:27 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 09:34:27 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 09:34:27 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 09:34:27 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:34:27 INFO - Return code: 0 09:34:27 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 09:34:27 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:34:27 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:34:27 INFO - ENV: RUST_BACKTRACE is now 1 09:34:27 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 09:34:27 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:34:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/L4ScK0lXS2yNhIhzFHjDGw/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0246', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 09:34:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/L4ScK0lXS2yNhIhzFHjDGw/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0246 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 09:34:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qQhUA9mFfc/Render', 09:34:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:34:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:34:27 INFO - 'HOME': '/Users/cltbld', 09:34:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:34:27 INFO - 'LOGNAME': 'cltbld', 09:34:27 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:34:27 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 09:34:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:34:27 INFO - 'MOZ_NO_REMOTE': '1', 09:34:27 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:34:27 INFO - 'NO_EM_RESTART': '1', 09:34:27 INFO - 'PAGER': '/bin/cat', 09:34:27 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:34:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:34:27 INFO - 'PWD': '/builds/slave/test', 09:34:27 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:34:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:34:27 INFO - 'RUST_BACKTRACE': '1', 09:34:27 INFO - 'SHELL': '/bin/bash', 09:34:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wFX9LpraDu/Listeners', 09:34:27 INFO - 'TMPDIR': '/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/', 09:34:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:34:27 INFO - 'USER': 'cltbld', 09:34:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:34:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:34:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:34:27 INFO - 'XPC_FLAGS': '0x0', 09:34:27 INFO - 'XPC_SERVICE_NAME': '0', 09:34:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:34:27 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/L4ScK0lXS2yNhIhzFHjDGw/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0246', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 09:34:28 INFO - mozversion application_buildid: 20170322075834 09:34:28 INFO - mozversion application_changeset: e03e0c60462c775c7558a1dc9d5cf2076c3cd1f9 09:34:28 INFO - mozversion application_display_name: Nightly 09:34:28 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:34:28 INFO - mozversion application_name: Firefox 09:34:28 INFO - mozversion application_remotingname: firefox 09:34:28 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 09:34:28 INFO - mozversion application_vendor: Mozilla 09:34:28 INFO - mozversion application_version: 55.0a1 09:34:28 INFO - mozversion platform_buildid: 20170322075834 09:34:28 INFO - mozversion platform_changeset: e03e0c60462c775c7558a1dc9d5cf2076c3cd1f9 09:34:28 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 09:34:28 INFO - mozversion platform_version: 55.0a1 09:34:28 INFO - using testdate: 1490200468 09:34:28 INFO - actual date: 1490200468 09:34:28 INFO - starting webserver on 'localhost:49244' 09:34:28 INFO - SUITE-START | Running 2 tests 09:34:28 INFO - TEST-START | dromaeo_css 09:34:28 INFO - operating with platform_type : mac_ 09:34:28 INFO - Initialising browser for dromaeo_css test... 09:34:28 INFO - TEST-INFO | started process 1641 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/tmp_XiYoC/profile http://localhost:49244/getInfo.html) 09:34:31 INFO - PID 1641 | 2017-03-22 09:34:31.337 plugin-container[1642:7546] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8e3f, name = 'com.apple.tsm.portname' 09:34:31 INFO - PID 1641 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:34:31 INFO - PID 1641 | 2017-03-22 09:34:31.340 plugin-container[1642:7546] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x940b, name = 'com.apple.CFPasteboardClient' 09:34:31 INFO - PID 1641 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:34:31 INFO - PID 1641 | 2017-03-22 09:34:31.340 plugin-container[1642:7546] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:34:31 INFO - PID 1641 | __metrics Screen width/height:1600/1200 09:34:31 INFO - PID 1641 | colorDepth:24 09:34:31 INFO - PID 1641 | Browser inner width/height: 1024/689 09:34:31 INFO - PID 1641 | __metrics 09:34:31 ERROR - PID 1641 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3350: TypeError: this.tabs is undefined 09:34:32 INFO - TEST-INFO | 1641: exit 0 09:34:32 INFO - Browser initialized. 09:34:32 INFO - Running cycle 1/1 for dromaeo_css test... 09:34:32 INFO - TEST-INFO | started process 1644 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/tmp_XiYoC/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:34:34 INFO - PID 1644 | 2017-03-22 09:34:34.739 plugin-container[1645:7679] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8b4b, name = 'com.apple.tsm.portname' 09:34:34 INFO - PID 1644 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:34:34 INFO - PID 1644 | 2017-03-22 09:34:34.739 plugin-container[1645:7679] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x900f, name = 'com.apple.CFPasteboardClient' 09:34:34 INFO - PID 1644 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:34:34 INFO - PID 1644 | 2017-03-22 09:34:34.739 plugin-container[1645:7679] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:34:44 INFO - PID 1644 | RSS: Main: 227549184 09:34:44 INFO - PID 1644 | 09:37:48 INFO - PID 1644 | Cycle 1(1): loaded http://localhost:49244/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49244/tests/dromaeo/cssquery-ext.html) 09:37:50 INFO - PID 1644 | RSS: Main: 251240448 09:37:50 INFO - PID 1644 | 09:39:32 INFO - PID 1644 | ************************* 09:39:32 INFO - PID 1644 | A coding exception was thrown and uncaught in a Task. 09:39:32 INFO - PID 1644 | 09:39:32 ERROR - PID 1644 | Full message: TypeError: NetworkError when attempting to fetch resource. 09:39:32 INFO - PID 1644 | Full stack: 09:39:32 INFO - PID 1644 | ************************* 09:40:52 INFO - PID 1644 | Cycle 1(1): loaded http://localhost:49244/tests/dromaeo/cssquery-ext.html (next: http://localhost:49244/tests/dromaeo/cssquery-jquery.html) 09:40:53 INFO - PID 1644 | RSS: Main: 259035136 09:40:53 INFO - PID 1644 | 09:43:55 INFO - PID 1644 | Cycle 1(1): loaded http://localhost:49244/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49244/tests/dromaeo/cssquery-mootools.html) 09:43:55 INFO - PID 1644 | RSS: Main: 270344192 09:43:55 INFO - PID 1644 | 09:46:57 INFO - PID 1644 | Cycle 1(1): loaded http://localhost:49244/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49244/tests/dromaeo/cssquery-prototype.html) 09:46:58 INFO - PID 1644 | RSS: Main: 263041024 09:46:58 INFO - PID 1644 | 09:50:01 INFO - PID 1644 | Cycle 1(1): loaded http://localhost:49244/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49244/tests/dromaeo/cssquery-yui.html) 09:50:01 INFO - PID 1644 | RSS: Main: 273235968 09:50:01 INFO - PID 1644 | 09:53:04 INFO - PID 1644 | Cycle 1(1): loaded http://localhost:49244/tests/dromaeo/cssquery-yui.html (next: http://localhost:49244/tests/dromaeo/cssquery-dojo.html) 09:53:05 INFO - PID 1644 | RSS: Main: 268148736 09:53:05 INFO - PID 1644 | 09:53:05 INFO - PID 1644 | __start_tp_report 09:53:05 INFO - PID 1644 | _x_x_mozilla_page_load 09:53:05 INFO - PID 1644 | _x_x_mozilla_page_load_details 09:53:05 INFO - PID 1644 | |i|pagename|runs| 09:53:05 INFO - PID 1644 | |0;dojo.html;3352.9329413411733;3573.6962358199553;3595.4427063805088;3627.365211088059;3630.473581330707;6043.335233124359;6137.416945390192;6216.254049514059;6237.0956211349385;6250.124982502453;6136.938630613704;6151.692415379235;6202;6218.84452888678;6281.623102613847;4855.5387238212315;5598.888022239553;5616.241807356016;5638.351589567212;5824.825255242349;5578.776848926038;5677.261955945722;5762.769489220426;5786.623869448473;5831.591788574801;5515.338159420895;5585.357683866367;5589.496945274945;5668.858278543028;5697.572682048863;5546.029444847136;5550.111982082847;5586.776528938878;5677.630953987978;5743.59794814359;5552.583556233299;5600.18797274397;5618.719064046782;5651.943480565183;5662.631928924607;5068.062408454448;5654.208410822488;6216.471599914013;6231.2522497300615;6243;6398.104265402847;6465.224173099211;6560.081588577603;6653.367930046637;6655.301193374675;6474.6762661866715;6556.082148499214;6578.243501997284;6593;6605.57063790852;6211.9689401553605;6272.65500397478;6278.30938596754;6283.8114856554375;6312.558120931537;6591.242007169141;6654.900176497356;6751.864962700718;6819.795406137824;6852.349026842442;6356.14192084072;6379.904301435482;6392.360763923571;6510.153680021662;6585.80242592723;6658.667066646745;6729.596224226562;6750;6757.189137303457;6795.762148324884;6584.374484423972;6585.1439312889015;6602.2737498875085;6669.266380698119;6696.330366963362;6417.7753778617525;6517.902231466532;6522.608643481311;6645.634490103091;6684.064231007663;4269.380939763717;5331.386890507563;5361.195820626859;5365.168398898177;5381.0583147948955;4714.740689262124;4715.95284047162;4719.480857105716;4729.527047295312;4778.402699662543;6306.716197771111;6347.619142851444;6348.460380867586;6349.333320001426;6354.459870911024;6473.546851720334;6550.410463058348;6639.336066393419;6640.302768209365;6704.765333213411;6351.237851457855;6361.236651601837;6367.299597044322;6371.23545174582;6373.840653983688;5884.705764711747;5925.459380561085;5931.851703707355;5943.435373639497;5944.821655350346;6091.817245482643;6343.333949935375;6550.508711846535;6774.983752437174;6863.553868998599;5688.744006519633;6701.597904125768;6780.999999999803;6792.252852186354;6801.863962720817;6876.346747059021;6907.205671347659;6913;6913.41235994926;6922.27316131819;6321.49428045746;6383.36166383358;6540.280569137296;6604.273529911609;6642.76750313756;6531.640759758259;6670.966645166744;6747.122874026345;6761.22245941723;6771.322867713189;6299.6535190564955;6545.247296560957;6553.180852393451;6553.279139294769;6558.409743123142;6250.687465626609;6385.074164246065;6393.6163830170335;6458.160439142881;6527.67361631907;6308.211473565804;6436.806895793134;6446.258680251643;6453.290138084901;6484.059811327425;6305.684715764101;6339.96830015847;6382.138411314413;6393.840153996001;6433.324500927335;6371.426571608392;6376.139221205078;6383.96808015957;6400.423961843457;6406.647634379968 09:53:05 INFO - PID 1644 | |1;ext.html;723.7647764476546;799.6001999000499;822.8189798244387;1276.9680757981055;1282.6985658370288;37169.25661486772;37695;37771;37843.86468405949;37900.43149352755;37200.44199337012;37587.73771671968;37623;37648;37674.24651506968;7873.842523149548;8000.159983201767;8374.28818550424;8575.185357391038;8585.527795971218;7339.376153026999;7686.308232259095;8406.83186336273;8407.117252688471;8518.361122915776;7878.6848526059175;7962.925005124321;8541.103184165666;8546.102659220785;8547.529885856307;8478.406511544194;8511.95744021276;8529.061803201643;8536.743897683049;8538.573071346438;7543.660535275926;8435;8510.106438823896;8514.106018868053;8552;38251.85244442671;38452.61547384518;38521;38582.22835543301;38678.806605967075;2980.955285670717;3018.667946525902;3030.3939212157657;3041.8022828516077;3056.144279601715;28389.858050709616;28518.572221416696;28719;28863.567046494318;29624.555631665542;2767.6955534891144;2799.328161241308;2801.90193343232;2871.612332335129;2883.9134825955257;234611.8269408642;237674;305002.47498762363;325973;326446;49569.50430495721;49721.502784971686;49853.50146498561;49870.75064624654;49876.50123498718;38583.8424847255;51312.48687513077;51775.22337164945;51905.7404712974;51958;14507.274636268145;14565.854341456661;14568.271586420637;14577;14610.269486525844;11919.880801191877;11966;11970.281783093042;11991;12050.75898482043;12019.038476921825;12257.080718946114;13506.797398039038;13723.313834308245;13993.440262389595;5116.846494605168;5943.673097979653;5995.220621319288;6037.396260374016;6048.939510604926;973.464594473037;974.133021610767;979.5787811241092;980.6910823090703;1015.6242190389603;24401.38996525101;24674.013039478577;24754.752452475244;24809.883555240056;24832.5033499329;988.4267125067444;1078.4823284823328;1103.564092183583;1116.525476672411;1119.1158984402393;11863;12462.189957652723;12507.249565026128;12516.123871329102;12521.749565008831;452466.47533524904;455460;456777;457961;459121;56429;56637;56685.7165714177;56717;57944.55138621484;1407.7184563087424;1414.1373762005169;1486.5466032860081;1516.8103987001625;1529.1971714849792;20231.797682022992;23937.803109844786;23999;24198.395040124138;24298.878505607358;38832.417513737884;38834.834954951395;38906.6382676605;38948.80525597354;39012.2197556053;426326;427804;428845;429623;430760;31940.5208921871;32813.83593082019;32905.84829530955;32914.50628240626;33977.66022339745;43566;43868.56131438773;43984.780076099414;44284.33573496336;44480.55519444764;26331.341716457242;29938.850305748332;31503.739850405727;35241;36889.63110368862;35184.296314073064;35249.82375088108;36236.81881590681;36307.818460907525;36641.45037824381;35219.47170792492;36330;36423.453648194765;36430.8178459106;36663.08342291464;30516.542251866686;32019.839900800347;32044.18787824435;32211.677883221804;32365;24160;24188;24283.635745463478;24611;24756.628650570616 09:53:05 INFO - PID 1644 | |2;jquery.html;416.4003834478353;422.2420754745232;422.37910271900313;437.1911962868693;447.57480393626065;16759.413420530283;18690.813091869077;20292.289769858056;21063;21200.36398908033;21591.6761248581;21624;21692.023858926346;21834.034936855718;22201.2229571965;5975.133605627192;6179.486025923633;6229.3459186785185;6231.065340198984;6233.532485063615;5949.107633854935;6090.878182436349;6126.326104128542;6153.753849846001;6156.230471191101;6068.878622427549;6120.816375508742;6130.356312587181;6138.14066030756;6149.969250153743;5889.057750759879;5971.910421343683;6134.785282515135;6136.417040381157;6139.26328840542;5329.733513324319;5408.432114627986;5409.945900541023;5420.593455490854;5451.918221226685;26162.08432704865;26539;26656.466870662673;26932.306769323106;26984.73015269842;1864.692325766247;1958.5495336072784;1962.3916585858287;1966.1545535419764;2020.8484363672637;15861.762073568905;15916;15963.44127955516;16134.435294764628;16692.165391730487;1709.9658006839918;1723.8716363328363;1748.6502699460032;1761.674090293287;1765.6645237404853;145223;155834.66248006176;156348.218258908;156678;157079;10361.689149325532;12289.938550307192;12334.938325308316;12501.93749031249;12731.809022864665;15993.28030238642;16193.109378984269;16229.188540572924;16236;16277.511674649779;7331.706731730777;8215.383846211558;8424.915750842536;8437.620307086196;8520.403571749917;6543.574667646588;7789.922100778919;7803.921960780433;7923.801904952422;7943.602819858984;7785.727499537489;7878.684852605831;7929.581477649373;7957.761267161879;7976.441649084624;2151.5239130218156;2162.491814423641;2166.180408456904;2175.695402643631;2371.5019845832226;831.6756464978663;914.4147745442922;917.3074328881652;934.2292608597934;956.4930586788873;15510.689786204213;18899.622007560047;19908.60182796336;20526.692099618518;20563.383098507067;946.2902822882838;953.6662168241198;990.6780296403698;992.836182029957;994.5126887825058;10154.136898363575;10572.682819515427;10597.682069537927;10604.787904241872;10662.573497060032;453741;556595;578016;747415;750151;136669.31665341608;141394.2930285342;148993.7650935237;153122;154500.90998179975;1173.8943495085484;1186.9525218991316;1233.4326209943347;1234.962951111468;1235.8516977962522;18814.905925470284;19951.501212469804;21097.367078987656;21109.788902110784;21205.575888481835;48711.512884872114;52843.47156528539;54552.454475456325;55187.44812551823;59731.402685972585;82608;82612.58693706493;83177;83551.58224209084;84076;6083.361247068994;8324.209200125979;8457.619407126816;8588.785280368042;8733.257673097603;10686.305390149615;12384.81422778641;13408.865911341152;14000.719985600435;14035.929820350833;11435.142364322543;11460.312381256817;11826;11833.822492662444;11938.22401543897;11382.772344553228;11581.247218930743;11598.826017609574;11599.88400115988;11911.642650720492;11490.08079353633;11630.883691163319;11905.166638335233;11957.505311836021;12002.459889304828;8351.29014033814;8504.659813607386;8541.359398045295;8658.696945606873;8795.296376290009;11504.482298296429;11636.709082273012;11690.590829320932;11773.76452470963;11886.227395219283 09:53:05 INFO - PID 1644 | |3;mootools.html;1226.2826246645711;1451.4919778077667;1461.6053665510312;1469.7795330700387;1476.8670819626234;10450.216233782468;10609.8408523872;10756.623518176866;10862.945685271572;10872.238943273975;8934.687285944994;10531.26281160319;10742.033217010467;10749.785004299909;10783.514741836616;2418.407490164912;2436.6345048242815;2444.8533088014774;2453.472503411766;2455.4107014316614;1994.41164856367;2448.4980578981267;2448.9142879999204;2456.5946618807966;2457.852528848266;2404.699412573428;2428.077330614364;2431.0794472366897;2438.317271164077;2458.4960083182996;2386.1886958434216;2410.144398738449;2412.1798588479824;2421.8617249892523;2424.7211570669424;2348.6477028445697;2379.940501487459;2430.2466235467064;2446.204983380391;2448.951020979588;10920.12638988879;10990.340579565172;11057;11057.225994180411;11074.335539867634;5466.617336786427;6139.846503837395;6341.2707538633185;6417.8395540111405;6422.4862011039;3994;4019.135885784541;4020.2763502569524;4040.717149799515;4080.9591904080876;1138.7758159978057;1155.763068570941;1175.6353544530602;1205.511767734069;1222.462116668663;13568.185908845402;13966.790498142434;15006.24968751569;15113.3199006045;15146.848531514654;5105.566026887682;5235.764390602432;5354.41101478837;5377.892442151135;5391.056565101092;11340.149488788375;11403.486843092081;11453.198276120758;11455;11470.48382822775;5234.816781412632;6177.135201071854;6262.373762623702;6280.277768056732;6288.434040936338;5592.748326325325;5802.013657678205;5802.419758024248;5831.096180092092;5883.294004719375;4131.277026520347;4200.6219440250525;4216.494020717533;4261.957380426218;4269.146170765859;1262.3940508555945;1287.9147857765063;1298.7597294500547;1299.3698056442618;1304.6477451088153;663;755.4032314471616;760.3271105071941;768.738628866177;796.9521828690297;5904.911426328608;6056.153607804325;6436.806895793134;6568.113304703899;6626.801195964129;495.7223954585444;535.6759160707794;578.8726480174355;583.7577405376805;600.9063504421911;3248.090534650302;3607.350676878187;3671.265746850587;3774.4336100518526;3784.8864534064023;10837.295575787706;12808.551700690432;13522.459101636021;13918.58244252674;13918.860811391756;13169.53906613264;13418.463261469627;13645.38595763193;13771.449142034407;13909.0959087661;10108.090271875566;10122.645707400206;10150.492475376202;10219.642312519027;10239.385636861813;5473.835784926299;6234.844128896814;6445.70994305248;6455.419012288917;6465.64438955862;11024.889751102386;11434.656960291204;12039.277643341427;12332.691682707646;12363.93818030904;12327.876721232673;12414.441350139085;12652.367381631066;12781;12817.807732884201;7034.402075823612;8356.582170891552;8777.648894044167;9118.088191180828;9247.352685312233;9881.654142104991;10249.33379330341;10288.897111028793;10352.637657681946;10367.377957322586;8860.069692682175;8906.688265910661;8966.775830604287;9046.366754327133;9168.679096231599;7445.329920307198;7840.490368126054;8488.236058754741;8674.739757807276;8811.986621538605;6565.868682626226;7733.651985660546;7865.528068315919;7887.64505597261;7904.20957904228;5797.739101740347;5867.20792692981;5951.7024148791525;5997.790077347272;6036.336003039574;5931.61444506123;6025.638461692312;6112.572119951738;6118.357572455006;6210.130581718653 09:53:05 INFO - PID 1644 | |4;prototype.html;364.49335423760994;402.32409551952725;403.247942587075;404.1391835390618;416.29438102416395;5242.501962313579;5799.680127948823;6483.675816209183;6547.34526547345;6552.279249282581;5050.621203409741;5634.267545219126;6200.379962003797;6239.095331176976;6381.682134686662;4823.4935331790275;4943.233798761198;4971.776270067838;4977.228529577903;4984.775685094161;4858.076965376585;4975.577075948549;4994.829317519857;5020.196768517038;5022.995400919813;3804.733668643196;4842.273658951168;4869.366982292297;4899.044686286158;4909.993451342484;4258.403823464718;4723.574878260956;4917.139500587401;4938.950610493885;4942.851714448572;3764.983454467306;3805.534869075426;4330.913381732347;4336.002719374562;4375.365571992075;5251.264822924793;5727.427257274281;6019.157317975487;6201.9689901550655;6252.87494250117;4974.42794078682;5122.2351571594845;5585.291133800104;5670.347909990322;5699.173619825102;6196.473299769525;6353.269374022;6714.294999025081;6877.6217308048035;6908.205556360982;3952.7035472339403;4497.527759585229;4499.527549607277;4500.189965806154;4510.639148868083;6593.307702691196;6671.266160722316;6879;7246.036465021477;7257.169703815516;4447.688661793708;4509;4572.634189264851;4582.702124361906;4595.517470665532;3897.8635747748694;4660.067986402665;4663.533646635377;4670.602998745145;4678.251479763221;5157.561607263425;5362.329708786402;5444.482774136451;5504.91742623861;5510.090835012258;5989.251343582052;6842.213145488233;6867;6868.62222577763;6878.7248510059035;1276.9553065642658;1474.5944865161953;1570.434643528329;1587.1588058329105;1594;1017.6539976407909;1030.7268573827942;1040.1834559870465;1043.0977204717865;1047.9580816767395;6450.193725784277;6476.255230648441;6488.026795980546;6488.480921526267;6518.674066296666;5924.289085309791;6852.862942741217;7067.89398159028;7089.716411343592;7144.714211431588;5642.520385767256;5707.828765137053;6430.3891130342545;6441.355864413521;6459.321771214049;3672.4307732301536;3701.130234394915;3702.777833330009;3714.647108524686;3720.479132921393;5263.210518422267;5713.571482138773;6386.361363863576;7242.601656908815;7308.843891052153;7232.349088582053;7292.739250832259;7303.853922921512;7308.780736577911;7317.743878964211;7041.169295983082;7222.53053551528;7287.417006639456;7336.486445948945;7376.9713328835;6163.753449861955;6751.763688271083;7113.288671132846;7132.543604142731;7154.463415243774;5523.6685798852195;5648.887022259614;5756.309242890712;5767.769289228551;6389.744410223539;5944.3164036136395;7009.509334346547;7121.145462544528;7273.163586187656;7280.5995670236525;4541.500434952219;4547.613452856544;4555;4558.521355257651;4563.155816174019;5516.834494965157;6899.896016637314;7029.683664235019;7080.5484875601405;7111.751088711871;5605.990921634145;6597.736090556324;6748.2576916538155;6838.384545390939;6879.484038697218;6436.9678151610815;6739.0902228198565;6832.521723479309;6842.144731908512;6894;5494.835154945358;6155.446009859134;6420.325865784025;6694.765683201064;6722.731090756511;5908.084246941731;6366.853966286113;6569.37590928861;6865.519413640997;6915.135608048994;5516;6587.538872278895;6660.533762636569;6669.166354205724;6679.499037572299 09:53:05 INFO - PID 1644 | |5;yui.html;635.6630985577642;642.6401215319421;711.79357986184;715.5241764226793;741.3513175971025;15113.697726045473;15281.770773438408;15778.368865245377;15789.052656840599;16006;14354.641133971656;14800.40798368064;15103.395864165448;15121.546353609408;15273.694526109472;878.0648609231164;894.8791913091715;900.4012331799346;901.0898992018076;901.2699713232303;892.5894088719197;893.8078313162669;901.6528636474972;929.9395539289925;939.6476321379482;979.0552117206894;981.8183636027322;982.4743762087285;985.2069084387066;987.4568987057125;971.5870754929127;978.3249557805092;978.7455261631956;979.2753362511726;986.8273052215869;722.7542635503954;724.0840336973732;724.6304384763756;729.5659082845698;734.9816254593625;16307.103109328982;16444.75332870008;16739.16304184786;17106.31574737009;17338.739918901225;14575.562733118024;14738.55784326472;14768.458091187453;14873.628159296104;14886.478973235884;10890.237683362167;11019.338839669565;11059.447027648584;11208.66374008781;11400.429978501043;3049.1767222849926;3058.464768665497;3078.922377167996;3148.1972097115317;3181.602299712536;108833;110100.34849477264;111333;112768;113460;11183.384913829817;11247.240398575927;11384.886151138548;11609.825852612217;11910.106741994385;24775.752242477705;24960.126395576066;25052.997880084957;25069;25100.874495627406;7440.627968601549;7552.848943021108;7571.318581327597;7676.232376762279;7866.7639970800965;4018.3771515415156;4057.4725285713257;4081.387791831249;4092.4066010428323;4096.32410652239;3357.261402491448;3962.7622342659533;4040.878773636796;4041.8989525262105;4049.817758200888;791.9564423956738;796.8924195233685;803.5580430763057;807.2089352434647;817.9059408168018;526.457748519026;550.2159422822461;566.8516323135271;569.0759196761691;587.538782056084;9509.334346595671;10049.59801607942;10179.134773544332;10242.283040187265;10334.276600638033;542.481929757081;571.6255852416591;582.8338923406809;584.4885724990634;594.5719082260765;4668.183067963093;4797.136515427187;4857.975710121497;4876.976713984602;4907.484714105039;78398.82401763978;79013;79220;80413;82286.17713822905;74872;75061.62469187619;78419.86320547228;82051.5897420521;82899.5855020721;55574.16638750422;74057.88913166306;74412;74692.62653686697;75284;9207.401518901517;10165.695029149138;10427.739306517095;10803.783924321628;10886.129109671365;35627;36484.723034693656;36890.8155459221;37618.059548511505;38131;49205.75397122991;62933.42666433376;64622.353776461634;66053.66973165104;67486.66256668685;13344.866551334362;13437.798433023709;13579.728405431639;13649.590512284647;14189.935754818223;14435.267767867923;14688.632784180481;14690.926545367205;14876.25618719038;14889.255537223313;11675.88324116782;13736.725265494833;13740.175589464265;13799.793003105162;13815.369897959328;11701.76596468083;13339.333033348488;13593.932030339785;13597.303707402321;13707;13208.857187098898;13527.053106282467;13579.86420135786;13636.250006249751;13771.449142034206;9275.318904615639;10540.551251312776;10627.893721063;10853.782924341627;11239.550417983517;9832.655857044969;10800.00199962978;10971.341719496537;10977.231593788358;11005.944970275097 09:53:05 INFO - PID 1644 | __end_tp_report 09:53:05 INFO - PID 1644 | __start_cc_report 09:53:05 INFO - PID 1644 | _x_x_mozilla_cycle_collect,422 09:53:05 INFO - PID 1644 | __end_cc_report 09:53:05 INFO - PID 1644 | __startTimestamp1490201585126__endTimestamp 09:53:05 INFO - PID 1644 | 09:53:05 INFO - PID 1644 | ------- Summary: start ------- 09:53:05 INFO - PID 1644 | Number of tests: 6 09:53:05 INFO - PID 1644 | 09:53:05 INFO - PID 1644 | [#0] dojo.html Cycles:165 Average:6144.10 Median:6367.30 stddev:692.18 (10.9%) stddev-sans-first:658.76 09:53:05 INFO - PID 1644 | Values: 3352.9 3573.7 3595.4 3627.4 3630.5 6043.3 6137.4 6216.3 6237.1 6250.1 6136.9 6151.7 6202.0 6218.8 6281.6 4855.5 5598.9 5616.2 5638.4 5824.8 5578.8 5677.3 5762.8 5786.6 5831.6 5515.3 5585.4 5589.5 5668.9 5697.6 5546.0 5550.1 5586.8 5677.6 5743.6 5552.6 5600.2 5618.7 5651.9 5662.6 5068.1 5654.2 6216.5 6231.3 6243.0 6398.1 6465.2 6560.1 6653.4 6655.3 6474.7 6556.1 6578.2 6593.0 6605.6 6212.0 6272.7 6278.3 6283.8 6312.6 6591.2 6654.9 6751.9 6819.8 6852.3 6356.1 6379.9 6392.4 6510.2 6585.8 6658.7 6729.6 6750.0 6757.2 6795.8 6584.4 6585.1 6602.3 6669.3 6696.3 6417.8 6517.9 6522.6 6645.6 6684.1 4269.4 5331.4 5361.2 5365.2 5381.1 4714.7 4716.0 4719.5 4729.5 4778.4 6306.7 6347.6 6348.5 6349.3 6354.5 6473.5 6550.4 6639.3 6640.3 6704.8 6351.2 6361.2 6367.3 6371.2 6373.8 5884.7 5925.5 5931.9 5943.4 5944.8 6091.8 6343.3 6550.5 6775.0 6863.6 5688.7 6701.6 6781.0 6792.3 6801.9 6876.3 6907.2 6913.0 6913.4 6922.3 6321.5 6383.4 6540.3 6604.3 6642.8 6531.6 6671.0 6747.1 6761.2 6771.3 6299.7 6545.2 6553.2 6553.3 6558.4 6250.7 6385.1 6393.6 6458.2 6527.7 6308.2 6436.8 6446.3 6453.3 6484.1 6305.7 6340.0 6382.1 6393.8 6433.3 6371.4 6376.1 6384.0 6400.4 6406.6 09:53:05 INFO - PID 1644 | 09:53:05 INFO - PID 1644 | [#1] ext.html Cycles:180 Average:52866.88 Median:36776.36 stddev:105730.84 (287.5%) stddev-sans-first:105954.96 09:53:05 INFO - PID 1644 | Values: 723.8 799.6 822.8 1277.0 1282.7 37169.3 37695.0 37771.0 37843.9 37900.4 37200.4 37587.7 37623.0 37648.0 37674.2 7873.8 8000.2 8374.3 8575.2 8585.5 7339.4 7686.3 8406.8 8407.1 8518.4 7878.7 7962.9 8541.1 8546.1 8547.5 8478.4 8512.0 8529.1 8536.7 8538.6 7543.7 8435.0 8510.1 8514.1 8552.0 38251.9 38452.6 38521.0 38582.2 38678.8 2981.0 3018.7 3030.4 3041.8 3056.1 28389.9 28518.6 28719.0 28863.6 29624.6 2767.7 2799.3 2801.9 2871.6 2883.9 234611.8 237674.0 305002.5 325973.0 326446.0 49569.5 49721.5 49853.5 49870.8 49876.5 38583.8 51312.5 51775.2 51905.7 51958.0 14507.3 14565.9 14568.3 14577.0 14610.3 11919.9 11966.0 11970.3 11991.0 12050.8 12019.0 12257.1 13506.8 13723.3 13993.4 5116.8 5943.7 5995.2 6037.4 6048.9 973.5 974.1 979.6 980.7 1015.6 24401.4 24674.0 24754.8 24809.9 24832.5 988.4 1078.5 1103.6 1116.5 1119.1 11863.0 12462.2 12507.2 12516.1 12521.7 452466.5 455460.0 456777.0 457961.0 459121.0 56429.0 56637.0 56685.7 56717.0 57944.6 1407.7 1414.1 1486.5 1516.8 1529.2 20231.8 23937.8 23999.0 24198.4 24298.9 38832.4 38834.8 38906.6 38948.8 39012.2 426326.0 427804.0 428845.0 429623.0 430760.0 31940.5 32813.8 32905.8 32914.5 33977.7 43566.0 43868.6 43984.8 44284.3 44480.6 26331.3 29938.9 31503.7 35241.0 36889.6 35184.3 35249.8 36236.8 36307.8 36641.5 35219.5 36330.0 36423.5 36430.8 36663.1 30516.5 32019.8 32044.2 32211.7 32365.0 24160.0 24188.0 24283.6 24611.0 24756.6 09:53:05 INFO - PID 1644 | 09:53:05 INFO - PID 1644 | [#2] jquery.html Cycles:180 Average:37707.64 Median:11933.86 stddev:106063.83 (888.8%) stddev-sans-first:106324.41 09:53:05 INFO - PID 1644 | Values: 416.4 422.2 422.4 437.2 447.6 16759.4 18690.8 20292.3 21063.0 21200.4 21591.7 21624.0 21692.0 21834.0 22201.2 5975.1 6179.5 6229.3 6231.1 6233.5 5949.1 6090.9 6126.3 6153.8 6156.2 6068.9 6120.8 6130.4 6138.1 6150.0 5889.1 5971.9 6134.8 6136.4 6139.3 5329.7 5408.4 5409.9 5420.6 5451.9 26162.1 26539.0 26656.5 26932.3 26984.7 1864.7 1958.5 1962.4 1966.2 2020.8 15861.8 15916.0 15963.4 16134.4 16692.2 1710.0 1723.9 1748.7 1761.7 1765.7 145223.0 155834.7 156348.2 156678.0 157079.0 10361.7 12289.9 12334.9 12501.9 12731.8 15993.3 16193.1 16229.2 16236.0 16277.5 7331.7 8215.4 8424.9 8437.6 8520.4 6543.6 7789.9 7803.9 7923.8 7943.6 7785.7 7878.7 7929.6 7957.8 7976.4 2151.5 2162.5 2166.2 2175.7 2371.5 831.7 914.4 917.3 934.2 956.5 15510.7 18899.6 19908.6 20526.7 20563.4 946.3 953.7 990.7 992.8 994.5 10154.1 10572.7 10597.7 10604.8 10662.6 453741.0 556595.0 578016.0 747415.0 750151.0 136669.3 141394.3 148993.8 153122.0 154500.9 1173.9 1187.0 1233.4 1235.0 1235.9 18814.9 19951.5 21097.4 21109.8 21205.6 48711.5 52843.5 54552.5 55187.4 59731.4 82608.0 82612.6 83177.0 83551.6 84076.0 6083.4 8324.2 8457.6 8588.8 8733.3 10686.3 12384.8 13408.9 14000.7 14035.9 11435.1 11460.3 11826.0 11833.8 11938.2 11382.8 11581.2 11598.8 11599.9 11911.6 11490.1 11630.9 11905.2 11957.5 12002.5 8351.3 8504.7 8541.4 8658.7 8795.3 11504.5 11636.7 11690.6 11773.8 11886.2 09:53:05 INFO - PID 1644 | 09:53:05 INFO - PID 1644 | [#3] mootools.html Cycles:180 Average:6660.07 Median:2457.22 stddev:4099.23 (166.8%) stddev-sans-first:4090.39 09:53:05 INFO - PID 1644 | Values: 1226.3 1451.5 1461.6 1469.8 1476.9 10450.2 10609.8 10756.6 10862.9 10872.2 8934.7 10531.3 10742.0 10749.8 10783.5 2418.4 2436.6 2444.9 2453.5 2455.4 1994.4 2448.5 2448.9 2456.6 2457.9 2404.7 2428.1 2431.1 2438.3 2458.5 2386.2 2410.1 2412.2 2421.9 2424.7 2348.6 2379.9 2430.2 2446.2 2449.0 10920.1 10990.3 11057.0 11057.2 11074.3 5466.6 6139.8 6341.3 6417.8 6422.5 3994.0 4019.1 4020.3 4040.7 4081.0 1138.8 1155.8 1175.6 1205.5 1222.5 13568.2 13966.8 15006.2 15113.3 15146.8 5105.6 5235.8 5354.4 5377.9 5391.1 11340.1 11403.5 11453.2 11455.0 11470.5 5234.8 6177.1 6262.4 6280.3 6288.4 5592.7 5802.0 5802.4 5831.1 5883.3 4131.3 4200.6 4216.5 4262.0 4269.1 1262.4 1287.9 1298.8 1299.4 1304.6 663.0 755.4 760.3 768.7 797.0 5904.9 6056.2 6436.8 6568.1 6626.8 495.7 535.7 578.9 583.8 600.9 3248.1 3607.4 3671.3 3774.4 3784.9 10837.3 12808.6 13522.5 13918.6 13918.9 13169.5 13418.5 13645.4 13771.4 13909.1 10108.1 10122.6 10150.5 10219.6 10239.4 5473.8 6234.8 6445.7 6455.4 6465.6 11024.9 11434.7 12039.3 12332.7 12363.9 12327.9 12414.4 12652.4 12781.0 12817.8 7034.4 8356.6 8777.6 9118.1 9247.4 9881.7 10249.3 10288.9 10352.6 10367.4 8860.1 8906.7 8966.8 9046.4 9168.7 7445.3 7840.5 8488.2 8674.7 8812.0 6565.9 7733.7 7865.5 7887.6 7904.2 5797.7 5867.2 5951.7 5997.8 6036.3 5931.6 6025.6 6112.6 6118.4 6210.1 09:53:05 INFO - PID 1644 | 09:53:05 INFO - PID 1644 | [#4] prototype.html Cycles:180 Average:5452.03 Median:5762.04 stddev:1698.85 (29.5%) stddev-sans-first:1660.15 09:53:05 INFO - PID 1644 | Values: 364.5 402.3 403.2 404.1 416.3 5242.5 5799.7 6483.7 6547.3 6552.3 5050.6 5634.3 6200.4 6239.1 6381.7 4823.5 4943.2 4971.8 4977.2 4984.8 4858.1 4975.6 4994.8 5020.2 5023.0 3804.7 4842.3 4869.4 4899.0 4910.0 4258.4 4723.6 4917.1 4939.0 4942.9 3765.0 3805.5 4330.9 4336.0 4375.4 5251.3 5727.4 6019.2 6202.0 6252.9 4974.4 5122.2 5585.3 5670.3 5699.2 6196.5 6353.3 6714.3 6877.6 6908.2 3952.7 4497.5 4499.5 4500.2 4510.6 6593.3 6671.3 6879.0 7246.0 7257.2 4447.7 4509.0 4572.6 4582.7 4595.5 3897.9 4660.1 4663.5 4670.6 4678.3 5157.6 5362.3 5444.5 5504.9 5510.1 5989.3 6842.2 6867.0 6868.6 6878.7 1277.0 1474.6 1570.4 1587.2 1594.0 1017.7 1030.7 1040.2 1043.1 1048.0 6450.2 6476.3 6488.0 6488.5 6518.7 5924.3 6852.9 7067.9 7089.7 7144.7 5642.5 5707.8 6430.4 6441.4 6459.3 3672.4 3701.1 3702.8 3714.6 3720.5 5263.2 5713.6 6386.4 7242.6 7308.8 7232.3 7292.7 7303.9 7308.8 7317.7 7041.2 7222.5 7287.4 7336.5 7377.0 6163.8 6751.8 7113.3 7132.5 7154.5 5523.7 5648.9 5756.3 5767.8 6389.7 5944.3 7009.5 7121.1 7273.2 7280.6 4541.5 4547.6 4555.0 4558.5 4563.2 5516.8 6899.9 7029.7 7080.5 7111.8 5606.0 6597.7 6748.3 6838.4 6879.5 6437.0 6739.1 6832.5 6842.1 6894.0 5494.8 6155.4 6420.3 6694.8 6722.7 5908.1 6366.9 6569.4 6865.5 6915.1 5516.0 6587.5 6660.5 6669.2 6679.5 09:53:05 INFO - PID 1644 | 09:53:05 INFO - PID 1644 | [#5] yui.html Cycles:180 Average:19165.40 Median:37874.53 stddev:26650.64 (70.4%) stddev-sans-first:26689.09 09:53:05 INFO - PID 1644 | Values: 635.7 642.6 711.8 715.5 741.4 15113.7 15281.8 15778.4 15789.1 16006.0 14354.6 14800.4 15103.4 15121.5 15273.7 878.1 894.9 900.4 901.1 901.3 892.6 893.8 901.7 929.9 939.6 979.1 981.8 982.5 985.2 987.5 971.6 978.3 978.7 979.3 986.8 722.8 724.1 724.6 729.6 735.0 16307.1 16444.8 16739.2 17106.3 17338.7 14575.6 14738.6 14768.5 14873.6 14886.5 10890.2 11019.3 11059.4 11208.7 11400.4 3049.2 3058.5 3078.9 3148.2 3181.6 108833.0 110100.3 111333.0 112768.0 113460.0 11183.4 11247.2 11384.9 11609.8 11910.1 24775.8 24960.1 25053.0 25069.0 25100.9 7440.6 7552.8 7571.3 7676.2 7866.8 4018.4 4057.5 4081.4 4092.4 4096.3 3357.3 3962.8 4040.9 4041.9 4049.8 792.0 796.9 803.6 807.2 817.9 526.5 550.2 566.9 569.1 587.5 9509.3 10049.6 10179.1 10242.3 10334.3 542.5 571.6 582.8 584.5 594.6 4668.2 4797.1 4858.0 4877.0 4907.5 78398.8 79013.0 79220.0 80413.0 82286.2 74872.0 75061.6 78419.9 82051.6 82899.6 55574.2 74057.9 74412.0 74692.6 75284.0 9207.4 10165.7 10427.7 10803.8 10886.1 35627.0 36484.7 36890.8 37618.1 38131.0 49205.8 62933.4 64622.4 66053.7 67486.7 13344.9 13437.8 13579.7 13649.6 14189.9 14435.3 14688.6 14690.9 14876.3 14889.3 11675.9 13736.7 13740.2 13799.8 13815.4 11701.8 13339.3 13593.9 13597.3 13707.0 13208.9 13527.1 13579.9 13636.3 13771.4 9275.3 10540.6 10627.9 10853.8 11239.6 9832.7 10800.0 10971.3 10977.2 11005.9 09:53:05 INFO - PID 1644 | -------- Summary: end -------- 09:53:05 INFO - PID 1644 | 09:53:05 INFO - TEST-INFO | 1644: exit 0 09:53:05 INFO - TEST-OK | dromaeo_css | took 1117678ms 09:53:05 INFO - TEST-START | kraken 09:53:05 INFO - operating with platform_type : mac_ 09:53:05 INFO - Initialising browser for kraken test... 09:53:05 INFO - TEST-INFO | started process 1655 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/tmpM2UGyn/profile http://localhost:49244/getInfo.html) 09:53:08 INFO - PID 1655 | 2017-03-22 09:53:08.678 plugin-container[1656:10110] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8d4b, name = 'com.apple.tsm.portname' 09:53:08 INFO - PID 1655 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:53:08 INFO - PID 1655 | 2017-03-22 09:53:08.681 plugin-container[1656:10110] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x910f, name = 'com.apple.CFPasteboardClient' 09:53:08 INFO - PID 1655 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:53:08 INFO - PID 1655 | 2017-03-22 09:53:08.681 plugin-container[1656:10110] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:53:08 INFO - PID 1655 | __metrics Screen width/height:1600/1200 09:53:08 INFO - PID 1655 | colorDepth:24 09:53:08 INFO - PID 1655 | Browser inner width/height: 1024/689 09:53:08 INFO - PID 1655 | __metrics 09:53:08 ERROR - PID 1655 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3350: TypeError: this.tabs is undefined 09:53:09 INFO - TEST-INFO | 1655: exit 0 09:53:09 INFO - Browser initialized. 09:53:09 INFO - Running cycle 1/1 for kraken test... 09:53:09 INFO - TEST-INFO | started process 1657 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/tmpM2UGyn/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:53:12 INFO - PID 1657 | 2017-03-22 09:53:12.173 plugin-container[1658:10242] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8a3f, name = 'com.apple.tsm.portname' 09:53:12 INFO - PID 1657 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:53:12 INFO - PID 1657 | 2017-03-22 09:53:12.175 plugin-container[1658:10242] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x900f, name = 'com.apple.CFPasteboardClient' 09:53:12 INFO - PID 1657 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:53:12 INFO - PID 1657 | 2017-03-22 09:53:12.176 plugin-container[1658:10242] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:53:21 INFO - PID 1657 | RSS: Main: 227323904 09:53:21 INFO - PID 1657 | 09:53:24 INFO - PID 1657 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49244/tests/kraken/driver.html?testName=audio-beat-detection) 09:53:25 INFO - PID 1657 | RSS: Main: 241938432 09:53:25 INFO - PID 1657 | 09:53:28 INFO - PID 1657 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49244/tests/kraken/driver.html?testName=audio-dft) 09:53:28 INFO - PID 1657 | RSS: Main: 250449920 09:53:28 INFO - PID 1657 | 09:53:32 INFO - PID 1657 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49244/tests/kraken/driver.html?testName=audio-fft) 09:53:32 INFO - PID 1657 | RSS: Main: 253009920 09:53:32 INFO - PID 1657 | 09:53:34 INFO - PID 1657 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49244/tests/kraken/driver.html?testName=audio-oscillator) 09:53:35 INFO - PID 1657 | RSS: Main: 255660032 09:53:35 INFO - PID 1657 | 09:53:37 INFO - PID 1657 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49244/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:53:37 INFO - PID 1657 | RSS: Main: 256102400 09:53:37 INFO - PID 1657 | 09:53:41 INFO - PID 1657 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49244/tests/kraken/driver.html?testName=imaging-darkroom) 09:53:41 INFO - PID 1657 | RSS: Main: 261423104 09:53:41 INFO - PID 1657 | 09:53:44 INFO - PID 1657 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49244/tests/kraken/driver.html?testName=imaging-desaturate) 09:53:45 INFO - PID 1657 | RSS: Main: 261955584 09:53:45 INFO - PID 1657 | 09:53:48 INFO - PID 1657 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49244/tests/kraken/driver.html?testName=json-parse-financial) 09:53:49 INFO - PID 1657 | RSS: Main: 261935104 09:53:49 INFO - PID 1657 | 09:53:51 INFO - PID 1657 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49244/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:53:51 INFO - PID 1657 | RSS: Main: 261890048 09:53:51 INFO - PID 1657 | 09:53:53 INFO - PID 1657 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:53:54 INFO - PID 1657 | RSS: Main: 261705728 09:53:54 INFO - PID 1657 | 09:53:56 INFO - PID 1657 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:53:57 INFO - PID 1657 | RSS: Main: 261804032 09:53:57 INFO - PID 1657 | 09:54:00 INFO - PID 1657 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:54:01 INFO - PID 1657 | RSS: Main: 262356992 09:54:01 INFO - PID 1657 | 09:54:04 INFO - PID 1657 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:54:05 INFO - PID 1657 | RSS: Main: 259342336 09:54:05 INFO - PID 1657 | 09:54:06 INFO - PID 1657 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49244/tests/kraken/driver.html?testName=ai-astar) 09:54:07 INFO - PID 1657 | RSS: Main: 262385664 09:54:07 INFO - PID 1657 | 09:54:07 INFO - PID 1657 | __start_tp_report 09:54:07 INFO - PID 1657 | _x_x_mozilla_page_load 09:54:07 INFO - PID 1657 | _x_x_mozilla_page_load_details 09:54:07 INFO - PID 1657 | |i|pagename|runs| 09:54:07 INFO - PID 1657 | |0;ai-astar;104;120;114;114;115;115;114;115;117;116 09:54:07 INFO - PID 1657 | |1;audio-beat-detection;147;118;129;154;95;105;145;150;169;112 09:54:07 INFO - PID 1657 | |2;audio-dft;194;197;205;215;206;223;197;202;197;196 09:54:07 INFO - PID 1657 | |3;audio-fft;68;83;52;111;89;52;84;83;96;89 09:54:07 INFO - PID 1657 | |4;audio-oscillator;90;71;86;72;72;70;85;70;72;84 09:54:07 INFO - PID 1657 | |5;imaging-gaussian-blur;122;88;88;88;89;89;127;136;130;90 09:54:07 INFO - PID 1657 | |6;imaging-darkroom;97;147;155;122;97;97;122;108;97;97 09:54:07 INFO - PID 1657 | |7;imaging-desaturate;91;133;76;137;146;117;90;91;91;92 09:54:07 INFO - PID 1657 | |8;json-parse-financial;67;66;68;67;71;100;54;59;69;100 09:54:07 INFO - PID 1657 | |9;json-stringify-tinderbox;59;63;62;72;64;74;81;59;58;59 09:54:07 INFO - PID 1657 | |10;stanford-crypto-aes;88;85;86;103;84;83;84;98;96;108 09:54:07 INFO - PID 1657 | |11;stanford-crypto-ccm;138;132;145;148;155;149;146;144;200;161 09:54:07 INFO - PID 1657 | |12;stanford-crypto-pbkdf2;211;233;231;187;233;245;226;238;190;193 09:54:07 INFO - PID 1657 | |13;stanford-crypto-sha256-iterative;70;67;62;70;70;71;69;74;97;94 09:54:07 INFO - PID 1657 | __end_tp_report 09:54:07 INFO - PID 1657 | __start_cc_report 09:54:07 INFO - PID 1657 | _x_x_mozilla_cycle_collect,999 09:54:07 INFO - PID 1657 | __end_cc_report 09:54:07 INFO - PID 1657 | __startTimestamp1490201647170__endTimestamp 09:54:07 INFO - PID 1657 | 09:54:07 INFO - PID 1657 | ------- Summary: start ------- 09:54:07 INFO - PID 1657 | Number of tests: 14 09:54:07 INFO - PID 1657 | 09:54:07 INFO - PID 1657 | [#0] ai-astar Cycles:10 Average:114.40 Median:115.00 stddev:4.09 (3.6%) stddev-sans-first:1.94 09:54:07 INFO - PID 1657 | Values: 104.0 120.0 114.0 114.0 115.0 115.0 114.0 115.0 117.0 116.0 09:54:07 INFO - PID 1657 | 09:54:07 INFO - PID 1657 | [#1] audio-beat-detection Cycles:10 Average:132.40 Median:148.50 stddev:24.20 (16.3%) stddev-sans-first:25.09 09:54:07 INFO - PID 1657 | Values: 147.0 118.0 129.0 154.0 95.0 105.0 145.0 150.0 169.0 112.0 09:54:07 INFO - PID 1657 | 09:54:07 INFO - PID 1657 | [#2] audio-dft Cycles:10 Average:203.20 Median:203.50 stddev:9.40 (4.6%) stddev-sans-first:9.36 09:54:07 INFO - PID 1657 | Values: 194.0 197.0 205.0 215.0 206.0 223.0 197.0 202.0 197.0 196.0 09:54:07 INFO - PID 1657 | 09:54:07 INFO - PID 1657 | [#3] audio-fft Cycles:10 Average:80.70 Median:83.50 stddev:18.62 (22.3%) stddev-sans-first:19.17 09:54:07 INFO - PID 1657 | Values: 68.0 83.0 52.0 111.0 89.0 52.0 84.0 83.0 96.0 89.0 09:54:07 INFO - PID 1657 | 09:54:07 INFO - PID 1657 | [#4] audio-oscillator Cycles:10 Average:77.20 Median:78.00 stddev:7.97 (10.2%) stddev-sans-first:6.98 09:54:07 INFO - PID 1657 | Values: 90.0 71.0 86.0 72.0 72.0 70.0 85.0 70.0 72.0 84.0 09:54:07 INFO - PID 1657 | 09:54:07 INFO - PID 1657 | [#5] imaging-gaussian-blur Cycles:10 Average:104.70 Median:88.00 stddev:20.98 (23.8%) stddev-sans-first:21.30 09:54:07 INFO - PID 1657 | Values: 122.0 88.0 88.0 88.0 89.0 89.0 127.0 136.0 130.0 90.0 09:54:07 INFO - PID 1657 | 09:54:07 INFO - PID 1657 | [#6] imaging-darkroom Cycles:10 Average:113.90 Median:97.00 stddev:22.06 (22.7%) stddev-sans-first:22.53 09:54:07 INFO - PID 1657 | Values: 97.0 147.0 155.0 122.0 97.0 97.0 122.0 108.0 97.0 97.0 09:54:07 INFO - PID 1657 | 09:54:07 INFO - PID 1657 | [#7] imaging-desaturate Cycles:10 Average:106.40 Median:90.50 stddev:24.58 (27.2%) stddev-sans-first:25.43 09:54:07 INFO - PID 1657 | Values: 91.0 133.0 76.0 137.0 146.0 117.0 90.0 91.0 91.0 92.0 09:54:07 INFO - PID 1657 | 09:54:07 INFO - PID 1657 | [#8] json-parse-financial Cycles:10 Average:72.10 Median:67.00 stddev:15.54 (23.2%) stddev-sans-first:16.37 09:54:07 INFO - PID 1657 | Values: 67.0 66.0 68.0 67.0 71.0 100.0 54.0 59.0 69.0 100.0 09:54:07 INFO - PID 1657 | 09:54:07 INFO - PID 1657 | [#9] json-stringify-tinderbox Cycles:10 Average:65.10 Median:63.50 stddev:7.87 (12.4%) stddev-sans-first:8.03 09:54:07 INFO - PID 1657 | Values: 59.0 63.0 62.0 72.0 64.0 74.0 81.0 59.0 58.0 59.0 09:54:07 INFO - PID 1657 | 09:54:07 INFO - PID 1657 | [#10] stanford-crypto-aes Cycles:10 Average:91.50 Median:85.50 stddev:9.05 (10.6%) stddev-sans-first:9.51 09:54:07 INFO - PID 1657 | Values: 88.0 85.0 86.0 103.0 84.0 83.0 84.0 98.0 96.0 108.0 09:54:07 INFO - PID 1657 | 09:54:07 INFO - PID 1657 | [#11] stanford-crypto-ccm Cycles:10 Average:151.80 Median:148.50 stddev:18.75 (12.6%) stddev-sans-first:19.21 09:54:07 INFO - PID 1657 | Values: 138.0 132.0 145.0 148.0 155.0 149.0 146.0 144.0 200.0 161.0 09:54:07 INFO - PID 1657 | 09:54:07 INFO - PID 1657 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:218.70 Median:232.00 stddev:21.67 (9.3%) stddev-sans-first:22.80 09:54:07 INFO - PID 1657 | Values: 211.0 233.0 231.0 187.0 233.0 245.0 226.0 238.0 190.0 193.0 09:54:07 INFO - PID 1657 | 09:54:07 INFO - PID 1657 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:74.40 Median:70.50 stddev:11.56 (16.4%) stddev-sans-first:12.15 09:54:07 INFO - PID 1657 | Values: 70.0 67.0 62.0 70.0 70.0 71.0 69.0 74.0 97.0 94.0 09:54:07 INFO - PID 1657 | -------- Summary: end -------- 09:54:07 INFO - PID 1657 | 09:54:08 INFO - TEST-INFO | 1657: exit 0 09:54:08 INFO - TEST-OK | kraken | took 62222ms 09:54:08 INFO - SUITE-END | took 1179s 09:54:08 INFO - Completed test suite (00:19:40) 09:54:08 INFO - javascript benchmark 09:54:08 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7672.089227294757, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3352.9329413411733, 3573.6962358199553, 3595.4427063805088, 3627.365211088059, 3630.473581330707, 6043.335233124359, 6137.416945390192, 6216.254049514059, 6237.0956211349385, 6250.124982502453, 6136.938630613704, 6151.692415379235, 6202.0, 6218.84452888678, 6281.623102613847, 4855.5387238212315, 5598.888022239553, 5616.241807356016, 5638.351589567212, 5824.825255242349, 5578.776848926038, 5677.261955945722, 5762.769489220426, 5786.623869448473, 5831.591788574801, 5515.338159420895, 5585.357683866367, 5589.496945274945, 5668.858278543028, 5697.572682048863, 5546.029444847136, 5550.111982082847, 5586.776528938878, 5677.630953987978, 5743.59794814359, 5552.583556233299, 5600.18797274397, 5618.719064046782, 5651.943480565183, 5662.631928924607, 5068.062408454448, 5654.208410822488, 6216.471599914013, 6231.2522497300615, 6243.0, 6398.104265402847, 6465.224173099211, 6560.081588577603, 6653.367930046637, 6655.301193374675, 6474.6762661866715, 6556.082148499214, 6578.243501997284, 6593.0, 6605.57063790852, 6211.9689401553605, 6272.65500397478, 6278.30938596754, 6283.8114856554375, 6312.558120931537, 6591.242007169141, 6654.900176497356, 6751.864962700718, 6819.795406137824, 6852.349026842442, 6356.14192084072, 6379.904301435482, 6392.360763923571, 6510.153680021662, 6585.80242592723, 6658.667066646745, 6729.596224226562, 6750.0, 6757.189137303457, 6795.762148324884, 6584.374484423972, 6585.1439312889015, 6602.2737498875085, 6669.266380698119, 6696.330366963362, 6417.7753778617525, 6517.902231466532, 6522.608643481311, 6645.634490103091, 6684.064231007663, 4269.380939763717, 5331.386890507563, 5361.195820626859, 5365.168398898177, 5381.0583147948955, 4714.740689262124, 4715.95284047162, 4719.480857105716, 4729.527047295312, 4778.402699662543, 6306.716197771111, 6347.619142851444, 6348.460380867586, 6349.333320001426, 6354.459870911024, 6473.546851720334, 6550.410463058348, 6639.336066393419, 6640.302768209365, 6704.765333213411, 6351.237851457855, 6361.236651601837, 6367.299597044322, 6371.23545174582, 6373.840653983688, 5884.705764711747, 5925.459380561085, 5931.851703707355, 5943.435373639497, 5944.821655350346, 6091.817245482643, 6343.333949935375, 6550.508711846535, 6774.983752437174, 6863.553868998599, 5688.744006519633, 6701.597904125768, 6780.999999999803, 6792.252852186354, 6801.863962720817, 6876.346747059021, 6907.205671347659, 6913.0, 6913.41235994926, 6922.27316131819, 6321.49428045746, 6383.36166383358, 6540.280569137296, 6604.273529911609, 6642.76750313756, 6531.640759758259, 6670.966645166744, 6747.122874026345, 6761.22245941723, 6771.322867713189, 6299.6535190564955, 6545.247296560957, 6553.180852393451, 6553.279139294769, 6558.409743123142, 6250.687465626609, 6385.074164246065, 6393.6163830170335, 6458.160439142881, 6527.67361631907, 6308.211473565804, 6436.806895793134, 6446.258680251643, 6453.290138084901, 6484.059811327425, 6305.684715764101, 6339.96830015847, 6382.138411314413, 6393.840153996001, 6433.324500927335, 6371.426571608392, 6376.139221205078, 6383.96808015957, 6400.423961843457, 6406.647634379968], "value": 6099.919405410246, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [723.7647764476546, 799.6001999000499, 822.8189798244387, 1276.9680757981055, 1282.6985658370288, 37169.25661486772, 37695.0, 37771.0, 37843.86468405949, 37900.43149352755, 37200.44199337012, 37587.73771671968, 37623.0, 37648.0, 37674.24651506968, 7873.842523149548, 8000.159983201767, 8374.28818550424, 8575.185357391038, 8585.527795971218, 7339.376153026999, 7686.308232259095, 8406.83186336273, 8407.117252688471, 8518.361122915776, 7878.6848526059175, 7962.925005124321, 8541.103184165666, 8546.102659220785, 8547.529885856307, 8478.406511544194, 8511.95744021276, 8529.061803201643, 8536.743897683049, 8538.573071346438, 7543.660535275926, 8435.0, 8510.106438823896, 8514.106018868053, 8552.0, 38251.85244442671, 38452.61547384518, 38521.0, 38582.22835543301, 38678.806605967075, 2980.955285670717, 3018.667946525902, 3030.3939212157657, 3041.8022828516077, 3056.144279601715, 28389.858050709616, 28518.572221416696, 28719.0, 28863.567046494318, 29624.555631665542, 2767.6955534891144, 2799.328161241308, 2801.90193343232, 2871.612332335129, 2883.9134825955257, 234611.8269408642, 237674.0, 305002.47498762363, 325973.0, 326446.0, 49569.50430495721, 49721.502784971686, 49853.50146498561, 49870.75064624654, 49876.50123498718, 38583.8424847255, 51312.48687513077, 51775.22337164945, 51905.7404712974, 51958.0, 14507.274636268145, 14565.854341456661, 14568.271586420637, 14577.0, 14610.269486525844, 11919.880801191877, 11966.0, 11970.281783093042, 11991.0, 12050.75898482043, 12019.038476921825, 12257.080718946114, 13506.797398039038, 13723.313834308245, 13993.440262389595, 5116.846494605168, 5943.673097979653, 5995.220621319288, 6037.396260374016, 6048.939510604926, 973.464594473037, 974.133021610767, 979.5787811241092, 980.6910823090703, 1015.6242190389603, 24401.38996525101, 24674.013039478577, 24754.752452475244, 24809.883555240056, 24832.5033499329, 988.4267125067444, 1078.4823284823328, 1103.564092183583, 1116.525476672411, 1119.1158984402393, 11863.0, 12462.189957652723, 12507.249565026128, 12516.123871329102, 12521.749565008831, 452466.47533524904, 455460.0, 456777.0, 457961.0, 459121.0, 56429.0, 56637.0, 56685.7165714177, 56717.0, 57944.55138621484, 1407.7184563087424, 1414.1373762005169, 1486.5466032860081, 1516.8103987001625, 1529.1971714849792, 20231.797682022992, 23937.803109844786, 23999.0, 24198.395040124138, 24298.878505607358, 38832.417513737884, 38834.834954951395, 38906.6382676605, 38948.80525597354, 39012.2197556053, 426326.0, 427804.0, 428845.0, 429623.0, 430760.0, 31940.5208921871, 32813.83593082019, 32905.84829530955, 32914.50628240626, 33977.66022339745, 43566.0, 43868.56131438773, 43984.780076099414, 44284.33573496336, 44480.55519444764, 26331.341716457242, 29938.850305748332, 31503.739850405727, 35241.0, 36889.63110368862, 35184.296314073064, 35249.82375088108, 36236.81881590681, 36307.818460907525, 36641.45037824381, 35219.47170792492, 36330.0, 36423.453648194765, 36430.8178459106, 36663.08342291464, 30516.542251866686, 32019.839900800347, 32044.18787824435, 32211.677883221804, 32365.0, 24160.0, 24188.0, 24283.635745463478, 24611.0, 24756.628650570616], "value": 17827.11451915849, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [416.4003834478353, 422.2420754745232, 422.37910271900313, 437.1911962868693, 447.57480393626065, 16759.413420530283, 18690.813091869077, 20292.289769858056, 21063.0, 21200.36398908033, 21591.6761248581, 21624.0, 21692.023858926346, 21834.034936855718, 22201.2229571965, 5975.133605627192, 6179.486025923633, 6229.3459186785185, 6231.065340198984, 6233.532485063615, 5949.107633854935, 6090.878182436349, 6126.326104128542, 6153.753849846001, 6156.230471191101, 6068.878622427549, 6120.816375508742, 6130.356312587181, 6138.14066030756, 6149.969250153743, 5889.057750759879, 5971.910421343683, 6134.785282515135, 6136.417040381157, 6139.26328840542, 5329.733513324319, 5408.432114627986, 5409.945900541023, 5420.593455490854, 5451.918221226685, 26162.08432704865, 26539.0, 26656.466870662673, 26932.306769323106, 26984.73015269842, 1864.692325766247, 1958.5495336072784, 1962.3916585858287, 1966.1545535419764, 2020.8484363672637, 15861.762073568905, 15916.0, 15963.44127955516, 16134.435294764628, 16692.165391730487, 1709.9658006839918, 1723.8716363328363, 1748.6502699460032, 1761.674090293287, 1765.6645237404853, 145223.0, 155834.66248006176, 156348.218258908, 156678.0, 157079.0, 10361.689149325532, 12289.938550307192, 12334.938325308316, 12501.93749031249, 12731.809022864665, 15993.28030238642, 16193.109378984269, 16229.188540572924, 16236.0, 16277.511674649779, 7331.706731730777, 8215.383846211558, 8424.915750842536, 8437.620307086196, 8520.403571749917, 6543.574667646588, 7789.922100778919, 7803.921960780433, 7923.801904952422, 7943.602819858984, 7785.727499537489, 7878.684852605831, 7929.581477649373, 7957.761267161879, 7976.441649084624, 2151.5239130218156, 2162.491814423641, 2166.180408456904, 2175.695402643631, 2371.5019845832226, 831.6756464978663, 914.4147745442922, 917.3074328881652, 934.2292608597934, 956.4930586788873, 15510.689786204213, 18899.622007560047, 19908.60182796336, 20526.692099618518, 20563.383098507067, 946.2902822882838, 953.6662168241198, 990.6780296403698, 992.836182029957, 994.5126887825058, 10154.136898363575, 10572.682819515427, 10597.682069537927, 10604.787904241872, 10662.573497060032, 453741.0, 556595.0, 578016.0, 747415.0, 750151.0, 136669.31665341608, 141394.2930285342, 148993.7650935237, 153122.0, 154500.90998179975, 1173.8943495085484, 1186.9525218991316, 1233.4326209943347, 1234.962951111468, 1235.8516977962522, 18814.905925470284, 19951.501212469804, 21097.367078987656, 21109.788902110784, 21205.575888481835, 48711.512884872114, 52843.47156528539, 54552.454475456325, 55187.44812551823, 59731.402685972585, 82608.0, 82612.58693706493, 83177.0, 83551.58224209084, 84076.0, 6083.361247068994, 8324.209200125979, 8457.619407126816, 8588.785280368042, 8733.257673097603, 10686.305390149615, 12384.81422778641, 13408.865911341152, 14000.719985600435, 14035.929820350833, 11435.142364322543, 11460.312381256817, 11826.0, 11833.822492662444, 11938.22401543897, 11382.772344553228, 11581.247218930743, 11598.826017609574, 11599.88400115988, 11911.642650720492, 11490.08079353633, 11630.883691163319, 11905.166638335233, 11957.505311836021, 12002.459889304828, 8351.29014033814, 8504.659813607386, 8541.359398045295, 8658.696945606873, 8795.296376290009, 11504.482298296429, 11636.709082273012, 11690.590829320932, 11773.76452470963, 11886.227395219283], "value": 10193.856368370933, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1226.2826246645711, 1451.4919778077667, 1461.6053665510312, 1469.7795330700387, 1476.8670819626234, 10450.216233782468, 10609.8408523872, 10756.623518176866, 10862.945685271572, 10872.238943273975, 8934.687285944994, 10531.26281160319, 10742.033217010467, 10749.785004299909, 10783.514741836616, 2418.407490164912, 2436.6345048242815, 2444.8533088014774, 2453.472503411766, 2455.4107014316614, 1994.41164856367, 2448.4980578981267, 2448.9142879999204, 2456.5946618807966, 2457.852528848266, 2404.699412573428, 2428.077330614364, 2431.0794472366897, 2438.317271164077, 2458.4960083182996, 2386.1886958434216, 2410.144398738449, 2412.1798588479824, 2421.8617249892523, 2424.7211570669424, 2348.6477028445697, 2379.940501487459, 2430.2466235467064, 2446.204983380391, 2448.951020979588, 10920.12638988879, 10990.340579565172, 11057.0, 11057.225994180411, 11074.335539867634, 5466.617336786427, 6139.846503837395, 6341.2707538633185, 6417.8395540111405, 6422.4862011039, 3994.0, 4019.135885784541, 4020.2763502569524, 4040.717149799515, 4080.9591904080876, 1138.7758159978057, 1155.763068570941, 1175.6353544530602, 1205.511767734069, 1222.462116668663, 13568.185908845402, 13966.790498142434, 15006.24968751569, 15113.3199006045, 15146.848531514654, 5105.566026887682, 5235.764390602432, 5354.41101478837, 5377.892442151135, 5391.056565101092, 11340.149488788375, 11403.486843092081, 11453.198276120758, 11455.0, 11470.48382822775, 5234.816781412632, 6177.135201071854, 6262.373762623702, 6280.277768056732, 6288.434040936338, 5592.748326325325, 5802.013657678205, 5802.419758024248, 5831.096180092092, 5883.294004719375, 4131.277026520347, 4200.6219440250525, 4216.494020717533, 4261.957380426218, 4269.146170765859, 1262.3940508555945, 1287.9147857765063, 1298.7597294500547, 1299.3698056442618, 1304.6477451088153, 663.0, 755.4032314471616, 760.3271105071941, 768.738628866177, 796.9521828690297, 5904.911426328608, 6056.153607804325, 6436.806895793134, 6568.113304703899, 6626.801195964129, 495.7223954585444, 535.6759160707794, 578.8726480174355, 583.7577405376805, 600.9063504421911, 3248.090534650302, 3607.350676878187, 3671.265746850587, 3774.4336100518526, 3784.8864534064023, 10837.295575787706, 12808.551700690432, 13522.459101636021, 13918.58244252674, 13918.860811391756, 13169.53906613264, 13418.463261469627, 13645.38595763193, 13771.449142034407, 13909.0959087661, 10108.090271875566, 10122.645707400206, 10150.492475376202, 10219.642312519027, 10239.385636861813, 5473.835784926299, 6234.844128896814, 6445.70994305248, 6455.419012288917, 6465.64438955862, 11024.889751102386, 11434.656960291204, 12039.277643341427, 12332.691682707646, 12363.93818030904, 12327.876721232673, 12414.441350139085, 12652.367381631066, 12781.0, 12817.807732884201, 7034.402075823612, 8356.582170891552, 8777.648894044167, 9118.088191180828, 9247.352685312233, 9881.654142104991, 10249.33379330341, 10288.897111028793, 10352.637657681946, 10367.377957322586, 8860.069692682175, 8906.688265910661, 8966.775830604287, 9046.366754327133, 9168.679096231599, 7445.329920307198, 7840.490368126054, 8488.236058754741, 8674.739757807276, 8811.986621538605, 6565.868682626226, 7733.651985660546, 7865.528068315919, 7887.64505597261, 7904.20957904228, 5797.739101740347, 5867.20792692981, 5951.7024148791525, 5997.790077347272, 6036.336003039574, 5931.61444506123, 6025.638461692312, 6112.572119951738, 6118.357572455006, 6210.130581718653], "value": 5041.172540311408, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [364.49335423760994, 402.32409551952725, 403.247942587075, 404.1391835390618, 416.29438102416395, 5242.501962313579, 5799.680127948823, 6483.675816209183, 6547.34526547345, 6552.279249282581, 5050.621203409741, 5634.267545219126, 6200.379962003797, 6239.095331176976, 6381.682134686662, 4823.4935331790275, 4943.233798761198, 4971.776270067838, 4977.228529577903, 4984.775685094161, 4858.076965376585, 4975.577075948549, 4994.829317519857, 5020.196768517038, 5022.995400919813, 3804.733668643196, 4842.273658951168, 4869.366982292297, 4899.044686286158, 4909.993451342484, 4258.403823464718, 4723.574878260956, 4917.139500587401, 4938.950610493885, 4942.851714448572, 3764.983454467306, 3805.534869075426, 4330.913381732347, 4336.002719374562, 4375.365571992075, 5251.264822924793, 5727.427257274281, 6019.157317975487, 6201.9689901550655, 6252.87494250117, 4974.42794078682, 5122.2351571594845, 5585.291133800104, 5670.347909990322, 5699.173619825102, 6196.473299769525, 6353.269374022, 6714.294999025081, 6877.6217308048035, 6908.205556360982, 3952.7035472339403, 4497.527759585229, 4499.527549607277, 4500.189965806154, 4510.639148868083, 6593.307702691196, 6671.266160722316, 6879.0, 7246.036465021477, 7257.169703815516, 4447.688661793708, 4509.0, 4572.634189264851, 4582.702124361906, 4595.517470665532, 3897.8635747748694, 4660.067986402665, 4663.533646635377, 4670.602998745145, 4678.251479763221, 5157.561607263425, 5362.329708786402, 5444.482774136451, 5504.91742623861, 5510.090835012258, 5989.251343582052, 6842.213145488233, 6867.0, 6868.62222577763, 6878.7248510059035, 1276.9553065642658, 1474.5944865161953, 1570.434643528329, 1587.1588058329105, 1594.0, 1017.6539976407909, 1030.7268573827942, 1040.1834559870465, 1043.0977204717865, 1047.9580816767395, 6450.193725784277, 6476.255230648441, 6488.026795980546, 6488.480921526267, 6518.674066296666, 5924.289085309791, 6852.862942741217, 7067.89398159028, 7089.716411343592, 7144.714211431588, 5642.520385767256, 5707.828765137053, 6430.3891130342545, 6441.355864413521, 6459.321771214049, 3672.4307732301536, 3701.130234394915, 3702.777833330009, 3714.647108524686, 3720.479132921393, 5263.210518422267, 5713.571482138773, 6386.361363863576, 7242.601656908815, 7308.843891052153, 7232.349088582053, 7292.739250832259, 7303.853922921512, 7308.780736577911, 7317.743878964211, 7041.169295983082, 7222.53053551528, 7287.417006639456, 7336.486445948945, 7376.9713328835, 6163.753449861955, 6751.763688271083, 7113.288671132846, 7132.543604142731, 7154.463415243774, 5523.6685798852195, 5648.887022259614, 5756.309242890712, 5767.769289228551, 6389.744410223539, 5944.3164036136395, 7009.509334346547, 7121.145462544528, 7273.163586187656, 7280.5995670236525, 4541.500434952219, 4547.613452856544, 4555.0, 4558.521355257651, 4563.155816174019, 5516.834494965157, 6899.896016637314, 7029.683664235019, 7080.5484875601405, 7111.751088711871, 5605.990921634145, 6597.736090556324, 6748.2576916538155, 6838.384545390939, 6879.484038697218, 6436.9678151610815, 6739.0902228198565, 6832.521723479309, 6842.144731908512, 6894.0, 5494.835154945358, 6155.446009859134, 6420.325865784025, 6694.765683201064, 6722.731090756511, 5908.084246941731, 6366.853966286113, 6569.37590928861, 6865.519413640997, 6915.135608048994, 5516.0, 6587.538872278895, 6660.533762636569, 6669.166354205724, 6679.499037572299], "value": 4917.422986258013, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [635.6630985577642, 642.6401215319421, 711.79357986184, 715.5241764226793, 741.3513175971025, 15113.697726045473, 15281.770773438408, 15778.368865245377, 15789.052656840599, 16006.0, 14354.641133971656, 14800.40798368064, 15103.395864165448, 15121.546353609408, 15273.694526109472, 878.0648609231164, 894.8791913091715, 900.4012331799346, 901.0898992018076, 901.2699713232303, 892.5894088719197, 893.8078313162669, 901.6528636474972, 929.9395539289925, 939.6476321379482, 979.0552117206894, 981.8183636027322, 982.4743762087285, 985.2069084387066, 987.4568987057125, 971.5870754929127, 978.3249557805092, 978.7455261631956, 979.2753362511726, 986.8273052215869, 722.7542635503954, 724.0840336973732, 724.6304384763756, 729.5659082845698, 734.9816254593625, 16307.103109328982, 16444.75332870008, 16739.16304184786, 17106.31574737009, 17338.739918901225, 14575.562733118024, 14738.55784326472, 14768.458091187453, 14873.628159296104, 14886.478973235884, 10890.237683362167, 11019.338839669565, 11059.447027648584, 11208.66374008781, 11400.429978501043, 3049.1767222849926, 3058.464768665497, 3078.922377167996, 3148.1972097115317, 3181.602299712536, 108833.0, 110100.34849477264, 111333.0, 112768.0, 113460.0, 11183.384913829817, 11247.240398575927, 11384.886151138548, 11609.825852612217, 11910.106741994385, 24775.752242477705, 24960.126395576066, 25052.997880084957, 25069.0, 25100.874495627406, 7440.627968601549, 7552.848943021108, 7571.318581327597, 7676.232376762279, 7866.7639970800965, 4018.3771515415156, 4057.4725285713257, 4081.387791831249, 4092.4066010428323, 4096.32410652239, 3357.261402491448, 3962.7622342659533, 4040.878773636796, 4041.8989525262105, 4049.817758200888, 791.9564423956738, 796.8924195233685, 803.5580430763057, 807.2089352434647, 817.9059408168018, 526.457748519026, 550.2159422822461, 566.8516323135271, 569.0759196761691, 587.538782056084, 9509.334346595671, 10049.59801607942, 10179.134773544332, 10242.283040187265, 10334.276600638033, 542.481929757081, 571.6255852416591, 582.8338923406809, 584.4885724990634, 594.5719082260765, 4668.183067963093, 4797.136515427187, 4857.975710121497, 4876.976713984602, 4907.484714105039, 78398.82401763978, 79013.0, 79220.0, 80413.0, 82286.17713822905, 74872.0, 75061.62469187619, 78419.86320547228, 82051.5897420521, 82899.5855020721, 55574.16638750422, 74057.88913166306, 74412.0, 74692.62653686697, 75284.0, 9207.401518901517, 10165.695029149138, 10427.739306517095, 10803.783924321628, 10886.129109671365, 35627.0, 36484.723034693656, 36890.8155459221, 37618.059548511505, 38131.0, 49205.75397122991, 62933.42666433376, 64622.353776461634, 66053.66973165104, 67486.66256668685, 13344.866551334362, 13437.798433023709, 13579.728405431639, 13649.590512284647, 14189.935754818223, 14435.267767867923, 14688.632784180481, 14690.926545367205, 14876.25618719038, 14889.255537223313, 11675.88324116782, 13736.725265494833, 13740.175589464265, 13799.793003105162, 13815.369897959328, 11701.76596468083, 13339.333033348488, 13593.932030339785, 13597.303707402321, 13707.0, 13208.857187098898, 13527.053106282467, 13579.86420135786, 13636.250006249751, 13771.449142034206, 9275.318904615639, 10540.551251312776, 10627.893721063, 10853.782924341627, 11239.550417983517, 9832.655857044969, 10800.00199962978, 10971.341719496537, 10977.231593788358, 11005.944970275097], "value": 7420.575293804164, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1606.5000000000002, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 120.0, 114.0, 114.0, 115.0, 115.0, 114.0, 115.0, 117.0, 116.0], "value": 114.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 118.0, 129.0, 154.0, 95.0, 105.0, 145.0, 150.0, 169.0, 112.0], "value": 132.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [194.0, 197.0, 205.0, 215.0, 206.0, 223.0, 197.0, 202.0, 197.0, 196.0], "value": 203.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 83.0, 52.0, 111.0, 89.0, 52.0, 84.0, 83.0, 96.0, 89.0], "value": 80.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 71.0, 86.0, 72.0, 72.0, 70.0, 85.0, 70.0, 72.0, 84.0], "value": 77.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [122.0, 88.0, 88.0, 88.0, 89.0, 89.0, 127.0, 136.0, 130.0, 90.0], "value": 104.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 147.0, 155.0, 122.0, 97.0, 97.0, 122.0, 108.0, 97.0, 97.0], "value": 113.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 133.0, 76.0, 137.0, 146.0, 117.0, 90.0, 91.0, 91.0, 92.0], "value": 106.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 66.0, 68.0, 67.0, 71.0, 100.0, 54.0, 59.0, 69.0, 100.0], "value": 72.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 63.0, 62.0, 72.0, 64.0, 74.0, 81.0, 59.0, 58.0, 59.0], "value": 65.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 85.0, 86.0, 103.0, 84.0, 83.0, 84.0, 98.0, 96.0, 108.0], "value": 91.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [138.0, 132.0, 145.0, 148.0, 155.0, 149.0, 146.0, 144.0, 200.0, 161.0], "value": 151.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [211.0, 233.0, 231.0, 187.0, 233.0, 245.0, 226.0, 238.0, 190.0, 193.0], "value": 218.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 67.0, 62.0, 70.0, 70.0, 71.0, 69.0, 74.0, 97.0, 94.0], "value": 74.4, "unit": "score"}]}]} 09:54:08 INFO - Return code: 0 09:54:08 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:54:08 ERROR - # TBPL SUCCESS # 09:54:08 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:54:08 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:54:08 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:54:08 INFO - 2017-03-22 09:54:08.465 screenresolution[1662:10630] starting screenresolution argv=screenresolution get 09:54:08 INFO - 2017-03-22 09:54:08.468 screenresolution[1662:10630] Display 0: 1600x1200x32@60 09:54:08 INFO - 2017-03-22 09:54:08.473 screenresolution[1663:10633] starting screenresolution argv=screenresolution list 09:54:08 INFO - Available Modes on Display 0 09:54:08 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:54:08 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:54:08 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:54:08 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:54:08 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:54:08 INFO - Intel Iris: 09:54:08 INFO - Chipset Model: Intel Iris 09:54:08 INFO - Type: GPU 09:54:08 INFO - Bus: Built-In 09:54:08 INFO - VRAM (Dynamic, Max): 1536 MB 09:54:08 INFO - Vendor: Intel (0x8086) 09:54:08 INFO - Device ID: 0x0a2e 09:54:08 INFO - Revision ID: 0x0009 09:54:08 INFO - Displays: 09:54:08 INFO - Display: 09:54:08 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:54:08 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:54:08 INFO - Display Serial Number: 1600x1200 60 09:54:08 INFO - Main Display: Yes 09:54:08 INFO - Mirror: Off 09:54:08 INFO - Online: Yes 09:54:08 INFO - Rotation: Supported 09:54:08 INFO - Return code: 0 09:54:08 INFO - Running post-action listener: _resource_record_post_action 09:54:08 INFO - [mozharness: 2017-03-22 16:54:08.710332Z] Finished run-tests step (success) 09:54:08 INFO - Running post-run listener: _resource_record_post_run 09:54:08 INFO - Total resource usage - Wall time: 1201s; CPU: 54.0%; Read bytes: 37509632; Write bytes: 516164608; Read time: 249; Write time: 1618 09:54:08 INFO - TinderboxPrint: CPU usage
54.1% 09:54:08 INFO - TinderboxPrint: I/O read bytes / time
37,509,632 / 249 09:54:08 INFO - TinderboxPrint: I/O write bytes / time
516,164,608 / 1,618 09:54:08 INFO - TinderboxPrint: CPU idle
2,207.1 (45.9%) 09:54:08 INFO - TinderboxPrint: CPU system
960.5 (20.0%) 09:54:08 INFO - TinderboxPrint: CPU user
1,638.2 (34.1%) 09:54:08 INFO - TinderboxPrint: Swap in / out
248,516,608 / 0 09:54:08 INFO - install - Wall time: 21s; CPU: 54.0%; Read bytes: 206326272; Write bytes: 204314624; Read time: 15802; Write time: 429 09:54:08 INFO - run-tests - Wall time: 1181s; CPU: 54.0%; Read bytes: 35576320; Write bytes: 300987392; Read time: 232; Write time: 1152 09:54:09 INFO - Running post-run listener: _upload_blobber_files 09:54:09 INFO - Blob upload gear active. 09:54:09 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:54:09 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 09:54:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 09:54:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 09:54:09 INFO - (blobuploader) - INFO - Open directory for files ... 09:54:09 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 09:54:09 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:54:09 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:54:10 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 09:54:10 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:54:10 INFO - (blobuploader) - INFO - Done attempting. 09:54:10 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 09:54:10 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:54:10 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:54:11 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 09:54:11 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:54:11 INFO - (blobuploader) - INFO - Done attempting. 09:54:11 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 09:54:11 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:54:11 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:54:12 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 09:54:12 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:54:12 INFO - (blobuploader) - INFO - Done attempting. 09:54:12 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:54:12 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:54:12 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:54:15 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:54:15 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:54:15 INFO - (blobuploader) - INFO - Done attempting. 09:54:15 INFO - (blobuploader) - INFO - Iteration through files over. 09:54:15 INFO - Return code: 0 09:54:15 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:54:15 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:54:15 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/35831cfad7b1679b01c7dc635d3125ed8b192ae319440dc642ae1ef62c5a80d2823c732ae65769532cb23bcc821812bbf03e18112c63afc57b1cf1a04a864d76", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a5a8422c8311825dd92a6b5d09cd41ea50387bc949444e2ca652304161fc90b33a0e3f9191ebb12b64f7fd347a7212de65f1a5ae793ade41020e75b9893825ea", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/352d3b82e80dae6bff8724955b02ab8dbee4b29c107ab840161238b1adbf6dc83998b85d4fb031fba635c8440b7935a9d74c30616a390566b92fa1adfb437653", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a456007ff43a57dc7da6b26906e2818f9c40ba6d38d6ef9620899eb84a9a33b6006156c55467e4e7bb41b065c26766527fa737a473db18a4af133e267c472923"} 09:54:15 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:54:15 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:54:15 INFO - Contents: 09:54:15 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/35831cfad7b1679b01c7dc635d3125ed8b192ae319440dc642ae1ef62c5a80d2823c732ae65769532cb23bcc821812bbf03e18112c63afc57b1cf1a04a864d76", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a5a8422c8311825dd92a6b5d09cd41ea50387bc949444e2ca652304161fc90b33a0e3f9191ebb12b64f7fd347a7212de65f1a5ae793ade41020e75b9893825ea", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/352d3b82e80dae6bff8724955b02ab8dbee4b29c107ab840161238b1adbf6dc83998b85d4fb031fba635c8440b7935a9d74c30616a390566b92fa1adfb437653", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a456007ff43a57dc7da6b26906e2818f9c40ba6d38d6ef9620899eb84a9a33b6006156c55467e4e7bb41b065c26766527fa737a473db18a4af133e267c472923"} 09:54:15 INFO - Running post-run listener: copy_logs_to_upload_dir 09:54:15 INFO - Copying logs to upload dir... 09:54:15 INFO - mkdir: /builds/slave/test/build/upload/logs 09:54:15 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1284.590785 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 24 secs) (at 2017-03-22 09:54:15.360336) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-22 09:54:15.364271) ========= 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.qQhUA9mFfc/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.wFX9LpraDu/Listeners TMPDIR=/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/35831cfad7b1679b01c7dc635d3125ed8b192ae319440dc642ae1ef62c5a80d2823c732ae65769532cb23bcc821812bbf03e18112c63afc57b1cf1a04a864d76", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a5a8422c8311825dd92a6b5d09cd41ea50387bc949444e2ca652304161fc90b33a0e3f9191ebb12b64f7fd347a7212de65f1a5ae793ade41020e75b9893825ea", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/352d3b82e80dae6bff8724955b02ab8dbee4b29c107ab840161238b1adbf6dc83998b85d4fb031fba635c8440b7935a9d74c30616a390566b92fa1adfb437653", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a456007ff43a57dc7da6b26906e2818f9c40ba6d38d6ef9620899eb84a9a33b6006156c55467e4e7bb41b065c26766527fa737a473db18a4af133e267c472923"} build_url:https://queue.taskcluster.net/v1/task/L4ScK0lXS2yNhIhzFHjDGw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011107 build_url: 'https://queue.taskcluster.net/v1/task/L4ScK0lXS2yNhIhzFHjDGw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/35831cfad7b1679b01c7dc635d3125ed8b192ae319440dc642ae1ef62c5a80d2823c732ae65769532cb23bcc821812bbf03e18112c63afc57b1cf1a04a864d76", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a5a8422c8311825dd92a6b5d09cd41ea50387bc949444e2ca652304161fc90b33a0e3f9191ebb12b64f7fd347a7212de65f1a5ae793ade41020e75b9893825ea", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/352d3b82e80dae6bff8724955b02ab8dbee4b29c107ab840161238b1adbf6dc83998b85d4fb031fba635c8440b7935a9d74c30616a390566b92fa1adfb437653", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a456007ff43a57dc7da6b26906e2818f9c40ba6d38d6ef9620899eb84a9a33b6006156c55467e4e7bb41b065c26766527fa737a473db18a4af133e267c472923"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-22 09:54:15.398392) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-22 09:54:15.398767) ========= 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.qQhUA9mFfc/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.wFX9LpraDu/Listeners TMPDIR=/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/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.005260 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-22 09:54:15.447737) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-22 09:54:15.448058) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-22 09:54:15.448376) ========= ========= Total master_lag: 0.18 =========