builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0039 starttime: 1488214559.38 results: success (0) buildid: 20170227072837 builduid: 0142dc885f2f4f1aa1a9df79482af5c5 revision: 106a96755d3bcebe64bbbc3b521d65d262ba9c02 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-02-27 08:55:59.382162) ========= master: http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-02-27 08:55:59.382606) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-27 08:55:59.382883) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-27 08:55:59.412126) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-27 08:55:59.412765) ========= 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.ttUlQVpanM/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.qwKIiY0j89/Listeners TMPDIR=/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/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-27 08:55:59-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 8.15M=0.001s 2017-02-27 08:55:59 (8.15 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.095512 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-27 08:55:59.533123) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-27 08:55:59.533425) ========= 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.ttUlQVpanM/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.qwKIiY0j89/Listeners TMPDIR=/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/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.026162 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-27 08:55:59.585546) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-02-27 08:55:59.585900) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 106a96755d3bcebe64bbbc3b521d65d262ba9c02 --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 106a96755d3bcebe64bbbc3b521d65d262ba9c02 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.ttUlQVpanM/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.qwKIiY0j89/Listeners TMPDIR=/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/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-27 08:55:59,656 truncating revision to first 12 chars 2017-02-27 08:55:59,656 Setting DEBUG logging. 2017-02-27 08:55:59,656 attempt 1/10 2017-02-27 08:55:59,656 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/106a96755d3b?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-02-27 08:56:01,246 unpacking tar archive at: mozilla-central-106a96755d3b/testing/mozharness/ program finished with exit code 0 elapsedTime=1.861066 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-02-27 08:56:01.475957) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-27 08:56:01.476621) ========= script_repo_revision: 106a96755d3bcebe64bbbc3b521d65d262ba9c02 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-27 08:56:01.477334) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-27 08:56:01.477892) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-27 08:56:01.499984) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 26 secs) (at 2017-02-27 08:56:01.500682) ========= /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.ttUlQVpanM/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.qwKIiY0j89/Listeners TMPDIR=/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/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 08:56:01 INFO - MultiFileLogger online at 20170227 08:56:01 in /builds/slave/test 08:56:01 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 08:56:01 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 08:56:01 INFO - {'append_to_log': False, 08:56:01 INFO - 'base_work_dir': '/builds/slave/test', 08:56:01 INFO - 'blob_upload_branch': 'Firefox', 08:56:01 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 08:56:01 INFO - 'branch': 'Firefox', 08:56:01 INFO - 'buildbot_json_path': 'buildprops.json', 08:56:01 INFO - 'config_files': ('talos/mac_config.py',), 08:56:01 INFO - 'default_actions': ('clobber', 08:56:01 INFO - 'read-buildbot-config', 08:56:01 INFO - 'download-and-extract', 08:56:01 INFO - 'populate-webroot', 08:56:01 INFO - 'create-virtualenv', 08:56:01 INFO - 'install', 08:56:01 INFO - 'run-tests'), 08:56:01 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 08:56:01 INFO - 'download_minidump_stackwalk': True, 08:56:01 INFO - 'download_symbols': 'ondemand', 08:56:01 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 08:56:01 INFO - 'tooltool.py': '/tools/tooltool.py', 08:56:01 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 08:56:01 INFO - '/tools/misc-python/virtualenv.py')}, 08:56:01 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 08:56:01 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 08:56:01 INFO - 'gecko_profile': False, 08:56:01 INFO - 'gecko_profile_interval': 0, 08:56:01 INFO - 'installer_path': 'installer.exe', 08:56:01 INFO - 'log_level': 'info', 08:56:01 INFO - 'log_name': 'talos', 08:56:01 INFO - 'log_to_console': True, 08:56:01 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 08:56:01 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 08:56:01 INFO - 'opt_config_files': (), 08:56:01 INFO - 'pip_index': False, 08:56:01 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:56:01 INFO - 'cmd': ('bash', 08:56:01 INFO - '-c', 08:56:01 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:56:01 INFO - 'enabled': True, 08:56:01 INFO - 'halt_on_failure': False, 08:56:01 INFO - 'name': 'check_screen_resolution'},), 08:56:01 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:56:01 INFO - 'cmd': ('bash', 08:56:01 INFO - '-c', 08:56:01 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:56:01 INFO - 'enabled': True, 08:56:01 INFO - 'halt_on_failure': False, 08:56:01 INFO - 'name': 'check_screen_resolution'},), 08:56:01 INFO - 'run_cmd_checks_enabled': True, 08:56:01 INFO - 'suite': 'dromaeojs-e10s', 08:56:01 INFO - 'system_bits': '32', 08:56:01 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 08:56:01 INFO - 'title': 't-yosemite-r7-0039', 08:56:01 INFO - 'tooltool_cache': '/builds/tooltool_cache', 08:56:01 INFO - 'use_talos_json': True, 08:56:01 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 08:56:01 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 08:56:01 INFO - 'work_dir': 'build'} 08:56:01 INFO - [mozharness: 2017-02-27 16:56:01.682460Z] Running clobber step. 08:56:01 INFO - Running pre-action listener: _resource_record_pre_action 08:56:01 INFO - Running main action method: clobber 08:56:01 INFO - rmtree: /builds/slave/test/build 08:56:01 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 08:56:04 INFO - Running post-action listener: _resource_record_post_action 08:56:04 INFO - [mozharness: 2017-02-27 16:56:04.171989Z] Finished clobber step (success) 08:56:04 INFO - [mozharness: 2017-02-27 16:56:04.172120Z] Running read-buildbot-config step. 08:56:04 INFO - Running pre-action listener: _resource_record_pre_action 08:56:04 INFO - Running main action method: read_buildbot_config 08:56:04 INFO - Using buildbot properties: 08:56:04 INFO - { 08:56:04 INFO - "project": "", 08:56:04 INFO - "product": "firefox", 08:56:04 INFO - "script_repo_revision": "production", 08:56:04 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 08:56:04 INFO - "repository": "", 08:56:04 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 08:56:04 INFO - "buildid": "20170227072837", 08:56:04 INFO - "pgo_build": "False", 08:56:04 INFO - "basedir": "/builds/slave/test", 08:56:04 INFO - "buildnumber": 68, 08:56:04 INFO - "slavename": "t-yosemite-r7-0039", 08:56:04 INFO - "master": "http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/", 08:56:04 INFO - "platform": "yosemite_r7", 08:56:04 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 08:56:04 INFO - "branch": "mozilla-central", 08:56:04 INFO - "repo_path": "mozilla-central", 08:56:04 INFO - "revision": "106a96755d3bcebe64bbbc3b521d65d262ba9c02", 08:56:04 INFO - "stage_platform": "macosx64", 08:56:04 INFO - "builduid": "0142dc885f2f4f1aa1a9df79482af5c5", 08:56:04 INFO - "slavebuilddir": "test" 08:56:04 INFO - } 08:56:04 INFO - Found installer url https://queue.taskcluster.net/v1/task/DW0cpsYTQ1i0sUdq36hu4g/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg. 08:56:04 INFO - Running post-action listener: _resource_record_post_action 08:56:04 INFO - [mozharness: 2017-02-27 16:56:04.175576Z] Finished read-buildbot-config step (success) 08:56:04 INFO - [mozharness: 2017-02-27 16:56:04.175720Z] Running download-and-extract step. 08:56:04 INFO - Running pre-action listener: _resource_record_pre_action 08:56:04 INFO - Running main action method: download_and_extract 08:56:04 INFO - mkdir: /builds/slave/test/build/tests 08:56:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:56:04 INFO - trying https://queue.taskcluster.net/v1/task/DW0cpsYTQ1i0sUdq36hu4g/artifacts/public/build/firefox-54.0a1.en-US.mac.test_packages.json 08:56:04 INFO - Downloading https://queue.taskcluster.net/v1/task/DW0cpsYTQ1i0sUdq36hu4g/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 08:56:04 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/DW0cpsYTQ1i0sUdq36hu4g/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 08:56:05 INFO - Downloaded 1199 bytes. 08:56:05 INFO - Reading from file /builds/slave/test/build/firefox-54.0a1.en-US.mac.test_packages.json 08:56:05 INFO - Using the following test package requirements: 08:56:05 INFO - {u'common': [u'firefox-54.0a1.en-US.mac.common.tests.zip'], 08:56:05 INFO - u'cppunittest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 08:56:05 INFO - u'firefox-54.0a1.en-US.mac.cppunittest.tests.zip'], 08:56:05 INFO - u'gtest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 08:56:05 INFO - u'firefox-54.0a1.en-US.mac.gtest.tests.zip'], 08:56:05 INFO - u'jittest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 08:56:05 INFO - u'jsshell-mac.zip'], 08:56:05 INFO - u'mochitest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 08:56:05 INFO - u'firefox-54.0a1.en-US.mac.mochitest.tests.zip'], 08:56:05 INFO - u'mozbase': [u'firefox-54.0a1.en-US.mac.common.tests.zip'], 08:56:05 INFO - u'reftest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 08:56:05 INFO - u'firefox-54.0a1.en-US.mac.reftest.tests.zip'], 08:56:05 INFO - u'talos': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 08:56:05 INFO - u'firefox-54.0a1.en-US.mac.talos.tests.zip'], 08:56:05 INFO - u'web-platform': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 08:56:05 INFO - u'firefox-54.0a1.en-US.mac.web-platform.tests.zip'], 08:56:05 INFO - u'xpcshell': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 08:56:05 INFO - u'firefox-54.0a1.en-US.mac.xpcshell.tests.zip']} 08:56:05 INFO - Downloading packages: [u'firefox-54.0a1.en-US.mac.common.tests.zip'] for test suite category: common 08:56:05 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/DW0cpsYTQ1i0sUdq36hu4g/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip 08:56:05 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/DW0cpsYTQ1i0sUdq36hu4g/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip'}, attempt #1 08:56:05 INFO - Fetch https://queue.taskcluster.net/v1/task/DW0cpsYTQ1i0sUdq36hu4g/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip into memory 08:56:06 INFO - Expected file size: 51745131 08:56:06 INFO - Obtained file size: 51745131 08:56:22 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 08:56:22 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/DW0cpsYTQ1i0sUdq36hu4g/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip 08:56:22 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/DW0cpsYTQ1i0sUdq36hu4g/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip'}, attempt #1 08:56:22 INFO - Fetch https://queue.taskcluster.net/v1/task/DW0cpsYTQ1i0sUdq36hu4g/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip into memory 08:56:24 INFO - Expected file size: 51745131 08:56:24 INFO - Obtained file size: 51745131 08:56:39 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/DW0cpsYTQ1i0sUdq36hu4g/artifacts/public/build/firefox-54.0a1.en-US.mac.talos.tests.zip 08:56:39 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/DW0cpsYTQ1i0sUdq36hu4g/artifacts/public/build/firefox-54.0a1.en-US.mac.talos.tests.zip'}, attempt #1 08:56:39 INFO - Fetch https://queue.taskcluster.net/v1/task/DW0cpsYTQ1i0sUdq36hu4g/artifacts/public/build/firefox-54.0a1.en-US.mac.talos.tests.zip into memory 08:56:41 INFO - Expected file size: 14404421 08:56:41 INFO - Obtained file size: 14404421 08:56:41 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')]} 08:56:41 INFO - trying https://queue.taskcluster.net/v1/task/DW0cpsYTQ1i0sUdq36hu4g/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg 08:56:41 INFO - Downloading https://queue.taskcluster.net/v1/task/DW0cpsYTQ1i0sUdq36hu4g/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-54.0a1.en-US.mac.dmg 08:56:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/DW0cpsYTQ1i0sUdq36hu4g/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 08:56:43 INFO - Downloaded 74961915 bytes. 08:56:43 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/DW0cpsYTQ1i0sUdq36hu4g/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg 08:56:43 INFO - mkdir: /builds/slave/test/properties 08:56:43 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 08:56:43 INFO - Writing to file /builds/slave/test/properties/build_url 08:56:43 INFO - Contents: 08:56:43 INFO - build_url:https://queue.taskcluster.net/v1/task/DW0cpsYTQ1i0sUdq36hu4g/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg 08:56:45 INFO - Running post-action listener: _resource_record_post_action 08:56:45 INFO - Running post-action listener: set_extra_try_arguments 08:56:45 INFO - [mozharness: 2017-02-27 16:56:45.008244Z] Finished download-and-extract step (success) 08:56:45 INFO - [mozharness: 2017-02-27 16:56:45.008383Z] Running populate-webroot step. 08:56:45 INFO - Running pre-action listener: _resource_record_pre_action 08:56:45 INFO - Running main action method: populate_webroot 08:56:45 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 08:56:45 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 08:56:45 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 08:56:45 INFO - '--tppagecycles', 08:56:45 INFO - '7'], 08:56:45 INFO - 'tests': ['tsvgm']}}, 08:56:45 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 08:56:45 INFO - 'tests': ['tresize', 'tcanvasmark']}, 08:56:45 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 08:56:45 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 08:56:45 INFO - 'tests': ['dromaeo_css', 'kraken']}, 08:56:45 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 08:56:45 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 08:56:45 INFO - 'talos_options': ['--disable-e10s'], 08:56:45 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:56:45 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 08:56:45 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:56:45 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 08:56:45 INFO - 'talos_options': ['--disable-e10s'], 08:56:45 INFO - 'tests': ['damp', 'tps']}, 08:56:45 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 08:56:45 INFO - 'tests': ['damp', 'tps']}, 08:56:45 INFO - 'g3': {'talos_options': ['--disable-e10s'], 08:56:45 INFO - 'tests': ['dromaeo_dom']}, 08:56:45 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 08:56:45 INFO - 'g4': {'talos_options': ['--disable-e10s'], 08:56:45 INFO - 'tests': ['basic_compositor_video']}, 08:56:45 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 08:56:45 INFO - 'other': {'talos_options': ['--disable-e10s'], 08:56:45 INFO - 'tests': ['a11yr', 08:56:45 INFO - 'ts_paint', 08:56:45 INFO - 'tpaint', 08:56:45 INFO - 'sessionrestore', 08:56:45 INFO - 'sessionrestore_no_auto_restore', 08:56:45 INFO - 'tabpaint']}, 08:56:45 INFO - 'other-e10s': {'tests': ['a11yr', 08:56:45 INFO - 'ts_paint', 08:56:45 INFO - 'tpaint', 08:56:45 INFO - 'sessionrestore', 08:56:45 INFO - 'sessionrestore_no_auto_restore', 08:56:45 INFO - 'tabpaint']}, 08:56:45 INFO - 'other-e10s_l64': {'tests': ['a11yr', 08:56:45 INFO - 'ts_paint', 08:56:45 INFO - 'tpaint', 08:56:45 INFO - 'sessionrestore', 08:56:45 INFO - 'sessionrestore_no_auto_restore', 08:56:45 INFO - 'tabpaint']}, 08:56:45 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 08:56:45 INFO - 'ts_paint', 08:56:45 INFO - 'tpaint', 08:56:45 INFO - 'sessionrestore', 08:56:45 INFO - 'sessionrestore_no_auto_restore', 08:56:45 INFO - 'tabpaint']}, 08:56:45 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 08:56:45 INFO - 'tests': ['a11yr', 08:56:45 INFO - 'ts_paint', 08:56:45 INFO - 'tpaint', 08:56:45 INFO - 'sessionrestore', 08:56:45 INFO - 'sessionrestore_no_auto_restore', 08:56:45 INFO - 'tabpaint']}, 08:56:45 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 08:56:45 INFO - 'tests': ['a11yr', 08:56:45 INFO - 'ts_paint', 08:56:45 INFO - 'tpaint', 08:56:45 INFO - 'sessionrestore', 08:56:45 INFO - 'sessionrestore_no_auto_restore', 08:56:45 INFO - 'tabpaint']}, 08:56:45 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 08:56:45 INFO - 'tests': ['tsvgx', 08:56:45 INFO - 'tsvgr_opacity', 08:56:45 INFO - 'tart', 08:56:45 INFO - 'tscrollx', 08:56:45 INFO - 'cart', 08:56:45 INFO - 'tsvg_static']}, 08:56:45 INFO - 'svgr-e10s': {'tests': ['tsvgx', 08:56:45 INFO - 'tsvgr_opacity', 08:56:45 INFO - 'tart', 08:56:45 INFO - 'tscrollx', 08:56:45 INFO - 'cart', 08:56:45 INFO - 'tsvg_static']}, 08:56:45 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 08:56:45 INFO - 'talos_options': ['--disable-e10s'], 08:56:45 INFO - 'tests': ['tp5o']}, 08:56:45 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 08:56:45 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 08:56:45 INFO - 'talos_options': ['--disable-e10s', 08:56:45 INFO - '--xperf_path', 08:56:45 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:56:45 INFO - 'tests': ['tp5n']}, 08:56:45 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 08:56:45 INFO - 'talos_options': ['--xperf_path', 08:56:45 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:56:45 INFO - 'tests': ['tp5n']}}, 08:56:45 INFO - 'talos.zip': {'path': '', 08:56:45 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 08:56:45 INFO - Running post-action listener: _resource_record_post_action 08:56:45 INFO - [mozharness: 2017-02-27 16:56:45.026603Z] Finished populate-webroot step (success) 08:56:45 INFO - [mozharness: 2017-02-27 16:56:45.026699Z] Running create-virtualenv step. 08:56:45 INFO - Running pre-action listener: _resource_record_pre_action 08:56:45 INFO - Running main action method: create_virtualenv 08:56:45 INFO - Creating virtualenv /builds/slave/test/build/venv 08:56:45 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 08:56:45 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 08:56:45 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 08:56:45 INFO - Using real prefix '/tools/python27' 08:56:45 INFO - New python executable in /builds/slave/test/build/venv/bin/python 08:56:45 INFO - Installing distribute.............................................................................................................................................................................................done. 08:56:48 INFO - Installing pip.................done. 08:56:48 INFO - Return code: 0 08:56:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 08:56:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 08:56:49 INFO - Reading from file tmpfile_stdout 08:56:49 INFO - Output received: 08:56:49 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) 08:56:49 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 08:56:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:56:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:56:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:56:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:56:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:56:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:56:49 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 0x10bef4b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bef7768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe061c8d5c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bcd2d30>, '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 0x10bbf9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bcc7be8>, '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.ttUlQVpanM/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.qwKIiY0j89/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/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 08:56:49 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 08:56:49 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 08:56:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ttUlQVpanM/Render', 08:56:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:56:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:56:49 INFO - 'HOME': '/Users/cltbld', 08:56:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:56:49 INFO - 'LOGNAME': 'cltbld', 08:56:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:56:49 INFO - 'MOZ_NO_REMOTE': '1', 08:56:49 INFO - 'NO_EM_RESTART': '1', 08:56:49 INFO - 'PAGER': '/bin/cat', 08:56:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:56:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:56:49 INFO - 'PWD': '/builds/slave/test', 08:56:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:56:49 INFO - 'SHELL': '/bin/bash', 08:56:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.qwKIiY0j89/Listeners', 08:56:49 INFO - 'TMPDIR': '/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/', 08:56:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:56:49 INFO - 'USER': 'cltbld', 08:56:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:56:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:56:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:56:49 INFO - 'XPC_FLAGS': '0x0', 08:56:49 INFO - 'XPC_SERVICE_NAME': '0', 08:56:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:56:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:56:49 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 08:56:49 INFO - Cleaning up... 08:56:49 INFO - Return code: 0 08:56:49 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 08:56:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:56:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:56:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:56:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:56:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:56:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:56:49 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 0x10bef4b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bef7768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe061c8d5c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bcd2d30>, '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 0x10bbf9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bcc7be8>, '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.ttUlQVpanM/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.qwKIiY0j89/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/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 08:56:49 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 08:56:49 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 08:56:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ttUlQVpanM/Render', 08:56:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:56:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:56:49 INFO - 'HOME': '/Users/cltbld', 08:56:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:56:49 INFO - 'LOGNAME': 'cltbld', 08:56:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:56:49 INFO - 'MOZ_NO_REMOTE': '1', 08:56:49 INFO - 'NO_EM_RESTART': '1', 08:56:49 INFO - 'PAGER': '/bin/cat', 08:56:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:56:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:56:49 INFO - 'PWD': '/builds/slave/test', 08:56:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:56:49 INFO - 'SHELL': '/bin/bash', 08:56:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.qwKIiY0j89/Listeners', 08:56:49 INFO - 'TMPDIR': '/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/', 08:56:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:56:49 INFO - 'USER': 'cltbld', 08:56:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:56:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:56:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:56:49 INFO - 'XPC_FLAGS': '0x0', 08:56:49 INFO - 'XPC_SERVICE_NAME': '0', 08:56:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:56:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:56:49 INFO - Downloading/unpacking psutil>=3.1.1 08:56:49 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 08:56:49 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 08:56:49 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 08:56:49 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 08:56:49 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 08:56:49 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 08:56:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 08:56:53 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:56:53 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:56:53 INFO - Installing collected packages: psutil 08:56:53 INFO - Running setup.py install for psutil 08:56:53 INFO - building 'psutil._psutil_osx' extension 08:56:53 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 08:56:53 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 08:56:53 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 08:56:53 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 08:56:54 INFO - building 'psutil._psutil_posix' extension 08:56:54 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 08:56:54 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 08:56:54 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 08:56:54 INFO - ^ 08:56:54 INFO - 1 warning generated. 08:56:54 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 08:56:54 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:56:54 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:56:54 INFO - Successfully installed psutil 08:56:54 INFO - Cleaning up... 08:56:54 INFO - Return code: 0 08:56:54 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 08:56:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:56:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:56:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:56:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:56:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:56:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:56:54 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10bef4b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bef7768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe061c8d5c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bcd2d30>, '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 0x10bbf9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bcc7be8>, '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.ttUlQVpanM/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.qwKIiY0j89/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/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 08:56:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 08:56:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 08:56:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ttUlQVpanM/Render', 08:56:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:56:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:56:54 INFO - 'HOME': '/Users/cltbld', 08:56:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:56:54 INFO - 'LOGNAME': 'cltbld', 08:56:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:56:54 INFO - 'MOZ_NO_REMOTE': '1', 08:56:54 INFO - 'NO_EM_RESTART': '1', 08:56:54 INFO - 'PAGER': '/bin/cat', 08:56:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:56:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:56:54 INFO - 'PWD': '/builds/slave/test', 08:56:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:56:54 INFO - 'SHELL': '/bin/bash', 08:56:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.qwKIiY0j89/Listeners', 08:56:54 INFO - 'TMPDIR': '/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/', 08:56:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:56:54 INFO - 'USER': 'cltbld', 08:56:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:56:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:56:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:56:54 INFO - 'XPC_FLAGS': '0x0', 08:56:54 INFO - 'XPC_SERVICE_NAME': '0', 08:56:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:56:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:56:54 INFO - Downloading/unpacking mozsystemmonitor==0.3 08:56:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:56:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:56:54 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:56:54 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:56:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:56:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:56:57 INFO - Downloading mozsystemmonitor-0.3.tar.gz 08:56:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 08:56:57 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 08:56:57 INFO - Installing collected packages: mozsystemmonitor 08:56:57 INFO - Running setup.py install for mozsystemmonitor 08:56:57 INFO - Successfully installed mozsystemmonitor 08:56:57 INFO - Cleaning up... 08:56:57 INFO - Return code: 0 08:56:57 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 08:56:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:56:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:56:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:56:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:56:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:56:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:56:58 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 0x10bef4b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bef7768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe061c8d5c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bcd2d30>, '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 0x10bbf9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bcc7be8>, '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.ttUlQVpanM/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.qwKIiY0j89/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/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 08:56:58 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 08:56:58 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 08:56:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ttUlQVpanM/Render', 08:56:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:56:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:56:58 INFO - 'HOME': '/Users/cltbld', 08:56:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:56:58 INFO - 'LOGNAME': 'cltbld', 08:56:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:56:58 INFO - 'MOZ_NO_REMOTE': '1', 08:56:58 INFO - 'NO_EM_RESTART': '1', 08:56:58 INFO - 'PAGER': '/bin/cat', 08:56:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:56:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:56:58 INFO - 'PWD': '/builds/slave/test', 08:56:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:56:58 INFO - 'SHELL': '/bin/bash', 08:56:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.qwKIiY0j89/Listeners', 08:56:58 INFO - 'TMPDIR': '/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/', 08:56:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:56:58 INFO - 'USER': 'cltbld', 08:56:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:56:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:56:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:56:58 INFO - 'XPC_FLAGS': '0x0', 08:56:58 INFO - 'XPC_SERVICE_NAME': '0', 08:56:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:56:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:56:58 INFO - Downloading/unpacking jsonschema==2.5.1 08:56:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:56:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:56:58 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:56:58 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:56:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:56:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:57:01 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 08:57:01 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 08:57:01 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 08:57:01 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 08:57:01 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 08:57:01 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 08:57:01 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 08:57:01 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 08:57:02 INFO - Downloading functools32-3.2.3-2.tar.gz 08:57:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 08:57:02 INFO - warning: no files found matching '*.txt' 08:57:02 INFO - no previously-included directories found matching 'build' 08:57:02 INFO - no previously-included directories found matching 'dist' 08:57:02 INFO - no previously-included directories found matching '.git*' 08:57:02 INFO - Installing collected packages: jsonschema, functools32 08:57:02 INFO - Running setup.py install for functools32 08:57:02 INFO - warning: no files found matching '*.txt' 08:57:02 INFO - no previously-included directories found matching 'build' 08:57:02 INFO - no previously-included directories found matching 'dist' 08:57:02 INFO - no previously-included directories found matching '.git*' 08:57:02 INFO - Successfully installed jsonschema functools32 08:57:02 INFO - Cleaning up... 08:57:02 INFO - Return code: 0 08:57:02 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 08:57:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:57:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:57:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:57:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:57:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:57:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:57:02 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10bef4b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bef7768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe061c8d5c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bcd2d30>, '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 0x10bbf9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bcc7be8>, '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.ttUlQVpanM/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.qwKIiY0j89/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/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 08:57:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 08:57:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 08:57:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ttUlQVpanM/Render', 08:57:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:57:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:57:02 INFO - 'HOME': '/Users/cltbld', 08:57:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:57:02 INFO - 'LOGNAME': 'cltbld', 08:57:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:57:02 INFO - 'MOZ_NO_REMOTE': '1', 08:57:02 INFO - 'NO_EM_RESTART': '1', 08:57:02 INFO - 'PAGER': '/bin/cat', 08:57:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:57:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:57:02 INFO - 'PWD': '/builds/slave/test', 08:57:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:57:02 INFO - 'SHELL': '/bin/bash', 08:57:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.qwKIiY0j89/Listeners', 08:57:02 INFO - 'TMPDIR': '/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/', 08:57:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:57:02 INFO - 'USER': 'cltbld', 08:57:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:57:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:57:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:57:02 INFO - 'XPC_FLAGS': '0x0', 08:57:02 INFO - 'XPC_SERVICE_NAME': '0', 08:57:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:57:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:57:02 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 08:57:02 INFO - Cleaning up... 08:57:02 INFO - Return code: 0 08:57:02 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 08:57:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:57:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:57:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:57:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:57:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:57:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:57:02 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10bef4b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bef7768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe061c8d5c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bcd2d30>, '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 0x10bbf9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bcc7be8>, '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.ttUlQVpanM/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.qwKIiY0j89/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/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 08:57:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 08:57:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 08:57:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ttUlQVpanM/Render', 08:57:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:57:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:57:02 INFO - 'HOME': '/Users/cltbld', 08:57:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:57:02 INFO - 'LOGNAME': 'cltbld', 08:57:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:57:02 INFO - 'MOZ_NO_REMOTE': '1', 08:57:02 INFO - 'NO_EM_RESTART': '1', 08:57:02 INFO - 'PAGER': '/bin/cat', 08:57:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:57:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:57:02 INFO - 'PWD': '/builds/slave/test', 08:57:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:57:02 INFO - 'SHELL': '/bin/bash', 08:57:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.qwKIiY0j89/Listeners', 08:57:02 INFO - 'TMPDIR': '/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/', 08:57:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:57:02 INFO - 'USER': 'cltbld', 08:57:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:57:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:57:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:57:02 INFO - 'XPC_FLAGS': '0x0', 08:57:02 INFO - 'XPC_SERVICE_NAME': '0', 08:57:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:57:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:57:03 INFO - Downloading/unpacking blobuploader==1.2.4 08:57:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:57:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:57:03 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:57:03 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:57:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:57:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:57:05 INFO - Downloading blobuploader-1.2.4.tar.gz 08:57:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 08:57:05 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 08:57:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:57:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:57:05 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:57:05 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:57:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:57:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:57:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 08:57:06 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 08:57:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:57:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:57:06 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:57:06 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:57:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:57:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:57:06 INFO - Downloading docopt-0.6.1.tar.gz 08:57:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 08:57:06 INFO - Installing collected packages: blobuploader, requests, docopt 08:57:06 INFO - Running setup.py install for blobuploader 08:57:07 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 08:57:07 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 08:57:07 INFO - Running setup.py install for requests 08:57:07 INFO - Running setup.py install for docopt 08:57:07 INFO - Successfully installed blobuploader requests docopt 08:57:07 INFO - Cleaning up... 08:57:07 INFO - Return code: 0 08:57:07 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:57:07 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')]} 08:57:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:57:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:57:07 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')]} 08:57:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:57:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:57:07 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 0x10bef4b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bef7768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe061c8d5c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bcd2d30>, '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 0x10bbf9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bcc7be8>, '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.ttUlQVpanM/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.qwKIiY0j89/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/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 08:57:07 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 08:57:07 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 08:57:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ttUlQVpanM/Render', 08:57:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:57:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:57:07 INFO - 'HOME': '/Users/cltbld', 08:57:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:57:07 INFO - 'LOGNAME': 'cltbld', 08:57:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:57:07 INFO - 'MOZ_NO_REMOTE': '1', 08:57:07 INFO - 'NO_EM_RESTART': '1', 08:57:07 INFO - 'PAGER': '/bin/cat', 08:57:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:57:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:57:07 INFO - 'PWD': '/builds/slave/test', 08:57:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:57:07 INFO - 'SHELL': '/bin/bash', 08:57:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.qwKIiY0j89/Listeners', 08:57:07 INFO - 'TMPDIR': '/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/', 08:57:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:57:07 INFO - 'USER': 'cltbld', 08:57:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:57:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:57:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:57:07 INFO - 'XPC_FLAGS': '0x0', 08:57:07 INFO - 'XPC_SERVICE_NAME': '0', 08:57:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:57:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:57:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:57:08 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-gurGmG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:57:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:57:08 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-zZ9PGB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:57:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:57:08 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-J9S5zi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:57:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:57:08 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-FD2vwG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:57:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:57:08 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-3z7Wob-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:57:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:57:08 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-XH5PDL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:57:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:57:08 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-WPQeK6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:57:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:57:08 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-iuFGMg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:57:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:57:08 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-OWLTa8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:57:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:57:09 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-D6z77A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:57:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:57:09 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-xZjfzZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:57:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:57:09 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-KICqa4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:57:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:57:09 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-UpzIFB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:57:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:57:09 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-gifGd_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:57:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:57:09 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-wo5eRZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:57:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:57:09 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-eotjUQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:57:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:57:09 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-yCtBdv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:57:09 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 08:57:09 INFO - Running setup.py install for manifestparser 08:57:10 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 08:57:10 INFO - Running setup.py install for mozcrash 08:57:10 INFO - Running setup.py install for mozdebug 08:57:10 INFO - Running setup.py install for mozdevice 08:57:10 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 08:57:10 INFO - Installing dm script to /builds/slave/test/build/venv/bin 08:57:10 INFO - Running setup.py install for mozfile 08:57:10 INFO - Running setup.py install for mozhttpd 08:57:10 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 08:57:10 INFO - Running setup.py install for mozinfo 08:57:10 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 08:57:10 INFO - Running setup.py install for mozInstall 08:57:11 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 08:57:11 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 08:57:11 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 08:57:11 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 08:57:11 INFO - Running setup.py install for mozleak 08:57:11 INFO - Running setup.py install for mozlog 08:57:11 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 08:57:11 INFO - Running setup.py install for moznetwork 08:57:11 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 08:57:11 INFO - Running setup.py install for mozprocess 08:57:11 INFO - Running setup.py install for mozprofile 08:57:11 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 08:57:11 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 08:57:11 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 08:57:11 INFO - Running setup.py install for mozrunner 08:57:12 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 08:57:12 INFO - Running setup.py install for mozscreenshot 08:57:12 INFO - Running setup.py install for moztest 08:57:12 INFO - Running setup.py install for mozversion 08:57:12 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 08:57:12 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 08:57:12 INFO - Cleaning up... 08:57:12 INFO - Return code: 0 08:57:12 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:57:12 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')]} 08:57:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:57:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:57:12 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')]} 08:57:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:57:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:57:12 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 0x10bef4b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bef7768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe061c8d5c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bcd2d30>, '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 0x10bbf9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bcc7be8>, '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.ttUlQVpanM/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.qwKIiY0j89/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/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 08:57:12 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 08:57:12 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 08:57:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ttUlQVpanM/Render', 08:57:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:57:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:57:12 INFO - 'HOME': '/Users/cltbld', 08:57:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:57:12 INFO - 'LOGNAME': 'cltbld', 08:57:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:57:12 INFO - 'MOZ_NO_REMOTE': '1', 08:57:12 INFO - 'NO_EM_RESTART': '1', 08:57:12 INFO - 'PAGER': '/bin/cat', 08:57:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:57:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:57:12 INFO - 'PWD': '/builds/slave/test', 08:57:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:57:12 INFO - 'SHELL': '/bin/bash', 08:57:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.qwKIiY0j89/Listeners', 08:57:12 INFO - 'TMPDIR': '/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/', 08:57:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:57:12 INFO - 'USER': 'cltbld', 08:57:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:57:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:57:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:57:12 INFO - 'XPC_FLAGS': '0x0', 08:57:12 INFO - 'XPC_SERVICE_NAME': '0', 08:57:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:57:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:57:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:57:12 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-JPntVv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:57:12 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)) 08:57:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:57:12 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-rR_iYZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:57:12 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)) 08:57:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:57:12 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-T5m11Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:57:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 08:57:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:57:13 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-hXEzYQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:57:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 08:57:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:57:13 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-n1HGJn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:57:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 08:57:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:57:13 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-E7J5I8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:57:13 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)) 08:57:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:57:13 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-JsE159-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:57:13 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)) 08:57:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:57:13 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-2MEoy8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:57:13 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)) 08:57:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:57:13 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-57LdO_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:57:13 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)) 08:57:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:57:13 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-IQkgK8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:57:13 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)) 08:57:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:57:13 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-U5D225-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:57:13 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)) 08:57:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:57:13 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-6G3ise-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:57:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 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)) 08:57:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:57:14 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-mb7Be2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:57:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 08:57:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:57:14 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-x1Jslf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:57:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 08:57:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:57:14 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-HX5bPf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:57:14 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)) 08:57:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:57:14 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-7gaGbs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:57:14 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)) 08:57:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:57:14 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-ucp718-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:57:14 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)) 08:57:14 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)) 08:57:14 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)) 08:57:14 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)) 08:57:14 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)) 08:57:14 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)) 08:57:14 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:57:14 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 08:57:14 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 08:57:14 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 08:57:14 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 08:57:14 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 08:57:14 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 08:57:17 INFO - Downloading blessings-1.6.tar.gz 08:57:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 08:57:17 INFO - Installing collected packages: blessings 08:57:17 INFO - Running setup.py install for blessings 08:57:17 INFO - Successfully installed blessings 08:57:17 INFO - Cleaning up... 08:57:17 INFO - Return code: 0 08:57:17 INFO - Done creating virtualenv /builds/slave/test/build/venv. 08:57:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:57:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:57:17 INFO - Reading from file tmpfile_stdout 08:57:17 INFO - Current package versions: 08:57:17 INFO - blessings == 1.6 08:57:17 INFO - blobuploader == 1.2.4 08:57:17 INFO - docopt == 0.6.1 08:57:17 INFO - functools32 == 3.2.3-2 08:57:17 INFO - jsonschema == 2.5.1 08:57:17 INFO - manifestparser == 1.1 08:57:17 INFO - mozInstall == 1.12 08:57:17 INFO - mozcrash == 1.0 08:57:17 INFO - mozdebug == 0.1 08:57:17 INFO - mozdevice == 0.49 08:57:17 INFO - mozfile == 1.2 08:57:17 INFO - mozhttpd == 0.7 08:57:17 INFO - mozinfo == 0.9 08:57:17 INFO - mozleak == 0.1 08:57:17 INFO - mozlog == 3.4 08:57:17 INFO - moznetwork == 0.27 08:57:17 INFO - mozprocess == 0.25 08:57:17 INFO - mozprofile == 0.28 08:57:17 INFO - mozrunner == 6.13 08:57:17 INFO - mozscreenshot == 0.1 08:57:17 INFO - mozsystemmonitor == 0.3 08:57:17 INFO - moztest == 0.8 08:57:17 INFO - mozversion == 1.4 08:57:17 INFO - psutil == 3.1.1 08:57:17 INFO - requests == 1.2.3 08:57:17 INFO - wsgiref == 0.1.2 08:57:17 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:57: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')]} 08:57:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:57:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:57: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')]} 08:57:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:57:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:57:17 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 0x10bef4b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bef7768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe061c8d5c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bcd2d30>, '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 0x10bbf9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bcc7be8>, '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.ttUlQVpanM/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.qwKIiY0j89/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/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 08:57:17 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 08:57:17 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 08:57:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ttUlQVpanM/Render', 08:57:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:57:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:57:17 INFO - 'HOME': '/Users/cltbld', 08:57:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:57:17 INFO - 'LOGNAME': 'cltbld', 08:57:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:57:17 INFO - 'MOZ_NO_REMOTE': '1', 08:57:17 INFO - 'NO_EM_RESTART': '1', 08:57:17 INFO - 'PAGER': '/bin/cat', 08:57:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:57:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:57:17 INFO - 'PWD': '/builds/slave/test', 08:57:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:57:17 INFO - 'SHELL': '/bin/bash', 08:57:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.qwKIiY0j89/Listeners', 08:57:17 INFO - 'TMPDIR': '/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/', 08:57:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:57:17 INFO - 'USER': 'cltbld', 08:57:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:57:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:57:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:57:17 INFO - 'XPC_FLAGS': '0x0', 08:57:17 INFO - 'XPC_SERVICE_NAME': '0', 08:57:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:57:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:57:18 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)) 08:57:18 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)) 08:57:18 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)) 08:57:18 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)) 08:57:18 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)) 08:57:18 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)) 08:57:18 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)) 08:57:18 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)) 08:57:18 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)) 08:57:18 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)) 08:57:18 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)) 08:57:18 INFO - Cleaning up... 08:57:18 INFO - Return code: 0 08:57:18 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 08:57:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:57:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:57:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:57:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:57:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:57:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:57:18 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 0x10bef4b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bef7768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe061c8d5c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bcd2d30>, '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 0x10bbf9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bcc7be8>, '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.ttUlQVpanM/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.qwKIiY0j89/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/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 08:57:18 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 08:57:18 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 08:57:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ttUlQVpanM/Render', 08:57:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:57:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:57:18 INFO - 'HOME': '/Users/cltbld', 08:57:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:57:18 INFO - 'LOGNAME': 'cltbld', 08:57:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:57:18 INFO - 'MOZ_NO_REMOTE': '1', 08:57:18 INFO - 'NO_EM_RESTART': '1', 08:57:18 INFO - 'PAGER': '/bin/cat', 08:57:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:57:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:57:18 INFO - 'PWD': '/builds/slave/test', 08:57:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:57:18 INFO - 'SHELL': '/bin/bash', 08:57:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.qwKIiY0j89/Listeners', 08:57:18 INFO - 'TMPDIR': '/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/', 08:57:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:57:18 INFO - 'USER': 'cltbld', 08:57:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:57:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:57:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:57:18 INFO - 'XPC_FLAGS': '0x0', 08:57:18 INFO - 'XPC_SERVICE_NAME': '0', 08:57:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:57:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:57:18 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 08:57:18 INFO - Cleaning up... 08:57:18 INFO - Return code: 0 08:57:18 INFO - Running post-action listener: _resource_record_post_action 08:57:18 INFO - Running post-action listener: _start_resource_monitoring 08:57:18 INFO - Starting resource monitoring. 08:57:18 INFO - [mozharness: 2017-02-27 16:57:18.455992Z] Finished create-virtualenv step (success) 08:57:18 INFO - [mozharness: 2017-02-27 16:57:18.456960Z] Running install step. 08:57:18 INFO - Running pre-action listener: _resource_record_pre_action 08:57:18 INFO - Running main action method: install 08:57:18 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:57:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:57:18 INFO - Reading from file tmpfile_stdout 08:57:18 INFO - Detecting whether we're running mozinstall >=1.0... 08:57:18 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 08:57:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 08:57:18 INFO - Reading from file tmpfile_stdout 08:57:18 INFO - Output received: 08:57:18 INFO - Usage: mozinstall [options] installer 08:57:18 INFO - Options: 08:57:18 INFO - -h, --help show this help message and exit 08:57:18 INFO - -d DEST, --destination=DEST 08:57:18 INFO - Directory to install application into. [default: 08:57:18 INFO - "/builds/slave/test"] 08:57:18 INFO - --app=APP Application being installed. [default: firefox] 08:57:18 INFO - mkdir: /builds/slave/test/build/application 08:57:18 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'] 08:57:18 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 08:57:39 INFO - Reading from file tmpfile_stdout 08:57:39 INFO - Output received: 08:57:39 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 08:57:39 INFO - Running post-action listener: _resource_record_post_action 08:57:39 INFO - [mozharness: 2017-02-27 16:57:39.714692Z] Finished install step (success) 08:57:39 INFO - [mozharness: 2017-02-27 16:57:39.714840Z] Running run-tests step. 08:57:39 INFO - Running pre-action listener: _resource_record_pre_action 08:57:39 INFO - Running main action method: run_tests 08:57:39 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:57:39 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:57:39 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:57:39 INFO - 2017-02-27 08:57:39.729 screenresolution[1621:7713] starting screenresolution argv=screenresolution get 08:57:39 INFO - 2017-02-27 08:57:39.731 screenresolution[1621:7713] Display 0: 1600x1200x32@60 08:57:39 INFO - 2017-02-27 08:57:39.737 screenresolution[1622:7716] starting screenresolution argv=screenresolution list 08:57:39 INFO - Available Modes on Display 0 08:57:39 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:57:39 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:57:39 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:57:39 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:57:39 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:57:39 INFO - Intel Iris: 08:57:39 INFO - Chipset Model: Intel Iris 08:57:39 INFO - Type: GPU 08:57:39 INFO - Bus: Built-In 08:57:39 INFO - VRAM (Dynamic, Max): 1536 MB 08:57:39 INFO - Vendor: Intel (0x8086) 08:57:39 INFO - Device ID: 0x0a2e 08:57:39 INFO - Revision ID: 0x0009 08:57:39 INFO - Displays: 08:57:39 INFO - Display: 08:57:39 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:57:39 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:57:39 INFO - Display Serial Number: 1600x1200 60 08:57:39 INFO - Main Display: Yes 08:57:39 INFO - Mirror: Off 08:57:39 INFO - Online: Yes 08:57:39 INFO - Rotation: Supported 08:57:39 INFO - Return code: 0 08:57:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 08:57:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 08:57:39 INFO - Python 2.7.3 08:57:39 INFO - Return code: 0 08:57:39 INFO - grabbing minidump binary from tooltool 08:57:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:57:39 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 0x10bcd2d30>, '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 0x10bbf9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bcc7be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 08:57:39 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 08:57:39 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 08:57:39 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 08:57:40 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 08:57:40 INFO - Return code: 0 08:57:40 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 08:57:40 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 08:57:40 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 08:57:40 INFO - ENV: RUST_BACKTRACE is now 1 08:57:40 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 08:57:40 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 08:57:40 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/DW0cpsYTQ1i0sUdq36hu4g/artifacts/public/build/firefox-54.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0039', '--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 08:57:40 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/DW0cpsYTQ1i0sUdq36hu4g/artifacts/public/build/firefox-54.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0039 --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 08:57:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ttUlQVpanM/Render', 08:57:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:57:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:57:40 INFO - 'HOME': '/Users/cltbld', 08:57:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:57:40 INFO - 'LOGNAME': 'cltbld', 08:57:40 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 08:57:40 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 08:57:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:57:40 INFO - 'MOZ_NO_REMOTE': '1', 08:57:40 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 08:57:40 INFO - 'NO_EM_RESTART': '1', 08:57:40 INFO - 'PAGER': '/bin/cat', 08:57:40 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:57:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:57:40 INFO - 'PWD': '/builds/slave/test', 08:57:40 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 08:57:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:57:40 INFO - 'RUST_BACKTRACE': '1', 08:57:40 INFO - 'SHELL': '/bin/bash', 08:57:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.qwKIiY0j89/Listeners', 08:57:40 INFO - 'TMPDIR': '/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/', 08:57:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:57:40 INFO - 'USER': 'cltbld', 08:57:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:57:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:57:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:57:40 INFO - 'XPC_FLAGS': '0x0', 08:57:40 INFO - 'XPC_SERVICE_NAME': '0', 08:57:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:57:40 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/DW0cpsYTQ1i0sUdq36hu4g/artifacts/public/build/firefox-54.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0039', '--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 08:57:40 INFO - mozversion application_buildid: 20170227072837 08:57:40 INFO - mozversion application_changeset: 106a96755d3bcebe64bbbc3b521d65d262ba9c02 08:57:40 INFO - mozversion application_display_name: Nightly 08:57:40 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 08:57:40 INFO - mozversion application_name: Firefox 08:57:40 INFO - mozversion application_remotingname: firefox 08:57:40 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 08:57:40 INFO - mozversion application_vendor: Mozilla 08:57:40 INFO - mozversion application_version: 54.0a1 08:57:40 INFO - mozversion platform_buildid: 20170227072837 08:57:40 INFO - mozversion platform_changeset: 106a96755d3bcebe64bbbc3b521d65d262ba9c02 08:57:40 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 08:57:40 INFO - mozversion platform_version: 54.0a1 08:57:40 INFO - using testdate: 1488214660 08:57:40 INFO - actual date: 1488214660 08:57:40 INFO - starting webserver on 'localhost:49252' 08:57:40 INFO - SUITE-START | Running 2 tests 08:57:40 INFO - TEST-START | dromaeo_css 08:57:40 INFO - operating with platform_type : mac_ 08:57:40 INFO - Initialising browser for dromaeo_css test... 08:57:40 INFO - TEST-INFO | started process 1632 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/tmpQyLIrh/profile http://localhost:49252/getInfo.html) 08:57:43 INFO - PROCESS | 1632 | 2017-02-27 08:57:43.194 plugin-container[1634:7867] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x913f, name = 'com.apple.tsm.portname' 08:57:43 INFO - PROCESS | 1632 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:57:43 INFO - PROCESS | 1632 | 2017-02-27 08:57:43.197 plugin-container[1634:7867] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x960f, name = 'com.apple.CFPasteboardClient' 08:57:43 INFO - PROCESS | 1632 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:57:43 INFO - PROCESS | 1632 | 2017-02-27 08:57:43.197 plugin-container[1634:7867] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 08:57:43 INFO - PROCESS | 1632 | __metrics Screen width/height:1600/1200 08:57:43 INFO - PROCESS | 1632 | colorDepth:24 08:57:43 INFO - PROCESS | 1632 | Browser inner width/height: 1024/689 08:57:43 INFO - PROCESS | 1632 | __metrics 08:57:43 ERROR - PROCESS | 1632 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3354: TypeError: this.tabs is undefined 08:57:44 INFO - TEST-INFO | 1632: exit 0 08:57:44 INFO - Browser initialized. 08:57:44 INFO - Running cycle 1/1 for dromaeo_css test... 08:57:44 INFO - TEST-INFO | started process 1635 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/tmpQyLIrh/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:57:46 INFO - PROCESS | 1635 | 2017-02-27 08:57:46.531 plugin-container[1636:8009] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8943, name = 'com.apple.tsm.portname' 08:57:46 INFO - PROCESS | 1635 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:57:46 INFO - PROCESS | 1635 | 2017-02-27 08:57:46.531 plugin-container[1636:8009] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8c0b, name = 'com.apple.CFPasteboardClient' 08:57:46 INFO - PROCESS | 1635 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:57:46 INFO - PROCESS | 1635 | 2017-02-27 08:57:46.531 plugin-container[1636:8009] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 08:57:56 INFO - PROCESS | 1635 | RSS: Main: 226189312 08:57:56 INFO - PROCESS | 1635 | 09:01:00 INFO - PROCESS | 1635 | Cycle 1(1): loaded http://localhost:49252/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49252/tests/dromaeo/cssquery-ext.html) 09:01:01 INFO - PROCESS | 1635 | RSS: Main: 248655872 09:01:01 INFO - PROCESS | 1635 | 09:02:44 INFO - PROCESS | 1635 | ************************* 09:02:44 INFO - PROCESS | 1635 | A coding exception was thrown and uncaught in a Task. 09:02:44 INFO - PROCESS | 1635 | 09:02:44 ERROR - PROCESS | 1635 | Full message: TypeError: NetworkError when attempting to fetch resource. 09:02:44 INFO - PROCESS | 1635 | Full stack: 09:02:44 INFO - PROCESS | 1635 | ************************* 09:04:04 INFO - PROCESS | 1635 | Cycle 1(1): loaded http://localhost:49252/tests/dromaeo/cssquery-ext.html (next: http://localhost:49252/tests/dromaeo/cssquery-jquery.html) 09:04:04 INFO - PROCESS | 1635 | RSS: Main: 256069632 09:04:04 INFO - PROCESS | 1635 | 09:07:06 INFO - PROCESS | 1635 | Cycle 1(1): loaded http://localhost:49252/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49252/tests/dromaeo/cssquery-mootools.html) 09:07:07 INFO - PROCESS | 1635 | RSS: Main: 268062720 09:07:07 INFO - PROCESS | 1635 | 09:10:09 INFO - PROCESS | 1635 | Cycle 1(1): loaded http://localhost:49252/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49252/tests/dromaeo/cssquery-prototype.html) 09:10:10 INFO - PROCESS | 1635 | RSS: Main: 262295552 09:10:10 INFO - PROCESS | 1635 | 09:13:13 INFO - PROCESS | 1635 | Cycle 1(1): loaded http://localhost:49252/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49252/tests/dromaeo/cssquery-yui.html) 09:13:13 INFO - PROCESS | 1635 | RSS: Main: 271962112 09:13:13 INFO - PROCESS | 1635 | 09:16:16 INFO - PROCESS | 1635 | Cycle 1(1): loaded http://localhost:49252/tests/dromaeo/cssquery-yui.html (next: http://localhost:49252/tests/dromaeo/cssquery-dojo.html) 09:16:17 INFO - PROCESS | 1635 | RSS: Main: 265863168 09:16:17 INFO - PROCESS | 1635 | 09:16:17 INFO - PROCESS | 1635 | __start_tp_report 09:16:17 INFO - PROCESS | 1635 | _x_x_mozilla_page_load 09:16:17 INFO - PROCESS | 1635 | _x_x_mozilla_page_load_details 09:16:17 INFO - PROCESS | 1635 | |i|pagename|runs| 09:16:17 INFO - PROCESS | 1635 | |0;dojo.html;2958.8816447342106;3406.301708149831;3415.590129184498;3468.3410152071115;3474.3051389722045;4821.445533763618;5745.885082298351;5811.808579241246;5910.290765108188;5948.851278718029;4860.902781944368;5821.301443826746;5961.254843144607;5999.250093738283;6019.217501724781;5311.521963023327;5332;5402.702851343174;5433.592480563953;5501.202325662776;5334;5417.7291135443265;5482.91775623366;5496.477834605706;5535.916961245584;5244.39689435716;5267.578593712494;5437.972810135963;5446.101393270105;5450.754716037788;4636;5027.818462661308;5361.249425080492;5369.3556773187465;5401.378841433207;5289.603279754034;5325.854941187663;5364.678119312854;5382.111951527993;5409.459054094598;4650.7442090685;4997.675151115166;5711.086785926719;5926.436988486087;6012.488938440238;6229.813105606839;6241.500679945639;6278.874422511524;6302.086197501382;6305.5901366411035;6081.1790408294755;6275.341089185661;6355.269144048401;6355.983042713144;6409.519286053518;5723.168586052507;6086.726097325631;6134.447899689049;6140.986737188403;6141.815745527641;5072.721000344943;5144.279800827887;6450.935490645127;6475.061116138134;6502.934970650327;6216.44052035319;6263.154474146023;6267;6269.905951410732;6272.3100458949475;6224.346443623354;6359.395857393541;6371.649559274192;6396.648184349812;6400.647964362005;6145.310039739107;6213.937860621426;6232.096346029891;6278.654673992883;6346.555741098171;5261.921071183935;6237.594556353823;6247.562670613104;6287.685615719196;6315.5579109462815;4272.487301523837;5161.793528258902;5187.533122019036;5200.817971370984;5221.530062294411;4560.247559152769;4579.564941330568;4595.793189306422;4628.004978929479;4650.162970665313;5846.619969701956;5904.645721256738;6047.516198704093;6051.364606716232;6073.878522429527;5696.231008813757;5969.970150149308;6110.649890384107;6265.154204182468;6284.497240220771;6005.279366476051;6036.426539478743;6047.969760151259;6063.969680151571;6091.817245482643;4606.976965115153;5625.212470254168;5637.915431268534;5648.717564121777;5661.547076233891;5335.626506144533;6251.812445626639;6420.935790642221;6429.935700642934;6441.806745797634;6318.4313411793155;6363.681815909279;6372.36276372359;6383.1382763326355;6385.393387628168;6530.9020364693615;6531.9020214695865;6535.823551760729;6535.934640653532;6538.411542961157;6160.599561028519;6194.81415557534;6243.125962365181;6260.029695397221;6321.810345689637;6254.8123556293385;6347.920853454832;6359.650219237987;6376.075469056868;6390.201224846895;6096.207493025879;6149.631022138686;6202.379762023942;6239.688015599293;6258.186435763321;6200.100985357189;6209.813705588839;6219.844503887439;6246.3753624637175;6275.780347687809;5515.4760297771645;5822.5633077518505;6039.516838652985;6107.450329470369;6111.755529778937;6177;6208.255009398901;6216.533759968111;6221.906671399842;6232.937670623417;5176.327077479904;6227.595206311576;6239.656818875005;6244.625322480666;6251.937480625135 09:16:17 INFO - PROCESS | 1635 | |1;ext.html;703.0579024107694;773.0491495460583;795.9363250939923;923.5843870258385;929.0106037070519;36377.818110909444;37282.44076338857;38567.807160964156;38639.22721545574;38742.612573874256;37882.0529486763;38114.61885381145;38177.854664360115;38189;38327.04182395434;7611.885821712679;7770.456068075208;10306.175505959543;10513.579456821719;10542.525586348595;10319.638812641559;10338;10361.948190259076;10385;10407.37555746654;10535.10451611614;10571.682849514527;10583.947080264588;10586.994235547649;10594.947025264863;10492.895071049345;10508.947455262713;10532.947335263312;10540.841887371696;10566.471676416148;10300;10390.740231494274;10392.584296628125;10416.79166416675;10484.528196231111;33203.50194747081;33356.166095847555;33404.49679764416;33488.49767253493;33507.6649233507;3157.605299337583;3557.5908770491465;3636.0364503406618;3646.2889736501393;3764.604716504782;24628.507429851303;24633.261002169962;24705;24831;25488;2926.3415731460464;2934.838583877886;3025.2134445044226;3095.1797773590015;3115;294301;347200.2639986809;349704;350764;351911.480885193;42932.3560146598;43072.13855722931;43073.78463107727;43076;43252.78373608112;44381.778091109336;44445.777771111585;44493.332600011025;44512.777436112614;44522.66432007045;11560;12196.780321967732;12803.743925121445;12828.80756788649;12917.418716157608;10926.562937482571;11044.116470682326;11054;11054.502547385388;11100.5559777608;10037.79924401508;11559.711007224887;11664.183507154417;11724.824127638092;11736.354500502384;5444.509994100471;5524.8342549723575;5525.226468294442;5526.088195447786;5536.640118392292;931.3061768982152;1114.5820317380983;1116.776644671069;1117.6647005898199;1170.73658426855;24585.766450704272;26569.734302657114;27166.320841979108;28321.15036548907;28459.71540284612;1007.7581380468635;1008.2186305613062;1041.958321667125;1043.5877828257796;1048.192891473561;11889.940550297193;13479.528216512375;14308.856911430961;14357.282135893163;14443.133411995104;176247.11876440537;216458;217043;217226;223682;44328.335074973904;44587;44670.776646117214;44687.55312446899;44737.88155296144;1136.3182090745486;1350.824392828926;1354.5394565847462;1371.2389623758909;1408.6407965968613;24673.01307947662;24750.25749227526;26197.38026197365;26593.734062659125;26759.732402675723;37999.810000949816;38040.61959380481;38158.046048849;38245;38296.61703382931;346953;408230.9176908193;411491;412781;413082;19761.604767904275;20669.34645228348;21780.45548861228;21834.23580174686;21985.67021494711;36932;37580;37610.05974850651;37982.43026354662;38409.807950960065;26066.34834129162;29689.703102969557;30471.695283046887;30923.610486442223;31448.52827207548;26770.598441023012;28490.715092848804;30822;30907.2273193163;31691;31367;31475.055748327588;31498.685013149574;31699.52450713195;32449.51325730163;21203.46991325229;25973.090941816947;27445.45109097847;27568.724312756614;28884.566731498624;22630.886845565667;22693.546129077655;23094.769052309934;23183.652245216;23376.6493502601 09:16:17 INFO - PROCESS | 1635 | |2;jquery.html;387.38212550981194;389.4236529935693;403.8970062634029;420.4072258116056;429.14171656686625;17987.550311242227;19681.507962300948;20137.59724805503;20948.476288092806;20994;21100.261490847824;21306.46733831655;21323.040463179157;21642;22034;6461.5476916615735;6831.555948863333;6843.349881761224;6857.348551887562;6862.210845752727;6480.773172938948;6665.133532640774;6672.933270667304;6688.565243259197;6805.251422343538;6659.63372014539;6663.8334041649105;6669.5331326807145;6676.065350850885;6679.165104361955;6374.968125159367;6602.933970660304;6645.136132302818;6658.93457046875;6672.9666351668175;6234.875302493925;6420.6147631142285;6424.1327420798525;6479.060536222221;6508.93491065088;21426.46433839151;21430.785692143036;25090.49819003628;25548;26002.6099608506;2867;2916.314666053476;2921.196670915494;2930.223490774925;2951.2621844538867;12919.289439080847;14996.175210363426;15262.847371526253;15901.443449479328;16243;1924.6054558815408;1957.8042195780451;1975.6838905775007;1978.782333943265;1983.355409491921;152831;157599.63600546002;159019.20490397475;161235;161585;19179.808201917804;19260.711089333672;19373.32193373225;19478.707819382722;19498;29731.256718582208;29810.40379192404;29817.65820538081;29853.402931941673;29857.552136717968;8136.877946830802;9300.767480812898;9588.280878933969;9706.854397184048;9860.556274967643;7908.920910790934;8032.718854840053;8052.59737013147;8164.387670924586;8190.180981901882;8926.821463570692;9026.413283136575;9027.77430564228;9105.2260557852;9123.17891389765;3151.4642510773224;3192.1062102611304;3204.727598154183;3828.617138286149;3851.8459261629782;780.8555417247827;821.2608652212937;840.932725381968;844.2401838345415;882.3029806452959;19013.049347532844;19453.51366215856;19983.200671972958;20454.897725511277;20455.97720114018;815.2133191470259;875.2166810704384;896.5338024227365;899.3839220134229;916.1205242966694;8464.44981076228;9192.172704456498;10683.227333087134;11389.145814063979;14794.186319752302;637624;654414;656602;657205;657584;120176.39911800386;137165.94251086042;142455;148759.51240487458;150840;805.9274665280153;973.0852998181687;1014.6195176808696;1060.6075751971666;1066.317556763672;18298.359557415428;19379;19598.510037248612;19620.920849353424;19716.21135154578;45560;49970.75014624903;54994.800207992834;58444;60075.09887351606;80309;82916.58541707252;83895.58052209699;83957.58021209855;83962.16037839543;8560.828783424422;11243.156763242952;11616.767664646828;11734.295942243492;11786.233894796811;11995.280283183038;12499.187552809402;12544.811827822408;12570.497180112692;12770.553030644254;10620.52207650673;11018.39398833072;11102.333859968428;11138.665840024812;11277.379744114152;9085.727428177166;10780.137588993019;10986.230963832455;11068;11098.50056747432;11019.228653994143;11133.610323638633;11136.554537818396;11146.219764616724;11467.770644587228;11232.66302010941;11278.830817537579;11518.481668325114;11593.768124637629;11601.593944211912;10314.845277320695;10608;10868.728281793019;10925.07136893373;10966.670999870017 09:16:17 INFO - PROCESS | 1635 | |3;mootools.html;1522.451917309769;1522.9695406091885;1552.1773460066163;1559.7504399296108;1565.2799712132419;10011.899881001196;10542.578296868116;10549.78900421991;10606.57573697052;10900;10502.579896804138;10647.09499692527;10658.467076646175;10666.093382062534;10694.091002264817;2258.9661155082686;2304.1474654377903;2306.08909480755;2307.596170670126;2311.7457079721216;2292.8306563652573;2330.9510720175904;2335.1710142899283;2336.7663233676667;2340.1224540797202;2281.4296425893526;2290.232772021375;2291.232437133562;2313.8611683298973;2314.884255787212;2272.250157448038;2274.442761523421;2274.704288442492;2292.747797742247;2292.9541409171725;2266.6939963104933;2295.3802473332107;2309.5034567567886;2316.0851463671925;2321.0831721469926;10715.83926241107;10759.30064545802;10798.190135739851;10818;10840.295380800222;5525.11598144295;5655.660660360391;5703.40114288002;5715.257016587859;5716.1711551825165;3292.111129994912;3376.9120838405825;3532.8410221540094;3578.6958108560802;3591.174029973121;1129.6836885672026;1164.714644912002;1203.8796120387892;1210.7033776724759;1223.0521345956865;12516.247725318553;12964.546240881618;13122.409491572807;14349.210793406359;14377;4334.566543345703;4397.758123303249;4755.477105181164;4823.469418363977;4861.343718597944;12362;12378.381080946097;12447.875521244852;12466.376681166086;12489.812652810215;5755.913661295084;5804.65172089676;5811.651300921958;5818.912716309259;5830.7959221427045;4308.67684923632;5034.471380504994;5149.2276158576515;5198.428172900978;5242.1914439518305;3222.9194270143435;3907.785071821077;3916.2950668879307;4007.138465229931;4028.576999415078;1015.5430056474615;1106.1372129738816;1115.5203262597;1127.768807394482;1130.7625398666373;702.0872865275122;754.8565772503207;891.7057371067662;899.2221728205055;930.1349744737324;5702.287214098238;5972.790952316648;6069.180660610849;6157.538184636171;6244.063390491372;840.815020695446;1012.3571532077184;1023.7747695506977;1076.079951641351;1108.9944550277196;3769.5228792695716;3836.7506112102624;3862.517185351831;3894.883153505399;3995.280849447069;12395.566155184526;12785.041121915894;12835.358232088358;12840.48638054486;12882.80675789864;12307.26156430617;12364.505419783289;12638.431270592846;12703.555375561811;12782.105252632227;9324.580393882292;9525.809483810284;9595.520223988913;9623.951880240555;9705.272104592184;5109.846704598868;5901.793437229677;5938.524918006463;5952.58331916779;6071.969640151771;10475.738106547396;10611.991860773214;11723;11838.585649502225;11993.820092698443;11733.76532469363;11790.292582445081;11960.820587691018;12105.939470302592;12137.939310303746;7792.181820908723;8071.475354102199;8084.83830323402;8085.919140808516;8088.595570221348;9231.446113233227;9346.626134954526;9399.577019034297;9479.620815167316;9487.383320084173;8376.288015518749;8404.579771011302;8429.236406853424;8495.915040849759;8499.235068843835;6850.588964662136;8206.05630352517;8288.668453261802;8390.412671112963;8480.787980300542;6341.55609107358;7521.135069466863;7559.697612095454;7562.395008399425;7588.203238660082;4875.19559272716;5189.273501709688;5243.265942768092;5286.682799032071;5581.302337207849;4753.310769938409;4904.5340692634145;5055.938252966918;5364;5502.779888804563 09:16:17 INFO - PROCESS | 1635 | |4;prototype.html;323.55511172138307;362.3079917357847;369.78737226095;378.9333027527509;383.9307528533036;4374.978125109378;5144.665596736209;5454.545454545456;5496.148097044959;5527.080898000782;4489.483709373423;4779.450363208232;5008.323876276701;5159.3808742950805;5361.051421718059;3612.602613712496;4599.3665004646455;4685;4688.484266730657;4689.62483001359;3706.425504046877;3942.8565715942486;4490.7754612269255;4728.810847566093;4739.507055277577;3679.2825399047197;4614.054118905633;4647.302904564325;4647.69789963653;4658.25467925132;3953.991732108294;4618.422697162855;4643.721376717391;4665.603423708989;4696.849271928365;4288.292431748758;4478.193925093482;4478.387780399054;4482.439695038121;4489.181881338063;3893.7469064510715;4440.357986997731;5097.2608971699265;5188.7146206958605;5241.6633758391745;4892.584130348924;4938.9753051234875;5020;5465.398806131322;5507.0637991541535;4918.434380046305;5017.949820501784;5554;5730.34101078373;5830.62100963436;4949.579285760715;4953.622261314589;4961.280614310905;4963.677360971526;4991.5258050485145;5623.26897503326;5739.713014349266;5898.616589921641;5951.464368206839;6052.002839063099;3946.441155743465;4306.138772245564;4363.127374525045;5001.349824522788;5118.923216151761;4585.12882552314;4960.70235785854;5195.6622819516615;5210.739463026909;5229.869253268623;4975.736040101745;5086.872828179251;5396.514313711707;5399.433059528771;5434.815192301905;5436.809711660073;5703.830714703476;5729.530965846563;5744.396838331999;5749.1663708762;1576.4471995084832;1619.0043123478977;1689.2060731456195;1723.603571178636;1730.351118330626;1187.2698290551248;1212.2060050666828;1247.189326937498;1291.2446218961825;1411.3416735335938;4248.194517330122;4715.386999690087;5095.949040509621;5102.336696229467;5130.588623347021;5281.5968734225835;5476.91784623231;5647.040003199466;5648.943510564842;5678.943210567924;4660.976695116503;4886.951130488721;4984.5513903748115;5065.657600735808;5072.340595722606;2723.319170207448;2813.338865366637;2956;2974.3640997451316;2981.6571094323995;4924.867280525499;5008.57427118699;5109.923351149736;5274.938297510972;5408.459154084639;4780.498047704941;5434.554450497292;5452.047265732313;5741.1388291756575;5867;5074.82238121664;5165.9483405166975;5215.58275337972;5859.648421094663;5887.735051922674;4650.232711602548;4863.975680121577;5447.618666693294;5456.290682211287;5939.613925094855;5592.524635406036;5736.4550367715;5869.505460179492;5887.322957859729;5926.229590153425;4664.927066774593;5364.704941228114;5673.687947162781;5985.40145985398;6134.325224225421;4131.26486875519;4668.603168730696;4725.740084295397;4743.051389722001;4769.356136921471;4763.928541071956;5640.548756099584;5698.373178950395;5863.185670878088;5935.614185077956;4812.711237325771;5048.646594738333;5525.834224973257;5725.341585717696;5733.684647344436;5723.198752174616;5779.739911703899;5791.594588378773;5815.156802263733;5833.6665333866795;4643.4461046425;5136.948630513796;5416.51251387377;5542.3903370628395;5581.469760372758;5219.2953951216095;5708.286464191976;5714.02861513552;5730.627509212055;5752.7698892043845;4830.7101573905675;5377.838664840061;5496.862578435571;5592.273004509388;5621.690807005654 09:16:17 INFO - PROCESS | 1635 | |5;yui.html;707.837197444588;729.8321386081197;758.3364556013488;819.3608984991705;827.1191181391818;11257;11511.424428778559;12945.741085178292;13719.382627781737;13802.447902083904;11257.831132533018;13238.404271807767;13284.9372050236;13350.933245333785;13472.326383680826;911.9179273865351;922.8292765838341;926.735880274122;927.0358826820099;927.8237134944372;870.1211776106147;909.5997760985179;935.6631612619485;942.4533770413178;951.2627713522006;814.698561532234;977.9413235205875;978.0073225675944;980.8970058143899;985.3152059318776;959.7360725800391;970.0735797313583;976.2531663277598;977.7262366537345;978.6672531339378;720.7549433192687;728.5446595877577;729.4565548666278;731.7658349328217;733.7248531800575;13755.243461609607;14223.359948802225;14317.78523322151;14326.99711020229;14367;12651.430685619169;12937.805932911015;13044.28256445895;13346.399412026385;13382.93308533451;10541.682289713786;10701.625443109531;10724.088452481548;10836.566537338496;11173.72065698364;3572.6427357264324;3637.254362855608;3713.517242758451;3746.737728359016;3797.696184305277;65874.67062664656;68579;68980;69521;69727.65136174287;10255.94872025635;10472.214583906238;10492.4229167395;10502.317349372266;10579.788404231873;21099.683504747132;21242.575148497253;21262.574748504943;21278.148874045175;21316.14735410597;6837.1111755471475;7243.710251589982;7261.491695581259;7343.412526997828;7348.301911318416;3946.901327466779;4050.2709512287483;4144.91710165795;4170.833166673299;4181.811818468176;3237.967620323767;3277.1643230975947;3337.0489410517885;3345.5985281766343;3349.8325083745717;627.5795217204484;630.2972186012563;635.6345101566525;636.5512313818839;638.4349850382445;616.5154427656552;625.3590070178013;630.9274433440121;648.7891435283552;689.8585789913055;9080.40977336471;9212.342711861009;9384.102997464777;9436.952815236018;9538.380005299634;630.854903372227;674.5008693566823;693.6185098195972;694.7776711452286;695.770395769405;4322.891927701769;4387.714798538085;4598.931016034762;4605.171069207575;4681.368015317957;63542.36457635457;65513;66801.33198668048;67262.66368668125;67774.98337524942;57847.8430431389;60607.39392606018;61914;61993.69003154955;62073.689631551555;35313.47029794504;50199.99600008052;50424.49575504198;50595.494045059815;50825.491745082814;8647.394682372174;8664.436811607226;8671.393002489765;8673.522956237468;8794.252488538546;27431.314217144733;28039.719602804525;34501.482477763355;34738.30523389467;35025.423855926034;33700.662993369755;42795.358069629605;43854.561454385046;43860;43920.56079439165;11287.492062857355;11289;11306.547738090385;11597.76804463923;11900.702482438008;11393.430328483708;11530.365829879438;11559.422028898689;11721.472533736172;11873.228240164362;11130.944345278222;11407.600733974272;11434.371109589052;11781.293122412682;11971.580994665144;11195.048420884315;11226.607068752555;11242.269252498561;11269.49287282058;11429.314241145557;10661.177520956426;11211.439428028729;11225.49485273181;11557.942210288895;11673.474693638977;8900.332475064526;8941.910580894108;8969.327300452362;9010.279177665901;9445.716628501155;6682.460664947289;7434.959105725095;8445.704400345958;8830.293576513735;8907.866382004146 09:16:17 INFO - PROCESS | 1635 | __end_tp_report 09:16:17 INFO - PROCESS | 1635 | __start_cc_report 09:16:17 INFO - PROCESS | 1635 | _x_x_mozilla_cycle_collect,417 09:16:17 INFO - PROCESS | 1635 | __end_cc_report 09:16:17 INFO - PROCESS | 1635 | __startTimestamp1488215777026__endTimestamp 09:16:17 INFO - PROCESS | 1635 | 09:16:17 INFO - PROCESS | 1635 | ------- Summary: start ------- 09:16:17 INFO - PROCESS | 1635 | Number of tests: 6 09:16:17 INFO - PROCESS | 1635 | 09:16:17 INFO - PROCESS | 1635 | [#0] dojo.html Cycles:165 Average:5810.98 Median:6091.82 stddev:681.91 (11.2%) stddev-sans-first:646.25 09:16:17 INFO - PROCESS | 1635 | Values: 2958.9 3406.3 3415.6 3468.3 3474.3 4821.4 5745.9 5811.8 5910.3 5948.9 4860.9 5821.3 5961.3 5999.3 6019.2 5311.5 5332.0 5402.7 5433.6 5501.2 5334.0 5417.7 5482.9 5496.5 5535.9 5244.4 5267.6 5438.0 5446.1 5450.8 4636.0 5027.8 5361.2 5369.4 5401.4 5289.6 5325.9 5364.7 5382.1 5409.5 4650.7 4997.7 5711.1 5926.4 6012.5 6229.8 6241.5 6278.9 6302.1 6305.6 6081.2 6275.3 6355.3 6356.0 6409.5 5723.2 6086.7 6134.4 6141.0 6141.8 5072.7 5144.3 6450.9 6475.1 6502.9 6216.4 6263.2 6267.0 6269.9 6272.3 6224.3 6359.4 6371.6 6396.6 6400.6 6145.3 6213.9 6232.1 6278.7 6346.6 5261.9 6237.6 6247.6 6287.7 6315.6 4272.5 5161.8 5187.5 5200.8 5221.5 4560.2 4579.6 4595.8 4628.0 4650.2 5846.6 5904.6 6047.5 6051.4 6073.9 5696.2 5970.0 6110.6 6265.2 6284.5 6005.3 6036.4 6048.0 6064.0 6091.8 4607.0 5625.2 5637.9 5648.7 5661.5 5335.6 6251.8 6420.9 6429.9 6441.8 6318.4 6363.7 6372.4 6383.1 6385.4 6530.9 6531.9 6535.8 6535.9 6538.4 6160.6 6194.8 6243.1 6260.0 6321.8 6254.8 6347.9 6359.7 6376.1 6390.2 6096.2 6149.6 6202.4 6239.7 6258.2 6200.1 6209.8 6219.8 6246.4 6275.8 5515.5 5822.6 6039.5 6107.5 6111.8 6177.0 6208.3 6216.5 6221.9 6232.9 5176.3 6227.6 6239.7 6244.6 6251.9 09:16:17 INFO - PROCESS | 1635 | 09:16:17 INFO - PROCESS | 1635 | [#1] ext.html Cycles:180 Average:45006.07 Median:27944.94 stddev:86300.23 (308.8%) stddev-sans-first:86478.22 09:16:17 INFO - PROCESS | 1635 | Values: 703.1 773.0 795.9 923.6 929.0 36377.8 37282.4 38567.8 38639.2 38742.6 37882.1 38114.6 38177.9 38189.0 38327.0 7611.9 7770.5 10306.2 10513.6 10542.5 10319.6 10338.0 10361.9 10385.0 10407.4 10535.1 10571.7 10583.9 10587.0 10594.9 10492.9 10508.9 10532.9 10540.8 10566.5 10300.0 10390.7 10392.6 10416.8 10484.5 33203.5 33356.2 33404.5 33488.5 33507.7 3157.6 3557.6 3636.0 3646.3 3764.6 24628.5 24633.3 24705.0 24831.0 25488.0 2926.3 2934.8 3025.2 3095.2 3115.0 294301.0 347200.3 349704.0 350764.0 351911.5 42932.4 43072.1 43073.8 43076.0 43252.8 44381.8 44445.8 44493.3 44512.8 44522.7 11560.0 12196.8 12803.7 12828.8 12917.4 10926.6 11044.1 11054.0 11054.5 11100.6 10037.8 11559.7 11664.2 11724.8 11736.4 5444.5 5524.8 5525.2 5526.1 5536.6 931.3 1114.6 1116.8 1117.7 1170.7 24585.8 26569.7 27166.3 28321.2 28459.7 1007.8 1008.2 1042.0 1043.6 1048.2 11889.9 13479.5 14308.9 14357.3 14443.1 176247.1 216458.0 217043.0 217226.0 223682.0 44328.3 44587.0 44670.8 44687.6 44737.9 1136.3 1350.8 1354.5 1371.2 1408.6 24673.0 24750.3 26197.4 26593.7 26759.7 37999.8 38040.6 38158.0 38245.0 38296.6 346953.0 408230.9 411491.0 412781.0 413082.0 19761.6 20669.3 21780.5 21834.2 21985.7 36932.0 37580.0 37610.1 37982.4 38409.8 26066.3 29689.7 30471.7 30923.6 31448.5 26770.6 28490.7 30822.0 30907.2 31691.0 31367.0 31475.1 31498.7 31699.5 32449.5 21203.5 25973.1 27445.5 27568.7 28884.6 22630.9 22693.5 23094.8 23183.7 23376.6 09:16:17 INFO - PROCESS | 1635 | 09:16:17 INFO - PROCESS | 1635 | [#2] jquery.html Cycles:180 Average:39359.30 Median:2918.76 stddev:109607.38 (3755.3%) stddev-sans-first:109875.79 09:16:17 INFO - PROCESS | 1635 | Values: 387.4 389.4 403.9 420.4 429.1 17987.6 19681.5 20137.6 20948.5 20994.0 21100.3 21306.5 21323.0 21642.0 22034.0 6461.5 6831.6 6843.3 6857.3 6862.2 6480.8 6665.1 6672.9 6688.6 6805.3 6659.6 6663.8 6669.5 6676.1 6679.2 6375.0 6602.9 6645.1 6658.9 6673.0 6234.9 6420.6 6424.1 6479.1 6508.9 21426.5 21430.8 25090.5 25548.0 26002.6 2867.0 2916.3 2921.2 2930.2 2951.3 12919.3 14996.2 15262.8 15901.4 16243.0 1924.6 1957.8 1975.7 1978.8 1983.4 152831.0 157599.6 159019.2 161235.0 161585.0 19179.8 19260.7 19373.3 19478.7 19498.0 29731.3 29810.4 29817.7 29853.4 29857.6 8136.9 9300.8 9588.3 9706.9 9860.6 7908.9 8032.7 8052.6 8164.4 8190.2 8926.8 9026.4 9027.8 9105.2 9123.2 3151.5 3192.1 3204.7 3828.6 3851.8 780.9 821.3 840.9 844.2 882.3 19013.0 19453.5 19983.2 20454.9 20456.0 815.2 875.2 896.5 899.4 916.1 8464.4 9192.2 10683.2 11389.1 14794.2 637624.0 654414.0 656602.0 657205.0 657584.0 120176.4 137165.9 142455.0 148759.5 150840.0 805.9 973.1 1014.6 1060.6 1066.3 18298.4 19379.0 19598.5 19620.9 19716.2 45560.0 49970.8 54994.8 58444.0 60075.1 80309.0 82916.6 83895.6 83957.6 83962.2 8560.8 11243.2 11616.8 11734.3 11786.2 11995.3 12499.2 12544.8 12570.5 12770.6 10620.5 11018.4 11102.3 11138.7 11277.4 9085.7 10780.1 10986.2 11068.0 11098.5 11019.2 11133.6 11136.6 11146.2 11467.8 11232.7 11278.8 11518.5 11593.8 11601.6 10314.8 10608.0 10868.7 10925.1 10966.7 09:16:17 INFO - PROCESS | 1635 | 09:16:17 INFO - PROCESS | 1635 | [#3] mootools.html Cycles:180 Average:6355.47 Median:3334.51 stddev:3952.48 (118.5%) stddev-sans-first:3946.89 09:16:17 INFO - PROCESS | 1635 | Values: 1522.5 1523.0 1552.2 1559.8 1565.3 10011.9 10542.6 10549.8 10606.6 10900.0 10502.6 10647.1 10658.5 10666.1 10694.1 2259.0 2304.1 2306.1 2307.6 2311.7 2292.8 2331.0 2335.2 2336.8 2340.1 2281.4 2290.2 2291.2 2313.9 2314.9 2272.3 2274.4 2274.7 2292.7 2293.0 2266.7 2295.4 2309.5 2316.1 2321.1 10715.8 10759.3 10798.2 10818.0 10840.3 5525.1 5655.7 5703.4 5715.3 5716.2 3292.1 3376.9 3532.8 3578.7 3591.2 1129.7 1164.7 1203.9 1210.7 1223.1 12516.2 12964.5 13122.4 14349.2 14377.0 4334.6 4397.8 4755.5 4823.5 4861.3 12362.0 12378.4 12447.9 12466.4 12489.8 5755.9 5804.7 5811.7 5818.9 5830.8 4308.7 5034.5 5149.2 5198.4 5242.2 3222.9 3907.8 3916.3 4007.1 4028.6 1015.5 1106.1 1115.5 1127.8 1130.8 702.1 754.9 891.7 899.2 930.1 5702.3 5972.8 6069.2 6157.5 6244.1 840.8 1012.4 1023.8 1076.1 1109.0 3769.5 3836.8 3862.5 3894.9 3995.3 12395.6 12785.0 12835.4 12840.5 12882.8 12307.3 12364.5 12638.4 12703.6 12782.1 9324.6 9525.8 9595.5 9624.0 9705.3 5109.8 5901.8 5938.5 5952.6 6072.0 10475.7 10612.0 11723.0 11838.6 11993.8 11733.8 11790.3 11960.8 12105.9 12137.9 7792.2 8071.5 8084.8 8085.9 8088.6 9231.4 9346.6 9399.6 9479.6 9487.4 8376.3 8404.6 8429.2 8495.9 8499.2 6850.6 8206.1 8288.7 8390.4 8480.8 6341.6 7521.1 7559.7 7562.4 7588.2 4875.2 5189.3 5243.3 5286.7 5581.3 4753.3 4904.5 5055.9 5364.0 5502.8 09:16:17 INFO - PROCESS | 1635 | 09:16:17 INFO - PROCESS | 1635 | [#4] prototype.html Cycles:180 Average:4745.90 Median:5073.58 stddev:1282.93 (25.3%) stddev-sans-first:1242.84 09:16:17 INFO - PROCESS | 1635 | Values: 323.6 362.3 369.8 378.9 383.9 4375.0 5144.7 5454.5 5496.1 5527.1 4489.5 4779.5 5008.3 5159.4 5361.1 3612.6 4599.4 4685.0 4688.5 4689.6 3706.4 3942.9 4490.8 4728.8 4739.5 3679.3 4614.1 4647.3 4647.7 4658.3 3954.0 4618.4 4643.7 4665.6 4696.8 4288.3 4478.2 4478.4 4482.4 4489.2 3893.7 4440.4 5097.3 5188.7 5241.7 4892.6 4939.0 5020.0 5465.4 5507.1 4918.4 5017.9 5554.0 5730.3 5830.6 4949.6 4953.6 4961.3 4963.7 4991.5 5623.3 5739.7 5898.6 5951.5 6052.0 3946.4 4306.1 4363.1 5001.3 5118.9 4585.1 4960.7 5195.7 5210.7 5229.9 4975.7 5086.9 5396.5 5399.4 5434.8 5436.8 5703.8 5729.5 5744.4 5749.2 1576.4 1619.0 1689.2 1723.6 1730.4 1187.3 1212.2 1247.2 1291.2 1411.3 4248.2 4715.4 5095.9 5102.3 5130.6 5281.6 5476.9 5647.0 5648.9 5678.9 4661.0 4887.0 4984.6 5065.7 5072.3 2723.3 2813.3 2956.0 2974.4 2981.7 4924.9 5008.6 5109.9 5274.9 5408.5 4780.5 5434.6 5452.0 5741.1 5867.0 5074.8 5165.9 5215.6 5859.6 5887.7 4650.2 4864.0 5447.6 5456.3 5939.6 5592.5 5736.5 5869.5 5887.3 5926.2 4664.9 5364.7 5673.7 5985.4 6134.3 4131.3 4668.6 4725.7 4743.1 4769.4 4763.9 5640.5 5698.4 5863.2 5935.6 4812.7 5048.6 5525.8 5725.3 5733.7 5723.2 5779.7 5791.6 5815.2 5833.7 4643.4 5136.9 5416.5 5542.4 5581.5 5219.3 5708.3 5714.0 5730.6 5752.8 4830.7 5377.8 5496.9 5592.3 5621.7 09:16:17 INFO - PROCESS | 1635 | 09:16:17 INFO - PROCESS | 1635 | [#5] yui.html Cycles:180 Average:14842.69 Median:27440.68 stddev:18606.89 (67.8%) stddev-sans-first:18628.82 09:16:17 INFO - PROCESS | 1635 | Values: 707.8 729.8 758.3 819.4 827.1 11257.0 11511.4 12945.7 13719.4 13802.4 11257.8 13238.4 13284.9 13350.9 13472.3 911.9 922.8 926.7 927.0 927.8 870.1 909.6 935.7 942.5 951.3 814.7 977.9 978.0 980.9 985.3 959.7 970.1 976.3 977.7 978.7 720.8 728.5 729.5 731.8 733.7 13755.2 14223.4 14317.8 14327.0 14367.0 12651.4 12937.8 13044.3 13346.4 13382.9 10541.7 10701.6 10724.1 10836.6 11173.7 3572.6 3637.3 3713.5 3746.7 3797.7 65874.7 68579.0 68980.0 69521.0 69727.7 10255.9 10472.2 10492.4 10502.3 10579.8 21099.7 21242.6 21262.6 21278.1 21316.1 6837.1 7243.7 7261.5 7343.4 7348.3 3946.9 4050.3 4144.9 4170.8 4181.8 3238.0 3277.2 3337.0 3345.6 3349.8 627.6 630.3 635.6 636.6 638.4 616.5 625.4 630.9 648.8 689.9 9080.4 9212.3 9384.1 9437.0 9538.4 630.9 674.5 693.6 694.8 695.8 4322.9 4387.7 4598.9 4605.2 4681.4 63542.4 65513.0 66801.3 67262.7 67775.0 57847.8 60607.4 61914.0 61993.7 62073.7 35313.5 50200.0 50424.5 50595.5 50825.5 8647.4 8664.4 8671.4 8673.5 8794.3 27431.3 28039.7 34501.5 34738.3 35025.4 33700.7 42795.4 43854.6 43860.0 43920.6 11287.5 11289.0 11306.5 11597.8 11900.7 11393.4 11530.4 11559.4 11721.5 11873.2 11130.9 11407.6 11434.4 11781.3 11971.6 11195.0 11226.6 11242.3 11269.5 11429.3 10661.2 11211.4 11225.5 11557.9 11673.5 8900.3 8941.9 8969.3 9010.3 9445.7 6682.5 7435.0 8445.7 8830.3 8907.9 09:16:17 INFO - PROCESS | 1635 | -------- Summary: end -------- 09:16:17 INFO - PROCESS | 1635 | 09:16:17 INFO - TEST-INFO | 1635: exit 0 09:16:17 INFO - TEST-OK | dromaeo_css | took 1117374ms 09:16:17 INFO - TEST-START | kraken 09:16:17 INFO - operating with platform_type : mac_ 09:16:17 INFO - Initialising browser for kraken test... 09:16:17 INFO - TEST-INFO | started process 1647 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/tmpVdAg7x/profile http://localhost:49252/getInfo.html) 09:16:20 INFO - PROCESS | 1647 | 2017-02-27 09:16:20.459 plugin-container[1648:10750] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x883f, name = 'com.apple.tsm.portname' 09:16:20 INFO - PROCESS | 1647 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:16:20 INFO - PROCESS | 1647 | 2017-02-27 09:16:20.462 plugin-container[1648:10750] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8c13, name = 'com.apple.CFPasteboardClient' 09:16:20 INFO - PROCESS | 1647 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:16:20 INFO - PROCESS | 1647 | 2017-02-27 09:16:20.462 plugin-container[1648:10750] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:16:20 INFO - PROCESS | 1647 | __metrics Screen width/height:1600/1200 09:16:20 INFO - PROCESS | 1647 | colorDepth:24 09:16:20 INFO - PROCESS | 1647 | Browser inner width/height: 1024/689 09:16:20 INFO - PROCESS | 1647 | __metrics 09:16:20 ERROR - PROCESS | 1647 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3354: TypeError: this.tabs is undefined 09:16:21 INFO - TEST-INFO | 1647: exit 0 09:16:21 INFO - Browser initialized. 09:16:21 INFO - Running cycle 1/1 for kraken test... 09:16:21 INFO - TEST-INFO | started process 1649 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/tmpVdAg7x/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:16:23 INFO - PROCESS | 1649 | 2017-02-27 09:16:23.982 plugin-container[1650:10882] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x434b, name = 'com.apple.tsm.portname' 09:16:23 INFO - PROCESS | 1649 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:16:23 INFO - PROCESS | 1649 | 2017-02-27 09:16:23.983 plugin-container[1650:10882] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9013, name = 'com.apple.CFPasteboardClient' 09:16:23 INFO - PROCESS | 1649 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:16:23 INFO - PROCESS | 1649 | 2017-02-27 09:16:23.983 plugin-container[1650:10882] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:16:33 INFO - PROCESS | 1649 | RSS: Main: 226852864 09:16:33 INFO - PROCESS | 1649 | 09:16:36 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49252/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49252/tests/kraken/driver.html?testName=audio-beat-detection) 09:16:37 INFO - PROCESS | 1649 | RSS: Main: 240558080 09:16:37 INFO - PROCESS | 1649 | 09:16:40 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49252/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49252/tests/kraken/driver.html?testName=audio-dft) 09:16:41 INFO - PROCESS | 1649 | RSS: Main: 248561664 09:16:41 INFO - PROCESS | 1649 | 09:16:44 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49252/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49252/tests/kraken/driver.html?testName=audio-fft) 09:16:45 INFO - PROCESS | 1649 | RSS: Main: 251650048 09:16:45 INFO - PROCESS | 1649 | 09:16:47 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49252/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49252/tests/kraken/driver.html?testName=audio-oscillator) 09:16:48 INFO - PROCESS | 1649 | RSS: Main: 253419520 09:16:48 INFO - PROCESS | 1649 | 09:16:49 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49252/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49252/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:16:50 INFO - PROCESS | 1649 | RSS: Main: 253689856 09:16:50 INFO - PROCESS | 1649 | 09:16:53 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49252/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49252/tests/kraken/driver.html?testName=imaging-darkroom) 09:16:54 INFO - PROCESS | 1649 | RSS: Main: 259182592 09:16:54 INFO - PROCESS | 1649 | 09:16:57 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49252/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49252/tests/kraken/driver.html?testName=imaging-desaturate) 09:16:58 INFO - PROCESS | 1649 | RSS: Main: 256204800 09:16:58 INFO - PROCESS | 1649 | 09:17:01 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49252/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49252/tests/kraken/driver.html?testName=json-parse-financial) 09:17:02 INFO - PROCESS | 1649 | RSS: Main: 257044480 09:17:02 INFO - PROCESS | 1649 | 09:17:04 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49252/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49252/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:17:04 INFO - PROCESS | 1649 | RSS: Main: 257523712 09:17:04 INFO - PROCESS | 1649 | 09:17:06 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49252/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49252/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:17:07 INFO - PROCESS | 1649 | RSS: Main: 257589248 09:17:07 INFO - PROCESS | 1649 | 09:17:09 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49252/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49252/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:17:10 INFO - PROCESS | 1649 | RSS: Main: 257679360 09:17:10 INFO - PROCESS | 1649 | 09:17:13 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49252/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49252/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:17:14 INFO - PROCESS | 1649 | RSS: Main: 257933312 09:17:14 INFO - PROCESS | 1649 | 09:17:17 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49252/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49252/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:17:17 INFO - PROCESS | 1649 | RSS: Main: 257703936 09:17:17 INFO - PROCESS | 1649 | 09:17:19 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49252/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49252/tests/kraken/driver.html?testName=ai-astar) 09:17:19 INFO - PROCESS | 1649 | RSS: Main: 261050368 09:17:19 INFO - PROCESS | 1649 | 09:17:19 INFO - PROCESS | 1649 | __start_tp_report 09:17:19 INFO - PROCESS | 1649 | _x_x_mozilla_page_load 09:17:19 INFO - PROCESS | 1649 | _x_x_mozilla_page_load_details 09:17:19 INFO - PROCESS | 1649 | |i|pagename|runs| 09:17:19 INFO - PROCESS | 1649 | |0;ai-astar;108;121;120;121;119;119;121;120;120;122 09:17:19 INFO - PROCESS | 1649 | |1;audio-beat-detection;164;145;117;191;165;122;117;114;134;116 09:17:19 INFO - PROCESS | 1649 | |2;audio-dft;205;259;264;278;276;200;198;197;194;204 09:17:19 INFO - PROCESS | 1649 | |3;audio-fft;80;58;60;87;69;62;63;82;98;84 09:17:19 INFO - PROCESS | 1649 | |4;audio-oscillator;85;71;86;72;70;70;87;88;88;71 09:17:19 INFO - PROCESS | 1649 | |5;imaging-gaussian-blur;91;90;112;95;135;125;90;90;90;90 09:17:19 INFO - PROCESS | 1649 | |6;imaging-darkroom;96;96;97;130;97;119;155;155;101;97 09:17:19 INFO - PROCESS | 1649 | |7;imaging-desaturate;94;93;93;94;93;93;97;94;95;94 09:17:19 INFO - PROCESS | 1649 | |8;json-parse-financial;65;65;68;85;105;102;78;67;67;68 09:17:19 INFO - PROCESS | 1649 | |9;json-stringify-tinderbox;61;70;84;67;86;57;69;77;93;88 09:17:19 INFO - PROCESS | 1649 | |10;stanford-crypto-aes;85;91;76;79;79;107;109;105;91;90 09:17:19 INFO - PROCESS | 1649 | |11;stanford-crypto-ccm;142;141;178;151;158;172;150;156;211;183 09:17:19 INFO - PROCESS | 1649 | |12;stanford-crypto-pbkdf2;180;176;174;178;177;178;174;174;177;174 09:17:19 INFO - PROCESS | 1649 | |13;stanford-crypto-sha256-iterative;66;69;70;67;73;67;68;67;67;67 09:17:19 INFO - PROCESS | 1649 | __end_tp_report 09:17:19 INFO - PROCESS | 1649 | __start_cc_report 09:17:19 INFO - PROCESS | 1649 | _x_x_mozilla_cycle_collect,1003 09:17:19 INFO - PROCESS | 1649 | __end_cc_report 09:17:19 INFO - PROCESS | 1649 | __startTimestamp1488215839758__endTimestamp 09:17:19 INFO - PROCESS | 1649 | 09:17:19 INFO - PROCESS | 1649 | ------- Summary: start ------- 09:17:19 INFO - PROCESS | 1649 | Number of tests: 14 09:17:19 INFO - PROCESS | 1649 | 09:17:19 INFO - PROCESS | 1649 | [#0] ai-astar Cycles:10 Average:119.10 Median:120.50 stddev:4.01 (3.3%) stddev-sans-first:1.00 09:17:19 INFO - PROCESS | 1649 | Values: 108.0 121.0 120.0 121.0 119.0 119.0 121.0 120.0 120.0 122.0 09:17:19 INFO - PROCESS | 1649 | 09:17:19 INFO - PROCESS | 1649 | [#1] audio-beat-detection Cycles:10 Average:138.50 Median:139.50 stddev:26.82 (19.2%) stddev-sans-first:26.81 09:17:19 INFO - PROCESS | 1649 | Values: 164.0 145.0 117.0 191.0 165.0 122.0 117.0 114.0 134.0 116.0 09:17:19 INFO - PROCESS | 1649 | 09:17:19 INFO - PROCESS | 1649 | [#2] audio-dft Cycles:10 Average:227.50 Median:232.00 stddev:36.46 (15.7%) stddev-sans-first:37.75 09:17:19 INFO - PROCESS | 1649 | Values: 205.0 259.0 264.0 278.0 276.0 200.0 198.0 197.0 194.0 204.0 09:17:19 INFO - PROCESS | 1649 | 09:17:19 INFO - PROCESS | 1649 | [#3] audio-fft Cycles:10 Average:74.30 Median:81.00 stddev:13.69 (16.9%) stddev-sans-first:14.36 09:17:19 INFO - PROCESS | 1649 | Values: 80.0 58.0 60.0 87.0 69.0 62.0 63.0 82.0 98.0 84.0 09:17:19 INFO - PROCESS | 1649 | 09:17:19 INFO - PROCESS | 1649 | [#4] audio-oscillator Cycles:10 Average:78.80 Median:85.50 stddev:8.50 (9.9%) stddev-sans-first:8.71 09:17:19 INFO - PROCESS | 1649 | Values: 85.0 71.0 86.0 72.0 70.0 70.0 87.0 88.0 88.0 71.0 09:17:19 INFO - PROCESS | 1649 | 09:17:19 INFO - PROCESS | 1649 | [#5] imaging-gaussian-blur Cycles:10 Average:100.80 Median:90.00 stddev:16.98 (18.9%) stddev-sans-first:17.63 09:17:19 INFO - PROCESS | 1649 | Values: 91.0 90.0 112.0 95.0 135.0 125.0 90.0 90.0 90.0 90.0 09:17:19 INFO - PROCESS | 1649 | 09:17:19 INFO - PROCESS | 1649 | [#6] imaging-darkroom Cycles:10 Average:114.30 Median:96.00 stddev:24.33 (25.3%) stddev-sans-first:24.88 09:17:19 INFO - PROCESS | 1649 | Values: 96.0 96.0 97.0 130.0 97.0 119.0 155.0 155.0 101.0 97.0 09:17:19 INFO - PROCESS | 1649 | 09:17:19 INFO - PROCESS | 1649 | [#7] imaging-desaturate Cycles:10 Average:94.00 Median:94.00 stddev:1.25 (1.3%) stddev-sans-first:1.32 09:17:19 INFO - PROCESS | 1649 | Values: 94.0 93.0 93.0 94.0 93.0 93.0 97.0 94.0 95.0 94.0 09:17:19 INFO - PROCESS | 1649 | 09:17:19 INFO - PROCESS | 1649 | [#8] json-parse-financial Cycles:10 Average:77.00 Median:67.50 stddev:15.36 (22.8%) stddev-sans-first:15.67 09:17:19 INFO - PROCESS | 1649 | Values: 65.0 65.0 68.0 85.0 105.0 102.0 78.0 67.0 67.0 68.0 09:17:19 INFO - PROCESS | 1649 | 09:17:19 INFO - PROCESS | 1649 | [#9] json-stringify-tinderbox Cycles:10 Average:75.20 Median:80.50 stddev:12.22 (15.2%) stddev-sans-first:11.83 09:17:19 INFO - PROCESS | 1649 | Values: 61.0 70.0 84.0 67.0 86.0 57.0 69.0 77.0 93.0 88.0 09:17:19 INFO - PROCESS | 1649 | 09:17:19 INFO - PROCESS | 1649 | [#10] stanford-crypto-aes Cycles:10 Average:91.20 Median:82.00 stddev:12.14 (14.8%) stddev-sans-first:12.66 09:17:19 INFO - PROCESS | 1649 | Values: 85.0 91.0 76.0 79.0 79.0 107.0 109.0 105.0 91.0 90.0 09:17:19 INFO - PROCESS | 1649 | 09:17:19 INFO - PROCESS | 1649 | [#11] stanford-crypto-ccm Cycles:10 Average:164.20 Median:165.00 stddev:21.88 (13.3%) stddev-sans-first:21.68 09:17:19 INFO - PROCESS | 1649 | Values: 142.0 141.0 178.0 151.0 158.0 172.0 150.0 156.0 211.0 183.0 09:17:19 INFO - PROCESS | 1649 | 09:17:19 INFO - PROCESS | 1649 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:176.20 Median:177.00 stddev:2.15 (1.2%) stddev-sans-first:1.79 09:17:19 INFO - PROCESS | 1649 | Values: 180.0 176.0 174.0 178.0 177.0 178.0 174.0 174.0 177.0 174.0 09:17:19 INFO - PROCESS | 1649 | 09:17:19 INFO - PROCESS | 1649 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.10 Median:67.50 stddev:2.08 (3.1%) stddev-sans-first:2.06 09:17:19 INFO - PROCESS | 1649 | Values: 66.0 69.0 70.0 67.0 73.0 67.0 68.0 67.0 67.0 67.0 09:17:19 INFO - PROCESS | 1649 | -------- Summary: end -------- 09:17:19 INFO - PROCESS | 1649 | 09:17:20 INFO - TEST-INFO | 1649: exit 0 09:17:20 INFO - TEST-OK | kraken | took 63044ms 09:17:20 INFO - SUITE-END | took 1180s 09:17:20 INFO - Completed test suite (00:19:40) 09:17:20 INFO - javascript benchmark 09:17:20 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7295.432660358491, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2958.8816447342106, 3406.301708149831, 3415.590129184498, 3468.3410152071115, 3474.3051389722045, 4821.445533763618, 5745.885082298351, 5811.808579241246, 5910.290765108188, 5948.851278718029, 4860.902781944368, 5821.301443826746, 5961.254843144607, 5999.250093738283, 6019.217501724781, 5311.521963023327, 5332.0, 5402.702851343174, 5433.592480563953, 5501.202325662776, 5334.0, 5417.7291135443265, 5482.91775623366, 5496.477834605706, 5535.916961245584, 5244.39689435716, 5267.578593712494, 5437.972810135963, 5446.101393270105, 5450.754716037788, 4636.0, 5027.818462661308, 5361.249425080492, 5369.3556773187465, 5401.378841433207, 5289.603279754034, 5325.854941187663, 5364.678119312854, 5382.111951527993, 5409.459054094598, 4650.7442090685, 4997.675151115166, 5711.086785926719, 5926.436988486087, 6012.488938440238, 6229.813105606839, 6241.500679945639, 6278.874422511524, 6302.086197501382, 6305.5901366411035, 6081.1790408294755, 6275.341089185661, 6355.269144048401, 6355.983042713144, 6409.519286053518, 5723.168586052507, 6086.726097325631, 6134.447899689049, 6140.986737188403, 6141.815745527641, 5072.721000344943, 5144.279800827887, 6450.935490645127, 6475.061116138134, 6502.934970650327, 6216.44052035319, 6263.154474146023, 6267.0, 6269.905951410732, 6272.3100458949475, 6224.346443623354, 6359.395857393541, 6371.649559274192, 6396.648184349812, 6400.647964362005, 6145.310039739107, 6213.937860621426, 6232.096346029891, 6278.654673992883, 6346.555741098171, 5261.921071183935, 6237.594556353823, 6247.562670613104, 6287.685615719196, 6315.5579109462815, 4272.487301523837, 5161.793528258902, 5187.533122019036, 5200.817971370984, 5221.530062294411, 4560.247559152769, 4579.564941330568, 4595.793189306422, 4628.004978929479, 4650.162970665313, 5846.619969701956, 5904.645721256738, 6047.516198704093, 6051.364606716232, 6073.878522429527, 5696.231008813757, 5969.970150149308, 6110.649890384107, 6265.154204182468, 6284.497240220771, 6005.279366476051, 6036.426539478743, 6047.969760151259, 6063.969680151571, 6091.817245482643, 4606.976965115153, 5625.212470254168, 5637.915431268534, 5648.717564121777, 5661.547076233891, 5335.626506144533, 6251.812445626639, 6420.935790642221, 6429.935700642934, 6441.806745797634, 6318.4313411793155, 6363.681815909279, 6372.36276372359, 6383.1382763326355, 6385.393387628168, 6530.9020364693615, 6531.9020214695865, 6535.823551760729, 6535.934640653532, 6538.411542961157, 6160.599561028519, 6194.81415557534, 6243.125962365181, 6260.029695397221, 6321.810345689637, 6254.8123556293385, 6347.920853454832, 6359.650219237987, 6376.075469056868, 6390.201224846895, 6096.207493025879, 6149.631022138686, 6202.379762023942, 6239.688015599293, 6258.186435763321, 6200.100985357189, 6209.813705588839, 6219.844503887439, 6246.3753624637175, 6275.780347687809, 5515.4760297771645, 5822.5633077518505, 6039.516838652985, 6107.450329470369, 6111.755529778937, 6177.0, 6208.255009398901, 6216.533759968111, 6221.906671399842, 6232.937670623417, 5176.327077479904, 6227.595206311576, 6239.656818875005, 6244.625322480666, 6251.937480625135], "value": 5770.033441568487, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [703.0579024107694, 773.0491495460583, 795.9363250939923, 923.5843870258385, 929.0106037070519, 36377.818110909444, 37282.44076338857, 38567.807160964156, 38639.22721545574, 38742.612573874256, 37882.0529486763, 38114.61885381145, 38177.854664360115, 38189.0, 38327.04182395434, 7611.885821712679, 7770.456068075208, 10306.175505959543, 10513.579456821719, 10542.525586348595, 10319.638812641559, 10338.0, 10361.948190259076, 10385.0, 10407.37555746654, 10535.10451611614, 10571.682849514527, 10583.947080264588, 10586.994235547649, 10594.947025264863, 10492.895071049345, 10508.947455262713, 10532.947335263312, 10540.841887371696, 10566.471676416148, 10300.0, 10390.740231494274, 10392.584296628125, 10416.79166416675, 10484.528196231111, 33203.50194747081, 33356.166095847555, 33404.49679764416, 33488.49767253493, 33507.6649233507, 3157.605299337583, 3557.5908770491465, 3636.0364503406618, 3646.2889736501393, 3764.604716504782, 24628.507429851303, 24633.261002169962, 24705.0, 24831.0, 25488.0, 2926.3415731460464, 2934.838583877886, 3025.2134445044226, 3095.1797773590015, 3115.0, 294301.0, 347200.2639986809, 349704.0, 350764.0, 351911.480885193, 42932.3560146598, 43072.13855722931, 43073.78463107727, 43076.0, 43252.78373608112, 44381.778091109336, 44445.777771111585, 44493.332600011025, 44512.777436112614, 44522.66432007045, 11560.0, 12196.780321967732, 12803.743925121445, 12828.80756788649, 12917.418716157608, 10926.562937482571, 11044.116470682326, 11054.0, 11054.502547385388, 11100.5559777608, 10037.79924401508, 11559.711007224887, 11664.183507154417, 11724.824127638092, 11736.354500502384, 5444.509994100471, 5524.8342549723575, 5525.226468294442, 5526.088195447786, 5536.640118392292, 931.3061768982152, 1114.5820317380983, 1116.776644671069, 1117.6647005898199, 1170.73658426855, 24585.766450704272, 26569.734302657114, 27166.320841979108, 28321.15036548907, 28459.71540284612, 1007.7581380468635, 1008.2186305613062, 1041.958321667125, 1043.5877828257796, 1048.192891473561, 11889.940550297193, 13479.528216512375, 14308.856911430961, 14357.282135893163, 14443.133411995104, 176247.11876440537, 216458.0, 217043.0, 217226.0, 223682.0, 44328.335074973904, 44587.0, 44670.776646117214, 44687.55312446899, 44737.88155296144, 1136.3182090745486, 1350.824392828926, 1354.5394565847462, 1371.2389623758909, 1408.6407965968613, 24673.01307947662, 24750.25749227526, 26197.38026197365, 26593.734062659125, 26759.732402675723, 37999.810000949816, 38040.61959380481, 38158.046048849, 38245.0, 38296.61703382931, 346953.0, 408230.9176908193, 411491.0, 412781.0, 413082.0, 19761.604767904275, 20669.34645228348, 21780.45548861228, 21834.23580174686, 21985.67021494711, 36932.0, 37580.0, 37610.05974850651, 37982.43026354662, 38409.807950960065, 26066.34834129162, 29689.703102969557, 30471.695283046887, 30923.610486442223, 31448.52827207548, 26770.598441023012, 28490.715092848804, 30822.0, 30907.2273193163, 31691.0, 31367.0, 31475.055748327588, 31498.685013149574, 31699.52450713195, 32449.51325730163, 21203.46991325229, 25973.090941816947, 27445.45109097847, 27568.724312756614, 28884.566731498624, 22630.886845565667, 22693.546129077655, 23094.769052309934, 23183.652245216, 23376.6493502601], "value": 16961.42379198332, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [387.38212550981194, 389.4236529935693, 403.8970062634029, 420.4072258116056, 429.14171656686625, 17987.550311242227, 19681.507962300948, 20137.59724805503, 20948.476288092806, 20994.0, 21100.261490847824, 21306.46733831655, 21323.040463179157, 21642.0, 22034.0, 6461.5476916615735, 6831.555948863333, 6843.349881761224, 6857.348551887562, 6862.210845752727, 6480.773172938948, 6665.133532640774, 6672.933270667304, 6688.565243259197, 6805.251422343538, 6659.63372014539, 6663.8334041649105, 6669.5331326807145, 6676.065350850885, 6679.165104361955, 6374.968125159367, 6602.933970660304, 6645.136132302818, 6658.93457046875, 6672.9666351668175, 6234.875302493925, 6420.6147631142285, 6424.1327420798525, 6479.060536222221, 6508.93491065088, 21426.46433839151, 21430.785692143036, 25090.49819003628, 25548.0, 26002.6099608506, 2867.0, 2916.314666053476, 2921.196670915494, 2930.223490774925, 2951.2621844538867, 12919.289439080847, 14996.175210363426, 15262.847371526253, 15901.443449479328, 16243.0, 1924.6054558815408, 1957.8042195780451, 1975.6838905775007, 1978.782333943265, 1983.355409491921, 152831.0, 157599.63600546002, 159019.20490397475, 161235.0, 161585.0, 19179.808201917804, 19260.711089333672, 19373.32193373225, 19478.707819382722, 19498.0, 29731.256718582208, 29810.40379192404, 29817.65820538081, 29853.402931941673, 29857.552136717968, 8136.877946830802, 9300.767480812898, 9588.280878933969, 9706.854397184048, 9860.556274967643, 7908.920910790934, 8032.718854840053, 8052.59737013147, 8164.387670924586, 8190.180981901882, 8926.821463570692, 9026.413283136575, 9027.77430564228, 9105.2260557852, 9123.17891389765, 3151.4642510773224, 3192.1062102611304, 3204.727598154183, 3828.617138286149, 3851.8459261629782, 780.8555417247827, 821.2608652212937, 840.932725381968, 844.2401838345415, 882.3029806452959, 19013.049347532844, 19453.51366215856, 19983.200671972958, 20454.897725511277, 20455.97720114018, 815.2133191470259, 875.2166810704384, 896.5338024227365, 899.3839220134229, 916.1205242966694, 8464.44981076228, 9192.172704456498, 10683.227333087134, 11389.145814063979, 14794.186319752302, 637624.0, 654414.0, 656602.0, 657205.0, 657584.0, 120176.39911800386, 137165.94251086042, 142455.0, 148759.51240487458, 150840.0, 805.9274665280153, 973.0852998181687, 1014.6195176808696, 1060.6075751971666, 1066.317556763672, 18298.359557415428, 19379.0, 19598.510037248612, 19620.920849353424, 19716.21135154578, 45560.0, 49970.75014624903, 54994.800207992834, 58444.0, 60075.09887351606, 80309.0, 82916.58541707252, 83895.58052209699, 83957.58021209855, 83962.16037839543, 8560.828783424422, 11243.156763242952, 11616.767664646828, 11734.295942243492, 11786.233894796811, 11995.280283183038, 12499.187552809402, 12544.811827822408, 12570.497180112692, 12770.553030644254, 10620.52207650673, 11018.39398833072, 11102.333859968428, 11138.665840024812, 11277.379744114152, 9085.727428177166, 10780.137588993019, 10986.230963832455, 11068.0, 11098.50056747432, 11019.228653994143, 11133.610323638633, 11136.554537818396, 11146.219764616724, 11467.770644587228, 11232.66302010941, 11278.830817537579, 11518.481668325114, 11593.768124637629, 11601.593944211912, 10314.845277320695, 10608.0, 10868.728281793019, 10925.07136893373, 10966.670999870017], "value": 10955.888643991284, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1522.451917309769, 1522.9695406091885, 1552.1773460066163, 1559.7504399296108, 1565.2799712132419, 10011.899881001196, 10542.578296868116, 10549.78900421991, 10606.57573697052, 10900.0, 10502.579896804138, 10647.09499692527, 10658.467076646175, 10666.093382062534, 10694.091002264817, 2258.9661155082686, 2304.1474654377903, 2306.08909480755, 2307.596170670126, 2311.7457079721216, 2292.8306563652573, 2330.9510720175904, 2335.1710142899283, 2336.7663233676667, 2340.1224540797202, 2281.4296425893526, 2290.232772021375, 2291.232437133562, 2313.8611683298973, 2314.884255787212, 2272.250157448038, 2274.442761523421, 2274.704288442492, 2292.747797742247, 2292.9541409171725, 2266.6939963104933, 2295.3802473332107, 2309.5034567567886, 2316.0851463671925, 2321.0831721469926, 10715.83926241107, 10759.30064545802, 10798.190135739851, 10818.0, 10840.295380800222, 5525.11598144295, 5655.660660360391, 5703.40114288002, 5715.257016587859, 5716.1711551825165, 3292.111129994912, 3376.9120838405825, 3532.8410221540094, 3578.6958108560802, 3591.174029973121, 1129.6836885672026, 1164.714644912002, 1203.8796120387892, 1210.7033776724759, 1223.0521345956865, 12516.247725318553, 12964.546240881618, 13122.409491572807, 14349.210793406359, 14377.0, 4334.566543345703, 4397.758123303249, 4755.477105181164, 4823.469418363977, 4861.343718597944, 12362.0, 12378.381080946097, 12447.875521244852, 12466.376681166086, 12489.812652810215, 5755.913661295084, 5804.65172089676, 5811.651300921958, 5818.912716309259, 5830.7959221427045, 4308.67684923632, 5034.471380504994, 5149.2276158576515, 5198.428172900978, 5242.1914439518305, 3222.9194270143435, 3907.785071821077, 3916.2950668879307, 4007.138465229931, 4028.576999415078, 1015.5430056474615, 1106.1372129738816, 1115.5203262597, 1127.768807394482, 1130.7625398666373, 702.0872865275122, 754.8565772503207, 891.7057371067662, 899.2221728205055, 930.1349744737324, 5702.287214098238, 5972.790952316648, 6069.180660610849, 6157.538184636171, 6244.063390491372, 840.815020695446, 1012.3571532077184, 1023.7747695506977, 1076.079951641351, 1108.9944550277196, 3769.5228792695716, 3836.7506112102624, 3862.517185351831, 3894.883153505399, 3995.280849447069, 12395.566155184526, 12785.041121915894, 12835.358232088358, 12840.48638054486, 12882.80675789864, 12307.26156430617, 12364.505419783289, 12638.431270592846, 12703.555375561811, 12782.105252632227, 9324.580393882292, 9525.809483810284, 9595.520223988913, 9623.951880240555, 9705.272104592184, 5109.846704598868, 5901.793437229677, 5938.524918006463, 5952.58331916779, 6071.969640151771, 10475.738106547396, 10611.991860773214, 11723.0, 11838.585649502225, 11993.820092698443, 11733.76532469363, 11790.292582445081, 11960.820587691018, 12105.939470302592, 12137.939310303746, 7792.181820908723, 8071.475354102199, 8084.83830323402, 8085.919140808516, 8088.595570221348, 9231.446113233227, 9346.626134954526, 9399.577019034297, 9479.620815167316, 9487.383320084173, 8376.288015518749, 8404.579771011302, 8429.236406853424, 8495.915040849759, 8499.235068843835, 6850.588964662136, 8206.05630352517, 8288.668453261802, 8390.412671112963, 8480.787980300542, 6341.55609107358, 7521.135069466863, 7559.697612095454, 7562.395008399425, 7588.203238660082, 4875.19559272716, 5189.273501709688, 5243.265942768092, 5286.682799032071, 5581.302337207849, 4753.310769938409, 4904.5340692634145, 5055.938252966918, 5364.0, 5502.779888804563], "value": 4885.046511951071, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [323.55511172138307, 362.3079917357847, 369.78737226095, 378.9333027527509, 383.9307528533036, 4374.978125109378, 5144.665596736209, 5454.545454545456, 5496.148097044959, 5527.080898000782, 4489.483709373423, 4779.450363208232, 5008.323876276701, 5159.3808742950805, 5361.051421718059, 3612.602613712496, 4599.3665004646455, 4685.0, 4688.484266730657, 4689.62483001359, 3706.425504046877, 3942.8565715942486, 4490.7754612269255, 4728.810847566093, 4739.507055277577, 3679.2825399047197, 4614.054118905633, 4647.302904564325, 4647.69789963653, 4658.25467925132, 3953.991732108294, 4618.422697162855, 4643.721376717391, 4665.603423708989, 4696.849271928365, 4288.292431748758, 4478.193925093482, 4478.387780399054, 4482.439695038121, 4489.181881338063, 3893.7469064510715, 4440.357986997731, 5097.2608971699265, 5188.7146206958605, 5241.6633758391745, 4892.584130348924, 4938.9753051234875, 5020.0, 5465.398806131322, 5507.0637991541535, 4918.434380046305, 5017.949820501784, 5554.0, 5730.34101078373, 5830.62100963436, 4949.579285760715, 4953.622261314589, 4961.280614310905, 4963.677360971526, 4991.5258050485145, 5623.26897503326, 5739.713014349266, 5898.616589921641, 5951.464368206839, 6052.002839063099, 3946.441155743465, 4306.138772245564, 4363.127374525045, 5001.349824522788, 5118.923216151761, 4585.12882552314, 4960.70235785854, 5195.6622819516615, 5210.739463026909, 5229.869253268623, 4975.736040101745, 5086.872828179251, 5396.514313711707, 5399.433059528771, 5434.815192301905, 5436.809711660073, 5703.830714703476, 5729.530965846563, 5744.396838331999, 5749.1663708762, 1576.4471995084832, 1619.0043123478977, 1689.2060731456195, 1723.603571178636, 1730.351118330626, 1187.2698290551248, 1212.2060050666828, 1247.189326937498, 1291.2446218961825, 1411.3416735335938, 4248.194517330122, 4715.386999690087, 5095.949040509621, 5102.336696229467, 5130.588623347021, 5281.5968734225835, 5476.91784623231, 5647.040003199466, 5648.943510564842, 5678.943210567924, 4660.976695116503, 4886.951130488721, 4984.5513903748115, 5065.657600735808, 5072.340595722606, 2723.319170207448, 2813.338865366637, 2956.0, 2974.3640997451316, 2981.6571094323995, 4924.867280525499, 5008.57427118699, 5109.923351149736, 5274.938297510972, 5408.459154084639, 4780.498047704941, 5434.554450497292, 5452.047265732313, 5741.1388291756575, 5867.0, 5074.82238121664, 5165.9483405166975, 5215.58275337972, 5859.648421094663, 5887.735051922674, 4650.232711602548, 4863.975680121577, 5447.618666693294, 5456.290682211287, 5939.613925094855, 5592.524635406036, 5736.4550367715, 5869.505460179492, 5887.322957859729, 5926.229590153425, 4664.927066774593, 5364.704941228114, 5673.687947162781, 5985.40145985398, 6134.325224225421, 4131.26486875519, 4668.603168730696, 4725.740084295397, 4743.051389722001, 4769.356136921471, 4763.928541071956, 5640.548756099584, 5698.373178950395, 5863.185670878088, 5935.614185077956, 4812.711237325771, 5048.646594738333, 5525.834224973257, 5725.341585717696, 5733.684647344436, 5723.198752174616, 5779.739911703899, 5791.594588378773, 5815.156802263733, 5833.6665333866795, 4643.4461046425, 5136.948630513796, 5416.51251387377, 5542.3903370628395, 5581.469760372758, 5219.2953951216095, 5708.286464191976, 5714.02861513552, 5730.627509212055, 5752.7698892043845, 4830.7101573905675, 5377.838664840061, 5496.862578435571, 5592.273004509388, 5621.690807005654], "value": 4371.225322688049, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [707.837197444588, 729.8321386081197, 758.3364556013488, 819.3608984991705, 827.1191181391818, 11257.0, 11511.424428778559, 12945.741085178292, 13719.382627781737, 13802.447902083904, 11257.831132533018, 13238.404271807767, 13284.9372050236, 13350.933245333785, 13472.326383680826, 911.9179273865351, 922.8292765838341, 926.735880274122, 927.0358826820099, 927.8237134944372, 870.1211776106147, 909.5997760985179, 935.6631612619485, 942.4533770413178, 951.2627713522006, 814.698561532234, 977.9413235205875, 978.0073225675944, 980.8970058143899, 985.3152059318776, 959.7360725800391, 970.0735797313583, 976.2531663277598, 977.7262366537345, 978.6672531339378, 720.7549433192687, 728.5446595877577, 729.4565548666278, 731.7658349328217, 733.7248531800575, 13755.243461609607, 14223.359948802225, 14317.78523322151, 14326.99711020229, 14367.0, 12651.430685619169, 12937.805932911015, 13044.28256445895, 13346.399412026385, 13382.93308533451, 10541.682289713786, 10701.625443109531, 10724.088452481548, 10836.566537338496, 11173.72065698364, 3572.6427357264324, 3637.254362855608, 3713.517242758451, 3746.737728359016, 3797.696184305277, 65874.67062664656, 68579.0, 68980.0, 69521.0, 69727.65136174287, 10255.94872025635, 10472.214583906238, 10492.4229167395, 10502.317349372266, 10579.788404231873, 21099.683504747132, 21242.575148497253, 21262.574748504943, 21278.148874045175, 21316.14735410597, 6837.1111755471475, 7243.710251589982, 7261.491695581259, 7343.412526997828, 7348.301911318416, 3946.901327466779, 4050.2709512287483, 4144.91710165795, 4170.833166673299, 4181.811818468176, 3237.967620323767, 3277.1643230975947, 3337.0489410517885, 3345.5985281766343, 3349.8325083745717, 627.5795217204484, 630.2972186012563, 635.6345101566525, 636.5512313818839, 638.4349850382445, 616.5154427656552, 625.3590070178013, 630.9274433440121, 648.7891435283552, 689.8585789913055, 9080.40977336471, 9212.342711861009, 9384.102997464777, 9436.952815236018, 9538.380005299634, 630.854903372227, 674.5008693566823, 693.6185098195972, 694.7776711452286, 695.770395769405, 4322.891927701769, 4387.714798538085, 4598.931016034762, 4605.171069207575, 4681.368015317957, 63542.36457635457, 65513.0, 66801.33198668048, 67262.66368668125, 67774.98337524942, 57847.8430431389, 60607.39392606018, 61914.0, 61993.69003154955, 62073.689631551555, 35313.47029794504, 50199.99600008052, 50424.49575504198, 50595.494045059815, 50825.491745082814, 8647.394682372174, 8664.436811607226, 8671.393002489765, 8673.522956237468, 8794.252488538546, 27431.314217144733, 28039.719602804525, 34501.482477763355, 34738.30523389467, 35025.423855926034, 33700.662993369755, 42795.358069629605, 43854.561454385046, 43860.0, 43920.56079439165, 11287.492062857355, 11289.0, 11306.547738090385, 11597.76804463923, 11900.702482438008, 11393.430328483708, 11530.365829879438, 11559.422028898689, 11721.472533736172, 11873.228240164362, 11130.944345278222, 11407.600733974272, 11434.371109589052, 11781.293122412682, 11971.580994665144, 11195.048420884315, 11226.607068752555, 11242.269252498561, 11269.49287282058, 11429.314241145557, 10661.177520956426, 11211.439428028729, 11225.49485273181, 11557.942210288895, 11673.474693638977, 8900.332475064526, 8941.910580894108, 8969.327300452362, 9010.279177665901, 9445.716628501155, 6682.460664947289, 7434.959105725095, 8445.704400345958, 8830.293576513735, 8907.866382004146], "value": 6584.285248394624, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1599.1999999999998, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [108.0, 121.0, 120.0, 121.0, 119.0, 119.0, 121.0, 120.0, 120.0, 122.0], "value": 119.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 145.0, 117.0, 191.0, 165.0, 122.0, 117.0, 114.0, 134.0, 116.0], "value": 138.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [205.0, 259.0, 264.0, 278.0, 276.0, 200.0, 198.0, 197.0, 194.0, 204.0], "value": 227.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 58.0, 60.0, 87.0, 69.0, 62.0, 63.0, 82.0, 98.0, 84.0], "value": 74.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 71.0, 86.0, 72.0, 70.0, 70.0, 87.0, 88.0, 88.0, 71.0], "value": 78.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 90.0, 112.0, 95.0, 135.0, 125.0, 90.0, 90.0, 90.0, 90.0], "value": 100.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 96.0, 97.0, 130.0, 97.0, 119.0, 155.0, 155.0, 101.0, 97.0], "value": 114.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 93.0, 93.0, 94.0, 93.0, 93.0, 97.0, 94.0, 95.0, 94.0], "value": 94.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 65.0, 68.0, 85.0, 105.0, 102.0, 78.0, 67.0, 67.0, 68.0], "value": 77.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 70.0, 84.0, 67.0, 86.0, 57.0, 69.0, 77.0, 93.0, 88.0], "value": 75.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 91.0, 76.0, 79.0, 79.0, 107.0, 109.0, 105.0, 91.0, 90.0], "value": 91.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 141.0, 178.0, 151.0, 158.0, 172.0, 150.0, 156.0, 211.0, 183.0], "value": 164.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [180.0, 176.0, 174.0, 178.0, 177.0, 178.0, 174.0, 174.0, 177.0, 174.0], "value": 176.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 69.0, 70.0, 67.0, 73.0, 67.0, 68.0, 67.0, 67.0, 67.0], "value": 68.1, "unit": "score"}]}]} 09:17:21 INFO - Return code: 0 09:17:21 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:17:21 ERROR - # TBPL SUCCESS # 09:17:21 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:17:21 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:17:21 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:17:21 INFO - 2017-02-27 09:17:21.102 screenresolution[1654:11268] starting screenresolution argv=screenresolution get 09:17:21 INFO - 2017-02-27 09:17:21.104 screenresolution[1654:11268] Display 0: 1600x1200x32@60 09:17:21 INFO - 2017-02-27 09:17:21.111 screenresolution[1655:11271] starting screenresolution argv=screenresolution list 09:17:21 INFO - Available Modes on Display 0 09:17:21 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:17:21 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:17:21 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:17:21 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:17:21 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:17:21 INFO - Intel Iris: 09:17:21 INFO - Chipset Model: Intel Iris 09:17:21 INFO - Type: GPU 09:17:21 INFO - Bus: Built-In 09:17:21 INFO - VRAM (Dynamic, Max): 1536 MB 09:17:21 INFO - Vendor: Intel (0x8086) 09:17:21 INFO - Device ID: 0x0a2e 09:17:21 INFO - Revision ID: 0x0009 09:17:21 INFO - Displays: 09:17:21 INFO - Display: 09:17:21 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:17:21 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:17:21 INFO - Display Serial Number: 1600x1200 60 09:17:21 INFO - Main Display: Yes 09:17:21 INFO - Mirror: Off 09:17:21 INFO - Online: Yes 09:17:21 INFO - Rotation: Supported 09:17:21 INFO - Return code: 0 09:17:21 INFO - Running post-action listener: _resource_record_post_action 09:17:21 INFO - [mozharness: 2017-02-27 17:17:21.350549Z] Finished run-tests step (success) 09:17:21 INFO - Running post-run listener: _resource_record_post_run 09:17:21 INFO - Total resource usage - Wall time: 1202s; CPU: 55.0%; Read bytes: 40253952; Write bytes: 518129664; Read time: 386; Write time: 1823 09:17:21 INFO - TinderboxPrint: CPU usage
54.5% 09:17:21 INFO - TinderboxPrint: I/O read bytes / time
40,253,952 / 386 09:17:21 INFO - TinderboxPrint: I/O write bytes / time
518,129,664 / 1,823 09:17:21 INFO - TinderboxPrint: CPU idle
2,186.3 (45.5%) 09:17:21 INFO - TinderboxPrint: CPU system
970.0 (20.2%) 09:17:21 INFO - TinderboxPrint: CPU user
1,653.3 (34.4%) 09:17:21 INFO - TinderboxPrint: Swap in / out
251,092,992 / 0 09:17:21 INFO - install - Wall time: 21s; CPU: 54.0%; Read bytes: 208448000; Write bytes: 204216320; Read time: 16849; Write time: 439 09:17:21 INFO - run-tests - Wall time: 1182s; CPU: 55.0%; Read bytes: 35330560; Write bytes: 302845952; Read time: 351; Write time: 1349 09:17:21 INFO - Running post-run listener: _upload_blobber_files 09:17:21 INFO - Blob upload gear active. 09:17:21 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:17:21 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 09:17:21 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'] 09:17:21 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 09:17:22 INFO - (blobuploader) - INFO - Open directory for files ... 09:17:22 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 09:17:22 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:17:22 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:17:23 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 09:17:23 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:17:23 INFO - (blobuploader) - INFO - Done attempting. 09:17:23 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 09:17:23 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:17:23 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:17:24 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 09:17:24 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:17:24 INFO - (blobuploader) - INFO - Done attempting. 09:17:24 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 09:17:24 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:17:24 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:17:25 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 09:17:25 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:17:25 INFO - (blobuploader) - INFO - Done attempting. 09:17:25 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:17:25 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:17:25 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:17:28 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:17:28 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:17:28 INFO - (blobuploader) - INFO - Done attempting. 09:17:28 INFO - (blobuploader) - INFO - Iteration through files over. 09:17:28 INFO - Return code: 0 09:17:28 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:17:28 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:17:28 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/822a5e80208dc68d7412a6d3a2c7f63fac7d5d3613a554e39fee727a43816e782a0b974d264751855dce754b120d839d5095302f2dcaf277cea8d85aba31204c", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bdfd7c2071529ff03951ed5845be33838737aa017b7c4e4c649cf27d142dc06080c12af7b352e1ac1460dbe93f4ad1de12763cd12b0b221f04c1006d0ec1aeda", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1912bb2a40d6cc610a653bbeaa3d463559f905dcd52948d8f1541f6b1d70a177f2b0364f003f4e99c6016a420a229919078fded50ec5aa5ebd518d80af86f5ab", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5c50b755f5842f8f0512f4e5d3dac8b6e9ef783fdbc097898c3db5a34e8f9ed4f30dc928792551940a8014faaa896e85b3b7340b881f58c3889302cb54f5fd7b"} 09:17:28 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:17:28 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:17:28 INFO - Contents: 09:17:28 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/822a5e80208dc68d7412a6d3a2c7f63fac7d5d3613a554e39fee727a43816e782a0b974d264751855dce754b120d839d5095302f2dcaf277cea8d85aba31204c", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bdfd7c2071529ff03951ed5845be33838737aa017b7c4e4c649cf27d142dc06080c12af7b352e1ac1460dbe93f4ad1de12763cd12b0b221f04c1006d0ec1aeda", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1912bb2a40d6cc610a653bbeaa3d463559f905dcd52948d8f1541f6b1d70a177f2b0364f003f4e99c6016a420a229919078fded50ec5aa5ebd518d80af86f5ab", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5c50b755f5842f8f0512f4e5d3dac8b6e9ef783fdbc097898c3db5a34e8f9ed4f30dc928792551940a8014faaa896e85b3b7340b881f58c3889302cb54f5fd7b"} 09:17:28 INFO - Running post-run listener: copy_logs_to_upload_dir 09:17:28 INFO - Copying logs to upload dir... 09:17:28 INFO - mkdir: /builds/slave/test/build/upload/logs 09:17:28 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1286.785090 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 26 secs) (at 2017-02-27 09:17:28.311499) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-27 09:17:28.315263) ========= 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.ttUlQVpanM/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.qwKIiY0j89/Listeners TMPDIR=/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/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/822a5e80208dc68d7412a6d3a2c7f63fac7d5d3613a554e39fee727a43816e782a0b974d264751855dce754b120d839d5095302f2dcaf277cea8d85aba31204c", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bdfd7c2071529ff03951ed5845be33838737aa017b7c4e4c649cf27d142dc06080c12af7b352e1ac1460dbe93f4ad1de12763cd12b0b221f04c1006d0ec1aeda", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1912bb2a40d6cc610a653bbeaa3d463559f905dcd52948d8f1541f6b1d70a177f2b0364f003f4e99c6016a420a229919078fded50ec5aa5ebd518d80af86f5ab", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5c50b755f5842f8f0512f4e5d3dac8b6e9ef783fdbc097898c3db5a34e8f9ed4f30dc928792551940a8014faaa896e85b3b7340b881f58c3889302cb54f5fd7b"} build_url:https://queue.taskcluster.net/v1/task/DW0cpsYTQ1i0sUdq36hu4g/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010900 build_url: 'https://queue.taskcluster.net/v1/task/DW0cpsYTQ1i0sUdq36hu4g/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/822a5e80208dc68d7412a6d3a2c7f63fac7d5d3613a554e39fee727a43816e782a0b974d264751855dce754b120d839d5095302f2dcaf277cea8d85aba31204c", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bdfd7c2071529ff03951ed5845be33838737aa017b7c4e4c649cf27d142dc06080c12af7b352e1ac1460dbe93f4ad1de12763cd12b0b221f04c1006d0ec1aeda", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1912bb2a40d6cc610a653bbeaa3d463559f905dcd52948d8f1541f6b1d70a177f2b0364f003f4e99c6016a420a229919078fded50ec5aa5ebd518d80af86f5ab", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5c50b755f5842f8f0512f4e5d3dac8b6e9ef783fdbc097898c3db5a34e8f9ed4f30dc928792551940a8014faaa896e85b3b7340b881f58c3889302cb54f5fd7b"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-27 09:17:28.349079) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-27 09:17:28.349398) ========= 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.ttUlQVpanM/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.qwKIiY0j89/Listeners TMPDIR=/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/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.004955 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-27 09:17:28.398749) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-27 09:17:28.399049) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-27 09:17:28.399383) ========= ========= Total master_lag: 0.17 =========