builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0299 starttime: 1479737639.2 results: success (0) buildid: 20161121030224 builduid: 6a52e0bfbe7141c99c5ed5a0d8094d11 revision: b7f895c1dc2e91530240efbf50ac063a0f8a9cb5 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-11-21 06:13:59.203622) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-11-21 06:13:59.204074) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-21 06:13:59.204378) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-21 06:13:59.228867) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-21 06:13:59.229206) ========= 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.vnd6pUV2Oy/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.k0TzGD54Zq/Listeners TMPDIR=/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/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-21 06:13:59-- 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% 14.4M=0.001s 2016-11-21 06:13:59 (14.4 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.091162 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-21 06:13:59.342570) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-21 06:13:59.342888) ========= 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.vnd6pUV2Oy/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.k0TzGD54Zq/Listeners TMPDIR=/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/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.037399 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-21 06:13:59.404875) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-11-21 06:13:59.408249) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev b7f895c1dc2e91530240efbf50ac063a0f8a9cb5 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev b7f895c1dc2e91530240efbf50ac063a0f8a9cb5 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.vnd6pUV2Oy/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.k0TzGD54Zq/Listeners TMPDIR=/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/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-21 06:13:59,789 truncating revision to first 12 chars 2016-11-21 06:13:59,789 Setting DEBUG logging. 2016-11-21 06:13:59,789 attempt 1/10 2016-11-21 06:13:59,789 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/b7f895c1dc2e?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-11-21 06:14:01,457 unpacking tar archive at: mozilla-central-b7f895c1dc2e/testing/mozharness/ program finished with exit code 0 elapsedTime=1.944236 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-11-21 06:14:01.377887) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-21 06:14:01.378278) ========= script_repo_revision: b7f895c1dc2e91530240efbf50ac063a0f8a9cb5 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-21 06:14:01.378693) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 1 secs) (at 2016-11-21 06:14:01.378965) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 1 secs) (at 2016-11-21 06:14:02.673948) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 17 secs) (at 2016-11-21 06:14:02.674376) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 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', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.vnd6pUV2Oy/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.k0TzGD54Zq/Listeners TMPDIR=/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 06:14:03 INFO - MultiFileLogger online at 20161121 06:14:03 in /builds/slave/test 06:14:03 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 06:14:03 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 06:14:03 INFO - {'append_to_log': False, 06:14:03 INFO - 'base_work_dir': '/builds/slave/test', 06:14:03 INFO - 'blob_upload_branch': 'Firefox', 06:14:03 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 06:14:03 INFO - 'branch': 'Firefox', 06:14:03 INFO - 'buildbot_json_path': 'buildprops.json', 06:14:03 INFO - 'config_files': ('talos/mac_config.py',), 06:14:03 INFO - 'default_actions': ('clobber', 06:14:03 INFO - 'read-buildbot-config', 06:14:03 INFO - 'download-and-extract', 06:14:03 INFO - 'populate-webroot', 06:14:03 INFO - 'create-virtualenv', 06:14:03 INFO - 'install', 06:14:03 INFO - 'run-tests'), 06:14:03 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 06:14:03 INFO - 'download_minidump_stackwalk': True, 06:14:03 INFO - 'download_symbols': 'ondemand', 06:14:03 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 06:14:03 INFO - 'tooltool.py': '/tools/tooltool.py', 06:14:03 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 06:14:03 INFO - '/tools/misc-python/virtualenv.py')}, 06:14:03 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 06:14:03 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 06:14:03 INFO - 'installer_path': 'installer.exe', 06:14:03 INFO - 'log_level': 'info', 06:14:03 INFO - 'log_name': 'talos', 06:14:03 INFO - 'log_to_console': True, 06:14:03 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 06:14:03 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 06:14:03 INFO - 'opt_config_files': (), 06:14:03 INFO - 'pip_index': False, 06:14:03 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 06:14:03 INFO - 'cmd': ('bash', 06:14:03 INFO - '-c', 06:14:03 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 06:14:03 INFO - 'enabled': True, 06:14:03 INFO - 'halt_on_failure': False, 06:14:03 INFO - 'name': 'check_screen_resolution'},), 06:14:03 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 06:14:03 INFO - 'cmd': ('bash', 06:14:03 INFO - '-c', 06:14:03 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 06:14:03 INFO - 'enabled': True, 06:14:03 INFO - 'halt_on_failure': False, 06:14:03 INFO - 'name': 'check_screen_resolution'},), 06:14:03 INFO - 'run_cmd_checks_enabled': True, 06:14:03 INFO - 'sps_profile': False, 06:14:03 INFO - 'sps_profile_interval': 0, 06:14:03 INFO - 'suite': 'dromaeojs-e10s', 06:14:03 INFO - 'system_bits': '32', 06:14:03 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 06:14:03 INFO - 'title': 't-yosemite-r7-0299', 06:14:03 INFO - 'tooltool_cache': '/builds/tooltool_cache', 06:14:03 INFO - 'use_talos_json': True, 06:14:03 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 06:14:03 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 06:14:03 INFO - 'work_dir': 'build'} 06:14:03 INFO - [mozharness: 2016-11-21 14:14:03.161289Z] Running clobber step. 06:14:03 INFO - Running pre-action listener: _resource_record_pre_action 06:14:03 INFO - Running main action method: clobber 06:14:03 INFO - rmtree: /builds/slave/test/build 06:14:03 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 06:14:05 INFO - Running post-action listener: _resource_record_post_action 06:14:05 INFO - [mozharness: 2016-11-21 14:14:05.494170Z] Finished clobber step (success) 06:14:05 INFO - [mozharness: 2016-11-21 14:14:05.494343Z] Running read-buildbot-config step. 06:14:05 INFO - Running pre-action listener: _resource_record_pre_action 06:14:05 INFO - Running main action method: read_buildbot_config 06:14:05 INFO - Using buildbot properties: 06:14:05 INFO - { 06:14:05 INFO - "project": "", 06:14:05 INFO - "product": "firefox", 06:14:05 INFO - "script_repo_revision": "production", 06:14:05 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 06:14:05 INFO - "repository": "", 06:14:05 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 06:14:05 INFO - "buildid": "20161121030224", 06:14:05 INFO - "stage_platform": "macosx64", 06:14:05 INFO - "basedir": "/builds/slave/test", 06:14:05 INFO - "nightly_build": "True", 06:14:05 INFO - "buildnumber": 175, 06:14:05 INFO - "slavename": "t-yosemite-r7-0299", 06:14:05 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 06:14:05 INFO - "platform": "yosemite_r7", 06:14:05 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 06:14:05 INFO - "branch": "mozilla-central", 06:14:05 INFO - "repo_path": "mozilla-central", 06:14:05 INFO - "revision": "b7f895c1dc2e91530240efbf50ac063a0f8a9cb5", 06:14:05 INFO - "pgo_build": "False", 06:14:05 INFO - "builduid": "6a52e0bfbe7141c99c5ed5a0d8094d11", 06:14:05 INFO - "slavebuilddir": "test" 06:14:05 INFO - } 06:14:05 INFO - Found installer url https://queue.taskcluster.net/v1/task/KvETyyTcSmGQ7MYt6svQ7Q/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg. 06:14:05 INFO - Running post-action listener: _resource_record_post_action 06:14:05 INFO - [mozharness: 2016-11-21 14:14:05.497730Z] Finished read-buildbot-config step (success) 06:14:05 INFO - [mozharness: 2016-11-21 14:14:05.497842Z] Running download-and-extract step. 06:14:05 INFO - Running pre-action listener: _resource_record_pre_action 06:14:05 INFO - Running main action method: download_and_extract 06:14:05 INFO - mkdir: /builds/slave/test/build/tests 06:14:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:14:05 INFO - trying https://queue.taskcluster.net/v1/task/KvETyyTcSmGQ7MYt6svQ7Q/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json 06:14:05 INFO - Downloading https://queue.taskcluster.net/v1/task/KvETyyTcSmGQ7MYt6svQ7Q/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json 06:14:05 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/KvETyyTcSmGQ7MYt6svQ7Q/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json'}, attempt #1 06:14:06 INFO - Downloaded 1199 bytes. 06:14:06 INFO - Reading from file /builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json 06:14:06 INFO - Using the following test package requirements: 06:14:06 INFO - {u'common': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 06:14:06 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 06:14:06 INFO - u'firefox-53.0a1.en-US.mac.cppunittest.tests.zip'], 06:14:06 INFO - u'gtest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 06:14:06 INFO - u'firefox-53.0a1.en-US.mac.gtest.tests.zip'], 06:14:06 INFO - u'jittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 06:14:06 INFO - u'jsshell-mac.zip'], 06:14:06 INFO - u'mochitest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 06:14:06 INFO - u'firefox-53.0a1.en-US.mac.mochitest.tests.zip'], 06:14:06 INFO - u'mozbase': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 06:14:06 INFO - u'reftest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 06:14:06 INFO - u'firefox-53.0a1.en-US.mac.reftest.tests.zip'], 06:14:06 INFO - u'talos': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 06:14:06 INFO - u'firefox-53.0a1.en-US.mac.talos.tests.zip'], 06:14:06 INFO - u'web-platform': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 06:14:06 INFO - u'firefox-53.0a1.en-US.mac.web-platform.tests.zip'], 06:14:06 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 06:14:06 INFO - u'firefox-53.0a1.en-US.mac.xpcshell.tests.zip']} 06:14:06 INFO - Downloading packages: [u'firefox-53.0a1.en-US.mac.common.tests.zip'] for test suite category: common 06:14:06 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/KvETyyTcSmGQ7MYt6svQ7Q/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 06:14:06 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/KvETyyTcSmGQ7MYt6svQ7Q/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 06:14:06 INFO - Fetch https://queue.taskcluster.net/v1/task/KvETyyTcSmGQ7MYt6svQ7Q/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 06:14:07 INFO - Http code: 200 06:14:07 INFO - via: 1.1 4c2196e36fd33f377384d605b4424e95.cloudfront.net (CloudFront) 06:14:07 INFO - x-amz-cf-id: 6X1psp_z_QngRpSDR8J6Kb87VLJrRReLGy1YetfgU_-DD7UrGIsZoQ== 06:14:07 INFO - x-amz-version-id: ZgcLuuUorp7jZes0Ff_2MtNpgJS.wtCQ 06:14:07 INFO - Expected file size: 16265676 06:14:07 INFO - Obtained file size: 16265676 06:14:14 INFO - Downloading packages: [u'firefox-53.0a1.en-US.mac.common.tests.zip', u'firefox-53.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 06:14:14 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/KvETyyTcSmGQ7MYt6svQ7Q/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 06:14:14 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/KvETyyTcSmGQ7MYt6svQ7Q/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 06:14:14 INFO - Fetch https://queue.taskcluster.net/v1/task/KvETyyTcSmGQ7MYt6svQ7Q/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 06:14:14 INFO - Http code: 200 06:14:14 INFO - via: 1.1 b3bccc0e4c2cc0a33e7e8308d88cacdd.cloudfront.net (CloudFront) 06:14:14 INFO - x-amz-cf-id: LMheoQTNnPZs7TbXISDBUQWG1iT6BrkpJ7ZpVId8tUm_jTv7CHAXdQ== 06:14:14 INFO - x-amz-version-id: ZgcLuuUorp7jZes0Ff_2MtNpgJS.wtCQ 06:14:14 INFO - Expected file size: 16265676 06:14:14 INFO - Obtained file size: 16265676 06:14:21 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/KvETyyTcSmGQ7MYt6svQ7Q/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip 06:14:21 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/KvETyyTcSmGQ7MYt6svQ7Q/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip'}, attempt #1 06:14:21 INFO - Fetch https://queue.taskcluster.net/v1/task/KvETyyTcSmGQ7MYt6svQ7Q/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip into memory 06:14:22 INFO - Http code: 200 06:14:22 INFO - via: 1.1 24b0e5a3429d07ef12381da50e07f70f.cloudfront.net (CloudFront) 06:14:22 INFO - x-amz-cf-id: r3-VhI3IZc8x2of_NU-iQnv4J1hCiCHOmgSUbPpFOLosnHaGTY4rFQ== 06:14:22 INFO - x-amz-version-id: E5wDGCcJCipAP7qzIByHuaENYNJbST3P 06:14:22 INFO - Expected file size: 14409392 06:14:22 INFO - Obtained file size: 14409392 06:14:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:14:22 INFO - trying https://queue.taskcluster.net/v1/task/KvETyyTcSmGQ7MYt6svQ7Q/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 06:14:22 INFO - Downloading https://queue.taskcluster.net/v1/task/KvETyyTcSmGQ7MYt6svQ7Q/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg 06:14:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/KvETyyTcSmGQ7MYt6svQ7Q/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg'}, attempt #1 06:14:25 INFO - Downloaded 107691321 bytes. 06:14:25 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/KvETyyTcSmGQ7MYt6svQ7Q/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 06:14:25 INFO - mkdir: /builds/slave/test/properties 06:14:25 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 06:14:25 INFO - Writing to file /builds/slave/test/properties/build_url 06:14:25 INFO - Contents: 06:14:25 INFO - build_url:https://queue.taskcluster.net/v1/task/KvETyyTcSmGQ7MYt6svQ7Q/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 06:14:26 INFO - Running post-action listener: _resource_record_post_action 06:14:26 INFO - Running post-action listener: set_extra_try_arguments 06:14:26 INFO - [mozharness: 2016-11-21 14:14:26.772630Z] Finished download-and-extract step (success) 06:14:26 INFO - [mozharness: 2016-11-21 14:14:26.772777Z] Running populate-webroot step. 06:14:26 INFO - Running pre-action listener: _resource_record_pre_action 06:14:26 INFO - Running main action method: populate_webroot 06:14:26 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 06:14:26 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 06:14:26 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 06:14:26 INFO - '--tppagecycles', 06:14:26 INFO - '7'], 06:14:26 INFO - 'tests': ['tsvgm']}}, 06:14:26 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 06:14:26 INFO - 'tests': ['tresize', 'tcanvasmark']}, 06:14:26 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 06:14:26 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 06:14:26 INFO - 'tests': ['dromaeo_css', 'kraken']}, 06:14:26 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 06:14:26 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 06:14:26 INFO - 'talos_options': ['--disable-e10s'], 06:14:26 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 06:14:26 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 06:14:26 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 06:14:26 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 06:14:26 INFO - 'talos_options': ['--disable-e10s'], 06:14:26 INFO - 'tests': ['damp', 'tps']}, 06:14:26 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 06:14:26 INFO - 'tests': ['damp', 'tps']}, 06:14:26 INFO - 'g3': {'talos_options': ['--disable-e10s'], 06:14:26 INFO - 'tests': ['dromaeo_dom']}, 06:14:26 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 06:14:26 INFO - 'g4': {'talos_options': ['--disable-e10s'], 06:14:26 INFO - 'tests': ['basic_compositor_video']}, 06:14:26 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 06:14:26 INFO - 'other': {'talos_options': ['--disable-e10s'], 06:14:26 INFO - 'tests': ['a11yr', 06:14:26 INFO - 'ts_paint', 06:14:26 INFO - 'tpaint', 06:14:26 INFO - 'sessionrestore', 06:14:26 INFO - 'sessionrestore_no_auto_restore', 06:14:26 INFO - 'tabpaint']}, 06:14:26 INFO - 'other-e10s': {'tests': ['a11yr', 06:14:26 INFO - 'ts_paint', 06:14:26 INFO - 'tpaint', 06:14:26 INFO - 'sessionrestore', 06:14:26 INFO - 'sessionrestore_no_auto_restore', 06:14:26 INFO - 'tabpaint']}, 06:14:26 INFO - 'other-e10s_l64': {'tests': ['a11yr', 06:14:26 INFO - 'ts_paint', 06:14:26 INFO - 'tpaint', 06:14:26 INFO - 'sessionrestore', 06:14:26 INFO - 'sessionrestore_no_auto_restore', 06:14:26 INFO - 'tabpaint']}, 06:14:26 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 06:14:26 INFO - 'ts_paint', 06:14:26 INFO - 'tpaint', 06:14:26 INFO - 'sessionrestore', 06:14:26 INFO - 'sessionrestore_no_auto_restore', 06:14:26 INFO - 'tabpaint']}, 06:14:26 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 06:14:26 INFO - 'tests': ['a11yr', 06:14:26 INFO - 'ts_paint', 06:14:26 INFO - 'tpaint', 06:14:26 INFO - 'sessionrestore', 06:14:26 INFO - 'sessionrestore_no_auto_restore', 06:14:26 INFO - 'tabpaint']}, 06:14:26 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 06:14:26 INFO - 'tests': ['a11yr', 06:14:26 INFO - 'ts_paint', 06:14:26 INFO - 'tpaint', 06:14:26 INFO - 'sessionrestore', 06:14:26 INFO - 'sessionrestore_no_auto_restore', 06:14:26 INFO - 'tabpaint']}, 06:14:26 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 06:14:26 INFO - 'tests': ['tsvgx', 06:14:26 INFO - 'tsvgr_opacity', 06:14:26 INFO - 'tart', 06:14:26 INFO - 'tscrollx', 06:14:26 INFO - 'cart']}, 06:14:26 INFO - 'svgr-e10s': {'tests': ['tsvgx', 06:14:26 INFO - 'tsvgr_opacity', 06:14:26 INFO - 'tart', 06:14:26 INFO - 'tscrollx', 06:14:26 INFO - 'cart']}, 06:14:26 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 06:14:26 INFO - 'talos_options': ['--disable-e10s'], 06:14:26 INFO - 'tests': ['tp5o']}, 06:14:26 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 06:14:26 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 06:14:26 INFO - 'talos_options': ['--disable-e10s', 06:14:26 INFO - '--xperf_path', 06:14:26 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 06:14:26 INFO - 'tests': ['tp5n']}, 06:14:26 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 06:14:26 INFO - 'talos_options': ['--xperf_path', 06:14:26 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 06:14:26 INFO - 'tests': ['tp5n']}}, 06:14:26 INFO - 'talos.zip': {'path': '', 06:14:26 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 06:14:26 INFO - Running post-action listener: _resource_record_post_action 06:14:26 INFO - [mozharness: 2016-11-21 14:14:26.790549Z] Finished populate-webroot step (success) 06:14:26 INFO - [mozharness: 2016-11-21 14:14:26.790649Z] Running create-virtualenv step. 06:14:26 INFO - Running pre-action listener: _resource_record_pre_action 06:14:26 INFO - Running main action method: create_virtualenv 06:14:26 INFO - Creating virtualenv /builds/slave/test/build/venv 06:14:26 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 06:14:26 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 06:14:26 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 06:14:26 INFO - Using real prefix '/tools/python27' 06:14:26 INFO - New python executable in /builds/slave/test/build/venv/bin/python 06:14:27 INFO - Installing distribute.............................................................................................................................................................................................done. 06:14:30 INFO - Installing pip.................done. 06:14:30 INFO - Return code: 0 06:14:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 06:14:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 06:14:30 INFO - Reading from file tmpfile_stdout 06:14:30 INFO - Output received: 06:14:30 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 06:14:30 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 06:14:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:14:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:14:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:14:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:14:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:14:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:14:30 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10752f3f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1074a5570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fba8a5a3d80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107345d30>, '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 0x1071acdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10733db20>, '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.vnd6pUV2Oy/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.k0TzGD54Zq/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 06:14:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 06:14:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 06:14:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vnd6pUV2Oy/Render', 06:14:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:14:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:14:30 INFO - 'HOME': '/Users/cltbld', 06:14:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:14:30 INFO - 'LOGNAME': 'cltbld', 06:14:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:14:30 INFO - 'MOZ_NO_REMOTE': '1', 06:14:30 INFO - 'NO_EM_RESTART': '1', 06:14:30 INFO - 'PAGER': '/bin/cat', 06:14:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:14:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:14:30 INFO - 'PWD': '/builds/slave/test', 06:14:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:14:30 INFO - 'SHELL': '/bin/bash', 06:14:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.k0TzGD54Zq/Listeners', 06:14:30 INFO - 'TMPDIR': '/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/', 06:14:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:14:30 INFO - 'USER': 'cltbld', 06:14:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:14:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:14:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:14:30 INFO - 'XPC_FLAGS': '0x0', 06:14:30 INFO - 'XPC_SERVICE_NAME': '0', 06:14:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:14:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:14:30 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 06:14:30 INFO - Cleaning up... 06:14:30 INFO - Return code: 0 06:14:31 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 06:14:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:14:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:14:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:14:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:14:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:14:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:14:31 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10752f3f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1074a5570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fba8a5a3d80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107345d30>, '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 0x1071acdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10733db20>, '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.vnd6pUV2Oy/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.k0TzGD54Zq/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 06:14:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 06:14:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 06:14:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vnd6pUV2Oy/Render', 06:14:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:14:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:14:31 INFO - 'HOME': '/Users/cltbld', 06:14:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:14:31 INFO - 'LOGNAME': 'cltbld', 06:14:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:14:31 INFO - 'MOZ_NO_REMOTE': '1', 06:14:31 INFO - 'NO_EM_RESTART': '1', 06:14:31 INFO - 'PAGER': '/bin/cat', 06:14:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:14:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:14:31 INFO - 'PWD': '/builds/slave/test', 06:14:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:14:31 INFO - 'SHELL': '/bin/bash', 06:14:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.k0TzGD54Zq/Listeners', 06:14:31 INFO - 'TMPDIR': '/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/', 06:14:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:14:31 INFO - 'USER': 'cltbld', 06:14:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:14:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:14:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:14:31 INFO - 'XPC_FLAGS': '0x0', 06:14:31 INFO - 'XPC_SERVICE_NAME': '0', 06:14:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:14:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:14:31 INFO - Downloading/unpacking psutil>=3.1.1 06:14:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:14:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:14:31 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:14:31 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:14:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:14:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:14:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 06:14:34 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 06:14:34 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 06:14:34 INFO - Installing collected packages: psutil 06:14:34 INFO - Running setup.py install for psutil 06:14:34 INFO - building 'psutil._psutil_osx' extension 06:14:34 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 06:14:35 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 06:14:35 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 06:14:35 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 06:14:35 INFO - building 'psutil._psutil_posix' extension 06:14:35 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 06:14:35 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 06:14:35 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 06:14:35 INFO - ^ 06:14:35 INFO - 1 warning generated. 06:14:35 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 06:14:35 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 06:14:35 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 06:14:35 INFO - Successfully installed psutil 06:14:35 INFO - Cleaning up... 06:14:36 INFO - Return code: 0 06:14:36 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 06:14:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:14:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:14:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:14:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:14:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:14:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:14:36 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 0x10752f3f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1074a5570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fba8a5a3d80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107345d30>, '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 0x1071acdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10733db20>, '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.vnd6pUV2Oy/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.k0TzGD54Zq/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 06:14:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 06:14:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 06:14:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vnd6pUV2Oy/Render', 06:14:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:14:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:14:36 INFO - 'HOME': '/Users/cltbld', 06:14:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:14:36 INFO - 'LOGNAME': 'cltbld', 06:14:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:14:36 INFO - 'MOZ_NO_REMOTE': '1', 06:14:36 INFO - 'NO_EM_RESTART': '1', 06:14:36 INFO - 'PAGER': '/bin/cat', 06:14:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:14:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:14:36 INFO - 'PWD': '/builds/slave/test', 06:14:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:14:36 INFO - 'SHELL': '/bin/bash', 06:14:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.k0TzGD54Zq/Listeners', 06:14:36 INFO - 'TMPDIR': '/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/', 06:14:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:14:36 INFO - 'USER': 'cltbld', 06:14:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:14:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:14:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:14:36 INFO - 'XPC_FLAGS': '0x0', 06:14:36 INFO - 'XPC_SERVICE_NAME': '0', 06:14:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:14:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:14:36 INFO - Downloading/unpacking mozsystemmonitor==0.3 06:14:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:14:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:14:36 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:14:36 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:14:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:14:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:14:38 INFO - Downloading mozsystemmonitor-0.3.tar.gz 06:14:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 06:14:38 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 06:14:38 INFO - Installing collected packages: mozsystemmonitor 06:14:38 INFO - Running setup.py install for mozsystemmonitor 06:14:39 INFO - Successfully installed mozsystemmonitor 06:14:39 INFO - Cleaning up... 06:14:39 INFO - Return code: 0 06:14:39 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 06:14: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')]} 06:14:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:14:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:14: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')]} 06:14:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:14:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:14:39 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10752f3f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1074a5570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fba8a5a3d80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107345d30>, '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 0x1071acdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10733db20>, '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.vnd6pUV2Oy/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.k0TzGD54Zq/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 06:14:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 06:14:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 06:14:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vnd6pUV2Oy/Render', 06:14:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:14:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:14:39 INFO - 'HOME': '/Users/cltbld', 06:14:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:14:39 INFO - 'LOGNAME': 'cltbld', 06:14:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:14:39 INFO - 'MOZ_NO_REMOTE': '1', 06:14:39 INFO - 'NO_EM_RESTART': '1', 06:14:39 INFO - 'PAGER': '/bin/cat', 06:14:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:14:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:14:39 INFO - 'PWD': '/builds/slave/test', 06:14:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:14:39 INFO - 'SHELL': '/bin/bash', 06:14:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.k0TzGD54Zq/Listeners', 06:14:39 INFO - 'TMPDIR': '/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/', 06:14:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:14:39 INFO - 'USER': 'cltbld', 06:14:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:14:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:14:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:14:39 INFO - 'XPC_FLAGS': '0x0', 06:14:39 INFO - 'XPC_SERVICE_NAME': '0', 06:14:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:14:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:14:39 INFO - Downloading/unpacking jsonschema==2.5.1 06:14:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:14:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:14:39 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:14:39 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:14:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:14:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:14:41 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 06:14:41 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 06:14:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:14:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:14:41 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:14:41 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:14:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:14:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:14:42 INFO - Downloading functools32-3.2.3-2.tar.gz 06:14:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 06:14:42 INFO - warning: no files found matching '*.txt' 06:14:42 INFO - no previously-included directories found matching 'build' 06:14:42 INFO - no previously-included directories found matching 'dist' 06:14:42 INFO - no previously-included directories found matching '.git*' 06:14:42 INFO - Installing collected packages: jsonschema, functools32 06:14:42 INFO - Running setup.py install for functools32 06:14:42 INFO - warning: no files found matching '*.txt' 06:14:42 INFO - no previously-included directories found matching 'build' 06:14:42 INFO - no previously-included directories found matching 'dist' 06:14:42 INFO - no previously-included directories found matching '.git*' 06:14:42 INFO - Successfully installed jsonschema functools32 06:14:42 INFO - Cleaning up... 06:14:42 INFO - Return code: 0 06:14:42 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 06:14: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')]} 06:14:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:14:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:14: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')]} 06:14:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:14:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:14: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', '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 0x10752f3f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1074a5570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fba8a5a3d80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107345d30>, '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 0x1071acdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10733db20>, '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.vnd6pUV2Oy/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.k0TzGD54Zq/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 06:14: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', 'functools32==3.2.3-2'] in /builds/slave/test/build 06:14: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 functools32==3.2.3-2 06:14:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vnd6pUV2Oy/Render', 06:14:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:14:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:14:42 INFO - 'HOME': '/Users/cltbld', 06:14:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:14:42 INFO - 'LOGNAME': 'cltbld', 06:14:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:14:42 INFO - 'MOZ_NO_REMOTE': '1', 06:14:42 INFO - 'NO_EM_RESTART': '1', 06:14:42 INFO - 'PAGER': '/bin/cat', 06:14:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:14:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:14:42 INFO - 'PWD': '/builds/slave/test', 06:14:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:14:42 INFO - 'SHELL': '/bin/bash', 06:14:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.k0TzGD54Zq/Listeners', 06:14:42 INFO - 'TMPDIR': '/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/', 06:14:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:14:42 INFO - 'USER': 'cltbld', 06:14:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:14:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:14:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:14:42 INFO - 'XPC_FLAGS': '0x0', 06:14:42 INFO - 'XPC_SERVICE_NAME': '0', 06:14:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:14:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:14:42 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 06:14:42 INFO - Cleaning up... 06:14:42 INFO - Return code: 0 06:14:42 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 06:14: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')]} 06:14:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:14:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:14: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')]} 06:14:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:14:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:14: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', '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 0x10752f3f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1074a5570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fba8a5a3d80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107345d30>, '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 0x1071acdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10733db20>, '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.vnd6pUV2Oy/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.k0TzGD54Zq/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 06:14: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', 'blobuploader==1.2.4'] in /builds/slave/test/build 06:14: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 blobuploader==1.2.4 06:14:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vnd6pUV2Oy/Render', 06:14:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:14:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:14:42 INFO - 'HOME': '/Users/cltbld', 06:14:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:14:42 INFO - 'LOGNAME': 'cltbld', 06:14:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:14:42 INFO - 'MOZ_NO_REMOTE': '1', 06:14:42 INFO - 'NO_EM_RESTART': '1', 06:14:42 INFO - 'PAGER': '/bin/cat', 06:14:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:14:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:14:42 INFO - 'PWD': '/builds/slave/test', 06:14:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:14:42 INFO - 'SHELL': '/bin/bash', 06:14:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.k0TzGD54Zq/Listeners', 06:14:42 INFO - 'TMPDIR': '/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/', 06:14:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:14:42 INFO - 'USER': 'cltbld', 06:14:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:14:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:14:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:14:42 INFO - 'XPC_FLAGS': '0x0', 06:14:42 INFO - 'XPC_SERVICE_NAME': '0', 06:14:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:14:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:14:43 INFO - Downloading/unpacking blobuploader==1.2.4 06:14:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:14:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:14:43 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:14:43 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:14:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:14:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:14:45 INFO - Downloading blobuploader-1.2.4.tar.gz 06:14:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 06:14:45 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 06:14: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 06:14: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 06:14: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 06:14: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 06:14: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 06:14: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 06:14:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 06:14:46 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 06:14:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:14:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:14:46 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:14:46 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:14:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:14:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:14:46 INFO - Downloading docopt-0.6.1.tar.gz 06:14:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 06:14:47 INFO - Installing collected packages: blobuploader, requests, docopt 06:14:47 INFO - Running setup.py install for blobuploader 06:14:47 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 06:14:47 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 06:14:47 INFO - Running setup.py install for requests 06:14:47 INFO - Running setup.py install for docopt 06:14:47 INFO - Successfully installed blobuploader requests docopt 06:14:47 INFO - Cleaning up... 06:14:47 INFO - Return code: 0 06:14:47 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:14:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:14:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:14:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:14:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:14:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:14:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:14:47 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 0x10752f3f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1074a5570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fba8a5a3d80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107345d30>, '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 0x1071acdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10733db20>, '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.vnd6pUV2Oy/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.k0TzGD54Zq/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 06:14:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 06:14:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 06:14:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vnd6pUV2Oy/Render', 06:14:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:14:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:14:47 INFO - 'HOME': '/Users/cltbld', 06:14:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:14:47 INFO - 'LOGNAME': 'cltbld', 06:14:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:14:47 INFO - 'MOZ_NO_REMOTE': '1', 06:14:47 INFO - 'NO_EM_RESTART': '1', 06:14:47 INFO - 'PAGER': '/bin/cat', 06:14:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:14:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:14:47 INFO - 'PWD': '/builds/slave/test', 06:14:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:14:47 INFO - 'SHELL': '/bin/bash', 06:14:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.k0TzGD54Zq/Listeners', 06:14:47 INFO - 'TMPDIR': '/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/', 06:14:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:14:47 INFO - 'USER': 'cltbld', 06:14:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:14:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:14:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:14:47 INFO - 'XPC_FLAGS': '0x0', 06:14:47 INFO - 'XPC_SERVICE_NAME': '0', 06:14:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:14:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:14:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 06:14:48 INFO - Running setup.py (path:/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/pip-LeYpAx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 06:14:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 06:14:48 INFO - Running setup.py (path:/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/pip-KKnwiI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 06:14:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 06:14:48 INFO - Running setup.py (path:/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/pip-mSsRyT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 06:14:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 06:14:48 INFO - Running setup.py (path:/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/pip-CQmDkn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 06:14:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 06:14:48 INFO - Running setup.py (path:/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/pip-7VqxLo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 06:14:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 06:14:48 INFO - Running setup.py (path:/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/pip-MGQsCI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 06:14:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 06:14:48 INFO - Running setup.py (path:/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/pip-WTqK_G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 06:14:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 06:14:48 INFO - Running setup.py (path:/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/pip-DjmfCd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 06:14:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 06:14:48 INFO - Running setup.py (path:/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/pip-ps2uU1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 06:14:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 06:14:49 INFO - Running setup.py (path:/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/pip-H6m2VB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 06:14:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 06:14:49 INFO - Running setup.py (path:/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/pip-2GifbR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 06:14:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 06:14:49 INFO - Running setup.py (path:/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/pip-Y0alSI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 06:14:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 06:14:49 INFO - Running setup.py (path:/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/pip-KL_Wz5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 06:14:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 06:14:49 INFO - Running setup.py (path:/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/pip-_PPKQR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 06:14:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 06:14:49 INFO - Running setup.py (path:/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/pip-dFyiO3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 06:14:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 06:14:49 INFO - Running setup.py (path:/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/pip-dGACZM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 06:14:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 06:14:49 INFO - Running setup.py (path:/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/pip-IfMtsc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 06:14:49 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 06:14:49 INFO - Running setup.py install for manifestparser 06:14:50 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 06:14:50 INFO - Running setup.py install for mozcrash 06:14:50 INFO - Running setup.py install for mozdebug 06:14:50 INFO - Running setup.py install for mozdevice 06:14:50 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 06:14:50 INFO - Installing dm script to /builds/slave/test/build/venv/bin 06:14:50 INFO - Running setup.py install for mozfile 06:14:50 INFO - Running setup.py install for mozhttpd 06:14:50 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 06:14:50 INFO - Running setup.py install for mozinfo 06:14:50 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 06:14:50 INFO - Running setup.py install for mozInstall 06:14:51 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 06:14:51 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 06:14:51 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 06:14:51 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 06:14:51 INFO - Running setup.py install for mozleak 06:14:51 INFO - Running setup.py install for mozlog 06:14:51 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 06:14:51 INFO - Running setup.py install for moznetwork 06:14:51 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 06:14:51 INFO - Running setup.py install for mozprocess 06:14:51 INFO - Running setup.py install for mozprofile 06:14:51 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 06:14:51 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 06:14:51 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 06:14:51 INFO - Running setup.py install for mozrunner 06:14:52 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 06:14:52 INFO - Running setup.py install for mozscreenshot 06:14:52 INFO - Running setup.py install for moztest 06:14:52 INFO - Running setup.py install for mozversion 06:14:52 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 06:14:52 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 06:14:52 INFO - Cleaning up... 06:14:52 INFO - Return code: 0 06:14:52 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:14:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:14:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:14:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:14:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:14:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:14:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:14:52 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 0x10752f3f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1074a5570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fba8a5a3d80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107345d30>, '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 0x1071acdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10733db20>, '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.vnd6pUV2Oy/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.k0TzGD54Zq/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 06:14:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 06:14:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 06:14:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vnd6pUV2Oy/Render', 06:14:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:14:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:14:52 INFO - 'HOME': '/Users/cltbld', 06:14:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:14:52 INFO - 'LOGNAME': 'cltbld', 06:14:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:14:52 INFO - 'MOZ_NO_REMOTE': '1', 06:14:52 INFO - 'NO_EM_RESTART': '1', 06:14:52 INFO - 'PAGER': '/bin/cat', 06:14:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:14:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:14:52 INFO - 'PWD': '/builds/slave/test', 06:14:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:14:52 INFO - 'SHELL': '/bin/bash', 06:14:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.k0TzGD54Zq/Listeners', 06:14:52 INFO - 'TMPDIR': '/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/', 06:14:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:14:52 INFO - 'USER': 'cltbld', 06:14:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:14:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:14:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:14:52 INFO - 'XPC_FLAGS': '0x0', 06:14:52 INFO - 'XPC_SERVICE_NAME': '0', 06:14:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:14:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:14:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 06:14:52 INFO - Running setup.py (path:/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/pip-7LvrsU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 06:14:52 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 06:14:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 06:14:52 INFO - Running setup.py (path:/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/pip-3eY1D_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 06:14:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 06:14:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 06:14:52 INFO - Running setup.py (path:/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/pip-IvUANr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 06:14:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 06:14:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 06:14:53 INFO - Running setup.py (path:/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/pip-q2_5vi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 06:14:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 06:14:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 06:14:53 INFO - Running setup.py (path:/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/pip-BNjtA6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 06:14:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 06:14:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 06:14:53 INFO - Running setup.py (path:/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/pip-zBXNNU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 06:14:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 06:14:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 06:14:53 INFO - Running setup.py (path:/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/pip-fSXgUA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 06:14:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 06:14:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 06:14:53 INFO - Running setup.py (path:/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/pip-IcAJCe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 06:14:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 06:14:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 06:14:53 INFO - Running setup.py (path:/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/pip-qdOMyh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 06:14:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 06:14:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 06:14:53 INFO - Running setup.py (path:/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/pip-y10rnf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 06:14:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 06:14:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 06:14:53 INFO - Running setup.py (path:/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/pip-wg6yM3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 06:14:53 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 06:14:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 06:14:53 INFO - Running setup.py (path:/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/pip-MAE1yx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 06:14:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 06:14:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 06:14:54 INFO - Running setup.py (path:/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/pip-sE9_RM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 06:14:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 06:14:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 06:14:54 INFO - Running setup.py (path:/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/pip-LP19Y9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 06:14:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 06:14:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 06:14:54 INFO - Running setup.py (path:/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/pip-DyvAyJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 06:14:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 06:14:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 06:14:54 INFO - Running setup.py (path:/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/pip-Ec9dhO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 06:14:54 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 06:14:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 06:14:54 INFO - Running setup.py (path:/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/pip-ek4DG5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 06:14:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 06:14:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 06:14:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 06:14:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 06:14:54 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.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 06:14:54 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.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 06:14:54 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 06:14:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:14:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:14:54 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:14:54 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:14:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:14:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:14:57 INFO - Downloading blessings-1.6.tar.gz 06:14:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 06:14:57 INFO - Installing collected packages: blessings 06:14:57 INFO - Running setup.py install for blessings 06:14:57 INFO - Successfully installed blessings 06:14:57 INFO - Cleaning up... 06:14:57 INFO - Return code: 0 06:14:57 INFO - Done creating virtualenv /builds/slave/test/build/venv. 06:14:57 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 06:14:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 06:14:58 INFO - Reading from file tmpfile_stdout 06:14:58 INFO - Current package versions: 06:14:58 INFO - blessings == 1.6 06:14:58 INFO - blobuploader == 1.2.4 06:14:58 INFO - docopt == 0.6.1 06:14:58 INFO - functools32 == 3.2.3-2 06:14:58 INFO - jsonschema == 2.5.1 06:14:58 INFO - manifestparser == 1.1 06:14:58 INFO - mozInstall == 1.12 06:14:58 INFO - mozcrash == 1.0 06:14:58 INFO - mozdebug == 0.1 06:14:58 INFO - mozdevice == 0.49 06:14:58 INFO - mozfile == 1.2 06:14:58 INFO - mozhttpd == 0.7 06:14:58 INFO - mozinfo == 0.9 06:14:58 INFO - mozleak == 0.1 06:14:58 INFO - mozlog == 3.4 06:14:58 INFO - moznetwork == 0.27 06:14:58 INFO - mozprocess == 0.23 06:14:58 INFO - mozprofile == 0.28 06:14:58 INFO - mozrunner == 6.13 06:14:58 INFO - mozscreenshot == 0.1 06:14:58 INFO - mozsystemmonitor == 0.3 06:14:58 INFO - moztest == 0.7 06:14:58 INFO - mozversion == 1.4 06:14:58 INFO - psutil == 3.1.1 06:14:58 INFO - requests == 1.2.3 06:14:58 INFO - wsgiref == 0.1.2 06:14:58 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:14:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:14:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:14:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:14:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:14:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:14:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:14:58 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 0x10752f3f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1074a5570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fba8a5a3d80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107345d30>, '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 0x1071acdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10733db20>, '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.vnd6pUV2Oy/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.k0TzGD54Zq/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 06:14:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 06:14:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 06:14:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vnd6pUV2Oy/Render', 06:14:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:14:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:14:58 INFO - 'HOME': '/Users/cltbld', 06:14:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:14:58 INFO - 'LOGNAME': 'cltbld', 06:14:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:14:58 INFO - 'MOZ_NO_REMOTE': '1', 06:14:58 INFO - 'NO_EM_RESTART': '1', 06:14:58 INFO - 'PAGER': '/bin/cat', 06:14:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:14:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:14:58 INFO - 'PWD': '/builds/slave/test', 06:14:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:14:58 INFO - 'SHELL': '/bin/bash', 06:14:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.k0TzGD54Zq/Listeners', 06:14:58 INFO - 'TMPDIR': '/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/', 06:14:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:14:58 INFO - 'USER': 'cltbld', 06:14:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:14:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:14:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:14:58 INFO - 'XPC_FLAGS': '0x0', 06:14:58 INFO - 'XPC_SERVICE_NAME': '0', 06:14:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:14:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:14:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 06:14:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 06:14:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 06:14:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 06:14:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 06:14:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 06:14:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 06:14:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 06:14:58 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 06:14:58 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 06:14:58 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 06:14:58 INFO - Cleaning up... 06:14:58 INFO - Return code: 0 06:14:58 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 06:14:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:14:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:14:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:14:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:14:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:14:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:14:58 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10752f3f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1074a5570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fba8a5a3d80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107345d30>, '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 0x1071acdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10733db20>, '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.vnd6pUV2Oy/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.k0TzGD54Zq/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 06:14:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 06:14:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 06:14:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vnd6pUV2Oy/Render', 06:14:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:14:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:14:58 INFO - 'HOME': '/Users/cltbld', 06:14:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:14:58 INFO - 'LOGNAME': 'cltbld', 06:14:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:14:58 INFO - 'MOZ_NO_REMOTE': '1', 06:14:58 INFO - 'NO_EM_RESTART': '1', 06:14:58 INFO - 'PAGER': '/bin/cat', 06:14:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:14:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:14:58 INFO - 'PWD': '/builds/slave/test', 06:14:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:14:58 INFO - 'SHELL': '/bin/bash', 06:14:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.k0TzGD54Zq/Listeners', 06:14:58 INFO - 'TMPDIR': '/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/', 06:14:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:14:58 INFO - 'USER': 'cltbld', 06:14:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:14:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:14:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:14:58 INFO - 'XPC_FLAGS': '0x0', 06:14:58 INFO - 'XPC_SERVICE_NAME': '0', 06:14:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:14:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:14:59 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 06:14:59 INFO - Cleaning up... 06:14:59 INFO - Return code: 0 06:14:59 INFO - Running post-action listener: _resource_record_post_action 06:14:59 INFO - Running post-action listener: _start_resource_monitoring 06:14:59 INFO - Starting resource monitoring. 06:14:59 INFO - [mozharness: 2016-11-21 14:14:59.098762Z] Finished create-virtualenv step (success) 06:14:59 INFO - [mozharness: 2016-11-21 14:14:59.099746Z] Running install step. 06:14:59 INFO - Running pre-action listener: _resource_record_pre_action 06:14:59 INFO - Running main action method: install 06:14:59 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 06:14:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 06:14:59 INFO - Reading from file tmpfile_stdout 06:14:59 INFO - Detecting whether we're running mozinstall >=1.0... 06:14:59 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 06:14:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 06:14:59 INFO - Reading from file tmpfile_stdout 06:14:59 INFO - Output received: 06:14:59 INFO - Usage: mozinstall [options] installer 06:14:59 INFO - Options: 06:14:59 INFO - -h, --help show this help message and exit 06:14:59 INFO - -d DEST, --destination=DEST 06:14:59 INFO - Directory to install application into. [default: 06:14:59 INFO - "/builds/slave/test"] 06:14:59 INFO - --app=APP Application being installed. [default: firefox] 06:14:59 INFO - mkdir: /builds/slave/test/build/application 06:14:59 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 06:14:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 06:15:31 INFO - Reading from file tmpfile_stdout 06:15:31 INFO - Output received: 06:15:31 INFO - /builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox 06:15:31 INFO - Running post-action listener: _resource_record_post_action 06:15:31 INFO - [mozharness: 2016-11-21 14:15:31.800833Z] Finished install step (success) 06:15:31 INFO - [mozharness: 2016-11-21 14:15:31.800964Z] Running run-tests step. 06:15:31 INFO - Running pre-action listener: _resource_record_pre_action 06:15:31 INFO - Running main action method: run_tests 06:15:31 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 06:15:31 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 06:15:31 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 06:15:31 INFO - 2016-11-21 06:15:31.815 screenresolution[1621:7328] starting screenresolution argv=screenresolution get 06:15:31 INFO - 2016-11-21 06:15:31.818 screenresolution[1621:7328] Display 0: 1600x1200x32@60 06:15:31 INFO - 2016-11-21 06:15:31.823 screenresolution[1622:7330] starting screenresolution argv=screenresolution list 06:15:31 INFO - Available Modes on Display 0 06:15:31 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 06:15:31 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 06:15:31 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 06:15:31 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 06:15:32 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 06:15:32 INFO - Intel Iris: 06:15:32 INFO - Chipset Model: Intel Iris 06:15:32 INFO - Type: GPU 06:15:32 INFO - Bus: Built-In 06:15:32 INFO - VRAM (Dynamic, Max): 1536 MB 06:15:32 INFO - Vendor: Intel (0x8086) 06:15:32 INFO - Device ID: 0x0a2e 06:15:32 INFO - Revision ID: 0x0009 06:15:32 INFO - Displays: 06:15:32 INFO - Display: 06:15:32 INFO - Resolution: 1600 x 1200 @ 60 Hz 06:15:32 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 06:15:32 INFO - Display Serial Number: 1600x1200 60 06:15:32 INFO - Main Display: Yes 06:15:32 INFO - Mirror: Off 06:15:32 INFO - Online: Yes 06:15:32 INFO - Rotation: Supported 06:15:32 INFO - Return code: 0 06:15:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 06:15:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 06:15:32 INFO - Python 2.7.3 06:15:32 INFO - Return code: 0 06:15:32 INFO - grabbing minidump binary from tooltool 06:15:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:15:32 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 0x107345d30>, '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 0x1071acdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10733db20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 06:15:32 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 06:15:32 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 06:15:32 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 06:15:32 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 06:15:32 INFO - Return code: 0 06:15:32 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 06:15:32 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 06:15:32 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 06:15:32 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 06:15:32 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 06:15:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/KvETyyTcSmGQ7MYt6svQ7Q/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0299', '--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 06:15:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/KvETyyTcSmGQ7MYt6svQ7Q/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0299 --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 06:15:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vnd6pUV2Oy/Render', 06:15:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:15:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:15:32 INFO - 'HOME': '/Users/cltbld', 06:15:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:15:32 INFO - 'LOGNAME': 'cltbld', 06:15:32 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 06:15:32 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 06:15:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:15:32 INFO - 'MOZ_NO_REMOTE': '1', 06:15:32 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 06:15:32 INFO - 'NO_EM_RESTART': '1', 06:15:32 INFO - 'PAGER': '/bin/cat', 06:15:32 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:15:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:15:32 INFO - 'PWD': '/builds/slave/test', 06:15:32 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 06:15:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:15:32 INFO - 'SHELL': '/bin/bash', 06:15:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.k0TzGD54Zq/Listeners', 06:15:32 INFO - 'TMPDIR': '/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/', 06:15:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:15:32 INFO - 'USER': 'cltbld', 06:15:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:15:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:15:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:15:32 INFO - 'XPC_FLAGS': '0x0', 06:15:32 INFO - 'XPC_SERVICE_NAME': '0', 06:15:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:15:32 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/KvETyyTcSmGQ7MYt6svQ7Q/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0299', '--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 06:15:32 INFO - mozversion application_buildid: 20161121030224 06:15:32 INFO - mozversion application_changeset: b7f895c1dc2e91530240efbf50ac063a0f8a9cb5 06:15:32 INFO - mozversion application_display_name: Nightly 06:15:32 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 06:15:32 INFO - mozversion application_name: Firefox 06:15:32 INFO - mozversion application_remotingname: firefox 06:15:32 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 06:15:32 INFO - mozversion application_vendor: Mozilla 06:15:32 INFO - mozversion application_version: 53.0a1 06:15:32 INFO - mozversion platform_buildid: 20161121030224 06:15:32 INFO - mozversion platform_changeset: b7f895c1dc2e91530240efbf50ac063a0f8a9cb5 06:15:32 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 06:15:32 INFO - mozversion platform_version: 53.0a1 06:15:32 INFO - using testdate: 1479737732 06:15:32 INFO - actual date: 1479737732 06:15:32 INFO - starting webserver on 'localhost:49235' 06:15:32 INFO - SUITE-START | Running 2 tests 06:15:32 INFO - TEST-START | dromaeo_css 06:15:32 INFO - operating with platform_type : mac_ 06:15:32 INFO - Initialising browser for dromaeo_css test... 06:15:32 INFO - TEST-INFO | started process 1632 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/tmp4Yb6VL/profile http://localhost:49235/getInfo.html) 06:15:36 INFO - PROCESS | 1632 | 2016-11-21 06:15:36.050 plugin-container[1635:7501] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8e3b, name = 'com.apple.tsm.portname' 06:15:36 INFO - PROCESS | 1632 | See /usr/include/servers/bootstrap_defs.h for the error codes. 06:15:36 INFO - PROCESS | 1632 | 2016-11-21 06:15:36.050 plugin-container[1635:7501] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x930f, name = 'com.apple.CFPasteboardClient' 06:15:36 INFO - PROCESS | 1632 | See /usr/include/servers/bootstrap_defs.h for the error codes. 06:15:36 INFO - PROCESS | 1632 | 2016-11-21 06:15:36.051 plugin-container[1635:7501] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 06:15:36 INFO - PROCESS | 1632 | __metrics Screen width/height:1600/1200 06:15:36 INFO - PROCESS | 1632 | colorDepth:24 06:15:36 INFO - PROCESS | 1632 | Browser inner width/height: 1024/689 06:15:36 INFO - PROCESS | 1632 | __metrics 06:15:36 ERROR - PROCESS | 1632 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3179: TypeError: this.tabs is undefined 06:15:36 INFO - PROCESS | 1632 | 06:15:36 INFO - PROCESS | 1632 | ###!!! [Parent][MessageChannel] Error: (msgtype=0xEC0015,name=PVRManager::Msg_NotifyVSync) Channel error: cannot send/recv 06:15:36 INFO - PROCESS | 1632 | 06:15:36 INFO - TEST-INFO | 1632: exit 0 06:15:36 INFO - Browser initialized. 06:15:36 INFO - Running cycle 1/1 for dromaeo_css test... 06:15:36 INFO - TEST-INFO | started process 1636 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/tmp4Yb6VL/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 06:15:40 INFO - PROCESS | 1636 | 2016-11-21 06:15:40.051 plugin-container[1637:7630] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8743, name = 'com.apple.tsm.portname' 06:15:40 INFO - PROCESS | 1636 | See /usr/include/servers/bootstrap_defs.h for the error codes. 06:15:40 INFO - PROCESS | 1636 | 2016-11-21 06:15:40.051 plugin-container[1637:7630] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8917, name = 'com.apple.CFPasteboardClient' 06:15:40 INFO - PROCESS | 1636 | See /usr/include/servers/bootstrap_defs.h for the error codes. 06:15:40 INFO - PROCESS | 1636 | 2016-11-21 06:15:40.051 plugin-container[1637:7630] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 06:15:49 INFO - PROCESS | 1636 | RSS: Main: 243056640 06:15:49 INFO - PROCESS | 1636 | 06:16:39 INFO - PROCESS | 1636 | 1479737799375 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 06:18:53 INFO - PROCESS | 1636 | Cycle 1(1): loaded http://localhost:49235/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49235/tests/dromaeo/cssquery-ext.html) 06:18:55 INFO - PROCESS | 1636 | RSS: Main: 247984128 06:18:55 INFO - PROCESS | 1636 | 06:21:57 INFO - PROCESS | 1636 | Cycle 1(1): loaded http://localhost:49235/tests/dromaeo/cssquery-ext.html (next: http://localhost:49235/tests/dromaeo/cssquery-jquery.html) 06:21:58 INFO - PROCESS | 1636 | RSS: Main: 259592192 06:21:58 INFO - PROCESS | 1636 | 06:25:00 INFO - PROCESS | 1636 | Cycle 1(1): loaded http://localhost:49235/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49235/tests/dromaeo/cssquery-mootools.html) 06:25:00 INFO - PROCESS | 1636 | RSS: Main: 264015872 06:25:00 INFO - PROCESS | 1636 | 06:28:02 INFO - PROCESS | 1636 | Cycle 1(1): loaded http://localhost:49235/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49235/tests/dromaeo/cssquery-prototype.html) 06:28:03 INFO - PROCESS | 1636 | RSS: Main: 262742016 06:28:03 INFO - PROCESS | 1636 | 06:31:06 INFO - PROCESS | 1636 | Cycle 1(1): loaded http://localhost:49235/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49235/tests/dromaeo/cssquery-yui.html) 06:31:06 INFO - PROCESS | 1636 | RSS: Main: 267153408 06:31:06 INFO - PROCESS | 1636 | 06:34:09 INFO - PROCESS | 1636 | Cycle 1(1): loaded http://localhost:49235/tests/dromaeo/cssquery-yui.html (next: http://localhost:49235/tests/dromaeo/cssquery-dojo.html) 06:34:10 INFO - PROCESS | 1636 | RSS: Main: 269422592 06:34:10 INFO - PROCESS | 1636 | 06:34:10 INFO - PROCESS | 1636 | __start_tp_report 06:34:10 INFO - PROCESS | 1636 | _x_x_mozilla_page_load 06:34:10 INFO - PROCESS | 1636 | _x_x_mozilla_page_load_details 06:34:10 INFO - PROCESS | 1636 | |i|pagename|runs| 06:34:10 INFO - PROCESS | 1636 | |0;dojo.html;2979.9702002979966;3371.780834245774;3376.797392156472;3402.4215883299835;3449.6032956210042;4405.493368262651;5019.849404517866;5596.96456155611;5827.941720582793;5888;5680.630759000662;5781.017227071397;5819.709014549267;5822.184894114816;5833.095870140124;5222.712750798704;5233.162693968966;5275;5346.786128554853;5347;5295.9672863791575;5316.441773613773;5317.6809391436445;5334.839954801362;5345.545628621572;5306.93861227754;5315.627906046579;5317.973410132944;5321.281626980366;5332.013577488177;5280.683159010472;5293.417724050351;5296.311479507678;5309.920351194735;5317.096093664087;5153.819616313411;5263.184206447969;5265.131253343194;5269.209618557209;5275.235090911835;5827.125931110325;5827.213326200994;5914.260717410324;5930.436608522184;5970.313413957407;5192.584593232532;6128.63228206309;6290.24517057956;6319.210098737658;6323.6521991290465;6143.419763699257;6208.627482351028;6219.1915051043525;6250.218722659668;6270.623762574214;5946.672932988728;5951.702414879239;5953.404659534055;5971.641701497924;6000.639961602318;6337.556371054075;6344.429001389804;6366.904496432558;6368.617882927039;6376.872462550816;6085.391460853968;6105.64170830808;6143.047827586731;6242.843928901813;6252.437280644764;5172.508611681884;6085.661154545993;6234.314225435199;6267.2165979252595;6288.213973253343;6107.267127944675;6127.403330890814;6147;6149.631022138686;6179.783707570214;6206.534509911775;6210.099535567318;6276.905846412307;6294.590851594723;6298.370162983665;4985;5009.89980200394;5011.749412529359;5019.698818070854;5031.195008798574;4384.671149663788;4404.185225733249;4406.911861762746;4483.730976141442;4496.910061798746;5739.799107031234;5950.732217050243;5951.851203719855;5982.012967860254;6023.0965355196195;6249.093881387264;6259.280182778948;6261.655608941552;6271.905921411182;6278.0269058295125;5567.888642227133;5592.804251851166;5627.874425114994;5636.464535869086;5862.970685146633;5484.424135465799;5485.917711234335;5507.339119305709;5509.752061157257;5542;6423.5503514754455;6436.259830119596;6444.355564443612;6451.935480645134;6452.322506136788;6153.6000159991245;6184.134221209124;6194.287656919512;6275.309715931153;6306.716197771203;5412.242286080031;6288.151099601679;6431.228252609717;6442.194725659293;6454.225492940877;6194.442500175006;6248.968755156195;6255.562110652393;6280.654563998841;6281.403266689657;6047.516198704181;6188.8762224754355;6266.655333956493;6268.811935641938;6283.340249273943;6123.923256659856;6133;6172.351903050115;6197.5351848610635;6247.781327653692;6149.169862068742;6194.504439644727;6197.03945888388;6215.689215539295;6229.844253893689;5240.921386179134;5916.437938395846;6026;6076.6961651916345;6135;6102.420270074336;6105.877882442415;6113.694315284307;6151.446369826737;6178.289496707936;5694.800681976111;6106.72519736604;6117.279373342293;6137.286941400388;6150.1082343059115 06:34:10 INFO - PROCESS | 1636 | |1;ext.html;456.3064142503395;476.8664773863317;530.1808705549925;532.8880935003647;534.3694440560138;38410;39968.40047399288;40169.79915100428;40181.3972790408;40275.39586906199;41533.79233103838;41859.74420767374;41955;42049.15901681964;42060;9341.97238303786;9530.379835427993;9534.189593884528;9540;9566.99546547613;9459.432434053979;9521.714348569554;9531.046895310483;9534.427934323963;9550.9522452388;9536.475493847836;9557.474338911357;9565.952170239174;9568.186704130123;9576.186024187917;9467.242620590336;9532.285078619061;9543.713688589354;9545.665901693443;9558.61765529378;9384.530773461367;9488.146066854017;9492.098250666175;9492.525373731285;9536.856947145798;39654.40518392227;39902.80048599767;39942.400863987066;39983;40119.39820902689;3189.39401513714;3296.5549650797075;3363.4114030044643;3396.4226081566194;3985.342418500944;30174.54738178929;30533.541996870066;30740;31265.37469250625;31307.686923130706;3143.387039527293;3170.397624451347;3181.9522707159413;3211.1008917503136;3271.656476070026;474463;478396;480333;480521;481770.9999999965;32021.67978320187;32984;57498.85002299931;57728.42271577231;57981.71009144927;34189.48715769265;34254.314913701586;34373.484397733555;34461.310773784884;34601;14077.929610351883;14291.57125286243;14300.499482518062;14303;14305.070170438888;11883.049356051495;12020.819687704692;12022.519099235933;12040.759184816254;12069.818952715716;7668.923310766932;7676.769696909102;7744.148143704188;7755.1469338372735;7783.29950304464;2777.6111344411797;2851.344190836119;2983.522636378169;3025.243689077731;3205.6473787883315;848.5460278750838;993.3146129170849;1024.8872624011353;1062.8246339354075;1124.100719424457;26592.468150637265;29251.122466326044;29764;30181.943631973216;30623.540646890313;835.0814104485017;1021.4586269277295;1026.3790406803926;1028.3726926574784;1141.851559297286;13123.212607243597;14839.851601484062;15313.770293445385;15441.768373474408;15718.056916584812;641637;642150;642713;647553;649842;56438.15342769862;56934.430655692915;56971.43028569744;56975;57032.42967570354;1161.6456980620992;1268.7462507498537;1332.8000799880097;1350.770369037266;1351.3445978700322;26084.956601735717;27073.052443164393;28453.715462845106;29843.850780745957;29954.700452995192;49880.750596246784;50122.74938625429;50296.49703503065;50527;50535;576221;579016;579672;580054;580449;25479.61780573256;25769;25875.964961401332;25943.0919917802;26042;43667.34498982581;44339;45752;45772.54227457816;46718.83202919954;37754;38004;38376.75799018715;38449.23101538009;39012;37037.25925481529;37576.88186976677;38219.808900955315;38312.233755325295;38642.80678596701;28368.71631283661;31778.728850845706;36641.08397290089;37172.81413592915;37514;32185.517217241293;33735.32529349448;33769.81805636791;33805.49291760576;34087.65912340944;22914.77085229193;22988.080476780742;24168.637470437607;24634.753652463245;25179.622305665795 06:34:10 INFO - PROCESS | 1636 | |2;jquery.html;366.52717993788013;370.6775105658076;376.2606478270199;387.5601192646346;404.7146761533119;18364.816351836478;19021.61956760864;19121;19593.51016224595;19697.704534431978;18601.534961625934;18609.813901860976;19417.611647767073;19523.609527809436;19818;4556.521565235652;5208.713520756366;5250.949810037989;5411.431799661038;5412.756425960822;5233.633645644806;5335.599830012745;5341.225522299264;5344.759485823128;5360.812371566996;5192.6624769390055;5234.79060837568;5331.786728530854;5340.813071542497;5350.3312085989255;5240.44975277596;5324.1215199492035;5326.36083669962;5339.466053394668;5345.866353341159;4959.181735013718;5164.741762911877;5174.275601415805;5185.455527169631;5186.8070343820755;12995.610131696065;13005.089643724943;13128.60614181576;13140.211587304697;17286.481405557726;2128.5636444528836;2136.444524423661;2151.741790985076;2170.3814412892248;2298.1152256381247;9772.560234789451;10251;10433.113185379252;10867.728306792314;11101.3894235817;1780.4925596205019;1823.872328936975;1850.1211924335967;1860.7301941218582;1894.4222012286248;107108.85782284389;119158;122967;123381;125327.3733631335;9619.951900240594;10792.892071079188;11087.390193539433;11391.544338226544;11413.42932853354;14252.358643861051;14268.57194284173;14278.2146981917;14317.570472885622;14318.498852540319;7937.849011893243;8197.631106600218;8252.504849709037;8374.115823939686;8398.706045288385;8053.31546818527;8097.838043239102;8120.431569790177;8220.506769593843;8222.465539739898;4441.333799930037;4495.820167193276;4543.840965566255;4555.180067587799;4569.588737013684;1625.7236269834157;1677.9412720554967;1890.782560005617;1897.459224121119;1969.3304276545757;769.3806485779002;892.6518657723493;968.7771812483169;988.5205675247643;1017.6946915925192;19905;21156.047977841034;21280;21346.78653213479;21356.35930922075;813;840.9831803363899;895.0870112485229;910.349100393222;950.8288508068475;8480.787980300542;10189.694309170736;11186.993170614684;11206.663800085847;11261.718457038476;430183;559030;560232;575920;607764;108288;110213;122373.38813305876;122592;123030.76969230371;948.2982592881218;984.4634674102631;1008.9798204035878;1010.9696709098739;1012.1548506996593;15785.921070394574;18914.14886330146;19102.522436939187;19199.90400047991;19215.80784192196;33277.83361083179;49907.25139122989;50474.49525504698;52470.21294680659;55124.17313740217;48333;50197.99604007972;54057.72971135119;58154.83690326085;60090;5886.264216972879;7769.484950434611;7925.920740792749;7968.92031079705;8023.59882005909;9325.813483730422;12122.030237581148;12230.388480576114;12328.938355308166;12711.93644031811;10435.739106522398;10712.785744285227;10785.568577257136;10940.617078402218;11125.332480051224;9245.798046253944;10331.483425828528;10617.946910265708;10671.399290106547;10758.892411075789;10840.5121769519;11031.79364127194;11068.723281918017;11093.16801239894;11143.888561114609;5672.971635141798;7976.481528700616;8067;8126.674933002851;8206.58967051657;10688.983924206366;11129.888701112885;11184.660808225222;11348.092152627934;11353.772924541629 06:34:10 INFO - PROCESS | 1636 | |3;mootools.html;1358.1240100135412;1442.8485009074047;1600.2318886934272;1722.3110755697728;1769.2038582637808;7514.3988480921535;7775.3779697624195;8085.797855053626;8514.70198543051;8543.444676096049;7986.482568312017;8260.586970651473;8292.875606865886;8367.079621241674;8385.580720963959;2312.0636142362287;2337.801286890608;2339.988300058506;2344.4373350395954;2351.8824058797077;2330.8718020508863;2370.3244575296044;2380.143148466551;2387.128698025217;2391.5575618510543;2324.651302304651;2343.215022967308;2347.319277409549;2351.0478256306224;2352.200251914349;2302.873341966191;2312.4681323295567;2334.031376978552;2337.322176568793;2338.789508944203;1968.4389948864505;2345.1205797825814;2353.7528559501347;2359.197872723282;2360.3037104054174;8360.623771930277;8377.790555236168;8451.36614753896;8465.703700370519;8482.363822713321;4941.579965702919;5103.974480127613;5107.7956881724685;5123.385193776733;5128.871778205537;3296.5549650797075;3384.424647809878;3571.017970058229;3626.3291291111227;3648.908777280563;1010.3281317923572;1029.093198368125;1068.5351871935734;1070.5931745936573;1095.2935356694966;10922.781544369069;10997.450127493674;11075.833862492149;11081.27971681838;11084.445777711082;4442.466903971479;4560.156371071363;4672.43930728308;4690.742009189459;4692.718436893797;9320.580573874191;9401.388909720847;9417.482038487811;9417.952910235543;9436.716898493056;5185.585153187736;5185.714785686823;5191.351081114861;5196.870078248074;5206.739663016834;4250.491075668155;4258.723182993095;4720.291956206596;4725.976370118196;4776.904461910743;3105.8602362893726;3723.739338246297;3833.4058220975794;3839;3841.2125514269505;1119.3116233516491;1248.3570960955115;1254.667513109027;1276.540445439641;1288.4846061575258;689.2797027106685;785.4541093939657;797.9068675914035;834.7312085629809;900.3022657440405;4744.857654270378;4775.283707443842;5582.469665381782;5753.51095156908;5758.88482230353;381.39929610863004;423.57642357642357;424.9851255206053;426.5201648145836;433.15318552230315;3773.2076263984873;3798.6770776346325;3847.6537111660086;3868.052327179859;3925.764454132704;7511.661975211128;9965.750856228653;10081.596736130472;10089.747756306151;10102.040306170902;10095.697129086138;10116.342437741523;10127.442990635585;10134.189264858942;10170.186385089175;7745.302922736981;7792.922070779447;7855.489393189425;7856.489328193649;7876.094249161305;5433.728313584384;5516.034693928626;5580.246666700106;5621.943780562142;5638;9385.765355866159;9396.859047114162;9415.764605884908;9431.62273509052;9437.81124377495;7815.6092195388865;9329.207017403596;9479.146876781122;9505.001974792547;9677.903220967983;7063.858722825617;7074.646267686492;7104.431645468453;7120.145582530129;7148.714051438093;7823.960880195563;7891.092524359772;7900.486468379537;7905.762827115196;7954.721584744506;6873.20958089826;6895.172579290517;7144.535605185646;7187.460940429384;7199.316064973819;6697.899531507129;6804.217514985642;6905.516613837184;6915.688794004182;7015.824604384931;6407.679616019273;6565.409113179646;6620.304867988984;6643.103181070687;6756.189257289157;4842.5157484251295;4900.117978763857;4914.655974081923;4933.6299777516115;4951.158303088556;4826.32431459603;4863.221884498464;4876.756162191948;4966.485222007323;5031.22016087507 06:34:10 INFO - PROCESS | 1636 | |4;prototype.html;301.29797571658025;304.57511771079345;308.284779311996;314.42146450531027;317.9998903448654;4442.289233722597;5014.774335154918;5368.248445217672;5472.288602481672;5477.260569823077;4925.211966085427;5035.345405097341;5202.947970520294;5213.9478605213935;5255.973720131404;2941.0000599796067;3993.1614360984154;4030.717849750519;4040.222302187028;4042.171354872244;3304.37216928784;3770.924581508368;4021.5978402159694;4057.797110144496;4064.8821574067074;3104.410162069211;3261.673832616743;3821.388577827548;3941.192055628589;3969.1466334738025;3559.754086069855;3772.6793222576116;3913.3738601823716;3950.3481925482547;3967.682585393162;3481.547398838135;3484.738644601665;3491.074865160709;3491.4064609016527;3492.51568083566;4928.704277743347;5152.587792976553;5177.249298851683;5214.895702085975;5220.347456567929;4551.4083169188125;4557.316402539613;4563.292689633112;4570.680052396334;4583.679142460029;4893.336265669689;5079.974600126976;5563.610547261694;5568.387477377485;5588.469095435927;2999.9700003000125;3534.434490481536;3829.5021647185968;3831.348670725983;3885.7862817545024;5394.491561423917;5648.519875810561;5906;5907;5982.564184595709;4013.4983127109113;4021.276170289376;4025.295573274665;4028.3756017817286;4033.69747268956;3867.8839634811;4073.287174744408;4074.1647962167685;4099.385092236188;4130.318497447887;4270.722403043792;4614;4663.183942809995;4673.299005149255;4676.766161691902;5736.475950581731;5743.4543718346695;5752.856178595569;5777.913331300034;5787.71061446926;1181.7518321152481;1263.5261776833688;1280.8719128087303;1282.0897163014179;1340.3700260877372;895.9283257339397;919.8593743757826;920.3281604428804;922.4511415707643;931.5249222896372;4470.6870519064005;4812.72462797359;4938.25926111079;4969.583668654416;5056.9494305057215;4682.765861706901;4762.14281429346;5088.6946783193125;5306.787728490895;5312.442193569696;4143.61330270647;4277.6739210844735;5042.974785126051;5065.45418207249;5079.561810330988;2234.1510226113974;2314.4213946513373;2622.7901767858525;2746.5742809864505;2872.6696429910826;5422.969635769191;5572;5630.915536266959;5686.800961966312;6101.7559297627595;5818.912716309259;6043.969780151071;6063.878722425527;6068.575199735976;6099.634021958696;4956.776945037482;5251.894962100737;6055.333913269536;6082.840591589922;6140.631562106288;4515.261624274744;5615.803446879339;5847.1229315603;5882.5293976480925;5927.051671732502;5044.798208071636;5282.498162674539;5445.53712934405;5609.831705048855;5885.792243227365;5038.949610503994;5818.2436283282905;5832.387599302012;6105.046385156707;6173.660448675187;2970.3762209936153;3259.755518336182;3919.804009799556;3946.0726729218036;3949.861754838567;5134.332536770196;5222.869428264324;5890.734916928663;5934.6142500737315;5948.5538584605465;5319.361676598678;5661.951704531115;5728.713564321851;5756.510696590837;5757.827265182052;4703.529647035269;4863.7324947128245;5583.246261754611;5674.829755107354;5716.598623818822;5341;5341.7030676809345;5478.397376288684;5500.18493897021;5620.915686264628;5160.838811267435;5563.749631266522;5697.6296540724725;5700.857478563069;5701.543876489954;5219.8434046978655;5583.822309299458;5605.299337582802;5605.804691325905;5621.3698027571545 06:34:10 INFO - PROCESS | 1636 | |5;yui.html;615.4460985113398;635.5963962883568;682.5154140560202;754.6679461037144;801.9077806052304;12042.57850975216;12798.48806047757;14298.498657640954;14906.776398354019;15017.174055426947;12962.222266664006;13495.66260843477;13581.728365432686;13584.388702508386;13601.319934003284;783.5612057247931;802.8434455281223;803.220875750521;805.7824387415394;807.4146243973131;652.3313603556345;672.1094549721614;778.595130532123;812.995935020324;816.3428440105713;854.8418542569614;867.9913200867974;871.9258862996653;873.8907636545432;874.921257086862;865.0268447996004;865.6580650643024;866.138192498465;867.1675191815864;867.5792240763257;617.3024482334978;644.1336402538578;644.445776632096;644.5842431631571;646.385933363302;14353.78469322961;14513;14539.114539611352;14614;14700.926495367456;13721.588352349445;13907.304634768221;14038.649033774136;14113.223772692498;14128.576142715736;11388.943055284753;11399.3730344831;11595.768084638345;11697.824532632018;11740.178187526877;3538;3665;3671.2657468506404;3736.7197460190323;3796.354619714655;88833.57608890862;91796;94841.5257923706;96394.51802740942;96597.03402966021;10532.57869685204;10687.30532515384;10805.945970270099;11027.393493357782;11120.277181983145;20450.488737781674;20869.68695469569;21269.255576054762;21303.828289443918;21434.57130857374;6956.234814170437;7199.604021778852;7349.963250183821;7420;7682.270184332479;4132.380142978517;4182.435371224907;4204.66362690984;4209.6000879916355;4302.26861433557;3165;3244.253821620993;3262.13553408347;3277.623073346548;3280.048785852076;608.3733754233147;626.6240255846456;627.8399008252958;633.0978355842998;635.3233805996599;559.3260121553518;582.8950788857961;595.7540073921292;613.8219916224334;621.5814256844485;8588.957055214809;8600.612972416257;8640.783980400414;8751.737447876574;8783.5169065702;517.4902720819989;527.604296777417;532.2521856791215;533.871870751017;567.2682239910552;3937.665298449664;3984.2230765000545;4310.202612516695;4413.271810151353;4461.330800379969;65067;77970.22029779744;82478.58760706279;98938.51592226123;99644;55799;55883.161752573746;65459.672701636184;84458.5777071123;86501.13498865056;58355.70822145947;61879;80413;82381.17618823855;82647.17352826394;8446.83106337882;8513.318934485102;8533.829323413622;8597.957010214908;8647.178518041028;43606.07545320936;44469;50946.98106037932;51957.22064168965;52353.7382313086;63645;73134.63432683016;74469;75663.86504202551;76845;10807.729806754895;11096.88903110991;11154.163437742363;11298.60454884075;11392.145589080686;12153.57462488844;12323.9419076799;12432.378381081091;12452.937735311629;12659.936700316808;11702.648920532398;12017.399130043637;12053.879461205626;12059.819102713642;12327.753444930871;11734.70663233426;11958.760824783629;12118.515259389525;12182.512699491921;12390.132690711564;11512.251703639236;11860.110491713329;12043.277403355827;12093.395330233629;12121.636350909486;9283.72148835536;9362;9906.455144967096;9911.058449447291;10147.441890696082;9997.050280223368;10167.237457190593;10177.796444071226;10319.587216511256;10343.689689309333 06:34:10 INFO - PROCESS | 1636 | __end_tp_report 06:34:10 INFO - PROCESS | 1636 | __start_cc_report 06:34:10 INFO - PROCESS | 1636 | _x_x_mozilla_cycle_collect,365 06:34:10 INFO - PROCESS | 1636 | __end_cc_report 06:34:10 INFO - PROCESS | 1636 | __startTimestamp1479738850049__endTimestamp 06:34:10 INFO - PROCESS | 1636 | 06:34:10 INFO - PROCESS | 1636 | ------- Summary: start ------- 06:34:10 INFO - PROCESS | 1636 | Number of tests: 6 06:34:10 INFO - PROCESS | 1636 | 06:34:10 INFO - PROCESS | 1636 | [#0] dojo.html Cycles:165 Average:5774.04 Median:6047.52 stddev:658.51 (10.9%) stddev-sans-first:622.99 06:34:10 INFO - PROCESS | 1636 | Values: 2980.0 3371.8 3376.8 3402.4 3449.6 4405.5 5019.8 5597.0 5827.9 5888.0 5680.6 5781.0 5819.7 5822.2 5833.1 5222.7 5233.2 5275.0 5346.8 5347.0 5296.0 5316.4 5317.7 5334.8 5345.5 5306.9 5315.6 5318.0 5321.3 5332.0 5280.7 5293.4 5296.3 5309.9 5317.1 5153.8 5263.2 5265.1 5269.2 5275.2 5827.1 5827.2 5914.3 5930.4 5970.3 5192.6 6128.6 6290.2 6319.2 6323.7 6143.4 6208.6 6219.2 6250.2 6270.6 5946.7 5951.7 5953.4 5971.6 6000.6 6337.6 6344.4 6366.9 6368.6 6376.9 6085.4 6105.6 6143.0 6242.8 6252.4 5172.5 6085.7 6234.3 6267.2 6288.2 6107.3 6127.4 6147.0 6149.6 6179.8 6206.5 6210.1 6276.9 6294.6 6298.4 4985.0 5009.9 5011.7 5019.7 5031.2 4384.7 4404.2 4406.9 4483.7 4496.9 5739.8 5950.7 5951.9 5982.0 6023.1 6249.1 6259.3 6261.7 6271.9 6278.0 5567.9 5592.8 5627.9 5636.5 5863.0 5484.4 5485.9 5507.3 5509.8 5542.0 6423.6 6436.3 6444.4 6451.9 6452.3 6153.6 6184.1 6194.3 6275.3 6306.7 5412.2 6288.2 6431.2 6442.2 6454.2 6194.4 6249.0 6255.6 6280.7 6281.4 6047.5 6188.9 6266.7 6268.8 6283.3 6123.9 6133.0 6172.4 6197.5 6247.8 6149.2 6194.5 6197.0 6215.7 6229.8 5240.9 5916.4 6026.0 6076.7 6135.0 6102.4 6105.9 6113.7 6151.4 6178.3 5694.8 6106.7 6117.3 6137.3 6150.1 06:34:10 INFO - PROCESS | 1636 | 06:34:10 INFO - PROCESS | 1636 | [#1] ext.html Cycles:180 Average:68087.17 Median:38429.62 stddev:153204.19 (398.7%) stddev-sans-first:153549.82 06:34:10 INFO - PROCESS | 1636 | Values: 456.3 476.9 530.2 532.9 534.4 38410.0 39968.4 40169.8 40181.4 40275.4 41533.8 41859.7 41955.0 42049.2 42060.0 9342.0 9530.4 9534.2 9540.0 9567.0 9459.4 9521.7 9531.0 9534.4 9551.0 9536.5 9557.5 9566.0 9568.2 9576.2 9467.2 9532.3 9543.7 9545.7 9558.6 9384.5 9488.1 9492.1 9492.5 9536.9 39654.4 39902.8 39942.4 39983.0 40119.4 3189.4 3296.6 3363.4 3396.4 3985.3 30174.5 30533.5 30740.0 31265.4 31307.7 3143.4 3170.4 3182.0 3211.1 3271.7 474463.0 478396.0 480333.0 480521.0 481771.0 32021.7 32984.0 57498.9 57728.4 57981.7 34189.5 34254.3 34373.5 34461.3 34601.0 14077.9 14291.6 14300.5 14303.0 14305.1 11883.0 12020.8 12022.5 12040.8 12069.8 7668.9 7676.8 7744.1 7755.1 7783.3 2777.6 2851.3 2983.5 3025.2 3205.6 848.5 993.3 1024.9 1062.8 1124.1 26592.5 29251.1 29764.0 30181.9 30623.5 835.1 1021.5 1026.4 1028.4 1141.9 13123.2 14839.9 15313.8 15441.8 15718.1 641637.0 642150.0 642713.0 647553.0 649842.0 56438.2 56934.4 56971.4 56975.0 57032.4 1161.6 1268.7 1332.8 1350.8 1351.3 26085.0 27073.1 28453.7 29843.9 29954.7 49880.8 50122.7 50296.5 50527.0 50535.0 576221.0 579016.0 579672.0 580054.0 580449.0 25479.6 25769.0 25876.0 25943.1 26042.0 43667.3 44339.0 45752.0 45772.5 46718.8 37754.0 38004.0 38376.8 38449.2 39012.0 37037.3 37576.9 38219.8 38312.2 38642.8 28368.7 31778.7 36641.1 37172.8 37514.0 32185.5 33735.3 33769.8 33805.5 34087.7 22914.8 22988.1 24168.6 24634.8 25179.6 06:34:10 INFO - PROCESS | 1636 | 06:34:10 INFO - PROCESS | 1636 | [#2] jquery.html Cycles:180 Average:31946.09 Median:2144.09 stddev:91928.18 (4287.5%) stddev-sans-first:92155.49 06:34:10 INFO - PROCESS | 1636 | Values: 366.5 370.7 376.3 387.6 404.7 18364.8 19021.6 19121.0 19593.5 19697.7 18601.5 18609.8 19417.6 19523.6 19818.0 4556.5 5208.7 5250.9 5411.4 5412.8 5233.6 5335.6 5341.2 5344.8 5360.8 5192.7 5234.8 5331.8 5340.8 5350.3 5240.4 5324.1 5326.4 5339.5 5345.9 4959.2 5164.7 5174.3 5185.5 5186.8 12995.6 13005.1 13128.6 13140.2 17286.5 2128.6 2136.4 2151.7 2170.4 2298.1 9772.6 10251.0 10433.1 10867.7 11101.4 1780.5 1823.9 1850.1 1860.7 1894.4 107108.9 119158.0 122967.0 123381.0 125327.4 9620.0 10792.9 11087.4 11391.5 11413.4 14252.4 14268.6 14278.2 14317.6 14318.5 7937.8 8197.6 8252.5 8374.1 8398.7 8053.3 8097.8 8120.4 8220.5 8222.5 4441.3 4495.8 4543.8 4555.2 4569.6 1625.7 1677.9 1890.8 1897.5 1969.3 769.4 892.7 968.8 988.5 1017.7 19905.0 21156.0 21280.0 21346.8 21356.4 813.0 841.0 895.1 910.3 950.8 8480.8 10189.7 11187.0 11206.7 11261.7 430183.0 559030.0 560232.0 575920.0 607764.0 108288.0 110213.0 122373.4 122592.0 123030.8 948.3 984.5 1009.0 1011.0 1012.2 15785.9 18914.1 19102.5 19199.9 19215.8 33277.8 49907.3 50474.5 52470.2 55124.2 48333.0 50198.0 54057.7 58154.8 60090.0 5886.3 7769.5 7925.9 7968.9 8023.6 9325.8 12122.0 12230.4 12328.9 12711.9 10435.7 10712.8 10785.6 10940.6 11125.3 9245.8 10331.5 10617.9 10671.4 10758.9 10840.5 11031.8 11068.7 11093.2 11143.9 5673.0 7976.5 8067.0 8126.7 8206.6 10689.0 11129.9 11184.7 11348.1 11353.8 06:34:10 INFO - PROCESS | 1636 | 06:34:10 INFO - PROCESS | 1636 | [#3] mootools.html Cycles:180 Average:5408.45 Median:4924.14 stddev:2992.38 (60.8%) stddev-sans-first:2985.29 06:34:10 INFO - PROCESS | 1636 | Values: 1358.1 1442.8 1600.2 1722.3 1769.2 7514.4 7775.4 8085.8 8514.7 8543.4 7986.5 8260.6 8292.9 8367.1 8385.6 2312.1 2337.8 2340.0 2344.4 2351.9 2330.9 2370.3 2380.1 2387.1 2391.6 2324.7 2343.2 2347.3 2351.0 2352.2 2302.9 2312.5 2334.0 2337.3 2338.8 1968.4 2345.1 2353.8 2359.2 2360.3 8360.6 8377.8 8451.4 8465.7 8482.4 4941.6 5104.0 5107.8 5123.4 5128.9 3296.6 3384.4 3571.0 3626.3 3648.9 1010.3 1029.1 1068.5 1070.6 1095.3 10922.8 10997.5 11075.8 11081.3 11084.4 4442.5 4560.2 4672.4 4690.7 4692.7 9320.6 9401.4 9417.5 9418.0 9436.7 5185.6 5185.7 5191.4 5196.9 5206.7 4250.5 4258.7 4720.3 4726.0 4776.9 3105.9 3723.7 3833.4 3839.0 3841.2 1119.3 1248.4 1254.7 1276.5 1288.5 689.3 785.5 797.9 834.7 900.3 4744.9 4775.3 5582.5 5753.5 5758.9 381.4 423.6 425.0 426.5 433.2 3773.2 3798.7 3847.7 3868.1 3925.8 7511.7 9965.8 10081.6 10089.7 10102.0 10095.7 10116.3 10127.4 10134.2 10170.2 7745.3 7792.9 7855.5 7856.5 7876.1 5433.7 5516.0 5580.2 5621.9 5638.0 9385.8 9396.9 9415.8 9431.6 9437.8 7815.6 9329.2 9479.1 9505.0 9677.9 7063.9 7074.6 7104.4 7120.1 7148.7 7824.0 7891.1 7900.5 7905.8 7954.7 6873.2 6895.2 7144.5 7187.5 7199.3 6697.9 6804.2 6905.5 6915.7 7015.8 6407.7 6565.4 6620.3 6643.1 6756.2 4842.5 4900.1 4914.7 4933.6 4951.2 4826.3 4863.2 4876.8 4966.5 5031.2 06:34:10 INFO - PROCESS | 1636 | 06:34:10 INFO - PROCESS | 1636 | [#4] prototype.html Cycles:180 Average:4508.72 Median:5050.87 stddev:1429.88 (28.3%) stddev-sans-first:1398.58 06:34:10 INFO - PROCESS | 1636 | Values: 301.3 304.6 308.3 314.4 318.0 4442.3 5014.8 5368.2 5472.3 5477.3 4925.2 5035.3 5202.9 5213.9 5256.0 2941.0 3993.2 4030.7 4040.2 4042.2 3304.4 3770.9 4021.6 4057.8 4064.9 3104.4 3261.7 3821.4 3941.2 3969.1 3559.8 3772.7 3913.4 3950.3 3967.7 3481.5 3484.7 3491.1 3491.4 3492.5 4928.7 5152.6 5177.2 5214.9 5220.3 4551.4 4557.3 4563.3 4570.7 4583.7 4893.3 5080.0 5563.6 5568.4 5588.5 3000.0 3534.4 3829.5 3831.3 3885.8 5394.5 5648.5 5906.0 5907.0 5982.6 4013.5 4021.3 4025.3 4028.4 4033.7 3867.9 4073.3 4074.2 4099.4 4130.3 4270.7 4614.0 4663.2 4673.3 4676.8 5736.5 5743.5 5752.9 5777.9 5787.7 1181.8 1263.5 1280.9 1282.1 1340.4 895.9 919.9 920.3 922.5 931.5 4470.7 4812.7 4938.3 4969.6 5056.9 4682.8 4762.1 5088.7 5306.8 5312.4 4143.6 4277.7 5043.0 5065.5 5079.6 2234.2 2314.4 2622.8 2746.6 2872.7 5423.0 5572.0 5630.9 5686.8 6101.8 5818.9 6044.0 6063.9 6068.6 6099.6 4956.8 5251.9 6055.3 6082.8 6140.6 4515.3 5615.8 5847.1 5882.5 5927.1 5044.8 5282.5 5445.5 5609.8 5885.8 5038.9 5818.2 5832.4 6105.0 6173.7 2970.4 3259.8 3919.8 3946.1 3949.9 5134.3 5222.9 5890.7 5934.6 5948.6 5319.4 5662.0 5728.7 5756.5 5757.8 4703.5 4863.7 5583.2 5674.8 5716.6 5341.0 5341.7 5478.4 5500.2 5620.9 5160.8 5563.7 5697.6 5700.9 5701.5 5219.8 5583.8 5605.3 5605.8 5621.4 06:34:10 INFO - PROCESS | 1636 | 06:34:10 INFO - PROCESS | 1636 | [#5] yui.html Cycles:180 Average:18547.80 Median:26440.67 stddev:26218.65 (99.2%) stddev-sans-first:26257.62 06:34:10 INFO - PROCESS | 1636 | Values: 615.4 635.6 682.5 754.7 801.9 12042.6 12798.5 14298.5 14906.8 15017.2 12962.2 13495.7 13581.7 13584.4 13601.3 783.6 802.8 803.2 805.8 807.4 652.3 672.1 778.6 813.0 816.3 854.8 868.0 871.9 873.9 874.9 865.0 865.7 866.1 867.2 867.6 617.3 644.1 644.4 644.6 646.4 14353.8 14513.0 14539.1 14614.0 14700.9 13721.6 13907.3 14038.6 14113.2 14128.6 11388.9 11399.4 11595.8 11697.8 11740.2 3538.0 3665.0 3671.3 3736.7 3796.4 88833.6 91796.0 94841.5 96394.5 96597.0 10532.6 10687.3 10805.9 11027.4 11120.3 20450.5 20869.7 21269.3 21303.8 21434.6 6956.2 7199.6 7350.0 7420.0 7682.3 4132.4 4182.4 4204.7 4209.6 4302.3 3165.0 3244.3 3262.1 3277.6 3280.0 608.4 626.6 627.8 633.1 635.3 559.3 582.9 595.8 613.8 621.6 8589.0 8600.6 8640.8 8751.7 8783.5 517.5 527.6 532.3 533.9 567.3 3937.7 3984.2 4310.2 4413.3 4461.3 65067.0 77970.2 82478.6 98938.5 99644.0 55799.0 55883.2 65459.7 84458.6 86501.1 58355.7 61879.0 80413.0 82381.2 82647.2 8446.8 8513.3 8533.8 8598.0 8647.2 43606.1 44469.0 50947.0 51957.2 52353.7 63645.0 73134.6 74469.0 75663.9 76845.0 10807.7 11096.9 11154.2 11298.6 11392.1 12153.6 12323.9 12432.4 12452.9 12659.9 11702.6 12017.4 12053.9 12059.8 12327.8 11734.7 11958.8 12118.5 12182.5 12390.1 11512.3 11860.1 12043.3 12093.4 12121.6 9283.7 9362.0 9906.5 9911.1 10147.4 9997.1 10167.2 10177.8 10319.6 10343.7 06:34:10 INFO - PROCESS | 1636 | -------- Summary: end -------- 06:34:10 INFO - PROCESS | 1636 | 06:34:10 INFO - TEST-INFO | 1636: exit 0 06:34:10 INFO - TEST-OK | dromaeo_css | took 1118283ms 06:34:10 INFO - TEST-START | kraken 06:34:10 INFO - operating with platform_type : mac_ 06:34:10 INFO - Initialising browser for kraken test... 06:34:10 INFO - TEST-INFO | started process 1649 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/tmpX9CyRj/profile http://localhost:49235/getInfo.html) 06:34:13 INFO - PROCESS | 1649 | 2016-11-21 06:34:13.965 plugin-container[1651:10057] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4643, name = 'com.apple.tsm.portname' 06:34:13 INFO - PROCESS | 1649 | See /usr/include/servers/bootstrap_defs.h for the error codes. 06:34:13 INFO - PROCESS | 1649 | 2016-11-21 06:34:13.966 plugin-container[1651:10057] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x900b, name = 'com.apple.CFPasteboardClient' 06:34:13 INFO - PROCESS | 1649 | See /usr/include/servers/bootstrap_defs.h for the error codes. 06:34:13 INFO - PROCESS | 1649 | 2016-11-21 06:34:13.966 plugin-container[1651:10057] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 06:34:14 INFO - PROCESS | 1649 | __metrics Screen width/height:1600/1200 06:34:14 INFO - PROCESS | 1649 | colorDepth:24 06:34:14 INFO - PROCESS | 1649 | Browser inner width/height: 1024/689 06:34:14 INFO - PROCESS | 1649 | __metrics 06:34:14 ERROR - PROCESS | 1649 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3179: TypeError: this.tabs is undefined 06:34:14 INFO - TEST-INFO | 1649: exit 0 06:34:14 INFO - Browser initialized. 06:34:14 INFO - Running cycle 1/1 for kraken test... 06:34:14 INFO - TEST-INFO | started process 1652 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/tmpX9CyRj/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 06:34:17 INFO - PROCESS | 1652 | 2016-11-21 06:34:17.851 plugin-container[1653:10171] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4543, name = 'com.apple.tsm.portname' 06:34:17 INFO - PROCESS | 1652 | See /usr/include/servers/bootstrap_defs.h for the error codes. 06:34:17 INFO - PROCESS | 1652 | 2016-11-21 06:34:17.852 plugin-container[1653:10171] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8f0b, name = 'com.apple.CFPasteboardClient' 06:34:17 INFO - PROCESS | 1652 | See /usr/include/servers/bootstrap_defs.h for the error codes. 06:34:17 INFO - PROCESS | 1652 | 2016-11-21 06:34:17.852 plugin-container[1653:10171] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 06:34:27 INFO - PROCESS | 1652 | RSS: Main: 243126272 06:34:27 INFO - PROCESS | 1652 | 06:34:30 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49235/tests/kraken/driver.html?testName=audio-beat-detection) 06:34:31 INFO - PROCESS | 1652 | RSS: Main: 254996480 06:34:31 INFO - PROCESS | 1652 | 06:34:33 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49235/tests/kraken/driver.html?testName=audio-dft) 06:34:34 INFO - PROCESS | 1652 | RSS: Main: 262701056 06:34:34 INFO - PROCESS | 1652 | 06:34:37 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49235/tests/kraken/driver.html?testName=audio-fft) 06:34:38 INFO - PROCESS | 1652 | RSS: Main: 265584640 06:34:38 INFO - PROCESS | 1652 | 06:34:40 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49235/tests/kraken/driver.html?testName=audio-oscillator) 06:34:41 INFO - PROCESS | 1652 | RSS: Main: 266985472 06:34:41 INFO - PROCESS | 1652 | 06:34:42 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49235/tests/kraken/driver.html?testName=imaging-gaussian-blur) 06:34:43 INFO - PROCESS | 1652 | RSS: Main: 266997760 06:34:43 INFO - PROCESS | 1652 | 06:34:46 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49235/tests/kraken/driver.html?testName=imaging-darkroom) 06:34:47 INFO - PROCESS | 1652 | RSS: Main: 272363520 06:34:47 INFO - PROCESS | 1652 | 06:34:50 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49235/tests/kraken/driver.html?testName=imaging-desaturate) 06:34:51 INFO - PROCESS | 1652 | RSS: Main: 271462400 06:34:51 INFO - PROCESS | 1652 | 06:34:54 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49235/tests/kraken/driver.html?testName=json-parse-financial) 06:34:54 INFO - PROCESS | 1652 | RSS: Main: 273293312 06:34:54 INFO - PROCESS | 1652 | 06:34:56 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49235/tests/kraken/driver.html?testName=json-stringify-tinderbox) 06:34:56 INFO - PROCESS | 1652 | RSS: Main: 272035840 06:34:56 INFO - PROCESS | 1652 | 06:34:58 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49235/tests/kraken/driver.html?testName=stanford-crypto-aes) 06:34:59 INFO - PROCESS | 1652 | RSS: Main: 272191488 06:34:59 INFO - PROCESS | 1652 | 06:35:01 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49235/tests/kraken/driver.html?testName=stanford-crypto-ccm) 06:35:02 INFO - PROCESS | 1652 | RSS: Main: 272203776 06:35:02 INFO - PROCESS | 1652 | 06:35:05 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49235/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 06:35:06 INFO - PROCESS | 1652 | RSS: Main: 272502784 06:35:06 INFO - PROCESS | 1652 | 06:35:09 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49235/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 06:35:09 INFO - PROCESS | 1652 | RSS: Main: 273068032 06:35:09 INFO - PROCESS | 1652 | 06:35:11 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49235/tests/kraken/driver.html?testName=ai-astar) 06:35:11 INFO - PROCESS | 1652 | RSS: Main: 274079744 06:35:11 INFO - PROCESS | 1652 | 06:35:11 INFO - PROCESS | 1652 | __start_tp_report 06:35:11 INFO - PROCESS | 1652 | _x_x_mozilla_page_load 06:35:11 INFO - PROCESS | 1652 | _x_x_mozilla_page_load_details 06:35:11 INFO - PROCESS | 1652 | |i|pagename|runs| 06:35:11 INFO - PROCESS | 1652 | |0;ai-astar;112;120;116;118;118;118;119;115;133;118 06:35:11 INFO - PROCESS | 1652 | |1;audio-beat-detection;139;105;147;186;158;142;145;120;183;147 06:35:11 INFO - PROCESS | 1652 | |2;audio-dft;190;212;261;241;195;198;196;196;195;194 06:35:11 INFO - PROCESS | 1652 | |3;audio-fft;86;60;60;85;61;60;59;62;80;57 06:35:11 INFO - PROCESS | 1652 | |4;audio-oscillator;88;70;89;73;70;72;87;70;73;89 06:35:11 INFO - PROCESS | 1652 | |5;imaging-gaussian-blur;109;85;89;91;90;90;92;127;89;90 06:35:11 INFO - PROCESS | 1652 | |6;imaging-darkroom;141;125;97;125;110;95;139;154;113;95 06:35:11 INFO - PROCESS | 1652 | |7;imaging-desaturate;92;86;148;149;91;92;95;89;93;94 06:35:11 INFO - PROCESS | 1652 | |8;json-parse-financial;57;58;56;59;57;58;61;54;51;56 06:35:11 INFO - PROCESS | 1652 | |9;json-stringify-tinderbox;70;77;83;85;78;70;70;70;71;73 06:35:11 INFO - PROCESS | 1652 | |10;stanford-crypto-aes;78;110;111;109;115;84;88;91;87;98 06:35:11 INFO - PROCESS | 1652 | |11;stanford-crypto-ccm;149;165;148;152;161;161;170;191;213;169 06:35:11 INFO - PROCESS | 1652 | |12;stanford-crypto-pbkdf2;177;180;177;173;177;175;175;177;175;172 06:35:11 INFO - PROCESS | 1652 | |13;stanford-crypto-sha256-iterative;68;70;70;67;68;68;67;70;67;68 06:35:11 INFO - PROCESS | 1652 | __end_tp_report 06:35:11 INFO - PROCESS | 1652 | __start_cc_report 06:35:11 INFO - PROCESS | 1652 | _x_x_mozilla_cycle_collect,938 06:35:11 INFO - PROCESS | 1652 | __end_cc_report 06:35:11 INFO - PROCESS | 1652 | __startTimestamp1479738911892__endTimestamp 06:35:11 INFO - PROCESS | 1652 | 06:35:11 INFO - PROCESS | 1652 | ------- Summary: start ------- 06:35:11 INFO - PROCESS | 1652 | Number of tests: 14 06:35:11 INFO - PROCESS | 1652 | 06:35:11 INFO - PROCESS | 1652 | [#0] ai-astar Cycles:10 Average:118.70 Median:118.00 stddev:5.52 (4.7%) stddev-sans-first:5.29 06:35:11 INFO - PROCESS | 1652 | Values: 112.0 120.0 116.0 118.0 118.0 118.0 119.0 115.0 133.0 118.0 06:35:11 INFO - PROCESS | 1652 | 06:35:11 INFO - PROCESS | 1652 | [#1] audio-beat-detection Cycles:10 Average:147.20 Median:147.00 stddev:24.77 (16.9%) stddev-sans-first:26.10 06:35:11 INFO - PROCESS | 1652 | Values: 139.0 105.0 147.0 186.0 158.0 142.0 145.0 120.0 183.0 147.0 06:35:11 INFO - PROCESS | 1652 | 06:35:11 INFO - PROCESS | 1652 | [#2] audio-dft Cycles:10 Average:207.80 Median:197.00 stddev:23.94 (12.2%) stddev-sans-first:24.51 06:35:11 INFO - PROCESS | 1652 | Values: 190.0 212.0 261.0 241.0 195.0 198.0 196.0 196.0 195.0 194.0 06:35:11 INFO - PROCESS | 1652 | 06:35:11 INFO - PROCESS | 1652 | [#3] audio-fft Cycles:10 Average:67.00 Median:61.50 stddev:11.67 (19.0%) stddev-sans-first:10.15 06:35:11 INFO - PROCESS | 1652 | Values: 86.0 60.0 60.0 85.0 61.0 60.0 59.0 62.0 80.0 57.0 06:35:11 INFO - PROCESS | 1652 | 06:35:11 INFO - PROCESS | 1652 | [#4] audio-oscillator Cycles:10 Average:78.10 Median:80.00 stddev:8.82 (11.0%) stddev-sans-first:8.60 06:35:11 INFO - PROCESS | 1652 | Values: 88.0 70.0 89.0 73.0 70.0 72.0 87.0 70.0 73.0 89.0 06:35:11 INFO - PROCESS | 1652 | 06:35:11 INFO - PROCESS | 1652 | [#5] imaging-gaussian-blur Cycles:10 Average:95.20 Median:90.00 stddev:12.87 (14.3%) stddev-sans-first:12.65 06:35:11 INFO - PROCESS | 1652 | Values: 109.0 85.0 89.0 91.0 90.0 90.0 92.0 127.0 89.0 90.0 06:35:11 INFO - PROCESS | 1652 | 06:35:11 INFO - PROCESS | 1652 | [#6] imaging-darkroom Cycles:10 Average:119.40 Median:147.50 stddev:20.90 (14.2%) stddev-sans-first:20.66 06:35:11 INFO - PROCESS | 1652 | Values: 141.0 125.0 97.0 125.0 110.0 95.0 139.0 154.0 113.0 95.0 06:35:11 INFO - PROCESS | 1652 | 06:35:11 INFO - PROCESS | 1652 | [#7] imaging-desaturate Cycles:10 Average:102.90 Median:92.00 stddev:24.17 (26.3%) stddev-sans-first:25.31 06:35:11 INFO - PROCESS | 1652 | Values: 92.0 86.0 148.0 149.0 91.0 92.0 95.0 89.0 93.0 94.0 06:35:11 INFO - PROCESS | 1652 | 06:35:11 INFO - PROCESS | 1652 | [#8] json-parse-financial Cycles:10 Average:56.70 Median:57.50 stddev:2.75 (4.8%) stddev-sans-first:2.92 06:35:11 INFO - PROCESS | 1652 | Values: 57.0 58.0 56.0 59.0 57.0 58.0 61.0 54.0 51.0 56.0 06:35:11 INFO - PROCESS | 1652 | 06:35:11 INFO - PROCESS | 1652 | [#9] json-stringify-tinderbox Cycles:10 Average:74.70 Median:75.00 stddev:5.74 (7.6%) stddev-sans-first:5.83 06:35:11 INFO - PROCESS | 1652 | Values: 70.0 77.0 83.0 85.0 78.0 70.0 70.0 70.0 71.0 73.0 06:35:11 INFO - PROCESS | 1652 | 06:35:11 INFO - PROCESS | 1652 | [#10] stanford-crypto-aes Cycles:10 Average:97.10 Median:85.50 stddev:13.25 (15.5%) stddev-sans-first:12.12 06:35:11 INFO - PROCESS | 1652 | Values: 78.0 110.0 111.0 109.0 115.0 84.0 88.0 91.0 87.0 98.0 06:35:11 INFO - PROCESS | 1652 | 06:35:11 INFO - PROCESS | 1652 | [#11] stanford-crypto-ccm Cycles:10 Average:167.90 Median:167.00 stddev:20.23 (12.1%) stddev-sans-first:20.27 06:35:11 INFO - PROCESS | 1652 | Values: 149.0 165.0 148.0 152.0 161.0 161.0 170.0 191.0 213.0 169.0 06:35:11 INFO - PROCESS | 1652 | 06:35:11 INFO - PROCESS | 1652 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:175.80 Median:177.00 stddev:2.30 (1.3%) stddev-sans-first:2.40 06:35:11 INFO - PROCESS | 1652 | Values: 177.0 180.0 177.0 173.0 177.0 175.0 175.0 177.0 175.0 172.0 06:35:11 INFO - PROCESS | 1652 | 06:35:11 INFO - PROCESS | 1652 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.30 Median:68.00 stddev:1.25 (1.8%) stddev-sans-first:1.32 06:35:11 INFO - PROCESS | 1652 | Values: 68.0 70.0 70.0 67.0 68.0 68.0 67.0 70.0 67.0 68.0 06:35:11 INFO - PROCESS | 1652 | -------- Summary: end -------- 06:35:11 INFO - PROCESS | 1652 | 06:35:13 INFO - TEST-INFO | 1652: exit 0 06:35:13 INFO - TEST-OK | kraken | took 62388ms 06:35:13 INFO - SUITE-END | took 1180s 06:35:13 INFO - Completed test suite (00:19:41) 06:35:13 INFO - javascript benchmark 06:35:13 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 6943.398856898214, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2979.9702002979966, 3371.780834245774, 3376.797392156472, 3402.4215883299835, 3449.6032956210042, 4405.493368262651, 5019.849404517866, 5596.96456155611, 5827.941720582793, 5888.0, 5680.630759000662, 5781.017227071397, 5819.709014549267, 5822.184894114816, 5833.095870140124, 5222.712750798704, 5233.162693968966, 5275.0, 5346.786128554853, 5347.0, 5295.9672863791575, 5316.441773613773, 5317.6809391436445, 5334.839954801362, 5345.545628621572, 5306.93861227754, 5315.627906046579, 5317.973410132944, 5321.281626980366, 5332.013577488177, 5280.683159010472, 5293.417724050351, 5296.311479507678, 5309.920351194735, 5317.096093664087, 5153.819616313411, 5263.184206447969, 5265.131253343194, 5269.209618557209, 5275.235090911835, 5827.125931110325, 5827.213326200994, 5914.260717410324, 5930.436608522184, 5970.313413957407, 5192.584593232532, 6128.63228206309, 6290.24517057956, 6319.210098737658, 6323.6521991290465, 6143.419763699257, 6208.627482351028, 6219.1915051043525, 6250.218722659668, 6270.623762574214, 5946.672932988728, 5951.702414879239, 5953.404659534055, 5971.641701497924, 6000.639961602318, 6337.556371054075, 6344.429001389804, 6366.904496432558, 6368.617882927039, 6376.872462550816, 6085.391460853968, 6105.64170830808, 6143.047827586731, 6242.843928901813, 6252.437280644764, 5172.508611681884, 6085.661154545993, 6234.314225435199, 6267.2165979252595, 6288.213973253343, 6107.267127944675, 6127.403330890814, 6147.0, 6149.631022138686, 6179.783707570214, 6206.534509911775, 6210.099535567318, 6276.905846412307, 6294.590851594723, 6298.370162983665, 4985.0, 5009.89980200394, 5011.749412529359, 5019.698818070854, 5031.195008798574, 4384.671149663788, 4404.185225733249, 4406.911861762746, 4483.730976141442, 4496.910061798746, 5739.799107031234, 5950.732217050243, 5951.851203719855, 5982.012967860254, 6023.0965355196195, 6249.093881387264, 6259.280182778948, 6261.655608941552, 6271.905921411182, 6278.0269058295125, 5567.888642227133, 5592.804251851166, 5627.874425114994, 5636.464535869086, 5862.970685146633, 5484.424135465799, 5485.917711234335, 5507.339119305709, 5509.752061157257, 5542.0, 6423.5503514754455, 6436.259830119596, 6444.355564443612, 6451.935480645134, 6452.322506136788, 6153.6000159991245, 6184.134221209124, 6194.287656919512, 6275.309715931153, 6306.716197771203, 5412.242286080031, 6288.151099601679, 6431.228252609717, 6442.194725659293, 6454.225492940877, 6194.442500175006, 6248.968755156195, 6255.562110652393, 6280.654563998841, 6281.403266689657, 6047.516198704181, 6188.8762224754355, 6266.655333956493, 6268.811935641938, 6283.340249273943, 6123.923256659856, 6133.0, 6172.351903050115, 6197.5351848610635, 6247.781327653692, 6149.169862068742, 6194.504439644727, 6197.03945888388, 6215.689215539295, 6229.844253893689, 5240.921386179134, 5916.437938395846, 6026.0, 6076.6961651916345, 6135.0, 6102.420270074336, 6105.877882442415, 6113.694315284307, 6151.446369826737, 6178.289496707936, 5694.800681976111, 6106.72519736604, 6117.279373342293, 6137.286941400388, 6150.1082343059115], "value": 5732.194237243046, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [456.3064142503395, 476.8664773863317, 530.1808705549925, 532.8880935003647, 534.3694440560138, 38410.0, 39968.40047399288, 40169.79915100428, 40181.3972790408, 40275.39586906199, 41533.79233103838, 41859.74420767374, 41955.0, 42049.15901681964, 42060.0, 9341.97238303786, 9530.379835427993, 9534.189593884528, 9540.0, 9566.99546547613, 9459.432434053979, 9521.714348569554, 9531.046895310483, 9534.427934323963, 9550.9522452388, 9536.475493847836, 9557.474338911357, 9565.952170239174, 9568.186704130123, 9576.186024187917, 9467.242620590336, 9532.285078619061, 9543.713688589354, 9545.665901693443, 9558.61765529378, 9384.530773461367, 9488.146066854017, 9492.098250666175, 9492.525373731285, 9536.856947145798, 39654.40518392227, 39902.80048599767, 39942.400863987066, 39983.0, 40119.39820902689, 3189.39401513714, 3296.5549650797075, 3363.4114030044643, 3396.4226081566194, 3985.342418500944, 30174.54738178929, 30533.541996870066, 30740.0, 31265.37469250625, 31307.686923130706, 3143.387039527293, 3170.397624451347, 3181.9522707159413, 3211.1008917503136, 3271.656476070026, 474463.0, 478396.0, 480333.0, 480521.0, 481770.9999999965, 32021.67978320187, 32984.0, 57498.85002299931, 57728.42271577231, 57981.71009144927, 34189.48715769265, 34254.314913701586, 34373.484397733555, 34461.310773784884, 34601.0, 14077.929610351883, 14291.57125286243, 14300.499482518062, 14303.0, 14305.070170438888, 11883.049356051495, 12020.819687704692, 12022.519099235933, 12040.759184816254, 12069.818952715716, 7668.923310766932, 7676.769696909102, 7744.148143704188, 7755.1469338372735, 7783.29950304464, 2777.6111344411797, 2851.344190836119, 2983.522636378169, 3025.243689077731, 3205.6473787883315, 848.5460278750838, 993.3146129170849, 1024.8872624011353, 1062.8246339354075, 1124.100719424457, 26592.468150637265, 29251.122466326044, 29764.0, 30181.943631973216, 30623.540646890313, 835.0814104485017, 1021.4586269277295, 1026.3790406803926, 1028.3726926574784, 1141.851559297286, 13123.212607243597, 14839.851601484062, 15313.770293445385, 15441.768373474408, 15718.056916584812, 641637.0, 642150.0, 642713.0, 647553.0, 649842.0, 56438.15342769862, 56934.430655692915, 56971.43028569744, 56975.0, 57032.42967570354, 1161.6456980620992, 1268.7462507498537, 1332.8000799880097, 1350.770369037266, 1351.3445978700322, 26084.956601735717, 27073.052443164393, 28453.715462845106, 29843.850780745957, 29954.700452995192, 49880.750596246784, 50122.74938625429, 50296.49703503065, 50527.0, 50535.0, 576221.0, 579016.0, 579672.0, 580054.0, 580449.0, 25479.61780573256, 25769.0, 25875.964961401332, 25943.0919917802, 26042.0, 43667.34498982581, 44339.0, 45752.0, 45772.54227457816, 46718.83202919954, 37754.0, 38004.0, 38376.75799018715, 38449.23101538009, 39012.0, 37037.25925481529, 37576.88186976677, 38219.808900955315, 38312.233755325295, 38642.80678596701, 28368.71631283661, 31778.728850845706, 36641.08397290089, 37172.81413592915, 37514.0, 32185.517217241293, 33735.32529349448, 33769.81805636791, 33805.49291760576, 34087.65912340944, 22914.77085229193, 22988.080476780742, 24168.637470437607, 24634.753652463245, 25179.622305665795], "value": 18188.0970178502, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [366.52717993788013, 370.6775105658076, 376.2606478270199, 387.5601192646346, 404.7146761533119, 18364.816351836478, 19021.61956760864, 19121.0, 19593.51016224595, 19697.704534431978, 18601.534961625934, 18609.813901860976, 19417.611647767073, 19523.609527809436, 19818.0, 4556.521565235652, 5208.713520756366, 5250.949810037989, 5411.431799661038, 5412.756425960822, 5233.633645644806, 5335.599830012745, 5341.225522299264, 5344.759485823128, 5360.812371566996, 5192.6624769390055, 5234.79060837568, 5331.786728530854, 5340.813071542497, 5350.3312085989255, 5240.44975277596, 5324.1215199492035, 5326.36083669962, 5339.466053394668, 5345.866353341159, 4959.181735013718, 5164.741762911877, 5174.275601415805, 5185.455527169631, 5186.8070343820755, 12995.610131696065, 13005.089643724943, 13128.60614181576, 13140.211587304697, 17286.481405557726, 2128.5636444528836, 2136.444524423661, 2151.741790985076, 2170.3814412892248, 2298.1152256381247, 9772.560234789451, 10251.0, 10433.113185379252, 10867.728306792314, 11101.3894235817, 1780.4925596205019, 1823.872328936975, 1850.1211924335967, 1860.7301941218582, 1894.4222012286248, 107108.85782284389, 119158.0, 122967.0, 123381.0, 125327.3733631335, 9619.951900240594, 10792.892071079188, 11087.390193539433, 11391.544338226544, 11413.42932853354, 14252.358643861051, 14268.57194284173, 14278.2146981917, 14317.570472885622, 14318.498852540319, 7937.849011893243, 8197.631106600218, 8252.504849709037, 8374.115823939686, 8398.706045288385, 8053.31546818527, 8097.838043239102, 8120.431569790177, 8220.506769593843, 8222.465539739898, 4441.333799930037, 4495.820167193276, 4543.840965566255, 4555.180067587799, 4569.588737013684, 1625.7236269834157, 1677.9412720554967, 1890.782560005617, 1897.459224121119, 1969.3304276545757, 769.3806485779002, 892.6518657723493, 968.7771812483169, 988.5205675247643, 1017.6946915925192, 19905.0, 21156.047977841034, 21280.0, 21346.78653213479, 21356.35930922075, 813.0, 840.9831803363899, 895.0870112485229, 910.349100393222, 950.8288508068475, 8480.787980300542, 10189.694309170736, 11186.993170614684, 11206.663800085847, 11261.718457038476, 430183.0, 559030.0, 560232.0, 575920.0, 607764.0, 108288.0, 110213.0, 122373.38813305876, 122592.0, 123030.76969230371, 948.2982592881218, 984.4634674102631, 1008.9798204035878, 1010.9696709098739, 1012.1548506996593, 15785.921070394574, 18914.14886330146, 19102.522436939187, 19199.90400047991, 19215.80784192196, 33277.83361083179, 49907.25139122989, 50474.49525504698, 52470.21294680659, 55124.17313740217, 48333.0, 50197.99604007972, 54057.72971135119, 58154.83690326085, 60090.0, 5886.264216972879, 7769.484950434611, 7925.920740792749, 7968.92031079705, 8023.59882005909, 9325.813483730422, 12122.030237581148, 12230.388480576114, 12328.938355308166, 12711.93644031811, 10435.739106522398, 10712.785744285227, 10785.568577257136, 10940.617078402218, 11125.332480051224, 9245.798046253944, 10331.483425828528, 10617.946910265708, 10671.399290106547, 10758.892411075789, 10840.5121769519, 11031.79364127194, 11068.723281918017, 11093.16801239894, 11143.888561114609, 5672.971635141798, 7976.481528700616, 8067.0, 8126.674933002851, 8206.58967051657, 10688.983924206366, 11129.888701112885, 11184.660808225222, 11348.092152627934, 11353.772924541629], "value": 8929.458702013135, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1358.1240100135412, 1442.8485009074047, 1600.2318886934272, 1722.3110755697728, 1769.2038582637808, 7514.3988480921535, 7775.3779697624195, 8085.797855053626, 8514.70198543051, 8543.444676096049, 7986.482568312017, 8260.586970651473, 8292.875606865886, 8367.079621241674, 8385.580720963959, 2312.0636142362287, 2337.801286890608, 2339.988300058506, 2344.4373350395954, 2351.8824058797077, 2330.8718020508863, 2370.3244575296044, 2380.143148466551, 2387.128698025217, 2391.5575618510543, 2324.651302304651, 2343.215022967308, 2347.319277409549, 2351.0478256306224, 2352.200251914349, 2302.873341966191, 2312.4681323295567, 2334.031376978552, 2337.322176568793, 2338.789508944203, 1968.4389948864505, 2345.1205797825814, 2353.7528559501347, 2359.197872723282, 2360.3037104054174, 8360.623771930277, 8377.790555236168, 8451.36614753896, 8465.703700370519, 8482.363822713321, 4941.579965702919, 5103.974480127613, 5107.7956881724685, 5123.385193776733, 5128.871778205537, 3296.5549650797075, 3384.424647809878, 3571.017970058229, 3626.3291291111227, 3648.908777280563, 1010.3281317923572, 1029.093198368125, 1068.5351871935734, 1070.5931745936573, 1095.2935356694966, 10922.781544369069, 10997.450127493674, 11075.833862492149, 11081.27971681838, 11084.445777711082, 4442.466903971479, 4560.156371071363, 4672.43930728308, 4690.742009189459, 4692.718436893797, 9320.580573874191, 9401.388909720847, 9417.482038487811, 9417.952910235543, 9436.716898493056, 5185.585153187736, 5185.714785686823, 5191.351081114861, 5196.870078248074, 5206.739663016834, 4250.491075668155, 4258.723182993095, 4720.291956206596, 4725.976370118196, 4776.904461910743, 3105.8602362893726, 3723.739338246297, 3833.4058220975794, 3839.0, 3841.2125514269505, 1119.3116233516491, 1248.3570960955115, 1254.667513109027, 1276.540445439641, 1288.4846061575258, 689.2797027106685, 785.4541093939657, 797.9068675914035, 834.7312085629809, 900.3022657440405, 4744.857654270378, 4775.283707443842, 5582.469665381782, 5753.51095156908, 5758.88482230353, 381.39929610863004, 423.57642357642357, 424.9851255206053, 426.5201648145836, 433.15318552230315, 3773.2076263984873, 3798.6770776346325, 3847.6537111660086, 3868.052327179859, 3925.764454132704, 7511.661975211128, 9965.750856228653, 10081.596736130472, 10089.747756306151, 10102.040306170902, 10095.697129086138, 10116.342437741523, 10127.442990635585, 10134.189264858942, 10170.186385089175, 7745.302922736981, 7792.922070779447, 7855.489393189425, 7856.489328193649, 7876.094249161305, 5433.728313584384, 5516.034693928626, 5580.246666700106, 5621.943780562142, 5638.0, 9385.765355866159, 9396.859047114162, 9415.764605884908, 9431.62273509052, 9437.81124377495, 7815.6092195388865, 9329.207017403596, 9479.146876781122, 9505.001974792547, 9677.903220967983, 7063.858722825617, 7074.646267686492, 7104.431645468453, 7120.145582530129, 7148.714051438093, 7823.960880195563, 7891.092524359772, 7900.486468379537, 7905.762827115196, 7954.721584744506, 6873.20958089826, 6895.172579290517, 7144.535605185646, 7187.460940429384, 7199.316064973819, 6697.899531507129, 6804.217514985642, 6905.516613837184, 6915.688794004182, 7015.824604384931, 6407.679616019273, 6565.409113179646, 6620.304867988984, 6643.103181070687, 6756.189257289157, 4842.5157484251295, 4900.117978763857, 4914.655974081923, 4933.6299777516115, 4951.158303088556, 4826.32431459603, 4863.221884498464, 4876.756162191948, 4966.485222007323, 5031.22016087507], "value": 4299.545296382944, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [301.29797571658025, 304.57511771079345, 308.284779311996, 314.42146450531027, 317.9998903448654, 4442.289233722597, 5014.774335154918, 5368.248445217672, 5472.288602481672, 5477.260569823077, 4925.211966085427, 5035.345405097341, 5202.947970520294, 5213.9478605213935, 5255.973720131404, 2941.0000599796067, 3993.1614360984154, 4030.717849750519, 4040.222302187028, 4042.171354872244, 3304.37216928784, 3770.924581508368, 4021.5978402159694, 4057.797110144496, 4064.8821574067074, 3104.410162069211, 3261.673832616743, 3821.388577827548, 3941.192055628589, 3969.1466334738025, 3559.754086069855, 3772.6793222576116, 3913.3738601823716, 3950.3481925482547, 3967.682585393162, 3481.547398838135, 3484.738644601665, 3491.074865160709, 3491.4064609016527, 3492.51568083566, 4928.704277743347, 5152.587792976553, 5177.249298851683, 5214.895702085975, 5220.347456567929, 4551.4083169188125, 4557.316402539613, 4563.292689633112, 4570.680052396334, 4583.679142460029, 4893.336265669689, 5079.974600126976, 5563.610547261694, 5568.387477377485, 5588.469095435927, 2999.9700003000125, 3534.434490481536, 3829.5021647185968, 3831.348670725983, 3885.7862817545024, 5394.491561423917, 5648.519875810561, 5906.0, 5907.0, 5982.564184595709, 4013.4983127109113, 4021.276170289376, 4025.295573274665, 4028.3756017817286, 4033.69747268956, 3867.8839634811, 4073.287174744408, 4074.1647962167685, 4099.385092236188, 4130.318497447887, 4270.722403043792, 4614.0, 4663.183942809995, 4673.299005149255, 4676.766161691902, 5736.475950581731, 5743.4543718346695, 5752.856178595569, 5777.913331300034, 5787.71061446926, 1181.7518321152481, 1263.5261776833688, 1280.8719128087303, 1282.0897163014179, 1340.3700260877372, 895.9283257339397, 919.8593743757826, 920.3281604428804, 922.4511415707643, 931.5249222896372, 4470.6870519064005, 4812.72462797359, 4938.25926111079, 4969.583668654416, 5056.9494305057215, 4682.765861706901, 4762.14281429346, 5088.6946783193125, 5306.787728490895, 5312.442193569696, 4143.61330270647, 4277.6739210844735, 5042.974785126051, 5065.45418207249, 5079.561810330988, 2234.1510226113974, 2314.4213946513373, 2622.7901767858525, 2746.5742809864505, 2872.6696429910826, 5422.969635769191, 5572.0, 5630.915536266959, 5686.800961966312, 6101.7559297627595, 5818.912716309259, 6043.969780151071, 6063.878722425527, 6068.575199735976, 6099.634021958696, 4956.776945037482, 5251.894962100737, 6055.333913269536, 6082.840591589922, 6140.631562106288, 4515.261624274744, 5615.803446879339, 5847.1229315603, 5882.5293976480925, 5927.051671732502, 5044.798208071636, 5282.498162674539, 5445.53712934405, 5609.831705048855, 5885.792243227365, 5038.949610503994, 5818.2436283282905, 5832.387599302012, 6105.046385156707, 6173.660448675187, 2970.3762209936153, 3259.755518336182, 3919.804009799556, 3946.0726729218036, 3949.861754838567, 5134.332536770196, 5222.869428264324, 5890.734916928663, 5934.6142500737315, 5948.5538584605465, 5319.361676598678, 5661.951704531115, 5728.713564321851, 5756.510696590837, 5757.827265182052, 4703.529647035269, 4863.7324947128245, 5583.246261754611, 5674.829755107354, 5716.598623818822, 5341.0, 5341.7030676809345, 5478.397376288684, 5500.18493897021, 5620.915686264628, 5160.838811267435, 5563.749631266522, 5697.6296540724725, 5700.857478563069, 5701.543876489954, 5219.8434046978655, 5583.822309299458, 5605.299337582802, 5605.804691325905, 5621.3698027571545], "value": 4056.6332725786433, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [615.4460985113398, 635.5963962883568, 682.5154140560202, 754.6679461037144, 801.9077806052304, 12042.57850975216, 12798.48806047757, 14298.498657640954, 14906.776398354019, 15017.174055426947, 12962.222266664006, 13495.66260843477, 13581.728365432686, 13584.388702508386, 13601.319934003284, 783.5612057247931, 802.8434455281223, 803.220875750521, 805.7824387415394, 807.4146243973131, 652.3313603556345, 672.1094549721614, 778.595130532123, 812.995935020324, 816.3428440105713, 854.8418542569614, 867.9913200867974, 871.9258862996653, 873.8907636545432, 874.921257086862, 865.0268447996004, 865.6580650643024, 866.138192498465, 867.1675191815864, 867.5792240763257, 617.3024482334978, 644.1336402538578, 644.445776632096, 644.5842431631571, 646.385933363302, 14353.78469322961, 14513.0, 14539.114539611352, 14614.0, 14700.926495367456, 13721.588352349445, 13907.304634768221, 14038.649033774136, 14113.223772692498, 14128.576142715736, 11388.943055284753, 11399.3730344831, 11595.768084638345, 11697.824532632018, 11740.178187526877, 3538.0, 3665.0, 3671.2657468506404, 3736.7197460190323, 3796.354619714655, 88833.57608890862, 91796.0, 94841.5257923706, 96394.51802740942, 96597.03402966021, 10532.57869685204, 10687.30532515384, 10805.945970270099, 11027.393493357782, 11120.277181983145, 20450.488737781674, 20869.68695469569, 21269.255576054762, 21303.828289443918, 21434.57130857374, 6956.234814170437, 7199.604021778852, 7349.963250183821, 7420.0, 7682.270184332479, 4132.380142978517, 4182.435371224907, 4204.66362690984, 4209.6000879916355, 4302.26861433557, 3165.0, 3244.253821620993, 3262.13553408347, 3277.623073346548, 3280.048785852076, 608.3733754233147, 626.6240255846456, 627.8399008252958, 633.0978355842998, 635.3233805996599, 559.3260121553518, 582.8950788857961, 595.7540073921292, 613.8219916224334, 621.5814256844485, 8588.957055214809, 8600.612972416257, 8640.783980400414, 8751.737447876574, 8783.5169065702, 517.4902720819989, 527.604296777417, 532.2521856791215, 533.871870751017, 567.2682239910552, 3937.665298449664, 3984.2230765000545, 4310.202612516695, 4413.271810151353, 4461.330800379969, 65067.0, 77970.22029779744, 82478.58760706279, 98938.51592226123, 99644.0, 55799.0, 55883.161752573746, 65459.672701636184, 84458.5777071123, 86501.13498865056, 58355.70822145947, 61879.0, 80413.0, 82381.17618823855, 82647.17352826394, 8446.83106337882, 8513.318934485102, 8533.829323413622, 8597.957010214908, 8647.178518041028, 43606.07545320936, 44469.0, 50946.98106037932, 51957.22064168965, 52353.7382313086, 63645.0, 73134.63432683016, 74469.0, 75663.86504202551, 76845.0, 10807.729806754895, 11096.88903110991, 11154.163437742363, 11298.60454884075, 11392.145589080686, 12153.57462488844, 12323.9419076799, 12432.378381081091, 12452.937735311629, 12659.936700316808, 11702.648920532398, 12017.399130043637, 12053.879461205626, 12059.819102713642, 12327.753444930871, 11734.70663233426, 11958.760824783629, 12118.515259389525, 12182.512699491921, 12390.132690711564, 11512.251703639236, 11860.110491713329, 12043.277403355827, 12093.395330233629, 12121.636350909486, 9283.72148835536, 9362.0, 9906.455144967096, 9911.058449447291, 10147.441890696082, 9997.050280223368, 10167.237457190593, 10177.796444071226, 10319.587216511256, 10343.689689309333], "value": 6900.286555490104, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1576.8, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 120.0, 116.0, 118.0, 118.0, 118.0, 119.0, 115.0, 133.0, 118.0], "value": 118.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [139.0, 105.0, 147.0, 186.0, 158.0, 142.0, 145.0, 120.0, 183.0, 147.0], "value": 147.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [190.0, 212.0, 261.0, 241.0, 195.0, 198.0, 196.0, 196.0, 195.0, 194.0], "value": 207.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 60.0, 60.0, 85.0, 61.0, 60.0, 59.0, 62.0, 80.0, 57.0], "value": 67.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 70.0, 89.0, 73.0, 70.0, 72.0, 87.0, 70.0, 73.0, 89.0], "value": 78.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [109.0, 85.0, 89.0, 91.0, 90.0, 90.0, 92.0, 127.0, 89.0, 90.0], "value": 95.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 125.0, 97.0, 125.0, 110.0, 95.0, 139.0, 154.0, 113.0, 95.0], "value": 119.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 86.0, 148.0, 149.0, 91.0, 92.0, 95.0, 89.0, 93.0, 94.0], "value": 102.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 58.0, 56.0, 59.0, 57.0, 58.0, 61.0, 54.0, 51.0, 56.0], "value": 56.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 77.0, 83.0, 85.0, 78.0, 70.0, 70.0, 70.0, 71.0, 73.0], "value": 74.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 110.0, 111.0, 109.0, 115.0, 84.0, 88.0, 91.0, 87.0, 98.0], "value": 97.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 165.0, 148.0, 152.0, 161.0, 161.0, 170.0, 191.0, 213.0, 169.0], "value": 167.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [177.0, 180.0, 177.0, 173.0, 177.0, 175.0, 175.0, 177.0, 175.0, 172.0], "value": 175.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 70.0, 70.0, 67.0, 68.0, 68.0, 67.0, 70.0, 67.0, 68.0], "value": 68.3, "unit": "score"}]}]} 06:35:13 INFO - Return code: 0 06:35:13 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 06:35:13 ERROR - # TBPL SUCCESS # 06:35:13 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 06:35:13 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 06:35:13 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 06:35:13 INFO - 2016-11-21 06:35:13.728 screenresolution[1657:10554] starting screenresolution argv=screenresolution get 06:35:13 INFO - 2016-11-21 06:35:13.730 screenresolution[1657:10554] Display 0: 1600x1200x32@60 06:35:13 INFO - 2016-11-21 06:35:13.736 screenresolution[1658:10557] starting screenresolution argv=screenresolution list 06:35:13 INFO - Available Modes on Display 0 06:35:13 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 06:35:13 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 06:35:13 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 06:35:13 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 06:35:13 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 06:35:13 INFO - Intel Iris: 06:35:13 INFO - Chipset Model: Intel Iris 06:35:13 INFO - Type: GPU 06:35:13 INFO - Bus: Built-In 06:35:13 INFO - VRAM (Dynamic, Max): 1536 MB 06:35:13 INFO - Vendor: Intel (0x8086) 06:35:13 INFO - Device ID: 0x0a2e 06:35:13 INFO - Revision ID: 0x0009 06:35:13 INFO - Displays: 06:35:13 INFO - Display: 06:35:13 INFO - Resolution: 1600 x 1200 @ 60 Hz 06:35:13 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 06:35:13 INFO - Display Serial Number: 1600x1200 60 06:35:13 INFO - Main Display: Yes 06:35:13 INFO - Mirror: Off 06:35:13 INFO - Online: Yes 06:35:13 INFO - Rotation: Supported 06:35:13 INFO - Return code: 0 06:35:13 INFO - Running post-action listener: _resource_record_post_action 06:35:13 INFO - [mozharness: 2016-11-21 14:35:13.970531Z] Finished run-tests step (success) 06:35:13 INFO - Running post-run listener: _resource_record_post_run 06:35:14 INFO - Total resource usage - Wall time: 1214s; CPU: 54.0%; Read bytes: 38602752; Write bytes: 699147264; Read time: 243; Write time: 2037 06:35:14 INFO - TinderboxPrint: CPU usage
54.4% 06:35:14 INFO - TinderboxPrint: I/O read bytes / time
38,602,752 / 243 06:35:14 INFO - TinderboxPrint: I/O write bytes / time
699,147,264 / 2,037 06:35:14 INFO - TinderboxPrint: CPU idle
2,217.3 (45.6%) 06:35:14 INFO - TinderboxPrint: CPU system
975.6 (20.1%) 06:35:14 INFO - TinderboxPrint: CPU user
1,664.8 (34.3%) 06:35:14 INFO - TinderboxPrint: Swap in / out
422,772,736 / 0 06:35:14 INFO - install - Wall time: 33s; CPU: 54.0%; Read bytes: 368163328; Write bytes: 367364096; Read time: 27448; Write time: 782 06:35:14 INFO - run-tests - Wall time: 1182s; CPU: 54.0%; Read bytes: 37672960; Write bytes: 311789056; Read time: 232; Write time: 1196 06:35:14 INFO - Running post-run listener: _upload_blobber_files 06:35:14 INFO - Blob upload gear active. 06:35:14 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 06:35:14 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 06:35:14 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', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 06:35:14 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 Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 06:35:14 INFO - (blobuploader) - INFO - Open directory for files ... 06:35:14 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 06:35:14 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:35:14 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:35:16 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 06:35:16 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:35:16 INFO - (blobuploader) - INFO - Done attempting. 06:35:16 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 06:35:16 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:35:16 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:35:17 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 06:35:17 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:35:17 INFO - (blobuploader) - INFO - Done attempting. 06:35:17 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 06:35:17 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:35:17 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:35:19 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 06:35:19 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:35:19 INFO - (blobuploader) - INFO - Done attempting. 06:35:19 INFO - (blobuploader) - INFO - Iteration through files over. 06:35:19 INFO - Return code: 0 06:35:19 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 06:35:19 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 06:35:19 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/30a582b7b3281bd54c2c35129d3d338ee0483faeed30bcb168ef2c66a1681ab4f577b3477156bd8dbdfc30b5cfaac0c439564e211f8e65fc20e37fd2586f3180", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/223a57126a4a703caa278bf401b98f2930a7e4f1578605bb859867c7cd627eb3d6cb23e9e6a8b0eac3b8bda76dd0447a931b964515649b8e2e55354b58631945", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8209f85937fc294138fa2ebf1fa08f204ce502895c88bfcbbf38e6cd4977a43194cc8f0ec1921624144649e7b4e2afac1396bb84e53d0a10893f04d7ddc24356"} 06:35:19 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 06:35:19 INFO - Writing to file /builds/slave/test/properties/blobber_files 06:35:19 INFO - Contents: 06:35:19 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/30a582b7b3281bd54c2c35129d3d338ee0483faeed30bcb168ef2c66a1681ab4f577b3477156bd8dbdfc30b5cfaac0c439564e211f8e65fc20e37fd2586f3180", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/223a57126a4a703caa278bf401b98f2930a7e4f1578605bb859867c7cd627eb3d6cb23e9e6a8b0eac3b8bda76dd0447a931b964515649b8e2e55354b58631945", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8209f85937fc294138fa2ebf1fa08f204ce502895c88bfcbbf38e6cd4977a43194cc8f0ec1921624144649e7b4e2afac1396bb84e53d0a10893f04d7ddc24356"} 06:35:19 INFO - Running post-run listener: copy_logs_to_upload_dir 06:35:19 INFO - Copying logs to upload dir... 06:35:19 INFO - mkdir: /builds/slave/test/build/upload/logs 06:35:19 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1276.808565 ========= master_lag: 0.34 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 17 secs) (at 2016-11-21 06:35:19.825449) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-21 06:35:19.832667) ========= 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.vnd6pUV2Oy/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.k0TzGD54Zq/Listeners TMPDIR=/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/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/Firefox/sha512/30a582b7b3281bd54c2c35129d3d338ee0483faeed30bcb168ef2c66a1681ab4f577b3477156bd8dbdfc30b5cfaac0c439564e211f8e65fc20e37fd2586f3180", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/223a57126a4a703caa278bf401b98f2930a7e4f1578605bb859867c7cd627eb3d6cb23e9e6a8b0eac3b8bda76dd0447a931b964515649b8e2e55354b58631945", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8209f85937fc294138fa2ebf1fa08f204ce502895c88bfcbbf38e6cd4977a43194cc8f0ec1921624144649e7b4e2afac1396bb84e53d0a10893f04d7ddc24356"} build_url:https://queue.taskcluster.net/v1/task/KvETyyTcSmGQ7MYt6svQ7Q/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011428 build_url: 'https://queue.taskcluster.net/v1/task/KvETyyTcSmGQ7MYt6svQ7Q/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/30a582b7b3281bd54c2c35129d3d338ee0483faeed30bcb168ef2c66a1681ab4f577b3477156bd8dbdfc30b5cfaac0c439564e211f8e65fc20e37fd2586f3180", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/223a57126a4a703caa278bf401b98f2930a7e4f1578605bb859867c7cd627eb3d6cb23e9e6a8b0eac3b8bda76dd0447a931b964515649b8e2e55354b58631945", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8209f85937fc294138fa2ebf1fa08f204ce502895c88bfcbbf38e6cd4977a43194cc8f0ec1921624144649e7b4e2afac1396bb84e53d0a10893f04d7ddc24356"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-21 06:35:19.872727) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-21 06:35:19.873063) ========= 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.vnd6pUV2Oy/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.k0TzGD54Zq/Listeners TMPDIR=/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/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.005078 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-21 06:35:19.921079) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-21 06:35:19.921399) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-21 06:35:19.921720) ========= ========= Total master_lag: 0.49 =========