builder: cedar_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0187 starttime: 1479479063.35 results: success (0) buildid: 20161118011229 builduid: 5de2d7d1c0d0409582cbc6fcbc66d802 revision: 4faf584a1be9ac695ea79b92c772519dcdcc7722 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-11-18 06:24:23.350359) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-11-18 06:24:23.350831) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-18 06:24:23.351226) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-18 06:24:23.381488) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-18 06:24:23.382109) ========= 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.NpuyIQgCKm/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.EIhk0nCU2G/Listeners TMPDIR=/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/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-11-18 06:24:23-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 219M=0s 2016-11-18 06:24:23 (219 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.084516 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-18 06:24:23.493825) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-18 06:24:23.494577) ========= 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.NpuyIQgCKm/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.EIhk0nCU2G/Listeners TMPDIR=/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/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.021730 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-18 06:24:23.547445) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-11-18 06:24:23.548251) ========= bash -c 'python archiver_client.py mozharness --repo projects/cedar --rev 4faf584a1be9ac695ea79b92c772519dcdcc7722 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo projects/cedar --rev 4faf584a1be9ac695ea79b92c772519dcdcc7722 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.NpuyIQgCKm/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.EIhk0nCU2G/Listeners TMPDIR=/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/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-11-18 06:24:23,626 truncating revision to first 12 chars 2016-11-18 06:24:23,626 Setting DEBUG logging. 2016-11-18 06:24:23,626 attempt 1/10 2016-11-18 06:24:23,626 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/projects/cedar/4faf584a1be9?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-11-18 06:24:25,792 unpacking tar archive at: cedar-4faf584a1be9/testing/mozharness/ program finished with exit code 0 elapsedTime=2.417193 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-11-18 06:24:25.993327) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-18 06:24:25.994123) ========= script_repo_revision: 4faf584a1be9ac695ea79b92c772519dcdcc7722 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-18 06:24:25.994841) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-18 06:24:25.995562) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-18 06:24:26.015446) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 17 secs) (at 2016-11-18 06:24:26.015784) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Cedar --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Cedar in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Cedar', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Cedar'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.NpuyIQgCKm/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.EIhk0nCU2G/Listeners TMPDIR=/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/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 06:24:26 INFO - MultiFileLogger online at 20161118 06:24:26 in /builds/slave/test 06:24:26 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Cedar --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Cedar 06:24:26 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 06:24:26 INFO - {'append_to_log': False, 06:24:26 INFO - 'base_work_dir': '/builds/slave/test', 06:24:26 INFO - 'blob_upload_branch': 'Cedar', 06:24:26 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 06:24:26 INFO - 'branch': 'Cedar', 06:24:26 INFO - 'buildbot_json_path': 'buildprops.json', 06:24:26 INFO - 'config_files': ('talos/mac_config.py',), 06:24:26 INFO - 'default_actions': ('clobber', 06:24:26 INFO - 'read-buildbot-config', 06:24:26 INFO - 'download-and-extract', 06:24:26 INFO - 'populate-webroot', 06:24:26 INFO - 'create-virtualenv', 06:24:26 INFO - 'install', 06:24:26 INFO - 'run-tests'), 06:24:26 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 06:24:26 INFO - 'download_minidump_stackwalk': True, 06:24:26 INFO - 'download_symbols': 'ondemand', 06:24:26 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 06:24:26 INFO - 'tooltool.py': '/tools/tooltool.py', 06:24:26 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 06:24:26 INFO - '/tools/misc-python/virtualenv.py')}, 06:24:26 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 06:24:26 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 06:24:26 INFO - 'installer_path': 'installer.exe', 06:24:26 INFO - 'log_level': 'info', 06:24:26 INFO - 'log_name': 'talos', 06:24:26 INFO - 'log_to_console': True, 06:24:26 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 06:24:26 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 06:24:26 INFO - 'opt_config_files': (), 06:24:26 INFO - 'pip_index': False, 06:24:26 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 06:24:26 INFO - 'cmd': ('bash', 06:24:26 INFO - '-c', 06:24:26 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 06:24:26 INFO - 'enabled': True, 06:24:26 INFO - 'halt_on_failure': False, 06:24:26 INFO - 'name': 'check_screen_resolution'},), 06:24:26 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 06:24:26 INFO - 'cmd': ('bash', 06:24:26 INFO - '-c', 06:24:26 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 06:24:26 INFO - 'enabled': True, 06:24:26 INFO - 'halt_on_failure': False, 06:24:26 INFO - 'name': 'check_screen_resolution'},), 06:24:26 INFO - 'run_cmd_checks_enabled': True, 06:24:26 INFO - 'sps_profile': False, 06:24:26 INFO - 'sps_profile_interval': 0, 06:24:26 INFO - 'suite': 'dromaeojs', 06:24:26 INFO - 'system_bits': '32', 06:24:26 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 06:24:26 INFO - 'title': 't-yosemite-r7-0187', 06:24:26 INFO - 'tooltool_cache': '/builds/tooltool_cache', 06:24:26 INFO - 'use_talos_json': True, 06:24:26 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 06:24:26 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 06:24:26 INFO - 'work_dir': 'build'} 06:24:26 INFO - [mozharness: 2016-11-18 14:24:26.217857Z] Running clobber step. 06:24:26 INFO - Running pre-action listener: _resource_record_pre_action 06:24:26 INFO - Running main action method: clobber 06:24:26 INFO - rmtree: /builds/slave/test/build 06:24:26 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 06:24:28 INFO - Running post-action listener: _resource_record_post_action 06:24:28 INFO - [mozharness: 2016-11-18 14:24:28.362950Z] Finished clobber step (success) 06:24:28 INFO - [mozharness: 2016-11-18 14:24:28.363438Z] Running read-buildbot-config step. 06:24:28 INFO - Running pre-action listener: _resource_record_pre_action 06:24:28 INFO - Running main action method: read_buildbot_config 06:24:28 INFO - Using buildbot properties: 06:24:28 INFO - { 06:24:28 INFO - "project": "", 06:24:28 INFO - "product": "firefox", 06:24:28 INFO - "script_repo_revision": "default", 06:24:28 INFO - "builddir": "cedar_yosemite_r7_test-dromaeojs", 06:24:28 INFO - "repository": "", 06:24:28 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 cedar talos dromaeojs", 06:24:28 INFO - "buildid": "20161118011229", 06:24:28 INFO - "pgo_build": "False", 06:24:28 INFO - "basedir": "/builds/slave/test", 06:24:28 INFO - "buildnumber": 1, 06:24:28 INFO - "slavename": "t-yosemite-r7-0187", 06:24:28 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 06:24:28 INFO - "platform": "yosemite_r7", 06:24:28 INFO - "scheduler": "tests-cedar-macosx64-talos", 06:24:28 INFO - "branch": "cedar", 06:24:28 INFO - "repo_path": "projects/cedar", 06:24:28 INFO - "revision": "4faf584a1be9ac695ea79b92c772519dcdcc7722", 06:24:28 INFO - "stage_platform": "macosx64", 06:24:28 INFO - "builduid": "5de2d7d1c0d0409582cbc6fcbc66d802", 06:24:28 INFO - "slavebuilddir": "test" 06:24:28 INFO - } 06:24:28 INFO - Found installer url https://queue.taskcluster.net/v1/task/Bjl2DGrTS3yeT88IzDPm3Q/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg. 06:24:28 INFO - Running post-action listener: _resource_record_post_action 06:24:28 INFO - [mozharness: 2016-11-18 14:24:28.369752Z] Finished read-buildbot-config step (success) 06:24:28 INFO - [mozharness: 2016-11-18 14:24:28.369884Z] Running download-and-extract step. 06:24:28 INFO - Running pre-action listener: _resource_record_pre_action 06:24:28 INFO - Running main action method: download_and_extract 06:24:28 INFO - mkdir: /builds/slave/test/build/tests 06:24:28 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')]} 06:24:28 INFO - trying https://queue.taskcluster.net/v1/task/Bjl2DGrTS3yeT88IzDPm3Q/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json 06:24:28 INFO - Downloading https://queue.taskcluster.net/v1/task/Bjl2DGrTS3yeT88IzDPm3Q/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json 06:24:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Bjl2DGrTS3yeT88IzDPm3Q/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json'}, attempt #1 06:24:29 INFO - Downloaded 1199 bytes. 06:24:29 INFO - Reading from file /builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json 06:24:29 INFO - Using the following test package requirements: 06:24:29 INFO - {u'common': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 06:24:29 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 06:24:29 INFO - u'firefox-53.0a1.en-US.mac.cppunittest.tests.zip'], 06:24:29 INFO - u'gtest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 06:24:29 INFO - u'firefox-53.0a1.en-US.mac.gtest.tests.zip'], 06:24:29 INFO - u'jittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 06:24:29 INFO - u'jsshell-mac.zip'], 06:24:29 INFO - u'mochitest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 06:24:29 INFO - u'firefox-53.0a1.en-US.mac.mochitest.tests.zip'], 06:24:29 INFO - u'mozbase': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 06:24:29 INFO - u'reftest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 06:24:29 INFO - u'firefox-53.0a1.en-US.mac.reftest.tests.zip'], 06:24:29 INFO - u'talos': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 06:24:29 INFO - u'firefox-53.0a1.en-US.mac.talos.tests.zip'], 06:24:29 INFO - u'web-platform': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 06:24:29 INFO - u'firefox-53.0a1.en-US.mac.web-platform.tests.zip'], 06:24:29 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 06:24:29 INFO - u'firefox-53.0a1.en-US.mac.xpcshell.tests.zip']} 06:24:29 INFO - Downloading packages: [u'firefox-53.0a1.en-US.mac.common.tests.zip'] for test suite category: common 06:24:29 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Bjl2DGrTS3yeT88IzDPm3Q/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 06:24:29 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Bjl2DGrTS3yeT88IzDPm3Q/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 06:24:29 INFO - Fetch https://queue.taskcluster.net/v1/task/Bjl2DGrTS3yeT88IzDPm3Q/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 06:24:30 INFO - Http code: 200 06:24:30 INFO - via: 1.1 24b0e5a3429d07ef12381da50e07f70f.cloudfront.net (CloudFront) 06:24:30 INFO - x-amz-cf-id: HjASkpNXufPmPZaMuTPdf6XhOLWeAenkdn-wtML8Ci8VdnII0oONTw== 06:24:30 INFO - x-amz-version-id: QLbXjCEz4yv1p0XFsQlq27n17lzCQllP 06:24:30 INFO - Expected file size: 16223011 06:24:30 INFO - Obtained file size: 16223011 06:24:37 INFO - Downloading packages: [u'firefox-53.0a1.en-US.mac.common.tests.zip', u'firefox-53.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 06:24:37 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Bjl2DGrTS3yeT88IzDPm3Q/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 06:24:37 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Bjl2DGrTS3yeT88IzDPm3Q/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 06:24:37 INFO - Fetch https://queue.taskcluster.net/v1/task/Bjl2DGrTS3yeT88IzDPm3Q/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 06:24:37 INFO - Http code: 200 06:24:37 INFO - via: 1.1 11dd60d1f68e8258294f92935b53a91f.cloudfront.net (CloudFront) 06:24:37 INFO - x-amz-cf-id: zT2sKvNAqsY-UAmC7svVmh0MvQhj9UBJI6mHmuw4qO9JeeS6sVpxyw== 06:24:37 INFO - x-amz-version-id: QLbXjCEz4yv1p0XFsQlq27n17lzCQllP 06:24:38 INFO - Expected file size: 16223011 06:24:38 INFO - Obtained file size: 16223011 06:24:44 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Bjl2DGrTS3yeT88IzDPm3Q/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip 06:24:44 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Bjl2DGrTS3yeT88IzDPm3Q/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip'}, attempt #1 06:24:44 INFO - Fetch https://queue.taskcluster.net/v1/task/Bjl2DGrTS3yeT88IzDPm3Q/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip into memory 06:24:45 INFO - Http code: 200 06:24:45 INFO - via: 1.1 8008015354a3ca72f56c382a1d1cfe9f.cloudfront.net (CloudFront) 06:24:45 INFO - x-amz-cf-id: MFdMIm0Mjl4t79pXaTmqTMh0nWWoZM_Ik3RjHpB9urGzz7a8lq3S6g== 06:24:45 INFO - x-amz-version-id: fnoLYAJ85vcbGGLjjpp4F8U7KoO7q7YQ 06:24:45 INFO - Expected file size: 14409392 06:24:45 INFO - Obtained file size: 14409392 06:24:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:24:46 INFO - trying https://queue.taskcluster.net/v1/task/Bjl2DGrTS3yeT88IzDPm3Q/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 06:24:46 INFO - Downloading https://queue.taskcluster.net/v1/task/Bjl2DGrTS3yeT88IzDPm3Q/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg 06:24:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Bjl2DGrTS3yeT88IzDPm3Q/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg'}, attempt #1 06:24:48 INFO - Downloaded 107357410 bytes. 06:24:48 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Bjl2DGrTS3yeT88IzDPm3Q/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 06:24:48 INFO - mkdir: /builds/slave/test/properties 06:24:48 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 06:24:48 INFO - Writing to file /builds/slave/test/properties/build_url 06:24:48 INFO - Contents: 06:24:48 INFO - build_url:https://queue.taskcluster.net/v1/task/Bjl2DGrTS3yeT88IzDPm3Q/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 06:24:49 INFO - Running post-action listener: _resource_record_post_action 06:24:49 INFO - Running post-action listener: set_extra_try_arguments 06:24:49 INFO - [mozharness: 2016-11-18 14:24:49.607578Z] Finished download-and-extract step (success) 06:24:49 INFO - [mozharness: 2016-11-18 14:24:49.607740Z] Running populate-webroot step. 06:24:49 INFO - Running pre-action listener: _resource_record_pre_action 06:24:49 INFO - Running main action method: populate_webroot 06:24:49 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 06:24:49 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 06:24:49 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 06:24:49 INFO - '--tppagecycles', 06:24:49 INFO - '7'], 06:24:49 INFO - 'tests': ['tsvgm']}}, 06:24:49 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 06:24:49 INFO - 'tests': ['tresize', 'tcanvasmark']}, 06:24:49 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 06:24:49 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 06:24:49 INFO - 'tests': ['dromaeo_css', 'kraken']}, 06:24:49 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 06:24:49 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 06:24:49 INFO - 'talos_options': ['--disable-e10s'], 06:24:49 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 06:24:49 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 06:24:49 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 06:24:49 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 06:24:49 INFO - 'talos_options': ['--disable-e10s'], 06:24:49 INFO - 'tests': ['damp', 'tps']}, 06:24:49 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 06:24:49 INFO - 'tests': ['damp', 'tps']}, 06:24:49 INFO - 'g3': {'talos_options': ['--disable-e10s'], 06:24:49 INFO - 'tests': ['dromaeo_dom']}, 06:24:49 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 06:24:49 INFO - 'g4': {'talos_options': ['--disable-e10s'], 06:24:49 INFO - 'tests': ['basic_compositor_video']}, 06:24:49 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 06:24:49 INFO - 'other': {'talos_options': ['--disable-e10s'], 06:24:49 INFO - 'tests': ['a11yr', 06:24:49 INFO - 'ts_paint', 06:24:49 INFO - 'tpaint', 06:24:49 INFO - 'sessionrestore', 06:24:49 INFO - 'sessionrestore_no_auto_restore', 06:24:49 INFO - 'tabpaint']}, 06:24:49 INFO - 'other-e10s': {'tests': ['a11yr', 06:24:49 INFO - 'ts_paint', 06:24:49 INFO - 'tpaint', 06:24:49 INFO - 'sessionrestore', 06:24:49 INFO - 'sessionrestore_no_auto_restore', 06:24:49 INFO - 'tabpaint']}, 06:24:49 INFO - 'other-e10s_l64': {'tests': ['a11yr', 06:24:49 INFO - 'ts_paint', 06:24:49 INFO - 'tpaint', 06:24:49 INFO - 'sessionrestore', 06:24:49 INFO - 'sessionrestore_no_auto_restore', 06:24:49 INFO - 'tabpaint']}, 06:24:49 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 06:24:49 INFO - 'ts_paint', 06:24:49 INFO - 'tpaint', 06:24:49 INFO - 'sessionrestore', 06:24:49 INFO - 'sessionrestore_no_auto_restore', 06:24:49 INFO - 'tabpaint']}, 06:24:49 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 06:24:49 INFO - 'tests': ['a11yr', 06:24:49 INFO - 'ts_paint', 06:24:49 INFO - 'tpaint', 06:24:49 INFO - 'sessionrestore', 06:24:49 INFO - 'sessionrestore_no_auto_restore', 06:24:49 INFO - 'tabpaint']}, 06:24:49 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 06:24:49 INFO - 'tests': ['a11yr', 06:24:49 INFO - 'ts_paint', 06:24:49 INFO - 'tpaint', 06:24:49 INFO - 'sessionrestore', 06:24:49 INFO - 'sessionrestore_no_auto_restore', 06:24:49 INFO - 'tabpaint']}, 06:24:49 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 06:24:49 INFO - 'tests': ['tsvgx', 06:24:49 INFO - 'tsvgr_opacity', 06:24:49 INFO - 'tart', 06:24:49 INFO - 'tscrollx', 06:24:49 INFO - 'cart']}, 06:24:49 INFO - 'svgr-e10s': {'tests': ['tsvgx', 06:24:49 INFO - 'tsvgr_opacity', 06:24:49 INFO - 'tart', 06:24:49 INFO - 'tscrollx', 06:24:49 INFO - 'cart']}, 06:24:49 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 06:24:49 INFO - 'talos_options': ['--disable-e10s'], 06:24:49 INFO - 'tests': ['tp5o']}, 06:24:49 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 06:24:49 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 06:24:49 INFO - 'talos_options': ['--disable-e10s', 06:24:49 INFO - '--xperf_path', 06:24:49 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 06:24:49 INFO - 'tests': ['tp5n']}, 06:24:49 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 06:24:49 INFO - 'talos_options': ['--xperf_path', 06:24:49 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 06:24:49 INFO - 'tests': ['tp5n']}}, 06:24:49 INFO - 'talos.zip': {'path': '', 06:24:49 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 06:24:49 INFO - Running post-action listener: _resource_record_post_action 06:24:49 INFO - [mozharness: 2016-11-18 14:24:49.625914Z] Finished populate-webroot step (success) 06:24:49 INFO - [mozharness: 2016-11-18 14:24:49.626052Z] Running create-virtualenv step. 06:24:49 INFO - Running pre-action listener: _resource_record_pre_action 06:24:49 INFO - Running main action method: create_virtualenv 06:24:49 INFO - Creating virtualenv /builds/slave/test/build/venv 06:24:49 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 06:24:49 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 06:24:49 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 06:24:49 INFO - Using real prefix '/tools/python27' 06:24:49 INFO - New python executable in /builds/slave/test/build/venv/bin/python 06:24:50 INFO - Installing distribute.............................................................................................................................................................................................done. 06:24:53 INFO - Installing pip.................done. 06:24:53 INFO - Return code: 0 06:24:53 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 06:24:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 06:24:53 INFO - Reading from file tmpfile_stdout 06:24:53 INFO - Output received: 06:24:53 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) 06:24:53 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 06:24:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:24:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:24:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:24:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:24:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:24:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:24:53 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 0x10d3ae3f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d324570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb54358c7a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d1c4d30>, '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 0x10d02bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d1bcb20>, '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.NpuyIQgCKm/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.EIhk0nCU2G/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/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 06:24:53 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 06:24:53 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 06:24:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NpuyIQgCKm/Render', 06:24:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:24:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:24:53 INFO - 'HOME': '/Users/cltbld', 06:24:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:24:53 INFO - 'LOGNAME': 'cltbld', 06:24:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:24:53 INFO - 'MOZ_NO_REMOTE': '1', 06:24:53 INFO - 'NO_EM_RESTART': '1', 06:24:53 INFO - 'PAGER': '/bin/cat', 06:24:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:24:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:24:53 INFO - 'PWD': '/builds/slave/test', 06:24:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:24:53 INFO - 'SHELL': '/bin/bash', 06:24:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EIhk0nCU2G/Listeners', 06:24:53 INFO - 'TMPDIR': '/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/T/', 06:24:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:24:53 INFO - 'USER': 'cltbld', 06:24:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:24:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:24:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:24:53 INFO - 'XPC_FLAGS': '0x0', 06:24:53 INFO - 'XPC_SERVICE_NAME': '0', 06:24:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:24:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:24:53 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 06:24:53 INFO - Cleaning up... 06:24:53 INFO - Return code: 0 06:24:53 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 06:24:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:24:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:24:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:24:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:24:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:24:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:24:53 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 0x10d3ae3f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d324570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb54358c7a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d1c4d30>, '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 0x10d02bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d1bcb20>, '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.NpuyIQgCKm/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.EIhk0nCU2G/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/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 06:24:53 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 06:24:53 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 06:24:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NpuyIQgCKm/Render', 06:24:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:24:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:24:53 INFO - 'HOME': '/Users/cltbld', 06:24:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:24:53 INFO - 'LOGNAME': 'cltbld', 06:24:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:24:53 INFO - 'MOZ_NO_REMOTE': '1', 06:24:53 INFO - 'NO_EM_RESTART': '1', 06:24:53 INFO - 'PAGER': '/bin/cat', 06:24:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:24:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:24:53 INFO - 'PWD': '/builds/slave/test', 06:24:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:24:53 INFO - 'SHELL': '/bin/bash', 06:24:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EIhk0nCU2G/Listeners', 06:24:53 INFO - 'TMPDIR': '/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/T/', 06:24:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:24:53 INFO - 'USER': 'cltbld', 06:24:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:24:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:24:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:24:53 INFO - 'XPC_FLAGS': '0x0', 06:24:53 INFO - 'XPC_SERVICE_NAME': '0', 06:24:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:24:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:24:54 INFO - Downloading/unpacking psutil>=3.1.1 06:24:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:24:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:24:54 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:24:54 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:24:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:24:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:24:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 06:24:57 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 06:24:57 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 06:24:57 INFO - Installing collected packages: psutil 06:24:57 INFO - Running setup.py install for psutil 06:24:57 INFO - building 'psutil._psutil_osx' extension 06:24:57 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 06:24:57 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 06:24:57 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 06:24:58 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 06:24:58 INFO - building 'psutil._psutil_posix' extension 06:24:58 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 06:24:58 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 06:24:58 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 06:24:58 INFO - ^ 06:24:58 INFO - 1 warning generated. 06:24:58 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 06:24:58 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 06:24:58 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 06:24:58 INFO - Successfully installed psutil 06:24:58 INFO - Cleaning up... 06:24:58 INFO - Return code: 0 06:24:58 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 06:24: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')]} 06:24:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:24:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:24: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')]} 06:24:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:24:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:24:58 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 0x10d3ae3f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d324570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb54358c7a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d1c4d30>, '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 0x10d02bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d1bcb20>, '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.NpuyIQgCKm/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.EIhk0nCU2G/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/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 06:24:58 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 06:24:58 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 06:24:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NpuyIQgCKm/Render', 06:24:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:24:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:24:58 INFO - 'HOME': '/Users/cltbld', 06:24:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:24:58 INFO - 'LOGNAME': 'cltbld', 06:24:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:24:58 INFO - 'MOZ_NO_REMOTE': '1', 06:24:58 INFO - 'NO_EM_RESTART': '1', 06:24:58 INFO - 'PAGER': '/bin/cat', 06:24:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:24:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:24:58 INFO - 'PWD': '/builds/slave/test', 06:24:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:24:58 INFO - 'SHELL': '/bin/bash', 06:24:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EIhk0nCU2G/Listeners', 06:24:58 INFO - 'TMPDIR': '/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/T/', 06:24:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:24:58 INFO - 'USER': 'cltbld', 06:24:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:24:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:24:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:24:58 INFO - 'XPC_FLAGS': '0x0', 06:24:58 INFO - 'XPC_SERVICE_NAME': '0', 06:24:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:24:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:24:58 INFO - Downloading/unpacking mozsystemmonitor==0.3 06:24:58 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 06:24:58 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 06:24:58 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 06:24:58 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 06:24:58 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 06:24:58 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 06:25:01 INFO - Downloading mozsystemmonitor-0.3.tar.gz 06:25:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 06:25:01 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 06:25:01 INFO - Installing collected packages: mozsystemmonitor 06:25:01 INFO - Running setup.py install for mozsystemmonitor 06:25:01 INFO - Successfully installed mozsystemmonitor 06:25:01 INFO - Cleaning up... 06:25:01 INFO - Return code: 0 06:25:01 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 06:25:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:25:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:25:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:25:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:25:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:25:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:25:01 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 0x10d3ae3f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d324570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb54358c7a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d1c4d30>, '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 0x10d02bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d1bcb20>, '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.NpuyIQgCKm/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.EIhk0nCU2G/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/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 06:25:01 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 06:25:01 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 06:25:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NpuyIQgCKm/Render', 06:25:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:25:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:25:01 INFO - 'HOME': '/Users/cltbld', 06:25:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:25:01 INFO - 'LOGNAME': 'cltbld', 06:25:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:25:01 INFO - 'MOZ_NO_REMOTE': '1', 06:25:01 INFO - 'NO_EM_RESTART': '1', 06:25:01 INFO - 'PAGER': '/bin/cat', 06:25:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:25:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:25:01 INFO - 'PWD': '/builds/slave/test', 06:25:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:25:01 INFO - 'SHELL': '/bin/bash', 06:25:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EIhk0nCU2G/Listeners', 06:25:01 INFO - 'TMPDIR': '/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/T/', 06:25:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:25:01 INFO - 'USER': 'cltbld', 06:25:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:25:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:25:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:25:01 INFO - 'XPC_FLAGS': '0x0', 06:25:01 INFO - 'XPC_SERVICE_NAME': '0', 06:25:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:25:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:25:01 INFO - Downloading/unpacking jsonschema==2.5.1 06:25:01 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 06:25:01 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 06:25:01 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 06:25:01 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 06:25:01 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 06:25:01 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 06:25:04 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 06:25:04 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 06:25:04 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 06:25:04 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 06:25:04 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 06:25:04 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 06:25:04 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 06:25:04 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 06:25:04 INFO - Downloading functools32-3.2.3-2.tar.gz 06:25:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 06:25:04 INFO - warning: no files found matching '*.txt' 06:25:04 INFO - no previously-included directories found matching 'build' 06:25:04 INFO - no previously-included directories found matching 'dist' 06:25:04 INFO - no previously-included directories found matching '.git*' 06:25:04 INFO - Installing collected packages: jsonschema, functools32 06:25:04 INFO - Running setup.py install for functools32 06:25:04 INFO - warning: no files found matching '*.txt' 06:25:04 INFO - no previously-included directories found matching 'build' 06:25:04 INFO - no previously-included directories found matching 'dist' 06:25:04 INFO - no previously-included directories found matching '.git*' 06:25:04 INFO - Successfully installed jsonschema functools32 06:25:04 INFO - Cleaning up... 06:25:04 INFO - Return code: 0 06:25:04 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 06:25:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:25:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:25:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:25:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:25:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:25:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:25:04 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10d3ae3f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d324570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb54358c7a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d1c4d30>, '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 0x10d02bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d1bcb20>, '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.NpuyIQgCKm/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.EIhk0nCU2G/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/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 06:25:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 06:25:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 06:25:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NpuyIQgCKm/Render', 06:25:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:25:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:25:04 INFO - 'HOME': '/Users/cltbld', 06:25:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:25:04 INFO - 'LOGNAME': 'cltbld', 06:25:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:25:04 INFO - 'MOZ_NO_REMOTE': '1', 06:25:04 INFO - 'NO_EM_RESTART': '1', 06:25:04 INFO - 'PAGER': '/bin/cat', 06:25:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:25:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:25:04 INFO - 'PWD': '/builds/slave/test', 06:25:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:25:04 INFO - 'SHELL': '/bin/bash', 06:25:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EIhk0nCU2G/Listeners', 06:25:04 INFO - 'TMPDIR': '/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/T/', 06:25:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:25:04 INFO - 'USER': 'cltbld', 06:25:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:25:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:25:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:25:04 INFO - 'XPC_FLAGS': '0x0', 06:25:04 INFO - 'XPC_SERVICE_NAME': '0', 06:25:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:25:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:25:05 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 06:25:05 INFO - Cleaning up... 06:25:05 INFO - Return code: 0 06:25:05 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 06:25: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')]} 06:25:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:25:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:25: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')]} 06:25:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:25:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:25:05 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10d3ae3f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d324570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb54358c7a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d1c4d30>, '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 0x10d02bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d1bcb20>, '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.NpuyIQgCKm/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.EIhk0nCU2G/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/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 06:25:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 06:25:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 06:25:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NpuyIQgCKm/Render', 06:25:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:25:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:25:05 INFO - 'HOME': '/Users/cltbld', 06:25:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:25:05 INFO - 'LOGNAME': 'cltbld', 06:25:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:25:05 INFO - 'MOZ_NO_REMOTE': '1', 06:25:05 INFO - 'NO_EM_RESTART': '1', 06:25:05 INFO - 'PAGER': '/bin/cat', 06:25:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:25:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:25:05 INFO - 'PWD': '/builds/slave/test', 06:25:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:25:05 INFO - 'SHELL': '/bin/bash', 06:25:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EIhk0nCU2G/Listeners', 06:25:05 INFO - 'TMPDIR': '/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/T/', 06:25:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:25:05 INFO - 'USER': 'cltbld', 06:25:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:25:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:25:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:25:05 INFO - 'XPC_FLAGS': '0x0', 06:25:05 INFO - 'XPC_SERVICE_NAME': '0', 06:25:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:25:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:25:05 INFO - Downloading/unpacking blobuploader==1.2.4 06:25:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:25:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:25:05 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:25:05 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:25:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:25:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:25:07 INFO - Downloading blobuploader-1.2.4.tar.gz 06:25:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 06:25:08 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 06:25:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:25:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:25:08 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:25:08 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:25:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:25:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:25:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 06:25:08 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 06:25:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:25:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:25:08 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:25:08 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:25:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:25:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:25:09 INFO - Downloading docopt-0.6.1.tar.gz 06:25:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 06:25:09 INFO - Installing collected packages: blobuploader, requests, docopt 06:25:09 INFO - Running setup.py install for blobuploader 06:25:09 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 06:25:09 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 06:25:09 INFO - Running setup.py install for requests 06:25:09 INFO - Running setup.py install for docopt 06:25:10 INFO - Successfully installed blobuploader requests docopt 06:25:10 INFO - Cleaning up... 06:25:10 INFO - Return code: 0 06:25:10 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:25:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:25:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:25:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:25:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:25:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:25:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:25:10 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 0x10d3ae3f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d324570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb54358c7a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d1c4d30>, '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 0x10d02bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d1bcb20>, '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.NpuyIQgCKm/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.EIhk0nCU2G/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/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 06:25:10 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 06:25:10 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 06:25:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NpuyIQgCKm/Render', 06:25:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:25:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:25:10 INFO - 'HOME': '/Users/cltbld', 06:25:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:25:10 INFO - 'LOGNAME': 'cltbld', 06:25:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:25:10 INFO - 'MOZ_NO_REMOTE': '1', 06:25:10 INFO - 'NO_EM_RESTART': '1', 06:25:10 INFO - 'PAGER': '/bin/cat', 06:25:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:25:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:25:10 INFO - 'PWD': '/builds/slave/test', 06:25:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:25:10 INFO - 'SHELL': '/bin/bash', 06:25:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EIhk0nCU2G/Listeners', 06:25:10 INFO - 'TMPDIR': '/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/T/', 06:25:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:25:10 INFO - 'USER': 'cltbld', 06:25:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:25:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:25:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:25:10 INFO - 'XPC_FLAGS': '0x0', 06:25:10 INFO - 'XPC_SERVICE_NAME': '0', 06:25:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:25:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:25:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 06:25:10 INFO - Running setup.py (path:/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/T/pip-w4fSpZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 06:25:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 06:25:10 INFO - Running setup.py (path:/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/T/pip-r8T6MF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 06:25:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 06:25:10 INFO - Running setup.py (path:/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/T/pip-xCbbXJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 06:25:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 06:25:10 INFO - Running setup.py (path:/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/T/pip-Lpf7XH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 06:25:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 06:25:10 INFO - Running setup.py (path:/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/T/pip-QQMd0g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 06:25:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 06:25:11 INFO - Running setup.py (path:/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/T/pip-wufioh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 06:25:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 06:25:11 INFO - Running setup.py (path:/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/T/pip-GzPZCt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 06:25:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 06:25:11 INFO - Running setup.py (path:/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/T/pip-xm7Inn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 06:25:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 06:25:11 INFO - Running setup.py (path:/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/T/pip-7hfOzh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 06:25:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 06:25:11 INFO - Running setup.py (path:/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/T/pip-iXvJ4P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 06:25:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 06:25:11 INFO - Running setup.py (path:/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/T/pip-3It1XG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 06:25:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 06:25:11 INFO - Running setup.py (path:/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/T/pip-d3LSDB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 06:25:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 06:25:11 INFO - Running setup.py (path:/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/T/pip-cY6nUl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 06:25:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 06:25:11 INFO - Running setup.py (path:/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/T/pip-BoLaRT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 06:25:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 06:25:11 INFO - Running setup.py (path:/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/T/pip-TFPZGd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 06:25:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 06:25:12 INFO - Running setup.py (path:/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/T/pip-2w0J5j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 06:25:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 06:25:12 INFO - Running setup.py (path:/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/T/pip-5UZNEn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 06:25:12 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 06:25:12 INFO - Running setup.py install for manifestparser 06:25:12 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 06:25:12 INFO - Running setup.py install for mozcrash 06:25:12 INFO - Running setup.py install for mozdebug 06:25:12 INFO - Running setup.py install for mozdevice 06:25:12 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 06:25:12 INFO - Installing dm script to /builds/slave/test/build/venv/bin 06:25:12 INFO - Running setup.py install for mozfile 06:25:12 INFO - Running setup.py install for mozhttpd 06:25:13 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 06:25:13 INFO - Running setup.py install for mozinfo 06:25:13 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 06:25:13 INFO - Running setup.py install for mozInstall 06:25:13 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 06:25:13 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 06:25:13 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 06:25:13 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 06:25:13 INFO - Running setup.py install for mozleak 06:25:13 INFO - Running setup.py install for mozlog 06:25:13 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 06:25:13 INFO - Running setup.py install for moznetwork 06:25:13 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 06:25:13 INFO - Running setup.py install for mozprocess 06:25:14 INFO - Running setup.py install for mozprofile 06:25:14 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 06:25:14 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 06:25:14 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 06:25:14 INFO - Running setup.py install for mozrunner 06:25:14 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 06:25:14 INFO - Running setup.py install for mozscreenshot 06:25:14 INFO - Running setup.py install for moztest 06:25:14 INFO - Running setup.py install for mozversion 06:25:14 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 06:25:14 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 06:25:14 INFO - Cleaning up... 06:25:14 INFO - Return code: 0 06:25:14 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:25: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')]} 06:25:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:25:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:25: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')]} 06:25:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:25:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:25:14 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 0x10d3ae3f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d324570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb54358c7a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d1c4d30>, '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 0x10d02bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d1bcb20>, '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.NpuyIQgCKm/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.EIhk0nCU2G/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/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 06:25:14 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 06:25:14 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 06:25:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NpuyIQgCKm/Render', 06:25:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:25:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:25:14 INFO - 'HOME': '/Users/cltbld', 06:25:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:25:14 INFO - 'LOGNAME': 'cltbld', 06:25:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:25:14 INFO - 'MOZ_NO_REMOTE': '1', 06:25:14 INFO - 'NO_EM_RESTART': '1', 06:25:14 INFO - 'PAGER': '/bin/cat', 06:25:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:25:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:25:14 INFO - 'PWD': '/builds/slave/test', 06:25:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:25:14 INFO - 'SHELL': '/bin/bash', 06:25:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EIhk0nCU2G/Listeners', 06:25:14 INFO - 'TMPDIR': '/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/T/', 06:25:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:25:14 INFO - 'USER': 'cltbld', 06:25:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:25:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:25:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:25:14 INFO - 'XPC_FLAGS': '0x0', 06:25:14 INFO - 'XPC_SERVICE_NAME': '0', 06:25:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:25:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:25:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 06:25:15 INFO - Running setup.py (path:/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/T/pip-0m2QO9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 06:25:15 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)) 06:25:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 06:25:15 INFO - Running setup.py (path:/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/T/pip-xwZ0ME-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 06:25:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 06:25:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 06:25:15 INFO - Running setup.py (path:/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/T/pip-nizVta-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 06:25:15 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)) 06:25:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 06:25:15 INFO - Running setup.py (path:/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/T/pip-AsJDIE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 06:25:15 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)) 06:25:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 06:25:15 INFO - Running setup.py (path:/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/T/pip-azkeCz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 06:25:15 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)) 06:25:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 06:25:15 INFO - Running setup.py (path:/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/T/pip-VBeJhI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 06:25:15 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)) 06:25:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 06:25:15 INFO - Running setup.py (path:/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/T/pip-L5f4Mj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 06:25:15 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)) 06:25:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 06:25:15 INFO - Running setup.py (path:/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/T/pip-luuc3O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 06:25:15 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)) 06:25:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 06:25:15 INFO - Running setup.py (path:/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/T/pip-ZC0fZS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 06:25:16 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)) 06:25:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 06:25:16 INFO - Running setup.py (path:/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/T/pip-FTibKT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 06:25:16 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)) 06:25:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 06:25:16 INFO - Running setup.py (path:/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/T/pip-I6LhLU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 06:25:16 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)) 06:25:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 06:25:16 INFO - Running setup.py (path:/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/T/pip-MB_zOx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 06:25:16 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)) 06:25:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 06:25:16 INFO - Running setup.py (path:/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/T/pip-efpqoL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 06:25:16 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)) 06:25:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 06:25:16 INFO - Running setup.py (path:/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/T/pip-bCEnFp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 06:25:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 06:25:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 06:25:16 INFO - Running setup.py (path:/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/T/pip-ACAjRn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 06:25:16 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)) 06:25:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 06:25:16 INFO - Running setup.py (path:/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/T/pip-AbZ6VO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 06:25:16 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)) 06:25:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 06:25:16 INFO - Running setup.py (path:/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/T/pip-KOpS1d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 06:25:16 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)) 06:25:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 06:25:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 06:25:16 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)) 06:25:16 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)) 06:25:16 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)) 06:25:16 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 06:25:16 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 06:25:16 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 06:25:16 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 06:25:16 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 06:25:16 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 06:25:16 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 06:25:19 INFO - Downloading blessings-1.6.tar.gz 06:25:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 06:25:19 INFO - Installing collected packages: blessings 06:25:19 INFO - Running setup.py install for blessings 06:25:19 INFO - Successfully installed blessings 06:25:19 INFO - Cleaning up... 06:25:19 INFO - Return code: 0 06:25:19 INFO - Done creating virtualenv /builds/slave/test/build/venv. 06:25:19 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 06:25:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 06:25:20 INFO - Reading from file tmpfile_stdout 06:25:20 INFO - Current package versions: 06:25:20 INFO - blessings == 1.6 06:25:20 INFO - blobuploader == 1.2.4 06:25:20 INFO - docopt == 0.6.1 06:25:20 INFO - functools32 == 3.2.3-2 06:25:20 INFO - jsonschema == 2.5.1 06:25:20 INFO - manifestparser == 1.1 06:25:20 INFO - mozInstall == 1.12 06:25:20 INFO - mozcrash == 1.0 06:25:20 INFO - mozdebug == 0.1 06:25:20 INFO - mozdevice == 0.48 06:25:20 INFO - mozfile == 1.2 06:25:20 INFO - mozhttpd == 0.7 06:25:20 INFO - mozinfo == 0.9 06:25:20 INFO - mozleak == 0.1 06:25:20 INFO - mozlog == 3.4 06:25:20 INFO - moznetwork == 0.27 06:25:20 INFO - mozprocess == 0.23 06:25:20 INFO - mozprofile == 0.28 06:25:20 INFO - mozrunner == 6.13 06:25:20 INFO - mozscreenshot == 0.1 06:25:20 INFO - mozsystemmonitor == 0.3 06:25:20 INFO - moztest == 0.7 06:25:20 INFO - mozversion == 1.4 06:25:20 INFO - psutil == 3.1.1 06:25:20 INFO - requests == 1.2.3 06:25:20 INFO - wsgiref == 0.1.2 06:25:20 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:25:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:25:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:25:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:25:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:25:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:25:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:25:21 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10d3ae3f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d324570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb54358c7a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d1c4d30>, '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 0x10d02bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d1bcb20>, '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.NpuyIQgCKm/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.EIhk0nCU2G/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/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 06:25:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 06:25:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 06:25:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NpuyIQgCKm/Render', 06:25:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:25:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:25:21 INFO - 'HOME': '/Users/cltbld', 06:25:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:25:21 INFO - 'LOGNAME': 'cltbld', 06:25:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:25:21 INFO - 'MOZ_NO_REMOTE': '1', 06:25:21 INFO - 'NO_EM_RESTART': '1', 06:25:21 INFO - 'PAGER': '/bin/cat', 06:25:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:25:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:25:21 INFO - 'PWD': '/builds/slave/test', 06:25:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:25:21 INFO - 'SHELL': '/bin/bash', 06:25:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EIhk0nCU2G/Listeners', 06:25:21 INFO - 'TMPDIR': '/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/T/', 06:25:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:25:21 INFO - 'USER': 'cltbld', 06:25:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:25:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:25:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:25:21 INFO - 'XPC_FLAGS': '0x0', 06:25:21 INFO - 'XPC_SERVICE_NAME': '0', 06:25:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:25:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:25:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 06:25:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 06:25:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 06:25:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 06:25:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 06:25:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 06:25:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 06:25:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 06:25:21 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 06:25:21 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 06:25:21 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 06:25:21 INFO - Cleaning up... 06:25:21 INFO - Return code: 0 06:25:21 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 06:25:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:25:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:25:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:25:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:25:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:25:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:25:21 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10d3ae3f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d324570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb54358c7a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d1c4d30>, '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 0x10d02bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d1bcb20>, '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.NpuyIQgCKm/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.EIhk0nCU2G/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/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 06:25:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 06:25:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 06:25:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NpuyIQgCKm/Render', 06:25:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:25:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:25:21 INFO - 'HOME': '/Users/cltbld', 06:25:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:25:21 INFO - 'LOGNAME': 'cltbld', 06:25:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:25:21 INFO - 'MOZ_NO_REMOTE': '1', 06:25:21 INFO - 'NO_EM_RESTART': '1', 06:25:21 INFO - 'PAGER': '/bin/cat', 06:25:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:25:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:25:21 INFO - 'PWD': '/builds/slave/test', 06:25:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:25:21 INFO - 'SHELL': '/bin/bash', 06:25:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EIhk0nCU2G/Listeners', 06:25:21 INFO - 'TMPDIR': '/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/T/', 06:25:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:25:21 INFO - 'USER': 'cltbld', 06:25:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:25:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:25:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:25:21 INFO - 'XPC_FLAGS': '0x0', 06:25:21 INFO - 'XPC_SERVICE_NAME': '0', 06:25:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:25:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:25:22 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 06:25:22 INFO - Cleaning up... 06:25:22 INFO - Return code: 0 06:25:22 INFO - Running post-action listener: _resource_record_post_action 06:25:22 INFO - Running post-action listener: _start_resource_monitoring 06:25:22 INFO - Starting resource monitoring. 06:25:22 INFO - [mozharness: 2016-11-18 14:25:22.222447Z] Finished create-virtualenv step (success) 06:25:22 INFO - [mozharness: 2016-11-18 14:25:22.223430Z] Running install step. 06:25:22 INFO - Running pre-action listener: _resource_record_pre_action 06:25:22 INFO - Running main action method: install 06:25:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 06:25:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 06:25:22 INFO - Reading from file tmpfile_stdout 06:25:22 INFO - Detecting whether we're running mozinstall >=1.0... 06:25:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 06:25:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 06:25:22 INFO - Reading from file tmpfile_stdout 06:25:22 INFO - Output received: 06:25:22 INFO - Usage: mozinstall [options] installer 06:25:22 INFO - Options: 06:25:22 INFO - -h, --help show this help message and exit 06:25:22 INFO - -d DEST, --destination=DEST 06:25:22 INFO - Directory to install application into. [default: 06:25:22 INFO - "/builds/slave/test"] 06:25:22 INFO - --app=APP Application being installed. [default: firefox] 06:25:22 INFO - mkdir: /builds/slave/test/build/application 06:25:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 06:25:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 06:25:54 INFO - Reading from file tmpfile_stdout 06:25:54 INFO - Output received: 06:25:54 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 06:25:54 INFO - Running post-action listener: _resource_record_post_action 06:25:54 INFO - [mozharness: 2016-11-18 14:25:54.952550Z] Finished install step (success) 06:25:54 INFO - [mozharness: 2016-11-18 14:25:54.952707Z] Running run-tests step. 06:25:54 INFO - Running pre-action listener: _resource_record_pre_action 06:25:54 INFO - Running main action method: run_tests 06:25:54 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 06:25:54 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 06:25:54 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 06:25:54 INFO - 2016-11-18 06:25:54.967 screenresolution[1923:63451] starting screenresolution argv=screenresolution get 06:25:54 INFO - 2016-11-18 06:25:54.969 screenresolution[1923:63451] Display 0: 1600x1200x32@60 06:25:54 INFO - 2016-11-18 06:25:54.975 screenresolution[1924:63453] starting screenresolution argv=screenresolution list 06:25:54 INFO - Available Modes on Display 0 06:25:54 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 06:25:54 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 06:25:54 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 06:25:54 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 06:25:55 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 06:25:55 INFO - Intel Iris: 06:25:55 INFO - Chipset Model: Intel Iris 06:25:55 INFO - Type: GPU 06:25:55 INFO - Bus: Built-In 06:25:55 INFO - VRAM (Dynamic, Max): 1536 MB 06:25:55 INFO - Vendor: Intel (0x8086) 06:25:55 INFO - Device ID: 0x0a2e 06:25:55 INFO - Revision ID: 0x0009 06:25:55 INFO - Displays: 06:25:55 INFO - Display: 06:25:55 INFO - Resolution: 1600 x 1200 @ 60 Hz 06:25:55 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 06:25:55 INFO - Display Serial Number: 1600x1200 60 06:25:55 INFO - Main Display: Yes 06:25:55 INFO - Mirror: Off 06:25:55 INFO - Online: Yes 06:25:55 INFO - Rotation: Supported 06:25:55 INFO - Return code: 0 06:25:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 06:25:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 06:25:55 INFO - Python 2.7.3 06:25:55 INFO - Return code: 0 06:25:55 INFO - grabbing minidump binary from tooltool 06:25:55 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')]} 06:25:55 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 0x10d1c4d30>, '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 0x10d02bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d1bcb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 06:25:55 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 06:25:55 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 06:25:55 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 06:25:55 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 06:25:55 INFO - Return code: 0 06:25:55 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 06:25:55 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 06:25:55 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 06:25:55 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 06:25:55 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 06:25:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Cedar', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Bjl2DGrTS3yeT88IzDPm3Q/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0187', '--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 06:25:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Cedar --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Bjl2DGrTS3yeT88IzDPm3Q/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0187 --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 06:25:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NpuyIQgCKm/Render', 06:25:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:25:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:25:55 INFO - 'HOME': '/Users/cltbld', 06:25:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:25:55 INFO - 'LOGNAME': 'cltbld', 06:25:55 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 06:25:55 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 06:25:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:25:55 INFO - 'MOZ_NO_REMOTE': '1', 06:25:55 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 06:25:55 INFO - 'NO_EM_RESTART': '1', 06:25:55 INFO - 'PAGER': '/bin/cat', 06:25:55 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:25:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:25:55 INFO - 'PWD': '/builds/slave/test', 06:25:55 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 06:25:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:25:55 INFO - 'SHELL': '/bin/bash', 06:25:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EIhk0nCU2G/Listeners', 06:25:55 INFO - 'TMPDIR': '/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/T/', 06:25:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:25:55 INFO - 'USER': 'cltbld', 06:25:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:25:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:25:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:25:55 INFO - 'XPC_FLAGS': '0x0', 06:25:55 INFO - 'XPC_SERVICE_NAME': '0', 06:25:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:25:55 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Cedar', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Bjl2DGrTS3yeT88IzDPm3Q/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0187', '--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 06:25:55 INFO - mozversion application_buildid: 20161118011229 06:25:55 INFO - mozversion application_changeset: 4faf584a1be9ac695ea79b92c772519dcdcc7722 06:25:55 INFO - mozversion application_display_name: Nightly 06:25:55 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 06:25:55 INFO - mozversion application_name: Firefox 06:25:55 INFO - mozversion application_remotingname: firefox 06:25:55 INFO - mozversion application_repository: https://hg.mozilla.org/projects/cedar 06:25:55 INFO - mozversion application_vendor: Mozilla 06:25:55 INFO - mozversion application_version: 53.0a1 06:25:55 INFO - mozversion platform_buildid: 20161118011229 06:25:55 INFO - mozversion platform_changeset: 4faf584a1be9ac695ea79b92c772519dcdcc7722 06:25:55 INFO - mozversion platform_repository: https://hg.mozilla.org/projects/cedar 06:25:55 INFO - mozversion platform_version: 53.0a1 06:25:55 INFO - using testdate: 1479479155 06:25:55 INFO - actual date: 1479479155 06:25:55 INFO - starting webserver on 'localhost:50660' 06:25:55 INFO - SUITE-START | Running 2 tests 06:25:55 INFO - TEST-START | dromaeo_css 06:25:55 INFO - operating with platform_type : mac_ 06:25:55 INFO - Initialising browser for dromaeo_css test... 06:25:55 INFO - TEST-INFO | started process 1934 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/T/tmpKewiqw/profile http://localhost:50660/getInfo.html) 06:25:58 INFO - PROCESS | 1934 | __metrics Screen width/height:1600/1200 06:25:58 INFO - PROCESS | 1934 | colorDepth:24 06:25:58 INFO - PROCESS | 1934 | Browser inner width/height: 1024/689 06:25:58 INFO - PROCESS | 1934 | __metrics 06:25:58 INFO - PROCESS | 1934 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 06:25:58 INFO - PROCESS | 1934 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 06:25:59 INFO - TEST-INFO | 1934: exit 0 06:25:59 INFO - Browser initialized. 06:25:59 INFO - Running cycle 1/1 for dromaeo_css test... 06:25:59 INFO - TEST-INFO | started process 1936 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/T/tmpKewiqw/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 06:26:11 INFO - PROCESS | 1936 | RSS: Main: 243830784 06:26:11 INFO - PROCESS | 1936 | 06:27:02 INFO - PROCESS | 1936 | 1479479222670 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] 06:29:15 INFO - PROCESS | 1936 | Cycle 1(1): loaded http://localhost:50660/tests/dromaeo/cssquery-dojo.html (next: http://localhost:50660/tests/dromaeo/cssquery-ext.html) 06:29:18 INFO - PROCESS | 1936 | RSS: Main: 465801216 06:29:18 INFO - PROCESS | 1936 | 06:32:21 INFO - PROCESS | 1936 | Cycle 1(1): loaded http://localhost:50660/tests/dromaeo/cssquery-ext.html (next: http://localhost:50660/tests/dromaeo/cssquery-jquery.html) 06:32:21 INFO - PROCESS | 1936 | RSS: Main: 369287168 06:32:21 INFO - PROCESS | 1936 | 06:35:24 INFO - PROCESS | 1936 | Cycle 1(1): loaded http://localhost:50660/tests/dromaeo/cssquery-jquery.html (next: http://localhost:50660/tests/dromaeo/cssquery-mootools.html) 06:35:24 INFO - PROCESS | 1936 | RSS: Main: 339808256 06:35:24 INFO - PROCESS | 1936 | 06:38:27 INFO - PROCESS | 1936 | Cycle 1(1): loaded http://localhost:50660/tests/dromaeo/cssquery-mootools.html (next: http://localhost:50660/tests/dromaeo/cssquery-prototype.html) 06:38:27 INFO - PROCESS | 1936 | RSS: Main: 347398144 06:38:27 INFO - PROCESS | 1936 | 06:41:31 INFO - PROCESS | 1936 | Cycle 1(1): loaded http://localhost:50660/tests/dromaeo/cssquery-prototype.html (next: http://localhost:50660/tests/dromaeo/cssquery-yui.html) 06:41:31 INFO - PROCESS | 1936 | RSS: Main: 329072640 06:41:31 INFO - PROCESS | 1936 | 06:44:35 INFO - PROCESS | 1936 | Cycle 1(1): loaded http://localhost:50660/tests/dromaeo/cssquery-yui.html (next: http://localhost:50660/tests/dromaeo/cssquery-dojo.html) 06:44:35 INFO - PROCESS | 1936 | RSS: Main: 435175424 06:44:35 INFO - PROCESS | 1936 | 06:44:35 INFO - PROCESS | 1936 | __start_tp_report 06:44:35 INFO - PROCESS | 1936 | _x_x_mozilla_page_load 06:44:35 INFO - PROCESS | 1936 | _x_x_mozilla_page_load_details 06:44:35 INFO - PROCESS | 1936 | |i|pagename|runs| 06:44:35 INFO - PROCESS | 1936 | |0;dojo.html;2704.3104008477835;2833.9008134715286;3262.738980881531;3299.3566254580364;3308.9503657445157;5494.478024587668;5528.917066243999;5552.583556233283;5574.219609254703;5615.887682246353;5556.277683901098;5568.972155139219;5580.944190558103;5591.329040515144;5604.467575580314;5038.647294689373;5080.542751152396;5084.034033533617;5106.285120083191;5165.225216217578;5076.796928122871;5084.389873215219;5184.818531351403;5190.455002224769;5202.4537423570355;5084.822031228908;5109.3613298337705;5115.360579927509;5119.385673719159;5149.098907691157;5135.948640513603;5142.948570514322;5149.356330458692;5152.433232344439;5220.269162317279;4965.702057876539;4976.950230497721;5025.14572522672;5110.693358398508;5123.2315152727015;5411.377691565481;5448.046591846411;5471.72641367934;5503.394626591072;5512.641678290898;5972.522198224132;5992.981193197167;5994.880102397928;6008.399160084;6016.759329626853;5983.072623743326;6002.189704389895;6107.633541987495;6115.327313995457;6147.938520614782;5613.410591887834;5619.971900140514;5621.550275977994;5622.57830662702;5624.3813180550105;6114.419130182626;6157.322694503601;6164;6167.475764560063;6169.84575385619;5829.7376618052285;5867.119932010232;5883.382244864228;5889.4699477046415;5896.941030589725;6029.758809647565;6054.909176362358;6061.818145455643;6065;6080.908786368208;5958.225430693982;5978.940210597925;6004.42957918997;6014.969925150346;6015.578909476295;6015.2781666200335;6030.366811484819;6030.547708921761;6033.728482218311;6052.182955300978;4786.856394308176;4788.233882578771;4806.903861922742;4835.782389792468;4863.537963893425;3890.105275786528;4336.82652693895;4382.23310920594;4413;4415.558444155558;4598.28726547379;5432.185172224198;5500.1749737538885;5506.311711036121;5568.916466253009;6133.447989680861;6156.5998210116195;6194.473469755031;6200.100985357189;6211.068339748984;5596.412376700469;5613.438656134354;5613.607047506635;5645.2096706461125;5655.491005809415;5132.28148059272;5145.048166089286;5158.4067832199025;5167.9224811627855;5282.867928301823;6117.1130186123655;6136.028952951454;6137.560853825989;6139.969300153471;6156.015037594143;5919.082542205965;5926.4666180044005;5926.525877929842;5946.613470124428;5986.461218490357;5595.517187945279;6082.482988945812;6118.235220597426;6127.988881834447;6140.815775526741;5887.793927212527;5929.0217114175675;5949.702514874152;6007.12896629995;6072.514198864169;6070.271567411939;6070.362611925684;6076.270847498152;6081.6655083969035;6112.908306375319;5674.517665998436;5899.026660600953;5912.320083190488;5923.49650279731;5929.584929054925;5787.971060144672;5830.679312637845;5838.357780644041;5841.007028805198;5848.4151584841175;5677.091665333527;5705.515031222392;5735.426457354231;5746.109353050284;5755.683437410982;5789.884202316014;5801.506871915934;5804.419558044331;5809.709514524172;5818.330891947311;5704.030314846569;5713.8285851424525;5726.284214473191;5727.11229759388;5729.8567535811935 06:44:35 INFO - PROCESS | 1936 | |1;ext.html;444.903313332635;472.95506926841983;474.3738265489553;500.29708259894835;515.2708916882611;29759.85120074402;31129.688703112963;37834.05414864626;38426.80786596063;38603.806980965055;39893.60106398935;40252.19495610093;40404;40457.98855028626;40651.59348406515;9193.17261446468;9372.578233979453;9382.343235973485;9389.62441502339;9393.85909211359;9315.534223288842;9410.52947352633;9414.29392795543;9433.481158536279;9436.81126377472;9385.483798391086;9433.622655093788;9451.149396554309;9454.669086581971;9455.338126331126;9354;9412.15290623847;9418.152366286999;9424.858627120599;9440.055994400574;9119.361644684876;9136.725898223063;9168.129027742421;9202.861957070649;9259.6296148154;38184.66353677608;38636.61363386386;38796;38797.418038729156;38816.22367552633;2810.9437811243865;3229.3694309474613;3232.595925509311;3453.792772433637;3599.460080987873;28146.85926570354;29277.85361073181;30100.548491772643;30134.54798178029;30378.392432151453;2701.9864900675566;3094.257378229231;3134.279115803355;3287.1289108386295;3290.128116049249;382778.34443311254;451744;473487;477360.6131969353;490251;59490.405095949354;59569.106463403084;59898.40101599015;59948;60036.799264015346;61918.69040654768;61921.69039154775;62056.44858878474;62137.75724485485;62154.68922655358;15387.769183462256;15629.68740625181;15633.687326253412;15643.765343519857;15651.921740391454;11770.352630605228;11820.113491488173;11853.46659400329;11864.88135118655;11867.465964031466;14236.572902812932;14457.855421445862;18067.186976586087;18088.00515971608;18095.466634671156;5830.504407125357;7401.111866576045;7545.434092443089;7546.622668866645;7647.923520764833;757.8408534207767;784.3764207455087;885.9645614175361;1007.37039350406;1049.375621505203;27599;27928.441431171264;28924.855375723408;29560.704392956224;29725.40549189004;1013.7719013222113;1021.9897801021895;1023.99488002561;1062.6121465664976;1098.9395233599614;13542.729145417234;14802.62993425173;14869.776953345709;15252.542423727306;15349.386024558893;656356;657900;659852;661343.6932815305;663971;56709;56965;57008.14487782687;57038.14442783361;57096;1207.2334067866968;1238.1889862140313;1324.9867501324863;1359.3067535556936;1376.9741542550864;24499.75500245046;30089.247768805955;30287.697123028487;30861;30881.382372351978;47555.811104722656;48026.27960580524;48077.51922480731;48127.75936120437;48158.518414816805;507220;508106;508844;511736;630123;37851.432228516045;39292.60707392889;39312.2137557253;39695.603043970346;40115.79942100271;43744;44335.77832110819;44579.777101115586;44641.33038004368;44894.3265851006;35973.64026359703;36574.49063243523;36719;37247.81376093102;37390.62609373871;36504.81747591245;36509;37224;37375.62624373721;37635.24729505449;35625.643743562236;35765.642343577274;36513.81743091374;36681.44977825388;36745.265094698494;31887.521687175176;33372.83313583417;33472;33647.831760841036;33873.661263388036;24555.50888982246;24648.876755616107;24855.502889941738;24943;25359.492810143325 06:44:35 INFO - PROCESS | 1936 | |2;jquery.html;343.1181862613084;347.7426704238864;370.34264181078584;372.08281585890774;396.95831937646545;14793.482228122019;14918.627034324147;15789.289481973296;20118.195272189096;20676.48308792277;18755.624887502243;19327.226910923546;19710.802891971078;19828.30600928964;20093.698594521094;5598.384177740456;5866.20806191163;5867.88264234715;5868;5869.471747542721;5226.137687281594;5533.335999680044;5812.941870581303;5866.325372582143;5882.14708867214;5768.3654797972185;5814.156947242647;5823.388544202861;5826.650400975934;5826.9708651456685;5529.419410961851;5709.771609135651;5737.053386191274;5763.077907534816;5767.971160144214;4871.318015477836;4962.5533701967;5035.697858128524;5046.343975283226;5059.569936555397;24260.757392426025;24312.513749725083;24350;24613.63079553808;24797.50404991908;2266.4787098967163;2360.480694247263;2370.182287110944;2415.263344679872;2432.1852179519883;14360.856391436057;15281.46514871974;15537.378504859793;15677.651323804654;15913.204339782964;1801.783785945695;1908.9713654295197;1910.436421255733;1931.3723040011912;1940.9514762130918;102009.9799002008;108096.4595177027;123427;124251;124519;10702.197335199891;10947.178961577914;11074.39090850011;11077.612283570037;11307;14029;14059.78910316346;14062.367193476113;14064.578062658136;14089.436422542984;8022.839543209103;8168;8192.83614327722;8278.461899976603;8293.004839419187;6859.416949559343;7900.407469439815;8066.475679080841;8089.676412943534;8218.342532597379;3805.1058001369656;3820.3505404081375;4223.036288126036;4448.332750087513;4538.659600529907;1784.3754685859844;1890.1588792986988;1891.527118220445;1895.3176856331709;1916.3292847503262;835.4611275727243;857;862.6894318045498;872.2934423117168;925.3291363761219;19035.68879728112;20553.897230514052;20562.97185140737;20626.381208563766;20875.895620521802;888.7689200807707;897.6409436225562;907.5462268865567;908.4957848394205;952.319091849331;8073.515589064675;10570.841437378445;10791.59709237794;11198.992090711712;11206.935341142578;560174;577702;579512;594096;597354;89833;90586.54706726424;120426;124161;126639;974.70758772368;981.8379967305458;1031.4430207687915;1031.5461197073414;1031.9432431216355;18401;18538.536536586693;18921.90539047296;19189.61620767605;19208.231670733014;42516.724498263866;50512.23832929934;52601;52671.4694957024;52723;58603.82792344044;75355.62322188573;75701.62149189219;78835.60582197053;79081;7530.811729706801;7719.961400192963;7787.98756161718;7891.487053341514;7918.524888506708;9342.439453632804;11971.580994665144;12288.1398302118;12389.814152787896;12436.502539898303;9698.75753106178;10875.401852898167;10914.345139291669;10920;10932.672019839418;8916.242119419921;10036.146927510952;10451.477426128511;10635.096016838655;10838.729031774268;9151.405158664666;10700.946495267473;11091.611793587186;11140.220184587;11295.491702873225;7678.961605191938;7836.647350869339;7845.803854903673;7869.960650196713;8022.83954320922;9375.531223438664;10528.473576321307;10814.675559733221;10941.45292735376;11021.44892755375 06:44:35 INFO - PROCESS | 1936 | |3;mootools.html;1327.7610030194564;1435.8277006759188;1563.6169138561054;1801.8018018018024;1847.344192811552;7823.9217607823975;8296.336293096552;8304.95847520763;8313.127121652231;8317.085120636724;7347.26527347265;7524.134724506684;7535.6232188390495;7813.218678132184;8124.431289809716;2216.9889150554222;2288.530851175505;2290.438842483594;2291;2291.9312420627407;2280.076568989562;2312.6646636237733;2323.779240972105;2328.0105954969154;2329.9184528541505;2263.1173842201474;2264.071730590458;2287.0623044551735;2294.4263934016494;2297.4830663100834;2262.1290803040865;2271.3186044186677;2274.9886250568807;2279.783420575051;2280.258915852338;2256.199049337494;2286.27982185611;2286.9303280531144;2288.9084436622516;2295.047555264563;6967.756128535528;8118.594070296462;8152.5516096614665;8210.958945205235;8250.174982501761;4178.707490475668;5151.922721159186;5162.967406518674;5169.405518365361;5169.457206993249;3251.138448311199;3305.3719793239206;3490.0925759302763;3583.62371950944;3584.766990145632;1047.0471870597719;1070.2347821307724;1087.5486673030682;1089.6676513663328;1097.3470784882982;10238.590456381737;10575.629852955186;10621.468926553718;10783.299085559414;10913.454327283684;4183.142455796555;4288.270993931039;4376.811594202899;4415.315626077963;4464.2187617166865;8557.700480483278;8566.057733649293;8567.957160214159;8572.057073721886;8581.528015959182;5028.79884804611;5036.169032109661;5037.0681423936685;5040.344755181803;5048.823291184791;3848.7299191266816;4176.869796403796;4570.13167498181;4739.4075740532435;4750.904981900343;3520;3536.964630353715;3595.658412450813;3612.2414292998237;3643.0710168906758;951.7906060666643;1120.9439528023565;1123.4663534820895;1125.3248051169232;1129.5538262386476;742.7920182348951;784.854801861646;820.4420993724325;882.8808110905074;901.4230892228848;5396.006954853458;5426.051228864532;5710.143478478179;5721.227634269404;5854.5901786875365;433.4040694045687;923.2429407885469;942.1332374215738;989.6981420666622;1036.9377837329785;3651.4887915691825;3755.887323380303;3841.8847434577006;3879.1853710721066;3925.509311336083;8106.351491880635;9698.418094914186;9802.578626099175;10117.190624750003;10164.33931794431;9639.759006024766;9669.033096690275;9997.950010249902;10044.899551004542;10072.496375181212;7641;7642.388608911274;7675.577843218565;7834.608269586498;7849.764507064797;5470.124780035176;5544.63959842609;5568.749406276808;5604.551635869039;5618.410066943075;9337.299702522203;9367.110124538158;9374.437533747998;9378.249740020645;9390.389624674373;9187.127222913812;9362.29782766309;9404.905950940403;9639;9643.807123857343;6627.900581491378;6688.7324507019175;6739.730410783513;6771.89842152378;6802.591844489347;7729.227077292226;7808;7819.8436031277915;7821.843563128819;7832.686692532235;6858.725650973905;7062.929370706433;7066.152061752417;7129.714811407485;7186.993820865187;6633.800985970429;6662.900056499059;6894.758683446055;6901.378875901193;6904;6273.96863015682;6450.225972883284;6528.151340325728;6553.377429144223;6657.633830139389;4330.805113769951;4672.696274742267;4705.694129881546;4718.197906355859;4770.666053376231;4769.689970152068;4809.759512024455;4850.272459131159;4896.094222568836;5046.268291097844 06:44:35 INFO - PROCESS | 1936 | |4;prototype.html;294.73179406739865;296.5033568772307;306.78831606191727;312.8826689991253;313.9905802825916;4992.725400102993;5074.995376826156;5148.910653607833;5166.715830629319;5173.405058418283;4292.720973136744;4763.476017638057;5024.974875125628;5071.728734965945;5081.002208696868;3271.9018429447037;3464.8094354810473;3658.5243918290585;3771.4531392948143;4015.237104950065;2703.6079768433565;4002.919941601166;4011.9598804012026;4017.1363156921257;4036.4550785643855;3535.92928141437;3898.961010389888;3915.4956314098863;3937.370020796673;3940.9802950985204;3066.9386612267854;3227.193201699575;3416.248425346432;3704;3927.528696556403;3277.4428347181038;3399.2351720862857;3414.334204830059;3420.9315813683834;3436.862525498977;3787.8295476703615;4345.956540434587;4908.975455122737;5034.79860805571;5056.637336171441;3927.7627547322786;4473.597376236155;4474.574915383034;4490.955090449086;4503.527129651405;4036.4147198656324;4510.684252102355;4556.270996640522;4683.96952670412;5465.857132148991;3664.8900532983785;3718.2563487302646;3736.4768932349493;3747.9812600937094;3752.343339915531;4666.090112428078;5076.36545431821;5223.869403264911;5664.49019588239;5689.544836413077;3777.905552361158;3788.8105594720155;3796.563395209531;3796.6013568575454;3813.161104556993;3199.5360672702327;3272.887218345774;3602.6217247189193;3616.782993020427;3835.2137811748526;4064.6748260139116;4429.9778501107285;4447.2439685253585;4475.955240447619;4492.932606010912;4455.977720111444;5381.623286369995;5401.432849550819;5426;5426.6975925778315;1032.488917985602;1150.465033759306;1195.8041958041958;1205.8311836342918;1219.4634360881184;826.6445428465818;848.2238751542393;859.6991053131479;863.6545381847311;864.8972559985942;4851.927221091686;4853.684510506806;4883.145449546315;4892.755362231946;4900.509949005071;4318.784060796947;5182.53357197854;5250.264962905197;5278.894422111536;5291.814786482455;3940.4483372327895;4134.945588874818;4624.72819974503;4763.595094416944;4823.614110871122;1987.5826076524093;2531.810049276837;2654.761071503574;2797.7062408447227;2816.788740844445;4317.129109142448;4693.319468677023;5381.919271210857;5512.6968016759465;5545.972270138624;4625.8843528911375;4927.77824997876;5057.747112644427;5389.784408623689;5493.642913210629;4661.627069834405;5396.568274538027;5875.50058245054;5882.205902203319;5904.110684580893;5670.262865827416;5676.5742569308295;5724.4561766632105;5736.110902810071;5739.139129130664;4648;4733;5251.36983561975;5623.325200975909;5647.661140331593;5899.587028907935;5913.34953155144;5913.881722365614;5914.835562973788;5919.7040147991565;3827.61723827615;3848.9615103848605;3854.1713531590844;3854.325493038651;3869.9806500967316;5306.363236411656;5337.839864804062;5374.838754837361;5530.695811730394;5653.745581448762;4706.341112244357;4796.616270698266;5078.577998160521;5339.036834556859;5429.592780541554;4421.607193733964;4488.630967554927;4725.385699858996;5400.918986215289;5561.4716601922755;5248.475152484873;5384.273123128484;5434.3207099112615;5435.429279925709;5444.455554444524;4783.617310615073;5511.319047690493;5529.502344788988;5548.472895074961;5576.228603395466;4438.60052595268;4504.355910092858;5253.921191182212;5408.5673146149;5412.594055445936 06:44:35 INFO - PROCESS | 1936 | |5;yui.html;672.253798283905;706.6043015911087;717.175248464266;756.5536333563197;769.8344855855993;13266.734665306689;14212.289385530708;14227.075240109398;14250.572482825532;14803.703925921474;13331.666708332297;13489.460421583124;13512.527061552848;13515;13531.391087401065;640.4300172846171;660.8050625065617;663.9668016599151;806.2904643913348;818.979525511861;660.4848218389664;664.362212276213;664.5912763650368;789.4592204340046;818.5416166946529;859.4113032572676;859.9269062129695;867.2974890338814;867.6876324523198;872.8036191856845;830.4560512864053;864.6411739128317;865.8614621660568;865.9350548708809;869.7521206456162;556.2351766321308;650.3983814971133;650.4503694378243;650.6161364794747;651.9991812567686;11100;11583.131265155147;13438.193708377528;13877.653058673613;13933.58199254024;13247.271400072992;13445;13573;13714.108582942077;13726.176429414267;9275.953620231923;11096.1123110151;11172.720681983015;11221.719457013558;11624.011958983496;3607.837647305851;3624.4744512045604;3652.8356223969986;3655.9268814623824;3688.5942546319884;76360.61819690921;83328.75006874841;84860.57569712173;86807;86874;10117;10284;10492.21308401873;10501.632442864464;10796.67609971702;19974.30089946845;20216.39350819478;20363.694544581842;20443.08006139727;20447.591048178954;7536.359409450258;7569;7573.242675732383;7665.23347665229;7759.495632783851;4130.359794231899;4270.444842170498;4276.379924910871;4323.913521729611;4462.553744625511;3265.771396002257;3377.273886114497;3378.831058447068;3379.259681264151;3379.377898608682;590.9970450147722;599.946004859556;605.5246631394334;612.1429998002743;619.3806193806194;626.3517259636254;635.9936400636027;644.8323435906605;654.6726636681659;666.7525288050897;8566.357523185787;8868.822623547492;8884.334699590097;8988.235999940078;9059.456432614064;537.6397813464988;592.6799528254694;627.419636835934;636.4590098416309;657.2441692054081;3716.7212459065136;3906.0430194601936;3944.954587034438;4030.3694753956906;4163.708540402143;88573;113195.86804131854;115807.40392924736;119329.8402095877;122825.38587307006;99640.00359996452;100643.49678251562;105173;105471.47264263629;107138;86053.70919436213;90216;90625.09374906166;91135.5443222793;94616;6566.967165164144;7621.264268204984;8373.539455330016;8411.200935911078;8426.368022398223;41469.585304146574;44599.55400445954;51468.48531514637;56334.43665563292;56944.430555693914;58830.411695882496;79075.60462197653;80537;80675.59662201651;83485.58257208676;11000.504977276201;11963.461644225858;12132.393380330772;12175.14773965814;12581.119321647397;14080.295985200493;14280;14570.781438278222;14784.63038424048;15057.54827355181;12169.087318450975;12649;12815.16701414405;13070.692930706853;13421.463141474233;13142.737145257233;13242.337883105614;13296.069275151034;13585.59243222705;13672.453101875813;11106.666799996014;12410.255384676948;13271.800922985969;13290.269035203157;13565.864341356855;8467.449615774955;10884.836727449254;10988.835167472655;11033.393163376091;11741.236819606698;10418.739531511774;10843.945780271048;11046.392448415092;11224.719382015515;11339.773204536028 06:44:35 INFO - PROCESS | 1936 | __end_tp_report 06:44:35 INFO - PROCESS | 1936 | __start_cc_report 06:44:35 INFO - PROCESS | 1936 | _x_x_mozilla_cycle_collect,2748 06:44:35 INFO - PROCESS | 1936 | __end_cc_report 06:44:35 INFO - PROCESS | 1936 | __startTimestamp1479480275405__endTimestamp 06:44:35 INFO - PROCESS | 1936 | 06:44:35 INFO - PROCESS | 1936 | ------- Summary: start ------- 06:44:35 INFO - PROCESS | 1936 | Number of tests: 6 06:44:35 INFO - PROCESS | 1936 | 06:44:35 INFO - PROCESS | 1936 | [#0] dojo.html Cycles:165 Average:5561.85 Median:5727.11 stddev:634.96 (11.1%) stddev-sans-first:596.02 06:44:35 INFO - PROCESS | 1936 | Values: 2704.3 2833.9 3262.7 3299.4 3309.0 5494.5 5528.9 5552.6 5574.2 5615.9 5556.3 5569.0 5580.9 5591.3 5604.5 5038.6 5080.5 5084.0 5106.3 5165.2 5076.8 5084.4 5184.8 5190.5 5202.5 5084.8 5109.4 5115.4 5119.4 5149.1 5135.9 5142.9 5149.4 5152.4 5220.3 4965.7 4977.0 5025.1 5110.7 5123.2 5411.4 5448.0 5471.7 5503.4 5512.6 5972.5 5993.0 5994.9 6008.4 6016.8 5983.1 6002.2 6107.6 6115.3 6147.9 5613.4 5620.0 5621.6 5622.6 5624.4 6114.4 6157.3 6164.0 6167.5 6169.8 5829.7 5867.1 5883.4 5889.5 5896.9 6029.8 6054.9 6061.8 6065.0 6080.9 5958.2 5978.9 6004.4 6015.0 6015.6 6015.3 6030.4 6030.5 6033.7 6052.2 4786.9 4788.2 4806.9 4835.8 4863.5 3890.1 4336.8 4382.2 4413.0 4415.6 4598.3 5432.2 5500.2 5506.3 5568.9 6133.4 6156.6 6194.5 6200.1 6211.1 5596.4 5613.4 5613.6 5645.2 5655.5 5132.3 5145.0 5158.4 5167.9 5282.9 6117.1 6136.0 6137.6 6140.0 6156.0 5919.1 5926.5 5926.5 5946.6 5986.5 5595.5 6082.5 6118.2 6128.0 6140.8 5887.8 5929.0 5949.7 6007.1 6072.5 6070.3 6070.4 6076.3 6081.7 6112.9 5674.5 5899.0 5912.3 5923.5 5929.6 5788.0 5830.7 5838.4 5841.0 5848.4 5677.1 5705.5 5735.4 5746.1 5755.7 5789.9 5801.5 5804.4 5809.7 5818.3 5704.0 5713.8 5726.3 5727.1 5729.9 06:44:35 INFO - PROCESS | 1936 | 06:44:35 INFO - PROCESS | 1936 | [#1] ext.html Cycles:180 Average:68098.57 Median:38716.31 stddev:148958.78 (384.7%) stddev-sans-first:149290.04 06:44:35 INFO - PROCESS | 1936 | Values: 444.9 473.0 474.4 500.3 515.3 29759.9 31129.7 37834.1 38426.8 38603.8 39893.6 40252.2 40404.0 40458.0 40651.6 9193.2 9372.6 9382.3 9389.6 9393.9 9315.5 9410.5 9414.3 9433.5 9436.8 9385.5 9433.6 9451.1 9454.7 9455.3 9354.0 9412.2 9418.2 9424.9 9440.1 9119.4 9136.7 9168.1 9202.9 9259.6 38184.7 38636.6 38796.0 38797.4 38816.2 2810.9 3229.4 3232.6 3453.8 3599.5 28146.9 29277.9 30100.5 30134.5 30378.4 2702.0 3094.3 3134.3 3287.1 3290.1 382778.3 451744.0 473487.0 477360.6 490251.0 59490.4 59569.1 59898.4 59948.0 60036.8 61918.7 61921.7 62056.4 62137.8 62154.7 15387.8 15629.7 15633.7 15643.8 15651.9 11770.4 11820.1 11853.5 11864.9 11867.5 14236.6 14457.9 18067.2 18088.0 18095.5 5830.5 7401.1 7545.4 7546.6 7647.9 757.8 784.4 886.0 1007.4 1049.4 27599.0 27928.4 28924.9 29560.7 29725.4 1013.8 1022.0 1024.0 1062.6 1098.9 13542.7 14802.6 14869.8 15252.5 15349.4 656356.0 657900.0 659852.0 661343.7 663971.0 56709.0 56965.0 57008.1 57038.1 57096.0 1207.2 1238.2 1325.0 1359.3 1377.0 24499.8 30089.2 30287.7 30861.0 30881.4 47555.8 48026.3 48077.5 48127.8 48158.5 507220.0 508106.0 508844.0 511736.0 630123.0 37851.4 39292.6 39312.2 39695.6 40115.8 43744.0 44335.8 44579.8 44641.3 44894.3 35973.6 36574.5 36719.0 37247.8 37390.6 36504.8 36509.0 37224.0 37375.6 37635.2 35625.6 35765.6 36513.8 36681.4 36745.3 31887.5 33372.8 33472.0 33647.8 33873.7 24555.5 24648.9 24855.5 24943.0 25359.5 06:44:35 INFO - PROCESS | 1936 | 06:44:35 INFO - PROCESS | 1936 | [#2] jquery.html Cycles:180 Average:33621.39 Median:345.43 stddev:96846.35 (28036.4%) stddev-sans-first:97085.80 06:44:35 INFO - PROCESS | 1936 | Values: 343.1 347.7 370.3 372.1 397.0 14793.5 14918.6 15789.3 20118.2 20676.5 18755.6 19327.2 19710.8 19828.3 20093.7 5598.4 5866.2 5867.9 5868.0 5869.5 5226.1 5533.3 5812.9 5866.3 5882.1 5768.4 5814.2 5823.4 5826.7 5827.0 5529.4 5709.8 5737.1 5763.1 5768.0 4871.3 4962.6 5035.7 5046.3 5059.6 24260.8 24312.5 24350.0 24613.6 24797.5 2266.5 2360.5 2370.2 2415.3 2432.2 14360.9 15281.5 15537.4 15677.7 15913.2 1801.8 1909.0 1910.4 1931.4 1941.0 102010.0 108096.5 123427.0 124251.0 124519.0 10702.2 10947.2 11074.4 11077.6 11307.0 14029.0 14059.8 14062.4 14064.6 14089.4 8022.8 8168.0 8192.8 8278.5 8293.0 6859.4 7900.4 8066.5 8089.7 8218.3 3805.1 3820.4 4223.0 4448.3 4538.7 1784.4 1890.2 1891.5 1895.3 1916.3 835.5 857.0 862.7 872.3 925.3 19035.7 20553.9 20563.0 20626.4 20875.9 888.8 897.6 907.5 908.5 952.3 8073.5 10570.8 10791.6 11199.0 11206.9 560174.0 577702.0 579512.0 594096.0 597354.0 89833.0 90586.5 120426.0 124161.0 126639.0 974.7 981.8 1031.4 1031.5 1031.9 18401.0 18538.5 18921.9 19189.6 19208.2 42516.7 50512.2 52601.0 52671.5 52723.0 58603.8 75355.6 75701.6 78835.6 79081.0 7530.8 7720.0 7788.0 7891.5 7918.5 9342.4 11971.6 12288.1 12389.8 12436.5 9698.8 10875.4 10914.3 10920.0 10932.7 8916.2 10036.1 10451.5 10635.1 10838.7 9151.4 10700.9 11091.6 11140.2 11295.5 7679.0 7836.6 7845.8 7870.0 8022.8 9375.5 10528.5 10814.7 10941.5 11021.4 06:44:35 INFO - PROCESS | 1936 | 06:44:35 INFO - PROCESS | 1936 | [#3] mootools.html Cycles:180 Average:5308.02 Median:5100.37 stddev:2901.59 (56.9%) stddev-sans-first:2894.31 06:44:35 INFO - PROCESS | 1936 | Values: 1327.8 1435.8 1563.6 1801.8 1847.3 7823.9 8296.3 8305.0 8313.1 8317.1 7347.3 7524.1 7535.6 7813.2 8124.4 2217.0 2288.5 2290.4 2291.0 2291.9 2280.1 2312.7 2323.8 2328.0 2329.9 2263.1 2264.1 2287.1 2294.4 2297.5 2262.1 2271.3 2275.0 2279.8 2280.3 2256.2 2286.3 2286.9 2288.9 2295.0 6967.8 8118.6 8152.6 8211.0 8250.2 4178.7 5151.9 5163.0 5169.4 5169.5 3251.1 3305.4 3490.1 3583.6 3584.8 1047.0 1070.2 1087.5 1089.7 1097.3 10238.6 10575.6 10621.5 10783.3 10913.5 4183.1 4288.3 4376.8 4415.3 4464.2 8557.7 8566.1 8568.0 8572.1 8581.5 5028.8 5036.2 5037.1 5040.3 5048.8 3848.7 4176.9 4570.1 4739.4 4750.9 3520.0 3537.0 3595.7 3612.2 3643.1 951.8 1120.9 1123.5 1125.3 1129.6 742.8 784.9 820.4 882.9 901.4 5396.0 5426.1 5710.1 5721.2 5854.6 433.4 923.2 942.1 989.7 1036.9 3651.5 3755.9 3841.9 3879.2 3925.5 8106.4 9698.4 9802.6 10117.2 10164.3 9639.8 9669.0 9998.0 10044.9 10072.5 7641.0 7642.4 7675.6 7834.6 7849.8 5470.1 5544.6 5568.7 5604.6 5618.4 9337.3 9367.1 9374.4 9378.2 9390.4 9187.1 9362.3 9404.9 9639.0 9643.8 6627.9 6688.7 6739.7 6771.9 6802.6 7729.2 7808.0 7819.8 7821.8 7832.7 6858.7 7062.9 7066.2 7129.7 7187.0 6633.8 6662.9 6894.8 6901.4 6904.0 6274.0 6450.2 6528.2 6553.4 6657.6 4330.8 4672.7 4705.7 4718.2 4770.7 4769.7 4809.8 4850.3 4896.1 5046.3 06:44:35 INFO - PROCESS | 1936 | 06:44:35 INFO - PROCESS | 1936 | [#4] prototype.html Cycles:180 Average:4290.75 Median:4688.64 stddev:1347.71 (28.7%) stddev-sans-first:1317.70 06:44:35 INFO - PROCESS | 1936 | Values: 294.7 296.5 306.8 312.9 314.0 4992.7 5075.0 5148.9 5166.7 5173.4 4292.7 4763.5 5025.0 5071.7 5081.0 3271.9 3464.8 3658.5 3771.5 4015.2 2703.6 4002.9 4012.0 4017.1 4036.5 3535.9 3899.0 3915.5 3937.4 3941.0 3066.9 3227.2 3416.2 3704.0 3927.5 3277.4 3399.2 3414.3 3420.9 3436.9 3787.8 4346.0 4909.0 5034.8 5056.6 3927.8 4473.6 4474.6 4491.0 4503.5 4036.4 4510.7 4556.3 4684.0 5465.9 3664.9 3718.3 3736.5 3748.0 3752.3 4666.1 5076.4 5223.9 5664.5 5689.5 3777.9 3788.8 3796.6 3796.6 3813.2 3199.5 3272.9 3602.6 3616.8 3835.2 4064.7 4430.0 4447.2 4476.0 4492.9 4456.0 5381.6 5401.4 5426.0 5426.7 1032.5 1150.5 1195.8 1205.8 1219.5 826.6 848.2 859.7 863.7 864.9 4851.9 4853.7 4883.1 4892.8 4900.5 4318.8 5182.5 5250.3 5278.9 5291.8 3940.4 4134.9 4624.7 4763.6 4823.6 1987.6 2531.8 2654.8 2797.7 2816.8 4317.1 4693.3 5381.9 5512.7 5546.0 4625.9 4927.8 5057.7 5389.8 5493.6 4661.6 5396.6 5875.5 5882.2 5904.1 5670.3 5676.6 5724.5 5736.1 5739.1 4648.0 4733.0 5251.4 5623.3 5647.7 5899.6 5913.3 5913.9 5914.8 5919.7 3827.6 3849.0 3854.2 3854.3 3870.0 5306.4 5337.8 5374.8 5530.7 5653.7 4706.3 4796.6 5078.6 5339.0 5429.6 4421.6 4488.6 4725.4 5400.9 5561.5 5248.5 5384.3 5434.3 5435.4 5444.5 4783.6 5511.3 5529.5 5548.5 5576.2 4438.6 4504.4 5253.9 5408.6 5412.6 06:44:35 INFO - PROCESS | 1936 | 06:44:35 INFO - PROCESS | 1936 | [#5] yui.html Cycles:180 Average:20695.74 Median:22799.97 stddev:31015.92 (136.0%) stddev-sans-first:31066.49 06:44:35 INFO - PROCESS | 1936 | Values: 672.3 706.6 717.2 756.6 769.8 13266.7 14212.3 14227.1 14250.6 14803.7 13331.7 13489.5 13512.5 13515.0 13531.4 640.4 660.8 664.0 806.3 819.0 660.5 664.4 664.6 789.5 818.5 859.4 859.9 867.3 867.7 872.8 830.5 864.6 865.9 865.9 869.8 556.2 650.4 650.5 650.6 652.0 11100.0 11583.1 13438.2 13877.7 13933.6 13247.3 13445.0 13573.0 13714.1 13726.2 9276.0 11096.1 11172.7 11221.7 11624.0 3607.8 3624.5 3652.8 3655.9 3688.6 76360.6 83328.8 84860.6 86807.0 86874.0 10117.0 10284.0 10492.2 10501.6 10796.7 19974.3 20216.4 20363.7 20443.1 20447.6 7536.4 7569.0 7573.2 7665.2 7759.5 4130.4 4270.4 4276.4 4323.9 4462.6 3265.8 3377.3 3378.8 3379.3 3379.4 591.0 599.9 605.5 612.1 619.4 626.4 636.0 644.8 654.7 666.8 8566.4 8868.8 8884.3 8988.2 9059.5 537.6 592.7 627.4 636.5 657.2 3716.7 3906.0 3945.0 4030.4 4163.7 88573.0 113195.9 115807.4 119329.8 122825.4 99640.0 100643.5 105173.0 105471.5 107138.0 86053.7 90216.0 90625.1 91135.5 94616.0 6567.0 7621.3 8373.5 8411.2 8426.4 41469.6 44599.6 51468.5 56334.4 56944.4 58830.4 79075.6 80537.0 80675.6 83485.6 11000.5 11963.5 12132.4 12175.1 12581.1 14080.3 14280.0 14570.8 14784.6 15057.5 12169.1 12649.0 12815.2 13070.7 13421.5 13142.7 13242.3 13296.1 13585.6 13672.5 11106.7 12410.3 13271.8 13290.3 13565.9 8467.4 10884.8 10988.8 11033.4 11741.2 10418.7 10843.9 11046.4 11224.7 11339.8 06:44:35 INFO - PROCESS | 1936 | -------- Summary: end -------- 06:44:35 INFO - PROCESS | 1936 | 06:44:36 INFO - TEST-INFO | 1936: exit 0 06:44:36 INFO - TEST-OK | dromaeo_css | took 1120514ms 06:44:36 INFO - TEST-START | kraken 06:44:36 INFO - operating with platform_type : mac_ 06:44:36 INFO - Initialising browser for kraken test... 06:44:36 INFO - TEST-INFO | started process 1939 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/T/tmpKBq2iH/profile http://localhost:50660/getInfo.html) 06:44:38 INFO - PROCESS | 1939 | __metrics Screen width/height:1600/1200 06:44:38 INFO - PROCESS | 1939 | colorDepth:24 06:44:38 INFO - PROCESS | 1939 | Browser inner width/height: 1024/689 06:44:38 INFO - PROCESS | 1939 | __metrics 06:44:38 INFO - PROCESS | 1939 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 06:44:38 INFO - PROCESS | 1939 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 06:44:39 INFO - TEST-INFO | 1939: exit 0 06:44:39 INFO - Browser initialized. 06:44:39 INFO - Running cycle 1/1 for kraken test... 06:44:39 INFO - TEST-INFO | started process 1940 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/T/tmpKBq2iH/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 06:44:52 INFO - PROCESS | 1940 | RSS: Main: 243830784 06:44:52 INFO - PROCESS | 1940 | 06:44:54 INFO - PROCESS | 1940 | Cycle 1(1): loaded http://localhost:50660/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:50660/tests/kraken/driver.html?testName=audio-beat-detection) 06:44:55 INFO - PROCESS | 1940 | RSS: Main: 293814272 06:44:55 INFO - PROCESS | 1940 | 06:44:58 INFO - PROCESS | 1940 | Cycle 1(1): loaded http://localhost:50660/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:50660/tests/kraken/driver.html?testName=audio-dft) 06:44:58 INFO - PROCESS | 1940 | RSS: Main: 296529920 06:44:58 INFO - PROCESS | 1940 | 06:45:02 INFO - PROCESS | 1940 | Cycle 1(1): loaded http://localhost:50660/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:50660/tests/kraken/driver.html?testName=audio-fft) 06:45:03 INFO - PROCESS | 1940 | RSS: Main: 384778240 06:45:03 INFO - PROCESS | 1940 | 06:45:04 INFO - PROCESS | 1940 | Cycle 1(1): loaded http://localhost:50660/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:50660/tests/kraken/driver.html?testName=audio-oscillator) 06:45:05 INFO - PROCESS | 1940 | RSS: Main: 287981568 06:45:05 INFO - PROCESS | 1940 | 06:45:07 INFO - PROCESS | 1940 | Cycle 1(1): loaded http://localhost:50660/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:50660/tests/kraken/driver.html?testName=imaging-gaussian-blur) 06:45:08 INFO - PROCESS | 1940 | RSS: Main: 292765696 06:45:08 INFO - PROCESS | 1940 | 06:45:10 INFO - PROCESS | 1940 | Cycle 1(1): loaded http://localhost:50660/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:50660/tests/kraken/driver.html?testName=imaging-darkroom) 06:45:11 INFO - PROCESS | 1940 | RSS: Main: 335024128 06:45:11 INFO - PROCESS | 1940 | 06:45:14 INFO - PROCESS | 1940 | Cycle 1(1): loaded http://localhost:50660/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:50660/tests/kraken/driver.html?testName=imaging-desaturate) 06:45:15 INFO - PROCESS | 1940 | RSS: Main: 335114240 06:45:15 INFO - PROCESS | 1940 | 06:45:18 INFO - PROCESS | 1940 | Cycle 1(1): loaded http://localhost:50660/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:50660/tests/kraken/driver.html?testName=json-parse-financial) 06:45:18 INFO - PROCESS | 1940 | RSS: Main: 332439552 06:45:18 INFO - PROCESS | 1940 | 06:45:20 INFO - PROCESS | 1940 | Cycle 1(1): loaded http://localhost:50660/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:50660/tests/kraken/driver.html?testName=json-stringify-tinderbox) 06:45:20 INFO - PROCESS | 1940 | RSS: Main: 309231616 06:45:20 INFO - PROCESS | 1940 | 06:45:22 INFO - PROCESS | 1940 | Cycle 1(1): loaded http://localhost:50660/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:50660/tests/kraken/driver.html?testName=stanford-crypto-aes) 06:45:23 INFO - PROCESS | 1940 | RSS: Main: 312528896 06:45:23 INFO - PROCESS | 1940 | 06:45:25 INFO - PROCESS | 1940 | Cycle 1(1): loaded http://localhost:50660/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:50660/tests/kraken/driver.html?testName=stanford-crypto-ccm) 06:45:26 INFO - PROCESS | 1940 | RSS: Main: 314351616 06:45:26 INFO - PROCESS | 1940 | 06:45:29 INFO - PROCESS | 1940 | Cycle 1(1): loaded http://localhost:50660/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:50660/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 06:45:30 INFO - PROCESS | 1940 | RSS: Main: 325873664 06:45:30 INFO - PROCESS | 1940 | 06:45:33 INFO - PROCESS | 1940 | Cycle 1(1): loaded http://localhost:50660/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:50660/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 06:45:33 INFO - PROCESS | 1940 | RSS: Main: 325873664 06:45:33 INFO - PROCESS | 1940 | 06:45:35 INFO - PROCESS | 1940 | Cycle 1(1): loaded http://localhost:50660/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:50660/tests/kraken/driver.html?testName=ai-astar) 06:45:35 INFO - PROCESS | 1940 | RSS: Main: 368963584 06:45:35 INFO - PROCESS | 1940 | 06:45:35 INFO - PROCESS | 1940 | __start_tp_report 06:45:35 INFO - PROCESS | 1940 | _x_x_mozilla_page_load 06:45:35 INFO - PROCESS | 1940 | _x_x_mozilla_page_load_details 06:45:35 INFO - PROCESS | 1940 | |i|pagename|runs| 06:45:35 INFO - PROCESS | 1940 | |0;ai-astar;112;110;112;112;112;112;112;114;112;112 06:45:35 INFO - PROCESS | 1940 | |1;audio-beat-detection;143;113;109;149;114;140;143;111;139;115 06:45:35 INFO - PROCESS | 1940 | |2;audio-dft;183;185;183;184;184;183;180;181;182;182 06:45:35 INFO - PROCESS | 1940 | |3;audio-fft;80;58;58;88;61;58;59;59;83;57 06:45:35 INFO - PROCESS | 1940 | |4;audio-oscillator;91;71;92;69;67;68;86;65;69;94 06:45:35 INFO - PROCESS | 1940 | |5;imaging-gaussian-blur;88;86;87;85;87;84;88;84;87;84 06:45:35 INFO - PROCESS | 1940 | |6;imaging-darkroom;93;94;96;94;93;94;94;95;92;94 06:45:35 INFO - PROCESS | 1940 | |7;imaging-desaturate;104;132;138;84;86;84;87;84;88;84 06:45:35 INFO - PROCESS | 1940 | |8;json-parse-financial;55;53;52;53;54;56;54;55;54;54 06:45:35 INFO - PROCESS | 1940 | |9;json-stringify-tinderbox;66;73;72;78;107;106;105;76;72;73 06:45:35 INFO - PROCESS | 1940 | |10;stanford-crypto-aes;88;89;88;88;89;87;98;107;108;107 06:45:35 INFO - PROCESS | 1940 | |11;stanford-crypto-ccm;150;150;148;148;150;148;155;166;201;148 06:45:35 INFO - PROCESS | 1940 | |12;stanford-crypto-pbkdf2;176;176;174;175;177;173;176;175;172;173 06:45:35 INFO - PROCESS | 1940 | |13;stanford-crypto-sha256-iterative;66;67;69;68;68;68;81;88;89;71 06:45:35 INFO - PROCESS | 1940 | __end_tp_report 06:45:35 INFO - PROCESS | 1940 | __start_cc_report 06:45:35 INFO - PROCESS | 1940 | _x_x_mozilla_cycle_collect,1935 06:45:35 INFO - PROCESS | 1940 | __end_cc_report 06:45:35 INFO - PROCESS | 1940 | __startTimestamp1479480335862__endTimestamp 06:45:35 INFO - PROCESS | 1940 | 06:45:35 INFO - PROCESS | 1940 | ------- Summary: start ------- 06:45:35 INFO - PROCESS | 1940 | Number of tests: 14 06:45:35 INFO - PROCESS | 1940 | 06:45:35 INFO - PROCESS | 1940 | [#0] ai-astar Cycles:10 Average:112.00 Median:112.00 stddev:0.94 (0.8%) stddev-sans-first:1.00 06:45:35 INFO - PROCESS | 1940 | Values: 112.0 110.0 112.0 112.0 112.0 112.0 112.0 114.0 112.0 112.0 06:45:35 INFO - PROCESS | 1940 | 06:45:35 INFO - PROCESS | 1940 | [#1] audio-beat-detection Cycles:10 Average:127.60 Median:139.50 stddev:16.31 (11.7%) stddev-sans-first:16.32 06:45:35 INFO - PROCESS | 1940 | Values: 143.0 113.0 109.0 149.0 114.0 140.0 143.0 111.0 139.0 115.0 06:45:35 INFO - PROCESS | 1940 | 06:45:35 INFO - PROCESS | 1940 | [#2] audio-dft Cycles:10 Average:182.70 Median:183.00 stddev:1.49 (0.8%) stddev-sans-first:1.58 06:45:35 INFO - PROCESS | 1940 | Values: 183.0 185.0 183.0 184.0 184.0 183.0 180.0 181.0 182.0 182.0 06:45:35 INFO - PROCESS | 1940 | 06:45:35 INFO - PROCESS | 1940 | [#3] audio-fft Cycles:10 Average:66.10 Median:60.00 stddev:12.31 (20.5%) stddev-sans-first:11.99 06:45:35 INFO - PROCESS | 1940 | Values: 80.0 58.0 58.0 88.0 61.0 58.0 59.0 59.0 83.0 57.0 06:45:35 INFO - PROCESS | 1940 | 06:45:35 INFO - PROCESS | 1940 | [#4] audio-oscillator Cycles:10 Average:77.20 Median:78.50 stddev:11.92 (15.2%) stddev-sans-first:11.55 06:45:35 INFO - PROCESS | 1940 | Values: 91.0 71.0 92.0 69.0 67.0 68.0 86.0 65.0 69.0 94.0 06:45:35 INFO - PROCESS | 1940 | 06:45:35 INFO - PROCESS | 1940 | [#5] imaging-gaussian-blur Cycles:10 Average:86.00 Median:87.00 stddev:1.63 (1.9%) stddev-sans-first:1.56 06:45:35 INFO - PROCESS | 1940 | Values: 88.0 86.0 87.0 85.0 87.0 84.0 88.0 84.0 87.0 84.0 06:45:35 INFO - PROCESS | 1940 | 06:45:35 INFO - PROCESS | 1940 | [#6] imaging-darkroom Cycles:10 Average:93.90 Median:94.00 stddev:1.10 (1.2%) stddev-sans-first:1.12 06:45:35 INFO - PROCESS | 1940 | Values: 93.0 94.0 96.0 94.0 93.0 94.0 94.0 95.0 92.0 94.0 06:45:35 INFO - PROCESS | 1940 | 06:45:35 INFO - PROCESS | 1940 | [#7] imaging-desaturate Cycles:10 Average:97.10 Median:84.00 stddev:20.90 (24.9%) stddev-sans-first:22.02 06:45:35 INFO - PROCESS | 1940 | Values: 104.0 132.0 138.0 84.0 86.0 84.0 87.0 84.0 88.0 84.0 06:45:35 INFO - PROCESS | 1940 | 06:45:35 INFO - PROCESS | 1940 | [#8] json-parse-financial Cycles:10 Average:54.00 Median:54.00 stddev:1.15 (2.1%) stddev-sans-first:1.17 06:45:35 INFO - PROCESS | 1940 | Values: 55.0 53.0 52.0 53.0 54.0 56.0 54.0 55.0 54.0 54.0 06:45:35 INFO - PROCESS | 1940 | 06:45:35 INFO - PROCESS | 1940 | [#9] json-stringify-tinderbox Cycles:10 Average:82.80 Median:72.50 stddev:16.31 (22.5%) stddev-sans-first:16.12 06:45:35 INFO - PROCESS | 1940 | Values: 66.0 73.0 72.0 78.0 107.0 106.0 105.0 76.0 72.0 73.0 06:45:35 INFO - PROCESS | 1940 | 06:45:35 INFO - PROCESS | 1940 | [#10] stanford-crypto-aes Cycles:10 Average:94.90 Median:88.00 stddev:9.12 (10.4%) stddev-sans-first:9.33 06:45:35 INFO - PROCESS | 1940 | Values: 88.0 89.0 88.0 88.0 89.0 87.0 98.0 107.0 108.0 107.0 06:45:35 INFO - PROCESS | 1940 | 06:45:35 INFO - PROCESS | 1940 | [#11] stanford-crypto-ccm Cycles:10 Average:156.40 Median:150.00 stddev:16.63 (11.1%) stddev-sans-first:17.47 06:45:35 INFO - PROCESS | 1940 | Values: 150.0 150.0 148.0 148.0 150.0 148.0 155.0 166.0 201.0 148.0 06:45:35 INFO - PROCESS | 1940 | 06:45:35 INFO - PROCESS | 1940 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:174.70 Median:175.50 stddev:1.64 (0.9%) stddev-sans-first:1.67 06:45:35 INFO - PROCESS | 1940 | Values: 176.0 176.0 174.0 175.0 177.0 173.0 176.0 175.0 172.0 173.0 06:45:35 INFO - PROCESS | 1940 | 06:45:35 INFO - PROCESS | 1940 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:73.50 Median:70.00 stddev:8.96 (12.8%) stddev-sans-first:9.08 06:45:35 INFO - PROCESS | 1940 | Values: 66.0 67.0 69.0 68.0 68.0 68.0 81.0 88.0 89.0 71.0 06:45:35 INFO - PROCESS | 1940 | -------- Summary: end -------- 06:45:35 INFO - PROCESS | 1940 | 06:45:36 INFO - TEST-INFO | 1940: exit 0 06:45:36 INFO - TEST-OK | kraken | took 60872ms 06:45:36 INFO - SUITE-END | took 1181s 06:45:37 INFO - Completed test suite (00:19:41) 06:45:37 INFO - javascript benchmark 06:45:37 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2704.3104008477835, 2833.9008134715286, 3262.738980881531, 3299.3566254580364, 3308.9503657445157, 5494.478024587668, 5528.917066243999, 5552.583556233283, 5574.219609254703, 5615.887682246353, 5556.277683901098, 5568.972155139219, 5580.944190558103, 5591.329040515144, 5604.467575580314, 5038.647294689373, 5080.542751152396, 5084.034033533617, 5106.285120083191, 5165.225216217578, 5076.796928122871, 5084.389873215219, 5184.818531351403, 5190.455002224769, 5202.4537423570355, 5084.822031228908, 5109.3613298337705, 5115.360579927509, 5119.385673719159, 5149.098907691157, 5135.948640513603, 5142.948570514322, 5149.356330458692, 5152.433232344439, 5220.269162317279, 4965.702057876539, 4976.950230497721, 5025.14572522672, 5110.693358398508, 5123.2315152727015, 5411.377691565481, 5448.046591846411, 5471.72641367934, 5503.394626591072, 5512.641678290898, 5972.522198224132, 5992.981193197167, 5994.880102397928, 6008.399160084, 6016.759329626853, 5983.072623743326, 6002.189704389895, 6107.633541987495, 6115.327313995457, 6147.938520614782, 5613.410591887834, 5619.971900140514, 5621.550275977994, 5622.57830662702, 5624.3813180550105, 6114.419130182626, 6157.322694503601, 6164.0, 6167.475764560063, 6169.84575385619, 5829.7376618052285, 5867.119932010232, 5883.382244864228, 5889.4699477046415, 5896.941030589725, 6029.758809647565, 6054.909176362358, 6061.818145455643, 6065.0, 6080.908786368208, 5958.225430693982, 5978.940210597925, 6004.42957918997, 6014.969925150346, 6015.578909476295, 6015.2781666200335, 6030.366811484819, 6030.547708921761, 6033.728482218311, 6052.182955300978, 4786.856394308176, 4788.233882578771, 4806.903861922742, 4835.782389792468, 4863.537963893425, 3890.105275786528, 4336.82652693895, 4382.23310920594, 4413.0, 4415.558444155558, 4598.28726547379, 5432.185172224198, 5500.1749737538885, 5506.311711036121, 5568.916466253009, 6133.447989680861, 6156.5998210116195, 6194.473469755031, 6200.100985357189, 6211.068339748984, 5596.412376700469, 5613.438656134354, 5613.607047506635, 5645.2096706461125, 5655.491005809415, 5132.28148059272, 5145.048166089286, 5158.4067832199025, 5167.9224811627855, 5282.867928301823, 6117.1130186123655, 6136.028952951454, 6137.560853825989, 6139.969300153471, 6156.015037594143, 5919.082542205965, 5926.4666180044005, 5926.525877929842, 5946.613470124428, 5986.461218490357, 5595.517187945279, 6082.482988945812, 6118.235220597426, 6127.988881834447, 6140.815775526741, 5887.793927212527, 5929.0217114175675, 5949.702514874152, 6007.12896629995, 6072.514198864169, 6070.271567411939, 6070.362611925684, 6076.270847498152, 6081.6655083969035, 6112.908306375319, 5674.517665998436, 5899.026660600953, 5912.320083190488, 5923.49650279731, 5929.584929054925, 5787.971060144672, 5830.679312637845, 5838.357780644041, 5841.007028805198, 5848.4151584841175, 5677.091665333527, 5705.515031222392, 5735.426457354231, 5746.109353050284, 5755.683437410982, 5789.884202316014, 5801.506871915934, 5804.419558044331, 5809.709514524172, 5818.330891947311, 5704.030314846569, 5713.8285851424525, 5726.284214473191, 5727.11229759388, 5729.8567535811935], "value": 5518.6243573990005, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [444.903313332635, 472.95506926841983, 474.3738265489553, 500.29708259894835, 515.2708916882611, 29759.85120074402, 31129.688703112963, 37834.05414864626, 38426.80786596063, 38603.806980965055, 39893.60106398935, 40252.19495610093, 40404.0, 40457.98855028626, 40651.59348406515, 9193.17261446468, 9372.578233979453, 9382.343235973485, 9389.62441502339, 9393.85909211359, 9315.534223288842, 9410.52947352633, 9414.29392795543, 9433.481158536279, 9436.81126377472, 9385.483798391086, 9433.622655093788, 9451.149396554309, 9454.669086581971, 9455.338126331126, 9354.0, 9412.15290623847, 9418.152366286999, 9424.858627120599, 9440.055994400574, 9119.361644684876, 9136.725898223063, 9168.129027742421, 9202.861957070649, 9259.6296148154, 38184.66353677608, 38636.61363386386, 38796.0, 38797.418038729156, 38816.22367552633, 2810.9437811243865, 3229.3694309474613, 3232.595925509311, 3453.792772433637, 3599.460080987873, 28146.85926570354, 29277.85361073181, 30100.548491772643, 30134.54798178029, 30378.392432151453, 2701.9864900675566, 3094.257378229231, 3134.279115803355, 3287.1289108386295, 3290.128116049249, 382778.34443311254, 451744.0, 473487.0, 477360.6131969353, 490251.0, 59490.405095949354, 59569.106463403084, 59898.40101599015, 59948.0, 60036.799264015346, 61918.69040654768, 61921.69039154775, 62056.44858878474, 62137.75724485485, 62154.68922655358, 15387.769183462256, 15629.68740625181, 15633.687326253412, 15643.765343519857, 15651.921740391454, 11770.352630605228, 11820.113491488173, 11853.46659400329, 11864.88135118655, 11867.465964031466, 14236.572902812932, 14457.855421445862, 18067.186976586087, 18088.00515971608, 18095.466634671156, 5830.504407125357, 7401.111866576045, 7545.434092443089, 7546.622668866645, 7647.923520764833, 757.8408534207767, 784.3764207455087, 885.9645614175361, 1007.37039350406, 1049.375621505203, 27599.0, 27928.441431171264, 28924.855375723408, 29560.704392956224, 29725.40549189004, 1013.7719013222113, 1021.9897801021895, 1023.99488002561, 1062.6121465664976, 1098.9395233599614, 13542.729145417234, 14802.62993425173, 14869.776953345709, 15252.542423727306, 15349.386024558893, 656356.0, 657900.0, 659852.0, 661343.6932815305, 663971.0, 56709.0, 56965.0, 57008.14487782687, 57038.14442783361, 57096.0, 1207.2334067866968, 1238.1889862140313, 1324.9867501324863, 1359.3067535556936, 1376.9741542550864, 24499.75500245046, 30089.247768805955, 30287.697123028487, 30861.0, 30881.382372351978, 47555.811104722656, 48026.27960580524, 48077.51922480731, 48127.75936120437, 48158.518414816805, 507220.0, 508106.0, 508844.0, 511736.0, 630123.0, 37851.432228516045, 39292.60707392889, 39312.2137557253, 39695.603043970346, 40115.79942100271, 43744.0, 44335.77832110819, 44579.777101115586, 44641.33038004368, 44894.3265851006, 35973.64026359703, 36574.49063243523, 36719.0, 37247.81376093102, 37390.62609373871, 36504.81747591245, 36509.0, 37224.0, 37375.62624373721, 37635.24729505449, 35625.643743562236, 35765.642343577274, 36513.81743091374, 36681.44977825388, 36745.265094698494, 31887.521687175176, 33372.83313583417, 33472.0, 33647.831760841036, 33873.661263388036, 24555.50888982246, 24648.876755616107, 24855.502889941738, 24943.0, 25359.492810143325], "value": 19377.60362298535, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [343.1181862613084, 347.7426704238864, 370.34264181078584, 372.08281585890774, 396.95831937646545, 14793.482228122019, 14918.627034324147, 15789.289481973296, 20118.195272189096, 20676.48308792277, 18755.624887502243, 19327.226910923546, 19710.802891971078, 19828.30600928964, 20093.698594521094, 5598.384177740456, 5866.20806191163, 5867.88264234715, 5868.0, 5869.471747542721, 5226.137687281594, 5533.335999680044, 5812.941870581303, 5866.325372582143, 5882.14708867214, 5768.3654797972185, 5814.156947242647, 5823.388544202861, 5826.650400975934, 5826.9708651456685, 5529.419410961851, 5709.771609135651, 5737.053386191274, 5763.077907534816, 5767.971160144214, 4871.318015477836, 4962.5533701967, 5035.697858128524, 5046.343975283226, 5059.569936555397, 24260.757392426025, 24312.513749725083, 24350.0, 24613.63079553808, 24797.50404991908, 2266.4787098967163, 2360.480694247263, 2370.182287110944, 2415.263344679872, 2432.1852179519883, 14360.856391436057, 15281.46514871974, 15537.378504859793, 15677.651323804654, 15913.204339782964, 1801.783785945695, 1908.9713654295197, 1910.436421255733, 1931.3723040011912, 1940.9514762130918, 102009.9799002008, 108096.4595177027, 123427.0, 124251.0, 124519.0, 10702.197335199891, 10947.178961577914, 11074.39090850011, 11077.612283570037, 11307.0, 14029.0, 14059.78910316346, 14062.367193476113, 14064.578062658136, 14089.436422542984, 8022.839543209103, 8168.0, 8192.83614327722, 8278.461899976603, 8293.004839419187, 6859.416949559343, 7900.407469439815, 8066.475679080841, 8089.676412943534, 8218.342532597379, 3805.1058001369656, 3820.3505404081375, 4223.036288126036, 4448.332750087513, 4538.659600529907, 1784.3754685859844, 1890.1588792986988, 1891.527118220445, 1895.3176856331709, 1916.3292847503262, 835.4611275727243, 857.0, 862.6894318045498, 872.2934423117168, 925.3291363761219, 19035.68879728112, 20553.897230514052, 20562.97185140737, 20626.381208563766, 20875.895620521802, 888.7689200807707, 897.6409436225562, 907.5462268865567, 908.4957848394205, 952.319091849331, 8073.515589064675, 10570.841437378445, 10791.59709237794, 11198.992090711712, 11206.935341142578, 560174.0, 577702.0, 579512.0, 594096.0, 597354.0, 89833.0, 90586.54706726424, 120426.0, 124161.0, 126639.0, 974.70758772368, 981.8379967305458, 1031.4430207687915, 1031.5461197073414, 1031.9432431216355, 18401.0, 18538.536536586693, 18921.90539047296, 19189.61620767605, 19208.231670733014, 42516.724498263866, 50512.23832929934, 52601.0, 52671.4694957024, 52723.0, 58603.82792344044, 75355.62322188573, 75701.62149189219, 78835.60582197053, 79081.0, 7530.811729706801, 7719.961400192963, 7787.98756161718, 7891.487053341514, 7918.524888506708, 9342.439453632804, 11971.580994665144, 12288.1398302118, 12389.814152787896, 12436.502539898303, 9698.75753106178, 10875.401852898167, 10914.345139291669, 10920.0, 10932.672019839418, 8916.242119419921, 10036.146927510952, 10451.477426128511, 10635.096016838655, 10838.729031774268, 9151.405158664666, 10700.946495267473, 11091.611793587186, 11140.220184587, 11295.491702873225, 7678.961605191938, 7836.647350869339, 7845.803854903673, 7869.960650196713, 8022.83954320922, 9375.531223438664, 10528.473576321307, 10814.675559733221, 10941.45292735376, 11021.44892755375], "value": 9280.247164813207, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1327.7610030194564, 1435.8277006759188, 1563.6169138561054, 1801.8018018018024, 1847.344192811552, 7823.9217607823975, 8296.336293096552, 8304.95847520763, 8313.127121652231, 8317.085120636724, 7347.26527347265, 7524.134724506684, 7535.6232188390495, 7813.218678132184, 8124.431289809716, 2216.9889150554222, 2288.530851175505, 2290.438842483594, 2291.0, 2291.9312420627407, 2280.076568989562, 2312.6646636237733, 2323.779240972105, 2328.0105954969154, 2329.9184528541505, 2263.1173842201474, 2264.071730590458, 2287.0623044551735, 2294.4263934016494, 2297.4830663100834, 2262.1290803040865, 2271.3186044186677, 2274.9886250568807, 2279.783420575051, 2280.258915852338, 2256.199049337494, 2286.27982185611, 2286.9303280531144, 2288.9084436622516, 2295.047555264563, 6967.756128535528, 8118.594070296462, 8152.5516096614665, 8210.958945205235, 8250.174982501761, 4178.707490475668, 5151.922721159186, 5162.967406518674, 5169.405518365361, 5169.457206993249, 3251.138448311199, 3305.3719793239206, 3490.0925759302763, 3583.62371950944, 3584.766990145632, 1047.0471870597719, 1070.2347821307724, 1087.5486673030682, 1089.6676513663328, 1097.3470784882982, 10238.590456381737, 10575.629852955186, 10621.468926553718, 10783.299085559414, 10913.454327283684, 4183.142455796555, 4288.270993931039, 4376.811594202899, 4415.315626077963, 4464.2187617166865, 8557.700480483278, 8566.057733649293, 8567.957160214159, 8572.057073721886, 8581.528015959182, 5028.79884804611, 5036.169032109661, 5037.0681423936685, 5040.344755181803, 5048.823291184791, 3848.7299191266816, 4176.869796403796, 4570.13167498181, 4739.4075740532435, 4750.904981900343, 3520.0, 3536.964630353715, 3595.658412450813, 3612.2414292998237, 3643.0710168906758, 951.7906060666643, 1120.9439528023565, 1123.4663534820895, 1125.3248051169232, 1129.5538262386476, 742.7920182348951, 784.854801861646, 820.4420993724325, 882.8808110905074, 901.4230892228848, 5396.006954853458, 5426.051228864532, 5710.143478478179, 5721.227634269404, 5854.5901786875365, 433.4040694045687, 923.2429407885469, 942.1332374215738, 989.6981420666622, 1036.9377837329785, 3651.4887915691825, 3755.887323380303, 3841.8847434577006, 3879.1853710721066, 3925.509311336083, 8106.351491880635, 9698.418094914186, 9802.578626099175, 10117.190624750003, 10164.33931794431, 9639.759006024766, 9669.033096690275, 9997.950010249902, 10044.899551004542, 10072.496375181212, 7641.0, 7642.388608911274, 7675.577843218565, 7834.608269586498, 7849.764507064797, 5470.124780035176, 5544.63959842609, 5568.749406276808, 5604.551635869039, 5618.410066943075, 9337.299702522203, 9367.110124538158, 9374.437533747998, 9378.249740020645, 9390.389624674373, 9187.127222913812, 9362.29782766309, 9404.905950940403, 9639.0, 9643.807123857343, 6627.900581491378, 6688.7324507019175, 6739.730410783513, 6771.89842152378, 6802.591844489347, 7729.227077292226, 7808.0, 7819.8436031277915, 7821.843563128819, 7832.686692532235, 6858.725650973905, 7062.929370706433, 7066.152061752417, 7129.714811407485, 7186.993820865187, 6633.800985970429, 6662.900056499059, 6894.758683446055, 6901.378875901193, 6904.0, 6273.96863015682, 6450.225972883284, 6528.151340325728, 6553.377429144223, 6657.633830139389, 4330.805113769951, 4672.696274742267, 4705.694129881546, 4718.197906355859, 4770.666053376231, 4769.689970152068, 4809.759512024455, 4850.272459131159, 4896.094222568836, 5046.268291097844], "value": 4301.435570835319, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [294.73179406739865, 296.5033568772307, 306.78831606191727, 312.8826689991253, 313.9905802825916, 4992.725400102993, 5074.995376826156, 5148.910653607833, 5166.715830629319, 5173.405058418283, 4292.720973136744, 4763.476017638057, 5024.974875125628, 5071.728734965945, 5081.002208696868, 3271.9018429447037, 3464.8094354810473, 3658.5243918290585, 3771.4531392948143, 4015.237104950065, 2703.6079768433565, 4002.919941601166, 4011.9598804012026, 4017.1363156921257, 4036.4550785643855, 3535.92928141437, 3898.961010389888, 3915.4956314098863, 3937.370020796673, 3940.9802950985204, 3066.9386612267854, 3227.193201699575, 3416.248425346432, 3704.0, 3927.528696556403, 3277.4428347181038, 3399.2351720862857, 3414.334204830059, 3420.9315813683834, 3436.862525498977, 3787.8295476703615, 4345.956540434587, 4908.975455122737, 5034.79860805571, 5056.637336171441, 3927.7627547322786, 4473.597376236155, 4474.574915383034, 4490.955090449086, 4503.527129651405, 4036.4147198656324, 4510.684252102355, 4556.270996640522, 4683.96952670412, 5465.857132148991, 3664.8900532983785, 3718.2563487302646, 3736.4768932349493, 3747.9812600937094, 3752.343339915531, 4666.090112428078, 5076.36545431821, 5223.869403264911, 5664.49019588239, 5689.544836413077, 3777.905552361158, 3788.8105594720155, 3796.563395209531, 3796.6013568575454, 3813.161104556993, 3199.5360672702327, 3272.887218345774, 3602.6217247189193, 3616.782993020427, 3835.2137811748526, 4064.6748260139116, 4429.9778501107285, 4447.2439685253585, 4475.955240447619, 4492.932606010912, 4455.977720111444, 5381.623286369995, 5401.432849550819, 5426.0, 5426.6975925778315, 1032.488917985602, 1150.465033759306, 1195.8041958041958, 1205.8311836342918, 1219.4634360881184, 826.6445428465818, 848.2238751542393, 859.6991053131479, 863.6545381847311, 864.8972559985942, 4851.927221091686, 4853.684510506806, 4883.145449546315, 4892.755362231946, 4900.509949005071, 4318.784060796947, 5182.53357197854, 5250.264962905197, 5278.894422111536, 5291.814786482455, 3940.4483372327895, 4134.945588874818, 4624.72819974503, 4763.595094416944, 4823.614110871122, 1987.5826076524093, 2531.810049276837, 2654.761071503574, 2797.7062408447227, 2816.788740844445, 4317.129109142448, 4693.319468677023, 5381.919271210857, 5512.6968016759465, 5545.972270138624, 4625.8843528911375, 4927.77824997876, 5057.747112644427, 5389.784408623689, 5493.642913210629, 4661.627069834405, 5396.568274538027, 5875.50058245054, 5882.205902203319, 5904.110684580893, 5670.262865827416, 5676.5742569308295, 5724.4561766632105, 5736.110902810071, 5739.139129130664, 4648.0, 4733.0, 5251.36983561975, 5623.325200975909, 5647.661140331593, 5899.587028907935, 5913.34953155144, 5913.881722365614, 5914.835562973788, 5919.7040147991565, 3827.61723827615, 3848.9615103848605, 3854.1713531590844, 3854.325493038651, 3869.9806500967316, 5306.363236411656, 5337.839864804062, 5374.838754837361, 5530.695811730394, 5653.745581448762, 4706.341112244357, 4796.616270698266, 5078.577998160521, 5339.036834556859, 5429.592780541554, 4421.607193733964, 4488.630967554927, 4725.385699858996, 5400.918986215289, 5561.4716601922755, 5248.475152484873, 5384.273123128484, 5434.3207099112615, 5435.429279925709, 5444.455554444524, 4783.617310615073, 5511.319047690493, 5529.502344788988, 5548.472895074961, 5576.228603395466, 4438.60052595268, 4504.355910092858, 5253.921191182212, 5408.5673146149, 5412.594055445936], "value": 3867.081437027504, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [672.253798283905, 706.6043015911087, 717.175248464266, 756.5536333563197, 769.8344855855993, 13266.734665306689, 14212.289385530708, 14227.075240109398, 14250.572482825532, 14803.703925921474, 13331.666708332297, 13489.460421583124, 13512.527061552848, 13515.0, 13531.391087401065, 640.4300172846171, 660.8050625065617, 663.9668016599151, 806.2904643913348, 818.979525511861, 660.4848218389664, 664.362212276213, 664.5912763650368, 789.4592204340046, 818.5416166946529, 859.4113032572676, 859.9269062129695, 867.2974890338814, 867.6876324523198, 872.8036191856845, 830.4560512864053, 864.6411739128317, 865.8614621660568, 865.9350548708809, 869.7521206456162, 556.2351766321308, 650.3983814971133, 650.4503694378243, 650.6161364794747, 651.9991812567686, 11100.0, 11583.131265155147, 13438.193708377528, 13877.653058673613, 13933.58199254024, 13247.271400072992, 13445.0, 13573.0, 13714.108582942077, 13726.176429414267, 9275.953620231923, 11096.1123110151, 11172.720681983015, 11221.719457013558, 11624.011958983496, 3607.837647305851, 3624.4744512045604, 3652.8356223969986, 3655.9268814623824, 3688.5942546319884, 76360.61819690921, 83328.75006874841, 84860.57569712173, 86807.0, 86874.0, 10117.0, 10284.0, 10492.21308401873, 10501.632442864464, 10796.67609971702, 19974.30089946845, 20216.39350819478, 20363.694544581842, 20443.08006139727, 20447.591048178954, 7536.359409450258, 7569.0, 7573.242675732383, 7665.23347665229, 7759.495632783851, 4130.359794231899, 4270.444842170498, 4276.379924910871, 4323.913521729611, 4462.553744625511, 3265.771396002257, 3377.273886114497, 3378.831058447068, 3379.259681264151, 3379.377898608682, 590.9970450147722, 599.946004859556, 605.5246631394334, 612.1429998002743, 619.3806193806194, 626.3517259636254, 635.9936400636027, 644.8323435906605, 654.6726636681659, 666.7525288050897, 8566.357523185787, 8868.822623547492, 8884.334699590097, 8988.235999940078, 9059.456432614064, 537.6397813464988, 592.6799528254694, 627.419636835934, 636.4590098416309, 657.2441692054081, 3716.7212459065136, 3906.0430194601936, 3944.954587034438, 4030.3694753956906, 4163.708540402143, 88573.0, 113195.86804131854, 115807.40392924736, 119329.8402095877, 122825.38587307006, 99640.00359996452, 100643.49678251562, 105173.0, 105471.47264263629, 107138.0, 86053.70919436213, 90216.0, 90625.09374906166, 91135.5443222793, 94616.0, 6566.967165164144, 7621.264268204984, 8373.539455330016, 8411.200935911078, 8426.368022398223, 41469.585304146574, 44599.55400445954, 51468.48531514637, 56334.43665563292, 56944.430555693914, 58830.411695882496, 79075.60462197653, 80537.0, 80675.59662201651, 83485.58257208676, 11000.504977276201, 11963.461644225858, 12132.393380330772, 12175.14773965814, 12581.119321647397, 14080.295985200493, 14280.0, 14570.781438278222, 14784.63038424048, 15057.54827355181, 12169.087318450975, 12649.0, 12815.16701414405, 13070.692930706853, 13421.463141474233, 13142.737145257233, 13242.337883105614, 13296.069275151034, 13585.59243222705, 13672.453101875813, 11106.666799996014, 12410.255384676948, 13271.800922985969, 13290.269035203157, 13565.864341356855, 8467.449615774955, 10884.836727449254, 10988.835167472655, 11033.393163376091, 11741.236819606698, 10418.739531511774, 10843.945780271048, 11046.392448415092, 11224.719382015515, 11339.773204536028], "value": 7137.575404998388, "unit": "score"}], "name": "dromaeo_css", "value": 7001.8793808649525, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 110.0, 112.0, 112.0, 112.0, 112.0, 112.0, 114.0, 112.0, 112.0], "value": 112.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 113.0, 109.0, 149.0, 114.0, 140.0, 143.0, 111.0, 139.0, 115.0], "value": 127.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [183.0, 185.0, 183.0, 184.0, 184.0, 183.0, 180.0, 181.0, 182.0, 182.0], "value": 182.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 58.0, 58.0, 88.0, 61.0, 58.0, 59.0, 59.0, 83.0, 57.0], "value": 66.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 71.0, 92.0, 69.0, 67.0, 68.0, 86.0, 65.0, 69.0, 94.0], "value": 77.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 86.0, 87.0, 85.0, 87.0, 84.0, 88.0, 84.0, 87.0, 84.0], "value": 86.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 94.0, 96.0, 94.0, 93.0, 94.0, 94.0, 95.0, 92.0, 94.0], "value": 93.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 132.0, 138.0, 84.0, 86.0, 84.0, 87.0, 84.0, 88.0, 84.0], "value": 97.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 53.0, 52.0, 53.0, 54.0, 56.0, 54.0, 55.0, 54.0, 54.0], "value": 54.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 73.0, 72.0, 78.0, 107.0, 106.0, 105.0, 76.0, 72.0, 73.0], "value": 82.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 89.0, 88.0, 88.0, 89.0, 87.0, 98.0, 107.0, 108.0, 107.0], "value": 94.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 150.0, 148.0, 148.0, 150.0, 148.0, 155.0, 166.0, 201.0, 148.0], "value": 156.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [176.0, 176.0, 174.0, 175.0, 177.0, 173.0, 176.0, 175.0, 172.0, 173.0], "value": 174.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 67.0, 69.0, 68.0, 68.0, 68.0, 81.0, 88.0, 89.0, 71.0], "value": 73.5, "unit": "score"}], "name": "kraken", "value": 1478.9, "alertThreshold": 2.0}]} 06:45:37 INFO - Return code: 0 06:45:37 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 06:45:37 INFO - # TBPL SUCCESS # 06:45:37 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 06:45:37 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 06:45:37 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 06:45:37 INFO - 2016-11-18 06:45:37.205 screenresolution[1944:65377] starting screenresolution argv=screenresolution get 06:45:37 INFO - 2016-11-18 06:45:37.208 screenresolution[1944:65377] Display 0: 1600x1200x32@60 06:45:37 INFO - 2016-11-18 06:45:37.214 screenresolution[1945:65380] starting screenresolution argv=screenresolution list 06:45:37 INFO - Available Modes on Display 0 06:45:37 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 06:45:37 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 06:45:37 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 06:45:37 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 06:45:37 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 06:45:37 INFO - Intel Iris: 06:45:37 INFO - Chipset Model: Intel Iris 06:45:37 INFO - Type: GPU 06:45:37 INFO - Bus: Built-In 06:45:37 INFO - VRAM (Dynamic, Max): 1536 MB 06:45:37 INFO - Vendor: Intel (0x8086) 06:45:37 INFO - Device ID: 0x0a2e 06:45:37 INFO - Revision ID: 0x0009 06:45:37 INFO - Displays: 06:45:37 INFO - Display: 06:45:37 INFO - Resolution: 1600 x 1200 @ 60 Hz 06:45:37 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 06:45:37 INFO - Display Serial Number: 1600x1200 60 06:45:37 INFO - Main Display: Yes 06:45:37 INFO - Mirror: Off 06:45:37 INFO - Online: Yes 06:45:37 INFO - Rotation: Supported 06:45:37 INFO - Return code: 0 06:45:37 INFO - Running post-action listener: _resource_record_post_action 06:45:37 INFO - [mozharness: 2016-11-18 14:45:37.459018Z] Finished run-tests step (success) 06:45:37 INFO - Running post-run listener: _resource_record_post_run 06:45:37 INFO - Total resource usage - Wall time: 1214s; CPU: 53.0%; Read bytes: 1003520; Write bytes: 688027136; Read time: 6; Write time: 2419 06:45:37 INFO - TinderboxPrint: CPU usage
53.4% 06:45:37 INFO - TinderboxPrint: I/O read bytes / time
1,003,520 / 6 06:45:37 INFO - TinderboxPrint: I/O write bytes / time
688,027,136 / 2,419 06:45:37 INFO - TinderboxPrint: CPU idle
2,261.6 (46.6%) 06:45:37 INFO - TinderboxPrint: CPU system
968.3 (19.9%) 06:45:37 INFO - TinderboxPrint: CPU user
1,627.8 (33.5%) 06:45:37 INFO - TinderboxPrint: Swap in / out
383,639,552 / 0 06:45:37 INFO - install - Wall time: 33s; CPU: 54.0%; Read bytes: 367696384; Write bytes: 366508032; Read time: 27523; Write time: 680 06:45:37 INFO - run-tests - Wall time: 1183s; CPU: 53.0%; Read bytes: 1003520; Write bytes: 302730752; Read time: 6; Write time: 1697 06:45:37 INFO - Running post-run listener: _upload_blobber_files 06:45:37 INFO - Blob upload gear active. 06:45:37 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 06:45:37 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 06:45:37 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', 'Cedar', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 06:45:37 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 Cedar -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 06:45:38 INFO - (blobuploader) - INFO - Open directory for files ... 06:45:38 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 06:45:38 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:45:38 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:45:39 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 06:45:39 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:45:39 INFO - (blobuploader) - INFO - Done attempting. 06:45:39 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 06:45:39 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:45:39 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:45:40 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 06:45:40 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:45:40 INFO - (blobuploader) - INFO - Done attempting. 06:45:40 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 06:45:40 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:45:40 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:45:43 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 06:45:43 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:45:43 INFO - (blobuploader) - INFO - Done attempting. 06:45:43 INFO - (blobuploader) - INFO - Iteration through files over. 06:45:43 INFO - Return code: 0 06:45:43 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 06:45:43 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 06:45:43 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/e2596da1f002ea893fcbc8d0422962632b0f2ca4ef9b015282503f0ba42b931c91de2d75cd2a5f26e18f4dbaaca9f5101a02d8fe0f9eb71edca1c9fe1a9ee11d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/c5b2adc65656d7466c41505c66d81eb1edf2833ff4444454d6d24b6e61106acb10ec1d25546944fc2a5ba740ad78e0afced14d5deab34b7d575126cb634c5368", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/e090bc540b73cd1922b5fd4c69b78980e85881169f90cc8dceb28ce676ac25ee166c76e24036afb4e3136ab01a5584a173a230f2ccd0084a92c61cab2887d69c"} 06:45:43 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 06:45:43 INFO - Writing to file /builds/slave/test/properties/blobber_files 06:45:43 INFO - Contents: 06:45:43 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/e2596da1f002ea893fcbc8d0422962632b0f2ca4ef9b015282503f0ba42b931c91de2d75cd2a5f26e18f4dbaaca9f5101a02d8fe0f9eb71edca1c9fe1a9ee11d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/c5b2adc65656d7466c41505c66d81eb1edf2833ff4444454d6d24b6e61106acb10ec1d25546944fc2a5ba740ad78e0afced14d5deab34b7d575126cb634c5368", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/e090bc540b73cd1922b5fd4c69b78980e85881169f90cc8dceb28ce676ac25ee166c76e24036afb4e3136ab01a5584a173a230f2ccd0084a92c61cab2887d69c"} 06:45:43 INFO - Running post-run listener: copy_logs_to_upload_dir 06:45:43 INFO - Copying logs to upload dir... 06:45:43 INFO - mkdir: /builds/slave/test/build/upload/logs 06:45:43 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1277.066093 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 17 secs) (at 2016-11-18 06:45:43.127950) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-18 06:45:43.131889) ========= 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.NpuyIQgCKm/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.EIhk0nCU2G/Listeners TMPDIR=/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/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/Cedar/sha512/e2596da1f002ea893fcbc8d0422962632b0f2ca4ef9b015282503f0ba42b931c91de2d75cd2a5f26e18f4dbaaca9f5101a02d8fe0f9eb71edca1c9fe1a9ee11d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/c5b2adc65656d7466c41505c66d81eb1edf2833ff4444454d6d24b6e61106acb10ec1d25546944fc2a5ba740ad78e0afced14d5deab34b7d575126cb634c5368", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/e090bc540b73cd1922b5fd4c69b78980e85881169f90cc8dceb28ce676ac25ee166c76e24036afb4e3136ab01a5584a173a230f2ccd0084a92c61cab2887d69c"} build_url:https://queue.taskcluster.net/v1/task/Bjl2DGrTS3yeT88IzDPm3Q/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011044 build_url: 'https://queue.taskcluster.net/v1/task/Bjl2DGrTS3yeT88IzDPm3Q/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/e2596da1f002ea893fcbc8d0422962632b0f2ca4ef9b015282503f0ba42b931c91de2d75cd2a5f26e18f4dbaaca9f5101a02d8fe0f9eb71edca1c9fe1a9ee11d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/c5b2adc65656d7466c41505c66d81eb1edf2833ff4444454d6d24b6e61106acb10ec1d25546944fc2a5ba740ad78e0afced14d5deab34b7d575126cb634c5368", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/e090bc540b73cd1922b5fd4c69b78980e85881169f90cc8dceb28ce676ac25ee166c76e24036afb4e3136ab01a5584a173a230f2ccd0084a92c61cab2887d69c"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-18 06:45:43.165242) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-18 06:45:43.165644) ========= 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.NpuyIQgCKm/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.EIhk0nCU2G/Listeners TMPDIR=/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/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.005293 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-18 06:45:43.217579) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-18 06:45:43.217940) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-18 06:45:43.218339) ========= ========= Total master_lag: 0.20 =========