builder: cedar_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0092 starttime: 1478202306.85 results: success (0) buildid: 20161103095420 builduid: e18958d3c73e41148b6943619853a73d revision: ba6a7158ef89e9ed080398f0638fea585d085afa ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-11-03 12:45:06.851149) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-11-03 12:45:06.851857) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-03 12:45:06.852129) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-03 12:45:06.885555) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-03 12:45:06.885823) ========= 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.4xRQwWsw7D/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.4kiiQkIpdd/Listeners TMPDIR=/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-11-03 12:45:06-- 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% 155M=0s 2016-11-03 12:45:07 (155 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.118141 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-03 12:45:07.025447) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-03 12:45:07.025723) ========= 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.4xRQwWsw7D/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.4kiiQkIpdd/Listeners TMPDIR=/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/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.020269 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-03 12:45:07.077702) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-11-03 12:45:07.078030) ========= bash -c 'python archiver_client.py mozharness --repo projects/cedar --rev ba6a7158ef89e9ed080398f0638fea585d085afa --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo projects/cedar --rev ba6a7158ef89e9ed080398f0638fea585d085afa --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.4xRQwWsw7D/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.4kiiQkIpdd/Listeners TMPDIR=/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-11-03 12:45:07,153 truncating revision to first 12 chars 2016-11-03 12:45:07,153 Setting DEBUG logging. 2016-11-03 12:45:07,153 attempt 1/10 2016-11-03 12:45:07,153 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/projects/cedar/ba6a7158ef89?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-11-03 12:45:08,432 unpacking tar archive at: cedar-ba6a7158ef89/testing/mozharness/ program finished with exit code 0 elapsedTime=1.532815 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-11-03 12:45:08.632897) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-03 12:45:08.633200) ========= script_repo_revision: ba6a7158ef89e9ed080398f0638fea585d085afa ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-03 12:45:08.633570) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-03 12:45:08.633884) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-03 12:45:08.653196) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-11-03 12:45:08.653621) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Cedar --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Cedar in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Cedar', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Cedar'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.4xRQwWsw7D/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.4kiiQkIpdd/Listeners TMPDIR=/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/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 12:45:08 INFO - MultiFileLogger online at 20161103 12:45:08 in /builds/slave/test 12:45:08 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Cedar --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Cedar 12:45:08 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 12:45:08 INFO - {'append_to_log': False, 12:45:08 INFO - 'base_work_dir': '/builds/slave/test', 12:45:08 INFO - 'blob_upload_branch': 'Cedar', 12:45:08 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 12:45:08 INFO - 'branch': 'Cedar', 12:45:08 INFO - 'buildbot_json_path': 'buildprops.json', 12:45:08 INFO - 'config_files': ('talos/mac_config.py',), 12:45:08 INFO - 'default_actions': ('clobber', 12:45:08 INFO - 'read-buildbot-config', 12:45:08 INFO - 'download-and-extract', 12:45:08 INFO - 'populate-webroot', 12:45:08 INFO - 'create-virtualenv', 12:45:08 INFO - 'install', 12:45:08 INFO - 'run-tests'), 12:45:08 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 12:45:08 INFO - 'download_minidump_stackwalk': True, 12:45:08 INFO - 'download_symbols': 'ondemand', 12:45:08 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 12:45:08 INFO - 'tooltool.py': '/tools/tooltool.py', 12:45:08 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 12:45:08 INFO - '/tools/misc-python/virtualenv.py')}, 12:45:08 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 12:45:08 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 12:45:08 INFO - 'installer_path': 'installer.exe', 12:45:08 INFO - 'log_level': 'info', 12:45:08 INFO - 'log_name': 'talos', 12:45:08 INFO - 'log_to_console': True, 12:45:08 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 12:45:08 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 12:45:08 INFO - 'opt_config_files': (), 12:45:08 INFO - 'pip_index': False, 12:45:08 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 12:45:08 INFO - 'cmd': ('bash', 12:45:08 INFO - '-c', 12:45:08 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 12:45:08 INFO - 'enabled': True, 12:45:08 INFO - 'halt_on_failure': False, 12:45:08 INFO - 'name': 'check_screen_resolution'},), 12:45:08 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 12:45:08 INFO - 'cmd': ('bash', 12:45:08 INFO - '-c', 12:45:08 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 12:45:08 INFO - 'enabled': True, 12:45:08 INFO - 'halt_on_failure': False, 12:45:08 INFO - 'name': 'check_screen_resolution'},), 12:45:08 INFO - 'run_cmd_checks_enabled': True, 12:45:08 INFO - 'sps_profile': False, 12:45:08 INFO - 'sps_profile_interval': 0, 12:45:08 INFO - 'suite': 'dromaeojs-e10s', 12:45:08 INFO - 'system_bits': '32', 12:45:08 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 12:45:08 INFO - 'title': 't-yosemite-r7-0092', 12:45:08 INFO - 'tooltool_cache': '/builds/tooltool_cache', 12:45:08 INFO - 'use_talos_json': True, 12:45:08 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 12:45:08 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 12:45:08 INFO - 'work_dir': 'build'} 12:45:08 INFO - [mozharness: 2016-11-03 19:45:08.857696Z] Running clobber step. 12:45:08 INFO - Running pre-action listener: _resource_record_pre_action 12:45:08 INFO - Running main action method: clobber 12:45:08 INFO - rmtree: /builds/slave/test/build 12:45:08 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 12:45:11 INFO - Running post-action listener: _resource_record_post_action 12:45:11 INFO - [mozharness: 2016-11-03 19:45:11.042325Z] Finished clobber step (success) 12:45:11 INFO - [mozharness: 2016-11-03 19:45:11.042520Z] Running read-buildbot-config step. 12:45:11 INFO - Running pre-action listener: _resource_record_pre_action 12:45:11 INFO - Running main action method: read_buildbot_config 12:45:11 INFO - Using buildbot properties: 12:45:11 INFO - { 12:45:11 INFO - "project": "", 12:45:11 INFO - "product": "firefox", 12:45:11 INFO - "script_repo_revision": "default", 12:45:11 INFO - "builddir": "cedar_yosemite_r7_test-dromaeojs-e10s", 12:45:11 INFO - "repository": "", 12:45:11 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 cedar talos dromaeojs-e10s", 12:45:11 INFO - "buildid": "20161103095420", 12:45:11 INFO - "pgo_build": "False", 12:45:11 INFO - "basedir": "/builds/slave/test", 12:45:11 INFO - "buildnumber": 0, 12:45:11 INFO - "slavename": "t-yosemite-r7-0092", 12:45:11 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 12:45:11 INFO - "platform": "yosemite_r7", 12:45:11 INFO - "scheduler": "tests-cedar-macosx64-talos", 12:45:11 INFO - "branch": "cedar", 12:45:11 INFO - "repo_path": "projects/cedar", 12:45:11 INFO - "revision": "ba6a7158ef89e9ed080398f0638fea585d085afa", 12:45:11 INFO - "stage_platform": "macosx64", 12:45:11 INFO - "builduid": "e18958d3c73e41148b6943619853a73d", 12:45:11 INFO - "slavebuilddir": "test" 12:45:11 INFO - } 12:45:11 INFO - Found installer url https://queue.taskcluster.net/v1/task/N4toZVw-SBayU-5p0x_HwQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg. 12:45:11 INFO - Running post-action listener: _resource_record_post_action 12:45:11 INFO - [mozharness: 2016-11-03 19:45:11.046048Z] Finished read-buildbot-config step (success) 12:45:11 INFO - [mozharness: 2016-11-03 19:45:11.046169Z] Running download-and-extract step. 12:45:11 INFO - Running pre-action listener: _resource_record_pre_action 12:45:11 INFO - Running main action method: download_and_extract 12:45:11 INFO - mkdir: /builds/slave/test/build/tests 12:45:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:45:11 INFO - trying https://queue.taskcluster.net/v1/task/N4toZVw-SBayU-5p0x_HwQ/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json 12:45:11 INFO - Downloading https://queue.taskcluster.net/v1/task/N4toZVw-SBayU-5p0x_HwQ/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 12:45:11 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/N4toZVw-SBayU-5p0x_HwQ/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 12:45:12 INFO - Downloaded 1199 bytes. 12:45:12 INFO - Reading from file /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 12:45:12 INFO - Using the following test package requirements: 12:45:12 INFO - {u'common': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 12:45:12 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 12:45:12 INFO - u'firefox-52.0a1.en-US.mac.cppunittest.tests.zip'], 12:45:12 INFO - u'gtest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 12:45:12 INFO - u'firefox-52.0a1.en-US.mac.gtest.tests.zip'], 12:45:12 INFO - u'jittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 12:45:12 INFO - u'jsshell-mac.zip'], 12:45:12 INFO - u'mochitest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 12:45:12 INFO - u'firefox-52.0a1.en-US.mac.mochitest.tests.zip'], 12:45:12 INFO - u'mozbase': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 12:45:12 INFO - u'reftest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 12:45:12 INFO - u'firefox-52.0a1.en-US.mac.reftest.tests.zip'], 12:45:12 INFO - u'talos': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 12:45:12 INFO - u'firefox-52.0a1.en-US.mac.talos.tests.zip'], 12:45:12 INFO - u'web-platform': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 12:45:12 INFO - u'firefox-52.0a1.en-US.mac.web-platform.tests.zip'], 12:45:12 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 12:45:12 INFO - u'firefox-52.0a1.en-US.mac.xpcshell.tests.zip']} 12:45:12 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip'] for test suite category: common 12:45:12 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/N4toZVw-SBayU-5p0x_HwQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 12:45:12 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/N4toZVw-SBayU-5p0x_HwQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 12:45:12 INFO - Fetch https://queue.taskcluster.net/v1/task/N4toZVw-SBayU-5p0x_HwQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 12:45:12 INFO - Http code: 200 12:45:12 INFO - via: 1.1 455cf6ccbccc261c46d02110de1c0237.cloudfront.net (CloudFront) 12:45:12 INFO - x-amz-cf-id: rzPGGwN2_fOfA74xDDKLADomgttii_CIE3ENxGJbjjzgxjpQPAtB8w== 12:45:12 INFO - x-amz-version-id: Gyj1OAOlskNw30OX5MUZ97f.sC6SDoVN 12:45:13 INFO - Expected file size: 16389155 12:45:13 INFO - Obtained file size: 16389155 12:45:20 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 12:45:20 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/N4toZVw-SBayU-5p0x_HwQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 12:45:20 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/N4toZVw-SBayU-5p0x_HwQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 12:45:20 INFO - Fetch https://queue.taskcluster.net/v1/task/N4toZVw-SBayU-5p0x_HwQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 12:45:21 INFO - Http code: 200 12:45:21 INFO - via: 1.1 b3bccc0e4c2cc0a33e7e8308d88cacdd.cloudfront.net (CloudFront) 12:45:21 INFO - x-amz-cf-id: qVDWnuCAY0PUPA1w-huASGa0ojOSe28470xWa9dkxpZalmoQDcy6Lw== 12:45:21 INFO - x-amz-version-id: Gyj1OAOlskNw30OX5MUZ97f.sC6SDoVN 12:45:21 INFO - Expected file size: 16389155 12:45:21 INFO - Obtained file size: 16389155 12:45:27 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/N4toZVw-SBayU-5p0x_HwQ/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip 12:45:27 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/N4toZVw-SBayU-5p0x_HwQ/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip'}, attempt #1 12:45:27 INFO - Fetch https://queue.taskcluster.net/v1/task/N4toZVw-SBayU-5p0x_HwQ/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip into memory 12:45:28 INFO - Http code: 200 12:45:28 INFO - via: 1.1 d8f42fc9558e3e49ebfdf8834baeb756.cloudfront.net (CloudFront) 12:45:28 INFO - x-amz-cf-id: UNy10qaia1r26BMkcJF49p_B1DrGPwiRzXRVJ2nc6tILUyMXQn35DQ== 12:45:28 INFO - x-amz-version-id: IIRP1bZowOWdH8.nML4VYRN_7v2VcHFG 12:45:28 INFO - Expected file size: 14409038 12:45:28 INFO - Obtained file size: 14409038 12:45:29 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')]} 12:45:29 INFO - trying https://queue.taskcluster.net/v1/task/N4toZVw-SBayU-5p0x_HwQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 12:45:29 INFO - Downloading https://queue.taskcluster.net/v1/task/N4toZVw-SBayU-5p0x_HwQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg 12:45:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/N4toZVw-SBayU-5p0x_HwQ/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 12:45:32 INFO - Downloaded 111976199 bytes. 12:45:32 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/N4toZVw-SBayU-5p0x_HwQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 12:45:32 INFO - mkdir: /builds/slave/test/properties 12:45:32 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 12:45:32 INFO - Writing to file /builds/slave/test/properties/build_url 12:45:32 INFO - Contents: 12:45:32 INFO - build_url:https://queue.taskcluster.net/v1/task/N4toZVw-SBayU-5p0x_HwQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 12:45:33 INFO - Running post-action listener: _resource_record_post_action 12:45:33 INFO - Running post-action listener: set_extra_try_arguments 12:45:33 INFO - [mozharness: 2016-11-03 19:45:33.277091Z] Finished download-and-extract step (success) 12:45:33 INFO - [mozharness: 2016-11-03 19:45:33.277220Z] Running populate-webroot step. 12:45:33 INFO - Running pre-action listener: _resource_record_pre_action 12:45:33 INFO - Running main action method: populate_webroot 12:45:33 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 12:45:33 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 12:45:33 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 12:45:33 INFO - '--tppagecycles', 12:45:33 INFO - '7'], 12:45:33 INFO - 'tests': ['tsvgm']}}, 12:45:33 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 12:45:33 INFO - 'tests': ['tresize', 'tcanvasmark']}, 12:45:33 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 12:45:33 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 12:45:33 INFO - 'tests': ['dromaeo_css', 'kraken']}, 12:45:33 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 12:45:33 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:45:33 INFO - 'talos_options': ['--disable-e10s'], 12:45:33 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:45:33 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:45:33 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:45:33 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:45:33 INFO - 'talos_options': ['--disable-e10s'], 12:45:33 INFO - 'tests': ['damp', 'tps']}, 12:45:33 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:45:33 INFO - 'tests': ['damp', 'tps']}, 12:45:33 INFO - 'g3': {'talos_options': ['--disable-e10s'], 12:45:33 INFO - 'tests': ['dromaeo_dom']}, 12:45:33 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 12:45:33 INFO - 'g4': {'talos_options': ['--disable-e10s'], 12:45:33 INFO - 'tests': ['basic_compositor_video']}, 12:45:33 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 12:45:33 INFO - 'other': {'talos_options': ['--disable-e10s'], 12:45:33 INFO - 'tests': ['a11yr', 12:45:33 INFO - 'ts_paint', 12:45:33 INFO - 'tpaint', 12:45:33 INFO - 'sessionrestore', 12:45:33 INFO - 'sessionrestore_no_auto_restore', 12:45:33 INFO - 'tabpaint']}, 12:45:33 INFO - 'other-e10s': {'tests': ['a11yr', 12:45:33 INFO - 'ts_paint', 12:45:33 INFO - 'tpaint', 12:45:33 INFO - 'sessionrestore', 12:45:33 INFO - 'sessionrestore_no_auto_restore', 12:45:33 INFO - 'tabpaint']}, 12:45:33 INFO - 'other-e10s_l64': {'tests': ['a11yr', 12:45:33 INFO - 'ts_paint', 12:45:33 INFO - 'tpaint', 12:45:33 INFO - 'sessionrestore', 12:45:33 INFO - 'sessionrestore_no_auto_restore', 12:45:33 INFO - 'tabpaint']}, 12:45:33 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 12:45:33 INFO - 'ts_paint', 12:45:33 INFO - 'tpaint', 12:45:33 INFO - 'sessionrestore', 12:45:33 INFO - 'sessionrestore_no_auto_restore', 12:45:33 INFO - 'tabpaint']}, 12:45:33 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 12:45:33 INFO - 'tests': ['a11yr', 12:45:33 INFO - 'ts_paint', 12:45:33 INFO - 'tpaint', 12:45:33 INFO - 'sessionrestore', 12:45:33 INFO - 'sessionrestore_no_auto_restore', 12:45:33 INFO - 'tabpaint']}, 12:45:33 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 12:45:33 INFO - 'tests': ['a11yr', 12:45:33 INFO - 'ts_paint', 12:45:33 INFO - 'tpaint', 12:45:33 INFO - 'sessionrestore', 12:45:33 INFO - 'sessionrestore_no_auto_restore', 12:45:33 INFO - 'tabpaint']}, 12:45:33 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 12:45:33 INFO - 'tests': ['tsvgx', 12:45:33 INFO - 'tsvgr_opacity', 12:45:33 INFO - 'tart', 12:45:33 INFO - 'tscrollx', 12:45:33 INFO - 'cart']}, 12:45:33 INFO - 'svgr-e10s': {'tests': ['tsvgx', 12:45:33 INFO - 'tsvgr_opacity', 12:45:33 INFO - 'tart', 12:45:33 INFO - 'tscrollx', 12:45:33 INFO - 'cart']}, 12:45:33 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:45:33 INFO - 'talos_options': ['--disable-e10s'], 12:45:33 INFO - 'tests': ['tp5o']}, 12:45:33 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:45:33 INFO - 'tests': ['tp5o']}, 12:45:33 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:45:33 INFO - 'talos_options': ['--disable-e10s', 12:45:33 INFO - '--xperf_path', 12:45:33 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:45:33 INFO - 'tests': ['tp5n']}, 12:45:33 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:45:33 INFO - 'talos_options': ['--xperf_path', 12:45:33 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:45:33 INFO - 'tests': ['tp5n']}}, 12:45:33 INFO - 'talos.zip': {'path': '', 12:45:33 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 12:45:33 INFO - Running post-action listener: _resource_record_post_action 12:45:33 INFO - [mozharness: 2016-11-03 19:45:33.295953Z] Finished populate-webroot step (success) 12:45:33 INFO - [mozharness: 2016-11-03 19:45:33.296066Z] Running create-virtualenv step. 12:45:33 INFO - Running pre-action listener: _resource_record_pre_action 12:45:33 INFO - Running main action method: create_virtualenv 12:45:33 INFO - Creating virtualenv /builds/slave/test/build/venv 12:45:33 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 12:45:33 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 12:45:33 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 12:45:33 INFO - Using real prefix '/tools/python27' 12:45:33 INFO - New python executable in /builds/slave/test/build/venv/bin/python 12:45:34 INFO - Installing distribute.............................................................................................................................................................................................done. 12:45:36 INFO - Installing pip.................done. 12:45:36 INFO - Return code: 0 12:45:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 12:45:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 12:45:37 INFO - Reading from file tmpfile_stdout 12:45:37 INFO - Output received: 12:45:37 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) 12:45:37 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 12:45:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:45:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:45:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:45:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:45:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:45:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:45:37 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 0x10a38c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a302570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdb31c86370>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a1a0d30>, '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 0x109fc6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a198b20>, '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.4xRQwWsw7D/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.4kiiQkIpdd/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/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 12:45:37 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 12:45:37 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 12:45:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4xRQwWsw7D/Render', 12:45:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:45:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:45:37 INFO - 'HOME': '/Users/cltbld', 12:45:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:45:37 INFO - 'LOGNAME': 'cltbld', 12:45:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:45:37 INFO - 'MOZ_NO_REMOTE': '1', 12:45:37 INFO - 'NO_EM_RESTART': '1', 12:45:37 INFO - 'PAGER': '/bin/cat', 12:45:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:45:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:45:37 INFO - 'PWD': '/builds/slave/test', 12:45:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:45:37 INFO - 'SHELL': '/bin/bash', 12:45:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4kiiQkIpdd/Listeners', 12:45:37 INFO - 'TMPDIR': '/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/', 12:45:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:45:37 INFO - 'USER': 'cltbld', 12:45:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:45:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:45:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:45:37 INFO - 'XPC_FLAGS': '0x0', 12:45:37 INFO - 'XPC_SERVICE_NAME': '0', 12:45:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:45:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:45:37 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 12:45:37 INFO - Cleaning up... 12:45:37 INFO - Return code: 0 12:45:37 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 12:45:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:45:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:45:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:45:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:45:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:45:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:45:37 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 0x10a38c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a302570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdb31c86370>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a1a0d30>, '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 0x109fc6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a198b20>, '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.4xRQwWsw7D/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.4kiiQkIpdd/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/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 12:45:37 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 12:45:37 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 12:45:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4xRQwWsw7D/Render', 12:45:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:45:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:45:37 INFO - 'HOME': '/Users/cltbld', 12:45:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:45:37 INFO - 'LOGNAME': 'cltbld', 12:45:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:45:37 INFO - 'MOZ_NO_REMOTE': '1', 12:45:37 INFO - 'NO_EM_RESTART': '1', 12:45:37 INFO - 'PAGER': '/bin/cat', 12:45:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:45:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:45:37 INFO - 'PWD': '/builds/slave/test', 12:45:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:45:37 INFO - 'SHELL': '/bin/bash', 12:45:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4kiiQkIpdd/Listeners', 12:45:37 INFO - 'TMPDIR': '/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/', 12:45:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:45:37 INFO - 'USER': 'cltbld', 12:45:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:45:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:45:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:45:37 INFO - 'XPC_FLAGS': '0x0', 12:45:37 INFO - 'XPC_SERVICE_NAME': '0', 12:45:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:45:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:45:37 INFO - Downloading/unpacking psutil>=3.1.1 12:45:37 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 12:45:37 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 12:45:37 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 12:45:37 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 12:45:37 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 12:45:37 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 12:45:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 12:45:41 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:45:41 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:45:41 INFO - Installing collected packages: psutil 12:45:41 INFO - Running setup.py install for psutil 12:45:41 INFO - building 'psutil._psutil_osx' extension 12:45:41 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 12:45:41 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 12:45:41 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 12:45:42 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 12:45:42 INFO - building 'psutil._psutil_posix' extension 12:45:42 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 12:45:42 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 12:45:42 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 12:45:42 INFO - ^ 12:45:42 INFO - 1 warning generated. 12:45:42 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 12:45:42 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:45:42 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:45:42 INFO - Successfully installed psutil 12:45:42 INFO - Cleaning up... 12:45:42 INFO - Return code: 0 12:45:42 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 12:45: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')]} 12:45:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:45:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:45: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')]} 12:45:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:45:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:45: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', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10a38c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a302570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdb31c86370>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a1a0d30>, '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 0x109fc6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a198b20>, '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.4xRQwWsw7D/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.4kiiQkIpdd/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/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 12:45: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', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 12:45: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 mozsystemmonitor==0.3 12:45:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4xRQwWsw7D/Render', 12:45:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:45:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:45:42 INFO - 'HOME': '/Users/cltbld', 12:45:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:45:42 INFO - 'LOGNAME': 'cltbld', 12:45:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:45:42 INFO - 'MOZ_NO_REMOTE': '1', 12:45:42 INFO - 'NO_EM_RESTART': '1', 12:45:42 INFO - 'PAGER': '/bin/cat', 12:45:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:45:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:45:42 INFO - 'PWD': '/builds/slave/test', 12:45:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:45:42 INFO - 'SHELL': '/bin/bash', 12:45:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4kiiQkIpdd/Listeners', 12:45:42 INFO - 'TMPDIR': '/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/', 12:45:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:45:42 INFO - 'USER': 'cltbld', 12:45:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:45:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:45:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:45:42 INFO - 'XPC_FLAGS': '0x0', 12:45:42 INFO - 'XPC_SERVICE_NAME': '0', 12:45:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:45:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:45:42 INFO - Downloading/unpacking mozsystemmonitor==0.3 12:45: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 12:45: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 12:45: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 12:45: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 12:45: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 12:45: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 12:45:45 INFO - Downloading mozsystemmonitor-0.3.tar.gz 12:45:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 12:45:45 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 12:45:45 INFO - Installing collected packages: mozsystemmonitor 12:45:45 INFO - Running setup.py install for mozsystemmonitor 12:45:45 INFO - Successfully installed mozsystemmonitor 12:45:45 INFO - Cleaning up... 12:45:45 INFO - Return code: 0 12:45:45 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 12:45:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:45:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:45:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:45:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:45:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:45:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:45:45 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10a38c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a302570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdb31c86370>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a1a0d30>, '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 0x109fc6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a198b20>, '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.4xRQwWsw7D/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.4kiiQkIpdd/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/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 12:45:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 12:45:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 12:45:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4xRQwWsw7D/Render', 12:45:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:45:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:45:45 INFO - 'HOME': '/Users/cltbld', 12:45:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:45:45 INFO - 'LOGNAME': 'cltbld', 12:45:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:45:45 INFO - 'MOZ_NO_REMOTE': '1', 12:45:45 INFO - 'NO_EM_RESTART': '1', 12:45:45 INFO - 'PAGER': '/bin/cat', 12:45:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:45:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:45:45 INFO - 'PWD': '/builds/slave/test', 12:45:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:45:45 INFO - 'SHELL': '/bin/bash', 12:45:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4kiiQkIpdd/Listeners', 12:45:45 INFO - 'TMPDIR': '/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/', 12:45:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:45:45 INFO - 'USER': 'cltbld', 12:45:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:45:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:45:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:45:45 INFO - 'XPC_FLAGS': '0x0', 12:45:45 INFO - 'XPC_SERVICE_NAME': '0', 12:45:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:45:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:45:45 INFO - Downloading/unpacking jsonschema==2.5.1 12:45: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 12:45: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 12:45: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 12:45: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 12:45: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 12:45: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 12:45:48 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 12:45:48 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 12:45:48 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 12:45:48 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 12:45:48 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 12:45:48 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 12:45:48 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 12:45:48 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 12:45:48 INFO - Downloading functools32-3.2.3-2.tar.gz 12:45:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 12:45:48 INFO - warning: no files found matching '*.txt' 12:45:48 INFO - no previously-included directories found matching 'build' 12:45:48 INFO - no previously-included directories found matching 'dist' 12:45:48 INFO - no previously-included directories found matching '.git*' 12:45:48 INFO - Installing collected packages: jsonschema, functools32 12:45:48 INFO - Running setup.py install for functools32 12:45:48 INFO - warning: no files found matching '*.txt' 12:45:48 INFO - no previously-included directories found matching 'build' 12:45:48 INFO - no previously-included directories found matching 'dist' 12:45:48 INFO - no previously-included directories found matching '.git*' 12:45:48 INFO - Successfully installed jsonschema functools32 12:45:48 INFO - Cleaning up... 12:45:48 INFO - Return code: 0 12:45:48 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 12:45:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:45:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:45:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:45:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:45:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:45:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:45:48 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10a38c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a302570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdb31c86370>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a1a0d30>, '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 0x109fc6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a198b20>, '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.4xRQwWsw7D/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.4kiiQkIpdd/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/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 12:45:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 12:45:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 12:45:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4xRQwWsw7D/Render', 12:45:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:45:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:45:48 INFO - 'HOME': '/Users/cltbld', 12:45:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:45:48 INFO - 'LOGNAME': 'cltbld', 12:45:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:45:48 INFO - 'MOZ_NO_REMOTE': '1', 12:45:48 INFO - 'NO_EM_RESTART': '1', 12:45:48 INFO - 'PAGER': '/bin/cat', 12:45:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:45:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:45:48 INFO - 'PWD': '/builds/slave/test', 12:45:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:45:48 INFO - 'SHELL': '/bin/bash', 12:45:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4kiiQkIpdd/Listeners', 12:45:48 INFO - 'TMPDIR': '/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/', 12:45:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:45:48 INFO - 'USER': 'cltbld', 12:45:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:45:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:45:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:45:48 INFO - 'XPC_FLAGS': '0x0', 12:45:48 INFO - 'XPC_SERVICE_NAME': '0', 12:45:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:45:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:45:49 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 12:45:49 INFO - Cleaning up... 12:45:49 INFO - Return code: 0 12:45:49 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 12:45:49 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')]} 12:45:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:45:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:45:49 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')]} 12:45:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:45:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:45:49 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 0x10a38c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a302570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdb31c86370>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a1a0d30>, '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 0x109fc6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a198b20>, '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.4xRQwWsw7D/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.4kiiQkIpdd/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/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 12:45:49 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 12:45:49 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 12:45:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4xRQwWsw7D/Render', 12:45:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:45:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:45:49 INFO - 'HOME': '/Users/cltbld', 12:45:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:45:49 INFO - 'LOGNAME': 'cltbld', 12:45:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:45:49 INFO - 'MOZ_NO_REMOTE': '1', 12:45:49 INFO - 'NO_EM_RESTART': '1', 12:45:49 INFO - 'PAGER': '/bin/cat', 12:45:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:45:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:45:49 INFO - 'PWD': '/builds/slave/test', 12:45:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:45:49 INFO - 'SHELL': '/bin/bash', 12:45:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4kiiQkIpdd/Listeners', 12:45:49 INFO - 'TMPDIR': '/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/', 12:45:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:45:49 INFO - 'USER': 'cltbld', 12:45:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:45:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:45:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:45:49 INFO - 'XPC_FLAGS': '0x0', 12:45:49 INFO - 'XPC_SERVICE_NAME': '0', 12:45:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:45:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:45:49 INFO - Downloading/unpacking blobuploader==1.2.4 12:45: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 12:45: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 12:45: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 12:45: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 12:45: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 12:45: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 12:45:51 INFO - Downloading blobuploader-1.2.4.tar.gz 12:45:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 12:45:52 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 12:45:52 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 12:45:52 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 12:45:52 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 12:45:52 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 12:45:52 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 12:45:52 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 12:45:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 12:45:52 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 12:45:52 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 12:45:52 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 12:45:52 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 12:45:52 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 12:45:52 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 12:45:52 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 12:45:53 INFO - Downloading docopt-0.6.1.tar.gz 12:45:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 12:45:53 INFO - Installing collected packages: blobuploader, requests, docopt 12:45:53 INFO - Running setup.py install for blobuploader 12:45:53 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 12:45:53 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 12:45:53 INFO - Running setup.py install for requests 12:45:53 INFO - Running setup.py install for docopt 12:45:54 INFO - Successfully installed blobuploader requests docopt 12:45:54 INFO - Cleaning up... 12:45:54 INFO - Return code: 0 12:45:54 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:45:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:45:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:45:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:45:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:45:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:45:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:45:54 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 0x10a38c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a302570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdb31c86370>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a1a0d30>, '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 0x109fc6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a198b20>, '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.4xRQwWsw7D/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.4kiiQkIpdd/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/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 12:45:54 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 12:45:54 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 12:45:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4xRQwWsw7D/Render', 12:45:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:45:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:45:54 INFO - 'HOME': '/Users/cltbld', 12:45:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:45:54 INFO - 'LOGNAME': 'cltbld', 12:45:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:45:54 INFO - 'MOZ_NO_REMOTE': '1', 12:45:54 INFO - 'NO_EM_RESTART': '1', 12:45:54 INFO - 'PAGER': '/bin/cat', 12:45:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:45:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:45:54 INFO - 'PWD': '/builds/slave/test', 12:45:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:45:54 INFO - 'SHELL': '/bin/bash', 12:45:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4kiiQkIpdd/Listeners', 12:45:54 INFO - 'TMPDIR': '/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/', 12:45:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:45:54 INFO - 'USER': 'cltbld', 12:45:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:45:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:45:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:45:54 INFO - 'XPC_FLAGS': '0x0', 12:45:54 INFO - 'XPC_SERVICE_NAME': '0', 12:45:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:45:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:45:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:45:54 INFO - Running setup.py (path:/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/pip-oC4jH4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:45:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:45:54 INFO - Running setup.py (path:/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/pip-09XBJt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:45:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:45:54 INFO - Running setup.py (path:/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/pip-Gbxtq8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:45:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:45:54 INFO - Running setup.py (path:/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/pip-rCAkpn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:45:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:45:54 INFO - Running setup.py (path:/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/pip-x25DJA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:45:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:45:54 INFO - Running setup.py (path:/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/pip-uw1rko-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:45:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:45:55 INFO - Running setup.py (path:/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/pip-bSQqbn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:45:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:45:55 INFO - Running setup.py (path:/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/pip-EwhSvR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:45:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:45:55 INFO - Running setup.py (path:/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/pip-XutaOI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:45:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:45:55 INFO - Running setup.py (path:/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/pip-zUp3bB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:45:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:45:55 INFO - Running setup.py (path:/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/pip-TsiVrL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:45:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:45:55 INFO - Running setup.py (path:/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/pip-8VRKAK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:45:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:45:55 INFO - Running setup.py (path:/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/pip-fY1_ej-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:45:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:45:55 INFO - Running setup.py (path:/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/pip-6WDKMt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:45:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:45:55 INFO - Running setup.py (path:/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/pip-umZdSc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:45:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:45:56 INFO - Running setup.py (path:/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/pip-lxUktq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:45:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:45:56 INFO - Running setup.py (path:/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/pip-pPzuXL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:45:56 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 12:45:56 INFO - Running setup.py install for manifestparser 12:45:56 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 12:45:56 INFO - Running setup.py install for mozcrash 12:45:56 INFO - Running setup.py install for mozdebug 12:45:56 INFO - Running setup.py install for mozdevice 12:45:56 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 12:45:56 INFO - Installing dm script to /builds/slave/test/build/venv/bin 12:45:56 INFO - Running setup.py install for mozfile 12:45:56 INFO - Running setup.py install for mozhttpd 12:45:57 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 12:45:57 INFO - Running setup.py install for mozinfo 12:45:57 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 12:45:57 INFO - Running setup.py install for mozInstall 12:45:57 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 12:45:57 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 12:45:57 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 12:45:57 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 12:45:57 INFO - Running setup.py install for mozleak 12:45:57 INFO - Running setup.py install for mozlog 12:45:57 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 12:45:57 INFO - Running setup.py install for moznetwork 12:45:57 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 12:45:57 INFO - Running setup.py install for mozprocess 12:45:58 INFO - Running setup.py install for mozprofile 12:45:58 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 12:45:58 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 12:45:58 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 12:45:58 INFO - Running setup.py install for mozrunner 12:45:58 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 12:45:58 INFO - Running setup.py install for mozscreenshot 12:45:58 INFO - Running setup.py install for moztest 12:45:58 INFO - Running setup.py install for mozversion 12:45:58 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 12:45:58 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 12:45:58 INFO - Cleaning up... 12:45:58 INFO - Return code: 0 12:45:58 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:45:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:45:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:45:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:45:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:45:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:45:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:45:58 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 0x10a38c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a302570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdb31c86370>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a1a0d30>, '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 0x109fc6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a198b20>, '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.4xRQwWsw7D/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.4kiiQkIpdd/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/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 12:45:58 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 12:45:58 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 12:45:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4xRQwWsw7D/Render', 12:45:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:45:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:45:58 INFO - 'HOME': '/Users/cltbld', 12:45:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:45:58 INFO - 'LOGNAME': 'cltbld', 12:45:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:45:58 INFO - 'MOZ_NO_REMOTE': '1', 12:45:58 INFO - 'NO_EM_RESTART': '1', 12:45:58 INFO - 'PAGER': '/bin/cat', 12:45:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:45:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:45:58 INFO - 'PWD': '/builds/slave/test', 12:45:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:45:58 INFO - 'SHELL': '/bin/bash', 12:45:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4kiiQkIpdd/Listeners', 12:45:58 INFO - 'TMPDIR': '/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/', 12:45:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:45:58 INFO - 'USER': 'cltbld', 12:45:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:45:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:45:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:45:58 INFO - 'XPC_FLAGS': '0x0', 12:45:58 INFO - 'XPC_SERVICE_NAME': '0', 12:45:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:45:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:45:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:45:59 INFO - Running setup.py (path:/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/pip-P6MUf2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:45:59 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)) 12:45:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:45:59 INFO - Running setup.py (path:/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/pip-VUaA4x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:45:59 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)) 12:45:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:45:59 INFO - Running setup.py (path:/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/pip-fQdUDI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:45:59 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)) 12:45:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:45:59 INFO - Running setup.py (path:/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/pip-kwYdnV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:45:59 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)) 12:45:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:45:59 INFO - Running setup.py (path:/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/pip-Qduqzc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:45:59 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)) 12:45:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:45:59 INFO - Running setup.py (path:/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/pip-67727A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:45:59 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)) 12:45:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:45:59 INFO - Running setup.py (path:/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/pip-3JJIJf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:45:59 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)) 12:45:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:45:59 INFO - Running setup.py (path:/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/pip-JGpoFR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:45:59 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)) 12:45:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:45:59 INFO - Running setup.py (path:/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/pip-xRQoM6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:46:00 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)) 12:46:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:46:00 INFO - Running setup.py (path:/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/pip-LiKU__-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:46:00 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)) 12:46:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:46:00 INFO - Running setup.py (path:/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/pip-gunfHd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:46:00 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)) 12:46:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:46:00 INFO - Running setup.py (path:/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/pip-_5AsLs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:46:00 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)) 12:46:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:46:00 INFO - Running setup.py (path:/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/pip-C287CC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:46:00 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)) 12:46:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:46:00 INFO - Running setup.py (path:/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/pip-rqet6K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:46:00 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)) 12:46:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:46:00 INFO - Running setup.py (path:/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/pip-K2kjgi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:46:00 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)) 12:46:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:46:00 INFO - Running setup.py (path:/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/pip-u7r4kv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:46:00 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)) 12:46:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:46:00 INFO - Running setup.py (path:/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/pip-JowM7g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:46:00 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)) 12:46:00 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)) 12:46:00 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)) 12:46:00 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)) 12:46:00 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)) 12:46:00 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)) 12:46:00 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 12:46:00 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 12:46:00 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 12:46:00 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 12:46:00 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 12:46:00 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 12:46:00 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 12:46:03 INFO - Downloading blessings-1.6.tar.gz 12:46:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 12:46:03 INFO - Installing collected packages: blessings 12:46:03 INFO - Running setup.py install for blessings 12:46:03 INFO - Successfully installed blessings 12:46:03 INFO - Cleaning up... 12:46:03 INFO - Return code: 0 12:46:03 INFO - Done creating virtualenv /builds/slave/test/build/venv. 12:46:03 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:46:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:46:04 INFO - Reading from file tmpfile_stdout 12:46:04 INFO - Current package versions: 12:46:04 INFO - blessings == 1.6 12:46:04 INFO - blobuploader == 1.2.4 12:46:04 INFO - docopt == 0.6.1 12:46:04 INFO - functools32 == 3.2.3-2 12:46:04 INFO - jsonschema == 2.5.1 12:46:04 INFO - manifestparser == 1.1 12:46:04 INFO - mozInstall == 1.12 12:46:04 INFO - mozcrash == 0.17 12:46:04 INFO - mozdebug == 0.1 12:46:04 INFO - mozdevice == 0.48 12:46:04 INFO - mozfile == 1.2 12:46:04 INFO - mozhttpd == 0.7 12:46:04 INFO - mozinfo == 0.9 12:46:04 INFO - mozleak == 0.1 12:46:04 INFO - mozlog == 3.4 12:46:04 INFO - moznetwork == 0.27 12:46:04 INFO - mozprocess == 0.23 12:46:04 INFO - mozprofile == 0.28 12:46:04 INFO - mozrunner == 6.12 12:46:04 INFO - mozscreenshot == 0.1 12:46:04 INFO - mozsystemmonitor == 0.3 12:46:04 INFO - moztest == 0.7 12:46:04 INFO - mozversion == 1.4 12:46:04 INFO - psutil == 3.1.1 12:46:04 INFO - requests == 1.2.3 12:46:04 INFO - wsgiref == 0.1.2 12:46:04 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:46:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:46:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:46:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:46:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:46:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:46:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:46:04 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 0x10a38c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a302570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdb31c86370>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a1a0d30>, '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 0x109fc6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a198b20>, '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.4xRQwWsw7D/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.4kiiQkIpdd/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/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 12:46:04 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 12:46:04 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 12:46:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4xRQwWsw7D/Render', 12:46:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:46:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:46:04 INFO - 'HOME': '/Users/cltbld', 12:46:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:46:04 INFO - 'LOGNAME': 'cltbld', 12:46:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:46:04 INFO - 'MOZ_NO_REMOTE': '1', 12:46:04 INFO - 'NO_EM_RESTART': '1', 12:46:04 INFO - 'PAGER': '/bin/cat', 12:46:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:46:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:46:04 INFO - 'PWD': '/builds/slave/test', 12:46:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:46:04 INFO - 'SHELL': '/bin/bash', 12:46:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4kiiQkIpdd/Listeners', 12:46:04 INFO - 'TMPDIR': '/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/', 12:46:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:46:04 INFO - 'USER': 'cltbld', 12:46:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:46:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:46:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:46:04 INFO - 'XPC_FLAGS': '0x0', 12:46:04 INFO - 'XPC_SERVICE_NAME': '0', 12:46:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:46:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:46:04 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)) 12:46:04 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)) 12:46:04 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)) 12:46:04 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)) 12:46:04 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)) 12:46:04 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)) 12:46:04 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)) 12:46:04 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)) 12:46:04 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)) 12:46:04 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)) 12:46:04 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)) 12:46:04 INFO - Cleaning up... 12:46:04 INFO - Return code: 0 12:46:04 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 12:46:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:46:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:46:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:46:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:46:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:46:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:46:04 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10a38c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a302570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdb31c86370>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a1a0d30>, '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 0x109fc6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a198b20>, '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.4xRQwWsw7D/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.4kiiQkIpdd/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/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 12:46:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 12:46:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 12:46:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4xRQwWsw7D/Render', 12:46:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:46:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:46:04 INFO - 'HOME': '/Users/cltbld', 12:46:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:46:04 INFO - 'LOGNAME': 'cltbld', 12:46:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:46:04 INFO - 'MOZ_NO_REMOTE': '1', 12:46:04 INFO - 'NO_EM_RESTART': '1', 12:46:04 INFO - 'PAGER': '/bin/cat', 12:46:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:46:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:46:04 INFO - 'PWD': '/builds/slave/test', 12:46:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:46:04 INFO - 'SHELL': '/bin/bash', 12:46:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4kiiQkIpdd/Listeners', 12:46:04 INFO - 'TMPDIR': '/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/', 12:46:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:46:04 INFO - 'USER': 'cltbld', 12:46:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:46:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:46:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:46:04 INFO - 'XPC_FLAGS': '0x0', 12:46:04 INFO - 'XPC_SERVICE_NAME': '0', 12:46:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:46:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:46:04 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 12:46:04 INFO - Cleaning up... 12:46:04 INFO - Return code: 0 12:46:04 INFO - Running post-action listener: _resource_record_post_action 12:46:04 INFO - Running post-action listener: _start_resource_monitoring 12:46:04 INFO - Starting resource monitoring. 12:46:04 INFO - [mozharness: 2016-11-03 19:46:04.635095Z] Finished create-virtualenv step (success) 12:46:04 INFO - [mozharness: 2016-11-03 19:46:04.636120Z] Running install step. 12:46:04 INFO - Running pre-action listener: _resource_record_pre_action 12:46:04 INFO - Running main action method: install 12:46:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:46:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:46:04 INFO - Reading from file tmpfile_stdout 12:46:04 INFO - Detecting whether we're running mozinstall >=1.0... 12:46:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 12:46:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 12:46:04 INFO - Reading from file tmpfile_stdout 12:46:04 INFO - Output received: 12:46:04 INFO - Usage: mozinstall [options] installer 12:46:04 INFO - Options: 12:46:04 INFO - -h, --help show this help message and exit 12:46:04 INFO - -d DEST, --destination=DEST 12:46:04 INFO - Directory to install application into. [default: 12:46:04 INFO - "/builds/slave/test"] 12:46:04 INFO - --app=APP Application being installed. [default: firefox] 12:46:04 INFO - mkdir: /builds/slave/test/build/application 12:46:04 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'] 12:46:04 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 12:46:38 INFO - Reading from file tmpfile_stdout 12:46:38 INFO - Output received: 12:46:38 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 12:46:38 INFO - Running post-action listener: _resource_record_post_action 12:46:38 INFO - [mozharness: 2016-11-03 19:46:38.916753Z] Finished install step (success) 12:46:38 INFO - [mozharness: 2016-11-03 19:46:38.916893Z] Running run-tests step. 12:46:38 INFO - Running pre-action listener: _resource_record_pre_action 12:46:38 INFO - Running main action method: run_tests 12:46:38 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 12:46:38 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 12:46:38 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 12:46:38 INFO - 2016-11-03 12:46:38.931 screenresolution[1921:18573] starting screenresolution argv=screenresolution get 12:46:38 INFO - 2016-11-03 12:46:38.934 screenresolution[1921:18573] Display 0: 1600x1200x32@60 12:46:38 INFO - 2016-11-03 12:46:38.939 screenresolution[1922:18575] starting screenresolution argv=screenresolution list 12:46:38 INFO - Available Modes on Display 0 12:46:38 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 12:46:38 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 12:46:38 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 12:46:38 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 12:46:39 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 12:46:39 INFO - Intel Iris: 12:46:39 INFO - Chipset Model: Intel Iris 12:46:39 INFO - Type: GPU 12:46:39 INFO - Bus: Built-In 12:46:39 INFO - VRAM (Dynamic, Max): 1536 MB 12:46:39 INFO - Vendor: Intel (0x8086) 12:46:39 INFO - Device ID: 0x0a2e 12:46:39 INFO - Revision ID: 0x0009 12:46:39 INFO - Displays: 12:46:39 INFO - Display: 12:46:39 INFO - Resolution: 1600 x 1200 @ 60 Hz 12:46:39 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 12:46:39 INFO - Display Serial Number: 1600x1200 60 12:46:39 INFO - Main Display: Yes 12:46:39 INFO - Mirror: Off 12:46:39 INFO - Online: Yes 12:46:39 INFO - Rotation: Supported 12:46:39 INFO - Return code: 0 12:46:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 12:46:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 12:46:39 INFO - Python 2.7.3 12:46:39 INFO - Return code: 0 12:46:39 INFO - grabbing minidump binary from tooltool 12:46: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')]} 12:46:39 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 0x10a1a0d30>, '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 0x109fc6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a198b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 12:46:39 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 12:46:39 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 12:46:39 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 12:46:39 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 12:46:39 INFO - Return code: 0 12:46:39 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 12:46:39 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 12:46:39 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 12:46:39 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 12:46:39 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 12:46:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Cedar', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/N4toZVw-SBayU-5p0x_HwQ/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0092', '--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 12:46:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Cedar --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/N4toZVw-SBayU-5p0x_HwQ/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0092 --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 12:46:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4xRQwWsw7D/Render', 12:46:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:46:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:46:39 INFO - 'HOME': '/Users/cltbld', 12:46:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:46:39 INFO - 'LOGNAME': 'cltbld', 12:46:39 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 12:46:39 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 12:46:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:46:39 INFO - 'MOZ_NO_REMOTE': '1', 12:46:39 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 12:46:39 INFO - 'NO_EM_RESTART': '1', 12:46:39 INFO - 'PAGER': '/bin/cat', 12:46:39 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:46:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:46:39 INFO - 'PWD': '/builds/slave/test', 12:46:39 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 12:46:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:46:39 INFO - 'SHELL': '/bin/bash', 12:46:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4kiiQkIpdd/Listeners', 12:46:39 INFO - 'TMPDIR': '/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/', 12:46:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:46:39 INFO - 'USER': 'cltbld', 12:46:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:46:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:46:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:46:39 INFO - 'XPC_FLAGS': '0x0', 12:46:39 INFO - 'XPC_SERVICE_NAME': '0', 12:46:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:46:39 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Cedar', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/N4toZVw-SBayU-5p0x_HwQ/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0092', '--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 12:46:39 INFO - mozversion application_buildid: 20161103095420 12:46:39 INFO - mozversion application_changeset: ba6a7158ef89e9ed080398f0638fea585d085afa 12:46:39 INFO - mozversion application_display_name: Nightly 12:46:39 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 12:46:39 INFO - mozversion application_name: Firefox 12:46:39 INFO - mozversion application_remotingname: firefox 12:46:39 INFO - mozversion application_repository: https://hg.mozilla.org/projects/cedar 12:46:39 INFO - mozversion application_vendor: Mozilla 12:46:39 INFO - mozversion application_version: 52.0a1 12:46:39 INFO - mozversion platform_buildid: 20161103095420 12:46:39 INFO - mozversion platform_changeset: ba6a7158ef89e9ed080398f0638fea585d085afa 12:46:39 INFO - mozversion platform_repository: https://hg.mozilla.org/projects/cedar 12:46:39 INFO - mozversion platform_version: 52.0a1 12:46:39 INFO - using testdate: 1478202399 12:46:39 INFO - actual date: 1478202399 12:46:39 INFO - starting webserver on 'localhost:56581' 12:46:39 INFO - SUITE-START | Running 2 tests 12:46:39 INFO - TEST-START | dromaeo_css 12:46:39 INFO - operating with platform_type : mac_ 12:46:39 INFO - Initialising browser for dromaeo_css test... 12:46:39 INFO - TEST-INFO | started process 1932 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/tmpmBJXSF/profile http://localhost:56581/getInfo.html) 12:46:42 INFO - PROCESS | 1932 | 2016-11-03 12:46:42.393 plugin-container[1934:18729] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8c3f, name = 'com.apple.tsm.portname' 12:46:42 INFO - PROCESS | 1932 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:46:42 INFO - PROCESS | 1932 | 2016-11-03 12:46:42.396 plugin-container[1934:18729] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x920b, name = 'com.apple.CFPasteboardClient' 12:46:42 INFO - PROCESS | 1932 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:46:42 INFO - PROCESS | 1932 | 2016-11-03 12:46:42.396 plugin-container[1934:18729] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 12:46:42 INFO - PROCESS | 1932 | __metrics Screen width/height:1600/1200 12:46:42 INFO - PROCESS | 1932 | colorDepth:24 12:46:42 INFO - PROCESS | 1932 | Browser inner width/height: 1024/689 12:46:42 INFO - PROCESS | 1932 | __metrics 12:46:42 ERROR - PROCESS | 1932 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3158: TypeError: this.tabs is undefined 12:46:43 INFO - TEST-INFO | 1932: exit 0 12:46:43 INFO - Browser initialized. 12:46:43 INFO - Running cycle 1/1 for dromaeo_css test... 12:46:43 INFO - TEST-INFO | started process 1935 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/tmpmBJXSF/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:46:45 INFO - PROCESS | 1935 | 2016-11-03 12:46:45.684 plugin-container[1936:18846] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x863f, name = 'com.apple.tsm.portname' 12:46:45 INFO - PROCESS | 1935 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:46:45 INFO - PROCESS | 1935 | 2016-11-03 12:46:45.685 plugin-container[1936:18846] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8a0b, name = 'com.apple.CFPasteboardClient' 12:46:45 INFO - PROCESS | 1935 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:46:45 INFO - PROCESS | 1935 | 2016-11-03 12:46:45.685 plugin-container[1936:18846] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 12:46:55 INFO - PROCESS | 1935 | RSS: Main: 239157248 12:46:55 INFO - PROCESS | 1935 | 12:47:44 INFO - PROCESS | 1935 | 1478202464846 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] 12:49:59 INFO - PROCESS | 1935 | Cycle 1(1): loaded http://localhost:56581/tests/dromaeo/cssquery-dojo.html (next: http://localhost:56581/tests/dromaeo/cssquery-ext.html) 12:50:00 INFO - PROCESS | 1935 | RSS: Main: 250437632 12:50:00 INFO - PROCESS | 1935 | 12:53:02 INFO - PROCESS | 1935 | Cycle 1(1): loaded http://localhost:56581/tests/dromaeo/cssquery-ext.html (next: http://localhost:56581/tests/dromaeo/cssquery-jquery.html) 12:53:03 INFO - PROCESS | 1935 | RSS: Main: 263184384 12:53:03 INFO - PROCESS | 1935 | 12:56:05 INFO - PROCESS | 1935 | Cycle 1(1): loaded http://localhost:56581/tests/dromaeo/cssquery-jquery.html (next: http://localhost:56581/tests/dromaeo/cssquery-mootools.html) 12:56:06 INFO - PROCESS | 1935 | RSS: Main: 267018240 12:56:06 INFO - PROCESS | 1935 | 12:59:08 INFO - PROCESS | 1935 | Cycle 1(1): loaded http://localhost:56581/tests/dromaeo/cssquery-mootools.html (next: http://localhost:56581/tests/dromaeo/cssquery-prototype.html) 12:59:08 INFO - PROCESS | 1935 | RSS: Main: 265801728 12:59:08 INFO - PROCESS | 1935 | 13:02:11 INFO - PROCESS | 1935 | Cycle 1(1): loaded http://localhost:56581/tests/dromaeo/cssquery-prototype.html (next: http://localhost:56581/tests/dromaeo/cssquery-yui.html) 13:02:12 INFO - PROCESS | 1935 | RSS: Main: 270635008 13:02:12 INFO - PROCESS | 1935 | 13:05:14 INFO - PROCESS | 1935 | Cycle 1(1): loaded http://localhost:56581/tests/dromaeo/cssquery-yui.html (next: http://localhost:56581/tests/dromaeo/cssquery-dojo.html) 13:05:15 INFO - PROCESS | 1935 | RSS: Main: 272412672 13:05:15 INFO - PROCESS | 1935 | 13:05:15 INFO - PROCESS | 1935 | __start_tp_report 13:05:15 INFO - PROCESS | 1935 | _x_x_mozilla_page_load 13:05:15 INFO - PROCESS | 1935 | _x_x_mozilla_page_load_details 13:05:15 INFO - PROCESS | 1935 | |i|pagename|runs| 13:05:15 INFO - PROCESS | 1935 | |0;dojo.html;3297.901062968112;3334.3331333733254;3344.849481773321;3358.0597432718846;3363.058343663772;5464.9453505464935;5531.778728850852;5549.639273447223;5554.1113421852515;5595.776168953237;4511.819527218916;5521.475459831309;5596.888062238752;5635.5773317001185;5643.181738647893;5049.495050494939;5100.464451232604;5141.228815677641;5174.793008279665;5200.843974680765;5042.596592272609;5188.066148093341;5191.091558977183;5225.137852254374;5226.346706661668;3997.9200415991663;4176.498820141587;5024.271480635324;5156.407013193493;5157;4995.875103122415;5049.242613607951;5059.569936555397;5107.412647545542;5148.639595228336;5006.199008158714;5048.545630893201;5048.949510504922;5110.361204849394;5118.616103792193;4412.691111622188;5046.1169295373165;5431.429699881495;5605.5515558755205;5626.184203290541;5945.702714864239;6075.33171351151;6090.725917333731;6092.847678808021;6102.938970610326;6137.508999280047;6155.859857019394;6181.103739957681;6187.226596675416;6212;5544.085225937756;5621.8594535136945;5680.4603562662305;5690.658560486384;5736.913946290809;5113.360829896263;6232.8130156095385;6242.687865606701;6260.029695397221;6298.055291706189;4895.412550493964;5966.671833049224;6026.849328766728;6039.876948849972;6074;5989.730462129215;6159.168512250789;6164.859500992331;6177.227846519185;6192.690365481798;5992.101184822312;5993.190919225936;5997.670128142907;6066.787662431793;6076.058210977306;6077.3314935357075;6105.358937311607;6109.2974307955155;6128.846778830476;6135.937063216329;4822.373091498082;4843.685160464559;4858.271259311061;4861.124997500484;4886.804527818847;4001.479807625048;4311.116221174651;4326.221280169537;4356.629686476622;4360.934585981213;5289.550388216968;5728.398518155617;5735.856603584861;5757.136429535603;5794.217780599565;6138.07928810682;6142.140100385949;6166.506679465632;6173.043178307369;6194.163787888668;5582;5761.683107429131;5822.243108395966;5838.211841401441;5846.590738648254;5220.92168617471;5289.656172348785;5293.126634105406;5313.043652142652;5315.176147697113;6147.66187859672;6192.907106393498;6197.101420294032;6198.659073750987;6270.529710271839;6165.290991536031;6169.136320914986;6182.072689096672;6186.1648677427975;6188.690565471618;6252.062190671436;6301.180846489927;6330.923742963615;6333.303336633059;6359.268684101387;5993.0710739835395;6040.6677632730625;6045.304789949212;6052.606580572248;6106.992346262817;6157.1072194532435;6161.507079433545;6169.136320915165;6193.071039344134;6200.162977997912;6024.457798797954;6041;6042.365551617193;6045.879082418239;6049.122877182872;4733.982193828343;5094.821681241138;5833.533317334688;5916.467517923407;5920.259967504062;4578.51925547826;4649.511801260819;5643.209950606836;5680.11958146488;5915.704214789157;4851.733154676385;5930.436608522184;5969.611975221596;6001.729922153426;6040.879182416415;5756.597038207453;5790.478856902899;5790.768369265183;5844.996177618175;5862.589618726648 13:05:15 INFO - PROCESS | 1935 | |1;ext.html;461.1376725523272;508.77105302613785;517.9715115668638;522.5923779452027;522.9123423279578;39321.410178847305;41033;41038.17923641526;41196.58803411969;42955;40964.97587560312;41353.79323103388;41357.759267222034;41440.963975900544;41594.58405415945;9529.857052144242;9700.544918262281;9735.659251926185;9754.853677194847;9759.756006099833;9514.191293740041;9592.99273576275;9594.424334539915;9617.75955601112;9637.759056023586;9545.47499887506;9578.563215517694;9622.134007939285;9636.855447168264;9639.325247232697;9544.856827147598;9591.568379422943;9631.759206019871;9635.759106022335;9673.758156046084;9438.716838494856;9505.952470237673;9527.857082143704;9532.237421006303;9576.856347154799;39902.401463978065;40093.79953100216;40187.7990610048;40354.394684079765;40465.19069618591;3301.851416686255;3349.832508374596;3352.832358382071;3403.3363494118657;3448.517207590939;30226.54660180099;30698.772049118008;30880.919167829245;30914;31121.37757244865;3201.823899685516;3221.0658908916384;3222.1783445221554;3305.3389322135667;3323.202431416443;477166;485726;489046;489998;490206;57875;57921.49745774145;57998;58017.12974305389;58149;61282.693586532674;61447.38552614506;61501.69249153726;61563.53745850116;61632.69183654053;15226.923865380824;15388.538343849703;15462.768058479132;15483.22583870802;15538.922305388402;12003.279803211835;12007.219530730474;12096.516139354504;12104.334261615519;12111.273323600612;13307.534236301683;15413;16206.270717817726;16281.023138611721;16444.83555164457;3042.726154646048;6119.112728654321;6625.4037136657935;6770.763023294161;6861.862762744817;856.8971723393232;876.4259410086404;877.3639111644007;908.8136931928939;1021.4841505039907;25526.489470210865;26809.659517024073;28014.859925700242;28082.719172808418;29852;857.8455877941906;860.489840330229;868.7958329792493;998.6155330197926;1078.692572616816;15202.695946081016;15221.847781522265;15431.691366172614;15562.766558501631;15583.298751556207;581283;618244;619007;619076.0461976973;627228;44534.10931781411;53553.447956923315;55934.44065559374;56214.718926405105;56434.717826410604;1121.3103941076179;1341.2824139085542;1342.0203251626367;1345.1122259308809;1349.5479014530047;28077.438451231268;29114.272143195743;29536.409271814013;30121.548176776927;30310.84844575763;49446.50553494563;49745.75127124341;49798.75100624474;49883.00233995227;50130.74934625303;557279;559310;561592;562713;563243;26126;26314;26433.60349594719;26591.069312573967;26771.732282676923;46559.53440465552;46920;47085.7645711783;47449.52550474451;47585.81035474141;37613.2477350446;38427.74405966177;40166;41504.377434337905;41884.58115418928;37136.62863371332;37481.6251837489;37586.06034849041;38557.036074098374;38819.805900970314;37601.87194384173;38378.848634541006;38434.0391490215;38618.22763544769;38752.418713718755;31655.359633642427;32166.83916580402;34183;34323.4851477233;34627.30745385129;23353;24781.1326603568;24849.751502484745;25486.617700734132;25627.487450251265 13:05:15 INFO - PROCESS | 1935 | |2;jquery.html;328.5269212334239;353.79303107682006;355.3284292686821;367.56994316649536;382.8162482008634;18468.538286542844;19153.425397238087;20079.99999999998;20089.899550502265;20357.694634580494;16267.349306027745;19157;19626.3130790422;20324.898375508103;20441.488962775937;5716.942830571704;5857.531397488212;5879.970600146993;6019.699015049252;6025.638461692291;5590.217369568264;5931.466168044875;5998.430149135826;6009.639421634694;6011.699415029253;5600.691961942091;5864.736086876101;5904.202932604107;5906.9704651476895;5910.645361278337;5704.1443783432405;5766.971165144189;5830.387809280007;5841.386654401269;5864.2962844458725;4826.155422800995;5008.62435317349;5020.648554601179;5084.872878178076;5092.694438333712;21123.47191320214;21222.363329100153;22766.089356425724;24566;24649.753502464926;1817;2061.711360409544;2257.8645281283175;2378.5837478441204;2400.303911865557;10543.683689489251;14893.192850948468;15183.468578599806;15404;15769.763453548207;1612.7822743929537;1832.404468547714;1841.49358926295;1848.3161230344858;1900.5862554555774;107735;113138.73722525586;113285.53859075578;113410;114741.27888081686;8251.422400431911;10101.444420556792;10322.432266225429;10472.633457828939;10767.83848242277;12686.746265074647;12879.227246365248;12903.677408064874;12937.805932911015;12952.899003584613;7847.2152784721075;8146.022477302761;8154.633041513028;8223.835523289621;8277.751667449986;8123.634436450374;8156.143604921516;8182.345412366996;8290.005199375993;8305.584720763938;5068.391792984866;5244.5279924806955;5273.44628813969;6421.29365769765;6542.116814230113;1728.4555365059966;1833.120102350879;1843.64970655575;1862.2644055597964;1888.8205620466033;767.808047988003;826.9456443035178;893.7854914820526;919.2462181011506;924.7641851327868;18142.274309027754;19778.406647800588;20387.98060096989;21486.892565537073;21506.46233844135;888.644542183119;909.7952960583948;953.7663272498144;969.345691658128;973.493783232715;9718.75703107428;10329.97427420023;10768.300060496043;11355.94322028401;11459.4270286487;576394;579560;590536;601256;601690;112565;113311;118847;123495;125004;971.820213260549;1011.3022014809758;1013.5945621751358;1017.3031473440717;1017.4912543728136;15775;18072.457826265232;19211.90394048021;19246.8075319245;19268.807311926703;31918.84040579875;52835.20747188872;56167.71916140393;56256.156157658486;57463.71268143632;59459;62392;74789;82061;82972;7696.538207707556;7912.36701063925;7953.363730901629;7981.920180798118;8181.386396020203;10356.844647330145;12561.309127998047;12791.232526048467;12812.039097067944;12846.807297890726;9412.67055653049;10668.8933110671;10937.890621094006;11272.943635281772;11445.198836081394;8053.798655033671;10481.109105726098;10597.4701264938;10676.83984740214;10730.195235357223;9669;10420.426876521864;11527.76944461123;11562.537498500302;11743.647690569296;7831.804204894923;7872.425514896929;8062.919370806451;8065.032196136502;8208.753737387888;8134.471259368122;9897.703068907944;10658.520366583678;11094.11247100214;11172.497237624164 13:05:15 INFO - PROCESS | 1935 | |3;mootools.html;1333.2533781082593;1433.8781203597694;1473.8894582906278;1620.1170362152625;1829.871908966373;7576.431767617423;7795.92204077959;8048.91951080489;8533.232009119185;8565.486070835746;8008.399370047256;8289.585520723955;8309.335253179746;8435.198656127674;8498.6175622097;2241.7421996470453;2258.6499092640665;2263.875486848223;2266.795988361047;2276.6243569810963;1911.2737159879296;2233.184887516053;2269.5120549081944;2282.942926426844;2291.7249930008425;2215.4904371994453;2220.755716871143;2233.1179184222224;2234.9888250558724;2251.1108112295633;2218.9889050554643;2219.1456289328644;2245.741739699939;2249.0666373454997;2255.6954811100536;1859.646667133254;2229.1194977983605;2244.842860999731;2247.932562023142;2252.8310376721647;8366.07973122956;8377;8404.663813447454;8476.915230847675;8489.023762267356;4937.136001199776;5110.616703747197;5114.2840002399325;5117.976404719034;5126.077306084903;3193.9680603194133;3222;3378.814165220912;3408.6761757632985;3426.732109119617;1009.9798004039878;1034.5086084110062;1050.4747626186906;1057.7672911959357;1058.9205809564314;10495.580176792919;10690.144788416908;10815.134789216923;10891.564337426493;10917;4116.361963895601;4147;4305.504866940342;4308.89227769302;4309.267424537836;8387.580620968927;8439.156084391512;8510.276626486819;8514.914850851535;8574.442661227;4961.305417241589;4973.32860063894;4986.276989836453;4986.476419975923;4989.126902791997;4167.74993500391;4180.47744031996;4635.6523260755575;4671.7430541320555;4714.316424118484;2905.956410653842;3010.9397812044076;3305.190228394011;3557.21741216937;3661.6338366163704;882.7572417585216;899.3524662243306;1102.9944850275858;1116.8548088748412;1125.0549538387793;849.6643825688818;854.0946596607616;859.3683642522741;875.7241468937265;916.9954150229339;4853.611711063107;5321.8935621287355;5382.085045542267;5573.386927437979;5622.613439127201;371.0612151257323;371.6525049079066;382.1439974457251;386.7679392364615;387.6201322703766;3292.9627167442177;3447;3650.5071815304595;3670.44943258508;3737.65479895492;9794.510274486247;10136.645217417356;10153.441560714084;10218.233632477446;10247.923967983403;10040.64857729976;10198.745031374276;10207.795844083077;10217.642382516577;10239.18086553074;6340.365963403715;7652.387808975269;7925.207479252144;7928.841423171504;7957.244061814118;5262.526372626482;5324.760385782572;5371.301730774974;5386.461353864739;5465.426130256265;9227.492487913229;9275.67535136267;9307.255419566554;9346.626134954799;9410.058994100535;9154.725358239264;9307.301952353466;9404.576794044147;9425.62297508092;9427.198688111588;6526.151600292122;6733;6824.249332573314;6862;7064.788056358318;7002.439804815501;7659.846803063796;7773.494722842997;7905.169957154646;7905.802354941173;6709.295523969913;6781.559198652072;7138;7172.928270717226;7246.311600397954;7017.789466316019;7024.754133605299;7026.402755765817;7055.082839230867;7096.680649370687;6639.302873198245;6658.034584985053;6736.932630673827;6741.2921643226755;6755.4257888080065;4723.858284251478;4768.76156192196;4783.521647835189;4817.8313759018265;4880;4616.238320677185;4699.859004229878;4710.858674239778;4883.536064073907;4900.166971614763 13:05:15 INFO - PROCESS | 1935 | |4;prototype.html;297.3324885631756;297.33990540998974;306.7469337796318;312.45632599277235;313.7458658486626;5284.260203571503;5419.864503387417;5426.670465735895;5556.388797232301;5566.916496252559;4247.490301163857;5253.502751715761;5380.923815236958;5452.6183167178315;5470.878469913659;3618.855245790165;3622.2832875343493;3825.6036546660475;4010.9799451002705;4147.320335264208;3947.940780888289;4059.97970010151;4092.5907409258984;4139.958600413987;4181.393697913801;3775.641314075172;4010.977200813803;4074.633283004529;4100.36444351126;4104.179164167163;3708.9985703860075;3840.231953609289;4091.7954102294916;4139.606737359946;4150.958490415087;3080.537919312099;3217.7264932480766;3285.9342813143603;3345.698887100148;3512.262424890776;4948.723587149915;5243.689077730567;5278.205410160525;5302.628815982883;5346.812861549827;4690.9530904690855;4690.95309046912;4691.8592442226445;4698.7415692137265;4703.952960470386;4887.633427492952;5019.347484826985;5434.91847622286;5661.915071273934;5759.251297331362;3540.8583656653705;3848.942265866014;3861.1698484825615;3864.768113913174;3866.5360156781085;4991.775370108354;5246.344206974167;5983.222181116471;6021.969890150521;6022.427869352405;3776.6789822864816;3873;3890.6771697622944;3904.2386734586485;3912.3349030664854;3953.466282051944;3992.121733218687;4003;4008.7594744315434;4018.3323920572634;4385.232584297735;4441.178381999341;4672.602828759594;4707.529247075334;4721.834735784231;5514.511082007835;5798.681072540966;5829.300483941955;5841.970790146022;5871.823845284648;1167.8656954450346;1277.4762347437693;1291.593148158327;1323.9536616218386;1343.596920923719;777.7627823513883;815.502543448496;883.1433509495779;904.3579058868277;907.2424525521131;4357.4988876279;5251.684898906078;5328.573714102862;5354.330708661418;5368.436314187033;4873.317735516959;5110.437851836295;5715.257016587817;5744.310682718101;5857.355690874;5171.689698618095;5337.332833395825;5345.091334473149;5413.618008076433;5441.211024401439;2420.031987205097;2439.865807380611;2557.5012872489688;2576.750276116082;2581.6833414958132;4742.834000810024;5030.7233102179;5045.7981680732355;5056.469070747592;5115.900081482425;4147.4815648044;4780.689255198401;4845.53967373099;5004.42449118356;5050.823221187315;4505.414296141481;5058;5070.771815268322;5095.394950590552;5145.382554093458;4636.863968327782;4703.45910220329;4801.423829140525;4899.167141585867;4913.975430122827;4841.370621819141;4851.611871050378;4910.9017819642695;5261.73691315425;5812.476877081083;5241;5902.793402230901;6238.968805155945;6246.312905580292;6262.780802671885;3519.2433626770367;3576.624454432247;3659.8654417130774;3998.8800335988803;4064.30906745848;5166.535011848951;5290.920636190537;5604.8318550443555;5923.674197919156;5931.6441013539325;5134.717590532557;5641.887162256649;5697.715114244188;5771.509421699203;5851.356350801323;4780.976095119502;4820.4456487504385;5383.608326754276;5392.62960649594;5810.244668193108;4267.914641707086;4417.690761646654;4933.313352315243;4978.8755281118265;5053.567818880083;4696.365990591226;5225.503577160163;5812.854678632899;5834.591578589628;5844.26946631671;4426.82292708301;5267.78928842842;5680.971595141998;5684.7441865117;5696.174054762119 13:05:15 INFO - PROCESS | 1935 | |5;yui.html;688.1673175457693;699.5348093517807;771.9382449404047;781.1173374087281;794.1938931984035;12213.450394732236;15556.922215388935;15594.06435613864;15640.3743850246;15654.982426142293;13966.371513281902;13993.230372329517;14134.858651413482;14163.787543186834;14164.362603682834;795.7891158842912;839.8698201778734;857.2627540315324;857.3998201259112;861.6984055580559;859.7635650196215;862.7713655881165;868;872.7512658892217;873.4409977614332;903.0969030969031;905.728281515546;906.995465022674;907.5326207003399;907.8728977943093;900.2662829793697;901.5852707754441;905.2983937448464;905.642271302835;906.3519583497765;659.4987809264945;674.3930462583664;677.0893148714971;677.474956908395;679.6601699150425;12340.691482712915;12441.75116497665;12536.122471427003;12554.246745195318;13572.185668859802;11543.826842597367;11562.942185289105;11603.709907252301;11642.6507204784;11751.823722644167;7906.565138917357;9790.069943355442;10419.947900260526;10434.791304173874;10489.318194317346;3616.6383361663625;3665;3706.592274849764;3733.1600389912323;3750;87879.56060219722;89231.55384223102;89258.10741892563;93841.5307923456;94449.58325625121;7835.294823465915;8889.511076890703;8931.955340223258;9003.234725048313;9079.45523268606;15497.302621382065;17074.317027318768;17783.644327113387;19122;19173.520661983563;7459.813504662318;7578.242175782378;7742.806429839186;7863.685452581947;8004.63979120941;4345.391645169679;4356.607905288539;4375.365571992043;4437.534058923767;4469.4860091089295;3487.302539492112;3570.589382221026;3581.8209089545417;3605.7656252343513;3613.33153366628;624.54720327762;629.4020680353682;645.1000853808911;664.3920812456548;665.7736369634348;564.3256308711051;587.49475451112;667.4093427316859;671.1042941739921;673.2762280548333;7346.412287017026;9171.532773980169;9252.213561847184;9283.860742088875;9513.476758778334;628.6416742456847;630.1335663462738;650.7201903181677;658.134553062721;659.9109120268703;4343.522212556616;4365.257906155961;4610.331501932201;4615.284630882219;4649.256119020941;103602.48198758958;104600;105492.47253763836;115665;122511.387443064;98563;98749;99298;100365.49817250867;100694;86975.56512217525;87301.12698873057;88033.1196688025;88456.55771721229;90448.54775726079;8497.107803680772;8848.336374771789;8924.821503570021;9045.412048216846;9094.226990705863;57522.7123864378;59088.40911590829;59220.70389648024;59863.50341241504;59888.101678473984;75254.24745752472;84152.57923710342;85463;85623.5718821402;87287;11045.72385690332;11419.885801141882;11647.12646551529;11768.58809941648;12130.575429859913;12353.938230308791;12459.813102803284;12471.250693948752;12920.160189587647;12930.965522758344;11665.24175928562;11795.764084718428;11988.76022479563;12177.939110304393;12353.505859765508;10249.641262555775;10367.63713270032;11608.6517404478;12222.694432639255;12377.814332784836;10040.9959004099;11579.942100289445;11966.820497692368;12022;12347.938260308641;9728.9027109728;9952.800943981225;9962.402255864672;10041.799164016826;10171.847422288523;7122.7507037253445;7255.2019277880445;9121.635134594542;9811.705648830546;10159.238057145596 13:05:15 INFO - PROCESS | 1935 | __end_tp_report 13:05:15 INFO - PROCESS | 1935 | __start_cc_report 13:05:15 INFO - PROCESS | 1935 | _x_x_mozilla_cycle_collect,369 13:05:15 INFO - PROCESS | 1935 | __end_cc_report 13:05:15 INFO - PROCESS | 1935 | __startTimestamp1478203515152__endTimestamp 13:05:15 INFO - PROCESS | 1935 | 13:05:15 INFO - PROCESS | 1935 | ------- Summary: start ------- 13:05:15 INFO - PROCESS | 1935 | Number of tests: 6 13:05:15 INFO - PROCESS | 1935 | 13:05:15 INFO - PROCESS | 1935 | [#0] dojo.html Cycles:165 Average:5574.40 Median:5790.77 stddev:684.47 (11.8%) stddev-sans-first:662.86 13:05:15 INFO - PROCESS | 1935 | Values: 3297.9 3334.3 3344.8 3358.1 3363.1 5464.9 5531.8 5549.6 5554.1 5595.8 4511.8 5521.5 5596.9 5635.6 5643.2 5049.5 5100.5 5141.2 5174.8 5200.8 5042.6 5188.1 5191.1 5225.1 5226.3 3997.9 4176.5 5024.3 5156.4 5157.0 4995.9 5049.2 5059.6 5107.4 5148.6 5006.2 5048.5 5048.9 5110.4 5118.6 4412.7 5046.1 5431.4 5605.6 5626.2 5945.7 6075.3 6090.7 6092.8 6102.9 6137.5 6155.9 6181.1 6187.2 6212.0 5544.1 5621.9 5680.5 5690.7 5736.9 5113.4 6232.8 6242.7 6260.0 6298.1 4895.4 5966.7 6026.8 6039.9 6074.0 5989.7 6159.2 6164.9 6177.2 6192.7 5992.1 5993.2 5997.7 6066.8 6076.1 6077.3 6105.4 6109.3 6128.8 6135.9 4822.4 4843.7 4858.3 4861.1 4886.8 4001.5 4311.1 4326.2 4356.6 4360.9 5289.6 5728.4 5735.9 5757.1 5794.2 6138.1 6142.1 6166.5 6173.0 6194.2 5582.0 5761.7 5822.2 5838.2 5846.6 5220.9 5289.7 5293.1 5313.0 5315.2 6147.7 6192.9 6197.1 6198.7 6270.5 6165.3 6169.1 6182.1 6186.2 6188.7 6252.1 6301.2 6330.9 6333.3 6359.3 5993.1 6040.7 6045.3 6052.6 6107.0 6157.1 6161.5 6169.1 6193.1 6200.2 6024.5 6041.0 6042.4 6045.9 6049.1 4734.0 5094.8 5833.5 5916.5 5920.3 4578.5 4649.5 5643.2 5680.1 5915.7 4851.7 5930.4 5969.6 6001.7 6040.9 5756.6 5790.5 5790.8 5845.0 5862.6 13:05:15 INFO - PROCESS | 1935 | 13:05:15 INFO - PROCESS | 1935 | [#1] ext.html Cycles:180 Average:68442.05 Median:41275.19 stddev:148470.45 (359.7%) stddev-sans-first:148799.22 13:05:15 INFO - PROCESS | 1935 | Values: 461.1 508.8 518.0 522.6 522.9 39321.4 41033.0 41038.2 41196.6 42955.0 40965.0 41353.8 41357.8 41441.0 41594.6 9529.9 9700.5 9735.7 9754.9 9759.8 9514.2 9593.0 9594.4 9617.8 9637.8 9545.5 9578.6 9622.1 9636.9 9639.3 9544.9 9591.6 9631.8 9635.8 9673.8 9438.7 9506.0 9527.9 9532.2 9576.9 39902.4 40093.8 40187.8 40354.4 40465.2 3301.9 3349.8 3352.8 3403.3 3448.5 30226.5 30698.8 30880.9 30914.0 31121.4 3201.8 3221.1 3222.2 3305.3 3323.2 477166.0 485726.0 489046.0 489998.0 490206.0 57875.0 57921.5 57998.0 58017.1 58149.0 61282.7 61447.4 61501.7 61563.5 61632.7 15226.9 15388.5 15462.8 15483.2 15538.9 12003.3 12007.2 12096.5 12104.3 12111.3 13307.5 15413.0 16206.3 16281.0 16444.8 3042.7 6119.1 6625.4 6770.8 6861.9 856.9 876.4 877.4 908.8 1021.5 25526.5 26809.7 28014.9 28082.7 29852.0 857.8 860.5 868.8 998.6 1078.7 15202.7 15221.8 15431.7 15562.8 15583.3 581283.0 618244.0 619007.0 619076.0 627228.0 44534.1 53553.4 55934.4 56214.7 56434.7 1121.3 1341.3 1342.0 1345.1 1349.5 28077.4 29114.3 29536.4 30121.5 30310.8 49446.5 49745.8 49798.8 49883.0 50130.7 557279.0 559310.0 561592.0 562713.0 563243.0 26126.0 26314.0 26433.6 26591.1 26771.7 46559.5 46920.0 47085.8 47449.5 47585.8 37613.2 38427.7 40166.0 41504.4 41884.6 37136.6 37481.6 37586.1 38557.0 38819.8 37601.9 38378.8 38434.0 38618.2 38752.4 31655.4 32166.8 34183.0 34323.5 34627.3 23353.0 24781.1 24849.8 25486.6 25627.5 13:05:15 INFO - PROCESS | 1935 | 13:05:15 INFO - PROCESS | 1935 | [#2] jquery.html Cycles:180 Average:33949.61 Median:24607.88 stddev:98174.04 (399.0%) stddev-sans-first:98416.98 13:05:15 INFO - PROCESS | 1935 | Values: 328.5 353.8 355.3 367.6 382.8 18468.5 19153.4 20080.0 20089.9 20357.7 16267.3 19157.0 19626.3 20324.9 20441.5 5716.9 5857.5 5880.0 6019.7 6025.6 5590.2 5931.5 5998.4 6009.6 6011.7 5600.7 5864.7 5904.2 5907.0 5910.6 5704.1 5767.0 5830.4 5841.4 5864.3 4826.2 5008.6 5020.6 5084.9 5092.7 21123.5 21222.4 22766.1 24566.0 24649.8 1817.0 2061.7 2257.9 2378.6 2400.3 10543.7 14893.2 15183.5 15404.0 15769.8 1612.8 1832.4 1841.5 1848.3 1900.6 107735.0 113138.7 113285.5 113410.0 114741.3 8251.4 10101.4 10322.4 10472.6 10767.8 12686.7 12879.2 12903.7 12937.8 12952.9 7847.2 8146.0 8154.6 8223.8 8277.8 8123.6 8156.1 8182.3 8290.0 8305.6 5068.4 5244.5 5273.4 6421.3 6542.1 1728.5 1833.1 1843.6 1862.3 1888.8 767.8 826.9 893.8 919.2 924.8 18142.3 19778.4 20388.0 21486.9 21506.5 888.6 909.8 953.8 969.3 973.5 9718.8 10330.0 10768.3 11355.9 11459.4 576394.0 579560.0 590536.0 601256.0 601690.0 112565.0 113311.0 118847.0 123495.0 125004.0 971.8 1011.3 1013.6 1017.3 1017.5 15775.0 18072.5 19211.9 19246.8 19268.8 31918.8 52835.2 56167.7 56256.2 57463.7 59459.0 62392.0 74789.0 82061.0 82972.0 7696.5 7912.4 7953.4 7981.9 8181.4 10356.8 12561.3 12791.2 12812.0 12846.8 9412.7 10668.9 10937.9 11272.9 11445.2 8053.8 10481.1 10597.5 10676.8 10730.2 9669.0 10420.4 11527.8 11562.5 11743.6 7831.8 7872.4 8062.9 8065.0 8208.8 8134.5 9897.7 10658.5 11094.1 11172.5 13:05:15 INFO - PROCESS | 1935 | 13:05:15 INFO - PROCESS | 1935 | [#3] mootools.html Cycles:180 Average:5304.72 Median:4881.77 stddev:3005.75 (61.6%) stddev-sans-first:2999.37 13:05:15 INFO - PROCESS | 1935 | Values: 1333.3 1433.9 1473.9 1620.1 1829.9 7576.4 7795.9 8048.9 8533.2 8565.5 8008.4 8289.6 8309.3 8435.2 8498.6 2241.7 2258.6 2263.9 2266.8 2276.6 1911.3 2233.2 2269.5 2282.9 2291.7 2215.5 2220.8 2233.1 2235.0 2251.1 2219.0 2219.1 2245.7 2249.1 2255.7 1859.6 2229.1 2244.8 2247.9 2252.8 8366.1 8377.0 8404.7 8476.9 8489.0 4937.1 5110.6 5114.3 5118.0 5126.1 3194.0 3222.0 3378.8 3408.7 3426.7 1010.0 1034.5 1050.5 1057.8 1058.9 10495.6 10690.1 10815.1 10891.6 10917.0 4116.4 4147.0 4305.5 4308.9 4309.3 8387.6 8439.2 8510.3 8514.9 8574.4 4961.3 4973.3 4986.3 4986.5 4989.1 4167.7 4180.5 4635.7 4671.7 4714.3 2906.0 3010.9 3305.2 3557.2 3661.6 882.8 899.4 1103.0 1116.9 1125.1 849.7 854.1 859.4 875.7 917.0 4853.6 5321.9 5382.1 5573.4 5622.6 371.1 371.7 382.1 386.8 387.6 3293.0 3447.0 3650.5 3670.4 3737.7 9794.5 10136.6 10153.4 10218.2 10247.9 10040.6 10198.7 10207.8 10217.6 10239.2 6340.4 7652.4 7925.2 7928.8 7957.2 5262.5 5324.8 5371.3 5386.5 5465.4 9227.5 9275.7 9307.3 9346.6 9410.1 9154.7 9307.3 9404.6 9425.6 9427.2 6526.2 6733.0 6824.2 6862.0 7064.8 7002.4 7659.8 7773.5 7905.2 7905.8 6709.3 6781.6 7138.0 7172.9 7246.3 7017.8 7024.8 7026.4 7055.1 7096.7 6639.3 6658.0 6736.9 6741.3 6755.4 4723.9 4768.8 4783.5 4817.8 4880.0 4616.2 4699.9 4710.9 4883.5 4900.2 13:05:15 INFO - PROCESS | 1935 | 13:05:15 INFO - PROCESS | 1935 | [#4] prototype.html Cycles:180 Average:4446.67 Median:4905.03 stddev:1371.37 (28.0%) stddev-sans-first:1339.39 13:05:15 INFO - PROCESS | 1935 | Values: 297.3 297.3 306.7 312.5 313.7 5284.3 5419.9 5426.7 5556.4 5566.9 4247.5 5253.5 5380.9 5452.6 5470.9 3618.9 3622.3 3825.6 4011.0 4147.3 3947.9 4060.0 4092.6 4140.0 4181.4 3775.6 4011.0 4074.6 4100.4 4104.2 3709.0 3840.2 4091.8 4139.6 4151.0 3080.5 3217.7 3285.9 3345.7 3512.3 4948.7 5243.7 5278.2 5302.6 5346.8 4691.0 4691.0 4691.9 4698.7 4704.0 4887.6 5019.3 5434.9 5661.9 5759.3 3540.9 3848.9 3861.2 3864.8 3866.5 4991.8 5246.3 5983.2 6022.0 6022.4 3776.7 3873.0 3890.7 3904.2 3912.3 3953.5 3992.1 4003.0 4008.8 4018.3 4385.2 4441.2 4672.6 4707.5 4721.8 5514.5 5798.7 5829.3 5842.0 5871.8 1167.9 1277.5 1291.6 1324.0 1343.6 777.8 815.5 883.1 904.4 907.2 4357.5 5251.7 5328.6 5354.3 5368.4 4873.3 5110.4 5715.3 5744.3 5857.4 5171.7 5337.3 5345.1 5413.6 5441.2 2420.0 2439.9 2557.5 2576.8 2581.7 4742.8 5030.7 5045.8 5056.5 5115.9 4147.5 4780.7 4845.5 5004.4 5050.8 4505.4 5058.0 5070.8 5095.4 5145.4 4636.9 4703.5 4801.4 4899.2 4914.0 4841.4 4851.6 4910.9 5261.7 5812.5 5241.0 5902.8 6239.0 6246.3 6262.8 3519.2 3576.6 3659.9 3998.9 4064.3 5166.5 5290.9 5604.8 5923.7 5931.6 5134.7 5641.9 5697.7 5771.5 5851.4 4781.0 4820.4 5383.6 5392.6 5810.2 4267.9 4417.7 4933.3 4978.9 5053.6 4696.4 5225.5 5812.9 5834.6 5844.3 4426.8 5267.8 5681.0 5684.7 5696.2 13:05:15 INFO - PROCESS | 1935 | 13:05:15 INFO - PROCESS | 1935 | [#5] yui.html Cycles:180 Average:20771.85 Median:626.59 stddev:31513.18 (5029.3%) stddev-sans-first:31565.50 13:05:15 INFO - PROCESS | 1935 | Values: 688.2 699.5 771.9 781.1 794.2 12213.5 15556.9 15594.1 15640.4 15655.0 13966.4 13993.2 14134.9 14163.8 14164.4 795.8 839.9 857.3 857.4 861.7 859.8 862.8 868.0 872.8 873.4 903.1 905.7 907.0 907.5 907.9 900.3 901.6 905.3 905.6 906.4 659.5 674.4 677.1 677.5 679.7 12340.7 12441.8 12536.1 12554.2 13572.2 11543.8 11562.9 11603.7 11642.7 11751.8 7906.6 9790.1 10419.9 10434.8 10489.3 3616.6 3665.0 3706.6 3733.2 3750.0 87879.6 89231.6 89258.1 93841.5 94449.6 7835.3 8889.5 8932.0 9003.2 9079.5 15497.3 17074.3 17783.6 19122.0 19173.5 7459.8 7578.2 7742.8 7863.7 8004.6 4345.4 4356.6 4375.4 4437.5 4469.5 3487.3 3570.6 3581.8 3605.8 3613.3 624.5 629.4 645.1 664.4 665.8 564.3 587.5 667.4 671.1 673.3 7346.4 9171.5 9252.2 9283.9 9513.5 628.6 630.1 650.7 658.1 659.9 4343.5 4365.3 4610.3 4615.3 4649.3 103602.5 104600.0 105492.5 115665.0 122511.4 98563.0 98749.0 99298.0 100365.5 100694.0 86975.6 87301.1 88033.1 88456.6 90448.5 8497.1 8848.3 8924.8 9045.4 9094.2 57522.7 59088.4 59220.7 59863.5 59888.1 75254.2 84152.6 85463.0 85623.6 87287.0 11045.7 11419.9 11647.1 11768.6 12130.6 12353.9 12459.8 12471.3 12920.2 12931.0 11665.2 11795.8 11988.8 12177.9 12353.5 10249.6 10367.6 11608.7 12222.7 12377.8 10041.0 11579.9 11966.8 12022.0 12347.9 9728.9 9952.8 9962.4 10041.8 10171.8 7122.8 7255.2 9121.6 9811.7 10159.2 13:05:15 INFO - PROCESS | 1935 | -------- Summary: end -------- 13:05:15 INFO - PROCESS | 1935 | 13:05:15 INFO - TEST-INFO | 1935: exit 0 13:05:15 INFO - TEST-OK | dromaeo_css | took 1116298ms 13:05:15 INFO - TEST-START | kraken 13:05:15 INFO - operating with platform_type : mac_ 13:05:15 INFO - Initialising browser for kraken test... 13:05:15 INFO - TEST-INFO | started process 1940 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/tmpiBHumf/profile http://localhost:56581/getInfo.html) 13:05:18 INFO - PROCESS | 1940 | 2016-11-03 13:05:18.578 plugin-container[1941:20950] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x873f, name = 'com.apple.tsm.portname' 13:05:18 INFO - PROCESS | 1940 | See /usr/include/servers/bootstrap_defs.h for the error codes. 13:05:18 INFO - PROCESS | 1940 | 2016-11-03 13:05:18.581 plugin-container[1941:20950] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8b13, name = 'com.apple.CFPasteboardClient' 13:05:18 INFO - PROCESS | 1940 | See /usr/include/servers/bootstrap_defs.h for the error codes. 13:05:18 INFO - PROCESS | 1940 | 2016-11-03 13:05:18.581 plugin-container[1941:20950] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 13:05:18 INFO - PROCESS | 1940 | __metrics Screen width/height:1600/1200 13:05:18 INFO - PROCESS | 1940 | colorDepth:24 13:05:18 INFO - PROCESS | 1940 | Browser inner width/height: 1024/689 13:05:18 INFO - PROCESS | 1940 | __metrics 13:05:18 ERROR - PROCESS | 1940 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3158: TypeError: this.tabs is undefined 13:05:19 INFO - TEST-INFO | 1940: exit 0 13:05:19 INFO - Browser initialized. 13:05:19 INFO - Running cycle 1/1 for kraken test... 13:05:19 INFO - TEST-INFO | started process 1942 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/T/tmpiBHumf/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 13:05:21 INFO - PROCESS | 1942 | 2016-11-03 13:05:21.865 plugin-container[1943:21065] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4447, name = 'com.apple.tsm.portname' 13:05:21 INFO - PROCESS | 1942 | See /usr/include/servers/bootstrap_defs.h for the error codes. 13:05:21 INFO - PROCESS | 1942 | 2016-11-03 13:05:21.866 plugin-container[1943:21065] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x471b, name = 'com.apple.CFPasteboardClient' 13:05:21 INFO - PROCESS | 1942 | See /usr/include/servers/bootstrap_defs.h for the error codes. 13:05:21 INFO - PROCESS | 1942 | 2016-11-03 13:05:21.866 plugin-container[1943:21065] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 13:05:31 INFO - PROCESS | 1942 | RSS: Main: 239181824 13:05:31 INFO - PROCESS | 1942 | 13:05:34 INFO - PROCESS | 1942 | Cycle 1(1): loaded http://localhost:56581/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:56581/tests/kraken/driver.html?testName=audio-beat-detection) 13:05:35 INFO - PROCESS | 1942 | RSS: Main: 250200064 13:05:35 INFO - PROCESS | 1942 | 13:05:38 INFO - PROCESS | 1942 | Cycle 1(1): loaded http://localhost:56581/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:56581/tests/kraken/driver.html?testName=audio-dft) 13:05:38 INFO - PROCESS | 1942 | RSS: Main: 257654784 13:05:38 INFO - PROCESS | 1942 | 13:05:42 INFO - PROCESS | 1942 | Cycle 1(1): loaded http://localhost:56581/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:56581/tests/kraken/driver.html?testName=audio-fft) 13:05:42 INFO - PROCESS | 1942 | RSS: Main: 260567040 13:05:42 INFO - PROCESS | 1942 | 13:05:44 INFO - PROCESS | 1942 | Cycle 1(1): loaded http://localhost:56581/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:56581/tests/kraken/driver.html?testName=audio-oscillator) 13:05:45 INFO - PROCESS | 1942 | RSS: Main: 262815744 13:05:45 INFO - PROCESS | 1942 | 13:05:47 INFO - PROCESS | 1942 | Cycle 1(1): loaded http://localhost:56581/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:56581/tests/kraken/driver.html?testName=imaging-gaussian-blur) 13:05:48 INFO - PROCESS | 1942 | RSS: Main: 263987200 13:05:48 INFO - PROCESS | 1942 | 13:05:50 INFO - PROCESS | 1942 | Cycle 1(1): loaded http://localhost:56581/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:56581/tests/kraken/driver.html?testName=imaging-darkroom) 13:05:51 INFO - PROCESS | 1942 | RSS: Main: 263655424 13:05:51 INFO - PROCESS | 1942 | 13:05:54 INFO - PROCESS | 1942 | Cycle 1(1): loaded http://localhost:56581/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:56581/tests/kraken/driver.html?testName=imaging-desaturate) 13:05:54 INFO - PROCESS | 1942 | RSS: Main: 263745536 13:05:54 INFO - PROCESS | 1942 | 13:05:57 INFO - PROCESS | 1942 | Cycle 1(1): loaded http://localhost:56581/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:56581/tests/kraken/driver.html?testName=json-parse-financial) 13:05:58 INFO - PROCESS | 1942 | RSS: Main: 264343552 13:05:58 INFO - PROCESS | 1942 | 13:06:00 INFO - PROCESS | 1942 | Cycle 1(1): loaded http://localhost:56581/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:56581/tests/kraken/driver.html?testName=json-stringify-tinderbox) 13:06:00 INFO - PROCESS | 1942 | RSS: Main: 263651328 13:06:00 INFO - PROCESS | 1942 | 13:06:02 INFO - PROCESS | 1942 | Cycle 1(1): loaded http://localhost:56581/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:56581/tests/kraken/driver.html?testName=stanford-crypto-aes) 13:06:03 INFO - PROCESS | 1942 | RSS: Main: 264294400 13:06:03 INFO - PROCESS | 1942 | 13:06:05 INFO - PROCESS | 1942 | Cycle 1(1): loaded http://localhost:56581/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:56581/tests/kraken/driver.html?testName=stanford-crypto-ccm) 13:06:06 INFO - PROCESS | 1942 | RSS: Main: 263802880 13:06:06 INFO - PROCESS | 1942 | 13:06:09 INFO - PROCESS | 1942 | Cycle 1(1): loaded http://localhost:56581/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:56581/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 13:06:10 INFO - PROCESS | 1942 | RSS: Main: 264130560 13:06:10 INFO - PROCESS | 1942 | 13:06:13 INFO - PROCESS | 1942 | Cycle 1(1): loaded http://localhost:56581/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:56581/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 13:06:13 INFO - PROCESS | 1942 | RSS: Main: 264142848 13:06:13 INFO - PROCESS | 1942 | 13:06:15 INFO - PROCESS | 1942 | Cycle 1(1): loaded http://localhost:56581/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:56581/tests/kraken/driver.html?testName=ai-astar) 13:06:15 INFO - PROCESS | 1942 | RSS: Main: 265998336 13:06:15 INFO - PROCESS | 1942 | 13:06:15 INFO - PROCESS | 1942 | __start_tp_report 13:06:15 INFO - PROCESS | 1942 | _x_x_mozilla_page_load 13:06:15 INFO - PROCESS | 1942 | _x_x_mozilla_page_load_details 13:06:15 INFO - PROCESS | 1942 | |i|pagename|runs| 13:06:15 INFO - PROCESS | 1942 | |0;ai-astar;107;120;117;117;115;113;117;126;120;118 13:06:15 INFO - PROCESS | 1942 | |1;audio-beat-detection;176;103;150;187;150;115;114;114;148;112 13:06:15 INFO - PROCESS | 1942 | |2;audio-dft;194;206;216;248;197;199;200;200;202;196 13:06:15 INFO - PROCESS | 1942 | |3;audio-fft;83;59;58;84;57;54;62;73;99;84 13:06:15 INFO - PROCESS | 1942 | |4;audio-oscillator;122;101;109;72;71;73;95;104;104;127 13:06:15 INFO - PROCESS | 1942 | |5;imaging-gaussian-blur;81;88;88;86;89;87;93;87;78;130 13:06:15 INFO - PROCESS | 1942 | |6;imaging-darkroom;96;96;95;96;95;94;98;95;94;95 13:06:15 INFO - PROCESS | 1942 | |7;imaging-desaturate;122;82;128;137;128;89;90;90;88;90 13:06:15 INFO - PROCESS | 1942 | |8;json-parse-financial;57;57;56;57;59;60;71;85;90;78 13:06:15 INFO - PROCESS | 1942 | |9;json-stringify-tinderbox;67;74;73;82;77;72;72;72;71;74 13:06:15 INFO - PROCESS | 1942 | |10;stanford-crypto-aes;89;88;85;91;91;86;85;91;107;109 13:06:15 INFO - PROCESS | 1942 | |11;stanford-crypto-ccm;152;149;148;147;150;183;170;154;234;177 13:06:15 INFO - PROCESS | 1942 | |12;stanford-crypto-pbkdf2;175;176;175;175;177;174;170;182;178;214 13:06:15 INFO - PROCESS | 1942 | |13;stanford-crypto-sha256-iterative;63;68;83;98;93;93;68;67;68;70 13:06:15 INFO - PROCESS | 1942 | __end_tp_report 13:06:15 INFO - PROCESS | 1942 | __start_cc_report 13:06:15 INFO - PROCESS | 1942 | _x_x_mozilla_cycle_collect,978 13:06:15 INFO - PROCESS | 1942 | __end_cc_report 13:06:15 INFO - PROCESS | 1942 | __startTimestamp1478203575863__endTimestamp 13:06:15 INFO - PROCESS | 1942 | 13:06:15 INFO - PROCESS | 1942 | ------- Summary: start ------- 13:06:15 INFO - PROCESS | 1942 | Number of tests: 14 13:06:15 INFO - PROCESS | 1942 | 13:06:15 INFO - PROCESS | 1942 | [#0] ai-astar Cycles:10 Average:117.00 Median:117.50 stddev:4.94 (4.2%) stddev-sans-first:3.69 13:06:15 INFO - PROCESS | 1942 | Values: 107.0 120.0 117.0 117.0 115.0 113.0 117.0 126.0 120.0 118.0 13:06:15 INFO - PROCESS | 1942 | 13:06:15 INFO - PROCESS | 1942 | [#1] audio-beat-detection Cycles:10 Average:136.90 Median:149.00 stddev:29.44 (19.8%) stddev-sans-first:27.62 13:06:15 INFO - PROCESS | 1942 | Values: 176.0 103.0 150.0 187.0 150.0 115.0 114.0 114.0 148.0 112.0 13:06:15 INFO - PROCESS | 1942 | 13:06:15 INFO - PROCESS | 1942 | [#2] audio-dft Cycles:10 Average:205.80 Median:201.00 stddev:16.07 (8.0%) stddev-sans-first:16.47 13:06:15 INFO - PROCESS | 1942 | Values: 194.0 206.0 216.0 248.0 197.0 199.0 200.0 200.0 202.0 196.0 13:06:15 INFO - PROCESS | 1942 | 13:06:15 INFO - PROCESS | 1942 | [#3] audio-fft Cycles:10 Average:71.30 Median:78.00 stddev:15.45 (19.8%) stddev-sans-first:15.80 13:06:15 INFO - PROCESS | 1942 | Values: 83.0 59.0 58.0 84.0 57.0 54.0 62.0 73.0 99.0 84.0 13:06:15 INFO - PROCESS | 1942 | 13:06:15 INFO - PROCESS | 1942 | [#4] audio-oscillator Cycles:10 Average:97.80 Median:99.00 stddev:20.16 (20.4%) stddev-sans-first:19.39 13:06:15 INFO - PROCESS | 1942 | Values: 122.0 101.0 109.0 72.0 71.0 73.0 95.0 104.0 104.0 127.0 13:06:15 INFO - PROCESS | 1942 | 13:06:15 INFO - PROCESS | 1942 | [#5] imaging-gaussian-blur Cycles:10 Average:90.70 Median:87.50 stddev:14.42 (16.5%) stddev-sans-first:14.86 13:06:15 INFO - PROCESS | 1942 | Values: 81.0 88.0 88.0 86.0 89.0 87.0 93.0 87.0 78.0 130.0 13:06:15 INFO - PROCESS | 1942 | 13:06:15 INFO - PROCESS | 1942 | [#6] imaging-darkroom Cycles:10 Average:95.40 Median:95.50 stddev:1.17 (1.2%) stddev-sans-first:1.22 13:06:15 INFO - PROCESS | 1942 | Values: 96.0 96.0 95.0 96.0 95.0 94.0 98.0 95.0 94.0 95.0 13:06:15 INFO - PROCESS | 1942 | 13:06:15 INFO - PROCESS | 1942 | [#7] imaging-desaturate Cycles:10 Average:104.40 Median:88.50 stddev:21.39 (24.2%) stddev-sans-first:21.71 13:06:15 INFO - PROCESS | 1942 | Values: 122.0 82.0 128.0 137.0 128.0 89.0 90.0 90.0 88.0 90.0 13:06:15 INFO - PROCESS | 1942 | 13:06:15 INFO - PROCESS | 1942 | [#8] json-parse-financial Cycles:10 Average:67.00 Median:65.50 stddev:13.01 (19.9%) stddev-sans-first:13.29 13:06:15 INFO - PROCESS | 1942 | Values: 57.0 57.0 56.0 57.0 59.0 60.0 71.0 85.0 90.0 78.0 13:06:15 INFO - PROCESS | 1942 | 13:06:15 INFO - PROCESS | 1942 | [#9] json-stringify-tinderbox Cycles:10 Average:73.40 Median:73.50 stddev:3.95 (5.4%) stddev-sans-first:3.44 13:06:15 INFO - PROCESS | 1942 | Values: 67.0 74.0 73.0 82.0 77.0 72.0 72.0 72.0 71.0 74.0 13:06:15 INFO - PROCESS | 1942 | 13:06:15 INFO - PROCESS | 1942 | [#10] stanford-crypto-aes Cycles:10 Average:92.20 Median:88.50 stddev:8.66 (9.8%) stddev-sans-first:9.11 13:06:15 INFO - PROCESS | 1942 | Values: 89.0 88.0 85.0 91.0 91.0 86.0 85.0 91.0 107.0 109.0 13:06:15 INFO - PROCESS | 1942 | 13:06:15 INFO - PROCESS | 1942 | [#11] stanford-crypto-ccm Cycles:10 Average:166.40 Median:162.00 stddev:27.12 (16.7%) stddev-sans-first:28.26 13:06:15 INFO - PROCESS | 1942 | Values: 152.0 149.0 148.0 147.0 150.0 183.0 170.0 154.0 234.0 177.0 13:06:15 INFO - PROCESS | 1942 | 13:06:15 INFO - PROCESS | 1942 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:179.60 Median:176.50 stddev:12.47 (7.1%) stddev-sans-first:13.11 13:06:15 INFO - PROCESS | 1942 | Values: 175.0 176.0 175.0 175.0 177.0 174.0 170.0 182.0 178.0 214.0 13:06:15 INFO - PROCESS | 1942 | 13:06:15 INFO - PROCESS | 1942 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:77.10 Median:76.50 stddev:13.24 (17.3%) stddev-sans-first:13.02 13:06:15 INFO - PROCESS | 1942 | Values: 63.0 68.0 83.0 98.0 93.0 93.0 68.0 67.0 68.0 70.0 13:06:15 INFO - PROCESS | 1942 | -------- Summary: end -------- 13:06:15 INFO - PROCESS | 1942 | 13:06:16 INFO - TEST-INFO | 1942: exit 0 13:06:16 INFO - TEST-OK | kraken | took 60925ms 13:06:16 INFO - SUITE-END | took 1177s 13:06:17 INFO - Completed test suite (00:19:37) 13:06:17 INFO - javascript benchmark 13:06:17 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7016.750436473237, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3297.901062968112, 3334.3331333733254, 3344.849481773321, 3358.0597432718846, 3363.058343663772, 5464.9453505464935, 5531.778728850852, 5549.639273447223, 5554.1113421852515, 5595.776168953237, 4511.819527218916, 5521.475459831309, 5596.888062238752, 5635.5773317001185, 5643.181738647893, 5049.495050494939, 5100.464451232604, 5141.228815677641, 5174.793008279665, 5200.843974680765, 5042.596592272609, 5188.066148093341, 5191.091558977183, 5225.137852254374, 5226.346706661668, 3997.9200415991663, 4176.498820141587, 5024.271480635324, 5156.407013193493, 5157.0, 4995.875103122415, 5049.242613607951, 5059.569936555397, 5107.412647545542, 5148.639595228336, 5006.199008158714, 5048.545630893201, 5048.949510504922, 5110.361204849394, 5118.616103792193, 4412.691111622188, 5046.1169295373165, 5431.429699881495, 5605.5515558755205, 5626.184203290541, 5945.702714864239, 6075.33171351151, 6090.725917333731, 6092.847678808021, 6102.938970610326, 6137.508999280047, 6155.859857019394, 6181.103739957681, 6187.226596675416, 6212.0, 5544.085225937756, 5621.8594535136945, 5680.4603562662305, 5690.658560486384, 5736.913946290809, 5113.360829896263, 6232.8130156095385, 6242.687865606701, 6260.029695397221, 6298.055291706189, 4895.412550493964, 5966.671833049224, 6026.849328766728, 6039.876948849972, 6074.0, 5989.730462129215, 6159.168512250789, 6164.859500992331, 6177.227846519185, 6192.690365481798, 5992.101184822312, 5993.190919225936, 5997.670128142907, 6066.787662431793, 6076.058210977306, 6077.3314935357075, 6105.358937311607, 6109.2974307955155, 6128.846778830476, 6135.937063216329, 4822.373091498082, 4843.685160464559, 4858.271259311061, 4861.124997500484, 4886.804527818847, 4001.479807625048, 4311.116221174651, 4326.221280169537, 4356.629686476622, 4360.934585981213, 5289.550388216968, 5728.398518155617, 5735.856603584861, 5757.136429535603, 5794.217780599565, 6138.07928810682, 6142.140100385949, 6166.506679465632, 6173.043178307369, 6194.163787888668, 5582.0, 5761.683107429131, 5822.243108395966, 5838.211841401441, 5846.590738648254, 5220.92168617471, 5289.656172348785, 5293.126634105406, 5313.043652142652, 5315.176147697113, 6147.66187859672, 6192.907106393498, 6197.101420294032, 6198.659073750987, 6270.529710271839, 6165.290991536031, 6169.136320914986, 6182.072689096672, 6186.1648677427975, 6188.690565471618, 6252.062190671436, 6301.180846489927, 6330.923742963615, 6333.303336633059, 6359.268684101387, 5993.0710739835395, 6040.6677632730625, 6045.304789949212, 6052.606580572248, 6106.992346262817, 6157.1072194532435, 6161.507079433545, 6169.136320915165, 6193.071039344134, 6200.162977997912, 6024.457798797954, 6041.0, 6042.365551617193, 6045.879082418239, 6049.122877182872, 4733.982193828343, 5094.821681241138, 5833.533317334688, 5916.467517923407, 5920.259967504062, 4578.51925547826, 4649.511801260819, 5643.209950606836, 5680.11958146488, 5915.704214789157, 4851.733154676385, 5930.436608522184, 5969.611975221596, 6001.729922153426, 6040.879182416415, 5756.597038207453, 5790.478856902899, 5790.768369265183, 5844.996177618175, 5862.589618726648], "value": 5532.125142075483, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [461.1376725523272, 508.77105302613785, 517.9715115668638, 522.5923779452027, 522.9123423279578, 39321.410178847305, 41033.0, 41038.17923641526, 41196.58803411969, 42955.0, 40964.97587560312, 41353.79323103388, 41357.759267222034, 41440.963975900544, 41594.58405415945, 9529.857052144242, 9700.544918262281, 9735.659251926185, 9754.853677194847, 9759.756006099833, 9514.191293740041, 9592.99273576275, 9594.424334539915, 9617.75955601112, 9637.759056023586, 9545.47499887506, 9578.563215517694, 9622.134007939285, 9636.855447168264, 9639.325247232697, 9544.856827147598, 9591.568379422943, 9631.759206019871, 9635.759106022335, 9673.758156046084, 9438.716838494856, 9505.952470237673, 9527.857082143704, 9532.237421006303, 9576.856347154799, 39902.401463978065, 40093.79953100216, 40187.7990610048, 40354.394684079765, 40465.19069618591, 3301.851416686255, 3349.832508374596, 3352.832358382071, 3403.3363494118657, 3448.517207590939, 30226.54660180099, 30698.772049118008, 30880.919167829245, 30914.0, 31121.37757244865, 3201.823899685516, 3221.0658908916384, 3222.1783445221554, 3305.3389322135667, 3323.202431416443, 477166.0, 485726.0, 489046.0, 489998.0, 490206.0, 57875.0, 57921.49745774145, 57998.0, 58017.12974305389, 58149.0, 61282.693586532674, 61447.38552614506, 61501.69249153726, 61563.53745850116, 61632.69183654053, 15226.923865380824, 15388.538343849703, 15462.768058479132, 15483.22583870802, 15538.922305388402, 12003.279803211835, 12007.219530730474, 12096.516139354504, 12104.334261615519, 12111.273323600612, 13307.534236301683, 15413.0, 16206.270717817726, 16281.023138611721, 16444.83555164457, 3042.726154646048, 6119.112728654321, 6625.4037136657935, 6770.763023294161, 6861.862762744817, 856.8971723393232, 876.4259410086404, 877.3639111644007, 908.8136931928939, 1021.4841505039907, 25526.489470210865, 26809.659517024073, 28014.859925700242, 28082.719172808418, 29852.0, 857.8455877941906, 860.489840330229, 868.7958329792493, 998.6155330197926, 1078.692572616816, 15202.695946081016, 15221.847781522265, 15431.691366172614, 15562.766558501631, 15583.298751556207, 581283.0, 618244.0, 619007.0, 619076.0461976973, 627228.0, 44534.10931781411, 53553.447956923315, 55934.44065559374, 56214.718926405105, 56434.717826410604, 1121.3103941076179, 1341.2824139085542, 1342.0203251626367, 1345.1122259308809, 1349.5479014530047, 28077.438451231268, 29114.272143195743, 29536.409271814013, 30121.548176776927, 30310.84844575763, 49446.50553494563, 49745.75127124341, 49798.75100624474, 49883.00233995227, 50130.74934625303, 557279.0, 559310.0, 561592.0, 562713.0, 563243.0, 26126.0, 26314.0, 26433.60349594719, 26591.069312573967, 26771.732282676923, 46559.53440465552, 46920.0, 47085.7645711783, 47449.52550474451, 47585.81035474141, 37613.2477350446, 38427.74405966177, 40166.0, 41504.377434337905, 41884.58115418928, 37136.62863371332, 37481.6251837489, 37586.06034849041, 38557.036074098374, 38819.805900970314, 37601.87194384173, 38378.848634541006, 38434.0391490215, 38618.22763544769, 38752.418713718755, 31655.359633642427, 32166.83916580402, 34183.0, 34323.4851477233, 34627.30745385129, 23353.0, 24781.1326603568, 24849.751502484745, 25486.617700734132, 25627.487450251265], "value": 19362.205521315333, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [328.5269212334239, 353.79303107682006, 355.3284292686821, 367.56994316649536, 382.8162482008634, 18468.538286542844, 19153.425397238087, 20079.99999999998, 20089.899550502265, 20357.694634580494, 16267.349306027745, 19157.0, 19626.3130790422, 20324.898375508103, 20441.488962775937, 5716.942830571704, 5857.531397488212, 5879.970600146993, 6019.699015049252, 6025.638461692291, 5590.217369568264, 5931.466168044875, 5998.430149135826, 6009.639421634694, 6011.699415029253, 5600.691961942091, 5864.736086876101, 5904.202932604107, 5906.9704651476895, 5910.645361278337, 5704.1443783432405, 5766.971165144189, 5830.387809280007, 5841.386654401269, 5864.2962844458725, 4826.155422800995, 5008.62435317349, 5020.648554601179, 5084.872878178076, 5092.694438333712, 21123.47191320214, 21222.363329100153, 22766.089356425724, 24566.0, 24649.753502464926, 1817.0, 2061.711360409544, 2257.8645281283175, 2378.5837478441204, 2400.303911865557, 10543.683689489251, 14893.192850948468, 15183.468578599806, 15404.0, 15769.763453548207, 1612.7822743929537, 1832.404468547714, 1841.49358926295, 1848.3161230344858, 1900.5862554555774, 107735.0, 113138.73722525586, 113285.53859075578, 113410.0, 114741.27888081686, 8251.422400431911, 10101.444420556792, 10322.432266225429, 10472.633457828939, 10767.83848242277, 12686.746265074647, 12879.227246365248, 12903.677408064874, 12937.805932911015, 12952.899003584613, 7847.2152784721075, 8146.022477302761, 8154.633041513028, 8223.835523289621, 8277.751667449986, 8123.634436450374, 8156.143604921516, 8182.345412366996, 8290.005199375993, 8305.584720763938, 5068.391792984866, 5244.5279924806955, 5273.44628813969, 6421.29365769765, 6542.116814230113, 1728.4555365059966, 1833.120102350879, 1843.64970655575, 1862.2644055597964, 1888.8205620466033, 767.808047988003, 826.9456443035178, 893.7854914820526, 919.2462181011506, 924.7641851327868, 18142.274309027754, 19778.406647800588, 20387.98060096989, 21486.892565537073, 21506.46233844135, 888.644542183119, 909.7952960583948, 953.7663272498144, 969.345691658128, 973.493783232715, 9718.75703107428, 10329.97427420023, 10768.300060496043, 11355.94322028401, 11459.4270286487, 576394.0, 579560.0, 590536.0, 601256.0, 601690.0, 112565.0, 113311.0, 118847.0, 123495.0, 125004.0, 971.820213260549, 1011.3022014809758, 1013.5945621751358, 1017.3031473440717, 1017.4912543728136, 15775.0, 18072.457826265232, 19211.90394048021, 19246.8075319245, 19268.807311926703, 31918.84040579875, 52835.20747188872, 56167.71916140393, 56256.156157658486, 57463.71268143632, 59459.0, 62392.0, 74789.0, 82061.0, 82972.0, 7696.538207707556, 7912.36701063925, 7953.363730901629, 7981.920180798118, 8181.386396020203, 10356.844647330145, 12561.309127998047, 12791.232526048467, 12812.039097067944, 12846.807297890726, 9412.67055653049, 10668.8933110671, 10937.890621094006, 11272.943635281772, 11445.198836081394, 8053.798655033671, 10481.109105726098, 10597.4701264938, 10676.83984740214, 10730.195235357223, 9669.0, 10420.426876521864, 11527.76944461123, 11562.537498500302, 11743.647690569296, 7831.804204894923, 7872.425514896929, 8062.919370806451, 8065.032196136502, 8208.753737387888, 8134.471259368122, 9897.703068907944, 10658.520366583678, 11094.11247100214, 11172.497237624164], "value": 9333.350133833277, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1333.2533781082593, 1433.8781203597694, 1473.8894582906278, 1620.1170362152625, 1829.871908966373, 7576.431767617423, 7795.92204077959, 8048.91951080489, 8533.232009119185, 8565.486070835746, 8008.399370047256, 8289.585520723955, 8309.335253179746, 8435.198656127674, 8498.6175622097, 2241.7421996470453, 2258.6499092640665, 2263.875486848223, 2266.795988361047, 2276.6243569810963, 1911.2737159879296, 2233.184887516053, 2269.5120549081944, 2282.942926426844, 2291.7249930008425, 2215.4904371994453, 2220.755716871143, 2233.1179184222224, 2234.9888250558724, 2251.1108112295633, 2218.9889050554643, 2219.1456289328644, 2245.741739699939, 2249.0666373454997, 2255.6954811100536, 1859.646667133254, 2229.1194977983605, 2244.842860999731, 2247.932562023142, 2252.8310376721647, 8366.07973122956, 8377.0, 8404.663813447454, 8476.915230847675, 8489.023762267356, 4937.136001199776, 5110.616703747197, 5114.2840002399325, 5117.976404719034, 5126.077306084903, 3193.9680603194133, 3222.0, 3378.814165220912, 3408.6761757632985, 3426.732109119617, 1009.9798004039878, 1034.5086084110062, 1050.4747626186906, 1057.7672911959357, 1058.9205809564314, 10495.580176792919, 10690.144788416908, 10815.134789216923, 10891.564337426493, 10917.0, 4116.361963895601, 4147.0, 4305.504866940342, 4308.89227769302, 4309.267424537836, 8387.580620968927, 8439.156084391512, 8510.276626486819, 8514.914850851535, 8574.442661227, 4961.305417241589, 4973.32860063894, 4986.276989836453, 4986.476419975923, 4989.126902791997, 4167.74993500391, 4180.47744031996, 4635.6523260755575, 4671.7430541320555, 4714.316424118484, 2905.956410653842, 3010.9397812044076, 3305.190228394011, 3557.21741216937, 3661.6338366163704, 882.7572417585216, 899.3524662243306, 1102.9944850275858, 1116.8548088748412, 1125.0549538387793, 849.6643825688818, 854.0946596607616, 859.3683642522741, 875.7241468937265, 916.9954150229339, 4853.611711063107, 5321.8935621287355, 5382.085045542267, 5573.386927437979, 5622.613439127201, 371.0612151257323, 371.6525049079066, 382.1439974457251, 386.7679392364615, 387.6201322703766, 3292.9627167442177, 3447.0, 3650.5071815304595, 3670.44943258508, 3737.65479895492, 9794.510274486247, 10136.645217417356, 10153.441560714084, 10218.233632477446, 10247.923967983403, 10040.64857729976, 10198.745031374276, 10207.795844083077, 10217.642382516577, 10239.18086553074, 6340.365963403715, 7652.387808975269, 7925.207479252144, 7928.841423171504, 7957.244061814118, 5262.526372626482, 5324.760385782572, 5371.301730774974, 5386.461353864739, 5465.426130256265, 9227.492487913229, 9275.67535136267, 9307.255419566554, 9346.626134954799, 9410.058994100535, 9154.725358239264, 9307.301952353466, 9404.576794044147, 9425.62297508092, 9427.198688111588, 6526.151600292122, 6733.0, 6824.249332573314, 6862.0, 7064.788056358318, 7002.439804815501, 7659.846803063796, 7773.494722842997, 7905.169957154646, 7905.802354941173, 6709.295523969913, 6781.559198652072, 7138.0, 7172.928270717226, 7246.311600397954, 7017.789466316019, 7024.754133605299, 7026.402755765817, 7055.082839230867, 7096.680649370687, 6639.302873198245, 6658.034584985053, 6736.932630673827, 6741.2921643226755, 6755.4257888080065, 4723.858284251478, 4768.76156192196, 4783.521647835189, 4817.8313759018265, 4880.0, 4616.238320677185, 4699.859004229878, 4710.858674239778, 4883.536064073907, 4900.166971614763], "value": 4173.181876029011, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [297.3324885631756, 297.33990540998974, 306.7469337796318, 312.45632599277235, 313.7458658486626, 5284.260203571503, 5419.864503387417, 5426.670465735895, 5556.388797232301, 5566.916496252559, 4247.490301163857, 5253.502751715761, 5380.923815236958, 5452.6183167178315, 5470.878469913659, 3618.855245790165, 3622.2832875343493, 3825.6036546660475, 4010.9799451002705, 4147.320335264208, 3947.940780888289, 4059.97970010151, 4092.5907409258984, 4139.958600413987, 4181.393697913801, 3775.641314075172, 4010.977200813803, 4074.633283004529, 4100.36444351126, 4104.179164167163, 3708.9985703860075, 3840.231953609289, 4091.7954102294916, 4139.606737359946, 4150.958490415087, 3080.537919312099, 3217.7264932480766, 3285.9342813143603, 3345.698887100148, 3512.262424890776, 4948.723587149915, 5243.689077730567, 5278.205410160525, 5302.628815982883, 5346.812861549827, 4690.9530904690855, 4690.95309046912, 4691.8592442226445, 4698.7415692137265, 4703.952960470386, 4887.633427492952, 5019.347484826985, 5434.91847622286, 5661.915071273934, 5759.251297331362, 3540.8583656653705, 3848.942265866014, 3861.1698484825615, 3864.768113913174, 3866.5360156781085, 4991.775370108354, 5246.344206974167, 5983.222181116471, 6021.969890150521, 6022.427869352405, 3776.6789822864816, 3873.0, 3890.6771697622944, 3904.2386734586485, 3912.3349030664854, 3953.466282051944, 3992.121733218687, 4003.0, 4008.7594744315434, 4018.3323920572634, 4385.232584297735, 4441.178381999341, 4672.602828759594, 4707.529247075334, 4721.834735784231, 5514.511082007835, 5798.681072540966, 5829.300483941955, 5841.970790146022, 5871.823845284648, 1167.8656954450346, 1277.4762347437693, 1291.593148158327, 1323.9536616218386, 1343.596920923719, 777.7627823513883, 815.502543448496, 883.1433509495779, 904.3579058868277, 907.2424525521131, 4357.4988876279, 5251.684898906078, 5328.573714102862, 5354.330708661418, 5368.436314187033, 4873.317735516959, 5110.437851836295, 5715.257016587817, 5744.310682718101, 5857.355690874, 5171.689698618095, 5337.332833395825, 5345.091334473149, 5413.618008076433, 5441.211024401439, 2420.031987205097, 2439.865807380611, 2557.5012872489688, 2576.750276116082, 2581.6833414958132, 4742.834000810024, 5030.7233102179, 5045.7981680732355, 5056.469070747592, 5115.900081482425, 4147.4815648044, 4780.689255198401, 4845.53967373099, 5004.42449118356, 5050.823221187315, 4505.414296141481, 5058.0, 5070.771815268322, 5095.394950590552, 5145.382554093458, 4636.863968327782, 4703.45910220329, 4801.423829140525, 4899.167141585867, 4913.975430122827, 4841.370621819141, 4851.611871050378, 4910.9017819642695, 5261.73691315425, 5812.476877081083, 5241.0, 5902.793402230901, 6238.968805155945, 6246.312905580292, 6262.780802671885, 3519.2433626770367, 3576.624454432247, 3659.8654417130774, 3998.8800335988803, 4064.30906745848, 5166.535011848951, 5290.920636190537, 5604.8318550443555, 5923.674197919156, 5931.6441013539325, 5134.717590532557, 5641.887162256649, 5697.715114244188, 5771.509421699203, 5851.356350801323, 4780.976095119502, 4820.4456487504385, 5383.608326754276, 5392.62960649594, 5810.244668193108, 4267.914641707086, 4417.690761646654, 4933.313352315243, 4978.8755281118265, 5053.567818880083, 4696.365990591226, 5225.503577160163, 5812.854678632899, 5834.591578589628, 5844.26946631671, 4426.82292708301, 5267.78928842842, 5680.971595141998, 5684.7441865117, 5696.174054762119], "value": 4008.2484564702754, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [688.1673175457693, 699.5348093517807, 771.9382449404047, 781.1173374087281, 794.1938931984035, 12213.450394732236, 15556.922215388935, 15594.06435613864, 15640.3743850246, 15654.982426142293, 13966.371513281902, 13993.230372329517, 14134.858651413482, 14163.787543186834, 14164.362603682834, 795.7891158842912, 839.8698201778734, 857.2627540315324, 857.3998201259112, 861.6984055580559, 859.7635650196215, 862.7713655881165, 868.0, 872.7512658892217, 873.4409977614332, 903.0969030969031, 905.728281515546, 906.995465022674, 907.5326207003399, 907.8728977943093, 900.2662829793697, 901.5852707754441, 905.2983937448464, 905.642271302835, 906.3519583497765, 659.4987809264945, 674.3930462583664, 677.0893148714971, 677.474956908395, 679.6601699150425, 12340.691482712915, 12441.75116497665, 12536.122471427003, 12554.246745195318, 13572.185668859802, 11543.826842597367, 11562.942185289105, 11603.709907252301, 11642.6507204784, 11751.823722644167, 7906.565138917357, 9790.069943355442, 10419.947900260526, 10434.791304173874, 10489.318194317346, 3616.6383361663625, 3665.0, 3706.592274849764, 3733.1600389912323, 3750.0, 87879.56060219722, 89231.55384223102, 89258.10741892563, 93841.5307923456, 94449.58325625121, 7835.294823465915, 8889.511076890703, 8931.955340223258, 9003.234725048313, 9079.45523268606, 15497.302621382065, 17074.317027318768, 17783.644327113387, 19122.0, 19173.520661983563, 7459.813504662318, 7578.242175782378, 7742.806429839186, 7863.685452581947, 8004.63979120941, 4345.391645169679, 4356.607905288539, 4375.365571992043, 4437.534058923767, 4469.4860091089295, 3487.302539492112, 3570.589382221026, 3581.8209089545417, 3605.7656252343513, 3613.33153366628, 624.54720327762, 629.4020680353682, 645.1000853808911, 664.3920812456548, 665.7736369634348, 564.3256308711051, 587.49475451112, 667.4093427316859, 671.1042941739921, 673.2762280548333, 7346.412287017026, 9171.532773980169, 9252.213561847184, 9283.860742088875, 9513.476758778334, 628.6416742456847, 630.1335663462738, 650.7201903181677, 658.134553062721, 659.9109120268703, 4343.522212556616, 4365.257906155961, 4610.331501932201, 4615.284630882219, 4649.256119020941, 103602.48198758958, 104600.0, 105492.47253763836, 115665.0, 122511.387443064, 98563.0, 98749.0, 99298.0, 100365.49817250867, 100694.0, 86975.56512217525, 87301.12698873057, 88033.1196688025, 88456.55771721229, 90448.54775726079, 8497.107803680772, 8848.336374771789, 8924.821503570021, 9045.412048216846, 9094.226990705863, 57522.7123864378, 59088.40911590829, 59220.70389648024, 59863.50341241504, 59888.101678473984, 75254.24745752472, 84152.57923710342, 85463.0, 85623.5718821402, 87287.0, 11045.72385690332, 11419.885801141882, 11647.12646551529, 11768.58809941648, 12130.575429859913, 12353.938230308791, 12459.813102803284, 12471.250693948752, 12920.160189587647, 12930.965522758344, 11665.24175928562, 11795.764084718428, 11988.76022479563, 12177.939110304393, 12353.505859765508, 10249.641262555775, 10367.63713270032, 11608.6517404478, 12222.694432639255, 12377.814332784836, 10040.9959004099, 11579.942100289445, 11966.820497692368, 12022.0, 12347.938260308641, 9728.9027109728, 9952.800943981225, 9962.402255864672, 10041.799164016826, 10171.847422288523, 7122.7507037253445, 7255.2019277880445, 9121.635134594542, 9811.705648830546, 10159.238057145596], "value": 7136.092827451176, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1575.0, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [107.0, 120.0, 117.0, 117.0, 115.0, 113.0, 117.0, 126.0, 120.0, 118.0], "value": 117.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [176.0, 103.0, 150.0, 187.0, 150.0, 115.0, 114.0, 114.0, 148.0, 112.0], "value": 136.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [194.0, 206.0, 216.0, 248.0, 197.0, 199.0, 200.0, 200.0, 202.0, 196.0], "value": 205.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 59.0, 58.0, 84.0, 57.0, 54.0, 62.0, 73.0, 99.0, 84.0], "value": 71.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [122.0, 101.0, 109.0, 72.0, 71.0, 73.0, 95.0, 104.0, 104.0, 127.0], "value": 97.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 88.0, 88.0, 86.0, 89.0, 87.0, 93.0, 87.0, 78.0, 130.0], "value": 90.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 96.0, 95.0, 96.0, 95.0, 94.0, 98.0, 95.0, 94.0, 95.0], "value": 95.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [122.0, 82.0, 128.0, 137.0, 128.0, 89.0, 90.0, 90.0, 88.0, 90.0], "value": 104.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 57.0, 56.0, 57.0, 59.0, 60.0, 71.0, 85.0, 90.0, 78.0], "value": 67.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 74.0, 73.0, 82.0, 77.0, 72.0, 72.0, 72.0, 71.0, 74.0], "value": 73.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 88.0, 85.0, 91.0, 91.0, 86.0, 85.0, 91.0, 107.0, 109.0], "value": 92.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 149.0, 148.0, 147.0, 150.0, 183.0, 170.0, 154.0, 234.0, 177.0], "value": 166.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [175.0, 176.0, 175.0, 175.0, 177.0, 174.0, 170.0, 182.0, 178.0, 214.0], "value": 179.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 68.0, 83.0, 98.0, 93.0, 93.0, 68.0, 67.0, 68.0, 70.0], "value": 77.1, "unit": "score"}]}]} 13:06:17 INFO - Return code: 0 13:06:17 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 13:06:17 ERROR - # TBPL SUCCESS # 13:06:17 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 13:06:17 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 13:06:17 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 13:06:17 INFO - 2016-11-03 13:06:17.205 screenresolution[1947:21440] starting screenresolution argv=screenresolution get 13:06:17 INFO - 2016-11-03 13:06:17.207 screenresolution[1947:21440] Display 0: 1600x1200x32@60 13:06:17 INFO - 2016-11-03 13:06:17.213 screenresolution[1948:21443] starting screenresolution argv=screenresolution list 13:06:17 INFO - Available Modes on Display 0 13:06:17 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 13:06:17 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 13:06:17 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 13:06:17 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 13:06:17 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 13:06:17 INFO - Intel Iris: 13:06:17 INFO - Chipset Model: Intel Iris 13:06:17 INFO - Type: GPU 13:06:17 INFO - Bus: Built-In 13:06:17 INFO - VRAM (Dynamic, Max): 1536 MB 13:06:17 INFO - Vendor: Intel (0x8086) 13:06:17 INFO - Device ID: 0x0a2e 13:06:17 INFO - Revision ID: 0x0009 13:06:17 INFO - Displays: 13:06:17 INFO - Display: 13:06:17 INFO - Resolution: 1600 x 1200 @ 60 Hz 13:06:17 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 13:06:17 INFO - Display Serial Number: 1600x1200 60 13:06:17 INFO - Main Display: Yes 13:06:17 INFO - Mirror: Off 13:06:17 INFO - Online: Yes 13:06:17 INFO - Rotation: Supported 13:06:17 INFO - Return code: 0 13:06:17 INFO - Running post-action listener: _resource_record_post_action 13:06:17 INFO - [mozharness: 2016-11-03 20:06:17.447733Z] Finished run-tests step (success) 13:06:17 INFO - Running post-run listener: _resource_record_post_run 13:06:17 INFO - Total resource usage - Wall time: 1213s; CPU: 55.0%; Read bytes: 2672128; Write bytes: 712271360; Read time: 16; Write time: 2129 13:06:17 INFO - TinderboxPrint: CPU usage
54.7% 13:06:17 INFO - TinderboxPrint: I/O read bytes / time
2,672,128 / 16 13:06:17 INFO - TinderboxPrint: I/O write bytes / time
712,271,360 / 2,129 13:06:17 INFO - TinderboxPrint: CPU idle
2,197.4 (45.3%) 13:06:17 INFO - TinderboxPrint: CPU system
985.3 (20.3%) 13:06:17 INFO - TinderboxPrint: CPU user
1,667.1 (34.4%) 13:06:17 INFO - TinderboxPrint: Swap in / out
408,068,096 / 0 13:06:17 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 402993664; Write bytes: 397649920; Read time: 29948; Write time: 760 13:06:17 INFO - run-tests - Wall time: 1179s; CPU: 55.0%; Read bytes: 964096; Write bytes: 302157312; Read time: 10; Write time: 1328 13:06:18 INFO - Running post-run listener: _upload_blobber_files 13:06:18 INFO - Blob upload gear active. 13:06:18 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 13:06:18 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 13:06:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Cedar', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 13:06:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Cedar -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 13:06:18 INFO - (blobuploader) - INFO - Open directory for files ... 13:06:18 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 13:06:18 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:06:18 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:06:19 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 13:06:19 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:06:19 INFO - (blobuploader) - INFO - Done attempting. 13:06:19 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 13:06:19 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:06:19 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:06:20 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 13:06:20 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:06:20 INFO - (blobuploader) - INFO - Done attempting. 13:06:20 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 13:06:20 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:06:20 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:06:22 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 13:06:22 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:06:22 INFO - (blobuploader) - INFO - Done attempting. 13:06:22 INFO - (blobuploader) - INFO - Iteration through files over. 13:06:22 INFO - Return code: 0 13:06:22 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 13:06:22 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 13:06:22 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/ca0de866de15d263b01ef15486f74395f296f52823a9a3f4408df01d141d21dea6fce057ec3addb4bde38e99834d2e20198505a67237a6f0ef42159905623271", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/418a831b5d5b973160cd10698baaa62cd70333e76becfe480fe19e8335700f467b7a61f8ffa106da46ac9df2e18e6b8a98b06ea23f58fc226e06e63d143aa05f", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/774c8f9b75df0c5818d1a1d569148c51360a132c292e1aa8713ae7d2a728b151c5e9ff5f61d417ce9da178d67421ac78b70abb0bee716cabbf2735215d723181"} 13:06:22 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 13:06:22 INFO - Writing to file /builds/slave/test/properties/blobber_files 13:06:22 INFO - Contents: 13:06:22 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/ca0de866de15d263b01ef15486f74395f296f52823a9a3f4408df01d141d21dea6fce057ec3addb4bde38e99834d2e20198505a67237a6f0ef42159905623271", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/418a831b5d5b973160cd10698baaa62cd70333e76becfe480fe19e8335700f467b7a61f8ffa106da46ac9df2e18e6b8a98b06ea23f58fc226e06e63d143aa05f", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/774c8f9b75df0c5818d1a1d569148c51360a132c292e1aa8713ae7d2a728b151c5e9ff5f61d417ce9da178d67421ac78b70abb0bee716cabbf2735215d723181"} 13:06:22 INFO - Running post-run listener: copy_logs_to_upload_dir 13:06:22 INFO - Copying logs to upload dir... 13:06:22 INFO - mkdir: /builds/slave/test/build/upload/logs 13:06:22 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1274.020914 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-11-03 13:06:22.713343) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-03 13:06:22.717259) ========= 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.4xRQwWsw7D/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.4kiiQkIpdd/Listeners TMPDIR=/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/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/Cedar/sha512/ca0de866de15d263b01ef15486f74395f296f52823a9a3f4408df01d141d21dea6fce057ec3addb4bde38e99834d2e20198505a67237a6f0ef42159905623271", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/418a831b5d5b973160cd10698baaa62cd70333e76becfe480fe19e8335700f467b7a61f8ffa106da46ac9df2e18e6b8a98b06ea23f58fc226e06e63d143aa05f", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/774c8f9b75df0c5818d1a1d569148c51360a132c292e1aa8713ae7d2a728b151c5e9ff5f61d417ce9da178d67421ac78b70abb0bee716cabbf2735215d723181"} build_url:https://queue.taskcluster.net/v1/task/N4toZVw-SBayU-5p0x_HwQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010937 build_url: 'https://queue.taskcluster.net/v1/task/N4toZVw-SBayU-5p0x_HwQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/ca0de866de15d263b01ef15486f74395f296f52823a9a3f4408df01d141d21dea6fce057ec3addb4bde38e99834d2e20198505a67237a6f0ef42159905623271", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/418a831b5d5b973160cd10698baaa62cd70333e76becfe480fe19e8335700f467b7a61f8ffa106da46ac9df2e18e6b8a98b06ea23f58fc226e06e63d143aa05f", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/774c8f9b75df0c5818d1a1d569148c51360a132c292e1aa8713ae7d2a728b151c5e9ff5f61d417ce9da178d67421ac78b70abb0bee716cabbf2735215d723181"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-03 13:06:22.749254) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-03 13:06:22.749559) ========= 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.4xRQwWsw7D/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.4kiiQkIpdd/Listeners TMPDIR=/var/folders/m7/_sns9kks7zdb5z2vgrvgs0wh00000w/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.004657 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-03 13:06:22.797650) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-03 13:06:22.798000) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-03 13:06:22.798289) ========= ========= Total master_lag: 0.18 =========