builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0088 starttime: 1486640994.11 results: success (0) buildid: 20170209022422 builduid: dd1381c013c5495ab38d92f9b7adaad1 revision: 70debab476881121ddfe92fc60c80cfae0a63bc7 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-02-09 03:49:54.107226) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-02-09 03:49:54.107780) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-09 03:49:54.108175) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-09 03:49:54.134453) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-09 03:49:54.134942) ========= 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.K6QiyQhPsn/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.pkvxAnA7w6/Listeners TMPDIR=/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/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 --2017-02-09 03:49:54-- 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% 164M=0s 2017-02-09 03:49:54 (164 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.129001 ========= master_lag: 0.32 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-09 03:49:54.588090) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-09 03:49:54.588399) ========= 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.K6QiyQhPsn/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.pkvxAnA7w6/Listeners TMPDIR=/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/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.028598 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-09 03:49:54.643434) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-02-09 03:49:54.644175) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 70debab476881121ddfe92fc60c80cfae0a63bc7 --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 70debab476881121ddfe92fc60c80cfae0a63bc7 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.K6QiyQhPsn/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.pkvxAnA7w6/Listeners TMPDIR=/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/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 2017-02-09 03:49:54,747 truncating revision to first 12 chars 2017-02-09 03:49:54,748 Setting DEBUG logging. 2017-02-09 03:49:54,748 attempt 1/10 2017-02-09 03:49:54,748 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/70debab47688?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-02-09 03:49:56,072 unpacking tar archive at: mozilla-central-70debab47688/testing/mozharness/ program finished with exit code 0 elapsedTime=1.592222 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-02-09 03:49:56.262833) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-09 03:49:56.263419) ========= script_repo_revision: 70debab476881121ddfe92fc60c80cfae0a63bc7 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-09 03:49:56.264147) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-09 03:49:56.264650) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-09 03:49:56.285154) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 8 secs) (at 2017-02-09 03:49:56.285421) ========= /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.K6QiyQhPsn/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.pkvxAnA7w6/Listeners TMPDIR=/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/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 03:49:56 INFO - MultiFileLogger online at 20170209 03:49:56 in /builds/slave/test 03:49:56 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 03:49:56 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 03:49:56 INFO - {'append_to_log': False, 03:49:56 INFO - 'base_work_dir': '/builds/slave/test', 03:49:56 INFO - 'blob_upload_branch': 'Firefox', 03:49:56 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 03:49:56 INFO - 'branch': 'Firefox', 03:49:56 INFO - 'buildbot_json_path': 'buildprops.json', 03:49:56 INFO - 'config_files': ('talos/mac_config.py',), 03:49:56 INFO - 'default_actions': ('clobber', 03:49:56 INFO - 'read-buildbot-config', 03:49:56 INFO - 'download-and-extract', 03:49:56 INFO - 'populate-webroot', 03:49:56 INFO - 'create-virtualenv', 03:49:56 INFO - 'install', 03:49:56 INFO - 'run-tests'), 03:49:56 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 03:49:56 INFO - 'download_minidump_stackwalk': True, 03:49:56 INFO - 'download_symbols': 'ondemand', 03:49:56 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 03:49:56 INFO - 'tooltool.py': '/tools/tooltool.py', 03:49:56 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 03:49:56 INFO - '/tools/misc-python/virtualenv.py')}, 03:49:56 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 03:49:56 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 03:49:56 INFO - 'installer_path': 'installer.exe', 03:49:56 INFO - 'log_level': 'info', 03:49:56 INFO - 'log_name': 'talos', 03:49:56 INFO - 'log_to_console': True, 03:49:56 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 03:49:56 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 03:49:56 INFO - 'opt_config_files': (), 03:49:56 INFO - 'pip_index': False, 03:49:56 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 03:49:56 INFO - 'cmd': ('bash', 03:49:56 INFO - '-c', 03:49:56 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 03:49:56 INFO - 'enabled': True, 03:49:56 INFO - 'halt_on_failure': False, 03:49:56 INFO - 'name': 'check_screen_resolution'},), 03:49:56 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 03:49:56 INFO - 'cmd': ('bash', 03:49:56 INFO - '-c', 03:49:56 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 03:49:56 INFO - 'enabled': True, 03:49:56 INFO - 'halt_on_failure': False, 03:49:56 INFO - 'name': 'check_screen_resolution'},), 03:49:56 INFO - 'run_cmd_checks_enabled': True, 03:49:56 INFO - 'sps_profile': False, 03:49:56 INFO - 'sps_profile_interval': 0, 03:49:56 INFO - 'suite': 'dromaeojs-e10s', 03:49:56 INFO - 'system_bits': '32', 03:49:56 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 03:49:56 INFO - 'title': 't-yosemite-r7-0088', 03:49:56 INFO - 'tooltool_cache': '/builds/tooltool_cache', 03:49:56 INFO - 'use_talos_json': True, 03:49:56 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 03:49:56 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 03:49:56 INFO - 'work_dir': 'build'} 03:49:56 INFO - [mozharness: 2017-02-09 11:49:56.503127Z] Running clobber step. 03:49:56 INFO - Running pre-action listener: _resource_record_pre_action 03:49:56 INFO - Running main action method: clobber 03:49:56 INFO - rmtree: /builds/slave/test/build 03:49:56 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 03:49:59 INFO - Running post-action listener: _resource_record_post_action 03:49:59 INFO - [mozharness: 2017-02-09 11:49:59.125510Z] Finished clobber step (success) 03:49:59 INFO - [mozharness: 2017-02-09 11:49:59.125632Z] Running read-buildbot-config step. 03:49:59 INFO - Running pre-action listener: _resource_record_pre_action 03:49:59 INFO - Running main action method: read_buildbot_config 03:49:59 INFO - Using buildbot properties: 03:49:59 INFO - { 03:49:59 INFO - "project": "", 03:49:59 INFO - "product": "firefox", 03:49:59 INFO - "script_repo_revision": "production", 03:49:59 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 03:49:59 INFO - "repository": "", 03:49:59 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 03:49:59 INFO - "buildid": "20170209022422", 03:49:59 INFO - "pgo_build": "False", 03:49:59 INFO - "basedir": "/builds/slave/test", 03:49:59 INFO - "buildnumber": 78, 03:49:59 INFO - "slavename": "t-yosemite-r7-0088", 03:49:59 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 03:49:59 INFO - "platform": "yosemite_r7", 03:49:59 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 03:49:59 INFO - "branch": "mozilla-central", 03:49:59 INFO - "repo_path": "mozilla-central", 03:49:59 INFO - "revision": "70debab476881121ddfe92fc60c80cfae0a63bc7", 03:49:59 INFO - "stage_platform": "macosx64", 03:49:59 INFO - "builduid": "dd1381c013c5495ab38d92f9b7adaad1", 03:49:59 INFO - "slavebuilddir": "test" 03:49:59 INFO - } 03:49:59 INFO - Found installer url https://queue.taskcluster.net/v1/task/OfSroXjURveNKqAuKyq7Hw/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg. 03:49:59 INFO - Running post-action listener: _resource_record_post_action 03:49:59 INFO - [mozharness: 2017-02-09 11:49:59.128956Z] Finished read-buildbot-config step (success) 03:49:59 INFO - [mozharness: 2017-02-09 11:49:59.129088Z] Running download-and-extract step. 03:49:59 INFO - Running pre-action listener: _resource_record_pre_action 03:49:59 INFO - Running main action method: download_and_extract 03:49:59 INFO - mkdir: /builds/slave/test/build/tests 03:49: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')]} 03:49:59 INFO - trying https://queue.taskcluster.net/v1/task/OfSroXjURveNKqAuKyq7Hw/artifacts/public/build/firefox-54.0a1.en-US.mac.test_packages.json 03:49:59 INFO - Downloading https://queue.taskcluster.net/v1/task/OfSroXjURveNKqAuKyq7Hw/artifacts/public/build/firefox-54.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-54.0a1.en-US.mac.test_packages.json 03:49:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/OfSroXjURveNKqAuKyq7Hw/artifacts/public/build/firefox-54.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-54.0a1.en-US.mac.test_packages.json'}, attempt #1 03:50:00 INFO - Downloaded 1199 bytes. 03:50:00 INFO - Reading from file /builds/slave/test/build/firefox-54.0a1.en-US.mac.test_packages.json 03:50:00 INFO - Using the following test package requirements: 03:50:00 INFO - {u'common': [u'firefox-54.0a1.en-US.mac.common.tests.zip'], 03:50:00 INFO - u'cppunittest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 03:50:00 INFO - u'firefox-54.0a1.en-US.mac.cppunittest.tests.zip'], 03:50:00 INFO - u'gtest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 03:50:00 INFO - u'firefox-54.0a1.en-US.mac.gtest.tests.zip'], 03:50:00 INFO - u'jittest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 03:50:00 INFO - u'jsshell-mac.zip'], 03:50:00 INFO - u'mochitest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 03:50:00 INFO - u'firefox-54.0a1.en-US.mac.mochitest.tests.zip'], 03:50:00 INFO - u'mozbase': [u'firefox-54.0a1.en-US.mac.common.tests.zip'], 03:50:00 INFO - u'reftest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 03:50:00 INFO - u'firefox-54.0a1.en-US.mac.reftest.tests.zip'], 03:50:00 INFO - u'talos': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 03:50:00 INFO - u'firefox-54.0a1.en-US.mac.talos.tests.zip'], 03:50:00 INFO - u'web-platform': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 03:50:00 INFO - u'firefox-54.0a1.en-US.mac.web-platform.tests.zip'], 03:50:00 INFO - u'xpcshell': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 03:50:00 INFO - u'firefox-54.0a1.en-US.mac.xpcshell.tests.zip']} 03:50:00 INFO - Downloading packages: [u'firefox-54.0a1.en-US.mac.common.tests.zip'] for test suite category: common 03:50:00 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/OfSroXjURveNKqAuKyq7Hw/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip 03:50:00 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/OfSroXjURveNKqAuKyq7Hw/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip'}, attempt #1 03:50:00 INFO - Fetch https://queue.taskcluster.net/v1/task/OfSroXjURveNKqAuKyq7Hw/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip into memory 03:50:01 INFO - Expected file size: 34939684 03:50:01 INFO - Obtained file size: 34939684 03:50:08 INFO - Downloading packages: [u'firefox-54.0a1.en-US.mac.common.tests.zip', u'firefox-54.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 03:50:08 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/OfSroXjURveNKqAuKyq7Hw/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip 03:50:08 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/OfSroXjURveNKqAuKyq7Hw/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip'}, attempt #1 03:50:08 INFO - Fetch https://queue.taskcluster.net/v1/task/OfSroXjURveNKqAuKyq7Hw/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip into memory 03:50:09 INFO - Expected file size: 34939684 03:50:09 INFO - Obtained file size: 34939684 03:50:16 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/OfSroXjURveNKqAuKyq7Hw/artifacts/public/build/firefox-54.0a1.en-US.mac.talos.tests.zip 03:50:16 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/OfSroXjURveNKqAuKyq7Hw/artifacts/public/build/firefox-54.0a1.en-US.mac.talos.tests.zip'}, attempt #1 03:50:16 INFO - Fetch https://queue.taskcluster.net/v1/task/OfSroXjURveNKqAuKyq7Hw/artifacts/public/build/firefox-54.0a1.en-US.mac.talos.tests.zip into memory 03:50:17 INFO - Expected file size: 14403454 03:50:17 INFO - Obtained file size: 14403454 03:50:17 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')]} 03:50:17 INFO - trying https://queue.taskcluster.net/v1/task/OfSroXjURveNKqAuKyq7Hw/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg 03:50:17 INFO - Downloading https://queue.taskcluster.net/v1/task/OfSroXjURveNKqAuKyq7Hw/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-54.0a1.en-US.mac.dmg 03:50:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/OfSroXjURveNKqAuKyq7Hw/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-54.0a1.en-US.mac.dmg'}, attempt #1 03:50:19 INFO - Downloaded 70303680 bytes. 03:50:19 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/OfSroXjURveNKqAuKyq7Hw/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg 03:50:19 INFO - mkdir: /builds/slave/test/properties 03:50:19 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 03:50:19 INFO - Writing to file /builds/slave/test/properties/build_url 03:50:19 INFO - Contents: 03:50:19 INFO - build_url:https://queue.taskcluster.net/v1/task/OfSroXjURveNKqAuKyq7Hw/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg 03:50:20 INFO - Running post-action listener: _resource_record_post_action 03:50:20 INFO - Running post-action listener: set_extra_try_arguments 03:50:20 INFO - [mozharness: 2017-02-09 11:50:20.709995Z] Finished download-and-extract step (success) 03:50:20 INFO - [mozharness: 2017-02-09 11:50:20.710137Z] Running populate-webroot step. 03:50:20 INFO - Running pre-action listener: _resource_record_pre_action 03:50:20 INFO - Running main action method: populate_webroot 03:50:20 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 03:50:20 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 03:50:20 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 03:50:20 INFO - '--tppagecycles', 03:50:20 INFO - '7'], 03:50:20 INFO - 'tests': ['tsvgm']}}, 03:50:20 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 03:50:20 INFO - 'tests': ['tresize', 'tcanvasmark']}, 03:50:20 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 03:50:20 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 03:50:20 INFO - 'tests': ['dromaeo_css', 'kraken']}, 03:50:20 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 03:50:20 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 03:50:20 INFO - 'talos_options': ['--disable-e10s'], 03:50:20 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:50:20 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 03:50:20 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:50:20 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 03:50:20 INFO - 'talos_options': ['--disable-e10s'], 03:50:20 INFO - 'tests': ['damp', 'tps']}, 03:50:20 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 03:50:20 INFO - 'tests': ['damp', 'tps']}, 03:50:20 INFO - 'g3': {'talos_options': ['--disable-e10s'], 03:50:20 INFO - 'tests': ['dromaeo_dom']}, 03:50:20 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 03:50:20 INFO - 'g4': {'talos_options': ['--disable-e10s'], 03:50:20 INFO - 'tests': ['basic_compositor_video']}, 03:50:20 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 03:50:20 INFO - 'other': {'talos_options': ['--disable-e10s'], 03:50:20 INFO - 'tests': ['a11yr', 03:50:20 INFO - 'ts_paint', 03:50:20 INFO - 'tpaint', 03:50:20 INFO - 'sessionrestore', 03:50:20 INFO - 'sessionrestore_no_auto_restore', 03:50:20 INFO - 'tabpaint']}, 03:50:20 INFO - 'other-e10s': {'tests': ['a11yr', 03:50:20 INFO - 'ts_paint', 03:50:20 INFO - 'tpaint', 03:50:20 INFO - 'sessionrestore', 03:50:20 INFO - 'sessionrestore_no_auto_restore', 03:50:20 INFO - 'tabpaint']}, 03:50:20 INFO - 'other-e10s_l64': {'tests': ['a11yr', 03:50:20 INFO - 'ts_paint', 03:50:20 INFO - 'tpaint', 03:50:20 INFO - 'sessionrestore', 03:50:20 INFO - 'sessionrestore_no_auto_restore', 03:50:20 INFO - 'tabpaint']}, 03:50:20 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 03:50:20 INFO - 'ts_paint', 03:50:20 INFO - 'tpaint', 03:50:20 INFO - 'sessionrestore', 03:50:20 INFO - 'sessionrestore_no_auto_restore', 03:50:20 INFO - 'tabpaint']}, 03:50:20 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 03:50:20 INFO - 'tests': ['a11yr', 03:50:20 INFO - 'ts_paint', 03:50:20 INFO - 'tpaint', 03:50:20 INFO - 'sessionrestore', 03:50:20 INFO - 'sessionrestore_no_auto_restore', 03:50:20 INFO - 'tabpaint']}, 03:50:20 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 03:50:20 INFO - 'tests': ['a11yr', 03:50:20 INFO - 'ts_paint', 03:50:20 INFO - 'tpaint', 03:50:20 INFO - 'sessionrestore', 03:50:20 INFO - 'sessionrestore_no_auto_restore', 03:50:20 INFO - 'tabpaint']}, 03:50:20 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 03:50:20 INFO - 'tests': ['tsvgx', 03:50:20 INFO - 'tsvgr_opacity', 03:50:20 INFO - 'tart', 03:50:20 INFO - 'tscrollx', 03:50:20 INFO - 'cart', 03:50:20 INFO - 'tsvg_static']}, 03:50:20 INFO - 'svgr-e10s': {'tests': ['tsvgx', 03:50:20 INFO - 'tsvgr_opacity', 03:50:20 INFO - 'tart', 03:50:20 INFO - 'tscrollx', 03:50:20 INFO - 'cart', 03:50:20 INFO - 'tsvg_static']}, 03:50:20 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 03:50:20 INFO - 'talos_options': ['--disable-e10s'], 03:50:20 INFO - 'tests': ['tp5o']}, 03:50:20 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 03:50:20 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 03:50:20 INFO - 'talos_options': ['--disable-e10s', 03:50:20 INFO - '--xperf_path', 03:50:20 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:50:20 INFO - 'tests': ['tp5n']}, 03:50:20 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 03:50:20 INFO - 'talos_options': ['--xperf_path', 03:50:20 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:50:20 INFO - 'tests': ['tp5n']}}, 03:50:20 INFO - 'talos.zip': {'path': '', 03:50:20 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 03:50:20 INFO - Running post-action listener: _resource_record_post_action 03:50:20 INFO - [mozharness: 2017-02-09 11:50:20.728934Z] Finished populate-webroot step (success) 03:50:20 INFO - [mozharness: 2017-02-09 11:50:20.729073Z] Running create-virtualenv step. 03:50:20 INFO - Running pre-action listener: _resource_record_pre_action 03:50:20 INFO - Running main action method: create_virtualenv 03:50:20 INFO - Creating virtualenv /builds/slave/test/build/venv 03:50:20 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 03:50:20 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 03:50:20 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 03:50:20 INFO - Using real prefix '/tools/python27' 03:50:20 INFO - New python executable in /builds/slave/test/build/venv/bin/python 03:50:21 INFO - Installing distribute.............................................................................................................................................................................................done. 03:50:24 INFO - Installing pip.................done. 03:50:24 INFO - Return code: 0 03:50:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 03:50:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 03:50:24 INFO - Reading from file tmpfile_stdout 03:50:24 INFO - Output received: 03:50:24 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) 03:50:24 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 03:50:24 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')]} 03:50:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:50:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:50:24 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')]} 03:50:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:50:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:50:24 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 0x1054dbb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1054de768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd15bda9c90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1052b9d30>, '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 0x1051e0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1052aebe8>, '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.K6QiyQhPsn/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.pkvxAnA7w6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/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 03:50:24 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 03:50:24 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 03:50:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.K6QiyQhPsn/Render', 03:50:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:50:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:50:24 INFO - 'HOME': '/Users/cltbld', 03:50:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:50:24 INFO - 'LOGNAME': 'cltbld', 03:50:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:50:24 INFO - 'MOZ_NO_REMOTE': '1', 03:50:24 INFO - 'NO_EM_RESTART': '1', 03:50:24 INFO - 'PAGER': '/bin/cat', 03:50:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:50:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:50:24 INFO - 'PWD': '/builds/slave/test', 03:50:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:50:24 INFO - 'SHELL': '/bin/bash', 03:50:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pkvxAnA7w6/Listeners', 03:50:24 INFO - 'TMPDIR': '/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/', 03:50:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:50:24 INFO - 'USER': 'cltbld', 03:50:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:50:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:50:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:50:24 INFO - 'XPC_FLAGS': '0x0', 03:50:24 INFO - 'XPC_SERVICE_NAME': '0', 03:50:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:50:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:50:24 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 03:50:24 INFO - Cleaning up... 03:50:24 INFO - Return code: 0 03:50:24 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 03:50:24 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')]} 03:50:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:50:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:50:24 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')]} 03:50:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:50:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:50:24 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 0x1054dbb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1054de768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd15bda9c90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1052b9d30>, '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 0x1051e0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1052aebe8>, '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.K6QiyQhPsn/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.pkvxAnA7w6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/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 03:50:24 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 03:50:24 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 03:50:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.K6QiyQhPsn/Render', 03:50:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:50:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:50:24 INFO - 'HOME': '/Users/cltbld', 03:50:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:50:24 INFO - 'LOGNAME': 'cltbld', 03:50:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:50:24 INFO - 'MOZ_NO_REMOTE': '1', 03:50:24 INFO - 'NO_EM_RESTART': '1', 03:50:24 INFO - 'PAGER': '/bin/cat', 03:50:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:50:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:50:24 INFO - 'PWD': '/builds/slave/test', 03:50:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:50:24 INFO - 'SHELL': '/bin/bash', 03:50:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pkvxAnA7w6/Listeners', 03:50:24 INFO - 'TMPDIR': '/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/', 03:50:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:50:24 INFO - 'USER': 'cltbld', 03:50:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:50:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:50:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:50:24 INFO - 'XPC_FLAGS': '0x0', 03:50:24 INFO - 'XPC_SERVICE_NAME': '0', 03:50:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:50:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:50:25 INFO - Downloading/unpacking psutil>=3.1.1 03:50:25 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 03:50:25 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 03:50:25 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 03:50:25 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 03:50:25 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 03:50:25 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 03:50:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 03:50:28 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:50:28 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:50:28 INFO - Installing collected packages: psutil 03:50:28 INFO - Running setup.py install for psutil 03:50:28 INFO - building 'psutil._psutil_osx' extension 03:50:28 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 03:50:29 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 03:50:29 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 03:50:29 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 03:50:29 INFO - building 'psutil._psutil_posix' extension 03:50:29 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 03:50:29 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 03:50:29 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 03:50:29 INFO - ^ 03:50:29 INFO - 1 warning generated. 03:50:29 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 03:50:29 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:50:29 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:50:29 INFO - Successfully installed psutil 03:50:29 INFO - Cleaning up... 03:50:29 INFO - Return code: 0 03:50:29 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 03:50:29 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')]} 03:50:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:50:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:50:29 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')]} 03:50:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:50:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:50:29 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 0x1054dbb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1054de768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd15bda9c90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1052b9d30>, '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 0x1051e0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1052aebe8>, '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.K6QiyQhPsn/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.pkvxAnA7w6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/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 03:50:29 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 03:50:29 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 03:50:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.K6QiyQhPsn/Render', 03:50:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:50:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:50:29 INFO - 'HOME': '/Users/cltbld', 03:50:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:50:29 INFO - 'LOGNAME': 'cltbld', 03:50:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:50:29 INFO - 'MOZ_NO_REMOTE': '1', 03:50:29 INFO - 'NO_EM_RESTART': '1', 03:50:29 INFO - 'PAGER': '/bin/cat', 03:50:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:50:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:50:29 INFO - 'PWD': '/builds/slave/test', 03:50:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:50:29 INFO - 'SHELL': '/bin/bash', 03:50:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pkvxAnA7w6/Listeners', 03:50:29 INFO - 'TMPDIR': '/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/', 03:50:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:50:29 INFO - 'USER': 'cltbld', 03:50:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:50:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:50:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:50:29 INFO - 'XPC_FLAGS': '0x0', 03:50:29 INFO - 'XPC_SERVICE_NAME': '0', 03:50:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:50:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:50:30 INFO - Downloading/unpacking mozsystemmonitor==0.3 03:50:30 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 03:50:30 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 03:50:30 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 03:50:30 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 03:50:30 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 03:50:30 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 03:50:32 INFO - Downloading mozsystemmonitor-0.3.tar.gz 03:50:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 03:50:32 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 03:50:32 INFO - Installing collected packages: mozsystemmonitor 03:50:32 INFO - Running setup.py install for mozsystemmonitor 03:50:33 INFO - Successfully installed mozsystemmonitor 03:50:33 INFO - Cleaning up... 03:50:33 INFO - Return code: 0 03:50:33 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 03:50:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:50:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:50:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:50:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:50:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:50:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:50:33 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 0x1054dbb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1054de768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd15bda9c90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1052b9d30>, '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 0x1051e0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1052aebe8>, '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.K6QiyQhPsn/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.pkvxAnA7w6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/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 03:50:33 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 03:50:33 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 03:50:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.K6QiyQhPsn/Render', 03:50:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:50:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:50:33 INFO - 'HOME': '/Users/cltbld', 03:50:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:50:33 INFO - 'LOGNAME': 'cltbld', 03:50:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:50:33 INFO - 'MOZ_NO_REMOTE': '1', 03:50:33 INFO - 'NO_EM_RESTART': '1', 03:50:33 INFO - 'PAGER': '/bin/cat', 03:50:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:50:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:50:33 INFO - 'PWD': '/builds/slave/test', 03:50:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:50:33 INFO - 'SHELL': '/bin/bash', 03:50:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pkvxAnA7w6/Listeners', 03:50:33 INFO - 'TMPDIR': '/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/', 03:50:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:50:33 INFO - 'USER': 'cltbld', 03:50:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:50:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:50:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:50:33 INFO - 'XPC_FLAGS': '0x0', 03:50:33 INFO - 'XPC_SERVICE_NAME': '0', 03:50:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:50:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:50:33 INFO - Downloading/unpacking jsonschema==2.5.1 03:50:33 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 03:50:33 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 03:50:33 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 03:50:33 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 03:50:33 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 03:50:33 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 03:50:35 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 03:50:35 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 03:50:35 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 03:50:35 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 03:50:35 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 03:50:35 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 03:50:35 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 03:50:35 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 03:50:36 INFO - Downloading functools32-3.2.3-2.tar.gz 03:50:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 03:50:36 INFO - warning: no files found matching '*.txt' 03:50:36 INFO - no previously-included directories found matching 'build' 03:50:36 INFO - no previously-included directories found matching 'dist' 03:50:36 INFO - no previously-included directories found matching '.git*' 03:50:36 INFO - Installing collected packages: jsonschema, functools32 03:50:36 INFO - Running setup.py install for functools32 03:50:36 INFO - warning: no files found matching '*.txt' 03:50:36 INFO - no previously-included directories found matching 'build' 03:50:36 INFO - no previously-included directories found matching 'dist' 03:50:36 INFO - no previously-included directories found matching '.git*' 03:50:36 INFO - Successfully installed jsonschema functools32 03:50:36 INFO - Cleaning up... 03:50:36 INFO - Return code: 0 03:50:36 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 03:50:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:50:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:50:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:50:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:50:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:50:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:50:36 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1054dbb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1054de768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd15bda9c90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1052b9d30>, '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 0x1051e0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1052aebe8>, '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.K6QiyQhPsn/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.pkvxAnA7w6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/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 03:50:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 03:50:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 03:50:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.K6QiyQhPsn/Render', 03:50:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:50:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:50:36 INFO - 'HOME': '/Users/cltbld', 03:50:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:50:36 INFO - 'LOGNAME': 'cltbld', 03:50:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:50:36 INFO - 'MOZ_NO_REMOTE': '1', 03:50:36 INFO - 'NO_EM_RESTART': '1', 03:50:36 INFO - 'PAGER': '/bin/cat', 03:50:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:50:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:50:36 INFO - 'PWD': '/builds/slave/test', 03:50:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:50:36 INFO - 'SHELL': '/bin/bash', 03:50:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pkvxAnA7w6/Listeners', 03:50:36 INFO - 'TMPDIR': '/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/', 03:50:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:50:36 INFO - 'USER': 'cltbld', 03:50:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:50:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:50:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:50:36 INFO - 'XPC_FLAGS': '0x0', 03:50:36 INFO - 'XPC_SERVICE_NAME': '0', 03:50:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:50:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:50:37 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 03:50:37 INFO - Cleaning up... 03:50:37 INFO - Return code: 0 03:50:37 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 03:50:37 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')]} 03:50:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:50:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:50:37 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')]} 03:50:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:50:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:50:37 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 0x1054dbb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1054de768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd15bda9c90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1052b9d30>, '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 0x1051e0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1052aebe8>, '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.K6QiyQhPsn/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.pkvxAnA7w6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/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 03:50:37 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 03:50:37 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 03:50:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.K6QiyQhPsn/Render', 03:50:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:50:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:50:37 INFO - 'HOME': '/Users/cltbld', 03:50:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:50:37 INFO - 'LOGNAME': 'cltbld', 03:50:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:50:37 INFO - 'MOZ_NO_REMOTE': '1', 03:50:37 INFO - 'NO_EM_RESTART': '1', 03:50:37 INFO - 'PAGER': '/bin/cat', 03:50:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:50:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:50:37 INFO - 'PWD': '/builds/slave/test', 03:50:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:50:37 INFO - 'SHELL': '/bin/bash', 03:50:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pkvxAnA7w6/Listeners', 03:50:37 INFO - 'TMPDIR': '/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/', 03:50:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:50:37 INFO - 'USER': 'cltbld', 03:50:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:50:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:50:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:50:37 INFO - 'XPC_FLAGS': '0x0', 03:50:37 INFO - 'XPC_SERVICE_NAME': '0', 03:50:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:50:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:50:37 INFO - Downloading/unpacking blobuploader==1.2.4 03:50:37 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 03:50:37 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 03:50:37 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 03:50:37 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 03:50:37 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 03:50:37 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 03:50:39 INFO - Downloading blobuploader-1.2.4.tar.gz 03:50:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 03:50:40 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 03:50:40 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 03:50:40 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 03:50:40 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 03:50:40 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 03:50:40 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 03:50:40 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 03:50:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 03:50:40 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 03:50:40 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 03:50:40 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 03:50:40 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 03:50:40 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 03:50:40 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 03:50:40 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 03:50:41 INFO - Downloading docopt-0.6.1.tar.gz 03:50:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 03:50:41 INFO - Installing collected packages: blobuploader, requests, docopt 03:50:41 INFO - Running setup.py install for blobuploader 03:50:41 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 03:50:41 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 03:50:41 INFO - Running setup.py install for requests 03:50:41 INFO - Running setup.py install for docopt 03:50:42 INFO - Successfully installed blobuploader requests docopt 03:50:42 INFO - Cleaning up... 03:50:42 INFO - Return code: 0 03:50:42 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:50:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:50:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:50:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:50:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:50:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:50:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:50:42 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 0x1054dbb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1054de768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd15bda9c90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1052b9d30>, '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 0x1051e0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1052aebe8>, '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.K6QiyQhPsn/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.pkvxAnA7w6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/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 03:50:42 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 03:50:42 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 03:50:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.K6QiyQhPsn/Render', 03:50:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:50:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:50:42 INFO - 'HOME': '/Users/cltbld', 03:50:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:50:42 INFO - 'LOGNAME': 'cltbld', 03:50:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:50:42 INFO - 'MOZ_NO_REMOTE': '1', 03:50:42 INFO - 'NO_EM_RESTART': '1', 03:50:42 INFO - 'PAGER': '/bin/cat', 03:50:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:50:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:50:42 INFO - 'PWD': '/builds/slave/test', 03:50:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:50:42 INFO - 'SHELL': '/bin/bash', 03:50:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pkvxAnA7w6/Listeners', 03:50:42 INFO - 'TMPDIR': '/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/', 03:50:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:50:42 INFO - 'USER': 'cltbld', 03:50:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:50:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:50:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:50:42 INFO - 'XPC_FLAGS': '0x0', 03:50:42 INFO - 'XPC_SERVICE_NAME': '0', 03:50:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:50:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:50:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:50:42 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-_GMLzN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:50:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:50:42 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-6BmwWG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:50:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:50:42 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-3TpBfI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:50:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:50:42 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-RrjFi8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:50:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:50:42 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-BxM_Xi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:50:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:50:42 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-rmwz3q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:50:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:50:43 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-L7b7Sm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:50:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:50:43 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-8Fedwc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:50:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:50:43 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-dv7sZ2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:50:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:50:43 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-UbHjNj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:50:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:50:43 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-E3_VVl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:50:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:50:43 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-qi86Dy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:50:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:50:43 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-cPPO_t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:50:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:50:43 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-Q6V0c6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:50:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:50:43 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-ore0AC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:50:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:50:43 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-0Zjxhh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:50:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:50:44 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-FUji44-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:50:44 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 03:50:44 INFO - Running setup.py install for manifestparser 03:50:44 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 03:50:44 INFO - Running setup.py install for mozcrash 03:50:44 INFO - Running setup.py install for mozdebug 03:50:44 INFO - Running setup.py install for mozdevice 03:50:44 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 03:50:44 INFO - Installing dm script to /builds/slave/test/build/venv/bin 03:50:44 INFO - Running setup.py install for mozfile 03:50:44 INFO - Running setup.py install for mozhttpd 03:50:45 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 03:50:45 INFO - Running setup.py install for mozinfo 03:50:45 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 03:50:45 INFO - Running setup.py install for mozInstall 03:50:45 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 03:50:45 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 03:50:45 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 03:50:45 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 03:50:45 INFO - Running setup.py install for mozleak 03:50:45 INFO - Running setup.py install for mozlog 03:50:45 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 03:50:45 INFO - Running setup.py install for moznetwork 03:50:45 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 03:50:45 INFO - Running setup.py install for mozprocess 03:50:45 INFO - Running setup.py install for mozprofile 03:50:46 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 03:50:46 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 03:50:46 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 03:50:46 INFO - Running setup.py install for mozrunner 03:50:46 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 03:50:46 INFO - Running setup.py install for mozscreenshot 03:50:46 INFO - Running setup.py install for moztest 03:50:46 INFO - Running setup.py install for mozversion 03:50:46 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 03:50:46 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 03:50:46 INFO - Cleaning up... 03:50:46 INFO - Return code: 0 03:50:46 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:50:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:50:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:50:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:50:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:50:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:50:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:50:46 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 0x1054dbb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1054de768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd15bda9c90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1052b9d30>, '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 0x1051e0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1052aebe8>, '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.K6QiyQhPsn/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.pkvxAnA7w6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/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 03:50:46 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 03:50:46 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 03:50:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.K6QiyQhPsn/Render', 03:50:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:50:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:50:46 INFO - 'HOME': '/Users/cltbld', 03:50:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:50:46 INFO - 'LOGNAME': 'cltbld', 03:50:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:50:46 INFO - 'MOZ_NO_REMOTE': '1', 03:50:46 INFO - 'NO_EM_RESTART': '1', 03:50:46 INFO - 'PAGER': '/bin/cat', 03:50:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:50:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:50:46 INFO - 'PWD': '/builds/slave/test', 03:50:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:50:46 INFO - 'SHELL': '/bin/bash', 03:50:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pkvxAnA7w6/Listeners', 03:50:46 INFO - 'TMPDIR': '/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/', 03:50:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:50:46 INFO - 'USER': 'cltbld', 03:50:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:50:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:50:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:50:46 INFO - 'XPC_FLAGS': '0x0', 03:50:46 INFO - 'XPC_SERVICE_NAME': '0', 03:50:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:50:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:50:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:50:47 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-Ew227B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:50:47 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)) 03:50:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:50:47 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-vlWAlA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:50:47 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)) 03:50:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:50:47 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-amJtKt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:50:47 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)) 03:50:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:50:47 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-mmEx_H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:50:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 03:50:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:50:47 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-_8ZT5m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:50:47 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)) 03:50:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:50:47 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-CTtEFw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:50:47 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)) 03:50:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:50:47 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-Vis3GK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:50:47 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)) 03:50:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:50:47 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-7ZArt9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:50:47 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)) 03:50:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:50:47 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-bsS_7h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:50:47 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)) 03:50:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:50:47 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-o1Nefy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:50:48 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)) 03:50:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:50:48 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-Jh2FvG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:50:48 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)) 03:50:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:50:48 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-RXBxL_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:50:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.24 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)) 03:50:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:50:48 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-6KWTx9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:50:48 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)) 03:50:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:50:48 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-s2pLDV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:50:48 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)) 03:50:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:50:48 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-75IBXh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:50:48 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)) 03:50:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:50:48 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-yTb8FX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:50:48 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 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)) 03:50:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:50:48 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-k5K9t2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:50:48 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)) 03:50:48 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)) 03:50:48 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)) 03:50:48 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)) 03:50:48 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 03:50:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 03:50:48 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 03:50: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 03:50: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 03:50: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 03:50: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 03:50: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 03:50: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 03:50:51 INFO - Downloading blessings-1.6.tar.gz 03:50:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 03:50:51 INFO - Installing collected packages: blessings 03:50:51 INFO - Running setup.py install for blessings 03:50:51 INFO - Successfully installed blessings 03:50:51 INFO - Cleaning up... 03:50:51 INFO - Return code: 0 03:50:51 INFO - Done creating virtualenv /builds/slave/test/build/venv. 03:50:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:50:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:50:52 INFO - Reading from file tmpfile_stdout 03:50:52 INFO - Current package versions: 03:50:52 INFO - blessings == 1.6 03:50:52 INFO - blobuploader == 1.2.4 03:50:52 INFO - docopt == 0.6.1 03:50:52 INFO - functools32 == 3.2.3-2 03:50:52 INFO - jsonschema == 2.5.1 03:50:52 INFO - manifestparser == 1.1 03:50:52 INFO - mozInstall == 1.12 03:50:52 INFO - mozcrash == 1.0 03:50:52 INFO - mozdebug == 0.1 03:50:52 INFO - mozdevice == 0.49 03:50:52 INFO - mozfile == 1.2 03:50:52 INFO - mozhttpd == 0.7 03:50:52 INFO - mozinfo == 0.9 03:50:52 INFO - mozleak == 0.1 03:50:52 INFO - mozlog == 3.4 03:50:52 INFO - moznetwork == 0.27 03:50:52 INFO - mozprocess == 0.24 03:50:52 INFO - mozprofile == 0.28 03:50:52 INFO - mozrunner == 6.13 03:50:52 INFO - mozscreenshot == 0.1 03:50:52 INFO - mozsystemmonitor == 0.3 03:50:52 INFO - moztest == 0.8 03:50:52 INFO - mozversion == 1.4 03:50:52 INFO - psutil == 3.1.1 03:50:52 INFO - requests == 1.2.3 03:50:52 INFO - wsgiref == 0.1.2 03:50:52 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:50: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')]} 03:50:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:50:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:50: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')]} 03:50:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:50:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:50:52 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 0x1054dbb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1054de768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd15bda9c90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1052b9d30>, '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 0x1051e0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1052aebe8>, '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.K6QiyQhPsn/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.pkvxAnA7w6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/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 03:50:52 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 03:50:52 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 03:50:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.K6QiyQhPsn/Render', 03:50:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:50:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:50:52 INFO - 'HOME': '/Users/cltbld', 03:50:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:50:52 INFO - 'LOGNAME': 'cltbld', 03:50:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:50:52 INFO - 'MOZ_NO_REMOTE': '1', 03:50:52 INFO - 'NO_EM_RESTART': '1', 03:50:52 INFO - 'PAGER': '/bin/cat', 03:50:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:50:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:50:52 INFO - 'PWD': '/builds/slave/test', 03:50:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:50:52 INFO - 'SHELL': '/bin/bash', 03:50:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pkvxAnA7w6/Listeners', 03:50:52 INFO - 'TMPDIR': '/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/', 03:50:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:50:52 INFO - 'USER': 'cltbld', 03:50:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:50:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:50:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:50:52 INFO - 'XPC_FLAGS': '0x0', 03:50:52 INFO - 'XPC_SERVICE_NAME': '0', 03:50:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:50:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:50:53 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)) 03:50:53 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)) 03:50:53 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)) 03:50:53 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)) 03:50:53 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)) 03:50:53 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)) 03:50:53 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)) 03:50:53 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)) 03:50:53 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)) 03:50:53 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)) 03:50:53 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)) 03:50:53 INFO - Cleaning up... 03:50:53 INFO - Return code: 0 03:50:53 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 03:50:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:50:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:50:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:50:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:50:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:50:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:50:53 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 0x1054dbb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1054de768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd15bda9c90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1052b9d30>, '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 0x1051e0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1052aebe8>, '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.K6QiyQhPsn/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.pkvxAnA7w6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/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 03:50:53 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 03:50:53 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 03:50:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.K6QiyQhPsn/Render', 03:50:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:50:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:50:53 INFO - 'HOME': '/Users/cltbld', 03:50:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:50:53 INFO - 'LOGNAME': 'cltbld', 03:50:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:50:53 INFO - 'MOZ_NO_REMOTE': '1', 03:50:53 INFO - 'NO_EM_RESTART': '1', 03:50:53 INFO - 'PAGER': '/bin/cat', 03:50:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:50:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:50:53 INFO - 'PWD': '/builds/slave/test', 03:50:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:50:53 INFO - 'SHELL': '/bin/bash', 03:50:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pkvxAnA7w6/Listeners', 03:50:53 INFO - 'TMPDIR': '/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/', 03:50:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:50:53 INFO - 'USER': 'cltbld', 03:50:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:50:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:50:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:50:53 INFO - 'XPC_FLAGS': '0x0', 03:50:53 INFO - 'XPC_SERVICE_NAME': '0', 03:50:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:50:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:50:53 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 03:50:53 INFO - Cleaning up... 03:50:53 INFO - Return code: 0 03:50:53 INFO - Running post-action listener: _resource_record_post_action 03:50:53 INFO - Running post-action listener: _start_resource_monitoring 03:50:53 INFO - Starting resource monitoring. 03:50:53 INFO - [mozharness: 2017-02-09 11:50:53.833080Z] Finished create-virtualenv step (success) 03:50:53 INFO - [mozharness: 2017-02-09 11:50:53.834057Z] Running install step. 03:50:53 INFO - Running pre-action listener: _resource_record_pre_action 03:50:53 INFO - Running main action method: install 03:50:53 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:50:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:50:54 INFO - Reading from file tmpfile_stdout 03:50:54 INFO - Detecting whether we're running mozinstall >=1.0... 03:50:54 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 03:50:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 03:50:54 INFO - Reading from file tmpfile_stdout 03:50:54 INFO - Output received: 03:50:54 INFO - Usage: mozinstall [options] installer 03:50:54 INFO - Options: 03:50:54 INFO - -h, --help show this help message and exit 03:50:54 INFO - -d DEST, --destination=DEST 03:50:54 INFO - Directory to install application into. [default: 03:50:54 INFO - "/builds/slave/test"] 03:50:54 INFO - --app=APP Application being installed. [default: firefox] 03:50:54 INFO - mkdir: /builds/slave/test/build/application 03:50:54 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-54.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 03:50:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-54.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 03:51:16 INFO - Reading from file tmpfile_stdout 03:51:16 INFO - Output received: 03:51:16 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 03:51:16 INFO - Running post-action listener: _resource_record_post_action 03:51:16 INFO - [mozharness: 2017-02-09 11:51:16.059641Z] Finished install step (success) 03:51:16 INFO - [mozharness: 2017-02-09 11:51:16.059817Z] Running run-tests step. 03:51:16 INFO - Running pre-action listener: _resource_record_pre_action 03:51:16 INFO - Running main action method: run_tests 03:51:16 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 03:51:16 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 03:51:16 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 03:51:16 INFO - 2017-02-09 03:51:16.074 screenresolution[1618:7298] starting screenresolution argv=screenresolution get 03:51:16 INFO - 2017-02-09 03:51:16.077 screenresolution[1618:7298] Display 0: 1600x1200x32@60 03:51:16 INFO - 2017-02-09 03:51:16.082 screenresolution[1619:7300] starting screenresolution argv=screenresolution list 03:51:16 INFO - Available Modes on Display 0 03:51:16 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 03:51:16 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 03:51:16 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 03:51:16 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 03:51:16 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 03:51:16 INFO - Intel Iris: 03:51:16 INFO - Chipset Model: Intel Iris 03:51:16 INFO - Type: GPU 03:51:16 INFO - Bus: Built-In 03:51:16 INFO - VRAM (Dynamic, Max): 1536 MB 03:51:16 INFO - Vendor: Intel (0x8086) 03:51:16 INFO - Device ID: 0x0a2e 03:51:16 INFO - Revision ID: 0x0009 03:51:16 INFO - Displays: 03:51:16 INFO - Display: 03:51:16 INFO - Resolution: 1600 x 1200 @ 60 Hz 03:51:16 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 03:51:16 INFO - Display Serial Number: 1600x1200 60 03:51:16 INFO - Main Display: Yes 03:51:16 INFO - Mirror: Off 03:51:16 INFO - Online: Yes 03:51:16 INFO - Rotation: Supported 03:51:16 INFO - Return code: 0 03:51:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 03:51:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 03:51:16 INFO - Python 2.7.3 03:51:16 INFO - Return code: 0 03:51:16 INFO - grabbing minidump binary from tooltool 03:51:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:51:16 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 0x1052b9d30>, '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 0x1051e0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1052aebe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 03:51:16 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 03:51:16 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 03:51:16 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 03:51:16 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 03:51:16 INFO - Return code: 0 03:51:16 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 03:51:16 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 03:51:16 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 03:51:16 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 03:51:16 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 03:51:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/OfSroXjURveNKqAuKyq7Hw/artifacts/public/build/firefox-54.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0088', '--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 03:51:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/OfSroXjURveNKqAuKyq7Hw/artifacts/public/build/firefox-54.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0088 --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 03:51:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.K6QiyQhPsn/Render', 03:51:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:51:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:51:16 INFO - 'HOME': '/Users/cltbld', 03:51:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:51:16 INFO - 'LOGNAME': 'cltbld', 03:51:16 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:51:16 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 03:51:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:51:16 INFO - 'MOZ_NO_REMOTE': '1', 03:51:16 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:51:16 INFO - 'NO_EM_RESTART': '1', 03:51:16 INFO - 'PAGER': '/bin/cat', 03:51:16 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:51:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:51:16 INFO - 'PWD': '/builds/slave/test', 03:51:16 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:51:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:51:16 INFO - 'SHELL': '/bin/bash', 03:51:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pkvxAnA7w6/Listeners', 03:51:16 INFO - 'TMPDIR': '/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/', 03:51:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:51:16 INFO - 'USER': 'cltbld', 03:51:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:51:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:51:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:51:16 INFO - 'XPC_FLAGS': '0x0', 03:51:16 INFO - 'XPC_SERVICE_NAME': '0', 03:51:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:51:16 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/OfSroXjURveNKqAuKyq7Hw/artifacts/public/build/firefox-54.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0088', '--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 03:51:16 INFO - mozversion application_buildid: 20170209022422 03:51:16 INFO - mozversion application_changeset: 70debab476881121ddfe92fc60c80cfae0a63bc7 03:51:16 INFO - mozversion application_display_name: Nightly 03:51:16 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 03:51:16 INFO - mozversion application_name: Firefox 03:51:16 INFO - mozversion application_remotingname: firefox 03:51:16 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 03:51:16 INFO - mozversion application_vendor: Mozilla 03:51:16 INFO - mozversion application_version: 54.0a1 03:51:16 INFO - mozversion platform_buildid: 20170209022422 03:51:16 INFO - mozversion platform_changeset: 70debab476881121ddfe92fc60c80cfae0a63bc7 03:51:16 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 03:51:16 INFO - mozversion platform_version: 54.0a1 03:51:16 INFO - using testdate: 1486641076 03:51:16 INFO - actual date: 1486641076 03:51:16 INFO - starting webserver on 'localhost:49246' 03:51:16 INFO - SUITE-START | Running 2 tests 03:51:16 INFO - TEST-START | dromaeo_css 03:51:16 INFO - operating with platform_type : mac_ 03:51:16 INFO - Initialising browser for dromaeo_css test... 03:51:16 INFO - TEST-INFO | started process 1629 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/tmplGDDXj/profile http://localhost:49246/getInfo.html) 03:51:19 INFO - PROCESS | 1629 | JavaScript error: chrome://webcompat/content/lib/ua_overrider.jsm, line 109: NS_ERROR_INSUFFICIENT_DOMAIN_LEVELS: Component returned failure code: 0x804b0050 (NS_ERROR_INSUFFICIENT_DOMAIN_LEVELS) [nsIEffectiveTLDService.getBaseDomain] 03:51:19 INFO - PROCESS | 1629 | 2017-02-09 03:51:19.500 plugin-container[1631:7463] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8d3f, name = 'com.apple.tsm.portname' 03:51:19 INFO - PROCESS | 1629 | See /usr/include/servers/bootstrap_defs.h for the error codes. 03:51:19 INFO - PROCESS | 1629 | 2017-02-09 03:51:19.503 plugin-container[1631:7463] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x931b, name = 'com.apple.CFPasteboardClient' 03:51:19 INFO - PROCESS | 1629 | See /usr/include/servers/bootstrap_defs.h for the error codes. 03:51:19 INFO - PROCESS | 1629 | 2017-02-09 03:51:19.503 plugin-container[1631:7463] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 03:51:19 INFO - PROCESS | 1629 | __metrics Screen width/height:1600/1200 03:51:19 INFO - PROCESS | 1629 | colorDepth:24 03:51:19 INFO - PROCESS | 1629 | Browser inner width/height: 1024/689 03:51:19 INFO - PROCESS | 1629 | __metrics 03:51:19 ERROR - PROCESS | 1629 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3283: TypeError: this.tabs is undefined 03:51:20 INFO - TEST-INFO | 1629: exit 0 03:51:20 INFO - Browser initialized. 03:51:20 INFO - Running cycle 1/1 for dromaeo_css test... 03:51:20 INFO - TEST-INFO | started process 1632 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/tmplGDDXj/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:51:22 INFO - PROCESS | 1632 | 2017-02-09 03:51:22.791 plugin-container[1633:7582] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8943, name = 'com.apple.tsm.portname' 03:51:22 INFO - PROCESS | 1632 | See /usr/include/servers/bootstrap_defs.h for the error codes. 03:51:22 INFO - PROCESS | 1632 | 2017-02-09 03:51:22.792 plugin-container[1633:7582] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8d0b, name = 'com.apple.CFPasteboardClient' 03:51:22 INFO - PROCESS | 1632 | See /usr/include/servers/bootstrap_defs.h for the error codes. 03:51:22 INFO - PROCESS | 1632 | 2017-02-09 03:51:22.792 plugin-container[1633:7582] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 03:51:32 INFO - PROCESS | 1632 | RSS: Main: 233476096 03:51:32 INFO - PROCESS | 1632 | 03:54:35 INFO - PROCESS | 1632 | Cycle 1(1): loaded http://localhost:49246/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49246/tests/dromaeo/cssquery-ext.html) 03:54:37 INFO - PROCESS | 1632 | RSS: Main: 260411392 03:54:37 INFO - PROCESS | 1632 | 03:56:20 INFO - PROCESS | 1632 | ************************* 03:56:20 INFO - PROCESS | 1632 | A coding exception was thrown and uncaught in a Task. 03:56:20 INFO - PROCESS | 1632 | 03:56:20 ERROR - PROCESS | 1632 | Full message: TypeError: NetworkError when attempting to fetch resource. 03:56:20 INFO - PROCESS | 1632 | Full stack: 03:56:20 INFO - PROCESS | 1632 | ************************* 03:57:39 INFO - PROCESS | 1632 | Cycle 1(1): loaded http://localhost:49246/tests/dromaeo/cssquery-ext.html (next: http://localhost:49246/tests/dromaeo/cssquery-jquery.html) 03:57:40 INFO - PROCESS | 1632 | RSS: Main: 266723328 03:57:40 INFO - PROCESS | 1632 | 04:00:42 INFO - PROCESS | 1632 | Cycle 1(1): loaded http://localhost:49246/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49246/tests/dromaeo/cssquery-mootools.html) 04:00:42 INFO - PROCESS | 1632 | RSS: Main: 278953984 04:00:42 INFO - PROCESS | 1632 | 04:03:44 INFO - PROCESS | 1632 | Cycle 1(1): loaded http://localhost:49246/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49246/tests/dromaeo/cssquery-prototype.html) 04:03:45 INFO - PROCESS | 1632 | RSS: Main: 271826944 04:03:45 INFO - PROCESS | 1632 | 04:06:48 INFO - PROCESS | 1632 | Cycle 1(1): loaded http://localhost:49246/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49246/tests/dromaeo/cssquery-yui.html) 04:06:48 INFO - PROCESS | 1632 | RSS: Main: 276901888 04:06:48 INFO - PROCESS | 1632 | 04:09:51 INFO - PROCESS | 1632 | Cycle 1(1): loaded http://localhost:49246/tests/dromaeo/cssquery-yui.html (next: http://localhost:49246/tests/dromaeo/cssquery-dojo.html) 04:09:51 INFO - PROCESS | 1632 | RSS: Main: 278716416 04:09:51 INFO - PROCESS | 1632 | 04:09:51 INFO - PROCESS | 1632 | __start_tp_report 04:09:51 INFO - PROCESS | 1632 | _x_x_mozilla_page_load 04:09:51 INFO - PROCESS | 1632 | _x_x_mozilla_page_load_details 04:09:51 INFO - PROCESS | 1632 | |i|pagename|runs| 04:09:51 INFO - PROCESS | 1632 | |0;dojo.html;3284.9671503284976;3286.704196622304;3322.534845121682;3330.4671252599587;3333.9499907501386;4330.307150855863;5300.966311569246;5330.227117068022;5469.671819690816;5509.586780991427;5364.624476286652;5366.677999320043;5454.10007348788;5485.4788795064505;5519.917201241985;4822.807087716487;4978.253262010691;5002.799888004475;5044.520770526794;5105.821296254632;4923.704577725348;5108.64239503235;5168.948310516885;5171.896562068757;5223.477652234784;4927.77824997876;4974.27872958419;4993.950060499385;5088.23676448532;5093.388793344785;5022.949770502321;5043.268726034741;5046.293518907356;5073.644844860862;5074.112030394666;4940.135476291634;4983.352164218664;5014.448410674823;5046.545810877039;5058.367704036996;4559;5011.749412529359;5296.629235953485;5383.057964856134;5412.404635490093;5854.20968169296;5884.382139875337;5941.851453713692;5983.282006159288;6048.576599638027;6078.483328917047;6081.452669259788;6090.23872015998;6110.663913484756;6117.235345581802;5585.74864131111;5599.972000139973;5611.186377975211;5611.803586874522;5614.933162699074;4668;5788.131780232999;6108.083787431832;6119.204503414528;6143.139960405547;5621.269234999424;5721.828345149652;5889.1755154278435;5899.675517846474;5953.821385358447;5886.0288052471715;6059.151718759377;6074.331823499411;6077.544184186204;6118.632882027093;6041.033434650522;6053.303870055;6065.575409721366;6068.757249709962;6069.0592958091565;4418.0059486615255;5760.654360738369;5823.272090988627;5917.201177841022;5929.436703513159;4095.406166105898;4117.608827161415;4777.641676874248;4810.182269014276;4822.445418776815;4302.204092242945;4308.547602501755;4314.439122914001;4317.136572685476;4350.303951367766;5449.427810079964;5528.75120619565;5553.750081246354;5565.1374037024325;5591.944080559224;5585.106382978704;5597.944020559742;6098;6141.785037523665;6145.692715364214;5185.0148471790235;5237.188235823453;5588.664680119125;5605.831825045255;5627.465390787868;5128.9230661540105;5137.486251374908;5331.20031995196;5332.33345831771;5337.572994160458;6115.879294474783;6124.869201255686;6134.202553668084;6156.26124865019;6156.322804491592;6106.521339539896;6111.682900513672;6111.866584976022;6115.179495343419;6145.8156255312415;6036.486898613667;6134.957077261001;6149.569530132848;6154.78458253959;6175.351588083367;5723.856903577277;5934.169216309634;5979.162917191684;5981.521478281814;6097.2683278006925;6040.426159514839;6078.787242446493;6095.329513753395;6117.510599152146;6132.5400594956445;4185.351270553069;4350.21696094706;5620.690862002629;5886.205362276038;5942.227510423617;5934.139549765174;5941.970290148522;5956.8808623828145;5958.523318134626;5987.64074155552;5651.208830763779;5729.140628905698;5795.39148389413;5807.62250453736;5817.563682723898;5620.325560932714;5650.971745141248;5804.5356371491025;5928.3775203603;5940.79207227745;5651.378348381592;5742.368339482737;5757.395473475225;5801.680907550125;5831.475167234969 04:09:51 INFO - PROCESS | 1632 | |1;ext.html;422.58375500132377;427.5831064711906;430.81474965764727;539.2558269587969;551.4320250142355;38683.806580967124;40815.79592102039;40894.59105408945;40955.59044409555;43085.353719694154;41645;42199.36700949488;42221.366679499835;42327.36508952368;42398.36402453966;7741.3419859312025;9279.72160835176;9329.30030247734;9360.672376466826;10502.159827213844;8705.564721763918;10864.565417383335;10887;10890.455477226147;10896.019358257756;10861.945690271537;10881.292715973475;10915.781684366308;10927.836082458769;10931.18016148788;9110.225630821387;10825.188110891675;10908.400037997906;10913.290636108628;10926.890731092746;10693.732656683567;10779.35323880562;10786.676399708022;10797.892021079768;10858.294210876267;35772;36288.9113326598;36306.27387452236;36366;36398.27203455916;3755.3615885299564;3814.6376094271;3816.3512202925567;3823.0824602095577;3925.332693442093;27918;28175.85912070447;28242;28322.44226567538;28403.289917752016;3030.499967505359;3060.0972713049705;3185.0922487091298;3230.7415406767636;3232.4504834178247;474637;478845;478849;479483;480177;36240.81879590585;45646.543534564895;54579.72710136503;54633.72683136638;55036.899262014536;57184.71407642935;57621.71189144111;57931.71034144887;61613.69193154005;61886.381136188065;14585.103936488189;14657.56027319182;14681.41274349014;14814.185219813015;14852.851471485363;11038.006579407891;11304.886951130384;11319.151063670093;11410.030147437395;11461.71345716347;17270.654586908193;17401.91299043497;17470.825291746918;17500.82499174992;17524.29902803899;7741.7677469676;7861.2531809478005;7896.960515197387;7931.920680793233;7951.920480795118;688.8036909480774;825.2820046559532;835.6197929941862;839.1189251286125;862.0646598440637;23537.17619883296;29580.852095739814;29791.106266812032;29949.25126871802;30463.543046854316;863.8272345530793;978.4765150644371;1011.2567263061791;1037.6316407675317;1191.8808119188186;14041.087329323562;14801;15063.472778452702;15326.310316035806;15573.532794015971;203886.9805650992;203931;204307;204429;204695;58854.528636784424;59456.81086378335;59646.70176649089;59704;59951;1313.8226339444052;1358.6263777461277;1368.6236285021698;1370.3833275026277;1371.5748118083427;25136.49727005486;28829.27926801847;29008.984685535906;29056.564151538165;29804.70195298019;48318.27522587094;48784.51215487799;48854.26718599295;48884.51115488942;48997;500582;503441;504083;505301;505462;24050.797460127273;24243.878780605985;24882.004719811004;24976.875115624305;26225;36730.44904326384;41618.37572436476;41690.16619667529;41758;42826.57173428226;27693.30766730848;33885;34410;35307.82346088253;35683;34317;34343.82828085944;34442.48336274908;35308.11729706778;35464.113397165274;34382.31235375329;34793.9561813146;35091;35143.297134057684;35155;28747;30123.849380752956;31082.689173108884;31403.05790826279;31420.371592568477;21728.456788580414;22815.745134017787;23105.613663180262;23673;23717.05131794778 04:09:51 INFO - PROCESS | 1632 | |2;jquery.html;370.7793862651722;379.70383101181056;385.452657226738;398.82252397683044;412.18387592566717;18393.816061839378;19831.60336793267;20026.59946801063;20209.39371818846;20268.29060982866;20051.59896802063;20409.69385459219;20463.897680511578;20766.792332076675;20920.686189707165;4505.571970662798;5047.899042019158;5418.566514678826;5419.458054194569;5687.60186786923;5538.972305138469;5586.664800111986;5629.859253518674;5632.5212356949705;5637.8590535236535;5353.866153346178;5499.120140777476;5524.751386187631;5562;5604.8038318658855;5039.143345631252;5057.9494205057845;5063.392392912837;5074.746262686888;5093.439721630618;4412.189845782712;4539.84110556129;4878.341423907763;4926.285688575136;4968.205087186069;22387.992540335723;23746.7625323748;23967.760322396727;24365.512689746283;24735.505289894103;2459.5203935232635;2529.9241022769347;2539.0478570536047;2561.1548189097552;2567.14000809729;9981.351212171186;10305.229071719861;10331.121854642364;10423.426711530863;15171.544853654408;1537.2160198298836;1736.2273788164273;1778.2175842629201;1801.2539997408226;1815.0471002723543;101068;123730.76269237282;130586;138405.38770367627;145721.99999999895;9151.67969121091;11603.825942610867;11655.30068195894;11703.063754899758;11710.824337634942;14842.480513181987;14893.70212595742;14924.403023878924;14925.940182262828;14930;8116.472429292077;8396.790080248044;8446.788830279169;8521.872171917426;8550.315974722007;8080.232377924088;8086.272235498715;8094.109647938722;8214.753557393287;8236.876446853303;4642.930356044662;4709.921997499208;4726.125666751662;4777.378940737682;4789.616830653539;1974.3682021753189;2007.4178488238247;2008.7991200879794;2015.6271089848426;2039.12317703386;997.9501024948723;1002.3785253142952;1005.3364779245662;1013.7668336282696;1054.8681414823147;20107;20326.79673203249;20840.06219720116;21015.474613134793;21026.158953641683;795.9880601790978;856.9785755356166;858.8571514072305;870.2515836380708;1058.3332500524775;5409.7295135243085;6311.495080393557;6328.398802113792;6502.772402965874;6515.641639709862;613713;692320.5383973048;706173;708891;712498;110440;129884;153477;159729;160222;1021.6832781837654;1039.5374058544026;1064.2497039587072;1071.212658695858;1075.2365820267541;13705.725885482434;15491.83811214186;17011.829881701025;19767.604647906675;19880.111389417983;59883.700581496814;60270.698646506484;60358.698206508685;60496;60926.39073609207;77346.6132669333;78746.6062669683;79494;81526;88563.67154492559;5759.769609215752;5931.347551769216;7841.666916624302;8285.337173026022;8417.83164336722;11169.218154729091;12670.176438531465;12749.49002039908;13043.347832608142;13162.736745265232;10896.526315265242;11077.058450031514;11078.224524283545;11311.038561722016;11382.94308528452;9351.924528679288;9364.484953327632;9924.603015879284;10838;11212.55149793999;10777.838332425177;10844.69863616371;11060.944695276794;11431.428428578703;11581.957623813898;8082.393820463371;8119.715809946623;8126.512409255463;8451.957740211259;8603.182697643713;8166.183381661786;10558.208134389797;10922.235443518877;11234;11303.095752339957 04:09:51 INFO - PROCESS | 1632 | |3;mootools.html;1193.6120760752756;1378.2764048874346;1383.6232948216525;1385.445821671332;1392.0812263905825;6218.098375735527;8086.272235498811;8335.708250211244;8397.1602839716;8540.871886921701;7442.367398771097;8387.748367548968;8478.957605211981;8510.234078932895;8525.445846020004;2215.8338124640636;2235.2400183937457;2238.21662418154;2242.8990695418665;2248.1344682297354;2262.400463877074;2293.220305096267;2295.0934380919525;2299.999999999992;2311.7341505726804;2247.314569056437;2248.853824501402;2265.4562904902787;2273.783990520898;2286.8970896309706;2183.257692384581;2248.392933907844;2271.6478945763515;2273.4089136824377;2273.897674604647;1890.5084677983687;1950.5123719070232;2041.4896275931017;2145.0240140735928;2283.2693538067824;8217.137200593912;8447.619857106383;8449.36629752771;8531.488110713377;8540.231379175842;5026.195808670632;5181.766820493087;5193.68837869729;5193.948060519384;5198.818041368571;3410.300888317889;3453.6546345365514;3480.060383696388;3490.1972546314237;3528.0826984983833;1197.574860924368;1200.6878211664944;1230.163488827597;1254.4731212890517;1256.4094875408543;9295.349325547215;10100.343477674001;10877.945610271898;11024.173187010925;11031.889681103166;4193.643540299047;4403.889902752457;4491.348754430589;4516.277395616686;4528.43394575678;8666.306695464475;8701.608427620646;8725.650973961097;8727.432716873383;8743.081976392514;4639.466461356919;4760.928586071212;5081.491850814963;5090.338256026693;5100.311457953128;4568.697921092473;4608.470025947059;4724.527547245317;4757.714537127784;4763.04739052191;3190.601174853143;3611.8622633870245;3643.7267204959735;3712.535933008374;3716.869909553153;1157.5948418053613;1163.6683545189749;1165.720227145496;1166.650004998497;1167.0371943146938;850.2475309351269;867.3841572483482;878.3587980774071;907.9001309855911;923.2613908872875;4094.5023535643236;5722.971385143048;5741.282339707536;5769.740361683735;5955.196048533479;716.7634680555509;800.8438354520929;920.6317473010849;953.699584630839;1000.2348203624088;3657.451382292624;3675.3751862183494;3690.4833323334756;3737.121776382548;3808.6953043756434;9760.658376956773;10028.799424011479;10038.297319187586;10046.497675116216;10050.899491005142;9982.850257246148;9997.750056248651;10008.49957502122;10011.849822252672;10013.79972400548;6499.675016249169;7450.292222238879;7705.57619330931;7867.173946735625;7868;5498.945010549844;5676.9432305678065;5678.943210568007;5745.2818397700285;5792.304923409218;8780;8953.731388058368;9025.593848276712;9084.046175151514;9100.544972751468;8874.511901845202;8976.147266009719;9239.214666753402;9293.953530232306;9436.103570160823;6922.307769223037;7219.566825990458;7240;7246;7261.745838895613;7801.726939557088;7814.960925195338;7821.804454888674;7856.607169641609;7940.523568585903;6105.511559075175;7173.641317933978;7194.856102877809;7209.315115064061;7307.159676637255;6823.351781580742;6927.016454886544;7028.0863487746265;7076.150861896605;7154;4914.950850491449;5189.584833213409;5302.867428314323;6379.425851673372;6409.4231519163495;4894;4917.754112294299;4970.3290055842;5025.447200807835;5148.897022059463;4849.418069831643;4944.554990050912;4982.252662100714;5008.298838162733;5012.020252000512 04:09:51 INFO - PROCESS | 1632 | |4;prototype.html;351.2535861294747;358.9712822974162;362.8457905390209;366.9651383118603;368.7437231124368;4713.222318317482;4970.744162573511;5315.415304316522;5368.570514358852;5422.9328003997;5143.562797162236;5251.5798736101115;5278.630495865291;5279.788808447667;5334.626576139672;3543.5393398858178;3546.521219635355;3797.9430308545393;4092.6521245694003;4109.773962432065;3239.8012735287975;3527.3121741260466;3752.1932784451337;3900.902477438058;4114.321137012389;3502.176988407722;3961.445397644332;3972.9801350993203;4001.2197621463824;4025.3559430491127;3186.474231751758;3257.5144595282936;3466.480027995796;3615.186583018813;3630.618785027587;2969.5100308449296;3027.3652227796965;3470.2538954124734;3479.80861052642;3480.756347055707;4531.731115287725;4917.655764096479;4974.37820272466;5074.898502029939;5222.791088356461;4573.827431969821;4589.839355622537;4603.158736505391;4620.676552641316;4638.8608341749805;4954.455009948902;5455.8542706031785;5668.518175955049;5699.31870098322;5733.168690539848;2926.4732348177317;3151.495760678303;3227.4836026235926;3514.9824250878582;3852.84588616455;4756.857294281177;5376.924615077;5529.972350138264;5531.363893152299;5828.504577110951;3117;3592.9820350898076;3852.711046671455;3857.672097871712;3868.4390763339156;3898.4913577780226;3929.9957021918453;3939.783311917872;3957.6438120569287;3968.1865217630316;3414.1805966567845;4484.461864576207;4606.838760643428;4640.628749700016;4669.626429885601;4223.092035212444;4636.374089497875;5617.9719101404235;5664;5699.373068962411;1192.6958625550428;1223.9265644061384;1292.2569522524586;1294.5080869269618;1305.0733978874916;754.3776384482824;855.2388374346837;867.3884911137633;873.3449912565576;879.3800370738614;4344.413504176896;4656.697314674535;4866.683665561727;4994.400671919393;5014.668179296572;4692.6245900327895;5118.718470484085;5557.305336832896;5631;5655.066913959151;4187.392828039917;4694.6009589185305;4845.709257444494;4973.104841128559;5001.974200448824;2510.134003768697;2796.6783819860975;2934.7380626330882;2937.4578346368326;2945.7937944344117;5346.358436987509;5621.606487545914;5745.827625171251;5746.9425305747245;5766.308043034779;4928.866360737051;5287.867803304948;5562.749676264497;5742.569307301969;6044;5089;5839.678817664984;5987.6108052976415;5993.550483713651;6025.6083354581815;4559.179347717442;4806.543378379049;5751.769929202785;5808.273965754281;5849.532037436909;4786.213786213786;5409.269748584048;5779.2775903012125;5827.8344331134795;5833.591648584558;5002.949970500248;5713.771449142154;5858.94141058584;6035.714642603801;6049.001914684204;3627.673509384168;3808.067023579241;3919.92160156801;3951.8221680025044;3965.2862484753023;5055.545000949932;5098.311727916832;5335.66608347913;5687.20379146928;5894.37904576245;5142.742862856917;5230.660007099526;5468.972655136698;5666.829995100154;5708.286464191976;4814.350062741485;5345.545628621455;5608.4111168326735;5723.456271654186;5727.255456790757;4341.869743907688;5562.888742225214;5576.832695019156;5601.019821531134;5657.9151312731165;4592;4966.776495057658;5559.861003474945;5600.243967064394;5636.689982050862;5092.930484598128;5324.520793128482;5528.502434780889;5589.227271591741;5611.80358687444 04:09:51 INFO - PROCESS | 1632 | |5;yui.html;676.1446769836155;694.8506071194693;753.6570860258582;794.9761507154785;810.1291112054543;14271.785923211148;14850.257487125627;15013.249337533121;15083.924580377097;15120.865935054888;14750.26248687564;15480.225988700548;15488.69022619547;15746;15805.367785288574;853.1340689200466;876.6537217798966;878.1174919206189;879.5690111845214;881.2421317666801;839.7270886961732;868.6829307302852;869.4956924983494;876.1545108969843;885.1767855894016;908.609298001859;912.9863052054225;925.8703781470599;926.0831776541243;927.0081013315755;784.2980532423506;854.0008190417227;903.3450748207563;920.6961702638114;921.6958403726754;675.7026908160394;685.2017399729309;686.0566720758957;686.8729285082276;687.0038444255799;14777.852221477862;15043.473478428203;15045.247737613076;15058.698826023417;15180;13477.730445391135;13694.315284235849;13754.587362379145;13769.35112195269;13775.38010789507;10536;11118.777624447546;11393.487293071748;11709.711931687507;11979.580714675032;3601.5318008658705;3625.673689367943;3629.94555081674;3633.9273214535824;3659.79871107089;91884.54057729669;92220;92733;92957;95837.5208123962;10481.790364192826;10487.29601014934;10492.895071049192;10611;10976.890231097746;20595.8970205148;20992.79007209939;21005.36983890486;21036;21330.466738331357;6856.417204537571;7029.859402811915;7411.407087432993;7443.404527637776;7610.124835643972;4050.1089760253285;4053.9594604054173;4100.7334523255895;4103.835846566104;4108.650764684976;3447.413939630269;3541.0970202598173;3750.7562008469363;3872.670822980022;3915.804209789556;702.0592406175749;713.6610110197614;728.9307515785991;729.4237552333703;733.5195446982235;631.9414979908635;637.8724255148989;654.6432194453939;671.3466482104053;684.4592771710293;8504.44721093127;9508.809823803485;9745.658901938397;9922.355046921926;10298.433586152834;583.3379114704841;641.6951947824839;683.4429939599211;692.4183685704032;692.8545005548891;4578.610818080434;4886.395762346204;4891.070181607471;4922.384701912261;4935.629827762932;95111;97729.5113524442;98499;99565;99679.50160249298;77228.61385693148;83592;83615;84516;86254;44643;55069.173962390596;63912.402189944696;68018.95943121714;75014.74955751337;7641.235876412315;8963.238124759468;9406.858897116686;9412.776339075876;9462.57418416159;44465.11069778651;48019;48593;49060.509394905595;49176.2623560654;45264.54735452735;49315.753421232665;55212.343629691175;59360;60942;11306.660800176009;11409.657710268706;11425.257358271685;11445.313281203154;11796.646100616996;12235.69410764738;12393.504259829506;12616.87383126157;12693.111482196158;12903.935480322538;10366;10979.39613321275;11589.478473468545;11611.477483513094;11802.40613194831;11369.602063927723;11466.082713382742;11683.532658693557;11686.415679215836;11942.522299107939;11373.943130284295;11476.942615286871;11630.6510804676;11648.301101933912;11987.981021613261;8730.56347182651;9409.435433873989;9546.085666937182;9657.613695452103;9786.1192492676;7435.925640743523;7778.727744529141;9299.070092990647;9323.72028839136;9355.85966210521 04:09:51 INFO - PROCESS | 1632 | __end_tp_report 04:09:51 INFO - PROCESS | 1632 | __start_cc_report 04:09:51 INFO - PROCESS | 1632 | _x_x_mozilla_cycle_collect,402 04:09:51 INFO - PROCESS | 1632 | __end_cc_report 04:09:51 INFO - PROCESS | 1632 | __startTimestamp1486642191966__endTimestamp 04:09:51 INFO - PROCESS | 1632 | 04:09:51 INFO - PROCESS | 1632 | ------- Summary: start ------- 04:09:51 INFO - PROCESS | 1632 | Number of tests: 6 04:09:51 INFO - PROCESS | 1632 | 04:09:51 INFO - PROCESS | 1632 | [#0] dojo.html Cycles:165 Average:5485.83 Median:5621.27 stddev:656.91 (11.7%) stddev-sans-first:635.83 04:09:51 INFO - PROCESS | 1632 | Values: 3285.0 3286.7 3322.5 3330.5 3333.9 4330.3 5301.0 5330.2 5469.7 5509.6 5364.6 5366.7 5454.1 5485.5 5519.9 4822.8 4978.3 5002.8 5044.5 5105.8 4923.7 5108.6 5168.9 5171.9 5223.5 4927.8 4974.3 4994.0 5088.2 5093.4 5022.9 5043.3 5046.3 5073.6 5074.1 4940.1 4983.4 5014.4 5046.5 5058.4 4559.0 5011.7 5296.6 5383.1 5412.4 5854.2 5884.4 5941.9 5983.3 6048.6 6078.5 6081.5 6090.2 6110.7 6117.2 5585.7 5600.0 5611.2 5611.8 5614.9 4668.0 5788.1 6108.1 6119.2 6143.1 5621.3 5721.8 5889.2 5899.7 5953.8 5886.0 6059.2 6074.3 6077.5 6118.6 6041.0 6053.3 6065.6 6068.8 6069.1 4418.0 5760.7 5823.3 5917.2 5929.4 4095.4 4117.6 4777.6 4810.2 4822.4 4302.2 4308.5 4314.4 4317.1 4350.3 5449.4 5528.8 5553.8 5565.1 5591.9 5585.1 5597.9 6098.0 6141.8 6145.7 5185.0 5237.2 5588.7 5605.8 5627.5 5128.9 5137.5 5331.2 5332.3 5337.6 6115.9 6124.9 6134.2 6156.3 6156.3 6106.5 6111.7 6111.9 6115.2 6145.8 6036.5 6135.0 6149.6 6154.8 6175.4 5723.9 5934.2 5979.2 5981.5 6097.3 6040.4 6078.8 6095.3 6117.5 6132.5 4185.4 4350.2 5620.7 5886.2 5942.2 5934.1 5942.0 5956.9 5958.5 5987.6 5651.2 5729.1 5795.4 5807.6 5817.6 5620.3 5651.0 5804.5 5928.4 5940.8 5651.4 5742.4 5757.4 5801.7 5831.5 04:09:51 INFO - PROCESS | 1632 | 04:09:51 INFO - PROCESS | 1632 | [#1] ext.html Cycles:180 Average:54416.52 Median:34363.07 stddev:111579.17 (324.7%) stddev-sans-first:111818.53 04:09:51 INFO - PROCESS | 1632 | Values: 422.6 427.6 430.8 539.3 551.4 38683.8 40815.8 40894.6 40955.6 43085.4 41645.0 42199.4 42221.4 42327.4 42398.4 7741.3 9279.7 9329.3 9360.7 10502.2 8705.6 10864.6 10887.0 10890.5 10896.0 10861.9 10881.3 10915.8 10927.8 10931.2 9110.2 10825.2 10908.4 10913.3 10926.9 10693.7 10779.4 10786.7 10797.9 10858.3 35772.0 36288.9 36306.3 36366.0 36398.3 3755.4 3814.6 3816.4 3823.1 3925.3 27918.0 28175.9 28242.0 28322.4 28403.3 3030.5 3060.1 3185.1 3230.7 3232.5 474637.0 478845.0 478849.0 479483.0 480177.0 36240.8 45646.5 54579.7 54633.7 55036.9 57184.7 57621.7 57931.7 61613.7 61886.4 14585.1 14657.6 14681.4 14814.2 14852.9 11038.0 11304.9 11319.2 11410.0 11461.7 17270.7 17401.9 17470.8 17500.8 17524.3 7741.8 7861.3 7897.0 7931.9 7951.9 688.8 825.3 835.6 839.1 862.1 23537.2 29580.9 29791.1 29949.3 30463.5 863.8 978.5 1011.3 1037.6 1191.9 14041.1 14801.0 15063.5 15326.3 15573.5 203887.0 203931.0 204307.0 204429.0 204695.0 58854.5 59456.8 59646.7 59704.0 59951.0 1313.8 1358.6 1368.6 1370.4 1371.6 25136.5 28829.3 29009.0 29056.6 29804.7 48318.3 48784.5 48854.3 48884.5 48997.0 500582.0 503441.0 504083.0 505301.0 505462.0 24050.8 24243.9 24882.0 24976.9 26225.0 36730.4 41618.4 41690.2 41758.0 42826.6 27693.3 33885.0 34410.0 35307.8 35683.0 34317.0 34343.8 34442.5 35308.1 35464.1 34382.3 34794.0 35091.0 35143.3 35155.0 28747.0 30123.8 31082.7 31403.1 31420.4 21728.5 22815.7 23105.6 23673.0 23717.1 04:09:51 INFO - PROCESS | 1632 | 04:09:51 INFO - PROCESS | 1632 | [#2] jquery.html Cycles:180 Average:38120.79 Median:2534.49 stddev:114814.50 (4530.1%) stddev-sans-first:115101.59 04:09:51 INFO - PROCESS | 1632 | Values: 370.8 379.7 385.5 398.8 412.2 18393.8 19831.6 20026.6 20209.4 20268.3 20051.6 20409.7 20463.9 20766.8 20920.7 4505.6 5047.9 5418.6 5419.5 5687.6 5539.0 5586.7 5629.9 5632.5 5637.9 5353.9 5499.1 5524.8 5562.0 5604.8 5039.1 5057.9 5063.4 5074.7 5093.4 4412.2 4539.8 4878.3 4926.3 4968.2 22388.0 23746.8 23967.8 24365.5 24735.5 2459.5 2529.9 2539.0 2561.2 2567.1 9981.4 10305.2 10331.1 10423.4 15171.5 1537.2 1736.2 1778.2 1801.3 1815.0 101068.0 123730.8 130586.0 138405.4 145722.0 9151.7 11603.8 11655.3 11703.1 11710.8 14842.5 14893.7 14924.4 14925.9 14930.0 8116.5 8396.8 8446.8 8521.9 8550.3 8080.2 8086.3 8094.1 8214.8 8236.9 4642.9 4709.9 4726.1 4777.4 4789.6 1974.4 2007.4 2008.8 2015.6 2039.1 998.0 1002.4 1005.3 1013.8 1054.9 20107.0 20326.8 20840.1 21015.5 21026.2 796.0 857.0 858.9 870.3 1058.3 5409.7 6311.5 6328.4 6502.8 6515.6 613713.0 692320.5 706173.0 708891.0 712498.0 110440.0 129884.0 153477.0 159729.0 160222.0 1021.7 1039.5 1064.2 1071.2 1075.2 13705.7 15491.8 17011.8 19767.6 19880.1 59883.7 60270.7 60358.7 60496.0 60926.4 77346.6 78746.6 79494.0 81526.0 88563.7 5759.8 5931.3 7841.7 8285.3 8417.8 11169.2 12670.2 12749.5 13043.3 13162.7 10896.5 11077.1 11078.2 11311.0 11382.9 9351.9 9364.5 9924.6 10838.0 11212.6 10777.8 10844.7 11060.9 11431.4 11582.0 8082.4 8119.7 8126.5 8452.0 8603.2 8166.2 10558.2 10922.2 11234.0 11303.1 04:09:51 INFO - PROCESS | 1632 | 04:09:51 INFO - PROCESS | 1632 | [#3] mootools.html Cycles:180 Average:5326.20 Median:5053.84 stddev:2925.29 (57.9%) stddev-sans-first:2917.00 04:09:51 INFO - PROCESS | 1632 | Values: 1193.6 1378.3 1383.6 1385.4 1392.1 6218.1 8086.3 8335.7 8397.2 8540.9 7442.4 8387.7 8479.0 8510.2 8525.4 2215.8 2235.2 2238.2 2242.9 2248.1 2262.4 2293.2 2295.1 2300.0 2311.7 2247.3 2248.9 2265.5 2273.8 2286.9 2183.3 2248.4 2271.6 2273.4 2273.9 1890.5 1950.5 2041.5 2145.0 2283.3 8217.1 8447.6 8449.4 8531.5 8540.2 5026.2 5181.8 5193.7 5193.9 5198.8 3410.3 3453.7 3480.1 3490.2 3528.1 1197.6 1200.7 1230.2 1254.5 1256.4 9295.3 10100.3 10877.9 11024.2 11031.9 4193.6 4403.9 4491.3 4516.3 4528.4 8666.3 8701.6 8725.7 8727.4 8743.1 4639.5 4760.9 5081.5 5090.3 5100.3 4568.7 4608.5 4724.5 4757.7 4763.0 3190.6 3611.9 3643.7 3712.5 3716.9 1157.6 1163.7 1165.7 1166.7 1167.0 850.2 867.4 878.4 907.9 923.3 4094.5 5723.0 5741.3 5769.7 5955.2 716.8 800.8 920.6 953.7 1000.2 3657.5 3675.4 3690.5 3737.1 3808.7 9760.7 10028.8 10038.3 10046.5 10050.9 9982.9 9997.8 10008.5 10011.8 10013.8 6499.7 7450.3 7705.6 7867.2 7868.0 5498.9 5676.9 5678.9 5745.3 5792.3 8780.0 8953.7 9025.6 9084.0 9100.5 8874.5 8976.1 9239.2 9294.0 9436.1 6922.3 7219.6 7240.0 7246.0 7261.7 7801.7 7815.0 7821.8 7856.6 7940.5 6105.5 7173.6 7194.9 7209.3 7307.2 6823.4 6927.0 7028.1 7076.2 7154.0 4915.0 5189.6 5302.9 6379.4 6409.4 4894.0 4917.8 4970.3 5025.4 5148.9 4849.4 4944.6 4982.3 5008.3 5012.0 04:09:51 INFO - PROCESS | 1632 | 04:09:51 INFO - PROCESS | 1632 | [#4] prototype.html Cycles:180 Average:4427.09 Median:4960.62 stddev:1401.31 (28.2%) stddev-sans-first:1371.45 04:09:51 INFO - PROCESS | 1632 | Values: 351.3 359.0 362.8 367.0 368.7 4713.2 4970.7 5315.4 5368.6 5422.9 5143.6 5251.6 5278.6 5279.8 5334.6 3543.5 3546.5 3797.9 4092.7 4109.8 3239.8 3527.3 3752.2 3900.9 4114.3 3502.2 3961.4 3973.0 4001.2 4025.4 3186.5 3257.5 3466.5 3615.2 3630.6 2969.5 3027.4 3470.3 3479.8 3480.8 4531.7 4917.7 4974.4 5074.9 5222.8 4573.8 4589.8 4603.2 4620.7 4638.9 4954.5 5455.9 5668.5 5699.3 5733.2 2926.5 3151.5 3227.5 3515.0 3852.8 4756.9 5376.9 5530.0 5531.4 5828.5 3117.0 3593.0 3852.7 3857.7 3868.4 3898.5 3930.0 3939.8 3957.6 3968.2 3414.2 4484.5 4606.8 4640.6 4669.6 4223.1 4636.4 5618.0 5664.0 5699.4 1192.7 1223.9 1292.3 1294.5 1305.1 754.4 855.2 867.4 873.3 879.4 4344.4 4656.7 4866.7 4994.4 5014.7 4692.6 5118.7 5557.3 5631.0 5655.1 4187.4 4694.6 4845.7 4973.1 5002.0 2510.1 2796.7 2934.7 2937.5 2945.8 5346.4 5621.6 5745.8 5746.9 5766.3 4928.9 5287.9 5562.7 5742.6 6044.0 5089.0 5839.7 5987.6 5993.6 6025.6 4559.2 4806.5 5751.8 5808.3 5849.5 4786.2 5409.3 5779.3 5827.8 5833.6 5002.9 5713.8 5858.9 6035.7 6049.0 3627.7 3808.1 3919.9 3951.8 3965.3 5055.5 5098.3 5335.7 5687.2 5894.4 5142.7 5230.7 5469.0 5666.8 5708.3 4814.4 5345.5 5608.4 5723.5 5727.3 4341.9 5562.9 5576.8 5601.0 5657.9 4592.0 4966.8 5559.9 5600.2 5636.7 5092.9 5324.5 5528.5 5589.2 5611.8 04:09:51 INFO - PROCESS | 1632 | 04:09:51 INFO - PROCESS | 1632 | [#5] yui.html Cycles:180 Average:18478.56 Median:27897.84 stddev:26289.98 (94.2%) stddev-sans-first:26329.75 04:09:51 INFO - PROCESS | 1632 | Values: 676.1 694.9 753.7 795.0 810.1 14271.8 14850.3 15013.2 15083.9 15120.9 14750.3 15480.2 15488.7 15746.0 15805.4 853.1 876.7 878.1 879.6 881.2 839.7 868.7 869.5 876.2 885.2 908.6 913.0 925.9 926.1 927.0 784.3 854.0 903.3 920.7 921.7 675.7 685.2 686.1 686.9 687.0 14777.9 15043.5 15045.2 15058.7 15180.0 13477.7 13694.3 13754.6 13769.4 13775.4 10536.0 11118.8 11393.5 11709.7 11979.6 3601.5 3625.7 3629.9 3633.9 3659.8 91884.5 92220.0 92733.0 92957.0 95837.5 10481.8 10487.3 10492.9 10611.0 10976.9 20595.9 20992.8 21005.4 21036.0 21330.5 6856.4 7029.9 7411.4 7443.4 7610.1 4050.1 4054.0 4100.7 4103.8 4108.7 3447.4 3541.1 3750.8 3872.7 3915.8 702.1 713.7 728.9 729.4 733.5 631.9 637.9 654.6 671.3 684.5 8504.4 9508.8 9745.7 9922.4 10298.4 583.3 641.7 683.4 692.4 692.9 4578.6 4886.4 4891.1 4922.4 4935.6 95111.0 97729.5 98499.0 99565.0 99679.5 77228.6 83592.0 83615.0 84516.0 86254.0 44643.0 55069.2 63912.4 68019.0 75014.7 7641.2 8963.2 9406.9 9412.8 9462.6 44465.1 48019.0 48593.0 49060.5 49176.3 45264.5 49315.8 55212.3 59360.0 60942.0 11306.7 11409.7 11425.3 11445.3 11796.6 12235.7 12393.5 12616.9 12693.1 12903.9 10366.0 10979.4 11589.5 11611.5 11802.4 11369.6 11466.1 11683.5 11686.4 11942.5 11373.9 11476.9 11630.7 11648.3 11988.0 8730.6 9409.4 9546.1 9657.6 9786.1 7435.9 7778.7 9299.1 9323.7 9355.9 04:09:51 INFO - PROCESS | 1632 | -------- Summary: end -------- 04:09:51 INFO - PROCESS | 1632 | 04:09:52 INFO - TEST-INFO | 1632: exit 0 04:09:52 INFO - TEST-OK | dromaeo_css | took 1116004ms 04:09:52 INFO - TEST-START | kraken 04:09:52 INFO - operating with platform_type : mac_ 04:09:52 INFO - Initialising browser for kraken test... 04:09:52 INFO - TEST-INFO | started process 1648 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/tmpc7zxhL/profile http://localhost:49246/getInfo.html) 04:09:55 INFO - PROCESS | 1648 | JavaScript error: chrome://webcompat/content/lib/ua_overrider.jsm, line 109: NS_ERROR_INSUFFICIENT_DOMAIN_LEVELS: Component returned failure code: 0x804b0050 (NS_ERROR_INSUFFICIENT_DOMAIN_LEVELS) [nsIEffectiveTLDService.getBaseDomain] 04:09:55 INFO - PROCESS | 1648 | 2017-02-09 04:09:55.448 plugin-container[1649:10136] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8a3f, name = 'com.apple.tsm.portname' 04:09:55 INFO - PROCESS | 1648 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:09:55 INFO - PROCESS | 1648 | 2017-02-09 04:09:55.451 plugin-container[1649:10136] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4717, name = 'com.apple.CFPasteboardClient' 04:09:55 INFO - PROCESS | 1648 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:09:55 INFO - PROCESS | 1648 | 2017-02-09 04:09:55.451 plugin-container[1649:10136] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 04:09:55 INFO - PROCESS | 1648 | __metrics Screen width/height:1600/1200 04:09:55 INFO - PROCESS | 1648 | colorDepth:24 04:09:55 INFO - PROCESS | 1648 | Browser inner width/height: 1024/689 04:09:55 INFO - PROCESS | 1648 | __metrics 04:09:55 ERROR - PROCESS | 1648 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3283: TypeError: this.tabs is undefined 04:09:56 INFO - TEST-INFO | 1648: exit 0 04:09:56 INFO - Browser initialized. 04:09:56 INFO - Running cycle 1/1 for kraken test... 04:09:56 INFO - TEST-INFO | started process 1650 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/tmpc7zxhL/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:09:58 INFO - PROCESS | 1650 | 2017-02-09 04:09:58.881 plugin-container[1651:10256] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x443f, name = 'com.apple.tsm.portname' 04:09:58 INFO - PROCESS | 1650 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:09:58 INFO - PROCESS | 1650 | 2017-02-09 04:09:58.882 plugin-container[1651:10256] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x900b, name = 'com.apple.CFPasteboardClient' 04:09:58 INFO - PROCESS | 1650 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:09:58 INFO - PROCESS | 1650 | 2017-02-09 04:09:58.882 plugin-container[1651:10256] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 04:10:08 INFO - PROCESS | 1650 | RSS: Main: 233926656 04:10:08 INFO - PROCESS | 1650 | 04:10:11 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49246/tests/kraken/driver.html?testName=audio-beat-detection) 04:10:12 INFO - PROCESS | 1650 | RSS: Main: 247349248 04:10:12 INFO - PROCESS | 1650 | 04:10:15 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49246/tests/kraken/driver.html?testName=audio-dft) 04:10:15 INFO - PROCESS | 1650 | RSS: Main: 255242240 04:10:15 INFO - PROCESS | 1650 | 04:10:19 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49246/tests/kraken/driver.html?testName=audio-fft) 04:10:19 INFO - PROCESS | 1650 | RSS: Main: 257769472 04:10:19 INFO - PROCESS | 1650 | 04:10:21 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49246/tests/kraken/driver.html?testName=audio-oscillator) 04:10:22 INFO - PROCESS | 1650 | RSS: Main: 260100096 04:10:22 INFO - PROCESS | 1650 | 04:10:24 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49246/tests/kraken/driver.html?testName=imaging-gaussian-blur) 04:10:25 INFO - PROCESS | 1650 | RSS: Main: 260771840 04:10:25 INFO - PROCESS | 1650 | 04:10:28 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49246/tests/kraken/driver.html?testName=imaging-darkroom) 04:10:28 INFO - PROCESS | 1650 | RSS: Main: 266350592 04:10:28 INFO - PROCESS | 1650 | 04:10:31 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49246/tests/kraken/driver.html?testName=imaging-desaturate) 04:10:32 INFO - PROCESS | 1650 | RSS: Main: 266002432 04:10:32 INFO - PROCESS | 1650 | 04:10:35 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49246/tests/kraken/driver.html?testName=json-parse-financial) 04:10:36 INFO - PROCESS | 1650 | RSS: Main: 265850880 04:10:36 INFO - PROCESS | 1650 | 04:10:37 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49246/tests/kraken/driver.html?testName=json-stringify-tinderbox) 04:10:38 INFO - PROCESS | 1650 | RSS: Main: 264974336 04:10:38 INFO - PROCESS | 1650 | 04:10:40 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49246/tests/kraken/driver.html?testName=stanford-crypto-aes) 04:10:41 INFO - PROCESS | 1650 | RSS: Main: 265023488 04:10:41 INFO - PROCESS | 1650 | 04:10:43 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49246/tests/kraken/driver.html?testName=stanford-crypto-ccm) 04:10:44 INFO - PROCESS | 1650 | RSS: Main: 265080832 04:10:44 INFO - PROCESS | 1650 | 04:10:47 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49246/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 04:10:47 INFO - PROCESS | 1650 | RSS: Main: 265650176 04:10:47 INFO - PROCESS | 1650 | 04:10:51 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49246/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 04:10:51 INFO - PROCESS | 1650 | RSS: Main: 265277440 04:10:51 INFO - PROCESS | 1650 | 04:10:53 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49246/tests/kraken/driver.html?testName=ai-astar) 04:10:53 INFO - PROCESS | 1650 | RSS: Main: 267317248 04:10:53 INFO - PROCESS | 1650 | 04:10:53 INFO - PROCESS | 1650 | __start_tp_report 04:10:53 INFO - PROCESS | 1650 | _x_x_mozilla_page_load 04:10:53 INFO - PROCESS | 1650 | _x_x_mozilla_page_load_details 04:10:53 INFO - PROCESS | 1650 | |i|pagename|runs| 04:10:53 INFO - PROCESS | 1650 | |0;ai-astar;103;119;102;173;176;124;136;119;117;119 04:10:53 INFO - PROCESS | 1650 | |1;audio-beat-detection;148;122;144;142;146;152;152;123;137;120 04:10:53 INFO - PROCESS | 1650 | |2;audio-dft;199;208;211;229;197;199;193;201;195;201 04:10:53 INFO - PROCESS | 1650 | |3;audio-fft;84;62;59;86;63;62;60;63;99;91 04:10:53 INFO - PROCESS | 1650 | |4;audio-oscillator;88;71;83;73;73;71;103;108;100;114 04:10:53 INFO - PROCESS | 1650 | |5;imaging-gaussian-blur;90;92;90;89;104;104;90;89;95;88 04:10:53 INFO - PROCESS | 1650 | |6;imaging-darkroom;95;97;98;96;97;96;97;97;92;158 04:10:53 INFO - PROCESS | 1650 | |7;imaging-desaturate;83;144;147;93;92;94;96;93;93;148 04:10:53 INFO - PROCESS | 1650 | |8;json-parse-financial;65;66;64;64;66;66;59;80;65;64 04:10:53 INFO - PROCESS | 1650 | |9;json-stringify-tinderbox;69;87;103;92;75;71;72;73;72;74 04:10:53 INFO - PROCESS | 1650 | |10;stanford-crypto-aes;81;97;118;112;81;108;97;82;80;119 04:10:53 INFO - PROCESS | 1650 | |11;stanford-crypto-ccm;166;151;150;137;162;155;177;152;193;149 04:10:53 INFO - PROCESS | 1650 | |12;stanford-crypto-pbkdf2;178;178;180;171;169;172;228;240;184;188 04:10:53 INFO - PROCESS | 1650 | |13;stanford-crypto-sha256-iterative;67;70;69;68;70;72;81;92;88;86 04:10:53 INFO - PROCESS | 1650 | __end_tp_report 04:10:53 INFO - PROCESS | 1650 | __start_cc_report 04:10:53 INFO - PROCESS | 1650 | _x_x_mozilla_cycle_collect,989 04:10:53 INFO - PROCESS | 1650 | __end_cc_report 04:10:53 INFO - PROCESS | 1650 | __startTimestamp1486642253728__endTimestamp 04:10:53 INFO - PROCESS | 1650 | 04:10:53 INFO - PROCESS | 1650 | ------- Summary: start ------- 04:10:53 INFO - PROCESS | 1650 | Number of tests: 14 04:10:53 INFO - PROCESS | 1650 | 04:10:53 INFO - PROCESS | 1650 | [#0] ai-astar Cycles:10 Average:128.80 Median:121.50 stddev:25.96 (21.4%) stddev-sans-first:25.81 04:10:53 INFO - PROCESS | 1650 | Values: 103.0 119.0 102.0 173.0 176.0 124.0 136.0 119.0 117.0 119.0 04:10:53 INFO - PROCESS | 1650 | 04:10:53 INFO - PROCESS | 1650 | [#1] audio-beat-detection Cycles:10 Average:138.60 Median:145.00 stddev:12.52 (8.6%) stddev-sans-first:12.81 04:10:53 INFO - PROCESS | 1650 | Values: 148.0 122.0 144.0 142.0 146.0 152.0 152.0 123.0 137.0 120.0 04:10:53 INFO - PROCESS | 1650 | 04:10:53 INFO - PROCESS | 1650 | [#2] audio-dft Cycles:10 Average:203.30 Median:201.00 stddev:10.56 (5.3%) stddev-sans-first:11.09 04:10:53 INFO - PROCESS | 1650 | Values: 199.0 208.0 211.0 229.0 197.0 199.0 193.0 201.0 195.0 201.0 04:10:53 INFO - PROCESS | 1650 | 04:10:53 INFO - PROCESS | 1650 | [#3] audio-fft Cycles:10 Average:72.90 Median:73.50 stddev:15.26 (20.8%) stddev-sans-first:15.65 04:10:53 INFO - PROCESS | 1650 | Values: 84.0 62.0 59.0 86.0 63.0 62.0 60.0 63.0 99.0 91.0 04:10:53 INFO - PROCESS | 1650 | 04:10:53 INFO - PROCESS | 1650 | [#4] audio-oscillator Cycles:10 Average:88.40 Median:72.00 stddev:16.65 (23.1%) stddev-sans-first:17.66 04:10:53 INFO - PROCESS | 1650 | Values: 88.0 71.0 83.0 73.0 73.0 71.0 103.0 108.0 100.0 114.0 04:10:53 INFO - PROCESS | 1650 | 04:10:53 INFO - PROCESS | 1650 | [#5] imaging-gaussian-blur Cycles:10 Average:93.10 Median:90.00 stddev:6.06 (6.7%) stddev-sans-first:6.33 04:10:53 INFO - PROCESS | 1650 | Values: 90.0 92.0 90.0 89.0 104.0 104.0 90.0 89.0 95.0 88.0 04:10:53 INFO - PROCESS | 1650 | 04:10:53 INFO - PROCESS | 1650 | [#6] imaging-darkroom Cycles:10 Average:102.30 Median:97.00 stddev:19.64 (20.2%) stddev-sans-first:20.65 04:10:53 INFO - PROCESS | 1650 | Values: 95.0 97.0 98.0 96.0 97.0 96.0 97.0 97.0 92.0 158.0 04:10:53 INFO - PROCESS | 1650 | 04:10:53 INFO - PROCESS | 1650 | [#7] imaging-desaturate Cycles:10 Average:108.30 Median:93.00 stddev:26.48 (28.5%) stddev-sans-first:26.46 04:10:53 INFO - PROCESS | 1650 | Values: 83.0 144.0 147.0 93.0 92.0 94.0 96.0 93.0 93.0 148.0 04:10:53 INFO - PROCESS | 1650 | 04:10:53 INFO - PROCESS | 1650 | [#8] json-parse-financial Cycles:10 Average:65.90 Median:65.50 stddev:5.36 (8.2%) stddev-sans-first:5.68 04:10:53 INFO - PROCESS | 1650 | Values: 65.0 66.0 64.0 64.0 66.0 66.0 59.0 80.0 65.0 64.0 04:10:53 INFO - PROCESS | 1650 | 04:10:53 INFO - PROCESS | 1650 | [#9] json-stringify-tinderbox Cycles:10 Average:78.80 Median:73.50 stddev:11.29 (15.4%) stddev-sans-first:11.41 04:10:53 INFO - PROCESS | 1650 | Values: 69.0 87.0 103.0 92.0 75.0 71.0 72.0 73.0 72.0 74.0 04:10:53 INFO - PROCESS | 1650 | 04:10:53 INFO - PROCESS | 1650 | [#10] stanford-crypto-aes Cycles:10 Average:97.50 Median:81.00 stddev:15.97 (19.7%) stddev-sans-first:15.78 04:10:53 INFO - PROCESS | 1650 | Values: 81.0 97.0 118.0 112.0 81.0 108.0 97.0 82.0 80.0 119.0 04:10:53 INFO - PROCESS | 1650 | 04:10:53 INFO - PROCESS | 1650 | [#11] stanford-crypto-ccm Cycles:10 Average:159.20 Median:158.50 stddev:16.10 (10.2%) stddev-sans-first:16.88 04:10:53 INFO - PROCESS | 1650 | Values: 166.0 151.0 150.0 137.0 162.0 155.0 177.0 152.0 193.0 149.0 04:10:53 INFO - PROCESS | 1650 | 04:10:53 INFO - PROCESS | 1650 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:188.80 Median:182.00 stddev:24.68 (13.6%) stddev-sans-first:25.87 04:10:53 INFO - PROCESS | 1650 | Values: 178.0 178.0 180.0 171.0 169.0 172.0 228.0 240.0 184.0 188.0 04:10:53 INFO - PROCESS | 1650 | 04:10:53 INFO - PROCESS | 1650 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:76.30 Median:76.50 stddev:9.46 (12.4%) stddev-sans-first:9.42 04:10:53 INFO - PROCESS | 1650 | Values: 67.0 70.0 69.0 68.0 70.0 72.0 81.0 92.0 88.0 86.0 04:10:53 INFO - PROCESS | 1650 | -------- Summary: end -------- 04:10:53 INFO - PROCESS | 1650 | 04:10:54 INFO - TEST-INFO | 1650: exit 0 04:10:54 INFO - TEST-OK | kraken | took 62004ms 04:10:54 INFO - SUITE-END | took 1178s 04:10:54 INFO - Completed test suite (00:19:38) 04:10:54 INFO - javascript benchmark 04:10:54 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 6983.421470428211, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3284.9671503284976, 3286.704196622304, 3322.534845121682, 3330.4671252599587, 3333.9499907501386, 4330.307150855863, 5300.966311569246, 5330.227117068022, 5469.671819690816, 5509.586780991427, 5364.624476286652, 5366.677999320043, 5454.10007348788, 5485.4788795064505, 5519.917201241985, 4822.807087716487, 4978.253262010691, 5002.799888004475, 5044.520770526794, 5105.821296254632, 4923.704577725348, 5108.64239503235, 5168.948310516885, 5171.896562068757, 5223.477652234784, 4927.77824997876, 4974.27872958419, 4993.950060499385, 5088.23676448532, 5093.388793344785, 5022.949770502321, 5043.268726034741, 5046.293518907356, 5073.644844860862, 5074.112030394666, 4940.135476291634, 4983.352164218664, 5014.448410674823, 5046.545810877039, 5058.367704036996, 4559.0, 5011.749412529359, 5296.629235953485, 5383.057964856134, 5412.404635490093, 5854.20968169296, 5884.382139875337, 5941.851453713692, 5983.282006159288, 6048.576599638027, 6078.483328917047, 6081.452669259788, 6090.23872015998, 6110.663913484756, 6117.235345581802, 5585.74864131111, 5599.972000139973, 5611.186377975211, 5611.803586874522, 5614.933162699074, 4668.0, 5788.131780232999, 6108.083787431832, 6119.204503414528, 6143.139960405547, 5621.269234999424, 5721.828345149652, 5889.1755154278435, 5899.675517846474, 5953.821385358447, 5886.0288052471715, 6059.151718759377, 6074.331823499411, 6077.544184186204, 6118.632882027093, 6041.033434650522, 6053.303870055, 6065.575409721366, 6068.757249709962, 6069.0592958091565, 4418.0059486615255, 5760.654360738369, 5823.272090988627, 5917.201177841022, 5929.436703513159, 4095.406166105898, 4117.608827161415, 4777.641676874248, 4810.182269014276, 4822.445418776815, 4302.204092242945, 4308.547602501755, 4314.439122914001, 4317.136572685476, 4350.303951367766, 5449.427810079964, 5528.75120619565, 5553.750081246354, 5565.1374037024325, 5591.944080559224, 5585.106382978704, 5597.944020559742, 6098.0, 6141.785037523665, 6145.692715364214, 5185.0148471790235, 5237.188235823453, 5588.664680119125, 5605.831825045255, 5627.465390787868, 5128.9230661540105, 5137.486251374908, 5331.20031995196, 5332.33345831771, 5337.572994160458, 6115.879294474783, 6124.869201255686, 6134.202553668084, 6156.26124865019, 6156.322804491592, 6106.521339539896, 6111.682900513672, 6111.866584976022, 6115.179495343419, 6145.8156255312415, 6036.486898613667, 6134.957077261001, 6149.569530132848, 6154.78458253959, 6175.351588083367, 5723.856903577277, 5934.169216309634, 5979.162917191684, 5981.521478281814, 6097.2683278006925, 6040.426159514839, 6078.787242446493, 6095.329513753395, 6117.510599152146, 6132.5400594956445, 4185.351270553069, 4350.21696094706, 5620.690862002629, 5886.205362276038, 5942.227510423617, 5934.139549765174, 5941.970290148522, 5956.8808623828145, 5958.523318134626, 5987.64074155552, 5651.208830763779, 5729.140628905698, 5795.39148389413, 5807.62250453736, 5817.563682723898, 5620.325560932714, 5650.971745141248, 5804.5356371491025, 5928.3775203603, 5940.79207227745, 5651.378348381592, 5742.368339482737, 5757.395473475225, 5801.680907550125, 5831.475167234969], "value": 5446.582175501341, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [422.58375500132377, 427.5831064711906, 430.81474965764727, 539.2558269587969, 551.4320250142355, 38683.806580967124, 40815.79592102039, 40894.59105408945, 40955.59044409555, 43085.353719694154, 41645.0, 42199.36700949488, 42221.366679499835, 42327.36508952368, 42398.36402453966, 7741.3419859312025, 9279.72160835176, 9329.30030247734, 9360.672376466826, 10502.159827213844, 8705.564721763918, 10864.565417383335, 10887.0, 10890.455477226147, 10896.019358257756, 10861.945690271537, 10881.292715973475, 10915.781684366308, 10927.836082458769, 10931.18016148788, 9110.225630821387, 10825.188110891675, 10908.400037997906, 10913.290636108628, 10926.890731092746, 10693.732656683567, 10779.35323880562, 10786.676399708022, 10797.892021079768, 10858.294210876267, 35772.0, 36288.9113326598, 36306.27387452236, 36366.0, 36398.27203455916, 3755.3615885299564, 3814.6376094271, 3816.3512202925567, 3823.0824602095577, 3925.332693442093, 27918.0, 28175.85912070447, 28242.0, 28322.44226567538, 28403.289917752016, 3030.499967505359, 3060.0972713049705, 3185.0922487091298, 3230.7415406767636, 3232.4504834178247, 474637.0, 478845.0, 478849.0, 479483.0, 480177.0, 36240.81879590585, 45646.543534564895, 54579.72710136503, 54633.72683136638, 55036.899262014536, 57184.71407642935, 57621.71189144111, 57931.71034144887, 61613.69193154005, 61886.381136188065, 14585.103936488189, 14657.56027319182, 14681.41274349014, 14814.185219813015, 14852.851471485363, 11038.006579407891, 11304.886951130384, 11319.151063670093, 11410.030147437395, 11461.71345716347, 17270.654586908193, 17401.91299043497, 17470.825291746918, 17500.82499174992, 17524.29902803899, 7741.7677469676, 7861.2531809478005, 7896.960515197387, 7931.920680793233, 7951.920480795118, 688.8036909480774, 825.2820046559532, 835.6197929941862, 839.1189251286125, 862.0646598440637, 23537.17619883296, 29580.852095739814, 29791.106266812032, 29949.25126871802, 30463.543046854316, 863.8272345530793, 978.4765150644371, 1011.2567263061791, 1037.6316407675317, 1191.8808119188186, 14041.087329323562, 14801.0, 15063.472778452702, 15326.310316035806, 15573.532794015971, 203886.9805650992, 203931.0, 204307.0, 204429.0, 204695.0, 58854.528636784424, 59456.81086378335, 59646.70176649089, 59704.0, 59951.0, 1313.8226339444052, 1358.6263777461277, 1368.6236285021698, 1370.3833275026277, 1371.5748118083427, 25136.49727005486, 28829.27926801847, 29008.984685535906, 29056.564151538165, 29804.70195298019, 48318.27522587094, 48784.51215487799, 48854.26718599295, 48884.51115488942, 48997.0, 500582.0, 503441.0, 504083.0, 505301.0, 505462.0, 24050.797460127273, 24243.878780605985, 24882.004719811004, 24976.875115624305, 26225.0, 36730.44904326384, 41618.37572436476, 41690.16619667529, 41758.0, 42826.57173428226, 27693.30766730848, 33885.0, 34410.0, 35307.82346088253, 35683.0, 34317.0, 34343.82828085944, 34442.48336274908, 35308.11729706778, 35464.113397165274, 34382.31235375329, 34793.9561813146, 35091.0, 35143.297134057684, 35155.0, 28747.0, 30123.849380752956, 31082.689173108884, 31403.05790826279, 31420.371592568477, 21728.456788580414, 22815.745134017787, 23105.613663180262, 23673.0, 23717.05131794778], "value": 18546.89653153618, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [370.7793862651722, 379.70383101181056, 385.452657226738, 398.82252397683044, 412.18387592566717, 18393.816061839378, 19831.60336793267, 20026.59946801063, 20209.39371818846, 20268.29060982866, 20051.59896802063, 20409.69385459219, 20463.897680511578, 20766.792332076675, 20920.686189707165, 4505.571970662798, 5047.899042019158, 5418.566514678826, 5419.458054194569, 5687.60186786923, 5538.972305138469, 5586.664800111986, 5629.859253518674, 5632.5212356949705, 5637.8590535236535, 5353.866153346178, 5499.120140777476, 5524.751386187631, 5562.0, 5604.8038318658855, 5039.143345631252, 5057.9494205057845, 5063.392392912837, 5074.746262686888, 5093.439721630618, 4412.189845782712, 4539.84110556129, 4878.341423907763, 4926.285688575136, 4968.205087186069, 22387.992540335723, 23746.7625323748, 23967.760322396727, 24365.512689746283, 24735.505289894103, 2459.5203935232635, 2529.9241022769347, 2539.0478570536047, 2561.1548189097552, 2567.14000809729, 9981.351212171186, 10305.229071719861, 10331.121854642364, 10423.426711530863, 15171.544853654408, 1537.2160198298836, 1736.2273788164273, 1778.2175842629201, 1801.2539997408226, 1815.0471002723543, 101068.0, 123730.76269237282, 130586.0, 138405.38770367627, 145721.99999999895, 9151.67969121091, 11603.825942610867, 11655.30068195894, 11703.063754899758, 11710.824337634942, 14842.480513181987, 14893.70212595742, 14924.403023878924, 14925.940182262828, 14930.0, 8116.472429292077, 8396.790080248044, 8446.788830279169, 8521.872171917426, 8550.315974722007, 8080.232377924088, 8086.272235498715, 8094.109647938722, 8214.753557393287, 8236.876446853303, 4642.930356044662, 4709.921997499208, 4726.125666751662, 4777.378940737682, 4789.616830653539, 1974.3682021753189, 2007.4178488238247, 2008.7991200879794, 2015.6271089848426, 2039.12317703386, 997.9501024948723, 1002.3785253142952, 1005.3364779245662, 1013.7668336282696, 1054.8681414823147, 20107.0, 20326.79673203249, 20840.06219720116, 21015.474613134793, 21026.158953641683, 795.9880601790978, 856.9785755356166, 858.8571514072305, 870.2515836380708, 1058.3332500524775, 5409.7295135243085, 6311.495080393557, 6328.398802113792, 6502.772402965874, 6515.641639709862, 613713.0, 692320.5383973048, 706173.0, 708891.0, 712498.0, 110440.0, 129884.0, 153477.0, 159729.0, 160222.0, 1021.6832781837654, 1039.5374058544026, 1064.2497039587072, 1071.212658695858, 1075.2365820267541, 13705.725885482434, 15491.83811214186, 17011.829881701025, 19767.604647906675, 19880.111389417983, 59883.700581496814, 60270.698646506484, 60358.698206508685, 60496.0, 60926.39073609207, 77346.6132669333, 78746.6062669683, 79494.0, 81526.0, 88563.67154492559, 5759.769609215752, 5931.347551769216, 7841.666916624302, 8285.337173026022, 8417.83164336722, 11169.218154729091, 12670.176438531465, 12749.49002039908, 13043.347832608142, 13162.736745265232, 10896.526315265242, 11077.058450031514, 11078.224524283545, 11311.038561722016, 11382.94308528452, 9351.924528679288, 9364.484953327632, 9924.603015879284, 10838.0, 11212.55149793999, 10777.838332425177, 10844.69863616371, 11060.944695276794, 11431.428428578703, 11581.957623813898, 8082.393820463371, 8119.715809946623, 8126.512409255463, 8451.957740211259, 8603.182697643713, 8166.183381661786, 10558.208134389797, 10922.235443518877, 11234.0, 11303.095752339957], "value": 9379.559892584995, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1193.6120760752756, 1378.2764048874346, 1383.6232948216525, 1385.445821671332, 1392.0812263905825, 6218.098375735527, 8086.272235498811, 8335.708250211244, 8397.1602839716, 8540.871886921701, 7442.367398771097, 8387.748367548968, 8478.957605211981, 8510.234078932895, 8525.445846020004, 2215.8338124640636, 2235.2400183937457, 2238.21662418154, 2242.8990695418665, 2248.1344682297354, 2262.400463877074, 2293.220305096267, 2295.0934380919525, 2299.999999999992, 2311.7341505726804, 2247.314569056437, 2248.853824501402, 2265.4562904902787, 2273.783990520898, 2286.8970896309706, 2183.257692384581, 2248.392933907844, 2271.6478945763515, 2273.4089136824377, 2273.897674604647, 1890.5084677983687, 1950.5123719070232, 2041.4896275931017, 2145.0240140735928, 2283.2693538067824, 8217.137200593912, 8447.619857106383, 8449.36629752771, 8531.488110713377, 8540.231379175842, 5026.195808670632, 5181.766820493087, 5193.68837869729, 5193.948060519384, 5198.818041368571, 3410.300888317889, 3453.6546345365514, 3480.060383696388, 3490.1972546314237, 3528.0826984983833, 1197.574860924368, 1200.6878211664944, 1230.163488827597, 1254.4731212890517, 1256.4094875408543, 9295.349325547215, 10100.343477674001, 10877.945610271898, 11024.173187010925, 11031.889681103166, 4193.643540299047, 4403.889902752457, 4491.348754430589, 4516.277395616686, 4528.43394575678, 8666.306695464475, 8701.608427620646, 8725.650973961097, 8727.432716873383, 8743.081976392514, 4639.466461356919, 4760.928586071212, 5081.491850814963, 5090.338256026693, 5100.311457953128, 4568.697921092473, 4608.470025947059, 4724.527547245317, 4757.714537127784, 4763.04739052191, 3190.601174853143, 3611.8622633870245, 3643.7267204959735, 3712.535933008374, 3716.869909553153, 1157.5948418053613, 1163.6683545189749, 1165.720227145496, 1166.650004998497, 1167.0371943146938, 850.2475309351269, 867.3841572483482, 878.3587980774071, 907.9001309855911, 923.2613908872875, 4094.5023535643236, 5722.971385143048, 5741.282339707536, 5769.740361683735, 5955.196048533479, 716.7634680555509, 800.8438354520929, 920.6317473010849, 953.699584630839, 1000.2348203624088, 3657.451382292624, 3675.3751862183494, 3690.4833323334756, 3737.121776382548, 3808.6953043756434, 9760.658376956773, 10028.799424011479, 10038.297319187586, 10046.497675116216, 10050.899491005142, 9982.850257246148, 9997.750056248651, 10008.49957502122, 10011.849822252672, 10013.79972400548, 6499.675016249169, 7450.292222238879, 7705.57619330931, 7867.173946735625, 7868.0, 5498.945010549844, 5676.9432305678065, 5678.943210568007, 5745.2818397700285, 5792.304923409218, 8780.0, 8953.731388058368, 9025.593848276712, 9084.046175151514, 9100.544972751468, 8874.511901845202, 8976.147266009719, 9239.214666753402, 9293.953530232306, 9436.103570160823, 6922.307769223037, 7219.566825990458, 7240.0, 7246.0, 7261.745838895613, 7801.726939557088, 7814.960925195338, 7821.804454888674, 7856.607169641609, 7940.523568585903, 6105.511559075175, 7173.641317933978, 7194.856102877809, 7209.315115064061, 7307.159676637255, 6823.351781580742, 6927.016454886544, 7028.0863487746265, 7076.150861896605, 7154.0, 4914.950850491449, 5189.584833213409, 5302.867428314323, 6379.425851673372, 6409.4231519163495, 4894.0, 4917.754112294299, 4970.3290055842, 5025.447200807835, 5148.897022059463, 4849.418069831643, 4944.554990050912, 4982.252662100714, 5008.298838162733, 5012.020252000512], "value": 4315.77881587258, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [351.2535861294747, 358.9712822974162, 362.8457905390209, 366.9651383118603, 368.7437231124368, 4713.222318317482, 4970.744162573511, 5315.415304316522, 5368.570514358852, 5422.9328003997, 5143.562797162236, 5251.5798736101115, 5278.630495865291, 5279.788808447667, 5334.626576139672, 3543.5393398858178, 3546.521219635355, 3797.9430308545393, 4092.6521245694003, 4109.773962432065, 3239.8012735287975, 3527.3121741260466, 3752.1932784451337, 3900.902477438058, 4114.321137012389, 3502.176988407722, 3961.445397644332, 3972.9801350993203, 4001.2197621463824, 4025.3559430491127, 3186.474231751758, 3257.5144595282936, 3466.480027995796, 3615.186583018813, 3630.618785027587, 2969.5100308449296, 3027.3652227796965, 3470.2538954124734, 3479.80861052642, 3480.756347055707, 4531.731115287725, 4917.655764096479, 4974.37820272466, 5074.898502029939, 5222.791088356461, 4573.827431969821, 4589.839355622537, 4603.158736505391, 4620.676552641316, 4638.8608341749805, 4954.455009948902, 5455.8542706031785, 5668.518175955049, 5699.31870098322, 5733.168690539848, 2926.4732348177317, 3151.495760678303, 3227.4836026235926, 3514.9824250878582, 3852.84588616455, 4756.857294281177, 5376.924615077, 5529.972350138264, 5531.363893152299, 5828.504577110951, 3117.0, 3592.9820350898076, 3852.711046671455, 3857.672097871712, 3868.4390763339156, 3898.4913577780226, 3929.9957021918453, 3939.783311917872, 3957.6438120569287, 3968.1865217630316, 3414.1805966567845, 4484.461864576207, 4606.838760643428, 4640.628749700016, 4669.626429885601, 4223.092035212444, 4636.374089497875, 5617.9719101404235, 5664.0, 5699.373068962411, 1192.6958625550428, 1223.9265644061384, 1292.2569522524586, 1294.5080869269618, 1305.0733978874916, 754.3776384482824, 855.2388374346837, 867.3884911137633, 873.3449912565576, 879.3800370738614, 4344.413504176896, 4656.697314674535, 4866.683665561727, 4994.400671919393, 5014.668179296572, 4692.6245900327895, 5118.718470484085, 5557.305336832896, 5631.0, 5655.066913959151, 4187.392828039917, 4694.6009589185305, 4845.709257444494, 4973.104841128559, 5001.974200448824, 2510.134003768697, 2796.6783819860975, 2934.7380626330882, 2937.4578346368326, 2945.7937944344117, 5346.358436987509, 5621.606487545914, 5745.827625171251, 5746.9425305747245, 5766.308043034779, 4928.866360737051, 5287.867803304948, 5562.749676264497, 5742.569307301969, 6044.0, 5089.0, 5839.678817664984, 5987.6108052976415, 5993.550483713651, 6025.6083354581815, 4559.179347717442, 4806.543378379049, 5751.769929202785, 5808.273965754281, 5849.532037436909, 4786.213786213786, 5409.269748584048, 5779.2775903012125, 5827.8344331134795, 5833.591648584558, 5002.949970500248, 5713.771449142154, 5858.94141058584, 6035.714642603801, 6049.001914684204, 3627.673509384168, 3808.067023579241, 3919.92160156801, 3951.8221680025044, 3965.2862484753023, 5055.545000949932, 5098.311727916832, 5335.66608347913, 5687.20379146928, 5894.37904576245, 5142.742862856917, 5230.660007099526, 5468.972655136698, 5666.829995100154, 5708.286464191976, 4814.350062741485, 5345.545628621455, 5608.4111168326735, 5723.456271654186, 5727.255456790757, 4341.869743907688, 5562.888742225214, 5576.832695019156, 5601.019821531134, 5657.9151312731165, 4592.0, 4966.776495057658, 5559.861003474945, 5600.243967064394, 5636.689982050862, 5092.930484598128, 5324.520793128482, 5528.502434780889, 5589.227271591741, 5611.80358687444], "value": 3999.914076245898, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [676.1446769836155, 694.8506071194693, 753.6570860258582, 794.9761507154785, 810.1291112054543, 14271.785923211148, 14850.257487125627, 15013.249337533121, 15083.924580377097, 15120.865935054888, 14750.26248687564, 15480.225988700548, 15488.69022619547, 15746.0, 15805.367785288574, 853.1340689200466, 876.6537217798966, 878.1174919206189, 879.5690111845214, 881.2421317666801, 839.7270886961732, 868.6829307302852, 869.4956924983494, 876.1545108969843, 885.1767855894016, 908.609298001859, 912.9863052054225, 925.8703781470599, 926.0831776541243, 927.0081013315755, 784.2980532423506, 854.0008190417227, 903.3450748207563, 920.6961702638114, 921.6958403726754, 675.7026908160394, 685.2017399729309, 686.0566720758957, 686.8729285082276, 687.0038444255799, 14777.852221477862, 15043.473478428203, 15045.247737613076, 15058.698826023417, 15180.0, 13477.730445391135, 13694.315284235849, 13754.587362379145, 13769.35112195269, 13775.38010789507, 10536.0, 11118.777624447546, 11393.487293071748, 11709.711931687507, 11979.580714675032, 3601.5318008658705, 3625.673689367943, 3629.94555081674, 3633.9273214535824, 3659.79871107089, 91884.54057729669, 92220.0, 92733.0, 92957.0, 95837.5208123962, 10481.790364192826, 10487.29601014934, 10492.895071049192, 10611.0, 10976.890231097746, 20595.8970205148, 20992.79007209939, 21005.36983890486, 21036.0, 21330.466738331357, 6856.417204537571, 7029.859402811915, 7411.407087432993, 7443.404527637776, 7610.124835643972, 4050.1089760253285, 4053.9594604054173, 4100.7334523255895, 4103.835846566104, 4108.650764684976, 3447.413939630269, 3541.0970202598173, 3750.7562008469363, 3872.670822980022, 3915.804209789556, 702.0592406175749, 713.6610110197614, 728.9307515785991, 729.4237552333703, 733.5195446982235, 631.9414979908635, 637.8724255148989, 654.6432194453939, 671.3466482104053, 684.4592771710293, 8504.44721093127, 9508.809823803485, 9745.658901938397, 9922.355046921926, 10298.433586152834, 583.3379114704841, 641.6951947824839, 683.4429939599211, 692.4183685704032, 692.8545005548891, 4578.610818080434, 4886.395762346204, 4891.070181607471, 4922.384701912261, 4935.629827762932, 95111.0, 97729.5113524442, 98499.0, 99565.0, 99679.50160249298, 77228.61385693148, 83592.0, 83615.0, 84516.0, 86254.0, 44643.0, 55069.173962390596, 63912.402189944696, 68018.95943121714, 75014.74955751337, 7641.235876412315, 8963.238124759468, 9406.858897116686, 9412.776339075876, 9462.57418416159, 44465.11069778651, 48019.0, 48593.0, 49060.509394905595, 49176.2623560654, 45264.54735452735, 49315.753421232665, 55212.343629691175, 59360.0, 60942.0, 11306.660800176009, 11409.657710268706, 11425.257358271685, 11445.313281203154, 11796.646100616996, 12235.69410764738, 12393.504259829506, 12616.87383126157, 12693.111482196158, 12903.935480322538, 10366.0, 10979.39613321275, 11589.478473468545, 11611.477483513094, 11802.40613194831, 11369.602063927723, 11466.082713382742, 11683.532658693557, 11686.415679215836, 11942.522299107939, 11373.943130284295, 11476.942615286871, 11630.6510804676, 11648.301101933912, 11987.981021613261, 8730.56347182651, 9409.435433873989, 9546.085666937182, 9657.613695452103, 9786.1192492676, 7435.925640743523, 7778.727744529141, 9299.070092990647, 9323.72028839136, 9355.85966210521], "value": 7090.4720505070845, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1602.1999999999998, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [103.0, 119.0, 102.0, 173.0, 176.0, 124.0, 136.0, 119.0, 117.0, 119.0], "value": 128.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 122.0, 144.0, 142.0, 146.0, 152.0, 152.0, 123.0, 137.0, 120.0], "value": 138.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [199.0, 208.0, 211.0, 229.0, 197.0, 199.0, 193.0, 201.0, 195.0, 201.0], "value": 203.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 62.0, 59.0, 86.0, 63.0, 62.0, 60.0, 63.0, 99.0, 91.0], "value": 72.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 71.0, 83.0, 73.0, 73.0, 71.0, 103.0, 108.0, 100.0, 114.0], "value": 88.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 92.0, 90.0, 89.0, 104.0, 104.0, 90.0, 89.0, 95.0, 88.0], "value": 93.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 97.0, 98.0, 96.0, 97.0, 96.0, 97.0, 97.0, 92.0, 158.0], "value": 102.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 144.0, 147.0, 93.0, 92.0, 94.0, 96.0, 93.0, 93.0, 148.0], "value": 108.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 66.0, 64.0, 64.0, 66.0, 66.0, 59.0, 80.0, 65.0, 64.0], "value": 65.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 87.0, 103.0, 92.0, 75.0, 71.0, 72.0, 73.0, 72.0, 74.0], "value": 78.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 97.0, 118.0, 112.0, 81.0, 108.0, 97.0, 82.0, 80.0, 119.0], "value": 97.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [166.0, 151.0, 150.0, 137.0, 162.0, 155.0, 177.0, 152.0, 193.0, 149.0], "value": 159.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [178.0, 178.0, 180.0, 171.0, 169.0, 172.0, 228.0, 240.0, 184.0, 188.0], "value": 188.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 70.0, 69.0, 68.0, 70.0, 72.0, 81.0, 92.0, 88.0, 86.0], "value": 76.3, "unit": "score"}]}]} 04:10:54 INFO - Return code: 0 04:10:55 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 04:10:55 ERROR - # TBPL SUCCESS # 04:10:55 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 04:10:55 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 04:10:55 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 04:10:55 INFO - 2017-02-09 04:10:55.028 screenresolution[1655:10642] starting screenresolution argv=screenresolution get 04:10:55 INFO - 2017-02-09 04:10:55.031 screenresolution[1655:10642] Display 0: 1600x1200x32@60 04:10:55 INFO - 2017-02-09 04:10:55.037 screenresolution[1656:10645] starting screenresolution argv=screenresolution list 04:10:55 INFO - Available Modes on Display 0 04:10:55 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 04:10:55 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 04:10:55 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 04:10:55 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 04:10:55 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 04:10:55 INFO - Intel Iris: 04:10:55 INFO - Chipset Model: Intel Iris 04:10:55 INFO - Type: GPU 04:10:55 INFO - Bus: Built-In 04:10:55 INFO - VRAM (Dynamic, Max): 1536 MB 04:10:55 INFO - Vendor: Intel (0x8086) 04:10:55 INFO - Device ID: 0x0a2e 04:10:55 INFO - Revision ID: 0x0009 04:10:55 INFO - Displays: 04:10:55 INFO - Display: 04:10:55 INFO - Resolution: 1600 x 1200 @ 60 Hz 04:10:55 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 04:10:55 INFO - Display Serial Number: 1600x1200 60 04:10:55 INFO - Main Display: Yes 04:10:55 INFO - Mirror: Off 04:10:55 INFO - Online: Yes 04:10:55 INFO - Rotation: Supported 04:10:55 INFO - Return code: 0 04:10:55 INFO - Running post-action listener: _resource_record_post_action 04:10:55 INFO - [mozharness: 2017-02-09 12:10:55.278804Z] Finished run-tests step (success) 04:10:55 INFO - Running post-run listener: _resource_record_post_run 04:10:55 INFO - Total resource usage - Wall time: 1200s; CPU: 54.0%; Read bytes: 40086016; Write bytes: 558821888; Read time: 390; Write time: 1736 04:10:55 INFO - TinderboxPrint: CPU usage
54.3% 04:10:55 INFO - TinderboxPrint: I/O read bytes / time
40,086,016 / 390 04:10:55 INFO - TinderboxPrint: I/O write bytes / time
558,821,888 / 1,736 04:10:55 INFO - TinderboxPrint: CPU idle
2,192.3 (45.7%) 04:10:55 INFO - TinderboxPrint: CPU system
967.2 (20.1%) 04:10:55 INFO - TinderboxPrint: CPU user
1,642.2 (34.2%) 04:10:55 INFO - TinderboxPrint: Swap in / out
292,511,744 / 0 04:10:55 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 250804736; Write bytes: 246859776; Read time: 18156; Write time: 520 04:10:55 INFO - run-tests - Wall time: 1179s; CPU: 54.0%; Read bytes: 35559936; Write bytes: 300530176; Read time: 356; Write time: 1178 04:10:55 INFO - Running post-run listener: _upload_blobber_files 04:10:55 INFO - Blob upload gear active. 04:10:55 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 04:10:55 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 04:10:55 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'] 04:10:55 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 04:10:56 INFO - (blobuploader) - INFO - Open directory for files ... 04:10:56 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 04:10:56 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:10:56 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:10:57 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 04:10:57 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:10:57 INFO - (blobuploader) - INFO - Done attempting. 04:10:57 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 04:10:57 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:10:57 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:10:58 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 04:10:58 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:10:58 INFO - (blobuploader) - INFO - Done attempting. 04:10:58 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 04:10:58 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:10:58 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:10:59 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 04:10:59 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:10:59 INFO - (blobuploader) - INFO - Done attempting. 04:10:59 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 04:10:59 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:10:59 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:11:01 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 04:11:01 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:11:01 INFO - (blobuploader) - INFO - Done attempting. 04:11:01 INFO - (blobuploader) - INFO - Iteration through files over. 04:11:01 INFO - Return code: 0 04:11:01 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 04:11:01 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 04:11:01 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b3d05e656ea5b132ddb33afb2a660bb3d1582e8da65499b315c7870371590f1e635241d2cdecb17e593ac5feb06fd8db2a64eac17968ae1320d921748be46f67", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ef04a241bcca8d803b2541662946aa2c77de64ed305c815d991d9fb3b3b4265b7b59f3019bc335f8f7a6be287a9a1ef7281960327e35c5550f22a17f9c328d18", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ff40a8dac759fa7f2f2e41f283c4acbddbefd28af89e69afaf24d3da0e5224b6ff545db537a75d97328144628493af3171f0a3e9e5cf1e2f1f1b093b754c8699", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/cede46adf630eacd23ae8d3c52138e093d7e480996ddb9fad90fba64ab52468df86a8c36ec8065e5a2d369fd08dcc57d13cef6026c60faa237b332fcfba30efd"} 04:11:01 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 04:11:01 INFO - Writing to file /builds/slave/test/properties/blobber_files 04:11:01 INFO - Contents: 04:11:01 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b3d05e656ea5b132ddb33afb2a660bb3d1582e8da65499b315c7870371590f1e635241d2cdecb17e593ac5feb06fd8db2a64eac17968ae1320d921748be46f67", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ef04a241bcca8d803b2541662946aa2c77de64ed305c815d991d9fb3b3b4265b7b59f3019bc335f8f7a6be287a9a1ef7281960327e35c5550f22a17f9c328d18", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ff40a8dac759fa7f2f2e41f283c4acbddbefd28af89e69afaf24d3da0e5224b6ff545db537a75d97328144628493af3171f0a3e9e5cf1e2f1f1b093b754c8699", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/cede46adf630eacd23ae8d3c52138e093d7e480996ddb9fad90fba64ab52468df86a8c36ec8065e5a2d369fd08dcc57d13cef6026c60faa237b332fcfba30efd"} 04:11:01 INFO - Running post-run listener: copy_logs_to_upload_dir 04:11:01 INFO - Copying logs to upload dir... 04:11:01 INFO - mkdir: /builds/slave/test/build/upload/logs 04:11:01 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1265.246045 ========= master_lag: 3.51 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 8 secs) (at 2017-02-09 04:11:05.045167) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 1 secs) (at 2017-02-09 04:11:05.051395) ========= 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.K6QiyQhPsn/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.pkvxAnA7w6/Listeners TMPDIR=/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/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/b3d05e656ea5b132ddb33afb2a660bb3d1582e8da65499b315c7870371590f1e635241d2cdecb17e593ac5feb06fd8db2a64eac17968ae1320d921748be46f67", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ef04a241bcca8d803b2541662946aa2c77de64ed305c815d991d9fb3b3b4265b7b59f3019bc335f8f7a6be287a9a1ef7281960327e35c5550f22a17f9c328d18", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ff40a8dac759fa7f2f2e41f283c4acbddbefd28af89e69afaf24d3da0e5224b6ff545db537a75d97328144628493af3171f0a3e9e5cf1e2f1f1b093b754c8699", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/cede46adf630eacd23ae8d3c52138e093d7e480996ddb9fad90fba64ab52468df86a8c36ec8065e5a2d369fd08dcc57d13cef6026c60faa237b332fcfba30efd"} build_url:https://queue.taskcluster.net/v1/task/OfSroXjURveNKqAuKyq7Hw/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011545 build_url: 'https://queue.taskcluster.net/v1/task/OfSroXjURveNKqAuKyq7Hw/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b3d05e656ea5b132ddb33afb2a660bb3d1582e8da65499b315c7870371590f1e635241d2cdecb17e593ac5feb06fd8db2a64eac17968ae1320d921748be46f67", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ef04a241bcca8d803b2541662946aa2c77de64ed305c815d991d9fb3b3b4265b7b59f3019bc335f8f7a6be287a9a1ef7281960327e35c5550f22a17f9c328d18", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ff40a8dac759fa7f2f2e41f283c4acbddbefd28af89e69afaf24d3da0e5224b6ff545db537a75d97328144628493af3171f0a3e9e5cf1e2f1f1b093b754c8699", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/cede46adf630eacd23ae8d3c52138e093d7e480996ddb9fad90fba64ab52468df86a8c36ec8065e5a2d369fd08dcc57d13cef6026c60faa237b332fcfba30efd"}' ========= master_lag: 1.09 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 1 secs) (at 2017-02-09 04:11:06.150895) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-09 04:11:06.151243) ========= 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.K6QiyQhPsn/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.pkvxAnA7w6/Listeners TMPDIR=/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/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.005117 ========= master_lag: 0.07 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-09 04:11:06.229395) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-09 04:11:06.229721) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-09 04:11:06.230193) ========= ========= Total master_lag: 5.05 =========