builder: graphics_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0247 starttime: 1482823595.02 results: success (0) buildid: 20161226221337 builduid: 24828ac6d39d438982f2aa24f0a93d37 revision: 2f4e31c8bc98d606f7631c15bbff41c0ed781136 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-12-26 23:26:35.018571) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-12-26 23:26:35.019030) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-26 23:26:35.019408) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-26 23:26:35.145193) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-26 23:26:35.145653) ========= 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.jtpAArVW4p/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.KkuD0jvWSD/Listeners TMPDIR=/var/folders/hr/hkb07m1j4sq5c29hg511636400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-12-26 23:26:35-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 153M=0s 2016-12-26 23:26:35 (153 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.170412 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-26 23:26:35.346311) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-26 23:26:35.346699) ========= 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.jtpAArVW4p/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.KkuD0jvWSD/Listeners TMPDIR=/var/folders/hr/hkb07m1j4sq5c29hg511636400000w/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.024455 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-26 23:26:35.395490) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-12-26 23:26:35.395890) ========= bash -c 'python archiver_client.py mozharness --repo projects/graphics --rev 2f4e31c8bc98d606f7631c15bbff41c0ed781136 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo projects/graphics --rev 2f4e31c8bc98d606f7631c15bbff41c0ed781136 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.jtpAArVW4p/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.KkuD0jvWSD/Listeners TMPDIR=/var/folders/hr/hkb07m1j4sq5c29hg511636400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-12-26 23:26:35,493 truncating revision to first 12 chars 2016-12-26 23:26:35,494 Setting DEBUG logging. 2016-12-26 23:26:35,494 attempt 1/10 2016-12-26 23:26:35,494 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/projects/graphics/2f4e31c8bc98?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-12-26 23:26:36,629 unpacking tar archive at: graphics-2f4e31c8bc98/testing/mozharness/ program finished with exit code 0 elapsedTime=1.408511 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-12-26 23:26:36.828275) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-26 23:26:36.828615) ========= script_repo_revision: 2f4e31c8bc98d606f7631c15bbff41c0ed781136 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-26 23:26:36.829144) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-26 23:26:36.829430) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-26 23:26:36.849329) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 6 secs) (at 2016-12-26 23:26:36.849796) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Graphics --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Graphics in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Graphics', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Graphics'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.jtpAArVW4p/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.KkuD0jvWSD/Listeners TMPDIR=/var/folders/hr/hkb07m1j4sq5c29hg511636400000w/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 23:26:37 INFO - MultiFileLogger online at 20161226 23:26:37 in /builds/slave/test 23:26:37 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Graphics --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Graphics 23:26:37 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 23:26:37 INFO - {'append_to_log': False, 23:26:37 INFO - 'base_work_dir': '/builds/slave/test', 23:26:37 INFO - 'blob_upload_branch': 'Graphics', 23:26:37 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 23:26:37 INFO - 'branch': 'Graphics', 23:26:37 INFO - 'buildbot_json_path': 'buildprops.json', 23:26:37 INFO - 'config_files': ('talos/mac_config.py',), 23:26:37 INFO - 'default_actions': ('clobber', 23:26:37 INFO - 'read-buildbot-config', 23:26:37 INFO - 'download-and-extract', 23:26:37 INFO - 'populate-webroot', 23:26:37 INFO - 'create-virtualenv', 23:26:37 INFO - 'install', 23:26:37 INFO - 'run-tests'), 23:26:37 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 23:26:37 INFO - 'download_minidump_stackwalk': True, 23:26:37 INFO - 'download_symbols': 'ondemand', 23:26:37 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 23:26:37 INFO - 'tooltool.py': '/tools/tooltool.py', 23:26:37 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 23:26:37 INFO - '/tools/misc-python/virtualenv.py')}, 23:26:37 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 23:26:37 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 23:26:37 INFO - 'installer_path': 'installer.exe', 23:26:37 INFO - 'log_level': 'info', 23:26:37 INFO - 'log_name': 'talos', 23:26:37 INFO - 'log_to_console': True, 23:26:37 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 23:26:37 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 23:26:37 INFO - 'opt_config_files': (), 23:26:37 INFO - 'pip_index': False, 23:26:37 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 23:26:37 INFO - 'cmd': ('bash', 23:26:37 INFO - '-c', 23:26:37 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 23:26:37 INFO - 'enabled': True, 23:26:37 INFO - 'halt_on_failure': False, 23:26:37 INFO - 'name': 'check_screen_resolution'},), 23:26:37 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 23:26:37 INFO - 'cmd': ('bash', 23:26:37 INFO - '-c', 23:26:37 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 23:26:37 INFO - 'enabled': True, 23:26:37 INFO - 'halt_on_failure': False, 23:26:37 INFO - 'name': 'check_screen_resolution'},), 23:26:37 INFO - 'run_cmd_checks_enabled': True, 23:26:37 INFO - 'sps_profile': False, 23:26:37 INFO - 'sps_profile_interval': 0, 23:26:37 INFO - 'suite': 'dromaeojs', 23:26:37 INFO - 'system_bits': '32', 23:26:37 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 23:26:37 INFO - 'title': 't-yosemite-r7-0247', 23:26:37 INFO - 'tooltool_cache': '/builds/tooltool_cache', 23:26:37 INFO - 'use_talos_json': True, 23:26:37 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 23:26:37 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 23:26:37 INFO - 'work_dir': 'build'} 23:26:37 INFO - [mozharness: 2016-12-27 07:26:37.056628Z] Running clobber step. 23:26:37 INFO - Running pre-action listener: _resource_record_pre_action 23:26:37 INFO - Running main action method: clobber 23:26:37 INFO - rmtree: /builds/slave/test/build 23:26:37 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 23:26:39 INFO - Running post-action listener: _resource_record_post_action 23:26:39 INFO - [mozharness: 2016-12-27 07:26:39.418388Z] Finished clobber step (success) 23:26:39 INFO - [mozharness: 2016-12-27 07:26:39.418531Z] Running read-buildbot-config step. 23:26:39 INFO - Running pre-action listener: _resource_record_pre_action 23:26:39 INFO - Running main action method: read_buildbot_config 23:26:39 INFO - Using buildbot properties: 23:26:39 INFO - { 23:26:39 INFO - "project": "", 23:26:39 INFO - "product": "firefox", 23:26:39 INFO - "script_repo_revision": "default", 23:26:39 INFO - "builddir": "graphics_yosemite_r7_test-dromaeojs", 23:26:39 INFO - "repository": "", 23:26:39 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 graphics talos dromaeojs", 23:26:39 INFO - "buildid": "20161226221337", 23:26:39 INFO - "pgo_build": "False", 23:26:39 INFO - "basedir": "/builds/slave/test", 23:26:39 INFO - "buildnumber": 11, 23:26:39 INFO - "slavename": "t-yosemite-r7-0247", 23:26:39 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 23:26:39 INFO - "platform": "yosemite_r7", 23:26:39 INFO - "scheduler": "tests-graphics-macosx64-talos", 23:26:39 INFO - "branch": "graphics", 23:26:39 INFO - "repo_path": "projects/graphics", 23:26:39 INFO - "revision": "2f4e31c8bc98d606f7631c15bbff41c0ed781136", 23:26:39 INFO - "stage_platform": "macosx64", 23:26:39 INFO - "builduid": "24828ac6d39d438982f2aa24f0a93d37", 23:26:39 INFO - "slavebuilddir": "test" 23:26:39 INFO - } 23:26:39 INFO - Found installer url https://queue.taskcluster.net/v1/task/P59eSwjsRM-Um1eSaFQMIg/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg. 23:26:39 INFO - Running post-action listener: _resource_record_post_action 23:26:39 INFO - [mozharness: 2016-12-27 07:26:39.421894Z] Finished read-buildbot-config step (success) 23:26:39 INFO - [mozharness: 2016-12-27 07:26:39.422029Z] Running download-and-extract step. 23:26:39 INFO - Running pre-action listener: _resource_record_pre_action 23:26:39 INFO - Running main action method: download_and_extract 23:26:39 INFO - mkdir: /builds/slave/test/build/tests 23:26: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')]} 23:26:39 INFO - trying https://queue.taskcluster.net/v1/task/P59eSwjsRM-Um1eSaFQMIg/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json 23:26:39 INFO - Downloading https://queue.taskcluster.net/v1/task/P59eSwjsRM-Um1eSaFQMIg/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json 23:26:39 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/P59eSwjsRM-Um1eSaFQMIg/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json'}, attempt #1 23:26:40 INFO - Downloaded 1199 bytes. 23:26:40 INFO - Reading from file /builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json 23:26:40 INFO - Using the following test package requirements: 23:26:40 INFO - {u'common': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 23:26:40 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 23:26:40 INFO - u'firefox-53.0a1.en-US.mac.cppunittest.tests.zip'], 23:26:40 INFO - u'gtest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 23:26:40 INFO - u'firefox-53.0a1.en-US.mac.gtest.tests.zip'], 23:26:40 INFO - u'jittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 23:26:40 INFO - u'jsshell-mac.zip'], 23:26:40 INFO - u'mochitest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 23:26:40 INFO - u'firefox-53.0a1.en-US.mac.mochitest.tests.zip'], 23:26:40 INFO - u'mozbase': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 23:26:40 INFO - u'reftest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 23:26:40 INFO - u'firefox-53.0a1.en-US.mac.reftest.tests.zip'], 23:26:40 INFO - u'talos': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 23:26:40 INFO - u'firefox-53.0a1.en-US.mac.talos.tests.zip'], 23:26:40 INFO - u'web-platform': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 23:26:40 INFO - u'firefox-53.0a1.en-US.mac.web-platform.tests.zip'], 23:26:40 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 23:26:40 INFO - u'firefox-53.0a1.en-US.mac.xpcshell.tests.zip']} 23:26:40 INFO - Downloading packages: [u'firefox-53.0a1.en-US.mac.common.tests.zip'] for test suite category: common 23:26:40 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/P59eSwjsRM-Um1eSaFQMIg/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 23:26:40 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/P59eSwjsRM-Um1eSaFQMIg/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 23:26:40 INFO - Fetch https://queue.taskcluster.net/v1/task/P59eSwjsRM-Um1eSaFQMIg/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 23:26:41 INFO - Expected file size: 16289461 23:26:41 INFO - Obtained file size: 16289461 23:26:48 INFO - Downloading packages: [u'firefox-53.0a1.en-US.mac.common.tests.zip', u'firefox-53.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 23:26:48 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/P59eSwjsRM-Um1eSaFQMIg/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 23:26:48 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/P59eSwjsRM-Um1eSaFQMIg/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 23:26:48 INFO - Fetch https://queue.taskcluster.net/v1/task/P59eSwjsRM-Um1eSaFQMIg/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 23:26:49 INFO - Expected file size: 16289461 23:26:49 INFO - Obtained file size: 16289461 23:26:55 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/P59eSwjsRM-Um1eSaFQMIg/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip 23:26:55 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/P59eSwjsRM-Um1eSaFQMIg/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip'}, attempt #1 23:26:55 INFO - Fetch https://queue.taskcluster.net/v1/task/P59eSwjsRM-Um1eSaFQMIg/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip into memory 23:26:56 INFO - Expected file size: 14409776 23:26:56 INFO - Obtained file size: 14409776 23:26:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:26:57 INFO - trying https://queue.taskcluster.net/v1/task/P59eSwjsRM-Um1eSaFQMIg/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 23:26:57 INFO - Downloading https://queue.taskcluster.net/v1/task/P59eSwjsRM-Um1eSaFQMIg/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg 23:26:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/P59eSwjsRM-Um1eSaFQMIg/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg'}, attempt #1 23:26:59 INFO - Downloaded 69620699 bytes. 23:26:59 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/P59eSwjsRM-Um1eSaFQMIg/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 23:26:59 INFO - mkdir: /builds/slave/test/properties 23:26:59 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 23:26:59 INFO - Writing to file /builds/slave/test/properties/build_url 23:26:59 INFO - Contents: 23:26:59 INFO - build_url:https://queue.taskcluster.net/v1/task/P59eSwjsRM-Um1eSaFQMIg/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 23:26:59 INFO - Running post-action listener: _resource_record_post_action 23:26:59 INFO - Running post-action listener: set_extra_try_arguments 23:26:59 INFO - [mozharness: 2016-12-27 07:26:59.887038Z] Finished download-and-extract step (success) 23:26:59 INFO - [mozharness: 2016-12-27 07:26:59.887162Z] Running populate-webroot step. 23:26:59 INFO - Running pre-action listener: _resource_record_pre_action 23:26:59 INFO - Running main action method: populate_webroot 23:26:59 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 23:26:59 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 23:26:59 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 23:26:59 INFO - '--tppagecycles', 23:26:59 INFO - '7'], 23:26:59 INFO - 'tests': ['tsvgm']}}, 23:26:59 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 23:26:59 INFO - 'tests': ['tresize', 'tcanvasmark']}, 23:26:59 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 23:26:59 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 23:26:59 INFO - 'tests': ['dromaeo_css', 'kraken']}, 23:26:59 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 23:26:59 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 23:26:59 INFO - 'talos_options': ['--disable-e10s'], 23:26:59 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 23:26:59 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 23:26:59 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 23:26:59 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 23:26:59 INFO - 'talos_options': ['--disable-e10s'], 23:26:59 INFO - 'tests': ['damp', 'tps']}, 23:26:59 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 23:26:59 INFO - 'tests': ['damp', 'tps']}, 23:26:59 INFO - 'g3': {'talos_options': ['--disable-e10s'], 23:26:59 INFO - 'tests': ['dromaeo_dom']}, 23:26:59 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 23:26:59 INFO - 'g4': {'talos_options': ['--disable-e10s'], 23:26:59 INFO - 'tests': ['basic_compositor_video']}, 23:26:59 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 23:26:59 INFO - 'other': {'talos_options': ['--disable-e10s'], 23:26:59 INFO - 'tests': ['a11yr', 23:26:59 INFO - 'ts_paint', 23:26:59 INFO - 'tpaint', 23:26:59 INFO - 'sessionrestore', 23:26:59 INFO - 'sessionrestore_no_auto_restore', 23:26:59 INFO - 'tabpaint']}, 23:26:59 INFO - 'other-e10s': {'tests': ['a11yr', 23:26:59 INFO - 'ts_paint', 23:26:59 INFO - 'tpaint', 23:26:59 INFO - 'sessionrestore', 23:26:59 INFO - 'sessionrestore_no_auto_restore', 23:26:59 INFO - 'tabpaint']}, 23:26:59 INFO - 'other-e10s_l64': {'tests': ['a11yr', 23:26:59 INFO - 'ts_paint', 23:26:59 INFO - 'tpaint', 23:26:59 INFO - 'sessionrestore', 23:26:59 INFO - 'sessionrestore_no_auto_restore', 23:26:59 INFO - 'tabpaint']}, 23:26:59 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 23:26:59 INFO - 'ts_paint', 23:26:59 INFO - 'tpaint', 23:26:59 INFO - 'sessionrestore', 23:26:59 INFO - 'sessionrestore_no_auto_restore', 23:26:59 INFO - 'tabpaint']}, 23:26:59 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 23:26:59 INFO - 'tests': ['a11yr', 23:26:59 INFO - 'ts_paint', 23:26:59 INFO - 'tpaint', 23:26:59 INFO - 'sessionrestore', 23:26:59 INFO - 'sessionrestore_no_auto_restore', 23:26:59 INFO - 'tabpaint']}, 23:26:59 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 23:26:59 INFO - 'tests': ['a11yr', 23:26:59 INFO - 'ts_paint', 23:26:59 INFO - 'tpaint', 23:26:59 INFO - 'sessionrestore', 23:26:59 INFO - 'sessionrestore_no_auto_restore', 23:26:59 INFO - 'tabpaint']}, 23:26:59 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 23:26:59 INFO - 'tests': ['tsvgx', 23:26:59 INFO - 'tsvgr_opacity', 23:26:59 INFO - 'tart', 23:26:59 INFO - 'tscrollx', 23:26:59 INFO - 'cart', 23:26:59 INFO - 'tsvg_static']}, 23:26:59 INFO - 'svgr-e10s': {'tests': ['tsvgx', 23:26:59 INFO - 'tsvgr_opacity', 23:26:59 INFO - 'tart', 23:26:59 INFO - 'tscrollx', 23:26:59 INFO - 'cart', 23:26:59 INFO - 'tsvg_static']}, 23:26:59 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 23:26:59 INFO - 'talos_options': ['--disable-e10s'], 23:26:59 INFO - 'tests': ['tp5o']}, 23:26:59 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 23:26:59 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 23:26:59 INFO - 'talos_options': ['--disable-e10s', 23:26:59 INFO - '--xperf_path', 23:26:59 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 23:26:59 INFO - 'tests': ['tp5n']}, 23:26:59 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 23:26:59 INFO - 'talos_options': ['--xperf_path', 23:26:59 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 23:26:59 INFO - 'tests': ['tp5n']}}, 23:26:59 INFO - 'talos.zip': {'path': '', 23:26:59 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 23:26:59 INFO - Running post-action listener: _resource_record_post_action 23:26:59 INFO - [mozharness: 2016-12-27 07:26:59.905101Z] Finished populate-webroot step (success) 23:26:59 INFO - [mozharness: 2016-12-27 07:26:59.905242Z] Running create-virtualenv step. 23:26:59 INFO - Running pre-action listener: _resource_record_pre_action 23:26:59 INFO - Running main action method: create_virtualenv 23:26:59 INFO - Creating virtualenv /builds/slave/test/build/venv 23:26:59 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 23:26:59 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 23:27:00 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 23:27:00 INFO - Using real prefix '/tools/python27' 23:27:00 INFO - New python executable in /builds/slave/test/build/venv/bin/python 23:27:00 INFO - Installing distribute.............................................................................................................................................................................................done. 23:27:03 INFO - Installing pip.................done. 23:27:03 INFO - Return code: 0 23:27:03 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 23:27:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 23:27:03 INFO - Reading from file tmpfile_stdout 23:27:03 INFO - Output received: 23:27:03 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) 23:27:03 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 23:27:03 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')]} 23:27:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:27:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:27:03 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')]} 23:27:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:27:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:27:03 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 0x10d424b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d427768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9fd8d4b510>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d202d30>, '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 0x10d129db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d1f7be8>, '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.jtpAArVW4p/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.KkuD0jvWSD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hr/hkb07m1j4sq5c29hg511636400000w/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 23:27:03 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 23:27:03 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 23:27:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jtpAArVW4p/Render', 23:27:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:27:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:27:03 INFO - 'HOME': '/Users/cltbld', 23:27:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:27:03 INFO - 'LOGNAME': 'cltbld', 23:27:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:27:03 INFO - 'MOZ_NO_REMOTE': '1', 23:27:03 INFO - 'NO_EM_RESTART': '1', 23:27:03 INFO - 'PAGER': '/bin/cat', 23:27:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:27:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:27:03 INFO - 'PWD': '/builds/slave/test', 23:27:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:27:03 INFO - 'SHELL': '/bin/bash', 23:27:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KkuD0jvWSD/Listeners', 23:27:03 INFO - 'TMPDIR': '/var/folders/hr/hkb07m1j4sq5c29hg511636400000w/T/', 23:27:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:27:03 INFO - 'USER': 'cltbld', 23:27:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:27:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:27:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:27:03 INFO - 'XPC_FLAGS': '0x0', 23:27:03 INFO - 'XPC_SERVICE_NAME': '0', 23:27:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:27:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:27:04 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 23:27:04 INFO - Cleaning up... 23:27:04 INFO - Return code: 0 23:27:04 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 23:27:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:27:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:27:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:27:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:27:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:27:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:27:04 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10d424b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d427768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9fd8d4b510>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d202d30>, '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 0x10d129db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d1f7be8>, '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.jtpAArVW4p/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.KkuD0jvWSD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hr/hkb07m1j4sq5c29hg511636400000w/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 23:27:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 23:27:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 23:27:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jtpAArVW4p/Render', 23:27:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:27:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:27:04 INFO - 'HOME': '/Users/cltbld', 23:27:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:27:04 INFO - 'LOGNAME': 'cltbld', 23:27:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:27:04 INFO - 'MOZ_NO_REMOTE': '1', 23:27:04 INFO - 'NO_EM_RESTART': '1', 23:27:04 INFO - 'PAGER': '/bin/cat', 23:27:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:27:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:27:04 INFO - 'PWD': '/builds/slave/test', 23:27:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:27:04 INFO - 'SHELL': '/bin/bash', 23:27:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KkuD0jvWSD/Listeners', 23:27:04 INFO - 'TMPDIR': '/var/folders/hr/hkb07m1j4sq5c29hg511636400000w/T/', 23:27:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:27:04 INFO - 'USER': 'cltbld', 23:27:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:27:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:27:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:27:04 INFO - 'XPC_FLAGS': '0x0', 23:27:04 INFO - 'XPC_SERVICE_NAME': '0', 23:27:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:27:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:27:04 INFO - Downloading/unpacking psutil>=3.1.1 23:27:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:27:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:27:04 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:27:04 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:27:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:27:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:27:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 23:27:08 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 23:27:08 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 23:27:08 INFO - Installing collected packages: psutil 23:27:08 INFO - Running setup.py install for psutil 23:27:08 INFO - building 'psutil._psutil_osx' extension 23:27:08 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 23:27:09 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 23:27:09 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 23:27:09 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 23:27:09 INFO - building 'psutil._psutil_posix' extension 23:27:09 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 23:27:09 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 23:27:09 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 23:27:09 INFO - ^ 23:27:09 INFO - 1 warning generated. 23:27:09 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 23:27:09 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 23:27:09 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 23:27:09 INFO - Successfully installed psutil 23:27:09 INFO - Cleaning up... 23:27:09 INFO - Return code: 0 23:27:09 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 23:27:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:27:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:27:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:27:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:27:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:27:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:27:09 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10d424b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d427768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9fd8d4b510>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d202d30>, '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 0x10d129db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d1f7be8>, '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.jtpAArVW4p/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.KkuD0jvWSD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hr/hkb07m1j4sq5c29hg511636400000w/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 23:27:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 23:27:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 23:27:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jtpAArVW4p/Render', 23:27:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:27:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:27:09 INFO - 'HOME': '/Users/cltbld', 23:27:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:27:09 INFO - 'LOGNAME': 'cltbld', 23:27:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:27:09 INFO - 'MOZ_NO_REMOTE': '1', 23:27:09 INFO - 'NO_EM_RESTART': '1', 23:27:09 INFO - 'PAGER': '/bin/cat', 23:27:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:27:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:27:09 INFO - 'PWD': '/builds/slave/test', 23:27:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:27:09 INFO - 'SHELL': '/bin/bash', 23:27:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KkuD0jvWSD/Listeners', 23:27:09 INFO - 'TMPDIR': '/var/folders/hr/hkb07m1j4sq5c29hg511636400000w/T/', 23:27:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:27:09 INFO - 'USER': 'cltbld', 23:27:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:27:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:27:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:27:09 INFO - 'XPC_FLAGS': '0x0', 23:27:09 INFO - 'XPC_SERVICE_NAME': '0', 23:27:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:27:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:27:09 INFO - Downloading/unpacking mozsystemmonitor==0.3 23:27:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:27:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:27:09 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:27:09 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:27:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:27:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:27:12 INFO - Downloading mozsystemmonitor-0.3.tar.gz 23:27:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 23:27:12 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 23:27:12 INFO - Installing collected packages: mozsystemmonitor 23:27:12 INFO - Running setup.py install for mozsystemmonitor 23:27:12 INFO - Successfully installed mozsystemmonitor 23:27:12 INFO - Cleaning up... 23:27:12 INFO - Return code: 0 23:27:12 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 23:27:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:27:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:27:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:27:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:27:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:27:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:27:12 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 0x10d424b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d427768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9fd8d4b510>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d202d30>, '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 0x10d129db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d1f7be8>, '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.jtpAArVW4p/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.KkuD0jvWSD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hr/hkb07m1j4sq5c29hg511636400000w/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 23:27:12 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 23:27:12 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 23:27:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jtpAArVW4p/Render', 23:27:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:27:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:27:12 INFO - 'HOME': '/Users/cltbld', 23:27:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:27:12 INFO - 'LOGNAME': 'cltbld', 23:27:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:27:12 INFO - 'MOZ_NO_REMOTE': '1', 23:27:12 INFO - 'NO_EM_RESTART': '1', 23:27:12 INFO - 'PAGER': '/bin/cat', 23:27:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:27:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:27:12 INFO - 'PWD': '/builds/slave/test', 23:27:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:27:12 INFO - 'SHELL': '/bin/bash', 23:27:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KkuD0jvWSD/Listeners', 23:27:12 INFO - 'TMPDIR': '/var/folders/hr/hkb07m1j4sq5c29hg511636400000w/T/', 23:27:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:27:12 INFO - 'USER': 'cltbld', 23:27:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:27:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:27:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:27:12 INFO - 'XPC_FLAGS': '0x0', 23:27:12 INFO - 'XPC_SERVICE_NAME': '0', 23:27:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:27:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:27:13 INFO - Downloading/unpacking jsonschema==2.5.1 23:27:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:27:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:27:13 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:27:13 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:27:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:27:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:27:15 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 23:27:15 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 23:27:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:27:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:27:15 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:27:15 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:27:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:27:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:27:16 INFO - Downloading functools32-3.2.3-2.tar.gz 23:27:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 23:27:16 INFO - warning: no files found matching '*.txt' 23:27:16 INFO - no previously-included directories found matching 'build' 23:27:16 INFO - no previously-included directories found matching 'dist' 23:27:16 INFO - no previously-included directories found matching '.git*' 23:27:16 INFO - Installing collected packages: jsonschema, functools32 23:27:16 INFO - Running setup.py install for functools32 23:27:16 INFO - warning: no files found matching '*.txt' 23:27:16 INFO - no previously-included directories found matching 'build' 23:27:16 INFO - no previously-included directories found matching 'dist' 23:27:16 INFO - no previously-included directories found matching '.git*' 23:27:16 INFO - Successfully installed jsonschema functools32 23:27:16 INFO - Cleaning up... 23:27:16 INFO - Return code: 0 23:27:16 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 23:27:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:27:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:27:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:27:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:27:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:27:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:27:16 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10d424b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d427768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9fd8d4b510>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d202d30>, '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 0x10d129db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d1f7be8>, '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.jtpAArVW4p/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.KkuD0jvWSD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hr/hkb07m1j4sq5c29hg511636400000w/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 23:27:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 23:27:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 23:27:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jtpAArVW4p/Render', 23:27:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:27:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:27:16 INFO - 'HOME': '/Users/cltbld', 23:27:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:27:16 INFO - 'LOGNAME': 'cltbld', 23:27:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:27:16 INFO - 'MOZ_NO_REMOTE': '1', 23:27:16 INFO - 'NO_EM_RESTART': '1', 23:27:16 INFO - 'PAGER': '/bin/cat', 23:27:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:27:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:27:16 INFO - 'PWD': '/builds/slave/test', 23:27:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:27:16 INFO - 'SHELL': '/bin/bash', 23:27:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KkuD0jvWSD/Listeners', 23:27:16 INFO - 'TMPDIR': '/var/folders/hr/hkb07m1j4sq5c29hg511636400000w/T/', 23:27:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:27:16 INFO - 'USER': 'cltbld', 23:27:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:27:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:27:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:27:16 INFO - 'XPC_FLAGS': '0x0', 23:27:16 INFO - 'XPC_SERVICE_NAME': '0', 23:27:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:27:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:27:16 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 23:27:16 INFO - Cleaning up... 23:27:16 INFO - Return code: 0 23:27:16 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 23:27:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:27:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:27:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:27:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:27:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:27:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:27:16 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10d424b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d427768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9fd8d4b510>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d202d30>, '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 0x10d129db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d1f7be8>, '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.jtpAArVW4p/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.KkuD0jvWSD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hr/hkb07m1j4sq5c29hg511636400000w/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 23:27:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 23:27:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 23:27:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jtpAArVW4p/Render', 23:27:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:27:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:27:16 INFO - 'HOME': '/Users/cltbld', 23:27:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:27:16 INFO - 'LOGNAME': 'cltbld', 23:27:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:27:16 INFO - 'MOZ_NO_REMOTE': '1', 23:27:16 INFO - 'NO_EM_RESTART': '1', 23:27:16 INFO - 'PAGER': '/bin/cat', 23:27:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:27:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:27:16 INFO - 'PWD': '/builds/slave/test', 23:27:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:27:16 INFO - 'SHELL': '/bin/bash', 23:27:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KkuD0jvWSD/Listeners', 23:27:16 INFO - 'TMPDIR': '/var/folders/hr/hkb07m1j4sq5c29hg511636400000w/T/', 23:27:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:27:16 INFO - 'USER': 'cltbld', 23:27:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:27:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:27:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:27:16 INFO - 'XPC_FLAGS': '0x0', 23:27:16 INFO - 'XPC_SERVICE_NAME': '0', 23:27:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:27:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:27:17 INFO - Downloading/unpacking blobuploader==1.2.4 23:27:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:27:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:27:17 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:27:17 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:27:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:27:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:27:19 INFO - Downloading blobuploader-1.2.4.tar.gz 23:27:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 23:27:19 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 23:27:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:27:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:27:19 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:27:19 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:27:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:27:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:27:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 23:27:20 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 23:27:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:27:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:27:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:27:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:27:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:27:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:27:20 INFO - Downloading docopt-0.6.1.tar.gz 23:27:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 23:27:21 INFO - Installing collected packages: blobuploader, requests, docopt 23:27:21 INFO - Running setup.py install for blobuploader 23:27:21 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 23:27:21 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 23:27:21 INFO - Running setup.py install for requests 23:27:21 INFO - Running setup.py install for docopt 23:27:21 INFO - Successfully installed blobuploader requests docopt 23:27:21 INFO - Cleaning up... 23:27:21 INFO - Return code: 0 23:27:21 INFO - Installing None into virtualenv /builds/slave/test/build/venv 23:27:21 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')]} 23:27:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:27:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:27:21 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')]} 23:27:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:27:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:27:21 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 0x10d424b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d427768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9fd8d4b510>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d202d30>, '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 0x10d129db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d1f7be8>, '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.jtpAArVW4p/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.KkuD0jvWSD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hr/hkb07m1j4sq5c29hg511636400000w/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 23:27:21 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 23:27:21 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 23:27:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jtpAArVW4p/Render', 23:27:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:27:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:27:21 INFO - 'HOME': '/Users/cltbld', 23:27:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:27:21 INFO - 'LOGNAME': 'cltbld', 23:27:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:27:21 INFO - 'MOZ_NO_REMOTE': '1', 23:27:21 INFO - 'NO_EM_RESTART': '1', 23:27:21 INFO - 'PAGER': '/bin/cat', 23:27:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:27:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:27:21 INFO - 'PWD': '/builds/slave/test', 23:27:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:27:21 INFO - 'SHELL': '/bin/bash', 23:27:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KkuD0jvWSD/Listeners', 23:27:21 INFO - 'TMPDIR': '/var/folders/hr/hkb07m1j4sq5c29hg511636400000w/T/', 23:27:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:27:21 INFO - 'USER': 'cltbld', 23:27:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:27:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:27:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:27:21 INFO - 'XPC_FLAGS': '0x0', 23:27:21 INFO - 'XPC_SERVICE_NAME': '0', 23:27:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:27:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:27:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 23:27:22 INFO - Running setup.py (path:/var/folders/hr/hkb07m1j4sq5c29hg511636400000w/T/pip-SrFvR1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 23:27:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 23:27:22 INFO - Running setup.py (path:/var/folders/hr/hkb07m1j4sq5c29hg511636400000w/T/pip-5hLBe2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 23:27:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 23:27:22 INFO - Running setup.py (path:/var/folders/hr/hkb07m1j4sq5c29hg511636400000w/T/pip-M1Ilxh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 23:27:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 23:27:22 INFO - Running setup.py (path:/var/folders/hr/hkb07m1j4sq5c29hg511636400000w/T/pip-J578yI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 23:27:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 23:27:22 INFO - Running setup.py (path:/var/folders/hr/hkb07m1j4sq5c29hg511636400000w/T/pip-7mctw9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 23:27:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 23:27:22 INFO - Running setup.py (path:/var/folders/hr/hkb07m1j4sq5c29hg511636400000w/T/pip-UXKjqb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 23:27:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 23:27:22 INFO - Running setup.py (path:/var/folders/hr/hkb07m1j4sq5c29hg511636400000w/T/pip-it49_y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 23:27:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 23:27:22 INFO - Running setup.py (path:/var/folders/hr/hkb07m1j4sq5c29hg511636400000w/T/pip-MUWz6I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 23:27:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 23:27:23 INFO - Running setup.py (path:/var/folders/hr/hkb07m1j4sq5c29hg511636400000w/T/pip-21jYE2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 23:27:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 23:27:23 INFO - Running setup.py (path:/var/folders/hr/hkb07m1j4sq5c29hg511636400000w/T/pip-zbDRHo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 23:27:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 23:27:23 INFO - Running setup.py (path:/var/folders/hr/hkb07m1j4sq5c29hg511636400000w/T/pip-0TOwNY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 23:27:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 23:27:23 INFO - Running setup.py (path:/var/folders/hr/hkb07m1j4sq5c29hg511636400000w/T/pip-oZ9qQi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 23:27:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 23:27:23 INFO - Running setup.py (path:/var/folders/hr/hkb07m1j4sq5c29hg511636400000w/T/pip-5a1vQI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 23:27:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 23:27:23 INFO - Running setup.py (path:/var/folders/hr/hkb07m1j4sq5c29hg511636400000w/T/pip-oMbxem-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 23:27:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 23:27:23 INFO - Running setup.py (path:/var/folders/hr/hkb07m1j4sq5c29hg511636400000w/T/pip-R2w8PG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 23:27:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 23:27:23 INFO - Running setup.py (path:/var/folders/hr/hkb07m1j4sq5c29hg511636400000w/T/pip-MXSrRr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 23:27:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 23:27:23 INFO - Running setup.py (path:/var/folders/hr/hkb07m1j4sq5c29hg511636400000w/T/pip-B4zP1d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 23:27:23 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 23:27:23 INFO - Running setup.py install for manifestparser 23:27:24 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 23:27:24 INFO - Running setup.py install for mozcrash 23:27:24 INFO - Running setup.py install for mozdebug 23:27:24 INFO - Running setup.py install for mozdevice 23:27:24 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 23:27:24 INFO - Installing dm script to /builds/slave/test/build/venv/bin 23:27:24 INFO - Running setup.py install for mozfile 23:27:24 INFO - Running setup.py install for mozhttpd 23:27:24 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 23:27:24 INFO - Running setup.py install for mozinfo 23:27:24 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 23:27:25 INFO - Running setup.py install for mozInstall 23:27:25 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 23:27:25 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 23:27:25 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 23:27:25 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 23:27:25 INFO - Running setup.py install for mozleak 23:27:25 INFO - Running setup.py install for mozlog 23:27:25 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 23:27:25 INFO - Running setup.py install for moznetwork 23:27:25 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 23:27:25 INFO - Running setup.py install for mozprocess 23:27:25 INFO - Running setup.py install for mozprofile 23:27:25 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 23:27:25 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 23:27:25 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 23:27:25 INFO - Running setup.py install for mozrunner 23:27:26 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 23:27:26 INFO - Running setup.py install for mozscreenshot 23:27:26 INFO - Running setup.py install for moztest 23:27:26 INFO - Running setup.py install for mozversion 23:27:26 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 23:27:26 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 23:27:26 INFO - Cleaning up... 23:27:26 INFO - Return code: 0 23:27:26 INFO - Installing None into virtualenv /builds/slave/test/build/venv 23:27:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:27:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:27:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:27:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:27:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:27:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:27:26 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10d424b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d427768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9fd8d4b510>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d202d30>, '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 0x10d129db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d1f7be8>, '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.jtpAArVW4p/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.KkuD0jvWSD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hr/hkb07m1j4sq5c29hg511636400000w/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 23:27:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 23:27:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 23:27:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jtpAArVW4p/Render', 23:27:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:27:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:27:26 INFO - 'HOME': '/Users/cltbld', 23:27:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:27:26 INFO - 'LOGNAME': 'cltbld', 23:27:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:27:26 INFO - 'MOZ_NO_REMOTE': '1', 23:27:26 INFO - 'NO_EM_RESTART': '1', 23:27:26 INFO - 'PAGER': '/bin/cat', 23:27:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:27:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:27:26 INFO - 'PWD': '/builds/slave/test', 23:27:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:27:26 INFO - 'SHELL': '/bin/bash', 23:27:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KkuD0jvWSD/Listeners', 23:27:26 INFO - 'TMPDIR': '/var/folders/hr/hkb07m1j4sq5c29hg511636400000w/T/', 23:27:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:27:26 INFO - 'USER': 'cltbld', 23:27:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:27:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:27:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:27:26 INFO - 'XPC_FLAGS': '0x0', 23:27:26 INFO - 'XPC_SERVICE_NAME': '0', 23:27:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:27:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:27:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 23:27:26 INFO - Running setup.py (path:/var/folders/hr/hkb07m1j4sq5c29hg511636400000w/T/pip-b4bJ53-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 23:27:26 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)) 23:27:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 23:27:26 INFO - Running setup.py (path:/var/folders/hr/hkb07m1j4sq5c29hg511636400000w/T/pip-bJDbqm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 23:27:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 23:27:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 23:27:27 INFO - Running setup.py (path:/var/folders/hr/hkb07m1j4sq5c29hg511636400000w/T/pip-hHBNDF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 23:27:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 23:27:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 23:27:27 INFO - Running setup.py (path:/var/folders/hr/hkb07m1j4sq5c29hg511636400000w/T/pip-k13i9h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 23:27:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 23:27:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 23:27:27 INFO - Running setup.py (path:/var/folders/hr/hkb07m1j4sq5c29hg511636400000w/T/pip-VwaNNk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 23:27:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 23:27:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 23:27:27 INFO - Running setup.py (path:/var/folders/hr/hkb07m1j4sq5c29hg511636400000w/T/pip-11khT8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 23:27:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 23:27:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 23:27:27 INFO - Running setup.py (path:/var/folders/hr/hkb07m1j4sq5c29hg511636400000w/T/pip-Xss77y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 23:27:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 23:27:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 23:27:27 INFO - Running setup.py (path:/var/folders/hr/hkb07m1j4sq5c29hg511636400000w/T/pip-ewCJkD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 23:27:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 23:27:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 23:27:27 INFO - Running setup.py (path:/var/folders/hr/hkb07m1j4sq5c29hg511636400000w/T/pip-whkbp8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 23:27:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 23:27:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 23:27:27 INFO - Running setup.py (path:/var/folders/hr/hkb07m1j4sq5c29hg511636400000w/T/pip-p4zfBN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 23:27:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 23:27:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 23:27:27 INFO - Running setup.py (path:/var/folders/hr/hkb07m1j4sq5c29hg511636400000w/T/pip-6T1rVo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 23:27:27 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)) 23:27:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 23:27:27 INFO - Running setup.py (path:/var/folders/hr/hkb07m1j4sq5c29hg511636400000w/T/pip-WGNxxJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 23:27:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 23:27:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 23:27:28 INFO - Running setup.py (path:/var/folders/hr/hkb07m1j4sq5c29hg511636400000w/T/pip-NoRzV1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 23:27:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 23:27:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 23:27:28 INFO - Running setup.py (path:/var/folders/hr/hkb07m1j4sq5c29hg511636400000w/T/pip-Bop_rx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 23:27:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 23:27:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 23:27:28 INFO - Running setup.py (path:/var/folders/hr/hkb07m1j4sq5c29hg511636400000w/T/pip-nVG4Oh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 23:27:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 23:27:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 23:27:28 INFO - Running setup.py (path:/var/folders/hr/hkb07m1j4sq5c29hg511636400000w/T/pip-fuWax_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 23:27:28 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 23:27:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 23:27:28 INFO - Running setup.py (path:/var/folders/hr/hkb07m1j4sq5c29hg511636400000w/T/pip-zaD8ar-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 23:27:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 23:27:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 23:27:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 23:27:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 23:27:28 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 23:27:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 23:27:28 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 23:27:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:27:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:27:28 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:27:28 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:27:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:27:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:27:31 INFO - Downloading blessings-1.6.tar.gz 23:27:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 23:27:31 INFO - Installing collected packages: blessings 23:27:31 INFO - Running setup.py install for blessings 23:27:31 INFO - Successfully installed blessings 23:27:31 INFO - Cleaning up... 23:27:31 INFO - Return code: 0 23:27:31 INFO - Done creating virtualenv /builds/slave/test/build/venv. 23:27:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 23:27:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 23:27:31 INFO - Reading from file tmpfile_stdout 23:27:31 INFO - Current package versions: 23:27:31 INFO - blessings == 1.6 23:27:31 INFO - blobuploader == 1.2.4 23:27:31 INFO - docopt == 0.6.1 23:27:31 INFO - functools32 == 3.2.3-2 23:27:31 INFO - jsonschema == 2.5.1 23:27:31 INFO - manifestparser == 1.1 23:27:31 INFO - mozInstall == 1.12 23:27:31 INFO - mozcrash == 1.0 23:27:31 INFO - mozdebug == 0.1 23:27:31 INFO - mozdevice == 0.49 23:27:31 INFO - mozfile == 1.2 23:27:31 INFO - mozhttpd == 0.7 23:27:31 INFO - mozinfo == 0.9 23:27:31 INFO - mozleak == 0.1 23:27:31 INFO - mozlog == 3.4 23:27:31 INFO - moznetwork == 0.27 23:27:31 INFO - mozprocess == 0.23 23:27:31 INFO - mozprofile == 0.28 23:27:31 INFO - mozrunner == 6.13 23:27:31 INFO - mozscreenshot == 0.1 23:27:31 INFO - mozsystemmonitor == 0.3 23:27:31 INFO - moztest == 0.7 23:27:31 INFO - mozversion == 1.4 23:27:31 INFO - psutil == 3.1.1 23:27:31 INFO - requests == 1.2.3 23:27:31 INFO - wsgiref == 0.1.2 23:27:31 INFO - Installing None into virtualenv /builds/slave/test/build/venv 23:27: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')]} 23:27:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:27:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:27: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')]} 23:27:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:27:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:27:31 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10d424b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d427768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9fd8d4b510>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d202d30>, '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 0x10d129db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d1f7be8>, '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.jtpAArVW4p/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.KkuD0jvWSD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hr/hkb07m1j4sq5c29hg511636400000w/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 23:27:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 23:27:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 23:27:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jtpAArVW4p/Render', 23:27:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:27:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:27:31 INFO - 'HOME': '/Users/cltbld', 23:27:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:27:31 INFO - 'LOGNAME': 'cltbld', 23:27:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:27:31 INFO - 'MOZ_NO_REMOTE': '1', 23:27:31 INFO - 'NO_EM_RESTART': '1', 23:27:31 INFO - 'PAGER': '/bin/cat', 23:27:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:27:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:27:31 INFO - 'PWD': '/builds/slave/test', 23:27:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:27:31 INFO - 'SHELL': '/bin/bash', 23:27:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KkuD0jvWSD/Listeners', 23:27:31 INFO - 'TMPDIR': '/var/folders/hr/hkb07m1j4sq5c29hg511636400000w/T/', 23:27:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:27:31 INFO - 'USER': 'cltbld', 23:27:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:27:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:27:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:27:31 INFO - 'XPC_FLAGS': '0x0', 23:27:31 INFO - 'XPC_SERVICE_NAME': '0', 23:27:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:27:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:27:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 23:27:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 23:27:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 23:27:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 23:27:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 23:27:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 23:27:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 23:27:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 23:27:32 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 23:27:32 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 23:27:32 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 23:27:32 INFO - Cleaning up... 23:27:32 INFO - Return code: 0 23:27:32 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 23:27:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:27:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:27:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:27:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:27:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:27:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:27:32 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10d424b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d427768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9fd8d4b510>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d202d30>, '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 0x10d129db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d1f7be8>, '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.jtpAArVW4p/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.KkuD0jvWSD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hr/hkb07m1j4sq5c29hg511636400000w/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 23:27:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 23:27:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 23:27:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jtpAArVW4p/Render', 23:27:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:27:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:27:32 INFO - 'HOME': '/Users/cltbld', 23:27:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:27:32 INFO - 'LOGNAME': 'cltbld', 23:27:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:27:32 INFO - 'MOZ_NO_REMOTE': '1', 23:27:32 INFO - 'NO_EM_RESTART': '1', 23:27:32 INFO - 'PAGER': '/bin/cat', 23:27:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:27:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:27:32 INFO - 'PWD': '/builds/slave/test', 23:27:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:27:32 INFO - 'SHELL': '/bin/bash', 23:27:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KkuD0jvWSD/Listeners', 23:27:32 INFO - 'TMPDIR': '/var/folders/hr/hkb07m1j4sq5c29hg511636400000w/T/', 23:27:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:27:32 INFO - 'USER': 'cltbld', 23:27:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:27:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:27:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:27:32 INFO - 'XPC_FLAGS': '0x0', 23:27:32 INFO - 'XPC_SERVICE_NAME': '0', 23:27:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:27:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:27:32 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 23:27:32 INFO - Cleaning up... 23:27:32 INFO - Return code: 0 23:27:32 INFO - Running post-action listener: _resource_record_post_action 23:27:32 INFO - Running post-action listener: _start_resource_monitoring 23:27:32 INFO - Starting resource monitoring. 23:27:32 INFO - [mozharness: 2016-12-27 07:27:32.518044Z] Finished create-virtualenv step (success) 23:27:32 INFO - [mozharness: 2016-12-27 07:27:32.519028Z] Running install step. 23:27:32 INFO - Running pre-action listener: _resource_record_pre_action 23:27:32 INFO - Running main action method: install 23:27:32 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 23:27:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 23:27:32 INFO - Reading from file tmpfile_stdout 23:27:32 INFO - Detecting whether we're running mozinstall >=1.0... 23:27:32 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 23:27:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 23:27:32 INFO - Reading from file tmpfile_stdout 23:27:32 INFO - Output received: 23:27:32 INFO - Usage: mozinstall [options] installer 23:27:32 INFO - Options: 23:27:32 INFO - -h, --help show this help message and exit 23:27:32 INFO - -d DEST, --destination=DEST 23:27:32 INFO - Directory to install application into. [default: 23:27:32 INFO - "/builds/slave/test"] 23:27:32 INFO - --app=APP Application being installed. [default: firefox] 23:27:32 INFO - mkdir: /builds/slave/test/build/application 23:27:32 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 23:27:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 23:27:54 INFO - Reading from file tmpfile_stdout 23:27:54 INFO - Output received: 23:27:54 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 23:27:54 INFO - Running post-action listener: _resource_record_post_action 23:27:54 INFO - [mozharness: 2016-12-27 07:27:54.835737Z] Finished install step (success) 23:27:54 INFO - [mozharness: 2016-12-27 07:27:54.835844Z] Running run-tests step. 23:27:54 INFO - Running pre-action listener: _resource_record_pre_action 23:27:54 INFO - Running main action method: run_tests 23:27:54 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 23:27:54 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 23:27:54 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 23:27:54 INFO - 2016-12-26 23:27:54.850 screenresolution[1632:7968] starting screenresolution argv=screenresolution get 23:27:54 INFO - 2016-12-26 23:27:54.853 screenresolution[1632:7968] Display 0: 1600x1200x32@60 23:27:54 INFO - 2016-12-26 23:27:54.858 screenresolution[1633:7971] starting screenresolution argv=screenresolution list 23:27:54 INFO - Available Modes on Display 0 23:27:54 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 23:27:54 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 23:27:54 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 23:27:54 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 23:27:55 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 23:27:55 INFO - Intel Iris: 23:27:55 INFO - Chipset Model: Intel Iris 23:27:55 INFO - Type: GPU 23:27:55 INFO - Bus: Built-In 23:27:55 INFO - VRAM (Dynamic, Max): 1536 MB 23:27:55 INFO - Vendor: Intel (0x8086) 23:27:55 INFO - Device ID: 0x0a2e 23:27:55 INFO - Revision ID: 0x0009 23:27:55 INFO - Displays: 23:27:55 INFO - Display: 23:27:55 INFO - Resolution: 1600 x 1200 @ 60 Hz 23:27:55 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 23:27:55 INFO - Display Serial Number: 1600x1200 60 23:27:55 INFO - Main Display: Yes 23:27:55 INFO - Mirror: Off 23:27:55 INFO - Online: Yes 23:27:55 INFO - Rotation: Supported 23:27:55 INFO - Return code: 0 23:27:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 23:27:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 23:27:55 INFO - Python 2.7.3 23:27:55 INFO - Return code: 0 23:27:55 INFO - grabbing minidump binary from tooltool 23:27:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:27:55 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d202d30>, '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 0x10d129db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d1f7be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 23:27:55 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 23:27:55 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 23:27:55 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 23:27:55 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 23:27:55 INFO - Return code: 0 23:27:55 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 23:27:55 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 23:27:55 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 23:27:55 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 23:27:55 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 23:27:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Graphics', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/P59eSwjsRM-Um1eSaFQMIg/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0247', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 23:27:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Graphics --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/P59eSwjsRM-Um1eSaFQMIg/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0247 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 23:27:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jtpAArVW4p/Render', 23:27:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:27:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:27:55 INFO - 'HOME': '/Users/cltbld', 23:27:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:27:55 INFO - 'LOGNAME': 'cltbld', 23:27:55 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 23:27:55 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 23:27:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:27:55 INFO - 'MOZ_NO_REMOTE': '1', 23:27:55 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 23:27:55 INFO - 'NO_EM_RESTART': '1', 23:27:55 INFO - 'PAGER': '/bin/cat', 23:27:55 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:27:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:27:55 INFO - 'PWD': '/builds/slave/test', 23:27:55 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 23:27:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:27:55 INFO - 'SHELL': '/bin/bash', 23:27:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KkuD0jvWSD/Listeners', 23:27:55 INFO - 'TMPDIR': '/var/folders/hr/hkb07m1j4sq5c29hg511636400000w/T/', 23:27:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:27:55 INFO - 'USER': 'cltbld', 23:27:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:27:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:27:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:27:55 INFO - 'XPC_FLAGS': '0x0', 23:27:55 INFO - 'XPC_SERVICE_NAME': '0', 23:27:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:27:55 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Graphics', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/P59eSwjsRM-Um1eSaFQMIg/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0247', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 23:27:55 INFO - mozversion application_buildid: 20161226221337 23:27:55 INFO - mozversion application_changeset: 2f4e31c8bc98d606f7631c15bbff41c0ed781136 23:27:55 INFO - mozversion application_display_name: Nightly 23:27:55 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 23:27:55 INFO - mozversion application_name: Firefox 23:27:55 INFO - mozversion application_remotingname: firefox 23:27:55 INFO - mozversion application_repository: https://hg.mozilla.org/projects/graphics 23:27:55 INFO - mozversion application_vendor: Mozilla 23:27:55 INFO - mozversion application_version: 53.0a1 23:27:55 INFO - mozversion platform_buildid: 20161226221337 23:27:55 INFO - mozversion platform_changeset: 2f4e31c8bc98d606f7631c15bbff41c0ed781136 23:27:55 INFO - mozversion platform_repository: https://hg.mozilla.org/projects/graphics 23:27:55 INFO - mozversion platform_version: 53.0a1 23:27:55 INFO - using testdate: 1482823675 23:27:55 INFO - actual date: 1482823675 23:27:55 INFO - starting webserver on 'localhost:49235' 23:27:55 INFO - SUITE-START | Running 2 tests 23:27:55 INFO - TEST-START | dromaeo_css 23:27:55 INFO - operating with platform_type : mac_ 23:27:55 INFO - Initialising browser for dromaeo_css test... 23:27:55 INFO - TEST-INFO | started process 1643 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/hr/hkb07m1j4sq5c29hg511636400000w/T/tmpYmH2v8/profile http://localhost:49235/getInfo.html) 23:27:56 INFO - PROCESS | 1643 | 1482823676020 addons.xpi WARN Exception running bootstrap method install on shield-recipe-client@mozilla.org: [Exception... "Component returned failure code: 0x8000ffff (NS_ERROR_UNEXPECTED) [nsIPrefBranch.getBoolPref]" nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js :: this.install :: line 38" data: no] Stack trace: this.install()@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:38 < callBootstrapMethod()@resource://gre/modules/addons/XPIProvider.jsm:4979 < processFileChanges()@resource://gre/modules/addons/XPIProvider.jsm -> resource://gre/modules/addons/XPIProviderUtils.js:2097 < checkForChanges()@resource://gre/modules/addons/XPIProvider.jsm:3825 < startup()@resource://gre/modules/addons/XPIProvider.jsm:2829 < callProvider()@resource://gre/modules/AddonManager.jsm:264 < _startProvider()@resource://gre/modules/AddonManager.jsm:771 < startup()@resource://gre/modules/AddonManager.jsm:957 < startup()@resource://gre/modules/AddonManager.jsm:2923 < observe()@resource://gre/components/addonManager.js:65 23:27:57 INFO - PROCESS | 1643 | JavaScript error: chrome://global/content/browser-content.js, line 1772: ReferenceError: event is not defined 23:27:57 INFO - PROCESS | 1643 | JavaScript error: chrome://global/content/browser-content.js, line 1772: ReferenceError: event is not defined 23:27:58 INFO - PROCESS | 1643 | JavaScript error: chrome://global/content/browser-content.js, line 1772: ReferenceError: event is not defined 23:27:58 INFO - PROCESS | 1643 | __metrics Screen width/height:1600/1200 23:27:58 INFO - PROCESS | 1643 | colorDepth:24 23:27:58 INFO - PROCESS | 1643 | Browser inner width/height: 1024/689 23:27:58 INFO - PROCESS | 1643 | __metrics 23:27:58 INFO - PROCESS | 1643 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 23:27:58 INFO - PROCESS | 1643 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 23:27:59 INFO - TEST-INFO | 1643: exit 0 23:27:59 INFO - Browser initialized. 23:27:59 INFO - Running cycle 1/1 for dromaeo_css test... 23:27:59 INFO - TEST-INFO | started process 1646 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/hr/hkb07m1j4sq5c29hg511636400000w/T/tmpYmH2v8/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 23:28:11 INFO - PROCESS | 1646 | RSS: Main: 226746368 23:28:11 INFO - PROCESS | 1646 | 23:29:02 INFO - PROCESS | 1646 | 1482823742252 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 23:31:16 INFO - PROCESS | 1646 | Cycle 1(1): loaded http://localhost:49235/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49235/tests/dromaeo/cssquery-ext.html) 23:31:17 INFO - PROCESS | 1646 | RSS: Main: 374157312 23:31:17 INFO - PROCESS | 1646 | 23:32:59 INFO - PROCESS | 1646 | ************************* 23:32:59 INFO - PROCESS | 1646 | A coding exception was thrown and uncaught in a Task. 23:32:59 INFO - PROCESS | 1646 | 23:32:59 INFO - PROCESS | 1646 | Full message: ReferenceError: fetch is not defined 23:32:59 INFO - PROCESS | 1646 | Full stack: apiCall@resource://shield-recipe-client/lib/NormandyApi.jsm:37:5 23:32:59 INFO - PROCESS | 1646 | get@resource://shield-recipe-client/lib/NormandyApi.jsm:44:12 23:32:59 INFO - PROCESS | 1646 | this.NormandyApi.fetchRecipes<@resource://shield-recipe-client/lib/NormandyApi.jsm:52:34 23:32:59 INFO - PROCESS | 1646 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 23:32:59 INFO - PROCESS | 1646 | TaskImpl@resource://gre/modules/Task.jsm:277:3 23:32:59 INFO - PROCESS | 1646 | asyncFunction@resource://gre/modules/Task.jsm:252:14 23:32:59 INFO - PROCESS | 1646 | this.RecipeRunner.start<@resource://shield-recipe-client/lib/RecipeRunner.jsm:64:23 23:32:59 INFO - PROCESS | 1646 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 23:32:59 INFO - PROCESS | 1646 | TaskImpl@resource://gre/modules/Task.jsm:277:3 23:32:59 INFO - PROCESS | 1646 | asyncFunction@resource://gre/modules/Task.jsm:252:14 23:32:59 INFO - PROCESS | 1646 | setTimeout_timer@resource://gre/modules/Timer.jsm:30:5 23:32:59 INFO - PROCESS | 1646 | 23:32:59 INFO - PROCESS | 1646 | ************************* 23:34:20 INFO - PROCESS | 1646 | Cycle 1(1): loaded http://localhost:49235/tests/dromaeo/cssquery-ext.html (next: http://localhost:49235/tests/dromaeo/cssquery-jquery.html) 23:34:21 INFO - PROCESS | 1646 | RSS: Main: 374501376 23:34:21 INFO - PROCESS | 1646 | 23:37:23 INFO - PROCESS | 1646 | Cycle 1(1): loaded http://localhost:49235/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49235/tests/dromaeo/cssquery-mootools.html) 23:37:24 INFO - PROCESS | 1646 | RSS: Main: 359792640 23:37:24 INFO - PROCESS | 1646 | 23:40:26 INFO - PROCESS | 1646 | Cycle 1(1): loaded http://localhost:49235/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49235/tests/dromaeo/cssquery-prototype.html) 23:40:27 INFO - PROCESS | 1646 | RSS: Main: 346845184 23:40:27 INFO - PROCESS | 1646 | 23:43:30 INFO - PROCESS | 1646 | Cycle 1(1): loaded http://localhost:49235/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49235/tests/dromaeo/cssquery-yui.html) 23:43:31 INFO - PROCESS | 1646 | RSS: Main: 342298624 23:43:31 INFO - PROCESS | 1646 | 23:46:35 INFO - PROCESS | 1646 | Cycle 1(1): loaded http://localhost:49235/tests/dromaeo/cssquery-yui.html (next: http://localhost:49235/tests/dromaeo/cssquery-dojo.html) 23:46:35 INFO - PROCESS | 1646 | RSS: Main: 609140736 23:46:35 INFO - PROCESS | 1646 | 23:46:35 INFO - PROCESS | 1646 | __start_tp_report 23:46:35 INFO - PROCESS | 1646 | _x_x_mozilla_page_load 23:46:35 INFO - PROCESS | 1646 | _x_x_mozilla_page_load_details 23:46:35 INFO - PROCESS | 1646 | |i|pagename|runs| 23:46:35 INFO - PROCESS | 1646 | |0;dojo.html;3374.3251349730053;3385.7303511183304;3389.4237979543477;3436.2783815398766;3437.6218615952257;4809.446913604932;5612.943870561293;5723.313202415716;5737.827865164047;5813.50585200258;5721.885562288751;5758.827235182951;5851.7074146292625;5853.473187413132;5909.881802363951;5113.002964421939;5188.4811518848;5192.1432963560965;5221.321228240324;5259.8422047338645;5321.840344789663;5353.785848566053;5356.866078348034;5357.4106848246665;5361.195820626898;5285.207218917155;5288.8413347599635;5330.1205301125465;5342.412334643186;5355.2770375999135;5166.638335316529;5182.196759502283;5211.270422140903;5215.426303106655;5239.685618862843;5228.8692782680355;5232.843014709565;5249.238860365225;5259.132243179871;5297.364316282071;5734.082546792493;5736.8852622947725;5750.079987202028;5752.165935939307;5760.395158508338;6128.754849806002;6143.095640351506;6149.96925015372;6204.751809927598;6264.655443950581;6138.633639632459;6148.723307451175;6171.413715697002;6198.845028874269;6201.224846894138;5797.536197104221;5820.3888591698105;5824.592278540462;5867.970660146672;5869.06095024794;6152.569320147547;6217.6891155442045;6232.688365581794;6243.125962365272;6243.96878015607;6053.545984051214;6100.176476175749;6142.539309551801;6151.9692401538605;6157.353477884758;6125.41859352575;6180.6909654517995;6186.659733714599;6202.596831205957;6227.190465239581;6138.969305153446;6183.598066125687;6205.720742566596;6222.097795819581;6224.035274532454;6193.752249909953;6219.564630475823;6222.408871157233;6229.3770622937345;6235.875282494325;4857.975710121426;4876.780544875489;4894.8042078317185;4917.385326834145;4949.307097006422;4437.977810110993;4458.08609235106;4468.3074123510905;4482.305242687388;4499.4150760400935;5804.390538993429;5815.127730840408;5817.7963771267805;5819.5053420459735;5822.796202132905;6122.112293717386;6156.907646385308;6166.5991710539565;6185.165002724574;6190.071489276645;5737.110747834093;5761.798337058183;5786.739596718157;5832.941670583325;5865.618734782225;5340.145576707708;5421.159720243369;5432.212329212242;5442.482964118402;5465.07093794056;6296.33888441707;6297.023961286008;6305.4009869062365;6311.621302721759;6377.5216858735785;6072.666003369857;6112.41229444207;6141.938580614136;6143.757528502154;6159.23009623797;6142.928535732134;6211.565190436626;6229.314775374614;6234.688265586612;6240.594361366497;6076.392360764065;6091.451769340603;6103.671041328402;6115.818436018264;6124.136831511461;6186.072089186478;6196.566240363131;6202.534809889186;6209.751609935733;6226.875462490634;5803.47768700819;5968.671723055274;6130.111133885651;6155.1075094112;6155.35368786271;5987.34139244674;6029.186059881859;6030.065339872327;6088.908666370096;6105.175801266772;6015.969920150371;6034.09488576717;6049.274087109575;6080.908786368296;6093.817185484442;6133.969330153321;6159.938400615936;6166.506679465721;6172.845678858064;6192.071189321638;5829.94170058294;5883.705814709333;5888.352281249145;5971.880562388641;6032.276126864805 23:46:35 INFO - PROCESS | 1646 | |1;ext.html;429.97635130067835;484.6002048310144;491.32933545710097;493.99965070731776;510.26776575613974;25882.341178587845;38166.80916595417;39937.20125597486;40072;42096.36855447171;40759.38860917081;41164.97087572812;41606.58393416065;41644;41698.583014169846;8399.958000210021;9293.395929264592;9516.333856630039;9520.190783783351;9531.046895310448;8499.575021248944;10343.37939723619;11385.601503947364;11404.942975285112;11545.480453379618;11350.035247004014;11351.20541562091;11371.772564548704;11414.71463213418;11453.312801231952;11253.831192532118;11322;11326.150538709548;11349.20555561111;11357.659270221906;11129.833052504138;11186.328820270728;11191.384473853936;11217.326960382403;11223.943880280547;38339.23321533554;38621.613783862085;38711.8064409679;38789.03027424309;38878.22243555141;3455.188030812757;3517.4020416528997;3605.13476765577;3977.443157957888;4174.874753757392;28688;30115;30272.394552108835;30425.847870760503;30799.69200308013;3194.552762613236;3207.566978457925;3232.9353412931846;3277.2134687675025;3306.9007929762147;458751;463977;464391;466867;470987.6450617759;59119.40880591225;59501.32244049028;59510.70244648749;59589.40410595925;59618.0381961809;60888;60998.695006524686;62594;62748.68625656843;62952;15384.461543845911;15850.44523441674;15866;15876.841231587767;15915.204239788196;10962.725931851768;11166.385848778233;11189.328640281608;12148.392580370946;12279.324637144864;16130;16558.751618725728;16808.57978550546;16895;16902.32390704358;7364.742234021784;7392.371648409838;7445.218252083453;7463.290987356192;7526.661300241502;798.1300382583013;1032.3496197395593;1033.6485594897772;1065.73177918277;1084.9909584086874;25478.872605636854;28303.048968278214;28735.281617959718;29063.9827606037;29648.555271670943;1010.3937637417491;1051.1013083813382;1066.7279843639822;1126.4649291586595;1127.1208457403238;12869;13536.864631353757;14728.55814325572;15280.930334876452;15521.37914483408;598791;599938;605561;608552;609700;51377.7431112842;51503.484965149866;51508.227376589384;51630;51644.74177629163;1331.2544974814134;1353.3165751295533;1388.8402833674256;1393.547097193396;1396.2041636267434;27309;28331.575026375034;28334.149975500768;28654.85672571624;28689;44219.55780442283;44612.33081503845;44670.77664611656;44856.551434485234;44899.32651010303;565251;569017;570020;570133;570226.148869253;23415.88292058529;23501.942412591132;23788.167414140422;23986.760132398453;24047.51904961856;40088;40238.79880600578;40941.38587921124;40961.77114686564;41428.79285603553;27642;34301.48547771835;34811.30377392387;34888;35540.644593553734;28389;29483.705162948096;33857.64569417196;34178.31643367169;34915;33710.32579348448;34343.79796707203;34449;34583.65416345804;34661.82669086739;27862.3034424141;29283.975060872766;30362.696373035986;30621.08136755901;30638.387232254783;20295.898520507304;21938.451538711022;23003.964821583402;23232.651510227697;23845.761542384353 23:46:35 INFO - PROCESS | 1646 | |2;jquery.html;343.6888271245198;364.215116424106;366.1925454373107;370.5460810507129;394.1978074618151;13554.932225338871;14533.128012319256;15555;19619.5095122622;20315.695264571044;15994.280257388416;20605.484862878435;21138.471538211554;21302.147914083416;21407.250746223886;5489.890202195954;5780.4797568218855;5793.420657934215;5794.71026448678;5800.4489573490455;4822.4936381679945;5202.739863006835;5771.365149833515;5802.39074897136;5818.563607729416;5740.368559458456;5749.971250143743;5750.626209296403;5753.971230143843;5783.392743761887;5426.864328391783;5571.108622620402;5637.323521177444;5654.9151762723595;5659.207710920475;4745.525447455261;4957.454679985199;4966.453690094087;4969.826056088055;4969.925451118236;20653.070611822503;20838.853863037526;24252.63621045668;24353.63469547958;24355.512889742284;2188.616992026389;2204.1954686539298;2256.684064231009;2268.7277526696903;2390.5218956208655;14378.568642940729;15170.772438413322;15411.460598879097;15470.226488675633;15764.605884852856;1679.9248480972187;1758.498827834061;1810.5835657798648;1818.890866547998;1825.470613522077;110092.4402937688;110403.44798276038;112297.87702122956;112803.43598282037;113962;10085.092341689136;10232.897671023344;10332.948335258276;10564.471776411148;10766.78466430667;11149.888501115047;13273.137246078973;13277.203367797963;13278.734425311439;13298.534551290659;6947;7911.129775724666;8172.836543269102;8197.877031844528;8311.709090181814;7925.524468531791;7968.442209045311;8213.425060245845;8420.705275315335;8436.28291595209;6293.527985401113;6399.424051835357;6406.167198264196;6457.644829534327;6530.2816690164045;1794.3719698105558;1811.2392795025858;1814.9001804900856;1820.9180586873622;1832.0748022248945;876.3514998900768;911.7401540560908;954.0316578672653;954.1889394015031;980.6518685866557;18141.63716725658;18660.440186794418;18772.68591198603;21600.243991460222;21782.673259901116;882.6513527156737;922.3681777982104;937.2689302344182;970.4569734122779;1042.3641578637025;10892.509837057352;11433.885661143282;11731.941340293244;12095.966516355595;12126.939365303117;503284;547843;547844;553417;562985;88455;108520;128805;129483.35258323836;135393;976.4824642939111;1060.1624716474053;1095.2606990281529;1096.4024606589269;1099.2580008494394;19361.903190483958;19642.410727678194;19671.70492442643;19815.306464274257;19926.402207933785;53626.19560706671;56034.15948760691;56539.43460565342;56970.575735606944;57073.14634268353;80897;81274.59362703386;81531.18468815235;81992;83052;6397.936020639734;6820.965895170492;7417.740379086706;8246.257836794717;8249.917500824915;9148.268138548767;11973.880261197277;12638.936805316282;13045.152065115737;13056.608301750963;10978.066864316303;11238.269512481662;11404.315741055563;11478.94260528692;11518.596849110241;9106.817863642822;10840.67477975662;10887.401192934149;11347.602833901105;11479.770404592029;9748.22014238845;10681.679549613524;11080;11274.887251127711;11649.534018639499;7826.491278066907;8122.944017277952;8183.71357002502;8202.958985205036;8340.416170868224;8231.34149268069;10042.648507302209;11062.889371106186;11386.94306528462;11607.187496875138 23:46:35 INFO - PROCESS | 1646 | |3;mootools.html;1316.6642506160929;1524.2378810594698;1689.2820551265718;1863.310575087218;1917.3289348727947;7903.525788452697;8328.20943497148;8365.498070115782;8371.83256334873;8405.495670259788;6620.735170593171;7392.815179620512;7602.657880395382;7619.085709714828;8168.91831081689;2268.761780013104;2298.2645553422913;2313.8148948084113;2314.2478694424294;2317.165820304689;2305.3775480620225;2336.9883150584224;2340.1458467659268;2348.8120950323932;2354.5526349993447;2282.783135602115;2288.8397812153157;2311.9422014449688;2320.2807129789735;2321.5589038082794;2266.784655457729;2291.942701432461;2292.4612716011725;2298.9310320690324;2302.815774738017;2295.8392912496133;2318.953620927572;2325.6744055832196;2326.639370897514;2331.976680233193;8074.475159114698;8297.004359476901;8300.501969881767;8313.667453301861;8320.70877519284;4997.725125118117;5153.072446959546;5164.845054648366;5165.612579056548;5170.948290517122;3212.887548935776;3278.098522906196;3475.9131021724406;3483.860645574174;3497.4928635347987;1023.3041531758402;1050.6847945616285;1062.3254233561668;1065.5364916261374;1069.4545781651336;10519.52662130206;10598.258121931467;10705.571777128906;10715.732106697316;10731.731706707395;4390.341448782644;4535.63714902807;4630.6063984561015;4634.189016922025;4646.512116227815;9173.81652366949;9176;9188.678396256098;9193.724188274362;9202.079792020744;5113.335266415343;5115.283860259567;5122.231665250168;5124.180131179068;5129.230615407719;3827.27281816454;4169.833206671699;4618.284165954283;4695.859124226278;4732.266498692708;3737.8691745788765;3750.774953502799;3824.7131465140224;3852.1910398816563;3861.4361183720075;1255.9686007849878;1266.2276011632896;1267.9175853569488;1277.757226127033;1281;825.6614787936917;834.9081601023883;845.4673555659895;890.9198172164537;909.8589718593629;4929.334539837076;5026.79892804291;5076.238564215398;5263.499967503081;5396.406395296514;427.2992292640073;431.45851955795274;433.05593805504304;435.67977536510637;893.78995935955;3255.4140254753897;3317.834108294576;3341.9974007797564;3483.4600636901323;3495.877644282438;8360.163983601591;9540.379875308083;9752.707418777449;9887.159591434665;9896.208303335716;9850.50747462624;9858.65494707696;9858.901410985942;9870.851937220947;9877.456739879375;7535.284148005931;7572.886406703904;7663.003809504728;7800.258975397328;7816.374690024784;5149.176131818891;5173.63784535079;5174.844754657366;5260.789568417221;5285.947140528546;9102.362834601514;9202.125798049176;9272.675456358995;9274.860877086714;9312.48781317034;9084.955230148353;9160.221381182673;9173.954130229306;9332.30007749408;9387.530623468936;6125.418085281891;6318.6840657965995;6777.220619628806;6890;7110.146782386147;7786.922130778619;7884.921150788648;7892.289693927574;7893.36853051766;7905.32804711606;6987.6855541499735;7079.362857342864;7108.218096009331;7167.892481612885;7219.314165154302;6925.965370173117;6927.411170050602;6957.451699633325;6969.512134150561;7034.331738484836;5794.391588883106;5843.970780146072;5847.532197424281;5941.1088336749835;5955.315138759098;4441.866743997685;4485.6860019798305;4535.138323718483;4539.455265368177;4543.863684089482;4396.296592545177;4502.707324023928;4605.7466839322815;4613.308003799322;4768.1417344878255 23:46:35 INFO - PROCESS | 1646 | |4;prototype.html;337.78381835625197;348.66702299304166;358.40683668528595;366.5454836003357;370.951776269085;5031.396232452102;5054.84835454936;5239.240310155025;5278.208268759688;5279.41926388097;4253.808578613962;4334.501532323784;5086.677463859395;5140.254663073862;5211.123995361667;3542.7697199682066;4036.616521430467;4114.773687447189;4118.5057793064725;4151.194230509723;3405.3529829332347;3437.226624009603;3472.8263586820685;4089.2379011309667;4123.402106694528;4031.778252196128;4080.510338759353;4100.733452325604;4101.589841015904;4126.607972242632;3857.228554289153;4031.7580945143386;4048.1903619276263;4072.2059198456313;4079.7348172368847;2937.4125174965093;3168.8098714077228;3239.5626590410466;3465.896023119285;3622.329868974222;4142.46148000758;4234.195502854448;4690.5778479936625;5098.821541246039;5172.405173405032;3628.546431696038;4065.4918135233097;4502.909941801146;4514.277715565527;4537.568930951555;4476.7090139140855;4659.767011649404;4852.902941941176;5512.972435137839;5526.944730552683;3290.8975493209805;3541.4333706607076;3649.9635003649887;3748.8125593720206;3761.5110035695184;5376.489233522809;5512.476314750093;5636.731735359553;5767.942320576824;5774.191613174159;3785.242951409729;3801.6958643308467;3803.4865293185067;3813.142043040349;3815.771053736785;3849.8845034649007;3850.614938506127;3872.399778034353;3876.592957739453;3890;3637.3452778499595;4247.341662042327;4386.881345256939;4483.910321793611;4497.953431188801;4840.322354870321;5271.973640131851;5508.807191748269;5553.555715542747;5571.303587051619;1011.8128146292812;1128.8532490776147;1212.5695378140808;1225.601679454181;1234.6851552854143;837.6440012994453;846.327169899931;851.3104385447683;856.5267739488071;863.1411745313453;4164.583541645811;4825.662203645711;4850.442199147073;4870.1233777920315;4879.658423910363;4561.429821272341;4838.975805120952;5106.795728170831;5281.471852814765;5322.520973112439;4444.488883778342;4712.952870471251;5126.384833819965;5200.921986170135;5219.3736751589295;2452.546278938402;2485.391079185575;2802.7057158998014;2849.3189018478784;2869.526528122878;4863.927041094386;5412.9701361989255;5426.782928682808;5593;5604.607677462538;4749.976250118727;5201.349831271091;5210.478952104835;5540.612157149041;5854.950767231435;4780.521947805191;5847.491505096993;5875.206847075761;5885.588008839341;5916.970415147896;4559.411650165479;4564.863054108382;5417.702026388586;5419.558580301458;5673.687947162781;5280.039970022483;5605.299337582802;5608.4111168326735;5611.831645050655;5628.635972891314;5392.919106213331;5794.507466865195;5824.883502330014;5871.853203669943;5882.617629854046;3608.8015159165448;3740.850365985439;3764.680002199844;3795.411711184771;3797.677197438138;4708.764561771966;5570.526505246938;5605.80379686709;5622.746976385991;5639.125935480007;4622.329762184397;5143.768530416215;5499.395066542597;5515.669059856422;5540.722963851711;4627.028324051987;5064.215046667772;5203.985222881502;5239.554637855673;5529.695866727368;4645.396098507307;4947.872414861644;5128.922646412123;5304.541251155963;5314.7874085036165;4711.952880471152;4965.379327584052;5401.432849550898;5414.83755487336;5512.81474482972;5173.956287266584;5403.000444917703;5414.7292635367285;5430;5440.782368705208 23:46:35 INFO - PROCESS | 1646 | |5;yui.html;715.4026387966048;747.1072068877689;779.431015358788;781.9491733037348;823.4194892600717;14779.187144707037;15924.840751592481;15942.84057159428;15943.920280398595;15970.76043859343;14343.569692909203;14497.855021449808;14514.564563063099;14522.491712790055;14533.78199327011;691.9792406227821;715.6636380900968;720.4776537010652;871.1071152069132;892.5403417240127;867.0982178534317;883.2360008592553;892.5983307511614;906.3247880329164;907.1790030022839;848.6435697007241;949.0414681172032;949.981000379992;952.6046690623383;952.8189643967659;687.3229868579446;867.1501928110487;939.5631031570318;942.5664194470552;943.2124176312788;559.9300087489064;677.8779819632464;695.1102588686489;702.764573867755;705.474421555939;12544.310062946533;15127.924360378127;15152.393904243818;15163.393464261417;15195.088294702242;13754.587362379145;13773.931130344385;13795;13841.446342146302;13889.652758681013;12608.747825043447;12789.296588687619;12825.23048617086;12970.740585188243;13315.600531984055;3745.419459983707;4483.641308695296;4529.297958816389;4654.790534425959;4678.929816052762;97667;105237.47381263121;105429.94570054278;107325;107549.46225268902;10957.890421095846;11091.667249982513;11094.722631934266;11133.832992505118;11305;21884.671729924063;21885.890570547046;21890.781092189194;21914.89042554809;21939.793311368026;7717.575533345607;7792.41556883236;7796.571188584682;7884.093329267093;7913.960430197927;4238.87283381499;4304.354346847936;4323.502797178302;4340.370646256275;4341.739495630272;3413;3429.725621950238;3454.464557993515;3502.6847583717586;3516.1385460562324;642.7107801489256;644.4779728419995;645.319188256394;662.3277373465936;662.7647185249264;463.87011636741767;542.5903442900585;587.295245705147;634.3561285295428;637.5441559285133;8330.50016998982;8911.866322005175;8964;8968.820623587622;9065.59204835784;551.3631755322609;599.8050633544116;611.6880391000622;624.0545573456218;632.2381530256031;4260.190563792869;4400.163968845909;4421.447319085114;4433.600975912119;4482.910341793146;102692.48653756833;102799;104064.43903341456;104896;105198.47400762947;64148;64549.677251613444;65493;82101.35797284021;85992.57003714942;64841.67579162074;70572.64713676399;74562.5087498247;75897.86153207706;77384.83922741163;9886.357386770125;9902.405855648683;9918.702438926844;9941.602335906482;9950.303478756417;45474.09051818879;49291.26063108985;49505.752471238855;49913;50919;65660;70039.2490187729;70213.64893175705;70925;73466;12507.499700011898;12950.676233094247;12959.157654752411;13191.274479903697;13536.458541658223;14515.05652132608;14559;14581.854181458473;14766.852331476977;15244.466443674419;12059.397030148282;13503;13791.241481718602;14078.155310681392;14084.507042253472;13278.535251266545;13512.054156208971;13599;14177.716445671234;14216.715665686836;13448.327583620976;13645.863541364857;13671.248081355223;13902.374393152535;14189.219592922489;10736.80003996191;10749.89250107489;11216.887831121585;11381.829272561085;11673.533058677558;9187.760253620118;9937;10163.94918025405;11112.611058612909;11233.32600044 23:46:35 INFO - PROCESS | 1646 | __end_tp_report 23:46:35 INFO - PROCESS | 1646 | __start_cc_report 23:46:35 INFO - PROCESS | 1646 | _x_x_mozilla_cycle_collect,1540 23:46:35 INFO - PROCESS | 1646 | __end_cc_report 23:46:35 INFO - PROCESS | 1646 | __startTimestamp1482824795344__endTimestamp 23:46:35 INFO - PROCESS | 1646 | 23:46:35 INFO - PROCESS | 1646 | ------- Summary: start ------- 23:46:35 INFO - PROCESS | 1646 | Number of tests: 6 23:46:35 INFO - PROCESS | 1646 | 23:46:35 INFO - PROCESS | 1646 | [#0] dojo.html Cycles:165 Average:5748.97 Median:6015.97 stddev:614.90 (10.2%) stddev-sans-first:587.89 23:46:35 INFO - PROCESS | 1646 | Values: 3374.3 3385.7 3389.4 3436.3 3437.6 4809.4 5612.9 5723.3 5737.8 5813.5 5721.9 5758.8 5851.7 5853.5 5909.9 5113.0 5188.5 5192.1 5221.3 5259.8 5321.8 5353.8 5356.9 5357.4 5361.2 5285.2 5288.8 5330.1 5342.4 5355.3 5166.6 5182.2 5211.3 5215.4 5239.7 5228.9 5232.8 5249.2 5259.1 5297.4 5734.1 5736.9 5750.1 5752.2 5760.4 6128.8 6143.1 6150.0 6204.8 6264.7 6138.6 6148.7 6171.4 6198.8 6201.2 5797.5 5820.4 5824.6 5868.0 5869.1 6152.6 6217.7 6232.7 6243.1 6244.0 6053.5 6100.2 6142.5 6152.0 6157.4 6125.4 6180.7 6186.7 6202.6 6227.2 6139.0 6183.6 6205.7 6222.1 6224.0 6193.8 6219.6 6222.4 6229.4 6235.9 4858.0 4876.8 4894.8 4917.4 4949.3 4438.0 4458.1 4468.3 4482.3 4499.4 5804.4 5815.1 5817.8 5819.5 5822.8 6122.1 6156.9 6166.6 6185.2 6190.1 5737.1 5761.8 5786.7 5832.9 5865.6 5340.1 5421.2 5432.2 5442.5 5465.1 6296.3 6297.0 6305.4 6311.6 6377.5 6072.7 6112.4 6141.9 6143.8 6159.2 6142.9 6211.6 6229.3 6234.7 6240.6 6076.4 6091.5 6103.7 6115.8 6124.1 6186.1 6196.6 6202.5 6209.8 6226.9 5803.5 5968.7 6130.1 6155.1 6155.4 5987.3 6029.2 6030.1 6088.9 6105.2 6016.0 6034.1 6049.3 6080.9 6093.8 6134.0 6159.9 6166.5 6172.8 6192.1 5829.9 5883.7 5888.4 5971.9 6032.3 23:46:35 INFO - PROCESS | 1646 | 23:46:35 INFO - PROCESS | 1646 | [#1] ext.html Cycles:180 Average:66864.91 Median:3292.06 stddev:146855.60 (4460.9%) stddev-sans-first:147182.86 23:46:35 INFO - PROCESS | 1646 | Values: 430.0 484.6 491.3 494.0 510.3 25882.3 38166.8 39937.2 40072.0 42096.4 40759.4 41165.0 41606.6 41644.0 41698.6 8400.0 9293.4 9516.3 9520.2 9531.0 8499.6 10343.4 11385.6 11404.9 11545.5 11350.0 11351.2 11371.8 11414.7 11453.3 11253.8 11322.0 11326.2 11349.2 11357.7 11129.8 11186.3 11191.4 11217.3 11223.9 38339.2 38621.6 38711.8 38789.0 38878.2 3455.2 3517.4 3605.1 3977.4 4174.9 28688.0 30115.0 30272.4 30425.8 30799.7 3194.6 3207.6 3232.9 3277.2 3306.9 458751.0 463977.0 464391.0 466867.0 470987.6 59119.4 59501.3 59510.7 59589.4 59618.0 60888.0 60998.7 62594.0 62748.7 62952.0 15384.5 15850.4 15866.0 15876.8 15915.2 10962.7 11166.4 11189.3 12148.4 12279.3 16130.0 16558.8 16808.6 16895.0 16902.3 7364.7 7392.4 7445.2 7463.3 7526.7 798.1 1032.3 1033.6 1065.7 1085.0 25478.9 28303.0 28735.3 29064.0 29648.6 1010.4 1051.1 1066.7 1126.5 1127.1 12869.0 13536.9 14728.6 15280.9 15521.4 598791.0 599938.0 605561.0 608552.0 609700.0 51377.7 51503.5 51508.2 51630.0 51644.7 1331.3 1353.3 1388.8 1393.5 1396.2 27309.0 28331.6 28334.1 28654.9 28689.0 44219.6 44612.3 44670.8 44856.6 44899.3 565251.0 569017.0 570020.0 570133.0 570226.1 23415.9 23501.9 23788.2 23986.8 24047.5 40088.0 40238.8 40941.4 40961.8 41428.8 27642.0 34301.5 34811.3 34888.0 35540.6 28389.0 29483.7 33857.6 34178.3 34915.0 33710.3 34343.8 34449.0 34583.7 34661.8 27862.3 29284.0 30362.7 30621.1 30638.4 20295.9 21938.5 23004.0 23232.7 23845.8 23:46:35 INFO - PROCESS | 1646 | 23:46:35 INFO - PROCESS | 1646 | [#2] jquery.html Cycles:180 Average:33065.38 Median:2329.62 stddev:90980.53 (3905.4%) stddev-sans-first:91202.58 23:46:35 INFO - PROCESS | 1646 | Values: 343.7 364.2 366.2 370.5 394.2 13554.9 14533.1 15555.0 19619.5 20315.7 15994.3 20605.5 21138.5 21302.1 21407.3 5489.9 5780.5 5793.4 5794.7 5800.4 4822.5 5202.7 5771.4 5802.4 5818.6 5740.4 5750.0 5750.6 5754.0 5783.4 5426.9 5571.1 5637.3 5654.9 5659.2 4745.5 4957.5 4966.5 4969.8 4969.9 20653.1 20838.9 24252.6 24353.6 24355.5 2188.6 2204.2 2256.7 2268.7 2390.5 14378.6 15170.8 15411.5 15470.2 15764.6 1679.9 1758.5 1810.6 1818.9 1825.5 110092.4 110403.4 112297.9 112803.4 113962.0 10085.1 10232.9 10332.9 10564.5 10766.8 11149.9 13273.1 13277.2 13278.7 13298.5 6947.0 7911.1 8172.8 8197.9 8311.7 7925.5 7968.4 8213.4 8420.7 8436.3 6293.5 6399.4 6406.2 6457.6 6530.3 1794.4 1811.2 1814.9 1820.9 1832.1 876.4 911.7 954.0 954.2 980.7 18141.6 18660.4 18772.7 21600.2 21782.7 882.7 922.4 937.3 970.5 1042.4 10892.5 11433.9 11731.9 12096.0 12126.9 503284.0 547843.0 547844.0 553417.0 562985.0 88455.0 108520.0 128805.0 129483.4 135393.0 976.5 1060.2 1095.3 1096.4 1099.3 19361.9 19642.4 19671.7 19815.3 19926.4 53626.2 56034.2 56539.4 56970.6 57073.1 80897.0 81274.6 81531.2 81992.0 83052.0 6397.9 6821.0 7417.7 8246.3 8249.9 9148.3 11973.9 12638.9 13045.2 13056.6 10978.1 11238.3 11404.3 11478.9 11518.6 9106.8 10840.7 10887.4 11347.6 11479.8 9748.2 10681.7 11080.0 11274.9 11649.5 7826.5 8122.9 8183.7 8203.0 8340.4 8231.3 10042.6 11062.9 11386.9 11607.2 23:46:35 INFO - PROCESS | 1646 | 23:46:35 INFO - PROCESS | 1646 | [#3] mootools.html Cycles:180 Average:5277.16 Median:5123.21 stddev:2913.03 (56.9%) stddev-sans-first:2906.00 23:46:35 INFO - PROCESS | 1646 | Values: 1316.7 1524.2 1689.3 1863.3 1917.3 7903.5 8328.2 8365.5 8371.8 8405.5 6620.7 7392.8 7602.7 7619.1 8168.9 2268.8 2298.3 2313.8 2314.2 2317.2 2305.4 2337.0 2340.1 2348.8 2354.6 2282.8 2288.8 2311.9 2320.3 2321.6 2266.8 2291.9 2292.5 2298.9 2302.8 2295.8 2319.0 2325.7 2326.6 2332.0 8074.5 8297.0 8300.5 8313.7 8320.7 4997.7 5153.1 5164.8 5165.6 5170.9 3212.9 3278.1 3475.9 3483.9 3497.5 1023.3 1050.7 1062.3 1065.5 1069.5 10519.5 10598.3 10705.6 10715.7 10731.7 4390.3 4535.6 4630.6 4634.2 4646.5 9173.8 9176.0 9188.7 9193.7 9202.1 5113.3 5115.3 5122.2 5124.2 5129.2 3827.3 4169.8 4618.3 4695.9 4732.3 3737.9 3750.8 3824.7 3852.2 3861.4 1256.0 1266.2 1267.9 1277.8 1281.0 825.7 834.9 845.5 890.9 909.9 4929.3 5026.8 5076.2 5263.5 5396.4 427.3 431.5 433.1 435.7 893.8 3255.4 3317.8 3342.0 3483.5 3495.9 8360.2 9540.4 9752.7 9887.2 9896.2 9850.5 9858.7 9858.9 9870.9 9877.5 7535.3 7572.9 7663.0 7800.3 7816.4 5149.2 5173.6 5174.8 5260.8 5285.9 9102.4 9202.1 9272.7 9274.9 9312.5 9085.0 9160.2 9174.0 9332.3 9387.5 6125.4 6318.7 6777.2 6890.0 7110.1 7786.9 7884.9 7892.3 7893.4 7905.3 6987.7 7079.4 7108.2 7167.9 7219.3 6926.0 6927.4 6957.5 6969.5 7034.3 5794.4 5844.0 5847.5 5941.1 5955.3 4441.9 4485.7 4535.1 4539.5 4543.9 4396.3 4502.7 4605.7 4613.3 4768.1 23:46:35 INFO - PROCESS | 1646 | 23:46:35 INFO - PROCESS | 1646 | [#4] prototype.html Cycles:180 Average:4367.05 Median:4832.32 stddev:1343.53 (27.8%) stddev-sans-first:1312.82 23:46:35 INFO - PROCESS | 1646 | Values: 337.8 348.7 358.4 366.5 371.0 5031.4 5054.8 5239.2 5278.2 5279.4 4253.8 4334.5 5086.7 5140.3 5211.1 3542.8 4036.6 4114.8 4118.5 4151.2 3405.4 3437.2 3472.8 4089.2 4123.4 4031.8 4080.5 4100.7 4101.6 4126.6 3857.2 4031.8 4048.2 4072.2 4079.7 2937.4 3168.8 3239.6 3465.9 3622.3 4142.5 4234.2 4690.6 5098.8 5172.4 3628.5 4065.5 4502.9 4514.3 4537.6 4476.7 4659.8 4852.9 5513.0 5526.9 3290.9 3541.4 3650.0 3748.8 3761.5 5376.5 5512.5 5636.7 5767.9 5774.2 3785.2 3801.7 3803.5 3813.1 3815.8 3849.9 3850.6 3872.4 3876.6 3890.0 3637.3 4247.3 4386.9 4483.9 4498.0 4840.3 5272.0 5508.8 5553.6 5571.3 1011.8 1128.9 1212.6 1225.6 1234.7 837.6 846.3 851.3 856.5 863.1 4164.6 4825.7 4850.4 4870.1 4879.7 4561.4 4839.0 5106.8 5281.5 5322.5 4444.5 4713.0 5126.4 5200.9 5219.4 2452.5 2485.4 2802.7 2849.3 2869.5 4863.9 5413.0 5426.8 5593.0 5604.6 4750.0 5201.3 5210.5 5540.6 5855.0 4780.5 5847.5 5875.2 5885.6 5917.0 4559.4 4564.9 5417.7 5419.6 5673.7 5280.0 5605.3 5608.4 5611.8 5628.6 5392.9 5794.5 5824.9 5871.9 5882.6 3608.8 3740.9 3764.7 3795.4 3797.7 4708.8 5570.5 5605.8 5622.7 5639.1 4622.3 5143.8 5499.4 5515.7 5540.7 4627.0 5064.2 5204.0 5239.6 5529.7 4645.4 4947.9 5128.9 5304.5 5314.8 4712.0 4965.4 5401.4 5414.8 5512.8 5174.0 5403.0 5414.7 5430.0 5440.8 23:46:35 INFO - PROCESS | 1646 | 23:46:35 INFO - PROCESS | 1646 | [#5] yui.html Cycles:180 Average:19931.14 Median:4427.52 stddev:28240.79 (637.8%) stddev-sans-first:28283.15 23:46:35 INFO - PROCESS | 1646 | Values: 715.4 747.1 779.4 781.9 823.4 14779.2 15924.8 15942.8 15943.9 15970.8 14343.6 14497.9 14514.6 14522.5 14533.8 692.0 715.7 720.5 871.1 892.5 867.1 883.2 892.6 906.3 907.2 848.6 949.0 950.0 952.6 952.8 687.3 867.2 939.6 942.6 943.2 559.9 677.9 695.1 702.8 705.5 12544.3 15127.9 15152.4 15163.4 15195.1 13754.6 13773.9 13795.0 13841.4 13889.7 12608.7 12789.3 12825.2 12970.7 13315.6 3745.4 4483.6 4529.3 4654.8 4678.9 97667.0 105237.5 105429.9 107325.0 107549.5 10957.9 11091.7 11094.7 11133.8 11305.0 21884.7 21885.9 21890.8 21914.9 21939.8 7717.6 7792.4 7796.6 7884.1 7914.0 4238.9 4304.4 4323.5 4340.4 4341.7 3413.0 3429.7 3454.5 3502.7 3516.1 642.7 644.5 645.3 662.3 662.8 463.9 542.6 587.3 634.4 637.5 8330.5 8911.9 8964.0 8968.8 9065.6 551.4 599.8 611.7 624.1 632.2 4260.2 4400.2 4421.4 4433.6 4482.9 102692.5 102799.0 104064.4 104896.0 105198.5 64148.0 64549.7 65493.0 82101.4 85992.6 64841.7 70572.6 74562.5 75897.9 77384.8 9886.4 9902.4 9918.7 9941.6 9950.3 45474.1 49291.3 49505.8 49913.0 50919.0 65660.0 70039.2 70213.6 70925.0 73466.0 12507.5 12950.7 12959.2 13191.3 13536.5 14515.1 14559.0 14581.9 14766.9 15244.5 12059.4 13503.0 13791.2 14078.2 14084.5 13278.5 13512.1 13599.0 14177.7 14216.7 13448.3 13645.9 13671.2 13902.4 14189.2 10736.8 10749.9 11216.9 11381.8 11673.5 9187.8 9937.0 10163.9 11112.6 11233.3 23:46:35 INFO - PROCESS | 1646 | -------- Summary: end -------- 23:46:35 INFO - PROCESS | 1646 | 23:46:35 INFO - TEST-INFO | 1646: exit 0 23:46:36 INFO - TEST-OK | dromaeo_css | took 1120563ms 23:46:36 INFO - TEST-START | kraken 23:46:36 INFO - operating with platform_type : mac_ 23:46:36 INFO - Initialising browser for kraken test... 23:46:36 INFO - TEST-INFO | started process 1652 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/hr/hkb07m1j4sq5c29hg511636400000w/T/tmp7xrqSY/profile http://localhost:49235/getInfo.html) 23:46:36 INFO - PROCESS | 1652 | 1482824796500 addons.xpi WARN Exception running bootstrap method install on shield-recipe-client@mozilla.org: [Exception... "Component returned failure code: 0x8000ffff (NS_ERROR_UNEXPECTED) [nsIPrefBranch.getBoolPref]" nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js :: this.install :: line 38" data: no] Stack trace: this.install()@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:38 < callBootstrapMethod()@resource://gre/modules/addons/XPIProvider.jsm:4979 < processFileChanges()@resource://gre/modules/addons/XPIProvider.jsm -> resource://gre/modules/addons/XPIProviderUtils.js:2097 < checkForChanges()@resource://gre/modules/addons/XPIProvider.jsm:3825 < startup()@resource://gre/modules/addons/XPIProvider.jsm:2829 < callProvider()@resource://gre/modules/AddonManager.jsm:264 < _startProvider()@resource://gre/modules/AddonManager.jsm:771 < startup()@resource://gre/modules/AddonManager.jsm:957 < startup()@resource://gre/modules/AddonManager.jsm:2923 < observe()@resource://gre/components/addonManager.js:65 23:46:37 INFO - PROCESS | 1652 | JavaScript error: chrome://global/content/browser-content.js, line 1772: ReferenceError: event is not defined 23:46:37 INFO - PROCESS | 1652 | JavaScript error: chrome://global/content/browser-content.js, line 1772: ReferenceError: event is not defined 23:46:38 INFO - PROCESS | 1652 | JavaScript error: chrome://global/content/browser-content.js, line 1772: ReferenceError: event is not defined 23:46:38 INFO - PROCESS | 1652 | __metrics Screen width/height:1600/1200 23:46:38 INFO - PROCESS | 1652 | colorDepth:24 23:46:38 INFO - PROCESS | 1652 | Browser inner width/height: 1024/689 23:46:38 INFO - PROCESS | 1652 | __metrics 23:46:38 INFO - PROCESS | 1652 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 23:46:38 INFO - PROCESS | 1652 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 23:46:39 INFO - TEST-INFO | 1652: exit 0 23:46:39 INFO - Browser initialized. 23:46:39 INFO - Running cycle 1/1 for kraken test... 23:46:39 INFO - TEST-INFO | started process 1653 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/hr/hkb07m1j4sq5c29hg511636400000w/T/tmp7xrqSY/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 23:46:51 INFO - PROCESS | 1653 | RSS: Main: 227213312 23:46:51 INFO - PROCESS | 1653 | 23:46:54 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49235/tests/kraken/driver.html?testName=audio-beat-detection) 23:46:54 INFO - PROCESS | 1653 | RSS: Main: 287592448 23:46:54 INFO - PROCESS | 1653 | 23:46:57 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49235/tests/kraken/driver.html?testName=audio-dft) 23:46:58 INFO - PROCESS | 1653 | RSS: Main: 289419264 23:46:58 INFO - PROCESS | 1653 | 23:47:01 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49235/tests/kraken/driver.html?testName=audio-fft) 23:47:02 INFO - PROCESS | 1653 | RSS: Main: 379957248 23:47:02 INFO - PROCESS | 1653 | 23:47:04 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49235/tests/kraken/driver.html?testName=audio-oscillator) 23:47:04 INFO - PROCESS | 1653 | RSS: Main: 285327360 23:47:04 INFO - PROCESS | 1653 | 23:47:06 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49235/tests/kraken/driver.html?testName=imaging-gaussian-blur) 23:47:07 INFO - PROCESS | 1653 | RSS: Main: 287887360 23:47:07 INFO - PROCESS | 1653 | 23:47:10 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49235/tests/kraken/driver.html?testName=imaging-darkroom) 23:47:10 INFO - PROCESS | 1653 | RSS: Main: 329465856 23:47:10 INFO - PROCESS | 1653 | 23:47:13 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49235/tests/kraken/driver.html?testName=imaging-desaturate) 23:47:14 INFO - PROCESS | 1653 | RSS: Main: 330543104 23:47:14 INFO - PROCESS | 1653 | 23:47:17 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49235/tests/kraken/driver.html?testName=json-parse-financial) 23:47:18 INFO - PROCESS | 1653 | RSS: Main: 327622656 23:47:18 INFO - PROCESS | 1653 | 23:47:19 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49235/tests/kraken/driver.html?testName=json-stringify-tinderbox) 23:47:20 INFO - PROCESS | 1653 | RSS: Main: 304357376 23:47:20 INFO - PROCESS | 1653 | 23:47:22 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49235/tests/kraken/driver.html?testName=stanford-crypto-aes) 23:47:22 INFO - PROCESS | 1653 | RSS: Main: 308854784 23:47:22 INFO - PROCESS | 1653 | 23:47:25 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49235/tests/kraken/driver.html?testName=stanford-crypto-ccm) 23:47:25 INFO - PROCESS | 1653 | RSS: Main: 310722560 23:47:25 INFO - PROCESS | 1653 | 23:47:28 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49235/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 23:47:29 INFO - PROCESS | 1653 | RSS: Main: 325640192 23:47:29 INFO - PROCESS | 1653 | 23:47:32 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49235/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 23:47:33 INFO - PROCESS | 1653 | RSS: Main: 319578112 23:47:33 INFO - PROCESS | 1653 | 23:47:34 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49235/tests/kraken/driver.html?testName=ai-astar) 23:47:35 INFO - PROCESS | 1653 | RSS: Main: 363778048 23:47:35 INFO - PROCESS | 1653 | 23:47:35 INFO - PROCESS | 1653 | __start_tp_report 23:47:35 INFO - PROCESS | 1653 | _x_x_mozilla_page_load 23:47:35 INFO - PROCESS | 1653 | _x_x_mozilla_page_load_details 23:47:35 INFO - PROCESS | 1653 | |i|pagename|runs| 23:47:35 INFO - PROCESS | 1653 | |0;ai-astar;111;115;115;114;114;115;114;113;114;114 23:47:35 INFO - PROCESS | 1653 | |1;audio-beat-detection;142;113;102;154;115;109;110;110;130;111 23:47:35 INFO - PROCESS | 1653 | |2;audio-dft;183;184;183;185;186;182;182;182;206;259 23:47:35 INFO - PROCESS | 1653 | |3;audio-fft;80;59;60;85;69;83;67;60;75;58 23:47:35 INFO - PROCESS | 1653 | |4;audio-oscillator;91;68;87;82;96;98;108;69;68;84 23:47:35 INFO - PROCESS | 1653 | |5;imaging-gaussian-blur;89;85;88;85;87;86;88;85;89;84 23:47:35 INFO - PROCESS | 1653 | |6;imaging-darkroom;94;93;94;94;94;104;147;114;94;93 23:47:35 INFO - PROCESS | 1653 | |7;imaging-desaturate;99;131;137;88;84;89;87;86;86;84 23:47:35 INFO - PROCESS | 1653 | |8;json-parse-financial;53;54;54;53;54;59;62;82;82;80 23:47:35 INFO - PROCESS | 1653 | |9;json-stringify-tinderbox;65;76;70;68;76;71;71;69;70;72 23:47:35 INFO - PROCESS | 1653 | |10;stanford-crypto-aes;87;88;85;87;87;89;85;89;90;85 23:47:35 INFO - PROCESS | 1653 | |11;stanford-crypto-ccm;140;136;148;145;146;154;135;136;187;149 23:47:35 INFO - PROCESS | 1653 | |12;stanford-crypto-pbkdf2;180;227;229;167;219;197;171;170;174;172 23:47:35 INFO - PROCESS | 1653 | |13;stanford-crypto-sha256-iterative;66;64;67;67;68;68;67;67;66;65 23:47:35 INFO - PROCESS | 1653 | __end_tp_report 23:47:35 INFO - PROCESS | 1653 | __start_cc_report 23:47:35 INFO - PROCESS | 1653 | _x_x_mozilla_cycle_collect,1962 23:47:35 INFO - PROCESS | 1653 | __end_cc_report 23:47:35 INFO - PROCESS | 1653 | __startTimestamp1482824855153__endTimestamp 23:47:35 INFO - PROCESS | 1653 | 23:47:35 INFO - PROCESS | 1653 | ------- Summary: start ------- 23:47:35 INFO - PROCESS | 1653 | Number of tests: 14 23:47:35 INFO - PROCESS | 1653 | 23:47:35 INFO - PROCESS | 1653 | [#0] ai-astar Cycles:10 Average:113.90 Median:114.00 stddev:1.20 (1.1%) stddev-sans-first:0.67 23:47:35 INFO - PROCESS | 1653 | Values: 111.0 115.0 115.0 114.0 114.0 115.0 114.0 113.0 114.0 114.0 23:47:35 INFO - PROCESS | 1653 | 23:47:35 INFO - PROCESS | 1653 | [#1] audio-beat-detection Cycles:10 Average:119.60 Median:114.00 stddev:16.79 (14.7%) stddev-sans-first:15.74 23:47:35 INFO - PROCESS | 1653 | Values: 142.0 113.0 102.0 154.0 115.0 109.0 110.0 110.0 130.0 111.0 23:47:35 INFO - PROCESS | 1653 | 23:47:35 INFO - PROCESS | 1653 | [#2] audio-dft Cycles:10 Average:193.20 Median:184.50 stddev:24.22 (13.1%) stddev-sans-first:25.41 23:47:35 INFO - PROCESS | 1653 | Values: 183.0 184.0 183.0 185.0 186.0 182.0 182.0 182.0 206.0 259.0 23:47:35 INFO - PROCESS | 1653 | 23:47:35 INFO - PROCESS | 1653 | [#3] audio-fft Cycles:10 Average:69.60 Median:72.00 stddev:10.50 (14.6%) stddev-sans-first:10.44 23:47:35 INFO - PROCESS | 1653 | Values: 80.0 59.0 60.0 85.0 69.0 83.0 67.0 60.0 75.0 58.0 23:47:35 INFO - PROCESS | 1653 | 23:47:35 INFO - PROCESS | 1653 | [#4] audio-oscillator Cycles:10 Average:85.10 Median:85.50 stddev:13.76 (16.1%) stddev-sans-first:14.42 23:47:35 INFO - PROCESS | 1653 | Values: 91.0 68.0 87.0 82.0 96.0 98.0 108.0 69.0 68.0 84.0 23:47:35 INFO - PROCESS | 1653 | 23:47:35 INFO - PROCESS | 1653 | [#5] imaging-gaussian-blur Cycles:10 Average:86.60 Median:87.50 stddev:1.84 (2.1%) stddev-sans-first:1.73 23:47:35 INFO - PROCESS | 1653 | Values: 89.0 85.0 88.0 85.0 87.0 86.0 88.0 85.0 89.0 84.0 23:47:35 INFO - PROCESS | 1653 | 23:47:35 INFO - PROCESS | 1653 | [#6] imaging-darkroom Cycles:10 Average:102.10 Median:94.00 stddev:17.18 (18.3%) stddev-sans-first:17.97 23:47:35 INFO - PROCESS | 1653 | Values: 94.0 93.0 94.0 94.0 94.0 104.0 147.0 114.0 94.0 93.0 23:47:35 INFO - PROCESS | 1653 | 23:47:35 INFO - PROCESS | 1653 | [#7] imaging-desaturate Cycles:10 Average:97.10 Median:86.50 stddev:19.96 (23.1%) stddev-sans-first:21.16 23:47:35 INFO - PROCESS | 1653 | Values: 99.0 131.0 137.0 88.0 84.0 89.0 87.0 86.0 86.0 84.0 23:47:35 INFO - PROCESS | 1653 | 23:47:35 INFO - PROCESS | 1653 | [#8] json-parse-financial Cycles:10 Average:63.30 Median:60.50 stddev:12.78 (21.1%) stddev-sans-first:13.00 23:47:35 INFO - PROCESS | 1653 | Values: 53.0 54.0 54.0 53.0 54.0 59.0 62.0 82.0 82.0 80.0 23:47:35 INFO - PROCESS | 1653 | 23:47:35 INFO - PROCESS | 1653 | [#9] json-stringify-tinderbox Cycles:10 Average:70.80 Median:71.00 stddev:3.36 (4.7%) stddev-sans-first:2.83 23:47:35 INFO - PROCESS | 1653 | Values: 65.0 76.0 70.0 68.0 76.0 71.0 71.0 69.0 70.0 72.0 23:47:35 INFO - PROCESS | 1653 | 23:47:35 INFO - PROCESS | 1653 | [#10] stanford-crypto-aes Cycles:10 Average:87.20 Median:87.50 stddev:1.81 (2.1%) stddev-sans-first:1.92 23:47:35 INFO - PROCESS | 1653 | Values: 87.0 88.0 85.0 87.0 87.0 89.0 85.0 89.0 90.0 85.0 23:47:35 INFO - PROCESS | 1653 | 23:47:35 INFO - PROCESS | 1653 | [#11] stanford-crypto-ccm Cycles:10 Average:147.60 Median:147.00 stddev:15.24 (10.4%) stddev-sans-first:15.91 23:47:35 INFO - PROCESS | 1653 | Values: 140.0 136.0 148.0 145.0 146.0 154.0 135.0 136.0 187.0 149.0 23:47:35 INFO - PROCESS | 1653 | 23:47:35 INFO - PROCESS | 1653 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:190.60 Median:188.50 stddev:25.27 (13.4%) stddev-sans-first:26.51 23:47:35 INFO - PROCESS | 1653 | Values: 180.0 227.0 229.0 167.0 219.0 197.0 171.0 170.0 174.0 172.0 23:47:35 INFO - PROCESS | 1653 | 23:47:35 INFO - PROCESS | 1653 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.50 Median:67.00 stddev:1.27 (1.9%) stddev-sans-first:1.33 23:47:35 INFO - PROCESS | 1653 | Values: 66.0 64.0 67.0 67.0 68.0 68.0 67.0 67.0 66.0 65.0 23:47:35 INFO - PROCESS | 1653 | -------- Summary: end -------- 23:47:35 INFO - PROCESS | 1653 | 23:47:35 INFO - TEST-INFO | 1653: exit 0 23:47:36 INFO - TEST-OK | kraken | took 60031ms 23:47:36 INFO - SUITE-END | took 1180s 23:47:36 INFO - Completed test suite (00:19:40) 23:47:36 INFO - javascript benchmark 23:47:36 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3374.3251349730053, 3385.7303511183304, 3389.4237979543477, 3436.2783815398766, 3437.6218615952257, 4809.446913604932, 5612.943870561293, 5723.313202415716, 5737.827865164047, 5813.50585200258, 5721.885562288751, 5758.827235182951, 5851.7074146292625, 5853.473187413132, 5909.881802363951, 5113.002964421939, 5188.4811518848, 5192.1432963560965, 5221.321228240324, 5259.8422047338645, 5321.840344789663, 5353.785848566053, 5356.866078348034, 5357.4106848246665, 5361.195820626898, 5285.207218917155, 5288.8413347599635, 5330.1205301125465, 5342.412334643186, 5355.2770375999135, 5166.638335316529, 5182.196759502283, 5211.270422140903, 5215.426303106655, 5239.685618862843, 5228.8692782680355, 5232.843014709565, 5249.238860365225, 5259.132243179871, 5297.364316282071, 5734.082546792493, 5736.8852622947725, 5750.079987202028, 5752.165935939307, 5760.395158508338, 6128.754849806002, 6143.095640351506, 6149.96925015372, 6204.751809927598, 6264.655443950581, 6138.633639632459, 6148.723307451175, 6171.413715697002, 6198.845028874269, 6201.224846894138, 5797.536197104221, 5820.3888591698105, 5824.592278540462, 5867.970660146672, 5869.06095024794, 6152.569320147547, 6217.6891155442045, 6232.688365581794, 6243.125962365272, 6243.96878015607, 6053.545984051214, 6100.176476175749, 6142.539309551801, 6151.9692401538605, 6157.353477884758, 6125.41859352575, 6180.6909654517995, 6186.659733714599, 6202.596831205957, 6227.190465239581, 6138.969305153446, 6183.598066125687, 6205.720742566596, 6222.097795819581, 6224.035274532454, 6193.752249909953, 6219.564630475823, 6222.408871157233, 6229.3770622937345, 6235.875282494325, 4857.975710121426, 4876.780544875489, 4894.8042078317185, 4917.385326834145, 4949.307097006422, 4437.977810110993, 4458.08609235106, 4468.3074123510905, 4482.305242687388, 4499.4150760400935, 5804.390538993429, 5815.127730840408, 5817.7963771267805, 5819.5053420459735, 5822.796202132905, 6122.112293717386, 6156.907646385308, 6166.5991710539565, 6185.165002724574, 6190.071489276645, 5737.110747834093, 5761.798337058183, 5786.739596718157, 5832.941670583325, 5865.618734782225, 5340.145576707708, 5421.159720243369, 5432.212329212242, 5442.482964118402, 5465.07093794056, 6296.33888441707, 6297.023961286008, 6305.4009869062365, 6311.621302721759, 6377.5216858735785, 6072.666003369857, 6112.41229444207, 6141.938580614136, 6143.757528502154, 6159.23009623797, 6142.928535732134, 6211.565190436626, 6229.314775374614, 6234.688265586612, 6240.594361366497, 6076.392360764065, 6091.451769340603, 6103.671041328402, 6115.818436018264, 6124.136831511461, 6186.072089186478, 6196.566240363131, 6202.534809889186, 6209.751609935733, 6226.875462490634, 5803.47768700819, 5968.671723055274, 6130.111133885651, 6155.1075094112, 6155.35368786271, 5987.34139244674, 6029.186059881859, 6030.065339872327, 6088.908666370096, 6105.175801266772, 6015.969920150371, 6034.09488576717, 6049.274087109575, 6080.908786368296, 6093.817185484442, 6133.969330153321, 6159.938400615936, 6166.506679465721, 6172.845678858064, 6192.071189321638, 5829.94170058294, 5883.705814709333, 5888.352281249145, 5971.880562388641, 6032.276126864805], "value": 5710.377907051295, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [429.97635130067835, 484.6002048310144, 491.32933545710097, 493.99965070731776, 510.26776575613974, 25882.341178587845, 38166.80916595417, 39937.20125597486, 40072.0, 42096.36855447171, 40759.38860917081, 41164.97087572812, 41606.58393416065, 41644.0, 41698.583014169846, 8399.958000210021, 9293.395929264592, 9516.333856630039, 9520.190783783351, 9531.046895310448, 8499.575021248944, 10343.37939723619, 11385.601503947364, 11404.942975285112, 11545.480453379618, 11350.035247004014, 11351.20541562091, 11371.772564548704, 11414.71463213418, 11453.312801231952, 11253.831192532118, 11322.0, 11326.150538709548, 11349.20555561111, 11357.659270221906, 11129.833052504138, 11186.328820270728, 11191.384473853936, 11217.326960382403, 11223.943880280547, 38339.23321533554, 38621.613783862085, 38711.8064409679, 38789.03027424309, 38878.22243555141, 3455.188030812757, 3517.4020416528997, 3605.13476765577, 3977.443157957888, 4174.874753757392, 28688.0, 30115.0, 30272.394552108835, 30425.847870760503, 30799.69200308013, 3194.552762613236, 3207.566978457925, 3232.9353412931846, 3277.2134687675025, 3306.9007929762147, 458751.0, 463977.0, 464391.0, 466867.0, 470987.6450617759, 59119.40880591225, 59501.32244049028, 59510.70244648749, 59589.40410595925, 59618.0381961809, 60888.0, 60998.695006524686, 62594.0, 62748.68625656843, 62952.0, 15384.461543845911, 15850.44523441674, 15866.0, 15876.841231587767, 15915.204239788196, 10962.725931851768, 11166.385848778233, 11189.328640281608, 12148.392580370946, 12279.324637144864, 16130.0, 16558.751618725728, 16808.57978550546, 16895.0, 16902.32390704358, 7364.742234021784, 7392.371648409838, 7445.218252083453, 7463.290987356192, 7526.661300241502, 798.1300382583013, 1032.3496197395593, 1033.6485594897772, 1065.73177918277, 1084.9909584086874, 25478.872605636854, 28303.048968278214, 28735.281617959718, 29063.9827606037, 29648.555271670943, 1010.3937637417491, 1051.1013083813382, 1066.7279843639822, 1126.4649291586595, 1127.1208457403238, 12869.0, 13536.864631353757, 14728.55814325572, 15280.930334876452, 15521.37914483408, 598791.0, 599938.0, 605561.0, 608552.0, 609700.0, 51377.7431112842, 51503.484965149866, 51508.227376589384, 51630.0, 51644.74177629163, 1331.2544974814134, 1353.3165751295533, 1388.8402833674256, 1393.547097193396, 1396.2041636267434, 27309.0, 28331.575026375034, 28334.149975500768, 28654.85672571624, 28689.0, 44219.55780442283, 44612.33081503845, 44670.77664611656, 44856.551434485234, 44899.32651010303, 565251.0, 569017.0, 570020.0, 570133.0, 570226.148869253, 23415.88292058529, 23501.942412591132, 23788.167414140422, 23986.760132398453, 24047.51904961856, 40088.0, 40238.79880600578, 40941.38587921124, 40961.77114686564, 41428.79285603553, 27642.0, 34301.48547771835, 34811.30377392387, 34888.0, 35540.644593553734, 28389.0, 29483.705162948096, 33857.64569417196, 34178.31643367169, 34915.0, 33710.32579348448, 34343.79796707203, 34449.0, 34583.65416345804, 34661.82669086739, 27862.3034424141, 29283.975060872766, 30362.696373035986, 30621.08136755901, 30638.387232254783, 20295.898520507304, 21938.451538711022, 23003.964821583402, 23232.651510227697, 23845.761542384353], "value": 19288.274577966276, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [343.6888271245198, 364.215116424106, 366.1925454373107, 370.5460810507129, 394.1978074618151, 13554.932225338871, 14533.128012319256, 15555.0, 19619.5095122622, 20315.695264571044, 15994.280257388416, 20605.484862878435, 21138.471538211554, 21302.147914083416, 21407.250746223886, 5489.890202195954, 5780.4797568218855, 5793.420657934215, 5794.71026448678, 5800.4489573490455, 4822.4936381679945, 5202.739863006835, 5771.365149833515, 5802.39074897136, 5818.563607729416, 5740.368559458456, 5749.971250143743, 5750.626209296403, 5753.971230143843, 5783.392743761887, 5426.864328391783, 5571.108622620402, 5637.323521177444, 5654.9151762723595, 5659.207710920475, 4745.525447455261, 4957.454679985199, 4966.453690094087, 4969.826056088055, 4969.925451118236, 20653.070611822503, 20838.853863037526, 24252.63621045668, 24353.63469547958, 24355.512889742284, 2188.616992026389, 2204.1954686539298, 2256.684064231009, 2268.7277526696903, 2390.5218956208655, 14378.568642940729, 15170.772438413322, 15411.460598879097, 15470.226488675633, 15764.605884852856, 1679.9248480972187, 1758.498827834061, 1810.5835657798648, 1818.890866547998, 1825.470613522077, 110092.4402937688, 110403.44798276038, 112297.87702122956, 112803.43598282037, 113962.0, 10085.092341689136, 10232.897671023344, 10332.948335258276, 10564.471776411148, 10766.78466430667, 11149.888501115047, 13273.137246078973, 13277.203367797963, 13278.734425311439, 13298.534551290659, 6947.0, 7911.129775724666, 8172.836543269102, 8197.877031844528, 8311.709090181814, 7925.524468531791, 7968.442209045311, 8213.425060245845, 8420.705275315335, 8436.28291595209, 6293.527985401113, 6399.424051835357, 6406.167198264196, 6457.644829534327, 6530.2816690164045, 1794.3719698105558, 1811.2392795025858, 1814.9001804900856, 1820.9180586873622, 1832.0748022248945, 876.3514998900768, 911.7401540560908, 954.0316578672653, 954.1889394015031, 980.6518685866557, 18141.63716725658, 18660.440186794418, 18772.68591198603, 21600.243991460222, 21782.673259901116, 882.6513527156737, 922.3681777982104, 937.2689302344182, 970.4569734122779, 1042.3641578637025, 10892.509837057352, 11433.885661143282, 11731.941340293244, 12095.966516355595, 12126.939365303117, 503284.0, 547843.0, 547844.0, 553417.0, 562985.0, 88455.0, 108520.0, 128805.0, 129483.35258323836, 135393.0, 976.4824642939111, 1060.1624716474053, 1095.2606990281529, 1096.4024606589269, 1099.2580008494394, 19361.903190483958, 19642.410727678194, 19671.70492442643, 19815.306464274257, 19926.402207933785, 53626.19560706671, 56034.15948760691, 56539.43460565342, 56970.575735606944, 57073.14634268353, 80897.0, 81274.59362703386, 81531.18468815235, 81992.0, 83052.0, 6397.936020639734, 6820.965895170492, 7417.740379086706, 8246.257836794717, 8249.917500824915, 9148.268138548767, 11973.880261197277, 12638.936805316282, 13045.152065115737, 13056.608301750963, 10978.066864316303, 11238.269512481662, 11404.315741055563, 11478.94260528692, 11518.596849110241, 9106.817863642822, 10840.67477975662, 10887.401192934149, 11347.602833901105, 11479.770404592029, 9748.22014238845, 10681.679549613524, 11080.0, 11274.887251127711, 11649.534018639499, 7826.491278066907, 8122.944017277952, 8183.71357002502, 8202.958985205036, 8340.416170868224, 8231.34149268069, 10042.648507302209, 11062.889371106186, 11386.94306528462, 11607.187496875138], "value": 9436.920386771608, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1316.6642506160929, 1524.2378810594698, 1689.2820551265718, 1863.310575087218, 1917.3289348727947, 7903.525788452697, 8328.20943497148, 8365.498070115782, 8371.83256334873, 8405.495670259788, 6620.735170593171, 7392.815179620512, 7602.657880395382, 7619.085709714828, 8168.91831081689, 2268.761780013104, 2298.2645553422913, 2313.8148948084113, 2314.2478694424294, 2317.165820304689, 2305.3775480620225, 2336.9883150584224, 2340.1458467659268, 2348.8120950323932, 2354.5526349993447, 2282.783135602115, 2288.8397812153157, 2311.9422014449688, 2320.2807129789735, 2321.5589038082794, 2266.784655457729, 2291.942701432461, 2292.4612716011725, 2298.9310320690324, 2302.815774738017, 2295.8392912496133, 2318.953620927572, 2325.6744055832196, 2326.639370897514, 2331.976680233193, 8074.475159114698, 8297.004359476901, 8300.501969881767, 8313.667453301861, 8320.70877519284, 4997.725125118117, 5153.072446959546, 5164.845054648366, 5165.612579056548, 5170.948290517122, 3212.887548935776, 3278.098522906196, 3475.9131021724406, 3483.860645574174, 3497.4928635347987, 1023.3041531758402, 1050.6847945616285, 1062.3254233561668, 1065.5364916261374, 1069.4545781651336, 10519.52662130206, 10598.258121931467, 10705.571777128906, 10715.732106697316, 10731.731706707395, 4390.341448782644, 4535.63714902807, 4630.6063984561015, 4634.189016922025, 4646.512116227815, 9173.81652366949, 9176.0, 9188.678396256098, 9193.724188274362, 9202.079792020744, 5113.335266415343, 5115.283860259567, 5122.231665250168, 5124.180131179068, 5129.230615407719, 3827.27281816454, 4169.833206671699, 4618.284165954283, 4695.859124226278, 4732.266498692708, 3737.8691745788765, 3750.774953502799, 3824.7131465140224, 3852.1910398816563, 3861.4361183720075, 1255.9686007849878, 1266.2276011632896, 1267.9175853569488, 1277.757226127033, 1281.0, 825.6614787936917, 834.9081601023883, 845.4673555659895, 890.9198172164537, 909.8589718593629, 4929.334539837076, 5026.79892804291, 5076.238564215398, 5263.499967503081, 5396.406395296514, 427.2992292640073, 431.45851955795274, 433.05593805504304, 435.67977536510637, 893.78995935955, 3255.4140254753897, 3317.834108294576, 3341.9974007797564, 3483.4600636901323, 3495.877644282438, 8360.163983601591, 9540.379875308083, 9752.707418777449, 9887.159591434665, 9896.208303335716, 9850.50747462624, 9858.65494707696, 9858.901410985942, 9870.851937220947, 9877.456739879375, 7535.284148005931, 7572.886406703904, 7663.003809504728, 7800.258975397328, 7816.374690024784, 5149.176131818891, 5173.63784535079, 5174.844754657366, 5260.789568417221, 5285.947140528546, 9102.362834601514, 9202.125798049176, 9272.675456358995, 9274.860877086714, 9312.48781317034, 9084.955230148353, 9160.221381182673, 9173.954130229306, 9332.30007749408, 9387.530623468936, 6125.418085281891, 6318.6840657965995, 6777.220619628806, 6890.0, 7110.146782386147, 7786.922130778619, 7884.921150788648, 7892.289693927574, 7893.36853051766, 7905.32804711606, 6987.6855541499735, 7079.362857342864, 7108.218096009331, 7167.892481612885, 7219.314165154302, 6925.965370173117, 6927.411170050602, 6957.451699633325, 6969.512134150561, 7034.331738484836, 5794.391588883106, 5843.970780146072, 5847.532197424281, 5941.1088336749835, 5955.315138759098, 4441.866743997685, 4485.6860019798305, 4535.138323718483, 4539.455265368177, 4543.863684089482, 4396.296592545177, 4502.707324023928, 4605.7466839322815, 4613.308003799322, 4768.1417344878255], "value": 4243.0498732185915, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [337.78381835625197, 348.66702299304166, 358.40683668528595, 366.5454836003357, 370.951776269085, 5031.396232452102, 5054.84835454936, 5239.240310155025, 5278.208268759688, 5279.41926388097, 4253.808578613962, 4334.501532323784, 5086.677463859395, 5140.254663073862, 5211.123995361667, 3542.7697199682066, 4036.616521430467, 4114.773687447189, 4118.5057793064725, 4151.194230509723, 3405.3529829332347, 3437.226624009603, 3472.8263586820685, 4089.2379011309667, 4123.402106694528, 4031.778252196128, 4080.510338759353, 4100.733452325604, 4101.589841015904, 4126.607972242632, 3857.228554289153, 4031.7580945143386, 4048.1903619276263, 4072.2059198456313, 4079.7348172368847, 2937.4125174965093, 3168.8098714077228, 3239.5626590410466, 3465.896023119285, 3622.329868974222, 4142.46148000758, 4234.195502854448, 4690.5778479936625, 5098.821541246039, 5172.405173405032, 3628.546431696038, 4065.4918135233097, 4502.909941801146, 4514.277715565527, 4537.568930951555, 4476.7090139140855, 4659.767011649404, 4852.902941941176, 5512.972435137839, 5526.944730552683, 3290.8975493209805, 3541.4333706607076, 3649.9635003649887, 3748.8125593720206, 3761.5110035695184, 5376.489233522809, 5512.476314750093, 5636.731735359553, 5767.942320576824, 5774.191613174159, 3785.242951409729, 3801.6958643308467, 3803.4865293185067, 3813.142043040349, 3815.771053736785, 3849.8845034649007, 3850.614938506127, 3872.399778034353, 3876.592957739453, 3890.0, 3637.3452778499595, 4247.341662042327, 4386.881345256939, 4483.910321793611, 4497.953431188801, 4840.322354870321, 5271.973640131851, 5508.807191748269, 5553.555715542747, 5571.303587051619, 1011.8128146292812, 1128.8532490776147, 1212.5695378140808, 1225.601679454181, 1234.6851552854143, 837.6440012994453, 846.327169899931, 851.3104385447683, 856.5267739488071, 863.1411745313453, 4164.583541645811, 4825.662203645711, 4850.442199147073, 4870.1233777920315, 4879.658423910363, 4561.429821272341, 4838.975805120952, 5106.795728170831, 5281.471852814765, 5322.520973112439, 4444.488883778342, 4712.952870471251, 5126.384833819965, 5200.921986170135, 5219.3736751589295, 2452.546278938402, 2485.391079185575, 2802.7057158998014, 2849.3189018478784, 2869.526528122878, 4863.927041094386, 5412.9701361989255, 5426.782928682808, 5593.0, 5604.607677462538, 4749.976250118727, 5201.349831271091, 5210.478952104835, 5540.612157149041, 5854.950767231435, 4780.521947805191, 5847.491505096993, 5875.206847075761, 5885.588008839341, 5916.970415147896, 4559.411650165479, 4564.863054108382, 5417.702026388586, 5419.558580301458, 5673.687947162781, 5280.039970022483, 5605.299337582802, 5608.4111168326735, 5611.831645050655, 5628.635972891314, 5392.919106213331, 5794.507466865195, 5824.883502330014, 5871.853203669943, 5882.617629854046, 3608.8015159165448, 3740.850365985439, 3764.680002199844, 3795.411711184771, 3797.677197438138, 4708.764561771966, 5570.526505246938, 5605.80379686709, 5622.746976385991, 5639.125935480007, 4622.329762184397, 5143.768530416215, 5499.395066542597, 5515.669059856422, 5540.722963851711, 4627.028324051987, 5064.215046667772, 5203.985222881502, 5239.554637855673, 5529.695866727368, 4645.396098507307, 4947.872414861644, 5128.922646412123, 5304.541251155963, 5314.7874085036165, 4711.952880471152, 4965.379327584052, 5401.432849550898, 5414.83755487336, 5512.81474482972, 5173.956287266584, 5403.000444917703, 5414.7292635367285, 5430.0, 5440.782368705208], "value": 3952.0497322492633, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [715.4026387966048, 747.1072068877689, 779.431015358788, 781.9491733037348, 823.4194892600717, 14779.187144707037, 15924.840751592481, 15942.84057159428, 15943.920280398595, 15970.76043859343, 14343.569692909203, 14497.855021449808, 14514.564563063099, 14522.491712790055, 14533.78199327011, 691.9792406227821, 715.6636380900968, 720.4776537010652, 871.1071152069132, 892.5403417240127, 867.0982178534317, 883.2360008592553, 892.5983307511614, 906.3247880329164, 907.1790030022839, 848.6435697007241, 949.0414681172032, 949.981000379992, 952.6046690623383, 952.8189643967659, 687.3229868579446, 867.1501928110487, 939.5631031570318, 942.5664194470552, 943.2124176312788, 559.9300087489064, 677.8779819632464, 695.1102588686489, 702.764573867755, 705.474421555939, 12544.310062946533, 15127.924360378127, 15152.393904243818, 15163.393464261417, 15195.088294702242, 13754.587362379145, 13773.931130344385, 13795.0, 13841.446342146302, 13889.652758681013, 12608.747825043447, 12789.296588687619, 12825.23048617086, 12970.740585188243, 13315.600531984055, 3745.419459983707, 4483.641308695296, 4529.297958816389, 4654.790534425959, 4678.929816052762, 97667.0, 105237.47381263121, 105429.94570054278, 107325.0, 107549.46225268902, 10957.890421095846, 11091.667249982513, 11094.722631934266, 11133.832992505118, 11305.0, 21884.671729924063, 21885.890570547046, 21890.781092189194, 21914.89042554809, 21939.793311368026, 7717.575533345607, 7792.41556883236, 7796.571188584682, 7884.093329267093, 7913.960430197927, 4238.87283381499, 4304.354346847936, 4323.502797178302, 4340.370646256275, 4341.739495630272, 3413.0, 3429.725621950238, 3454.464557993515, 3502.6847583717586, 3516.1385460562324, 642.7107801489256, 644.4779728419995, 645.319188256394, 662.3277373465936, 662.7647185249264, 463.87011636741767, 542.5903442900585, 587.295245705147, 634.3561285295428, 637.5441559285133, 8330.50016998982, 8911.866322005175, 8964.0, 8968.820623587622, 9065.59204835784, 551.3631755322609, 599.8050633544116, 611.6880391000622, 624.0545573456218, 632.2381530256031, 4260.190563792869, 4400.163968845909, 4421.447319085114, 4433.600975912119, 4482.910341793146, 102692.48653756833, 102799.0, 104064.43903341456, 104896.0, 105198.47400762947, 64148.0, 64549.677251613444, 65493.0, 82101.35797284021, 85992.57003714942, 64841.67579162074, 70572.64713676399, 74562.5087498247, 75897.86153207706, 77384.83922741163, 9886.357386770125, 9902.405855648683, 9918.702438926844, 9941.602335906482, 9950.303478756417, 45474.09051818879, 49291.26063108985, 49505.752471238855, 49913.0, 50919.0, 65660.0, 70039.2490187729, 70213.64893175705, 70925.0, 73466.0, 12507.499700011898, 12950.676233094247, 12959.157654752411, 13191.274479903697, 13536.458541658223, 14515.05652132608, 14559.0, 14581.854181458473, 14766.852331476977, 15244.466443674419, 12059.397030148282, 13503.0, 13791.241481718602, 14078.155310681392, 14084.507042253472, 13278.535251266545, 13512.054156208971, 13599.0, 14177.716445671234, 14216.715665686836, 13448.327583620976, 13645.863541364857, 13671.248081355223, 13902.374393152535, 14189.219592922489, 10736.80003996191, 10749.89250107489, 11216.887831121585, 11381.829272561085, 11673.533058677558, 9187.760253620118, 9937.0, 10163.94918025405, 11112.611058612909, 11233.32600044], "value": 7416.034070748773, "unit": "score"}], "name": "dromaeo_css", "value": 7110.797063862804, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 115.0, 115.0, 114.0, 114.0, 115.0, 114.0, 113.0, 114.0, 114.0], "value": 113.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 113.0, 102.0, 154.0, 115.0, 109.0, 110.0, 110.0, 130.0, 111.0], "value": 119.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [183.0, 184.0, 183.0, 185.0, 186.0, 182.0, 182.0, 182.0, 206.0, 259.0], "value": 193.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 59.0, 60.0, 85.0, 69.0, 83.0, 67.0, 60.0, 75.0, 58.0], "value": 69.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 68.0, 87.0, 82.0, 96.0, 98.0, 108.0, 69.0, 68.0, 84.0], "value": 85.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 85.0, 88.0, 85.0, 87.0, 86.0, 88.0, 85.0, 89.0, 84.0], "value": 86.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 93.0, 94.0, 94.0, 94.0, 104.0, 147.0, 114.0, 94.0, 93.0], "value": 102.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [99.0, 131.0, 137.0, 88.0, 84.0, 89.0, 87.0, 86.0, 86.0, 84.0], "value": 97.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 54.0, 54.0, 53.0, 54.0, 59.0, 62.0, 82.0, 82.0, 80.0], "value": 63.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 76.0, 70.0, 68.0, 76.0, 71.0, 71.0, 69.0, 70.0, 72.0], "value": 70.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 88.0, 85.0, 87.0, 87.0, 89.0, 85.0, 89.0, 90.0, 85.0], "value": 87.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 136.0, 148.0, 145.0, 146.0, 154.0, 135.0, 136.0, 187.0, 149.0], "value": 147.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [180.0, 227.0, 229.0, 167.0, 219.0, 197.0, 171.0, 170.0, 174.0, 172.0], "value": 190.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 64.0, 67.0, 67.0, 68.0, 68.0, 67.0, 67.0, 66.0, 65.0], "value": 66.5, "unit": "score"}], "name": "kraken", "value": 1493.1999999999998, "alertThreshold": 2.0}]} 23:47:36 INFO - Return code: 0 23:47:36 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 23:47:36 INFO - # TBPL SUCCESS # 23:47:36 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 23:47:36 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 23:47:36 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 23:47:36 INFO - 2016-12-26 23:47:36.501 screenresolution[1658:10323] starting screenresolution argv=screenresolution get 23:47:36 INFO - 2016-12-26 23:47:36.503 screenresolution[1658:10323] Display 0: 1600x1200x32@60 23:47:36 INFO - 2016-12-26 23:47:36.509 screenresolution[1659:10325] starting screenresolution argv=screenresolution list 23:47:36 INFO - Available Modes on Display 0 23:47:36 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 23:47:36 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 23:47:36 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 23:47:36 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 23:47:36 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 23:47:36 INFO - Intel Iris: 23:47:36 INFO - Chipset Model: Intel Iris 23:47:36 INFO - Type: GPU 23:47:36 INFO - Bus: Built-In 23:47:36 INFO - VRAM (Dynamic, Max): 1536 MB 23:47:36 INFO - Vendor: Intel (0x8086) 23:47:36 INFO - Device ID: 0x0a2e 23:47:36 INFO - Revision ID: 0x0009 23:47:36 INFO - Displays: 23:47:36 INFO - Display: 23:47:36 INFO - Resolution: 1600 x 1200 @ 60 Hz 23:47:36 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 23:47:36 INFO - Display Serial Number: 1600x1200 60 23:47:36 INFO - Main Display: Yes 23:47:36 INFO - Mirror: Off 23:47:36 INFO - Online: Yes 23:47:36 INFO - Rotation: Supported 23:47:36 INFO - Return code: 0 23:47:36 INFO - Running post-action listener: _resource_record_post_action 23:47:36 INFO - [mozharness: 2016-12-27 07:47:36.742187Z] Finished run-tests step (success) 23:47:36 INFO - Running post-run listener: _resource_record_post_run 23:47:36 INFO - Total resource usage - Wall time: 1203s; CPU: 54.0%; Read bytes: 31583232; Write bytes: 553689088; Read time: 195; Write time: 1696 23:47:36 INFO - TinderboxPrint: CPU usage
53.7% 23:47:36 INFO - TinderboxPrint: I/O read bytes / time
31,583,232 / 195 23:47:36 INFO - TinderboxPrint: I/O write bytes / time
553,689,088 / 1,696 23:47:36 INFO - TinderboxPrint: CPU idle
2,227.3 (46.3%) 23:47:36 INFO - TinderboxPrint: CPU system
961.6 (20.0%) 23:47:36 INFO - TinderboxPrint: CPU user
1,624.8 (33.8%) 23:47:36 INFO - TinderboxPrint: Swap in / out
277,278,720 / 0 23:47:36 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 246080000; Write bytes: 238444544; Read time: 17913; Write time: 526 23:47:36 INFO - run-tests - Wall time: 1182s; CPU: 54.0%; Read bytes: 29502464; Write bytes: 299821568; Read time: 174; Write time: 1124 23:47:37 INFO - Running post-run listener: _upload_blobber_files 23:47:37 INFO - Blob upload gear active. 23:47:37 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 23:47:37 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 23:47:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Graphics', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 23:47:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Graphics -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 23:47:37 INFO - (blobuploader) - INFO - Open directory for files ... 23:47:37 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 23:47:37 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:47:37 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:47:38 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 23:47:38 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:47:38 INFO - (blobuploader) - INFO - Done attempting. 23:47:38 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 23:47:38 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:47:38 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:47:39 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 23:47:39 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:47:39 INFO - (blobuploader) - INFO - Done attempting. 23:47:39 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 23:47:39 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:47:39 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:47:40 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 23:47:40 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:47:40 INFO - (blobuploader) - INFO - Done attempting. 23:47:40 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 23:47:40 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:47:40 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:47:42 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 23:47:42 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:47:42 INFO - (blobuploader) - INFO - Done attempting. 23:47:42 INFO - (blobuploader) - INFO - Iteration through files over. 23:47:42 INFO - Return code: 0 23:47:42 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 23:47:42 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 23:47:42 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/a050ec053ece0dd5aa1f37a4b603ed9c64ab716e9af2ca8fd4e64c4344c5d13a266504e3409168d0319a30cb1b2a7295f456f29cdfd1498259ad146645a46e62", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/c721b51c455313a623fb6e28ab68daf2164ecefce7e541723e458ae122c497a2f85eb7c01c38830f5fa5168e59f4463bf646e5196491dc82edf0a79a6f0c8885", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/bd9ba0e34e60cf4045a225fccd4dcd42121a8e2bf56fac684b326f6758e46cd38d334b627f282eaf887361f951e0c333b495439f93d23e0859149a461c2d3fe4", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/36df247f60544dffee0cc63fd2ed0761414c04cd418f3fbdd695dd11d72a05ed04f90c9e50cd37259a44262297aa858c1bca4dad93b48697a0bdd1430b5726ea"} 23:47:42 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 23:47:42 INFO - Writing to file /builds/slave/test/properties/blobber_files 23:47:42 INFO - Contents: 23:47:42 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/a050ec053ece0dd5aa1f37a4b603ed9c64ab716e9af2ca8fd4e64c4344c5d13a266504e3409168d0319a30cb1b2a7295f456f29cdfd1498259ad146645a46e62", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/c721b51c455313a623fb6e28ab68daf2164ecefce7e541723e458ae122c497a2f85eb7c01c38830f5fa5168e59f4463bf646e5196491dc82edf0a79a6f0c8885", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/bd9ba0e34e60cf4045a225fccd4dcd42121a8e2bf56fac684b326f6758e46cd38d334b627f282eaf887361f951e0c333b495439f93d23e0859149a461c2d3fe4", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/36df247f60544dffee0cc63fd2ed0761414c04cd418f3fbdd695dd11d72a05ed04f90c9e50cd37259a44262297aa858c1bca4dad93b48697a0bdd1430b5726ea"} 23:47:42 INFO - Running post-run listener: copy_logs_to_upload_dir 23:47:42 INFO - Copying logs to upload dir... 23:47:42 INFO - mkdir: /builds/slave/test/build/upload/logs 23:47:42 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1266.146756 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 6 secs) (at 2016-12-26 23:47:43.045331) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-26 23:47:43.048923) ========= 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.jtpAArVW4p/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.KkuD0jvWSD/Listeners TMPDIR=/var/folders/hr/hkb07m1j4sq5c29hg511636400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/a050ec053ece0dd5aa1f37a4b603ed9c64ab716e9af2ca8fd4e64c4344c5d13a266504e3409168d0319a30cb1b2a7295f456f29cdfd1498259ad146645a46e62", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/c721b51c455313a623fb6e28ab68daf2164ecefce7e541723e458ae122c497a2f85eb7c01c38830f5fa5168e59f4463bf646e5196491dc82edf0a79a6f0c8885", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/bd9ba0e34e60cf4045a225fccd4dcd42121a8e2bf56fac684b326f6758e46cd38d334b627f282eaf887361f951e0c333b495439f93d23e0859149a461c2d3fe4", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/36df247f60544dffee0cc63fd2ed0761414c04cd418f3fbdd695dd11d72a05ed04f90c9e50cd37259a44262297aa858c1bca4dad93b48697a0bdd1430b5726ea"} build_url:https://queue.taskcluster.net/v1/task/P59eSwjsRM-Um1eSaFQMIg/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011157 build_url: 'https://queue.taskcluster.net/v1/task/P59eSwjsRM-Um1eSaFQMIg/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/a050ec053ece0dd5aa1f37a4b603ed9c64ab716e9af2ca8fd4e64c4344c5d13a266504e3409168d0319a30cb1b2a7295f456f29cdfd1498259ad146645a46e62", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/c721b51c455313a623fb6e28ab68daf2164ecefce7e541723e458ae122c497a2f85eb7c01c38830f5fa5168e59f4463bf646e5196491dc82edf0a79a6f0c8885", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/bd9ba0e34e60cf4045a225fccd4dcd42121a8e2bf56fac684b326f6758e46cd38d334b627f282eaf887361f951e0c333b495439f93d23e0859149a461c2d3fe4", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/36df247f60544dffee0cc63fd2ed0761414c04cd418f3fbdd695dd11d72a05ed04f90c9e50cd37259a44262297aa858c1bca4dad93b48697a0bdd1430b5726ea"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-26 23:47:43.087551) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-26 23:47:43.087907) ========= 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.jtpAArVW4p/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.KkuD0jvWSD/Listeners TMPDIR=/var/folders/hr/hkb07m1j4sq5c29hg511636400000w/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.004807 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-26 23:47:43.137448) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-26 23:47:43.137799) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-26 23:47:43.138189) ========= ========= Total master_lag: 0.20 =========