builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0166 starttime: 1475854452.01 results: success (0) buildid: 20161007063900 builduid: 9d129c0789f54a68a3f841eb72384498 revision: 9c11fedf43675388a547f09cc4a64e78739f5220 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-10-07 08:34:12.005438) ========= master: http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-10-07 08:34:12.005888) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-07 08:34:12.006160) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-07 08:34:12.030417) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-07 08:34:12.030691) ========= 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.CC1Bw8tf70/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.ux1vVasRc3/Listeners TMPDIR=/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-10-07 08:34:12-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 16.1M=0.001s 2016-10-07 08:34:12 (16.1 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.116305 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-07 08:34:12.169052) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-07 08:34:12.169362) ========= 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.CC1Bw8tf70/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.ux1vVasRc3/Listeners TMPDIR=/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/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.028208 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-07 08:34:12.217287) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-07 08:34:12.217619) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 9c11fedf43675388a547f09cc4a64e78739f5220 --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 9c11fedf43675388a547f09cc4a64e78739f5220 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.CC1Bw8tf70/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.ux1vVasRc3/Listeners TMPDIR=/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-10-07 08:34:12,287 truncating revision to first 12 chars 2016-10-07 08:34:12,287 Setting DEBUG logging. 2016-10-07 08:34:12,287 attempt 1/10 2016-10-07 08:34:12,287 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/9c11fedf4367?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-10-07 08:34:13,383 unpacking tar archive at: mozilla-central-9c11fedf4367/testing/mozharness/ program finished with exit code 0 elapsedTime=1.358138 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-07 08:34:13.596982) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-07 08:34:13.597301) ========= script_repo_revision: 9c11fedf43675388a547f09cc4a64e78739f5220 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-07 08:34:13.597676) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-07 08:34:13.597953) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-07 08:34:13.615590) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 18 secs) (at 2016-10-07 08:34:13.615879) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --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', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--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.CC1Bw8tf70/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.ux1vVasRc3/Listeners TMPDIR=/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/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 08:34:13 INFO - MultiFileLogger online at 20161007 08:34:13 in /builds/slave/test 08:34:13 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 08:34:13 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 08:34:13 INFO - {'append_to_log': False, 08:34:13 INFO - 'base_work_dir': '/builds/slave/test', 08:34:13 INFO - 'blob_upload_branch': 'Firefox', 08:34:13 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 08:34:13 INFO - 'branch': 'Firefox', 08:34:13 INFO - 'buildbot_json_path': 'buildprops.json', 08:34:13 INFO - 'config_files': ('talos/mac_config.py',), 08:34:13 INFO - 'default_actions': ('clobber', 08:34:13 INFO - 'read-buildbot-config', 08:34:13 INFO - 'download-and-extract', 08:34:13 INFO - 'populate-webroot', 08:34:13 INFO - 'create-virtualenv', 08:34:13 INFO - 'install', 08:34:13 INFO - 'run-tests'), 08:34:13 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 08:34:13 INFO - 'download_minidump_stackwalk': True, 08:34:13 INFO - 'download_symbols': 'ondemand', 08:34:13 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 08:34:13 INFO - 'tooltool.py': '/tools/tooltool.py', 08:34:13 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 08:34:13 INFO - '/tools/misc-python/virtualenv.py')}, 08:34:13 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 08:34:13 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 08:34:13 INFO - 'installer_path': 'installer.exe', 08:34:13 INFO - 'log_level': 'info', 08:34:13 INFO - 'log_name': 'talos', 08:34:13 INFO - 'log_to_console': True, 08:34:13 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 08:34:13 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 08:34:13 INFO - 'opt_config_files': (), 08:34:13 INFO - 'pip_index': False, 08:34:13 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:34:13 INFO - 'cmd': ('bash', 08:34:13 INFO - '-c', 08:34:13 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:34:13 INFO - 'enabled': True, 08:34:13 INFO - 'halt_on_failure': False, 08:34:13 INFO - 'name': 'check_screen_resolution'},), 08:34:13 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:34:13 INFO - 'cmd': ('bash', 08:34:13 INFO - '-c', 08:34:13 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:34:13 INFO - 'enabled': True, 08:34:13 INFO - 'halt_on_failure': False, 08:34:13 INFO - 'name': 'check_screen_resolution'},), 08:34:13 INFO - 'run_cmd_checks_enabled': True, 08:34:13 INFO - 'sps_profile': False, 08:34:13 INFO - 'sps_profile_interval': 0, 08:34:13 INFO - 'suite': 'dromaeojs', 08:34:13 INFO - 'system_bits': '32', 08:34:13 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 08:34:13 INFO - 'title': 't-yosemite-r7-0166', 08:34:13 INFO - 'tooltool_cache': '/builds/tooltool_cache', 08:34:13 INFO - 'use_talos_json': True, 08:34:13 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 08:34:13 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 08:34:13 INFO - 'work_dir': 'build'} 08:34:13 INFO - [mozharness: 2016-10-07 15:34:13.818083Z] Running clobber step. 08:34:13 INFO - Running pre-action listener: _resource_record_pre_action 08:34:13 INFO - Running main action method: clobber 08:34:13 INFO - rmtree: /builds/slave/test/build 08:34:13 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 08:34:16 INFO - Running post-action listener: _resource_record_post_action 08:34:16 INFO - [mozharness: 2016-10-07 15:34:16.064459Z] Finished clobber step (success) 08:34:16 INFO - [mozharness: 2016-10-07 15:34:16.064582Z] Running read-buildbot-config step. 08:34:16 INFO - Running pre-action listener: _resource_record_pre_action 08:34:16 INFO - Running main action method: read_buildbot_config 08:34:16 INFO - Using buildbot properties: 08:34:16 INFO - { 08:34:16 INFO - "project": "", 08:34:16 INFO - "product": "firefox", 08:34:16 INFO - "script_repo_revision": "production", 08:34:16 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 08:34:16 INFO - "repository": "", 08:34:16 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 08:34:16 INFO - "buildid": "20161007063900", 08:34:16 INFO - "pgo_build": "False", 08:34:16 INFO - "basedir": "/builds/slave/test", 08:34:16 INFO - "buildnumber": 45, 08:34:16 INFO - "slavename": "t-yosemite-r7-0166", 08:34:16 INFO - "master": "http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/", 08:34:16 INFO - "platform": "yosemite_r7", 08:34:16 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 08:34:16 INFO - "branch": "mozilla-central", 08:34:16 INFO - "repo_path": "mozilla-central", 08:34:16 INFO - "revision": "9c11fedf43675388a547f09cc4a64e78739f5220", 08:34:16 INFO - "stage_platform": "macosx64", 08:34:16 INFO - "builduid": "9d129c0789f54a68a3f841eb72384498", 08:34:16 INFO - "slavebuilddir": "test" 08:34:16 INFO - } 08:34:16 INFO - Found installer url https://queue.taskcluster.net/v1/task/Ugh6ElVORo6zhOLuW1U50w/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg. 08:34:16 INFO - Running post-action listener: _resource_record_post_action 08:34:16 INFO - [mozharness: 2016-10-07 15:34:16.068174Z] Finished read-buildbot-config step (success) 08:34:16 INFO - [mozharness: 2016-10-07 15:34:16.068290Z] Running download-and-extract step. 08:34:16 INFO - Running pre-action listener: _resource_record_pre_action 08:34:16 INFO - Running main action method: download_and_extract 08:34:16 INFO - mkdir: /builds/slave/test/build/tests 08:34:16 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')]} 08:34:16 INFO - trying https://queue.taskcluster.net/v1/task/Ugh6ElVORo6zhOLuW1U50w/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json 08:34:16 INFO - Downloading https://queue.taskcluster.net/v1/task/Ugh6ElVORo6zhOLuW1U50w/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 08:34:16 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Ugh6ElVORo6zhOLuW1U50w/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json'}, attempt #1 08:34:17 INFO - Downloaded 1199 bytes. 08:34:17 INFO - Reading from file /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 08:34:17 INFO - Using the following test package requirements: 08:34:17 INFO - {u'common': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 08:34:17 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 08:34:17 INFO - u'firefox-52.0a1.en-US.mac.cppunittest.tests.zip'], 08:34:17 INFO - u'gtest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 08:34:17 INFO - u'firefox-52.0a1.en-US.mac.gtest.tests.zip'], 08:34:17 INFO - u'jittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 08:34:17 INFO - u'jsshell-mac.zip'], 08:34:17 INFO - u'mochitest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 08:34:17 INFO - u'firefox-52.0a1.en-US.mac.mochitest.tests.zip'], 08:34:17 INFO - u'mozbase': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 08:34:17 INFO - u'reftest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 08:34:17 INFO - u'firefox-52.0a1.en-US.mac.reftest.tests.zip'], 08:34:17 INFO - u'talos': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 08:34:17 INFO - u'firefox-52.0a1.en-US.mac.talos.tests.zip'], 08:34:17 INFO - u'web-platform': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 08:34:17 INFO - u'firefox-52.0a1.en-US.mac.web-platform.tests.zip'], 08:34:17 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 08:34:17 INFO - u'firefox-52.0a1.en-US.mac.xpcshell.tests.zip']} 08:34:17 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip'] for test suite category: common 08:34:17 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Ugh6ElVORo6zhOLuW1U50w/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 08:34:17 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Ugh6ElVORo6zhOLuW1U50w/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 08:34:17 INFO - Fetch https://queue.taskcluster.net/v1/task/Ugh6ElVORo6zhOLuW1U50w/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 08:34:17 INFO - Http code: 200 08:34:17 INFO - via: 1.1 e1eb7447ab46f530f6009f1d93917c54.cloudfront.net (CloudFront) 08:34:17 INFO - x-amz-cf-id: ldLSA3Hv1OxSeDci6T_fmMdz0TPdj2m5jPKmrgRIRY62PMv9J50j9A== 08:34:17 INFO - x-amz-version-id: e6bsoMY.SDI3CD1Cw.nDUtWBiASvs3jb 08:34:18 INFO - Expected file size: 16482376 08:34:18 INFO - Obtained file size: 16482376 08:34:24 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip', u'firefox-52.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 08:34:24 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Ugh6ElVORo6zhOLuW1U50w/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 08:34:24 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Ugh6ElVORo6zhOLuW1U50w/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 08:34:24 INFO - Fetch https://queue.taskcluster.net/v1/task/Ugh6ElVORo6zhOLuW1U50w/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 08:34:25 INFO - Http code: 200 08:34:25 INFO - via: 1.1 c0740de9f16cf32ffedfd2f9806b8483.cloudfront.net (CloudFront) 08:34:25 INFO - x-amz-cf-id: YoQAfTbL_6TzkOoZM32NHaOe042g5YTIeWC8hHHY_dJoLFHDZot24Q== 08:34:25 INFO - x-amz-version-id: e6bsoMY.SDI3CD1Cw.nDUtWBiASvs3jb 08:34:25 INFO - Expected file size: 16482376 08:34:25 INFO - Obtained file size: 16482376 08:34:32 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Ugh6ElVORo6zhOLuW1U50w/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip 08:34:32 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Ugh6ElVORo6zhOLuW1U50w/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip'}, attempt #1 08:34:32 INFO - Fetch https://queue.taskcluster.net/v1/task/Ugh6ElVORo6zhOLuW1U50w/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip into memory 08:34:33 INFO - Http code: 200 08:34:33 INFO - via: 1.1 ba26a6eef2785fa2e20e601890b882b2.cloudfront.net (CloudFront) 08:34:33 INFO - x-amz-cf-id: Q4yyvqYIc0MslevPeUb5G7jiGt76HcTd3R24CuLLSuuqKyV6WZfMjw== 08:34:33 INFO - x-amz-version-id: Ubpyp1p_PbPvXvmVjREk_sDJETR12eAt 08:34:34 INFO - Expected file size: 14409006 08:34:34 INFO - Obtained file size: 14409006 08:34:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:34:34 INFO - trying https://queue.taskcluster.net/v1/task/Ugh6ElVORo6zhOLuW1U50w/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 08:34:34 INFO - Downloading https://queue.taskcluster.net/v1/task/Ugh6ElVORo6zhOLuW1U50w/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg 08:34:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Ugh6ElVORo6zhOLuW1U50w/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg'}, attempt #1 08:34:38 INFO - Downloaded 111752866 bytes. 08:34:38 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Ugh6ElVORo6zhOLuW1U50w/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 08:34:38 INFO - mkdir: /builds/slave/test/properties 08:34:38 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 08:34:38 INFO - Writing to file /builds/slave/test/properties/build_url 08:34:38 INFO - Contents: 08:34:38 INFO - build_url:https://queue.taskcluster.net/v1/task/Ugh6ElVORo6zhOLuW1U50w/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 08:34:39 INFO - Running post-action listener: _resource_record_post_action 08:34:39 INFO - Running post-action listener: set_extra_try_arguments 08:34:39 INFO - [mozharness: 2016-10-07 15:34:39.549770Z] Finished download-and-extract step (success) 08:34:39 INFO - [mozharness: 2016-10-07 15:34:39.549927Z] Running populate-webroot step. 08:34:39 INFO - Running pre-action listener: _resource_record_pre_action 08:34:39 INFO - Running main action method: populate_webroot 08:34:39 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 08:34:39 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 08:34:39 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 08:34:39 INFO - '--tppagecycles', 08:34:39 INFO - '7'], 08:34:39 INFO - 'tests': ['tsvgm']}}, 08:34:39 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 08:34:39 INFO - 'tests': ['tresize', 'tcanvasmark']}, 08:34:39 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 08:34:39 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 08:34:39 INFO - 'tests': ['dromaeo_css', 'kraken']}, 08:34:39 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 08:34:39 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:34:39 INFO - 'talos_options': ['--disable-e10s'], 08:34:39 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:34:39 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:34:39 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:34:39 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:34:39 INFO - 'talos_options': ['--disable-e10s'], 08:34:39 INFO - 'tests': ['damp', 'tps']}, 08:34:39 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:34:39 INFO - 'tests': ['damp', 'tps']}, 08:34:39 INFO - 'g3': {'talos_options': ['--disable-e10s'], 08:34:39 INFO - 'tests': ['dromaeo_dom']}, 08:34:39 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 08:34:39 INFO - 'g4': {'talos_options': ['--disable-e10s'], 08:34:39 INFO - 'tests': ['basic_compositor_video']}, 08:34:39 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 08:34:39 INFO - 'other': {'talos_options': ['--disable-e10s'], 08:34:39 INFO - 'tests': ['a11yr', 08:34:39 INFO - 'ts_paint', 08:34:39 INFO - 'tpaint', 08:34:39 INFO - 'sessionrestore', 08:34:39 INFO - 'sessionrestore_no_auto_restore', 08:34:39 INFO - 'tabpaint']}, 08:34:39 INFO - 'other-e10s': {'tests': ['a11yr', 08:34:39 INFO - 'ts_paint', 08:34:39 INFO - 'tpaint', 08:34:39 INFO - 'sessionrestore', 08:34:39 INFO - 'sessionrestore_no_auto_restore', 08:34:39 INFO - 'tabpaint']}, 08:34:39 INFO - 'other-e10s_l64': {'tests': ['a11yr', 08:34:39 INFO - 'ts_paint', 08:34:39 INFO - 'tpaint', 08:34:39 INFO - 'sessionrestore', 08:34:39 INFO - 'sessionrestore_no_auto_restore', 08:34:39 INFO - 'tabpaint']}, 08:34:39 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 08:34:39 INFO - 'ts_paint', 08:34:39 INFO - 'tpaint', 08:34:39 INFO - 'sessionrestore', 08:34:39 INFO - 'sessionrestore_no_auto_restore', 08:34:39 INFO - 'tabpaint']}, 08:34:39 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 08:34:39 INFO - 'tests': ['a11yr', 08:34:39 INFO - 'ts_paint', 08:34:39 INFO - 'tpaint', 08:34:39 INFO - 'sessionrestore', 08:34:39 INFO - 'sessionrestore_no_auto_restore', 08:34:39 INFO - 'tabpaint']}, 08:34:39 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 08:34:39 INFO - 'tests': ['a11yr', 08:34:39 INFO - 'ts_paint', 08:34:39 INFO - 'tpaint', 08:34:39 INFO - 'sessionrestore', 08:34:39 INFO - 'sessionrestore_no_auto_restore', 08:34:39 INFO - 'tabpaint']}, 08:34:39 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 08:34:39 INFO - 'tests': ['tsvgx', 08:34:39 INFO - 'tsvgr_opacity', 08:34:39 INFO - 'tart', 08:34:39 INFO - 'tscrollx', 08:34:39 INFO - 'cart']}, 08:34:39 INFO - 'svgr-e10s': {'tests': ['tsvgx', 08:34:39 INFO - 'tsvgr_opacity', 08:34:39 INFO - 'tart', 08:34:39 INFO - 'tscrollx', 08:34:39 INFO - 'cart']}, 08:34:39 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:34:39 INFO - 'talos_options': ['--disable-e10s'], 08:34:39 INFO - 'tests': ['tp5o']}, 08:34:39 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:34:39 INFO - 'tests': ['tp5o']}, 08:34:39 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:34:39 INFO - 'talos_options': ['--disable-e10s', 08:34:39 INFO - '--xperf_path', 08:34:39 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:34:39 INFO - 'tests': ['tp5n']}, 08:34:39 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:34:39 INFO - 'talos_options': ['--xperf_path', 08:34:39 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:34:39 INFO - 'tests': ['tp5n']}}, 08:34:39 INFO - 'talos.zip': {'path': '', 08:34:39 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 08:34:39 INFO - Running post-action listener: _resource_record_post_action 08:34:39 INFO - [mozharness: 2016-10-07 15:34:39.568773Z] Finished populate-webroot step (success) 08:34:39 INFO - [mozharness: 2016-10-07 15:34:39.568892Z] Running create-virtualenv step. 08:34:39 INFO - Running pre-action listener: _resource_record_pre_action 08:34:39 INFO - Running main action method: create_virtualenv 08:34:39 INFO - Creating virtualenv /builds/slave/test/build/venv 08:34:39 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 08:34:39 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 08:34:39 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 08:34:39 INFO - Using real prefix '/tools/python27' 08:34:39 INFO - New python executable in /builds/slave/test/build/venv/bin/python 08:34:40 INFO - Installing distribute.............................................................................................................................................................................................done. 08:34:43 INFO - Installing pip.................done. 08:34:43 INFO - Return code: 0 08:34:43 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 08:34:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 08:34:43 INFO - Reading from file tmpfile_stdout 08:34:43 INFO - Output received: 08:34:43 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) 08:34:43 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 08:34:43 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')]} 08:34:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:34:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:34:43 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')]} 08:34:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:34:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:34:43 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 0x10b289490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b1ff570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb649c6c420>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b09dd30>, '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 0x10aec3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b095b20>, '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.CC1Bw8tf70/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.ux1vVasRc3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/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 08:34:43 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 08:34:43 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 08:34:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CC1Bw8tf70/Render', 08:34:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:34:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:34:43 INFO - 'HOME': '/Users/cltbld', 08:34:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:34:43 INFO - 'LOGNAME': 'cltbld', 08:34:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:34:43 INFO - 'MOZ_NO_REMOTE': '1', 08:34:43 INFO - 'NO_EM_RESTART': '1', 08:34:43 INFO - 'PAGER': '/bin/cat', 08:34:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:34:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:34:43 INFO - 'PWD': '/builds/slave/test', 08:34:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:34:43 INFO - 'SHELL': '/bin/bash', 08:34:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ux1vVasRc3/Listeners', 08:34:43 INFO - 'TMPDIR': '/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/', 08:34:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:34:43 INFO - 'USER': 'cltbld', 08:34:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:34:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:34:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:34:43 INFO - 'XPC_FLAGS': '0x0', 08:34:43 INFO - 'XPC_SERVICE_NAME': '0', 08:34:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:34:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:34:43 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 08:34:43 INFO - Cleaning up... 08:34:43 INFO - Return code: 0 08:34:43 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 08:34:43 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')]} 08:34:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:34:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:34:43 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')]} 08:34:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:34:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:34:43 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 0x10b289490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b1ff570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb649c6c420>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b09dd30>, '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 0x10aec3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b095b20>, '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.CC1Bw8tf70/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.ux1vVasRc3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/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 08:34:43 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 08:34:43 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 08:34:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CC1Bw8tf70/Render', 08:34:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:34:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:34:43 INFO - 'HOME': '/Users/cltbld', 08:34:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:34:43 INFO - 'LOGNAME': 'cltbld', 08:34:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:34:43 INFO - 'MOZ_NO_REMOTE': '1', 08:34:43 INFO - 'NO_EM_RESTART': '1', 08:34:43 INFO - 'PAGER': '/bin/cat', 08:34:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:34:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:34:43 INFO - 'PWD': '/builds/slave/test', 08:34:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:34:43 INFO - 'SHELL': '/bin/bash', 08:34:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ux1vVasRc3/Listeners', 08:34:43 INFO - 'TMPDIR': '/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/', 08:34:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:34:43 INFO - 'USER': 'cltbld', 08:34:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:34:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:34:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:34:43 INFO - 'XPC_FLAGS': '0x0', 08:34:43 INFO - 'XPC_SERVICE_NAME': '0', 08:34:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:34:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:34:44 INFO - Downloading/unpacking psutil>=3.1.1 08:34:44 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 08:34:44 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 08:34:44 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 08:34:44 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 08:34:44 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 08:34:44 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 08:34:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 08:34:47 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:34:47 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:34:47 INFO - Installing collected packages: psutil 08:34:47 INFO - Running setup.py install for psutil 08:34:47 INFO - building 'psutil._psutil_osx' extension 08:34:47 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 08:34:48 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 08:34:48 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 08:34:48 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 08:34:48 INFO - building 'psutil._psutil_posix' extension 08:34:48 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 08:34:48 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 08:34:48 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 08:34:48 INFO - ^ 08:34:48 INFO - 1 warning generated. 08:34:48 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 08:34:48 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:34:48 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:34:48 INFO - Successfully installed psutil 08:34:48 INFO - Cleaning up... 08:34:48 INFO - Return code: 0 08:34:48 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 08:34:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:34:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:34:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:34:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:34:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:34:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:34:48 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10b289490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b1ff570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb649c6c420>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b09dd30>, '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 0x10aec3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b095b20>, '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.CC1Bw8tf70/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.ux1vVasRc3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/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 08:34:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 08:34:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 08:34:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CC1Bw8tf70/Render', 08:34:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:34:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:34:48 INFO - 'HOME': '/Users/cltbld', 08:34:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:34:48 INFO - 'LOGNAME': 'cltbld', 08:34:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:34:48 INFO - 'MOZ_NO_REMOTE': '1', 08:34:48 INFO - 'NO_EM_RESTART': '1', 08:34:48 INFO - 'PAGER': '/bin/cat', 08:34:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:34:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:34:48 INFO - 'PWD': '/builds/slave/test', 08:34:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:34:48 INFO - 'SHELL': '/bin/bash', 08:34:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ux1vVasRc3/Listeners', 08:34:48 INFO - 'TMPDIR': '/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/', 08:34:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:34:48 INFO - 'USER': 'cltbld', 08:34:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:34:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:34:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:34:48 INFO - 'XPC_FLAGS': '0x0', 08:34:48 INFO - 'XPC_SERVICE_NAME': '0', 08:34:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:34:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:34:49 INFO - Downloading/unpacking mozsystemmonitor==0.3 08:34:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:34:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:34:49 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:34:49 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:34:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:34:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:34:51 INFO - Downloading mozsystemmonitor-0.3.tar.gz 08:34:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 08:34:51 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 08:34:51 INFO - Installing collected packages: mozsystemmonitor 08:34:51 INFO - Running setup.py install for mozsystemmonitor 08:34:51 INFO - Successfully installed mozsystemmonitor 08:34:51 INFO - Cleaning up... 08:34:51 INFO - Return code: 0 08:34:51 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 08:34:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:34:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:34:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:34:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:34:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:34:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:34:51 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 0x10b289490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b1ff570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb649c6c420>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b09dd30>, '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 0x10aec3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b095b20>, '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.CC1Bw8tf70/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.ux1vVasRc3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/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 08:34:51 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 08:34:51 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 08:34:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CC1Bw8tf70/Render', 08:34:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:34:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:34:51 INFO - 'HOME': '/Users/cltbld', 08:34:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:34:51 INFO - 'LOGNAME': 'cltbld', 08:34:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:34:51 INFO - 'MOZ_NO_REMOTE': '1', 08:34:51 INFO - 'NO_EM_RESTART': '1', 08:34:51 INFO - 'PAGER': '/bin/cat', 08:34:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:34:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:34:51 INFO - 'PWD': '/builds/slave/test', 08:34:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:34:51 INFO - 'SHELL': '/bin/bash', 08:34:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ux1vVasRc3/Listeners', 08:34:51 INFO - 'TMPDIR': '/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/', 08:34:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:34:51 INFO - 'USER': 'cltbld', 08:34:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:34:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:34:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:34:51 INFO - 'XPC_FLAGS': '0x0', 08:34:51 INFO - 'XPC_SERVICE_NAME': '0', 08:34:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:34:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:34:52 INFO - Downloading/unpacking jsonschema==2.5.1 08:34:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:34:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:34:52 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:34:52 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:34:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:34:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:34:54 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 08:34:54 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 08:34: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 08:34: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 08:34: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 08:34: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 08:34: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 08:34: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 08:34:54 INFO - Downloading functools32-3.2.3-2.tar.gz 08:34:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 08:34:54 INFO - warning: no files found matching '*.txt' 08:34:54 INFO - no previously-included directories found matching 'build' 08:34:54 INFO - no previously-included directories found matching 'dist' 08:34:54 INFO - no previously-included directories found matching '.git*' 08:34:54 INFO - Installing collected packages: jsonschema, functools32 08:34:54 INFO - Running setup.py install for functools32 08:34:55 INFO - warning: no files found matching '*.txt' 08:34:55 INFO - no previously-included directories found matching 'build' 08:34:55 INFO - no previously-included directories found matching 'dist' 08:34:55 INFO - no previously-included directories found matching '.git*' 08:34:55 INFO - Successfully installed jsonschema functools32 08:34:55 INFO - Cleaning up... 08:34:55 INFO - Return code: 0 08:34:55 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 08:34:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:34:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:34:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:34:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:34:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:34:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:34:55 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 0x10b289490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b1ff570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb649c6c420>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b09dd30>, '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 0x10aec3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b095b20>, '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.CC1Bw8tf70/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.ux1vVasRc3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/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 08:34:55 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 08:34:55 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 08:34:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CC1Bw8tf70/Render', 08:34:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:34:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:34:55 INFO - 'HOME': '/Users/cltbld', 08:34:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:34:55 INFO - 'LOGNAME': 'cltbld', 08:34:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:34:55 INFO - 'MOZ_NO_REMOTE': '1', 08:34:55 INFO - 'NO_EM_RESTART': '1', 08:34:55 INFO - 'PAGER': '/bin/cat', 08:34:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:34:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:34:55 INFO - 'PWD': '/builds/slave/test', 08:34:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:34:55 INFO - 'SHELL': '/bin/bash', 08:34:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ux1vVasRc3/Listeners', 08:34:55 INFO - 'TMPDIR': '/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/', 08:34:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:34:55 INFO - 'USER': 'cltbld', 08:34:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:34:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:34:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:34:55 INFO - 'XPC_FLAGS': '0x0', 08:34:55 INFO - 'XPC_SERVICE_NAME': '0', 08:34:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:34:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:34:55 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 08:34:55 INFO - Cleaning up... 08:34:55 INFO - Return code: 0 08:34:55 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 08:34:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:34:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:34:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:34:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:34:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:34:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:34:55 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 0x10b289490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b1ff570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb649c6c420>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b09dd30>, '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 0x10aec3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b095b20>, '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.CC1Bw8tf70/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.ux1vVasRc3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/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 08:34:55 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 08:34:55 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 08:34:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CC1Bw8tf70/Render', 08:34:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:34:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:34:55 INFO - 'HOME': '/Users/cltbld', 08:34:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:34:55 INFO - 'LOGNAME': 'cltbld', 08:34:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:34:55 INFO - 'MOZ_NO_REMOTE': '1', 08:34:55 INFO - 'NO_EM_RESTART': '1', 08:34:55 INFO - 'PAGER': '/bin/cat', 08:34:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:34:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:34:55 INFO - 'PWD': '/builds/slave/test', 08:34:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:34:55 INFO - 'SHELL': '/bin/bash', 08:34:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ux1vVasRc3/Listeners', 08:34:55 INFO - 'TMPDIR': '/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/', 08:34:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:34:55 INFO - 'USER': 'cltbld', 08:34:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:34:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:34:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:34:55 INFO - 'XPC_FLAGS': '0x0', 08:34:55 INFO - 'XPC_SERVICE_NAME': '0', 08:34:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:34:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:34:55 INFO - Downloading/unpacking blobuploader==1.2.4 08:34:55 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 08:34:55 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 08:34:55 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 08:34:55 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 08:34:55 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 08:34:55 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 08:34:58 INFO - Downloading blobuploader-1.2.4.tar.gz 08:34:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 08:34:58 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 08:34:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:34:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:34:58 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:34:58 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:34:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:34:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:34:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 08:34:58 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 08:34:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:34:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:34:58 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:34:58 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:34:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:34:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:34:59 INFO - Downloading docopt-0.6.1.tar.gz 08:34:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 08:34:59 INFO - Installing collected packages: blobuploader, requests, docopt 08:34:59 INFO - Running setup.py install for blobuploader 08:34:59 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 08:34:59 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 08:34:59 INFO - Running setup.py install for requests 08:35:00 INFO - Running setup.py install for docopt 08:35:00 INFO - Successfully installed blobuploader requests docopt 08:35:00 INFO - Cleaning up... 08:35:00 INFO - Return code: 0 08:35:00 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:35:00 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')]} 08:35:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:35:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:35:00 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')]} 08:35:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:35:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:35:00 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 0x10b289490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b1ff570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb649c6c420>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b09dd30>, '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 0x10aec3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b095b20>, '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.CC1Bw8tf70/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.ux1vVasRc3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/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 08:35:00 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 08:35:00 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 08:35:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CC1Bw8tf70/Render', 08:35:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:35:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:35:00 INFO - 'HOME': '/Users/cltbld', 08:35:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:35:00 INFO - 'LOGNAME': 'cltbld', 08:35:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:35:00 INFO - 'MOZ_NO_REMOTE': '1', 08:35:00 INFO - 'NO_EM_RESTART': '1', 08:35:00 INFO - 'PAGER': '/bin/cat', 08:35:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:35:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:35:00 INFO - 'PWD': '/builds/slave/test', 08:35:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:35:00 INFO - 'SHELL': '/bin/bash', 08:35:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ux1vVasRc3/Listeners', 08:35:00 INFO - 'TMPDIR': '/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/', 08:35:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:35:00 INFO - 'USER': 'cltbld', 08:35:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:35:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:35:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:35:00 INFO - 'XPC_FLAGS': '0x0', 08:35:00 INFO - 'XPC_SERVICE_NAME': '0', 08:35:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:35:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:35:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:35:00 INFO - Running setup.py (path:/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/pip-S65md4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:35:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:35:00 INFO - Running setup.py (path:/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/pip-amcmKQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:35:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:35:00 INFO - Running setup.py (path:/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/pip-z8YrmD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:35:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:35:00 INFO - Running setup.py (path:/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/pip-J8Otmc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:35:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:35:00 INFO - Running setup.py (path:/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/pip-j66jw3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:35:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:35:01 INFO - Running setup.py (path:/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/pip-RWYGh4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:35:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:35:01 INFO - Running setup.py (path:/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/pip-iqWScv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:35:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:35:01 INFO - Running setup.py (path:/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/pip-4D3fa2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:35:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:35:01 INFO - Running setup.py (path:/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/pip-KofWcy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:35:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:35:01 INFO - Running setup.py (path:/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/pip-MWTeRy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:35:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:35:01 INFO - Running setup.py (path:/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/pip-GMeSV2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:35:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:35:01 INFO - Running setup.py (path:/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/pip-W07HN9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:35:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:35:01 INFO - Running setup.py (path:/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/pip-Frc4xn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:35:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:35:01 INFO - Running setup.py (path:/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/pip-N2Xb5B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:35:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:35:02 INFO - Running setup.py (path:/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/pip-AHH3TT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:35:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:35:02 INFO - Running setup.py (path:/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/pip-k8LGlV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:35:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:35:02 INFO - Running setup.py (path:/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/pip-4Po4pZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:35:02 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 08:35:02 INFO - Running setup.py install for manifestparser 08:35:02 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 08:35:02 INFO - Running setup.py install for mozcrash 08:35:02 INFO - Running setup.py install for mozdebug 08:35:02 INFO - Running setup.py install for mozdevice 08:35:02 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 08:35:02 INFO - Installing dm script to /builds/slave/test/build/venv/bin 08:35:02 INFO - Running setup.py install for mozfile 08:35:03 INFO - Running setup.py install for mozhttpd 08:35:03 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 08:35:03 INFO - Running setup.py install for mozinfo 08:35:03 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 08:35:03 INFO - Running setup.py install for mozInstall 08:35:03 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 08:35:03 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 08:35:03 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 08:35:03 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 08:35:03 INFO - Running setup.py install for mozleak 08:35:03 INFO - Running setup.py install for mozlog 08:35:03 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 08:35:03 INFO - Running setup.py install for moznetwork 08:35:03 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 08:35:03 INFO - Running setup.py install for mozprocess 08:35:04 INFO - Running setup.py install for mozprofile 08:35:04 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 08:35:04 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 08:35:04 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 08:35:04 INFO - Running setup.py install for mozrunner 08:35:04 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 08:35:04 INFO - Running setup.py install for mozscreenshot 08:35:04 INFO - Running setup.py install for moztest 08:35:04 INFO - Running setup.py install for mozversion 08:35:04 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 08:35:04 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 08:35:04 INFO - Cleaning up... 08:35:04 INFO - Return code: 0 08:35:04 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:35:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:35:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:35:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:35:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:35:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:35:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:35:04 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 0x10b289490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b1ff570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb649c6c420>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b09dd30>, '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 0x10aec3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b095b20>, '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.CC1Bw8tf70/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.ux1vVasRc3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/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 08:35:04 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 08:35:04 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 08:35:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CC1Bw8tf70/Render', 08:35:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:35:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:35:04 INFO - 'HOME': '/Users/cltbld', 08:35:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:35:04 INFO - 'LOGNAME': 'cltbld', 08:35:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:35:04 INFO - 'MOZ_NO_REMOTE': '1', 08:35:04 INFO - 'NO_EM_RESTART': '1', 08:35:04 INFO - 'PAGER': '/bin/cat', 08:35:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:35:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:35:04 INFO - 'PWD': '/builds/slave/test', 08:35:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:35:04 INFO - 'SHELL': '/bin/bash', 08:35:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ux1vVasRc3/Listeners', 08:35:04 INFO - 'TMPDIR': '/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/', 08:35:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:35:04 INFO - 'USER': 'cltbld', 08:35:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:35:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:35:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:35:04 INFO - 'XPC_FLAGS': '0x0', 08:35:04 INFO - 'XPC_SERVICE_NAME': '0', 08:35:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:35:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:35:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:35:05 INFO - Running setup.py (path:/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/pip-PrrDPK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:35:05 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)) 08:35:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:35:05 INFO - Running setup.py (path:/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/pip-eOntN0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:35:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 08:35:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:35:05 INFO - Running setup.py (path:/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/pip-3DlSZf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:35:05 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)) 08:35:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:35:05 INFO - Running setup.py (path:/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/pip-fczE4i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:35:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 08:35:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:35:05 INFO - Running setup.py (path:/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/pip-J2oRp0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:35:05 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)) 08:35:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:35:05 INFO - Running setup.py (path:/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/pip-SIrWzb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:35:05 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)) 08:35:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:35:05 INFO - Running setup.py (path:/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/pip-wne1oh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:35:05 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)) 08:35:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:35:05 INFO - Running setup.py (path:/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/pip-pT7xlw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:35:06 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)) 08:35:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:35:06 INFO - Running setup.py (path:/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/pip-_trVva-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:35:06 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)) 08:35:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:35:06 INFO - Running setup.py (path:/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/pip-yjU1_Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:35:06 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)) 08:35:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:35:06 INFO - Running setup.py (path:/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/pip-8Tdc4P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:35:06 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)) 08:35:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:35:06 INFO - Running setup.py (path:/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/pip-maN7c7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:35:06 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)) 08:35:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:35:06 INFO - Running setup.py (path:/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/pip-c0TXiD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:35:06 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)) 08:35:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:35:06 INFO - Running setup.py (path:/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/pip-SactZD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:35:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 08:35:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:35:06 INFO - Running setup.py (path:/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/pip-Ri2hbJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:35:06 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)) 08:35:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:35:06 INFO - Running setup.py (path:/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/pip-krD8LJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:35:06 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)) 08:35:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:35:06 INFO - Running setup.py (path:/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/pip-8rra_G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:35:06 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)) 08:35:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 08:35:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 08:35:06 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)) 08:35:06 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 08:35:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 08:35:06 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:35:06 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 08:35:06 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 08:35:06 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 08:35:06 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 08:35:06 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 08:35:06 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 08:35:09 INFO - Downloading blessings-1.6.tar.gz 08:35:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 08:35:09 INFO - Installing collected packages: blessings 08:35:09 INFO - Running setup.py install for blessings 08:35:09 INFO - Successfully installed blessings 08:35:09 INFO - Cleaning up... 08:35:09 INFO - Return code: 0 08:35:09 INFO - Done creating virtualenv /builds/slave/test/build/venv. 08:35:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:35:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:35:10 INFO - Reading from file tmpfile_stdout 08:35:10 INFO - Current package versions: 08:35:10 INFO - blessings == 1.6 08:35:10 INFO - blobuploader == 1.2.4 08:35:10 INFO - docopt == 0.6.1 08:35:10 INFO - functools32 == 3.2.3-2 08:35:10 INFO - jsonschema == 2.5.1 08:35:10 INFO - manifestparser == 1.1 08:35:10 INFO - mozInstall == 1.12 08:35:10 INFO - mozcrash == 0.17 08:35:10 INFO - mozdebug == 0.1 08:35:10 INFO - mozdevice == 0.48 08:35:10 INFO - mozfile == 1.2 08:35:10 INFO - mozhttpd == 0.7 08:35:10 INFO - mozinfo == 0.9 08:35:10 INFO - mozleak == 0.1 08:35:10 INFO - mozlog == 3.4 08:35:10 INFO - moznetwork == 0.27 08:35:10 INFO - mozprocess == 0.23 08:35:10 INFO - mozprofile == 0.28 08:35:10 INFO - mozrunner == 6.12 08:35:10 INFO - mozscreenshot == 0.1 08:35:10 INFO - mozsystemmonitor == 0.3 08:35:10 INFO - moztest == 0.7 08:35:10 INFO - mozversion == 1.4 08:35:10 INFO - psutil == 3.1.1 08:35:10 INFO - requests == 1.2.3 08:35:10 INFO - wsgiref == 0.1.2 08:35:10 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:35:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:35:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:35:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:35:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:35:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:35:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:35:10 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 0x10b289490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b1ff570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb649c6c420>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b09dd30>, '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 0x10aec3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b095b20>, '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.CC1Bw8tf70/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.ux1vVasRc3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/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 08:35:10 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 08:35:10 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 08:35:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CC1Bw8tf70/Render', 08:35:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:35:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:35:10 INFO - 'HOME': '/Users/cltbld', 08:35:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:35:10 INFO - 'LOGNAME': 'cltbld', 08:35:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:35:10 INFO - 'MOZ_NO_REMOTE': '1', 08:35:10 INFO - 'NO_EM_RESTART': '1', 08:35:10 INFO - 'PAGER': '/bin/cat', 08:35:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:35:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:35:10 INFO - 'PWD': '/builds/slave/test', 08:35:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:35:10 INFO - 'SHELL': '/bin/bash', 08:35:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ux1vVasRc3/Listeners', 08:35:10 INFO - 'TMPDIR': '/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/', 08:35:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:35:10 INFO - 'USER': 'cltbld', 08:35:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:35:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:35:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:35:10 INFO - 'XPC_FLAGS': '0x0', 08:35:10 INFO - 'XPC_SERVICE_NAME': '0', 08:35:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:35:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:35:11 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)) 08:35:11 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)) 08:35:11 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)) 08:35:11 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)) 08:35:11 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)) 08:35:11 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)) 08:35:11 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)) 08:35:11 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)) 08:35:11 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)) 08:35:11 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)) 08:35:11 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)) 08:35:11 INFO - Cleaning up... 08:35:11 INFO - Return code: 0 08:35:11 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 08:35:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:35:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:35:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:35:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:35:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:35:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:35:11 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 0x10b289490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b1ff570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb649c6c420>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b09dd30>, '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 0x10aec3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b095b20>, '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.CC1Bw8tf70/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.ux1vVasRc3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/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 08:35:11 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 08:35:11 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 08:35:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CC1Bw8tf70/Render', 08:35:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:35:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:35:11 INFO - 'HOME': '/Users/cltbld', 08:35:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:35:11 INFO - 'LOGNAME': 'cltbld', 08:35:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:35:11 INFO - 'MOZ_NO_REMOTE': '1', 08:35:11 INFO - 'NO_EM_RESTART': '1', 08:35:11 INFO - 'PAGER': '/bin/cat', 08:35:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:35:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:35:11 INFO - 'PWD': '/builds/slave/test', 08:35:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:35:11 INFO - 'SHELL': '/bin/bash', 08:35:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ux1vVasRc3/Listeners', 08:35:11 INFO - 'TMPDIR': '/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/', 08:35:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:35:11 INFO - 'USER': 'cltbld', 08:35:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:35:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:35:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:35:11 INFO - 'XPC_FLAGS': '0x0', 08:35:11 INFO - 'XPC_SERVICE_NAME': '0', 08:35:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:35:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:35:11 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 08:35:11 INFO - Cleaning up... 08:35:11 INFO - Return code: 0 08:35:11 INFO - Running post-action listener: _resource_record_post_action 08:35:11 INFO - Running post-action listener: _start_resource_monitoring 08:35:11 INFO - Starting resource monitoring. 08:35:11 INFO - [mozharness: 2016-10-07 15:35:11.828566Z] Finished create-virtualenv step (success) 08:35:11 INFO - [mozharness: 2016-10-07 15:35:11.829538Z] Running install step. 08:35:11 INFO - Running pre-action listener: _resource_record_pre_action 08:35:11 INFO - Running main action method: install 08:35:11 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:35:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:35:12 INFO - Reading from file tmpfile_stdout 08:35:12 INFO - Detecting whether we're running mozinstall >=1.0... 08:35:12 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 08:35:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 08:35:12 INFO - Reading from file tmpfile_stdout 08:35:12 INFO - Output received: 08:35:12 INFO - Usage: mozinstall [options] installer 08:35:12 INFO - Options: 08:35:12 INFO - -h, --help show this help message and exit 08:35:12 INFO - -d DEST, --destination=DEST 08:35:12 INFO - Directory to install application into. [default: 08:35:12 INFO - "/builds/slave/test"] 08:35:12 INFO - --app=APP Application being installed. [default: firefox] 08:35:12 INFO - mkdir: /builds/slave/test/build/application 08:35:12 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 08:35:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 08:35:46 INFO - Reading from file tmpfile_stdout 08:35:46 INFO - Output received: 08:35:46 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 08:35:46 INFO - Running post-action listener: _resource_record_post_action 08:35:46 INFO - [mozharness: 2016-10-07 15:35:46.635665Z] Finished install step (success) 08:35:46 INFO - [mozharness: 2016-10-07 15:35:46.635798Z] Running run-tests step. 08:35:46 INFO - Running pre-action listener: _resource_record_pre_action 08:35:46 INFO - Running main action method: run_tests 08:35:46 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:35:46 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:35:46 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:35:46 INFO - 2016-10-07 08:35:46.650 screenresolution[1639:7499] starting screenresolution argv=screenresolution get 08:35:46 INFO - 2016-10-07 08:35:46.652 screenresolution[1639:7499] Display 0: 1600x1200x32@60 08:35:46 INFO - 2016-10-07 08:35:46.659 screenresolution[1640:7501] starting screenresolution argv=screenresolution list 08:35:46 INFO - Available Modes on Display 0 08:35:46 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:35:46 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:35:46 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:35:46 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:35:46 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:35:46 INFO - Intel Iris: 08:35:46 INFO - Chipset Model: Intel Iris 08:35:46 INFO - Type: GPU 08:35:46 INFO - Bus: Built-In 08:35:46 INFO - VRAM (Dynamic, Max): 1536 MB 08:35:46 INFO - Vendor: Intel (0x8086) 08:35:46 INFO - Device ID: 0x0a2e 08:35:46 INFO - Revision ID: 0x0009 08:35:46 INFO - Displays: 08:35:46 INFO - Display: 08:35:46 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:35:46 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:35:46 INFO - Display Serial Number: 1600x1200 60 08:35:46 INFO - Main Display: Yes 08:35:46 INFO - Mirror: Off 08:35:46 INFO - Online: Yes 08:35:46 INFO - Rotation: Supported 08:35:46 INFO - Return code: 0 08:35:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 08:35:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 08:35:46 INFO - Python 2.7.3 08:35:46 INFO - Return code: 0 08:35:46 INFO - grabbing minidump binary from tooltool 08:35:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:35:46 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 0x10b09dd30>, '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 0x10aec3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b095b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 08:35:46 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 08:35:46 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 08:35:46 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 08:35:46 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 08:35:47 INFO - Return code: 0 08:35:47 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 08:35:47 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 08:35:47 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 08:35:47 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 08:35:47 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 08:35:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Ugh6ElVORo6zhOLuW1U50w/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0166', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 08:35:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Ugh6ElVORo6zhOLuW1U50w/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0166 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 08:35:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CC1Bw8tf70/Render', 08:35:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:35:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:35:47 INFO - 'HOME': '/Users/cltbld', 08:35:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:35:47 INFO - 'LOGNAME': 'cltbld', 08:35:47 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 08:35:47 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 08:35:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:35:47 INFO - 'MOZ_NO_REMOTE': '1', 08:35:47 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 08:35:47 INFO - 'NO_EM_RESTART': '1', 08:35:47 INFO - 'PAGER': '/bin/cat', 08:35:47 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:35:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:35:47 INFO - 'PWD': '/builds/slave/test', 08:35:47 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 08:35:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:35:47 INFO - 'SHELL': '/bin/bash', 08:35:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ux1vVasRc3/Listeners', 08:35:47 INFO - 'TMPDIR': '/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/', 08:35:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:35:47 INFO - 'USER': 'cltbld', 08:35:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:35:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:35:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:35:47 INFO - 'XPC_FLAGS': '0x0', 08:35:47 INFO - 'XPC_SERVICE_NAME': '0', 08:35:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:35:47 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Ugh6ElVORo6zhOLuW1U50w/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0166', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 08:35:47 INFO - mozversion application_buildid: 20161007063900 08:35:47 INFO - mozversion application_changeset: 9c11fedf43675388a547f09cc4a64e78739f5220 08:35:47 INFO - mozversion application_display_name: Nightly 08:35:47 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 08:35:47 INFO - mozversion application_name: Firefox 08:35:47 INFO - mozversion application_remotingname: firefox 08:35:47 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 08:35:47 INFO - mozversion application_vendor: Mozilla 08:35:47 INFO - mozversion application_version: 52.0a1 08:35:47 INFO - mozversion platform_buildid: 20161007063900 08:35:47 INFO - mozversion platform_changeset: 9c11fedf43675388a547f09cc4a64e78739f5220 08:35:47 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 08:35:47 INFO - mozversion platform_version: 52.0a1 08:35:47 INFO - using testdate: 1475854547 08:35:47 INFO - actual date: 1475854547 08:35:47 INFO - starting webserver on 'localhost:49272' 08:35:47 INFO - SUITE-START | Running 2 tests 08:35:47 INFO - TEST-START | dromaeo_css 08:35:47 INFO - operating with platform_type : mac_ 08:35:47 INFO - Initialising browser for dromaeo_css test... 08:35:47 INFO - TEST-INFO | started process 1650 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/tmpSfhOwn/profile http://localhost:49272/getInfo.html) 08:35:49 INFO - PROCESS | 1650 | __metrics Screen width/height:1600/1200 08:35:49 INFO - PROCESS | 1650 | colorDepth:24 08:35:49 INFO - PROCESS | 1650 | Browser inner width/height: 1024/689 08:35:49 INFO - PROCESS | 1650 | __metrics 08:35:49 INFO - PROCESS | 1650 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 08:35:49 INFO - PROCESS | 1650 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 08:35:50 INFO - TEST-INFO | 1650: exit 0 08:35:50 INFO - Browser initialized. 08:35:50 INFO - Running cycle 1/1 for dromaeo_css test... 08:35:50 INFO - TEST-INFO | started process 1652 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/tmpSfhOwn/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:36:02 INFO - PROCESS | 1652 | RSS: Main: 234999808 08:36:02 INFO - PROCESS | 1652 | 08:36:54 INFO - PROCESS | 1652 | 1475854614273 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] 08:39:07 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49272/tests/dromaeo/cssquery-ext.html) 08:39:08 INFO - PROCESS | 1652 | RSS: Main: 358940672 08:39:08 INFO - PROCESS | 1652 | 08:42:11 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-ext.html (next: http://localhost:49272/tests/dromaeo/cssquery-jquery.html) 08:42:12 INFO - PROCESS | 1652 | RSS: Main: 372760576 08:42:12 INFO - PROCESS | 1652 | 08:45:14 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49272/tests/dromaeo/cssquery-mootools.html) 08:45:15 INFO - PROCESS | 1652 | RSS: Main: 343343104 08:45:15 INFO - PROCESS | 1652 | 08:48:17 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49272/tests/dromaeo/cssquery-prototype.html) 08:48:18 INFO - PROCESS | 1652 | RSS: Main: 344264704 08:48:18 INFO - PROCESS | 1652 | 08:51:21 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49272/tests/dromaeo/cssquery-yui.html) 08:51:22 INFO - PROCESS | 1652 | RSS: Main: 326406144 08:51:22 INFO - PROCESS | 1652 | 08:54:25 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-yui.html (next: http://localhost:49272/tests/dromaeo/cssquery-dojo.html) 08:54:25 INFO - PROCESS | 1652 | RSS: Main: 419053568 08:54:25 INFO - PROCESS | 1652 | 08:54:25 INFO - PROCESS | 1652 | __start_tp_report 08:54:25 INFO - PROCESS | 1652 | _x_x_mozilla_page_load 08:54:25 INFO - PROCESS | 1652 | _x_x_mozilla_page_load_details 08:54:25 INFO - PROCESS | 1652 | |i|pagename|runs| 08:54:25 INFO - PROCESS | 1652 | |0;dojo.html;2821.844798536081;3320.7879124119695;3331.1339051846526;3374.7975121492696;3395.575553055868;5585.832425027245;5668.036433806253;5689.089745640698;5699.914501282474;5701.3728489866135;5642.520385767215;5678.091505359143;5679.119736440858;5707.258056452666;5714.428557144294;5146.433892271866;5186.403563590179;5193.714345710984;5227.921581176285;5241.86895327616;5291.761870715808;5294.708791016512;5313.468653134694;5316.441773613773;5328.387235467912;5165.793368265265;5224.947750522503;5247.580193584523;5294.788208471657;5306.495882891119;3965.524137103537;5268.841934741964;5282.154855223146;5293.100172970604;5345.705986170759;5100.362454693163;5119.846404607868;5176.663516871391;5210.76551555181;5211.400688920785;5400.324959380077;5487.396386397493;5496.642718223303;5516.200150978086;5524.66851988882;5949.137375080589;5951.047832346847;5955.7915472958675;5956.851078723023;5959.433853783927;6011.0382338825575;6070.817875463743;6078.149059131725;6147.077938309245;6234.2830574483605;5743.2246646703;5803.042497987869;5803.448672376117;5810.099434587646;5862.14998825168;6240.2651863093715;6301.40136687014;6302.590331628521;6302.779402720883;6310.085037669604;5098.668586541863;6035.698215089228;6143.109249158847;6161.568690191643;6165.815025549241;5440.455954404528;6136.004954352043;6141.341837703795;6170.629762214281;6248.062790581449;6154.323024467305;6187.783427580103;6198;6199.814005579839;6211.3478084801345;6152.600080994721;6230.563860529719;6236.438720515175;6240.906386404116;6246.781362652377;4817.205161148441;4927.334809800631;4939.629527785431;4945.455999840015;4989.0520801048415;4053.310937140693;4338.828516300644;4451.910961780746;4480.708753930984;4493.370928070073;5706.514946229534;5747.597668163272;5768.932747441737;5770.105633626795;5770.365259821417;6115.727150604525;6130.887362651087;6179.536534759821;6218.253809542884;6257.937420625735;5699.116636921284;5732.140178973104;5733.483986441241;5773.797917072882;5779.624324418899;4588.9541104588525;5414.242006119146;5427.864303392446;5437.945620543823;5443.510084092451;6164.229471316085;6229.314775374614;6372.426481616677;6384.170057892537;6395.42441180296;5202;6141.547378150984;6231.781887633911;6269.122322874892;6287.591306565058;6317.5893566918;6327.2407311121115;6353.110564521063;6380.330065343072;6391.041343798282;6101.816945491642;6221.5333849960525;6249.562530622813;6251.093591429127;6255.343188965093;6170.475509581735;6186.443220110212;6189.690515474298;6238.376162383725;6301.779437719842;6129;6138.232720909887;6145.8156255312415;6148.754049837957;6164.568480206342;5868.23712917318;6063.909041364295;6112.266528016667;6136.386361363828;6146.692665366624;5835.183074369506;5893.5874488787085;5922.496587790086;5932.139839723302;5944.4352786485215;6098.4322286166835;6123.4351415035735;6129.847850912965;6138.480361557057;6139.338817383189;5418.322709661293;5840.737166827418;5871.236739224044;6000.759969601341;6067.969660151671 08:54:25 INFO - PROCESS | 1652 | |1;ext.html;461.3955718009408;470.44722451119924;501.6513523101446;502.76872638586224;535.9682610973874;25132.49735005299;31695.366092678134;40800;41977;41987.58012419875;41374;43118.353224701656;43171;43184.78407607965;43218.351724724074;9741.025897410256;9919.900800992005;9925.553350099226;9927.702168934944;9942.801143977116;9590.472524011177;9703.95148024259;9718.173955213815;9720.805583888317;9722.222222222204;9709.12617864392;9752.366096203725;9759.707208783713;9763.414195148314;9784.364016338915;9676.032396760304;9728.17310528606;9729.221662267002;9731.318807683465;9768.90231097687;9685.128338449573;9743.512824358753;9761.902380976171;9764.316497845153;9769.413835169842;40456.59543404558;40729.59270407288;40783.184336313105;40806;40813.795931020155;3341.365140623274;3502.684758371733;3666.7799932004164;3771.5662698789442;4002.679785617144;29898.551521727193;30177.245568860955;31353.68646313553;31998.040058798273;32213.51679724806;3207.2142325130494;3267.1668724475344;3274.8526316315824;3339.215284408162;3375.3080618473155;493013;499984.50007750094;503916;508358;517559;63970.36029639737;65224.34775652183;65302;65361.346386536476;65479;69046;69361.65319173371;69378.61242775116;69431.65284173547;69441.65279173572;16576.171191440622;16785.748213776802;16817.663646726996;16820.831791682172;16869.819112661997;12754.61736147917;12851.293178875252;12868.099233053783;12871.099023068482;12892.48430062787;16699.409626930268;17341.28455002286;19581.51046223827;20007.799922000886;20019.39941801748;6747.359000894907;8252.876206856901;8423.536705481258;8487.702930397407;8621.78445538854;703.1079553342953;711.8683043636944;713.8679344321317;765.326512668859;955.4028732042474;29199.124026279245;30189;30532.847335763625;30815.69184308173;31044.689553104632;1003.94478303694;1073.8281874899978;1079.2445288298222;1169.906407487399;1193.12901581844;15321;15773.369065237492;15946.611519883369;16105.275262612977;16185.028898266142;694784;698145;698535;699300.0769915305;702878;57427.13859292114;57960.13059804106;58008;58011;58011.54971125671;1227.5826219085554;1267.8034904589804;1269.898408127329;1346.1922846292146;1376.0711519724146;29636.851815741647;31573.210669733442;31656;31728.524072139397;32317.51523727193;50919.490805091475;50952;50974.72563185802;51163;51239.48760512496;533276.3336183294;535960;536609;537302.2102458267;539719;26946.865265673547;27285.863570682814;27540;27829.72170278352;42681.57318426776;37475.2504949894;47642.5235747652;47718.52281477141;48122.75938620425;48420.5157948416;38777.806110969264;39291;40934.1813163741;40938.795306023276;41041.58958410378;38600;38622.034449139;39571.80214098911;40006.59993400029;40123;38783;39191.216175675756;39425;39712.60287397205;39775.403368950065;32764.672353277114;34345.82827085849;34800.82599587087;35265;35409;25066.60801495607;25572.872135639947;25768.484630307663;25834.35414114662;26394.472110557297 08:54:25 INFO - PROCESS | 1652 | |2;jquery.html;350.2731831449739;373.8504598160737;375.3974870333115;381.1234161428714;401.6224748736188;14767.556973290792;14980.775288370683;16084.919575402137;20065.89967050163;20707.689384659243;15853.286602102904;18245.36141235057;19267.421977340666;20167.899160504177;20383.796162038376;5927.673977931212;6180;6215.254169499666;6221.7822376216845;6238.189035425388;4587.518310577391;6114.81655550332;6163.106349579309;6179.134921111048;6202.3797620238065;5937.52499800015;6056.849198652242;6059.757609695607;6127.325994140663;6151.4463698267145;6019.247594050744;6033;6133.846653833645;6135.447809697104;6138.294096178952;5146.279520867081;5325.946740532584;5332.600054995891;5434.565234781208;5502.147167189092;13222.471101155943;13489.86510134896;13497.190168589916;15434.38262469511;24956.126535571348;1742.8779985401027;2246.921357752487;2327.7323107842644;2451.6690246816806;2619.4237267801245;9729.70810875675;9833.606655733762;9873.901260987443;10372.273940824145;10462.790744185073;1792;1876.6434377468238;1880.0787614069145;1888.7827899791562;1939.2533874458295;116224;118732;123355.3832230833;123656;127508;9291.95354023239;9484.762880927894;11286.887131128748;11511.654650360502;11746.88253117475;14382.979574289307;14438.711225775425;14498.130112193303;14503.92748036253;14510.419583216553;8042.798930026796;8356.997160340798;8390.664373424994;8535.871961920577;8541.487510749375;6835;8218.137095604994;8262.752117436487;8292.875606865902;8293.875591866126;4505.797239124247;4507.526709695435;4525.230710779176;4535.501094879561;4604.194266003436;1594.9920250398907;1854.4065898912488;1914.4352416037302;1924.923003079861;1925.528245579842;807.0557447786105;852.1691350932791;884.2263019857625;949.1995150075498;1009.8232809258351;21517.569648607252;21878.671819922714;21882.671759923614;21952.89023554872;21977.890110549346;744.8994385757961;886.1492966751862;886.3263919421287;897.7935074932802;977.0913050862766;10816.567337306577;11425.31448113116;11447.885521144683;11503.07975361969;11570.479328430241;586674;591754.0412297911;595464;613266;615023;56927.14609280864;98953.59940805612;108364;125642;134896;895.2345744388584;926.1294383279561;949.2738055387634;951.695457453622;952.1763674421576;19153.13810878486;19188.616227675087;19421.028948552797;19457.929813860374;19616.901915490333;35453.82273088618;50307.975361970224;51194.46416607508;51250.74374628103;51357.97284054373;47605.761971189924;59886;72155;74230.25769742449;75345.62327188329;7535.623218839146;7730.729424470116;7954.761357159295;8006.83986320282;8223.342132629256;8995.370324077514;10709.625163119254;11569.305841649528;12317.876821231674;12777.808332874829;10818.945905270422;10819.945900270448;10977.670669879917;10978.560857565928;11081.5567377304;10838.132949363875;10839.891601083888;10877.836832447361;10929.125669946545;11219.60731374398;10283.897161028293;10413.635522756667;10997.61508347204;11169.994700476997;11303.98264156197;7848.568328741974;7924.881126782988;7966.083900351534;7986.680532778624;8025.719099831478;8940.597673104856;10826.621068262573;11075.446227688422;11143.219974602025;11153.721156970816 08:54:25 INFO - PROCESS | 1652 | |3;mootools.html;1491.8433564475727;1525.1459182857598;1723.9569010774728;1883.8681292309527;1919.5776929075598;8404.36967227458;8944.776380590489;8955.507447090424;8956.77608059798;8974.73075807726;7798.727044553441;8092.838143237132;8097.947266855301;8186.263236308731;8725.301975841934;2128.776478469758;2171.0555908179913;2288.4164538042774;2375.6674065630827;2378.286514045788;2381.7261014983324;2381.8928148233285;2382.714074311085;2385.33210701004;2387.033251533132;2327.7904988551027;2378.6194208926577;2380.3097101840444;2386.296042667417;2388.450656349041;2344.4256157241416;2354.799842013431;2367.30164601443;2368.2421625079983;2376.7742064503846;2331.4754180309296;2348.4833336665906;2354.4702441950426;2355.7526459721826;2373.715154181509;8625.827483450359;8801.427907186013;8814.69148579803;8822.911770882272;8830.205281524693;5167.431582525919;5337.786488540453;5344;5344.518993290622;5351.545118664918;3306.470964645645;3423.058658868806;3501;3511.7892926424497;3515.402381595135;1004.6132239088004;1050.721558786922;1070.9625163119254;1071.9678409647722;1074.790415868906;11527.884721152765;11577.884221157765;11586.768264634744;11596.652100437;11612.535498580046;4335.7181783184;4596.655250856199;4712.740799256006;4800.711957282574;4804.711717296974;9531.142197202285;9652.75868103303;9681.467519286507;9684.903150968401;9691.951540242253;5240.580753539708;5257.76340064698;5287.656302340336;5294.41761406245;5297.496737809902;4735.313379559944;4835.419749629997;4843.15244832153;4855.247436647254;4878.390201224847;3574.37448446526;3732.0296722852254;3737.3272810893754;3742.176721121349;3754.155315054091;1205.1021988618536;1239.8964921220122;1283.781757101295;1285.029802499126;1293.6571808470762;822.4653974916232;834.4576025583419;881.9559022048871;931.8089791592707;950.1496160936061;5647.181158731961;5740.799072032459;5953.136795164677;5971.074483454984;6058.1518587397795;943.4905151218403;949.7862980829258;967.1054274795786;976.067855198284;996.2179688944144;3909.6090390961244;3909.7458665186673;3949.2101579684177;4026.5570787213383;4041.656459200942;10345.224108191765;10675.412852293042;10758.193135514712;10772.515236814363;10810.945945270223;9996.400215987063;10550.47247637615;10731.624393146203;10738;10777.622783202707;7988.480748751429;7990.80022999418;7999.760007199793;8018.997625296838;8192.631331589973;5232.607554433356;5794.971025144847;5855.3266374365785;5886.970565147318;6067.1744287676365;9926.12142478673;9966.451845148586;9973.650922217688;9976.20190384753;9993.750156246151;9404.623815047322;10229.795404092025;10233.079022887976;10243.334183278062;10280.280380373302;7455.515391499535;7594.6202689866395;7608.619569021637;7609.581473018816;7621.12357078943;8093.231143041402;8195.098539160568;8206.671733130846;8263.545504997042;8271.917280827114;5999.70001499932;7281.635918204174;7377.631118443949;7550.433717471322;7550.5469671819865;7199.928000719926;7305.452091093295;7332.890006650011;7334.119905611361;7385.372243359375;6702.028205910018;6709.194896612437;6894.517383783288;6903.827404314731;6966.686499107654;4812.989272252726;4893;4934.703917764946;5006.799728010985;5115.232715092766;3883.825227864809;4206.621404073526;4911.557959783637;4979.651424400257;5132.743362831919 08:54:25 INFO - PROCESS | 1652 | |4;prototype.html;297.49722968183767;300.3467458278244;310.89740385672724;313.57667149348384;318.39346045782764;4837.540433658797;5167.354080739908;5239.659422137558;5294.89410211796;5302.3637163540425;4884.853454396374;5028.7485625718655;5043.890344124296;5053.646244762868;5078.927249014339;2876.827390356575;3898.5516665583536;3921.9215615687667;3923.7253392262555;3980.7810570418615;3441.363347780656;3953.802309884494;3960.7623542587394;3966.583508731585;4016.7790771507557;3092.8350154891555;3147.6065491813524;3641.617630148836;3827.349350610389;3932.4101384792225;3293.5059741038917;3957.940630890539;3961.2768445726056;3979.880603581897;3994.282458542825;2677.4645070985684;2913;3267.738580913521;3485.6514348565192;3500.597431295408;4092.815823287959;4940.900117450069;5070.898582028339;5099.898002039939;5101.7194054327;4295.634871035965;4530.844634618151;4541.772911354452;4568.771561421916;4581.908361832745;4758.976205118987;4776.450708168571;5445.883593863248;5494.697791621459;5543.6396634218645;3810.3522401191863;3816.484774555455;3816.5078952758195;3820.5033345665165;3826.770393776382;4765;5302.363716354023;5364.731763411853;5411.540019098342;5795.043817770063;3766.608203790558;3781.2248489059675;3786.769299977518;3794.71539634523;3813.618638136164;3032.180072532522;3327.6006879174656;3710.257948410329;3879.573246942834;3894.922101557953;4095.8156882939743;4103.071556368502;4519.231730605805;4538.319252112209;4560.201964656172;5229.738513074331;5306.363236411578;5422.457754224625;5487.314085739283;5503.147012213113;1009.5305682857506;1141.4235810915434;1288.0017986060877;1302.3488255872064;1321.0752473268751;834.2866848844272;850.0776657326746;855.8159995600976;866.7356456280895;902.7722297675157;4254.765987870697;4412.84555040572;4890.975545122323;4897.3064814351965;5120.667156634807;5381.277991042829;5623.718814059281;5652.943470565324;5676.971615141981;5713.8285851424525;4611.746353950565;4751.067558561463;4849.806007759721;4946.752662366939;5049.772760225798;2333.5916214662366;2456.066694656055;2774.972250277471;2792.9301767455568;2938.103878317091;5338.985592737367;5583.804566840141;5805.6806875621405;5830.737616807254;5857.58996870215;5639.238702775155;5657.57568182388;5685.60200785941;5701.629394089371;5709.314882214161;4798.824288049381;5318.7074710891275;5501.807436739695;5541.944580554143;5671.801486947937;4823.421189457287;4891.584215341736;5317.680939143664;5571.526420254324;5715.657060576213;4651.488056585107;4718.598919091915;5492.340919089735;5632.268894128952;5643.548516118783;4933.753312334297;4946.975265123651;5940.6435613863305;6006.8498287541415;6039.456448919618;3710.3692372296236;3786.1670432504807;3791.7724936503896;3796.5823759385894;3839.884803455901;4711.387519622427;5190.429052804113;5467.125259958388;5729.455701708331;5733.681253311678;4623.583877451046;5621.5221706155435;5650.547956163579;5651.6043876928225;5658.179563963285;4271.423357846651;5488.929658716512;5490.039243132356;5525.259543243866;5626.5780066496;5104.183330667075;5310.495502927216;5398.325209348832;5413.702246376487;5432.348118225838;4650.9767451162525;5064.5441910228255;5134.5635620972635;5293.894122117613;5331.440908364085;5397.244385785914;5400.324959380077;5407.594430417813;5415.756290966837;5420.403755586961 08:54:25 INFO - PROCESS | 1652 | |5;yui.html;714.253604982793;726.9273072692731;730.6638946084801;771.0477560213136;778.7157687444085;13130.47478100876;15191.164485953268;16007.919960400211;16032.038077715331;16496.25766840492;13169.275689837055;13305.201687898732;14311.92844035781;14459.855401445982;14467.927660361709;662.6752891083358;684.449018540075;688.9138857642795;689.8137502874221;826.4173757500962;697.5187120886573;698.9829797644416;835.3776436554745;859.7034023261965;867.6746220167437;891.4473026723441;897.8922529296493;898.2544488074915;902.4044130873625;904.8868891388577;893.3924931046886;898.1422741282059;898.4294972692322;899.7435730816751;901;527.2749968792909;664.8038828545542;665.1486097794831;669.4109183918121;670.6043434373743;11971.880281197251;12376.381180940916;12386;12428.254304741655;14134.646633834134;14237.857621423756;14840.406383744637;14882.479113231093;14919.99999999989;14959.850401495954;11862.88137118635;11901.285922844658;11937;11953.581624643095;12205.07537787989;3795.9810200949096;3807.3527500324735;3809.580946095927;3817.8091095445393;3951.9604803952166;115777.42111289389;118185.40907295495;119597;122385;122533.16200257004;11253.212275140662;11285.71785705364;11301.20891537601;11306.547738090549;11537.250078744855;20673.896630516752;21381;23544.64683029756;23563.52872942566;23627.29118126459;7896.447248692536;7975.8404831903035;8006.319462845723;8062.959685201654;8223.424360294723;4310.719803212781;4332.696711230246;4339.609435150852;4363.4109395231235;4470.307102399133;3593.9640603594153;3774.3772277573894;3775.3954569307934;3775.8867233983024;3779.1874746928993;691.2879733874117;697.3410127429631;700.6356694518822;705.611913447612;748.5209465941801;629.5624540944;663.9111856555253;667.4460198035716;688.5765254368628;697.316629702894;9130.908690913138;9481.668141615148;9598.712038638852;9626.229901607854;9720.805583888283;556.4657928388792;628.6510986402591;650.9218893732782;673.4040374268808;678.131991051455;3920.313945059603;4343.370211319404;4670.182718024332;4693.953060469352;4767.523247675274;96645;96687.51656241674;97728;97833.15167938318;129310;100526;121986.39006805087;123872;125648.37175814062;127639;67906.64186716238;68889.65555172192;85509.57245213733;92083.53958230166;105788.47105764576;9583.520823958635;9713.320067595201;9723.562439690373;9791.314607977372;9864.506774661382;57085.42914570801;63809;64275.678621606596;65106.674466627366;66028;83436.16563834283;85657;88768;92303.15393692223;99548;12599.370031498573;12775.169613975062;12959.222446653232;13013.934930325287;13178.868211317764;14195.716085678436;14220.502282420066;14374.85625143777;14377.28113594337;14852.480163194236;13328.200307981551;13551;13564.321783910567;13568.59294221175;13698.794518082037;11348.829767553329;13324;13528.323583820966;13529.864701353254;13532.188068715908;13095;13324.733505329645;13413.195208287534;13448.93275533616;13598.11612245201;11138.27601205919;11207.551697931991;11474.426278685865;11487.25332853362;11502.252353596989;11025.338479691245;11209.943950280196;11432.199746018032;11444.542218311175;11472.426378680866 08:54:25 INFO - PROCESS | 1652 | __end_tp_report 08:54:25 INFO - PROCESS | 1652 | __start_cc_report 08:54:25 INFO - PROCESS | 1652 | _x_x_mozilla_cycle_collect,1378 08:54:25 INFO - PROCESS | 1652 | __end_cc_report 08:54:25 INFO - PROCESS | 1652 | __startTimestamp1475855665624__endTimestamp 08:54:25 INFO - PROCESS | 1652 | 08:54:25 INFO - PROCESS | 1652 | ------- Summary: start ------- 08:54:25 INFO - PROCESS | 1652 | Number of tests: 6 08:54:25 INFO - PROCESS | 1652 | 08:54:25 INFO - PROCESS | 1652 | [#0] dojo.html Cycles:165 Average:5696.60 Median:5871.24 stddev:664.83 (11.3%) stddev-sans-first:627.46 08:54:25 INFO - PROCESS | 1652 | Values: 2821.8 3320.8 3331.1 3374.8 3395.6 5585.8 5668.0 5689.1 5699.9 5701.4 5642.5 5678.1 5679.1 5707.3 5714.4 5146.4 5186.4 5193.7 5227.9 5241.9 5291.8 5294.7 5313.5 5316.4 5328.4 5165.8 5224.9 5247.6 5294.8 5306.5 3965.5 5268.8 5282.2 5293.1 5345.7 5100.4 5119.8 5176.7 5210.8 5211.4 5400.3 5487.4 5496.6 5516.2 5524.7 5949.1 5951.0 5955.8 5956.9 5959.4 6011.0 6070.8 6078.1 6147.1 6234.3 5743.2 5803.0 5803.4 5810.1 5862.1 6240.3 6301.4 6302.6 6302.8 6310.1 5098.7 6035.7 6143.1 6161.6 6165.8 5440.5 6136.0 6141.3 6170.6 6248.1 6154.3 6187.8 6198.0 6199.8 6211.3 6152.6 6230.6 6236.4 6240.9 6246.8 4817.2 4927.3 4939.6 4945.5 4989.1 4053.3 4338.8 4451.9 4480.7 4493.4 5706.5 5747.6 5768.9 5770.1 5770.4 6115.7 6130.9 6179.5 6218.3 6257.9 5699.1 5732.1 5733.5 5773.8 5779.6 4589.0 5414.2 5427.9 5437.9 5443.5 6164.2 6229.3 6372.4 6384.2 6395.4 5202.0 6141.5 6231.8 6269.1 6287.6 6317.6 6327.2 6353.1 6380.3 6391.0 6101.8 6221.5 6249.6 6251.1 6255.3 6170.5 6186.4 6189.7 6238.4 6301.8 6129.0 6138.2 6145.8 6148.8 6164.6 5868.2 6063.9 6112.3 6136.4 6146.7 5835.2 5893.6 5922.5 5932.1 5944.4 6098.4 6123.4 6129.8 6138.5 6139.3 5418.3 5840.7 5871.2 6000.8 6068.0 08:54:25 INFO - PROCESS | 1652 | 08:54:25 INFO - PROCESS | 1652 | [#1] ext.html Cycles:180 Average:71755.58 Median:40936.49 stddev:156734.59 (382.9%) stddev-sans-first:157082.86 08:54:25 INFO - PROCESS | 1652 | Values: 461.4 470.4 501.7 502.8 536.0 25132.5 31695.4 40800.0 41977.0 41987.6 41374.0 43118.4 43171.0 43184.8 43218.4 9741.0 9919.9 9925.6 9927.7 9942.8 9590.5 9704.0 9718.2 9720.8 9722.2 9709.1 9752.4 9759.7 9763.4 9784.4 9676.0 9728.2 9729.2 9731.3 9768.9 9685.1 9743.5 9761.9 9764.3 9769.4 40456.6 40729.6 40783.2 40806.0 40813.8 3341.4 3502.7 3666.8 3771.6 4002.7 29898.6 30177.2 31353.7 31998.0 32213.5 3207.2 3267.2 3274.9 3339.2 3375.3 493013.0 499984.5 503916.0 508358.0 517559.0 63970.4 65224.3 65302.0 65361.3 65479.0 69046.0 69361.7 69378.6 69431.7 69441.7 16576.2 16785.7 16817.7 16820.8 16869.8 12754.6 12851.3 12868.1 12871.1 12892.5 16699.4 17341.3 19581.5 20007.8 20019.4 6747.4 8252.9 8423.5 8487.7 8621.8 703.1 711.9 713.9 765.3 955.4 29199.1 30189.0 30532.8 30815.7 31044.7 1003.9 1073.8 1079.2 1169.9 1193.1 15321.0 15773.4 15946.6 16105.3 16185.0 694784.0 698145.0 698535.0 699300.1 702878.0 57427.1 57960.1 58008.0 58011.0 58011.5 1227.6 1267.8 1269.9 1346.2 1376.1 29636.9 31573.2 31656.0 31728.5 32317.5 50919.5 50952.0 50974.7 51163.0 51239.5 533276.3 535960.0 536609.0 537302.2 539719.0 26946.9 27285.9 27540.0 27829.7 42681.6 37475.3 47642.5 47718.5 48122.8 48420.5 38777.8 39291.0 40934.2 40938.8 41041.6 38600.0 38622.0 39571.8 40006.6 40123.0 38783.0 39191.2 39425.0 39712.6 39775.4 32764.7 34345.8 34800.8 35265.0 35409.0 25066.6 25572.9 25768.5 25834.4 26394.5 08:54:25 INFO - PROCESS | 1652 | 08:54:25 INFO - PROCESS | 1652 | [#2] jquery.html Cycles:180 Average:33637.77 Median:378.26 stddev:99825.38 (26390.6%) stddev-sans-first:100074.13 08:54:25 INFO - PROCESS | 1652 | Values: 350.3 373.9 375.4 381.1 401.6 14767.6 14980.8 16084.9 20065.9 20707.7 15853.3 18245.4 19267.4 20167.9 20383.8 5927.7 6180.0 6215.3 6221.8 6238.2 4587.5 6114.8 6163.1 6179.1 6202.4 5937.5 6056.8 6059.8 6127.3 6151.4 6019.2 6033.0 6133.8 6135.4 6138.3 5146.3 5325.9 5332.6 5434.6 5502.1 13222.5 13489.9 13497.2 15434.4 24956.1 1742.9 2246.9 2327.7 2451.7 2619.4 9729.7 9833.6 9873.9 10372.3 10462.8 1792.0 1876.6 1880.1 1888.8 1939.3 116224.0 118732.0 123355.4 123656.0 127508.0 9292.0 9484.8 11286.9 11511.7 11746.9 14383.0 14438.7 14498.1 14503.9 14510.4 8042.8 8357.0 8390.7 8535.9 8541.5 6835.0 8218.1 8262.8 8292.9 8293.9 4505.8 4507.5 4525.2 4535.5 4604.2 1595.0 1854.4 1914.4 1924.9 1925.5 807.1 852.2 884.2 949.2 1009.8 21517.6 21878.7 21882.7 21952.9 21977.9 744.9 886.1 886.3 897.8 977.1 10816.6 11425.3 11447.9 11503.1 11570.5 586674.0 591754.0 595464.0 613266.0 615023.0 56927.1 98953.6 108364.0 125642.0 134896.0 895.2 926.1 949.3 951.7 952.2 19153.1 19188.6 19421.0 19457.9 19616.9 35453.8 50308.0 51194.5 51250.7 51358.0 47605.8 59886.0 72155.0 74230.3 75345.6 7535.6 7730.7 7954.8 8006.8 8223.3 8995.4 10709.6 11569.3 12317.9 12777.8 10818.9 10819.9 10977.7 10978.6 11081.6 10838.1 10839.9 10877.8 10929.1 11219.6 10283.9 10413.6 10997.6 11170.0 11304.0 7848.6 7924.9 7966.1 7986.7 8025.7 8940.6 10826.6 11075.4 11143.2 11153.7 08:54:25 INFO - PROCESS | 1652 | 08:54:25 INFO - PROCESS | 1652 | [#3] mootools.html Cycles:180 Average:5651.56 Median:5061.02 stddev:3165.70 (62.6%) stddev-sans-first:3159.14 08:54:25 INFO - PROCESS | 1652 | Values: 1491.8 1525.1 1724.0 1883.9 1919.6 8404.4 8944.8 8955.5 8956.8 8974.7 7798.7 8092.8 8097.9 8186.3 8725.3 2128.8 2171.1 2288.4 2375.7 2378.3 2381.7 2381.9 2382.7 2385.3 2387.0 2327.8 2378.6 2380.3 2386.3 2388.5 2344.4 2354.8 2367.3 2368.2 2376.8 2331.5 2348.5 2354.5 2355.8 2373.7 8625.8 8801.4 8814.7 8822.9 8830.2 5167.4 5337.8 5344.0 5344.5 5351.5 3306.5 3423.1 3501.0 3511.8 3515.4 1004.6 1050.7 1071.0 1072.0 1074.8 11527.9 11577.9 11586.8 11596.7 11612.5 4335.7 4596.7 4712.7 4800.7 4804.7 9531.1 9652.8 9681.5 9684.9 9692.0 5240.6 5257.8 5287.7 5294.4 5297.5 4735.3 4835.4 4843.2 4855.2 4878.4 3574.4 3732.0 3737.3 3742.2 3754.2 1205.1 1239.9 1283.8 1285.0 1293.7 822.5 834.5 882.0 931.8 950.1 5647.2 5740.8 5953.1 5971.1 6058.2 943.5 949.8 967.1 976.1 996.2 3909.6 3909.7 3949.2 4026.6 4041.7 10345.2 10675.4 10758.2 10772.5 10810.9 9996.4 10550.5 10731.6 10738.0 10777.6 7988.5 7990.8 7999.8 8019.0 8192.6 5232.6 5795.0 5855.3 5887.0 6067.2 9926.1 9966.5 9973.7 9976.2 9993.8 9404.6 10229.8 10233.1 10243.3 10280.3 7455.5 7594.6 7608.6 7609.6 7621.1 8093.2 8195.1 8206.7 8263.5 8271.9 5999.7 7281.6 7377.6 7550.4 7550.5 7199.9 7305.5 7332.9 7334.1 7385.4 6702.0 6709.2 6894.5 6903.8 6966.7 4813.0 4893.0 4934.7 5006.8 5115.2 3883.8 4206.6 4911.6 4979.7 5132.7 08:54:25 INFO - PROCESS | 1652 | 08:54:25 INFO - PROCESS | 1652 | [#4] prototype.html Cycles:180 Average:4384.59 Median:4894.45 stddev:1377.23 (28.1%) stddev-sans-first:1346.50 08:54:25 INFO - PROCESS | 1652 | Values: 297.5 300.3 310.9 313.6 318.4 4837.5 5167.4 5239.7 5294.9 5302.4 4884.9 5028.7 5043.9 5053.6 5078.9 2876.8 3898.6 3921.9 3923.7 3980.8 3441.4 3953.8 3960.8 3966.6 4016.8 3092.8 3147.6 3641.6 3827.3 3932.4 3293.5 3957.9 3961.3 3979.9 3994.3 2677.5 2913.0 3267.7 3485.7 3500.6 4092.8 4940.9 5070.9 5099.9 5101.7 4295.6 4530.8 4541.8 4568.8 4581.9 4759.0 4776.5 5445.9 5494.7 5543.6 3810.4 3816.5 3816.5 3820.5 3826.8 4765.0 5302.4 5364.7 5411.5 5795.0 3766.6 3781.2 3786.8 3794.7 3813.6 3032.2 3327.6 3710.3 3879.6 3894.9 4095.8 4103.1 4519.2 4538.3 4560.2 5229.7 5306.4 5422.5 5487.3 5503.1 1009.5 1141.4 1288.0 1302.3 1321.1 834.3 850.1 855.8 866.7 902.8 4254.8 4412.8 4891.0 4897.3 5120.7 5381.3 5623.7 5652.9 5677.0 5713.8 4611.7 4751.1 4849.8 4946.8 5049.8 2333.6 2456.1 2775.0 2792.9 2938.1 5339.0 5583.8 5805.7 5830.7 5857.6 5639.2 5657.6 5685.6 5701.6 5709.3 4798.8 5318.7 5501.8 5541.9 5671.8 4823.4 4891.6 5317.7 5571.5 5715.7 4651.5 4718.6 5492.3 5632.3 5643.5 4933.8 4947.0 5940.6 6006.8 6039.5 3710.4 3786.2 3791.8 3796.6 3839.9 4711.4 5190.4 5467.1 5729.5 5733.7 4623.6 5621.5 5650.5 5651.6 5658.2 4271.4 5488.9 5490.0 5525.3 5626.6 5104.2 5310.5 5398.3 5413.7 5432.3 4651.0 5064.5 5134.6 5293.9 5331.4 5397.2 5400.3 5407.6 5415.8 5420.4 08:54:25 INFO - PROCESS | 1652 | 08:54:25 INFO - PROCESS | 1652 | [#5] yui.html Cycles:180 Average:22874.31 Median:3869.06 stddev:34805.35 (899.6%) stddev-sans-first:34863.21 08:54:25 INFO - PROCESS | 1652 | Values: 714.3 726.9 730.7 771.0 778.7 13130.5 15191.2 16007.9 16032.0 16496.3 13169.3 13305.2 14311.9 14459.9 14467.9 662.7 684.4 688.9 689.8 826.4 697.5 699.0 835.4 859.7 867.7 891.4 897.9 898.3 902.4 904.9 893.4 898.1 898.4 899.7 901.0 527.3 664.8 665.1 669.4 670.6 11971.9 12376.4 12386.0 12428.3 14134.6 14237.9 14840.4 14882.5 14920.0 14959.9 11862.9 11901.3 11937.0 11953.6 12205.1 3796.0 3807.4 3809.6 3817.8 3952.0 115777.4 118185.4 119597.0 122385.0 122533.2 11253.2 11285.7 11301.2 11306.5 11537.3 20673.9 21381.0 23544.6 23563.5 23627.3 7896.4 7975.8 8006.3 8063.0 8223.4 4310.7 4332.7 4339.6 4363.4 4470.3 3594.0 3774.4 3775.4 3775.9 3779.2 691.3 697.3 700.6 705.6 748.5 629.6 663.9 667.4 688.6 697.3 9130.9 9481.7 9598.7 9626.2 9720.8 556.5 628.7 650.9 673.4 678.1 3920.3 4343.4 4670.2 4694.0 4767.5 96645.0 96687.5 97728.0 97833.2 129310.0 100526.0 121986.4 123872.0 125648.4 127639.0 67906.6 68889.7 85509.6 92083.5 105788.5 9583.5 9713.3 9723.6 9791.3 9864.5 57085.4 63809.0 64275.7 65106.7 66028.0 83436.2 85657.0 88768.0 92303.2 99548.0 12599.4 12775.2 12959.2 13013.9 13178.9 14195.7 14220.5 14374.9 14377.3 14852.5 13328.2 13551.0 13564.3 13568.6 13698.8 11348.8 13324.0 13528.3 13529.9 13532.2 13095.0 13324.7 13413.2 13448.9 13598.1 11138.3 11207.6 11474.4 11487.3 11502.3 11025.3 11209.9 11432.2 11444.5 11472.4 08:54:25 INFO - PROCESS | 1652 | -------- Summary: end -------- 08:54:25 INFO - PROCESS | 1652 | 08:54:26 INFO - TEST-INFO | 1652: exit 0 08:54:26 INFO - TEST-OK | dromaeo_css | took 1119059ms 08:54:26 INFO - TEST-START | kraken 08:54:26 INFO - operating with platform_type : mac_ 08:54:26 INFO - Initialising browser for kraken test... 08:54:26 INFO - TEST-INFO | started process 1661 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/tmphRQqa5/profile http://localhost:49272/getInfo.html) 08:54:28 INFO - PROCESS | 1661 | __metrics Screen width/height:1600/1200 08:54:28 INFO - PROCESS | 1661 | colorDepth:24 08:54:28 INFO - PROCESS | 1661 | Browser inner width/height: 1024/689 08:54:28 INFO - PROCESS | 1661 | __metrics 08:54:28 INFO - PROCESS | 1661 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 08:54:28 INFO - PROCESS | 1661 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 08:54:29 INFO - TEST-INFO | 1661: exit 0 08:54:29 INFO - Browser initialized. 08:54:29 INFO - Running cycle 1/1 for kraken test... 08:54:29 INFO - TEST-INFO | started process 1662 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/tmphRQqa5/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:54:41 INFO - PROCESS | 1662 | RSS: Main: 235233280 08:54:41 INFO - PROCESS | 1662 | 08:54:43 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49272/tests/kraken/driver.html?testName=audio-beat-detection) 08:54:44 INFO - PROCESS | 1662 | RSS: Main: 293892096 08:54:44 INFO - PROCESS | 1662 | 08:54:47 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49272/tests/kraken/driver.html?testName=audio-dft) 08:54:47 INFO - PROCESS | 1662 | RSS: Main: 294121472 08:54:47 INFO - PROCESS | 1662 | 08:54:51 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49272/tests/kraken/driver.html?testName=audio-fft) 08:54:52 INFO - PROCESS | 1662 | RSS: Main: 385368064 08:54:52 INFO - PROCESS | 1662 | 08:54:54 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49272/tests/kraken/driver.html?testName=audio-oscillator) 08:54:54 INFO - PROCESS | 1662 | RSS: Main: 285351936 08:54:54 INFO - PROCESS | 1662 | 08:54:56 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49272/tests/kraken/driver.html?testName=imaging-gaussian-blur) 08:54:57 INFO - PROCESS | 1662 | RSS: Main: 289751040 08:54:57 INFO - PROCESS | 1662 | 08:55:00 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49272/tests/kraken/driver.html?testName=imaging-darkroom) 08:55:00 INFO - PROCESS | 1662 | RSS: Main: 328777728 08:55:00 INFO - PROCESS | 1662 | 08:55:03 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49272/tests/kraken/driver.html?testName=imaging-desaturate) 08:55:04 INFO - PROCESS | 1662 | RSS: Main: 328646656 08:55:04 INFO - PROCESS | 1662 | 08:55:06 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49272/tests/kraken/driver.html?testName=json-parse-financial) 08:55:07 INFO - PROCESS | 1662 | RSS: Main: 327127040 08:55:07 INFO - PROCESS | 1662 | 08:55:09 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49272/tests/kraken/driver.html?testName=json-stringify-tinderbox) 08:55:09 INFO - PROCESS | 1662 | RSS: Main: 300986368 08:55:09 INFO - PROCESS | 1662 | 08:55:11 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-aes) 08:55:12 INFO - PROCESS | 1662 | RSS: Main: 306401280 08:55:12 INFO - PROCESS | 1662 | 08:55:14 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-ccm) 08:55:14 INFO - PROCESS | 1662 | RSS: Main: 308981760 08:55:14 INFO - PROCESS | 1662 | 08:55:17 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 08:55:18 INFO - PROCESS | 1662 | RSS: Main: 325185536 08:55:18 INFO - PROCESS | 1662 | 08:55:21 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 08:55:22 INFO - PROCESS | 1662 | RSS: Main: 318550016 08:55:22 INFO - PROCESS | 1662 | 08:55:23 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49272/tests/kraken/driver.html?testName=ai-astar) 08:55:24 INFO - PROCESS | 1662 | RSS: Main: 362786816 08:55:24 INFO - PROCESS | 1662 | 08:55:24 INFO - PROCESS | 1662 | __start_tp_report 08:55:24 INFO - PROCESS | 1662 | _x_x_mozilla_page_load 08:55:24 INFO - PROCESS | 1662 | _x_x_mozilla_page_load_details 08:55:24 INFO - PROCESS | 1662 | |i|pagename|runs| 08:55:24 INFO - PROCESS | 1662 | |0;ai-astar;112;113;112;112;112;112;112;112;111;111 08:55:24 INFO - PROCESS | 1662 | |1;audio-beat-detection;153;113;115;151;112;113;114;111;143;114 08:55:24 INFO - PROCESS | 1662 | |2;audio-dft;184;183;185;186;184;183;182;181;183;184 08:55:24 INFO - PROCESS | 1662 | |3;audio-fft;80;59;62;86;66;58;60;59;83;58 08:55:24 INFO - PROCESS | 1662 | |4;audio-oscillator;85;71;90;69;69;69;91;67;69;90 08:55:24 INFO - PROCESS | 1662 | |5;imaging-gaussian-blur;92;130;88;84;86;83;88;85;87;85 08:55:24 INFO - PROCESS | 1662 | |6;imaging-darkroom;97;96;97;96;96;95;96;96;95;96 08:55:24 INFO - PROCESS | 1662 | |7;imaging-desaturate;84;86;84;85;84;84;86;84;85;103 08:55:24 INFO - PROCESS | 1662 | |8;json-parse-financial;56;55;53;52;53;55;54;55;53;55 08:55:24 INFO - PROCESS | 1662 | |9;json-stringify-tinderbox;63;69;68;75;71;71;69;70;73;70 08:55:24 INFO - PROCESS | 1662 | |10;stanford-crypto-aes;91;86;87;86;86;87;85;85;86;88 08:55:24 INFO - PROCESS | 1662 | |11;stanford-crypto-ccm;160;160;142;144;156;157;142;156;175;146 08:55:24 INFO - PROCESS | 1662 | |12;stanford-crypto-pbkdf2;170;169;172;170;173;172;172;169;172;202 08:55:24 INFO - PROCESS | 1662 | |13;stanford-crypto-sha256-iterative;68;68;70;68;67;69;69;66;67;67 08:55:24 INFO - PROCESS | 1662 | __end_tp_report 08:55:24 INFO - PROCESS | 1662 | __start_cc_report 08:55:24 INFO - PROCESS | 1662 | _x_x_mozilla_cycle_collect,2020 08:55:24 INFO - PROCESS | 1662 | __end_cc_report 08:55:24 INFO - PROCESS | 1662 | __startTimestamp1475855724227__endTimestamp 08:55:24 INFO - PROCESS | 1662 | 08:55:24 INFO - PROCESS | 1662 | ------- Summary: start ------- 08:55:24 INFO - PROCESS | 1662 | Number of tests: 14 08:55:24 INFO - PROCESS | 1662 | 08:55:24 INFO - PROCESS | 1662 | [#0] ai-astar Cycles:10 Average:111.90 Median:112.00 stddev:0.57 (0.5%) stddev-sans-first:0.60 08:55:24 INFO - PROCESS | 1662 | Values: 112.0 113.0 112.0 112.0 112.0 112.0 112.0 112.0 111.0 111.0 08:55:24 INFO - PROCESS | 1662 | 08:55:24 INFO - PROCESS | 1662 | [#1] audio-beat-detection Cycles:10 Average:123.90 Median:114.50 stddev:17.53 (15.3%) stddev-sans-first:15.11 08:55:24 INFO - PROCESS | 1662 | Values: 153.0 113.0 115.0 151.0 112.0 113.0 114.0 111.0 143.0 114.0 08:55:24 INFO - PROCESS | 1662 | 08:55:24 INFO - PROCESS | 1662 | [#2] audio-dft Cycles:10 Average:183.50 Median:184.00 stddev:1.43 (0.8%) stddev-sans-first:1.51 08:55:24 INFO - PROCESS | 1662 | Values: 184.0 183.0 185.0 186.0 184.0 183.0 182.0 181.0 183.0 184.0 08:55:24 INFO - PROCESS | 1662 | 08:55:24 INFO - PROCESS | 1662 | [#3] audio-fft Cycles:10 Average:67.10 Median:64.00 stddev:11.31 (17.7%) stddev-sans-first:10.99 08:55:24 INFO - PROCESS | 1662 | Values: 80.0 59.0 62.0 86.0 66.0 58.0 60.0 59.0 83.0 58.0 08:55:24 INFO - PROCESS | 1662 | 08:55:24 INFO - PROCESS | 1662 | [#4] audio-oscillator Cycles:10 Average:77.00 Median:78.00 stddev:10.49 (13.4%) stddev-sans-first:10.72 08:55:24 INFO - PROCESS | 1662 | Values: 85.0 71.0 90.0 69.0 69.0 69.0 91.0 67.0 69.0 90.0 08:55:24 INFO - PROCESS | 1662 | 08:55:24 INFO - PROCESS | 1662 | [#5] imaging-gaussian-blur Cycles:10 Average:90.80 Median:86.50 stddev:14.01 (16.2%) stddev-sans-first:14.85 08:55:24 INFO - PROCESS | 1662 | Values: 92.0 130.0 88.0 84.0 86.0 83.0 88.0 85.0 87.0 85.0 08:55:24 INFO - PROCESS | 1662 | 08:55:24 INFO - PROCESS | 1662 | [#6] imaging-darkroom Cycles:10 Average:96.00 Median:96.00 stddev:0.67 (0.7%) stddev-sans-first:0.60 08:55:24 INFO - PROCESS | 1662 | Values: 97.0 96.0 97.0 96.0 96.0 95.0 96.0 96.0 95.0 96.0 08:55:24 INFO - PROCESS | 1662 | 08:55:24 INFO - PROCESS | 1662 | [#7] imaging-desaturate Cycles:10 Average:86.50 Median:84.50 stddev:5.85 (6.9%) stddev-sans-first:6.14 08:55:24 INFO - PROCESS | 1662 | Values: 84.0 86.0 84.0 85.0 84.0 84.0 86.0 84.0 85.0 103.0 08:55:24 INFO - PROCESS | 1662 | 08:55:24 INFO - PROCESS | 1662 | [#8] json-parse-financial Cycles:10 Average:54.10 Median:55.00 stddev:1.29 (2.3%) stddev-sans-first:1.17 08:55:24 INFO - PROCESS | 1662 | Values: 56.0 55.0 53.0 52.0 53.0 55.0 54.0 55.0 53.0 55.0 08:55:24 INFO - PROCESS | 1662 | 08:55:24 INFO - PROCESS | 1662 | [#9] json-stringify-tinderbox Cycles:10 Average:69.90 Median:70.50 stddev:3.18 (4.5%) stddev-sans-first:2.18 08:55:24 INFO - PROCESS | 1662 | Values: 63.0 69.0 68.0 75.0 71.0 71.0 69.0 70.0 73.0 70.0 08:55:24 INFO - PROCESS | 1662 | 08:55:24 INFO - PROCESS | 1662 | [#10] stanford-crypto-aes Cycles:10 Average:86.70 Median:86.50 stddev:1.77 (2.0%) stddev-sans-first:0.97 08:55:24 INFO - PROCESS | 1662 | Values: 91.0 86.0 87.0 86.0 86.0 87.0 85.0 85.0 86.0 88.0 08:55:24 INFO - PROCESS | 1662 | 08:55:24 INFO - PROCESS | 1662 | [#11] stanford-crypto-ccm Cycles:10 Average:153.80 Median:156.50 stddev:10.44 (6.7%) stddev-sans-first:10.83 08:55:24 INFO - PROCESS | 1662 | Values: 160.0 160.0 142.0 144.0 156.0 157.0 142.0 156.0 175.0 146.0 08:55:24 INFO - PROCESS | 1662 | 08:55:24 INFO - PROCESS | 1662 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:174.10 Median:172.00 stddev:9.90 (5.8%) stddev-sans-first:10.39 08:55:24 INFO - PROCESS | 1662 | Values: 170.0 169.0 172.0 170.0 173.0 172.0 172.0 169.0 172.0 202.0 08:55:24 INFO - PROCESS | 1662 | 08:55:24 INFO - PROCESS | 1662 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.90 Median:68.00 stddev:1.20 (1.8%) stddev-sans-first:1.27 08:55:24 INFO - PROCESS | 1662 | Values: 68.0 68.0 70.0 68.0 67.0 69.0 69.0 66.0 67.0 67.0 08:55:24 INFO - PROCESS | 1662 | -------- Summary: end -------- 08:55:24 INFO - PROCESS | 1662 | 08:55:25 INFO - TEST-INFO | 1662: exit 0 08:55:25 INFO - TEST-OK | kraken | took 58811ms 08:55:25 INFO - SUITE-END | took 1177s 08:55:25 INFO - Completed test suite (00:19:38) 08:55:25 INFO - javascript benchmark 08:55:25 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2821.844798536081, 3320.7879124119695, 3331.1339051846526, 3374.7975121492696, 3395.575553055868, 5585.832425027245, 5668.036433806253, 5689.089745640698, 5699.914501282474, 5701.3728489866135, 5642.520385767215, 5678.091505359143, 5679.119736440858, 5707.258056452666, 5714.428557144294, 5146.433892271866, 5186.403563590179, 5193.714345710984, 5227.921581176285, 5241.86895327616, 5291.761870715808, 5294.708791016512, 5313.468653134694, 5316.441773613773, 5328.387235467912, 5165.793368265265, 5224.947750522503, 5247.580193584523, 5294.788208471657, 5306.495882891119, 3965.524137103537, 5268.841934741964, 5282.154855223146, 5293.100172970604, 5345.705986170759, 5100.362454693163, 5119.846404607868, 5176.663516871391, 5210.76551555181, 5211.400688920785, 5400.324959380077, 5487.396386397493, 5496.642718223303, 5516.200150978086, 5524.66851988882, 5949.137375080589, 5951.047832346847, 5955.7915472958675, 5956.851078723023, 5959.433853783927, 6011.0382338825575, 6070.817875463743, 6078.149059131725, 6147.077938309245, 6234.2830574483605, 5743.2246646703, 5803.042497987869, 5803.448672376117, 5810.099434587646, 5862.14998825168, 6240.2651863093715, 6301.40136687014, 6302.590331628521, 6302.779402720883, 6310.085037669604, 5098.668586541863, 6035.698215089228, 6143.109249158847, 6161.568690191643, 6165.815025549241, 5440.455954404528, 6136.004954352043, 6141.341837703795, 6170.629762214281, 6248.062790581449, 6154.323024467305, 6187.783427580103, 6198.0, 6199.814005579839, 6211.3478084801345, 6152.600080994721, 6230.563860529719, 6236.438720515175, 6240.906386404116, 6246.781362652377, 4817.205161148441, 4927.334809800631, 4939.629527785431, 4945.455999840015, 4989.0520801048415, 4053.310937140693, 4338.828516300644, 4451.910961780746, 4480.708753930984, 4493.370928070073, 5706.514946229534, 5747.597668163272, 5768.932747441737, 5770.105633626795, 5770.365259821417, 6115.727150604525, 6130.887362651087, 6179.536534759821, 6218.253809542884, 6257.937420625735, 5699.116636921284, 5732.140178973104, 5733.483986441241, 5773.797917072882, 5779.624324418899, 4588.9541104588525, 5414.242006119146, 5427.864303392446, 5437.945620543823, 5443.510084092451, 6164.229471316085, 6229.314775374614, 6372.426481616677, 6384.170057892537, 6395.42441180296, 5202.0, 6141.547378150984, 6231.781887633911, 6269.122322874892, 6287.591306565058, 6317.5893566918, 6327.2407311121115, 6353.110564521063, 6380.330065343072, 6391.041343798282, 6101.816945491642, 6221.5333849960525, 6249.562530622813, 6251.093591429127, 6255.343188965093, 6170.475509581735, 6186.443220110212, 6189.690515474298, 6238.376162383725, 6301.779437719842, 6129.0, 6138.232720909887, 6145.8156255312415, 6148.754049837957, 6164.568480206342, 5868.23712917318, 6063.909041364295, 6112.266528016667, 6136.386361363828, 6146.692665366624, 5835.183074369506, 5893.5874488787085, 5922.496587790086, 5932.139839723302, 5944.4352786485215, 6098.4322286166835, 6123.4351415035735, 6129.847850912965, 6138.480361557057, 6139.338817383189, 5418.322709661293, 5840.737166827418, 5871.236739224044, 6000.759969601341, 6067.969660151671], "value": 5653.264773868143, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [461.3955718009408, 470.44722451119924, 501.6513523101446, 502.76872638586224, 535.9682610973874, 25132.49735005299, 31695.366092678134, 40800.0, 41977.0, 41987.58012419875, 41374.0, 43118.353224701656, 43171.0, 43184.78407607965, 43218.351724724074, 9741.025897410256, 9919.900800992005, 9925.553350099226, 9927.702168934944, 9942.801143977116, 9590.472524011177, 9703.95148024259, 9718.173955213815, 9720.805583888317, 9722.222222222204, 9709.12617864392, 9752.366096203725, 9759.707208783713, 9763.414195148314, 9784.364016338915, 9676.032396760304, 9728.17310528606, 9729.221662267002, 9731.318807683465, 9768.90231097687, 9685.128338449573, 9743.512824358753, 9761.902380976171, 9764.316497845153, 9769.413835169842, 40456.59543404558, 40729.59270407288, 40783.184336313105, 40806.0, 40813.795931020155, 3341.365140623274, 3502.684758371733, 3666.7799932004164, 3771.5662698789442, 4002.679785617144, 29898.551521727193, 30177.245568860955, 31353.68646313553, 31998.040058798273, 32213.51679724806, 3207.2142325130494, 3267.1668724475344, 3274.8526316315824, 3339.215284408162, 3375.3080618473155, 493013.0, 499984.50007750094, 503916.0, 508358.0, 517559.0, 63970.36029639737, 65224.34775652183, 65302.0, 65361.346386536476, 65479.0, 69046.0, 69361.65319173371, 69378.61242775116, 69431.65284173547, 69441.65279173572, 16576.171191440622, 16785.748213776802, 16817.663646726996, 16820.831791682172, 16869.819112661997, 12754.61736147917, 12851.293178875252, 12868.099233053783, 12871.099023068482, 12892.48430062787, 16699.409626930268, 17341.28455002286, 19581.51046223827, 20007.799922000886, 20019.39941801748, 6747.359000894907, 8252.876206856901, 8423.536705481258, 8487.702930397407, 8621.78445538854, 703.1079553342953, 711.8683043636944, 713.8679344321317, 765.326512668859, 955.4028732042474, 29199.124026279245, 30189.0, 30532.847335763625, 30815.69184308173, 31044.689553104632, 1003.94478303694, 1073.8281874899978, 1079.2445288298222, 1169.906407487399, 1193.12901581844, 15321.0, 15773.369065237492, 15946.611519883369, 16105.275262612977, 16185.028898266142, 694784.0, 698145.0, 698535.0, 699300.0769915305, 702878.0, 57427.13859292114, 57960.13059804106, 58008.0, 58011.0, 58011.54971125671, 1227.5826219085554, 1267.8034904589804, 1269.898408127329, 1346.1922846292146, 1376.0711519724146, 29636.851815741647, 31573.210669733442, 31656.0, 31728.524072139397, 32317.51523727193, 50919.490805091475, 50952.0, 50974.72563185802, 51163.0, 51239.48760512496, 533276.3336183294, 535960.0, 536609.0, 537302.2102458267, 539719.0, 26946.865265673547, 27285.863570682814, 27540.0, 27829.72170278352, 42681.57318426776, 37475.2504949894, 47642.5235747652, 47718.52281477141, 48122.75938620425, 48420.5157948416, 38777.806110969264, 39291.0, 40934.1813163741, 40938.795306023276, 41041.58958410378, 38600.0, 38622.034449139, 39571.80214098911, 40006.59993400029, 40123.0, 38783.0, 39191.216175675756, 39425.0, 39712.60287397205, 39775.403368950065, 32764.672353277114, 34345.82827085849, 34800.82599587087, 35265.0, 35409.0, 25066.60801495607, 25572.872135639947, 25768.484630307663, 25834.35414114662, 26394.472110557297], "value": 20269.252030808097, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [350.2731831449739, 373.8504598160737, 375.3974870333115, 381.1234161428714, 401.6224748736188, 14767.556973290792, 14980.775288370683, 16084.919575402137, 20065.89967050163, 20707.689384659243, 15853.286602102904, 18245.36141235057, 19267.421977340666, 20167.899160504177, 20383.796162038376, 5927.673977931212, 6180.0, 6215.254169499666, 6221.7822376216845, 6238.189035425388, 4587.518310577391, 6114.81655550332, 6163.106349579309, 6179.134921111048, 6202.3797620238065, 5937.52499800015, 6056.849198652242, 6059.757609695607, 6127.325994140663, 6151.4463698267145, 6019.247594050744, 6033.0, 6133.846653833645, 6135.447809697104, 6138.294096178952, 5146.279520867081, 5325.946740532584, 5332.600054995891, 5434.565234781208, 5502.147167189092, 13222.471101155943, 13489.86510134896, 13497.190168589916, 15434.38262469511, 24956.126535571348, 1742.8779985401027, 2246.921357752487, 2327.7323107842644, 2451.6690246816806, 2619.4237267801245, 9729.70810875675, 9833.606655733762, 9873.901260987443, 10372.273940824145, 10462.790744185073, 1792.0, 1876.6434377468238, 1880.0787614069145, 1888.7827899791562, 1939.2533874458295, 116224.0, 118732.0, 123355.3832230833, 123656.0, 127508.0, 9291.95354023239, 9484.762880927894, 11286.887131128748, 11511.654650360502, 11746.88253117475, 14382.979574289307, 14438.711225775425, 14498.130112193303, 14503.92748036253, 14510.419583216553, 8042.798930026796, 8356.997160340798, 8390.664373424994, 8535.871961920577, 8541.487510749375, 6835.0, 8218.137095604994, 8262.752117436487, 8292.875606865902, 8293.875591866126, 4505.797239124247, 4507.526709695435, 4525.230710779176, 4535.501094879561, 4604.194266003436, 1594.9920250398907, 1854.4065898912488, 1914.4352416037302, 1924.923003079861, 1925.528245579842, 807.0557447786105, 852.1691350932791, 884.2263019857625, 949.1995150075498, 1009.8232809258351, 21517.569648607252, 21878.671819922714, 21882.671759923614, 21952.89023554872, 21977.890110549346, 744.8994385757961, 886.1492966751862, 886.3263919421287, 897.7935074932802, 977.0913050862766, 10816.567337306577, 11425.31448113116, 11447.885521144683, 11503.07975361969, 11570.479328430241, 586674.0, 591754.0412297911, 595464.0, 613266.0, 615023.0, 56927.14609280864, 98953.59940805612, 108364.0, 125642.0, 134896.0, 895.2345744388584, 926.1294383279561, 949.2738055387634, 951.695457453622, 952.1763674421576, 19153.13810878486, 19188.616227675087, 19421.028948552797, 19457.929813860374, 19616.901915490333, 35453.82273088618, 50307.975361970224, 51194.46416607508, 51250.74374628103, 51357.97284054373, 47605.761971189924, 59886.0, 72155.0, 74230.25769742449, 75345.62327188329, 7535.623218839146, 7730.729424470116, 7954.761357159295, 8006.83986320282, 8223.342132629256, 8995.370324077514, 10709.625163119254, 11569.305841649528, 12317.876821231674, 12777.808332874829, 10818.945905270422, 10819.945900270448, 10977.670669879917, 10978.560857565928, 11081.5567377304, 10838.132949363875, 10839.891601083888, 10877.836832447361, 10929.125669946545, 11219.60731374398, 10283.897161028293, 10413.635522756667, 10997.61508347204, 11169.994700476997, 11303.98264156197, 7848.568328741974, 7924.881126782988, 7966.083900351534, 7986.680532778624, 8025.719099831478, 8940.597673104856, 10826.621068262573, 11075.446227688422, 11143.219974602025, 11153.721156970816], "value": 9168.29391100589, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1491.8433564475727, 1525.1459182857598, 1723.9569010774728, 1883.8681292309527, 1919.5776929075598, 8404.36967227458, 8944.776380590489, 8955.507447090424, 8956.77608059798, 8974.73075807726, 7798.727044553441, 8092.838143237132, 8097.947266855301, 8186.263236308731, 8725.301975841934, 2128.776478469758, 2171.0555908179913, 2288.4164538042774, 2375.6674065630827, 2378.286514045788, 2381.7261014983324, 2381.8928148233285, 2382.714074311085, 2385.33210701004, 2387.033251533132, 2327.7904988551027, 2378.6194208926577, 2380.3097101840444, 2386.296042667417, 2388.450656349041, 2344.4256157241416, 2354.799842013431, 2367.30164601443, 2368.2421625079983, 2376.7742064503846, 2331.4754180309296, 2348.4833336665906, 2354.4702441950426, 2355.7526459721826, 2373.715154181509, 8625.827483450359, 8801.427907186013, 8814.69148579803, 8822.911770882272, 8830.205281524693, 5167.431582525919, 5337.786488540453, 5344.0, 5344.518993290622, 5351.545118664918, 3306.470964645645, 3423.058658868806, 3501.0, 3511.7892926424497, 3515.402381595135, 1004.6132239088004, 1050.721558786922, 1070.9625163119254, 1071.9678409647722, 1074.790415868906, 11527.884721152765, 11577.884221157765, 11586.768264634744, 11596.652100437, 11612.535498580046, 4335.7181783184, 4596.655250856199, 4712.740799256006, 4800.711957282574, 4804.711717296974, 9531.142197202285, 9652.75868103303, 9681.467519286507, 9684.903150968401, 9691.951540242253, 5240.580753539708, 5257.76340064698, 5287.656302340336, 5294.41761406245, 5297.496737809902, 4735.313379559944, 4835.419749629997, 4843.15244832153, 4855.247436647254, 4878.390201224847, 3574.37448446526, 3732.0296722852254, 3737.3272810893754, 3742.176721121349, 3754.155315054091, 1205.1021988618536, 1239.8964921220122, 1283.781757101295, 1285.029802499126, 1293.6571808470762, 822.4653974916232, 834.4576025583419, 881.9559022048871, 931.8089791592707, 950.1496160936061, 5647.181158731961, 5740.799072032459, 5953.136795164677, 5971.074483454984, 6058.1518587397795, 943.4905151218403, 949.7862980829258, 967.1054274795786, 976.067855198284, 996.2179688944144, 3909.6090390961244, 3909.7458665186673, 3949.2101579684177, 4026.5570787213383, 4041.656459200942, 10345.224108191765, 10675.412852293042, 10758.193135514712, 10772.515236814363, 10810.945945270223, 9996.400215987063, 10550.47247637615, 10731.624393146203, 10738.0, 10777.622783202707, 7988.480748751429, 7990.80022999418, 7999.760007199793, 8018.997625296838, 8192.631331589973, 5232.607554433356, 5794.971025144847, 5855.3266374365785, 5886.970565147318, 6067.1744287676365, 9926.12142478673, 9966.451845148586, 9973.650922217688, 9976.20190384753, 9993.750156246151, 9404.623815047322, 10229.795404092025, 10233.079022887976, 10243.334183278062, 10280.280380373302, 7455.515391499535, 7594.6202689866395, 7608.619569021637, 7609.581473018816, 7621.12357078943, 8093.231143041402, 8195.098539160568, 8206.671733130846, 8263.545504997042, 8271.917280827114, 5999.70001499932, 7281.635918204174, 7377.631118443949, 7550.433717471322, 7550.5469671819865, 7199.928000719926, 7305.452091093295, 7332.890006650011, 7334.119905611361, 7385.372243359375, 6702.028205910018, 6709.194896612437, 6894.517383783288, 6903.827404314731, 6966.686499107654, 4812.989272252726, 4893.0, 4934.703917764946, 5006.799728010985, 5115.232715092766, 3883.825227864809, 4206.621404073526, 4911.557959783637, 4979.651424400257, 5132.743362831919], "value": 4551.560111093308, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [297.49722968183767, 300.3467458278244, 310.89740385672724, 313.57667149348384, 318.39346045782764, 4837.540433658797, 5167.354080739908, 5239.659422137558, 5294.89410211796, 5302.3637163540425, 4884.853454396374, 5028.7485625718655, 5043.890344124296, 5053.646244762868, 5078.927249014339, 2876.827390356575, 3898.5516665583536, 3921.9215615687667, 3923.7253392262555, 3980.7810570418615, 3441.363347780656, 3953.802309884494, 3960.7623542587394, 3966.583508731585, 4016.7790771507557, 3092.8350154891555, 3147.6065491813524, 3641.617630148836, 3827.349350610389, 3932.4101384792225, 3293.5059741038917, 3957.940630890539, 3961.2768445726056, 3979.880603581897, 3994.282458542825, 2677.4645070985684, 2913.0, 3267.738580913521, 3485.6514348565192, 3500.597431295408, 4092.815823287959, 4940.900117450069, 5070.898582028339, 5099.898002039939, 5101.7194054327, 4295.634871035965, 4530.844634618151, 4541.772911354452, 4568.771561421916, 4581.908361832745, 4758.976205118987, 4776.450708168571, 5445.883593863248, 5494.697791621459, 5543.6396634218645, 3810.3522401191863, 3816.484774555455, 3816.5078952758195, 3820.5033345665165, 3826.770393776382, 4765.0, 5302.363716354023, 5364.731763411853, 5411.540019098342, 5795.043817770063, 3766.608203790558, 3781.2248489059675, 3786.769299977518, 3794.71539634523, 3813.618638136164, 3032.180072532522, 3327.6006879174656, 3710.257948410329, 3879.573246942834, 3894.922101557953, 4095.8156882939743, 4103.071556368502, 4519.231730605805, 4538.319252112209, 4560.201964656172, 5229.738513074331, 5306.363236411578, 5422.457754224625, 5487.314085739283, 5503.147012213113, 1009.5305682857506, 1141.4235810915434, 1288.0017986060877, 1302.3488255872064, 1321.0752473268751, 834.2866848844272, 850.0776657326746, 855.8159995600976, 866.7356456280895, 902.7722297675157, 4254.765987870697, 4412.84555040572, 4890.975545122323, 4897.3064814351965, 5120.667156634807, 5381.277991042829, 5623.718814059281, 5652.943470565324, 5676.971615141981, 5713.8285851424525, 4611.746353950565, 4751.067558561463, 4849.806007759721, 4946.752662366939, 5049.772760225798, 2333.5916214662366, 2456.066694656055, 2774.972250277471, 2792.9301767455568, 2938.103878317091, 5338.985592737367, 5583.804566840141, 5805.6806875621405, 5830.737616807254, 5857.58996870215, 5639.238702775155, 5657.57568182388, 5685.60200785941, 5701.629394089371, 5709.314882214161, 4798.824288049381, 5318.7074710891275, 5501.807436739695, 5541.944580554143, 5671.801486947937, 4823.421189457287, 4891.584215341736, 5317.680939143664, 5571.526420254324, 5715.657060576213, 4651.488056585107, 4718.598919091915, 5492.340919089735, 5632.268894128952, 5643.548516118783, 4933.753312334297, 4946.975265123651, 5940.6435613863305, 6006.8498287541415, 6039.456448919618, 3710.3692372296236, 3786.1670432504807, 3791.7724936503896, 3796.5823759385894, 3839.884803455901, 4711.387519622427, 5190.429052804113, 5467.125259958388, 5729.455701708331, 5733.681253311678, 4623.583877451046, 5621.5221706155435, 5650.547956163579, 5651.6043876928225, 5658.179563963285, 4271.423357846651, 5488.929658716512, 5490.039243132356, 5525.259543243866, 5626.5780066496, 5104.183330667075, 5310.495502927216, 5398.325209348832, 5413.702246376487, 5432.348118225838, 4650.9767451162525, 5064.5441910228255, 5134.5635620972635, 5293.894122117613, 5331.440908364085, 5397.244385785914, 5400.324959380077, 5407.594430417813, 5415.756290966837, 5420.403755586961], "value": 3948.351310894096, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [714.253604982793, 726.9273072692731, 730.6638946084801, 771.0477560213136, 778.7157687444085, 13130.47478100876, 15191.164485953268, 16007.919960400211, 16032.038077715331, 16496.25766840492, 13169.275689837055, 13305.201687898732, 14311.92844035781, 14459.855401445982, 14467.927660361709, 662.6752891083358, 684.449018540075, 688.9138857642795, 689.8137502874221, 826.4173757500962, 697.5187120886573, 698.9829797644416, 835.3776436554745, 859.7034023261965, 867.6746220167437, 891.4473026723441, 897.8922529296493, 898.2544488074915, 902.4044130873625, 904.8868891388577, 893.3924931046886, 898.1422741282059, 898.4294972692322, 899.7435730816751, 901.0, 527.2749968792909, 664.8038828545542, 665.1486097794831, 669.4109183918121, 670.6043434373743, 11971.880281197251, 12376.381180940916, 12386.0, 12428.254304741655, 14134.646633834134, 14237.857621423756, 14840.406383744637, 14882.479113231093, 14919.99999999989, 14959.850401495954, 11862.88137118635, 11901.285922844658, 11937.0, 11953.581624643095, 12205.07537787989, 3795.9810200949096, 3807.3527500324735, 3809.580946095927, 3817.8091095445393, 3951.9604803952166, 115777.42111289389, 118185.40907295495, 119597.0, 122385.0, 122533.16200257004, 11253.212275140662, 11285.71785705364, 11301.20891537601, 11306.547738090549, 11537.250078744855, 20673.896630516752, 21381.0, 23544.64683029756, 23563.52872942566, 23627.29118126459, 7896.447248692536, 7975.8404831903035, 8006.319462845723, 8062.959685201654, 8223.424360294723, 4310.719803212781, 4332.696711230246, 4339.609435150852, 4363.4109395231235, 4470.307102399133, 3593.9640603594153, 3774.3772277573894, 3775.3954569307934, 3775.8867233983024, 3779.1874746928993, 691.2879733874117, 697.3410127429631, 700.6356694518822, 705.611913447612, 748.5209465941801, 629.5624540944, 663.9111856555253, 667.4460198035716, 688.5765254368628, 697.316629702894, 9130.908690913138, 9481.668141615148, 9598.712038638852, 9626.229901607854, 9720.805583888283, 556.4657928388792, 628.6510986402591, 650.9218893732782, 673.4040374268808, 678.131991051455, 3920.313945059603, 4343.370211319404, 4670.182718024332, 4693.953060469352, 4767.523247675274, 96645.0, 96687.51656241674, 97728.0, 97833.15167938318, 129310.0, 100526.0, 121986.39006805087, 123872.0, 125648.37175814062, 127639.0, 67906.64186716238, 68889.65555172192, 85509.57245213733, 92083.53958230166, 105788.47105764576, 9583.520823958635, 9713.320067595201, 9723.562439690373, 9791.314607977372, 9864.506774661382, 57085.42914570801, 63809.0, 64275.678621606596, 65106.674466627366, 66028.0, 83436.16563834283, 85657.0, 88768.0, 92303.15393692223, 99548.0, 12599.370031498573, 12775.169613975062, 12959.222446653232, 13013.934930325287, 13178.868211317764, 14195.716085678436, 14220.502282420066, 14374.85625143777, 14377.28113594337, 14852.480163194236, 13328.200307981551, 13551.0, 13564.321783910567, 13568.59294221175, 13698.794518082037, 11348.829767553329, 13324.0, 13528.323583820966, 13529.864701353254, 13532.188068715908, 13095.0, 13324.733505329645, 13413.195208287534, 13448.93275533616, 13598.11612245201, 11138.27601205919, 11207.551697931991, 11474.426278685865, 11487.25332853362, 11502.252353596989, 11025.338479691245, 11209.943950280196, 11432.199746018032, 11444.542218311175, 11472.426378680866], "value": 7659.9266598499635, "unit": "score"}], "name": "dromaeo_css", "value": 7245.122262126518, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 113.0, 112.0, 112.0, 112.0, 112.0, 112.0, 112.0, 111.0, 111.0], "value": 111.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 113.0, 115.0, 151.0, 112.0, 113.0, 114.0, 111.0, 143.0, 114.0], "value": 123.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 183.0, 185.0, 186.0, 184.0, 183.0, 182.0, 181.0, 183.0, 184.0], "value": 183.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 59.0, 62.0, 86.0, 66.0, 58.0, 60.0, 59.0, 83.0, 58.0], "value": 67.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 71.0, 90.0, 69.0, 69.0, 69.0, 91.0, 67.0, 69.0, 90.0], "value": 77.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 130.0, 88.0, 84.0, 86.0, 83.0, 88.0, 85.0, 87.0, 85.0], "value": 90.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 96.0, 97.0, 96.0, 96.0, 95.0, 96.0, 96.0, 95.0, 96.0], "value": 96.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 86.0, 84.0, 85.0, 84.0, 84.0, 86.0, 84.0, 85.0, 103.0], "value": 86.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 55.0, 53.0, 52.0, 53.0, 55.0, 54.0, 55.0, 53.0, 55.0], "value": 54.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 69.0, 68.0, 75.0, 71.0, 71.0, 69.0, 70.0, 73.0, 70.0], "value": 69.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 86.0, 87.0, 86.0, 86.0, 87.0, 85.0, 85.0, 86.0, 88.0], "value": 86.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 160.0, 142.0, 144.0, 156.0, 157.0, 142.0, 156.0, 175.0, 146.0], "value": 153.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [170.0, 169.0, 172.0, 170.0, 173.0, 172.0, 172.0, 169.0, 172.0, 202.0], "value": 174.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 68.0, 70.0, 68.0, 67.0, 69.0, 69.0, 66.0, 67.0, 67.0], "value": 67.9, "unit": "score"}], "name": "kraken", "value": 1443.1999999999998, "alertThreshold": 2.0}]} 08:55:25 INFO - Return code: 0 08:55:25 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 08:55:25 INFO - # TBPL SUCCESS # 08:55:25 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:55:25 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:55:25 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:55:25 INFO - 2016-10-07 08:55:25.531 screenresolution[1666:9747] starting screenresolution argv=screenresolution get 08:55:25 INFO - 2016-10-07 08:55:25.534 screenresolution[1666:9747] Display 0: 1600x1200x32@60 08:55:25 INFO - 2016-10-07 08:55:25.540 screenresolution[1667:9750] starting screenresolution argv=screenresolution list 08:55:25 INFO - Available Modes on Display 0 08:55:25 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:55:25 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:55:25 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:55:25 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:55:25 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:55:25 INFO - Intel Iris: 08:55:25 INFO - Chipset Model: Intel Iris 08:55:25 INFO - Type: GPU 08:55:25 INFO - Bus: Built-In 08:55:25 INFO - VRAM (Dynamic, Max): 1536 MB 08:55:25 INFO - Vendor: Intel (0x8086) 08:55:25 INFO - Device ID: 0x0a2e 08:55:25 INFO - Revision ID: 0x0009 08:55:25 INFO - Displays: 08:55:25 INFO - Display: 08:55:25 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:55:25 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:55:25 INFO - Display Serial Number: 1600x1200 60 08:55:25 INFO - Main Display: Yes 08:55:25 INFO - Mirror: Off 08:55:25 INFO - Online: Yes 08:55:25 INFO - Rotation: Supported 08:55:25 INFO - Return code: 0 08:55:25 INFO - Running post-action listener: _resource_record_post_action 08:55:25 INFO - [mozharness: 2016-10-07 15:55:25.778943Z] Finished run-tests step (success) 08:55:25 INFO - Running post-run listener: _resource_record_post_run 08:55:25 INFO - Total resource usage - Wall time: 1213s; CPU: 53.0%; Read bytes: 37064704; Write bytes: 706825216; Read time: 360; Write time: 2098 08:55:25 INFO - TinderboxPrint: CPU usage
53.5% 08:55:25 INFO - TinderboxPrint: I/O read bytes / time
37,064,704 / 360 08:55:25 INFO - TinderboxPrint: I/O write bytes / time
706,825,216 / 2,098 08:55:25 INFO - TinderboxPrint: CPU idle
2,257.5 (46.5%) 08:55:25 INFO - TinderboxPrint: CPU system
972.0 (20.0%) 08:55:25 INFO - TinderboxPrint: CPU user
1,624.2 (33.5%) 08:55:25 INFO - TinderboxPrint: Swap in / out
446,119,936 / 0 08:55:25 INFO - install - Wall time: 35s; CPU: 54.0%; Read bytes: 391961088; Write bytes: 386639872; Read time: 29465; Write time: 720 08:55:25 INFO - run-tests - Wall time: 1179s; CPU: 53.0%; Read bytes: 36077568; Write bytes: 295822336; Read time: 344; Write time: 1329 08:55:26 INFO - Running post-run listener: _upload_blobber_files 08:55:26 INFO - Blob upload gear active. 08:55:26 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 08:55:26 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 08:55:26 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'] 08:55:26 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 08:55:26 INFO - (blobuploader) - INFO - Open directory for files ... 08:55:26 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 08:55:26 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:55:26 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:55:28 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 08:55:28 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:55:28 INFO - (blobuploader) - INFO - Done attempting. 08:55:28 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 08:55:28 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:55:28 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:55:29 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 08:55:29 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:55:29 INFO - (blobuploader) - INFO - Done attempting. 08:55:29 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 08:55:29 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:55:29 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:55:32 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 08:55:32 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:55:32 INFO - (blobuploader) - INFO - Done attempting. 08:55:32 INFO - (blobuploader) - INFO - Iteration through files over. 08:55:32 INFO - Return code: 0 08:55:32 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 08:55:32 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 08:55:32 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/feb59f58f86df37e9b8d15036f95056d0adbe44070058f3ceaf1ca6dba69251c4f600a20e3c358d9de5e2f48b33d7327455ada9b709f76c1fcd748e96ffb8e17", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ff87cde512cf08a2062c56459c571de32e439126b409cdb7a6e02f59f1b058a401d7bd4dbcfc9a3c1ae0e7bac1950936cde57debc29ebebdd2ae4ca1af27af54", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/009579d1aaadb945d47541260ec5d509e33306910511c4fcea7c88bcf694ff0c354c49911c54566b0cac0249c6096f38be1968ba1537847594626862081a282d"} 08:55:32 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 08:55:32 INFO - Writing to file /builds/slave/test/properties/blobber_files 08:55:32 INFO - Contents: 08:55:32 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/feb59f58f86df37e9b8d15036f95056d0adbe44070058f3ceaf1ca6dba69251c4f600a20e3c358d9de5e2f48b33d7327455ada9b709f76c1fcd748e96ffb8e17", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ff87cde512cf08a2062c56459c571de32e439126b409cdb7a6e02f59f1b058a401d7bd4dbcfc9a3c1ae0e7bac1950936cde57debc29ebebdd2ae4ca1af27af54", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/009579d1aaadb945d47541260ec5d509e33306910511c4fcea7c88bcf694ff0c354c49911c54566b0cac0249c6096f38be1968ba1537847594626862081a282d"} 08:55:32 INFO - Running post-run listener: copy_logs_to_upload_dir 08:55:32 INFO - Copying logs to upload dir... 08:55:32 INFO - mkdir: /builds/slave/test/build/upload/logs 08:55:32 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1278.684811 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 18 secs) (at 2016-10-07 08:55:32.335925) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-07 08:55:32.339253) ========= 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.CC1Bw8tf70/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.ux1vVasRc3/Listeners TMPDIR=/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/feb59f58f86df37e9b8d15036f95056d0adbe44070058f3ceaf1ca6dba69251c4f600a20e3c358d9de5e2f48b33d7327455ada9b709f76c1fcd748e96ffb8e17", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ff87cde512cf08a2062c56459c571de32e439126b409cdb7a6e02f59f1b058a401d7bd4dbcfc9a3c1ae0e7bac1950936cde57debc29ebebdd2ae4ca1af27af54", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/009579d1aaadb945d47541260ec5d509e33306910511c4fcea7c88bcf694ff0c354c49911c54566b0cac0249c6096f38be1968ba1537847594626862081a282d"} build_url:https://queue.taskcluster.net/v1/task/Ugh6ElVORo6zhOLuW1U50w/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011266 build_url: 'https://queue.taskcluster.net/v1/task/Ugh6ElVORo6zhOLuW1U50w/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/feb59f58f86df37e9b8d15036f95056d0adbe44070058f3ceaf1ca6dba69251c4f600a20e3c358d9de5e2f48b33d7327455ada9b709f76c1fcd748e96ffb8e17", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ff87cde512cf08a2062c56459c571de32e439126b409cdb7a6e02f59f1b058a401d7bd4dbcfc9a3c1ae0e7bac1950936cde57debc29ebebdd2ae4ca1af27af54", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/009579d1aaadb945d47541260ec5d509e33306910511c4fcea7c88bcf694ff0c354c49911c54566b0cac0249c6096f38be1968ba1537847594626862081a282d"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-07 08:55:32.371828) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-07 08:55:32.372161) ========= 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.CC1Bw8tf70/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.ux1vVasRc3/Listeners TMPDIR=/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/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.005121 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-07 08:55:32.420741) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-07 08:55:32.421091) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-07 08:55:32.421410) ========= ========= Total master_lag: 0.16 =========