builder: graphics_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0024 starttime: 1483985999.44 results: success (0) buildid: 20170109075508 builduid: cc4bad446bc4409e871d278cc9a83e16 revision: 10e6190a35ec0538db0b7843af952f9e52cc4ca2 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-01-09 10:19:59.443831) ========= master: http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-01-09 10:19:59.444281) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-09 10:19:59.444579) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-09 10:19:59.475097) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-09 10:19:59.475520) ========= 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.8zCsXPyheg/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.u5J9zxbz4t/Listeners TMPDIR=/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/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-09 10:19:59-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 29.3M=0s 2017-01-09 10:19:59 (29.3 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.105125 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-09 10:19:59.605961) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-09 10:19:59.606326) ========= 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.8zCsXPyheg/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.u5J9zxbz4t/Listeners TMPDIR=/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/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.020547 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-09 10:19:59.657867) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-01-09 10:19:59.658443) ========= bash -c 'python archiver_client.py mozharness --repo projects/graphics --rev 10e6190a35ec0538db0b7843af952f9e52cc4ca2 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo projects/graphics --rev 10e6190a35ec0538db0b7843af952f9e52cc4ca2 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.8zCsXPyheg/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.u5J9zxbz4t/Listeners TMPDIR=/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/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-09 10:19:59,730 truncating revision to first 12 chars 2017-01-09 10:19:59,730 Setting DEBUG logging. 2017-01-09 10:19:59,730 attempt 1/10 2017-01-09 10:19:59,730 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/projects/graphics/10e6190a35ec?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-01-09 10:20:00,968 unpacking tar archive at: graphics-10e6190a35ec/testing/mozharness/ program finished with exit code 0 elapsedTime=1.492544 ========= master_lag: 0.04 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-01-09 10:20:01.186420) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-09 10:20:01.186893) ========= script_repo_revision: 10e6190a35ec0538db0b7843af952f9e52cc4ca2 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-09 10:20:01.187308) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-09 10:20:01.187578) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-09 10:20:01.211512) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 3 secs) (at 2017-01-09 10:20:01.212099) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Graphics --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Graphics 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', 'Graphics', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Graphics'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.8zCsXPyheg/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.u5J9zxbz4t/Listeners TMPDIR=/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/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:20:01 INFO - MultiFileLogger online at 20170109 10:20:01 in /builds/slave/test 10:20:01 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Graphics --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Graphics 10:20:01 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 10:20:01 INFO - {'append_to_log': False, 10:20:01 INFO - 'base_work_dir': '/builds/slave/test', 10:20:01 INFO - 'blob_upload_branch': 'Graphics', 10:20:01 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 10:20:01 INFO - 'branch': 'Graphics', 10:20:01 INFO - 'buildbot_json_path': 'buildprops.json', 10:20:01 INFO - 'config_files': ('talos/mac_config.py',), 10:20:01 INFO - 'default_actions': ('clobber', 10:20:01 INFO - 'read-buildbot-config', 10:20:01 INFO - 'download-and-extract', 10:20:01 INFO - 'populate-webroot', 10:20:01 INFO - 'create-virtualenv', 10:20:01 INFO - 'install', 10:20:01 INFO - 'run-tests'), 10:20:01 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 10:20:01 INFO - 'download_minidump_stackwalk': True, 10:20:01 INFO - 'download_symbols': 'ondemand', 10:20:01 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 10:20:01 INFO - 'tooltool.py': '/tools/tooltool.py', 10:20:01 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 10:20:01 INFO - '/tools/misc-python/virtualenv.py')}, 10:20:01 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 10:20:01 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 10:20:01 INFO - 'installer_path': 'installer.exe', 10:20:01 INFO - 'log_level': 'info', 10:20:01 INFO - 'log_name': 'talos', 10:20:01 INFO - 'log_to_console': True, 10:20:01 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 10:20:01 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 10:20:01 INFO - 'opt_config_files': (), 10:20:01 INFO - 'pip_index': False, 10:20:01 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 10:20:01 INFO - 'cmd': ('bash', 10:20:01 INFO - '-c', 10:20:01 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 10:20:01 INFO - 'enabled': True, 10:20:01 INFO - 'halt_on_failure': False, 10:20:01 INFO - 'name': 'check_screen_resolution'},), 10:20:01 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 10:20:01 INFO - 'cmd': ('bash', 10:20:01 INFO - '-c', 10:20:01 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 10:20:01 INFO - 'enabled': True, 10:20:01 INFO - 'halt_on_failure': False, 10:20:01 INFO - 'name': 'check_screen_resolution'},), 10:20:01 INFO - 'run_cmd_checks_enabled': True, 10:20:01 INFO - 'sps_profile': False, 10:20:01 INFO - 'sps_profile_interval': 0, 10:20:01 INFO - 'suite': 'dromaeojs-e10s', 10:20:01 INFO - 'system_bits': '32', 10:20:01 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 10:20:01 INFO - 'title': 't-yosemite-r7-0024', 10:20:01 INFO - 'tooltool_cache': '/builds/tooltool_cache', 10:20:01 INFO - 'use_talos_json': True, 10:20:01 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 10:20:01 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 10:20:01 INFO - 'work_dir': 'build'} 10:20:01 INFO - [mozharness: 2017-01-09 18:20:01.412191Z] Running clobber step. 10:20:01 INFO - Running pre-action listener: _resource_record_pre_action 10:20:01 INFO - Running main action method: clobber 10:20:01 INFO - rmtree: /builds/slave/test/build 10:20:01 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 10:20:02 INFO - Running post-action listener: _resource_record_post_action 10:20:02 INFO - [mozharness: 2017-01-09 18:20:02.350378Z] Finished clobber step (success) 10:20:02 INFO - [mozharness: 2017-01-09 18:20:02.350496Z] Running read-buildbot-config step. 10:20:02 INFO - Running pre-action listener: _resource_record_pre_action 10:20:02 INFO - Running main action method: read_buildbot_config 10:20:02 INFO - Using buildbot properties: 10:20:02 INFO - { 10:20:02 INFO - "project": "", 10:20:02 INFO - "product": "firefox", 10:20:02 INFO - "script_repo_revision": "default", 10:20:02 INFO - "builddir": "graphics_yosemite_r7_test-dromaeojs-e10s", 10:20:02 INFO - "repository": "", 10:20:02 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 graphics talos dromaeojs-e10s", 10:20:02 INFO - "buildid": "20170109075508", 10:20:02 INFO - "pgo_build": "False", 10:20:02 INFO - "basedir": "/builds/slave/test", 10:20:02 INFO - "buildnumber": 0, 10:20:02 INFO - "slavename": "t-yosemite-r7-0024", 10:20:02 INFO - "master": "http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/", 10:20:02 INFO - "platform": "yosemite_r7", 10:20:02 INFO - "scheduler": "tests-graphics-macosx64-talos-8-3600", 10:20:02 INFO - "branch": "graphics", 10:20:02 INFO - "repo_path": "projects/graphics", 10:20:02 INFO - "revision": "10e6190a35ec0538db0b7843af952f9e52cc4ca2", 10:20:02 INFO - "stage_platform": "macosx64", 10:20:02 INFO - "builduid": "cc4bad446bc4409e871d278cc9a83e16", 10:20:02 INFO - "slavebuilddir": "test" 10:20:02 INFO - } 10:20:02 INFO - Found installer url https://queue.taskcluster.net/v1/task/BK2LFD8rQKi1FVC8xBBxNg/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg. 10:20:02 INFO - Running post-action listener: _resource_record_post_action 10:20:02 INFO - [mozharness: 2017-01-09 18:20:02.353788Z] Finished read-buildbot-config step (success) 10:20:02 INFO - [mozharness: 2017-01-09 18:20:02.353924Z] Running download-and-extract step. 10:20:02 INFO - Running pre-action listener: _resource_record_pre_action 10:20:02 INFO - Running main action method: download_and_extract 10:20:02 INFO - mkdir: /builds/slave/test/build/tests 10:20:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:20:02 INFO - trying https://queue.taskcluster.net/v1/task/BK2LFD8rQKi1FVC8xBBxNg/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json 10:20:02 INFO - Downloading https://queue.taskcluster.net/v1/task/BK2LFD8rQKi1FVC8xBBxNg/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json 10:20:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/BK2LFD8rQKi1FVC8xBBxNg/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json'}, attempt #1 10:20:03 INFO - Downloaded 1199 bytes. 10:20:03 INFO - Reading from file /builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json 10:20:03 INFO - Using the following test package requirements: 10:20:03 INFO - {u'common': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 10:20:03 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 10:20:03 INFO - u'firefox-53.0a1.en-US.mac.cppunittest.tests.zip'], 10:20:03 INFO - u'gtest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 10:20:03 INFO - u'firefox-53.0a1.en-US.mac.gtest.tests.zip'], 10:20:03 INFO - u'jittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 10:20:03 INFO - u'jsshell-mac.zip'], 10:20:03 INFO - u'mochitest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 10:20:03 INFO - u'firefox-53.0a1.en-US.mac.mochitest.tests.zip'], 10:20:03 INFO - u'mozbase': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 10:20:03 INFO - u'reftest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 10:20:03 INFO - u'firefox-53.0a1.en-US.mac.reftest.tests.zip'], 10:20:03 INFO - u'talos': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 10:20:03 INFO - u'firefox-53.0a1.en-US.mac.talos.tests.zip'], 10:20:03 INFO - u'web-platform': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 10:20:03 INFO - u'firefox-53.0a1.en-US.mac.web-platform.tests.zip'], 10:20:03 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 10:20:03 INFO - u'firefox-53.0a1.en-US.mac.xpcshell.tests.zip']} 10:20:03 INFO - Downloading packages: [u'firefox-53.0a1.en-US.mac.common.tests.zip'] for test suite category: common 10:20:03 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/BK2LFD8rQKi1FVC8xBBxNg/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 10:20:03 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/BK2LFD8rQKi1FVC8xBBxNg/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 10:20:03 INFO - Fetch https://queue.taskcluster.net/v1/task/BK2LFD8rQKi1FVC8xBBxNg/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 10:20:04 INFO - Expected file size: 16304854 10:20:04 INFO - Obtained file size: 16304854 10:20:11 INFO - Downloading packages: [u'firefox-53.0a1.en-US.mac.common.tests.zip', u'firefox-53.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 10:20:11 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/BK2LFD8rQKi1FVC8xBBxNg/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 10:20:11 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/BK2LFD8rQKi1FVC8xBBxNg/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 10:20:11 INFO - Fetch https://queue.taskcluster.net/v1/task/BK2LFD8rQKi1FVC8xBBxNg/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 10:20:12 INFO - Expected file size: 16304854 10:20:12 INFO - Obtained file size: 16304854 10:20:18 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/BK2LFD8rQKi1FVC8xBBxNg/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip 10:20:18 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/BK2LFD8rQKi1FVC8xBBxNg/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip'}, attempt #1 10:20:18 INFO - Fetch https://queue.taskcluster.net/v1/task/BK2LFD8rQKi1FVC8xBBxNg/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip into memory 10:20:19 INFO - Expected file size: 14403537 10:20:19 INFO - Obtained file size: 14403537 10:20:20 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:20:20 INFO - trying https://queue.taskcluster.net/v1/task/BK2LFD8rQKi1FVC8xBBxNg/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 10:20:20 INFO - Downloading https://queue.taskcluster.net/v1/task/BK2LFD8rQKi1FVC8xBBxNg/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg 10:20:20 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/BK2LFD8rQKi1FVC8xBBxNg/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg'}, attempt #1 10:20:22 INFO - Downloaded 69914061 bytes. 10:20:22 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/BK2LFD8rQKi1FVC8xBBxNg/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 10:20:22 INFO - mkdir: /builds/slave/test/properties 10:20:22 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 10:20:22 INFO - Writing to file /builds/slave/test/properties/build_url 10:20:22 INFO - Contents: 10:20:22 INFO - build_url:https://queue.taskcluster.net/v1/task/BK2LFD8rQKi1FVC8xBBxNg/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 10:20:23 INFO - Running post-action listener: _resource_record_post_action 10:20:23 INFO - Running post-action listener: set_extra_try_arguments 10:20:23 INFO - [mozharness: 2017-01-09 18:20:23.203150Z] Finished download-and-extract step (success) 10:20:23 INFO - [mozharness: 2017-01-09 18:20:23.203303Z] Running populate-webroot step. 10:20:23 INFO - Running pre-action listener: _resource_record_pre_action 10:20:23 INFO - Running main action method: populate_webroot 10:20:23 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 10:20:23 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 10:20:23 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 10:20:23 INFO - '--tppagecycles', 10:20:23 INFO - '7'], 10:20:23 INFO - 'tests': ['tsvgm']}}, 10:20:23 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 10:20:23 INFO - 'tests': ['tresize', 'tcanvasmark']}, 10:20:23 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 10:20:23 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 10:20:23 INFO - 'tests': ['dromaeo_css', 'kraken']}, 10:20:23 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 10:20:23 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 10:20:23 INFO - 'talos_options': ['--disable-e10s'], 10:20:23 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:20:23 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 10:20:23 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:20:23 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 10:20:23 INFO - 'talos_options': ['--disable-e10s'], 10:20:23 INFO - 'tests': ['damp', 'tps']}, 10:20:23 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 10:20:23 INFO - 'tests': ['damp', 'tps']}, 10:20:23 INFO - 'g3': {'talos_options': ['--disable-e10s'], 10:20:23 INFO - 'tests': ['dromaeo_dom']}, 10:20:23 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 10:20:23 INFO - 'g4': {'talos_options': ['--disable-e10s'], 10:20:23 INFO - 'tests': ['basic_compositor_video']}, 10:20:23 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 10:20:23 INFO - 'other': {'talos_options': ['--disable-e10s'], 10:20:23 INFO - 'tests': ['a11yr', 10:20:23 INFO - 'ts_paint', 10:20:23 INFO - 'tpaint', 10:20:23 INFO - 'sessionrestore', 10:20:23 INFO - 'sessionrestore_no_auto_restore', 10:20:23 INFO - 'tabpaint']}, 10:20:23 INFO - 'other-e10s': {'tests': ['a11yr', 10:20:23 INFO - 'ts_paint', 10:20:23 INFO - 'tpaint', 10:20:23 INFO - 'sessionrestore', 10:20:23 INFO - 'sessionrestore_no_auto_restore', 10:20:23 INFO - 'tabpaint']}, 10:20:23 INFO - 'other-e10s_l64': {'tests': ['a11yr', 10:20:23 INFO - 'ts_paint', 10:20:23 INFO - 'tpaint', 10:20:23 INFO - 'sessionrestore', 10:20:23 INFO - 'sessionrestore_no_auto_restore', 10:20:23 INFO - 'tabpaint']}, 10:20:23 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 10:20:23 INFO - 'ts_paint', 10:20:23 INFO - 'tpaint', 10:20:23 INFO - 'sessionrestore', 10:20:23 INFO - 'sessionrestore_no_auto_restore', 10:20:23 INFO - 'tabpaint']}, 10:20:23 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 10:20:23 INFO - 'tests': ['a11yr', 10:20:23 INFO - 'ts_paint', 10:20:23 INFO - 'tpaint', 10:20:23 INFO - 'sessionrestore', 10:20:23 INFO - 'sessionrestore_no_auto_restore', 10:20:23 INFO - 'tabpaint']}, 10:20:23 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 10:20:23 INFO - 'tests': ['a11yr', 10:20:23 INFO - 'ts_paint', 10:20:23 INFO - 'tpaint', 10:20:23 INFO - 'sessionrestore', 10:20:23 INFO - 'sessionrestore_no_auto_restore', 10:20:23 INFO - 'tabpaint']}, 10:20:23 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 10:20:23 INFO - 'tests': ['tsvgx', 10:20:23 INFO - 'tsvgr_opacity', 10:20:23 INFO - 'tart', 10:20:23 INFO - 'tscrollx', 10:20:23 INFO - 'cart', 10:20:23 INFO - 'tsvg_static']}, 10:20:23 INFO - 'svgr-e10s': {'tests': ['tsvgx', 10:20:23 INFO - 'tsvgr_opacity', 10:20:23 INFO - 'tart', 10:20:23 INFO - 'tscrollx', 10:20:23 INFO - 'cart', 10:20:23 INFO - 'tsvg_static']}, 10:20:23 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 10:20:23 INFO - 'talos_options': ['--disable-e10s'], 10:20:23 INFO - 'tests': ['tp5o']}, 10:20:23 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 10:20:23 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 10:20:23 INFO - 'talos_options': ['--disable-e10s', 10:20:23 INFO - '--xperf_path', 10:20:23 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:20:23 INFO - 'tests': ['tp5n']}, 10:20:23 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 10:20:23 INFO - 'talos_options': ['--xperf_path', 10:20:23 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:20:23 INFO - 'tests': ['tp5n']}}, 10:20:23 INFO - 'talos.zip': {'path': '', 10:20:23 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 10:20:23 INFO - Running post-action listener: _resource_record_post_action 10:20:23 INFO - [mozharness: 2017-01-09 18:20:23.222139Z] Finished populate-webroot step (success) 10:20:23 INFO - [mozharness: 2017-01-09 18:20:23.222273Z] Running create-virtualenv step. 10:20:23 INFO - Running pre-action listener: _resource_record_pre_action 10:20:23 INFO - Running main action method: create_virtualenv 10:20:23 INFO - Creating virtualenv /builds/slave/test/build/venv 10:20:23 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:20:23 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 10:20:23 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 10:20:23 INFO - Using real prefix '/tools/python27' 10:20:23 INFO - New python executable in /builds/slave/test/build/venv/bin/python 10:20:24 INFO - Installing distribute.............................................................................................................................................................................................done. 10:20:26 INFO - Installing pip.................done. 10:20:26 INFO - Return code: 0 10:20:26 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 10:20:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 10:20:27 INFO - Reading from file tmpfile_stdout 10:20:27 INFO - Output received: 10:20:27 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:20:27 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 10:20:27 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:20:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:20:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:20:27 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:20:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:20:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:20:27 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 0x108bcbb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108bce768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd1c95a0a10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1089a9d30>, '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 0x1088d0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10899ebe8>, '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.8zCsXPyheg/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.u5J9zxbz4t/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/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:20:27 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:20:27 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:20:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8zCsXPyheg/Render', 10:20:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:20:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:20:27 INFO - 'HOME': '/Users/cltbld', 10:20:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:20:27 INFO - 'LOGNAME': 'cltbld', 10:20:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:20:27 INFO - 'MOZ_NO_REMOTE': '1', 10:20:27 INFO - 'NO_EM_RESTART': '1', 10:20:27 INFO - 'PAGER': '/bin/cat', 10:20:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:20:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:20:27 INFO - 'PWD': '/builds/slave/test', 10:20:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:20:27 INFO - 'SHELL': '/bin/bash', 10:20:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.u5J9zxbz4t/Listeners', 10:20:27 INFO - 'TMPDIR': '/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/', 10:20:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:20:27 INFO - 'USER': 'cltbld', 10:20:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:20:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:20:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:20:27 INFO - 'XPC_FLAGS': '0x0', 10:20:27 INFO - 'XPC_SERVICE_NAME': '0', 10:20:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:20:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:20:27 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:20:27 INFO - Cleaning up... 10:20:27 INFO - Return code: 0 10:20:27 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 10:20:27 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:20:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:20:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:20:27 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:20:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:20:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:20:27 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 0x108bcbb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108bce768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd1c95a0a10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1089a9d30>, '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 0x1088d0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10899ebe8>, '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.8zCsXPyheg/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.u5J9zxbz4t/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/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:20:27 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:20:27 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:20:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8zCsXPyheg/Render', 10:20:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:20:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:20:27 INFO - 'HOME': '/Users/cltbld', 10:20:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:20:27 INFO - 'LOGNAME': 'cltbld', 10:20:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:20:27 INFO - 'MOZ_NO_REMOTE': '1', 10:20:27 INFO - 'NO_EM_RESTART': '1', 10:20:27 INFO - 'PAGER': '/bin/cat', 10:20:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:20:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:20:27 INFO - 'PWD': '/builds/slave/test', 10:20:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:20:27 INFO - 'SHELL': '/bin/bash', 10:20:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.u5J9zxbz4t/Listeners', 10:20:27 INFO - 'TMPDIR': '/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/', 10:20:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:20:27 INFO - 'USER': 'cltbld', 10:20:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:20:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:20:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:20:27 INFO - 'XPC_FLAGS': '0x0', 10:20:27 INFO - 'XPC_SERVICE_NAME': '0', 10:20:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:20:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:20:27 INFO - Downloading/unpacking psutil>=3.1.1 10:20:27 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:20:27 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:20:27 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:20:27 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:20:27 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:20:27 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:20:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 10:20:31 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:20:31 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:20:31 INFO - Installing collected packages: psutil 10:20:31 INFO - Running setup.py install for psutil 10:20:31 INFO - building 'psutil._psutil_osx' extension 10:20:31 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:20:31 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:20:31 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:20:32 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:20:32 INFO - building 'psutil._psutil_posix' extension 10:20:32 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:20:32 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 10:20:32 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 10:20:32 INFO - ^ 10:20:32 INFO - 1 warning generated. 10:20:32 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:20:32 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:20:32 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:20:32 INFO - Successfully installed psutil 10:20:32 INFO - Cleaning up... 10:20:32 INFO - Return code: 0 10:20:32 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 10:20:32 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:20:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:20:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:20:32 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:20:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:20:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:20:32 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 0x108bcbb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108bce768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd1c95a0a10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1089a9d30>, '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 0x1088d0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10899ebe8>, '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.8zCsXPyheg/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.u5J9zxbz4t/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/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:20:32 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:20:32 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:20:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8zCsXPyheg/Render', 10:20:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:20:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:20:32 INFO - 'HOME': '/Users/cltbld', 10:20:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:20:32 INFO - 'LOGNAME': 'cltbld', 10:20:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:20:32 INFO - 'MOZ_NO_REMOTE': '1', 10:20:32 INFO - 'NO_EM_RESTART': '1', 10:20:32 INFO - 'PAGER': '/bin/cat', 10:20:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:20:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:20:32 INFO - 'PWD': '/builds/slave/test', 10:20:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:20:32 INFO - 'SHELL': '/bin/bash', 10:20:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.u5J9zxbz4t/Listeners', 10:20:32 INFO - 'TMPDIR': '/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/', 10:20:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:20:32 INFO - 'USER': 'cltbld', 10:20:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:20:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:20:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:20:32 INFO - 'XPC_FLAGS': '0x0', 10:20:32 INFO - 'XPC_SERVICE_NAME': '0', 10:20:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:20:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:20:32 INFO - Downloading/unpacking mozsystemmonitor==0.3 10:20:32 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:20:32 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:20:32 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:20:32 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:20:32 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:20:32 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:20:35 INFO - Downloading mozsystemmonitor-0.3.tar.gz 10:20:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 10:20:35 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 10:20:35 INFO - Installing collected packages: mozsystemmonitor 10:20:35 INFO - Running setup.py install for mozsystemmonitor 10:20:35 INFO - Successfully installed mozsystemmonitor 10:20:35 INFO - Cleaning up... 10:20:35 INFO - Return code: 0 10:20:35 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 10:20:35 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:20:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:20:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:20:35 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:20:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:20:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:20:35 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 0x108bcbb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108bce768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd1c95a0a10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1089a9d30>, '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 0x1088d0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10899ebe8>, '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.8zCsXPyheg/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.u5J9zxbz4t/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/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:20:35 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:20:35 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:20:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8zCsXPyheg/Render', 10:20:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:20:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:20:35 INFO - 'HOME': '/Users/cltbld', 10:20:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:20:35 INFO - 'LOGNAME': 'cltbld', 10:20:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:20:35 INFO - 'MOZ_NO_REMOTE': '1', 10:20:35 INFO - 'NO_EM_RESTART': '1', 10:20:35 INFO - 'PAGER': '/bin/cat', 10:20:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:20:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:20:35 INFO - 'PWD': '/builds/slave/test', 10:20:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:20:35 INFO - 'SHELL': '/bin/bash', 10:20:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.u5J9zxbz4t/Listeners', 10:20:35 INFO - 'TMPDIR': '/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/', 10:20:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:20:35 INFO - 'USER': 'cltbld', 10:20:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:20:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:20:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:20:35 INFO - 'XPC_FLAGS': '0x0', 10:20:35 INFO - 'XPC_SERVICE_NAME': '0', 10:20:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:20:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:20:35 INFO - Downloading/unpacking jsonschema==2.5.1 10:20:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:20:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:20:35 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:20:35 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:20:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:20:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:20:38 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 10:20:38 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 10:20:38 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:20:38 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:20:38 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:20:38 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:20:38 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:20:38 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:20:39 INFO - Downloading functools32-3.2.3-2.tar.gz 10:20:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 10:20:39 INFO - warning: no files found matching '*.txt' 10:20:39 INFO - no previously-included directories found matching 'build' 10:20:39 INFO - no previously-included directories found matching 'dist' 10:20:39 INFO - no previously-included directories found matching '.git*' 10:20:39 INFO - Installing collected packages: jsonschema, functools32 10:20:39 INFO - Running setup.py install for functools32 10:20:39 INFO - warning: no files found matching '*.txt' 10:20:39 INFO - no previously-included directories found matching 'build' 10:20:39 INFO - no previously-included directories found matching 'dist' 10:20:39 INFO - no previously-included directories found matching '.git*' 10:20:39 INFO - Successfully installed jsonschema functools32 10:20:39 INFO - Cleaning up... 10:20:39 INFO - Return code: 0 10:20:39 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 10:20:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:20:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:20:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:20:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:20:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:20:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:20:39 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 0x108bcbb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108bce768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd1c95a0a10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1089a9d30>, '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 0x1088d0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10899ebe8>, '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.8zCsXPyheg/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.u5J9zxbz4t/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/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:20:39 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:20:39 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:20:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8zCsXPyheg/Render', 10:20:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:20:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:20:39 INFO - 'HOME': '/Users/cltbld', 10:20:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:20:39 INFO - 'LOGNAME': 'cltbld', 10:20:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:20:39 INFO - 'MOZ_NO_REMOTE': '1', 10:20:39 INFO - 'NO_EM_RESTART': '1', 10:20:39 INFO - 'PAGER': '/bin/cat', 10:20:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:20:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:20:39 INFO - 'PWD': '/builds/slave/test', 10:20:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:20:39 INFO - 'SHELL': '/bin/bash', 10:20:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.u5J9zxbz4t/Listeners', 10:20:39 INFO - 'TMPDIR': '/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/', 10:20:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:20:39 INFO - 'USER': 'cltbld', 10:20:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:20:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:20:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:20:39 INFO - 'XPC_FLAGS': '0x0', 10:20:39 INFO - 'XPC_SERVICE_NAME': '0', 10:20:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:20:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:20:39 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 10:20:39 INFO - Cleaning up... 10:20:39 INFO - Return code: 0 10:20:39 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 10:20:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:20:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:20:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:20:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:20:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:20:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:20:39 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 0x108bcbb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108bce768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd1c95a0a10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1089a9d30>, '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 0x1088d0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10899ebe8>, '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.8zCsXPyheg/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.u5J9zxbz4t/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/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:20:39 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:20:39 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:20:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8zCsXPyheg/Render', 10:20:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:20:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:20:39 INFO - 'HOME': '/Users/cltbld', 10:20:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:20:39 INFO - 'LOGNAME': 'cltbld', 10:20:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:20:39 INFO - 'MOZ_NO_REMOTE': '1', 10:20:39 INFO - 'NO_EM_RESTART': '1', 10:20:39 INFO - 'PAGER': '/bin/cat', 10:20:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:20:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:20:39 INFO - 'PWD': '/builds/slave/test', 10:20:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:20:39 INFO - 'SHELL': '/bin/bash', 10:20:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.u5J9zxbz4t/Listeners', 10:20:39 INFO - 'TMPDIR': '/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/', 10:20:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:20:39 INFO - 'USER': 'cltbld', 10:20:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:20:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:20:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:20:39 INFO - 'XPC_FLAGS': '0x0', 10:20:39 INFO - 'XPC_SERVICE_NAME': '0', 10:20:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:20:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:20:40 INFO - Downloading/unpacking blobuploader==1.2.4 10:20:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:20:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:20:40 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:20:40 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:20:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:20:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:20:42 INFO - Downloading blobuploader-1.2.4.tar.gz 10:20:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 10:20:42 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 10:20:42 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:20:42 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:20:42 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:20:42 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:20:42 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:20:42 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:20:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 10:20:43 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 10:20:43 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:20:43 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:20:43 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:20:43 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:20:43 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:20:43 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:20:43 INFO - Downloading docopt-0.6.1.tar.gz 10:20:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 10:20:43 INFO - Installing collected packages: blobuploader, requests, docopt 10:20:43 INFO - Running setup.py install for blobuploader 10:20:44 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 10:20:44 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 10:20:44 INFO - Running setup.py install for requests 10:20:44 INFO - Running setup.py install for docopt 10:20:44 INFO - Successfully installed blobuploader requests docopt 10:20:44 INFO - Cleaning up... 10:20:44 INFO - Return code: 0 10:20:44 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:20:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:20:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:20:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:20:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:20:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:20:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:20:44 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 0x108bcbb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108bce768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd1c95a0a10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1089a9d30>, '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 0x1088d0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10899ebe8>, '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.8zCsXPyheg/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.u5J9zxbz4t/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/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:20:44 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:20:44 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:20:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8zCsXPyheg/Render', 10:20:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:20:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:20:44 INFO - 'HOME': '/Users/cltbld', 10:20:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:20:44 INFO - 'LOGNAME': 'cltbld', 10:20:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:20:44 INFO - 'MOZ_NO_REMOTE': '1', 10:20:44 INFO - 'NO_EM_RESTART': '1', 10:20:44 INFO - 'PAGER': '/bin/cat', 10:20:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:20:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:20:44 INFO - 'PWD': '/builds/slave/test', 10:20:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:20:44 INFO - 'SHELL': '/bin/bash', 10:20:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.u5J9zxbz4t/Listeners', 10:20:44 INFO - 'TMPDIR': '/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/', 10:20:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:20:44 INFO - 'USER': 'cltbld', 10:20:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:20:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:20:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:20:44 INFO - 'XPC_FLAGS': '0x0', 10:20:44 INFO - 'XPC_SERVICE_NAME': '0', 10:20:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:20:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:20:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:20:45 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-9zAhmM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:20:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:20:45 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-y01CrI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:20:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:20:45 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-hkF45G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:20:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:20:45 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-NNZrjr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:20:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:20:45 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-SuTM20-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:20:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:20:45 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-R8Wxy3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:20:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:20:45 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-ot1vK_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:20:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:20:45 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-LeLI2z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:20:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:20:45 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-pTn9yj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:20:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:20:46 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-aL_PYg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:20:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:20:46 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-cjHE5m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:20:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:20:46 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-Ticv6X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:20:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:20:46 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-lS1LNX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:20:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:20:46 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-eeMD4H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:20:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:20:46 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-pVRbWt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:20:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:20:46 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-phmoOQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:20:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:20:46 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-EjLFnX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:20:46 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 10:20:46 INFO - Running setup.py install for manifestparser 10:20:47 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 10:20:47 INFO - Running setup.py install for mozcrash 10:20:47 INFO - Running setup.py install for mozdebug 10:20:47 INFO - Running setup.py install for mozdevice 10:20:47 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 10:20:47 INFO - Installing dm script to /builds/slave/test/build/venv/bin 10:20:47 INFO - Running setup.py install for mozfile 10:20:47 INFO - Running setup.py install for mozhttpd 10:20:47 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 10:20:47 INFO - Running setup.py install for mozinfo 10:20:47 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 10:20:47 INFO - Running setup.py install for mozInstall 10:20:48 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 10:20:48 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 10:20:48 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 10:20:48 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 10:20:48 INFO - Running setup.py install for mozleak 10:20:48 INFO - Running setup.py install for mozlog 10:20:48 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 10:20:48 INFO - Running setup.py install for moznetwork 10:20:48 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 10:20:48 INFO - Running setup.py install for mozprocess 10:20:48 INFO - Running setup.py install for mozprofile 10:20:48 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 10:20:48 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 10:20:48 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 10:20:48 INFO - Running setup.py install for mozrunner 10:20:49 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 10:20:49 INFO - Running setup.py install for mozscreenshot 10:20:49 INFO - Running setup.py install for moztest 10:20:49 INFO - Running setup.py install for mozversion 10:20:49 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 10:20:49 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 10:20:49 INFO - Cleaning up... 10:20:49 INFO - Return code: 0 10:20:49 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:20: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:20:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:20:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:20: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:20:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:20:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:20:49 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 0x108bcbb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108bce768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd1c95a0a10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1089a9d30>, '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 0x1088d0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10899ebe8>, '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.8zCsXPyheg/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.u5J9zxbz4t/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/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:20:49 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:20:49 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:20:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8zCsXPyheg/Render', 10:20:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:20:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:20:49 INFO - 'HOME': '/Users/cltbld', 10:20:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:20:49 INFO - 'LOGNAME': 'cltbld', 10:20:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:20:49 INFO - 'MOZ_NO_REMOTE': '1', 10:20:49 INFO - 'NO_EM_RESTART': '1', 10:20:49 INFO - 'PAGER': '/bin/cat', 10:20:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:20:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:20:49 INFO - 'PWD': '/builds/slave/test', 10:20:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:20:49 INFO - 'SHELL': '/bin/bash', 10:20:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.u5J9zxbz4t/Listeners', 10:20:49 INFO - 'TMPDIR': '/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/', 10:20:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:20:49 INFO - 'USER': 'cltbld', 10:20:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:20:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:20:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:20:49 INFO - 'XPC_FLAGS': '0x0', 10:20:49 INFO - 'XPC_SERVICE_NAME': '0', 10:20:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:20:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:20:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:20:49 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-plOcXH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:20:49 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:20:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:20:49 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-b3Nptc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:20:50 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:20:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:20:50 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-F6muL5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:20:50 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:20:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:20:50 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-dgxQK8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:20:50 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:20:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:20:50 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-q4yZjh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:20:50 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:20:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:20:50 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-5aYmwS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:20:50 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:20:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:20:50 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-FXkZhO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:20:50 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:20:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:20:50 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-9K1xM1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:20:50 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:20:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:20:50 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-y2xJyH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:20:50 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:20:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:20:50 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-HXtPNa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:20:50 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:20:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:20:50 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-5rX9RH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:20:50 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:20:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:20:50 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-zC7YTu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:20:51 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:20:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:20:51 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-Gv3xGK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:20:51 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:20:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:20:51 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-FGGgnk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:20:51 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:20:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:20:51 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-QRHFSA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:20:51 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:20:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:20:51 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-k_UdL7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:20:51 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:20:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:20:51 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-BmB3SE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:20:51 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:20:51 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:20:51 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:20:51 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:20:51 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:20:51 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:20:51 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 10:20:51 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:20:51 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:20:51 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:20:51 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:20:51 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:20:51 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:20:54 INFO - Downloading blessings-1.6.tar.gz 10:20:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 10:20:54 INFO - Installing collected packages: blessings 10:20:54 INFO - Running setup.py install for blessings 10:20:54 INFO - Successfully installed blessings 10:20:54 INFO - Cleaning up... 10:20:54 INFO - Return code: 0 10:20:54 INFO - Done creating virtualenv /builds/slave/test/build/venv. 10:20:54 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:20:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:20:54 INFO - Reading from file tmpfile_stdout 10:20:54 INFO - Current package versions: 10:20:54 INFO - blessings == 1.6 10:20:54 INFO - blobuploader == 1.2.4 10:20:54 INFO - docopt == 0.6.1 10:20:54 INFO - functools32 == 3.2.3-2 10:20:54 INFO - jsonschema == 2.5.1 10:20:54 INFO - manifestparser == 1.1 10:20:54 INFO - mozInstall == 1.12 10:20:54 INFO - mozcrash == 1.0 10:20:54 INFO - mozdebug == 0.1 10:20:54 INFO - mozdevice == 0.49 10:20:54 INFO - mozfile == 1.2 10:20:54 INFO - mozhttpd == 0.7 10:20:54 INFO - mozinfo == 0.9 10:20:54 INFO - mozleak == 0.1 10:20:54 INFO - mozlog == 3.4 10:20:54 INFO - moznetwork == 0.27 10:20:54 INFO - mozprocess == 0.24 10:20:54 INFO - mozprofile == 0.28 10:20:54 INFO - mozrunner == 6.13 10:20:54 INFO - mozscreenshot == 0.1 10:20:54 INFO - mozsystemmonitor == 0.3 10:20:54 INFO - moztest == 0.8 10:20:54 INFO - mozversion == 1.4 10:20:54 INFO - psutil == 3.1.1 10:20:54 INFO - requests == 1.2.3 10:20:54 INFO - wsgiref == 0.1.2 10:20:54 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:20:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:20:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:20:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:20:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:20:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:20:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:20:55 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 0x108bcbb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108bce768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd1c95a0a10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1089a9d30>, '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 0x1088d0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10899ebe8>, '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.8zCsXPyheg/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.u5J9zxbz4t/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/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:20:55 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:20:55 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:20:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8zCsXPyheg/Render', 10:20:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:20:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:20:55 INFO - 'HOME': '/Users/cltbld', 10:20:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:20:55 INFO - 'LOGNAME': 'cltbld', 10:20:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:20:55 INFO - 'MOZ_NO_REMOTE': '1', 10:20:55 INFO - 'NO_EM_RESTART': '1', 10:20:55 INFO - 'PAGER': '/bin/cat', 10:20:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:20:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:20:55 INFO - 'PWD': '/builds/slave/test', 10:20:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:20:55 INFO - 'SHELL': '/bin/bash', 10:20:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.u5J9zxbz4t/Listeners', 10:20:55 INFO - 'TMPDIR': '/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/', 10:20:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:20:55 INFO - 'USER': 'cltbld', 10:20:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:20:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:20:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:20:55 INFO - 'XPC_FLAGS': '0x0', 10:20:55 INFO - 'XPC_SERVICE_NAME': '0', 10:20:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:20:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:20:55 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:20:55 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:20:55 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:20:55 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:20:55 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:20:55 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:20:55 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:20:55 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:20:55 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:20:55 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:20:55 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:20:55 INFO - Cleaning up... 10:20:55 INFO - Return code: 0 10:20:55 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 10:20:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:20:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:20:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:20:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:20:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:20:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:20: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', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x108bcbb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108bce768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd1c95a0a10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1089a9d30>, '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 0x1088d0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10899ebe8>, '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.8zCsXPyheg/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.u5J9zxbz4t/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/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:20: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', 'jsonschema'] in /builds/slave/test/build 10:20: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 jsonschema 10:20:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8zCsXPyheg/Render', 10:20:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:20:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:20:56 INFO - 'HOME': '/Users/cltbld', 10:20:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:20:56 INFO - 'LOGNAME': 'cltbld', 10:20:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:20:56 INFO - 'MOZ_NO_REMOTE': '1', 10:20:56 INFO - 'NO_EM_RESTART': '1', 10:20:56 INFO - 'PAGER': '/bin/cat', 10:20:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:20:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:20:56 INFO - 'PWD': '/builds/slave/test', 10:20:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:20:56 INFO - 'SHELL': '/bin/bash', 10:20:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.u5J9zxbz4t/Listeners', 10:20:56 INFO - 'TMPDIR': '/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/', 10:20:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:20:56 INFO - 'USER': 'cltbld', 10:20:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:20:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:20:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:20:56 INFO - 'XPC_FLAGS': '0x0', 10:20:56 INFO - 'XPC_SERVICE_NAME': '0', 10:20:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:20:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:20:56 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 10:20:56 INFO - Cleaning up... 10:20:56 INFO - Return code: 0 10:20:56 INFO - Running post-action listener: _resource_record_post_action 10:20:56 INFO - Running post-action listener: _start_resource_monitoring 10:20:56 INFO - Starting resource monitoring. 10:20:56 INFO - [mozharness: 2017-01-09 18:20:56.712161Z] Finished create-virtualenv step (success) 10:20:56 INFO - [mozharness: 2017-01-09 18:20:56.713206Z] Running install step. 10:20:56 INFO - Running pre-action listener: _resource_record_pre_action 10:20:56 INFO - Running main action method: install 10:20:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:20:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:20:56 INFO - Reading from file tmpfile_stdout 10:20:56 INFO - Detecting whether we're running mozinstall >=1.0... 10:20:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 10:20:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 10:20:57 INFO - Reading from file tmpfile_stdout 10:20:57 INFO - Output received: 10:20:57 INFO - Usage: mozinstall [options] installer 10:20:57 INFO - Options: 10:20:57 INFO - -h, --help show this help message and exit 10:20:57 INFO - -d DEST, --destination=DEST 10:20:57 INFO - Directory to install application into. [default: 10:20:57 INFO - "/builds/slave/test"] 10:20:57 INFO - --app=APP Application being installed. [default: firefox] 10:20:57 INFO - mkdir: /builds/slave/test/build/application 10:20:57 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 10:20:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 10:21:18 INFO - Reading from file tmpfile_stdout 10:21:18 INFO - Output received: 10:21:18 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 10:21:18 INFO - Running post-action listener: _resource_record_post_action 10:21:18 INFO - [mozharness: 2017-01-09 18:21:18.959185Z] Finished install step (success) 10:21:18 INFO - [mozharness: 2017-01-09 18:21:18.959314Z] Running run-tests step. 10:21:18 INFO - Running pre-action listener: _resource_record_pre_action 10:21:18 INFO - Running main action method: run_tests 10:21:18 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 10:21:18 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 10:21:18 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 10:21:18 INFO - 2017-01-09 10:21:18.974 screenresolution[2412:26482] starting screenresolution argv=screenresolution get 10:21:18 INFO - 2017-01-09 10:21:18.976 screenresolution[2412:26482] Display 0: 1600x1200x32@60 10:21:18 INFO - 2017-01-09 10:21:18.982 screenresolution[2413:26484] starting screenresolution argv=screenresolution list 10:21:18 INFO - Available Modes on Display 0 10:21:18 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 10:21:18 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 10:21:18 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 10:21:18 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 10:21:19 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 10:21:19 INFO - Intel Iris: 10:21:19 INFO - Chipset Model: Intel Iris 10:21:19 INFO - Type: GPU 10:21:19 INFO - Bus: Built-In 10:21:19 INFO - VRAM (Dynamic, Max): 1536 MB 10:21:19 INFO - Vendor: Intel (0x8086) 10:21:19 INFO - Device ID: 0x0a2e 10:21:19 INFO - Revision ID: 0x0009 10:21:19 INFO - Displays: 10:21:19 INFO - Display: 10:21:19 INFO - Resolution: 1600 x 1200 @ 60 Hz 10:21:19 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 10:21:19 INFO - Display Serial Number: 1600x1200 60 10:21:19 INFO - Main Display: Yes 10:21:19 INFO - Mirror: Off 10:21:19 INFO - Online: Yes 10:21:19 INFO - Rotation: Supported 10:21:19 INFO - Return code: 0 10:21:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 10:21:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 10:21:19 INFO - Python 2.7.3 10:21:19 INFO - Return code: 0 10:21:19 INFO - grabbing minidump binary from tooltool 10:21:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:21:19 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 0x1089a9d30>, '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 0x1088d0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10899ebe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 10:21:19 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:21:19 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:21:19 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:21:19 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 10:21:19 INFO - Return code: 0 10:21:19 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 10:21:19 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 10:21:19 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 10:21:19 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 10:21:19 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 10:21:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Graphics', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/BK2LFD8rQKi1FVC8xBBxNg/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0024', '--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:21:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Graphics --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/BK2LFD8rQKi1FVC8xBBxNg/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0024 --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:21:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8zCsXPyheg/Render', 10:21:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:21:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:21:19 INFO - 'HOME': '/Users/cltbld', 10:21:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:21:19 INFO - 'LOGNAME': 'cltbld', 10:21:19 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 10:21:19 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 10:21:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:21:19 INFO - 'MOZ_NO_REMOTE': '1', 10:21:19 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 10:21:19 INFO - 'NO_EM_RESTART': '1', 10:21:19 INFO - 'PAGER': '/bin/cat', 10:21:19 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:21:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:21:19 INFO - 'PWD': '/builds/slave/test', 10:21:19 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 10:21:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:21:19 INFO - 'SHELL': '/bin/bash', 10:21:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.u5J9zxbz4t/Listeners', 10:21:19 INFO - 'TMPDIR': '/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/', 10:21:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:21:19 INFO - 'USER': 'cltbld', 10:21:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:21:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:21:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:21:19 INFO - 'XPC_FLAGS': '0x0', 10:21:19 INFO - 'XPC_SERVICE_NAME': '0', 10:21:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:21:19 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Graphics', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/BK2LFD8rQKi1FVC8xBBxNg/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0024', '--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:21:19 INFO - mozversion application_buildid: 20170109075508 10:21:19 INFO - mozversion application_changeset: 10e6190a35ec0538db0b7843af952f9e52cc4ca2 10:21:19 INFO - mozversion application_display_name: Nightly 10:21:19 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 10:21:19 INFO - mozversion application_name: Firefox 10:21:19 INFO - mozversion application_remotingname: firefox 10:21:19 INFO - mozversion application_repository: https://hg.mozilla.org/projects/graphics 10:21:19 INFO - mozversion application_vendor: Mozilla 10:21:19 INFO - mozversion application_version: 53.0a1 10:21:19 INFO - mozversion platform_buildid: 20170109075508 10:21:19 INFO - mozversion platform_changeset: 10e6190a35ec0538db0b7843af952f9e52cc4ca2 10:21:19 INFO - mozversion platform_repository: https://hg.mozilla.org/projects/graphics 10:21:19 INFO - mozversion platform_version: 53.0a1 10:21:19 INFO - using testdate: 1483986079 10:21:19 INFO - actual date: 1483986079 10:21:19 INFO - starting webserver on 'localhost:56909' 10:21:19 INFO - SUITE-START | Running 2 tests 10:21:19 INFO - TEST-START | dromaeo_css 10:21:19 INFO - operating with platform_type : mac_ 10:21:19 INFO - Initialising browser for dromaeo_css test... 10:21:19 INFO - TEST-INFO | started process 2423 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/tmp5kVWQe/profile http://localhost:56909/getInfo.html) 10:21:20 INFO - PROCESS | 2423 | 1483986080096 addons.xpi WARN Exception running bootstrap method install on shield-recipe-client@mozilla.org: [Exception... "Component returned failure code: 0x8000ffff (NS_ERROR_UNEXPECTED) [nsIPrefBranch.getBoolPref]" nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: 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 :: this.install :: line 38" data: no] Stack trace: this.install()@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:38 < callBootstrapMethod()@resource://gre/modules/addons/XPIProvider.jsm:4906 < processFileChanges()@resource://gre/modules/addons/XPIProvider.jsm -> resource://gre/modules/addons/XPIProviderUtils.js:2060 < checkForChanges()@resource://gre/modules/addons/XPIProvider.jsm:3761 < startup()@resource://gre/modules/addons/XPIProvider.jsm:2794 < callProvider()@resource://gre/modules/AddonManager.jsm:267 < _startProvider()@resource://gre/modules/AddonManager.jsm:973 < startup()@resource://gre/modules/AddonManager.jsm:1155 < startup()@resource://gre/modules/AddonManager.jsm:3124 < observe()@resource://gre/components/addonManager.js:65 10:21:22 INFO - PROCESS | 2423 | 2017-01-09 10:21:22.538 plugin-container[2425:26639] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8d3f, name = 'com.apple.tsm.portname' 10:21:22 INFO - PROCESS | 2423 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:21:22 INFO - PROCESS | 2423 | 2017-01-09 10:21:22.542 plugin-container[2425:26639] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4c23, name = 'com.apple.CFPasteboardClient' 10:21:22 INFO - PROCESS | 2423 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:21:22 INFO - PROCESS | 2423 | 2017-01-09 10:21:22.542 plugin-container[2425:26639] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 10:21:22 INFO - PROCESS | 2423 | __metrics Screen width/height:1600/1200 10:21:22 INFO - PROCESS | 2423 | colorDepth:24 10:21:22 INFO - PROCESS | 2423 | Browser inner width/height: 1024/689 10:21:22 INFO - PROCESS | 2423 | __metrics 10:21:22 ERROR - PROCESS | 2423 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3252: TypeError: this.tabs is undefined 10:21:23 INFO - TEST-INFO | 2423: exit 0 10:21:23 INFO - Browser initialized. 10:21:23 INFO - Running cycle 1/1 for dromaeo_css test... 10:21:23 INFO - TEST-INFO | started process 2426 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/tmp5kVWQe/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:21:25 INFO - PROCESS | 2426 | 2017-01-09 10:21:25.930 plugin-container[2427:26755] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x863f, name = 'com.apple.tsm.portname' 10:21:25 INFO - PROCESS | 2426 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:21:25 INFO - PROCESS | 2426 | 2017-01-09 10:21:25.931 plugin-container[2427:26755] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8b0f, name = 'com.apple.CFPasteboardClient' 10:21:25 INFO - PROCESS | 2426 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:21:25 INFO - PROCESS | 2426 | 2017-01-09 10:21:25.931 plugin-container[2427:26755] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 10:21:35 INFO - PROCESS | 2426 | RSS: Main: 232976384 10:21:35 INFO - PROCESS | 2426 | 10:24:39 INFO - PROCESS | 2426 | Cycle 1(1): loaded http://localhost:56909/tests/dromaeo/cssquery-dojo.html (next: http://localhost:56909/tests/dromaeo/cssquery-ext.html) 10:24:41 INFO - PROCESS | 2426 | RSS: Main: 248700928 10:24:41 INFO - PROCESS | 2426 | 10:26:23 INFO - PROCESS | 2426 | ************************* 10:26:23 INFO - PROCESS | 2426 | A coding exception was thrown and uncaught in a Task. 10:26:23 INFO - PROCESS | 2426 | 10:26:23 INFO - PROCESS | 2426 | Full message: ReferenceError: fetch is not defined 10:26:23 INFO - PROCESS | 2426 | Full stack: apiCall@resource://shield-recipe-client/lib/NormandyApi.jsm:37:5 10:26:23 INFO - PROCESS | 2426 | get@resource://shield-recipe-client/lib/NormandyApi.jsm:44:12 10:26:23 INFO - PROCESS | 2426 | this.NormandyApi.fetchRecipes<@resource://shield-recipe-client/lib/NormandyApi.jsm:52:34 10:26:23 INFO - PROCESS | 2426 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 10:26:23 INFO - PROCESS | 2426 | TaskImpl@resource://gre/modules/Task.jsm:277:3 10:26:23 INFO - PROCESS | 2426 | asyncFunction@resource://gre/modules/Task.jsm:252:14 10:26:23 INFO - PROCESS | 2426 | this.RecipeRunner.start<@resource://shield-recipe-client/lib/RecipeRunner.jsm:64:23 10:26:23 INFO - PROCESS | 2426 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 10:26:23 INFO - PROCESS | 2426 | TaskImpl@resource://gre/modules/Task.jsm:277:3 10:26:23 INFO - PROCESS | 2426 | asyncFunction@resource://gre/modules/Task.jsm:252:14 10:26:23 INFO - PROCESS | 2426 | setTimeout_timer@resource://gre/modules/Timer.jsm:30:5 10:26:23 INFO - PROCESS | 2426 | 10:26:23 INFO - PROCESS | 2426 | ************************* 10:27:44 INFO - PROCESS | 2426 | Cycle 1(1): loaded http://localhost:56909/tests/dromaeo/cssquery-ext.html (next: http://localhost:56909/tests/dromaeo/cssquery-jquery.html) 10:27:44 INFO - PROCESS | 2426 | RSS: Main: 261390336 10:27:44 INFO - PROCESS | 2426 | 10:30:46 INFO - PROCESS | 2426 | Cycle 1(1): loaded http://localhost:56909/tests/dromaeo/cssquery-jquery.html (next: http://localhost:56909/tests/dromaeo/cssquery-mootools.html) 10:30:47 INFO - PROCESS | 2426 | RSS: Main: 269242368 10:30:47 INFO - PROCESS | 2426 | 10:33:49 INFO - PROCESS | 2426 | Cycle 1(1): loaded http://localhost:56909/tests/dromaeo/cssquery-mootools.html (next: http://localhost:56909/tests/dromaeo/cssquery-prototype.html) 10:33:50 INFO - PROCESS | 2426 | RSS: Main: 266821632 10:33:50 INFO - PROCESS | 2426 | 10:36:52 INFO - PROCESS | 2426 | Cycle 1(1): loaded http://localhost:56909/tests/dromaeo/cssquery-prototype.html (next: http://localhost:56909/tests/dromaeo/cssquery-yui.html) 10:36:53 INFO - PROCESS | 2426 | RSS: Main: 271290368 10:36:53 INFO - PROCESS | 2426 | 10:39:56 INFO - PROCESS | 2426 | Cycle 1(1): loaded http://localhost:56909/tests/dromaeo/cssquery-yui.html (next: http://localhost:56909/tests/dromaeo/cssquery-dojo.html) 10:39:56 INFO - PROCESS | 2426 | RSS: Main: 274685952 10:39:56 INFO - PROCESS | 2426 | 10:39:56 INFO - PROCESS | 2426 | __start_tp_report 10:39:56 INFO - PROCESS | 2426 | _x_x_mozilla_page_load 10:39:56 INFO - PROCESS | 2426 | _x_x_mozilla_page_load_details 10:39:56 INFO - PROCESS | 2426 | |i|pagename|runs| 10:39:56 INFO - PROCESS | 2426 | |0;dojo.html;3145.386649603327;3298.7690861639694;3307.9173020674493;3334.6165191003033;3353.6646335366468;5632.2396476475715;5662.688552129631;5671.971640141799;5678.659280443177;5688.37427882933;5552.694601796899;5641.407652196512;5694.117411801167;5708.743106560204;5713.371529131792;5155.639105262633;5195.480451954812;5198.246254293125;5198.68807871527;5246.186841039645;5241.711705856195;5262.9473705263035;5267.947320526803;5282.7358632068435;5310.522053015228;5258.526732594066;5264.8683782905355;5281.207818827168;5282.418933917285;5305.602079844008;5188.584913206934;5207.140821764423;5234.26720259164;5295.629305948585;5306.867328316785;5080.491950804927;5119.59043276537;5171.22431635254;5195;5264.42091369949;5252.8686782830355;5445.537129343971;5787.247657804501;5788.131780232957;5801.53587712982;6056.723889863425;6087.178230938855;6120.3252356365065;6291.779787707408;6297.779577714804;4627.289523950893;6137.726793749422;6202.41077097675;6220.502359811204;6245;4853.733044682541;4880.365552478155;5862.648241105548;5885.499732522783;5892.351841297454;6159.353267906895;6208.037754148114;6213.596116252429;6309.779157729549;6316.052592111128;4612.538746125428;5409.755756176101;6040.516758659297;6082.665453400105;6137.3862613738265;6163.106349579286;6226.097215903759;6256.74973001084;6265.373462653698;6268.059791031382;6184.165137706352;6200.968995155085;6223.3465486123305;6253.468455181361;6285.622862628257;5009.373828271466;6099.96950015256;6217.564770466114;6266.717997690115;6270.498360131179;4854.68444551103;4895.04546613407;4931.087748766489;4951.529604687549;4958.504149585013;4380.299152135643;4384.934225986613;4391.385206071152;4421.491528474202;4424.778761061934;5929.99190137669;5996.2504686914135;6004.909926351108;6005.3394126646035;6016.007358785839;6156.630602163885;6159.076138579249;6189.35011823761;6225.252969643671;6247.531435142382;5889.057750759858;5920.703964801829;5965.224520812267;5967.5524335675;6011.398860113954;5465.59008074388;5473.972630136824;5476.014317422822;5476.616636835383;5477.20580515823;6158.076288556752;6173.84565385869;6253.062040693932;6337;6341.207349081365;6153.507719382349;6184.907226391518;6245.500359971283;6254.093156492374;6273.466755325849;6324.873502530016;6325.304216536086;6326.114343991753;6339.017452294902;6346.365363463617;6025.427584379477;6103.05402662588;6214.223222097238;6258.374162583706;6282.591631544118;5099.87250318745;6188.9690551546955;6199.132121502903;6225.190725205694;6247.843803904939;6175.5677102602385;6179.938200618116;6186.938130618636;6192.876142477216;6222.439980401786;6118.969405152946;6153.169322141633;6174.25908890936;6176.536759742948;6210.782622608187;5704.914426283692;5744.540436765133;5773.278340207474;5774.682392468287;6020.187274717857;6006.639601623741;6043.969780151071;6109.175261339662;6123.387661234019;6133.334663918993;5865.5600829937075;5897.144913987534;5902.38025007368;6090.908636370546;6094.299155596986 10:39:56 INFO - PROCESS | 2426 | |1;ext.html;463.25184826505193;477.4270874950059;495.34614309111964;497.2094369951776;519.8310549071552;37987.81006094969;41072.767816965505;41097;41141.97145071375;41260;41914.161716765644;42593.78703106488;42682;42737.786311068405;42753.14493710124;8569.528675922822;8814.294856411472;9335;9565.95217023914;10809.783804323908;9687.5640596173;9807.558659860288;10634.57461701531;11554.01790847779;11568.942155289213;9711;9906.455144967023;11385.943070284637;11587.188896777228;11647.708807279843;11538;11555.76888462226;11558.479868405942;11574.421278936104;11597.246178998423;9184.632614695403;9456.385334953206;10283.331583447052;11460.255083419552;11535.538578456852;33768.493472597685;40272;40928.18143637111;40932;42142;3928.4303775952326;3978.661813745835;4044.3529035354495;4081.734687245349;4084.4281800547947;29002.70997290021;29608;29628.407431851458;29676.406471870658;29707.851460742775;3106.254498920243;3228.983855080733;3275.6233033201247;3282.5404443377947;3286.753493487974;468839;472043;472997;473146;486486;56931;57458.7127064362;57698.4230157693;57995;58041.41958580445;58970.70514647399;59058.11412828811;59184;59224;59392.406075938685;15549.455769048029;15779.211039447982;15799.104107507173;15799.605009874846;15808.288627011581;11004.449777511092;11309.434528273718;11990.580329688593;12259.877401226053;12292.201006934522;18171.273149074153;18225.544361390806;18227.27090916375;18376;18499.722504162448;7773.494722842997;7870.56627255756;7889.329407000355;7901;7956.641951112214;856.952867592276;871.914466489225;896.8430524658289;903.2006674093343;913.4473643445752;29300.706992929798;30221.09336719902;30582.847085764428;30992;31011.84494077515;955.6511873166244;996.2080146283722;1097.6871591596357;1104.7956128116325;1117.4301106435776;13582.728345433035;14403.135811851322;15852.603684907739;15880.682386352208;16200.51398458048;242659;245246;245260;245634;246030;50442.738931527005;59918.80162396728;60560.394396056356;60601.696991514764;60709;1384.9861501384858;1410.8095407119908;1459.0516164492951;1460.160407765539;1463.751162302411;29179.708202918548;29923.653435595887;29954.550681740227;30285.848570757003;30352.392952141276;52504;52582;52984.20523692071;53009.939801204535;53017;518017;518394;518743;519702;519768;25238.116665916605;25861;26961.191164265103;27264.454710906066;27303.86348068247;42202.15595688007;42361.57638423576;42835;42857.78571107124;43595.34606980834;36125.27749444944;36392.27215455623;37337.25325493421;37618.43572346362;37683;35942.28115437729;36202.8189859049;36914.8154259227;37039;37128.44307335338;34716.30567388587;34837.477437837944;35028.47457288194;35489.290214195054;35653.930382088576;31956.36087278195;32512.51231231581;34300.97097087091;34472;34633;22990.310290691305;23693.526129477657;24439.26682199537;24510.019599216546;24820.00719971181 10:39:56 INFO - PROCESS | 2426 | |2;jquery.html;354.22248165277216;376.2813027118205;391.50865663592197;396.9702272329576;409.9651529619982;18508.72236916444;19721.506962325948;21472.892635536802;21904.123835046597;22175.00212490438;21885.890570547126;22137.778622213813;22172.55654886905;22253.2211372602;22793.658095128587;5790.044642633971;5992.101184822268;6119.66341851198;6124.989376752853;6149.477294429978;4178.268802959486;4407.228734971383;5954.821355359324;6087.573869829113;6428.485721142298;5597.888042239153;5950.851228719273;6046.667433291167;6102.664353460558;6592.868142637144;5177.275181474578;6397.4242318191355;6680.365365290289;6750.898736518956;6752.527323087385;5864.325602555676;5911.527077833763;5914.615549989237;5924.851878703023;5936.7328470218945;21636.35090947274;22236;26214.475710485873;26288.605670914952;26406;2200.4388880835445;2738.2469820799242;2747.162115554755;2793.7625301849366;2796.7063458336784;13060.477580896753;14789.890758193178;15596.454124105716;15679.216039198109;16038.679226415523;1568.8980216285904;1771.2649250561;1834.5688763140652;1847.9076046197636;1863.3850829226458;123306;126273;126525;127131.36434317862;127630;8998.055204203596;9852.261080418997;10223;10389.84415233772;10485.633002844865;11480.253783504046;11840.111991600665;12721.93639031799;12735.490580376867;12918.354082295848;8111.675532978615;8263.338932885355;8278.8344233115;8372.790680233044;8390.832183356299;8441.48112870576;8475;8595.3553483489;8721.825563488695;8762;4000.3599424092004;4072.9389059164137;4110.588941105865;4117.835286588503;4154.626083652486;1447.8552144785438;1637.7297745872036;1996.740423744933;2031.7663468700994;2054.4555692741437;810.9417210540302;981.2346369831542;1021.923355748307;1023.2734758321675;1065.2010991756183;18791.90604046971;21786.128554857627;21795.528841608855;21880.343589692333;21901.780982189975;1041.7812259425605;1062.1290541755686;1063.038584730712;1071.6249312740479;1085.1969542538623;11030.50362733679;11569.710757230969;11600.129990250765;11607.477663505162;11620.477078531487;721348.3932580303;722171;732359;756617;760513;152023.23988379986;160446;161744;162850;163903;867.1848462445407;872.3152325424511;962.5957098018849;998.1515711645188;1012.8278192707257;18252.36116735908;18485.72271415957;18618.534536636693;18657.90671046636;19011;60120.39879601323;60764;61421.692891535255;61806.69096654488;62554;87788;88888.55555722439;90332;90966;91122.63316050133;7569.545827250383;7582.848343032998;7620.466567340233;7683.846323073619;7685.231476852494;10675.412852293199;12848;12864.188031198659;13185.868141318464;13242;10863.456827158452;11010.339379637247;11325.32048077118;11352.829707554229;11496.195266331277;8426.578671066545;11087;11302.660920172408;11421.371824549396;11639.59261425846;10446.216533759847;11011.339319640847;11046.779064418506;11645.41772911334;11920.284782913053;6178.41305076017;6330;7044.29557044312;7682.347000505019;7688.769336919901;9553.713388598353;10522.894771052192;10786.460676966277;11259.831102533632;11603.825942611036 10:39:56 INFO - PROCESS | 2426 | |3;mootools.html;1320.9537666181684;1416.6104321311632;1445.21958142603;1458.6863824277777;1466.2448838847993;7075.6815943282545;7616.2764537369;7860.056793184812;8072.515649061053;8519.488830670156;6809.387155156035;7340.743073992411;7995.320397766182;8306.584670766468;8426.325893928486;2000.9499762505945;2229.3534874886263;2314.9537009259807;2315.30540837748;2320.0603755478974;2236.575050740354;2333.4399744061475;2347.1784875293683;2349.34218418843;2358.481134150493;2041.4386043837915;2122;2254.695616091831;2322.581935251654;2330.6154484510034;2221.244776775896;2229.264342766899;2253.7746225377496;2254.864708117518;2261.050358849287;2346.835721499496;2362.126013375062;2370.917017904365;2376.893039813195;2388.3312672451743;8457.957710211409;8480.321574274043;8484.490930544187;8528.189821966904;8618.956905215497;5119.180931051014;5281.128613778722;5282.2076688496645;5302.416734159239;5303.23103150045;3265.379577880219;3455.2571197192724;3495.16116132129;3544.946825797615;3601.693856022241;1218.9573364932273;1220.5544976083754;1245.1097465312346;1246.4577908609783;1261.0920137501075;10757.784844303149;10789.082927951134;10821;10865.674029779118;10872.782544349147;4509;4633.189191891473;4635.768211589407;4676.205045142334;4762.380890484214;9461.716148515556;9499.572519236513;9512.334136610509;9523.90476095244;9546.140847323773;5266.104762190437;5270.156774915995;5278.181881808326;5291.76187071575;5321.467853214647;4767.666263361601;4779.808807647655;4845.466998630148;4851.320815085891;4854.587360074363;3321.737739658914;3808.562015368268;3844.8654297099465;3881.1849511602313;3920.862769803043;1267.6387229639508;1279.9168054076456;1281.2568710148091;1293.534327642048;1294.2040645003256;676.5974245324024;749.9212582678849;777.6461709921972;813.2396209543977;854.2354592639552;4893.290472881484;5279.44565820591;5411.269478620415;5487.947986836895;5734.885302293931;970.1753509516996;975.8097171051577;976.9951150244203;992.7518120469882;1010.6462738041773;3457.8962631120603;3703.8333275002688;3722.4788529605876;3741.139537906297;3812.390017597171;9688.12806847387;10000.899990999997;10062.496875156214;10081.14310283634;10095.848562271573;9971.650992215382;10002.999700030085;10023.799524009624;10027.097561219525;10050.69847904564;7449.1061072670545;7863.488873223222;8025;8036.316913062338;8096.676132954616;4752.310914917249;5236.659617124875;5383.67697938125;5663.971680141738;5884.705764711833;9144.3598948073;9150.816983660423;9182.127697868691;9243.630254789734;9442.669506567237;8094.392920530866;9370.812583748151;9417.764555886157;9494.620215191591;9624.326297159132;7171.426285897011;7218.602976836324;7227.060482137288;7234.96382518084;7303.977443157856;7789.104253010976;7819.530828150329;7858.921410785819;7866.72466463671;7868.645910934136;6257.8422991746675;6963.303669633199;7047.612381319077;7059.223485416497;7141.321574450419;6890.2420733718245;6930.584164950119;6960.37356637905;6966.9303306966285;7064.540804847668;6240.126382306572;6308.779192728232;6369.808905732835;6398.1362516059735;6408.61548307103;4879.292502587176;4901.975490122527;4928.433230178432;4951.3810773653295;5030.949690503195;4198.748075115503;4923.975380123076;5025.824096156617;5074.11203039474;5120.615953803405 10:39:56 INFO - PROCESS | 2426 | |4;prototype.html;303.41592434563444;321.322010557723;350.09849637184254;354.6117001882938;362.99993517858303;5139.6659217150855;5260.316158899338;5274.762635681394;5401.297831281938;5403.972980135099;4359.673024523157;4474.523407275599;4943.555080042795;4989;5110.751269441444;4128.50457945047;4143.440635514197;4151.46031015969;4161.542230354667;4177.686673499485;3344.615369232545;3787.829547670348;4163.770992595406;4171.332586786115;4190.559991200926;3947.2894878921784;3955.6835453163676;3957.9604203958024;3981.9800900995456;3992.940105898414;3961.346377847637;3976.840926362942;3989.4215338775716;3996.860894645012;3998.66078344169;3457.913552161191;3483.6690514401093;3491.6682915123024;3494.790312581253;3501.124718820295;3676.6507181817683;4349.586789255016;4913.484084171147;5032.4212715537815;5216.713080780555;3651.214988777426;4473.329000649928;4499.255454172951;4538.568835960578;4552.567506086916;4653.7440440775745;4794.568488836021;5052.46949070346;5285.418603953601;5598.776048958037;3326.935380678184;3495.8951231463097;3554.377983852841;3683.3001729671278;3819.76543721923;4673.751742538937;4969.6085096172765;5499.50504454597;5903.926290859294;5956.37458066901;3239.5302681111107;3247.5615791868267;3550.2366991097037;3843.481130047464;3889.8444062237195;3419.333230020172;3883.825227864753;3894.883153505399;3926.293267211929;3934.744241624285;3911.3350730375905;4007.719459637797;4350.73895566267;4433.975336012237;4584.4957054723955;4737.8341758038305;5611.298587676541;5652.067408877571;5668.8866222675315;5695.373508914016;1046.9790604187874;1076.42949236903;1128.4583399968062;1206.3787149617954;1258.6727450862659;706.0715159565156;846.2087947768856;861.793169639282;862.8998027514968;866.7443104284251;4435.512093669694;4634.814607415666;4861.517237242604;4875.246060533369;5017.498250174954;4845.53967373099;4954.925676114861;5011.2961593058535;5204.947950520446;5318.25544423781;4693.6949098308505;4710.869391346052;4779.928301075487;4941.703497790072;4987.1771157759285;2605.501836444052;2884.908441379983;2887.870045847942;2888.8555572221726;2920.3867187890355;4865.661942965642;5095.121256490072;5689.203511508392;5712.34308054579;5739.8565035874435;4622.331445240331;5305.761240744176;5384.757685904144;5530.723463826793;5690.5447564194765;4735.602997115859;5020.748962551785;5186.351706036746;5559.833205003856;5760.6255593386295;4473.5526447355005;4889.853304400874;5365.704886231295;5458.727063646722;5513.8345849624575;4378.540253273361;4737.620990320835;5120.209485830306;5230.660007099526;5388.595855310946;5408.999335123015;5563.638363506359;5644.78637093027;5709.143628455598;5748.39641837601;3246.8701251949656;3519.507268982293;3530.480984340049;3569.5538057742783;3661.725370597269;4566.794494247833;5143.122760192428;5241.947580524146;5516.503514683698;5532.5020748132865;4777.498362672009;4829;4858.82994095205;5165.870853228699;5387.784488620411;4436.600705936481;4454.665900057444;4555.635549155993;4755.714657120584;5137.640365174402;4263.976645605095;4509.301058335963;4965.47862474435;5452.563794896319;5476.644018138808;5636.379998200113;5646.71766411686;5675.262215911905;5675.460831221028;5695.601307908572;5006.924896126488;5345.037893179265;5484.972575137099;5496.395396506461;5574.888502229852 10:39:56 INFO - PROCESS | 2426 | |5;yui.html;636.907648390983;765.5865832450473;817.2603793566824;828.225609055533;830.6469750356099;15209.695806083886;15852.920735396307;15855.3657853686;15923.12422816746;15952.202389880518;12528.1856679316;14335.641608959755;14468.421263149461;14478.927605361985;14510.419583216659;677.5088061155648;698.8637215742932;840.848647243496;855.529458797662;862.7886167888876;864.4813112132733;878.1262643669526;878.666106879385;878.666106879385;883.0639522106555;911.7994041310934;914.1315750037458;927.6567669962124;928.0672923711694;930;811.6121432350687;859.1494420523699;910.0308171797014;919.9908000920005;920.8158368326328;679.480197648799;679.7824696097251;681.8261343357412;682.5154140560209;682.7610336382276;13536.93231533846;14282.008042547697;15028.774568381374;15050.473233436887;15576.299066542033;14278.286085695674;14520.927395363062;14548.709025819424;14579.19814410207;14619.195944223064;11743.119266054995;11846.111541634413;11862.52549898003;11891.108166887518;12256.509739610405;3700.3870531227967;3756.718246131551;3762.3792074307707;3773.8490460381554;3891.8054097295303;82150;100779.06878716151;101871;102423.46364804533;106138.93861061368;11070.833937490945;11171.497282622302;11197.60808371703;11329.943350283196;11380.886191138148;22536.436589085402;22569.210077647203;22644.75453850054;22675.88662056679;23118.959646815933;7716.074071111502;7881.330086942674;7888.7633370998965;7900.44696871225;8063.475874068166;4240.936385954213;4317.096655714274;4321.6974811762875;4335.053988678612;4370.584794444522;3572.160542272564;3629;3665.4135338345736;3709.7774133552075;3714.201446688975;651.3618250101;660.9735610575523;663.9535232533773;672.7544446277074;673.9629320387328;669.3520314287058;698.8322802527357;707.5224223649017;709.4785332780402;734.1740541890373;7859.764207073797;8393.748187554384;9176.220021298132;10261.127804136582;10788.489611454403;531.1554628141274;536.4447796530573;568.6929058308467;591.9437653422918;593.1843714892024;4711.882202944886;4819.566239038503;5399.7030163340605;5566.053770858964;5733.226014488074;97315.51342243243;102759;103000.48499757453;103504;105780.47109764403;83205.58397207975;86596.56701716452;88057;89921.65117523243;90122.54938725264;67364.32635673581;70910;71017;77204.61397692976;77842.22157778463;10735.570577176826;10843.132549395872;11145.387003714874;11173.664790056311;11197.328160310408;44600.54696991696;46646.06707865756;47862.76068619634;48707.0258594819;49928.75178120576;54019.72990135024;65158.022629661544;66039;66465.33534664784;67697.9845302311;12863.807042894177;12981.4158362872;12990.805137923127;13297.26865022433;13482.319857306675;13361.331933403097;13800.102993305403;14422.855771442151;14912.179830109448;15257.542273731806;10832.025117739442;13635.113717608323;13649.795253071014;13915.373808178858;14192.148471091768;11484.310941343547;13377.799333010207;13513.864861351654;13704.65738356549;14001.45983941786;12862.807057893951;13246;13699.178049317074;13785.793213102012;13903.860961390257;11114.110871130128;11122.45201312016;11127.276727013043;11272.718182045515;11456.599019034295;7929.46269162871;8373.413861029912;9340.486273255035;10892.836607451052;11472.967432931076 10:39:56 INFO - PROCESS | 2426 | __end_tp_report 10:39:56 INFO - PROCESS | 2426 | __start_cc_report 10:39:56 INFO - PROCESS | 2426 | _x_x_mozilla_cycle_collect,374 10:39:56 INFO - PROCESS | 2426 | __end_cc_report 10:39:56 INFO - PROCESS | 2426 | __startTimestamp1483987196663__endTimestamp 10:39:56 INFO - PROCESS | 2426 | 10:39:56 INFO - PROCESS | 2426 | ------- Summary: start ------- 10:39:56 INFO - PROCESS | 2426 | Number of tests: 6 10:39:56 INFO - PROCESS | 2426 | 10:39:56 INFO - PROCESS | 2426 | [#0] dojo.html Cycles:165 Average:5708.62 Median:5996.25 stddev:663.78 (11.1%) stddev-sans-first:634.63 10:39:56 INFO - PROCESS | 2426 | Values: 3145.4 3298.8 3307.9 3334.6 3353.7 5632.2 5662.7 5672.0 5678.7 5688.4 5552.7 5641.4 5694.1 5708.7 5713.4 5155.6 5195.5 5198.2 5198.7 5246.2 5241.7 5262.9 5267.9 5282.7 5310.5 5258.5 5264.9 5281.2 5282.4 5305.6 5188.6 5207.1 5234.3 5295.6 5306.9 5080.5 5119.6 5171.2 5195.0 5264.4 5252.9 5445.5 5787.2 5788.1 5801.5 6056.7 6087.2 6120.3 6291.8 6297.8 4627.3 6137.7 6202.4 6220.5 6245.0 4853.7 4880.4 5862.6 5885.5 5892.4 6159.4 6208.0 6213.6 6309.8 6316.1 4612.5 5409.8 6040.5 6082.7 6137.4 6163.1 6226.1 6256.7 6265.4 6268.1 6184.2 6201.0 6223.3 6253.5 6285.6 5009.4 6100.0 6217.6 6266.7 6270.5 4854.7 4895.0 4931.1 4951.5 4958.5 4380.3 4384.9 4391.4 4421.5 4424.8 5930.0 5996.3 6004.9 6005.3 6016.0 6156.6 6159.1 6189.4 6225.3 6247.5 5889.1 5920.7 5965.2 5967.6 6011.4 5465.6 5474.0 5476.0 5476.6 5477.2 6158.1 6173.8 6253.1 6337.0 6341.2 6153.5 6184.9 6245.5 6254.1 6273.5 6324.9 6325.3 6326.1 6339.0 6346.4 6025.4 6103.1 6214.2 6258.4 6282.6 5099.9 6189.0 6199.1 6225.2 6247.8 6175.6 6179.9 6186.9 6192.9 6222.4 6119.0 6153.2 6174.3 6176.5 6210.8 5704.9 5744.5 5773.3 5774.7 6020.2 6006.6 6044.0 6109.2 6123.4 6133.3 5865.6 5897.1 5902.4 6090.9 6094.3 10:39:56 INFO - PROCESS | 2426 | 10:39:56 INFO - PROCESS | 2426 | [#1] ext.html Cycles:180 Average:56884.73 Median:36164.05 stddev:114427.47 (316.4%) stddev-sans-first:114670.05 10:39:56 INFO - PROCESS | 2426 | Values: 463.3 477.4 495.3 497.2 519.8 37987.8 41072.8 41097.0 41142.0 41260.0 41914.2 42593.8 42682.0 42737.8 42753.1 8569.5 8814.3 9335.0 9566.0 10809.8 9687.6 9807.6 10634.6 11554.0 11568.9 9711.0 9906.5 11385.9 11587.2 11647.7 11538.0 11555.8 11558.5 11574.4 11597.2 9184.6 9456.4 10283.3 11460.3 11535.5 33768.5 40272.0 40928.2 40932.0 42142.0 3928.4 3978.7 4044.4 4081.7 4084.4 29002.7 29608.0 29628.4 29676.4 29707.9 3106.3 3229.0 3275.6 3282.5 3286.8 468839.0 472043.0 472997.0 473146.0 486486.0 56931.0 57458.7 57698.4 57995.0 58041.4 58970.7 59058.1 59184.0 59224.0 59392.4 15549.5 15779.2 15799.1 15799.6 15808.3 11004.4 11309.4 11990.6 12259.9 12292.2 18171.3 18225.5 18227.3 18376.0 18499.7 7773.5 7870.6 7889.3 7901.0 7956.6 857.0 871.9 896.8 903.2 913.4 29300.7 30221.1 30582.8 30992.0 31011.8 955.7 996.2 1097.7 1104.8 1117.4 13582.7 14403.1 15852.6 15880.7 16200.5 242659.0 245246.0 245260.0 245634.0 246030.0 50442.7 59918.8 60560.4 60601.7 60709.0 1385.0 1410.8 1459.1 1460.2 1463.8 29179.7 29923.7 29954.6 30285.8 30352.4 52504.0 52582.0 52984.2 53009.9 53017.0 518017.0 518394.0 518743.0 519702.0 519768.0 25238.1 25861.0 26961.2 27264.5 27303.9 42202.2 42361.6 42835.0 42857.8 43595.3 36125.3 36392.3 37337.3 37618.4 37683.0 35942.3 36202.8 36914.8 37039.0 37128.4 34716.3 34837.5 35028.5 35489.3 35653.9 31956.4 32512.5 34301.0 34472.0 34633.0 22990.3 23693.5 24439.3 24510.0 24820.0 10:39:56 INFO - PROCESS | 2426 | 10:39:56 INFO - PROCESS | 2426 | [#2] jquery.html Cycles:180 Average:40662.98 Median:22244.61 stddev:123244.69 (554.0%) stddev-sans-first:123553.26 10:39:56 INFO - PROCESS | 2426 | Values: 354.2 376.3 391.5 397.0 410.0 18508.7 19721.5 21472.9 21904.1 22175.0 21885.9 22137.8 22172.6 22253.2 22793.7 5790.0 5992.1 6119.7 6125.0 6149.5 4178.3 4407.2 5954.8 6087.6 6428.5 5597.9 5950.9 6046.7 6102.7 6592.9 5177.3 6397.4 6680.4 6750.9 6752.5 5864.3 5911.5 5914.6 5924.9 5936.7 21636.4 22236.0 26214.5 26288.6 26406.0 2200.4 2738.2 2747.2 2793.8 2796.7 13060.5 14789.9 15596.5 15679.2 16038.7 1568.9 1771.3 1834.6 1847.9 1863.4 123306.0 126273.0 126525.0 127131.4 127630.0 8998.1 9852.3 10223.0 10389.8 10485.6 11480.3 11840.1 12721.9 12735.5 12918.4 8111.7 8263.3 8278.8 8372.8 8390.8 8441.5 8475.0 8595.4 8721.8 8762.0 4000.4 4072.9 4110.6 4117.8 4154.6 1447.9 1637.7 1996.7 2031.8 2054.5 810.9 981.2 1021.9 1023.3 1065.2 18791.9 21786.1 21795.5 21880.3 21901.8 1041.8 1062.1 1063.0 1071.6 1085.2 11030.5 11569.7 11600.1 11607.5 11620.5 721348.4 722171.0 732359.0 756617.0 760513.0 152023.2 160446.0 161744.0 162850.0 163903.0 867.2 872.3 962.6 998.2 1012.8 18252.4 18485.7 18618.5 18657.9 19011.0 60120.4 60764.0 61421.7 61806.7 62554.0 87788.0 88888.6 90332.0 90966.0 91122.6 7569.5 7582.8 7620.5 7683.8 7685.2 10675.4 12848.0 12864.2 13185.9 13242.0 10863.5 11010.3 11325.3 11352.8 11496.2 8426.6 11087.0 11302.7 11421.4 11639.6 10446.2 11011.3 11046.8 11645.4 11920.3 6178.4 6330.0 7044.3 7682.3 7688.8 9553.7 10522.9 10786.5 11259.8 11603.8 10:39:56 INFO - PROCESS | 2426 | 10:39:56 INFO - PROCESS | 2426 | [#3] mootools.html Cycles:180 Average:5405.99 Median:5119.90 stddev:2953.11 (57.7%) stddev-sans-first:2945.43 10:39:56 INFO - PROCESS | 2426 | Values: 1321.0 1416.6 1445.2 1458.7 1466.2 7075.7 7616.3 7860.1 8072.5 8519.5 6809.4 7340.7 7995.3 8306.6 8426.3 2000.9 2229.4 2315.0 2315.3 2320.1 2236.6 2333.4 2347.2 2349.3 2358.5 2041.4 2122.0 2254.7 2322.6 2330.6 2221.2 2229.3 2253.8 2254.9 2261.1 2346.8 2362.1 2370.9 2376.9 2388.3 8458.0 8480.3 8484.5 8528.2 8619.0 5119.2 5281.1 5282.2 5302.4 5303.2 3265.4 3455.3 3495.2 3544.9 3601.7 1219.0 1220.6 1245.1 1246.5 1261.1 10757.8 10789.1 10821.0 10865.7 10872.8 4509.0 4633.2 4635.8 4676.2 4762.4 9461.7 9499.6 9512.3 9523.9 9546.1 5266.1 5270.2 5278.2 5291.8 5321.5 4767.7 4779.8 4845.5 4851.3 4854.6 3321.7 3808.6 3844.9 3881.2 3920.9 1267.6 1279.9 1281.3 1293.5 1294.2 676.6 749.9 777.6 813.2 854.2 4893.3 5279.4 5411.3 5487.9 5734.9 970.2 975.8 977.0 992.8 1010.6 3457.9 3703.8 3722.5 3741.1 3812.4 9688.1 10000.9 10062.5 10081.1 10095.8 9971.7 10003.0 10023.8 10027.1 10050.7 7449.1 7863.5 8025.0 8036.3 8096.7 4752.3 5236.7 5383.7 5664.0 5884.7 9144.4 9150.8 9182.1 9243.6 9442.7 8094.4 9370.8 9417.8 9494.6 9624.3 7171.4 7218.6 7227.1 7235.0 7304.0 7789.1 7819.5 7858.9 7866.7 7868.6 6257.8 6963.3 7047.6 7059.2 7141.3 6890.2 6930.6 6960.4 6966.9 7064.5 6240.1 6308.8 6369.8 6398.1 6408.6 4879.3 4902.0 4928.4 4951.4 5030.9 4198.7 4924.0 5025.8 5074.1 5120.6 10:39:56 INFO - PROCESS | 2426 | 10:39:56 INFO - PROCESS | 2426 | [#4] prototype.html Cycles:180 Average:4322.01 Median:4737.73 stddev:1316.91 (27.8%) stddev-sans-first:1285.60 10:39:56 INFO - PROCESS | 2426 | Values: 303.4 321.3 350.1 354.6 363.0 5139.7 5260.3 5274.8 5401.3 5404.0 4359.7 4474.5 4943.6 4989.0 5110.8 4128.5 4143.4 4151.5 4161.5 4177.7 3344.6 3787.8 4163.8 4171.3 4190.6 3947.3 3955.7 3958.0 3982.0 3992.9 3961.3 3976.8 3989.4 3996.9 3998.7 3457.9 3483.7 3491.7 3494.8 3501.1 3676.7 4349.6 4913.5 5032.4 5216.7 3651.2 4473.3 4499.3 4538.6 4552.6 4653.7 4794.6 5052.5 5285.4 5598.8 3326.9 3495.9 3554.4 3683.3 3819.8 4673.8 4969.6 5499.5 5903.9 5956.4 3239.5 3247.6 3550.2 3843.5 3889.8 3419.3 3883.8 3894.9 3926.3 3934.7 3911.3 4007.7 4350.7 4434.0 4584.5 4737.8 5611.3 5652.1 5668.9 5695.4 1047.0 1076.4 1128.5 1206.4 1258.7 706.1 846.2 861.8 862.9 866.7 4435.5 4634.8 4861.5 4875.2 5017.5 4845.5 4954.9 5011.3 5204.9 5318.3 4693.7 4710.9 4779.9 4941.7 4987.2 2605.5 2884.9 2887.9 2888.9 2920.4 4865.7 5095.1 5689.2 5712.3 5739.9 4622.3 5305.8 5384.8 5530.7 5690.5 4735.6 5020.7 5186.4 5559.8 5760.6 4473.6 4889.9 5365.7 5458.7 5513.8 4378.5 4737.6 5120.2 5230.7 5388.6 5409.0 5563.6 5644.8 5709.1 5748.4 3246.9 3519.5 3530.5 3569.6 3661.7 4566.8 5143.1 5241.9 5516.5 5532.5 4777.5 4829.0 4858.8 5165.9 5387.8 4436.6 4454.7 4555.6 4755.7 5137.6 4264.0 4509.3 4965.5 5452.6 5476.6 5636.4 5646.7 5675.3 5675.5 5695.6 5006.9 5345.0 5485.0 5496.4 5574.9 10:39:56 INFO - PROCESS | 2426 | 10:39:56 INFO - PROCESS | 2426 | [#5] yui.html Cycles:180 Average:19950.63 Median:3832.83 stddev:28215.57 (736.2%) stddev-sans-first:28257.46 10:39:56 INFO - PROCESS | 2426 | Values: 636.9 765.6 817.3 828.2 830.6 15209.7 15852.9 15855.4 15923.1 15952.2 12528.2 14335.6 14468.4 14478.9 14510.4 677.5 698.9 840.8 855.5 862.8 864.5 878.1 878.7 878.7 883.1 911.8 914.1 927.7 928.1 930.0 811.6 859.1 910.0 920.0 920.8 679.5 679.8 681.8 682.5 682.8 13536.9 14282.0 15028.8 15050.5 15576.3 14278.3 14520.9 14548.7 14579.2 14619.2 11743.1 11846.1 11862.5 11891.1 12256.5 3700.4 3756.7 3762.4 3773.8 3891.8 82150.0 100779.1 101871.0 102423.5 106138.9 11070.8 11171.5 11197.6 11329.9 11380.9 22536.4 22569.2 22644.8 22675.9 23119.0 7716.1 7881.3 7888.8 7900.4 8063.5 4240.9 4317.1 4321.7 4335.1 4370.6 3572.2 3629.0 3665.4 3709.8 3714.2 651.4 661.0 664.0 672.8 674.0 669.4 698.8 707.5 709.5 734.2 7859.8 8393.7 9176.2 10261.1 10788.5 531.2 536.4 568.7 591.9 593.2 4711.9 4819.6 5399.7 5566.1 5733.2 97315.5 102759.0 103000.5 103504.0 105780.5 83205.6 86596.6 88057.0 89921.7 90122.5 67364.3 70910.0 71017.0 77204.6 77842.2 10735.6 10843.1 11145.4 11173.7 11197.3 44600.5 46646.1 47862.8 48707.0 49928.8 54019.7 65158.0 66039.0 66465.3 67698.0 12863.8 12981.4 12990.8 13297.3 13482.3 13361.3 13800.1 14422.9 14912.2 15257.5 10832.0 13635.1 13649.8 13915.4 14192.1 11484.3 13377.8 13513.9 13704.7 14001.5 12862.8 13246.0 13699.2 13785.8 13903.9 11114.1 11122.5 11127.3 11272.7 11456.6 7929.5 8373.4 9340.5 10892.8 11473.0 10:39:56 INFO - PROCESS | 2426 | -------- Summary: end -------- 10:39:56 INFO - PROCESS | 2426 | 10:39:57 INFO - TEST-INFO | 2426: exit 0 10:39:57 INFO - TEST-OK | dromaeo_css | took 1117699ms 10:39:57 INFO - TEST-START | kraken 10:39:57 INFO - operating with platform_type : mac_ 10:39:57 INFO - Initialising browser for kraken test... 10:39:57 INFO - TEST-INFO | started process 2432 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/tmpGgD8eH/profile http://localhost:56909/getInfo.html) 10:39:57 INFO - PROCESS | 2432 | 1483987197783 addons.xpi WARN Exception running bootstrap method install on shield-recipe-client@mozilla.org: [Exception... "Component returned failure code: 0x8000ffff (NS_ERROR_UNEXPECTED) [nsIPrefBranch.getBoolPref]" nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: 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 :: this.install :: line 38" data: no] Stack trace: this.install()@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:38 < callBootstrapMethod()@resource://gre/modules/addons/XPIProvider.jsm:4906 < processFileChanges()@resource://gre/modules/addons/XPIProvider.jsm -> resource://gre/modules/addons/XPIProviderUtils.js:2060 < checkForChanges()@resource://gre/modules/addons/XPIProvider.jsm:3761 < startup()@resource://gre/modules/addons/XPIProvider.jsm:2794 < callProvider()@resource://gre/modules/AddonManager.jsm:267 < _startProvider()@resource://gre/modules/AddonManager.jsm:973 < startup()@resource://gre/modules/AddonManager.jsm:1155 < startup()@resource://gre/modules/AddonManager.jsm:3124 < observe()@resource://gre/components/addonManager.js:65 10:40:00 INFO - PROCESS | 2432 | 2017-01-09 10:40:00.064 plugin-container[2433:28749] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x893b, name = 'com.apple.tsm.portname' 10:40:00 INFO - PROCESS | 2432 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:40:00 INFO - PROCESS | 2432 | 2017-01-09 10:40:00.067 plugin-container[2433:28749] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8e0b, name = 'com.apple.CFPasteboardClient' 10:40:00 INFO - PROCESS | 2432 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:40:00 INFO - PROCESS | 2432 | 2017-01-09 10:40:00.067 plugin-container[2433:28749] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 10:40:00 INFO - PROCESS | 2432 | __metrics Screen width/height:1600/1200 10:40:00 INFO - PROCESS | 2432 | colorDepth:24 10:40:00 INFO - PROCESS | 2432 | Browser inner width/height: 1024/689 10:40:00 INFO - PROCESS | 2432 | __metrics 10:40:00 ERROR - PROCESS | 2432 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3252: TypeError: this.tabs is undefined 10:40:00 INFO - TEST-INFO | 2432: exit 0 10:40:00 INFO - Browser initialized. 10:40:00 INFO - Running cycle 1/1 for kraken test... 10:40:00 INFO - TEST-INFO | started process 2434 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/tmpGgD8eH/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:40:03 INFO - PROCESS | 2434 | 2017-01-09 10:40:03.282 plugin-container[2435:28862] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x444f, name = 'com.apple.tsm.portname' 10:40:03 INFO - PROCESS | 2434 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:40:03 INFO - PROCESS | 2434 | 2017-01-09 10:40:03.282 plugin-container[2435:28862] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8c0b, name = 'com.apple.CFPasteboardClient' 10:40:03 INFO - PROCESS | 2434 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:40:03 INFO - PROCESS | 2434 | 2017-01-09 10:40:03.282 plugin-container[2435:28862] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 10:40:13 INFO - PROCESS | 2434 | RSS: Main: 233291776 10:40:13 INFO - PROCESS | 2434 | 10:40:16 INFO - PROCESS | 2434 | Cycle 1(1): loaded http://localhost:56909/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:56909/tests/kraken/driver.html?testName=audio-beat-detection) 10:40:16 INFO - PROCESS | 2434 | RSS: Main: 245915648 10:40:16 INFO - PROCESS | 2434 | 10:40:19 INFO - PROCESS | 2434 | Cycle 1(1): loaded http://localhost:56909/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:56909/tests/kraken/driver.html?testName=audio-dft) 10:40:20 INFO - PROCESS | 2434 | RSS: Main: 253751296 10:40:20 INFO - PROCESS | 2434 | 10:40:23 INFO - PROCESS | 2434 | Cycle 1(1): loaded http://localhost:56909/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:56909/tests/kraken/driver.html?testName=audio-fft) 10:40:24 INFO - PROCESS | 2434 | RSS: Main: 256368640 10:40:24 INFO - PROCESS | 2434 | 10:40:26 INFO - PROCESS | 2434 | Cycle 1(1): loaded http://localhost:56909/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:56909/tests/kraken/driver.html?testName=audio-oscillator) 10:40:26 INFO - PROCESS | 2434 | RSS: Main: 257548288 10:40:26 INFO - PROCESS | 2434 | 10:40:28 INFO - PROCESS | 2434 | Cycle 1(1): loaded http://localhost:56909/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:56909/tests/kraken/driver.html?testName=imaging-gaussian-blur) 10:40:29 INFO - PROCESS | 2434 | RSS: Main: 259182592 10:40:29 INFO - PROCESS | 2434 | 10:40:32 INFO - PROCESS | 2434 | Cycle 1(1): loaded http://localhost:56909/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:56909/tests/kraken/driver.html?testName=imaging-darkroom) 10:40:33 INFO - PROCESS | 2434 | RSS: Main: 265199616 10:40:33 INFO - PROCESS | 2434 | 10:40:36 INFO - PROCESS | 2434 | Cycle 1(1): loaded http://localhost:56909/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:56909/tests/kraken/driver.html?testName=imaging-desaturate) 10:40:36 INFO - PROCESS | 2434 | RSS: Main: 264269824 10:40:36 INFO - PROCESS | 2434 | 10:40:39 INFO - PROCESS | 2434 | Cycle 1(1): loaded http://localhost:56909/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:56909/tests/kraken/driver.html?testName=json-parse-financial) 10:40:40 INFO - PROCESS | 2434 | RSS: Main: 263921664 10:40:40 INFO - PROCESS | 2434 | 10:40:41 INFO - PROCESS | 2434 | Cycle 1(1): loaded http://localhost:56909/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:56909/tests/kraken/driver.html?testName=json-stringify-tinderbox) 10:40:42 INFO - PROCESS | 2434 | RSS: Main: 263024640 10:40:42 INFO - PROCESS | 2434 | 10:40:44 INFO - PROCESS | 2434 | Cycle 1(1): loaded http://localhost:56909/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:56909/tests/kraken/driver.html?testName=stanford-crypto-aes) 10:40:45 INFO - PROCESS | 2434 | RSS: Main: 263012352 10:40:45 INFO - PROCESS | 2434 | 10:40:47 INFO - PROCESS | 2434 | Cycle 1(1): loaded http://localhost:56909/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:56909/tests/kraken/driver.html?testName=stanford-crypto-ccm) 10:40:48 INFO - PROCESS | 2434 | RSS: Main: 263102464 10:40:48 INFO - PROCESS | 2434 | 10:40:51 INFO - PROCESS | 2434 | Cycle 1(1): loaded http://localhost:56909/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:56909/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 10:40:51 INFO - PROCESS | 2434 | RSS: Main: 263344128 10:40:51 INFO - PROCESS | 2434 | 10:40:54 INFO - PROCESS | 2434 | Cycle 1(1): loaded http://localhost:56909/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:56909/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 10:40:55 INFO - PROCESS | 2434 | RSS: Main: 271872000 10:40:55 INFO - PROCESS | 2434 | 10:40:57 INFO - PROCESS | 2434 | Cycle 1(1): loaded http://localhost:56909/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:56909/tests/kraken/driver.html?testName=ai-astar) 10:40:57 INFO - PROCESS | 2434 | RSS: Main: 273022976 10:40:57 INFO - PROCESS | 2434 | 10:40:57 INFO - PROCESS | 2434 | __start_tp_report 10:40:57 INFO - PROCESS | 2434 | _x_x_mozilla_page_load 10:40:57 INFO - PROCESS | 2434 | _x_x_mozilla_page_load_details 10:40:57 INFO - PROCESS | 2434 | |i|pagename|runs| 10:40:57 INFO - PROCESS | 2434 | |0;ai-astar;106;117;120;118;117;119;117;119;117;116 10:40:57 INFO - PROCESS | 2434 | |1;audio-beat-detection;144;115;119;184;117;115;114;112;129;114 10:40:57 INFO - PROCESS | 2434 | |2;audio-dft;200;202;211;252;277;201;199;200;195;198 10:40:57 INFO - PROCESS | 2434 | |3;audio-fft;87;77;70;110;86;63;61;61;75;61 10:40:57 INFO - PROCESS | 2434 | |4;audio-oscillator;101;101;118;101;69;70;81;69;71;82 10:40:57 INFO - PROCESS | 2434 | |5;imaging-gaussian-blur;130;128;90;89;102;89;125;88;80;130 10:40:57 INFO - PROCESS | 2434 | |6;imaging-darkroom;120;96;98;97;101;99;96;96;98;96 10:40:57 INFO - PROCESS | 2434 | |7;imaging-desaturate;91;96;114;117;92;92;91;93;92;92 10:40:57 INFO - PROCESS | 2434 | |8;json-parse-financial;61;61;61;61;61;62;64;63;62;63 10:40:57 INFO - PROCESS | 2434 | |9;json-stringify-tinderbox;75;70;70;90;100;116;63;105;104;106 10:40:57 INFO - PROCESS | 2434 | |10;stanford-crypto-aes;88;89;85;86;89;85;89;87;87;90 10:40:57 INFO - PROCESS | 2434 | |11;stanford-crypto-ccm;142;149;148;154;159;142;149;161;214;175 10:40:57 INFO - PROCESS | 2434 | |12;stanford-crypto-pbkdf2;175;178;176;174;173;173;174;180;174;177 10:40:57 INFO - PROCESS | 2434 | |13;stanford-crypto-sha256-iterative;67;69;84;88;90;87;86;87;65;73 10:40:57 INFO - PROCESS | 2434 | __end_tp_report 10:40:57 INFO - PROCESS | 2434 | __start_cc_report 10:40:57 INFO - PROCESS | 2434 | _x_x_mozilla_cycle_collect,924 10:40:57 INFO - PROCESS | 2434 | __end_cc_report 10:40:57 INFO - PROCESS | 2434 | __startTimestamp1483987257506__endTimestamp 10:40:57 INFO - PROCESS | 2434 | 10:40:57 INFO - PROCESS | 2434 | ------- Summary: start ------- 10:40:57 INFO - PROCESS | 2434 | Number of tests: 14 10:40:57 INFO - PROCESS | 2434 | 10:40:57 INFO - PROCESS | 2434 | [#0] ai-astar Cycles:10 Average:116.60 Median:117.50 stddev:3.92 (3.3%) stddev-sans-first:1.30 10:40:57 INFO - PROCESS | 2434 | Values: 106.0 117.0 120.0 118.0 117.0 119.0 117.0 119.0 117.0 116.0 10:40:57 INFO - PROCESS | 2434 | 10:40:57 INFO - PROCESS | 2434 | [#1] audio-beat-detection Cycles:10 Average:126.30 Median:118.00 stddev:22.49 (19.1%) stddev-sans-first:22.92 10:40:57 INFO - PROCESS | 2434 | Values: 144.0 115.0 119.0 184.0 117.0 115.0 114.0 112.0 129.0 114.0 10:40:57 INFO - PROCESS | 2434 | 10:40:57 INFO - PROCESS | 2434 | [#2] audio-dft Cycles:10 Average:213.50 Median:201.50 stddev:27.82 (13.8%) stddev-sans-first:29.08 10:40:57 INFO - PROCESS | 2434 | Values: 200.0 202.0 211.0 252.0 277.0 201.0 199.0 200.0 195.0 198.0 10:40:57 INFO - PROCESS | 2434 | 10:40:57 INFO - PROCESS | 2434 | [#3] audio-fft Cycles:10 Average:75.10 Median:72.50 stddev:15.81 (21.8%) stddev-sans-first:16.18 10:40:57 INFO - PROCESS | 2434 | Values: 87.0 77.0 70.0 110.0 86.0 63.0 61.0 61.0 75.0 61.0 10:40:57 INFO - PROCESS | 2434 | 10:40:57 INFO - PROCESS | 2434 | [#4] audio-oscillator Cycles:10 Average:86.30 Median:69.50 stddev:17.63 (25.4%) stddev-sans-first:17.88 10:40:57 INFO - PROCESS | 2434 | Values: 101.0 101.0 118.0 101.0 69.0 70.0 81.0 69.0 71.0 82.0 10:40:57 INFO - PROCESS | 2434 | 10:40:57 INFO - PROCESS | 2434 | [#5] imaging-gaussian-blur Cycles:10 Average:105.10 Median:84.00 stddev:20.65 (24.6%) stddev-sans-first:19.84 10:40:57 INFO - PROCESS | 2434 | Values: 130.0 128.0 90.0 89.0 102.0 89.0 125.0 88.0 80.0 130.0 10:40:57 INFO - PROCESS | 2434 | 10:40:57 INFO - PROCESS | 2434 | [#6] imaging-darkroom Cycles:10 Average:99.70 Median:96.50 stddev:7.32 (7.6%) stddev-sans-first:1.74 10:40:57 INFO - PROCESS | 2434 | Values: 120.0 96.0 98.0 97.0 101.0 99.0 96.0 96.0 98.0 96.0 10:40:57 INFO - PROCESS | 2434 | 10:40:57 INFO - PROCESS | 2434 | [#7] imaging-desaturate Cycles:10 Average:97.00 Median:92.00 stddev:9.88 (10.7%) stddev-sans-first:10.23 10:40:57 INFO - PROCESS | 2434 | Values: 91.0 96.0 114.0 117.0 92.0 92.0 91.0 93.0 92.0 92.0 10:40:57 INFO - PROCESS | 2434 | 10:40:57 INFO - PROCESS | 2434 | [#8] json-parse-financial Cycles:10 Average:61.90 Median:62.00 stddev:1.10 (1.8%) stddev-sans-first:1.12 10:40:57 INFO - PROCESS | 2434 | Values: 61.0 61.0 61.0 61.0 61.0 62.0 64.0 63.0 62.0 63.0 10:40:57 INFO - PROCESS | 2434 | 10:40:57 INFO - PROCESS | 2434 | [#9] json-stringify-tinderbox Cycles:10 Average:89.90 Median:66.50 stddev:18.88 (28.4%) stddev-sans-first:19.24 10:40:57 INFO - PROCESS | 2434 | Values: 75.0 70.0 70.0 90.0 100.0 116.0 63.0 105.0 104.0 106.0 10:40:57 INFO - PROCESS | 2434 | 10:40:57 INFO - PROCESS | 2434 | [#10] stanford-crypto-aes Cycles:10 Average:87.50 Median:88.50 stddev:1.78 (2.0%) stddev-sans-first:1.88 10:40:57 INFO - PROCESS | 2434 | Values: 88.0 89.0 85.0 86.0 89.0 85.0 89.0 87.0 87.0 90.0 10:40:57 INFO - PROCESS | 2434 | 10:40:57 INFO - PROCESS | 2434 | [#11] stanford-crypto-ccm Cycles:10 Average:159.30 Median:156.50 stddev:21.62 (13.8%) stddev-sans-first:22.01 10:40:57 INFO - PROCESS | 2434 | Values: 142.0 149.0 148.0 154.0 159.0 142.0 149.0 161.0 214.0 175.0 10:40:57 INFO - PROCESS | 2434 | 10:40:57 INFO - PROCESS | 2434 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:175.40 Median:175.50 stddev:2.32 (1.3%) stddev-sans-first:2.46 10:40:57 INFO - PROCESS | 2434 | Values: 175.0 178.0 176.0 174.0 173.0 173.0 174.0 180.0 174.0 177.0 10:40:57 INFO - PROCESS | 2434 | 10:40:57 INFO - PROCESS | 2434 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:79.60 Median:86.50 stddev:9.87 (11.4%) stddev-sans-first:9.35 10:40:57 INFO - PROCESS | 2434 | Values: 67.0 69.0 84.0 88.0 90.0 87.0 86.0 87.0 65.0 73.0 10:40:57 INFO - PROCESS | 2434 | -------- Summary: end -------- 10:40:57 INFO - PROCESS | 2434 | 10:40:58 INFO - TEST-INFO | 2434: exit 0 10:40:58 INFO - TEST-OK | kraken | took 61221ms 10:40:58 INFO - SUITE-END | took 1178s 10:40:58 INFO - Completed test suite (00:19:39) 10:40:58 INFO - javascript benchmark 10:40:58 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7194.711278661035, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3145.386649603327, 3298.7690861639694, 3307.9173020674493, 3334.6165191003033, 3353.6646335366468, 5632.2396476475715, 5662.688552129631, 5671.971640141799, 5678.659280443177, 5688.37427882933, 5552.694601796899, 5641.407652196512, 5694.117411801167, 5708.743106560204, 5713.371529131792, 5155.639105262633, 5195.480451954812, 5198.246254293125, 5198.68807871527, 5246.186841039645, 5241.711705856195, 5262.9473705263035, 5267.947320526803, 5282.7358632068435, 5310.522053015228, 5258.526732594066, 5264.8683782905355, 5281.207818827168, 5282.418933917285, 5305.602079844008, 5188.584913206934, 5207.140821764423, 5234.26720259164, 5295.629305948585, 5306.867328316785, 5080.491950804927, 5119.59043276537, 5171.22431635254, 5195.0, 5264.42091369949, 5252.8686782830355, 5445.537129343971, 5787.247657804501, 5788.131780232957, 5801.53587712982, 6056.723889863425, 6087.178230938855, 6120.3252356365065, 6291.779787707408, 6297.779577714804, 4627.289523950893, 6137.726793749422, 6202.41077097675, 6220.502359811204, 6245.0, 4853.733044682541, 4880.365552478155, 5862.648241105548, 5885.499732522783, 5892.351841297454, 6159.353267906895, 6208.037754148114, 6213.596116252429, 6309.779157729549, 6316.052592111128, 4612.538746125428, 5409.755756176101, 6040.516758659297, 6082.665453400105, 6137.3862613738265, 6163.106349579286, 6226.097215903759, 6256.74973001084, 6265.373462653698, 6268.059791031382, 6184.165137706352, 6200.968995155085, 6223.3465486123305, 6253.468455181361, 6285.622862628257, 5009.373828271466, 6099.96950015256, 6217.564770466114, 6266.717997690115, 6270.498360131179, 4854.68444551103, 4895.04546613407, 4931.087748766489, 4951.529604687549, 4958.504149585013, 4380.299152135643, 4384.934225986613, 4391.385206071152, 4421.491528474202, 4424.778761061934, 5929.99190137669, 5996.2504686914135, 6004.909926351108, 6005.3394126646035, 6016.007358785839, 6156.630602163885, 6159.076138579249, 6189.35011823761, 6225.252969643671, 6247.531435142382, 5889.057750759858, 5920.703964801829, 5965.224520812267, 5967.5524335675, 6011.398860113954, 5465.59008074388, 5473.972630136824, 5476.014317422822, 5476.616636835383, 5477.20580515823, 6158.076288556752, 6173.84565385869, 6253.062040693932, 6337.0, 6341.207349081365, 6153.507719382349, 6184.907226391518, 6245.500359971283, 6254.093156492374, 6273.466755325849, 6324.873502530016, 6325.304216536086, 6326.114343991753, 6339.017452294902, 6346.365363463617, 6025.427584379477, 6103.05402662588, 6214.223222097238, 6258.374162583706, 6282.591631544118, 5099.87250318745, 6188.9690551546955, 6199.132121502903, 6225.190725205694, 6247.843803904939, 6175.5677102602385, 6179.938200618116, 6186.938130618636, 6192.876142477216, 6222.439980401786, 6118.969405152946, 6153.169322141633, 6174.25908890936, 6176.536759742948, 6210.782622608187, 5704.914426283692, 5744.540436765133, 5773.278340207474, 5774.682392468287, 6020.187274717857, 6006.639601623741, 6043.969780151071, 6109.175261339662, 6123.387661234019, 6133.334663918993, 5865.5600829937075, 5897.144913987534, 5902.38025007368, 6090.908636370546, 6094.299155596986], "value": 5667.301217545468, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [463.25184826505193, 477.4270874950059, 495.34614309111964, 497.2094369951776, 519.8310549071552, 37987.81006094969, 41072.767816965505, 41097.0, 41141.97145071375, 41260.0, 41914.161716765644, 42593.78703106488, 42682.0, 42737.786311068405, 42753.14493710124, 8569.528675922822, 8814.294856411472, 9335.0, 9565.95217023914, 10809.783804323908, 9687.5640596173, 9807.558659860288, 10634.57461701531, 11554.01790847779, 11568.942155289213, 9711.0, 9906.455144967023, 11385.943070284637, 11587.188896777228, 11647.708807279843, 11538.0, 11555.76888462226, 11558.479868405942, 11574.421278936104, 11597.246178998423, 9184.632614695403, 9456.385334953206, 10283.331583447052, 11460.255083419552, 11535.538578456852, 33768.493472597685, 40272.0, 40928.18143637111, 40932.0, 42142.0, 3928.4303775952326, 3978.661813745835, 4044.3529035354495, 4081.734687245349, 4084.4281800547947, 29002.70997290021, 29608.0, 29628.407431851458, 29676.406471870658, 29707.851460742775, 3106.254498920243, 3228.983855080733, 3275.6233033201247, 3282.5404443377947, 3286.753493487974, 468839.0, 472043.0, 472997.0, 473146.0, 486486.0, 56931.0, 57458.7127064362, 57698.4230157693, 57995.0, 58041.41958580445, 58970.70514647399, 59058.11412828811, 59184.0, 59224.0, 59392.406075938685, 15549.455769048029, 15779.211039447982, 15799.104107507173, 15799.605009874846, 15808.288627011581, 11004.449777511092, 11309.434528273718, 11990.580329688593, 12259.877401226053, 12292.201006934522, 18171.273149074153, 18225.544361390806, 18227.27090916375, 18376.0, 18499.722504162448, 7773.494722842997, 7870.56627255756, 7889.329407000355, 7901.0, 7956.641951112214, 856.952867592276, 871.914466489225, 896.8430524658289, 903.2006674093343, 913.4473643445752, 29300.706992929798, 30221.09336719902, 30582.847085764428, 30992.0, 31011.84494077515, 955.6511873166244, 996.2080146283722, 1097.6871591596357, 1104.7956128116325, 1117.4301106435776, 13582.728345433035, 14403.135811851322, 15852.603684907739, 15880.682386352208, 16200.51398458048, 242659.0, 245246.0, 245260.0, 245634.0, 246030.0, 50442.738931527005, 59918.80162396728, 60560.394396056356, 60601.696991514764, 60709.0, 1384.9861501384858, 1410.8095407119908, 1459.0516164492951, 1460.160407765539, 1463.751162302411, 29179.708202918548, 29923.653435595887, 29954.550681740227, 30285.848570757003, 30352.392952141276, 52504.0, 52582.0, 52984.20523692071, 53009.939801204535, 53017.0, 518017.0, 518394.0, 518743.0, 519702.0, 519768.0, 25238.116665916605, 25861.0, 26961.191164265103, 27264.454710906066, 27303.86348068247, 42202.15595688007, 42361.57638423576, 42835.0, 42857.78571107124, 43595.34606980834, 36125.27749444944, 36392.27215455623, 37337.25325493421, 37618.43572346362, 37683.0, 35942.28115437729, 36202.8189859049, 36914.8154259227, 37039.0, 37128.44307335338, 34716.30567388587, 34837.477437837944, 35028.47457288194, 35489.290214195054, 35653.930382088576, 31956.36087278195, 32512.51231231581, 34300.97097087091, 34472.0, 34633.0, 22990.310290691305, 23693.526129477657, 24439.26682199537, 24510.019599216546, 24820.00719971181], "value": 19409.359437668943, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [354.22248165277216, 376.2813027118205, 391.50865663592197, 396.9702272329576, 409.9651529619982, 18508.72236916444, 19721.506962325948, 21472.892635536802, 21904.123835046597, 22175.00212490438, 21885.890570547126, 22137.778622213813, 22172.55654886905, 22253.2211372602, 22793.658095128587, 5790.044642633971, 5992.101184822268, 6119.66341851198, 6124.989376752853, 6149.477294429978, 4178.268802959486, 4407.228734971383, 5954.821355359324, 6087.573869829113, 6428.485721142298, 5597.888042239153, 5950.851228719273, 6046.667433291167, 6102.664353460558, 6592.868142637144, 5177.275181474578, 6397.4242318191355, 6680.365365290289, 6750.898736518956, 6752.527323087385, 5864.325602555676, 5911.527077833763, 5914.615549989237, 5924.851878703023, 5936.7328470218945, 21636.35090947274, 22236.0, 26214.475710485873, 26288.605670914952, 26406.0, 2200.4388880835445, 2738.2469820799242, 2747.162115554755, 2793.7625301849366, 2796.7063458336784, 13060.477580896753, 14789.890758193178, 15596.454124105716, 15679.216039198109, 16038.679226415523, 1568.8980216285904, 1771.2649250561, 1834.5688763140652, 1847.9076046197636, 1863.3850829226458, 123306.0, 126273.0, 126525.0, 127131.36434317862, 127630.0, 8998.055204203596, 9852.261080418997, 10223.0, 10389.84415233772, 10485.633002844865, 11480.253783504046, 11840.111991600665, 12721.93639031799, 12735.490580376867, 12918.354082295848, 8111.675532978615, 8263.338932885355, 8278.8344233115, 8372.790680233044, 8390.832183356299, 8441.48112870576, 8475.0, 8595.3553483489, 8721.825563488695, 8762.0, 4000.3599424092004, 4072.9389059164137, 4110.588941105865, 4117.835286588503, 4154.626083652486, 1447.8552144785438, 1637.7297745872036, 1996.740423744933, 2031.7663468700994, 2054.4555692741437, 810.9417210540302, 981.2346369831542, 1021.923355748307, 1023.2734758321675, 1065.2010991756183, 18791.90604046971, 21786.128554857627, 21795.528841608855, 21880.343589692333, 21901.780982189975, 1041.7812259425605, 1062.1290541755686, 1063.038584730712, 1071.6249312740479, 1085.1969542538623, 11030.50362733679, 11569.710757230969, 11600.129990250765, 11607.477663505162, 11620.477078531487, 721348.3932580303, 722171.0, 732359.0, 756617.0, 760513.0, 152023.23988379986, 160446.0, 161744.0, 162850.0, 163903.0, 867.1848462445407, 872.3152325424511, 962.5957098018849, 998.1515711645188, 1012.8278192707257, 18252.36116735908, 18485.72271415957, 18618.534536636693, 18657.90671046636, 19011.0, 60120.39879601323, 60764.0, 61421.692891535255, 61806.69096654488, 62554.0, 87788.0, 88888.55555722439, 90332.0, 90966.0, 91122.63316050133, 7569.545827250383, 7582.848343032998, 7620.466567340233, 7683.846323073619, 7685.231476852494, 10675.412852293199, 12848.0, 12864.188031198659, 13185.868141318464, 13242.0, 10863.456827158452, 11010.339379637247, 11325.32048077118, 11352.829707554229, 11496.195266331277, 8426.578671066545, 11087.0, 11302.660920172408, 11421.371824549396, 11639.59261425846, 10446.216533759847, 11011.339319640847, 11046.779064418506, 11645.41772911334, 11920.284782913053, 6178.41305076017, 6330.0, 7044.29557044312, 7682.347000505019, 7688.769336919901, 9553.713388598353, 10522.894771052192, 10786.460676966277, 11259.831102533632, 11603.825942611036], "value": 9833.581839345386, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1320.9537666181684, 1416.6104321311632, 1445.21958142603, 1458.6863824277777, 1466.2448838847993, 7075.6815943282545, 7616.2764537369, 7860.056793184812, 8072.515649061053, 8519.488830670156, 6809.387155156035, 7340.743073992411, 7995.320397766182, 8306.584670766468, 8426.325893928486, 2000.9499762505945, 2229.3534874886263, 2314.9537009259807, 2315.30540837748, 2320.0603755478974, 2236.575050740354, 2333.4399744061475, 2347.1784875293683, 2349.34218418843, 2358.481134150493, 2041.4386043837915, 2122.0, 2254.695616091831, 2322.581935251654, 2330.6154484510034, 2221.244776775896, 2229.264342766899, 2253.7746225377496, 2254.864708117518, 2261.050358849287, 2346.835721499496, 2362.126013375062, 2370.917017904365, 2376.893039813195, 2388.3312672451743, 8457.957710211409, 8480.321574274043, 8484.490930544187, 8528.189821966904, 8618.956905215497, 5119.180931051014, 5281.128613778722, 5282.2076688496645, 5302.416734159239, 5303.23103150045, 3265.379577880219, 3455.2571197192724, 3495.16116132129, 3544.946825797615, 3601.693856022241, 1218.9573364932273, 1220.5544976083754, 1245.1097465312346, 1246.4577908609783, 1261.0920137501075, 10757.784844303149, 10789.082927951134, 10821.0, 10865.674029779118, 10872.782544349147, 4509.0, 4633.189191891473, 4635.768211589407, 4676.205045142334, 4762.380890484214, 9461.716148515556, 9499.572519236513, 9512.334136610509, 9523.90476095244, 9546.140847323773, 5266.104762190437, 5270.156774915995, 5278.181881808326, 5291.76187071575, 5321.467853214647, 4767.666263361601, 4779.808807647655, 4845.466998630148, 4851.320815085891, 4854.587360074363, 3321.737739658914, 3808.562015368268, 3844.8654297099465, 3881.1849511602313, 3920.862769803043, 1267.6387229639508, 1279.9168054076456, 1281.2568710148091, 1293.534327642048, 1294.2040645003256, 676.5974245324024, 749.9212582678849, 777.6461709921972, 813.2396209543977, 854.2354592639552, 4893.290472881484, 5279.44565820591, 5411.269478620415, 5487.947986836895, 5734.885302293931, 970.1753509516996, 975.8097171051577, 976.9951150244203, 992.7518120469882, 1010.6462738041773, 3457.8962631120603, 3703.8333275002688, 3722.4788529605876, 3741.139537906297, 3812.390017597171, 9688.12806847387, 10000.899990999997, 10062.496875156214, 10081.14310283634, 10095.848562271573, 9971.650992215382, 10002.999700030085, 10023.799524009624, 10027.097561219525, 10050.69847904564, 7449.1061072670545, 7863.488873223222, 8025.0, 8036.316913062338, 8096.676132954616, 4752.310914917249, 5236.659617124875, 5383.67697938125, 5663.971680141738, 5884.705764711833, 9144.3598948073, 9150.816983660423, 9182.127697868691, 9243.630254789734, 9442.669506567237, 8094.392920530866, 9370.812583748151, 9417.764555886157, 9494.620215191591, 9624.326297159132, 7171.426285897011, 7218.602976836324, 7227.060482137288, 7234.96382518084, 7303.977443157856, 7789.104253010976, 7819.530828150329, 7858.921410785819, 7866.72466463671, 7868.645910934136, 6257.8422991746675, 6963.303669633199, 7047.612381319077, 7059.223485416497, 7141.321574450419, 6890.2420733718245, 6930.584164950119, 6960.37356637905, 6966.9303306966285, 7064.540804847668, 6240.126382306572, 6308.779192728232, 6369.808905732835, 6398.1362516059735, 6408.61548307103, 4879.292502587176, 4901.975490122527, 4928.433230178432, 4951.3810773653295, 5030.949690503195, 4198.748075115503, 4923.975380123076, 5025.824096156617, 5074.11203039474, 5120.615953803405], "value": 4390.995601309803, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [303.41592434563444, 321.322010557723, 350.09849637184254, 354.6117001882938, 362.99993517858303, 5139.6659217150855, 5260.316158899338, 5274.762635681394, 5401.297831281938, 5403.972980135099, 4359.673024523157, 4474.523407275599, 4943.555080042795, 4989.0, 5110.751269441444, 4128.50457945047, 4143.440635514197, 4151.46031015969, 4161.542230354667, 4177.686673499485, 3344.615369232545, 3787.829547670348, 4163.770992595406, 4171.332586786115, 4190.559991200926, 3947.2894878921784, 3955.6835453163676, 3957.9604203958024, 3981.9800900995456, 3992.940105898414, 3961.346377847637, 3976.840926362942, 3989.4215338775716, 3996.860894645012, 3998.66078344169, 3457.913552161191, 3483.6690514401093, 3491.6682915123024, 3494.790312581253, 3501.124718820295, 3676.6507181817683, 4349.586789255016, 4913.484084171147, 5032.4212715537815, 5216.713080780555, 3651.214988777426, 4473.329000649928, 4499.255454172951, 4538.568835960578, 4552.567506086916, 4653.7440440775745, 4794.568488836021, 5052.46949070346, 5285.418603953601, 5598.776048958037, 3326.935380678184, 3495.8951231463097, 3554.377983852841, 3683.3001729671278, 3819.76543721923, 4673.751742538937, 4969.6085096172765, 5499.50504454597, 5903.926290859294, 5956.37458066901, 3239.5302681111107, 3247.5615791868267, 3550.2366991097037, 3843.481130047464, 3889.8444062237195, 3419.333230020172, 3883.825227864753, 3894.883153505399, 3926.293267211929, 3934.744241624285, 3911.3350730375905, 4007.719459637797, 4350.73895566267, 4433.975336012237, 4584.4957054723955, 4737.8341758038305, 5611.298587676541, 5652.067408877571, 5668.8866222675315, 5695.373508914016, 1046.9790604187874, 1076.42949236903, 1128.4583399968062, 1206.3787149617954, 1258.6727450862659, 706.0715159565156, 846.2087947768856, 861.793169639282, 862.8998027514968, 866.7443104284251, 4435.512093669694, 4634.814607415666, 4861.517237242604, 4875.246060533369, 5017.498250174954, 4845.53967373099, 4954.925676114861, 5011.2961593058535, 5204.947950520446, 5318.25544423781, 4693.6949098308505, 4710.869391346052, 4779.928301075487, 4941.703497790072, 4987.1771157759285, 2605.501836444052, 2884.908441379983, 2887.870045847942, 2888.8555572221726, 2920.3867187890355, 4865.661942965642, 5095.121256490072, 5689.203511508392, 5712.34308054579, 5739.8565035874435, 4622.331445240331, 5305.761240744176, 5384.757685904144, 5530.723463826793, 5690.5447564194765, 4735.602997115859, 5020.748962551785, 5186.351706036746, 5559.833205003856, 5760.6255593386295, 4473.5526447355005, 4889.853304400874, 5365.704886231295, 5458.727063646722, 5513.8345849624575, 4378.540253273361, 4737.620990320835, 5120.209485830306, 5230.660007099526, 5388.595855310946, 5408.999335123015, 5563.638363506359, 5644.78637093027, 5709.143628455598, 5748.39641837601, 3246.8701251949656, 3519.507268982293, 3530.480984340049, 3569.5538057742783, 3661.725370597269, 4566.794494247833, 5143.122760192428, 5241.947580524146, 5516.503514683698, 5532.5020748132865, 4777.498362672009, 4829.0, 4858.82994095205, 5165.870853228699, 5387.784488620411, 4436.600705936481, 4454.665900057444, 4555.635549155993, 4755.714657120584, 5137.640365174402, 4263.976645605095, 4509.301058335963, 4965.47862474435, 5452.563794896319, 5476.644018138808, 5636.379998200113, 5646.71766411686, 5675.262215911905, 5675.460831221028, 5695.601307908572, 5006.924896126488, 5345.037893179265, 5484.972575137099, 5496.395396506461, 5574.888502229852], "value": 3910.660669269637, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [636.907648390983, 765.5865832450473, 817.2603793566824, 828.225609055533, 830.6469750356099, 15209.695806083886, 15852.920735396307, 15855.3657853686, 15923.12422816746, 15952.202389880518, 12528.1856679316, 14335.641608959755, 14468.421263149461, 14478.927605361985, 14510.419583216659, 677.5088061155648, 698.8637215742932, 840.848647243496, 855.529458797662, 862.7886167888876, 864.4813112132733, 878.1262643669526, 878.666106879385, 878.666106879385, 883.0639522106555, 911.7994041310934, 914.1315750037458, 927.6567669962124, 928.0672923711694, 930.0, 811.6121432350687, 859.1494420523699, 910.0308171797014, 919.9908000920005, 920.8158368326328, 679.480197648799, 679.7824696097251, 681.8261343357412, 682.5154140560209, 682.7610336382276, 13536.93231533846, 14282.008042547697, 15028.774568381374, 15050.473233436887, 15576.299066542033, 14278.286085695674, 14520.927395363062, 14548.709025819424, 14579.19814410207, 14619.195944223064, 11743.119266054995, 11846.111541634413, 11862.52549898003, 11891.108166887518, 12256.509739610405, 3700.3870531227967, 3756.718246131551, 3762.3792074307707, 3773.8490460381554, 3891.8054097295303, 82150.0, 100779.06878716151, 101871.0, 102423.46364804533, 106138.93861061368, 11070.833937490945, 11171.497282622302, 11197.60808371703, 11329.943350283196, 11380.886191138148, 22536.436589085402, 22569.210077647203, 22644.75453850054, 22675.88662056679, 23118.959646815933, 7716.074071111502, 7881.330086942674, 7888.7633370998965, 7900.44696871225, 8063.475874068166, 4240.936385954213, 4317.096655714274, 4321.6974811762875, 4335.053988678612, 4370.584794444522, 3572.160542272564, 3629.0, 3665.4135338345736, 3709.7774133552075, 3714.201446688975, 651.3618250101, 660.9735610575523, 663.9535232533773, 672.7544446277074, 673.9629320387328, 669.3520314287058, 698.8322802527357, 707.5224223649017, 709.4785332780402, 734.1740541890373, 7859.764207073797, 8393.748187554384, 9176.220021298132, 10261.127804136582, 10788.489611454403, 531.1554628141274, 536.4447796530573, 568.6929058308467, 591.9437653422918, 593.1843714892024, 4711.882202944886, 4819.566239038503, 5399.7030163340605, 5566.053770858964, 5733.226014488074, 97315.51342243243, 102759.0, 103000.48499757453, 103504.0, 105780.47109764403, 83205.58397207975, 86596.56701716452, 88057.0, 89921.65117523243, 90122.54938725264, 67364.32635673581, 70910.0, 71017.0, 77204.61397692976, 77842.22157778463, 10735.570577176826, 10843.132549395872, 11145.387003714874, 11173.664790056311, 11197.328160310408, 44600.54696991696, 46646.06707865756, 47862.76068619634, 48707.0258594819, 49928.75178120576, 54019.72990135024, 65158.022629661544, 66039.0, 66465.33534664784, 67697.9845302311, 12863.807042894177, 12981.4158362872, 12990.805137923127, 13297.26865022433, 13482.319857306675, 13361.331933403097, 13800.102993305403, 14422.855771442151, 14912.179830109448, 15257.542273731806, 10832.025117739442, 13635.113717608323, 13649.795253071014, 13915.373808178858, 14192.148471091768, 11484.310941343547, 13377.799333010207, 13513.864861351654, 13704.65738356549, 14001.45983941786, 12862.807057893951, 13246.0, 13699.178049317074, 13785.793213102012, 13903.860961390257, 11114.110871130128, 11122.45201312016, 11127.276727013043, 11272.718182045515, 11456.599019034295, 7929.46269162871, 8373.413861029912, 9340.486273255035, 10892.836607451052, 11472.967432931076], "value": 7466.528848244527, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1573.2, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [106.0, 117.0, 120.0, 118.0, 117.0, 119.0, 117.0, 119.0, 117.0, 116.0], "value": 116.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 115.0, 119.0, 184.0, 117.0, 115.0, 114.0, 112.0, 129.0, 114.0], "value": 126.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [200.0, 202.0, 211.0, 252.0, 277.0, 201.0, 199.0, 200.0, 195.0, 198.0], "value": 213.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 77.0, 70.0, 110.0, 86.0, 63.0, 61.0, 61.0, 75.0, 61.0], "value": 75.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [101.0, 101.0, 118.0, 101.0, 69.0, 70.0, 81.0, 69.0, 71.0, 82.0], "value": 86.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [130.0, 128.0, 90.0, 89.0, 102.0, 89.0, 125.0, 88.0, 80.0, 130.0], "value": 105.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [120.0, 96.0, 98.0, 97.0, 101.0, 99.0, 96.0, 96.0, 98.0, 96.0], "value": 99.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 96.0, 114.0, 117.0, 92.0, 92.0, 91.0, 93.0, 92.0, 92.0], "value": 97.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 61.0, 61.0, 61.0, 61.0, 62.0, 64.0, 63.0, 62.0, 63.0], "value": 61.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 70.0, 70.0, 90.0, 100.0, 116.0, 63.0, 105.0, 104.0, 106.0], "value": 89.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 89.0, 85.0, 86.0, 89.0, 85.0, 89.0, 87.0, 87.0, 90.0], "value": 87.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 149.0, 148.0, 154.0, 159.0, 142.0, 149.0, 161.0, 214.0, 175.0], "value": 159.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [175.0, 178.0, 176.0, 174.0, 173.0, 173.0, 174.0, 180.0, 174.0, 177.0], "value": 175.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 69.0, 84.0, 88.0, 90.0, 87.0, 86.0, 87.0, 65.0, 73.0], "value": 79.6, "unit": "score"}]}]} 10:40:58 INFO - Return code: 0 10:40:58 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 10:40:58 ERROR - # TBPL SUCCESS # 10:40:58 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 10:40:58 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 10:40:58 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 10:40:58 INFO - 2017-01-09 10:40:58.840 screenresolution[2439:29267] starting screenresolution argv=screenresolution get 10:40:58 INFO - 2017-01-09 10:40:58.844 screenresolution[2439:29267] Display 0: 1600x1200x32@60 10:40:58 INFO - 2017-01-09 10:40:58.850 screenresolution[2440:29270] starting screenresolution argv=screenresolution list 10:40:58 INFO - Available Modes on Display 0 10:40:58 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 10:40:58 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 10:40:58 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 10:40:58 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 10:40:59 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 10:40:59 INFO - Intel Iris: 10:40:59 INFO - Chipset Model: Intel Iris 10:40:59 INFO - Type: GPU 10:40:59 INFO - Bus: Built-In 10:40:59 INFO - VRAM (Dynamic, Max): 1536 MB 10:40:59 INFO - Vendor: Intel (0x8086) 10:40:59 INFO - Device ID: 0x0a2e 10:40:59 INFO - Revision ID: 0x0009 10:40:59 INFO - Displays: 10:40:59 INFO - Display: 10:40:59 INFO - Resolution: 1600 x 1200 @ 60 Hz 10:40:59 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 10:40:59 INFO - Display Serial Number: 1600x1200 60 10:40:59 INFO - Main Display: Yes 10:40:59 INFO - Mirror: Off 10:40:59 INFO - Online: Yes 10:40:59 INFO - Rotation: Supported 10:40:59 INFO - Return code: 0 10:40:59 INFO - Running post-action listener: _resource_record_post_action 10:40:59 INFO - [mozharness: 2017-01-09 18:40:59.090229Z] Finished run-tests step (success) 10:40:59 INFO - Running post-run listener: _resource_record_post_run 10:40:59 INFO - Total resource usage - Wall time: 1201s; CPU: 55.0%; Read bytes: 28672; Write bytes: 551537152; Read time: 1; Write time: 1773 10:40:59 INFO - TinderboxPrint: CPU usage
54.8% 10:40:59 INFO - TinderboxPrint: I/O read bytes / time
28,672 / 1 10:40:59 INFO - TinderboxPrint: I/O write bytes / time
551,537,152 / 1,773 10:40:59 INFO - TinderboxPrint: CPU idle
2,174.2 (45.2%) 10:40:59 INFO - TinderboxPrint: CPU system
971.4 (20.2%) 10:40:59 INFO - TinderboxPrint: CPU user
1,660.2 (34.5%) 10:40:59 INFO - TinderboxPrint: Swap in / out
249,376,768 / 0 10:40:59 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 246075904; Write bytes: 241649152; Read time: 17955; Write time: 490 10:40:59 INFO - run-tests - Wall time: 1180s; CPU: 55.0%; Read bytes: 28672; Write bytes: 296408064; Read time: 1; Write time: 1246 10:40:59 INFO - Running post-run listener: _upload_blobber_files 10:40:59 INFO - Blob upload gear active. 10:40:59 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 10:40:59 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:40:59 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', 'Graphics', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 10:40:59 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 Graphics -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 10:40:59 INFO - (blobuploader) - INFO - Open directory for files ... 10:40:59 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 10:40:59 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:40:59 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:41:00 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 10:41:00 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:41:00 INFO - (blobuploader) - INFO - Done attempting. 10:41:00 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 10:41:00 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:41:00 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:41:01 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 10:41:01 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:41:01 INFO - (blobuploader) - INFO - Done attempting. 10:41:01 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 10:41:01 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:41:01 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:41:02 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 10:41:02 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:41:02 INFO - (blobuploader) - INFO - Done attempting. 10:41:02 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 10:41:02 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:41:02 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:41:04 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 10:41:04 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:41:04 INFO - (blobuploader) - INFO - Done attempting. 10:41:04 INFO - (blobuploader) - INFO - Iteration through files over. 10:41:04 INFO - Return code: 0 10:41:04 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 10:41:04 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 10:41:04 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/7d0476fa3cea1277765f1173264bca91549f41cc401a1e36d38a8259c423d7f99d94d39efba8fb0e13a4e9247ccac4015ba81aafdac0fd4e002e03c8e87fff2e", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/25bacae3db0d888ac419c5e8805ec9be38c1a5512851785fc6ba1ac7ec0f2af7e9a4f6d0b7805c3946d72adc9f1d1aa71b648b8a3cc2f5f2526f2cc9d315af42", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/75aac8bdd5abca1b31ca271088fb910034a1f34054fc5b9b36d2ebbfc47b61bcfd7fa29161ad90aa92217906b681a5914c1602a07814222ea29040cdf1373b30", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/944b2ddedb97c5d16a68a8ee84abdb6275584bd6b47decdc1e6b8d4cf7fc1635b8d2f35fe196f058474eef7d9355b5138ecabaef4af7dd40d107e1b2c571a4ac"} 10:41:04 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 10:41:04 INFO - Writing to file /builds/slave/test/properties/blobber_files 10:41:04 INFO - Contents: 10:41:04 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/7d0476fa3cea1277765f1173264bca91549f41cc401a1e36d38a8259c423d7f99d94d39efba8fb0e13a4e9247ccac4015ba81aafdac0fd4e002e03c8e87fff2e", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/25bacae3db0d888ac419c5e8805ec9be38c1a5512851785fc6ba1ac7ec0f2af7e9a4f6d0b7805c3946d72adc9f1d1aa71b648b8a3cc2f5f2526f2cc9d315af42", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/75aac8bdd5abca1b31ca271088fb910034a1f34054fc5b9b36d2ebbfc47b61bcfd7fa29161ad90aa92217906b681a5914c1602a07814222ea29040cdf1373b30", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/944b2ddedb97c5d16a68a8ee84abdb6275584bd6b47decdc1e6b8d4cf7fc1635b8d2f35fe196f058474eef7d9355b5138ecabaef4af7dd40d107e1b2c571a4ac"} 10:41:04 INFO - Running post-run listener: copy_logs_to_upload_dir 10:41:04 INFO - Copying logs to upload dir... 10:41:04 INFO - mkdir: /builds/slave/test/build/upload/logs 10:41:04 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1263.689218 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 3 secs) (at 2017-01-09 10:41:04.944919) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-09 10:41:04.948756) ========= 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.8zCsXPyheg/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.u5J9zxbz4t/Listeners TMPDIR=/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/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/Graphics/sha512/7d0476fa3cea1277765f1173264bca91549f41cc401a1e36d38a8259c423d7f99d94d39efba8fb0e13a4e9247ccac4015ba81aafdac0fd4e002e03c8e87fff2e", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/25bacae3db0d888ac419c5e8805ec9be38c1a5512851785fc6ba1ac7ec0f2af7e9a4f6d0b7805c3946d72adc9f1d1aa71b648b8a3cc2f5f2526f2cc9d315af42", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/75aac8bdd5abca1b31ca271088fb910034a1f34054fc5b9b36d2ebbfc47b61bcfd7fa29161ad90aa92217906b681a5914c1602a07814222ea29040cdf1373b30", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/944b2ddedb97c5d16a68a8ee84abdb6275584bd6b47decdc1e6b8d4cf7fc1635b8d2f35fe196f058474eef7d9355b5138ecabaef4af7dd40d107e1b2c571a4ac"} build_url:https://queue.taskcluster.net/v1/task/BK2LFD8rQKi1FVC8xBBxNg/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011318 build_url: 'https://queue.taskcluster.net/v1/task/BK2LFD8rQKi1FVC8xBBxNg/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/7d0476fa3cea1277765f1173264bca91549f41cc401a1e36d38a8259c423d7f99d94d39efba8fb0e13a4e9247ccac4015ba81aafdac0fd4e002e03c8e87fff2e", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/25bacae3db0d888ac419c5e8805ec9be38c1a5512851785fc6ba1ac7ec0f2af7e9a4f6d0b7805c3946d72adc9f1d1aa71b648b8a3cc2f5f2526f2cc9d315af42", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/75aac8bdd5abca1b31ca271088fb910034a1f34054fc5b9b36d2ebbfc47b61bcfd7fa29161ad90aa92217906b681a5914c1602a07814222ea29040cdf1373b30", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/944b2ddedb97c5d16a68a8ee84abdb6275584bd6b47decdc1e6b8d4cf7fc1635b8d2f35fe196f058474eef7d9355b5138ecabaef4af7dd40d107e1b2c571a4ac"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-09 10:41:04.988268) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-09 10:41:04.988614) ========= 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.8zCsXPyheg/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.u5J9zxbz4t/Listeners TMPDIR=/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/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.005318 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-09 10:41:05.037443) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-09 10:41:05.037808) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-09 10:41:05.038328) ========= ========= Total master_lag: 0.21 =========