builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0011 starttime: 1479907702.32 results: success (0) buildid: 20161123030208 builduid: f4871a9f0cf64f1e8d578ca886745a14 revision: 0ddfec7126ec503b54df9c4b7c3b988906f6c882 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-11-23 05:28:22.319323) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-11-23 05:28:22.319780) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-23 05:28:22.320102) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-23 05:28:22.345316) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-23 05:28:22.345707) ========= 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.k6igA4CxJG/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.j4FVZuPvZN/Listeners TMPDIR=/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/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:28:22-- 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% 159M=0s 2016-11-23 05:28:22 (159 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.128157 ========= master_lag: 0.13 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-23 05:28:22.604841) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-23 05:28:22.605531) ========= 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.k6igA4CxJG/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.j4FVZuPvZN/Listeners TMPDIR=/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/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.019337 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-23 05:28:22.647809) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-11-23 05:28:22.648184) ========= 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.k6igA4CxJG/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.j4FVZuPvZN/Listeners TMPDIR=/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/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:28:22,723 truncating revision to first 12 chars 2016-11-23 05:28:22,723 Setting DEBUG logging. 2016-11-23 05:28:22,723 attempt 1/10 2016-11-23 05:28:22,723 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:24,734 unpacking tar archive at: mozilla-central-0ddfec7126ec/testing/mozharness/ program finished with exit code 0 elapsedTime=2.260253 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-11-23 05:28:24.931943) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-23 05:28:24.935372) ========= script_repo_revision: 0ddfec7126ec503b54df9c4b7c3b988906f6c882 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-23 05:28:24.935854) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-23 05:28:24.936134) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-23 05:28:24.956432) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 16 secs) (at 2016-11-23 05:28:24.956836) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.k6igA4CxJG/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.j4FVZuPvZN/Listeners TMPDIR=/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/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:25 INFO - MultiFileLogger online at 20161123 05:28:25 in /builds/slave/test 05:28:25 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 05:28:25 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:28:25 INFO - {'append_to_log': False, 05:28:25 INFO - 'base_work_dir': '/builds/slave/test', 05:28:25 INFO - 'blob_upload_branch': 'Firefox', 05:28:25 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:28:25 INFO - 'branch': 'Firefox', 05:28:25 INFO - 'buildbot_json_path': 'buildprops.json', 05:28:25 INFO - 'config_files': ('talos/mac_config.py',), 05:28:25 INFO - 'default_actions': ('clobber', 05:28:25 INFO - 'read-buildbot-config', 05:28:25 INFO - 'download-and-extract', 05:28:25 INFO - 'populate-webroot', 05:28:25 INFO - 'create-virtualenv', 05:28:25 INFO - 'install', 05:28:25 INFO - 'run-tests'), 05:28:25 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:28:25 INFO - 'download_minidump_stackwalk': True, 05:28:25 INFO - 'download_symbols': 'ondemand', 05:28:25 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:28:25 INFO - 'tooltool.py': '/tools/tooltool.py', 05:28:25 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:28:25 INFO - '/tools/misc-python/virtualenv.py')}, 05:28:25 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:28:25 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:28:25 INFO - 'installer_path': 'installer.exe', 05:28:25 INFO - 'log_level': 'info', 05:28:25 INFO - 'log_name': 'talos', 05:28:25 INFO - 'log_to_console': True, 05:28:25 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 05:28:25 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 05:28:25 INFO - 'opt_config_files': (), 05:28:25 INFO - 'pip_index': False, 05:28:25 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:28:25 INFO - 'cmd': ('bash', 05:28:25 INFO - '-c', 05:28:25 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:28:25 INFO - 'enabled': True, 05:28:25 INFO - 'halt_on_failure': False, 05:28:25 INFO - 'name': 'check_screen_resolution'},), 05:28:25 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:28:25 INFO - 'cmd': ('bash', 05:28:25 INFO - '-c', 05:28:25 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:28:25 INFO - 'enabled': True, 05:28:25 INFO - 'halt_on_failure': False, 05:28:25 INFO - 'name': 'check_screen_resolution'},), 05:28:25 INFO - 'run_cmd_checks_enabled': True, 05:28:25 INFO - 'sps_profile': False, 05:28:25 INFO - 'sps_profile_interval': 0, 05:28:25 INFO - 'suite': 'dromaeojs', 05:28:25 INFO - 'system_bits': '32', 05:28:25 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:28:25 INFO - 'title': 't-yosemite-r7-0011', 05:28:25 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:28:25 INFO - 'use_talos_json': True, 05:28:25 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:28:25 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:28:25 INFO - 'work_dir': 'build'} 05:28:25 INFO - [mozharness: 2016-11-23 13:28:25.161165Z] Running clobber step. 05:28:25 INFO - Running pre-action listener: _resource_record_pre_action 05:28:25 INFO - Running main action method: clobber 05:28:25 INFO - rmtree: /builds/slave/test/build 05:28:25 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:28:26 INFO - Running post-action listener: _resource_record_post_action 05:28:26 INFO - [mozharness: 2016-11-23 13:28:26.805248Z] Finished clobber step (success) 05:28:26 INFO - [mozharness: 2016-11-23 13:28:26.805402Z] Running read-buildbot-config step. 05:28:26 INFO - Running pre-action listener: _resource_record_pre_action 05:28:26 INFO - Running main action method: read_buildbot_config 05:28:26 INFO - Using buildbot properties: 05:28:26 INFO - { 05:28:26 INFO - "project": "", 05:28:26 INFO - "product": "firefox", 05:28:26 INFO - "script_repo_revision": "production", 05:28:26 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 05:28:26 INFO - "repository": "", 05:28:26 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 05:28:26 INFO - "buildid": "20161123030208", 05:28:26 INFO - "stage_platform": "macosx64", 05:28:26 INFO - "basedir": "/builds/slave/test", 05:28:26 INFO - "nightly_build": "True", 05:28:26 INFO - "buildnumber": 2, 05:28:26 INFO - "slavename": "t-yosemite-r7-0011", 05:28:26 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 05:28:26 INFO - "platform": "yosemite_r7", 05:28:26 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 05:28:26 INFO - "branch": "mozilla-central", 05:28:26 INFO - "repo_path": "mozilla-central", 05:28:26 INFO - "revision": "0ddfec7126ec503b54df9c4b7c3b988906f6c882", 05:28:26 INFO - "pgo_build": "False", 05:28:26 INFO - "builduid": "f4871a9f0cf64f1e8d578ca886745a14", 05:28:26 INFO - "slavebuilddir": "test" 05:28:26 INFO - } 05:28:26 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:26 INFO - Running post-action listener: _resource_record_post_action 05:28:26 INFO - [mozharness: 2016-11-23 13:28:26.808901Z] Finished read-buildbot-config step (success) 05:28:26 INFO - [mozharness: 2016-11-23 13:28:26.809014Z] Running download-and-extract step. 05:28:26 INFO - Running pre-action listener: _resource_record_pre_action 05:28:26 INFO - Running main action method: download_and_extract 05:28:26 INFO - mkdir: /builds/slave/test/build/tests 05:28:26 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:26 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:26 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:26 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:28 INFO - Downloaded 1199 bytes. 05:28:28 INFO - Reading from file /builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json 05:28:28 INFO - Using the following test package requirements: 05:28:28 INFO - {u'common': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 05:28:28 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 05:28:28 INFO - u'firefox-53.0a1.en-US.mac.cppunittest.tests.zip'], 05:28:28 INFO - u'gtest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 05:28:28 INFO - u'firefox-53.0a1.en-US.mac.gtest.tests.zip'], 05:28:28 INFO - u'jittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 05:28:28 INFO - u'jsshell-mac.zip'], 05:28:28 INFO - u'mochitest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 05:28:28 INFO - u'firefox-53.0a1.en-US.mac.mochitest.tests.zip'], 05:28:28 INFO - u'mozbase': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 05:28:28 INFO - u'reftest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 05:28:28 INFO - u'firefox-53.0a1.en-US.mac.reftest.tests.zip'], 05:28:28 INFO - u'talos': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 05:28:28 INFO - u'firefox-53.0a1.en-US.mac.talos.tests.zip'], 05:28:28 INFO - u'web-platform': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 05:28:28 INFO - u'firefox-53.0a1.en-US.mac.web-platform.tests.zip'], 05:28:28 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 05:28:28 INFO - u'firefox-53.0a1.en-US.mac.xpcshell.tests.zip']} 05:28:28 INFO - Downloading packages: [u'firefox-53.0a1.en-US.mac.common.tests.zip'] for test suite category: common 05:28:28 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:28 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:28 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:28 INFO - Http code: 200 05:28:28 INFO - via: 1.1 7f9e159e8ac7593f5d3d995abc6d2db3.cloudfront.net (CloudFront) 05:28:28 INFO - x-amz-cf-id: rd1C9YE5bjtRfMTlhwAHQUKCoeRAcauwmN6sik4ti6hdFc-g0j2AhQ== 05:28:28 INFO - x-amz-version-id: vcWn6PjYNn3AhzJJD8CRnsUW8VCIwjU4 05:28:29 INFO - Expected file size: 16267236 05:28:29 INFO - Obtained file size: 16267236 05:28:35 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:35 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:35 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:35 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:36 INFO - Http code: 200 05:28:36 INFO - via: 1.1 441811a054e8d055b893175754efd0c3.cloudfront.net (CloudFront) 05:28:36 INFO - x-amz-cf-id: PoGGKea_kXiflJtxb_WTBdw6BeM7wm_eeMtAYu2BV--LPRG_W-sEQA== 05:28:36 INFO - x-amz-version-id: vcWn6PjYNn3AhzJJD8CRnsUW8VCIwjU4 05:28:36 INFO - Expected file size: 16267236 05:28:36 INFO - Obtained file size: 16267236 05:28:43 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:43 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:43 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:43 INFO - Http code: 200 05:28:43 INFO - via: 1.1 b9a8ce441329f20cea895e08f19f1ec8.cloudfront.net (CloudFront) 05:28:43 INFO - x-amz-cf-id: 9irYr53-OaC1Q4MWU4gKp1Cd_zULMVp_iutwxb2DvEccDuLEJVQ_vQ== 05:28:43 INFO - x-amz-version-id: z3q583wKmwQ04QNBkHK66C5xH2nA4JrI 05:28:44 INFO - Expected file size: 14409506 05:28:44 INFO - Obtained file size: 14409506 05:28:44 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:44 INFO - trying https://queue.taskcluster.net/v1/task/XV6MW-jpSf2qB-jdeZep1A/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 05:28:44 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:44 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:47 INFO - Downloaded 107673113 bytes. 05:28:47 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:47 INFO - mkdir: /builds/slave/test/properties 05:28:47 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:28:47 INFO - Writing to file /builds/slave/test/properties/build_url 05:28:47 INFO - Contents: 05:28:47 INFO - build_url:https://queue.taskcluster.net/v1/task/XV6MW-jpSf2qB-jdeZep1A/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 05:28:47 INFO - Running post-action listener: _resource_record_post_action 05:28:47 INFO - Running post-action listener: set_extra_try_arguments 05:28:47 INFO - [mozharness: 2016-11-23 13:28:47.690099Z] Finished download-and-extract step (success) 05:28:47 INFO - [mozharness: 2016-11-23 13:28:47.690228Z] Running populate-webroot step. 05:28:47 INFO - Running pre-action listener: _resource_record_pre_action 05:28:47 INFO - Running main action method: populate_webroot 05:28:47 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:28:47 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:28:47 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:28:47 INFO - '--tppagecycles', 05:28:47 INFO - '7'], 05:28:47 INFO - 'tests': ['tsvgm']}}, 05:28:47 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 05:28:47 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:28:47 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:28:47 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 05:28:47 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:28:47 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:28:47 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 05:28:47 INFO - 'talos_options': ['--disable-e10s'], 05:28:47 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:28:47 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 05:28:47 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:28:47 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 05:28:47 INFO - 'talos_options': ['--disable-e10s'], 05:28:47 INFO - 'tests': ['damp', 'tps']}, 05:28:47 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 05:28:47 INFO - 'tests': ['damp', 'tps']}, 05:28:47 INFO - 'g3': {'talos_options': ['--disable-e10s'], 05:28:47 INFO - 'tests': ['dromaeo_dom']}, 05:28:47 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:28:47 INFO - 'g4': {'talos_options': ['--disable-e10s'], 05:28:47 INFO - 'tests': ['basic_compositor_video']}, 05:28:47 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 05:28:47 INFO - 'other': {'talos_options': ['--disable-e10s'], 05:28:47 INFO - 'tests': ['a11yr', 05:28:47 INFO - 'ts_paint', 05:28:47 INFO - 'tpaint', 05:28:47 INFO - 'sessionrestore', 05:28:47 INFO - 'sessionrestore_no_auto_restore', 05:28:47 INFO - 'tabpaint']}, 05:28:47 INFO - 'other-e10s': {'tests': ['a11yr', 05:28:47 INFO - 'ts_paint', 05:28:47 INFO - 'tpaint', 05:28:47 INFO - 'sessionrestore', 05:28:47 INFO - 'sessionrestore_no_auto_restore', 05:28:47 INFO - 'tabpaint']}, 05:28:47 INFO - 'other-e10s_l64': {'tests': ['a11yr', 05:28:47 INFO - 'ts_paint', 05:28:47 INFO - 'tpaint', 05:28:47 INFO - 'sessionrestore', 05:28:47 INFO - 'sessionrestore_no_auto_restore', 05:28:47 INFO - 'tabpaint']}, 05:28:47 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 05:28:47 INFO - 'ts_paint', 05:28:47 INFO - 'tpaint', 05:28:47 INFO - 'sessionrestore', 05:28:47 INFO - 'sessionrestore_no_auto_restore', 05:28:47 INFO - 'tabpaint']}, 05:28:47 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 05:28:47 INFO - 'tests': ['a11yr', 05:28:47 INFO - 'ts_paint', 05:28:47 INFO - 'tpaint', 05:28:47 INFO - 'sessionrestore', 05:28:47 INFO - 'sessionrestore_no_auto_restore', 05:28:47 INFO - 'tabpaint']}, 05:28:47 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 05:28:47 INFO - 'tests': ['a11yr', 05:28:47 INFO - 'ts_paint', 05:28:47 INFO - 'tpaint', 05:28:47 INFO - 'sessionrestore', 05:28:47 INFO - 'sessionrestore_no_auto_restore', 05:28:47 INFO - 'tabpaint']}, 05:28:47 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 05:28:47 INFO - 'tests': ['tsvgx', 05:28:47 INFO - 'tsvgr_opacity', 05:28:47 INFO - 'tart', 05:28:47 INFO - 'tscrollx', 05:28:47 INFO - 'cart']}, 05:28:47 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:28:47 INFO - 'tsvgr_opacity', 05:28:47 INFO - 'tart', 05:28:47 INFO - 'tscrollx', 05:28:47 INFO - 'cart']}, 05:28:47 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 05:28:47 INFO - 'talos_options': ['--disable-e10s'], 05:28:47 INFO - 'tests': ['tp5o']}, 05:28:47 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 05:28:47 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 05:28:47 INFO - 'talos_options': ['--disable-e10s', 05:28:47 INFO - '--xperf_path', 05:28:47 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:28:47 INFO - 'tests': ['tp5n']}, 05:28:47 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 05:28:47 INFO - 'talos_options': ['--xperf_path', 05:28:47 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:28:47 INFO - 'tests': ['tp5n']}}, 05:28:47 INFO - 'talos.zip': {'path': '', 05:28:47 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:28:47 INFO - Running post-action listener: _resource_record_post_action 05:28:47 INFO - [mozharness: 2016-11-23 13:28:47.708141Z] Finished populate-webroot step (success) 05:28:47 INFO - [mozharness: 2016-11-23 13:28:47.708234Z] Running create-virtualenv step. 05:28:47 INFO - Running pre-action listener: _resource_record_pre_action 05:28:47 INFO - Running main action method: create_virtualenv 05:28:47 INFO - Creating virtualenv /builds/slave/test/build/venv 05:28:47 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:47 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:28:47 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:28:47 INFO - Using real prefix '/tools/python27' 05:28:47 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:28:48 INFO - Installing distribute.............................................................................................................................................................................................done. 05:28:51 INFO - Installing pip.................done. 05:28:51 INFO - Return code: 0 05:28:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 05:28:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 05:28:51 INFO - Reading from file tmpfile_stdout 05:28:51 INFO - Output received: 05:28:51 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:51 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:28:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:28:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:28:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:28:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:28:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:28:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:28:51 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x101be73f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101b5d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc753551250>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1019fdd30>, '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 0x101864db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1019f5b20>, '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.k6igA4CxJG/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.j4FVZuPvZN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/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:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 05:28:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 05:28:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.k6igA4CxJG/Render', 05:28:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:28:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:28:51 INFO - 'HOME': '/Users/cltbld', 05:28:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:28:51 INFO - 'LOGNAME': 'cltbld', 05:28:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:28:51 INFO - 'MOZ_NO_REMOTE': '1', 05:28:51 INFO - 'NO_EM_RESTART': '1', 05:28:51 INFO - 'PAGER': '/bin/cat', 05:28:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:28:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:28:51 INFO - 'PWD': '/builds/slave/test', 05:28:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:28:51 INFO - 'SHELL': '/bin/bash', 05:28:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.j4FVZuPvZN/Listeners', 05:28:51 INFO - 'TMPDIR': '/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/', 05:28:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:28:51 INFO - 'USER': 'cltbld', 05:28:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:28:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:28:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:28:51 INFO - 'XPC_FLAGS': '0x0', 05:28:51 INFO - 'XPC_SERVICE_NAME': '0', 05:28:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:28:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:28:51 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:51 INFO - Cleaning up... 05:28:51 INFO - Return code: 0 05:28:51 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 05:28:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:28:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:28:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:28:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:28:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:28:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:28:51 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x101be73f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101b5d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc753551250>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1019fdd30>, '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 0x101864db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1019f5b20>, '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.k6igA4CxJG/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.j4FVZuPvZN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/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:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 05:28:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 05:28:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.k6igA4CxJG/Render', 05:28:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:28:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:28:51 INFO - 'HOME': '/Users/cltbld', 05:28:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:28:51 INFO - 'LOGNAME': 'cltbld', 05:28:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:28:51 INFO - 'MOZ_NO_REMOTE': '1', 05:28:51 INFO - 'NO_EM_RESTART': '1', 05:28:51 INFO - 'PAGER': '/bin/cat', 05:28:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:28:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:28:51 INFO - 'PWD': '/builds/slave/test', 05:28:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:28:51 INFO - 'SHELL': '/bin/bash', 05:28:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.j4FVZuPvZN/Listeners', 05:28:51 INFO - 'TMPDIR': '/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/', 05:28:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:28:51 INFO - 'USER': 'cltbld', 05:28:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:28:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:28:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:28:51 INFO - 'XPC_FLAGS': '0x0', 05:28:51 INFO - 'XPC_SERVICE_NAME': '0', 05:28:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:28:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:28:52 INFO - Downloading/unpacking psutil>=3.1.1 05:28:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:28:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:28:52 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:28:52 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:28:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:28:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:28:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:28:55 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:28:55 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:28:55 INFO - Installing collected packages: psutil 05:28:55 INFO - Running setup.py install for psutil 05:28:55 INFO - building 'psutil._psutil_osx' extension 05:28:55 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:56 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:56 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:56 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:56 INFO - building 'psutil._psutil_posix' extension 05:28:56 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:56 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 05:28:56 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 05:28:56 INFO - ^ 05:28:56 INFO - 1 warning generated. 05:28:56 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:56 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:28:56 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:28:56 INFO - Successfully installed psutil 05:28:56 INFO - Cleaning up... 05:28:56 INFO - Return code: 0 05:28:56 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 05:28:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:28:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:28:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:28:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:28:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:28:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:28:56 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 0x101be73f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101b5d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc753551250>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1019fdd30>, '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 0x101864db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1019f5b20>, '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.k6igA4CxJG/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.j4FVZuPvZN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/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:56 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:56 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:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.k6igA4CxJG/Render', 05:28:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:28:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:28:56 INFO - 'HOME': '/Users/cltbld', 05:28:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:28:56 INFO - 'LOGNAME': 'cltbld', 05:28:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:28:56 INFO - 'MOZ_NO_REMOTE': '1', 05:28:56 INFO - 'NO_EM_RESTART': '1', 05:28:56 INFO - 'PAGER': '/bin/cat', 05:28:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:28:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:28:56 INFO - 'PWD': '/builds/slave/test', 05:28:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:28:56 INFO - 'SHELL': '/bin/bash', 05:28:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.j4FVZuPvZN/Listeners', 05:28:56 INFO - 'TMPDIR': '/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/', 05:28:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:28:56 INFO - 'USER': 'cltbld', 05:28:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:28:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:28:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:28:56 INFO - 'XPC_FLAGS': '0x0', 05:28:56 INFO - 'XPC_SERVICE_NAME': '0', 05:28:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:28:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:28:56 INFO - Downloading/unpacking mozsystemmonitor==0.3 05:28:56 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:56 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:56 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:56 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:56 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:56 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:59 INFO - Downloading mozsystemmonitor-0.3.tar.gz 05:28:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:28:59 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:59 INFO - Installing collected packages: mozsystemmonitor 05:28:59 INFO - Running setup.py install for mozsystemmonitor 05:28:59 INFO - Successfully installed mozsystemmonitor 05:28:59 INFO - Cleaning up... 05:28:59 INFO - Return code: 0 05:28:59 INFO - Installing jsonschema==2.5.1 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==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x101be73f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101b5d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc753551250>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1019fdd30>, '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 0x101864db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1019f5b20>, '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.k6igA4CxJG/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.j4FVZuPvZN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/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==2.5.1'] 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==2.5.1 05:28:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.k6igA4CxJG/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.j4FVZuPvZN/Listeners', 05:28:59 INFO - 'TMPDIR': '/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/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 - Downloading/unpacking jsonschema==2.5.1 05:28:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:28:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:28:59 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:28:59 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:28:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:28:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:29:02 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:29:02 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 05:29:02 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:29:02 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:29:02 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:29:02 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:29:02 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:29:02 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:29:02 INFO - Downloading functools32-3.2.3-2.tar.gz 05:29:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:29:02 INFO - warning: no files found matching '*.txt' 05:29:02 INFO - no previously-included directories found matching 'build' 05:29:02 INFO - no previously-included directories found matching 'dist' 05:29:02 INFO - no previously-included directories found matching '.git*' 05:29:02 INFO - Installing collected packages: jsonschema, functools32 05:29:03 INFO - Running setup.py install for functools32 05:29:03 INFO - warning: no files found matching '*.txt' 05:29:03 INFO - no previously-included directories found matching 'build' 05:29:03 INFO - no previously-included directories found matching 'dist' 05:29:03 INFO - no previously-included directories found matching '.git*' 05:29:03 INFO - Successfully installed jsonschema functools32 05:29:03 INFO - Cleaning up... 05:29:03 INFO - Return code: 0 05:29:03 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 05:29:03 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:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:29:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:29:03 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:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:29:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:29:03 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 0x101be73f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101b5d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc753551250>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1019fdd30>, '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 0x101864db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1019f5b20>, '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.k6igA4CxJG/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.j4FVZuPvZN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/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:29:03 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:29:03 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:29:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.k6igA4CxJG/Render', 05:29:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:29:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:29:03 INFO - 'HOME': '/Users/cltbld', 05:29:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:29:03 INFO - 'LOGNAME': 'cltbld', 05:29:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:29:03 INFO - 'MOZ_NO_REMOTE': '1', 05:29:03 INFO - 'NO_EM_RESTART': '1', 05:29:03 INFO - 'PAGER': '/bin/cat', 05:29:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:29:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:29:03 INFO - 'PWD': '/builds/slave/test', 05:29:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:29:03 INFO - 'SHELL': '/bin/bash', 05:29:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.j4FVZuPvZN/Listeners', 05:29:03 INFO - 'TMPDIR': '/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/', 05:29:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:29:03 INFO - 'USER': 'cltbld', 05:29:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:29:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:29:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:29:03 INFO - 'XPC_FLAGS': '0x0', 05:29:03 INFO - 'XPC_SERVICE_NAME': '0', 05:29:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:29:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:29:03 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 05:29:03 INFO - Cleaning up... 05:29:03 INFO - Return code: 0 05:29:03 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:29:03 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:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:29:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:29:03 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:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:29:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:29:03 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 0x101be73f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101b5d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc753551250>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1019fdd30>, '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 0x101864db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1019f5b20>, '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.k6igA4CxJG/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.j4FVZuPvZN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/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:29:03 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:29:03 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:29:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.k6igA4CxJG/Render', 05:29:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:29:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:29:03 INFO - 'HOME': '/Users/cltbld', 05:29:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:29:03 INFO - 'LOGNAME': 'cltbld', 05:29:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:29:03 INFO - 'MOZ_NO_REMOTE': '1', 05:29:03 INFO - 'NO_EM_RESTART': '1', 05:29:03 INFO - 'PAGER': '/bin/cat', 05:29:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:29:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:29:03 INFO - 'PWD': '/builds/slave/test', 05:29:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:29:03 INFO - 'SHELL': '/bin/bash', 05:29:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.j4FVZuPvZN/Listeners', 05:29:03 INFO - 'TMPDIR': '/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/', 05:29:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:29:03 INFO - 'USER': 'cltbld', 05:29:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:29:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:29:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:29:03 INFO - 'XPC_FLAGS': '0x0', 05:29:03 INFO - 'XPC_SERVICE_NAME': '0', 05:29:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:29:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:29:03 INFO - Downloading/unpacking blobuploader==1.2.4 05:29:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:29:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:29:03 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:29:03 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:29:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:29:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:29:06 INFO - Downloading blobuploader-1.2.4.tar.gz 05:29:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:29:06 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:29:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:29:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:29:06 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:29:06 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:29:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:29:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:29:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:29:07 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:29:07 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:29:07 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:29:07 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:29:07 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:29:07 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:29:07 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:29:07 INFO - Downloading docopt-0.6.1.tar.gz 05:29:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:29:07 INFO - Installing collected packages: blobuploader, requests, docopt 05:29:07 INFO - Running setup.py install for blobuploader 05:29:07 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:29:07 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:29:07 INFO - Running setup.py install for requests 05:29:08 INFO - Running setup.py install for docopt 05:29:08 INFO - Successfully installed blobuploader requests docopt 05:29:08 INFO - Cleaning up... 05:29:08 INFO - Return code: 0 05:29:08 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:29:08 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:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:29:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:29:08 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:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:29:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:29:08 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 0x101be73f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101b5d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc753551250>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1019fdd30>, '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 0x101864db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1019f5b20>, '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.k6igA4CxJG/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.j4FVZuPvZN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/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:29:08 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:29:08 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:29:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.k6igA4CxJG/Render', 05:29:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:29:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:29:08 INFO - 'HOME': '/Users/cltbld', 05:29:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:29:08 INFO - 'LOGNAME': 'cltbld', 05:29:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:29:08 INFO - 'MOZ_NO_REMOTE': '1', 05:29:08 INFO - 'NO_EM_RESTART': '1', 05:29:08 INFO - 'PAGER': '/bin/cat', 05:29:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:29:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:29:08 INFO - 'PWD': '/builds/slave/test', 05:29:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:29:08 INFO - 'SHELL': '/bin/bash', 05:29:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.j4FVZuPvZN/Listeners', 05:29:08 INFO - 'TMPDIR': '/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/', 05:29:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:29:08 INFO - 'USER': 'cltbld', 05:29:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:29:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:29:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:29:08 INFO - 'XPC_FLAGS': '0x0', 05:29:08 INFO - 'XPC_SERVICE_NAME': '0', 05:29:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:29:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:29:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:29:08 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-Cm0dM7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:29:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:29:08 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-zp59tv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:29:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:29:09 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-h2Irpl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:29:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:29:09 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-J8PFAj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:29:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:29:09 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-ecasX5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:29:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:29:09 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-_xbwzz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:29:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:29:09 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-gGIZ7x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:29:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:29:09 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-ZT8tnz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:29:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:29:09 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-UgjUmM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:29:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:29:09 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-ACi9FW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:29:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:29:09 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-20YqY6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:29:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:29:09 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-SmgQO9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:29:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:29:10 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-AqOv7s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:29:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:29:10 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-0_01Po-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:29:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:29:10 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-SU5f7E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:29:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:29:10 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-gUR7Qa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:29:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:29:10 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-1DwVyF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:29:10 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:29:10 INFO - Running setup.py install for manifestparser 05:29:10 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:29:10 INFO - Running setup.py install for mozcrash 05:29:10 INFO - Running setup.py install for mozdebug 05:29:10 INFO - Running setup.py install for mozdevice 05:29:11 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 05:29:11 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:29:11 INFO - Running setup.py install for mozfile 05:29:11 INFO - Running setup.py install for mozhttpd 05:29:11 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:29:11 INFO - Running setup.py install for mozinfo 05:29:11 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:29:11 INFO - Running setup.py install for mozInstall 05:29:11 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:29:11 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:29:11 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:29:11 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:29:11 INFO - Running setup.py install for mozleak 05:29:11 INFO - Running setup.py install for mozlog 05:29:12 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:29:12 INFO - Running setup.py install for moznetwork 05:29:12 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:29:12 INFO - Running setup.py install for mozprocess 05:29:12 INFO - Running setup.py install for mozprofile 05:29:12 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:29:12 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:29:12 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:29:12 INFO - Running setup.py install for mozrunner 05:29:12 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:29:12 INFO - Running setup.py install for mozscreenshot 05:29:12 INFO - Running setup.py install for moztest 05:29:13 INFO - Running setup.py install for mozversion 05:29:13 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:29:13 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:29:13 INFO - Cleaning up... 05:29:13 INFO - Return code: 0 05:29:13 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:29:13 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:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:29:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:29:13 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:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:29:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:29:13 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 0x101be73f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101b5d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc753551250>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1019fdd30>, '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 0x101864db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1019f5b20>, '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.k6igA4CxJG/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.j4FVZuPvZN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/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:29:13 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:29:13 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:29:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.k6igA4CxJG/Render', 05:29:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:29:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:29:13 INFO - 'HOME': '/Users/cltbld', 05:29:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:29:13 INFO - 'LOGNAME': 'cltbld', 05:29:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:29:13 INFO - 'MOZ_NO_REMOTE': '1', 05:29:13 INFO - 'NO_EM_RESTART': '1', 05:29:13 INFO - 'PAGER': '/bin/cat', 05:29:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:29:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:29:13 INFO - 'PWD': '/builds/slave/test', 05:29:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:29:13 INFO - 'SHELL': '/bin/bash', 05:29:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.j4FVZuPvZN/Listeners', 05:29:13 INFO - 'TMPDIR': '/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/', 05:29:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:29:13 INFO - 'USER': 'cltbld', 05:29:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:29:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:29:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:29:13 INFO - 'XPC_FLAGS': '0x0', 05:29:13 INFO - 'XPC_SERVICE_NAME': '0', 05:29:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:29:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:29:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:29:13 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-G4akVI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:29:13 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:29:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:29:13 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-FzqV0p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:29:13 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:29:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:29:13 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-J12nbR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:29:13 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:29:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:29:13 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-gQRvAx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:29:13 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:29:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:29:13 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-LUhF9i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:29:13 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:29:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:29:13 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-xagUL5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:29:14 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:29:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:29:14 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-tJguSC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:29:14 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:29:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:29:14 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-yUadAm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:29:14 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:29:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:29:14 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-v3s_sq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:29:14 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:29:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:29:14 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-H8YsA1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:29:14 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:29:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:29:14 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-cOgt3P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:29:14 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:29:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:29:14 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-eBKfVD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:29:14 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:29:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:29:14 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-E5gjOj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:29:14 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:29:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:29:14 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-RV1l_l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:29:14 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:29:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:29:14 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-brUer1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:29:15 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:29:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:29:15 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-7RWgeX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:29:15 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:29:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:29:15 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-ZhBGUP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:29:15 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:29:15 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:29:15 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:29:15 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:29:15 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:29:15 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:29:15 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:29:15 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:29:15 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:29:15 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:29:15 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:29:15 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:29:15 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:29:18 INFO - Downloading blessings-1.6.tar.gz 05:29:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:29:18 INFO - Installing collected packages: blessings 05:29:18 INFO - Running setup.py install for blessings 05:29:18 INFO - Successfully installed blessings 05:29:18 INFO - Cleaning up... 05:29:18 INFO - Return code: 0 05:29:18 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:29:18 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:29:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:29:18 INFO - Reading from file tmpfile_stdout 05:29:18 INFO - Current package versions: 05:29:18 INFO - blessings == 1.6 05:29:18 INFO - blobuploader == 1.2.4 05:29:18 INFO - docopt == 0.6.1 05:29:18 INFO - functools32 == 3.2.3-2 05:29:18 INFO - jsonschema == 2.5.1 05:29:18 INFO - manifestparser == 1.1 05:29:18 INFO - mozInstall == 1.12 05:29:18 INFO - mozcrash == 1.0 05:29:18 INFO - mozdebug == 0.1 05:29:18 INFO - mozdevice == 0.49 05:29:18 INFO - mozfile == 1.2 05:29:18 INFO - mozhttpd == 0.7 05:29:18 INFO - mozinfo == 0.9 05:29:18 INFO - mozleak == 0.1 05:29:18 INFO - mozlog == 3.4 05:29:18 INFO - moznetwork == 0.27 05:29:18 INFO - mozprocess == 0.23 05:29:18 INFO - mozprofile == 0.28 05:29:18 INFO - mozrunner == 6.13 05:29:18 INFO - mozscreenshot == 0.1 05:29:18 INFO - mozsystemmonitor == 0.3 05:29:18 INFO - moztest == 0.7 05:29:18 INFO - mozversion == 1.4 05:29:18 INFO - psutil == 3.1.1 05:29:18 INFO - requests == 1.2.3 05:29:18 INFO - wsgiref == 0.1.2 05:29:18 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:29:18 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:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:29:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:29:19 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:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:29:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:29:19 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 0x101be73f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101b5d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc753551250>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1019fdd30>, '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 0x101864db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1019f5b20>, '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.k6igA4CxJG/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.j4FVZuPvZN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/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:29:19 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:29:19 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:29:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.k6igA4CxJG/Render', 05:29:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:29:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:29:19 INFO - 'HOME': '/Users/cltbld', 05:29:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:29:19 INFO - 'LOGNAME': 'cltbld', 05:29:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:29:19 INFO - 'MOZ_NO_REMOTE': '1', 05:29:19 INFO - 'NO_EM_RESTART': '1', 05:29:19 INFO - 'PAGER': '/bin/cat', 05:29:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:29:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:29:19 INFO - 'PWD': '/builds/slave/test', 05:29:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:29:19 INFO - 'SHELL': '/bin/bash', 05:29:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.j4FVZuPvZN/Listeners', 05:29:19 INFO - 'TMPDIR': '/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/', 05:29:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:29:19 INFO - 'USER': 'cltbld', 05:29:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:29:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:29:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:29:19 INFO - 'XPC_FLAGS': '0x0', 05:29:19 INFO - 'XPC_SERVICE_NAME': '0', 05:29:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:29:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:29:19 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:29:19 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:29:19 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:29:19 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:29:19 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:29:19 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:29:19 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:29:19 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:29:19 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:29:19 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:29:19 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:29:19 INFO - Cleaning up... 05:29:19 INFO - Return code: 0 05:29:19 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:29:19 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:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:29:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:29:19 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:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:29:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:29:19 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 0x101be73f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101b5d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc753551250>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1019fdd30>, '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 0x101864db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1019f5b20>, '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.k6igA4CxJG/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.j4FVZuPvZN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/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:29:19 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:29:19 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:29:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.k6igA4CxJG/Render', 05:29:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:29:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:29:19 INFO - 'HOME': '/Users/cltbld', 05:29:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:29:19 INFO - 'LOGNAME': 'cltbld', 05:29:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:29:19 INFO - 'MOZ_NO_REMOTE': '1', 05:29:19 INFO - 'NO_EM_RESTART': '1', 05:29:19 INFO - 'PAGER': '/bin/cat', 05:29:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:29:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:29:19 INFO - 'PWD': '/builds/slave/test', 05:29:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:29:19 INFO - 'SHELL': '/bin/bash', 05:29:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.j4FVZuPvZN/Listeners', 05:29:19 INFO - 'TMPDIR': '/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/', 05:29:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:29:19 INFO - 'USER': 'cltbld', 05:29:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:29:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:29:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:29:19 INFO - 'XPC_FLAGS': '0x0', 05:29:19 INFO - 'XPC_SERVICE_NAME': '0', 05:29:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:29:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:29:19 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 05:29:19 INFO - Cleaning up... 05:29:19 INFO - Return code: 0 05:29:19 INFO - Running post-action listener: _resource_record_post_action 05:29:19 INFO - Running post-action listener: _start_resource_monitoring 05:29:19 INFO - Starting resource monitoring. 05:29:19 INFO - [mozharness: 2016-11-23 13:29:19.983398Z] Finished create-virtualenv step (success) 05:29:19 INFO - [mozharness: 2016-11-23 13:29:19.984200Z] Running install step. 05:29:19 INFO - Running pre-action listener: _resource_record_pre_action 05:29:19 INFO - Running main action method: install 05:29:19 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:29:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:29:20 INFO - Reading from file tmpfile_stdout 05:29:20 INFO - Detecting whether we're running mozinstall >=1.0... 05:29:20 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:29:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:29:20 INFO - Reading from file tmpfile_stdout 05:29:20 INFO - Output received: 05:29:20 INFO - Usage: mozinstall [options] installer 05:29:20 INFO - Options: 05:29:20 INFO - -h, --help show this help message and exit 05:29:20 INFO - -d DEST, --destination=DEST 05:29:20 INFO - Directory to install application into. [default: 05:29:20 INFO - "/builds/slave/test"] 05:29:20 INFO - --app=APP Application being installed. [default: firefox] 05:29:20 INFO - mkdir: /builds/slave/test/build/application 05:29:20 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:20 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:53 INFO - Reading from file tmpfile_stdout 05:29:53 INFO - Output received: 05:29:53 INFO - /builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox 05:29:53 INFO - Running post-action listener: _resource_record_post_action 05:29:53 INFO - [mozharness: 2016-11-23 13:29:53.005001Z] Finished install step (success) 05:29:53 INFO - [mozharness: 2016-11-23 13:29:53.005163Z] Running run-tests step. 05:29:53 INFO - Running pre-action listener: _resource_record_pre_action 05:29:53 INFO - Running main action method: run_tests 05:29:53 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:29:53 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:29:53 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:29:53 INFO - 2016-11-23 05:29:53.019 screenresolution[1890:17041] starting screenresolution argv=screenresolution get 05:29:53 INFO - 2016-11-23 05:29:53.022 screenresolution[1890:17041] Display 0: 1600x1200x32@50 05:29:53 INFO - 2016-11-23 05:29:53.028 screenresolution[1891:17044] starting screenresolution argv=screenresolution list 05:29:53 INFO - Available Modes on Display 0 05:29:53 INFO - 1600x1200x32@50 1152x870x32@75 1280x1024x32@75 05:29:53 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:29:53 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:29:53 INFO - 640x480x32@60 1280x1024x32@60 1600x1200x32@60 05:29:53 INFO - 1280x960x32@60 1280x960x32@75 1344x1008x32@50 05:29:53 INFO - Graphics/Displays: 05:29:53 INFO - Intel Iris: 05:29:53 INFO - Chipset Model: Intel Iris 05:29:53 INFO - Type: GPU 05:29:53 INFO - Bus: Built-In 05:29:53 INFO - VRAM (Dynamic, Max): 1536 MB 05:29:53 INFO - Vendor: Intel (0x8086) 05:29:53 INFO - Device ID: 0x0a2e 05:29:53 INFO - Revision ID: 0x0009 05:29:53 INFO - Displays: 05:29:53 INFO - Display: 05:29:53 INFO - Resolution: 1600 x 1200 @ 50 Hz 05:29:53 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:29:53 INFO - Display Serial Number: 1600x1200 50 05:29:53 INFO - Main Display: Yes 05:29:53 INFO - Mirror: Off 05:29:53 INFO - Online: Yes 05:29:53 INFO - Rotation: Supported 05:29:53 INFO - Return code: 0 05:29:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:29:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:29:53 INFO - Python 2.7.3 05:29:53 INFO - Return code: 0 05:29:53 INFO - grabbing minidump binary from tooltool 05:29:53 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:53 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 0x1019fdd30>, '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 0x101864db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1019f5b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:29:53 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:53 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:53 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:53 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:29:53 INFO - Return code: 0 05:29:53 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 05:29:53 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:29:53 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:29:53 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 05:29:53 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:29:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/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-0011', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 05:29:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/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-0011 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 05:29:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.k6igA4CxJG/Render', 05:29:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:29:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:29:53 INFO - 'HOME': '/Users/cltbld', 05:29:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:29:53 INFO - 'LOGNAME': 'cltbld', 05:29:53 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:29:53 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 05:29:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:29:53 INFO - 'MOZ_NO_REMOTE': '1', 05:29:53 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:29:53 INFO - 'NO_EM_RESTART': '1', 05:29:53 INFO - 'PAGER': '/bin/cat', 05:29:53 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:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:29:53 INFO - 'PWD': '/builds/slave/test', 05:29:53 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:29:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:29:53 INFO - 'SHELL': '/bin/bash', 05:29:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.j4FVZuPvZN/Listeners', 05:29:53 INFO - 'TMPDIR': '/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/', 05:29:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:29:53 INFO - 'USER': 'cltbld', 05:29:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:29:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:29:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:29:53 INFO - 'XPC_FLAGS': '0x0', 05:29:53 INFO - 'XPC_SERVICE_NAME': '0', 05:29:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:29:53 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/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-0011', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 05:29:53 INFO - mozversion application_buildid: 20161123030208 05:29:53 INFO - mozversion application_changeset: 0ddfec7126ec503b54df9c4b7c3b988906f6c882 05:29:53 INFO - mozversion application_display_name: Nightly 05:29:53 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:29:53 INFO - mozversion application_name: Firefox 05:29:53 INFO - mozversion application_remotingname: firefox 05:29:53 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 05:29:53 INFO - mozversion application_vendor: Mozilla 05:29:53 INFO - mozversion application_version: 53.0a1 05:29:53 INFO - mozversion platform_buildid: 20161123030208 05:29:53 INFO - mozversion platform_changeset: 0ddfec7126ec503b54df9c4b7c3b988906f6c882 05:29:53 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 05:29:53 INFO - mozversion platform_version: 53.0a1 05:29:53 INFO - using testdate: 1479907793 05:29:53 INFO - actual date: 1479907793 05:29:53 INFO - starting webserver on 'localhost:53200' 05:29:53 INFO - SUITE-START | Running 2 tests 05:29:53 INFO - TEST-START | dromaeo_css 05:29:53 INFO - operating with platform_type : mac_ 05:29:53 INFO - Initialising browser for dromaeo_css test... 05:29:53 INFO - TEST-INFO | started process 1901 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/tmppsfCno/profile http://localhost:53200/getInfo.html) 05:29:56 INFO - PROCESS | 1901 | __metrics Screen width/height:1600/1200 05:29:56 INFO - PROCESS | 1901 | colorDepth:24 05:29:56 INFO - PROCESS | 1901 | Browser inner width/height: 1024/689 05:29:56 INFO - PROCESS | 1901 | __metrics 05:29:56 INFO - PROCESS | 1901 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 05:29:56 INFO - PROCESS | 1901 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:29:57 INFO - TEST-INFO | 1901: exit 0 05:29:57 INFO - Browser initialized. 05:29:57 INFO - Running cycle 1/1 for dromaeo_css test... 05:29:57 INFO - TEST-INFO | started process 1904 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/tmppsfCno/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:30:09 INFO - PROCESS | 1904 | RSS: Main: 234369024 05:30:09 INFO - PROCESS | 1904 | 05:31:00 INFO - PROCESS | 1904 | 1479907860572 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:33:14 INFO - PROCESS | 1904 | Cycle 1(1): loaded http://localhost:53200/tests/dromaeo/cssquery-dojo.html (next: http://localhost:53200/tests/dromaeo/cssquery-ext.html) 05:33:16 INFO - PROCESS | 1904 | RSS: Main: 452542464 05:33:16 INFO - PROCESS | 1904 | 05:36:20 INFO - PROCESS | 1904 | Cycle 1(1): loaded http://localhost:53200/tests/dromaeo/cssquery-ext.html (next: http://localhost:53200/tests/dromaeo/cssquery-jquery.html) 05:36:20 INFO - PROCESS | 1904 | RSS: Main: 370315264 05:36:20 INFO - PROCESS | 1904 | 05:39:23 INFO - PROCESS | 1904 | Cycle 1(1): loaded http://localhost:53200/tests/dromaeo/cssquery-jquery.html (next: http://localhost:53200/tests/dromaeo/cssquery-mootools.html) 05:39:23 INFO - PROCESS | 1904 | RSS: Main: 347688960 05:39:23 INFO - PROCESS | 1904 | 05:42:26 INFO - PROCESS | 1904 | Cycle 1(1): loaded http://localhost:53200/tests/dromaeo/cssquery-mootools.html (next: http://localhost:53200/tests/dromaeo/cssquery-prototype.html) 05:42:26 INFO - PROCESS | 1904 | RSS: Main: 345210880 05:42:26 INFO - PROCESS | 1904 | 05:45:30 INFO - PROCESS | 1904 | Cycle 1(1): loaded http://localhost:53200/tests/dromaeo/cssquery-prototype.html (next: http://localhost:53200/tests/dromaeo/cssquery-yui.html) 05:45:30 INFO - PROCESS | 1904 | RSS: Main: 338149376 05:45:30 INFO - PROCESS | 1904 | 05:48:34 INFO - PROCESS | 1904 | Cycle 1(1): loaded http://localhost:53200/tests/dromaeo/cssquery-yui.html (next: http://localhost:53200/tests/dromaeo/cssquery-dojo.html) 05:48:34 INFO - PROCESS | 1904 | RSS: Main: 434855936 05:48:34 INFO - PROCESS | 1904 | 05:48:34 INFO - PROCESS | 1904 | __start_tp_report 05:48:34 INFO - PROCESS | 1904 | _x_x_mozilla_page_load 05:48:34 INFO - PROCESS | 1904 | _x_x_mozilla_page_load_details 05:48:34 INFO - PROCESS | 1904 | |i|pagename|runs| 05:48:34 INFO - PROCESS | 1904 | |0;dojo.html;3246.9837650811746;3266.7549933754976;3280.458724310489;3301.3067255876263;3322.734181265499;5410.540104091147;5491.560675145989;5510.201020851974;5548.140038294066;5566.137248726449;5459.099248623982;5541.722913854302;5545.140503222007;5550.361708403535;5556.972215138919;5018.146915024446;5039.722815245178;5049.697018178921;5050.772715227805;5109.131447653907;5096.05722941257;5115.4884511548735;5151.124308867483;5219.19102539107;5231.712255825928;4983.352164218646;5150.845474635767;5166.095933211692;5194.688318700871;5211.009908117446;5122.078025955345;5203.661761985459;5205.843824685266;5210.47895210476;5225.294585230983;5077.543021128079;5077.822276220315;5092.6180536459915;5117.181250999822;5140.408852981917;5458.61789674723;5512.889742205133;5513.255710479114;5532.723363831792;5534.4742249486235;5911.896254466396;5918.674472903988;5918.852028699274;5921.200637913913;5930.28836539618;4980.452150263468;6007.669578173199;6009.399060094;6024.728887200043;6040.546958978094;5534.75093620788;5548.500634942875;5550.694711790809;5559.249501317351;5561.026820306471;6114.488701380082;6129.278400901673;6133.08984587446;6144.41628045335;6159.907601385983;5763.481286684135;5786.392428795;5795.565332600072;5796.043652797325;5808.448197421239;5935.465808077293;5952.196453478725;5960.225170727865;5960.731767070492;6034.24571928509;5905.143754155709;5924.703764811742;5930.080837470113;5938.732757025944;5987.491063259672;5777.653340799566;5860.912086318709;5870.354261031283;5946.910796338058;5951.880962380815;4726.858194254178;4782.163121453732;4792.880177995578;4798.064377446434;4826.396700412449;4286.61420472159;4323.935140972888;4353.172897149595;4371.912561748811;4378.365137055109;5436.238926550286;5620.49415552594;5677.034904066319;5713.200151978726;5721.5136713379;5765.827025189251;5813.5349172066135;5917.763289468373;5925;5991.310999235144;5656.547476201977;5658.490735833795;5669.06460434032;5672.262605861294;5703.914441283384;5210.973945130326;5211.765470553834;5216.843494695166;5221.295125158131;5262.500062494057;6215.533834962305;6268.122462855295;6289.716962736779;6308.968455157695;6310.1796766420075;5995.070764031582;6029.758809647565;6080.2399700037495;6091.4822240108315;6124.234470691164;6305.187775007646;6345;6362.809115726535;6365.204349456318;6392.328805475358;5761.107028410603;5832.9708351457975;5879.794207202898;5921.526277897672;5927.614705044159;5883.35283118848;5900.586958912835;5961.254843144607;5970.641761494324;6032.90950635732;5699.2590963174525;5707.486326230659;5753.1370294456165;5758.3090029196765;5793.246877905845;5616.6068375213345;5709.200711900255;5715.314162300384;5772.7402266898935;5774.855628609318;5486.890262194814;5583.218349431002;5583.497485226187;5604.131359639263;5622.297212848394;5734.799282025109;5741.311042674739;5742.36833948257;5743.712814359349;5750.482456578928;5606.355269144101;5688.715564221855;5698.943010570007;5722.313322401338;5735.225744524436 05:48:34 INFO - PROCESS | 1904 | |1;ext.html;504.42999410665954;507.3556583139413;515.1217174717108;516.1921592707411;550.0209626864183;35821.46267805978;38128.61871381285;39330.41004384936;39599.80200098999;39745;40163.99590010251;40545.189096218055;40557.18885622293;40573.78278651645;40729.796351018274;8437.198466145706;9146.679866204668;9336.766580835465;9341.81316373672;9351.111644393772;8474.957625211866;10956.835647465254;11243.66269011931;11294;11393.088552915788;11183.44082795857;11242.887571124265;11243.943780281088;11264.324140551553;11267.549298028069;8359;8593.14068593142;10980.890191098146;11108.389038602874;11212.159088068387;10875.619353322596;11104.222704410695;11124.38815865127;11131.443427828575;11135.443227838576;38555.036124097125;38813.611863881284;38829.8351049469;38869.22261554753;38917;3271.8200498972546;3287.342531493711;3381.205416727067;3424.2466657335344;3641.5994240633772;28823.135305940854;29031.85484072587;29285.26786830325;29567.260818479495;29591.408171836658;3113.206132436237;3130.660412664373;3142.5600415941785;3164.114048066545;3236.1262459136137;453597.05216213095;472513;475606.99999999657;481136;481353.4240548741;54059.72970135124;55614.72192639092;56042;56104;56116.02143656995;54446.45553544493;54522.727386362814;54524.72737636286;54543.181852272246;54554.18168727472;13734.587962361145;14242.572722818331;14256.572302830931;14286;14321.35553900077;9909.851352229722;10157.492125393848;10176.745581360377;10863.67408977732;10886.891131088747;14911.70176596462;15263.618409539851;15265.3893844245;15460.690786184437;15593.844061559239;6378.681065946684;6432.35676432353;6436.00483850008;6482.643454609947;6508.153939987771;860.9225169734754;931.6599441203912;958.7794807194384;1014.1238708389568;1036.8652075230173;28430.857845710638;28646.997355092473;29065.564016539767;29159.271018224714;29206.7079329204;763.065245074779;915.6474757218518;946.522006386784;1067.0556557446716;1083.4311986207147;13724.588262352145;14666.8199563124;14696.853031469549;15130.848691513163;15194.936354455303;631394;634558;638062;640788;642744;54862.72568637131;54892;54924;55032.89934201293;55267.72366138144;1204.3737256626505;1242.4160644497072;1319.182107093608;1346.3941226447942;1373.6909195430949;28587.857060715396;29594.704052959194;29732.851335743184;30435.391292174474;30568.388632227674;46849.76575117103;47462.52537474581;47493;47513.52486475091;47555.52444475511;509036.90963089897;510394;515459.53621391417;517033;642981;22678.206262780725;23299.650505242094;24176.51646967086;24778.00887964461;25363.87318063398;43269.567304326556;43998;44416.7779161115;44589.777051114535;45038.874028149556;36069.819650901576;36370;36698.633013669525;37386;37555.81222093872;27619.86190069037;36380;36386.31256990743;37202;37748.245035099695;29283.85358073196;29479.85260073686;35496;35788.82105589456;36627.267454650224;30585.694143058285;30917.845410772803;32720.34559308848;32757.508637370935;32818.52316645804;24186.395340116636;24370;24459.143929962367;24761;26041.348966275993 05:48:34 INFO - PROCESS | 1904 | |2;jquery.html;315.20410962320136;352.72311235679996;369.4107897902846;373.23486851953487;403.1976367029612;14581.927090364547;14852.034617749852;15418.768718469204;19221.519462013457;20070.49823754407;18445.7233141503;18618.906905465486;18647.533811654714;19497.610047799037;19863.702044469344;5192.922106168401;5796.855078623047;5797.985352563305;5802.65184088956;5809.73856176471;4949.703017818924;5673.971630141844;5774.971125144369;5812;5820.185174075633;5736.340320863092;5736.713164341766;5747.856303592402;5752.108423194411;5757.337906140785;5464;5536.833894983137;5670.064439367499;5679.2333035040565;5691.4877661010705;4958.851234462936;4964.304997300381;4968.428630707443;4977.203647416396;4999.650024498286;23027.309180724606;24000.15999440029;24030.639540406908;24042.759572404226;24342.687444692725;2194.550117225981;2283.908643654252;2561.692596888385;2608.504384167002;2977.330100727341;14233.868548919221;14387.78418323726;14512.1292722437;14892.478763243234;15256.771148432783;1601.1513897634272;1746.3974928649595;1796.5986530506227;1840.9447716568525;1865.664180447519;90320.6451903222;112858;115028;115383.99999999916;115992.84007159904;8780.604872780741;10050.246231532517;10206.4386458744;10283.382997020053;10605.416702081306;13225.669358265928;13264;13267.203967761965;13281.468741250435;13292.069555131047;7060.717571297193;7091.3972312353;7259.382952449101;7520.811979700551;7527.209642987517;6174.691265436711;7059.964700176569;7063.57618542889;7254.600996945113;7258.891116633255;5303.655262407931;5992.64044157352;6234.470070044006;6310.274318453345;6412.807615771534;1768.6823316629193;1802.2971041293906;1807.5752198233404;1815;1822.2528763207285;845.4589062999685;907.4872696926216;910.3354551177678;932.3892850183141;1018.3889666200369;19192.904035480013;20763.896180519303;20907.790922090888;20973.790262097184;21015.579688406146;835.9373047021497;924.9213816825645;934.8878134623889;996.4469719305855;1009.0363702663939;10045.849312260321;10375.481225938824;10409.635662751918;10450.581976720998;10552.227947258798;562875;578065;583301;602820;603305.6694330532;84359.57820210859;99397.50301248448;125796;128034;130437;940.7554035950566;951.3530799056708;997.7006897930737;999.2555546118189;1002.774375765447;14813.777793332893;17646.470605881845;17689.734653979944;18235.635287293913;18677.533061673566;54527.18209226944;54753.41815182247;56764;56945.145822811864;57107.42892571187;60989.69505152446;77853.27513536999;80308;82405.58797205976;83194;7810.843783124419;7939.523628582304;7981.760547183594;8014.599270036591;8227.588620569068;11582.594609188638;12071.275922706176;12226.388680566113;12483.31341776211;12581.119321647397;9412.341136120405;10482.842757358798;10788.676339709822;10946.890531094905;11088.889111108785;10537.63118290856;10639.521221545205;10770.623028193975;11022.44887755593;11061.391623460788;7760.68957241704;10628.309159904582;11000.504977275881;11104.000639942444;11339;6769.3907548320885;7029.789106326818;7169.283071692789;7294.525855819355;8050.188707540757;9109.954450227706;10420.010099040579;10768.300060496043;10937.125029997422;11020.614278500214 05:48:34 INFO - PROCESS | 1904 | |3;mootools.html;1459.9635009124775;1479.7706355514892;1669.8580620647244;1805.5756897129181;1859.5722983713745;7902.367810575154;8299.128591497896;8306.501609903402;8310.626021829017;8319.91680083199;7341.155767086787;7590.544567325965;7592.8861067084035;7659.885101723478;8092.352611791057;2044.8670836395625;2196.5606878624258;2277.578647950126;2281.7490076091617;2293.805026572735;2319.8724070176136;2325.4186453386656;2327.1622216002224;2328.313147621447;2328.441174118208;2024.2409096588779;2177.858439201455;2283.600369935263;2290.816734661223;2296.5751336002895;2250.032486031006;2254.9774502255013;2274.9658755118685;2275.681404603357;2278.6695929090356;2099.3387083068783;2112.5141217519904;2271.738750043733;2288.439332363565;2290.6564015397657;8102.878456823152;8161.510309381456;8242.752717418487;8244.340452763765;8247.21651443112;4900.264960255954;5069.746512674388;5081.644284900059;5085.415177254626;5092.439831618519;3258.348330333919;3425.69168774809;3448.189675426298;3514.420120680085;3518.47222916562;1004.1665417703289;1051.2326002018492;1064.0689396777818;1073.940933248671;1087.7933192693363;9125.45247285165;10715.62495312668;10758.623448179354;10767;10786.298890572167;4196.622303992655;4433.202023635777;4544.341070544752;4564.612007979359;4568.314752787109;9188.908110918805;9196.448213107235;9198.080191980749;9201.493917834583;9201.769955751026;5066.290719299227;5079.492050794891;5084.822031228963;5088.440271570124;5088.898222035613;4482.686211965196;4530.592246697747;4563.475200351935;4654.25531914892;4663.626909847204;3405.335959487876;3532.792004996877;3544.6455354464347;3549.1304630365207;3681.9263614727556;1223.0949097667662;1227.6071657069651;1229.7417542316214;1238.3313010973973;1242.1987817857612;824.2828738996991;858.1247127929477;863.6200071968314;914.1133100892124;927.6196759328643;5547.11246200606;5594.412586678422;5696.658200507983;5696.8006119785605;5953.434423729739;850.6937502499095;874.73320637205;880.3969281042506;913.2830727878717;976.3019441099648;3679.2273622539033;3712.6844218241213;3793.412021136728;3794.6205379461835;3805.860165363586;7314.40186288819;10027.949860250797;10032.749181270527;10068.546915388824;10070.899291006996;9792.314537982415;9996;9998.05018523239;10005.549750261256;10081.848772268422;7607.65765540552;7762.534247945141;7762.534247945255;7763.767086987399;7771.533707977539;5341.7062061586985;5385.057614917296;5466.972665136808;5484.862878428071;5509.752061157337;8362.958185209034;8576.78558036029;9191.862122068309;9335.859962100438;9404.153626173676;8912.777180570538;9225.953870230605;9366.438013719198;9378.67174648884;9494.620215191591;6595.406413422815;6720.857454232694;6999.12510936133;7167.462440316893;7177.533460325062;7381.372583330262;7677.193894641206;7756.146823849259;7815.179406162271;7829.804254893673;6682.665866706742;6897.206821215625;6940.722371105301;6956.2000369958105;7117.359437650636;6874.896876546756;6931.445484361138;6938.306169383021;6955.374016338554;7087.433005359662;5623.803166889303;6499.350064993652;6544.149260596092;6549.705263263069;6602.042703808017;4674.158651442773;4706.599939005223;4710.882227944328;4755.262934245197;4771.570558649738;4662.626989840873;4775.450823155243;4811.927821082757;4827.372441582572;4935.580475659609 05:48:34 INFO - PROCESS | 1904 | |4;prototype.html;248.3158897238109;296.87531236880494;301.84002478686295;307.11244503385205;312.2147798287309;5031.094403007457;5053.216751403534;5056;5069.797208111676;5085.110105731492;3926.862559810407;4755.453122890868;4762.5951794097455;4932.358793356859;4935.6545041847085;3685.70514358852;3878.476405685232;3909.921801563967;3916.4321173429835;3948.8478779065476;3056.5720799088144;3216.7748257621975;3703.370427027412;3906.394508851118;3914.9021274468223;3542.415501442259;3697.9815100924457;3884.864029758959;3884.941725874114;3919.9216015679813;3895.824687889052;3907.1794923065904;3914.706397020235;3918.608139186087;3944.447777311179;2850.8432036238;3084.892028778982;3200.6639302873095;3236.660150684191;3448.2241495663525;4128.710990230685;4296.720713153666;4892.804287828482;4905.23968784839;4985.277134815467;3603.549556305462;3720.4605332226674;4408.713433626804;4430.645548356124;4450.532694067141;5276.841694749163;5280.419153893069;5290.814821481268;5298.231756395301;5307.65500242483;3694.2057457646465;3730.6269373062746;3738.018850009499;3745.550533935945;3758.0980564664555;5440.646357986719;5530.640508366943;5578.358488773762;5587.636803607753;5607.878209516002;3757.140215593244;3760.2291530236234;3766.6798322142376;3775.528058992626;3790.6967442604528;3215.742740580748;3500.5099286099967;3571.4107172316794;3822.2164456286396;3849.230153969161;3690.4648825920626;4192.538820729717;4410.977945110254;4426.480947646629;4435.691471016058;5313.309269794902;5324.840254792362;5333.733313334318;5354.36691809002;5417.891642167214;1080.125098670064;1229.7724920889664;1232.9075319351084;1244.0918129765323;1246.7880460321767;739.9001134846727;795.1531618825932;840.5125027484045;860.2257967829004;860.4579115157411;4293.604578511934;4797.71356620709;4806.711597304173;4817.494163112823;4843.6367272454;4944.60443164546;5132.769025393866;5147.485251474823;5154.252633368141;5168.8449346519665;3795.9430608540893;4107.034846810997;4402.559744025636;4516.503184649686;4847.95152048482;2652.2441104285185;2676.183763952013;2678.7455191756753;2678.7723043541127;2689.314224872645;5290.391604965402;5438.356301278153;5463.945360546423;5468.261784659099;5521.420250873601;4185.079282557833;4433.091216300715;4666.836660716926;5245.26566280721;5292.655977361459;5001.974595207974;5362;5406.37790671524;5475.616706830484;5476.342838859283;4762.809487620595;4898.118338698926;4951.554360107608;5133.383993920754;5160.684025573455;4208.32666773327;4421.778911054499;4956.479569645136;5063.291139240436;5097.39432078894;4696.671233013656;4710.387649605529;4915.7542122893;5040.664223980575;5474.671519708831;3064.6169228846393;3567.3935430977317;3604.459331100356;3604.459331100356;3644.799536025544;4778.682339065481;4909.262561627904;4947.3321101652255;5086.3795505966855;5125.026245013435;4246.766427846498;4250.681198910032;4413.713108647928;4924.039812236722;4979.651424400257;4279.373837941686;4422.402975598382;4808.3989501312335;4948.480409556944;4974.776135074003;4011.950917302929;4579.771011449481;4606.56237657422;4716;4774.546418090275;4681.906361872675;4802.783874725716;4999.725015124203;5049.065922804293;5212.0030982184635;4336.005437498786;4402;4454.420925279693;5165.966806638762;5186.9221961669855 05:48:34 INFO - PROCESS | 1904 | |5;yui.html;669.63170256359;706.4913262451036;721.0518168608284;724.1563578431128;750.3921823323105;13971.650708732273;14829.925850370733;14854.702905941875;14901.18043507608;14909.925450372733;13320.933395333033;13467.5286364977;13476.865231347683;13481.528146514875;13500.594982150527;644.3974883483934;666.1273731411842;668.4585485756528;800.9639566219535;816.3550794872059;808.7978005498626;814.2305521282391;825.6119623776815;826.3100311240122;830.3730683334074;893.1604291965532;893.2407453664366;893.6470094312742;894.4946105450432;896.3725392225435;886.0342226972566;886.3352485635774;887.3655336434416;888.6223355074133;889.3507739350279;510.3416592595547;530.1067700923955;625.9217597800275;640.4428147511649;642.3031011352688;13882;13907.930460347734;13930.651733706638;13932.303384830719;13935.512257070954;11294.661160165208;12575.685607859878;12641.93679031599;12961.416736246798;12969;11583.536658533649;11732.589359372381;11745.119116066244;11790;12388.380580970916;4282.678799090049;4389.473263208403;4391.824327026915;4419.160359531711;4466.491413068753;85765;85832.57083714604;89302.5534872328;92648.53675731579;92658.5367073167;9875.506224688737;10240.436775977243;10518.163268345817;10542.367457952547;10731.731706707238;19270.90364548196;19800.70298945517;19922.30271940475;19970;20025.99870006523;7016.8071427857385;7413.592252426169;7435.367993720486;7474.289942455458;7545.962270188614;4238.470191226097;4241.957580424156;4266.658667306608;4310.655147588248;4422.867313980586;3128.264857758425;3219.935601287961;3229.1927018245437;3237.43344914639;3238.239013831748;608.4159207161175;614.1371373220633;615.4952938590313;630.1618846933567;632.5256057956533;583.276736846316;585.6808039618332;601.8736065426222;611.7338957553478;617.4288782875822;8019.478733882279;8321.875171872427;8491.980962284566;8546.2308392245;8581.184787445183;519.3222844188296;532.4170033812968;613.8404014956144;628.1425853709744;630.8328293002266;3834.5206849143856;4030.576789437067;4173.520045194841;4358.869233922987;4400.295952647561;85190;99796;100443.49778251062;106788.46605766921;110218.44890775495;60372.69813650991;93275.54229094501;97194;97332.51333743427;99543.50228248957;82328.17671823205;84012.57993710115;84640.7679616029;85749;85818.57090714756;8557.48655080697;8645.91354086451;8658.523781192094;8754;8795.780105497413;49908.50091499184;51618;52507.73746131245;52913.20630190627;53766.46233537615;74809.6259518699;78164.2183578157;78371.98722147026;78424;79981.60009199916;11072;11470.770584588428;11550.653480395602;11556.248843825124;11757.941210293893;11711.707207319885;12098.818517722417;12110.818337725117;12342.691432714253;12644.683882903;11652.766944661229;11738.178327517333;11996.040316774463;12078.51685932553;12374.62876113718;9441.480718560546;11639.825402619137;11642.94178529102;11910.106741994212;12417.31704756211;11343.886561134283;11422.371769552754;11428.485718142538;11631.6510504685;11916.582919597773;9525.651708129753;9542.570584323583;9580.425174489554;9636.903630963601;10038.096571308617;7801.843963120819;9354.532273386167;9448.149666530046;9922.801543969224;9953.286570261394 05:48:34 INFO - PROCESS | 1904 | __end_tp_report 05:48:34 INFO - PROCESS | 1904 | __start_cc_report 05:48:34 INFO - PROCESS | 1904 | _x_x_mozilla_cycle_collect,2298 05:48:34 INFO - PROCESS | 1904 | __end_cc_report 05:48:34 INFO - PROCESS | 1904 | __startTimestamp1479908914531__endTimestamp 05:48:34 INFO - PROCESS | 1904 | 05:48:34 INFO - PROCESS | 1904 | ------- Summary: start ------- 05:48:34 INFO - PROCESS | 1904 | Number of tests: 6 05:48:34 INFO - PROCESS | 1904 | 05:48:34 INFO - PROCESS | 1904 | [#0] dojo.html Cycles:165 Average:5545.47 Median:5699.26 stddev:589.95 (10.4%) stddev-sans-first:563.54 05:48:34 INFO - PROCESS | 1904 | Values: 3247.0 3266.8 3280.5 3301.3 3322.7 5410.5 5491.6 5510.2 5548.1 5566.1 5459.1 5541.7 5545.1 5550.4 5557.0 5018.1 5039.7 5049.7 5050.8 5109.1 5096.1 5115.5 5151.1 5219.2 5231.7 4983.4 5150.8 5166.1 5194.7 5211.0 5122.1 5203.7 5205.8 5210.5 5225.3 5077.5 5077.8 5092.6 5117.2 5140.4 5458.6 5512.9 5513.3 5532.7 5534.5 5911.9 5918.7 5918.9 5921.2 5930.3 4980.5 6007.7 6009.4 6024.7 6040.5 5534.8 5548.5 5550.7 5559.2 5561.0 6114.5 6129.3 6133.1 6144.4 6159.9 5763.5 5786.4 5795.6 5796.0 5808.4 5935.5 5952.2 5960.2 5960.7 6034.2 5905.1 5924.7 5930.1 5938.7 5987.5 5777.7 5860.9 5870.4 5946.9 5951.9 4726.9 4782.2 4792.9 4798.1 4826.4 4286.6 4323.9 4353.2 4371.9 4378.4 5436.2 5620.5 5677.0 5713.2 5721.5 5765.8 5813.5 5917.8 5925.0 5991.3 5656.5 5658.5 5669.1 5672.3 5703.9 5211.0 5211.8 5216.8 5221.3 5262.5 6215.5 6268.1 6289.7 6309.0 6310.2 5995.1 6029.8 6080.2 6091.5 6124.2 6305.2 6345.0 6362.8 6365.2 6392.3 5761.1 5833.0 5879.8 5921.5 5927.6 5883.4 5900.6 5961.3 5970.6 6032.9 5699.3 5707.5 5753.1 5758.3 5793.2 5616.6 5709.2 5715.3 5772.7 5774.9 5486.9 5583.2 5583.5 5604.1 5622.3 5734.8 5741.3 5742.4 5743.7 5750.5 5606.4 5688.7 5698.9 5722.3 5735.2 05:48:34 INFO - PROCESS | 1904 | 05:48:34 INFO - PROCESS | 1904 | [#1] ext.html Cycles:180 Average:67258.34 Median:36219.91 stddev:148389.43 (409.7%) stddev-sans-first:148721.06 05:48:34 INFO - PROCESS | 1904 | Values: 504.4 507.4 515.1 516.2 550.0 35821.5 38128.6 39330.4 39599.8 39745.0 40164.0 40545.2 40557.2 40573.8 40729.8 8437.2 9146.7 9336.8 9341.8 9351.1 8475.0 10956.8 11243.7 11294.0 11393.1 11183.4 11242.9 11243.9 11264.3 11267.5 8359.0 8593.1 10980.9 11108.4 11212.2 10875.6 11104.2 11124.4 11131.4 11135.4 38555.0 38813.6 38829.8 38869.2 38917.0 3271.8 3287.3 3381.2 3424.2 3641.6 28823.1 29031.9 29285.3 29567.3 29591.4 3113.2 3130.7 3142.6 3164.1 3236.1 453597.1 472513.0 475607.0 481136.0 481353.4 54059.7 55614.7 56042.0 56104.0 56116.0 54446.5 54522.7 54524.7 54543.2 54554.2 13734.6 14242.6 14256.6 14286.0 14321.4 9909.9 10157.5 10176.7 10863.7 10886.9 14911.7 15263.6 15265.4 15460.7 15593.8 6378.7 6432.4 6436.0 6482.6 6508.2 860.9 931.7 958.8 1014.1 1036.9 28430.9 28647.0 29065.6 29159.3 29206.7 763.1 915.6 946.5 1067.1 1083.4 13724.6 14666.8 14696.9 15130.8 15194.9 631394.0 634558.0 638062.0 640788.0 642744.0 54862.7 54892.0 54924.0 55032.9 55267.7 1204.4 1242.4 1319.2 1346.4 1373.7 28587.9 29594.7 29732.9 30435.4 30568.4 46849.8 47462.5 47493.0 47513.5 47555.5 509036.9 510394.0 515459.5 517033.0 642981.0 22678.2 23299.7 24176.5 24778.0 25363.9 43269.6 43998.0 44416.8 44589.8 45038.9 36069.8 36370.0 36698.6 37386.0 37555.8 27619.9 36380.0 36386.3 37202.0 37748.2 29283.9 29479.9 35496.0 35788.8 36627.3 30585.7 30917.8 32720.3 32757.5 32818.5 24186.4 24370.0 24459.1 24761.0 26041.3 05:48:34 INFO - PROCESS | 1904 | 05:48:34 INFO - PROCESS | 1904 | [#2] jquery.html Cycles:180 Average:33760.87 Median:1646.27 stddev:97569.32 (5926.7%) stddev-sans-first:97810.70 05:48:34 INFO - PROCESS | 1904 | Values: 315.2 352.7 369.4 373.2 403.2 14581.9 14852.0 15418.8 19221.5 20070.5 18445.7 18618.9 18647.5 19497.6 19863.7 5192.9 5796.9 5798.0 5802.7 5809.7 4949.7 5674.0 5775.0 5812.0 5820.2 5736.3 5736.7 5747.9 5752.1 5757.3 5464.0 5536.8 5670.1 5679.2 5691.5 4958.9 4964.3 4968.4 4977.2 4999.7 23027.3 24000.2 24030.6 24042.8 24342.7 2194.6 2283.9 2561.7 2608.5 2977.3 14233.9 14387.8 14512.1 14892.5 15256.8 1601.2 1746.4 1796.6 1840.9 1865.7 90320.6 112858.0 115028.0 115384.0 115992.8 8780.6 10050.2 10206.4 10283.4 10605.4 13225.7 13264.0 13267.2 13281.5 13292.1 7060.7 7091.4 7259.4 7520.8 7527.2 6174.7 7060.0 7063.6 7254.6 7258.9 5303.7 5992.6 6234.5 6310.3 6412.8 1768.7 1802.3 1807.6 1815.0 1822.3 845.5 907.5 910.3 932.4 1018.4 19192.9 20763.9 20907.8 20973.8 21015.6 835.9 924.9 934.9 996.4 1009.0 10045.8 10375.5 10409.6 10450.6 10552.2 562875.0 578065.0 583301.0 602820.0 603305.7 84359.6 99397.5 125796.0 128034.0 130437.0 940.8 951.4 997.7 999.3 1002.8 14813.8 17646.5 17689.7 18235.6 18677.5 54527.2 54753.4 56764.0 56945.1 57107.4 60989.7 77853.3 80308.0 82405.6 83194.0 7810.8 7939.5 7981.8 8014.6 8227.6 11582.6 12071.3 12226.4 12483.3 12581.1 9412.3 10482.8 10788.7 10946.9 11088.9 10537.6 10639.5 10770.6 11022.4 11061.4 7760.7 10628.3 11000.5 11104.0 11339.0 6769.4 7029.8 7169.3 7294.5 8050.2 9110.0 10420.0 10768.3 10937.1 11020.6 05:48:34 INFO - PROCESS | 1904 | 05:48:34 INFO - PROCESS | 1904 | [#3] mootools.html Cycles:180 Average:5321.04 Median:5085.12 stddev:2896.70 (57.0%) stddev-sans-first:2890.29 05:48:34 INFO - PROCESS | 1904 | Values: 1460.0 1479.8 1669.9 1805.6 1859.6 7902.4 8299.1 8306.5 8310.6 8319.9 7341.2 7590.5 7592.9 7659.9 8092.4 2044.9 2196.6 2277.6 2281.7 2293.8 2319.9 2325.4 2327.2 2328.3 2328.4 2024.2 2177.9 2283.6 2290.8 2296.6 2250.0 2255.0 2275.0 2275.7 2278.7 2099.3 2112.5 2271.7 2288.4 2290.7 8102.9 8161.5 8242.8 8244.3 8247.2 4900.3 5069.7 5081.6 5085.4 5092.4 3258.3 3425.7 3448.2 3514.4 3518.5 1004.2 1051.2 1064.1 1073.9 1087.8 9125.5 10715.6 10758.6 10767.0 10786.3 4196.6 4433.2 4544.3 4564.6 4568.3 9188.9 9196.4 9198.1 9201.5 9201.8 5066.3 5079.5 5084.8 5088.4 5088.9 4482.7 4530.6 4563.5 4654.3 4663.6 3405.3 3532.8 3544.6 3549.1 3681.9 1223.1 1227.6 1229.7 1238.3 1242.2 824.3 858.1 863.6 914.1 927.6 5547.1 5594.4 5696.7 5696.8 5953.4 850.7 874.7 880.4 913.3 976.3 3679.2 3712.7 3793.4 3794.6 3805.9 7314.4 10027.9 10032.7 10068.5 10070.9 9792.3 9996.0 9998.1 10005.5 10081.8 7607.7 7762.5 7762.5 7763.8 7771.5 5341.7 5385.1 5467.0 5484.9 5509.8 8363.0 8576.8 9191.9 9335.9 9404.2 8912.8 9226.0 9366.4 9378.7 9494.6 6595.4 6720.9 6999.1 7167.5 7177.5 7381.4 7677.2 7756.1 7815.2 7829.8 6682.7 6897.2 6940.7 6956.2 7117.4 6874.9 6931.4 6938.3 6955.4 7087.4 5623.8 6499.4 6544.1 6549.7 6602.0 4674.2 4706.6 4710.9 4755.3 4771.6 4662.6 4775.5 4811.9 4827.4 4935.6 05:48:34 INFO - PROCESS | 1904 | 05:48:34 INFO - PROCESS | 1904 | [#4] prototype.html Cycles:180 Average:4155.58 Median:4548.14 stddev:1251.45 (27.5%) stddev-sans-first:1220.11 05:48:34 INFO - PROCESS | 1904 | Values: 248.3 296.9 301.8 307.1 312.2 5031.1 5053.2 5056.0 5069.8 5085.1 3926.9 4755.5 4762.6 4932.4 4935.7 3685.7 3878.5 3909.9 3916.4 3948.8 3056.6 3216.8 3703.4 3906.4 3914.9 3542.4 3698.0 3884.9 3884.9 3919.9 3895.8 3907.2 3914.7 3918.6 3944.4 2850.8 3084.9 3200.7 3236.7 3448.2 4128.7 4296.7 4892.8 4905.2 4985.3 3603.5 3720.5 4408.7 4430.6 4450.5 5276.8 5280.4 5290.8 5298.2 5307.7 3694.2 3730.6 3738.0 3745.6 3758.1 5440.6 5530.6 5578.4 5587.6 5607.9 3757.1 3760.2 3766.7 3775.5 3790.7 3215.7 3500.5 3571.4 3822.2 3849.2 3690.5 4192.5 4411.0 4426.5 4435.7 5313.3 5324.8 5333.7 5354.4 5417.9 1080.1 1229.8 1232.9 1244.1 1246.8 739.9 795.2 840.5 860.2 860.5 4293.6 4797.7 4806.7 4817.5 4843.6 4944.6 5132.8 5147.5 5154.3 5168.8 3795.9 4107.0 4402.6 4516.5 4848.0 2652.2 2676.2 2678.7 2678.8 2689.3 5290.4 5438.4 5463.9 5468.3 5521.4 4185.1 4433.1 4666.8 5245.3 5292.7 5002.0 5362.0 5406.4 5475.6 5476.3 4762.8 4898.1 4951.6 5133.4 5160.7 4208.3 4421.8 4956.5 5063.3 5097.4 4696.7 4710.4 4915.8 5040.7 5474.7 3064.6 3567.4 3604.5 3604.5 3644.8 4778.7 4909.3 4947.3 5086.4 5125.0 4246.8 4250.7 4413.7 4924.0 4979.7 4279.4 4422.4 4808.4 4948.5 4974.8 4012.0 4579.8 4606.6 4716.0 4774.5 4681.9 4802.8 4999.7 5049.1 5212.0 4336.0 4402.0 4454.4 5166.0 5186.9 05:48:34 INFO - PROCESS | 1904 | 05:48:34 INFO - PROCESS | 1904 | [#5] yui.html Cycles:180 Average:19859.51 Median:27174.87 stddev:29260.56 (107.7%) stddev-sans-first:29307.16 05:48:34 INFO - PROCESS | 1904 | Values: 669.6 706.5 721.1 724.2 750.4 13971.7 14829.9 14854.7 14901.2 14909.9 13320.9 13467.5 13476.9 13481.5 13500.6 644.4 666.1 668.5 801.0 816.4 808.8 814.2 825.6 826.3 830.4 893.2 893.2 893.6 894.5 896.4 886.0 886.3 887.4 888.6 889.4 510.3 530.1 625.9 640.4 642.3 13882.0 13907.9 13930.7 13932.3 13935.5 11294.7 12575.7 12641.9 12961.4 12969.0 11583.5 11732.6 11745.1 11790.0 12388.4 4282.7 4389.5 4391.8 4419.2 4466.5 85765.0 85832.6 89302.6 92648.5 92658.5 9875.5 10240.4 10518.2 10542.4 10731.7 19270.9 19800.7 19922.3 19970.0 20026.0 7016.8 7413.6 7435.4 7474.3 7546.0 4238.5 4242.0 4266.7 4310.7 4422.9 3128.3 3219.9 3229.2 3237.4 3238.2 608.4 614.1 615.5 630.2 632.5 583.3 585.7 601.9 611.7 617.4 8019.5 8321.9 8492.0 8546.2 8581.2 519.3 532.4 613.8 628.1 630.8 3834.5 4030.6 4173.5 4358.9 4400.3 85190.0 99796.0 100443.5 106788.5 110218.4 60372.7 93275.5 97194.0 97332.5 99543.5 82328.2 84012.6 84640.8 85749.0 85818.6 8557.5 8645.9 8658.5 8754.0 8795.8 49908.5 51618.0 52507.7 52913.2 53766.5 74809.6 78164.2 78372.0 78424.0 79981.6 11072.0 11470.8 11550.7 11556.2 11757.9 11711.7 12098.8 12110.8 12342.7 12644.7 11652.8 11738.2 11996.0 12078.5 12374.6 9441.5 11639.8 11642.9 11910.1 12417.3 11343.9 11422.4 11428.5 11631.7 11916.6 9525.7 9542.6 9580.4 9636.9 10038.1 7801.8 9354.5 9448.1 9922.8 9953.3 05:48:34 INFO - PROCESS | 1904 | -------- Summary: end -------- 05:48:34 INFO - PROCESS | 1904 | 05:48:35 INFO - TEST-INFO | 1904: exit 0 05:48:35 INFO - TEST-OK | dromaeo_css | took 1121666ms 05:48:35 INFO - TEST-START | kraken 05:48:35 INFO - operating with platform_type : mac_ 05:48:35 INFO - Initialising browser for kraken test... 05:48:35 INFO - TEST-INFO | started process 1909 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/tmp874Wta/profile http://localhost:53200/getInfo.html) 05:48:37 INFO - PROCESS | 1909 | __metrics Screen width/height:1600/1200 05:48:37 INFO - PROCESS | 1909 | colorDepth:24 05:48:37 INFO - PROCESS | 1909 | Browser inner width/height: 1024/689 05:48:37 INFO - PROCESS | 1909 | __metrics 05:48:37 INFO - PROCESS | 1909 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 05:48:37 INFO - PROCESS | 1909 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:48:38 INFO - TEST-INFO | 1909: exit 0 05:48:38 INFO - Browser initialized. 05:48:38 INFO - Running cycle 1/1 for kraken test... 05:48:38 INFO - TEST-INFO | started process 1911 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/tmp874Wta/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:48:51 INFO - PROCESS | 1911 | RSS: Main: 244277248 05:48:51 INFO - PROCESS | 1911 | 05:48:53 INFO - PROCESS | 1911 | Cycle 1(1): loaded http://localhost:53200/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:53200/tests/kraken/driver.html?testName=audio-beat-detection) 05:48:54 INFO - PROCESS | 1911 | RSS: Main: 293662720 05:48:54 INFO - PROCESS | 1911 | 05:48:57 INFO - PROCESS | 1911 | Cycle 1(1): loaded http://localhost:53200/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:53200/tests/kraken/driver.html?testName=audio-dft) 05:48:57 INFO - PROCESS | 1911 | RSS: Main: 293822464 05:48:57 INFO - PROCESS | 1911 | 05:49:01 INFO - PROCESS | 1911 | Cycle 1(1): loaded http://localhost:53200/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:53200/tests/kraken/driver.html?testName=audio-fft) 05:49:02 INFO - PROCESS | 1911 | RSS: Main: 386568192 05:49:02 INFO - PROCESS | 1911 | 05:49:03 INFO - PROCESS | 1911 | Cycle 1(1): loaded http://localhost:53200/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:53200/tests/kraken/driver.html?testName=audio-oscillator) 05:49:04 INFO - PROCESS | 1911 | RSS: Main: 290390016 05:49:04 INFO - PROCESS | 1911 | 05:49:06 INFO - PROCESS | 1911 | Cycle 1(1): loaded http://localhost:53200/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:53200/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:49:07 INFO - PROCESS | 1911 | RSS: Main: 292675584 05:49:07 INFO - PROCESS | 1911 | 05:49:10 INFO - PROCESS | 1911 | Cycle 1(1): loaded http://localhost:53200/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:53200/tests/kraken/driver.html?testName=imaging-darkroom) 05:49:10 INFO - PROCESS | 1911 | RSS: Main: 335437824 05:49:10 INFO - PROCESS | 1911 | 05:49:13 INFO - PROCESS | 1911 | Cycle 1(1): loaded http://localhost:53200/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:53200/tests/kraken/driver.html?testName=imaging-desaturate) 05:49:14 INFO - PROCESS | 1911 | RSS: Main: 336670720 05:49:14 INFO - PROCESS | 1911 | 05:49:17 INFO - PROCESS | 1911 | Cycle 1(1): loaded http://localhost:53200/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:53200/tests/kraken/driver.html?testName=json-parse-financial) 05:49:17 INFO - PROCESS | 1911 | RSS: Main: 333287424 05:49:17 INFO - PROCESS | 1911 | 05:49:19 INFO - PROCESS | 1911 | Cycle 1(1): loaded http://localhost:53200/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:53200/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:49:19 INFO - PROCESS | 1911 | RSS: Main: 310992896 05:49:19 INFO - PROCESS | 1911 | 05:49:21 INFO - PROCESS | 1911 | Cycle 1(1): loaded http://localhost:53200/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:53200/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:49:22 INFO - PROCESS | 1911 | RSS: Main: 314408960 05:49:22 INFO - PROCESS | 1911 | 05:49:24 INFO - PROCESS | 1911 | Cycle 1(1): loaded http://localhost:53200/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:53200/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:49:25 INFO - PROCESS | 1911 | RSS: Main: 316022784 05:49:25 INFO - PROCESS | 1911 | 05:49:28 INFO - PROCESS | 1911 | Cycle 1(1): loaded http://localhost:53200/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:53200/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:49:29 INFO - PROCESS | 1911 | RSS: Main: 333975552 05:49:29 INFO - PROCESS | 1911 | 05:49:31 INFO - PROCESS | 1911 | Cycle 1(1): loaded http://localhost:53200/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:53200/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:49:32 INFO - PROCESS | 1911 | RSS: Main: 327729152 05:49:32 INFO - PROCESS | 1911 | 05:49:34 INFO - PROCESS | 1911 | Cycle 1(1): loaded http://localhost:53200/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:53200/tests/kraken/driver.html?testName=ai-astar) 05:49:34 INFO - PROCESS | 1911 | RSS: Main: 371511296 05:49:34 INFO - PROCESS | 1911 | 05:49:34 INFO - PROCESS | 1911 | __start_tp_report 05:49:34 INFO - PROCESS | 1911 | _x_x_mozilla_page_load 05:49:34 INFO - PROCESS | 1911 | _x_x_mozilla_page_load_details 05:49:34 INFO - PROCESS | 1911 | |i|pagename|runs| 05:49:34 INFO - PROCESS | 1911 | |0;ai-astar;113;115;115;115;115;114;115;114;114;114 05:49:34 INFO - PROCESS | 1911 | |1;audio-beat-detection;151;114;114;149;116;113;117;116;133;111 05:49:34 INFO - PROCESS | 1911 | |2;audio-dft;180;185;188;186;184;183;183;192;233;260 05:49:34 INFO - PROCESS | 1911 | |3;audio-fft;80;60;60;88;62;60;59;58;77;59 05:49:34 INFO - PROCESS | 1911 | |4;audio-oscillator;82;69;87;73;69;69;81;76;98;114 05:49:34 INFO - PROCESS | 1911 | |5;imaging-gaussian-blur;87;85;87;84;88;84;86;86;134;84 05:49:34 INFO - PROCESS | 1911 | |6;imaging-darkroom;93;93;94;93;93;93;93;94;93;100 05:49:34 INFO - PROCESS | 1911 | |7;imaging-desaturate;88;85;86;87;87;85;87;85;87;87 05:49:34 INFO - PROCESS | 1911 | |8;json-parse-financial;54;54;54;54;55;54;55;55;55;55 05:49:34 INFO - PROCESS | 1911 | |9;json-stringify-tinderbox;72;71;86;74;76;73;72;74;74;74 05:49:34 INFO - PROCESS | 1911 | |10;stanford-crypto-aes;87;87;87;88;88;86;86;86;88;89 05:49:34 INFO - PROCESS | 1911 | |11;stanford-crypto-ccm;151;145;142;179;157;159;152;150;198;168 05:49:34 INFO - PROCESS | 1911 | |12;stanford-crypto-pbkdf2;175;176;175;181;176;176;175;177;175;175 05:49:34 INFO - PROCESS | 1911 | |13;stanford-crypto-sha256-iterative;68;68;67;68;67;70;68;67;65;81 05:49:34 INFO - PROCESS | 1911 | __end_tp_report 05:49:34 INFO - PROCESS | 1911 | __start_cc_report 05:49:34 INFO - PROCESS | 1911 | _x_x_mozilla_cycle_collect,2008 05:49:34 INFO - PROCESS | 1911 | __end_cc_report 05:49:34 INFO - PROCESS | 1911 | __startTimestamp1479908974563__endTimestamp 05:49:34 INFO - PROCESS | 1911 | 05:49:34 INFO - PROCESS | 1911 | ------- Summary: start ------- 05:49:34 INFO - PROCESS | 1911 | Number of tests: 14 05:49:34 INFO - PROCESS | 1911 | 05:49:34 INFO - PROCESS | 1911 | [#0] ai-astar Cycles:10 Average:114.40 Median:115.00 stddev:0.70 (0.6%) stddev-sans-first:0.53 05:49:34 INFO - PROCESS | 1911 | Values: 113.0 115.0 115.0 115.0 115.0 114.0 115.0 114.0 114.0 114.0 05:49:34 INFO - PROCESS | 1911 | 05:49:34 INFO - PROCESS | 1911 | [#1] audio-beat-detection Cycles:10 Average:123.40 Median:116.50 stddev:15.27 (13.1%) stddev-sans-first:12.51 05:49:34 INFO - PROCESS | 1911 | Values: 151.0 114.0 114.0 149.0 116.0 113.0 117.0 116.0 133.0 111.0 05:49:34 INFO - PROCESS | 1911 | 05:49:34 INFO - PROCESS | 1911 | [#2] audio-dft Cycles:10 Average:197.40 Median:187.00 stddev:26.84 (14.4%) stddev-sans-first:27.72 05:49:34 INFO - PROCESS | 1911 | Values: 180.0 185.0 188.0 186.0 184.0 183.0 183.0 192.0 233.0 260.0 05:49:34 INFO - PROCESS | 1911 | 05:49:34 INFO - PROCESS | 1911 | [#3] audio-fft Cycles:10 Average:66.30 Median:61.00 stddev:10.99 (18.0%) stddev-sans-first:10.47 05:49:34 INFO - PROCESS | 1911 | Values: 80.0 60.0 60.0 88.0 62.0 60.0 59.0 58.0 77.0 59.0 05:49:34 INFO - PROCESS | 1911 | 05:49:34 INFO - PROCESS | 1911 | [#4] audio-oscillator Cycles:10 Average:81.80 Median:78.50 stddev:14.64 (18.7%) stddev-sans-first:15.53 05:49:34 INFO - PROCESS | 1911 | Values: 82.0 69.0 87.0 73.0 69.0 69.0 81.0 76.0 98.0 114.0 05:49:34 INFO - PROCESS | 1911 | 05:49:34 INFO - PROCESS | 1911 | [#5] imaging-gaussian-blur Cycles:10 Average:90.50 Median:86.00 stddev:15.35 (17.8%) stddev-sans-first:16.23 05:49:34 INFO - PROCESS | 1911 | Values: 87.0 85.0 87.0 84.0 88.0 84.0 86.0 86.0 134.0 84.0 05:49:34 INFO - PROCESS | 1911 | 05:49:34 INFO - PROCESS | 1911 | [#6] imaging-darkroom Cycles:10 Average:93.90 Median:93.00 stddev:2.18 (2.3%) stddev-sans-first:2.29 05:49:34 INFO - PROCESS | 1911 | Values: 93.0 93.0 94.0 93.0 93.0 93.0 93.0 94.0 93.0 100.0 05:49:34 INFO - PROCESS | 1911 | 05:49:34 INFO - PROCESS | 1911 | [#7] imaging-desaturate Cycles:10 Average:86.40 Median:87.00 stddev:1.07 (1.2%) stddev-sans-first:0.97 05:49:34 INFO - PROCESS | 1911 | Values: 88.0 85.0 86.0 87.0 87.0 85.0 87.0 85.0 87.0 87.0 05:49:34 INFO - PROCESS | 1911 | 05:49:34 INFO - PROCESS | 1911 | [#8] json-parse-financial Cycles:10 Average:54.50 Median:55.00 stddev:0.53 (1.0%) stddev-sans-first:0.53 05:49:34 INFO - PROCESS | 1911 | Values: 54.0 54.0 54.0 54.0 55.0 54.0 55.0 55.0 55.0 55.0 05:49:34 INFO - PROCESS | 1911 | 05:49:34 INFO - PROCESS | 1911 | [#9] json-stringify-tinderbox Cycles:10 Average:74.60 Median:74.00 stddev:4.25 (5.7%) stddev-sans-first:4.40 05:49:34 INFO - PROCESS | 1911 | Values: 72.0 71.0 86.0 74.0 76.0 73.0 72.0 74.0 74.0 74.0 05:49:34 INFO - PROCESS | 1911 | 05:49:34 INFO - PROCESS | 1911 | [#10] stanford-crypto-aes Cycles:10 Average:87.20 Median:87.50 stddev:1.03 (1.2%) stddev-sans-first:1.09 05:49:34 INFO - PROCESS | 1911 | Values: 87.0 87.0 87.0 88.0 88.0 86.0 86.0 86.0 88.0 89.0 05:49:34 INFO - PROCESS | 1911 | 05:49:34 INFO - PROCESS | 1911 | [#11] stanford-crypto-ccm Cycles:10 Average:160.10 Median:158.00 stddev:17.23 (10.9%) stddev-sans-first:17.96 05:49:34 INFO - PROCESS | 1911 | Values: 151.0 145.0 142.0 179.0 157.0 159.0 152.0 150.0 198.0 168.0 05:49:34 INFO - PROCESS | 1911 | 05:49:34 INFO - PROCESS | 1911 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:176.10 Median:176.00 stddev:1.85 (1.1%) stddev-sans-first:1.92 05:49:34 INFO - PROCESS | 1911 | Values: 175.0 176.0 175.0 181.0 176.0 176.0 175.0 177.0 175.0 175.0 05:49:34 INFO - PROCESS | 1911 | 05:49:34 INFO - PROCESS | 1911 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.90 Median:68.00 stddev:4.43 (6.5%) stddev-sans-first:4.69 05:49:34 INFO - PROCESS | 1911 | Values: 68.0 68.0 67.0 68.0 67.0 70.0 68.0 67.0 65.0 81.0 05:49:34 INFO - PROCESS | 1911 | -------- Summary: end -------- 05:49:34 INFO - PROCESS | 1911 | 05:49:35 INFO - TEST-INFO | 1911: exit 0 05:49:35 INFO - TEST-OK | kraken | took 60356ms 05:49:35 INFO - SUITE-END | took 1182s 05:49:35 INFO - Completed test suite (00:19:42) 05:49:35 INFO - javascript benchmark 05:49:35 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3246.9837650811746, 3266.7549933754976, 3280.458724310489, 3301.3067255876263, 3322.734181265499, 5410.540104091147, 5491.560675145989, 5510.201020851974, 5548.140038294066, 5566.137248726449, 5459.099248623982, 5541.722913854302, 5545.140503222007, 5550.361708403535, 5556.972215138919, 5018.146915024446, 5039.722815245178, 5049.697018178921, 5050.772715227805, 5109.131447653907, 5096.05722941257, 5115.4884511548735, 5151.124308867483, 5219.19102539107, 5231.712255825928, 4983.352164218646, 5150.845474635767, 5166.095933211692, 5194.688318700871, 5211.009908117446, 5122.078025955345, 5203.661761985459, 5205.843824685266, 5210.47895210476, 5225.294585230983, 5077.543021128079, 5077.822276220315, 5092.6180536459915, 5117.181250999822, 5140.408852981917, 5458.61789674723, 5512.889742205133, 5513.255710479114, 5532.723363831792, 5534.4742249486235, 5911.896254466396, 5918.674472903988, 5918.852028699274, 5921.200637913913, 5930.28836539618, 4980.452150263468, 6007.669578173199, 6009.399060094, 6024.728887200043, 6040.546958978094, 5534.75093620788, 5548.500634942875, 5550.694711790809, 5559.249501317351, 5561.026820306471, 6114.488701380082, 6129.278400901673, 6133.08984587446, 6144.41628045335, 6159.907601385983, 5763.481286684135, 5786.392428795, 5795.565332600072, 5796.043652797325, 5808.448197421239, 5935.465808077293, 5952.196453478725, 5960.225170727865, 5960.731767070492, 6034.24571928509, 5905.143754155709, 5924.703764811742, 5930.080837470113, 5938.732757025944, 5987.491063259672, 5777.653340799566, 5860.912086318709, 5870.354261031283, 5946.910796338058, 5951.880962380815, 4726.858194254178, 4782.163121453732, 4792.880177995578, 4798.064377446434, 4826.396700412449, 4286.61420472159, 4323.935140972888, 4353.172897149595, 4371.912561748811, 4378.365137055109, 5436.238926550286, 5620.49415552594, 5677.034904066319, 5713.200151978726, 5721.5136713379, 5765.827025189251, 5813.5349172066135, 5917.763289468373, 5925.0, 5991.310999235144, 5656.547476201977, 5658.490735833795, 5669.06460434032, 5672.262605861294, 5703.914441283384, 5210.973945130326, 5211.765470553834, 5216.843494695166, 5221.295125158131, 5262.500062494057, 6215.533834962305, 6268.122462855295, 6289.716962736779, 6308.968455157695, 6310.1796766420075, 5995.070764031582, 6029.758809647565, 6080.2399700037495, 6091.4822240108315, 6124.234470691164, 6305.187775007646, 6345.0, 6362.809115726535, 6365.204349456318, 6392.328805475358, 5761.107028410603, 5832.9708351457975, 5879.794207202898, 5921.526277897672, 5927.614705044159, 5883.35283118848, 5900.586958912835, 5961.254843144607, 5970.641761494324, 6032.90950635732, 5699.2590963174525, 5707.486326230659, 5753.1370294456165, 5758.3090029196765, 5793.246877905845, 5616.6068375213345, 5709.200711900255, 5715.314162300384, 5772.7402266898935, 5774.855628609318, 5486.890262194814, 5583.218349431002, 5583.497485226187, 5604.131359639263, 5622.297212848394, 5734.799282025109, 5741.311042674739, 5742.36833948257, 5743.712814359349, 5750.482456578928, 5606.355269144101, 5688.715564221855, 5698.943010570007, 5722.313322401338, 5735.225744524436], "value": 5509.037708882507, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [504.42999410665954, 507.3556583139413, 515.1217174717108, 516.1921592707411, 550.0209626864183, 35821.46267805978, 38128.61871381285, 39330.41004384936, 39599.80200098999, 39745.0, 40163.99590010251, 40545.189096218055, 40557.18885622293, 40573.78278651645, 40729.796351018274, 8437.198466145706, 9146.679866204668, 9336.766580835465, 9341.81316373672, 9351.111644393772, 8474.957625211866, 10956.835647465254, 11243.66269011931, 11294.0, 11393.088552915788, 11183.44082795857, 11242.887571124265, 11243.943780281088, 11264.324140551553, 11267.549298028069, 8359.0, 8593.14068593142, 10980.890191098146, 11108.389038602874, 11212.159088068387, 10875.619353322596, 11104.222704410695, 11124.38815865127, 11131.443427828575, 11135.443227838576, 38555.036124097125, 38813.611863881284, 38829.8351049469, 38869.22261554753, 38917.0, 3271.8200498972546, 3287.342531493711, 3381.205416727067, 3424.2466657335344, 3641.5994240633772, 28823.135305940854, 29031.85484072587, 29285.26786830325, 29567.260818479495, 29591.408171836658, 3113.206132436237, 3130.660412664373, 3142.5600415941785, 3164.114048066545, 3236.1262459136137, 453597.05216213095, 472513.0, 475606.99999999657, 481136.0, 481353.4240548741, 54059.72970135124, 55614.72192639092, 56042.0, 56104.0, 56116.02143656995, 54446.45553544493, 54522.727386362814, 54524.72737636286, 54543.181852272246, 54554.18168727472, 13734.587962361145, 14242.572722818331, 14256.572302830931, 14286.0, 14321.35553900077, 9909.851352229722, 10157.492125393848, 10176.745581360377, 10863.67408977732, 10886.891131088747, 14911.70176596462, 15263.618409539851, 15265.3893844245, 15460.690786184437, 15593.844061559239, 6378.681065946684, 6432.35676432353, 6436.00483850008, 6482.643454609947, 6508.153939987771, 860.9225169734754, 931.6599441203912, 958.7794807194384, 1014.1238708389568, 1036.8652075230173, 28430.857845710638, 28646.997355092473, 29065.564016539767, 29159.271018224714, 29206.7079329204, 763.065245074779, 915.6474757218518, 946.522006386784, 1067.0556557446716, 1083.4311986207147, 13724.588262352145, 14666.8199563124, 14696.853031469549, 15130.848691513163, 15194.936354455303, 631394.0, 634558.0, 638062.0, 640788.0, 642744.0, 54862.72568637131, 54892.0, 54924.0, 55032.89934201293, 55267.72366138144, 1204.3737256626505, 1242.4160644497072, 1319.182107093608, 1346.3941226447942, 1373.6909195430949, 28587.857060715396, 29594.704052959194, 29732.851335743184, 30435.391292174474, 30568.388632227674, 46849.76575117103, 47462.52537474581, 47493.0, 47513.52486475091, 47555.52444475511, 509036.90963089897, 510394.0, 515459.53621391417, 517033.0, 642981.0, 22678.206262780725, 23299.650505242094, 24176.51646967086, 24778.00887964461, 25363.87318063398, 43269.567304326556, 43998.0, 44416.7779161115, 44589.777051114535, 45038.874028149556, 36069.819650901576, 36370.0, 36698.633013669525, 37386.0, 37555.81222093872, 27619.86190069037, 36380.0, 36386.31256990743, 37202.0, 37748.245035099695, 29283.85358073196, 29479.85260073686, 35496.0, 35788.82105589456, 36627.267454650224, 30585.694143058285, 30917.845410772803, 32720.34559308848, 32757.508637370935, 32818.52316645804, 24186.395340116636, 24370.0, 24459.143929962367, 24761.0, 26041.348966275993], "value": 19066.122853420507, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [315.20410962320136, 352.72311235679996, 369.4107897902846, 373.23486851953487, 403.1976367029612, 14581.927090364547, 14852.034617749852, 15418.768718469204, 19221.519462013457, 20070.49823754407, 18445.7233141503, 18618.906905465486, 18647.533811654714, 19497.610047799037, 19863.702044469344, 5192.922106168401, 5796.855078623047, 5797.985352563305, 5802.65184088956, 5809.73856176471, 4949.703017818924, 5673.971630141844, 5774.971125144369, 5812.0, 5820.185174075633, 5736.340320863092, 5736.713164341766, 5747.856303592402, 5752.108423194411, 5757.337906140785, 5464.0, 5536.833894983137, 5670.064439367499, 5679.2333035040565, 5691.4877661010705, 4958.851234462936, 4964.304997300381, 4968.428630707443, 4977.203647416396, 4999.650024498286, 23027.309180724606, 24000.15999440029, 24030.639540406908, 24042.759572404226, 24342.687444692725, 2194.550117225981, 2283.908643654252, 2561.692596888385, 2608.504384167002, 2977.330100727341, 14233.868548919221, 14387.78418323726, 14512.1292722437, 14892.478763243234, 15256.771148432783, 1601.1513897634272, 1746.3974928649595, 1796.5986530506227, 1840.9447716568525, 1865.664180447519, 90320.6451903222, 112858.0, 115028.0, 115383.99999999916, 115992.84007159904, 8780.604872780741, 10050.246231532517, 10206.4386458744, 10283.382997020053, 10605.416702081306, 13225.669358265928, 13264.0, 13267.203967761965, 13281.468741250435, 13292.069555131047, 7060.717571297193, 7091.3972312353, 7259.382952449101, 7520.811979700551, 7527.209642987517, 6174.691265436711, 7059.964700176569, 7063.57618542889, 7254.600996945113, 7258.891116633255, 5303.655262407931, 5992.64044157352, 6234.470070044006, 6310.274318453345, 6412.807615771534, 1768.6823316629193, 1802.2971041293906, 1807.5752198233404, 1815.0, 1822.2528763207285, 845.4589062999685, 907.4872696926216, 910.3354551177678, 932.3892850183141, 1018.3889666200369, 19192.904035480013, 20763.896180519303, 20907.790922090888, 20973.790262097184, 21015.579688406146, 835.9373047021497, 924.9213816825645, 934.8878134623889, 996.4469719305855, 1009.0363702663939, 10045.849312260321, 10375.481225938824, 10409.635662751918, 10450.581976720998, 10552.227947258798, 562875.0, 578065.0, 583301.0, 602820.0, 603305.6694330532, 84359.57820210859, 99397.50301248448, 125796.0, 128034.0, 130437.0, 940.7554035950566, 951.3530799056708, 997.7006897930737, 999.2555546118189, 1002.774375765447, 14813.777793332893, 17646.470605881845, 17689.734653979944, 18235.635287293913, 18677.533061673566, 54527.18209226944, 54753.41815182247, 56764.0, 56945.145822811864, 57107.42892571187, 60989.69505152446, 77853.27513536999, 80308.0, 82405.58797205976, 83194.0, 7810.843783124419, 7939.523628582304, 7981.760547183594, 8014.599270036591, 8227.588620569068, 11582.594609188638, 12071.275922706176, 12226.388680566113, 12483.31341776211, 12581.119321647397, 9412.341136120405, 10482.842757358798, 10788.676339709822, 10946.890531094905, 11088.889111108785, 10537.63118290856, 10639.521221545205, 10770.623028193975, 11022.44887755593, 11061.391623460788, 7760.68957241704, 10628.309159904582, 11000.504977275881, 11104.000639942444, 11339.0, 6769.3907548320885, 7029.789106326818, 7169.283071692789, 7294.525855819355, 8050.188707540757, 9109.954450227706, 10420.010099040579, 10768.300060496043, 10937.125029997422, 11020.614278500214], "value": 9246.023963317013, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1459.9635009124775, 1479.7706355514892, 1669.8580620647244, 1805.5756897129181, 1859.5722983713745, 7902.367810575154, 8299.128591497896, 8306.501609903402, 8310.626021829017, 8319.91680083199, 7341.155767086787, 7590.544567325965, 7592.8861067084035, 7659.885101723478, 8092.352611791057, 2044.8670836395625, 2196.5606878624258, 2277.578647950126, 2281.7490076091617, 2293.805026572735, 2319.8724070176136, 2325.4186453386656, 2327.1622216002224, 2328.313147621447, 2328.441174118208, 2024.2409096588779, 2177.858439201455, 2283.600369935263, 2290.816734661223, 2296.5751336002895, 2250.032486031006, 2254.9774502255013, 2274.9658755118685, 2275.681404603357, 2278.6695929090356, 2099.3387083068783, 2112.5141217519904, 2271.738750043733, 2288.439332363565, 2290.6564015397657, 8102.878456823152, 8161.510309381456, 8242.752717418487, 8244.340452763765, 8247.21651443112, 4900.264960255954, 5069.746512674388, 5081.644284900059, 5085.415177254626, 5092.439831618519, 3258.348330333919, 3425.69168774809, 3448.189675426298, 3514.420120680085, 3518.47222916562, 1004.1665417703289, 1051.2326002018492, 1064.0689396777818, 1073.940933248671, 1087.7933192693363, 9125.45247285165, 10715.62495312668, 10758.623448179354, 10767.0, 10786.298890572167, 4196.622303992655, 4433.202023635777, 4544.341070544752, 4564.612007979359, 4568.314752787109, 9188.908110918805, 9196.448213107235, 9198.080191980749, 9201.493917834583, 9201.769955751026, 5066.290719299227, 5079.492050794891, 5084.822031228963, 5088.440271570124, 5088.898222035613, 4482.686211965196, 4530.592246697747, 4563.475200351935, 4654.25531914892, 4663.626909847204, 3405.335959487876, 3532.792004996877, 3544.6455354464347, 3549.1304630365207, 3681.9263614727556, 1223.0949097667662, 1227.6071657069651, 1229.7417542316214, 1238.3313010973973, 1242.1987817857612, 824.2828738996991, 858.1247127929477, 863.6200071968314, 914.1133100892124, 927.6196759328643, 5547.11246200606, 5594.412586678422, 5696.658200507983, 5696.8006119785605, 5953.434423729739, 850.6937502499095, 874.73320637205, 880.3969281042506, 913.2830727878717, 976.3019441099648, 3679.2273622539033, 3712.6844218241213, 3793.412021136728, 3794.6205379461835, 3805.860165363586, 7314.40186288819, 10027.949860250797, 10032.749181270527, 10068.546915388824, 10070.899291006996, 9792.314537982415, 9996.0, 9998.05018523239, 10005.549750261256, 10081.848772268422, 7607.65765540552, 7762.534247945141, 7762.534247945255, 7763.767086987399, 7771.533707977539, 5341.7062061586985, 5385.057614917296, 5466.972665136808, 5484.862878428071, 5509.752061157337, 8362.958185209034, 8576.78558036029, 9191.862122068309, 9335.859962100438, 9404.153626173676, 8912.777180570538, 9225.953870230605, 9366.438013719198, 9378.67174648884, 9494.620215191591, 6595.406413422815, 6720.857454232694, 6999.12510936133, 7167.462440316893, 7177.533460325062, 7381.372583330262, 7677.193894641206, 7756.146823849259, 7815.179406162271, 7829.804254893673, 6682.665866706742, 6897.206821215625, 6940.722371105301, 6956.2000369958105, 7117.359437650636, 6874.896876546756, 6931.445484361138, 6938.306169383021, 6955.374016338554, 7087.433005359662, 5623.803166889303, 6499.350064993652, 6544.149260596092, 6549.705263263069, 6602.042703808017, 4674.158651442773, 4706.599939005223, 4710.882227944328, 4755.262934245197, 4771.570558649738, 4662.626989840873, 4775.450823155243, 4811.927821082757, 4827.372441582572, 4935.580475659609], "value": 4328.844948263617, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [248.3158897238109, 296.87531236880494, 301.84002478686295, 307.11244503385205, 312.2147798287309, 5031.094403007457, 5053.216751403534, 5056.0, 5069.797208111676, 5085.110105731492, 3926.862559810407, 4755.453122890868, 4762.5951794097455, 4932.358793356859, 4935.6545041847085, 3685.70514358852, 3878.476405685232, 3909.921801563967, 3916.4321173429835, 3948.8478779065476, 3056.5720799088144, 3216.7748257621975, 3703.370427027412, 3906.394508851118, 3914.9021274468223, 3542.415501442259, 3697.9815100924457, 3884.864029758959, 3884.941725874114, 3919.9216015679813, 3895.824687889052, 3907.1794923065904, 3914.706397020235, 3918.608139186087, 3944.447777311179, 2850.8432036238, 3084.892028778982, 3200.6639302873095, 3236.660150684191, 3448.2241495663525, 4128.710990230685, 4296.720713153666, 4892.804287828482, 4905.23968784839, 4985.277134815467, 3603.549556305462, 3720.4605332226674, 4408.713433626804, 4430.645548356124, 4450.532694067141, 5276.841694749163, 5280.419153893069, 5290.814821481268, 5298.231756395301, 5307.65500242483, 3694.2057457646465, 3730.6269373062746, 3738.018850009499, 3745.550533935945, 3758.0980564664555, 5440.646357986719, 5530.640508366943, 5578.358488773762, 5587.636803607753, 5607.878209516002, 3757.140215593244, 3760.2291530236234, 3766.6798322142376, 3775.528058992626, 3790.6967442604528, 3215.742740580748, 3500.5099286099967, 3571.4107172316794, 3822.2164456286396, 3849.230153969161, 3690.4648825920626, 4192.538820729717, 4410.977945110254, 4426.480947646629, 4435.691471016058, 5313.309269794902, 5324.840254792362, 5333.733313334318, 5354.36691809002, 5417.891642167214, 1080.125098670064, 1229.7724920889664, 1232.9075319351084, 1244.0918129765323, 1246.7880460321767, 739.9001134846727, 795.1531618825932, 840.5125027484045, 860.2257967829004, 860.4579115157411, 4293.604578511934, 4797.71356620709, 4806.711597304173, 4817.494163112823, 4843.6367272454, 4944.60443164546, 5132.769025393866, 5147.485251474823, 5154.252633368141, 5168.8449346519665, 3795.9430608540893, 4107.034846810997, 4402.559744025636, 4516.503184649686, 4847.95152048482, 2652.2441104285185, 2676.183763952013, 2678.7455191756753, 2678.7723043541127, 2689.314224872645, 5290.391604965402, 5438.356301278153, 5463.945360546423, 5468.261784659099, 5521.420250873601, 4185.079282557833, 4433.091216300715, 4666.836660716926, 5245.26566280721, 5292.655977361459, 5001.974595207974, 5362.0, 5406.37790671524, 5475.616706830484, 5476.342838859283, 4762.809487620595, 4898.118338698926, 4951.554360107608, 5133.383993920754, 5160.684025573455, 4208.32666773327, 4421.778911054499, 4956.479569645136, 5063.291139240436, 5097.39432078894, 4696.671233013656, 4710.387649605529, 4915.7542122893, 5040.664223980575, 5474.671519708831, 3064.6169228846393, 3567.3935430977317, 3604.459331100356, 3604.459331100356, 3644.799536025544, 4778.682339065481, 4909.262561627904, 4947.3321101652255, 5086.3795505966855, 5125.026245013435, 4246.766427846498, 4250.681198910032, 4413.713108647928, 4924.039812236722, 4979.651424400257, 4279.373837941686, 4422.402975598382, 4808.3989501312335, 4948.480409556944, 4974.776135074003, 4011.950917302929, 4579.771011449481, 4606.56237657422, 4716.0, 4774.546418090275, 4681.906361872675, 4802.783874725716, 4999.725015124203, 5049.065922804293, 5212.0030982184635, 4336.005437498786, 4402.0, 4454.420925279693, 5165.966806638762, 5186.9221961669855], "value": 3759.9121122082747, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [669.63170256359, 706.4913262451036, 721.0518168608284, 724.1563578431128, 750.3921823323105, 13971.650708732273, 14829.925850370733, 14854.702905941875, 14901.18043507608, 14909.925450372733, 13320.933395333033, 13467.5286364977, 13476.865231347683, 13481.528146514875, 13500.594982150527, 644.3974883483934, 666.1273731411842, 668.4585485756528, 800.9639566219535, 816.3550794872059, 808.7978005498626, 814.2305521282391, 825.6119623776815, 826.3100311240122, 830.3730683334074, 893.1604291965532, 893.2407453664366, 893.6470094312742, 894.4946105450432, 896.3725392225435, 886.0342226972566, 886.3352485635774, 887.3655336434416, 888.6223355074133, 889.3507739350279, 510.3416592595547, 530.1067700923955, 625.9217597800275, 640.4428147511649, 642.3031011352688, 13882.0, 13907.930460347734, 13930.651733706638, 13932.303384830719, 13935.512257070954, 11294.661160165208, 12575.685607859878, 12641.93679031599, 12961.416736246798, 12969.0, 11583.536658533649, 11732.589359372381, 11745.119116066244, 11790.0, 12388.380580970916, 4282.678799090049, 4389.473263208403, 4391.824327026915, 4419.160359531711, 4466.491413068753, 85765.0, 85832.57083714604, 89302.5534872328, 92648.53675731579, 92658.5367073167, 9875.506224688737, 10240.436775977243, 10518.163268345817, 10542.367457952547, 10731.731706707238, 19270.90364548196, 19800.70298945517, 19922.30271940475, 19970.0, 20025.99870006523, 7016.8071427857385, 7413.592252426169, 7435.367993720486, 7474.289942455458, 7545.962270188614, 4238.470191226097, 4241.957580424156, 4266.658667306608, 4310.655147588248, 4422.867313980586, 3128.264857758425, 3219.935601287961, 3229.1927018245437, 3237.43344914639, 3238.239013831748, 608.4159207161175, 614.1371373220633, 615.4952938590313, 630.1618846933567, 632.5256057956533, 583.276736846316, 585.6808039618332, 601.8736065426222, 611.7338957553478, 617.4288782875822, 8019.478733882279, 8321.875171872427, 8491.980962284566, 8546.2308392245, 8581.184787445183, 519.3222844188296, 532.4170033812968, 613.8404014956144, 628.1425853709744, 630.8328293002266, 3834.5206849143856, 4030.576789437067, 4173.520045194841, 4358.869233922987, 4400.295952647561, 85190.0, 99796.0, 100443.49778251062, 106788.46605766921, 110218.44890775495, 60372.69813650991, 93275.54229094501, 97194.0, 97332.51333743427, 99543.50228248957, 82328.17671823205, 84012.57993710115, 84640.7679616029, 85749.0, 85818.57090714756, 8557.48655080697, 8645.91354086451, 8658.523781192094, 8754.0, 8795.780105497413, 49908.50091499184, 51618.0, 52507.73746131245, 52913.20630190627, 53766.46233537615, 74809.6259518699, 78164.2183578157, 78371.98722147026, 78424.0, 79981.60009199916, 11072.0, 11470.770584588428, 11550.653480395602, 11556.248843825124, 11757.941210293893, 11711.707207319885, 12098.818517722417, 12110.818337725117, 12342.691432714253, 12644.683882903, 11652.766944661229, 11738.178327517333, 11996.040316774463, 12078.51685932553, 12374.62876113718, 9441.480718560546, 11639.825402619137, 11642.94178529102, 11910.106741994212, 12417.31704756211, 11343.886561134283, 11422.371769552754, 11428.485718142538, 11631.6510504685, 11916.582919597773, 9525.651708129753, 9542.570584323583, 9580.425174489554, 9636.903630963601, 10038.096571308617, 7801.843963120819, 9354.532273386167, 9448.149666530046, 9922.801543969224, 9953.286570261394], "value": 7002.653495153684, "unit": "score"}], "name": "dromaeo_css", "value": 6929.3523688188125, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 115.0, 115.0, 115.0, 115.0, 114.0, 115.0, 114.0, 114.0, 114.0], "value": 114.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 114.0, 114.0, 149.0, 116.0, 113.0, 117.0, 116.0, 133.0, 111.0], "value": 123.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [180.0, 185.0, 188.0, 186.0, 184.0, 183.0, 183.0, 192.0, 233.0, 260.0], "value": 197.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 60.0, 60.0, 88.0, 62.0, 60.0, 59.0, 58.0, 77.0, 59.0], "value": 66.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 69.0, 87.0, 73.0, 69.0, 69.0, 81.0, 76.0, 98.0, 114.0], "value": 81.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 85.0, 87.0, 84.0, 88.0, 84.0, 86.0, 86.0, 134.0, 84.0], "value": 90.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 93.0, 94.0, 93.0, 93.0, 93.0, 93.0, 94.0, 93.0, 100.0], "value": 93.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 85.0, 86.0, 87.0, 87.0, 85.0, 87.0, 85.0, 87.0, 87.0], "value": 86.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 54.0, 54.0, 54.0, 55.0, 54.0, 55.0, 55.0, 55.0, 55.0], "value": 54.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 71.0, 86.0, 74.0, 76.0, 73.0, 72.0, 74.0, 74.0, 74.0], "value": 74.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 87.0, 87.0, 88.0, 88.0, 86.0, 86.0, 86.0, 88.0, 89.0], "value": 87.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 145.0, 142.0, 179.0, 157.0, 159.0, 152.0, 150.0, 198.0, 168.0], "value": 160.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [175.0, 176.0, 175.0, 181.0, 176.0, 176.0, 175.0, 177.0, 175.0, 175.0], "value": 176.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 68.0, 67.0, 68.0, 67.0, 70.0, 68.0, 67.0, 65.0, 81.0], "value": 68.9, "unit": "score"}], "name": "kraken", "value": 1475.5, "alertThreshold": 2.0}]} 05:49:35 INFO - Return code: 0 05:49:35 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 05:49:35 INFO - # TBPL SUCCESS # 05:49:35 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:49:35 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:49:35 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:49:35 INFO - 2016-11-23 05:49:35.859 screenresolution[1915:19110] starting screenresolution argv=screenresolution get 05:49:35 INFO - 2016-11-23 05:49:35.861 screenresolution[1915:19110] Display 0: 1600x1200x32@50 05:49:35 INFO - 2016-11-23 05:49:35.868 screenresolution[1916:19112] starting screenresolution argv=screenresolution list 05:49:35 INFO - Available Modes on Display 0 05:49:35 INFO - 1600x1200x32@50 1152x870x32@75 1280x1024x32@75 05:49:35 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:49:35 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:49:35 INFO - 640x480x32@60 1280x1024x32@60 1600x1200x32@60 05:49:35 INFO - 1280x960x32@60 1280x960x32@75 1344x1008x32@50 05:49:36 INFO - Graphics/Displays: 05:49:36 INFO - Intel Iris: 05:49:36 INFO - Chipset Model: Intel Iris 05:49:36 INFO - Type: GPU 05:49:36 INFO - Bus: Built-In 05:49:36 INFO - VRAM (Dynamic, Max): 1536 MB 05:49:36 INFO - Vendor: Intel (0x8086) 05:49:36 INFO - Device ID: 0x0a2e 05:49:36 INFO - Revision ID: 0x0009 05:49:36 INFO - Displays: 05:49:36 INFO - Display: 05:49:36 INFO - Resolution: 1600 x 1200 @ 50 Hz 05:49:36 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:49:36 INFO - Display Serial Number: 1600x1200 50 05:49:36 INFO - Main Display: Yes 05:49:36 INFO - Mirror: Off 05:49:36 INFO - Online: Yes 05:49:36 INFO - Rotation: Supported 05:49:36 INFO - Return code: 0 05:49:36 INFO - Running post-action listener: _resource_record_post_action 05:49:36 INFO - [mozharness: 2016-11-23 13:49:36.115498Z] Finished run-tests step (success) 05:49:36 INFO - Running post-run listener: _resource_record_post_run 05:49:36 INFO - Total resource usage - Wall time: 1215s; CPU: 54.0%; Read bytes: 606208; Write bytes: 689555968; Read time: 7; Write time: 2440 05:49:36 INFO - TinderboxPrint: CPU usage
53.7% 05:49:36 INFO - TinderboxPrint: I/O read bytes / time
606,208 / 7 05:49:36 INFO - TinderboxPrint: I/O write bytes / time
689,555,968 / 2,440 05:49:36 INFO - TinderboxPrint: CPU idle
2,252.6 (46.3%) 05:49:36 INFO - TinderboxPrint: CPU system
973.5 (20.0%) 05:49:36 INFO - TinderboxPrint: CPU user
1,635.6 (33.6%) 05:49:36 INFO - TinderboxPrint: Swap in / out
382,906,368 / 0 05:49:36 INFO - install - Wall time: 33s; CPU: 54.0%; Read bytes: 0; Write bytes: 386035200; Read time: 0; Write time: 834 05:49:36 INFO - run-tests - Wall time: 1183s; CPU: 54.0%; Read bytes: 512000; Write bytes: 302148608; Read time: 5; Write time: 1599 05:49:36 INFO - Running post-run listener: _upload_blobber_files 05:49:36 INFO - Blob upload gear active. 05:49:36 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:49:36 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:36 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:36 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:37 INFO - (blobuploader) - INFO - Open directory for files ... 05:49:37 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 05:49:37 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:49:37 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:49:37 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 05:49:37 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:49:37 INFO - (blobuploader) - INFO - Done attempting. 05:49:37 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 05:49:37 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:49:37 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:49:38 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 05:49:38 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:49:38 INFO - (blobuploader) - INFO - Done attempting. 05:49:38 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 05:49:38 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:49:38 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:49:40 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 05:49:40 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:49:40 INFO - (blobuploader) - INFO - Done attempting. 05:49:40 INFO - (blobuploader) - INFO - Iteration through files over. 05:49:40 INFO - Return code: 0 05:49:40 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:49:40 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:49:40 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/33a3eaf18374a14b29e4ae2bfec52bec73b88a3a20ec31e34745bad52193b98f7a0f2d202a512881c475d2a67d2c7d337ada161fd7c875b156c6f29436c976a8", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a841925d985eed5672630583d06f253f394f27ef95ad917fec85334b7e79c57f800beab31c7d7e392ad080ac957160bad21f2921568dcd0520f63a6eba8b8d08", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/cc8ca0ff417aa02649c3356f575b8c0cab365fe2954fb1ee2084a8bfacfa511e9ad360cc039556c65277aafd29b740671592c63181437907fada1555e31f5d20"} 05:49:40 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:49:40 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:49:40 INFO - Contents: 05:49:40 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/33a3eaf18374a14b29e4ae2bfec52bec73b88a3a20ec31e34745bad52193b98f7a0f2d202a512881c475d2a67d2c7d337ada161fd7c875b156c6f29436c976a8", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a841925d985eed5672630583d06f253f394f27ef95ad917fec85334b7e79c57f800beab31c7d7e392ad080ac957160bad21f2921568dcd0520f63a6eba8b8d08", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/cc8ca0ff417aa02649c3356f575b8c0cab365fe2954fb1ee2084a8bfacfa511e9ad360cc039556c65277aafd29b740671592c63181437907fada1555e31f5d20"} 05:49:40 INFO - Running post-run listener: copy_logs_to_upload_dir 05:49:40 INFO - Copying logs to upload dir... 05:49:40 INFO - mkdir: /builds/slave/test/build/upload/logs 05:49:40 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1275.980913 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 16 secs) (at 2016-11-23 05:49:40.974283) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-23 05:49:40.977728) ========= 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.k6igA4CxJG/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.j4FVZuPvZN/Listeners TMPDIR=/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/33a3eaf18374a14b29e4ae2bfec52bec73b88a3a20ec31e34745bad52193b98f7a0f2d202a512881c475d2a67d2c7d337ada161fd7c875b156c6f29436c976a8", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a841925d985eed5672630583d06f253f394f27ef95ad917fec85334b7e79c57f800beab31c7d7e392ad080ac957160bad21f2921568dcd0520f63a6eba8b8d08", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/cc8ca0ff417aa02649c3356f575b8c0cab365fe2954fb1ee2084a8bfacfa511e9ad360cc039556c65277aafd29b740671592c63181437907fada1555e31f5d20"} 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.011223 build_url: 'https://queue.taskcluster.net/v1/task/XV6MW-jpSf2qB-jdeZep1A/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/33a3eaf18374a14b29e4ae2bfec52bec73b88a3a20ec31e34745bad52193b98f7a0f2d202a512881c475d2a67d2c7d337ada161fd7c875b156c6f29436c976a8", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a841925d985eed5672630583d06f253f394f27ef95ad917fec85334b7e79c57f800beab31c7d7e392ad080ac957160bad21f2921568dcd0520f63a6eba8b8d08", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/cc8ca0ff417aa02649c3356f575b8c0cab365fe2954fb1ee2084a8bfacfa511e9ad360cc039556c65277aafd29b740671592c63181437907fada1555e31f5d20"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-23 05:49:41.012582) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-23 05:49:41.012927) ========= 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.k6igA4CxJG/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.j4FVZuPvZN/Listeners TMPDIR=/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/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.005166 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-23 05:49:41.061976) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-23 05:49:41.062297) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-23 05:49:41.062620) ========= ========= Total master_lag: 0.28 =========