builder: graphics_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0166 starttime: 1482850598.65 results: success (0) buildid: 20161227061937 builduid: b527b91f0455464da9f82baf68493d95 revision: 9ab4320b60d3b40b7936bc50b6b53c0e6a5d70c7 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-12-27 06:56:38.654357) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-12-27 06:56:38.655083) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-27 06:56:38.655405) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-27 06:56:38.724361) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-27 06:56:38.725156) ========= 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.Y5sy0yW6bu/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.c2kW9j24SS/Listeners TMPDIR=/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/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-27 06:56:38-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 14.9M=0.001s 2016-12-27 06:56:38 (14.9 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.154516 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-27 06:56:38.910615) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-27 06:56:38.910958) ========= 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.Y5sy0yW6bu/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.c2kW9j24SS/Listeners TMPDIR=/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/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.026254 ========= master_lag: 0.05 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-27 06:56:38.983880) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-12-27 06:56:38.984307) ========= bash -c 'python archiver_client.py mozharness --repo projects/graphics --rev 9ab4320b60d3b40b7936bc50b6b53c0e6a5d70c7 --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 9ab4320b60d3b40b7936bc50b6b53c0e6a5d70c7 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.Y5sy0yW6bu/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.c2kW9j24SS/Listeners TMPDIR=/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/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-27 06:56:39,080 truncating revision to first 12 chars 2016-12-27 06:56:39,080 Setting DEBUG logging. 2016-12-27 06:56:39,080 attempt 1/10 2016-12-27 06:56:39,080 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/projects/graphics/9ab4320b60d3?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-12-27 06:56:40,264 unpacking tar archive at: graphics-9ab4320b60d3/testing/mozharness/ program finished with exit code 0 elapsedTime=1.435820 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-12-27 06:56:40.445612) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-27 06:56:40.445962) ========= script_repo_revision: 9ab4320b60d3b40b7936bc50b6b53c0e6a5d70c7 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-27 06:56:40.446429) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-27 06:56:40.446736) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-27 06:56:40.467422) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 5 secs) (at 2016-12-27 06:56:40.467817) ========= /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.Y5sy0yW6bu/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.c2kW9j24SS/Listeners TMPDIR=/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/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 06:56:40 INFO - MultiFileLogger online at 20161227 06:56:40 in /builds/slave/test 06:56:40 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 06:56:40 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 06:56:40 INFO - {'append_to_log': False, 06:56:40 INFO - 'base_work_dir': '/builds/slave/test', 06:56:40 INFO - 'blob_upload_branch': 'Graphics', 06:56:40 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 06:56:40 INFO - 'branch': 'Graphics', 06:56:40 INFO - 'buildbot_json_path': 'buildprops.json', 06:56:40 INFO - 'config_files': ('talos/mac_config.py',), 06:56:40 INFO - 'default_actions': ('clobber', 06:56:40 INFO - 'read-buildbot-config', 06:56:40 INFO - 'download-and-extract', 06:56:40 INFO - 'populate-webroot', 06:56:40 INFO - 'create-virtualenv', 06:56:40 INFO - 'install', 06:56:40 INFO - 'run-tests'), 06:56:40 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 06:56:40 INFO - 'download_minidump_stackwalk': True, 06:56:40 INFO - 'download_symbols': 'ondemand', 06:56:40 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 06:56:40 INFO - 'tooltool.py': '/tools/tooltool.py', 06:56:40 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 06:56:40 INFO - '/tools/misc-python/virtualenv.py')}, 06:56:40 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 06:56:40 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 06:56:40 INFO - 'installer_path': 'installer.exe', 06:56:40 INFO - 'log_level': 'info', 06:56:40 INFO - 'log_name': 'talos', 06:56:40 INFO - 'log_to_console': True, 06:56:40 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 06:56:40 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 06:56:40 INFO - 'opt_config_files': (), 06:56:40 INFO - 'pip_index': False, 06:56:40 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 06:56:40 INFO - 'cmd': ('bash', 06:56:40 INFO - '-c', 06:56:40 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 06:56:40 INFO - 'enabled': True, 06:56:40 INFO - 'halt_on_failure': False, 06:56:40 INFO - 'name': 'check_screen_resolution'},), 06:56:40 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 06:56:40 INFO - 'cmd': ('bash', 06:56:40 INFO - '-c', 06:56:40 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 06:56:40 INFO - 'enabled': True, 06:56:40 INFO - 'halt_on_failure': False, 06:56:40 INFO - 'name': 'check_screen_resolution'},), 06:56:40 INFO - 'run_cmd_checks_enabled': True, 06:56:40 INFO - 'sps_profile': False, 06:56:40 INFO - 'sps_profile_interval': 0, 06:56:40 INFO - 'suite': 'dromaeojs', 06:56:40 INFO - 'system_bits': '32', 06:56:40 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 06:56:40 INFO - 'title': 't-yosemite-r7-0166', 06:56:40 INFO - 'tooltool_cache': '/builds/tooltool_cache', 06:56:40 INFO - 'use_talos_json': True, 06:56:40 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 06:56:40 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 06:56:40 INFO - 'work_dir': 'build'} 06:56:40 INFO - [mozharness: 2016-12-27 14:56:40.676020Z] Running clobber step. 06:56:40 INFO - Running pre-action listener: _resource_record_pre_action 06:56:40 INFO - Running main action method: clobber 06:56:40 INFO - rmtree: /builds/slave/test/build 06:56:40 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 06:56:43 INFO - Running post-action listener: _resource_record_post_action 06:56:43 INFO - [mozharness: 2016-12-27 14:56:43.150786Z] Finished clobber step (success) 06:56:43 INFO - [mozharness: 2016-12-27 14:56:43.150987Z] Running read-buildbot-config step. 06:56:43 INFO - Running pre-action listener: _resource_record_pre_action 06:56:43 INFO - Running main action method: read_buildbot_config 06:56:43 INFO - Using buildbot properties: 06:56:43 INFO - { 06:56:43 INFO - "project": "", 06:56:43 INFO - "product": "firefox", 06:56:43 INFO - "script_repo_revision": "default", 06:56:43 INFO - "builddir": "graphics_yosemite_r7_test-dromaeojs", 06:56:43 INFO - "repository": "", 06:56:43 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 graphics talos dromaeojs", 06:56:43 INFO - "buildid": "20161227061937", 06:56:43 INFO - "pgo_build": "False", 06:56:43 INFO - "basedir": "/builds/slave/test", 06:56:43 INFO - "buildnumber": 12, 06:56:43 INFO - "slavename": "t-yosemite-r7-0166", 06:56:43 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 06:56:43 INFO - "platform": "yosemite_r7", 06:56:43 INFO - "scheduler": "tests-graphics-macosx64-talos", 06:56:43 INFO - "branch": "graphics", 06:56:43 INFO - "repo_path": "projects/graphics", 06:56:43 INFO - "revision": "9ab4320b60d3b40b7936bc50b6b53c0e6a5d70c7", 06:56:43 INFO - "stage_platform": "macosx64", 06:56:43 INFO - "builduid": "b527b91f0455464da9f82baf68493d95", 06:56:43 INFO - "slavebuilddir": "test" 06:56:43 INFO - } 06:56:43 INFO - Found installer url https://queue.taskcluster.net/v1/task/MCwqTyqFQWeJ0jsOr460oA/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg. 06:56:43 INFO - Running post-action listener: _resource_record_post_action 06:56:43 INFO - [mozharness: 2016-12-27 14:56:43.156773Z] Finished read-buildbot-config step (success) 06:56:43 INFO - [mozharness: 2016-12-27 14:56:43.156932Z] Running download-and-extract step. 06:56:43 INFO - Running pre-action listener: _resource_record_pre_action 06:56:43 INFO - Running main action method: download_and_extract 06:56:43 INFO - mkdir: /builds/slave/test/build/tests 06:56:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:56:43 INFO - trying https://queue.taskcluster.net/v1/task/MCwqTyqFQWeJ0jsOr460oA/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json 06:56:43 INFO - Downloading https://queue.taskcluster.net/v1/task/MCwqTyqFQWeJ0jsOr460oA/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 06:56:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/MCwqTyqFQWeJ0jsOr460oA/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 06:56:44 INFO - Downloaded 1199 bytes. 06:56:44 INFO - Reading from file /builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json 06:56:44 INFO - Using the following test package requirements: 06:56:44 INFO - {u'common': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 06:56:44 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 06:56:44 INFO - u'firefox-53.0a1.en-US.mac.cppunittest.tests.zip'], 06:56:44 INFO - u'gtest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 06:56:44 INFO - u'firefox-53.0a1.en-US.mac.gtest.tests.zip'], 06:56:44 INFO - u'jittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 06:56:44 INFO - u'jsshell-mac.zip'], 06:56:44 INFO - u'mochitest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 06:56:44 INFO - u'firefox-53.0a1.en-US.mac.mochitest.tests.zip'], 06:56:44 INFO - u'mozbase': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 06:56:44 INFO - u'reftest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 06:56:44 INFO - u'firefox-53.0a1.en-US.mac.reftest.tests.zip'], 06:56:44 INFO - u'talos': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 06:56:44 INFO - u'firefox-53.0a1.en-US.mac.talos.tests.zip'], 06:56:44 INFO - u'web-platform': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 06:56:44 INFO - u'firefox-53.0a1.en-US.mac.web-platform.tests.zip'], 06:56:44 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 06:56:44 INFO - u'firefox-53.0a1.en-US.mac.xpcshell.tests.zip']} 06:56:44 INFO - Downloading packages: [u'firefox-53.0a1.en-US.mac.common.tests.zip'] for test suite category: common 06:56:44 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/MCwqTyqFQWeJ0jsOr460oA/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 06:56:44 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/MCwqTyqFQWeJ0jsOr460oA/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 06:56:44 INFO - Fetch https://queue.taskcluster.net/v1/task/MCwqTyqFQWeJ0jsOr460oA/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 06:56:44 INFO - Expected file size: 16289454 06:56:44 INFO - Obtained file size: 16289454 06:56:51 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 06:56:51 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/MCwqTyqFQWeJ0jsOr460oA/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 06:56:51 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/MCwqTyqFQWeJ0jsOr460oA/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 06:56:51 INFO - Fetch https://queue.taskcluster.net/v1/task/MCwqTyqFQWeJ0jsOr460oA/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 06:56:52 INFO - Expected file size: 16289454 06:56:52 INFO - Obtained file size: 16289454 06:56:58 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/MCwqTyqFQWeJ0jsOr460oA/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip 06:56:58 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/MCwqTyqFQWeJ0jsOr460oA/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip'}, attempt #1 06:56:58 INFO - Fetch https://queue.taskcluster.net/v1/task/MCwqTyqFQWeJ0jsOr460oA/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip into memory 06:57:00 INFO - Expected file size: 14409776 06:57:00 INFO - Obtained file size: 14409776 06:57:00 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')]} 06:57:00 INFO - trying https://queue.taskcluster.net/v1/task/MCwqTyqFQWeJ0jsOr460oA/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 06:57:00 INFO - Downloading https://queue.taskcluster.net/v1/task/MCwqTyqFQWeJ0jsOr460oA/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg 06:57:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/MCwqTyqFQWeJ0jsOr460oA/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 06:57:02 INFO - Downloaded 69619913 bytes. 06:57:02 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/MCwqTyqFQWeJ0jsOr460oA/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 06:57:02 INFO - mkdir: /builds/slave/test/properties 06:57:02 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 06:57:02 INFO - Writing to file /builds/slave/test/properties/build_url 06:57:02 INFO - Contents: 06:57:02 INFO - build_url:https://queue.taskcluster.net/v1/task/MCwqTyqFQWeJ0jsOr460oA/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 06:57:03 INFO - Running post-action listener: _resource_record_post_action 06:57:03 INFO - Running post-action listener: set_extra_try_arguments 06:57:03 INFO - [mozharness: 2016-12-27 14:57:03.230518Z] Finished download-and-extract step (success) 06:57:03 INFO - [mozharness: 2016-12-27 14:57:03.230637Z] Running populate-webroot step. 06:57:03 INFO - Running pre-action listener: _resource_record_pre_action 06:57:03 INFO - Running main action method: populate_webroot 06:57:03 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 06:57:03 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 06:57:03 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 06:57:03 INFO - '--tppagecycles', 06:57:03 INFO - '7'], 06:57:03 INFO - 'tests': ['tsvgm']}}, 06:57:03 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 06:57:03 INFO - 'tests': ['tresize', 'tcanvasmark']}, 06:57:03 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 06:57:03 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 06:57:03 INFO - 'tests': ['dromaeo_css', 'kraken']}, 06:57:03 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 06:57:03 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 06:57:03 INFO - 'talos_options': ['--disable-e10s'], 06:57:03 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 06:57:03 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 06:57:03 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 06:57:03 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 06:57:03 INFO - 'talos_options': ['--disable-e10s'], 06:57:03 INFO - 'tests': ['damp', 'tps']}, 06:57:03 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 06:57:03 INFO - 'tests': ['damp', 'tps']}, 06:57:03 INFO - 'g3': {'talos_options': ['--disable-e10s'], 06:57:03 INFO - 'tests': ['dromaeo_dom']}, 06:57:03 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 06:57:03 INFO - 'g4': {'talos_options': ['--disable-e10s'], 06:57:03 INFO - 'tests': ['basic_compositor_video']}, 06:57:03 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 06:57:03 INFO - 'other': {'talos_options': ['--disable-e10s'], 06:57:03 INFO - 'tests': ['a11yr', 06:57:03 INFO - 'ts_paint', 06:57:03 INFO - 'tpaint', 06:57:03 INFO - 'sessionrestore', 06:57:03 INFO - 'sessionrestore_no_auto_restore', 06:57:03 INFO - 'tabpaint']}, 06:57:03 INFO - 'other-e10s': {'tests': ['a11yr', 06:57:03 INFO - 'ts_paint', 06:57:03 INFO - 'tpaint', 06:57:03 INFO - 'sessionrestore', 06:57:03 INFO - 'sessionrestore_no_auto_restore', 06:57:03 INFO - 'tabpaint']}, 06:57:03 INFO - 'other-e10s_l64': {'tests': ['a11yr', 06:57:03 INFO - 'ts_paint', 06:57:03 INFO - 'tpaint', 06:57:03 INFO - 'sessionrestore', 06:57:03 INFO - 'sessionrestore_no_auto_restore', 06:57:03 INFO - 'tabpaint']}, 06:57:03 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 06:57:03 INFO - 'ts_paint', 06:57:03 INFO - 'tpaint', 06:57:03 INFO - 'sessionrestore', 06:57:03 INFO - 'sessionrestore_no_auto_restore', 06:57:03 INFO - 'tabpaint']}, 06:57:03 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 06:57:03 INFO - 'tests': ['a11yr', 06:57:03 INFO - 'ts_paint', 06:57:03 INFO - 'tpaint', 06:57:03 INFO - 'sessionrestore', 06:57:03 INFO - 'sessionrestore_no_auto_restore', 06:57:03 INFO - 'tabpaint']}, 06:57:03 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 06:57:03 INFO - 'tests': ['a11yr', 06:57:03 INFO - 'ts_paint', 06:57:03 INFO - 'tpaint', 06:57:03 INFO - 'sessionrestore', 06:57:03 INFO - 'sessionrestore_no_auto_restore', 06:57:03 INFO - 'tabpaint']}, 06:57:03 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 06:57:03 INFO - 'tests': ['tsvgx', 06:57:03 INFO - 'tsvgr_opacity', 06:57:03 INFO - 'tart', 06:57:03 INFO - 'tscrollx', 06:57:03 INFO - 'cart', 06:57:03 INFO - 'tsvg_static']}, 06:57:03 INFO - 'svgr-e10s': {'tests': ['tsvgx', 06:57:03 INFO - 'tsvgr_opacity', 06:57:03 INFO - 'tart', 06:57:03 INFO - 'tscrollx', 06:57:03 INFO - 'cart', 06:57:03 INFO - 'tsvg_static']}, 06:57:03 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 06:57:03 INFO - 'talos_options': ['--disable-e10s'], 06:57:03 INFO - 'tests': ['tp5o']}, 06:57:03 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 06:57:03 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 06:57:03 INFO - 'talos_options': ['--disable-e10s', 06:57:03 INFO - '--xperf_path', 06:57:03 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 06:57:03 INFO - 'tests': ['tp5n']}, 06:57:03 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 06:57:03 INFO - 'talos_options': ['--xperf_path', 06:57:03 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 06:57:03 INFO - 'tests': ['tp5n']}}, 06:57:03 INFO - 'talos.zip': {'path': '', 06:57:03 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 06:57:03 INFO - Running post-action listener: _resource_record_post_action 06:57:03 INFO - [mozharness: 2016-12-27 14:57:03.248733Z] Finished populate-webroot step (success) 06:57:03 INFO - [mozharness: 2016-12-27 14:57:03.248870Z] Running create-virtualenv step. 06:57:03 INFO - Running pre-action listener: _resource_record_pre_action 06:57:03 INFO - Running main action method: create_virtualenv 06:57:03 INFO - Creating virtualenv /builds/slave/test/build/venv 06:57:03 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 06:57:03 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 06:57:03 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 06:57:03 INFO - Using real prefix '/tools/python27' 06:57:03 INFO - New python executable in /builds/slave/test/build/venv/bin/python 06:57:04 INFO - Installing distribute.............................................................................................................................................................................................done. 06:57:06 INFO - Installing pip.................done. 06:57:06 INFO - Return code: 0 06:57:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 06:57:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 06:57:07 INFO - Reading from file tmpfile_stdout 06:57:07 INFO - Output received: 06:57:07 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) 06:57:07 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 06:57:07 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')]} 06:57:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:57:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:57:07 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')]} 06:57:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:57:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:57:07 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 0x108df5b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108df8768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fca63c68c10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108bd3d30>, '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 0x108afadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108bc8be8>, '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.Y5sy0yW6bu/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.c2kW9j24SS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/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 06:57:07 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 06:57:07 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 06:57:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Y5sy0yW6bu/Render', 06:57:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:57:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:57:07 INFO - 'HOME': '/Users/cltbld', 06:57:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:57:07 INFO - 'LOGNAME': 'cltbld', 06:57:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:57:07 INFO - 'MOZ_NO_REMOTE': '1', 06:57:07 INFO - 'NO_EM_RESTART': '1', 06:57:07 INFO - 'PAGER': '/bin/cat', 06:57:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:57:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:57:07 INFO - 'PWD': '/builds/slave/test', 06:57:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:57:07 INFO - 'SHELL': '/bin/bash', 06:57:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.c2kW9j24SS/Listeners', 06:57:07 INFO - 'TMPDIR': '/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/', 06:57:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:57:07 INFO - 'USER': 'cltbld', 06:57:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:57:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:57:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:57:07 INFO - 'XPC_FLAGS': '0x0', 06:57:07 INFO - 'XPC_SERVICE_NAME': '0', 06:57:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:57:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:57:07 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 06:57:07 INFO - Cleaning up... 06:57:07 INFO - Return code: 0 06:57:07 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 06:57:07 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')]} 06:57:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:57:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:57:07 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')]} 06:57:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:57:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:57:07 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 0x108df5b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108df8768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fca63c68c10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108bd3d30>, '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 0x108afadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108bc8be8>, '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.Y5sy0yW6bu/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.c2kW9j24SS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/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 06:57:07 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 06:57:07 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 06:57:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Y5sy0yW6bu/Render', 06:57:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:57:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:57:07 INFO - 'HOME': '/Users/cltbld', 06:57:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:57:07 INFO - 'LOGNAME': 'cltbld', 06:57:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:57:07 INFO - 'MOZ_NO_REMOTE': '1', 06:57:07 INFO - 'NO_EM_RESTART': '1', 06:57:07 INFO - 'PAGER': '/bin/cat', 06:57:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:57:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:57:07 INFO - 'PWD': '/builds/slave/test', 06:57:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:57:07 INFO - 'SHELL': '/bin/bash', 06:57:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.c2kW9j24SS/Listeners', 06:57:07 INFO - 'TMPDIR': '/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/', 06:57:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:57:07 INFO - 'USER': 'cltbld', 06:57:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:57:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:57:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:57:07 INFO - 'XPC_FLAGS': '0x0', 06:57:07 INFO - 'XPC_SERVICE_NAME': '0', 06:57:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:57:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:57:07 INFO - Downloading/unpacking psutil>=3.1.1 06:57:07 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 06:57:07 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 06:57:07 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 06:57:07 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 06:57:07 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 06:57:07 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 06:57:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 06:57:11 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 06:57:11 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 06:57:11 INFO - Installing collected packages: psutil 06:57:11 INFO - Running setup.py install for psutil 06:57:11 INFO - building 'psutil._psutil_osx' extension 06:57:11 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 06:57:12 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 06:57:12 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 06:57:12 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 06:57:12 INFO - building 'psutil._psutil_posix' extension 06:57:12 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 06:57:12 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 06:57:12 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 06:57:12 INFO - ^ 06:57:12 INFO - 1 warning generated. 06:57:12 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 06:57:12 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 06:57:12 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 06:57:12 INFO - Successfully installed psutil 06:57:12 INFO - Cleaning up... 06:57:12 INFO - Return code: 0 06:57:12 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 06:57: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')]} 06:57:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:57:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:57: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')]} 06:57:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:57:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:57: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', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x108df5b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108df8768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fca63c68c10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108bd3d30>, '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 0x108afadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108bc8be8>, '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.Y5sy0yW6bu/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.c2kW9j24SS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/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 06:57: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', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 06:57: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 mozsystemmonitor==0.3 06:57:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Y5sy0yW6bu/Render', 06:57:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:57:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:57:12 INFO - 'HOME': '/Users/cltbld', 06:57:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:57:12 INFO - 'LOGNAME': 'cltbld', 06:57:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:57:12 INFO - 'MOZ_NO_REMOTE': '1', 06:57:12 INFO - 'NO_EM_RESTART': '1', 06:57:12 INFO - 'PAGER': '/bin/cat', 06:57:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:57:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:57:12 INFO - 'PWD': '/builds/slave/test', 06:57:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:57:12 INFO - 'SHELL': '/bin/bash', 06:57:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.c2kW9j24SS/Listeners', 06:57:12 INFO - 'TMPDIR': '/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/', 06:57:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:57:12 INFO - 'USER': 'cltbld', 06:57:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:57:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:57:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:57:12 INFO - 'XPC_FLAGS': '0x0', 06:57:12 INFO - 'XPC_SERVICE_NAME': '0', 06:57:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:57:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:57:13 INFO - Downloading/unpacking mozsystemmonitor==0.3 06:57: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 06:57: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 06:57: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 06:57: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 06:57: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 06:57: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 06:57:15 INFO - Downloading mozsystemmonitor-0.3.tar.gz 06:57:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 06:57:15 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 06:57:15 INFO - Installing collected packages: mozsystemmonitor 06:57:15 INFO - Running setup.py install for mozsystemmonitor 06:57:16 INFO - Successfully installed mozsystemmonitor 06:57:16 INFO - Cleaning up... 06:57:16 INFO - Return code: 0 06:57:16 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 06:57: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')]} 06:57:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:57:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:57: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')]} 06:57:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:57:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:57: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', '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 0x108df5b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108df8768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fca63c68c10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108bd3d30>, '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 0x108afadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108bc8be8>, '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.Y5sy0yW6bu/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.c2kW9j24SS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/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 06:57: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', 'jsonschema==2.5.1'] in /builds/slave/test/build 06:57: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 jsonschema==2.5.1 06:57:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Y5sy0yW6bu/Render', 06:57:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:57:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:57:16 INFO - 'HOME': '/Users/cltbld', 06:57:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:57:16 INFO - 'LOGNAME': 'cltbld', 06:57:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:57:16 INFO - 'MOZ_NO_REMOTE': '1', 06:57:16 INFO - 'NO_EM_RESTART': '1', 06:57:16 INFO - 'PAGER': '/bin/cat', 06:57:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:57:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:57:16 INFO - 'PWD': '/builds/slave/test', 06:57:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:57:16 INFO - 'SHELL': '/bin/bash', 06:57:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.c2kW9j24SS/Listeners', 06:57:16 INFO - 'TMPDIR': '/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/', 06:57:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:57:16 INFO - 'USER': 'cltbld', 06:57:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:57:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:57:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:57:16 INFO - 'XPC_FLAGS': '0x0', 06:57:16 INFO - 'XPC_SERVICE_NAME': '0', 06:57:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:57:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:57:16 INFO - Downloading/unpacking jsonschema==2.5.1 06:57:16 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 06:57:16 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 06:57:16 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 06:57:16 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 06:57:16 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 06:57:16 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 06:57:18 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 06:57:19 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 06:57: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 06:57: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 06:57: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 06:57: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 06:57: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 06:57: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 06:57:19 INFO - Downloading functools32-3.2.3-2.tar.gz 06:57:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 06:57:19 INFO - warning: no files found matching '*.txt' 06:57:19 INFO - no previously-included directories found matching 'build' 06:57:19 INFO - no previously-included directories found matching 'dist' 06:57:19 INFO - no previously-included directories found matching '.git*' 06:57:19 INFO - Installing collected packages: jsonschema, functools32 06:57:19 INFO - Running setup.py install for functools32 06:57:19 INFO - warning: no files found matching '*.txt' 06:57:19 INFO - no previously-included directories found matching 'build' 06:57:19 INFO - no previously-included directories found matching 'dist' 06:57:19 INFO - no previously-included directories found matching '.git*' 06:57:19 INFO - Successfully installed jsonschema functools32 06:57:19 INFO - Cleaning up... 06:57:19 INFO - Return code: 0 06:57:19 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 06:57:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:57:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:57:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:57:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:57:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:57:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:57:19 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 0x108df5b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108df8768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fca63c68c10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108bd3d30>, '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 0x108afadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108bc8be8>, '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.Y5sy0yW6bu/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.c2kW9j24SS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/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 06:57:19 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 06:57:19 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 06:57:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Y5sy0yW6bu/Render', 06:57:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:57:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:57:19 INFO - 'HOME': '/Users/cltbld', 06:57:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:57:19 INFO - 'LOGNAME': 'cltbld', 06:57:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:57:19 INFO - 'MOZ_NO_REMOTE': '1', 06:57:19 INFO - 'NO_EM_RESTART': '1', 06:57:19 INFO - 'PAGER': '/bin/cat', 06:57:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:57:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:57:19 INFO - 'PWD': '/builds/slave/test', 06:57:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:57:19 INFO - 'SHELL': '/bin/bash', 06:57:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.c2kW9j24SS/Listeners', 06:57:19 INFO - 'TMPDIR': '/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/', 06:57:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:57:19 INFO - 'USER': 'cltbld', 06:57:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:57:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:57:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:57:19 INFO - 'XPC_FLAGS': '0x0', 06:57:19 INFO - 'XPC_SERVICE_NAME': '0', 06:57:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:57:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:57:20 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 06:57:20 INFO - Cleaning up... 06:57:20 INFO - Return code: 0 06:57:20 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 06:57:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:57:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:57:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:57:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:57:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:57:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:57:20 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 0x108df5b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108df8768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fca63c68c10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108bd3d30>, '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 0x108afadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108bc8be8>, '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.Y5sy0yW6bu/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.c2kW9j24SS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/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 06:57:20 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 06:57:20 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 06:57:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Y5sy0yW6bu/Render', 06:57:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:57:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:57:20 INFO - 'HOME': '/Users/cltbld', 06:57:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:57:20 INFO - 'LOGNAME': 'cltbld', 06:57:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:57:20 INFO - 'MOZ_NO_REMOTE': '1', 06:57:20 INFO - 'NO_EM_RESTART': '1', 06:57:20 INFO - 'PAGER': '/bin/cat', 06:57:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:57:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:57:20 INFO - 'PWD': '/builds/slave/test', 06:57:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:57:20 INFO - 'SHELL': '/bin/bash', 06:57:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.c2kW9j24SS/Listeners', 06:57:20 INFO - 'TMPDIR': '/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/', 06:57:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:57:20 INFO - 'USER': 'cltbld', 06:57:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:57:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:57:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:57:20 INFO - 'XPC_FLAGS': '0x0', 06:57:20 INFO - 'XPC_SERVICE_NAME': '0', 06:57:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:57:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:57:20 INFO - Downloading/unpacking blobuploader==1.2.4 06:57: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 06:57: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 06:57: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 06:57: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 06:57: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 06:57: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 06:57:23 INFO - Downloading blobuploader-1.2.4.tar.gz 06:57:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 06:57:23 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 06:57:23 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 06:57:23 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 06:57:23 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 06:57:23 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 06:57:23 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 06:57:23 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 06:57:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 06:57:23 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 06:57:23 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 06:57:23 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 06:57:23 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 06:57:23 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 06:57:23 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 06:57:23 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 06:57:24 INFO - Downloading docopt-0.6.1.tar.gz 06:57:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 06:57:24 INFO - Installing collected packages: blobuploader, requests, docopt 06:57:24 INFO - Running setup.py install for blobuploader 06:57:24 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 06:57:24 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 06:57:24 INFO - Running setup.py install for requests 06:57:25 INFO - Running setup.py install for docopt 06:57:25 INFO - Successfully installed blobuploader requests docopt 06:57:25 INFO - Cleaning up... 06:57:25 INFO - Return code: 0 06:57:25 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:57:25 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')]} 06:57:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:57:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:57:25 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')]} 06:57:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:57:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:57:25 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 0x108df5b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108df8768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fca63c68c10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108bd3d30>, '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 0x108afadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108bc8be8>, '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.Y5sy0yW6bu/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.c2kW9j24SS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/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 06:57:25 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 06:57:25 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 06:57:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Y5sy0yW6bu/Render', 06:57:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:57:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:57:25 INFO - 'HOME': '/Users/cltbld', 06:57:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:57:25 INFO - 'LOGNAME': 'cltbld', 06:57:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:57:25 INFO - 'MOZ_NO_REMOTE': '1', 06:57:25 INFO - 'NO_EM_RESTART': '1', 06:57:25 INFO - 'PAGER': '/bin/cat', 06:57:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:57:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:57:25 INFO - 'PWD': '/builds/slave/test', 06:57:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:57:25 INFO - 'SHELL': '/bin/bash', 06:57:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.c2kW9j24SS/Listeners', 06:57:25 INFO - 'TMPDIR': '/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/', 06:57:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:57:25 INFO - 'USER': 'cltbld', 06:57:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:57:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:57:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:57:25 INFO - 'XPC_FLAGS': '0x0', 06:57:25 INFO - 'XPC_SERVICE_NAME': '0', 06:57:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:57:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:57:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 06:57:25 INFO - Running setup.py (path:/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/pip-8zwVYS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 06:57:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 06:57:25 INFO - Running setup.py (path:/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/pip-Rc7AxT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 06:57:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 06:57:25 INFO - Running setup.py (path:/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/pip-8ee8pd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 06:57:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 06:57:25 INFO - Running setup.py (path:/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/pip-ZzDB0e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 06:57:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 06:57:26 INFO - Running setup.py (path:/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/pip-98yBbB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 06:57:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 06:57:26 INFO - Running setup.py (path:/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/pip-ROvDiG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 06:57:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 06:57:26 INFO - Running setup.py (path:/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/pip-YEhdvk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 06:57:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 06:57:26 INFO - Running setup.py (path:/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/pip-glrXIG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 06:57:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 06:57:26 INFO - Running setup.py (path:/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/pip-fWl8xg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 06:57:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 06:57:26 INFO - Running setup.py (path:/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/pip-iuXZbN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 06:57:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 06:57:26 INFO - Running setup.py (path:/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/pip-sv3O_g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 06:57:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 06:57:26 INFO - Running setup.py (path:/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/pip-TEiD8G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 06:57:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 06:57:26 INFO - Running setup.py (path:/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/pip-bPhTH2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 06:57:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 06:57:26 INFO - Running setup.py (path:/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/pip-Xcy0CJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 06:57:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 06:57:27 INFO - Running setup.py (path:/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/pip-PR5RbT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 06:57:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 06:57:27 INFO - Running setup.py (path:/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/pip-b884E8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 06:57:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 06:57:27 INFO - Running setup.py (path:/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/pip-9S3oED-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 06:57:27 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 06:57:27 INFO - Running setup.py install for manifestparser 06:57:27 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 06:57:27 INFO - Running setup.py install for mozcrash 06:57:27 INFO - Running setup.py install for mozdebug 06:57:27 INFO - Running setup.py install for mozdevice 06:57:27 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 06:57:27 INFO - Installing dm script to /builds/slave/test/build/venv/bin 06:57:27 INFO - Running setup.py install for mozfile 06:57:28 INFO - Running setup.py install for mozhttpd 06:57:28 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 06:57:28 INFO - Running setup.py install for mozinfo 06:57:28 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 06:57:28 INFO - Running setup.py install for mozInstall 06:57:28 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 06:57:28 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 06:57:28 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 06:57:28 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 06:57:28 INFO - Running setup.py install for mozleak 06:57:28 INFO - Running setup.py install for mozlog 06:57:28 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 06:57:28 INFO - Running setup.py install for moznetwork 06:57:28 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 06:57:28 INFO - Running setup.py install for mozprocess 06:57:29 INFO - Running setup.py install for mozprofile 06:57:29 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 06:57:29 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 06:57:29 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 06:57:29 INFO - Running setup.py install for mozrunner 06:57:29 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 06:57:29 INFO - Running setup.py install for mozscreenshot 06:57:29 INFO - Running setup.py install for moztest 06:57:29 INFO - Running setup.py install for mozversion 06:57:29 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 06:57:29 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 06:57:29 INFO - Cleaning up... 06:57:29 INFO - Return code: 0 06:57:29 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:57:29 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')]} 06:57:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:57:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:57:29 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')]} 06:57:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:57:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:57:29 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 0x108df5b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108df8768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fca63c68c10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108bd3d30>, '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 0x108afadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108bc8be8>, '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.Y5sy0yW6bu/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.c2kW9j24SS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/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 06:57:29 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 06:57:29 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 06:57:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Y5sy0yW6bu/Render', 06:57:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:57:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:57:29 INFO - 'HOME': '/Users/cltbld', 06:57:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:57:29 INFO - 'LOGNAME': 'cltbld', 06:57:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:57:29 INFO - 'MOZ_NO_REMOTE': '1', 06:57:29 INFO - 'NO_EM_RESTART': '1', 06:57:29 INFO - 'PAGER': '/bin/cat', 06:57:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:57:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:57:29 INFO - 'PWD': '/builds/slave/test', 06:57:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:57:29 INFO - 'SHELL': '/bin/bash', 06:57:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.c2kW9j24SS/Listeners', 06:57:29 INFO - 'TMPDIR': '/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/', 06:57:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:57:29 INFO - 'USER': 'cltbld', 06:57:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:57:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:57:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:57:29 INFO - 'XPC_FLAGS': '0x0', 06:57:29 INFO - 'XPC_SERVICE_NAME': '0', 06:57:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:57:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:57:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 06:57:30 INFO - Running setup.py (path:/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/pip-oKH7cm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 06:57:30 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)) 06:57:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 06:57:30 INFO - Running setup.py (path:/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/pip-_TVyRJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 06:57:30 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)) 06:57:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 06:57:30 INFO - Running setup.py (path:/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/pip-djnmvP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 06:57:30 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)) 06:57:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 06:57:30 INFO - Running setup.py (path:/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/pip-oq2UHx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 06:57:30 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)) 06:57:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 06:57:30 INFO - Running setup.py (path:/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/pip-qDcMAo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 06:57:30 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)) 06:57:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 06:57:30 INFO - Running setup.py (path:/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/pip-sU7pTx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 06:57:30 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)) 06:57:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 06:57:30 INFO - Running setup.py (path:/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/pip-OaVu67-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 06:57:30 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)) 06:57:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 06:57:30 INFO - Running setup.py (path:/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/pip-FTC4cv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 06:57:31 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)) 06:57:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 06:57:31 INFO - Running setup.py (path:/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/pip-nZP8ZM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 06:57:31 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)) 06:57:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 06:57:31 INFO - Running setup.py (path:/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/pip-HmnvNR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 06:57:31 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)) 06:57:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 06:57:31 INFO - Running setup.py (path:/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/pip-0uY3Cp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 06:57:31 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)) 06:57:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 06:57:31 INFO - Running setup.py (path:/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/pip-EKAhle-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 06:57:31 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)) 06:57:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 06:57:31 INFO - Running setup.py (path:/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/pip-BvvR8A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 06:57:31 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)) 06:57:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 06:57:31 INFO - Running setup.py (path:/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/pip-ZMYGMJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 06:57:31 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)) 06:57:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 06:57:31 INFO - Running setup.py (path:/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/pip-IuEJ0X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 06:57:31 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)) 06:57:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 06:57:31 INFO - Running setup.py (path:/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/pip-cV3csA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 06:57:31 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)) 06:57:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 06:57:31 INFO - Running setup.py (path:/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/pip-9Wbolf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 06:57:31 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)) 06:57:31 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)) 06:57:31 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)) 06:57:31 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)) 06:57:31 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)) 06:57:31 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)) 06:57:31 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 06:57:31 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 06:57:31 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 06:57:31 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 06:57:31 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 06:57:31 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 06:57:31 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 06:57:34 INFO - Downloading blessings-1.6.tar.gz 06:57:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 06:57:34 INFO - Installing collected packages: blessings 06:57:34 INFO - Running setup.py install for blessings 06:57:34 INFO - Successfully installed blessings 06:57:34 INFO - Cleaning up... 06:57:34 INFO - Return code: 0 06:57:34 INFO - Done creating virtualenv /builds/slave/test/build/venv. 06:57:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 06:57:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 06:57:35 INFO - Reading from file tmpfile_stdout 06:57:35 INFO - Current package versions: 06:57:35 INFO - blessings == 1.6 06:57:35 INFO - blobuploader == 1.2.4 06:57:35 INFO - docopt == 0.6.1 06:57:35 INFO - functools32 == 3.2.3-2 06:57:35 INFO - jsonschema == 2.5.1 06:57:35 INFO - manifestparser == 1.1 06:57:35 INFO - mozInstall == 1.12 06:57:35 INFO - mozcrash == 1.0 06:57:35 INFO - mozdebug == 0.1 06:57:35 INFO - mozdevice == 0.49 06:57:35 INFO - mozfile == 1.2 06:57:35 INFO - mozhttpd == 0.7 06:57:35 INFO - mozinfo == 0.9 06:57:35 INFO - mozleak == 0.1 06:57:35 INFO - mozlog == 3.4 06:57:35 INFO - moznetwork == 0.27 06:57:35 INFO - mozprocess == 0.23 06:57:35 INFO - mozprofile == 0.28 06:57:35 INFO - mozrunner == 6.13 06:57:35 INFO - mozscreenshot == 0.1 06:57:35 INFO - mozsystemmonitor == 0.3 06:57:35 INFO - moztest == 0.7 06:57:35 INFO - mozversion == 1.4 06:57:35 INFO - psutil == 3.1.1 06:57:35 INFO - requests == 1.2.3 06:57:35 INFO - wsgiref == 0.1.2 06:57:35 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:57:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:57:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:57:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:57:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:57:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:57:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:57:35 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 0x108df5b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108df8768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fca63c68c10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108bd3d30>, '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 0x108afadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108bc8be8>, '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.Y5sy0yW6bu/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.c2kW9j24SS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/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 06:57:35 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 06:57:35 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 06:57:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Y5sy0yW6bu/Render', 06:57:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:57:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:57:35 INFO - 'HOME': '/Users/cltbld', 06:57:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:57:35 INFO - 'LOGNAME': 'cltbld', 06:57:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:57:35 INFO - 'MOZ_NO_REMOTE': '1', 06:57:35 INFO - 'NO_EM_RESTART': '1', 06:57:35 INFO - 'PAGER': '/bin/cat', 06:57:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:57:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:57:35 INFO - 'PWD': '/builds/slave/test', 06:57:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:57:35 INFO - 'SHELL': '/bin/bash', 06:57:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.c2kW9j24SS/Listeners', 06:57:35 INFO - 'TMPDIR': '/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/', 06:57:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:57:35 INFO - 'USER': 'cltbld', 06:57:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:57:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:57:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:57:35 INFO - 'XPC_FLAGS': '0x0', 06:57:35 INFO - 'XPC_SERVICE_NAME': '0', 06:57:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:57:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:57:35 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)) 06:57:35 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)) 06:57:35 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)) 06:57:35 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)) 06:57:35 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)) 06:57:35 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)) 06:57:35 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)) 06:57:35 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)) 06:57:35 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)) 06:57:35 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)) 06:57:35 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)) 06:57:35 INFO - Cleaning up... 06:57:35 INFO - Return code: 0 06:57:35 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 06:57:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:57:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:57:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:57:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:57:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:57:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:57:35 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x108df5b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108df8768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fca63c68c10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108bd3d30>, '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 0x108afadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108bc8be8>, '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.Y5sy0yW6bu/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.c2kW9j24SS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/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 06:57:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 06:57:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 06:57:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Y5sy0yW6bu/Render', 06:57:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:57:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:57:35 INFO - 'HOME': '/Users/cltbld', 06:57:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:57:35 INFO - 'LOGNAME': 'cltbld', 06:57:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:57:35 INFO - 'MOZ_NO_REMOTE': '1', 06:57:35 INFO - 'NO_EM_RESTART': '1', 06:57:35 INFO - 'PAGER': '/bin/cat', 06:57:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:57:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:57:35 INFO - 'PWD': '/builds/slave/test', 06:57:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:57:35 INFO - 'SHELL': '/bin/bash', 06:57:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.c2kW9j24SS/Listeners', 06:57:35 INFO - 'TMPDIR': '/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/', 06:57:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:57:35 INFO - 'USER': 'cltbld', 06:57:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:57:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:57:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:57:35 INFO - 'XPC_FLAGS': '0x0', 06:57:35 INFO - 'XPC_SERVICE_NAME': '0', 06:57:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:57:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:57:35 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 06:57:35 INFO - Cleaning up... 06:57:35 INFO - Return code: 0 06:57:35 INFO - Running post-action listener: _resource_record_post_action 06:57:35 INFO - Running post-action listener: _start_resource_monitoring 06:57:35 INFO - Starting resource monitoring. 06:57:35 INFO - [mozharness: 2016-12-27 14:57:35.818885Z] Finished create-virtualenv step (success) 06:57:35 INFO - [mozharness: 2016-12-27 14:57:35.819858Z] Running install step. 06:57:35 INFO - Running pre-action listener: _resource_record_pre_action 06:57:35 INFO - Running main action method: install 06:57:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 06:57:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 06:57:36 INFO - Reading from file tmpfile_stdout 06:57:36 INFO - Detecting whether we're running mozinstall >=1.0... 06:57:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 06:57:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 06:57:36 INFO - Reading from file tmpfile_stdout 06:57:36 INFO - Output received: 06:57:36 INFO - Usage: mozinstall [options] installer 06:57:36 INFO - Options: 06:57:36 INFO - -h, --help show this help message and exit 06:57:36 INFO - -d DEST, --destination=DEST 06:57:36 INFO - Directory to install application into. [default: 06:57:36 INFO - "/builds/slave/test"] 06:57:36 INFO - --app=APP Application being installed. [default: firefox] 06:57:36 INFO - mkdir: /builds/slave/test/build/application 06:57:36 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'] 06:57:36 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 06:57:57 INFO - Reading from file tmpfile_stdout 06:57:57 INFO - Output received: 06:57:57 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 06:57:57 INFO - Running post-action listener: _resource_record_post_action 06:57:58 INFO - [mozharness: 2016-12-27 14:57:58.000142Z] Finished install step (success) 06:57:58 INFO - [mozharness: 2016-12-27 14:57:58.000298Z] Running run-tests step. 06:57:58 INFO - Running pre-action listener: _resource_record_pre_action 06:57:58 INFO - Running main action method: run_tests 06:57:58 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 06:57:58 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 06:57:58 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 06:57:58 INFO - 2016-12-27 06:57:58.014 screenresolution[1625:8165] starting screenresolution argv=screenresolution get 06:57:58 INFO - 2016-12-27 06:57:58.017 screenresolution[1625:8165] Display 0: 1600x1200x32@60 06:57:58 INFO - 2016-12-27 06:57:58.023 screenresolution[1626:8168] starting screenresolution argv=screenresolution list 06:57:58 INFO - Available Modes on Display 0 06:57:58 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 06:57:58 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 06:57:58 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 06:57:58 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 06:57:58 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 06:57:58 INFO - Intel Iris: 06:57:58 INFO - Chipset Model: Intel Iris 06:57:58 INFO - Type: GPU 06:57:58 INFO - Bus: Built-In 06:57:58 INFO - VRAM (Dynamic, Max): 1536 MB 06:57:58 INFO - Vendor: Intel (0x8086) 06:57:58 INFO - Device ID: 0x0a2e 06:57:58 INFO - Revision ID: 0x0009 06:57:58 INFO - Displays: 06:57:58 INFO - Display: 06:57:58 INFO - Resolution: 1600 x 1200 @ 60 Hz 06:57:58 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 06:57:58 INFO - Display Serial Number: 1600x1200 60 06:57:58 INFO - Main Display: Yes 06:57:58 INFO - Mirror: Off 06:57:58 INFO - Online: Yes 06:57:58 INFO - Rotation: Supported 06:57:58 INFO - Return code: 0 06:57:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 06:57:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 06:57:58 INFO - Python 2.7.3 06:57:58 INFO - Return code: 0 06:57:58 INFO - grabbing minidump binary from tooltool 06:57:58 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')]} 06:57:58 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 0x108bd3d30>, '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 0x108afadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108bc8be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 06:57:58 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 06:57:58 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 06:57:58 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 06:57:58 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 06:57:58 INFO - Return code: 0 06:57:58 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 06:57:58 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 06:57:58 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 06:57:58 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 06:57:58 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 06:57:58 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/MCwqTyqFQWeJ0jsOr460oA/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0166', '--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 06:57:58 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/MCwqTyqFQWeJ0jsOr460oA/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0166 --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 06:57:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Y5sy0yW6bu/Render', 06:57:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:57:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:57:58 INFO - 'HOME': '/Users/cltbld', 06:57:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:57:58 INFO - 'LOGNAME': 'cltbld', 06:57:58 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 06:57:58 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 06:57:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:57:58 INFO - 'MOZ_NO_REMOTE': '1', 06:57:58 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 06:57:58 INFO - 'NO_EM_RESTART': '1', 06:57:58 INFO - 'PAGER': '/bin/cat', 06:57:58 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:57:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:57:58 INFO - 'PWD': '/builds/slave/test', 06:57:58 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 06:57:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:57:58 INFO - 'SHELL': '/bin/bash', 06:57:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.c2kW9j24SS/Listeners', 06:57:58 INFO - 'TMPDIR': '/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/', 06:57:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:57:58 INFO - 'USER': 'cltbld', 06:57:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:57:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:57:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:57:58 INFO - 'XPC_FLAGS': '0x0', 06:57:58 INFO - 'XPC_SERVICE_NAME': '0', 06:57:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:57:58 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/MCwqTyqFQWeJ0jsOr460oA/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0166', '--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 06:57:58 INFO - mozversion application_buildid: 20161227061937 06:57:58 INFO - mozversion application_changeset: 9ab4320b60d3b40b7936bc50b6b53c0e6a5d70c7 06:57:58 INFO - mozversion application_display_name: Nightly 06:57:58 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 06:57:58 INFO - mozversion application_name: Firefox 06:57:58 INFO - mozversion application_remotingname: firefox 06:57:58 INFO - mozversion application_repository: https://hg.mozilla.org/projects/graphics 06:57:58 INFO - mozversion application_vendor: Mozilla 06:57:58 INFO - mozversion application_version: 53.0a1 06:57:58 INFO - mozversion platform_buildid: 20161227061937 06:57:58 INFO - mozversion platform_changeset: 9ab4320b60d3b40b7936bc50b6b53c0e6a5d70c7 06:57:58 INFO - mozversion platform_repository: https://hg.mozilla.org/projects/graphics 06:57:58 INFO - mozversion platform_version: 53.0a1 06:57:58 INFO - using testdate: 1482850678 06:57:58 INFO - actual date: 1482850678 06:57:58 INFO - starting webserver on 'localhost:49241' 06:57:58 INFO - SUITE-START | Running 2 tests 06:57:58 INFO - TEST-START | dromaeo_css 06:57:58 INFO - operating with platform_type : mac_ 06:57:58 INFO - Initialising browser for dromaeo_css test... 06:57:58 INFO - TEST-INFO | started process 1636 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/tmpf3Anqs/profile http://localhost:49241/getInfo.html) 06:57:59 INFO - PROCESS | 1636 | 1482850679185 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 06:58:00 INFO - PROCESS | 1636 | JavaScript error: chrome://global/content/browser-content.js, line 1772: ReferenceError: event is not defined 06:58:00 INFO - PROCESS | 1636 | JavaScript error: chrome://global/content/browser-content.js, line 1772: ReferenceError: event is not defined 06:58:00 INFO - PROCESS | 1636 | JavaScript error: chrome://global/content/browser-content.js, line 1772: ReferenceError: event is not defined 06:58:01 INFO - PROCESS | 1636 | __metrics Screen width/height:1600/1200 06:58:01 INFO - PROCESS | 1636 | colorDepth:24 06:58:01 INFO - PROCESS | 1636 | Browser inner width/height: 1024/689 06:58:01 INFO - PROCESS | 1636 | __metrics 06:58:01 INFO - PROCESS | 1636 | 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" 06:58:01 INFO - PROCESS | 1636 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 06:58:01 INFO - TEST-INFO | 1636: exit 0 06:58:01 INFO - Browser initialized. 06:58:01 INFO - Running cycle 1/1 for dromaeo_css test... 06:58:01 INFO - TEST-INFO | started process 1639 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/tmpf3Anqs/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 06:58:13 INFO - PROCESS | 1639 | RSS: Main: 235667456 06:58:13 INFO - PROCESS | 1639 | 06:59:04 INFO - PROCESS | 1639 | 1482850744904 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] 07:01:16 INFO - PROCESS | 1639 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49241/tests/dromaeo/cssquery-ext.html) 07:01:20 INFO - PROCESS | 1639 | RSS: Main: 566059008 07:01:20 INFO - PROCESS | 1639 | 07:03:02 INFO - PROCESS | 1639 | ************************* 07:03:02 INFO - PROCESS | 1639 | A coding exception was thrown and uncaught in a Task. 07:03:02 INFO - PROCESS | 1639 | 07:03:02 INFO - PROCESS | 1639 | Full message: ReferenceError: fetch is not defined 07:03:02 INFO - PROCESS | 1639 | Full stack: apiCall@resource://shield-recipe-client/lib/NormandyApi.jsm:37:5 07:03:02 INFO - PROCESS | 1639 | get@resource://shield-recipe-client/lib/NormandyApi.jsm:44:12 07:03:02 INFO - PROCESS | 1639 | this.NormandyApi.fetchRecipes<@resource://shield-recipe-client/lib/NormandyApi.jsm:52:34 07:03:02 INFO - PROCESS | 1639 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 07:03:02 INFO - PROCESS | 1639 | TaskImpl@resource://gre/modules/Task.jsm:277:3 07:03:02 INFO - PROCESS | 1639 | asyncFunction@resource://gre/modules/Task.jsm:252:14 07:03:02 INFO - PROCESS | 1639 | this.RecipeRunner.start<@resource://shield-recipe-client/lib/RecipeRunner.jsm:64:23 07:03:02 INFO - PROCESS | 1639 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 07:03:02 INFO - PROCESS | 1639 | TaskImpl@resource://gre/modules/Task.jsm:277:3 07:03:02 INFO - PROCESS | 1639 | asyncFunction@resource://gre/modules/Task.jsm:252:14 07:03:02 INFO - PROCESS | 1639 | setTimeout_timer@resource://gre/modules/Timer.jsm:30:5 07:03:02 INFO - PROCESS | 1639 | 07:03:02 INFO - PROCESS | 1639 | ************************* 07:04:23 INFO - PROCESS | 1639 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-ext.html (next: http://localhost:49241/tests/dromaeo/cssquery-jquery.html) 07:04:24 INFO - PROCESS | 1639 | RSS: Main: 364343296 07:04:24 INFO - PROCESS | 1639 | 07:07:26 INFO - PROCESS | 1639 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49241/tests/dromaeo/cssquery-mootools.html) 07:07:27 INFO - PROCESS | 1639 | RSS: Main: 349622272 07:07:27 INFO - PROCESS | 1639 | 07:10:29 INFO - PROCESS | 1639 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49241/tests/dromaeo/cssquery-prototype.html) 07:10:30 INFO - PROCESS | 1639 | RSS: Main: 346693632 07:10:30 INFO - PROCESS | 1639 | 07:13:33 INFO - PROCESS | 1639 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49241/tests/dromaeo/cssquery-yui.html) 07:13:34 INFO - PROCESS | 1639 | RSS: Main: 342122496 07:13:34 INFO - PROCESS | 1639 | 07:16:37 INFO - PROCESS | 1639 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-yui.html (next: http://localhost:49241/tests/dromaeo/cssquery-dojo.html) 07:16:38 INFO - PROCESS | 1639 | RSS: Main: 394747904 07:16:38 INFO - PROCESS | 1639 | 07:16:38 INFO - PROCESS | 1639 | __start_tp_report 07:16:38 INFO - PROCESS | 1639 | _x_x_mozilla_page_load 07:16:38 INFO - PROCESS | 1639 | _x_x_mozilla_page_load_details 07:16:38 INFO - PROCESS | 1639 | |i|pagename|runs| 07:16:38 INFO - PROCESS | 1639 | |0;dojo.html;3278.65574114718;3357.4628059510483;3365.3437579671972;3380.5267262583234;3412.0787387405403;5601.691906945116;5618.831435056944;5677.716114194294;5700.714964251786;5714.599978001536;5628.577856660757;5675.886482270352;5703.087505999041;5708.686022268784;5712.828615141542;5104.6937183768905;5124;5175.2754614353835;5180.067587834188;5217.139172036609;5215.843524694265;5249.26510288558;5257.106291930381;5275.815346462875;5278.604104692145;5114.897702045938;5211.140161873324;5232.502912223333;5242.056429842627;5251.107311756991;5089.923651145235;5146.408163061221;5148.459411761749;5167.638265321429;5218.321618189649;5110.207917772751;5156.458571849939;5170.534651881311;5200.661956972823;5200.76596553156;5626.662400255997;5631.436856314377;5642.125470552071;5644.887102257973;5708.372079071299;6038.667873266969;6063.18147050147;6084.148219249309;6192.535559833031;6225.968870155665;6156.6921653917125;6187.041008643622;6226.159468471789;6296.842578935518;6305.0857625644485;5761.769529218868;5769.076947688433;5770.682612456271;5785.0454674979;5806.303243610751;6126.632402055891;6220.844478888064;6273.215848018997;6277.1525844011385;6299.559030867887;6047.546434017467;6120.265568131853;6121.051237058263;6128.448439640454;6129.417705317987;6169;6177.62934223948;6189.81430557084;6193.256809182927;6198.690065496708;6128.055925366366;6144.78493252734;6197.597156184834;6208.50331973441;6221.595596286227;6239.469645080219;6243.43809057187;6246.094316324107;6253.1245625612455;6260.280067792262;4830.468648448667;4896.3389942358035;4942.382202224722;4955.1823949048;4971.577415919615;4396.406485124531;4417.072414792865;4420.381146639473;4432.180046691373;4450.577195166454;5720.513756330759;5739.942600574024;5751.7411716472025;5752.07966725322;5758.654480731169;6132.78535251273;6268.968655156695;6275.968620156961;6279.560430769802;6288.968555157195;5552.833414997556;5595.7481913313995;5605.159226116115;5690.9430905690415;5701.71491425427;5371.57027437804;5377.166539186433;5379.569634429236;5381.977424289373;5383.7846486140115;6225.875482490415;6257.561970662101;6257.624542527463;6259.155014073133;6273.372662733873;6166.938330616636;6219.160413344141;6259.6870156492905;6266.248050234001;6296.716647750771;6285.182926219562;6291;6291.276503202007;6299.716512756845;6304.905426418699;6007.068904319837;6081;6171.290301615371;6175.876482470415;6179.227596550431;6164.722587483664;6184.041473571604;6189.195404597373;6189.381061893775;6200.286966998853;6036.245469316335;6039.124326972652;6046.546509011754;6047.576669633084;6049.122877182872;5919;6021;6037.18498002764;6049.304330002106;6050.394960503914;5865.413458654271;5879.265091863517;5885.705714714333;5886.882262354643;5887.970560147171;4378.412850253109;5825.359210486928;5861.648301101947;5962.135490353961;5990.9700451497465;5933.931892259262;5976.940230597638;5986.790462333972;5990.161377407254;5990.4908082813445 07:16:38 INFO - PROCESS | 1639 | |1;ext.html;452.3170013279947;452.35765213396974;472.89123501594656;491.8721132505548;504.2612572581169;36379;37907.62092379075;38078.04804879876;38185.09074546269;38279.42580861289;31300;39251.60748392515;39259.0185245369;39267.0183245419;39415;8859.689910853105;9160.45037297761;9198.632054717804;9220.585073671667;9224.215941644934;7970.362371010306;9200.585973631203;9361.76595585109;9364;9371;9329.11373419524;9374;9393.483358415284;9399.24806015517;9435.386699864486;9278.39690420124;9356.438613683133;9368.156865882036;9378.577963991602;9378.765530861714;9109.77018102561;9118.224950879117;9232.261419086457;9247.72256832296;9256.028117047748;36808.44787328192;37194.44208336877;37237.62762372369;37313;37341.62658373436;3323;3398.507216453625;3455.58532976042;3462.5844898612077;3608.963910360915;26874.865625671748;27109.1867243983;27273;27279.590806137923;29367.55948660772;3138.952915706266;3169.6513383527795;3221.855016524262;3305.289362787012;3346.548215990859;473188;476680;477369.6131519355;477758;478463;45809.541904580525;54981.900361992535;62315.6884215576;63068.68465657642;63962.6801865997;63093;66966.66516667386;67124;67149.66425167843;67376;15629.3748250071;15840.207989600474;15864;15867.761983570255;15870.52388428349;11992.940035299767;12096.637100886988;12106.394680265952;12120;12124.333161676017;19180.232790688497;19292.710609340873;19302.22791088341;19457.1085782844;19502.512437189183;6544.280129185786;8007.6796928123385;8089.676412943416;8092.190780921978;8148.496146656995;864.26105679643;923.1553128887127;976.272676855747;989.5645915797027;1002.8144793213279;27432.588511172347;27833.02584409536;29061.564076538867;29672.258193545335;29744.10767676973;986.7286496213599;1005.7787286796893;1021.7088129882948;1043.4313299251926;1169.351010189336;11062.170337224741;12153.331566763736;13118.147320424332;13711.862881371258;13860.653483662989;647132;649367;652709;653971;654551;47237;47695.79954605948;47729.761351193025;47780.80547986328;47854.28218576724;1314.3165553911913;1353.9796903046463;1378.2488543743484;1379.0071148772868;1382.640513466506;21706;25230.873845630653;26117.73882261153;26534.203973880816;26694.59958100591;43107;43721;43784.56215437805;43825;43844.34233486564;496698;595070;596743;597079;597234.1659500444;23352;23923.531087991796;24061.157859474835;24249.03003879825;24371;28201.858990704914;38418.84743457701;39204.41193382045;39401.211975760896;39832;29875.701242987292;30691.46542672813;33123;33900.66099338975;34153.829230853684;32736.83631581827;32925.15790728215;33777.662223377454;33917.982460526226;34437.48343774795;28475.43049138964;29175.7082429173;32947.67052329542;39991.40012899867;40667.7966610165;39051.02372440712;39369.60630393659;40013.599864000986;40639;41166;25812.35469113287;29727.51362431913;30849.845750771103;31089.533656995616;31616.367672646877 07:16:38 INFO - PROCESS | 1639 | |2;jquery.html;349.1898794796071;367;368.4694040581562;374.9756265842721;394.3158619794656;14210.786838197422;14640.926795366022;14873.256337183151;19718;20243.760998240246;19255.518612034706;19350.516237094078;19389.90305048473;19612.803871961278;19884;5421.728913554316;5750.539956803466;5754.251947246873;5755.712214389285;5767.480926716595;4567.292069729181;5456.699881506515;5596.664200148004;5658.405867383927;5667.979763642543;5611.466910643482;5613.2422123013275;5622.859428514299;5625.606207565472;5639.802606908759;5030.245463180516;5091.821786237464;5652.971735141298;5657.462541058593;5663.4336566343445;4956.826511072131;4971.403431588196;4975.651704380694;4979.875503112451;4981.900361992776;21705.348839534683;22570.774292257032;22820.087196512286;23153.652695209585;23180.768192318028;2230.3754948614414;2444.8899799509063;2792.3577577157166;2867.483852906476;2878.913632591026;9626.037396260388;9667.419954802665;9687.41875487473;9847.359921605073;10241.23190760696;1763.6649036683118;1776.3782676063404;1786.553361659585;1822.735703323024;1868.3087257714596;105804;109085;111721.88278117197;112609;115686;10762.40806755636;10861.239713220157;10968.106292542194;11038.668839934815;11222.66332010041;11252.324860508232;13720.588382348546;13734.313284335743;13738.65653358674;13796.241206733726;7883.724069657649;8014.759557213293;8040.517568945882;8196.959015204886;8201.548914809624;7886.250806173404;7889.329407000355;7889.763307100796;8069.798255043671;8078.7172448964;4093.7718684394563;4228.76741779199;4349.325854492558;4363.650907927358;4544.409226800561;1684.6041180322613;1898.1553208822218;1925.5667474818333;1943.7278780970676;1951.5413877739004;852.5481494807761;880.2034159086037;893.2362829780444;905.42053086025;973.8733964584559;19288.324908628132;20143.697844532344;20295.188192472135;20311.898440507703;20334.694979575317;907.6051917415945;927.6335847340398;940.7507010642186;963.3016063354012;986.6645340584092;9143.94844592867;9722.708318750449;10038.297319187586;10395.688129356131;10704.250702450909;488488;500103;507819.7654070384;509043;519394;101181;114256;114909.42545287387;117526.4123679376;120741.18888216684;925.3522534226069;926.9490178040272;969.6751588218026;971.315222767961;973.6105557776946;16425;17781.822181778018;17831.910840445715;18116;18354.90822545879;53193.73403132959;53588.73205633947;53690;54947;55325.44674553203;77136.61431692805;77495;78163.82754258804;78478.21521784937;81981.59009204915;7783.766487005399;7801.648925798239;7924.564148971631;8007;8155.166868706907;9947.353422027545;12047.578334758242;12450.688732781753;12575.748485030432;12736.490540378652;10459.797123330914;10716.678499645022;10774.407407592658;10859.891401085888;11012.284201526874;10992.120630349393;11053.834192486958;11103.666889993314;11351.602693905674;11545.249558778652;9330.906690933276;11452.312861228353;11506.597269095542;11538.711532211762;11748.88251117478;7693;7778.805529861799;8060.234277743605;8064.35485161174;8263.380246481658;8182.140875208018;10485.26603137773;10817.459127043774;11236.044936180517;11237.269577477438 07:16:38 INFO - PROCESS | 1639 | |3;mootools.html;1269.8539667938185;1487.9107253564787;1650.909199994;1864.94405167845;1879.2483006797288;7869.173736757644;8189.672413103469;8189.95905020474;8205.138460461647;8214.835703285931;7274.96362518188;7577.507462014965;7582.241775822415;7656.617169141535;7660.233976602337;2067.8552501324953;2195.5279614882797;2261.9208327708534;2266.5126997695493;2276.0781883337277;2261.796438320551;2266.603344414721;2273.522560262347;2274.6701728249395;2275.920342788003;2267.6825244465704;2288.0504590594883;2291.3698732848434;2294.9885250573725;2295.426143464134;2218.7892150245702;2280.09936075251;2282.2240438251074;2287.645414960684;2289.1873384948435;2250.0099956019462;2280.5552917181158;2289.0843662534953;2301.160076572056;2318.2813327868416;8223.629936652866;8290.41967062306;8302.335813134996;8308.584570771438;8315.625796839096;4967.652264341497;5108.233764935252;5147.897042059175;5157.020166168453;5162.535371816517;3263.2820779428725;3421.298633780069;3503.562054743157;3511.455724362728;3521.5245941798044;986.6497393425302;1016.1464369929295;1052.3054783842626;1057.8148823955853;1066.3441983180367;10627.946860265727;10673.946630266877;10683;10683.30558513694;10690.46547672621;4258.68059895509;4501.009777848868;4583.449986001635;4592.402987611656;4640.118377508263;9202.17180453749;9205.401648892801;9220.631174752934;9223.44659320443;9226.26189904806;4635.629149668019;5041.2438134280155;5143.3827940646615;5143.922841157386;5147.202183661539;4418.381426600278;4610.847288177955;4650.093231819763;4663.48701642819;4736.360591320127;3376.5104059911178;3702.315071711742;3711.6102809205186;3798.601146879593;3874.6512813846325;982.415462799633;1236.2953116723381;1247.6444213399138;1250.1873782041746;1260.6281147061638;745.3180339988958;745.7464462082918;875.4090988582681;885.5350940756026;928.0997432490474;5448.645838020516;5648.181013752983;5823.941760582425;5836.649801011953;5963.4931030862;967.9951600241953;991.4398364923795;999.9001098791417;1025.2259544044198;1046.4715318763976;3574.803385813805;3644.653757892996;3677.6279299851444;3734.7012239020814;3778.3954567269107;9673.613055477843;9903.60385584583;9999.700008999742;10020;10048.748781280527;9244.90755092454;9683.41899486033;9690.660826871026;9982.80034399308;9987.400755954666;7551.660175292125;7661.885071723928;7671.079470463468;7772.805679858049;7777.961110194526;5178.249153872743;5489.615726899079;5498.367687715964;5737.770489180386;5810.418958104325;9094.136057074567;9178.403403778733;9185.173334399937;9187.989321174531;9272.350935434455;9257.43785354459;9315.534223288943;9392.76518087026;9473.052694730748;9545.856812147962;6511.218653761579;6625.867482650417;6732.629705366252;7040.753573624898;7118.039064726403;7766.145723970246;7861.60691965388;7920.920790792249;7929.326007289445;7943.841123177389;7044.8591028178125;7126.893096603451;7138.714451421885;7148.535345202546;7152.570845749272;6293.433590976834;6876.553024053621;6986.091808064919;6989.161300643752;7087.220405755466;5934.643921364732;6287.748490060347;6347.523935704933;6348.333424990309;6353.491720662243;4669.159551280802;4820.831270905502;4865.464798872192;4865.610751139971;4929.605631549396;4752.809887604457;4754.263089221176;4822.927656085231;4893.6085113191575;4908 07:16:38 INFO - PROCESS | 1639 | |4;prototype.html;339.6111452387017;345.08208166277706;352.3921235868127;357.5334188883509;360.79074137000526;4526.207913615121;5055.800726683895;5194.07847075458;5262.578993680506;5279.445658205891;4758.9292409208;5055.24171374294;5169.767360468779;5188.9881473112755;5222.738863056841;3344.4983252512075;4108.897277568055;4120.587941205886;4121.052158003646;4139.730917490361;3466.1282907230066;3473.1838018065732;4113.136241389312;4136.503619565641;4171.791410429481;3720.572134204574;3771;4065.154391925649;4074.7962601869935;4108.52751933527;3386.8814591489304;4019.577944315863;4040.0707837197315;4066.552679205285;4095;2869.9426011479864;3588.3804822479474;3594.3530164570366;3619.6561326673923;3629.528161339009;3864;4636.774348312829;4641.95358046422;4814.748165477001;5219.791208351661;4365.4106695596;4645.814167433298;4654.020198387727;4657.4876763555985;4680.625549955996;5259.605529585297;5477.233187353734;5511.614187006911;5531.751071201806;5587.720613969326;3252.6259480159847;3466.77465964714;3679.3929001714687;3934.645881870617;3961.207758448322;5195.454477279869;5698.974184646762;5788.450097240755;5819.272590926134;5882.823515294548;3787.337215987191;3793.4878791363367;3803.5816060233906;3808.333541630204;3821.464994900716;3129.342838003999;3433.4678124890684;3732.234891847165;3759.3233218020473;3871.9806400967814;4216.030313028021;4441.755703436277;4465.553444655508;4481.955180448219;4490.797914093873;4763.666543342002;5403.919216156784;5470.398256191816;5502;5514.200440936042;1102.327580176092;1140.914431417647;1179.9262670969376;1200.0459634590522;1200.9131735779133;815.8740937506203;818.2349503214394;844.6325848256029;848.9133908597006;857.2327766648814;4925.694690906913;4947.876303092451;4964.287320874292;4967.554039135026;4967.776450059756;4652.092642016757;4688.624910007191;4804.774782430528;5362.329708786402;5439.5104440600535;4696.60078893291;4875.5124487551675;5187.273781670569;5262.55268302191;5279.683219006872;2502.737212592688;2835.560488124344;2843.5829544886683;2881.7406433420674;2920.036387991958;4740.786664600101;5007.799688012438;5460.3244410239495;5467.687754938786;5723.627964182315;4997.975010124999;5021.924671129936;5150.356205474316;5210;5538.944610553843;4866.659333846667;5062.569681577033;5298.682079075268;5393.622446428869;5670.213882626959;4904.8773780655765;5115.109962870966;5434.049041417815;5453.7818487260065;5504.311961004874;4844.539768721966;5361.7561375368905;5393.568514518907;5425.430329815313;5430.456954304538;4936.802527898843;5071.847844564669;5427.538659214011;5561.8609534760335;5700.743466543932;2807.971920280771;3501.509788629645;3571.0536707771944;3602.9283162181327;3618.9866837285604;4509.842155524541;4851.80592776285;5183.688978661293;5263.842084737464;5423.014084647702;4623.491415944311;4778.829186849244;5135.998480296306;5279.656822306538;5301.522862942354;4465.910681786281;5219.086659834438;5268.867193553405;5549.833504994856;5615.522680572197;4547.976705241426;4788.899821689889;4835.685680430761;5022.3219865317715;5419.701916394636;4595.517470665532;5352.625316227826;5409.350148204835;5471.644343117685;5542.695151766691;5427.945720542744;5467.890642187213;5478.506934375925;5484.765927666243;5527.977324195037 07:16:38 INFO - PROCESS | 1639 | |5;yui.html;738.8633102875968;748.3302444312341;776.1113525013859;813.7843471480057;834.8705950577655;14145;14594.124352538855;14616;14649;14671.706565868677;14086.366113524891;14244.21656808875;14267.215303158322;14269.215193164371;14274.857251427482;687.8693048320828;710.0237173885907;855.3926712034432;864.8616221404592;886.5434301334818;870.6604424274537;876.0844917061686;879.4723166100353;886.9822603547925;899.6446403670546;941.5339406993547;944.0417975754615;945.7210123013693;949.126803340928;949.2500924269854;730.82825535999;756.1455555222618;942.0908822985851;944.0182210501071;945.6170251048268;557.8549577109981;558.3829867995847;560.117814442255;675.2909445082644;696.9581825090511;14736.926315368462;14768.113913165136;14770.483033093898;14779.408823647149;14827.0362426443;13043.673908152277;13046.478140874353;13188.406521706545;13361.86638133616;13399.464021439131;10519.52662130206;12916.677083072904;13150.079494435393;13230.536931207362;13600.591982240549;4374.387585737999;4490.483594386622;4503.594676479132;4572.404683636092;4630.259158534652;110785.9999999992;114703;118884.40557797241;120523.39738301252;120835;10756.677299681021;10782.46087695612;10828.68443067235;11122.777544449065;11327.263727857828;20796.480087997617;20841;21023.26418575373;21050.684239736416;21362.359129226148;7616.314531692064;7673.808154796175;7708.614569271514;7766.301032906952;7861.685532578747;4255.319148936205;4261.786910654455;4264.57354264571;4270.530241673413;4401.207782599099;3324.5844269466315;3392.9660703393142;3397.7451691123265;3421.9657803422147;3422.401079811023;596.6241268001131;618.9164462797555;624.3849807939176;631.2015300644688;638.1512588257699;534.7967772246521;659.9109120268798;683.7880257120308;689.7482418917059;697.1494776372817;8361.581920903931;9372.718818435458;9520.809583808423;9628.422294662343;10145.492725363702;604.7701873288122;613.7016871815081;673.2123415603755;692.9965350173318;700.0968750312143;4530.61489773373;4651.953480465219;4654.8096391282015;4656.348111264426;4755.334253204554;99940;103417;104226;104894.4755276234;107800;69083.07464671187;91608;93567;94485.5275723617;95159.52420237854;67536;75319.62340188264;75756.62121689467;77503.61248193723;77951.61024194956;7781.182273372602;8632.611532480927;8890.4665720057;9081.818363632558;9119.817603648022;38196;52457.7377113112;52461;52688.68278293074;53368.46631533635;63084.36915630785;63919.36080639134;64899;67207;67225;10989;12895.935520322339;13270.80093798613;13327.866721332663;13418.664533386744;12404.379781011094;14756.852431475549;15090.320935558146;15162.924185379445;15393.153376563949;13596.93201533986;13809.930950345586;13815.516456923959;13945.233012184428;13987.650308742363;13486.730265394834;13730.176189428666;13892.652683682989;13988.790168147283;14024.509142179975;13126.934365328112;13132.34338283063;13242.139260948008;13571.52499662507;13652.863471365557;9428.010058943712;10892.61875834342;11252.774944500901;11499.597514086967;11894.048476121716;10212.795744085226;11066.114710823276;11066.778664426827;11265.63631369977;11509.654710358702 07:16:38 INFO - PROCESS | 1639 | __end_tp_report 07:16:38 INFO - PROCESS | 1639 | __start_cc_report 07:16:38 INFO - PROCESS | 1639 | _x_x_mozilla_cycle_collect,3888 07:16:38 INFO - PROCESS | 1639 | __end_cc_report 07:16:38 INFO - PROCESS | 1639 | __startTimestamp1482851798042__endTimestamp 07:16:38 INFO - PROCESS | 1639 | 07:16:38 INFO - PROCESS | 1639 | ------- Summary: start ------- 07:16:38 INFO - PROCESS | 1639 | Number of tests: 6 07:16:38 INFO - PROCESS | 1639 | 07:16:38 INFO - PROCESS | 1639 | [#0] dojo.html Cycles:165 Average:5708.06 Median:5887.97 stddev:636.11 (10.8%) stddev-sans-first:608.84 07:16:38 INFO - PROCESS | 1639 | Values: 3278.7 3357.5 3365.3 3380.5 3412.1 5601.7 5618.8 5677.7 5700.7 5714.6 5628.6 5675.9 5703.1 5708.7 5712.8 5104.7 5124.0 5175.3 5180.1 5217.1 5215.8 5249.3 5257.1 5275.8 5278.6 5114.9 5211.1 5232.5 5242.1 5251.1 5089.9 5146.4 5148.5 5167.6 5218.3 5110.2 5156.5 5170.5 5200.7 5200.8 5626.7 5631.4 5642.1 5644.9 5708.4 6038.7 6063.2 6084.1 6192.5 6226.0 6156.7 6187.0 6226.2 6296.8 6305.1 5761.8 5769.1 5770.7 5785.0 5806.3 6126.6 6220.8 6273.2 6277.2 6299.6 6047.5 6120.3 6121.1 6128.4 6129.4 6169.0 6177.6 6189.8 6193.3 6198.7 6128.1 6144.8 6197.6 6208.5 6221.6 6239.5 6243.4 6246.1 6253.1 6260.3 4830.5 4896.3 4942.4 4955.2 4971.6 4396.4 4417.1 4420.4 4432.2 4450.6 5720.5 5739.9 5751.7 5752.1 5758.7 6132.8 6269.0 6276.0 6279.6 6289.0 5552.8 5595.7 5605.2 5690.9 5701.7 5371.6 5377.2 5379.6 5382.0 5383.8 6225.9 6257.6 6257.6 6259.2 6273.4 6166.9 6219.2 6259.7 6266.2 6296.7 6285.2 6291.0 6291.3 6299.7 6304.9 6007.1 6081.0 6171.3 6175.9 6179.2 6164.7 6184.0 6189.2 6189.4 6200.3 6036.2 6039.1 6046.5 6047.6 6049.1 5919.0 6021.0 6037.2 6049.3 6050.4 5865.4 5879.3 5885.7 5886.9 5888.0 4378.4 5825.4 5861.6 5962.1 5991.0 5933.9 5976.9 5986.8 5990.2 5990.5 07:16:38 INFO - PROCESS | 1639 | 07:16:38 INFO - PROCESS | 1639 | [#1] ext.html Cycles:180 Average:68616.41 Median:38734.94 stddev:153546.50 (396.4%) stddev-sans-first:153891.94 07:16:38 INFO - PROCESS | 1639 | Values: 452.3 452.4 472.9 491.9 504.3 36379.0 37907.6 38078.0 38185.1 38279.4 31300.0 39251.6 39259.0 39267.0 39415.0 8859.7 9160.5 9198.6 9220.6 9224.2 7970.4 9200.6 9361.8 9364.0 9371.0 9329.1 9374.0 9393.5 9399.2 9435.4 9278.4 9356.4 9368.2 9378.6 9378.8 9109.8 9118.2 9232.3 9247.7 9256.0 36808.4 37194.4 37237.6 37313.0 37341.6 3323.0 3398.5 3455.6 3462.6 3609.0 26874.9 27109.2 27273.0 27279.6 29367.6 3139.0 3169.7 3221.9 3305.3 3346.5 473188.0 476680.0 477369.6 477758.0 478463.0 45809.5 54981.9 62315.7 63068.7 63962.7 63093.0 66966.7 67124.0 67149.7 67376.0 15629.4 15840.2 15864.0 15867.8 15870.5 11992.9 12096.6 12106.4 12120.0 12124.3 19180.2 19292.7 19302.2 19457.1 19502.5 6544.3 8007.7 8089.7 8092.2 8148.5 864.3 923.2 976.3 989.6 1002.8 27432.6 27833.0 29061.6 29672.3 29744.1 986.7 1005.8 1021.7 1043.4 1169.4 11062.2 12153.3 13118.1 13711.9 13860.7 647132.0 649367.0 652709.0 653971.0 654551.0 47237.0 47695.8 47729.8 47780.8 47854.3 1314.3 1354.0 1378.2 1379.0 1382.6 21706.0 25230.9 26117.7 26534.2 26694.6 43107.0 43721.0 43784.6 43825.0 43844.3 496698.0 595070.0 596743.0 597079.0 597234.2 23352.0 23923.5 24061.2 24249.0 24371.0 28201.9 38418.8 39204.4 39401.2 39832.0 29875.7 30691.5 33123.0 33900.7 34153.8 32736.8 32925.2 33777.7 33918.0 34437.5 28475.4 29175.7 32947.7 39991.4 40667.8 39051.0 39369.6 40013.6 40639.0 41166.0 25812.4 29727.5 30849.8 31089.5 31616.4 07:16:38 INFO - PROCESS | 1639 | 07:16:38 INFO - PROCESS | 1639 | [#2] jquery.html Cycles:180 Average:31430.09 Median:2244.04 stddev:84795.89 (3778.7%) stddev-sans-first:85001.65 07:16:38 INFO - PROCESS | 1639 | Values: 349.2 367.0 368.5 375.0 394.3 14210.8 14640.9 14873.3 19718.0 20243.8 19255.5 19350.5 19389.9 19612.8 19884.0 5421.7 5750.5 5754.3 5755.7 5767.5 4567.3 5456.7 5596.7 5658.4 5668.0 5611.5 5613.2 5622.9 5625.6 5639.8 5030.2 5091.8 5653.0 5657.5 5663.4 4956.8 4971.4 4975.7 4979.9 4981.9 21705.3 22570.8 22820.1 23153.7 23180.8 2230.4 2444.9 2792.4 2867.5 2878.9 9626.0 9667.4 9687.4 9847.4 10241.2 1763.7 1776.4 1786.6 1822.7 1868.3 105804.0 109085.0 111721.9 112609.0 115686.0 10762.4 10861.2 10968.1 11038.7 11222.7 11252.3 13720.6 13734.3 13738.7 13796.2 7883.7 8014.8 8040.5 8197.0 8201.5 7886.3 7889.3 7889.8 8069.8 8078.7 4093.8 4228.8 4349.3 4363.7 4544.4 1684.6 1898.2 1925.6 1943.7 1951.5 852.5 880.2 893.2 905.4 973.9 19288.3 20143.7 20295.2 20311.9 20334.7 907.6 927.6 940.8 963.3 986.7 9143.9 9722.7 10038.3 10395.7 10704.3 488488.0 500103.0 507819.8 509043.0 519394.0 101181.0 114256.0 114909.4 117526.4 120741.2 925.4 926.9 969.7 971.3 973.6 16425.0 17781.8 17831.9 18116.0 18354.9 53193.7 53588.7 53690.0 54947.0 55325.4 77136.6 77495.0 78163.8 78478.2 81981.6 7783.8 7801.6 7924.6 8007.0 8155.2 9947.4 12047.6 12450.7 12575.7 12736.5 10459.8 10716.7 10774.4 10859.9 11012.3 10992.1 11053.8 11103.7 11351.6 11545.2 9330.9 11452.3 11506.6 11538.7 11748.9 7693.0 7778.8 8060.2 8064.4 8263.4 8182.1 10485.3 10817.5 11236.0 11237.3 07:16:38 INFO - PROCESS | 1639 | 07:16:38 INFO - PROCESS | 1639 | [#3] mootools.html Cycles:180 Average:5354.08 Median:5159.78 stddev:2915.11 (56.5%) stddev-sans-first:2907.13 07:16:38 INFO - PROCESS | 1639 | Values: 1269.9 1487.9 1650.9 1864.9 1879.2 7869.2 8189.7 8190.0 8205.1 8214.8 7275.0 7577.5 7582.2 7656.6 7660.2 2067.9 2195.5 2261.9 2266.5 2276.1 2261.8 2266.6 2273.5 2274.7 2275.9 2267.7 2288.1 2291.4 2295.0 2295.4 2218.8 2280.1 2282.2 2287.6 2289.2 2250.0 2280.6 2289.1 2301.2 2318.3 8223.6 8290.4 8302.3 8308.6 8315.6 4967.7 5108.2 5147.9 5157.0 5162.5 3263.3 3421.3 3503.6 3511.5 3521.5 986.6 1016.1 1052.3 1057.8 1066.3 10627.9 10673.9 10683.0 10683.3 10690.5 4258.7 4501.0 4583.4 4592.4 4640.1 9202.2 9205.4 9220.6 9223.4 9226.3 4635.6 5041.2 5143.4 5143.9 5147.2 4418.4 4610.8 4650.1 4663.5 4736.4 3376.5 3702.3 3711.6 3798.6 3874.7 982.4 1236.3 1247.6 1250.2 1260.6 745.3 745.7 875.4 885.5 928.1 5448.6 5648.2 5823.9 5836.6 5963.5 968.0 991.4 999.9 1025.2 1046.5 3574.8 3644.7 3677.6 3734.7 3778.4 9673.6 9903.6 9999.7 10020.0 10048.7 9244.9 9683.4 9690.7 9982.8 9987.4 7551.7 7661.9 7671.1 7772.8 7778.0 5178.2 5489.6 5498.4 5737.8 5810.4 9094.1 9178.4 9185.2 9188.0 9272.4 9257.4 9315.5 9392.8 9473.1 9545.9 6511.2 6625.9 6732.6 7040.8 7118.0 7766.1 7861.6 7920.9 7929.3 7943.8 7044.9 7126.9 7138.7 7148.5 7152.6 6293.4 6876.6 6986.1 6989.2 7087.2 5934.6 6287.7 6347.5 6348.3 6353.5 4669.2 4820.8 4865.5 4865.6 4929.6 4752.8 4754.3 4822.9 4893.6 4908.0 07:16:38 INFO - PROCESS | 1639 | 07:16:38 INFO - PROCESS | 1639 | [#4] prototype.html Cycles:180 Average:4352.07 Median:4848.17 stddev:1322.91 (27.3%) stddev-sans-first:1291.89 07:16:38 INFO - PROCESS | 1639 | Values: 339.6 345.1 352.4 357.5 360.8 4526.2 5055.8 5194.1 5262.6 5279.4 4758.9 5055.2 5169.8 5189.0 5222.7 3344.5 4108.9 4120.6 4121.1 4139.7 3466.1 3473.2 4113.1 4136.5 4171.8 3720.6 3771.0 4065.2 4074.8 4108.5 3386.9 4019.6 4040.1 4066.6 4095.0 2869.9 3588.4 3594.4 3619.7 3629.5 3864.0 4636.8 4642.0 4814.7 5219.8 4365.4 4645.8 4654.0 4657.5 4680.6 5259.6 5477.2 5511.6 5531.8 5587.7 3252.6 3466.8 3679.4 3934.6 3961.2 5195.5 5699.0 5788.5 5819.3 5882.8 3787.3 3793.5 3803.6 3808.3 3821.5 3129.3 3433.5 3732.2 3759.3 3872.0 4216.0 4441.8 4465.6 4482.0 4490.8 4763.7 5403.9 5470.4 5502.0 5514.2 1102.3 1140.9 1179.9 1200.0 1200.9 815.9 818.2 844.6 848.9 857.2 4925.7 4947.9 4964.3 4967.6 4967.8 4652.1 4688.6 4804.8 5362.3 5439.5 4696.6 4875.5 5187.3 5262.6 5279.7 2502.7 2835.6 2843.6 2881.7 2920.0 4740.8 5007.8 5460.3 5467.7 5723.6 4998.0 5021.9 5150.4 5210.0 5538.9 4866.7 5062.6 5298.7 5393.6 5670.2 4904.9 5115.1 5434.0 5453.8 5504.3 4844.5 5361.8 5393.6 5425.4 5430.5 4936.8 5071.8 5427.5 5561.9 5700.7 2808.0 3501.5 3571.1 3602.9 3619.0 4509.8 4851.8 5183.7 5263.8 5423.0 4623.5 4778.8 5136.0 5279.7 5301.5 4465.9 5219.1 5268.9 5549.8 5615.5 4548.0 4788.9 4835.7 5022.3 5419.7 4595.5 5352.6 5409.4 5471.6 5542.7 5427.9 5467.9 5478.5 5484.8 5528.0 07:16:38 INFO - PROCESS | 1639 | 07:16:38 INFO - PROCESS | 1639 | [#5] yui.html Cycles:180 Average:20587.88 Median:4445.85 stddev:30221.97 (679.8%) stddev-sans-first:30270.00 07:16:38 INFO - PROCESS | 1639 | Values: 738.9 748.3 776.1 813.8 834.9 14145.0 14594.1 14616.0 14649.0 14671.7 14086.4 14244.2 14267.2 14269.2 14274.9 687.9 710.0 855.4 864.9 886.5 870.7 876.1 879.5 887.0 899.6 941.5 944.0 945.7 949.1 949.3 730.8 756.1 942.1 944.0 945.6 557.9 558.4 560.1 675.3 697.0 14736.9 14768.1 14770.5 14779.4 14827.0 13043.7 13046.5 13188.4 13361.9 13399.5 10519.5 12916.7 13150.1 13230.5 13600.6 4374.4 4490.5 4503.6 4572.4 4630.3 110786.0 114703.0 118884.4 120523.4 120835.0 10756.7 10782.5 10828.7 11122.8 11327.3 20796.5 20841.0 21023.3 21050.7 21362.4 7616.3 7673.8 7708.6 7766.3 7861.7 4255.3 4261.8 4264.6 4270.5 4401.2 3324.6 3393.0 3397.7 3422.0 3422.4 596.6 618.9 624.4 631.2 638.2 534.8 659.9 683.8 689.7 697.1 8361.6 9372.7 9520.8 9628.4 10145.5 604.8 613.7 673.2 693.0 700.1 4530.6 4652.0 4654.8 4656.3 4755.3 99940.0 103417.0 104226.0 104894.5 107800.0 69083.1 91608.0 93567.0 94485.5 95159.5 67536.0 75319.6 75756.6 77503.6 77951.6 7781.2 8632.6 8890.5 9081.8 9119.8 38196.0 52457.7 52461.0 52688.7 53368.5 63084.4 63919.4 64899.0 67207.0 67225.0 10989.0 12895.9 13270.8 13327.9 13418.7 12404.4 14756.9 15090.3 15162.9 15393.2 13596.9 13809.9 13815.5 13945.2 13987.7 13486.7 13730.2 13892.7 13988.8 14024.5 13126.9 13132.3 13242.1 13571.5 13652.9 9428.0 10892.6 11252.8 11499.6 11894.0 10212.8 11066.1 11066.8 11265.6 11509.7 07:16:38 INFO - PROCESS | 1639 | -------- Summary: end -------- 07:16:38 INFO - PROCESS | 1639 | 07:16:38 INFO - TEST-INFO | 1639: exit 0 07:16:38 INFO - TEST-OK | dromaeo_css | took 1120032ms 07:16:38 INFO - TEST-START | kraken 07:16:38 INFO - operating with platform_type : mac_ 07:16:38 INFO - Initialising browser for kraken test... 07:16:38 INFO - TEST-INFO | started process 1644 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/tmpGxTdWp/profile http://localhost:49241/getInfo.html) 07:16:39 INFO - PROCESS | 1644 | 1482851799133 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 07:16:40 INFO - PROCESS | 1644 | JavaScript error: chrome://global/content/browser-content.js, line 1772: ReferenceError: event is not defined 07:16:40 INFO - PROCESS | 1644 | JavaScript error: chrome://global/content/browser-content.js, line 1772: ReferenceError: event is not defined 07:16:40 INFO - PROCESS | 1644 | JavaScript error: chrome://global/content/browser-content.js, line 1772: ReferenceError: event is not defined 07:16:40 INFO - PROCESS | 1644 | __metrics Screen width/height:1600/1200 07:16:40 INFO - PROCESS | 1644 | colorDepth:24 07:16:40 INFO - PROCESS | 1644 | Browser inner width/height: 1024/689 07:16:40 INFO - PROCESS | 1644 | __metrics 07:16:41 INFO - PROCESS | 1644 | 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" 07:16:41 INFO - PROCESS | 1644 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 07:16:41 INFO - TEST-INFO | 1644: exit 0 07:16:41 INFO - Browser initialized. 07:16:41 INFO - Running cycle 1/1 for kraken test... 07:16:41 INFO - TEST-INFO | started process 1645 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/tmpGxTdWp/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:16:53 INFO - PROCESS | 1645 | RSS: Main: 235470848 07:16:53 INFO - PROCESS | 1645 | 07:16:56 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-beat-detection) 07:16:57 INFO - PROCESS | 1645 | RSS: Main: 287027200 07:16:57 INFO - PROCESS | 1645 | 07:17:00 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-dft) 07:17:00 INFO - PROCESS | 1645 | RSS: Main: 288378880 07:17:00 INFO - PROCESS | 1645 | 07:17:03 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-fft) 07:17:04 INFO - PROCESS | 1645 | RSS: Main: 378904576 07:17:04 INFO - PROCESS | 1645 | 07:17:06 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-oscillator) 07:17:07 INFO - PROCESS | 1645 | RSS: Main: 284749824 07:17:07 INFO - PROCESS | 1645 | 07:17:09 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49241/tests/kraken/driver.html?testName=imaging-gaussian-blur) 07:17:09 INFO - PROCESS | 1645 | RSS: Main: 287125504 07:17:09 INFO - PROCESS | 1645 | 07:17:12 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49241/tests/kraken/driver.html?testName=imaging-darkroom) 07:17:13 INFO - PROCESS | 1645 | RSS: Main: 328257536 07:17:13 INFO - PROCESS | 1645 | 07:17:16 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49241/tests/kraken/driver.html?testName=imaging-desaturate) 07:17:17 INFO - PROCESS | 1645 | RSS: Main: 330387456 07:17:17 INFO - PROCESS | 1645 | 07:17:19 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49241/tests/kraken/driver.html?testName=json-parse-financial) 07:17:20 INFO - PROCESS | 1645 | RSS: Main: 325550080 07:17:20 INFO - PROCESS | 1645 | 07:17:21 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49241/tests/kraken/driver.html?testName=json-stringify-tinderbox) 07:17:22 INFO - PROCESS | 1645 | RSS: Main: 304570368 07:17:22 INFO - PROCESS | 1645 | 07:17:24 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-aes) 07:17:25 INFO - PROCESS | 1645 | RSS: Main: 306413568 07:17:25 INFO - PROCESS | 1645 | 07:17:27 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-ccm) 07:17:27 INFO - PROCESS | 1645 | RSS: Main: 308125696 07:17:27 INFO - PROCESS | 1645 | 07:17:30 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 07:17:31 INFO - PROCESS | 1645 | RSS: Main: 325169152 07:17:31 INFO - PROCESS | 1645 | 07:17:34 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 07:17:35 INFO - PROCESS | 1645 | RSS: Main: 319275008 07:17:35 INFO - PROCESS | 1645 | 07:17:37 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49241/tests/kraken/driver.html?testName=ai-astar) 07:17:37 INFO - PROCESS | 1645 | RSS: Main: 362930176 07:17:37 INFO - PROCESS | 1645 | 07:17:37 INFO - PROCESS | 1645 | __start_tp_report 07:17:37 INFO - PROCESS | 1645 | _x_x_mozilla_page_load 07:17:37 INFO - PROCESS | 1645 | _x_x_mozilla_page_load_details 07:17:37 INFO - PROCESS | 1645 | |i|pagename|runs| 07:17:37 INFO - PROCESS | 1645 | |0;ai-astar;113;116;114;114;113;114;114;113;118;149 07:17:37 INFO - PROCESS | 1645 | |1;audio-beat-detection;141;133;124;133;108;108;108;108;129;112 07:17:37 INFO - PROCESS | 1645 | |2;audio-dft;183;186;181;183;183;184;179;184;186;185 07:17:37 INFO - PROCESS | 1645 | |3;audio-fft;79;58;58;88;61;58;58;59;75;58 07:17:37 INFO - PROCESS | 1645 | |4;audio-oscillator;86;69;86;70;70;69;82;70;73;114 07:17:37 INFO - PROCESS | 1645 | |5;imaging-gaussian-blur;89;87;89;87;94;130;127;87;89;87 07:17:37 INFO - PROCESS | 1645 | |6;imaging-darkroom;94;104;150;125;94;93;94;97;94;94 07:17:37 INFO - PROCESS | 1645 | |7;imaging-desaturate;88;87;89;86;87;85;85;88;85;84 07:17:37 INFO - PROCESS | 1645 | |8;json-parse-financial;53;55;53;53;55;55;55;54;55;54 07:17:37 INFO - PROCESS | 1645 | |9;json-stringify-tinderbox;65;73;68;78;73;71;70;71;70;69 07:17:37 INFO - PROCESS | 1645 | |10;stanford-crypto-aes;88;85;85;87;90;90;87;87;87;87 07:17:37 INFO - PROCESS | 1645 | |11;stanford-crypto-ccm;141;131;139;141;139;177;155;152;202;138 07:17:37 INFO - PROCESS | 1645 | |12;stanford-crypto-pbkdf2;173;173;229;172;172;170;171;170;171;171 07:17:37 INFO - PROCESS | 1645 | |13;stanford-crypto-sha256-iterative;66;66;66;65;66;69;82;95;87;77 07:17:37 INFO - PROCESS | 1645 | __end_tp_report 07:17:37 INFO - PROCESS | 1645 | __start_cc_report 07:17:37 INFO - PROCESS | 1645 | _x_x_mozilla_cycle_collect,2090 07:17:37 INFO - PROCESS | 1645 | __end_cc_report 07:17:37 INFO - PROCESS | 1645 | __startTimestamp1482851857403__endTimestamp 07:17:37 INFO - PROCESS | 1645 | 07:17:37 INFO - PROCESS | 1645 | ------- Summary: start ------- 07:17:37 INFO - PROCESS | 1645 | Number of tests: 14 07:17:37 INFO - PROCESS | 1645 | 07:17:37 INFO - PROCESS | 1645 | [#0] ai-astar Cycles:10 Average:117.80 Median:114.00 stddev:11.07 (9.7%) stddev-sans-first:11.61 07:17:37 INFO - PROCESS | 1645 | Values: 113.0 116.0 114.0 114.0 113.0 114.0 114.0 113.0 118.0 149.0 07:17:37 INFO - PROCESS | 1645 | 07:17:37 INFO - PROCESS | 1645 | [#1] audio-beat-detection Cycles:10 Average:120.40 Median:126.50 stddev:12.97 (10.3%) stddev-sans-first:11.42 07:17:37 INFO - PROCESS | 1645 | Values: 141.0 133.0 124.0 133.0 108.0 108.0 108.0 108.0 129.0 112.0 07:17:37 INFO - PROCESS | 1645 | 07:17:37 INFO - PROCESS | 1645 | [#2] audio-dft Cycles:10 Average:183.40 Median:184.00 stddev:2.17 (1.2%) stddev-sans-first:2.30 07:17:37 INFO - PROCESS | 1645 | Values: 183.0 186.0 181.0 183.0 183.0 184.0 179.0 184.0 186.0 185.0 07:17:37 INFO - PROCESS | 1645 | 07:17:37 INFO - PROCESS | 1645 | [#3] audio-fft Cycles:10 Average:65.20 Median:60.00 stddev:11.16 (18.6%) stddev-sans-first:10.67 07:17:37 INFO - PROCESS | 1645 | Values: 79.0 58.0 58.0 88.0 61.0 58.0 58.0 59.0 75.0 58.0 07:17:37 INFO - PROCESS | 1645 | 07:17:37 INFO - PROCESS | 1645 | [#4] audio-oscillator Cycles:10 Average:78.90 Median:71.50 stddev:14.18 (19.8%) stddev-sans-first:14.81 07:17:37 INFO - PROCESS | 1645 | Values: 86.0 69.0 86.0 70.0 70.0 69.0 82.0 70.0 73.0 114.0 07:17:37 INFO - PROCESS | 1645 | 07:17:37 INFO - PROCESS | 1645 | [#5] imaging-gaussian-blur Cycles:10 Average:96.60 Median:88.00 stddev:16.96 (19.3%) stddev-sans-first:17.76 07:17:37 INFO - PROCESS | 1645 | Values: 89.0 87.0 89.0 87.0 94.0 130.0 127.0 87.0 89.0 87.0 07:17:37 INFO - PROCESS | 1645 | 07:17:37 INFO - PROCESS | 1645 | [#6] imaging-darkroom Cycles:10 Average:103.90 Median:94.00 stddev:18.94 (20.1%) stddev-sans-first:19.74 07:17:37 INFO - PROCESS | 1645 | Values: 94.0 104.0 150.0 125.0 94.0 93.0 94.0 97.0 94.0 94.0 07:17:37 INFO - PROCESS | 1645 | 07:17:37 INFO - PROCESS | 1645 | [#7] imaging-desaturate Cycles:10 Average:86.40 Median:87.00 stddev:1.65 (1.9%) stddev-sans-first:1.64 07:17:37 INFO - PROCESS | 1645 | Values: 88.0 87.0 89.0 86.0 87.0 85.0 85.0 88.0 85.0 84.0 07:17:37 INFO - PROCESS | 1645 | 07:17:37 INFO - PROCESS | 1645 | [#8] json-parse-financial Cycles:10 Average:54.20 Median:55.00 stddev:0.92 (1.7%) stddev-sans-first:0.87 07:17:37 INFO - PROCESS | 1645 | Values: 53.0 55.0 53.0 53.0 55.0 55.0 55.0 54.0 55.0 54.0 07:17:37 INFO - PROCESS | 1645 | 07:17:37 INFO - PROCESS | 1645 | [#9] json-stringify-tinderbox Cycles:10 Average:70.80 Median:71.00 stddev:3.46 (4.9%) stddev-sans-first:2.96 07:17:37 INFO - PROCESS | 1645 | Values: 65.0 73.0 68.0 78.0 73.0 71.0 70.0 71.0 70.0 69.0 07:17:37 INFO - PROCESS | 1645 | 07:17:37 INFO - PROCESS | 1645 | [#10] stanford-crypto-aes Cycles:10 Average:87.30 Median:87.00 stddev:1.70 (2.0%) stddev-sans-first:1.79 07:17:37 INFO - PROCESS | 1645 | Values: 88.0 85.0 85.0 87.0 90.0 90.0 87.0 87.0 87.0 87.0 07:17:37 INFO - PROCESS | 1645 | 07:17:37 INFO - PROCESS | 1645 | [#11] stanford-crypto-ccm Cycles:10 Average:151.50 Median:146.50 stddev:21.98 (15.0%) stddev-sans-first:22.98 07:17:37 INFO - PROCESS | 1645 | Values: 141.0 131.0 139.0 141.0 139.0 177.0 155.0 152.0 202.0 138.0 07:17:37 INFO - PROCESS | 1645 | 07:17:37 INFO - PROCESS | 1645 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:177.20 Median:172.00 stddev:18.23 (10.6%) stddev-sans-first:19.27 07:17:37 INFO - PROCESS | 1645 | Values: 173.0 173.0 229.0 172.0 172.0 170.0 171.0 170.0 171.0 171.0 07:17:37 INFO - PROCESS | 1645 | 07:17:37 INFO - PROCESS | 1645 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:73.90 Median:73.00 stddev:10.77 (14.8%) stddev-sans-first:11.04 07:17:37 INFO - PROCESS | 1645 | Values: 66.0 66.0 66.0 65.0 66.0 69.0 82.0 95.0 87.0 77.0 07:17:37 INFO - PROCESS | 1645 | -------- Summary: end -------- 07:17:37 INFO - PROCESS | 1645 | 07:17:38 INFO - TEST-INFO | 1645: exit 0 07:17:38 INFO - TEST-OK | kraken | took 59738ms 07:17:38 INFO - SUITE-END | took 1179s 07:17:38 INFO - Completed test suite (00:19:39) 07:17:38 INFO - javascript benchmark 07:17:38 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3278.65574114718, 3357.4628059510483, 3365.3437579671972, 3380.5267262583234, 3412.0787387405403, 5601.691906945116, 5618.831435056944, 5677.716114194294, 5700.714964251786, 5714.599978001536, 5628.577856660757, 5675.886482270352, 5703.087505999041, 5708.686022268784, 5712.828615141542, 5104.6937183768905, 5124.0, 5175.2754614353835, 5180.067587834188, 5217.139172036609, 5215.843524694265, 5249.26510288558, 5257.106291930381, 5275.815346462875, 5278.604104692145, 5114.897702045938, 5211.140161873324, 5232.502912223333, 5242.056429842627, 5251.107311756991, 5089.923651145235, 5146.408163061221, 5148.459411761749, 5167.638265321429, 5218.321618189649, 5110.207917772751, 5156.458571849939, 5170.534651881311, 5200.661956972823, 5200.76596553156, 5626.662400255997, 5631.436856314377, 5642.125470552071, 5644.887102257973, 5708.372079071299, 6038.667873266969, 6063.18147050147, 6084.148219249309, 6192.535559833031, 6225.968870155665, 6156.6921653917125, 6187.041008643622, 6226.159468471789, 6296.842578935518, 6305.0857625644485, 5761.769529218868, 5769.076947688433, 5770.682612456271, 5785.0454674979, 5806.303243610751, 6126.632402055891, 6220.844478888064, 6273.215848018997, 6277.1525844011385, 6299.559030867887, 6047.546434017467, 6120.265568131853, 6121.051237058263, 6128.448439640454, 6129.417705317987, 6169.0, 6177.62934223948, 6189.81430557084, 6193.256809182927, 6198.690065496708, 6128.055925366366, 6144.78493252734, 6197.597156184834, 6208.50331973441, 6221.595596286227, 6239.469645080219, 6243.43809057187, 6246.094316324107, 6253.1245625612455, 6260.280067792262, 4830.468648448667, 4896.3389942358035, 4942.382202224722, 4955.1823949048, 4971.577415919615, 4396.406485124531, 4417.072414792865, 4420.381146639473, 4432.180046691373, 4450.577195166454, 5720.513756330759, 5739.942600574024, 5751.7411716472025, 5752.07966725322, 5758.654480731169, 6132.78535251273, 6268.968655156695, 6275.968620156961, 6279.560430769802, 6288.968555157195, 5552.833414997556, 5595.7481913313995, 5605.159226116115, 5690.9430905690415, 5701.71491425427, 5371.57027437804, 5377.166539186433, 5379.569634429236, 5381.977424289373, 5383.7846486140115, 6225.875482490415, 6257.561970662101, 6257.624542527463, 6259.155014073133, 6273.372662733873, 6166.938330616636, 6219.160413344141, 6259.6870156492905, 6266.248050234001, 6296.716647750771, 6285.182926219562, 6291.0, 6291.276503202007, 6299.716512756845, 6304.905426418699, 6007.068904319837, 6081.0, 6171.290301615371, 6175.876482470415, 6179.227596550431, 6164.722587483664, 6184.041473571604, 6189.195404597373, 6189.381061893775, 6200.286966998853, 6036.245469316335, 6039.124326972652, 6046.546509011754, 6047.576669633084, 6049.122877182872, 5919.0, 6021.0, 6037.18498002764, 6049.304330002106, 6050.394960503914, 5865.413458654271, 5879.265091863517, 5885.705714714333, 5886.882262354643, 5887.970560147171, 4378.412850253109, 5825.359210486928, 5861.648301101947, 5962.135490353961, 5990.9700451497465, 5933.931892259262, 5976.940230597638, 5986.790462333972, 5990.161377407254, 5990.4908082813445], "value": 5667.532527236367, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [452.3170013279947, 452.35765213396974, 472.89123501594656, 491.8721132505548, 504.2612572581169, 36379.0, 37907.62092379075, 38078.04804879876, 38185.09074546269, 38279.42580861289, 31300.0, 39251.60748392515, 39259.0185245369, 39267.0183245419, 39415.0, 8859.689910853105, 9160.45037297761, 9198.632054717804, 9220.585073671667, 9224.215941644934, 7970.362371010306, 9200.585973631203, 9361.76595585109, 9364.0, 9371.0, 9329.11373419524, 9374.0, 9393.483358415284, 9399.24806015517, 9435.386699864486, 9278.39690420124, 9356.438613683133, 9368.156865882036, 9378.577963991602, 9378.765530861714, 9109.77018102561, 9118.224950879117, 9232.261419086457, 9247.72256832296, 9256.028117047748, 36808.44787328192, 37194.44208336877, 37237.62762372369, 37313.0, 37341.62658373436, 3323.0, 3398.507216453625, 3455.58532976042, 3462.5844898612077, 3608.963910360915, 26874.865625671748, 27109.1867243983, 27273.0, 27279.590806137923, 29367.55948660772, 3138.952915706266, 3169.6513383527795, 3221.855016524262, 3305.289362787012, 3346.548215990859, 473188.0, 476680.0, 477369.6131519355, 477758.0, 478463.0, 45809.541904580525, 54981.900361992535, 62315.6884215576, 63068.68465657642, 63962.6801865997, 63093.0, 66966.66516667386, 67124.0, 67149.66425167843, 67376.0, 15629.3748250071, 15840.207989600474, 15864.0, 15867.761983570255, 15870.52388428349, 11992.940035299767, 12096.637100886988, 12106.394680265952, 12120.0, 12124.333161676017, 19180.232790688497, 19292.710609340873, 19302.22791088341, 19457.1085782844, 19502.512437189183, 6544.280129185786, 8007.6796928123385, 8089.676412943416, 8092.190780921978, 8148.496146656995, 864.26105679643, 923.1553128887127, 976.272676855747, 989.5645915797027, 1002.8144793213279, 27432.588511172347, 27833.02584409536, 29061.564076538867, 29672.258193545335, 29744.10767676973, 986.7286496213599, 1005.7787286796893, 1021.7088129882948, 1043.4313299251926, 1169.351010189336, 11062.170337224741, 12153.331566763736, 13118.147320424332, 13711.862881371258, 13860.653483662989, 647132.0, 649367.0, 652709.0, 653971.0, 654551.0, 47237.0, 47695.79954605948, 47729.761351193025, 47780.80547986328, 47854.28218576724, 1314.3165553911913, 1353.9796903046463, 1378.2488543743484, 1379.0071148772868, 1382.640513466506, 21706.0, 25230.873845630653, 26117.73882261153, 26534.203973880816, 26694.59958100591, 43107.0, 43721.0, 43784.56215437805, 43825.0, 43844.34233486564, 496698.0, 595070.0, 596743.0, 597079.0, 597234.1659500444, 23352.0, 23923.531087991796, 24061.157859474835, 24249.03003879825, 24371.0, 28201.858990704914, 38418.84743457701, 39204.41193382045, 39401.211975760896, 39832.0, 29875.701242987292, 30691.46542672813, 33123.0, 33900.66099338975, 34153.829230853684, 32736.83631581827, 32925.15790728215, 33777.662223377454, 33917.982460526226, 34437.48343774795, 28475.43049138964, 29175.7082429173, 32947.67052329542, 39991.40012899867, 40667.7966610165, 39051.02372440712, 39369.60630393659, 40013.599864000986, 40639.0, 41166.0, 25812.35469113287, 29727.51362431913, 30849.845750771103, 31089.533656995616, 31616.367672646877], "value": 18988.34021325347, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [349.1898794796071, 367.0, 368.4694040581562, 374.9756265842721, 394.3158619794656, 14210.786838197422, 14640.926795366022, 14873.256337183151, 19718.0, 20243.760998240246, 19255.518612034706, 19350.516237094078, 19389.90305048473, 19612.803871961278, 19884.0, 5421.728913554316, 5750.539956803466, 5754.251947246873, 5755.712214389285, 5767.480926716595, 4567.292069729181, 5456.699881506515, 5596.664200148004, 5658.405867383927, 5667.979763642543, 5611.466910643482, 5613.2422123013275, 5622.859428514299, 5625.606207565472, 5639.802606908759, 5030.245463180516, 5091.821786237464, 5652.971735141298, 5657.462541058593, 5663.4336566343445, 4956.826511072131, 4971.403431588196, 4975.651704380694, 4979.875503112451, 4981.900361992776, 21705.348839534683, 22570.774292257032, 22820.087196512286, 23153.652695209585, 23180.768192318028, 2230.3754948614414, 2444.8899799509063, 2792.3577577157166, 2867.483852906476, 2878.913632591026, 9626.037396260388, 9667.419954802665, 9687.41875487473, 9847.359921605073, 10241.23190760696, 1763.6649036683118, 1776.3782676063404, 1786.553361659585, 1822.735703323024, 1868.3087257714596, 105804.0, 109085.0, 111721.88278117197, 112609.0, 115686.0, 10762.40806755636, 10861.239713220157, 10968.106292542194, 11038.668839934815, 11222.66332010041, 11252.324860508232, 13720.588382348546, 13734.313284335743, 13738.65653358674, 13796.241206733726, 7883.724069657649, 8014.759557213293, 8040.517568945882, 8196.959015204886, 8201.548914809624, 7886.250806173404, 7889.329407000355, 7889.763307100796, 8069.798255043671, 8078.7172448964, 4093.7718684394563, 4228.76741779199, 4349.325854492558, 4363.650907927358, 4544.409226800561, 1684.6041180322613, 1898.1553208822218, 1925.5667474818333, 1943.7278780970676, 1951.5413877739004, 852.5481494807761, 880.2034159086037, 893.2362829780444, 905.42053086025, 973.8733964584559, 19288.324908628132, 20143.697844532344, 20295.188192472135, 20311.898440507703, 20334.694979575317, 907.6051917415945, 927.6335847340398, 940.7507010642186, 963.3016063354012, 986.6645340584092, 9143.94844592867, 9722.708318750449, 10038.297319187586, 10395.688129356131, 10704.250702450909, 488488.0, 500103.0, 507819.7654070384, 509043.0, 519394.0, 101181.0, 114256.0, 114909.42545287387, 117526.4123679376, 120741.18888216684, 925.3522534226069, 926.9490178040272, 969.6751588218026, 971.315222767961, 973.6105557776946, 16425.0, 17781.822181778018, 17831.910840445715, 18116.0, 18354.90822545879, 53193.73403132959, 53588.73205633947, 53690.0, 54947.0, 55325.44674553203, 77136.61431692805, 77495.0, 78163.82754258804, 78478.21521784937, 81981.59009204915, 7783.766487005399, 7801.648925798239, 7924.564148971631, 8007.0, 8155.166868706907, 9947.353422027545, 12047.578334758242, 12450.688732781753, 12575.748485030432, 12736.490540378652, 10459.797123330914, 10716.678499645022, 10774.407407592658, 10859.891401085888, 11012.284201526874, 10992.120630349393, 11053.834192486958, 11103.666889993314, 11351.602693905674, 11545.249558778652, 9330.906690933276, 11452.312861228353, 11506.597269095542, 11538.711532211762, 11748.88251117478, 7693.0, 7778.805529861799, 8060.234277743605, 8064.35485161174, 8263.380246481658, 8182.140875208018, 10485.26603137773, 10817.459127043774, 11236.044936180517, 11237.269577477438], "value": 9128.66559439175, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1269.8539667938185, 1487.9107253564787, 1650.909199994, 1864.94405167845, 1879.2483006797288, 7869.173736757644, 8189.672413103469, 8189.95905020474, 8205.138460461647, 8214.835703285931, 7274.96362518188, 7577.507462014965, 7582.241775822415, 7656.617169141535, 7660.233976602337, 2067.8552501324953, 2195.5279614882797, 2261.9208327708534, 2266.5126997695493, 2276.0781883337277, 2261.796438320551, 2266.603344414721, 2273.522560262347, 2274.6701728249395, 2275.920342788003, 2267.6825244465704, 2288.0504590594883, 2291.3698732848434, 2294.9885250573725, 2295.426143464134, 2218.7892150245702, 2280.09936075251, 2282.2240438251074, 2287.645414960684, 2289.1873384948435, 2250.0099956019462, 2280.5552917181158, 2289.0843662534953, 2301.160076572056, 2318.2813327868416, 8223.629936652866, 8290.41967062306, 8302.335813134996, 8308.584570771438, 8315.625796839096, 4967.652264341497, 5108.233764935252, 5147.897042059175, 5157.020166168453, 5162.535371816517, 3263.2820779428725, 3421.298633780069, 3503.562054743157, 3511.455724362728, 3521.5245941798044, 986.6497393425302, 1016.1464369929295, 1052.3054783842626, 1057.8148823955853, 1066.3441983180367, 10627.946860265727, 10673.946630266877, 10683.0, 10683.30558513694, 10690.46547672621, 4258.68059895509, 4501.009777848868, 4583.449986001635, 4592.402987611656, 4640.118377508263, 9202.17180453749, 9205.401648892801, 9220.631174752934, 9223.44659320443, 9226.26189904806, 4635.629149668019, 5041.2438134280155, 5143.3827940646615, 5143.922841157386, 5147.202183661539, 4418.381426600278, 4610.847288177955, 4650.093231819763, 4663.48701642819, 4736.360591320127, 3376.5104059911178, 3702.315071711742, 3711.6102809205186, 3798.601146879593, 3874.6512813846325, 982.415462799633, 1236.2953116723381, 1247.6444213399138, 1250.1873782041746, 1260.6281147061638, 745.3180339988958, 745.7464462082918, 875.4090988582681, 885.5350940756026, 928.0997432490474, 5448.645838020516, 5648.181013752983, 5823.941760582425, 5836.649801011953, 5963.4931030862, 967.9951600241953, 991.4398364923795, 999.9001098791417, 1025.2259544044198, 1046.4715318763976, 3574.803385813805, 3644.653757892996, 3677.6279299851444, 3734.7012239020814, 3778.3954567269107, 9673.613055477843, 9903.60385584583, 9999.700008999742, 10020.0, 10048.748781280527, 9244.90755092454, 9683.41899486033, 9690.660826871026, 9982.80034399308, 9987.400755954666, 7551.660175292125, 7661.885071723928, 7671.079470463468, 7772.805679858049, 7777.961110194526, 5178.249153872743, 5489.615726899079, 5498.367687715964, 5737.770489180386, 5810.418958104325, 9094.136057074567, 9178.403403778733, 9185.173334399937, 9187.989321174531, 9272.350935434455, 9257.43785354459, 9315.534223288943, 9392.76518087026, 9473.052694730748, 9545.856812147962, 6511.218653761579, 6625.867482650417, 6732.629705366252, 7040.753573624898, 7118.039064726403, 7766.145723970246, 7861.60691965388, 7920.920790792249, 7929.326007289445, 7943.841123177389, 7044.8591028178125, 7126.893096603451, 7138.714451421885, 7148.535345202546, 7152.570845749272, 6293.433590976834, 6876.553024053621, 6986.091808064919, 6989.161300643752, 7087.220405755466, 5934.643921364732, 6287.748490060347, 6347.523935704933, 6348.333424990309, 6353.491720662243, 4669.159551280802, 4820.831270905502, 4865.464798872192, 4865.610751139971, 4929.605631549396, 4752.809887604457, 4754.263089221176, 4822.927656085231, 4893.6085113191575, 4908.0], "value": 4353.014153569203, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [339.6111452387017, 345.08208166277706, 352.3921235868127, 357.5334188883509, 360.79074137000526, 4526.207913615121, 5055.800726683895, 5194.07847075458, 5262.578993680506, 5279.445658205891, 4758.9292409208, 5055.24171374294, 5169.767360468779, 5188.9881473112755, 5222.738863056841, 3344.4983252512075, 4108.897277568055, 4120.587941205886, 4121.052158003646, 4139.730917490361, 3466.1282907230066, 3473.1838018065732, 4113.136241389312, 4136.503619565641, 4171.791410429481, 3720.572134204574, 3771.0, 4065.154391925649, 4074.7962601869935, 4108.52751933527, 3386.8814591489304, 4019.577944315863, 4040.0707837197315, 4066.552679205285, 4095.0, 2869.9426011479864, 3588.3804822479474, 3594.3530164570366, 3619.6561326673923, 3629.528161339009, 3864.0, 4636.774348312829, 4641.95358046422, 4814.748165477001, 5219.791208351661, 4365.4106695596, 4645.814167433298, 4654.020198387727, 4657.4876763555985, 4680.625549955996, 5259.605529585297, 5477.233187353734, 5511.614187006911, 5531.751071201806, 5587.720613969326, 3252.6259480159847, 3466.77465964714, 3679.3929001714687, 3934.645881870617, 3961.207758448322, 5195.454477279869, 5698.974184646762, 5788.450097240755, 5819.272590926134, 5882.823515294548, 3787.337215987191, 3793.4878791363367, 3803.5816060233906, 3808.333541630204, 3821.464994900716, 3129.342838003999, 3433.4678124890684, 3732.234891847165, 3759.3233218020473, 3871.9806400967814, 4216.030313028021, 4441.755703436277, 4465.553444655508, 4481.955180448219, 4490.797914093873, 4763.666543342002, 5403.919216156784, 5470.398256191816, 5502.0, 5514.200440936042, 1102.327580176092, 1140.914431417647, 1179.9262670969376, 1200.0459634590522, 1200.9131735779133, 815.8740937506203, 818.2349503214394, 844.6325848256029, 848.9133908597006, 857.2327766648814, 4925.694690906913, 4947.876303092451, 4964.287320874292, 4967.554039135026, 4967.776450059756, 4652.092642016757, 4688.624910007191, 4804.774782430528, 5362.329708786402, 5439.5104440600535, 4696.60078893291, 4875.5124487551675, 5187.273781670569, 5262.55268302191, 5279.683219006872, 2502.737212592688, 2835.560488124344, 2843.5829544886683, 2881.7406433420674, 2920.036387991958, 4740.786664600101, 5007.799688012438, 5460.3244410239495, 5467.687754938786, 5723.627964182315, 4997.975010124999, 5021.924671129936, 5150.356205474316, 5210.0, 5538.944610553843, 4866.659333846667, 5062.569681577033, 5298.682079075268, 5393.622446428869, 5670.213882626959, 4904.8773780655765, 5115.109962870966, 5434.049041417815, 5453.7818487260065, 5504.311961004874, 4844.539768721966, 5361.7561375368905, 5393.568514518907, 5425.430329815313, 5430.456954304538, 4936.802527898843, 5071.847844564669, 5427.538659214011, 5561.8609534760335, 5700.743466543932, 2807.971920280771, 3501.509788629645, 3571.0536707771944, 3602.9283162181327, 3618.9866837285604, 4509.842155524541, 4851.80592776285, 5183.688978661293, 5263.842084737464, 5423.014084647702, 4623.491415944311, 4778.829186849244, 5135.998480296306, 5279.656822306538, 5301.522862942354, 4465.910681786281, 5219.086659834438, 5268.867193553405, 5549.833504994856, 5615.522680572197, 4547.976705241426, 4788.899821689889, 4835.685680430761, 5022.3219865317715, 5419.701916394636, 4595.517470665532, 5352.625316227826, 5409.350148204835, 5471.644343117685, 5542.695151766691, 5427.945720542744, 5467.890642187213, 5478.506934375925, 5484.765927666243, 5527.977324195037], "value": 3939.9758407492327, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [738.8633102875968, 748.3302444312341, 776.1113525013859, 813.7843471480057, 834.8705950577655, 14145.0, 14594.124352538855, 14616.0, 14649.0, 14671.706565868677, 14086.366113524891, 14244.21656808875, 14267.215303158322, 14269.215193164371, 14274.857251427482, 687.8693048320828, 710.0237173885907, 855.3926712034432, 864.8616221404592, 886.5434301334818, 870.6604424274537, 876.0844917061686, 879.4723166100353, 886.9822603547925, 899.6446403670546, 941.5339406993547, 944.0417975754615, 945.7210123013693, 949.126803340928, 949.2500924269854, 730.82825535999, 756.1455555222618, 942.0908822985851, 944.0182210501071, 945.6170251048268, 557.8549577109981, 558.3829867995847, 560.117814442255, 675.2909445082644, 696.9581825090511, 14736.926315368462, 14768.113913165136, 14770.483033093898, 14779.408823647149, 14827.0362426443, 13043.673908152277, 13046.478140874353, 13188.406521706545, 13361.86638133616, 13399.464021439131, 10519.52662130206, 12916.677083072904, 13150.079494435393, 13230.536931207362, 13600.591982240549, 4374.387585737999, 4490.483594386622, 4503.594676479132, 4572.404683636092, 4630.259158534652, 110785.9999999992, 114703.0, 118884.40557797241, 120523.39738301252, 120835.0, 10756.677299681021, 10782.46087695612, 10828.68443067235, 11122.777544449065, 11327.263727857828, 20796.480087997617, 20841.0, 21023.26418575373, 21050.684239736416, 21362.359129226148, 7616.314531692064, 7673.808154796175, 7708.614569271514, 7766.301032906952, 7861.685532578747, 4255.319148936205, 4261.786910654455, 4264.57354264571, 4270.530241673413, 4401.207782599099, 3324.5844269466315, 3392.9660703393142, 3397.7451691123265, 3421.9657803422147, 3422.401079811023, 596.6241268001131, 618.9164462797555, 624.3849807939176, 631.2015300644688, 638.1512588257699, 534.7967772246521, 659.9109120268798, 683.7880257120308, 689.7482418917059, 697.1494776372817, 8361.581920903931, 9372.718818435458, 9520.809583808423, 9628.422294662343, 10145.492725363702, 604.7701873288122, 613.7016871815081, 673.2123415603755, 692.9965350173318, 700.0968750312143, 4530.61489773373, 4651.953480465219, 4654.8096391282015, 4656.348111264426, 4755.334253204554, 99940.0, 103417.0, 104226.0, 104894.4755276234, 107800.0, 69083.07464671187, 91608.0, 93567.0, 94485.5275723617, 95159.52420237854, 67536.0, 75319.62340188264, 75756.62121689467, 77503.61248193723, 77951.61024194956, 7781.182273372602, 8632.611532480927, 8890.4665720057, 9081.818363632558, 9119.817603648022, 38196.0, 52457.7377113112, 52461.0, 52688.68278293074, 53368.46631533635, 63084.36915630785, 63919.36080639134, 64899.0, 67207.0, 67225.0, 10989.0, 12895.935520322339, 13270.80093798613, 13327.866721332663, 13418.664533386744, 12404.379781011094, 14756.852431475549, 15090.320935558146, 15162.924185379445, 15393.153376563949, 13596.93201533986, 13809.930950345586, 13815.516456923959, 13945.233012184428, 13987.650308742363, 13486.730265394834, 13730.176189428666, 13892.652683682989, 13988.790168147283, 14024.509142179975, 13126.934365328112, 13132.34338283063, 13242.139260948008, 13571.52499662507, 13652.863471365557, 9428.010058943712, 10892.61875834342, 11252.774944500901, 11499.597514086967, 11894.048476121716, 10212.795744085226, 11066.114710823276, 11066.778664426827, 11265.63631369977, 11509.654710358702], "value": 7455.2993021021675, "unit": "score"}], "name": "dromaeo_css", "value": 7076.9700681388, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 116.0, 114.0, 114.0, 113.0, 114.0, 114.0, 113.0, 118.0, 149.0], "value": 117.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 133.0, 124.0, 133.0, 108.0, 108.0, 108.0, 108.0, 129.0, 112.0], "value": 120.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [183.0, 186.0, 181.0, 183.0, 183.0, 184.0, 179.0, 184.0, 186.0, 185.0], "value": 183.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 58.0, 58.0, 88.0, 61.0, 58.0, 58.0, 59.0, 75.0, 58.0], "value": 65.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 69.0, 86.0, 70.0, 70.0, 69.0, 82.0, 70.0, 73.0, 114.0], "value": 78.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 87.0, 89.0, 87.0, 94.0, 130.0, 127.0, 87.0, 89.0, 87.0], "value": 96.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 104.0, 150.0, 125.0, 94.0, 93.0, 94.0, 97.0, 94.0, 94.0], "value": 103.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 87.0, 89.0, 86.0, 87.0, 85.0, 85.0, 88.0, 85.0, 84.0], "value": 86.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 55.0, 53.0, 53.0, 55.0, 55.0, 55.0, 54.0, 55.0, 54.0], "value": 54.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 73.0, 68.0, 78.0, 73.0, 71.0, 70.0, 71.0, 70.0, 69.0], "value": 70.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 85.0, 85.0, 87.0, 90.0, 90.0, 87.0, 87.0, 87.0, 87.0], "value": 87.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 131.0, 139.0, 141.0, 139.0, 177.0, 155.0, 152.0, 202.0, 138.0], "value": 151.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [173.0, 173.0, 229.0, 172.0, 172.0, 170.0, 171.0, 170.0, 171.0, 171.0], "value": 177.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 66.0, 66.0, 65.0, 66.0, 69.0, 82.0, 95.0, 87.0, 77.0], "value": 73.9, "unit": "score"}], "name": "kraken", "value": 1467.5000000000002, "alertThreshold": 2.0}]} 07:17:38 INFO - Return code: 0 07:17:38 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 07:17:38 INFO - # TBPL SUCCESS # 07:17:38 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 07:17:38 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 07:17:38 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 07:17:38 INFO - 2016-12-27 07:17:38.716 screenresolution[1649:10398] starting screenresolution argv=screenresolution get 07:17:38 INFO - 2016-12-27 07:17:38.719 screenresolution[1649:10398] Display 0: 1600x1200x32@60 07:17:38 INFO - 2016-12-27 07:17:38.725 screenresolution[1650:10401] starting screenresolution argv=screenresolution list 07:17:38 INFO - Available Modes on Display 0 07:17:38 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 07:17:38 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 07:17:38 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 07:17:38 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 07:17:38 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 07:17:38 INFO - Intel Iris: 07:17:38 INFO - Chipset Model: Intel Iris 07:17:38 INFO - Type: GPU 07:17:38 INFO - Bus: Built-In 07:17:38 INFO - VRAM (Dynamic, Max): 1536 MB 07:17:38 INFO - Vendor: Intel (0x8086) 07:17:38 INFO - Device ID: 0x0a2e 07:17:38 INFO - Revision ID: 0x0009 07:17:38 INFO - Displays: 07:17:38 INFO - Display: 07:17:38 INFO - Resolution: 1600 x 1200 @ 60 Hz 07:17:38 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 07:17:38 INFO - Display Serial Number: 1600x1200 60 07:17:38 INFO - Main Display: Yes 07:17:38 INFO - Mirror: Off 07:17:38 INFO - Online: Yes 07:17:38 INFO - Rotation: Supported 07:17:38 INFO - Return code: 0 07:17:38 INFO - Running post-action listener: _resource_record_post_action 07:17:38 INFO - [mozharness: 2016-12-27 15:17:38.966827Z] Finished run-tests step (success) 07:17:38 INFO - Running post-run listener: _resource_record_post_run 07:17:39 INFO - Total resource usage - Wall time: 1202s; CPU: 54.0%; Read bytes: 31987712; Write bytes: 553013760; Read time: 296; Write time: 1865 07:17:39 INFO - TinderboxPrint: CPU usage
53.9% 07:17:39 INFO - TinderboxPrint: I/O read bytes / time
31,987,712 / 296 07:17:39 INFO - TinderboxPrint: I/O write bytes / time
553,013,760 / 1,865 07:17:39 INFO - TinderboxPrint: CPU idle
2,218.8 (46.1%) 07:17:39 INFO - TinderboxPrint: CPU system
963.1 (20.0%) 07:17:39 INFO - TinderboxPrint: CPU user
1,627.8 (33.8%) 07:17:39 INFO - TinderboxPrint: Swap in / out
278,593,536 / 0 07:17:39 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 248197632; Write bytes: 240562176; Read time: 18072; Write time: 436 07:17:39 INFO - run-tests - Wall time: 1181s; CPU: 54.0%; Read bytes: 28112896; Write bytes: 298428928; Read time: 263; Write time: 1300 07:17:39 INFO - Running post-run listener: _upload_blobber_files 07:17:39 INFO - Blob upload gear active. 07:17:39 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 07:17:39 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 07:17:39 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'] 07:17:39 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 07:17:39 INFO - (blobuploader) - INFO - Open directory for files ... 07:17:39 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 07:17:39 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:17:39 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:17:41 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 07:17:41 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:17:41 INFO - (blobuploader) - INFO - Done attempting. 07:17:41 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 07:17:41 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:17:41 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:17:41 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 07:17:41 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:17:41 INFO - (blobuploader) - INFO - Done attempting. 07:17:41 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 07:17:41 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:17:41 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:17:42 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 07:17:42 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:17:42 INFO - (blobuploader) - INFO - Done attempting. 07:17:42 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 07:17:42 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:17:42 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:17:45 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 07:17:45 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:17:45 INFO - (blobuploader) - INFO - Done attempting. 07:17:45 INFO - (blobuploader) - INFO - Iteration through files over. 07:17:45 INFO - Return code: 0 07:17:45 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 07:17:45 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 07:17:45 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/0bd68a69c21b9e674d4892a871ebde7761ed67e4abf5aa4772178955a7fbcb4b323e481b589e0d747680388b9ada3a8f97e7b4352d716d30f6064eacf6c16cdf", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/4e02a81cdce1592dbe84b25dc291cf36c7e86aa1b8d0edcbd6e5c965645488034e3dd8633a03140660137e0fb19846af1453ced84b6c32d28dcf203c0778a7e6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/74ffd243dc225e9e69943c8112abc468d85d42753b476bf1e93a01cdf90ddc54d660060247fea741744374449043f931df29fa1de58587721cfe2b5e3b06e2dd", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/87f59bee93f661537d7d4e541dc954823f6ef1e09645c036393105dfb48f4e98c2f12b1777c1b084c16283b6350c28a6ff8296a3168f2929f05a2b3788a1afb8"} 07:17:45 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 07:17:45 INFO - Writing to file /builds/slave/test/properties/blobber_files 07:17:45 INFO - Contents: 07:17:45 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/0bd68a69c21b9e674d4892a871ebde7761ed67e4abf5aa4772178955a7fbcb4b323e481b589e0d747680388b9ada3a8f97e7b4352d716d30f6064eacf6c16cdf", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/4e02a81cdce1592dbe84b25dc291cf36c7e86aa1b8d0edcbd6e5c965645488034e3dd8633a03140660137e0fb19846af1453ced84b6c32d28dcf203c0778a7e6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/74ffd243dc225e9e69943c8112abc468d85d42753b476bf1e93a01cdf90ddc54d660060247fea741744374449043f931df29fa1de58587721cfe2b5e3b06e2dd", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/87f59bee93f661537d7d4e541dc954823f6ef1e09645c036393105dfb48f4e98c2f12b1777c1b084c16283b6350c28a6ff8296a3168f2929f05a2b3788a1afb8"} 07:17:45 INFO - Running post-run listener: copy_logs_to_upload_dir 07:17:45 INFO - Copying logs to upload dir... 07:17:45 INFO - mkdir: /builds/slave/test/build/upload/logs 07:17:45 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1265.348080 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 5 secs) (at 2016-12-27 07:17:45.855511) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-27 07:17:45.858782) ========= 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.Y5sy0yW6bu/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.c2kW9j24SS/Listeners TMPDIR=/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/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/0bd68a69c21b9e674d4892a871ebde7761ed67e4abf5aa4772178955a7fbcb4b323e481b589e0d747680388b9ada3a8f97e7b4352d716d30f6064eacf6c16cdf", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/4e02a81cdce1592dbe84b25dc291cf36c7e86aa1b8d0edcbd6e5c965645488034e3dd8633a03140660137e0fb19846af1453ced84b6c32d28dcf203c0778a7e6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/74ffd243dc225e9e69943c8112abc468d85d42753b476bf1e93a01cdf90ddc54d660060247fea741744374449043f931df29fa1de58587721cfe2b5e3b06e2dd", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/87f59bee93f661537d7d4e541dc954823f6ef1e09645c036393105dfb48f4e98c2f12b1777c1b084c16283b6350c28a6ff8296a3168f2929f05a2b3788a1afb8"} build_url:https://queue.taskcluster.net/v1/task/MCwqTyqFQWeJ0jsOr460oA/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010585 build_url: 'https://queue.taskcluster.net/v1/task/MCwqTyqFQWeJ0jsOr460oA/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/0bd68a69c21b9e674d4892a871ebde7761ed67e4abf5aa4772178955a7fbcb4b323e481b589e0d747680388b9ada3a8f97e7b4352d716d30f6064eacf6c16cdf", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/4e02a81cdce1592dbe84b25dc291cf36c7e86aa1b8d0edcbd6e5c965645488034e3dd8633a03140660137e0fb19846af1453ced84b6c32d28dcf203c0778a7e6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/74ffd243dc225e9e69943c8112abc468d85d42753b476bf1e93a01cdf90ddc54d660060247fea741744374449043f931df29fa1de58587721cfe2b5e3b06e2dd", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/87f59bee93f661537d7d4e541dc954823f6ef1e09645c036393105dfb48f4e98c2f12b1777c1b084c16283b6350c28a6ff8296a3168f2929f05a2b3788a1afb8"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-27 07:17:45.892246) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-27 07:17:45.892595) ========= 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.Y5sy0yW6bu/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.c2kW9j24SS/Listeners TMPDIR=/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/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.005231 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-27 07:17:45.941401) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-27 07:17:45.941735) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-27 07:17:45.942225) ========= ========= Total master_lag: 0.21 =========