builder: autoland_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0335 starttime: 1473069633.17 results: success (0) buildid: 20160905014120 builduid: 6e3494377bbe452ca976c37cdc73324d revision: 8515bb6df598578d036cd14422f5ab4e9d65980e ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-05 03:00:33.168634) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-05 03:00:33.169142) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-05 03:00:33.169601) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-05 03:00:33.198917) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-05 03:00:33.199261) ========= 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.4unqib97vF/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.0vOC3nxODO/Listeners TMPDIR=/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/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 --2016-09-05 03:00:33-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 19.2M=0.001s 2016-09-05 03:00:33 (19.2 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.501707 ========= master_lag: 0.08 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-05 03:00:33.782124) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-05 03:00:33.782532) ========= 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.4unqib97vF/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.0vOC3nxODO/Listeners TMPDIR=/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/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.027084 ========= master_lag: 0.09 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-05 03:00:33.902991) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-05 03:00:33.903341) ========= bash -c 'python archiver_client.py mozharness --repo integration/autoland --rev 8515bb6df598578d036cd14422f5ab4e9d65980e --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/autoland --rev 8515bb6df598578d036cd14422f5ab4e9d65980e --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.4unqib97vF/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.0vOC3nxODO/Listeners TMPDIR=/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/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 2016-09-05 03:00:34,017 truncating revision to first 12 chars 2016-09-05 03:00:34,017 Setting DEBUG logging. 2016-09-05 03:00:34,017 attempt 1/10 2016-09-05 03:00:34,018 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/8515bb6df598?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-05 03:00:34,683 unpacking tar archive at: autoland-8515bb6df598/testing/mozharness/ program finished with exit code 0 elapsedTime=0.947612 ========= master_lag: 0.05 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-05 03:00:34.902359) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-05 03:00:34.902624) ========= script_repo_revision: 8515bb6df598578d036cd14422f5ab4e9d65980e ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-05 03:00:34.903013) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-05 03:00:34.903278) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-05 03:00:34.922687) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 10 secs) (at 2016-09-05 03:00:34.922949) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Autoland --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Autoland', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Autoland'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.4unqib97vF/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.0vOC3nxODO/Listeners TMPDIR=/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/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 03:00:35 INFO - MultiFileLogger online at 20160905 03:00:35 in /builds/slave/test 03:00:35 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Autoland --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland 03:00:35 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 03:00:35 INFO - {'append_to_log': False, 03:00:35 INFO - 'base_work_dir': '/builds/slave/test', 03:00:35 INFO - 'blob_upload_branch': 'Autoland', 03:00:35 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 03:00:35 INFO - 'branch': 'Autoland', 03:00:35 INFO - 'buildbot_json_path': 'buildprops.json', 03:00:35 INFO - 'config_files': ('talos/mac_config.py',), 03:00:35 INFO - 'default_actions': ('clobber', 03:00:35 INFO - 'read-buildbot-config', 03:00:35 INFO - 'download-and-extract', 03:00:35 INFO - 'populate-webroot', 03:00:35 INFO - 'create-virtualenv', 03:00:35 INFO - 'install', 03:00:35 INFO - 'run-tests'), 03:00:35 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 03:00:35 INFO - 'download_minidump_stackwalk': True, 03:00:35 INFO - 'download_symbols': 'ondemand', 03:00:35 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 03:00:35 INFO - 'tooltool.py': '/tools/tooltool.py', 03:00:35 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 03:00:35 INFO - '/tools/misc-python/virtualenv.py')}, 03:00:35 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 03:00:35 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 03:00:35 INFO - 'installer_path': 'installer.exe', 03:00:35 INFO - 'log_level': 'info', 03:00:35 INFO - 'log_name': 'talos', 03:00:35 INFO - 'log_to_console': True, 03:00:35 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 03:00:35 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 03:00:35 INFO - 'opt_config_files': (), 03:00:35 INFO - 'pip_index': False, 03:00:35 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 03:00:35 INFO - 'cmd': ('bash', 03:00:35 INFO - '-c', 03:00:35 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 03:00:35 INFO - 'enabled': True, 03:00:35 INFO - 'halt_on_failure': False, 03:00:35 INFO - 'name': 'check_screen_resolution'},), 03:00:35 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 03:00:35 INFO - 'cmd': ('bash', 03:00:35 INFO - '-c', 03:00:35 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 03:00:35 INFO - 'enabled': True, 03:00:35 INFO - 'halt_on_failure': False, 03:00:35 INFO - 'name': 'check_screen_resolution'},), 03:00:35 INFO - 'run_cmd_checks_enabled': True, 03:00:35 INFO - 'sps_profile': False, 03:00:35 INFO - 'sps_profile_interval': 0, 03:00:35 INFO - 'suite': 'dromaeojs-e10s', 03:00:35 INFO - 'system_bits': '32', 03:00:35 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 03:00:35 INFO - 'title': 't-yosemite-r7-0335', 03:00:35 INFO - 'tooltool_cache': '/builds/tooltool_cache', 03:00:35 INFO - 'use_talos_json': True, 03:00:35 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 03:00:35 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 03:00:35 INFO - 'work_dir': 'build'} 03:00:35 INFO - [mozharness: 2016-09-05 10:00:35.125506Z] Running clobber step. 03:00:35 INFO - Running pre-action listener: _resource_record_pre_action 03:00:35 INFO - Running main action method: clobber 03:00:35 INFO - rmtree: /builds/slave/test/build 03:00:35 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 03:00:37 INFO - Running post-action listener: _resource_record_post_action 03:00:37 INFO - [mozharness: 2016-09-05 10:00:37.216464Z] Finished clobber step (success) 03:00:37 INFO - [mozharness: 2016-09-05 10:00:37.216629Z] Running read-buildbot-config step. 03:00:37 INFO - Running pre-action listener: _resource_record_pre_action 03:00:37 INFO - Running main action method: read_buildbot_config 03:00:37 INFO - Using buildbot properties: 03:00:37 INFO - { 03:00:37 INFO - "project": "", 03:00:37 INFO - "product": "firefox", 03:00:37 INFO - "script_repo_revision": "production", 03:00:37 INFO - "builddir": "autoland_yosemite_r7_test-dromaeojs-e10s", 03:00:37 INFO - "repository": "", 03:00:37 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 autoland talos dromaeojs-e10s", 03:00:37 INFO - "buildid": "20160905014120", 03:00:37 INFO - "pgo_build": "False", 03:00:37 INFO - "basedir": "/builds/slave/test", 03:00:37 INFO - "buildnumber": 108, 03:00:37 INFO - "slavename": "t-yosemite-r7-0335", 03:00:37 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 03:00:37 INFO - "platform": "yosemite_r7", 03:00:37 INFO - "scheduler": "tests-autoland-macosx64-talos-7-3600", 03:00:37 INFO - "branch": "autoland", 03:00:37 INFO - "repo_path": "integration/autoland", 03:00:37 INFO - "revision": "8515bb6df598578d036cd14422f5ab4e9d65980e", 03:00:37 INFO - "stage_platform": "macosx64", 03:00:37 INFO - "builduid": "6e3494377bbe452ca976c37cdc73324d", 03:00:37 INFO - "slavebuilddir": "test" 03:00:37 INFO - } 03:00:37 INFO - Found installer url https://queue.taskcluster.net/v1/task/TpkaSgRSRPO5RFZeVuHFkw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg. 03:00:37 INFO - Running post-action listener: _resource_record_post_action 03:00:37 INFO - [mozharness: 2016-09-05 10:00:37.220064Z] Finished read-buildbot-config step (success) 03:00:37 INFO - [mozharness: 2016-09-05 10:00:37.220182Z] Running download-and-extract step. 03:00:37 INFO - Running pre-action listener: _resource_record_pre_action 03:00:37 INFO - Running main action method: download_and_extract 03:00:37 INFO - mkdir: /builds/slave/test/build/tests 03:00: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')]} 03:00:37 INFO - trying https://queue.taskcluster.net/v1/task/TpkaSgRSRPO5RFZeVuHFkw/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json 03:00:37 INFO - Downloading https://queue.taskcluster.net/v1/task/TpkaSgRSRPO5RFZeVuHFkw/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 03:00:37 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/TpkaSgRSRPO5RFZeVuHFkw/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json'}, attempt #1 03:00:38 INFO - Downloaded 1199 bytes. 03:00:38 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 03:00:38 INFO - Using the following test package requirements: 03:00:38 INFO - {u'common': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 03:00:38 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 03:00:38 INFO - u'firefox-51.0a1.en-US.mac.cppunittest.tests.zip'], 03:00:38 INFO - u'gtest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 03:00:38 INFO - u'firefox-51.0a1.en-US.mac.gtest.tests.zip'], 03:00:38 INFO - u'jittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 03:00:38 INFO - u'jsshell-mac.zip'], 03:00:38 INFO - u'mochitest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 03:00:38 INFO - u'firefox-51.0a1.en-US.mac.mochitest.tests.zip'], 03:00:38 INFO - u'mozbase': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 03:00:38 INFO - u'reftest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 03:00:38 INFO - u'firefox-51.0a1.en-US.mac.reftest.tests.zip'], 03:00:38 INFO - u'talos': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 03:00:38 INFO - u'firefox-51.0a1.en-US.mac.talos.tests.zip'], 03:00:38 INFO - u'web-platform': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 03:00:38 INFO - u'firefox-51.0a1.en-US.mac.web-platform.tests.zip'], 03:00:38 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 03:00:38 INFO - u'firefox-51.0a1.en-US.mac.xpcshell.tests.zip']} 03:00:38 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip'] for test suite category: common 03:00:39 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/TpkaSgRSRPO5RFZeVuHFkw/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 03:00:39 INFO - retry: Calling unzip with args: (), kwargs: {'file_object': >, 'verbose': False, 'extract_dirs': '*', 'extract_to': '/builds/slave/test/build/tests'}, attempt #1 03:00:45 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip', u'firefox-51.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 03:00:46 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/TpkaSgRSRPO5RFZeVuHFkw/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 03:00:46 INFO - retry: Calling unzip with args: (), kwargs: {'file_object': >, 'verbose': False, 'extract_dirs': '*', 'extract_to': '/builds/slave/test/build/tests'}, attempt #1 03:00:53 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/TpkaSgRSRPO5RFZeVuHFkw/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip 03:00:53 INFO - retry: Calling unzip with args: (), kwargs: {'file_object': >, 'verbose': False, 'extract_dirs': '*', 'extract_to': '/builds/slave/test/build/tests'}, attempt #1 03:00:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:00:54 INFO - trying https://queue.taskcluster.net/v1/task/TpkaSgRSRPO5RFZeVuHFkw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 03:00:54 INFO - Downloading https://queue.taskcluster.net/v1/task/TpkaSgRSRPO5RFZeVuHFkw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg 03:00:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/TpkaSgRSRPO5RFZeVuHFkw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg'}, attempt #1 03:00:57 INFO - Downloaded 111496107 bytes. 03:00:57 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/TpkaSgRSRPO5RFZeVuHFkw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 03:00:57 INFO - mkdir: /builds/slave/test/properties 03:00:57 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 03:00:57 INFO - Writing to file /builds/slave/test/properties/build_url 03:00:57 INFO - Contents: 03:00:57 INFO - build_url:https://queue.taskcluster.net/v1/task/TpkaSgRSRPO5RFZeVuHFkw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 03:00:58 INFO - Running post-action listener: _resource_record_post_action 03:00:58 INFO - Running post-action listener: set_extra_try_arguments 03:00:58 INFO - [mozharness: 2016-09-05 10:00:58.309657Z] Finished download-and-extract step (success) 03:00:58 INFO - [mozharness: 2016-09-05 10:00:58.309796Z] Running populate-webroot step. 03:00:58 INFO - Running pre-action listener: _resource_record_pre_action 03:00:58 INFO - Running main action method: populate_webroot 03:00:58 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 03:00:58 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 03:00:58 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 03:00:58 INFO - '--tppagecycles', 03:00:58 INFO - '7'], 03:00:58 INFO - 'tests': ['tsvgm']}}, 03:00:58 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 03:00:58 INFO - 'tests': ['tresize', 'tcanvasmark']}, 03:00:58 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 03:00:58 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 03:00:58 INFO - 'tests': ['dromaeo_css', 'kraken']}, 03:00:58 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 03:00:58 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:00:58 INFO - 'talos_options': ['--disable-e10s'], 03:00:58 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:00:58 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:00:58 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:00:58 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:00:58 INFO - 'talos_options': ['--disable-e10s'], 03:00:58 INFO - 'tests': ['damp', 'tps']}, 03:00:58 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:00:58 INFO - 'tests': ['damp', 'tps']}, 03:00:58 INFO - 'g3': {'talos_options': ['--disable-e10s'], 03:00:58 INFO - 'tests': ['dromaeo_dom']}, 03:00:58 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 03:00:58 INFO - 'g4': {'talos_options': ['--disable-e10s'], 03:00:58 INFO - 'tests': ['basic_compositor_video']}, 03:00:58 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 03:00:58 INFO - 'other': {'talos_options': ['--disable-e10s'], 03:00:58 INFO - 'tests': ['a11yr', 03:00:58 INFO - 'ts_paint', 03:00:58 INFO - 'tpaint', 03:00:58 INFO - 'sessionrestore', 03:00:58 INFO - 'sessionrestore_no_auto_restore', 03:00:58 INFO - 'tabpaint']}, 03:00:58 INFO - 'other-e10s': {'tests': ['a11yr', 03:00:58 INFO - 'ts_paint', 03:00:58 INFO - 'tpaint', 03:00:58 INFO - 'sessionrestore', 03:00:58 INFO - 'sessionrestore_no_auto_restore', 03:00:58 INFO - 'tabpaint']}, 03:00:58 INFO - 'other-e10s_l64': {'tests': ['a11yr', 03:00:58 INFO - 'ts_paint', 03:00:58 INFO - 'tpaint', 03:00:58 INFO - 'sessionrestore', 03:00:58 INFO - 'sessionrestore_no_auto_restore', 03:00:58 INFO - 'tabpaint']}, 03:00:58 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 03:00:58 INFO - 'ts_paint', 03:00:58 INFO - 'tpaint', 03:00:58 INFO - 'sessionrestore', 03:00:58 INFO - 'sessionrestore_no_auto_restore', 03:00:58 INFO - 'tabpaint']}, 03:00:58 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 03:00:58 INFO - 'tests': ['a11yr', 03:00:58 INFO - 'ts_paint', 03:00:58 INFO - 'tpaint', 03:00:58 INFO - 'sessionrestore', 03:00:58 INFO - 'sessionrestore_no_auto_restore', 03:00:58 INFO - 'tabpaint']}, 03:00:58 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 03:00:58 INFO - 'tests': ['a11yr', 03:00:58 INFO - 'ts_paint', 03:00:58 INFO - 'tpaint', 03:00:58 INFO - 'sessionrestore', 03:00:58 INFO - 'sessionrestore_no_auto_restore', 03:00:58 INFO - 'tabpaint']}, 03:00:58 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 03:00:58 INFO - 'tests': ['tsvgx', 03:00:58 INFO - 'tsvgr_opacity', 03:00:58 INFO - 'tart', 03:00:58 INFO - 'tscrollx', 03:00:58 INFO - 'cart']}, 03:00:58 INFO - 'svgr-e10s': {'tests': ['tsvgx', 03:00:58 INFO - 'tsvgr_opacity', 03:00:58 INFO - 'tart', 03:00:58 INFO - 'tscrollx', 03:00:58 INFO - 'cart']}, 03:00:58 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:00:58 INFO - 'talos_options': ['--disable-e10s'], 03:00:58 INFO - 'tests': ['tp5o']}, 03:00:58 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:00:58 INFO - 'tests': ['tp5o']}, 03:00:58 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:00:58 INFO - 'talos_options': ['--disable-e10s', 03:00:58 INFO - '--xperf_path', 03:00:58 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:00:58 INFO - 'tests': ['tp5n']}, 03:00:58 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:00:58 INFO - 'talos_options': ['--xperf_path', 03:00:58 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:00:58 INFO - 'tests': ['tp5n']}}, 03:00:58 INFO - 'talos.zip': {'path': '', 03:00:58 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 03:00:58 INFO - Running post-action listener: _resource_record_post_action 03:00:58 INFO - [mozharness: 2016-09-05 10:00:58.328617Z] Finished populate-webroot step (success) 03:00:58 INFO - [mozharness: 2016-09-05 10:00:58.328757Z] Running create-virtualenv step. 03:00:58 INFO - Running pre-action listener: _resource_record_pre_action 03:00:58 INFO - Running main action method: create_virtualenv 03:00:58 INFO - Creating virtualenv /builds/slave/test/build/venv 03:00:58 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 03:00:58 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 03:00:58 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 03:00:58 INFO - Using real prefix '/tools/python27' 03:00:58 INFO - New python executable in /builds/slave/test/build/venv/bin/python 03:00:59 INFO - Installing distribute.............................................................................................................................................................................................done. 03:01:02 INFO - Installing pip.................done. 03:01:02 INFO - Return code: 0 03:01:02 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 03:01:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:01:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:01:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:01:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:01:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:01:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:01:02 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10aadaa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ab6f618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9e9347d5a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aa3ed30>, '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 0x10a7a3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10aa36b20>, '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.4unqib97vF/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.0vOC3nxODO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/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 03:01:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 03:01:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 03:01:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4unqib97vF/Render', 03:01:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:01:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:01:02 INFO - 'HOME': '/Users/cltbld', 03:01:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:01:02 INFO - 'LOGNAME': 'cltbld', 03:01:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:01:02 INFO - 'MOZ_NO_REMOTE': '1', 03:01:02 INFO - 'NO_EM_RESTART': '1', 03:01:02 INFO - 'PAGER': '/bin/cat', 03:01:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:01:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:01:02 INFO - 'PWD': '/builds/slave/test', 03:01:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:01:02 INFO - 'SHELL': '/bin/bash', 03:01:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0vOC3nxODO/Listeners', 03:01:02 INFO - 'TMPDIR': '/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/', 03:01:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:01:02 INFO - 'USER': 'cltbld', 03:01:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:01:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:01:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:01:02 INFO - 'XPC_FLAGS': '0x0', 03:01:02 INFO - 'XPC_SERVICE_NAME': '0', 03:01:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:01:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:01:02 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 03:01:02 INFO - Cleaning up... 03:01:02 INFO - Return code: 0 03:01:02 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 03:01:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:01:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:01:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:01:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:01:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:01:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:01:02 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10aadaa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ab6f618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9e9347d5a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aa3ed30>, '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 0x10a7a3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10aa36b20>, '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.4unqib97vF/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.0vOC3nxODO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/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 03:01:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 03:01:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 03:01:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4unqib97vF/Render', 03:01:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:01:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:01:02 INFO - 'HOME': '/Users/cltbld', 03:01:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:01:02 INFO - 'LOGNAME': 'cltbld', 03:01:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:01:02 INFO - 'MOZ_NO_REMOTE': '1', 03:01:02 INFO - 'NO_EM_RESTART': '1', 03:01:02 INFO - 'PAGER': '/bin/cat', 03:01:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:01:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:01:02 INFO - 'PWD': '/builds/slave/test', 03:01:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:01:02 INFO - 'SHELL': '/bin/bash', 03:01:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0vOC3nxODO/Listeners', 03:01:02 INFO - 'TMPDIR': '/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/', 03:01:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:01:02 INFO - 'USER': 'cltbld', 03:01:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:01:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:01:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:01:02 INFO - 'XPC_FLAGS': '0x0', 03:01:02 INFO - 'XPC_SERVICE_NAME': '0', 03:01:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:01:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:01:02 INFO - Downloading/unpacking psutil>=3.1.1 03:01:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:01:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:01:02 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:01:02 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:01:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:01:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:01:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 03:01:06 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:01:06 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:01:06 INFO - Installing collected packages: psutil 03:01:06 INFO - Running setup.py install for psutil 03:01:06 INFO - building 'psutil._psutil_osx' extension 03:01:06 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 03:01:07 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 03:01:07 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 03:01:07 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 03:01:07 INFO - building 'psutil._psutil_posix' extension 03:01:07 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 03:01:07 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 03:01:07 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 03:01:07 INFO - ^ 03:01:07 INFO - 1 warning generated. 03:01:07 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 03:01:07 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:01:07 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:01:07 INFO - Successfully installed psutil 03:01:07 INFO - Cleaning up... 03:01:07 INFO - Return code: 0 03:01:07 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 03:01:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:01:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:01:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:01:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:01:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:01:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:01:07 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10aadaa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ab6f618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9e9347d5a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aa3ed30>, '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 0x10a7a3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10aa36b20>, '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.4unqib97vF/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.0vOC3nxODO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/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 03:01:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 03:01:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 03:01:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4unqib97vF/Render', 03:01:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:01:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:01:07 INFO - 'HOME': '/Users/cltbld', 03:01:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:01:07 INFO - 'LOGNAME': 'cltbld', 03:01:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:01:07 INFO - 'MOZ_NO_REMOTE': '1', 03:01:07 INFO - 'NO_EM_RESTART': '1', 03:01:07 INFO - 'PAGER': '/bin/cat', 03:01:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:01:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:01:07 INFO - 'PWD': '/builds/slave/test', 03:01:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:01:07 INFO - 'SHELL': '/bin/bash', 03:01:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0vOC3nxODO/Listeners', 03:01:07 INFO - 'TMPDIR': '/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/', 03:01:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:01:07 INFO - 'USER': 'cltbld', 03:01:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:01:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:01:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:01:07 INFO - 'XPC_FLAGS': '0x0', 03:01:07 INFO - 'XPC_SERVICE_NAME': '0', 03:01:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:01:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:01:07 INFO - Downloading/unpacking mozsystemmonitor==0.3 03:01:07 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 03:01:07 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 03:01:07 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 03:01:07 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 03:01:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:01:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:01:10 INFO - Downloading mozsystemmonitor-0.3.tar.gz 03:01:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 03:01:10 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 03:01:10 INFO - Installing collected packages: mozsystemmonitor 03:01:10 INFO - Running setup.py install for mozsystemmonitor 03:01:10 INFO - Successfully installed mozsystemmonitor 03:01:10 INFO - Cleaning up... 03:01:10 INFO - Return code: 0 03:01:10 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 03:01:10 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')]} 03:01:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:01:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:01:10 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')]} 03:01:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:01:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:01:10 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 0x10aadaa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ab6f618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9e9347d5a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aa3ed30>, '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 0x10a7a3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10aa36b20>, '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.4unqib97vF/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.0vOC3nxODO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/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 03:01:10 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 03:01:10 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 03:01:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4unqib97vF/Render', 03:01:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:01:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:01:10 INFO - 'HOME': '/Users/cltbld', 03:01:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:01:10 INFO - 'LOGNAME': 'cltbld', 03:01:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:01:10 INFO - 'MOZ_NO_REMOTE': '1', 03:01:10 INFO - 'NO_EM_RESTART': '1', 03:01:10 INFO - 'PAGER': '/bin/cat', 03:01:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:01:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:01:10 INFO - 'PWD': '/builds/slave/test', 03:01:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:01:10 INFO - 'SHELL': '/bin/bash', 03:01:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0vOC3nxODO/Listeners', 03:01:10 INFO - 'TMPDIR': '/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/', 03:01:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:01:10 INFO - 'USER': 'cltbld', 03:01:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:01:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:01:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:01:10 INFO - 'XPC_FLAGS': '0x0', 03:01:10 INFO - 'XPC_SERVICE_NAME': '0', 03:01:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:01:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:01:11 INFO - Downloading/unpacking jsonschema==2.5.1 03:01:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:01:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:01:11 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:01:11 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:01:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:01:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:01:13 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 03:01:13 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 03:01:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:01:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:01:13 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:01:13 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:01:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:01:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:01:13 INFO - Downloading functools32-3.2.3-2.tar.gz 03:01:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 03:01:13 INFO - warning: no files found matching '*.txt' 03:01:13 INFO - no previously-included directories found matching 'build' 03:01:13 INFO - no previously-included directories found matching 'dist' 03:01:13 INFO - no previously-included directories found matching '.git*' 03:01:13 INFO - Installing collected packages: jsonschema, functools32 03:01:14 INFO - Running setup.py install for functools32 03:01:14 INFO - warning: no files found matching '*.txt' 03:01:14 INFO - no previously-included directories found matching 'build' 03:01:14 INFO - no previously-included directories found matching 'dist' 03:01:14 INFO - no previously-included directories found matching '.git*' 03:01:14 INFO - Successfully installed jsonschema functools32 03:01:14 INFO - Cleaning up... 03:01:14 INFO - Return code: 0 03:01:14 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 03:01:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:01:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:01:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:01:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:01:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:01:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:01:14 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10aadaa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ab6f618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9e9347d5a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aa3ed30>, '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 0x10a7a3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10aa36b20>, '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.4unqib97vF/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.0vOC3nxODO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/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 03:01:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 03:01:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 03:01:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4unqib97vF/Render', 03:01:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:01:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:01:14 INFO - 'HOME': '/Users/cltbld', 03:01:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:01:14 INFO - 'LOGNAME': 'cltbld', 03:01:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:01:14 INFO - 'MOZ_NO_REMOTE': '1', 03:01:14 INFO - 'NO_EM_RESTART': '1', 03:01:14 INFO - 'PAGER': '/bin/cat', 03:01:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:01:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:01:14 INFO - 'PWD': '/builds/slave/test', 03:01:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:01:14 INFO - 'SHELL': '/bin/bash', 03:01:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0vOC3nxODO/Listeners', 03:01:14 INFO - 'TMPDIR': '/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/', 03:01:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:01:14 INFO - 'USER': 'cltbld', 03:01:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:01:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:01:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:01:14 INFO - 'XPC_FLAGS': '0x0', 03:01:14 INFO - 'XPC_SERVICE_NAME': '0', 03:01:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:01:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:01:14 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 03:01:14 INFO - Cleaning up... 03:01:14 INFO - Return code: 0 03:01:14 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 03:01:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:01:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:01:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:01:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:01:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:01:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:01:14 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10aadaa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ab6f618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9e9347d5a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aa3ed30>, '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 0x10a7a3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10aa36b20>, '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.4unqib97vF/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.0vOC3nxODO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/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 03:01:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 03:01:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 03:01:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4unqib97vF/Render', 03:01:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:01:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:01:14 INFO - 'HOME': '/Users/cltbld', 03:01:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:01:14 INFO - 'LOGNAME': 'cltbld', 03:01:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:01:14 INFO - 'MOZ_NO_REMOTE': '1', 03:01:14 INFO - 'NO_EM_RESTART': '1', 03:01:14 INFO - 'PAGER': '/bin/cat', 03:01:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:01:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:01:14 INFO - 'PWD': '/builds/slave/test', 03:01:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:01:14 INFO - 'SHELL': '/bin/bash', 03:01:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0vOC3nxODO/Listeners', 03:01:14 INFO - 'TMPDIR': '/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/', 03:01:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:01:14 INFO - 'USER': 'cltbld', 03:01:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:01:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:01:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:01:14 INFO - 'XPC_FLAGS': '0x0', 03:01:14 INFO - 'XPC_SERVICE_NAME': '0', 03:01:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:01:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:01:14 INFO - Downloading/unpacking blobuploader==1.2.4 03:01:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:01:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:01:14 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:01:14 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:01:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:01:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:01:17 INFO - Downloading blobuploader-1.2.4.tar.gz 03:01:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 03:01:17 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 03:01:17 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 03:01:17 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 03:01:17 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 03:01:17 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 03:01:17 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 03:01:17 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 03:01:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 03:01:18 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 03:01:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:01:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:01:18 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:01:18 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:01:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:01:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:01:18 INFO - Downloading docopt-0.6.1.tar.gz 03:01:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 03:01:18 INFO - Installing collected packages: blobuploader, requests, docopt 03:01:18 INFO - Running setup.py install for blobuploader 03:01:18 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 03:01:18 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 03:01:18 INFO - Running setup.py install for requests 03:01:19 INFO - Running setup.py install for docopt 03:01:19 INFO - Successfully installed blobuploader requests docopt 03:01:19 INFO - Cleaning up... 03:01:19 INFO - Return code: 0 03:01:19 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:01:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:01:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:01:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:01:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:01:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:01:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:01:19 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 0x10aadaa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ab6f618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9e9347d5a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aa3ed30>, '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 0x10a7a3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10aa36b20>, '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.4unqib97vF/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.0vOC3nxODO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/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 03:01:19 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 03:01:19 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 03:01:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4unqib97vF/Render', 03:01:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:01:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:01:19 INFO - 'HOME': '/Users/cltbld', 03:01:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:01:19 INFO - 'LOGNAME': 'cltbld', 03:01:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:01:19 INFO - 'MOZ_NO_REMOTE': '1', 03:01:19 INFO - 'NO_EM_RESTART': '1', 03:01:19 INFO - 'PAGER': '/bin/cat', 03:01:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:01:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:01:19 INFO - 'PWD': '/builds/slave/test', 03:01:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:01:19 INFO - 'SHELL': '/bin/bash', 03:01:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0vOC3nxODO/Listeners', 03:01:19 INFO - 'TMPDIR': '/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/', 03:01:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:01:19 INFO - 'USER': 'cltbld', 03:01:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:01:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:01:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:01:19 INFO - 'XPC_FLAGS': '0x0', 03:01:19 INFO - 'XPC_SERVICE_NAME': '0', 03:01:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:01:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:01:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:01:19 INFO - Running setup.py (path:/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/pip-IoG8zo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:01:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:01:19 INFO - Running setup.py (path:/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/pip-sxWfku-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:01:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:01:19 INFO - Running setup.py (path:/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/pip-0XYMpu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:01:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:01:19 INFO - Running setup.py (path:/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/pip-cheQls-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:01:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:01:20 INFO - Running setup.py (path:/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/pip-iMS8Dm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:01:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:01:20 INFO - Running setup.py (path:/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/pip-9lqVKl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:01:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:01:20 INFO - Running setup.py (path:/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/pip-CU65rO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:01:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:01:20 INFO - Running setup.py (path:/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/pip-VmQxRl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:01:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:01:20 INFO - Running setup.py (path:/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/pip-8Mhz6X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:01:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:01:20 INFO - Running setup.py (path:/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/pip-ZKhnUK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:01:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:01:20 INFO - Running setup.py (path:/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/pip-Se1eJW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:01:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:01:20 INFO - Running setup.py (path:/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/pip-ylk6R1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:01:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:01:20 INFO - Running setup.py (path:/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/pip-VkFX2N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:01:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:01:20 INFO - Running setup.py (path:/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/pip-clu6Dm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:01:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:01:21 INFO - Running setup.py (path:/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/pip-fYoZtH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:01:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:01:21 INFO - Running setup.py (path:/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/pip-QwStXi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:01:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:01:21 INFO - Running setup.py (path:/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/pip-41_Ynp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:01:21 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 03:01:21 INFO - Running setup.py install for manifestparser 03:01:21 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 03:01:21 INFO - Running setup.py install for mozcrash 03:01:21 INFO - Running setup.py install for mozdebug 03:01:21 INFO - Running setup.py install for mozdevice 03:01:21 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 03:01:21 INFO - Installing dm script to /builds/slave/test/build/venv/bin 03:01:22 INFO - Running setup.py install for mozfile 03:01:22 INFO - Running setup.py install for mozhttpd 03:01:22 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 03:01:22 INFO - Running setup.py install for mozinfo 03:01:22 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 03:01:22 INFO - Running setup.py install for mozInstall 03:01:22 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 03:01:22 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 03:01:22 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 03:01:22 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 03:01:22 INFO - Running setup.py install for mozleak 03:01:22 INFO - Running setup.py install for mozlog 03:01:22 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 03:01:22 INFO - Running setup.py install for moznetwork 03:01:23 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 03:01:23 INFO - Running setup.py install for mozprocess 03:01:23 INFO - Running setup.py install for mozprofile 03:01:23 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 03:01:23 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 03:01:23 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 03:01:23 INFO - Running setup.py install for mozrunner 03:01:23 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 03:01:23 INFO - Running setup.py install for mozscreenshot 03:01:23 INFO - Running setup.py install for moztest 03:01:23 INFO - Running setup.py install for mozversion 03:01:23 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 03:01:23 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 03:01:23 INFO - Cleaning up... 03:01:23 INFO - Return code: 0 03:01:23 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:01:23 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')]} 03:01:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:01:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:01:23 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')]} 03:01:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:01:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:01:23 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 0x10aadaa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ab6f618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9e9347d5a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aa3ed30>, '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 0x10a7a3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10aa36b20>, '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.4unqib97vF/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.0vOC3nxODO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/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 03:01:23 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 03:01:23 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 03:01:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4unqib97vF/Render', 03:01:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:01:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:01:23 INFO - 'HOME': '/Users/cltbld', 03:01:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:01:23 INFO - 'LOGNAME': 'cltbld', 03:01:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:01:23 INFO - 'MOZ_NO_REMOTE': '1', 03:01:23 INFO - 'NO_EM_RESTART': '1', 03:01:23 INFO - 'PAGER': '/bin/cat', 03:01:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:01:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:01:23 INFO - 'PWD': '/builds/slave/test', 03:01:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:01:23 INFO - 'SHELL': '/bin/bash', 03:01:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0vOC3nxODO/Listeners', 03:01:23 INFO - 'TMPDIR': '/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/', 03:01:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:01:23 INFO - 'USER': 'cltbld', 03:01:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:01:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:01:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:01:23 INFO - 'XPC_FLAGS': '0x0', 03:01:23 INFO - 'XPC_SERVICE_NAME': '0', 03:01:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:01:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:01:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:01:24 INFO - Running setup.py (path:/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/pip-GmgdFD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:01:24 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)) 03:01:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:01:24 INFO - Running setup.py (path:/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/pip-FR3Bz5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:01:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 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)) 03:01:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:01:24 INFO - Running setup.py (path:/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/pip-D8lPTa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:01:24 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)) 03:01:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:01:24 INFO - Running setup.py (path:/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/pip-laAau1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:01:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 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)) 03:01:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:01:24 INFO - Running setup.py (path:/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/pip-isfBn9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:01:24 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)) 03:01:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:01:24 INFO - Running setup.py (path:/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/pip-viFKoA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:01:24 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)) 03:01:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:01:24 INFO - Running setup.py (path:/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/pip-VOaSh1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:01:24 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)) 03:01:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:01:24 INFO - Running setup.py (path:/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/pip-kmlCiB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:01:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 03:01:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:01:25 INFO - Running setup.py (path:/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/pip-W4kGsC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:01:25 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)) 03:01:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:01:25 INFO - Running setup.py (path:/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/pip-kUF2ER-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:01:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.3 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)) 03:01:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:01:25 INFO - Running setup.py (path:/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/pip-3cFePU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:01:25 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)) 03:01:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:01:25 INFO - Running setup.py (path:/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/pip-K7LGEc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:01:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 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)) 03:01:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:01:25 INFO - Running setup.py (path:/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/pip-St6q6O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:01:25 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)) 03:01:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:01:25 INFO - Running setup.py (path:/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/pip-uwwXrr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:01:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 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)) 03:01:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:01:25 INFO - Running setup.py (path:/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/pip-_cit0H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:01:25 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)) 03:01:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:01:25 INFO - Running setup.py (path:/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/pip-HoDrId-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:01:25 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 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)) 03:01:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:01:25 INFO - Running setup.py (path:/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/pip-WTL7YU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:01:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 03:01:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 03:01:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 03:01:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 03:01:26 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 03:01:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 03:01:26 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 03:01:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:01:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:01:26 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:01:26 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:01:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:01:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:01:28 INFO - Downloading blessings-1.6.tar.gz 03:01:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 03:01:28 INFO - Installing collected packages: blessings 03:01:28 INFO - Running setup.py install for blessings 03:01:28 INFO - Successfully installed blessings 03:01:28 INFO - Cleaning up... 03:01:28 INFO - Return code: 0 03:01:28 INFO - Done creating virtualenv /builds/slave/test/build/venv. 03:01:28 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:01:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:01:29 INFO - Reading from file tmpfile_stdout 03:01:29 INFO - Current package versions: 03:01:29 INFO - blessings == 1.6 03:01:29 INFO - blobuploader == 1.2.4 03:01:29 INFO - docopt == 0.6.1 03:01:29 INFO - functools32 == 3.2.3-2 03:01:29 INFO - jsonschema == 2.5.1 03:01:29 INFO - manifestparser == 1.1 03:01:29 INFO - mozInstall == 1.12 03:01:29 INFO - mozcrash == 0.17 03:01:29 INFO - mozdebug == 0.1 03:01:29 INFO - mozdevice == 0.48 03:01:29 INFO - mozfile == 1.2 03:01:29 INFO - mozhttpd == 0.7 03:01:29 INFO - mozinfo == 0.9 03:01:29 INFO - mozleak == 0.1 03:01:29 INFO - mozlog == 3.3 03:01:29 INFO - moznetwork == 0.27 03:01:29 INFO - mozprocess == 0.23 03:01:29 INFO - mozprofile == 0.28 03:01:29 INFO - mozrunner == 6.12 03:01:29 INFO - mozscreenshot == 0.1 03:01:29 INFO - mozsystemmonitor == 0.3 03:01:29 INFO - moztest == 0.7 03:01:29 INFO - mozversion == 1.4 03:01:29 INFO - psutil == 3.1.1 03:01:29 INFO - requests == 1.2.3 03:01:29 INFO - wsgiref == 0.1.2 03:01:29 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:01:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:01:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:01:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:01:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:01:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:01:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:01:29 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 0x10aadaa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ab6f618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9e9347d5a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aa3ed30>, '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 0x10a7a3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10aa36b20>, '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.4unqib97vF/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.0vOC3nxODO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/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 03:01:29 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 03:01:29 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 03:01:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4unqib97vF/Render', 03:01:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:01:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:01:29 INFO - 'HOME': '/Users/cltbld', 03:01:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:01:29 INFO - 'LOGNAME': 'cltbld', 03:01:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:01:29 INFO - 'MOZ_NO_REMOTE': '1', 03:01:29 INFO - 'NO_EM_RESTART': '1', 03:01:29 INFO - 'PAGER': '/bin/cat', 03:01:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:01:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:01:29 INFO - 'PWD': '/builds/slave/test', 03:01:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:01:29 INFO - 'SHELL': '/bin/bash', 03:01:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0vOC3nxODO/Listeners', 03:01:29 INFO - 'TMPDIR': '/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/', 03:01:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:01:29 INFO - 'USER': 'cltbld', 03:01:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:01:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:01:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:01:29 INFO - 'XPC_FLAGS': '0x0', 03:01:29 INFO - 'XPC_SERVICE_NAME': '0', 03:01:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:01:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:01:29 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)) 03:01:29 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)) 03:01:29 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)) 03:01:29 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)) 03:01:29 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)) 03:01:29 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)) 03:01:29 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)) 03:01:29 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)) 03:01:29 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)) 03:01:29 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)) 03:01:29 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)) 03:01:29 INFO - Cleaning up... 03:01:29 INFO - Return code: 0 03:01:29 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 03:01:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:01:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:01:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:01:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:01:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:01:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:01:29 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 0x10aadaa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ab6f618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9e9347d5a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aa3ed30>, '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 0x10a7a3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10aa36b20>, '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.4unqib97vF/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.0vOC3nxODO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/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 03:01:29 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 03:01:29 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 03:01:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4unqib97vF/Render', 03:01:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:01:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:01:29 INFO - 'HOME': '/Users/cltbld', 03:01:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:01:29 INFO - 'LOGNAME': 'cltbld', 03:01:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:01:29 INFO - 'MOZ_NO_REMOTE': '1', 03:01:29 INFO - 'NO_EM_RESTART': '1', 03:01:29 INFO - 'PAGER': '/bin/cat', 03:01:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:01:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:01:29 INFO - 'PWD': '/builds/slave/test', 03:01:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:01:29 INFO - 'SHELL': '/bin/bash', 03:01:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0vOC3nxODO/Listeners', 03:01:29 INFO - 'TMPDIR': '/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/', 03:01:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:01:29 INFO - 'USER': 'cltbld', 03:01:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:01:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:01:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:01:29 INFO - 'XPC_FLAGS': '0x0', 03:01:29 INFO - 'XPC_SERVICE_NAME': '0', 03:01:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:01:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:01:29 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 03:01:29 INFO - Cleaning up... 03:01:29 INFO - Return code: 0 03:01:29 INFO - Running post-action listener: _resource_record_post_action 03:01:29 INFO - Running post-action listener: _start_resource_monitoring 03:01:29 INFO - Starting resource monitoring. 03:01:29 INFO - [mozharness: 2016-09-05 10:01:29.735940Z] Finished create-virtualenv step (success) 03:01:29 INFO - [mozharness: 2016-09-05 10:01:29.736976Z] Running install step. 03:01:29 INFO - Running pre-action listener: _resource_record_pre_action 03:01:29 INFO - Running main action method: install 03:01:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:01:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:01:29 INFO - Reading from file tmpfile_stdout 03:01:29 INFO - Detecting whether we're running mozinstall >=1.0... 03:01:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 03:01:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 03:01:30 INFO - Reading from file tmpfile_stdout 03:01:30 INFO - Output received: 03:01:30 INFO - Usage: mozinstall [options] installer 03:01:30 INFO - Options: 03:01:30 INFO - -h, --help show this help message and exit 03:01:30 INFO - -d DEST, --destination=DEST 03:01:30 INFO - Directory to install application into. [default: 03:01:30 INFO - "/builds/slave/test"] 03:01:30 INFO - --app=APP Application being installed. [default: firefox] 03:01:30 INFO - mkdir: /builds/slave/test/build/application 03:01:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 03:01:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 03:02:04 INFO - Reading from file tmpfile_stdout 03:02:04 INFO - Output received: 03:02:04 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 03:02:04 INFO - Running post-action listener: _resource_record_post_action 03:02:04 INFO - [mozharness: 2016-09-05 10:02:04.356777Z] Finished install step (success) 03:02:04 INFO - [mozharness: 2016-09-05 10:02:04.356908Z] Running run-tests step. 03:02:04 INFO - Running pre-action listener: _resource_record_pre_action 03:02:04 INFO - Running main action method: run_tests 03:02:04 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 03:02:04 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 03:02:04 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 03:02:04 INFO - 2016-09-05 03:02:04.371 screenresolution[1643:7713] starting screenresolution argv=screenresolution get 03:02:04 INFO - 2016-09-05 03:02:04.373 screenresolution[1643:7713] Display 0: 1600x1200x32@60 03:02:04 INFO - 2016-09-05 03:02:04.379 screenresolution[1644:7715] starting screenresolution argv=screenresolution list 03:02:04 INFO - Available Modes on Display 0 03:02:04 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 03:02:04 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 03:02:04 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 03:02:04 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 03:02:04 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 03:02:04 INFO - Intel Iris: 03:02:04 INFO - Chipset Model: Intel Iris 03:02:04 INFO - Type: GPU 03:02:04 INFO - Bus: Built-In 03:02:04 INFO - VRAM (Dynamic, Max): 1536 MB 03:02:04 INFO - Vendor: Intel (0x8086) 03:02:04 INFO - Device ID: 0x0a2e 03:02:04 INFO - Revision ID: 0x0009 03:02:04 INFO - Displays: 03:02:04 INFO - Display: 03:02:04 INFO - Resolution: 1600 x 1200 @ 60 Hz 03:02:04 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 03:02:04 INFO - Display Serial Number: 1600x1200 60 03:02:04 INFO - Main Display: Yes 03:02:04 INFO - Mirror: Off 03:02:04 INFO - Online: Yes 03:02:04 INFO - Rotation: Supported 03:02:04 INFO - Return code: 0 03:02:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 03:02:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 03:02:04 INFO - Python 2.7.3 03:02:04 INFO - Return code: 0 03:02:04 INFO - grabbing minidump binary from tooltool 03:02:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:02:04 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 0x10aa3ed30>, '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 0x10a7a3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10aa36b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 03:02:04 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 03:02:04 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 03:02:04 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 03:02:04 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 03:02:04 INFO - Return code: 0 03:02:04 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 03:02:04 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 03:02:04 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 03:02:04 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 03:02:04 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 03:02:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/TpkaSgRSRPO5RFZeVuHFkw/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0335', '--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 03:02:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Autoland --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/TpkaSgRSRPO5RFZeVuHFkw/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0335 --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 03:02:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4unqib97vF/Render', 03:02:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:02:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:02:04 INFO - 'HOME': '/Users/cltbld', 03:02:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:02:04 INFO - 'LOGNAME': 'cltbld', 03:02:04 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:02:04 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 03:02:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:02:04 INFO - 'MOZ_NO_REMOTE': '1', 03:02:04 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:02:04 INFO - 'NO_EM_RESTART': '1', 03:02:04 INFO - 'PAGER': '/bin/cat', 03:02:04 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:02:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:02:04 INFO - 'PWD': '/builds/slave/test', 03:02:04 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:02:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:02:04 INFO - 'SHELL': '/bin/bash', 03:02:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0vOC3nxODO/Listeners', 03:02:04 INFO - 'TMPDIR': '/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/', 03:02:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:02:04 INFO - 'USER': 'cltbld', 03:02:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:02:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:02:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:02:04 INFO - 'XPC_FLAGS': '0x0', 03:02:04 INFO - 'XPC_SERVICE_NAME': '0', 03:02:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:02:04 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/TpkaSgRSRPO5RFZeVuHFkw/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0335', '--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 03:02:04 INFO - mozversion application_buildid: 20160905014120 03:02:04 INFO - mozversion application_changeset: 8515bb6df598578d036cd14422f5ab4e9d65980e 03:02:04 INFO - mozversion application_display_name: Nightly 03:02:04 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 03:02:04 INFO - mozversion application_name: Firefox 03:02:04 INFO - mozversion application_remotingname: firefox 03:02:04 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland 03:02:04 INFO - mozversion application_vendor: Mozilla 03:02:04 INFO - mozversion application_version: 51.0a1 03:02:04 INFO - mozversion platform_buildid: 20160905014120 03:02:04 INFO - mozversion platform_changeset: 8515bb6df598578d036cd14422f5ab4e9d65980e 03:02:04 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland 03:02:04 INFO - mozversion platform_version: 51.0a1 03:02:04 INFO - using testdate: 1473069724 03:02:04 INFO - actual date: 1473069724 03:02:04 INFO - starting webserver on 'localhost:49271' 03:02:04 INFO - SUITE-START | Running 2 tests 03:02:04 INFO - TEST-START | dromaeo_css 03:02:04 INFO - operating with platform_type : mac_ 03:02:04 INFO - Initialising browser for dromaeo_css test... 03:02:04 INFO - TEST-INFO | started process 1654 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/tmpbRQrp7/profile http://localhost:49271/getInfo.html) 03:02:07 INFO - PROCESS | 1654 | __metrics Screen width/height:1600/1200 03:02:07 INFO - PROCESS | 1654 | colorDepth:24 03:02:07 INFO - PROCESS | 1654 | Browser inner width/height: 1024/689 03:02:07 INFO - PROCESS | 1654 | __metrics 03:02:07 ERROR - PROCESS | 1654 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2998: TypeError: this.tabs is undefined 03:02:08 INFO - TEST-INFO | 1654: exit 0 03:02:08 INFO - Browser initialized. 03:02:08 INFO - Running cycle 1/1 for dromaeo_css test... 03:02:08 INFO - TEST-INFO | started process 1657 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/tmpbRQrp7/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:02:20 INFO - PROCESS | 1657 | RSS: Main: 243957760 03:02:20 INFO - PROCESS | 1657 | 03:03:10 INFO - PROCESS | 1657 | 1473069790214 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 03:05:21 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49271/tests/dromaeo/cssquery-ext.html) 03:05:22 INFO - PROCESS | 1657 | RSS: Main: 262443008 03:05:22 INFO - PROCESS | 1657 | 03:08:25 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-ext.html (next: http://localhost:49271/tests/dromaeo/cssquery-jquery.html) 03:08:25 INFO - PROCESS | 1657 | RSS: Main: 272785408 03:08:25 INFO - PROCESS | 1657 | 03:11:27 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49271/tests/dromaeo/cssquery-mootools.html) 03:11:28 INFO - PROCESS | 1657 | RSS: Main: 276008960 03:11:28 INFO - PROCESS | 1657 | 03:14:30 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49271/tests/dromaeo/cssquery-prototype.html) 03:14:31 INFO - PROCESS | 1657 | RSS: Main: 271499264 03:14:31 INFO - PROCESS | 1657 | 03:17:34 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49271/tests/dromaeo/cssquery-yui.html) 03:17:34 INFO - PROCESS | 1657 | RSS: Main: 276881408 03:17:34 INFO - PROCESS | 1657 | 03:20:37 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-yui.html (next: http://localhost:49271/tests/dromaeo/cssquery-dojo.html) 03:20:37 INFO - PROCESS | 1657 | RSS: Main: 280801280 03:20:37 INFO - PROCESS | 1657 | 03:20:37 INFO - PROCESS | 1657 | __start_tp_report 03:20:37 INFO - PROCESS | 1657 | _x_x_mozilla_page_load 03:20:37 INFO - PROCESS | 1657 | _x_x_mozilla_page_load_details 03:20:37 INFO - PROCESS | 1657 | |i|pagename|runs| 03:20:37 INFO - PROCESS | 1657 | |0;dojo.html;3359.596848378195;3376.729861611071;3383.154211447138;3405.761596688232;3410.9147271318216;5471;5674.60277780555;5699.031164702;5706.315242170935;5736.541076713864;5555.999920014396;5657.66054036757;5718.571107166968;5758.136279558058;5797;5113;5207.218917162418;5207.895842083156;5225.477452254782;5291.761870715827;5189.740512974355;5336.4930331618625;5337.973310133463;5349.277847490597;5376.758045887945;5212.973935130338;5235.65968212065;5245.344331958505;5285.445028272034;5305.0450918834595;5096.79612815487;5149.124648809711;5189.896202075975;5204.245384419276;5267.47325267474;5103.106956282636;5127.66670166438;5144.845654630367;5202.791888324463;5223.399309079467;4358.3244597087505;5533.861653458655;5716.657000579979;5716.742746576414;5737.598368114233;6120.296165940929;6135.754569817202;6163.537734669918;6227.283862355796;6271.843203919893;4812.759362031919;4841.878953026167;5013.097642424362;5127.564157046656;5578.163275508706;5513.003072772208;5635.8591035224445;5785.27684039495;5815.040518314515;5815.389384114692;6206.503479721611;6211.627302361872;6216;6269.996800511987;6286.9056964145575;5348.983693098299;5605.355384130796;5631.1271752878365;6114.419130182626;6142.508599312045;5200.791968321225;6095.603785753912;6248.9062664060075;6259.593126446766;6277.089821975788;6139.29398119222;6153.415425534657;6161.568690191643;6210.4410603045035;6250.9062364064575;6243.125962365272;6247.5939063961605;6260.373962603703;6280.309165991737;6281.309056003836;4802.927956080662;4911.852644420673;4934.407871055425;4943.653944223942;4954.801807927714;4335.523092459827;4339.435873336446;4379.211741886427;4414.073044660658;4486.192485352668;5689.630174038674;5768.942310576925;5772.62477938941;5774.989376859033;5778.566607504454;6098.26820781509;6179.412955769195;6189.319174890758;6206.596571222856;6210.161628180228;5684.54523638108;5706.28671416073;5759.913601295984;5762.942370576325;5789.710514474259;5376.623636345418;5384.326959130109;5384.865378365494;5399.082156033483;5423.403425623178;6247.406496382836;6306.716197771111;6307.5584709070845;6312.652804095727;6319.905201422074;6167.013277875519;6187.288461826947;6191.938080619317;6299.4645455135;6315.842103947438;6270.529710271657;6290.716917738621;6292.433680968735;6306.46395056407;6320.241571011324;6011.7595296187665;6143.938560614337;6175.84560385994;6179.28938172098;6192.938070619416;6172.753089876354;6198.380161983946;6268.216472940882;6318.368163183829;6331.873362532631;6006.039033754579;6037.094435834484;6126.111713801563;6140.355262697352;6163.660998645118;5943.881122377615;6133.3866613338305;6133.6319820810895;6140.9078863816185;6180.289266734383;5735.340435849931;5773.769049237983;5907.793227237026;5925.555583331354;5945.316288626863;5978.491828194652;5986.311574169026;6027.849303767441;6065.54508411862;6125;5870.735816888165;5873.177755114202;5887.852803679943;5889.882202355843;5895.941040589711 03:20:37 INFO - PROCESS | 1657 | |1;ext.html;455.3988734869973;483.25096101043385;506.56435465499663;513.5532087084237;592.745119598573;38022.61977380229;39573;39806.6067687631;39892.60107398925;39905.40141897867;34304.65695343046;35485.46771798419;40924.38613420801;41084;41969.37045944313;7639.923600763991;8303.543305118215;9696.12734853863;9717.805643887117;9770.609175633002;9566.904330956706;9690.612375504972;9698.806023879519;9700.175485083777;9708.951455242714;9560.187384072362;9651.565679544403;9683.225341972626;9694.709158725249;9721.902780972207;9663.371880827724;9667.27495437845;9692.079252471003;9728.367656102331;9737.026297370207;9585.329026968115;9630.759231019281;9662.855057174149;9667.951660241653;9675.903240967571;39548;39860.402093968616;39866.003349916195;39914.40128398076;40036.39945400821;3284.0640417480904;3324.8670053197843;3346.665333466634;3357.8656853726065;3402.188327265678;29789.95735149281;30355.392892142256;30605.69394306073;30745.846270768725;31270.530942035886;3208.6630903755236;3216.035189443181;3248.7888287261253;3311.3046260285128;3335.533025376449;384591;462334.94822836405;505285;509008;510219;32469.51295730566;33092.669073309444;57473.850522989305;57702.134467983014;57709;32509;34586.48120278198;34591.4811277831;34689.82655086708;34714.30571388609;14238.43046278158;14326.49857254991;14373.209473479059;14384.424623014962;14411.85588144126;12129.150959432754;12221.144519883523;12236.81644775329;12250.571230006906;12280.692982675504;7440.73957411488;7602.961985190039;7617.6572054257695;7667.923320766832;7686.000819893378;3153.810771353726;3187.1394723424546;3206.9839650801596;3219.211293233173;3221.371832492688;904.6200595749801;934.5140526926097;1050.2490719135856;1081.3944191252924;1117.4803716271972;29304.7069529302;29446.85276573646;30177.547336789965;30233;31030.379392412477;815.1685281013333;969.8060387922444;1004.9397036177853;1038.8857225705167;1083.777825545777;14961.326740296712;15534.1456219909;15582.195204863006;15653.452129175424;15768.290426930816;656880;673595;673631;675040;677511;57137;57302.7134864323;57797.55506112381;57869;57998.420015799304;1329.2755448280711;1342.536824795444;1373.7458570164351;1376.5870238928483;1376.876081152701;28578;29501.96743113981;29571;30129.698703012687;30174.9438769642;48794.51205488042;49061;49068.75465622649;49243.753781230866;49397;440310;485884;487752;488357;495853;24598.754012459645;25753.871230643726;26138.215853524423;26611.7338826617;26715.732842671325;43165.568344316154;45790.77104614455;46636.300455492514;47189;47211.76394118008;37125.814370927976;38123.2375352497;38202.80898595489;38277.61722382741;39333.40999885061;36209.63790362063;37918.431223531115;38208.28443734261;38344.6165538341;38756;30521.542176866922;31947;37179.07052323713;37798.62201378061;39300.410493843185;26272.474550509265;32697;33423;34401.827990859885;34635.65364346324;21823.563528729654;22491.212807551656;23141;25313;26360.34099147537 03:20:37 INFO - PROCESS | 1657 | |2;jquery.html;314.3132256020597;368.0541009605314;372.62923391225735;373.6450372146461;394.1191437137996;20094.6985795213;20321.695174572375;20365.79634203661;20897.164113435483;21112.4721881953;20445.386638400836;20701.378958631267;20973.580528389462;21266.574668506622;21282.89358553209;5290.444503327153;5919.911201331984;6032.879342413149;6086.6043707159115;6157.568970172089;6026.789062382795;6136.263648362181;6139.017757158833;6141.692915354237;6157.846053848645;5740.885182296352;5788.42115788422;5976.5218782497295;6026.276846778371;6038.33578306386;5838.9124163137585;5954.4938680212235;5969.343372229051;5995.430434108753;6038.69806509675;5903.468687818074;5967.850803729898;6040.244969378828;6041;6067.423594758491;12855.614331569974;13189.670258243525;13221.801672974912;13257.867421325856;13258.867411325957;2390.8087353011715;2394.1740099665763;2401.447667036591;2629.9868500657567;2635.749603787637;9388.483633400161;9986.950065249628;10210.540525676362;10494.527746251437;10528.526216320208;1819.1177279019664;1852.2683540001624;1895.3082125024405;1907.7519922410136;1988.3040935672543;116795;120430.79569204284;125118;125187;125401;9200.401973871676;11206.887931120747;11435.656930292105;11510.654680359603;11749.823752643717;14186.219757913208;14322;14331;14359.497417590333;14398.928005359907;8101.837963240702;8321.750347489586;8375.539345336065;8474.525444567698;8481.57592120404;6548.377904099103;8165.346772258205;8184.836303273901;8204.466709663971;8340.207680270365;4414.3157810539415;4443.488998765119;4447.555244475591;4452.755098469615;4453.710508816916;1893.829555340026;1943.2518480385154;1948.8733232339853;1950.0639692947182;1951.5316324082119;842.3640151685438;907.6913849291273;922.9261659067258;927.1701826864918;941.9670311539201;20639.69040464394;21084.05121769524;21125.68311475329;21285.36143915685;21362.465938351354;700.4431476975816;836.2766207230702;843.8397203844713;893.5710858787688;937.95310234488;10668.359898406121;11286.7178320541;11303.773924521463;11332.943335283271;11356.886431135748;499216;499528;505105;595964;597552;98692;102615;133467;139328.30335848255;149398.25300873426;775.5075527040257;884.8186121845006;995.5713551054116;1001.8829005658638;1033.503918119292;10861.67414977552;11201.621195331958;11582.478788454708;11744.29534227949;19897.502562436053;38092.523985940825;50324.49675503198;50411;50655;51630.54618172728;65598.01602976055;65698.67150664216;68267;69205.65397172981;70370.94443583452;12371.05197712012;12602;12609.495620175458;12981.610551683465;13007.219566826021;13743.793843092162;13775;13856.722865542833;14133.293335332986;14311.928440357731;9212;9817.803643926938;11833.585824496102;11926.165168438127;12013.63959081229;10124.34191777532;11432.942835286103;11469.942650286695;11786.410679466164;12196.75606487883;11779.823302650639;11791.587294444653;12105.576304829288;12110.636680899586;12214.083943704436;12327.445264962918;12353.691157721129;12654.620361389174;12687.302198379179;12796.36018199105;11755.000824929977;11818.054555635357;12073.154879158727;12168.75662486763;12257.877421225674 03:20:37 INFO - PROCESS | 1657 | |3;mootools.html;1457.2276693352521;1502.444095684597;1713.1348668922192;1816.7918334307685;1877.8403835673967;6412.877746394374;7075.823104422386;7524.645563798514;8227.218414250636;8810.867836982443;7316.865885787698;8196.13940536244;8854.335924805633;8929.196372326489;8933.553322333873;2386.331827088418;2424.3333083402117;2426.7694569016003;2426.805855531562;2430.173740928084;1967.2721093195537;1990.7810140884565;2336.929892103231;2419.6733440985417;2425.454272788626;2386.1648423051965;2401.171595799455;2407.5425669122897;2427.7208121066037;2430.052279610953;2381.1784934197676;2393.5930891748444;2400.31590996566;2401.939951501209;2415.9637605435933;2358.811295096388;2363.7636236376225;2375.1449478187837;2388.4387169015267;2393.270052633946;8920.464772113693;8955.462672239686;9011.954940225258;9018.143276388733;9059.411138275991;5283.128283833163;5415.918761218585;5451.836444906619;5458.808941687062;5471.452854714536;3626.528551288342;3663.230721548478;3683.6684698377007;3717.126475278307;3732.701383889282;1066.4188017530464;1068.3536460441399;1071.5606601293432;1071.7642118733866;1076.6231818863414;9784.706458806248;11740.941295293555;11763.117766167486;11810.88189118115;11849.763004739943;3968.8015599219925;4587.564181402761;4719.244920812654;4819.469858315582;4896.828610998669;9929.751756206153;10016.599336026624;10042.046005629454;10055.497225138713;10066.59733610662;5386.569074474032;5417.729113544307;5427.348718153847;5435.945640543544;5476.151196564539;4472.418585583853;4800.303955926371;4934.062528119646;4950.9009819803405;4987.401511818605;3266.0691702865047;3370.4775759757276;3494.7204223662047;3668.4313931340153;4050.5139383274195;1297.0531511996296;1305.6213698027655;1306.6341424401182;1314.3953781260536;1317.775978083728;753.3784627682185;846.4667259626395;898.3576742629051;912.6075787411344;938.7136923238478;4826.8793280168275;5703.298624116646;5769.509591684669;6071.1465611162685;6092.208012958287;908.028409601733;934.9762010598384;938.9108034736826;949.6486300068881;959.8752162219007;3206.294615184656;3769.7738135711943;3997.9400308995387;4104.281750693616;4122.3404255319;9894.15899648538;11004.944975275072;11019.898010199076;11085.390303533382;11130.666080017612;10799.67600971972;10833.404162771123;10885.945570272257;11009.504572294267;11019.063379612822;8172.550509721904;8282.668693252203;8413.621387037598;8427.157284271523;8432.325413966746;4835.95164048355;5565.248691426767;5795.82612521625;5894.852628684317;5945.554083443672;9982.450965196984;10085.991400859855;10100.141487973311;10263.7434064149;10278.17774578047;9523.904760952302;9851.359661621971;10091.69724908254;10345.27583069208;10364.429956352473;5873.295204575478;6959.686814093479;7359.043324367798;7642.5032372895585;7644.579548124907;7505.924940750522;8368.665253389796;8545.658173672984;8564.957175214084;8571.571421428778;6564.901526477011;7275.090613673291;7667.731629392944;7673.30940215361;7710.306072453282;7500.699972001059;7517.962410187914;7528.51064680794;7536.434767392358;7630.580318082559;6906.274841141809;6910.689018994057;6970.51206415546;6973.825654358681;7012.123484564429;4949.381327334083;5032.874178145576;5071.366079240095;5080.796768129233;5141.408737995179;4928;5015.598752099896;5093.083245015932;5141.511556402136;5237.738113094406 03:20:37 INFO - PROCESS | 1657 | |4;prototype.html;255.4979465350586;301.1672724915607;309.84198058989915;312.7591754349151;315.6874694052888;5255.632105752599;5386;5552.555795536357;5662.8301150965435;5674.233978412913;4797.544233297831;5561.749721262543;5633.436656334365;5728.68492232928;5731.627444216123;4115.053537686326;4371.497277813044;4374.9781251093855;4380.759058251795;4384.802683879233;3957.6635985941084;4137.710360274782;4226.936595951063;4251.787410629471;4351.521332653406;3863.594322596129;4252.72418274518;4268.445102136718;4298.269294219975;4310.719803212797;4287.914241715148;4294.785260736966;4303.225419424502;4324.589164029412;4340.327249276335;3604.3331983582857;3742.906427339311;3749.981250093759;3751.4560388743753;3756.530433695788;3870.4581358609817;4627.190241707722;4766.571008609207;5230.3524389817085;5435.021696094701;4115.835366585333;4642.187617166982;4973.880876802727;4989.850304490871;4999.750062484379;4694.08465349257;5201.375834899798;5622.550195984311;5962.284525856881;6046.486048685867;3376.763626546143;3503.597086335053;3576.302620988908;3857.40210267409;4055.8174882130374;5230.450802665703;6322.241331040304;6353.506925872416;6358.395952384516;6372.745090196387;4034.3343348347194;4154.462848745983;4172.457580514574;4179.895502612459;4194.3498757692;3471.062813040465;3548.361294966931;4178.059936514321;4195.853145139906;4240.745555266694;4524.773761311987;4539.504581472248;4942.566655669887;4942.728149951715;4950.282209079735;5241.580673546107;5476.26070480488;5653.66078035311;5894.204282421818;6080.878382432326;1371.8079468874366;1411.9647008824656;1428.8356838963443;1442.2572375226766;1449.145004447381;846.9074893387584;851.284920666643;878.6644300662956;885.1193062902453;889.5596679643618;4320.746983374786;4554.681172317973;4595.770211489412;4634.86095417138;4748.246017310021;4659.6505262105475;4773.54406905897;4893.8988727536735;5176.696370115263;5179.974100129475;3993.843324604739;4672.415948006499;4720.291956206528;4731.858044258678;4738.715677059387;2578.123438031041;2830.886764529396;2935.882564697431;3025.455418024733;3259.315543735842;5540.418256083134;6416.839579010562;6464.22429308486;6468.579542329733;6484.902726459107;5360.571154307646;5572.944270557243;6163.668367194683;6272.874542509125;6380.649064301508;4934.235193545006;5176.938727560841;5989.981703110481;6192.080455058946;6429.2962364974255;4972.378452693413;5111.667741596784;5716.085111487532;6083.574149809471;6209.782657606962;5456.380937150269;5611.887762244651;6039.214902062704;6137.754489820357;6150.077488376779;5364.409914909279;6079.0273556230795;6414.317548189754;6415.871682566416;6430;3956.327424337812;3966.8611598595066;4114.588541145861;4158.771267580312;4248.065425606362;5096.51583099605;5925.229720136354;6092.69536523181;6101.206843110367;6127.662978536223;5868.970655146868;6013.969930150496;6016.510423954183;6038.5169186465855;6049.274087109575;5681.659100453986;5960.195373624679;5972.850678733066;5989.940100599112;5990.7903223386975;5066.4680208577565;5228.607854410859;5713.314402271754;5867.735951882261;5940.495057919952;5237.214417837355;5735.967525845388;5991.820245392645;6020.916235077728;6028.939710603014;4871.318015477765;5053.772580233825;5813.593048486566;5821;5888.882222355443 03:20:37 INFO - PROCESS | 1657 | |5;yui.html;729.9525530840496;733.757859906231;763.351151521207;793.7380664380747;857.5540718826211;12612.684682882933;14036.719265614682;14790.260486975634;14950.40198392063;14958.626034349147;14613.269336533183;14622.707545849076;14740.6314842129;14742.26288685564;14857.182854942974;830.1947111302028;850.5874650794391;851.1403482482706;853.5092321914892;856.1010938514535;672.4351544702449;827.1769589258696;837.874318852171;841.2554888923286;845.2012847858776;879.0681877210146;881.1276835932413;888.6534251641865;889.2174886100223;891.4651209274448;872.7861673889875;876.0451108291928;877.1842186766305;877.1842186766337;879.6657270237333;649.8570314530796;656.7766959233884;657.3065415986133;660.4584240922445;664.8969409741497;14860.256987150708;14991.250437478191;15022.924885375613;15022.924885375613;15030.549083527512;14128.717425651532;14499.42002319906;14549.272536373139;14599.78100328496;14857;11934.940325298317;11983.520659173622;12001.51993920242;12015.399230038463;12028.954546132038;3643.4899114123778;3795.734298599099;3799.0882188274613;3929.0766669832565;3934;99337.50331248369;102113;104556;113009.99999999917;115142;10798;10897.237193396386;11082.501287442085;11246.550137994553;11354.829677554842;20487.28294509685;21379.358619241448;21761.238356657443;21798.45503862416;21819.563608727738;5959.761609535657;6883.346082122191;6976.5814051157095;7523.9623801881735;7598.784194528849;3825.598312177237;4348.326009468537;4354.433923589976;4375.496817865923;4412.5659160772875;3313.122022663996;3449.3618680544178;3489.616142224353;3502.141975216088;3515.050936247199;559.8208573256519;649.8148027812051;672.1497305908031;675.864827034595;676.7293082766835;539.8515408262681;609.439315829438;646.7089809585706;657.8223879552494;677.3598948993155;6960.79117626472;7580.3177714004905;9213.631454741735;9289.349745517884;9486.762830929281;552.3316786688072;562.9211910332557;627.5481653209682;630.2090875950744;642.7718160053207;4404.955950440519;4454.287314029804;4624.768761561976;4679.321498382784;4689.882752931204;150528.2473587647;151620;154188.229058854;154401;160390;98495;138723.30638346946;140628.29685851507;150178;150461;114238;116854;118495;118972;119279.40360298143;8855.690050848189;8869.467831930106;8870.2460290876;8874.156955089256;9095.363324567217;64488.71022579616;65421.67289163524;66753.3324666747;66938;67369.66315168393;97691.51154244183;101980.49009754903;102460.48769756104;103546.50348951003;104170;11351.413091519556;11778.352190629255;11860.288382697066;11918.344491052729;12141.028717702739;12960.416781244678;13085.869141308845;13538.526151585042;13599.320033998458;13789.448422063006;11819;12110.697232569257;12507.499700012262;12515.311657858905;12583.937080314907;11852.881471185177;12199.329036903055;12281;12514.937425312815;12566.246025238515;11671.474783634927;11845.703857403634;12019.819702704292;12141.271523708605;12405;8881.866771998555;8888.777780555538;8895.06601806801;9184.448933064037;9229;6899.793006209822;8183.058948221031;8649.740507784776;9181.989981102208;9732.41605503672 03:20:37 INFO - PROCESS | 1657 | __end_tp_report 03:20:37 INFO - PROCESS | 1657 | __start_cc_report 03:20:37 INFO - PROCESS | 1657 | _x_x_mozilla_cycle_collect,379 03:20:37 INFO - PROCESS | 1657 | __end_cc_report 03:20:37 INFO - PROCESS | 1657 | __startTimestamp1473070837846__endTimestamp 03:20:37 INFO - PROCESS | 1657 | 03:20:37 INFO - PROCESS | 1657 | ------- Summary: start ------- 03:20:37 INFO - PROCESS | 1657 | Number of tests: 6 03:20:37 INFO - PROCESS | 1657 | 03:20:37 INFO - PROCESS | 1657 | [#0] dojo.html Cycles:165 Average:5675.57 Median:5789.71 stddev:641.78 (11.1%) stddev-sans-first:617.50 03:20:37 INFO - PROCESS | 1657 | Values: 3359.6 3376.7 3383.2 3405.8 3410.9 5471.0 5674.6 5699.0 5706.3 5736.5 5556.0 5657.7 5718.6 5758.1 5797.0 5113.0 5207.2 5207.9 5225.5 5291.8 5189.7 5336.5 5338.0 5349.3 5376.8 5213.0 5235.7 5245.3 5285.4 5305.0 5096.8 5149.1 5189.9 5204.2 5267.5 5103.1 5127.7 5144.8 5202.8 5223.4 4358.3 5533.9 5716.7 5716.7 5737.6 6120.3 6135.8 6163.5 6227.3 6271.8 4812.8 4841.9 5013.1 5127.6 5578.2 5513.0 5635.9 5785.3 5815.0 5815.4 6206.5 6211.6 6216.0 6270.0 6286.9 5349.0 5605.4 5631.1 6114.4 6142.5 5200.8 6095.6 6248.9 6259.6 6277.1 6139.3 6153.4 6161.6 6210.4 6250.9 6243.1 6247.6 6260.4 6280.3 6281.3 4802.9 4911.9 4934.4 4943.7 4954.8 4335.5 4339.4 4379.2 4414.1 4486.2 5689.6 5768.9 5772.6 5775.0 5778.6 6098.3 6179.4 6189.3 6206.6 6210.2 5684.5 5706.3 5759.9 5762.9 5789.7 5376.6 5384.3 5384.9 5399.1 5423.4 6247.4 6306.7 6307.6 6312.7 6319.9 6167.0 6187.3 6191.9 6299.5 6315.8 6270.5 6290.7 6292.4 6306.5 6320.2 6011.8 6143.9 6175.8 6179.3 6192.9 6172.8 6198.4 6268.2 6318.4 6331.9 6006.0 6037.1 6126.1 6140.4 6163.7 5943.9 6133.4 6133.6 6140.9 6180.3 5735.3 5773.8 5907.8 5925.6 5945.3 5978.5 5986.3 6027.8 6065.5 6125.0 5870.7 5873.2 5887.9 5889.9 5895.9 03:20:37 INFO - PROCESS | 1657 | 03:20:37 INFO - PROCESS | 1657 | [#1] ext.html Cycles:180 Average:65908.02 Median:38242.95 stddev:147464.35 (385.6%) stddev-sans-first:147796.14 03:20:37 INFO - PROCESS | 1657 | Values: 455.4 483.3 506.6 513.6 592.7 38022.6 39573.0 39806.6 39892.6 39905.4 34304.7 35485.5 40924.4 41084.0 41969.4 7639.9 8303.5 9696.1 9717.8 9770.6 9566.9 9690.6 9698.8 9700.2 9709.0 9560.2 9651.6 9683.2 9694.7 9721.9 9663.4 9667.3 9692.1 9728.4 9737.0 9585.3 9630.8 9662.9 9668.0 9675.9 39548.0 39860.4 39866.0 39914.4 40036.4 3284.1 3324.9 3346.7 3357.9 3402.2 29790.0 30355.4 30605.7 30745.8 31270.5 3208.7 3216.0 3248.8 3311.3 3335.5 384591.0 462334.9 505285.0 509008.0 510219.0 32469.5 33092.7 57473.9 57702.1 57709.0 32509.0 34586.5 34591.5 34689.8 34714.3 14238.4 14326.5 14373.2 14384.4 14411.9 12129.2 12221.1 12236.8 12250.6 12280.7 7440.7 7603.0 7617.7 7667.9 7686.0 3153.8 3187.1 3207.0 3219.2 3221.4 904.6 934.5 1050.2 1081.4 1117.5 29304.7 29446.9 30177.5 30233.0 31030.4 815.2 969.8 1004.9 1038.9 1083.8 14961.3 15534.1 15582.2 15653.5 15768.3 656880.0 673595.0 673631.0 675040.0 677511.0 57137.0 57302.7 57797.6 57869.0 57998.4 1329.3 1342.5 1373.7 1376.6 1376.9 28578.0 29502.0 29571.0 30129.7 30174.9 48794.5 49061.0 49068.8 49243.8 49397.0 440310.0 485884.0 487752.0 488357.0 495853.0 24598.8 25753.9 26138.2 26611.7 26715.7 43165.6 45790.8 46636.3 47189.0 47211.8 37125.8 38123.2 38202.8 38277.6 39333.4 36209.6 37918.4 38208.3 38344.6 38756.0 30521.5 31947.0 37179.1 37798.6 39300.4 26272.5 32697.0 33423.0 34401.8 34635.7 21823.6 22491.2 23141.0 25313.0 26360.3 03:20:37 INFO - PROCESS | 1657 | 03:20:37 INFO - PROCESS | 1657 | [#2] jquery.html Cycles:180 Average:32884.43 Median:21119.08 stddev:90909.58 (430.5%) stddev-sans-first:91131.71 03:20:37 INFO - PROCESS | 1657 | Values: 314.3 368.1 372.6 373.6 394.1 20094.7 20321.7 20365.8 20897.2 21112.5 20445.4 20701.4 20973.6 21266.6 21282.9 5290.4 5919.9 6032.9 6086.6 6157.6 6026.8 6136.3 6139.0 6141.7 6157.8 5740.9 5788.4 5976.5 6026.3 6038.3 5838.9 5954.5 5969.3 5995.4 6038.7 5903.5 5967.9 6040.2 6041.0 6067.4 12855.6 13189.7 13221.8 13257.9 13258.9 2390.8 2394.2 2401.4 2630.0 2635.7 9388.5 9987.0 10210.5 10494.5 10528.5 1819.1 1852.3 1895.3 1907.8 1988.3 116795.0 120430.8 125118.0 125187.0 125401.0 9200.4 11206.9 11435.7 11510.7 11749.8 14186.2 14322.0 14331.0 14359.5 14398.9 8101.8 8321.8 8375.5 8474.5 8481.6 6548.4 8165.3 8184.8 8204.5 8340.2 4414.3 4443.5 4447.6 4452.8 4453.7 1893.8 1943.3 1948.9 1950.1 1951.5 842.4 907.7 922.9 927.2 942.0 20639.7 21084.1 21125.7 21285.4 21362.5 700.4 836.3 843.8 893.6 938.0 10668.4 11286.7 11303.8 11332.9 11356.9 499216.0 499528.0 505105.0 595964.0 597552.0 98692.0 102615.0 133467.0 139328.3 149398.3 775.5 884.8 995.6 1001.9 1033.5 10861.7 11201.6 11582.5 11744.3 19897.5 38092.5 50324.5 50411.0 50655.0 51630.5 65598.0 65698.7 68267.0 69205.7 70370.9 12371.1 12602.0 12609.5 12981.6 13007.2 13743.8 13775.0 13856.7 14133.3 14311.9 9212.0 9817.8 11833.6 11926.2 12013.6 10124.3 11432.9 11469.9 11786.4 12196.8 11779.8 11791.6 12105.6 12110.6 12214.1 12327.4 12353.7 12654.6 12687.3 12796.4 11755.0 11818.1 12073.2 12168.8 12257.9 03:20:37 INFO - PROCESS | 1657 | 03:20:37 INFO - PROCESS | 1657 | [#3] mootools.html Cycles:180 Average:5680.62 Median:4950.14 stddev:3196.59 (64.6%) stddev-sans-first:3189.80 03:20:37 INFO - PROCESS | 1657 | Values: 1457.2 1502.4 1713.1 1816.8 1877.8 6412.9 7075.8 7524.6 8227.2 8810.9 7316.9 8196.1 8854.3 8929.2 8933.6 2386.3 2424.3 2426.8 2426.8 2430.2 1967.3 1990.8 2336.9 2419.7 2425.5 2386.2 2401.2 2407.5 2427.7 2430.1 2381.2 2393.6 2400.3 2401.9 2416.0 2358.8 2363.8 2375.1 2388.4 2393.3 8920.5 8955.5 9012.0 9018.1 9059.4 5283.1 5415.9 5451.8 5458.8 5471.5 3626.5 3663.2 3683.7 3717.1 3732.7 1066.4 1068.4 1071.6 1071.8 1076.6 9784.7 11740.9 11763.1 11810.9 11849.8 3968.8 4587.6 4719.2 4819.5 4896.8 9929.8 10016.6 10042.0 10055.5 10066.6 5386.6 5417.7 5427.3 5435.9 5476.2 4472.4 4800.3 4934.1 4950.9 4987.4 3266.1 3370.5 3494.7 3668.4 4050.5 1297.1 1305.6 1306.6 1314.4 1317.8 753.4 846.5 898.4 912.6 938.7 4826.9 5703.3 5769.5 6071.1 6092.2 908.0 935.0 938.9 949.6 959.9 3206.3 3769.8 3997.9 4104.3 4122.3 9894.2 11004.9 11019.9 11085.4 11130.7 10799.7 10833.4 10885.9 11009.5 11019.1 8172.6 8282.7 8413.6 8427.2 8432.3 4836.0 5565.2 5795.8 5894.9 5945.6 9982.5 10086.0 10100.1 10263.7 10278.2 9523.9 9851.4 10091.7 10345.3 10364.4 5873.3 6959.7 7359.0 7642.5 7644.6 7505.9 8368.7 8545.7 8565.0 8571.6 6564.9 7275.1 7667.7 7673.3 7710.3 7500.7 7518.0 7528.5 7536.4 7630.6 6906.3 6910.7 6970.5 6973.8 7012.1 4949.4 5032.9 5071.4 5080.8 5141.4 4928.0 5015.6 5093.1 5141.5 5237.7 03:20:37 INFO - PROCESS | 1657 | 03:20:37 INFO - PROCESS | 1657 | [#4] prototype.html Cycles:180 Average:4695.30 Median:5026.76 stddev:1465.87 (29.2%) stddev-sans-first:1431.60 03:20:37 INFO - PROCESS | 1657 | Values: 255.5 301.2 309.8 312.8 315.7 5255.6 5386.0 5552.6 5662.8 5674.2 4797.5 5561.7 5633.4 5728.7 5731.6 4115.1 4371.5 4375.0 4380.8 4384.8 3957.7 4137.7 4226.9 4251.8 4351.5 3863.6 4252.7 4268.4 4298.3 4310.7 4287.9 4294.8 4303.2 4324.6 4340.3 3604.3 3742.9 3750.0 3751.5 3756.5 3870.5 4627.2 4766.6 5230.4 5435.0 4115.8 4642.2 4973.9 4989.9 4999.8 4694.1 5201.4 5622.6 5962.3 6046.5 3376.8 3503.6 3576.3 3857.4 4055.8 5230.5 6322.2 6353.5 6358.4 6372.7 4034.3 4154.5 4172.5 4179.9 4194.3 3471.1 3548.4 4178.1 4195.9 4240.7 4524.8 4539.5 4942.6 4942.7 4950.3 5241.6 5476.3 5653.7 5894.2 6080.9 1371.8 1412.0 1428.8 1442.3 1449.1 846.9 851.3 878.7 885.1 889.6 4320.7 4554.7 4595.8 4634.9 4748.2 4659.7 4773.5 4893.9 5176.7 5180.0 3993.8 4672.4 4720.3 4731.9 4738.7 2578.1 2830.9 2935.9 3025.5 3259.3 5540.4 6416.8 6464.2 6468.6 6484.9 5360.6 5572.9 6163.7 6272.9 6380.6 4934.2 5176.9 5990.0 6192.1 6429.3 4972.4 5111.7 5716.1 6083.6 6209.8 5456.4 5611.9 6039.2 6137.8 6150.1 5364.4 6079.0 6414.3 6415.9 6430.0 3956.3 3966.9 4114.6 4158.8 4248.1 5096.5 5925.2 6092.7 6101.2 6127.7 5869.0 6014.0 6016.5 6038.5 6049.3 5681.7 5960.2 5972.9 5989.9 5990.8 5066.5 5228.6 5713.3 5867.7 5940.5 5237.2 5736.0 5991.8 6020.9 6028.9 4871.3 5053.8 5813.6 5821.0 5888.9 03:20:37 INFO - PROCESS | 1657 | 03:20:37 INFO - PROCESS | 1657 | [#5] yui.html Cycles:180 Average:25336.08 Median:3877.34 stddev:41719.65 (1076.0%) stddev-sans-first:41795.78 03:20:37 INFO - PROCESS | 1657 | Values: 730.0 733.8 763.4 793.7 857.6 12612.7 14036.7 14790.3 14950.4 14958.6 14613.3 14622.7 14740.6 14742.3 14857.2 830.2 850.6 851.1 853.5 856.1 672.4 827.2 837.9 841.3 845.2 879.1 881.1 888.7 889.2 891.5 872.8 876.0 877.2 877.2 879.7 649.9 656.8 657.3 660.5 664.9 14860.3 14991.3 15022.9 15022.9 15030.5 14128.7 14499.4 14549.3 14599.8 14857.0 11934.9 11983.5 12001.5 12015.4 12029.0 3643.5 3795.7 3799.1 3929.1 3934.0 99337.5 102113.0 104556.0 113010.0 115142.0 10798.0 10897.2 11082.5 11246.6 11354.8 20487.3 21379.4 21761.2 21798.5 21819.6 5959.8 6883.3 6976.6 7524.0 7598.8 3825.6 4348.3 4354.4 4375.5 4412.6 3313.1 3449.4 3489.6 3502.1 3515.1 559.8 649.8 672.1 675.9 676.7 539.9 609.4 646.7 657.8 677.4 6960.8 7580.3 9213.6 9289.3 9486.8 552.3 562.9 627.5 630.2 642.8 4405.0 4454.3 4624.8 4679.3 4689.9 150528.2 151620.0 154188.2 154401.0 160390.0 98495.0 138723.3 140628.3 150178.0 150461.0 114238.0 116854.0 118495.0 118972.0 119279.4 8855.7 8869.5 8870.2 8874.2 9095.4 64488.7 65421.7 66753.3 66938.0 67369.7 97691.5 101980.5 102460.5 103546.5 104170.0 11351.4 11778.4 11860.3 11918.3 12141.0 12960.4 13085.9 13538.5 13599.3 13789.4 11819.0 12110.7 12507.5 12515.3 12583.9 11852.9 12199.3 12281.0 12514.9 12566.2 11671.5 11845.7 12019.8 12141.3 12405.0 8881.9 8888.8 8895.1 9184.4 9229.0 6899.8 8183.1 8649.7 9182.0 9732.4 03:20:37 INFO - PROCESS | 1657 | -------- Summary: end -------- 03:20:37 INFO - PROCESS | 1657 | 03:20:38 INFO - TEST-INFO | 1657: exit 0 03:20:38 INFO - TEST-OK | dromaeo_css | took 1113626ms 03:20:38 INFO - TEST-START | kraken 03:20:38 INFO - operating with platform_type : mac_ 03:20:38 INFO - Initialising browser for kraken test... 03:20:38 INFO - TEST-INFO | started process 1665 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/tmphVYCVx/profile http://localhost:49271/getInfo.html) 03:20:41 INFO - PROCESS | 1665 | __metrics Screen width/height:1600/1200 03:20:41 INFO - PROCESS | 1665 | colorDepth:24 03:20:41 INFO - PROCESS | 1665 | Browser inner width/height: 1024/689 03:20:41 INFO - PROCESS | 1665 | __metrics 03:20:41 ERROR - PROCESS | 1665 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2998: TypeError: this.tabs is undefined 03:20:41 INFO - TEST-INFO | 1665: exit 0 03:20:41 INFO - Browser initialized. 03:20:41 INFO - Running cycle 1/1 for kraken test... 03:20:41 INFO - TEST-INFO | started process 1667 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/tmphVYCVx/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:20:54 INFO - PROCESS | 1667 | RSS: Main: 243548160 03:20:54 INFO - PROCESS | 1667 | 03:20:57 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-beat-detection) 03:20:58 INFO - PROCESS | 1667 | RSS: Main: 256589824 03:20:58 INFO - PROCESS | 1667 | 03:21:00 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-dft) 03:21:01 INFO - PROCESS | 1667 | RSS: Main: 265232384 03:21:01 INFO - PROCESS | 1667 | 03:21:04 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-fft) 03:21:05 INFO - PROCESS | 1667 | RSS: Main: 261824512 03:21:05 INFO - PROCESS | 1667 | 03:21:07 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-oscillator) 03:21:08 INFO - PROCESS | 1667 | RSS: Main: 264208384 03:21:08 INFO - PROCESS | 1667 | 03:21:10 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49271/tests/kraken/driver.html?testName=imaging-gaussian-blur) 03:21:10 INFO - PROCESS | 1667 | RSS: Main: 264515584 03:21:10 INFO - PROCESS | 1667 | 03:21:13 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49271/tests/kraken/driver.html?testName=imaging-darkroom) 03:21:14 INFO - PROCESS | 1667 | RSS: Main: 265424896 03:21:14 INFO - PROCESS | 1667 | 03:21:17 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49271/tests/kraken/driver.html?testName=imaging-desaturate) 03:21:17 INFO - PROCESS | 1667 | RSS: Main: 265809920 03:21:17 INFO - PROCESS | 1667 | 03:21:20 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49271/tests/kraken/driver.html?testName=json-parse-financial) 03:21:21 INFO - PROCESS | 1667 | RSS: Main: 266031104 03:21:21 INFO - PROCESS | 1667 | 03:21:23 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49271/tests/kraken/driver.html?testName=json-stringify-tinderbox) 03:21:23 INFO - PROCESS | 1667 | RSS: Main: 265252864 03:21:23 INFO - PROCESS | 1667 | 03:21:25 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-aes) 03:21:26 INFO - PROCESS | 1667 | RSS: Main: 265310208 03:21:26 INFO - PROCESS | 1667 | 03:21:28 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-ccm) 03:21:29 INFO - PROCESS | 1667 | RSS: Main: 265605120 03:21:29 INFO - PROCESS | 1667 | 03:21:32 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 03:21:32 INFO - PROCESS | 1667 | RSS: Main: 265842688 03:21:32 INFO - PROCESS | 1667 | 03:21:35 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 03:21:36 INFO - PROCESS | 1667 | RSS: Main: 267001856 03:21:36 INFO - PROCESS | 1667 | 03:21:38 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49271/tests/kraken/driver.html?testName=ai-astar) 03:21:38 INFO - PROCESS | 1667 | RSS: Main: 268886016 03:21:38 INFO - PROCESS | 1667 | 03:21:38 INFO - PROCESS | 1667 | __start_tp_report 03:21:38 INFO - PROCESS | 1667 | _x_x_mozilla_page_load 03:21:38 INFO - PROCESS | 1667 | _x_x_mozilla_page_load_details 03:21:38 INFO - PROCESS | 1667 | |i|pagename|runs| 03:21:38 INFO - PROCESS | 1667 | |0;ai-astar;108;116;124;142;116;117;119;118;118;135 03:21:38 INFO - PROCESS | 1667 | |1;audio-beat-detection;143;112;139;158;129;133;157;95;158;98 03:21:38 INFO - PROCESS | 1667 | |2;audio-dft;193;205;228;197;222;256;266;269;196;194 03:21:38 INFO - PROCESS | 1667 | |3;audio-fft;66;80;75;148;53;57;87;88;144;64 03:21:38 INFO - PROCESS | 1667 | |4;audio-oscillator;69;76;133;88;102;130;149;68;76;124 03:21:38 INFO - PROCESS | 1667 | |5;imaging-gaussian-blur;94;86;89;86;89;86;134;87;88;126 03:21:38 INFO - PROCESS | 1667 | |6;imaging-darkroom;95;95;95;94;95;95;96;118;154;118 03:21:38 INFO - PROCESS | 1667 | |7;imaging-desaturate;98;92;92;90;95;91;92;96;137;151 03:21:38 INFO - PROCESS | 1667 | |8;json-parse-financial;56;58;55;58;59;57;60;57;58;70 03:21:38 INFO - PROCESS | 1667 | |9;json-stringify-tinderbox;65;69;70;72;76;82;74;72;71;73 03:21:38 INFO - PROCESS | 1667 | |10;stanford-crypto-aes;91;88;111;101;89;84;87;88;94;94 03:21:38 INFO - PROCESS | 1667 | |11;stanford-crypto-ccm;149;133;162;132;143;164;165;167;181;145 03:21:38 INFO - PROCESS | 1667 | |12;stanford-crypto-pbkdf2;181;181;181;181;248;182;181;181;186;229 03:21:38 INFO - PROCESS | 1667 | |13;stanford-crypto-sha256-iterative;69;71;74;68;71;71;68;70;70;67 03:21:38 INFO - PROCESS | 1667 | __end_tp_report 03:21:38 INFO - PROCESS | 1667 | __start_cc_report 03:21:38 INFO - PROCESS | 1667 | _x_x_mozilla_cycle_collect,952 03:21:38 INFO - PROCESS | 1667 | __end_cc_report 03:21:38 INFO - PROCESS | 1667 | __startTimestamp1473070898490__endTimestamp 03:21:38 INFO - PROCESS | 1667 | 03:21:38 INFO - PROCESS | 1667 | ------- Summary: start ------- 03:21:38 INFO - PROCESS | 1667 | Number of tests: 14 03:21:38 INFO - PROCESS | 1667 | 03:21:38 INFO - PROCESS | 1667 | [#0] ai-astar Cycles:10 Average:121.30 Median:118.50 stddev:10.01 (8.4%) stddev-sans-first:9.39 03:21:38 INFO - PROCESS | 1667 | Values: 108.0 116.0 124.0 142.0 116.0 117.0 119.0 118.0 118.0 135.0 03:21:38 INFO - PROCESS | 1667 | 03:21:38 INFO - PROCESS | 1667 | [#1] audio-beat-detection Cycles:10 Average:132.20 Median:157.50 stddev:23.76 (15.1%) stddev-sans-first:24.88 03:21:38 INFO - PROCESS | 1667 | Values: 143.0 112.0 139.0 158.0 129.0 133.0 157.0 95.0 158.0 98.0 03:21:38 INFO - PROCESS | 1667 | 03:21:38 INFO - PROCESS | 1667 | [#2] audio-dft Cycles:10 Average:222.60 Median:225.00 stddev:30.82 (13.7%) stddev-sans-first:30.77 03:21:38 INFO - PROCESS | 1667 | Values: 193.0 205.0 228.0 197.0 222.0 256.0 266.0 269.0 196.0 194.0 03:21:38 INFO - PROCESS | 1667 | 03:21:38 INFO - PROCESS | 1667 | [#3] audio-fft Cycles:10 Average:86.20 Median:70.50 stddev:33.64 (47.7%) stddev-sans-first:34.88 03:21:38 INFO - PROCESS | 1667 | Values: 66.0 80.0 75.0 148.0 53.0 57.0 87.0 88.0 144.0 64.0 03:21:38 INFO - PROCESS | 1667 | 03:21:38 INFO - PROCESS | 1667 | [#4] audio-oscillator Cycles:10 Average:101.50 Median:68.50 stddev:30.24 (44.1%) stddev-sans-first:29.70 03:21:38 INFO - PROCESS | 1667 | Values: 69.0 76.0 133.0 88.0 102.0 130.0 149.0 68.0 76.0 124.0 03:21:38 INFO - PROCESS | 1667 | 03:21:38 INFO - PROCESS | 1667 | [#5] imaging-gaussian-blur Cycles:10 Average:96.50 Median:87.50 stddev:17.91 (20.5%) stddev-sans-first:18.98 03:21:38 INFO - PROCESS | 1667 | Values: 94.0 86.0 89.0 86.0 89.0 86.0 134.0 87.0 88.0 126.0 03:21:38 INFO - PROCESS | 1667 | 03:21:38 INFO - PROCESS | 1667 | [#6] imaging-darkroom Cycles:10 Average:105.50 Median:95.00 stddev:19.55 (20.6%) stddev-sans-first:20.36 03:21:38 INFO - PROCESS | 1667 | Values: 95.0 95.0 95.0 94.0 95.0 95.0 96.0 118.0 154.0 118.0 03:21:38 INFO - PROCESS | 1667 | 03:21:38 INFO - PROCESS | 1667 | [#7] imaging-desaturate Cycles:10 Average:103.40 Median:92.00 stddev:21.79 (23.7%) stddev-sans-first:23.02 03:21:38 INFO - PROCESS | 1667 | Values: 98.0 92.0 92.0 90.0 95.0 91.0 92.0 96.0 137.0 151.0 03:21:38 INFO - PROCESS | 1667 | 03:21:38 INFO - PROCESS | 1667 | [#8] json-parse-financial Cycles:10 Average:58.80 Median:58.00 stddev:4.18 (7.2%) stddev-sans-first:4.31 03:21:38 INFO - PROCESS | 1667 | Values: 56.0 58.0 55.0 58.0 59.0 57.0 60.0 57.0 58.0 70.0 03:21:38 INFO - PROCESS | 1667 | 03:21:38 INFO - PROCESS | 1667 | [#9] json-stringify-tinderbox Cycles:10 Average:72.40 Median:72.50 stddev:4.50 (6.2%) stddev-sans-first:3.90 03:21:38 INFO - PROCESS | 1667 | Values: 65.0 69.0 70.0 72.0 76.0 82.0 74.0 72.0 71.0 73.0 03:21:38 INFO - PROCESS | 1667 | 03:21:38 INFO - PROCESS | 1667 | [#10] stanford-crypto-aes Cycles:10 Average:92.70 Median:88.50 stddev:8.00 (9.0%) stddev-sans-first:8.46 03:21:38 INFO - PROCESS | 1667 | Values: 91.0 88.0 111.0 101.0 89.0 84.0 87.0 88.0 94.0 94.0 03:21:38 INFO - PROCESS | 1667 | 03:21:38 INFO - PROCESS | 1667 | [#11] stanford-crypto-ccm Cycles:10 Average:154.10 Median:163.00 stddev:16.11 (9.9%) stddev-sans-first:16.98 03:21:38 INFO - PROCESS | 1667 | Values: 149.0 133.0 162.0 132.0 143.0 164.0 165.0 167.0 181.0 145.0 03:21:38 INFO - PROCESS | 1667 | 03:21:38 INFO - PROCESS | 1667 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:193.10 Median:181.50 stddev:24.39 (13.4%) stddev-sans-first:25.48 03:21:38 INFO - PROCESS | 1667 | Values: 181.0 181.0 181.0 181.0 248.0 182.0 181.0 181.0 186.0 229.0 03:21:38 INFO - PROCESS | 1667 | 03:21:38 INFO - PROCESS | 1667 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.90 Median:70.50 stddev:2.02 (2.9%) stddev-sans-first:2.12 03:21:38 INFO - PROCESS | 1667 | Values: 69.0 71.0 74.0 68.0 71.0 71.0 68.0 70.0 70.0 67.0 03:21:38 INFO - PROCESS | 1667 | -------- Summary: end -------- 03:21:38 INFO - PROCESS | 1667 | 03:21:39 INFO - TEST-INFO | 1667: exit 0 03:21:39 INFO - TEST-OK | kraken | took 60749ms 03:21:39 INFO - SUITE-END | took 1174s 03:21:39 INFO - Completed test suite (00:19:34) 03:21:39 INFO - javascript benchmark 03:21:39 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7227.535040756116, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3359.596848378195, 3376.729861611071, 3383.154211447138, 3405.761596688232, 3410.9147271318216, 5471.0, 5674.60277780555, 5699.031164702, 5706.315242170935, 5736.541076713864, 5555.999920014396, 5657.66054036757, 5718.571107166968, 5758.136279558058, 5797.0, 5113.0, 5207.218917162418, 5207.895842083156, 5225.477452254782, 5291.761870715827, 5189.740512974355, 5336.4930331618625, 5337.973310133463, 5349.277847490597, 5376.758045887945, 5212.973935130338, 5235.65968212065, 5245.344331958505, 5285.445028272034, 5305.0450918834595, 5096.79612815487, 5149.124648809711, 5189.896202075975, 5204.245384419276, 5267.47325267474, 5103.106956282636, 5127.66670166438, 5144.845654630367, 5202.791888324463, 5223.399309079467, 4358.3244597087505, 5533.861653458655, 5716.657000579979, 5716.742746576414, 5737.598368114233, 6120.296165940929, 6135.754569817202, 6163.537734669918, 6227.283862355796, 6271.843203919893, 4812.759362031919, 4841.878953026167, 5013.097642424362, 5127.564157046656, 5578.163275508706, 5513.003072772208, 5635.8591035224445, 5785.27684039495, 5815.040518314515, 5815.389384114692, 6206.503479721611, 6211.627302361872, 6216.0, 6269.996800511987, 6286.9056964145575, 5348.983693098299, 5605.355384130796, 5631.1271752878365, 6114.419130182626, 6142.508599312045, 5200.791968321225, 6095.603785753912, 6248.9062664060075, 6259.593126446766, 6277.089821975788, 6139.29398119222, 6153.415425534657, 6161.568690191643, 6210.4410603045035, 6250.9062364064575, 6243.125962365272, 6247.5939063961605, 6260.373962603703, 6280.309165991737, 6281.309056003836, 4802.927956080662, 4911.852644420673, 4934.407871055425, 4943.653944223942, 4954.801807927714, 4335.523092459827, 4339.435873336446, 4379.211741886427, 4414.073044660658, 4486.192485352668, 5689.630174038674, 5768.942310576925, 5772.62477938941, 5774.989376859033, 5778.566607504454, 6098.26820781509, 6179.412955769195, 6189.319174890758, 6206.596571222856, 6210.161628180228, 5684.54523638108, 5706.28671416073, 5759.913601295984, 5762.942370576325, 5789.710514474259, 5376.623636345418, 5384.326959130109, 5384.865378365494, 5399.082156033483, 5423.403425623178, 6247.406496382836, 6306.716197771111, 6307.5584709070845, 6312.652804095727, 6319.905201422074, 6167.013277875519, 6187.288461826947, 6191.938080619317, 6299.4645455135, 6315.842103947438, 6270.529710271657, 6290.716917738621, 6292.433680968735, 6306.46395056407, 6320.241571011324, 6011.7595296187665, 6143.938560614337, 6175.84560385994, 6179.28938172098, 6192.938070619416, 6172.753089876354, 6198.380161983946, 6268.216472940882, 6318.368163183829, 6331.873362532631, 6006.039033754579, 6037.094435834484, 6126.111713801563, 6140.355262697352, 6163.660998645118, 5943.881122377615, 6133.3866613338305, 6133.6319820810895, 6140.9078863816185, 6180.289266734383, 5735.340435849931, 5773.769049237983, 5907.793227237026, 5925.555583331354, 5945.316288626863, 5978.491828194652, 5986.311574169026, 6027.849303767441, 6065.54508411862, 6125.0, 5870.735816888165, 5873.177755114202, 5887.852803679943, 5889.882202355843, 5895.941040589711], "value": 5635.512615850569, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [455.3988734869973, 483.25096101043385, 506.56435465499663, 513.5532087084237, 592.745119598573, 38022.61977380229, 39573.0, 39806.6067687631, 39892.60107398925, 39905.40141897867, 34304.65695343046, 35485.46771798419, 40924.38613420801, 41084.0, 41969.37045944313, 7639.923600763991, 8303.543305118215, 9696.12734853863, 9717.805643887117, 9770.609175633002, 9566.904330956706, 9690.612375504972, 9698.806023879519, 9700.175485083777, 9708.951455242714, 9560.187384072362, 9651.565679544403, 9683.225341972626, 9694.709158725249, 9721.902780972207, 9663.371880827724, 9667.27495437845, 9692.079252471003, 9728.367656102331, 9737.026297370207, 9585.329026968115, 9630.759231019281, 9662.855057174149, 9667.951660241653, 9675.903240967571, 39548.0, 39860.402093968616, 39866.003349916195, 39914.40128398076, 40036.39945400821, 3284.0640417480904, 3324.8670053197843, 3346.665333466634, 3357.8656853726065, 3402.188327265678, 29789.95735149281, 30355.392892142256, 30605.69394306073, 30745.846270768725, 31270.530942035886, 3208.6630903755236, 3216.035189443181, 3248.7888287261253, 3311.3046260285128, 3335.533025376449, 384591.0, 462334.94822836405, 505285.0, 509008.0, 510219.0, 32469.51295730566, 33092.669073309444, 57473.850522989305, 57702.134467983014, 57709.0, 32509.0, 34586.48120278198, 34591.4811277831, 34689.82655086708, 34714.30571388609, 14238.43046278158, 14326.49857254991, 14373.209473479059, 14384.424623014962, 14411.85588144126, 12129.150959432754, 12221.144519883523, 12236.81644775329, 12250.571230006906, 12280.692982675504, 7440.73957411488, 7602.961985190039, 7617.6572054257695, 7667.923320766832, 7686.000819893378, 3153.810771353726, 3187.1394723424546, 3206.9839650801596, 3219.211293233173, 3221.371832492688, 904.6200595749801, 934.5140526926097, 1050.2490719135856, 1081.3944191252924, 1117.4803716271972, 29304.7069529302, 29446.85276573646, 30177.547336789965, 30233.0, 31030.379392412477, 815.1685281013333, 969.8060387922444, 1004.9397036177853, 1038.8857225705167, 1083.777825545777, 14961.326740296712, 15534.1456219909, 15582.195204863006, 15653.452129175424, 15768.290426930816, 656880.0, 673595.0, 673631.0, 675040.0, 677511.0, 57137.0, 57302.7134864323, 57797.55506112381, 57869.0, 57998.420015799304, 1329.2755448280711, 1342.536824795444, 1373.7458570164351, 1376.5870238928483, 1376.876081152701, 28578.0, 29501.96743113981, 29571.0, 30129.698703012687, 30174.9438769642, 48794.51205488042, 49061.0, 49068.75465622649, 49243.753781230866, 49397.0, 440310.0, 485884.0, 487752.0, 488357.0, 495853.0, 24598.754012459645, 25753.871230643726, 26138.215853524423, 26611.7338826617, 26715.732842671325, 43165.568344316154, 45790.77104614455, 46636.300455492514, 47189.0, 47211.76394118008, 37125.814370927976, 38123.2375352497, 38202.80898595489, 38277.61722382741, 39333.40999885061, 36209.63790362063, 37918.431223531115, 38208.28443734261, 38344.6165538341, 38756.0, 30521.542176866922, 31947.0, 37179.07052323713, 37798.62201378061, 39300.410493843185, 26272.474550509265, 32697.0, 33423.0, 34401.827990859885, 34635.65364346324, 21823.563528729654, 22491.212807551656, 23141.0, 25313.0, 26360.34099147537], "value": 18170.986601346023, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [314.3132256020597, 368.0541009605314, 372.62923391225735, 373.6450372146461, 394.1191437137996, 20094.6985795213, 20321.695174572375, 20365.79634203661, 20897.164113435483, 21112.4721881953, 20445.386638400836, 20701.378958631267, 20973.580528389462, 21266.574668506622, 21282.89358553209, 5290.444503327153, 5919.911201331984, 6032.879342413149, 6086.6043707159115, 6157.568970172089, 6026.789062382795, 6136.263648362181, 6139.017757158833, 6141.692915354237, 6157.846053848645, 5740.885182296352, 5788.42115788422, 5976.5218782497295, 6026.276846778371, 6038.33578306386, 5838.9124163137585, 5954.4938680212235, 5969.343372229051, 5995.430434108753, 6038.69806509675, 5903.468687818074, 5967.850803729898, 6040.244969378828, 6041.0, 6067.423594758491, 12855.614331569974, 13189.670258243525, 13221.801672974912, 13257.867421325856, 13258.867411325957, 2390.8087353011715, 2394.1740099665763, 2401.447667036591, 2629.9868500657567, 2635.749603787637, 9388.483633400161, 9986.950065249628, 10210.540525676362, 10494.527746251437, 10528.526216320208, 1819.1177279019664, 1852.2683540001624, 1895.3082125024405, 1907.7519922410136, 1988.3040935672543, 116795.0, 120430.79569204284, 125118.0, 125187.0, 125401.0, 9200.401973871676, 11206.887931120747, 11435.656930292105, 11510.654680359603, 11749.823752643717, 14186.219757913208, 14322.0, 14331.0, 14359.497417590333, 14398.928005359907, 8101.837963240702, 8321.750347489586, 8375.539345336065, 8474.525444567698, 8481.57592120404, 6548.377904099103, 8165.346772258205, 8184.836303273901, 8204.466709663971, 8340.207680270365, 4414.3157810539415, 4443.488998765119, 4447.555244475591, 4452.755098469615, 4453.710508816916, 1893.829555340026, 1943.2518480385154, 1948.8733232339853, 1950.0639692947182, 1951.5316324082119, 842.3640151685438, 907.6913849291273, 922.9261659067258, 927.1701826864918, 941.9670311539201, 20639.69040464394, 21084.05121769524, 21125.68311475329, 21285.36143915685, 21362.465938351354, 700.4431476975816, 836.2766207230702, 843.8397203844713, 893.5710858787688, 937.95310234488, 10668.359898406121, 11286.7178320541, 11303.773924521463, 11332.943335283271, 11356.886431135748, 499216.0, 499528.0, 505105.0, 595964.0, 597552.0, 98692.0, 102615.0, 133467.0, 139328.30335848255, 149398.25300873426, 775.5075527040257, 884.8186121845006, 995.5713551054116, 1001.8829005658638, 1033.503918119292, 10861.67414977552, 11201.621195331958, 11582.478788454708, 11744.29534227949, 19897.502562436053, 38092.523985940825, 50324.49675503198, 50411.0, 50655.0, 51630.54618172728, 65598.01602976055, 65698.67150664216, 68267.0, 69205.65397172981, 70370.94443583452, 12371.05197712012, 12602.0, 12609.495620175458, 12981.610551683465, 13007.219566826021, 13743.793843092162, 13775.0, 13856.722865542833, 14133.293335332986, 14311.928440357731, 9212.0, 9817.803643926938, 11833.585824496102, 11926.165168438127, 12013.63959081229, 10124.34191777532, 11432.942835286103, 11469.942650286695, 11786.410679466164, 12196.75606487883, 11779.823302650639, 11791.587294444653, 12105.576304829288, 12110.636680899586, 12214.083943704436, 12327.445264962918, 12353.691157721129, 12654.620361389174, 12687.302198379179, 12796.36018199105, 11755.000824929977, 11818.054555635357, 12073.154879158727, 12168.75662486763, 12257.877421225674], "value": 9507.85284971915, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1457.2276693352521, 1502.444095684597, 1713.1348668922192, 1816.7918334307685, 1877.8403835673967, 6412.877746394374, 7075.823104422386, 7524.645563798514, 8227.218414250636, 8810.867836982443, 7316.865885787698, 8196.13940536244, 8854.335924805633, 8929.196372326489, 8933.553322333873, 2386.331827088418, 2424.3333083402117, 2426.7694569016003, 2426.805855531562, 2430.173740928084, 1967.2721093195537, 1990.7810140884565, 2336.929892103231, 2419.6733440985417, 2425.454272788626, 2386.1648423051965, 2401.171595799455, 2407.5425669122897, 2427.7208121066037, 2430.052279610953, 2381.1784934197676, 2393.5930891748444, 2400.31590996566, 2401.939951501209, 2415.9637605435933, 2358.811295096388, 2363.7636236376225, 2375.1449478187837, 2388.4387169015267, 2393.270052633946, 8920.464772113693, 8955.462672239686, 9011.954940225258, 9018.143276388733, 9059.411138275991, 5283.128283833163, 5415.918761218585, 5451.836444906619, 5458.808941687062, 5471.452854714536, 3626.528551288342, 3663.230721548478, 3683.6684698377007, 3717.126475278307, 3732.701383889282, 1066.4188017530464, 1068.3536460441399, 1071.5606601293432, 1071.7642118733866, 1076.6231818863414, 9784.706458806248, 11740.941295293555, 11763.117766167486, 11810.88189118115, 11849.763004739943, 3968.8015599219925, 4587.564181402761, 4719.244920812654, 4819.469858315582, 4896.828610998669, 9929.751756206153, 10016.599336026624, 10042.046005629454, 10055.497225138713, 10066.59733610662, 5386.569074474032, 5417.729113544307, 5427.348718153847, 5435.945640543544, 5476.151196564539, 4472.418585583853, 4800.303955926371, 4934.062528119646, 4950.9009819803405, 4987.401511818605, 3266.0691702865047, 3370.4775759757276, 3494.7204223662047, 3668.4313931340153, 4050.5139383274195, 1297.0531511996296, 1305.6213698027655, 1306.6341424401182, 1314.3953781260536, 1317.775978083728, 753.3784627682185, 846.4667259626395, 898.3576742629051, 912.6075787411344, 938.7136923238478, 4826.8793280168275, 5703.298624116646, 5769.509591684669, 6071.1465611162685, 6092.208012958287, 908.028409601733, 934.9762010598384, 938.9108034736826, 949.6486300068881, 959.8752162219007, 3206.294615184656, 3769.7738135711943, 3997.9400308995387, 4104.281750693616, 4122.3404255319, 9894.15899648538, 11004.944975275072, 11019.898010199076, 11085.390303533382, 11130.666080017612, 10799.67600971972, 10833.404162771123, 10885.945570272257, 11009.504572294267, 11019.063379612822, 8172.550509721904, 8282.668693252203, 8413.621387037598, 8427.157284271523, 8432.325413966746, 4835.95164048355, 5565.248691426767, 5795.82612521625, 5894.852628684317, 5945.554083443672, 9982.450965196984, 10085.991400859855, 10100.141487973311, 10263.7434064149, 10278.17774578047, 9523.904760952302, 9851.359661621971, 10091.69724908254, 10345.27583069208, 10364.429956352473, 5873.295204575478, 6959.686814093479, 7359.043324367798, 7642.5032372895585, 7644.579548124907, 7505.924940750522, 8368.665253389796, 8545.658173672984, 8564.957175214084, 8571.571421428778, 6564.901526477011, 7275.090613673291, 7667.731629392944, 7673.30940215361, 7710.306072453282, 7500.699972001059, 7517.962410187914, 7528.51064680794, 7536.434767392358, 7630.580318082559, 6906.274841141809, 6910.689018994057, 6970.51206415546, 6973.825654358681, 7012.123484564429, 4949.381327334083, 5032.874178145576, 5071.366079240095, 5080.796768129233, 5141.408737995179, 4928.0, 5015.598752099896, 5093.083245015932, 5141.511556402136, 5237.738113094406], "value": 4572.081815233376, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [255.4979465350586, 301.1672724915607, 309.84198058989915, 312.7591754349151, 315.6874694052888, 5255.632105752599, 5386.0, 5552.555795536357, 5662.8301150965435, 5674.233978412913, 4797.544233297831, 5561.749721262543, 5633.436656334365, 5728.68492232928, 5731.627444216123, 4115.053537686326, 4371.497277813044, 4374.9781251093855, 4380.759058251795, 4384.802683879233, 3957.6635985941084, 4137.710360274782, 4226.936595951063, 4251.787410629471, 4351.521332653406, 3863.594322596129, 4252.72418274518, 4268.445102136718, 4298.269294219975, 4310.719803212797, 4287.914241715148, 4294.785260736966, 4303.225419424502, 4324.589164029412, 4340.327249276335, 3604.3331983582857, 3742.906427339311, 3749.981250093759, 3751.4560388743753, 3756.530433695788, 3870.4581358609817, 4627.190241707722, 4766.571008609207, 5230.3524389817085, 5435.021696094701, 4115.835366585333, 4642.187617166982, 4973.880876802727, 4989.850304490871, 4999.750062484379, 4694.08465349257, 5201.375834899798, 5622.550195984311, 5962.284525856881, 6046.486048685867, 3376.763626546143, 3503.597086335053, 3576.302620988908, 3857.40210267409, 4055.8174882130374, 5230.450802665703, 6322.241331040304, 6353.506925872416, 6358.395952384516, 6372.745090196387, 4034.3343348347194, 4154.462848745983, 4172.457580514574, 4179.895502612459, 4194.3498757692, 3471.062813040465, 3548.361294966931, 4178.059936514321, 4195.853145139906, 4240.745555266694, 4524.773761311987, 4539.504581472248, 4942.566655669887, 4942.728149951715, 4950.282209079735, 5241.580673546107, 5476.26070480488, 5653.66078035311, 5894.204282421818, 6080.878382432326, 1371.8079468874366, 1411.9647008824656, 1428.8356838963443, 1442.2572375226766, 1449.145004447381, 846.9074893387584, 851.284920666643, 878.6644300662956, 885.1193062902453, 889.5596679643618, 4320.746983374786, 4554.681172317973, 4595.770211489412, 4634.86095417138, 4748.246017310021, 4659.6505262105475, 4773.54406905897, 4893.8988727536735, 5176.696370115263, 5179.974100129475, 3993.843324604739, 4672.415948006499, 4720.291956206528, 4731.858044258678, 4738.715677059387, 2578.123438031041, 2830.886764529396, 2935.882564697431, 3025.455418024733, 3259.315543735842, 5540.418256083134, 6416.839579010562, 6464.22429308486, 6468.579542329733, 6484.902726459107, 5360.571154307646, 5572.944270557243, 6163.668367194683, 6272.874542509125, 6380.649064301508, 4934.235193545006, 5176.938727560841, 5989.981703110481, 6192.080455058946, 6429.2962364974255, 4972.378452693413, 5111.667741596784, 5716.085111487532, 6083.574149809471, 6209.782657606962, 5456.380937150269, 5611.887762244651, 6039.214902062704, 6137.754489820357, 6150.077488376779, 5364.409914909279, 6079.0273556230795, 6414.317548189754, 6415.871682566416, 6430.0, 3956.327424337812, 3966.8611598595066, 4114.588541145861, 4158.771267580312, 4248.065425606362, 5096.51583099605, 5925.229720136354, 6092.69536523181, 6101.206843110367, 6127.662978536223, 5868.970655146868, 6013.969930150496, 6016.510423954183, 6038.5169186465855, 6049.274087109575, 5681.659100453986, 5960.195373624679, 5972.850678733066, 5989.940100599112, 5990.7903223386975, 5066.4680208577565, 5228.607854410859, 5713.314402271754, 5867.735951882261, 5940.495057919952, 5237.214417837355, 5735.967525845388, 5991.820245392645, 6020.916235077728, 6028.939710603014, 4871.318015477765, 5053.772580233825, 5813.593048486566, 5821.0, 5888.882222355443], "value": 4224.293358488378, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [729.9525530840496, 733.757859906231, 763.351151521207, 793.7380664380747, 857.5540718826211, 12612.684682882933, 14036.719265614682, 14790.260486975634, 14950.40198392063, 14958.626034349147, 14613.269336533183, 14622.707545849076, 14740.6314842129, 14742.26288685564, 14857.182854942974, 830.1947111302028, 850.5874650794391, 851.1403482482706, 853.5092321914892, 856.1010938514535, 672.4351544702449, 827.1769589258696, 837.874318852171, 841.2554888923286, 845.2012847858776, 879.0681877210146, 881.1276835932413, 888.6534251641865, 889.2174886100223, 891.4651209274448, 872.7861673889875, 876.0451108291928, 877.1842186766305, 877.1842186766337, 879.6657270237333, 649.8570314530796, 656.7766959233884, 657.3065415986133, 660.4584240922445, 664.8969409741497, 14860.256987150708, 14991.250437478191, 15022.924885375613, 15022.924885375613, 15030.549083527512, 14128.717425651532, 14499.42002319906, 14549.272536373139, 14599.78100328496, 14857.0, 11934.940325298317, 11983.520659173622, 12001.51993920242, 12015.399230038463, 12028.954546132038, 3643.4899114123778, 3795.734298599099, 3799.0882188274613, 3929.0766669832565, 3934.0, 99337.50331248369, 102113.0, 104556.0, 113009.99999999917, 115142.0, 10798.0, 10897.237193396386, 11082.501287442085, 11246.550137994553, 11354.829677554842, 20487.28294509685, 21379.358619241448, 21761.238356657443, 21798.45503862416, 21819.563608727738, 5959.761609535657, 6883.346082122191, 6976.5814051157095, 7523.9623801881735, 7598.784194528849, 3825.598312177237, 4348.326009468537, 4354.433923589976, 4375.496817865923, 4412.5659160772875, 3313.122022663996, 3449.3618680544178, 3489.616142224353, 3502.141975216088, 3515.050936247199, 559.8208573256519, 649.8148027812051, 672.1497305908031, 675.864827034595, 676.7293082766835, 539.8515408262681, 609.439315829438, 646.7089809585706, 657.8223879552494, 677.3598948993155, 6960.79117626472, 7580.3177714004905, 9213.631454741735, 9289.349745517884, 9486.762830929281, 552.3316786688072, 562.9211910332557, 627.5481653209682, 630.2090875950744, 642.7718160053207, 4404.955950440519, 4454.287314029804, 4624.768761561976, 4679.321498382784, 4689.882752931204, 150528.2473587647, 151620.0, 154188.229058854, 154401.0, 160390.0, 98495.0, 138723.30638346946, 140628.29685851507, 150178.0, 150461.0, 114238.0, 116854.0, 118495.0, 118972.0, 119279.40360298143, 8855.690050848189, 8869.467831930106, 8870.2460290876, 8874.156955089256, 9095.363324567217, 64488.71022579616, 65421.67289163524, 66753.3324666747, 66938.0, 67369.66315168393, 97691.51154244183, 101980.49009754903, 102460.48769756104, 103546.50348951003, 104170.0, 11351.413091519556, 11778.352190629255, 11860.288382697066, 11918.344491052729, 12141.028717702739, 12960.416781244678, 13085.869141308845, 13538.526151585042, 13599.320033998458, 13789.448422063006, 11819.0, 12110.697232569257, 12507.499700012262, 12515.311657858905, 12583.937080314907, 11852.881471185177, 12199.329036903055, 12281.0, 12514.937425312815, 12566.246025238515, 11671.474783634927, 11845.703857403634, 12019.819702704292, 12141.271523708605, 12405.0, 8881.866771998555, 8888.777780555538, 8895.06601806801, 9184.448933064037, 9229.0, 6899.793006209822, 8183.058948221031, 8649.740507784776, 9181.989981102208, 9732.41605503672], "value": 7579.448253941596, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1610.2, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [108.0, 116.0, 124.0, 142.0, 116.0, 117.0, 119.0, 118.0, 118.0, 135.0], "value": 121.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 112.0, 139.0, 158.0, 129.0, 133.0, 157.0, 95.0, 158.0, 98.0], "value": 132.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [193.0, 205.0, 228.0, 197.0, 222.0, 256.0, 266.0, 269.0, 196.0, 194.0], "value": 222.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 80.0, 75.0, 148.0, 53.0, 57.0, 87.0, 88.0, 144.0, 64.0], "value": 86.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 76.0, 133.0, 88.0, 102.0, 130.0, 149.0, 68.0, 76.0, 124.0], "value": 101.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 86.0, 89.0, 86.0, 89.0, 86.0, 134.0, 87.0, 88.0, 126.0], "value": 96.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 94.0, 95.0, 95.0, 96.0, 118.0, 154.0, 118.0], "value": 105.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [98.0, 92.0, 92.0, 90.0, 95.0, 91.0, 92.0, 96.0, 137.0, 151.0], "value": 103.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 58.0, 55.0, 58.0, 59.0, 57.0, 60.0, 57.0, 58.0, 70.0], "value": 58.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 69.0, 70.0, 72.0, 76.0, 82.0, 74.0, 72.0, 71.0, 73.0], "value": 72.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 88.0, 111.0, 101.0, 89.0, 84.0, 87.0, 88.0, 94.0, 94.0], "value": 92.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 133.0, 162.0, 132.0, 143.0, 164.0, 165.0, 167.0, 181.0, 145.0], "value": 154.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [181.0, 181.0, 181.0, 181.0, 248.0, 182.0, 181.0, 181.0, 186.0, 229.0], "value": 193.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 71.0, 74.0, 68.0, 71.0, 71.0, 68.0, 70.0, 70.0, 67.0], "value": 69.9, "unit": "score"}]}]} 03:21:39 INFO - Return code: 0 03:21:39 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 03:21:39 ERROR - # TBPL SUCCESS # 03:21:39 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 03:21:39 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 03:21:39 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 03:21:39 INFO - 2016-09-05 03:21:39.808 screenresolution[1672:10583] starting screenresolution argv=screenresolution get 03:21:39 INFO - 2016-09-05 03:21:39.810 screenresolution[1672:10583] Display 0: 1600x1200x32@60 03:21:39 INFO - 2016-09-05 03:21:39.816 screenresolution[1673:10586] starting screenresolution argv=screenresolution list 03:21:39 INFO - Available Modes on Display 0 03:21:39 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 03:21:39 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 03:21:39 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 03:21:39 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 03:21:40 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 03:21:40 INFO - Intel Iris: 03:21:40 INFO - Chipset Model: Intel Iris 03:21:40 INFO - Type: GPU 03:21:40 INFO - Bus: Built-In 03:21:40 INFO - VRAM (Dynamic, Max): 1536 MB 03:21:40 INFO - Vendor: Intel (0x8086) 03:21:40 INFO - Device ID: 0x0a2e 03:21:40 INFO - Revision ID: 0x0009 03:21:40 INFO - Displays: 03:21:40 INFO - Display: 03:21:40 INFO - Resolution: 1600 x 1200 @ 60 Hz 03:21:40 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 03:21:40 INFO - Display Serial Number: 1600x1200 60 03:21:40 INFO - Main Display: Yes 03:21:40 INFO - Mirror: Off 03:21:40 INFO - Online: Yes 03:21:40 INFO - Rotation: Supported 03:21:40 INFO - Return code: 0 03:21:40 INFO - Running post-action listener: _resource_record_post_action 03:21:40 INFO - [mozharness: 2016-09-05 10:21:40.059017Z] Finished run-tests step (success) 03:21:40 INFO - Running post-run listener: _resource_record_post_run 03:21:40 INFO - Total resource usage - Wall time: 1209s; CPU: 55.0%; Read bytes: 31951872; Write bytes: 705520640; Read time: 194; Write time: 2021 03:21:40 INFO - TinderboxPrint: CPU usage
54.5% 03:21:40 INFO - TinderboxPrint: I/O read bytes / time
31,951,872 / 194 03:21:40 INFO - TinderboxPrint: I/O write bytes / time
705,520,640 / 2,021 03:21:40 INFO - TinderboxPrint: CPU idle
2,199.4 (45.5%) 03:21:40 INFO - TinderboxPrint: CPU system
979.6 (20.3%) 03:21:40 INFO - TinderboxPrint: CPU user
1,658.7 (34.3%) 03:21:40 INFO - TinderboxPrint: Swap in / out
433,426,432 / 0 03:21:40 INFO - install - Wall time: 35s; CPU: 54.0%; Read bytes: 394109440; Write bytes: 387511808; Read time: 29708; Write time: 820 03:21:40 INFO - run-tests - Wall time: 1176s; CPU: 55.0%; Read bytes: 30194688; Write bytes: 297192960; Read time: 180; Write time: 1150 03:21:40 INFO - Running post-run listener: _upload_blobber_files 03:21:40 INFO - Blob upload gear active. 03:21:40 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 03:21:40 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 03:21:40 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', 'Autoland', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 03:21:40 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 Autoland -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 03:21:40 INFO - (blobuploader) - INFO - Open directory for files ... 03:21:40 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 03:21:40 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:21:40 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:21:42 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 03:21:42 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:21:42 INFO - (blobuploader) - INFO - Done attempting. 03:21:42 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 03:21:42 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:21:42 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:21:42 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 03:21:42 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:21:42 INFO - (blobuploader) - INFO - Done attempting. 03:21:42 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 03:21:42 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:21:42 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:21:45 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 03:21:45 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:21:45 INFO - (blobuploader) - INFO - Done attempting. 03:21:45 INFO - (blobuploader) - INFO - Iteration through files over. 03:21:45 INFO - Return code: 0 03:21:45 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 03:21:45 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 03:21:45 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/d72f92a5d7a3d336f0274448cd530d5e1b31c6593e0c2df8ee59ed9bab35514f10784527f1f9d9592080d170d8b16c0e187151b59cc37683478db2e40134c065", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/f4161e5e8e7173e1339fe49930de55b5354961f99322d7944bd04979625b42665a742be9fa25eae115122a016bccaf8c0711f5316e778c886adc456a1ef1c627", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/2cd3c0110b2449f052e20345f21a212bad21cc20a118cd2dd2bf2a7c85077be64984e6d8f8da2260d8f33f77436e0d35cccb904584798517a6f0dcff4461a0d2"} 03:21:45 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 03:21:45 INFO - Writing to file /builds/slave/test/properties/blobber_files 03:21:45 INFO - Contents: 03:21:45 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/d72f92a5d7a3d336f0274448cd530d5e1b31c6593e0c2df8ee59ed9bab35514f10784527f1f9d9592080d170d8b16c0e187151b59cc37683478db2e40134c065", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/f4161e5e8e7173e1339fe49930de55b5354961f99322d7944bd04979625b42665a742be9fa25eae115122a016bccaf8c0711f5316e778c886adc456a1ef1c627", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/2cd3c0110b2449f052e20345f21a212bad21cc20a118cd2dd2bf2a7c85077be64984e6d8f8da2260d8f33f77436e0d35cccb904584798517a6f0dcff4461a0d2"} 03:21:45 INFO - Running post-run listener: copy_logs_to_upload_dir 03:21:45 INFO - Copying logs to upload dir... 03:21:45 INFO - mkdir: /builds/slave/test/build/upload/logs 03:21:45 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1270.576769 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 10 secs) (at 2016-09-05 03:21:45.546364) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-05 03:21:45.549620) ========= 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.4unqib97vF/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.0vOC3nxODO/Listeners TMPDIR=/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/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/Autoland/sha512/d72f92a5d7a3d336f0274448cd530d5e1b31c6593e0c2df8ee59ed9bab35514f10784527f1f9d9592080d170d8b16c0e187151b59cc37683478db2e40134c065", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/f4161e5e8e7173e1339fe49930de55b5354961f99322d7944bd04979625b42665a742be9fa25eae115122a016bccaf8c0711f5316e778c886adc456a1ef1c627", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/2cd3c0110b2449f052e20345f21a212bad21cc20a118cd2dd2bf2a7c85077be64984e6d8f8da2260d8f33f77436e0d35cccb904584798517a6f0dcff4461a0d2"} build_url:https://queue.taskcluster.net/v1/task/TpkaSgRSRPO5RFZeVuHFkw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011324 build_url: 'https://queue.taskcluster.net/v1/task/TpkaSgRSRPO5RFZeVuHFkw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/d72f92a5d7a3d336f0274448cd530d5e1b31c6593e0c2df8ee59ed9bab35514f10784527f1f9d9592080d170d8b16c0e187151b59cc37683478db2e40134c065", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/f4161e5e8e7173e1339fe49930de55b5354961f99322d7944bd04979625b42665a742be9fa25eae115122a016bccaf8c0711f5316e778c886adc456a1ef1c627", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/2cd3c0110b2449f052e20345f21a212bad21cc20a118cd2dd2bf2a7c85077be64984e6d8f8da2260d8f33f77436e0d35cccb904584798517a6f0dcff4461a0d2"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-05 03:21:45.585997) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-05 03:21:45.586311) ========= 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.4unqib97vF/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.0vOC3nxODO/Listeners TMPDIR=/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/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.005231 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-05 03:21:45.635367) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-05 03:21:45.635700) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-05 03:21:45.636057) ========= ========= Total master_lag: 0.34 =========