builder: larch_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0007 starttime: 1476746809.45 results: success (0) buildid: 20161017142049 builduid: 3658f20c32d04e0d82112331e655564f revision: 8c8266b3c23446275dc503366fd739ba3bc4834a ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-10-17 16:26:49.448478) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-10-17 16:26:49.448875) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-17 16:26:49.449149) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-17 16:26:49.492476) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-17 16:26:49.492807) ========= 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.rh6HAuXYyA/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.yJtiG9O7a5/Listeners TMPDIR=/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-10-17 16:26:49-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 46.6M=0s 2016-10-17 16:26:50 (46.6 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.598856 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-17 16:26:50.113933) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-17 16:26:50.114225) ========= 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.rh6HAuXYyA/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.yJtiG9O7a5/Listeners TMPDIR=/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/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.026152 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-17 16:26:50.165091) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-17 16:26:50.165885) ========= bash -c 'python archiver_client.py mozharness --repo projects/larch --rev 8c8266b3c23446275dc503366fd739ba3bc4834a --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo projects/larch --rev 8c8266b3c23446275dc503366fd739ba3bc4834a --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.rh6HAuXYyA/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.yJtiG9O7a5/Listeners TMPDIR=/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-10-17 16:26:50,243 truncating revision to first 12 chars 2016-10-17 16:26:50,243 Setting DEBUG logging. 2016-10-17 16:26:50,243 attempt 1/10 2016-10-17 16:26:50,243 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/projects/larch/8c8266b3c234?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-10-17 16:26:50,898 unpacking tar archive at: larch-8c8266b3c234/testing/mozharness/ program finished with exit code 0 elapsedTime=0.921487 ========= master_lag: 0.04 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-17 16:26:51.126521) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-17 16:26:51.126836) ========= script_repo_revision: 8c8266b3c23446275dc503366fd739ba3bc4834a ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-17 16:26:51.127274) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-17 16:26:51.127584) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-17 16:26:51.151702) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 18 secs) (at 2016-10-17 16:26:51.151998) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Larch --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Larch in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Larch', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Larch'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.rh6HAuXYyA/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.yJtiG9O7a5/Listeners TMPDIR=/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/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 16:26:51 INFO - MultiFileLogger online at 20161017 16:26:51 in /builds/slave/test 16:26:51 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Larch --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Larch 16:26:51 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 16:26:51 INFO - {'append_to_log': False, 16:26:51 INFO - 'base_work_dir': '/builds/slave/test', 16:26:51 INFO - 'blob_upload_branch': 'Larch', 16:26:51 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 16:26:51 INFO - 'branch': 'Larch', 16:26:51 INFO - 'buildbot_json_path': 'buildprops.json', 16:26:51 INFO - 'config_files': ('talos/mac_config.py',), 16:26:51 INFO - 'default_actions': ('clobber', 16:26:51 INFO - 'read-buildbot-config', 16:26:51 INFO - 'download-and-extract', 16:26:51 INFO - 'populate-webroot', 16:26:51 INFO - 'create-virtualenv', 16:26:51 INFO - 'install', 16:26:51 INFO - 'run-tests'), 16:26:51 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 16:26:51 INFO - 'download_minidump_stackwalk': True, 16:26:51 INFO - 'download_symbols': 'ondemand', 16:26:51 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 16:26:51 INFO - 'tooltool.py': '/tools/tooltool.py', 16:26:51 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 16:26:51 INFO - '/tools/misc-python/virtualenv.py')}, 16:26:51 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 16:26:51 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 16:26:51 INFO - 'installer_path': 'installer.exe', 16:26:51 INFO - 'log_level': 'info', 16:26:51 INFO - 'log_name': 'talos', 16:26:51 INFO - 'log_to_console': True, 16:26:51 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 16:26:51 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 16:26:51 INFO - 'opt_config_files': (), 16:26:51 INFO - 'pip_index': False, 16:26:51 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 16:26:51 INFO - 'cmd': ('bash', 16:26:51 INFO - '-c', 16:26:51 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 16:26:51 INFO - 'enabled': True, 16:26:51 INFO - 'halt_on_failure': False, 16:26:51 INFO - 'name': 'check_screen_resolution'},), 16:26:51 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 16:26:51 INFO - 'cmd': ('bash', 16:26:51 INFO - '-c', 16:26:51 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 16:26:51 INFO - 'enabled': True, 16:26:51 INFO - 'halt_on_failure': False, 16:26:51 INFO - 'name': 'check_screen_resolution'},), 16:26:51 INFO - 'run_cmd_checks_enabled': True, 16:26:51 INFO - 'sps_profile': False, 16:26:51 INFO - 'sps_profile_interval': 0, 16:26:51 INFO - 'suite': 'dromaeojs', 16:26:51 INFO - 'system_bits': '32', 16:26:51 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 16:26:51 INFO - 'title': 't-yosemite-r7-0007', 16:26:51 INFO - 'tooltool_cache': '/builds/tooltool_cache', 16:26:51 INFO - 'use_talos_json': True, 16:26:51 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 16:26:51 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 16:26:51 INFO - 'work_dir': 'build'} 16:26:51 INFO - [mozharness: 2016-10-17 23:26:51.360838Z] Running clobber step. 16:26:51 INFO - Running pre-action listener: _resource_record_pre_action 16:26:51 INFO - Running main action method: clobber 16:26:51 INFO - rmtree: /builds/slave/test/build 16:26:51 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 16:26:53 INFO - Running post-action listener: _resource_record_post_action 16:26:53 INFO - [mozharness: 2016-10-17 23:26:53.981113Z] Finished clobber step (success) 16:26:53 INFO - [mozharness: 2016-10-17 23:26:53.981317Z] Running read-buildbot-config step. 16:26:53 INFO - Running pre-action listener: _resource_record_pre_action 16:26:53 INFO - Running main action method: read_buildbot_config 16:26:53 INFO - Using buildbot properties: 16:26:53 INFO - { 16:26:53 INFO - "project": "", 16:26:53 INFO - "product": "firefox", 16:26:53 INFO - "script_repo_revision": "production", 16:26:53 INFO - "builddir": "larch_yosemite_r7_test-dromaeojs", 16:26:53 INFO - "repository": "", 16:26:53 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 larch talos dromaeojs", 16:26:53 INFO - "buildid": "20161017142049", 16:26:53 INFO - "pgo_build": "False", 16:26:53 INFO - "basedir": "/builds/slave/test", 16:26:53 INFO - "buildnumber": 0, 16:26:53 INFO - "slavename": "t-yosemite-r7-0007", 16:26:53 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 16:26:53 INFO - "platform": "yosemite_r7", 16:26:53 INFO - "scheduler": "tests-larch-macosx64-talos", 16:26:53 INFO - "branch": "larch", 16:26:53 INFO - "repo_path": "projects/larch", 16:26:53 INFO - "revision": "8c8266b3c23446275dc503366fd739ba3bc4834a", 16:26:53 INFO - "stage_platform": "macosx64", 16:26:53 INFO - "builduid": "3658f20c32d04e0d82112331e655564f", 16:26:53 INFO - "slavebuilddir": "test" 16:26:53 INFO - } 16:26:53 INFO - Found installer url https://queue.taskcluster.net/v1/task/V6dbqvZfQiKZUCjcPPrl0g/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg. 16:26:53 INFO - Running post-action listener: _resource_record_post_action 16:26:53 INFO - [mozharness: 2016-10-17 23:26:53.987092Z] Finished read-buildbot-config step (success) 16:26:53 INFO - [mozharness: 2016-10-17 23:26:53.987293Z] Running download-and-extract step. 16:26:53 INFO - Running pre-action listener: _resource_record_pre_action 16:26:53 INFO - Running main action method: download_and_extract 16:26:53 INFO - mkdir: /builds/slave/test/build/tests 16:26: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')]} 16:26:53 INFO - trying https://queue.taskcluster.net/v1/task/V6dbqvZfQiKZUCjcPPrl0g/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json 16:26:53 INFO - Downloading https://queue.taskcluster.net/v1/task/V6dbqvZfQiKZUCjcPPrl0g/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 16:26:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/V6dbqvZfQiKZUCjcPPrl0g/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json'}, attempt #1 16:26:54 INFO - Downloaded 1199 bytes. 16:26:54 INFO - Reading from file /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 16:26:54 INFO - Using the following test package requirements: 16:26:54 INFO - {u'common': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 16:26:54 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 16:26:54 INFO - u'firefox-52.0a1.en-US.mac.cppunittest.tests.zip'], 16:26:54 INFO - u'gtest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 16:26:54 INFO - u'firefox-52.0a1.en-US.mac.gtest.tests.zip'], 16:26:54 INFO - u'jittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 16:26:54 INFO - u'jsshell-mac.zip'], 16:26:54 INFO - u'mochitest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 16:26:54 INFO - u'firefox-52.0a1.en-US.mac.mochitest.tests.zip'], 16:26:54 INFO - u'mozbase': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 16:26:54 INFO - u'reftest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 16:26:54 INFO - u'firefox-52.0a1.en-US.mac.reftest.tests.zip'], 16:26:54 INFO - u'talos': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 16:26:54 INFO - u'firefox-52.0a1.en-US.mac.talos.tests.zip'], 16:26:54 INFO - u'web-platform': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 16:26:54 INFO - u'firefox-52.0a1.en-US.mac.web-platform.tests.zip'], 16:26:54 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 16:26:54 INFO - u'firefox-52.0a1.en-US.mac.xpcshell.tests.zip']} 16:26:54 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip'] for test suite category: common 16:26:54 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/V6dbqvZfQiKZUCjcPPrl0g/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 16:26:54 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/V6dbqvZfQiKZUCjcPPrl0g/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 16:26:54 INFO - Fetch https://queue.taskcluster.net/v1/task/V6dbqvZfQiKZUCjcPPrl0g/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 16:26:55 INFO - Http code: 200 16:26:55 INFO - via: 1.1 76bce8bb4fbd102fc0b3aa2e41094b79.cloudfront.net (CloudFront) 16:26:55 INFO - x-amz-cf-id: SUVmof2t6TLTX09nQQgcwpnmhJdS2q_7dWu11FexKI6soFAu-3KHXQ== 16:26:55 INFO - x-amz-version-id: SMPvhBTK3totf3lt5JJ5PNmIT33TWLrz 16:26:55 INFO - Expected file size: 16508477 16:26:55 INFO - Obtained file size: 16508477 16:27:02 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip', u'firefox-52.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 16:27:02 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/V6dbqvZfQiKZUCjcPPrl0g/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 16:27:02 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/V6dbqvZfQiKZUCjcPPrl0g/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 16:27:02 INFO - Fetch https://queue.taskcluster.net/v1/task/V6dbqvZfQiKZUCjcPPrl0g/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 16:27:02 INFO - Http code: 200 16:27:02 INFO - via: 1.1 ff555a0cc6346f82fe74596f8ed6cc33.cloudfront.net (CloudFront) 16:27:02 INFO - x-amz-cf-id: CnWiW05yBGDX-YEK3iBH9cpMx9A_oM5uN7Q7hOQxwms6vo4fAN9njg== 16:27:02 INFO - x-amz-version-id: SMPvhBTK3totf3lt5JJ5PNmIT33TWLrz 16:27:03 INFO - Expected file size: 16508477 16:27:03 INFO - Obtained file size: 16508477 16:27:09 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/V6dbqvZfQiKZUCjcPPrl0g/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip 16:27:09 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/V6dbqvZfQiKZUCjcPPrl0g/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip'}, attempt #1 16:27:09 INFO - Fetch https://queue.taskcluster.net/v1/task/V6dbqvZfQiKZUCjcPPrl0g/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip into memory 16:27:10 INFO - Http code: 200 16:27:10 INFO - via: 1.1 d2bd759914e30b1d5aee2929535c55f9.cloudfront.net (CloudFront) 16:27:10 INFO - x-amz-cf-id: i9vQDra70avaLx7J-hiJQL8t8jnmNHGo0SpcyH0ITLQztu5N7_nWyg== 16:27:10 INFO - x-amz-version-id: yCTwt37Pu2esJ6GkdzPXiFT_za08mX_T 16:27:10 INFO - Expected file size: 14409006 16:27:10 INFO - Obtained file size: 14409006 16:27: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')]} 16:27:10 INFO - trying https://queue.taskcluster.net/v1/task/V6dbqvZfQiKZUCjcPPrl0g/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 16:27:10 INFO - Downloading https://queue.taskcluster.net/v1/task/V6dbqvZfQiKZUCjcPPrl0g/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg 16:27:10 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/V6dbqvZfQiKZUCjcPPrl0g/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg'}, attempt #1 16:27:13 INFO - Downloaded 111698919 bytes. 16:27:13 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/V6dbqvZfQiKZUCjcPPrl0g/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 16:27:13 INFO - mkdir: /builds/slave/test/properties 16:27:13 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 16:27:13 INFO - Writing to file /builds/slave/test/properties/build_url 16:27:13 INFO - Contents: 16:27:13 INFO - build_url:https://queue.taskcluster.net/v1/task/V6dbqvZfQiKZUCjcPPrl0g/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 16:27:14 INFO - Running post-action listener: _resource_record_post_action 16:27:14 INFO - Running post-action listener: set_extra_try_arguments 16:27:14 INFO - [mozharness: 2016-10-17 23:27:14.117148Z] Finished download-and-extract step (success) 16:27:14 INFO - [mozharness: 2016-10-17 23:27:14.117263Z] Running populate-webroot step. 16:27:14 INFO - Running pre-action listener: _resource_record_pre_action 16:27:14 INFO - Running main action method: populate_webroot 16:27:14 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 16:27:14 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 16:27:14 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 16:27:14 INFO - '--tppagecycles', 16:27:14 INFO - '7'], 16:27:14 INFO - 'tests': ['tsvgm']}}, 16:27:14 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 16:27:14 INFO - 'tests': ['tresize', 'tcanvasmark']}, 16:27:14 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 16:27:14 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 16:27:14 INFO - 'tests': ['dromaeo_css', 'kraken']}, 16:27:14 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 16:27:14 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:27:14 INFO - 'talos_options': ['--disable-e10s'], 16:27:14 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:27:14 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:27:14 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:27:14 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:27:14 INFO - 'talos_options': ['--disable-e10s'], 16:27:14 INFO - 'tests': ['damp', 'tps']}, 16:27:14 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:27:14 INFO - 'tests': ['damp', 'tps']}, 16:27:14 INFO - 'g3': {'talos_options': ['--disable-e10s'], 16:27:14 INFO - 'tests': ['dromaeo_dom']}, 16:27:14 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 16:27:14 INFO - 'g4': {'talos_options': ['--disable-e10s'], 16:27:14 INFO - 'tests': ['basic_compositor_video']}, 16:27:14 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 16:27:14 INFO - 'other': {'talos_options': ['--disable-e10s'], 16:27:14 INFO - 'tests': ['a11yr', 16:27:14 INFO - 'ts_paint', 16:27:14 INFO - 'tpaint', 16:27:14 INFO - 'sessionrestore', 16:27:14 INFO - 'sessionrestore_no_auto_restore', 16:27:14 INFO - 'tabpaint']}, 16:27:14 INFO - 'other-e10s': {'tests': ['a11yr', 16:27:14 INFO - 'ts_paint', 16:27:14 INFO - 'tpaint', 16:27:14 INFO - 'sessionrestore', 16:27:14 INFO - 'sessionrestore_no_auto_restore', 16:27:14 INFO - 'tabpaint']}, 16:27:14 INFO - 'other-e10s_l64': {'tests': ['a11yr', 16:27:14 INFO - 'ts_paint', 16:27:14 INFO - 'tpaint', 16:27:14 INFO - 'sessionrestore', 16:27:14 INFO - 'sessionrestore_no_auto_restore', 16:27:14 INFO - 'tabpaint']}, 16:27:14 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 16:27:14 INFO - 'ts_paint', 16:27:14 INFO - 'tpaint', 16:27:14 INFO - 'sessionrestore', 16:27:14 INFO - 'sessionrestore_no_auto_restore', 16:27:14 INFO - 'tabpaint']}, 16:27:14 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 16:27:14 INFO - 'tests': ['a11yr', 16:27:14 INFO - 'ts_paint', 16:27:14 INFO - 'tpaint', 16:27:14 INFO - 'sessionrestore', 16:27:14 INFO - 'sessionrestore_no_auto_restore', 16:27:14 INFO - 'tabpaint']}, 16:27:14 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 16:27:14 INFO - 'tests': ['a11yr', 16:27:14 INFO - 'ts_paint', 16:27:14 INFO - 'tpaint', 16:27:14 INFO - 'sessionrestore', 16:27:14 INFO - 'sessionrestore_no_auto_restore', 16:27:14 INFO - 'tabpaint']}, 16:27:14 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 16:27:14 INFO - 'tests': ['tsvgx', 16:27:14 INFO - 'tsvgr_opacity', 16:27:14 INFO - 'tart', 16:27:14 INFO - 'tscrollx', 16:27:14 INFO - 'cart']}, 16:27:14 INFO - 'svgr-e10s': {'tests': ['tsvgx', 16:27:14 INFO - 'tsvgr_opacity', 16:27:14 INFO - 'tart', 16:27:14 INFO - 'tscrollx', 16:27:14 INFO - 'cart']}, 16:27:14 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:27:14 INFO - 'talos_options': ['--disable-e10s'], 16:27:14 INFO - 'tests': ['tp5o']}, 16:27:14 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:27:14 INFO - 'tests': ['tp5o']}, 16:27:14 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:27:14 INFO - 'talos_options': ['--disable-e10s', 16:27:14 INFO - '--xperf_path', 16:27:14 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:27:14 INFO - 'tests': ['tp5n']}, 16:27:14 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:27:14 INFO - 'talos_options': ['--xperf_path', 16:27:14 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:27:14 INFO - 'tests': ['tp5n']}}, 16:27:14 INFO - 'talos.zip': {'path': '', 16:27:14 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 16:27:14 INFO - Running post-action listener: _resource_record_post_action 16:27:14 INFO - [mozharness: 2016-10-17 23:27:14.136944Z] Finished populate-webroot step (success) 16:27:14 INFO - [mozharness: 2016-10-17 23:27:14.137084Z] Running create-virtualenv step. 16:27:14 INFO - Running pre-action listener: _resource_record_pre_action 16:27:14 INFO - Running main action method: create_virtualenv 16:27:14 INFO - Creating virtualenv /builds/slave/test/build/venv 16:27:14 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 16:27:14 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 16:27:14 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 16:27:14 INFO - Using real prefix '/tools/python27' 16:27:14 INFO - New python executable in /builds/slave/test/build/venv/bin/python 16:27:14 INFO - Installing distribute.............................................................................................................................................................................................done. 16:27:17 INFO - Installing pip.................done. 16:27:17 INFO - Return code: 0 16:27:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 16:27:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 16:27:18 INFO - Reading from file tmpfile_stdout 16:27:18 INFO - Output received: 16:27:18 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) 16:27:18 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 16:27:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:27:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:27:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:27:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:27:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:27:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:27:18 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11057c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1104f2570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fed134cb690>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110390d30>, '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 0x1101b6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x110388b20>, '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.rh6HAuXYyA/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.yJtiG9O7a5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/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 16:27:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 16:27:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 16:27:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rh6HAuXYyA/Render', 16:27:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:27:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:27:18 INFO - 'HOME': '/Users/cltbld', 16:27:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:27:18 INFO - 'LOGNAME': 'cltbld', 16:27:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:27:18 INFO - 'MOZ_NO_REMOTE': '1', 16:27:18 INFO - 'NO_EM_RESTART': '1', 16:27:18 INFO - 'PAGER': '/bin/cat', 16:27:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:27:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:27:18 INFO - 'PWD': '/builds/slave/test', 16:27:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:27:18 INFO - 'SHELL': '/bin/bash', 16:27:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yJtiG9O7a5/Listeners', 16:27:18 INFO - 'TMPDIR': '/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/', 16:27:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:27:18 INFO - 'USER': 'cltbld', 16:27:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:27:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:27:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:27:18 INFO - 'XPC_FLAGS': '0x0', 16:27:18 INFO - 'XPC_SERVICE_NAME': '0', 16:27:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:27:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:27:18 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 16:27:18 INFO - Cleaning up... 16:27:18 INFO - Return code: 0 16:27:18 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 16:27:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:27:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:27:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:27:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:27:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:27:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:27:18 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x11057c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1104f2570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fed134cb690>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110390d30>, '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 0x1101b6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x110388b20>, '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.rh6HAuXYyA/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.yJtiG9O7a5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/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 16:27:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 16:27:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 16:27:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rh6HAuXYyA/Render', 16:27:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:27:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:27:18 INFO - 'HOME': '/Users/cltbld', 16:27:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:27:18 INFO - 'LOGNAME': 'cltbld', 16:27:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:27:18 INFO - 'MOZ_NO_REMOTE': '1', 16:27:18 INFO - 'NO_EM_RESTART': '1', 16:27:18 INFO - 'PAGER': '/bin/cat', 16:27:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:27:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:27:18 INFO - 'PWD': '/builds/slave/test', 16:27:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:27:18 INFO - 'SHELL': '/bin/bash', 16:27:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yJtiG9O7a5/Listeners', 16:27:18 INFO - 'TMPDIR': '/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/', 16:27:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:27:18 INFO - 'USER': 'cltbld', 16:27:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:27:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:27:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:27:18 INFO - 'XPC_FLAGS': '0x0', 16:27:18 INFO - 'XPC_SERVICE_NAME': '0', 16:27:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:27:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:27:18 INFO - Downloading/unpacking psutil>=3.1.1 16:27:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:27:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:27:18 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:27:18 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:27:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:27:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:27:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 16:27:22 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:27:22 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:27:22 INFO - Installing collected packages: psutil 16:27:22 INFO - Running setup.py install for psutil 16:27:22 INFO - building 'psutil._psutil_osx' extension 16:27:22 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 16:27:23 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 16:27:23 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 16:27:23 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 16:27:23 INFO - building 'psutil._psutil_posix' extension 16:27:23 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 16:27:23 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 16:27:23 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 16:27:23 INFO - ^ 16:27:23 INFO - 1 warning generated. 16:27:23 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 16:27:23 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:27:23 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:27:23 INFO - Successfully installed psutil 16:27:23 INFO - Cleaning up... 16:27:23 INFO - Return code: 0 16:27:23 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 16:27:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:27:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:27:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:27:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:27:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:27:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:27:23 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 0x11057c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1104f2570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fed134cb690>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110390d30>, '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 0x1101b6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x110388b20>, '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.rh6HAuXYyA/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.yJtiG9O7a5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/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 16:27:23 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 16:27:23 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 16:27:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rh6HAuXYyA/Render', 16:27:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:27:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:27:23 INFO - 'HOME': '/Users/cltbld', 16:27:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:27:23 INFO - 'LOGNAME': 'cltbld', 16:27:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:27:23 INFO - 'MOZ_NO_REMOTE': '1', 16:27:23 INFO - 'NO_EM_RESTART': '1', 16:27:23 INFO - 'PAGER': '/bin/cat', 16:27:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:27:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:27:23 INFO - 'PWD': '/builds/slave/test', 16:27:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:27:23 INFO - 'SHELL': '/bin/bash', 16:27:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yJtiG9O7a5/Listeners', 16:27:23 INFO - 'TMPDIR': '/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/', 16:27:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:27:23 INFO - 'USER': 'cltbld', 16:27:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:27:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:27:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:27:23 INFO - 'XPC_FLAGS': '0x0', 16:27:23 INFO - 'XPC_SERVICE_NAME': '0', 16:27:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:27:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:27:23 INFO - Downloading/unpacking mozsystemmonitor==0.3 16:27:23 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 16:27:23 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 16:27:23 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 16:27:23 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 16:27:23 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 16:27:23 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 16:27:26 INFO - Downloading mozsystemmonitor-0.3.tar.gz 16:27:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 16:27:26 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 16:27:26 INFO - Installing collected packages: mozsystemmonitor 16:27:26 INFO - Running setup.py install for mozsystemmonitor 16:27:26 INFO - Successfully installed mozsystemmonitor 16:27:26 INFO - Cleaning up... 16:27:26 INFO - Return code: 0 16:27:26 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 16:27:26 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')]} 16:27:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:27:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:27:26 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')]} 16:27:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:27:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:27:26 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 0x11057c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1104f2570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fed134cb690>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110390d30>, '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 0x1101b6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x110388b20>, '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.rh6HAuXYyA/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.yJtiG9O7a5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/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 16:27:26 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 16:27:26 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 16:27:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rh6HAuXYyA/Render', 16:27:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:27:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:27:26 INFO - 'HOME': '/Users/cltbld', 16:27:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:27:26 INFO - 'LOGNAME': 'cltbld', 16:27:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:27:26 INFO - 'MOZ_NO_REMOTE': '1', 16:27:26 INFO - 'NO_EM_RESTART': '1', 16:27:26 INFO - 'PAGER': '/bin/cat', 16:27:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:27:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:27:26 INFO - 'PWD': '/builds/slave/test', 16:27:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:27:26 INFO - 'SHELL': '/bin/bash', 16:27:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yJtiG9O7a5/Listeners', 16:27:26 INFO - 'TMPDIR': '/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/', 16:27:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:27:26 INFO - 'USER': 'cltbld', 16:27:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:27:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:27:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:27:26 INFO - 'XPC_FLAGS': '0x0', 16:27:26 INFO - 'XPC_SERVICE_NAME': '0', 16:27:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:27:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:27:27 INFO - Downloading/unpacking jsonschema==2.5.1 16:27:27 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 16:27:27 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 16:27:27 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 16:27:27 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 16:27:27 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 16:27:27 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 16:27:29 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 16:27:29 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 16:27:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:27:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:27:29 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:27:29 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:27:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:27:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:27:29 INFO - Downloading functools32-3.2.3-2.tar.gz 16:27:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 16:27:30 INFO - warning: no files found matching '*.txt' 16:27:30 INFO - no previously-included directories found matching 'build' 16:27:30 INFO - no previously-included directories found matching 'dist' 16:27:30 INFO - no previously-included directories found matching '.git*' 16:27:30 INFO - Installing collected packages: jsonschema, functools32 16:27:30 INFO - Running setup.py install for functools32 16:27:30 INFO - warning: no files found matching '*.txt' 16:27:30 INFO - no previously-included directories found matching 'build' 16:27:30 INFO - no previously-included directories found matching 'dist' 16:27:30 INFO - no previously-included directories found matching '.git*' 16:27:30 INFO - Successfully installed jsonschema functools32 16:27:30 INFO - Cleaning up... 16:27:30 INFO - Return code: 0 16:27:30 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 16:27:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:27:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:27:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:27:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:27:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:27:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:27:30 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x11057c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1104f2570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fed134cb690>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110390d30>, '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 0x1101b6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x110388b20>, '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.rh6HAuXYyA/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.yJtiG9O7a5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/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 16:27:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 16:27:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 16:27:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rh6HAuXYyA/Render', 16:27:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:27:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:27:30 INFO - 'HOME': '/Users/cltbld', 16:27:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:27:30 INFO - 'LOGNAME': 'cltbld', 16:27:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:27:30 INFO - 'MOZ_NO_REMOTE': '1', 16:27:30 INFO - 'NO_EM_RESTART': '1', 16:27:30 INFO - 'PAGER': '/bin/cat', 16:27:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:27:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:27:30 INFO - 'PWD': '/builds/slave/test', 16:27:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:27:30 INFO - 'SHELL': '/bin/bash', 16:27:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yJtiG9O7a5/Listeners', 16:27:30 INFO - 'TMPDIR': '/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/', 16:27:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:27:30 INFO - 'USER': 'cltbld', 16:27:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:27:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:27:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:27:30 INFO - 'XPC_FLAGS': '0x0', 16:27:30 INFO - 'XPC_SERVICE_NAME': '0', 16:27:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:27:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:27:30 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 16:27:30 INFO - Cleaning up... 16:27:30 INFO - Return code: 0 16:27:30 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 16:27:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:27:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:27:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:27:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:27:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:27:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:27:30 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x11057c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1104f2570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fed134cb690>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110390d30>, '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 0x1101b6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x110388b20>, '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.rh6HAuXYyA/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.yJtiG9O7a5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/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 16:27:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 16:27:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 16:27:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rh6HAuXYyA/Render', 16:27:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:27:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:27:30 INFO - 'HOME': '/Users/cltbld', 16:27:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:27:30 INFO - 'LOGNAME': 'cltbld', 16:27:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:27:30 INFO - 'MOZ_NO_REMOTE': '1', 16:27:30 INFO - 'NO_EM_RESTART': '1', 16:27:30 INFO - 'PAGER': '/bin/cat', 16:27:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:27:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:27:30 INFO - 'PWD': '/builds/slave/test', 16:27:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:27:30 INFO - 'SHELL': '/bin/bash', 16:27:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yJtiG9O7a5/Listeners', 16:27:30 INFO - 'TMPDIR': '/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/', 16:27:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:27:30 INFO - 'USER': 'cltbld', 16:27:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:27:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:27:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:27:30 INFO - 'XPC_FLAGS': '0x0', 16:27:30 INFO - 'XPC_SERVICE_NAME': '0', 16:27:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:27:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:27:30 INFO - Downloading/unpacking blobuploader==1.2.4 16:27:30 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 16:27:30 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 16:27:30 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 16:27:30 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 16:27:30 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 16:27:30 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 16:27:33 INFO - Downloading blobuploader-1.2.4.tar.gz 16:27:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 16:27:33 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 16:27:33 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 16:27:33 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 16:27:33 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 16:27:33 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 16:27:33 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 16:27:33 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 16:27:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 16:27:34 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 16:27:34 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 16:27:34 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 16:27:34 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 16:27:34 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 16:27:34 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 16:27:34 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 16:27:34 INFO - Downloading docopt-0.6.1.tar.gz 16:27:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 16:27:34 INFO - Installing collected packages: blobuploader, requests, docopt 16:27:34 INFO - Running setup.py install for blobuploader 16:27:34 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 16:27:34 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 16:27:34 INFO - Running setup.py install for requests 16:27:35 INFO - Running setup.py install for docopt 16:27:35 INFO - Successfully installed blobuploader requests docopt 16:27:35 INFO - Cleaning up... 16:27:35 INFO - Return code: 0 16:27:35 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:27:35 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')]} 16:27:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:27:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:27:35 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')]} 16:27:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:27:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:27:35 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 0x11057c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1104f2570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fed134cb690>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110390d30>, '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 0x1101b6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x110388b20>, '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.rh6HAuXYyA/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.yJtiG9O7a5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/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 16:27:35 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 16:27:35 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 16:27:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rh6HAuXYyA/Render', 16:27:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:27:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:27:35 INFO - 'HOME': '/Users/cltbld', 16:27:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:27:35 INFO - 'LOGNAME': 'cltbld', 16:27:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:27:35 INFO - 'MOZ_NO_REMOTE': '1', 16:27:35 INFO - 'NO_EM_RESTART': '1', 16:27:35 INFO - 'PAGER': '/bin/cat', 16:27:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:27:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:27:35 INFO - 'PWD': '/builds/slave/test', 16:27:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:27:35 INFO - 'SHELL': '/bin/bash', 16:27:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yJtiG9O7a5/Listeners', 16:27:35 INFO - 'TMPDIR': '/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/', 16:27:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:27:35 INFO - 'USER': 'cltbld', 16:27:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:27:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:27:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:27:35 INFO - 'XPC_FLAGS': '0x0', 16:27:35 INFO - 'XPC_SERVICE_NAME': '0', 16:27:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:27:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:27:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:27:35 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-2C_BLU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:27:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:27:35 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-yuSOiN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:27:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:27:35 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-PTZS2p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:27:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:27:36 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-OPDLDF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:27:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:27:36 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-0Rgv8Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:27:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:27:36 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-9uHPb4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:27:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:27:36 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-XwFJXZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:27:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:27:36 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-b9hNvC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:27:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:27:36 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-eBW9b_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:27:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:27:36 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-LfCps7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:27:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:27:36 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-1rMRTZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:27:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:27:36 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-MyhxMd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:27:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:27:37 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-T72gNc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:27:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:27:37 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-zXnzML-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:27:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:27:37 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-LgNujZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:27:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:27:37 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-pa0MNG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:27:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:27:37 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-ts926l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:27:37 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 16:27:37 INFO - Running setup.py install for manifestparser 16:27:37 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 16:27:37 INFO - Running setup.py install for mozcrash 16:27:37 INFO - Running setup.py install for mozdebug 16:27:37 INFO - Running setup.py install for mozdevice 16:27:38 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 16:27:38 INFO - Installing dm script to /builds/slave/test/build/venv/bin 16:27:38 INFO - Running setup.py install for mozfile 16:27:38 INFO - Running setup.py install for mozhttpd 16:27:38 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 16:27:38 INFO - Running setup.py install for mozinfo 16:27:38 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 16:27:38 INFO - Running setup.py install for mozInstall 16:27:38 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 16:27:38 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 16:27:38 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 16:27:38 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 16:27:38 INFO - Running setup.py install for mozleak 16:27:38 INFO - Running setup.py install for mozlog 16:27:39 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 16:27:39 INFO - Running setup.py install for moznetwork 16:27:39 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 16:27:39 INFO - Running setup.py install for mozprocess 16:27:39 INFO - Running setup.py install for mozprofile 16:27:39 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 16:27:39 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 16:27:39 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 16:27:39 INFO - Running setup.py install for mozrunner 16:27:39 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 16:27:39 INFO - Running setup.py install for mozscreenshot 16:27:39 INFO - Running setup.py install for moztest 16:27:39 INFO - Running setup.py install for mozversion 16:27:40 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 16:27:40 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 16:27:40 INFO - Cleaning up... 16:27:40 INFO - Return code: 0 16:27:40 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:27:40 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')]} 16:27:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:27:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:27:40 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')]} 16:27:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:27:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:27:40 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 0x11057c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1104f2570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fed134cb690>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110390d30>, '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 0x1101b6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x110388b20>, '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.rh6HAuXYyA/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.yJtiG9O7a5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/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 16:27:40 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 16:27:40 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 16:27:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rh6HAuXYyA/Render', 16:27:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:27:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:27:40 INFO - 'HOME': '/Users/cltbld', 16:27:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:27:40 INFO - 'LOGNAME': 'cltbld', 16:27:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:27:40 INFO - 'MOZ_NO_REMOTE': '1', 16:27:40 INFO - 'NO_EM_RESTART': '1', 16:27:40 INFO - 'PAGER': '/bin/cat', 16:27:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:27:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:27:40 INFO - 'PWD': '/builds/slave/test', 16:27:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:27:40 INFO - 'SHELL': '/bin/bash', 16:27:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yJtiG9O7a5/Listeners', 16:27:40 INFO - 'TMPDIR': '/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/', 16:27:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:27:40 INFO - 'USER': 'cltbld', 16:27:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:27:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:27:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:27:40 INFO - 'XPC_FLAGS': '0x0', 16:27:40 INFO - 'XPC_SERVICE_NAME': '0', 16:27:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:27:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:27:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:27:40 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-xPAr_m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:27:40 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)) 16:27:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:27:40 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-WrY5O5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:27:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 16:27:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:27:40 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-MmMTeW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:27:40 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)) 16:27:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:27:40 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-XP7ztM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:27:40 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)) 16:27:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:27:40 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-BDxeck-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:27:40 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)) 16:27:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:27:40 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-cxNmLs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:27:41 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)) 16:27:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:27:41 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-lL7chQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:27:41 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)) 16:27:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:27:41 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-h8wAQp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:27:41 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)) 16:27:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:27:41 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-cR5nnT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:27:41 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)) 16:27:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:27:41 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-GCfsfJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:27:41 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)) 16:27:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:27:41 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-uZwKo9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:27:41 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)) 16:27:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:27:41 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-QiDDM2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:27:41 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)) 16:27:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:27:41 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-84uns1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:27:41 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)) 16:27:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:27:41 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-CC_tMB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:27:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 16:27:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:27:41 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-ejS84s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:27:41 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)) 16:27:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:27:41 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-BsV8yH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:27:42 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)) 16:27:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:27:42 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-paDwD6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:27:42 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)) 16:27:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 16:27:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 16:27:42 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)) 16:27:42 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)) 16:27:42 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)) 16:27:42 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 16:27:42 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 16:27:42 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 16:27:42 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 16:27:42 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 16:27:42 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 16:27:42 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 16:27:44 INFO - Downloading blessings-1.6.tar.gz 16:27:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 16:27:44 INFO - Installing collected packages: blessings 16:27:44 INFO - Running setup.py install for blessings 16:27:44 INFO - Successfully installed blessings 16:27:44 INFO - Cleaning up... 16:27:45 INFO - Return code: 0 16:27:45 INFO - Done creating virtualenv /builds/slave/test/build/venv. 16:27:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:27:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:27:45 INFO - Reading from file tmpfile_stdout 16:27:45 INFO - Current package versions: 16:27:45 INFO - blessings == 1.6 16:27:45 INFO - blobuploader == 1.2.4 16:27:45 INFO - docopt == 0.6.1 16:27:45 INFO - functools32 == 3.2.3-2 16:27:45 INFO - jsonschema == 2.5.1 16:27:45 INFO - manifestparser == 1.1 16:27:45 INFO - mozInstall == 1.12 16:27:45 INFO - mozcrash == 0.17 16:27:45 INFO - mozdebug == 0.1 16:27:45 INFO - mozdevice == 0.48 16:27:45 INFO - mozfile == 1.2 16:27:45 INFO - mozhttpd == 0.7 16:27:45 INFO - mozinfo == 0.9 16:27:45 INFO - mozleak == 0.1 16:27:45 INFO - mozlog == 3.4 16:27:45 INFO - moznetwork == 0.27 16:27:45 INFO - mozprocess == 0.23 16:27:45 INFO - mozprofile == 0.28 16:27:45 INFO - mozrunner == 6.12 16:27:45 INFO - mozscreenshot == 0.1 16:27:45 INFO - mozsystemmonitor == 0.3 16:27:45 INFO - moztest == 0.7 16:27:45 INFO - mozversion == 1.4 16:27:45 INFO - psutil == 3.1.1 16:27:45 INFO - requests == 1.2.3 16:27:45 INFO - wsgiref == 0.1.2 16:27:45 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:27:45 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')]} 16:27:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:27:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:27:45 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')]} 16:27:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:27:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:27:45 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 0x11057c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1104f2570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fed134cb690>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110390d30>, '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 0x1101b6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x110388b20>, '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.rh6HAuXYyA/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.yJtiG9O7a5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/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 16:27:45 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 16:27:45 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 16:27:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rh6HAuXYyA/Render', 16:27:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:27:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:27:45 INFO - 'HOME': '/Users/cltbld', 16:27:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:27:45 INFO - 'LOGNAME': 'cltbld', 16:27:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:27:45 INFO - 'MOZ_NO_REMOTE': '1', 16:27:45 INFO - 'NO_EM_RESTART': '1', 16:27:45 INFO - 'PAGER': '/bin/cat', 16:27:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:27:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:27:45 INFO - 'PWD': '/builds/slave/test', 16:27:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:27:45 INFO - 'SHELL': '/bin/bash', 16:27:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yJtiG9O7a5/Listeners', 16:27:45 INFO - 'TMPDIR': '/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/', 16:27:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:27:45 INFO - 'USER': 'cltbld', 16:27:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:27:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:27:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:27:45 INFO - 'XPC_FLAGS': '0x0', 16:27:45 INFO - 'XPC_SERVICE_NAME': '0', 16:27:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:27:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:27:45 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)) 16:27:45 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)) 16:27:45 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)) 16:27:45 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)) 16:27:45 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)) 16:27:45 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)) 16:27:45 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)) 16:27:45 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)) 16:27:45 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)) 16:27:45 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)) 16:27:45 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)) 16:27:45 INFO - Cleaning up... 16:27:45 INFO - Return code: 0 16:27:45 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 16:27:45 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')]} 16:27:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:27:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:27:45 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')]} 16:27:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:27:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:27:45 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 0x11057c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1104f2570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fed134cb690>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110390d30>, '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 0x1101b6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x110388b20>, '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.rh6HAuXYyA/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.yJtiG9O7a5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/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 16:27:45 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 16:27:45 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 16:27:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rh6HAuXYyA/Render', 16:27:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:27:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:27:45 INFO - 'HOME': '/Users/cltbld', 16:27:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:27:45 INFO - 'LOGNAME': 'cltbld', 16:27:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:27:45 INFO - 'MOZ_NO_REMOTE': '1', 16:27:45 INFO - 'NO_EM_RESTART': '1', 16:27:45 INFO - 'PAGER': '/bin/cat', 16:27:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:27:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:27:45 INFO - 'PWD': '/builds/slave/test', 16:27:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:27:45 INFO - 'SHELL': '/bin/bash', 16:27:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yJtiG9O7a5/Listeners', 16:27:45 INFO - 'TMPDIR': '/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/', 16:27:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:27:45 INFO - 'USER': 'cltbld', 16:27:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:27:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:27:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:27:45 INFO - 'XPC_FLAGS': '0x0', 16:27:45 INFO - 'XPC_SERVICE_NAME': '0', 16:27:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:27:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:27:45 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 16:27:45 INFO - Cleaning up... 16:27:45 INFO - Return code: 0 16:27:45 INFO - Running post-action listener: _resource_record_post_action 16:27:45 INFO - Running post-action listener: _start_resource_monitoring 16:27:45 INFO - Starting resource monitoring. 16:27:45 INFO - [mozharness: 2016-10-17 23:27:45.937017Z] Finished create-virtualenv step (success) 16:27:45 INFO - [mozharness: 2016-10-17 23:27:45.937821Z] Running install step. 16:27:45 INFO - Running pre-action listener: _resource_record_pre_action 16:27:45 INFO - Running main action method: install 16:27:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:27:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:27:46 INFO - Reading from file tmpfile_stdout 16:27:46 INFO - Detecting whether we're running mozinstall >=1.0... 16:27:46 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 16:27:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 16:27:46 INFO - Reading from file tmpfile_stdout 16:27:46 INFO - Output received: 16:27:46 INFO - Usage: mozinstall [options] installer 16:27:46 INFO - Options: 16:27:46 INFO - -h, --help show this help message and exit 16:27:46 INFO - -d DEST, --destination=DEST 16:27:46 INFO - Directory to install application into. [default: 16:27:46 INFO - "/builds/slave/test"] 16:27:46 INFO - --app=APP Application being installed. [default: firefox] 16:27:46 INFO - mkdir: /builds/slave/test/build/application 16:27:46 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 16:27:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 16:28:20 INFO - Reading from file tmpfile_stdout 16:28:20 INFO - Output received: 16:28:20 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 16:28:20 INFO - Running post-action listener: _resource_record_post_action 16:28:20 INFO - [mozharness: 2016-10-17 23:28:20.543696Z] Finished install step (success) 16:28:20 INFO - [mozharness: 2016-10-17 23:28:20.543801Z] Running run-tests step. 16:28:20 INFO - Running pre-action listener: _resource_record_pre_action 16:28:20 INFO - Running main action method: run_tests 16:28:20 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 16:28:20 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 16:28:20 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 16:28:20 INFO - 2016-10-17 16:28:20.558 screenresolution[1653:8142] starting screenresolution argv=screenresolution get 16:28:20 INFO - 2016-10-17 16:28:20.560 screenresolution[1653:8142] Display 0: 1600x1200x32@60 16:28:20 INFO - 2016-10-17 16:28:20.566 screenresolution[1654:8144] starting screenresolution argv=screenresolution list 16:28:20 INFO - Available Modes on Display 0 16:28:20 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 16:28:20 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 16:28:20 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 16:28:20 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 16:28:20 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 16:28:20 INFO - Intel Iris: 16:28:20 INFO - Chipset Model: Intel Iris 16:28:20 INFO - Type: GPU 16:28:20 INFO - Bus: Built-In 16:28:20 INFO - VRAM (Dynamic, Max): 1536 MB 16:28:20 INFO - Vendor: Intel (0x8086) 16:28:20 INFO - Device ID: 0x0a2e 16:28:20 INFO - Revision ID: 0x0009 16:28:20 INFO - Displays: 16:28:20 INFO - Display: 16:28:20 INFO - Resolution: 1600 x 1200 @ 60 Hz 16:28:20 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 16:28:20 INFO - Display Serial Number: 1600x1200 60 16:28:20 INFO - Main Display: Yes 16:28:20 INFO - Mirror: Off 16:28:20 INFO - Online: Yes 16:28:20 INFO - Rotation: Supported 16:28:20 INFO - Return code: 0 16:28:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 16:28:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 16:28:20 INFO - Python 2.7.3 16:28:20 INFO - Return code: 0 16:28:20 INFO - grabbing minidump binary from tooltool 16:28: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')]} 16:28:20 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 0x110390d30>, '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 0x1101b6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x110388b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 16:28:20 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 16:28:20 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 16:28:20 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 16:28:20 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 16:28:20 INFO - Return code: 0 16:28:20 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 16:28:20 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 16:28:20 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 16:28:20 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 16:28:20 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 16:28:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Larch', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/V6dbqvZfQiKZUCjcPPrl0g/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0007', '--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 16:28:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Larch --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/V6dbqvZfQiKZUCjcPPrl0g/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0007 --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 16:28:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rh6HAuXYyA/Render', 16:28:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:28:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:28:20 INFO - 'HOME': '/Users/cltbld', 16:28:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:28:20 INFO - 'LOGNAME': 'cltbld', 16:28:20 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:28:20 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 16:28:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:28:20 INFO - 'MOZ_NO_REMOTE': '1', 16:28:20 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:28:20 INFO - 'NO_EM_RESTART': '1', 16:28:20 INFO - 'PAGER': '/bin/cat', 16:28:20 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:28:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:28:20 INFO - 'PWD': '/builds/slave/test', 16:28:20 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:28:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:28:20 INFO - 'SHELL': '/bin/bash', 16:28:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yJtiG9O7a5/Listeners', 16:28:20 INFO - 'TMPDIR': '/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/', 16:28:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:28:20 INFO - 'USER': 'cltbld', 16:28:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:28:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:28:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:28:20 INFO - 'XPC_FLAGS': '0x0', 16:28:20 INFO - 'XPC_SERVICE_NAME': '0', 16:28:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:28:20 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Larch', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/V6dbqvZfQiKZUCjcPPrl0g/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0007', '--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 16:28:21 INFO - mozversion application_buildid: 20161017142049 16:28:21 INFO - mozversion application_changeset: 8c8266b3c23446275dc503366fd739ba3bc4834a 16:28:21 INFO - mozversion application_display_name: Nightly 16:28:21 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 16:28:21 INFO - mozversion application_name: Firefox 16:28:21 INFO - mozversion application_remotingname: firefox 16:28:21 INFO - mozversion application_repository: https://hg.mozilla.org/projects/larch 16:28:21 INFO - mozversion application_vendor: Mozilla 16:28:21 INFO - mozversion application_version: 52.0a1 16:28:21 INFO - mozversion platform_buildid: 20161017142049 16:28:21 INFO - mozversion platform_changeset: 8c8266b3c23446275dc503366fd739ba3bc4834a 16:28:21 INFO - mozversion platform_repository: https://hg.mozilla.org/projects/larch 16:28:21 INFO - mozversion platform_version: 52.0a1 16:28:21 INFO - using testdate: 1476746901 16:28:21 INFO - actual date: 1476746901 16:28:21 INFO - starting webserver on 'localhost:49287' 16:28:21 INFO - SUITE-START | Running 2 tests 16:28:21 INFO - TEST-START | dromaeo_css 16:28:21 INFO - operating with platform_type : mac_ 16:28:21 INFO - Initialising browser for dromaeo_css test... 16:28:21 INFO - TEST-INFO | started process 1664 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/tmpz8f2Zm/profile http://localhost:49287/getInfo.html) 16:28:23 INFO - PROCESS | 1664 | __metrics Screen width/height:1600/1200 16:28:23 INFO - PROCESS | 1664 | colorDepth:24 16:28:23 INFO - PROCESS | 1664 | Browser inner width/height: 1024/689 16:28:23 INFO - PROCESS | 1664 | __metrics 16:28:23 INFO - PROCESS | 1664 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 16:28:23 INFO - PROCESS | 1664 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 181: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 16:28:24 INFO - TEST-INFO | 1664: exit 0 16:28:24 INFO - Browser initialized. 16:28:24 INFO - Running cycle 1/1 for dromaeo_css test... 16:28:24 INFO - TEST-INFO | started process 1668 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/tmpz8f2Zm/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:28:36 INFO - PROCESS | 1668 | RSS: Main: 246120448 16:28:36 INFO - PROCESS | 1668 | 16:29:28 INFO - PROCESS | 1668 | 1476746968397 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] 16:31:40 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49287/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49287/tests/dromaeo/cssquery-ext.html) 16:31:43 INFO - PROCESS | 1668 | RSS: Main: 448077824 16:31:43 INFO - PROCESS | 1668 | 16:34:46 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49287/tests/dromaeo/cssquery-ext.html (next: http://localhost:49287/tests/dromaeo/cssquery-jquery.html) 16:34:47 INFO - PROCESS | 1668 | RSS: Main: 372224000 16:34:47 INFO - PROCESS | 1668 | 16:37:49 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49287/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49287/tests/dromaeo/cssquery-mootools.html) 16:37:50 INFO - PROCESS | 1668 | RSS: Main: 340492288 16:37:50 INFO - PROCESS | 1668 | 16:40:52 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49287/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49287/tests/dromaeo/cssquery-prototype.html) 16:40:53 INFO - PROCESS | 1668 | RSS: Main: 355115008 16:40:53 INFO - PROCESS | 1668 | 16:43:56 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49287/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49287/tests/dromaeo/cssquery-yui.html) 16:43:57 INFO - PROCESS | 1668 | RSS: Main: 340901888 16:43:57 INFO - PROCESS | 1668 | 16:47:00 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49287/tests/dromaeo/cssquery-yui.html (next: http://localhost:49287/tests/dromaeo/cssquery-dojo.html) 16:47:01 INFO - PROCESS | 1668 | RSS: Main: 383655936 16:47:01 INFO - PROCESS | 1668 | 16:47:01 INFO - PROCESS | 1668 | __start_tp_report 16:47:01 INFO - PROCESS | 1668 | _x_x_mozilla_page_load 16:47:01 INFO - PROCESS | 1668 | _x_x_mozilla_page_load_details 16:47:01 INFO - PROCESS | 1668 | |i|pagename|runs| 16:47:01 INFO - PROCESS | 1668 | |0;dojo.html;3323.5180898769677;3325.6674332566745;3365.697087262146;3378.7465940054503;3415.5047518109873;5530.6128571000045;5596.076647353182;5657.038303488406;5665.376808551061;5696.287964004499;5619.213310136574;5640.435956404358;5729.111987641912;5748.626339287944;5773.624714393562;5036.571891389236;5075.6447048706605;5106.183010718286;5154.407243167027;5173.068847607429;5212.1660534314515;5262.947370526284;5266.4733526647415;5275.182346736262;5335;5081.822136225252;5223.37319521656;5253.50091741284;5258.973705131488;5322.760475778599;5138.2549530318265;5190.636655434121;5193.714345711022;5193.948060519384;5197.376314842243;5070.492950704936;5149.922751158736;5156.123459011977;5163.8192663256605;5164.6384753067305;5090.465501122366;5606.131049687264;5607.57943154261;5628.831135065954;5634.3802181759975;5696.287964004499;6110.847228819271;6114.2357205349335;6124.540659450559;6130.080487926846;6101.267847858241;6158;6202.4417802398;6206.34833342497;6231.158793562901;5756.395578464285;5762.049261871786;5772.9711351442975;5784.161296611967;5828.388019258002;6340;6348.936510634927;6349.015902535114;6352.618842869442;6362.491000720024;5920.111983202468;5979.133025711248;5985.311689155716;6109.816705498843;6119.44924956756;6130.724117414727;6143.788236234952;6153.107799369066;6221.875562488724;6228.844278893064;6116.052011938156;6132.7546898124465;6139.723712432951;6145.139680444741;6147.569670123138;5970.850728731816;6151.446369826737;6154.5076393888385;6166.167567378437;6169.352218017133;4804.111239420718;4813.735244561512;4866.926996095061;4868.9756551217015;4878.975605121952;4278.636315913182;4415.359772832921;4431.977840110779;4436.134953684065;4444.266695995189;5695.373508914016;5698.487136157766;5706.771729130788;5708;5710.486056254957;6117.510599152057;6161.7227224775;6199.132121502993;6210.968945155336;6223;5609.962157000968;5683.516901063374;5684.317881854121;5714.657120572696;5730.885382292331;5323.760430780624;5328.307320048369;5332.360116786011;5342.572594192456;5347;6253.624782512973;6296.2759282681245;6304.275008374096;6305.306416294204;6333.081703153109;6268.404501572343;6287.811365659038;6296.212973378328;6302.558820882494;6318.210223722035;6340.841478962879;6341.0805433212845;6342.175517182921;6347.365263473615;6367.808965731036;5974.850628734142;6021.42796434338;6026.397360263939;6101.145839582373;6122.846928826815;6154.507639388928;6239.625622462667;6288.937110628835;6291.182146320949;6297.370262973666;6034.607750496204;6057.242844644419;6062.36345183767;6070.484008859296;6094.969525152524;4587.013792034729;5797.130430435468;6045.6070355426755;6049.485793707408;6097.32929377777;5612.494875461065;5750.6837123958585;5758.769649214152;5762.423757624204;5772.3650398456975;5872.383399742966;5893.557983151195;5899.233099697012;5906;5914.053751399755;5132.769025393941;5727.455891690282;5788.652680839163;5792.768289268382;5800.216970709069 16:47:01 INFO - PROCESS | 1668 | |1;ext.html;447.9652003871057;456.112860486354;485.6915858429896;514.3930162408357;554.5341912793256;28798;39876.40185397218;40765.79617101914;41065.17869642613;43195;42687;43054.923626909345;43076.784616076955;43095.3535696964;43185.568144318546;8449.23956843884;9378.343515953886;9558.426494410323;9566.23470122392;9566.617335306579;8513.74458766238;11331;11660.708482287926;11682.182247242696;11752.118591105658;11503.252288601256;11536;11580.073594112493;11594.47824847884;11674;8567.357448191347;8782.25350845179;11361.204715669906;11460.140489463323;11480.483378247998;11114.11087113029;11453.942730286379;11461.197716159872;11531.942340288246;11582.18924675273;39921.40117898234;40133.79933100345;40178.39732404016;40198.79900600507;40307.79846100751;3237.27161388688;3267.2648654052887;3475.669811367916;3658.798766067865;3923.019245188703;29769.958051468093;30600.2349941251;30649.38701225963;31203.063908082568;31675.524867127013;3186.6972637599624;3201.295714942732;3210.5826242588546;3214.100051985448;3258.4949332853207;488010;488309;489104;489770;496680.5165974183;57095;57596;57672.423275766705;57737;57792;34367.828160859535;34500.30999379999;35013.12467188341;36026.63973360285;61497.38502614917;15670.216489175496;15745.68508629821;15766.975146615434;15793.842061579237;15802;10793.460326983619;11010.50452729629;11047.613333533287;11939.88060119405;12067.094967877445;16559.91720041392;16631;16682.165891705365;18501.53746156357;18909.43271701851;7418;7467.813304667318;7551.207123252089;7639.288052741308;7647.388208943271;739.6153999920011;774.2837874965634;844.6748002019267;929.1034152043176;1061.4056128567872;28198.43603127926;29724.256893577833;29733.702662973526;30543.2364190897;30600.54099188514;896.8833801916655;972.6304004478253;1033.1631378583222;1104.9558017679203;1150.9194356394971;15257.313420895864;15297.923510382378;15446.995945263521;15526.146061966474;15809.367625294859;662448;663730;666497;667206;667732;56450;57071.42928570744;57206.7139664299;57251.42748572461;57345.13982290269;1216.3492531495608;1252.7118762684452;1257.6289994451658;1353.363918958088;1369.1716511510592;29426.97005604879;30844.537331940486;31029.534556982115;31804.363912722078;32495.878733274385;49241.26138108003;49486;49788.0254369478;49934.75032624813;49952;488465.55767220934;488676.7830804258;491815;492049;493622;25310.493790124463;25699.35751606225;25711;26172.953081876512;26702.732972670026;32780.836095819366;46075.3088703663;46828.29757553571;46879.06241875212;47321;37972.24055518819;38670;38703.61296387;39263.803680981415;39596.802015989735;38387.6161238384;38553.614463855;39252;39591.208175836895;39931.20137597174;37240.255194895406;37674.86975390743;37811.05472363213;38041;38376.6162338373;25980.480390392466;32414.865479709042;34682.13294667654;34762.82618586891;34912.47631285583;24310.756892431556;24759.50480990406;25354;25422.983080676564;25514.96540309924 16:47:01 INFO - PROCESS | 1668 | |2;jquery.html;345.4524578543009;353.08726940857883;367.5754503548401;367.7863051928635;374.69275194340634;13113.67215819605;14489.840812734983;16141.677166456679;16875.662486750258;20844;19334.129964151613;19412.029398530052;20471.283505077325;20719.689204661943;20756.377308680763;5745.827625171251;6091.238595175603;6092.482139018167;6097;6100.450959413652;5116.744162791845;5890.322612899528;6614.801555953329;6670.866582668344;6747.12287402637;5588.972055139739;6635.734570617194;6669.9666501667425;6677.332266773333;6694.564853284545;5834.620749651281;5989.071693887455;5997.640141591519;6000.909986350208;6008.068749343859;5565.026120428949;5715.199872017921;5775.537956963432;5786.508146807527;5790.7683692652245;13097.869021309856;13162.60512184636;13277.800832987514;13364.198148111145;13364.465421383133;1803.9458816235533;2097.454661787931;2130.1053557505884;2272.0230300970575;2399.748026457232;9388.671396501159;9778.559964801601;10201.591936322538;10281.948590257001;12787.552435664707;1793.6233390987777;1833.404143653318;1833.62410705805;1861.4322631597358;1892.8350451676772;102681.1648543932;112648;125630.37184814109;126101;126635;11002.669919902415;11132.944335278271;11515.193936424537;11563.88436115645;11762.70593235176;14590.781138282935;14608.926955365368;14619.78070328946;14621.78067328991;14628.19544925018;8052.718154864551;8364.247217750431;8376.371772117116;8490.575471226413;8565;8123.796905077302;8132.471389359673;8168.7957801055445;8295.8340833183;8310.21052999964;4440.444944381952;4524.434445694288;4539.296409056601;4604.907901841944;4617.168909596305;1765.7263124215767;1843.6681397348607;1898.7911329753715;1908.9809101909082;1910;719.5143278287135;803.4375936844116;827.9420440569223;862.7847902243973;952.4380615436787;19210.711839322423;20696;20866.859566372263;20946;20998.790012099988;654.9738010479527;836.9497830130211;876.2595606712318;915.1397686174975;937.465644582595;9980;10041.849372259421;10887.564497420015;10912.454377281105;10939.28894621863;595335;600096;601710;604257.5316715158;611631;68710.28224294333;72874.9068763969;100837;121926;141232;966.0773960867355;967.1054274795786;1003.9799204015878;1004.8492726091145;1005.9446730429896;14879.776803348175;15094.54716358511;19147.329843455413;19519.707204391663;19680.704789427884;54040.729796350766;54167;55038.724806375714;57436.71281643732;57633;56403;76059;77225.61387193028;77980;78000.60999694966;7887.64505597238;7945.761627151194;8067.072286686871;8171.142030086755;8189.50862948225;11134.164937629872;11281.548738050386;11671;12420.565280215149;12528.624141275777;10727.839082413926;10803.621873234399;11015;11138.33170009802;11175.944120279346;10586.576536938435;10699.679009629723;11029.61396351124;11113.055390291916;11222.49498772541;11133.55465781392;11286.49210785533;11313.71715707114;11313.943430282796;11364.431778411212;7631;8091;8212.055613604272;8215.671373145246;8216.876746848682;9654.75863103428;10854.511546980562;11101.5559377624;11130.220884538328;11147.498362573866 16:47:01 INFO - PROCESS | 1668 | |3;mootools.html;1488.315374927533;1504.7441934871072;1682.3102527963538;1829.5517598188428;1839.7608310919586;7846.843063138734;8128.4310098293145;8419.536925469096;8917.955410222949;8919.687810926618;7794.337481314081;7874.212578742123;7894.763157105296;8500.107488713673;8746.475211487299;2310.9191178308806;2342.9297121086393;2344.800691941187;2345.2729653807287;2348.635961425982;2208.61349263879;2229.1752051740796;2256.8194544436406;2261.7398999115067;2268.920587779428;2324.721033475985;2341.098677009347;2356.799672027871;2360.5278944211227;2363.633636786301;2320.9419764505856;2321.6749655048307;2327.4297797039835;2332.5421611492734;2332.9650055249185;2214.5017371091535;2216.357256395643;2218.401031721426;2219.911203551856;2224.265992222579;8744.125587441205;8824.338174636929;8867.911320886773;8869.73390798277;8881.822363552757;5163.483651634844;5327.573794096463;5338.252644629755;5339.893202135974;5342.946570534284;3439.2949445363643;3513.54323937889;3660.8535658573624;3666.5233519642275;3679.8344074516713;1041.265907535186;1062.1184416933927;1077.8167711488986;1080.3578560587862;1103.9834402483968;11697;11747.236429632047;11756.70608234801;11760.235584687054;11764.647060588197;4510.977445112753;4576.55154492345;4789.616830653539;4794.544518270759;4797.4482934462985;10040.849387259197;10056.64801731936;10072.848907266396;10079.143272821892;10082.29423940317;5338.54622357104;5345.984262990097;5349.117395629755;5352.9732351338;5360.91958620621;4332.978335108304;4443.822247110144;4505.098980203907;4844.903101937942;4888.364512613409;2941.9411611767646;3360.4119279126057;3829.8659546915724;3875.3605655067163;3970.722049456568;1232.6610182199968;1257.4844313831286;1258.6601617563206;1273.1660762200772;1273.9681507962189;799.8040480082417;871.6034204436966;885.5041176940933;899.4917871402637;900.5812297281665;5649.717514124277;5777.335606405317;5863.296404431496;5887.587868849139;6171.043488259327;426.09242313871255;430.4038906114991;433.80695590461943;443.82690750607287;446.2770312094363;3936.3505021671676;3957.920841583152;3991.720579559461;4017.578154293757;4028.778417187083;10178.898211017944;10646.574137034431;10694.572217111385;10793.62222322215;10813.405262710627;10806.405647689453;10822.18833587484;10823.242373033812;10832.78334433307;10835.295705779099;7966.0440747110715;8222.876656850152;8234.217749313806;8249.876251856227;8256.339492840558;5709.22925405065;5856.267966504187;6008.309044459768;6133.110698948717;6170.444659980623;9664.226861851175;9978.700638980843;9999.500024998575;10083.798324033625;10135.290529663147;8646.870296945424;10359.378437293786;10363.792724145625;10371.844422333821;10406.843897341394;7006.493603875191;7254.419646428379;7369.115706115301;7494.850102998019;7556.206598307099;8262.586870656563;8443.155684431507;8505.06444291115;8506.02180749197;8506.276966457674;7344.596047217455;7349.052501087323;7526.623668816428;7610.543367397752;7618.885716714143;7182.102237220348;7342.3758980487255;7395.963020184865;7401.592912389655;7428.368588669808;6980.616066116412;7119.2524784898915;7124.821879453055;7139.32176443237;7143.249958754256;4715.151272770797;4917.116595642712;5043.016611760818;5073.847784566469;5134.178531434953;4849.4908034657265;4966.850994470172;5021.974890125526;5093.541581257705;5160.380754309507 16:47:01 INFO - PROCESS | 1668 | |4;prototype.html;299.55665614889966;302.9091272618214;309.4738943795547;315.6575115999141;318.5922019814638;4211.925958880489;4925.43357513886;5343.759530821113;5357.249985002098;5444.96545656325;4510.34599983003;4601.33280674302;5106.412762532301;5201.687898726079;5381.461853814617;3368.797872127668;3945.270125026879;4041.312976793952;4129.277376459123;4149.149424368013;3400.387930172568;3989.3842993587646;4110.568390319018;4131.7934103294865;4170.871846115413;3899.922001559967;3988.441618173458;4103.671706263507;4119.464469618945;4143.544210136883;3392.423288041039;4117.608827161415;4121.278776214151;4142.91714165718;4160.791960401983;2958.852057397143;3576.678098971105;3583.605803361628;3592.760497628314;3598.298331825295;4366.607005369532;5126.948730512684;5298.576113910878;5310.389305229909;5363.659085228693;4048.291548978946;4286.357046443059;4430.357598148283;4670.949036466802;4765.165411316633;4754.572088512016;5688.203651488795;5721.570882183812;5797.536197104221;5800.390958949291;3466.757326987112;3723.8696645617542;3960.980195099006;3979.820908059144;3996.220736956295;5006.599472042228;5694.402087780765;5863.5309175265875;5952.672603006833;5981.581289309706;3345.6821601947777;3563.3585954528435;4028.315186418316;4036.414719865662;4060.167665628539;3677.852885884588;4066.6543343815515;4136.6483848872585;4140.585941405835;4142.813573389205;3692.5205702849166;4250.213710463512;4510.548945105464;4714.811407543729;4757.572728181532;5038.3198268234055;5126.051680439131;5337.037943941073;5646.2942132233475;5705.686187259657;1194.7610477904454;1246.956356527517;1350.2438634364592;1403.2773121842056;1406.2336026865382;878.9121087891288;890.9688160914337;904.3759805734022;906.9455832650062;956.0964888180603;4447.911041779146;4895.6817806843155;5286.365636123613;5303.893922121613;5402.540784033323;4903.891326729469;5055.646104772704;5201.895962080737;5491.172046396109;5809.506191973645;4686.179918514246;5262.210668399771;5275.235090911873;5275.920861187085;5304.655197412156;2401.7598240176194;3023.9244018899703;3030.5605687175234;3035.4839677254918;3035.9089227323216;5630.915536266959;5906.173135760997;5933.525317974638;5940.287165540077;5954.166416701665;5835.374081663269;5836.649801011953;5897.174395584621;5914.615549989237;5918.674472904032;5954.761809527657;5957.3744756799915;5959.553033522417;5971.820845374733;5996.340402555803;5269.604779641466;5532.197831314517;5725.683092888576;5747.683877386617;5769.365369809234;4767.76161191946;5180.197069454241;5642.717864106696;5708.257926469699;5720.771169153188;5648.435156484319;5940.198073260054;5971.970140149271;5974.2830860297045;6005.489533389711;3945.678197803768;3969.80150992455;3973.980130099331;3980;4025.335819589735;4591.3572099906705;4811.566958973709;5665.512987629059;5748.338941021669;5754.338251101177;5080.796768129381;5090.49095090488;5228.555572776357;5304.336957880265;5514.972425137848;5478.863028424162;5588.30146231721;5599.32808063035;5601.059443792042;5608.46719561641;5309.33633295838;5464.235007099089;5465.918011229755;5468.48049435303;5500.1749737539685;5188.766505507186;5530.446955304437;5581.888362232651;5630.7466164023535;5650.23721797552;4624.791884365144;4686;5112.3415823772175;5582.8325150245555;5583.190437386637 16:47:01 INFO - PROCESS | 1668 | |5;yui.html;675.1088563096713;695.3567949646575;738.3760722189751;760.551274747899;802.374148164432;14677;15091.773623395658;15530;15889.602759931007;16205.35178592855;13999.300034998234;14115.153090814558;14137.858621413809;14149.71700565988;14153.080049796781;808.05861171735;832.9292010179142;836.2849763452252;836.4103307168432;837.049948308671;665.2981104934292;687.086175386737;688.1432616392573;688.8381230410874;690.0856365315951;720.099875156055;864.1013346120028;891.0332289465937;893.1604291965532;901.0898992018076;880.295763389286;880.6565439478609;883.4037025008158;885.234272354412;885.5616469847403;650.1418128070949;653.7025653327725;654.201873714068;654.2247436787375;654.2933631677777;11836.526538938431;12423.565175218824;12442.56451024219;12599.37003149848;12617.369131543386;12298.139130260884;14081.225532595703;14127.434902603884;14378.209198494078;14519.128852268897;11437.334365601666;11734.647960561197;11737.882621173765;11985.340806255652;12055.698607534794;3765.1904840459433;3791.298609757204;3823.7323387362894;3858.3826587745825;3937.3503371943593;95763.04236957681;98980;110390.44804776004;110719.44640276827;112850.87149128485;10058.541511480795;10923.453827308604;10932.672019839418;11125.165612579089;11423.086153107732;20879.26922557703;21420.892895535733;21635.783642163693;21661.67507487389;21672;7772;7859;7917.247861453153;7928.722494712657;8040.597970101471;3698.7041036716996;4311.568843115663;4411.779411029435;4445.710743884238;4547.590716835501;3513.3500302443617;3617.2881288447015;3631.6005239423644;3655.592596850232;3670.541182352206;587.811900191943;660.2143449295323;661.5501459007825;672.1430176524872;672.7275453441364;554.5314209492972;626.4862812493802;659.6938901664089;666.7599664120912;667.3326673326674;9203.769905752275;9344.813103737888;9647.469389183521;10047.357604519366;10301;682.689376333767;686.2828344380134;692.2247083266774;693.7398475571661;694.2952902803659;4275.636570891509;4627.45450101991;4670.462896766915;4705.7647117644665;4786.282057691375;135539;146932;155328;156664.21667891816;162764;112541;130340.3482982579;134451.32774336066;137090;137363.31318343544;77056;82070;108971.45514272377;113561;113651;8944;8954.865677014981;8962.641494340154;8981.326400520118;9105.681301154427;59689.403105968384;61770.38229617646;62931.685341574834;63165.68417157885;65507;91098.63352049592;95813.5209323949;97835.02164978259;98241.50879245558;99477;11773.470193841127;11925.986291165002;12150.513979440724;12267.325297108744;12335.50657973671;13033.804492932424;13257;13350.532731354357;13440.865591344353;13664.65838354049;8694.652213911626;11722.706932326419;12407.75184496287;12415.689607759878;12743.681407964505;11964.880351196376;12251.816222756845;12484.687882803002;12580;12587.307698076327;11818.349990750246;12064.879351206726;12365.07261955339;12418.192817466836;12657.367131643565;9958.701238962552;10122.331016269396;10286.794264114826;10352.948235259077;10387;8776.692815751418;10321.432321222104;10481.37111773296;10642.62750803749;10762.08522275584 16:47:01 INFO - PROCESS | 1668 | __end_tp_report 16:47:01 INFO - PROCESS | 1668 | __start_cc_report 16:47:01 INFO - PROCESS | 1668 | _x_x_mozilla_cycle_collect,2824 16:47:01 INFO - PROCESS | 1668 | __end_cc_report 16:47:01 INFO - PROCESS | 1668 | __startTimestamp1476748021224__endTimestamp 16:47:01 INFO - PROCESS | 1668 | 16:47:01 INFO - PROCESS | 1668 | ------- Summary: start ------- 16:47:01 INFO - PROCESS | 1668 | Number of tests: 6 16:47:01 INFO - PROCESS | 1668 | 16:47:01 INFO - PROCESS | 1668 | [#0] dojo.html Cycles:165 Average:5678.66 Median:5784.16 stddev:637.83 (11.0%) stddev-sans-first:612.44 16:47:01 INFO - PROCESS | 1668 | Values: 3323.5 3325.7 3365.7 3378.7 3415.5 5530.6 5596.1 5657.0 5665.4 5696.3 5619.2 5640.4 5729.1 5748.6 5773.6 5036.6 5075.6 5106.2 5154.4 5173.1 5212.2 5262.9 5266.5 5275.2 5335.0 5081.8 5223.4 5253.5 5259.0 5322.8 5138.3 5190.6 5193.7 5193.9 5197.4 5070.5 5149.9 5156.1 5163.8 5164.6 5090.5 5606.1 5607.6 5628.8 5634.4 5696.3 6110.8 6114.2 6124.5 6130.1 6101.3 6158.0 6202.4 6206.3 6231.2 5756.4 5762.0 5773.0 5784.2 5828.4 6340.0 6348.9 6349.0 6352.6 6362.5 5920.1 5979.1 5985.3 6109.8 6119.4 6130.7 6143.8 6153.1 6221.9 6228.8 6116.1 6132.8 6139.7 6145.1 6147.6 5970.9 6151.4 6154.5 6166.2 6169.4 4804.1 4813.7 4866.9 4869.0 4879.0 4278.6 4415.4 4432.0 4436.1 4444.3 5695.4 5698.5 5706.8 5708.0 5710.5 6117.5 6161.7 6199.1 6211.0 6223.0 5610.0 5683.5 5684.3 5714.7 5730.9 5323.8 5328.3 5332.4 5342.6 5347.0 6253.6 6296.3 6304.3 6305.3 6333.1 6268.4 6287.8 6296.2 6302.6 6318.2 6340.8 6341.1 6342.2 6347.4 6367.8 5974.9 6021.4 6026.4 6101.1 6122.8 6154.5 6239.6 6288.9 6291.2 6297.4 6034.6 6057.2 6062.4 6070.5 6095.0 4587.0 5797.1 6045.6 6049.5 6097.3 5612.5 5750.7 5758.8 5762.4 5772.4 5872.4 5893.6 5899.2 5906.0 5914.1 5132.8 5727.5 5788.7 5792.8 5800.2 16:47:01 INFO - PROCESS | 1668 | 16:47:01 INFO - PROCESS | 1668 | [#1] ext.html Cycles:180 Average:67776.74 Median:20449.53 stddev:148362.48 (725.5%) stddev-sans-first:148692.55 16:47:01 INFO - PROCESS | 1668 | Values: 448.0 456.1 485.7 514.4 554.5 28798.0 39876.4 40765.8 41065.2 43195.0 42687.0 43054.9 43076.8 43095.4 43185.6 8449.2 9378.3 9558.4 9566.2 9566.6 8513.7 11331.0 11660.7 11682.2 11752.1 11503.3 11536.0 11580.1 11594.5 11674.0 8567.4 8782.3 11361.2 11460.1 11480.5 11114.1 11453.9 11461.2 11531.9 11582.2 39921.4 40133.8 40178.4 40198.8 40307.8 3237.3 3267.3 3475.7 3658.8 3923.0 29770.0 30600.2 30649.4 31203.1 31675.5 3186.7 3201.3 3210.6 3214.1 3258.5 488010.0 488309.0 489104.0 489770.0 496680.5 57095.0 57596.0 57672.4 57737.0 57792.0 34367.8 34500.3 35013.1 36026.6 61497.4 15670.2 15745.7 15767.0 15793.8 15802.0 10793.5 11010.5 11047.6 11939.9 12067.1 16559.9 16631.0 16682.2 18501.5 18909.4 7418.0 7467.8 7551.2 7639.3 7647.4 739.6 774.3 844.7 929.1 1061.4 28198.4 29724.3 29733.7 30543.2 30600.5 896.9 972.6 1033.2 1105.0 1150.9 15257.3 15297.9 15447.0 15526.1 15809.4 662448.0 663730.0 666497.0 667206.0 667732.0 56450.0 57071.4 57206.7 57251.4 57345.1 1216.3 1252.7 1257.6 1353.4 1369.2 29427.0 30844.5 31029.5 31804.4 32495.9 49241.3 49486.0 49788.0 49934.8 49952.0 488465.6 488676.8 491815.0 492049.0 493622.0 25310.5 25699.4 25711.0 26173.0 26702.7 32780.8 46075.3 46828.3 46879.1 47321.0 37972.2 38670.0 38703.6 39263.8 39596.8 38387.6 38553.6 39252.0 39591.2 39931.2 37240.3 37674.9 37811.1 38041.0 38376.6 25980.5 32414.9 34682.1 34762.8 34912.5 24310.8 24759.5 25354.0 25423.0 25515.0 16:47:01 INFO - PROCESS | 1668 | 16:47:01 INFO - PROCESS | 1668 | [#2] jquery.html Cycles:180 Average:33846.50 Median:2335.89 stddev:100126.41 (4286.4%) stddev-sans-first:100375.70 16:47:01 INFO - PROCESS | 1668 | Values: 345.5 353.1 367.6 367.8 374.7 13113.7 14489.8 16141.7 16875.7 20844.0 19334.1 19412.0 20471.3 20719.7 20756.4 5745.8 6091.2 6092.5 6097.0 6100.5 5116.7 5890.3 6614.8 6670.9 6747.1 5589.0 6635.7 6670.0 6677.3 6694.6 5834.6 5989.1 5997.6 6000.9 6008.1 5565.0 5715.2 5775.5 5786.5 5790.8 13097.9 13162.6 13277.8 13364.2 13364.5 1803.9 2097.5 2130.1 2272.0 2399.7 9388.7 9778.6 10201.6 10281.9 12787.6 1793.6 1833.4 1833.6 1861.4 1892.8 102681.2 112648.0 125630.4 126101.0 126635.0 11002.7 11132.9 11515.2 11563.9 11762.7 14590.8 14608.9 14619.8 14621.8 14628.2 8052.7 8364.2 8376.4 8490.6 8565.0 8123.8 8132.5 8168.8 8295.8 8310.2 4440.4 4524.4 4539.3 4604.9 4617.2 1765.7 1843.7 1898.8 1909.0 1910.0 719.5 803.4 827.9 862.8 952.4 19210.7 20696.0 20866.9 20946.0 20998.8 655.0 836.9 876.3 915.1 937.5 9980.0 10041.8 10887.6 10912.5 10939.3 595335.0 600096.0 601710.0 604257.5 611631.0 68710.3 72874.9 100837.0 121926.0 141232.0 966.1 967.1 1004.0 1004.8 1005.9 14879.8 15094.5 19147.3 19519.7 19680.7 54040.7 54167.0 55038.7 57436.7 57633.0 56403.0 76059.0 77225.6 77980.0 78000.6 7887.6 7945.8 8067.1 8171.1 8189.5 11134.2 11281.5 11671.0 12420.6 12528.6 10727.8 10803.6 11015.0 11138.3 11175.9 10586.6 10699.7 11029.6 11113.1 11222.5 11133.6 11286.5 11313.7 11313.9 11364.4 7631.0 8091.0 8212.1 8215.7 8216.9 9654.8 10854.5 11101.6 11130.2 11147.5 16:47:01 INFO - PROCESS | 1668 | 16:47:01 INFO - PROCESS | 1668 | [#3] mootools.html Cycles:180 Average:5676.89 Median:4645.85 stddev:3231.83 (69.6%) stddev-sans-first:3225.57 16:47:01 INFO - PROCESS | 1668 | Values: 1488.3 1504.7 1682.3 1829.6 1839.8 7846.8 8128.4 8419.5 8918.0 8919.7 7794.3 7874.2 7894.8 8500.1 8746.5 2310.9 2342.9 2344.8 2345.3 2348.6 2208.6 2229.2 2256.8 2261.7 2268.9 2324.7 2341.1 2356.8 2360.5 2363.6 2320.9 2321.7 2327.4 2332.5 2333.0 2214.5 2216.4 2218.4 2219.9 2224.3 8744.1 8824.3 8867.9 8869.7 8881.8 5163.5 5327.6 5338.3 5339.9 5342.9 3439.3 3513.5 3660.9 3666.5 3679.8 1041.3 1062.1 1077.8 1080.4 1104.0 11697.0 11747.2 11756.7 11760.2 11764.6 4511.0 4576.6 4789.6 4794.5 4797.4 10040.8 10056.6 10072.8 10079.1 10082.3 5338.5 5346.0 5349.1 5353.0 5360.9 4333.0 4443.8 4505.1 4844.9 4888.4 2941.9 3360.4 3829.9 3875.4 3970.7 1232.7 1257.5 1258.7 1273.2 1274.0 799.8 871.6 885.5 899.5 900.6 5649.7 5777.3 5863.3 5887.6 6171.0 426.1 430.4 433.8 443.8 446.3 3936.4 3957.9 3991.7 4017.6 4028.8 10178.9 10646.6 10694.6 10793.6 10813.4 10806.4 10822.2 10823.2 10832.8 10835.3 7966.0 8222.9 8234.2 8249.9 8256.3 5709.2 5856.3 6008.3 6133.1 6170.4 9664.2 9978.7 9999.5 10083.8 10135.3 8646.9 10359.4 10363.8 10371.8 10406.8 7006.5 7254.4 7369.1 7494.9 7556.2 8262.6 8443.2 8505.1 8506.0 8506.3 7344.6 7349.1 7526.6 7610.5 7618.9 7182.1 7342.4 7396.0 7401.6 7428.4 6980.6 7119.3 7124.8 7139.3 7143.2 4715.2 4917.1 5043.0 5073.8 5134.2 4849.5 4966.9 5022.0 5093.5 5160.4 16:47:01 INFO - PROCESS | 1668 | 16:47:01 INFO - PROCESS | 1668 | [#4] prototype.html Cycles:180 Average:4539.02 Median:5109.38 stddev:1393.13 (27.3%) stddev-sans-first:1360.21 16:47:01 INFO - PROCESS | 1668 | Values: 299.6 302.9 309.5 315.7 318.6 4211.9 4925.4 5343.8 5357.2 5445.0 4510.3 4601.3 5106.4 5201.7 5381.5 3368.8 3945.3 4041.3 4129.3 4149.1 3400.4 3989.4 4110.6 4131.8 4170.9 3899.9 3988.4 4103.7 4119.5 4143.5 3392.4 4117.6 4121.3 4142.9 4160.8 2958.9 3576.7 3583.6 3592.8 3598.3 4366.6 5126.9 5298.6 5310.4 5363.7 4048.3 4286.4 4430.4 4670.9 4765.2 4754.6 5688.2 5721.6 5797.5 5800.4 3466.8 3723.9 3961.0 3979.8 3996.2 5006.6 5694.4 5863.5 5952.7 5981.6 3345.7 3563.4 4028.3 4036.4 4060.2 3677.9 4066.7 4136.6 4140.6 4142.8 3692.5 4250.2 4510.5 4714.8 4757.6 5038.3 5126.1 5337.0 5646.3 5705.7 1194.8 1247.0 1350.2 1403.3 1406.2 878.9 891.0 904.4 906.9 956.1 4447.9 4895.7 5286.4 5303.9 5402.5 4903.9 5055.6 5201.9 5491.2 5809.5 4686.2 5262.2 5275.2 5275.9 5304.7 2401.8 3023.9 3030.6 3035.5 3035.9 5630.9 5906.2 5933.5 5940.3 5954.2 5835.4 5836.6 5897.2 5914.6 5918.7 5954.8 5957.4 5959.6 5971.8 5996.3 5269.6 5532.2 5725.7 5747.7 5769.4 4767.8 5180.2 5642.7 5708.3 5720.8 5648.4 5940.2 5972.0 5974.3 6005.5 3945.7 3969.8 3974.0 3980.0 4025.3 4591.4 4811.6 5665.5 5748.3 5754.3 5080.8 5090.5 5228.6 5304.3 5515.0 5478.9 5588.3 5599.3 5601.1 5608.5 5309.3 5464.2 5465.9 5468.5 5500.2 5188.8 5530.4 5581.9 5630.7 5650.2 4624.8 4686.0 5112.3 5582.8 5583.2 16:47:01 INFO - PROCESS | 1668 | 16:47:01 INFO - PROCESS | 1668 | [#5] yui.html Cycles:180 Average:24371.16 Median:3731.95 stddev:39569.70 (1060.3%) stddev-sans-first:39640.70 16:47:01 INFO - PROCESS | 1668 | Values: 675.1 695.4 738.4 760.6 802.4 14677.0 15091.8 15530.0 15889.6 16205.4 13999.3 14115.2 14137.9 14149.7 14153.1 808.1 832.9 836.3 836.4 837.0 665.3 687.1 688.1 688.8 690.1 720.1 864.1 891.0 893.2 901.1 880.3 880.7 883.4 885.2 885.6 650.1 653.7 654.2 654.2 654.3 11836.5 12423.6 12442.6 12599.4 12617.4 12298.1 14081.2 14127.4 14378.2 14519.1 11437.3 11734.6 11737.9 11985.3 12055.7 3765.2 3791.3 3823.7 3858.4 3937.4 95763.0 98980.0 110390.4 110719.4 112850.9 10058.5 10923.5 10932.7 11125.2 11423.1 20879.3 21420.9 21635.8 21661.7 21672.0 7772.0 7859.0 7917.2 7928.7 8040.6 3698.7 4311.6 4411.8 4445.7 4547.6 3513.4 3617.3 3631.6 3655.6 3670.5 587.8 660.2 661.6 672.1 672.7 554.5 626.5 659.7 666.8 667.3 9203.8 9344.8 9647.5 10047.4 10301.0 682.7 686.3 692.2 693.7 694.3 4275.6 4627.5 4670.5 4705.8 4786.3 135539.0 146932.0 155328.0 156664.2 162764.0 112541.0 130340.3 134451.3 137090.0 137363.3 77056.0 82070.0 108971.5 113561.0 113651.0 8944.0 8954.9 8962.6 8981.3 9105.7 59689.4 61770.4 62931.7 63165.7 65507.0 91098.6 95813.5 97835.0 98241.5 99477.0 11773.5 11926.0 12150.5 12267.3 12335.5 13033.8 13257.0 13350.5 13440.9 13664.7 8694.7 11722.7 12407.8 12415.7 12743.7 11964.9 12251.8 12484.7 12580.0 12587.3 11818.3 12064.9 12365.1 12418.2 12657.4 9958.7 10122.3 10286.8 10352.9 10387.0 8776.7 10321.4 10481.4 10642.6 10762.1 16:47:01 INFO - PROCESS | 1668 | -------- Summary: end -------- 16:47:01 INFO - PROCESS | 1668 | 16:47:01 INFO - TEST-INFO | 1668: exit 0 16:47:01 INFO - TEST-OK | dromaeo_css | took 1120686ms 16:47:01 INFO - TEST-START | kraken 16:47:01 INFO - operating with platform_type : mac_ 16:47:01 INFO - Initialising browser for kraken test... 16:47:01 INFO - TEST-INFO | started process 1674 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/tmpyr4Cem/profile http://localhost:49287/getInfo.html) 16:47:04 INFO - PROCESS | 1674 | __metrics Screen width/height:1600/1200 16:47:04 INFO - PROCESS | 1674 | colorDepth:24 16:47:04 INFO - PROCESS | 1674 | Browser inner width/height: 1024/689 16:47:04 INFO - PROCESS | 1674 | __metrics 16:47:04 INFO - PROCESS | 1674 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 16:47:04 INFO - PROCESS | 1674 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 181: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 16:47:04 INFO - TEST-INFO | 1674: exit 0 16:47:04 INFO - Browser initialized. 16:47:04 INFO - Running cycle 1/1 for kraken test... 16:47:04 INFO - TEST-INFO | started process 1675 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/tmpyr4Cem/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:47:16 INFO - PROCESS | 1675 | RSS: Main: 246226944 16:47:16 INFO - PROCESS | 1675 | 16:47:19 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49287/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49287/tests/kraken/driver.html?testName=audio-beat-detection) 16:47:20 INFO - PROCESS | 1675 | RSS: Main: 295788544 16:47:20 INFO - PROCESS | 1675 | 16:47:23 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49287/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49287/tests/kraken/driver.html?testName=audio-dft) 16:47:23 INFO - PROCESS | 1675 | RSS: Main: 295718912 16:47:23 INFO - PROCESS | 1675 | 16:47:27 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49287/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49287/tests/kraken/driver.html?testName=audio-fft) 16:47:27 INFO - PROCESS | 1675 | RSS: Main: 386899968 16:47:27 INFO - PROCESS | 1675 | 16:47:29 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49287/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49287/tests/kraken/driver.html?testName=audio-oscillator) 16:47:30 INFO - PROCESS | 1675 | RSS: Main: 288481280 16:47:30 INFO - PROCESS | 1675 | 16:47:32 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49287/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49287/tests/kraken/driver.html?testName=imaging-gaussian-blur) 16:47:33 INFO - PROCESS | 1675 | RSS: Main: 291561472 16:47:33 INFO - PROCESS | 1675 | 16:47:35 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49287/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49287/tests/kraken/driver.html?testName=imaging-darkroom) 16:47:36 INFO - PROCESS | 1675 | RSS: Main: 329314304 16:47:36 INFO - PROCESS | 1675 | 16:47:39 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49287/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49287/tests/kraken/driver.html?testName=imaging-desaturate) 16:47:40 INFO - PROCESS | 1675 | RSS: Main: 330829824 16:47:40 INFO - PROCESS | 1675 | 16:47:42 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49287/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49287/tests/kraken/driver.html?testName=json-parse-financial) 16:47:43 INFO - PROCESS | 1675 | RSS: Main: 328511488 16:47:43 INFO - PROCESS | 1675 | 16:47:45 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49287/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49287/tests/kraken/driver.html?testName=json-stringify-tinderbox) 16:47:45 INFO - PROCESS | 1675 | RSS: Main: 302161920 16:47:45 INFO - PROCESS | 1675 | 16:47:47 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49287/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49287/tests/kraken/driver.html?testName=stanford-crypto-aes) 16:47:48 INFO - PROCESS | 1675 | RSS: Main: 304521216 16:47:48 INFO - PROCESS | 1675 | 16:47:50 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49287/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49287/tests/kraken/driver.html?testName=stanford-crypto-ccm) 16:47:51 INFO - PROCESS | 1675 | RSS: Main: 314130432 16:47:51 INFO - PROCESS | 1675 | 16:47:54 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49287/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49287/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 16:47:54 INFO - PROCESS | 1675 | RSS: Main: 322465792 16:47:54 INFO - PROCESS | 1675 | 16:47:57 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49287/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49287/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 16:47:58 INFO - PROCESS | 1675 | RSS: Main: 322236416 16:47:58 INFO - PROCESS | 1675 | 16:48:00 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49287/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49287/tests/kraken/driver.html?testName=ai-astar) 16:48:00 INFO - PROCESS | 1675 | RSS: Main: 365813760 16:48:00 INFO - PROCESS | 1675 | 16:48:00 INFO - PROCESS | 1675 | __start_tp_report 16:48:00 INFO - PROCESS | 1675 | _x_x_mozilla_page_load 16:48:00 INFO - PROCESS | 1675 | _x_x_mozilla_page_load_details 16:48:00 INFO - PROCESS | 1675 | |i|pagename|runs| 16:48:00 INFO - PROCESS | 1675 | |0;ai-astar;112;113;113;112;113;113;110;113;112;113 16:48:00 INFO - PROCESS | 1675 | |1;audio-beat-detection;146;115;114;151;114;112;112;113;145;114 16:48:00 INFO - PROCESS | 1675 | |2;audio-dft;189;187;183;188;185;184;182;184;184;184 16:48:00 INFO - PROCESS | 1675 | |3;audio-fft;79;59;61;90;60;60;59;60;98;81 16:48:00 INFO - PROCESS | 1675 | |4;audio-oscillator;88;74;76;71;86;100;124;83;69;91 16:48:00 INFO - PROCESS | 1675 | |5;imaging-gaussian-blur;89;86;90;91;88;86;95;88;88;84 16:48:00 INFO - PROCESS | 1675 | |6;imaging-darkroom;94;93;94;94;94;93;93;94;94;93 16:48:00 INFO - PROCESS | 1675 | |7;imaging-desaturate;91;87;87;91;87;91;86;89;90;88 16:48:00 INFO - PROCESS | 1675 | |8;json-parse-financial;56;52;54;53;54;55;53;53;54;53 16:48:00 INFO - PROCESS | 1675 | |9;json-stringify-tinderbox;64;76;68;76;98;101;104;75;71;71 16:48:00 INFO - PROCESS | 1675 | |10;stanford-crypto-aes;90;89;86;86;88;86;89;91;88;87 16:48:00 INFO - PROCESS | 1675 | |11;stanford-crypto-ccm;146;160;159;160;145;146;143;142;178;145 16:48:00 INFO - PROCESS | 1675 | |12;stanford-crypto-pbkdf2;223;177;170;171;172;170;172;172;174;172 16:48:00 INFO - PROCESS | 1675 | |13;stanford-crypto-sha256-iterative;70;71;81;86;89;90;73;70;68;68 16:48:00 INFO - PROCESS | 1675 | __end_tp_report 16:48:00 INFO - PROCESS | 1675 | __start_cc_report 16:48:00 INFO - PROCESS | 1675 | _x_x_mozilla_cycle_collect,2003 16:48:00 INFO - PROCESS | 1675 | __end_cc_report 16:48:00 INFO - PROCESS | 1675 | __startTimestamp1476748080592__endTimestamp 16:48:00 INFO - PROCESS | 1675 | 16:48:00 INFO - PROCESS | 1675 | ------- Summary: start ------- 16:48:00 INFO - PROCESS | 1675 | Number of tests: 14 16:48:00 INFO - PROCESS | 1675 | 16:48:00 INFO - PROCESS | 1675 | [#0] ai-astar Cycles:10 Average:112.40 Median:113.00 stddev:0.97 (0.9%) stddev-sans-first:1.01 16:48:00 INFO - PROCESS | 1675 | Values: 112.0 113.0 113.0 112.0 113.0 113.0 110.0 113.0 112.0 113.0 16:48:00 INFO - PROCESS | 1675 | 16:48:00 INFO - PROCESS | 1675 | [#1] audio-beat-detection Cycles:10 Average:123.60 Median:114.50 stddev:16.47 (14.4%) stddev-sans-first:15.35 16:48:00 INFO - PROCESS | 1675 | Values: 146.0 115.0 114.0 151.0 114.0 112.0 112.0 113.0 145.0 114.0 16:48:00 INFO - PROCESS | 1675 | 16:48:00 INFO - PROCESS | 1675 | [#2] audio-dft Cycles:10 Average:185.00 Median:184.50 stddev:2.26 (1.2%) stddev-sans-first:1.88 16:48:00 INFO - PROCESS | 1675 | Values: 189.0 187.0 183.0 188.0 185.0 184.0 182.0 184.0 184.0 184.0 16:48:00 INFO - PROCESS | 1675 | 16:48:00 INFO - PROCESS | 1675 | [#3] audio-fft Cycles:10 Average:70.70 Median:70.00 stddev:14.92 (21.3%) stddev-sans-first:15.52 16:48:00 INFO - PROCESS | 1675 | Values: 79.0 59.0 61.0 90.0 60.0 60.0 59.0 60.0 98.0 81.0 16:48:00 INFO - PROCESS | 1675 | 16:48:00 INFO - PROCESS | 1675 | [#4] audio-oscillator Cycles:10 Average:86.20 Median:79.50 stddev:16.45 (20.7%) stddev-sans-first:17.44 16:48:00 INFO - PROCESS | 1675 | Values: 88.0 74.0 76.0 71.0 86.0 100.0 124.0 83.0 69.0 91.0 16:48:00 INFO - PROCESS | 1675 | 16:48:00 INFO - PROCESS | 1675 | [#5] imaging-gaussian-blur Cycles:10 Average:88.50 Median:88.50 stddev:3.06 (3.5%) stddev-sans-first:3.24 16:48:00 INFO - PROCESS | 1675 | Values: 89.0 86.0 90.0 91.0 88.0 86.0 95.0 88.0 88.0 84.0 16:48:00 INFO - PROCESS | 1675 | 16:48:00 INFO - PROCESS | 1675 | [#6] imaging-darkroom Cycles:10 Average:93.60 Median:94.00 stddev:0.52 (0.5%) stddev-sans-first:0.53 16:48:00 INFO - PROCESS | 1675 | Values: 94.0 93.0 94.0 94.0 94.0 93.0 93.0 94.0 94.0 93.0 16:48:00 INFO - PROCESS | 1675 | 16:48:00 INFO - PROCESS | 1675 | [#7] imaging-desaturate Cycles:10 Average:88.70 Median:89.50 stddev:1.95 (2.2%) stddev-sans-first:1.88 16:48:00 INFO - PROCESS | 1675 | Values: 91.0 87.0 87.0 91.0 87.0 91.0 86.0 89.0 90.0 88.0 16:48:00 INFO - PROCESS | 1675 | 16:48:00 INFO - PROCESS | 1675 | [#8] json-parse-financial Cycles:10 Average:53.70 Median:54.00 stddev:1.16 (2.1%) stddev-sans-first:0.88 16:48:00 INFO - PROCESS | 1675 | Values: 56.0 52.0 54.0 53.0 54.0 55.0 53.0 53.0 54.0 53.0 16:48:00 INFO - PROCESS | 1675 | 16:48:00 INFO - PROCESS | 1675 | [#9] json-stringify-tinderbox Cycles:10 Average:80.40 Median:73.00 stddev:14.75 (20.2%) stddev-sans-first:14.40 16:48:00 INFO - PROCESS | 1675 | Values: 64.0 76.0 68.0 76.0 98.0 101.0 104.0 75.0 71.0 71.0 16:48:00 INFO - PROCESS | 1675 | 16:48:00 INFO - PROCESS | 1675 | [#10] stanford-crypto-aes Cycles:10 Average:88.00 Median:88.50 stddev:1.76 (2.0%) stddev-sans-first:1.72 16:48:00 INFO - PROCESS | 1675 | Values: 90.0 89.0 86.0 86.0 88.0 86.0 89.0 91.0 88.0 87.0 16:48:00 INFO - PROCESS | 1675 | 16:48:00 INFO - PROCESS | 1675 | [#11] stanford-crypto-ccm Cycles:10 Average:152.40 Median:152.50 stddev:11.56 (7.6%) stddev-sans-first:12.03 16:48:00 INFO - PROCESS | 1675 | Values: 146.0 160.0 159.0 160.0 145.0 146.0 143.0 142.0 178.0 145.0 16:48:00 INFO - PROCESS | 1675 | 16:48:00 INFO - PROCESS | 1675 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:177.30 Median:172.00 stddev:16.19 (9.4%) stddev-sans-first:2.17 16:48:00 INFO - PROCESS | 1675 | Values: 223.0 177.0 170.0 171.0 172.0 170.0 172.0 172.0 174.0 172.0 16:48:00 INFO - PROCESS | 1675 | 16:48:00 INFO - PROCESS | 1675 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:76.60 Median:77.00 stddev:8.95 (11.6%) stddev-sans-first:9.17 16:48:00 INFO - PROCESS | 1675 | Values: 70.0 71.0 81.0 86.0 89.0 90.0 73.0 70.0 68.0 68.0 16:48:00 INFO - PROCESS | 1675 | -------- Summary: end -------- 16:48:00 INFO - PROCESS | 1675 | 16:48:01 INFO - TEST-INFO | 1675: exit 0 16:48:01 INFO - TEST-OK | kraken | took 59608ms 16:48:01 INFO - SUITE-END | took 1180s 16:48:01 INFO - Completed test suite (00:19:40) 16:48:01 INFO - javascript benchmark 16:48:01 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3323.5180898769677, 3325.6674332566745, 3365.697087262146, 3378.7465940054503, 3415.5047518109873, 5530.6128571000045, 5596.076647353182, 5657.038303488406, 5665.376808551061, 5696.287964004499, 5619.213310136574, 5640.435956404358, 5729.111987641912, 5748.626339287944, 5773.624714393562, 5036.571891389236, 5075.6447048706605, 5106.183010718286, 5154.407243167027, 5173.068847607429, 5212.1660534314515, 5262.947370526284, 5266.4733526647415, 5275.182346736262, 5335.0, 5081.822136225252, 5223.37319521656, 5253.50091741284, 5258.973705131488, 5322.760475778599, 5138.2549530318265, 5190.636655434121, 5193.714345711022, 5193.948060519384, 5197.376314842243, 5070.492950704936, 5149.922751158736, 5156.123459011977, 5163.8192663256605, 5164.6384753067305, 5090.465501122366, 5606.131049687264, 5607.57943154261, 5628.831135065954, 5634.3802181759975, 5696.287964004499, 6110.847228819271, 6114.2357205349335, 6124.540659450559, 6130.080487926846, 6101.267847858241, 6158.0, 6202.4417802398, 6206.34833342497, 6231.158793562901, 5756.395578464285, 5762.049261871786, 5772.9711351442975, 5784.161296611967, 5828.388019258002, 6340.0, 6348.936510634927, 6349.015902535114, 6352.618842869442, 6362.491000720024, 5920.111983202468, 5979.133025711248, 5985.311689155716, 6109.816705498843, 6119.44924956756, 6130.724117414727, 6143.788236234952, 6153.107799369066, 6221.875562488724, 6228.844278893064, 6116.052011938156, 6132.7546898124465, 6139.723712432951, 6145.139680444741, 6147.569670123138, 5970.850728731816, 6151.446369826737, 6154.5076393888385, 6166.167567378437, 6169.352218017133, 4804.111239420718, 4813.735244561512, 4866.926996095061, 4868.9756551217015, 4878.975605121952, 4278.636315913182, 4415.359772832921, 4431.977840110779, 4436.134953684065, 4444.266695995189, 5695.373508914016, 5698.487136157766, 5706.771729130788, 5708.0, 5710.486056254957, 6117.510599152057, 6161.7227224775, 6199.132121502993, 6210.968945155336, 6223.0, 5609.962157000968, 5683.516901063374, 5684.317881854121, 5714.657120572696, 5730.885382292331, 5323.760430780624, 5328.307320048369, 5332.360116786011, 5342.572594192456, 5347.0, 6253.624782512973, 6296.2759282681245, 6304.275008374096, 6305.306416294204, 6333.081703153109, 6268.404501572343, 6287.811365659038, 6296.212973378328, 6302.558820882494, 6318.210223722035, 6340.841478962879, 6341.0805433212845, 6342.175517182921, 6347.365263473615, 6367.808965731036, 5974.850628734142, 6021.42796434338, 6026.397360263939, 6101.145839582373, 6122.846928826815, 6154.507639388928, 6239.625622462667, 6288.937110628835, 6291.182146320949, 6297.370262973666, 6034.607750496204, 6057.242844644419, 6062.36345183767, 6070.484008859296, 6094.969525152524, 4587.013792034729, 5797.130430435468, 6045.6070355426755, 6049.485793707408, 6097.32929377777, 5612.494875461065, 5750.6837123958585, 5758.769649214152, 5762.423757624204, 5772.3650398456975, 5872.383399742966, 5893.557983151195, 5899.233099697012, 5906.0, 5914.053751399755, 5132.769025393941, 5727.455891690282, 5788.652680839163, 5792.768289268382, 5800.216970709069], "value": 5638.31294924351, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [447.9652003871057, 456.112860486354, 485.6915858429896, 514.3930162408357, 554.5341912793256, 28798.0, 39876.40185397218, 40765.79617101914, 41065.17869642613, 43195.0, 42687.0, 43054.923626909345, 43076.784616076955, 43095.3535696964, 43185.568144318546, 8449.23956843884, 9378.343515953886, 9558.426494410323, 9566.23470122392, 9566.617335306579, 8513.74458766238, 11331.0, 11660.708482287926, 11682.182247242696, 11752.118591105658, 11503.252288601256, 11536.0, 11580.073594112493, 11594.47824847884, 11674.0, 8567.357448191347, 8782.25350845179, 11361.204715669906, 11460.140489463323, 11480.483378247998, 11114.11087113029, 11453.942730286379, 11461.197716159872, 11531.942340288246, 11582.18924675273, 39921.40117898234, 40133.79933100345, 40178.39732404016, 40198.79900600507, 40307.79846100751, 3237.27161388688, 3267.2648654052887, 3475.669811367916, 3658.798766067865, 3923.019245188703, 29769.958051468093, 30600.2349941251, 30649.38701225963, 31203.063908082568, 31675.524867127013, 3186.6972637599624, 3201.295714942732, 3210.5826242588546, 3214.100051985448, 3258.4949332853207, 488010.0, 488309.0, 489104.0, 489770.0, 496680.5165974183, 57095.0, 57596.0, 57672.423275766705, 57737.0, 57792.0, 34367.828160859535, 34500.30999379999, 35013.12467188341, 36026.63973360285, 61497.38502614917, 15670.216489175496, 15745.68508629821, 15766.975146615434, 15793.842061579237, 15802.0, 10793.460326983619, 11010.50452729629, 11047.613333533287, 11939.88060119405, 12067.094967877445, 16559.91720041392, 16631.0, 16682.165891705365, 18501.53746156357, 18909.43271701851, 7418.0, 7467.813304667318, 7551.207123252089, 7639.288052741308, 7647.388208943271, 739.6153999920011, 774.2837874965634, 844.6748002019267, 929.1034152043176, 1061.4056128567872, 28198.43603127926, 29724.256893577833, 29733.702662973526, 30543.2364190897, 30600.54099188514, 896.8833801916655, 972.6304004478253, 1033.1631378583222, 1104.9558017679203, 1150.9194356394971, 15257.313420895864, 15297.923510382378, 15446.995945263521, 15526.146061966474, 15809.367625294859, 662448.0, 663730.0, 666497.0, 667206.0, 667732.0, 56450.0, 57071.42928570744, 57206.7139664299, 57251.42748572461, 57345.13982290269, 1216.3492531495608, 1252.7118762684452, 1257.6289994451658, 1353.363918958088, 1369.1716511510592, 29426.97005604879, 30844.537331940486, 31029.534556982115, 31804.363912722078, 32495.878733274385, 49241.26138108003, 49486.0, 49788.0254369478, 49934.75032624813, 49952.0, 488465.55767220934, 488676.7830804258, 491815.0, 492049.0, 493622.0, 25310.493790124463, 25699.35751606225, 25711.0, 26172.953081876512, 26702.732972670026, 32780.836095819366, 46075.3088703663, 46828.29757553571, 46879.06241875212, 47321.0, 37972.24055518819, 38670.0, 38703.61296387, 39263.803680981415, 39596.802015989735, 38387.6161238384, 38553.614463855, 39252.0, 39591.208175836895, 39931.20137597174, 37240.255194895406, 37674.86975390743, 37811.05472363213, 38041.0, 38376.6162338373, 25980.480390392466, 32414.865479709042, 34682.13294667654, 34762.82618586891, 34912.47631285583, 24310.756892431556, 24759.50480990406, 25354.0, 25422.983080676564, 25514.96540309924], "value": 19635.049300486084, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [345.4524578543009, 353.08726940857883, 367.5754503548401, 367.7863051928635, 374.69275194340634, 13113.67215819605, 14489.840812734983, 16141.677166456679, 16875.662486750258, 20844.0, 19334.129964151613, 19412.029398530052, 20471.283505077325, 20719.689204661943, 20756.377308680763, 5745.827625171251, 6091.238595175603, 6092.482139018167, 6097.0, 6100.450959413652, 5116.744162791845, 5890.322612899528, 6614.801555953329, 6670.866582668344, 6747.12287402637, 5588.972055139739, 6635.734570617194, 6669.9666501667425, 6677.332266773333, 6694.564853284545, 5834.620749651281, 5989.071693887455, 5997.640141591519, 6000.909986350208, 6008.068749343859, 5565.026120428949, 5715.199872017921, 5775.537956963432, 5786.508146807527, 5790.7683692652245, 13097.869021309856, 13162.60512184636, 13277.800832987514, 13364.198148111145, 13364.465421383133, 1803.9458816235533, 2097.454661787931, 2130.1053557505884, 2272.0230300970575, 2399.748026457232, 9388.671396501159, 9778.559964801601, 10201.591936322538, 10281.948590257001, 12787.552435664707, 1793.6233390987777, 1833.404143653318, 1833.62410705805, 1861.4322631597358, 1892.8350451676772, 102681.1648543932, 112648.0, 125630.37184814109, 126101.0, 126635.0, 11002.669919902415, 11132.944335278271, 11515.193936424537, 11563.88436115645, 11762.70593235176, 14590.781138282935, 14608.926955365368, 14619.78070328946, 14621.78067328991, 14628.19544925018, 8052.718154864551, 8364.247217750431, 8376.371772117116, 8490.575471226413, 8565.0, 8123.796905077302, 8132.471389359673, 8168.7957801055445, 8295.8340833183, 8310.21052999964, 4440.444944381952, 4524.434445694288, 4539.296409056601, 4604.907901841944, 4617.168909596305, 1765.7263124215767, 1843.6681397348607, 1898.7911329753715, 1908.9809101909082, 1910.0, 719.5143278287135, 803.4375936844116, 827.9420440569223, 862.7847902243973, 952.4380615436787, 19210.711839322423, 20696.0, 20866.859566372263, 20946.0, 20998.790012099988, 654.9738010479527, 836.9497830130211, 876.2595606712318, 915.1397686174975, 937.465644582595, 9980.0, 10041.849372259421, 10887.564497420015, 10912.454377281105, 10939.28894621863, 595335.0, 600096.0, 601710.0, 604257.5316715158, 611631.0, 68710.28224294333, 72874.9068763969, 100837.0, 121926.0, 141232.0, 966.0773960867355, 967.1054274795786, 1003.9799204015878, 1004.8492726091145, 1005.9446730429896, 14879.776803348175, 15094.54716358511, 19147.329843455413, 19519.707204391663, 19680.704789427884, 54040.729796350766, 54167.0, 55038.724806375714, 57436.71281643732, 57633.0, 56403.0, 76059.0, 77225.61387193028, 77980.0, 78000.60999694966, 7887.64505597238, 7945.761627151194, 8067.072286686871, 8171.142030086755, 8189.50862948225, 11134.164937629872, 11281.548738050386, 11671.0, 12420.565280215149, 12528.624141275777, 10727.839082413926, 10803.621873234399, 11015.0, 11138.33170009802, 11175.944120279346, 10586.576536938435, 10699.679009629723, 11029.61396351124, 11113.055390291916, 11222.49498772541, 11133.55465781392, 11286.49210785533, 11313.71715707114, 11313.943430282796, 11364.431778411212, 7631.0, 8091.0, 8212.055613604272, 8215.671373145246, 8216.876746848682, 9654.75863103428, 10854.511546980562, 11101.5559377624, 11130.220884538328, 11147.498362573866], "value": 9180.47011318025, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1488.315374927533, 1504.7441934871072, 1682.3102527963538, 1829.5517598188428, 1839.7608310919586, 7846.843063138734, 8128.4310098293145, 8419.536925469096, 8917.955410222949, 8919.687810926618, 7794.337481314081, 7874.212578742123, 7894.763157105296, 8500.107488713673, 8746.475211487299, 2310.9191178308806, 2342.9297121086393, 2344.800691941187, 2345.2729653807287, 2348.635961425982, 2208.61349263879, 2229.1752051740796, 2256.8194544436406, 2261.7398999115067, 2268.920587779428, 2324.721033475985, 2341.098677009347, 2356.799672027871, 2360.5278944211227, 2363.633636786301, 2320.9419764505856, 2321.6749655048307, 2327.4297797039835, 2332.5421611492734, 2332.9650055249185, 2214.5017371091535, 2216.357256395643, 2218.401031721426, 2219.911203551856, 2224.265992222579, 8744.125587441205, 8824.338174636929, 8867.911320886773, 8869.73390798277, 8881.822363552757, 5163.483651634844, 5327.573794096463, 5338.252644629755, 5339.893202135974, 5342.946570534284, 3439.2949445363643, 3513.54323937889, 3660.8535658573624, 3666.5233519642275, 3679.8344074516713, 1041.265907535186, 1062.1184416933927, 1077.8167711488986, 1080.3578560587862, 1103.9834402483968, 11697.0, 11747.236429632047, 11756.70608234801, 11760.235584687054, 11764.647060588197, 4510.977445112753, 4576.55154492345, 4789.616830653539, 4794.544518270759, 4797.4482934462985, 10040.849387259197, 10056.64801731936, 10072.848907266396, 10079.143272821892, 10082.29423940317, 5338.54622357104, 5345.984262990097, 5349.117395629755, 5352.9732351338, 5360.91958620621, 4332.978335108304, 4443.822247110144, 4505.098980203907, 4844.903101937942, 4888.364512613409, 2941.9411611767646, 3360.4119279126057, 3829.8659546915724, 3875.3605655067163, 3970.722049456568, 1232.6610182199968, 1257.4844313831286, 1258.6601617563206, 1273.1660762200772, 1273.9681507962189, 799.8040480082417, 871.6034204436966, 885.5041176940933, 899.4917871402637, 900.5812297281665, 5649.717514124277, 5777.335606405317, 5863.296404431496, 5887.587868849139, 6171.043488259327, 426.09242313871255, 430.4038906114991, 433.80695590461943, 443.82690750607287, 446.2770312094363, 3936.3505021671676, 3957.920841583152, 3991.720579559461, 4017.578154293757, 4028.778417187083, 10178.898211017944, 10646.574137034431, 10694.572217111385, 10793.62222322215, 10813.405262710627, 10806.405647689453, 10822.18833587484, 10823.242373033812, 10832.78334433307, 10835.295705779099, 7966.0440747110715, 8222.876656850152, 8234.217749313806, 8249.876251856227, 8256.339492840558, 5709.22925405065, 5856.267966504187, 6008.309044459768, 6133.110698948717, 6170.444659980623, 9664.226861851175, 9978.700638980843, 9999.500024998575, 10083.798324033625, 10135.290529663147, 8646.870296945424, 10359.378437293786, 10363.792724145625, 10371.844422333821, 10406.843897341394, 7006.493603875191, 7254.419646428379, 7369.115706115301, 7494.850102998019, 7556.206598307099, 8262.586870656563, 8443.155684431507, 8505.06444291115, 8506.02180749197, 8506.276966457674, 7344.596047217455, 7349.052501087323, 7526.623668816428, 7610.543367397752, 7618.885716714143, 7182.102237220348, 7342.3758980487255, 7395.963020184865, 7401.592912389655, 7428.368588669808, 6980.616066116412, 7119.2524784898915, 7124.821879453055, 7139.32176443237, 7143.249958754256, 4715.151272770797, 4917.116595642712, 5043.016611760818, 5073.847784566469, 5134.178531434953, 4849.4908034657265, 4966.850994470172, 5021.974890125526, 5093.541581257705, 5160.380754309507], "value": 4465.652134264701, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [299.55665614889966, 302.9091272618214, 309.4738943795547, 315.6575115999141, 318.5922019814638, 4211.925958880489, 4925.43357513886, 5343.759530821113, 5357.249985002098, 5444.96545656325, 4510.34599983003, 4601.33280674302, 5106.412762532301, 5201.687898726079, 5381.461853814617, 3368.797872127668, 3945.270125026879, 4041.312976793952, 4129.277376459123, 4149.149424368013, 3400.387930172568, 3989.3842993587646, 4110.568390319018, 4131.7934103294865, 4170.871846115413, 3899.922001559967, 3988.441618173458, 4103.671706263507, 4119.464469618945, 4143.544210136883, 3392.423288041039, 4117.608827161415, 4121.278776214151, 4142.91714165718, 4160.791960401983, 2958.852057397143, 3576.678098971105, 3583.605803361628, 3592.760497628314, 3598.298331825295, 4366.607005369532, 5126.948730512684, 5298.576113910878, 5310.389305229909, 5363.659085228693, 4048.291548978946, 4286.357046443059, 4430.357598148283, 4670.949036466802, 4765.165411316633, 4754.572088512016, 5688.203651488795, 5721.570882183812, 5797.536197104221, 5800.390958949291, 3466.757326987112, 3723.8696645617542, 3960.980195099006, 3979.820908059144, 3996.220736956295, 5006.599472042228, 5694.402087780765, 5863.5309175265875, 5952.672603006833, 5981.581289309706, 3345.6821601947777, 3563.3585954528435, 4028.315186418316, 4036.414719865662, 4060.167665628539, 3677.852885884588, 4066.6543343815515, 4136.6483848872585, 4140.585941405835, 4142.813573389205, 3692.5205702849166, 4250.213710463512, 4510.548945105464, 4714.811407543729, 4757.572728181532, 5038.3198268234055, 5126.051680439131, 5337.037943941073, 5646.2942132233475, 5705.686187259657, 1194.7610477904454, 1246.956356527517, 1350.2438634364592, 1403.2773121842056, 1406.2336026865382, 878.9121087891288, 890.9688160914337, 904.3759805734022, 906.9455832650062, 956.0964888180603, 4447.911041779146, 4895.6817806843155, 5286.365636123613, 5303.893922121613, 5402.540784033323, 4903.891326729469, 5055.646104772704, 5201.895962080737, 5491.172046396109, 5809.506191973645, 4686.179918514246, 5262.210668399771, 5275.235090911873, 5275.920861187085, 5304.655197412156, 2401.7598240176194, 3023.9244018899703, 3030.5605687175234, 3035.4839677254918, 3035.9089227323216, 5630.915536266959, 5906.173135760997, 5933.525317974638, 5940.287165540077, 5954.166416701665, 5835.374081663269, 5836.649801011953, 5897.174395584621, 5914.615549989237, 5918.674472904032, 5954.761809527657, 5957.3744756799915, 5959.553033522417, 5971.820845374733, 5996.340402555803, 5269.604779641466, 5532.197831314517, 5725.683092888576, 5747.683877386617, 5769.365369809234, 4767.76161191946, 5180.197069454241, 5642.717864106696, 5708.257926469699, 5720.771169153188, 5648.435156484319, 5940.198073260054, 5971.970140149271, 5974.2830860297045, 6005.489533389711, 3945.678197803768, 3969.80150992455, 3973.980130099331, 3980.0, 4025.335819589735, 4591.3572099906705, 4811.566958973709, 5665.512987629059, 5748.338941021669, 5754.338251101177, 5080.796768129381, 5090.49095090488, 5228.555572776357, 5304.336957880265, 5514.972425137848, 5478.863028424162, 5588.30146231721, 5599.32808063035, 5601.059443792042, 5608.46719561641, 5309.33633295838, 5464.235007099089, 5465.918011229755, 5468.48049435303, 5500.1749737539685, 5188.766505507186, 5530.446955304437, 5581.888362232651, 5630.7466164023535, 5650.23721797552, 4624.791884365144, 4686.0, 5112.3415823772175, 5582.8325150245555, 5583.190437386637], "value": 4098.277646287748, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [675.1088563096713, 695.3567949646575, 738.3760722189751, 760.551274747899, 802.374148164432, 14677.0, 15091.773623395658, 15530.0, 15889.602759931007, 16205.35178592855, 13999.300034998234, 14115.153090814558, 14137.858621413809, 14149.71700565988, 14153.080049796781, 808.05861171735, 832.9292010179142, 836.2849763452252, 836.4103307168432, 837.049948308671, 665.2981104934292, 687.086175386737, 688.1432616392573, 688.8381230410874, 690.0856365315951, 720.099875156055, 864.1013346120028, 891.0332289465937, 893.1604291965532, 901.0898992018076, 880.295763389286, 880.6565439478609, 883.4037025008158, 885.234272354412, 885.5616469847403, 650.1418128070949, 653.7025653327725, 654.201873714068, 654.2247436787375, 654.2933631677777, 11836.526538938431, 12423.565175218824, 12442.56451024219, 12599.37003149848, 12617.369131543386, 12298.139130260884, 14081.225532595703, 14127.434902603884, 14378.209198494078, 14519.128852268897, 11437.334365601666, 11734.647960561197, 11737.882621173765, 11985.340806255652, 12055.698607534794, 3765.1904840459433, 3791.298609757204, 3823.7323387362894, 3858.3826587745825, 3937.3503371943593, 95763.04236957681, 98980.0, 110390.44804776004, 110719.44640276827, 112850.87149128485, 10058.541511480795, 10923.453827308604, 10932.672019839418, 11125.165612579089, 11423.086153107732, 20879.26922557703, 21420.892895535733, 21635.783642163693, 21661.67507487389, 21672.0, 7772.0, 7859.0, 7917.247861453153, 7928.722494712657, 8040.597970101471, 3698.7041036716996, 4311.568843115663, 4411.779411029435, 4445.710743884238, 4547.590716835501, 3513.3500302443617, 3617.2881288447015, 3631.6005239423644, 3655.592596850232, 3670.541182352206, 587.811900191943, 660.2143449295323, 661.5501459007825, 672.1430176524872, 672.7275453441364, 554.5314209492972, 626.4862812493802, 659.6938901664089, 666.7599664120912, 667.3326673326674, 9203.769905752275, 9344.813103737888, 9647.469389183521, 10047.357604519366, 10301.0, 682.689376333767, 686.2828344380134, 692.2247083266774, 693.7398475571661, 694.2952902803659, 4275.636570891509, 4627.45450101991, 4670.462896766915, 4705.7647117644665, 4786.282057691375, 135539.0, 146932.0, 155328.0, 156664.21667891816, 162764.0, 112541.0, 130340.3482982579, 134451.32774336066, 137090.0, 137363.31318343544, 77056.0, 82070.0, 108971.45514272377, 113561.0, 113651.0, 8944.0, 8954.865677014981, 8962.641494340154, 8981.326400520118, 9105.681301154427, 59689.403105968384, 61770.38229617646, 62931.685341574834, 63165.68417157885, 65507.0, 91098.63352049592, 95813.5209323949, 97835.02164978259, 98241.50879245558, 99477.0, 11773.470193841127, 11925.986291165002, 12150.513979440724, 12267.325297108744, 12335.50657973671, 13033.804492932424, 13257.0, 13350.532731354357, 13440.865591344353, 13664.65838354049, 8694.652213911626, 11722.706932326419, 12407.75184496287, 12415.689607759878, 12743.681407964505, 11964.880351196376, 12251.816222756845, 12484.687882803002, 12580.0, 12587.307698076327, 11818.349990750246, 12064.879351206726, 12365.07261955339, 12418.192817466836, 12657.367131643565, 9958.701238962552, 10122.331016269396, 10286.794264114826, 10352.948235259077, 10387.0, 8776.692815751418, 10321.432321222104, 10481.37111773296, 10642.62750803749, 10762.08522275584], "value": 7562.272682741492, "unit": "score"}], "name": "dromaeo_css", "value": 7211.712581381116, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 113.0, 113.0, 112.0, 113.0, 113.0, 110.0, 113.0, 112.0, 113.0], "value": 112.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 115.0, 114.0, 151.0, 114.0, 112.0, 112.0, 113.0, 145.0, 114.0], "value": 123.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [189.0, 187.0, 183.0, 188.0, 185.0, 184.0, 182.0, 184.0, 184.0, 184.0], "value": 185.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 59.0, 61.0, 90.0, 60.0, 60.0, 59.0, 60.0, 98.0, 81.0], "value": 70.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 74.0, 76.0, 71.0, 86.0, 100.0, 124.0, 83.0, 69.0, 91.0], "value": 86.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 86.0, 90.0, 91.0, 88.0, 86.0, 95.0, 88.0, 88.0, 84.0], "value": 88.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 93.0, 94.0, 94.0, 94.0, 93.0, 93.0, 94.0, 94.0, 93.0], "value": 93.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 87.0, 87.0, 91.0, 87.0, 91.0, 86.0, 89.0, 90.0, 88.0], "value": 88.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 52.0, 54.0, 53.0, 54.0, 55.0, 53.0, 53.0, 54.0, 53.0], "value": 53.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 76.0, 68.0, 76.0, 98.0, 101.0, 104.0, 75.0, 71.0, 71.0], "value": 80.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 89.0, 86.0, 86.0, 88.0, 86.0, 89.0, 91.0, 88.0, 87.0], "value": 88.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 160.0, 159.0, 160.0, 145.0, 146.0, 143.0, 142.0, 178.0, 145.0], "value": 152.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [223.0, 177.0, 170.0, 171.0, 172.0, 170.0, 172.0, 172.0, 174.0, 172.0], "value": 177.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 71.0, 81.0, 86.0, 89.0, 90.0, 73.0, 70.0, 68.0, 68.0], "value": 76.6, "unit": "score"}], "name": "kraken", "value": 1477.1000000000001, "alertThreshold": 2.0}]} 16:48:01 INFO - Return code: 0 16:48:01 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 16:48:01 INFO - # TBPL SUCCESS # 16:48:01 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 16:48:01 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 16:48:01 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 16:48:01 INFO - 2016-10-17 16:48:01.919 screenresolution[1679:10433] starting screenresolution argv=screenresolution get 16:48:01 INFO - 2016-10-17 16:48:01.922 screenresolution[1679:10433] Display 0: 1600x1200x32@60 16:48:01 INFO - 2016-10-17 16:48:01.927 screenresolution[1680:10436] starting screenresolution argv=screenresolution list 16:48:01 INFO - Available Modes on Display 0 16:48:01 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 16:48:01 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 16:48:01 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 16:48:01 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 16:48:02 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 16:48:02 INFO - Intel Iris: 16:48:02 INFO - Chipset Model: Intel Iris 16:48:02 INFO - Type: GPU 16:48:02 INFO - Bus: Built-In 16:48:02 INFO - VRAM (Dynamic, Max): 1536 MB 16:48:02 INFO - Vendor: Intel (0x8086) 16:48:02 INFO - Device ID: 0x0a2e 16:48:02 INFO - Revision ID: 0x0009 16:48:02 INFO - Displays: 16:48:02 INFO - Display: 16:48:02 INFO - Resolution: 1600 x 1200 @ 60 Hz 16:48:02 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 16:48:02 INFO - Display Serial Number: 1600x1200 60 16:48:02 INFO - Main Display: Yes 16:48:02 INFO - Mirror: Off 16:48:02 INFO - Online: Yes 16:48:02 INFO - Rotation: Supported 16:48:02 INFO - Return code: 0 16:48:02 INFO - Running post-action listener: _resource_record_post_action 16:48:02 INFO - [mozharness: 2016-10-17 23:48:02.166843Z] Finished run-tests step (success) 16:48:02 INFO - Running post-run listener: _resource_record_post_run 16:48:02 INFO - Total resource usage - Wall time: 1215s; CPU: 53.0%; Read bytes: 39775744; Write bytes: 707558912; Read time: 363; Write time: 2313 16:48:02 INFO - TinderboxPrint: CPU usage
53.5% 16:48:02 INFO - TinderboxPrint: I/O read bytes / time
39,775,744 / 363 16:48:02 INFO - TinderboxPrint: I/O write bytes / time
707,558,912 / 2,313 16:48:02 INFO - TinderboxPrint: CPU idle
2,261.8 (46.5%) 16:48:02 INFO - TinderboxPrint: CPU system
973.5 (20.0%) 16:48:02 INFO - TinderboxPrint: CPU user
1,626.3 (33.5%) 16:48:02 INFO - TinderboxPrint: Swap in / out
450,605,056 / 0 16:48:02 INFO - install - Wall time: 35s; CPU: 55.0%; Read bytes: 397433344; Write bytes: 391911424; Read time: 29636; Write time: 758 16:48:02 INFO - run-tests - Wall time: 1182s; CPU: 53.0%; Read bytes: 37977600; Write bytes: 296474112; Read time: 346; Write time: 1478 16:48:02 INFO - Running post-run listener: _upload_blobber_files 16:48:02 INFO - Blob upload gear active. 16:48:02 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 16:48:02 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 16:48:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Larch', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 16:48:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Larch -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 16:48:03 INFO - (blobuploader) - INFO - Open directory for files ... 16:48:03 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 16:48:03 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:48:03 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:48:03 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 16:48:03 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:48:03 INFO - (blobuploader) - INFO - Done attempting. 16:48:03 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 16:48:03 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:48:03 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:48:04 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 16:48:04 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:48:04 INFO - (blobuploader) - INFO - Done attempting. 16:48:04 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 16:48:04 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:48:04 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:48:07 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 16:48:07 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:48:07 INFO - (blobuploader) - INFO - Done attempting. 16:48:07 INFO - (blobuploader) - INFO - Iteration through files over. 16:48:07 INFO - Return code: 0 16:48:07 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 16:48:07 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 16:48:07 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/086070ca0af26aa2c8d2bd5478c4bd99d4819baaa03da3864c4258863fd282417367217435550eb72ef2641975807fe3708563952a61695dcbf3a0159d8400dd", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/f108b41fd5b689d8a2d3d8815dfb71ae1dc128c9213a042aaccb4570b3f8b0be782bbc4b0279999ece94dfef04d74f5eb8ecd7245dfb6a22e22c6fe81dc41366", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/445a1d5a816faa2f4345e2e131940e0bd35e45c8eaeea4888582ebdb01807272c7ecc5a7f8273ec8bd0d282a28d4724c4dd1264d3478bcb4f70bb95db515cff8"} 16:48:07 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 16:48:07 INFO - Writing to file /builds/slave/test/properties/blobber_files 16:48:07 INFO - Contents: 16:48:07 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/086070ca0af26aa2c8d2bd5478c4bd99d4819baaa03da3864c4258863fd282417367217435550eb72ef2641975807fe3708563952a61695dcbf3a0159d8400dd", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/f108b41fd5b689d8a2d3d8815dfb71ae1dc128c9213a042aaccb4570b3f8b0be782bbc4b0279999ece94dfef04d74f5eb8ecd7245dfb6a22e22c6fe81dc41366", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/445a1d5a816faa2f4345e2e131940e0bd35e45c8eaeea4888582ebdb01807272c7ecc5a7f8273ec8bd0d282a28d4724c4dd1264d3478bcb4f70bb95db515cff8"} 16:48:07 INFO - Running post-run listener: copy_logs_to_upload_dir 16:48:07 INFO - Copying logs to upload dir... 16:48:07 INFO - mkdir: /builds/slave/test/build/upload/logs 16:48:07 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1275.982615 ========= master_lag: 2.39 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 18 secs) (at 2016-10-17 16:48:09.528098) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-17 16:48:09.532465) ========= 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.rh6HAuXYyA/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.yJtiG9O7a5/Listeners TMPDIR=/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/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/Larch/sha512/086070ca0af26aa2c8d2bd5478c4bd99d4819baaa03da3864c4258863fd282417367217435550eb72ef2641975807fe3708563952a61695dcbf3a0159d8400dd", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/f108b41fd5b689d8a2d3d8815dfb71ae1dc128c9213a042aaccb4570b3f8b0be782bbc4b0279999ece94dfef04d74f5eb8ecd7245dfb6a22e22c6fe81dc41366", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/445a1d5a816faa2f4345e2e131940e0bd35e45c8eaeea4888582ebdb01807272c7ecc5a7f8273ec8bd0d282a28d4724c4dd1264d3478bcb4f70bb95db515cff8"} build_url:https://queue.taskcluster.net/v1/task/V6dbqvZfQiKZUCjcPPrl0g/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011153 build_url: 'https://queue.taskcluster.net/v1/task/V6dbqvZfQiKZUCjcPPrl0g/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/086070ca0af26aa2c8d2bd5478c4bd99d4819baaa03da3864c4258863fd282417367217435550eb72ef2641975807fe3708563952a61695dcbf3a0159d8400dd", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/f108b41fd5b689d8a2d3d8815dfb71ae1dc128c9213a042aaccb4570b3f8b0be782bbc4b0279999ece94dfef04d74f5eb8ecd7245dfb6a22e22c6fe81dc41366", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/445a1d5a816faa2f4345e2e131940e0bd35e45c8eaeea4888582ebdb01807272c7ecc5a7f8273ec8bd0d282a28d4724c4dd1264d3478bcb4f70bb95db515cff8"}' ========= master_lag: 0.04 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-17 16:48:09.579774) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-17 16:48:09.580114) ========= 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.rh6HAuXYyA/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.yJtiG9O7a5/Listeners TMPDIR=/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/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.005198 ========= master_lag: 0.60 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-17 16:48:10.188494) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-17 16:48:10.188822) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-17 16:48:10.202040) ========= ========= Total master_lag: 3.12 =========