builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0353 starttime: 1487869123.5 results: success (0) buildid: 20170223073723 builduid: 195e179e7d724298ab55d7e7b9df7433 revision: 22ec1dab9e821676f4204d36ce9801803032f504 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-02-23 08:58:43.498689) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-02-23 08:58:43.499228) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-23 08:58:43.499561) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-23 08:58:43.523984) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-23 08:58:43.524295) ========= 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.MlSm6Gtn1j/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.zdnglL2ycc/Listeners TMPDIR=/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-02-23 08:58:43-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 60.5M=0s 2017-02-23 08:58:43 (60.5 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.116996 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-23 08:58:43.669148) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-23 08:58:43.670053) ========= 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.MlSm6Gtn1j/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.zdnglL2ycc/Listeners TMPDIR=/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/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.021254 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-23 08:58:43.721985) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-02-23 08:58:43.722425) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 22ec1dab9e821676f4204d36ce9801803032f504 --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 22ec1dab9e821676f4204d36ce9801803032f504 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.MlSm6Gtn1j/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.zdnglL2ycc/Listeners TMPDIR=/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-02-23 08:58:43,812 truncating revision to first 12 chars 2017-02-23 08:58:43,812 Setting DEBUG logging. 2017-02-23 08:58:43,813 attempt 1/10 2017-02-23 08:58:43,813 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/22ec1dab9e82?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-02-23 08:58:45,548 unpacking tar archive at: mozilla-central-22ec1dab9e82/testing/mozharness/ program finished with exit code 0 elapsedTime=1.995678 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-02-23 08:58:45.741908) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-23 08:58:45.742344) ========= script_repo_revision: 22ec1dab9e821676f4204d36ce9801803032f504 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-23 08:58:45.742854) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-23 08:58:45.743206) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-23 08:58:45.762238) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 21 secs) (at 2017-02-23 08:58:45.762592) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.MlSm6Gtn1j/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.zdnglL2ycc/Listeners TMPDIR=/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 08:58:45 INFO - MultiFileLogger online at 20170223 08:58:45 in /builds/slave/test 08:58:45 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 08:58:45 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 08:58:45 INFO - {'append_to_log': False, 08:58:45 INFO - 'base_work_dir': '/builds/slave/test', 08:58:45 INFO - 'blob_upload_branch': 'Firefox', 08:58:45 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 08:58:45 INFO - 'branch': 'Firefox', 08:58:45 INFO - 'buildbot_json_path': 'buildprops.json', 08:58:45 INFO - 'config_files': ('talos/mac_config.py',), 08:58:45 INFO - 'default_actions': ('clobber', 08:58:45 INFO - 'read-buildbot-config', 08:58:45 INFO - 'download-and-extract', 08:58:45 INFO - 'populate-webroot', 08:58:45 INFO - 'create-virtualenv', 08:58:45 INFO - 'install', 08:58:45 INFO - 'run-tests'), 08:58:45 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 08:58:45 INFO - 'download_minidump_stackwalk': True, 08:58:45 INFO - 'download_symbols': 'ondemand', 08:58:45 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 08:58:45 INFO - 'tooltool.py': '/tools/tooltool.py', 08:58:45 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 08:58:45 INFO - '/tools/misc-python/virtualenv.py')}, 08:58:45 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 08:58:45 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 08:58:45 INFO - 'gecko_profile': False, 08:58:45 INFO - 'gecko_profile_interval': 0, 08:58:45 INFO - 'installer_path': 'installer.exe', 08:58:45 INFO - 'log_level': 'info', 08:58:45 INFO - 'log_name': 'talos', 08:58:45 INFO - 'log_to_console': True, 08:58:45 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 08:58:45 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 08:58:45 INFO - 'opt_config_files': (), 08:58:45 INFO - 'pip_index': False, 08:58:45 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:58:45 INFO - 'cmd': ('bash', 08:58:45 INFO - '-c', 08:58:45 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:58:45 INFO - 'enabled': True, 08:58:45 INFO - 'halt_on_failure': False, 08:58:45 INFO - 'name': 'check_screen_resolution'},), 08:58:45 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:58:45 INFO - 'cmd': ('bash', 08:58:45 INFO - '-c', 08:58:45 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:58:45 INFO - 'enabled': True, 08:58:45 INFO - 'halt_on_failure': False, 08:58:45 INFO - 'name': 'check_screen_resolution'},), 08:58:45 INFO - 'run_cmd_checks_enabled': True, 08:58:45 INFO - 'suite': 'dromaeojs-e10s', 08:58:45 INFO - 'system_bits': '32', 08:58:45 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 08:58:45 INFO - 'title': 't-yosemite-r7-0353', 08:58:45 INFO - 'tooltool_cache': '/builds/tooltool_cache', 08:58:45 INFO - 'use_talos_json': True, 08:58:45 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 08:58:45 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 08:58:45 INFO - 'work_dir': 'build'} 08:58:45 INFO - [mozharness: 2017-02-23 16:58:45.971806Z] Running clobber step. 08:58:45 INFO - Running pre-action listener: _resource_record_pre_action 08:58:45 INFO - Running main action method: clobber 08:58:45 INFO - rmtree: /builds/slave/test/build 08:58:45 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 08:58:47 INFO - Running post-action listener: _resource_record_post_action 08:58:47 INFO - [mozharness: 2017-02-23 16:58:47.820840Z] Finished clobber step (success) 08:58:47 INFO - [mozharness: 2017-02-23 16:58:47.820965Z] Running read-buildbot-config step. 08:58:47 INFO - Running pre-action listener: _resource_record_pre_action 08:58:47 INFO - Running main action method: read_buildbot_config 08:58:47 INFO - Using buildbot properties: 08:58:47 INFO - { 08:58:47 INFO - "project": "", 08:58:47 INFO - "product": "firefox", 08:58:47 INFO - "script_repo_revision": "production", 08:58:47 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 08:58:47 INFO - "repository": "", 08:58:47 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 08:58:47 INFO - "buildid": "20170223073723", 08:58:47 INFO - "pgo_build": "False", 08:58:47 INFO - "basedir": "/builds/slave/test", 08:58:47 INFO - "buildnumber": 6, 08:58:47 INFO - "slavename": "t-yosemite-r7-0353", 08:58:47 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 08:58:47 INFO - "platform": "yosemite_r7", 08:58:47 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 08:58:47 INFO - "branch": "mozilla-central", 08:58:47 INFO - "repo_path": "mozilla-central", 08:58:47 INFO - "revision": "22ec1dab9e821676f4204d36ce9801803032f504", 08:58:47 INFO - "stage_platform": "macosx64", 08:58:47 INFO - "builduid": "195e179e7d724298ab55d7e7b9df7433", 08:58:47 INFO - "slavebuilddir": "test" 08:58:47 INFO - } 08:58:47 INFO - Found installer url https://queue.taskcluster.net/v1/task/Tsg-KtrqQ-G1VABHyuSZ4Q/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg. 08:58:47 INFO - Running post-action listener: _resource_record_post_action 08:58:47 INFO - [mozharness: 2017-02-23 16:58:47.824226Z] Finished read-buildbot-config step (success) 08:58:47 INFO - [mozharness: 2017-02-23 16:58:47.824332Z] Running download-and-extract step. 08:58:47 INFO - Running pre-action listener: _resource_record_pre_action 08:58:47 INFO - Running main action method: download_and_extract 08:58:47 INFO - mkdir: /builds/slave/test/build/tests 08:58:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:58:47 INFO - trying https://queue.taskcluster.net/v1/task/Tsg-KtrqQ-G1VABHyuSZ4Q/artifacts/public/build/firefox-54.0a1.en-US.mac.test_packages.json 08:58:47 INFO - Downloading https://queue.taskcluster.net/v1/task/Tsg-KtrqQ-G1VABHyuSZ4Q/artifacts/public/build/firefox-54.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-54.0a1.en-US.mac.test_packages.json 08:58:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Tsg-KtrqQ-G1VABHyuSZ4Q/artifacts/public/build/firefox-54.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-54.0a1.en-US.mac.test_packages.json'}, attempt #1 08:58:49 INFO - Downloaded 1199 bytes. 08:58:49 INFO - Reading from file /builds/slave/test/build/firefox-54.0a1.en-US.mac.test_packages.json 08:58:49 INFO - Using the following test package requirements: 08:58:49 INFO - {u'common': [u'firefox-54.0a1.en-US.mac.common.tests.zip'], 08:58:49 INFO - u'cppunittest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 08:58:49 INFO - u'firefox-54.0a1.en-US.mac.cppunittest.tests.zip'], 08:58:49 INFO - u'gtest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 08:58:49 INFO - u'firefox-54.0a1.en-US.mac.gtest.tests.zip'], 08:58:49 INFO - u'jittest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 08:58:49 INFO - u'jsshell-mac.zip'], 08:58:49 INFO - u'mochitest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 08:58:49 INFO - u'firefox-54.0a1.en-US.mac.mochitest.tests.zip'], 08:58:49 INFO - u'mozbase': [u'firefox-54.0a1.en-US.mac.common.tests.zip'], 08:58:49 INFO - u'reftest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 08:58:49 INFO - u'firefox-54.0a1.en-US.mac.reftest.tests.zip'], 08:58:49 INFO - u'talos': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 08:58:49 INFO - u'firefox-54.0a1.en-US.mac.talos.tests.zip'], 08:58:49 INFO - u'web-platform': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 08:58:49 INFO - u'firefox-54.0a1.en-US.mac.web-platform.tests.zip'], 08:58:49 INFO - u'xpcshell': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 08:58:49 INFO - u'firefox-54.0a1.en-US.mac.xpcshell.tests.zip']} 08:58:49 INFO - Downloading packages: [u'firefox-54.0a1.en-US.mac.common.tests.zip'] for test suite category: common 08:58:49 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Tsg-KtrqQ-G1VABHyuSZ4Q/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip 08:58:49 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Tsg-KtrqQ-G1VABHyuSZ4Q/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip'}, attempt #1 08:58:49 INFO - Fetch https://queue.taskcluster.net/v1/task/Tsg-KtrqQ-G1VABHyuSZ4Q/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip into memory 08:58:50 INFO - Expected file size: 51450553 08:58:50 INFO - Obtained file size: 51450553 08:59:06 INFO - Downloading packages: [u'firefox-54.0a1.en-US.mac.common.tests.zip', u'firefox-54.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 08:59:06 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Tsg-KtrqQ-G1VABHyuSZ4Q/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip 08:59:06 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Tsg-KtrqQ-G1VABHyuSZ4Q/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip'}, attempt #1 08:59:06 INFO - Fetch https://queue.taskcluster.net/v1/task/Tsg-KtrqQ-G1VABHyuSZ4Q/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip into memory 08:59:08 INFO - Expected file size: 51450553 08:59:08 INFO - Obtained file size: 51450553 08:59:23 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Tsg-KtrqQ-G1VABHyuSZ4Q/artifacts/public/build/firefox-54.0a1.en-US.mac.talos.tests.zip 08:59:23 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Tsg-KtrqQ-G1VABHyuSZ4Q/artifacts/public/build/firefox-54.0a1.en-US.mac.talos.tests.zip'}, attempt #1 08:59:23 INFO - Fetch https://queue.taskcluster.net/v1/task/Tsg-KtrqQ-G1VABHyuSZ4Q/artifacts/public/build/firefox-54.0a1.en-US.mac.talos.tests.zip into memory 08:59:24 INFO - Expected file size: 14404306 08:59:24 INFO - Obtained file size: 14404306 08:59:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:59:24 INFO - trying https://queue.taskcluster.net/v1/task/Tsg-KtrqQ-G1VABHyuSZ4Q/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg 08:59:24 INFO - Downloading https://queue.taskcluster.net/v1/task/Tsg-KtrqQ-G1VABHyuSZ4Q/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-54.0a1.en-US.mac.dmg 08:59:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Tsg-KtrqQ-G1VABHyuSZ4Q/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-54.0a1.en-US.mac.dmg'}, attempt #1 08:59:26 INFO - Downloaded 74935184 bytes. 08:59:26 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Tsg-KtrqQ-G1VABHyuSZ4Q/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg 08:59:26 INFO - mkdir: /builds/slave/test/properties 08:59:26 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 08:59:26 INFO - Writing to file /builds/slave/test/properties/build_url 08:59:26 INFO - Contents: 08:59:26 INFO - build_url:https://queue.taskcluster.net/v1/task/Tsg-KtrqQ-G1VABHyuSZ4Q/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg 08:59:27 INFO - Running post-action listener: _resource_record_post_action 08:59:27 INFO - Running post-action listener: set_extra_try_arguments 08:59:27 INFO - [mozharness: 2017-02-23 16:59:27.704272Z] Finished download-and-extract step (success) 08:59:27 INFO - [mozharness: 2017-02-23 16:59:27.704424Z] Running populate-webroot step. 08:59:27 INFO - Running pre-action listener: _resource_record_pre_action 08:59:27 INFO - Running main action method: populate_webroot 08:59:27 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 08:59:27 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 08:59:27 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 08:59:27 INFO - '--tppagecycles', 08:59:27 INFO - '7'], 08:59:27 INFO - 'tests': ['tsvgm']}}, 08:59:27 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 08:59:27 INFO - 'tests': ['tresize', 'tcanvasmark']}, 08:59:27 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 08:59:27 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 08:59:27 INFO - 'tests': ['dromaeo_css', 'kraken']}, 08:59:27 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 08:59:27 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 08:59:27 INFO - 'talos_options': ['--disable-e10s'], 08:59:27 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:59:27 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 08:59:27 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:59:27 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 08:59:27 INFO - 'talos_options': ['--disable-e10s'], 08:59:27 INFO - 'tests': ['damp', 'tps']}, 08:59:27 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 08:59:27 INFO - 'tests': ['damp', 'tps']}, 08:59:27 INFO - 'g3': {'talos_options': ['--disable-e10s'], 08:59:27 INFO - 'tests': ['dromaeo_dom']}, 08:59:27 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 08:59:27 INFO - 'g4': {'talos_options': ['--disable-e10s'], 08:59:27 INFO - 'tests': ['basic_compositor_video']}, 08:59:27 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 08:59:27 INFO - 'other': {'talos_options': ['--disable-e10s'], 08:59:27 INFO - 'tests': ['a11yr', 08:59:27 INFO - 'ts_paint', 08:59:27 INFO - 'tpaint', 08:59:27 INFO - 'sessionrestore', 08:59:27 INFO - 'sessionrestore_no_auto_restore', 08:59:27 INFO - 'tabpaint']}, 08:59:27 INFO - 'other-e10s': {'tests': ['a11yr', 08:59:27 INFO - 'ts_paint', 08:59:27 INFO - 'tpaint', 08:59:27 INFO - 'sessionrestore', 08:59:27 INFO - 'sessionrestore_no_auto_restore', 08:59:27 INFO - 'tabpaint']}, 08:59:27 INFO - 'other-e10s_l64': {'tests': ['a11yr', 08:59:27 INFO - 'ts_paint', 08:59:27 INFO - 'tpaint', 08:59:27 INFO - 'sessionrestore', 08:59:27 INFO - 'sessionrestore_no_auto_restore', 08:59:27 INFO - 'tabpaint']}, 08:59:27 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 08:59:27 INFO - 'ts_paint', 08:59:27 INFO - 'tpaint', 08:59:27 INFO - 'sessionrestore', 08:59:27 INFO - 'sessionrestore_no_auto_restore', 08:59:27 INFO - 'tabpaint']}, 08:59:27 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 08:59:27 INFO - 'tests': ['a11yr', 08:59:27 INFO - 'ts_paint', 08:59:27 INFO - 'tpaint', 08:59:27 INFO - 'sessionrestore', 08:59:27 INFO - 'sessionrestore_no_auto_restore', 08:59:27 INFO - 'tabpaint']}, 08:59:27 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 08:59:27 INFO - 'tests': ['a11yr', 08:59:27 INFO - 'ts_paint', 08:59:27 INFO - 'tpaint', 08:59:27 INFO - 'sessionrestore', 08:59:27 INFO - 'sessionrestore_no_auto_restore', 08:59:27 INFO - 'tabpaint']}, 08:59:27 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 08:59:27 INFO - 'tests': ['tsvgx', 08:59:27 INFO - 'tsvgr_opacity', 08:59:27 INFO - 'tart', 08:59:27 INFO - 'tscrollx', 08:59:27 INFO - 'cart', 08:59:27 INFO - 'tsvg_static']}, 08:59:27 INFO - 'svgr-e10s': {'tests': ['tsvgx', 08:59:27 INFO - 'tsvgr_opacity', 08:59:27 INFO - 'tart', 08:59:27 INFO - 'tscrollx', 08:59:27 INFO - 'cart', 08:59:27 INFO - 'tsvg_static']}, 08:59:27 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 08:59:27 INFO - 'talos_options': ['--disable-e10s'], 08:59:27 INFO - 'tests': ['tp5o']}, 08:59:27 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 08:59:27 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 08:59:27 INFO - 'talos_options': ['--disable-e10s', 08:59:27 INFO - '--xperf_path', 08:59:27 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:59:27 INFO - 'tests': ['tp5n']}, 08:59:27 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 08:59:27 INFO - 'talos_options': ['--xperf_path', 08:59:27 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:59:27 INFO - 'tests': ['tp5n']}}, 08:59:27 INFO - 'talos.zip': {'path': '', 08:59:27 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 08:59:27 INFO - Running post-action listener: _resource_record_post_action 08:59:27 INFO - [mozharness: 2017-02-23 16:59:27.723111Z] Finished populate-webroot step (success) 08:59:27 INFO - [mozharness: 2017-02-23 16:59:27.723258Z] Running create-virtualenv step. 08:59:27 INFO - Running pre-action listener: _resource_record_pre_action 08:59:27 INFO - Running main action method: create_virtualenv 08:59:27 INFO - Creating virtualenv /builds/slave/test/build/venv 08:59:27 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 08:59:27 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 08:59:27 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 08:59:27 INFO - Using real prefix '/tools/python27' 08:59:27 INFO - New python executable in /builds/slave/test/build/venv/bin/python 08:59:28 INFO - Installing distribute.............................................................................................................................................................................................done. 08:59:31 INFO - Installing pip.................done. 08:59:31 INFO - Return code: 0 08:59:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 08:59:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 08:59:31 INFO - Reading from file tmpfile_stdout 08:59:31 INFO - Output received: 08:59:31 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 08:59:31 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 08:59: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')]} 08:59:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:59:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:59: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')]} 08:59:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:59:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:59:31 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 0x10bf12b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bf15768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8912495870>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bcf0d30>, '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 0x10bc17db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bce5be8>, '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.MlSm6Gtn1j/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.zdnglL2ycc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 08:59:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 08:59:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 08:59:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MlSm6Gtn1j/Render', 08:59:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:59:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:59:31 INFO - 'HOME': '/Users/cltbld', 08:59:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:59:31 INFO - 'LOGNAME': 'cltbld', 08:59:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:59:31 INFO - 'MOZ_NO_REMOTE': '1', 08:59:31 INFO - 'NO_EM_RESTART': '1', 08:59:31 INFO - 'PAGER': '/bin/cat', 08:59:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:59:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:59:31 INFO - 'PWD': '/builds/slave/test', 08:59:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:59:31 INFO - 'SHELL': '/bin/bash', 08:59:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zdnglL2ycc/Listeners', 08:59:31 INFO - 'TMPDIR': '/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/', 08:59:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:59:31 INFO - 'USER': 'cltbld', 08:59:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:59:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:59:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:59:31 INFO - 'XPC_FLAGS': '0x0', 08:59:31 INFO - 'XPC_SERVICE_NAME': '0', 08:59:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:59:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:59:31 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 08:59:31 INFO - Cleaning up... 08:59:31 INFO - Return code: 0 08:59:31 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 08:59: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')]} 08:59:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:59:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:59: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')]} 08:59:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:59:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:59:31 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 0x10bf12b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bf15768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8912495870>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bcf0d30>, '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 0x10bc17db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bce5be8>, '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.MlSm6Gtn1j/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.zdnglL2ycc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 08:59:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 08:59:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 08:59:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MlSm6Gtn1j/Render', 08:59:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:59:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:59:31 INFO - 'HOME': '/Users/cltbld', 08:59:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:59:31 INFO - 'LOGNAME': 'cltbld', 08:59:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:59:31 INFO - 'MOZ_NO_REMOTE': '1', 08:59:31 INFO - 'NO_EM_RESTART': '1', 08:59:31 INFO - 'PAGER': '/bin/cat', 08:59:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:59:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:59:31 INFO - 'PWD': '/builds/slave/test', 08:59:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:59:31 INFO - 'SHELL': '/bin/bash', 08:59:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zdnglL2ycc/Listeners', 08:59:31 INFO - 'TMPDIR': '/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/', 08:59:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:59:31 INFO - 'USER': 'cltbld', 08:59:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:59:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:59:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:59:31 INFO - 'XPC_FLAGS': '0x0', 08:59:31 INFO - 'XPC_SERVICE_NAME': '0', 08:59:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:59:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:59:32 INFO - Downloading/unpacking psutil>=3.1.1 08:59:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:59:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:59:32 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:59:32 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:59:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:59:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:59:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 08:59:35 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:59:35 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:59:35 INFO - Installing collected packages: psutil 08:59:35 INFO - Running setup.py install for psutil 08:59:35 INFO - building 'psutil._psutil_osx' extension 08:59:35 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 08:59:36 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 08:59:36 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 08:59:36 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 08:59:36 INFO - building 'psutil._psutil_posix' extension 08:59:36 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 08:59:36 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 08:59:36 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 08:59:36 INFO - ^ 08:59:36 INFO - 1 warning generated. 08:59:36 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 08:59:36 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:59:36 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:59:36 INFO - Successfully installed psutil 08:59:36 INFO - Cleaning up... 08:59:36 INFO - Return code: 0 08:59:36 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 08:59:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:59:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:59:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:59:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:59:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:59:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:59:36 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10bf12b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bf15768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8912495870>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bcf0d30>, '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 0x10bc17db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bce5be8>, '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.MlSm6Gtn1j/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.zdnglL2ycc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 08:59:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 08:59:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 08:59:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MlSm6Gtn1j/Render', 08:59:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:59:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:59:36 INFO - 'HOME': '/Users/cltbld', 08:59:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:59:36 INFO - 'LOGNAME': 'cltbld', 08:59:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:59:36 INFO - 'MOZ_NO_REMOTE': '1', 08:59:36 INFO - 'NO_EM_RESTART': '1', 08:59:36 INFO - 'PAGER': '/bin/cat', 08:59:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:59:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:59:36 INFO - 'PWD': '/builds/slave/test', 08:59:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:59:36 INFO - 'SHELL': '/bin/bash', 08:59:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zdnglL2ycc/Listeners', 08:59:36 INFO - 'TMPDIR': '/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/', 08:59:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:59:36 INFO - 'USER': 'cltbld', 08:59:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:59:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:59:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:59:36 INFO - 'XPC_FLAGS': '0x0', 08:59:36 INFO - 'XPC_SERVICE_NAME': '0', 08:59:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:59:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:59:36 INFO - Downloading/unpacking mozsystemmonitor==0.3 08:59:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:59:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:59:36 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:59:36 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:59:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:59:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:59:39 INFO - Downloading mozsystemmonitor-0.3.tar.gz 08:59:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 08:59:39 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 08:59:39 INFO - Installing collected packages: mozsystemmonitor 08:59:39 INFO - Running setup.py install for mozsystemmonitor 08:59:39 INFO - Successfully installed mozsystemmonitor 08:59:39 INFO - Cleaning up... 08:59:39 INFO - Return code: 0 08:59:39 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 08:59:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:59:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:59:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:59:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:59:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:59:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:59:40 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 0x10bf12b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bf15768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8912495870>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bcf0d30>, '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 0x10bc17db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bce5be8>, '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.MlSm6Gtn1j/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.zdnglL2ycc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 08:59:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 08:59:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 08:59:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MlSm6Gtn1j/Render', 08:59:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:59:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:59:40 INFO - 'HOME': '/Users/cltbld', 08:59:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:59:40 INFO - 'LOGNAME': 'cltbld', 08:59:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:59:40 INFO - 'MOZ_NO_REMOTE': '1', 08:59:40 INFO - 'NO_EM_RESTART': '1', 08:59:40 INFO - 'PAGER': '/bin/cat', 08:59:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:59:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:59:40 INFO - 'PWD': '/builds/slave/test', 08:59:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:59:40 INFO - 'SHELL': '/bin/bash', 08:59:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zdnglL2ycc/Listeners', 08:59:40 INFO - 'TMPDIR': '/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/', 08:59:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:59:40 INFO - 'USER': 'cltbld', 08:59:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:59:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:59:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:59:40 INFO - 'XPC_FLAGS': '0x0', 08:59:40 INFO - 'XPC_SERVICE_NAME': '0', 08:59:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:59:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:59:40 INFO - Downloading/unpacking jsonschema==2.5.1 08:59:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:59:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:59:40 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:59:40 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:59:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:59:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:59:43 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 08:59:43 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 08:59:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:59:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:59:43 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:59:43 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:59:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:59:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:59:43 INFO - Downloading functools32-3.2.3-2.tar.gz 08:59:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 08:59:43 INFO - warning: no files found matching '*.txt' 08:59:43 INFO - no previously-included directories found matching 'build' 08:59:43 INFO - no previously-included directories found matching 'dist' 08:59:43 INFO - no previously-included directories found matching '.git*' 08:59:43 INFO - Installing collected packages: jsonschema, functools32 08:59:43 INFO - Running setup.py install for functools32 08:59:43 INFO - warning: no files found matching '*.txt' 08:59:43 INFO - no previously-included directories found matching 'build' 08:59:43 INFO - no previously-included directories found matching 'dist' 08:59:43 INFO - no previously-included directories found matching '.git*' 08:59:43 INFO - Successfully installed jsonschema functools32 08:59:43 INFO - Cleaning up... 08:59:43 INFO - Return code: 0 08:59:43 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 08:59:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:59:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:59:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:59:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:59:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:59:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:59:43 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 0x10bf12b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bf15768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8912495870>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bcf0d30>, '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 0x10bc17db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bce5be8>, '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.MlSm6Gtn1j/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.zdnglL2ycc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 08:59:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 08:59:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 08:59:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MlSm6Gtn1j/Render', 08:59:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:59:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:59:43 INFO - 'HOME': '/Users/cltbld', 08:59:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:59:43 INFO - 'LOGNAME': 'cltbld', 08:59:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:59:43 INFO - 'MOZ_NO_REMOTE': '1', 08:59:43 INFO - 'NO_EM_RESTART': '1', 08:59:43 INFO - 'PAGER': '/bin/cat', 08:59:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:59:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:59:43 INFO - 'PWD': '/builds/slave/test', 08:59:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:59:43 INFO - 'SHELL': '/bin/bash', 08:59:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zdnglL2ycc/Listeners', 08:59:43 INFO - 'TMPDIR': '/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/', 08:59:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:59:43 INFO - 'USER': 'cltbld', 08:59:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:59:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:59:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:59:43 INFO - 'XPC_FLAGS': '0x0', 08:59:43 INFO - 'XPC_SERVICE_NAME': '0', 08:59:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:59:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:59:44 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 08:59:44 INFO - Cleaning up... 08:59:44 INFO - Return code: 0 08:59:44 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 08:59:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:59:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:59:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:59:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:59:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:59:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:59:44 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 0x10bf12b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bf15768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8912495870>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bcf0d30>, '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 0x10bc17db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bce5be8>, '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.MlSm6Gtn1j/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.zdnglL2ycc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 08:59:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 08:59:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 08:59:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MlSm6Gtn1j/Render', 08:59:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:59:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:59:44 INFO - 'HOME': '/Users/cltbld', 08:59:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:59:44 INFO - 'LOGNAME': 'cltbld', 08:59:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:59:44 INFO - 'MOZ_NO_REMOTE': '1', 08:59:44 INFO - 'NO_EM_RESTART': '1', 08:59:44 INFO - 'PAGER': '/bin/cat', 08:59:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:59:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:59:44 INFO - 'PWD': '/builds/slave/test', 08:59:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:59:44 INFO - 'SHELL': '/bin/bash', 08:59:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zdnglL2ycc/Listeners', 08:59:44 INFO - 'TMPDIR': '/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/', 08:59:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:59:44 INFO - 'USER': 'cltbld', 08:59:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:59:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:59:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:59:44 INFO - 'XPC_FLAGS': '0x0', 08:59:44 INFO - 'XPC_SERVICE_NAME': '0', 08:59:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:59:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:59:44 INFO - Downloading/unpacking blobuploader==1.2.4 08:59:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:59:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:59:44 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:59:44 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:59:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:59:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:59:47 INFO - Downloading blobuploader-1.2.4.tar.gz 08:59:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 08:59:47 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 08:59:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:59:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:59:47 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:59:47 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:59:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:59:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:59:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 08:59:47 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 08:59:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:59:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:59:47 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:59:47 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:59:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:59:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:59:48 INFO - Downloading docopt-0.6.1.tar.gz 08:59:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 08:59:48 INFO - Installing collected packages: blobuploader, requests, docopt 08:59:48 INFO - Running setup.py install for blobuploader 08:59:48 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 08:59:48 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 08:59:48 INFO - Running setup.py install for requests 08:59:49 INFO - Running setup.py install for docopt 08:59:49 INFO - Successfully installed blobuploader requests docopt 08:59:49 INFO - Cleaning up... 08:59:49 INFO - Return code: 0 08:59:49 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:59:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:59:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:59:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:59:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:59:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:59:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:59:49 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 0x10bf12b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bf15768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8912495870>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bcf0d30>, '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 0x10bc17db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bce5be8>, '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.MlSm6Gtn1j/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.zdnglL2ycc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 08:59:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 08:59:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 08:59:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MlSm6Gtn1j/Render', 08:59:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:59:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:59:49 INFO - 'HOME': '/Users/cltbld', 08:59:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:59:49 INFO - 'LOGNAME': 'cltbld', 08:59:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:59:49 INFO - 'MOZ_NO_REMOTE': '1', 08:59:49 INFO - 'NO_EM_RESTART': '1', 08:59:49 INFO - 'PAGER': '/bin/cat', 08:59:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:59:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:59:49 INFO - 'PWD': '/builds/slave/test', 08:59:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:59:49 INFO - 'SHELL': '/bin/bash', 08:59:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zdnglL2ycc/Listeners', 08:59:49 INFO - 'TMPDIR': '/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/', 08:59:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:59:49 INFO - 'USER': 'cltbld', 08:59:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:59:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:59:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:59:49 INFO - 'XPC_FLAGS': '0x0', 08:59:49 INFO - 'XPC_SERVICE_NAME': '0', 08:59:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:59:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:59:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:59:49 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-YSoP4M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:59:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:59:49 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-im4Lgv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:59:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:59:49 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-Zj19yL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:59:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:59:49 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-Rlrs5I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:59:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:59:50 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-om_xe0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:59:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:59:50 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-ly68H9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:59:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:59:50 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-eUTAqh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:59:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:59:50 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-j0XAOe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:59:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:59:50 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-2U0NVO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:59:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:59:50 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-AXiRE5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:59:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:59:50 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-XigFje-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:59:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:59:50 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-yl0bNC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:59:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:59:50 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-sZL_32-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:59:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:59:50 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-4DOUGG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:59:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:59:51 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-HIAA8G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:59:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:59:51 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-LO0bLM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:59:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:59:51 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-MrkdCF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:59:51 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 08:59:51 INFO - Running setup.py install for manifestparser 08:59:51 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 08:59:51 INFO - Running setup.py install for mozcrash 08:59:51 INFO - Running setup.py install for mozdebug 08:59:51 INFO - Running setup.py install for mozdevice 08:59:51 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 08:59:51 INFO - Installing dm script to /builds/slave/test/build/venv/bin 08:59:52 INFO - Running setup.py install for mozfile 08:59:52 INFO - Running setup.py install for mozhttpd 08:59:52 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 08:59:52 INFO - Running setup.py install for mozinfo 08:59:52 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 08:59:52 INFO - Running setup.py install for mozInstall 08:59:52 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 08:59:52 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 08:59:52 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 08:59:52 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 08:59:52 INFO - Running setup.py install for mozleak 08:59:52 INFO - Running setup.py install for mozlog 08:59:52 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 08:59:52 INFO - Running setup.py install for moznetwork 08:59:53 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 08:59:53 INFO - Running setup.py install for mozprocess 08:59:53 INFO - Running setup.py install for mozprofile 08:59:53 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 08:59:53 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 08:59:53 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 08:59:53 INFO - Running setup.py install for mozrunner 08:59:53 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 08:59:53 INFO - Running setup.py install for mozscreenshot 08:59:53 INFO - Running setup.py install for moztest 08:59:53 INFO - Running setup.py install for mozversion 08:59:53 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 08:59:53 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 08:59:53 INFO - Cleaning up... 08:59:53 INFO - Return code: 0 08:59:53 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:59:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:59:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:59:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:59:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:59:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:59:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:59:53 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 0x10bf12b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bf15768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8912495870>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bcf0d30>, '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 0x10bc17db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bce5be8>, '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.MlSm6Gtn1j/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.zdnglL2ycc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 08:59:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 08:59:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 08:59:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MlSm6Gtn1j/Render', 08:59:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:59:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:59:53 INFO - 'HOME': '/Users/cltbld', 08:59:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:59:53 INFO - 'LOGNAME': 'cltbld', 08:59:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:59:53 INFO - 'MOZ_NO_REMOTE': '1', 08:59:53 INFO - 'NO_EM_RESTART': '1', 08:59:53 INFO - 'PAGER': '/bin/cat', 08:59:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:59:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:59:53 INFO - 'PWD': '/builds/slave/test', 08:59:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:59:53 INFO - 'SHELL': '/bin/bash', 08:59:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zdnglL2ycc/Listeners', 08:59:53 INFO - 'TMPDIR': '/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/', 08:59:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:59:53 INFO - 'USER': 'cltbld', 08:59:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:59:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:59:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:59:53 INFO - 'XPC_FLAGS': '0x0', 08:59:53 INFO - 'XPC_SERVICE_NAME': '0', 08:59:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:59:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:59:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:59:54 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-ERFgd3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:59:54 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 08:59:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:59:54 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-PT6P3P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:59:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 08:59:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:59:54 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-gtwwfy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:59:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 08:59:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:59:54 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-wblnJ_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:59:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 08:59:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:59:54 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-eTHm5z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:59:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 08:59:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:59:54 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-xfjKLq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:59:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 08:59:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:59:54 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-aoVzZn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:59:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 08:59:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:59:55 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-Nf2gdR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:59:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 08:59:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:59:55 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-QAhzUy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:59:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 08:59:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:59:55 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-lrHSwN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:59:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:59:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:59:55 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-FF26DQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:59:55 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 08:59:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:59:55 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-WsyfQA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:59:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 08:59:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:59:55 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-bkKAfX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:59:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 08:59:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:59:55 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-RKSLvv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:59:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 08:59:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:59:55 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-CWMSLG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:59:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 08:59:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:59:55 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-5FrgcG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:59:55 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 08:59:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:59:55 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-hxjL8U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:59:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 08:59:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 08:59:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 08:59:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 08:59:56 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 08:59:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 08:59:56 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:59:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:59:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:59:56 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:59:56 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:59:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:59:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:59:58 INFO - Downloading blessings-1.6.tar.gz 08:59:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 08:59:58 INFO - Installing collected packages: blessings 08:59:58 INFO - Running setup.py install for blessings 08:59:58 INFO - Successfully installed blessings 08:59:58 INFO - Cleaning up... 08:59:59 INFO - Return code: 0 08:59:59 INFO - Done creating virtualenv /builds/slave/test/build/venv. 08:59:59 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:59:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:59:59 INFO - Reading from file tmpfile_stdout 08:59:59 INFO - Current package versions: 08:59:59 INFO - blessings == 1.6 08:59:59 INFO - blobuploader == 1.2.4 08:59:59 INFO - docopt == 0.6.1 08:59:59 INFO - functools32 == 3.2.3-2 08:59:59 INFO - jsonschema == 2.5.1 08:59:59 INFO - manifestparser == 1.1 08:59:59 INFO - mozInstall == 1.12 08:59:59 INFO - mozcrash == 1.0 08:59:59 INFO - mozdebug == 0.1 08:59:59 INFO - mozdevice == 0.49 08:59:59 INFO - mozfile == 1.2 08:59:59 INFO - mozhttpd == 0.7 08:59:59 INFO - mozinfo == 0.9 08:59:59 INFO - mozleak == 0.1 08:59:59 INFO - mozlog == 3.4 08:59:59 INFO - moznetwork == 0.27 08:59:59 INFO - mozprocess == 0.25 08:59:59 INFO - mozprofile == 0.28 08:59:59 INFO - mozrunner == 6.13 08:59:59 INFO - mozscreenshot == 0.1 08:59:59 INFO - mozsystemmonitor == 0.3 08:59:59 INFO - moztest == 0.8 08:59:59 INFO - mozversion == 1.4 08:59:59 INFO - psutil == 3.1.1 08:59:59 INFO - requests == 1.2.3 08:59:59 INFO - wsgiref == 0.1.2 08:59:59 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:59:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:59:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:59:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:59:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:59:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:59:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:59:59 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 0x10bf12b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bf15768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8912495870>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bcf0d30>, '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 0x10bc17db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bce5be8>, '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.MlSm6Gtn1j/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.zdnglL2ycc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 08:59:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 08:59:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 08:59:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MlSm6Gtn1j/Render', 08:59:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:59:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:59:59 INFO - 'HOME': '/Users/cltbld', 08:59:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:59:59 INFO - 'LOGNAME': 'cltbld', 08:59:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:59:59 INFO - 'MOZ_NO_REMOTE': '1', 08:59:59 INFO - 'NO_EM_RESTART': '1', 08:59:59 INFO - 'PAGER': '/bin/cat', 08:59:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:59:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:59:59 INFO - 'PWD': '/builds/slave/test', 08:59:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:59:59 INFO - 'SHELL': '/bin/bash', 08:59:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zdnglL2ycc/Listeners', 08:59:59 INFO - 'TMPDIR': '/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/', 08:59:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:59:59 INFO - 'USER': 'cltbld', 08:59:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:59:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:59:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:59:59 INFO - 'XPC_FLAGS': '0x0', 08:59:59 INFO - 'XPC_SERVICE_NAME': '0', 08:59:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:59:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:59:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 08:59:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 08:59:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 08:59:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 08:59:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 08:59:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 08:59:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 08:59:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 08:59:59 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 08:59:59 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 08:59:59 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 08:59:59 INFO - Cleaning up... 08:59:59 INFO - Return code: 0 08:59:59 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 08:59:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:59:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:59:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:59:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:59:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:59:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:59:59 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 0x10bf12b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bf15768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8912495870>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bcf0d30>, '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 0x10bc17db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bce5be8>, '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.MlSm6Gtn1j/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.zdnglL2ycc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 08:59:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 08:59:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 08:59:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MlSm6Gtn1j/Render', 08:59:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:59:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:59:59 INFO - 'HOME': '/Users/cltbld', 08:59:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:59:59 INFO - 'LOGNAME': 'cltbld', 08:59:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:59:59 INFO - 'MOZ_NO_REMOTE': '1', 08:59:59 INFO - 'NO_EM_RESTART': '1', 08:59:59 INFO - 'PAGER': '/bin/cat', 08:59:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:59:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:59:59 INFO - 'PWD': '/builds/slave/test', 08:59:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:59:59 INFO - 'SHELL': '/bin/bash', 08:59:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zdnglL2ycc/Listeners', 08:59:59 INFO - 'TMPDIR': '/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/', 08:59:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:59:59 INFO - 'USER': 'cltbld', 08:59:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:59:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:59:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:59:59 INFO - 'XPC_FLAGS': '0x0', 08:59:59 INFO - 'XPC_SERVICE_NAME': '0', 08:59:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:59:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:59:59 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 08:59:59 INFO - Cleaning up... 08:59:59 INFO - Return code: 0 08:59:59 INFO - Running post-action listener: _resource_record_post_action 08:59:59 INFO - Running post-action listener: _start_resource_monitoring 08:59:59 INFO - Starting resource monitoring. 08:59:59 INFO - [mozharness: 2017-02-23 16:59:59.890798Z] Finished create-virtualenv step (success) 08:59:59 INFO - [mozharness: 2017-02-23 16:59:59.891805Z] Running install step. 08:59:59 INFO - Running pre-action listener: _resource_record_pre_action 08:59:59 INFO - Running main action method: install 08:59:59 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:59:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:00:00 INFO - Reading from file tmpfile_stdout 09:00:00 INFO - Detecting whether we're running mozinstall >=1.0... 09:00:00 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:00:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:00:00 INFO - Reading from file tmpfile_stdout 09:00:00 INFO - Output received: 09:00:00 INFO - Usage: mozinstall [options] installer 09:00:00 INFO - Options: 09:00:00 INFO - -h, --help show this help message and exit 09:00:00 INFO - -d DEST, --destination=DEST 09:00:00 INFO - Directory to install application into. [default: 09:00:00 INFO - "/builds/slave/test"] 09:00:00 INFO - --app=APP Application being installed. [default: firefox] 09:00:00 INFO - mkdir: /builds/slave/test/build/application 09:00:00 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-54.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 09:00:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-54.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 09:00:20 INFO - Reading from file tmpfile_stdout 09:00:20 INFO - Output received: 09:00:20 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 09:00:20 INFO - Running post-action listener: _resource_record_post_action 09:00:20 INFO - [mozharness: 2017-02-23 17:00:20.597961Z] Finished install step (success) 09:00:20 INFO - [mozharness: 2017-02-23 17:00:20.598086Z] Running run-tests step. 09:00:20 INFO - Running pre-action listener: _resource_record_pre_action 09:00:20 INFO - Running main action method: run_tests 09:00:20 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:00:20 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:00:20 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:00:20 INFO - 2017-02-23 09:00:20.612 screenresolution[1935:10667] starting screenresolution argv=screenresolution get 09:00:20 INFO - 2017-02-23 09:00:20.615 screenresolution[1935:10667] Display 0: 1600x1200x32@60 09:00:20 INFO - 2017-02-23 09:00:20.621 screenresolution[1936:10670] starting screenresolution argv=screenresolution list 09:00:20 INFO - Available Modes on Display 0 09:00:20 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:00:20 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:00:20 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:00:20 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:00:20 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:00:20 INFO - Intel Iris: 09:00:20 INFO - Chipset Model: Intel Iris 09:00:20 INFO - Type: GPU 09:00:20 INFO - Bus: Built-In 09:00:20 INFO - VRAM (Dynamic, Max): 1536 MB 09:00:20 INFO - Vendor: Intel (0x8086) 09:00:20 INFO - Device ID: 0x0a2e 09:00:20 INFO - Revision ID: 0x0009 09:00:20 INFO - Displays: 09:00:20 INFO - Display: 09:00:20 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:00:20 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:00:20 INFO - Display Serial Number: 1600x1200 60 09:00:20 INFO - Main Display: Yes 09:00:20 INFO - Mirror: Off 09:00:20 INFO - Online: Yes 09:00:20 INFO - Rotation: Supported 09:00:20 INFO - Return code: 0 09:00:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:00:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:00:20 INFO - Python 2.7.3 09:00:20 INFO - Return code: 0 09:00:20 INFO - grabbing minidump binary from tooltool 09:00:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:00:20 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 0x10bcf0d30>, '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 0x10bc17db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bce5be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:00:20 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 09:00:20 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 09:00:20 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 09:00:20 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:00:20 INFO - Return code: 0 09:00:20 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 09:00:20 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:00:20 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:00:20 INFO - ENV: RUST_BACKTRACE is now 1 09:00:20 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 09:00:20 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:00:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Tsg-KtrqQ-G1VABHyuSZ4Q/artifacts/public/build/firefox-54.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0353', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 09:00:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Tsg-KtrqQ-G1VABHyuSZ4Q/artifacts/public/build/firefox-54.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0353 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 09:00:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MlSm6Gtn1j/Render', 09:00:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:00:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:00:20 INFO - 'HOME': '/Users/cltbld', 09:00:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:00:20 INFO - 'LOGNAME': 'cltbld', 09:00:20 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:00:20 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 09:00:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:00:20 INFO - 'MOZ_NO_REMOTE': '1', 09:00:20 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:00:20 INFO - 'NO_EM_RESTART': '1', 09:00:20 INFO - 'PAGER': '/bin/cat', 09:00:20 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:00:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:00:20 INFO - 'PWD': '/builds/slave/test', 09:00:20 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:00:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:00:20 INFO - 'RUST_BACKTRACE': '1', 09:00:20 INFO - 'SHELL': '/bin/bash', 09:00:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zdnglL2ycc/Listeners', 09:00:20 INFO - 'TMPDIR': '/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/', 09:00:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:00:20 INFO - 'USER': 'cltbld', 09:00:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:00:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:00:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:00:20 INFO - 'XPC_FLAGS': '0x0', 09:00:20 INFO - 'XPC_SERVICE_NAME': '0', 09:00:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:00:20 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Tsg-KtrqQ-G1VABHyuSZ4Q/artifacts/public/build/firefox-54.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0353', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 09:00:21 INFO - mozversion application_buildid: 20170223073723 09:00:21 INFO - mozversion application_changeset: 22ec1dab9e821676f4204d36ce9801803032f504 09:00:21 INFO - mozversion application_display_name: Nightly 09:00:21 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:00:21 INFO - mozversion application_name: Firefox 09:00:21 INFO - mozversion application_remotingname: firefox 09:00:21 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 09:00:21 INFO - mozversion application_vendor: Mozilla 09:00:21 INFO - mozversion application_version: 54.0a1 09:00:21 INFO - mozversion platform_buildid: 20170223073723 09:00:21 INFO - mozversion platform_changeset: 22ec1dab9e821676f4204d36ce9801803032f504 09:00:21 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 09:00:21 INFO - mozversion platform_version: 54.0a1 09:00:21 INFO - using testdate: 1487869221 09:00:21 INFO - actual date: 1487869221 09:00:21 INFO - starting webserver on 'localhost:49318' 09:00:21 INFO - SUITE-START | Running 2 tests 09:00:21 INFO - TEST-START | dromaeo_css 09:00:21 INFO - operating with platform_type : mac_ 09:00:21 INFO - Initialising browser for dromaeo_css test... 09:00:21 INFO - TEST-INFO | started process 1946 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/tmpBWaZ8M/profile http://localhost:49318/getInfo.html) 09:00:21 INFO - PROCESS | 1946 | 1487869221727 addons.xpi WARN Error loading bootstrap.js for disableSHA1rollout@mozilla.org: Error opening input stream (invalid filename?): jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/disableSHA1rollout@mozilla.org.xpi!/bootstrap.js 09:00:21 INFO - PROCESS | 1946 | 1487869221728 addons.xpi WARN Add-on disableSHA1rollout@mozilla.org is missing bootstrap method install 09:00:21 INFO - PROCESS | 1946 | 1487869221768 addons.xpi WARN Add-on disableSHA1rollout@mozilla.org is missing bootstrap method startup 09:00:23 INFO - PROCESS | 1946 | 2017-02-23 09:00:23.825 plugin-container[1948:10835] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x484b, name = 'com.apple.tsm.portname' 09:00:23 INFO - PROCESS | 1946 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:00:23 INFO - PROCESS | 1946 | 2017-02-23 09:00:23.828 plugin-container[1948:10835] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x960b, name = 'com.apple.CFPasteboardClient' 09:00:23 INFO - PROCESS | 1946 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:00:23 INFO - PROCESS | 1946 | 2017-02-23 09:00:23.828 plugin-container[1948:10835] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:00:23 INFO - PROCESS | 1946 | __metrics Screen width/height:1600/1200 09:00:23 INFO - PROCESS | 1946 | colorDepth:24 09:00:23 INFO - PROCESS | 1946 | Browser inner width/height: 1024/689 09:00:23 INFO - PROCESS | 1946 | __metrics 09:00:23 ERROR - PROCESS | 1946 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3280: TypeError: this.tabs is undefined 09:00:23 INFO - PROCESS | 1946 | 1487869223991 addons.xpi WARN Add-on disableSHA1rollout@mozilla.org is missing bootstrap method shutdown 09:00:24 INFO - TEST-INFO | 1946: exit 0 09:00:24 INFO - Browser initialized. 09:00:24 INFO - Running cycle 1/1 for dromaeo_css test... 09:00:24 INFO - TEST-INFO | started process 1949 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/tmpBWaZ8M/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:00:25 INFO - PROCESS | 1949 | 1487869224998 addons.xpi WARN Error loading bootstrap.js for disableSHA1rollout@mozilla.org: Error opening input stream (invalid filename?): jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/disableSHA1rollout@mozilla.org.xpi!/bootstrap.js 09:00:25 INFO - PROCESS | 1949 | 1487869224999 addons.xpi WARN Add-on disableSHA1rollout@mozilla.org is missing bootstrap method startup 09:00:27 INFO - PROCESS | 1949 | 2017-02-23 09:00:27.372 plugin-container[1950:10957] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4347, name = 'com.apple.tsm.portname' 09:00:27 INFO - PROCESS | 1949 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:00:27 INFO - PROCESS | 1949 | 2017-02-23 09:00:27.374 plugin-container[1950:10957] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x900b, name = 'com.apple.CFPasteboardClient' 09:00:27 INFO - PROCESS | 1949 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:00:27 INFO - PROCESS | 1949 | 2017-02-23 09:00:27.375 plugin-container[1950:10957] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:00:36 INFO - PROCESS | 1949 | RSS: Main: 227979264 09:00:36 INFO - PROCESS | 1949 | 09:03:39 INFO - PROCESS | 1949 | Cycle 1(1): loaded http://localhost:49318/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49318/tests/dromaeo/cssquery-ext.html) 09:03:42 INFO - PROCESS | 1949 | RSS: Main: 248938496 09:03:42 INFO - PROCESS | 1949 | 09:05:25 INFO - PROCESS | 1949 | ************************* 09:05:25 INFO - PROCESS | 1949 | A coding exception was thrown and uncaught in a Task. 09:05:25 INFO - PROCESS | 1949 | 09:05:25 ERROR - PROCESS | 1949 | Full message: TypeError: NetworkError when attempting to fetch resource. 09:05:25 INFO - PROCESS | 1949 | Full stack: 09:05:25 INFO - PROCESS | 1949 | ************************* 09:06:44 INFO - PROCESS | 1949 | Cycle 1(1): loaded http://localhost:49318/tests/dromaeo/cssquery-ext.html (next: http://localhost:49318/tests/dromaeo/cssquery-jquery.html) 09:06:45 INFO - PROCESS | 1949 | RSS: Main: 254136320 09:06:45 INFO - PROCESS | 1949 | 09:09:47 INFO - PROCESS | 1949 | Cycle 1(1): loaded http://localhost:49318/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49318/tests/dromaeo/cssquery-mootools.html) 09:09:48 INFO - PROCESS | 1949 | RSS: Main: 267083776 09:09:48 INFO - PROCESS | 1949 | 09:12:50 INFO - PROCESS | 1949 | Cycle 1(1): loaded http://localhost:49318/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49318/tests/dromaeo/cssquery-prototype.html) 09:12:50 INFO - PROCESS | 1949 | RSS: Main: 261771264 09:12:50 INFO - PROCESS | 1949 | 09:15:53 INFO - PROCESS | 1949 | Cycle 1(1): loaded http://localhost:49318/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49318/tests/dromaeo/cssquery-yui.html) 09:15:54 INFO - PROCESS | 1949 | RSS: Main: 271970304 09:15:54 INFO - PROCESS | 1949 | 09:18:57 INFO - PROCESS | 1949 | Cycle 1(1): loaded http://localhost:49318/tests/dromaeo/cssquery-yui.html (next: http://localhost:49318/tests/dromaeo/cssquery-dojo.html) 09:18:57 INFO - PROCESS | 1949 | RSS: Main: 265461760 09:18:57 INFO - PROCESS | 1949 | 09:18:57 INFO - PROCESS | 1949 | __start_tp_report 09:18:57 INFO - PROCESS | 1949 | _x_x_mozilla_page_load 09:18:57 INFO - PROCESS | 1949 | _x_x_mozilla_page_load_details 09:18:57 INFO - PROCESS | 1949 | |i|pagename|runs| 09:18:57 INFO - PROCESS | 1949 | |0;dojo.html;3228.9838550807244;3265.167382317509;3326.2848487575166;3328.3176948725527;3353.882614108507;5431.619786614929;5440.292761940952;5462.590305727072;5473.233747275374;5540.86147846303;4324.243257429953;5468;5507.394186639466;5534.75093620787;5560.138178582316;4976.776045077981;5025.246213068051;5070.72111033893;5126.205438157073;5147.691138531682;4986.077575648516;5112.872178195537;5195.792168313263;5201.5838732901275;5207.583393328525;4970.701757894538;5143.691378517301;5173.275741396207;5177.637565370425;5205.973970130125;5002.874928126789;5017.2724954881705;5059.721715305656;5061.139606266944;5100.234964755279;5055.11535481293;5096.031753966735;5119.25770763241;5132.871678208037;5145.25393817898;5162.793488260465;5521.3926468088475;5599.636023658449;5614.326280846284;5663.037283661787;5922.496587790043;6009.789657362015;6107.847303817396;6158.538109641795;6166.136740856284;6104.4505994460715;6129.969350153221;6130.754769809202;6134.478569321613;6144.600600960924;5659.8585035374035;5683.715814209273;5686.658800471985;5717.1138473537085;5732.398098199629;5306.469353064663;6143.447089761943;6179.104029915637;6273.592216505913;6287.559870808999;5734.397888221677;5764.625299355528;6004.0693692477735;6058.697065146725;6060.272767267956;6041.214642096499;6053.303870054912;6057.3336932937345;6080.270367555921;6086.6348019119;6037.849053773691;6105.945000794801;6106.39999602577;6125.693715314216;6137.101203255209;6102.298235702862;6112.8777424452155;6115.388461153849;6135.417135372132;6147.600405973598;4145.461090058334;4788.066327066258;4805.183118869801;4851.593038018935;4866.367372241586;4265.509466411402;4269.978650106729;4302.7848607569495;4325.070109926381;4362.083962367875;5682.261306030272;5704.629199102045;5728.198052272685;5732.34078081026;5736.397678243808;5763.971180144073;5975.940240597625;6133.877322453615;6210.5031597473;6233;5554.527865131509;5566.526845218121;5568.359638641527;5613.663180209119;5668.801591944262;5251.264822924793;5255.08036093682;5278.313819203479;5282.524572788468;5285.022270879899;5588.273524441796;5896.204012458435;6239.656818874914;6257.812265632038;6257.967435373203;5919.378465261086;5986.311574169026;5986.371430999682;5990.430909063632;6003.129546215687;6191.040388739752;6199.752009919553;6203.565750397609;6204.689765511796;6230.221222347207;5790.218320526844;5897.262842144732;5908.763649453974;6060.545459090497;6080.087986802015;6061.333253342041;6063.393660634078;6064.211652485149;6074.392560744067;6085.604435711664;5834.212381328466;5835.708214589169;5846.239988801429;5856.824295271149;5860.765569377348;4815.470298267258;5860;5942.078977758454;5942.168096466585;5985.281766188085;5630.4369563043365;5758.971205143947;5787.797427090031;5793.594448388572;5822.679752613647;4764.404449443819;5666.150077488245;5764.913526297193;5797.826065218051;5804.796832110856;4712.976435117803;5668.206451096932;5746.310442746897;5765.106408506688;5801.9709901451915 09:18:57 INFO - PROCESS | 1949 | |1;ext.html;444.77538842884337;484.71644088208416;488.60178954152383;501.1655593436928;536.7879687523431;36136.81931590342;38627.806860965655;38665.64670236539;38682.80658596703;38708.80645596775;31885.840570797114;38577.61422385775;38781.41827872584;39104.413433798516;39147.60852391475;9108.043655416186;9335.066493350645;9352.15830575248;10707.518161682743;11065.170112241614;10828.350298982046;10929.672109836678;10939.234253602212;10948.507317170706;10981.450927453636;10994.890051099506;11029.007389334958;11044.779104417907;11060.944695276512;11082.6121085762;10960.397178155199;10999.450027498593;11009.61466348682;11013.229073964825;11020.5040773165;9355.158035776745;10955.726106847313;11013.339199647966;11044.834327485094;11046.613368532144;35115;35354;35422.64577354219;35445.82277088598;35617.82191089028;3669.376206044952;3690.186737710513;3711.6102809205186;3733.5146430964073;3882.048898020003;26642.73357266422;26818.731812682014;27885.442291154064;27889;28094;3080.168354544283;3105.6583775784648;3187.235063584746;3208.053624180849;3230.321632457163;426828;427467;428843;429558;431483;45692.77153614277;46804;52686.73656631769;53250.73374633102;60127;57829;60843.69578152081;62805.05792413117;63073;66548.66725666437;15342.002769819926;15375.923685341919;15482.612934676497;15599.29803158838;15599.376024959101;11721.4139293035;11858.762824743457;11869.228500147463;11877.88122118785;11888.821667674993;16290.104044277441;19285.32258303563;19624.607507849763;20027;20131.59736805285;6632.436242919406;7165.6417179140835;8511.829763404698;8538.87191692125;8605.49403854323;817.48764785147;830.7776697334606;842.751388340441;850.8213275212152;854.4830377621573;25681;28409.28976775597;29007.70992290092;29038.564421533694;29090.151078993917;940.022376728195;991.7966816802539;1029.8197815382277;1060.528065011078;1174.9530018799323;12688.302143382203;13460.461581536825;14380;14862.80449703319;15238.619034524005;562658;564255;568289;568589;569450;45534.17863285498;54346;55859.441405586236;55949.720251399296;56136.43863561394;1292.954746583865;1362.868818880327;1366.3031853754658;1369.6712788930586;1388.2919710947288;27388.86305568539;28451.146465606063;28623.570646439905;28677.426451470437;28939.565906510998;47133;47647.28529071997;47655.76172119117;47707.76146119247;47766.04467910553;391994;392426;392623;392654;393227;34386.796462123704;38382.80808595939;39168;40259.1948161041;40443.59556404516;43380.566194337654;44247.557524424345;44384.33423498586;44880.775596123116;44958.325625114994;29287.7071229285;33497.66502334945;33770.324593508485;35574.64425355713;36910;35742.64257357393;36218.74031337192;36396.636033639326;37049.259014819014;37355;33425.66574334323;34683.4797478033;35288.11779705528;35464.08329776565;35583.82208088943;26298.342541435853;31610.683893160774;32263.54814033327;32358.19104522406;32465.51301730523;23511.882440587688;23597.29208123759;23968.640470393308;24077.277681669577;24230.394240143432 09:18:57 INFO - PROCESS | 1949 | |2;jquery.html;390.6718356580472;393.77161246477044;402.3441789882491;403.5601194697779;420.6697742272317;19823.702644460343;20813.063412146454;20880.686789698168;21088.789112108876;21097.26159584415;20695.379138625827;20869.68695469569;21682.674759878613;21712.674309885366;21871.781282187174;5766.221560089376;5999.970000149993;6023.036314189731;6026.337102918676;6034.974054410739;5705.828825135231;5902.144189092579;5903.203067585885;6015.6992150392525;6016.067509536029;5262.684238945675;5801.129830525434;5920.171176035358;5937.346891841894;5946.970265148668;5630.943690563083;5772.826815195551;5855.151003104526;5896.380880007602;5907.911381329262;5180.430152683201;5182.663126896739;5188.11801993662;5190.4031036430915;5193.013327467806;25563.74436255632;25576.87211563949;25850.870745646338;25854;26208.475830483476;2714.7285271472697;2744.341358074068;2753.994791900962;2755.875985580654;2782.318332008671;12180.269183848997;15259.465918692904;15475.845241547553;15617.29722162494;15842.603934901605;1757.7432136022805;1802.342145117023;1803.6843552378411;1823.2706917233213;1836.1094868988664;93617;115950.84049159569;119338;120326.79673203244;120928.79071209264;9910.702678919644;10814.080803131821;11457;11503.252288601214;11642.185047046625;14622.415103395957;14676.853231467761;14681;14684.972051956474;14697.191654458893;8261.38039647017;8413.915860841313;8442.324614030862;8533.786655333666;8618.095100014534;6342.936570634327;8172.346212303001;8220.301274391624;8415.158484151658;8421.242088211968;6944.131983502062;7178.425725941912;7190.9280907191305;7202.603856787822;7215.422766178693;1616.5473667373155;1674.6232097777902;1821.8087100854484;1906.3518403742794;1909.112262797778;877.1929824561419;976.4531862157214;993.8012397520525;1005.5570256681685;1030.108955753268;21019.68470472944;21043.368698939055;21049.684254736192;21050.368488945354;21103;980.6175591519313;983.3559018842669;1006.7885743993843;1008.6873069348526;1014.1683819268129;10483.012341482794;11179.83230251547;11267.605633802941;11300.095992320594;11309;513416;539374;638226;650197;654309;123668.38165809293;123941;126559.46881062328;128626;130006;959.2949182350967;1002.674130907458;1013.3261381181579;1013.3717095400899;1017.9287449878437;14505.854941450873;17887.73168402501;18653.44039678812;18856;19028;52302.738486307324;60195.69902150461;60359.99999999825;60649.0902636452;61554.69222653858;83024;84412;85471;88670;89118;6172.722227499863;7643.4267429941865;8285.585720714062;8517.701880434155;8640.740777776677;10151.84772228432;11776.52893884235;12938;13366;13508.932455338054;10455.581776728846;10901.182411319342;11272.380019099028;11559.19085664029;11577;9293.581788819383;10886.693596768439;11077.612283570037;11185.384803835866;11556.59551915679;11281.548738050715;11363.943180284046;11894.048476121716;11905.940470297593;11970.162088653711;7267.600281984542;8255.958720206361;8395.950506186726;8417.326613870999;8451.746447606582;8256.669733210605;9584.616615335588;10895.346279223271;11324.490397932277;11508.309501429942 09:18:57 INFO - PROCESS | 1949 | |3;mootools.html;1252.1923359433165;1374.4502199120354;1431.9212443315614;1433.4767809749437;1461.3496993837744;8221.91778082219;8317.293030092434;8589.355798315135;8598.22615964563;8605.612747426365;7124.287571242873;7569.788833786595;8217.301529369995;8582.399232053758;8611.698590549331;2237.093976939338;2274.465500607356;2276.829237807163;2280.2361208995007;2289.2331069091874;2061.9793802061936;2186.278528085728;2264.184893438361;2303.1363238785457;2303.585354636165;2243.9775602244013;2256.909723611054;2272.1138755885136;2281.7718228177214;2295.4490922178725;1898.7531620889333;2156.7088443060143;2226.7661895500983;2248.808851247646;2251.3808702606752;1926.4702206893076;2007.056683358819;2234.2850287907877;2292.6904867842795;2294.357579877637;8232.094469608339;8467.237948584596;8481.491110533389;8578.313734901192;8591.398602097855;5024;5178.275041494194;5180.222966555009;5192.584593232532;5200.167973124282;3107.409592177479;3162.320101178235;3164.2089477630593;3198.0885447647543;3431.296584200233;1146.5012719467059;1181.6159748081823;1214.6295379909125;1218.8049912014035;1620.6710497392144;10650.25448218625;10746.677599672023;10799.352038877692;10862.02241798242;10875.619353322596;4093.385992101149;4345.413369195181;4388.232059389594;4392.253316936129;4397.164538737629;7296.7810965671115;7316.670749816271;8648.913510864812;8697.652093916298;8717.389782715276;5064.746762661852;5070.8225212117395;5075.390953085654;5077.314562534085;5094.694318340911;4533.705309154961;4659.324397962277;4684.859454216379;4755.286706993978;4765.666403351732;3055.6486004109365;3156.826374549422;3456.4469684850305;3666.0834791302173;3703.9444408333898;1150.9712257193469;1151.073385924339;1152.1877076660935;1155.399192419937;1156.8553930758655;738.8670039392961;744.709563270325;888.8400087984224;891.31814162166;933.0762545080457;5259.289995850588;5522.530584900329;5570.275864137636;5580.953808488813;5706.80026199081;867.5922316511229;916.3219217778795;940.5062342270226;941.204682043672;997.9501024948723;3672.559292884871;3789.6778773804535;3792.241551689673;3848.3073046851837;3927.7643341399607;9053.547322633842;9434.622615095457;9617.374870633386;9858.654947076819;9992.700218993441;9908.950455247821;10094.545745441474;10108.696739097839;10125.898741012643;10131;6372.36276372359;7840.019997500426;7900.486468379537;7901.170377110436;7907.406944479072;5269.893322402185;5496.1755736639825;5512.310961129859;5541.889162216814;5664.801731939362;9131.817363652823;9151.17639412456;9208.585613647268;9388.76528086776;9424.434533927986;7836.686532538634;9298.581563829508;9480.099390557885;9537.475438850652;9555.808883822423;7133.108361454818;7301.342879140902;7311.780646580611;7311.926880731337;7324.926750732638;7629.46593738433;7978.680852765824;8019.438639295194;8039.4774339670075;8106.756797296091;7058.223595404604;7105.396041336544;7111.679974401268;7186.712531498891;7190.604516751419;5960.940390596212;6994.33553812387;7027.472939529658;7048.718051278097;7063.894041589483;6322.778702745381;6333.461655759312;6350.364963503797;6363.713632886624;6399.776007839703;4743.45450273223;4773.331733557236;4844.346013288302;4865.172920603435;4945.653804233669;4910.533499317559;4916.188828843344;4957.082939656175;5025.120603894377;5033.748312584283 09:18:57 INFO - PROCESS | 1949 | |4;prototype.html;330.5207449198661;350.79829098268493;350.95415661329224;354.3957552373204;355.54312708170005;4560.749158796265;4914.4348399934015;5322.749153948819;5419.295491586088;5420.620556561043;4422.734635921847;5353.678779273247;5392.325959255093;5416.458354164582;5457.699826509541;3319.7053149271683;3340.2818394045335;3479.9376548637165;4030.3148464760916;4089.631933126018;3436.690697837194;3449.7067749241346;4026.5973402659793;4068.8169032393466;4107.54816970133;3559.4660800879947;3726.869559565416;3875.418687196915;4046.4941882264716;4062.7359221650645;3885.630865067814;3947.46709194258;4038.8795882161808;4061.4923134608025;4061.5175460956834;3446.276281980762;3446.4313388291152;3447.24160684649;3451.8953934740894;3462.8614855406004;5258.81594144207;5260.395054568736;5264.7367631618645;5279.313689220415;5328.840134795923;4162.771047592381;4474.709143905636;4482.350059241392;4588.977055114703;4625.468071171791;5580.91628625571;5615.831525054255;5629.971850140723;5654.717264136777;5688.776912963733;3222.548843161959;3797.487339209199;3806.401311449198;3847.5382954045413;3847.6921846252235;5291.259223708684;5687.459691329317;5899.941000589982;6022.488088512482;6063.727132279058;3715.1826598148364;3726.1616136369644;3822.9426558601645;3831.4827498287373;3855.9036024099623;3462.4113900636953;3761.4733937248807;3887.863924762676;3908.6091390861257;3927.9214415711526;3709.7588656737225;4488.349189367524;4557.954420455753;4562.954370456319;4586.7615743749;5103.8468845934685;5339.492748188916;5442.557722203619;5717.284289962235;5761.654300741969;1093.9617113401152;1194.5281613762515;1201.6154830454345;1250.0811903251104;1264.1972347559183;799.6121880887881;809.3889113719105;814.2915663372903;857.5497863621648;861.29804209569;4587.610053145453;4775.808967641256;4882.6826256293225;4947.960928205117;4959.429665588431;4909.533594308535;4978.800847966113;5120.948790512121;5233.921491177635;5615.494605485526;4523.6929166124855;4839.903201936012;4875.975620121877;5091.312672789126;5105.2086926526445;2473.9628905566433;2521.7730404263702;2960.9703902960696;2963.259185203699;3019.022540236149;5140.329392947232;5278.126265175884;5469.8085567005755;5505.366882808448;5599.496045355938;4653.534646535387;5222.112240918977;5557.944420555743;5808.680522571299;5951.821445356646;5080.923786143137;5314.5216930476445;5825.7087145642545;5960.37416071315;5997.730102145327;5854.560907931837;5857.238558987475;5857.355690873916;5871.236739223874;5946.643201407929;5187.792488300577;5340.798320377884;5618.3258009037545;5744.511716504144;5765.682887441231;6100.816975490742;6108.328083910677;6117.785877494266;6157;6216.285127210429;3324.7672662913365;3780.3762379207124;3832.789196594214;3864.9613503864603;3889.416587511896;5354.21580778001;5541.778328866801;5787.334456537384;5792.333881603669;5891;5123.743812809419;5660;5661.716914154358;5678.829635110953;5732.022452253966;4535.931961020521;5041.017001684636;5164.638475306842;5351.518363347317;5450.427705091067;4759.309900064541;5171.586273098068;5556;5653.773849046157;5667.574931880043;5330.440303768049;5655.632383895034;5731.7420716067045;5814.296352196249;5828.726796549265;4772.665913386029;5178.663386879841;5461.563074954073;5582.916256256078;5583.860403489945 09:18:57 INFO - PROCESS | 1949 | |5;yui.html;655.9409653131218;689.9896501552478;731.593965349231;760.5132715062359;804.2681160144269;14035.789463158048;15031.173285469295;15037.473688420907;15045.699086018272;15055.021423607457;15044.871634627392;16058.919705401486;16068.196590170473;16072.517824465285;16289.674206515863;861.6811779641541;884.0938038510546;884.5975081337975;885.4465958775766;885.6545947080643;873.5544872115204;905.9365844390895;912.7672443526891;913.6117150211167;915.8443064678987;835.1940377535707;931.1061381074143;949.6960972488806;956.622134256968;958.9520523973808;942.8727121838601;953.580424613168;954.3319676226669;955.073578628729;957.712686194139;693.7814588404636;698.4203111417511;703.8275622472527;705.675389320913;708.8405108850519;14712.779308310384;14776.113433193936;14781.187034713086;14804.111753294837;15194.468193613171;13863.861361386358;13950.860491395058;13985.510507132201;14079.569379891178;14228;10783.352998820095;11010.834837477445;11069.225154239206;11258.268212566158;11636.243644163102;3611.0972256935765;3647.58052823926;3662.6424416288214;3673.045008297862;3814.065553939275;98545.52181717208;99461.50807737962;100047.49928751074;100486.49756751243;101670.49164754203;9218.400803947856;10031.69904902854;10080.69757907264;10100.646477373257;10493.26547141708;20452.897735511226;20756;20856.687149692767;21056.894715526632;21096;7253.1296244451005;7463.179050304462;7584.355329796918;7655.923440765521;7686.192949740309;4070.430139780433;4131.566185550534;4135.7518548887165;4147.91704165915;4148.336266197394;3096.256898344381;3579.3736096183065;3637.254362855608;3679.2273622539033;3692.8892133236045;676.6041865508635;680.9727610895608;691.0843132848995;695.183159787248;696.9756058537927;607.7113371148669;638.5625846295303;641.884460797061;688.1398252184769;702.6030292884504;8601.82796344082;9141.177294043435;9590.616375344907;9701.223902087817;10042.849357259647;623.2614351992903;659.3867703036223;666.1440049536322;673.6598018000976;674.1674032569772;3505.088676944013;4554.977225113919;4593.356930029731;4651.86044418668;4818.523464610716;88558;92387;93261;93381;93699.59450608246;74496.62751686315;80531;83280;84139.5793021031;84334;61309.38690613126;74661.62669186619;74734;74841.25158748451;75573;8754.47473151613;9138.725838224864;9183.022179671283;9310.581023853807;9336;40482.390160280374;42485.15029699451;44234.028406164754;48629.513704863915;49850.750746246034;46403.76798115988;62523;63367.0494942567;64092.679536602016;65190;10306;11414.315141091562;11569.132315076571;11666.941665291619;11730.472128754056;10722.463876805972;12174;12424.56514022005;12725.03131847025;12841.678958026125;9626.518674066408;9822;11598.304101753922;11738.295702257892;12001.63995080149;10065.295429319878;10315.896841031494;10614.310069845435;10644.148468122687;10688.305260158066;11213.775724485628;11523.423828808358;11570.421478925851;11960.34218117977;12061.276323420623;9200.263978881807;9484.288678349289;9669.516524173903;9672.661456848977;9855.655052073143;8249.546274954935;8327.625256863577;9452.621895124394;9484.905150948678;9505.572249248662 09:18:57 INFO - PROCESS | 1949 | __end_tp_report 09:18:57 INFO - PROCESS | 1949 | __start_cc_report 09:18:57 INFO - PROCESS | 1949 | _x_x_mozilla_cycle_collect,426 09:18:57 INFO - PROCESS | 1949 | __end_cc_report 09:18:57 INFO - PROCESS | 1949 | __startTimestamp1487870337536__endTimestamp 09:18:57 INFO - PROCESS | 1949 | 09:18:57 INFO - PROCESS | 1949 | ------- Summary: start ------- 09:18:57 INFO - PROCESS | 1949 | Number of tests: 6 09:18:57 INFO - PROCESS | 1949 | 09:18:57 INFO - PROCESS | 1949 | [#0] dojo.html Cycles:165 Average:5561.08 Median:5734.40 stddev:638.03 (11.1%) stddev-sans-first:613.19 09:18:57 INFO - PROCESS | 1949 | Values: 3229.0 3265.2 3326.3 3328.3 3353.9 5431.6 5440.3 5462.6 5473.2 5540.9 4324.2 5468.0 5507.4 5534.8 5560.1 4976.8 5025.2 5070.7 5126.2 5147.7 4986.1 5112.9 5195.8 5201.6 5207.6 4970.7 5143.7 5173.3 5177.6 5206.0 5002.9 5017.3 5059.7 5061.1 5100.2 5055.1 5096.0 5119.3 5132.9 5145.3 5162.8 5521.4 5599.6 5614.3 5663.0 5922.5 6009.8 6107.8 6158.5 6166.1 6104.5 6130.0 6130.8 6134.5 6144.6 5659.9 5683.7 5686.7 5717.1 5732.4 5306.5 6143.4 6179.1 6273.6 6287.6 5734.4 5764.6 6004.1 6058.7 6060.3 6041.2 6053.3 6057.3 6080.3 6086.6 6037.8 6105.9 6106.4 6125.7 6137.1 6102.3 6112.9 6115.4 6135.4 6147.6 4145.5 4788.1 4805.2 4851.6 4866.4 4265.5 4270.0 4302.8 4325.1 4362.1 5682.3 5704.6 5728.2 5732.3 5736.4 5764.0 5975.9 6133.9 6210.5 6233.0 5554.5 5566.5 5568.4 5613.7 5668.8 5251.3 5255.1 5278.3 5282.5 5285.0 5588.3 5896.2 6239.7 6257.8 6258.0 5919.4 5986.3 5986.4 5990.4 6003.1 6191.0 6199.8 6203.6 6204.7 6230.2 5790.2 5897.3 5908.8 6060.5 6080.1 6061.3 6063.4 6064.2 6074.4 6085.6 5834.2 5835.7 5846.2 5856.8 5860.8 4815.5 5860.0 5942.1 5942.2 5985.3 5630.4 5759.0 5787.8 5793.6 5822.7 4764.4 5666.2 5764.9 5797.8 5804.8 4713.0 5668.2 5746.3 5765.1 5802.0 09:18:57 INFO - PROCESS | 1949 | 09:18:57 INFO - PROCESS | 1949 | [#1] ext.html Cycles:180 Average:60387.70 Median:35454.95 stddev:124663.94 (351.6%) stddev-sans-first:124932.41 09:18:57 INFO - PROCESS | 1949 | Values: 444.8 484.7 488.6 501.2 536.8 36136.8 38627.8 38665.6 38682.8 38708.8 31885.8 38577.6 38781.4 39104.4 39147.6 9108.0 9335.1 9352.2 10707.5 11065.2 10828.4 10929.7 10939.2 10948.5 10981.5 10994.9 11029.0 11044.8 11060.9 11082.6 10960.4 10999.5 11009.6 11013.2 11020.5 9355.2 10955.7 11013.3 11044.8 11046.6 35115.0 35354.0 35422.6 35445.8 35617.8 3669.4 3690.2 3711.6 3733.5 3882.0 26642.7 26818.7 27885.4 27889.0 28094.0 3080.2 3105.7 3187.2 3208.1 3230.3 426828.0 427467.0 428843.0 429558.0 431483.0 45692.8 46804.0 52686.7 53250.7 60127.0 57829.0 60843.7 62805.1 63073.0 66548.7 15342.0 15375.9 15482.6 15599.3 15599.4 11721.4 11858.8 11869.2 11877.9 11888.8 16290.1 19285.3 19624.6 20027.0 20131.6 6632.4 7165.6 8511.8 8538.9 8605.5 817.5 830.8 842.8 850.8 854.5 25681.0 28409.3 29007.7 29038.6 29090.2 940.0 991.8 1029.8 1060.5 1175.0 12688.3 13460.5 14380.0 14862.8 15238.6 562658.0 564255.0 568289.0 568589.0 569450.0 45534.2 54346.0 55859.4 55949.7 56136.4 1293.0 1362.9 1366.3 1369.7 1388.3 27388.9 28451.1 28623.6 28677.4 28939.6 47133.0 47647.3 47655.8 47707.8 47766.0 391994.0 392426.0 392623.0 392654.0 393227.0 34386.8 38382.8 39168.0 40259.2 40443.6 43380.6 44247.6 44384.3 44880.8 44958.3 29287.7 33497.7 33770.3 35574.6 36910.0 35742.6 36218.7 36396.6 37049.3 37355.0 33425.7 34683.5 35288.1 35464.1 35583.8 26298.3 31610.7 32263.5 32358.2 32465.5 23511.9 23597.3 23968.6 24077.3 24230.4 09:18:57 INFO - PROCESS | 1949 | 09:18:57 INFO - PROCESS | 1949 | [#2] jquery.html Cycles:180 Average:35486.68 Median:25713.87 stddev:100474.09 (390.7%) stddev-sans-first:100721.38 09:18:57 INFO - PROCESS | 1949 | Values: 390.7 393.8 402.3 403.6 420.7 19823.7 20813.1 20880.7 21088.8 21097.3 20695.4 20869.7 21682.7 21712.7 21871.8 5766.2 6000.0 6023.0 6026.3 6035.0 5705.8 5902.1 5903.2 6015.7 6016.1 5262.7 5801.1 5920.2 5937.3 5947.0 5630.9 5772.8 5855.2 5896.4 5907.9 5180.4 5182.7 5188.1 5190.4 5193.0 25563.7 25576.9 25850.9 25854.0 26208.5 2714.7 2744.3 2754.0 2755.9 2782.3 12180.3 15259.5 15475.8 15617.3 15842.6 1757.7 1802.3 1803.7 1823.3 1836.1 93617.0 115950.8 119338.0 120326.8 120928.8 9910.7 10814.1 11457.0 11503.3 11642.2 14622.4 14676.9 14681.0 14685.0 14697.2 8261.4 8413.9 8442.3 8533.8 8618.1 6342.9 8172.3 8220.3 8415.2 8421.2 6944.1 7178.4 7190.9 7202.6 7215.4 1616.5 1674.6 1821.8 1906.4 1909.1 877.2 976.5 993.8 1005.6 1030.1 21019.7 21043.4 21049.7 21050.4 21103.0 980.6 983.4 1006.8 1008.7 1014.2 10483.0 11179.8 11267.6 11300.1 11309.0 513416.0 539374.0 638226.0 650197.0 654309.0 123668.4 123941.0 126559.5 128626.0 130006.0 959.3 1002.7 1013.3 1013.4 1017.9 14505.9 17887.7 18653.4 18856.0 19028.0 52302.7 60195.7 60360.0 60649.1 61554.7 83024.0 84412.0 85471.0 88670.0 89118.0 6172.7 7643.4 8285.6 8517.7 8640.7 10151.8 11776.5 12938.0 13366.0 13508.9 10455.6 10901.2 11272.4 11559.2 11577.0 9293.6 10886.7 11077.6 11185.4 11556.6 11281.5 11363.9 11894.0 11905.9 11970.2 7267.6 8256.0 8396.0 8417.3 8451.7 8256.7 9584.6 10895.3 11324.5 11508.3 09:18:57 INFO - PROCESS | 1949 | 09:18:57 INFO - PROCESS | 1949 | [#3] mootools.html Cycles:180 Average:5339.49 Median:5179.25 stddev:2941.09 (56.8%) stddev-sans-first:2933.30 09:18:57 INFO - PROCESS | 1949 | Values: 1252.2 1374.5 1431.9 1433.5 1461.3 8221.9 8317.3 8589.4 8598.2 8605.6 7124.3 7569.8 8217.3 8582.4 8611.7 2237.1 2274.5 2276.8 2280.2 2289.2 2062.0 2186.3 2264.2 2303.1 2303.6 2244.0 2256.9 2272.1 2281.8 2295.4 1898.8 2156.7 2226.8 2248.8 2251.4 1926.5 2007.1 2234.3 2292.7 2294.4 8232.1 8467.2 8481.5 8578.3 8591.4 5024.0 5178.3 5180.2 5192.6 5200.2 3107.4 3162.3 3164.2 3198.1 3431.3 1146.5 1181.6 1214.6 1218.8 1620.7 10650.3 10746.7 10799.4 10862.0 10875.6 4093.4 4345.4 4388.2 4392.3 4397.2 7296.8 7316.7 8648.9 8697.7 8717.4 5064.7 5070.8 5075.4 5077.3 5094.7 4533.7 4659.3 4684.9 4755.3 4765.7 3055.6 3156.8 3456.4 3666.1 3703.9 1151.0 1151.1 1152.2 1155.4 1156.9 738.9 744.7 888.8 891.3 933.1 5259.3 5522.5 5570.3 5581.0 5706.8 867.6 916.3 940.5 941.2 998.0 3672.6 3789.7 3792.2 3848.3 3927.8 9053.5 9434.6 9617.4 9858.7 9992.7 9909.0 10094.5 10108.7 10125.9 10131.0 6372.4 7840.0 7900.5 7901.2 7907.4 5269.9 5496.2 5512.3 5541.9 5664.8 9131.8 9151.2 9208.6 9388.8 9424.4 7836.7 9298.6 9480.1 9537.5 9555.8 7133.1 7301.3 7311.8 7311.9 7324.9 7629.5 7978.7 8019.4 8039.5 8106.8 7058.2 7105.4 7111.7 7186.7 7190.6 5960.9 6994.3 7027.5 7048.7 7063.9 6322.8 6333.5 6350.4 6363.7 6399.8 4743.5 4773.3 4844.3 4865.2 4945.7 4910.5 4916.2 4957.1 5025.1 5033.7 09:18:57 INFO - PROCESS | 1949 | 09:18:57 INFO - PROCESS | 1949 | [#4] prototype.html Cycles:180 Average:4498.33 Median:5104.53 stddev:1419.63 (27.8%) stddev-sans-first:1388.72 09:18:57 INFO - PROCESS | 1949 | Values: 330.5 350.8 351.0 354.4 355.5 4560.7 4914.4 5322.7 5419.3 5420.6 4422.7 5353.7 5392.3 5416.5 5457.7 3319.7 3340.3 3479.9 4030.3 4089.6 3436.7 3449.7 4026.6 4068.8 4107.5 3559.5 3726.9 3875.4 4046.5 4062.7 3885.6 3947.5 4038.9 4061.5 4061.5 3446.3 3446.4 3447.2 3451.9 3462.9 5258.8 5260.4 5264.7 5279.3 5328.8 4162.8 4474.7 4482.4 4589.0 4625.5 5580.9 5615.8 5630.0 5654.7 5688.8 3222.5 3797.5 3806.4 3847.5 3847.7 5291.3 5687.5 5899.9 6022.5 6063.7 3715.2 3726.2 3822.9 3831.5 3855.9 3462.4 3761.5 3887.9 3908.6 3927.9 3709.8 4488.3 4558.0 4563.0 4586.8 5103.8 5339.5 5442.6 5717.3 5761.7 1094.0 1194.5 1201.6 1250.1 1264.2 799.6 809.4 814.3 857.5 861.3 4587.6 4775.8 4882.7 4948.0 4959.4 4909.5 4978.8 5120.9 5233.9 5615.5 4523.7 4839.9 4876.0 5091.3 5105.2 2474.0 2521.8 2961.0 2963.3 3019.0 5140.3 5278.1 5469.8 5505.4 5599.5 4653.5 5222.1 5557.9 5808.7 5951.8 5080.9 5314.5 5825.7 5960.4 5997.7 5854.6 5857.2 5857.4 5871.2 5946.6 5187.8 5340.8 5618.3 5744.5 5765.7 6100.8 6108.3 6117.8 6157.0 6216.3 3324.8 3780.4 3832.8 3865.0 3889.4 5354.2 5541.8 5787.3 5792.3 5891.0 5123.7 5660.0 5661.7 5678.8 5732.0 4535.9 5041.0 5164.6 5351.5 5450.4 4759.3 5171.6 5556.0 5653.8 5667.6 5330.4 5655.6 5731.7 5814.3 5828.7 4772.7 5178.7 5461.6 5582.9 5583.9 09:18:57 INFO - PROCESS | 1949 | 09:18:57 INFO - PROCESS | 1949 | [#5] yui.html Cycles:180 Average:18807.65 Median:62945.02 stddev:26986.23 (42.9%) stddev-sans-first:27027.52 09:18:57 INFO - PROCESS | 1949 | Values: 655.9 690.0 731.6 760.5 804.3 14035.8 15031.2 15037.5 15045.7 15055.0 15044.9 16058.9 16068.2 16072.5 16289.7 861.7 884.1 884.6 885.4 885.7 873.6 905.9 912.8 913.6 915.8 835.2 931.1 949.7 956.6 959.0 942.9 953.6 954.3 955.1 957.7 693.8 698.4 703.8 705.7 708.8 14712.8 14776.1 14781.2 14804.1 15194.5 13863.9 13950.9 13985.5 14079.6 14228.0 10783.4 11010.8 11069.2 11258.3 11636.2 3611.1 3647.6 3662.6 3673.0 3814.1 98545.5 99461.5 100047.5 100486.5 101670.5 9218.4 10031.7 10080.7 10100.6 10493.3 20452.9 20756.0 20856.7 21056.9 21096.0 7253.1 7463.2 7584.4 7655.9 7686.2 4070.4 4131.6 4135.8 4147.9 4148.3 3096.3 3579.4 3637.3 3679.2 3692.9 676.6 681.0 691.1 695.2 697.0 607.7 638.6 641.9 688.1 702.6 8601.8 9141.2 9590.6 9701.2 10042.8 623.3 659.4 666.1 673.7 674.2 3505.1 4555.0 4593.4 4651.9 4818.5 88558.0 92387.0 93261.0 93381.0 93699.6 74496.6 80531.0 83280.0 84139.6 84334.0 61309.4 74661.6 74734.0 74841.3 75573.0 8754.5 9138.7 9183.0 9310.6 9336.0 40482.4 42485.2 44234.0 48629.5 49850.8 46403.8 62523.0 63367.0 64092.7 65190.0 10306.0 11414.3 11569.1 11666.9 11730.5 10722.5 12174.0 12424.6 12725.0 12841.7 9626.5 9822.0 11598.3 11738.3 12001.6 10065.3 10315.9 10614.3 10644.1 10688.3 11213.8 11523.4 11570.4 11960.3 12061.3 9200.3 9484.3 9669.5 9672.7 9855.7 8249.5 8327.6 9452.6 9484.9 9505.6 09:18:57 INFO - PROCESS | 1949 | -------- Summary: end -------- 09:18:57 INFO - PROCESS | 1949 | 09:18:57 INFO - PROCESS | 1949 | 1487870337562 addons.xpi WARN Add-on disableSHA1rollout@mozilla.org is missing bootstrap method shutdown 09:18:58 INFO - TEST-INFO | 1949: exit 0 09:18:58 INFO - TEST-OK | dromaeo_css | took 1116988ms 09:18:58 INFO - TEST-START | kraken 09:18:58 INFO - operating with platform_type : mac_ 09:18:58 INFO - Initialising browser for kraken test... 09:18:58 INFO - TEST-INFO | started process 1954 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/tmp2eLjcp/profile http://localhost:49318/getInfo.html) 09:18:58 INFO - PROCESS | 1954 | 1487870338715 addons.xpi WARN Error loading bootstrap.js for disableSHA1rollout@mozilla.org: Error opening input stream (invalid filename?): jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/disableSHA1rollout@mozilla.org.xpi!/bootstrap.js 09:18:58 INFO - PROCESS | 1954 | 1487870338716 addons.xpi WARN Add-on disableSHA1rollout@mozilla.org is missing bootstrap method install 09:18:58 INFO - PROCESS | 1954 | 1487870338756 addons.xpi WARN Add-on disableSHA1rollout@mozilla.org is missing bootstrap method startup 09:19:01 INFO - PROCESS | 1954 | 2017-02-23 09:19:01.150 plugin-container[1955:13082] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x883f, name = 'com.apple.tsm.portname' 09:19:01 INFO - PROCESS | 1954 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:19:01 INFO - PROCESS | 1954 | 2017-02-23 09:19:01.153 plugin-container[1955:13082] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8e0f, name = 'com.apple.CFPasteboardClient' 09:19:01 INFO - PROCESS | 1954 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:19:01 INFO - PROCESS | 1954 | 2017-02-23 09:19:01.153 plugin-container[1955:13082] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:19:01 INFO - PROCESS | 1954 | __metrics Screen width/height:1600/1200 09:19:01 INFO - PROCESS | 1954 | colorDepth:24 09:19:01 INFO - PROCESS | 1954 | Browser inner width/height: 1024/689 09:19:01 INFO - PROCESS | 1954 | __metrics 09:19:01 ERROR - PROCESS | 1954 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3280: TypeError: this.tabs is undefined 09:19:01 INFO - PROCESS | 1954 | 1487870341355 addons.xpi WARN Add-on disableSHA1rollout@mozilla.org is missing bootstrap method shutdown 09:19:02 INFO - TEST-INFO | 1954: exit 0 09:19:02 INFO - Browser initialized. 09:19:02 INFO - Running cycle 1/1 for kraken test... 09:19:02 INFO - TEST-INFO | started process 1956 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/tmp2eLjcp/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:19:02 INFO - PROCESS | 1956 | 1487870342435 addons.xpi WARN Error loading bootstrap.js for disableSHA1rollout@mozilla.org: Error opening input stream (invalid filename?): jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/disableSHA1rollout@mozilla.org.xpi!/bootstrap.js 09:19:02 INFO - PROCESS | 1956 | 1487870342436 addons.xpi WARN Add-on disableSHA1rollout@mozilla.org is missing bootstrap method startup 09:19:04 INFO - PROCESS | 1956 | 2017-02-23 09:19:04.715 plugin-container[1957:13207] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x883f, name = 'com.apple.tsm.portname' 09:19:04 INFO - PROCESS | 1956 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:19:04 INFO - PROCESS | 1956 | 2017-02-23 09:19:04.715 plugin-container[1957:13207] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8b0b, name = 'com.apple.CFPasteboardClient' 09:19:04 INFO - PROCESS | 1956 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:19:04 INFO - PROCESS | 1956 | 2017-02-23 09:19:04.715 plugin-container[1957:13207] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:19:14 INFO - PROCESS | 1956 | RSS: Main: 226684928 09:19:14 INFO - PROCESS | 1956 | 09:19:17 INFO - PROCESS | 1956 | Cycle 1(1): loaded http://localhost:49318/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49318/tests/kraken/driver.html?testName=audio-beat-detection) 09:19:18 INFO - PROCESS | 1956 | RSS: Main: 240443392 09:19:18 INFO - PROCESS | 1956 | 09:19:21 INFO - PROCESS | 1956 | Cycle 1(1): loaded http://localhost:49318/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49318/tests/kraken/driver.html?testName=audio-dft) 09:19:21 INFO - PROCESS | 1956 | RSS: Main: 248344576 09:19:21 INFO - PROCESS | 1956 | 09:19:25 INFO - PROCESS | 1956 | Cycle 1(1): loaded http://localhost:49318/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49318/tests/kraken/driver.html?testName=audio-fft) 09:19:26 INFO - PROCESS | 1956 | RSS: Main: 251019264 09:19:26 INFO - PROCESS | 1956 | 09:19:27 INFO - PROCESS | 1956 | Cycle 1(1): loaded http://localhost:49318/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49318/tests/kraken/driver.html?testName=audio-oscillator) 09:19:28 INFO - PROCESS | 1956 | RSS: Main: 252243968 09:19:28 INFO - PROCESS | 1956 | 09:19:30 INFO - PROCESS | 1956 | Cycle 1(1): loaded http://localhost:49318/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49318/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:19:31 INFO - PROCESS | 1956 | RSS: Main: 253861888 09:19:31 INFO - PROCESS | 1956 | 09:19:34 INFO - PROCESS | 1956 | Cycle 1(1): loaded http://localhost:49318/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49318/tests/kraken/driver.html?testName=imaging-darkroom) 09:19:34 INFO - PROCESS | 1956 | RSS: Main: 259362816 09:19:34 INFO - PROCESS | 1956 | 09:19:37 INFO - PROCESS | 1956 | Cycle 1(1): loaded http://localhost:49318/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49318/tests/kraken/driver.html?testName=imaging-desaturate) 09:19:38 INFO - PROCESS | 1956 | RSS: Main: 259125248 09:19:38 INFO - PROCESS | 1956 | 09:19:41 INFO - PROCESS | 1956 | Cycle 1(1): loaded http://localhost:49318/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49318/tests/kraken/driver.html?testName=json-parse-financial) 09:19:42 INFO - PROCESS | 1956 | RSS: Main: 258957312 09:19:42 INFO - PROCESS | 1956 | 09:19:44 INFO - PROCESS | 1956 | Cycle 1(1): loaded http://localhost:49318/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49318/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:19:44 INFO - PROCESS | 1956 | RSS: Main: 258424832 09:19:44 INFO - PROCESS | 1956 | 09:19:46 INFO - PROCESS | 1956 | Cycle 1(1): loaded http://localhost:49318/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49318/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:19:47 INFO - PROCESS | 1956 | RSS: Main: 258453504 09:19:47 INFO - PROCESS | 1956 | 09:19:49 INFO - PROCESS | 1956 | Cycle 1(1): loaded http://localhost:49318/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49318/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:19:50 INFO - PROCESS | 1956 | RSS: Main: 258015232 09:19:50 INFO - PROCESS | 1956 | 09:19:53 INFO - PROCESS | 1956 | Cycle 1(1): loaded http://localhost:49318/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49318/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:19:54 INFO - PROCESS | 1956 | RSS: Main: 258347008 09:19:54 INFO - PROCESS | 1956 | 09:19:56 INFO - PROCESS | 1956 | Cycle 1(1): loaded http://localhost:49318/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49318/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:19:57 INFO - PROCESS | 1956 | RSS: Main: 258146304 09:19:57 INFO - PROCESS | 1956 | 09:19:59 INFO - PROCESS | 1956 | Cycle 1(1): loaded http://localhost:49318/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49318/tests/kraken/driver.html?testName=ai-astar) 09:19:59 INFO - PROCESS | 1956 | RSS: Main: 261283840 09:19:59 INFO - PROCESS | 1956 | 09:19:59 INFO - PROCESS | 1956 | __start_tp_report 09:19:59 INFO - PROCESS | 1956 | _x_x_mozilla_page_load 09:19:59 INFO - PROCESS | 1956 | _x_x_mozilla_page_load_details 09:19:59 INFO - PROCESS | 1956 | |i|pagename|runs| 09:19:59 INFO - PROCESS | 1956 | |0;ai-astar;137;101;175;105;165;176;175;117;118;117 09:19:59 INFO - PROCESS | 1956 | |1;audio-beat-detection;150;115;134;158;116;116;132;116;148;115 09:19:59 INFO - PROCESS | 1956 | |2;audio-dft;209;214;223;209;275;198;208;279;242;220 09:19:59 INFO - PROCESS | 1956 | |3;audio-fft;82;60;60;90;60;60;62;63;75;61 09:19:59 INFO - PROCESS | 1956 | |4;audio-oscillator;89;71;83;72;85;98;85;70;69;82 09:19:59 INFO - PROCESS | 1956 | |5;imaging-gaussian-blur;89;89;88;91;84;85;104;137;113;90 09:19:59 INFO - PROCESS | 1956 | |6;imaging-darkroom;153;106;97;98;96;95;97;105;95;152 09:19:59 INFO - PROCESS | 1956 | |7;imaging-desaturate;93;95;91;135;148;129;141;132;93;100 09:19:59 INFO - PROCESS | 1956 | |8;json-parse-financial;62;60;68;83;92;73;62;62;61;62 09:19:59 INFO - PROCESS | 1956 | |9;json-stringify-tinderbox;72;77;70;82;104;75;72;86;71;78 09:19:59 INFO - PROCESS | 1956 | |10;stanford-crypto-aes;90;86;90;93;107;107;90;90;88;81 09:19:59 INFO - PROCESS | 1956 | |11;stanford-crypto-ccm;150;139;146;163;141;144;145;153;203;177 09:19:59 INFO - PROCESS | 1956 | |12;stanford-crypto-pbkdf2;177;179;173;177;172;176;175;175;176;174 09:19:59 INFO - PROCESS | 1956 | |13;stanford-crypto-sha256-iterative;69;66;68;70;67;69;76;86;88;84 09:19:59 INFO - PROCESS | 1956 | __end_tp_report 09:19:59 INFO - PROCESS | 1956 | __start_cc_report 09:19:59 INFO - PROCESS | 1956 | _x_x_mozilla_cycle_collect,1012 09:19:59 INFO - PROCESS | 1956 | __end_cc_report 09:19:59 INFO - PROCESS | 1956 | __startTimestamp1487870399656__endTimestamp 09:19:59 INFO - PROCESS | 1956 | 09:19:59 INFO - PROCESS | 1956 | ------- Summary: start ------- 09:19:59 INFO - PROCESS | 1956 | Number of tests: 14 09:19:59 INFO - PROCESS | 1956 | 09:19:59 INFO - PROCESS | 1956 | [#0] ai-astar Cycles:10 Average:138.60 Median:151.00 stddev:31.00 (20.5%) stddev-sans-first:32.87 09:19:59 INFO - PROCESS | 1956 | Values: 137.0 101.0 175.0 105.0 165.0 176.0 175.0 117.0 118.0 117.0 09:19:59 INFO - PROCESS | 1956 | 09:19:59 INFO - PROCESS | 1956 | [#1] audio-beat-detection Cycles:10 Average:130.00 Median:133.00 stddev:16.89 (12.7%) stddev-sans-first:16.28 09:19:59 INFO - PROCESS | 1956 | Values: 150.0 115.0 134.0 158.0 116.0 116.0 132.0 116.0 148.0 115.0 09:19:59 INFO - PROCESS | 1956 | 09:19:59 INFO - PROCESS | 1956 | [#2] audio-dft Cycles:10 Average:227.70 Median:221.50 stddev:28.50 (12.9%) stddev-sans-first:29.42 09:19:59 INFO - PROCESS | 1956 | Values: 209.0 214.0 223.0 209.0 275.0 198.0 208.0 279.0 242.0 220.0 09:19:59 INFO - PROCESS | 1956 | 09:19:59 INFO - PROCESS | 1956 | [#3] audio-fft Cycles:10 Average:67.30 Median:62.50 stddev:11.01 (17.6%) stddev-sans-first:10.31 09:19:59 INFO - PROCESS | 1956 | Values: 82.0 60.0 60.0 90.0 60.0 60.0 62.0 63.0 75.0 61.0 09:19:59 INFO - PROCESS | 1956 | 09:19:59 INFO - PROCESS | 1956 | [#4] audio-oscillator Cycles:10 Average:80.40 Median:84.00 stddev:9.62 (11.4%) stddev-sans-first:9.68 09:19:59 INFO - PROCESS | 1956 | Values: 89.0 71.0 83.0 72.0 85.0 98.0 85.0 70.0 69.0 82.0 09:19:59 INFO - PROCESS | 1956 | 09:19:59 INFO - PROCESS | 1956 | [#5] imaging-gaussian-blur Cycles:10 Average:97.00 Median:88.50 stddev:16.71 (18.9%) stddev-sans-first:17.47 09:19:59 INFO - PROCESS | 1956 | Values: 89.0 89.0 88.0 91.0 84.0 85.0 104.0 137.0 113.0 90.0 09:19:59 INFO - PROCESS | 1956 | 09:19:59 INFO - PROCESS | 1956 | [#6] imaging-darkroom Cycles:10 Average:109.40 Median:95.50 stddev:23.04 (24.1%) stddev-sans-first:18.26 09:19:59 INFO - PROCESS | 1956 | Values: 153.0 106.0 97.0 98.0 96.0 95.0 97.0 105.0 95.0 152.0 09:19:59 INFO - PROCESS | 1956 | 09:19:59 INFO - PROCESS | 1956 | [#7] imaging-desaturate Cycles:10 Average:115.70 Median:119.50 stddev:23.13 (19.4%) stddev-sans-first:23.03 09:19:59 INFO - PROCESS | 1956 | Values: 93.0 95.0 91.0 135.0 148.0 129.0 141.0 132.0 93.0 100.0 09:19:59 INFO - PROCESS | 1956 | 09:19:59 INFO - PROCESS | 1956 | [#8] json-parse-financial Cycles:10 Average:68.50 Median:65.00 stddev:10.96 (16.9%) stddev-sans-first:11.37 09:19:59 INFO - PROCESS | 1956 | Values: 62.0 60.0 68.0 83.0 92.0 73.0 62.0 62.0 61.0 62.0 09:19:59 INFO - PROCESS | 1956 | 09:19:59 INFO - PROCESS | 1956 | [#9] json-stringify-tinderbox Cycles:10 Average:78.70 Median:76.00 stddev:10.25 (13.5%) stddev-sans-first:10.58 09:19:59 INFO - PROCESS | 1956 | Values: 72.0 77.0 70.0 82.0 104.0 75.0 72.0 86.0 71.0 78.0 09:19:59 INFO - PROCESS | 1956 | 09:19:59 INFO - PROCESS | 1956 | [#10] stanford-crypto-aes Cycles:10 Average:92.20 Median:90.00 stddev:8.43 (9.4%) stddev-sans-first:8.90 09:19:59 INFO - PROCESS | 1956 | Values: 90.0 86.0 90.0 93.0 107.0 107.0 90.0 90.0 88.0 81.0 09:19:59 INFO - PROCESS | 1956 | 09:19:59 INFO - PROCESS | 1956 | [#11] stanford-crypto-ccm Cycles:10 Average:156.10 Median:151.50 stddev:20.06 (13.2%) stddev-sans-first:21.16 09:19:59 INFO - PROCESS | 1956 | Values: 150.0 139.0 146.0 163.0 141.0 144.0 145.0 153.0 203.0 177.0 09:19:59 INFO - PROCESS | 1956 | 09:19:59 INFO - PROCESS | 1956 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:175.40 Median:176.00 stddev:2.07 (1.2%) stddev-sans-first:2.11 09:19:59 INFO - PROCESS | 1956 | Values: 177.0 179.0 173.0 177.0 172.0 176.0 175.0 175.0 176.0 174.0 09:19:59 INFO - PROCESS | 1956 | 09:19:59 INFO - PROCESS | 1956 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:74.30 Median:73.00 stddev:8.55 (11.7%) stddev-sans-first:8.85 09:19:59 INFO - PROCESS | 1956 | Values: 69.0 66.0 68.0 70.0 67.0 69.0 76.0 86.0 88.0 84.0 09:19:59 INFO - PROCESS | 1956 | -------- Summary: end -------- 09:19:59 INFO - PROCESS | 1956 | 09:19:59 INFO - PROCESS | 1956 | 1487870399679 addons.xpi WARN Add-on disableSHA1rollout@mozilla.org is missing bootstrap method shutdown 09:20:00 INFO - TEST-INFO | 1956: exit 0 09:20:00 INFO - TEST-OK | kraken | took 62357ms 09:20:00 INFO - SUITE-END | took 1179s 09:20:00 INFO - Completed test suite (00:19:39) 09:20:00 INFO - javascript benchmark 09:20:00 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7121.469595418365, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3228.9838550807244, 3265.167382317509, 3326.2848487575166, 3328.3176948725527, 3353.882614108507, 5431.619786614929, 5440.292761940952, 5462.590305727072, 5473.233747275374, 5540.86147846303, 4324.243257429953, 5468.0, 5507.394186639466, 5534.75093620787, 5560.138178582316, 4976.776045077981, 5025.246213068051, 5070.72111033893, 5126.205438157073, 5147.691138531682, 4986.077575648516, 5112.872178195537, 5195.792168313263, 5201.5838732901275, 5207.583393328525, 4970.701757894538, 5143.691378517301, 5173.275741396207, 5177.637565370425, 5205.973970130125, 5002.874928126789, 5017.2724954881705, 5059.721715305656, 5061.139606266944, 5100.234964755279, 5055.11535481293, 5096.031753966735, 5119.25770763241, 5132.871678208037, 5145.25393817898, 5162.793488260465, 5521.3926468088475, 5599.636023658449, 5614.326280846284, 5663.037283661787, 5922.496587790043, 6009.789657362015, 6107.847303817396, 6158.538109641795, 6166.136740856284, 6104.4505994460715, 6129.969350153221, 6130.754769809202, 6134.478569321613, 6144.600600960924, 5659.8585035374035, 5683.715814209273, 5686.658800471985, 5717.1138473537085, 5732.398098199629, 5306.469353064663, 6143.447089761943, 6179.104029915637, 6273.592216505913, 6287.559870808999, 5734.397888221677, 5764.625299355528, 6004.0693692477735, 6058.697065146725, 6060.272767267956, 6041.214642096499, 6053.303870054912, 6057.3336932937345, 6080.270367555921, 6086.6348019119, 6037.849053773691, 6105.945000794801, 6106.39999602577, 6125.693715314216, 6137.101203255209, 6102.298235702862, 6112.8777424452155, 6115.388461153849, 6135.417135372132, 6147.600405973598, 4145.461090058334, 4788.066327066258, 4805.183118869801, 4851.593038018935, 4866.367372241586, 4265.509466411402, 4269.978650106729, 4302.7848607569495, 4325.070109926381, 4362.083962367875, 5682.261306030272, 5704.629199102045, 5728.198052272685, 5732.34078081026, 5736.397678243808, 5763.971180144073, 5975.940240597625, 6133.877322453615, 6210.5031597473, 6233.0, 5554.527865131509, 5566.526845218121, 5568.359638641527, 5613.663180209119, 5668.801591944262, 5251.264822924793, 5255.08036093682, 5278.313819203479, 5282.524572788468, 5285.022270879899, 5588.273524441796, 5896.204012458435, 6239.656818874914, 6257.812265632038, 6257.967435373203, 5919.378465261086, 5986.311574169026, 5986.371430999682, 5990.430909063632, 6003.129546215687, 6191.040388739752, 6199.752009919553, 6203.565750397609, 6204.689765511796, 6230.221222347207, 5790.218320526844, 5897.262842144732, 5908.763649453974, 6060.545459090497, 6080.087986802015, 6061.333253342041, 6063.393660634078, 6064.211652485149, 6074.392560744067, 6085.604435711664, 5834.212381328466, 5835.708214589169, 5846.239988801429, 5856.824295271149, 5860.765569377348, 4815.470298267258, 5860.0, 5942.078977758454, 5942.168096466585, 5985.281766188085, 5630.4369563043365, 5758.971205143947, 5787.797427090031, 5793.594448388572, 5822.679752613647, 4764.404449443819, 5666.150077488245, 5764.913526297193, 5797.826065218051, 5804.796832110856, 4712.976435117803, 5668.206451096932, 5746.310442746897, 5765.106408506688, 5801.9709901451915], "value": 5521.907859135423, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [444.77538842884337, 484.71644088208416, 488.60178954152383, 501.1655593436928, 536.7879687523431, 36136.81931590342, 38627.806860965655, 38665.64670236539, 38682.80658596703, 38708.80645596775, 31885.840570797114, 38577.61422385775, 38781.41827872584, 39104.413433798516, 39147.60852391475, 9108.043655416186, 9335.066493350645, 9352.15830575248, 10707.518161682743, 11065.170112241614, 10828.350298982046, 10929.672109836678, 10939.234253602212, 10948.507317170706, 10981.450927453636, 10994.890051099506, 11029.007389334958, 11044.779104417907, 11060.944695276512, 11082.6121085762, 10960.397178155199, 10999.450027498593, 11009.61466348682, 11013.229073964825, 11020.5040773165, 9355.158035776745, 10955.726106847313, 11013.339199647966, 11044.834327485094, 11046.613368532144, 35115.0, 35354.0, 35422.64577354219, 35445.82277088598, 35617.82191089028, 3669.376206044952, 3690.186737710513, 3711.6102809205186, 3733.5146430964073, 3882.048898020003, 26642.73357266422, 26818.731812682014, 27885.442291154064, 27889.0, 28094.0, 3080.168354544283, 3105.6583775784648, 3187.235063584746, 3208.053624180849, 3230.321632457163, 426828.0, 427467.0, 428843.0, 429558.0, 431483.0, 45692.77153614277, 46804.0, 52686.73656631769, 53250.73374633102, 60127.0, 57829.0, 60843.69578152081, 62805.05792413117, 63073.0, 66548.66725666437, 15342.002769819926, 15375.923685341919, 15482.612934676497, 15599.29803158838, 15599.376024959101, 11721.4139293035, 11858.762824743457, 11869.228500147463, 11877.88122118785, 11888.821667674993, 16290.104044277441, 19285.32258303563, 19624.607507849763, 20027.0, 20131.59736805285, 6632.436242919406, 7165.6417179140835, 8511.829763404698, 8538.87191692125, 8605.49403854323, 817.48764785147, 830.7776697334606, 842.751388340441, 850.8213275212152, 854.4830377621573, 25681.0, 28409.28976775597, 29007.70992290092, 29038.564421533694, 29090.151078993917, 940.022376728195, 991.7966816802539, 1029.8197815382277, 1060.528065011078, 1174.9530018799323, 12688.302143382203, 13460.461581536825, 14380.0, 14862.80449703319, 15238.619034524005, 562658.0, 564255.0, 568289.0, 568589.0, 569450.0, 45534.17863285498, 54346.0, 55859.441405586236, 55949.720251399296, 56136.43863561394, 1292.954746583865, 1362.868818880327, 1366.3031853754658, 1369.6712788930586, 1388.2919710947288, 27388.86305568539, 28451.146465606063, 28623.570646439905, 28677.426451470437, 28939.565906510998, 47133.0, 47647.28529071997, 47655.76172119117, 47707.76146119247, 47766.04467910553, 391994.0, 392426.0, 392623.0, 392654.0, 393227.0, 34386.796462123704, 38382.80808595939, 39168.0, 40259.1948161041, 40443.59556404516, 43380.566194337654, 44247.557524424345, 44384.33423498586, 44880.775596123116, 44958.325625114994, 29287.7071229285, 33497.66502334945, 33770.324593508485, 35574.64425355713, 36910.0, 35742.64257357393, 36218.74031337192, 36396.636033639326, 37049.259014819014, 37355.0, 33425.66574334323, 34683.4797478033, 35288.11779705528, 35464.08329776565, 35583.82208088943, 26298.342541435853, 31610.683893160774, 32263.54814033327, 32358.19104522406, 32465.51301730523, 23511.882440587688, 23597.29208123759, 23968.640470393308, 24077.277681669577, 24230.394240143432], "value": 19269.913864957853, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [390.6718356580472, 393.77161246477044, 402.3441789882491, 403.5601194697779, 420.6697742272317, 19823.702644460343, 20813.063412146454, 20880.686789698168, 21088.789112108876, 21097.26159584415, 20695.379138625827, 20869.68695469569, 21682.674759878613, 21712.674309885366, 21871.781282187174, 5766.221560089376, 5999.970000149993, 6023.036314189731, 6026.337102918676, 6034.974054410739, 5705.828825135231, 5902.144189092579, 5903.203067585885, 6015.6992150392525, 6016.067509536029, 5262.684238945675, 5801.129830525434, 5920.171176035358, 5937.346891841894, 5946.970265148668, 5630.943690563083, 5772.826815195551, 5855.151003104526, 5896.380880007602, 5907.911381329262, 5180.430152683201, 5182.663126896739, 5188.11801993662, 5190.4031036430915, 5193.013327467806, 25563.74436255632, 25576.87211563949, 25850.870745646338, 25854.0, 26208.475830483476, 2714.7285271472697, 2744.341358074068, 2753.994791900962, 2755.875985580654, 2782.318332008671, 12180.269183848997, 15259.465918692904, 15475.845241547553, 15617.29722162494, 15842.603934901605, 1757.7432136022805, 1802.342145117023, 1803.6843552378411, 1823.2706917233213, 1836.1094868988664, 93617.0, 115950.84049159569, 119338.0, 120326.79673203244, 120928.79071209264, 9910.702678919644, 10814.080803131821, 11457.0, 11503.252288601214, 11642.185047046625, 14622.415103395957, 14676.853231467761, 14681.0, 14684.972051956474, 14697.191654458893, 8261.38039647017, 8413.915860841313, 8442.324614030862, 8533.786655333666, 8618.095100014534, 6342.936570634327, 8172.346212303001, 8220.301274391624, 8415.158484151658, 8421.242088211968, 6944.131983502062, 7178.425725941912, 7190.9280907191305, 7202.603856787822, 7215.422766178693, 1616.5473667373155, 1674.6232097777902, 1821.8087100854484, 1906.3518403742794, 1909.112262797778, 877.1929824561419, 976.4531862157214, 993.8012397520525, 1005.5570256681685, 1030.108955753268, 21019.68470472944, 21043.368698939055, 21049.684254736192, 21050.368488945354, 21103.0, 980.6175591519313, 983.3559018842669, 1006.7885743993843, 1008.6873069348526, 1014.1683819268129, 10483.012341482794, 11179.83230251547, 11267.605633802941, 11300.095992320594, 11309.0, 513416.0, 539374.0, 638226.0, 650197.0, 654309.0, 123668.38165809293, 123941.0, 126559.46881062328, 128626.0, 130006.0, 959.2949182350967, 1002.674130907458, 1013.3261381181579, 1013.3717095400899, 1017.9287449878437, 14505.854941450873, 17887.73168402501, 18653.44039678812, 18856.0, 19028.0, 52302.738486307324, 60195.69902150461, 60359.99999999825, 60649.0902636452, 61554.69222653858, 83024.0, 84412.0, 85471.0, 88670.0, 89118.0, 6172.722227499863, 7643.4267429941865, 8285.585720714062, 8517.701880434155, 8640.740777776677, 10151.84772228432, 11776.52893884235, 12938.0, 13366.0, 13508.932455338054, 10455.581776728846, 10901.182411319342, 11272.380019099028, 11559.19085664029, 11577.0, 9293.581788819383, 10886.693596768439, 11077.612283570037, 11185.384803835866, 11556.59551915679, 11281.548738050715, 11363.943180284046, 11894.048476121716, 11905.940470297593, 11970.162088653711, 7267.600281984542, 8255.958720206361, 8395.950506186726, 8417.326613870999, 8451.746447606582, 8256.669733210605, 9584.616615335588, 10895.346279223271, 11324.490397932277, 11508.309501429942], "value": 9857.320614229478, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1252.1923359433165, 1374.4502199120354, 1431.9212443315614, 1433.4767809749437, 1461.3496993837744, 8221.91778082219, 8317.293030092434, 8589.355798315135, 8598.22615964563, 8605.612747426365, 7124.287571242873, 7569.788833786595, 8217.301529369995, 8582.399232053758, 8611.698590549331, 2237.093976939338, 2274.465500607356, 2276.829237807163, 2280.2361208995007, 2289.2331069091874, 2061.9793802061936, 2186.278528085728, 2264.184893438361, 2303.1363238785457, 2303.585354636165, 2243.9775602244013, 2256.909723611054, 2272.1138755885136, 2281.7718228177214, 2295.4490922178725, 1898.7531620889333, 2156.7088443060143, 2226.7661895500983, 2248.808851247646, 2251.3808702606752, 1926.4702206893076, 2007.056683358819, 2234.2850287907877, 2292.6904867842795, 2294.357579877637, 8232.094469608339, 8467.237948584596, 8481.491110533389, 8578.313734901192, 8591.398602097855, 5024.0, 5178.275041494194, 5180.222966555009, 5192.584593232532, 5200.167973124282, 3107.409592177479, 3162.320101178235, 3164.2089477630593, 3198.0885447647543, 3431.296584200233, 1146.5012719467059, 1181.6159748081823, 1214.6295379909125, 1218.8049912014035, 1620.6710497392144, 10650.25448218625, 10746.677599672023, 10799.352038877692, 10862.02241798242, 10875.619353322596, 4093.385992101149, 4345.413369195181, 4388.232059389594, 4392.253316936129, 4397.164538737629, 7296.7810965671115, 7316.670749816271, 8648.913510864812, 8697.652093916298, 8717.389782715276, 5064.746762661852, 5070.8225212117395, 5075.390953085654, 5077.314562534085, 5094.694318340911, 4533.705309154961, 4659.324397962277, 4684.859454216379, 4755.286706993978, 4765.666403351732, 3055.6486004109365, 3156.826374549422, 3456.4469684850305, 3666.0834791302173, 3703.9444408333898, 1150.9712257193469, 1151.073385924339, 1152.1877076660935, 1155.399192419937, 1156.8553930758655, 738.8670039392961, 744.709563270325, 888.8400087984224, 891.31814162166, 933.0762545080457, 5259.289995850588, 5522.530584900329, 5570.275864137636, 5580.953808488813, 5706.80026199081, 867.5922316511229, 916.3219217778795, 940.5062342270226, 941.204682043672, 997.9501024948723, 3672.559292884871, 3789.6778773804535, 3792.241551689673, 3848.3073046851837, 3927.7643341399607, 9053.547322633842, 9434.622615095457, 9617.374870633386, 9858.654947076819, 9992.700218993441, 9908.950455247821, 10094.545745441474, 10108.696739097839, 10125.898741012643, 10131.0, 6372.36276372359, 7840.019997500426, 7900.486468379537, 7901.170377110436, 7907.406944479072, 5269.893322402185, 5496.1755736639825, 5512.310961129859, 5541.889162216814, 5664.801731939362, 9131.817363652823, 9151.17639412456, 9208.585613647268, 9388.76528086776, 9424.434533927986, 7836.686532538634, 9298.581563829508, 9480.099390557885, 9537.475438850652, 9555.808883822423, 7133.108361454818, 7301.342879140902, 7311.780646580611, 7311.926880731337, 7324.926750732638, 7629.46593738433, 7978.680852765824, 8019.438639295194, 8039.4774339670075, 8106.756797296091, 7058.223595404604, 7105.396041336544, 7111.679974401268, 7186.712531498891, 7190.604516751419, 5960.940390596212, 6994.33553812387, 7027.472939529658, 7048.718051278097, 7063.894041589483, 6322.778702745381, 6333.461655759312, 6350.364963503797, 6363.713632886624, 6399.776007839703, 4743.45450273223, 4773.331733557236, 4844.346013288302, 4865.172920603435, 4945.653804233669, 4910.533499317559, 4916.188828843344, 4957.082939656175, 5025.120603894377, 5033.748312584283], "value": 4322.680573789468, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [330.5207449198661, 350.79829098268493, 350.95415661329224, 354.3957552373204, 355.54312708170005, 4560.749158796265, 4914.4348399934015, 5322.749153948819, 5419.295491586088, 5420.620556561043, 4422.734635921847, 5353.678779273247, 5392.325959255093, 5416.458354164582, 5457.699826509541, 3319.7053149271683, 3340.2818394045335, 3479.9376548637165, 4030.3148464760916, 4089.631933126018, 3436.690697837194, 3449.7067749241346, 4026.5973402659793, 4068.8169032393466, 4107.54816970133, 3559.4660800879947, 3726.869559565416, 3875.418687196915, 4046.4941882264716, 4062.7359221650645, 3885.630865067814, 3947.46709194258, 4038.8795882161808, 4061.4923134608025, 4061.5175460956834, 3446.276281980762, 3446.4313388291152, 3447.24160684649, 3451.8953934740894, 3462.8614855406004, 5258.81594144207, 5260.395054568736, 5264.7367631618645, 5279.313689220415, 5328.840134795923, 4162.771047592381, 4474.709143905636, 4482.350059241392, 4588.977055114703, 4625.468071171791, 5580.91628625571, 5615.831525054255, 5629.971850140723, 5654.717264136777, 5688.776912963733, 3222.548843161959, 3797.487339209199, 3806.401311449198, 3847.5382954045413, 3847.6921846252235, 5291.259223708684, 5687.459691329317, 5899.941000589982, 6022.488088512482, 6063.727132279058, 3715.1826598148364, 3726.1616136369644, 3822.9426558601645, 3831.4827498287373, 3855.9036024099623, 3462.4113900636953, 3761.4733937248807, 3887.863924762676, 3908.6091390861257, 3927.9214415711526, 3709.7588656737225, 4488.349189367524, 4557.954420455753, 4562.954370456319, 4586.7615743749, 5103.8468845934685, 5339.492748188916, 5442.557722203619, 5717.284289962235, 5761.654300741969, 1093.9617113401152, 1194.5281613762515, 1201.6154830454345, 1250.0811903251104, 1264.1972347559183, 799.6121880887881, 809.3889113719105, 814.2915663372903, 857.5497863621648, 861.29804209569, 4587.610053145453, 4775.808967641256, 4882.6826256293225, 4947.960928205117, 4959.429665588431, 4909.533594308535, 4978.800847966113, 5120.948790512121, 5233.921491177635, 5615.494605485526, 4523.6929166124855, 4839.903201936012, 4875.975620121877, 5091.312672789126, 5105.2086926526445, 2473.9628905566433, 2521.7730404263702, 2960.9703902960696, 2963.259185203699, 3019.022540236149, 5140.329392947232, 5278.126265175884, 5469.8085567005755, 5505.366882808448, 5599.496045355938, 4653.534646535387, 5222.112240918977, 5557.944420555743, 5808.680522571299, 5951.821445356646, 5080.923786143137, 5314.5216930476445, 5825.7087145642545, 5960.37416071315, 5997.730102145327, 5854.560907931837, 5857.238558987475, 5857.355690873916, 5871.236739223874, 5946.643201407929, 5187.792488300577, 5340.798320377884, 5618.3258009037545, 5744.511716504144, 5765.682887441231, 6100.816975490742, 6108.328083910677, 6117.785877494266, 6157.0, 6216.285127210429, 3324.7672662913365, 3780.3762379207124, 3832.789196594214, 3864.9613503864603, 3889.416587511896, 5354.21580778001, 5541.778328866801, 5787.334456537384, 5792.333881603669, 5891.0, 5123.743812809419, 5660.0, 5661.716914154358, 5678.829635110953, 5732.022452253966, 4535.931961020521, 5041.017001684636, 5164.638475306842, 5351.518363347317, 5450.427705091067, 4759.309900064541, 5171.586273098068, 5556.0, 5653.773849046157, 5667.574931880043, 5330.440303768049, 5655.632383895034, 5731.7420716067045, 5814.296352196249, 5828.726796549265, 4772.665913386029, 5178.663386879841, 5461.563074954073, 5582.916256256078, 5583.860403489945], "value": 4050.521970838596, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [655.9409653131218, 689.9896501552478, 731.593965349231, 760.5132715062359, 804.2681160144269, 14035.789463158048, 15031.173285469295, 15037.473688420907, 15045.699086018272, 15055.021423607457, 15044.871634627392, 16058.919705401486, 16068.196590170473, 16072.517824465285, 16289.674206515863, 861.6811779641541, 884.0938038510546, 884.5975081337975, 885.4465958775766, 885.6545947080643, 873.5544872115204, 905.9365844390895, 912.7672443526891, 913.6117150211167, 915.8443064678987, 835.1940377535707, 931.1061381074143, 949.6960972488806, 956.622134256968, 958.9520523973808, 942.8727121838601, 953.580424613168, 954.3319676226669, 955.073578628729, 957.712686194139, 693.7814588404636, 698.4203111417511, 703.8275622472527, 705.675389320913, 708.8405108850519, 14712.779308310384, 14776.113433193936, 14781.187034713086, 14804.111753294837, 15194.468193613171, 13863.861361386358, 13950.860491395058, 13985.510507132201, 14079.569379891178, 14228.0, 10783.352998820095, 11010.834837477445, 11069.225154239206, 11258.268212566158, 11636.243644163102, 3611.0972256935765, 3647.58052823926, 3662.6424416288214, 3673.045008297862, 3814.065553939275, 98545.52181717208, 99461.50807737962, 100047.49928751074, 100486.49756751243, 101670.49164754203, 9218.400803947856, 10031.69904902854, 10080.69757907264, 10100.646477373257, 10493.26547141708, 20452.897735511226, 20756.0, 20856.687149692767, 21056.894715526632, 21096.0, 7253.1296244451005, 7463.179050304462, 7584.355329796918, 7655.923440765521, 7686.192949740309, 4070.430139780433, 4131.566185550534, 4135.7518548887165, 4147.91704165915, 4148.336266197394, 3096.256898344381, 3579.3736096183065, 3637.254362855608, 3679.2273622539033, 3692.8892133236045, 676.6041865508635, 680.9727610895608, 691.0843132848995, 695.183159787248, 696.9756058537927, 607.7113371148669, 638.5625846295303, 641.884460797061, 688.1398252184769, 702.6030292884504, 8601.82796344082, 9141.177294043435, 9590.616375344907, 9701.223902087817, 10042.849357259647, 623.2614351992903, 659.3867703036223, 666.1440049536322, 673.6598018000976, 674.1674032569772, 3505.088676944013, 4554.977225113919, 4593.356930029731, 4651.86044418668, 4818.523464610716, 88558.0, 92387.0, 93261.0, 93381.0, 93699.59450608246, 74496.62751686315, 80531.0, 83280.0, 84139.5793021031, 84334.0, 61309.38690613126, 74661.62669186619, 74734.0, 74841.25158748451, 75573.0, 8754.47473151613, 9138.725838224864, 9183.022179671283, 9310.581023853807, 9336.0, 40482.390160280374, 42485.15029699451, 44234.028406164754, 48629.513704863915, 49850.750746246034, 46403.76798115988, 62523.0, 63367.0494942567, 64092.679536602016, 65190.0, 10306.0, 11414.315141091562, 11569.132315076571, 11666.941665291619, 11730.472128754056, 10722.463876805972, 12174.0, 12424.56514022005, 12725.03131847025, 12841.678958026125, 9626.518674066408, 9822.0, 11598.304101753922, 11738.295702257892, 12001.63995080149, 10065.295429319878, 10315.896841031494, 10614.310069845435, 10644.148468122687, 10688.305260158066, 11213.775724485628, 11523.423828808358, 11570.421478925851, 11960.34218117977, 12061.276323420623, 9200.263978881807, 9484.288678349289, 9669.516524173903, 9672.661456848977, 9855.655052073143, 8249.546274954935, 8327.625256863577, 9452.621895124394, 9484.905150948678, 9505.572249248662], "value": 7101.950330533791, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1611.3, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [137.0, 101.0, 175.0, 105.0, 165.0, 176.0, 175.0, 117.0, 118.0, 117.0], "value": 138.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 115.0, 134.0, 158.0, 116.0, 116.0, 132.0, 116.0, 148.0, 115.0], "value": 130.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [209.0, 214.0, 223.0, 209.0, 275.0, 198.0, 208.0, 279.0, 242.0, 220.0], "value": 227.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 60.0, 60.0, 90.0, 60.0, 60.0, 62.0, 63.0, 75.0, 61.0], "value": 67.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 71.0, 83.0, 72.0, 85.0, 98.0, 85.0, 70.0, 69.0, 82.0], "value": 80.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 89.0, 88.0, 91.0, 84.0, 85.0, 104.0, 137.0, 113.0, 90.0], "value": 97.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 106.0, 97.0, 98.0, 96.0, 95.0, 97.0, 105.0, 95.0, 152.0], "value": 109.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 95.0, 91.0, 135.0, 148.0, 129.0, 141.0, 132.0, 93.0, 100.0], "value": 115.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 60.0, 68.0, 83.0, 92.0, 73.0, 62.0, 62.0, 61.0, 62.0], "value": 68.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 77.0, 70.0, 82.0, 104.0, 75.0, 72.0, 86.0, 71.0, 78.0], "value": 78.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 86.0, 90.0, 93.0, 107.0, 107.0, 90.0, 90.0, 88.0, 81.0], "value": 92.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 139.0, 146.0, 163.0, 141.0, 144.0, 145.0, 153.0, 203.0, 177.0], "value": 156.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [177.0, 179.0, 173.0, 177.0, 172.0, 176.0, 175.0, 175.0, 176.0, 174.0], "value": 175.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 66.0, 68.0, 70.0, 67.0, 69.0, 76.0, 86.0, 88.0, 84.0], "value": 74.3, "unit": "score"}]}]} 09:20:00 INFO - Return code: 0 09:20:00 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:20:00 ERROR - # TBPL SUCCESS # 09:20:00 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:20:00 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:20:00 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:20:00 INFO - 2017-02-23 09:20:00.973 screenresolution[1961:13596] starting screenresolution argv=screenresolution get 09:20:00 INFO - 2017-02-23 09:20:00.975 screenresolution[1961:13596] Display 0: 1600x1200x32@60 09:20:00 INFO - 2017-02-23 09:20:00.982 screenresolution[1962:13599] starting screenresolution argv=screenresolution list 09:20:00 INFO - Available Modes on Display 0 09:20:00 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:20:00 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:20:00 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:20:00 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:20:01 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:20:01 INFO - Intel Iris: 09:20:01 INFO - Chipset Model: Intel Iris 09:20:01 INFO - Type: GPU 09:20:01 INFO - Bus: Built-In 09:20:01 INFO - VRAM (Dynamic, Max): 1536 MB 09:20:01 INFO - Vendor: Intel (0x8086) 09:20:01 INFO - Device ID: 0x0a2e 09:20:01 INFO - Revision ID: 0x0009 09:20:01 INFO - Displays: 09:20:01 INFO - Display: 09:20:01 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:20:01 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:20:01 INFO - Display Serial Number: 1600x1200 60 09:20:01 INFO - Main Display: Yes 09:20:01 INFO - Mirror: Off 09:20:01 INFO - Online: Yes 09:20:01 INFO - Rotation: Supported 09:20:01 INFO - Return code: 0 09:20:01 INFO - Running post-action listener: _resource_record_post_action 09:20:01 INFO - [mozharness: 2017-02-23 17:20:01.220112Z] Finished run-tests step (success) 09:20:01 INFO - Running post-run listener: _resource_record_post_run 09:20:01 INFO - Total resource usage - Wall time: 1200s; CPU: 55.0%; Read bytes: 2078208; Write bytes: 514207232; Read time: 18; Write time: 1625 09:20:01 INFO - TinderboxPrint: CPU usage
54.6% 09:20:01 INFO - TinderboxPrint: I/O read bytes / time
2,078,208 / 18 09:20:01 INFO - TinderboxPrint: I/O write bytes / time
514,207,232 / 1,625 09:20:01 INFO - TinderboxPrint: CPU idle
2,178.6 (45.4%) 09:20:01 INFO - TinderboxPrint: CPU system
973.8 (20.3%) 09:20:01 INFO - TinderboxPrint: CPU user
1,649.3 (34.3%) 09:20:01 INFO - TinderboxPrint: Swap in / out
212,901,888 / 0 09:20:01 INFO - install - Wall time: 21s; CPU: 54.0%; Read bytes: 203903488; Write bytes: 202357760; Read time: 15920; Write time: 442 09:20:01 INFO - run-tests - Wall time: 1181s; CPU: 55.0%; Read bytes: 2078208; Write bytes: 299743744; Read time: 18; Write time: 1135 09:20:01 INFO - Running post-run listener: _upload_blobber_files 09:20:01 INFO - Blob upload gear active. 09:20:01 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:20:01 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 09:20:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 09:20:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 09:20:02 INFO - (blobuploader) - INFO - Open directory for files ... 09:20:02 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 09:20:02 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:20:02 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:20:03 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 09:20:03 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:20:03 INFO - (blobuploader) - INFO - Done attempting. 09:20:03 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 09:20:03 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:20:03 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:20:04 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 09:20:04 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:20:04 INFO - (blobuploader) - INFO - Done attempting. 09:20:04 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 09:20:04 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:20:04 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:20:05 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 09:20:05 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:20:05 INFO - (blobuploader) - INFO - Done attempting. 09:20:05 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:20:05 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:20:05 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:20:07 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:20:07 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:20:07 INFO - (blobuploader) - INFO - Done attempting. 09:20:07 INFO - (blobuploader) - INFO - Iteration through files over. 09:20:07 INFO - Return code: 0 09:20:07 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:20:07 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:20:07 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e6d923b5c29bd57211509a48118433b299eb1bf10f346f28399c51b80c0dd9aaf25a0e9d77d80cae3f763f1cdc47adf6427a84cd4b85b374088df6eb5c658e69", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ddb85de13f195f1318be02221abb71dd240dbad5fd077342476341f8c39c341b56cb813a0d88d7213a86ba2c3b7782734827f73c8cacd5cc56976c30bb31fafa", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/211fed42e7ba00b0ce8b177b750388f0c26ff30b7dd02119aa614bcd045791f98f6a24d9c85e66bc809a52755d3ac0cecd65392a82a209393f54a6292cd63e79", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/18ee3a5fd9b29981d9f8658d792b0687ee079462696907469af880f06b80a1cc7bed9a0b2f952236dde1c3d7de18a8188b0510292b33f40e6f974c34083521f5"} 09:20:07 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:20:07 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:20:07 INFO - Contents: 09:20:07 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e6d923b5c29bd57211509a48118433b299eb1bf10f346f28399c51b80c0dd9aaf25a0e9d77d80cae3f763f1cdc47adf6427a84cd4b85b374088df6eb5c658e69", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ddb85de13f195f1318be02221abb71dd240dbad5fd077342476341f8c39c341b56cb813a0d88d7213a86ba2c3b7782734827f73c8cacd5cc56976c30bb31fafa", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/211fed42e7ba00b0ce8b177b750388f0c26ff30b7dd02119aa614bcd045791f98f6a24d9c85e66bc809a52755d3ac0cecd65392a82a209393f54a6292cd63e79", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/18ee3a5fd9b29981d9f8658d792b0687ee079462696907469af880f06b80a1cc7bed9a0b2f952236dde1c3d7de18a8188b0510292b33f40e6f974c34083521f5"} 09:20:07 INFO - Running post-run listener: copy_logs_to_upload_dir 09:20:07 INFO - Copying logs to upload dir... 09:20:07 INFO - mkdir: /builds/slave/test/build/upload/logs 09:20:07 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1281.695825 ========= master_lag: 0.06 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 21 secs) (at 2017-02-23 09:20:07.516268) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-23 09:20:07.519807) ========= 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.MlSm6Gtn1j/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.zdnglL2ycc/Listeners TMPDIR=/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e6d923b5c29bd57211509a48118433b299eb1bf10f346f28399c51b80c0dd9aaf25a0e9d77d80cae3f763f1cdc47adf6427a84cd4b85b374088df6eb5c658e69", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ddb85de13f195f1318be02221abb71dd240dbad5fd077342476341f8c39c341b56cb813a0d88d7213a86ba2c3b7782734827f73c8cacd5cc56976c30bb31fafa", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/211fed42e7ba00b0ce8b177b750388f0c26ff30b7dd02119aa614bcd045791f98f6a24d9c85e66bc809a52755d3ac0cecd65392a82a209393f54a6292cd63e79", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/18ee3a5fd9b29981d9f8658d792b0687ee079462696907469af880f06b80a1cc7bed9a0b2f952236dde1c3d7de18a8188b0510292b33f40e6f974c34083521f5"} build_url:https://queue.taskcluster.net/v1/task/Tsg-KtrqQ-G1VABHyuSZ4Q/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011532 build_url: 'https://queue.taskcluster.net/v1/task/Tsg-KtrqQ-G1VABHyuSZ4Q/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e6d923b5c29bd57211509a48118433b299eb1bf10f346f28399c51b80c0dd9aaf25a0e9d77d80cae3f763f1cdc47adf6427a84cd4b85b374088df6eb5c658e69", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ddb85de13f195f1318be02221abb71dd240dbad5fd077342476341f8c39c341b56cb813a0d88d7213a86ba2c3b7782734827f73c8cacd5cc56976c30bb31fafa", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/211fed42e7ba00b0ce8b177b750388f0c26ff30b7dd02119aa614bcd045791f98f6a24d9c85e66bc809a52755d3ac0cecd65392a82a209393f54a6292cd63e79", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/18ee3a5fd9b29981d9f8658d792b0687ee079462696907469af880f06b80a1cc7bed9a0b2f952236dde1c3d7de18a8188b0510292b33f40e6f974c34083521f5"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-23 09:20:07.561211) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-23 09:20:07.561873) ========= 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.MlSm6Gtn1j/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.zdnglL2ycc/Listeners TMPDIR=/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/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.005246 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-23 09:20:07.614176) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-23 09:20:07.614777) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-23 09:20:07.615439) ========= ========= Total master_lag: 0.22 =========