builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0385 starttime: 1479907678.98 results: success (0) buildid: 20161123030208 builduid: f4871a9f0cf64f1e8d578ca886745a14 revision: 0ddfec7126ec503b54df9c4b7c3b988906f6c882 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-11-23 05:27:58.975504) ========= master: http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-11-23 05:27:58.975974) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-23 05:27:58.976255) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-23 05:27:59.003022) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-23 05:27:59.003435) ========= 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.8FBCNktbhp/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.ptvva15s3s/Listeners TMPDIR=/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/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 05:27:59-- 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% 70.4M=0s 2016-11-23 05:27:59 (70.4 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.173763 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-23 05:27:59.201184) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-23 05:27:59.201536) ========= 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.8FBCNktbhp/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.ptvva15s3s/Listeners TMPDIR=/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/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.024995 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-23 05:27:59.251880) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-11-23 05:27:59.252490) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 0ddfec7126ec503b54df9c4b7c3b988906f6c882 --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 0ddfec7126ec503b54df9c4b7c3b988906f6c882 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.8FBCNktbhp/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.ptvva15s3s/Listeners TMPDIR=/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/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 05:27:59,353 truncating revision to first 12 chars 2016-11-23 05:27:59,353 Setting DEBUG logging. 2016-11-23 05:27:59,353 attempt 1/10 2016-11-23 05:27:59,353 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/0ddfec7126ec?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-11-23 05:28:01,501 unpacking tar archive at: mozilla-central-0ddfec7126ec/testing/mozharness/ program finished with exit code 0 elapsedTime=2.427839 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-11-23 05:28:01.704656) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-23 05:28:01.705045) ========= script_repo_revision: 0ddfec7126ec503b54df9c4b7c3b988906f6c882 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-23 05:28:01.705500) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-23 05:28:01.705847) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-23 05:28:01.726006) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 17 secs) (at 2016-11-23 05:28:01.726565) ========= /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.8FBCNktbhp/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.ptvva15s3s/Listeners TMPDIR=/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/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 05:28:01 INFO - MultiFileLogger online at 20161123 05:28:01 in /builds/slave/test 05:28:01 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 05:28:01 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:28:01 INFO - {'append_to_log': False, 05:28:01 INFO - 'base_work_dir': '/builds/slave/test', 05:28:01 INFO - 'blob_upload_branch': 'Firefox', 05:28:01 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:28:01 INFO - 'branch': 'Firefox', 05:28:01 INFO - 'buildbot_json_path': 'buildprops.json', 05:28:01 INFO - 'config_files': ('talos/mac_config.py',), 05:28:01 INFO - 'default_actions': ('clobber', 05:28:01 INFO - 'read-buildbot-config', 05:28:01 INFO - 'download-and-extract', 05:28:01 INFO - 'populate-webroot', 05:28:01 INFO - 'create-virtualenv', 05:28:01 INFO - 'install', 05:28:01 INFO - 'run-tests'), 05:28:01 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:28:01 INFO - 'download_minidump_stackwalk': True, 05:28:01 INFO - 'download_symbols': 'ondemand', 05:28:01 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:28:01 INFO - 'tooltool.py': '/tools/tooltool.py', 05:28:01 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:28:01 INFO - '/tools/misc-python/virtualenv.py')}, 05:28:01 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:28:01 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:28:01 INFO - 'installer_path': 'installer.exe', 05:28:01 INFO - 'log_level': 'info', 05:28:01 INFO - 'log_name': 'talos', 05:28:01 INFO - 'log_to_console': True, 05:28:01 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 05:28:01 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 05:28:01 INFO - 'opt_config_files': (), 05:28:01 INFO - 'pip_index': False, 05:28:01 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:28:01 INFO - 'cmd': ('bash', 05:28:01 INFO - '-c', 05:28:01 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:28:01 INFO - 'enabled': True, 05:28:01 INFO - 'halt_on_failure': False, 05:28:01 INFO - 'name': 'check_screen_resolution'},), 05:28:01 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:28:01 INFO - 'cmd': ('bash', 05:28:01 INFO - '-c', 05:28:01 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:28:01 INFO - 'enabled': True, 05:28:01 INFO - 'halt_on_failure': False, 05:28:01 INFO - 'name': 'check_screen_resolution'},), 05:28:01 INFO - 'run_cmd_checks_enabled': True, 05:28:01 INFO - 'sps_profile': False, 05:28:01 INFO - 'sps_profile_interval': 0, 05:28:01 INFO - 'suite': 'dromaeojs-e10s', 05:28:01 INFO - 'system_bits': '32', 05:28:01 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:28:01 INFO - 'title': 't-yosemite-r7-0385', 05:28:01 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:28:01 INFO - 'use_talos_json': True, 05:28:01 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:28:01 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:28:01 INFO - 'work_dir': 'build'} 05:28:01 INFO - [mozharness: 2016-11-23 13:28:01.935275Z] Running clobber step. 05:28:01 INFO - Running pre-action listener: _resource_record_pre_action 05:28:01 INFO - Running main action method: clobber 05:28:01 INFO - rmtree: /builds/slave/test/build 05:28:01 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:28:04 INFO - Running post-action listener: _resource_record_post_action 05:28:04 INFO - [mozharness: 2016-11-23 13:28:04.214589Z] Finished clobber step (success) 05:28:04 INFO - [mozharness: 2016-11-23 13:28:04.214742Z] Running read-buildbot-config step. 05:28:04 INFO - Running pre-action listener: _resource_record_pre_action 05:28:04 INFO - Running main action method: read_buildbot_config 05:28:04 INFO - Using buildbot properties: 05:28:04 INFO - { 05:28:04 INFO - "project": "", 05:28:04 INFO - "product": "firefox", 05:28:04 INFO - "script_repo_revision": "production", 05:28:04 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 05:28:04 INFO - "repository": "", 05:28:04 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 05:28:04 INFO - "buildid": "20161123030208", 05:28:04 INFO - "stage_platform": "macosx64", 05:28:04 INFO - "basedir": "/builds/slave/test", 05:28:04 INFO - "nightly_build": "True", 05:28:04 INFO - "buildnumber": 94, 05:28:04 INFO - "slavename": "t-yosemite-r7-0385", 05:28:04 INFO - "master": "http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/", 05:28:04 INFO - "platform": "yosemite_r7", 05:28:04 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 05:28:04 INFO - "branch": "mozilla-central", 05:28:04 INFO - "repo_path": "mozilla-central", 05:28:04 INFO - "revision": "0ddfec7126ec503b54df9c4b7c3b988906f6c882", 05:28:04 INFO - "pgo_build": "False", 05:28:04 INFO - "builduid": "f4871a9f0cf64f1e8d578ca886745a14", 05:28:04 INFO - "slavebuilddir": "test" 05:28:04 INFO - } 05:28:04 INFO - Found installer url https://queue.taskcluster.net/v1/task/XV6MW-jpSf2qB-jdeZep1A/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg. 05:28:04 INFO - Running post-action listener: _resource_record_post_action 05:28:04 INFO - [mozharness: 2016-11-23 13:28:04.218317Z] Finished read-buildbot-config step (success) 05:28:04 INFO - [mozharness: 2016-11-23 13:28:04.218449Z] Running download-and-extract step. 05:28:04 INFO - Running pre-action listener: _resource_record_pre_action 05:28:04 INFO - Running main action method: download_and_extract 05:28:04 INFO - mkdir: /builds/slave/test/build/tests 05:28:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:28:04 INFO - trying https://queue.taskcluster.net/v1/task/XV6MW-jpSf2qB-jdeZep1A/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json 05:28:04 INFO - Downloading https://queue.taskcluster.net/v1/task/XV6MW-jpSf2qB-jdeZep1A/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 05:28:04 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/XV6MW-jpSf2qB-jdeZep1A/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 05:28:05 INFO - Downloaded 1199 bytes. 05:28:05 INFO - Reading from file /builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json 05:28:05 INFO - Using the following test package requirements: 05:28:05 INFO - {u'common': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 05:28:05 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 05:28:05 INFO - u'firefox-53.0a1.en-US.mac.cppunittest.tests.zip'], 05:28:05 INFO - u'gtest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 05:28:05 INFO - u'firefox-53.0a1.en-US.mac.gtest.tests.zip'], 05:28:05 INFO - u'jittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 05:28:05 INFO - u'jsshell-mac.zip'], 05:28:05 INFO - u'mochitest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 05:28:05 INFO - u'firefox-53.0a1.en-US.mac.mochitest.tests.zip'], 05:28:05 INFO - u'mozbase': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 05:28:05 INFO - u'reftest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 05:28:05 INFO - u'firefox-53.0a1.en-US.mac.reftest.tests.zip'], 05:28:05 INFO - u'talos': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 05:28:05 INFO - u'firefox-53.0a1.en-US.mac.talos.tests.zip'], 05:28:05 INFO - u'web-platform': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 05:28:05 INFO - u'firefox-53.0a1.en-US.mac.web-platform.tests.zip'], 05:28:05 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 05:28:05 INFO - u'firefox-53.0a1.en-US.mac.xpcshell.tests.zip']} 05:28:05 INFO - Downloading packages: [u'firefox-53.0a1.en-US.mac.common.tests.zip'] for test suite category: common 05:28:05 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/XV6MW-jpSf2qB-jdeZep1A/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 05:28:05 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/XV6MW-jpSf2qB-jdeZep1A/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 05:28:05 INFO - Fetch https://queue.taskcluster.net/v1/task/XV6MW-jpSf2qB-jdeZep1A/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 05:28:06 INFO - Http code: 200 05:28:06 INFO - via: 1.1 1b52a5dd431f9e3c81753e61dfdf467a.cloudfront.net (CloudFront) 05:28:06 INFO - x-amz-cf-id: l0_23WtM6lYxoM1rIHcKd9uiWW5Hg4BDMPiBtdVeYWl9qIGLLkXZNw== 05:28:06 INFO - x-amz-version-id: vcWn6PjYNn3AhzJJD8CRnsUW8VCIwjU4 05:28:07 INFO - Expected file size: 16267236 05:28:07 INFO - Obtained file size: 16267236 05:28:14 INFO - Downloading packages: [u'firefox-53.0a1.en-US.mac.common.tests.zip', u'firefox-53.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 05:28:14 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/XV6MW-jpSf2qB-jdeZep1A/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 05:28:14 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/XV6MW-jpSf2qB-jdeZep1A/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 05:28:14 INFO - Fetch https://queue.taskcluster.net/v1/task/XV6MW-jpSf2qB-jdeZep1A/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 05:28:14 INFO - Http code: 200 05:28:14 INFO - via: 1.1 423912f2b1c5569e2fa5017167c61fa0.cloudfront.net (CloudFront) 05:28:14 INFO - x-amz-cf-id: HopcQtqSBjOYP8HuHFN1W6kRRW7iI2ZP1oFi8MRNIdCOlNi9HeN4Eg== 05:28:14 INFO - x-amz-version-id: vcWn6PjYNn3AhzJJD8CRnsUW8VCIwjU4 05:28:14 INFO - Expected file size: 16267236 05:28:14 INFO - Obtained file size: 16267236 05:28:21 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/XV6MW-jpSf2qB-jdeZep1A/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip 05:28:21 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/XV6MW-jpSf2qB-jdeZep1A/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip'}, attempt #1 05:28:21 INFO - Fetch https://queue.taskcluster.net/v1/task/XV6MW-jpSf2qB-jdeZep1A/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip into memory 05:28:21 INFO - Http code: 200 05:28:21 INFO - via: 1.1 9baadab4e4d95afe7af7bcba342b29c6.cloudfront.net (CloudFront) 05:28:21 INFO - x-amz-cf-id: PxZ6-msiDXIHqdD1rVQa66cU-ysEXhKC8baYNgBH6asOillwNpFsBg== 05:28:21 INFO - x-amz-version-id: z3q583wKmwQ04QNBkHK66C5xH2nA4JrI 05:28:22 INFO - Expected file size: 14409506 05:28:22 INFO - Obtained file size: 14409506 05:28:23 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')]} 05:28:23 INFO - trying https://queue.taskcluster.net/v1/task/XV6MW-jpSf2qB-jdeZep1A/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 05:28:23 INFO - Downloading https://queue.taskcluster.net/v1/task/XV6MW-jpSf2qB-jdeZep1A/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg 05:28:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/XV6MW-jpSf2qB-jdeZep1A/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 05:28:26 INFO - Downloaded 107673113 bytes. 05:28:26 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/XV6MW-jpSf2qB-jdeZep1A/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 05:28:26 INFO - mkdir: /builds/slave/test/properties 05:28:26 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:28:26 INFO - Writing to file /builds/slave/test/properties/build_url 05:28:26 INFO - Contents: 05:28:26 INFO - build_url:https://queue.taskcluster.net/v1/task/XV6MW-jpSf2qB-jdeZep1A/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 05:28:27 INFO - Running post-action listener: _resource_record_post_action 05:28:27 INFO - Running post-action listener: set_extra_try_arguments 05:28:27 INFO - [mozharness: 2016-11-23 13:28:27.115334Z] Finished download-and-extract step (success) 05:28:27 INFO - [mozharness: 2016-11-23 13:28:27.115475Z] Running populate-webroot step. 05:28:27 INFO - Running pre-action listener: _resource_record_pre_action 05:28:27 INFO - Running main action method: populate_webroot 05:28:27 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:28:27 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:28:27 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:28:27 INFO - '--tppagecycles', 05:28:27 INFO - '7'], 05:28:27 INFO - 'tests': ['tsvgm']}}, 05:28:27 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 05:28:27 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:28:27 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:28:27 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 05:28:27 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:28:27 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:28:27 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 05:28:27 INFO - 'talos_options': ['--disable-e10s'], 05:28:27 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:28:27 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 05:28:27 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:28:27 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 05:28:27 INFO - 'talos_options': ['--disable-e10s'], 05:28:27 INFO - 'tests': ['damp', 'tps']}, 05:28:27 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 05:28:27 INFO - 'tests': ['damp', 'tps']}, 05:28:27 INFO - 'g3': {'talos_options': ['--disable-e10s'], 05:28:27 INFO - 'tests': ['dromaeo_dom']}, 05:28:27 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:28:27 INFO - 'g4': {'talos_options': ['--disable-e10s'], 05:28:27 INFO - 'tests': ['basic_compositor_video']}, 05:28:27 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 05:28:27 INFO - 'other': {'talos_options': ['--disable-e10s'], 05:28:27 INFO - 'tests': ['a11yr', 05:28:27 INFO - 'ts_paint', 05:28:27 INFO - 'tpaint', 05:28:27 INFO - 'sessionrestore', 05:28:27 INFO - 'sessionrestore_no_auto_restore', 05:28:27 INFO - 'tabpaint']}, 05:28:27 INFO - 'other-e10s': {'tests': ['a11yr', 05:28:27 INFO - 'ts_paint', 05:28:27 INFO - 'tpaint', 05:28:27 INFO - 'sessionrestore', 05:28:27 INFO - 'sessionrestore_no_auto_restore', 05:28:27 INFO - 'tabpaint']}, 05:28:27 INFO - 'other-e10s_l64': {'tests': ['a11yr', 05:28:27 INFO - 'ts_paint', 05:28:27 INFO - 'tpaint', 05:28:27 INFO - 'sessionrestore', 05:28:27 INFO - 'sessionrestore_no_auto_restore', 05:28:27 INFO - 'tabpaint']}, 05:28:27 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 05:28:27 INFO - 'ts_paint', 05:28:27 INFO - 'tpaint', 05:28:27 INFO - 'sessionrestore', 05:28:27 INFO - 'sessionrestore_no_auto_restore', 05:28:27 INFO - 'tabpaint']}, 05:28:27 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 05:28:27 INFO - 'tests': ['a11yr', 05:28:27 INFO - 'ts_paint', 05:28:27 INFO - 'tpaint', 05:28:27 INFO - 'sessionrestore', 05:28:27 INFO - 'sessionrestore_no_auto_restore', 05:28:27 INFO - 'tabpaint']}, 05:28:27 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 05:28:27 INFO - 'tests': ['a11yr', 05:28:27 INFO - 'ts_paint', 05:28:27 INFO - 'tpaint', 05:28:27 INFO - 'sessionrestore', 05:28:27 INFO - 'sessionrestore_no_auto_restore', 05:28:27 INFO - 'tabpaint']}, 05:28:27 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 05:28:27 INFO - 'tests': ['tsvgx', 05:28:27 INFO - 'tsvgr_opacity', 05:28:27 INFO - 'tart', 05:28:27 INFO - 'tscrollx', 05:28:27 INFO - 'cart']}, 05:28:27 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:28:27 INFO - 'tsvgr_opacity', 05:28:27 INFO - 'tart', 05:28:27 INFO - 'tscrollx', 05:28:27 INFO - 'cart']}, 05:28:27 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 05:28:27 INFO - 'talos_options': ['--disable-e10s'], 05:28:27 INFO - 'tests': ['tp5o']}, 05:28:27 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 05:28:27 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 05:28:27 INFO - 'talos_options': ['--disable-e10s', 05:28:27 INFO - '--xperf_path', 05:28:27 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:28:27 INFO - 'tests': ['tp5n']}, 05:28:27 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 05:28:27 INFO - 'talos_options': ['--xperf_path', 05:28:27 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:28:27 INFO - 'tests': ['tp5n']}}, 05:28:27 INFO - 'talos.zip': {'path': '', 05:28:27 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:28:27 INFO - Running post-action listener: _resource_record_post_action 05:28:27 INFO - [mozharness: 2016-11-23 13:28:27.133308Z] Finished populate-webroot step (success) 05:28:27 INFO - [mozharness: 2016-11-23 13:28:27.133442Z] Running create-virtualenv step. 05:28:27 INFO - Running pre-action listener: _resource_record_pre_action 05:28:27 INFO - Running main action method: create_virtualenv 05:28:27 INFO - Creating virtualenv /builds/slave/test/build/venv 05:28:27 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 05:28:27 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:28:27 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:28:27 INFO - Using real prefix '/tools/python27' 05:28:27 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:28:27 INFO - Installing distribute.............................................................................................................................................................................................done. 05:28:30 INFO - Installing pip.................done. 05:28:30 INFO - Return code: 0 05:28:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 05:28:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 05:28:31 INFO - Reading from file tmpfile_stdout 05:28:31 INFO - Output received: 05:28:31 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) 05:28:31 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:28:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:28:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:28:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:28:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:28:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:28:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:28:31 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x101c073f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101b7d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9203c9e3b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101a1dd30>, '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 0x101884db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101a15b20>, '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.8FBCNktbhp/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.ptvva15s3s/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/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 05:28:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 05:28:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 05:28:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8FBCNktbhp/Render', 05:28:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:28:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:28:31 INFO - 'HOME': '/Users/cltbld', 05:28:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:28:31 INFO - 'LOGNAME': 'cltbld', 05:28:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:28:31 INFO - 'MOZ_NO_REMOTE': '1', 05:28:31 INFO - 'NO_EM_RESTART': '1', 05:28:31 INFO - 'PAGER': '/bin/cat', 05:28:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:28:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:28:31 INFO - 'PWD': '/builds/slave/test', 05:28:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:28:31 INFO - 'SHELL': '/bin/bash', 05:28:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ptvva15s3s/Listeners', 05:28:31 INFO - 'TMPDIR': '/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/', 05:28:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:28:31 INFO - 'USER': 'cltbld', 05:28:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:28:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:28:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:28:31 INFO - 'XPC_FLAGS': '0x0', 05:28:31 INFO - 'XPC_SERVICE_NAME': '0', 05:28:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:28:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:28:31 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 05:28:31 INFO - Cleaning up... 05:28:31 INFO - Return code: 0 05:28:31 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 05:28:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:28:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:28:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:28:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:28:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:28:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:28:31 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x101c073f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101b7d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9203c9e3b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101a1dd30>, '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 0x101884db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101a15b20>, '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.8FBCNktbhp/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.ptvva15s3s/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/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 05:28:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 05:28:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 05:28:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8FBCNktbhp/Render', 05:28:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:28:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:28:31 INFO - 'HOME': '/Users/cltbld', 05:28:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:28:31 INFO - 'LOGNAME': 'cltbld', 05:28:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:28:31 INFO - 'MOZ_NO_REMOTE': '1', 05:28:31 INFO - 'NO_EM_RESTART': '1', 05:28:31 INFO - 'PAGER': '/bin/cat', 05:28:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:28:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:28:31 INFO - 'PWD': '/builds/slave/test', 05:28:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:28:31 INFO - 'SHELL': '/bin/bash', 05:28:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ptvva15s3s/Listeners', 05:28:31 INFO - 'TMPDIR': '/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/', 05:28:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:28:31 INFO - 'USER': 'cltbld', 05:28:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:28:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:28:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:28:31 INFO - 'XPC_FLAGS': '0x0', 05:28:31 INFO - 'XPC_SERVICE_NAME': '0', 05:28:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:28:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:28:31 INFO - Downloading/unpacking psutil>=3.1.1 05:28:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:28:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:28:31 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:28:31 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:28:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:28:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:28:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:28:35 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:28:35 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:28:35 INFO - Installing collected packages: psutil 05:28:35 INFO - Running setup.py install for psutil 05:28:35 INFO - building 'psutil._psutil_osx' extension 05:28:35 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 05:28:35 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 05:28:35 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 05:28:35 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 05:28:35 INFO - building 'psutil._psutil_posix' extension 05:28:35 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 05:28:35 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 05:28:35 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 05:28:35 INFO - ^ 05:28:36 INFO - 1 warning generated. 05:28:36 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 05:28:36 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:28:36 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:28:36 INFO - Successfully installed psutil 05:28:36 INFO - Cleaning up... 05:28:36 INFO - Return code: 0 05:28:36 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 05:28:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:28:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:28:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:28:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:28:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:28:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:28:36 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x101c073f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101b7d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9203c9e3b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101a1dd30>, '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 0x101884db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101a15b20>, '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.8FBCNktbhp/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.ptvva15s3s/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/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 05:28:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 05:28:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 05:28:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8FBCNktbhp/Render', 05:28:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:28:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:28:36 INFO - 'HOME': '/Users/cltbld', 05:28:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:28:36 INFO - 'LOGNAME': 'cltbld', 05:28:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:28:36 INFO - 'MOZ_NO_REMOTE': '1', 05:28:36 INFO - 'NO_EM_RESTART': '1', 05:28:36 INFO - 'PAGER': '/bin/cat', 05:28:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:28:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:28:36 INFO - 'PWD': '/builds/slave/test', 05:28:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:28:36 INFO - 'SHELL': '/bin/bash', 05:28:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ptvva15s3s/Listeners', 05:28:36 INFO - 'TMPDIR': '/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/', 05:28:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:28:36 INFO - 'USER': 'cltbld', 05:28:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:28:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:28:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:28:36 INFO - 'XPC_FLAGS': '0x0', 05:28:36 INFO - 'XPC_SERVICE_NAME': '0', 05:28:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:28:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:28:36 INFO - Downloading/unpacking mozsystemmonitor==0.3 05:28:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:28:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:28:36 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:28:36 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:28:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:28:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:28:38 INFO - Downloading mozsystemmonitor-0.3.tar.gz 05:28:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:28:39 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 05:28:39 INFO - Installing collected packages: mozsystemmonitor 05:28:39 INFO - Running setup.py install for mozsystemmonitor 05:28:39 INFO - Successfully installed mozsystemmonitor 05:28:39 INFO - Cleaning up... 05:28:39 INFO - Return code: 0 05:28:39 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 05:28:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:28:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:28:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:28:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:28:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:28:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:28:39 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x101c073f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101b7d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9203c9e3b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101a1dd30>, '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 0x101884db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101a15b20>, '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.8FBCNktbhp/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.ptvva15s3s/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/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 05:28:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 05:28:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 05:28:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8FBCNktbhp/Render', 05:28:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:28:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:28:39 INFO - 'HOME': '/Users/cltbld', 05:28:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:28:39 INFO - 'LOGNAME': 'cltbld', 05:28:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:28:39 INFO - 'MOZ_NO_REMOTE': '1', 05:28:39 INFO - 'NO_EM_RESTART': '1', 05:28:39 INFO - 'PAGER': '/bin/cat', 05:28:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:28:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:28:39 INFO - 'PWD': '/builds/slave/test', 05:28:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:28:39 INFO - 'SHELL': '/bin/bash', 05:28:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ptvva15s3s/Listeners', 05:28:39 INFO - 'TMPDIR': '/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/', 05:28:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:28:39 INFO - 'USER': 'cltbld', 05:28:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:28:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:28:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:28:39 INFO - 'XPC_FLAGS': '0x0', 05:28:39 INFO - 'XPC_SERVICE_NAME': '0', 05:28:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:28:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:28:39 INFO - Downloading/unpacking jsonschema==2.5.1 05:28:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:28:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:28:39 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:28:39 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:28:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:28:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:28:42 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:28:42 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 05:28:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:28:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:28:42 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:28:42 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:28:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:28:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:28:42 INFO - Downloading functools32-3.2.3-2.tar.gz 05:28:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:28:42 INFO - warning: no files found matching '*.txt' 05:28:42 INFO - no previously-included directories found matching 'build' 05:28:42 INFO - no previously-included directories found matching 'dist' 05:28:42 INFO - no previously-included directories found matching '.git*' 05:28:42 INFO - Installing collected packages: jsonschema, functools32 05:28:42 INFO - Running setup.py install for functools32 05:28:42 INFO - warning: no files found matching '*.txt' 05:28:42 INFO - no previously-included directories found matching 'build' 05:28:42 INFO - no previously-included directories found matching 'dist' 05:28:42 INFO - no previously-included directories found matching '.git*' 05:28:42 INFO - Successfully installed jsonschema functools32 05:28:42 INFO - Cleaning up... 05:28:42 INFO - Return code: 0 05:28:42 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 05:28:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:28:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:28:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:28:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:28:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:28:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:28:42 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x101c073f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101b7d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9203c9e3b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101a1dd30>, '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 0x101884db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101a15b20>, '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.8FBCNktbhp/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.ptvva15s3s/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/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 05:28:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 05:28:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 05:28:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8FBCNktbhp/Render', 05:28:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:28:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:28:42 INFO - 'HOME': '/Users/cltbld', 05:28:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:28:42 INFO - 'LOGNAME': 'cltbld', 05:28:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:28:42 INFO - 'MOZ_NO_REMOTE': '1', 05:28:42 INFO - 'NO_EM_RESTART': '1', 05:28:42 INFO - 'PAGER': '/bin/cat', 05:28:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:28:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:28:42 INFO - 'PWD': '/builds/slave/test', 05:28:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:28:42 INFO - 'SHELL': '/bin/bash', 05:28:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ptvva15s3s/Listeners', 05:28:42 INFO - 'TMPDIR': '/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/', 05:28:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:28:42 INFO - 'USER': 'cltbld', 05:28:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:28:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:28:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:28:42 INFO - 'XPC_FLAGS': '0x0', 05:28:42 INFO - 'XPC_SERVICE_NAME': '0', 05:28:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:28:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:28:43 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 05:28:43 INFO - Cleaning up... 05:28:43 INFO - Return code: 0 05:28:43 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:28:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:28:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:28:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:28:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:28:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:28:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:28:43 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x101c073f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101b7d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9203c9e3b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101a1dd30>, '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 0x101884db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101a15b20>, '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.8FBCNktbhp/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.ptvva15s3s/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/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 05:28:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 05:28:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 05:28:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8FBCNktbhp/Render', 05:28:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:28:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:28:43 INFO - 'HOME': '/Users/cltbld', 05:28:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:28:43 INFO - 'LOGNAME': 'cltbld', 05:28:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:28:43 INFO - 'MOZ_NO_REMOTE': '1', 05:28:43 INFO - 'NO_EM_RESTART': '1', 05:28:43 INFO - 'PAGER': '/bin/cat', 05:28:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:28:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:28:43 INFO - 'PWD': '/builds/slave/test', 05:28:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:28:43 INFO - 'SHELL': '/bin/bash', 05:28:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ptvva15s3s/Listeners', 05:28:43 INFO - 'TMPDIR': '/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/', 05:28:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:28:43 INFO - 'USER': 'cltbld', 05:28:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:28:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:28:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:28:43 INFO - 'XPC_FLAGS': '0x0', 05:28:43 INFO - 'XPC_SERVICE_NAME': '0', 05:28:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:28:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:28:43 INFO - Downloading/unpacking blobuploader==1.2.4 05:28:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:28:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:28:43 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:28:43 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:28:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:28:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:28:45 INFO - Downloading blobuploader-1.2.4.tar.gz 05:28:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:28:45 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:28:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:28:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:28:45 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:28:46 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:28:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:28:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:28:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:28:46 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:28:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:28:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:28:46 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:28:46 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:28:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:28:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:28:47 INFO - Downloading docopt-0.6.1.tar.gz 05:28:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:28:47 INFO - Installing collected packages: blobuploader, requests, docopt 05:28:47 INFO - Running setup.py install for blobuploader 05:28:47 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:28:47 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:28:47 INFO - Running setup.py install for requests 05:28:47 INFO - Running setup.py install for docopt 05:28:47 INFO - Successfully installed blobuploader requests docopt 05:28:47 INFO - Cleaning up... 05:28:48 INFO - Return code: 0 05:28:48 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:28:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:28:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:28:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:28:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:28:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:28:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:28:48 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 0x101c073f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101b7d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9203c9e3b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101a1dd30>, '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 0x101884db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101a15b20>, '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.8FBCNktbhp/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.ptvva15s3s/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/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 05:28:48 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 05:28:48 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 05:28:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8FBCNktbhp/Render', 05:28:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:28:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:28:48 INFO - 'HOME': '/Users/cltbld', 05:28:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:28:48 INFO - 'LOGNAME': 'cltbld', 05:28:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:28:48 INFO - 'MOZ_NO_REMOTE': '1', 05:28:48 INFO - 'NO_EM_RESTART': '1', 05:28:48 INFO - 'PAGER': '/bin/cat', 05:28:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:28:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:28:48 INFO - 'PWD': '/builds/slave/test', 05:28:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:28:48 INFO - 'SHELL': '/bin/bash', 05:28:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ptvva15s3s/Listeners', 05:28:48 INFO - 'TMPDIR': '/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/', 05:28:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:28:48 INFO - 'USER': 'cltbld', 05:28:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:28:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:28:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:28:48 INFO - 'XPC_FLAGS': '0x0', 05:28:48 INFO - 'XPC_SERVICE_NAME': '0', 05:28:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:28:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:28:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:28:48 INFO - Running setup.py (path:/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-r7TsUN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:28:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:28:48 INFO - Running setup.py (path:/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-qY0U59-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:28:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:28:48 INFO - Running setup.py (path:/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-T5Vwbx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:28:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:28:48 INFO - Running setup.py (path:/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-U6aYTF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:28:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:28:48 INFO - Running setup.py (path:/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-95EM3X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:28:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:28:48 INFO - Running setup.py (path:/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-2i3iXY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:28:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:28:48 INFO - Running setup.py (path:/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-JheXSu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:28:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:28:49 INFO - Running setup.py (path:/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-HFaRGw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:28:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:28:49 INFO - Running setup.py (path:/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-m65aJn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:28:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:28:49 INFO - Running setup.py (path:/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-S1U7ph-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:28:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:28:49 INFO - Running setup.py (path:/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-n7SWcv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:28:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:28:49 INFO - Running setup.py (path:/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-Em8FR9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:28:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:28:49 INFO - Running setup.py (path:/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-2Dlxw8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:28:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:28:49 INFO - Running setup.py (path:/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-Xb2u8P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:28:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:28:49 INFO - Running setup.py (path:/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-aEiQOr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:28:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:28:49 INFO - Running setup.py (path:/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-IoDMdv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:28:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:28:49 INFO - Running setup.py (path:/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-alV5Qt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:28:50 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:28:50 INFO - Running setup.py install for manifestparser 05:28:50 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:28:50 INFO - Running setup.py install for mozcrash 05:28:50 INFO - Running setup.py install for mozdebug 05:28:50 INFO - Running setup.py install for mozdevice 05:28:50 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 05:28:50 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:28:50 INFO - Running setup.py install for mozfile 05:28:50 INFO - Running setup.py install for mozhttpd 05:28:50 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:28:50 INFO - Running setup.py install for mozinfo 05:28:51 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:28:51 INFO - Running setup.py install for mozInstall 05:28:51 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:28:51 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:28:51 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:28:51 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:28:51 INFO - Running setup.py install for mozleak 05:28:51 INFO - Running setup.py install for mozlog 05:28:51 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:28:51 INFO - Running setup.py install for moznetwork 05:28:51 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:28:51 INFO - Running setup.py install for mozprocess 05:28:51 INFO - Running setup.py install for mozprofile 05:28:52 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:28:52 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:28:52 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:28:52 INFO - Running setup.py install for mozrunner 05:28:52 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:28:52 INFO - Running setup.py install for mozscreenshot 05:28:52 INFO - Running setup.py install for moztest 05:28:52 INFO - Running setup.py install for mozversion 05:28:52 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:28:52 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:28:52 INFO - Cleaning up... 05:28:52 INFO - Return code: 0 05:28:52 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:28:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:28:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:28:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:28:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:28:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:28:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:28:52 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x101c073f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101b7d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9203c9e3b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101a1dd30>, '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 0x101884db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101a15b20>, '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.8FBCNktbhp/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.ptvva15s3s/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/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 05:28:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 05:28:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:28:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8FBCNktbhp/Render', 05:28:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:28:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:28:52 INFO - 'HOME': '/Users/cltbld', 05:28:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:28:52 INFO - 'LOGNAME': 'cltbld', 05:28:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:28:52 INFO - 'MOZ_NO_REMOTE': '1', 05:28:52 INFO - 'NO_EM_RESTART': '1', 05:28:52 INFO - 'PAGER': '/bin/cat', 05:28:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:28:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:28:52 INFO - 'PWD': '/builds/slave/test', 05:28:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:28:52 INFO - 'SHELL': '/bin/bash', 05:28:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ptvva15s3s/Listeners', 05:28:52 INFO - 'TMPDIR': '/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/', 05:28:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:28:52 INFO - 'USER': 'cltbld', 05:28:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:28:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:28:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:28:52 INFO - 'XPC_FLAGS': '0x0', 05:28:52 INFO - 'XPC_SERVICE_NAME': '0', 05:28:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:28:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:28:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:28:52 INFO - Running setup.py (path:/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-FugH7o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:28:53 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)) 05:28:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:28:53 INFO - Running setup.py (path:/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-Hm4oir-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:28:53 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)) 05:28:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:28:53 INFO - Running setup.py (path:/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-1AqXVP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:28:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 05:28:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:28:53 INFO - Running setup.py (path:/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-yq4l9t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:28:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:28:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:28:53 INFO - Running setup.py (path:/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-9hmXGK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:28:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 05:28:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:28:53 INFO - Running setup.py (path:/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-ohUEq1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:28:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 05:28:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:28:53 INFO - Running setup.py (path:/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-agaMoe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:28:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 05:28:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:28:53 INFO - Running setup.py (path:/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-RBmNMF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:28:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 05:28:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:28:53 INFO - Running setup.py (path:/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-nDfK7S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:28:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 05:28:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:28:53 INFO - Running setup.py (path:/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-XiDl3f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:28:54 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)) 05:28:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:28:54 INFO - Running setup.py (path:/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-22ML_i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:28:54 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)) 05:28:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:28:54 INFO - Running setup.py (path:/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-0fcK3z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:28:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 05:28:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:28:54 INFO - Running setup.py (path:/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-Wp6Vxw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:28:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 05:28:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:28:54 INFO - Running setup.py (path:/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-coYqQN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:28:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 05:28:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:28:54 INFO - Running setup.py (path:/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-ErtHVS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:28:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 05:28:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:28:54 INFO - Running setup.py (path:/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-UAO9Wt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:28:54 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 05:28:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:28:54 INFO - Running setup.py (path:/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-2Gmepo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:28:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 05:28:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:28:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:28:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 05:28:54 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:28:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:28:54 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:28:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:28:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:28:54 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:28:54 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:28:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:28:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:28:57 INFO - Downloading blessings-1.6.tar.gz 05:28:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:28:57 INFO - Installing collected packages: blessings 05:28:57 INFO - Running setup.py install for blessings 05:28:57 INFO - Successfully installed blessings 05:28:57 INFO - Cleaning up... 05:28:57 INFO - Return code: 0 05:28:57 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:28:57 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:28:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:28:57 INFO - Reading from file tmpfile_stdout 05:28:57 INFO - Current package versions: 05:28:57 INFO - blessings == 1.6 05:28:57 INFO - blobuploader == 1.2.4 05:28:57 INFO - docopt == 0.6.1 05:28:57 INFO - functools32 == 3.2.3-2 05:28:57 INFO - jsonschema == 2.5.1 05:28:57 INFO - manifestparser == 1.1 05:28:57 INFO - mozInstall == 1.12 05:28:57 INFO - mozcrash == 1.0 05:28:57 INFO - mozdebug == 0.1 05:28:57 INFO - mozdevice == 0.49 05:28:57 INFO - mozfile == 1.2 05:28:57 INFO - mozhttpd == 0.7 05:28:57 INFO - mozinfo == 0.9 05:28:57 INFO - mozleak == 0.1 05:28:57 INFO - mozlog == 3.4 05:28:57 INFO - moznetwork == 0.27 05:28:57 INFO - mozprocess == 0.23 05:28:57 INFO - mozprofile == 0.28 05:28:57 INFO - mozrunner == 6.13 05:28:57 INFO - mozscreenshot == 0.1 05:28:57 INFO - mozsystemmonitor == 0.3 05:28:57 INFO - moztest == 0.7 05:28:57 INFO - mozversion == 1.4 05:28:57 INFO - psutil == 3.1.1 05:28:57 INFO - requests == 1.2.3 05:28:57 INFO - wsgiref == 0.1.2 05:28:57 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:28:57 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')]} 05:28:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:28:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:28:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:28:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:28:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:28:58 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x101c073f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101b7d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9203c9e3b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101a1dd30>, '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 0x101884db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101a15b20>, '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.8FBCNktbhp/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.ptvva15s3s/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/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 05:28:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 05:28:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:28:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8FBCNktbhp/Render', 05:28:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:28:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:28:58 INFO - 'HOME': '/Users/cltbld', 05:28:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:28:58 INFO - 'LOGNAME': 'cltbld', 05:28:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:28:58 INFO - 'MOZ_NO_REMOTE': '1', 05:28:58 INFO - 'NO_EM_RESTART': '1', 05:28:58 INFO - 'PAGER': '/bin/cat', 05:28:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:28:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:28:58 INFO - 'PWD': '/builds/slave/test', 05:28:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:28:58 INFO - 'SHELL': '/bin/bash', 05:28:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ptvva15s3s/Listeners', 05:28:58 INFO - 'TMPDIR': '/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/', 05:28:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:28:58 INFO - 'USER': 'cltbld', 05:28:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:28:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:28:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:28:58 INFO - 'XPC_FLAGS': '0x0', 05:28:58 INFO - 'XPC_SERVICE_NAME': '0', 05:28:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:28:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:28:59 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)) 05:28:59 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)) 05:28:59 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)) 05:28:59 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)) 05:28:59 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)) 05:28:59 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)) 05:28:59 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)) 05:28:59 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)) 05:28:59 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)) 05:28:59 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)) 05:28:59 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)) 05:28:59 INFO - Cleaning up... 05:28:59 INFO - Return code: 0 05:28:59 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:28: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')]} 05:28:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:28:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:28: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')]} 05:28:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:28:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:28: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', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x101c073f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101b7d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9203c9e3b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101a1dd30>, '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 0x101884db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101a15b20>, '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.8FBCNktbhp/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.ptvva15s3s/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/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 05:28: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', 'jsonschema'] in /builds/slave/test/build 05:28: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 jsonschema 05:28:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8FBCNktbhp/Render', 05:28:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:28:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:28:59 INFO - 'HOME': '/Users/cltbld', 05:28:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:28:59 INFO - 'LOGNAME': 'cltbld', 05:28:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:28:59 INFO - 'MOZ_NO_REMOTE': '1', 05:28:59 INFO - 'NO_EM_RESTART': '1', 05:28:59 INFO - 'PAGER': '/bin/cat', 05:28:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:28:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:28:59 INFO - 'PWD': '/builds/slave/test', 05:28:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:28:59 INFO - 'SHELL': '/bin/bash', 05:28:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ptvva15s3s/Listeners', 05:28:59 INFO - 'TMPDIR': '/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/', 05:28:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:28:59 INFO - 'USER': 'cltbld', 05:28:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:28:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:28:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:28:59 INFO - 'XPC_FLAGS': '0x0', 05:28:59 INFO - 'XPC_SERVICE_NAME': '0', 05:28:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:28:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:28:59 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 05:28:59 INFO - Cleaning up... 05:28:59 INFO - Return code: 0 05:28:59 INFO - Running post-action listener: _resource_record_post_action 05:28:59 INFO - Running post-action listener: _start_resource_monitoring 05:28:59 INFO - Starting resource monitoring. 05:28:59 INFO - [mozharness: 2016-11-23 13:28:59.751581Z] Finished create-virtualenv step (success) 05:28:59 INFO - [mozharness: 2016-11-23 13:28:59.752627Z] Running install step. 05:28:59 INFO - Running pre-action listener: _resource_record_pre_action 05:28:59 INFO - Running main action method: install 05:28:59 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:28:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:29:00 INFO - Reading from file tmpfile_stdout 05:29:00 INFO - Detecting whether we're running mozinstall >=1.0... 05:29:00 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:29:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:29:00 INFO - Reading from file tmpfile_stdout 05:29:00 INFO - Output received: 05:29:00 INFO - Usage: mozinstall [options] installer 05:29:00 INFO - Options: 05:29:00 INFO - -h, --help show this help message and exit 05:29:00 INFO - -d DEST, --destination=DEST 05:29:00 INFO - Directory to install application into. [default: 05:29:00 INFO - "/builds/slave/test"] 05:29:00 INFO - --app=APP Application being installed. [default: firefox] 05:29:00 INFO - mkdir: /builds/slave/test/build/application 05:29:00 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'] 05:29:00 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 05:29:32 INFO - Reading from file tmpfile_stdout 05:29:32 INFO - Output received: 05:29:32 INFO - /builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox 05:29:32 INFO - Running post-action listener: _resource_record_post_action 05:29:32 INFO - [mozharness: 2016-11-23 13:29:32.864112Z] Finished install step (success) 05:29:32 INFO - [mozharness: 2016-11-23 13:29:32.864273Z] Running run-tests step. 05:29:32 INFO - Running pre-action listener: _resource_record_pre_action 05:29:32 INFO - Running main action method: run_tests 05:29:32 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:29:32 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:29:32 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:29:32 INFO - 2016-11-23 05:29:32.878 screenresolution[1622:7312] starting screenresolution argv=screenresolution get 05:29:32 INFO - 2016-11-23 05:29:32.881 screenresolution[1622:7312] Display 0: 1600x1200x32@60 05:29:32 INFO - 2016-11-23 05:29:32.887 screenresolution[1623:7314] starting screenresolution argv=screenresolution list 05:29:32 INFO - Available Modes on Display 0 05:29:32 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:29:32 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:29:32 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:29:32 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:29:33 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:29:33 INFO - Intel Iris: 05:29:33 INFO - Chipset Model: Intel Iris 05:29:33 INFO - Type: GPU 05:29:33 INFO - Bus: Built-In 05:29:33 INFO - VRAM (Dynamic, Max): 1536 MB 05:29:33 INFO - Vendor: Intel (0x8086) 05:29:33 INFO - Device ID: 0x0a2e 05:29:33 INFO - Revision ID: 0x0009 05:29:33 INFO - Displays: 05:29:33 INFO - Display: 05:29:33 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:29:33 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:29:33 INFO - Display Serial Number: 1600x1200 60 05:29:33 INFO - Main Display: Yes 05:29:33 INFO - Mirror: Off 05:29:33 INFO - Online: Yes 05:29:33 INFO - Rotation: Supported 05:29:33 INFO - Return code: 0 05:29:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:29:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:29:33 INFO - Python 2.7.3 05:29:33 INFO - Return code: 0 05:29:33 INFO - grabbing minidump binary from tooltool 05:29:33 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')]} 05:29:33 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 0x101a1dd30>, '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 0x101884db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101a15b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:29:33 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 05:29:33 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 05:29:33 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 05:29:33 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:29:33 INFO - Return code: 0 05:29:33 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 05:29:33 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:29:33 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:29:33 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 05:29:33 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:29:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/XV6MW-jpSf2qB-jdeZep1A/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0385', '--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 05:29:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/XV6MW-jpSf2qB-jdeZep1A/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0385 --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 05:29:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8FBCNktbhp/Render', 05:29:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:29:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:29:33 INFO - 'HOME': '/Users/cltbld', 05:29:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:29:33 INFO - 'LOGNAME': 'cltbld', 05:29:33 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:29:33 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 05:29:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:29:33 INFO - 'MOZ_NO_REMOTE': '1', 05:29:33 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:29:33 INFO - 'NO_EM_RESTART': '1', 05:29:33 INFO - 'PAGER': '/bin/cat', 05:29:33 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:29:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:29:33 INFO - 'PWD': '/builds/slave/test', 05:29:33 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:29:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:29:33 INFO - 'SHELL': '/bin/bash', 05:29:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ptvva15s3s/Listeners', 05:29:33 INFO - 'TMPDIR': '/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/', 05:29:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:29:33 INFO - 'USER': 'cltbld', 05:29:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:29:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:29:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:29:33 INFO - 'XPC_FLAGS': '0x0', 05:29:33 INFO - 'XPC_SERVICE_NAME': '0', 05:29:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:29:33 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/XV6MW-jpSf2qB-jdeZep1A/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0385', '--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 05:29:33 INFO - mozversion application_buildid: 20161123030208 05:29:33 INFO - mozversion application_changeset: 0ddfec7126ec503b54df9c4b7c3b988906f6c882 05:29:33 INFO - mozversion application_display_name: Nightly 05:29:33 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:29:33 INFO - mozversion application_name: Firefox 05:29:33 INFO - mozversion application_remotingname: firefox 05:29:33 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 05:29:33 INFO - mozversion application_vendor: Mozilla 05:29:33 INFO - mozversion application_version: 53.0a1 05:29:33 INFO - mozversion platform_buildid: 20161123030208 05:29:33 INFO - mozversion platform_changeset: 0ddfec7126ec503b54df9c4b7c3b988906f6c882 05:29:33 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 05:29:33 INFO - mozversion platform_version: 53.0a1 05:29:33 INFO - using testdate: 1479907773 05:29:33 INFO - actual date: 1479907773 05:29:33 INFO - starting webserver on 'localhost:49236' 05:29:33 INFO - SUITE-START | Running 2 tests 05:29:33 INFO - TEST-START | dromaeo_css 05:29:33 INFO - operating with platform_type : mac_ 05:29:33 INFO - Initialising browser for dromaeo_css test... 05:29:33 INFO - TEST-INFO | started process 1633 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/tmp6JdR74/profile http://localhost:49236/getInfo.html) 05:29:36 INFO - PROCESS | 1633 | 2016-11-23 05:29:36.722 plugin-container[1636:7484] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9347, name = 'com.apple.tsm.portname' 05:29:36 INFO - PROCESS | 1633 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:29:36 INFO - PROCESS | 1633 | 2016-11-23 05:29:36.722 plugin-container[1636:7484] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4b23, name = 'com.apple.CFPasteboardClient' 05:29:36 INFO - PROCESS | 1633 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:29:36 INFO - PROCESS | 1633 | 2016-11-23 05:29:36.722 plugin-container[1636:7484] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 05:29:36 INFO - PROCESS | 1633 | __metrics Screen width/height:1600/1200 05:29:36 INFO - PROCESS | 1633 | colorDepth:24 05:29:36 INFO - PROCESS | 1633 | Browser inner width/height: 1024/689 05:29:36 INFO - PROCESS | 1633 | __metrics 05:29:36 ERROR - PROCESS | 1633 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3198: TypeError: this.tabs is undefined 05:29:37 INFO - TEST-INFO | 1633: exit 0 05:29:37 INFO - Browser initialized. 05:29:37 INFO - Running cycle 1/1 for dromaeo_css test... 05:29:37 INFO - TEST-INFO | started process 1637 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/tmp6JdR74/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:29:40 INFO - PROCESS | 1637 | 2016-11-23 05:29:40.399 plugin-container[1638:7614] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8843, name = 'com.apple.tsm.portname' 05:29:40 INFO - PROCESS | 1637 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:29:40 INFO - PROCESS | 1637 | 2016-11-23 05:29:40.399 plugin-container[1638:7614] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8e0b, name = 'com.apple.CFPasteboardClient' 05:29:40 INFO - PROCESS | 1637 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:29:40 INFO - PROCESS | 1637 | 2016-11-23 05:29:40.399 plugin-container[1638:7614] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 05:29:49 INFO - PROCESS | 1637 | RSS: Main: 241377280 05:29:49 INFO - PROCESS | 1637 | 05:30:39 INFO - PROCESS | 1637 | 1479907839652 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] 05:32:52 INFO - PROCESS | 1637 | Cycle 1(1): loaded http://localhost:49236/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49236/tests/dromaeo/cssquery-ext.html) 05:32:55 INFO - PROCESS | 1637 | RSS: Main: 253022208 05:32:55 INFO - PROCESS | 1637 | 05:35:57 INFO - PROCESS | 1637 | Cycle 1(1): loaded http://localhost:49236/tests/dromaeo/cssquery-ext.html (next: http://localhost:49236/tests/dromaeo/cssquery-jquery.html) 05:35:58 INFO - PROCESS | 1637 | RSS: Main: 263598080 05:35:58 INFO - PROCESS | 1637 | 05:39:00 INFO - PROCESS | 1637 | Cycle 1(1): loaded http://localhost:49236/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49236/tests/dromaeo/cssquery-mootools.html) 05:39:01 INFO - PROCESS | 1637 | RSS: Main: 269828096 05:39:01 INFO - PROCESS | 1637 | 05:42:02 INFO - PROCESS | 1637 | Cycle 1(1): loaded http://localhost:49236/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49236/tests/dromaeo/cssquery-prototype.html) 05:42:03 INFO - PROCESS | 1637 | RSS: Main: 268402688 05:42:03 INFO - PROCESS | 1637 | 05:45:06 INFO - PROCESS | 1637 | Cycle 1(1): loaded http://localhost:49236/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49236/tests/dromaeo/cssquery-yui.html) 05:45:07 INFO - PROCESS | 1637 | RSS: Main: 272887808 05:45:07 INFO - PROCESS | 1637 | 05:48:09 INFO - PROCESS | 1637 | Cycle 1(1): loaded http://localhost:49236/tests/dromaeo/cssquery-yui.html (next: http://localhost:49236/tests/dromaeo/cssquery-dojo.html) 05:48:10 INFO - PROCESS | 1637 | RSS: Main: 275558400 05:48:10 INFO - PROCESS | 1637 | 05:48:10 INFO - PROCESS | 1637 | __start_tp_report 05:48:10 INFO - PROCESS | 1637 | _x_x_mozilla_page_load 05:48:10 INFO - PROCESS | 1637 | _x_x_mozilla_page_load_details 05:48:10 INFO - PROCESS | 1637 | |i|pagename|runs| 05:48:10 INFO - PROCESS | 1637 | |0;dojo.html;2703.891844326227;2966.5401862711287;3184.9489668409415;3317.3531161423516;3373.4939759036142;4300.3764454154125;5383.300170977777;5433.049216387136;5481.862953426156;5486.780528778844;4503.549645035495;5427.701476418795;5497.670139791615;5513.117901135819;5521.1442226454965;4986.675866068694;5037.4458809530925;5080.77136528857;5121.155009423441;5122.334096567441;4159.376093585956;4976.178930476485;5081.898362032775;5098.617603679721;5134.0758663440565;4946.901061978759;5092.823386308802;5111.948880511203;5138.486151384869;5145.511176438233;5092.516210959953;5098.745062746885;5115.411727651331;5131.666441681297;5183.533481986601;4886;4896.657233993622;4992.525710057539;5000.849974500771;5036.143855544529;4248.766317852517;5427.565794736451;5443.700596467193;5476.26070480484;5551.528120109796;5890.646561206298;5932.881342373172;5954.404559544011;5960.195373624592;5966.2840459144745;5902.527797776168;5944.316403613597;5952.255968003999;6044.2746870375395;6063.423974722395;5526.4197259288;5531.668099913978;5532.363778165482;5543.25166102575;5547.33431988164;6088.817335479943;6131.346082861964;6145.938540614626;6151.046587778812;6183.103449999815;5800.216970708901;5829.47534721877;5843.970780146072;5853.5317174625925;5873.060310350324;6061.212042434543;6063.060225665029;6066.75732970685;6082.57421980466;6092.360302168208;5719.714014299351;5917.852053698692;5969.925413425625;5976.641401515923;6022.849428764316;5275.498827611371;5878.353381128158;5880.617759845682;5928.466438020599;5993.011153159767;3983.701222408331;4747.359106520645;4762.547557981986;4768.022555376139;4775.785089670973;4252.596003379674;4257.850975215914;4277.615014648696;4289.806958686867;4365.781710914442;4540.568645978627;5333.773232156625;5582.469665381782;5634.042212823748;5666.065099258576;6001.279846418458;6098.847528811815;6110.297315808739;6126.908096378558;6132.202813634199;5704.914426283609;5712.314522257273;5728.79949201776;5734.770609175586;5758.424157584208;5089.109405853961;5122.103631864409;5207.739613019409;5230.215467679878;5246.134387825966;6157.753689852356;6167.136600875881;6216.533759968111;6229.12792209082;6243.500519958302;6076.210092687922;6076.817695469143;6081.179040829431;6101.145839582373;6104.236970378703;6205.968970155121;6234.781782637586;6250.062271463648;6258.593191442542;6266.530010249158;5285.048691235615;5524.281843360337;5755.65466072037;5772.855678607901;5841.4450627190345;4607.308903664343;4840.685355452025;5976.61152025117;5993.400659933972;6028.547858910687;5825.825225243249;5830.912536311874;5867.061270196748;5886.05823068307;5906.438888305604;4831.951680483291;5761.135829625422;5829.59192856496;5844.26946631671;5859.472647461749;4757.785899634456;5588.776448941997;5599.636023658449;5620.269364982526;5655.4910058094965;5688.288963879515;5729.822667386366;5779.219805326227;5789;5828.329742079715;5600.103983362643;5674.574406919415;5680.488756011979;5681.261436013292;5695.544356451557 05:48:10 INFO - PROCESS | 1637 | |1;ext.html;452.6265830689681;470.75520729220807;471.83721616042453;503.9647224694271;558.9385167631563;29311.974080907174;30947;31078.06765797024;39346.80326598367;40778;39834.40248396277;40228.39657405141;40261.7986910065;40315.992100197436;40396.59603403965;9047.819043619123;9194.218491428237;9200.723978280663;9202.493862837542;9227.44635321883;8296.751097467086;8502.74491765248;9090.59092340843;10733.785324293509;11074.833877491845;11088.722781930435;11136.164787640919;11138.108951283919;11141.442927853575;11143.832842507369;9379.624815007426;11046.447677616126;11095.11239100878;11135.888641113566;11137.276077055047;10893.727656808564;10981.505832237568;10987.175961802897;10992.945035274852;10993.340399576051;37867.62132378668;38228.61771382279;38280.0235969325;38290.23419531622;38299.617003830164;2938.7208215219734;3254.902352929416;3317.8506967186295;3488.4069708149423;3607.494950706903;28071.43857122846;28164.15507534777;29088.563671544944;29634.25914352137;29745.25636859074;3132.201288671397;3228.515722641599;3234.2237862912966;3236.304194598149;3263.5757351544385;407099;487009;489298;492019;496555;54426.45573544214;54672.72663636736;54738;54773.178402323996;54858.72570637121;56146.71926640422;56490.71754641201;56730.865382692115;56777;56884.14673779897;15130.470433534774;15521.146336951577;15521.844781552265;15660.843391565939;15689.921550392175;11085;12334.321612311229;12338.321392323509;12350;12358.258504489759;12939.09426340166;19309.413104682593;19684.212631494578;19872.90063549702;20349.49126271855;7000.054992576039;7844.137144914055;8103.10865804761;8113.310368618733;8141.185881411811;777.5140537164273;855.4354126276628;915.5834095486671;921.2814005076051;1049.9422531760827;23864.522709545712;29236.561451578244;29261.707382926324;29772;29783.85108074489;920.7007722490217;984.2519685039422;1022.8465730140539;1057.603398725478;1132.4960392625203;12440.755924407487;15252.771208431883;15404.152771597446;15610.531684049496;15698.843011569967;574918;675106;687776;687894;688847;55251.44748552463;55356.16965745517;55572.44427555754;55787.44212557822;55870.441295587334;1273.045216087934;1314.0801438992744;1340.631326385252;1342.5502456676927;1345.6232254968625;28182.718172818008;29109.41781164407;29137.271568210115;29949;30190.69809301879;47261;47470.28794568015;47557.28664070111;47573.52426475829;47662.523374765806;613949;615204;616959;618260;618689;33332.833335833166;34910;37473.437898431;39968.60031399649;40567;43486;43623.56376436195;43748.56251437445;43998;44075.77962110169;36358.8182059088;36486.63513364938;37345.06637334089;37410;37441.625583744906;36690;36743.448848266766;37601.4359784598;37770;37820.24359512849;35623.64376356203;35652.46521302131;36544.81727591345;36575.634243658285;36949.445758314185;31225;31549;32981.340373192885;33073.669263307056;33104;23689.881550592138;24013.75986240115;24131.638025429984;24617;24627.79187247955 05:48:10 INFO - PROCESS | 1637 | |2;jquery.html;368.78241837315994;376.4015215806867;381.870164144191;387.6975958752171;399.894028082558;17742.290308387666;19253.422397328068;19647.410577682673;19751;19798.30705925293;19195.656304058688;19308.806911930875;19451.221951121974;19476;19819.801801981976;4942.382202224722;5164.612654050942;5429.972850135754;5717.971410142944;5718.828435146952;5542.2517960075475;5720.456556627135;5721.628094173865;5723.198752174699;5732.398098199692;5407.134858422653;5634.3520495143175;5672.716364181795;5675.432456754313;5712.828615141531;5531.944680553203;5587.692676902769;5588.776448942038;5593.608447408683;5610.214569960209;4728.290756386569;4904.068227036852;4926.802927882881;4934.037862616791;4958.553730164266;18293.81090229131;22146.335609931568;23797.524049519085;24230.757692423027;24591.50816983668;2220.977790222093;2323.268170526279;2377.679013333195;2409.445827459694;2503.5994240921364;13240.668983275495;13310;14742.484013059491;14783.482578109822;14991.700165996619;1731.6277000444838;1759.7096479080935;1763.2506184871506;1788.3383148235107;1871.9532011699816;101256.98743012623;117298.41350793277;119503;120471;120533.19200212003;8944.182582486495;9544.475053872104;9657.46883921391;9804.558794854249;10281.794364112826;11844.526218951318;12225.144239903122;12234.143609947396;12262.20295680778;12266;7629.351505122016;7726.6136693166245;7779.766607001686;7835.569043702537;7859.960700196462;8022;8049.959750201211;8087.716929907425;8182.549959752152;8217.95891020541;3665.5051568038507;3785.356489396809;3797.867074652374;3844.6539811417106;3855.8264878080554;1616.6685829404944;1889.3576184097474;1921.5580416504004;1927.1905799564217;1931.6092413462284;697.6720941157648;948.4593781544592;949.8907625623142;957.6073809737974;957.9425234485954;19566.5108372289;20455;20629.484262893548;20732.378028659165;20863;879.3976126353341;906.8231694819578;934.6495064350869;962.9503840813406;988.1353815411514;7066.611336376446;10328.948355258175;10595.099416549525;10659.626913058006;10660.41367724767;582604;590958;594995;595671;597571;124303.37848310881;128771;129173;131443;135516;975.6438899801522;989.8267803134567;1022.3201570955232;1026.1688032693394;1029.5727273181697;15396.076235425462;17461.12694365302;18061.81938180654;18462;18728.6254274911;48720;48906.75546622386;54021;55695;56121.15818262811;81415.59292203501;82193;83665;84055;85603;7765.883511747215;7815.47598218342;7839.568823714751;7852.960735196288;8060.1133875274845;10004.24968127408;11591.072714183014;12578.056645751789;12761.489540418279;12859.485620575073;10139.138173255062;10865.076468500267;10946.90530946899;10995.890041099487;11118.499667515143;5066.6200034998255;8459.06950235486;8747.606357714045;10714.410707411167;11157.995780379804;11024.889751102386;11208.775824483628;11259.380734059378;11490.597829075943;11493.942530287295;7856.489328193649;7863.567503787347;8058.476199047043;8070.152633973348;8299.626516806878;8209.83580328402;10653.307535010199;10985.780284394426;11327;11357.318560886373 05:48:10 INFO - PROCESS | 1637 | |3;mootools.html;1455.6943041961188;1474.9705005899882;1620.679146495606;1708.709519381705;1741.1903464888824;7423.591702456362;7642.350400215974;7754.108277548084;8164.346852251821;8186.713465028725;6896.275891031445;7064.434845212383;7751.844963100734;8058.39562032847;8065.838683226332;1991.5718120604104;2298.333483289844;2304.9193278235266;2305.873176975266;2307.042577330406;1668.990260892161;2042.908069136885;2166.9598592675525;2340.742518322983;2341.9648705269433;1896.2130715752946;2284.977150228493;2297.2304278066867;2311.3874823171873;2313.710786151731;2259.830512711545;2272.4091736148557;2288.496530763238;2293.312006398074;2295.747467778543;2299.0803678528555;2314.3982564533176;2315.988420057889;2319.4433335999406;2330.300909727092;8032;8152.959235203845;8190.8771368429525;8209.302209312216;8213.671453141867;4942.901141977176;5109.310243117169;5110.131277682803;5111.361079865017;5117.334746482933;3116.236522052089;3170.746340292771;3519.04985653872;3521.9119522011897;3536.752427330088;1020.8774947006407;1058.7035630023606;1074.591655171038;1077.1113831089383;1079.6760971708457;8465.66137354505;10171.949140254326;10434.895651043544;10495.580176792919;10524.10545103667;4148.357004664222;4221.071364299849;4313.202057619351;4324.17840610283;4327.221100201994;8321.708740194064;8392.370572207108;8396.79008024792;8406;8429.32565394767;4619.960508885474;4656.371389862393;4944.703317800943;4960.677555958923;4960.925586116211;3539.309834582283;4291;4591.609713174417;4677.251639737626;4692.741899195577;3519.260955199437;3539.2744487380023;3589.6230895756094;3612.8012959286966;3635.836387362575;1132.8017596920506;1134.625573560723;1137.3119262846024;1143.6168883424145;1146.8165093584987;774.77972193795;783.4633276205817;879.3712495565701;881.2377293640955;898.9910100898908;5214;5321.228421878806;5410.48600382963;5531.308586426697;5613.8596535086135;354.58159371940843;412.75028607692514;423.2360589136638;427.4891504651973;431.967602429819;3104.5653608494827;3665.6700896919406;3768.773873567595;3793.8482460701816;3836.3861782114727;9408.247340212767;9768.413895166314;9833.704988850346;9852.45811480361;9861.40831550095;9553.761155971157;9558.235341172689;9587.089226523469;9810.411375317504;9854.556544955494;7544.924550754532;7659.61701914902;7662.96168519165;7689.038870141344;7690.423218258653;4079.3269110597607;5379.838604841861;5398;5446.809361672323;5450.563954883521;9003.724580237691;9024.593893274687;9088.500132492776;9089.272858171462;9129.817403651758;8968.192862642394;9156;9169.35814492979;9245.72262832116;9261.67584134552;5991.191189189578;6094.299155597163;6133.539984501091;6474.352564743488;6633.270340262664;7613.581253031137;7825.882611760942;7838.843223135619;7845.725399610987;7853.332466740391;6888.621125837926;6985.44116470671;6994.650267486503;7010;7124.109486314211;6791.932080679328;6856.622885741132;6886.621235832077;6897.172339319314;6929.133858267716;6446.67766611677;6487.870242595027;6580.539362244597;6585.967070164618;6675.198976122702;3984.521857377133;4744.288356746378;4765.11845308619;4815.711057336431;4834;3831.3678243090694;4752.667313288037;4829.710217386827;4855.344528488608;4917.557419832232 05:48:10 INFO - PROCESS | 1637 | |4;prototype.html;259.32316653534275;271.2716356582576;308.12185271974886;312.46100476677725;314.1345592891582;4172.833086676533;5061.873453163673;5269.920951185735;5309.4692029800135;5332.520073193412;5195.220716892468;5210.374755029392;5222.250546067063;5224.764885580148;5234.8691282717955;3835.252125835449;3837.6354246346623;3966.5720340677335;3972.7616343019367;3991.501062367204;3976.423418604315;3994.8002599870038;4023;4050.027993281606;4061.2283666103344;3102.1158969693643;3974.6621537169376;4000.5999400059904;4004.5583589907756;4021.77880216588;3725.7578257413184;3953.802309884494;4002.399640053986;4019.356902895537;4027.4965629296485;2783.8403412127177;3165.7942233754734;3379.3579219948133;3388.0852169914233;3409.1988382730037;5046.243063540462;5108.843843169009;5109.208072748693;5120.308758317654;5121.462246464105;3872.7095467839745;4507.481639611421;4521.977390113028;4534.455865296152;4592.277895789079;5237.240600113;5450.91823622646;5508.20131080995;5553.917281019618;5579.944200558024;3753.0242137044565;3775.1505911170043;3783.205526839367;3804.619538046173;3832.3485007548784;5070.873228169288;5650.802221922254;5817.7382017809305;5865.472107510344;5887.735051922674;3374.8818791342187;3452.2405070884365;3566.589842168132;3613.6205698401814;3763.2312812977857;3476.0962149841225;3881.5924327945722;3886.9116647338224;3912.080661044652;3927.19492504036;3791.3554695701796;3821.464994900716;3875.9806200968815;4566.703164294377;4579.702319349231;4110.630043296058;4820.903581928412;5183.455737147545;5567.276254086943;5658.74535645897;1214.5870404062662;1216.7444836584416;1239.4732238798474;1267.8541967673837;1271.4215032160346;729.8722723523363;754.0950858969259;828.8549503836925;831.8128421105203;863.2705363967438;4530.614897733663;4543.522930092358;5250.921236181461;5253.527182553512;5379.327584051994;5061.772220250097;5302.072137375943;5712.685802280831;5736.340320863154;5795.507381872588;5019.42276638184;5176.275321454999;5428.294321738149;5440.292761940922;5447.673139611636;2442.7190873049826;2807.494650962846;2901.9709802901702;2916.3000879788697;2916.4896143175283;5092.541671249605;5155.81416274259;5238.869028274324;5817.7963771267805;5834.912476312774;4748.857534273978;5127.615428842777;5162.793488260428;5534.363548192009;5560.277163968739;5434.5652347812875;5768.711564421846;5775.025861681237;5811.854703632358;5814.244148260784;5084.491550844886;5461.453854614507;5478.369987451335;5480.506754392124;5502;4769.856904292877;5122.8975420492125;5335.1730481775385;5365.758540865592;5423.722036744655;4787.162246606899;5746.821901510097;5794.797182098607;5801.332846722681;5894.233749612523;3638.6725194732603;3640.7633503822167;3664.2488289899975;3702.0929872181487;3703.8888833335045;5164.431912489698;5455.509004189642;5494.945050549443;5542.115680668592;5577.553795696415;4325.961769175312;4613.261878099488;5037.571806396498;5382.8552014949055;5433.397147841317;4691.906161876812;4874.683145595525;5348.411674715856;5352.892942141213;5391.595630327663;4399.450068741407;4787.593054590259;5154.871128221825;5194.506521880416;5206.869828254324;4440.777961101868;4585.426821647294;5136.5377116059735;5343.809038294281;5606.747696353592;5063.13932628064;5449.618526703092;5462.235287059894;5492.286002819767;5515.227868098389 05:48:10 INFO - PROCESS | 1637 | |5;yui.html;694.7880896326618;703.2721133626695;704.2957042957042;727.5561907236581;745.2063552316782;13967.30163491825;14517.27413629317;14526.927365363159;14579;14619.78070328946;13064.608061758163;13205.141665791716;13218.603441896734;13221.140625859336;13221.537246196385;648.6011103171539;666.1074160624762;811.9391045671568;813.9348852091848;834.0866750907746;837.4849467577426;838.0153319849184;838.6938767349903;841.8947631546057;848.588434609214;832.4505826154739;884.3455842676406;885.1104639836955;890.6437425029975;893.1871996483202;885.2431171348469;887.4941283468426;888.8177923525694;890.7149712092133;893.7810236492069;642.7075680565332;662.2351184382046;664.4684252597903;665.4809248785955;666.6433458099893;12130.939345303304;13369.66303369665;13653.453861845514;13710.657233569142;13720;13090.86909130906;13392.665183370396;13407.865921340759;13416.865831341756;13550.661233469144;10936.398498082603;11314.981651651391;11392.658220253406;11447.370394628291;11469.827952580717;3533.770304930197;3553.5202747628996;3560.8753693620856;3648.6533779290926;3679.7240206984584;88382.55808720915;91434.91102082432;91855.6221656669;93747;93913.53043234808;10343.689689309333;10413.114885234689;10461.738456538647;10595.682129536126;10740.946295268473;20228.494287642632;20261.797382026285;20262.89868550648;20281.49296267605;20282.594348112954;6039.48664363534;7587.2412758723685;7619.085709714759;7648.694052237959;7782.766517004499;4183.435236243129;4188.350805625133;4293.2272191005295;4336.783160841946;4382.649388048949;3301.9174520636698;3379.9662003379653;3407.1993081625797;3412.7099196568074;3418.3676019935892;605.5428151745492;612.1277180018545;617.1883972576048;617.2870334763355;618.0358640520802;579.2759051186017;641.0160701932512;645.7675236914707;648.487694721165;668.1881513960465;8690.435121717195;8881.600327985256;8905.332100092519;8943.686970956116;8970.282377409792;656.8883289840738;664.6809531424943;666.0974379715416;668.7625892807984;681.8670359279992;4193.559676234013;4294.162638285505;4429.955700443019;4555.293929440942;4602.941323495615;70592.6470367645;93557;100977.48533771999;119056;121429.78570214362;101299.49350253202;102946;103145;103328.45007324746;105163.94836051695;85104.72342914861;85302.57348713216;86051;87496;87651.56174219088;8719.607617657348;8730.869036964577;8764;8842.381033327605;8948.149925757043;38580;52028.179013734334;57303.42696572981;57548;58585.70707146437;63823.6808815953;74717;81665.14172003951;82622;84029.73955390551;9383;11048.613298534512;11172;11244.66266012021;11245;10751.408672522773;11859.110566707359;11866.457360543074;11957.162998590014;12107.515699371927;10696.358218506915;11761.294322340687;11825.526978920747;11848.76302473963;11858.822117668415;11724.120690948042;11900.940495297467;11970.341631210018;12265.448054837376;12339.75320493603;9515.809683806423;11421.600243991421;11711.765764684487;11885.5840045598;11957.581484647995;9366.76583085401;9653.03469653029;9712.417254964725;9825.263105266991;10445.791084178425;9833;10089.243306751876;10115.443650598992;10132.696019119438;10138.341007834468 05:48:10 INFO - PROCESS | 1637 | __end_tp_report 05:48:10 INFO - PROCESS | 1637 | __start_cc_report 05:48:10 INFO - PROCESS | 1637 | _x_x_mozilla_cycle_collect,365 05:48:10 INFO - PROCESS | 1637 | __end_cc_report 05:48:10 INFO - PROCESS | 1637 | __startTimestamp1479908890046__endTimestamp 05:48:10 INFO - PROCESS | 1637 | 05:48:10 INFO - PROCESS | 1637 | ------- Summary: start ------- 05:48:10 INFO - PROCESS | 1637 | Number of tests: 6 05:48:10 INFO - PROCESS | 1637 | 05:48:10 INFO - PROCESS | 1637 | [#0] dojo.html Cycles:165 Average:5468.98 Median:5674.57 stddev:674.99 (11.9%) stddev-sans-first:641.26 05:48:10 INFO - PROCESS | 1637 | Values: 2703.9 2966.5 3184.9 3317.4 3373.5 4300.4 5383.3 5433.0 5481.9 5486.8 4503.5 5427.7 5497.7 5513.1 5521.1 4986.7 5037.4 5080.8 5121.2 5122.3 4159.4 4976.2 5081.9 5098.6 5134.1 4946.9 5092.8 5111.9 5138.5 5145.5 5092.5 5098.7 5115.4 5131.7 5183.5 4886.0 4896.7 4992.5 5000.8 5036.1 4248.8 5427.6 5443.7 5476.3 5551.5 5890.6 5932.9 5954.4 5960.2 5966.3 5902.5 5944.3 5952.3 6044.3 6063.4 5526.4 5531.7 5532.4 5543.3 5547.3 6088.8 6131.3 6145.9 6151.0 6183.1 5800.2 5829.5 5844.0 5853.5 5873.1 6061.2 6063.1 6066.8 6082.6 6092.4 5719.7 5917.9 5969.9 5976.6 6022.8 5275.5 5878.4 5880.6 5928.5 5993.0 3983.7 4747.4 4762.5 4768.0 4775.8 4252.6 4257.9 4277.6 4289.8 4365.8 4540.6 5333.8 5582.5 5634.0 5666.1 6001.3 6098.8 6110.3 6126.9 6132.2 5704.9 5712.3 5728.8 5734.8 5758.4 5089.1 5122.1 5207.7 5230.2 5246.1 6157.8 6167.1 6216.5 6229.1 6243.5 6076.2 6076.8 6081.2 6101.1 6104.2 6206.0 6234.8 6250.1 6258.6 6266.5 5285.0 5524.3 5755.7 5772.9 5841.4 4607.3 4840.7 5976.6 5993.4 6028.5 5825.8 5830.9 5867.1 5886.1 5906.4 4832.0 5761.1 5829.6 5844.3 5859.5 4757.8 5588.8 5599.6 5620.3 5655.5 5688.3 5729.8 5779.2 5789.0 5828.3 5600.1 5674.6 5680.5 5681.3 5695.5 05:48:10 INFO - PROCESS | 1637 | 05:48:10 INFO - PROCESS | 1637 | [#1] ext.html Cycles:180 Average:70735.23 Median:36632.82 stddev:158257.23 (432.0%) stddev-sans-first:158613.20 05:48:10 INFO - PROCESS | 1637 | Values: 452.6 470.8 471.8 504.0 558.9 29312.0 30947.0 31078.1 39346.8 40778.0 39834.4 40228.4 40261.8 40316.0 40396.6 9047.8 9194.2 9200.7 9202.5 9227.4 8296.8 8502.7 9090.6 10733.8 11074.8 11088.7 11136.2 11138.1 11141.4 11143.8 9379.6 11046.4 11095.1 11135.9 11137.3 10893.7 10981.5 10987.2 10992.9 10993.3 37867.6 38228.6 38280.0 38290.2 38299.6 2938.7 3254.9 3317.9 3488.4 3607.5 28071.4 28164.2 29088.6 29634.3 29745.3 3132.2 3228.5 3234.2 3236.3 3263.6 407099.0 487009.0 489298.0 492019.0 496555.0 54426.5 54672.7 54738.0 54773.2 54858.7 56146.7 56490.7 56730.9 56777.0 56884.1 15130.5 15521.1 15521.8 15660.8 15689.9 11085.0 12334.3 12338.3 12350.0 12358.3 12939.1 19309.4 19684.2 19872.9 20349.5 7000.1 7844.1 8103.1 8113.3 8141.2 777.5 855.4 915.6 921.3 1049.9 23864.5 29236.6 29261.7 29772.0 29783.9 920.7 984.3 1022.8 1057.6 1132.5 12440.8 15252.8 15404.2 15610.5 15698.8 574918.0 675106.0 687776.0 687894.0 688847.0 55251.4 55356.2 55572.4 55787.4 55870.4 1273.0 1314.1 1340.6 1342.6 1345.6 28182.7 29109.4 29137.3 29949.0 30190.7 47261.0 47470.3 47557.3 47573.5 47662.5 613949.0 615204.0 616959.0 618260.0 618689.0 33332.8 34910.0 37473.4 39968.6 40567.0 43486.0 43623.6 43748.6 43998.0 44075.8 36358.8 36486.6 37345.1 37410.0 37441.6 36690.0 36743.4 37601.4 37770.0 37820.2 35623.6 35652.5 36544.8 36575.6 36949.4 31225.0 31549.0 32981.3 33073.7 33104.0 23689.9 24013.8 24131.6 24617.0 24627.8 05:48:10 INFO - PROCESS | 1637 | 05:48:10 INFO - PROCESS | 1637 | [#2] jquery.html Cycles:180 Average:34575.91 Median:372.59 stddev:99090.14 (26594.8%) stddev-sans-first:99334.82 05:48:10 INFO - PROCESS | 1637 | Values: 368.8 376.4 381.9 387.7 399.9 17742.3 19253.4 19647.4 19751.0 19798.3 19195.7 19308.8 19451.2 19476.0 19819.8 4942.4 5164.6 5430.0 5718.0 5718.8 5542.3 5720.5 5721.6 5723.2 5732.4 5407.1 5634.4 5672.7 5675.4 5712.8 5531.9 5587.7 5588.8 5593.6 5610.2 4728.3 4904.1 4926.8 4934.0 4958.6 18293.8 22146.3 23797.5 24230.8 24591.5 2221.0 2323.3 2377.7 2409.4 2503.6 13240.7 13310.0 14742.5 14783.5 14991.7 1731.6 1759.7 1763.3 1788.3 1872.0 101257.0 117298.4 119503.0 120471.0 120533.2 8944.2 9544.5 9657.5 9804.6 10281.8 11844.5 12225.1 12234.1 12262.2 12266.0 7629.4 7726.6 7779.8 7835.6 7860.0 8022.0 8050.0 8087.7 8182.5 8218.0 3665.5 3785.4 3797.9 3844.7 3855.8 1616.7 1889.4 1921.6 1927.2 1931.6 697.7 948.5 949.9 957.6 957.9 19566.5 20455.0 20629.5 20732.4 20863.0 879.4 906.8 934.6 963.0 988.1 7066.6 10328.9 10595.1 10659.6 10660.4 582604.0 590958.0 594995.0 595671.0 597571.0 124303.4 128771.0 129173.0 131443.0 135516.0 975.6 989.8 1022.3 1026.2 1029.6 15396.1 17461.1 18061.8 18462.0 18728.6 48720.0 48906.8 54021.0 55695.0 56121.2 81415.6 82193.0 83665.0 84055.0 85603.0 7765.9 7815.5 7839.6 7853.0 8060.1 10004.2 11591.1 12578.1 12761.5 12859.5 10139.1 10865.1 10946.9 10995.9 11118.5 5066.6 8459.1 8747.6 10714.4 11158.0 11024.9 11208.8 11259.4 11490.6 11493.9 7856.5 7863.6 8058.5 8070.2 8299.6 8209.8 10653.3 10985.8 11327.0 11357.3 05:48:10 INFO - PROCESS | 1637 | 05:48:10 INFO - PROCESS | 1637 | [#3] mootools.html Cycles:180 Average:5192.58 Median:5035.12 stddev:2861.84 (56.8%) stddev-sans-first:2856.09 05:48:10 INFO - PROCESS | 1637 | Values: 1455.7 1475.0 1620.7 1708.7 1741.2 7423.6 7642.4 7754.1 8164.3 8186.7 6896.3 7064.4 7751.8 8058.4 8065.8 1991.6 2298.3 2304.9 2305.9 2307.0 1669.0 2042.9 2167.0 2340.7 2342.0 1896.2 2285.0 2297.2 2311.4 2313.7 2259.8 2272.4 2288.5 2293.3 2295.7 2299.1 2314.4 2316.0 2319.4 2330.3 8032.0 8153.0 8190.9 8209.3 8213.7 4942.9 5109.3 5110.1 5111.4 5117.3 3116.2 3170.7 3519.0 3521.9 3536.8 1020.9 1058.7 1074.6 1077.1 1079.7 8465.7 10171.9 10434.9 10495.6 10524.1 4148.4 4221.1 4313.2 4324.2 4327.2 8321.7 8392.4 8396.8 8406.0 8429.3 4620.0 4656.4 4944.7 4960.7 4960.9 3539.3 4291.0 4591.6 4677.3 4692.7 3519.3 3539.3 3589.6 3612.8 3635.8 1132.8 1134.6 1137.3 1143.6 1146.8 774.8 783.5 879.4 881.2 899.0 5214.0 5321.2 5410.5 5531.3 5613.9 354.6 412.8 423.2 427.5 432.0 3104.6 3665.7 3768.8 3793.8 3836.4 9408.2 9768.4 9833.7 9852.5 9861.4 9553.8 9558.2 9587.1 9810.4 9854.6 7544.9 7659.6 7663.0 7689.0 7690.4 4079.3 5379.8 5398.0 5446.8 5450.6 9003.7 9024.6 9088.5 9089.3 9129.8 8968.2 9156.0 9169.4 9245.7 9261.7 5991.2 6094.3 6133.5 6474.4 6633.3 7613.6 7825.9 7838.8 7845.7 7853.3 6888.6 6985.4 6994.7 7010.0 7124.1 6791.9 6856.6 6886.6 6897.2 6929.1 6446.7 6487.9 6580.5 6586.0 6675.2 3984.5 4744.3 4765.1 4815.7 4834.0 3831.4 4752.7 4829.7 4855.3 4917.6 05:48:10 INFO - PROCESS | 1637 | 05:48:10 INFO - PROCESS | 1637 | [#4] prototype.html Cycles:180 Average:4396.60 Median:5062.51 stddev:1362.93 (26.9%) stddev-sans-first:1330.91 05:48:10 INFO - PROCESS | 1637 | Values: 259.3 271.3 308.1 312.5 314.1 4172.8 5061.9 5269.9 5309.5 5332.5 5195.2 5210.4 5222.3 5224.8 5234.9 3835.3 3837.6 3966.6 3972.8 3991.5 3976.4 3994.8 4023.0 4050.0 4061.2 3102.1 3974.7 4000.6 4004.6 4021.8 3725.8 3953.8 4002.4 4019.4 4027.5 2783.8 3165.8 3379.4 3388.1 3409.2 5046.2 5108.8 5109.2 5120.3 5121.5 3872.7 4507.5 4522.0 4534.5 4592.3 5237.2 5450.9 5508.2 5553.9 5579.9 3753.0 3775.2 3783.2 3804.6 3832.3 5070.9 5650.8 5817.7 5865.5 5887.7 3374.9 3452.2 3566.6 3613.6 3763.2 3476.1 3881.6 3886.9 3912.1 3927.2 3791.4 3821.5 3876.0 4566.7 4579.7 4110.6 4820.9 5183.5 5567.3 5658.7 1214.6 1216.7 1239.5 1267.9 1271.4 729.9 754.1 828.9 831.8 863.3 4530.6 4543.5 5250.9 5253.5 5379.3 5061.8 5302.1 5712.7 5736.3 5795.5 5019.4 5176.3 5428.3 5440.3 5447.7 2442.7 2807.5 2902.0 2916.3 2916.5 5092.5 5155.8 5238.9 5817.8 5834.9 4748.9 5127.6 5162.8 5534.4 5560.3 5434.6 5768.7 5775.0 5811.9 5814.2 5084.5 5461.5 5478.4 5480.5 5502.0 4769.9 5122.9 5335.2 5365.8 5423.7 4787.2 5746.8 5794.8 5801.3 5894.2 3638.7 3640.8 3664.2 3702.1 3703.9 5164.4 5455.5 5494.9 5542.1 5577.6 4326.0 4613.3 5037.6 5382.9 5433.4 4691.9 4874.7 5348.4 5352.9 5391.6 4399.5 4787.6 5154.9 5194.5 5206.9 4440.8 4585.4 5136.5 5343.8 5606.7 5063.1 5449.6 5462.2 5492.3 5515.2 05:48:10 INFO - PROCESS | 1637 | 05:48:10 INFO - PROCESS | 1637 | [#5] yui.html Cycles:180 Average:20381.65 Median:4579.12 stddev:30623.02 (668.8%) stddev-sans-first:30673.25 05:48:10 INFO - PROCESS | 1637 | Values: 694.8 703.3 704.3 727.6 745.2 13967.3 14517.3 14526.9 14579.0 14619.8 13064.6 13205.1 13218.6 13221.1 13221.5 648.6 666.1 811.9 813.9 834.1 837.5 838.0 838.7 841.9 848.6 832.5 884.3 885.1 890.6 893.2 885.2 887.5 888.8 890.7 893.8 642.7 662.2 664.5 665.5 666.6 12130.9 13369.7 13653.5 13710.7 13720.0 13090.9 13392.7 13407.9 13416.9 13550.7 10936.4 11315.0 11392.7 11447.4 11469.8 3533.8 3553.5 3560.9 3648.7 3679.7 88382.6 91434.9 91855.6 93747.0 93913.5 10343.7 10413.1 10461.7 10595.7 10740.9 20228.5 20261.8 20262.9 20281.5 20282.6 6039.5 7587.2 7619.1 7648.7 7782.8 4183.4 4188.4 4293.2 4336.8 4382.6 3301.9 3380.0 3407.2 3412.7 3418.4 605.5 612.1 617.2 617.3 618.0 579.3 641.0 645.8 648.5 668.2 8690.4 8881.6 8905.3 8943.7 8970.3 656.9 664.7 666.1 668.8 681.9 4193.6 4294.2 4430.0 4555.3 4602.9 70592.6 93557.0 100977.5 119056.0 121429.8 101299.5 102946.0 103145.0 103328.5 105163.9 85104.7 85302.6 86051.0 87496.0 87651.6 8719.6 8730.9 8764.0 8842.4 8948.1 38580.0 52028.2 57303.4 57548.0 58585.7 63823.7 74717.0 81665.1 82622.0 84029.7 9383.0 11048.6 11172.0 11244.7 11245.0 10751.4 11859.1 11866.5 11957.2 12107.5 10696.4 11761.3 11825.5 11848.8 11858.8 11724.1 11900.9 11970.3 12265.4 12339.8 9515.8 11421.6 11711.8 11885.6 11957.6 9366.8 9653.0 9712.4 9825.3 10445.8 9833.0 10089.2 10115.4 10132.7 10138.3 05:48:10 INFO - PROCESS | 1637 | -------- Summary: end -------- 05:48:10 INFO - PROCESS | 1637 | 05:48:10 INFO - TEST-INFO | 1637: exit 0 05:48:10 INFO - TEST-OK | dromaeo_css | took 1117229ms 05:48:10 INFO - TEST-START | kraken 05:48:10 INFO - operating with platform_type : mac_ 05:48:10 INFO - Initialising browser for kraken test... 05:48:10 INFO - TEST-INFO | started process 1649 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/tmpr_lGFK/profile http://localhost:49236/getInfo.html) 05:48:14 INFO - PROCESS | 1649 | 2016-11-23 05:48:14.156 plugin-container[1651:9943] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4747, name = 'com.apple.tsm.portname' 05:48:14 INFO - PROCESS | 1649 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:48:14 INFO - PROCESS | 1649 | 2016-11-23 05:48:14.157 plugin-container[1651:9943] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x474b, name = 'com.apple.CFPasteboardClient' 05:48:14 INFO - PROCESS | 1649 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:48:14 INFO - PROCESS | 1649 | 2016-11-23 05:48:14.157 plugin-container[1651:9943] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 05:48:14 INFO - PROCESS | 1649 | __metrics Screen width/height:1600/1200 05:48:14 INFO - PROCESS | 1649 | colorDepth:24 05:48:14 INFO - PROCESS | 1649 | Browser inner width/height: 1024/689 05:48:14 INFO - PROCESS | 1649 | __metrics 05:48:14 ERROR - PROCESS | 1649 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3198: TypeError: this.tabs is undefined 05:48:15 INFO - TEST-INFO | 1649: exit 0 05:48:15 INFO - Browser initialized. 05:48:15 INFO - Running cycle 1/1 for kraken test... 05:48:15 INFO - TEST-INFO | started process 1652 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/tmpr_lGFK/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:48:17 INFO - PROCESS | 1652 | 2016-11-23 05:48:17.814 plugin-container[1653:10060] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4947, name = 'com.apple.tsm.portname' 05:48:17 INFO - PROCESS | 1652 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:48:17 INFO - PROCESS | 1652 | 2016-11-23 05:48:17.814 plugin-container[1653:10060] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8d17, name = 'com.apple.CFPasteboardClient' 05:48:17 INFO - PROCESS | 1652 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:48:17 INFO - PROCESS | 1652 | 2016-11-23 05:48:17.814 plugin-container[1653:10060] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 05:48:27 INFO - PROCESS | 1652 | RSS: Main: 241815552 05:48:27 INFO - PROCESS | 1652 | 05:48:30 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49236/tests/kraken/driver.html?testName=audio-beat-detection) 05:48:31 INFO - PROCESS | 1652 | RSS: Main: 253767680 05:48:31 INFO - PROCESS | 1652 | 05:48:33 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49236/tests/kraken/driver.html?testName=audio-dft) 05:48:34 INFO - PROCESS | 1652 | RSS: Main: 261484544 05:48:34 INFO - PROCESS | 1652 | 05:48:37 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49236/tests/kraken/driver.html?testName=audio-fft) 05:48:38 INFO - PROCESS | 1652 | RSS: Main: 264310784 05:48:38 INFO - PROCESS | 1652 | 05:48:40 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49236/tests/kraken/driver.html?testName=audio-oscillator) 05:48:41 INFO - PROCESS | 1652 | RSS: Main: 265392128 05:48:41 INFO - PROCESS | 1652 | 05:48:43 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49236/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:48:43 INFO - PROCESS | 1652 | RSS: Main: 267051008 05:48:43 INFO - PROCESS | 1652 | 05:48:46 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49236/tests/kraken/driver.html?testName=imaging-darkroom) 05:48:47 INFO - PROCESS | 1652 | RSS: Main: 272404480 05:48:47 INFO - PROCESS | 1652 | 05:48:50 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49236/tests/kraken/driver.html?testName=imaging-desaturate) 05:48:50 INFO - PROCESS | 1652 | RSS: Main: 271724544 05:48:50 INFO - PROCESS | 1652 | 05:48:53 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49236/tests/kraken/driver.html?testName=json-parse-financial) 05:48:54 INFO - PROCESS | 1652 | RSS: Main: 271736832 05:48:54 INFO - PROCESS | 1652 | 05:48:56 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49236/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:48:56 INFO - PROCESS | 1652 | RSS: Main: 270856192 05:48:56 INFO - PROCESS | 1652 | 05:48:58 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49236/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:48:59 INFO - PROCESS | 1652 | RSS: Main: 270987264 05:48:59 INFO - PROCESS | 1652 | 05:49:01 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49236/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:49:02 INFO - PROCESS | 1652 | RSS: Main: 270864384 05:49:02 INFO - PROCESS | 1652 | 05:49:05 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49236/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:49:06 INFO - PROCESS | 1652 | RSS: Main: 271138816 05:49:06 INFO - PROCESS | 1652 | 05:49:09 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49236/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:49:09 INFO - PROCESS | 1652 | RSS: Main: 271196160 05:49:09 INFO - PROCESS | 1652 | 05:49:11 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49236/tests/kraken/driver.html?testName=ai-astar) 05:49:11 INFO - PROCESS | 1652 | RSS: Main: 272863232 05:49:11 INFO - PROCESS | 1652 | 05:49:11 INFO - PROCESS | 1652 | __start_tp_report 05:49:11 INFO - PROCESS | 1652 | _x_x_mozilla_page_load 05:49:11 INFO - PROCESS | 1652 | _x_x_mozilla_page_load_details 05:49:11 INFO - PROCESS | 1652 | |i|pagename|runs| 05:49:11 INFO - PROCESS | 1652 | |0;ai-astar;109;119;120;118;119;118;119;119;119;115 05:49:11 INFO - PROCESS | 1652 | |1;audio-beat-detection;146;114;117;149;120;118;116;118;159;145 05:49:11 INFO - PROCESS | 1652 | |2;audio-dft;196;200;202;222;238;278;237;194;278;279 05:49:11 INFO - PROCESS | 1652 | |3;audio-fft;83;58;59;86;62;57;53;59;78;70 05:49:11 INFO - PROCESS | 1652 | |4;audio-oscillator;88;72;85;75;71;73;83;71;73;82 05:49:11 INFO - PROCESS | 1652 | |5;imaging-gaussian-blur;90;86;96;134;113;86;112;132;100;131 05:49:11 INFO - PROCESS | 1652 | |6;imaging-darkroom;100;100;99;98;99;131;99;98;98;101 05:49:11 INFO - PROCESS | 1652 | |7;imaging-desaturate;93;79;81;78;78;74;77;121;106;150 05:49:11 INFO - PROCESS | 1652 | |8;json-parse-financial;55;59;62;59;58;60;59;59;58;57 05:49:11 INFO - PROCESS | 1652 | |9;json-stringify-tinderbox;71;96;128;120;82;70;71;74;88;103 05:49:11 INFO - PROCESS | 1652 | |10;stanford-crypto-aes;90;90;92;89;103;79;94;103;128;114 05:49:11 INFO - PROCESS | 1652 | |11;stanford-crypto-ccm;166;164;125;153;173;182;167;164;209;153 05:49:11 INFO - PROCESS | 1652 | |12;stanford-crypto-pbkdf2;177;176;175;176;186;176;178;174;176;167 05:49:11 INFO - PROCESS | 1652 | |13;stanford-crypto-sha256-iterative;74;81;77;61;88;61;73;76;66;96 05:49:11 INFO - PROCESS | 1652 | __end_tp_report 05:49:11 INFO - PROCESS | 1652 | __start_cc_report 05:49:11 INFO - PROCESS | 1652 | _x_x_mozilla_cycle_collect,939 05:49:11 INFO - PROCESS | 1652 | __end_cc_report 05:49:11 INFO - PROCESS | 1652 | __startTimestamp1479908951944__endTimestamp 05:49:11 INFO - PROCESS | 1652 | 05:49:11 INFO - PROCESS | 1652 | ------- Summary: start ------- 05:49:11 INFO - PROCESS | 1652 | Number of tests: 14 05:49:11 INFO - PROCESS | 1652 | 05:49:11 INFO - PROCESS | 1652 | [#0] ai-astar Cycles:10 Average:117.50 Median:119.00 stddev:3.27 (2.8%) stddev-sans-first:1.42 05:49:11 INFO - PROCESS | 1652 | Values: 109.0 119.0 120.0 118.0 119.0 118.0 119.0 119.0 119.0 115.0 05:49:11 INFO - PROCESS | 1652 | 05:49:11 INFO - PROCESS | 1652 | [#1] audio-beat-detection Cycles:10 Average:130.20 Median:132.50 stddev:17.29 (13.1%) stddev-sans-first:17.37 05:49:11 INFO - PROCESS | 1652 | Values: 146.0 114.0 117.0 149.0 120.0 118.0 116.0 118.0 159.0 145.0 05:49:11 INFO - PROCESS | 1652 | 05:49:11 INFO - PROCESS | 1652 | [#2] audio-dft Cycles:10 Average:232.40 Median:237.50 stddev:35.38 (14.9%) stddev-sans-first:34.99 05:49:11 INFO - PROCESS | 1652 | Values: 196.0 200.0 202.0 222.0 238.0 278.0 237.0 194.0 278.0 279.0 05:49:11 INFO - PROCESS | 1652 | 05:49:11 INFO - PROCESS | 1652 | [#3] audio-fft Cycles:10 Average:66.50 Median:66.00 stddev:11.90 (18.0%) stddev-sans-first:11.02 05:49:11 INFO - PROCESS | 1652 | Values: 83.0 58.0 59.0 86.0 62.0 57.0 53.0 59.0 78.0 70.0 05:49:11 INFO - PROCESS | 1652 | 05:49:11 INFO - PROCESS | 1652 | [#4] audio-oscillator Cycles:10 Average:77.30 Median:78.50 stddev:6.48 (8.3%) stddev-sans-first:5.60 05:49:11 INFO - PROCESS | 1652 | Values: 88.0 72.0 85.0 75.0 71.0 73.0 83.0 71.0 73.0 82.0 05:49:11 INFO - PROCESS | 1652 | 05:49:11 INFO - PROCESS | 1652 | [#5] imaging-gaussian-blur Cycles:10 Average:108.00 Median:110.00 stddev:19.21 (17.5%) stddev-sans-first:19.24 05:49:11 INFO - PROCESS | 1652 | Values: 90.0 86.0 96.0 134.0 113.0 86.0 112.0 132.0 100.0 131.0 05:49:11 INFO - PROCESS | 1652 | 05:49:11 INFO - PROCESS | 1652 | [#6] imaging-darkroom Cycles:10 Average:102.30 Median:98.00 stddev:10.13 (10.3%) stddev-sans-first:10.71 05:49:11 INFO - PROCESS | 1652 | Values: 100.0 100.0 99.0 98.0 99.0 131.0 99.0 98.0 98.0 101.0 05:49:11 INFO - PROCESS | 1652 | 05:49:11 INFO - PROCESS | 1652 | [#7] imaging-desaturate Cycles:10 Average:93.70 Median:78.00 stddev:24.95 (32.0%) stddev-sans-first:26.47 05:49:11 INFO - PROCESS | 1652 | Values: 93.0 79.0 81.0 78.0 78.0 74.0 77.0 121.0 106.0 150.0 05:49:11 INFO - PROCESS | 1652 | 05:49:11 INFO - PROCESS | 1652 | [#8] json-parse-financial Cycles:10 Average:58.60 Median:59.00 stddev:1.84 (3.1%) stddev-sans-first:1.41 05:49:11 INFO - PROCESS | 1652 | Values: 55.0 59.0 62.0 59.0 58.0 60.0 59.0 59.0 58.0 57.0 05:49:11 INFO - PROCESS | 1652 | 05:49:11 INFO - PROCESS | 1652 | [#9] json-stringify-tinderbox Cycles:10 Average:90.30 Median:72.50 stddev:21.07 (29.1%) stddev-sans-first:21.15 05:49:11 INFO - PROCESS | 1652 | Values: 71.0 96.0 128.0 120.0 82.0 70.0 71.0 74.0 88.0 103.0 05:49:11 INFO - PROCESS | 1652 | 05:49:11 INFO - PROCESS | 1652 | [#10] stanford-crypto-aes Cycles:10 Average:98.20 Median:89.50 stddev:14.25 (15.9%) stddev-sans-first:14.80 05:49:11 INFO - PROCESS | 1652 | Values: 90.0 90.0 92.0 89.0 103.0 79.0 94.0 103.0 128.0 114.0 05:49:11 INFO - PROCESS | 1652 | 05:49:11 INFO - PROCESS | 1652 | [#11] stanford-crypto-ccm Cycles:10 Average:165.60 Median:166.50 stddev:21.55 (12.9%) stddev-sans-first:22.86 05:49:11 INFO - PROCESS | 1652 | Values: 166.0 164.0 125.0 153.0 173.0 182.0 167.0 164.0 209.0 153.0 05:49:11 INFO - PROCESS | 1652 | 05:49:11 INFO - PROCESS | 1652 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:176.10 Median:176.00 stddev:4.61 (2.6%) stddev-sans-first:4.87 05:49:11 INFO - PROCESS | 1652 | Values: 177.0 176.0 175.0 176.0 186.0 176.0 178.0 174.0 176.0 167.0 05:49:11 INFO - PROCESS | 1652 | 05:49:11 INFO - PROCESS | 1652 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:75.30 Median:76.50 stddev:11.20 (14.6%) stddev-sans-first:11.86 05:49:11 INFO - PROCESS | 1652 | Values: 74.0 81.0 77.0 61.0 88.0 61.0 73.0 76.0 66.0 96.0 05:49:11 INFO - PROCESS | 1652 | -------- Summary: end -------- 05:49:11 INFO - PROCESS | 1652 | 05:49:13 INFO - TEST-INFO | 1652: exit 0 05:49:13 INFO - TEST-OK | kraken | took 62434ms 05:49:13 INFO - SUITE-END | took 1179s 05:49:13 INFO - Completed test suite (00:19:40) 05:49:13 INFO - javascript benchmark 05:49:13 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 6965.889044865568, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2703.891844326227, 2966.5401862711287, 3184.9489668409415, 3317.3531161423516, 3373.4939759036142, 4300.3764454154125, 5383.300170977777, 5433.049216387136, 5481.862953426156, 5486.780528778844, 4503.549645035495, 5427.701476418795, 5497.670139791615, 5513.117901135819, 5521.1442226454965, 4986.675866068694, 5037.4458809530925, 5080.77136528857, 5121.155009423441, 5122.334096567441, 4159.376093585956, 4976.178930476485, 5081.898362032775, 5098.617603679721, 5134.0758663440565, 4946.901061978759, 5092.823386308802, 5111.948880511203, 5138.486151384869, 5145.511176438233, 5092.516210959953, 5098.745062746885, 5115.411727651331, 5131.666441681297, 5183.533481986601, 4886.0, 4896.657233993622, 4992.525710057539, 5000.849974500771, 5036.143855544529, 4248.766317852517, 5427.565794736451, 5443.700596467193, 5476.26070480484, 5551.528120109796, 5890.646561206298, 5932.881342373172, 5954.404559544011, 5960.195373624592, 5966.2840459144745, 5902.527797776168, 5944.316403613597, 5952.255968003999, 6044.2746870375395, 6063.423974722395, 5526.4197259288, 5531.668099913978, 5532.363778165482, 5543.25166102575, 5547.33431988164, 6088.817335479943, 6131.346082861964, 6145.938540614626, 6151.046587778812, 6183.103449999815, 5800.216970708901, 5829.47534721877, 5843.970780146072, 5853.5317174625925, 5873.060310350324, 6061.212042434543, 6063.060225665029, 6066.75732970685, 6082.57421980466, 6092.360302168208, 5719.714014299351, 5917.852053698692, 5969.925413425625, 5976.641401515923, 6022.849428764316, 5275.498827611371, 5878.353381128158, 5880.617759845682, 5928.466438020599, 5993.011153159767, 3983.701222408331, 4747.359106520645, 4762.547557981986, 4768.022555376139, 4775.785089670973, 4252.596003379674, 4257.850975215914, 4277.615014648696, 4289.806958686867, 4365.781710914442, 4540.568645978627, 5333.773232156625, 5582.469665381782, 5634.042212823748, 5666.065099258576, 6001.279846418458, 6098.847528811815, 6110.297315808739, 6126.908096378558, 6132.202813634199, 5704.914426283609, 5712.314522257273, 5728.79949201776, 5734.770609175586, 5758.424157584208, 5089.109405853961, 5122.103631864409, 5207.739613019409, 5230.215467679878, 5246.134387825966, 6157.753689852356, 6167.136600875881, 6216.533759968111, 6229.12792209082, 6243.500519958302, 6076.210092687922, 6076.817695469143, 6081.179040829431, 6101.145839582373, 6104.236970378703, 6205.968970155121, 6234.781782637586, 6250.062271463648, 6258.593191442542, 6266.530010249158, 5285.048691235615, 5524.281843360337, 5755.65466072037, 5772.855678607901, 5841.4450627190345, 4607.308903664343, 4840.685355452025, 5976.61152025117, 5993.400659933972, 6028.547858910687, 5825.825225243249, 5830.912536311874, 5867.061270196748, 5886.05823068307, 5906.438888305604, 4831.951680483291, 5761.135829625422, 5829.59192856496, 5844.26946631671, 5859.472647461749, 4757.785899634456, 5588.776448941997, 5599.636023658449, 5620.269364982526, 5655.4910058094965, 5688.288963879515, 5729.822667386366, 5779.219805326227, 5789.0, 5828.329742079715, 5600.103983362643, 5674.574406919415, 5680.488756011979, 5681.261436013292, 5695.544356451557], "value": 5426.035982869851, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [452.6265830689681, 470.75520729220807, 471.83721616042453, 503.9647224694271, 558.9385167631563, 29311.974080907174, 30947.0, 31078.06765797024, 39346.80326598367, 40778.0, 39834.40248396277, 40228.39657405141, 40261.7986910065, 40315.992100197436, 40396.59603403965, 9047.819043619123, 9194.218491428237, 9200.723978280663, 9202.493862837542, 9227.44635321883, 8296.751097467086, 8502.74491765248, 9090.59092340843, 10733.785324293509, 11074.833877491845, 11088.722781930435, 11136.164787640919, 11138.108951283919, 11141.442927853575, 11143.832842507369, 9379.624815007426, 11046.447677616126, 11095.11239100878, 11135.888641113566, 11137.276077055047, 10893.727656808564, 10981.505832237568, 10987.175961802897, 10992.945035274852, 10993.340399576051, 37867.62132378668, 38228.61771382279, 38280.0235969325, 38290.23419531622, 38299.617003830164, 2938.7208215219734, 3254.902352929416, 3317.8506967186295, 3488.4069708149423, 3607.494950706903, 28071.43857122846, 28164.15507534777, 29088.563671544944, 29634.25914352137, 29745.25636859074, 3132.201288671397, 3228.515722641599, 3234.2237862912966, 3236.304194598149, 3263.5757351544385, 407099.0, 487009.0, 489298.0, 492019.0, 496555.0, 54426.45573544214, 54672.72663636736, 54738.0, 54773.178402323996, 54858.72570637121, 56146.71926640422, 56490.71754641201, 56730.865382692115, 56777.0, 56884.14673779897, 15130.470433534774, 15521.146336951577, 15521.844781552265, 15660.843391565939, 15689.921550392175, 11085.0, 12334.321612311229, 12338.321392323509, 12350.0, 12358.258504489759, 12939.09426340166, 19309.413104682593, 19684.212631494578, 19872.90063549702, 20349.49126271855, 7000.054992576039, 7844.137144914055, 8103.10865804761, 8113.310368618733, 8141.185881411811, 777.5140537164273, 855.4354126276628, 915.5834095486671, 921.2814005076051, 1049.9422531760827, 23864.522709545712, 29236.561451578244, 29261.707382926324, 29772.0, 29783.85108074489, 920.7007722490217, 984.2519685039422, 1022.8465730140539, 1057.603398725478, 1132.4960392625203, 12440.755924407487, 15252.771208431883, 15404.152771597446, 15610.531684049496, 15698.843011569967, 574918.0, 675106.0, 687776.0, 687894.0, 688847.0, 55251.44748552463, 55356.16965745517, 55572.44427555754, 55787.44212557822, 55870.441295587334, 1273.045216087934, 1314.0801438992744, 1340.631326385252, 1342.5502456676927, 1345.6232254968625, 28182.718172818008, 29109.41781164407, 29137.271568210115, 29949.0, 30190.69809301879, 47261.0, 47470.28794568015, 47557.28664070111, 47573.52426475829, 47662.523374765806, 613949.0, 615204.0, 616959.0, 618260.0, 618689.0, 33332.833335833166, 34910.0, 37473.437898431, 39968.60031399649, 40567.0, 43486.0, 43623.56376436195, 43748.56251437445, 43998.0, 44075.77962110169, 36358.8182059088, 36486.63513364938, 37345.06637334089, 37410.0, 37441.625583744906, 36690.0, 36743.448848266766, 37601.4359784598, 37770.0, 37820.24359512849, 35623.64376356203, 35652.46521302131, 36544.81727591345, 36575.634243658285, 36949.445758314185, 31225.0, 31549.0, 32981.340373192885, 33073.669263307056, 33104.0, 23689.881550592138, 24013.75986240115, 24131.638025429984, 24617.0, 24627.79187247955], "value": 19682.29816285281, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [368.78241837315994, 376.4015215806867, 381.870164144191, 387.6975958752171, 399.894028082558, 17742.290308387666, 19253.422397328068, 19647.410577682673, 19751.0, 19798.30705925293, 19195.656304058688, 19308.806911930875, 19451.221951121974, 19476.0, 19819.801801981976, 4942.382202224722, 5164.612654050942, 5429.972850135754, 5717.971410142944, 5718.828435146952, 5542.2517960075475, 5720.456556627135, 5721.628094173865, 5723.198752174699, 5732.398098199692, 5407.134858422653, 5634.3520495143175, 5672.716364181795, 5675.432456754313, 5712.828615141531, 5531.944680553203, 5587.692676902769, 5588.776448942038, 5593.608447408683, 5610.214569960209, 4728.290756386569, 4904.068227036852, 4926.802927882881, 4934.037862616791, 4958.553730164266, 18293.81090229131, 22146.335609931568, 23797.524049519085, 24230.757692423027, 24591.50816983668, 2220.977790222093, 2323.268170526279, 2377.679013333195, 2409.445827459694, 2503.5994240921364, 13240.668983275495, 13310.0, 14742.484013059491, 14783.482578109822, 14991.700165996619, 1731.6277000444838, 1759.7096479080935, 1763.2506184871506, 1788.3383148235107, 1871.9532011699816, 101256.98743012623, 117298.41350793277, 119503.0, 120471.0, 120533.19200212003, 8944.182582486495, 9544.475053872104, 9657.46883921391, 9804.558794854249, 10281.794364112826, 11844.526218951318, 12225.144239903122, 12234.143609947396, 12262.20295680778, 12266.0, 7629.351505122016, 7726.6136693166245, 7779.766607001686, 7835.569043702537, 7859.960700196462, 8022.0, 8049.959750201211, 8087.716929907425, 8182.549959752152, 8217.95891020541, 3665.5051568038507, 3785.356489396809, 3797.867074652374, 3844.6539811417106, 3855.8264878080554, 1616.6685829404944, 1889.3576184097474, 1921.5580416504004, 1927.1905799564217, 1931.6092413462284, 697.6720941157648, 948.4593781544592, 949.8907625623142, 957.6073809737974, 957.9425234485954, 19566.5108372289, 20455.0, 20629.484262893548, 20732.378028659165, 20863.0, 879.3976126353341, 906.8231694819578, 934.6495064350869, 962.9503840813406, 988.1353815411514, 7066.611336376446, 10328.948355258175, 10595.099416549525, 10659.626913058006, 10660.41367724767, 582604.0, 590958.0, 594995.0, 595671.0, 597571.0, 124303.37848310881, 128771.0, 129173.0, 131443.0, 135516.0, 975.6438899801522, 989.8267803134567, 1022.3201570955232, 1026.1688032693394, 1029.5727273181697, 15396.076235425462, 17461.12694365302, 18061.81938180654, 18462.0, 18728.6254274911, 48720.0, 48906.75546622386, 54021.0, 55695.0, 56121.15818262811, 81415.59292203501, 82193.0, 83665.0, 84055.0, 85603.0, 7765.883511747215, 7815.47598218342, 7839.568823714751, 7852.960735196288, 8060.1133875274845, 10004.24968127408, 11591.072714183014, 12578.056645751789, 12761.489540418279, 12859.485620575073, 10139.138173255062, 10865.076468500267, 10946.90530946899, 10995.890041099487, 11118.499667515143, 5066.6200034998255, 8459.06950235486, 8747.606357714045, 10714.410707411167, 11157.995780379804, 11024.889751102386, 11208.775824483628, 11259.380734059378, 11490.597829075943, 11493.942530287295, 7856.489328193649, 7863.567503787347, 8058.476199047043, 8070.152633973348, 8299.626516806878, 8209.83580328402, 10653.307535010199, 10985.780284394426, 11327.0, 11357.318560886373], "value": 9193.855659507573, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1455.6943041961188, 1474.9705005899882, 1620.679146495606, 1708.709519381705, 1741.1903464888824, 7423.591702456362, 7642.350400215974, 7754.108277548084, 8164.346852251821, 8186.713465028725, 6896.275891031445, 7064.434845212383, 7751.844963100734, 8058.39562032847, 8065.838683226332, 1991.5718120604104, 2298.333483289844, 2304.9193278235266, 2305.873176975266, 2307.042577330406, 1668.990260892161, 2042.908069136885, 2166.9598592675525, 2340.742518322983, 2341.9648705269433, 1896.2130715752946, 2284.977150228493, 2297.2304278066867, 2311.3874823171873, 2313.710786151731, 2259.830512711545, 2272.4091736148557, 2288.496530763238, 2293.312006398074, 2295.747467778543, 2299.0803678528555, 2314.3982564533176, 2315.988420057889, 2319.4433335999406, 2330.300909727092, 8032.0, 8152.959235203845, 8190.8771368429525, 8209.302209312216, 8213.671453141867, 4942.901141977176, 5109.310243117169, 5110.131277682803, 5111.361079865017, 5117.334746482933, 3116.236522052089, 3170.746340292771, 3519.04985653872, 3521.9119522011897, 3536.752427330088, 1020.8774947006407, 1058.7035630023606, 1074.591655171038, 1077.1113831089383, 1079.6760971708457, 8465.66137354505, 10171.949140254326, 10434.895651043544, 10495.580176792919, 10524.10545103667, 4148.357004664222, 4221.071364299849, 4313.202057619351, 4324.17840610283, 4327.221100201994, 8321.708740194064, 8392.370572207108, 8396.79008024792, 8406.0, 8429.32565394767, 4619.960508885474, 4656.371389862393, 4944.703317800943, 4960.677555958923, 4960.925586116211, 3539.309834582283, 4291.0, 4591.609713174417, 4677.251639737626, 4692.741899195577, 3519.260955199437, 3539.2744487380023, 3589.6230895756094, 3612.8012959286966, 3635.836387362575, 1132.8017596920506, 1134.625573560723, 1137.3119262846024, 1143.6168883424145, 1146.8165093584987, 774.77972193795, 783.4633276205817, 879.3712495565701, 881.2377293640955, 898.9910100898908, 5214.0, 5321.228421878806, 5410.48600382963, 5531.308586426697, 5613.8596535086135, 354.58159371940843, 412.75028607692514, 423.2360589136638, 427.4891504651973, 431.967602429819, 3104.5653608494827, 3665.6700896919406, 3768.773873567595, 3793.8482460701816, 3836.3861782114727, 9408.247340212767, 9768.413895166314, 9833.704988850346, 9852.45811480361, 9861.40831550095, 9553.761155971157, 9558.235341172689, 9587.089226523469, 9810.411375317504, 9854.556544955494, 7544.924550754532, 7659.61701914902, 7662.96168519165, 7689.038870141344, 7690.423218258653, 4079.3269110597607, 5379.838604841861, 5398.0, 5446.809361672323, 5450.563954883521, 9003.724580237691, 9024.593893274687, 9088.500132492776, 9089.272858171462, 9129.817403651758, 8968.192862642394, 9156.0, 9169.35814492979, 9245.72262832116, 9261.67584134552, 5991.191189189578, 6094.299155597163, 6133.539984501091, 6474.352564743488, 6633.270340262664, 7613.581253031137, 7825.882611760942, 7838.843223135619, 7845.725399610987, 7853.332466740391, 6888.621125837926, 6985.44116470671, 6994.650267486503, 7010.0, 7124.109486314211, 6791.932080679328, 6856.622885741132, 6886.621235832077, 6897.172339319314, 6929.133858267716, 6446.67766611677, 6487.870242595027, 6580.539362244597, 6585.967070164618, 6675.198976122702, 3984.521857377133, 4744.288356746378, 4765.11845308619, 4815.711057336431, 4834.0, 3831.3678243090694, 4752.667313288037, 4829.710217386827, 4855.344528488608, 4917.557419832232], "value": 4143.462957213507, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [259.32316653534275, 271.2716356582576, 308.12185271974886, 312.46100476677725, 314.1345592891582, 4172.833086676533, 5061.873453163673, 5269.920951185735, 5309.4692029800135, 5332.520073193412, 5195.220716892468, 5210.374755029392, 5222.250546067063, 5224.764885580148, 5234.8691282717955, 3835.252125835449, 3837.6354246346623, 3966.5720340677335, 3972.7616343019367, 3991.501062367204, 3976.423418604315, 3994.8002599870038, 4023.0, 4050.027993281606, 4061.2283666103344, 3102.1158969693643, 3974.6621537169376, 4000.5999400059904, 4004.5583589907756, 4021.77880216588, 3725.7578257413184, 3953.802309884494, 4002.399640053986, 4019.356902895537, 4027.4965629296485, 2783.8403412127177, 3165.7942233754734, 3379.3579219948133, 3388.0852169914233, 3409.1988382730037, 5046.243063540462, 5108.843843169009, 5109.208072748693, 5120.308758317654, 5121.462246464105, 3872.7095467839745, 4507.481639611421, 4521.977390113028, 4534.455865296152, 4592.277895789079, 5237.240600113, 5450.91823622646, 5508.20131080995, 5553.917281019618, 5579.944200558024, 3753.0242137044565, 3775.1505911170043, 3783.205526839367, 3804.619538046173, 3832.3485007548784, 5070.873228169288, 5650.802221922254, 5817.7382017809305, 5865.472107510344, 5887.735051922674, 3374.8818791342187, 3452.2405070884365, 3566.589842168132, 3613.6205698401814, 3763.2312812977857, 3476.0962149841225, 3881.5924327945722, 3886.9116647338224, 3912.080661044652, 3927.19492504036, 3791.3554695701796, 3821.464994900716, 3875.9806200968815, 4566.703164294377, 4579.702319349231, 4110.630043296058, 4820.903581928412, 5183.455737147545, 5567.276254086943, 5658.74535645897, 1214.5870404062662, 1216.7444836584416, 1239.4732238798474, 1267.8541967673837, 1271.4215032160346, 729.8722723523363, 754.0950858969259, 828.8549503836925, 831.8128421105203, 863.2705363967438, 4530.614897733663, 4543.522930092358, 5250.921236181461, 5253.527182553512, 5379.327584051994, 5061.772220250097, 5302.072137375943, 5712.685802280831, 5736.340320863154, 5795.507381872588, 5019.42276638184, 5176.275321454999, 5428.294321738149, 5440.292761940922, 5447.673139611636, 2442.7190873049826, 2807.494650962846, 2901.9709802901702, 2916.3000879788697, 2916.4896143175283, 5092.541671249605, 5155.81416274259, 5238.869028274324, 5817.7963771267805, 5834.912476312774, 4748.857534273978, 5127.615428842777, 5162.793488260428, 5534.363548192009, 5560.277163968739, 5434.5652347812875, 5768.711564421846, 5775.025861681237, 5811.854703632358, 5814.244148260784, 5084.491550844886, 5461.453854614507, 5478.369987451335, 5480.506754392124, 5502.0, 4769.856904292877, 5122.8975420492125, 5335.1730481775385, 5365.758540865592, 5423.722036744655, 4787.162246606899, 5746.821901510097, 5794.797182098607, 5801.332846722681, 5894.233749612523, 3638.6725194732603, 3640.7633503822167, 3664.2488289899975, 3702.0929872181487, 3703.8888833335045, 5164.431912489698, 5455.509004189642, 5494.945050549443, 5542.115680668592, 5577.553795696415, 4325.961769175312, 4613.261878099488, 5037.571806396498, 5382.8552014949055, 5433.397147841317, 4691.906161876812, 4874.683145595525, 5348.411674715856, 5352.892942141213, 5391.595630327663, 4399.450068741407, 4787.593054590259, 5154.871128221825, 5194.506521880416, 5206.869828254324, 4440.777961101868, 4585.426821647294, 5136.5377116059735, 5343.809038294281, 5606.747696353592, 5063.13932628064, 5449.618526703092, 5462.235287059894, 5492.286002819767, 5515.227868098389], "value": 3954.8959290987073, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [694.7880896326618, 703.2721133626695, 704.2957042957042, 727.5561907236581, 745.2063552316782, 13967.30163491825, 14517.27413629317, 14526.927365363159, 14579.0, 14619.78070328946, 13064.608061758163, 13205.141665791716, 13218.603441896734, 13221.140625859336, 13221.537246196385, 648.6011103171539, 666.1074160624762, 811.9391045671568, 813.9348852091848, 834.0866750907746, 837.4849467577426, 838.0153319849184, 838.6938767349903, 841.8947631546057, 848.588434609214, 832.4505826154739, 884.3455842676406, 885.1104639836955, 890.6437425029975, 893.1871996483202, 885.2431171348469, 887.4941283468426, 888.8177923525694, 890.7149712092133, 893.7810236492069, 642.7075680565332, 662.2351184382046, 664.4684252597903, 665.4809248785955, 666.6433458099893, 12130.939345303304, 13369.66303369665, 13653.453861845514, 13710.657233569142, 13720.0, 13090.86909130906, 13392.665183370396, 13407.865921340759, 13416.865831341756, 13550.661233469144, 10936.398498082603, 11314.981651651391, 11392.658220253406, 11447.370394628291, 11469.827952580717, 3533.770304930197, 3553.5202747628996, 3560.8753693620856, 3648.6533779290926, 3679.7240206984584, 88382.55808720915, 91434.91102082432, 91855.6221656669, 93747.0, 93913.53043234808, 10343.689689309333, 10413.114885234689, 10461.738456538647, 10595.682129536126, 10740.946295268473, 20228.494287642632, 20261.797382026285, 20262.89868550648, 20281.49296267605, 20282.594348112954, 6039.48664363534, 7587.2412758723685, 7619.085709714759, 7648.694052237959, 7782.766517004499, 4183.435236243129, 4188.350805625133, 4293.2272191005295, 4336.783160841946, 4382.649388048949, 3301.9174520636698, 3379.9662003379653, 3407.1993081625797, 3412.7099196568074, 3418.3676019935892, 605.5428151745492, 612.1277180018545, 617.1883972576048, 617.2870334763355, 618.0358640520802, 579.2759051186017, 641.0160701932512, 645.7675236914707, 648.487694721165, 668.1881513960465, 8690.435121717195, 8881.600327985256, 8905.332100092519, 8943.686970956116, 8970.282377409792, 656.8883289840738, 664.6809531424943, 666.0974379715416, 668.7625892807984, 681.8670359279992, 4193.559676234013, 4294.162638285505, 4429.955700443019, 4555.293929440942, 4602.941323495615, 70592.6470367645, 93557.0, 100977.48533771999, 119056.0, 121429.78570214362, 101299.49350253202, 102946.0, 103145.0, 103328.45007324746, 105163.94836051695, 85104.72342914861, 85302.57348713216, 86051.0, 87496.0, 87651.56174219088, 8719.607617657348, 8730.869036964577, 8764.0, 8842.381033327605, 8948.149925757043, 38580.0, 52028.179013734334, 57303.42696572981, 57548.0, 58585.70707146437, 63823.6808815953, 74717.0, 81665.14172003951, 82622.0, 84029.73955390551, 9383.0, 11048.613298534512, 11172.0, 11244.66266012021, 11245.0, 10751.408672522773, 11859.110566707359, 11866.457360543074, 11957.162998590014, 12107.515699371927, 10696.358218506915, 11761.294322340687, 11825.526978920747, 11848.76302473963, 11858.822117668415, 11724.120690948042, 11900.940495297467, 11970.341631210018, 12265.448054837376, 12339.75320493603, 9515.809683806423, 11421.600243991421, 11711.765764684487, 11885.5840045598, 11957.581484647995, 9366.76583085401, 9653.03469653029, 9712.417254964725, 9825.263105266991, 10445.791084178425, 9833.0, 10089.243306751876, 10115.443650598992, 10132.696019119438, 10138.341007834468], "value": 7099.922217296586, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1591.9999999999998, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [109.0, 119.0, 120.0, 118.0, 119.0, 118.0, 119.0, 119.0, 119.0, 115.0], "value": 117.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 114.0, 117.0, 149.0, 120.0, 118.0, 116.0, 118.0, 159.0, 145.0], "value": 130.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [196.0, 200.0, 202.0, 222.0, 238.0, 278.0, 237.0, 194.0, 278.0, 279.0], "value": 232.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 58.0, 59.0, 86.0, 62.0, 57.0, 53.0, 59.0, 78.0, 70.0], "value": 66.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 72.0, 85.0, 75.0, 71.0, 73.0, 83.0, 71.0, 73.0, 82.0], "value": 77.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 86.0, 96.0, 134.0, 113.0, 86.0, 112.0, 132.0, 100.0, 131.0], "value": 108.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [100.0, 100.0, 99.0, 98.0, 99.0, 131.0, 99.0, 98.0, 98.0, 101.0], "value": 102.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 79.0, 81.0, 78.0, 78.0, 74.0, 77.0, 121.0, 106.0, 150.0], "value": 93.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 59.0, 62.0, 59.0, 58.0, 60.0, 59.0, 59.0, 58.0, 57.0], "value": 58.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 96.0, 128.0, 120.0, 82.0, 70.0, 71.0, 74.0, 88.0, 103.0], "value": 90.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 90.0, 92.0, 89.0, 103.0, 79.0, 94.0, 103.0, 128.0, 114.0], "value": 98.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [166.0, 164.0, 125.0, 153.0, 173.0, 182.0, 167.0, 164.0, 209.0, 153.0], "value": 165.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [177.0, 176.0, 175.0, 176.0, 186.0, 176.0, 178.0, 174.0, 176.0, 167.0], "value": 176.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 81.0, 77.0, 61.0, 88.0, 61.0, 73.0, 76.0, 66.0, 96.0], "value": 75.3, "unit": "score"}]}]} 05:49:13 INFO - Return code: 0 05:49:13 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 05:49:13 ERROR - # TBPL SUCCESS # 05:49:13 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:49:13 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:49:13 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:49:13 INFO - 2016-11-23 05:49:13.710 screenresolution[1657:10438] starting screenresolution argv=screenresolution get 05:49:13 INFO - 2016-11-23 05:49:13.713 screenresolution[1657:10438] Display 0: 1600x1200x32@60 05:49:13 INFO - 2016-11-23 05:49:13.719 screenresolution[1658:10441] starting screenresolution argv=screenresolution list 05:49:13 INFO - Available Modes on Display 0 05:49:13 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:49:13 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:49:13 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:49:13 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:49:13 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:49:13 INFO - Intel Iris: 05:49:13 INFO - Chipset Model: Intel Iris 05:49:13 INFO - Type: GPU 05:49:13 INFO - Bus: Built-In 05:49:13 INFO - VRAM (Dynamic, Max): 1536 MB 05:49:13 INFO - Vendor: Intel (0x8086) 05:49:13 INFO - Device ID: 0x0a2e 05:49:13 INFO - Revision ID: 0x0009 05:49:13 INFO - Displays: 05:49:13 INFO - Display: 05:49:13 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:49:13 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:49:13 INFO - Display Serial Number: 1600x1200 60 05:49:13 INFO - Main Display: Yes 05:49:13 INFO - Mirror: Off 05:49:13 INFO - Online: Yes 05:49:13 INFO - Rotation: Supported 05:49:13 INFO - Return code: 0 05:49:13 INFO - Running post-action listener: _resource_record_post_action 05:49:13 INFO - [mozharness: 2016-11-23 13:49:13.953376Z] Finished run-tests step (success) 05:49:13 INFO - Running post-run listener: _resource_record_post_run 05:49:14 INFO - Total resource usage - Wall time: 1213s; CPU: 55.0%; Read bytes: 38983680; Write bytes: 696201728; Read time: 245; Write time: 2012 05:49:14 INFO - TinderboxPrint: CPU usage
54.6% 05:49:14 INFO - TinderboxPrint: I/O read bytes / time
38,983,680 / 245 05:49:14 INFO - TinderboxPrint: I/O write bytes / time
696,201,728 / 2,012 05:49:14 INFO - TinderboxPrint: CPU idle
2,201.2 (45.4%) 05:49:14 INFO - TinderboxPrint: CPU system
984.0 (20.3%) 05:49:14 INFO - TinderboxPrint: CPU user
1,668.5 (34.4%) 05:49:14 INFO - TinderboxPrint: Swap in / out
421,937,152 / 0 05:49:14 INFO - install - Wall time: 33s; CPU: 54.0%; Read bytes: 383969792; Write bytes: 386518528; Read time: 28912; Write time: 848 05:49:14 INFO - run-tests - Wall time: 1181s; CPU: 55.0%; Read bytes: 35796992; Write bytes: 308327424; Read time: 221; Write time: 1159 05:49:14 INFO - Running post-run listener: _upload_blobber_files 05:49:14 INFO - Blob upload gear active. 05:49:14 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:49:14 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 05:49:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 05:49:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 05:49:14 INFO - (blobuploader) - INFO - Open directory for files ... 05:49:14 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 05:49:14 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:49:14 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:49:15 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 05:49:15 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:49:15 INFO - (blobuploader) - INFO - Done attempting. 05:49:15 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 05:49:15 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:49:15 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:49:16 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 05:49:16 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:49:16 INFO - (blobuploader) - INFO - Done attempting. 05:49:16 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 05:49:16 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:49:16 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:49:19 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 05:49:19 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:49:19 INFO - (blobuploader) - INFO - Done attempting. 05:49:19 INFO - (blobuploader) - INFO - Iteration through files over. 05:49:19 INFO - Return code: 0 05:49:19 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:49:19 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:49:19 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/58e4729ec4e1430620621b26dae34ca55c360feb3f03dbc73c359681401bc94bfcee8536f238a7933ad37bd50bdea1c1336cc5c4f077e1be248d266ae0741b76", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3823752f52a48c945b9b2f4ab901ab349d9c8bd974ddd9c93c26c9a98af2cba4229cb331dd813282ada5e6a6230597c2106af6704166248a3852e198911f23bd", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f2fddcc98677f1b03c04d384c6b86834df533250591b140dcdb484cdb0d1fd190a62a905b8e91c8123c9c8d64250f16639c17826a60b41876f4c160d4d73bf13"} 05:49:19 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:49:19 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:49:19 INFO - Contents: 05:49:19 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/58e4729ec4e1430620621b26dae34ca55c360feb3f03dbc73c359681401bc94bfcee8536f238a7933ad37bd50bdea1c1336cc5c4f077e1be248d266ae0741b76", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3823752f52a48c945b9b2f4ab901ab349d9c8bd974ddd9c93c26c9a98af2cba4229cb331dd813282ada5e6a6230597c2106af6704166248a3852e198911f23bd", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f2fddcc98677f1b03c04d384c6b86834df533250591b140dcdb484cdb0d1fd190a62a905b8e91c8123c9c8d64250f16639c17826a60b41876f4c160d4d73bf13"} 05:49:19 INFO - Running post-run listener: copy_logs_to_upload_dir 05:49:19 INFO - Copying logs to upload dir... 05:49:19 INFO - mkdir: /builds/slave/test/build/upload/logs 05:49:19 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1277.447867 ========= master_lag: 0.24 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 17 secs) (at 2016-11-23 05:49:19.416853) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-23 05:49:19.421907) ========= 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.8FBCNktbhp/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.ptvva15s3s/Listeners TMPDIR=/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/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/58e4729ec4e1430620621b26dae34ca55c360feb3f03dbc73c359681401bc94bfcee8536f238a7933ad37bd50bdea1c1336cc5c4f077e1be248d266ae0741b76", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3823752f52a48c945b9b2f4ab901ab349d9c8bd974ddd9c93c26c9a98af2cba4229cb331dd813282ada5e6a6230597c2106af6704166248a3852e198911f23bd", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f2fddcc98677f1b03c04d384c6b86834df533250591b140dcdb484cdb0d1fd190a62a905b8e91c8123c9c8d64250f16639c17826a60b41876f4c160d4d73bf13"} build_url:https://queue.taskcluster.net/v1/task/XV6MW-jpSf2qB-jdeZep1A/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011106 build_url: 'https://queue.taskcluster.net/v1/task/XV6MW-jpSf2qB-jdeZep1A/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/58e4729ec4e1430620621b26dae34ca55c360feb3f03dbc73c359681401bc94bfcee8536f238a7933ad37bd50bdea1c1336cc5c4f077e1be248d266ae0741b76", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3823752f52a48c945b9b2f4ab901ab349d9c8bd974ddd9c93c26c9a98af2cba4229cb331dd813282ada5e6a6230597c2106af6704166248a3852e198911f23bd", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f2fddcc98677f1b03c04d384c6b86834df533250591b140dcdb484cdb0d1fd190a62a905b8e91c8123c9c8d64250f16639c17826a60b41876f4c160d4d73bf13"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-23 05:49:19.457620) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-23 05:49:19.457918) ========= 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.8FBCNktbhp/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.ptvva15s3s/Listeners TMPDIR=/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/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.005235 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-23 05:49:19.508856) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-23 05:49:19.509481) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-23 05:49:19.510072) ========= ========= Total master_lag: 0.39 =========