builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0377 starttime: 1479390319.14 results: success (0) buildid: 20161117030212 builduid: c298b52a4b464d9ea352f6c8ae53d8d8 revision: 13f49da109ea460665ad27c8497cb1489548450c ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-11-17 05:45:19.144882) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-11-17 05:45:19.145340) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-17 05:45:19.145615) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-17 05:45:19.170304) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-17 05:45:19.170670) ========= 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.WG4E4ZgQLW/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.UOENEx601L/Listeners TMPDIR=/var/folders/yn/qkptzzdx4_q5h519lzspx_l800000w/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-17 05:45:19-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 145M=0s 2016-11-17 05:45:19 (145 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.084106 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-17 05:45:19.274468) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-17 05:45:19.274781) ========= 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.WG4E4ZgQLW/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.UOENEx601L/Listeners TMPDIR=/var/folders/yn/qkptzzdx4_q5h519lzspx_l800000w/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.024157 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-17 05:45:19.322916) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-11-17 05:45:19.323285) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 13f49da109ea460665ad27c8497cb1489548450c --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 13f49da109ea460665ad27c8497cb1489548450c --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.WG4E4ZgQLW/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.UOENEx601L/Listeners TMPDIR=/var/folders/yn/qkptzzdx4_q5h519lzspx_l800000w/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-17 05:45:19,432 truncating revision to first 12 chars 2016-11-17 05:45:19,433 Setting DEBUG logging. 2016-11-17 05:45:19,433 attempt 1/10 2016-11-17 05:45:19,433 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/13f49da109ea?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-11-17 05:45:20,460 unpacking tar archive at: mozilla-central-13f49da109ea/testing/mozharness/ program finished with exit code 0 elapsedTime=1.312695 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-11-17 05:45:20.657657) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-17 05:45:20.657976) ========= script_repo_revision: 13f49da109ea460665ad27c8497cb1489548450c ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-17 05:45:20.658387) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-17 05:45:20.658653) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-17 05:45:20.676741) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2016-11-17 05:45:20.677087) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.WG4E4ZgQLW/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.UOENEx601L/Listeners TMPDIR=/var/folders/yn/qkptzzdx4_q5h519lzspx_l800000w/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:45:20 INFO - MultiFileLogger online at 20161117 05:45:20 in /builds/slave/test 05:45:20 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 05:45:20 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:45:20 INFO - {'append_to_log': False, 05:45:20 INFO - 'base_work_dir': '/builds/slave/test', 05:45:20 INFO - 'blob_upload_branch': 'Firefox', 05:45:20 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:45:20 INFO - 'branch': 'Firefox', 05:45:20 INFO - 'buildbot_json_path': 'buildprops.json', 05:45:20 INFO - 'config_files': ('talos/mac_config.py',), 05:45:20 INFO - 'default_actions': ('clobber', 05:45:20 INFO - 'read-buildbot-config', 05:45:20 INFO - 'download-and-extract', 05:45:20 INFO - 'populate-webroot', 05:45:20 INFO - 'create-virtualenv', 05:45:20 INFO - 'install', 05:45:20 INFO - 'run-tests'), 05:45:20 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:45:20 INFO - 'download_minidump_stackwalk': True, 05:45:20 INFO - 'download_symbols': 'ondemand', 05:45:20 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:45:20 INFO - 'tooltool.py': '/tools/tooltool.py', 05:45:20 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:45:20 INFO - '/tools/misc-python/virtualenv.py')}, 05:45:20 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:45:20 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:45:20 INFO - 'installer_path': 'installer.exe', 05:45:20 INFO - 'log_level': 'info', 05:45:20 INFO - 'log_name': 'talos', 05:45:20 INFO - 'log_to_console': True, 05:45:20 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 05:45:20 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 05:45:20 INFO - 'opt_config_files': (), 05:45:20 INFO - 'pip_index': False, 05:45:20 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:45:20 INFO - 'cmd': ('bash', 05:45:20 INFO - '-c', 05:45:20 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:45:20 INFO - 'enabled': True, 05:45:20 INFO - 'halt_on_failure': False, 05:45:20 INFO - 'name': 'check_screen_resolution'},), 05:45:20 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:45:20 INFO - 'cmd': ('bash', 05:45:20 INFO - '-c', 05:45:20 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:45:20 INFO - 'enabled': True, 05:45:20 INFO - 'halt_on_failure': False, 05:45:20 INFO - 'name': 'check_screen_resolution'},), 05:45:20 INFO - 'run_cmd_checks_enabled': True, 05:45:20 INFO - 'sps_profile': False, 05:45:20 INFO - 'sps_profile_interval': 0, 05:45:20 INFO - 'suite': 'dromaeojs-e10s', 05:45:20 INFO - 'system_bits': '32', 05:45:20 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:45:20 INFO - 'title': 't-yosemite-r7-0377', 05:45:20 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:45:20 INFO - 'use_talos_json': True, 05:45:20 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:45:20 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:45:20 INFO - 'work_dir': 'build'} 05:45:20 INFO - [mozharness: 2016-11-17 13:45:20.879358Z] Running clobber step. 05:45:20 INFO - Running pre-action listener: _resource_record_pre_action 05:45:20 INFO - Running main action method: clobber 05:45:20 INFO - rmtree: /builds/slave/test/build 05:45:20 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:45:23 INFO - Running post-action listener: _resource_record_post_action 05:45:23 INFO - [mozharness: 2016-11-17 13:45:23.128762Z] Finished clobber step (success) 05:45:23 INFO - [mozharness: 2016-11-17 13:45:23.128900Z] Running read-buildbot-config step. 05:45:23 INFO - Running pre-action listener: _resource_record_pre_action 05:45:23 INFO - Running main action method: read_buildbot_config 05:45:23 INFO - Using buildbot properties: 05:45:23 INFO - { 05:45:23 INFO - "project": "", 05:45:23 INFO - "product": "firefox", 05:45:23 INFO - "script_repo_revision": "production", 05:45:23 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 05:45:23 INFO - "repository": "", 05:45:23 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 05:45:23 INFO - "buildid": "20161117030212", 05:45:23 INFO - "stage_platform": "macosx64", 05:45:23 INFO - "basedir": "/builds/slave/test", 05:45:23 INFO - "nightly_build": "True", 05:45:23 INFO - "buildnumber": 26, 05:45:23 INFO - "slavename": "t-yosemite-r7-0377", 05:45:23 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 05:45:23 INFO - "platform": "yosemite_r7", 05:45:23 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 05:45:23 INFO - "branch": "mozilla-central", 05:45:23 INFO - "repo_path": "mozilla-central", 05:45:23 INFO - "revision": "13f49da109ea460665ad27c8497cb1489548450c", 05:45:23 INFO - "pgo_build": "False", 05:45:23 INFO - "builduid": "c298b52a4b464d9ea352f6c8ae53d8d8", 05:45:23 INFO - "slavebuilddir": "test" 05:45:23 INFO - } 05:45:23 INFO - Found installer url https://queue.taskcluster.net/v1/task/MsCrFnpwQE-iTu3tgEVw1g/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg. 05:45:23 INFO - Running post-action listener: _resource_record_post_action 05:45:23 INFO - [mozharness: 2016-11-17 13:45:23.132483Z] Finished read-buildbot-config step (success) 05:45:23 INFO - [mozharness: 2016-11-17 13:45:23.132611Z] Running download-and-extract step. 05:45:23 INFO - Running pre-action listener: _resource_record_pre_action 05:45:23 INFO - Running main action method: download_and_extract 05:45:23 INFO - mkdir: /builds/slave/test/build/tests 05:45:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:45:23 INFO - trying https://queue.taskcluster.net/v1/task/MsCrFnpwQE-iTu3tgEVw1g/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json 05:45:23 INFO - Downloading https://queue.taskcluster.net/v1/task/MsCrFnpwQE-iTu3tgEVw1g/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:45:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/MsCrFnpwQE-iTu3tgEVw1g/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:45:24 INFO - Downloaded 1199 bytes. 05:45:24 INFO - Reading from file /builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json 05:45:24 INFO - Using the following test package requirements: 05:45:24 INFO - {u'common': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 05:45:24 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 05:45:24 INFO - u'firefox-53.0a1.en-US.mac.cppunittest.tests.zip'], 05:45:24 INFO - u'gtest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 05:45:24 INFO - u'firefox-53.0a1.en-US.mac.gtest.tests.zip'], 05:45:24 INFO - u'jittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 05:45:24 INFO - u'jsshell-mac.zip'], 05:45:24 INFO - u'mochitest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 05:45:24 INFO - u'firefox-53.0a1.en-US.mac.mochitest.tests.zip'], 05:45:24 INFO - u'mozbase': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 05:45:24 INFO - u'reftest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 05:45:24 INFO - u'firefox-53.0a1.en-US.mac.reftest.tests.zip'], 05:45:24 INFO - u'talos': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 05:45:24 INFO - u'firefox-53.0a1.en-US.mac.talos.tests.zip'], 05:45:24 INFO - u'web-platform': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 05:45:24 INFO - u'firefox-53.0a1.en-US.mac.web-platform.tests.zip'], 05:45:24 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 05:45:24 INFO - u'firefox-53.0a1.en-US.mac.xpcshell.tests.zip']} 05:45:24 INFO - Downloading packages: [u'firefox-53.0a1.en-US.mac.common.tests.zip'] for test suite category: common 05:45:24 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/MsCrFnpwQE-iTu3tgEVw1g/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 05:45:24 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/MsCrFnpwQE-iTu3tgEVw1g/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 05:45:24 INFO - Fetch https://queue.taskcluster.net/v1/task/MsCrFnpwQE-iTu3tgEVw1g/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 05:45:24 INFO - Http code: 200 05:45:24 INFO - via: 1.1 41b2ee7cbe95749816a7586d9ab4629d.cloudfront.net (CloudFront) 05:45:24 INFO - x-amz-cf-id: kT2u3BLWDiODh1VXj9C5-9NJT8R-upc8hfZ-4diroy3hFi_q__fJtA== 05:45:24 INFO - x-amz-version-id: 5nwTwTv3uUsT3JHyCUQLAOH6tCbKURlk 05:45:25 INFO - Expected file size: 16273550 05:45:25 INFO - Obtained file size: 16273550 05:45:31 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:45:31 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/MsCrFnpwQE-iTu3tgEVw1g/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 05:45:31 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/MsCrFnpwQE-iTu3tgEVw1g/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 05:45:31 INFO - Fetch https://queue.taskcluster.net/v1/task/MsCrFnpwQE-iTu3tgEVw1g/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 05:45:32 INFO - Http code: 200 05:45:32 INFO - via: 1.1 441811a054e8d055b893175754efd0c3.cloudfront.net (CloudFront) 05:45:32 INFO - x-amz-cf-id: h-rKQLL9azc8vW9gWyaok-rz2dZugZL4CfrCh01s44imN162I7Nlxw== 05:45:32 INFO - x-amz-version-id: 5nwTwTv3uUsT3JHyCUQLAOH6tCbKURlk 05:45:32 INFO - Expected file size: 16273550 05:45:32 INFO - Obtained file size: 16273550 05:45:38 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/MsCrFnpwQE-iTu3tgEVw1g/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip 05:45:38 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/MsCrFnpwQE-iTu3tgEVw1g/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip'}, attempt #1 05:45:38 INFO - Fetch https://queue.taskcluster.net/v1/task/MsCrFnpwQE-iTu3tgEVw1g/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip into memory 05:45:39 INFO - Http code: 200 05:45:39 INFO - via: 1.1 9552af4ceb2e0dd55964a80d1e549fd5.cloudfront.net (CloudFront) 05:45:39 INFO - x-amz-cf-id: BDjbmpu605aOOMnTDnpzdpbBeK9yoUjzb3HCx1njGJ0qzbPP21n9yA== 05:45:39 INFO - x-amz-version-id: SN272BGxZCqEwR38b36OmXBPUR1rGDmh 05:45:39 INFO - Expected file size: 14409389 05:45:39 INFO - Obtained file size: 14409389 05:45:40 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:45:40 INFO - trying https://queue.taskcluster.net/v1/task/MsCrFnpwQE-iTu3tgEVw1g/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 05:45:40 INFO - Downloading https://queue.taskcluster.net/v1/task/MsCrFnpwQE-iTu3tgEVw1g/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg 05:45:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/MsCrFnpwQE-iTu3tgEVw1g/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:45:42 INFO - Downloaded 111126680 bytes. 05:45:42 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/MsCrFnpwQE-iTu3tgEVw1g/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 05:45:42 INFO - mkdir: /builds/slave/test/properties 05:45:42 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:45:42 INFO - Writing to file /builds/slave/test/properties/build_url 05:45:42 INFO - Contents: 05:45:42 INFO - build_url:https://queue.taskcluster.net/v1/task/MsCrFnpwQE-iTu3tgEVw1g/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 05:45:43 INFO - Running post-action listener: _resource_record_post_action 05:45:43 INFO - Running post-action listener: set_extra_try_arguments 05:45:43 INFO - [mozharness: 2016-11-17 13:45:43.612740Z] Finished download-and-extract step (success) 05:45:43 INFO - [mozharness: 2016-11-17 13:45:43.612893Z] Running populate-webroot step. 05:45:43 INFO - Running pre-action listener: _resource_record_pre_action 05:45:43 INFO - Running main action method: populate_webroot 05:45:43 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:45:43 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:45:43 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:45:43 INFO - '--tppagecycles', 05:45:43 INFO - '7'], 05:45:43 INFO - 'tests': ['tsvgm']}}, 05:45:43 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 05:45:43 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:45:43 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:45:43 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 05:45:43 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:45:43 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:45:43 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 05:45:43 INFO - 'talos_options': ['--disable-e10s'], 05:45:43 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:45:43 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 05:45:43 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:45:43 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 05:45:43 INFO - 'talos_options': ['--disable-e10s'], 05:45:43 INFO - 'tests': ['damp', 'tps']}, 05:45:43 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 05:45:43 INFO - 'tests': ['damp', 'tps']}, 05:45:43 INFO - 'g3': {'talos_options': ['--disable-e10s'], 05:45:43 INFO - 'tests': ['dromaeo_dom']}, 05:45:43 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:45:43 INFO - 'g4': {'talos_options': ['--disable-e10s'], 05:45:43 INFO - 'tests': ['basic_compositor_video']}, 05:45:43 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 05:45:43 INFO - 'other': {'talos_options': ['--disable-e10s'], 05:45:43 INFO - 'tests': ['a11yr', 05:45:43 INFO - 'ts_paint', 05:45:43 INFO - 'tpaint', 05:45:43 INFO - 'sessionrestore', 05:45:43 INFO - 'sessionrestore_no_auto_restore', 05:45:43 INFO - 'tabpaint']}, 05:45:43 INFO - 'other-e10s': {'tests': ['a11yr', 05:45:43 INFO - 'ts_paint', 05:45:43 INFO - 'tpaint', 05:45:43 INFO - 'sessionrestore', 05:45:43 INFO - 'sessionrestore_no_auto_restore', 05:45:43 INFO - 'tabpaint']}, 05:45:43 INFO - 'other-e10s_l64': {'tests': ['a11yr', 05:45:43 INFO - 'ts_paint', 05:45:43 INFO - 'tpaint', 05:45:43 INFO - 'sessionrestore', 05:45:43 INFO - 'sessionrestore_no_auto_restore', 05:45:43 INFO - 'tabpaint']}, 05:45:43 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 05:45:43 INFO - 'ts_paint', 05:45:43 INFO - 'tpaint', 05:45:43 INFO - 'sessionrestore', 05:45:43 INFO - 'sessionrestore_no_auto_restore', 05:45:43 INFO - 'tabpaint']}, 05:45:43 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 05:45:43 INFO - 'tests': ['a11yr', 05:45:43 INFO - 'ts_paint', 05:45:43 INFO - 'tpaint', 05:45:43 INFO - 'sessionrestore', 05:45:43 INFO - 'sessionrestore_no_auto_restore', 05:45:43 INFO - 'tabpaint']}, 05:45:43 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 05:45:43 INFO - 'tests': ['a11yr', 05:45:43 INFO - 'ts_paint', 05:45:43 INFO - 'tpaint', 05:45:43 INFO - 'sessionrestore', 05:45:43 INFO - 'sessionrestore_no_auto_restore', 05:45:43 INFO - 'tabpaint']}, 05:45:43 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 05:45:43 INFO - 'tests': ['tsvgx', 05:45:43 INFO - 'tsvgr_opacity', 05:45:43 INFO - 'tart', 05:45:43 INFO - 'tscrollx', 05:45:43 INFO - 'cart']}, 05:45:43 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:45:43 INFO - 'tsvgr_opacity', 05:45:43 INFO - 'tart', 05:45:43 INFO - 'tscrollx', 05:45:43 INFO - 'cart']}, 05:45:43 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 05:45:43 INFO - 'talos_options': ['--disable-e10s'], 05:45:43 INFO - 'tests': ['tp5o']}, 05:45:43 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 05:45:43 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 05:45:43 INFO - 'talos_options': ['--disable-e10s', 05:45:43 INFO - '--xperf_path', 05:45:43 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:45:43 INFO - 'tests': ['tp5n']}, 05:45:43 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 05:45:43 INFO - 'talos_options': ['--xperf_path', 05:45:43 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:45:43 INFO - 'tests': ['tp5n']}}, 05:45:43 INFO - 'talos.zip': {'path': '', 05:45:43 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:45:43 INFO - Running post-action listener: _resource_record_post_action 05:45:43 INFO - [mozharness: 2016-11-17 13:45:43.630981Z] Finished populate-webroot step (success) 05:45:43 INFO - [mozharness: 2016-11-17 13:45:43.631073Z] Running create-virtualenv step. 05:45:43 INFO - Running pre-action listener: _resource_record_pre_action 05:45:43 INFO - Running main action method: create_virtualenv 05:45:43 INFO - Creating virtualenv /builds/slave/test/build/venv 05:45:43 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:45:43 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:45:43 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:45:43 INFO - Using real prefix '/tools/python27' 05:45:43 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:45:44 INFO - Installing distribute.............................................................................................................................................................................................done. 05:45:47 INFO - Installing pip.................done. 05:45:47 INFO - Return code: 0 05:45:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 05:45:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 05:45:47 INFO - Reading from file tmpfile_stdout 05:45:47 INFO - Output received: 05:45:47 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:45:47 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:45:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:45:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:45:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:45:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:45:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:45:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:45:47 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 0x1060d23f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106048570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9ea8c68690>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105ee8d30>, '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 0x105d4fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105ee0b20>, '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.WG4E4ZgQLW/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.UOENEx601L/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yn/qkptzzdx4_q5h519lzspx_l800000w/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:45:47 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:45:47 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:45:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WG4E4ZgQLW/Render', 05:45:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:45:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:45:47 INFO - 'HOME': '/Users/cltbld', 05:45:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:45:47 INFO - 'LOGNAME': 'cltbld', 05:45:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:45:47 INFO - 'MOZ_NO_REMOTE': '1', 05:45:47 INFO - 'NO_EM_RESTART': '1', 05:45:47 INFO - 'PAGER': '/bin/cat', 05:45:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:45:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:45:47 INFO - 'PWD': '/builds/slave/test', 05:45:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:45:47 INFO - 'SHELL': '/bin/bash', 05:45:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UOENEx601L/Listeners', 05:45:47 INFO - 'TMPDIR': '/var/folders/yn/qkptzzdx4_q5h519lzspx_l800000w/T/', 05:45:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:45:47 INFO - 'USER': 'cltbld', 05:45:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:45:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:45:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:45:47 INFO - 'XPC_FLAGS': '0x0', 05:45:47 INFO - 'XPC_SERVICE_NAME': '0', 05:45:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:45:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:45:47 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:45:47 INFO - Cleaning up... 05:45:47 INFO - Return code: 0 05:45:47 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 05:45:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:45:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:45:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:45:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:45:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:45:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:45:47 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 0x1060d23f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106048570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9ea8c68690>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105ee8d30>, '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 0x105d4fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105ee0b20>, '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.WG4E4ZgQLW/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.UOENEx601L/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yn/qkptzzdx4_q5h519lzspx_l800000w/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:45:47 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:45:47 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:45:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WG4E4ZgQLW/Render', 05:45:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:45:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:45:47 INFO - 'HOME': '/Users/cltbld', 05:45:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:45:47 INFO - 'LOGNAME': 'cltbld', 05:45:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:45:47 INFO - 'MOZ_NO_REMOTE': '1', 05:45:47 INFO - 'NO_EM_RESTART': '1', 05:45:47 INFO - 'PAGER': '/bin/cat', 05:45:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:45:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:45:47 INFO - 'PWD': '/builds/slave/test', 05:45:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:45:47 INFO - 'SHELL': '/bin/bash', 05:45:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UOENEx601L/Listeners', 05:45:47 INFO - 'TMPDIR': '/var/folders/yn/qkptzzdx4_q5h519lzspx_l800000w/T/', 05:45:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:45:47 INFO - 'USER': 'cltbld', 05:45:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:45:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:45:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:45:47 INFO - 'XPC_FLAGS': '0x0', 05:45:47 INFO - 'XPC_SERVICE_NAME': '0', 05:45:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:45:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:45:48 INFO - Downloading/unpacking psutil>=3.1.1 05:45:48 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:45:48 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:45:48 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:45:48 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:45:48 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:45:48 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:45:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:45:51 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:45:51 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:45:51 INFO - Installing collected packages: psutil 05:45:51 INFO - Running setup.py install for psutil 05:45:51 INFO - building 'psutil._psutil_osx' extension 05:45:51 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:45:52 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:45:52 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:45:52 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:45:52 INFO - building 'psutil._psutil_posix' extension 05:45:52 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:45:52 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 05:45:52 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 05:45:52 INFO - ^ 05:45:52 INFO - 1 warning generated. 05:45:52 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:45:52 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:45:52 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:45:52 INFO - Successfully installed psutil 05:45:52 INFO - Cleaning up... 05:45:52 INFO - Return code: 0 05:45:52 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 05:45:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:45:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:45:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:45:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:45:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:45:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:45:52 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 0x1060d23f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106048570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9ea8c68690>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105ee8d30>, '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 0x105d4fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105ee0b20>, '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.WG4E4ZgQLW/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.UOENEx601L/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yn/qkptzzdx4_q5h519lzspx_l800000w/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:45:52 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:45:52 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:45:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WG4E4ZgQLW/Render', 05:45:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:45:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:45:52 INFO - 'HOME': '/Users/cltbld', 05:45:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:45:52 INFO - 'LOGNAME': 'cltbld', 05:45:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:45:52 INFO - 'MOZ_NO_REMOTE': '1', 05:45:52 INFO - 'NO_EM_RESTART': '1', 05:45:52 INFO - 'PAGER': '/bin/cat', 05:45:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:45:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:45:52 INFO - 'PWD': '/builds/slave/test', 05:45:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:45:52 INFO - 'SHELL': '/bin/bash', 05:45:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UOENEx601L/Listeners', 05:45:52 INFO - 'TMPDIR': '/var/folders/yn/qkptzzdx4_q5h519lzspx_l800000w/T/', 05:45:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:45:52 INFO - 'USER': 'cltbld', 05:45:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:45:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:45:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:45:52 INFO - 'XPC_FLAGS': '0x0', 05:45:52 INFO - 'XPC_SERVICE_NAME': '0', 05:45:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:45:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:45:52 INFO - Downloading/unpacking mozsystemmonitor==0.3 05:45: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:45: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:45: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:45: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:45: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:45: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:45:55 INFO - Downloading mozsystemmonitor-0.3.tar.gz 05:45:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:45:55 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 05:45:55 INFO - Installing collected packages: mozsystemmonitor 05:45:55 INFO - Running setup.py install for mozsystemmonitor 05:45:55 INFO - Successfully installed mozsystemmonitor 05:45:55 INFO - Cleaning up... 05:45:55 INFO - Return code: 0 05:45:55 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 05:45:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:45:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:45:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:45:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:45:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:45:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:45:55 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1060d23f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106048570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9ea8c68690>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105ee8d30>, '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 0x105d4fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105ee0b20>, '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.WG4E4ZgQLW/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.UOENEx601L/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yn/qkptzzdx4_q5h519lzspx_l800000w/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:45:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 05:45:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 05:45:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WG4E4ZgQLW/Render', 05:45:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:45:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:45:55 INFO - 'HOME': '/Users/cltbld', 05:45:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:45:55 INFO - 'LOGNAME': 'cltbld', 05:45:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:45:55 INFO - 'MOZ_NO_REMOTE': '1', 05:45:55 INFO - 'NO_EM_RESTART': '1', 05:45:55 INFO - 'PAGER': '/bin/cat', 05:45:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:45:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:45:55 INFO - 'PWD': '/builds/slave/test', 05:45:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:45:55 INFO - 'SHELL': '/bin/bash', 05:45:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UOENEx601L/Listeners', 05:45:55 INFO - 'TMPDIR': '/var/folders/yn/qkptzzdx4_q5h519lzspx_l800000w/T/', 05:45:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:45:55 INFO - 'USER': 'cltbld', 05:45:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:45:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:45:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:45:55 INFO - 'XPC_FLAGS': '0x0', 05:45:55 INFO - 'XPC_SERVICE_NAME': '0', 05:45:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:45:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:45:55 INFO - Downloading/unpacking jsonschema==2.5.1 05:45:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:45:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:45:55 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:45:55 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:45:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:45:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:45:58 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:45:58 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 05:45:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:45:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:45:58 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:45:58 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:45:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:45:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:45:58 INFO - Downloading functools32-3.2.3-2.tar.gz 05:45:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:45:58 INFO - warning: no files found matching '*.txt' 05:45:58 INFO - no previously-included directories found matching 'build' 05:45:58 INFO - no previously-included directories found matching 'dist' 05:45:58 INFO - no previously-included directories found matching '.git*' 05:45:58 INFO - Installing collected packages: jsonschema, functools32 05:45:58 INFO - Running setup.py install for functools32 05:45:59 INFO - warning: no files found matching '*.txt' 05:45:59 INFO - no previously-included directories found matching 'build' 05:45:59 INFO - no previously-included directories found matching 'dist' 05:45:59 INFO - no previously-included directories found matching '.git*' 05:45:59 INFO - Successfully installed jsonschema functools32 05:45:59 INFO - Cleaning up... 05:45:59 INFO - Return code: 0 05:45:59 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 05:45: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:45:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:45:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:45: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:45:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:45:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:45: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', '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 0x1060d23f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106048570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9ea8c68690>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105ee8d30>, '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 0x105d4fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105ee0b20>, '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.WG4E4ZgQLW/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.UOENEx601L/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yn/qkptzzdx4_q5h519lzspx_l800000w/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:45: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', 'functools32==3.2.3-2'] in /builds/slave/test/build 05:45: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 functools32==3.2.3-2 05:45:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WG4E4ZgQLW/Render', 05:45:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:45:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:45:59 INFO - 'HOME': '/Users/cltbld', 05:45:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:45:59 INFO - 'LOGNAME': 'cltbld', 05:45:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:45:59 INFO - 'MOZ_NO_REMOTE': '1', 05:45:59 INFO - 'NO_EM_RESTART': '1', 05:45:59 INFO - 'PAGER': '/bin/cat', 05:45:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:45:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:45:59 INFO - 'PWD': '/builds/slave/test', 05:45:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:45:59 INFO - 'SHELL': '/bin/bash', 05:45:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UOENEx601L/Listeners', 05:45:59 INFO - 'TMPDIR': '/var/folders/yn/qkptzzdx4_q5h519lzspx_l800000w/T/', 05:45:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:45:59 INFO - 'USER': 'cltbld', 05:45:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:45:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:45:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:45:59 INFO - 'XPC_FLAGS': '0x0', 05:45:59 INFO - 'XPC_SERVICE_NAME': '0', 05:45:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:45:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:45:59 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 05:45:59 INFO - Cleaning up... 05:45:59 INFO - Return code: 0 05:45:59 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:45: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:45:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:45:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:45: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:45:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:45:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:45: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', '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 0x1060d23f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106048570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9ea8c68690>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105ee8d30>, '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 0x105d4fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105ee0b20>, '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.WG4E4ZgQLW/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.UOENEx601L/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yn/qkptzzdx4_q5h519lzspx_l800000w/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:45: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', 'blobuploader==1.2.4'] in /builds/slave/test/build 05:45: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 blobuploader==1.2.4 05:45:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WG4E4ZgQLW/Render', 05:45:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:45:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:45:59 INFO - 'HOME': '/Users/cltbld', 05:45:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:45:59 INFO - 'LOGNAME': 'cltbld', 05:45:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:45:59 INFO - 'MOZ_NO_REMOTE': '1', 05:45:59 INFO - 'NO_EM_RESTART': '1', 05:45:59 INFO - 'PAGER': '/bin/cat', 05:45:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:45:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:45:59 INFO - 'PWD': '/builds/slave/test', 05:45:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:45:59 INFO - 'SHELL': '/bin/bash', 05:45:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UOENEx601L/Listeners', 05:45:59 INFO - 'TMPDIR': '/var/folders/yn/qkptzzdx4_q5h519lzspx_l800000w/T/', 05:45:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:45:59 INFO - 'USER': 'cltbld', 05:45:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:45:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:45:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:45:59 INFO - 'XPC_FLAGS': '0x0', 05:45:59 INFO - 'XPC_SERVICE_NAME': '0', 05:45:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:45:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:45:59 INFO - Downloading/unpacking blobuploader==1.2.4 05:45: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:45: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:45: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:45: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:45: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:45: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:46:02 INFO - Downloading blobuploader-1.2.4.tar.gz 05:46:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:46:02 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:46: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:46: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:46: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:46: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:46: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:46: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:46:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:46:03 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:46: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:46: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:46: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:46: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:46: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:46: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:46:03 INFO - Downloading docopt-0.6.1.tar.gz 05:46:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:46:03 INFO - Installing collected packages: blobuploader, requests, docopt 05:46:03 INFO - Running setup.py install for blobuploader 05:46:03 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:46:03 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:46:03 INFO - Running setup.py install for requests 05:46:04 INFO - Running setup.py install for docopt 05:46:04 INFO - Successfully installed blobuploader requests docopt 05:46:04 INFO - Cleaning up... 05:46:04 INFO - Return code: 0 05:46:04 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:46:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:46:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:46:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:46:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:46:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:46:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:46:04 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 0x1060d23f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106048570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9ea8c68690>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105ee8d30>, '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 0x105d4fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105ee0b20>, '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.WG4E4ZgQLW/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.UOENEx601L/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yn/qkptzzdx4_q5h519lzspx_l800000w/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:46:04 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:46:04 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:46:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WG4E4ZgQLW/Render', 05:46:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:46:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:46:04 INFO - 'HOME': '/Users/cltbld', 05:46:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:46:04 INFO - 'LOGNAME': 'cltbld', 05:46:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:46:04 INFO - 'MOZ_NO_REMOTE': '1', 05:46:04 INFO - 'NO_EM_RESTART': '1', 05:46:04 INFO - 'PAGER': '/bin/cat', 05:46:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:46:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:46:04 INFO - 'PWD': '/builds/slave/test', 05:46:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:46:04 INFO - 'SHELL': '/bin/bash', 05:46:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UOENEx601L/Listeners', 05:46:04 INFO - 'TMPDIR': '/var/folders/yn/qkptzzdx4_q5h519lzspx_l800000w/T/', 05:46:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:46:04 INFO - 'USER': 'cltbld', 05:46:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:46:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:46:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:46:04 INFO - 'XPC_FLAGS': '0x0', 05:46:04 INFO - 'XPC_SERVICE_NAME': '0', 05:46:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:46:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:46:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:46:04 INFO - Running setup.py (path:/var/folders/yn/qkptzzdx4_q5h519lzspx_l800000w/T/pip-BoJ2bb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:46:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:46:04 INFO - Running setup.py (path:/var/folders/yn/qkptzzdx4_q5h519lzspx_l800000w/T/pip-pXlexC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:46:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:46:05 INFO - Running setup.py (path:/var/folders/yn/qkptzzdx4_q5h519lzspx_l800000w/T/pip-qkn_BK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:46:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:46:05 INFO - Running setup.py (path:/var/folders/yn/qkptzzdx4_q5h519lzspx_l800000w/T/pip-XAJI2D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:46:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:46:05 INFO - Running setup.py (path:/var/folders/yn/qkptzzdx4_q5h519lzspx_l800000w/T/pip-6HBqcQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:46:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:46:05 INFO - Running setup.py (path:/var/folders/yn/qkptzzdx4_q5h519lzspx_l800000w/T/pip-Qp0kWl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:46:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:46:05 INFO - Running setup.py (path:/var/folders/yn/qkptzzdx4_q5h519lzspx_l800000w/T/pip-Y1NR08-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:46:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:46:05 INFO - Running setup.py (path:/var/folders/yn/qkptzzdx4_q5h519lzspx_l800000w/T/pip-lLvzKp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:46:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:46:05 INFO - Running setup.py (path:/var/folders/yn/qkptzzdx4_q5h519lzspx_l800000w/T/pip-de9imd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:46:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:46:05 INFO - Running setup.py (path:/var/folders/yn/qkptzzdx4_q5h519lzspx_l800000w/T/pip-AP83iA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:46:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:46:05 INFO - Running setup.py (path:/var/folders/yn/qkptzzdx4_q5h519lzspx_l800000w/T/pip-XLKKxA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:46:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:46:05 INFO - Running setup.py (path:/var/folders/yn/qkptzzdx4_q5h519lzspx_l800000w/T/pip-5v2wrk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:46:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:46:06 INFO - Running setup.py (path:/var/folders/yn/qkptzzdx4_q5h519lzspx_l800000w/T/pip-IIW6sP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:46:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:46:06 INFO - Running setup.py (path:/var/folders/yn/qkptzzdx4_q5h519lzspx_l800000w/T/pip-CRtIpc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:46:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:46:06 INFO - Running setup.py (path:/var/folders/yn/qkptzzdx4_q5h519lzspx_l800000w/T/pip-W4fVZn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:46:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:46:06 INFO - Running setup.py (path:/var/folders/yn/qkptzzdx4_q5h519lzspx_l800000w/T/pip-kYSa92-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:46:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:46:06 INFO - Running setup.py (path:/var/folders/yn/qkptzzdx4_q5h519lzspx_l800000w/T/pip-aSQDiV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:46:06 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:46:06 INFO - Running setup.py install for manifestparser 05:46:06 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:46:06 INFO - Running setup.py install for mozcrash 05:46:06 INFO - Running setup.py install for mozdebug 05:46:07 INFO - Running setup.py install for mozdevice 05:46:07 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 05:46:07 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:46:07 INFO - Running setup.py install for mozfile 05:46:07 INFO - Running setup.py install for mozhttpd 05:46:07 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:46:07 INFO - Running setup.py install for mozinfo 05:46:07 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:46:07 INFO - Running setup.py install for mozInstall 05:46:07 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:46:07 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:46:07 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:46:07 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:46:07 INFO - Running setup.py install for mozleak 05:46:07 INFO - Running setup.py install for mozlog 05:46:08 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:46:08 INFO - Running setup.py install for moznetwork 05:46:08 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:46:08 INFO - Running setup.py install for mozprocess 05:46:08 INFO - Running setup.py install for mozprofile 05:46:08 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:46:08 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:46:08 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:46:08 INFO - Running setup.py install for mozrunner 05:46:08 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:46:08 INFO - Running setup.py install for mozscreenshot 05:46:08 INFO - Running setup.py install for moztest 05:46:09 INFO - Running setup.py install for mozversion 05:46:09 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:46:09 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:46:09 INFO - Cleaning up... 05:46:09 INFO - Return code: 0 05:46:09 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:46:09 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:46:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:46:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:46:09 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:46:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:46:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:46:09 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 0x1060d23f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106048570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9ea8c68690>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105ee8d30>, '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 0x105d4fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105ee0b20>, '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.WG4E4ZgQLW/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.UOENEx601L/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yn/qkptzzdx4_q5h519lzspx_l800000w/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:46:09 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:46:09 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:46:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WG4E4ZgQLW/Render', 05:46:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:46:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:46:09 INFO - 'HOME': '/Users/cltbld', 05:46:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:46:09 INFO - 'LOGNAME': 'cltbld', 05:46:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:46:09 INFO - 'MOZ_NO_REMOTE': '1', 05:46:09 INFO - 'NO_EM_RESTART': '1', 05:46:09 INFO - 'PAGER': '/bin/cat', 05:46:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:46:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:46:09 INFO - 'PWD': '/builds/slave/test', 05:46:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:46:09 INFO - 'SHELL': '/bin/bash', 05:46:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UOENEx601L/Listeners', 05:46:09 INFO - 'TMPDIR': '/var/folders/yn/qkptzzdx4_q5h519lzspx_l800000w/T/', 05:46:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:46:09 INFO - 'USER': 'cltbld', 05:46:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:46:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:46:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:46:09 INFO - 'XPC_FLAGS': '0x0', 05:46:09 INFO - 'XPC_SERVICE_NAME': '0', 05:46:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:46:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:46:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:46:09 INFO - Running setup.py (path:/var/folders/yn/qkptzzdx4_q5h519lzspx_l800000w/T/pip-b5P8vN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:46:09 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:46:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:46:09 INFO - Running setup.py (path:/var/folders/yn/qkptzzdx4_q5h519lzspx_l800000w/T/pip-RlNEvu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:46:09 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:46:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:46:09 INFO - Running setup.py (path:/var/folders/yn/qkptzzdx4_q5h519lzspx_l800000w/T/pip-n9dceb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:46:09 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:46:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:46:09 INFO - Running setup.py (path:/var/folders/yn/qkptzzdx4_q5h519lzspx_l800000w/T/pip-j7fJnp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:46:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:46:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:46:09 INFO - Running setup.py (path:/var/folders/yn/qkptzzdx4_q5h519lzspx_l800000w/T/pip-U6LGtd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:46:09 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:46:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:46:09 INFO - Running setup.py (path:/var/folders/yn/qkptzzdx4_q5h519lzspx_l800000w/T/pip-G8n91L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:46:10 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:46:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:46:10 INFO - Running setup.py (path:/var/folders/yn/qkptzzdx4_q5h519lzspx_l800000w/T/pip-Ef3h9Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:46:10 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:46:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:46:10 INFO - Running setup.py (path:/var/folders/yn/qkptzzdx4_q5h519lzspx_l800000w/T/pip-2m60C7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:46:10 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:46:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:46:10 INFO - Running setup.py (path:/var/folders/yn/qkptzzdx4_q5h519lzspx_l800000w/T/pip-jyM0vk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:46:10 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:46:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:46:10 INFO - Running setup.py (path:/var/folders/yn/qkptzzdx4_q5h519lzspx_l800000w/T/pip-b2KAmg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:46:10 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:46:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:46:10 INFO - Running setup.py (path:/var/folders/yn/qkptzzdx4_q5h519lzspx_l800000w/T/pip-lpaxJ7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:46:10 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:46:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:46:10 INFO - Running setup.py (path:/var/folders/yn/qkptzzdx4_q5h519lzspx_l800000w/T/pip-l2GR50-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:46:10 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:46:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:46:10 INFO - Running setup.py (path:/var/folders/yn/qkptzzdx4_q5h519lzspx_l800000w/T/pip-qo_5uO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:46:10 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:46:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:46:10 INFO - Running setup.py (path:/var/folders/yn/qkptzzdx4_q5h519lzspx_l800000w/T/pip-GpJmFP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:46:10 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:46:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:46:10 INFO - Running setup.py (path:/var/folders/yn/qkptzzdx4_q5h519lzspx_l800000w/T/pip-8c3Bvk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:46:11 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:46:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:46:11 INFO - Running setup.py (path:/var/folders/yn/qkptzzdx4_q5h519lzspx_l800000w/T/pip-l5ZmTV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:46:11 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:46:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:46:11 INFO - Running setup.py (path:/var/folders/yn/qkptzzdx4_q5h519lzspx_l800000w/T/pip-KdPNXw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:46:11 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:46:11 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:46:11 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:46:11 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:46:11 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:46:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:46:11 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:46:11 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:46:11 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:46:11 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:46:11 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:46:11 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:46:11 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:46:13 INFO - Downloading blessings-1.6.tar.gz 05:46:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:46:13 INFO - Installing collected packages: blessings 05:46:13 INFO - Running setup.py install for blessings 05:46:14 INFO - Successfully installed blessings 05:46:14 INFO - Cleaning up... 05:46:14 INFO - Return code: 0 05:46:14 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:46:14 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:46:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:46:14 INFO - Reading from file tmpfile_stdout 05:46:14 INFO - Current package versions: 05:46:14 INFO - blessings == 1.6 05:46:14 INFO - blobuploader == 1.2.4 05:46:14 INFO - docopt == 0.6.1 05:46:14 INFO - functools32 == 3.2.3-2 05:46:14 INFO - jsonschema == 2.5.1 05:46:14 INFO - manifestparser == 1.1 05:46:14 INFO - mozInstall == 1.12 05:46:14 INFO - mozcrash == 1.0 05:46:14 INFO - mozdebug == 0.1 05:46:14 INFO - mozdevice == 0.48 05:46:14 INFO - mozfile == 1.2 05:46:14 INFO - mozhttpd == 0.7 05:46:14 INFO - mozinfo == 0.9 05:46:14 INFO - mozleak == 0.1 05:46:14 INFO - mozlog == 3.4 05:46:14 INFO - moznetwork == 0.27 05:46:14 INFO - mozprocess == 0.23 05:46:14 INFO - mozprofile == 0.28 05:46:14 INFO - mozrunner == 6.13 05:46:14 INFO - mozscreenshot == 0.1 05:46:14 INFO - mozsystemmonitor == 0.3 05:46:14 INFO - moztest == 0.7 05:46:14 INFO - mozversion == 1.4 05:46:14 INFO - psutil == 3.1.1 05:46:14 INFO - requests == 1.2.3 05:46:14 INFO - wsgiref == 0.1.2 05:46:14 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:46:14 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:46:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:46:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:46:14 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:46:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:46:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:46:14 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 0x1060d23f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106048570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9ea8c68690>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105ee8d30>, '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 0x105d4fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105ee0b20>, '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.WG4E4ZgQLW/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.UOENEx601L/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yn/qkptzzdx4_q5h519lzspx_l800000w/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:46:14 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:46:14 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:46:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WG4E4ZgQLW/Render', 05:46:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:46:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:46:14 INFO - 'HOME': '/Users/cltbld', 05:46:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:46:14 INFO - 'LOGNAME': 'cltbld', 05:46:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:46:14 INFO - 'MOZ_NO_REMOTE': '1', 05:46:14 INFO - 'NO_EM_RESTART': '1', 05:46:14 INFO - 'PAGER': '/bin/cat', 05:46:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:46:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:46:14 INFO - 'PWD': '/builds/slave/test', 05:46:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:46:14 INFO - 'SHELL': '/bin/bash', 05:46:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UOENEx601L/Listeners', 05:46:14 INFO - 'TMPDIR': '/var/folders/yn/qkptzzdx4_q5h519lzspx_l800000w/T/', 05:46:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:46:14 INFO - 'USER': 'cltbld', 05:46:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:46:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:46:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:46:14 INFO - 'XPC_FLAGS': '0x0', 05:46:14 INFO - 'XPC_SERVICE_NAME': '0', 05:46:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:46:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:46:14 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:46:14 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:46:14 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:46:14 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:46:14 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:46:14 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:46:14 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:46:14 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:46:14 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:46:14 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:46:14 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:46:14 INFO - Cleaning up... 05:46:14 INFO - Return code: 0 05:46:14 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:46:14 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:46:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:46:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:46:14 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:46:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:46:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:46:14 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 0x1060d23f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106048570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9ea8c68690>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105ee8d30>, '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 0x105d4fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105ee0b20>, '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.WG4E4ZgQLW/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.UOENEx601L/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yn/qkptzzdx4_q5h519lzspx_l800000w/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:46:14 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:46:14 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:46:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WG4E4ZgQLW/Render', 05:46:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:46:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:46:14 INFO - 'HOME': '/Users/cltbld', 05:46:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:46:14 INFO - 'LOGNAME': 'cltbld', 05:46:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:46:14 INFO - 'MOZ_NO_REMOTE': '1', 05:46:14 INFO - 'NO_EM_RESTART': '1', 05:46:14 INFO - 'PAGER': '/bin/cat', 05:46:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:46:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:46:14 INFO - 'PWD': '/builds/slave/test', 05:46:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:46:14 INFO - 'SHELL': '/bin/bash', 05:46:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UOENEx601L/Listeners', 05:46:14 INFO - 'TMPDIR': '/var/folders/yn/qkptzzdx4_q5h519lzspx_l800000w/T/', 05:46:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:46:14 INFO - 'USER': 'cltbld', 05:46:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:46:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:46:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:46:14 INFO - 'XPC_FLAGS': '0x0', 05:46:14 INFO - 'XPC_SERVICE_NAME': '0', 05:46:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:46:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:46:14 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 05:46:14 INFO - Cleaning up... 05:46:14 INFO - Return code: 0 05:46:14 INFO - Running post-action listener: _resource_record_post_action 05:46:14 INFO - Running post-action listener: _start_resource_monitoring 05:46:14 INFO - Starting resource monitoring. 05:46:14 INFO - [mozharness: 2016-11-17 13:46:14.972629Z] Finished create-virtualenv step (success) 05:46:14 INFO - [mozharness: 2016-11-17 13:46:14.973615Z] Running install step. 05:46:14 INFO - Running pre-action listener: _resource_record_pre_action 05:46:14 INFO - Running main action method: install 05:46:14 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:46:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:46:15 INFO - Reading from file tmpfile_stdout 05:46:15 INFO - Detecting whether we're running mozinstall >=1.0... 05:46:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:46:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:46:15 INFO - Reading from file tmpfile_stdout 05:46:15 INFO - Output received: 05:46:15 INFO - Usage: mozinstall [options] installer 05:46:15 INFO - Options: 05:46:15 INFO - -h, --help show this help message and exit 05:46:15 INFO - -d DEST, --destination=DEST 05:46:15 INFO - Directory to install application into. [default: 05:46:15 INFO - "/builds/slave/test"] 05:46:15 INFO - --app=APP Application being installed. [default: firefox] 05:46:15 INFO - mkdir: /builds/slave/test/build/application 05:46:15 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:46:15 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:46:48 INFO - Reading from file tmpfile_stdout 05:46:48 INFO - Output received: 05:46:48 INFO - /builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox 05:46:48 INFO - Running post-action listener: _resource_record_post_action 05:46:48 INFO - [mozharness: 2016-11-17 13:46:48.939251Z] Finished install step (success) 05:46:48 INFO - [mozharness: 2016-11-17 13:46:48.939394Z] Running run-tests step. 05:46:48 INFO - Running pre-action listener: _resource_record_pre_action 05:46:48 INFO - Running main action method: run_tests 05:46:48 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:46:48 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:46:48 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:46:48 INFO - 2016-11-17 05:46:48.953 screenresolution[1624:7511] starting screenresolution argv=screenresolution get 05:46:48 INFO - 2016-11-17 05:46:48.956 screenresolution[1624:7511] Display 0: 1600x1200x32@60 05:46:48 INFO - 2016-11-17 05:46:48.962 screenresolution[1625:7513] starting screenresolution argv=screenresolution list 05:46:48 INFO - Available Modes on Display 0 05:46:48 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:46:48 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:46:48 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:46:48 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:46:49 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:46:49 INFO - Intel Iris: 05:46:49 INFO - Chipset Model: Intel Iris 05:46:49 INFO - Type: GPU 05:46:49 INFO - Bus: Built-In 05:46:49 INFO - VRAM (Dynamic, Max): 1536 MB 05:46:49 INFO - Vendor: Intel (0x8086) 05:46:49 INFO - Device ID: 0x0a2e 05:46:49 INFO - Revision ID: 0x0009 05:46:49 INFO - Displays: 05:46:49 INFO - Display: 05:46:49 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:46:49 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:46:49 INFO - Display Serial Number: 1600x1200 60 05:46:49 INFO - Main Display: Yes 05:46:49 INFO - Mirror: Off 05:46:49 INFO - Online: Yes 05:46:49 INFO - Rotation: Supported 05:46:49 INFO - Return code: 0 05:46:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:46:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:46:49 INFO - Python 2.7.3 05:46:49 INFO - Return code: 0 05:46:49 INFO - grabbing minidump binary from tooltool 05:46:49 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:46:49 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 0x105ee8d30>, '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 0x105d4fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105ee0b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:46:49 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:46:49 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:46:49 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:46:49 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:46:49 INFO - Return code: 0 05:46:49 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 05:46:49 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:46:49 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:46:49 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 05:46:49 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:46:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/MsCrFnpwQE-iTu3tgEVw1g/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0377', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 05:46:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/MsCrFnpwQE-iTu3tgEVw1g/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0377 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 05:46:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WG4E4ZgQLW/Render', 05:46:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:46:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:46:49 INFO - 'HOME': '/Users/cltbld', 05:46:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:46:49 INFO - 'LOGNAME': 'cltbld', 05:46:49 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:46:49 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 05:46:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:46:49 INFO - 'MOZ_NO_REMOTE': '1', 05:46:49 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:46:49 INFO - 'NO_EM_RESTART': '1', 05:46:49 INFO - 'PAGER': '/bin/cat', 05:46:49 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:46:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:46:49 INFO - 'PWD': '/builds/slave/test', 05:46:49 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:46:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:46:49 INFO - 'SHELL': '/bin/bash', 05:46:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UOENEx601L/Listeners', 05:46:49 INFO - 'TMPDIR': '/var/folders/yn/qkptzzdx4_q5h519lzspx_l800000w/T/', 05:46:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:46:49 INFO - 'USER': 'cltbld', 05:46:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:46:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:46:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:46:49 INFO - 'XPC_FLAGS': '0x0', 05:46:49 INFO - 'XPC_SERVICE_NAME': '0', 05:46:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:46:49 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/MsCrFnpwQE-iTu3tgEVw1g/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0377', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 05:46:49 INFO - mozversion application_buildid: 20161117030212 05:46:49 INFO - mozversion application_changeset: 13f49da109ea460665ad27c8497cb1489548450c 05:46:49 INFO - mozversion application_display_name: Nightly 05:46:49 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:46:49 INFO - mozversion application_name: Firefox 05:46:49 INFO - mozversion application_remotingname: firefox 05:46:49 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 05:46:49 INFO - mozversion application_vendor: Mozilla 05:46:49 INFO - mozversion application_version: 53.0a1 05:46:49 INFO - mozversion platform_buildid: 20161117030212 05:46:49 INFO - mozversion platform_changeset: 13f49da109ea460665ad27c8497cb1489548450c 05:46:49 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 05:46:49 INFO - mozversion platform_version: 53.0a1 05:46:49 INFO - using testdate: 1479390409 05:46:49 INFO - actual date: 1479390409 05:46:49 INFO - starting webserver on 'localhost:49244' 05:46:49 INFO - SUITE-START | Running 2 tests 05:46:49 INFO - TEST-START | dromaeo_css 05:46:49 INFO - operating with platform_type : mac_ 05:46:49 INFO - Initialising browser for dromaeo_css test... 05:46:49 INFO - TEST-INFO | started process 1635 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/yn/qkptzzdx4_q5h519lzspx_l800000w/T/tmppNQ_Xw/profile http://localhost:49244/getInfo.html) 05:46:52 INFO - PROCESS | 1635 | 2016-11-17 05:46:52.400 plugin-container[1638:7688] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8f3f, name = 'com.apple.tsm.portname' 05:46:52 INFO - PROCESS | 1635 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:46:52 INFO - PROCESS | 1635 | 2016-11-17 05:46:52.401 plugin-container[1638:7688] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x950b, name = 'com.apple.CFPasteboardClient' 05:46:52 INFO - PROCESS | 1635 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:46:52 INFO - PROCESS | 1635 | 2016-11-17 05:46:52.401 plugin-container[1638:7688] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 05:46:52 INFO - PROCESS | 1635 | __metrics Screen width/height:1600/1200 05:46:52 INFO - PROCESS | 1635 | colorDepth:24 05:46:52 INFO - PROCESS | 1635 | Browser inner width/height: 1024/689 05:46:52 INFO - PROCESS | 1635 | __metrics 05:46:52 ERROR - PROCESS | 1635 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3179: TypeError: this.tabs is undefined 05:46:53 INFO - TEST-INFO | 1635: exit 0 05:46:53 INFO - Browser initialized. 05:46:53 INFO - Running cycle 1/1 for dromaeo_css test... 05:46:53 INFO - TEST-INFO | started process 1639 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/yn/qkptzzdx4_q5h519lzspx_l800000w/T/tmppNQ_Xw/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:46:55 INFO - PROCESS | 1639 | 2016-11-17 05:46:55.750 plugin-container[1640:7812] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x883f, name = 'com.apple.tsm.portname' 05:46:55 INFO - PROCESS | 1639 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:46:55 INFO - PROCESS | 1639 | 2016-11-17 05:46:55.751 plugin-container[1640:7812] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4707, name = 'com.apple.CFPasteboardClient' 05:46:55 INFO - PROCESS | 1639 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:46:55 INFO - PROCESS | 1639 | 2016-11-17 05:46:55.751 plugin-container[1640:7812] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 05:47:05 INFO - PROCESS | 1639 | RSS: Main: 237441024 05:47:05 INFO - PROCESS | 1639 | 05:47:55 INFO - PROCESS | 1639 | 1479390475108 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:50:08 INFO - PROCESS | 1639 | Cycle 1(1): loaded http://localhost:49244/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49244/tests/dromaeo/cssquery-ext.html) 05:50:10 INFO - PROCESS | 1639 | RSS: Main: 249495552 05:50:10 INFO - PROCESS | 1639 | 05:53:12 INFO - PROCESS | 1639 | Cycle 1(1): loaded http://localhost:49244/tests/dromaeo/cssquery-ext.html (next: http://localhost:49244/tests/dromaeo/cssquery-jquery.html) 05:53:13 INFO - PROCESS | 1639 | RSS: Main: 265412608 05:53:13 INFO - PROCESS | 1639 | 05:56:15 INFO - PROCESS | 1639 | Cycle 1(1): loaded http://localhost:49244/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49244/tests/dromaeo/cssquery-mootools.html) 05:56:15 INFO - PROCESS | 1639 | RSS: Main: 268795904 05:56:15 INFO - PROCESS | 1639 | 05:59:17 INFO - PROCESS | 1639 | Cycle 1(1): loaded http://localhost:49244/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49244/tests/dromaeo/cssquery-prototype.html) 05:59:18 INFO - PROCESS | 1639 | RSS: Main: 267468800 05:59:18 INFO - PROCESS | 1639 | 06:02:21 INFO - PROCESS | 1639 | Cycle 1(1): loaded http://localhost:49244/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49244/tests/dromaeo/cssquery-yui.html) 06:02:21 INFO - PROCESS | 1639 | RSS: Main: 271814656 06:02:21 INFO - PROCESS | 1639 | 06:05:24 INFO - PROCESS | 1639 | Cycle 1(1): loaded http://localhost:49244/tests/dromaeo/cssquery-yui.html (next: http://localhost:49244/tests/dromaeo/cssquery-dojo.html) 06:05:25 INFO - PROCESS | 1639 | RSS: Main: 274419712 06:05:25 INFO - PROCESS | 1639 | 06:05:25 INFO - PROCESS | 1639 | __start_tp_report 06:05:25 INFO - PROCESS | 1639 | _x_x_mozilla_page_load 06:05:25 INFO - PROCESS | 1639 | _x_x_mozilla_page_load_details 06:05:25 INFO - PROCESS | 1639 | |i|pagename|runs| 06:05:25 INFO - PROCESS | 1639 | |0;dojo.html;3059.3575349176676;3404.165979335062;3423.640517745638;3433.8798142065034;3478.008767501263;5661.1508273758955;5744.62659927105;5775.989201889668;5814.331351894522;5817.360090390059;5724.398938111501;5789.82630521084;5862.706864656771;5914.112883067542;5948.494377977866;5230.163173892178;5243.292155559008;5308.442613525582;5317.601179911503;5406.59450541209;5406.297181366436;5414.972925135388;5434.7826086956675;5437.048516509615;5452.2094296327;5227.26818245446;5325.893482130375;5353.839384818442;5376.11294136467;5413.756380962846;5232.6598771080135;5265.921011184835;5278.313819203479;5297.046531624306;5299.920501192447;4461.57615026572;4654.813807447698;5203.4796520348045;5231.790728370861;5240.213967904807;4862.149123903303;5231.869203269949;5867.823965281048;5886.293644762656;5903.026000709878;6197.318294987548;6206.937930620681;6212.9068063979075;6282.403171698681;6316.147320111818;6261.092141639482;6292.590981586182;6301.432871041582;6302.716377763011;6317.684115794192;5974.761009559569;5987.856515139407;5992.400759924017;6006.78976235834;6010.579259451796;6206.0690896365;6266.154069200599;6270.905936410957;6287.2140982377205;6394.680265986682;6256.405641464054;6258.311585725566;6266.154069200599;6282;6305.905411418832;5549.611527193058;6100.581173165827;6326.778562750282;6334.3665633436285;6340.270868850049;4908.361912951294;5983.6708981005595;6135.140800782589;6174.320824709278;6206.224221972254;6220.564560480813;6270.404311590483;6292.528060395489;6304.779332723333;6338.366163383717;4968.950310496849;5015.924761128586;5015.949840501621;5037.093323201786;5078.822241221539;4346.587074227943;4422.071365013319;4450.666200034945;4456.710313829591;4484.9103017940115;4884.3650325457465;5940.376260492672;5953;5955.464008239279;5992.490638295706;6231.875362492816;6247.094171345129;6260.217472815898;6260.593061450991;6276.717547710364;5830.533557315404;5857.414258574108;5892.145638882338;5918.852028699231;6012.218411606463;3882.5535063467496;5459.69971651555;5470.56235501159;5471.316085489314;5541.168824676251;4700.2479603264;5273.789048438096;6276.560640755103;6303.212098487689;6368.426841584094;6250.937490625218;6286.842828929313;6295.622262664255;6299.338569450325;6337.144485494399;5334.733263336895;6382.234131904015;6398.168238129014;6400.615963042233;6465.385788350287;6133.824358647991;6178.289496707936;6179;6187.81436556904;6260.217472815898;5677.807660391199;5988.131720900532;6215.409536094064;6256.311805701278;6273.52948528853;6208;6222.191115155001;6240.375962403905;6247;6275.34108918557;6090.604110732788;6110.012029148408;6110.1445797587485;6227.813165605039;6244.937550624436;5822.70886455667;5860.472557469848;6059.242594675666;6099.420555047263;6134.589663226485;6206.751729930933;6215.96892015537;6230.937690623035;6237.750489980532;6268.059791031382;6081.6351018939;6084.574079814371;6100.176476175837;6122.632642041492;6168.383161683796 06:05:25 INFO - PROCESS | 1639 | |1;ext.html;473.3089689053981;477.65370106672646;519.7687029271974;521.2702216896342;561.9859503512414;26466.86766566167;31591.842040789794;33132.006039818785;34480.31039379211;34541.30917381651;32601.021969340956;33044.834775826144;33105.83447082761;33155.66844331556;33170.50244246338;7119.750808721696;7289.343959043685;7299.19708832028;7301.853962920738;7312.305330993574;7534.510256833289;7612.238776122399;7625.31372176504;7634.389248860078;7663.080430348366;7581.50720203185;7623.0090088288725;7631.3894888408795;7641.61791910405;7656.3109320161175;7571.886421703679;7634.541927484369;7638.045244344457;7654.11977622575;7662.080550333968;7539;7577.583232922187;7588.27911348421;7602.695892164307;7617.27635874591;31301.37397252042;31470;31526.211844703837;31546.526802097986;31646.208844778834;3175.4442972479724;3219.581454410935;3235.692609202122;3277.5083737439336;3511.596166440841;17689.115544222866;18095.276188952557;19107.61784764297;20611.175552977864;21383.25158619441;2366.3137690069857;2481.027002042682;2851.600775891377;2874.3963767608825;2885.0623547346986;412516.937415311;414039.8596014031;502704;506297;511995;64005.67997159984;64766;64776.35223647797;64982.35017649858;65087;67811;68226.97659535111;68941.3105868945;68976;69079;13487.123336983064;16144.515664530083;16145.757813632805;16187.514374568782;16306.510804675878;12329.815052774216;12395.256284622772;12426.316552589695;12428.813567796491;12435;19265.036748162536;19926.50183745389;19928.50178745514;19961;19990;8308.335333173332;8362.45644033136;8387.916120838714;8391.538465384463;8454.323654107657;850.3792231670791;929.9070092990783;945.4563625915126;956.3544607389983;1010.2524132142308;26430.678466077003;28339.858300708776;28843.711562884524;29401;29638.703612963593;906.769347405377;1007.8762180169206;1013.716159475348;1048.09863517375;1071.7320669832543;14877.181755003363;15189.772153417707;15261.084334939938;15389;15635.51462611073;553997.9901306482;665165;667666;669354;669616;51183;51933.22100168425;52082.739586301825;52232;52386.21420678693;1336.9665758356118;1340.9329533523285;1389.1387339849357;1392.484780631173;1397.3781667158016;28309.716902831533;28315.575266370608;28694.856525717238;28796.712032879404;29057;48374.27438588354;48970;49136.50863491462;49148.75425622849;49189.508104918496;600965;607811;608444;609754;611101;24675.753242467345;24720.381990450383;25033.374165646004;25056;25097.121600744616;31444.52833207458;39099;39288;39352.606473934895;40969;33282.16794580155;34102.31795364029;34169.58304169563;35091.82454087815;35165.82417087898;34073.65926340705;34181.65818341785;34829.825850870584;34917.650823491436;35315.823420882734;34746.78386256469;35349.116272092375;35457.290854182254;35515.46726799152;35597.28805423929;30661;31583.101462161863;31810.204744881565;32098.67901320957;32164.517532237503;23530;23817.928193231;23980.598446483964;24166.87916560476;24291.271261861464 06:05:25 INFO - PROCESS | 1639 | |2;jquery.html;357.6459305287766;362.24471975930186;365.89206184175674;374.35423893783246;390.91399892023765;13056.347182640877;14247.71504569908;15571.377144914204;15613.68772624547;16074.437394691187;17463;18960.241590336405;18964.051797410142;19211.42365729027;19265.940373279464;5010.924836127461;5413.26920865684;5417.566594672437;5427.83716488506;5437.891242175174;3033.848307584623;4125.690573207006;5263.131583288753;5276.736163191844;5400.189971504266;5276.234945932857;5304.6286759926825;5322.0952438085615;5327.653702509344;5335.1997200419855;4575.633949284066;5281;5296.364436267634;5321.680699158043;5338.412774594792;4746.3862286822305;4762.952370476286;4773.140834649762;4778.2115950868065;4790.616750659939;12109.970652494549;17418.390356337593;18413.53966150857;19661.803381966285;20889.477763056046;2066.2045112631595;2174.2388665181097;2281.691971583848;2408.963865542018;2712.8372297662004;7435.330820226151;8760.420460271289;8983.056779038237;9444.669436569755;9463.716088517356;1620.2141961148834;1710.7433884917361;1721.8966861988195;1773.689604319239;1816.2825683854803;119201;124588;125342.99999999908;125882;126585;11016.944915275373;11184.44077796107;11260.662180134608;11382.146339024606;11517.366544839946;14199.85800142006;14230.14619122856;14257.215853128178;14279.714405712035;14290.499832505813;6764.222114456802;8032.4778889372;8060.596970151469;8219;8268.875966860502;7848.7645370638975;7887.290143887078;7959;8093.595320233965;8099.797505062303;4105.2405305018665;4380.145871555016;4470.776461176928;4476.574725401082;4538.977305113519;1564.1084581788498;1850.0934542074287;1926.0369815092454;1935.5741736817877;1938.7382703335152;846.551327796269;888.6001299415288;937.9869740680049;940.1585580905052;940.9294302927308;16179.191040447931;20463.18147274122;20583;21433.78566214349;21618;860.7691001867265;901.6889173235223;945.8013817098487;960.5293406230896;1036.8600238967642;8510.74467765968;9541.809163816684;11195.216334856645;11335.716607084889;11344.149188810872;554739.6208303326;561218;576132;580079;584088;126723;126831.36584317204;127795;128068;131205;912.9863052054092;952.3238500664468;972.0522490571636;973.73709098543;978.2467500024941;18743.90628046851;18788.624227515647;19064.618707626047;19174.80825191786;19312.517187069872;53088.469115308355;55155;55688;56188.43811561832;56815.71592142013;74962.2518874065;75241.24758752562;75330.46695330423;75506.62246688732;78260;5477.67133971963;6400.583962042452;7014.579125252501;7275.163356214103;7426.925730742623;9847.803043938939;10925.180611454014;11933.880661193278;12519.248845069325;12637.17858339205;8305.916940830755;10317;10745.140388769036;10916.836247456453;10934.398608076313;8381.664733410595;8621.525816080175;10032.749181270527;10202.540885660312;10539.736506587089;8672.609732561923;10792.730181745519;10868.021878031015;10924;11036.11711063129;7016.403605693573;7039.084918960502;7204.783856484314;7266.818329541804;7322.707091716485;10152.898471015194;10381.480925953823;10772.946135269274;11036.503357348778;11099.001089901947 06:05:25 INFO - PROCESS | 1639 | |3;mootools.html;1300.5123078845434;1447.5512591096765;1519.77203419487;1594.4339759385418;1747;7208.639568021604;7474.775756727293;7687.500312479685;8109.675612975481;8118.391120665951;7660.502067365617;7926.6829326827;7952.801179970489;8020.919790802091;8056;1723.8965662060255;2024.564718585504;2067.14213601356;2072.1918451803735;2076.4497408186844;2058.2178772066595;2089.5925294567564;2094.7486301643826;2106.2628080171894;2112.5458026524293;2045.7442819647545;2047.9180832766672;2068.338131797825;2074.2429013410074;2078.490769761411;2047.5597746484502;2069.4826293426645;2073.1292857000094;2074.1703318672503;2078.5842831433774;2080.771115177329;2084.5726626041624;2085.176355339632;2092.6442504774227;2101.432613194444;7924.286814186751;7959.9602001989615;7987.281144697005;7999.600019999035;8000;4978.2034874419915;5152.227165925104;5152.587792976553;5158.92261616076;5169.53474187325;3227.7256433203206;3295.1762059485127;3389.2204792897523;3427.3145370925913;3427.485877118427;940.4592359393308;984.916282116021;1009.1321463541349;1023.687775228555;1032.7005168501125;9563.952180239125;10540.893206213314;10898.400587967659;10965;10982.176336774693;4328.567143285646;4595.609373203315;4695.624350051988;4703.871070942949;4705.200115980291;9323.393979391318;9357.766055848659;9394.248460123174;9416.340856140005;9426.010268921802;5174.482551744795;5180.093483640348;5183.766730497136;5190.24741412493;5193.68837869729;4153.169366126786;4570.085982803453;4684.391029166187;4725.3384526166365;4748.169070412733;3732.738708290448;3765.1151979284846;3821.3694740368082;3856.151646637735;3866.2460820140363;1256.836611240551;1262.7095767973233;1265.436880588148;1267.3029833591488;1280.2382582363664;748.8280840484624;856.1524091149842;880.506916126971;887.924526415249;888.9688860889967;5488.368837583649;5498.065328894098;5572.498475137257;5668.971655141698;5697.800576979786;769.7382889817488;865.1694373401605;869.4956924983494;874.4665753890122;884.045231150356;3242.4974129010043;3707.9073023174637;3776.962230377716;3795.2978698940233;3799.544054713452;9610.327277090537;10049.346792458467;10074.093331600192;10143.847842282372;10172.389656620626;7709.38324934004;10129.544170512345;10151.847722284172;10153.64462243818;10200.6429774959;6866.588004719732;7364.96317518409;7797.532148071134;7831.843363132705;7844.960775195973;4409.492908315585;5061.924071138862;5160.838811267435;5278.2346559749385;5449.6730196188355;7565.886511702439;9285.907140928775;9305.395149315273;9444.48055356962;9480.95259523698;9332.346735728433;9420;9427.481488517922;9531.47576883278;9535.47554884488;6937.687804048931;7084.929150708633;7139.178994415709;7245;7335.303146201102;7957.283844453796;7997.640106195351;8020;8042.557659328559;8067.515949043076;6804.215968976753;6888.758893438906;7043.788686339418;7146;7216.278372162742;6042.788502402571;6456.515761317826;6912.308769123048;7029.0862187915245;7120.964395177991;6446.677666116581;6613.735450581923;6672.299408562031;6687.030380594883;6713.227978782503;4827.275908613736;4858.951410485991;4876.902461950812;4943.752812359296;4949.480304567968;4743.881402964954;4808.134535783593;4897.828575999965;4965.230389289666;4979.900401992012 06:05:25 INFO - PROCESS | 1639 | |4;prototype.html;296.2800395040053;296.488557238763;307.3100888505306;308.4986896293522;313.43112251263955;4803.7357945313;4882.755862206885;5373.032854086267;5487.094629386152;5522.309711286094;5134.614903882214;5282;5353;5390.811321603745;5405.270338479063;3375.4936759486027;3970.285348637244;4145.170965806836;4145.7719825409595;4166.437530933331;3480.738944579167;4000.8599699010542;4042.43405923171;4142.606452387018;4178.979105104486;3418.316336732651;3654.7076233901357;3663.249033948034;3742.139307959171;4078.4697989261354;3379.7465190110715;3667.5232219811255;3743.12036671382;4064.349704047353;4089.4683691120263;3341.63242043375;3473.6352682968177;3580.7672501287593;3593.2633810069133;3597.874074407383;3738.2523495301048;4206.980649288328;4780.306855505967;4927.36386033474;5155.7980246099305;3935;4720.59874910633;4724.684904396855;4734.502877197879;4735.455422626374;5013.573846223076;5023.623228257895;5448.673079615236;5470.769076957692;5632.549396048306;3784.8675296364772;3919;3937.7243592948507;3967.2858885400906;3994.7003974702016;5672.631278966854;5997.8800423991715;6001.679630481287;6012.038073908154;6081.685481412641;4000.239954408653;4004;4008.8396464141692;4010.8195131219163;4022.9798851005557;3783.659470647655;4014.237294913957;4053.9054455296905;4073.368627862686;4080.6939479539155;3899.0447340401215;4665.836695715634;4688.765561721969;4691.695039822401;4702.811887524461;5754.367019627837;5779.381773103538;5781.624194427349;5781.94218057814;5784.797532086357;1107.0866535108569;1198.8261702053328;1241.7954584053455;1249.2941488059218;1249.4939549482474;844.9986765682686;903.6701603914524;907.088376181933;916.4923700512619;925.9074092590687;4741.66808323421;4937.987712518925;4963.230699241623;5034.194528875362;5085.042258730416;4646.09510100857;5030.798768049311;5440;5562.499375056264;5596.362364463132;5015.229440255625;5026.411398129966;5033.723145227048;5042.218456139304;5062.582476906472;2446.7001019042045;2698.623701912;2768.4878297515024;2913.46100971321;2923.532234842415;4886.877828054256;4890.24201248807;5139.997700448452;5539.169124631257;5639.971800140973;4815.879603009883;4835.8065677372515;5610.382857885629;5626.634268772516;5671.517920976763;5203.661761985459;5254.97372513135;5658.462446067617;5667.744951477193;5685.516731077904;4992.500749924979;5070.290159377616;5165.276861239505;5309.309789727311;5456.236126942152;4469.530699276659;5411.810586629449;5426.375966763872;5431.782728690808;5463.535599474089;4782.402199725035;4908.674657842291;5641.210230567806;5646.322441307069;5684.459976302244;2973.2120987937783;3468.358353704472;3493.8427770750886;3738.5044982941035;3787.8295476704166;5421.620486565903;5449.264349312792;5483.369412517452;5490.3411590609385;5561.193626924154;4382.715123517089;4851.80592776285;4852.417709874838;5329.89469159672;5380;5288.4182739897815;5725.456081672234;5733.168690539932;5757.568182386254;5758.452946970031;4808.2547205183255;5267.841964741064;5345.705986170642;5526.364468086222;5664.977865715388;5280.709560974184;5550.47270509301;5799.941020937676;5862.794802181903;5871.943050250996;5300.7084610345255;5614.410486898817;5683.801066962802;5699.458551437607;5703.201551782837 06:05:25 INFO - PROCESS | 1639 | |5;yui.html;643.2377632505481;690.5511417578573;714.9070620819299;751.1587022534759;786.2766255045359;12479.500819967201;12976.870231297684;13221.140625859336;13321.866781332184;13428.395722192501;12255.571055013077;12479.68800779981;12482.687932801684;12518.874811251884;12554.68613284666;812.0174588747608;819.9590020498952;828.7016673997356;830.8006078541137;831.2726364431123;649.3571364349308;668.7826456401665;687.1582311668192;697.5779653309751;740.2375553180233;852.6078004118082;865.8268346330727;866.3199388480044;870.7779516223386;873.8601823708208;849.524266410812;863.4215075899132;865.4547634989916;866.9393142480061;888.4447220487195;642.1523588862703;645.9870802583969;646.8059582125344;647.1037612906111;648.4779752299397;11498.885011149865;11586.536538538448;11741.295522268605;11862.940685296604;12075.577354792627;9639.469829159394;10328.79342413155;10801.891981080167;10915;10940.179486538542;9199.310051746079;9259.861102083474;9292.117248861347;9344.392614480037;9559.378640388353;3019.3961207758534;3121.500559910426;3169.2235402326346;3179.2528755742383;3210.1011716719354;86784.13215867824;89210.3236902894;89950;90508.09491905062;91470;8055.436119471698;8069.475484093515;8130.674773009132;8281.171882811792;8411.158884111663;16000.279987400594;17919.74561780689;17998.5500362492;18041;18108.456746297634;7623.695052197961;7624.390048796083;7644.617769111634;7673.577953212515;7820.921790782019;4223.66210703143;4319.092990471973;4322.589354011364;4327.394164816928;4328.156009578165;3213.2609499815176;3295.8352082395786;3375.392429362689;3391.796492210475;3397.1676939150066;643.5173619785161;648.5913874259187;653.496807458264;662.57595139111;664.0636702249805;531.3278702441414;573.0172753727378;590.2268028882178;601.6269912654095;615.7998650438625;7898.9477042406015;8091.433599647969;8790.384673072824;8880.644774209088;8943.865842012376;611.5382885921101;611.5688439650036;653.79048759794;658.9505787065989;662.7216569041015;3699.9445008324897;4018.0422284050187;4024.6377825995214;4270.12462445198;4369.956300437018;88344;93324;93827.06172938184;118674;120594.55838091426;67904;72588;102794.45808312882;103438;105931;82884.58557707173;90796.99999999868;90885;92748.68128296846;93093.5345323269;6759.898601520883;6953.548019378724;8268.793280168044;8314.6674133034;8503.702370417006;42200;49843;54556.727216363666;56678.43321566732;58651.70674146602;56077.719611401684;83874;83945.74081388906;84644.15355846363;85521;9827.557759900965;10239.7440063999;10719.936906312576;11109.94445027802;11120.388378639253;10323.070923616911;11219.775604488028;11963.820542691692;12064.336461494702;12345.074119440898;9179.862302065341;11342.262752921033;11389;11395.373254470753;11825.655930246528;11216.66350009501;11315.773684526428;11402.48688808989;11724.765504689687;11765.294082355085;10894.564217431214;11311.264767790068;11413.429328533373;11447.313161210353;11561.479733411848;9240.584173712065;9566.66516671913;9579.994100619613;9751.853722194315;9855.704328870146;7200.855982880418;9715.319927605;9780.559874805509;9807.264455165749;9846.409215447096 06:05:25 INFO - PROCESS | 1639 | __end_tp_report 06:05:25 INFO - PROCESS | 1639 | __start_cc_report 06:05:25 INFO - PROCESS | 1639 | _x_x_mozilla_cycle_collect,387 06:05:25 INFO - PROCESS | 1639 | __end_cc_report 06:05:25 INFO - PROCESS | 1639 | __startTimestamp1479391525101__endTimestamp 06:05:25 INFO - PROCESS | 1639 | 06:05:25 INFO - PROCESS | 1639 | ------- Summary: start ------- 06:05:25 INFO - PROCESS | 1639 | Number of tests: 6 06:05:25 INFO - PROCESS | 1639 | 06:05:25 INFO - PROCESS | 1639 | [#0] dojo.html Cycles:165 Average:5758.00 Median:6006.79 stddev:680.98 (11.3%) stddev-sans-first:649.33 06:05:25 INFO - PROCESS | 1639 | Values: 3059.4 3404.2 3423.6 3433.9 3478.0 5661.2 5744.6 5776.0 5814.3 5817.4 5724.4 5789.8 5862.7 5914.1 5948.5 5230.2 5243.3 5308.4 5317.6 5406.6 5406.3 5415.0 5434.8 5437.0 5452.2 5227.3 5325.9 5353.8 5376.1 5413.8 5232.7 5265.9 5278.3 5297.0 5299.9 4461.6 4654.8 5203.5 5231.8 5240.2 4862.1 5231.9 5867.8 5886.3 5903.0 6197.3 6206.9 6212.9 6282.4 6316.1 6261.1 6292.6 6301.4 6302.7 6317.7 5974.8 5987.9 5992.4 6006.8 6010.6 6206.1 6266.2 6270.9 6287.2 6394.7 6256.4 6258.3 6266.2 6282.0 6305.9 5549.6 6100.6 6326.8 6334.4 6340.3 4908.4 5983.7 6135.1 6174.3 6206.2 6220.6 6270.4 6292.5 6304.8 6338.4 4969.0 5015.9 5015.9 5037.1 5078.8 4346.6 4422.1 4450.7 4456.7 4484.9 4884.4 5940.4 5953.0 5955.5 5992.5 6231.9 6247.1 6260.2 6260.6 6276.7 5830.5 5857.4 5892.1 5918.9 6012.2 3882.6 5459.7 5470.6 5471.3 5541.2 4700.2 5273.8 6276.6 6303.2 6368.4 6250.9 6286.8 6295.6 6299.3 6337.1 5334.7 6382.2 6398.2 6400.6 6465.4 6133.8 6178.3 6179.0 6187.8 6260.2 5677.8 5988.1 6215.4 6256.3 6273.5 6208.0 6222.2 6240.4 6247.0 6275.3 6090.6 6110.0 6110.1 6227.8 6244.9 5822.7 5860.5 6059.2 6099.4 6134.6 6206.8 6216.0 6230.9 6237.8 6268.1 6081.6 6084.6 6100.2 6122.6 6168.4 06:05:25 INFO - PROCESS | 1639 | 06:05:25 INFO - PROCESS | 1639 | [#1] ext.html Cycles:180 Average:68656.12 Median:34788.30 stddev:155728.61 (447.6%) stddev-sans-first:156081.32 06:05:25 INFO - PROCESS | 1639 | Values: 473.3 477.7 519.8 521.3 562.0 26466.9 31591.8 33132.0 34480.3 34541.3 32601.0 33044.8 33105.8 33155.7 33170.5 7119.8 7289.3 7299.2 7301.9 7312.3 7534.5 7612.2 7625.3 7634.4 7663.1 7581.5 7623.0 7631.4 7641.6 7656.3 7571.9 7634.5 7638.0 7654.1 7662.1 7539.0 7577.6 7588.3 7602.7 7617.3 31301.4 31470.0 31526.2 31546.5 31646.2 3175.4 3219.6 3235.7 3277.5 3511.6 17689.1 18095.3 19107.6 20611.2 21383.3 2366.3 2481.0 2851.6 2874.4 2885.1 412516.9 414039.9 502704.0 506297.0 511995.0 64005.7 64766.0 64776.4 64982.4 65087.0 67811.0 68227.0 68941.3 68976.0 69079.0 13487.1 16144.5 16145.8 16187.5 16306.5 12329.8 12395.3 12426.3 12428.8 12435.0 19265.0 19926.5 19928.5 19961.0 19990.0 8308.3 8362.5 8387.9 8391.5 8454.3 850.4 929.9 945.5 956.4 1010.3 26430.7 28339.9 28843.7 29401.0 29638.7 906.8 1007.9 1013.7 1048.1 1071.7 14877.2 15189.8 15261.1 15389.0 15635.5 553998.0 665165.0 667666.0 669354.0 669616.0 51183.0 51933.2 52082.7 52232.0 52386.2 1337.0 1340.9 1389.1 1392.5 1397.4 28309.7 28315.6 28694.9 28796.7 29057.0 48374.3 48970.0 49136.5 49148.8 49189.5 600965.0 607811.0 608444.0 609754.0 611101.0 24675.8 24720.4 25033.4 25056.0 25097.1 31444.5 39099.0 39288.0 39352.6 40969.0 33282.2 34102.3 34169.6 35091.8 35165.8 34073.7 34181.7 34829.8 34917.7 35315.8 34746.8 35349.1 35457.3 35515.5 35597.3 30661.0 31583.1 31810.2 32098.7 32164.5 23530.0 23817.9 23980.6 24166.9 24291.3 06:05:25 INFO - PROCESS | 1639 | 06:05:25 INFO - PROCESS | 1639 | [#2] jquery.html Cycles:180 Average:33640.65 Median:4115.47 stddev:95944.16 (2331.3%) stddev-sans-first:96180.76 06:05:25 INFO - PROCESS | 1639 | Values: 357.6 362.2 365.9 374.4 390.9 13056.3 14247.7 15571.4 15613.7 16074.4 17463.0 18960.2 18964.1 19211.4 19265.9 5010.9 5413.3 5417.6 5427.8 5437.9 3033.8 4125.7 5263.1 5276.7 5400.2 5276.2 5304.6 5322.1 5327.7 5335.2 4575.6 5281.0 5296.4 5321.7 5338.4 4746.4 4763.0 4773.1 4778.2 4790.6 12110.0 17418.4 18413.5 19661.8 20889.5 2066.2 2174.2 2281.7 2409.0 2712.8 7435.3 8760.4 8983.1 9444.7 9463.7 1620.2 1710.7 1721.9 1773.7 1816.3 119201.0 124588.0 125343.0 125882.0 126585.0 11016.9 11184.4 11260.7 11382.1 11517.4 14199.9 14230.1 14257.2 14279.7 14290.5 6764.2 8032.5 8060.6 8219.0 8268.9 7848.8 7887.3 7959.0 8093.6 8099.8 4105.2 4380.1 4470.8 4476.6 4539.0 1564.1 1850.1 1926.0 1935.6 1938.7 846.6 888.6 938.0 940.2 940.9 16179.2 20463.2 20583.0 21433.8 21618.0 860.8 901.7 945.8 960.5 1036.9 8510.7 9541.8 11195.2 11335.7 11344.1 554739.6 561218.0 576132.0 580079.0 584088.0 126723.0 126831.4 127795.0 128068.0 131205.0 913.0 952.3 972.1 973.7 978.2 18743.9 18788.6 19064.6 19174.8 19312.5 53088.5 55155.0 55688.0 56188.4 56815.7 74962.3 75241.2 75330.5 75506.6 78260.0 5477.7 6400.6 7014.6 7275.2 7426.9 9847.8 10925.2 11933.9 12519.2 12637.2 8305.9 10317.0 10745.1 10916.8 10934.4 8381.7 8621.5 10032.7 10202.5 10539.7 8672.6 10792.7 10868.0 10924.0 11036.1 7016.4 7039.1 7204.8 7266.8 7322.7 10152.9 10381.5 10772.9 11036.5 11099.0 06:05:25 INFO - PROCESS | 1639 | 06:05:25 INFO - PROCESS | 1639 | [#3] mootools.html Cycles:180 Average:5315.02 Median:5159.88 stddev:2958.39 (57.3%) stddev-sans-first:2951.30 06:05:25 INFO - PROCESS | 1639 | Values: 1300.5 1447.6 1519.8 1594.4 1747.0 7208.6 7474.8 7687.5 8109.7 8118.4 7660.5 7926.7 7952.8 8020.9 8056.0 1723.9 2024.6 2067.1 2072.2 2076.4 2058.2 2089.6 2094.7 2106.3 2112.5 2045.7 2047.9 2068.3 2074.2 2078.5 2047.6 2069.5 2073.1 2074.2 2078.6 2080.8 2084.6 2085.2 2092.6 2101.4 7924.3 7960.0 7987.3 7999.6 8000.0 4978.2 5152.2 5152.6 5158.9 5169.5 3227.7 3295.2 3389.2 3427.3 3427.5 940.5 984.9 1009.1 1023.7 1032.7 9564.0 10540.9 10898.4 10965.0 10982.2 4328.6 4595.6 4695.6 4703.9 4705.2 9323.4 9357.8 9394.2 9416.3 9426.0 5174.5 5180.1 5183.8 5190.2 5193.7 4153.2 4570.1 4684.4 4725.3 4748.2 3732.7 3765.1 3821.4 3856.2 3866.2 1256.8 1262.7 1265.4 1267.3 1280.2 748.8 856.2 880.5 887.9 889.0 5488.4 5498.1 5572.5 5669.0 5697.8 769.7 865.2 869.5 874.5 884.0 3242.5 3707.9 3777.0 3795.3 3799.5 9610.3 10049.3 10074.1 10143.8 10172.4 7709.4 10129.5 10151.8 10153.6 10200.6 6866.6 7365.0 7797.5 7831.8 7845.0 4409.5 5061.9 5160.8 5278.2 5449.7 7565.9 9285.9 9305.4 9444.5 9481.0 9332.3 9420.0 9427.5 9531.5 9535.5 6937.7 7084.9 7139.2 7245.0 7335.3 7957.3 7997.6 8020.0 8042.6 8067.5 6804.2 6888.8 7043.8 7146.0 7216.3 6042.8 6456.5 6912.3 7029.1 7121.0 6446.7 6613.7 6672.3 6687.0 6713.2 4827.3 4859.0 4876.9 4943.8 4949.5 4743.9 4808.1 4897.8 4965.2 4979.9 06:05:25 INFO - PROCESS | 1639 | 06:05:25 INFO - PROCESS | 1639 | [#4] prototype.html Cycles:180 Average:4458.35 Median:4977.87 stddev:1372.17 (27.6%) stddev-sans-first:1339.99 06:05:25 INFO - PROCESS | 1639 | Values: 296.3 296.5 307.3 308.5 313.4 4803.7 4882.8 5373.0 5487.1 5522.3 5134.6 5282.0 5353.0 5390.8 5405.3 3375.5 3970.3 4145.2 4145.8 4166.4 3480.7 4000.9 4042.4 4142.6 4179.0 3418.3 3654.7 3663.2 3742.1 4078.5 3379.7 3667.5 3743.1 4064.3 4089.5 3341.6 3473.6 3580.8 3593.3 3597.9 3738.3 4207.0 4780.3 4927.4 5155.8 3935.0 4720.6 4724.7 4734.5 4735.5 5013.6 5023.6 5448.7 5470.8 5632.5 3784.9 3919.0 3937.7 3967.3 3994.7 5672.6 5997.9 6001.7 6012.0 6081.7 4000.2 4004.0 4008.8 4010.8 4023.0 3783.7 4014.2 4053.9 4073.4 4080.7 3899.0 4665.8 4688.8 4691.7 4702.8 5754.4 5779.4 5781.6 5781.9 5784.8 1107.1 1198.8 1241.8 1249.3 1249.5 845.0 903.7 907.1 916.5 925.9 4741.7 4938.0 4963.2 5034.2 5085.0 4646.1 5030.8 5440.0 5562.5 5596.4 5015.2 5026.4 5033.7 5042.2 5062.6 2446.7 2698.6 2768.5 2913.5 2923.5 4886.9 4890.2 5140.0 5539.2 5640.0 4815.9 4835.8 5610.4 5626.6 5671.5 5203.7 5255.0 5658.5 5667.7 5685.5 4992.5 5070.3 5165.3 5309.3 5456.2 4469.5 5411.8 5426.4 5431.8 5463.5 4782.4 4908.7 5641.2 5646.3 5684.5 2973.2 3468.4 3493.8 3738.5 3787.8 5421.6 5449.3 5483.4 5490.3 5561.2 4382.7 4851.8 4852.4 5329.9 5380.0 5288.4 5725.5 5733.2 5757.6 5758.5 4808.3 5267.8 5345.7 5526.4 5665.0 5280.7 5550.5 5799.9 5862.8 5871.9 5300.7 5614.4 5683.8 5699.5 5703.2 06:05:25 INFO - PROCESS | 1639 | 06:05:25 INFO - PROCESS | 1639 | [#5] yui.html Cycles:180 Average:19652.70 Median:647.79 stddev:30240.17 (4668.2%) stddev-sans-first:30291.32 06:05:25 INFO - PROCESS | 1639 | Values: 643.2 690.6 714.9 751.2 786.3 12479.5 12976.9 13221.1 13321.9 13428.4 12255.6 12479.7 12482.7 12518.9 12554.7 812.0 820.0 828.7 830.8 831.3 649.4 668.8 687.2 697.6 740.2 852.6 865.8 866.3 870.8 873.9 849.5 863.4 865.5 866.9 888.4 642.2 646.0 646.8 647.1 648.5 11498.9 11586.5 11741.3 11862.9 12075.6 9639.5 10328.8 10801.9 10915.0 10940.2 9199.3 9259.9 9292.1 9344.4 9559.4 3019.4 3121.5 3169.2 3179.3 3210.1 86784.1 89210.3 89950.0 90508.1 91470.0 8055.4 8069.5 8130.7 8281.2 8411.2 16000.3 17919.7 17998.6 18041.0 18108.5 7623.7 7624.4 7644.6 7673.6 7820.9 4223.7 4319.1 4322.6 4327.4 4328.2 3213.3 3295.8 3375.4 3391.8 3397.2 643.5 648.6 653.5 662.6 664.1 531.3 573.0 590.2 601.6 615.8 7898.9 8091.4 8790.4 8880.6 8943.9 611.5 611.6 653.8 659.0 662.7 3699.9 4018.0 4024.6 4270.1 4370.0 88344.0 93324.0 93827.1 118674.0 120594.6 67904.0 72588.0 102794.5 103438.0 105931.0 82884.6 90797.0 90885.0 92748.7 93093.5 6759.9 6953.5 8268.8 8314.7 8503.7 42200.0 49843.0 54556.7 56678.4 58651.7 56077.7 83874.0 83945.7 84644.2 85521.0 9827.6 10239.7 10719.9 11109.9 11120.4 10323.1 11219.8 11963.8 12064.3 12345.1 9179.9 11342.3 11389.0 11395.4 11825.7 11216.7 11315.8 11402.5 11724.8 11765.3 10894.6 11311.3 11413.4 11447.3 11561.5 9240.6 9566.7 9580.0 9751.9 9855.7 7200.9 9715.3 9780.6 9807.3 9846.4 06:05:25 INFO - PROCESS | 1639 | -------- Summary: end -------- 06:05:25 INFO - PROCESS | 1639 | 06:05:25 INFO - TEST-INFO | 1639: exit 0 06:05:25 INFO - TEST-OK | dromaeo_css | took 1116175ms 06:05:25 INFO - TEST-START | kraken 06:05:25 INFO - operating with platform_type : mac_ 06:05:25 INFO - Initialising browser for kraken test... 06:05:25 INFO - TEST-INFO | started process 1651 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/yn/qkptzzdx4_q5h519lzspx_l800000w/T/tmpoZsyYd/profile http://localhost:49244/getInfo.html) 06:05:28 INFO - PROCESS | 1651 | 2016-11-17 06:05:28.603 plugin-container[1653:10362] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8943, name = 'com.apple.tsm.portname' 06:05:28 INFO - PROCESS | 1651 | See /usr/include/servers/bootstrap_defs.h for the error codes. 06:05:28 INFO - PROCESS | 1651 | 2016-11-17 06:05:28.603 plugin-container[1653:10362] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8947, name = 'com.apple.CFPasteboardClient' 06:05:28 INFO - PROCESS | 1651 | See /usr/include/servers/bootstrap_defs.h for the error codes. 06:05:28 INFO - PROCESS | 1651 | 2016-11-17 06:05:28.603 plugin-container[1653:10362] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 06:05:28 INFO - PROCESS | 1651 | __metrics Screen width/height:1600/1200 06:05:28 INFO - PROCESS | 1651 | colorDepth:24 06:05:28 INFO - PROCESS | 1651 | Browser inner width/height: 1024/689 06:05:28 INFO - PROCESS | 1651 | __metrics 06:05:28 ERROR - PROCESS | 1651 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3179: TypeError: this.tabs is undefined 06:05:29 INFO - TEST-INFO | 1651: exit 0 06:05:29 INFO - Browser initialized. 06:05:29 INFO - Running cycle 1/1 for kraken test... 06:05:29 INFO - TEST-INFO | started process 1654 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/yn/qkptzzdx4_q5h519lzspx_l800000w/T/tmpoZsyYd/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 06:05:31 INFO - PROCESS | 1654 | 2016-11-17 06:05:31.736 plugin-container[1655:10476] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x873f, name = 'com.apple.tsm.portname' 06:05:31 INFO - PROCESS | 1654 | See /usr/include/servers/bootstrap_defs.h for the error codes. 06:05:31 INFO - PROCESS | 1654 | 2016-11-17 06:05:31.737 plugin-container[1655:10476] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8d0b, name = 'com.apple.CFPasteboardClient' 06:05:31 INFO - PROCESS | 1654 | See /usr/include/servers/bootstrap_defs.h for the error codes. 06:05:31 INFO - PROCESS | 1654 | 2016-11-17 06:05:31.737 plugin-container[1655:10476] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 06:05:41 INFO - PROCESS | 1654 | RSS: Main: 237805568 06:05:41 INFO - PROCESS | 1654 | 06:05:44 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49244/tests/kraken/driver.html?testName=audio-beat-detection) 06:05:45 INFO - PROCESS | 1654 | RSS: Main: 249905152 06:05:45 INFO - PROCESS | 1654 | 06:05:47 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49244/tests/kraken/driver.html?testName=audio-dft) 06:05:48 INFO - PROCESS | 1654 | RSS: Main: 257327104 06:05:48 INFO - PROCESS | 1654 | 06:05:51 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49244/tests/kraken/driver.html?testName=audio-fft) 06:05:52 INFO - PROCESS | 1654 | RSS: Main: 260071424 06:05:52 INFO - PROCESS | 1654 | 06:05:54 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49244/tests/kraken/driver.html?testName=audio-oscillator) 06:05:55 INFO - PROCESS | 1654 | RSS: Main: 261369856 06:05:55 INFO - PROCESS | 1654 | 06:05:57 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49244/tests/kraken/driver.html?testName=imaging-gaussian-blur) 06:05:57 INFO - PROCESS | 1654 | RSS: Main: 261668864 06:05:57 INFO - PROCESS | 1654 | 06:06:00 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49244/tests/kraken/driver.html?testName=imaging-darkroom) 06:06:01 INFO - PROCESS | 1654 | RSS: Main: 266924032 06:06:01 INFO - PROCESS | 1654 | 06:06:04 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49244/tests/kraken/driver.html?testName=imaging-desaturate) 06:06:04 INFO - PROCESS | 1654 | RSS: Main: 268754944 06:06:04 INFO - PROCESS | 1654 | 06:06:07 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49244/tests/kraken/driver.html?testName=json-parse-financial) 06:06:08 INFO - PROCESS | 1654 | RSS: Main: 268574720 06:06:08 INFO - PROCESS | 1654 | 06:06:09 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49244/tests/kraken/driver.html?testName=json-stringify-tinderbox) 06:06:10 INFO - PROCESS | 1654 | RSS: Main: 267173888 06:06:10 INFO - PROCESS | 1654 | 06:06:12 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-aes) 06:06:13 INFO - PROCESS | 1654 | RSS: Main: 267399168 06:06:13 INFO - PROCESS | 1654 | 06:06:15 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-ccm) 06:06:16 INFO - PROCESS | 1654 | RSS: Main: 267194368 06:06:16 INFO - PROCESS | 1654 | 06:06:19 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 06:06:19 INFO - PROCESS | 1654 | RSS: Main: 267587584 06:06:19 INFO - PROCESS | 1654 | 06:06:22 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 06:06:23 INFO - PROCESS | 1654 | RSS: Main: 267395072 06:06:23 INFO - PROCESS | 1654 | 06:06:25 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49244/tests/kraken/driver.html?testName=ai-astar) 06:06:25 INFO - PROCESS | 1654 | RSS: Main: 269197312 06:06:25 INFO - PROCESS | 1654 | 06:06:25 INFO - PROCESS | 1654 | __start_tp_report 06:06:25 INFO - PROCESS | 1654 | _x_x_mozilla_page_load 06:06:25 INFO - PROCESS | 1654 | _x_x_mozilla_page_load_details 06:06:25 INFO - PROCESS | 1654 | |i|pagename|runs| 06:06:25 INFO - PROCESS | 1654 | |0;ai-astar;105;117;117;117;116;117;117;117;123;143 06:06:25 INFO - PROCESS | 1654 | |1;audio-beat-detection;147;115;116;152;116;118;120;144;157;117 06:06:25 INFO - PROCESS | 1654 | |2;audio-dft;195;227;278;199;191;198;195;194;196;220 06:06:25 INFO - PROCESS | 1654 | |3;audio-fft;86;57;58;84;59;62;60;51;72;71 06:06:25 INFO - PROCESS | 1654 | |4;audio-oscillator;87;71;88;73;71;72;86;70;71;88 06:06:25 INFO - PROCESS | 1654 | |5;imaging-gaussian-blur;89;87;89;87;91;87;92;95;137;82 06:06:25 INFO - PROCESS | 1654 | |6;imaging-darkroom;99;99;99;99;99;129;98;99;113;170 06:06:25 INFO - PROCESS | 1654 | |7;imaging-desaturate;99;95;91;93;96;94;92;95;92;134 06:06:25 INFO - PROCESS | 1654 | |8;json-parse-financial;55;57;56;59;57;59;67;85;83;58 06:06:25 INFO - PROCESS | 1654 | |9;json-stringify-tinderbox;71;75;73;85;101;109;102;85;74;73 06:06:25 INFO - PROCESS | 1654 | |10;stanford-crypto-aes;87;94;90;86;89;89;107;104;87;90 06:06:25 INFO - PROCESS | 1654 | |11;stanford-crypto-ccm;163;174;146;151;151;146;148;187;204;163 06:06:25 INFO - PROCESS | 1654 | |12;stanford-crypto-pbkdf2;179;175;177;173;176;174;176;190;172;174 06:06:25 INFO - PROCESS | 1654 | |13;stanford-crypto-sha256-iterative;69;70;68;59;73;82;92;93;71;73 06:06:25 INFO - PROCESS | 1654 | __end_tp_report 06:06:25 INFO - PROCESS | 1654 | __start_cc_report 06:06:25 INFO - PROCESS | 1654 | _x_x_mozilla_cycle_collect,965 06:06:25 INFO - PROCESS | 1654 | __end_cc_report 06:06:25 INFO - PROCESS | 1654 | __startTimestamp1479391585470__endTimestamp 06:06:25 INFO - PROCESS | 1654 | 06:06:25 INFO - PROCESS | 1654 | ------- Summary: start ------- 06:06:25 INFO - PROCESS | 1654 | Number of tests: 14 06:06:25 INFO - PROCESS | 1654 | 06:06:25 INFO - PROCESS | 1654 | [#0] ai-astar Cycles:10 Average:118.90 Median:117.00 stddev:9.55 (8.2%) stddev-sans-first:8.71 06:06:25 INFO - PROCESS | 1654 | Values: 105.0 117.0 117.0 117.0 116.0 117.0 117.0 117.0 123.0 143.0 06:06:25 INFO - PROCESS | 1654 | 06:06:25 INFO - PROCESS | 1654 | [#1] audio-beat-detection Cycles:10 Average:130.20 Median:132.00 stddev:17.41 (13.2%) stddev-sans-first:17.37 06:06:25 INFO - PROCESS | 1654 | Values: 147.0 115.0 116.0 152.0 116.0 118.0 120.0 144.0 157.0 117.0 06:06:25 INFO - PROCESS | 1654 | 06:06:25 INFO - PROCESS | 1654 | [#2] audio-dft Cycles:10 Average:209.30 Median:198.50 stddev:26.95 (13.6%) stddev-sans-first:28.08 06:06:25 INFO - PROCESS | 1654 | Values: 195.0 227.0 278.0 199.0 191.0 198.0 195.0 194.0 196.0 220.0 06:06:25 INFO - PROCESS | 1654 | 06:06:25 INFO - PROCESS | 1654 | [#3] audio-fft Cycles:10 Average:66.00 Median:66.50 stddev:11.81 (17.8%) stddev-sans-first:10.07 06:06:25 INFO - PROCESS | 1654 | Values: 86.0 57.0 58.0 84.0 59.0 62.0 60.0 51.0 72.0 71.0 06:06:25 INFO - PROCESS | 1654 | 06:06:25 INFO - PROCESS | 1654 | [#4] audio-oscillator Cycles:10 Average:77.70 Median:79.50 stddev:8.27 (10.4%) stddev-sans-first:8.06 06:06:25 INFO - PROCESS | 1654 | Values: 87.0 71.0 88.0 73.0 71.0 72.0 86.0 70.0 71.0 88.0 06:06:25 INFO - PROCESS | 1654 | 06:06:25 INFO - PROCESS | 1654 | [#5] imaging-gaussian-blur Cycles:10 Average:93.60 Median:89.00 stddev:15.64 (17.6%) stddev-sans-first:16.50 06:06:25 INFO - PROCESS | 1654 | Values: 89.0 87.0 89.0 87.0 91.0 87.0 92.0 95.0 137.0 82.0 06:06:25 INFO - PROCESS | 1654 | 06:06:25 INFO - PROCESS | 1654 | [#6] imaging-darkroom Cycles:10 Average:110.40 Median:99.00 stddev:23.19 (23.4%) stddev-sans-first:24.22 06:06:25 INFO - PROCESS | 1654 | Values: 99.0 99.0 99.0 99.0 99.0 129.0 98.0 99.0 113.0 170.0 06:06:25 INFO - PROCESS | 1654 | 06:06:25 INFO - PROCESS | 1654 | [#7] imaging-desaturate Cycles:10 Average:98.10 Median:94.50 stddev:12.83 (13.6%) stddev-sans-first:13.60 06:06:25 INFO - PROCESS | 1654 | Values: 99.0 95.0 91.0 93.0 96.0 94.0 92.0 95.0 92.0 134.0 06:06:25 INFO - PROCESS | 1654 | 06:06:25 INFO - PROCESS | 1654 | [#8] json-parse-financial Cycles:10 Average:63.60 Median:59.00 stddev:11.25 (19.1%) stddev-sans-first:11.49 06:06:25 INFO - PROCESS | 1654 | Values: 55.0 57.0 56.0 59.0 57.0 59.0 67.0 85.0 83.0 58.0 06:06:25 INFO - PROCESS | 1654 | 06:06:25 INFO - PROCESS | 1654 | [#9] json-stringify-tinderbox Cycles:10 Average:84.80 Median:73.50 stddev:14.24 (19.4%) stddev-sans-first:14.20 06:06:25 INFO - PROCESS | 1654 | Values: 71.0 75.0 73.0 85.0 101.0 109.0 102.0 85.0 74.0 73.0 06:06:25 INFO - PROCESS | 1654 | 06:06:25 INFO - PROCESS | 1654 | [#10] stanford-crypto-aes Cycles:10 Average:92.30 Median:89.00 stddev:7.33 (8.2%) stddev-sans-first:7.52 06:06:25 INFO - PROCESS | 1654 | Values: 87.0 94.0 90.0 86.0 89.0 89.0 107.0 104.0 87.0 90.0 06:06:25 INFO - PROCESS | 1654 | 06:06:25 INFO - PROCESS | 1654 | [#11] stanford-crypto-ccm Cycles:10 Average:163.30 Median:163.00 stddev:19.63 (12.0%) stddev-sans-first:20.82 06:06:25 INFO - PROCESS | 1654 | Values: 163.0 174.0 146.0 151.0 151.0 146.0 148.0 187.0 204.0 163.0 06:06:25 INFO - PROCESS | 1654 | 06:06:25 INFO - PROCESS | 1654 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:176.60 Median:176.00 stddev:5.13 (2.9%) stddev-sans-first:5.36 06:06:25 INFO - PROCESS | 1654 | Values: 179.0 175.0 177.0 173.0 176.0 174.0 176.0 190.0 172.0 174.0 06:06:25 INFO - PROCESS | 1654 | 06:06:25 INFO - PROCESS | 1654 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:75.00 Median:73.00 stddev:10.81 (14.8%) stddev-sans-first:11.25 06:06:25 INFO - PROCESS | 1654 | Values: 69.0 70.0 68.0 59.0 73.0 82.0 92.0 93.0 71.0 73.0 06:06:25 INFO - PROCESS | 1654 | -------- Summary: end -------- 06:06:25 INFO - PROCESS | 1654 | 06:06:26 INFO - TEST-INFO | 1654: exit 0 06:06:26 INFO - TEST-OK | kraken | took 60588ms 06:06:26 INFO - SUITE-END | took 1176s 06:06:26 INFO - Completed test suite (00:19:37) 06:06:26 INFO - javascript benchmark 06:06:26 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 6883.889071430902, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3059.3575349176676, 3404.165979335062, 3423.640517745638, 3433.8798142065034, 3478.008767501263, 5661.1508273758955, 5744.62659927105, 5775.989201889668, 5814.331351894522, 5817.360090390059, 5724.398938111501, 5789.82630521084, 5862.706864656771, 5914.112883067542, 5948.494377977866, 5230.163173892178, 5243.292155559008, 5308.442613525582, 5317.601179911503, 5406.59450541209, 5406.297181366436, 5414.972925135388, 5434.7826086956675, 5437.048516509615, 5452.2094296327, 5227.26818245446, 5325.893482130375, 5353.839384818442, 5376.11294136467, 5413.756380962846, 5232.6598771080135, 5265.921011184835, 5278.313819203479, 5297.046531624306, 5299.920501192447, 4461.57615026572, 4654.813807447698, 5203.4796520348045, 5231.790728370861, 5240.213967904807, 4862.149123903303, 5231.869203269949, 5867.823965281048, 5886.293644762656, 5903.026000709878, 6197.318294987548, 6206.937930620681, 6212.9068063979075, 6282.403171698681, 6316.147320111818, 6261.092141639482, 6292.590981586182, 6301.432871041582, 6302.716377763011, 6317.684115794192, 5974.761009559569, 5987.856515139407, 5992.400759924017, 6006.78976235834, 6010.579259451796, 6206.0690896365, 6266.154069200599, 6270.905936410957, 6287.2140982377205, 6394.680265986682, 6256.405641464054, 6258.311585725566, 6266.154069200599, 6282.0, 6305.905411418832, 5549.611527193058, 6100.581173165827, 6326.778562750282, 6334.3665633436285, 6340.270868850049, 4908.361912951294, 5983.6708981005595, 6135.140800782589, 6174.320824709278, 6206.224221972254, 6220.564560480813, 6270.404311590483, 6292.528060395489, 6304.779332723333, 6338.366163383717, 4968.950310496849, 5015.924761128586, 5015.949840501621, 5037.093323201786, 5078.822241221539, 4346.587074227943, 4422.071365013319, 4450.666200034945, 4456.710313829591, 4484.9103017940115, 4884.3650325457465, 5940.376260492672, 5953.0, 5955.464008239279, 5992.490638295706, 6231.875362492816, 6247.094171345129, 6260.217472815898, 6260.593061450991, 6276.717547710364, 5830.533557315404, 5857.414258574108, 5892.145638882338, 5918.852028699231, 6012.218411606463, 3882.5535063467496, 5459.69971651555, 5470.56235501159, 5471.316085489314, 5541.168824676251, 4700.2479603264, 5273.789048438096, 6276.560640755103, 6303.212098487689, 6368.426841584094, 6250.937490625218, 6286.842828929313, 6295.622262664255, 6299.338569450325, 6337.144485494399, 5334.733263336895, 6382.234131904015, 6398.168238129014, 6400.615963042233, 6465.385788350287, 6133.824358647991, 6178.289496707936, 6179.0, 6187.81436556904, 6260.217472815898, 5677.807660391199, 5988.131720900532, 6215.409536094064, 6256.311805701278, 6273.52948528853, 6208.0, 6222.191115155001, 6240.375962403905, 6247.0, 6275.34108918557, 6090.604110732788, 6110.012029148408, 6110.1445797587485, 6227.813165605039, 6244.937550624436, 5822.70886455667, 5860.472557469848, 6059.242594675666, 6099.420555047263, 6134.589663226485, 6206.751729930933, 6215.96892015537, 6230.937690623035, 6237.750489980532, 6268.059791031382, 6081.6351018939, 6084.574079814371, 6100.176476175837, 6122.632642041492, 6168.383161683796], "value": 5716.333731315872, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [473.3089689053981, 477.65370106672646, 519.7687029271974, 521.2702216896342, 561.9859503512414, 26466.86766566167, 31591.842040789794, 33132.006039818785, 34480.31039379211, 34541.30917381651, 32601.021969340956, 33044.834775826144, 33105.83447082761, 33155.66844331556, 33170.50244246338, 7119.750808721696, 7289.343959043685, 7299.19708832028, 7301.853962920738, 7312.305330993574, 7534.510256833289, 7612.238776122399, 7625.31372176504, 7634.389248860078, 7663.080430348366, 7581.50720203185, 7623.0090088288725, 7631.3894888408795, 7641.61791910405, 7656.3109320161175, 7571.886421703679, 7634.541927484369, 7638.045244344457, 7654.11977622575, 7662.080550333968, 7539.0, 7577.583232922187, 7588.27911348421, 7602.695892164307, 7617.27635874591, 31301.37397252042, 31470.0, 31526.211844703837, 31546.526802097986, 31646.208844778834, 3175.4442972479724, 3219.581454410935, 3235.692609202122, 3277.5083737439336, 3511.596166440841, 17689.115544222866, 18095.276188952557, 19107.61784764297, 20611.175552977864, 21383.25158619441, 2366.3137690069857, 2481.027002042682, 2851.600775891377, 2874.3963767608825, 2885.0623547346986, 412516.937415311, 414039.8596014031, 502704.0, 506297.0, 511995.0, 64005.67997159984, 64766.0, 64776.35223647797, 64982.35017649858, 65087.0, 67811.0, 68226.97659535111, 68941.3105868945, 68976.0, 69079.0, 13487.123336983064, 16144.515664530083, 16145.757813632805, 16187.514374568782, 16306.510804675878, 12329.815052774216, 12395.256284622772, 12426.316552589695, 12428.813567796491, 12435.0, 19265.036748162536, 19926.50183745389, 19928.50178745514, 19961.0, 19990.0, 8308.335333173332, 8362.45644033136, 8387.916120838714, 8391.538465384463, 8454.323654107657, 850.3792231670791, 929.9070092990783, 945.4563625915126, 956.3544607389983, 1010.2524132142308, 26430.678466077003, 28339.858300708776, 28843.711562884524, 29401.0, 29638.703612963593, 906.769347405377, 1007.8762180169206, 1013.716159475348, 1048.09863517375, 1071.7320669832543, 14877.181755003363, 15189.772153417707, 15261.084334939938, 15389.0, 15635.51462611073, 553997.9901306482, 665165.0, 667666.0, 669354.0, 669616.0, 51183.0, 51933.22100168425, 52082.739586301825, 52232.0, 52386.21420678693, 1336.9665758356118, 1340.9329533523285, 1389.1387339849357, 1392.484780631173, 1397.3781667158016, 28309.716902831533, 28315.575266370608, 28694.856525717238, 28796.712032879404, 29057.0, 48374.27438588354, 48970.0, 49136.50863491462, 49148.75425622849, 49189.508104918496, 600965.0, 607811.0, 608444.0, 609754.0, 611101.0, 24675.753242467345, 24720.381990450383, 25033.374165646004, 25056.0, 25097.121600744616, 31444.52833207458, 39099.0, 39288.0, 39352.606473934895, 40969.0, 33282.16794580155, 34102.31795364029, 34169.58304169563, 35091.82454087815, 35165.82417087898, 34073.65926340705, 34181.65818341785, 34829.825850870584, 34917.650823491436, 35315.823420882734, 34746.78386256469, 35349.116272092375, 35457.290854182254, 35515.46726799152, 35597.28805423929, 30661.0, 31583.101462161863, 31810.204744881565, 32098.67901320957, 32164.517532237503, 23530.0, 23817.928193231, 23980.598446483964, 24166.87916560476, 24291.271261861464], "value": 18210.45999354129, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [357.6459305287766, 362.24471975930186, 365.89206184175674, 374.35423893783246, 390.91399892023765, 13056.347182640877, 14247.71504569908, 15571.377144914204, 15613.68772624547, 16074.437394691187, 17463.0, 18960.241590336405, 18964.051797410142, 19211.42365729027, 19265.940373279464, 5010.924836127461, 5413.26920865684, 5417.566594672437, 5427.83716488506, 5437.891242175174, 3033.848307584623, 4125.690573207006, 5263.131583288753, 5276.736163191844, 5400.189971504266, 5276.234945932857, 5304.6286759926825, 5322.0952438085615, 5327.653702509344, 5335.1997200419855, 4575.633949284066, 5281.0, 5296.364436267634, 5321.680699158043, 5338.412774594792, 4746.3862286822305, 4762.952370476286, 4773.140834649762, 4778.2115950868065, 4790.616750659939, 12109.970652494549, 17418.390356337593, 18413.53966150857, 19661.803381966285, 20889.477763056046, 2066.2045112631595, 2174.2388665181097, 2281.691971583848, 2408.963865542018, 2712.8372297662004, 7435.330820226151, 8760.420460271289, 8983.056779038237, 9444.669436569755, 9463.716088517356, 1620.2141961148834, 1710.7433884917361, 1721.8966861988195, 1773.689604319239, 1816.2825683854803, 119201.0, 124588.0, 125342.99999999908, 125882.0, 126585.0, 11016.944915275373, 11184.44077796107, 11260.662180134608, 11382.146339024606, 11517.366544839946, 14199.85800142006, 14230.14619122856, 14257.215853128178, 14279.714405712035, 14290.499832505813, 6764.222114456802, 8032.4778889372, 8060.596970151469, 8219.0, 8268.875966860502, 7848.7645370638975, 7887.290143887078, 7959.0, 8093.595320233965, 8099.797505062303, 4105.2405305018665, 4380.145871555016, 4470.776461176928, 4476.574725401082, 4538.977305113519, 1564.1084581788498, 1850.0934542074287, 1926.0369815092454, 1935.5741736817877, 1938.7382703335152, 846.551327796269, 888.6001299415288, 937.9869740680049, 940.1585580905052, 940.9294302927308, 16179.191040447931, 20463.18147274122, 20583.0, 21433.78566214349, 21618.0, 860.7691001867265, 901.6889173235223, 945.8013817098487, 960.5293406230896, 1036.8600238967642, 8510.74467765968, 9541.809163816684, 11195.216334856645, 11335.716607084889, 11344.149188810872, 554739.6208303326, 561218.0, 576132.0, 580079.0, 584088.0, 126723.0, 126831.36584317204, 127795.0, 128068.0, 131205.0, 912.9863052054092, 952.3238500664468, 972.0522490571636, 973.73709098543, 978.2467500024941, 18743.90628046851, 18788.624227515647, 19064.618707626047, 19174.80825191786, 19312.517187069872, 53088.469115308355, 55155.0, 55688.0, 56188.43811561832, 56815.71592142013, 74962.2518874065, 75241.24758752562, 75330.46695330423, 75506.62246688732, 78260.0, 5477.67133971963, 6400.583962042452, 7014.579125252501, 7275.163356214103, 7426.925730742623, 9847.803043938939, 10925.180611454014, 11933.880661193278, 12519.248845069325, 12637.17858339205, 8305.916940830755, 10317.0, 10745.140388769036, 10916.836247456453, 10934.398608076313, 8381.664733410595, 8621.525816080175, 10032.749181270527, 10202.540885660312, 10539.736506587089, 8672.609732561923, 10792.730181745519, 10868.021878031015, 10924.0, 11036.11711063129, 7016.403605693573, 7039.084918960502, 7204.783856484314, 7266.818329541804, 7322.707091716485, 10152.898471015194, 10381.480925953823, 10772.946135269274, 11036.503357348778, 11099.001089901947], "value": 8865.611018182455, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1300.5123078845434, 1447.5512591096765, 1519.77203419487, 1594.4339759385418, 1747.0, 7208.639568021604, 7474.775756727293, 7687.500312479685, 8109.675612975481, 8118.391120665951, 7660.502067365617, 7926.6829326827, 7952.801179970489, 8020.919790802091, 8056.0, 1723.8965662060255, 2024.564718585504, 2067.14213601356, 2072.1918451803735, 2076.4497408186844, 2058.2178772066595, 2089.5925294567564, 2094.7486301643826, 2106.2628080171894, 2112.5458026524293, 2045.7442819647545, 2047.9180832766672, 2068.338131797825, 2074.2429013410074, 2078.490769761411, 2047.5597746484502, 2069.4826293426645, 2073.1292857000094, 2074.1703318672503, 2078.5842831433774, 2080.771115177329, 2084.5726626041624, 2085.176355339632, 2092.6442504774227, 2101.432613194444, 7924.286814186751, 7959.9602001989615, 7987.281144697005, 7999.600019999035, 8000.0, 4978.2034874419915, 5152.227165925104, 5152.587792976553, 5158.92261616076, 5169.53474187325, 3227.7256433203206, 3295.1762059485127, 3389.2204792897523, 3427.3145370925913, 3427.485877118427, 940.4592359393308, 984.916282116021, 1009.1321463541349, 1023.687775228555, 1032.7005168501125, 9563.952180239125, 10540.893206213314, 10898.400587967659, 10965.0, 10982.176336774693, 4328.567143285646, 4595.609373203315, 4695.624350051988, 4703.871070942949, 4705.200115980291, 9323.393979391318, 9357.766055848659, 9394.248460123174, 9416.340856140005, 9426.010268921802, 5174.482551744795, 5180.093483640348, 5183.766730497136, 5190.24741412493, 5193.68837869729, 4153.169366126786, 4570.085982803453, 4684.391029166187, 4725.3384526166365, 4748.169070412733, 3732.738708290448, 3765.1151979284846, 3821.3694740368082, 3856.151646637735, 3866.2460820140363, 1256.836611240551, 1262.7095767973233, 1265.436880588148, 1267.3029833591488, 1280.2382582363664, 748.8280840484624, 856.1524091149842, 880.506916126971, 887.924526415249, 888.9688860889967, 5488.368837583649, 5498.065328894098, 5572.498475137257, 5668.971655141698, 5697.800576979786, 769.7382889817488, 865.1694373401605, 869.4956924983494, 874.4665753890122, 884.045231150356, 3242.4974129010043, 3707.9073023174637, 3776.962230377716, 3795.2978698940233, 3799.544054713452, 9610.327277090537, 10049.346792458467, 10074.093331600192, 10143.847842282372, 10172.389656620626, 7709.38324934004, 10129.544170512345, 10151.847722284172, 10153.64462243818, 10200.6429774959, 6866.588004719732, 7364.96317518409, 7797.532148071134, 7831.843363132705, 7844.960775195973, 4409.492908315585, 5061.924071138862, 5160.838811267435, 5278.2346559749385, 5449.6730196188355, 7565.886511702439, 9285.907140928775, 9305.395149315273, 9444.48055356962, 9480.95259523698, 9332.346735728433, 9420.0, 9427.481488517922, 9531.47576883278, 9535.47554884488, 6937.687804048931, 7084.929150708633, 7139.178994415709, 7245.0, 7335.303146201102, 7957.283844453796, 7997.640106195351, 8020.0, 8042.557659328559, 8067.515949043076, 6804.215968976753, 6888.758893438906, 7043.788686339418, 7146.0, 7216.278372162742, 6042.788502402571, 6456.515761317826, 6912.308769123048, 7029.0862187915245, 7120.964395177991, 6446.677666116581, 6613.735450581923, 6672.299408562031, 6687.030380594883, 6713.227978782503, 4827.275908613736, 4858.951410485991, 4876.902461950812, 4943.752812359296, 4949.480304567968, 4743.881402964954, 4808.134535783593, 4897.828575999965, 4965.230389289666, 4979.900401992012], "value": 4270.482307130372, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [296.2800395040053, 296.488557238763, 307.3100888505306, 308.4986896293522, 313.43112251263955, 4803.7357945313, 4882.755862206885, 5373.032854086267, 5487.094629386152, 5522.309711286094, 5134.614903882214, 5282.0, 5353.0, 5390.811321603745, 5405.270338479063, 3375.4936759486027, 3970.285348637244, 4145.170965806836, 4145.7719825409595, 4166.437530933331, 3480.738944579167, 4000.8599699010542, 4042.43405923171, 4142.606452387018, 4178.979105104486, 3418.316336732651, 3654.7076233901357, 3663.249033948034, 3742.139307959171, 4078.4697989261354, 3379.7465190110715, 3667.5232219811255, 3743.12036671382, 4064.349704047353, 4089.4683691120263, 3341.63242043375, 3473.6352682968177, 3580.7672501287593, 3593.2633810069133, 3597.874074407383, 3738.2523495301048, 4206.980649288328, 4780.306855505967, 4927.36386033474, 5155.7980246099305, 3935.0, 4720.59874910633, 4724.684904396855, 4734.502877197879, 4735.455422626374, 5013.573846223076, 5023.623228257895, 5448.673079615236, 5470.769076957692, 5632.549396048306, 3784.8675296364772, 3919.0, 3937.7243592948507, 3967.2858885400906, 3994.7003974702016, 5672.631278966854, 5997.8800423991715, 6001.679630481287, 6012.038073908154, 6081.685481412641, 4000.239954408653, 4004.0, 4008.8396464141692, 4010.8195131219163, 4022.9798851005557, 3783.659470647655, 4014.237294913957, 4053.9054455296905, 4073.368627862686, 4080.6939479539155, 3899.0447340401215, 4665.836695715634, 4688.765561721969, 4691.695039822401, 4702.811887524461, 5754.367019627837, 5779.381773103538, 5781.624194427349, 5781.94218057814, 5784.797532086357, 1107.0866535108569, 1198.8261702053328, 1241.7954584053455, 1249.2941488059218, 1249.4939549482474, 844.9986765682686, 903.6701603914524, 907.088376181933, 916.4923700512619, 925.9074092590687, 4741.66808323421, 4937.987712518925, 4963.230699241623, 5034.194528875362, 5085.042258730416, 4646.09510100857, 5030.798768049311, 5440.0, 5562.499375056264, 5596.362364463132, 5015.229440255625, 5026.411398129966, 5033.723145227048, 5042.218456139304, 5062.582476906472, 2446.7001019042045, 2698.623701912, 2768.4878297515024, 2913.46100971321, 2923.532234842415, 4886.877828054256, 4890.24201248807, 5139.997700448452, 5539.169124631257, 5639.971800140973, 4815.879603009883, 4835.8065677372515, 5610.382857885629, 5626.634268772516, 5671.517920976763, 5203.661761985459, 5254.97372513135, 5658.462446067617, 5667.744951477193, 5685.516731077904, 4992.500749924979, 5070.290159377616, 5165.276861239505, 5309.309789727311, 5456.236126942152, 4469.530699276659, 5411.810586629449, 5426.375966763872, 5431.782728690808, 5463.535599474089, 4782.402199725035, 4908.674657842291, 5641.210230567806, 5646.322441307069, 5684.459976302244, 2973.2120987937783, 3468.358353704472, 3493.8427770750886, 3738.5044982941035, 3787.8295476704166, 5421.620486565903, 5449.264349312792, 5483.369412517452, 5490.3411590609385, 5561.193626924154, 4382.715123517089, 4851.80592776285, 4852.417709874838, 5329.89469159672, 5380.0, 5288.4182739897815, 5725.456081672234, 5733.168690539932, 5757.568182386254, 5758.452946970031, 4808.2547205183255, 5267.841964741064, 5345.705986170642, 5526.364468086222, 5664.977865715388, 5280.709560974184, 5550.47270509301, 5799.941020937676, 5862.794802181903, 5871.943050250996, 5300.7084610345255, 5614.410486898817, 5683.801066962802, 5699.458551437607, 5703.201551782837], "value": 4019.5261779199122, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [643.2377632505481, 690.5511417578573, 714.9070620819299, 751.1587022534759, 786.2766255045359, 12479.500819967201, 12976.870231297684, 13221.140625859336, 13321.866781332184, 13428.395722192501, 12255.571055013077, 12479.68800779981, 12482.687932801684, 12518.874811251884, 12554.68613284666, 812.0174588747608, 819.9590020498952, 828.7016673997356, 830.8006078541137, 831.2726364431123, 649.3571364349308, 668.7826456401665, 687.1582311668192, 697.5779653309751, 740.2375553180233, 852.6078004118082, 865.8268346330727, 866.3199388480044, 870.7779516223386, 873.8601823708208, 849.524266410812, 863.4215075899132, 865.4547634989916, 866.9393142480061, 888.4447220487195, 642.1523588862703, 645.9870802583969, 646.8059582125344, 647.1037612906111, 648.4779752299397, 11498.885011149865, 11586.536538538448, 11741.295522268605, 11862.940685296604, 12075.577354792627, 9639.469829159394, 10328.79342413155, 10801.891981080167, 10915.0, 10940.179486538542, 9199.310051746079, 9259.861102083474, 9292.117248861347, 9344.392614480037, 9559.378640388353, 3019.3961207758534, 3121.500559910426, 3169.2235402326346, 3179.2528755742383, 3210.1011716719354, 86784.13215867824, 89210.3236902894, 89950.0, 90508.09491905062, 91470.0, 8055.436119471698, 8069.475484093515, 8130.674773009132, 8281.171882811792, 8411.158884111663, 16000.279987400594, 17919.74561780689, 17998.5500362492, 18041.0, 18108.456746297634, 7623.695052197961, 7624.390048796083, 7644.617769111634, 7673.577953212515, 7820.921790782019, 4223.66210703143, 4319.092990471973, 4322.589354011364, 4327.394164816928, 4328.156009578165, 3213.2609499815176, 3295.8352082395786, 3375.392429362689, 3391.796492210475, 3397.1676939150066, 643.5173619785161, 648.5913874259187, 653.496807458264, 662.57595139111, 664.0636702249805, 531.3278702441414, 573.0172753727378, 590.2268028882178, 601.6269912654095, 615.7998650438625, 7898.9477042406015, 8091.433599647969, 8790.384673072824, 8880.644774209088, 8943.865842012376, 611.5382885921101, 611.5688439650036, 653.79048759794, 658.9505787065989, 662.7216569041015, 3699.9445008324897, 4018.0422284050187, 4024.6377825995214, 4270.12462445198, 4369.956300437018, 88344.0, 93324.0, 93827.06172938184, 118674.0, 120594.55838091426, 67904.0, 72588.0, 102794.45808312882, 103438.0, 105931.0, 82884.58557707173, 90796.99999999868, 90885.0, 92748.68128296846, 93093.5345323269, 6759.898601520883, 6953.548019378724, 8268.793280168044, 8314.6674133034, 8503.702370417006, 42200.0, 49843.0, 54556.727216363666, 56678.43321566732, 58651.70674146602, 56077.719611401684, 83874.0, 83945.74081388906, 84644.15355846363, 85521.0, 9827.557759900965, 10239.7440063999, 10719.936906312576, 11109.94445027802, 11120.388378639253, 10323.070923616911, 11219.775604488028, 11963.820542691692, 12064.336461494702, 12345.074119440898, 9179.862302065341, 11342.262752921033, 11389.0, 11395.373254470753, 11825.655930246528, 11216.66350009501, 11315.773684526428, 11402.48688808989, 11724.765504689687, 11765.294082355085, 10894.564217431214, 11311.264767790068, 11413.429328533373, 11447.313161210353, 11561.479733411848, 9240.584173712065, 9566.66516671913, 9579.994100619613, 9751.853722194315, 9855.704328870146, 7200.855982880418, 9715.319927605, 9780.559874805509, 9807.264455165749, 9846.409215447096], "value": 6716.755825505897, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1559.8, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [105.0, 117.0, 117.0, 117.0, 116.0, 117.0, 117.0, 117.0, 123.0, 143.0], "value": 118.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 115.0, 116.0, 152.0, 116.0, 118.0, 120.0, 144.0, 157.0, 117.0], "value": 130.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [195.0, 227.0, 278.0, 199.0, 191.0, 198.0, 195.0, 194.0, 196.0, 220.0], "value": 209.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 57.0, 58.0, 84.0, 59.0, 62.0, 60.0, 51.0, 72.0, 71.0], "value": 66.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 71.0, 88.0, 73.0, 71.0, 72.0, 86.0, 70.0, 71.0, 88.0], "value": 77.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 87.0, 89.0, 87.0, 91.0, 87.0, 92.0, 95.0, 137.0, 82.0], "value": 93.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [99.0, 99.0, 99.0, 99.0, 99.0, 129.0, 98.0, 99.0, 113.0, 170.0], "value": 110.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [99.0, 95.0, 91.0, 93.0, 96.0, 94.0, 92.0, 95.0, 92.0, 134.0], "value": 98.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 57.0, 56.0, 59.0, 57.0, 59.0, 67.0, 85.0, 83.0, 58.0], "value": 63.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 75.0, 73.0, 85.0, 101.0, 109.0, 102.0, 85.0, 74.0, 73.0], "value": 84.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 94.0, 90.0, 86.0, 89.0, 89.0, 107.0, 104.0, 87.0, 90.0], "value": 92.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 174.0, 146.0, 151.0, 151.0, 146.0, 148.0, 187.0, 204.0, 163.0], "value": 163.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [179.0, 175.0, 177.0, 173.0, 176.0, 174.0, 176.0, 190.0, 172.0, 174.0], "value": 176.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 70.0, 68.0, 59.0, 73.0, 82.0, 92.0, 93.0, 71.0, 73.0], "value": 75.0, "unit": "score"}]}]} 06:06:26 INFO - Return code: 0 06:06:26 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 06:06:26 ERROR - # TBPL SUCCESS # 06:06:26 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 06:06:26 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 06:06:26 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 06:06:26 INFO - 2016-11-17 06:06:26.800 screenresolution[1659:10868] starting screenresolution argv=screenresolution get 06:06:26 INFO - 2016-11-17 06:06:26.803 screenresolution[1659:10868] Display 0: 1600x1200x32@60 06:06:26 INFO - 2016-11-17 06:06:26.808 screenresolution[1660:10871] starting screenresolution argv=screenresolution list 06:06:26 INFO - Available Modes on Display 0 06:06:26 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 06:06:26 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 06:06:26 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 06:06:26 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 06:06:27 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 06:06:27 INFO - Intel Iris: 06:06:27 INFO - Chipset Model: Intel Iris 06:06:27 INFO - Type: GPU 06:06:27 INFO - Bus: Built-In 06:06:27 INFO - VRAM (Dynamic, Max): 1536 MB 06:06:27 INFO - Vendor: Intel (0x8086) 06:06:27 INFO - Device ID: 0x0a2e 06:06:27 INFO - Revision ID: 0x0009 06:06:27 INFO - Displays: 06:06:27 INFO - Display: 06:06:27 INFO - Resolution: 1600 x 1200 @ 60 Hz 06:06:27 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 06:06:27 INFO - Display Serial Number: 1600x1200 60 06:06:27 INFO - Main Display: Yes 06:06:27 INFO - Mirror: Off 06:06:27 INFO - Online: Yes 06:06:27 INFO - Rotation: Supported 06:06:27 INFO - Return code: 0 06:06:27 INFO - Running post-action listener: _resource_record_post_action 06:06:27 INFO - [mozharness: 2016-11-17 14:06:27.049322Z] Finished run-tests step (success) 06:06:27 INFO - Running post-run listener: _resource_record_post_run 06:06:27 INFO - Total resource usage - Wall time: 1211s; CPU: 55.0%; Read bytes: 38922240; Write bytes: 702761984; Read time: 249; Write time: 2012 06:06:27 INFO - TinderboxPrint: CPU usage
54.6% 06:06:27 INFO - TinderboxPrint: I/O read bytes / time
38,922,240 / 249 06:06:27 INFO - TinderboxPrint: I/O write bytes / time
702,761,984 / 2,012 06:06:27 INFO - TinderboxPrint: CPU idle
2,200.2 (45.4%) 06:06:27 INFO - TinderboxPrint: CPU system
979.1 (20.2%) 06:06:27 INFO - TinderboxPrint: CPU user
1,666.4 (34.4%) 06:06:27 INFO - TinderboxPrint: Swap in / out
437,272,576 / 0 06:06:27 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 376877568; Write bytes: 370350080; Read time: 28255; Write time: 779 06:06:27 INFO - run-tests - Wall time: 1178s; CPU: 55.0%; Read bytes: 37980160; Write bytes: 302490624; Read time: 236; Write time: 1161 06:06:27 INFO - Running post-run listener: _upload_blobber_files 06:06:27 INFO - Blob upload gear active. 06:06:27 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 06:06:27 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 06:06:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 06:06:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 06:06:27 INFO - (blobuploader) - INFO - Open directory for files ... 06:06:27 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 06:06:27 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:06:27 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:06:29 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 06:06:29 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:06:29 INFO - (blobuploader) - INFO - Done attempting. 06:06:29 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 06:06:29 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:06:29 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:06:29 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 06:06:29 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:06:29 INFO - (blobuploader) - INFO - Done attempting. 06:06:29 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 06:06:29 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:06:29 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:06:34 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 06:06:34 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:06:34 INFO - (blobuploader) - INFO - Done attempting. 06:06:34 INFO - (blobuploader) - INFO - Iteration through files over. 06:06:34 INFO - Return code: 0 06:06:34 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 06:06:34 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 06:06:34 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f7c82c9199ff7b9f4a6687c7ba210fa71f0293fa49b91c8fad6eb7bd07641b0ec1c4cc889e9ba74bd2d5b58741185334a0e5c746196b0424dea183828c865c61", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5d66bc4363fe2e0411361ce8fce01174c05d9c116fa9a3cf9409f5117320837bf138cdf1ea6e0c643af62c4ce60c1c01a45cd8659b29a28bea7e98346a8bd62e", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/74dbb7a9db75eddf730531692ad45dd03ba5e975b94797cffb8ba84d11c9273223411d7c23f411f5bd8b114264413092dd9f05b741d0a18da13a805a3e172abc"} 06:06:34 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 06:06:34 INFO - Writing to file /builds/slave/test/properties/blobber_files 06:06:34 INFO - Contents: 06:06:34 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f7c82c9199ff7b9f4a6687c7ba210fa71f0293fa49b91c8fad6eb7bd07641b0ec1c4cc889e9ba74bd2d5b58741185334a0e5c746196b0424dea183828c865c61", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5d66bc4363fe2e0411361ce8fce01174c05d9c116fa9a3cf9409f5117320837bf138cdf1ea6e0c643af62c4ce60c1c01a45cd8659b29a28bea7e98346a8bd62e", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/74dbb7a9db75eddf730531692ad45dd03ba5e975b94797cffb8ba84d11c9273223411d7c23f411f5bd8b114264413092dd9f05b741d0a18da13a805a3e172abc"} 06:06:34 INFO - Running post-run listener: copy_logs_to_upload_dir 06:06:34 INFO - Copying logs to upload dir... 06:06:34 INFO - mkdir: /builds/slave/test/build/upload/logs 06:06:34 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1273.836963 ========= master_lag: 0.10 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2016-11-17 06:06:34.612830) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-17 06:06:34.616075) ========= 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.WG4E4ZgQLW/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.UOENEx601L/Listeners TMPDIR=/var/folders/yn/qkptzzdx4_q5h519lzspx_l800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f7c82c9199ff7b9f4a6687c7ba210fa71f0293fa49b91c8fad6eb7bd07641b0ec1c4cc889e9ba74bd2d5b58741185334a0e5c746196b0424dea183828c865c61", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5d66bc4363fe2e0411361ce8fce01174c05d9c116fa9a3cf9409f5117320837bf138cdf1ea6e0c643af62c4ce60c1c01a45cd8659b29a28bea7e98346a8bd62e", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/74dbb7a9db75eddf730531692ad45dd03ba5e975b94797cffb8ba84d11c9273223411d7c23f411f5bd8b114264413092dd9f05b741d0a18da13a805a3e172abc"} build_url:https://queue.taskcluster.net/v1/task/MsCrFnpwQE-iTu3tgEVw1g/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011311 build_url: 'https://queue.taskcluster.net/v1/task/MsCrFnpwQE-iTu3tgEVw1g/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f7c82c9199ff7b9f4a6687c7ba210fa71f0293fa49b91c8fad6eb7bd07641b0ec1c4cc889e9ba74bd2d5b58741185334a0e5c746196b0424dea183828c865c61", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5d66bc4363fe2e0411361ce8fce01174c05d9c116fa9a3cf9409f5117320837bf138cdf1ea6e0c643af62c4ce60c1c01a45cd8659b29a28bea7e98346a8bd62e", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/74dbb7a9db75eddf730531692ad45dd03ba5e975b94797cffb8ba84d11c9273223411d7c23f411f5bd8b114264413092dd9f05b741d0a18da13a805a3e172abc"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-17 06:06:34.648621) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-17 06:06:34.648950) ========= 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.WG4E4ZgQLW/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.UOENEx601L/Listeners TMPDIR=/var/folders/yn/qkptzzdx4_q5h519lzspx_l800000w/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.005162 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-17 06:06:34.697927) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-17 06:06:34.698262) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-17 06:06:34.698596) ========= ========= Total master_lag: 0.23 =========