builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0098 starttime: 1479953846.14 results: success (0) buildid: 20161123161136 builduid: 5e2a2eacfbf348b0bf9ab61e80e729d5 revision: 34fce7c12173bdd6dda54c2ebf6d344252f1ac48 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-11-23 18:17:26.137332) ========= master: http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-11-23 18:17:26.137999) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-23 18:17:26.138460) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-23 18:17:26.163702) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-23 18:17:26.164069) ========= 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.ZKsv634csA/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.s7KhYF5v4a/Listeners TMPDIR=/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-11-23 18:17:26-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 145M=0s 2016-11-23 18:17:26 (145 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.094986 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-23 18:17:26.282571) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-23 18:17:26.282898) ========= 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.ZKsv634csA/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.s7KhYF5v4a/Listeners TMPDIR=/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/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.019538 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-23 18:17:26.333069) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-11-23 18:17:26.333655) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 34fce7c12173bdd6dda54c2ebf6d344252f1ac48 --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 34fce7c12173bdd6dda54c2ebf6d344252f1ac48 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.ZKsv634csA/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.s7KhYF5v4a/Listeners TMPDIR=/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-11-23 18:17:26,408 truncating revision to first 12 chars 2016-11-23 18:17:26,409 Setting DEBUG logging. 2016-11-23 18:17:26,409 attempt 1/10 2016-11-23 18:17:26,409 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/34fce7c12173?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-11-23 18:17:28,402 unpacking tar archive at: mozilla-central-34fce7c12173/testing/mozharness/ program finished with exit code 0 elapsedTime=2.235756 ========= master_lag: 0.18 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-11-23 18:17:28.749129) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-23 18:17:28.749455) ========= script_repo_revision: 34fce7c12173bdd6dda54c2ebf6d344252f1ac48 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-23 18:17:28.749866) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-23 18:17:28.750128) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-23 18:17:28.770383) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 12 secs) (at 2016-11-23 18:17:28.770768) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.ZKsv634csA/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.s7KhYF5v4a/Listeners TMPDIR=/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/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 18:17:28 INFO - MultiFileLogger online at 20161123 18:17:28 in /builds/slave/test 18:17:28 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 18:17:28 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 18:17:28 INFO - {'append_to_log': False, 18:17:28 INFO - 'base_work_dir': '/builds/slave/test', 18:17:28 INFO - 'blob_upload_branch': 'Firefox', 18:17:28 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 18:17:28 INFO - 'branch': 'Firefox', 18:17:28 INFO - 'buildbot_json_path': 'buildprops.json', 18:17:28 INFO - 'config_files': ('talos/mac_config.py',), 18:17:28 INFO - 'default_actions': ('clobber', 18:17:28 INFO - 'read-buildbot-config', 18:17:28 INFO - 'download-and-extract', 18:17:28 INFO - 'populate-webroot', 18:17:28 INFO - 'create-virtualenv', 18:17:28 INFO - 'install', 18:17:28 INFO - 'run-tests'), 18:17:28 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 18:17:28 INFO - 'download_minidump_stackwalk': True, 18:17:28 INFO - 'download_symbols': 'ondemand', 18:17:28 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 18:17:28 INFO - 'tooltool.py': '/tools/tooltool.py', 18:17:28 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 18:17:28 INFO - '/tools/misc-python/virtualenv.py')}, 18:17:28 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 18:17:28 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 18:17:28 INFO - 'installer_path': 'installer.exe', 18:17:28 INFO - 'log_level': 'info', 18:17:28 INFO - 'log_name': 'talos', 18:17:28 INFO - 'log_to_console': True, 18:17:28 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 18:17:28 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 18:17:28 INFO - 'opt_config_files': (), 18:17:28 INFO - 'pip_index': False, 18:17:28 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 18:17:28 INFO - 'cmd': ('bash', 18:17:28 INFO - '-c', 18:17:28 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 18:17:28 INFO - 'enabled': True, 18:17:28 INFO - 'halt_on_failure': False, 18:17:28 INFO - 'name': 'check_screen_resolution'},), 18:17:28 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 18:17:28 INFO - 'cmd': ('bash', 18:17:28 INFO - '-c', 18:17:28 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 18:17:28 INFO - 'enabled': True, 18:17:28 INFO - 'halt_on_failure': False, 18:17:28 INFO - 'name': 'check_screen_resolution'},), 18:17:28 INFO - 'run_cmd_checks_enabled': True, 18:17:28 INFO - 'sps_profile': False, 18:17:28 INFO - 'sps_profile_interval': 0, 18:17:28 INFO - 'suite': 'dromaeojs-e10s', 18:17:28 INFO - 'system_bits': '32', 18:17:28 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 18:17:28 INFO - 'title': 't-yosemite-r7-0098', 18:17:28 INFO - 'tooltool_cache': '/builds/tooltool_cache', 18:17:28 INFO - 'use_talos_json': True, 18:17:28 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 18:17:28 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 18:17:28 INFO - 'work_dir': 'build'} 18:17:28 INFO - [mozharness: 2016-11-24 02:17:28.973602Z] Running clobber step. 18:17:28 INFO - Running pre-action listener: _resource_record_pre_action 18:17:28 INFO - Running main action method: clobber 18:17:28 INFO - rmtree: /builds/slave/test/build 18:17:28 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 18:17:30 INFO - Running post-action listener: _resource_record_post_action 18:17:30 INFO - [mozharness: 2016-11-24 02:17:30.495597Z] Finished clobber step (success) 18:17:30 INFO - [mozharness: 2016-11-24 02:17:30.495717Z] Running read-buildbot-config step. 18:17:30 INFO - Running pre-action listener: _resource_record_pre_action 18:17:30 INFO - Running main action method: read_buildbot_config 18:17:30 INFO - Using buildbot properties: 18:17:30 INFO - { 18:17:30 INFO - "project": "", 18:17:30 INFO - "product": "firefox", 18:17:30 INFO - "script_repo_revision": "production", 18:17:30 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 18:17:30 INFO - "repository": "", 18:17:30 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 18:17:30 INFO - "buildid": "20161123161136", 18:17:30 INFO - "pgo_build": "False", 18:17:30 INFO - "basedir": "/builds/slave/test", 18:17:30 INFO - "buildnumber": 96, 18:17:30 INFO - "slavename": "t-yosemite-r7-0098", 18:17:30 INFO - "master": "http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/", 18:17:30 INFO - "platform": "yosemite_r7", 18:17:30 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 18:17:30 INFO - "branch": "mozilla-central", 18:17:30 INFO - "repo_path": "mozilla-central", 18:17:30 INFO - "revision": "34fce7c12173bdd6dda54c2ebf6d344252f1ac48", 18:17:30 INFO - "stage_platform": "macosx64", 18:17:30 INFO - "builduid": "5e2a2eacfbf348b0bf9ab61e80e729d5", 18:17:30 INFO - "slavebuilddir": "test" 18:17:30 INFO - } 18:17:30 INFO - Found installer url https://queue.taskcluster.net/v1/task/WAgGw4aTSRm02Ai6JIZioQ/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg. 18:17:30 INFO - Running post-action listener: _resource_record_post_action 18:17:30 INFO - [mozharness: 2016-11-24 02:17:30.498827Z] Finished read-buildbot-config step (success) 18:17:30 INFO - [mozharness: 2016-11-24 02:17:30.498935Z] Running download-and-extract step. 18:17:30 INFO - Running pre-action listener: _resource_record_pre_action 18:17:30 INFO - Running main action method: download_and_extract 18:17:30 INFO - mkdir: /builds/slave/test/build/tests 18:17:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:17:30 INFO - trying https://queue.taskcluster.net/v1/task/WAgGw4aTSRm02Ai6JIZioQ/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json 18:17:30 INFO - Downloading https://queue.taskcluster.net/v1/task/WAgGw4aTSRm02Ai6JIZioQ/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json 18:17:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/WAgGw4aTSRm02Ai6JIZioQ/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json'}, attempt #1 18:17:31 INFO - Downloaded 1199 bytes. 18:17:31 INFO - Reading from file /builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json 18:17:31 INFO - Using the following test package requirements: 18:17:31 INFO - {u'common': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 18:17:31 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 18:17:31 INFO - u'firefox-53.0a1.en-US.mac.cppunittest.tests.zip'], 18:17:31 INFO - u'gtest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 18:17:31 INFO - u'firefox-53.0a1.en-US.mac.gtest.tests.zip'], 18:17:31 INFO - u'jittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 18:17:31 INFO - u'jsshell-mac.zip'], 18:17:31 INFO - u'mochitest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 18:17:31 INFO - u'firefox-53.0a1.en-US.mac.mochitest.tests.zip'], 18:17:31 INFO - u'mozbase': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 18:17:31 INFO - u'reftest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 18:17:31 INFO - u'firefox-53.0a1.en-US.mac.reftest.tests.zip'], 18:17:31 INFO - u'talos': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 18:17:31 INFO - u'firefox-53.0a1.en-US.mac.talos.tests.zip'], 18:17:31 INFO - u'web-platform': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 18:17:31 INFO - u'firefox-53.0a1.en-US.mac.web-platform.tests.zip'], 18:17:31 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 18:17:31 INFO - u'firefox-53.0a1.en-US.mac.xpcshell.tests.zip']} 18:17:31 INFO - Downloading packages: [u'firefox-53.0a1.en-US.mac.common.tests.zip'] for test suite category: common 18:17:31 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/WAgGw4aTSRm02Ai6JIZioQ/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 18:17:31 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/WAgGw4aTSRm02Ai6JIZioQ/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 18:17:31 INFO - Fetch https://queue.taskcluster.net/v1/task/WAgGw4aTSRm02Ai6JIZioQ/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 18:17:32 INFO - Http code: 200 18:17:32 INFO - via: 1.1 ae162f6796e551002447afd7c07ec67a.cloudfront.net (CloudFront) 18:17:32 INFO - x-amz-cf-id: 4lHEoKk9iecAplTUhY5mktYBEIsyyGJ8O-SrBng2JxtjcuEya24i5w== 18:17:32 INFO - x-amz-version-id: tz2kUGIK8PmQRLRR9E8M_tSdB20pmB9A 18:17:32 INFO - Expected file size: 16267861 18:17:32 INFO - Obtained file size: 16267861 18:17:38 INFO - Downloading packages: [u'firefox-53.0a1.en-US.mac.common.tests.zip', u'firefox-53.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 18:17:38 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/WAgGw4aTSRm02Ai6JIZioQ/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 18:17:38 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/WAgGw4aTSRm02Ai6JIZioQ/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 18:17:38 INFO - Fetch https://queue.taskcluster.net/v1/task/WAgGw4aTSRm02Ai6JIZioQ/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 18:17:39 INFO - Http code: 200 18:17:39 INFO - via: 1.1 441811a054e8d055b893175754efd0c3.cloudfront.net (CloudFront) 18:17:39 INFO - x-amz-cf-id: mpTCzN9pezHkNKJ03ZyPX2F8Y051IsynFrvs4hsb_tPlWWvE_NDKZA== 18:17:39 INFO - x-amz-version-id: tz2kUGIK8PmQRLRR9E8M_tSdB20pmB9A 18:17:39 INFO - Expected file size: 16267861 18:17:39 INFO - Obtained file size: 16267861 18:17:46 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/WAgGw4aTSRm02Ai6JIZioQ/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip 18:17:46 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/WAgGw4aTSRm02Ai6JIZioQ/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip'}, attempt #1 18:17:46 INFO - Fetch https://queue.taskcluster.net/v1/task/WAgGw4aTSRm02Ai6JIZioQ/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip into memory 18:17:46 INFO - Http code: 200 18:17:46 INFO - via: 1.1 e2af8a85927835558866752f53562ecd.cloudfront.net (CloudFront) 18:17:46 INFO - x-amz-cf-id: S0fzTBchFZv1YvIrQrFGmnyBHwrE6XlR3qikRPCwrplYwujs1ODqnA== 18:17:46 INFO - x-amz-version-id: rQTDQ.jCiCdskjLKh8s0.qtlGbdaejYj 18:17:46 INFO - Expected file size: 14409749 18:17:46 INFO - Obtained file size: 14409749 18:17:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:17:47 INFO - trying https://queue.taskcluster.net/v1/task/WAgGw4aTSRm02Ai6JIZioQ/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 18:17:47 INFO - Downloading https://queue.taskcluster.net/v1/task/WAgGw4aTSRm02Ai6JIZioQ/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg 18:17:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/WAgGw4aTSRm02Ai6JIZioQ/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg'}, attempt #1 18:17:50 INFO - Downloaded 107543562 bytes. 18:17:50 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/WAgGw4aTSRm02Ai6JIZioQ/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 18:17:50 INFO - mkdir: /builds/slave/test/properties 18:17:50 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 18:17:50 INFO - Writing to file /builds/slave/test/properties/build_url 18:17:50 INFO - Contents: 18:17:50 INFO - build_url:https://queue.taskcluster.net/v1/task/WAgGw4aTSRm02Ai6JIZioQ/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 18:17:50 INFO - Running post-action listener: _resource_record_post_action 18:17:50 INFO - Running post-action listener: set_extra_try_arguments 18:17:50 INFO - [mozharness: 2016-11-24 02:17:50.764474Z] Finished download-and-extract step (success) 18:17:50 INFO - [mozharness: 2016-11-24 02:17:50.764615Z] Running populate-webroot step. 18:17:50 INFO - Running pre-action listener: _resource_record_pre_action 18:17:50 INFO - Running main action method: populate_webroot 18:17:50 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 18:17:50 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 18:17:50 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 18:17:50 INFO - '--tppagecycles', 18:17:50 INFO - '7'], 18:17:50 INFO - 'tests': ['tsvgm']}}, 18:17:50 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 18:17:50 INFO - 'tests': ['tresize', 'tcanvasmark']}, 18:17:50 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 18:17:50 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 18:17:50 INFO - 'tests': ['dromaeo_css', 'kraken']}, 18:17:50 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 18:17:50 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 18:17:50 INFO - 'talos_options': ['--disable-e10s'], 18:17:50 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:17:50 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 18:17:50 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:17:50 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 18:17:50 INFO - 'talos_options': ['--disable-e10s'], 18:17:50 INFO - 'tests': ['damp', 'tps']}, 18:17:50 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 18:17:50 INFO - 'tests': ['damp', 'tps']}, 18:17:50 INFO - 'g3': {'talos_options': ['--disable-e10s'], 18:17:50 INFO - 'tests': ['dromaeo_dom']}, 18:17:50 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 18:17:50 INFO - 'g4': {'talos_options': ['--disable-e10s'], 18:17:50 INFO - 'tests': ['basic_compositor_video']}, 18:17:50 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 18:17:50 INFO - 'other': {'talos_options': ['--disable-e10s'], 18:17:50 INFO - 'tests': ['a11yr', 18:17:50 INFO - 'ts_paint', 18:17:50 INFO - 'tpaint', 18:17:50 INFO - 'sessionrestore', 18:17:50 INFO - 'sessionrestore_no_auto_restore', 18:17:50 INFO - 'tabpaint']}, 18:17:50 INFO - 'other-e10s': {'tests': ['a11yr', 18:17:50 INFO - 'ts_paint', 18:17:50 INFO - 'tpaint', 18:17:50 INFO - 'sessionrestore', 18:17:50 INFO - 'sessionrestore_no_auto_restore', 18:17:50 INFO - 'tabpaint']}, 18:17:50 INFO - 'other-e10s_l64': {'tests': ['a11yr', 18:17:50 INFO - 'ts_paint', 18:17:50 INFO - 'tpaint', 18:17:50 INFO - 'sessionrestore', 18:17:50 INFO - 'sessionrestore_no_auto_restore', 18:17:50 INFO - 'tabpaint']}, 18:17:50 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 18:17:50 INFO - 'ts_paint', 18:17:50 INFO - 'tpaint', 18:17:50 INFO - 'sessionrestore', 18:17:50 INFO - 'sessionrestore_no_auto_restore', 18:17:50 INFO - 'tabpaint']}, 18:17:50 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 18:17:50 INFO - 'tests': ['a11yr', 18:17:50 INFO - 'ts_paint', 18:17:50 INFO - 'tpaint', 18:17:50 INFO - 'sessionrestore', 18:17:50 INFO - 'sessionrestore_no_auto_restore', 18:17:50 INFO - 'tabpaint']}, 18:17:50 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 18:17:50 INFO - 'tests': ['a11yr', 18:17:50 INFO - 'ts_paint', 18:17:50 INFO - 'tpaint', 18:17:50 INFO - 'sessionrestore', 18:17:50 INFO - 'sessionrestore_no_auto_restore', 18:17:50 INFO - 'tabpaint']}, 18:17:50 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 18:17:50 INFO - 'tests': ['tsvgx', 18:17:50 INFO - 'tsvgr_opacity', 18:17:50 INFO - 'tart', 18:17:50 INFO - 'tscrollx', 18:17:50 INFO - 'cart', 18:17:50 INFO - 'tsvg_static']}, 18:17:50 INFO - 'svgr-e10s': {'tests': ['tsvgx', 18:17:50 INFO - 'tsvgr_opacity', 18:17:50 INFO - 'tart', 18:17:50 INFO - 'tscrollx', 18:17:50 INFO - 'cart', 18:17:50 INFO - 'tsvg_static']}, 18:17:50 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 18:17:50 INFO - 'talos_options': ['--disable-e10s'], 18:17:50 INFO - 'tests': ['tp5o']}, 18:17:50 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 18:17:50 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 18:17:50 INFO - 'talos_options': ['--disable-e10s', 18:17:50 INFO - '--xperf_path', 18:17:50 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:17:50 INFO - 'tests': ['tp5n']}, 18:17:50 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 18:17:50 INFO - 'talos_options': ['--xperf_path', 18:17:50 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:17:50 INFO - 'tests': ['tp5n']}}, 18:17:50 INFO - 'talos.zip': {'path': '', 18:17:50 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 18:17:50 INFO - Running post-action listener: _resource_record_post_action 18:17:50 INFO - [mozharness: 2016-11-24 02:17:50.782955Z] Finished populate-webroot step (success) 18:17:50 INFO - [mozharness: 2016-11-24 02:17:50.783083Z] Running create-virtualenv step. 18:17:50 INFO - Running pre-action listener: _resource_record_pre_action 18:17:50 INFO - Running main action method: create_virtualenv 18:17:50 INFO - Creating virtualenv /builds/slave/test/build/venv 18:17:50 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 18:17:50 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 18:17:50 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 18:17:50 INFO - Using real prefix '/tools/python27' 18:17:50 INFO - New python executable in /builds/slave/test/build/venv/bin/python 18:17:51 INFO - Installing distribute.............................................................................................................................................................................................done. 18:17:54 INFO - Installing pip.................done. 18:17:54 INFO - Return code: 0 18:17:54 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 18:17:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 18:17:54 INFO - Reading from file tmpfile_stdout 18:17:54 INFO - Output received: 18:17:54 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) 18:17:54 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 18:17:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:17:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:17:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:17:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:17:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:17:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:17:54 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 0x1076693f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1075df570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd168dc78a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10747fd30>, '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 0x1072e6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107477b20>, '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.ZKsv634csA/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.s7KhYF5v4a/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/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 18:17:54 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 18:17:54 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 18:17:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZKsv634csA/Render', 18:17:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:17:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:17:54 INFO - 'HOME': '/Users/cltbld', 18:17:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:17:54 INFO - 'LOGNAME': 'cltbld', 18:17:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:17:54 INFO - 'MOZ_NO_REMOTE': '1', 18:17:54 INFO - 'NO_EM_RESTART': '1', 18:17:54 INFO - 'PAGER': '/bin/cat', 18:17:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:17:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:17:54 INFO - 'PWD': '/builds/slave/test', 18:17:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:17:54 INFO - 'SHELL': '/bin/bash', 18:17:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.s7KhYF5v4a/Listeners', 18:17:54 INFO - 'TMPDIR': '/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/', 18:17:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:17:54 INFO - 'USER': 'cltbld', 18:17:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:17:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:17:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:17:54 INFO - 'XPC_FLAGS': '0x0', 18:17:54 INFO - 'XPC_SERVICE_NAME': '0', 18:17:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:17:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:17:54 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 18:17:54 INFO - Cleaning up... 18:17:54 INFO - Return code: 0 18:17:54 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 18:17:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:17:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:17:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:17:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:17:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:17:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:17:54 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 0x1076693f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1075df570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd168dc78a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10747fd30>, '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 0x1072e6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107477b20>, '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.ZKsv634csA/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.s7KhYF5v4a/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/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 18:17:54 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 18:17:54 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 18:17:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZKsv634csA/Render', 18:17:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:17:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:17:54 INFO - 'HOME': '/Users/cltbld', 18:17:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:17:54 INFO - 'LOGNAME': 'cltbld', 18:17:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:17:54 INFO - 'MOZ_NO_REMOTE': '1', 18:17:54 INFO - 'NO_EM_RESTART': '1', 18:17:54 INFO - 'PAGER': '/bin/cat', 18:17:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:17:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:17:54 INFO - 'PWD': '/builds/slave/test', 18:17:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:17:54 INFO - 'SHELL': '/bin/bash', 18:17:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.s7KhYF5v4a/Listeners', 18:17:54 INFO - 'TMPDIR': '/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/', 18:17:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:17:54 INFO - 'USER': 'cltbld', 18:17:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:17:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:17:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:17:54 INFO - 'XPC_FLAGS': '0x0', 18:17:54 INFO - 'XPC_SERVICE_NAME': '0', 18:17:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:17:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:17:55 INFO - Downloading/unpacking psutil>=3.1.1 18:17: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 18:17: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 18:17: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 18:17: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 18:17: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 18:17: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 18:17:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 18:17:58 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:17:58 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:17:58 INFO - Installing collected packages: psutil 18:17:58 INFO - Running setup.py install for psutil 18:17:58 INFO - building 'psutil._psutil_osx' extension 18:17:58 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 18:17:59 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 18:17:59 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 18:17:59 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 18:17:59 INFO - building 'psutil._psutil_posix' extension 18:17:59 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 18:17:59 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 18:17:59 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 18:17:59 INFO - ^ 18:17:59 INFO - 1 warning generated. 18:17:59 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 18:17:59 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:17:59 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:17:59 INFO - Successfully installed psutil 18:17:59 INFO - Cleaning up... 18:17:59 INFO - Return code: 0 18:17:59 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 18:17:59 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')]} 18:17:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:17:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:17:59 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')]} 18:17:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:17:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:17:59 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 0x1076693f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1075df570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd168dc78a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10747fd30>, '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 0x1072e6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107477b20>, '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.ZKsv634csA/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.s7KhYF5v4a/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/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 18:17:59 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 18:17:59 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 18:17:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZKsv634csA/Render', 18:17:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:17:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:17:59 INFO - 'HOME': '/Users/cltbld', 18:17:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:17:59 INFO - 'LOGNAME': 'cltbld', 18:17:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:17:59 INFO - 'MOZ_NO_REMOTE': '1', 18:17:59 INFO - 'NO_EM_RESTART': '1', 18:17:59 INFO - 'PAGER': '/bin/cat', 18:17:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:17:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:17:59 INFO - 'PWD': '/builds/slave/test', 18:17:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:17:59 INFO - 'SHELL': '/bin/bash', 18:17:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.s7KhYF5v4a/Listeners', 18:17:59 INFO - 'TMPDIR': '/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/', 18:17:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:17:59 INFO - 'USER': 'cltbld', 18:17:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:17:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:17:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:17:59 INFO - 'XPC_FLAGS': '0x0', 18:17:59 INFO - 'XPC_SERVICE_NAME': '0', 18:17:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:17:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:17:59 INFO - Downloading/unpacking mozsystemmonitor==0.3 18:17:59 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 18:17:59 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 18:17:59 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 18:17:59 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 18:17:59 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 18:17:59 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 18:18:02 INFO - Downloading mozsystemmonitor-0.3.tar.gz 18:18:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 18:18:02 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 18:18:02 INFO - Installing collected packages: mozsystemmonitor 18:18:02 INFO - Running setup.py install for mozsystemmonitor 18:18:02 INFO - Successfully installed mozsystemmonitor 18:18:02 INFO - Cleaning up... 18:18:02 INFO - Return code: 0 18:18:02 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 18:18:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:18:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:18:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:18:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:18:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:18:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:18:02 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 0x1076693f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1075df570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd168dc78a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10747fd30>, '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 0x1072e6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107477b20>, '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.ZKsv634csA/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.s7KhYF5v4a/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/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 18:18:02 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 18:18:02 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 18:18:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZKsv634csA/Render', 18:18:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:18:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:18:02 INFO - 'HOME': '/Users/cltbld', 18:18:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:18:02 INFO - 'LOGNAME': 'cltbld', 18:18:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:18:02 INFO - 'MOZ_NO_REMOTE': '1', 18:18:02 INFO - 'NO_EM_RESTART': '1', 18:18:02 INFO - 'PAGER': '/bin/cat', 18:18:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:18:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:18:02 INFO - 'PWD': '/builds/slave/test', 18:18:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:18:02 INFO - 'SHELL': '/bin/bash', 18:18:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.s7KhYF5v4a/Listeners', 18:18:02 INFO - 'TMPDIR': '/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/', 18:18:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:18:02 INFO - 'USER': 'cltbld', 18:18:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:18:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:18:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:18:02 INFO - 'XPC_FLAGS': '0x0', 18:18:02 INFO - 'XPC_SERVICE_NAME': '0', 18:18:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:18:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:18:03 INFO - Downloading/unpacking jsonschema==2.5.1 18:18:03 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 18:18:03 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 18:18:03 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 18:18:03 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 18:18:03 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 18:18:03 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 18:18:05 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 18:18:05 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 18:18:05 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 18:18:05 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 18:18:05 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 18:18:05 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 18:18:05 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 18:18:05 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 18:18:05 INFO - Downloading functools32-3.2.3-2.tar.gz 18:18:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 18:18:06 INFO - warning: no files found matching '*.txt' 18:18:06 INFO - no previously-included directories found matching 'build' 18:18:06 INFO - no previously-included directories found matching 'dist' 18:18:06 INFO - no previously-included directories found matching '.git*' 18:18:06 INFO - Installing collected packages: jsonschema, functools32 18:18:06 INFO - Running setup.py install for functools32 18:18:06 INFO - warning: no files found matching '*.txt' 18:18:06 INFO - no previously-included directories found matching 'build' 18:18:06 INFO - no previously-included directories found matching 'dist' 18:18:06 INFO - no previously-included directories found matching '.git*' 18:18:06 INFO - Successfully installed jsonschema functools32 18:18:06 INFO - Cleaning up... 18:18:06 INFO - Return code: 0 18:18:06 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 18:18:06 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')]} 18:18:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:18:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:18:06 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')]} 18:18:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:18:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:18:06 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 0x1076693f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1075df570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd168dc78a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10747fd30>, '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 0x1072e6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107477b20>, '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.ZKsv634csA/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.s7KhYF5v4a/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/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 18:18:06 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 18:18:06 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 18:18:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZKsv634csA/Render', 18:18:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:18:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:18:06 INFO - 'HOME': '/Users/cltbld', 18:18:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:18:06 INFO - 'LOGNAME': 'cltbld', 18:18:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:18:06 INFO - 'MOZ_NO_REMOTE': '1', 18:18:06 INFO - 'NO_EM_RESTART': '1', 18:18:06 INFO - 'PAGER': '/bin/cat', 18:18:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:18:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:18:06 INFO - 'PWD': '/builds/slave/test', 18:18:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:18:06 INFO - 'SHELL': '/bin/bash', 18:18:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.s7KhYF5v4a/Listeners', 18:18:06 INFO - 'TMPDIR': '/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/', 18:18:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:18:06 INFO - 'USER': 'cltbld', 18:18:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:18:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:18:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:18:06 INFO - 'XPC_FLAGS': '0x0', 18:18:06 INFO - 'XPC_SERVICE_NAME': '0', 18:18:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:18:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:18:06 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 18:18:06 INFO - Cleaning up... 18:18:06 INFO - Return code: 0 18:18:06 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 18:18:06 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')]} 18:18:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:18:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:18:06 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')]} 18:18:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:18:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:18:06 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 0x1076693f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1075df570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd168dc78a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10747fd30>, '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 0x1072e6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107477b20>, '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.ZKsv634csA/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.s7KhYF5v4a/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/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 18:18:06 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 18:18:06 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 18:18:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZKsv634csA/Render', 18:18:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:18:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:18:06 INFO - 'HOME': '/Users/cltbld', 18:18:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:18:06 INFO - 'LOGNAME': 'cltbld', 18:18:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:18:06 INFO - 'MOZ_NO_REMOTE': '1', 18:18:06 INFO - 'NO_EM_RESTART': '1', 18:18:06 INFO - 'PAGER': '/bin/cat', 18:18:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:18:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:18:06 INFO - 'PWD': '/builds/slave/test', 18:18:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:18:06 INFO - 'SHELL': '/bin/bash', 18:18:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.s7KhYF5v4a/Listeners', 18:18:06 INFO - 'TMPDIR': '/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/', 18:18:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:18:06 INFO - 'USER': 'cltbld', 18:18:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:18:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:18:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:18:06 INFO - 'XPC_FLAGS': '0x0', 18:18:06 INFO - 'XPC_SERVICE_NAME': '0', 18:18:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:18:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:18:06 INFO - Downloading/unpacking blobuploader==1.2.4 18:18: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 18:18: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 18:18: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 18:18: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 18:18: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 18:18: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 18:18:09 INFO - Downloading blobuploader-1.2.4.tar.gz 18:18:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 18:18:09 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 18:18:09 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 18:18:09 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 18:18:09 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 18:18:09 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 18:18:09 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 18:18:09 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 18:18:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 18:18:10 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 18:18:10 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 18:18:10 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 18:18:10 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 18:18:10 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 18:18:10 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 18:18:10 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 18:18:10 INFO - Downloading docopt-0.6.1.tar.gz 18:18:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 18:18:10 INFO - Installing collected packages: blobuploader, requests, docopt 18:18:10 INFO - Running setup.py install for blobuploader 18:18:10 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 18:18:10 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 18:18:10 INFO - Running setup.py install for requests 18:18:11 INFO - Running setup.py install for docopt 18:18:11 INFO - Successfully installed blobuploader requests docopt 18:18:11 INFO - Cleaning up... 18:18:11 INFO - Return code: 0 18:18:11 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:18: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')]} 18:18:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:18:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:18: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')]} 18:18:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:18:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:18:11 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 0x1076693f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1075df570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd168dc78a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10747fd30>, '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 0x1072e6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107477b20>, '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.ZKsv634csA/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.s7KhYF5v4a/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/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 18:18:11 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 18:18:11 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 18:18:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZKsv634csA/Render', 18:18:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:18:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:18:11 INFO - 'HOME': '/Users/cltbld', 18:18:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:18:11 INFO - 'LOGNAME': 'cltbld', 18:18:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:18:11 INFO - 'MOZ_NO_REMOTE': '1', 18:18:11 INFO - 'NO_EM_RESTART': '1', 18:18:11 INFO - 'PAGER': '/bin/cat', 18:18:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:18:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:18:11 INFO - 'PWD': '/builds/slave/test', 18:18:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:18:11 INFO - 'SHELL': '/bin/bash', 18:18:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.s7KhYF5v4a/Listeners', 18:18:11 INFO - 'TMPDIR': '/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/', 18:18:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:18:11 INFO - 'USER': 'cltbld', 18:18:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:18:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:18:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:18:11 INFO - 'XPC_FLAGS': '0x0', 18:18:11 INFO - 'XPC_SERVICE_NAME': '0', 18:18:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:18:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:18:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:18:11 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-hkibbr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:18:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:18:12 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-DwwLf5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:18:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:18:12 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-BkjtA6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:18:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:18:12 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-Dsmym_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:18:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:18:12 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-of6kJP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:18:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:18:12 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-Q48MHE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:18:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:18:12 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-scenJk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:18:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:18:12 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-KAWn8N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:18:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:18:12 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-taRwr_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:18:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:18:12 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-d2Qk_1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:18:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:18:12 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-LbLFta-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:18:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:18:13 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-IYzqyz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:18:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:18:13 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-DLuWha-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:18:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:18:13 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-KYGwTu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:18:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:18:13 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-y_OfCq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:18:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:18:13 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-pFb2P1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:18:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:18:13 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-kUGttP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:18:13 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 18:18:13 INFO - Running setup.py install for manifestparser 18:18:13 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 18:18:13 INFO - Running setup.py install for mozcrash 18:18:13 INFO - Running setup.py install for mozdebug 18:18:14 INFO - Running setup.py install for mozdevice 18:18:14 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 18:18:14 INFO - Installing dm script to /builds/slave/test/build/venv/bin 18:18:14 INFO - Running setup.py install for mozfile 18:18:14 INFO - Running setup.py install for mozhttpd 18:18:14 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 18:18:14 INFO - Running setup.py install for mozinfo 18:18:14 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 18:18:14 INFO - Running setup.py install for mozInstall 18:18:14 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 18:18:14 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 18:18:14 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 18:18:14 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 18:18:14 INFO - Running setup.py install for mozleak 18:18:15 INFO - Running setup.py install for mozlog 18:18:15 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 18:18:15 INFO - Running setup.py install for moznetwork 18:18:15 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 18:18:15 INFO - Running setup.py install for mozprocess 18:18:15 INFO - Running setup.py install for mozprofile 18:18:15 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 18:18:15 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 18:18:15 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 18:18:15 INFO - Running setup.py install for mozrunner 18:18:15 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 18:18:15 INFO - Running setup.py install for mozscreenshot 18:18:15 INFO - Running setup.py install for moztest 18:18:16 INFO - Running setup.py install for mozversion 18:18:16 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 18:18:16 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 18:18:16 INFO - Cleaning up... 18:18:16 INFO - Return code: 0 18:18:16 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:18: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')]} 18:18:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:18:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:18: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')]} 18:18:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:18:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:18:16 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 0x1076693f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1075df570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd168dc78a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10747fd30>, '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 0x1072e6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107477b20>, '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.ZKsv634csA/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.s7KhYF5v4a/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/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 18:18:16 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 18:18:16 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 18:18:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZKsv634csA/Render', 18:18:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:18:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:18:16 INFO - 'HOME': '/Users/cltbld', 18:18:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:18:16 INFO - 'LOGNAME': 'cltbld', 18:18:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:18:16 INFO - 'MOZ_NO_REMOTE': '1', 18:18:16 INFO - 'NO_EM_RESTART': '1', 18:18:16 INFO - 'PAGER': '/bin/cat', 18:18:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:18:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:18:16 INFO - 'PWD': '/builds/slave/test', 18:18:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:18:16 INFO - 'SHELL': '/bin/bash', 18:18:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.s7KhYF5v4a/Listeners', 18:18:16 INFO - 'TMPDIR': '/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/', 18:18:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:18:16 INFO - 'USER': 'cltbld', 18:18:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:18:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:18:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:18:16 INFO - 'XPC_FLAGS': '0x0', 18:18:16 INFO - 'XPC_SERVICE_NAME': '0', 18:18:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:18:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:18:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:18:16 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-kCDv_M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:18:16 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)) 18:18:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:18:16 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-EPT2YC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:18:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 18:18:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:18:16 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-Q7TKQy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:18:16 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)) 18:18:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:18:16 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-VxRKDg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:18:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 18:18:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:18:17 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-MLz5nF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:18:17 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)) 18:18:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:18:17 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-0xrTA4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:18:17 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)) 18:18:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:18:17 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-1FEpsN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:18:17 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)) 18:18:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:18:17 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-oPbO3F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:18:17 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)) 18:18:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:18:17 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-OyoXRb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:18:17 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)) 18:18:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:18:17 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-TWV9Y6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:18:17 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)) 18:18:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:18:17 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-r5b1aq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:18:17 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)) 18:18:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:18:17 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-Ym2m1l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:18:17 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)) 18:18:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:18:17 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-P0sLrj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:18:18 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)) 18:18:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:18:18 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-tQUuVQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:18:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 18:18:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:18:18 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-Z8wln8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:18:18 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)) 18:18:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:18:18 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-Pk2XzD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:18:18 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)) 18:18:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:18:18 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-FNZ51i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:18:18 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)) 18:18:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 18:18:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 18:18:18 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)) 18:18:18 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 18:18:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 18:18:18 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 18:18:18 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 18:18:18 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 18:18:18 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 18:18:18 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 18:18:18 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 18:18:18 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 18:18:20 INFO - Downloading blessings-1.6.tar.gz 18:18:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 18:18:21 INFO - Installing collected packages: blessings 18:18:21 INFO - Running setup.py install for blessings 18:18:21 INFO - Successfully installed blessings 18:18:21 INFO - Cleaning up... 18:18:21 INFO - Return code: 0 18:18:21 INFO - Done creating virtualenv /builds/slave/test/build/venv. 18:18:21 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:18:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:18:21 INFO - Reading from file tmpfile_stdout 18:18:21 INFO - Current package versions: 18:18:21 INFO - blessings == 1.6 18:18:21 INFO - blobuploader == 1.2.4 18:18:21 INFO - docopt == 0.6.1 18:18:21 INFO - functools32 == 3.2.3-2 18:18:21 INFO - jsonschema == 2.5.1 18:18:21 INFO - manifestparser == 1.1 18:18:21 INFO - mozInstall == 1.12 18:18:21 INFO - mozcrash == 1.0 18:18:21 INFO - mozdebug == 0.1 18:18:21 INFO - mozdevice == 0.49 18:18:21 INFO - mozfile == 1.2 18:18:21 INFO - mozhttpd == 0.7 18:18:21 INFO - mozinfo == 0.9 18:18:21 INFO - mozleak == 0.1 18:18:21 INFO - mozlog == 3.4 18:18:21 INFO - moznetwork == 0.27 18:18:21 INFO - mozprocess == 0.23 18:18:21 INFO - mozprofile == 0.28 18:18:21 INFO - mozrunner == 6.13 18:18:21 INFO - mozscreenshot == 0.1 18:18:21 INFO - mozsystemmonitor == 0.3 18:18:21 INFO - moztest == 0.7 18:18:21 INFO - mozversion == 1.4 18:18:21 INFO - psutil == 3.1.1 18:18:21 INFO - requests == 1.2.3 18:18:21 INFO - wsgiref == 0.1.2 18:18:21 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:18:21 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')]} 18:18:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:18:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:18:21 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')]} 18:18:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:18:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:18:22 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 0x1076693f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1075df570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd168dc78a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10747fd30>, '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 0x1072e6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107477b20>, '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.ZKsv634csA/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.s7KhYF5v4a/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/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 18:18:22 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 18:18:22 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 18:18:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZKsv634csA/Render', 18:18:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:18:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:18:22 INFO - 'HOME': '/Users/cltbld', 18:18:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:18:22 INFO - 'LOGNAME': 'cltbld', 18:18:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:18:22 INFO - 'MOZ_NO_REMOTE': '1', 18:18:22 INFO - 'NO_EM_RESTART': '1', 18:18:22 INFO - 'PAGER': '/bin/cat', 18:18:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:18:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:18:22 INFO - 'PWD': '/builds/slave/test', 18:18:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:18:22 INFO - 'SHELL': '/bin/bash', 18:18:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.s7KhYF5v4a/Listeners', 18:18:22 INFO - 'TMPDIR': '/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/', 18:18:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:18:22 INFO - 'USER': 'cltbld', 18:18:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:18:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:18:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:18:22 INFO - 'XPC_FLAGS': '0x0', 18:18:22 INFO - 'XPC_SERVICE_NAME': '0', 18:18:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:18:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:18:22 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)) 18:18:22 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)) 18:18:22 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)) 18:18:22 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)) 18:18:22 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)) 18:18:22 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)) 18:18:22 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)) 18:18:22 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)) 18:18:22 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)) 18:18:22 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)) 18:18:22 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)) 18:18:22 INFO - Cleaning up... 18:18:22 INFO - Return code: 0 18:18:22 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 18:18:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:18:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:18:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:18:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:18:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:18:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:18:23 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 0x1076693f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1075df570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd168dc78a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10747fd30>, '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 0x1072e6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107477b20>, '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.ZKsv634csA/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.s7KhYF5v4a/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/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 18:18:23 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 18:18:23 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 18:18:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZKsv634csA/Render', 18:18:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:18:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:18:23 INFO - 'HOME': '/Users/cltbld', 18:18:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:18:23 INFO - 'LOGNAME': 'cltbld', 18:18:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:18:23 INFO - 'MOZ_NO_REMOTE': '1', 18:18:23 INFO - 'NO_EM_RESTART': '1', 18:18:23 INFO - 'PAGER': '/bin/cat', 18:18:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:18:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:18:23 INFO - 'PWD': '/builds/slave/test', 18:18:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:18:23 INFO - 'SHELL': '/bin/bash', 18:18:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.s7KhYF5v4a/Listeners', 18:18:23 INFO - 'TMPDIR': '/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/', 18:18:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:18:23 INFO - 'USER': 'cltbld', 18:18:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:18:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:18:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:18:23 INFO - 'XPC_FLAGS': '0x0', 18:18:23 INFO - 'XPC_SERVICE_NAME': '0', 18:18:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:18:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:18:23 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 18:18:23 INFO - Cleaning up... 18:18:23 INFO - Return code: 0 18:18:23 INFO - Running post-action listener: _resource_record_post_action 18:18:23 INFO - Running post-action listener: _start_resource_monitoring 18:18:23 INFO - Starting resource monitoring. 18:18:23 INFO - [mozharness: 2016-11-24 02:18:23.574474Z] Finished create-virtualenv step (success) 18:18:23 INFO - [mozharness: 2016-11-24 02:18:23.575536Z] Running install step. 18:18:23 INFO - Running pre-action listener: _resource_record_pre_action 18:18:23 INFO - Running main action method: install 18:18:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:18:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:18:23 INFO - Reading from file tmpfile_stdout 18:18:23 INFO - Detecting whether we're running mozinstall >=1.0... 18:18:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 18:18:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 18:18:23 INFO - Reading from file tmpfile_stdout 18:18:23 INFO - Output received: 18:18:23 INFO - Usage: mozinstall [options] installer 18:18:23 INFO - Options: 18:18:23 INFO - -h, --help show this help message and exit 18:18:23 INFO - -d DEST, --destination=DEST 18:18:23 INFO - Directory to install application into. [default: 18:18:23 INFO - "/builds/slave/test"] 18:18:23 INFO - --app=APP Application being installed. [default: firefox] 18:18:23 INFO - mkdir: /builds/slave/test/build/application 18:18:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 18:18:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 18:18:56 INFO - Reading from file tmpfile_stdout 18:18:56 INFO - Output received: 18:18:56 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 18:18:56 INFO - Running post-action listener: _resource_record_post_action 18:18:56 INFO - [mozharness: 2016-11-24 02:18:56.318946Z] Finished install step (success) 18:18:56 INFO - [mozharness: 2016-11-24 02:18:56.319076Z] Running run-tests step. 18:18:56 INFO - Running pre-action listener: _resource_record_pre_action 18:18:56 INFO - Running main action method: run_tests 18:18:56 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 18:18:56 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 18:18:56 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 18:18:56 INFO - 2016-11-23 18:18:56.333 screenresolution[1892:14570] starting screenresolution argv=screenresolution get 18:18:56 INFO - 2016-11-23 18:18:56.336 screenresolution[1892:14570] Display 0: 1600x1200x32@60 18:18:56 INFO - 2016-11-23 18:18:56.342 screenresolution[1893:14572] starting screenresolution argv=screenresolution list 18:18:56 INFO - Available Modes on Display 0 18:18:56 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 18:18:56 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 18:18:56 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 18:18:56 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 18:18:56 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 18:18:56 INFO - Intel Iris: 18:18:56 INFO - Chipset Model: Intel Iris 18:18:56 INFO - Type: GPU 18:18:56 INFO - Bus: Built-In 18:18:56 INFO - VRAM (Dynamic, Max): 1536 MB 18:18:56 INFO - Vendor: Intel (0x8086) 18:18:56 INFO - Device ID: 0x0a2e 18:18:56 INFO - Revision ID: 0x0009 18:18:56 INFO - Displays: 18:18:56 INFO - Display: 18:18:56 INFO - Resolution: 1600 x 1200 @ 60 Hz 18:18:56 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 18:18:56 INFO - Display Serial Number: 1600x1200 60 18:18:56 INFO - Main Display: Yes 18:18:56 INFO - Mirror: Off 18:18:56 INFO - Online: Yes 18:18:56 INFO - Rotation: Supported 18:18:56 INFO - Return code: 0 18:18:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 18:18:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 18:18:56 INFO - Python 2.7.3 18:18:56 INFO - Return code: 0 18:18:56 INFO - grabbing minidump binary from tooltool 18:18:56 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')]} 18:18:56 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 0x10747fd30>, '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 0x1072e6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107477b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 18:18:56 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 18:18:56 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 18:18:56 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 18:18:56 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 18:18:56 INFO - Return code: 0 18:18:56 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 18:18:56 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 18:18:56 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 18:18:56 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 18:18:56 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 18:18:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/WAgGw4aTSRm02Ai6JIZioQ/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0098', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 18:18:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/WAgGw4aTSRm02Ai6JIZioQ/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0098 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 18:18:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZKsv634csA/Render', 18:18:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:18:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:18:56 INFO - 'HOME': '/Users/cltbld', 18:18:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:18:56 INFO - 'LOGNAME': 'cltbld', 18:18:56 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 18:18:56 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 18:18:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:18:56 INFO - 'MOZ_NO_REMOTE': '1', 18:18:56 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 18:18:56 INFO - 'NO_EM_RESTART': '1', 18:18:56 INFO - 'PAGER': '/bin/cat', 18:18:56 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:18:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:18:56 INFO - 'PWD': '/builds/slave/test', 18:18:56 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 18:18:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:18:56 INFO - 'SHELL': '/bin/bash', 18:18:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.s7KhYF5v4a/Listeners', 18:18:56 INFO - 'TMPDIR': '/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/', 18:18:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:18:56 INFO - 'USER': 'cltbld', 18:18:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:18:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:18:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:18:56 INFO - 'XPC_FLAGS': '0x0', 18:18:56 INFO - 'XPC_SERVICE_NAME': '0', 18:18:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:18:56 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/WAgGw4aTSRm02Ai6JIZioQ/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0098', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 18:18:56 INFO - mozversion application_buildid: 20161123161136 18:18:56 INFO - mozversion application_changeset: 34fce7c12173bdd6dda54c2ebf6d344252f1ac48 18:18:56 INFO - mozversion application_display_name: Nightly 18:18:56 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 18:18:56 INFO - mozversion application_name: Firefox 18:18:56 INFO - mozversion application_remotingname: firefox 18:18:56 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 18:18:56 INFO - mozversion application_vendor: Mozilla 18:18:56 INFO - mozversion application_version: 53.0a1 18:18:56 INFO - mozversion platform_buildid: 20161123161136 18:18:56 INFO - mozversion platform_changeset: 34fce7c12173bdd6dda54c2ebf6d344252f1ac48 18:18:56 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 18:18:56 INFO - mozversion platform_version: 53.0a1 18:18:56 INFO - using testdate: 1479953936 18:18:56 INFO - actual date: 1479953936 18:18:56 INFO - starting webserver on 'localhost:51265' 18:18:56 INFO - SUITE-START | Running 2 tests 18:18:56 INFO - TEST-START | dromaeo_css 18:18:56 INFO - operating with platform_type : mac_ 18:18:56 INFO - Initialising browser for dromaeo_css test... 18:18:56 INFO - TEST-INFO | started process 1903 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/tmp0bCLcX/profile http://localhost:51265/getInfo.html) 18:18:59 INFO - PROCESS | 1903 | 2016-11-23 18:18:59.818 plugin-container[1905:14737] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8d3f, name = 'com.apple.tsm.portname' 18:18:59 INFO - PROCESS | 1903 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:18:59 INFO - PROCESS | 1903 | 2016-11-23 18:18:59.821 plugin-container[1905:14737] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x930f, name = 'com.apple.CFPasteboardClient' 18:18:59 INFO - PROCESS | 1903 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:18:59 INFO - PROCESS | 1903 | 2016-11-23 18:18:59.821 plugin-container[1905:14737] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 18:18:59 INFO - PROCESS | 1903 | __metrics Screen width/height:1600/1200 18:18:59 INFO - PROCESS | 1903 | colorDepth:24 18:18:59 INFO - PROCESS | 1903 | Browser inner width/height: 1024/689 18:18:59 INFO - PROCESS | 1903 | __metrics 18:19:00 ERROR - PROCESS | 1903 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3198: TypeError: this.tabs is undefined 18:19:00 INFO - TEST-INFO | 1903: exit 0 18:19:00 INFO - Browser initialized. 18:19:00 INFO - Running cycle 1/1 for dromaeo_css test... 18:19:00 INFO - TEST-INFO | started process 1906 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/tmp0bCLcX/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:19:03 INFO - PROCESS | 1906 | 2016-11-23 18:19:03.683 plugin-container[1907:14864] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x883f, name = 'com.apple.tsm.portname' 18:19:03 INFO - PROCESS | 1906 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:19:03 INFO - PROCESS | 1906 | 2016-11-23 18:19:03.684 plugin-container[1907:14864] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4707, name = 'com.apple.CFPasteboardClient' 18:19:03 INFO - PROCESS | 1906 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:19:03 INFO - PROCESS | 1906 | 2016-11-23 18:19:03.684 plugin-container[1907:14864] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 18:19:13 INFO - PROCESS | 1906 | RSS: Main: 241639424 18:19:13 INFO - PROCESS | 1906 | 18:20:03 INFO - PROCESS | 1906 | 1479954002997 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] 18:22:15 INFO - PROCESS | 1906 | Cycle 1(1): loaded http://localhost:51265/tests/dromaeo/cssquery-dojo.html (next: http://localhost:51265/tests/dromaeo/cssquery-ext.html) 18:22:18 INFO - PROCESS | 1906 | RSS: Main: 254205952 18:22:18 INFO - PROCESS | 1906 | 18:25:20 INFO - PROCESS | 1906 | Cycle 1(1): loaded http://localhost:51265/tests/dromaeo/cssquery-ext.html (next: http://localhost:51265/tests/dromaeo/cssquery-jquery.html) 18:25:21 INFO - PROCESS | 1906 | RSS: Main: 264306688 18:25:21 INFO - PROCESS | 1906 | 18:28:23 INFO - PROCESS | 1906 | Cycle 1(1): loaded http://localhost:51265/tests/dromaeo/cssquery-jquery.html (next: http://localhost:51265/tests/dromaeo/cssquery-mootools.html) 18:28:23 INFO - PROCESS | 1906 | RSS: Main: 270761984 18:28:23 INFO - PROCESS | 1906 | 18:31:26 INFO - PROCESS | 1906 | Cycle 1(1): loaded http://localhost:51265/tests/dromaeo/cssquery-mootools.html (next: http://localhost:51265/tests/dromaeo/cssquery-prototype.html) 18:31:26 INFO - PROCESS | 1906 | RSS: Main: 269328384 18:31:26 INFO - PROCESS | 1906 | 18:34:29 INFO - PROCESS | 1906 | Cycle 1(1): loaded http://localhost:51265/tests/dromaeo/cssquery-prototype.html (next: http://localhost:51265/tests/dromaeo/cssquery-yui.html) 18:34:30 INFO - PROCESS | 1906 | RSS: Main: 273793024 18:34:30 INFO - PROCESS | 1906 | 18:37:33 INFO - PROCESS | 1906 | Cycle 1(1): loaded http://localhost:51265/tests/dromaeo/cssquery-yui.html (next: http://localhost:51265/tests/dromaeo/cssquery-dojo.html) 18:37:33 INFO - PROCESS | 1906 | RSS: Main: 275881984 18:37:33 INFO - PROCESS | 1906 | 18:37:33 INFO - PROCESS | 1906 | __start_tp_report 18:37:33 INFO - PROCESS | 1906 | _x_x_mozilla_page_load 18:37:33 INFO - PROCESS | 1906 | _x_x_mozilla_page_load_details 18:37:33 INFO - PROCESS | 1906 | |i|pagename|runs| 18:37:33 INFO - PROCESS | 1906 | |0;dojo.html;2931.355101877587;3230.6930841570043;3310.2882880180773;3315.436375816111;3348.999999999999;4411.801468933898;4626.65300102492;5375.516203541681;5539;5578.497935185833;4535.750533720644;5343.465653434664;5449.182622606601;5547.55619550436;5620;4068.9593104068877;5089.618278629099;5092.083424983502;5166.380034395859;5169.095408303532;4126.885740849969;4637.281221410687;5082.720450375228;5100.642954993152;5213.426523082458;5184.948150518503;5196.636235463519;5201.817936372227;5218.47815218477;5224.138017227153;4931.975340123312;4976.800927962877;5035;5073.340465739467;5137.717425541594;5001.074801161797;5033.823816166453;5048.040872234264;5096.923546146811;5097.337346145015;5405.8107966221;5533.833984980457;5564.777408903639;5569.860753481195;5590.105583106683;5972.910406343908;6002.969985150046;6026.969865150646;6081.3006504252135;6095.359987201368;6063.484603808682;6123.969380153071;6140.662263575547;6167.660778657172;6189.752409903598;5670.206171136044;5671.120976248688;5679.290088738908;5681.829545113571;5688.516476099495;6106.236720409949;6161.7535298588455;6226.315105338409;6244.12582238487;6247.406496382836;5136.666116702402;5756.280464941882;5841.065429531254;5886.05823068307;5972.283326000821;6088.665123418255;6089.330173680892;6109.450149486567;6110.022396416641;6113.541484388688;5987.700614969234;6057.394260573996;6070.848228794315;6077.513798896078;6083.6349819011;6005.759769609254;6023.578349515492;6061.818145455643;6091.14723938649;6143.542582966849;4866.7079975201605;4873.780679869415;4876.683015603974;4888.462269150391;4905.583025442877;4368.27923392643;4413.889652758642;4416.205083085076;4431.911361772746;4448.933266001012;5441.102218134043;5515.365732940683;5558.861028474321;5571.777128914879;5631.493165615114;6176.227971503562;6199.132121502993;6200.503959683215;6219.533534984895;6228.626282423069;5692.316921969307;5699.914501282484;5706.457886500776;5727.656340619576;5745.253117094751;4476.37330773692;5168.681986093522;5193.7403129844115;5247.02929957959;5322.813701520428;6123.557162997564;6131.570790044654;6161.952468080437;6189.566730328834;6243.687815609202;6089.964705999902;6110.534509600366;6123.130109042633;6130.367562715148;6168.907466388097;5826.213461182686;5908.822735317947;6191.288001879661;6207.937920620736;6244.469220116341;5881.000229961002;6016.308124565556;6040.275166979991;6071.787487437919;6088;5312.628116031995;6101.237345331833;6102.511799055976;6103.969480152571;6125.479334256638;5889.587728858939;5922.318933322723;5958.582899197015;5966.164736936919;5996.280446346466;5993.160957465871;6016.7292471838;6024.518038556993;6026.517878569616;6047.153398524122;4884.7069175849565;5650.717464126859;5785.813908148735;5817.505512031525;5878.441548052928;5861.706914654165;5909.349971503218;5919.260092488439;5955.404459554184;5967.31375891778;5838.708064596838;5859.472647461749;5862.882742345215;5866.178734977021;5869.647821130746 18:37:33 INFO - PROCESS | 1906 | |1;ext.html;482.879280179955;492.8767808047988;504.97742072303606;509.4676063513628;514.0951924612681;34244.657553424455;37382;37879.05302367439;37917.62082379175;37944.81027594865;38568;38956.61043389565;38975.025624359405;38990.635327763535;39153;8396.11840756721;9109.817803643924;9297.209737172314;9346.205572526344;9363.204127649158;8387.329013678891;11069.889301107007;11352.943235283812;11354.43227838609;11557.942210288978;11251.774964500704;11252.718682032973;11266;11295.491702873349;11303.830442543327;9944.751381215456;11332.773344533145;11338.206325557214;11341.149413793999;11366.488508017159;9335.859962100574;11151;11209.887901120966;11223.270487418373;11243.66269011931;37083.814580926926;37285;37426.43860342097;37448.25103497915;37655;3320.236345640492;3488.4418493040994;3869.4582758413844;3922.4508568800393;3936.862209822671;22575.789087955967;23561.646575301213;28709;29516;29581.26046848846;2594.8962041518316;2699.9188929635884;3190.9361812763846;3276.967230327714;3301.7193538549254;329845;406559.9672001621;412835.93582032196;430257;435435;53644.463555363945;54081.918361632546;54135.729321353145;54212.45787542153;54485;56611.71694141503;56869.43130568724;57010.714946425;57104;57198;15011.174385408689;15542.766858497132;15559.533214003372;15578.9221053894;15599.844001559839;10237.48812559384;10896.891031089588;11141.610043648434;12159.696007599703;12204.633860984184;14530.346134423766;14895.553133406016;14934.133233345832;15000.62498437526;15017.849821501864;5610.410906854803;6736.2590115087305;6766.830829229309;6770.390664840188;6794.398968546679;949.5110018340561;991.0931497679543;1024.4314405505015;1040.38617215843;1047.664747280863;26766;28834.990775323182;28846.27884302867;29145;29206.7079329204;819.4059307002376;1031.6492392586556;1072.131573425528;1084.5173897615643;1100.5872797700863;14651.92674036623;14730.852691473163;14805.777913331309;15059.924700376429;15112.471063512945;419648;516051;518129;519385.8061419413;519662;51481.22778158331;51691.74154129205;51695.48304516982;51837;52002.69993250199;1292.825468561751;1337.5786627212397;1338.578347820453;1355.3562058022558;1355.966100847467;27249.31876703098;28180.166982584025;28212.43575128527;28500.002499912403;28502;46667.766661166475;46836.7658161707;46935;46939.295910562054;47057.76471117623;420156.49608760024;421281;421395;422246;424820.8758956185;22764.772352276264;22991.88504057469;24368.147114850894;24589.877050614632;24741.87629061843;41829;42367.57632423636;43369.78315108404;43397.349039763794;43539;35195.82402087973;35431.64568354283;35741.285174296885;35995.46006809847;36945.44581831221;31468.685313147493;35468.46797298094;36209;36477.81761091178;36700.44949325709;34571.65428345785;35242.295154097286;35705.64294357023;35957;36088.27823443569;25256.873715631304;25881;28725.276483411064;28868;30981.535276971314;23353.76646233584;23907.521849563258;24057.917393716976;24077.27768166888;24453.87773061123 18:37:33 INFO - PROCESS | 1906 | |2;jquery.html;348.27037356737634;355.19371027766954;371.927474142542;374.3617132788595;388.39992212032405;19065.14206860691;19591.608167836635;19855;20376;20662.793372066273;18358.173882175302;19017;19624;20142.19431222753;20181.19275228989;4893.877653058684;5225.843224703265;5441.319835020622;5445.782168713246;5456.836294911159;6500.934990650104;6678.599284042973;6682.398584127428;6690.565113267647;6697.3637504437;5553.389127196006;6406.551541392105;6526.738930442777;6603.580230250495;6622.768203112892;6300.68496575174;6549.21409430869;6570.737170513174;6575.80272591823;6604;5707.543396528268;5777.682227477487;5797.275340582427;5860.765569377219;5868.295804503487;12958.740825183444;12981.286029268387;13013.544525941546;13030.543930962465;13062.673433164247;1973.6546104431668;2420.322309753272;2457.31195265326;2753.435545713124;2936.3393236521824;8292;8564.100769419247;8571.742847714579;10108.584798128268;11909.464074116599;1485.6657252118187;1786.2676302715824;1820.3992682414903;1831.8733978603195;1906;82073.17926820775;103962;110043;110542.44728776385;111569.44215278953;9624.03759624046;11047.889521104848;11185.66443006711;11259.493322800494;11449.942750286194;13831.446742130403;14134.787978180335;14225.64435889111;14230;14260.215688137045;6991.650417479105;7972.60136993148;8060.5893968555265;8289.253967142986;8445.746627601182;8075.475094118863;8083;8100.027996640323;8245.628946697412;8278.089410164877;4341.717788343748;4460.553944605514;4486.573775491323;4498.145352383037;4530.569595888385;1871.5321169707572;1927.6530224559433;1928.421473557927;1948.9707654385195;1960.8724983134468;820.4789958376355;824.5877061469265;855.153398135841;879.4547380624055;970.5635659224388;18171.364002259856;20201.696974545393;20555.8972205141;20729;20894.37316880496;925.8240934222611;926.8923636254746;929.1683942871092;969.3360048366891;973.3478569358547;10105.595776169019;10644.787104258026;10914.126869850394;10928.398938058483;10962.506687198937;246391.76804116223;276056;277798.6110069437;279325;287261;110915;114816.27775583373;129449.6440284775;137411;139219;989.4113002763344;995.134123518403;1061.1669839176363;1064.1114669251263;1066.157735389034;16088.954217975794;17224.13879306055;18620.627587448445;18743.437696869114;19350.612987739885;51175.48824511707;51637.7418112907;52061.21908171456;52518;54454.18318725143;50355.99288014293;72509.63745181452;74055.82102085317;81272.18727812647;82335.588322058;7687.615619218905;7818.609069546614;8091.029076510857;8162.591870406574;8281.710140145065;9094.499012771541;9501.572429240563;10324.812646545442;12007.519699211934;12846.42191101384;5696.373398926197;6313.715882785194;6570;7698.34564062061;8049.154838741848;7553.546787192786;7692.499987500794;7747.203835308591;7783.922160778547;8159.673613055411;7938.126806051214;8073.515589064675;8128.105908349959;8339.666413343399;8399.49603023796;9723.756906077404;11095.778084438105;11503.194776365908;12372.257664540157;12517.812232816334;11359.375234362189;11507.597234096766;11846.99300559462;11899.940500297444;12006.879931200576 18:37:33 INFO - PROCESS | 1906 | |3;mootools.html;1444.0830072903707;1464.2678660669665;1647.546924595736;1755.2803350626245;1783.8840475369093;7512.737054203104;7837.059552853666;8134.5932703364815;8511.957440212806;8521.360897932656;7963.163867793885;8295.460795048333;8300.003999520051;8372.91627083729;8423.410361274713;2301.976980230197;2327.697399338084;2331.825113116515;2340.20433052762;2340.578695834749;1950.3076407875212;2328.836981411302;2351.9412014699683;2355.0815182079004;2364.538914911593;2292.587334279829;2302.827287953402;2319.246244970383;2325.4418939454577;2327.173853282086;2280.9771902281013;2283.3720726800098;2305.8616483010987;2312.2022902098747;2313.803326717231;2297.885105744706;2311.8959646815933;2332.2769941318074;2335.8364914455997;2336.824738144646;8317.293030092449;8459.577021148918;8473.15268473154;8487.787805304855;8509.021462531826;5054.444011158769;5178.689278643255;5218.321618189649;5223.059849227137;5232.686038837682;3035.05913166919;3241.222106694376;3265.330607225537;3603.2433189030335;3606.3688854450365;1022.4478781458004;1022.4734261855151;1072.84443755655;1074.484247561175;1092.983605245922;10661.413622250773;10832.40421776802;10940;10941.507632156492;10971.506282217319;3803.6386543278345;4401.493828209796;4493.303537951622;4583.793729282191;4640.373549570832;8010.759677209693;8314.293285070837;8550.700725474579;9452.10205030521;9454;5136.9743151284;5181.870453238624;5200.401953775288;5202.323697919246;5204.271402003723;3836.501254836853;4264.744115353089;4430.490493593214;4739.9763001185465;4768.427788665383;3163.1933856866353;3713.9814300928324;3718.5909549949483;3860.1700634363747;3868.5357757069332;1032.7108409645161;1046.7801761629992;1220.7741567809805;1260.287937315414;1263.3556885988212;809.2595275323014;855.4738835616175;887.973360799177;921.7972046149837;933.6638810028385;4932.605391568666;5514.420985796433;5670.5180059695385;5881.911771323434;6021.54838387114;958.5255298627225;971.4220039076738;975.4293738163254;997.33677104726;1040.0119886108228;3110.5178697301503;3112.7042930921525;3683.226522430266;3770.71719621024;3778.5843557208686;9333;9806;10065.144462720751;10150.238732095122;10170.084692377573;9905;9972.301938864355;9979.700608981742;9979.952105028868;9992.950035249778;7625.389968802482;7838;7853.41099417546;7854.253845884746;7867.567283799446;4208.642265407475;5490.5882058844945;5555.055730494456;5642.351129620146;5935.5548333875995;9166.129217724305;9350.719478415658;9362.906370936204;9393.154616084585;9393.624255029723;8016.478928869605;9349.172786380348;9467.66863159786;9563.760905977406;9589.952050239704;4959.578435832995;6789.558678686068;6839.965800170968;6944.513884028272;7121.643917804193;7597.506162099446;7851;7857.05715314166;7857.607119644109;7860.960695196487;5801.419858014165;7160.606166660676;7162.641867906688;7181;7245.673944672397;6974.093367862146;6983;7000.439964802906;7049.471289653399;7052.435805135679;6607.834804129743;6695.464362851058;6749.730010799513;6756.18925728896;6756.189257289157;4769.499202583679;4801.95198048015;4828.203346447937;4870.31815545831;4943.703377797344;4741.383620129361;4850.854474365775;4905.656604037683;4956.330895329084;4971.105201063699 18:37:33 INFO - PROCESS | 1906 | |4;prototype.html;249.9377645008713;286.2000708021081;296.9851507424629;298.7729325712459;306.21608681774654;4443;4468.787949964768;4501.549845015505;5217.765200565974;5483.999999999995;4925.655204135712;4932.914758753078;5386.905951476876;5403.729813509329;5404.027275090482;3785.6403641653997;4134.834606615732;4136.97931510342;4155.70910036296;4168.2080404723;3435.57055368079;3812.6759225465867;3840.423936409547;4162.62536371728;4181.581841815824;3511.4206155984234;3822.0507541154075;4054.5945405459365;4057.898552536181;4061.390791381287;3999.6600288975474;4058.1883623275316;4058.2492238935893;4074.653654439376;4078.6125318094732;3519.8768043118625;3521.6654417830264;3524.4713293006;3530.3998320285614;3532.205253817896;4162.271602469556;4262.039461847554;5180.611454140954;5228.92156617651;5356.7857285708915;4346.956530434687;4650.651201159926;4659.347691323217;4665.813367465297;4668.346431499593;5005.424376196747;5377.784888604451;5446.618736688433;5624;5693.4875861172695;3527.3121741260466;3720.962790372089;3833.463315135883;3871.4386413970096;3877.9224415511812;5503.394626591072;5761.625494342854;5911.763529458816;6022.69886505673;6083.48290395317;3120.672329405425;3401;3894.3838307102237;3904.4533765272886;3909.139989202371;3501.964980350164;3792.8140362095974;3996.200759848042;3997.5802540733384;4006.25884211416;3968.583298753647;4078.8579197824656;4438.622717069085;4540;4661.300804879227;5020.623453241022;5790.478856902899;5802.723400851806;5817.3019237690905;5856.707164641751;1040.9063184313447;1071.8552995345528;1185.205912038936;1254.2286493806243;1265.4938024789974;801.6512816924657;865.446114486729;868.4398562926876;871.694906782621;873.8208667223204;5156.61325400589;5177.86087060846;5184.507471790174;5192.8961420771375;5195.766190521435;5084.974575127175;5099.515546023122;5413.915642477675;5618.988582055269;5641.52047075995;4189.475366604061;4939.382577177853;4996.850094497171;5113.667611605234;5141;2377.97622023781;2452.852828830276;2537.885795139186;2544.8929715573786;2902.811317264371;4670.95329046712;5279.815206467755;5361.624686272002;5960.354205061325;5963.318794746104;5655.575831812631;5722.54219662426;5832.708364581754;5835.649861008353;5869.677167755729;5129.358830146231;5888.764449422061;5912.984377967267;5921.200637913913;5955.851103722442;4984.675996060245;5512.759629083424;5665.093585026376;5675.205471234113;5759.971200143972;4537.478190008192;5231.738413079407;5292.735363231746;5409.486098820606;5551.306086739158;5006.592879689999;5126.923096153486;5277.997180535686;5683.573731970201;5734.741936612946;3139.591853059179;3347.748918831146;3715.8327875245136;3722.4788529605335;3761.8683346082757;4919.120778976361;5361.185377676751;5464.590155738256;5558.638688485235;5561.610687251854;5000.924986125284;5019;5538.280023596906;5617.8033768817895;5794.232759008542;5416.674999500043;5660.981023415825;5759;5779.364269930388;5781.8554536136935;5332.866853258727;5626.690532020614;5644.6613203207935;5650.576206784425;5650.717464126859;4833.703990604435;5370.489803468664;5847.596576821617;5909.645421274738;5938.7825495772395;5497.862553436196;5652.689102099424;5700.885982280414;5714.914276285942;5776.306843178845 18:37:33 INFO - PROCESS | 1906 | |5;yui.html;652.3020368206019;697.6860412814235;728.497336837582;764.3503022430934;786.7010535996322;13844.861551384483;14456.710865782678;14710.338034788434;14792.556223313304;14806.555803325891;13304.933475332635;13411.865881341184;13422.39599218035;13422.798658020112;13475.258860762655;640.6700549217157;659.4757168051386;792.0455850699922;810.1250649298743;821.9876701849477;786.3669745854585;813.6501304439089;813.894193754811;816.4407380944043;819.2217393476192;868.5526953618892;868.8479516084692;869.695606537713;870.1994165367845;870.4559650218613;834.0616806093145;861.0442408926118;862.0948004595213;863.317978796753;863.7365603490932;625.7747211004034;631.3497097989078;636.6625688385163;637.0603360043945;637.0953246390137;11394.601188958346;12575.685607859785;13620.727585448236;13645.317734113354;13694.52069177584;12847.503642231048;13144.145630533985;13180.209187448689;13196.472141114344;13321;9569.569369378394;11297.71755706114;11326.017946859267;11473.254238474472;11539.249948753388;3053.282478617523;3520.278342939717;3638.308721342963;3660.652238037382;3679.6688298053305;61896.1431157066;64053.67973160104;89395.55302223512;89947;90544.42681475592;8327.6668933242;10198.43908585035;10490.265681402381;10661.626843060456;10857.348559086322;16252.51242462728;19937.900310498353;20129;20158;20240.98795060241;7457.105147382349;7498.175200727916;7583.734569290048;7605.163432022473;7848.13670496245;4114.773687447219;4138.151678905817;4151.667866570667;4180.247555439989;4188.853390131391;3368.9326213476083;3483.5645544306963;3483.6690514401093;3491.546099007113;3502.3695734767985;580.2804522392204;616.8612062285949;650.4828661214345;653.8888388973886;663.3996233408773;611.9632822030692;624.3880996623335;639.402158981354;653.3531803514486;658.7299207324974;7524.887126693104;8569.871451928226;8774.824503510023;9056.456612603133;9119.589618467186;568.8919105369966;618.9721462534106;656.5141795071708;660.5105487126594;685.1572565744162;3867.7485963412287;4111.188741391321;4393.340998850198;4474.574915383034;4500.189965806187;80237;110668;111326.88673113327;112018;115464;67885;101101.49449252707;102370;102952.48523757483;103130.48434757929;71196.28803711897;89794.20411591731;89927.55036224646;90275;92700.26647202081;7117.572945623279;8015.799605009922;9214.953925230599;9301;9557.283203759607;41942;45232;50163.49836501588;52485;52647.73676131595;74941.62529187319;75135.62432187804;76111;77661.61169194117;77857.61071194608;12160.087993400353;12442.253464792142;12546.623601291976;12593.811092833797;12896.355182240663;14210;14211.360488777824;14322.713545729235;14538.273086345853;14821.333040013442;12903.548375806802;13213.933930330288;13386.933065334611;13595.660108497366;13728.725425491633;9453.43279403238;10540.841887371542;10683.94658026705;12529.624111276677;12641.2415255085;11329.433528323387;11335.943320283675;12244.510219591117;13254.204747715168;13267.2703001332;9372.250219982521;11172.944135279271;11197.272177308449;11273.210875238654;11560.248583842023;7483.550986940801;11009.889901101207;11041;11335.489902954221;11343 18:37:33 INFO - PROCESS | 1906 | __end_tp_report 18:37:33 INFO - PROCESS | 1906 | __start_cc_report 18:37:33 INFO - PROCESS | 1906 | _x_x_mozilla_cycle_collect,367 18:37:33 INFO - PROCESS | 1906 | __end_cc_report 18:37:33 INFO - PROCESS | 1906 | __startTimestamp1479955053704__endTimestamp 18:37:33 INFO - PROCESS | 1906 | 18:37:33 INFO - PROCESS | 1906 | ------- Summary: start ------- 18:37:33 INFO - PROCESS | 1906 | Number of tests: 6 18:37:33 INFO - PROCESS | 1906 | 18:37:33 INFO - PROCESS | 1906 | [#0] dojo.html Cycles:165 Average:5567.63 Median:5826.21 stddev:669.64 (11.5%) stddev-sans-first:638.96 18:37:33 INFO - PROCESS | 1906 | Values: 2931.4 3230.7 3310.3 3315.4 3349.0 4411.8 4626.7 5375.5 5539.0 5578.5 4535.8 5343.5 5449.2 5547.6 5620.0 4069.0 5089.6 5092.1 5166.4 5169.1 4126.9 4637.3 5082.7 5100.6 5213.4 5184.9 5196.6 5201.8 5218.5 5224.1 4932.0 4976.8 5035.0 5073.3 5137.7 5001.1 5033.8 5048.0 5096.9 5097.3 5405.8 5533.8 5564.8 5569.9 5590.1 5972.9 6003.0 6027.0 6081.3 6095.4 6063.5 6124.0 6140.7 6167.7 6189.8 5670.2 5671.1 5679.3 5681.8 5688.5 6106.2 6161.8 6226.3 6244.1 6247.4 5136.7 5756.3 5841.1 5886.1 5972.3 6088.7 6089.3 6109.5 6110.0 6113.5 5987.7 6057.4 6070.8 6077.5 6083.6 6005.8 6023.6 6061.8 6091.1 6143.5 4866.7 4873.8 4876.7 4888.5 4905.6 4368.3 4413.9 4416.2 4431.9 4448.9 5441.1 5515.4 5558.9 5571.8 5631.5 6176.2 6199.1 6200.5 6219.5 6228.6 5692.3 5699.9 5706.5 5727.7 5745.3 4476.4 5168.7 5193.7 5247.0 5322.8 6123.6 6131.6 6162.0 6189.6 6243.7 6090.0 6110.5 6123.1 6130.4 6168.9 5826.2 5908.8 6191.3 6207.9 6244.5 5881.0 6016.3 6040.3 6071.8 6088.0 5312.6 6101.2 6102.5 6104.0 6125.5 5889.6 5922.3 5958.6 5966.2 5996.3 5993.2 6016.7 6024.5 6026.5 6047.2 4884.7 5650.7 5785.8 5817.5 5878.4 5861.7 5909.3 5919.3 5955.4 5967.3 5838.7 5859.5 5862.9 5866.2 5869.6 18:37:33 INFO - PROCESS | 1906 | 18:37:33 INFO - PROCESS | 1906 | [#1] ext.html Cycles:180 Average:57836.75 Median:35219.06 stddev:117981.15 (335.0%) stddev-sans-first:118233.53 18:37:33 INFO - PROCESS | 1906 | Values: 482.9 492.9 505.0 509.5 514.1 34244.7 37382.0 37879.1 37917.6 37944.8 38568.0 38956.6 38975.0 38990.6 39153.0 8396.1 9109.8 9297.2 9346.2 9363.2 8387.3 11069.9 11352.9 11354.4 11557.9 11251.8 11252.7 11266.0 11295.5 11303.8 9944.8 11332.8 11338.2 11341.1 11366.5 9335.9 11151.0 11209.9 11223.3 11243.7 37083.8 37285.0 37426.4 37448.3 37655.0 3320.2 3488.4 3869.5 3922.5 3936.9 22575.8 23561.6 28709.0 29516.0 29581.3 2594.9 2699.9 3190.9 3277.0 3301.7 329845.0 406560.0 412835.9 430257.0 435435.0 53644.5 54081.9 54135.7 54212.5 54485.0 56611.7 56869.4 57010.7 57104.0 57198.0 15011.2 15542.8 15559.5 15578.9 15599.8 10237.5 10896.9 11141.6 12159.7 12204.6 14530.3 14895.6 14934.1 15000.6 15017.8 5610.4 6736.3 6766.8 6770.4 6794.4 949.5 991.1 1024.4 1040.4 1047.7 26766.0 28835.0 28846.3 29145.0 29206.7 819.4 1031.6 1072.1 1084.5 1100.6 14651.9 14730.9 14805.8 15059.9 15112.5 419648.0 516051.0 518129.0 519385.8 519662.0 51481.2 51691.7 51695.5 51837.0 52002.7 1292.8 1337.6 1338.6 1355.4 1356.0 27249.3 28180.2 28212.4 28500.0 28502.0 46667.8 46836.8 46935.0 46939.3 47057.8 420156.5 421281.0 421395.0 422246.0 424820.9 22764.8 22991.9 24368.1 24589.9 24741.9 41829.0 42367.6 43369.8 43397.3 43539.0 35195.8 35431.6 35741.3 35995.5 36945.4 31468.7 35468.5 36209.0 36477.8 36700.4 34571.7 35242.3 35705.6 35957.0 36088.3 25256.9 25881.0 28725.3 28868.0 30981.5 23353.8 23907.5 24057.9 24077.3 24453.9 18:37:33 INFO - PROCESS | 1906 | 18:37:33 INFO - PROCESS | 1906 | [#2] jquery.html Cycles:180 Average:24693.05 Median:4401.14 stddev:50472.87 (1146.8%) stddev-sans-first:50581.36 18:37:33 INFO - PROCESS | 1906 | Values: 348.3 355.2 371.9 374.4 388.4 19065.1 19591.6 19855.0 20376.0 20662.8 18358.2 19017.0 19624.0 20142.2 20181.2 4893.9 5225.8 5441.3 5445.8 5456.8 6500.9 6678.6 6682.4 6690.6 6697.4 5553.4 6406.6 6526.7 6603.6 6622.8 6300.7 6549.2 6570.7 6575.8 6604.0 5707.5 5777.7 5797.3 5860.8 5868.3 12958.7 12981.3 13013.5 13030.5 13062.7 1973.7 2420.3 2457.3 2753.4 2936.3 8292.0 8564.1 8571.7 10108.6 11909.5 1485.7 1786.3 1820.4 1831.9 1906.0 82073.2 103962.0 110043.0 110542.4 111569.4 9624.0 11047.9 11185.7 11259.5 11449.9 13831.4 14134.8 14225.6 14230.0 14260.2 6991.7 7972.6 8060.6 8289.3 8445.7 8075.5 8083.0 8100.0 8245.6 8278.1 4341.7 4460.6 4486.6 4498.1 4530.6 1871.5 1927.7 1928.4 1949.0 1960.9 820.5 824.6 855.2 879.5 970.6 18171.4 20201.7 20555.9 20729.0 20894.4 925.8 926.9 929.2 969.3 973.3 10105.6 10644.8 10914.1 10928.4 10962.5 246391.8 276056.0 277798.6 279325.0 287261.0 110915.0 114816.3 129449.6 137411.0 139219.0 989.4 995.1 1061.2 1064.1 1066.2 16089.0 17224.1 18620.6 18743.4 19350.6 51175.5 51637.7 52061.2 52518.0 54454.2 50356.0 72509.6 74055.8 81272.2 82335.6 7687.6 7818.6 8091.0 8162.6 8281.7 9094.5 9501.6 10324.8 12007.5 12846.4 5696.4 6313.7 6570.0 7698.3 8049.2 7553.5 7692.5 7747.2 7783.9 8159.7 7938.1 8073.5 8128.1 8339.7 8399.5 9723.8 11095.8 11503.2 12372.3 12517.8 11359.4 11507.6 11847.0 11899.9 12006.9 18:37:33 INFO - PROCESS | 1906 | 18:37:33 INFO - PROCESS | 1906 | [#3] mootools.html Cycles:180 Average:5367.76 Median:5191.14 stddev:2951.46 (56.9%) stddev-sans-first:2945.01 18:37:33 INFO - PROCESS | 1906 | Values: 1444.1 1464.3 1647.5 1755.3 1783.9 7512.7 7837.1 8134.6 8512.0 8521.4 7963.2 8295.5 8300.0 8372.9 8423.4 2302.0 2327.7 2331.8 2340.2 2340.6 1950.3 2328.8 2351.9 2355.1 2364.5 2292.6 2302.8 2319.2 2325.4 2327.2 2281.0 2283.4 2305.9 2312.2 2313.8 2297.9 2311.9 2332.3 2335.8 2336.8 8317.3 8459.6 8473.2 8487.8 8509.0 5054.4 5178.7 5218.3 5223.1 5232.7 3035.1 3241.2 3265.3 3603.2 3606.4 1022.4 1022.5 1072.8 1074.5 1093.0 10661.4 10832.4 10940.0 10941.5 10971.5 3803.6 4401.5 4493.3 4583.8 4640.4 8010.8 8314.3 8550.7 9452.1 9454.0 5137.0 5181.9 5200.4 5202.3 5204.3 3836.5 4264.7 4430.5 4740.0 4768.4 3163.2 3714.0 3718.6 3860.2 3868.5 1032.7 1046.8 1220.8 1260.3 1263.4 809.3 855.5 888.0 921.8 933.7 4932.6 5514.4 5670.5 5881.9 6021.5 958.5 971.4 975.4 997.3 1040.0 3110.5 3112.7 3683.2 3770.7 3778.6 9333.0 9806.0 10065.1 10150.2 10170.1 9905.0 9972.3 9979.7 9980.0 9993.0 7625.4 7838.0 7853.4 7854.3 7867.6 4208.6 5490.6 5555.1 5642.4 5935.6 9166.1 9350.7 9362.9 9393.2 9393.6 8016.5 9349.2 9467.7 9563.8 9590.0 4959.6 6789.6 6840.0 6944.5 7121.6 7597.5 7851.0 7857.1 7857.6 7861.0 5801.4 7160.6 7162.6 7181.0 7245.7 6974.1 6983.0 7000.4 7049.5 7052.4 6607.8 6695.5 6749.7 6756.2 6756.2 4769.5 4802.0 4828.2 4870.3 4943.7 4741.4 4850.9 4905.7 4956.3 4971.1 18:37:33 INFO - PROCESS | 1906 | 18:37:33 INFO - PROCESS | 1906 | [#4] prototype.html Cycles:180 Average:4490.62 Median:5092.25 stddev:1420.35 (27.9%) stddev-sans-first:1388.22 18:37:33 INFO - PROCESS | 1906 | Values: 249.9 286.2 297.0 298.8 306.2 4443.0 4468.8 4501.5 5217.8 5484.0 4925.7 4932.9 5386.9 5403.7 5404.0 3785.6 4134.8 4137.0 4155.7 4168.2 3435.6 3812.7 3840.4 4162.6 4181.6 3511.4 3822.1 4054.6 4057.9 4061.4 3999.7 4058.2 4058.2 4074.7 4078.6 3519.9 3521.7 3524.5 3530.4 3532.2 4162.3 4262.0 5180.6 5228.9 5356.8 4347.0 4650.7 4659.3 4665.8 4668.3 5005.4 5377.8 5446.6 5624.0 5693.5 3527.3 3721.0 3833.5 3871.4 3877.9 5503.4 5761.6 5911.8 6022.7 6083.5 3120.7 3401.0 3894.4 3904.5 3909.1 3502.0 3792.8 3996.2 3997.6 4006.3 3968.6 4078.9 4438.6 4540.0 4661.3 5020.6 5790.5 5802.7 5817.3 5856.7 1040.9 1071.9 1185.2 1254.2 1265.5 801.7 865.4 868.4 871.7 873.8 5156.6 5177.9 5184.5 5192.9 5195.8 5085.0 5099.5 5413.9 5619.0 5641.5 4189.5 4939.4 4996.9 5113.7 5141.0 2378.0 2452.9 2537.9 2544.9 2902.8 4671.0 5279.8 5361.6 5960.4 5963.3 5655.6 5722.5 5832.7 5835.6 5869.7 5129.4 5888.8 5913.0 5921.2 5955.9 4984.7 5512.8 5665.1 5675.2 5760.0 4537.5 5231.7 5292.7 5409.5 5551.3 5006.6 5126.9 5278.0 5683.6 5734.7 3139.6 3347.7 3715.8 3722.5 3761.9 4919.1 5361.2 5464.6 5558.6 5561.6 5000.9 5019.0 5538.3 5617.8 5794.2 5416.7 5661.0 5759.0 5779.4 5781.9 5332.9 5626.7 5644.7 5650.6 5650.7 4833.7 5370.5 5847.6 5909.6 5938.8 5497.9 5652.7 5700.9 5714.9 5776.3 18:37:33 INFO - PROCESS | 1906 | 18:37:33 INFO - PROCESS | 1906 | [#5] yui.html Cycles:180 Average:19963.47 Median:23167.67 stddev:29543.92 (127.5%) stddev-sans-first:29591.21 18:37:33 INFO - PROCESS | 1906 | Values: 652.3 697.7 728.5 764.4 786.7 13844.9 14456.7 14710.3 14792.6 14806.6 13304.9 13411.9 13422.4 13422.8 13475.3 640.7 659.5 792.0 810.1 822.0 786.4 813.7 813.9 816.4 819.2 868.6 868.8 869.7 870.2 870.5 834.1 861.0 862.1 863.3 863.7 625.8 631.3 636.7 637.1 637.1 11394.6 12575.7 13620.7 13645.3 13694.5 12847.5 13144.1 13180.2 13196.5 13321.0 9569.6 11297.7 11326.0 11473.3 11539.2 3053.3 3520.3 3638.3 3660.7 3679.7 61896.1 64053.7 89395.6 89947.0 90544.4 8327.7 10198.4 10490.3 10661.6 10857.3 16252.5 19937.9 20129.0 20158.0 20241.0 7457.1 7498.2 7583.7 7605.2 7848.1 4114.8 4138.2 4151.7 4180.2 4188.9 3368.9 3483.6 3483.7 3491.5 3502.4 580.3 616.9 650.5 653.9 663.4 612.0 624.4 639.4 653.4 658.7 7524.9 8569.9 8774.8 9056.5 9119.6 568.9 619.0 656.5 660.5 685.2 3867.7 4111.2 4393.3 4474.6 4500.2 80237.0 110668.0 111326.9 112018.0 115464.0 67885.0 101101.5 102370.0 102952.5 103130.5 71196.3 89794.2 89927.6 90275.0 92700.3 7117.6 8015.8 9215.0 9301.0 9557.3 41942.0 45232.0 50163.5 52485.0 52647.7 74941.6 75135.6 76111.0 77661.6 77857.6 12160.1 12442.3 12546.6 12593.8 12896.4 14210.0 14211.4 14322.7 14538.3 14821.3 12903.5 13213.9 13386.9 13595.7 13728.7 9453.4 10540.8 10683.9 12529.6 12641.2 11329.4 11335.9 12244.5 13254.2 13267.3 9372.3 11172.9 11197.3 11273.2 11560.2 7483.6 11009.9 11041.0 11335.5 11343.0 18:37:33 INFO - PROCESS | 1906 | -------- Summary: end -------- 18:37:33 INFO - PROCESS | 1906 | 18:37:34 INFO - TEST-INFO | 1906: exit 0 18:37:34 INFO - TEST-OK | dromaeo_css | took 1117383ms 18:37:34 INFO - TEST-START | kraken 18:37:34 INFO - operating with platform_type : mac_ 18:37:34 INFO - Initialising browser for kraken test... 18:37:34 INFO - TEST-INFO | started process 1912 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/tmpeIBl5j/profile http://localhost:51265/getInfo.html) 18:37:37 INFO - PROCESS | 1912 | 2016-11-23 18:37:37.363 plugin-container[1913:17062] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4637, name = 'com.apple.tsm.portname' 18:37:37 INFO - PROCESS | 1912 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:37:37 INFO - PROCESS | 1912 | 2016-11-23 18:37:37.366 plugin-container[1913:17062] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8c0b, name = 'com.apple.CFPasteboardClient' 18:37:37 INFO - PROCESS | 1912 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:37:37 INFO - PROCESS | 1912 | 2016-11-23 18:37:37.366 plugin-container[1913:17062] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 18:37:37 INFO - PROCESS | 1912 | __metrics Screen width/height:1600/1200 18:37:37 INFO - PROCESS | 1912 | colorDepth:24 18:37:37 INFO - PROCESS | 1912 | Browser inner width/height: 1024/689 18:37:37 INFO - PROCESS | 1912 | __metrics 18:37:37 ERROR - PROCESS | 1912 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3198: TypeError: this.tabs is undefined 18:37:38 INFO - TEST-INFO | 1912: exit 0 18:37:38 INFO - Browser initialized. 18:37:38 INFO - Running cycle 1/1 for kraken test... 18:37:38 INFO - TEST-INFO | started process 1914 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/tmpeIBl5j/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:37:41 INFO - PROCESS | 1914 | 2016-11-23 18:37:40.997 plugin-container[1915:17184] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x444b, name = 'com.apple.tsm.portname' 18:37:41 INFO - PROCESS | 1914 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:37:41 INFO - PROCESS | 1914 | 2016-11-23 18:37:40.998 plugin-container[1915:17184] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8d0f, name = 'com.apple.CFPasteboardClient' 18:37:41 INFO - PROCESS | 1914 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:37:41 INFO - PROCESS | 1914 | 2016-11-23 18:37:40.998 plugin-container[1915:17184] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 18:37:50 INFO - PROCESS | 1914 | RSS: Main: 241516544 18:37:50 INFO - PROCESS | 1914 | 18:37:53 INFO - PROCESS | 1914 | Cycle 1(1): loaded http://localhost:51265/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:51265/tests/kraken/driver.html?testName=audio-beat-detection) 18:37:54 INFO - PROCESS | 1914 | RSS: Main: 253489152 18:37:54 INFO - PROCESS | 1914 | 18:37:57 INFO - PROCESS | 1914 | Cycle 1(1): loaded http://localhost:51265/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:51265/tests/kraken/driver.html?testName=audio-dft) 18:37:58 INFO - PROCESS | 1914 | RSS: Main: 261165056 18:37:58 INFO - PROCESS | 1914 | 18:38:01 INFO - PROCESS | 1914 | Cycle 1(1): loaded http://localhost:51265/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:51265/tests/kraken/driver.html?testName=audio-fft) 18:38:02 INFO - PROCESS | 1914 | RSS: Main: 263872512 18:38:02 INFO - PROCESS | 1914 | 18:38:04 INFO - PROCESS | 1914 | Cycle 1(1): loaded http://localhost:51265/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:51265/tests/kraken/driver.html?testName=audio-oscillator) 18:38:04 INFO - PROCESS | 1914 | RSS: Main: 264994816 18:38:04 INFO - PROCESS | 1914 | 18:38:06 INFO - PROCESS | 1914 | Cycle 1(1): loaded http://localhost:51265/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:51265/tests/kraken/driver.html?testName=imaging-gaussian-blur) 18:38:07 INFO - PROCESS | 1914 | RSS: Main: 265334784 18:38:07 INFO - PROCESS | 1914 | 18:38:10 INFO - PROCESS | 1914 | Cycle 1(1): loaded http://localhost:51265/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:51265/tests/kraken/driver.html?testName=imaging-darkroom) 18:38:10 INFO - PROCESS | 1914 | RSS: Main: 271060992 18:38:10 INFO - PROCESS | 1914 | 18:38:13 INFO - PROCESS | 1914 | Cycle 1(1): loaded http://localhost:51265/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:51265/tests/kraken/driver.html?testName=imaging-desaturate) 18:38:14 INFO - PROCESS | 1914 | RSS: Main: 271691776 18:38:14 INFO - PROCESS | 1914 | 18:38:17 INFO - PROCESS | 1914 | Cycle 1(1): loaded http://localhost:51265/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:51265/tests/kraken/driver.html?testName=json-parse-financial) 18:38:17 INFO - PROCESS | 1914 | RSS: Main: 272216064 18:38:17 INFO - PROCESS | 1914 | 18:38:19 INFO - PROCESS | 1914 | Cycle 1(1): loaded http://localhost:51265/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:51265/tests/kraken/driver.html?testName=json-stringify-tinderbox) 18:38:20 INFO - PROCESS | 1914 | RSS: Main: 271306752 18:38:20 INFO - PROCESS | 1914 | 18:38:22 INFO - PROCESS | 1914 | Cycle 1(1): loaded http://localhost:51265/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:51265/tests/kraken/driver.html?testName=stanford-crypto-aes) 18:38:22 INFO - PROCESS | 1914 | RSS: Main: 271364096 18:38:22 INFO - PROCESS | 1914 | 18:38:24 INFO - PROCESS | 1914 | Cycle 1(1): loaded http://localhost:51265/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:51265/tests/kraken/driver.html?testName=stanford-crypto-ccm) 18:38:25 INFO - PROCESS | 1914 | RSS: Main: 271347712 18:38:25 INFO - PROCESS | 1914 | 18:38:28 INFO - PROCESS | 1914 | Cycle 1(1): loaded http://localhost:51265/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:51265/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 18:38:29 INFO - PROCESS | 1914 | RSS: Main: 271630336 18:38:29 INFO - PROCESS | 1914 | 18:38:32 INFO - PROCESS | 1914 | Cycle 1(1): loaded http://localhost:51265/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:51265/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 18:38:33 INFO - PROCESS | 1914 | RSS: Main: 279801856 18:38:33 INFO - PROCESS | 1914 | 18:38:34 INFO - PROCESS | 1914 | Cycle 1(1): loaded http://localhost:51265/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:51265/tests/kraken/driver.html?testName=ai-astar) 18:38:35 INFO - PROCESS | 1914 | RSS: Main: 280956928 18:38:35 INFO - PROCESS | 1914 | 18:38:35 INFO - PROCESS | 1914 | __start_tp_report 18:38:35 INFO - PROCESS | 1914 | _x_x_mozilla_page_load 18:38:35 INFO - PROCESS | 1914 | _x_x_mozilla_page_load_details 18:38:35 INFO - PROCESS | 1914 | |i|pagename|runs| 18:38:35 INFO - PROCESS | 1914 | |0;ai-astar;108;175;102;173;174;128;120;119;118;119 18:38:35 INFO - PROCESS | 1914 | |1;audio-beat-detection;150;134;143;191;119;119;117;128;164;149 18:38:35 INFO - PROCESS | 1914 | |2;audio-dft;199;208;271;271;195;198;196;196;198;195 18:38:35 INFO - PROCESS | 1914 | |3;audio-fft;84;61;58;82;61;62;59;67;97;84 18:38:35 INFO - PROCESS | 1914 | |4;audio-oscillator;83;71;115;103;101;75;81;71;70;81 18:38:35 INFO - PROCESS | 1914 | |5;imaging-gaussian-blur;91;88;90;89;97;105;136;81;137;112 18:38:35 INFO - PROCESS | 1914 | |6;imaging-darkroom;96;95;97;97;97;96;119;97;97;95 18:38:35 INFO - PROCESS | 1914 | |7;imaging-desaturate;92;97;93;93;111;93;92;95;92;91 18:38:35 INFO - PROCESS | 1914 | |8;json-parse-financial;57;60;56;59;57;58;58;57;59;57 18:38:35 INFO - PROCESS | 1914 | |9;json-stringify-tinderbox;77;70;73;82;79;77;71;72;77;73 18:38:35 INFO - PROCESS | 1914 | |10;stanford-crypto-aes;89;87;88;89;90;90;90;90;88;93 18:38:35 INFO - PROCESS | 1914 | |11;stanford-crypto-ccm;156;162;154;161;146;151;172;163;222;169 18:38:35 INFO - PROCESS | 1914 | |12;stanford-crypto-pbkdf2;173;176;195;244;184;177;176;176;174;178 18:38:35 INFO - PROCESS | 1914 | |13;stanford-crypto-sha256-iterative;71;71;72;68;71;67;67;68;66;70 18:38:35 INFO - PROCESS | 1914 | __end_tp_report 18:38:35 INFO - PROCESS | 1914 | __start_cc_report 18:38:35 INFO - PROCESS | 1914 | _x_x_mozilla_cycle_collect,938 18:38:35 INFO - PROCESS | 1914 | __end_cc_report 18:38:35 INFO - PROCESS | 1914 | __startTimestamp1479955115031__endTimestamp 18:38:35 INFO - PROCESS | 1914 | 18:38:35 INFO - PROCESS | 1914 | ------- Summary: start ------- 18:38:35 INFO - PROCESS | 1914 | Number of tests: 14 18:38:35 INFO - PROCESS | 1914 | 18:38:35 INFO - PROCESS | 1914 | [#0] ai-astar Cycles:10 Average:133.60 Median:124.00 stddev:28.75 (23.2%) stddev-sans-first:28.96 18:38:35 INFO - PROCESS | 1914 | Values: 108.0 175.0 102.0 173.0 174.0 128.0 120.0 119.0 118.0 119.0 18:38:35 INFO - PROCESS | 1914 | 18:38:35 INFO - PROCESS | 1914 | [#1] audio-beat-detection Cycles:10 Average:141.40 Median:146.00 stddev:23.42 (16.0%) stddev-sans-first:24.64 18:38:35 INFO - PROCESS | 1914 | Values: 150.0 134.0 143.0 191.0 119.0 119.0 117.0 128.0 164.0 149.0 18:38:35 INFO - PROCESS | 1914 | 18:38:35 INFO - PROCESS | 1914 | [#2] audio-dft Cycles:10 Average:212.70 Median:198.50 stddev:30.96 (15.6%) stddev-sans-first:32.43 18:38:35 INFO - PROCESS | 1914 | Values: 199.0 208.0 271.0 271.0 195.0 198.0 196.0 196.0 198.0 195.0 18:38:35 INFO - PROCESS | 1914 | 18:38:35 INFO - PROCESS | 1914 | [#3] audio-fft Cycles:10 Average:71.50 Median:74.50 stddev:13.91 (18.7%) stddev-sans-first:14.00 18:38:35 INFO - PROCESS | 1914 | Values: 84.0 61.0 58.0 82.0 61.0 62.0 59.0 67.0 97.0 84.0 18:38:35 INFO - PROCESS | 1914 | 18:38:35 INFO - PROCESS | 1914 | [#4] audio-oscillator Cycles:10 Average:85.10 Median:73.00 stddev:15.75 (21.6%) stddev-sans-first:16.69 18:38:35 INFO - PROCESS | 1914 | Values: 83.0 71.0 115.0 103.0 101.0 75.0 81.0 71.0 70.0 81.0 18:38:35 INFO - PROCESS | 1914 | 18:38:35 INFO - PROCESS | 1914 | [#5] imaging-gaussian-blur Cycles:10 Average:102.60 Median:88.50 stddev:19.95 (22.5%) stddev-sans-first:20.71 18:38:35 INFO - PROCESS | 1914 | Values: 91.0 88.0 90.0 89.0 97.0 105.0 136.0 81.0 137.0 112.0 18:38:35 INFO - PROCESS | 1914 | 18:38:35 INFO - PROCESS | 1914 | [#6] imaging-darkroom Cycles:10 Average:98.60 Median:97.00 stddev:7.21 (7.4%) stddev-sans-first:7.59 18:38:35 INFO - PROCESS | 1914 | Values: 96.0 95.0 97.0 97.0 97.0 96.0 119.0 97.0 97.0 95.0 18:38:35 INFO - PROCESS | 1914 | 18:38:35 INFO - PROCESS | 1914 | [#7] imaging-desaturate Cycles:10 Average:94.90 Median:93.00 stddev:5.92 (6.4%) stddev-sans-first:6.18 18:38:35 INFO - PROCESS | 1914 | Values: 92.0 97.0 93.0 93.0 111.0 93.0 92.0 95.0 92.0 91.0 18:38:35 INFO - PROCESS | 1914 | 18:38:35 INFO - PROCESS | 1914 | [#8] json-parse-financial Cycles:10 Average:57.80 Median:58.00 stddev:1.23 (2.1%) stddev-sans-first:1.27 18:38:35 INFO - PROCESS | 1914 | Values: 57.0 60.0 56.0 59.0 57.0 58.0 58.0 57.0 59.0 57.0 18:38:35 INFO - PROCESS | 1914 | 18:38:35 INFO - PROCESS | 1914 | [#9] json-stringify-tinderbox Cycles:10 Average:75.10 Median:77.00 stddev:3.87 (5.0%) stddev-sans-first:4.04 18:38:35 INFO - PROCESS | 1914 | Values: 77.0 70.0 73.0 82.0 79.0 77.0 71.0 72.0 77.0 73.0 18:38:35 INFO - PROCESS | 1914 | 18:38:35 INFO - PROCESS | 1914 | [#10] stanford-crypto-aes Cycles:10 Average:89.40 Median:90.00 stddev:1.65 (1.8%) stddev-sans-first:1.74 18:38:35 INFO - PROCESS | 1914 | Values: 89.0 87.0 88.0 89.0 90.0 90.0 90.0 90.0 88.0 93.0 18:38:35 INFO - PROCESS | 1914 | 18:38:35 INFO - PROCESS | 1914 | [#11] stanford-crypto-ccm Cycles:10 Average:165.60 Median:162.50 stddev:21.34 (13.1%) stddev-sans-first:22.35 18:38:35 INFO - PROCESS | 1914 | Values: 156.0 162.0 154.0 161.0 146.0 151.0 172.0 163.0 222.0 169.0 18:38:35 INFO - PROCESS | 1914 | 18:38:35 INFO - PROCESS | 1914 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:185.30 Median:177.50 stddev:21.61 (12.2%) stddev-sans-first:22.46 18:38:35 INFO - PROCESS | 1914 | Values: 173.0 176.0 195.0 244.0 184.0 177.0 176.0 176.0 174.0 178.0 18:38:35 INFO - PROCESS | 1914 | 18:38:35 INFO - PROCESS | 1914 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.10 Median:70.50 stddev:2.13 (3.0%) stddev-sans-first:2.15 18:38:35 INFO - PROCESS | 1914 | Values: 71.0 71.0 72.0 68.0 71.0 67.0 67.0 68.0 66.0 70.0 18:38:35 INFO - PROCESS | 1914 | -------- Summary: end -------- 18:38:35 INFO - PROCESS | 1914 | 18:38:35 INFO - TEST-INFO | 1914: exit 0 18:38:36 INFO - TEST-OK | kraken | took 61732ms 18:38:36 INFO - SUITE-END | took 1179s 18:38:36 INFO - Completed test suite (00:19:39) 18:38:36 INFO - javascript benchmark 18:38:36 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 6964.1531249341315, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2931.355101877587, 3230.6930841570043, 3310.2882880180773, 3315.436375816111, 3348.999999999999, 4411.801468933898, 4626.65300102492, 5375.516203541681, 5539.0, 5578.497935185833, 4535.750533720644, 5343.465653434664, 5449.182622606601, 5547.55619550436, 5620.0, 4068.9593104068877, 5089.618278629099, 5092.083424983502, 5166.380034395859, 5169.095408303532, 4126.885740849969, 4637.281221410687, 5082.720450375228, 5100.642954993152, 5213.426523082458, 5184.948150518503, 5196.636235463519, 5201.817936372227, 5218.47815218477, 5224.138017227153, 4931.975340123312, 4976.800927962877, 5035.0, 5073.340465739467, 5137.717425541594, 5001.074801161797, 5033.823816166453, 5048.040872234264, 5096.923546146811, 5097.337346145015, 5405.8107966221, 5533.833984980457, 5564.777408903639, 5569.860753481195, 5590.105583106683, 5972.910406343908, 6002.969985150046, 6026.969865150646, 6081.3006504252135, 6095.359987201368, 6063.484603808682, 6123.969380153071, 6140.662263575547, 6167.660778657172, 6189.752409903598, 5670.206171136044, 5671.120976248688, 5679.290088738908, 5681.829545113571, 5688.516476099495, 6106.236720409949, 6161.7535298588455, 6226.315105338409, 6244.12582238487, 6247.406496382836, 5136.666116702402, 5756.280464941882, 5841.065429531254, 5886.05823068307, 5972.283326000821, 6088.665123418255, 6089.330173680892, 6109.450149486567, 6110.022396416641, 6113.541484388688, 5987.700614969234, 6057.394260573996, 6070.848228794315, 6077.513798896078, 6083.6349819011, 6005.759769609254, 6023.578349515492, 6061.818145455643, 6091.14723938649, 6143.542582966849, 4866.7079975201605, 4873.780679869415, 4876.683015603974, 4888.462269150391, 4905.583025442877, 4368.27923392643, 4413.889652758642, 4416.205083085076, 4431.911361772746, 4448.933266001012, 5441.102218134043, 5515.365732940683, 5558.861028474321, 5571.777128914879, 5631.493165615114, 6176.227971503562, 6199.132121502993, 6200.503959683215, 6219.533534984895, 6228.626282423069, 5692.316921969307, 5699.914501282484, 5706.457886500776, 5727.656340619576, 5745.253117094751, 4476.37330773692, 5168.681986093522, 5193.7403129844115, 5247.02929957959, 5322.813701520428, 6123.557162997564, 6131.570790044654, 6161.952468080437, 6189.566730328834, 6243.687815609202, 6089.964705999902, 6110.534509600366, 6123.130109042633, 6130.367562715148, 6168.907466388097, 5826.213461182686, 5908.822735317947, 6191.288001879661, 6207.937920620736, 6244.469220116341, 5881.000229961002, 6016.308124565556, 6040.275166979991, 6071.787487437919, 6088.0, 5312.628116031995, 6101.237345331833, 6102.511799055976, 6103.969480152571, 6125.479334256638, 5889.587728858939, 5922.318933322723, 5958.582899197015, 5966.164736936919, 5996.280446346466, 5993.160957465871, 6016.7292471838, 6024.518038556993, 6026.517878569616, 6047.153398524122, 4884.7069175849565, 5650.717464126859, 5785.813908148735, 5817.505512031525, 5878.441548052928, 5861.706914654165, 5909.349971503218, 5919.260092488439, 5955.404459554184, 5967.31375891778, 5838.708064596838, 5859.472647461749, 5862.882742345215, 5866.178734977021, 5869.647821130746], "value": 5524.485874836233, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [482.879280179955, 492.8767808047988, 504.97742072303606, 509.4676063513628, 514.0951924612681, 34244.657553424455, 37382.0, 37879.05302367439, 37917.62082379175, 37944.81027594865, 38568.0, 38956.61043389565, 38975.025624359405, 38990.635327763535, 39153.0, 8396.11840756721, 9109.817803643924, 9297.209737172314, 9346.205572526344, 9363.204127649158, 8387.329013678891, 11069.889301107007, 11352.943235283812, 11354.43227838609, 11557.942210288978, 11251.774964500704, 11252.718682032973, 11266.0, 11295.491702873349, 11303.830442543327, 9944.751381215456, 11332.773344533145, 11338.206325557214, 11341.149413793999, 11366.488508017159, 9335.859962100574, 11151.0, 11209.887901120966, 11223.270487418373, 11243.66269011931, 37083.814580926926, 37285.0, 37426.43860342097, 37448.25103497915, 37655.0, 3320.236345640492, 3488.4418493040994, 3869.4582758413844, 3922.4508568800393, 3936.862209822671, 22575.789087955967, 23561.646575301213, 28709.0, 29516.0, 29581.26046848846, 2594.8962041518316, 2699.9188929635884, 3190.9361812763846, 3276.967230327714, 3301.7193538549254, 329845.0, 406559.9672001621, 412835.93582032196, 430257.0, 435435.0, 53644.463555363945, 54081.918361632546, 54135.729321353145, 54212.45787542153, 54485.0, 56611.71694141503, 56869.43130568724, 57010.714946425, 57104.0, 57198.0, 15011.174385408689, 15542.766858497132, 15559.533214003372, 15578.9221053894, 15599.844001559839, 10237.48812559384, 10896.891031089588, 11141.610043648434, 12159.696007599703, 12204.633860984184, 14530.346134423766, 14895.553133406016, 14934.133233345832, 15000.62498437526, 15017.849821501864, 5610.410906854803, 6736.2590115087305, 6766.830829229309, 6770.390664840188, 6794.398968546679, 949.5110018340561, 991.0931497679543, 1024.4314405505015, 1040.38617215843, 1047.664747280863, 26766.0, 28834.990775323182, 28846.27884302867, 29145.0, 29206.7079329204, 819.4059307002376, 1031.6492392586556, 1072.131573425528, 1084.5173897615643, 1100.5872797700863, 14651.92674036623, 14730.852691473163, 14805.777913331309, 15059.924700376429, 15112.471063512945, 419648.0, 516051.0, 518129.0, 519385.8061419413, 519662.0, 51481.22778158331, 51691.74154129205, 51695.48304516982, 51837.0, 52002.69993250199, 1292.825468561751, 1337.5786627212397, 1338.578347820453, 1355.3562058022558, 1355.966100847467, 27249.31876703098, 28180.166982584025, 28212.43575128527, 28500.002499912403, 28502.0, 46667.766661166475, 46836.7658161707, 46935.0, 46939.295910562054, 47057.76471117623, 420156.49608760024, 421281.0, 421395.0, 422246.0, 424820.8758956185, 22764.772352276264, 22991.88504057469, 24368.147114850894, 24589.877050614632, 24741.87629061843, 41829.0, 42367.57632423636, 43369.78315108404, 43397.349039763794, 43539.0, 35195.82402087973, 35431.64568354283, 35741.285174296885, 35995.46006809847, 36945.44581831221, 31468.685313147493, 35468.46797298094, 36209.0, 36477.81761091178, 36700.44949325709, 34571.65428345785, 35242.295154097286, 35705.64294357023, 35957.0, 36088.27823443569, 25256.873715631304, 25881.0, 28725.276483411064, 28868.0, 30981.535276971314, 23353.76646233584, 23907.521849563258, 24057.917393716976, 24077.27768166888, 24453.87773061123], "value": 18680.560901146113, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [348.27037356737634, 355.19371027766954, 371.927474142542, 374.3617132788595, 388.39992212032405, 19065.14206860691, 19591.608167836635, 19855.0, 20376.0, 20662.793372066273, 18358.173882175302, 19017.0, 19624.0, 20142.19431222753, 20181.19275228989, 4893.877653058684, 5225.843224703265, 5441.319835020622, 5445.782168713246, 5456.836294911159, 6500.934990650104, 6678.599284042973, 6682.398584127428, 6690.565113267647, 6697.3637504437, 5553.389127196006, 6406.551541392105, 6526.738930442777, 6603.580230250495, 6622.768203112892, 6300.68496575174, 6549.21409430869, 6570.737170513174, 6575.80272591823, 6604.0, 5707.543396528268, 5777.682227477487, 5797.275340582427, 5860.765569377219, 5868.295804503487, 12958.740825183444, 12981.286029268387, 13013.544525941546, 13030.543930962465, 13062.673433164247, 1973.6546104431668, 2420.322309753272, 2457.31195265326, 2753.435545713124, 2936.3393236521824, 8292.0, 8564.100769419247, 8571.742847714579, 10108.584798128268, 11909.464074116599, 1485.6657252118187, 1786.2676302715824, 1820.3992682414903, 1831.8733978603195, 1906.0, 82073.17926820775, 103962.0, 110043.0, 110542.44728776385, 111569.44215278953, 9624.03759624046, 11047.889521104848, 11185.66443006711, 11259.493322800494, 11449.942750286194, 13831.446742130403, 14134.787978180335, 14225.64435889111, 14230.0, 14260.215688137045, 6991.650417479105, 7972.60136993148, 8060.5893968555265, 8289.253967142986, 8445.746627601182, 8075.475094118863, 8083.0, 8100.027996640323, 8245.628946697412, 8278.089410164877, 4341.717788343748, 4460.553944605514, 4486.573775491323, 4498.145352383037, 4530.569595888385, 1871.5321169707572, 1927.6530224559433, 1928.421473557927, 1948.9707654385195, 1960.8724983134468, 820.4789958376355, 824.5877061469265, 855.153398135841, 879.4547380624055, 970.5635659224388, 18171.364002259856, 20201.696974545393, 20555.8972205141, 20729.0, 20894.37316880496, 925.8240934222611, 926.8923636254746, 929.1683942871092, 969.3360048366891, 973.3478569358547, 10105.595776169019, 10644.787104258026, 10914.126869850394, 10928.398938058483, 10962.506687198937, 246391.76804116223, 276056.0, 277798.6110069437, 279325.0, 287261.0, 110915.0, 114816.27775583373, 129449.6440284775, 137411.0, 139219.0, 989.4113002763344, 995.134123518403, 1061.1669839176363, 1064.1114669251263, 1066.157735389034, 16088.954217975794, 17224.13879306055, 18620.627587448445, 18743.437696869114, 19350.612987739885, 51175.48824511707, 51637.7418112907, 52061.21908171456, 52518.0, 54454.18318725143, 50355.99288014293, 72509.63745181452, 74055.82102085317, 81272.18727812647, 82335.588322058, 7687.615619218905, 7818.609069546614, 8091.029076510857, 8162.591870406574, 8281.710140145065, 9094.499012771541, 9501.572429240563, 10324.812646545442, 12007.519699211934, 12846.42191101384, 5696.373398926197, 6313.715882785194, 6570.0, 7698.34564062061, 8049.154838741848, 7553.546787192786, 7692.499987500794, 7747.203835308591, 7783.922160778547, 8159.673613055411, 7938.126806051214, 8073.515589064675, 8128.105908349959, 8339.666413343399, 8399.49603023796, 9723.756906077404, 11095.778084438105, 11503.194776365908, 12372.257664540157, 12517.812232816334, 11359.375234362189, 11507.597234096766, 11846.99300559462, 11899.940500297444, 12006.879931200576], "value": 8860.607777730826, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1444.0830072903707, 1464.2678660669665, 1647.546924595736, 1755.2803350626245, 1783.8840475369093, 7512.737054203104, 7837.059552853666, 8134.5932703364815, 8511.957440212806, 8521.360897932656, 7963.163867793885, 8295.460795048333, 8300.003999520051, 8372.91627083729, 8423.410361274713, 2301.976980230197, 2327.697399338084, 2331.825113116515, 2340.20433052762, 2340.578695834749, 1950.3076407875212, 2328.836981411302, 2351.9412014699683, 2355.0815182079004, 2364.538914911593, 2292.587334279829, 2302.827287953402, 2319.246244970383, 2325.4418939454577, 2327.173853282086, 2280.9771902281013, 2283.3720726800098, 2305.8616483010987, 2312.2022902098747, 2313.803326717231, 2297.885105744706, 2311.8959646815933, 2332.2769941318074, 2335.8364914455997, 2336.824738144646, 8317.293030092449, 8459.577021148918, 8473.15268473154, 8487.787805304855, 8509.021462531826, 5054.444011158769, 5178.689278643255, 5218.321618189649, 5223.059849227137, 5232.686038837682, 3035.05913166919, 3241.222106694376, 3265.330607225537, 3603.2433189030335, 3606.3688854450365, 1022.4478781458004, 1022.4734261855151, 1072.84443755655, 1074.484247561175, 1092.983605245922, 10661.413622250773, 10832.40421776802, 10940.0, 10941.507632156492, 10971.506282217319, 3803.6386543278345, 4401.493828209796, 4493.303537951622, 4583.793729282191, 4640.373549570832, 8010.759677209693, 8314.293285070837, 8550.700725474579, 9452.10205030521, 9454.0, 5136.9743151284, 5181.870453238624, 5200.401953775288, 5202.323697919246, 5204.271402003723, 3836.501254836853, 4264.744115353089, 4430.490493593214, 4739.9763001185465, 4768.427788665383, 3163.1933856866353, 3713.9814300928324, 3718.5909549949483, 3860.1700634363747, 3868.5357757069332, 1032.7108409645161, 1046.7801761629992, 1220.7741567809805, 1260.287937315414, 1263.3556885988212, 809.2595275323014, 855.4738835616175, 887.973360799177, 921.7972046149837, 933.6638810028385, 4932.605391568666, 5514.420985796433, 5670.5180059695385, 5881.911771323434, 6021.54838387114, 958.5255298627225, 971.4220039076738, 975.4293738163254, 997.33677104726, 1040.0119886108228, 3110.5178697301503, 3112.7042930921525, 3683.226522430266, 3770.71719621024, 3778.5843557208686, 9333.0, 9806.0, 10065.144462720751, 10150.238732095122, 10170.084692377573, 9905.0, 9972.301938864355, 9979.700608981742, 9979.952105028868, 9992.950035249778, 7625.389968802482, 7838.0, 7853.41099417546, 7854.253845884746, 7867.567283799446, 4208.642265407475, 5490.5882058844945, 5555.055730494456, 5642.351129620146, 5935.5548333875995, 9166.129217724305, 9350.719478415658, 9362.906370936204, 9393.154616084585, 9393.624255029723, 8016.478928869605, 9349.172786380348, 9467.66863159786, 9563.760905977406, 9589.952050239704, 4959.578435832995, 6789.558678686068, 6839.965800170968, 6944.513884028272, 7121.643917804193, 7597.506162099446, 7851.0, 7857.05715314166, 7857.607119644109, 7860.960695196487, 5801.419858014165, 7160.606166660676, 7162.641867906688, 7181.0, 7245.673944672397, 6974.093367862146, 6983.0, 7000.439964802906, 7049.471289653399, 7052.435805135679, 6607.834804129743, 6695.464362851058, 6749.730010799513, 6756.18925728896, 6756.189257289157, 4769.499202583679, 4801.95198048015, 4828.203346447937, 4870.31815545831, 4943.703377797344, 4741.383620129361, 4850.854474365775, 4905.656604037683, 4956.330895329084, 4971.105201063699], "value": 4361.673943420866, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [249.9377645008713, 286.2000708021081, 296.9851507424629, 298.7729325712459, 306.21608681774654, 4443.0, 4468.787949964768, 4501.549845015505, 5217.765200565974, 5483.999999999995, 4925.655204135712, 4932.914758753078, 5386.905951476876, 5403.729813509329, 5404.027275090482, 3785.6403641653997, 4134.834606615732, 4136.97931510342, 4155.70910036296, 4168.2080404723, 3435.57055368079, 3812.6759225465867, 3840.423936409547, 4162.62536371728, 4181.581841815824, 3511.4206155984234, 3822.0507541154075, 4054.5945405459365, 4057.898552536181, 4061.390791381287, 3999.6600288975474, 4058.1883623275316, 4058.2492238935893, 4074.653654439376, 4078.6125318094732, 3519.8768043118625, 3521.6654417830264, 3524.4713293006, 3530.3998320285614, 3532.205253817896, 4162.271602469556, 4262.039461847554, 5180.611454140954, 5228.92156617651, 5356.7857285708915, 4346.956530434687, 4650.651201159926, 4659.347691323217, 4665.813367465297, 4668.346431499593, 5005.424376196747, 5377.784888604451, 5446.618736688433, 5624.0, 5693.4875861172695, 3527.3121741260466, 3720.962790372089, 3833.463315135883, 3871.4386413970096, 3877.9224415511812, 5503.394626591072, 5761.625494342854, 5911.763529458816, 6022.69886505673, 6083.48290395317, 3120.672329405425, 3401.0, 3894.3838307102237, 3904.4533765272886, 3909.139989202371, 3501.964980350164, 3792.8140362095974, 3996.200759848042, 3997.5802540733384, 4006.25884211416, 3968.583298753647, 4078.8579197824656, 4438.622717069085, 4540.0, 4661.300804879227, 5020.623453241022, 5790.478856902899, 5802.723400851806, 5817.3019237690905, 5856.707164641751, 1040.9063184313447, 1071.8552995345528, 1185.205912038936, 1254.2286493806243, 1265.4938024789974, 801.6512816924657, 865.446114486729, 868.4398562926876, 871.694906782621, 873.8208667223204, 5156.61325400589, 5177.86087060846, 5184.507471790174, 5192.8961420771375, 5195.766190521435, 5084.974575127175, 5099.515546023122, 5413.915642477675, 5618.988582055269, 5641.52047075995, 4189.475366604061, 4939.382577177853, 4996.850094497171, 5113.667611605234, 5141.0, 2377.97622023781, 2452.852828830276, 2537.885795139186, 2544.8929715573786, 2902.811317264371, 4670.95329046712, 5279.815206467755, 5361.624686272002, 5960.354205061325, 5963.318794746104, 5655.575831812631, 5722.54219662426, 5832.708364581754, 5835.649861008353, 5869.677167755729, 5129.358830146231, 5888.764449422061, 5912.984377967267, 5921.200637913913, 5955.851103722442, 4984.675996060245, 5512.759629083424, 5665.093585026376, 5675.205471234113, 5759.971200143972, 4537.478190008192, 5231.738413079407, 5292.735363231746, 5409.486098820606, 5551.306086739158, 5006.592879689999, 5126.923096153486, 5277.997180535686, 5683.573731970201, 5734.741936612946, 3139.591853059179, 3347.748918831146, 3715.8327875245136, 3722.4788529605335, 3761.8683346082757, 4919.120778976361, 5361.185377676751, 5464.590155738256, 5558.638688485235, 5561.610687251854, 5000.924986125284, 5019.0, 5538.280023596906, 5617.8033768817895, 5794.232759008542, 5416.674999500043, 5660.981023415825, 5759.0, 5779.364269930388, 5781.8554536136935, 5332.866853258727, 5626.690532020614, 5644.6613203207935, 5650.576206784425, 5650.717464126859, 4833.703990604435, 5370.489803468664, 5847.596576821617, 5909.645421274738, 5938.7825495772395, 5497.862553436196, 5652.689102099424, 5700.885982280414, 5714.914276285942, 5776.306843178845], "value": 4024.3694419357166, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [652.3020368206019, 697.6860412814235, 728.497336837582, 764.3503022430934, 786.7010535996322, 13844.861551384483, 14456.710865782678, 14710.338034788434, 14792.556223313304, 14806.555803325891, 13304.933475332635, 13411.865881341184, 13422.39599218035, 13422.798658020112, 13475.258860762655, 640.6700549217157, 659.4757168051386, 792.0455850699922, 810.1250649298743, 821.9876701849477, 786.3669745854585, 813.6501304439089, 813.894193754811, 816.4407380944043, 819.2217393476192, 868.5526953618892, 868.8479516084692, 869.695606537713, 870.1994165367845, 870.4559650218613, 834.0616806093145, 861.0442408926118, 862.0948004595213, 863.317978796753, 863.7365603490932, 625.7747211004034, 631.3497097989078, 636.6625688385163, 637.0603360043945, 637.0953246390137, 11394.601188958346, 12575.685607859785, 13620.727585448236, 13645.317734113354, 13694.52069177584, 12847.503642231048, 13144.145630533985, 13180.209187448689, 13196.472141114344, 13321.0, 9569.569369378394, 11297.71755706114, 11326.017946859267, 11473.254238474472, 11539.249948753388, 3053.282478617523, 3520.278342939717, 3638.308721342963, 3660.652238037382, 3679.6688298053305, 61896.1431157066, 64053.67973160104, 89395.55302223512, 89947.0, 90544.42681475592, 8327.6668933242, 10198.43908585035, 10490.265681402381, 10661.626843060456, 10857.348559086322, 16252.51242462728, 19937.900310498353, 20129.0, 20158.0, 20240.98795060241, 7457.105147382349, 7498.175200727916, 7583.734569290048, 7605.163432022473, 7848.13670496245, 4114.773687447219, 4138.151678905817, 4151.667866570667, 4180.247555439989, 4188.853390131391, 3368.9326213476083, 3483.5645544306963, 3483.6690514401093, 3491.546099007113, 3502.3695734767985, 580.2804522392204, 616.8612062285949, 650.4828661214345, 653.8888388973886, 663.3996233408773, 611.9632822030692, 624.3880996623335, 639.402158981354, 653.3531803514486, 658.7299207324974, 7524.887126693104, 8569.871451928226, 8774.824503510023, 9056.456612603133, 9119.589618467186, 568.8919105369966, 618.9721462534106, 656.5141795071708, 660.5105487126594, 685.1572565744162, 3867.7485963412287, 4111.188741391321, 4393.340998850198, 4474.574915383034, 4500.189965806187, 80237.0, 110668.0, 111326.88673113327, 112018.0, 115464.0, 67885.0, 101101.49449252707, 102370.0, 102952.48523757483, 103130.48434757929, 71196.28803711897, 89794.20411591731, 89927.55036224646, 90275.0, 92700.26647202081, 7117.572945623279, 8015.799605009922, 9214.953925230599, 9301.0, 9557.283203759607, 41942.0, 45232.0, 50163.49836501588, 52485.0, 52647.73676131595, 74941.62529187319, 75135.62432187804, 76111.0, 77661.61169194117, 77857.61071194608, 12160.087993400353, 12442.253464792142, 12546.623601291976, 12593.811092833797, 12896.355182240663, 14210.0, 14211.360488777824, 14322.713545729235, 14538.273086345853, 14821.333040013442, 12903.548375806802, 13213.933930330288, 13386.933065334611, 13595.660108497366, 13728.725425491633, 9453.43279403238, 10540.841887371542, 10683.94658026705, 12529.624111276677, 12641.2415255085, 11329.433528323387, 11335.943320283675, 12244.510219591117, 13254.204747715168, 13267.2703001332, 9372.250219982521, 11172.944135279271, 11197.272177308449, 11273.210875238654, 11560.248583842023, 7483.550986940801, 11009.889901101207, 11041.0, 11335.489902954221, 11343.0], "value": 7106.7043246636995, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1582.6999999999998, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [108.0, 175.0, 102.0, 173.0, 174.0, 128.0, 120.0, 119.0, 118.0, 119.0], "value": 133.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 134.0, 143.0, 191.0, 119.0, 119.0, 117.0, 128.0, 164.0, 149.0], "value": 141.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [199.0, 208.0, 271.0, 271.0, 195.0, 198.0, 196.0, 196.0, 198.0, 195.0], "value": 212.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 61.0, 58.0, 82.0, 61.0, 62.0, 59.0, 67.0, 97.0, 84.0], "value": 71.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 71.0, 115.0, 103.0, 101.0, 75.0, 81.0, 71.0, 70.0, 81.0], "value": 85.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 88.0, 90.0, 89.0, 97.0, 105.0, 136.0, 81.0, 137.0, 112.0], "value": 102.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 95.0, 97.0, 97.0, 97.0, 96.0, 119.0, 97.0, 97.0, 95.0], "value": 98.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 97.0, 93.0, 93.0, 111.0, 93.0, 92.0, 95.0, 92.0, 91.0], "value": 94.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 60.0, 56.0, 59.0, 57.0, 58.0, 58.0, 57.0, 59.0, 57.0], "value": 57.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 70.0, 73.0, 82.0, 79.0, 77.0, 71.0, 72.0, 77.0, 73.0], "value": 75.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 87.0, 88.0, 89.0, 90.0, 90.0, 90.0, 90.0, 88.0, 93.0], "value": 89.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [156.0, 162.0, 154.0, 161.0, 146.0, 151.0, 172.0, 163.0, 222.0, 169.0], "value": 165.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [173.0, 176.0, 195.0, 244.0, 184.0, 177.0, 176.0, 176.0, 174.0, 178.0], "value": 185.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 71.0, 72.0, 68.0, 71.0, 67.0, 67.0, 68.0, 66.0, 70.0], "value": 69.1, "unit": "score"}]}]} 18:38:36 INFO - Return code: 0 18:38:36 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 18:38:36 ERROR - # TBPL SUCCESS # 18:38:36 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 18:38:36 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 18:38:36 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 18:38:36 INFO - 2016-11-23 18:38:36.378 screenresolution[1919:17590] starting screenresolution argv=screenresolution get 18:38:36 INFO - 2016-11-23 18:38:36.381 screenresolution[1919:17590] Display 0: 1600x1200x32@60 18:38:36 INFO - 2016-11-23 18:38:36.387 screenresolution[1920:17593] starting screenresolution argv=screenresolution list 18:38:36 INFO - Available Modes on Display 0 18:38:36 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 18:38:36 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 18:38:36 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 18:38:36 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 18:38:36 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 18:38:36 INFO - Intel Iris: 18:38:36 INFO - Chipset Model: Intel Iris 18:38:36 INFO - Type: GPU 18:38:36 INFO - Bus: Built-In 18:38:36 INFO - VRAM (Dynamic, Max): 1536 MB 18:38:36 INFO - Vendor: Intel (0x8086) 18:38:36 INFO - Device ID: 0x0a2e 18:38:36 INFO - Revision ID: 0x0009 18:38:36 INFO - Displays: 18:38:36 INFO - Display: 18:38:36 INFO - Resolution: 1600 x 1200 @ 60 Hz 18:38:36 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 18:38:36 INFO - Display Serial Number: 1600x1200 60 18:38:36 INFO - Main Display: Yes 18:38:36 INFO - Mirror: Off 18:38:36 INFO - Online: Yes 18:38:36 INFO - Rotation: Supported 18:38:36 INFO - Return code: 0 18:38:36 INFO - Running post-action listener: _resource_record_post_action 18:38:36 INFO - [mozharness: 2016-11-24 02:38:36.636826Z] Finished run-tests step (success) 18:38:36 INFO - Running post-run listener: _resource_record_post_run 18:38:36 INFO - Total resource usage - Wall time: 1212s; CPU: 55.0%; Read bytes: 999424; Write bytes: 695768576; Read time: 15; Write time: 2045 18:38:36 INFO - TinderboxPrint: CPU usage
54.7% 18:38:36 INFO - TinderboxPrint: I/O read bytes / time
999,424 / 15 18:38:36 INFO - TinderboxPrint: I/O write bytes / time
695,768,576 / 2,045 18:38:36 INFO - TinderboxPrint: CPU idle
2,198.5 (45.3%) 18:38:36 INFO - TinderboxPrint: CPU system
987.4 (20.4%) 18:38:36 INFO - TinderboxPrint: CPU user
1,663.9 (34.3%) 18:38:36 INFO - TinderboxPrint: Swap in / out
384,225,280 / 0 18:38:36 INFO - install - Wall time: 33s; CPU: 54.0%; Read bytes: 367434240; Write bytes: 367302144; Read time: 27637; Write time: 714 18:38:36 INFO - run-tests - Wall time: 1180s; CPU: 55.0%; Read bytes: 999424; Write bytes: 309936128; Read time: 15; Write time: 1285 18:38:37 INFO - Running post-run listener: _upload_blobber_files 18:38:37 INFO - Blob upload gear active. 18:38:37 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 18:38:37 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 18:38:37 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'] 18:38:37 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 18:38:37 INFO - (blobuploader) - INFO - Open directory for files ... 18:38:37 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 18:38:37 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:38:37 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:38:38 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 18:38:38 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:38:38 INFO - (blobuploader) - INFO - Done attempting. 18:38:38 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 18:38:38 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:38:38 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:38:39 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 18:38:39 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:38:39 INFO - (blobuploader) - INFO - Done attempting. 18:38:39 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 18:38:39 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:38:39 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:38:41 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 18:38:41 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:38:41 INFO - (blobuploader) - INFO - Done attempting. 18:38:41 INFO - (blobuploader) - INFO - Iteration through files over. 18:38:41 INFO - Return code: 0 18:38:41 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 18:38:41 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 18:38:41 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8b3d7488da98f71f0aa2b4630185fe050ae09e7036997bb9a7292f87315f3e096cf1f6472c63ec0cf321bbc687f8cd3ea176d6935878598cf34c90fe92063ad9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6bbaf62953ed27b5daea69d4b5e09e8680dc023e05beb5d00f9aca83950ea302a8eb5b9525a8bc351ff5fc137dacf748c24d973fbd957af8a3c397bd3acc9ab4", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/71993e707c8822fb01ed622c484dc3c2cb07348e43bacea3dee55fcc40a7b737b7384177bce415c7c290e162e8d8e448477432933f29edd65830aace40b7dd1d"} 18:38:41 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 18:38:41 INFO - Writing to file /builds/slave/test/properties/blobber_files 18:38:41 INFO - Contents: 18:38:41 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8b3d7488da98f71f0aa2b4630185fe050ae09e7036997bb9a7292f87315f3e096cf1f6472c63ec0cf321bbc687f8cd3ea176d6935878598cf34c90fe92063ad9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6bbaf62953ed27b5daea69d4b5e09e8680dc023e05beb5d00f9aca83950ea302a8eb5b9525a8bc351ff5fc137dacf748c24d973fbd957af8a3c397bd3acc9ab4", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/71993e707c8822fb01ed622c484dc3c2cb07348e43bacea3dee55fcc40a7b737b7384177bce415c7c290e162e8d8e448477432933f29edd65830aace40b7dd1d"} 18:38:41 INFO - Running post-run listener: copy_logs_to_upload_dir 18:38:41 INFO - Copying logs to upload dir... 18:38:41 INFO - mkdir: /builds/slave/test/build/upload/logs 18:38:41 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1272.944639 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 12 secs) (at 2016-11-23 18:38:41.759088) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-23 18:38:41.762962) ========= 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.ZKsv634csA/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.s7KhYF5v4a/Listeners TMPDIR=/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8b3d7488da98f71f0aa2b4630185fe050ae09e7036997bb9a7292f87315f3e096cf1f6472c63ec0cf321bbc687f8cd3ea176d6935878598cf34c90fe92063ad9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6bbaf62953ed27b5daea69d4b5e09e8680dc023e05beb5d00f9aca83950ea302a8eb5b9525a8bc351ff5fc137dacf748c24d973fbd957af8a3c397bd3acc9ab4", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/71993e707c8822fb01ed622c484dc3c2cb07348e43bacea3dee55fcc40a7b737b7384177bce415c7c290e162e8d8e448477432933f29edd65830aace40b7dd1d"} build_url:https://queue.taskcluster.net/v1/task/WAgGw4aTSRm02Ai6JIZioQ/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011213 build_url: 'https://queue.taskcluster.net/v1/task/WAgGw4aTSRm02Ai6JIZioQ/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8b3d7488da98f71f0aa2b4630185fe050ae09e7036997bb9a7292f87315f3e096cf1f6472c63ec0cf321bbc687f8cd3ea176d6935878598cf34c90fe92063ad9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6bbaf62953ed27b5daea69d4b5e09e8680dc023e05beb5d00f9aca83950ea302a8eb5b9525a8bc351ff5fc137dacf748c24d973fbd957af8a3c397bd3acc9ab4", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/71993e707c8822fb01ed622c484dc3c2cb07348e43bacea3dee55fcc40a7b737b7384177bce415c7c290e162e8d8e448477432933f29edd65830aace40b7dd1d"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-23 18:38:41.797763) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-23 18:38:41.798080) ========= 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.ZKsv634csA/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.s7KhYF5v4a/Listeners TMPDIR=/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/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.005137 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-23 18:38:41.846411) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-23 18:38:41.846738) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-23 18:38:41.847063) ========= ========= Total master_lag: 0.34 =========