builder: autoland_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0062 starttime: 1475360376.38 results: success (0) buildid: 20161001140154 builduid: a8d642069ee54614b3012c9c686d4721 revision: 7c576fe3279d87543f0a03b844eba7bc215e17f1 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-10-01 15:19:36.382406) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-10-01 15:19:36.387449) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-01 15:19:36.387742) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-01 15:19:36.433232) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-01 15:19:36.433578) ========= 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.T0A3Ghbg0c/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.dt5Iifp37T/Listeners TMPDIR=/var/folders/t6/qpy_kfds3kz26lvtw39n2d7m00000w/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-10-01 15:19:36-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 8.07M=0.001s 2016-10-01 15:19:36 (8.07 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.441492 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-01 15:19:36.900697) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-01 15:19:36.901000) ========= 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.T0A3Ghbg0c/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.dt5Iifp37T/Listeners TMPDIR=/var/folders/t6/qpy_kfds3kz26lvtw39n2d7m00000w/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.020564 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-01 15:19:36.952711) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-01 15:19:36.953037) ========= bash -c 'python archiver_client.py mozharness --repo integration/autoland --rev 7c576fe3279d87543f0a03b844eba7bc215e17f1 --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 7c576fe3279d87543f0a03b844eba7bc215e17f1 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.T0A3Ghbg0c/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.dt5Iifp37T/Listeners TMPDIR=/var/folders/t6/qpy_kfds3kz26lvtw39n2d7m00000w/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-10-01 15:19:37,027 truncating revision to first 12 chars 2016-10-01 15:19:37,027 Setting DEBUG logging. 2016-10-01 15:19:37,027 attempt 1/10 2016-10-01 15:19:37,027 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/7c576fe3279d?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-10-01 15:19:38,172 unpacking tar archive at: autoland-7c576fe3279d/testing/mozharness/ program finished with exit code 0 elapsedTime=1.389272 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-01 15:19:38.365304) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-01 15:19:38.365712) ========= script_repo_revision: 7c576fe3279d87543f0a03b844eba7bc215e17f1 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-01 15:19:38.366388) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-01 15:19:38.366822) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-01 15:19:38.385911) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2016-10-01 15:19:38.386395) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --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', '--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.T0A3Ghbg0c/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.dt5Iifp37T/Listeners TMPDIR=/var/folders/t6/qpy_kfds3kz26lvtw39n2d7m00000w/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 15:19:38 INFO - MultiFileLogger online at 20161001 15:19:38 in /builds/slave/test 15:19:38 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland 15:19:38 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 15:19:38 INFO - {'append_to_log': False, 15:19:38 INFO - 'base_work_dir': '/builds/slave/test', 15:19:38 INFO - 'blob_upload_branch': 'Autoland', 15:19:38 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 15:19:38 INFO - 'branch': 'Autoland', 15:19:38 INFO - 'buildbot_json_path': 'buildprops.json', 15:19:38 INFO - 'config_files': ('talos/mac_config.py',), 15:19:38 INFO - 'default_actions': ('clobber', 15:19:38 INFO - 'read-buildbot-config', 15:19:38 INFO - 'download-and-extract', 15:19:38 INFO - 'populate-webroot', 15:19:38 INFO - 'create-virtualenv', 15:19:38 INFO - 'install', 15:19:38 INFO - 'run-tests'), 15:19:38 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 15:19:38 INFO - 'download_minidump_stackwalk': True, 15:19:38 INFO - 'download_symbols': 'ondemand', 15:19:38 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 15:19:38 INFO - 'tooltool.py': '/tools/tooltool.py', 15:19:38 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 15:19:38 INFO - '/tools/misc-python/virtualenv.py')}, 15:19:38 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 15:19:38 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 15:19:38 INFO - 'installer_path': 'installer.exe', 15:19:38 INFO - 'log_level': 'info', 15:19:38 INFO - 'log_name': 'talos', 15:19:38 INFO - 'log_to_console': True, 15:19:38 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 15:19:38 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 15:19:38 INFO - 'opt_config_files': (), 15:19:38 INFO - 'pip_index': False, 15:19:38 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 15:19:38 INFO - 'cmd': ('bash', 15:19:38 INFO - '-c', 15:19:38 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 15:19:38 INFO - 'enabled': True, 15:19:38 INFO - 'halt_on_failure': False, 15:19:38 INFO - 'name': 'check_screen_resolution'},), 15:19:38 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 15:19:38 INFO - 'cmd': ('bash', 15:19:38 INFO - '-c', 15:19:38 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 15:19:38 INFO - 'enabled': True, 15:19:38 INFO - 'halt_on_failure': False, 15:19:38 INFO - 'name': 'check_screen_resolution'},), 15:19:38 INFO - 'run_cmd_checks_enabled': True, 15:19:38 INFO - 'sps_profile': False, 15:19:38 INFO - 'sps_profile_interval': 0, 15:19:38 INFO - 'suite': 'dromaeojs', 15:19:38 INFO - 'system_bits': '32', 15:19:38 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 15:19:38 INFO - 'title': 't-yosemite-r7-0062', 15:19:38 INFO - 'tooltool_cache': '/builds/tooltool_cache', 15:19:38 INFO - 'use_talos_json': True, 15:19:38 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 15:19:38 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 15:19:38 INFO - 'work_dir': 'build'} 15:19:38 INFO - [mozharness: 2016-10-01 22:19:38.590766Z] Running clobber step. 15:19:38 INFO - Running pre-action listener: _resource_record_pre_action 15:19:38 INFO - Running main action method: clobber 15:19:38 INFO - rmtree: /builds/slave/test/build 15:19:38 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 15:19:38 INFO - Running post-action listener: _resource_record_post_action 15:19:38 INFO - [mozharness: 2016-10-01 22:19:38.784552Z] Finished clobber step (success) 15:19:38 INFO - [mozharness: 2016-10-01 22:19:38.784732Z] Running read-buildbot-config step. 15:19:38 INFO - Running pre-action listener: _resource_record_pre_action 15:19:38 INFO - Running main action method: read_buildbot_config 15:19:38 INFO - Using buildbot properties: 15:19:38 INFO - { 15:19:38 INFO - "project": "", 15:19:38 INFO - "product": "firefox", 15:19:38 INFO - "script_repo_revision": "production", 15:19:38 INFO - "builddir": "autoland_yosemite_r7_test-dromaeojs", 15:19:38 INFO - "repository": "", 15:19:38 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 autoland talos dromaeojs", 15:19:38 INFO - "buildid": "20161001140154", 15:19:38 INFO - "pgo_build": "False", 15:19:38 INFO - "basedir": "/builds/slave/test", 15:19:38 INFO - "buildnumber": 337, 15:19:38 INFO - "slavename": "t-yosemite-r7-0062", 15:19:38 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 15:19:38 INFO - "platform": "yosemite_r7", 15:19:38 INFO - "scheduler": "tests-autoland-macosx64-talos", 15:19:38 INFO - "branch": "autoland", 15:19:38 INFO - "repo_path": "integration/autoland", 15:19:38 INFO - "revision": "7c576fe3279d87543f0a03b844eba7bc215e17f1", 15:19:38 INFO - "stage_platform": "macosx64", 15:19:38 INFO - "builduid": "a8d642069ee54614b3012c9c686d4721", 15:19:38 INFO - "slavebuilddir": "test" 15:19:38 INFO - } 15:19:38 INFO - Found installer url https://queue.taskcluster.net/v1/task/Uvk4IryfSfim7_tiAfCcjw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg. 15:19:38 INFO - Running post-action listener: _resource_record_post_action 15:19:38 INFO - [mozharness: 2016-10-01 22:19:38.789993Z] Finished read-buildbot-config step (success) 15:19:38 INFO - [mozharness: 2016-10-01 22:19:38.790162Z] Running download-and-extract step. 15:19:38 INFO - Running pre-action listener: _resource_record_pre_action 15:19:38 INFO - Running main action method: download_and_extract 15:19:38 INFO - mkdir: /builds/slave/test/build/tests 15:19:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:19:38 INFO - trying https://queue.taskcluster.net/v1/task/Uvk4IryfSfim7_tiAfCcjw/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json 15:19:38 INFO - Downloading https://queue.taskcluster.net/v1/task/Uvk4IryfSfim7_tiAfCcjw/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 15:19:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Uvk4IryfSfim7_tiAfCcjw/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json'}, attempt #1 15:19:39 INFO - Downloaded 1199 bytes. 15:19:39 INFO - Reading from file /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 15:19:39 INFO - Using the following test package requirements: 15:19:39 INFO - {u'common': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 15:19:39 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 15:19:39 INFO - u'firefox-52.0a1.en-US.mac.cppunittest.tests.zip'], 15:19:39 INFO - u'gtest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 15:19:39 INFO - u'firefox-52.0a1.en-US.mac.gtest.tests.zip'], 15:19:39 INFO - u'jittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 15:19:39 INFO - u'jsshell-mac.zip'], 15:19:39 INFO - u'mochitest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 15:19:39 INFO - u'firefox-52.0a1.en-US.mac.mochitest.tests.zip'], 15:19:39 INFO - u'mozbase': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 15:19:39 INFO - u'reftest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 15:19:39 INFO - u'firefox-52.0a1.en-US.mac.reftest.tests.zip'], 15:19:39 INFO - u'talos': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 15:19:39 INFO - u'firefox-52.0a1.en-US.mac.talos.tests.zip'], 15:19:39 INFO - u'web-platform': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 15:19:39 INFO - u'firefox-52.0a1.en-US.mac.web-platform.tests.zip'], 15:19:39 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 15:19:39 INFO - u'firefox-52.0a1.en-US.mac.xpcshell.tests.zip']} 15:19:39 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip'] for test suite category: common 15:19:39 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Uvk4IryfSfim7_tiAfCcjw/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 15:19:39 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Uvk4IryfSfim7_tiAfCcjw/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 15:19:39 INFO - Fetch https://queue.taskcluster.net/v1/task/Uvk4IryfSfim7_tiAfCcjw/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 15:19:40 INFO - Http code: 200 15:19:40 INFO - via: 1.1 1b52a5dd431f9e3c81753e61dfdf467a.cloudfront.net (CloudFront) 15:19:40 INFO - x-amz-cf-id: 26DqJ_VAqu6Mr1GKJ4TmGFPuHI1K49eTrhTqMQ0B264wXcZkWJtpFw== 15:19:40 INFO - x-amz-version-id: _Q1J2TCvaFKYCMre1u2oWzpBp43v1iiY 15:19:41 INFO - Expected file size: 16475695 15:19:41 INFO - Obtained file size: 16475695 15:19:48 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip', u'firefox-52.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 15:19:48 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Uvk4IryfSfim7_tiAfCcjw/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 15:19:48 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Uvk4IryfSfim7_tiAfCcjw/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 15:19:48 INFO - Fetch https://queue.taskcluster.net/v1/task/Uvk4IryfSfim7_tiAfCcjw/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 15:19:48 INFO - Http code: 200 15:19:48 INFO - via: 1.1 93ab344e1a3fa44edbf3f1bc5729427a.cloudfront.net (CloudFront) 15:19:48 INFO - x-amz-cf-id: tbI5j3iC9rXKWG_-zTsfPhwoT9rdokp0ZTtgRufM-yUIoBLFK5FMBw== 15:19:48 INFO - x-amz-version-id: _Q1J2TCvaFKYCMre1u2oWzpBp43v1iiY 15:19:49 INFO - Expected file size: 16475695 15:19:49 INFO - Obtained file size: 16475695 15:19:55 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Uvk4IryfSfim7_tiAfCcjw/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip 15:19:55 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Uvk4IryfSfim7_tiAfCcjw/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip'}, attempt #1 15:19:55 INFO - Fetch https://queue.taskcluster.net/v1/task/Uvk4IryfSfim7_tiAfCcjw/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip into memory 15:19:56 INFO - Http code: 200 15:19:56 INFO - via: 1.1 5c2ff4ca1e447265402af29264e83497.cloudfront.net (CloudFront) 15:19:56 INFO - x-amz-cf-id: sMBh-b6Cnql05q9hQsUaDfvihLrGvvq7ldrAODfGamrsX8i0xX0A0g== 15:19:56 INFO - x-amz-version-id: qeHxlS6j1okSxiQ2SyH2F.MnVDyesIbw 15:19:58 INFO - Expected file size: 14408522 15:19:58 INFO - Obtained file size: 14408522 15:19:58 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')]} 15:19:58 INFO - trying https://queue.taskcluster.net/v1/task/Uvk4IryfSfim7_tiAfCcjw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 15:19:58 INFO - Downloading https://queue.taskcluster.net/v1/task/Uvk4IryfSfim7_tiAfCcjw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg 15:19:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Uvk4IryfSfim7_tiAfCcjw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg'}, attempt #1 15:20:01 INFO - Downloaded 111826321 bytes. 15:20:01 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Uvk4IryfSfim7_tiAfCcjw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 15:20:01 INFO - mkdir: /builds/slave/test/properties 15:20:01 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 15:20:01 INFO - Writing to file /builds/slave/test/properties/build_url 15:20:01 INFO - Contents: 15:20:01 INFO - build_url:https://queue.taskcluster.net/v1/task/Uvk4IryfSfim7_tiAfCcjw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 15:20:01 INFO - Running post-action listener: _resource_record_post_action 15:20:01 INFO - Running post-action listener: set_extra_try_arguments 15:20:01 INFO - [mozharness: 2016-10-01 22:20:01.869722Z] Finished download-and-extract step (success) 15:20:01 INFO - [mozharness: 2016-10-01 22:20:01.869848Z] Running populate-webroot step. 15:20:01 INFO - Running pre-action listener: _resource_record_pre_action 15:20:01 INFO - Running main action method: populate_webroot 15:20:01 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 15:20:01 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 15:20:01 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 15:20:01 INFO - '--tppagecycles', 15:20:01 INFO - '7'], 15:20:01 INFO - 'tests': ['tsvgm']}}, 15:20:01 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 15:20:01 INFO - 'tests': ['tresize', 'tcanvasmark']}, 15:20:01 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 15:20:01 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 15:20:01 INFO - 'tests': ['dromaeo_css', 'kraken']}, 15:20:01 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 15:20:01 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:20:01 INFO - 'talos_options': ['--disable-e10s'], 15:20:01 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:20:01 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:20:01 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:20:01 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:20:01 INFO - 'talos_options': ['--disable-e10s'], 15:20:01 INFO - 'tests': ['damp', 'tps']}, 15:20:01 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:20:01 INFO - 'tests': ['damp', 'tps']}, 15:20:01 INFO - 'g3': {'talos_options': ['--disable-e10s'], 15:20:01 INFO - 'tests': ['dromaeo_dom']}, 15:20:01 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 15:20:01 INFO - 'g4': {'talos_options': ['--disable-e10s'], 15:20:01 INFO - 'tests': ['basic_compositor_video']}, 15:20:01 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 15:20:01 INFO - 'other': {'talos_options': ['--disable-e10s'], 15:20:01 INFO - 'tests': ['a11yr', 15:20:01 INFO - 'ts_paint', 15:20:01 INFO - 'tpaint', 15:20:01 INFO - 'sessionrestore', 15:20:01 INFO - 'sessionrestore_no_auto_restore', 15:20:01 INFO - 'tabpaint']}, 15:20:01 INFO - 'other-e10s': {'tests': ['a11yr', 15:20:01 INFO - 'ts_paint', 15:20:01 INFO - 'tpaint', 15:20:01 INFO - 'sessionrestore', 15:20:01 INFO - 'sessionrestore_no_auto_restore', 15:20:01 INFO - 'tabpaint']}, 15:20:01 INFO - 'other-e10s_l64': {'tests': ['a11yr', 15:20:01 INFO - 'ts_paint', 15:20:01 INFO - 'tpaint', 15:20:01 INFO - 'sessionrestore', 15:20:01 INFO - 'sessionrestore_no_auto_restore', 15:20:01 INFO - 'tabpaint']}, 15:20:01 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 15:20:01 INFO - 'ts_paint', 15:20:01 INFO - 'tpaint', 15:20:01 INFO - 'sessionrestore', 15:20:01 INFO - 'sessionrestore_no_auto_restore', 15:20:01 INFO - 'tabpaint']}, 15:20:01 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 15:20:01 INFO - 'tests': ['a11yr', 15:20:01 INFO - 'ts_paint', 15:20:01 INFO - 'tpaint', 15:20:01 INFO - 'sessionrestore', 15:20:01 INFO - 'sessionrestore_no_auto_restore', 15:20:01 INFO - 'tabpaint']}, 15:20:01 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 15:20:01 INFO - 'tests': ['a11yr', 15:20:01 INFO - 'ts_paint', 15:20:01 INFO - 'tpaint', 15:20:01 INFO - 'sessionrestore', 15:20:01 INFO - 'sessionrestore_no_auto_restore', 15:20:01 INFO - 'tabpaint']}, 15:20:01 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 15:20:01 INFO - 'tests': ['tsvgx', 15:20:01 INFO - 'tsvgr_opacity', 15:20:01 INFO - 'tart', 15:20:01 INFO - 'tscrollx', 15:20:01 INFO - 'cart']}, 15:20:01 INFO - 'svgr-e10s': {'tests': ['tsvgx', 15:20:01 INFO - 'tsvgr_opacity', 15:20:01 INFO - 'tart', 15:20:01 INFO - 'tscrollx', 15:20:01 INFO - 'cart']}, 15:20:01 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:20:01 INFO - 'talos_options': ['--disable-e10s'], 15:20:01 INFO - 'tests': ['tp5o']}, 15:20:01 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:20:01 INFO - 'tests': ['tp5o']}, 15:20:01 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:20:01 INFO - 'talos_options': ['--disable-e10s', 15:20:01 INFO - '--xperf_path', 15:20:01 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:20:01 INFO - 'tests': ['tp5n']}, 15:20:01 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:20:01 INFO - 'talos_options': ['--xperf_path', 15:20:01 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:20:01 INFO - 'tests': ['tp5n']}}, 15:20:01 INFO - 'talos.zip': {'path': '', 15:20:01 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 15:20:01 INFO - Running post-action listener: _resource_record_post_action 15:20:01 INFO - [mozharness: 2016-10-01 22:20:01.888497Z] Finished populate-webroot step (success) 15:20:01 INFO - [mozharness: 2016-10-01 22:20:01.888606Z] Running create-virtualenv step. 15:20:01 INFO - Running pre-action listener: _resource_record_pre_action 15:20:01 INFO - Running main action method: create_virtualenv 15:20:01 INFO - Creating virtualenv /builds/slave/test/build/venv 15:20:01 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 15:20:01 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 15:20:02 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 15:20:02 INFO - Using real prefix '/tools/python27' 15:20:02 INFO - New python executable in /builds/slave/test/build/venv/bin/python 15:20:02 INFO - Installing distribute.............................................................................................................................................................................................done. 15:20:05 INFO - Installing pip.................done. 15:20:05 INFO - Return code: 0 15:20:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 15:20:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 15:20:05 INFO - Reading from file tmpfile_stdout 15:20:05 INFO - Output received: 15:20:05 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 15:20:05 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 15:20:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:20:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:20:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:20:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:20:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:20:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:20:05 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10a310490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a286570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffc5a552040>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a124d30>, '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 0x109f4adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a11cb20>, '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.T0A3Ghbg0c/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.dt5Iifp37T/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/t6/qpy_kfds3kz26lvtw39n2d7m00000w/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 15:20:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 15:20:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 15:20:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.T0A3Ghbg0c/Render', 15:20:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:20:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:20:05 INFO - 'HOME': '/Users/cltbld', 15:20:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:20:05 INFO - 'LOGNAME': 'cltbld', 15:20:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:20:05 INFO - 'MOZ_NO_REMOTE': '1', 15:20:05 INFO - 'NO_EM_RESTART': '1', 15:20:05 INFO - 'PAGER': '/bin/cat', 15:20:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:20:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:20:05 INFO - 'PWD': '/builds/slave/test', 15:20:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:20:05 INFO - 'SHELL': '/bin/bash', 15:20:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.dt5Iifp37T/Listeners', 15:20:05 INFO - 'TMPDIR': '/var/folders/t6/qpy_kfds3kz26lvtw39n2d7m00000w/T/', 15:20:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:20:05 INFO - 'USER': 'cltbld', 15:20:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:20:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:20:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:20:05 INFO - 'XPC_FLAGS': '0x0', 15:20:05 INFO - 'XPC_SERVICE_NAME': '0', 15:20:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:20:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:20:06 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 15:20:06 INFO - Cleaning up... 15:20:06 INFO - Return code: 0 15:20:06 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 15:20:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:20:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:20:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:20:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:20:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:20:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:20:06 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10a310490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a286570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffc5a552040>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a124d30>, '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 0x109f4adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a11cb20>, '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.T0A3Ghbg0c/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.dt5Iifp37T/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/t6/qpy_kfds3kz26lvtw39n2d7m00000w/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 15:20:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 15:20:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 15:20:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.T0A3Ghbg0c/Render', 15:20:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:20:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:20:06 INFO - 'HOME': '/Users/cltbld', 15:20:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:20:06 INFO - 'LOGNAME': 'cltbld', 15:20:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:20:06 INFO - 'MOZ_NO_REMOTE': '1', 15:20:06 INFO - 'NO_EM_RESTART': '1', 15:20:06 INFO - 'PAGER': '/bin/cat', 15:20:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:20:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:20:06 INFO - 'PWD': '/builds/slave/test', 15:20:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:20:06 INFO - 'SHELL': '/bin/bash', 15:20:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.dt5Iifp37T/Listeners', 15:20:06 INFO - 'TMPDIR': '/var/folders/t6/qpy_kfds3kz26lvtw39n2d7m00000w/T/', 15:20:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:20:06 INFO - 'USER': 'cltbld', 15:20:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:20:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:20:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:20:06 INFO - 'XPC_FLAGS': '0x0', 15:20:06 INFO - 'XPC_SERVICE_NAME': '0', 15:20:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:20:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:20:06 INFO - Downloading/unpacking psutil>=3.1.1 15:20:06 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 15:20:06 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 15:20:06 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 15:20:06 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 15:20:06 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 15:20:06 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 15:20:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 15:20:10 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:20:10 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:20:10 INFO - Installing collected packages: psutil 15:20:10 INFO - Running setup.py install for psutil 15:20:10 INFO - building 'psutil._psutil_osx' extension 15:20:10 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 15:20:10 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 15:20:10 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 15:20:10 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 15:20:10 INFO - building 'psutil._psutil_posix' extension 15:20:10 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 15:20:11 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 15:20:11 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 15:20:11 INFO - ^ 15:20:11 INFO - 1 warning generated. 15:20:11 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 15:20:11 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:20:11 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:20:11 INFO - Successfully installed psutil 15:20:11 INFO - Cleaning up... 15:20:11 INFO - Return code: 0 15:20:11 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 15:20:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:20:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:20:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:20:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:20:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:20:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:20:11 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10a310490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a286570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffc5a552040>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a124d30>, '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 0x109f4adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a11cb20>, '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.T0A3Ghbg0c/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.dt5Iifp37T/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/t6/qpy_kfds3kz26lvtw39n2d7m00000w/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 15:20:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 15:20:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 15:20:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.T0A3Ghbg0c/Render', 15:20:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:20:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:20:11 INFO - 'HOME': '/Users/cltbld', 15:20:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:20:11 INFO - 'LOGNAME': 'cltbld', 15:20:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:20:11 INFO - 'MOZ_NO_REMOTE': '1', 15:20:11 INFO - 'NO_EM_RESTART': '1', 15:20:11 INFO - 'PAGER': '/bin/cat', 15:20:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:20:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:20:11 INFO - 'PWD': '/builds/slave/test', 15:20:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:20:11 INFO - 'SHELL': '/bin/bash', 15:20:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.dt5Iifp37T/Listeners', 15:20:11 INFO - 'TMPDIR': '/var/folders/t6/qpy_kfds3kz26lvtw39n2d7m00000w/T/', 15:20:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:20:11 INFO - 'USER': 'cltbld', 15:20:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:20:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:20:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:20:11 INFO - 'XPC_FLAGS': '0x0', 15:20:11 INFO - 'XPC_SERVICE_NAME': '0', 15:20:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:20:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:20:11 INFO - Downloading/unpacking mozsystemmonitor==0.3 15:20: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 15:20: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 15:20: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 15:20: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 15:20: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 15:20: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 15:20:14 INFO - Downloading mozsystemmonitor-0.3.tar.gz 15:20:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 15:20:14 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 15:20:14 INFO - Installing collected packages: mozsystemmonitor 15:20:14 INFO - Running setup.py install for mozsystemmonitor 15:20:14 INFO - Successfully installed mozsystemmonitor 15:20:14 INFO - Cleaning up... 15:20:14 INFO - Return code: 0 15:20:14 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 15:20: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')]} 15:20:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:20:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:20: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')]} 15:20:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:20:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:20: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', '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 0x10a310490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a286570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffc5a552040>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a124d30>, '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 0x109f4adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a11cb20>, '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.T0A3Ghbg0c/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.dt5Iifp37T/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/t6/qpy_kfds3kz26lvtw39n2d7m00000w/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 15:20: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', 'jsonschema==2.5.1'] in /builds/slave/test/build 15:20: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 jsonschema==2.5.1 15:20:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.T0A3Ghbg0c/Render', 15:20:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:20:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:20:14 INFO - 'HOME': '/Users/cltbld', 15:20:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:20:14 INFO - 'LOGNAME': 'cltbld', 15:20:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:20:14 INFO - 'MOZ_NO_REMOTE': '1', 15:20:14 INFO - 'NO_EM_RESTART': '1', 15:20:14 INFO - 'PAGER': '/bin/cat', 15:20:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:20:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:20:14 INFO - 'PWD': '/builds/slave/test', 15:20:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:20:14 INFO - 'SHELL': '/bin/bash', 15:20:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.dt5Iifp37T/Listeners', 15:20:14 INFO - 'TMPDIR': '/var/folders/t6/qpy_kfds3kz26lvtw39n2d7m00000w/T/', 15:20:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:20:14 INFO - 'USER': 'cltbld', 15:20:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:20:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:20:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:20:14 INFO - 'XPC_FLAGS': '0x0', 15:20:14 INFO - 'XPC_SERVICE_NAME': '0', 15:20:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:20:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:20:14 INFO - Downloading/unpacking jsonschema==2.5.1 15:20: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 15:20: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 15:20: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 15:20: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 15:20: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 15:20: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 15:20:17 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 15:20:17 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 15:20: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 15:20: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 15:20: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 15:20: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 15:20: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 15:20: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 15:20:17 INFO - Downloading functools32-3.2.3-2.tar.gz 15:20:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 15:20:17 INFO - warning: no files found matching '*.txt' 15:20:17 INFO - no previously-included directories found matching 'build' 15:20:17 INFO - no previously-included directories found matching 'dist' 15:20:17 INFO - no previously-included directories found matching '.git*' 15:20:17 INFO - Installing collected packages: jsonschema, functools32 15:20:17 INFO - Running setup.py install for functools32 15:20:17 INFO - warning: no files found matching '*.txt' 15:20:17 INFO - no previously-included directories found matching 'build' 15:20:17 INFO - no previously-included directories found matching 'dist' 15:20:17 INFO - no previously-included directories found matching '.git*' 15:20:17 INFO - Successfully installed jsonschema functools32 15:20:17 INFO - Cleaning up... 15:20:17 INFO - Return code: 0 15:20:17 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 15:20:17 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')]} 15:20:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:20:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:20:17 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')]} 15:20:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:20:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:20:17 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 0x10a310490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a286570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffc5a552040>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a124d30>, '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 0x109f4adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a11cb20>, '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.T0A3Ghbg0c/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.dt5Iifp37T/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/t6/qpy_kfds3kz26lvtw39n2d7m00000w/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 15:20:17 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 15:20:17 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 15:20:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.T0A3Ghbg0c/Render', 15:20:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:20:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:20:17 INFO - 'HOME': '/Users/cltbld', 15:20:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:20:17 INFO - 'LOGNAME': 'cltbld', 15:20:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:20:17 INFO - 'MOZ_NO_REMOTE': '1', 15:20:17 INFO - 'NO_EM_RESTART': '1', 15:20:17 INFO - 'PAGER': '/bin/cat', 15:20:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:20:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:20:17 INFO - 'PWD': '/builds/slave/test', 15:20:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:20:17 INFO - 'SHELL': '/bin/bash', 15:20:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.dt5Iifp37T/Listeners', 15:20:17 INFO - 'TMPDIR': '/var/folders/t6/qpy_kfds3kz26lvtw39n2d7m00000w/T/', 15:20:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:20:17 INFO - 'USER': 'cltbld', 15:20:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:20:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:20:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:20:17 INFO - 'XPC_FLAGS': '0x0', 15:20:17 INFO - 'XPC_SERVICE_NAME': '0', 15:20:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:20:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:20:18 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 15:20:18 INFO - Cleaning up... 15:20:18 INFO - Return code: 0 15:20:18 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 15:20:18 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')]} 15:20:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:20:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:20:18 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')]} 15:20:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:20:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:20:18 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 0x10a310490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a286570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffc5a552040>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a124d30>, '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 0x109f4adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a11cb20>, '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.T0A3Ghbg0c/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.dt5Iifp37T/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/t6/qpy_kfds3kz26lvtw39n2d7m00000w/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 15:20:18 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 15:20:18 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 15:20:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.T0A3Ghbg0c/Render', 15:20:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:20:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:20:18 INFO - 'HOME': '/Users/cltbld', 15:20:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:20:18 INFO - 'LOGNAME': 'cltbld', 15:20:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:20:18 INFO - 'MOZ_NO_REMOTE': '1', 15:20:18 INFO - 'NO_EM_RESTART': '1', 15:20:18 INFO - 'PAGER': '/bin/cat', 15:20:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:20:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:20:18 INFO - 'PWD': '/builds/slave/test', 15:20:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:20:18 INFO - 'SHELL': '/bin/bash', 15:20:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.dt5Iifp37T/Listeners', 15:20:18 INFO - 'TMPDIR': '/var/folders/t6/qpy_kfds3kz26lvtw39n2d7m00000w/T/', 15:20:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:20:18 INFO - 'USER': 'cltbld', 15:20:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:20:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:20:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:20:18 INFO - 'XPC_FLAGS': '0x0', 15:20:18 INFO - 'XPC_SERVICE_NAME': '0', 15:20:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:20:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:20:18 INFO - Downloading/unpacking blobuploader==1.2.4 15:20: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 15:20: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 15:20: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 15:20: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 15:20: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 15:20: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 15:20:20 INFO - Downloading blobuploader-1.2.4.tar.gz 15:20:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 15:20:21 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 15:20:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:20:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:20:21 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:20:21 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:20:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:20:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:20:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 15:20:21 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 15:20:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:20:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:20:21 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:20:21 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:20:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:20:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:20:22 INFO - Downloading docopt-0.6.1.tar.gz 15:20:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 15:20:22 INFO - Installing collected packages: blobuploader, requests, docopt 15:20:22 INFO - Running setup.py install for blobuploader 15:20:22 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 15:20:22 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 15:20:22 INFO - Running setup.py install for requests 15:20:22 INFO - Running setup.py install for docopt 15:20:22 INFO - Successfully installed blobuploader requests docopt 15:20:22 INFO - Cleaning up... 15:20:23 INFO - Return code: 0 15:20:23 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:20: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')]} 15:20:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:20:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:20: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')]} 15:20:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:20:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:20:23 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 0x10a310490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a286570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffc5a552040>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a124d30>, '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 0x109f4adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a11cb20>, '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.T0A3Ghbg0c/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.dt5Iifp37T/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/t6/qpy_kfds3kz26lvtw39n2d7m00000w/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 15:20:23 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 15:20:23 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 15:20:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.T0A3Ghbg0c/Render', 15:20:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:20:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:20:23 INFO - 'HOME': '/Users/cltbld', 15:20:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:20:23 INFO - 'LOGNAME': 'cltbld', 15:20:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:20:23 INFO - 'MOZ_NO_REMOTE': '1', 15:20:23 INFO - 'NO_EM_RESTART': '1', 15:20:23 INFO - 'PAGER': '/bin/cat', 15:20:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:20:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:20:23 INFO - 'PWD': '/builds/slave/test', 15:20:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:20:23 INFO - 'SHELL': '/bin/bash', 15:20:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.dt5Iifp37T/Listeners', 15:20:23 INFO - 'TMPDIR': '/var/folders/t6/qpy_kfds3kz26lvtw39n2d7m00000w/T/', 15:20:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:20:23 INFO - 'USER': 'cltbld', 15:20:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:20:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:20:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:20:23 INFO - 'XPC_FLAGS': '0x0', 15:20:23 INFO - 'XPC_SERVICE_NAME': '0', 15:20:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:20:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:20:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:20:23 INFO - Running setup.py (path:/var/folders/t6/qpy_kfds3kz26lvtw39n2d7m00000w/T/pip-hWNpo3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:20:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:20:23 INFO - Running setup.py (path:/var/folders/t6/qpy_kfds3kz26lvtw39n2d7m00000w/T/pip-bZN7Kt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:20:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:20:23 INFO - Running setup.py (path:/var/folders/t6/qpy_kfds3kz26lvtw39n2d7m00000w/T/pip-jrn86R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:20:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:20:23 INFO - Running setup.py (path:/var/folders/t6/qpy_kfds3kz26lvtw39n2d7m00000w/T/pip-AWJT4N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:20:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:20:23 INFO - Running setup.py (path:/var/folders/t6/qpy_kfds3kz26lvtw39n2d7m00000w/T/pip-snYqXN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:20:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:20:23 INFO - Running setup.py (path:/var/folders/t6/qpy_kfds3kz26lvtw39n2d7m00000w/T/pip-vuD453-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:20:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:20:23 INFO - Running setup.py (path:/var/folders/t6/qpy_kfds3kz26lvtw39n2d7m00000w/T/pip-a6sDGM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:20:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:20:24 INFO - Running setup.py (path:/var/folders/t6/qpy_kfds3kz26lvtw39n2d7m00000w/T/pip-LcAspw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:20:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:20:24 INFO - Running setup.py (path:/var/folders/t6/qpy_kfds3kz26lvtw39n2d7m00000w/T/pip-lTzgL1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:20:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:20:24 INFO - Running setup.py (path:/var/folders/t6/qpy_kfds3kz26lvtw39n2d7m00000w/T/pip-lXx89o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:20:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:20:24 INFO - Running setup.py (path:/var/folders/t6/qpy_kfds3kz26lvtw39n2d7m00000w/T/pip-Nwpobp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:20:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:20:24 INFO - Running setup.py (path:/var/folders/t6/qpy_kfds3kz26lvtw39n2d7m00000w/T/pip-ckHnt4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:20:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:20:24 INFO - Running setup.py (path:/var/folders/t6/qpy_kfds3kz26lvtw39n2d7m00000w/T/pip-eUmhEb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:20:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:20:24 INFO - Running setup.py (path:/var/folders/t6/qpy_kfds3kz26lvtw39n2d7m00000w/T/pip-TifK25-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:20:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:20:24 INFO - Running setup.py (path:/var/folders/t6/qpy_kfds3kz26lvtw39n2d7m00000w/T/pip-BZlBVX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:20:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:20:24 INFO - Running setup.py (path:/var/folders/t6/qpy_kfds3kz26lvtw39n2d7m00000w/T/pip-PzKKfd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:20:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:20:25 INFO - Running setup.py (path:/var/folders/t6/qpy_kfds3kz26lvtw39n2d7m00000w/T/pip-8hMm2s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:20:25 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 15:20:25 INFO - Running setup.py install for manifestparser 15:20:25 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 15:20:25 INFO - Running setup.py install for mozcrash 15:20:25 INFO - Running setup.py install for mozdebug 15:20:25 INFO - Running setup.py install for mozdevice 15:20:25 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 15:20:25 INFO - Installing dm script to /builds/slave/test/build/venv/bin 15:20:25 INFO - Running setup.py install for mozfile 15:20:25 INFO - Running setup.py install for mozhttpd 15:20:26 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 15:20:26 INFO - Running setup.py install for mozinfo 15:20:26 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 15:20:26 INFO - Running setup.py install for mozInstall 15:20:26 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 15:20:26 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 15:20:26 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 15:20:26 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 15:20:26 INFO - Running setup.py install for mozleak 15:20:26 INFO - Running setup.py install for mozlog 15:20:26 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 15:20:26 INFO - Running setup.py install for moznetwork 15:20:26 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 15:20:26 INFO - Running setup.py install for mozprocess 15:20:26 INFO - Running setup.py install for mozprofile 15:20:27 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 15:20:27 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 15:20:27 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 15:20:27 INFO - Running setup.py install for mozrunner 15:20:27 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 15:20:27 INFO - Running setup.py install for mozscreenshot 15:20:27 INFO - Running setup.py install for moztest 15:20:27 INFO - Running setup.py install for mozversion 15:20:27 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 15:20:27 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 15:20:27 INFO - Cleaning up... 15:20:27 INFO - Return code: 0 15:20:27 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:20:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:20:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:20:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:20:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:20:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:20:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:20:27 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 0x10a310490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a286570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffc5a552040>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a124d30>, '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 0x109f4adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a11cb20>, '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.T0A3Ghbg0c/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.dt5Iifp37T/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/t6/qpy_kfds3kz26lvtw39n2d7m00000w/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 15:20:27 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 15:20:27 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 15:20:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.T0A3Ghbg0c/Render', 15:20:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:20:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:20:27 INFO - 'HOME': '/Users/cltbld', 15:20:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:20:27 INFO - 'LOGNAME': 'cltbld', 15:20:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:20:27 INFO - 'MOZ_NO_REMOTE': '1', 15:20:27 INFO - 'NO_EM_RESTART': '1', 15:20:27 INFO - 'PAGER': '/bin/cat', 15:20:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:20:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:20:27 INFO - 'PWD': '/builds/slave/test', 15:20:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:20:27 INFO - 'SHELL': '/bin/bash', 15:20:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.dt5Iifp37T/Listeners', 15:20:27 INFO - 'TMPDIR': '/var/folders/t6/qpy_kfds3kz26lvtw39n2d7m00000w/T/', 15:20:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:20:27 INFO - 'USER': 'cltbld', 15:20:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:20:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:20:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:20:27 INFO - 'XPC_FLAGS': '0x0', 15:20:27 INFO - 'XPC_SERVICE_NAME': '0', 15:20:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:20:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:20:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:20:28 INFO - Running setup.py (path:/var/folders/t6/qpy_kfds3kz26lvtw39n2d7m00000w/T/pip-QRxH9A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:20:28 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)) 15:20:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:20:28 INFO - Running setup.py (path:/var/folders/t6/qpy_kfds3kz26lvtw39n2d7m00000w/T/pip-rY1Mzd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:20:28 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)) 15:20:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:20:28 INFO - Running setup.py (path:/var/folders/t6/qpy_kfds3kz26lvtw39n2d7m00000w/T/pip-aWAKCb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:20:28 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)) 15:20:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:20:28 INFO - Running setup.py (path:/var/folders/t6/qpy_kfds3kz26lvtw39n2d7m00000w/T/pip-tTuHXe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:20:28 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)) 15:20:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:20:28 INFO - Running setup.py (path:/var/folders/t6/qpy_kfds3kz26lvtw39n2d7m00000w/T/pip-SFSQDE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:20:28 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)) 15:20:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:20:28 INFO - Running setup.py (path:/var/folders/t6/qpy_kfds3kz26lvtw39n2d7m00000w/T/pip-9Kmk8W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:20:28 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)) 15:20:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:20:28 INFO - Running setup.py (path:/var/folders/t6/qpy_kfds3kz26lvtw39n2d7m00000w/T/pip-40x3cq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:20:28 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)) 15:20:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:20:28 INFO - Running setup.py (path:/var/folders/t6/qpy_kfds3kz26lvtw39n2d7m00000w/T/pip-mOQ9Am-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:20:28 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)) 15:20:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:20:28 INFO - Running setup.py (path:/var/folders/t6/qpy_kfds3kz26lvtw39n2d7m00000w/T/pip-z0pC0I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:20:28 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)) 15:20:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:20:28 INFO - Running setup.py (path:/var/folders/t6/qpy_kfds3kz26lvtw39n2d7m00000w/T/pip-zyFyDt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:20:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 15:20:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:20:29 INFO - Running setup.py (path:/var/folders/t6/qpy_kfds3kz26lvtw39n2d7m00000w/T/pip-SfxYwV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:20:29 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)) 15:20:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:20:29 INFO - Running setup.py (path:/var/folders/t6/qpy_kfds3kz26lvtw39n2d7m00000w/T/pip-Dm4FF4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:20:29 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)) 15:20:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:20:29 INFO - Running setup.py (path:/var/folders/t6/qpy_kfds3kz26lvtw39n2d7m00000w/T/pip-D7T_zp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:20:29 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)) 15:20:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:20:29 INFO - Running setup.py (path:/var/folders/t6/qpy_kfds3kz26lvtw39n2d7m00000w/T/pip-YXU052-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:20:29 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)) 15:20:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:20:29 INFO - Running setup.py (path:/var/folders/t6/qpy_kfds3kz26lvtw39n2d7m00000w/T/pip-4XzmwR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:20:29 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)) 15:20:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:20:29 INFO - Running setup.py (path:/var/folders/t6/qpy_kfds3kz26lvtw39n2d7m00000w/T/pip-wBpmu2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:20:29 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)) 15:20:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:20:29 INFO - Running setup.py (path:/var/folders/t6/qpy_kfds3kz26lvtw39n2d7m00000w/T/pip-Y9rdfC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:20:29 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)) 15:20:29 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)) 15:20:29 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)) 15:20:29 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)) 15:20:29 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)) 15:20:29 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)) 15:20:29 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 15:20:29 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 15:20:29 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 15:20:29 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 15:20:29 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 15:20:29 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 15:20:29 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 15:20:32 INFO - Downloading blessings-1.6.tar.gz 15:20:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 15:20:32 INFO - Installing collected packages: blessings 15:20:32 INFO - Running setup.py install for blessings 15:20:32 INFO - Successfully installed blessings 15:20:32 INFO - Cleaning up... 15:20:32 INFO - Return code: 0 15:20:32 INFO - Done creating virtualenv /builds/slave/test/build/venv. 15:20:32 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:20:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:20:32 INFO - Reading from file tmpfile_stdout 15:20:32 INFO - Current package versions: 15:20:32 INFO - blessings == 1.6 15:20:32 INFO - blobuploader == 1.2.4 15:20:32 INFO - docopt == 0.6.1 15:20:32 INFO - functools32 == 3.2.3-2 15:20:32 INFO - jsonschema == 2.5.1 15:20:32 INFO - manifestparser == 1.1 15:20:32 INFO - mozInstall == 1.12 15:20:32 INFO - mozcrash == 0.17 15:20:32 INFO - mozdebug == 0.1 15:20:32 INFO - mozdevice == 0.48 15:20:32 INFO - mozfile == 1.2 15:20:32 INFO - mozhttpd == 0.7 15:20:32 INFO - mozinfo == 0.9 15:20:32 INFO - mozleak == 0.1 15:20:32 INFO - mozlog == 3.4 15:20:32 INFO - moznetwork == 0.27 15:20:32 INFO - mozprocess == 0.23 15:20:32 INFO - mozprofile == 0.28 15:20:32 INFO - mozrunner == 6.12 15:20:32 INFO - mozscreenshot == 0.1 15:20:32 INFO - mozsystemmonitor == 0.3 15:20:32 INFO - moztest == 0.7 15:20:32 INFO - mozversion == 1.4 15:20:32 INFO - psutil == 3.1.1 15:20:32 INFO - requests == 1.2.3 15:20:32 INFO - wsgiref == 0.1.2 15:20:32 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:20:32 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')]} 15:20:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:20:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:20:32 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')]} 15:20:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:20:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:20:32 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 0x10a310490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a286570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffc5a552040>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a124d30>, '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 0x109f4adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a11cb20>, '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.T0A3Ghbg0c/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.dt5Iifp37T/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/t6/qpy_kfds3kz26lvtw39n2d7m00000w/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 15:20:32 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 15:20:32 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 15:20:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.T0A3Ghbg0c/Render', 15:20:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:20:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:20:32 INFO - 'HOME': '/Users/cltbld', 15:20:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:20:32 INFO - 'LOGNAME': 'cltbld', 15:20:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:20:32 INFO - 'MOZ_NO_REMOTE': '1', 15:20:32 INFO - 'NO_EM_RESTART': '1', 15:20:32 INFO - 'PAGER': '/bin/cat', 15:20:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:20:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:20:32 INFO - 'PWD': '/builds/slave/test', 15:20:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:20:32 INFO - 'SHELL': '/bin/bash', 15:20:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.dt5Iifp37T/Listeners', 15:20:32 INFO - 'TMPDIR': '/var/folders/t6/qpy_kfds3kz26lvtw39n2d7m00000w/T/', 15:20:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:20:32 INFO - 'USER': 'cltbld', 15:20:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:20:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:20:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:20:32 INFO - 'XPC_FLAGS': '0x0', 15:20:32 INFO - 'XPC_SERVICE_NAME': '0', 15:20:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:20:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:20:33 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)) 15:20:33 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)) 15:20:33 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)) 15:20:33 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)) 15:20:33 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)) 15:20:33 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)) 15:20:33 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)) 15:20:33 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)) 15:20:33 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)) 15:20:33 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)) 15:20:33 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)) 15:20:33 INFO - Cleaning up... 15:20:33 INFO - Return code: 0 15:20:33 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 15:20:33 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')]} 15:20:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:20:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:20:33 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')]} 15:20:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:20:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:20:33 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 0x10a310490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a286570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffc5a552040>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a124d30>, '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 0x109f4adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a11cb20>, '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.T0A3Ghbg0c/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.dt5Iifp37T/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/t6/qpy_kfds3kz26lvtw39n2d7m00000w/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 15:20:33 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 15:20:33 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 15:20:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.T0A3Ghbg0c/Render', 15:20:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:20:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:20:33 INFO - 'HOME': '/Users/cltbld', 15:20:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:20:33 INFO - 'LOGNAME': 'cltbld', 15:20:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:20:33 INFO - 'MOZ_NO_REMOTE': '1', 15:20:33 INFO - 'NO_EM_RESTART': '1', 15:20:33 INFO - 'PAGER': '/bin/cat', 15:20:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:20:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:20:33 INFO - 'PWD': '/builds/slave/test', 15:20:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:20:33 INFO - 'SHELL': '/bin/bash', 15:20:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.dt5Iifp37T/Listeners', 15:20:33 INFO - 'TMPDIR': '/var/folders/t6/qpy_kfds3kz26lvtw39n2d7m00000w/T/', 15:20:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:20:33 INFO - 'USER': 'cltbld', 15:20:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:20:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:20:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:20:33 INFO - 'XPC_FLAGS': '0x0', 15:20:33 INFO - 'XPC_SERVICE_NAME': '0', 15:20:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:20:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:20:33 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 15:20:33 INFO - Cleaning up... 15:20:33 INFO - Return code: 0 15:20:33 INFO - Running post-action listener: _resource_record_post_action 15:20:33 INFO - Running post-action listener: _start_resource_monitoring 15:20:33 INFO - Starting resource monitoring. 15:20:33 INFO - [mozharness: 2016-10-01 22:20:33.528617Z] Finished create-virtualenv step (success) 15:20:33 INFO - [mozharness: 2016-10-01 22:20:33.529675Z] Running install step. 15:20:33 INFO - Running pre-action listener: _resource_record_pre_action 15:20:33 INFO - Running main action method: install 15:20:33 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:20:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:20:33 INFO - Reading from file tmpfile_stdout 15:20:33 INFO - Detecting whether we're running mozinstall >=1.0... 15:20:33 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 15:20:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 15:20:33 INFO - Reading from file tmpfile_stdout 15:20:33 INFO - Output received: 15:20:33 INFO - Usage: mozinstall [options] installer 15:20:33 INFO - Options: 15:20:33 INFO - -h, --help show this help message and exit 15:20:33 INFO - -d DEST, --destination=DEST 15:20:33 INFO - Directory to install application into. [default: 15:20:33 INFO - "/builds/slave/test"] 15:20:33 INFO - --app=APP Application being installed. [default: firefox] 15:20:33 INFO - mkdir: /builds/slave/test/build/application 15:20:33 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 15:20:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 15:21:08 INFO - Reading from file tmpfile_stdout 15:21:08 INFO - Output received: 15:21:08 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 15:21:08 INFO - Running post-action listener: _resource_record_post_action 15:21:08 INFO - [mozharness: 2016-10-01 22:21:08.556744Z] Finished install step (success) 15:21:08 INFO - [mozharness: 2016-10-01 22:21:08.556885Z] Running run-tests step. 15:21:08 INFO - Running pre-action listener: _resource_record_pre_action 15:21:08 INFO - Running main action method: run_tests 15:21:08 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 15:21:08 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 15:21:08 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 15:21:08 INFO - 2016-10-01 15:21:08.571 screenresolution[1919:9535] starting screenresolution argv=screenresolution get 15:21:08 INFO - 2016-10-01 15:21:08.574 screenresolution[1919:9535] Display 0: 1600x1200x32@60 15:21:08 INFO - 2016-10-01 15:21:08.579 screenresolution[1920:9537] starting screenresolution argv=screenresolution list 15:21:08 INFO - Available Modes on Display 0 15:21:08 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 15:21:08 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 15:21:08 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 15:21:08 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 15:21:08 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 15:21:08 INFO - Intel Iris: 15:21:08 INFO - Chipset Model: Intel Iris 15:21:08 INFO - Type: GPU 15:21:08 INFO - Bus: Built-In 15:21:08 INFO - VRAM (Dynamic, Max): 1536 MB 15:21:08 INFO - Vendor: Intel (0x8086) 15:21:08 INFO - Device ID: 0x0a2e 15:21:08 INFO - Revision ID: 0x0009 15:21:08 INFO - Displays: 15:21:08 INFO - Display: 15:21:08 INFO - Resolution: 1600 x 1200 @ 60 Hz 15:21:08 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 15:21:08 INFO - Display Serial Number: 1600x1200 60 15:21:08 INFO - Main Display: Yes 15:21:08 INFO - Mirror: Off 15:21:08 INFO - Online: Yes 15:21:08 INFO - Rotation: Supported 15:21:08 INFO - Return code: 0 15:21:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 15:21:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 15:21:08 INFO - Python 2.7.3 15:21:08 INFO - Return code: 0 15:21:08 INFO - grabbing minidump binary from tooltool 15:21:08 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')]} 15:21:08 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 0x10a124d30>, '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 0x109f4adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a11cb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 15:21:08 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 15:21:08 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 15:21:08 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 15:21:08 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 15:21:08 INFO - Return code: 0 15:21:08 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 15:21:08 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 15:21:08 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 15:21:08 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 15:21:08 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 15:21:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Uvk4IryfSfim7_tiAfCcjw/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0062', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 15:21:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Autoland --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Uvk4IryfSfim7_tiAfCcjw/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0062 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 15:21:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.T0A3Ghbg0c/Render', 15:21:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:21:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:21:08 INFO - 'HOME': '/Users/cltbld', 15:21:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:21:08 INFO - 'LOGNAME': 'cltbld', 15:21:08 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 15:21:08 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 15:21:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:21:08 INFO - 'MOZ_NO_REMOTE': '1', 15:21:08 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 15:21:08 INFO - 'NO_EM_RESTART': '1', 15:21:08 INFO - 'PAGER': '/bin/cat', 15:21:08 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:21:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:21:08 INFO - 'PWD': '/builds/slave/test', 15:21:08 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 15:21:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:21:08 INFO - 'SHELL': '/bin/bash', 15:21:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.dt5Iifp37T/Listeners', 15:21:08 INFO - 'TMPDIR': '/var/folders/t6/qpy_kfds3kz26lvtw39n2d7m00000w/T/', 15:21:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:21:08 INFO - 'USER': 'cltbld', 15:21:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:21:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:21:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:21:08 INFO - 'XPC_FLAGS': '0x0', 15:21:08 INFO - 'XPC_SERVICE_NAME': '0', 15:21:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:21:08 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Uvk4IryfSfim7_tiAfCcjw/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0062', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 15:21:09 INFO - mozversion application_buildid: 20161001140154 15:21:09 INFO - mozversion application_changeset: 7c576fe3279d87543f0a03b844eba7bc215e17f1 15:21:09 INFO - mozversion application_display_name: Nightly 15:21:09 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 15:21:09 INFO - mozversion application_name: Firefox 15:21:09 INFO - mozversion application_remotingname: firefox 15:21:09 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland 15:21:09 INFO - mozversion application_vendor: Mozilla 15:21:09 INFO - mozversion application_version: 52.0a1 15:21:09 INFO - mozversion platform_buildid: 20161001140154 15:21:09 INFO - mozversion platform_changeset: 7c576fe3279d87543f0a03b844eba7bc215e17f1 15:21:09 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland 15:21:09 INFO - mozversion platform_version: 52.0a1 15:21:09 INFO - using testdate: 1475360469 15:21:09 INFO - actual date: 1475360469 15:21:09 INFO - starting webserver on 'localhost:49438' 15:21:09 INFO - SUITE-START | Running 2 tests 15:21:09 INFO - TEST-START | dromaeo_css 15:21:09 INFO - operating with platform_type : mac_ 15:21:09 INFO - Initialising browser for dromaeo_css test... 15:21:09 INFO - TEST-INFO | started process 1930 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/t6/qpy_kfds3kz26lvtw39n2d7m00000w/T/tmpjOLWmh/profile http://localhost:49438/getInfo.html) 15:21:11 INFO - PROCESS | 1930 | __metrics Screen width/height:1600/1200 15:21:11 INFO - PROCESS | 1930 | colorDepth:24 15:21:11 INFO - PROCESS | 1930 | Browser inner width/height: 1024/689 15:21:11 INFO - PROCESS | 1930 | __metrics 15:21:11 INFO - PROCESS | 1930 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 15:21:11 INFO - PROCESS | 1930 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 15:21:12 INFO - TEST-INFO | 1930: exit 0 15:21:12 INFO - Browser initialized. 15:21:12 INFO - Running cycle 1/1 for dromaeo_css test... 15:21:12 INFO - TEST-INFO | started process 1932 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/t6/qpy_kfds3kz26lvtw39n2d7m00000w/T/tmpjOLWmh/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:21:24 INFO - PROCESS | 1932 | RSS: Main: 236163072 15:21:24 INFO - PROCESS | 1932 | 15:22:15 INFO - PROCESS | 1932 | 1475360535791 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] 15:24:26 INFO - PROCESS | 1932 | Cycle 1(1): loaded http://localhost:49438/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49438/tests/dromaeo/cssquery-ext.html) 15:24:29 INFO - PROCESS | 1932 | RSS: Main: 393871360 15:24:29 INFO - PROCESS | 1932 | 15:27:32 INFO - PROCESS | 1932 | Cycle 1(1): loaded http://localhost:49438/tests/dromaeo/cssquery-ext.html (next: http://localhost:49438/tests/dromaeo/cssquery-jquery.html) 15:27:33 INFO - PROCESS | 1932 | RSS: Main: 371191808 15:27:33 INFO - PROCESS | 1932 | 15:30:35 INFO - PROCESS | 1932 | Cycle 1(1): loaded http://localhost:49438/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49438/tests/dromaeo/cssquery-mootools.html) 15:30:35 INFO - PROCESS | 1932 | RSS: Main: 338673664 15:30:35 INFO - PROCESS | 1932 | 15:33:38 INFO - PROCESS | 1932 | Cycle 1(1): loaded http://localhost:49438/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49438/tests/dromaeo/cssquery-prototype.html) 15:33:38 INFO - PROCESS | 1932 | RSS: Main: 347783168 15:33:38 INFO - PROCESS | 1932 | 15:36:42 INFO - PROCESS | 1932 | Cycle 1(1): loaded http://localhost:49438/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49438/tests/dromaeo/cssquery-yui.html) 15:36:42 INFO - PROCESS | 1932 | RSS: Main: 328155136 15:36:42 INFO - PROCESS | 1932 | 15:39:46 INFO - PROCESS | 1932 | Cycle 1(1): loaded http://localhost:49438/tests/dromaeo/cssquery-yui.html (next: http://localhost:49438/tests/dromaeo/cssquery-dojo.html) 15:39:46 INFO - PROCESS | 1932 | RSS: Main: 377606144 15:39:46 INFO - PROCESS | 1932 | 15:39:46 INFO - PROCESS | 1932 | __start_tp_report 15:39:46 INFO - PROCESS | 1932 | _x_x_mozilla_page_load 15:39:46 INFO - PROCESS | 1932 | _x_x_mozilla_page_load_details 15:39:46 INFO - PROCESS | 1932 | |i|pagename|runs| 15:39:46 INFO - PROCESS | 1932 | |0;dojo.html;3127.249460129569;3140.89006884759;3190.3140824722664;3210.325831575369;3224.193951512122;5212.139996900507;5319.813806516772;5349.7592608332625;5428.267183930173;5452.345718513774;5261.316028916235;5328.760205790739;5413.431589683086;5432.782688692458;5477.534409575181;4877.902441951159;4904.534069263432;4910.7299098549565;4957.206846904496;4970.403551573798;4921.237208232729;5047.47001564836;5051.469595692454;5053.898922021557;5114.667546609477;4863.805447782084;5015.724135172564;5021.62337824661;5041.59667226621;5129.204973229137;4906.460289368167;4977.303177555145;4980.800767969277;4982.377202849644;4992.226204938241;4946.480619534969;4949.381327334083;4968.130577149021;4978.6514943953935;4995.975020124913;5273.024490469237;5344.946550534483;5350.732463376855;5352.518273355417;5360.973195134038;5709.400512946122;5723.313202415695;5726.055200891848;5734;5737.168110623936;4762.785674644615;5594.160875868612;5758.078707406795;5766.567507436959;5805.5065319447895;5359.035373632705;5387.622866399314;5437.945620543823;5442.891142177174;5470.91793623096;5128.153854613947;5944.881102377928;5983.581149319506;5996.2504686914135;6001.5798894077;5493.093639549509;5545.833624991256;5562.082256427725;5573;5640.182173584807;5736.483716465538;5761.9135712964335;5763.250777398911;5814.94185058144;5831.183634291203;5678.375378708339;5690.118031705092;5690.345610254791;5692.800751973661;5700.657960522382;4681.9297710534365;5642.7178641067785;5686.488216060575;5706;5739.8565035874435;4661.696989695659;4663.230566956482;4665.86002419928;4671.229247174178;4672.415948006499;4166.791660416967;4178.039051018222;4234.25900467417;4272.6154646082;4276.294411422143;5413.188021796762;5438.619296649275;5444.3194600674915;5459.5359394451125;5461.890762184734;5086.880886205029;5811.709414529257;5861.677607731531;5890.911636325459;5898.675572843535;5283.128283833202;5351.411344752074;5408.161734931092;5426.728663566806;5463.399026107125;5074.2895994560795;5092.745362731848;5099.1331473648825;5101.20931255656;5174.844754657366;6078.665673388006;6081.665508397081;6087.817365479043;6087.969560152171;6090.482309003784;5805.506531944832;5873.970630146822;5877.059670452707;5880.558958078076;5894.056950888009;5774.942250577608;5856.297244330707;5857.414258574108;6016.518678505621;6067.575269731076;5017.573506251863;5474.726263686879;5769.509591684586;5780.479756821906;5822.330432000204;5817.738201780845;5830.53355731532;5831.4168583141345;5832.883342333214;5840.357560668238;4793.520647935178;5415.079436495726;5489.3687225969525;5537.058700021086;5678.148277758204;5487.5884308677805;5548.223248745259;5583.190437386637;5583.944160558505;5588.6646801192055;5475.534579560623;5491.505764481216;5494.890102197854;5495.890082198253;5498.505134537911;5571.387147413862;5604.187392828098;5605.075162598126;5631.465010823965;5695.914561281501;5482.479164479368;5514.724263786875;5518.91721624184;5533.280673512418;5563.082091455027 15:39:46 INFO - PROCESS | 1932 | |1;ext.html;486.763919499043;492.9383827021622;500.5495054450994;513.7636687123922;514.8455463360993;32967.67032329676;42837;42870.7856460718;42984.92537686559;44514.10971780562;43559.34660980088;43873.56126438735;43976;43991.340129898075;44034.89912752183;8772.56137193141;9071.138241867046;9922.603095876157;10112.696619101402;10126.392416454999;8937.73186804397;10022.398656080622;12408.193467424633;12408.456266465353;12514.12401131921;12212.145149839514;12267.141300108997;12288.5699000535;12294.20087694297;12329.321887296195;8875.334349923782;9142.131497507726;12318.876811231907;12351.691207719834;12386.81419778704;11811.29132252059;12183.208091474025;12223.066464048565;12309.138360314782;12347;42023.7898810504;42367.788161059;42377.94055148615;42420.78789606032;42574.78712606417;3321.168607072715;3333.0834020644274;3336.9499457508155;3349.112485191402;3407.4207384744404;25332.873335633387;31384.529232061537;31785.682143178503;32335;32772;3236.9190770230935;3256.6743325667244;3349.2416481347245;3351.7318614511023;3373.9831300843584;495214;500317;501586;501693;502880;62317;62529.374706253264;62568.74862502724;62598;62672;34863.825680871436;35322.29355412877;35577.8221108898;36531;36547.451788223196;16407.343706251617;16525.58686032859;16564.83435165657;16586.004839709654;16586.6682666346;11385.886141138648;11556.248843825124;12524.123311368117;12526;12619;19435.282681043514;19460.500963391125;19552.676753562893;19581.349097287093;19585.902070489556;7707.882357058195;7790.818570800185;7818;7866.426714656977;7930.246626570582;807.1556493412525;986.8026394721085;1021.545412291538;1037.834604456405;1070.550368845087;29840.850795745882;30266.84866575697;30707.9252226171;31182.555408230626;31859.044228673178;1032.8037672842136;1038.46000079958;1040.765827688764;1112.7703887204523;1201.6515210589007;11696.766064678828;15863.603409914615;16035.839641603668;16084.919575402282;16232.431864884673;671745;677918;677925;678349;680223;58540.707296463246;59130;59186.11220831691;59235;59286.40713592895;1206.9758604827855;1253.8746125387388;1316.8090626859243;1347.7776166932347;1367.3641756583236;29694;29977.779876621116;31533.526997094603;31691.890783822455;32409.86565470197;50830.491695084056;51190.74404627953;51517;51565.742171288905;51587;525357;527436;527928;528163;529123;26742.465150697266;26743.465130697667;27174.72825271722;27349.726502734717;28166.85916570404;47247.76376118098;47335.28997065116;48224.517754823406;48288.100939306656;48501;34100.14749631279;39697.40453893136;39728.80135599303;39731.801340994265;41252.58747412488;31305.686943130277;39011.80494097511;40326.59673403228;40664.79667601643;40884.38673419961;38581.4212786814;39180;39276.41085383664;39618.405723914744;39719;32803;35087;35465.82267088648;35545.93362199138;35608;20452.79547204509;25606.487870242865;25748.8712556436;25802.61296080598;26402.73597264003 15:39:46 INFO - PROCESS | 1932 | |2;jquery.html;351.4991137628878;365.2858661317125;369.41447805228694;378.9071677439026;399.60638770810766;15025.39898404064;15310.693786124284;16191;20501.897490512565;21006.789932100673;20460.28389006385;20481.385558433234;21097.894510527425;21119.049642766073;21289.467763305925;5794.739236734336;5795.304563452391;6084;6096.969515152418;6104.328523862371;6012.969935150318;6151.046587778901;6158.168647232634;6163.660998645095;6164.876702465948;3859.2667393195343;4067.0849058961644;5849.795257165979;6107.114468402079;6158.876822463548;6179.134921111048;6197.6901154942525;6200.286966998808;6232.657203853832;6795.524313298071;6057.606255593417;6062.120992456069;6064.4845188158615;6067.302260240041;6075.939240607581;13072.607821765363;13111.278879661615;13204.60386188416;13287.20276783396;13415.46338146473;1913.244268513943;2118.1315660579244;2253.0086761824746;2262.4457008032973;2594.662693849806;9461.852759083444;9714;10009.59961601535;10225.079742823107;10383.532741026673;1804.7834259888766;1860.6278744251204;1890.8392786613153;1904.7428597139346;1909.6849019911697;109352;112586;113373.43313283463;113573;114574.42712786466;10531.94734026325;10857;10960.725981850359;11343.092552595772;11370.317780933005;14420.495282665057;14424.42302307896;14440.277986100653;14441;14466.927665361605;8219.671213151527;8234.670613175525;8357.582120894052;8411.327093832479;8555.657773688983;8285.917140828635;8312.25189732927;8316.417850750511;8461.196186362286;8484.745457636282;4375.5405682403525;4471.910561788746;4478.977605111953;4568.383268258809;4579.977100114545;1825.8174182581636;1876.9718454223198;1883.1337584710911;1891.0639233579466;1892.5836316010455;668.4318329420051;827.6192951242496;926.6339795780764;947.0624082158613;1029.4904022508906;20172.982701730005;20923.68614470784;20940.267090651752;20962.895185523976;21279.574408511744;818.8075802186481;842.6123982967781;931.7624005878457;937.5687183895484;1029.3052189771875;9674.419534827792;10988.395638239814;11011;11018.338899665885;11025.83461248082;590052.0497397486;599461.008084879;603099;617731;617944;117978;126190;134287.65712342947;135556;138159;985.3644399362514;991.1822746234385;1022.616518805448;1023.5087158164123;1027.014066496157;17859.910700446413;18113.547161321072;18138.637227255116;18155.092245387943;18627.348042818438;42110.315587376164;48979;50569.99999999853;50862.49137508726;51279;80052.59973700094;81290.18709813063;81475;81946.18053819386;82019.17980820115;7301.917140373829;8002.279794818494;8091.87862182056;8141.185881411811;8202.220789025034;10841.403722795321;12125;12205.755884882075;12697.746045079231;13122.343882806012;11287.275199184085;11306.94346528262;11475.770484590428;11492;11653.067754579444;5127.435982042047;7828;10994.91096691626;11164.609238676607;11601.709957250798;11032.448377580928;11299.265547739371;11615.6515304541;11664.125190610908;11689.824652630388;7976.401769867167;8232;8251.33989280844;8351.916480835114;8390.328773697967;9410.153086222273;10839.67480975572;11139.498722557342;11251.043661288555;11595.710107247387 15:39:46 INFO - PROCESS | 1932 | |3;mootools.html;1519.179642992784;1544.7605621128725;1760.6478704259152;1864.6550388178196;1927.2483731344778;8583.957080214597;9088;9090.999990001095;9140.497272650002;9174.357794954356;7948.165442628528;8141.715039973602;8555.61499732512;8853.601587928559;8862.955685221565;2154.439845640137;2300.1029598456616;2350.670906073151;2387.7254115776645;2391.258709799959;2340.9765902341014;2357.2456813819585;2365.6215005599106;2382.833201675883;2384.940376490584;2360.4216966843146;2378.6550950112223;2391.6771235883107;2404.915827946022;2405.037984806083;2354.058376649337;2373.22870067228;2374;2374.3589230907646;2376.3940195250275;2371.3241726107976;2377.9048838046456;2378.726446458662;2381.714194296678;2388.534235824015;8910.688125915562;8936.419132756415;9017.864732029026;9032.09679032098;9037.683681071197;5263.289455923439;5414.756335964852;5418.91871621926;5420.7018613976215;5430.294061771944;3536.3104194682046;3624.638101464326;3730.645588669072;3756.398976163801;3769.754965927206;1097.9890201098046;1114.5653195253858;1769.3807167491404;1830.7162389829598;1878.2111513164505;11493.195476316661;11813.1140164488;11817.881821181763;11827;11903.988161006326;4536.954630453719;4611.700239484423;4650.837220697327;4651.395318608559;4651.883702907455;9291.907080929239;9297.209737172416;9298.163165315154;9306.90693093074;9309.534523273946;5281.313429254173;5281.656692314988;5283.101872681654;5285.788568457218;5304.6817190967895;4420.491643461043;4554.635629149726;4554.931676024796;4705.788239529229;4960.851174464772;3137.6862313768897;3702.0564203792687;3797.487339209227;3809.7523660961947;3840.5967373425387;1017.9541920613442;1144.811106167473;1160.1704781081398;1167.1870791392703;1172.355204637463;859.1537335724306;876.7808047988003;886.2688282167238;900.9953901400023;927.7216834949488;5679.346875109332;5760.654360738369;5904.704764761745;6009.429104235091;6089.512838972872;871.5293741379584;905.2079430498314;948.2224575847877;970.9951450242845;1000.554753134848;3413.2832105258176;3796.354619714655;3805.448210009533;3826.9234615307537;3921.6078392160557;10541.947290263653;10782.514786834612;10901.563937442572;10903.073238774634;10907.618233361953;10833.349999000086;10845.783084338269;10865.239433239756;10869.184811139037;10875.673729788119;6348.809535714028;8235.38234632405;8312.792180195544;8315.625796839156;8319.084900660922;5800.332961709458;6060.121282414113;6070.969645151746;6085.726142323518;6113.602615829956;9398.2951278653;9625.278104142077;9893.950530247303;9946.104850563484;10038.698839034842;9837.262205334486;10156.89843101589;10188.134008609055;10223.488825558841;10265.178785696977;6572.34276572339;7269.345758881726;7538.09542854861;7694.499857509244;7711.421643376882;8539.871901921351;8614.79392884984;8615.3107751381;8616.870746938675;8634.61144248523;7406.444516661164;7476;7597.6581053853815;7662.501937374052;7699.499532530368;6985.545939513915;7368.336849683555;7451;7529.435292352986;7644.770656880302;6920.273371295942;7059.5058345915295;7070.116235470567;7072.115985501812;7074.823129421806;4884.41387033544;4937.1113199624415;4952.727599982036;5087.64386492942;5099.719515426687;4858.927116093332;4936.234883593049;5018.84943451697;5073.036123136592;5127.359080114986 15:39:46 INFO - PROCESS | 1932 | |4;prototype.html;297.44378013115465;297.663640086702;308.1817773810532;311.26230832925967;314.51564590530586;4540.523245059271;4987.476314986929;5362.9006053758985;5454.490934170281;5487.615866889314;5033.446320904697;5071.797128114871;5079.517445842648;5121.615878809085;5155.272983550515;4027.7381970171846;4054.3918412238104;4087.509498860141;4093.5087789465306;4131.462909821719;3775.509183806101;4034.6570541504007;4060.695447841409;4065.959340406602;4076.673866090706;3608.935364067593;3800.771953682788;3814.8474061037664;4046.210988857174;4096.733712308705;3573.2138929435614;3942.645161935425;3995.680345572376;4017.718759686823;4025.818838152261;3248.47321758773;3437.3984552703178;3491.6208097801227;3521.7006554442905;3531.0692125535743;5020.497950204986;5038.672486288379;5194.662346947474;5201.55786758126;5287.973560132214;3704.759190652626;4180.47744031996;4577.519360467137;4608.953910460886;4678.415198100238;4804.519548045202;5109.565686916617;5496.780128794843;5551.528120109796;5575.721213939287;3470.722342212617;3655.798931058791;3831.578526362098;3838.059675379522;3856.9007832767807;4610.746408947506;5147.911253248021;5800.738966746507;5814.622049566765;5839.416058394169;3388.8305584720665;3726.366517691999;3831.8084095795657;3854.325493038707;3910.1397692507603;3914.393269043303;3920.5491368492417;3938.5667576566557;3944.6055394460323;3958.3666613342302;4217.966598183465;4331.7834108294455;4417.801198945991;4593.54064593538;4600.953990460052;5366.758495867617;5521.42639347792;5541.9168712469345;5566.304211973504;5569.131897073011;1070.9357438553711;1154.9076073914234;1161.6050542815315;1304.8629893861198;1338.3508998135896;836.477610748432;842.1241908415179;864.8789169516273;879.1779685993616;906.9501177435172;5057.595392368602;5304.549113325334;5319.255304257407;5323.494268044529;5369.275147855068;5449.727513624303;5611.607187496836;5826.417358264224;5838.79564215259;5879.617824841372;4713.245880659147;4741.2651039088305;4975;5316.601254905898;5346.572274218053;2996.6964370498904;3045.9847700761798;3059.5008445861367;3080.553319768621;3081.6302043754895;4878.585320247747;5490.368607610096;5903.35063143054;5991.400859913973;6009.188759517497;4886.853394398174;4975.701457912537;5356.250124982505;5558.943800677858;5891.111866168394;5556.555475562026;5870.53035757147;5983.521318294613;5991.940080599225;6050.2437195350585;5083.415407228216;5133.640645154804;5287.68273903567;5493.780248790164;5826.329972053268;5722.685252311163;5723.370429252695;5731.254936858182;5736;5738.081906894877;5567.248421463212;5921.940780592311;5998.970005149947;6032.728527216374;6105.9084113737445;3401.897943061712;3814.332491813866;3821.8089095544556;3850.5956874528893;3870.438786376016;5401.918971215514;5644.85887852807;5692.743826527899;5706.828795136153;5824.563157763269;4813.593659999591;5198.298229738937;5420.864478388072;5435.537979271806;5491.423400543045;4617.607503362252;4842.709437433765;5415.702136382537;5439.891202176013;5474.151506516497;4780.067886762048;5267.9736601316745;5354.678719276856;5366.195070739421;5430.429804870431;4139.985703502661;5200.765965531485;5605.439456054525;5745.885082298414;5748.54011679073;4575.842668558684;5124.974375128249;5614.915776263278;5621.325440947113;5641.5204707600315 15:39:46 INFO - PROCESS | 1932 | |5;yui.html;693.4002088193712;721.224683465275;777.3159619534808;791.36690647482;826.6693322670931;15692.058476491418;15932;15938.362465501366;15943.681126377465;15994.84005159948;14339.856601433983;14416.711665766677;14428.567142985701;14445;14796.482123125692;661.8676264747057;683.4088513435892;683.893996430554;685.4893104637027;686.0258433025091;682.4199430484074;832.8167803083312;853.3216093205916;855.0380821575727;864.3430992445755;888.5201990924893;896.0412358776113;903.6340282185693;903.7785742493065;905;902.341290857671;902.3818684201343;902.6840605787954;903.4172958441834;903.6159632156373;522.5871561466437;537.5887446103734;665.012456502094;667.4827009067963;667.7929841749074;14120.152790832582;14694;14761.33573989173;15079.472218472301;15100.924495377563;12660.11379203456;14303.928480357636;14671.159390802482;14779.33492992818;14952.47666331684;10136.290459667825;11619.419029048599;11826.822597660956;11910.523579056828;12213.328266945315;3813.523309586302;3875.6318149775725;3883.8446462141483;3906.2773386923227;3907.5115610548683;112281.43859280733;116042.41978790137;116100;117838.41080794542;119499.99999999913;9473.573689184002;11022.228444008842;11198.888011119785;11281.605143819927;11398.829017564743;20984.370468885958;21954.89022554877;22002.339929802132;22021.77978220197;22057.448563785714;7943.682252709942;8116.5941702914615;8207.58962051907;8351.707690230813;8458.661653533913;4474.977625111854;4513.526079761644;4549.613282870927;4641;4699.741514216683;3569.785812851237;3671.30245253401;3731.589525152231;3739.626037396293;3776.50905382304;659.1562799616498;665.8401983523919;667.3960066140054;675.3651567526509;688.8174633722068;624.8437890527368;644.8162273751958;652.5138771615177;652.7650045983443;668.7057694614355;6710.899336509956;8061.113277539466;8903.910960890438;9726.90273097274;10674.35953842772;618.6071844378852;660.3660485933461;665.5873358517654;680.8536164724608;683.5898460923406;3637.5816781069984;4641.559051890065;4679;4735.881602959885;4773.260144677581;104225.47887260515;138817.70690129188;139834.9024764609;143284;144839;93059;96179.51910240404;117785;120544;120573;90737;92988.53505732563;95423.52288238515;96660.51669741607;97588.65673731185;9173.44959302444;9698.951505242429;9875.160611348116;9879.604815807288;9894.257930655085;46767;49061;58025.419745802;58489.70755146197;58744.412555873896;51905.22142167946;52173;71850;81178.59410702909;83078.58460707658;12688;12871.549495767978;13073.899839218511;13302.600921972356;13434.462621495031;13233.801492977806;14447.422103116178;14853.925730371278;15017.924910375816;15028.474003409829;13467;13584.32078396096;13748.518801841887;14140;14195.148291102567;11455.885441145481;13332.466701331838;13357.799633005707;13754.65613359634;14047.909459918801;11613.709657258298;13297.401616927067;13411.463541458232;13444.798328024892;13878.653033673834;11215.775684486427;11300.321980681185;11449.771004579696;11663.416829158678;11811.232269902428;11360.318380897173;11395.487203075716;11426.917048529143;11491.885081149081;11548.364839933884 15:39:46 INFO - PROCESS | 1932 | __end_tp_report 15:39:46 INFO - PROCESS | 1932 | __start_cc_report 15:39:46 INFO - PROCESS | 1932 | _x_x_mozilla_cycle_collect,2366 15:39:46 INFO - PROCESS | 1932 | __end_cc_report 15:39:46 INFO - PROCESS | 1932 | __startTimestamp1475361586572__endTimestamp 15:39:46 INFO - PROCESS | 1932 | 15:39:46 INFO - PROCESS | 1932 | ------- Summary: start ------- 15:39:46 INFO - PROCESS | 1932 | Number of tests: 6 15:39:46 INFO - PROCESS | 1932 | 15:39:46 INFO - PROCESS | 1932 | [#0] dojo.html Cycles:165 Average:5350.58 Median:5475.53 stddev:564.33 (10.3%) stddev-sans-first:538.43 15:39:46 INFO - PROCESS | 1932 | Values: 3127.2 3140.9 3190.3 3210.3 3224.2 5212.1 5319.8 5349.8 5428.3 5452.3 5261.3 5328.8 5413.4 5432.8 5477.5 4877.9 4904.5 4910.7 4957.2 4970.4 4921.2 5047.5 5051.5 5053.9 5114.7 4863.8 5015.7 5021.6 5041.6 5129.2 4906.5 4977.3 4980.8 4982.4 4992.2 4946.5 4949.4 4968.1 4978.7 4996.0 5273.0 5344.9 5350.7 5352.5 5361.0 5709.4 5723.3 5726.1 5734.0 5737.2 4762.8 5594.2 5758.1 5766.6 5805.5 5359.0 5387.6 5437.9 5442.9 5470.9 5128.2 5944.9 5983.6 5996.3 6001.6 5493.1 5545.8 5562.1 5573.0 5640.2 5736.5 5761.9 5763.3 5814.9 5831.2 5678.4 5690.1 5690.3 5692.8 5700.7 4681.9 5642.7 5686.5 5706.0 5739.9 4661.7 4663.2 4665.9 4671.2 4672.4 4166.8 4178.0 4234.3 4272.6 4276.3 5413.2 5438.6 5444.3 5459.5 5461.9 5086.9 5811.7 5861.7 5890.9 5898.7 5283.1 5351.4 5408.2 5426.7 5463.4 5074.3 5092.7 5099.1 5101.2 5174.8 6078.7 6081.7 6087.8 6088.0 6090.5 5805.5 5874.0 5877.1 5880.6 5894.1 5774.9 5856.3 5857.4 6016.5 6067.6 5017.6 5474.7 5769.5 5780.5 5822.3 5817.7 5830.5 5831.4 5832.9 5840.4 4793.5 5415.1 5489.4 5537.1 5678.1 5487.6 5548.2 5583.2 5583.9 5588.7 5475.5 5491.5 5494.9 5495.9 5498.5 5571.4 5604.2 5605.1 5631.5 5695.9 5482.5 5514.7 5518.9 5533.3 5563.1 15:39:46 INFO - PROCESS | 1932 | 15:39:46 INFO - PROCESS | 1932 | [#1] ext.html Cycles:180 Average:70095.60 Median:34481.99 stddev:153296.75 (444.6%) stddev-sans-first:153637.70 15:39:46 INFO - PROCESS | 1932 | Values: 486.8 492.9 500.5 513.8 514.8 32967.7 42837.0 42870.8 42984.9 44514.1 43559.3 43873.6 43976.0 43991.3 44034.9 8772.6 9071.1 9922.6 10112.7 10126.4 8937.7 10022.4 12408.2 12408.5 12514.1 12212.1 12267.1 12288.6 12294.2 12329.3 8875.3 9142.1 12318.9 12351.7 12386.8 11811.3 12183.2 12223.1 12309.1 12347.0 42023.8 42367.8 42377.9 42420.8 42574.8 3321.2 3333.1 3336.9 3349.1 3407.4 25332.9 31384.5 31785.7 32335.0 32772.0 3236.9 3256.7 3349.2 3351.7 3374.0 495214.0 500317.0 501586.0 501693.0 502880.0 62317.0 62529.4 62568.7 62598.0 62672.0 34863.8 35322.3 35577.8 36531.0 36547.5 16407.3 16525.6 16564.8 16586.0 16586.7 11385.9 11556.2 12524.1 12526.0 12619.0 19435.3 19460.5 19552.7 19581.3 19585.9 7707.9 7790.8 7818.0 7866.4 7930.2 807.2 986.8 1021.5 1037.8 1070.6 29840.9 30266.8 30707.9 31182.6 31859.0 1032.8 1038.5 1040.8 1112.8 1201.7 11696.8 15863.6 16035.8 16084.9 16232.4 671745.0 677918.0 677925.0 678349.0 680223.0 58540.7 59130.0 59186.1 59235.0 59286.4 1207.0 1253.9 1316.8 1347.8 1367.4 29694.0 29977.8 31533.5 31691.9 32409.9 50830.5 51190.7 51517.0 51565.7 51587.0 525357.0 527436.0 527928.0 528163.0 529123.0 26742.5 26743.5 27174.7 27349.7 28166.9 47247.8 47335.3 48224.5 48288.1 48501.0 34100.1 39697.4 39728.8 39731.8 41252.6 31305.7 39011.8 40326.6 40664.8 40884.4 38581.4 39180.0 39276.4 39618.4 39719.0 32803.0 35087.0 35465.8 35545.9 35608.0 20452.8 25606.5 25748.9 25802.6 26402.7 15:39:46 INFO - PROCESS | 1932 | 15:39:46 INFO - PROCESS | 1932 | [#2] jquery.html Cycles:180 Average:34648.84 Median:11771.24 stddev:101089.60 (858.8%) stddev-sans-first:101340.38 15:39:46 INFO - PROCESS | 1932 | Values: 351.5 365.3 369.4 378.9 399.6 15025.4 15310.7 16191.0 20501.9 21006.8 20460.3 20481.4 21097.9 21119.0 21289.5 5794.7 5795.3 6084.0 6097.0 6104.3 6013.0 6151.0 6158.2 6163.7 6164.9 3859.3 4067.1 5849.8 6107.1 6158.9 6179.1 6197.7 6200.3 6232.7 6795.5 6057.6 6062.1 6064.5 6067.3 6075.9 13072.6 13111.3 13204.6 13287.2 13415.5 1913.2 2118.1 2253.0 2262.4 2594.7 9461.9 9714.0 10009.6 10225.1 10383.5 1804.8 1860.6 1890.8 1904.7 1909.7 109352.0 112586.0 113373.4 113573.0 114574.4 10531.9 10857.0 10960.7 11343.1 11370.3 14420.5 14424.4 14440.3 14441.0 14466.9 8219.7 8234.7 8357.6 8411.3 8555.7 8285.9 8312.3 8316.4 8461.2 8484.7 4375.5 4471.9 4479.0 4568.4 4580.0 1825.8 1877.0 1883.1 1891.1 1892.6 668.4 827.6 926.6 947.1 1029.5 20173.0 20923.7 20940.3 20962.9 21279.6 818.8 842.6 931.8 937.6 1029.3 9674.4 10988.4 11011.0 11018.3 11025.8 590052.0 599461.0 603099.0 617731.0 617944.0 117978.0 126190.0 134287.7 135556.0 138159.0 985.4 991.2 1022.6 1023.5 1027.0 17859.9 18113.5 18138.6 18155.1 18627.3 42110.3 48979.0 50570.0 50862.5 51279.0 80052.6 81290.2 81475.0 81946.2 82019.2 7301.9 8002.3 8091.9 8141.2 8202.2 10841.4 12125.0 12205.8 12697.7 13122.3 11287.3 11306.9 11475.8 11492.0 11653.1 5127.4 7828.0 10994.9 11164.6 11601.7 11032.4 11299.3 11615.7 11664.1 11689.8 7976.4 8232.0 8251.3 8351.9 8390.3 9410.2 10839.7 11139.5 11251.0 11595.7 15:39:46 INFO - PROCESS | 1932 | 15:39:46 INFO - PROCESS | 1932 | [#3] mootools.html Cycles:180 Average:5721.76 Median:4989.85 stddev:3193.83 (64.0%) stddev-sans-first:3187.17 15:39:46 INFO - PROCESS | 1932 | Values: 1519.2 1544.8 1760.6 1864.7 1927.2 8584.0 9088.0 9091.0 9140.5 9174.4 7948.2 8141.7 8555.6 8853.6 8863.0 2154.4 2300.1 2350.7 2387.7 2391.3 2341.0 2357.2 2365.6 2382.8 2384.9 2360.4 2378.7 2391.7 2404.9 2405.0 2354.1 2373.2 2374.0 2374.4 2376.4 2371.3 2377.9 2378.7 2381.7 2388.5 8910.7 8936.4 9017.9 9032.1 9037.7 5263.3 5414.8 5418.9 5420.7 5430.3 3536.3 3624.6 3730.6 3756.4 3769.8 1098.0 1114.6 1769.4 1830.7 1878.2 11493.2 11813.1 11817.9 11827.0 11904.0 4537.0 4611.7 4650.8 4651.4 4651.9 9291.9 9297.2 9298.2 9306.9 9309.5 5281.3 5281.7 5283.1 5285.8 5304.7 4420.5 4554.6 4554.9 4705.8 4960.9 3137.7 3702.1 3797.5 3809.8 3840.6 1018.0 1144.8 1160.2 1167.2 1172.4 859.2 876.8 886.3 901.0 927.7 5679.3 5760.7 5904.7 6009.4 6089.5 871.5 905.2 948.2 971.0 1000.6 3413.3 3796.4 3805.4 3826.9 3921.6 10541.9 10782.5 10901.6 10903.1 10907.6 10833.3 10845.8 10865.2 10869.2 10875.7 6348.8 8235.4 8312.8 8315.6 8319.1 5800.3 6060.1 6071.0 6085.7 6113.6 9398.3 9625.3 9894.0 9946.1 10038.7 9837.3 10156.9 10188.1 10223.5 10265.2 6572.3 7269.3 7538.1 7694.5 7711.4 8539.9 8614.8 8615.3 8616.9 8634.6 7406.4 7476.0 7597.7 7662.5 7699.5 6985.5 7368.3 7451.0 7529.4 7644.8 6920.3 7059.5 7070.1 7072.1 7074.8 4884.4 4937.1 4952.7 5087.6 5099.7 4858.9 4936.2 5018.8 5073.0 5127.4 15:39:46 INFO - PROCESS | 1932 | 15:39:46 INFO - PROCESS | 1932 | [#4] prototype.html Cycles:180 Average:4505.51 Median:5075.66 stddev:1387.20 (27.3%) stddev-sans-first:1354.66 15:39:46 INFO - PROCESS | 1932 | Values: 297.4 297.7 308.2 311.3 314.5 4540.5 4987.5 5362.9 5454.5 5487.6 5033.4 5071.8 5079.5 5121.6 5155.3 4027.7 4054.4 4087.5 4093.5 4131.5 3775.5 4034.7 4060.7 4066.0 4076.7 3608.9 3800.8 3814.8 4046.2 4096.7 3573.2 3942.6 3995.7 4017.7 4025.8 3248.5 3437.4 3491.6 3521.7 3531.1 5020.5 5038.7 5194.7 5201.6 5288.0 3704.8 4180.5 4577.5 4609.0 4678.4 4804.5 5109.6 5496.8 5551.5 5575.7 3470.7 3655.8 3831.6 3838.1 3856.9 4610.7 5147.9 5800.7 5814.6 5839.4 3388.8 3726.4 3831.8 3854.3 3910.1 3914.4 3920.5 3938.6 3944.6 3958.4 4218.0 4331.8 4417.8 4593.5 4601.0 5366.8 5521.4 5541.9 5566.3 5569.1 1070.9 1154.9 1161.6 1304.9 1338.4 836.5 842.1 864.9 879.2 907.0 5057.6 5304.5 5319.3 5323.5 5369.3 5449.7 5611.6 5826.4 5838.8 5879.6 4713.2 4741.3 4975.0 5316.6 5346.6 2996.7 3046.0 3059.5 3080.6 3081.6 4878.6 5490.4 5903.4 5991.4 6009.2 4886.9 4975.7 5356.3 5558.9 5891.1 5556.6 5870.5 5983.5 5991.9 6050.2 5083.4 5133.6 5287.7 5493.8 5826.3 5722.7 5723.4 5731.3 5736.0 5738.1 5567.2 5921.9 5999.0 6032.7 6105.9 3401.9 3814.3 3821.8 3850.6 3870.4 5401.9 5644.9 5692.7 5706.8 5824.6 4813.6 5198.3 5420.9 5435.5 5491.4 4617.6 4842.7 5415.7 5439.9 5474.2 4780.1 5268.0 5354.7 5366.2 5430.4 4140.0 5200.8 5605.4 5745.9 5748.5 4575.8 5125.0 5614.9 5621.3 5641.5 15:39:46 INFO - PROCESS | 1932 | 15:39:46 INFO - PROCESS | 1932 | [#5] yui.html Cycles:180 Average:22818.06 Median:3879.74 stddev:35483.19 (914.6%) stddev-sans-first:35543.84 15:39:46 INFO - PROCESS | 1932 | Values: 693.4 721.2 777.3 791.4 826.7 15692.1 15932.0 15938.4 15943.7 15994.8 14339.9 14416.7 14428.6 14445.0 14796.5 661.9 683.4 683.9 685.5 686.0 682.4 832.8 853.3 855.0 864.3 888.5 896.0 903.6 903.8 905.0 902.3 902.4 902.7 903.4 903.6 522.6 537.6 665.0 667.5 667.8 14120.2 14694.0 14761.3 15079.5 15100.9 12660.1 14303.9 14671.2 14779.3 14952.5 10136.3 11619.4 11826.8 11910.5 12213.3 3813.5 3875.6 3883.8 3906.3 3907.5 112281.4 116042.4 116100.0 117838.4 119500.0 9473.6 11022.2 11198.9 11281.6 11398.8 20984.4 21954.9 22002.3 22021.8 22057.4 7943.7 8116.6 8207.6 8351.7 8458.7 4475.0 4513.5 4549.6 4641.0 4699.7 3569.8 3671.3 3731.6 3739.6 3776.5 659.2 665.8 667.4 675.4 688.8 624.8 644.8 652.5 652.8 668.7 6710.9 8061.1 8903.9 9726.9 10674.4 618.6 660.4 665.6 680.9 683.6 3637.6 4641.6 4679.0 4735.9 4773.3 104225.5 138817.7 139834.9 143284.0 144839.0 93059.0 96179.5 117785.0 120544.0 120573.0 90737.0 92988.5 95423.5 96660.5 97588.7 9173.4 9699.0 9875.2 9879.6 9894.3 46767.0 49061.0 58025.4 58489.7 58744.4 51905.2 52173.0 71850.0 81178.6 83078.6 12688.0 12871.5 13073.9 13302.6 13434.5 13233.8 14447.4 14853.9 15017.9 15028.5 13467.0 13584.3 13748.5 14140.0 14195.1 11455.9 13332.5 13357.8 13754.7 14047.9 11613.7 13297.4 13411.5 13444.8 13878.7 11215.8 11300.3 11449.8 11663.4 11811.2 11360.3 11395.5 11426.9 11491.9 11548.4 15:39:46 INFO - PROCESS | 1932 | -------- Summary: end -------- 15:39:46 INFO - PROCESS | 1932 | 15:39:47 INFO - TEST-INFO | 1932: exit 0 15:39:47 INFO - TEST-OK | dromaeo_css | took 1118133ms 15:39:47 INFO - TEST-START | kraken 15:39:47 INFO - operating with platform_type : mac_ 15:39:47 INFO - Initialising browser for kraken test... 15:39:47 INFO - TEST-INFO | started process 1939 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/t6/qpy_kfds3kz26lvtw39n2d7m00000w/T/tmpmAZHxH/profile http://localhost:49438/getInfo.html) 15:39:49 INFO - PROCESS | 1939 | __metrics Screen width/height:1600/1200 15:39:49 INFO - PROCESS | 1939 | colorDepth:24 15:39:49 INFO - PROCESS | 1939 | Browser inner width/height: 1024/689 15:39:49 INFO - PROCESS | 1939 | __metrics 15:39:49 INFO - PROCESS | 1939 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 15:39:49 INFO - PROCESS | 1939 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 15:39:50 INFO - TEST-INFO | 1939: exit 0 15:39:50 INFO - Browser initialized. 15:39:50 INFO - Running cycle 1/1 for kraken test... 15:39:50 INFO - TEST-INFO | started process 1940 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/t6/qpy_kfds3kz26lvtw39n2d7m00000w/T/tmpmAZHxH/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:40:02 INFO - PROCESS | 1940 | RSS: Main: 236564480 15:40:02 INFO - PROCESS | 1940 | 15:40:05 INFO - PROCESS | 1940 | Cycle 1(1): loaded http://localhost:49438/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49438/tests/kraken/driver.html?testName=audio-beat-detection) 15:40:05 INFO - PROCESS | 1940 | RSS: Main: 295903232 15:40:05 INFO - PROCESS | 1940 | 15:40:08 INFO - PROCESS | 1940 | Cycle 1(1): loaded http://localhost:49438/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49438/tests/kraken/driver.html?testName=audio-dft) 15:40:09 INFO - PROCESS | 1940 | RSS: Main: 295878656 15:40:09 INFO - PROCESS | 1940 | 15:40:12 INFO - PROCESS | 1940 | Cycle 1(1): loaded http://localhost:49438/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49438/tests/kraken/driver.html?testName=audio-fft) 15:40:13 INFO - PROCESS | 1940 | RSS: Main: 385884160 15:40:13 INFO - PROCESS | 1940 | 15:40:15 INFO - PROCESS | 1940 | Cycle 1(1): loaded http://localhost:49438/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49438/tests/kraken/driver.html?testName=audio-oscillator) 15:40:15 INFO - PROCESS | 1940 | RSS: Main: 286724096 15:40:15 INFO - PROCESS | 1940 | 15:40:17 INFO - PROCESS | 1940 | Cycle 1(1): loaded http://localhost:49438/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49438/tests/kraken/driver.html?testName=imaging-gaussian-blur) 15:40:18 INFO - PROCESS | 1940 | RSS: Main: 290758656 15:40:18 INFO - PROCESS | 1940 | 15:40:21 INFO - PROCESS | 1940 | Cycle 1(1): loaded http://localhost:49438/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49438/tests/kraken/driver.html?testName=imaging-darkroom) 15:40:21 INFO - PROCESS | 1940 | RSS: Main: 329248768 15:40:21 INFO - PROCESS | 1940 | 15:40:24 INFO - PROCESS | 1940 | Cycle 1(1): loaded http://localhost:49438/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49438/tests/kraken/driver.html?testName=imaging-desaturate) 15:40:25 INFO - PROCESS | 1940 | RSS: Main: 329453568 15:40:25 INFO - PROCESS | 1940 | 15:40:28 INFO - PROCESS | 1940 | Cycle 1(1): loaded http://localhost:49438/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49438/tests/kraken/driver.html?testName=json-parse-financial) 15:40:28 INFO - PROCESS | 1940 | RSS: Main: 328433664 15:40:28 INFO - PROCESS | 1940 | 15:40:30 INFO - PROCESS | 1940 | Cycle 1(1): loaded http://localhost:49438/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49438/tests/kraken/driver.html?testName=json-stringify-tinderbox) 15:40:31 INFO - PROCESS | 1940 | RSS: Main: 304553984 15:40:31 INFO - PROCESS | 1940 | 15:40:32 INFO - PROCESS | 1940 | Cycle 1(1): loaded http://localhost:49438/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49438/tests/kraken/driver.html?testName=stanford-crypto-aes) 15:40:33 INFO - PROCESS | 1940 | RSS: Main: 307515392 15:40:33 INFO - PROCESS | 1940 | 15:40:35 INFO - PROCESS | 1940 | Cycle 1(1): loaded http://localhost:49438/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49438/tests/kraken/driver.html?testName=stanford-crypto-ccm) 15:40:36 INFO - PROCESS | 1940 | RSS: Main: 307109888 15:40:36 INFO - PROCESS | 1940 | 15:40:39 INFO - PROCESS | 1940 | Cycle 1(1): loaded http://localhost:49438/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49438/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 15:40:40 INFO - PROCESS | 1940 | RSS: Main: 322383872 15:40:40 INFO - PROCESS | 1940 | 15:40:42 INFO - PROCESS | 1940 | Cycle 1(1): loaded http://localhost:49438/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49438/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 15:40:43 INFO - PROCESS | 1940 | RSS: Main: 318513152 15:40:43 INFO - PROCESS | 1940 | 15:40:45 INFO - PROCESS | 1940 | Cycle 1(1): loaded http://localhost:49438/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49438/tests/kraken/driver.html?testName=ai-astar) 15:40:45 INFO - PROCESS | 1940 | RSS: Main: 362029056 15:40:45 INFO - PROCESS | 1940 | 15:40:45 INFO - PROCESS | 1940 | __start_tp_report 15:40:45 INFO - PROCESS | 1940 | _x_x_mozilla_page_load 15:40:45 INFO - PROCESS | 1940 | _x_x_mozilla_page_load_details 15:40:45 INFO - PROCESS | 1940 | |i|pagename|runs| 15:40:45 INFO - PROCESS | 1940 | |0;ai-astar;112;113;113;112;112;113;112;111;113;111 15:40:45 INFO - PROCESS | 1940 | |1;audio-beat-detection;161;141;139;149;114;113;111;113;142;112 15:40:45 INFO - PROCESS | 1940 | |2;audio-dft;183;185;185;184;184;185;182;184;181;184 15:40:45 INFO - PROCESS | 1940 | |3;audio-fft;80;58;59;88;60;59;61;58;81;57 15:40:45 INFO - PROCESS | 1940 | |4;audio-oscillator;87;71;93;70;69;68;91;67;69;90 15:40:45 INFO - PROCESS | 1940 | |5;imaging-gaussian-blur;88;86;92;83;87;84;88;86;88;85 15:40:45 INFO - PROCESS | 1940 | |6;imaging-darkroom;95;97;97;96;97;96;108;91;142;111 15:40:45 INFO - PROCESS | 1940 | |7;imaging-desaturate;86;85;85;87;82;84;85;84;86;87 15:40:45 INFO - PROCESS | 1940 | |8;json-parse-financial;52;58;57;54;56;56;57;57;56;55 15:40:45 INFO - PROCESS | 1940 | |9;json-stringify-tinderbox;62;71;66;77;76;71;70;69;69;71 15:40:45 INFO - PROCESS | 1940 | |10;stanford-crypto-aes;102;103;86;85;86;88;89;88;85;91 15:40:45 INFO - PROCESS | 1940 | |11;stanford-crypto-ccm;147;145;146;147;142;148;150;160;189;150 15:40:45 INFO - PROCESS | 1940 | |12;stanford-crypto-pbkdf2;165;170;171;166;170;171;172;168;172;175 15:40:45 INFO - PROCESS | 1940 | |13;stanford-crypto-sha256-iterative;67;68;79;82;83;85;67;69;69;69 15:40:45 INFO - PROCESS | 1940 | __end_tp_report 15:40:45 INFO - PROCESS | 1940 | __start_cc_report 15:40:45 INFO - PROCESS | 1940 | _x_x_mozilla_cycle_collect,1962 15:40:45 INFO - PROCESS | 1940 | __end_cc_report 15:40:45 INFO - PROCESS | 1940 | __startTimestamp1475361645649__endTimestamp 15:40:45 INFO - PROCESS | 1940 | 15:40:45 INFO - PROCESS | 1940 | ------- Summary: start ------- 15:40:45 INFO - PROCESS | 1940 | Number of tests: 14 15:40:45 INFO - PROCESS | 1940 | 15:40:45 INFO - PROCESS | 1940 | [#0] ai-astar Cycles:10 Average:112.20 Median:112.50 stddev:0.79 (0.7%) stddev-sans-first:0.83 15:40:45 INFO - PROCESS | 1940 | Values: 112.0 113.0 113.0 112.0 112.0 113.0 112.0 111.0 113.0 111.0 15:40:45 INFO - PROCESS | 1940 | 15:40:45 INFO - PROCESS | 1940 | [#1] audio-beat-detection Cycles:10 Average:129.50 Median:140.00 stddev:18.81 (13.4%) stddev-sans-first:16.13 15:40:45 INFO - PROCESS | 1940 | Values: 161.0 141.0 139.0 149.0 114.0 113.0 111.0 113.0 142.0 112.0 15:40:45 INFO - PROCESS | 1940 | 15:40:45 INFO - PROCESS | 1940 | [#2] audio-dft Cycles:10 Average:183.70 Median:184.00 stddev:1.34 (0.7%) stddev-sans-first:1.39 15:40:45 INFO - PROCESS | 1940 | Values: 183.0 185.0 185.0 184.0 184.0 185.0 182.0 184.0 181.0 184.0 15:40:45 INFO - PROCESS | 1940 | 15:40:45 INFO - PROCESS | 1940 | [#3] audio-fft Cycles:10 Average:66.10 Median:60.50 stddev:11.89 (19.7%) stddev-sans-first:11.50 15:40:45 INFO - PROCESS | 1940 | Values: 80.0 58.0 59.0 88.0 60.0 59.0 61.0 58.0 81.0 57.0 15:40:45 INFO - PROCESS | 1940 | 15:40:45 INFO - PROCESS | 1940 | [#4] audio-oscillator Cycles:10 Average:77.50 Median:79.00 stddev:11.12 (14.1%) stddev-sans-first:11.25 15:40:45 INFO - PROCESS | 1940 | Values: 87.0 71.0 93.0 70.0 69.0 68.0 91.0 67.0 69.0 90.0 15:40:45 INFO - PROCESS | 1940 | 15:40:45 INFO - PROCESS | 1940 | [#5] imaging-gaussian-blur Cycles:10 Average:86.70 Median:87.50 stddev:2.54 (2.9%) stddev-sans-first:2.65 15:40:45 INFO - PROCESS | 1940 | Values: 88.0 86.0 92.0 83.0 87.0 84.0 88.0 86.0 88.0 85.0 15:40:45 INFO - PROCESS | 1940 | 15:40:45 INFO - PROCESS | 1940 | [#6] imaging-darkroom Cycles:10 Average:103.00 Median:96.00 stddev:15.00 (15.6%) stddev-sans-first:15.62 15:40:45 INFO - PROCESS | 1940 | Values: 95.0 97.0 97.0 96.0 97.0 96.0 108.0 91.0 142.0 111.0 15:40:45 INFO - PROCESS | 1940 | 15:40:45 INFO - PROCESS | 1940 | [#7] imaging-desaturate Cycles:10 Average:85.10 Median:85.50 stddev:1.52 (1.8%) stddev-sans-first:1.58 15:40:45 INFO - PROCESS | 1940 | Values: 86.0 85.0 85.0 87.0 82.0 84.0 85.0 84.0 86.0 87.0 15:40:45 INFO - PROCESS | 1940 | 15:40:45 INFO - PROCESS | 1940 | [#8] json-parse-financial Cycles:10 Average:55.80 Median:56.50 stddev:1.75 (3.1%) stddev-sans-first:1.20 15:40:45 INFO - PROCESS | 1940 | Values: 52.0 58.0 57.0 54.0 56.0 56.0 57.0 57.0 56.0 55.0 15:40:45 INFO - PROCESS | 1940 | 15:40:45 INFO - PROCESS | 1940 | [#9] json-stringify-tinderbox Cycles:10 Average:70.20 Median:71.00 stddev:4.34 (6.1%) stddev-sans-first:3.44 15:40:45 INFO - PROCESS | 1940 | Values: 62.0 71.0 66.0 77.0 76.0 71.0 70.0 69.0 69.0 71.0 15:40:45 INFO - PROCESS | 1940 | 15:40:45 INFO - PROCESS | 1940 | [#10] stanford-crypto-aes Cycles:10 Average:90.30 Median:87.00 stddev:6.70 (7.7%) stddev-sans-first:5.61 15:40:45 INFO - PROCESS | 1940 | Values: 102.0 103.0 86.0 85.0 86.0 88.0 89.0 88.0 85.0 91.0 15:40:45 INFO - PROCESS | 1940 | 15:40:45 INFO - PROCESS | 1940 | [#11] stanford-crypto-ccm Cycles:10 Average:152.40 Median:149.00 stddev:13.70 (9.2%) stddev-sans-first:14.40 15:40:45 INFO - PROCESS | 1940 | Values: 147.0 145.0 146.0 147.0 142.0 148.0 150.0 160.0 189.0 150.0 15:40:45 INFO - PROCESS | 1940 | 15:40:45 INFO - PROCESS | 1940 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:170.00 Median:171.00 stddev:2.98 (1.7%) stddev-sans-first:2.55 15:40:45 INFO - PROCESS | 1940 | Values: 165.0 170.0 171.0 166.0 170.0 171.0 172.0 168.0 172.0 175.0 15:40:45 INFO - PROCESS | 1940 | 15:40:45 INFO - PROCESS | 1940 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:73.80 Median:74.00 stddev:7.45 (10.1%) stddev-sans-first:7.49 15:40:45 INFO - PROCESS | 1940 | Values: 67.0 68.0 79.0 82.0 83.0 85.0 67.0 69.0 69.0 69.0 15:40:45 INFO - PROCESS | 1940 | -------- Summary: end -------- 15:40:45 INFO - PROCESS | 1940 | 15:40:46 INFO - TEST-INFO | 1940: exit 0 15:40:46 INFO - TEST-OK | kraken | took 59244ms 15:40:46 INFO - SUITE-END | took 1177s 15:40:46 INFO - Completed test suite (00:19:37) 15:40:46 INFO - javascript benchmark 15:40:46 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3127.249460129569, 3140.89006884759, 3190.3140824722664, 3210.325831575369, 3224.193951512122, 5212.139996900507, 5319.813806516772, 5349.7592608332625, 5428.267183930173, 5452.345718513774, 5261.316028916235, 5328.760205790739, 5413.431589683086, 5432.782688692458, 5477.534409575181, 4877.902441951159, 4904.534069263432, 4910.7299098549565, 4957.206846904496, 4970.403551573798, 4921.237208232729, 5047.47001564836, 5051.469595692454, 5053.898922021557, 5114.667546609477, 4863.805447782084, 5015.724135172564, 5021.62337824661, 5041.59667226621, 5129.204973229137, 4906.460289368167, 4977.303177555145, 4980.800767969277, 4982.377202849644, 4992.226204938241, 4946.480619534969, 4949.381327334083, 4968.130577149021, 4978.6514943953935, 4995.975020124913, 5273.024490469237, 5344.946550534483, 5350.732463376855, 5352.518273355417, 5360.973195134038, 5709.400512946122, 5723.313202415695, 5726.055200891848, 5734.0, 5737.168110623936, 4762.785674644615, 5594.160875868612, 5758.078707406795, 5766.567507436959, 5805.5065319447895, 5359.035373632705, 5387.622866399314, 5437.945620543823, 5442.891142177174, 5470.91793623096, 5128.153854613947, 5944.881102377928, 5983.581149319506, 5996.2504686914135, 6001.5798894077, 5493.093639549509, 5545.833624991256, 5562.082256427725, 5573.0, 5640.182173584807, 5736.483716465538, 5761.9135712964335, 5763.250777398911, 5814.94185058144, 5831.183634291203, 5678.375378708339, 5690.118031705092, 5690.345610254791, 5692.800751973661, 5700.657960522382, 4681.9297710534365, 5642.7178641067785, 5686.488216060575, 5706.0, 5739.8565035874435, 4661.696989695659, 4663.230566956482, 4665.86002419928, 4671.229247174178, 4672.415948006499, 4166.791660416967, 4178.039051018222, 4234.25900467417, 4272.6154646082, 4276.294411422143, 5413.188021796762, 5438.619296649275, 5444.3194600674915, 5459.5359394451125, 5461.890762184734, 5086.880886205029, 5811.709414529257, 5861.677607731531, 5890.911636325459, 5898.675572843535, 5283.128283833202, 5351.411344752074, 5408.161734931092, 5426.728663566806, 5463.399026107125, 5074.2895994560795, 5092.745362731848, 5099.1331473648825, 5101.20931255656, 5174.844754657366, 6078.665673388006, 6081.665508397081, 6087.817365479043, 6087.969560152171, 6090.482309003784, 5805.506531944832, 5873.970630146822, 5877.059670452707, 5880.558958078076, 5894.056950888009, 5774.942250577608, 5856.297244330707, 5857.414258574108, 6016.518678505621, 6067.575269731076, 5017.573506251863, 5474.726263686879, 5769.509591684586, 5780.479756821906, 5822.330432000204, 5817.738201780845, 5830.53355731532, 5831.4168583141345, 5832.883342333214, 5840.357560668238, 4793.520647935178, 5415.079436495726, 5489.3687225969525, 5537.058700021086, 5678.148277758204, 5487.5884308677805, 5548.223248745259, 5583.190437386637, 5583.944160558505, 5588.6646801192055, 5475.534579560623, 5491.505764481216, 5494.890102197854, 5495.890082198253, 5498.505134537911, 5571.387147413862, 5604.187392828098, 5605.075162598126, 5631.465010823965, 5695.914561281501, 5482.479164479368, 5514.724263786875, 5518.91721624184, 5533.280673512418, 5563.082091455027], "value": 5317.6378315575, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [486.763919499043, 492.9383827021622, 500.5495054450994, 513.7636687123922, 514.8455463360993, 32967.67032329676, 42837.0, 42870.7856460718, 42984.92537686559, 44514.10971780562, 43559.34660980088, 43873.56126438735, 43976.0, 43991.340129898075, 44034.89912752183, 8772.56137193141, 9071.138241867046, 9922.603095876157, 10112.696619101402, 10126.392416454999, 8937.73186804397, 10022.398656080622, 12408.193467424633, 12408.456266465353, 12514.12401131921, 12212.145149839514, 12267.141300108997, 12288.5699000535, 12294.20087694297, 12329.321887296195, 8875.334349923782, 9142.131497507726, 12318.876811231907, 12351.691207719834, 12386.81419778704, 11811.29132252059, 12183.208091474025, 12223.066464048565, 12309.138360314782, 12347.0, 42023.7898810504, 42367.788161059, 42377.94055148615, 42420.78789606032, 42574.78712606417, 3321.168607072715, 3333.0834020644274, 3336.9499457508155, 3349.112485191402, 3407.4207384744404, 25332.873335633387, 31384.529232061537, 31785.682143178503, 32335.0, 32772.0, 3236.9190770230935, 3256.6743325667244, 3349.2416481347245, 3351.7318614511023, 3373.9831300843584, 495214.0, 500317.0, 501586.0, 501693.0, 502880.0, 62317.0, 62529.374706253264, 62568.74862502724, 62598.0, 62672.0, 34863.825680871436, 35322.29355412877, 35577.8221108898, 36531.0, 36547.451788223196, 16407.343706251617, 16525.58686032859, 16564.83435165657, 16586.004839709654, 16586.6682666346, 11385.886141138648, 11556.248843825124, 12524.123311368117, 12526.0, 12619.0, 19435.282681043514, 19460.500963391125, 19552.676753562893, 19581.349097287093, 19585.902070489556, 7707.882357058195, 7790.818570800185, 7818.0, 7866.426714656977, 7930.246626570582, 807.1556493412525, 986.8026394721085, 1021.545412291538, 1037.834604456405, 1070.550368845087, 29840.850795745882, 30266.84866575697, 30707.9252226171, 31182.555408230626, 31859.044228673178, 1032.8037672842136, 1038.46000079958, 1040.765827688764, 1112.7703887204523, 1201.6515210589007, 11696.766064678828, 15863.603409914615, 16035.839641603668, 16084.919575402282, 16232.431864884673, 671745.0, 677918.0, 677925.0, 678349.0, 680223.0, 58540.707296463246, 59130.0, 59186.11220831691, 59235.0, 59286.40713592895, 1206.9758604827855, 1253.8746125387388, 1316.8090626859243, 1347.7776166932347, 1367.3641756583236, 29694.0, 29977.779876621116, 31533.526997094603, 31691.890783822455, 32409.86565470197, 50830.491695084056, 51190.74404627953, 51517.0, 51565.742171288905, 51587.0, 525357.0, 527436.0, 527928.0, 528163.0, 529123.0, 26742.465150697266, 26743.465130697667, 27174.72825271722, 27349.726502734717, 28166.85916570404, 47247.76376118098, 47335.28997065116, 48224.517754823406, 48288.100939306656, 48501.0, 34100.14749631279, 39697.40453893136, 39728.80135599303, 39731.801340994265, 41252.58747412488, 31305.686943130277, 39011.80494097511, 40326.59673403228, 40664.79667601643, 40884.38673419961, 38581.4212786814, 39180.0, 39276.41085383664, 39618.405723914744, 39719.0, 32803.0, 35087.0, 35465.82267088648, 35545.93362199138, 35608.0, 20452.79547204509, 25606.487870242865, 25748.8712556436, 25802.61296080598, 26402.73597264003], "value": 20294.708975992024, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [351.4991137628878, 365.2858661317125, 369.41447805228694, 378.9071677439026, 399.60638770810766, 15025.39898404064, 15310.693786124284, 16191.0, 20501.897490512565, 21006.789932100673, 20460.28389006385, 20481.385558433234, 21097.894510527425, 21119.049642766073, 21289.467763305925, 5794.739236734336, 5795.304563452391, 6084.0, 6096.969515152418, 6104.328523862371, 6012.969935150318, 6151.046587778901, 6158.168647232634, 6163.660998645095, 6164.876702465948, 3859.2667393195343, 4067.0849058961644, 5849.795257165979, 6107.114468402079, 6158.876822463548, 6179.134921111048, 6197.6901154942525, 6200.286966998808, 6232.657203853832, 6795.524313298071, 6057.606255593417, 6062.120992456069, 6064.4845188158615, 6067.302260240041, 6075.939240607581, 13072.607821765363, 13111.278879661615, 13204.60386188416, 13287.20276783396, 13415.46338146473, 1913.244268513943, 2118.1315660579244, 2253.0086761824746, 2262.4457008032973, 2594.662693849806, 9461.852759083444, 9714.0, 10009.59961601535, 10225.079742823107, 10383.532741026673, 1804.7834259888766, 1860.6278744251204, 1890.8392786613153, 1904.7428597139346, 1909.6849019911697, 109352.0, 112586.0, 113373.43313283463, 113573.0, 114574.42712786466, 10531.94734026325, 10857.0, 10960.725981850359, 11343.092552595772, 11370.317780933005, 14420.495282665057, 14424.42302307896, 14440.277986100653, 14441.0, 14466.927665361605, 8219.671213151527, 8234.670613175525, 8357.582120894052, 8411.327093832479, 8555.657773688983, 8285.917140828635, 8312.25189732927, 8316.417850750511, 8461.196186362286, 8484.745457636282, 4375.5405682403525, 4471.910561788746, 4478.977605111953, 4568.383268258809, 4579.977100114545, 1825.8174182581636, 1876.9718454223198, 1883.1337584710911, 1891.0639233579466, 1892.5836316010455, 668.4318329420051, 827.6192951242496, 926.6339795780764, 947.0624082158613, 1029.4904022508906, 20172.982701730005, 20923.68614470784, 20940.267090651752, 20962.895185523976, 21279.574408511744, 818.8075802186481, 842.6123982967781, 931.7624005878457, 937.5687183895484, 1029.3052189771875, 9674.419534827792, 10988.395638239814, 11011.0, 11018.338899665885, 11025.83461248082, 590052.0497397486, 599461.008084879, 603099.0, 617731.0, 617944.0, 117978.0, 126190.0, 134287.65712342947, 135556.0, 138159.0, 985.3644399362514, 991.1822746234385, 1022.616518805448, 1023.5087158164123, 1027.014066496157, 17859.910700446413, 18113.547161321072, 18138.637227255116, 18155.092245387943, 18627.348042818438, 42110.315587376164, 48979.0, 50569.99999999853, 50862.49137508726, 51279.0, 80052.59973700094, 81290.18709813063, 81475.0, 81946.18053819386, 82019.17980820115, 7301.917140373829, 8002.279794818494, 8091.87862182056, 8141.185881411811, 8202.220789025034, 10841.403722795321, 12125.0, 12205.755884882075, 12697.746045079231, 13122.343882806012, 11287.275199184085, 11306.94346528262, 11475.770484590428, 11492.0, 11653.067754579444, 5127.435982042047, 7828.0, 10994.91096691626, 11164.609238676607, 11601.709957250798, 11032.448377580928, 11299.265547739371, 11615.6515304541, 11664.125190610908, 11689.824652630388, 7976.401769867167, 8232.0, 8251.33989280844, 8351.916480835114, 8390.328773697967, 9410.153086222273, 10839.67480975572, 11139.498722557342, 11251.043661288555, 11595.710107247387], "value": 9266.334987878403, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1519.179642992784, 1544.7605621128725, 1760.6478704259152, 1864.6550388178196, 1927.2483731344778, 8583.957080214597, 9088.0, 9090.999990001095, 9140.497272650002, 9174.357794954356, 7948.165442628528, 8141.715039973602, 8555.61499732512, 8853.601587928559, 8862.955685221565, 2154.439845640137, 2300.1029598456616, 2350.670906073151, 2387.7254115776645, 2391.258709799959, 2340.9765902341014, 2357.2456813819585, 2365.6215005599106, 2382.833201675883, 2384.940376490584, 2360.4216966843146, 2378.6550950112223, 2391.6771235883107, 2404.915827946022, 2405.037984806083, 2354.058376649337, 2373.22870067228, 2374.0, 2374.3589230907646, 2376.3940195250275, 2371.3241726107976, 2377.9048838046456, 2378.726446458662, 2381.714194296678, 2388.534235824015, 8910.688125915562, 8936.419132756415, 9017.864732029026, 9032.09679032098, 9037.683681071197, 5263.289455923439, 5414.756335964852, 5418.91871621926, 5420.7018613976215, 5430.294061771944, 3536.3104194682046, 3624.638101464326, 3730.645588669072, 3756.398976163801, 3769.754965927206, 1097.9890201098046, 1114.5653195253858, 1769.3807167491404, 1830.7162389829598, 1878.2111513164505, 11493.195476316661, 11813.1140164488, 11817.881821181763, 11827.0, 11903.988161006326, 4536.954630453719, 4611.700239484423, 4650.837220697327, 4651.395318608559, 4651.883702907455, 9291.907080929239, 9297.209737172416, 9298.163165315154, 9306.90693093074, 9309.534523273946, 5281.313429254173, 5281.656692314988, 5283.101872681654, 5285.788568457218, 5304.6817190967895, 4420.491643461043, 4554.635629149726, 4554.931676024796, 4705.788239529229, 4960.851174464772, 3137.6862313768897, 3702.0564203792687, 3797.487339209227, 3809.7523660961947, 3840.5967373425387, 1017.9541920613442, 1144.811106167473, 1160.1704781081398, 1167.1870791392703, 1172.355204637463, 859.1537335724306, 876.7808047988003, 886.2688282167238, 900.9953901400023, 927.7216834949488, 5679.346875109332, 5760.654360738369, 5904.704764761745, 6009.429104235091, 6089.512838972872, 871.5293741379584, 905.2079430498314, 948.2224575847877, 970.9951450242845, 1000.554753134848, 3413.2832105258176, 3796.354619714655, 3805.448210009533, 3826.9234615307537, 3921.6078392160557, 10541.947290263653, 10782.514786834612, 10901.563937442572, 10903.073238774634, 10907.618233361953, 10833.349999000086, 10845.783084338269, 10865.239433239756, 10869.184811139037, 10875.673729788119, 6348.809535714028, 8235.38234632405, 8312.792180195544, 8315.625796839156, 8319.084900660922, 5800.332961709458, 6060.121282414113, 6070.969645151746, 6085.726142323518, 6113.602615829956, 9398.2951278653, 9625.278104142077, 9893.950530247303, 9946.104850563484, 10038.698839034842, 9837.262205334486, 10156.89843101589, 10188.134008609055, 10223.488825558841, 10265.178785696977, 6572.34276572339, 7269.345758881726, 7538.09542854861, 7694.499857509244, 7711.421643376882, 8539.871901921351, 8614.79392884984, 8615.3107751381, 8616.870746938675, 8634.61144248523, 7406.444516661164, 7476.0, 7597.6581053853815, 7662.501937374052, 7699.499532530368, 6985.545939513915, 7368.336849683555, 7451.0, 7529.435292352986, 7644.770656880302, 6920.273371295942, 7059.5058345915295, 7070.116235470567, 7072.115985501812, 7074.823129421806, 4884.41387033544, 4937.1113199624415, 4952.727599982036, 5087.64386492942, 5099.719515426687, 4858.927116093332, 4936.234883593049, 5018.84943451697, 5073.036123136592, 5127.359080114986], "value": 4624.705016612993, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [297.44378013115465, 297.663640086702, 308.1817773810532, 311.26230832925967, 314.51564590530586, 4540.523245059271, 4987.476314986929, 5362.9006053758985, 5454.490934170281, 5487.615866889314, 5033.446320904697, 5071.797128114871, 5079.517445842648, 5121.615878809085, 5155.272983550515, 4027.7381970171846, 4054.3918412238104, 4087.509498860141, 4093.5087789465306, 4131.462909821719, 3775.509183806101, 4034.6570541504007, 4060.695447841409, 4065.959340406602, 4076.673866090706, 3608.935364067593, 3800.771953682788, 3814.8474061037664, 4046.210988857174, 4096.733712308705, 3573.2138929435614, 3942.645161935425, 3995.680345572376, 4017.718759686823, 4025.818838152261, 3248.47321758773, 3437.3984552703178, 3491.6208097801227, 3521.7006554442905, 3531.0692125535743, 5020.497950204986, 5038.672486288379, 5194.662346947474, 5201.55786758126, 5287.973560132214, 3704.759190652626, 4180.47744031996, 4577.519360467137, 4608.953910460886, 4678.415198100238, 4804.519548045202, 5109.565686916617, 5496.780128794843, 5551.528120109796, 5575.721213939287, 3470.722342212617, 3655.798931058791, 3831.578526362098, 3838.059675379522, 3856.9007832767807, 4610.746408947506, 5147.911253248021, 5800.738966746507, 5814.622049566765, 5839.416058394169, 3388.8305584720665, 3726.366517691999, 3831.8084095795657, 3854.325493038707, 3910.1397692507603, 3914.393269043303, 3920.5491368492417, 3938.5667576566557, 3944.6055394460323, 3958.3666613342302, 4217.966598183465, 4331.7834108294455, 4417.801198945991, 4593.54064593538, 4600.953990460052, 5366.758495867617, 5521.42639347792, 5541.9168712469345, 5566.304211973504, 5569.131897073011, 1070.9357438553711, 1154.9076073914234, 1161.6050542815315, 1304.8629893861198, 1338.3508998135896, 836.477610748432, 842.1241908415179, 864.8789169516273, 879.1779685993616, 906.9501177435172, 5057.595392368602, 5304.549113325334, 5319.255304257407, 5323.494268044529, 5369.275147855068, 5449.727513624303, 5611.607187496836, 5826.417358264224, 5838.79564215259, 5879.617824841372, 4713.245880659147, 4741.2651039088305, 4975.0, 5316.601254905898, 5346.572274218053, 2996.6964370498904, 3045.9847700761798, 3059.5008445861367, 3080.553319768621, 3081.6302043754895, 4878.585320247747, 5490.368607610096, 5903.35063143054, 5991.400859913973, 6009.188759517497, 4886.853394398174, 4975.701457912537, 5356.250124982505, 5558.943800677858, 5891.111866168394, 5556.555475562026, 5870.53035757147, 5983.521318294613, 5991.940080599225, 6050.2437195350585, 5083.415407228216, 5133.640645154804, 5287.68273903567, 5493.780248790164, 5826.329972053268, 5722.685252311163, 5723.370429252695, 5731.254936858182, 5736.0, 5738.081906894877, 5567.248421463212, 5921.940780592311, 5998.970005149947, 6032.728527216374, 6105.9084113737445, 3401.897943061712, 3814.332491813866, 3821.8089095544556, 3850.5956874528893, 3870.438786376016, 5401.918971215514, 5644.85887852807, 5692.743826527899, 5706.828795136153, 5824.563157763269, 4813.593659999591, 5198.298229738937, 5420.864478388072, 5435.537979271806, 5491.423400543045, 4617.607503362252, 4842.709437433765, 5415.702136382537, 5439.891202176013, 5474.151506516497, 4780.067886762048, 5267.9736601316745, 5354.678719276856, 5366.195070739421, 5430.429804870431, 4139.985703502661, 5200.765965531485, 5605.439456054525, 5745.885082298414, 5748.54011679073, 4575.842668558684, 5124.974375128249, 5614.915776263278, 5621.325440947113, 5641.5204707600315], "value": 4058.110363499081, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [693.4002088193712, 721.224683465275, 777.3159619534808, 791.36690647482, 826.6693322670931, 15692.058476491418, 15932.0, 15938.362465501366, 15943.681126377465, 15994.84005159948, 14339.856601433983, 14416.711665766677, 14428.567142985701, 14445.0, 14796.482123125692, 661.8676264747057, 683.4088513435892, 683.893996430554, 685.4893104637027, 686.0258433025091, 682.4199430484074, 832.8167803083312, 853.3216093205916, 855.0380821575727, 864.3430992445755, 888.5201990924893, 896.0412358776113, 903.6340282185693, 903.7785742493065, 905.0, 902.341290857671, 902.3818684201343, 902.6840605787954, 903.4172958441834, 903.6159632156373, 522.5871561466437, 537.5887446103734, 665.012456502094, 667.4827009067963, 667.7929841749074, 14120.152790832582, 14694.0, 14761.33573989173, 15079.472218472301, 15100.924495377563, 12660.11379203456, 14303.928480357636, 14671.159390802482, 14779.33492992818, 14952.47666331684, 10136.290459667825, 11619.419029048599, 11826.822597660956, 11910.523579056828, 12213.328266945315, 3813.523309586302, 3875.6318149775725, 3883.8446462141483, 3906.2773386923227, 3907.5115610548683, 112281.43859280733, 116042.41978790137, 116100.0, 117838.41080794542, 119499.99999999913, 9473.573689184002, 11022.228444008842, 11198.888011119785, 11281.605143819927, 11398.829017564743, 20984.370468885958, 21954.89022554877, 22002.339929802132, 22021.77978220197, 22057.448563785714, 7943.682252709942, 8116.5941702914615, 8207.58962051907, 8351.707690230813, 8458.661653533913, 4474.977625111854, 4513.526079761644, 4549.613282870927, 4641.0, 4699.741514216683, 3569.785812851237, 3671.30245253401, 3731.589525152231, 3739.626037396293, 3776.50905382304, 659.1562799616498, 665.8401983523919, 667.3960066140054, 675.3651567526509, 688.8174633722068, 624.8437890527368, 644.8162273751958, 652.5138771615177, 652.7650045983443, 668.7057694614355, 6710.899336509956, 8061.113277539466, 8903.910960890438, 9726.90273097274, 10674.35953842772, 618.6071844378852, 660.3660485933461, 665.5873358517654, 680.8536164724608, 683.5898460923406, 3637.5816781069984, 4641.559051890065, 4679.0, 4735.881602959885, 4773.260144677581, 104225.47887260515, 138817.70690129188, 139834.9024764609, 143284.0, 144839.0, 93059.0, 96179.51910240404, 117785.0, 120544.0, 120573.0, 90737.0, 92988.53505732563, 95423.52288238515, 96660.51669741607, 97588.65673731185, 9173.44959302444, 9698.951505242429, 9875.160611348116, 9879.604815807288, 9894.257930655085, 46767.0, 49061.0, 58025.419745802, 58489.70755146197, 58744.412555873896, 51905.22142167946, 52173.0, 71850.0, 81178.59410702909, 83078.58460707658, 12688.0, 12871.549495767978, 13073.899839218511, 13302.600921972356, 13434.462621495031, 13233.801492977806, 14447.422103116178, 14853.925730371278, 15017.924910375816, 15028.474003409829, 13467.0, 13584.32078396096, 13748.518801841887, 14140.0, 14195.148291102567, 11455.885441145481, 13332.466701331838, 13357.799633005707, 13754.65613359634, 14047.909459918801, 11613.709657258298, 13297.401616927067, 13411.463541458232, 13444.798328024892, 13878.653033673834, 11215.775684486427, 11300.321980681185, 11449.771004579696, 11663.416829158678, 11811.232269902428, 11360.318380897173, 11395.487203075716, 11426.917048529143, 11491.885081149081, 11548.364839933884], "value": 7634.33997485774, "unit": "score"}], "name": "dromaeo_css", "value": 7233.904119800745, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 113.0, 113.0, 112.0, 112.0, 113.0, 112.0, 111.0, 113.0, 111.0], "value": 112.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 141.0, 139.0, 149.0, 114.0, 113.0, 111.0, 113.0, 142.0, 112.0], "value": 129.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [183.0, 185.0, 185.0, 184.0, 184.0, 185.0, 182.0, 184.0, 181.0, 184.0], "value": 183.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 58.0, 59.0, 88.0, 60.0, 59.0, 61.0, 58.0, 81.0, 57.0], "value": 66.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 71.0, 93.0, 70.0, 69.0, 68.0, 91.0, 67.0, 69.0, 90.0], "value": 77.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 86.0, 92.0, 83.0, 87.0, 84.0, 88.0, 86.0, 88.0, 85.0], "value": 86.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 97.0, 97.0, 96.0, 97.0, 96.0, 108.0, 91.0, 142.0, 111.0], "value": 103.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 85.0, 85.0, 87.0, 82.0, 84.0, 85.0, 84.0, 86.0, 87.0], "value": 85.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [52.0, 58.0, 57.0, 54.0, 56.0, 56.0, 57.0, 57.0, 56.0, 55.0], "value": 55.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 71.0, 66.0, 77.0, 76.0, 71.0, 70.0, 69.0, 69.0, 71.0], "value": 70.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [102.0, 103.0, 86.0, 85.0, 86.0, 88.0, 89.0, 88.0, 85.0, 91.0], "value": 90.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 145.0, 146.0, 147.0, 142.0, 148.0, 150.0, 160.0, 189.0, 150.0], "value": 152.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [165.0, 170.0, 171.0, 166.0, 170.0, 171.0, 172.0, 168.0, 172.0, 175.0], "value": 170.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 68.0, 79.0, 82.0, 83.0, 85.0, 67.0, 69.0, 69.0, 69.0], "value": 73.8, "unit": "score"}], "name": "kraken", "value": 1456.3000000000002, "alertThreshold": 2.0}]} 15:40:46 INFO - Return code: 0 15:40:46 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 15:40:46 INFO - # TBPL SUCCESS # 15:40:46 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 15:40:46 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 15:40:46 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 15:40:46 INFO - 2016-10-01 15:40:46.972 screenresolution[1944:11688] starting screenresolution argv=screenresolution get 15:40:46 INFO - 2016-10-01 15:40:46.974 screenresolution[1944:11688] Display 0: 1600x1200x32@60 15:40:46 INFO - 2016-10-01 15:40:46.980 screenresolution[1945:11691] starting screenresolution argv=screenresolution list 15:40:46 INFO - Available Modes on Display 0 15:40:46 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 15:40:46 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 15:40:46 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 15:40:46 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 15:40:47 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 15:40:47 INFO - Intel Iris: 15:40:47 INFO - Chipset Model: Intel Iris 15:40:47 INFO - Type: GPU 15:40:47 INFO - Bus: Built-In 15:40:47 INFO - VRAM (Dynamic, Max): 1536 MB 15:40:47 INFO - Vendor: Intel (0x8086) 15:40:47 INFO - Device ID: 0x0a2e 15:40:47 INFO - Revision ID: 0x0009 15:40:47 INFO - Displays: 15:40:47 INFO - Display: 15:40:47 INFO - Resolution: 1600 x 1200 @ 60 Hz 15:40:47 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 15:40:47 INFO - Display Serial Number: 1600x1200 60 15:40:47 INFO - Main Display: Yes 15:40:47 INFO - Mirror: Off 15:40:47 INFO - Online: Yes 15:40:47 INFO - Rotation: Supported 15:40:47 INFO - Return code: 0 15:40:47 INFO - Running post-action listener: _resource_record_post_action 15:40:47 INFO - [mozharness: 2016-10-01 22:40:47.216619Z] Finished run-tests step (success) 15:40:47 INFO - Running post-run listener: _resource_record_post_run 15:40:47 INFO - Total resource usage - Wall time: 1213s; CPU: 54.0%; Read bytes: 3706880; Write bytes: 706854912; Read time: 28; Write time: 1864 15:40:47 INFO - TinderboxPrint: CPU usage
53.7% 15:40:47 INFO - TinderboxPrint: I/O read bytes / time
3,706,880 / 28 15:40:47 INFO - TinderboxPrint: I/O write bytes / time
706,854,912 / 1,864 15:40:47 INFO - TinderboxPrint: CPU idle
2,247.7 (46.3%) 15:40:47 INFO - TinderboxPrint: CPU system
975.5 (20.1%) 15:40:47 INFO - TinderboxPrint: CPU user
1,630.5 (33.6%) 15:40:47 INFO - TinderboxPrint: Swap in / out
412,413,952 / 0 15:40:47 INFO - install - Wall time: 35s; CPU: 55.0%; Read bytes: 0; Write bytes: 410912768; Read time: 0; Write time: 752 15:40:47 INFO - run-tests - Wall time: 1179s; CPU: 54.0%; Read bytes: 1134592; Write bytes: 294418432; Read time: 18; Write time: 1106 15:40:47 INFO - Running post-run listener: _upload_blobber_files 15:40:47 INFO - Blob upload gear active. 15:40:47 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 15:40:47 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 15:40:47 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'] 15:40:47 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 15:40:48 INFO - (blobuploader) - INFO - Open directory for files ... 15:40:48 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 15:40:48 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:40:48 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:40:48 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 15:40:48 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:40:48 INFO - (blobuploader) - INFO - Done attempting. 15:40:48 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 15:40:48 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:40:48 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:40:49 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 15:40:49 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:40:49 INFO - (blobuploader) - INFO - Done attempting. 15:40:49 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 15:40:49 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:40:49 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:40:52 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 15:40:52 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:40:52 INFO - (blobuploader) - INFO - Done attempting. 15:40:52 INFO - (blobuploader) - INFO - Iteration through files over. 15:40:52 INFO - Return code: 0 15:40:52 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 15:40:52 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 15:40:52 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/21346f75a34542dc4ef54ccb993f1edd64c9b2ffe706cbb5063524b0925199a864be3abceab9b64b9658d713a7562167d5e0c21f5defca8e9cf85701ab56acc6", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/bf951355499367be00fe39c3bf399ada7e07251e6a69df0655080fefd3ee5f3302ae2d72e197d67d2a8f205a9dc4d06229f09176e699f833ffd3bb802b5b165a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/e41811d00ee363e3f6070274040cd8a5166402f0847715d23f73fe17a56af0f728240bc0f5eff48828c58c060469d668c02090780ffb6c23af4102b948f4f64c"} 15:40:52 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 15:40:52 INFO - Writing to file /builds/slave/test/properties/blobber_files 15:40:52 INFO - Contents: 15:40:52 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/21346f75a34542dc4ef54ccb993f1edd64c9b2ffe706cbb5063524b0925199a864be3abceab9b64b9658d713a7562167d5e0c21f5defca8e9cf85701ab56acc6", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/bf951355499367be00fe39c3bf399ada7e07251e6a69df0655080fefd3ee5f3302ae2d72e197d67d2a8f205a9dc4d06229f09176e699f833ffd3bb802b5b165a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/e41811d00ee363e3f6070274040cd8a5166402f0847715d23f73fe17a56af0f728240bc0f5eff48828c58c060469d668c02090780ffb6c23af4102b948f4f64c"} 15:40:52 INFO - Running post-run listener: copy_logs_to_upload_dir 15:40:52 INFO - Copying logs to upload dir... 15:40:52 INFO - mkdir: /builds/slave/test/build/upload/logs 15:40:52 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1273.717272 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2016-10-01 15:40:52.145274) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-01 15:40:52.148511) ========= 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.T0A3Ghbg0c/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.dt5Iifp37T/Listeners TMPDIR=/var/folders/t6/qpy_kfds3kz26lvtw39n2d7m00000w/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_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/21346f75a34542dc4ef54ccb993f1edd64c9b2ffe706cbb5063524b0925199a864be3abceab9b64b9658d713a7562167d5e0c21f5defca8e9cf85701ab56acc6", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/bf951355499367be00fe39c3bf399ada7e07251e6a69df0655080fefd3ee5f3302ae2d72e197d67d2a8f205a9dc4d06229f09176e699f833ffd3bb802b5b165a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/e41811d00ee363e3f6070274040cd8a5166402f0847715d23f73fe17a56af0f728240bc0f5eff48828c58c060469d668c02090780ffb6c23af4102b948f4f64c"} build_url:https://queue.taskcluster.net/v1/task/Uvk4IryfSfim7_tiAfCcjw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011446 build_url: 'https://queue.taskcluster.net/v1/task/Uvk4IryfSfim7_tiAfCcjw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/21346f75a34542dc4ef54ccb993f1edd64c9b2ffe706cbb5063524b0925199a864be3abceab9b64b9658d713a7562167d5e0c21f5defca8e9cf85701ab56acc6", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/bf951355499367be00fe39c3bf399ada7e07251e6a69df0655080fefd3ee5f3302ae2d72e197d67d2a8f205a9dc4d06229f09176e699f833ffd3bb802b5b165a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/e41811d00ee363e3f6070274040cd8a5166402f0847715d23f73fe17a56af0f728240bc0f5eff48828c58c060469d668c02090780ffb6c23af4102b948f4f64c"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-01 15:40:52.182484) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-01 15:40:52.182850) ========= 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.T0A3Ghbg0c/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.dt5Iifp37T/Listeners TMPDIR=/var/folders/t6/qpy_kfds3kz26lvtw39n2d7m00000w/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.005159 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-01 15:40:52.232373) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-01 15:40:52.232754) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-01 15:40:52.233142) ========= ========= Total master_lag: 0.19 =========