builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0351 starttime: 1485714146.3 results: success (0) buildid: 20170129090243 builduid: 92920da917384210874e87f176c5708a revision: 77af15f468e84d9a5ff659641737343ffa0444cf ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-01-29 10:22:26.295640) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-01-29 10:22:26.296278) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-29 10:22:26.296587) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-29 10:22:26.616225) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-29 10:22:26.616588) ========= 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.6HmhrAIC7u/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.NKnqyClXf7/Listeners TMPDIR=/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/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-01-29 10:22:26-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 7.89M=0.001s 2017-01-29 10:22:27 (7.89 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.661816 ========= master_lag: 0.04 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-29 10:22:27.322846) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-29 10:22:27.323214) ========= 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.6HmhrAIC7u/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.NKnqyClXf7/Listeners TMPDIR=/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/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.021437 ========= master_lag: 0.05 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-29 10:22:27.396550) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-01-29 10:22:27.397675) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 77af15f468e84d9a5ff659641737343ffa0444cf --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 77af15f468e84d9a5ff659641737343ffa0444cf --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.6HmhrAIC7u/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.NKnqyClXf7/Listeners TMPDIR=/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/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-01-29 10:22:27,500 truncating revision to first 12 chars 2017-01-29 10:22:27,500 Setting DEBUG logging. 2017-01-29 10:22:27,500 attempt 1/10 2017-01-29 10:22:27,500 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/77af15f468e8?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-01-29 10:22:29,173 unpacking tar archive at: mozilla-central-77af15f468e8/testing/mozharness/ program finished with exit code 0 elapsedTime=1.940603 ========= master_lag: 0.04 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-01-29 10:22:29.382438) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-29 10:22:29.382708) ========= script_repo_revision: 77af15f468e84d9a5ff659641737343ffa0444cf ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-29 10:22:29.383079) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-29 10:22:29.383337) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-29 10:22:29.418165) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 2 secs) (at 2017-01-29 10:22:29.418421) ========= /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.6HmhrAIC7u/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.NKnqyClXf7/Listeners TMPDIR=/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/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 10:22:29 INFO - MultiFileLogger online at 20170129 10:22:29 in /builds/slave/test 10:22:29 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 10:22:29 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 10:22:29 INFO - {'append_to_log': False, 10:22:29 INFO - 'base_work_dir': '/builds/slave/test', 10:22:29 INFO - 'blob_upload_branch': 'Firefox', 10:22:29 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 10:22:29 INFO - 'branch': 'Firefox', 10:22:29 INFO - 'buildbot_json_path': 'buildprops.json', 10:22:29 INFO - 'config_files': ('talos/mac_config.py',), 10:22:29 INFO - 'default_actions': ('clobber', 10:22:29 INFO - 'read-buildbot-config', 10:22:29 INFO - 'download-and-extract', 10:22:29 INFO - 'populate-webroot', 10:22:29 INFO - 'create-virtualenv', 10:22:29 INFO - 'install', 10:22:29 INFO - 'run-tests'), 10:22:29 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 10:22:29 INFO - 'download_minidump_stackwalk': True, 10:22:29 INFO - 'download_symbols': 'ondemand', 10:22:29 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 10:22:29 INFO - 'tooltool.py': '/tools/tooltool.py', 10:22:29 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 10:22:29 INFO - '/tools/misc-python/virtualenv.py')}, 10:22:29 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 10:22:29 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 10:22:29 INFO - 'installer_path': 'installer.exe', 10:22:29 INFO - 'log_level': 'info', 10:22:29 INFO - 'log_name': 'talos', 10:22:29 INFO - 'log_to_console': True, 10:22:29 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 10:22:29 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 10:22:29 INFO - 'opt_config_files': (), 10:22:29 INFO - 'pip_index': False, 10:22:29 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 10:22:29 INFO - 'cmd': ('bash', 10:22:29 INFO - '-c', 10:22:29 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 10:22:29 INFO - 'enabled': True, 10:22:29 INFO - 'halt_on_failure': False, 10:22:29 INFO - 'name': 'check_screen_resolution'},), 10:22:29 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 10:22:29 INFO - 'cmd': ('bash', 10:22:29 INFO - '-c', 10:22:29 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 10:22:29 INFO - 'enabled': True, 10:22:29 INFO - 'halt_on_failure': False, 10:22:29 INFO - 'name': 'check_screen_resolution'},), 10:22:29 INFO - 'run_cmd_checks_enabled': True, 10:22:29 INFO - 'sps_profile': False, 10:22:29 INFO - 'sps_profile_interval': 0, 10:22:29 INFO - 'suite': 'dromaeojs-e10s', 10:22:29 INFO - 'system_bits': '32', 10:22:29 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 10:22:29 INFO - 'title': 't-yosemite-r7-0351', 10:22:29 INFO - 'tooltool_cache': '/builds/tooltool_cache', 10:22:29 INFO - 'use_talos_json': True, 10:22:29 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 10:22:29 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 10:22:29 INFO - 'work_dir': 'build'} 10:22:29 INFO - [mozharness: 2017-01-29 18:22:29.638629Z] Running clobber step. 10:22:29 INFO - Running pre-action listener: _resource_record_pre_action 10:22:29 INFO - Running main action method: clobber 10:22:29 INFO - rmtree: /builds/slave/test/build 10:22:29 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 10:22:31 INFO - Running post-action listener: _resource_record_post_action 10:22:31 INFO - [mozharness: 2017-01-29 18:22:31.515731Z] Finished clobber step (success) 10:22:31 INFO - [mozharness: 2017-01-29 18:22:31.515916Z] Running read-buildbot-config step. 10:22:31 INFO - Running pre-action listener: _resource_record_pre_action 10:22:31 INFO - Running main action method: read_buildbot_config 10:22:31 INFO - Using buildbot properties: 10:22:31 INFO - { 10:22:31 INFO - "project": "", 10:22:31 INFO - "product": "firefox", 10:22:31 INFO - "script_repo_revision": "production", 10:22:31 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 10:22:31 INFO - "repository": "", 10:22:31 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 10:22:31 INFO - "buildid": "20170129090243", 10:22:31 INFO - "pgo_build": "False", 10:22:31 INFO - "basedir": "/builds/slave/test", 10:22:31 INFO - "buildnumber": 76, 10:22:31 INFO - "slavename": "t-yosemite-r7-0351", 10:22:31 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 10:22:31 INFO - "platform": "yosemite_r7", 10:22:31 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 10:22:31 INFO - "branch": "mozilla-central", 10:22:31 INFO - "repo_path": "mozilla-central", 10:22:31 INFO - "revision": "77af15f468e84d9a5ff659641737343ffa0444cf", 10:22:31 INFO - "stage_platform": "macosx64", 10:22:31 INFO - "builduid": "92920da917384210874e87f176c5708a", 10:22:31 INFO - "slavebuilddir": "test" 10:22:31 INFO - } 10:22:31 INFO - Found installer url https://queue.taskcluster.net/v1/task/F_PIFeKGQqevudDkfWvfsw/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg. 10:22:31 INFO - Running post-action listener: _resource_record_post_action 10:22:31 INFO - [mozharness: 2017-01-29 18:22:31.519269Z] Finished read-buildbot-config step (success) 10:22:31 INFO - [mozharness: 2017-01-29 18:22:31.519359Z] Running download-and-extract step. 10:22:31 INFO - Running pre-action listener: _resource_record_pre_action 10:22:31 INFO - Running main action method: download_and_extract 10:22:31 INFO - mkdir: /builds/slave/test/build/tests 10:22:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:22:31 INFO - trying https://queue.taskcluster.net/v1/task/F_PIFeKGQqevudDkfWvfsw/artifacts/public/build/firefox-54.0a1.en-US.mac.test_packages.json 10:22:31 INFO - Downloading https://queue.taskcluster.net/v1/task/F_PIFeKGQqevudDkfWvfsw/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 10:22:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/F_PIFeKGQqevudDkfWvfsw/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 10:22:32 INFO - Downloaded 1199 bytes. 10:22:32 INFO - Reading from file /builds/slave/test/build/firefox-54.0a1.en-US.mac.test_packages.json 10:22:32 INFO - Using the following test package requirements: 10:22:32 INFO - {u'common': [u'firefox-54.0a1.en-US.mac.common.tests.zip'], 10:22:32 INFO - u'cppunittest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 10:22:32 INFO - u'firefox-54.0a1.en-US.mac.cppunittest.tests.zip'], 10:22:32 INFO - u'gtest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 10:22:32 INFO - u'firefox-54.0a1.en-US.mac.gtest.tests.zip'], 10:22:32 INFO - u'jittest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 10:22:32 INFO - u'jsshell-mac.zip'], 10:22:32 INFO - u'mochitest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 10:22:32 INFO - u'firefox-54.0a1.en-US.mac.mochitest.tests.zip'], 10:22:32 INFO - u'mozbase': [u'firefox-54.0a1.en-US.mac.common.tests.zip'], 10:22:32 INFO - u'reftest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 10:22:32 INFO - u'firefox-54.0a1.en-US.mac.reftest.tests.zip'], 10:22:32 INFO - u'talos': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 10:22:32 INFO - u'firefox-54.0a1.en-US.mac.talos.tests.zip'], 10:22:32 INFO - u'web-platform': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 10:22:32 INFO - u'firefox-54.0a1.en-US.mac.web-platform.tests.zip'], 10:22:32 INFO - u'xpcshell': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 10:22:32 INFO - u'firefox-54.0a1.en-US.mac.xpcshell.tests.zip']} 10:22:32 INFO - Downloading packages: [u'firefox-54.0a1.en-US.mac.common.tests.zip'] for test suite category: common 10:22:32 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/F_PIFeKGQqevudDkfWvfsw/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip 10:22:32 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/F_PIFeKGQqevudDkfWvfsw/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip'}, attempt #1 10:22:32 INFO - Fetch https://queue.taskcluster.net/v1/task/F_PIFeKGQqevudDkfWvfsw/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip into memory 10:22:33 INFO - Expected file size: 16364573 10:22:33 INFO - Obtained file size: 16364573 10:22:40 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 10:22:40 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/F_PIFeKGQqevudDkfWvfsw/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip 10:22:40 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/F_PIFeKGQqevudDkfWvfsw/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip'}, attempt #1 10:22:40 INFO - Fetch https://queue.taskcluster.net/v1/task/F_PIFeKGQqevudDkfWvfsw/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip into memory 10:22:41 INFO - Expected file size: 16364573 10:22:41 INFO - Obtained file size: 16364573 10:22:47 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/F_PIFeKGQqevudDkfWvfsw/artifacts/public/build/firefox-54.0a1.en-US.mac.talos.tests.zip 10:22:47 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/F_PIFeKGQqevudDkfWvfsw/artifacts/public/build/firefox-54.0a1.en-US.mac.talos.tests.zip'}, attempt #1 10:22:47 INFO - Fetch https://queue.taskcluster.net/v1/task/F_PIFeKGQqevudDkfWvfsw/artifacts/public/build/firefox-54.0a1.en-US.mac.talos.tests.zip into memory 10:22:49 INFO - Expected file size: 14403454 10:22:49 INFO - Obtained file size: 14403454 10:22: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')]} 10:22:49 INFO - trying https://queue.taskcluster.net/v1/task/F_PIFeKGQqevudDkfWvfsw/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg 10:22:49 INFO - Downloading https://queue.taskcluster.net/v1/task/F_PIFeKGQqevudDkfWvfsw/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-54.0a1.en-US.mac.dmg 10:22:49 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/F_PIFeKGQqevudDkfWvfsw/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 10:22:51 INFO - Downloaded 70155787 bytes. 10:22:51 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/F_PIFeKGQqevudDkfWvfsw/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg 10:22:51 INFO - mkdir: /builds/slave/test/properties 10:22:51 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 10:22:51 INFO - Writing to file /builds/slave/test/properties/build_url 10:22:51 INFO - Contents: 10:22:51 INFO - build_url:https://queue.taskcluster.net/v1/task/F_PIFeKGQqevudDkfWvfsw/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg 10:22:52 INFO - Running post-action listener: _resource_record_post_action 10:22:52 INFO - Running post-action listener: set_extra_try_arguments 10:22:52 INFO - [mozharness: 2017-01-29 18:22:52.455765Z] Finished download-and-extract step (success) 10:22:52 INFO - [mozharness: 2017-01-29 18:22:52.455905Z] Running populate-webroot step. 10:22:52 INFO - Running pre-action listener: _resource_record_pre_action 10:22:52 INFO - Running main action method: populate_webroot 10:22:52 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 10:22:52 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 10:22:52 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 10:22:52 INFO - '--tppagecycles', 10:22:52 INFO - '7'], 10:22:52 INFO - 'tests': ['tsvgm']}}, 10:22:52 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 10:22:52 INFO - 'tests': ['tresize', 'tcanvasmark']}, 10:22:52 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 10:22:52 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 10:22:52 INFO - 'tests': ['dromaeo_css', 'kraken']}, 10:22:52 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 10:22:52 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 10:22:52 INFO - 'talos_options': ['--disable-e10s'], 10:22:52 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:22:52 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 10:22:52 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:22:52 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 10:22:52 INFO - 'talos_options': ['--disable-e10s'], 10:22:52 INFO - 'tests': ['damp', 'tps']}, 10:22:52 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 10:22:52 INFO - 'tests': ['damp', 'tps']}, 10:22:52 INFO - 'g3': {'talos_options': ['--disable-e10s'], 10:22:52 INFO - 'tests': ['dromaeo_dom']}, 10:22:52 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 10:22:52 INFO - 'g4': {'talos_options': ['--disable-e10s'], 10:22:52 INFO - 'tests': ['basic_compositor_video']}, 10:22:52 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 10:22:52 INFO - 'other': {'talos_options': ['--disable-e10s'], 10:22:52 INFO - 'tests': ['a11yr', 10:22:52 INFO - 'ts_paint', 10:22:52 INFO - 'tpaint', 10:22:52 INFO - 'sessionrestore', 10:22:52 INFO - 'sessionrestore_no_auto_restore', 10:22:52 INFO - 'tabpaint']}, 10:22:52 INFO - 'other-e10s': {'tests': ['a11yr', 10:22:52 INFO - 'ts_paint', 10:22:52 INFO - 'tpaint', 10:22:52 INFO - 'sessionrestore', 10:22:52 INFO - 'sessionrestore_no_auto_restore', 10:22:52 INFO - 'tabpaint']}, 10:22:52 INFO - 'other-e10s_l64': {'tests': ['a11yr', 10:22:52 INFO - 'ts_paint', 10:22:52 INFO - 'tpaint', 10:22:52 INFO - 'sessionrestore', 10:22:52 INFO - 'sessionrestore_no_auto_restore', 10:22:52 INFO - 'tabpaint']}, 10:22:52 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 10:22:52 INFO - 'ts_paint', 10:22:52 INFO - 'tpaint', 10:22:52 INFO - 'sessionrestore', 10:22:52 INFO - 'sessionrestore_no_auto_restore', 10:22:52 INFO - 'tabpaint']}, 10:22:52 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 10:22:52 INFO - 'tests': ['a11yr', 10:22:52 INFO - 'ts_paint', 10:22:52 INFO - 'tpaint', 10:22:52 INFO - 'sessionrestore', 10:22:52 INFO - 'sessionrestore_no_auto_restore', 10:22:52 INFO - 'tabpaint']}, 10:22:52 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 10:22:52 INFO - 'tests': ['a11yr', 10:22:52 INFO - 'ts_paint', 10:22:52 INFO - 'tpaint', 10:22:52 INFO - 'sessionrestore', 10:22:52 INFO - 'sessionrestore_no_auto_restore', 10:22:52 INFO - 'tabpaint']}, 10:22:52 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 10:22:52 INFO - 'tests': ['tsvgx', 10:22:52 INFO - 'tsvgr_opacity', 10:22:52 INFO - 'tart', 10:22:52 INFO - 'tscrollx', 10:22:52 INFO - 'cart', 10:22:52 INFO - 'tsvg_static']}, 10:22:52 INFO - 'svgr-e10s': {'tests': ['tsvgx', 10:22:52 INFO - 'tsvgr_opacity', 10:22:52 INFO - 'tart', 10:22:52 INFO - 'tscrollx', 10:22:52 INFO - 'cart', 10:22:52 INFO - 'tsvg_static']}, 10:22:52 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 10:22:52 INFO - 'talos_options': ['--disable-e10s'], 10:22:52 INFO - 'tests': ['tp5o']}, 10:22:52 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 10:22:52 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 10:22:52 INFO - 'talos_options': ['--disable-e10s', 10:22:52 INFO - '--xperf_path', 10:22:52 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:22:52 INFO - 'tests': ['tp5n']}, 10:22:52 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 10:22:52 INFO - 'talos_options': ['--xperf_path', 10:22:52 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:22:52 INFO - 'tests': ['tp5n']}}, 10:22:52 INFO - 'talos.zip': {'path': '', 10:22:52 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 10:22:52 INFO - Running post-action listener: _resource_record_post_action 10:22:52 INFO - [mozharness: 2017-01-29 18:22:52.474894Z] Finished populate-webroot step (success) 10:22:52 INFO - [mozharness: 2017-01-29 18:22:52.475004Z] Running create-virtualenv step. 10:22:52 INFO - Running pre-action listener: _resource_record_pre_action 10:22:52 INFO - Running main action method: create_virtualenv 10:22:52 INFO - Creating virtualenv /builds/slave/test/build/venv 10:22:52 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 10:22:52 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 10:22:52 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 10:22:52 INFO - Using real prefix '/tools/python27' 10:22:52 INFO - New python executable in /builds/slave/test/build/venv/bin/python 10:22:53 INFO - Installing distribute.............................................................................................................................................................................................done. 10:22:56 INFO - Installing pip.................done. 10:22:56 INFO - Return code: 0 10:22:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 10:22:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 10:22:56 INFO - Reading from file tmpfile_stdout 10:22:56 INFO - Output received: 10:22:56 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) 10:22:56 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 10:22:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:22:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:22:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:22:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:22:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:22:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:22:56 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x102968b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10296b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa970d56850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102746d30>, '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 0x10266ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10273bbe8>, '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.6HmhrAIC7u/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.NKnqyClXf7/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/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 10:22:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 10:22:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 10:22:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6HmhrAIC7u/Render', 10:22:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:22:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:22:56 INFO - 'HOME': '/Users/cltbld', 10:22:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:22:56 INFO - 'LOGNAME': 'cltbld', 10:22:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:22:56 INFO - 'MOZ_NO_REMOTE': '1', 10:22:56 INFO - 'NO_EM_RESTART': '1', 10:22:56 INFO - 'PAGER': '/bin/cat', 10:22:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:22:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:22:56 INFO - 'PWD': '/builds/slave/test', 10:22:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:22:56 INFO - 'SHELL': '/bin/bash', 10:22:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NKnqyClXf7/Listeners', 10:22:56 INFO - 'TMPDIR': '/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/', 10:22:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:22:56 INFO - 'USER': 'cltbld', 10:22:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:22:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:22:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:22:56 INFO - 'XPC_FLAGS': '0x0', 10:22:56 INFO - 'XPC_SERVICE_NAME': '0', 10:22:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:22:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:22:56 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 10:22:56 INFO - Cleaning up... 10:22:56 INFO - Return code: 0 10:22:56 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 10:22:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:22:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:22:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:22:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:22:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:22:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:22:56 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x102968b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10296b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa970d56850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102746d30>, '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 0x10266ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10273bbe8>, '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.6HmhrAIC7u/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.NKnqyClXf7/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/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 10:22:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 10:22:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 10:22:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6HmhrAIC7u/Render', 10:22:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:22:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:22:56 INFO - 'HOME': '/Users/cltbld', 10:22:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:22:56 INFO - 'LOGNAME': 'cltbld', 10:22:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:22:56 INFO - 'MOZ_NO_REMOTE': '1', 10:22:56 INFO - 'NO_EM_RESTART': '1', 10:22:56 INFO - 'PAGER': '/bin/cat', 10:22:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:22:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:22:56 INFO - 'PWD': '/builds/slave/test', 10:22:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:22:56 INFO - 'SHELL': '/bin/bash', 10:22:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NKnqyClXf7/Listeners', 10:22:56 INFO - 'TMPDIR': '/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/', 10:22:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:22:56 INFO - 'USER': 'cltbld', 10:22:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:22:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:22:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:22:56 INFO - 'XPC_FLAGS': '0x0', 10:22:56 INFO - 'XPC_SERVICE_NAME': '0', 10:22:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:22:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:22:56 INFO - Downloading/unpacking psutil>=3.1.1 10:22:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:22:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:22:56 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:22:56 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:22:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:22:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:23:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 10:23:01 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:23:01 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:23:01 INFO - Installing collected packages: psutil 10:23:01 INFO - Running setup.py install for psutil 10:23:01 INFO - building 'psutil._psutil_osx' extension 10:23:01 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 10:23:01 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 10:23:01 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 10:23:01 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 10:23:01 INFO - building 'psutil._psutil_posix' extension 10:23:01 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 10:23:01 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 10:23:01 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 10:23:01 INFO - ^ 10:23:01 INFO - 1 warning generated. 10:23:01 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 10:23:01 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:23:01 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:23:01 INFO - Successfully installed psutil 10:23:01 INFO - Cleaning up... 10:23:01 INFO - Return code: 0 10:23:01 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 10:23:01 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')]} 10:23:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:23:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:23:01 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')]} 10:23:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:23:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:23:01 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 0x102968b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10296b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa970d56850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102746d30>, '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 0x10266ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10273bbe8>, '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.6HmhrAIC7u/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.NKnqyClXf7/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/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 10:23:01 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 10:23:01 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 10:23:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6HmhrAIC7u/Render', 10:23:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:23:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:23:01 INFO - 'HOME': '/Users/cltbld', 10:23:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:23:01 INFO - 'LOGNAME': 'cltbld', 10:23:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:23:01 INFO - 'MOZ_NO_REMOTE': '1', 10:23:01 INFO - 'NO_EM_RESTART': '1', 10:23:01 INFO - 'PAGER': '/bin/cat', 10:23:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:23:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:23:01 INFO - 'PWD': '/builds/slave/test', 10:23:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:23:01 INFO - 'SHELL': '/bin/bash', 10:23:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NKnqyClXf7/Listeners', 10:23:01 INFO - 'TMPDIR': '/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/', 10:23:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:23:01 INFO - 'USER': 'cltbld', 10:23:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:23:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:23:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:23:01 INFO - 'XPC_FLAGS': '0x0', 10:23:01 INFO - 'XPC_SERVICE_NAME': '0', 10:23:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:23:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:23:02 INFO - Downloading/unpacking mozsystemmonitor==0.3 10:23:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:23:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:23:02 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:23:02 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:23:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:23:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:23:04 INFO - Downloading mozsystemmonitor-0.3.tar.gz 10:23:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 10:23:04 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 10:23:04 INFO - Installing collected packages: mozsystemmonitor 10:23:04 INFO - Running setup.py install for mozsystemmonitor 10:23:05 INFO - Successfully installed mozsystemmonitor 10:23:05 INFO - Cleaning up... 10:23:05 INFO - Return code: 0 10:23:05 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 10:23:05 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')]} 10:23:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:23:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:23:05 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')]} 10:23:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:23:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:23:05 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 0x102968b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10296b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa970d56850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102746d30>, '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 0x10266ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10273bbe8>, '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.6HmhrAIC7u/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.NKnqyClXf7/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/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 10:23:05 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 10:23:05 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 10:23:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6HmhrAIC7u/Render', 10:23:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:23:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:23:05 INFO - 'HOME': '/Users/cltbld', 10:23:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:23:05 INFO - 'LOGNAME': 'cltbld', 10:23:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:23:05 INFO - 'MOZ_NO_REMOTE': '1', 10:23:05 INFO - 'NO_EM_RESTART': '1', 10:23:05 INFO - 'PAGER': '/bin/cat', 10:23:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:23:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:23:05 INFO - 'PWD': '/builds/slave/test', 10:23:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:23:05 INFO - 'SHELL': '/bin/bash', 10:23:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NKnqyClXf7/Listeners', 10:23:05 INFO - 'TMPDIR': '/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/', 10:23:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:23:05 INFO - 'USER': 'cltbld', 10:23:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:23:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:23:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:23:05 INFO - 'XPC_FLAGS': '0x0', 10:23:05 INFO - 'XPC_SERVICE_NAME': '0', 10:23:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:23:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:23:05 INFO - Downloading/unpacking jsonschema==2.5.1 10:23: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 10:23: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 10:23: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 10:23: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 10:23: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 10:23: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 10:23:07 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 10:23:08 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 10:23:08 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 10:23:08 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 10:23:08 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 10:23:08 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 10:23:08 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 10:23:08 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 10:23:08 INFO - Downloading functools32-3.2.3-2.tar.gz 10:23:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 10:23:08 INFO - warning: no files found matching '*.txt' 10:23:08 INFO - no previously-included directories found matching 'build' 10:23:08 INFO - no previously-included directories found matching 'dist' 10:23:08 INFO - no previously-included directories found matching '.git*' 10:23:08 INFO - Installing collected packages: jsonschema, functools32 10:23:08 INFO - Running setup.py install for functools32 10:23:08 INFO - warning: no files found matching '*.txt' 10:23:08 INFO - no previously-included directories found matching 'build' 10:23:08 INFO - no previously-included directories found matching 'dist' 10:23:08 INFO - no previously-included directories found matching '.git*' 10:23:08 INFO - Successfully installed jsonschema functools32 10:23:08 INFO - Cleaning up... 10:23:08 INFO - Return code: 0 10:23:08 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 10:23:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:23:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:23:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:23:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:23:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:23:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:23:08 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 0x102968b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10296b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa970d56850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102746d30>, '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 0x10266ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10273bbe8>, '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.6HmhrAIC7u/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.NKnqyClXf7/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/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 10:23:08 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 10:23:08 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 10:23:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6HmhrAIC7u/Render', 10:23:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:23:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:23:08 INFO - 'HOME': '/Users/cltbld', 10:23:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:23:08 INFO - 'LOGNAME': 'cltbld', 10:23:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:23:08 INFO - 'MOZ_NO_REMOTE': '1', 10:23:08 INFO - 'NO_EM_RESTART': '1', 10:23:08 INFO - 'PAGER': '/bin/cat', 10:23:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:23:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:23:08 INFO - 'PWD': '/builds/slave/test', 10:23:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:23:08 INFO - 'SHELL': '/bin/bash', 10:23:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NKnqyClXf7/Listeners', 10:23:08 INFO - 'TMPDIR': '/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/', 10:23:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:23:08 INFO - 'USER': 'cltbld', 10:23:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:23:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:23:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:23:08 INFO - 'XPC_FLAGS': '0x0', 10:23:08 INFO - 'XPC_SERVICE_NAME': '0', 10:23:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:23:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:23:09 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 10:23:09 INFO - Cleaning up... 10:23:09 INFO - Return code: 0 10:23:09 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 10:23:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:23:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:23:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:23:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:23:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:23:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:23:09 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 0x102968b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10296b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa970d56850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102746d30>, '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 0x10266ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10273bbe8>, '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.6HmhrAIC7u/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.NKnqyClXf7/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/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 10:23:09 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 10:23:09 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 10:23:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6HmhrAIC7u/Render', 10:23:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:23:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:23:09 INFO - 'HOME': '/Users/cltbld', 10:23:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:23:09 INFO - 'LOGNAME': 'cltbld', 10:23:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:23:09 INFO - 'MOZ_NO_REMOTE': '1', 10:23:09 INFO - 'NO_EM_RESTART': '1', 10:23:09 INFO - 'PAGER': '/bin/cat', 10:23:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:23:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:23:09 INFO - 'PWD': '/builds/slave/test', 10:23:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:23:09 INFO - 'SHELL': '/bin/bash', 10:23:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NKnqyClXf7/Listeners', 10:23:09 INFO - 'TMPDIR': '/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/', 10:23:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:23:09 INFO - 'USER': 'cltbld', 10:23:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:23:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:23:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:23:09 INFO - 'XPC_FLAGS': '0x0', 10:23:09 INFO - 'XPC_SERVICE_NAME': '0', 10:23:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:23:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:23:09 INFO - Downloading/unpacking blobuploader==1.2.4 10:23:09 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 10:23:09 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 10:23:09 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 10:23:09 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 10:23:09 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 10:23:09 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 10:23:11 INFO - Downloading blobuploader-1.2.4.tar.gz 10:23:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 10:23:12 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 10:23:12 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 10:23:12 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 10:23:12 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 10:23:12 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 10:23:12 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 10:23:12 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 10:23:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 10:23:12 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 10:23:12 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 10:23:12 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 10:23:12 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 10:23:12 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 10:23:12 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 10:23:12 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 10:23:13 INFO - Downloading docopt-0.6.1.tar.gz 10:23:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 10:23:13 INFO - Installing collected packages: blobuploader, requests, docopt 10:23:13 INFO - Running setup.py install for blobuploader 10:23:13 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 10:23:13 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 10:23:13 INFO - Running setup.py install for requests 10:23:14 INFO - Running setup.py install for docopt 10:23:14 INFO - Successfully installed blobuploader requests docopt 10:23:14 INFO - Cleaning up... 10:23:14 INFO - Return code: 0 10:23:14 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:23:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:23:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:23:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:23:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:23:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:23:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:23:14 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 0x102968b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10296b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa970d56850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102746d30>, '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 0x10266ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10273bbe8>, '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.6HmhrAIC7u/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.NKnqyClXf7/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/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 10:23:14 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 10:23:14 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 10:23:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6HmhrAIC7u/Render', 10:23:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:23:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:23:14 INFO - 'HOME': '/Users/cltbld', 10:23:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:23:14 INFO - 'LOGNAME': 'cltbld', 10:23:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:23:14 INFO - 'MOZ_NO_REMOTE': '1', 10:23:14 INFO - 'NO_EM_RESTART': '1', 10:23:14 INFO - 'PAGER': '/bin/cat', 10:23:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:23:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:23:14 INFO - 'PWD': '/builds/slave/test', 10:23:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:23:14 INFO - 'SHELL': '/bin/bash', 10:23:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NKnqyClXf7/Listeners', 10:23:14 INFO - 'TMPDIR': '/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/', 10:23:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:23:14 INFO - 'USER': 'cltbld', 10:23:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:23:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:23:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:23:14 INFO - 'XPC_FLAGS': '0x0', 10:23:14 INFO - 'XPC_SERVICE_NAME': '0', 10:23:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:23:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:23:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:23:14 INFO - Running setup.py (path:/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/pip-jCKxwS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:23:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:23:14 INFO - Running setup.py (path:/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/pip-hgtCky-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:23:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:23:14 INFO - Running setup.py (path:/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/pip-AwOWx8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:23:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:23:14 INFO - Running setup.py (path:/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/pip-DgG5s0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:23:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:23:14 INFO - Running setup.py (path:/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/pip-x6rTlr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:23:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:23:15 INFO - Running setup.py (path:/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/pip-0e4BGf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:23:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:23:15 INFO - Running setup.py (path:/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/pip-MAspyx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:23:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:23:15 INFO - Running setup.py (path:/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/pip-WdHqu2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:23:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:23:15 INFO - Running setup.py (path:/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/pip-pBb2M1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:23:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:23:15 INFO - Running setup.py (path:/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/pip-ReihyF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:23:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:23:15 INFO - Running setup.py (path:/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/pip-WkMZSg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:23:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:23:15 INFO - Running setup.py (path:/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/pip-82NCyL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:23:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:23:15 INFO - Running setup.py (path:/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/pip-Yv6LFD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:23:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:23:15 INFO - Running setup.py (path:/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/pip-vxLkNJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:23:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:23:15 INFO - Running setup.py (path:/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/pip-1N_lYG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:23:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:23:16 INFO - Running setup.py (path:/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/pip-S60kU1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:23:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:23:16 INFO - Running setup.py (path:/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/pip-B8Cxir-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:23:16 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 10:23:16 INFO - Running setup.py install for manifestparser 10:23:16 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 10:23:16 INFO - Running setup.py install for mozcrash 10:23:16 INFO - Running setup.py install for mozdebug 10:23:16 INFO - Running setup.py install for mozdevice 10:23:16 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 10:23:16 INFO - Installing dm script to /builds/slave/test/build/venv/bin 10:23:16 INFO - Running setup.py install for mozfile 10:23:17 INFO - Running setup.py install for mozhttpd 10:23:17 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 10:23:17 INFO - Running setup.py install for mozinfo 10:23:17 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 10:23:17 INFO - Running setup.py install for mozInstall 10:23:17 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 10:23:17 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 10:23:17 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 10:23:17 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 10:23:17 INFO - Running setup.py install for mozleak 10:23:17 INFO - Running setup.py install for mozlog 10:23:17 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 10:23:17 INFO - Running setup.py install for moznetwork 10:23:17 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 10:23:17 INFO - Running setup.py install for mozprocess 10:23:18 INFO - Running setup.py install for mozprofile 10:23:18 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 10:23:18 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 10:23:18 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 10:23:18 INFO - Running setup.py install for mozrunner 10:23:18 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 10:23:18 INFO - Running setup.py install for mozscreenshot 10:23:18 INFO - Running setup.py install for moztest 10:23:18 INFO - Running setup.py install for mozversion 10:23:18 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 10:23:18 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 10:23:18 INFO - Cleaning up... 10:23:18 INFO - Return code: 0 10:23:18 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:23: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')]} 10:23:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:23:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:23: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')]} 10:23:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:23:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:23:18 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 0x102968b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10296b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa970d56850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102746d30>, '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 0x10266ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10273bbe8>, '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.6HmhrAIC7u/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.NKnqyClXf7/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/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 10:23:18 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 10:23:18 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 10:23:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6HmhrAIC7u/Render', 10:23:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:23:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:23:18 INFO - 'HOME': '/Users/cltbld', 10:23:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:23:18 INFO - 'LOGNAME': 'cltbld', 10:23:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:23:18 INFO - 'MOZ_NO_REMOTE': '1', 10:23:18 INFO - 'NO_EM_RESTART': '1', 10:23:18 INFO - 'PAGER': '/bin/cat', 10:23:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:23:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:23:18 INFO - 'PWD': '/builds/slave/test', 10:23:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:23:18 INFO - 'SHELL': '/bin/bash', 10:23:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NKnqyClXf7/Listeners', 10:23:18 INFO - 'TMPDIR': '/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/', 10:23:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:23:18 INFO - 'USER': 'cltbld', 10:23:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:23:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:23:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:23:18 INFO - 'XPC_FLAGS': '0x0', 10:23:18 INFO - 'XPC_SERVICE_NAME': '0', 10:23:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:23:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:23:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:23:19 INFO - Running setup.py (path:/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/pip-5JcSgm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:23:19 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)) 10:23:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:23:19 INFO - Running setup.py (path:/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/pip-iTe49j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:23:19 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)) 10:23:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:23:19 INFO - Running setup.py (path:/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/pip-F6xG0O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:23:19 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)) 10:23:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:23:19 INFO - Running setup.py (path:/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/pip-v2viMe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:23:19 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)) 10:23:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:23:19 INFO - Running setup.py (path:/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/pip-7SuK_J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:23:19 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)) 10:23:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:23:19 INFO - Running setup.py (path:/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/pip-ysZIB9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:23:19 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)) 10:23:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:23:19 INFO - Running setup.py (path:/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/pip-jEAr5S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:23:19 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)) 10:23:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:23:19 INFO - Running setup.py (path:/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/pip-kREb6S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:23:20 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)) 10:23:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:23:20 INFO - Running setup.py (path:/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/pip-8m2sKQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:23:20 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)) 10:23:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:23:20 INFO - Running setup.py (path:/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/pip-QXHxww-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:23:20 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)) 10:23:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:23:20 INFO - Running setup.py (path:/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/pip-TuXELb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:23:20 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)) 10:23:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:23:20 INFO - Running setup.py (path:/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/pip-5SdKFV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:23:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.24 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 10:23:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:23:20 INFO - Running setup.py (path:/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/pip-Llxcen-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:23:20 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)) 10:23:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:23:20 INFO - Running setup.py (path:/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/pip-bmoh2J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:23:20 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)) 10:23:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:23:20 INFO - Running setup.py (path:/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/pip-f75clx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:23:20 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)) 10:23:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:23:20 INFO - Running setup.py (path:/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/pip-VjnAOk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:23:20 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)) 10:23:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:23:20 INFO - Running setup.py (path:/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/pip-fvGqFm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:23:21 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)) 10:23:21 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)) 10:23:21 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)) 10:23:21 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)) 10:23:21 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)) 10:23:21 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)) 10:23:21 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 10:23:21 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 10:23:21 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 10:23:21 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 10:23:21 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 10:23:21 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 10:23:21 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 10:23:23 INFO - Downloading blessings-1.6.tar.gz 10:23:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 10:23:23 INFO - Installing collected packages: blessings 10:23:23 INFO - Running setup.py install for blessings 10:23:23 INFO - Successfully installed blessings 10:23:23 INFO - Cleaning up... 10:23:24 INFO - Return code: 0 10:23:24 INFO - Done creating virtualenv /builds/slave/test/build/venv. 10:23:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:23:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:23:24 INFO - Reading from file tmpfile_stdout 10:23:24 INFO - Current package versions: 10:23:24 INFO - blessings == 1.6 10:23:24 INFO - blobuploader == 1.2.4 10:23:24 INFO - docopt == 0.6.1 10:23:24 INFO - functools32 == 3.2.3-2 10:23:24 INFO - jsonschema == 2.5.1 10:23:24 INFO - manifestparser == 1.1 10:23:24 INFO - mozInstall == 1.12 10:23:24 INFO - mozcrash == 1.0 10:23:24 INFO - mozdebug == 0.1 10:23:24 INFO - mozdevice == 0.49 10:23:24 INFO - mozfile == 1.2 10:23:24 INFO - mozhttpd == 0.7 10:23:24 INFO - mozinfo == 0.9 10:23:24 INFO - mozleak == 0.1 10:23:24 INFO - mozlog == 3.4 10:23:24 INFO - moznetwork == 0.27 10:23:24 INFO - mozprocess == 0.24 10:23:24 INFO - mozprofile == 0.28 10:23:24 INFO - mozrunner == 6.13 10:23:24 INFO - mozscreenshot == 0.1 10:23:24 INFO - mozsystemmonitor == 0.3 10:23:24 INFO - moztest == 0.8 10:23:24 INFO - mozversion == 1.4 10:23:24 INFO - psutil == 3.1.1 10:23:24 INFO - requests == 1.2.3 10:23:24 INFO - wsgiref == 0.1.2 10:23:24 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:23:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:23:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:23:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:23:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:23:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:23:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:23:24 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 0x102968b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10296b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa970d56850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102746d30>, '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 0x10266ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10273bbe8>, '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.6HmhrAIC7u/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.NKnqyClXf7/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/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 10:23:24 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 10:23:24 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 10:23:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6HmhrAIC7u/Render', 10:23:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:23:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:23:24 INFO - 'HOME': '/Users/cltbld', 10:23:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:23:24 INFO - 'LOGNAME': 'cltbld', 10:23:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:23:24 INFO - 'MOZ_NO_REMOTE': '1', 10:23:24 INFO - 'NO_EM_RESTART': '1', 10:23:24 INFO - 'PAGER': '/bin/cat', 10:23:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:23:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:23:24 INFO - 'PWD': '/builds/slave/test', 10:23:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:23:24 INFO - 'SHELL': '/bin/bash', 10:23:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NKnqyClXf7/Listeners', 10:23:24 INFO - 'TMPDIR': '/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/', 10:23:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:23:24 INFO - 'USER': 'cltbld', 10:23:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:23:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:23:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:23:24 INFO - 'XPC_FLAGS': '0x0', 10:23:24 INFO - 'XPC_SERVICE_NAME': '0', 10:23:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:23:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:23:24 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)) 10:23:24 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)) 10:23:24 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)) 10:23:24 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)) 10:23:24 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)) 10:23:24 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)) 10:23:24 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)) 10:23:24 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)) 10:23:24 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)) 10:23:24 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)) 10:23:24 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)) 10:23:24 INFO - Cleaning up... 10:23:24 INFO - Return code: 0 10:23:24 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 10:23:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:23:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:23:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:23:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:23:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:23:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:23:24 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x102968b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10296b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa970d56850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102746d30>, '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 0x10266ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10273bbe8>, '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.6HmhrAIC7u/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.NKnqyClXf7/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/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 10:23:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 10:23:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 10:23:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6HmhrAIC7u/Render', 10:23:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:23:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:23:24 INFO - 'HOME': '/Users/cltbld', 10:23:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:23:24 INFO - 'LOGNAME': 'cltbld', 10:23:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:23:24 INFO - 'MOZ_NO_REMOTE': '1', 10:23:24 INFO - 'NO_EM_RESTART': '1', 10:23:24 INFO - 'PAGER': '/bin/cat', 10:23:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:23:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:23:24 INFO - 'PWD': '/builds/slave/test', 10:23:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:23:24 INFO - 'SHELL': '/bin/bash', 10:23:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NKnqyClXf7/Listeners', 10:23:24 INFO - 'TMPDIR': '/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/', 10:23:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:23:24 INFO - 'USER': 'cltbld', 10:23:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:23:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:23:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:23:24 INFO - 'XPC_FLAGS': '0x0', 10:23:24 INFO - 'XPC_SERVICE_NAME': '0', 10:23:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:23:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:23:24 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 10:23:24 INFO - Cleaning up... 10:23:24 INFO - Return code: 0 10:23:24 INFO - Running post-action listener: _resource_record_post_action 10:23:24 INFO - Running post-action listener: _start_resource_monitoring 10:23:24 INFO - Starting resource monitoring. 10:23:24 INFO - [mozharness: 2017-01-29 18:23:24.886622Z] Finished create-virtualenv step (success) 10:23:24 INFO - [mozharness: 2017-01-29 18:23:24.887597Z] Running install step. 10:23:24 INFO - Running pre-action listener: _resource_record_pre_action 10:23:24 INFO - Running main action method: install 10:23:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:23:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:23:25 INFO - Reading from file tmpfile_stdout 10:23:25 INFO - Detecting whether we're running mozinstall >=1.0... 10:23:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 10:23:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 10:23:25 INFO - Reading from file tmpfile_stdout 10:23:25 INFO - Output received: 10:23:25 INFO - Usage: mozinstall [options] installer 10:23:25 INFO - Options: 10:23:25 INFO - -h, --help show this help message and exit 10:23:25 INFO - -d DEST, --destination=DEST 10:23:25 INFO - Directory to install application into. [default: 10:23:25 INFO - "/builds/slave/test"] 10:23:25 INFO - --app=APP Application being installed. [default: firefox] 10:23:25 INFO - mkdir: /builds/slave/test/build/application 10:23:25 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'] 10:23:25 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 10:23:47 INFO - Reading from file tmpfile_stdout 10:23:47 INFO - Output received: 10:23:47 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 10:23:47 INFO - Running post-action listener: _resource_record_post_action 10:23:47 INFO - [mozharness: 2017-01-29 18:23:47.177967Z] Finished install step (success) 10:23:47 INFO - [mozharness: 2017-01-29 18:23:47.178129Z] Running run-tests step. 10:23:47 INFO - Running pre-action listener: _resource_record_pre_action 10:23:47 INFO - Running main action method: run_tests 10:23:47 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 10:23:47 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 10:23:47 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 10:23:47 INFO - 2017-01-29 10:23:47.192 screenresolution[2531:298815] starting screenresolution argv=screenresolution get 10:23:47 INFO - 2017-01-29 10:23:47.195 screenresolution[2531:298815] Display 0: 1600x1200x32@60 10:23:47 INFO - 2017-01-29 10:23:47.200 screenresolution[2532:298818] starting screenresolution argv=screenresolution list 10:23:47 INFO - Available Modes on Display 0 10:23:47 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 10:23:47 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 10:23:47 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 10:23:47 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 10:23:47 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 10:23:47 INFO - Intel Iris: 10:23:47 INFO - Chipset Model: Intel Iris 10:23:47 INFO - Type: GPU 10:23:47 INFO - Bus: Built-In 10:23:47 INFO - VRAM (Dynamic, Max): 1536 MB 10:23:47 INFO - Vendor: Intel (0x8086) 10:23:47 INFO - Device ID: 0x0a2e 10:23:47 INFO - Revision ID: 0x0009 10:23:47 INFO - Displays: 10:23:47 INFO - Display: 10:23:47 INFO - Resolution: 1600 x 1200 @ 60 Hz 10:23:47 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 10:23:47 INFO - Display Serial Number: 1600x1200 60 10:23:47 INFO - Main Display: Yes 10:23:47 INFO - Mirror: Off 10:23:47 INFO - Online: Yes 10:23:47 INFO - Rotation: Supported 10:23:47 INFO - Return code: 0 10:23:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 10:23:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 10:23:47 INFO - Python 2.7.3 10:23:47 INFO - Return code: 0 10:23:47 INFO - grabbing minidump binary from tooltool 10:23:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:23:47 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 0x102746d30>, '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 0x10266ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10273bbe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 10:23:47 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 10:23:47 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 10:23:47 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 10:23:47 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 10:23:47 INFO - Return code: 0 10:23:47 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 10:23:47 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 10:23:47 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 10:23:47 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 10:23:47 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 10:23:47 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/F_PIFeKGQqevudDkfWvfsw/artifacts/public/build/firefox-54.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0351', '--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 10:23:47 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/F_PIFeKGQqevudDkfWvfsw/artifacts/public/build/firefox-54.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0351 --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 10:23:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6HmhrAIC7u/Render', 10:23:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:23:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:23:47 INFO - 'HOME': '/Users/cltbld', 10:23:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:23:47 INFO - 'LOGNAME': 'cltbld', 10:23:47 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 10:23:47 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 10:23:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:23:47 INFO - 'MOZ_NO_REMOTE': '1', 10:23:47 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 10:23:47 INFO - 'NO_EM_RESTART': '1', 10:23:47 INFO - 'PAGER': '/bin/cat', 10:23:47 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:23:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:23:47 INFO - 'PWD': '/builds/slave/test', 10:23:47 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 10:23:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:23:47 INFO - 'SHELL': '/bin/bash', 10:23:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NKnqyClXf7/Listeners', 10:23:47 INFO - 'TMPDIR': '/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/', 10:23:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:23:47 INFO - 'USER': 'cltbld', 10:23:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:23:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:23:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:23:47 INFO - 'XPC_FLAGS': '0x0', 10:23:47 INFO - 'XPC_SERVICE_NAME': '0', 10:23:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:23:47 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/F_PIFeKGQqevudDkfWvfsw/artifacts/public/build/firefox-54.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0351', '--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 10:23:47 INFO - mozversion application_buildid: 20170129090243 10:23:47 INFO - mozversion application_changeset: 77af15f468e84d9a5ff659641737343ffa0444cf 10:23:47 INFO - mozversion application_display_name: Nightly 10:23:47 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 10:23:47 INFO - mozversion application_name: Firefox 10:23:47 INFO - mozversion application_remotingname: firefox 10:23:47 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 10:23:47 INFO - mozversion application_vendor: Mozilla 10:23:47 INFO - mozversion application_version: 54.0a1 10:23:47 INFO - mozversion platform_buildid: 20170129090243 10:23:47 INFO - mozversion platform_changeset: 77af15f468e84d9a5ff659641737343ffa0444cf 10:23:47 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 10:23:47 INFO - mozversion platform_version: 54.0a1 10:23:47 INFO - using testdate: 1485714227 10:23:47 INFO - actual date: 1485714227 10:23:47 INFO - starting webserver on 'localhost:55238' 10:23:47 INFO - SUITE-START | Running 2 tests 10:23:47 INFO - TEST-START | dromaeo_css 10:23:47 INFO - operating with platform_type : mac_ 10:23:47 INFO - Initialising browser for dromaeo_css test... 10:23:47 INFO - TEST-INFO | started process 2542 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/tmpNEev21/profile http://localhost:55238/getInfo.html) 10:23:50 INFO - PROCESS | 2542 | JavaScript error: chrome://webcompat/content/lib/ua_overrider.jsm, line 109: NS_ERROR_INSUFFICIENT_DOMAIN_LEVELS: Component returned failure code: 0x804b0050 (NS_ERROR_INSUFFICIENT_DOMAIN_LEVELS) [nsIEffectiveTLDService.getBaseDomain] 10:23:50 INFO - PROCESS | 2542 | 2017-01-29 10:23:50.626 plugin-container[2544:298973] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8f37, name = 'com.apple.tsm.portname' 10:23:50 INFO - PROCESS | 2542 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:23:50 INFO - PROCESS | 2542 | 2017-01-29 10:23:50.631 plugin-container[2544:298973] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4b1f, name = 'com.apple.CFPasteboardClient' 10:23:50 INFO - PROCESS | 2542 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:23:50 INFO - PROCESS | 2542 | 2017-01-29 10:23:50.631 plugin-container[2544:298973] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 10:23:50 INFO - PROCESS | 2542 | __metrics Screen width/height:1600/1200 10:23:50 INFO - PROCESS | 2542 | colorDepth:24 10:23:50 INFO - PROCESS | 2542 | Browser inner width/height: 1024/689 10:23:50 INFO - PROCESS | 2542 | __metrics 10:23:50 ERROR - PROCESS | 2542 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3279: TypeError: this.tabs is undefined 10:23:50 INFO - PROCESS | 2542 | 1485714230778 addons.xpi WARN Exception running bootstrap method shutdown on shield-recipe-client@mozilla.org: ReferenceError: LogManager is not defined (resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:65:3) JS Stack trace: this.shutdown@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@bootstrap.js:65:3 < callBootstrapMethod@XPIProvider.jsm:4903:11 < observe@XPIProvider.jsm:2884:15 < forceQuit@jar:file:///var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/tmpNEev21/profile/extensions/talos-powers@TalosPowersService.js:238:7 < whenDone@jar:file:///var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/tmpNEev21/profile/extensions/talos-powers@TalosPowersService.js:223:9 < promise callback*forceQuit@jar:file:///var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/tmpNEev21/profile/extensions/talos-powers@TalosPowersService.js:225:7 < receiveMessage@jar:file:///var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/tmpNEev21/profile/extensions/talos-powers@TalosPowersService.js:55:9 10:23:51 INFO - TEST-INFO | 2542: exit 0 10:23:51 INFO - Browser initialized. 10:23:51 INFO - Running cycle 1/1 for dromaeo_css test... 10:23:51 INFO - TEST-INFO | started process 2545 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/tmpNEev21/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:23:54 INFO - PROCESS | 2545 | 2017-01-29 10:23:54.034 plugin-container[2546:299090] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x433f, name = 'com.apple.tsm.portname' 10:23:54 INFO - PROCESS | 2545 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:23:54 INFO - PROCESS | 2545 | 2017-01-29 10:23:54.035 plugin-container[2546:299090] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8f0b, name = 'com.apple.CFPasteboardClient' 10:23:54 INFO - PROCESS | 2545 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:23:54 INFO - PROCESS | 2545 | 2017-01-29 10:23:54.035 plugin-container[2546:299090] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 10:24:03 INFO - PROCESS | 2545 | RSS: Main: 233857024 10:24:03 INFO - PROCESS | 2545 | 10:27:06 INFO - PROCESS | 2545 | Cycle 1(1): loaded http://localhost:55238/tests/dromaeo/cssquery-dojo.html (next: http://localhost:55238/tests/dromaeo/cssquery-ext.html) 10:27:07 INFO - PROCESS | 2545 | RSS: Main: 257552384 10:27:07 INFO - PROCESS | 2545 | 10:28:51 INFO - PROCESS | 2545 | ************************* 10:28:51 INFO - PROCESS | 2545 | A coding exception was thrown and uncaught in a Task. 10:28:51 INFO - PROCESS | 2545 | 10:28:51 ERROR - PROCESS | 2545 | Full message: TypeError: NetworkError when attempting to fetch resource. 10:28:51 INFO - PROCESS | 2545 | Full stack: 10:28:51 INFO - PROCESS | 2545 | ************************* 10:30:09 INFO - PROCESS | 2545 | Cycle 1(1): loaded http://localhost:55238/tests/dromaeo/cssquery-ext.html (next: http://localhost:55238/tests/dromaeo/cssquery-jquery.html) 10:30:10 INFO - PROCESS | 2545 | RSS: Main: 265973760 10:30:10 INFO - PROCESS | 2545 | 10:33:12 INFO - PROCESS | 2545 | Cycle 1(1): loaded http://localhost:55238/tests/dromaeo/cssquery-jquery.html (next: http://localhost:55238/tests/dromaeo/cssquery-mootools.html) 10:33:13 INFO - PROCESS | 2545 | RSS: Main: 276652032 10:33:13 INFO - PROCESS | 2545 | 10:36:15 INFO - PROCESS | 2545 | Cycle 1(1): loaded http://localhost:55238/tests/dromaeo/cssquery-mootools.html (next: http://localhost:55238/tests/dromaeo/cssquery-prototype.html) 10:36:15 INFO - PROCESS | 2545 | RSS: Main: 272883712 10:36:15 INFO - PROCESS | 2545 | 10:39:18 INFO - PROCESS | 2545 | Cycle 1(1): loaded http://localhost:55238/tests/dromaeo/cssquery-prototype.html (next: http://localhost:55238/tests/dromaeo/cssquery-yui.html) 10:39:19 INFO - PROCESS | 2545 | RSS: Main: 277782528 10:39:19 INFO - PROCESS | 2545 | 10:42:21 INFO - PROCESS | 2545 | Cycle 1(1): loaded http://localhost:55238/tests/dromaeo/cssquery-yui.html (next: http://localhost:55238/tests/dromaeo/cssquery-dojo.html) 10:42:22 INFO - PROCESS | 2545 | RSS: Main: 280371200 10:42:22 INFO - PROCESS | 2545 | 10:42:22 INFO - PROCESS | 2545 | __start_tp_report 10:42:22 INFO - PROCESS | 2545 | _x_x_mozilla_page_load 10:42:22 INFO - PROCESS | 2545 | _x_x_mozilla_page_load_details 10:42:22 INFO - PROCESS | 2545 | |i|pagename|runs| 10:42:22 INFO - PROCESS | 2545 | |0;dojo.html;3332.600087989441;3333.050080726995;3364.798112113272;3371;3393.847276872542;5274.945010997797;5592.468715472029;5628.859278518039;5639.887202255953;5669.659820410768;4876.97561512192;5632.35227948786;5639.041362968295;5710.086386178213;5746.109353050273;5084.542391184793;5136.768845401947;5231.372235331765;5236.345456817898;5245.868853278679;5128.71792051437;5267.367915850104;5270.9736451317685;5275.314209152804;5320.600954928376;5061.797528098871;5072.67027643202;5146.922796158061;5207.531322181003;5228.2680424740565;5083.745812709387;5148.716820574867;5165.560927321182;5177;5177.741112944338;5080.847574572768;5086.79652813887;5099.770510327007;5126.359205099363;5215.061288967984;4282.593153650399;5236.266922630834;5264.657797243205;5343.812966546152;5541.556675465953;6101.701646049711;6141.424638644238;6153.7230824613;6186.072089186658;6209.534284928648;5210.400803907523;6121.938780612226;6170.784022559189;6234.220722409699;6256.092866534327;5675.120356344773;5891.587588868823;5899.76400943966;5905.320888097881;5913.733881975321;6109.495762817221;6138.938610613836;6148.8770224595255;6175.104609831549;6238.438540531283;6046.893259404724;6116.694165291718;6118.694065296718;6133.754649814046;6138.631682099089;6095.512359011268;6176.598521096114;6240;6254.21822272216;6288.276848162428;6091.817245482643;6111.682900513672;6116.67056464255;6128.448439640454;6220.284667263232;6216.564840461125;6219.720112594944;6232.376762323822;6235.345288744707;6245.781397651061;4993.850184494471;4999.950000499948;5002.474740152305;5007.499250074963;5009.674371165863;3651.123730304708;4372.060007098489;4384.605385515319;4406.537313582092;4459.9331010034875;5738.426157384311;5825.03886858672;5829.213056237354;5841.9415805842245;5853.824385268449;5524.889502209933;6191.318954914956;6194.132821405007;6232.439080482778;6245.437910588069;5603.327600687943;5676.262085928803;5700.37295897451;5704.1443783432815;5829.708514574254;5423.538999185113;5424.457554244623;5428.430014848463;5434.70109143993;5462.808801692001;6259.6870156492;6264.812055638338;6283.748650053946;6284.905726414016;6319.526035547352;6124.74712746768;6127.20346354971;6139.461473530676;6227.252729672468;6247.531435142473;6153.753849846135;6204.906926396198;6210.813675589739;6233.470155036873;6255.46828519581;6153.13856060143;6180.783672571439;6201.348858369806;6205.28639206479;6208.192934918432;6137.263528376623;6140.320203811367;6245.718942647501;6250.843728906631;6254.874902502015;5912.260967379078;6058.787942421994;6080.270367555921;6111.572189946661;6124.625058545555;6104.38956104386;6107.725152368065;6115.908261375994;6122.265328160649;6130.938690613037;4278.6790990676445;5696.743646535999;5811.593188476766;5846.912296315473;5892.175095486549;6045.909311360421;6092.45167934888;6111.022236442148;6127.724252408563;6142.877142457215;5872.823815285548;5875.089361149029;5881.970655135248;5887.764489420375;5889.587728858939 10:42:22 INFO - PROCESS | 2545 | |1;ext.html;458.0586893932966;461.47852926193406;488.08727679239826;490.08109794135993;496.4167103653208;37495.43756843649;39938.400923986126;43387.34918976214;43505.34741978869;45644.315335269996;44228.11543769131;44607.88480287995;44851.87870303244;44871.55128448715;44952;7872.409569282298;9365.67220147295;9553.952230238874;9581.90418095817;9620.42277463351;8440.788980275482;11378.943105284461;11650.009749171288;11706.473208705585;11854.703632409215;11640.301581905112;11674.241174323684;11687.714351421337;11697.59058432955;11704.53181872724;11664.941675291655;11710.648680539598;11727.944484996306;11736.119791015708;11742.354170520617;11479.426028698532;11517.308961462339;11575.53697852085;11579.53681852725;11585.884141158565;41957;42020.36969445461;42038;42320.365194522106;42435;4012.9398059029136;4059.6346328830546;4109.321961876317;4118.052847845012;4197.538270790211;32058.519122213187;32067.679323206703;32071;32075.358492830244;32273.83863080693;2844.743973042436;3158.384115097557;3277.8033318001;3373.8650453981813;3391.6947474727394;491198;495568;497350;497649;498123.18768123625;66027;66366.66816665886;66635.33364666389;66668.6666566664;66709.6664516684;69978;70005;70253.64873175704;70444.82998264988;70604.6469767648;13171.538996135088;16445.342186312653;16610.418635347705;16637.41769038078;16669.749953750703;11274.7181320466;11603.187776855539;12404.875951240372;12598.748025039447;12730.80903786444;18834.71747923782;19419.90290048569;19435.028248587514;19447.222111115396;19567.412977610693;7592.544447333178;7831.490953088031;7836.294733474015;7886.408519361071;7974.481658692166;756.5082696247373;775.8525880082766;786.2805532937298;793.4644115222202;799.9240072193132;22945.08219671228;24171.637425438632;30009.69990300113;30127.397452051275;30449.08652740421;934.56075644447;994.9950250248702;1016.2936758952457;1017.2624846985876;1029.3000759483473;15449.227538623023;15570.84429155694;15570.9221453892;15740.291686874118;16028.439004635014;549151;588726;601228;655993;657637;60998;61013;61207.693961529905;61437.078443823375;61464.07803882945;1318.7296604196117;1336.5121730568274;1375.8555351688128;1376.876081152701;1376.951806686761;24366.75633243645;28294;28486.43324617166;29634.703652964057;29686.554701679925;41545.58454415538;52225.7388713054;52586.47413525969;52802.471975279754;52920.20619690785;489279;491305;491788;492464;492614.5369273131;26674.066407675637;26842;26871.596926046517;26968.595471067558;27071.59392609152;39827;40950;48706.26940595965;48756;48847.755761220964;39566.010849728984;39865.80067099762;40364.79817600893;40573.79713101416;41819;39820.606278780106;40485.1902961945;40906.74295704721;40979.59020409758;41503.7924810374;37988.860334190016;38032.61967380291;38795.6120438792;38840.80579597084;38900;31301.8434907824;34534.48198277078;34928;35151.82424087863;35208.47187292244;20474.36832482434;25252.24243272705;25347.239582812545;25555;25890.482190356463 10:42:22 INFO - PROCESS | 2545 | |2;jquery.html;346.55986896641264;375.7520036775729;392.7466783924369;410.2553864735507;410.48689138576776;20168.294109706163;21540.676889846625;21860.672089918626;22077.66883496747;22342.882855857224;15794.921025394857;18971.71542426865;21676.674849877225;21853.781462185412;21979.010944507496;5202.401723801754;5445.373782015079;5722.513586345166;5946.286445626531;5991.64050156992;5781.913271300934;5919.289685237777;5920.407959204088;5950.196723442323;5956.255468066492;5639.7180140993;5780.710964451781;5844.123381492789;5851.590388672774;5855.033919403293;5600.887982240373;5813.302403711539;5841.153032810219;5842.47417732404;5852.034414321611;5127.871803204913;5131.897362052775;5156.664816786897;5167.354080739908;5169.302144210521;26374.73625263742;26597.335066623295;26683.73316266851;26782.5982610261;26820.463590728075;2421.4551725861716;2649.7880169586388;2673.7459941305547;3014.80403773754;3061.8853561571354;16031.679366412607;16069.678606427806;16112.677746445122;16277.755833662504;16749.91625041879;1647.2751989124747;1784.295203394665;1908.319218791139;1910.3695780392577;1928.7974762650001;90129.54935225281;101618.49190754072;128933.7106628931;135068;135090.32454837664;11645.12661550387;11894.799462099367;12040.638780836589;12060.336681482428;12083;15827.920860395854;15848.44530441429;15895.68208635844;15929.964552304064;15983.12092834882;8684.913150868537;8841.469511829311;8901.510416927002;8913.331500137387;8915.12782315708;8532.744017679479;8545.444546104483;8564.700235491728;8674.826503469896;8723.084076172037;4875.352880550864;4881.926771098365;4884.7069175849565;4926.58124059459;4984.651074424755;1773.866959978007;2043.1725151313742;2048.805363490466;2049.7130401743766;2050.7026481160146;963.9155949556749;985.7042887133831;990.8216521026285;991.4992928571025;1032.1587905856702;16278.51164465068;19364.128614212394;20069.29757458482;21246.575068498543;22369.328920132422;1006.2201793609864;1011.1708399112657;1035.5754140802235;1039.111559616532;1044.7022598559527;10842.62050828233;11578.884211157949;11597.710057248636;11613.477393517313;11630.88369116315;671237;675324;681783;712763;716536;117636;124518;142704;154424;157668;853.1127627267572;969.6945462179391;974.4202199691173;995.7759504111517;1000.9949950250202;14959.77560336574;18090.366837160636;18627.813721862607;19192.040397979767;19859.702104468735;60869.69565152146;61213;61298;61468.692656536434;61526.69236653788;64101.35898640954;81033;82501.349972999;88604.5569772147;91987;7391.260873912565;7970.840583188187;8336.208060234268;8773.341999349946;8908.21835632863;10974.945125274322;11743.823842642538;12308.674423591801;12936.417861196456;13505.594832155051;10079.496025198858;10953.890461095605;11404.372759498307;11422.485988130387;11560.94219528897;9290.767730806783;10678.092362149304;11209.103271738079;11387.829182562435;11592.88407115918;11151.944240278746;11193.27243729155;11462.369569673749;11899;11910.583129590423;7543.811404714926;7668.923310767044;7694.923050769421;7860.292573668397;7872.84254314922;9450.291228157777;10281.74295642615;11078.169137314573;11458.541658333574;11491.195616306779 10:42:22 INFO - PROCESS | 2545 | |3;mootools.html;1416.0866241274377;1431.7494438473263;1435.3253970633798;1444.3789170656617;1445.6096853849458;7428.851422971537;7688.807779805507;7982.401319901009;8391;8413.326933845294;7864.882026769603;8164.91835081649;8242.876356854653;8272.338212942965;8445.366597505197;2223.844330896838;2261.502469456717;2271.931842044741;2273.4771002669395;2277.4989502309463;2046.8260197883199;2122.7877212278727;2246.146464343555;2285.325828880476;2293;2211.2702808073295;2288.7253529576474;2290.89690963907;2296.781805728453;2298.586254474202;2261.683364328995;2271.8409711320214;2294.621387471065;2297.6323788193977;2298;2293.896774645128;2299.5171014087064;2319;2324.9070037198494;2325.7906788389128;8309.044459887129;8359.331253499706;8362;8381.371397145238;8387.077421483631;5010.123228435045;5138.280640710266;5159.2003239497935;5169.586433085344;5192.71440070796;3344.1171128271426;3493.074335301147;3493.737969652286;3503.7722548034294;3553.6091029986683;1175.729582196091;1219.7926352520092;1239.783037968352;1269.6698858296818;1270.748312911781;10528.473576321154;10616.469176541143;10657.147428205724;10674.839877401846;10683.252172347939;4507.864764057083;4606.470255920612;4631.722096674211;4651.2557990721325;4681.625469962395;8365.372597055124;8455.95772021136;8533;8536;8542.530160841088;5082.466341034212;5095.566876815438;5096.668716533413;5103.642745007888;5106.131957567222;4516.728996260234;4700.153972284953;4740.502247264057;4747.098051370229;4782.760861956888;3599.7300202484917;3626.818659067036;3664.4320130379833;3712.0719820044988;3721.5348081489815;953.9379940303858;1081.0972837680624;1116.1963386361806;1155.9595414160465;1167.2062997161843;724.0551080839555;822.3503432288425;886.663068034143;906.1074841281333;937.4609599480326;5237.292965449692;5320.521153096162;5489.807856724996;5592.440755924375;5864.618799778001;837.9748607541661;898.7258886039821;941.7174847545709;981.4062492192121;1011.6256984915628;3121.395940987026;3516.558211133423;3735.3836616958442;3780.7542509737327;3824.9808750956067;9520.143187113194;9757.219422446187;9819.558119884623;9839.508024598885;9869.11177993984;9086.772830679154;9357.204637605744;9622.951885240669;9744.269179811543;9774.413535187912;7306.342429181293;7609.505382150142;7818.296353328228;7821.882671759929;7824.256695613907;5338;5358.919616205682;5520.889582208413;5574.386817450159;5585.525230355465;8776.34177436682;8887.955560222157;9006.054364291916;9045.412048216846;9070.501122438329;6370.90443643355;8994.010658827654;9132.406393584395;9185.402948808307;9265.305102117234;7581.431392645684;7594.6202689866395;7642.312191902755;7655.57894315818;7739.806504837199;7638.236176382317;7705.345045670956;7714.768556943301;7722.6138693063995;7726.227377262228;6845.589264643938;6933.237343892067;6958.582485050913;6986.615736134561;7098.680559374944;6809.727610895707;6848.760293389708;6904.275051119761;6909.30906909305;6911.723531058701;6217.937820621917;6288.182536270256;6300.653464059521;6303.243610766737;6375.489960803217;4918.1393256180745;4978.377702787152;4984.7258400788305;5006.5994720423005;5062.468440813662;4943.5797957174045;5000.824971125993;5040.924386134137;5105.617078719037;5131.3842338918075 10:42:22 INFO - PROCESS | 2545 | |4;prototype.html;359.6062311768614;363.12305781537583;371.8791392797341;376.4428645604507;378.0341228162045;4303.741775493472;4453.151941944094;5075.122204784235;5323.866903327419;5324.467553244674;4575.56532129448;4710.458297295812;5078.39059312883;5083.644794435673;5298.125809241471;3105.922351941197;3187.378461200067;3459.948100778484;4029.838806447753;4184.623383895449;3484.8431820568007;3783.602721714222;3886.145048089416;4150.854720084798;4165.500139983191;3768.6231376862233;3968.8214030368704;3972.304846651839;4002.779847108408;4047.98800299925;3316.718078963267;3475.7740746851373;3524.18943642961;3963.960360396388;3989.640932316091;3105.8602362893726;3367.888596763062;3515.982420087909;3530.6292839252023;3550.928981420383;4869.659123861331;5107.008266775961;5206.609504287194;5207.244949482341;5222.242774797671;3742.8128593570486;4602.792874320664;4615.8153673853;4637.768111594407;4650.4186976627925;4677.906441871143;5626.690532020737;5635.746391412396;5676.574256930706;5757.107648314476;3244.6430892601793;3641.3809652358896;3699.426588878728;3801.182745709658;3851.364524853396;4730.314104454835;5662.207290979266;5877.883961773758;5879.177454988967;5930.229070220844;3455.7235421166247;3699.648533389324;3836.848945316394;3860.4209368594934;3874.612538746103;3046.558249053875;3639.85344616445;3751.8686845960806;3950.7827069511454;3971.3447281198855;4224.4930608327195;4481.305397663302;4536.138133754642;4541.318802179633;4552.7495987720995;5154.948450515447;5198.764531251598;5679.716014199274;5720.799772007959;5741.45456181662;1154.2093665838759;1234.0867757859298;1237.3874931908763;1268.3594784633701;1278.9040821938393;850.0606829453365;860.3695996087504;872.6945569050781;882.8587426011808;886.689658619478;4662.580367766917;5105.617078719111;5106.642535022513;5165.302684137669;5170.663906846118;4633.513481084505;4663.673542852036;5017.093504468278;5146.356705411824;5244.475552444801;3915.1190982029384;4605.654575906753;4815.374001379798;4869.732164730974;5139.280500729901;2588.9482210355686;2952.852357382122;2956.2904902823598;2957.2015555800244;2967.6438827340858;4912.72979986097;5016.498350164954;5313.954127660879;5800.970995145081;5892.672394404186;4657.252221189515;5514.338279406497;5608;5638.126090455986;5777.046787280052;4640.582347588733;5667.688277144714;5698.686572238566;5707;5788.82633520995;4646.038466669047;5228;5438.18427235902;5496.31296087989;5502.532284755841;5110.4123025852505;5237.764300606405;5427.348718153847;5470.945290547043;5519.8896022080135;5156.948430515647;5547.83634382583;5732.254806875079;5750.482456578928;5813.09896965971;3113.1438854315243;3592.922123363033;3705.9073523162137;3927.9214415710953;3934.3114954882208;4567.931481027721;5411.431799660979;5603.739158689263;5771.336296325976;5961.314448838439;4858.1012512685265;5289.6297259193025;5384.515393614593;5620.325560932551;5671.744771485211;4049.979750101231;4955.8265460710145;5558.610897237317;5589.832305030855;5657.538970547825;4200.474940632421;4461;4836.331465644347;5535.564351685246;5570.805021824379;5622.971885140548;5676.4323567642905;5758.395368486249;5779.450952159538;5783.768649253982;4828.66199366041;5569.972150139385;5580.441955804387;5613.47393672857;5621.775128994958 10:42:22 INFO - PROCESS | 2545 | |5;yui.html;677.8508728079823;681.7647911470541;753.7286576832342;818.394388152767;821.3224090125647;13294.867051329471;14787.556373308818;14937.25313734313;15897.364105435783;15910.045397276172;14325.57023289303;14362.712745745079;14396.784048239284;14460.132392056485;14519.709605807877;873.554487211522;882.4176043811086;897.4211633496391;898.7034278688018;900.5452246615483;839.5844057191669;878.363186689648;879.5074758135432;880.7357792662176;881.4094556647062;916.5921165081542;926.1988380051241;935.377973647527;937.4890684576922;937.6624415210518;912.6531917871199;921.2952091649893;928.1553786054658;932.6875496708611;935.4667839331584;663.6747993483207;694.198201077756;699.4928676709396;700.1248439450686;700.1807884774826;14073.366698498592;14214.644633884132;14822;15264.465743698916;15468.149251791147;14023.439062437592;14110.294485275797;14169.362378692984;14215.431382744679;14358.425662973468;11048.613298534594;11612.18714689972;11768.111024058211;11777.470013849397;11859.28844269338;3600.7299452541165;3680.3191409589313;3719.20037192005;3826.1391186982983;3843.557990831034;114797.27804082862;115003.27495087497;115570.42214788956;116024.6795064094;118831.81168188294;10832.72918177036;10845.07816835562;10896.509657065451;11068.778624427467;11096.389698566494;22459.101635934705;22662.433439163822;22758.20346287872;22761.430964226027;22815.429614259443;7641.3886889048745;7702.537847729154;7722.189170137167;7730.806729831799;7879.724209652749;4275.187714334266;4321.978390108092;4336.934945975813;4395.780210989437;4530.909381812411;3562.412201986695;3578.159132603836;3666.431703086026;3667.2482141161;3679.1170119171206;650.5283669339691;658.9176352955881;662.5097427903316;711.2851584157888;724.7390939261861;635.853753636657;659.6833519910374;683.4634811672813;688.6281408039703;709.7870638808337;8896.555172241491;10083.546240419199;10230.079292863678;10330;10356.326838755456;605.0651743056987;666.5567397680488;683.5112894280613;706.992930070703;710.4955481608116;4481.775911204427;4865.659403841697;4882.292067650171;4894.3147959285725;4896.608271338285;97873;100237.49881250694;101557;101732.96534069277;103415;81158.59420702858;84056.57971710224;84254;85256.14743852607;88241;73893;74313.62843185749;74746.62626686941;75260.4947901039;76148;9150.588223529823;9246.352755307333;9306.348555601044;9307.953460232926;9310.813783724152;49727.25409118794;53682.46317536931;54943.45056549383;55010.17484737812;55328.72335638457;71510.28489715036;71588.28411715817;72602;74013;74108.62945685237;11416.372099534605;11430.428478576205;11619.244749091282;11805.645830625095;11905.166638335233;12825.807612886001;13049;13060.80408793888;13098.410571524495;13105.934470327587;11814.586489472827;11864.466099025392;11873.287602743863;11892;12283.877161228631;11549.769004620028;11832.11259155584;11956.282623042645;12166.783321668116;12187.878121218675;11618.6514404568;11680.299182049104;11721.706957326134;11738.58914937973;12176.878231217574;10039.749006274902;10206.336588121749;10310.32982856112;10440.060394564525;10496;6844.315568443116;8702.869456958024;8864.778380540538;9677.951610241904;9703.223742100756 10:42:22 INFO - PROCESS | 2545 | __end_tp_report 10:42:22 INFO - PROCESS | 2545 | __start_cc_report 10:42:22 INFO - PROCESS | 2545 | _x_x_mozilla_cycle_collect,394 10:42:22 INFO - PROCESS | 2545 | __end_cc_report 10:42:22 INFO - PROCESS | 2545 | __startTimestamp1485715342144__endTimestamp 10:42:22 INFO - PROCESS | 2545 | 10:42:22 INFO - PROCESS | 2545 | ------- Summary: start ------- 10:42:22 INFO - PROCESS | 2545 | Number of tests: 6 10:42:22 INFO - PROCESS | 2545 | 10:42:22 INFO - PROCESS | 2545 | [#0] dojo.html Cycles:165 Average:5676.06 Median:5891.59 stddev:663.08 (11.3%) stddev-sans-first:639.12 10:42:22 INFO - PROCESS | 2545 | Values: 3332.6 3333.1 3364.8 3371.0 3393.8 5274.9 5592.5 5628.9 5639.9 5669.7 4877.0 5632.4 5639.0 5710.1 5746.1 5084.5 5136.8 5231.4 5236.3 5245.9 5128.7 5267.4 5271.0 5275.3 5320.6 5061.8 5072.7 5146.9 5207.5 5228.3 5083.7 5148.7 5165.6 5177.0 5177.7 5080.8 5086.8 5099.8 5126.4 5215.1 4282.6 5236.3 5264.7 5343.8 5541.6 6101.7 6141.4 6153.7 6186.1 6209.5 5210.4 6121.9 6170.8 6234.2 6256.1 5675.1 5891.6 5899.8 5905.3 5913.7 6109.5 6138.9 6148.9 6175.1 6238.4 6046.9 6116.7 6118.7 6133.8 6138.6 6095.5 6176.6 6240.0 6254.2 6288.3 6091.8 6111.7 6116.7 6128.4 6220.3 6216.6 6219.7 6232.4 6235.3 6245.8 4993.9 5000.0 5002.5 5007.5 5009.7 3651.1 4372.1 4384.6 4406.5 4459.9 5738.4 5825.0 5829.2 5841.9 5853.8 5524.9 6191.3 6194.1 6232.4 6245.4 5603.3 5676.3 5700.4 5704.1 5829.7 5423.5 5424.5 5428.4 5434.7 5462.8 6259.7 6264.8 6283.7 6284.9 6319.5 6124.7 6127.2 6139.5 6227.3 6247.5 6153.8 6204.9 6210.8 6233.5 6255.5 6153.1 6180.8 6201.3 6205.3 6208.2 6137.3 6140.3 6245.7 6250.8 6254.9 5912.3 6058.8 6080.3 6111.6 6124.6 6104.4 6107.7 6115.9 6122.3 6130.9 4278.7 5696.7 5811.6 5846.9 5892.2 6045.9 6092.5 6111.0 6127.7 6142.9 5872.8 5875.1 5882.0 5887.8 5889.6 10:42:22 INFO - PROCESS | 2545 | 10:42:22 INFO - PROCESS | 2545 | [#1] ext.html Cycles:180 Average:68073.61 Median:39846.40 stddev:142809.56 (358.4%) stddev-sans-first:143119.95 10:42:22 INFO - PROCESS | 2545 | Values: 458.1 461.5 488.1 490.1 496.4 37495.4 39938.4 43387.3 43505.3 45644.3 44228.1 44607.9 44851.9 44871.6 44952.0 7872.4 9365.7 9554.0 9581.9 9620.4 8440.8 11378.9 11650.0 11706.5 11854.7 11640.3 11674.2 11687.7 11697.6 11704.5 11664.9 11710.6 11727.9 11736.1 11742.4 11479.4 11517.3 11575.5 11579.5 11585.9 41957.0 42020.4 42038.0 42320.4 42435.0 4012.9 4059.6 4109.3 4118.1 4197.5 32058.5 32067.7 32071.0 32075.4 32273.8 2844.7 3158.4 3277.8 3373.9 3391.7 491198.0 495568.0 497350.0 497649.0 498123.2 66027.0 66366.7 66635.3 66668.7 66709.7 69978.0 70005.0 70253.6 70444.8 70604.6 13171.5 16445.3 16610.4 16637.4 16669.7 11274.7 11603.2 12404.9 12598.7 12730.8 18834.7 19419.9 19435.0 19447.2 19567.4 7592.5 7831.5 7836.3 7886.4 7974.5 756.5 775.9 786.3 793.5 799.9 22945.1 24171.6 30009.7 30127.4 30449.1 934.6 995.0 1016.3 1017.3 1029.3 15449.2 15570.8 15570.9 15740.3 16028.4 549151.0 588726.0 601228.0 655993.0 657637.0 60998.0 61013.0 61207.7 61437.1 61464.1 1318.7 1336.5 1375.9 1376.9 1377.0 24366.8 28294.0 28486.4 29634.7 29686.6 41545.6 52225.7 52586.5 52802.5 52920.2 489279.0 491305.0 491788.0 492464.0 492614.5 26674.1 26842.0 26871.6 26968.6 27071.6 39827.0 40950.0 48706.3 48756.0 48847.8 39566.0 39865.8 40364.8 40573.8 41819.0 39820.6 40485.2 40906.7 40979.6 41503.8 37988.9 38032.6 38795.6 38840.8 38900.0 31301.8 34534.5 34928.0 35151.8 35208.5 20474.4 25252.2 25347.2 25555.0 25890.5 10:42:22 INFO - PROCESS | 2545 | 10:42:22 INFO - PROCESS | 2545 | [#2] jquery.html Cycles:180 Average:38527.44 Median:14512.26 stddev:115052.42 (792.8%) stddev-sans-first:115339.45 10:42:22 INFO - PROCESS | 2545 | Values: 346.6 375.8 392.7 410.3 410.5 20168.3 21540.7 21860.7 22077.7 22342.9 15794.9 18971.7 21676.7 21853.8 21979.0 5202.4 5445.4 5722.5 5946.3 5991.6 5781.9 5919.3 5920.4 5950.2 5956.3 5639.7 5780.7 5844.1 5851.6 5855.0 5600.9 5813.3 5841.2 5842.5 5852.0 5127.9 5131.9 5156.7 5167.4 5169.3 26374.7 26597.3 26683.7 26782.6 26820.5 2421.5 2649.8 2673.7 3014.8 3061.9 16031.7 16069.7 16112.7 16277.8 16749.9 1647.3 1784.3 1908.3 1910.4 1928.8 90129.5 101618.5 128933.7 135068.0 135090.3 11645.1 11894.8 12040.6 12060.3 12083.0 15827.9 15848.4 15895.7 15930.0 15983.1 8684.9 8841.5 8901.5 8913.3 8915.1 8532.7 8545.4 8564.7 8674.8 8723.1 4875.4 4881.9 4884.7 4926.6 4984.7 1773.9 2043.2 2048.8 2049.7 2050.7 963.9 985.7 990.8 991.5 1032.2 16278.5 19364.1 20069.3 21246.6 22369.3 1006.2 1011.2 1035.6 1039.1 1044.7 10842.6 11578.9 11597.7 11613.5 11630.9 671237.0 675324.0 681783.0 712763.0 716536.0 117636.0 124518.0 142704.0 154424.0 157668.0 853.1 969.7 974.4 995.8 1001.0 14959.8 18090.4 18627.8 19192.0 19859.7 60869.7 61213.0 61298.0 61468.7 61526.7 64101.4 81033.0 82501.3 88604.6 91987.0 7391.3 7970.8 8336.2 8773.3 8908.2 10974.9 11743.8 12308.7 12936.4 13505.6 10079.5 10953.9 11404.4 11422.5 11560.9 9290.8 10678.1 11209.1 11387.8 11592.9 11151.9 11193.3 11462.4 11899.0 11910.6 7543.8 7668.9 7694.9 7860.3 7872.8 9450.3 10281.7 11078.2 11458.5 11491.2 10:42:22 INFO - PROCESS | 2545 | 10:42:22 INFO - PROCESS | 2545 | [#3] mootools.html Cycles:180 Average:5328.44 Median:5278.91 stddev:2867.19 (54.3%) stddev-sans-first:2860.16 10:42:22 INFO - PROCESS | 2545 | Values: 1416.1 1431.7 1435.3 1444.4 1445.6 7428.9 7688.8 7982.4 8391.0 8413.3 7864.9 8164.9 8242.9 8272.3 8445.4 2223.8 2261.5 2271.9 2273.5 2277.5 2046.8 2122.8 2246.1 2285.3 2293.0 2211.3 2288.7 2290.9 2296.8 2298.6 2261.7 2271.8 2294.6 2297.6 2298.0 2293.9 2299.5 2319.0 2324.9 2325.8 8309.0 8359.3 8362.0 8381.4 8387.1 5010.1 5138.3 5159.2 5169.6 5192.7 3344.1 3493.1 3493.7 3503.8 3553.6 1175.7 1219.8 1239.8 1269.7 1270.7 10528.5 10616.5 10657.1 10674.8 10683.3 4507.9 4606.5 4631.7 4651.3 4681.6 8365.4 8456.0 8533.0 8536.0 8542.5 5082.5 5095.6 5096.7 5103.6 5106.1 4516.7 4700.2 4740.5 4747.1 4782.8 3599.7 3626.8 3664.4 3712.1 3721.5 953.9 1081.1 1116.2 1156.0 1167.2 724.1 822.4 886.7 906.1 937.5 5237.3 5320.5 5489.8 5592.4 5864.6 838.0 898.7 941.7 981.4 1011.6 3121.4 3516.6 3735.4 3780.8 3825.0 9520.1 9757.2 9819.6 9839.5 9869.1 9086.8 9357.2 9623.0 9744.3 9774.4 7306.3 7609.5 7818.3 7821.9 7824.3 5338.0 5358.9 5520.9 5574.4 5585.5 8776.3 8888.0 9006.1 9045.4 9070.5 6370.9 8994.0 9132.4 9185.4 9265.3 7581.4 7594.6 7642.3 7655.6 7739.8 7638.2 7705.3 7714.8 7722.6 7726.2 6845.6 6933.2 6958.6 6986.6 7098.7 6809.7 6848.8 6904.3 6909.3 6911.7 6217.9 6288.2 6300.7 6303.2 6375.5 4918.1 4978.4 4984.7 5006.6 5062.5 4943.6 5000.8 5040.9 5105.6 5131.4 10:42:22 INFO - PROCESS | 2545 | 10:42:22 INFO - PROCESS | 2545 | [#4] prototype.html Cycles:180 Average:4398.21 Median:4847.22 stddev:1369.22 (28.2%) stddev-sans-first:1339.09 10:42:22 INFO - PROCESS | 2545 | Values: 359.6 363.1 371.9 376.4 378.0 4303.7 4453.2 5075.1 5323.9 5324.5 4575.6 4710.5 5078.4 5083.6 5298.1 3105.9 3187.4 3459.9 4029.8 4184.6 3484.8 3783.6 3886.1 4150.9 4165.5 3768.6 3968.8 3972.3 4002.8 4048.0 3316.7 3475.8 3524.2 3964.0 3989.6 3105.9 3367.9 3516.0 3530.6 3550.9 4869.7 5107.0 5206.6 5207.2 5222.2 3742.8 4602.8 4615.8 4637.8 4650.4 4677.9 5626.7 5635.7 5676.6 5757.1 3244.6 3641.4 3699.4 3801.2 3851.4 4730.3 5662.2 5877.9 5879.2 5930.2 3455.7 3699.6 3836.8 3860.4 3874.6 3046.6 3639.9 3751.9 3950.8 3971.3 4224.5 4481.3 4536.1 4541.3 4552.7 5154.9 5198.8 5679.7 5720.8 5741.5 1154.2 1234.1 1237.4 1268.4 1278.9 850.1 860.4 872.7 882.9 886.7 4662.6 5105.6 5106.6 5165.3 5170.7 4633.5 4663.7 5017.1 5146.4 5244.5 3915.1 4605.7 4815.4 4869.7 5139.3 2588.9 2952.9 2956.3 2957.2 2967.6 4912.7 5016.5 5314.0 5801.0 5892.7 4657.3 5514.3 5608.0 5638.1 5777.0 4640.6 5667.7 5698.7 5707.0 5788.8 4646.0 5228.0 5438.2 5496.3 5502.5 5110.4 5237.8 5427.3 5470.9 5519.9 5156.9 5547.8 5732.3 5750.5 5813.1 3113.1 3592.9 3705.9 3927.9 3934.3 4567.9 5411.4 5603.7 5771.3 5961.3 4858.1 5289.6 5384.5 5620.3 5671.7 4050.0 4955.8 5558.6 5589.8 5657.5 4200.5 4461.0 4836.3 5535.6 5570.8 5623.0 5676.4 5758.4 5779.5 5783.8 4828.7 5570.0 5580.4 5613.5 5621.8 10:42:22 INFO - PROCESS | 2545 | 10:42:22 INFO - PROCESS | 2545 | [#5] yui.html Cycles:180 Average:20459.90 Median:4366.36 stddev:30087.04 (689.1%) stddev-sans-first:30134.77 10:42:22 INFO - PROCESS | 2545 | Values: 677.9 681.8 753.7 818.4 821.3 13294.9 14787.6 14937.3 15897.4 15910.0 14325.6 14362.7 14396.8 14460.1 14519.7 873.6 882.4 897.4 898.7 900.5 839.6 878.4 879.5 880.7 881.4 916.6 926.2 935.4 937.5 937.7 912.7 921.3 928.2 932.7 935.5 663.7 694.2 699.5 700.1 700.2 14073.4 14214.6 14822.0 15264.5 15468.1 14023.4 14110.3 14169.4 14215.4 14358.4 11048.6 11612.2 11768.1 11777.5 11859.3 3600.7 3680.3 3719.2 3826.1 3843.6 114797.3 115003.3 115570.4 116024.7 118831.8 10832.7 10845.1 10896.5 11068.8 11096.4 22459.1 22662.4 22758.2 22761.4 22815.4 7641.4 7702.5 7722.2 7730.8 7879.7 4275.2 4322.0 4336.9 4395.8 4530.9 3562.4 3578.2 3666.4 3667.2 3679.1 650.5 658.9 662.5 711.3 724.7 635.9 659.7 683.5 688.6 709.8 8896.6 10083.5 10230.1 10330.0 10356.3 605.1 666.6 683.5 707.0 710.5 4481.8 4865.7 4882.3 4894.3 4896.6 97873.0 100237.5 101557.0 101733.0 103415.0 81158.6 84056.6 84254.0 85256.1 88241.0 73893.0 74313.6 74746.6 75260.5 76148.0 9150.6 9246.4 9306.3 9308.0 9310.8 49727.3 53682.5 54943.5 55010.2 55328.7 71510.3 71588.3 72602.0 74013.0 74108.6 11416.4 11430.4 11619.2 11805.6 11905.2 12825.8 13049.0 13060.8 13098.4 13105.9 11814.6 11864.5 11873.3 11892.0 12283.9 11549.8 11832.1 11956.3 12166.8 12187.9 11618.7 11680.3 11721.7 11738.6 12176.9 10039.7 10206.3 10310.3 10440.1 10496.0 6844.3 8702.9 8864.8 9678.0 9703.2 10:42:22 INFO - PROCESS | 2545 | -------- Summary: end -------- 10:42:22 INFO - PROCESS | 2545 | 10:42:22 INFO - TEST-INFO | 2545: exit 0 10:42:22 INFO - TEST-OK | dromaeo_css | took 1115051ms 10:42:22 INFO - TEST-START | kraken 10:42:22 INFO - operating with platform_type : mac_ 10:42:22 INFO - Initialising browser for kraken test... 10:42:22 INFO - TEST-INFO | started process 2555 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/tmpPsmnU6/profile http://localhost:55238/getInfo.html) 10:42:25 INFO - PROCESS | 2555 | JavaScript error: chrome://webcompat/content/lib/ua_overrider.jsm, line 109: NS_ERROR_INSUFFICIENT_DOMAIN_LEVELS: Component returned failure code: 0x804b0050 (NS_ERROR_INSUFFICIENT_DOMAIN_LEVELS) [nsIEffectiveTLDService.getBaseDomain] 10:42:25 INFO - PROCESS | 2555 | 2017-01-29 10:42:25.640 plugin-container[2556:301307] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x443f, name = 'com.apple.tsm.portname' 10:42:25 INFO - PROCESS | 2555 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:42:25 INFO - PROCESS | 2555 | 2017-01-29 10:42:25.643 plugin-container[2556:301307] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x900b, name = 'com.apple.CFPasteboardClient' 10:42:25 INFO - PROCESS | 2555 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:42:25 INFO - PROCESS | 2555 | 2017-01-29 10:42:25.643 plugin-container[2556:301307] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 10:42:25 INFO - PROCESS | 2555 | __metrics Screen width/height:1600/1200 10:42:25 INFO - PROCESS | 2555 | colorDepth:24 10:42:25 INFO - PROCESS | 2555 | Browser inner width/height: 1024/689 10:42:25 INFO - PROCESS | 2555 | __metrics 10:42:25 ERROR - PROCESS | 2555 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3279: TypeError: this.tabs is undefined 10:42:25 INFO - PROCESS | 2555 | 1485715345788 addons.xpi WARN Exception running bootstrap method shutdown on shield-recipe-client@mozilla.org: ReferenceError: LogManager is not defined (resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:65:3) JS Stack trace: this.shutdown@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@bootstrap.js:65:3 < callBootstrapMethod@XPIProvider.jsm:4903:11 < observe@XPIProvider.jsm:2884:15 < forceQuit@jar:file:///var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/tmpPsmnU6/profile/extensions/talos-powers@TalosPowersService.js:238:7 < whenDone@jar:file:///var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/tmpPsmnU6/profile/extensions/talos-powers@TalosPowersService.js:223:9 < promise callback*forceQuit@jar:file:///var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/tmpPsmnU6/profile/extensions/talos-powers@TalosPowersService.js:225:7 < receiveMessage@jar:file:///var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/tmpPsmnU6/profile/extensions/talos-powers@TalosPowersService.js:55:9 10:42:26 INFO - TEST-INFO | 2555: exit 0 10:42:26 INFO - Browser initialized. 10:42:26 INFO - Running cycle 1/1 for kraken test... 10:42:26 INFO - TEST-INFO | started process 2557 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/T/tmpPsmnU6/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:42:29 INFO - PROCESS | 2557 | 2017-01-29 10:42:29.023 plugin-container[2558:301424] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4347, name = 'com.apple.tsm.portname' 10:42:29 INFO - PROCESS | 2557 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:42:29 INFO - PROCESS | 2557 | 2017-01-29 10:42:29.024 plugin-container[2558:301424] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8b1f, name = 'com.apple.CFPasteboardClient' 10:42:29 INFO - PROCESS | 2557 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:42:29 INFO - PROCESS | 2557 | 2017-01-29 10:42:29.024 plugin-container[2558:301424] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 10:42:38 INFO - PROCESS | 2557 | RSS: Main: 233771008 10:42:38 INFO - PROCESS | 2557 | 10:42:41 INFO - PROCESS | 2557 | Cycle 1(1): loaded http://localhost:55238/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:55238/tests/kraken/driver.html?testName=audio-beat-detection) 10:42:42 INFO - PROCESS | 2557 | RSS: Main: 247435264 10:42:42 INFO - PROCESS | 2557 | 10:42:45 INFO - PROCESS | 2557 | Cycle 1(1): loaded http://localhost:55238/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:55238/tests/kraken/driver.html?testName=audio-dft) 10:42:46 INFO - PROCESS | 2557 | RSS: Main: 256319488 10:42:46 INFO - PROCESS | 2557 | 10:42:49 INFO - PROCESS | 2557 | Cycle 1(1): loaded http://localhost:55238/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:55238/tests/kraken/driver.html?testName=audio-fft) 10:42:50 INFO - PROCESS | 2557 | RSS: Main: 258281472 10:42:50 INFO - PROCESS | 2557 | 10:42:52 INFO - PROCESS | 2557 | Cycle 1(1): loaded http://localhost:55238/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:55238/tests/kraken/driver.html?testName=audio-oscillator) 10:42:52 INFO - PROCESS | 2557 | RSS: Main: 260513792 10:42:52 INFO - PROCESS | 2557 | 10:42:54 INFO - PROCESS | 2557 | Cycle 1(1): loaded http://localhost:55238/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:55238/tests/kraken/driver.html?testName=imaging-gaussian-blur) 10:42:55 INFO - PROCESS | 2557 | RSS: Main: 260841472 10:42:55 INFO - PROCESS | 2557 | 10:42:58 INFO - PROCESS | 2557 | Cycle 1(1): loaded http://localhost:55238/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:55238/tests/kraken/driver.html?testName=imaging-darkroom) 10:42:59 INFO - PROCESS | 2557 | RSS: Main: 266604544 10:42:59 INFO - PROCESS | 2557 | 10:43:02 INFO - PROCESS | 2557 | Cycle 1(1): loaded http://localhost:55238/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:55238/tests/kraken/driver.html?testName=imaging-desaturate) 10:43:02 INFO - PROCESS | 2557 | RSS: Main: 265670656 10:43:02 INFO - PROCESS | 2557 | 10:43:05 INFO - PROCESS | 2557 | Cycle 1(1): loaded http://localhost:55238/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:55238/tests/kraken/driver.html?testName=json-parse-financial) 10:43:06 INFO - PROCESS | 2557 | RSS: Main: 265302016 10:43:06 INFO - PROCESS | 2557 | 10:43:08 INFO - PROCESS | 2557 | Cycle 1(1): loaded http://localhost:55238/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:55238/tests/kraken/driver.html?testName=json-stringify-tinderbox) 10:43:08 INFO - PROCESS | 2557 | RSS: Main: 265011200 10:43:08 INFO - PROCESS | 2557 | 10:43:10 INFO - PROCESS | 2557 | Cycle 1(1): loaded http://localhost:55238/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:55238/tests/kraken/driver.html?testName=stanford-crypto-aes) 10:43:11 INFO - PROCESS | 2557 | RSS: Main: 265015296 10:43:11 INFO - PROCESS | 2557 | 10:43:13 INFO - PROCESS | 2557 | Cycle 1(1): loaded http://localhost:55238/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:55238/tests/kraken/driver.html?testName=stanford-crypto-ccm) 10:43:14 INFO - PROCESS | 2557 | RSS: Main: 265072640 10:43:14 INFO - PROCESS | 2557 | 10:43:17 INFO - PROCESS | 2557 | Cycle 1(1): loaded http://localhost:55238/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:55238/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 10:43:17 INFO - PROCESS | 2557 | RSS: Main: 265355264 10:43:17 INFO - PROCESS | 2557 | 10:43:21 INFO - PROCESS | 2557 | Cycle 1(1): loaded http://localhost:55238/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:55238/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 10:43:21 INFO - PROCESS | 2557 | RSS: Main: 262787072 10:43:21 INFO - PROCESS | 2557 | 10:43:23 INFO - PROCESS | 2557 | Cycle 1(1): loaded http://localhost:55238/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:55238/tests/kraken/driver.html?testName=ai-astar) 10:43:23 INFO - PROCESS | 2557 | RSS: Main: 264765440 10:43:23 INFO - PROCESS | 2557 | 10:43:23 INFO - PROCESS | 2557 | __start_tp_report 10:43:23 INFO - PROCESS | 2557 | _x_x_mozilla_page_load 10:43:23 INFO - PROCESS | 2557 | _x_x_mozilla_page_load_details 10:43:23 INFO - PROCESS | 2557 | |i|pagename|runs| 10:43:23 INFO - PROCESS | 2557 | |0;ai-astar;108;119;121;118;119;120;119;125;124;119 10:43:23 INFO - PROCESS | 2557 | |1;audio-beat-detection;153;138;147;185;113;113;114;113;132;116 10:43:23 INFO - PROCESS | 2557 | |2;audio-dft;206;208;265;279;197;250;219;204;195;203 10:43:23 INFO - PROCESS | 2557 | |3;audio-fft;86;58;60;84;79;86;81;63;73;58 10:43:23 INFO - PROCESS | 2557 | |4;audio-oscillator;86;72;98;73;72;102;114;87;97;69 10:43:23 INFO - PROCESS | 2557 | |5;imaging-gaussian-blur;89;76;133;114;121;132;135;86;90;86 10:43:23 INFO - PROCESS | 2557 | |6;imaging-darkroom;95;120;96;96;99;96;97;93;123;137 10:43:23 INFO - PROCESS | 2557 | |7;imaging-desaturate;92;93;92;93;90;93;91;93;98;92 10:43:23 INFO - PROCESS | 2557 | |8;json-parse-financial;60;58;57;77;63;62;60;61;63;85 10:43:23 INFO - PROCESS | 2557 | |9;json-stringify-tinderbox;67;75;76;84;75;69;69;71;73;73 10:43:23 INFO - PROCESS | 2557 | |10;stanford-crypto-aes;90;91;92;86;92;83;102;124;77;99 10:43:23 INFO - PROCESS | 2557 | |11;stanford-crypto-ccm;140;147;137;148;155;149;163;143;168;152 10:43:23 INFO - PROCESS | 2557 | |12;stanford-crypto-pbkdf2;164;245;214;187;244;187;176;229;183;174 10:43:23 INFO - PROCESS | 2557 | |13;stanford-crypto-sha256-iterative;72;69;66;69;67;70;67;69;68;71 10:43:23 INFO - PROCESS | 2557 | __end_tp_report 10:43:23 INFO - PROCESS | 2557 | __start_cc_report 10:43:23 INFO - PROCESS | 2557 | _x_x_mozilla_cycle_collect,943 10:43:23 INFO - PROCESS | 2557 | __end_cc_report 10:43:23 INFO - PROCESS | 2557 | __startTimestamp1485715403801__endTimestamp 10:43:23 INFO - PROCESS | 2557 | 10:43:23 INFO - PROCESS | 2557 | ------- Summary: start ------- 10:43:23 INFO - PROCESS | 2557 | Number of tests: 14 10:43:23 INFO - PROCESS | 2557 | 10:43:23 INFO - PROCESS | 2557 | [#0] ai-astar Cycles:10 Average:119.20 Median:119.50 stddev:4.57 (3.8%) stddev-sans-first:2.46 10:43:23 INFO - PROCESS | 2557 | Values: 108.0 119.0 121.0 118.0 119.0 120.0 119.0 125.0 124.0 119.0 10:43:23 INFO - PROCESS | 2557 | 10:43:23 INFO - PROCESS | 2557 | [#1] audio-beat-detection Cycles:10 Average:132.40 Median:135.00 stddev:23.97 (17.8%) stddev-sans-first:24.24 10:43:23 INFO - PROCESS | 2557 | Values: 153.0 138.0 147.0 185.0 113.0 113.0 114.0 113.0 132.0 116.0 10:43:23 INFO - PROCESS | 2557 | 10:43:23 INFO - PROCESS | 2557 | [#2] audio-dft Cycles:10 Average:222.60 Median:213.50 stddev:30.51 (14.3%) stddev-sans-first:31.77 10:43:23 INFO - PROCESS | 2557 | Values: 206.0 208.0 265.0 279.0 197.0 250.0 219.0 204.0 195.0 203.0 10:43:23 INFO - PROCESS | 2557 | 10:43:23 INFO - PROCESS | 2557 | [#3] audio-fft Cycles:10 Average:72.80 Median:80.00 stddev:11.91 (14.9%) stddev-sans-first:11.64 10:43:23 INFO - PROCESS | 2557 | Values: 86.0 58.0 60.0 84.0 79.0 86.0 81.0 63.0 73.0 58.0 10:43:23 INFO - PROCESS | 2557 | 10:43:23 INFO - PROCESS | 2557 | [#4] audio-oscillator Cycles:10 Average:87.00 Median:79.50 stddev:15.44 (19.4%) stddev-sans-first:16.37 10:43:23 INFO - PROCESS | 2557 | Values: 86.0 72.0 98.0 73.0 72.0 102.0 114.0 87.0 97.0 69.0 10:43:23 INFO - PROCESS | 2557 | 10:43:23 INFO - PROCESS | 2557 | [#5] imaging-gaussian-blur Cycles:10 Average:106.20 Median:81.00 stddev:23.04 (28.5%) stddev-sans-first:23.59 10:43:23 INFO - PROCESS | 2557 | Values: 89.0 76.0 133.0 114.0 121.0 132.0 135.0 86.0 90.0 86.0 10:43:23 INFO - PROCESS | 2557 | 10:43:23 INFO - PROCESS | 2557 | [#6] imaging-darkroom Cycles:10 Average:105.20 Median:96.00 stddev:15.49 (16.1%) stddev-sans-first:15.98 10:43:23 INFO - PROCESS | 2557 | Values: 95.0 120.0 96.0 96.0 99.0 96.0 97.0 93.0 123.0 137.0 10:43:23 INFO - PROCESS | 2557 | 10:43:23 INFO - PROCESS | 2557 | [#7] imaging-desaturate Cycles:10 Average:92.70 Median:93.00 stddev:2.11 (2.3%) stddev-sans-first:2.22 10:43:23 INFO - PROCESS | 2557 | Values: 92.0 93.0 92.0 93.0 90.0 93.0 91.0 93.0 98.0 92.0 10:43:23 INFO - PROCESS | 2557 | 10:43:23 INFO - PROCESS | 2557 | [#8] json-parse-financial Cycles:10 Average:64.60 Median:62.50 stddev:9.06 (14.5%) stddev-sans-first:9.45 10:43:23 INFO - PROCESS | 2557 | Values: 60.0 58.0 57.0 77.0 63.0 62.0 60.0 61.0 63.0 85.0 10:43:23 INFO - PROCESS | 2557 | 10:43:23 INFO - PROCESS | 2557 | [#9] json-stringify-tinderbox Cycles:10 Average:73.20 Median:74.00 stddev:4.83 (6.5%) stddev-sans-first:4.57 10:43:23 INFO - PROCESS | 2557 | Values: 67.0 75.0 76.0 84.0 75.0 69.0 69.0 71.0 73.0 73.0 10:43:23 INFO - PROCESS | 2557 | 10:43:23 INFO - PROCESS | 2557 | [#10] stanford-crypto-aes Cycles:10 Average:93.60 Median:90.50 stddev:12.89 (14.2%) stddev-sans-first:13.60 10:43:23 INFO - PROCESS | 2557 | Values: 90.0 91.0 92.0 86.0 92.0 83.0 102.0 124.0 77.0 99.0 10:43:23 INFO - PROCESS | 2557 | 10:43:23 INFO - PROCESS | 2557 | [#11] stanford-crypto-ccm Cycles:10 Average:150.20 Median:150.50 stddev:9.74 (6.5%) stddev-sans-first:9.60 10:43:23 INFO - PROCESS | 2557 | Values: 140.0 147.0 137.0 148.0 155.0 149.0 163.0 143.0 168.0 152.0 10:43:23 INFO - PROCESS | 2557 | 10:43:23 INFO - PROCESS | 2557 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:200.30 Median:200.50 stddev:30.13 (15.0%) stddev-sans-first:28.96 10:43:23 INFO - PROCESS | 2557 | Values: 164.0 245.0 214.0 187.0 244.0 187.0 176.0 229.0 183.0 174.0 10:43:23 INFO - PROCESS | 2557 | 10:43:23 INFO - PROCESS | 2557 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.80 Median:69.00 stddev:1.87 (2.7%) stddev-sans-first:1.59 10:43:23 INFO - PROCESS | 2557 | Values: 72.0 69.0 66.0 69.0 67.0 70.0 67.0 69.0 68.0 71.0 10:43:23 INFO - PROCESS | 2557 | -------- Summary: end -------- 10:43:23 INFO - PROCESS | 2557 | 10:43:24 INFO - TEST-INFO | 2557: exit 0 10:43:24 INFO - TEST-OK | kraken | took 61929ms 10:43:24 INFO - SUITE-END | took 1176s 10:43:24 INFO - Completed test suite (00:19:37) 10:43:24 INFO - javascript benchmark 10:43:25 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7256.857123380804, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3332.600087989441, 3333.050080726995, 3364.798112113272, 3371.0, 3393.847276872542, 5274.945010997797, 5592.468715472029, 5628.859278518039, 5639.887202255953, 5669.659820410768, 4876.97561512192, 5632.35227948786, 5639.041362968295, 5710.086386178213, 5746.109353050273, 5084.542391184793, 5136.768845401947, 5231.372235331765, 5236.345456817898, 5245.868853278679, 5128.71792051437, 5267.367915850104, 5270.9736451317685, 5275.314209152804, 5320.600954928376, 5061.797528098871, 5072.67027643202, 5146.922796158061, 5207.531322181003, 5228.2680424740565, 5083.745812709387, 5148.716820574867, 5165.560927321182, 5177.0, 5177.741112944338, 5080.847574572768, 5086.79652813887, 5099.770510327007, 5126.359205099363, 5215.061288967984, 4282.593153650399, 5236.266922630834, 5264.657797243205, 5343.812966546152, 5541.556675465953, 6101.701646049711, 6141.424638644238, 6153.7230824613, 6186.072089186658, 6209.534284928648, 5210.400803907523, 6121.938780612226, 6170.784022559189, 6234.220722409699, 6256.092866534327, 5675.120356344773, 5891.587588868823, 5899.76400943966, 5905.320888097881, 5913.733881975321, 6109.495762817221, 6138.938610613836, 6148.8770224595255, 6175.104609831549, 6238.438540531283, 6046.893259404724, 6116.694165291718, 6118.694065296718, 6133.754649814046, 6138.631682099089, 6095.512359011268, 6176.598521096114, 6240.0, 6254.21822272216, 6288.276848162428, 6091.817245482643, 6111.682900513672, 6116.67056464255, 6128.448439640454, 6220.284667263232, 6216.564840461125, 6219.720112594944, 6232.376762323822, 6235.345288744707, 6245.781397651061, 4993.850184494471, 4999.950000499948, 5002.474740152305, 5007.499250074963, 5009.674371165863, 3651.123730304708, 4372.060007098489, 4384.605385515319, 4406.537313582092, 4459.9331010034875, 5738.426157384311, 5825.03886858672, 5829.213056237354, 5841.9415805842245, 5853.824385268449, 5524.889502209933, 6191.318954914956, 6194.132821405007, 6232.439080482778, 6245.437910588069, 5603.327600687943, 5676.262085928803, 5700.37295897451, 5704.1443783432815, 5829.708514574254, 5423.538999185113, 5424.457554244623, 5428.430014848463, 5434.70109143993, 5462.808801692001, 6259.6870156492, 6264.812055638338, 6283.748650053946, 6284.905726414016, 6319.526035547352, 6124.74712746768, 6127.20346354971, 6139.461473530676, 6227.252729672468, 6247.531435142473, 6153.753849846135, 6204.906926396198, 6210.813675589739, 6233.470155036873, 6255.46828519581, 6153.13856060143, 6180.783672571439, 6201.348858369806, 6205.28639206479, 6208.192934918432, 6137.263528376623, 6140.320203811367, 6245.718942647501, 6250.843728906631, 6254.874902502015, 5912.260967379078, 6058.787942421994, 6080.270367555921, 6111.572189946661, 6124.625058545555, 6104.38956104386, 6107.725152368065, 6115.908261375994, 6122.265328160649, 6130.938690613037, 4278.6790990676445, 5696.743646535999, 5811.593188476766, 5846.912296315473, 5892.175095486549, 6045.909311360421, 6092.45167934888, 6111.022236442148, 6127.724252408563, 6142.877142457215, 5872.823815285548, 5875.089361149029, 5881.970655135248, 5887.764489420375, 5889.587728858939], "value": 5633.98441225102, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [458.0586893932966, 461.47852926193406, 488.08727679239826, 490.08109794135993, 496.4167103653208, 37495.43756843649, 39938.400923986126, 43387.34918976214, 43505.34741978869, 45644.315335269996, 44228.11543769131, 44607.88480287995, 44851.87870303244, 44871.55128448715, 44952.0, 7872.409569282298, 9365.67220147295, 9553.952230238874, 9581.90418095817, 9620.42277463351, 8440.788980275482, 11378.943105284461, 11650.009749171288, 11706.473208705585, 11854.703632409215, 11640.301581905112, 11674.241174323684, 11687.714351421337, 11697.59058432955, 11704.53181872724, 11664.941675291655, 11710.648680539598, 11727.944484996306, 11736.119791015708, 11742.354170520617, 11479.426028698532, 11517.308961462339, 11575.53697852085, 11579.53681852725, 11585.884141158565, 41957.0, 42020.36969445461, 42038.0, 42320.365194522106, 42435.0, 4012.9398059029136, 4059.6346328830546, 4109.321961876317, 4118.052847845012, 4197.538270790211, 32058.519122213187, 32067.679323206703, 32071.0, 32075.358492830244, 32273.83863080693, 2844.743973042436, 3158.384115097557, 3277.8033318001, 3373.8650453981813, 3391.6947474727394, 491198.0, 495568.0, 497350.0, 497649.0, 498123.18768123625, 66027.0, 66366.66816665886, 66635.33364666389, 66668.6666566664, 66709.6664516684, 69978.0, 70005.0, 70253.64873175704, 70444.82998264988, 70604.6469767648, 13171.538996135088, 16445.342186312653, 16610.418635347705, 16637.41769038078, 16669.749953750703, 11274.7181320466, 11603.187776855539, 12404.875951240372, 12598.748025039447, 12730.80903786444, 18834.71747923782, 19419.90290048569, 19435.028248587514, 19447.222111115396, 19567.412977610693, 7592.544447333178, 7831.490953088031, 7836.294733474015, 7886.408519361071, 7974.481658692166, 756.5082696247373, 775.8525880082766, 786.2805532937298, 793.4644115222202, 799.9240072193132, 22945.08219671228, 24171.637425438632, 30009.69990300113, 30127.397452051275, 30449.08652740421, 934.56075644447, 994.9950250248702, 1016.2936758952457, 1017.2624846985876, 1029.3000759483473, 15449.227538623023, 15570.84429155694, 15570.9221453892, 15740.291686874118, 16028.439004635014, 549151.0, 588726.0, 601228.0, 655993.0, 657637.0, 60998.0, 61013.0, 61207.693961529905, 61437.078443823375, 61464.07803882945, 1318.7296604196117, 1336.5121730568274, 1375.8555351688128, 1376.876081152701, 1376.951806686761, 24366.75633243645, 28294.0, 28486.43324617166, 29634.703652964057, 29686.554701679925, 41545.58454415538, 52225.7388713054, 52586.47413525969, 52802.471975279754, 52920.20619690785, 489279.0, 491305.0, 491788.0, 492464.0, 492614.5369273131, 26674.066407675637, 26842.0, 26871.596926046517, 26968.595471067558, 27071.59392609152, 39827.0, 40950.0, 48706.26940595965, 48756.0, 48847.755761220964, 39566.010849728984, 39865.80067099762, 40364.79817600893, 40573.79713101416, 41819.0, 39820.606278780106, 40485.1902961945, 40906.74295704721, 40979.59020409758, 41503.7924810374, 37988.860334190016, 38032.61967380291, 38795.6120438792, 38840.80579597084, 38900.0, 31301.8434907824, 34534.48198277078, 34928.0, 35151.82424087863, 35208.47187292244, 20474.36832482434, 25252.24243272705, 25347.239582812545, 25555.0, 25890.482190356463], "value": 20377.098638406555, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [346.55986896641264, 375.7520036775729, 392.7466783924369, 410.2553864735507, 410.48689138576776, 20168.294109706163, 21540.676889846625, 21860.672089918626, 22077.66883496747, 22342.882855857224, 15794.921025394857, 18971.71542426865, 21676.674849877225, 21853.781462185412, 21979.010944507496, 5202.401723801754, 5445.373782015079, 5722.513586345166, 5946.286445626531, 5991.64050156992, 5781.913271300934, 5919.289685237777, 5920.407959204088, 5950.196723442323, 5956.255468066492, 5639.7180140993, 5780.710964451781, 5844.123381492789, 5851.590388672774, 5855.033919403293, 5600.887982240373, 5813.302403711539, 5841.153032810219, 5842.47417732404, 5852.034414321611, 5127.871803204913, 5131.897362052775, 5156.664816786897, 5167.354080739908, 5169.302144210521, 26374.73625263742, 26597.335066623295, 26683.73316266851, 26782.5982610261, 26820.463590728075, 2421.4551725861716, 2649.7880169586388, 2673.7459941305547, 3014.80403773754, 3061.8853561571354, 16031.679366412607, 16069.678606427806, 16112.677746445122, 16277.755833662504, 16749.91625041879, 1647.2751989124747, 1784.295203394665, 1908.319218791139, 1910.3695780392577, 1928.7974762650001, 90129.54935225281, 101618.49190754072, 128933.7106628931, 135068.0, 135090.32454837664, 11645.12661550387, 11894.799462099367, 12040.638780836589, 12060.336681482428, 12083.0, 15827.920860395854, 15848.44530441429, 15895.68208635844, 15929.964552304064, 15983.12092834882, 8684.913150868537, 8841.469511829311, 8901.510416927002, 8913.331500137387, 8915.12782315708, 8532.744017679479, 8545.444546104483, 8564.700235491728, 8674.826503469896, 8723.084076172037, 4875.352880550864, 4881.926771098365, 4884.7069175849565, 4926.58124059459, 4984.651074424755, 1773.866959978007, 2043.1725151313742, 2048.805363490466, 2049.7130401743766, 2050.7026481160146, 963.9155949556749, 985.7042887133831, 990.8216521026285, 991.4992928571025, 1032.1587905856702, 16278.51164465068, 19364.128614212394, 20069.29757458482, 21246.575068498543, 22369.328920132422, 1006.2201793609864, 1011.1708399112657, 1035.5754140802235, 1039.111559616532, 1044.7022598559527, 10842.62050828233, 11578.884211157949, 11597.710057248636, 11613.477393517313, 11630.88369116315, 671237.0, 675324.0, 681783.0, 712763.0, 716536.0, 117636.0, 124518.0, 142704.0, 154424.0, 157668.0, 853.1127627267572, 969.6945462179391, 974.4202199691173, 995.7759504111517, 1000.9949950250202, 14959.77560336574, 18090.366837160636, 18627.813721862607, 19192.040397979767, 19859.702104468735, 60869.69565152146, 61213.0, 61298.0, 61468.692656536434, 61526.69236653788, 64101.35898640954, 81033.0, 82501.349972999, 88604.5569772147, 91987.0, 7391.260873912565, 7970.840583188187, 8336.208060234268, 8773.341999349946, 8908.21835632863, 10974.945125274322, 11743.823842642538, 12308.674423591801, 12936.417861196456, 13505.594832155051, 10079.496025198858, 10953.890461095605, 11404.372759498307, 11422.485988130387, 11560.94219528897, 9290.767730806783, 10678.092362149304, 11209.103271738079, 11387.829182562435, 11592.88407115918, 11151.944240278746, 11193.27243729155, 11462.369569673749, 11899.0, 11910.583129590423, 7543.811404714926, 7668.923310767044, 7694.923050769421, 7860.292573668397, 7872.84254314922, 9450.291228157777, 10281.74295642615, 11078.169137314573, 11458.541658333574, 11491.195616306779], "value": 9943.783632006485, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1416.0866241274377, 1431.7494438473263, 1435.3253970633798, 1444.3789170656617, 1445.6096853849458, 7428.851422971537, 7688.807779805507, 7982.401319901009, 8391.0, 8413.326933845294, 7864.882026769603, 8164.91835081649, 8242.876356854653, 8272.338212942965, 8445.366597505197, 2223.844330896838, 2261.502469456717, 2271.931842044741, 2273.4771002669395, 2277.4989502309463, 2046.8260197883199, 2122.7877212278727, 2246.146464343555, 2285.325828880476, 2293.0, 2211.2702808073295, 2288.7253529576474, 2290.89690963907, 2296.781805728453, 2298.586254474202, 2261.683364328995, 2271.8409711320214, 2294.621387471065, 2297.6323788193977, 2298.0, 2293.896774645128, 2299.5171014087064, 2319.0, 2324.9070037198494, 2325.7906788389128, 8309.044459887129, 8359.331253499706, 8362.0, 8381.371397145238, 8387.077421483631, 5010.123228435045, 5138.280640710266, 5159.2003239497935, 5169.586433085344, 5192.71440070796, 3344.1171128271426, 3493.074335301147, 3493.737969652286, 3503.7722548034294, 3553.6091029986683, 1175.729582196091, 1219.7926352520092, 1239.783037968352, 1269.6698858296818, 1270.748312911781, 10528.473576321154, 10616.469176541143, 10657.147428205724, 10674.839877401846, 10683.252172347939, 4507.864764057083, 4606.470255920612, 4631.722096674211, 4651.2557990721325, 4681.625469962395, 8365.372597055124, 8455.95772021136, 8533.0, 8536.0, 8542.530160841088, 5082.466341034212, 5095.566876815438, 5096.668716533413, 5103.642745007888, 5106.131957567222, 4516.728996260234, 4700.153972284953, 4740.502247264057, 4747.098051370229, 4782.760861956888, 3599.7300202484917, 3626.818659067036, 3664.4320130379833, 3712.0719820044988, 3721.5348081489815, 953.9379940303858, 1081.0972837680624, 1116.1963386361806, 1155.9595414160465, 1167.2062997161843, 724.0551080839555, 822.3503432288425, 886.663068034143, 906.1074841281333, 937.4609599480326, 5237.292965449692, 5320.521153096162, 5489.807856724996, 5592.440755924375, 5864.618799778001, 837.9748607541661, 898.7258886039821, 941.7174847545709, 981.4062492192121, 1011.6256984915628, 3121.395940987026, 3516.558211133423, 3735.3836616958442, 3780.7542509737327, 3824.9808750956067, 9520.143187113194, 9757.219422446187, 9819.558119884623, 9839.508024598885, 9869.11177993984, 9086.772830679154, 9357.204637605744, 9622.951885240669, 9744.269179811543, 9774.413535187912, 7306.342429181293, 7609.505382150142, 7818.296353328228, 7821.882671759929, 7824.256695613907, 5338.0, 5358.919616205682, 5520.889582208413, 5574.386817450159, 5585.525230355465, 8776.34177436682, 8887.955560222157, 9006.054364291916, 9045.412048216846, 9070.501122438329, 6370.90443643355, 8994.010658827654, 9132.406393584395, 9185.402948808307, 9265.305102117234, 7581.431392645684, 7594.6202689866395, 7642.312191902755, 7655.57894315818, 7739.806504837199, 7638.236176382317, 7705.345045670956, 7714.768556943301, 7722.6138693063995, 7726.227377262228, 6845.589264643938, 6933.237343892067, 6958.582485050913, 6986.615736134561, 7098.680559374944, 6809.727610895707, 6848.760293389708, 6904.275051119761, 6909.30906909305, 6911.723531058701, 6217.937820621917, 6288.182536270256, 6300.653464059521, 6303.243610766737, 6375.489960803217, 4918.1393256180745, 4978.377702787152, 4984.7258400788305, 5006.5994720423005, 5062.468440813662, 4943.5797957174045, 5000.824971125993, 5040.924386134137, 5105.617078719037, 5131.3842338918075], "value": 4338.792368104441, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [359.6062311768614, 363.12305781537583, 371.8791392797341, 376.4428645604507, 378.0341228162045, 4303.741775493472, 4453.151941944094, 5075.122204784235, 5323.866903327419, 5324.467553244674, 4575.56532129448, 4710.458297295812, 5078.39059312883, 5083.644794435673, 5298.125809241471, 3105.922351941197, 3187.378461200067, 3459.948100778484, 4029.838806447753, 4184.623383895449, 3484.8431820568007, 3783.602721714222, 3886.145048089416, 4150.854720084798, 4165.500139983191, 3768.6231376862233, 3968.8214030368704, 3972.304846651839, 4002.779847108408, 4047.98800299925, 3316.718078963267, 3475.7740746851373, 3524.18943642961, 3963.960360396388, 3989.640932316091, 3105.8602362893726, 3367.888596763062, 3515.982420087909, 3530.6292839252023, 3550.928981420383, 4869.659123861331, 5107.008266775961, 5206.609504287194, 5207.244949482341, 5222.242774797671, 3742.8128593570486, 4602.792874320664, 4615.8153673853, 4637.768111594407, 4650.4186976627925, 4677.906441871143, 5626.690532020737, 5635.746391412396, 5676.574256930706, 5757.107648314476, 3244.6430892601793, 3641.3809652358896, 3699.426588878728, 3801.182745709658, 3851.364524853396, 4730.314104454835, 5662.207290979266, 5877.883961773758, 5879.177454988967, 5930.229070220844, 3455.7235421166247, 3699.648533389324, 3836.848945316394, 3860.4209368594934, 3874.612538746103, 3046.558249053875, 3639.85344616445, 3751.8686845960806, 3950.7827069511454, 3971.3447281198855, 4224.4930608327195, 4481.305397663302, 4536.138133754642, 4541.318802179633, 4552.7495987720995, 5154.948450515447, 5198.764531251598, 5679.716014199274, 5720.799772007959, 5741.45456181662, 1154.2093665838759, 1234.0867757859298, 1237.3874931908763, 1268.3594784633701, 1278.9040821938393, 850.0606829453365, 860.3695996087504, 872.6945569050781, 882.8587426011808, 886.689658619478, 4662.580367766917, 5105.617078719111, 5106.642535022513, 5165.302684137669, 5170.663906846118, 4633.513481084505, 4663.673542852036, 5017.093504468278, 5146.356705411824, 5244.475552444801, 3915.1190982029384, 4605.654575906753, 4815.374001379798, 4869.732164730974, 5139.280500729901, 2588.9482210355686, 2952.852357382122, 2956.2904902823598, 2957.2015555800244, 2967.6438827340858, 4912.72979986097, 5016.498350164954, 5313.954127660879, 5800.970995145081, 5892.672394404186, 4657.252221189515, 5514.338279406497, 5608.0, 5638.126090455986, 5777.046787280052, 4640.582347588733, 5667.688277144714, 5698.686572238566, 5707.0, 5788.82633520995, 4646.038466669047, 5228.0, 5438.18427235902, 5496.31296087989, 5502.532284755841, 5110.4123025852505, 5237.764300606405, 5427.348718153847, 5470.945290547043, 5519.8896022080135, 5156.948430515647, 5547.83634382583, 5732.254806875079, 5750.482456578928, 5813.09896965971, 3113.1438854315243, 3592.922123363033, 3705.9073523162137, 3927.9214415710953, 3934.3114954882208, 4567.931481027721, 5411.431799660979, 5603.739158689263, 5771.336296325976, 5961.314448838439, 4858.1012512685265, 5289.6297259193025, 5384.515393614593, 5620.325560932551, 5671.744771485211, 4049.979750101231, 4955.8265460710145, 5558.610897237317, 5589.832305030855, 5657.538970547825, 4200.474940632421, 4461.0, 4836.331465644347, 5535.564351685246, 5570.805021824379, 5622.971885140548, 5676.4323567642905, 5758.395368486249, 5779.450952159538, 5783.768649253982, 4828.66199366041, 5569.972150139385, 5580.441955804387, 5613.47393672857, 5621.775128994958], "value": 3986.0334596996236, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [677.8508728079823, 681.7647911470541, 753.7286576832342, 818.394388152767, 821.3224090125647, 13294.867051329471, 14787.556373308818, 14937.25313734313, 15897.364105435783, 15910.045397276172, 14325.57023289303, 14362.712745745079, 14396.784048239284, 14460.132392056485, 14519.709605807877, 873.554487211522, 882.4176043811086, 897.4211633496391, 898.7034278688018, 900.5452246615483, 839.5844057191669, 878.363186689648, 879.5074758135432, 880.7357792662176, 881.4094556647062, 916.5921165081542, 926.1988380051241, 935.377973647527, 937.4890684576922, 937.6624415210518, 912.6531917871199, 921.2952091649893, 928.1553786054658, 932.6875496708611, 935.4667839331584, 663.6747993483207, 694.198201077756, 699.4928676709396, 700.1248439450686, 700.1807884774826, 14073.366698498592, 14214.644633884132, 14822.0, 15264.465743698916, 15468.149251791147, 14023.439062437592, 14110.294485275797, 14169.362378692984, 14215.431382744679, 14358.425662973468, 11048.613298534594, 11612.18714689972, 11768.111024058211, 11777.470013849397, 11859.28844269338, 3600.7299452541165, 3680.3191409589313, 3719.20037192005, 3826.1391186982983, 3843.557990831034, 114797.27804082862, 115003.27495087497, 115570.42214788956, 116024.6795064094, 118831.81168188294, 10832.72918177036, 10845.07816835562, 10896.509657065451, 11068.778624427467, 11096.389698566494, 22459.101635934705, 22662.433439163822, 22758.20346287872, 22761.430964226027, 22815.429614259443, 7641.3886889048745, 7702.537847729154, 7722.189170137167, 7730.806729831799, 7879.724209652749, 4275.187714334266, 4321.978390108092, 4336.934945975813, 4395.780210989437, 4530.909381812411, 3562.412201986695, 3578.159132603836, 3666.431703086026, 3667.2482141161, 3679.1170119171206, 650.5283669339691, 658.9176352955881, 662.5097427903316, 711.2851584157888, 724.7390939261861, 635.853753636657, 659.6833519910374, 683.4634811672813, 688.6281408039703, 709.7870638808337, 8896.555172241491, 10083.546240419199, 10230.079292863678, 10330.0, 10356.326838755456, 605.0651743056987, 666.5567397680488, 683.5112894280613, 706.992930070703, 710.4955481608116, 4481.775911204427, 4865.659403841697, 4882.292067650171, 4894.3147959285725, 4896.608271338285, 97873.0, 100237.49881250694, 101557.0, 101732.96534069277, 103415.0, 81158.59420702858, 84056.57971710224, 84254.0, 85256.14743852607, 88241.0, 73893.0, 74313.62843185749, 74746.62626686941, 75260.4947901039, 76148.0, 9150.588223529823, 9246.352755307333, 9306.348555601044, 9307.953460232926, 9310.813783724152, 49727.25409118794, 53682.46317536931, 54943.45056549383, 55010.17484737812, 55328.72335638457, 71510.28489715036, 71588.28411715817, 72602.0, 74013.0, 74108.62945685237, 11416.372099534605, 11430.428478576205, 11619.244749091282, 11805.645830625095, 11905.166638335233, 12825.807612886001, 13049.0, 13060.80408793888, 13098.410571524495, 13105.934470327587, 11814.586489472827, 11864.466099025392, 11873.287602743863, 11892.0, 12283.877161228631, 11549.769004620028, 11832.11259155584, 11956.282623042645, 12166.783321668116, 12187.878121218675, 11618.6514404568, 11680.299182049104, 11721.706957326134, 11738.58914937973, 12176.878231217574, 10039.749006274902, 10206.336588121749, 10310.32982856112, 10440.060394564525, 10496.0, 6844.315568443116, 8702.869456958024, 8864.778380540538, 9677.951610241904, 9703.223742100756], "value": 7396.41421157955, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1588.8, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [108.0, 119.0, 121.0, 118.0, 119.0, 120.0, 119.0, 125.0, 124.0, 119.0], "value": 119.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 138.0, 147.0, 185.0, 113.0, 113.0, 114.0, 113.0, 132.0, 116.0], "value": 132.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [206.0, 208.0, 265.0, 279.0, 197.0, 250.0, 219.0, 204.0, 195.0, 203.0], "value": 222.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 58.0, 60.0, 84.0, 79.0, 86.0, 81.0, 63.0, 73.0, 58.0], "value": 72.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 72.0, 98.0, 73.0, 72.0, 102.0, 114.0, 87.0, 97.0, 69.0], "value": 87.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 76.0, 133.0, 114.0, 121.0, 132.0, 135.0, 86.0, 90.0, 86.0], "value": 106.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 120.0, 96.0, 96.0, 99.0, 96.0, 97.0, 93.0, 123.0, 137.0], "value": 105.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 93.0, 92.0, 93.0, 90.0, 93.0, 91.0, 93.0, 98.0, 92.0], "value": 92.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 58.0, 57.0, 77.0, 63.0, 62.0, 60.0, 61.0, 63.0, 85.0], "value": 64.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 75.0, 76.0, 84.0, 75.0, 69.0, 69.0, 71.0, 73.0, 73.0], "value": 73.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 91.0, 92.0, 86.0, 92.0, 83.0, 102.0, 124.0, 77.0, 99.0], "value": 93.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 147.0, 137.0, 148.0, 155.0, 149.0, 163.0, 143.0, 168.0, 152.0], "value": 150.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 245.0, 214.0, 187.0, 244.0, 187.0, 176.0, 229.0, 183.0, 174.0], "value": 200.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 69.0, 66.0, 69.0, 67.0, 70.0, 67.0, 69.0, 68.0, 71.0], "value": 68.8, "unit": "score"}]}]} 10:43:25 INFO - Return code: 0 10:43:25 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 10:43:25 ERROR - # TBPL SUCCESS # 10:43:25 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 10:43:25 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 10:43:25 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 10:43:25 INFO - 2017-01-29 10:43:25.159 screenresolution[2562:301821] starting screenresolution argv=screenresolution get 10:43:25 INFO - 2017-01-29 10:43:25.161 screenresolution[2562:301821] Display 0: 1600x1200x32@60 10:43:25 INFO - 2017-01-29 10:43:25.167 screenresolution[2563:301824] starting screenresolution argv=screenresolution list 10:43:25 INFO - Available Modes on Display 0 10:43:25 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 10:43:25 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 10:43:25 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 10:43:25 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 10:43:25 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 10:43:25 INFO - Intel Iris: 10:43:25 INFO - Chipset Model: Intel Iris 10:43:25 INFO - Type: GPU 10:43:25 INFO - Bus: Built-In 10:43:25 INFO - VRAM (Dynamic, Max): 1536 MB 10:43:25 INFO - Vendor: Intel (0x8086) 10:43:25 INFO - Device ID: 0x0a2e 10:43:25 INFO - Revision ID: 0x0009 10:43:25 INFO - Displays: 10:43:25 INFO - Display: 10:43:25 INFO - Resolution: 1600 x 1200 @ 60 Hz 10:43:25 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 10:43:25 INFO - Display Serial Number: 1600x1200 60 10:43:25 INFO - Main Display: Yes 10:43:25 INFO - Mirror: Off 10:43:25 INFO - Online: Yes 10:43:25 INFO - Rotation: Supported 10:43:25 INFO - Return code: 0 10:43:25 INFO - Running post-action listener: _resource_record_post_action 10:43:25 INFO - [mozharness: 2017-01-29 18:43:25.415021Z] Finished run-tests step (success) 10:43:25 INFO - Running post-run listener: _resource_record_post_run 10:43:25 INFO - Total resource usage - Wall time: 1200s; CPU: 54.0%; Read bytes: 946176; Write bytes: 559055872; Read time: 16; Write time: 1740 10:43:25 INFO - TinderboxPrint: CPU usage
54.5% 10:43:25 INFO - TinderboxPrint: I/O read bytes / time
946,176 / 16 10:43:25 INFO - TinderboxPrint: I/O write bytes / time
559,055,872 / 1,740 10:43:25 INFO - TinderboxPrint: CPU idle
2,186.2 (45.5%) 10:43:25 INFO - TinderboxPrint: CPU system
968.2 (20.2%) 10:43:25 INFO - TinderboxPrint: CPU user
1,647.3 (34.3%) 10:43:25 INFO - TinderboxPrint: Swap in / out
252,305,408 / 0 10:43:25 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 248416768; Write bytes: 242853888; Read time: 17824; Write time: 523 10:43:25 INFO - run-tests - Wall time: 1178s; CPU: 54.0%; Read bytes: 901120; Write bytes: 302881792; Read time: 15; Write time: 1176 10:43:25 INFO - Running post-run listener: _upload_blobber_files 10:43:25 INFO - Blob upload gear active. 10:43:25 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 10:43:25 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 10:43:25 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'] 10:43:25 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 10:43:26 INFO - (blobuploader) - INFO - Open directory for files ... 10:43:26 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 10:43:26 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:43:26 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:43:27 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 10:43:27 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:43:27 INFO - (blobuploader) - INFO - Done attempting. 10:43:27 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 10:43:27 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:43:27 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:43:28 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 10:43:28 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:43:28 INFO - (blobuploader) - INFO - Done attempting. 10:43:28 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 10:43:28 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:43:28 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:43:29 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 10:43:29 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:43:29 INFO - (blobuploader) - INFO - Done attempting. 10:43:29 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 10:43:29 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:43:29 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:43:31 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 10:43:31 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:43:31 INFO - (blobuploader) - INFO - Done attempting. 10:43:31 INFO - (blobuploader) - INFO - Iteration through files over. 10:43:31 INFO - Return code: 0 10:43:31 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 10:43:31 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 10:43:31 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c967dbebbc9391b00e78588954435fca018a8ab8ab97073a744a7d8c251de5fb953446d1eb59f3529026200972dc99a1dacc7a70019cf5168755775b1aaa20c0", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c7bb9f099cf751bb1dbca9528e2d30407e9420dbc4da12aa460842408e042bc85949c4461678d8a422bb7bfa19ccff43c18c8c1b63b72c0e788b54b7743a419a", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0833a86e52bbb6c0882e31e3d83a38421e10bfe5b8f997da4593908b52fac8176d994e95e09665784c38066ccaa85d256b47084110a591a0d587ee05bf42b823", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3d2c881f064985791defbad1ee3fe6f647d83b543795914bbde485998409e1d3301d4c24d6b661f902021ad4e3905d1c336fcd4e6dd862dffc8539d091811c05"} 10:43:31 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 10:43:31 INFO - Writing to file /builds/slave/test/properties/blobber_files 10:43:31 INFO - Contents: 10:43:31 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c967dbebbc9391b00e78588954435fca018a8ab8ab97073a744a7d8c251de5fb953446d1eb59f3529026200972dc99a1dacc7a70019cf5168755775b1aaa20c0", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c7bb9f099cf751bb1dbca9528e2d30407e9420dbc4da12aa460842408e042bc85949c4461678d8a422bb7bfa19ccff43c18c8c1b63b72c0e788b54b7743a419a", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0833a86e52bbb6c0882e31e3d83a38421e10bfe5b8f997da4593908b52fac8176d994e95e09665784c38066ccaa85d256b47084110a591a0d587ee05bf42b823", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3d2c881f064985791defbad1ee3fe6f647d83b543795914bbde485998409e1d3301d4c24d6b661f902021ad4e3905d1c336fcd4e6dd862dffc8539d091811c05"} 10:43:31 INFO - Running post-run listener: copy_logs_to_upload_dir 10:43:31 INFO - Copying logs to upload dir... 10:43:31 INFO - mkdir: /builds/slave/test/build/upload/logs 10:43:31 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1262.204756 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 2 secs) (at 2017-01-29 10:43:31.668648) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-29 10:43:31.674485) ========= 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.6HmhrAIC7u/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.NKnqyClXf7/Listeners TMPDIR=/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/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/c967dbebbc9391b00e78588954435fca018a8ab8ab97073a744a7d8c251de5fb953446d1eb59f3529026200972dc99a1dacc7a70019cf5168755775b1aaa20c0", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c7bb9f099cf751bb1dbca9528e2d30407e9420dbc4da12aa460842408e042bc85949c4461678d8a422bb7bfa19ccff43c18c8c1b63b72c0e788b54b7743a419a", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0833a86e52bbb6c0882e31e3d83a38421e10bfe5b8f997da4593908b52fac8176d994e95e09665784c38066ccaa85d256b47084110a591a0d587ee05bf42b823", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3d2c881f064985791defbad1ee3fe6f647d83b543795914bbde485998409e1d3301d4c24d6b661f902021ad4e3905d1c336fcd4e6dd862dffc8539d091811c05"} build_url:https://queue.taskcluster.net/v1/task/F_PIFeKGQqevudDkfWvfsw/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011408 build_url: 'https://queue.taskcluster.net/v1/task/F_PIFeKGQqevudDkfWvfsw/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/c967dbebbc9391b00e78588954435fca018a8ab8ab97073a744a7d8c251de5fb953446d1eb59f3529026200972dc99a1dacc7a70019cf5168755775b1aaa20c0", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c7bb9f099cf751bb1dbca9528e2d30407e9420dbc4da12aa460842408e042bc85949c4461678d8a422bb7bfa19ccff43c18c8c1b63b72c0e788b54b7743a419a", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0833a86e52bbb6c0882e31e3d83a38421e10bfe5b8f997da4593908b52fac8176d994e95e09665784c38066ccaa85d256b47084110a591a0d587ee05bf42b823", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3d2c881f064985791defbad1ee3fe6f647d83b543795914bbde485998409e1d3301d4c24d6b661f902021ad4e3905d1c336fcd4e6dd862dffc8539d091811c05"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-29 10:43:31.709241) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-29 10:43:31.709560) ========= 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.6HmhrAIC7u/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.NKnqyClXf7/Listeners TMPDIR=/var/folders/r4/175kjq1972gggq8bl2fbwrf400000w/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.005188 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-29 10:43:31.757704) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-29 10:43:31.758005) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-29 10:43:31.758317) ========= ========= Total master_lag: 0.25 =========