builder: autoland_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0114 starttime: 1476834124.6 results: success (0) buildid: 20161018152323 builduid: 5e382677afe242a682c83109137385f6 revision: 352cd8e264990a86fb486088e0a83b20aa9a1274 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-10-18 16:42:04.602545) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-10-18 16:42:04.603016) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-18 16:42:04.603317) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-18 16:42:04.636408) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-18 16:42:04.637012) ========= 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.5vBKHtcXHC/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.uYdZICfFhj/Listeners TMPDIR=/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/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-18 16:42:04-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 12.5M=0.001s 2016-10-18 16:42:04 (12.5 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.074905 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-18 16:42:04.734339) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-18 16:42:04.734669) ========= 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.5vBKHtcXHC/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.uYdZICfFhj/Listeners TMPDIR=/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/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.020268 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-18 16:42:04.783288) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-10-18 16:42:04.783665) ========= bash -c 'python archiver_client.py mozharness --repo integration/autoland --rev 352cd8e264990a86fb486088e0a83b20aa9a1274 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/autoland --rev 352cd8e264990a86fb486088e0a83b20aa9a1274 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.5vBKHtcXHC/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.uYdZICfFhj/Listeners TMPDIR=/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/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-18 16:42:04,859 truncating revision to first 12 chars 2016-10-18 16:42:04,860 Setting DEBUG logging. 2016-10-18 16:42:04,860 attempt 1/10 2016-10-18 16:42:04,860 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/352cd8e26499?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-10-18 16:42:06,788 unpacking tar archive at: autoland-352cd8e26499/testing/mozharness/ program finished with exit code 0 elapsedTime=2.161577 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-10-18 16:42:06.966976) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-18 16:42:06.967322) ========= script_repo_revision: 352cd8e264990a86fb486088e0a83b20aa9a1274 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-18 16:42:06.967770) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-18 16:42:06.968040) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-18 16:42:06.986822) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2016-10-18 16:42:06.987185) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Autoland --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Autoland', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Autoland'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.5vBKHtcXHC/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.uYdZICfFhj/Listeners TMPDIR=/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/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:42:07 INFO - MultiFileLogger online at 20161018 16:42:07 in /builds/slave/test 16:42:07 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Autoland --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland 16:42:07 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 16:42:07 INFO - {'append_to_log': False, 16:42:07 INFO - 'base_work_dir': '/builds/slave/test', 16:42:07 INFO - 'blob_upload_branch': 'Autoland', 16:42:07 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 16:42:07 INFO - 'branch': 'Autoland', 16:42:07 INFO - 'buildbot_json_path': 'buildprops.json', 16:42:07 INFO - 'config_files': ('talos/mac_config.py',), 16:42:07 INFO - 'default_actions': ('clobber', 16:42:07 INFO - 'read-buildbot-config', 16:42:07 INFO - 'download-and-extract', 16:42:07 INFO - 'populate-webroot', 16:42:07 INFO - 'create-virtualenv', 16:42:07 INFO - 'install', 16:42:07 INFO - 'run-tests'), 16:42:07 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 16:42:07 INFO - 'download_minidump_stackwalk': True, 16:42:07 INFO - 'download_symbols': 'ondemand', 16:42:07 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 16:42:07 INFO - 'tooltool.py': '/tools/tooltool.py', 16:42:07 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 16:42:07 INFO - '/tools/misc-python/virtualenv.py')}, 16:42:07 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 16:42:07 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 16:42:07 INFO - 'installer_path': 'installer.exe', 16:42:07 INFO - 'log_level': 'info', 16:42:07 INFO - 'log_name': 'talos', 16:42:07 INFO - 'log_to_console': True, 16:42:07 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 16:42:07 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 16:42:07 INFO - 'opt_config_files': (), 16:42:07 INFO - 'pip_index': False, 16:42:07 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 16:42:07 INFO - 'cmd': ('bash', 16:42:07 INFO - '-c', 16:42:07 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 16:42:07 INFO - 'enabled': True, 16:42:07 INFO - 'halt_on_failure': False, 16:42:07 INFO - 'name': 'check_screen_resolution'},), 16:42:07 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 16:42:07 INFO - 'cmd': ('bash', 16:42:07 INFO - '-c', 16:42:07 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 16:42:07 INFO - 'enabled': True, 16:42:07 INFO - 'halt_on_failure': False, 16:42:07 INFO - 'name': 'check_screen_resolution'},), 16:42:07 INFO - 'run_cmd_checks_enabled': True, 16:42:07 INFO - 'sps_profile': False, 16:42:07 INFO - 'sps_profile_interval': 0, 16:42:07 INFO - 'suite': 'dromaeojs-e10s', 16:42:07 INFO - 'system_bits': '32', 16:42:07 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 16:42:07 INFO - 'title': 't-yosemite-r7-0114', 16:42:07 INFO - 'tooltool_cache': '/builds/tooltool_cache', 16:42:07 INFO - 'use_talos_json': True, 16:42:07 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 16:42:07 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 16:42:07 INFO - 'work_dir': 'build'} 16:42:07 INFO - [mozharness: 2016-10-18 23:42:07.191469Z] Running clobber step. 16:42:07 INFO - Running pre-action listener: _resource_record_pre_action 16:42:07 INFO - Running main action method: clobber 16:42:07 INFO - rmtree: /builds/slave/test/build 16:42:07 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 16:42:09 INFO - Running post-action listener: _resource_record_post_action 16:42:09 INFO - [mozharness: 2016-10-18 23:42:09.174003Z] Finished clobber step (success) 16:42:09 INFO - [mozharness: 2016-10-18 23:42:09.174143Z] Running read-buildbot-config step. 16:42:09 INFO - Running pre-action listener: _resource_record_pre_action 16:42:09 INFO - Running main action method: read_buildbot_config 16:42:09 INFO - Using buildbot properties: 16:42:09 INFO - { 16:42:09 INFO - "project": "", 16:42:09 INFO - "product": "firefox", 16:42:09 INFO - "script_repo_revision": "production", 16:42:09 INFO - "builddir": "autoland_yosemite_r7_test-dromaeojs-e10s", 16:42:09 INFO - "repository": "", 16:42:09 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 autoland talos dromaeojs-e10s", 16:42:09 INFO - "buildid": "20161018152323", 16:42:09 INFO - "pgo_build": "False", 16:42:09 INFO - "basedir": "/builds/slave/test", 16:42:09 INFO - "buildnumber": 204, 16:42:09 INFO - "slavename": "t-yosemite-r7-0114", 16:42:09 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 16:42:09 INFO - "platform": "yosemite_r7", 16:42:09 INFO - "scheduler": "tests-autoland-macosx64-talos-8-3600", 16:42:09 INFO - "branch": "autoland", 16:42:09 INFO - "repo_path": "integration/autoland", 16:42:09 INFO - "revision": "352cd8e264990a86fb486088e0a83b20aa9a1274", 16:42:09 INFO - "stage_platform": "macosx64", 16:42:09 INFO - "builduid": "5e382677afe242a682c83109137385f6", 16:42:09 INFO - "slavebuilddir": "test" 16:42:09 INFO - } 16:42:09 INFO - Found installer url https://queue.taskcluster.net/v1/task/ZfEMSV2MR6-x5OhRQB8aUA/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg. 16:42:09 INFO - Running post-action listener: _resource_record_post_action 16:42:09 INFO - [mozharness: 2016-10-18 23:42:09.177460Z] Finished read-buildbot-config step (success) 16:42:09 INFO - [mozharness: 2016-10-18 23:42:09.177567Z] Running download-and-extract step. 16:42:09 INFO - Running pre-action listener: _resource_record_pre_action 16:42:09 INFO - Running main action method: download_and_extract 16:42:09 INFO - mkdir: /builds/slave/test/build/tests 16:42:09 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:42:09 INFO - trying https://queue.taskcluster.net/v1/task/ZfEMSV2MR6-x5OhRQB8aUA/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json 16:42:09 INFO - Downloading https://queue.taskcluster.net/v1/task/ZfEMSV2MR6-x5OhRQB8aUA/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:42:09 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ZfEMSV2MR6-x5OhRQB8aUA/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:42:10 INFO - Downloaded 1199 bytes. 16:42:10 INFO - Reading from file /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 16:42:10 INFO - Using the following test package requirements: 16:42:10 INFO - {u'common': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 16:42:10 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 16:42:10 INFO - u'firefox-52.0a1.en-US.mac.cppunittest.tests.zip'], 16:42:10 INFO - u'gtest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 16:42:10 INFO - u'firefox-52.0a1.en-US.mac.gtest.tests.zip'], 16:42:10 INFO - u'jittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 16:42:10 INFO - u'jsshell-mac.zip'], 16:42:10 INFO - u'mochitest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 16:42:10 INFO - u'firefox-52.0a1.en-US.mac.mochitest.tests.zip'], 16:42:10 INFO - u'mozbase': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 16:42:10 INFO - u'reftest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 16:42:10 INFO - u'firefox-52.0a1.en-US.mac.reftest.tests.zip'], 16:42:10 INFO - u'talos': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 16:42:10 INFO - u'firefox-52.0a1.en-US.mac.talos.tests.zip'], 16:42:10 INFO - u'web-platform': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 16:42:10 INFO - u'firefox-52.0a1.en-US.mac.web-platform.tests.zip'], 16:42:10 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 16:42:10 INFO - u'firefox-52.0a1.en-US.mac.xpcshell.tests.zip']} 16:42:10 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip'] for test suite category: common 16:42:10 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/ZfEMSV2MR6-x5OhRQB8aUA/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 16:42:10 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ZfEMSV2MR6-x5OhRQB8aUA/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 16:42:10 INFO - Fetch https://queue.taskcluster.net/v1/task/ZfEMSV2MR6-x5OhRQB8aUA/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 16:42:10 INFO - Http code: 200 16:42:10 INFO - via: 1.1 bb8a7ff26b131d51953dc2d3e19a2751.cloudfront.net (CloudFront) 16:42:10 INFO - x-amz-cf-id: YpbwNj4PyGbuPRVdd-EAJeEMbo572AVE9jB3N6BH_ZYKwBNMww5Z1w== 16:42:10 INFO - x-amz-version-id: WWkV1mOGkFGbktbK5w9wdgDgAi9.7oOe 16:42:11 INFO - Expected file size: 16483656 16:42:11 INFO - Obtained file size: 16483656 16:42:18 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:42:18 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/ZfEMSV2MR6-x5OhRQB8aUA/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 16:42:18 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ZfEMSV2MR6-x5OhRQB8aUA/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 16:42:18 INFO - Fetch https://queue.taskcluster.net/v1/task/ZfEMSV2MR6-x5OhRQB8aUA/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 16:42:18 INFO - Http code: 200 16:42:18 INFO - via: 1.1 a1aa00de8387e7235a256b2a5b73ede8.cloudfront.net (CloudFront) 16:42:18 INFO - x-amz-cf-id: wAYhDttrD11FgL9qSP9AxpDrmgYHM89nXGWW5twuQfRItT-2a3vKwg== 16:42:18 INFO - x-amz-version-id: WWkV1mOGkFGbktbK5w9wdgDgAi9.7oOe 16:42:19 INFO - Expected file size: 16483656 16:42:19 INFO - Obtained file size: 16483656 16:42:25 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/ZfEMSV2MR6-x5OhRQB8aUA/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip 16:42:25 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ZfEMSV2MR6-x5OhRQB8aUA/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip'}, attempt #1 16:42:25 INFO - Fetch https://queue.taskcluster.net/v1/task/ZfEMSV2MR6-x5OhRQB8aUA/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip into memory 16:42:26 INFO - Http code: 200 16:42:26 INFO - via: 1.1 b3bccc0e4c2cc0a33e7e8308d88cacdd.cloudfront.net (CloudFront) 16:42:26 INFO - x-amz-cf-id: lO0fbakBYi2Am-J0rX92Casyh8-B3J3zayqChRq2n8Km6pYHfcIf4g== 16:42:26 INFO - x-amz-version-id: vw_4lmqCTlPC76X3OgZIEN_1iSpL60yv 16:42:26 INFO - Expected file size: 14409028 16:42:26 INFO - Obtained file size: 14409028 16:42:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:42:26 INFO - trying https://queue.taskcluster.net/v1/task/ZfEMSV2MR6-x5OhRQB8aUA/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 16:42:26 INFO - Downloading https://queue.taskcluster.net/v1/task/ZfEMSV2MR6-x5OhRQB8aUA/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg 16:42:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ZfEMSV2MR6-x5OhRQB8aUA/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:42:29 INFO - Downloaded 111627254 bytes. 16:42:29 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/ZfEMSV2MR6-x5OhRQB8aUA/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 16:42:29 INFO - mkdir: /builds/slave/test/properties 16:42:29 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 16:42:29 INFO - Writing to file /builds/slave/test/properties/build_url 16:42:29 INFO - Contents: 16:42:29 INFO - build_url:https://queue.taskcluster.net/v1/task/ZfEMSV2MR6-x5OhRQB8aUA/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 16:42:30 INFO - Running post-action listener: _resource_record_post_action 16:42:30 INFO - Running post-action listener: set_extra_try_arguments 16:42:30 INFO - [mozharness: 2016-10-18 23:42:30.408326Z] Finished download-and-extract step (success) 16:42:30 INFO - [mozharness: 2016-10-18 23:42:30.408439Z] Running populate-webroot step. 16:42:30 INFO - Running pre-action listener: _resource_record_pre_action 16:42:30 INFO - Running main action method: populate_webroot 16:42:30 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 16:42:30 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 16:42:30 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 16:42:30 INFO - '--tppagecycles', 16:42:30 INFO - '7'], 16:42:30 INFO - 'tests': ['tsvgm']}}, 16:42:30 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 16:42:30 INFO - 'tests': ['tresize', 'tcanvasmark']}, 16:42:30 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 16:42:30 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 16:42:30 INFO - 'tests': ['dromaeo_css', 'kraken']}, 16:42:30 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 16:42:30 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:42:30 INFO - 'talos_options': ['--disable-e10s'], 16:42:30 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:42:30 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:42:30 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:42:30 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:42:30 INFO - 'talos_options': ['--disable-e10s'], 16:42:30 INFO - 'tests': ['damp', 'tps']}, 16:42:30 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:42:30 INFO - 'tests': ['damp', 'tps']}, 16:42:30 INFO - 'g3': {'talos_options': ['--disable-e10s'], 16:42:30 INFO - 'tests': ['dromaeo_dom']}, 16:42:30 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 16:42:30 INFO - 'g4': {'talos_options': ['--disable-e10s'], 16:42:30 INFO - 'tests': ['basic_compositor_video']}, 16:42:30 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 16:42:30 INFO - 'other': {'talos_options': ['--disable-e10s'], 16:42:30 INFO - 'tests': ['a11yr', 16:42:30 INFO - 'ts_paint', 16:42:30 INFO - 'tpaint', 16:42:30 INFO - 'sessionrestore', 16:42:30 INFO - 'sessionrestore_no_auto_restore', 16:42:30 INFO - 'tabpaint']}, 16:42:30 INFO - 'other-e10s': {'tests': ['a11yr', 16:42:30 INFO - 'ts_paint', 16:42:30 INFO - 'tpaint', 16:42:30 INFO - 'sessionrestore', 16:42:30 INFO - 'sessionrestore_no_auto_restore', 16:42:30 INFO - 'tabpaint']}, 16:42:30 INFO - 'other-e10s_l64': {'tests': ['a11yr', 16:42:30 INFO - 'ts_paint', 16:42:30 INFO - 'tpaint', 16:42:30 INFO - 'sessionrestore', 16:42:30 INFO - 'sessionrestore_no_auto_restore', 16:42:30 INFO - 'tabpaint']}, 16:42:30 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 16:42:30 INFO - 'ts_paint', 16:42:30 INFO - 'tpaint', 16:42:30 INFO - 'sessionrestore', 16:42:30 INFO - 'sessionrestore_no_auto_restore', 16:42:30 INFO - 'tabpaint']}, 16:42:30 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 16:42:30 INFO - 'tests': ['a11yr', 16:42:30 INFO - 'ts_paint', 16:42:30 INFO - 'tpaint', 16:42:30 INFO - 'sessionrestore', 16:42:30 INFO - 'sessionrestore_no_auto_restore', 16:42:30 INFO - 'tabpaint']}, 16:42:30 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 16:42:30 INFO - 'tests': ['a11yr', 16:42:30 INFO - 'ts_paint', 16:42:30 INFO - 'tpaint', 16:42:30 INFO - 'sessionrestore', 16:42:30 INFO - 'sessionrestore_no_auto_restore', 16:42:30 INFO - 'tabpaint']}, 16:42:30 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 16:42:30 INFO - 'tests': ['tsvgx', 16:42:30 INFO - 'tsvgr_opacity', 16:42:30 INFO - 'tart', 16:42:30 INFO - 'tscrollx', 16:42:30 INFO - 'cart']}, 16:42:30 INFO - 'svgr-e10s': {'tests': ['tsvgx', 16:42:30 INFO - 'tsvgr_opacity', 16:42:30 INFO - 'tart', 16:42:30 INFO - 'tscrollx', 16:42:30 INFO - 'cart']}, 16:42:30 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:42:30 INFO - 'talos_options': ['--disable-e10s'], 16:42:30 INFO - 'tests': ['tp5o']}, 16:42:30 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:42:30 INFO - 'tests': ['tp5o']}, 16:42:30 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:42:30 INFO - 'talos_options': ['--disable-e10s', 16:42:30 INFO - '--xperf_path', 16:42:30 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:42:30 INFO - 'tests': ['tp5n']}, 16:42:30 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:42:30 INFO - 'talos_options': ['--xperf_path', 16:42:30 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:42:30 INFO - 'tests': ['tp5n']}}, 16:42:30 INFO - 'talos.zip': {'path': '', 16:42:30 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 16:42:30 INFO - Running post-action listener: _resource_record_post_action 16:42:30 INFO - [mozharness: 2016-10-18 23:42:30.427134Z] Finished populate-webroot step (success) 16:42:30 INFO - [mozharness: 2016-10-18 23:42:30.427273Z] Running create-virtualenv step. 16:42:30 INFO - Running pre-action listener: _resource_record_pre_action 16:42:30 INFO - Running main action method: create_virtualenv 16:42:30 INFO - Creating virtualenv /builds/slave/test/build/venv 16:42:30 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:42:30 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 16:42:30 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 16:42:30 INFO - Using real prefix '/tools/python27' 16:42:30 INFO - New python executable in /builds/slave/test/build/venv/bin/python 16:42:31 INFO - Installing distribute.............................................................................................................................................................................................done. 16:42:34 INFO - Installing pip.................done. 16:42:34 INFO - Return code: 0 16:42:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 16:42:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 16:42:34 INFO - Reading from file tmpfile_stdout 16:42:34 INFO - Output received: 16:42:34 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:42:34 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 16:42:34 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:42:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:42:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:42:34 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:42:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:42:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:42:34 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 0x1032e8490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10325e570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa2b0d742c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1030fcd30>, '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 0x102f22db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1030f4b20>, '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.5vBKHtcXHC/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.uYdZICfFhj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/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:42:34 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:42:34 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:42:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5vBKHtcXHC/Render', 16:42:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:42:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:42:34 INFO - 'HOME': '/Users/cltbld', 16:42:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:42:34 INFO - 'LOGNAME': 'cltbld', 16:42:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:42:34 INFO - 'MOZ_NO_REMOTE': '1', 16:42:34 INFO - 'NO_EM_RESTART': '1', 16:42:34 INFO - 'PAGER': '/bin/cat', 16:42:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:42:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:42:34 INFO - 'PWD': '/builds/slave/test', 16:42:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:42:34 INFO - 'SHELL': '/bin/bash', 16:42:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.uYdZICfFhj/Listeners', 16:42:34 INFO - 'TMPDIR': '/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/', 16:42:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:42:34 INFO - 'USER': 'cltbld', 16:42:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:42:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:42:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:42:34 INFO - 'XPC_FLAGS': '0x0', 16:42:34 INFO - 'XPC_SERVICE_NAME': '0', 16:42:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:42:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:42:34 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:42:34 INFO - Cleaning up... 16:42:34 INFO - Return code: 0 16:42:34 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 16:42:34 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:42:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:42:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:42:34 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:42:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:42:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:42:34 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 0x1032e8490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10325e570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa2b0d742c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1030fcd30>, '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 0x102f22db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1030f4b20>, '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.5vBKHtcXHC/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.uYdZICfFhj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/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:42:34 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:42:34 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:42:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5vBKHtcXHC/Render', 16:42:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:42:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:42:34 INFO - 'HOME': '/Users/cltbld', 16:42:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:42:34 INFO - 'LOGNAME': 'cltbld', 16:42:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:42:34 INFO - 'MOZ_NO_REMOTE': '1', 16:42:34 INFO - 'NO_EM_RESTART': '1', 16:42:34 INFO - 'PAGER': '/bin/cat', 16:42:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:42:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:42:34 INFO - 'PWD': '/builds/slave/test', 16:42:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:42:34 INFO - 'SHELL': '/bin/bash', 16:42:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.uYdZICfFhj/Listeners', 16:42:34 INFO - 'TMPDIR': '/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/', 16:42:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:42:34 INFO - 'USER': 'cltbld', 16:42:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:42:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:42:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:42:34 INFO - 'XPC_FLAGS': '0x0', 16:42:34 INFO - 'XPC_SERVICE_NAME': '0', 16:42:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:42:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:42:35 INFO - Downloading/unpacking psutil>=3.1.1 16:42: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:42: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:42: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:42: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:42: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:42: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:42:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 16:42:38 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:42:38 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:42:38 INFO - Installing collected packages: psutil 16:42:38 INFO - Running setup.py install for psutil 16:42:38 INFO - building 'psutil._psutil_osx' extension 16:42:38 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:42:38 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:42:39 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:42:39 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:42:39 INFO - building 'psutil._psutil_posix' extension 16:42:39 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:42:39 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 16:42:39 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 16:42:39 INFO - ^ 16:42:39 INFO - 1 warning generated. 16:42:39 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:42:39 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:42:39 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:42:39 INFO - Successfully installed psutil 16:42:39 INFO - Cleaning up... 16:42:39 INFO - Return code: 0 16:42:39 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 16:42:39 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:42:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:42:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:42:39 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:42:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:42:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:42:39 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 0x1032e8490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10325e570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa2b0d742c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1030fcd30>, '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 0x102f22db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1030f4b20>, '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.5vBKHtcXHC/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.uYdZICfFhj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/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:42:39 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:42:39 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:42:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5vBKHtcXHC/Render', 16:42:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:42:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:42:39 INFO - 'HOME': '/Users/cltbld', 16:42:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:42:39 INFO - 'LOGNAME': 'cltbld', 16:42:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:42:39 INFO - 'MOZ_NO_REMOTE': '1', 16:42:39 INFO - 'NO_EM_RESTART': '1', 16:42:39 INFO - 'PAGER': '/bin/cat', 16:42:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:42:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:42:39 INFO - 'PWD': '/builds/slave/test', 16:42:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:42:39 INFO - 'SHELL': '/bin/bash', 16:42:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.uYdZICfFhj/Listeners', 16:42:39 INFO - 'TMPDIR': '/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/', 16:42:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:42:39 INFO - 'USER': 'cltbld', 16:42:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:42:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:42:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:42:39 INFO - 'XPC_FLAGS': '0x0', 16:42:39 INFO - 'XPC_SERVICE_NAME': '0', 16:42:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:42:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:42:39 INFO - Downloading/unpacking mozsystemmonitor==0.3 16:42:39 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:42:39 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:42:39 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:42:39 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:42:39 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:42:39 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:42:42 INFO - Downloading mozsystemmonitor-0.3.tar.gz 16:42:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 16:42:42 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 16:42:42 INFO - Installing collected packages: mozsystemmonitor 16:42:42 INFO - Running setup.py install for mozsystemmonitor 16:42:42 INFO - Successfully installed mozsystemmonitor 16:42:42 INFO - Cleaning up... 16:42:42 INFO - Return code: 0 16:42:42 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 16:42: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:42:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:42:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:42: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:42:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:42:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:42:42 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 0x1032e8490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10325e570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa2b0d742c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1030fcd30>, '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 0x102f22db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1030f4b20>, '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.5vBKHtcXHC/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.uYdZICfFhj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/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:42:42 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:42:42 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:42:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5vBKHtcXHC/Render', 16:42:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:42:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:42:42 INFO - 'HOME': '/Users/cltbld', 16:42:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:42:42 INFO - 'LOGNAME': 'cltbld', 16:42:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:42:42 INFO - 'MOZ_NO_REMOTE': '1', 16:42:42 INFO - 'NO_EM_RESTART': '1', 16:42:42 INFO - 'PAGER': '/bin/cat', 16:42:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:42:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:42:42 INFO - 'PWD': '/builds/slave/test', 16:42:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:42:42 INFO - 'SHELL': '/bin/bash', 16:42:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.uYdZICfFhj/Listeners', 16:42:42 INFO - 'TMPDIR': '/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/', 16:42:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:42:42 INFO - 'USER': 'cltbld', 16:42:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:42:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:42:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:42:42 INFO - 'XPC_FLAGS': '0x0', 16:42:42 INFO - 'XPC_SERVICE_NAME': '0', 16:42:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:42:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:42:42 INFO - Downloading/unpacking jsonschema==2.5.1 16:42:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:42:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:42:42 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:42:42 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:42:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:42:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:42:45 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 16:42:45 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 16:42: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:42: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:42: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:42: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:42: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:42: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:42:45 INFO - Downloading functools32-3.2.3-2.tar.gz 16:42:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 16:42:45 INFO - warning: no files found matching '*.txt' 16:42:45 INFO - no previously-included directories found matching 'build' 16:42:45 INFO - no previously-included directories found matching 'dist' 16:42:45 INFO - no previously-included directories found matching '.git*' 16:42:45 INFO - Installing collected packages: jsonschema, functools32 16:42:45 INFO - Running setup.py install for functools32 16:42:45 INFO - warning: no files found matching '*.txt' 16:42:45 INFO - no previously-included directories found matching 'build' 16:42:45 INFO - no previously-included directories found matching 'dist' 16:42:45 INFO - no previously-included directories found matching '.git*' 16:42:46 INFO - Successfully installed jsonschema functools32 16:42:46 INFO - Cleaning up... 16:42:46 INFO - Return code: 0 16:42:46 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 16:42:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:42:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:42:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:42:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:42:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:42:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:42:46 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 0x1032e8490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10325e570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa2b0d742c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1030fcd30>, '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 0x102f22db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1030f4b20>, '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.5vBKHtcXHC/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.uYdZICfFhj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/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:42:46 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:42:46 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:42:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5vBKHtcXHC/Render', 16:42:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:42:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:42:46 INFO - 'HOME': '/Users/cltbld', 16:42:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:42:46 INFO - 'LOGNAME': 'cltbld', 16:42:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:42:46 INFO - 'MOZ_NO_REMOTE': '1', 16:42:46 INFO - 'NO_EM_RESTART': '1', 16:42:46 INFO - 'PAGER': '/bin/cat', 16:42:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:42:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:42:46 INFO - 'PWD': '/builds/slave/test', 16:42:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:42:46 INFO - 'SHELL': '/bin/bash', 16:42:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.uYdZICfFhj/Listeners', 16:42:46 INFO - 'TMPDIR': '/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/', 16:42:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:42:46 INFO - 'USER': 'cltbld', 16:42:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:42:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:42:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:42:46 INFO - 'XPC_FLAGS': '0x0', 16:42:46 INFO - 'XPC_SERVICE_NAME': '0', 16:42:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:42:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:42:46 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 16:42:46 INFO - Cleaning up... 16:42:46 INFO - Return code: 0 16:42:46 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 16:42:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:42:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:42:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:42:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:42:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:42:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:42:46 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 0x1032e8490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10325e570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa2b0d742c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1030fcd30>, '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 0x102f22db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1030f4b20>, '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.5vBKHtcXHC/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.uYdZICfFhj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/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:42:46 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:42:46 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:42:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5vBKHtcXHC/Render', 16:42:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:42:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:42:46 INFO - 'HOME': '/Users/cltbld', 16:42:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:42:46 INFO - 'LOGNAME': 'cltbld', 16:42:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:42:46 INFO - 'MOZ_NO_REMOTE': '1', 16:42:46 INFO - 'NO_EM_RESTART': '1', 16:42:46 INFO - 'PAGER': '/bin/cat', 16:42:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:42:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:42:46 INFO - 'PWD': '/builds/slave/test', 16:42:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:42:46 INFO - 'SHELL': '/bin/bash', 16:42:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.uYdZICfFhj/Listeners', 16:42:46 INFO - 'TMPDIR': '/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/', 16:42:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:42:46 INFO - 'USER': 'cltbld', 16:42:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:42:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:42:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:42:46 INFO - 'XPC_FLAGS': '0x0', 16:42:46 INFO - 'XPC_SERVICE_NAME': '0', 16:42:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:42:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:42:46 INFO - Downloading/unpacking blobuploader==1.2.4 16:42:46 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:42:46 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:42:46 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:42:46 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:42:46 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:42:46 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:42:49 INFO - Downloading blobuploader-1.2.4.tar.gz 16:42:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 16:42:49 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 16:42:49 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:42:49 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:42:49 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:42:49 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:42:49 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:42:49 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:42:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 16:42:49 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 16:42:49 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:42:49 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:42:49 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:42:49 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:42:49 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:42:49 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:42:50 INFO - Downloading docopt-0.6.1.tar.gz 16:42:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 16:42:50 INFO - Installing collected packages: blobuploader, requests, docopt 16:42:50 INFO - Running setup.py install for blobuploader 16:42:50 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 16:42:50 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 16:42:50 INFO - Running setup.py install for requests 16:42:51 INFO - Running setup.py install for docopt 16:42:51 INFO - Successfully installed blobuploader requests docopt 16:42:51 INFO - Cleaning up... 16:42:51 INFO - Return code: 0 16:42:51 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:42:51 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:42:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:42:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:42:51 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:42:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:42:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:42:51 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 0x1032e8490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10325e570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa2b0d742c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1030fcd30>, '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 0x102f22db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1030f4b20>, '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.5vBKHtcXHC/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.uYdZICfFhj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/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:42:51 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:42:51 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:42:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5vBKHtcXHC/Render', 16:42:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:42:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:42:51 INFO - 'HOME': '/Users/cltbld', 16:42:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:42:51 INFO - 'LOGNAME': 'cltbld', 16:42:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:42:51 INFO - 'MOZ_NO_REMOTE': '1', 16:42:51 INFO - 'NO_EM_RESTART': '1', 16:42:51 INFO - 'PAGER': '/bin/cat', 16:42:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:42:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:42:51 INFO - 'PWD': '/builds/slave/test', 16:42:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:42:51 INFO - 'SHELL': '/bin/bash', 16:42:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.uYdZICfFhj/Listeners', 16:42:51 INFO - 'TMPDIR': '/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/', 16:42:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:42:51 INFO - 'USER': 'cltbld', 16:42:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:42:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:42:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:42:51 INFO - 'XPC_FLAGS': '0x0', 16:42:51 INFO - 'XPC_SERVICE_NAME': '0', 16:42:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:42:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:42:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:42:51 INFO - Running setup.py (path:/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/pip-mSAyo2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:42:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:42:51 INFO - Running setup.py (path:/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/pip-ya94BO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:42:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:42:51 INFO - Running setup.py (path:/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/pip-RhLMEG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:42:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:42:51 INFO - Running setup.py (path:/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/pip-AVmP9Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:42:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:42:51 INFO - Running setup.py (path:/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/pip-LOS4eF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:42:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:42:52 INFO - Running setup.py (path:/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/pip-klUUXI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:42:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:42:52 INFO - Running setup.py (path:/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/pip-6CcAXz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:42:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:42:52 INFO - Running setup.py (path:/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/pip-J1MjoB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:42:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:42:52 INFO - Running setup.py (path:/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/pip-4IF1Wf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:42:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:42:52 INFO - Running setup.py (path:/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/pip-FM3clC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:42:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:42:52 INFO - Running setup.py (path:/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/pip-uJCbao-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:42:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:42:52 INFO - Running setup.py (path:/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/pip-8NTPFh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:42:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:42:52 INFO - Running setup.py (path:/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/pip-zBi5Dn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:42:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:42:52 INFO - Running setup.py (path:/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/pip-nJXbbu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:42:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:42:53 INFO - Running setup.py (path:/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/pip-3s4Yom-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:42:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:42:53 INFO - Running setup.py (path:/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/pip-6Wcdgi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:42:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:42:53 INFO - Running setup.py (path:/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/pip-ppJ3gp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:42:53 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 16:42:53 INFO - Running setup.py install for manifestparser 16:42:53 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 16:42:53 INFO - Running setup.py install for mozcrash 16:42:53 INFO - Running setup.py install for mozdebug 16:42:53 INFO - Running setup.py install for mozdevice 16:42:53 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 16:42:53 INFO - Installing dm script to /builds/slave/test/build/venv/bin 16:42:53 INFO - Running setup.py install for mozfile 16:42:54 INFO - Running setup.py install for mozhttpd 16:42:54 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 16:42:54 INFO - Running setup.py install for mozinfo 16:42:54 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 16:42:54 INFO - Running setup.py install for mozInstall 16:42:54 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 16:42:54 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 16:42:54 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 16:42:54 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 16:42:54 INFO - Running setup.py install for mozleak 16:42:54 INFO - Running setup.py install for mozlog 16:42:54 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 16:42:54 INFO - Running setup.py install for moznetwork 16:42:54 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 16:42:55 INFO - Running setup.py install for mozprocess 16:42:55 INFO - Running setup.py install for mozprofile 16:42:55 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 16:42:55 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 16:42:55 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 16:42:55 INFO - Running setup.py install for mozrunner 16:42:55 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 16:42:55 INFO - Running setup.py install for mozscreenshot 16:42:55 INFO - Running setup.py install for moztest 16:42:55 INFO - Running setup.py install for mozversion 16:42:55 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 16:42:55 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 16:42:55 INFO - Cleaning up... 16:42:55 INFO - Return code: 0 16:42:55 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:42:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:42:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:42:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:42:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:42:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:42:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:42:55 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 0x1032e8490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10325e570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa2b0d742c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1030fcd30>, '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 0x102f22db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1030f4b20>, '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.5vBKHtcXHC/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.uYdZICfFhj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/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:42:55 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:42:55 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:42:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5vBKHtcXHC/Render', 16:42:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:42:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:42:55 INFO - 'HOME': '/Users/cltbld', 16:42:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:42:55 INFO - 'LOGNAME': 'cltbld', 16:42:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:42:55 INFO - 'MOZ_NO_REMOTE': '1', 16:42:55 INFO - 'NO_EM_RESTART': '1', 16:42:55 INFO - 'PAGER': '/bin/cat', 16:42:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:42:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:42:55 INFO - 'PWD': '/builds/slave/test', 16:42:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:42:55 INFO - 'SHELL': '/bin/bash', 16:42:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.uYdZICfFhj/Listeners', 16:42:55 INFO - 'TMPDIR': '/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/', 16:42:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:42:55 INFO - 'USER': 'cltbld', 16:42:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:42:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:42:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:42:55 INFO - 'XPC_FLAGS': '0x0', 16:42:55 INFO - 'XPC_SERVICE_NAME': '0', 16:42:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:42:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:42:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:42:56 INFO - Running setup.py (path:/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/pip-s2E5hy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:42:56 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:42:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:42:56 INFO - Running setup.py (path:/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/pip-IPMM7_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:42:56 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:42:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:42:56 INFO - Running setup.py (path:/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/pip-8eq5Pv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:42:56 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:42:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:42:56 INFO - Running setup.py (path:/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/pip-_kL6cQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:42:56 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:42:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:42:56 INFO - Running setup.py (path:/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/pip-_rUYta-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:42:56 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:42:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:42:56 INFO - Running setup.py (path:/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/pip-iJQyk3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:42:56 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:42:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:42:56 INFO - Running setup.py (path:/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/pip-tUq0YL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:42:56 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:42:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:42:56 INFO - Running setup.py (path:/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/pip-F8l5kE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:42:57 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:42:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:42:57 INFO - Running setup.py (path:/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/pip-BTlMBV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:42:57 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:42:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:42:57 INFO - Running setup.py (path:/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/pip-NJ0n0H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:42:57 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:42:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:42:57 INFO - Running setup.py (path:/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/pip-KLPsvd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:42:57 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:42:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:42:57 INFO - Running setup.py (path:/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/pip-wK6GFb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:42:57 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:42:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:42:57 INFO - Running setup.py (path:/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/pip-nyjPs4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:42:57 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:42:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:42:57 INFO - Running setup.py (path:/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/pip-362K81-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:42:57 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:42:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:42:57 INFO - Running setup.py (path:/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/pip-Wx_MVR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:42:57 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:42:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:42:57 INFO - Running setup.py (path:/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/pip-Xeko6h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:42:57 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:42:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:42:57 INFO - Running setup.py (path:/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/pip-B55XVv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:42:58 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:42:58 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:42:58 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:42:58 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:42:58 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:42:58 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:42:58 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 16:42:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:42:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:42:58 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:42:58 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:42:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:42:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:43:00 INFO - Downloading blessings-1.6.tar.gz 16:43:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 16:43:00 INFO - Installing collected packages: blessings 16:43:00 INFO - Running setup.py install for blessings 16:43:00 INFO - Successfully installed blessings 16:43:00 INFO - Cleaning up... 16:43:00 INFO - Return code: 0 16:43:00 INFO - Done creating virtualenv /builds/slave/test/build/venv. 16:43:00 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:43:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:43:01 INFO - Reading from file tmpfile_stdout 16:43:01 INFO - Current package versions: 16:43:01 INFO - blessings == 1.6 16:43:01 INFO - blobuploader == 1.2.4 16:43:01 INFO - docopt == 0.6.1 16:43:01 INFO - functools32 == 3.2.3-2 16:43:01 INFO - jsonschema == 2.5.1 16:43:01 INFO - manifestparser == 1.1 16:43:01 INFO - mozInstall == 1.12 16:43:01 INFO - mozcrash == 0.17 16:43:01 INFO - mozdebug == 0.1 16:43:01 INFO - mozdevice == 0.48 16:43:01 INFO - mozfile == 1.2 16:43:01 INFO - mozhttpd == 0.7 16:43:01 INFO - mozinfo == 0.9 16:43:01 INFO - mozleak == 0.1 16:43:01 INFO - mozlog == 3.4 16:43:01 INFO - moznetwork == 0.27 16:43:01 INFO - mozprocess == 0.23 16:43:01 INFO - mozprofile == 0.28 16:43:01 INFO - mozrunner == 6.12 16:43:01 INFO - mozscreenshot == 0.1 16:43:01 INFO - mozsystemmonitor == 0.3 16:43:01 INFO - moztest == 0.7 16:43:01 INFO - mozversion == 1.4 16:43:01 INFO - psutil == 3.1.1 16:43:01 INFO - requests == 1.2.3 16:43:01 INFO - wsgiref == 0.1.2 16:43:01 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:43:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:43:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:43:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:43:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:43:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:43:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:43:02 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 0x1032e8490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10325e570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa2b0d742c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1030fcd30>, '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 0x102f22db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1030f4b20>, '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.5vBKHtcXHC/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.uYdZICfFhj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/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:43:02 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:43:02 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:43:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5vBKHtcXHC/Render', 16:43:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:43:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:43:02 INFO - 'HOME': '/Users/cltbld', 16:43:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:43:02 INFO - 'LOGNAME': 'cltbld', 16:43:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:43:02 INFO - 'MOZ_NO_REMOTE': '1', 16:43:02 INFO - 'NO_EM_RESTART': '1', 16:43:02 INFO - 'PAGER': '/bin/cat', 16:43:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:43:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:43:02 INFO - 'PWD': '/builds/slave/test', 16:43:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:43:02 INFO - 'SHELL': '/bin/bash', 16:43:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.uYdZICfFhj/Listeners', 16:43:02 INFO - 'TMPDIR': '/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/', 16:43:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:43:02 INFO - 'USER': 'cltbld', 16:43:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:43:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:43:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:43:02 INFO - 'XPC_FLAGS': '0x0', 16:43:02 INFO - 'XPC_SERVICE_NAME': '0', 16:43:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:43:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:43:02 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:43:02 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:43:02 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:43:02 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:43:02 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:43:02 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:43:02 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:43:02 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:43:02 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:43:02 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:43:02 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:43:02 INFO - Cleaning up... 16:43:02 INFO - Return code: 0 16:43:02 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 16:43:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:43:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:43:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:43:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:43:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:43:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:43:03 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 0x1032e8490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10325e570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa2b0d742c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1030fcd30>, '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 0x102f22db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1030f4b20>, '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.5vBKHtcXHC/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.uYdZICfFhj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/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:43:03 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:43:03 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:43:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5vBKHtcXHC/Render', 16:43:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:43:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:43:03 INFO - 'HOME': '/Users/cltbld', 16:43:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:43:03 INFO - 'LOGNAME': 'cltbld', 16:43:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:43:03 INFO - 'MOZ_NO_REMOTE': '1', 16:43:03 INFO - 'NO_EM_RESTART': '1', 16:43:03 INFO - 'PAGER': '/bin/cat', 16:43:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:43:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:43:03 INFO - 'PWD': '/builds/slave/test', 16:43:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:43:03 INFO - 'SHELL': '/bin/bash', 16:43:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.uYdZICfFhj/Listeners', 16:43:03 INFO - 'TMPDIR': '/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/', 16:43:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:43:03 INFO - 'USER': 'cltbld', 16:43:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:43:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:43:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:43:03 INFO - 'XPC_FLAGS': '0x0', 16:43:03 INFO - 'XPC_SERVICE_NAME': '0', 16:43:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:43:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:43:03 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 16:43:03 INFO - Cleaning up... 16:43:03 INFO - Return code: 0 16:43:03 INFO - Running post-action listener: _resource_record_post_action 16:43:03 INFO - Running post-action listener: _start_resource_monitoring 16:43:03 INFO - Starting resource monitoring. 16:43:03 INFO - [mozharness: 2016-10-18 23:43:03.393660Z] Finished create-virtualenv step (success) 16:43:03 INFO - [mozharness: 2016-10-18 23:43:03.394663Z] Running install step. 16:43:03 INFO - Running pre-action listener: _resource_record_pre_action 16:43:03 INFO - Running main action method: install 16:43:03 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:43:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:43:03 INFO - Reading from file tmpfile_stdout 16:43:03 INFO - Detecting whether we're running mozinstall >=1.0... 16:43:03 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 16:43:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 16:43:03 INFO - Reading from file tmpfile_stdout 16:43:03 INFO - Output received: 16:43:03 INFO - Usage: mozinstall [options] installer 16:43:03 INFO - Options: 16:43:03 INFO - -h, --help show this help message and exit 16:43:03 INFO - -d DEST, --destination=DEST 16:43:03 INFO - Directory to install application into. [default: 16:43:03 INFO - "/builds/slave/test"] 16:43:03 INFO - --app=APP Application being installed. [default: firefox] 16:43:03 INFO - mkdir: /builds/slave/test/build/application 16:43:03 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:43:03 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:43:38 INFO - Reading from file tmpfile_stdout 16:43:38 INFO - Output received: 16:43:38 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 16:43:38 INFO - Running post-action listener: _resource_record_post_action 16:43:38 INFO - [mozharness: 2016-10-18 23:43:38.109410Z] Finished install step (success) 16:43:38 INFO - [mozharness: 2016-10-18 23:43:38.109540Z] Running run-tests step. 16:43:38 INFO - Running pre-action listener: _resource_record_pre_action 16:43:38 INFO - Running main action method: run_tests 16:43:38 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 16:43:38 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 16:43:38 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 16:43:38 INFO - 2016-10-18 16:43:38.124 screenresolution[1928:18076] starting screenresolution argv=screenresolution get 16:43:38 INFO - 2016-10-18 16:43:38.126 screenresolution[1928:18076] Display 0: 1600x1200x32@60 16:43:38 INFO - 2016-10-18 16:43:38.133 screenresolution[1929:18078] starting screenresolution argv=screenresolution list 16:43:38 INFO - Available Modes on Display 0 16:43:38 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 16:43:38 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 16:43:38 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 16:43:38 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 16:43:38 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 16:43:38 INFO - Intel Iris: 16:43:38 INFO - Chipset Model: Intel Iris 16:43:38 INFO - Type: GPU 16:43:38 INFO - Bus: Built-In 16:43:38 INFO - VRAM (Dynamic, Max): 1536 MB 16:43:38 INFO - Vendor: Intel (0x8086) 16:43:38 INFO - Device ID: 0x0a2e 16:43:38 INFO - Revision ID: 0x0009 16:43:38 INFO - Displays: 16:43:38 INFO - Display: 16:43:38 INFO - Resolution: 1600 x 1200 @ 60 Hz 16:43:38 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 16:43:38 INFO - Display Serial Number: 1600x1200 60 16:43:38 INFO - Main Display: Yes 16:43:38 INFO - Mirror: Off 16:43:38 INFO - Online: Yes 16:43:38 INFO - Rotation: Supported 16:43:38 INFO - Return code: 0 16:43:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 16:43:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 16:43:38 INFO - Python 2.7.3 16:43:38 INFO - Return code: 0 16:43:38 INFO - grabbing minidump binary from tooltool 16:43:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:43:38 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 0x1030fcd30>, '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 0x102f22db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1030f4b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 16:43:38 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:43:38 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:43:38 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:43:38 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 16:43:38 INFO - Return code: 0 16:43:38 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 16:43:38 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 16:43:38 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 16:43:38 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 16:43:38 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 16:43:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/ZfEMSV2MR6-x5OhRQB8aUA/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0114', '--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:43:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Autoland --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/ZfEMSV2MR6-x5OhRQB8aUA/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0114 --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:43:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5vBKHtcXHC/Render', 16:43:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:43:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:43:38 INFO - 'HOME': '/Users/cltbld', 16:43:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:43:38 INFO - 'LOGNAME': 'cltbld', 16:43:38 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:43:38 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 16:43:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:43:38 INFO - 'MOZ_NO_REMOTE': '1', 16:43:38 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:43:38 INFO - 'NO_EM_RESTART': '1', 16:43:38 INFO - 'PAGER': '/bin/cat', 16:43:38 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:43:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:43:38 INFO - 'PWD': '/builds/slave/test', 16:43:38 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:43:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:43:38 INFO - 'SHELL': '/bin/bash', 16:43:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.uYdZICfFhj/Listeners', 16:43:38 INFO - 'TMPDIR': '/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/', 16:43:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:43:38 INFO - 'USER': 'cltbld', 16:43:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:43:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:43:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:43:38 INFO - 'XPC_FLAGS': '0x0', 16:43:38 INFO - 'XPC_SERVICE_NAME': '0', 16:43:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:43:38 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/ZfEMSV2MR6-x5OhRQB8aUA/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0114', '--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:43:38 INFO - mozversion application_buildid: 20161018152323 16:43:38 INFO - mozversion application_changeset: 352cd8e264990a86fb486088e0a83b20aa9a1274 16:43:38 INFO - mozversion application_display_name: Nightly 16:43:38 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 16:43:38 INFO - mozversion application_name: Firefox 16:43:38 INFO - mozversion application_remotingname: firefox 16:43:38 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland 16:43:38 INFO - mozversion application_vendor: Mozilla 16:43:38 INFO - mozversion application_version: 52.0a1 16:43:38 INFO - mozversion platform_buildid: 20161018152323 16:43:38 INFO - mozversion platform_changeset: 352cd8e264990a86fb486088e0a83b20aa9a1274 16:43:38 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland 16:43:38 INFO - mozversion platform_version: 52.0a1 16:43:38 INFO - using testdate: 1476834218 16:43:38 INFO - actual date: 1476834218 16:43:38 INFO - starting webserver on 'localhost:57270' 16:43:38 INFO - SUITE-START | Running 2 tests 16:43:38 INFO - TEST-START | dromaeo_css 16:43:38 INFO - operating with platform_type : mac_ 16:43:38 INFO - Initialising browser for dromaeo_css test... 16:43:38 INFO - TEST-INFO | started process 1939 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/tmpK_wDpf/profile http://localhost:57270/getInfo.html) 16:43:41 INFO - PROCESS | 1939 | 2016-10-18 16:43:41.423 plugin-container[1941:18236] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8f3b, name = 'com.apple.tsm.portname' 16:43:41 INFO - PROCESS | 1939 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:43:41 INFO - PROCESS | 1939 | 2016-10-18 16:43:41.426 plugin-container[1941:18236] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x910b, name = 'com.apple.CFPasteboardClient' 16:43:41 INFO - PROCESS | 1939 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:43:41 INFO - PROCESS | 1939 | 2016-10-18 16:43:41.426 plugin-container[1941:18236] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 16:43:41 INFO - PROCESS | 1939 | __metrics Screen width/height:1600/1200 16:43:41 INFO - PROCESS | 1939 | colorDepth:24 16:43:41 INFO - PROCESS | 1939 | Browser inner width/height: 1024/689 16:43:41 INFO - PROCESS | 1939 | __metrics 16:43:41 ERROR - PROCESS | 1939 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3087: TypeError: this.tabs is undefined 16:43:42 INFO - TEST-INFO | 1939: exit 0 16:43:42 INFO - Browser initialized. 16:43:42 INFO - Running cycle 1/1 for dromaeo_css test... 16:43:42 INFO - TEST-INFO | started process 1942 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/tmpK_wDpf/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:43:44 INFO - PROCESS | 1942 | 2016-10-18 16:43:44.527 plugin-container[1943:18353] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8743, name = 'com.apple.tsm.portname' 16:43:44 INFO - PROCESS | 1942 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:43:44 INFO - PROCESS | 1942 | 2016-10-18 16:43:44.530 plugin-container[1943:18353] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4707, name = 'com.apple.CFPasteboardClient' 16:43:44 INFO - PROCESS | 1942 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:43:44 INFO - PROCESS | 1942 | 2016-10-18 16:43:44.530 plugin-container[1943:18353] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 16:43:54 INFO - PROCESS | 1942 | RSS: Main: 237199360 16:43:54 INFO - PROCESS | 1942 | 16:44:43 INFO - PROCESS | 1942 | 1476834283832 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:46:57 INFO - PROCESS | 1942 | Cycle 1(1): loaded http://localhost:57270/tests/dromaeo/cssquery-dojo.html (next: http://localhost:57270/tests/dromaeo/cssquery-ext.html) 16:46:59 INFO - PROCESS | 1942 | RSS: Main: 249520128 16:46:59 INFO - PROCESS | 1942 | 16:50:01 INFO - PROCESS | 1942 | Cycle 1(1): loaded http://localhost:57270/tests/dromaeo/cssquery-ext.html (next: http://localhost:57270/tests/dromaeo/cssquery-jquery.html) 16:50:02 INFO - PROCESS | 1942 | RSS: Main: 263593984 16:50:02 INFO - PROCESS | 1942 | 16:53:04 INFO - PROCESS | 1942 | Cycle 1(1): loaded http://localhost:57270/tests/dromaeo/cssquery-jquery.html (next: http://localhost:57270/tests/dromaeo/cssquery-mootools.html) 16:53:04 INFO - PROCESS | 1942 | RSS: Main: 268279808 16:53:04 INFO - PROCESS | 1942 | 16:56:06 INFO - PROCESS | 1942 | Cycle 1(1): loaded http://localhost:57270/tests/dromaeo/cssquery-mootools.html (next: http://localhost:57270/tests/dromaeo/cssquery-prototype.html) 16:56:07 INFO - PROCESS | 1942 | RSS: Main: 268107776 16:56:07 INFO - PROCESS | 1942 | 16:59:10 INFO - PROCESS | 1942 | Cycle 1(1): loaded http://localhost:57270/tests/dromaeo/cssquery-prototype.html (next: http://localhost:57270/tests/dromaeo/cssquery-yui.html) 16:59:10 INFO - PROCESS | 1942 | RSS: Main: 273465344 16:59:10 INFO - PROCESS | 1942 | 17:02:13 INFO - PROCESS | 1942 | Cycle 1(1): loaded http://localhost:57270/tests/dromaeo/cssquery-yui.html (next: http://localhost:57270/tests/dromaeo/cssquery-dojo.html) 17:02:13 INFO - PROCESS | 1942 | RSS: Main: 276000768 17:02:13 INFO - PROCESS | 1942 | 17:02:13 INFO - PROCESS | 1942 | __start_tp_report 17:02:13 INFO - PROCESS | 1942 | _x_x_mozilla_page_load 17:02:13 INFO - PROCESS | 1942 | _x_x_mozilla_page_load_details 17:02:13 INFO - PROCESS | 1942 | |i|pagename|runs| 17:02:13 INFO - PROCESS | 1942 | |0;dojo.html;3308.6856748608875;3351.447011243145;3374.0552645259304;3381.695647391735;3384.4415671414217;5511.3937466878715;5595.132754423061;5600.439956004398;5614.747336369868;5712.885742285162;5415.458454154583;5524.8342549723575;5648.971755141229;5707.058335374669;5718.59969802114;5113.028524580336;5145.74271286436;5176.456472070435;5230.503102205284;5280.577553795687;5145.897082058356;5241.633085684004;5277.920831187535;5283.101872681634;5348.064088784467;5108.846734597931;5148.510891465305;5167.431582525919;5171.81898633546;5248.107821670325;5188.066148093341;5196.428392876781;5208.817691380784;5217.895642087175;5253.3958594761325;4719.914419683492;5109.591232701375;5157.192505892223;5158.432572417031;5246.239295302198;4445.8221671133115;5507.862303442406;5563.554915606742;5587.944120558783;5653.830385088454;6038.33578306386;6070.9982852829235;6093.534666521248;6103.580118178676;6134.8773024539705;6113.354685976905;6131.957816599981;6159.876802463926;6188.84527886802;6229.626222426669;5747.913781293242;5757.68332741699;5766.740496677659;5783;5823.2138661280605;5050.217216331474;6132.816015519542;6208.9689551551955;6210.037444196157;6215.782447614311;5920.763169473259;5978.730957106941;6022.277326720821;6040.124181993587;6067.544934129958;6094.238220222473;6168.290646575611;6178.66017369049;6179.783707570214;6198.256209254918;5967.19442875215;6054.424829641177;6109.266887973383;6113.141862489121;6118;6147.897245286402;6166.136740856284;6168.982117950578;6192.504599632019;6220.222472190976;4756.571908528249;4801.279808028754;4818.68678535894;4833.468318484964;4881.609471242292;4366.519682834885;4384.101259241847;4397.912041759147;4417.757023363746;4442.200403927324;4861.687344416988;4894.388201474816;5298.655587386807;5697.401772813795;5806.65160090396;6083;6115.088229002436;6127.678242056309;6143.416375444325;6178.196834411498;4949.6287778416045;5590.8043218487155;5750;5775.047117225695;5819.563532735062;4453.755043472575;5354.518093371616;5359.705216213146;5377;5430.0768869292315;6111.53096535531;6137.048757442603;6151.876962460637;6202.038684003986;6212;6072.362401947732;6110.315755985069;6111.622125543816;6116.761878222105;6137.232845894263;6217.875642487215;6218.875622487615;6256.343083976117;6259.467945224706;6289.968550157403;6013.30846952606;6053.303870055;6129.2644882612585;6143.447089761943;6145.477634401126;6084.60450070744;6091;6119.296280927751;6130.306415576139;6145.432914606747;5983.970080149571;6012.008018676869;6029.216201893726;6034.396560344106;6065.332813390612;5893.6463812171405;5948.434898684618;5995.160677505066;6007.969960150172;6070.089486577049;5888.352281249145;5919.378465261258;5947.91078133837;5948.672822994604;5959.91060134107;4715.669903106749;6043.8186854394435;6073.48375388097;6081.939180608138;6114.0828875669;5731.226284451545;5768.53851691872;5811.94188058114;5914.497267732291;5953.523718102628 17:02:13 INFO - PROCESS | 1942 | |1;ext.html;488.3968299150551;489.8897748006698;499.7151623574564;501.69145975225234;512.2035235209253;39812.800935995314;41131;41250.17499650009;43469.34795978063;43708.3443748344;42662.7866860666;42968.35547466783;43054;43235.78382108093;43265.35101973473;9465.242780577555;9575.760605984871;9626.614935402575;9655.08276713711;9685.079917407871;8371.790705232357;9586.808263834719;9608.375455595398;9658.227341812639;9689.951550242238;9613.182879455255;9642.084002019797;9642.517874106301;9681.75795605112;9738.9513052435;9607.231421486264;9620.759481012961;9648.986856380085;9655.517224138765;9663.565139568736;9471.857922131174;9494.667686630935;9499.477528735917;9531.475768832712;9555.665551705657;40460.39309410361;40667.59332406668;40874.591254087376;40948.79525602383;40969.59030409688;3356.194513316811;3365.8317084145697;3413.163774875147;3553.502509648651;3726.31063253299;30508;30624.846875765477;30627.846860765778;30649.846750766104;31708.524372134205;2932.8240305581735;3178.96821031789;3191.5850939377965;3232.9838350808095;3282.1302354875975;422430;457256;481669;486370;489702.9633666458;57959;58277.70861145667;58482.707586461795;58530;58586;59857.40142598518;59945.40054599398;60147.69926150341;60240.698796505734;60267;15552.377904883904;15711.135887526067;15726.528204153894;15733.44932927342;15777;10662.680119596425;12092.879071209176;12175.75648487043;12240.571579994836;12295.938520307342;15204.46784362542;15799.36802527909;15811.367545298059;15824.496672816063;15835.841641583667;5628.634138781048;6294.9685251574365;6380.968095159495;6469.935300646934;6471.870562588721;842.5113434208145;852.6461518469889;959.6353385713384;969.8652576485529;1043.5877828257796;27401.86299068492;28147.859260703564;28220.71779282222;28517.429651406856;29332.560011599842;894.2711689972651;975.1954637424153;1020.1634659579222;1048.1509976918721;1134.489479734123;14314.427422903176;15103.640672339543;15210.619734506505;15224.619384515476;15533.834962377867;632487;639549.6045039583;643640;643890;647084;54909.725451373284;55132.44867551354;55237.44762552404;55324.44675553193;55472.445275547536;1333.0468714868916;1349.2916218984915;1366.4670778396433;1379.0966916669597;1388.3891087921281;26751.197464076107;28391.858040709663;28617.998370056946;28854.42291154207;29261.268468288465;48798;48924.2661360087;48925.75537122291;48998.75500622474;49105;534034;537823;538363;538496;538511;25063.248102556954;25223.864926078004;25424.237272881845;25448.982040718165;25674.358141046625;46157;46602.83492912704;46826.53173468222;47015.76492117518;47030;30569.847150764104;34958.82520587381;36779.81610091932;37627.2474550502;39129.608703913735;37646.43530346992;38116.428253575665;38753.612463876125;38892.6110738889;39322.6067739319;36490.722824701006;36902;37559.248815023;37728;37869;32527;33127.66872331246;33565.496517551765;34196.31607367789;34349;23085.422864428525;25178.370540735894;25405.491890162462;25645.71771411459;25741.742582574683 17:02:13 INFO - PROCESS | 1942 | |2;jquery.html;327.81806097615816;372.8061408067805;373.4715377740499;377.9603141670125;400.40139990713885;19106.235750569977;19558.51103722406;20788.896055519737;21042.789572104273;21075.683864742005;14095.577132686036;17158.970461772304;17916.64166716665;20397.59204815903;20909;5231.555317797992;5704.971475142618;5921.555883308748;6014.789482368118;6018.608790428608;5643.746031428575;5939.910901336483;6015.127806468059;6035.064564992434;6052.303985041711;5891.5286777057945;6046.455818976291;6052.03167493199;6062.060380641008;6549.279579246279;6614.834629134262;6658.067870498134;6681.8997715034075;6687.866242675143;6692.83267918301;5052.469490703496;5340.732963351856;5392.299001129867;5454.945450545483;5892.558058145656;13009.089363744542;13094.952403807672;13185.208887466686;13223.140495867834;13285.202887826665;2064.2671851492655;2133.2426988419197;2327.9695320133274;2340.414896275931;2382.285314405671;9654.469004204766;9714.562844671937;9805.656802011896;10116.94941525295;10633.415162166079;1818.254515648591;1832.3769918227733;1908.7518622579114;1925.9614807703908;1934.351992082654;113858;120096.3995180036;120160.39919800432;120733.39633301778;120837.39581302126;10974.134397152504;11013.394263315435;11548.76902461946;11582.6525204244;11745.412729363497;14294.714105718036;14479;14498.275086245645;14520.927395363167;14531.346089426;7302.306280903305;8016.799580010546;8099.109097999215;8369.748907532783;8478.74563763088;6858.485613579001;8196.918030819736;8315.708950186714;8337.874931876027;8353.20644538768;3823.751456155341;3859.6912247020173;4505.842295519641;4539;4592.609628181575;1920.2511020701938;1950.2004178286834;1987.4236471423126;1988.7016947457996;1999.4001799460395;842.2461896602506;873.2097451806126;883.2227639677043;996.5266498413341;1019.1184625299063;16797.076160811273;21224.469388265108;21459.78540214578;21658.458538536663;21683.457913552287;902.3412908576776;927.248928368024;929.3355250995571;945.6973768394048;978.3102912446708;11139.610113646146;11416.543338266376;11417.657470275904;11493.367864767351;11503.022243109262;538925;568284;572194;584980.0750996303;585139;65765.35586610372;106311.93688063175;117783;122014.77985220212;122216;834.4576025583298;959.3284700709531;970.5826494607387;996.7009897030862;1006.8489726540931;18797.812021880152;19550.120244588743;19586.902065489583;19603;19613.411597651524;52299.21551176659;53608;53689.19466207932;53910.46089539211;54060.72969635126;76262;76850.61574692091;77014.614926925;78114.60942695478;78163;6837.965810171117;7890.171531989058;7905.960470197612;8112.067112282163;8363.038250601016;7807.219278072374;12040.337781421755;12236.877631223573;12628;12753.426095825525;10815.944970455588;11057.170712196777;11129.332240065622;11158.442077896236;11201.983642944022;10018.248631352531;10080.24398170155;10316.845247321446;11049.83425248638;11437.714057148305;11285.266457680225;11385.886141138482;11594.362310073027;11664.475098620413;11773.587924422947;8018.198180181935;8095.5142691438705;8132.593370331341;8257.133001034805;8261.297789687944;8314.41799074059;8861.778455538662;11001.944990275317;11173;11315.886841131813 17:02:13 INFO - PROCESS | 1942 | |3;mootools.html;1545.6599548099418;1595.696817604655;1748.7202047672374;1841.521204486833;1892.7728672559294;8061.596920153998;8353.874691879626;8766.868496972551;9147.954260228706;9157.450552966826;8589.570521473917;8885.68900088497;8901.554922253878;9027.95486022569;9087.546362482142;2381.690380250565;2424.284835973392;2427.3446169534304;2431.927042188728;2436.7319594844553;2360.9409764755846;2390.713114426271;2412.445137618349;2420.782129608335;2430.1008626808016;2405.037984806083;2414.7343792182846;2421.9152329668464;2438.0735320578156;2449.9955018442442;2399.3281881073326;2399.6040653292184;2406.2420337593603;2406.6750988616577;2408.277516744978;2359.7640235976437;2366.1008816649737;2369.07606033647;2369.7867191952637;2374.620060790265;8949.731508054703;9010.549472526347;9050.094990500964;9063.410878292956;9081.45511269326;4579.267317229227;5451.155070964007;5477.3427188737205;5493.533049690781;5525.309336332958;3499.9650003500146;3641.3263546243766;3685.299793039314;3703.092742278132;3719.107414220595;1052.8631277933896;1083.8494521200528;1085.4789700943513;1091.7052395853075;1106.750981029262;9467.147956683932;9598.896126945421;11004.119670426428;11452;11902.523899044028;4716.245400735866;4729.18528502573;4789.808407663654;4903.485134060944;4919.877003074952;10008.049235322633;10056;10135.290529663;10154.76595149066;10176.185905127571;4743.525647435298;5408.540274076748;5456.0451920914475;5480.972595137078;5483.424240454775;4461.420015397977;4615.930761038587;4824.276358546176;4941.72820494869;4999.950000499948;3915.6280153385383;3964.147708242742;4058.4723985882238;4078.8368465261055;4109.486314210723;1223.1437993404475;1287.6458973782285;1288.67138879587;1298.539018648385;1313.3892739876003;847.0555330806106;893.2541327991071;912.4160537256162;915.3958387464241;947.7062110745691;5724.3130824300515;5836.416358364129;5993.790217342372;6005.309389420186;6154.476869466056;421.42054674822117;424.60511724096267;426.5883422497243;446.557907671403;454.2867697714622;3239.6598357172156;3852.499175107218;4062.5937406259727;4063.4717486726536;4096.959030409717;10551.050405463546;10811.6215932442;10938.56245750177;10944.507497162647;10963.177761667906;10623.575056997788;10744.838827417596;10882.347059176474;10885.83671244932;10928.071113955402;7528.774136775905;8341.290990265896;8345.66617335312;8353.874691879626;8368.330533557422;5107.387113546398;5704.286964129484;5858.384869588632;5896.909071821727;5925.200097986889;10073.345232559861;10119.443430611387;10158.847617285599;10214.693559193236;10248.948755256177;8935.865962010444;9989.60041598328;10106.545205465625;10285.53715082838;10292;6951;6951.165860096618;7155.8568828624175;7414.258574142543;7707.11368192665;8299.460535065202;8363.62363693623;8380.58097095155;8389.454685445426;8391.874121888055;6943.166819981635;7523.360514356122;7639.2809389392805;7671.156172821097;7825.5304681717;6408.9038664421005;6469.870602587827;7182.60495672743;7274.672639731119;7669.884951725617;6216.533759968111;6998.370146686619;7113.2175460698245;7154.892676609959;7186.820329491596;4762.380890484214;4770.928436073392;5045.369328833895;5113.616478764182;5118.8976220476125;5016.698998060128;5059.342285502861;5097.413797413196;5144.202648589475;5154.974225128851 17:02:13 INFO - PROCESS | 1942 | |4;prototype.html;261.6990460969884;289.5627602320496;312.0638085742771;318.9202699325169;319.4920077077446;4394;4857.757112144387;5416.756245968931;5626;5735.741891614877;4828.275758636207;4868.683535570194;5554.666719996793;5555.388907220213;5559.610827242094;4251.6173544381;4256.977935480645;4307.483102027745;4313.705888233533;4319.2441322768545;3862.7102967277424;3973.3180103430213;4081.2041651877894;4237.364395340693;4341.026396918105;4195.790210489478;4228.281192197333;4233.026403927099;4236.237477254093;4270.665274746135;3594.975432001867;4171.144915292388;4220.514640816299;4226.323788193889;4257.723247988902;3179.205198700325;3365.737848306885;3378.4256676365076;3650.7079433645245;3652.9634703652887;4390.8024138913825;4639.489656137822;5097.8215762448135;5385.973070134663;5386.3536375634785;4402.811240964954;4663.766811659403;4849.369581954358;4856.441509226449;4870.878228044327;5726.578294774393;5926.97036514819;5939.613925094855;5954.7915822946425;5966.67183304918;3449.9477659313898;3875.748076375055;3907.987386495957;3973.7613844716752;3988.541317748467;5949.613275137102;6213.650444688736;6250.4060814761815;6338.144350512714;6378.93621063788;3363.814990175515;3493.982530087384;4042.7265411395906;4057.594240575978;4058.492688413948;3768.586779957516;3794.9430758538642;4082.5708703321475;4105.281575724296;4114.177164567099;3706.647868452547;3809.657130858236;4254.744715317091;4839.2983017462275;4841.564259216688;4796.760161991887;5444.047291723932;5644.520215781705;5667.319921609434;5973.058755904349;1307.5946456598485;1344.9932750336186;1354.959351219465;1389.6039628705973;1407.9155250684787;864.9135086491301;921.5852866210232;924.4360939826701;936.4521754773398;939.7650587353162;5540.418256083134;5545.77816887328;5549.528290095387;5582.581306401955;5584.413636568183;5921.0460548019055;6052;6086;6118.755249789959;6168.321484636686;4829.73436460998;5117.692938423706;5515.731381782212;5535.197396377503;5588.161775733673;2387.3434805428647;2444.9511009779703;2684.852333121697;2723.6867760207433;2724.577690457982;4736.905261894743;5245.6852588844795;5267;5282.630215884851;5288.127458969304;4522.237075759671;4757.575454827044;4783.0389540288315;5080.078954601046;5135.948640513622;4404.537523560044;4855.5387238212315;4897.057868415817;5326.014687282865;5328.280682107866;4992.700437973734;5097.439281679087;5116.872078198075;5126.666766660155;5137.640365174551;4634.119517291704;4934.5312195341385;5038.874028149326;5135.716070982255;6041.395860414099;5233.424323324507;5898.9115163273445;6160.014397696347;6236.160332701773;6500.5449618526245;3361.798292102482;3469.462233353834;3738.7009039277336;3743.644353786386;4063.8577649782114;4987.2768448574025;5189.287535113479;5483.259759932358;5497.312835895513;6235.844103897439;5872.856407411349;5921.993261145702;6002.158618484261;6004.309504406874;6052.576319657582;5270.762815673381;6009.536090202996;6034.24571928509;6040.426159514839;6045.78839740607;5595.440455954372;5822.417758224313;5868.882622347443;5870.853228669317;5913.497352725067;5197.402298735694;5967.522598192048;6069.117169344526;6087.726052327745;6089.33017368098;4635.374224479787;5861.9413805861395;5880.529557635294;5907.586468947133;5945.751392207512 17:02:13 INFO - PROCESS | 1942 | |5;yui.html;703.3283214530124;704.4540481127127;732.8607564562733;734.8162959260185;830.3523251863546;15136.470223542177;16352.836471635295;16362.345506179754;16395.67208655826;16396.75404868928;14574.344154513046;14599.562013139595;14708.97037207396;14723.631909202275;14770.556883293519;860.1871231686059;878.5343767803074;880.1110878013221;880.7049638371121;884.0628933330657;691.4399336537396;715.6028404235647;875.181705105725;878.556329053827;881.2597418168737;888.1163242573648;919.1681528216974;936.2743873498059;936.9344145909789;942.8161508505844;916.6974898283551;918.1690570034096;930.4138392812484;932.664240873285;933.3886304470583;667.3293340193123;678.3216783216783;678.3386198456515;692.1071817355605;693.8889777635554;14927.179005154821;14976.326065326977;14976.326065327086;14979.325930333162;15006.849931500654;11910.642680719593;13574.796378054338;13619.387127579184;13759.931200343934;13807;12411.75176496474;12492.125551211418;12552.309622970733;12641.49434022638;12944.893225625876;4449.332600109977;4450.577195166454;4453.22068637987;4535.433070866142;4681.157391669498;117775.4111229447;117984;118304;119991.40004299923;122138;10972.835407468894;10973.396463194442;11049.779004420026;11155;11436.970672639502;21582.136714531556;21583.784162158492;21584.676229856566;21590.568188636455;21654;7590.8102297443;7699.730509432143;7745.302922736868;7764.340031097307;7917.3270272026375;4200.894977625584;4211.241976444208;4265.125649241898;4280.978595107004;4469.307257375112;3374.79751214923;3474.409350410436;3475.6350583188905;3481.5473988381855;3744.737868349244;616.442119881508;698.2563569798149;702.67676868641;711.5623891306913;728.8943103249999;533.2188344075947;563.1777604697188;602.5631417222567;609.2597494044757;626.4143026270451;9479;9564.18704410119;9614.260520281101;9634.277429192838;9765.609375624896;614.6772944204239;645.2643985856128;671.466629693089;679.4123083532709;686.8591938652664;4561.612262957686;4776.47458779534;4778.522147785194;4820.927686084711;4836.540528649773;152955;153536.46463535444;158868;161055;163082;138183;138325.999999998;139232;139588;143240.28379858035;71422;73532.63233683797;78198.6090069546;111867.44066279616;113734.29398559028;9017.504037278013;9020.323475739477;9039.81920361576;9086.954565227396;9184.908150918673;62348;62621.68689156525;64192.67903660452;65624.6718766403;65726.67136664285;96659;100010.4999474998;104072.47963760435;106027.40958885777;107281;11601.76796464049;11702.4733886977;11766.647000589996;11880.881191187977;12053.758924821279;9844.490057601672;11182.720431989266;11495.885041149482;12945.28800915921;13345.266010369134;10168.440735759308;12217.144799863925;12272.386380680751;12291;12664.430100645677;12036.879631203576;12326.938365308117;12402.13185077036;12527.749445010866;12712.6821829455;11934.641960741192;12039.939800301292;12257.080718945936;12330.506779729069;12551.184173028723;10332.330789689304;10511.316764410289;10584.89415105839;10750.569977200825;11089.5564177432;8421.789455263668;9304.209142222717;10599.947000264949;10730.356178629307;10922.235443518877 17:02:13 INFO - PROCESS | 1942 | __end_tp_report 17:02:13 INFO - PROCESS | 1942 | __start_cc_report 17:02:13 INFO - PROCESS | 1942 | _x_x_mozilla_cycle_collect,363 17:02:13 INFO - PROCESS | 1942 | __end_cc_report 17:02:13 INFO - PROCESS | 1942 | __startTimestamp1476835333699__endTimestamp 17:02:13 INFO - PROCESS | 1942 | 17:02:13 INFO - PROCESS | 1942 | ------- Summary: start ------- 17:02:13 INFO - PROCESS | 1942 | Number of tests: 6 17:02:13 INFO - PROCESS | 1942 | 17:02:13 INFO - PROCESS | 1942 | [#0] dojo.html Cycles:165 Average:5636.12 Median:5914.50 stddev:641.94 (10.9%) stddev-sans-first:617.40 17:02:13 INFO - PROCESS | 1942 | Values: 3308.7 3351.4 3374.1 3381.7 3384.4 5511.4 5595.1 5600.4 5614.7 5712.9 5415.5 5524.8 5649.0 5707.1 5718.6 5113.0 5145.7 5176.5 5230.5 5280.6 5145.9 5241.6 5277.9 5283.1 5348.1 5108.8 5148.5 5167.4 5171.8 5248.1 5188.1 5196.4 5208.8 5217.9 5253.4 4719.9 5109.6 5157.2 5158.4 5246.2 4445.8 5507.9 5563.6 5587.9 5653.8 6038.3 6071.0 6093.5 6103.6 6134.9 6113.4 6132.0 6159.9 6188.8 6229.6 5747.9 5757.7 5766.7 5783.0 5823.2 5050.2 6132.8 6209.0 6210.0 6215.8 5920.8 5978.7 6022.3 6040.1 6067.5 6094.2 6168.3 6178.7 6179.8 6198.3 5967.2 6054.4 6109.3 6113.1 6118.0 6147.9 6166.1 6169.0 6192.5 6220.2 4756.6 4801.3 4818.7 4833.5 4881.6 4366.5 4384.1 4397.9 4417.8 4442.2 4861.7 4894.4 5298.7 5697.4 5806.7 6083.0 6115.1 6127.7 6143.4 6178.2 4949.6 5590.8 5750.0 5775.0 5819.6 4453.8 5354.5 5359.7 5377.0 5430.1 6111.5 6137.0 6151.9 6202.0 6212.0 6072.4 6110.3 6111.6 6116.8 6137.2 6217.9 6218.9 6256.3 6259.5 6290.0 6013.3 6053.3 6129.3 6143.4 6145.5 6084.6 6091.0 6119.3 6130.3 6145.4 5984.0 6012.0 6029.2 6034.4 6065.3 5893.6 5948.4 5995.2 6008.0 6070.1 5888.4 5919.4 5947.9 5948.7 5959.9 4715.7 6043.8 6073.5 6081.9 6114.1 5731.2 5768.5 5811.9 5914.5 5953.5 17:02:13 INFO - PROCESS | 1942 | 17:02:13 INFO - PROCESS | 1942 | [#1] ext.html Cycles:180 Average:67980.60 Median:41190.59 stddev:147929.69 (359.1%) stddev-sans-first:148257.88 17:02:13 INFO - PROCESS | 1942 | Values: 488.4 489.9 499.7 501.7 512.2 39812.8 41131.0 41250.2 43469.3 43708.3 42662.8 42968.4 43054.0 43235.8 43265.4 9465.2 9575.8 9626.6 9655.1 9685.1 8371.8 9586.8 9608.4 9658.2 9690.0 9613.2 9642.1 9642.5 9681.8 9739.0 9607.2 9620.8 9649.0 9655.5 9663.6 9471.9 9494.7 9499.5 9531.5 9555.7 40460.4 40667.6 40874.6 40948.8 40969.6 3356.2 3365.8 3413.2 3553.5 3726.3 30508.0 30624.8 30627.8 30649.8 31708.5 2932.8 3179.0 3191.6 3233.0 3282.1 422430.0 457256.0 481669.0 486370.0 489703.0 57959.0 58277.7 58482.7 58530.0 58586.0 59857.4 59945.4 60147.7 60240.7 60267.0 15552.4 15711.1 15726.5 15733.4 15777.0 10662.7 12092.9 12175.8 12240.6 12295.9 15204.5 15799.4 15811.4 15824.5 15835.8 5628.6 6295.0 6381.0 6469.9 6471.9 842.5 852.6 959.6 969.9 1043.6 27401.9 28147.9 28220.7 28517.4 29332.6 894.3 975.2 1020.2 1048.2 1134.5 14314.4 15103.6 15210.6 15224.6 15533.8 632487.0 639549.6 643640.0 643890.0 647084.0 54909.7 55132.4 55237.4 55324.4 55472.4 1333.0 1349.3 1366.5 1379.1 1388.4 26751.2 28391.9 28618.0 28854.4 29261.3 48798.0 48924.3 48925.8 48998.8 49105.0 534034.0 537823.0 538363.0 538496.0 538511.0 25063.2 25223.9 25424.2 25449.0 25674.4 46157.0 46602.8 46826.5 47015.8 47030.0 30569.8 34958.8 36779.8 37627.2 39129.6 37646.4 38116.4 38753.6 38892.6 39322.6 36490.7 36902.0 37559.2 37728.0 37869.0 32527.0 33127.7 33565.5 34196.3 34349.0 23085.4 25178.4 25405.5 25645.7 25741.7 17:02:13 INFO - PROCESS | 1942 | 17:02:13 INFO - PROCESS | 1942 | [#2] jquery.html Cycles:180 Average:33216.01 Median:350.31 stddev:95078.90 (27141.2%) stddev-sans-first:95313.55 17:02:13 INFO - PROCESS | 1942 | Values: 327.8 372.8 373.5 378.0 400.4 19106.2 19558.5 20788.9 21042.8 21075.7 14095.6 17159.0 17916.6 20397.6 20909.0 5231.6 5705.0 5921.6 6014.8 6018.6 5643.7 5939.9 6015.1 6035.1 6052.3 5891.5 6046.5 6052.0 6062.1 6549.3 6614.8 6658.1 6681.9 6687.9 6692.8 5052.5 5340.7 5392.3 5454.9 5892.6 13009.1 13095.0 13185.2 13223.1 13285.2 2064.3 2133.2 2328.0 2340.4 2382.3 9654.5 9714.6 9805.7 10116.9 10633.4 1818.3 1832.4 1908.8 1926.0 1934.4 113858.0 120096.4 120160.4 120733.4 120837.4 10974.1 11013.4 11548.8 11582.7 11745.4 14294.7 14479.0 14498.3 14520.9 14531.3 7302.3 8016.8 8099.1 8369.7 8478.7 6858.5 8196.9 8315.7 8337.9 8353.2 3823.8 3859.7 4505.8 4539.0 4592.6 1920.3 1950.2 1987.4 1988.7 1999.4 842.2 873.2 883.2 996.5 1019.1 16797.1 21224.5 21459.8 21658.5 21683.5 902.3 927.2 929.3 945.7 978.3 11139.6 11416.5 11417.7 11493.4 11503.0 538925.0 568284.0 572194.0 584980.1 585139.0 65765.4 106311.9 117783.0 122014.8 122216.0 834.5 959.3 970.6 996.7 1006.8 18797.8 19550.1 19586.9 19603.0 19613.4 52299.2 53608.0 53689.2 53910.5 54060.7 76262.0 76850.6 77014.6 78114.6 78163.0 6838.0 7890.2 7906.0 8112.1 8363.0 7807.2 12040.3 12236.9 12628.0 12753.4 10815.9 11057.2 11129.3 11158.4 11202.0 10018.2 10080.2 10316.8 11049.8 11437.7 11285.3 11385.9 11594.4 11664.5 11773.6 8018.2 8095.5 8132.6 8257.1 8261.3 8314.4 8861.8 11001.9 11173.0 11315.9 17:02:13 INFO - PROCESS | 1942 | 17:02:13 INFO - PROCESS | 1942 | [#3] mootools.html Cycles:180 Average:5702.46 Median:4736.36 stddev:3191.95 (67.4%) stddev-sans-first:3185.62 17:02:13 INFO - PROCESS | 1942 | Values: 1545.7 1595.7 1748.7 1841.5 1892.8 8061.6 8353.9 8766.9 9148.0 9157.5 8589.6 8885.7 8901.6 9028.0 9087.5 2381.7 2424.3 2427.3 2431.9 2436.7 2360.9 2390.7 2412.4 2420.8 2430.1 2405.0 2414.7 2421.9 2438.1 2450.0 2399.3 2399.6 2406.2 2406.7 2408.3 2359.8 2366.1 2369.1 2369.8 2374.6 8949.7 9010.5 9050.1 9063.4 9081.5 4579.3 5451.2 5477.3 5493.5 5525.3 3500.0 3641.3 3685.3 3703.1 3719.1 1052.9 1083.8 1085.5 1091.7 1106.8 9467.1 9598.9 11004.1 11452.0 11902.5 4716.2 4729.2 4789.8 4903.5 4919.9 10008.0 10056.0 10135.3 10154.8 10176.2 4743.5 5408.5 5456.0 5481.0 5483.4 4461.4 4615.9 4824.3 4941.7 5000.0 3915.6 3964.1 4058.5 4078.8 4109.5 1223.1 1287.6 1288.7 1298.5 1313.4 847.1 893.3 912.4 915.4 947.7 5724.3 5836.4 5993.8 6005.3 6154.5 421.4 424.6 426.6 446.6 454.3 3239.7 3852.5 4062.6 4063.5 4097.0 10551.1 10811.6 10938.6 10944.5 10963.2 10623.6 10744.8 10882.3 10885.8 10928.1 7528.8 8341.3 8345.7 8353.9 8368.3 5107.4 5704.3 5858.4 5896.9 5925.2 10073.3 10119.4 10158.8 10214.7 10248.9 8935.9 9989.6 10106.5 10285.5 10292.0 6951.0 6951.2 7155.9 7414.3 7707.1 8299.5 8363.6 8380.6 8389.5 8391.9 6943.2 7523.4 7639.3 7671.2 7825.5 6408.9 6469.9 7182.6 7274.7 7669.9 6216.5 6998.4 7113.2 7154.9 7186.8 4762.4 4770.9 5045.4 5113.6 5118.9 5016.7 5059.3 5097.4 5144.2 5155.0 17:02:13 INFO - PROCESS | 1942 | 17:02:13 INFO - PROCESS | 1942 | [#4] prototype.html Cycles:180 Average:4599.87 Median:5059.48 stddev:1449.64 (28.7%) stddev-sans-first:1416.67 17:02:13 INFO - PROCESS | 1942 | Values: 261.7 289.6 312.1 318.9 319.5 4394.0 4857.8 5416.8 5626.0 5735.7 4828.3 4868.7 5554.7 5555.4 5559.6 4251.6 4257.0 4307.5 4313.7 4319.2 3862.7 3973.3 4081.2 4237.4 4341.0 4195.8 4228.3 4233.0 4236.2 4270.7 3595.0 4171.1 4220.5 4226.3 4257.7 3179.2 3365.7 3378.4 3650.7 3653.0 4390.8 4639.5 5097.8 5386.0 5386.4 4402.8 4663.8 4849.4 4856.4 4870.9 5726.6 5927.0 5939.6 5954.8 5966.7 3449.9 3875.7 3908.0 3973.8 3988.5 5949.6 6213.7 6250.4 6338.1 6378.9 3363.8 3494.0 4042.7 4057.6 4058.5 3768.6 3794.9 4082.6 4105.3 4114.2 3706.6 3809.7 4254.7 4839.3 4841.6 4796.8 5444.0 5644.5 5667.3 5973.1 1307.6 1345.0 1355.0 1389.6 1407.9 864.9 921.6 924.4 936.5 939.8 5540.4 5545.8 5549.5 5582.6 5584.4 5921.0 6052.0 6086.0 6118.8 6168.3 4829.7 5117.7 5515.7 5535.2 5588.2 2387.3 2445.0 2684.9 2723.7 2724.6 4736.9 5245.7 5267.0 5282.6 5288.1 4522.2 4757.6 4783.0 5080.1 5135.9 4404.5 4855.5 4897.1 5326.0 5328.3 4992.7 5097.4 5116.9 5126.7 5137.6 4634.1 4934.5 5038.9 5135.7 6041.4 5233.4 5898.9 6160.0 6236.2 6500.5 3361.8 3469.5 3738.7 3743.6 4063.9 4987.3 5189.3 5483.3 5497.3 6235.8 5872.9 5922.0 6002.2 6004.3 6052.6 5270.8 6009.5 6034.2 6040.4 6045.8 5595.4 5822.4 5868.9 5870.9 5913.5 5197.4 5967.5 6069.1 6087.7 6089.3 4635.4 5861.9 5880.5 5907.6 5945.8 17:02:13 INFO - PROCESS | 1942 | 17:02:13 INFO - PROCESS | 1942 | [#5] yui.html Cycles:180 Average:25252.75 Median:3478.59 stddev:41523.85 (1193.7%) stddev-sans-first:41599.42 17:02:13 INFO - PROCESS | 1942 | Values: 703.3 704.5 732.9 734.8 830.4 15136.5 16352.8 16362.3 16395.7 16396.8 14574.3 14599.6 14709.0 14723.6 14770.6 860.2 878.5 880.1 880.7 884.1 691.4 715.6 875.2 878.6 881.3 888.1 919.2 936.3 936.9 942.8 916.7 918.2 930.4 932.7 933.4 667.3 678.3 678.3 692.1 693.9 14927.2 14976.3 14976.3 14979.3 15006.8 11910.6 13574.8 13619.4 13759.9 13807.0 12411.8 12492.1 12552.3 12641.5 12944.9 4449.3 4450.6 4453.2 4535.4 4681.2 117775.4 117984.0 118304.0 119991.4 122138.0 10972.8 10973.4 11049.8 11155.0 11437.0 21582.1 21583.8 21584.7 21590.6 21654.0 7590.8 7699.7 7745.3 7764.3 7917.3 4200.9 4211.2 4265.1 4281.0 4469.3 3374.8 3474.4 3475.6 3481.5 3744.7 616.4 698.3 702.7 711.6 728.9 533.2 563.2 602.6 609.3 626.4 9479.0 9564.2 9614.3 9634.3 9765.6 614.7 645.3 671.5 679.4 686.9 4561.6 4776.5 4778.5 4820.9 4836.5 152955.0 153536.5 158868.0 161055.0 163082.0 138183.0 138326.0 139232.0 139588.0 143240.3 71422.0 73532.6 78198.6 111867.4 113734.3 9017.5 9020.3 9039.8 9087.0 9184.9 62348.0 62621.7 64192.7 65624.7 65726.7 96659.0 100010.5 104072.5 106027.4 107281.0 11601.8 11702.5 11766.6 11880.9 12053.8 9844.5 11182.7 11495.9 12945.3 13345.3 10168.4 12217.1 12272.4 12291.0 12664.4 12036.9 12326.9 12402.1 12527.7 12712.7 11934.6 12039.9 12257.1 12330.5 12551.2 10332.3 10511.3 10584.9 10750.6 11089.6 8421.8 9304.2 10599.9 10730.4 10922.2 17:02:13 INFO - PROCESS | 1942 | -------- Summary: end -------- 17:02:13 INFO - PROCESS | 1942 | 17:02:14 INFO - TEST-INFO | 1942: exit 0 17:02:14 INFO - TEST-OK | dromaeo_css | took 1115615ms 17:02:14 INFO - TEST-START | kraken 17:02:14 INFO - operating with platform_type : mac_ 17:02:14 INFO - Initialising browser for kraken test... 17:02:14 INFO - TEST-INFO | started process 1947 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/tmpuXFTsj/profile http://localhost:57270/getInfo.html) 17:02:17 INFO - PROCESS | 1947 | 2016-10-18 17:02:17.129 plugin-container[1948:20236] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8843, name = 'com.apple.tsm.portname' 17:02:17 INFO - PROCESS | 1947 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:02:17 INFO - PROCESS | 1947 | 2016-10-18 17:02:17.132 plugin-container[1948:20236] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8953, name = 'com.apple.CFPasteboardClient' 17:02:17 INFO - PROCESS | 1947 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:02:17 INFO - PROCESS | 1947 | 2016-10-18 17:02:17.132 plugin-container[1948:20236] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 17:02:17 INFO - PROCESS | 1947 | __metrics Screen width/height:1600/1200 17:02:17 INFO - PROCESS | 1947 | colorDepth:24 17:02:17 INFO - PROCESS | 1947 | Browser inner width/height: 1024/689 17:02:17 INFO - PROCESS | 1947 | __metrics 17:02:17 ERROR - PROCESS | 1947 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3087: TypeError: this.tabs is undefined 17:02:17 INFO - TEST-INFO | 1947: exit 0 17:02:17 INFO - Browser initialized. 17:02:17 INFO - Running cycle 1/1 for kraken test... 17:02:17 INFO - TEST-INFO | started process 1949 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/tmpuXFTsj/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 17:02:20 INFO - PROCESS | 1949 | 2016-10-18 17:02:20.511 plugin-container[1950:20349] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x443b, name = 'com.apple.tsm.portname' 17:02:20 INFO - PROCESS | 1949 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:02:20 INFO - PROCESS | 1949 | 2016-10-18 17:02:20.512 plugin-container[1950:20349] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8e0b, name = 'com.apple.CFPasteboardClient' 17:02:20 INFO - PROCESS | 1949 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:02:20 INFO - PROCESS | 1949 | 2016-10-18 17:02:20.512 plugin-container[1950:20349] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 17:02:30 INFO - PROCESS | 1949 | RSS: Main: 237375488 17:02:30 INFO - PROCESS | 1949 | 17:02:32 INFO - PROCESS | 1949 | Cycle 1(1): loaded http://localhost:57270/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:57270/tests/kraken/driver.html?testName=audio-beat-detection) 17:02:33 INFO - PROCESS | 1949 | RSS: Main: 249655296 17:02:33 INFO - PROCESS | 1949 | 17:02:36 INFO - PROCESS | 1949 | Cycle 1(1): loaded http://localhost:57270/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:57270/tests/kraken/driver.html?testName=audio-dft) 17:02:36 INFO - PROCESS | 1949 | RSS: Main: 257302528 17:02:36 INFO - PROCESS | 1949 | 17:02:40 INFO - PROCESS | 1949 | Cycle 1(1): loaded http://localhost:57270/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:57270/tests/kraken/driver.html?testName=audio-fft) 17:02:40 INFO - PROCESS | 1949 | RSS: Main: 260071424 17:02:40 INFO - PROCESS | 1949 | 17:02:42 INFO - PROCESS | 1949 | Cycle 1(1): loaded http://localhost:57270/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:57270/tests/kraken/driver.html?testName=audio-oscillator) 17:02:43 INFO - PROCESS | 1949 | RSS: Main: 261230592 17:02:43 INFO - PROCESS | 1949 | 17:02:45 INFO - PROCESS | 1949 | Cycle 1(1): loaded http://localhost:57270/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:57270/tests/kraken/driver.html?testName=imaging-gaussian-blur) 17:02:45 INFO - PROCESS | 1949 | RSS: Main: 262840320 17:02:45 INFO - PROCESS | 1949 | 17:02:48 INFO - PROCESS | 1949 | Cycle 1(1): loaded http://localhost:57270/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:57270/tests/kraken/driver.html?testName=imaging-darkroom) 17:02:49 INFO - PROCESS | 1949 | RSS: Main: 263634944 17:02:49 INFO - PROCESS | 1949 | 17:02:52 INFO - PROCESS | 1949 | Cycle 1(1): loaded http://localhost:57270/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:57270/tests/kraken/driver.html?testName=imaging-desaturate) 17:02:52 INFO - PROCESS | 1949 | RSS: Main: 264028160 17:02:52 INFO - PROCESS | 1949 | 17:02:55 INFO - PROCESS | 1949 | Cycle 1(1): loaded http://localhost:57270/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:57270/tests/kraken/driver.html?testName=json-parse-financial) 17:02:56 INFO - PROCESS | 1949 | RSS: Main: 263929856 17:02:56 INFO - PROCESS | 1949 | 17:02:57 INFO - PROCESS | 1949 | Cycle 1(1): loaded http://localhost:57270/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:57270/tests/kraken/driver.html?testName=json-stringify-tinderbox) 17:02:58 INFO - PROCESS | 1949 | RSS: Main: 263794688 17:02:58 INFO - PROCESS | 1949 | 17:03:00 INFO - PROCESS | 1949 | Cycle 1(1): loaded http://localhost:57270/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:57270/tests/kraken/driver.html?testName=stanford-crypto-aes) 17:03:01 INFO - PROCESS | 1949 | RSS: Main: 263761920 17:03:01 INFO - PROCESS | 1949 | 17:03:03 INFO - PROCESS | 1949 | Cycle 1(1): loaded http://localhost:57270/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:57270/tests/kraken/driver.html?testName=stanford-crypto-ccm) 17:03:03 INFO - PROCESS | 1949 | RSS: Main: 263606272 17:03:03 INFO - PROCESS | 1949 | 17:03:06 INFO - PROCESS | 1949 | Cycle 1(1): loaded http://localhost:57270/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:57270/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 17:03:07 INFO - PROCESS | 1949 | RSS: Main: 264052736 17:03:07 INFO - PROCESS | 1949 | 17:03:10 INFO - PROCESS | 1949 | Cycle 1(1): loaded http://localhost:57270/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:57270/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 17:03:11 INFO - PROCESS | 1949 | RSS: Main: 263860224 17:03:11 INFO - PROCESS | 1949 | 17:03:13 INFO - PROCESS | 1949 | Cycle 1(1): loaded http://localhost:57270/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:57270/tests/kraken/driver.html?testName=ai-astar) 17:03:13 INFO - PROCESS | 1949 | RSS: Main: 265641984 17:03:13 INFO - PROCESS | 1949 | 17:03:13 INFO - PROCESS | 1949 | __start_tp_report 17:03:13 INFO - PROCESS | 1949 | _x_x_mozilla_page_load 17:03:13 INFO - PROCESS | 1949 | _x_x_mozilla_page_load_details 17:03:13 INFO - PROCESS | 1949 | |i|pagename|runs| 17:03:13 INFO - PROCESS | 1949 | |0;ai-astar;114;118;118;122;118;117;118;117;118;118 17:03:13 INFO - PROCESS | 1949 | |1;audio-beat-detection;149;116;110;154;120;129;102;116;173;140 17:03:13 INFO - PROCESS | 1949 | |2;audio-dft;216;197;203;208;214;196;195;193;198;199 17:03:13 INFO - PROCESS | 1949 | |3;audio-fft;81;60;60;89;83;81;80;75;76;60 17:03:13 INFO - PROCESS | 1949 | |4;audio-oscillator;86;70;88;71;71;70;89;68;71;87 17:03:13 INFO - PROCESS | 1949 | |5;imaging-gaussian-blur;87;86;88;86;92;86;90;86;102;84 17:03:13 INFO - PROCESS | 1949 | |6;imaging-darkroom;95;95;95;96;96;94;96;96;95;115 17:03:13 INFO - PROCESS | 1949 | |7;imaging-desaturate;140;136;88;87;87;88;95;90;90;87 17:03:13 INFO - PROCESS | 1949 | |8;json-parse-financial;58;58;56;58;58;62;65;81;79;84 17:03:13 INFO - PROCESS | 1949 | |9;json-stringify-tinderbox;69;75;70;81;72;70;73;70;72;71 17:03:13 INFO - PROCESS | 1949 | |10;stanford-crypto-aes;87;88;104;90;84;91;89;89;90;86 17:03:13 INFO - PROCESS | 1949 | |11;stanford-crypto-ccm;159;171;147;140;183;161;162;148;185;173 17:03:13 INFO - PROCESS | 1949 | |12;stanford-crypto-pbkdf2;175;175;174;174;174;173;172;231;183;169 17:03:13 INFO - PROCESS | 1949 | |13;stanford-crypto-sha256-iterative;69;71;73;67;73;83;87;87;86;85 17:03:13 INFO - PROCESS | 1949 | __end_tp_report 17:03:13 INFO - PROCESS | 1949 | __start_cc_report 17:03:13 INFO - PROCESS | 1949 | _x_x_mozilla_cycle_collect,939 17:03:13 INFO - PROCESS | 1949 | __end_cc_report 17:03:13 INFO - PROCESS | 1949 | __startTimestamp1476835393304__endTimestamp 17:03:13 INFO - PROCESS | 1949 | 17:03:13 INFO - PROCESS | 1949 | ------- Summary: start ------- 17:03:13 INFO - PROCESS | 1949 | Number of tests: 14 17:03:13 INFO - PROCESS | 1949 | 17:03:13 INFO - PROCESS | 1949 | [#0] ai-astar Cycles:10 Average:117.80 Median:118.00 stddev:1.93 (1.6%) stddev-sans-first:1.48 17:03:13 INFO - PROCESS | 1949 | Values: 114.0 118.0 118.0 122.0 118.0 117.0 118.0 117.0 118.0 118.0 17:03:13 INFO - PROCESS | 1949 | 17:03:13 INFO - PROCESS | 1949 | [#1] audio-beat-detection Cycles:10 Average:130.90 Median:134.50 stddev:22.50 (16.7%) stddev-sans-first:22.89 17:03:13 INFO - PROCESS | 1949 | Values: 149.0 116.0 110.0 154.0 120.0 129.0 102.0 116.0 173.0 140.0 17:03:13 INFO - PROCESS | 1949 | 17:03:13 INFO - PROCESS | 1949 | [#2] audio-dft Cycles:10 Average:201.90 Median:201.00 stddev:8.12 (4.0%) stddev-sans-first:6.82 17:03:13 INFO - PROCESS | 1949 | Values: 216.0 197.0 203.0 208.0 214.0 196.0 195.0 193.0 198.0 199.0 17:03:13 INFO - PROCESS | 1949 | 17:03:13 INFO - PROCESS | 1949 | [#3] audio-fft Cycles:10 Average:74.50 Median:80.50 stddev:10.70 (13.3%) stddev-sans-first:11.09 17:03:13 INFO - PROCESS | 1949 | Values: 81.0 60.0 60.0 89.0 83.0 81.0 80.0 75.0 76.0 60.0 17:03:13 INFO - PROCESS | 1949 | 17:03:13 INFO - PROCESS | 1949 | [#4] audio-oscillator Cycles:10 Average:77.10 Median:78.50 stddev:9.02 (11.5%) stddev-sans-first:8.98 17:03:13 INFO - PROCESS | 1949 | Values: 86.0 70.0 88.0 71.0 71.0 70.0 89.0 68.0 71.0 87.0 17:03:13 INFO - PROCESS | 1949 | 17:03:13 INFO - PROCESS | 1949 | [#5] imaging-gaussian-blur Cycles:10 Average:88.70 Median:86.50 stddev:5.21 (6.0%) stddev-sans-first:5.49 17:03:13 INFO - PROCESS | 1949 | Values: 87.0 86.0 88.0 86.0 92.0 86.0 90.0 86.0 102.0 84.0 17:03:13 INFO - PROCESS | 1949 | 17:03:13 INFO - PROCESS | 1949 | [#6] imaging-darkroom Cycles:10 Average:97.30 Median:95.50 stddev:6.25 (6.5%) stddev-sans-first:6.58 17:03:13 INFO - PROCESS | 1949 | Values: 95.0 95.0 95.0 96.0 96.0 94.0 96.0 96.0 95.0 115.0 17:03:13 INFO - PROCESS | 1949 | 17:03:13 INFO - PROCESS | 1949 | [#7] imaging-desaturate Cycles:10 Average:98.80 Median:88.00 stddev:20.82 (23.7%) stddev-sans-first:15.87 17:03:13 INFO - PROCESS | 1949 | Values: 140.0 136.0 88.0 87.0 87.0 88.0 95.0 90.0 90.0 87.0 17:03:13 INFO - PROCESS | 1949 | 17:03:13 INFO - PROCESS | 1949 | [#8] json-parse-financial Cycles:10 Average:65.90 Median:63.50 stddev:11.01 (17.3%) stddev-sans-first:11.30 17:03:13 INFO - PROCESS | 1949 | Values: 58.0 58.0 56.0 58.0 58.0 62.0 65.0 81.0 79.0 84.0 17:03:13 INFO - PROCESS | 1949 | 17:03:13 INFO - PROCESS | 1949 | [#9] json-stringify-tinderbox Cycles:10 Average:72.30 Median:72.00 stddev:3.53 (4.9%) stddev-sans-first:3.54 17:03:13 INFO - PROCESS | 1949 | Values: 69.0 75.0 70.0 81.0 72.0 70.0 73.0 70.0 72.0 71.0 17:03:13 INFO - PROCESS | 1949 | 17:03:13 INFO - PROCESS | 1949 | [#10] stanford-crypto-aes Cycles:10 Average:89.80 Median:89.00 stddev:5.41 (6.1%) stddev-sans-first:5.64 17:03:13 INFO - PROCESS | 1949 | Values: 87.0 88.0 104.0 90.0 84.0 91.0 89.0 89.0 90.0 86.0 17:03:13 INFO - PROCESS | 1949 | 17:03:13 INFO - PROCESS | 1949 | [#11] stanford-crypto-ccm Cycles:10 Average:162.90 Median:166.50 stddev:15.20 (9.1%) stddev-sans-first:16.05 17:03:13 INFO - PROCESS | 1949 | Values: 159.0 171.0 147.0 140.0 183.0 161.0 162.0 148.0 185.0 173.0 17:03:13 INFO - PROCESS | 1949 | 17:03:13 INFO - PROCESS | 1949 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:180.00 Median:174.50 stddev:18.26 (10.5%) stddev-sans-first:19.28 17:03:13 INFO - PROCESS | 1949 | Values: 175.0 175.0 174.0 174.0 174.0 173.0 172.0 231.0 183.0 169.0 17:03:13 INFO - PROCESS | 1949 | 17:03:13 INFO - PROCESS | 1949 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:78.10 Median:84.00 stddev:8.17 (9.7%) stddev-sans-first:7.98 17:03:13 INFO - PROCESS | 1949 | Values: 69.0 71.0 73.0 67.0 73.0 83.0 87.0 87.0 86.0 85.0 17:03:13 INFO - PROCESS | 1949 | -------- Summary: end -------- 17:03:13 INFO - PROCESS | 1949 | 17:03:14 INFO - TEST-INFO | 1949: exit 0 17:03:14 INFO - TEST-OK | kraken | took 59817ms 17:03:14 INFO - SUITE-END | took 1175s 17:03:14 INFO - Completed test suite (00:19:35) 17:03:14 INFO - javascript benchmark 17:03:14 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7252.629464517326, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3308.6856748608875, 3351.447011243145, 3374.0552645259304, 3381.695647391735, 3384.4415671414217, 5511.3937466878715, 5595.132754423061, 5600.439956004398, 5614.747336369868, 5712.885742285162, 5415.458454154583, 5524.8342549723575, 5648.971755141229, 5707.058335374669, 5718.59969802114, 5113.028524580336, 5145.74271286436, 5176.456472070435, 5230.503102205284, 5280.577553795687, 5145.897082058356, 5241.633085684004, 5277.920831187535, 5283.101872681634, 5348.064088784467, 5108.846734597931, 5148.510891465305, 5167.431582525919, 5171.81898633546, 5248.107821670325, 5188.066148093341, 5196.428392876781, 5208.817691380784, 5217.895642087175, 5253.3958594761325, 4719.914419683492, 5109.591232701375, 5157.192505892223, 5158.432572417031, 5246.239295302198, 4445.8221671133115, 5507.862303442406, 5563.554915606742, 5587.944120558783, 5653.830385088454, 6038.33578306386, 6070.9982852829235, 6093.534666521248, 6103.580118178676, 6134.8773024539705, 6113.354685976905, 6131.957816599981, 6159.876802463926, 6188.84527886802, 6229.626222426669, 5747.913781293242, 5757.68332741699, 5766.740496677659, 5783.0, 5823.2138661280605, 5050.217216331474, 6132.816015519542, 6208.9689551551955, 6210.037444196157, 6215.782447614311, 5920.763169473259, 5978.730957106941, 6022.277326720821, 6040.124181993587, 6067.544934129958, 6094.238220222473, 6168.290646575611, 6178.66017369049, 6179.783707570214, 6198.256209254918, 5967.19442875215, 6054.424829641177, 6109.266887973383, 6113.141862489121, 6118.0, 6147.897245286402, 6166.136740856284, 6168.982117950578, 6192.504599632019, 6220.222472190976, 4756.571908528249, 4801.279808028754, 4818.68678535894, 4833.468318484964, 4881.609471242292, 4366.519682834885, 4384.101259241847, 4397.912041759147, 4417.757023363746, 4442.200403927324, 4861.687344416988, 4894.388201474816, 5298.655587386807, 5697.401772813795, 5806.65160090396, 6083.0, 6115.088229002436, 6127.678242056309, 6143.416375444325, 6178.196834411498, 4949.6287778416045, 5590.8043218487155, 5750.0, 5775.047117225695, 5819.563532735062, 4453.755043472575, 5354.518093371616, 5359.705216213146, 5377.0, 5430.0768869292315, 6111.53096535531, 6137.048757442603, 6151.876962460637, 6202.038684003986, 6212.0, 6072.362401947732, 6110.315755985069, 6111.622125543816, 6116.761878222105, 6137.232845894263, 6217.875642487215, 6218.875622487615, 6256.343083976117, 6259.467945224706, 6289.968550157403, 6013.30846952606, 6053.303870055, 6129.2644882612585, 6143.447089761943, 6145.477634401126, 6084.60450070744, 6091.0, 6119.296280927751, 6130.306415576139, 6145.432914606747, 5983.970080149571, 6012.008018676869, 6029.216201893726, 6034.396560344106, 6065.332813390612, 5893.6463812171405, 5948.434898684618, 5995.160677505066, 6007.969960150172, 6070.089486577049, 5888.352281249145, 5919.378465261258, 5947.91078133837, 5948.672822994604, 5959.91060134107, 4715.669903106749, 6043.8186854394435, 6073.48375388097, 6081.939180608138, 6114.0828875669, 5731.226284451545, 5768.53851691872, 5811.94188058114, 5914.497267732291, 5953.523718102628], "value": 5597.104343053842, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [488.3968299150551, 489.8897748006698, 499.7151623574564, 501.69145975225234, 512.2035235209253, 39812.800935995314, 41131.0, 41250.17499650009, 43469.34795978063, 43708.3443748344, 42662.7866860666, 42968.35547466783, 43054.0, 43235.78382108093, 43265.35101973473, 9465.242780577555, 9575.760605984871, 9626.614935402575, 9655.08276713711, 9685.079917407871, 8371.790705232357, 9586.808263834719, 9608.375455595398, 9658.227341812639, 9689.951550242238, 9613.182879455255, 9642.084002019797, 9642.517874106301, 9681.75795605112, 9738.9513052435, 9607.231421486264, 9620.759481012961, 9648.986856380085, 9655.517224138765, 9663.565139568736, 9471.857922131174, 9494.667686630935, 9499.477528735917, 9531.475768832712, 9555.665551705657, 40460.39309410361, 40667.59332406668, 40874.591254087376, 40948.79525602383, 40969.59030409688, 3356.194513316811, 3365.8317084145697, 3413.163774875147, 3553.502509648651, 3726.31063253299, 30508.0, 30624.846875765477, 30627.846860765778, 30649.846750766104, 31708.524372134205, 2932.8240305581735, 3178.96821031789, 3191.5850939377965, 3232.9838350808095, 3282.1302354875975, 422430.0, 457256.0, 481669.0, 486370.0, 489702.9633666458, 57959.0, 58277.70861145667, 58482.707586461795, 58530.0, 58586.0, 59857.40142598518, 59945.40054599398, 60147.69926150341, 60240.698796505734, 60267.0, 15552.377904883904, 15711.135887526067, 15726.528204153894, 15733.44932927342, 15777.0, 10662.680119596425, 12092.879071209176, 12175.75648487043, 12240.571579994836, 12295.938520307342, 15204.46784362542, 15799.36802527909, 15811.367545298059, 15824.496672816063, 15835.841641583667, 5628.634138781048, 6294.9685251574365, 6380.968095159495, 6469.935300646934, 6471.870562588721, 842.5113434208145, 852.6461518469889, 959.6353385713384, 969.8652576485529, 1043.5877828257796, 27401.86299068492, 28147.859260703564, 28220.71779282222, 28517.429651406856, 29332.560011599842, 894.2711689972651, 975.1954637424153, 1020.1634659579222, 1048.1509976918721, 1134.489479734123, 14314.427422903176, 15103.640672339543, 15210.619734506505, 15224.619384515476, 15533.834962377867, 632487.0, 639549.6045039583, 643640.0, 643890.0, 647084.0, 54909.725451373284, 55132.44867551354, 55237.44762552404, 55324.44675553193, 55472.445275547536, 1333.0468714868916, 1349.2916218984915, 1366.4670778396433, 1379.0966916669597, 1388.3891087921281, 26751.197464076107, 28391.858040709663, 28617.998370056946, 28854.42291154207, 29261.268468288465, 48798.0, 48924.2661360087, 48925.75537122291, 48998.75500622474, 49105.0, 534034.0, 537823.0, 538363.0, 538496.0, 538511.0, 25063.248102556954, 25223.864926078004, 25424.237272881845, 25448.982040718165, 25674.358141046625, 46157.0, 46602.83492912704, 46826.53173468222, 47015.76492117518, 47030.0, 30569.847150764104, 34958.82520587381, 36779.81610091932, 37627.2474550502, 39129.608703913735, 37646.43530346992, 38116.428253575665, 38753.612463876125, 38892.6110738889, 39322.6067739319, 36490.722824701006, 36902.0, 37559.248815023, 37728.0, 37869.0, 32527.0, 33127.66872331246, 33565.496517551765, 34196.31607367789, 34349.0, 23085.422864428525, 25178.370540735894, 25405.491890162462, 25645.71771411459, 25741.742582574683], "value": 19386.366108084552, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [327.81806097615816, 372.8061408067805, 373.4715377740499, 377.9603141670125, 400.40139990713885, 19106.235750569977, 19558.51103722406, 20788.896055519737, 21042.789572104273, 21075.683864742005, 14095.577132686036, 17158.970461772304, 17916.64166716665, 20397.59204815903, 20909.0, 5231.555317797992, 5704.971475142618, 5921.555883308748, 6014.789482368118, 6018.608790428608, 5643.746031428575, 5939.910901336483, 6015.127806468059, 6035.064564992434, 6052.303985041711, 5891.5286777057945, 6046.455818976291, 6052.03167493199, 6062.060380641008, 6549.279579246279, 6614.834629134262, 6658.067870498134, 6681.8997715034075, 6687.866242675143, 6692.83267918301, 5052.469490703496, 5340.732963351856, 5392.299001129867, 5454.945450545483, 5892.558058145656, 13009.089363744542, 13094.952403807672, 13185.208887466686, 13223.140495867834, 13285.202887826665, 2064.2671851492655, 2133.2426988419197, 2327.9695320133274, 2340.414896275931, 2382.285314405671, 9654.469004204766, 9714.562844671937, 9805.656802011896, 10116.94941525295, 10633.415162166079, 1818.254515648591, 1832.3769918227733, 1908.7518622579114, 1925.9614807703908, 1934.351992082654, 113858.0, 120096.3995180036, 120160.39919800432, 120733.39633301778, 120837.39581302126, 10974.134397152504, 11013.394263315435, 11548.76902461946, 11582.6525204244, 11745.412729363497, 14294.714105718036, 14479.0, 14498.275086245645, 14520.927395363167, 14531.346089426, 7302.306280903305, 8016.799580010546, 8099.109097999215, 8369.748907532783, 8478.74563763088, 6858.485613579001, 8196.918030819736, 8315.708950186714, 8337.874931876027, 8353.20644538768, 3823.751456155341, 3859.6912247020173, 4505.842295519641, 4539.0, 4592.609628181575, 1920.2511020701938, 1950.2004178286834, 1987.4236471423126, 1988.7016947457996, 1999.4001799460395, 842.2461896602506, 873.2097451806126, 883.2227639677043, 996.5266498413341, 1019.1184625299063, 16797.076160811273, 21224.469388265108, 21459.78540214578, 21658.458538536663, 21683.457913552287, 902.3412908576776, 927.248928368024, 929.3355250995571, 945.6973768394048, 978.3102912446708, 11139.610113646146, 11416.543338266376, 11417.657470275904, 11493.367864767351, 11503.022243109262, 538925.0, 568284.0, 572194.0, 584980.0750996303, 585139.0, 65765.35586610372, 106311.93688063175, 117783.0, 122014.77985220212, 122216.0, 834.4576025583298, 959.3284700709531, 970.5826494607387, 996.7009897030862, 1006.8489726540931, 18797.812021880152, 19550.120244588743, 19586.902065489583, 19603.0, 19613.411597651524, 52299.21551176659, 53608.0, 53689.19466207932, 53910.46089539211, 54060.72969635126, 76262.0, 76850.61574692091, 77014.614926925, 78114.60942695478, 78163.0, 6837.965810171117, 7890.171531989058, 7905.960470197612, 8112.067112282163, 8363.038250601016, 7807.219278072374, 12040.337781421755, 12236.877631223573, 12628.0, 12753.426095825525, 10815.944970455588, 11057.170712196777, 11129.332240065622, 11158.442077896236, 11201.983642944022, 10018.248631352531, 10080.24398170155, 10316.845247321446, 11049.83425248638, 11437.714057148305, 11285.266457680225, 11385.886141138482, 11594.362310073027, 11664.475098620413, 11773.587924422947, 8018.198180181935, 8095.5142691438705, 8132.593370331341, 8257.133001034805, 8261.297789687944, 8314.41799074059, 8861.778455538662, 11001.944990275317, 11173.0, 11315.886841131813], "value": 9241.329741669604, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1545.6599548099418, 1595.696817604655, 1748.7202047672374, 1841.521204486833, 1892.7728672559294, 8061.596920153998, 8353.874691879626, 8766.868496972551, 9147.954260228706, 9157.450552966826, 8589.570521473917, 8885.68900088497, 8901.554922253878, 9027.95486022569, 9087.546362482142, 2381.690380250565, 2424.284835973392, 2427.3446169534304, 2431.927042188728, 2436.7319594844553, 2360.9409764755846, 2390.713114426271, 2412.445137618349, 2420.782129608335, 2430.1008626808016, 2405.037984806083, 2414.7343792182846, 2421.9152329668464, 2438.0735320578156, 2449.9955018442442, 2399.3281881073326, 2399.6040653292184, 2406.2420337593603, 2406.6750988616577, 2408.277516744978, 2359.7640235976437, 2366.1008816649737, 2369.07606033647, 2369.7867191952637, 2374.620060790265, 8949.731508054703, 9010.549472526347, 9050.094990500964, 9063.410878292956, 9081.45511269326, 4579.267317229227, 5451.155070964007, 5477.3427188737205, 5493.533049690781, 5525.309336332958, 3499.9650003500146, 3641.3263546243766, 3685.299793039314, 3703.092742278132, 3719.107414220595, 1052.8631277933896, 1083.8494521200528, 1085.4789700943513, 1091.7052395853075, 1106.750981029262, 9467.147956683932, 9598.896126945421, 11004.119670426428, 11452.0, 11902.523899044028, 4716.245400735866, 4729.18528502573, 4789.808407663654, 4903.485134060944, 4919.877003074952, 10008.049235322633, 10056.0, 10135.290529663, 10154.76595149066, 10176.185905127571, 4743.525647435298, 5408.540274076748, 5456.0451920914475, 5480.972595137078, 5483.424240454775, 4461.420015397977, 4615.930761038587, 4824.276358546176, 4941.72820494869, 4999.950000499948, 3915.6280153385383, 3964.147708242742, 4058.4723985882238, 4078.8368465261055, 4109.486314210723, 1223.1437993404475, 1287.6458973782285, 1288.67138879587, 1298.539018648385, 1313.3892739876003, 847.0555330806106, 893.2541327991071, 912.4160537256162, 915.3958387464241, 947.7062110745691, 5724.3130824300515, 5836.416358364129, 5993.790217342372, 6005.309389420186, 6154.476869466056, 421.42054674822117, 424.60511724096267, 426.5883422497243, 446.557907671403, 454.2867697714622, 3239.6598357172156, 3852.499175107218, 4062.5937406259727, 4063.4717486726536, 4096.959030409717, 10551.050405463546, 10811.6215932442, 10938.56245750177, 10944.507497162647, 10963.177761667906, 10623.575056997788, 10744.838827417596, 10882.347059176474, 10885.83671244932, 10928.071113955402, 7528.774136775905, 8341.290990265896, 8345.66617335312, 8353.874691879626, 8368.330533557422, 5107.387113546398, 5704.286964129484, 5858.384869588632, 5896.909071821727, 5925.200097986889, 10073.345232559861, 10119.443430611387, 10158.847617285599, 10214.693559193236, 10248.948755256177, 8935.865962010444, 9989.60041598328, 10106.545205465625, 10285.53715082838, 10292.0, 6951.0, 6951.165860096618, 7155.8568828624175, 7414.258574142543, 7707.11368192665, 8299.460535065202, 8363.62363693623, 8380.58097095155, 8389.454685445426, 8391.874121888055, 6943.166819981635, 7523.360514356122, 7639.2809389392805, 7671.156172821097, 7825.5304681717, 6408.9038664421005, 6469.870602587827, 7182.60495672743, 7274.672639731119, 7669.884951725617, 6216.533759968111, 6998.370146686619, 7113.2175460698245, 7154.892676609959, 7186.820329491596, 4762.380890484214, 4770.928436073392, 5045.369328833895, 5113.616478764182, 5118.8976220476125, 5016.698998060128, 5059.342285502861, 5097.413797413196, 5144.202648589475, 5154.974225128851], "value": 4520.186783438345, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [261.6990460969884, 289.5627602320496, 312.0638085742771, 318.9202699325169, 319.4920077077446, 4394.0, 4857.757112144387, 5416.756245968931, 5626.0, 5735.741891614877, 4828.275758636207, 4868.683535570194, 5554.666719996793, 5555.388907220213, 5559.610827242094, 4251.6173544381, 4256.977935480645, 4307.483102027745, 4313.705888233533, 4319.2441322768545, 3862.7102967277424, 3973.3180103430213, 4081.2041651877894, 4237.364395340693, 4341.026396918105, 4195.790210489478, 4228.281192197333, 4233.026403927099, 4236.237477254093, 4270.665274746135, 3594.975432001867, 4171.144915292388, 4220.514640816299, 4226.323788193889, 4257.723247988902, 3179.205198700325, 3365.737848306885, 3378.4256676365076, 3650.7079433645245, 3652.9634703652887, 4390.8024138913825, 4639.489656137822, 5097.8215762448135, 5385.973070134663, 5386.3536375634785, 4402.811240964954, 4663.766811659403, 4849.369581954358, 4856.441509226449, 4870.878228044327, 5726.578294774393, 5926.97036514819, 5939.613925094855, 5954.7915822946425, 5966.67183304918, 3449.9477659313898, 3875.748076375055, 3907.987386495957, 3973.7613844716752, 3988.541317748467, 5949.613275137102, 6213.650444688736, 6250.4060814761815, 6338.144350512714, 6378.93621063788, 3363.814990175515, 3493.982530087384, 4042.7265411395906, 4057.594240575978, 4058.492688413948, 3768.586779957516, 3794.9430758538642, 4082.5708703321475, 4105.281575724296, 4114.177164567099, 3706.647868452547, 3809.657130858236, 4254.744715317091, 4839.2983017462275, 4841.564259216688, 4796.760161991887, 5444.047291723932, 5644.520215781705, 5667.319921609434, 5973.058755904349, 1307.5946456598485, 1344.9932750336186, 1354.959351219465, 1389.6039628705973, 1407.9155250684787, 864.9135086491301, 921.5852866210232, 924.4360939826701, 936.4521754773398, 939.7650587353162, 5540.418256083134, 5545.77816887328, 5549.528290095387, 5582.581306401955, 5584.413636568183, 5921.0460548019055, 6052.0, 6086.0, 6118.755249789959, 6168.321484636686, 4829.73436460998, 5117.692938423706, 5515.731381782212, 5535.197396377503, 5588.161775733673, 2387.3434805428647, 2444.9511009779703, 2684.852333121697, 2723.6867760207433, 2724.577690457982, 4736.905261894743, 5245.6852588844795, 5267.0, 5282.630215884851, 5288.127458969304, 4522.237075759671, 4757.575454827044, 4783.0389540288315, 5080.078954601046, 5135.948640513622, 4404.537523560044, 4855.5387238212315, 4897.057868415817, 5326.014687282865, 5328.280682107866, 4992.700437973734, 5097.439281679087, 5116.872078198075, 5126.666766660155, 5137.640365174551, 4634.119517291704, 4934.5312195341385, 5038.874028149326, 5135.716070982255, 6041.395860414099, 5233.424323324507, 5898.9115163273445, 6160.014397696347, 6236.160332701773, 6500.5449618526245, 3361.798292102482, 3469.462233353834, 3738.7009039277336, 3743.644353786386, 4063.8577649782114, 4987.2768448574025, 5189.287535113479, 5483.259759932358, 5497.312835895513, 6235.844103897439, 5872.856407411349, 5921.993261145702, 6002.158618484261, 6004.309504406874, 6052.576319657582, 5270.762815673381, 6009.536090202996, 6034.24571928509, 6040.426159514839, 6045.78839740607, 5595.440455954372, 5822.417758224313, 5868.882622347443, 5870.853228669317, 5913.497352725067, 5197.402298735694, 5967.522598192048, 6069.117169344526, 6087.726052327745, 6089.33017368098, 4635.374224479787, 5861.9413805861395, 5880.529557635294, 5907.586468947133, 5945.751392207512], "value": 4138.21737299992, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [703.3283214530124, 704.4540481127127, 732.8607564562733, 734.8162959260185, 830.3523251863546, 15136.470223542177, 16352.836471635295, 16362.345506179754, 16395.67208655826, 16396.75404868928, 14574.344154513046, 14599.562013139595, 14708.97037207396, 14723.631909202275, 14770.556883293519, 860.1871231686059, 878.5343767803074, 880.1110878013221, 880.7049638371121, 884.0628933330657, 691.4399336537396, 715.6028404235647, 875.181705105725, 878.556329053827, 881.2597418168737, 888.1163242573648, 919.1681528216974, 936.2743873498059, 936.9344145909789, 942.8161508505844, 916.6974898283551, 918.1690570034096, 930.4138392812484, 932.664240873285, 933.3886304470583, 667.3293340193123, 678.3216783216783, 678.3386198456515, 692.1071817355605, 693.8889777635554, 14927.179005154821, 14976.326065326977, 14976.326065327086, 14979.325930333162, 15006.849931500654, 11910.642680719593, 13574.796378054338, 13619.387127579184, 13759.931200343934, 13807.0, 12411.75176496474, 12492.125551211418, 12552.309622970733, 12641.49434022638, 12944.893225625876, 4449.332600109977, 4450.577195166454, 4453.22068637987, 4535.433070866142, 4681.157391669498, 117775.4111229447, 117984.0, 118304.0, 119991.40004299923, 122138.0, 10972.835407468894, 10973.396463194442, 11049.779004420026, 11155.0, 11436.970672639502, 21582.136714531556, 21583.784162158492, 21584.676229856566, 21590.568188636455, 21654.0, 7590.8102297443, 7699.730509432143, 7745.302922736868, 7764.340031097307, 7917.3270272026375, 4200.894977625584, 4211.241976444208, 4265.125649241898, 4280.978595107004, 4469.307257375112, 3374.79751214923, 3474.409350410436, 3475.6350583188905, 3481.5473988381855, 3744.737868349244, 616.442119881508, 698.2563569798149, 702.67676868641, 711.5623891306913, 728.8943103249999, 533.2188344075947, 563.1777604697188, 602.5631417222567, 609.2597494044757, 626.4143026270451, 9479.0, 9564.18704410119, 9614.260520281101, 9634.277429192838, 9765.609375624896, 614.6772944204239, 645.2643985856128, 671.466629693089, 679.4123083532709, 686.8591938652664, 4561.612262957686, 4776.47458779534, 4778.522147785194, 4820.927686084711, 4836.540528649773, 152955.0, 153536.46463535444, 158868.0, 161055.0, 163082.0, 138183.0, 138325.999999998, 139232.0, 139588.0, 143240.28379858035, 71422.0, 73532.63233683797, 78198.6090069546, 111867.44066279616, 113734.29398559028, 9017.504037278013, 9020.323475739477, 9039.81920361576, 9086.954565227396, 9184.908150918673, 62348.0, 62621.68689156525, 64192.67903660452, 65624.6718766403, 65726.67136664285, 96659.0, 100010.4999474998, 104072.47963760435, 106027.40958885777, 107281.0, 11601.76796464049, 11702.4733886977, 11766.647000589996, 11880.881191187977, 12053.758924821279, 9844.490057601672, 11182.720431989266, 11495.885041149482, 12945.28800915921, 13345.266010369134, 10168.440735759308, 12217.144799863925, 12272.386380680751, 12291.0, 12664.430100645677, 12036.879631203576, 12326.938365308117, 12402.13185077036, 12527.749445010866, 12712.6821829455, 11934.641960741192, 12039.939800301292, 12257.080718945936, 12330.506779729069, 12551.184173028723, 10332.330789689304, 10511.316764410289, 10584.89415105839, 10750.569977200825, 11089.5564177432, 8421.789455263668, 9304.209142222717, 10599.947000264949, 10730.356178629307, 10922.235443518877], "value": 7758.2591679691395, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1536.0, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 118.0, 118.0, 122.0, 118.0, 117.0, 118.0, 117.0, 118.0, 118.0], "value": 117.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 116.0, 110.0, 154.0, 120.0, 129.0, 102.0, 116.0, 173.0, 140.0], "value": 130.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [216.0, 197.0, 203.0, 208.0, 214.0, 196.0, 195.0, 193.0, 198.0, 199.0], "value": 201.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 60.0, 60.0, 89.0, 83.0, 81.0, 80.0, 75.0, 76.0, 60.0], "value": 74.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 70.0, 88.0, 71.0, 71.0, 70.0, 89.0, 68.0, 71.0, 87.0], "value": 77.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 86.0, 88.0, 86.0, 92.0, 86.0, 90.0, 86.0, 102.0, 84.0], "value": 88.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 96.0, 96.0, 94.0, 96.0, 96.0, 95.0, 115.0], "value": 97.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 136.0, 88.0, 87.0, 87.0, 88.0, 95.0, 90.0, 90.0, 87.0], "value": 98.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 58.0, 56.0, 58.0, 58.0, 62.0, 65.0, 81.0, 79.0, 84.0], "value": 65.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 75.0, 70.0, 81.0, 72.0, 70.0, 73.0, 70.0, 72.0, 71.0], "value": 72.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 88.0, 104.0, 90.0, 84.0, 91.0, 89.0, 89.0, 90.0, 86.0], "value": 89.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [159.0, 171.0, 147.0, 140.0, 183.0, 161.0, 162.0, 148.0, 185.0, 173.0], "value": 162.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [175.0, 175.0, 174.0, 174.0, 174.0, 173.0, 172.0, 231.0, 183.0, 169.0], "value": 180.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 71.0, 73.0, 67.0, 73.0, 83.0, 87.0, 87.0, 86.0, 85.0], "value": 78.1, "unit": "score"}]}]} 17:03:14 INFO - Return code: 0 17:03:14 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 17:03:14 ERROR - # TBPL SUCCESS # 17:03:14 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 17:03:14 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 17:03:14 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 17:03:14 INFO - 2016-10-18 17:03:14.597 screenresolution[1954:20729] starting screenresolution argv=screenresolution get 17:03:14 INFO - 2016-10-18 17:03:14.599 screenresolution[1954:20729] Display 0: 1600x1200x32@60 17:03:14 INFO - 2016-10-18 17:03:14.605 screenresolution[1955:20732] starting screenresolution argv=screenresolution list 17:03:14 INFO - Available Modes on Display 0 17:03:14 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 17:03:14 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 17:03:14 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 17:03:14 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 17:03:14 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 17:03:14 INFO - Intel Iris: 17:03:14 INFO - Chipset Model: Intel Iris 17:03:14 INFO - Type: GPU 17:03:14 INFO - Bus: Built-In 17:03:14 INFO - VRAM (Dynamic, Max): 1536 MB 17:03:14 INFO - Vendor: Intel (0x8086) 17:03:14 INFO - Device ID: 0x0a2e 17:03:14 INFO - Revision ID: 0x0009 17:03:14 INFO - Displays: 17:03:14 INFO - Display: 17:03:14 INFO - Resolution: 1600 x 1200 @ 60 Hz 17:03:14 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 17:03:14 INFO - Display Serial Number: 1600x1200 60 17:03:14 INFO - Main Display: Yes 17:03:14 INFO - Mirror: Off 17:03:14 INFO - Online: Yes 17:03:14 INFO - Rotation: Supported 17:03:14 INFO - Return code: 0 17:03:14 INFO - Running post-action listener: _resource_record_post_action 17:03:14 INFO - [mozharness: 2016-10-19 00:03:14.845342Z] Finished run-tests step (success) 17:03:14 INFO - Running post-run listener: _resource_record_post_run 17:03:14 INFO - Total resource usage - Wall time: 1210s; CPU: 55.0%; Read bytes: 1046016; Write bytes: 710304768; Read time: 13; Write time: 2079 17:03:14 INFO - TinderboxPrint: CPU usage
54.6% 17:03:14 INFO - TinderboxPrint: I/O read bytes / time
1,046,016 / 13 17:03:14 INFO - TinderboxPrint: I/O write bytes / time
710,304,768 / 2,079 17:03:14 INFO - TinderboxPrint: CPU idle
2,197.7 (45.4%) 17:03:14 INFO - TinderboxPrint: CPU system
980.2 (20.2%) 17:03:14 INFO - TinderboxPrint: CPU user
1,663.7 (34.4%) 17:03:14 INFO - TinderboxPrint: Swap in / out
407,969,792 / 0 17:03:14 INFO - install - Wall time: 35s; CPU: 55.0%; Read bytes: 393095680; Write bytes: 389569536; Read time: 29020; Write time: 779 17:03:14 INFO - run-tests - Wall time: 1177s; CPU: 55.0%; Read bytes: 1046016; Write bytes: 299083776; Read time: 13; Write time: 1251 17:03:15 INFO - Running post-run listener: _upload_blobber_files 17:03:15 INFO - Blob upload gear active. 17:03:15 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 17:03:15 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 17:03:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Autoland', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 17:03:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Autoland -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 17:03:15 INFO - (blobuploader) - INFO - Open directory for files ... 17:03:15 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 17:03:15 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:03:15 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:03:16 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 17:03:16 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:03:16 INFO - (blobuploader) - INFO - Done attempting. 17:03:16 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 17:03:16 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:03:16 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:03:17 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 17:03:17 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:03:17 INFO - (blobuploader) - INFO - Done attempting. 17:03:17 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 17:03:17 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:03:17 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:03:19 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 17:03:19 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:03:19 INFO - (blobuploader) - INFO - Done attempting. 17:03:19 INFO - (blobuploader) - INFO - Iteration through files over. 17:03:19 INFO - Return code: 0 17:03:19 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 17:03:19 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 17:03:19 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/ed98f85437c5ca3f3d196d846109a2dd539f7da9e464996e4007d6310d237b1e4fb7039b0c03052f174669ab2bf9c4e56fcbfd3d6962e510e4a0aa2eb97a3a5c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/1696fa34590f85191514eda5f964270e85c760d75fd436d5d9af02af4b207a54fd7117ecfe639489127353c2aedf8fd1bfb1a2ce7ddc1c1b4780adcc7ec9abb8", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/fca5391c69d98c8a4597f8debede0d92f903996ad2d4272fe7cea2f3a8958dc8f2bd092c2690a9b3de6f07eba8161f55bd65356cc8260402b8d4ab708533e2a1"} 17:03:19 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 17:03:19 INFO - Writing to file /builds/slave/test/properties/blobber_files 17:03:19 INFO - Contents: 17:03:19 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/ed98f85437c5ca3f3d196d846109a2dd539f7da9e464996e4007d6310d237b1e4fb7039b0c03052f174669ab2bf9c4e56fcbfd3d6962e510e4a0aa2eb97a3a5c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/1696fa34590f85191514eda5f964270e85c760d75fd436d5d9af02af4b207a54fd7117ecfe639489127353c2aedf8fd1bfb1a2ce7ddc1c1b4780adcc7ec9abb8", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/fca5391c69d98c8a4597f8debede0d92f903996ad2d4272fe7cea2f3a8958dc8f2bd092c2690a9b3de6f07eba8161f55bd65356cc8260402b8d4ab708533e2a1"} 17:03:19 INFO - Running post-run listener: copy_logs_to_upload_dir 17:03:19 INFO - Copying logs to upload dir... 17:03:19 INFO - mkdir: /builds/slave/test/build/upload/logs 17:03:19 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1272.994619 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2016-10-18 17:03:20.021632) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-18 17:03:20.025699) ========= 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.5vBKHtcXHC/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.uYdZICfFhj/Listeners TMPDIR=/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/ed98f85437c5ca3f3d196d846109a2dd539f7da9e464996e4007d6310d237b1e4fb7039b0c03052f174669ab2bf9c4e56fcbfd3d6962e510e4a0aa2eb97a3a5c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/1696fa34590f85191514eda5f964270e85c760d75fd436d5d9af02af4b207a54fd7117ecfe639489127353c2aedf8fd1bfb1a2ce7ddc1c1b4780adcc7ec9abb8", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/fca5391c69d98c8a4597f8debede0d92f903996ad2d4272fe7cea2f3a8958dc8f2bd092c2690a9b3de6f07eba8161f55bd65356cc8260402b8d4ab708533e2a1"} build_url:https://queue.taskcluster.net/v1/task/ZfEMSV2MR6-x5OhRQB8aUA/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011651 build_url: 'https://queue.taskcluster.net/v1/task/ZfEMSV2MR6-x5OhRQB8aUA/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/ed98f85437c5ca3f3d196d846109a2dd539f7da9e464996e4007d6310d237b1e4fb7039b0c03052f174669ab2bf9c4e56fcbfd3d6962e510e4a0aa2eb97a3a5c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/1696fa34590f85191514eda5f964270e85c760d75fd436d5d9af02af4b207a54fd7117ecfe639489127353c2aedf8fd1bfb1a2ce7ddc1c1b4780adcc7ec9abb8", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/fca5391c69d98c8a4597f8debede0d92f903996ad2d4272fe7cea2f3a8958dc8f2bd092c2690a9b3de6f07eba8161f55bd65356cc8260402b8d4ab708533e2a1"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-18 17:03:20.059850) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-18 17:03:20.060430) ========= 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.5vBKHtcXHC/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.uYdZICfFhj/Listeners TMPDIR=/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/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.005286 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-18 17:03:20.110112) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-18 17:03:20.110437) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-18 17:03:20.110797) ========= ========= Total master_lag: 0.18 =========