builder: larch_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0242 starttime: 1476746810.51 results: success (0) buildid: 20161017142049 builduid: 3658f20c32d04e0d82112331e655564f revision: 8c8266b3c23446275dc503366fd739ba3bc4834a ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-10-17 16:26:50.507541) ========= master: http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-10-17 16:26:50.507999) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-17 16:26:50.508270) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-17 16:26:50.534344) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-17 16:26:50.534707) ========= 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.L3jjK6jxeB/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.z2OF3cmixP/Listeners TMPDIR=/var/folders/hy/45ssh3ld6sx_27c31ssq2l9w00000w/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-- 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% 39.2M=0s 2016-10-17 16:26:50 (39.2 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.136294 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-17 16:26:50.693862) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-17 16:26:50.694192) ========= 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.L3jjK6jxeB/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.z2OF3cmixP/Listeners TMPDIR=/var/folders/hy/45ssh3ld6sx_27c31ssq2l9w00000w/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.027122 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-17 16:26:50.742925) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-17 16:26:50.743355) ========= 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.L3jjK6jxeB/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.z2OF3cmixP/Listeners TMPDIR=/var/folders/hy/45ssh3ld6sx_27c31ssq2l9w00000w/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,826 truncating revision to first 12 chars 2016-10-17 16:26:50,826 Setting DEBUG logging. 2016-10-17 16:26:50,826 attempt 1/10 2016-10-17 16:26:50,826 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:51,912 unpacking tar archive at: larch-8c8266b3c234/testing/mozharness/ program finished with exit code 0 elapsedTime=1.371560 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-17 16:26:52.136152) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-17 16:26:52.136473) ========= script_repo_revision: 8c8266b3c23446275dc503366fd739ba3bc4834a ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-17 16:26:52.136858) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-17 16:26:52.137141) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-17 16:26:52.159272) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-10-17 16:26:52.159676) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --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-e10s', '--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.L3jjK6jxeB/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.z2OF3cmixP/Listeners TMPDIR=/var/folders/hy/45ssh3ld6sx_27c31ssq2l9w00000w/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:52 INFO - MultiFileLogger online at 20161017 16:26:52 in /builds/slave/test 16:26:52 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Larch --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Larch 16:26:52 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 16:26:52 INFO - {'append_to_log': False, 16:26:52 INFO - 'base_work_dir': '/builds/slave/test', 16:26:52 INFO - 'blob_upload_branch': 'Larch', 16:26:52 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 16:26:52 INFO - 'branch': 'Larch', 16:26:52 INFO - 'buildbot_json_path': 'buildprops.json', 16:26:52 INFO - 'config_files': ('talos/mac_config.py',), 16:26:52 INFO - 'default_actions': ('clobber', 16:26:52 INFO - 'read-buildbot-config', 16:26:52 INFO - 'download-and-extract', 16:26:52 INFO - 'populate-webroot', 16:26:52 INFO - 'create-virtualenv', 16:26:52 INFO - 'install', 16:26:52 INFO - 'run-tests'), 16:26:52 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 16:26:52 INFO - 'download_minidump_stackwalk': True, 16:26:52 INFO - 'download_symbols': 'ondemand', 16:26:52 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 16:26:52 INFO - 'tooltool.py': '/tools/tooltool.py', 16:26:52 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 16:26:52 INFO - '/tools/misc-python/virtualenv.py')}, 16:26:52 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 16:26:52 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 16:26:52 INFO - 'installer_path': 'installer.exe', 16:26:52 INFO - 'log_level': 'info', 16:26:52 INFO - 'log_name': 'talos', 16:26:52 INFO - 'log_to_console': True, 16:26:52 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 16:26:52 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 16:26:52 INFO - 'opt_config_files': (), 16:26:52 INFO - 'pip_index': False, 16:26:52 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 16:26:52 INFO - 'cmd': ('bash', 16:26:52 INFO - '-c', 16:26:52 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 16:26:52 INFO - 'enabled': True, 16:26:52 INFO - 'halt_on_failure': False, 16:26:52 INFO - 'name': 'check_screen_resolution'},), 16:26:52 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 16:26:52 INFO - 'cmd': ('bash', 16:26:52 INFO - '-c', 16:26:52 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 16:26:52 INFO - 'enabled': True, 16:26:52 INFO - 'halt_on_failure': False, 16:26:52 INFO - 'name': 'check_screen_resolution'},), 16:26:52 INFO - 'run_cmd_checks_enabled': True, 16:26:52 INFO - 'sps_profile': False, 16:26:52 INFO - 'sps_profile_interval': 0, 16:26:52 INFO - 'suite': 'dromaeojs-e10s', 16:26:52 INFO - 'system_bits': '32', 16:26:52 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 16:26:52 INFO - 'title': 't-yosemite-r7-0242', 16:26:52 INFO - 'tooltool_cache': '/builds/tooltool_cache', 16:26:52 INFO - 'use_talos_json': True, 16:26:52 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 16:26:52 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 16:26:52 INFO - 'work_dir': 'build'} 16:26:52 INFO - [mozharness: 2016-10-17 23:26:52.363104Z] Running clobber step. 16:26:52 INFO - Running pre-action listener: _resource_record_pre_action 16:26:52 INFO - Running main action method: clobber 16:26:52 INFO - rmtree: /builds/slave/test/build 16:26:52 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 16:26:54 INFO - Running post-action listener: _resource_record_post_action 16:26:54 INFO - [mozharness: 2016-10-17 23:26:54.464298Z] Finished clobber step (success) 16:26:54 INFO - [mozharness: 2016-10-17 23:26:54.464424Z] Running read-buildbot-config step. 16:26:54 INFO - Running pre-action listener: _resource_record_pre_action 16:26:54 INFO - Running main action method: read_buildbot_config 16:26:54 INFO - Using buildbot properties: 16:26:54 INFO - { 16:26:54 INFO - "project": "", 16:26:54 INFO - "product": "firefox", 16:26:54 INFO - "script_repo_revision": "production", 16:26:54 INFO - "builddir": "larch_yosemite_r7_test-dromaeojs-e10s", 16:26:54 INFO - "repository": "", 16:26:54 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 larch talos dromaeojs-e10s", 16:26:54 INFO - "buildid": "20161017142049", 16:26:54 INFO - "pgo_build": "False", 16:26:54 INFO - "basedir": "/builds/slave/test", 16:26:54 INFO - "buildnumber": 0, 16:26:54 INFO - "slavename": "t-yosemite-r7-0242", 16:26:54 INFO - "master": "http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/", 16:26:54 INFO - "platform": "yosemite_r7", 16:26:54 INFO - "scheduler": "tests-larch-macosx64-talos", 16:26:54 INFO - "branch": "larch", 16:26:54 INFO - "repo_path": "projects/larch", 16:26:54 INFO - "revision": "8c8266b3c23446275dc503366fd739ba3bc4834a", 16:26:54 INFO - "stage_platform": "macosx64", 16:26:54 INFO - "builduid": "3658f20c32d04e0d82112331e655564f", 16:26:54 INFO - "slavebuilddir": "test" 16:26:54 INFO - } 16:26:54 INFO - Found installer url https://queue.taskcluster.net/v1/task/V6dbqvZfQiKZUCjcPPrl0g/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg. 16:26:54 INFO - Running post-action listener: _resource_record_post_action 16:26:54 INFO - [mozharness: 2016-10-17 23:26:54.467741Z] Finished read-buildbot-config step (success) 16:26:54 INFO - [mozharness: 2016-10-17 23:26:54.467846Z] Running download-and-extract step. 16:26:54 INFO - Running pre-action listener: _resource_record_pre_action 16:26:54 INFO - Running main action method: download_and_extract 16:26:54 INFO - mkdir: /builds/slave/test/build/tests 16:26:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:26:54 INFO - trying https://queue.taskcluster.net/v1/task/V6dbqvZfQiKZUCjcPPrl0g/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json 16:26:54 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:54 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:55 INFO - Downloaded 1199 bytes. 16:26:55 INFO - Reading from file /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 16:26:55 INFO - Using the following test package requirements: 16:26:55 INFO - {u'common': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 16:26:55 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 16:26:55 INFO - u'firefox-52.0a1.en-US.mac.cppunittest.tests.zip'], 16:26:55 INFO - u'gtest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 16:26:55 INFO - u'firefox-52.0a1.en-US.mac.gtest.tests.zip'], 16:26:55 INFO - u'jittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 16:26:55 INFO - u'jsshell-mac.zip'], 16:26:55 INFO - u'mochitest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 16:26:55 INFO - u'firefox-52.0a1.en-US.mac.mochitest.tests.zip'], 16:26:55 INFO - u'mozbase': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 16:26:55 INFO - u'reftest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 16:26:55 INFO - u'firefox-52.0a1.en-US.mac.reftest.tests.zip'], 16:26:55 INFO - u'talos': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 16:26:55 INFO - u'firefox-52.0a1.en-US.mac.talos.tests.zip'], 16:26:55 INFO - u'web-platform': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 16:26:55 INFO - u'firefox-52.0a1.en-US.mac.web-platform.tests.zip'], 16:26:55 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 16:26:55 INFO - u'firefox-52.0a1.en-US.mac.xpcshell.tests.zip']} 16:26:55 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip'] for test suite category: common 16:26:55 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:55 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:55 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:56 INFO - Http code: 200 16:26:56 INFO - via: 1.1 8008015354a3ca72f56c382a1d1cfe9f.cloudfront.net (CloudFront) 16:26:56 INFO - x-amz-cf-id: 9DImrt1lvICzCoMgJQAlCbJCJH1A2nmiBlkg03Kk7gqFgfWlIf11Bw== 16:26:56 INFO - x-amz-version-id: SMPvhBTK3totf3lt5JJ5PNmIT33TWLrz 16:26:56 INFO - Expected file size: 16508477 16:26:56 INFO - Obtained file size: 16508477 16:27:03 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:03 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:03 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:03 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:04 INFO - Http code: 200 16:27:04 INFO - via: 1.1 c0740de9f16cf32ffedfd2f9806b8483.cloudfront.net (CloudFront) 16:27:04 INFO - x-amz-cf-id: fVIDrT3aY03S6ZC1vvWMPoK5jJ97oYD6cOJDAYcvMgzm_QR09E6ELw== 16:27:04 INFO - x-amz-version-id: SMPvhBTK3totf3lt5JJ5PNmIT33TWLrz 16:27:04 INFO - Expected file size: 16508477 16:27:04 INFO - Obtained file size: 16508477 16:27:11 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:11 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:11 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:11 INFO - Http code: 200 16:27:11 INFO - via: 1.1 6cd88b9fd84ad5daa3b3867fb8c5c91a.cloudfront.net (CloudFront) 16:27:11 INFO - x-amz-cf-id: H8vmjEAliF27UzokAu3d_PZ473F6ipOwTu1kkgRmhN4_iXw20t2j4g== 16:27:11 INFO - x-amz-version-id: yCTwt37Pu2esJ6GkdzPXiFT_za08mX_T 16:27:11 INFO - Expected file size: 14409006 16:27:11 INFO - Obtained file size: 14409006 16:27:12 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:12 INFO - trying https://queue.taskcluster.net/v1/task/V6dbqvZfQiKZUCjcPPrl0g/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 16:27:12 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:12 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:15 INFO - Downloaded 111698919 bytes. 16:27:15 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:15 INFO - mkdir: /builds/slave/test/properties 16:27:15 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 16:27:15 INFO - Writing to file /builds/slave/test/properties/build_url 16:27:15 INFO - Contents: 16:27:15 INFO - build_url:https://queue.taskcluster.net/v1/task/V6dbqvZfQiKZUCjcPPrl0g/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 16:27:15 INFO - Running post-action listener: _resource_record_post_action 16:27:15 INFO - Running post-action listener: set_extra_try_arguments 16:27:15 INFO - [mozharness: 2016-10-17 23:27:15.850717Z] Finished download-and-extract step (success) 16:27:15 INFO - [mozharness: 2016-10-17 23:27:15.850839Z] Running populate-webroot step. 16:27:15 INFO - Running pre-action listener: _resource_record_pre_action 16:27:15 INFO - Running main action method: populate_webroot 16:27:15 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 16:27:15 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 16:27:15 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 16:27:15 INFO - '--tppagecycles', 16:27:15 INFO - '7'], 16:27:15 INFO - 'tests': ['tsvgm']}}, 16:27:15 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 16:27:15 INFO - 'tests': ['tresize', 'tcanvasmark']}, 16:27:15 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 16:27:15 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 16:27:15 INFO - 'tests': ['dromaeo_css', 'kraken']}, 16:27:15 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 16:27:15 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:27:15 INFO - 'talos_options': ['--disable-e10s'], 16:27:15 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:27:15 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:27:15 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:27:15 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:27:15 INFO - 'talos_options': ['--disable-e10s'], 16:27:15 INFO - 'tests': ['damp', 'tps']}, 16:27:15 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:27:15 INFO - 'tests': ['damp', 'tps']}, 16:27:15 INFO - 'g3': {'talos_options': ['--disable-e10s'], 16:27:15 INFO - 'tests': ['dromaeo_dom']}, 16:27:15 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 16:27:15 INFO - 'g4': {'talos_options': ['--disable-e10s'], 16:27:15 INFO - 'tests': ['basic_compositor_video']}, 16:27:15 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 16:27:15 INFO - 'other': {'talos_options': ['--disable-e10s'], 16:27:15 INFO - 'tests': ['a11yr', 16:27:15 INFO - 'ts_paint', 16:27:15 INFO - 'tpaint', 16:27:15 INFO - 'sessionrestore', 16:27:15 INFO - 'sessionrestore_no_auto_restore', 16:27:15 INFO - 'tabpaint']}, 16:27:15 INFO - 'other-e10s': {'tests': ['a11yr', 16:27:15 INFO - 'ts_paint', 16:27:15 INFO - 'tpaint', 16:27:15 INFO - 'sessionrestore', 16:27:15 INFO - 'sessionrestore_no_auto_restore', 16:27:15 INFO - 'tabpaint']}, 16:27:15 INFO - 'other-e10s_l64': {'tests': ['a11yr', 16:27:15 INFO - 'ts_paint', 16:27:15 INFO - 'tpaint', 16:27:15 INFO - 'sessionrestore', 16:27:15 INFO - 'sessionrestore_no_auto_restore', 16:27:15 INFO - 'tabpaint']}, 16:27:15 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 16:27:15 INFO - 'ts_paint', 16:27:15 INFO - 'tpaint', 16:27:15 INFO - 'sessionrestore', 16:27:15 INFO - 'sessionrestore_no_auto_restore', 16:27:15 INFO - 'tabpaint']}, 16:27:15 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 16:27:15 INFO - 'tests': ['a11yr', 16:27:15 INFO - 'ts_paint', 16:27:15 INFO - 'tpaint', 16:27:15 INFO - 'sessionrestore', 16:27:15 INFO - 'sessionrestore_no_auto_restore', 16:27:15 INFO - 'tabpaint']}, 16:27:15 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 16:27:15 INFO - 'tests': ['a11yr', 16:27:15 INFO - 'ts_paint', 16:27:15 INFO - 'tpaint', 16:27:15 INFO - 'sessionrestore', 16:27:15 INFO - 'sessionrestore_no_auto_restore', 16:27:15 INFO - 'tabpaint']}, 16:27:15 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 16:27:15 INFO - 'tests': ['tsvgx', 16:27:15 INFO - 'tsvgr_opacity', 16:27:15 INFO - 'tart', 16:27:15 INFO - 'tscrollx', 16:27:15 INFO - 'cart']}, 16:27:15 INFO - 'svgr-e10s': {'tests': ['tsvgx', 16:27:15 INFO - 'tsvgr_opacity', 16:27:15 INFO - 'tart', 16:27:15 INFO - 'tscrollx', 16:27:15 INFO - 'cart']}, 16:27:15 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:27:15 INFO - 'talos_options': ['--disable-e10s'], 16:27:15 INFO - 'tests': ['tp5o']}, 16:27:15 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:27:15 INFO - 'tests': ['tp5o']}, 16:27:15 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:27:15 INFO - 'talos_options': ['--disable-e10s', 16:27:15 INFO - '--xperf_path', 16:27:15 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:27:15 INFO - 'tests': ['tp5n']}, 16:27:15 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:27:15 INFO - 'talos_options': ['--xperf_path', 16:27:15 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:27:15 INFO - 'tests': ['tp5n']}}, 16:27:15 INFO - 'talos.zip': {'path': '', 16:27:15 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 16:27:15 INFO - Running post-action listener: _resource_record_post_action 16:27:15 INFO - [mozharness: 2016-10-17 23:27:15.869905Z] Finished populate-webroot step (success) 16:27:15 INFO - [mozharness: 2016-10-17 23:27:15.870000Z] Running create-virtualenv step. 16:27:15 INFO - Running pre-action listener: _resource_record_pre_action 16:27:15 INFO - Running main action method: create_virtualenv 16:27:15 INFO - Creating virtualenv /builds/slave/test/build/venv 16:27:15 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:15 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 16:27:15 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 16:27:15 INFO - Using real prefix '/tools/python27' 16:27:15 INFO - New python executable in /builds/slave/test/build/venv/bin/python 16:27:16 INFO - Installing distribute.............................................................................................................................................................................................done. 16:27:19 INFO - Installing pip.................done. 16:27:19 INFO - Return code: 0 16:27:19 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 16:27:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 16:27:19 INFO - Reading from file tmpfile_stdout 16:27:19 INFO - Output received: 16:27:19 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:19 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 16:27:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:27:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:27:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:27:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:27:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:27:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:27:19 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 0x105a91490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105a07570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe3594b1160>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1058a5d30>, '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 0x1056cbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10589db20>, '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.L3jjK6jxeB/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.z2OF3cmixP/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hy/45ssh3ld6sx_27c31ssq2l9w00000w/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:19 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:19 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:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.L3jjK6jxeB/Render', 16:27:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:27:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:27:19 INFO - 'HOME': '/Users/cltbld', 16:27:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:27:19 INFO - 'LOGNAME': 'cltbld', 16:27:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:27:19 INFO - 'MOZ_NO_REMOTE': '1', 16:27:19 INFO - 'NO_EM_RESTART': '1', 16:27:19 INFO - 'PAGER': '/bin/cat', 16:27:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:27:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:27:19 INFO - 'PWD': '/builds/slave/test', 16:27:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:27:19 INFO - 'SHELL': '/bin/bash', 16:27:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.z2OF3cmixP/Listeners', 16:27:19 INFO - 'TMPDIR': '/var/folders/hy/45ssh3ld6sx_27c31ssq2l9w00000w/T/', 16:27:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:27:19 INFO - 'USER': 'cltbld', 16:27:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:27:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:27:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:27:19 INFO - 'XPC_FLAGS': '0x0', 16:27:19 INFO - 'XPC_SERVICE_NAME': '0', 16:27:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:27:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:27:20 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:20 INFO - Cleaning up... 16:27:20 INFO - Return code: 0 16:27:20 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 16:27: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:27:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:27:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:27: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:27:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:27:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:27:20 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 0x105a91490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105a07570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe3594b1160>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1058a5d30>, '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 0x1056cbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10589db20>, '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.L3jjK6jxeB/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.z2OF3cmixP/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hy/45ssh3ld6sx_27c31ssq2l9w00000w/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:20 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:20 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:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.L3jjK6jxeB/Render', 16:27:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:27:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:27:20 INFO - 'HOME': '/Users/cltbld', 16:27:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:27:20 INFO - 'LOGNAME': 'cltbld', 16:27:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:27:20 INFO - 'MOZ_NO_REMOTE': '1', 16:27:20 INFO - 'NO_EM_RESTART': '1', 16:27:20 INFO - 'PAGER': '/bin/cat', 16:27:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:27:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:27:20 INFO - 'PWD': '/builds/slave/test', 16:27:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:27:20 INFO - 'SHELL': '/bin/bash', 16:27:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.z2OF3cmixP/Listeners', 16:27:20 INFO - 'TMPDIR': '/var/folders/hy/45ssh3ld6sx_27c31ssq2l9w00000w/T/', 16:27:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:27:20 INFO - 'USER': 'cltbld', 16:27:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:27:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:27:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:27:20 INFO - 'XPC_FLAGS': '0x0', 16:27:20 INFO - 'XPC_SERVICE_NAME': '0', 16:27:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:27:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:27:20 INFO - Downloading/unpacking psutil>=3.1.1 16:27:20 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:20 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:20 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:20 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:20 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:20 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 - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 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 - Installing collected packages: psutil 16:27:23 INFO - Running setup.py install for psutil 16:27:23 INFO - building 'psutil._psutil_osx' extension 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_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 16:27:24 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:24 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:24 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:24 INFO - building 'psutil._psutil_posix' extension 16:27:24 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:24 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 16:27:24 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 16:27:24 INFO - ^ 16:27:24 INFO - 1 warning generated. 16:27:24 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:24 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:27:24 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:27:24 INFO - Successfully installed psutil 16:27:24 INFO - Cleaning up... 16:27:25 INFO - Return code: 0 16:27:25 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 16:27:25 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:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:27:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:27:25 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:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:27:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:27:25 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 0x105a91490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105a07570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe3594b1160>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1058a5d30>, '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 0x1056cbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10589db20>, '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.L3jjK6jxeB/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.z2OF3cmixP/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hy/45ssh3ld6sx_27c31ssq2l9w00000w/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:25 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:25 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:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.L3jjK6jxeB/Render', 16:27:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:27:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:27:25 INFO - 'HOME': '/Users/cltbld', 16:27:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:27:25 INFO - 'LOGNAME': 'cltbld', 16:27:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:27:25 INFO - 'MOZ_NO_REMOTE': '1', 16:27:25 INFO - 'NO_EM_RESTART': '1', 16:27:25 INFO - 'PAGER': '/bin/cat', 16:27:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:27:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:27:25 INFO - 'PWD': '/builds/slave/test', 16:27:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:27:25 INFO - 'SHELL': '/bin/bash', 16:27:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.z2OF3cmixP/Listeners', 16:27:25 INFO - 'TMPDIR': '/var/folders/hy/45ssh3ld6sx_27c31ssq2l9w00000w/T/', 16:27:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:27:25 INFO - 'USER': 'cltbld', 16:27:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:27:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:27:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:27:25 INFO - 'XPC_FLAGS': '0x0', 16:27:25 INFO - 'XPC_SERVICE_NAME': '0', 16:27:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:27:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:27:25 INFO - Downloading/unpacking mozsystemmonitor==0.3 16:27:25 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:25 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:25 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:25 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:25 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:25 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 - Downloading mozsystemmonitor-0.3.tar.gz 16:27:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 16:27:27 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:27 INFO - Installing collected packages: mozsystemmonitor 16:27:27 INFO - Running setup.py install for mozsystemmonitor 16:27:28 INFO - Successfully installed mozsystemmonitor 16:27:28 INFO - Cleaning up... 16:27:28 INFO - Return code: 0 16:27:28 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 16:27:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:27:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:27:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:27:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:27:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:27:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:27:28 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 0x105a91490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105a07570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe3594b1160>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1058a5d30>, '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 0x1056cbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10589db20>, '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.L3jjK6jxeB/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.z2OF3cmixP/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hy/45ssh3ld6sx_27c31ssq2l9w00000w/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:28 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:28 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:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.L3jjK6jxeB/Render', 16:27:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:27:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:27:28 INFO - 'HOME': '/Users/cltbld', 16:27:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:27:28 INFO - 'LOGNAME': 'cltbld', 16:27:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:27:28 INFO - 'MOZ_NO_REMOTE': '1', 16:27:28 INFO - 'NO_EM_RESTART': '1', 16:27:28 INFO - 'PAGER': '/bin/cat', 16:27:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:27:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:27:28 INFO - 'PWD': '/builds/slave/test', 16:27:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:27:28 INFO - 'SHELL': '/bin/bash', 16:27:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.z2OF3cmixP/Listeners', 16:27:28 INFO - 'TMPDIR': '/var/folders/hy/45ssh3ld6sx_27c31ssq2l9w00000w/T/', 16:27:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:27:28 INFO - 'USER': 'cltbld', 16:27:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:27:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:27:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:27:28 INFO - 'XPC_FLAGS': '0x0', 16:27:28 INFO - 'XPC_SERVICE_NAME': '0', 16:27:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:27:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:27:28 INFO - Downloading/unpacking jsonschema==2.5.1 16:27:28 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:28 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:28 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:28 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:28 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:28 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 - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 16:27:30 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 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:31 INFO - Downloading functools32-3.2.3-2.tar.gz 16:27:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 16:27:31 INFO - warning: no files found matching '*.txt' 16:27:31 INFO - no previously-included directories found matching 'build' 16:27:31 INFO - no previously-included directories found matching 'dist' 16:27:31 INFO - no previously-included directories found matching '.git*' 16:27:31 INFO - Installing collected packages: jsonschema, functools32 16:27:31 INFO - Running setup.py install for functools32 16:27:31 INFO - warning: no files found matching '*.txt' 16:27:31 INFO - no previously-included directories found matching 'build' 16:27:31 INFO - no previously-included directories found matching 'dist' 16:27:31 INFO - no previously-included directories found matching '.git*' 16:27:31 INFO - Successfully installed jsonschema functools32 16:27:31 INFO - Cleaning up... 16:27:31 INFO - Return code: 0 16:27:31 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 16:27:31 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:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:27:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:27:31 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:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:27:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:27:31 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 0x105a91490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105a07570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe3594b1160>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1058a5d30>, '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 0x1056cbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10589db20>, '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.L3jjK6jxeB/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.z2OF3cmixP/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hy/45ssh3ld6sx_27c31ssq2l9w00000w/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:31 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:31 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:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.L3jjK6jxeB/Render', 16:27:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:27:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:27:31 INFO - 'HOME': '/Users/cltbld', 16:27:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:27:31 INFO - 'LOGNAME': 'cltbld', 16:27:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:27:31 INFO - 'MOZ_NO_REMOTE': '1', 16:27:31 INFO - 'NO_EM_RESTART': '1', 16:27:31 INFO - 'PAGER': '/bin/cat', 16:27:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:27:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:27:31 INFO - 'PWD': '/builds/slave/test', 16:27:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:27:31 INFO - 'SHELL': '/bin/bash', 16:27:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.z2OF3cmixP/Listeners', 16:27:31 INFO - 'TMPDIR': '/var/folders/hy/45ssh3ld6sx_27c31ssq2l9w00000w/T/', 16:27:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:27:31 INFO - 'USER': 'cltbld', 16:27:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:27:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:27:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:27:31 INFO - 'XPC_FLAGS': '0x0', 16:27:31 INFO - 'XPC_SERVICE_NAME': '0', 16:27:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:27:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:27:31 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 16:27:31 INFO - Cleaning up... 16:27:31 INFO - Return code: 0 16:27:31 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 16:27:31 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:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:27:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:27:31 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:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:27:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:27:31 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 0x105a91490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105a07570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe3594b1160>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1058a5d30>, '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 0x1056cbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10589db20>, '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.L3jjK6jxeB/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.z2OF3cmixP/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hy/45ssh3ld6sx_27c31ssq2l9w00000w/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:31 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:31 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:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.L3jjK6jxeB/Render', 16:27:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:27:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:27:31 INFO - 'HOME': '/Users/cltbld', 16:27:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:27:31 INFO - 'LOGNAME': 'cltbld', 16:27:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:27:31 INFO - 'MOZ_NO_REMOTE': '1', 16:27:31 INFO - 'NO_EM_RESTART': '1', 16:27:31 INFO - 'PAGER': '/bin/cat', 16:27:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:27:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:27:31 INFO - 'PWD': '/builds/slave/test', 16:27:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:27:31 INFO - 'SHELL': '/bin/bash', 16:27:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.z2OF3cmixP/Listeners', 16:27:31 INFO - 'TMPDIR': '/var/folders/hy/45ssh3ld6sx_27c31ssq2l9w00000w/T/', 16:27:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:27:31 INFO - 'USER': 'cltbld', 16:27:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:27:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:27:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:27:31 INFO - 'XPC_FLAGS': '0x0', 16:27:31 INFO - 'XPC_SERVICE_NAME': '0', 16:27:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:27:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:27:32 INFO - Downloading/unpacking blobuploader==1.2.4 16:27:32 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:32 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:32 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:32 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:32 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:32 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 blobuploader-1.2.4.tar.gz 16:27:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 16:27:35 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 16:27:35 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:35 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:35 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:35 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:35 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:35 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:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 16:27:35 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 16:27:35 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:35 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:35 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:35 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:35 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:35 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:36 INFO - Downloading docopt-0.6.1.tar.gz 16:27:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 16:27:36 INFO - Installing collected packages: blobuploader, requests, docopt 16:27:36 INFO - Running setup.py install for blobuploader 16:27:36 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 16:27:36 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 16:27:36 INFO - Running setup.py install for requests 16:27:36 INFO - Running setup.py install for docopt 16:27:37 INFO - Successfully installed blobuploader requests docopt 16:27:37 INFO - Cleaning up... 16:27:37 INFO - Return code: 0 16:27:37 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:27:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:27:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:27:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:27:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:27:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:27:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:27:37 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 0x105a91490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105a07570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe3594b1160>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1058a5d30>, '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 0x1056cbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10589db20>, '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.L3jjK6jxeB/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.z2OF3cmixP/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hy/45ssh3ld6sx_27c31ssq2l9w00000w/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:37 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:37 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:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.L3jjK6jxeB/Render', 16:27:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:27:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:27:37 INFO - 'HOME': '/Users/cltbld', 16:27:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:27:37 INFO - 'LOGNAME': 'cltbld', 16:27:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:27:37 INFO - 'MOZ_NO_REMOTE': '1', 16:27:37 INFO - 'NO_EM_RESTART': '1', 16:27:37 INFO - 'PAGER': '/bin/cat', 16:27:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:27:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:27:37 INFO - 'PWD': '/builds/slave/test', 16:27:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:27:37 INFO - 'SHELL': '/bin/bash', 16:27:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.z2OF3cmixP/Listeners', 16:27:37 INFO - 'TMPDIR': '/var/folders/hy/45ssh3ld6sx_27c31ssq2l9w00000w/T/', 16:27:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:27:37 INFO - 'USER': 'cltbld', 16:27:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:27:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:27:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:27:37 INFO - 'XPC_FLAGS': '0x0', 16:27:37 INFO - 'XPC_SERVICE_NAME': '0', 16:27:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:27:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:27:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:27:38 INFO - Running setup.py (path:/var/folders/hy/45ssh3ld6sx_27c31ssq2l9w00000w/T/pip-NTLKzq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:27:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:27:38 INFO - Running setup.py (path:/var/folders/hy/45ssh3ld6sx_27c31ssq2l9w00000w/T/pip-MlXcQH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:27:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:27:38 INFO - Running setup.py (path:/var/folders/hy/45ssh3ld6sx_27c31ssq2l9w00000w/T/pip-FozeGQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:27:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:27:38 INFO - Running setup.py (path:/var/folders/hy/45ssh3ld6sx_27c31ssq2l9w00000w/T/pip-nA984y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:27:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:27:38 INFO - Running setup.py (path:/var/folders/hy/45ssh3ld6sx_27c31ssq2l9w00000w/T/pip-XUaxlF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:27:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:27:38 INFO - Running setup.py (path:/var/folders/hy/45ssh3ld6sx_27c31ssq2l9w00000w/T/pip-hTwLgH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:27:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:27:38 INFO - Running setup.py (path:/var/folders/hy/45ssh3ld6sx_27c31ssq2l9w00000w/T/pip-clpEmT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:27:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:27:38 INFO - Running setup.py (path:/var/folders/hy/45ssh3ld6sx_27c31ssq2l9w00000w/T/pip-rmvWfg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:27:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:27:39 INFO - Running setup.py (path:/var/folders/hy/45ssh3ld6sx_27c31ssq2l9w00000w/T/pip-gmntpF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:27:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:27:39 INFO - Running setup.py (path:/var/folders/hy/45ssh3ld6sx_27c31ssq2l9w00000w/T/pip-4d844z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:27:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:27:39 INFO - Running setup.py (path:/var/folders/hy/45ssh3ld6sx_27c31ssq2l9w00000w/T/pip-xCx2ip-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:27:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:27:39 INFO - Running setup.py (path:/var/folders/hy/45ssh3ld6sx_27c31ssq2l9w00000w/T/pip-Q1G7vg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:27:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:27:39 INFO - Running setup.py (path:/var/folders/hy/45ssh3ld6sx_27c31ssq2l9w00000w/T/pip-CfdW_r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:27:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:27:39 INFO - Running setup.py (path:/var/folders/hy/45ssh3ld6sx_27c31ssq2l9w00000w/T/pip-BLWEVi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:27:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:27:39 INFO - Running setup.py (path:/var/folders/hy/45ssh3ld6sx_27c31ssq2l9w00000w/T/pip-i9fDyG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:27:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:27:39 INFO - Running setup.py (path:/var/folders/hy/45ssh3ld6sx_27c31ssq2l9w00000w/T/pip-LbeJcs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:27:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:27:39 INFO - Running setup.py (path:/var/folders/hy/45ssh3ld6sx_27c31ssq2l9w00000w/T/pip-JnfStg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:27:39 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 16:27:39 INFO - Running setup.py install for manifestparser 16:27:40 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 16:27:40 INFO - Running setup.py install for mozcrash 16:27:40 INFO - Running setup.py install for mozdebug 16:27:40 INFO - Running setup.py install for mozdevice 16:27:40 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 16:27:40 INFO - Installing dm script to /builds/slave/test/build/venv/bin 16:27:40 INFO - Running setup.py install for mozfile 16:27:40 INFO - Running setup.py install for mozhttpd 16:27:40 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 16:27:40 INFO - Running setup.py install for mozinfo 16:27:41 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 16:27:41 INFO - Running setup.py install for mozInstall 16:27:41 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 16:27:41 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 16:27:41 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 16:27:41 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 16:27:41 INFO - Running setup.py install for mozleak 16:27:41 INFO - Running setup.py install for mozlog 16:27:41 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 16:27:41 INFO - Running setup.py install for moznetwork 16:27:41 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 16:27:41 INFO - Running setup.py install for mozprocess 16:27:41 INFO - Running setup.py install for mozprofile 16:27:41 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 16:27:41 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 16:27:41 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 16:27:41 INFO - Running setup.py install for mozrunner 16:27:42 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 16:27:42 INFO - Running setup.py install for mozscreenshot 16:27:42 INFO - Running setup.py install for moztest 16:27:42 INFO - Running setup.py install for mozversion 16:27:42 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 16:27:42 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 16:27:42 INFO - Cleaning up... 16:27:42 INFO - Return code: 0 16:27:42 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:27:42 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:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:27:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:27:42 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:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:27:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:27:43 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 0x105a91490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105a07570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe3594b1160>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1058a5d30>, '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 0x1056cbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10589db20>, '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.L3jjK6jxeB/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.z2OF3cmixP/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hy/45ssh3ld6sx_27c31ssq2l9w00000w/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:43 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:43 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:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.L3jjK6jxeB/Render', 16:27:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:27:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:27:43 INFO - 'HOME': '/Users/cltbld', 16:27:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:27:43 INFO - 'LOGNAME': 'cltbld', 16:27:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:27:43 INFO - 'MOZ_NO_REMOTE': '1', 16:27:43 INFO - 'NO_EM_RESTART': '1', 16:27:43 INFO - 'PAGER': '/bin/cat', 16:27:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:27:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:27:43 INFO - 'PWD': '/builds/slave/test', 16:27:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:27:43 INFO - 'SHELL': '/bin/bash', 16:27:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.z2OF3cmixP/Listeners', 16:27:43 INFO - 'TMPDIR': '/var/folders/hy/45ssh3ld6sx_27c31ssq2l9w00000w/T/', 16:27:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:27:43 INFO - 'USER': 'cltbld', 16:27:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:27:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:27:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:27:43 INFO - 'XPC_FLAGS': '0x0', 16:27:43 INFO - 'XPC_SERVICE_NAME': '0', 16:27:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:27:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:27:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:27:43 INFO - Running setup.py (path:/var/folders/hy/45ssh3ld6sx_27c31ssq2l9w00000w/T/pip-YCXlF9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:27:43 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:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:27:43 INFO - Running setup.py (path:/var/folders/hy/45ssh3ld6sx_27c31ssq2l9w00000w/T/pip-Q75QqJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:27:43 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:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:27:43 INFO - Running setup.py (path:/var/folders/hy/45ssh3ld6sx_27c31ssq2l9w00000w/T/pip-rBgDSU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:27:43 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:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:27:43 INFO - Running setup.py (path:/var/folders/hy/45ssh3ld6sx_27c31ssq2l9w00000w/T/pip-GA2iy_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:27:43 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:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:27:43 INFO - Running setup.py (path:/var/folders/hy/45ssh3ld6sx_27c31ssq2l9w00000w/T/pip-OSDg5n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:27:43 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:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:27:43 INFO - Running setup.py (path:/var/folders/hy/45ssh3ld6sx_27c31ssq2l9w00000w/T/pip-Q0HZV0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:27:43 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:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:27:43 INFO - Running setup.py (path:/var/folders/hy/45ssh3ld6sx_27c31ssq2l9w00000w/T/pip-e_Y0vc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:27:44 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:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:27:44 INFO - Running setup.py (path:/var/folders/hy/45ssh3ld6sx_27c31ssq2l9w00000w/T/pip-tn2Iry-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:27:44 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:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:27:44 INFO - Running setup.py (path:/var/folders/hy/45ssh3ld6sx_27c31ssq2l9w00000w/T/pip-fMStkJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:27:44 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:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:27:44 INFO - Running setup.py (path:/var/folders/hy/45ssh3ld6sx_27c31ssq2l9w00000w/T/pip-LSL4UL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:27:44 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:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:27:44 INFO - Running setup.py (path:/var/folders/hy/45ssh3ld6sx_27c31ssq2l9w00000w/T/pip-VyAEZJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:27:44 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:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:27:44 INFO - Running setup.py (path:/var/folders/hy/45ssh3ld6sx_27c31ssq2l9w00000w/T/pip-9whcxH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:27:44 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:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:27:44 INFO - Running setup.py (path:/var/folders/hy/45ssh3ld6sx_27c31ssq2l9w00000w/T/pip-3xvEpQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:27:44 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:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:27:44 INFO - Running setup.py (path:/var/folders/hy/45ssh3ld6sx_27c31ssq2l9w00000w/T/pip-NNeL20-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:27:44 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:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:27:44 INFO - Running setup.py (path:/var/folders/hy/45ssh3ld6sx_27c31ssq2l9w00000w/T/pip-hluSog-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:27:44 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:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:27:44 INFO - Running setup.py (path:/var/folders/hy/45ssh3ld6sx_27c31ssq2l9w00000w/T/pip-kkg1YT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:27:45 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:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:27:45 INFO - Running setup.py (path:/var/folders/hy/45ssh3ld6sx_27c31ssq2l9w00000w/T/pip-kr36tg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:27:45 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:45 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:45 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:45 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:45 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:45 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:45 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 16:27:45 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:45 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:45 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:45 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:45 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:45 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:47 INFO - Downloading blessings-1.6.tar.gz 16:27:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 16:27:47 INFO - Installing collected packages: blessings 16:27:47 INFO - Running setup.py install for blessings 16:27:48 INFO - Successfully installed blessings 16:27:48 INFO - Cleaning up... 16:27:48 INFO - Return code: 0 16:27:48 INFO - Done creating virtualenv /builds/slave/test/build/venv. 16:27:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:27:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:27:48 INFO - Reading from file tmpfile_stdout 16:27:48 INFO - Current package versions: 16:27:48 INFO - blessings == 1.6 16:27:48 INFO - blobuploader == 1.2.4 16:27:48 INFO - docopt == 0.6.1 16:27:48 INFO - functools32 == 3.2.3-2 16:27:48 INFO - jsonschema == 2.5.1 16:27:48 INFO - manifestparser == 1.1 16:27:48 INFO - mozInstall == 1.12 16:27:48 INFO - mozcrash == 0.17 16:27:48 INFO - mozdebug == 0.1 16:27:48 INFO - mozdevice == 0.48 16:27:48 INFO - mozfile == 1.2 16:27:48 INFO - mozhttpd == 0.7 16:27:48 INFO - mozinfo == 0.9 16:27:48 INFO - mozleak == 0.1 16:27:48 INFO - mozlog == 3.4 16:27:48 INFO - moznetwork == 0.27 16:27:48 INFO - mozprocess == 0.23 16:27:48 INFO - mozprofile == 0.28 16:27:48 INFO - mozrunner == 6.12 16:27:48 INFO - mozscreenshot == 0.1 16:27:48 INFO - mozsystemmonitor == 0.3 16:27:48 INFO - moztest == 0.7 16:27:48 INFO - mozversion == 1.4 16:27:48 INFO - psutil == 3.1.1 16:27:48 INFO - requests == 1.2.3 16:27:48 INFO - wsgiref == 0.1.2 16:27:48 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:27:48 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:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:27:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:27:48 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:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:27:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:27:48 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 0x105a91490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105a07570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe3594b1160>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1058a5d30>, '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 0x1056cbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10589db20>, '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.L3jjK6jxeB/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.z2OF3cmixP/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hy/45ssh3ld6sx_27c31ssq2l9w00000w/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:48 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:48 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:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.L3jjK6jxeB/Render', 16:27:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:27:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:27:48 INFO - 'HOME': '/Users/cltbld', 16:27:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:27:48 INFO - 'LOGNAME': 'cltbld', 16:27:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:27:48 INFO - 'MOZ_NO_REMOTE': '1', 16:27:48 INFO - 'NO_EM_RESTART': '1', 16:27:48 INFO - 'PAGER': '/bin/cat', 16:27:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:27:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:27:48 INFO - 'PWD': '/builds/slave/test', 16:27:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:27:48 INFO - 'SHELL': '/bin/bash', 16:27:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.z2OF3cmixP/Listeners', 16:27:48 INFO - 'TMPDIR': '/var/folders/hy/45ssh3ld6sx_27c31ssq2l9w00000w/T/', 16:27:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:27:48 INFO - 'USER': 'cltbld', 16:27:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:27:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:27:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:27:48 INFO - 'XPC_FLAGS': '0x0', 16:27:48 INFO - 'XPC_SERVICE_NAME': '0', 16:27:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:27:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:27:48 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:48 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:48 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:48 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:48 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:48 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:48 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:48 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:48 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:48 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:48 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:48 INFO - Cleaning up... 16:27:48 INFO - Return code: 0 16:27:48 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 16:27:48 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:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:27:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:27:48 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:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:27:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:27:48 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 0x105a91490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105a07570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe3594b1160>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1058a5d30>, '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 0x1056cbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10589db20>, '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.L3jjK6jxeB/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.z2OF3cmixP/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hy/45ssh3ld6sx_27c31ssq2l9w00000w/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:48 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:48 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:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.L3jjK6jxeB/Render', 16:27:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:27:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:27:48 INFO - 'HOME': '/Users/cltbld', 16:27:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:27:48 INFO - 'LOGNAME': 'cltbld', 16:27:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:27:48 INFO - 'MOZ_NO_REMOTE': '1', 16:27:48 INFO - 'NO_EM_RESTART': '1', 16:27:48 INFO - 'PAGER': '/bin/cat', 16:27:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:27:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:27:48 INFO - 'PWD': '/builds/slave/test', 16:27:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:27:48 INFO - 'SHELL': '/bin/bash', 16:27:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.z2OF3cmixP/Listeners', 16:27:48 INFO - 'TMPDIR': '/var/folders/hy/45ssh3ld6sx_27c31ssq2l9w00000w/T/', 16:27:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:27:48 INFO - 'USER': 'cltbld', 16:27:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:27:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:27:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:27:48 INFO - 'XPC_FLAGS': '0x0', 16:27:48 INFO - 'XPC_SERVICE_NAME': '0', 16:27:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:27:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:27:48 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 16:27:48 INFO - Cleaning up... 16:27:48 INFO - Return code: 0 16:27:48 INFO - Running post-action listener: _resource_record_post_action 16:27:48 INFO - Running post-action listener: _start_resource_monitoring 16:27:48 INFO - Starting resource monitoring. 16:27:49 INFO - [mozharness: 2016-10-17 23:27:49.000435Z] Finished create-virtualenv step (success) 16:27:49 INFO - [mozharness: 2016-10-17 23:27:49.001419Z] Running install step. 16:27:49 INFO - Running pre-action listener: _resource_record_pre_action 16:27:49 INFO - Running main action method: install 16:27:49 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:27:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:27:49 INFO - Reading from file tmpfile_stdout 16:27:49 INFO - Detecting whether we're running mozinstall >=1.0... 16:27:49 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 16:27:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 16:27:49 INFO - Reading from file tmpfile_stdout 16:27:49 INFO - Output received: 16:27:49 INFO - Usage: mozinstall [options] installer 16:27:49 INFO - Options: 16:27:49 INFO - -h, --help show this help message and exit 16:27:49 INFO - -d DEST, --destination=DEST 16:27:49 INFO - Directory to install application into. [default: 16:27:49 INFO - "/builds/slave/test"] 16:27:49 INFO - --app=APP Application being installed. [default: firefox] 16:27:49 INFO - mkdir: /builds/slave/test/build/application 16:27:49 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:49 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:23 INFO - Reading from file tmpfile_stdout 16:28:23 INFO - Output received: 16:28:23 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 16:28:23 INFO - Running post-action listener: _resource_record_post_action 16:28:23 INFO - [mozharness: 2016-10-17 23:28:23.854934Z] Finished install step (success) 16:28:23 INFO - [mozharness: 2016-10-17 23:28:23.855088Z] Running run-tests step. 16:28:23 INFO - Running pre-action listener: _resource_record_pre_action 16:28:23 INFO - Running main action method: run_tests 16:28:23 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 16:28:23 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 16:28:23 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 16:28:23 INFO - 2016-10-17 16:28:23.869 screenresolution[1638:7561] starting screenresolution argv=screenresolution get 16:28:23 INFO - 2016-10-17 16:28:23.872 screenresolution[1638:7561] Display 0: 1600x1200x32@60 16:28:23 INFO - 2016-10-17 16:28:23.878 screenresolution[1639:7563] starting screenresolution argv=screenresolution list 16:28:23 INFO - Available Modes on Display 0 16:28:23 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 16:28:23 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 16:28:23 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 16:28:23 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 16:28:24 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 16:28:24 INFO - Intel Iris: 16:28:24 INFO - Chipset Model: Intel Iris 16:28:24 INFO - Type: GPU 16:28:24 INFO - Bus: Built-In 16:28:24 INFO - VRAM (Dynamic, Max): 1536 MB 16:28:24 INFO - Vendor: Intel (0x8086) 16:28:24 INFO - Device ID: 0x0a2e 16:28:24 INFO - Revision ID: 0x0009 16:28:24 INFO - Displays: 16:28:24 INFO - Display: 16:28:24 INFO - Resolution: 1600 x 1200 @ 60 Hz 16:28:24 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 16:28:24 INFO - Display Serial Number: 1600x1200 60 16:28:24 INFO - Main Display: Yes 16:28:24 INFO - Mirror: Off 16:28:24 INFO - Online: Yes 16:28:24 INFO - Rotation: Supported 16:28:24 INFO - Return code: 0 16:28:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 16:28:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 16:28:24 INFO - Python 2.7.3 16:28:24 INFO - Return code: 0 16:28:24 INFO - grabbing minidump binary from tooltool 16:28:24 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:24 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 0x1058a5d30>, '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 0x1056cbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10589db20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 16:28:24 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:24 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:24 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:24 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 16:28:24 INFO - Return code: 0 16:28:24 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 16:28:24 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 16:28:24 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 16:28:24 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 16:28:24 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 16:28:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Larch', '--suite', 'dromaeojs-e10s', '--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-0242', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 16:28:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Larch --suite dromaeojs-e10s --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-0242 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 16:28:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.L3jjK6jxeB/Render', 16:28:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:28:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:28:24 INFO - 'HOME': '/Users/cltbld', 16:28:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:28:24 INFO - 'LOGNAME': 'cltbld', 16:28:24 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:28:24 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 16:28:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:28:24 INFO - 'MOZ_NO_REMOTE': '1', 16:28:24 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:28:24 INFO - 'NO_EM_RESTART': '1', 16:28:24 INFO - 'PAGER': '/bin/cat', 16:28:24 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:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:28:24 INFO - 'PWD': '/builds/slave/test', 16:28:24 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:28:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:28:24 INFO - 'SHELL': '/bin/bash', 16:28:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.z2OF3cmixP/Listeners', 16:28:24 INFO - 'TMPDIR': '/var/folders/hy/45ssh3ld6sx_27c31ssq2l9w00000w/T/', 16:28:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:28:24 INFO - 'USER': 'cltbld', 16:28:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:28:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:28:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:28:24 INFO - 'XPC_FLAGS': '0x0', 16:28:24 INFO - 'XPC_SERVICE_NAME': '0', 16:28:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:28:24 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Larch', '--suite', 'dromaeojs-e10s', '--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-0242', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 16:28:24 INFO - mozversion application_buildid: 20161017142049 16:28:24 INFO - mozversion application_changeset: 8c8266b3c23446275dc503366fd739ba3bc4834a 16:28:24 INFO - mozversion application_display_name: Nightly 16:28:24 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 16:28:24 INFO - mozversion application_name: Firefox 16:28:24 INFO - mozversion application_remotingname: firefox 16:28:24 INFO - mozversion application_repository: https://hg.mozilla.org/projects/larch 16:28:24 INFO - mozversion application_vendor: Mozilla 16:28:24 INFO - mozversion application_version: 52.0a1 16:28:24 INFO - mozversion platform_buildid: 20161017142049 16:28:24 INFO - mozversion platform_changeset: 8c8266b3c23446275dc503366fd739ba3bc4834a 16:28:24 INFO - mozversion platform_repository: https://hg.mozilla.org/projects/larch 16:28:24 INFO - mozversion platform_version: 52.0a1 16:28:24 INFO - using testdate: 1476746904 16:28:24 INFO - actual date: 1476746904 16:28:24 INFO - starting webserver on 'localhost:49277' 16:28:24 INFO - SUITE-START | Running 2 tests 16:28:24 INFO - TEST-START | dromaeo_css 16:28:24 INFO - operating with platform_type : mac_ 16:28:24 INFO - Initialising browser for dromaeo_css test... 16:28:24 INFO - TEST-INFO | started process 1649 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/hy/45ssh3ld6sx_27c31ssq2l9w00000w/T/tmp8QQWo1/profile http://localhost:49277/getInfo.html) 16:28:27 INFO - PROCESS | 1649 | 2016-10-17 16:28:27.145 plugin-container[1651:7727] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8f3f, name = 'com.apple.tsm.portname' 16:28:27 INFO - PROCESS | 1649 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:28:27 INFO - PROCESS | 1649 | 2016-10-17 16:28:27.148 plugin-container[1651:7727] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8d4b, name = 'com.apple.CFPasteboardClient' 16:28:27 INFO - PROCESS | 1649 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:28:27 INFO - PROCESS | 1649 | 2016-10-17 16:28:27.148 plugin-container[1651:7727] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 16:28:27 INFO - PROCESS | 1649 | __metrics Screen width/height:1600/1200 16:28:27 INFO - PROCESS | 1649 | colorDepth:24 16:28:27 INFO - PROCESS | 1649 | Browser inner width/height: 1024/689 16:28:27 INFO - PROCESS | 1649 | __metrics 16:28:27 ERROR - PROCESS | 1649 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3083: TypeError: this.tabs is undefined 16:28:27 INFO - TEST-INFO | 1649: exit 0 16:28:27 INFO - Browser initialized. 16:28:27 INFO - Running cycle 1/1 for dromaeo_css test... 16:28:27 INFO - TEST-INFO | started process 1652 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/hy/45ssh3ld6sx_27c31ssq2l9w00000w/T/tmp8QQWo1/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:28:30 INFO - PROCESS | 1652 | 2016-10-17 16:28:30.588 plugin-container[1653:7850] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4443, name = 'com.apple.tsm.portname' 16:28:30 INFO - PROCESS | 1652 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:28:30 INFO - PROCESS | 1652 | 2016-10-17 16:28:30.588 plugin-container[1653:7850] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x460f, name = 'com.apple.CFPasteboardClient' 16:28:30 INFO - PROCESS | 1652 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:28:30 INFO - PROCESS | 1652 | 2016-10-17 16:28:30.588 plugin-container[1653:7850] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 16:28:40 INFO - PROCESS | 1652 | RSS: Main: 244973568 16:28:40 INFO - PROCESS | 1652 | 16:29:29 INFO - PROCESS | 1652 | 1476746969938 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:43 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49277/tests/dromaeo/cssquery-ext.html) 16:31:44 INFO - PROCESS | 1652 | RSS: Main: 257536000 16:31:44 INFO - PROCESS | 1652 | 16:34:46 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-ext.html (next: http://localhost:49277/tests/dromaeo/cssquery-jquery.html) 16:34:47 INFO - PROCESS | 1652 | RSS: Main: 267812864 16:34:47 INFO - PROCESS | 1652 | 16:37:49 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49277/tests/dromaeo/cssquery-mootools.html) 16:37:49 INFO - PROCESS | 1652 | RSS: Main: 272814080 16:37:49 INFO - PROCESS | 1652 | 16:40:51 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49277/tests/dromaeo/cssquery-prototype.html) 16:40:52 INFO - PROCESS | 1652 | RSS: Main: 272424960 16:40:52 INFO - PROCESS | 1652 | 16:43:55 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49277/tests/dromaeo/cssquery-yui.html) 16:43:55 INFO - PROCESS | 1652 | RSS: Main: 277811200 16:43:55 INFO - PROCESS | 1652 | 16:46:58 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-yui.html (next: http://localhost:49277/tests/dromaeo/cssquery-dojo.html) 16:46:58 INFO - PROCESS | 1652 | RSS: Main: 280539136 16:46:58 INFO - PROCESS | 1652 | 16:46:58 INFO - PROCESS | 1652 | __start_tp_report 16:46:58 INFO - PROCESS | 1652 | _x_x_mozilla_page_load 16:46:58 INFO - PROCESS | 1652 | _x_x_mozilla_page_load_details 16:46:58 INFO - PROCESS | 1652 | |i|pagename|runs| 16:46:58 INFO - PROCESS | 1652 | |0;dojo.html;3002.939941201176;3093.783435159539;3364.3271345730845;3372.258103217291;3402.1834759657672;4510.706804057734;5003;5608.43915608439;5615.522680572146;5634.3520495143075;5621.409751976034;5637.069883469233;5697.572682048843;5768.913466298009;5786.421357864211;5126.92309615356;5173.456787037363;5179.611529135311;5258.6318957672975;5310.36275646921;4013.9197216055663;4353.216421044211;5282.445343238962;5286.101362768339;5308.124159513675;5098.694078355311;5135.35808023997;5142.382914050338;5224.843254702365;5258.2638430619745;5073.695578265316;5169.663971841818;5188.0661480933795;5213.165893457067;5255.816046438395;5118.61610379223;5133.204353325241;5136.922946155811;5205.193195054772;5214.843554693365;5201.817936372209;5511.035568775448;5641.633293835887;5655.293088363955;5682.7442765075675;6051.606645568024;6055.5761096723245;6149.077638354238;6191.938080619227;6203.2245969253845;5298.708571028592;6281;6293.402126797947;6320.399562041599;6324.3991820776955;5801.9419805802245;5834.737436815353;5839.9124013139835;5861.413858614104;5884.440978107073;6165.784197553064;6174.783882564088;6198.380161983766;6213.502919766408;6271.592346497463;6111.602745821508;6128.105930682418;6177;6189;6204.906926396107;6086.665233412116;6255.061740738925;6275.780347687901;6287.559870808999;6293.402126797947;6106.187656479728;6133.539984501181;6136.386361363828;6152.692365381713;6194.81415557534;4778.1638213312535;6011.947909115887;6189.381061893865;6206.224221972254;6224.128621992924;4862.80548778052;4894.779734911937;4910.975445122752;4915.992221594564;4940.135476291634;4318.438602981592;4347.7826108694435;4371.322445021026;4376.234159022159;4425.291953287523;5298.655587386807;5304.496072873071;5826.300843898759;5841.620294680833;5892.793752218652;6194.721237544321;6247.125402443661;6274.058891166362;6294.464970477469;6307.905381419282;5649.209110724501;5705.2012718219485;5787.334456537552;5794.391588883106;5820.912686309709;5374.193870919394;5385.27298814655;5415.241866138744;5424.403315635277;5437.4562543745305;6224.782132625336;6249.843753906007;6285.96857015712;6288.151099601495;6320.494360451153;4557.384753058294;5835.67903765297;6117.9492029047315;6156.753729850935;6160.568760186744;6226.377362263737;6280.623162610258;6293.150424692791;6299.6535190564955;6304.84237894038;6134.110553969738;6224.315325314121;6249.7187626557825;6252.249730032425;6264.934961056722;6094.542909281733;6218.253809542884;6261.436470717657;6285.214348206474;6293.937060629518;6099.370313949917;6108.389161084034;6111.98679349419;6113.5414843886;6190;6123.295820980644;6131.877362452637;6136.110264011782;6140.587664837023;6143.600665956698;5583.637063590853;5788.189653448437;5907.3501914790195;5934.139549765347;6017.488513476404;6138.785142519991;6157.353477884758;6166.167567378347;6171.413715697002;6175.166352542529;5968.522518198621;5981.342052374148;5984.820455386345;6009.909851352146;6017.488513476228 16:46:58 INFO - PROCESS | 1652 | |1;ext.html;513.2532165698908;526.9973650131747;527.3118580252768;528.5137673340527;531.1687209517105;38724;40675.389869151986;40719.59280407195;40745.79627101868;40762;41790.16419671605;42273.36589951153;42321;42369.788151059205;42434.15131697364;8081.7171399001045;9604.663836765714;9614.471204083773;9625.71122866315;9640.325177237597;9472.81054378912;9545.713628591153;9559.522023898811;9560.330776845623;9602.75993100171;7837.608119594026;8309.83380332393;9485.810283794355;9607.991160928106;9625.759356016086;9428.528573571328;9493.81012379752;9555;9562.713118606453;9570.808583828353;9407.059294070606;9435.669751558731;9487;9497.050294970517;9509.619615215452;39743.60256397457;39879.401808972885;40082.997925051815;40180;40216.19567608661;3277.770556061053;3367.410703126943;3436.5017072524342;3684.281565094808;3989.3617021276455;30097.548536771967;30189.396212075637;30710.161229846268;30784.538231926537;30807;3148.9055328340187;3300.900972970791;3358.0765289545325;3362.495625656147;3363.6636336366414;491022;492088;494624.5268773633;496323;498670.5066474681;55345;55666.44333556694;55688.72155639196;55821.88356232934;55860;58487.70756146277;58945.347459364435;59039.70480147572;59163.40836591579;59356.40643593509;14953.102813831205;15163.469278575418;15168.786497080207;15219.467318643794;15270.847291526943;11748.941255293668;11789.351585662871;11795.587154449724;11841.526338946345;11841.5855445059;11832.940835295769;14930.552083437515;14935.850641493664;15004.549863504113;15121.621959451102;3122.953155702666;3192.952105718416;3219.259570298844;5924.851878702981;5985.970070149621;889.3818795936891;897.6544030548156;968.5544649461186;977.3647129365827;993.911666724935;26455.603165952525;29517.409651807273;29937;30089.849550752544;30174.54738178929;954.8901876284177;991.8710567626163;1043.4939054558533;1058.6982709927788;1088.2273585754044;14872.62818429548;15341.693166136614;15468.5359439217;15510.224488775742;15590.376384944702;553608;675723;677861;680010;686357;55335.44664553383;55528.72235638796;55793.25757310674;55796.44203557912;56026.719866401225;1285.8135570342247;1310.0567591334225;1323.4110820684896;1325.5758157389735;1331.9667008324675;25743.613845791955;29295.777464478462;29483.85258073696;29618.555721664623;29755.958541450946;48041;48097.557073287855;48339;48403.75798120987;48662;600375;607357;612165;613020;614285;25846.483070338865;25970.350741230865;26148.60777088307;26207.60688589711;26869.462610747283;41972.160556789306;43343.78328108339;43489.34765978576;43694;44026.559734402246;36077.45883811797;36243;36533.269334612625;38094.61905380911;39390.606093938695;31095;37780;37981.24037519179;38849.41725874058;38868.61131388763;36567.902962911154;37276.44085338668;37366.439503408015;37601;37625;26741.732582673925;28583.714162858105;30942;33444.66555334415;34029;25286.49427011486;25326.74673253244;25462.618060728735;25680.614790778527;25775.87112064428 16:46:58 INFO - PROCESS | 1652 | |2;jquery.html;364.64447164015104;365.8737735481259;370.74603896331007;373.9775613463191;395.1839451532585;18576.442706718804;19288.903555482237;19568.21727130915;19651.99999999998;19927.800721992775;19202.519937001583;19439.514012149702;19577.412677619657;19624.018799060024;19658.918759468223;5454.672719636815;5655.745491452894;5682.51698605619;5869.266341707286;5914.2015827863115;5751.683657398841;5935.673537955411;5937.168796368512;5951.6429014259065;5963.880722385571;5565.220869078333;5797.5651826113;5832.358440571534;5890.499307558863;5893.58744887858;5758.280214973129;5778.393268706767;5830.3586605473365;5856.912146317809;5878.823635290927;5008.749562521859;5135.537801597874;5295.391030031557;5339.893202135935;5397.27136836526;12512.874871251262;12755.043371747064;12765.914897233746;18210.90894545532;22663.20678776235;2132.1791110422446;2216.7561568227484;2217.0909926930044;2238.418011317069;2273.283915566591;9480.336376453652;9623.326367154303;9679.274054445945;9812.411255324632;9932.851007234898;1754.3859649122705;1813.6826055440245;1843.3456123076248;1854.6939754940418;1873.8594605404512;118762.4061879685;123824.14263786051;123871;124847.37576312151;125117;9068.274538036942;9547.236221102296;10266.435346056038;10699.983501567338;11587.536498539965;14397.280135993158;14418.783718244234;14428.350724217435;14441.855581444051;14454.421823126957;6414.358564143548;6624.6025238485845;6782.423494002966;8205.548694821842;8287.709930152416;6129.938700612936;7797.337226335825;7874.370050395954;7918.920810791934;8085.393595480363;4384.956150438455;4499.842505512292;4528.071745292207;4565.634749220055;4630.21286381316;1906.2660875563008;1916.7029110487617;1920.3758778397078;1921.404364646936;1947.8928658923612;794.6821271491334;795.5385876191795;906.9727908162765;959.3690725766226;959.9440615323228;19571.608567828564;20457.38627841167;20510.692339614918;20532.178712851317;20638.690419643717;817.8446095241885;876.7895705030746;882.3029806452959;904.9185573298435;923.5013092929877;10133.391996480235;10782.456940574622;10784.352938823695;10795.513888543537;10849.96925292096;592683;594589.027054862;599133;599599;609563;110423;111008;111639;126547.36726316309;130896;967.8403063494585;993.1062044160169;1010.6008126790024;1011.8633984412157;1016.7966406718685;17678.646427071642;18431.986240756887;18459.630807384045;18601.81398186055;18674.81325186785;50020.49979500158;50880;52180.47819521756;52463.73768131135;53793;50764.238536421246;66816.6659166701;69748.30251697621;74559.25440745523;76376;7323.340899319087;7510.699572017058;7530.134034586093;7629.6566654499575;7662.923370766221;8964.820703585761;10202.438865862448;11372.97643212115;11427.885721143015;12530.060245481443;8880.333974952033;10362.533685983997;10654;10754.73113172177;10930.453477325942;10371.340585506283;10482.737931551772;10609.469526523488;10641.201909856636;10792.190585705946;9364.95317523408;10677.305975111592;10772.138228941823;10985.231033827555;11023.228374013743;6232.5041989923;7377.5204611700065;7487.812804679708;7511.661975211238;7655.157932627518;7882.330001949669;8804.559772011502;10303.53634086453;10440.112590429899;10508.369497830156 16:46:58 INFO - PROCESS | 1652 | |3;mootools.html;1501.932413041413;1536.4775976168105;1727.1968534631394;1796.3263776083968;1828.8171182881706;7890.684372625088;8178.263956243937;8428.91571084289;8995;9013.864792028126;8415.495070295787;8766.605502752376;8774.956125219365;8887.11128887111;8916.242119419858;2306.423394151462;2327.9301620951314;2345.4839935214227;2348.154664320843;2363.7045369328835;2091.9122056530596;2291.049214575949;2294.609916314222;2313.704325577682;2354.987355437161;2298.609236429811;2304.873231972241;2315.8147348212187;2320.6519022146726;2323.93028209154;2291.46150654596;2297.368223738477;2298.5517824024323;2302.9424264393356;2307.896144673485;2289.8740569268684;2292.7363353214428;2298.138198175684;2299.471121642015;2300.252417964168;8473.915260847374;8595.44129631572;8601.5699215039;8606.010308814504;8610.268127209194;5182.481751824825;5326.7070311132875;5350.5452036576935;5351.491608297206;5357.4106848246665;3490.3019396120876;3555.3955827509385;3625.3655610268097;3685.7051435885064;3685.981570092159;1040.8534998698965;1074.7635520185625;1078.8813230544633;1091.7052395853075;1098.5605757696906;11510.136739744552;11617.593384231512;11618.535258589647;11634.883651163465;11671.533138674442;4497.595216430472;4651.279051746985;4749.762511874393;4780.163471392492;4781.330613714083;9891.35706179096;9976.900230997597;9995.350302230478;10003.299769016245;10012.69961901144;5342.893142137135;5345.572354211654;5351.652142610717;5358.97320513395;5374.381946076173;4349.608535231781;4533.38799262102;4735.763211839394;4860.513948605111;4902.28916807061;3825.6174382562076;3862.729608927348;3929.351656976624;3961.999190408825;4000.3999400089638;1240.7208378115033;1242.5837344489516;1245.3337464456472;1255.0837888341562;1257.1451413039222;617.8115674719254;840.0549381945311;893.9508327041945;898.1377877237794;934.1219253901311;4661.3707149534375;5355.7054362009685;5635.295588051494;5870.119482077637;6010.489108425745;882.82343531294;908.3505293714893;919.3059240273551;946.2193690205607;982.7985263021063;3851.383778595411;3967.016491754123;4005.0575470896592;4088.897777555585;4096.426500289962;10620.468976551141;10924.836127458095;10980.725481862857;10982.890171098186;11009.174311926477;10832.187585931088;10904.50929708165;10916.890831091747;10942.23404361687;10955.726106847233;8173.509589424534;8265.504069755834;8268.007839059232;8301.584920753938;8326.916730832736;5639.295088113986;5764.769409223584;5866.735996880236;5907.9704601476715;5970.880582388415;10002.949985250027;10029.348092374265;10086.193104551472;10113.988601139827;10207.846882296622;9279.39683920543;9596.280278979244;9742.31803773729;10161.491925403849;10272.33229840088;6385.808425747235;6794.796156115324;7763.524930263232;7790.571518566533;8386.454880432751;8067.556284404414;8207.507549547046;8231.588420579066;8305.418620696493;8322.250997410261;7221.67502462401;7398.556086634819;7447.590382528796;7467.290607392289;7506.474546781673;7195.172555156224;7306.196318405077;7359.411247100112;7366.410687145123;7390.445716571387;7010.193827709879;7026;7106.289371062852;7114.253003434772;7123.786286411416;4936.691776679111;4959.975200123977;5027.698338099725;5118.232265160255;5118.8720281991755;4955.876103097451;4972.604723874637;5130.692158470354;5227.163653815372;5233.973830130977 16:46:58 INFO - PROCESS | 1652 | |4;prototype.html;300.5101684254665;306.7821846488993;307.57400999615527;314.18939137026473;314.5093653899915;5475.808346707866;5484.2596249506305;5508.03609368361;5515.007298686235;5577.247071645326;4847.927281090787;5140.126178549646;5338.225957236198;5358.285348688411;5407.5133238008575;3881.4862203740563;4016.3975403689533;4055.3916912463073;4083.121763988554;4096.918061638766;3576.4277715565513;3623.855045798165;4120.958790412103;4125.381192821071;4127.8968025799295;3430.7941523508543;4142.2543942090415;4146.0099152406865;4165.500139983206;4165.770882601456;3844.3656796628525;3902.2000489899788;3942.1327307992196;4154.709170358061;4181.895452613679;2725.5639097744265;3311.503274508843;3566.340227057977;3631.0377749896297;3634.60019397866;4566.885827854297;4776.450708168571;4870.464248932614;5179.948200517984;5253.395859476171;3771.396576547739;4047.340590357939;4317.4025610523995;4603.790180713243;4835.153848076572;5032.798688052473;5474.09677403228;5754.79858204965;5787.276590426197;5808.651480911159;3359.8152101634646;3540.3804334241663;3693.445983102529;3719.7910679029155;4070.857519986816;4538.387317712132;5707.829894871541;5761.740721667535;6053.364396738323;6121.571489995702;3451.6893479586956;4059.2693315203546;4083.7952803922913;4089.7341672791767;4093.89765255871;3758.924821503609;3980.20395920817;4156.314208155681;4159.376093585925;4174.499060112806;3953.4069889516227;4310.3103503439925;4797.232442809134;4848.539388758062;4861.149298872683;5513.58648101386;5742.28221472316;5748.913766293509;5798.9130163047585;5844.071456419371;1305.7660510817377;1426.9357878895266;1428.728541577097;1433.4982756035304;1435.1747745046432;766.2682138557664;830.5971603772166;865.6623916672503;874.6982291109557;883.9513826739591;4869.659123861366;5064.809769704117;5259.342582177228;5449.6730196188355;5449.727513624303;5959.3683968650075;5965.28416590012;5976.491998180203;5982.252218472691;6009.699515024231;4381.101874115799;4693.882652933636;5206.349206349207;5442;5468.91796623051;2515.452996407196;2578.9871050645;3110.4603221405096;3116.189790654401;3119.2357872321427;6045.879082418327;6100;6108.419700128481;6146.907796383058;6148.661823599656;5195.9740201298755;5273.683578985273;5572.3034620672415;5999.640201487181;6000.21997140369;6153.384661533811;6153.6924153093905;6162.9493400766905;6232.968835155795;6249.437550620466;5670.203276139584;5700.486956173964;5818.94181058184;5888.4700376966275;5931.61444506123;4805.687630304019;4966.938720431125;5147.150720131286;5317.893642127213;5532.91700624499;5589.847908555154;6128.9693551531955;6161.7843375483435;6163.660998644938;6182.69086545662;3615.8610037838;3650.9229777216315;3709.9258014840093;3818.8614375949555;3913.412988051815;5248.58011359098;5392.218128371443;5892.793752218652;5939.851503712442;5958.553108516792;5007.574356179765;5148.9227661584355;5701.971490142523;5791.681457519709;5812.593118481835;5433.8369848904595;5642.85892852682;5669.092945128925;5704.172894930129;5784.421557844182;4952.207646776498;5420.322459692538;5612.270404847343;5628.605997580294;5660.773569057192;4140.875773726672;5509.724513774215;5692.943070569241;5743.4543718346695;5746.224259725051;4575.748333841538;4918.729469879191;5407.756216070405;5507.449255074461;5651.886962260814 16:46:58 INFO - PROCESS | 1652 | |5;yui.html;684.3361938919248;691.0601581848688;744.4937442539072;748.4162353364375;770.7995921060523;14542.563723088299;15664.216789160553;15709.607259818511;15711.214439278034;15719;14244.572662820132;14379.137251764902;14382.424703011893;14388;14400;661.4344735251362;688.1192074145102;801.9919800801975;817.8650522663753;840.756180707594;821.1624143373755;836.4646626159263;837.0875745437472;837.6900546797663;841.1168273313027;889.0442774017924;894.2443635128307;894.9910500895005;895.292718752185;896.7713233125577;873.5195642396657;877.8112705768259;887.49856331173;887.5296093070683;889.5907882374115;652.1131261484378;660.9239937407211;662.102850637389;666.4168852254278;666.5067849791168;14295.07082039664;14331.426742930375;14339.139651620937;14344.928275358661;14372.49696260626;13994.860051399559;13996.580102596938;14041.087329323562;14083.436662533486;14172.645683857883;11678.182527223013;11685.941570292094;11722.648320550397;11919.582814601534;11949.402529873472;3585.8565657373933;3692.150805314766;3797.4303854421782;3830.138218900725;3851.364524853424;91640.6253906192;96930;100570;101633.4918325411;101649;10441.791164176826;10598.947005265078;10694.73263168427;10742.785144297226;10768.838467422995;19927.800721992593;20038.49903752418;20079.39761807119;20098.39704808858;20134.093965771575;7435.405167586689;7593.848123037619;7742.264484874041;7792.376609871197;7879.369650427952;3915.3148199065618;4386.232409328355;4445.088756804847;4450.955490445119;4516.525764794715;3545.858165673344;3680;3690.151265209017;3697.4638677391627;3702.241040586673;591.3317950715664;624.7657128576784;672.4721093941232;686.3239708886742;698.7379732600275;534.398801348483;565.6973519167226;568.766805609698;613.6747523812387;619.615838180331;8430.15698430152;9016.819663606691;9481;9518.476483793458;9688.70933871985;534.3827878799992;557.1642536195707;635.2789583822395;646.4828137489989;662.2715013485224;4385;4634.490206077328;4684.976575117103;4689.742064186503;4727.834525791581;97710.5114474423;99562;118557;155229;157091;123805;131506;133703;134803;134827.325863372;108054;109950.45024774825;113074;115829;115977.8402215984;7597.696092156252;8231.506109633441;9080.63677452921;9085.682001129928;9175.77060573491;49266.27568035101;52157.95684086373;58073.54816129462;60568.39431605628;60779.392206079145;78485.21514785007;86958.69561956449;92601.07398925924;97307.51346243224;97897.53153702557;12921.418536165707;13209.94320454342;13214.669633259246;13238.735225295633;13497.527586534425;14365.281735913371;14556;14672.266386680836;14891.404343826125;14969.925150374178;11865.11011674145;13067.803982940453;13423.463061477822;13744.931275343559;13897.444102236203;13752.793708094187;13895;13940.372683229481;14213.431462741375;14464.566063018126;13117.803232951705;13322;13677.452901883813;13942.372593233125;14154.646133846325;10980.011798938134;11361.659150225507;11474.713132171762;11485.425728713699;11719.941400293284;8678.696245631372;9178.99031106591;9353.158215760614;11373.431328433711;11614.88385116138 16:46:58 INFO - PROCESS | 1652 | __end_tp_report 16:46:58 INFO - PROCESS | 1652 | __start_cc_report 16:46:58 INFO - PROCESS | 1652 | _x_x_mozilla_cycle_collect,383 16:46:58 INFO - PROCESS | 1652 | __end_cc_report 16:46:58 INFO - PROCESS | 1652 | __startTimestamp1476748018769__endTimestamp 16:46:58 INFO - PROCESS | 1652 | 16:46:58 INFO - PROCESS | 1652 | ------- Summary: start ------- 16:46:58 INFO - PROCESS | 1652 | Number of tests: 6 16:46:58 INFO - PROCESS | 1652 | 16:46:58 INFO - PROCESS | 1652 | [#0] dojo.html Cycles:165 Average:5679.10 Median:5934.14 stddev:691.34 (11.7%) stddev-sans-first:660.82 16:46:58 INFO - PROCESS | 1652 | Values: 3002.9 3093.8 3364.3 3372.3 3402.2 4510.7 5003.0 5608.4 5615.5 5634.4 5621.4 5637.1 5697.6 5768.9 5786.4 5126.9 5173.5 5179.6 5258.6 5310.4 4013.9 4353.2 5282.4 5286.1 5308.1 5098.7 5135.4 5142.4 5224.8 5258.3 5073.7 5169.7 5188.1 5213.2 5255.8 5118.6 5133.2 5136.9 5205.2 5214.8 5201.8 5511.0 5641.6 5655.3 5682.7 6051.6 6055.6 6149.1 6191.9 6203.2 5298.7 6281.0 6293.4 6320.4 6324.4 5801.9 5834.7 5839.9 5861.4 5884.4 6165.8 6174.8 6198.4 6213.5 6271.6 6111.6 6128.1 6177.0 6189.0 6204.9 6086.7 6255.1 6275.8 6287.6 6293.4 6106.2 6133.5 6136.4 6152.7 6194.8 4778.2 6011.9 6189.4 6206.2 6224.1 4862.8 4894.8 4911.0 4916.0 4940.1 4318.4 4347.8 4371.3 4376.2 4425.3 5298.7 5304.5 5826.3 5841.6 5892.8 6194.7 6247.1 6274.1 6294.5 6307.9 5649.2 5705.2 5787.3 5794.4 5820.9 5374.2 5385.3 5415.2 5424.4 5437.5 6224.8 6249.8 6286.0 6288.2 6320.5 4557.4 5835.7 6117.9 6156.8 6160.6 6226.4 6280.6 6293.2 6299.7 6304.8 6134.1 6224.3 6249.7 6252.2 6264.9 6094.5 6218.3 6261.4 6285.2 6293.9 6099.4 6108.4 6112.0 6113.5 6190.0 6123.3 6131.9 6136.1 6140.6 6143.6 5583.6 5788.2 5907.4 5934.1 6017.5 6138.8 6157.4 6166.2 6171.4 6175.2 5968.5 5981.3 5984.8 6009.9 6017.5 16:46:58 INFO - PROCESS | 1652 | 16:46:58 INFO - PROCESS | 1652 | [#1] ext.html Cycles:180 Average:70672.14 Median:40198.10 stddev:158264.50 (393.7%) stddev-sans-first:158620.81 16:46:58 INFO - PROCESS | 1652 | Values: 513.3 527.0 527.3 528.5 531.2 38724.0 40675.4 40719.6 40745.8 40762.0 41790.2 42273.4 42321.0 42369.8 42434.2 8081.7 9604.7 9614.5 9625.7 9640.3 9472.8 9545.7 9559.5 9560.3 9602.8 7837.6 8309.8 9485.8 9608.0 9625.8 9428.5 9493.8 9555.0 9562.7 9570.8 9407.1 9435.7 9487.0 9497.1 9509.6 39743.6 39879.4 40083.0 40180.0 40216.2 3277.8 3367.4 3436.5 3684.3 3989.4 30097.5 30189.4 30710.2 30784.5 30807.0 3148.9 3300.9 3358.1 3362.5 3363.7 491022.0 492088.0 494624.5 496323.0 498670.5 55345.0 55666.4 55688.7 55821.9 55860.0 58487.7 58945.3 59039.7 59163.4 59356.4 14953.1 15163.5 15168.8 15219.5 15270.8 11748.9 11789.4 11795.6 11841.5 11841.6 11832.9 14930.6 14935.9 15004.5 15121.6 3123.0 3193.0 3219.3 5924.9 5986.0 889.4 897.7 968.6 977.4 993.9 26455.6 29517.4 29937.0 30089.8 30174.5 954.9 991.9 1043.5 1058.7 1088.2 14872.6 15341.7 15468.5 15510.2 15590.4 553608.0 675723.0 677861.0 680010.0 686357.0 55335.4 55528.7 55793.3 55796.4 56026.7 1285.8 1310.1 1323.4 1325.6 1332.0 25743.6 29295.8 29483.9 29618.6 29756.0 48041.0 48097.6 48339.0 48403.8 48662.0 600375.0 607357.0 612165.0 613020.0 614285.0 25846.5 25970.4 26148.6 26207.6 26869.5 41972.2 43343.8 43489.3 43694.0 44026.6 36077.5 36243.0 36533.3 38094.6 39390.6 31095.0 37780.0 37981.2 38849.4 38868.6 36567.9 37276.4 37366.4 37601.0 37625.0 26741.7 28583.7 30942.0 33444.7 34029.0 25286.5 25326.7 25462.6 25680.6 25775.9 16:46:58 INFO - PROCESS | 1652 | 16:46:58 INFO - PROCESS | 1652 | [#2] jquery.html Cycles:180 Average:33947.54 Median:2390.07 stddev:99905.45 (4180.0%) stddev-sans-first:100153.89 16:46:58 INFO - PROCESS | 1652 | Values: 364.6 365.9 370.7 374.0 395.2 18576.4 19288.9 19568.2 19652.0 19927.8 19202.5 19439.5 19577.4 19624.0 19658.9 5454.7 5655.7 5682.5 5869.3 5914.2 5751.7 5935.7 5937.2 5951.6 5963.9 5565.2 5797.6 5832.4 5890.5 5893.6 5758.3 5778.4 5830.4 5856.9 5878.8 5008.7 5135.5 5295.4 5339.9 5397.3 12512.9 12755.0 12765.9 18210.9 22663.2 2132.2 2216.8 2217.1 2238.4 2273.3 9480.3 9623.3 9679.3 9812.4 9932.9 1754.4 1813.7 1843.3 1854.7 1873.9 118762.4 123824.1 123871.0 124847.4 125117.0 9068.3 9547.2 10266.4 10700.0 11587.5 14397.3 14418.8 14428.4 14441.9 14454.4 6414.4 6624.6 6782.4 8205.5 8287.7 6129.9 7797.3 7874.4 7918.9 8085.4 4385.0 4499.8 4528.1 4565.6 4630.2 1906.3 1916.7 1920.4 1921.4 1947.9 794.7 795.5 907.0 959.4 959.9 19571.6 20457.4 20510.7 20532.2 20638.7 817.8 876.8 882.3 904.9 923.5 10133.4 10782.5 10784.4 10795.5 10850.0 592683.0 594589.0 599133.0 599599.0 609563.0 110423.0 111008.0 111639.0 126547.4 130896.0 967.8 993.1 1010.6 1011.9 1016.8 17678.6 18432.0 18459.6 18601.8 18674.8 50020.5 50880.0 52180.5 52463.7 53793.0 50764.2 66816.7 69748.3 74559.3 76376.0 7323.3 7510.7 7530.1 7629.7 7662.9 8964.8 10202.4 11373.0 11427.9 12530.1 8880.3 10362.5 10654.0 10754.7 10930.5 10371.3 10482.7 10609.5 10641.2 10792.2 9365.0 10677.3 10772.1 10985.2 11023.2 6232.5 7377.5 7487.8 7511.7 7655.2 7882.3 8804.6 10303.5 10440.1 10508.4 16:46:58 INFO - PROCESS | 1652 | 16:46:58 INFO - PROCESS | 1652 | [#3] mootools.html Cycles:180 Average:5699.33 Median:4946.28 stddev:3209.97 (64.9%) stddev-sans-first:3203.48 16:46:58 INFO - PROCESS | 1652 | Values: 1501.9 1536.5 1727.2 1796.3 1828.8 7890.7 8178.3 8428.9 8995.0 9013.9 8415.5 8766.6 8775.0 8887.1 8916.2 2306.4 2327.9 2345.5 2348.2 2363.7 2091.9 2291.0 2294.6 2313.7 2355.0 2298.6 2304.9 2315.8 2320.7 2323.9 2291.5 2297.4 2298.6 2302.9 2307.9 2289.9 2292.7 2298.1 2299.5 2300.3 8473.9 8595.4 8601.6 8606.0 8610.3 5182.5 5326.7 5350.5 5351.5 5357.4 3490.3 3555.4 3625.4 3685.7 3686.0 1040.9 1074.8 1078.9 1091.7 1098.6 11510.1 11617.6 11618.5 11634.9 11671.5 4497.6 4651.3 4749.8 4780.2 4781.3 9891.4 9976.9 9995.4 10003.3 10012.7 5342.9 5345.6 5351.7 5359.0 5374.4 4349.6 4533.4 4735.8 4860.5 4902.3 3825.6 3862.7 3929.4 3962.0 4000.4 1240.7 1242.6 1245.3 1255.1 1257.1 617.8 840.1 894.0 898.1 934.1 4661.4 5355.7 5635.3 5870.1 6010.5 882.8 908.4 919.3 946.2 982.8 3851.4 3967.0 4005.1 4088.9 4096.4 10620.5 10924.8 10980.7 10982.9 11009.2 10832.2 10904.5 10916.9 10942.2 10955.7 8173.5 8265.5 8268.0 8301.6 8326.9 5639.3 5764.8 5866.7 5908.0 5970.9 10002.9 10029.3 10086.2 10114.0 10207.8 9279.4 9596.3 9742.3 10161.5 10272.3 6385.8 6794.8 7763.5 7790.6 8386.5 8067.6 8207.5 8231.6 8305.4 8322.3 7221.7 7398.6 7447.6 7467.3 7506.5 7195.2 7306.2 7359.4 7366.4 7390.4 7010.2 7026.0 7106.3 7114.3 7123.8 4936.7 4960.0 5027.7 5118.2 5118.9 4955.9 4972.6 5130.7 5227.2 5234.0 16:46:58 INFO - PROCESS | 1652 | 16:46:58 INFO - PROCESS | 1652 | [#4] prototype.html Cycles:180 Average:4601.95 Median:5187.96 stddev:1449.79 (27.9%) stddev-sans-first:1417.45 16:46:58 INFO - PROCESS | 1652 | Values: 300.5 306.8 307.6 314.2 314.5 5475.8 5484.3 5508.0 5515.0 5577.2 4847.9 5140.1 5338.2 5358.3 5407.5 3881.5 4016.4 4055.4 4083.1 4096.9 3576.4 3623.9 4121.0 4125.4 4127.9 3430.8 4142.3 4146.0 4165.5 4165.8 3844.4 3902.2 3942.1 4154.7 4181.9 2725.6 3311.5 3566.3 3631.0 3634.6 4566.9 4776.5 4870.5 5179.9 5253.4 3771.4 4047.3 4317.4 4603.8 4835.2 5032.8 5474.1 5754.8 5787.3 5808.7 3359.8 3540.4 3693.4 3719.8 4070.9 4538.4 5707.8 5761.7 6053.4 6121.6 3451.7 4059.3 4083.8 4089.7 4093.9 3758.9 3980.2 4156.3 4159.4 4174.5 3953.4 4310.3 4797.2 4848.5 4861.1 5513.6 5742.3 5748.9 5798.9 5844.1 1305.8 1426.9 1428.7 1433.5 1435.2 766.3 830.6 865.7 874.7 884.0 4869.7 5064.8 5259.3 5449.7 5449.7 5959.4 5965.3 5976.5 5982.3 6009.7 4381.1 4693.9 5206.3 5442.0 5468.9 2515.5 2579.0 3110.5 3116.2 3119.2 6045.9 6100.0 6108.4 6146.9 6148.7 5196.0 5273.7 5572.3 5999.6 6000.2 6153.4 6153.7 6162.9 6233.0 6249.4 5670.2 5700.5 5818.9 5888.5 5931.6 4805.7 4966.9 5147.2 5317.9 5532.9 5589.8 6129.0 6161.8 6163.7 6182.7 3615.9 3650.9 3709.9 3818.9 3913.4 5248.6 5392.2 5892.8 5939.9 5958.6 5007.6 5148.9 5702.0 5791.7 5812.6 5433.8 5642.9 5669.1 5704.2 5784.4 4952.2 5420.3 5612.3 5628.6 5660.8 4140.9 5509.7 5692.9 5743.5 5746.2 4575.7 4918.7 5407.8 5507.4 5651.9 16:46:58 INFO - PROCESS | 1652 | 16:46:58 INFO - PROCESS | 1652 | [#5] yui.html Cycles:180 Average:23742.86 Median:3813.78 stddev:37504.08 (983.4%) stddev-sans-first:37569.32 16:46:58 INFO - PROCESS | 1652 | Values: 684.3 691.1 744.5 748.4 770.8 14542.6 15664.2 15709.6 15711.2 15719.0 14244.6 14379.1 14382.4 14388.0 14400.0 661.4 688.1 802.0 817.9 840.8 821.2 836.5 837.1 837.7 841.1 889.0 894.2 895.0 895.3 896.8 873.5 877.8 887.5 887.5 889.6 652.1 660.9 662.1 666.4 666.5 14295.1 14331.4 14339.1 14344.9 14372.5 13994.9 13996.6 14041.1 14083.4 14172.6 11678.2 11685.9 11722.6 11919.6 11949.4 3585.9 3692.2 3797.4 3830.1 3851.4 91640.6 96930.0 100570.0 101633.5 101649.0 10441.8 10598.9 10694.7 10742.8 10768.8 19927.8 20038.5 20079.4 20098.4 20134.1 7435.4 7593.8 7742.3 7792.4 7879.4 3915.3 4386.2 4445.1 4451.0 4516.5 3545.9 3680.0 3690.2 3697.5 3702.2 591.3 624.8 672.5 686.3 698.7 534.4 565.7 568.8 613.7 619.6 8430.2 9016.8 9481.0 9518.5 9688.7 534.4 557.2 635.3 646.5 662.3 4385.0 4634.5 4685.0 4689.7 4727.8 97710.5 99562.0 118557.0 155229.0 157091.0 123805.0 131506.0 133703.0 134803.0 134827.3 108054.0 109950.5 113074.0 115829.0 115977.8 7597.7 8231.5 9080.6 9085.7 9175.8 49266.3 52158.0 58073.5 60568.4 60779.4 78485.2 86958.7 92601.1 97307.5 97897.5 12921.4 13209.9 13214.7 13238.7 13497.5 14365.3 14556.0 14672.3 14891.4 14969.9 11865.1 13067.8 13423.5 13744.9 13897.4 13752.8 13895.0 13940.4 14213.4 14464.6 13117.8 13322.0 13677.5 13942.4 14154.6 10980.0 11361.7 11474.7 11485.4 11719.9 8678.7 9179.0 9353.2 11373.4 11614.9 16:46:58 INFO - PROCESS | 1652 | -------- Summary: end -------- 16:46:58 INFO - PROCESS | 1652 | 16:46:59 INFO - TEST-INFO | 1652: exit 0 16:46:59 INFO - TEST-OK | dromaeo_css | took 1115060ms 16:46:59 INFO - TEST-START | kraken 16:46:59 INFO - operating with platform_type : mac_ 16:46:59 INFO - Initialising browser for kraken test... 16:46:59 INFO - TEST-INFO | started process 1665 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/hy/45ssh3ld6sx_27c31ssq2l9w00000w/T/tmpFZrjre/profile http://localhost:49277/getInfo.html) 16:47:02 INFO - PROCESS | 1665 | 2016-10-17 16:47:02.054 plugin-container[1666:10233] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8843, name = 'com.apple.tsm.portname' 16:47:02 INFO - PROCESS | 1665 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:47:02 INFO - PROCESS | 1665 | 2016-10-17 16:47:02.056 plugin-container[1666:10233] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4607, name = 'com.apple.CFPasteboardClient' 16:47:02 INFO - PROCESS | 1665 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:47:02 INFO - PROCESS | 1665 | 2016-10-17 16:47:02.057 plugin-container[1666:10233] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 16:47:02 INFO - PROCESS | 1665 | __metrics Screen width/height:1600/1200 16:47:02 INFO - PROCESS | 1665 | colorDepth:24 16:47:02 INFO - PROCESS | 1665 | Browser inner width/height: 1024/689 16:47:02 INFO - PROCESS | 1665 | __metrics 16:47:02 ERROR - PROCESS | 1665 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3083: TypeError: this.tabs is undefined 16:47:02 INFO - TEST-INFO | 1665: exit 0 16:47:02 INFO - Browser initialized. 16:47:02 INFO - Running cycle 1/1 for kraken test... 16:47:02 INFO - TEST-INFO | started process 1667 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/hy/45ssh3ld6sx_27c31ssq2l9w00000w/T/tmpFZrjre/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:47:05 INFO - PROCESS | 1667 | 2016-10-17 16:47:05.440 plugin-container[1668:10346] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x894b, name = 'com.apple.tsm.portname' 16:47:05 INFO - PROCESS | 1667 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:47:05 INFO - PROCESS | 1667 | 2016-10-17 16:47:05.441 plugin-container[1668:10346] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8e13, name = 'com.apple.CFPasteboardClient' 16:47:05 INFO - PROCESS | 1667 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:47:05 INFO - PROCESS | 1667 | 2016-10-17 16:47:05.441 plugin-container[1668:10346] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 16:47:14 INFO - PROCESS | 1667 | RSS: Main: 244371456 16:47:14 INFO - PROCESS | 1667 | 16:47:17 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49277/tests/kraken/driver.html?testName=audio-beat-detection) 16:47:18 INFO - PROCESS | 1667 | RSS: Main: 256458752 16:47:18 INFO - PROCESS | 1667 | 16:47:21 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49277/tests/kraken/driver.html?testName=audio-dft) 16:47:21 INFO - PROCESS | 1667 | RSS: Main: 263942144 16:47:21 INFO - PROCESS | 1667 | 16:47:25 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49277/tests/kraken/driver.html?testName=audio-fft) 16:47:26 INFO - PROCESS | 1667 | RSS: Main: 262569984 16:47:26 INFO - PROCESS | 1667 | 16:47:28 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49277/tests/kraken/driver.html?testName=audio-oscillator) 16:47:28 INFO - PROCESS | 1667 | RSS: Main: 263380992 16:47:28 INFO - PROCESS | 1667 | 16:47:30 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49277/tests/kraken/driver.html?testName=imaging-gaussian-blur) 16:47:31 INFO - PROCESS | 1667 | RSS: Main: 264941568 16:47:31 INFO - PROCESS | 1667 | 16:47:34 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49277/tests/kraken/driver.html?testName=imaging-darkroom) 16:47:34 INFO - PROCESS | 1667 | RSS: Main: 265916416 16:47:34 INFO - PROCESS | 1667 | 16:47:37 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49277/tests/kraken/driver.html?testName=imaging-desaturate) 16:47:38 INFO - PROCESS | 1667 | RSS: Main: 265846784 16:47:38 INFO - PROCESS | 1667 | 16:47:41 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49277/tests/kraken/driver.html?testName=json-parse-financial) 16:47:42 INFO - PROCESS | 1667 | RSS: Main: 266334208 16:47:42 INFO - PROCESS | 1667 | 16:47:43 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49277/tests/kraken/driver.html?testName=json-stringify-tinderbox) 16:47:44 INFO - PROCESS | 1667 | RSS: Main: 265400320 16:47:44 INFO - PROCESS | 1667 | 16:47:46 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-aes) 16:47:46 INFO - PROCESS | 1667 | RSS: Main: 265506816 16:47:46 INFO - PROCESS | 1667 | 16:47:49 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-ccm) 16:47:49 INFO - PROCESS | 1667 | RSS: Main: 266039296 16:47:49 INFO - PROCESS | 1667 | 16:47:52 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 16:47:53 INFO - PROCESS | 1667 | RSS: Main: 265945088 16:47:53 INFO - PROCESS | 1667 | 16:47:56 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 16:47:57 INFO - PROCESS | 1667 | RSS: Main: 265773056 16:47:57 INFO - PROCESS | 1667 | 16:47:59 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49277/tests/kraken/driver.html?testName=ai-astar) 16:47:59 INFO - PROCESS | 1667 | RSS: Main: 267169792 16:47:59 INFO - PROCESS | 1667 | 16:47:59 INFO - PROCESS | 1667 | __start_tp_report 16:47:59 INFO - PROCESS | 1667 | _x_x_mozilla_page_load 16:47:59 INFO - PROCESS | 1667 | _x_x_mozilla_page_load_details 16:47:59 INFO - PROCESS | 1667 | |i|pagename|runs| 16:47:59 INFO - PROCESS | 1667 | |0;ai-astar;113;126;128;119;118;121;173;145;118;98 16:47:59 INFO - PROCESS | 1667 | |1;audio-beat-detection;172;142;118;148;118;112;115;115;144;116 16:47:59 INFO - PROCESS | 1667 | |2;audio-dft;199;195;221;293;268;276;200;279;278;193 16:47:59 INFO - PROCESS | 1667 | |3;audio-fft;82;70;59;85;61;60;59;60;77;60 16:47:59 INFO - PROCESS | 1667 | |4;audio-oscillator;85;71;87;73;77;86;119;70;70;87 16:47:59 INFO - PROCESS | 1667 | |5;imaging-gaussian-blur;91;89;91;88;99;88;92;90;90;87 16:47:59 INFO - PROCESS | 1667 | |6;imaging-darkroom;100;102;99;113;101;100;111;135;101;102 16:47:59 INFO - PROCESS | 1667 | |7;imaging-desaturate;110;128;98;124;81;153;157;113;91;92 16:47:59 INFO - PROCESS | 1667 | |8;json-parse-financial;56;56;56;57;48;59;80;51;53;59 16:47:59 INFO - PROCESS | 1667 | |9;json-stringify-tinderbox;71;70;80;97;109;67;63;63;76;65 16:47:59 INFO - PROCESS | 1667 | |10;stanford-crypto-aes;81;82;112;108;90;88;91;103;93;89 16:47:59 INFO - PROCESS | 1667 | |11;stanford-crypto-ccm;148;153;159;136;138;137;132;153;221;168 16:47:59 INFO - PROCESS | 1667 | |12;stanford-crypto-pbkdf2;176;204;229;179;174;175;174;173;207;200 16:47:59 INFO - PROCESS | 1667 | |13;stanford-crypto-sha256-iterative;71;70;71;69;71;70;70;70;70;86 16:47:59 INFO - PROCESS | 1667 | __end_tp_report 16:47:59 INFO - PROCESS | 1667 | __start_cc_report 16:47:59 INFO - PROCESS | 1667 | _x_x_mozilla_cycle_collect,1004 16:47:59 INFO - PROCESS | 1667 | __end_cc_report 16:47:59 INFO - PROCESS | 1667 | __startTimestamp1476748079394__endTimestamp 16:47:59 INFO - PROCESS | 1667 | 16:47:59 INFO - PROCESS | 1667 | ------- Summary: start ------- 16:47:59 INFO - PROCESS | 1667 | Number of tests: 14 16:47:59 INFO - PROCESS | 1667 | 16:47:59 INFO - PROCESS | 1667 | [#0] ai-astar Cycles:10 Average:125.90 Median:127.00 stddev:20.35 (16.0%) stddev-sans-first:21.05 16:47:59 INFO - PROCESS | 1667 | Values: 113.0 126.0 128.0 119.0 118.0 121.0 173.0 145.0 118.0 98.0 16:47:59 INFO - PROCESS | 1667 | 16:47:59 INFO - PROCESS | 1667 | [#1] audio-beat-detection Cycles:10 Average:130.00 Median:130.00 stddev:20.24 (15.6%) stddev-sans-first:14.69 16:47:59 INFO - PROCESS | 1667 | Values: 172.0 142.0 118.0 148.0 118.0 112.0 115.0 115.0 144.0 116.0 16:47:59 INFO - PROCESS | 1667 | 16:47:59 INFO - PROCESS | 1667 | [#2] audio-dft Cycles:10 Average:240.20 Median:272.00 stddev:41.81 (15.4%) stddev-sans-first:41.60 16:47:59 INFO - PROCESS | 1667 | Values: 199.0 195.0 221.0 293.0 268.0 276.0 200.0 279.0 278.0 193.0 16:47:59 INFO - PROCESS | 1667 | 16:47:59 INFO - PROCESS | 1667 | [#3] audio-fft Cycles:10 Average:67.30 Median:65.50 stddev:10.37 (15.8%) stddev-sans-first:9.54 16:47:59 INFO - PROCESS | 1667 | Values: 82.0 70.0 59.0 85.0 61.0 60.0 59.0 60.0 77.0 60.0 16:47:59 INFO - PROCESS | 1667 | 16:47:59 INFO - PROCESS | 1667 | [#4] audio-oscillator Cycles:10 Average:82.50 Median:81.00 stddev:14.74 (18.2%) stddev-sans-first:15.61 16:47:59 INFO - PROCESS | 1667 | Values: 85.0 71.0 87.0 73.0 77.0 86.0 119.0 70.0 70.0 87.0 16:47:59 INFO - PROCESS | 1667 | 16:47:59 INFO - PROCESS | 1667 | [#5] imaging-gaussian-blur Cycles:10 Average:90.50 Median:90.50 stddev:3.37 (3.7%) stddev-sans-first:3.57 16:47:59 INFO - PROCESS | 1667 | Values: 91.0 89.0 91.0 88.0 99.0 88.0 92.0 90.0 90.0 87.0 16:47:59 INFO - PROCESS | 1667 | 16:47:59 INFO - PROCESS | 1667 | [#6] imaging-darkroom Cycles:10 Average:106.40 Median:106.50 stddev:11.14 (10.5%) stddev-sans-first:11.57 16:47:59 INFO - PROCESS | 1667 | Values: 100.0 102.0 99.0 113.0 101.0 100.0 111.0 135.0 101.0 102.0 16:47:59 INFO - PROCESS | 1667 | 16:47:59 INFO - PROCESS | 1667 | [#7] imaging-desaturate Cycles:10 Average:114.70 Median:119.00 stddev:25.90 (21.8%) stddev-sans-first:27.41 16:47:59 INFO - PROCESS | 1667 | Values: 110.0 128.0 98.0 124.0 81.0 153.0 157.0 113.0 91.0 92.0 16:47:59 INFO - PROCESS | 1667 | 16:47:59 INFO - PROCESS | 1667 | [#8] json-parse-financial Cycles:10 Average:57.50 Median:56.50 stddev:8.63 (15.3%) stddev-sans-first:9.14 16:47:59 INFO - PROCESS | 1667 | Values: 56.0 56.0 56.0 57.0 48.0 59.0 80.0 51.0 53.0 59.0 16:47:59 INFO - PROCESS | 1667 | 16:47:59 INFO - PROCESS | 1667 | [#9] json-stringify-tinderbox Cycles:10 Average:76.10 Median:70.50 stddev:15.44 (21.9%) stddev-sans-first:16.27 16:47:59 INFO - PROCESS | 1667 | Values: 71.0 70.0 80.0 97.0 109.0 67.0 63.0 63.0 76.0 65.0 16:47:59 INFO - PROCESS | 1667 | 16:47:59 INFO - PROCESS | 1667 | [#10] stanford-crypto-aes Cycles:10 Average:93.70 Median:88.50 stddev:10.54 (11.9%) stddev-sans-first:10.13 16:47:59 INFO - PROCESS | 1667 | Values: 81.0 82.0 112.0 108.0 90.0 88.0 91.0 103.0 93.0 89.0 16:47:59 INFO - PROCESS | 1667 | 16:47:59 INFO - PROCESS | 1667 | [#11] stanford-crypto-ccm Cycles:10 Average:154.50 Median:153.00 stddev:26.03 (17.0%) stddev-sans-first:27.50 16:47:59 INFO - PROCESS | 1667 | Values: 148.0 153.0 159.0 136.0 138.0 137.0 132.0 153.0 221.0 168.0 16:47:59 INFO - PROCESS | 1667 | 16:47:59 INFO - PROCESS | 1667 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:189.10 Median:189.50 stddev:19.55 (10.3%) stddev-sans-first:20.16 16:47:59 INFO - PROCESS | 1667 | Values: 176.0 204.0 229.0 179.0 174.0 175.0 174.0 173.0 207.0 200.0 16:47:59 INFO - PROCESS | 1667 | 16:47:59 INFO - PROCESS | 1667 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:71.80 Median:70.50 stddev:5.03 (7.1%) stddev-sans-first:5.33 16:47:59 INFO - PROCESS | 1667 | Values: 71.0 70.0 71.0 69.0 71.0 70.0 70.0 70.0 70.0 86.0 16:47:59 INFO - PROCESS | 1667 | -------- Summary: end -------- 16:47:59 INFO - PROCESS | 1667 | 16:48:00 INFO - TEST-INFO | 1667: exit 0 16:48:00 INFO - TEST-OK | kraken | took 60804ms 16:48:00 INFO - SUITE-END | took 1175s 16:48:00 INFO - Completed test suite (00:19:36) 16:48:00 INFO - javascript benchmark 16:48:00 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7203.977964834192, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3002.939941201176, 3093.783435159539, 3364.3271345730845, 3372.258103217291, 3402.1834759657672, 4510.706804057734, 5003.0, 5608.43915608439, 5615.522680572146, 5634.3520495143075, 5621.409751976034, 5637.069883469233, 5697.572682048843, 5768.913466298009, 5786.421357864211, 5126.92309615356, 5173.456787037363, 5179.611529135311, 5258.6318957672975, 5310.36275646921, 4013.9197216055663, 4353.216421044211, 5282.445343238962, 5286.101362768339, 5308.124159513675, 5098.694078355311, 5135.35808023997, 5142.382914050338, 5224.843254702365, 5258.2638430619745, 5073.695578265316, 5169.663971841818, 5188.0661480933795, 5213.165893457067, 5255.816046438395, 5118.61610379223, 5133.204353325241, 5136.922946155811, 5205.193195054772, 5214.843554693365, 5201.817936372209, 5511.035568775448, 5641.633293835887, 5655.293088363955, 5682.7442765075675, 6051.606645568024, 6055.5761096723245, 6149.077638354238, 6191.938080619227, 6203.2245969253845, 5298.708571028592, 6281.0, 6293.402126797947, 6320.399562041599, 6324.3991820776955, 5801.9419805802245, 5834.737436815353, 5839.9124013139835, 5861.413858614104, 5884.440978107073, 6165.784197553064, 6174.783882564088, 6198.380161983766, 6213.502919766408, 6271.592346497463, 6111.602745821508, 6128.105930682418, 6177.0, 6189.0, 6204.906926396107, 6086.665233412116, 6255.061740738925, 6275.780347687901, 6287.559870808999, 6293.402126797947, 6106.187656479728, 6133.539984501181, 6136.386361363828, 6152.692365381713, 6194.81415557534, 4778.1638213312535, 6011.947909115887, 6189.381061893865, 6206.224221972254, 6224.128621992924, 4862.80548778052, 4894.779734911937, 4910.975445122752, 4915.992221594564, 4940.135476291634, 4318.438602981592, 4347.7826108694435, 4371.322445021026, 4376.234159022159, 4425.291953287523, 5298.655587386807, 5304.496072873071, 5826.300843898759, 5841.620294680833, 5892.793752218652, 6194.721237544321, 6247.125402443661, 6274.058891166362, 6294.464970477469, 6307.905381419282, 5649.209110724501, 5705.2012718219485, 5787.334456537552, 5794.391588883106, 5820.912686309709, 5374.193870919394, 5385.27298814655, 5415.241866138744, 5424.403315635277, 5437.4562543745305, 6224.782132625336, 6249.843753906007, 6285.96857015712, 6288.151099601495, 6320.494360451153, 4557.384753058294, 5835.67903765297, 6117.9492029047315, 6156.753729850935, 6160.568760186744, 6226.377362263737, 6280.623162610258, 6293.150424692791, 6299.6535190564955, 6304.84237894038, 6134.110553969738, 6224.315325314121, 6249.7187626557825, 6252.249730032425, 6264.934961056722, 6094.542909281733, 6218.253809542884, 6261.436470717657, 6285.214348206474, 6293.937060629518, 6099.370313949917, 6108.389161084034, 6111.98679349419, 6113.5414843886, 6190.0, 6123.295820980644, 6131.877362452637, 6136.110264011782, 6140.587664837023, 6143.600665956698, 5583.637063590853, 5788.189653448437, 5907.3501914790195, 5934.139549765347, 6017.488513476404, 6138.785142519991, 6157.353477884758, 6166.167567378347, 6171.413715697002, 6175.166352542529, 5968.522518198621, 5981.342052374148, 5984.820455386345, 6009.909851352146, 6017.488513476228], "value": 5634.09699269774, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [513.2532165698908, 526.9973650131747, 527.3118580252768, 528.5137673340527, 531.1687209517105, 38724.0, 40675.389869151986, 40719.59280407195, 40745.79627101868, 40762.0, 41790.16419671605, 42273.36589951153, 42321.0, 42369.788151059205, 42434.15131697364, 8081.7171399001045, 9604.663836765714, 9614.471204083773, 9625.71122866315, 9640.325177237597, 9472.81054378912, 9545.713628591153, 9559.522023898811, 9560.330776845623, 9602.75993100171, 7837.608119594026, 8309.83380332393, 9485.810283794355, 9607.991160928106, 9625.759356016086, 9428.528573571328, 9493.81012379752, 9555.0, 9562.713118606453, 9570.808583828353, 9407.059294070606, 9435.669751558731, 9487.0, 9497.050294970517, 9509.619615215452, 39743.60256397457, 39879.401808972885, 40082.997925051815, 40180.0, 40216.19567608661, 3277.770556061053, 3367.410703126943, 3436.5017072524342, 3684.281565094808, 3989.3617021276455, 30097.548536771967, 30189.396212075637, 30710.161229846268, 30784.538231926537, 30807.0, 3148.9055328340187, 3300.900972970791, 3358.0765289545325, 3362.495625656147, 3363.6636336366414, 491022.0, 492088.0, 494624.5268773633, 496323.0, 498670.5066474681, 55345.0, 55666.44333556694, 55688.72155639196, 55821.88356232934, 55860.0, 58487.70756146277, 58945.347459364435, 59039.70480147572, 59163.40836591579, 59356.40643593509, 14953.102813831205, 15163.469278575418, 15168.786497080207, 15219.467318643794, 15270.847291526943, 11748.941255293668, 11789.351585662871, 11795.587154449724, 11841.526338946345, 11841.5855445059, 11832.940835295769, 14930.552083437515, 14935.850641493664, 15004.549863504113, 15121.621959451102, 3122.953155702666, 3192.952105718416, 3219.259570298844, 5924.851878702981, 5985.970070149621, 889.3818795936891, 897.6544030548156, 968.5544649461186, 977.3647129365827, 993.911666724935, 26455.603165952525, 29517.409651807273, 29937.0, 30089.849550752544, 30174.54738178929, 954.8901876284177, 991.8710567626163, 1043.4939054558533, 1058.6982709927788, 1088.2273585754044, 14872.62818429548, 15341.693166136614, 15468.5359439217, 15510.224488775742, 15590.376384944702, 553608.0, 675723.0, 677861.0, 680010.0, 686357.0, 55335.44664553383, 55528.72235638796, 55793.25757310674, 55796.44203557912, 56026.719866401225, 1285.8135570342247, 1310.0567591334225, 1323.4110820684896, 1325.5758157389735, 1331.9667008324675, 25743.613845791955, 29295.777464478462, 29483.85258073696, 29618.555721664623, 29755.958541450946, 48041.0, 48097.557073287855, 48339.0, 48403.75798120987, 48662.0, 600375.0, 607357.0, 612165.0, 613020.0, 614285.0, 25846.483070338865, 25970.350741230865, 26148.60777088307, 26207.60688589711, 26869.462610747283, 41972.160556789306, 43343.78328108339, 43489.34765978576, 43694.0, 44026.559734402246, 36077.45883811797, 36243.0, 36533.269334612625, 38094.61905380911, 39390.606093938695, 31095.0, 37780.0, 37981.24037519179, 38849.41725874058, 38868.61131388763, 36567.902962911154, 37276.44085338668, 37366.439503408015, 37601.0, 37625.0, 26741.732582673925, 28583.714162858105, 30942.0, 33444.66555334415, 34029.0, 25286.49427011486, 25326.74673253244, 25462.618060728735, 25680.614790778527, 25775.87112064428], "value": 19113.844991133486, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [364.64447164015104, 365.8737735481259, 370.74603896331007, 373.9775613463191, 395.1839451532585, 18576.442706718804, 19288.903555482237, 19568.21727130915, 19651.99999999998, 19927.800721992775, 19202.519937001583, 19439.514012149702, 19577.412677619657, 19624.018799060024, 19658.918759468223, 5454.672719636815, 5655.745491452894, 5682.51698605619, 5869.266341707286, 5914.2015827863115, 5751.683657398841, 5935.673537955411, 5937.168796368512, 5951.6429014259065, 5963.880722385571, 5565.220869078333, 5797.5651826113, 5832.358440571534, 5890.499307558863, 5893.58744887858, 5758.280214973129, 5778.393268706767, 5830.3586605473365, 5856.912146317809, 5878.823635290927, 5008.749562521859, 5135.537801597874, 5295.391030031557, 5339.893202135935, 5397.27136836526, 12512.874871251262, 12755.043371747064, 12765.914897233746, 18210.90894545532, 22663.20678776235, 2132.1791110422446, 2216.7561568227484, 2217.0909926930044, 2238.418011317069, 2273.283915566591, 9480.336376453652, 9623.326367154303, 9679.274054445945, 9812.411255324632, 9932.851007234898, 1754.3859649122705, 1813.6826055440245, 1843.3456123076248, 1854.6939754940418, 1873.8594605404512, 118762.4061879685, 123824.14263786051, 123871.0, 124847.37576312151, 125117.0, 9068.274538036942, 9547.236221102296, 10266.435346056038, 10699.983501567338, 11587.536498539965, 14397.280135993158, 14418.783718244234, 14428.350724217435, 14441.855581444051, 14454.421823126957, 6414.358564143548, 6624.6025238485845, 6782.423494002966, 8205.548694821842, 8287.709930152416, 6129.938700612936, 7797.337226335825, 7874.370050395954, 7918.920810791934, 8085.393595480363, 4384.956150438455, 4499.842505512292, 4528.071745292207, 4565.634749220055, 4630.21286381316, 1906.2660875563008, 1916.7029110487617, 1920.3758778397078, 1921.404364646936, 1947.8928658923612, 794.6821271491334, 795.5385876191795, 906.9727908162765, 959.3690725766226, 959.9440615323228, 19571.608567828564, 20457.38627841167, 20510.692339614918, 20532.178712851317, 20638.690419643717, 817.8446095241885, 876.7895705030746, 882.3029806452959, 904.9185573298435, 923.5013092929877, 10133.391996480235, 10782.456940574622, 10784.352938823695, 10795.513888543537, 10849.96925292096, 592683.0, 594589.027054862, 599133.0, 599599.0, 609563.0, 110423.0, 111008.0, 111639.0, 126547.36726316309, 130896.0, 967.8403063494585, 993.1062044160169, 1010.6008126790024, 1011.8633984412157, 1016.7966406718685, 17678.646427071642, 18431.986240756887, 18459.630807384045, 18601.81398186055, 18674.81325186785, 50020.49979500158, 50880.0, 52180.47819521756, 52463.73768131135, 53793.0, 50764.238536421246, 66816.6659166701, 69748.30251697621, 74559.25440745523, 76376.0, 7323.340899319087, 7510.699572017058, 7530.134034586093, 7629.6566654499575, 7662.923370766221, 8964.820703585761, 10202.438865862448, 11372.97643212115, 11427.885721143015, 12530.060245481443, 8880.333974952033, 10362.533685983997, 10654.0, 10754.73113172177, 10930.453477325942, 10371.340585506283, 10482.737931551772, 10609.469526523488, 10641.201909856636, 10792.190585705946, 9364.95317523408, 10677.305975111592, 10772.138228941823, 10985.231033827555, 11023.228374013743, 6232.5041989923, 7377.5204611700065, 7487.812804679708, 7511.661975211238, 7655.157932627518, 7882.330001949669, 8804.559772011502, 10303.53634086453, 10440.112590429899, 10508.369497830156], "value": 9007.550942180369, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1501.932413041413, 1536.4775976168105, 1727.1968534631394, 1796.3263776083968, 1828.8171182881706, 7890.684372625088, 8178.263956243937, 8428.91571084289, 8995.0, 9013.864792028126, 8415.495070295787, 8766.605502752376, 8774.956125219365, 8887.11128887111, 8916.242119419858, 2306.423394151462, 2327.9301620951314, 2345.4839935214227, 2348.154664320843, 2363.7045369328835, 2091.9122056530596, 2291.049214575949, 2294.609916314222, 2313.704325577682, 2354.987355437161, 2298.609236429811, 2304.873231972241, 2315.8147348212187, 2320.6519022146726, 2323.93028209154, 2291.46150654596, 2297.368223738477, 2298.5517824024323, 2302.9424264393356, 2307.896144673485, 2289.8740569268684, 2292.7363353214428, 2298.138198175684, 2299.471121642015, 2300.252417964168, 8473.915260847374, 8595.44129631572, 8601.5699215039, 8606.010308814504, 8610.268127209194, 5182.481751824825, 5326.7070311132875, 5350.5452036576935, 5351.491608297206, 5357.4106848246665, 3490.3019396120876, 3555.3955827509385, 3625.3655610268097, 3685.7051435885064, 3685.981570092159, 1040.8534998698965, 1074.7635520185625, 1078.8813230544633, 1091.7052395853075, 1098.5605757696906, 11510.136739744552, 11617.593384231512, 11618.535258589647, 11634.883651163465, 11671.533138674442, 4497.595216430472, 4651.279051746985, 4749.762511874393, 4780.163471392492, 4781.330613714083, 9891.35706179096, 9976.900230997597, 9995.350302230478, 10003.299769016245, 10012.69961901144, 5342.893142137135, 5345.572354211654, 5351.652142610717, 5358.97320513395, 5374.381946076173, 4349.608535231781, 4533.38799262102, 4735.763211839394, 4860.513948605111, 4902.28916807061, 3825.6174382562076, 3862.729608927348, 3929.351656976624, 3961.999190408825, 4000.3999400089638, 1240.7208378115033, 1242.5837344489516, 1245.3337464456472, 1255.0837888341562, 1257.1451413039222, 617.8115674719254, 840.0549381945311, 893.9508327041945, 898.1377877237794, 934.1219253901311, 4661.3707149534375, 5355.7054362009685, 5635.295588051494, 5870.119482077637, 6010.489108425745, 882.82343531294, 908.3505293714893, 919.3059240273551, 946.2193690205607, 982.7985263021063, 3851.383778595411, 3967.016491754123, 4005.0575470896592, 4088.897777555585, 4096.426500289962, 10620.468976551141, 10924.836127458095, 10980.725481862857, 10982.890171098186, 11009.174311926477, 10832.187585931088, 10904.50929708165, 10916.890831091747, 10942.23404361687, 10955.726106847233, 8173.509589424534, 8265.504069755834, 8268.007839059232, 8301.584920753938, 8326.916730832736, 5639.295088113986, 5764.769409223584, 5866.735996880236, 5907.9704601476715, 5970.880582388415, 10002.949985250027, 10029.348092374265, 10086.193104551472, 10113.988601139827, 10207.846882296622, 9279.39683920543, 9596.280278979244, 9742.31803773729, 10161.491925403849, 10272.33229840088, 6385.808425747235, 6794.796156115324, 7763.524930263232, 7790.571518566533, 8386.454880432751, 8067.556284404414, 8207.507549547046, 8231.588420579066, 8305.418620696493, 8322.250997410261, 7221.67502462401, 7398.556086634819, 7447.590382528796, 7467.290607392289, 7506.474546781673, 7195.172555156224, 7306.196318405077, 7359.411247100112, 7366.410687145123, 7390.445716571387, 7010.193827709879, 7026.0, 7106.289371062852, 7114.253003434772, 7123.786286411416, 4936.691776679111, 4959.975200123977, 5027.698338099725, 5118.232265160255, 5118.8720281991755, 4955.876103097451, 4972.604723874637, 5130.692158470354, 5227.163653815372, 5233.973830130977], "value": 4567.2198823139315, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [300.5101684254665, 306.7821846488993, 307.57400999615527, 314.18939137026473, 314.5093653899915, 5475.808346707866, 5484.2596249506305, 5508.03609368361, 5515.007298686235, 5577.247071645326, 4847.927281090787, 5140.126178549646, 5338.225957236198, 5358.285348688411, 5407.5133238008575, 3881.4862203740563, 4016.3975403689533, 4055.3916912463073, 4083.121763988554, 4096.918061638766, 3576.4277715565513, 3623.855045798165, 4120.958790412103, 4125.381192821071, 4127.8968025799295, 3430.7941523508543, 4142.2543942090415, 4146.0099152406865, 4165.500139983206, 4165.770882601456, 3844.3656796628525, 3902.2000489899788, 3942.1327307992196, 4154.709170358061, 4181.895452613679, 2725.5639097744265, 3311.503274508843, 3566.340227057977, 3631.0377749896297, 3634.60019397866, 4566.885827854297, 4776.450708168571, 4870.464248932614, 5179.948200517984, 5253.395859476171, 3771.396576547739, 4047.340590357939, 4317.4025610523995, 4603.790180713243, 4835.153848076572, 5032.798688052473, 5474.09677403228, 5754.79858204965, 5787.276590426197, 5808.651480911159, 3359.8152101634646, 3540.3804334241663, 3693.445983102529, 3719.7910679029155, 4070.857519986816, 4538.387317712132, 5707.829894871541, 5761.740721667535, 6053.364396738323, 6121.571489995702, 3451.6893479586956, 4059.2693315203546, 4083.7952803922913, 4089.7341672791767, 4093.89765255871, 3758.924821503609, 3980.20395920817, 4156.314208155681, 4159.376093585925, 4174.499060112806, 3953.4069889516227, 4310.3103503439925, 4797.232442809134, 4848.539388758062, 4861.149298872683, 5513.58648101386, 5742.28221472316, 5748.913766293509, 5798.9130163047585, 5844.071456419371, 1305.7660510817377, 1426.9357878895266, 1428.728541577097, 1433.4982756035304, 1435.1747745046432, 766.2682138557664, 830.5971603772166, 865.6623916672503, 874.6982291109557, 883.9513826739591, 4869.659123861366, 5064.809769704117, 5259.342582177228, 5449.6730196188355, 5449.727513624303, 5959.3683968650075, 5965.28416590012, 5976.491998180203, 5982.252218472691, 6009.699515024231, 4381.101874115799, 4693.882652933636, 5206.349206349207, 5442.0, 5468.91796623051, 2515.452996407196, 2578.9871050645, 3110.4603221405096, 3116.189790654401, 3119.2357872321427, 6045.879082418327, 6100.0, 6108.419700128481, 6146.907796383058, 6148.661823599656, 5195.9740201298755, 5273.683578985273, 5572.3034620672415, 5999.640201487181, 6000.21997140369, 6153.384661533811, 6153.6924153093905, 6162.9493400766905, 6232.968835155795, 6249.437550620466, 5670.203276139584, 5700.486956173964, 5818.94181058184, 5888.4700376966275, 5931.61444506123, 4805.687630304019, 4966.938720431125, 5147.150720131286, 5317.893642127213, 5532.91700624499, 5589.847908555154, 6128.9693551531955, 6161.7843375483435, 6163.660998644938, 6182.69086545662, 3615.8610037838, 3650.9229777216315, 3709.9258014840093, 3818.8614375949555, 3913.412988051815, 5248.58011359098, 5392.218128371443, 5892.793752218652, 5939.851503712442, 5958.553108516792, 5007.574356179765, 5148.9227661584355, 5701.971490142523, 5791.681457519709, 5812.593118481835, 5433.8369848904595, 5642.85892852682, 5669.092945128925, 5704.172894930129, 5784.421557844182, 4952.207646776498, 5420.322459692538, 5612.270404847343, 5628.605997580294, 5660.773569057192, 4140.875773726672, 5509.724513774215, 5692.943070569241, 5743.4543718346695, 5746.224259725051, 4575.748333841538, 4918.729469879191, 5407.756216070405, 5507.449255074461, 5651.886962260814], "value": 4139.654170868028, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [684.3361938919248, 691.0601581848688, 744.4937442539072, 748.4162353364375, 770.7995921060523, 14542.563723088299, 15664.216789160553, 15709.607259818511, 15711.214439278034, 15719.0, 14244.572662820132, 14379.137251764902, 14382.424703011893, 14388.0, 14400.0, 661.4344735251362, 688.1192074145102, 801.9919800801975, 817.8650522663753, 840.756180707594, 821.1624143373755, 836.4646626159263, 837.0875745437472, 837.6900546797663, 841.1168273313027, 889.0442774017924, 894.2443635128307, 894.9910500895005, 895.292718752185, 896.7713233125577, 873.5195642396657, 877.8112705768259, 887.49856331173, 887.5296093070683, 889.5907882374115, 652.1131261484378, 660.9239937407211, 662.102850637389, 666.4168852254278, 666.5067849791168, 14295.07082039664, 14331.426742930375, 14339.139651620937, 14344.928275358661, 14372.49696260626, 13994.860051399559, 13996.580102596938, 14041.087329323562, 14083.436662533486, 14172.645683857883, 11678.182527223013, 11685.941570292094, 11722.648320550397, 11919.582814601534, 11949.402529873472, 3585.8565657373933, 3692.150805314766, 3797.4303854421782, 3830.138218900725, 3851.364524853424, 91640.6253906192, 96930.0, 100570.0, 101633.4918325411, 101649.0, 10441.791164176826, 10598.947005265078, 10694.73263168427, 10742.785144297226, 10768.838467422995, 19927.800721992593, 20038.49903752418, 20079.39761807119, 20098.39704808858, 20134.093965771575, 7435.405167586689, 7593.848123037619, 7742.264484874041, 7792.376609871197, 7879.369650427952, 3915.3148199065618, 4386.232409328355, 4445.088756804847, 4450.955490445119, 4516.525764794715, 3545.858165673344, 3680.0, 3690.151265209017, 3697.4638677391627, 3702.241040586673, 591.3317950715664, 624.7657128576784, 672.4721093941232, 686.3239708886742, 698.7379732600275, 534.398801348483, 565.6973519167226, 568.766805609698, 613.6747523812387, 619.615838180331, 8430.15698430152, 9016.819663606691, 9481.0, 9518.476483793458, 9688.70933871985, 534.3827878799992, 557.1642536195707, 635.2789583822395, 646.4828137489989, 662.2715013485224, 4385.0, 4634.490206077328, 4684.976575117103, 4689.742064186503, 4727.834525791581, 97710.5114474423, 99562.0, 118557.0, 155229.0, 157091.0, 123805.0, 131506.0, 133703.0, 134803.0, 134827.325863372, 108054.0, 109950.45024774825, 113074.0, 115829.0, 115977.8402215984, 7597.696092156252, 8231.506109633441, 9080.63677452921, 9085.682001129928, 9175.77060573491, 49266.27568035101, 52157.95684086373, 58073.54816129462, 60568.39431605628, 60779.392206079145, 78485.21514785007, 86958.69561956449, 92601.07398925924, 97307.51346243224, 97897.53153702557, 12921.418536165707, 13209.94320454342, 13214.669633259246, 13238.735225295633, 13497.527586534425, 14365.281735913371, 14556.0, 14672.266386680836, 14891.404343826125, 14969.925150374178, 11865.11011674145, 13067.803982940453, 13423.463061477822, 13744.931275343559, 13897.444102236203, 13752.793708094187, 13895.0, 13940.372683229481, 14213.431462741375, 14464.566063018126, 13117.803232951705, 13322.0, 13677.452901883813, 13942.372593233125, 14154.646133846325, 10980.011798938134, 11361.659150225507, 11474.713132171762, 11485.425728713699, 11719.941400293284, 8678.696245631372, 9178.99031106591, 9353.158215760614, 11373.431328433711, 11614.88385116138], "value": 7620.717849864668, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1600.1999999999998, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 126.0, 128.0, 119.0, 118.0, 121.0, 173.0, 145.0, 118.0, 98.0], "value": 125.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [172.0, 142.0, 118.0, 148.0, 118.0, 112.0, 115.0, 115.0, 144.0, 116.0], "value": 130.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [199.0, 195.0, 221.0, 293.0, 268.0, 276.0, 200.0, 279.0, 278.0, 193.0], "value": 240.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 70.0, 59.0, 85.0, 61.0, 60.0, 59.0, 60.0, 77.0, 60.0], "value": 67.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 71.0, 87.0, 73.0, 77.0, 86.0, 119.0, 70.0, 70.0, 87.0], "value": 82.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 89.0, 91.0, 88.0, 99.0, 88.0, 92.0, 90.0, 90.0, 87.0], "value": 90.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [100.0, 102.0, 99.0, 113.0, 101.0, 100.0, 111.0, 135.0, 101.0, 102.0], "value": 106.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 128.0, 98.0, 124.0, 81.0, 153.0, 157.0, 113.0, 91.0, 92.0], "value": 114.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 56.0, 56.0, 57.0, 48.0, 59.0, 80.0, 51.0, 53.0, 59.0], "value": 57.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 70.0, 80.0, 97.0, 109.0, 67.0, 63.0, 63.0, 76.0, 65.0], "value": 76.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 82.0, 112.0, 108.0, 90.0, 88.0, 91.0, 103.0, 93.0, 89.0], "value": 93.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 153.0, 159.0, 136.0, 138.0, 137.0, 132.0, 153.0, 221.0, 168.0], "value": 154.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [176.0, 204.0, 229.0, 179.0, 174.0, 175.0, 174.0, 173.0, 207.0, 200.0], "value": 189.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 70.0, 71.0, 69.0, 71.0, 70.0, 70.0, 70.0, 70.0, 86.0], "value": 71.8, "unit": "score"}]}]} 16:48:00 INFO - Return code: 0 16:48:00 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 16:48:00 ERROR - # TBPL SUCCESS # 16:48:00 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 16:48:00 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 16:48:00 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 16:48:00 INFO - 2016-10-17 16:48:00.715 screenresolution[1672:10741] starting screenresolution argv=screenresolution get 16:48:00 INFO - 2016-10-17 16:48:00.718 screenresolution[1672:10741] Display 0: 1600x1200x32@60 16:48:00 INFO - 2016-10-17 16:48:00.724 screenresolution[1673:10744] starting screenresolution argv=screenresolution list 16:48:00 INFO - Available Modes on Display 0 16:48:00 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 16:48:00 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 16:48:00 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 16:48:00 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 16:48:00 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 16:48:00 INFO - Intel Iris: 16:48:00 INFO - Chipset Model: Intel Iris 16:48:00 INFO - Type: GPU 16:48:00 INFO - Bus: Built-In 16:48:00 INFO - VRAM (Dynamic, Max): 1536 MB 16:48:00 INFO - Vendor: Intel (0x8086) 16:48:00 INFO - Device ID: 0x0a2e 16:48:00 INFO - Revision ID: 0x0009 16:48:00 INFO - Displays: 16:48:00 INFO - Display: 16:48:00 INFO - Resolution: 1600 x 1200 @ 60 Hz 16:48:00 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 16:48:00 INFO - Display Serial Number: 1600x1200 60 16:48:00 INFO - Main Display: Yes 16:48:00 INFO - Mirror: Off 16:48:00 INFO - Online: Yes 16:48:00 INFO - Rotation: Supported 16:48:00 INFO - Return code: 0 16:48:00 INFO - Running post-action listener: _resource_record_post_action 16:48:00 INFO - [mozharness: 2016-10-17 23:48:00.964123Z] Finished run-tests step (success) 16:48:00 INFO - Running post-run listener: _resource_record_post_run 16:48:01 INFO - Total resource usage - Wall time: 1211s; CPU: 55.0%; Read bytes: 38394368; Write bytes: 710963712; Read time: 242; Write time: 2054 16:48:01 INFO - TinderboxPrint: CPU usage
54.6% 16:48:01 INFO - TinderboxPrint: I/O read bytes / time
38,394,368 / 242 16:48:01 INFO - TinderboxPrint: I/O write bytes / time
710,963,712 / 2,054 16:48:01 INFO - TinderboxPrint: CPU idle
2,200.5 (45.4%) 16:48:01 INFO - TinderboxPrint: CPU system
978.8 (20.2%) 16:48:01 INFO - TinderboxPrint: CPU user
1,666.4 (34.4%) 16:48:01 INFO - TinderboxPrint: Swap in / out
446,042,112 / 0 16:48:01 INFO - install - Wall time: 35s; CPU: 55.0%; Read bytes: 389065216; Write bytes: 383073792; Read time: 29111; Write time: 834 16:48:01 INFO - run-tests - Wall time: 1177s; CPU: 55.0%; Read bytes: 37440000; Write bytes: 300160000; Read time: 230; Write time: 1157 16:48:01 INFO - Running post-run listener: _upload_blobber_files 16:48:01 INFO - Blob upload gear active. 16:48:01 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 16:48:01 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:01 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:01 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:01 INFO - (blobuploader) - INFO - Open directory for files ... 16:48:01 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 16:48:01 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:48:01 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:48:02 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 16:48:02 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:48:02 INFO - (blobuploader) - INFO - Done attempting. 16:48:02 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 16:48:02 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:48:02 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:48:04 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_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:06 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 16:48:06 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:48:06 INFO - (blobuploader) - INFO - Done attempting. 16:48:06 INFO - (blobuploader) - INFO - Iteration through files over. 16:48:06 INFO - Return code: 0 16:48:06 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 16:48:06 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 16:48:06 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/da3d8b3a4539122195ef1069f68505fbf2a96b9ea30cad009fab61bc883fe89a3611b33d792c2eef395dec6629a319195133cfa65aec0b35af25260ccf30ef22", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/ecbd2b7cc88213e6ba778ebb15ba7a4294282fe3c95e472cdcc159c599e218ff0109a21b3c5cccfda17663d30c49ec664680e41ac5784eb2a2e3ef68c5f6b9e2", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/70794e2f7051fefa712d0d7741d88da438ee801dd46b8180c278287f461a318e4edf9999ce65e50ba41346a0acc5808f57c02567d039cbaf2d73cb1c9c2307c5"} 16:48:06 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 16:48:06 INFO - Writing to file /builds/slave/test/properties/blobber_files 16:48:06 INFO - Contents: 16:48:06 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/da3d8b3a4539122195ef1069f68505fbf2a96b9ea30cad009fab61bc883fe89a3611b33d792c2eef395dec6629a319195133cfa65aec0b35af25260ccf30ef22", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/ecbd2b7cc88213e6ba778ebb15ba7a4294282fe3c95e472cdcc159c599e218ff0109a21b3c5cccfda17663d30c49ec664680e41ac5784eb2a2e3ef68c5f6b9e2", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/70794e2f7051fefa712d0d7741d88da438ee801dd46b8180c278287f461a318e4edf9999ce65e50ba41346a0acc5808f57c02567d039cbaf2d73cb1c9c2307c5"} 16:48:06 INFO - Running post-run listener: copy_logs_to_upload_dir 16:48:06 INFO - Copying logs to upload dir... 16:48:06 INFO - mkdir: /builds/slave/test/build/upload/logs 16:48:06 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1274.384051 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-10-17 16:48:06.583959) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-17 16:48:06.587287) ========= 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.L3jjK6jxeB/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.z2OF3cmixP/Listeners TMPDIR=/var/folders/hy/45ssh3ld6sx_27c31ssq2l9w00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/da3d8b3a4539122195ef1069f68505fbf2a96b9ea30cad009fab61bc883fe89a3611b33d792c2eef395dec6629a319195133cfa65aec0b35af25260ccf30ef22", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/ecbd2b7cc88213e6ba778ebb15ba7a4294282fe3c95e472cdcc159c599e218ff0109a21b3c5cccfda17663d30c49ec664680e41ac5784eb2a2e3ef68c5f6b9e2", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/70794e2f7051fefa712d0d7741d88da438ee801dd46b8180c278287f461a318e4edf9999ce65e50ba41346a0acc5808f57c02567d039cbaf2d73cb1c9c2307c5"} 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.011200 build_url: 'https://queue.taskcluster.net/v1/task/V6dbqvZfQiKZUCjcPPrl0g/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/da3d8b3a4539122195ef1069f68505fbf2a96b9ea30cad009fab61bc883fe89a3611b33d792c2eef395dec6629a319195133cfa65aec0b35af25260ccf30ef22", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/ecbd2b7cc88213e6ba778ebb15ba7a4294282fe3c95e472cdcc159c599e218ff0109a21b3c5cccfda17663d30c49ec664680e41ac5784eb2a2e3ef68c5f6b9e2", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/70794e2f7051fefa712d0d7741d88da438ee801dd46b8180c278287f461a318e4edf9999ce65e50ba41346a0acc5808f57c02567d039cbaf2d73cb1c9c2307c5"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-17 16:48:06.619956) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-17 16:48:06.620293) ========= 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.L3jjK6jxeB/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.z2OF3cmixP/Listeners TMPDIR=/var/folders/hy/45ssh3ld6sx_27c31ssq2l9w00000w/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.004977 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-17 16:48:06.668987) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-17 16:48:06.669307) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-17 16:48:06.669655) ========= ========= Total master_lag: 0.17 =========