builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0374 starttime: 1482537215.7 results: success (0) buildid: 20161223143436 builduid: dbe5aa66022644ce8dadcfe0b0638cf3 revision: dd35d18c8e14751cca293db90ea2205e0a0871c7 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-12-23 15:53:35.702766) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-12-23 15:53:35.703271) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-23 15:53:35.703588) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-23 15:53:35.731376) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-23 15:53:35.731693) ========= 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.7L1P6PQ1Hl/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.nIP4upsNEJ/Listeners TMPDIR=/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-12-23 15:53:35-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 157M=0s 2016-12-23 15:53:36 (157 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.623320 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-23 15:53:36.379107) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-23 15:53:36.379437) ========= 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.7L1P6PQ1Hl/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.nIP4upsNEJ/Listeners TMPDIR=/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/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.025474 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-23 15:53:36.427452) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-12-23 15:53:36.427817) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev dd35d18c8e14751cca293db90ea2205e0a0871c7 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev dd35d18c8e14751cca293db90ea2205e0a0871c7 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.7L1P6PQ1Hl/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.nIP4upsNEJ/Listeners TMPDIR=/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-12-23 15:53:36,519 truncating revision to first 12 chars 2016-12-23 15:53:36,519 Setting DEBUG logging. 2016-12-23 15:53:36,519 attempt 1/10 2016-12-23 15:53:36,519 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/dd35d18c8e14?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-12-23 15:53:37,278 unpacking tar archive at: mozilla-central-dd35d18c8e14/testing/mozharness/ program finished with exit code 0 elapsedTime=1.032669 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-12-23 15:53:37.485224) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-23 15:53:37.485572) ========= script_repo_revision: dd35d18c8e14751cca293db90ea2205e0a0871c7 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-23 15:53:37.486040) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-23 15:53:37.486846) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-23 15:53:37.512798) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 5 secs) (at 2016-12-23 15:53:37.513215) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.7L1P6PQ1Hl/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.nIP4upsNEJ/Listeners TMPDIR=/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/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 15:53:37 INFO - MultiFileLogger online at 20161223 15:53:37 in /builds/slave/test 15:53:37 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 15:53:37 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 15:53:37 INFO - {'append_to_log': False, 15:53:37 INFO - 'base_work_dir': '/builds/slave/test', 15:53:37 INFO - 'blob_upload_branch': 'Firefox', 15:53:37 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 15:53:37 INFO - 'branch': 'Firefox', 15:53:37 INFO - 'buildbot_json_path': 'buildprops.json', 15:53:37 INFO - 'config_files': ('talos/mac_config.py',), 15:53:37 INFO - 'default_actions': ('clobber', 15:53:37 INFO - 'read-buildbot-config', 15:53:37 INFO - 'download-and-extract', 15:53:37 INFO - 'populate-webroot', 15:53:37 INFO - 'create-virtualenv', 15:53:37 INFO - 'install', 15:53:37 INFO - 'run-tests'), 15:53:37 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 15:53:37 INFO - 'download_minidump_stackwalk': True, 15:53:37 INFO - 'download_symbols': 'ondemand', 15:53:37 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 15:53:37 INFO - 'tooltool.py': '/tools/tooltool.py', 15:53:37 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 15:53:37 INFO - '/tools/misc-python/virtualenv.py')}, 15:53:37 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 15:53:37 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 15:53:37 INFO - 'installer_path': 'installer.exe', 15:53:37 INFO - 'log_level': 'info', 15:53:37 INFO - 'log_name': 'talos', 15:53:37 INFO - 'log_to_console': True, 15:53:37 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 15:53:37 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 15:53:37 INFO - 'opt_config_files': (), 15:53:37 INFO - 'pip_index': False, 15:53:37 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 15:53:37 INFO - 'cmd': ('bash', 15:53:37 INFO - '-c', 15:53:37 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 15:53:37 INFO - 'enabled': True, 15:53:37 INFO - 'halt_on_failure': False, 15:53:37 INFO - 'name': 'check_screen_resolution'},), 15:53:37 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 15:53:37 INFO - 'cmd': ('bash', 15:53:37 INFO - '-c', 15:53:37 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 15:53:37 INFO - 'enabled': True, 15:53:37 INFO - 'halt_on_failure': False, 15:53:37 INFO - 'name': 'check_screen_resolution'},), 15:53:37 INFO - 'run_cmd_checks_enabled': True, 15:53:37 INFO - 'sps_profile': False, 15:53:37 INFO - 'sps_profile_interval': 0, 15:53:37 INFO - 'suite': 'dromaeojs', 15:53:37 INFO - 'system_bits': '32', 15:53:37 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 15:53:37 INFO - 'title': 't-yosemite-r7-0374', 15:53:37 INFO - 'tooltool_cache': '/builds/tooltool_cache', 15:53:37 INFO - 'use_talos_json': True, 15:53:37 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 15:53:37 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 15:53:37 INFO - 'work_dir': 'build'} 15:53:37 INFO - [mozharness: 2016-12-23 23:53:37.704767Z] Running clobber step. 15:53:37 INFO - Running pre-action listener: _resource_record_pre_action 15:53:37 INFO - Running main action method: clobber 15:53:37 INFO - rmtree: /builds/slave/test/build 15:53:37 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 15:53:40 INFO - Running post-action listener: _resource_record_post_action 15:53:40 INFO - [mozharness: 2016-12-23 23:53:40.075876Z] Finished clobber step (success) 15:53:40 INFO - [mozharness: 2016-12-23 23:53:40.076009Z] Running read-buildbot-config step. 15:53:40 INFO - Running pre-action listener: _resource_record_pre_action 15:53:40 INFO - Running main action method: read_buildbot_config 15:53:40 INFO - Using buildbot properties: 15:53:40 INFO - { 15:53:40 INFO - "project": "", 15:53:40 INFO - "product": "firefox", 15:53:40 INFO - "script_repo_revision": "production", 15:53:40 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 15:53:40 INFO - "repository": "", 15:53:40 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 15:53:40 INFO - "buildid": "20161223143436", 15:53:40 INFO - "pgo_build": "False", 15:53:40 INFO - "basedir": "/builds/slave/test", 15:53:40 INFO - "buildnumber": 15, 15:53:40 INFO - "slavename": "t-yosemite-r7-0374", 15:53:40 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 15:53:40 INFO - "platform": "yosemite_r7", 15:53:40 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 15:53:40 INFO - "branch": "mozilla-central", 15:53:40 INFO - "repo_path": "mozilla-central", 15:53:40 INFO - "revision": "dd35d18c8e14751cca293db90ea2205e0a0871c7", 15:53:40 INFO - "stage_platform": "macosx64", 15:53:40 INFO - "builduid": "dbe5aa66022644ce8dadcfe0b0638cf3", 15:53:40 INFO - "slavebuilddir": "test" 15:53:40 INFO - } 15:53:40 INFO - Found installer url https://queue.taskcluster.net/v1/task/LdxC6YjcTGGLp2maRH7HQA/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg. 15:53:40 INFO - Running post-action listener: _resource_record_post_action 15:53:40 INFO - [mozharness: 2016-12-23 23:53:40.079535Z] Finished read-buildbot-config step (success) 15:53:40 INFO - [mozharness: 2016-12-23 23:53:40.079654Z] Running download-and-extract step. 15:53:40 INFO - Running pre-action listener: _resource_record_pre_action 15:53:40 INFO - Running main action method: download_and_extract 15:53:40 INFO - mkdir: /builds/slave/test/build/tests 15:53:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:53:40 INFO - trying https://queue.taskcluster.net/v1/task/LdxC6YjcTGGLp2maRH7HQA/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json 15:53:40 INFO - Downloading https://queue.taskcluster.net/v1/task/LdxC6YjcTGGLp2maRH7HQA/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 15:53:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/LdxC6YjcTGGLp2maRH7HQA/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 15:53:41 INFO - Downloaded 1199 bytes. 15:53:41 INFO - Reading from file /builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json 15:53:41 INFO - Using the following test package requirements: 15:53:41 INFO - {u'common': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 15:53:41 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 15:53:41 INFO - u'firefox-53.0a1.en-US.mac.cppunittest.tests.zip'], 15:53:41 INFO - u'gtest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 15:53:41 INFO - u'firefox-53.0a1.en-US.mac.gtest.tests.zip'], 15:53:41 INFO - u'jittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 15:53:41 INFO - u'jsshell-mac.zip'], 15:53:41 INFO - u'mochitest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 15:53:41 INFO - u'firefox-53.0a1.en-US.mac.mochitest.tests.zip'], 15:53:41 INFO - u'mozbase': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 15:53:41 INFO - u'reftest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 15:53:41 INFO - u'firefox-53.0a1.en-US.mac.reftest.tests.zip'], 15:53:41 INFO - u'talos': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 15:53:41 INFO - u'firefox-53.0a1.en-US.mac.talos.tests.zip'], 15:53:41 INFO - u'web-platform': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 15:53:41 INFO - u'firefox-53.0a1.en-US.mac.web-platform.tests.zip'], 15:53:41 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 15:53:41 INFO - u'firefox-53.0a1.en-US.mac.xpcshell.tests.zip']} 15:53:41 INFO - Downloading packages: [u'firefox-53.0a1.en-US.mac.common.tests.zip'] for test suite category: common 15:53:41 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/LdxC6YjcTGGLp2maRH7HQA/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 15:53:41 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/LdxC6YjcTGGLp2maRH7HQA/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 15:53:41 INFO - Fetch https://queue.taskcluster.net/v1/task/LdxC6YjcTGGLp2maRH7HQA/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 15:53:42 INFO - Expected file size: 16289498 15:53:42 INFO - Obtained file size: 16289498 15:53:48 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 15:53:48 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/LdxC6YjcTGGLp2maRH7HQA/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 15:53:48 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/LdxC6YjcTGGLp2maRH7HQA/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 15:53:48 INFO - Fetch https://queue.taskcluster.net/v1/task/LdxC6YjcTGGLp2maRH7HQA/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 15:53:49 INFO - Expected file size: 16289498 15:53:49 INFO - Obtained file size: 16289498 15:53:55 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/LdxC6YjcTGGLp2maRH7HQA/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip 15:53:55 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/LdxC6YjcTGGLp2maRH7HQA/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip'}, attempt #1 15:53:55 INFO - Fetch https://queue.taskcluster.net/v1/task/LdxC6YjcTGGLp2maRH7HQA/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip into memory 15:53:56 INFO - Expected file size: 14409776 15:53:56 INFO - Obtained file size: 14409776 15:53:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:53:57 INFO - trying https://queue.taskcluster.net/v1/task/LdxC6YjcTGGLp2maRH7HQA/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 15:53:57 INFO - Downloading https://queue.taskcluster.net/v1/task/LdxC6YjcTGGLp2maRH7HQA/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg 15:53:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/LdxC6YjcTGGLp2maRH7HQA/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 15:53:59 INFO - Downloaded 69690074 bytes. 15:53:59 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/LdxC6YjcTGGLp2maRH7HQA/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 15:53:59 INFO - mkdir: /builds/slave/test/properties 15:53:59 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 15:53:59 INFO - Writing to file /builds/slave/test/properties/build_url 15:53:59 INFO - Contents: 15:53:59 INFO - build_url:https://queue.taskcluster.net/v1/task/LdxC6YjcTGGLp2maRH7HQA/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 15:54:00 INFO - Running post-action listener: _resource_record_post_action 15:54:00 INFO - Running post-action listener: set_extra_try_arguments 15:54:00 INFO - [mozharness: 2016-12-23 23:54:00.190652Z] Finished download-and-extract step (success) 15:54:00 INFO - [mozharness: 2016-12-23 23:54:00.190771Z] Running populate-webroot step. 15:54:00 INFO - Running pre-action listener: _resource_record_pre_action 15:54:00 INFO - Running main action method: populate_webroot 15:54:00 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 15:54:00 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 15:54:00 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 15:54:00 INFO - '--tppagecycles', 15:54:00 INFO - '7'], 15:54:00 INFO - 'tests': ['tsvgm']}}, 15:54:00 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 15:54:00 INFO - 'tests': ['tresize', 'tcanvasmark']}, 15:54:00 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 15:54:00 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 15:54:00 INFO - 'tests': ['dromaeo_css', 'kraken']}, 15:54:00 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 15:54:00 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 15:54:00 INFO - 'talos_options': ['--disable-e10s'], 15:54:00 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:54:00 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 15:54:00 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:54:00 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 15:54:00 INFO - 'talos_options': ['--disable-e10s'], 15:54:00 INFO - 'tests': ['damp', 'tps']}, 15:54:00 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 15:54:00 INFO - 'tests': ['damp', 'tps']}, 15:54:00 INFO - 'g3': {'talos_options': ['--disable-e10s'], 15:54:00 INFO - 'tests': ['dromaeo_dom']}, 15:54:00 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 15:54:00 INFO - 'g4': {'talos_options': ['--disable-e10s'], 15:54:00 INFO - 'tests': ['basic_compositor_video']}, 15:54:00 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 15:54:00 INFO - 'other': {'talos_options': ['--disable-e10s'], 15:54:00 INFO - 'tests': ['a11yr', 15:54:00 INFO - 'ts_paint', 15:54:00 INFO - 'tpaint', 15:54:00 INFO - 'sessionrestore', 15:54:00 INFO - 'sessionrestore_no_auto_restore', 15:54:00 INFO - 'tabpaint']}, 15:54:00 INFO - 'other-e10s': {'tests': ['a11yr', 15:54:00 INFO - 'ts_paint', 15:54:00 INFO - 'tpaint', 15:54:00 INFO - 'sessionrestore', 15:54:00 INFO - 'sessionrestore_no_auto_restore', 15:54:00 INFO - 'tabpaint']}, 15:54:00 INFO - 'other-e10s_l64': {'tests': ['a11yr', 15:54:00 INFO - 'ts_paint', 15:54:00 INFO - 'tpaint', 15:54:00 INFO - 'sessionrestore', 15:54:00 INFO - 'sessionrestore_no_auto_restore', 15:54:00 INFO - 'tabpaint']}, 15:54:00 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 15:54:00 INFO - 'ts_paint', 15:54:00 INFO - 'tpaint', 15:54:00 INFO - 'sessionrestore', 15:54:00 INFO - 'sessionrestore_no_auto_restore', 15:54:00 INFO - 'tabpaint']}, 15:54:00 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 15:54:00 INFO - 'tests': ['a11yr', 15:54:00 INFO - 'ts_paint', 15:54:00 INFO - 'tpaint', 15:54:00 INFO - 'sessionrestore', 15:54:00 INFO - 'sessionrestore_no_auto_restore', 15:54:00 INFO - 'tabpaint']}, 15:54:00 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 15:54:00 INFO - 'tests': ['a11yr', 15:54:00 INFO - 'ts_paint', 15:54:00 INFO - 'tpaint', 15:54:00 INFO - 'sessionrestore', 15:54:00 INFO - 'sessionrestore_no_auto_restore', 15:54:00 INFO - 'tabpaint']}, 15:54:00 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 15:54:00 INFO - 'tests': ['tsvgx', 15:54:00 INFO - 'tsvgr_opacity', 15:54:00 INFO - 'tart', 15:54:00 INFO - 'tscrollx', 15:54:00 INFO - 'cart', 15:54:00 INFO - 'tsvg_static']}, 15:54:00 INFO - 'svgr-e10s': {'tests': ['tsvgx', 15:54:00 INFO - 'tsvgr_opacity', 15:54:00 INFO - 'tart', 15:54:00 INFO - 'tscrollx', 15:54:00 INFO - 'cart', 15:54:00 INFO - 'tsvg_static']}, 15:54:00 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 15:54:00 INFO - 'talos_options': ['--disable-e10s'], 15:54:00 INFO - 'tests': ['tp5o']}, 15:54:00 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 15:54:00 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 15:54:00 INFO - 'talos_options': ['--disable-e10s', 15:54:00 INFO - '--xperf_path', 15:54:00 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:54:00 INFO - 'tests': ['tp5n']}, 15:54:00 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 15:54:00 INFO - 'talos_options': ['--xperf_path', 15:54:00 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:54:00 INFO - 'tests': ['tp5n']}}, 15:54:00 INFO - 'talos.zip': {'path': '', 15:54:00 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 15:54:00 INFO - Running post-action listener: _resource_record_post_action 15:54:00 INFO - [mozharness: 2016-12-23 23:54:00.208840Z] Finished populate-webroot step (success) 15:54:00 INFO - [mozharness: 2016-12-23 23:54:00.208957Z] Running create-virtualenv step. 15:54:00 INFO - Running pre-action listener: _resource_record_pre_action 15:54:00 INFO - Running main action method: create_virtualenv 15:54:00 INFO - Creating virtualenv /builds/slave/test/build/venv 15:54:00 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 15:54:00 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 15:54:00 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 15:54:00 INFO - Using real prefix '/tools/python27' 15:54:00 INFO - New python executable in /builds/slave/test/build/venv/bin/python 15:54:01 INFO - Installing distribute.............................................................................................................................................................................................done. 15:54:04 INFO - Installing pip.................done. 15:54:04 INFO - Return code: 0 15:54:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 15:54:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 15:54:04 INFO - Reading from file tmpfile_stdout 15:54:04 INFO - Output received: 15:54:04 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) 15:54:04 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 15:54:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:54:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:54:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:54:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:54:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:54:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:54:04 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 0x108444b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108447768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe9e3467a70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108222d30>, '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 0x108149db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108217be8>, '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.7L1P6PQ1Hl/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.nIP4upsNEJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/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 15:54:04 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 15:54:04 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 15:54:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7L1P6PQ1Hl/Render', 15:54:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:54:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:54:04 INFO - 'HOME': '/Users/cltbld', 15:54:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:54:04 INFO - 'LOGNAME': 'cltbld', 15:54:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:54:04 INFO - 'MOZ_NO_REMOTE': '1', 15:54:04 INFO - 'NO_EM_RESTART': '1', 15:54:04 INFO - 'PAGER': '/bin/cat', 15:54:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:54:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:54:04 INFO - 'PWD': '/builds/slave/test', 15:54:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:54:04 INFO - 'SHELL': '/bin/bash', 15:54:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.nIP4upsNEJ/Listeners', 15:54:04 INFO - 'TMPDIR': '/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/', 15:54:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:54:04 INFO - 'USER': 'cltbld', 15:54:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:54:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:54:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:54:04 INFO - 'XPC_FLAGS': '0x0', 15:54:04 INFO - 'XPC_SERVICE_NAME': '0', 15:54:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:54:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:54:04 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 15:54:04 INFO - Cleaning up... 15:54:04 INFO - Return code: 0 15:54:04 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 15:54:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:54:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:54:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:54:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:54:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:54:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:54:04 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 0x108444b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108447768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe9e3467a70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108222d30>, '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 0x108149db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108217be8>, '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.7L1P6PQ1Hl/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.nIP4upsNEJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/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 15:54:04 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 15:54:04 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 15:54:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7L1P6PQ1Hl/Render', 15:54:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:54:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:54:04 INFO - 'HOME': '/Users/cltbld', 15:54:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:54:04 INFO - 'LOGNAME': 'cltbld', 15:54:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:54:04 INFO - 'MOZ_NO_REMOTE': '1', 15:54:04 INFO - 'NO_EM_RESTART': '1', 15:54:04 INFO - 'PAGER': '/bin/cat', 15:54:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:54:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:54:04 INFO - 'PWD': '/builds/slave/test', 15:54:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:54:04 INFO - 'SHELL': '/bin/bash', 15:54:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.nIP4upsNEJ/Listeners', 15:54:04 INFO - 'TMPDIR': '/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/', 15:54:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:54:04 INFO - 'USER': 'cltbld', 15:54:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:54:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:54:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:54:04 INFO - 'XPC_FLAGS': '0x0', 15:54:04 INFO - 'XPC_SERVICE_NAME': '0', 15:54:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:54:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:54:04 INFO - Downloading/unpacking psutil>=3.1.1 15:54:04 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 15:54:04 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 15:54:04 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 15:54:04 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 15:54:04 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 15:54:04 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 15:54:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 15:54:08 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:54:08 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:54:08 INFO - Installing collected packages: psutil 15:54:08 INFO - Running setup.py install for psutil 15:54:08 INFO - building 'psutil._psutil_osx' extension 15:54:08 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 15:54:09 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 15:54:09 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 15:54:09 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 15:54:09 INFO - building 'psutil._psutil_posix' extension 15:54:09 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 15:54:09 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 15:54:09 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 15:54:09 INFO - ^ 15:54:09 INFO - 1 warning generated. 15:54:09 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 15:54:09 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:54:09 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:54:09 INFO - Successfully installed psutil 15:54:09 INFO - Cleaning up... 15:54:09 INFO - Return code: 0 15:54:09 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 15:54:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:54:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:54:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:54:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:54:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:54:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:54:09 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x108444b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108447768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe9e3467a70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108222d30>, '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 0x108149db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108217be8>, '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.7L1P6PQ1Hl/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.nIP4upsNEJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/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 15:54:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 15:54:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 15:54:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7L1P6PQ1Hl/Render', 15:54:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:54:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:54:09 INFO - 'HOME': '/Users/cltbld', 15:54:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:54:09 INFO - 'LOGNAME': 'cltbld', 15:54:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:54:09 INFO - 'MOZ_NO_REMOTE': '1', 15:54:09 INFO - 'NO_EM_RESTART': '1', 15:54:09 INFO - 'PAGER': '/bin/cat', 15:54:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:54:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:54:09 INFO - 'PWD': '/builds/slave/test', 15:54:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:54:09 INFO - 'SHELL': '/bin/bash', 15:54:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.nIP4upsNEJ/Listeners', 15:54:09 INFO - 'TMPDIR': '/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/', 15:54:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:54:09 INFO - 'USER': 'cltbld', 15:54:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:54:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:54:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:54:09 INFO - 'XPC_FLAGS': '0x0', 15:54:09 INFO - 'XPC_SERVICE_NAME': '0', 15:54:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:54:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:54:10 INFO - Downloading/unpacking mozsystemmonitor==0.3 15:54:10 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 15:54:10 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 15:54:10 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 15:54:10 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 15:54:10 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 15:54:10 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 15:54:12 INFO - Downloading mozsystemmonitor-0.3.tar.gz 15:54:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 15:54:12 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 15:54:12 INFO - Installing collected packages: mozsystemmonitor 15:54:12 INFO - Running setup.py install for mozsystemmonitor 15:54:12 INFO - Successfully installed mozsystemmonitor 15:54:12 INFO - Cleaning up... 15:54:13 INFO - Return code: 0 15:54:13 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 15:54:13 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')]} 15:54:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:54:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:54:13 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')]} 15:54:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:54:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:54:13 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 0x108444b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108447768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe9e3467a70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108222d30>, '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 0x108149db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108217be8>, '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.7L1P6PQ1Hl/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.nIP4upsNEJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/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 15:54:13 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 15:54:13 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 15:54:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7L1P6PQ1Hl/Render', 15:54:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:54:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:54:13 INFO - 'HOME': '/Users/cltbld', 15:54:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:54:13 INFO - 'LOGNAME': 'cltbld', 15:54:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:54:13 INFO - 'MOZ_NO_REMOTE': '1', 15:54:13 INFO - 'NO_EM_RESTART': '1', 15:54:13 INFO - 'PAGER': '/bin/cat', 15:54:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:54:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:54:13 INFO - 'PWD': '/builds/slave/test', 15:54:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:54:13 INFO - 'SHELL': '/bin/bash', 15:54:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.nIP4upsNEJ/Listeners', 15:54:13 INFO - 'TMPDIR': '/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/', 15:54:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:54:13 INFO - 'USER': 'cltbld', 15:54:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:54:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:54:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:54:13 INFO - 'XPC_FLAGS': '0x0', 15:54:13 INFO - 'XPC_SERVICE_NAME': '0', 15:54:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:54:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:54:13 INFO - Downloading/unpacking jsonschema==2.5.1 15:54:13 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 15:54:13 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 15:54:13 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 15:54:13 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 15:54:13 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 15:54:13 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 15:54:15 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 15:54:15 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 15:54:15 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 15:54:15 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 15:54:15 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 15:54:15 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 15:54:15 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 15:54:15 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 15:54:16 INFO - Downloading functools32-3.2.3-2.tar.gz 15:54:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 15:54:16 INFO - warning: no files found matching '*.txt' 15:54:16 INFO - no previously-included directories found matching 'build' 15:54:16 INFO - no previously-included directories found matching 'dist' 15:54:16 INFO - no previously-included directories found matching '.git*' 15:54:16 INFO - Installing collected packages: jsonschema, functools32 15:54:16 INFO - Running setup.py install for functools32 15:54:16 INFO - warning: no files found matching '*.txt' 15:54:16 INFO - no previously-included directories found matching 'build' 15:54:16 INFO - no previously-included directories found matching 'dist' 15:54:16 INFO - no previously-included directories found matching '.git*' 15:54:16 INFO - Successfully installed jsonschema functools32 15:54:16 INFO - Cleaning up... 15:54:16 INFO - Return code: 0 15:54:16 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 15:54:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:54:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:54:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:54:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:54:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:54:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:54:16 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 0x108444b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108447768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe9e3467a70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108222d30>, '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 0x108149db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108217be8>, '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.7L1P6PQ1Hl/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.nIP4upsNEJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/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 15:54:16 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 15:54:16 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 15:54:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7L1P6PQ1Hl/Render', 15:54:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:54:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:54:16 INFO - 'HOME': '/Users/cltbld', 15:54:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:54:16 INFO - 'LOGNAME': 'cltbld', 15:54:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:54:16 INFO - 'MOZ_NO_REMOTE': '1', 15:54:16 INFO - 'NO_EM_RESTART': '1', 15:54:16 INFO - 'PAGER': '/bin/cat', 15:54:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:54:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:54:16 INFO - 'PWD': '/builds/slave/test', 15:54:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:54:16 INFO - 'SHELL': '/bin/bash', 15:54:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.nIP4upsNEJ/Listeners', 15:54:16 INFO - 'TMPDIR': '/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/', 15:54:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:54:16 INFO - 'USER': 'cltbld', 15:54:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:54:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:54:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:54:16 INFO - 'XPC_FLAGS': '0x0', 15:54:16 INFO - 'XPC_SERVICE_NAME': '0', 15:54:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:54:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:54:16 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 15:54:16 INFO - Cleaning up... 15:54:16 INFO - Return code: 0 15:54:16 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 15:54:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:54:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:54:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:54:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:54:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:54:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:54:16 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 0x108444b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108447768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe9e3467a70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108222d30>, '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 0x108149db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108217be8>, '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.7L1P6PQ1Hl/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.nIP4upsNEJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/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 15:54:16 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 15:54:16 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 15:54:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7L1P6PQ1Hl/Render', 15:54:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:54:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:54:16 INFO - 'HOME': '/Users/cltbld', 15:54:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:54:16 INFO - 'LOGNAME': 'cltbld', 15:54:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:54:16 INFO - 'MOZ_NO_REMOTE': '1', 15:54:16 INFO - 'NO_EM_RESTART': '1', 15:54:16 INFO - 'PAGER': '/bin/cat', 15:54:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:54:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:54:16 INFO - 'PWD': '/builds/slave/test', 15:54:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:54:16 INFO - 'SHELL': '/bin/bash', 15:54:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.nIP4upsNEJ/Listeners', 15:54:16 INFO - 'TMPDIR': '/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/', 15:54:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:54:16 INFO - 'USER': 'cltbld', 15:54:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:54:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:54:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:54:16 INFO - 'XPC_FLAGS': '0x0', 15:54:16 INFO - 'XPC_SERVICE_NAME': '0', 15:54:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:54:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:54:17 INFO - Downloading/unpacking blobuploader==1.2.4 15:54:17 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 15:54:17 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 15:54:17 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 15:54:17 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 15:54:17 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 15:54:17 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 15:54:19 INFO - Downloading blobuploader-1.2.4.tar.gz 15:54:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 15:54:19 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 15:54:19 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 15:54:19 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 15:54:19 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 15:54:19 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 15:54:19 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 15:54:19 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 15:54:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 15:54:20 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 15:54:20 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 15:54:20 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 15:54:20 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 15:54:20 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 15:54:20 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 15:54:20 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 15:54:21 INFO - Downloading docopt-0.6.1.tar.gz 15:54:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 15:54:21 INFO - Installing collected packages: blobuploader, requests, docopt 15:54:21 INFO - Running setup.py install for blobuploader 15:54:21 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 15:54:21 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 15:54:21 INFO - Running setup.py install for requests 15:54:21 INFO - Running setup.py install for docopt 15:54:21 INFO - Successfully installed blobuploader requests docopt 15:54:21 INFO - Cleaning up... 15:54:22 INFO - Return code: 0 15:54:22 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:54:22 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')]} 15:54:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:54:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:54:22 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')]} 15:54:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:54:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:54:22 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 0x108444b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108447768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe9e3467a70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108222d30>, '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 0x108149db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108217be8>, '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.7L1P6PQ1Hl/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.nIP4upsNEJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/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 15:54:22 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 15:54:22 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 15:54:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7L1P6PQ1Hl/Render', 15:54:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:54:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:54:22 INFO - 'HOME': '/Users/cltbld', 15:54:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:54:22 INFO - 'LOGNAME': 'cltbld', 15:54:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:54:22 INFO - 'MOZ_NO_REMOTE': '1', 15:54:22 INFO - 'NO_EM_RESTART': '1', 15:54:22 INFO - 'PAGER': '/bin/cat', 15:54:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:54:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:54:22 INFO - 'PWD': '/builds/slave/test', 15:54:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:54:22 INFO - 'SHELL': '/bin/bash', 15:54:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.nIP4upsNEJ/Listeners', 15:54:22 INFO - 'TMPDIR': '/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/', 15:54:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:54:22 INFO - 'USER': 'cltbld', 15:54:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:54:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:54:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:54:22 INFO - 'XPC_FLAGS': '0x0', 15:54:22 INFO - 'XPC_SERVICE_NAME': '0', 15:54:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:54:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:54:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:54:22 INFO - Running setup.py (path:/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/pip-bnXYbZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:54:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:54:22 INFO - Running setup.py (path:/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/pip-Hry3D_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:54:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:54:22 INFO - Running setup.py (path:/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/pip-rceq27-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:54:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:54:22 INFO - Running setup.py (path:/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/pip-EQPrc9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:54:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:54:22 INFO - Running setup.py (path:/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/pip-YG04Iz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:54:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:54:22 INFO - Running setup.py (path:/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/pip-F3rquV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:54:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:54:22 INFO - Running setup.py (path:/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/pip-EW093S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:54:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:54:23 INFO - Running setup.py (path:/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/pip-wrwMUb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:54:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:54:23 INFO - Running setup.py (path:/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/pip-k1VgHk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:54:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:54:23 INFO - Running setup.py (path:/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/pip-QJ9TEY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:54:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:54:23 INFO - Running setup.py (path:/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/pip-iENw8u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:54:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:54:23 INFO - Running setup.py (path:/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/pip-gfB8DF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:54:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:54:23 INFO - Running setup.py (path:/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/pip-rH2NSE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:54:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:54:23 INFO - Running setup.py (path:/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/pip-tGtUNB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:54:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:54:23 INFO - Running setup.py (path:/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/pip-4uT_Ro-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:54:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:54:23 INFO - Running setup.py (path:/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/pip-dxp_Xi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:54:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:54:23 INFO - Running setup.py (path:/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/pip-Vq3Unw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:54:24 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 15:54:24 INFO - Running setup.py install for manifestparser 15:54:24 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 15:54:24 INFO - Running setup.py install for mozcrash 15:54:24 INFO - Running setup.py install for mozdebug 15:54:24 INFO - Running setup.py install for mozdevice 15:54:24 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 15:54:24 INFO - Installing dm script to /builds/slave/test/build/venv/bin 15:54:24 INFO - Running setup.py install for mozfile 15:54:24 INFO - Running setup.py install for mozhttpd 15:54:24 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 15:54:24 INFO - Running setup.py install for mozinfo 15:54:25 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 15:54:25 INFO - Running setup.py install for mozInstall 15:54:25 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 15:54:25 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 15:54:25 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 15:54:25 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 15:54:25 INFO - Running setup.py install for mozleak 15:54:25 INFO - Running setup.py install for mozlog 15:54:25 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 15:54:25 INFO - Running setup.py install for moznetwork 15:54:25 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 15:54:25 INFO - Running setup.py install for mozprocess 15:54:25 INFO - Running setup.py install for mozprofile 15:54:26 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 15:54:26 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 15:54:26 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 15:54:26 INFO - Running setup.py install for mozrunner 15:54:26 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 15:54:26 INFO - Running setup.py install for mozscreenshot 15:54:26 INFO - Running setup.py install for moztest 15:54:26 INFO - Running setup.py install for mozversion 15:54:26 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 15:54:26 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 15:54:26 INFO - Cleaning up... 15:54:26 INFO - Return code: 0 15:54:26 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:54:26 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')]} 15:54:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:54:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:54:26 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')]} 15:54:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:54:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:54:26 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 0x108444b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108447768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe9e3467a70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108222d30>, '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 0x108149db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108217be8>, '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.7L1P6PQ1Hl/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.nIP4upsNEJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/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 15:54:26 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 15:54:26 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 15:54:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7L1P6PQ1Hl/Render', 15:54:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:54:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:54:26 INFO - 'HOME': '/Users/cltbld', 15:54:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:54:26 INFO - 'LOGNAME': 'cltbld', 15:54:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:54:26 INFO - 'MOZ_NO_REMOTE': '1', 15:54:26 INFO - 'NO_EM_RESTART': '1', 15:54:26 INFO - 'PAGER': '/bin/cat', 15:54:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:54:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:54:26 INFO - 'PWD': '/builds/slave/test', 15:54:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:54:26 INFO - 'SHELL': '/bin/bash', 15:54:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.nIP4upsNEJ/Listeners', 15:54:26 INFO - 'TMPDIR': '/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/', 15:54:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:54:26 INFO - 'USER': 'cltbld', 15:54:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:54:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:54:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:54:26 INFO - 'XPC_FLAGS': '0x0', 15:54:26 INFO - 'XPC_SERVICE_NAME': '0', 15:54:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:54:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:54:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:54:26 INFO - Running setup.py (path:/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/pip-ReYh0P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:54:27 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)) 15:54:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:54:27 INFO - Running setup.py (path:/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/pip-9a_tqe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:54:27 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)) 15:54:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:54:27 INFO - Running setup.py (path:/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/pip-s5Uhfi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:54:27 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)) 15:54:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:54:27 INFO - Running setup.py (path:/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/pip-PoniLd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:54:27 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)) 15:54:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:54:27 INFO - Running setup.py (path:/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/pip-yTpCEA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:54:27 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)) 15:54:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:54:27 INFO - Running setup.py (path:/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/pip-NnaBYc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:54:27 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)) 15:54:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:54:27 INFO - Running setup.py (path:/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/pip-bU3FE1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:54:27 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)) 15:54:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:54:27 INFO - Running setup.py (path:/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/pip-8x18qg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:54:27 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)) 15:54:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:54:27 INFO - Running setup.py (path:/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/pip-bGDRpN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:54:27 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)) 15:54:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:54:27 INFO - Running setup.py (path:/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/pip-NI0x3Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:54:27 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)) 15:54:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:54:27 INFO - Running setup.py (path:/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/pip-ppaG_3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:54:28 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)) 15:54:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:54:28 INFO - Running setup.py (path:/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/pip-RCWvft-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:54:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 15:54:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:54:28 INFO - Running setup.py (path:/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/pip-ByFi4C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:54:28 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)) 15:54:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:54:28 INFO - Running setup.py (path:/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/pip-Xod3Ix-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:54:28 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)) 15:54:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:54:28 INFO - Running setup.py (path:/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/pip-gLiV0o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:54:28 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)) 15:54:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:54:28 INFO - Running setup.py (path:/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/pip-gqDEBc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:54:28 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 15:54:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:54:28 INFO - Running setup.py (path:/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/pip-hvC5YC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:54:28 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)) 15:54:28 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)) 15:54:28 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)) 15:54:28 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)) 15:54:28 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)) 15:54:28 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)) 15:54:28 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 15:54:28 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 15:54:28 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 15:54:28 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 15:54:28 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 15:54:28 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 15:54:28 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 15:54:31 INFO - Downloading blessings-1.6.tar.gz 15:54:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 15:54:31 INFO - Installing collected packages: blessings 15:54:31 INFO - Running setup.py install for blessings 15:54:31 INFO - Successfully installed blessings 15:54:31 INFO - Cleaning up... 15:54:31 INFO - Return code: 0 15:54:31 INFO - Done creating virtualenv /builds/slave/test/build/venv. 15:54:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:54:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:54:31 INFO - Reading from file tmpfile_stdout 15:54:31 INFO - Current package versions: 15:54:31 INFO - blessings == 1.6 15:54:31 INFO - blobuploader == 1.2.4 15:54:31 INFO - docopt == 0.6.1 15:54:31 INFO - functools32 == 3.2.3-2 15:54:31 INFO - jsonschema == 2.5.1 15:54:31 INFO - manifestparser == 1.1 15:54:31 INFO - mozInstall == 1.12 15:54:31 INFO - mozcrash == 1.0 15:54:31 INFO - mozdebug == 0.1 15:54:31 INFO - mozdevice == 0.49 15:54:31 INFO - mozfile == 1.2 15:54:31 INFO - mozhttpd == 0.7 15:54:31 INFO - mozinfo == 0.9 15:54:31 INFO - mozleak == 0.1 15:54:31 INFO - mozlog == 3.4 15:54:31 INFO - moznetwork == 0.27 15:54:31 INFO - mozprocess == 0.23 15:54:31 INFO - mozprofile == 0.28 15:54:31 INFO - mozrunner == 6.13 15:54:31 INFO - mozscreenshot == 0.1 15:54:31 INFO - mozsystemmonitor == 0.3 15:54:31 INFO - moztest == 0.7 15:54:31 INFO - mozversion == 1.4 15:54:31 INFO - psutil == 3.1.1 15:54:31 INFO - requests == 1.2.3 15:54:31 INFO - wsgiref == 0.1.2 15:54:31 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:54:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:54:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:54:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:54:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:54:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:54:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:54:31 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 0x108444b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108447768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe9e3467a70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108222d30>, '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 0x108149db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108217be8>, '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.7L1P6PQ1Hl/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.nIP4upsNEJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/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 15:54:31 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 15:54:31 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 15:54:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7L1P6PQ1Hl/Render', 15:54:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:54:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:54:31 INFO - 'HOME': '/Users/cltbld', 15:54:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:54:32 INFO - 'LOGNAME': 'cltbld', 15:54:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:54:32 INFO - 'MOZ_NO_REMOTE': '1', 15:54:32 INFO - 'NO_EM_RESTART': '1', 15:54:32 INFO - 'PAGER': '/bin/cat', 15:54:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:54:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:54:32 INFO - 'PWD': '/builds/slave/test', 15:54:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:54:32 INFO - 'SHELL': '/bin/bash', 15:54:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.nIP4upsNEJ/Listeners', 15:54:32 INFO - 'TMPDIR': '/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/', 15:54:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:54:32 INFO - 'USER': 'cltbld', 15:54:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:54:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:54:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:54:32 INFO - 'XPC_FLAGS': '0x0', 15:54:32 INFO - 'XPC_SERVICE_NAME': '0', 15:54:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:54:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:54:32 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)) 15:54:32 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)) 15:54:32 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)) 15:54:32 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)) 15:54:32 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)) 15:54:32 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)) 15:54:32 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)) 15:54:32 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)) 15:54:32 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)) 15:54:32 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)) 15:54:32 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)) 15:54:32 INFO - Cleaning up... 15:54:32 INFO - Return code: 0 15:54:32 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 15:54: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')]} 15:54:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:54:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:54: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')]} 15:54:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:54:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:54: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', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x108444b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108447768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe9e3467a70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108222d30>, '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 0x108149db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108217be8>, '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.7L1P6PQ1Hl/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.nIP4upsNEJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/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 15:54: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', 'jsonschema'] in /builds/slave/test/build 15:54: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 jsonschema 15:54:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7L1P6PQ1Hl/Render', 15:54:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:54:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:54:32 INFO - 'HOME': '/Users/cltbld', 15:54:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:54:32 INFO - 'LOGNAME': 'cltbld', 15:54:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:54:32 INFO - 'MOZ_NO_REMOTE': '1', 15:54:32 INFO - 'NO_EM_RESTART': '1', 15:54:32 INFO - 'PAGER': '/bin/cat', 15:54:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:54:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:54:32 INFO - 'PWD': '/builds/slave/test', 15:54:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:54:32 INFO - 'SHELL': '/bin/bash', 15:54:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.nIP4upsNEJ/Listeners', 15:54:32 INFO - 'TMPDIR': '/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/', 15:54:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:54:32 INFO - 'USER': 'cltbld', 15:54:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:54:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:54:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:54:32 INFO - 'XPC_FLAGS': '0x0', 15:54:32 INFO - 'XPC_SERVICE_NAME': '0', 15:54:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:54:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:54:32 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 15:54:32 INFO - Cleaning up... 15:54:32 INFO - Return code: 0 15:54:32 INFO - Running post-action listener: _resource_record_post_action 15:54:32 INFO - Running post-action listener: _start_resource_monitoring 15:54:32 INFO - Starting resource monitoring. 15:54:32 INFO - [mozharness: 2016-12-23 23:54:32.558128Z] Finished create-virtualenv step (success) 15:54:32 INFO - [mozharness: 2016-12-23 23:54:32.558912Z] Running install step. 15:54:32 INFO - Running pre-action listener: _resource_record_pre_action 15:54:32 INFO - Running main action method: install 15:54:32 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:54:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:54:32 INFO - Reading from file tmpfile_stdout 15:54:32 INFO - Detecting whether we're running mozinstall >=1.0... 15:54:32 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 15:54:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 15:54:32 INFO - Reading from file tmpfile_stdout 15:54:32 INFO - Output received: 15:54:32 INFO - Usage: mozinstall [options] installer 15:54:32 INFO - Options: 15:54:32 INFO - -h, --help show this help message and exit 15:54:32 INFO - -d DEST, --destination=DEST 15:54:32 INFO - Directory to install application into. [default: 15:54:32 INFO - "/builds/slave/test"] 15:54:32 INFO - --app=APP Application being installed. [default: firefox] 15:54:32 INFO - mkdir: /builds/slave/test/build/application 15:54:32 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'] 15:54:32 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 15:54:54 INFO - Reading from file tmpfile_stdout 15:54:54 INFO - Output received: 15:54:54 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 15:54:54 INFO - Running post-action listener: _resource_record_post_action 15:54:54 INFO - [mozharness: 2016-12-23 23:54:54.837060Z] Finished install step (success) 15:54:54 INFO - [mozharness: 2016-12-23 23:54:54.837194Z] Running run-tests step. 15:54:54 INFO - Running pre-action listener: _resource_record_pre_action 15:54:54 INFO - Running main action method: run_tests 15:54:54 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 15:54:54 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 15:54:54 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 15:54:54 INFO - 2016-12-23 15:54:54.851 screenresolution[1630:8548] starting screenresolution argv=screenresolution get 15:54:54 INFO - 2016-12-23 15:54:54.854 screenresolution[1630:8548] Display 0: 1600x1200x32@60 15:54:54 INFO - 2016-12-23 15:54:54.860 screenresolution[1631:8550] starting screenresolution argv=screenresolution list 15:54:54 INFO - Available Modes on Display 0 15:54:54 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 15:54:54 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 15:54:54 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 15:54:54 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 15:54:55 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 15:54:55 INFO - Intel Iris: 15:54:55 INFO - Chipset Model: Intel Iris 15:54:55 INFO - Type: GPU 15:54:55 INFO - Bus: Built-In 15:54:55 INFO - VRAM (Dynamic, Max): 1536 MB 15:54:55 INFO - Vendor: Intel (0x8086) 15:54:55 INFO - Device ID: 0x0a2e 15:54:55 INFO - Revision ID: 0x0009 15:54:55 INFO - Displays: 15:54:55 INFO - Display: 15:54:55 INFO - Resolution: 1600 x 1200 @ 60 Hz 15:54:55 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 15:54:55 INFO - Display Serial Number: 1600x1200 60 15:54:55 INFO - Main Display: Yes 15:54:55 INFO - Mirror: Off 15:54:55 INFO - Online: Yes 15:54:55 INFO - Rotation: Supported 15:54:55 INFO - Return code: 0 15:54:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 15:54:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 15:54:55 INFO - Python 2.7.3 15:54:55 INFO - Return code: 0 15:54:55 INFO - grabbing minidump binary from tooltool 15:54: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')]} 15:54:55 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 0x108222d30>, '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 0x108149db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108217be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 15:54:55 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 15:54:55 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 15:54:55 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 15:54:55 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 15:54:55 INFO - Return code: 0 15:54:55 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 15:54:55 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 15:54:55 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 15:54:55 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 15:54:55 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 15:54:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/LdxC6YjcTGGLp2maRH7HQA/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0374', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 15:54:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/LdxC6YjcTGGLp2maRH7HQA/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0374 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 15:54:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7L1P6PQ1Hl/Render', 15:54:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:54:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:54:55 INFO - 'HOME': '/Users/cltbld', 15:54:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:54:55 INFO - 'LOGNAME': 'cltbld', 15:54:55 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 15:54:55 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 15:54:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:54:55 INFO - 'MOZ_NO_REMOTE': '1', 15:54:55 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 15:54:55 INFO - 'NO_EM_RESTART': '1', 15:54:55 INFO - 'PAGER': '/bin/cat', 15:54:55 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:54:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:54:55 INFO - 'PWD': '/builds/slave/test', 15:54:55 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 15:54:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:54:55 INFO - 'SHELL': '/bin/bash', 15:54:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.nIP4upsNEJ/Listeners', 15:54:55 INFO - 'TMPDIR': '/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/', 15:54:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:54:55 INFO - 'USER': 'cltbld', 15:54:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:54:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:54:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:54:55 INFO - 'XPC_FLAGS': '0x0', 15:54:55 INFO - 'XPC_SERVICE_NAME': '0', 15:54:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:54:55 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/LdxC6YjcTGGLp2maRH7HQA/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0374', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 15:54:55 INFO - mozversion application_buildid: 20161223143436 15:54:55 INFO - mozversion application_changeset: dd35d18c8e14751cca293db90ea2205e0a0871c7 15:54:55 INFO - mozversion application_display_name: Nightly 15:54:55 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 15:54:55 INFO - mozversion application_name: Firefox 15:54:55 INFO - mozversion application_remotingname: firefox 15:54:55 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 15:54:55 INFO - mozversion application_vendor: Mozilla 15:54:55 INFO - mozversion application_version: 53.0a1 15:54:55 INFO - mozversion platform_buildid: 20161223143436 15:54:55 INFO - mozversion platform_changeset: dd35d18c8e14751cca293db90ea2205e0a0871c7 15:54:55 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 15:54:55 INFO - mozversion platform_version: 53.0a1 15:54:55 INFO - using testdate: 1482537295 15:54:55 INFO - actual date: 1482537295 15:54:55 INFO - starting webserver on 'localhost:49240' 15:54:55 INFO - SUITE-START | Running 2 tests 15:54:55 INFO - TEST-START | dromaeo_css 15:54:55 INFO - operating with platform_type : mac_ 15:54:55 INFO - Initialising browser for dromaeo_css test... 15:54:55 INFO - TEST-INFO | started process 1641 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/tmpJbJbSp/profile http://localhost:49240/getInfo.html) 15:54:55 INFO - PROCESS | 1641 | 1482537295970 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:4979 < processFileChanges()@resource://gre/modules/addons/XPIProvider.jsm -> resource://gre/modules/addons/XPIProviderUtils.js:2097 < checkForChanges()@resource://gre/modules/addons/XPIProvider.jsm:3825 < startup()@resource://gre/modules/addons/XPIProvider.jsm:2829 < callProvider()@resource://gre/modules/AddonManager.jsm:264 < _startProvider()@resource://gre/modules/AddonManager.jsm:771 < startup()@resource://gre/modules/AddonManager.jsm:957 < startup()@resource://gre/modules/AddonManager.jsm:2923 < observe()@resource://gre/components/addonManager.js:65 15:54:57 INFO - PROCESS | 1641 | JavaScript error: chrome://global/content/browser-content.js, line 1772: ReferenceError: event is not defined 15:54:57 INFO - PROCESS | 1641 | JavaScript error: chrome://global/content/browser-content.js, line 1772: ReferenceError: event is not defined 15:54:57 INFO - PROCESS | 1641 | JavaScript error: chrome://global/content/browser-content.js, line 1772: ReferenceError: event is not defined 15:54:57 INFO - PROCESS | 1641 | __metrics Screen width/height:1600/1200 15:54:57 INFO - PROCESS | 1641 | colorDepth:24 15:54:57 INFO - PROCESS | 1641 | Browser inner width/height: 1024/689 15:54:57 INFO - PROCESS | 1641 | __metrics 15:54:57 INFO - PROCESS | 1641 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 15:54:57 INFO - PROCESS | 1641 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 15:54:58 INFO - TEST-INFO | 1641: exit 0 15:54:58 INFO - Browser initialized. 15:54:58 INFO - Running cycle 1/1 for dromaeo_css test... 15:54:58 INFO - TEST-INFO | started process 1644 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/tmpJbJbSp/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:55:10 INFO - PROCESS | 1644 | RSS: Main: 227213312 15:55:10 INFO - PROCESS | 1644 | 15:56:02 INFO - PROCESS | 1644 | 1482537362376 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 15:58:15 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49240/tests/dromaeo/cssquery-ext.html) 15:58:17 INFO - PROCESS | 1644 | RSS: Main: 434941952 15:58:17 INFO - PROCESS | 1644 | 15:59:59 INFO - PROCESS | 1644 | ************************* 15:59:59 INFO - PROCESS | 1644 | A coding exception was thrown and uncaught in a Task. 15:59:59 INFO - PROCESS | 1644 | 15:59:59 INFO - PROCESS | 1644 | Full message: ReferenceError: fetch is not defined 15:59:59 INFO - PROCESS | 1644 | Full stack: apiCall@resource://shield-recipe-client/lib/NormandyApi.jsm:37:5 15:59:59 INFO - PROCESS | 1644 | get@resource://shield-recipe-client/lib/NormandyApi.jsm:44:12 15:59:59 INFO - PROCESS | 1644 | this.NormandyApi.fetchRecipes<@resource://shield-recipe-client/lib/NormandyApi.jsm:52:34 15:59:59 INFO - PROCESS | 1644 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 15:59:59 INFO - PROCESS | 1644 | TaskImpl@resource://gre/modules/Task.jsm:277:3 15:59:59 INFO - PROCESS | 1644 | asyncFunction@resource://gre/modules/Task.jsm:252:14 15:59:59 INFO - PROCESS | 1644 | this.RecipeRunner.start<@resource://shield-recipe-client/lib/RecipeRunner.jsm:64:23 15:59:59 INFO - PROCESS | 1644 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 15:59:59 INFO - PROCESS | 1644 | TaskImpl@resource://gre/modules/Task.jsm:277:3 15:59:59 INFO - PROCESS | 1644 | asyncFunction@resource://gre/modules/Task.jsm:252:14 15:59:59 INFO - PROCESS | 1644 | setTimeout_timer@resource://gre/modules/Timer.jsm:30:5 15:59:59 INFO - PROCESS | 1644 | 15:59:59 INFO - PROCESS | 1644 | ************************* 16:01:20 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-ext.html (next: http://localhost:49240/tests/dromaeo/cssquery-jquery.html) 16:01:21 INFO - PROCESS | 1644 | RSS: Main: 368046080 16:01:21 INFO - PROCESS | 1644 | 16:04:23 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49240/tests/dromaeo/cssquery-mootools.html) 16:04:24 INFO - PROCESS | 1644 | RSS: Main: 335478784 16:04:24 INFO - PROCESS | 1644 | 16:07:26 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49240/tests/dromaeo/cssquery-prototype.html) 16:07:27 INFO - PROCESS | 1644 | RSS: Main: 348127232 16:07:27 INFO - PROCESS | 1644 | 16:10:31 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49240/tests/dromaeo/cssquery-yui.html) 16:10:31 INFO - PROCESS | 1644 | RSS: Main: 336195584 16:10:31 INFO - PROCESS | 1644 | 16:13:35 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-yui.html (next: http://localhost:49240/tests/dromaeo/cssquery-dojo.html) 16:13:35 INFO - PROCESS | 1644 | RSS: Main: 528318464 16:13:35 INFO - PROCESS | 1644 | 16:13:35 INFO - PROCESS | 1644 | __start_tp_report 16:13:35 INFO - PROCESS | 1644 | _x_x_mozilla_page_load 16:13:35 INFO - PROCESS | 1644 | _x_x_mozilla_page_load_details 16:13:35 INFO - PROCESS | 1644 | |i|pagename|runs| 16:13:35 INFO - PROCESS | 1644 | |0;dojo.html;2966.24360787999;3298.884539041131;3304.90085297441;3312.0726196664928;3372.7301815854735;5533.529649979757;5543.473370029846;5571.4428557144265;5633.52115070219;5645.774169033234;5600.4959553640265;5651.180578816069;5697.772089116441;5722.885542289152;5766.192733017381;3816.732828701992;5115.309433226504;5130.820421285256;5157.355330583677;5199.454057323983;4346.261135606955;4548.230678116674;5171.120909445403;5254.763535640886;5323.414424413311;5096.745162741885;5207.192885102815;5217.087009773293;5258.368995720538;5265.394479634852;4248.235317642823;5043.722595257259;5125.846224613268;5157.638965272433;5217.243499692523;5117.411497677777;5145.511176438233;5150.4334523202415;5168.147255702805;5202.0896343140175;5263.289455923439;5577.749001294951;5633.8591535211535;5645.491905728463;5645.745941432645;6070.787522436738;6115.266168059817;6117.796956032686;6120.204373431426;6168.383161683841;4966.627502937295;6149.385061493815;6258.092576576371;6293.402126797947;6303.180586523723;5742.7415766290615;5780.393058728857;5787.65274083548;5795.130730390475;5802.303723553158;6137.355577664371;6243.282022567463;6243.8126856194385;6251.468625166912;6282.842928926813;5969.522438204933;6031.849203769941;6044.063170208537;6061.090836374579;6063.060225664941;6189.752409903644;6197.566170368031;6207.937920620826;6208.255009398811;6219.813405597839;5122.590192784568;6130.264368275835;6139.386061393914;6161.44546990773;6223.657698826608;6234.937650623436;6236.968815155895;6242.65665388408;6244.406781355764;6254.906176407358;4918.385201849769;4922.704637721748;4927.77824997876;4930.605551555867;4958.380202474691;4310.956890431118;4341.08837144197;4344.847930322424;4354.042110735633;4406.911861762746;5722.170285308607;5736.913946290809;5744.281964754406;5748.798792042258;5760.971195143998;6281.780137695158;6287.497000240061;6299.653519056405;6319.652419116993;6323.683815809283;5559.221708960748;5640.520555752807;5645.2096706461125;5724.227229323988;5735.053716136791;5335.973320133375;5342.572594192456;5383.273258110184;5404.1353383459245;5419.512243898068;6286.622802631857;6291.465225455888;6292.4651404629285;6294.748210071637;6303.495720342454;6194.194754681863;6213.689315534115;6247.187865577446;6257.998720204745;6268.028455589391;6242.126102345766;6265.561410701207;6281.120643109877;6295.9370406297185;6298.874022519432;5622.85942851432;5784.045632470764;6109.083637454418;6112.41229444207;6129.756146212572;6118.418750218722;6127.172831667668;6183.505319574333;6199.163112979871;6204.751809927733;5993.790217342546;6003.759849605967;6012.6392416455155;6013.548983826143;6017.608855424383;5986.4013598639785;5995.850103747267;6000.279966404059;6015;6017.729202185999;5739.770409183753;5766.106253530709;5784.855378615568;5836.095405212199;5852.209951656472;5921.3190483094995;5954.821355359346;5957.195778569838;5964.985952388191;5978.850528736816;5732.885342293214;5783.913241301468;5786.681732504752;5790.39200883918;5815.912761308668 16:13:35 INFO - PROCESS | 1644 | |1;ext.html;435.16231254834435;444.7198265092991;477.6656340561607;500.4820011288317;515.5231410944878;37797.81101094494;39278.41082383763;39288.80355598225;39294.821155365345;41372.75881723546;39433;39720.80139599298;39776.8066957991;39862;40049.39925901114;8285.130061343563;8468.364872634545;9231.538423078837;9443;9468.81062378752;11065.446727663624;11288.492017859217;11366.31802091873;11540.596079137233;11555.364454954974;11203.383813890232;11233.438328083563;11290.774184516305;11306.095512359032;11317.151213659008;11096.61161859331;11149.386783726892;11220.887791122066;11250.493727782188;11251.718707032349;11084.00243978046;11113.611023614134;11182.720431989184;11196.104311655048;11208.383538905357;38345;38796.41805372922;38805.41791873124;38817.22365552673;38915.61084389148;3306.8677252909856;3371.7976921384798;3482.407990641597;3713.1830997180577;3718.4980027696456;27271.727282727315;27411.177664670093;27966.16101516958;28239;28996.420071598663;3140.1678555182893;3146.11908665574;3160.5101209312593;3220.066180807563;3270.721988630969;499647;512825;521957.7804221947;525244;645872.5412745907;52525;52671.73664131655;53141;53201.935961281335;53223.73388133034;48353.27470087951;54553.72723136359;54805.45194548083;54808;54826.72586637041;14336.928315358566;14573.979821412611;14575.927120364331;14600.34298456572;14690.779638305434;10247;10556.47217639115;11045.226834121695;11315.717107072225;11422.25755325901;16039.839601604068;16391.67216655684;16415.753763693556;16422;16463;7276.235995220532;7296.6716497757725;7300.860629929181;7341.853162936712;7387.778366648901;829.6349606173264;885.6634478898106;945.4516380499294;990.3117333453325;1039.163473403902;27893.58159627607;29148.125556233346;29414.26464338366;29476.410471790445;29965.850170749443;983.2625530851861;996.9052939970691;1030.2221822524095;1116.3525155409843;1143.7540928700369;14478.348474318682;15070;15197.6200594986;15250.609725747803;15518.292987771336;608892;614502;614967;615990;616331;55356.72321638366;55901.88196236052;55974.44025559692;55989.440105599235;56041.439585604436;1343.280600830938;1386.6394737368357;1397.1407584335764;1402.9719405612034;1403.1440821099325;25843;27649.585256221577;28546;29287.26781830386;29327.706722932497;46397.53602464067;46594.534054659016;46697.766511167225;46718.299225510964;46750.766246168554;556115.2194239002;560753;560760;561306;562252.6986285545;21666.89166554157;22006.559868802855;23780.88109559441;25182.874085629453;25607.23178304654;43599;44311.67064988056;44779.776101119285;44817.551824482645;45008.77495612501;36723.44914826226;36735.265294694495;36881.81559092188;37993.24013519659;38336.42495362516;30535.389292214477;36803;37785.62214377821;37852.81073594614;38669.419958701204;35443.82278088593;36127.27745445024;36397.45403818998;36556.81721591375;37011.81494092512;31909.840450797597;33073.669263307056;33086.503702444;33552.49671254884;33565.83217083899;22834;24262.15082472105;24376.87811561002;24881.377965551004;24994.625080623442 16:13:35 INFO - PROCESS | 1644 | |2;jquery.html;346.6793216274945;365.1583100952304;366.5766040223542;374.10961910652645;398.02682441430693;14073.296335183239;14826.110433374006;15985.200739963013;18017.738758286894;20551.177952881902;18767.967761773096;19860.900695496537;20505.897470512664;20625;20669.793302067013;5612.354579223391;5808.738606762706;5864.44287792659;5884.941150588482;5904.763809447617;4054.858079967202;4298.161858437606;4332.545082766295;5755.769769209248;5839.036558967765;4275.636570891478;5616.971915140439;6110.144579758838;6172.598781079238;6463.579867308634;5486.506214440699;6177.25872895251;6348.873022539523;6426.453751431134;6454.644994525276;5476.698781567012;5601.859953501154;5606.159076138571;5610.523105536035;5618.803341883015;20522.5895482091;24254;24565;24844.3788905277;25429.364265893317;2141.657334826436;2158.8380871434706;2222.5443784024237;2370.2178281167176;2788.261110805638;14258.64353391163;15387.999780014376;15541.378344866305;15685.372585096582;16017.599560010976;1809.240119149947;1812.0939530234882;1857.0436225343958;1868.4674867662786;1870.6071724937779;105789;106051.46974265156;106773.46613266962;108182.37726434109;108416.4579177107;10548.894511054792;10593.940605939499;10620.256582039183;10676.359418434764;10721.98141176587;13734.175949443066;13746.793798093036;13766.380512876945;13771.655708607364;13772.793408098887;7026.508144429943;8334.541600211925;8496.957515212385;8668.176523230282;8669.479831210148;8349.290310323691;8402.873956890651;8470.195331443627;8609.48343099416;8613.870791938127;5607.934492446504;6563.967180164069;6683.832904177434;6684.1978962523835;6689.13041304637;1648.8763342749342;1775.742517334979;1815.4009176971567;1818.3090425638172;1839.1356062650532;773.8877862709876;788.2826627768703;968.1625394034251;986.3527908393;1060.7560261139981;21039.263625773023;21042.684359734616;22226.333210003726;22455.66316505221;22508.922590900365;939.5490164720973;953.8998405167496;956.6747305916022;966.5457235099492;992.4494050290683;10993;11422.371769552754;11939.58211462612;11942.880571194351;11948.701282467833;506700;520053;520555;526299;530516;103023;103752;104297;106978.97657675705;107075;997.3766396002499;1019.1490105761771;1055.2138656700806;1078.5146683992234;1087.690008347617;19155.521111972314;19388;19538.21847126099;19581.216751329786;19626;47618.285725714835;48240.7587962072;48968;49380.753096234286;50738;66567.66716166388;72595.63702181455;77472;79658.2034179674;80452.59773701095;8020.679172833022;8057.717979870677;8250.422470427013;8365.372597055124;8487.27858132041;12409.937950310552;12762.17045892014;13022.679216799655;13314.86685133175;13356.666083347605;10908.509117089592;11303.773924521627;11410.462756545498;11461.770764584495;11508.712282193012;9584;11002.834957475805;11064.280821746883;11205.495752690986;11535.480903359537;11196.776064478501;11349.829752553553;11562.595309164139;11578.710532236424;11781.352025638673;8038.276555110068;8111.553864537271;8217.671293148447;8248.876266856121;8337.50241476492;8217.378321887036;10299.691009269734;10774.838377424188;11092.611758588411;11139.72150696239 16:13:35 INFO - PROCESS | 1644 | |3;mootools.html;1397.8182836231288;1490.9403623855046;1675.656490419464;1851.9166637501312;1874.746909167262;7440.776776696708;7871.2128787121255;8306.293965012967;8319.667213311475;8333.541655208961;7494.625268736555;7666.425018123635;7825.804354891116;8055.1944805519415;8072.31385332246;2246.528229071897;2306.5963456395066;2306.9733968384076;2312.2022902098834;2312.9537409251807;2028.5131568423546;2311.803496702774;2330.3009097270838;2350.2831636350907;2355.3169580821627;1712.3921008042153;1899.99300370803;1975.8024197580198;1976.505873531617;1977.3079422202259;2028.1785876719878;2221.2669818959703;2252.5945329840624;2304.596695578267;2305.204704376987;2296.494771150344;2305.204704376987;2316.224064938248;2327.918522851692;2329.7087864017;8133.983252093488;8315.750527484186;8316.126806685332;8317.168283171635;8320.500769953762;4970.950290497085;5151.6908985461;5153.845384638467;5159.7678104485385;5163.7676304566385;2982.522796352595;3001.4897467430587;3257.576515053028;3421.4354631485776;3499.177693242086;986.2258127370052;1017.3895662602454;1047.1256500821746;1055.2560444886412;1055.3351388625117;10554.677970846402;10648.680539583826;10662.840057399146;10672.252942294042;10701.625443109531;4281.207976524353;4504.977475112669;4571.9085618287445;4574.74838883858;4587.908241835212;8944.910550894409;8947.373683842068;8949.776255593662;8955.641774328953;8964.596593153325;5083.313752643419;5096.388433388017;5203.687778733288;5206.687598744012;5207.609429292743;4518.344839998182;4713.599344055725;4744.383230180055;4745.477997420281;4754.857354279377;3763.1532905096683;3788.8484460621817;3825.5026846509777;3865.1496670732395;3889.922201555953;1173.4719376280707;1174.988250117488;1175.5944199251244;1190.2679851891025;1192.5349113845607;633.5280216238839;758.5297115788246;773.9651715672808;874.6588830356039;879.520661239626;4436.511983681857;5243.2659427680155;5541.972290138524;5545.364117585322;5700.144978253213;873.724776695339;897.7441429192648;910.8041771019263;915.3272344826547;964.1033838530234;3625.6374362563533;3673.9265214696093;3740.60723624021;3790.8104594770152;3793.3740932745786;9107.499087550115;9855.211583073336;9975.750606234757;9985.900140998498;9990.400575965465;9740.561674724655;9916.107550320507;9944.602215911282;9955.502224888727;9959.751006224757;7545.50954187976;7797.064352277763;7797.415193860483;7811.140774514799;7814.57019863913;4801.567858892717;5344.946550534445;5478.726063696879;5501.477359650827;5543.8891222176135;7649.426293027934;7733.8839917400155;9074;9094.317926155432;9202.49386283734;9297;9359.625614975597;9371.390859594105;9394.577244023898;9494.71515854498;6505.2518960318275;6735.73057077731;6744.0895479109695;6751.628660423527;6774.254831968381;7588;7653.6938522460705;7697.576633284999;7745.264199901;7784.2215778421705;7044.929550704427;7090.610016448938;7124.465665075244;7134.32223938725;7165.498415110892;6312;6801.149856267966;6908.861822763617;6942.305769423017;6960.025596416404;6254.874902502015;6379.202599675041;6385.96807015962;6391.424771770563;6394.2646595640235;4605.815767369268;4716.740579268173;4752.952470475251;4791.448983366958;4827.203511420576;4108.815103320298;4785.808567657255;4817.542333478314;4868.805247790048;4869.9026019480125 16:13:35 INFO - PROCESS | 1644 | |4;prototype.html;333.58968468783394;341.97435192360564;344.81040410583483;367.358958617213;367.87308378609373;5254.57963362931;5326.7070311132875;5345.251664766931;5347.893042139155;5369.24830523727;4190.721829841897;4560.771961401933;4991.725455099977;4996.7751451184695;5136.43499215087;3979.482667253253;4091.5908409158997;4130.297849365615;4142.917141657165;4146.854860079898;3913.8434462621462;4110.527289361732;4144.647704945083;4180.372944058385;4215.114825886568;3156.942424287855;3772.9434058489146;3802.7338086333966;4115.362118871565;4163.562825903282;4077.6330130288416;4097.815598298083;4103.753774773523;4105.261053010442;4126.381042843583;3146.480830662938;3618.9276214475562;3676.3382591133586;3694.242680250542;3710.5547334319785;4234.894127646803;4925.38432695913;5184;5235.424103348629;5255.737213139328;3594.9101272468138;4141.544430112685;4650.837220697294;4659.627229821606;4708.9058218835435;5476.041692703761;5501.249375312344;5518.475744804237;5519.144532597454;5590.776368945238;3887.8833634990997;4050.9467538440217;4060.6954478414236;4069.0061869683936;4099.139180772041;4897.8898399516565;5760.452756988079;5784.334801497839;5796.797112101099;5813.912791308049;4053.9391909121387;4077.4903137107863;4081.510218773766;4094.979525102415;4106.568810274938;3477.565304336958;4004.8598299059395;4132.710710250314;4140.503139623204;4148.8133034013545;3738.794366309879;4479.372887795711;4644.558766917137;4712.858614241578;4724.574788269072;5543.891221755584;5544.390117087198;5550.666959982414;5573.627955087195;5581.441855814467;1123.9100871930225;1202.8272434376588;1283.8844503994499;1335.2589312931416;1350.9324533773272;818.9231161023235;843.9450686641698;872.341382256405;910.4582773249906;958.5925981457852;4914.410270767531;4945.802167913315;4975.701457912537;4982.252662100714;5027.673201241981;4749.905001899942;4960.702357858468;5226.320578324793;5393.8112166074;5495.279836480968;4244.787760611957;4783.832565860248;4892.510748925079;5095.515925987025;5174.767135478913;2378.6550950112137;2564.4228799288303;2647.007372235412;2655.9601605975927;2821.6190814240226;5195.948040519546;5276.81531146408;5527.281453411111;5666.688332141772;5690.4025077367105;4856.8300109495995;5077.892674540025;5100.577225817707;5451.427600102012;5530.6128571000445;4760.857174284777;4932.8520144395725;5236;5545.833624991256;5591.412901645269;4734.621230301636;5092.719900405513;5219.303726288896;5453.972730136483;5479.315085614298;4563.566461186182;5035.874103147451;5383.5154836063375;5384.596155288291;5391.892162156813;4624.953750462452;5445.673259604437;5480.80817171413;5637.267155269788;5653.717314134195;3576.7853928764357;3580.6061333253842;3598.6221446747713;3606.5852426971232;3621.855125795044;4569.725816451024;5011.84964451067;5039.8488045357235;5205.739713014258;5770.019096753478;4665.790039448165;5512.767329231668;5526.972365138148;5573.6098473106495;5621;4782.871622128119;5074.873128171826;5350.279802791639;5422.376426710979;5487.588430867621;4040.212158629039;5105.4639262878345;5185.688858668492;5437.211604317273;5506.201600767786;4211.663066954698;4585.839495617637;4586.472555656009;5408;5436.3476382834315;4730.266808644666;5205.479452054764;5365.887785084564;5410.348929024828;5444.3466783984595 16:13:35 INFO - PROCESS | 1644 | |5;yui.html;613.3314686991179;729.0340299103689;735.3051366458699;745.0128579631983;816.841645011751;14635.926820365896;15056.247187640629;15383.538493845175;15443.613909652251;15514.379424822993;13929.373178206979;14104.435822567111;14110.223937683424;14151.787723184161;14158.079724817879;852.957352132394;876.4784952952983;886.8891388576428;887.973360799177;889.8175873945826;886.366248132585;889.799795046116;891.7859713668738;892.4288455388556;893.3746377535751;935.3452583191759;936.7517607833929;937.9437233765962;938.9108034736723;939.3565407695751;933.9392939458913;934.887813462382;934.9298802589765;935.4247138010143;935.5182081228173;695.2803848017328;695.5305169010898;698.2005603583918;698.2424069884182;698.3016983016983;14963.177025263607;14979.850201497955;15014.324355403924;15043.473478428312;15046.699066018728;13541.987922441003;13701.520446784314;14017.64955876101;14075.648108797259;14145.646358841008;11017.173711971554;11481.483333250024;11594;11598.768024639545;12134.939325303318;4267.850625228133;4514.255147900592;4586.082783443325;4607.14767767964;4705.529447055302;101797.49101254446;102499.97500024979;106561;107844;107874.46062769715;9853.753656158653;10183.898161018444;10705.521167622077;10729.248952573245;10786.137109031415;19431.902840485705;20541.281055163;20570.97145142737;21041.05315260817;21074;7235.529690570202;7320.1581818090535;7353.301436363537;7532.962335188289;7694.730684426018;4314.115606300701;4317.400189403342;4386.144701783185;4442.666799989949;4526.841560545365;3473.0449126490416;3545.7163426925786;3549.4853246279663;3581.7134629230122;3582.731295152874;629.361198383641;635.548760380126;642.5662677692633;645.1065274594627;646.683125268615;547.8904219156105;642.5502148496072;670.886328694365;680.8740383029156;683.2893790457869;7531.435142364345;8598.699045533502;8679.609417576225;9068.22920051803;9199.632014719336;640.2860810196597;661.3452681845035;663.8639078988892;670.6043434373743;704.126882665491;4277.443932288783;4345.587169218919;4577.771111444415;4589.35748995141;4715.929261061086;84821;93151.53424232836;97759;100026;103995.4800225994;72231.6388418065;88333.55833220793;90807;92582;93535;60014.699926500085;80451.99999999884;82360;83713;84070;7388.519746216479;8423.904892363953;8588.527630980356;9040.186383225542;9260.490673012779;35800.104997375274;46990.76504617455;51615.48384516107;53156.46843531515;54483.72758136184;67431.66284168548;67770;68311.65844170748;68380;69279.65360173167;10596.576136954436;12827.551035713705;13059.281739504419;13068.281244531641;13558;13083.803742944054;14486.782698259323;14770.704585908437;14862.405503779728;15252.0086194397;13631.250281234627;13723.725525489634;13851.515196968057;14168.645783855485;14296.428142874169;13361.933190333986;13607.387667554785;13745.450181992608;14066.437342506186;14144.717105657623;12884.549040783528;13361.732765344834;13701.177929324273;13780.724385512032;13959.162450252612;11077;11141.16441266892;11359;11608.24546404481;11695.76608467843;9706;11182.776344472903;11554.884451155382;11592.6522204334;11640.5343786251 16:13:35 INFO - PROCESS | 1644 | __end_tp_report 16:13:35 INFO - PROCESS | 1644 | __start_cc_report 16:13:35 INFO - PROCESS | 1644 | _x_x_mozilla_cycle_collect,2276 16:13:35 INFO - PROCESS | 1644 | __end_cc_report 16:13:35 INFO - PROCESS | 1644 | __startTimestamp1482538415382__endTimestamp 16:13:35 INFO - PROCESS | 1644 | 16:13:35 INFO - PROCESS | 1644 | ------- Summary: start ------- 16:13:35 INFO - PROCESS | 1644 | Number of tests: 6 16:13:35 INFO - PROCESS | 1644 | 16:13:35 INFO - PROCESS | 1644 | [#0] dojo.html Cycles:165 Average:5654.93 Median:5787.65 stddev:678.99 (11.7%) stddev-sans-first:647.48 16:13:35 INFO - PROCESS | 1644 | Values: 2966.2 3298.9 3304.9 3312.1 3372.7 5533.5 5543.5 5571.4 5633.5 5645.8 5600.5 5651.2 5697.8 5722.9 5766.2 3816.7 5115.3 5130.8 5157.4 5199.5 4346.3 4548.2 5171.1 5254.8 5323.4 5096.7 5207.2 5217.1 5258.4 5265.4 4248.2 5043.7 5125.8 5157.6 5217.2 5117.4 5145.5 5150.4 5168.1 5202.1 5263.3 5577.7 5633.9 5645.5 5645.7 6070.8 6115.3 6117.8 6120.2 6168.4 4966.6 6149.4 6258.1 6293.4 6303.2 5742.7 5780.4 5787.7 5795.1 5802.3 6137.4 6243.3 6243.8 6251.5 6282.8 5969.5 6031.8 6044.1 6061.1 6063.1 6189.8 6197.6 6207.9 6208.3 6219.8 5122.6 6130.3 6139.4 6161.4 6223.7 6234.9 6237.0 6242.7 6244.4 6254.9 4918.4 4922.7 4927.8 4930.6 4958.4 4311.0 4341.1 4344.8 4354.0 4406.9 5722.2 5736.9 5744.3 5748.8 5761.0 6281.8 6287.5 6299.7 6319.7 6323.7 5559.2 5640.5 5645.2 5724.2 5735.1 5336.0 5342.6 5383.3 5404.1 5419.5 6286.6 6291.5 6292.5 6294.7 6303.5 6194.2 6213.7 6247.2 6258.0 6268.0 6242.1 6265.6 6281.1 6295.9 6298.9 5622.9 5784.0 6109.1 6112.4 6129.8 6118.4 6127.2 6183.5 6199.2 6204.8 5993.8 6003.8 6012.6 6013.5 6017.6 5986.4 5995.9 6000.3 6015.0 6017.7 5739.8 5766.1 5784.9 5836.1 5852.2 5921.3 5954.8 5957.2 5965.0 5978.9 5732.9 5783.9 5786.7 5790.4 5815.9 16:13:35 INFO - PROCESS | 1644 | 16:13:35 INFO - PROCESS | 1644 | [#1] ext.html Cycles:180 Average:69216.23 Median:36477.14 stddev:153358.91 (420.4%) stddev-sans-first:153702.17 16:13:35 INFO - PROCESS | 1644 | Values: 435.2 444.7 477.7 500.5 515.5 37797.8 39278.4 39288.8 39294.8 41372.8 39433.0 39720.8 39776.8 39862.0 40049.4 8285.1 8468.4 9231.5 9443.0 9468.8 11065.4 11288.5 11366.3 11540.6 11555.4 11203.4 11233.4 11290.8 11306.1 11317.2 11096.6 11149.4 11220.9 11250.5 11251.7 11084.0 11113.6 11182.7 11196.1 11208.4 38345.0 38796.4 38805.4 38817.2 38915.6 3306.9 3371.8 3482.4 3713.2 3718.5 27271.7 27411.2 27966.2 28239.0 28996.4 3140.2 3146.1 3160.5 3220.1 3270.7 499647.0 512825.0 521957.8 525244.0 645872.5 52525.0 52671.7 53141.0 53201.9 53223.7 48353.3 54553.7 54805.5 54808.0 54826.7 14336.9 14574.0 14575.9 14600.3 14690.8 10247.0 10556.5 11045.2 11315.7 11422.3 16039.8 16391.7 16415.8 16422.0 16463.0 7276.2 7296.7 7300.9 7341.9 7387.8 829.6 885.7 945.5 990.3 1039.2 27893.6 29148.1 29414.3 29476.4 29965.9 983.3 996.9 1030.2 1116.4 1143.8 14478.3 15070.0 15197.6 15250.6 15518.3 608892.0 614502.0 614967.0 615990.0 616331.0 55356.7 55901.9 55974.4 55989.4 56041.4 1343.3 1386.6 1397.1 1403.0 1403.1 25843.0 27649.6 28546.0 29287.3 29327.7 46397.5 46594.5 46697.8 46718.3 46750.8 556115.2 560753.0 560760.0 561306.0 562252.7 21666.9 22006.6 23780.9 25182.9 25607.2 43599.0 44311.7 44779.8 44817.6 45008.8 36723.4 36735.3 36881.8 37993.2 38336.4 30535.4 36803.0 37785.6 37852.8 38669.4 35443.8 36127.3 36397.5 36556.8 37011.8 31909.8 33073.7 33086.5 33552.5 33565.8 22834.0 24262.2 24376.9 24881.4 24994.6 16:13:35 INFO - PROCESS | 1644 | 16:13:35 INFO - PROCESS | 1644 | [#2] jquery.html Cycles:180 Average:31795.72 Median:22482.29 stddev:86758.68 (385.9%) stddev-sans-first:86969.92 16:13:35 INFO - PROCESS | 1644 | Values: 346.7 365.2 366.6 374.1 398.0 14073.3 14826.1 15985.2 18017.7 20551.2 18768.0 19860.9 20505.9 20625.0 20669.8 5612.4 5808.7 5864.4 5884.9 5904.8 4054.9 4298.2 4332.5 5755.8 5839.0 4275.6 5617.0 6110.1 6172.6 6463.6 5486.5 6177.3 6348.9 6426.5 6454.6 5476.7 5601.9 5606.2 5610.5 5618.8 20522.6 24254.0 24565.0 24844.4 25429.4 2141.7 2158.8 2222.5 2370.2 2788.3 14258.6 15388.0 15541.4 15685.4 16017.6 1809.2 1812.1 1857.0 1868.5 1870.6 105789.0 106051.5 106773.5 108182.4 108416.5 10548.9 10593.9 10620.3 10676.4 10722.0 13734.2 13746.8 13766.4 13771.7 13772.8 7026.5 8334.5 8497.0 8668.2 8669.5 8349.3 8402.9 8470.2 8609.5 8613.9 5607.9 6564.0 6683.8 6684.2 6689.1 1648.9 1775.7 1815.4 1818.3 1839.1 773.9 788.3 968.2 986.4 1060.8 21039.3 21042.7 22226.3 22455.7 22508.9 939.5 953.9 956.7 966.5 992.4 10993.0 11422.4 11939.6 11942.9 11948.7 506700.0 520053.0 520555.0 526299.0 530516.0 103023.0 103752.0 104297.0 106979.0 107075.0 997.4 1019.1 1055.2 1078.5 1087.7 19155.5 19388.0 19538.2 19581.2 19626.0 47618.3 48240.8 48968.0 49380.8 50738.0 66567.7 72595.6 77472.0 79658.2 80452.6 8020.7 8057.7 8250.4 8365.4 8487.3 12409.9 12762.2 13022.7 13314.9 13356.7 10908.5 11303.8 11410.5 11461.8 11508.7 9584.0 11002.8 11064.3 11205.5 11535.5 11196.8 11349.8 11562.6 11578.7 11781.4 8038.3 8111.6 8217.7 8248.9 8337.5 8217.4 10299.7 10774.8 11092.6 11139.7 16:13:35 INFO - PROCESS | 1644 | 16:13:35 INFO - PROCESS | 1644 | [#3] mootools.html Cycles:180 Average:5296.99 Median:5225.44 stddev:2903.80 (55.6%) stddev-sans-first:2897.16 16:13:35 INFO - PROCESS | 1644 | Values: 1397.8 1490.9 1675.7 1851.9 1874.7 7440.8 7871.2 8306.3 8319.7 8333.5 7494.6 7666.4 7825.8 8055.2 8072.3 2246.5 2306.6 2307.0 2312.2 2313.0 2028.5 2311.8 2330.3 2350.3 2355.3 1712.4 1900.0 1975.8 1976.5 1977.3 2028.2 2221.3 2252.6 2304.6 2305.2 2296.5 2305.2 2316.2 2327.9 2329.7 8134.0 8315.8 8316.1 8317.2 8320.5 4971.0 5151.7 5153.8 5159.8 5163.8 2982.5 3001.5 3257.6 3421.4 3499.2 986.2 1017.4 1047.1 1055.3 1055.3 10554.7 10648.7 10662.8 10672.3 10701.6 4281.2 4505.0 4571.9 4574.7 4587.9 8944.9 8947.4 8949.8 8955.6 8964.6 5083.3 5096.4 5203.7 5206.7 5207.6 4518.3 4713.6 4744.4 4745.5 4754.9 3763.2 3788.8 3825.5 3865.1 3889.9 1173.5 1175.0 1175.6 1190.3 1192.5 633.5 758.5 774.0 874.7 879.5 4436.5 5243.3 5542.0 5545.4 5700.1 873.7 897.7 910.8 915.3 964.1 3625.6 3673.9 3740.6 3790.8 3793.4 9107.5 9855.2 9975.8 9985.9 9990.4 9740.6 9916.1 9944.6 9955.5 9959.8 7545.5 7797.1 7797.4 7811.1 7814.6 4801.6 5344.9 5478.7 5501.5 5543.9 7649.4 7733.9 9074.0 9094.3 9202.5 9297.0 9359.6 9371.4 9394.6 9494.7 6505.3 6735.7 6744.1 6751.6 6774.3 7588.0 7653.7 7697.6 7745.3 7784.2 7044.9 7090.6 7124.5 7134.3 7165.5 6312.0 6801.1 6908.9 6942.3 6960.0 6254.9 6379.2 6386.0 6391.4 6394.3 4605.8 4716.7 4753.0 4791.4 4827.2 4108.8 4785.8 4817.5 4868.8 4869.9 16:13:35 INFO - PROCESS | 1644 | 16:13:35 INFO - PROCESS | 1644 | [#4] prototype.html Cycles:180 Average:4402.48 Median:4906.15 stddev:1310.01 (26.7%) stddev-sans-first:1277.59 16:13:35 INFO - PROCESS | 1644 | Values: 333.6 342.0 344.8 367.4 367.9 5254.6 5326.7 5345.3 5347.9 5369.2 4190.7 4560.8 4991.7 4996.8 5136.4 3979.5 4091.6 4130.3 4142.9 4146.9 3913.8 4110.5 4144.6 4180.4 4215.1 3156.9 3772.9 3802.7 4115.4 4163.6 4077.6 4097.8 4103.8 4105.3 4126.4 3146.5 3618.9 3676.3 3694.2 3710.6 4234.9 4925.4 5184.0 5235.4 5255.7 3594.9 4141.5 4650.8 4659.6 4708.9 5476.0 5501.2 5518.5 5519.1 5590.8 3887.9 4050.9 4060.7 4069.0 4099.1 4897.9 5760.5 5784.3 5796.8 5813.9 4053.9 4077.5 4081.5 4095.0 4106.6 3477.6 4004.9 4132.7 4140.5 4148.8 3738.8 4479.4 4644.6 4712.9 4724.6 5543.9 5544.4 5550.7 5573.6 5581.4 1123.9 1202.8 1283.9 1335.3 1350.9 818.9 843.9 872.3 910.5 958.6 4914.4 4945.8 4975.7 4982.3 5027.7 4749.9 4960.7 5226.3 5393.8 5495.3 4244.8 4783.8 4892.5 5095.5 5174.8 2378.7 2564.4 2647.0 2656.0 2821.6 5195.9 5276.8 5527.3 5666.7 5690.4 4856.8 5077.9 5100.6 5451.4 5530.6 4760.9 4932.9 5236.0 5545.8 5591.4 4734.6 5092.7 5219.3 5454.0 5479.3 4563.6 5035.9 5383.5 5384.6 5391.9 4625.0 5445.7 5480.8 5637.3 5653.7 3576.8 3580.6 3598.6 3606.6 3621.9 4569.7 5011.8 5039.8 5205.7 5770.0 4665.8 5512.8 5527.0 5573.6 5621.0 4782.9 5074.9 5350.3 5422.4 5487.6 4040.2 5105.5 5185.7 5437.2 5506.2 4211.7 4585.8 4586.5 5408.0 5436.3 4730.3 5205.5 5365.9 5410.3 5444.3 16:13:35 INFO - PROCESS | 1644 | 16:13:35 INFO - PROCESS | 1644 | [#5] yui.html Cycles:180 Average:20104.12 Median:4581.93 stddev:28820.08 (629.0%) stddev-sans-first:28863.77 16:13:35 INFO - PROCESS | 1644 | Values: 613.3 729.0 735.3 745.0 816.8 14635.9 15056.2 15383.5 15443.6 15514.4 13929.4 14104.4 14110.2 14151.8 14158.1 853.0 876.5 886.9 888.0 889.8 886.4 889.8 891.8 892.4 893.4 935.3 936.8 937.9 938.9 939.4 933.9 934.9 934.9 935.4 935.5 695.3 695.5 698.2 698.2 698.3 14963.2 14979.9 15014.3 15043.5 15046.7 13542.0 13701.5 14017.6 14075.6 14145.6 11017.2 11481.5 11594.0 11598.8 12134.9 4267.9 4514.3 4586.1 4607.1 4705.5 101797.5 102500.0 106561.0 107844.0 107874.5 9853.8 10183.9 10705.5 10729.2 10786.1 19431.9 20541.3 20571.0 21041.1 21074.0 7235.5 7320.2 7353.3 7533.0 7694.7 4314.1 4317.4 4386.1 4442.7 4526.8 3473.0 3545.7 3549.5 3581.7 3582.7 629.4 635.5 642.6 645.1 646.7 547.9 642.6 670.9 680.9 683.3 7531.4 8598.7 8679.6 9068.2 9199.6 640.3 661.3 663.9 670.6 704.1 4277.4 4345.6 4577.8 4589.4 4715.9 84821.0 93151.5 97759.0 100026.0 103995.5 72231.6 88333.6 90807.0 92582.0 93535.0 60014.7 80452.0 82360.0 83713.0 84070.0 7388.5 8423.9 8588.5 9040.2 9260.5 35800.1 46990.8 51615.5 53156.5 54483.7 67431.7 67770.0 68311.7 68380.0 69279.7 10596.6 12827.6 13059.3 13068.3 13558.0 13083.8 14486.8 14770.7 14862.4 15252.0 13631.3 13723.7 13851.5 14168.6 14296.4 13361.9 13607.4 13745.5 14066.4 14144.7 12884.5 13361.7 13701.2 13780.7 13959.2 11077.0 11141.2 11359.0 11608.2 11695.8 9706.0 11182.8 11554.9 11592.7 11640.5 16:13:35 INFO - PROCESS | 1644 | -------- Summary: end -------- 16:13:35 INFO - PROCESS | 1644 | 16:13:36 INFO - TEST-INFO | 1644: exit 0 16:13:36 INFO - TEST-OK | dromaeo_css | took 1120645ms 16:13:36 INFO - TEST-START | kraken 16:13:36 INFO - operating with platform_type : mac_ 16:13:36 INFO - Initialising browser for kraken test... 16:13:36 INFO - TEST-INFO | started process 1651 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/tmpTaxm4t/profile http://localhost:49240/getInfo.html) 16:13:36 INFO - PROCESS | 1651 | 1482538416578 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:4979 < processFileChanges()@resource://gre/modules/addons/XPIProvider.jsm -> resource://gre/modules/addons/XPIProviderUtils.js:2097 < checkForChanges()@resource://gre/modules/addons/XPIProvider.jsm:3825 < startup()@resource://gre/modules/addons/XPIProvider.jsm:2829 < callProvider()@resource://gre/modules/AddonManager.jsm:264 < _startProvider()@resource://gre/modules/AddonManager.jsm:771 < startup()@resource://gre/modules/AddonManager.jsm:957 < startup()@resource://gre/modules/AddonManager.jsm:2923 < observe()@resource://gre/components/addonManager.js:65 16:13:37 INFO - PROCESS | 1651 | JavaScript error: chrome://global/content/browser-content.js, line 1772: ReferenceError: event is not defined 16:13:37 INFO - PROCESS | 1651 | JavaScript error: chrome://global/content/browser-content.js, line 1772: ReferenceError: event is not defined 16:13:38 INFO - PROCESS | 1651 | JavaScript error: chrome://global/content/browser-content.js, line 1772: ReferenceError: event is not defined 16:13:38 INFO - PROCESS | 1651 | __metrics Screen width/height:1600/1200 16:13:38 INFO - PROCESS | 1651 | colorDepth:24 16:13:38 INFO - PROCESS | 1651 | Browser inner width/height: 1024/689 16:13:38 INFO - PROCESS | 1651 | __metrics 16:13:38 INFO - PROCESS | 1651 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 16:13:38 INFO - PROCESS | 1651 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 16:13:39 INFO - TEST-INFO | 1651: exit 0 16:13:39 INFO - Browser initialized. 16:13:39 INFO - Running cycle 1/1 for kraken test... 16:13:39 INFO - TEST-INFO | started process 1652 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/tmpTaxm4t/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:13:51 INFO - PROCESS | 1652 | RSS: Main: 234643456 16:13:51 INFO - PROCESS | 1652 | 16:13:54 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49240/tests/kraken/driver.html?testName=audio-beat-detection) 16:13:55 INFO - PROCESS | 1652 | RSS: Main: 289652736 16:13:55 INFO - PROCESS | 1652 | 16:13:57 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49240/tests/kraken/driver.html?testName=audio-dft) 16:13:58 INFO - PROCESS | 1652 | RSS: Main: 291258368 16:13:58 INFO - PROCESS | 1652 | 16:14:01 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49240/tests/kraken/driver.html?testName=audio-fft) 16:14:02 INFO - PROCESS | 1652 | RSS: Main: 379699200 16:14:02 INFO - PROCESS | 1652 | 16:14:04 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49240/tests/kraken/driver.html?testName=audio-oscillator) 16:14:05 INFO - PROCESS | 1652 | RSS: Main: 282816512 16:14:05 INFO - PROCESS | 1652 | 16:14:07 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49240/tests/kraken/driver.html?testName=imaging-gaussian-blur) 16:14:07 INFO - PROCESS | 1652 | RSS: Main: 286343168 16:14:07 INFO - PROCESS | 1652 | 16:14:10 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49240/tests/kraken/driver.html?testName=imaging-darkroom) 16:14:11 INFO - PROCESS | 1652 | RSS: Main: 330752000 16:14:11 INFO - PROCESS | 1652 | 16:14:14 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49240/tests/kraken/driver.html?testName=imaging-desaturate) 16:14:14 INFO - PROCESS | 1652 | RSS: Main: 328740864 16:14:14 INFO - PROCESS | 1652 | 16:14:17 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49240/tests/kraken/driver.html?testName=json-parse-financial) 16:14:18 INFO - PROCESS | 1652 | RSS: Main: 326856704 16:14:18 INFO - PROCESS | 1652 | 16:14:19 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49240/tests/kraken/driver.html?testName=json-stringify-tinderbox) 16:14:20 INFO - PROCESS | 1652 | RSS: Main: 304721920 16:14:20 INFO - PROCESS | 1652 | 16:14:22 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-aes) 16:14:22 INFO - PROCESS | 1652 | RSS: Main: 306499584 16:14:22 INFO - PROCESS | 1652 | 16:14:25 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-ccm) 16:14:25 INFO - PROCESS | 1652 | RSS: Main: 308572160 16:14:25 INFO - PROCESS | 1652 | 16:14:28 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 16:14:29 INFO - PROCESS | 1652 | RSS: Main: 326283264 16:14:29 INFO - PROCESS | 1652 | 16:14:32 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 16:14:33 INFO - PROCESS | 1652 | RSS: Main: 319537152 16:14:33 INFO - PROCESS | 1652 | 16:14:34 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49240/tests/kraken/driver.html?testName=ai-astar) 16:14:35 INFO - PROCESS | 1652 | RSS: Main: 363696128 16:14:35 INFO - PROCESS | 1652 | 16:14:35 INFO - PROCESS | 1652 | __start_tp_report 16:14:35 INFO - PROCESS | 1652 | _x_x_mozilla_page_load 16:14:35 INFO - PROCESS | 1652 | _x_x_mozilla_page_load_details 16:14:35 INFO - PROCESS | 1652 | |i|pagename|runs| 16:14:35 INFO - PROCESS | 1652 | |0;ai-astar;113;113;114;113;113;114;120;168;161;114 16:14:35 INFO - PROCESS | 1652 | |1;audio-beat-detection;185;140;138;147;133;100;132;136;161;113 16:14:35 INFO - PROCESS | 1652 | |2;audio-dft;180;183;186;185;182;181;182;182;180;181 16:14:35 INFO - PROCESS | 1652 | |3;audio-fft;81;59;64;76;53;80;81;80;73;58 16:14:35 INFO - PROCESS | 1652 | |4;audio-oscillator;86;70;85;68;69;69;83;69;68;83 16:14:35 INFO - PROCESS | 1652 | |5;imaging-gaussian-blur;88;87;89;88;89;86;89;87;91;84 16:14:35 INFO - PROCESS | 1652 | |6;imaging-darkroom;110;151;148;96;94;95;95;96;95;95 16:14:35 INFO - PROCESS | 1652 | |7;imaging-desaturate;85;84;87;87;84;87;85;88;85;85 16:14:35 INFO - PROCESS | 1652 | |8;json-parse-financial;56;55;54;53;56;55;54;55;54;55 16:14:35 INFO - PROCESS | 1652 | |9;json-stringify-tinderbox;64;76;70;69;77;71;69;73;71;71 16:14:35 INFO - PROCESS | 1652 | |10;stanford-crypto-aes;88;85;88;102;106;87;85;87;85;90 16:14:35 INFO - PROCESS | 1652 | |11;stanford-crypto-ccm;140;145;146;150;138;129;142;150;205;176 16:14:35 INFO - PROCESS | 1652 | |12;stanford-crypto-pbkdf2;216;171;172;176;171;170;171;168;169;172 16:14:35 INFO - PROCESS | 1652 | |13;stanford-crypto-sha256-iterative;66;68;67;66;67;66;68;65;66;66 16:14:35 INFO - PROCESS | 1652 | __end_tp_report 16:14:35 INFO - PROCESS | 1652 | __start_cc_report 16:14:35 INFO - PROCESS | 1652 | _x_x_mozilla_cycle_collect,1963 16:14:35 INFO - PROCESS | 1652 | __end_cc_report 16:14:35 INFO - PROCESS | 1652 | __startTimestamp1482538475006__endTimestamp 16:14:35 INFO - PROCESS | 1652 | 16:14:35 INFO - PROCESS | 1652 | ------- Summary: start ------- 16:14:35 INFO - PROCESS | 1652 | Number of tests: 14 16:14:35 INFO - PROCESS | 1652 | 16:14:35 INFO - PROCESS | 1652 | [#0] ai-astar Cycles:10 Average:124.30 Median:114.00 stddev:21.35 (18.7%) stddev-sans-first:22.25 16:14:35 INFO - PROCESS | 1652 | Values: 113.0 113.0 114.0 113.0 113.0 114.0 120.0 168.0 161.0 114.0 16:14:35 INFO - PROCESS | 1652 | 16:14:35 INFO - PROCESS | 1652 | [#1] audio-beat-detection Cycles:10 Average:138.50 Median:139.00 stddev:23.46 (16.9%) stddev-sans-first:17.86 16:14:35 INFO - PROCESS | 1652 | Values: 185.0 140.0 138.0 147.0 133.0 100.0 132.0 136.0 161.0 113.0 16:14:35 INFO - PROCESS | 1652 | 16:14:35 INFO - PROCESS | 1652 | [#2] audio-dft Cycles:10 Average:182.20 Median:182.00 stddev:1.99 (1.1%) stddev-sans-first:1.94 16:14:35 INFO - PROCESS | 1652 | Values: 180.0 183.0 186.0 185.0 182.0 181.0 182.0 182.0 180.0 181.0 16:14:35 INFO - PROCESS | 1652 | 16:14:35 INFO - PROCESS | 1652 | [#3] audio-fft Cycles:10 Average:70.50 Median:78.00 stddev:10.93 (14.0%) stddev-sans-first:10.91 16:14:35 INFO - PROCESS | 1652 | Values: 81.0 59.0 64.0 76.0 53.0 80.0 81.0 80.0 73.0 58.0 16:14:35 INFO - PROCESS | 1652 | 16:14:35 INFO - PROCESS | 1652 | [#4] audio-oscillator Cycles:10 Average:75.00 Median:76.50 stddev:8.03 (10.5%) stddev-sans-first:7.46 16:14:35 INFO - PROCESS | 1652 | Values: 86.0 70.0 85.0 68.0 69.0 69.0 83.0 69.0 68.0 83.0 16:14:35 INFO - PROCESS | 1652 | 16:14:35 INFO - PROCESS | 1652 | [#5] imaging-gaussian-blur Cycles:10 Average:87.80 Median:88.50 stddev:1.93 (2.2%) stddev-sans-first:2.05 16:14:35 INFO - PROCESS | 1652 | Values: 88.0 87.0 89.0 88.0 89.0 86.0 89.0 87.0 91.0 84.0 16:14:35 INFO - PROCESS | 1652 | 16:14:35 INFO - PROCESS | 1652 | [#6] imaging-darkroom Cycles:10 Average:107.50 Median:95.00 stddev:22.63 (23.8%) stddev-sans-first:23.99 16:14:35 INFO - PROCESS | 1652 | Values: 110.0 151.0 148.0 96.0 94.0 95.0 95.0 96.0 95.0 95.0 16:14:35 INFO - PROCESS | 1652 | 16:14:35 INFO - PROCESS | 1652 | [#7] imaging-desaturate Cycles:10 Average:85.70 Median:86.00 stddev:1.42 (1.6%) stddev-sans-first:1.48 16:14:35 INFO - PROCESS | 1652 | Values: 85.0 84.0 87.0 87.0 84.0 87.0 85.0 88.0 85.0 85.0 16:14:35 INFO - PROCESS | 1652 | 16:14:35 INFO - PROCESS | 1652 | [#8] json-parse-financial Cycles:10 Average:54.70 Median:55.00 stddev:0.95 (1.7%) stddev-sans-first:0.88 16:14:35 INFO - PROCESS | 1652 | Values: 56.0 55.0 54.0 53.0 56.0 55.0 54.0 55.0 54.0 55.0 16:14:35 INFO - PROCESS | 1652 | 16:14:35 INFO - PROCESS | 1652 | [#9] json-stringify-tinderbox Cycles:10 Average:71.10 Median:71.00 stddev:3.70 (5.2%) stddev-sans-first:2.89 16:14:35 INFO - PROCESS | 1652 | Values: 64.0 76.0 70.0 69.0 77.0 71.0 69.0 73.0 71.0 71.0 16:14:35 INFO - PROCESS | 1652 | 16:14:35 INFO - PROCESS | 1652 | [#10] stanford-crypto-aes Cycles:10 Average:90.30 Median:87.00 stddev:7.45 (8.6%) stddev-sans-first:7.86 16:14:35 INFO - PROCESS | 1652 | Values: 88.0 85.0 88.0 102.0 106.0 87.0 85.0 87.0 85.0 90.0 16:14:35 INFO - PROCESS | 1652 | 16:14:35 INFO - PROCESS | 1652 | [#11] stanford-crypto-ccm Cycles:10 Average:152.10 Median:148.00 stddev:22.23 (15.0%) stddev-sans-first:23.14 16:14:35 INFO - PROCESS | 1652 | Values: 140.0 145.0 146.0 150.0 138.0 129.0 142.0 150.0 205.0 176.0 16:14:35 INFO - PROCESS | 1652 | 16:14:35 INFO - PROCESS | 1652 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:175.60 Median:171.50 stddev:14.35 (8.4%) stddev-sans-first:2.26 16:14:35 INFO - PROCESS | 1652 | Values: 216.0 171.0 172.0 176.0 171.0 170.0 171.0 168.0 169.0 172.0 16:14:35 INFO - PROCESS | 1652 | 16:14:35 INFO - PROCESS | 1652 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.50 Median:66.50 stddev:0.97 (1.5%) stddev-sans-first:1.01 16:14:35 INFO - PROCESS | 1652 | Values: 66.0 68.0 67.0 66.0 67.0 66.0 68.0 65.0 66.0 66.0 16:14:35 INFO - PROCESS | 1652 | -------- Summary: end -------- 16:14:35 INFO - PROCESS | 1652 | 16:14:35 INFO - TEST-INFO | 1652: exit 0 16:14:35 INFO - TEST-OK | kraken | took 59801ms 16:14:35 INFO - SUITE-END | took 1180s 16:14:36 INFO - Completed test suite (00:19:40) 16:14:36 INFO - javascript benchmark 16:14:36 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2966.24360787999, 3298.884539041131, 3304.90085297441, 3312.0726196664928, 3372.7301815854735, 5533.529649979757, 5543.473370029846, 5571.4428557144265, 5633.52115070219, 5645.774169033234, 5600.4959553640265, 5651.180578816069, 5697.772089116441, 5722.885542289152, 5766.192733017381, 3816.732828701992, 5115.309433226504, 5130.820421285256, 5157.355330583677, 5199.454057323983, 4346.261135606955, 4548.230678116674, 5171.120909445403, 5254.763535640886, 5323.414424413311, 5096.745162741885, 5207.192885102815, 5217.087009773293, 5258.368995720538, 5265.394479634852, 4248.235317642823, 5043.722595257259, 5125.846224613268, 5157.638965272433, 5217.243499692523, 5117.411497677777, 5145.511176438233, 5150.4334523202415, 5168.147255702805, 5202.0896343140175, 5263.289455923439, 5577.749001294951, 5633.8591535211535, 5645.491905728463, 5645.745941432645, 6070.787522436738, 6115.266168059817, 6117.796956032686, 6120.204373431426, 6168.383161683841, 4966.627502937295, 6149.385061493815, 6258.092576576371, 6293.402126797947, 6303.180586523723, 5742.7415766290615, 5780.393058728857, 5787.65274083548, 5795.130730390475, 5802.303723553158, 6137.355577664371, 6243.282022567463, 6243.8126856194385, 6251.468625166912, 6282.842928926813, 5969.522438204933, 6031.849203769941, 6044.063170208537, 6061.090836374579, 6063.060225664941, 6189.752409903644, 6197.566170368031, 6207.937920620826, 6208.255009398811, 6219.813405597839, 5122.590192784568, 6130.264368275835, 6139.386061393914, 6161.44546990773, 6223.657698826608, 6234.937650623436, 6236.968815155895, 6242.65665388408, 6244.406781355764, 6254.906176407358, 4918.385201849769, 4922.704637721748, 4927.77824997876, 4930.605551555867, 4958.380202474691, 4310.956890431118, 4341.08837144197, 4344.847930322424, 4354.042110735633, 4406.911861762746, 5722.170285308607, 5736.913946290809, 5744.281964754406, 5748.798792042258, 5760.971195143998, 6281.780137695158, 6287.497000240061, 6299.653519056405, 6319.652419116993, 6323.683815809283, 5559.221708960748, 5640.520555752807, 5645.2096706461125, 5724.227229323988, 5735.053716136791, 5335.973320133375, 5342.572594192456, 5383.273258110184, 5404.1353383459245, 5419.512243898068, 6286.622802631857, 6291.465225455888, 6292.4651404629285, 6294.748210071637, 6303.495720342454, 6194.194754681863, 6213.689315534115, 6247.187865577446, 6257.998720204745, 6268.028455589391, 6242.126102345766, 6265.561410701207, 6281.120643109877, 6295.9370406297185, 6298.874022519432, 5622.85942851432, 5784.045632470764, 6109.083637454418, 6112.41229444207, 6129.756146212572, 6118.418750218722, 6127.172831667668, 6183.505319574333, 6199.163112979871, 6204.751809927733, 5993.790217342546, 6003.759849605967, 6012.6392416455155, 6013.548983826143, 6017.608855424383, 5986.4013598639785, 5995.850103747267, 6000.279966404059, 6015.0, 6017.729202185999, 5739.770409183753, 5766.106253530709, 5784.855378615568, 5836.095405212199, 5852.209951656472, 5921.3190483094995, 5954.821355359346, 5957.195778569838, 5964.985952388191, 5978.850528736816, 5732.885342293214, 5783.913241301468, 5786.681732504752, 5790.39200883918, 5815.912761308668], "value": 5610.231329343908, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [435.16231254834435, 444.7198265092991, 477.6656340561607, 500.4820011288317, 515.5231410944878, 37797.81101094494, 39278.41082383763, 39288.80355598225, 39294.821155365345, 41372.75881723546, 39433.0, 39720.80139599298, 39776.8066957991, 39862.0, 40049.39925901114, 8285.130061343563, 8468.364872634545, 9231.538423078837, 9443.0, 9468.81062378752, 11065.446727663624, 11288.492017859217, 11366.31802091873, 11540.596079137233, 11555.364454954974, 11203.383813890232, 11233.438328083563, 11290.774184516305, 11306.095512359032, 11317.151213659008, 11096.61161859331, 11149.386783726892, 11220.887791122066, 11250.493727782188, 11251.718707032349, 11084.00243978046, 11113.611023614134, 11182.720431989184, 11196.104311655048, 11208.383538905357, 38345.0, 38796.41805372922, 38805.41791873124, 38817.22365552673, 38915.61084389148, 3306.8677252909856, 3371.7976921384798, 3482.407990641597, 3713.1830997180577, 3718.4980027696456, 27271.727282727315, 27411.177664670093, 27966.16101516958, 28239.0, 28996.420071598663, 3140.1678555182893, 3146.11908665574, 3160.5101209312593, 3220.066180807563, 3270.721988630969, 499647.0, 512825.0, 521957.7804221947, 525244.0, 645872.5412745907, 52525.0, 52671.73664131655, 53141.0, 53201.935961281335, 53223.73388133034, 48353.27470087951, 54553.72723136359, 54805.45194548083, 54808.0, 54826.72586637041, 14336.928315358566, 14573.979821412611, 14575.927120364331, 14600.34298456572, 14690.779638305434, 10247.0, 10556.47217639115, 11045.226834121695, 11315.717107072225, 11422.25755325901, 16039.839601604068, 16391.67216655684, 16415.753763693556, 16422.0, 16463.0, 7276.235995220532, 7296.6716497757725, 7300.860629929181, 7341.853162936712, 7387.778366648901, 829.6349606173264, 885.6634478898106, 945.4516380499294, 990.3117333453325, 1039.163473403902, 27893.58159627607, 29148.125556233346, 29414.26464338366, 29476.410471790445, 29965.850170749443, 983.2625530851861, 996.9052939970691, 1030.2221822524095, 1116.3525155409843, 1143.7540928700369, 14478.348474318682, 15070.0, 15197.6200594986, 15250.609725747803, 15518.292987771336, 608892.0, 614502.0, 614967.0, 615990.0, 616331.0, 55356.72321638366, 55901.88196236052, 55974.44025559692, 55989.440105599235, 56041.439585604436, 1343.280600830938, 1386.6394737368357, 1397.1407584335764, 1402.9719405612034, 1403.1440821099325, 25843.0, 27649.585256221577, 28546.0, 29287.26781830386, 29327.706722932497, 46397.53602464067, 46594.534054659016, 46697.766511167225, 46718.299225510964, 46750.766246168554, 556115.2194239002, 560753.0, 560760.0, 561306.0, 562252.6986285545, 21666.89166554157, 22006.559868802855, 23780.88109559441, 25182.874085629453, 25607.23178304654, 43599.0, 44311.67064988056, 44779.776101119285, 44817.551824482645, 45008.77495612501, 36723.44914826226, 36735.265294694495, 36881.81559092188, 37993.24013519659, 38336.42495362516, 30535.389292214477, 36803.0, 37785.62214377821, 37852.81073594614, 38669.419958701204, 35443.82278088593, 36127.27745445024, 36397.45403818998, 36556.81721591375, 37011.81494092512, 31909.840450797597, 33073.669263307056, 33086.503702444, 33552.49671254884, 33565.83217083899, 22834.0, 24262.15082472105, 24376.87811561002, 24881.377965551004, 24994.625080623442], "value": 19425.51491351193, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [346.6793216274945, 365.1583100952304, 366.5766040223542, 374.10961910652645, 398.02682441430693, 14073.296335183239, 14826.110433374006, 15985.200739963013, 18017.738758286894, 20551.177952881902, 18767.967761773096, 19860.900695496537, 20505.897470512664, 20625.0, 20669.793302067013, 5612.354579223391, 5808.738606762706, 5864.44287792659, 5884.941150588482, 5904.763809447617, 4054.858079967202, 4298.161858437606, 4332.545082766295, 5755.769769209248, 5839.036558967765, 4275.636570891478, 5616.971915140439, 6110.144579758838, 6172.598781079238, 6463.579867308634, 5486.506214440699, 6177.25872895251, 6348.873022539523, 6426.453751431134, 6454.644994525276, 5476.698781567012, 5601.859953501154, 5606.159076138571, 5610.523105536035, 5618.803341883015, 20522.5895482091, 24254.0, 24565.0, 24844.3788905277, 25429.364265893317, 2141.657334826436, 2158.8380871434706, 2222.5443784024237, 2370.2178281167176, 2788.261110805638, 14258.64353391163, 15387.999780014376, 15541.378344866305, 15685.372585096582, 16017.599560010976, 1809.240119149947, 1812.0939530234882, 1857.0436225343958, 1868.4674867662786, 1870.6071724937779, 105789.0, 106051.46974265156, 106773.46613266962, 108182.37726434109, 108416.4579177107, 10548.894511054792, 10593.940605939499, 10620.256582039183, 10676.359418434764, 10721.98141176587, 13734.175949443066, 13746.793798093036, 13766.380512876945, 13771.655708607364, 13772.793408098887, 7026.508144429943, 8334.541600211925, 8496.957515212385, 8668.176523230282, 8669.479831210148, 8349.290310323691, 8402.873956890651, 8470.195331443627, 8609.48343099416, 8613.870791938127, 5607.934492446504, 6563.967180164069, 6683.832904177434, 6684.1978962523835, 6689.13041304637, 1648.8763342749342, 1775.742517334979, 1815.4009176971567, 1818.3090425638172, 1839.1356062650532, 773.8877862709876, 788.2826627768703, 968.1625394034251, 986.3527908393, 1060.7560261139981, 21039.263625773023, 21042.684359734616, 22226.333210003726, 22455.66316505221, 22508.922590900365, 939.5490164720973, 953.8998405167496, 956.6747305916022, 966.5457235099492, 992.4494050290683, 10993.0, 11422.371769552754, 11939.58211462612, 11942.880571194351, 11948.701282467833, 506700.0, 520053.0, 520555.0, 526299.0, 530516.0, 103023.0, 103752.0, 104297.0, 106978.97657675705, 107075.0, 997.3766396002499, 1019.1490105761771, 1055.2138656700806, 1078.5146683992234, 1087.690008347617, 19155.521111972314, 19388.0, 19538.21847126099, 19581.216751329786, 19626.0, 47618.285725714835, 48240.7587962072, 48968.0, 49380.753096234286, 50738.0, 66567.66716166388, 72595.63702181455, 77472.0, 79658.2034179674, 80452.59773701095, 8020.679172833022, 8057.717979870677, 8250.422470427013, 8365.372597055124, 8487.27858132041, 12409.937950310552, 12762.17045892014, 13022.679216799655, 13314.86685133175, 13356.666083347605, 10908.509117089592, 11303.773924521627, 11410.462756545498, 11461.770764584495, 11508.712282193012, 9584.0, 11002.834957475805, 11064.280821746883, 11205.495752690986, 11535.480903359537, 11196.776064478501, 11349.829752553553, 11562.595309164139, 11578.710532236424, 11781.352025638673, 8038.276555110068, 8111.553864537271, 8217.671293148447, 8248.876266856121, 8337.50241476492, 8217.378321887036, 10299.691009269734, 10774.838377424188, 11092.611758588411, 11139.72150696239], "value": 9509.033181940731, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1397.8182836231288, 1490.9403623855046, 1675.656490419464, 1851.9166637501312, 1874.746909167262, 7440.776776696708, 7871.2128787121255, 8306.293965012967, 8319.667213311475, 8333.541655208961, 7494.625268736555, 7666.425018123635, 7825.804354891116, 8055.1944805519415, 8072.31385332246, 2246.528229071897, 2306.5963456395066, 2306.9733968384076, 2312.2022902098834, 2312.9537409251807, 2028.5131568423546, 2311.803496702774, 2330.3009097270838, 2350.2831636350907, 2355.3169580821627, 1712.3921008042153, 1899.99300370803, 1975.8024197580198, 1976.505873531617, 1977.3079422202259, 2028.1785876719878, 2221.2669818959703, 2252.5945329840624, 2304.596695578267, 2305.204704376987, 2296.494771150344, 2305.204704376987, 2316.224064938248, 2327.918522851692, 2329.7087864017, 8133.983252093488, 8315.750527484186, 8316.126806685332, 8317.168283171635, 8320.500769953762, 4970.950290497085, 5151.6908985461, 5153.845384638467, 5159.7678104485385, 5163.7676304566385, 2982.522796352595, 3001.4897467430587, 3257.576515053028, 3421.4354631485776, 3499.177693242086, 986.2258127370052, 1017.3895662602454, 1047.1256500821746, 1055.2560444886412, 1055.3351388625117, 10554.677970846402, 10648.680539583826, 10662.840057399146, 10672.252942294042, 10701.625443109531, 4281.207976524353, 4504.977475112669, 4571.9085618287445, 4574.74838883858, 4587.908241835212, 8944.910550894409, 8947.373683842068, 8949.776255593662, 8955.641774328953, 8964.596593153325, 5083.313752643419, 5096.388433388017, 5203.687778733288, 5206.687598744012, 5207.609429292743, 4518.344839998182, 4713.599344055725, 4744.383230180055, 4745.477997420281, 4754.857354279377, 3763.1532905096683, 3788.8484460621817, 3825.5026846509777, 3865.1496670732395, 3889.922201555953, 1173.4719376280707, 1174.988250117488, 1175.5944199251244, 1190.2679851891025, 1192.5349113845607, 633.5280216238839, 758.5297115788246, 773.9651715672808, 874.6588830356039, 879.520661239626, 4436.511983681857, 5243.2659427680155, 5541.972290138524, 5545.364117585322, 5700.144978253213, 873.724776695339, 897.7441429192648, 910.8041771019263, 915.3272344826547, 964.1033838530234, 3625.6374362563533, 3673.9265214696093, 3740.60723624021, 3790.8104594770152, 3793.3740932745786, 9107.499087550115, 9855.211583073336, 9975.750606234757, 9985.900140998498, 9990.400575965465, 9740.561674724655, 9916.107550320507, 9944.602215911282, 9955.502224888727, 9959.751006224757, 7545.50954187976, 7797.064352277763, 7797.415193860483, 7811.140774514799, 7814.57019863913, 4801.567858892717, 5344.946550534445, 5478.726063696879, 5501.477359650827, 5543.8891222176135, 7649.426293027934, 7733.8839917400155, 9074.0, 9094.317926155432, 9202.49386283734, 9297.0, 9359.625614975597, 9371.390859594105, 9394.577244023898, 9494.71515854498, 6505.2518960318275, 6735.73057077731, 6744.0895479109695, 6751.628660423527, 6774.254831968381, 7588.0, 7653.6938522460705, 7697.576633284999, 7745.264199901, 7784.2215778421705, 7044.929550704427, 7090.610016448938, 7124.465665075244, 7134.32223938725, 7165.498415110892, 6312.0, 6801.149856267966, 6908.861822763617, 6942.305769423017, 6960.025596416404, 6254.874902502015, 6379.202599675041, 6385.96807015962, 6391.424771770563, 6394.2646595640235, 4605.815767369268, 4716.740579268173, 4752.952470475251, 4791.448983366958, 4827.203511420576, 4108.815103320298, 4785.808567657255, 4817.542333478314, 4868.805247790048, 4869.9026019480125], "value": 4286.672382163996, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [333.58968468783394, 341.97435192360564, 344.81040410583483, 367.358958617213, 367.87308378609373, 5254.57963362931, 5326.7070311132875, 5345.251664766931, 5347.893042139155, 5369.24830523727, 4190.721829841897, 4560.771961401933, 4991.725455099977, 4996.7751451184695, 5136.43499215087, 3979.482667253253, 4091.5908409158997, 4130.297849365615, 4142.917141657165, 4146.854860079898, 3913.8434462621462, 4110.527289361732, 4144.647704945083, 4180.372944058385, 4215.114825886568, 3156.942424287855, 3772.9434058489146, 3802.7338086333966, 4115.362118871565, 4163.562825903282, 4077.6330130288416, 4097.815598298083, 4103.753774773523, 4105.261053010442, 4126.381042843583, 3146.480830662938, 3618.9276214475562, 3676.3382591133586, 3694.242680250542, 3710.5547334319785, 4234.894127646803, 4925.38432695913, 5184.0, 5235.424103348629, 5255.737213139328, 3594.9101272468138, 4141.544430112685, 4650.837220697294, 4659.627229821606, 4708.9058218835435, 5476.041692703761, 5501.249375312344, 5518.475744804237, 5519.144532597454, 5590.776368945238, 3887.8833634990997, 4050.9467538440217, 4060.6954478414236, 4069.0061869683936, 4099.139180772041, 4897.8898399516565, 5760.452756988079, 5784.334801497839, 5796.797112101099, 5813.912791308049, 4053.9391909121387, 4077.4903137107863, 4081.510218773766, 4094.979525102415, 4106.568810274938, 3477.565304336958, 4004.8598299059395, 4132.710710250314, 4140.503139623204, 4148.8133034013545, 3738.794366309879, 4479.372887795711, 4644.558766917137, 4712.858614241578, 4724.574788269072, 5543.891221755584, 5544.390117087198, 5550.666959982414, 5573.627955087195, 5581.441855814467, 1123.9100871930225, 1202.8272434376588, 1283.8844503994499, 1335.2589312931416, 1350.9324533773272, 818.9231161023235, 843.9450686641698, 872.341382256405, 910.4582773249906, 958.5925981457852, 4914.410270767531, 4945.802167913315, 4975.701457912537, 4982.252662100714, 5027.673201241981, 4749.905001899942, 4960.702357858468, 5226.320578324793, 5393.8112166074, 5495.279836480968, 4244.787760611957, 4783.832565860248, 4892.510748925079, 5095.515925987025, 5174.767135478913, 2378.6550950112137, 2564.4228799288303, 2647.007372235412, 2655.9601605975927, 2821.6190814240226, 5195.948040519546, 5276.81531146408, 5527.281453411111, 5666.688332141772, 5690.4025077367105, 4856.8300109495995, 5077.892674540025, 5100.577225817707, 5451.427600102012, 5530.6128571000445, 4760.857174284777, 4932.8520144395725, 5236.0, 5545.833624991256, 5591.412901645269, 4734.621230301636, 5092.719900405513, 5219.303726288896, 5453.972730136483, 5479.315085614298, 4563.566461186182, 5035.874103147451, 5383.5154836063375, 5384.596155288291, 5391.892162156813, 4624.953750462452, 5445.673259604437, 5480.80817171413, 5637.267155269788, 5653.717314134195, 3576.7853928764357, 3580.6061333253842, 3598.6221446747713, 3606.5852426971232, 3621.855125795044, 4569.725816451024, 5011.84964451067, 5039.8488045357235, 5205.739713014258, 5770.019096753478, 4665.790039448165, 5512.767329231668, 5526.972365138148, 5573.6098473106495, 5621.0, 4782.871622128119, 5074.873128171826, 5350.279802791639, 5422.376426710979, 5487.588430867621, 4040.212158629039, 5105.4639262878345, 5185.688858668492, 5437.211604317273, 5506.201600767786, 4211.663066954698, 4585.839495617637, 4586.472555656009, 5408.0, 5436.3476382834315, 4730.266808644666, 5205.479452054764, 5365.887785084564, 5410.348929024828, 5444.3466783984595], "value": 3998.521917086298, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [613.3314686991179, 729.0340299103689, 735.3051366458699, 745.0128579631983, 816.841645011751, 14635.926820365896, 15056.247187640629, 15383.538493845175, 15443.613909652251, 15514.379424822993, 13929.373178206979, 14104.435822567111, 14110.223937683424, 14151.787723184161, 14158.079724817879, 852.957352132394, 876.4784952952983, 886.8891388576428, 887.973360799177, 889.8175873945826, 886.366248132585, 889.799795046116, 891.7859713668738, 892.4288455388556, 893.3746377535751, 935.3452583191759, 936.7517607833929, 937.9437233765962, 938.9108034736723, 939.3565407695751, 933.9392939458913, 934.887813462382, 934.9298802589765, 935.4247138010143, 935.5182081228173, 695.2803848017328, 695.5305169010898, 698.2005603583918, 698.2424069884182, 698.3016983016983, 14963.177025263607, 14979.850201497955, 15014.324355403924, 15043.473478428312, 15046.699066018728, 13541.987922441003, 13701.520446784314, 14017.64955876101, 14075.648108797259, 14145.646358841008, 11017.173711971554, 11481.483333250024, 11594.0, 11598.768024639545, 12134.939325303318, 4267.850625228133, 4514.255147900592, 4586.082783443325, 4607.14767767964, 4705.529447055302, 101797.49101254446, 102499.97500024979, 106561.0, 107844.0, 107874.46062769715, 9853.753656158653, 10183.898161018444, 10705.521167622077, 10729.248952573245, 10786.137109031415, 19431.902840485705, 20541.281055163, 20570.97145142737, 21041.05315260817, 21074.0, 7235.529690570202, 7320.1581818090535, 7353.301436363537, 7532.962335188289, 7694.730684426018, 4314.115606300701, 4317.400189403342, 4386.144701783185, 4442.666799989949, 4526.841560545365, 3473.0449126490416, 3545.7163426925786, 3549.4853246279663, 3581.7134629230122, 3582.731295152874, 629.361198383641, 635.548760380126, 642.5662677692633, 645.1065274594627, 646.683125268615, 547.8904219156105, 642.5502148496072, 670.886328694365, 680.8740383029156, 683.2893790457869, 7531.435142364345, 8598.699045533502, 8679.609417576225, 9068.22920051803, 9199.632014719336, 640.2860810196597, 661.3452681845035, 663.8639078988892, 670.6043434373743, 704.126882665491, 4277.443932288783, 4345.587169218919, 4577.771111444415, 4589.35748995141, 4715.929261061086, 84821.0, 93151.53424232836, 97759.0, 100026.0, 103995.4800225994, 72231.6388418065, 88333.55833220793, 90807.0, 92582.0, 93535.0, 60014.699926500085, 80451.99999999884, 82360.0, 83713.0, 84070.0, 7388.519746216479, 8423.904892363953, 8588.527630980356, 9040.186383225542, 9260.490673012779, 35800.104997375274, 46990.76504617455, 51615.48384516107, 53156.46843531515, 54483.72758136184, 67431.66284168548, 67770.0, 68311.65844170748, 68380.0, 69279.65360173167, 10596.576136954436, 12827.551035713705, 13059.281739504419, 13068.281244531641, 13558.0, 13083.803742944054, 14486.782698259323, 14770.704585908437, 14862.405503779728, 15252.0086194397, 13631.250281234627, 13723.725525489634, 13851.515196968057, 14168.645783855485, 14296.428142874169, 13361.933190333986, 13607.387667554785, 13745.450181992608, 14066.437342506186, 14144.717105657623, 12884.549040783528, 13361.732765344834, 13701.177929324273, 13780.724385512032, 13959.162450252612, 11077.0, 11141.16441266892, 11359.0, 11608.24546404481, 11695.76608467843, 9706.0, 11182.776344472903, 11554.884451155382, 11592.6522204334, 11640.5343786251], "value": 7456.045866186275, "unit": "score"}], "name": "dromaeo_css", "value": 7139.664006098935, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 113.0, 114.0, 113.0, 113.0, 114.0, 120.0, 168.0, 161.0, 114.0], "value": 124.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 140.0, 138.0, 147.0, 133.0, 100.0, 132.0, 136.0, 161.0, 113.0], "value": 138.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [180.0, 183.0, 186.0, 185.0, 182.0, 181.0, 182.0, 182.0, 180.0, 181.0], "value": 182.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 59.0, 64.0, 76.0, 53.0, 80.0, 81.0, 80.0, 73.0, 58.0], "value": 70.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 70.0, 85.0, 68.0, 69.0, 69.0, 83.0, 69.0, 68.0, 83.0], "value": 75.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 87.0, 89.0, 88.0, 89.0, 86.0, 89.0, 87.0, 91.0, 84.0], "value": 87.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 151.0, 148.0, 96.0, 94.0, 95.0, 95.0, 96.0, 95.0, 95.0], "value": 107.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 84.0, 87.0, 87.0, 84.0, 87.0, 85.0, 88.0, 85.0, 85.0], "value": 85.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 55.0, 54.0, 53.0, 56.0, 55.0, 54.0, 55.0, 54.0, 55.0], "value": 54.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 76.0, 70.0, 69.0, 77.0, 71.0, 69.0, 73.0, 71.0, 71.0], "value": 71.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 85.0, 88.0, 102.0, 106.0, 87.0, 85.0, 87.0, 85.0, 90.0], "value": 90.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 145.0, 146.0, 150.0, 138.0, 129.0, 142.0, 150.0, 205.0, 176.0], "value": 152.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [216.0, 171.0, 172.0, 176.0, 171.0, 170.0, 171.0, 168.0, 169.0, 172.0], "value": 175.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 68.0, 67.0, 66.0, 67.0, 66.0, 68.0, 65.0, 66.0, 66.0], "value": 66.5, "unit": "score"}], "name": "kraken", "value": 1481.8, "alertThreshold": 2.0}]} 16:14:36 INFO - Return code: 0 16:14:36 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 16:14:36 INFO - # TBPL SUCCESS # 16:14:36 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 16:14:36 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 16:14:36 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 16:14:36 INFO - 2016-12-23 16:14:36.307 screenresolution[1656:10931] starting screenresolution argv=screenresolution get 16:14:36 INFO - 2016-12-23 16:14:36.309 screenresolution[1656:10931] Display 0: 1600x1200x32@60 16:14:36 INFO - 2016-12-23 16:14:36.316 screenresolution[1657:10934] starting screenresolution argv=screenresolution list 16:14:36 INFO - Available Modes on Display 0 16:14:36 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 16:14:36 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 16:14:36 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 16:14:36 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 16:14:36 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 16:14:36 INFO - Intel Iris: 16:14:36 INFO - Chipset Model: Intel Iris 16:14:36 INFO - Type: GPU 16:14:36 INFO - Bus: Built-In 16:14:36 INFO - VRAM (Dynamic, Max): 1536 MB 16:14:36 INFO - Vendor: Intel (0x8086) 16:14:36 INFO - Device ID: 0x0a2e 16:14:36 INFO - Revision ID: 0x0009 16:14:36 INFO - Displays: 16:14:36 INFO - Display: 16:14:36 INFO - Resolution: 1600 x 1200 @ 60 Hz 16:14:36 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 16:14:36 INFO - Display Serial Number: 1600x1200 60 16:14:36 INFO - Main Display: Yes 16:14:36 INFO - Mirror: Off 16:14:36 INFO - Online: Yes 16:14:36 INFO - Rotation: Supported 16:14:36 INFO - Return code: 0 16:14:36 INFO - Running post-action listener: _resource_record_post_action 16:14:36 INFO - [mozharness: 2016-12-24 00:14:36.552678Z] Finished run-tests step (success) 16:14:36 INFO - Running post-run listener: _resource_record_post_run 16:14:36 INFO - Total resource usage - Wall time: 1203s; CPU: 54.0%; Read bytes: 32250880; Write bytes: 551864832; Read time: 195; Write time: 1710 16:14:36 INFO - TinderboxPrint: CPU usage
53.7% 16:14:36 INFO - TinderboxPrint: I/O read bytes / time
32,250,880 / 195 16:14:36 INFO - TinderboxPrint: I/O write bytes / time
551,864,832 / 1,710 16:14:36 INFO - TinderboxPrint: CPU idle
2,230.6 (46.3%) 16:14:36 INFO - TinderboxPrint: CPU system
960.4 (20.0%) 16:14:36 INFO - TinderboxPrint: CPU user
1,622.7 (33.7%) 16:14:36 INFO - TinderboxPrint: Swap in / out
278,519,808 / 0 16:14:36 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 245742080; Write bytes: 239040000; Read time: 18096; Write time: 530 16:14:36 INFO - run-tests - Wall time: 1182s; CPU: 54.0%; Read bytes: 29084672; Write bytes: 299078656; Read time: 175; Write time: 1145 16:14:37 INFO - Running post-run listener: _upload_blobber_files 16:14:37 INFO - Blob upload gear active. 16:14:37 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 16:14:37 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 16:14:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 16:14:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 16:14:37 INFO - (blobuploader) - INFO - Open directory for files ... 16:14:37 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 16:14:37 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:14:37 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:14:38 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 16:14:38 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:14:38 INFO - (blobuploader) - INFO - Done attempting. 16:14:38 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 16:14:38 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:14:38 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:14:39 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 16:14:39 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:14:39 INFO - (blobuploader) - INFO - Done attempting. 16:14:39 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 16:14:39 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:14:39 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:14:40 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 16:14:40 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:14:40 INFO - (blobuploader) - INFO - Done attempting. 16:14:40 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 16:14:40 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:14:40 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:14:42 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 16:14:42 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:14:42 INFO - (blobuploader) - INFO - Done attempting. 16:14:42 INFO - (blobuploader) - INFO - Iteration through files over. 16:14:42 INFO - Return code: 0 16:14:42 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 16:14:42 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 16:14:42 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/610ca771a1a649bd6a227c5eb257afd7c932a5ee9954dabc1acb910dc80e4565723331b187d227dad2534573d7f447e2ee663fde7f25669c634da6d9e05ce098", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/fb16e5b1e4d58de2b0a4b9d2ee79f52105388c600ecc3844fc1f617050b6fa6e07417990b79e17af911dcb011bf42a6cf3d23ea632d1a358a7b4ac47248202b0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5b49909f1bb04e1ca6012e78a4e46a5f054432e6e813e5ed0b48608f9e3d5802773a367798e0b9efa960abdc2b130f424d979602931eb30ea7700e192c4ca4d5", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5d0057e8bd2d3d5da23adac6227ec91926ef53b8cb249aae14ee6f7a92461549074270cc3d4536b52216c0152e549284ead4bccf8f870279ba82d0c4b73edf65"} 16:14:42 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 16:14:42 INFO - Writing to file /builds/slave/test/properties/blobber_files 16:14:42 INFO - Contents: 16:14:42 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/610ca771a1a649bd6a227c5eb257afd7c932a5ee9954dabc1acb910dc80e4565723331b187d227dad2534573d7f447e2ee663fde7f25669c634da6d9e05ce098", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/fb16e5b1e4d58de2b0a4b9d2ee79f52105388c600ecc3844fc1f617050b6fa6e07417990b79e17af911dcb011bf42a6cf3d23ea632d1a358a7b4ac47248202b0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5b49909f1bb04e1ca6012e78a4e46a5f054432e6e813e5ed0b48608f9e3d5802773a367798e0b9efa960abdc2b130f424d979602931eb30ea7700e192c4ca4d5", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5d0057e8bd2d3d5da23adac6227ec91926ef53b8cb249aae14ee6f7a92461549074270cc3d4536b52216c0152e549284ead4bccf8f870279ba82d0c4b73edf65"} 16:14:42 INFO - Running post-run listener: copy_logs_to_upload_dir 16:14:42 INFO - Copying logs to upload dir... 16:14:42 INFO - mkdir: /builds/slave/test/build/upload/logs 16:14:42 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1265.153899 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 5 secs) (at 2016-12-23 16:14:42.709680) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-23 16:14:42.713314) ========= 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.7L1P6PQ1Hl/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.nIP4upsNEJ/Listeners TMPDIR=/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/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_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/610ca771a1a649bd6a227c5eb257afd7c932a5ee9954dabc1acb910dc80e4565723331b187d227dad2534573d7f447e2ee663fde7f25669c634da6d9e05ce098", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/fb16e5b1e4d58de2b0a4b9d2ee79f52105388c600ecc3844fc1f617050b6fa6e07417990b79e17af911dcb011bf42a6cf3d23ea632d1a358a7b4ac47248202b0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5b49909f1bb04e1ca6012e78a4e46a5f054432e6e813e5ed0b48608f9e3d5802773a367798e0b9efa960abdc2b130f424d979602931eb30ea7700e192c4ca4d5", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5d0057e8bd2d3d5da23adac6227ec91926ef53b8cb249aae14ee6f7a92461549074270cc3d4536b52216c0152e549284ead4bccf8f870279ba82d0c4b73edf65"} build_url:https://queue.taskcluster.net/v1/task/LdxC6YjcTGGLp2maRH7HQA/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010946 build_url: 'https://queue.taskcluster.net/v1/task/LdxC6YjcTGGLp2maRH7HQA/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/610ca771a1a649bd6a227c5eb257afd7c932a5ee9954dabc1acb910dc80e4565723331b187d227dad2534573d7f447e2ee663fde7f25669c634da6d9e05ce098", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/fb16e5b1e4d58de2b0a4b9d2ee79f52105388c600ecc3844fc1f617050b6fa6e07417990b79e17af911dcb011bf42a6cf3d23ea632d1a358a7b4ac47248202b0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5b49909f1bb04e1ca6012e78a4e46a5f054432e6e813e5ed0b48608f9e3d5802773a367798e0b9efa960abdc2b130f424d979602931eb30ea7700e192c4ca4d5", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5d0057e8bd2d3d5da23adac6227ec91926ef53b8cb249aae14ee6f7a92461549074270cc3d4536b52216c0152e549284ead4bccf8f870279ba82d0c4b73edf65"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-23 16:14:42.748224) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-23 16:14:42.748591) ========= 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.7L1P6PQ1Hl/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.nIP4upsNEJ/Listeners TMPDIR=/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/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.005187 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-23 16:14:42.798478) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-23 16:14:42.798853) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-23 16:14:42.799252) ========= ========= Total master_lag: 0.18 =========