builder: mozilla-beta_yosemite_r7_devedition_test-dromaeojs slave: t-yosemite-r7-0346 starttime: 1495733312.54 results: success (0) buildid: 20170525093444 builduid: 6cea08e737b8452ab18ddb0c798c0ddc revision: d3ce3e3812c9372a12b6416f0ceedd2e768b5277 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-05-25 10:28:32.536417) ========= master: http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-05-25 10:28:32.536827) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-25 10:28:32.537097) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-25 10:28:32.561830) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-25 10:28:32.562147) ========= 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.d28168JV24/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.6KEp177nmv/Listeners TMPDIR=/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-05-25 10:28:32-- 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% 65.3M=0s 2017-05-25 10:28:32 (65.3 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.099469 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-25 10:28:32.684715) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-25 10:28:32.685107) ========= 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.d28168JV24/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.6KEp177nmv/Listeners TMPDIR=/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/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.041284 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-25 10:28:32.746401) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-25 10:28:32.746734) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev d3ce3e3812c9372a12b6416f0ceedd2e768b5277 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-beta --rev d3ce3e3812c9372a12b6416f0ceedd2e768b5277 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.d28168JV24/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.6KEp177nmv/Listeners TMPDIR=/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-05-25 10:28:32,837 truncating revision to first 12 chars 2017-05-25 10:28:32,837 Setting DEBUG logging. 2017-05-25 10:28:32,837 attempt 1/10 2017-05-25 10:28:32,837 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/d3ce3e3812c9?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-05-25 10:28:33,871 unpacking tar archive at: mozilla-beta-d3ce3e3812c9/testing/mozharness/ program finished with exit code 0 elapsedTime=1.314533 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-25 10:28:34.083602) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-25 10:28:34.083903) ========= script_repo_revision: d3ce3e3812c9372a12b6416f0ceedd2e768b5277 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-25 10:28:34.084277) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-25 10:28:34.084554) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-25 10:28:34.103043) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2017-05-25 10:28:34.103516) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 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', 'Mozilla-Beta', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Beta'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.d28168JV24/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.6KEp177nmv/Listeners TMPDIR=/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 10:28:34 INFO - MultiFileLogger online at 20170525 10:28:34 in /builds/slave/test 10:28:34 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 10:28:34 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 10:28:34 INFO - {'append_to_log': False, 10:28:34 INFO - 'base_work_dir': '/builds/slave/test', 10:28:34 INFO - 'blob_upload_branch': 'Mozilla-Beta', 10:28:34 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 10:28:34 INFO - 'branch': 'Mozilla-Beta', 10:28:34 INFO - 'buildbot_json_path': 'buildprops.json', 10:28:34 INFO - 'config_files': ('talos/mac_config.py',), 10:28:34 INFO - 'default_actions': ('clobber', 10:28:34 INFO - 'read-buildbot-config', 10:28:34 INFO - 'download-and-extract', 10:28:34 INFO - 'populate-webroot', 10:28:34 INFO - 'create-virtualenv', 10:28:34 INFO - 'install', 10:28:34 INFO - 'run-tests'), 10:28:34 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 10:28:34 INFO - 'download_minidump_stackwalk': True, 10:28:34 INFO - 'download_symbols': 'ondemand', 10:28:34 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 10:28:34 INFO - 'tooltool.py': '/tools/tooltool.py', 10:28:34 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 10:28:34 INFO - '/tools/misc-python/virtualenv.py')}, 10:28:34 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 10:28:34 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 10:28:34 INFO - 'gecko_profile': False, 10:28:34 INFO - 'gecko_profile_interval': 0, 10:28:34 INFO - 'installer_path': 'installer.exe', 10:28:34 INFO - 'log_level': 'info', 10:28:34 INFO - 'log_name': 'talos', 10:28:34 INFO - 'log_to_console': True, 10:28:34 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 10:28:34 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 10:28:34 INFO - 'opt_config_files': (), 10:28:34 INFO - 'pip_index': False, 10:28:34 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 10:28:34 INFO - 'cmd': ('bash', 10:28:34 INFO - '-c', 10:28:34 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 10:28:34 INFO - 'enabled': True, 10:28:34 INFO - 'halt_on_failure': False, 10:28:34 INFO - 'name': 'check_screen_resolution'},), 10:28:34 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 10:28:34 INFO - 'cmd': ('bash', 10:28:34 INFO - '-c', 10:28:34 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 10:28:34 INFO - 'enabled': True, 10:28:34 INFO - 'halt_on_failure': False, 10:28:34 INFO - 'name': 'check_screen_resolution'},), 10:28:34 INFO - 'run_cmd_checks_enabled': True, 10:28:34 INFO - 'suite': 'dromaeojs', 10:28:34 INFO - 'system_bits': '32', 10:28:34 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 10:28:34 INFO - 'title': 't-yosemite-r7-0346', 10:28:34 INFO - 'tooltool_cache': '/builds/tooltool_cache', 10:28:34 INFO - 'use_talos_json': True, 10:28:34 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 10:28:34 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 10:28:34 INFO - 'work_dir': 'build'} 10:28:34 INFO - [mozharness: 2017-05-25 17:28:34.302077Z] Running clobber step. 10:28:34 INFO - Running pre-action listener: _resource_record_pre_action 10:28:34 INFO - Running main action method: clobber 10:28:34 INFO - rmtree: /builds/slave/test/build 10:28:34 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 10:28:36 INFO - Running post-action listener: _resource_record_post_action 10:28:36 INFO - [mozharness: 2017-05-25 17:28:36.717768Z] Finished clobber step (success) 10:28:36 INFO - [mozharness: 2017-05-25 17:28:36.718001Z] Running read-buildbot-config step. 10:28:36 INFO - Running pre-action listener: _resource_record_pre_action 10:28:36 INFO - Running main action method: read_buildbot_config 10:28:36 INFO - Using buildbot properties: 10:28:36 INFO - { 10:28:36 INFO - "project": "", 10:28:36 INFO - "product": "firefox", 10:28:36 INFO - "script_repo_revision": "production", 10:28:36 INFO - "builddir": "mozilla-beta_yosemite_r7_devedition_test-dromaeojs", 10:28:36 INFO - "repository": "", 10:28:36 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 DevEdition mozilla-beta talos dromaeojs", 10:28:36 INFO - "buildid": "20170525093444", 10:28:36 INFO - "pgo_build": "False", 10:28:36 INFO - "basedir": "/builds/slave/test", 10:28:36 INFO - "buildnumber": 6, 10:28:36 INFO - "slavename": "t-yosemite-r7-0346", 10:28:36 INFO - "master": "http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/", 10:28:36 INFO - "platform": "yosemite_r7_devedition", 10:28:36 INFO - "scheduler": "tests-mozilla-beta-macosx64-devedition-talos", 10:28:36 INFO - "branch": "mozilla-beta", 10:28:36 INFO - "repo_path": "releases/mozilla-beta", 10:28:36 INFO - "revision": "d3ce3e3812c9372a12b6416f0ceedd2e768b5277", 10:28:36 INFO - "stage_platform": "macosx64-devedition", 10:28:36 INFO - "builduid": "6cea08e737b8452ab18ddb0c798c0ddc", 10:28:36 INFO - "slavebuilddir": "test" 10:28:36 INFO - } 10:28:36 INFO - Found installer url https://queue.taskcluster.net/v1/task/KlIGQZy6RByOmf-6GVai7w/artifacts/public/build/firefox-54.0.en-US.mac.dmg. 10:28:36 INFO - Running post-action listener: _resource_record_post_action 10:28:36 INFO - [mozharness: 2017-05-25 17:28:36.723929Z] Finished read-buildbot-config step (success) 10:28:36 INFO - [mozharness: 2017-05-25 17:28:36.724127Z] Running download-and-extract step. 10:28:36 INFO - Running pre-action listener: _resource_record_pre_action 10:28:36 INFO - Running main action method: download_and_extract 10:28:36 INFO - mkdir: /builds/slave/test/build/tests 10:28:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:28:36 INFO - trying https://queue.taskcluster.net/v1/task/KlIGQZy6RByOmf-6GVai7w/artifacts/public/build/firefox-54.0.en-US.mac.test_packages.json 10:28:36 INFO - Downloading https://queue.taskcluster.net/v1/task/KlIGQZy6RByOmf-6GVai7w/artifacts/public/build/firefox-54.0.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-54.0.en-US.mac.test_packages.json 10:28:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/KlIGQZy6RByOmf-6GVai7w/artifacts/public/build/firefox-54.0.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-54.0.en-US.mac.test_packages.json'}, attempt #1 10:28:37 INFO - Downloaded 1287 bytes. 10:28:37 INFO - Reading from file /builds/slave/test/build/firefox-54.0.en-US.mac.test_packages.json 10:28:37 INFO - Using the following test package requirements: 10:28:37 INFO - {u'awsy': [u'firefox-54.0.en-US.mac.common.tests.zip', 10:28:37 INFO - u'firefox-54.0.en-US.mac.awsy.tests.zip'], 10:28:37 INFO - u'common': [u'firefox-54.0.en-US.mac.common.tests.zip'], 10:28:37 INFO - u'cppunittest': [u'firefox-54.0.en-US.mac.common.tests.zip', 10:28:37 INFO - u'firefox-54.0.en-US.mac.cppunittest.tests.zip'], 10:28:37 INFO - u'gtest': [u'firefox-54.0.en-US.mac.common.tests.zip', 10:28:37 INFO - u'firefox-54.0.en-US.mac.gtest.tests.zip'], 10:28:37 INFO - u'jittest': [u'firefox-54.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 10:28:37 INFO - u'mochitest': [u'firefox-54.0.en-US.mac.common.tests.zip', 10:28:37 INFO - u'firefox-54.0.en-US.mac.mochitest.tests.zip'], 10:28:37 INFO - u'mozbase': [u'firefox-54.0.en-US.mac.common.tests.zip'], 10:28:37 INFO - u'reftest': [u'firefox-54.0.en-US.mac.common.tests.zip', 10:28:37 INFO - u'firefox-54.0.en-US.mac.reftest.tests.zip'], 10:28:37 INFO - u'talos': [u'firefox-54.0.en-US.mac.common.tests.zip', 10:28:37 INFO - u'firefox-54.0.en-US.mac.talos.tests.zip'], 10:28:37 INFO - u'web-platform': [u'firefox-54.0.en-US.mac.common.tests.zip', 10:28:37 INFO - u'firefox-54.0.en-US.mac.web-platform.tests.zip'], 10:28:37 INFO - u'xpcshell': [u'firefox-54.0.en-US.mac.common.tests.zip', 10:28:37 INFO - u'firefox-54.0.en-US.mac.xpcshell.tests.zip']} 10:28:37 INFO - Downloading packages: [u'firefox-54.0.en-US.mac.common.tests.zip'] for test suite category: common 10:28:37 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/KlIGQZy6RByOmf-6GVai7w/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip 10:28:37 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/KlIGQZy6RByOmf-6GVai7w/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip'}, attempt #1 10:28:37 INFO - Fetch https://queue.taskcluster.net/v1/task/KlIGQZy6RByOmf-6GVai7w/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip into memory 10:28:39 INFO - Expected file size: 51965427 10:28:39 INFO - Obtained file size: 51965427 10:28:55 INFO - Downloading packages: [u'firefox-54.0.en-US.mac.common.tests.zip', u'firefox-54.0.en-US.mac.talos.tests.zip'] for test suite category: talos 10:28:55 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/KlIGQZy6RByOmf-6GVai7w/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip 10:28:55 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/KlIGQZy6RByOmf-6GVai7w/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip'}, attempt #1 10:28:55 INFO - Fetch https://queue.taskcluster.net/v1/task/KlIGQZy6RByOmf-6GVai7w/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip into memory 10:28:57 INFO - Expected file size: 51965427 10:28:57 INFO - Obtained file size: 51965427 10:29:12 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/KlIGQZy6RByOmf-6GVai7w/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip 10:29:12 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/KlIGQZy6RByOmf-6GVai7w/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip'}, attempt #1 10:29:12 INFO - Fetch https://queue.taskcluster.net/v1/task/KlIGQZy6RByOmf-6GVai7w/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip into memory 10:29:13 INFO - Expected file size: 14404449 10:29:13 INFO - Obtained file size: 14404449 10:29:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:29:14 INFO - trying https://queue.taskcluster.net/v1/task/KlIGQZy6RByOmf-6GVai7w/artifacts/public/build/firefox-54.0.en-US.mac.dmg 10:29:14 INFO - Downloading https://queue.taskcluster.net/v1/task/KlIGQZy6RByOmf-6GVai7w/artifacts/public/build/firefox-54.0.en-US.mac.dmg to /builds/slave/test/build/firefox-54.0.en-US.mac.dmg 10:29:14 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/KlIGQZy6RByOmf-6GVai7w/artifacts/public/build/firefox-54.0.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-54.0.en-US.mac.dmg'}, attempt #1 10:29:16 INFO - Downloaded 74890819 bytes. 10:29:16 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/KlIGQZy6RByOmf-6GVai7w/artifacts/public/build/firefox-54.0.en-US.mac.dmg 10:29:16 INFO - mkdir: /builds/slave/test/properties 10:29:16 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 10:29:16 INFO - Writing to file /builds/slave/test/properties/build_url 10:29:16 INFO - Contents: 10:29:16 INFO - build_url:https://queue.taskcluster.net/v1/task/KlIGQZy6RByOmf-6GVai7w/artifacts/public/build/firefox-54.0.en-US.mac.dmg 10:29:16 INFO - Running post-action listener: _resource_record_post_action 10:29:16 INFO - Running post-action listener: set_extra_try_arguments 10:29:16 INFO - [mozharness: 2017-05-25 17:29:16.808797Z] Finished download-and-extract step (success) 10:29:16 INFO - [mozharness: 2017-05-25 17:29:16.808960Z] Running populate-webroot step. 10:29:16 INFO - Running pre-action listener: _resource_record_pre_action 10:29:16 INFO - Running main action method: populate_webroot 10:29:16 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 10:29:16 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 10:29:16 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 10:29:16 INFO - '--tppagecycles', 10:29:16 INFO - '7'], 10:29:16 INFO - 'tests': ['tsvgm']}}, 10:29:16 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 10:29:16 INFO - 'tests': ['tresize', 'tcanvasmark']}, 10:29:16 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 10:29:16 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 10:29:16 INFO - 'tests': ['dromaeo_css', 'kraken']}, 10:29:16 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 10:29:16 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 10:29:16 INFO - 'talos_options': ['--disable-e10s'], 10:29:16 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:29:16 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 10:29:16 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:29:16 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 10:29:16 INFO - 'talos_options': ['--disable-e10s'], 10:29:16 INFO - 'tests': ['damp', 'tps']}, 10:29:16 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 10:29:16 INFO - 'tests': ['damp', 'tps']}, 10:29:16 INFO - 'g3': {'talos_options': ['--disable-e10s'], 10:29:16 INFO - 'tests': ['dromaeo_dom']}, 10:29:16 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 10:29:16 INFO - 'g4': {'talos_options': ['--disable-e10s'], 10:29:16 INFO - 'tests': ['basic_compositor_video']}, 10:29:16 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 10:29:16 INFO - 'other': {'talos_options': ['--disable-e10s'], 10:29:16 INFO - 'tests': ['a11yr', 10:29:16 INFO - 'ts_paint', 10:29:16 INFO - 'tpaint', 10:29:16 INFO - 'sessionrestore', 10:29:16 INFO - 'sessionrestore_no_auto_restore', 10:29:16 INFO - 'tabpaint']}, 10:29:16 INFO - 'other-e10s': {'tests': ['a11yr', 10:29:16 INFO - 'ts_paint', 10:29:16 INFO - 'tpaint', 10:29:16 INFO - 'sessionrestore', 10:29:16 INFO - 'sessionrestore_no_auto_restore', 10:29:16 INFO - 'tabpaint']}, 10:29:16 INFO - 'other-e10s_l64': {'tests': ['a11yr', 10:29:16 INFO - 'ts_paint', 10:29:16 INFO - 'tpaint', 10:29:16 INFO - 'sessionrestore', 10:29:16 INFO - 'sessionrestore_no_auto_restore', 10:29:16 INFO - 'tabpaint']}, 10:29:16 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 10:29:16 INFO - 'ts_paint', 10:29:16 INFO - 'tpaint', 10:29:16 INFO - 'sessionrestore', 10:29:16 INFO - 'sessionrestore_no_auto_restore', 10:29:16 INFO - 'tabpaint']}, 10:29:16 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 10:29:16 INFO - 'tests': ['a11yr', 10:29:16 INFO - 'ts_paint', 10:29:16 INFO - 'tpaint', 10:29:16 INFO - 'sessionrestore', 10:29:16 INFO - 'sessionrestore_no_auto_restore', 10:29:16 INFO - 'tabpaint']}, 10:29:16 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 10:29:16 INFO - 'tests': ['a11yr', 10:29:16 INFO - 'ts_paint', 10:29:16 INFO - 'tpaint', 10:29:16 INFO - 'sessionrestore', 10:29:16 INFO - 'sessionrestore_no_auto_restore', 10:29:16 INFO - 'tabpaint']}, 10:29:16 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 10:29:16 INFO - 'tests': ['tsvgx', 10:29:16 INFO - 'tsvgr_opacity', 10:29:16 INFO - 'tart', 10:29:16 INFO - 'tscrollx', 10:29:16 INFO - 'cart', 10:29:16 INFO - 'tsvg_static']}, 10:29:16 INFO - 'svgr-e10s': {'tests': ['tsvgx', 10:29:16 INFO - 'tsvgr_opacity', 10:29:16 INFO - 'tart', 10:29:16 INFO - 'tscrollx', 10:29:16 INFO - 'cart', 10:29:16 INFO - 'tsvg_static']}, 10:29:16 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 10:29:16 INFO - 'talos_options': ['--disable-e10s'], 10:29:16 INFO - 'tests': ['tp5o']}, 10:29:16 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 10:29:16 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 10:29:16 INFO - 'talos_options': ['--disable-e10s', 10:29:16 INFO - '--xperf_path', 10:29:16 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:29:16 INFO - 'tests': ['tp5n']}, 10:29:16 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 10:29:16 INFO - 'talos_options': ['--xperf_path', 10:29:16 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:29:16 INFO - 'tests': ['tp5n']}}, 10:29:16 INFO - 'talos.zip': {'path': '', 10:29:16 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 10:29:16 INFO - Running post-action listener: _resource_record_post_action 10:29:16 INFO - [mozharness: 2017-05-25 17:29:16.827585Z] Finished populate-webroot step (success) 10:29:16 INFO - [mozharness: 2017-05-25 17:29:16.827729Z] Running create-virtualenv step. 10:29:16 INFO - Running pre-action listener: _resource_record_pre_action 10:29:16 INFO - Running main action method: create_virtualenv 10:29:16 INFO - Creating virtualenv /builds/slave/test/build/venv 10:29:16 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 10:29:16 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 10:29:16 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 10:29:16 INFO - Using real prefix '/tools/python27' 10:29:16 INFO - New python executable in /builds/slave/test/build/venv/bin/python 10:29:17 INFO - Installing distribute.............................................................................................................................................................................................done. 10:29:20 INFO - Installing pip.................done. 10:29:20 INFO - Return code: 0 10:29:20 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 10:29:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 10:29:20 INFO - Reading from file tmpfile_stdout 10:29:20 INFO - Output received: 10:29:20 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 10:29:20 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 10:29:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:29:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:29:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:29:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:29: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/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 0x108c9ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108c9d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8bca57cfc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108a78d30>, '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 0x10899fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108a6dbe8>, '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.d28168JV24/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.6KEp177nmv/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 10:29:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 10:29:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 10:29:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.d28168JV24/Render', 10:29:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:29:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:29:20 INFO - 'HOME': '/Users/cltbld', 10:29:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:29:20 INFO - 'LOGNAME': 'cltbld', 10:29:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:29:20 INFO - 'MOZ_NO_REMOTE': '1', 10:29:20 INFO - 'NO_EM_RESTART': '1', 10:29:20 INFO - 'PAGER': '/bin/cat', 10:29:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:29:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:29:20 INFO - 'PWD': '/builds/slave/test', 10:29:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:29:20 INFO - 'SHELL': '/bin/bash', 10:29:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6KEp177nmv/Listeners', 10:29:20 INFO - 'TMPDIR': '/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/', 10:29:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:29:20 INFO - 'USER': 'cltbld', 10:29:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:29:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:29:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:29:20 INFO - 'XPC_FLAGS': '0x0', 10:29:20 INFO - 'XPC_SERVICE_NAME': '0', 10:29:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:29:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:29:21 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 10:29:21 INFO - Cleaning up... 10:29:21 INFO - Return code: 0 10:29:21 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 10:29:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:29:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:29:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:29:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:29:21 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/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 0x108c9ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108c9d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8bca57cfc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108a78d30>, '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 0x10899fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108a6dbe8>, '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.d28168JV24/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.6KEp177nmv/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 10:29:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 10:29:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 10:29:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.d28168JV24/Render', 10:29:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:29:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:29:21 INFO - 'HOME': '/Users/cltbld', 10:29:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:29:21 INFO - 'LOGNAME': 'cltbld', 10:29:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:29:21 INFO - 'MOZ_NO_REMOTE': '1', 10:29:21 INFO - 'NO_EM_RESTART': '1', 10:29:21 INFO - 'PAGER': '/bin/cat', 10:29:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:29:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:29:21 INFO - 'PWD': '/builds/slave/test', 10:29:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:29:21 INFO - 'SHELL': '/bin/bash', 10:29:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6KEp177nmv/Listeners', 10:29:21 INFO - 'TMPDIR': '/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/', 10:29:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:29:21 INFO - 'USER': 'cltbld', 10:29:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:29:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:29:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:29:21 INFO - 'XPC_FLAGS': '0x0', 10:29:21 INFO - 'XPC_SERVICE_NAME': '0', 10:29:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:29:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:29:21 INFO - Downloading/unpacking psutil>=3.1.1 10:29:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:29:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:29:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:29:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:29:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 10:29:23 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:29:23 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:29:23 INFO - Installing collected packages: psutil 10:29:23 INFO - Running setup.py install for psutil 10:29:23 INFO - building 'psutil._psutil_osx' extension 10:29:23 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 10:29:24 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 10:29:24 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 10:29:24 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 10:29:24 INFO - building 'psutil._psutil_posix' extension 10:29:24 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 10:29:24 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 10:29:24 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 10:29:24 INFO - ^ 10:29:24 INFO - 1 warning generated. 10:29:24 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 10:29:24 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:29:24 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:29:24 INFO - Successfully installed psutil 10:29:24 INFO - Cleaning up... 10:29:24 INFO - Return code: 0 10:29:24 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 10:29:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:29:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:29:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:29:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:29:24 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/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 0x108c9ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108c9d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8bca57cfc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108a78d30>, '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 0x10899fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108a6dbe8>, '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.d28168JV24/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.6KEp177nmv/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 10:29:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 10:29:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 10:29:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.d28168JV24/Render', 10:29:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:29:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:29:24 INFO - 'HOME': '/Users/cltbld', 10:29:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:29:24 INFO - 'LOGNAME': 'cltbld', 10:29:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:29:24 INFO - 'MOZ_NO_REMOTE': '1', 10:29:24 INFO - 'NO_EM_RESTART': '1', 10:29:24 INFO - 'PAGER': '/bin/cat', 10:29:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:29:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:29:24 INFO - 'PWD': '/builds/slave/test', 10:29:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:29:24 INFO - 'SHELL': '/bin/bash', 10:29:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6KEp177nmv/Listeners', 10:29:24 INFO - 'TMPDIR': '/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/', 10:29:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:29:24 INFO - 'USER': 'cltbld', 10:29:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:29:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:29:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:29:24 INFO - 'XPC_FLAGS': '0x0', 10:29:24 INFO - 'XPC_SERVICE_NAME': '0', 10:29:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:29:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:29:24 INFO - Downloading/unpacking mozsystemmonitor==0.3 10:29:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:29:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:29:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:29:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:29:26 INFO - Downloading mozsystemmonitor-0.3.tar.gz 10:29:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 10:29:26 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 10:29:26 INFO - Installing collected packages: mozsystemmonitor 10:29:26 INFO - Running setup.py install for mozsystemmonitor 10:29:26 INFO - Successfully installed mozsystemmonitor 10:29:26 INFO - Cleaning up... 10:29:26 INFO - Return code: 0 10:29:26 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 10:29:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:29:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:29:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:29:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:29:26 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/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 0x108c9ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108c9d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8bca57cfc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108a78d30>, '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 0x10899fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108a6dbe8>, '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.d28168JV24/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.6KEp177nmv/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 10:29:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 10:29:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 10:29:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.d28168JV24/Render', 10:29:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:29:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:29:26 INFO - 'HOME': '/Users/cltbld', 10:29:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:29:26 INFO - 'LOGNAME': 'cltbld', 10:29:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:29:26 INFO - 'MOZ_NO_REMOTE': '1', 10:29:26 INFO - 'NO_EM_RESTART': '1', 10:29:26 INFO - 'PAGER': '/bin/cat', 10:29:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:29:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:29:26 INFO - 'PWD': '/builds/slave/test', 10:29:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:29:26 INFO - 'SHELL': '/bin/bash', 10:29:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6KEp177nmv/Listeners', 10:29:26 INFO - 'TMPDIR': '/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/', 10:29:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:29:26 INFO - 'USER': 'cltbld', 10:29:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:29:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:29:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:29:26 INFO - 'XPC_FLAGS': '0x0', 10:29:26 INFO - 'XPC_SERVICE_NAME': '0', 10:29:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:29:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:29:27 INFO - Downloading/unpacking jsonschema==2.5.1 10:29:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:29:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:29:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:29:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:29:28 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 10:29:28 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 10:29:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:29:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:29:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:29:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:29:29 INFO - Downloading functools32-3.2.3-2.tar.gz 10:29:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 10:29:29 INFO - warning: no files found matching '*.txt' 10:29:29 INFO - no previously-included directories found matching 'build' 10:29:29 INFO - no previously-included directories found matching 'dist' 10:29:29 INFO - no previously-included directories found matching '.git*' 10:29:29 INFO - Installing collected packages: jsonschema, functools32 10:29:29 INFO - Running setup.py install for functools32 10:29:29 INFO - warning: no files found matching '*.txt' 10:29:29 INFO - no previously-included directories found matching 'build' 10:29:29 INFO - no previously-included directories found matching 'dist' 10:29:29 INFO - no previously-included directories found matching '.git*' 10:29:29 INFO - Successfully installed jsonschema functools32 10:29:29 INFO - Cleaning up... 10:29:29 INFO - Return code: 0 10:29:29 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 10:29:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:29:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:29:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:29:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:29:29 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/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 0x108c9ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108c9d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8bca57cfc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108a78d30>, '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 0x10899fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108a6dbe8>, '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.d28168JV24/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.6KEp177nmv/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 10:29:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 10:29:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 10:29:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.d28168JV24/Render', 10:29:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:29:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:29:29 INFO - 'HOME': '/Users/cltbld', 10:29:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:29:29 INFO - 'LOGNAME': 'cltbld', 10:29:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:29:29 INFO - 'MOZ_NO_REMOTE': '1', 10:29:29 INFO - 'NO_EM_RESTART': '1', 10:29:29 INFO - 'PAGER': '/bin/cat', 10:29:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:29:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:29:29 INFO - 'PWD': '/builds/slave/test', 10:29:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:29:29 INFO - 'SHELL': '/bin/bash', 10:29:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6KEp177nmv/Listeners', 10:29:29 INFO - 'TMPDIR': '/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/', 10:29:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:29:29 INFO - 'USER': 'cltbld', 10:29:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:29:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:29:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:29:29 INFO - 'XPC_FLAGS': '0x0', 10:29:29 INFO - 'XPC_SERVICE_NAME': '0', 10:29:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:29:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:29:29 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 10:29:29 INFO - Cleaning up... 10:29:29 INFO - Return code: 0 10:29:29 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 10:29:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:29:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:29:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:29:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:29:29 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/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 0x108c9ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108c9d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8bca57cfc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108a78d30>, '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 0x10899fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108a6dbe8>, '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.d28168JV24/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.6KEp177nmv/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 10:29:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 10:29:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 10:29:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.d28168JV24/Render', 10:29:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:29:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:29:29 INFO - 'HOME': '/Users/cltbld', 10:29:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:29:29 INFO - 'LOGNAME': 'cltbld', 10:29:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:29:29 INFO - 'MOZ_NO_REMOTE': '1', 10:29:29 INFO - 'NO_EM_RESTART': '1', 10:29:29 INFO - 'PAGER': '/bin/cat', 10:29:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:29:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:29:29 INFO - 'PWD': '/builds/slave/test', 10:29:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:29:29 INFO - 'SHELL': '/bin/bash', 10:29:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6KEp177nmv/Listeners', 10:29:29 INFO - 'TMPDIR': '/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/', 10:29:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:29:29 INFO - 'USER': 'cltbld', 10:29:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:29:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:29:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:29:29 INFO - 'XPC_FLAGS': '0x0', 10:29:29 INFO - 'XPC_SERVICE_NAME': '0', 10:29:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:29:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:29:30 INFO - Downloading/unpacking blobuploader==1.2.4 10:29:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:29:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:29:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:29:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:29:31 INFO - Downloading blobuploader-1.2.4.tar.gz 10:29:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 10:29:31 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 10:29: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 10:29: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 10:29: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 10:29: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 10:29:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 10:29:32 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 10:29:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:29:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:29:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:29:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:29:32 INFO - Downloading docopt-0.6.1.tar.gz 10:29:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 10:29:32 INFO - Installing collected packages: blobuploader, requests, docopt 10:29:32 INFO - Running setup.py install for blobuploader 10:29:32 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 10:29:32 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 10:29:32 INFO - Running setup.py install for requests 10:29:33 INFO - Running setup.py install for docopt 10:29:33 INFO - Successfully installed blobuploader requests docopt 10:29:33 INFO - Cleaning up... 10:29:33 INFO - Return code: 0 10:29:33 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:29:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:29:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:29:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:29:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:29:33 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/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 0x108c9ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108c9d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8bca57cfc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108a78d30>, '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 0x10899fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108a6dbe8>, '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.d28168JV24/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.6KEp177nmv/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 10:29:33 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/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 10:29:33 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/pub --find-links http://pypi.pub.build.mozilla.org/pub 10:29:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.d28168JV24/Render', 10:29:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:29:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:29:33 INFO - 'HOME': '/Users/cltbld', 10:29:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:29:33 INFO - 'LOGNAME': 'cltbld', 10:29:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:29:33 INFO - 'MOZ_NO_REMOTE': '1', 10:29:33 INFO - 'NO_EM_RESTART': '1', 10:29:33 INFO - 'PAGER': '/bin/cat', 10:29:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:29:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:29:33 INFO - 'PWD': '/builds/slave/test', 10:29:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:29:33 INFO - 'SHELL': '/bin/bash', 10:29:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6KEp177nmv/Listeners', 10:29:33 INFO - 'TMPDIR': '/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/', 10:29:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:29:33 INFO - 'USER': 'cltbld', 10:29:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:29:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:29:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:29:33 INFO - 'XPC_FLAGS': '0x0', 10:29:33 INFO - 'XPC_SERVICE_NAME': '0', 10:29:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:29:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:29:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:29:33 INFO - Running setup.py (path:/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/pip-RSxUX7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:29:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:29:33 INFO - Running setup.py (path:/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/pip-3Jl7Ph-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:29:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:29:33 INFO - Running setup.py (path:/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/pip-puHaLr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:29:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:29:33 INFO - Running setup.py (path:/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/pip-B5LDI7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:29:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:29:33 INFO - Running setup.py (path:/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/pip-gexT40-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:29:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:29:34 INFO - Running setup.py (path:/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/pip-ThAund-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:29:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:29:34 INFO - Running setup.py (path:/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/pip-KDM6Eg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:29:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:29:34 INFO - Running setup.py (path:/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/pip-2DOUpC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:29:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:29:34 INFO - Running setup.py (path:/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/pip-SzLbfC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:29:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:29:34 INFO - Running setup.py (path:/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/pip-_2RlgM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:29:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:29:34 INFO - Running setup.py (path:/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/pip-wolXDm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:29:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:29:34 INFO - Running setup.py (path:/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/pip-evJ1fO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:29:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:29:34 INFO - Running setup.py (path:/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/pip-K_QO7u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:29:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:29:34 INFO - Running setup.py (path:/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/pip-ymqVwA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:29:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:29:34 INFO - Running setup.py (path:/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/pip-mk1Tzj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:29:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:29:35 INFO - Running setup.py (path:/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/pip-hugUR3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:29:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:29:35 INFO - Running setup.py (path:/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/pip-ekCGjG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:29:35 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 10:29:35 INFO - Running setup.py install for manifestparser 10:29:35 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 10:29:35 INFO - Running setup.py install for mozcrash 10:29:35 INFO - Running setup.py install for mozdebug 10:29:35 INFO - Running setup.py install for mozdevice 10:29:35 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 10:29:35 INFO - Installing dm script to /builds/slave/test/build/venv/bin 10:29:35 INFO - Running setup.py install for mozfile 10:29:36 INFO - Running setup.py install for mozhttpd 10:29:36 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 10:29:36 INFO - Running setup.py install for mozinfo 10:29:36 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 10:29:36 INFO - Running setup.py install for mozInstall 10:29:36 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 10:29:36 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 10:29:36 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 10:29:36 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 10:29:36 INFO - Running setup.py install for mozleak 10:29:36 INFO - Running setup.py install for mozlog 10:29:36 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 10:29:36 INFO - Running setup.py install for moznetwork 10:29:36 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 10:29:36 INFO - Running setup.py install for mozprocess 10:29:37 INFO - Running setup.py install for mozprofile 10:29:37 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 10:29:37 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 10:29:37 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 10:29:37 INFO - Running setup.py install for mozrunner 10:29:37 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 10:29:37 INFO - Running setup.py install for mozscreenshot 10:29:37 INFO - Running setup.py install for moztest 10:29:37 INFO - Running setup.py install for mozversion 10:29:37 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 10:29:37 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 10:29:37 INFO - Cleaning up... 10:29:37 INFO - Return code: 0 10:29:37 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:29:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:29:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:29:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:29:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:29:37 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/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 0x108c9ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108c9d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8bca57cfc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108a78d30>, '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 0x10899fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108a6dbe8>, '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.d28168JV24/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.6KEp177nmv/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 10:29:37 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/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 10:29:37 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/pub --find-links http://pypi.pub.build.mozilla.org/pub 10:29:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.d28168JV24/Render', 10:29:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:29:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:29:37 INFO - 'HOME': '/Users/cltbld', 10:29:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:29:37 INFO - 'LOGNAME': 'cltbld', 10:29:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:29:37 INFO - 'MOZ_NO_REMOTE': '1', 10:29:37 INFO - 'NO_EM_RESTART': '1', 10:29:37 INFO - 'PAGER': '/bin/cat', 10:29:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:29:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:29:37 INFO - 'PWD': '/builds/slave/test', 10:29:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:29:37 INFO - 'SHELL': '/bin/bash', 10:29:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6KEp177nmv/Listeners', 10:29:37 INFO - 'TMPDIR': '/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/', 10:29:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:29:37 INFO - 'USER': 'cltbld', 10:29:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:29:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:29:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:29:37 INFO - 'XPC_FLAGS': '0x0', 10:29:37 INFO - 'XPC_SERVICE_NAME': '0', 10:29:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:29:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:29:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:29:38 INFO - Running setup.py (path:/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/pip-OpjXh3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:29:38 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 10:29:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:29:38 INFO - Running setup.py (path:/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/pip-kwKvAH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:29:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 10:29:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:29:38 INFO - Running setup.py (path:/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/pip-4n0XJj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:29:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 10:29:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:29:38 INFO - Running setup.py (path:/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/pip-kulCyI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:29:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 10:29:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:29:38 INFO - Running setup.py (path:/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/pip-Z0GAg_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:29:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 10:29:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:29:38 INFO - Running setup.py (path:/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/pip-vjNBXs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:29:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 10:29:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:29:38 INFO - Running setup.py (path:/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/pip-HMwcOV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:29:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 10:29:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:29:38 INFO - Running setup.py (path:/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/pip-k4KavG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:29:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 10:29:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:29:38 INFO - Running setup.py (path:/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/pip-3clJnW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:29:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 10:29:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:29:39 INFO - Running setup.py (path:/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/pip-J73aRI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:29:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 10:29:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:29:39 INFO - Running setup.py (path:/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/pip-HVlFlY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:29:39 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 10:29:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:29:39 INFO - Running setup.py (path:/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/pip-y4BSC9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:29:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 10:29:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:29:39 INFO - Running setup.py (path:/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/pip-YSnnTJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:29:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 10:29:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:29:39 INFO - Running setup.py (path:/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/pip-5tlXUH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:29:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 10:29:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:29:39 INFO - Running setup.py (path:/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/pip-WLr4xT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:29:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 10:29:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:29:39 INFO - Running setup.py (path:/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/pip-rKEVUD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:29:39 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 10:29:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:29:39 INFO - Running setup.py (path:/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/pip-RewVGV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:29:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 10:29:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 10:29:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 10:29:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 10:29:39 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 10:29:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 10:29:39 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 10:29:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:29:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:29:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:29:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:29:41 INFO - Downloading blessings-1.6.tar.gz 10:29:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 10:29:41 INFO - Installing collected packages: blessings 10:29:41 INFO - Running setup.py install for blessings 10:29:41 INFO - Successfully installed blessings 10:29:41 INFO - Cleaning up... 10:29:41 INFO - Return code: 0 10:29:41 INFO - Done creating virtualenv /builds/slave/test/build/venv. 10:29:41 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:29:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:29:41 INFO - Reading from file tmpfile_stdout 10:29:41 INFO - Current package versions: 10:29:41 INFO - blessings == 1.6 10:29:41 INFO - blobuploader == 1.2.4 10:29:41 INFO - docopt == 0.6.1 10:29:41 INFO - functools32 == 3.2.3-2 10:29:41 INFO - jsonschema == 2.5.1 10:29:41 INFO - manifestparser == 1.1 10:29:41 INFO - mozInstall == 1.12 10:29:41 INFO - mozcrash == 1.0 10:29:41 INFO - mozdebug == 0.1 10:29:41 INFO - mozdevice == 0.49 10:29:41 INFO - mozfile == 1.2 10:29:41 INFO - mozhttpd == 0.7 10:29:41 INFO - mozinfo == 0.9 10:29:41 INFO - mozleak == 0.1 10:29:41 INFO - mozlog == 3.4 10:29:41 INFO - moznetwork == 0.27 10:29:41 INFO - mozprocess == 0.25 10:29:41 INFO - mozprofile == 0.28 10:29:41 INFO - mozrunner == 6.13 10:29:41 INFO - mozscreenshot == 0.1 10:29:41 INFO - mozsystemmonitor == 0.3 10:29:41 INFO - moztest == 0.8 10:29:41 INFO - mozversion == 1.4 10:29:41 INFO - psutil == 3.1.1 10:29:41 INFO - requests == 1.2.3 10:29:41 INFO - wsgiref == 0.1.2 10:29:41 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:29:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:29:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:29:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:29:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:29:41 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/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 0x108c9ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108c9d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8bca57cfc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108a78d30>, '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 0x10899fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108a6dbe8>, '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.d28168JV24/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.6KEp177nmv/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 10:29:41 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/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 10:29:41 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/pub --find-links http://pypi.pub.build.mozilla.org/pub 10:29:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.d28168JV24/Render', 10:29:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:29:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:29:41 INFO - 'HOME': '/Users/cltbld', 10:29:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:29:41 INFO - 'LOGNAME': 'cltbld', 10:29:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:29:41 INFO - 'MOZ_NO_REMOTE': '1', 10:29:41 INFO - 'NO_EM_RESTART': '1', 10:29:41 INFO - 'PAGER': '/bin/cat', 10:29:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:29:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:29:41 INFO - 'PWD': '/builds/slave/test', 10:29:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:29:41 INFO - 'SHELL': '/bin/bash', 10:29:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6KEp177nmv/Listeners', 10:29:41 INFO - 'TMPDIR': '/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/', 10:29:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:29:41 INFO - 'USER': 'cltbld', 10:29:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:29:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:29:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:29:41 INFO - 'XPC_FLAGS': '0x0', 10:29:41 INFO - 'XPC_SERVICE_NAME': '0', 10:29:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:29:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:29:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 10:29:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 10:29:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 10:29:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 10:29:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 10:29:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 10:29:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 10:29:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 10:29:42 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 10:29:42 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 10:29:42 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 10:29:42 INFO - Cleaning up... 10:29:42 INFO - Return code: 0 10:29:42 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 10:29:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:29:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:29:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:29:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:29:42 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/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 0x108c9ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108c9d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8bca57cfc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108a78d30>, '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 0x10899fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108a6dbe8>, '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.d28168JV24/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.6KEp177nmv/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 10:29:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 10:29:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 10:29:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.d28168JV24/Render', 10:29:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:29:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:29:42 INFO - 'HOME': '/Users/cltbld', 10:29:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:29:42 INFO - 'LOGNAME': 'cltbld', 10:29:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:29:42 INFO - 'MOZ_NO_REMOTE': '1', 10:29:42 INFO - 'NO_EM_RESTART': '1', 10:29:42 INFO - 'PAGER': '/bin/cat', 10:29:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:29:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:29:42 INFO - 'PWD': '/builds/slave/test', 10:29:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:29:42 INFO - 'SHELL': '/bin/bash', 10:29:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6KEp177nmv/Listeners', 10:29:42 INFO - 'TMPDIR': '/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/', 10:29:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:29:42 INFO - 'USER': 'cltbld', 10:29:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:29:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:29:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:29:42 INFO - 'XPC_FLAGS': '0x0', 10:29:42 INFO - 'XPC_SERVICE_NAME': '0', 10:29:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:29:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:29:42 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 10:29:42 INFO - Cleaning up... 10:29:42 INFO - Return code: 0 10:29:42 INFO - Running post-action listener: _resource_record_post_action 10:29:42 INFO - Running post-action listener: _start_resource_monitoring 10:29:42 INFO - Starting resource monitoring. 10:29:42 INFO - [mozharness: 2017-05-25 17:29:42.500221Z] Finished create-virtualenv step (success) 10:29:42 INFO - [mozharness: 2017-05-25 17:29:42.501156Z] Running install step. 10:29:42 INFO - Running pre-action listener: _resource_record_pre_action 10:29:42 INFO - Running main action method: install 10:29:42 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:29:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:29:42 INFO - Reading from file tmpfile_stdout 10:29:42 INFO - Detecting whether we're running mozinstall >=1.0... 10:29:42 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 10:29:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 10:29:42 INFO - Reading from file tmpfile_stdout 10:29:42 INFO - Output received: 10:29:42 INFO - Usage: mozinstall [options] installer 10:29:42 INFO - Options: 10:29:42 INFO - -h, --help show this help message and exit 10:29:42 INFO - -d DEST, --destination=DEST 10:29:42 INFO - Directory to install application into. [default: 10:29:42 INFO - "/builds/slave/test"] 10:29:42 INFO - --app=APP Application being installed. [default: firefox] 10:29:42 INFO - mkdir: /builds/slave/test/build/application 10:29:42 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-54.0.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 10:29:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-54.0.en-US.mac.dmg --destination /builds/slave/test/build/application 10:30:02 INFO - Reading from file tmpfile_stdout 10:30:02 INFO - Output received: 10:30:02 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 10:30:02 INFO - Running post-action listener: _resource_record_post_action 10:30:02 INFO - [mozharness: 2017-05-25 17:30:02.808836Z] Finished install step (success) 10:30:02 INFO - [mozharness: 2017-05-25 17:30:02.808969Z] Running run-tests step. 10:30:02 INFO - Running pre-action listener: _resource_record_pre_action 10:30:02 INFO - Running main action method: run_tests 10:30:02 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 10:30:02 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 10:30:02 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 10:30:02 INFO - 2017-05-25 10:30:02.823 screenresolution[1629:7893] starting screenresolution argv=screenresolution get 10:30:02 INFO - 2017-05-25 10:30:02.826 screenresolution[1629:7893] Display 0: 1600x1200x32@60 10:30:02 INFO - 2017-05-25 10:30:02.832 screenresolution[1630:7896] starting screenresolution argv=screenresolution list 10:30:02 INFO - Available Modes on Display 0 10:30:02 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 10:30:02 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 10:30:02 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 10:30:02 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 10:30:03 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 10:30:03 INFO - Intel Iris: 10:30:03 INFO - Chipset Model: Intel Iris 10:30:03 INFO - Type: GPU 10:30:03 INFO - Bus: Built-In 10:30:03 INFO - VRAM (Dynamic, Max): 1536 MB 10:30:03 INFO - Vendor: Intel (0x8086) 10:30:03 INFO - Device ID: 0x0a2e 10:30:03 INFO - Revision ID: 0x0009 10:30:03 INFO - Displays: 10:30:03 INFO - Display: 10:30:03 INFO - Resolution: 1600 x 1200 @ 60 Hz 10:30:03 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 10:30:03 INFO - Display Serial Number: 1600x1200 60 10:30:03 INFO - Main Display: Yes 10:30:03 INFO - Mirror: Off 10:30:03 INFO - Online: Yes 10:30:03 INFO - Rotation: Supported 10:30:03 INFO - Return code: 0 10:30:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 10:30:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 10:30:03 INFO - Python 2.7.3 10:30:03 INFO - Return code: 0 10:30:03 INFO - grabbing minidump binary from tooltool 10:30:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:30:03 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 0x108a78d30>, '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 0x10899fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108a6dbe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 10:30:03 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 10:30:03 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 10:30:03 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 10:30:03 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 10:30:03 INFO - Return code: 0 10:30:03 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 10:30:03 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 10:30:03 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 10:30:03 INFO - ENV: RUST_BACKTRACE is now 1 10:30:03 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 10:30:03 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 10:30:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/KlIGQZy6RByOmf-6GVai7w/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0346', '--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 10:30:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Beta --suite dromaeojs --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/KlIGQZy6RByOmf-6GVai7w/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0346 --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 10:30:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.d28168JV24/Render', 10:30:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:30:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:30:03 INFO - 'HOME': '/Users/cltbld', 10:30:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:30:03 INFO - 'LOGNAME': 'cltbld', 10:30:03 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 10:30:03 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 10:30:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:30:03 INFO - 'MOZ_NO_REMOTE': '1', 10:30:03 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 10:30:03 INFO - 'NO_EM_RESTART': '1', 10:30:03 INFO - 'PAGER': '/bin/cat', 10:30:03 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:30:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:30:03 INFO - 'PWD': '/builds/slave/test', 10:30:03 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 10:30:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:30:03 INFO - 'RUST_BACKTRACE': '1', 10:30:03 INFO - 'SHELL': '/bin/bash', 10:30:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6KEp177nmv/Listeners', 10:30:03 INFO - 'TMPDIR': '/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/', 10:30:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:30:03 INFO - 'USER': 'cltbld', 10:30:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:30:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:30:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:30:03 INFO - 'XPC_FLAGS': '0x0', 10:30:03 INFO - 'XPC_SERVICE_NAME': '0', 10:30:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:30:03 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/KlIGQZy6RByOmf-6GVai7w/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0346', '--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 10:30:03 INFO - mozversion application_buildid: 20170525093444 10:30:03 INFO - mozversion application_changeset: d3ce3e3812c9372a12b6416f0ceedd2e768b5277 10:30:03 INFO - mozversion application_display_name: FirefoxDeveloperEdition 10:30:03 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 10:30:03 INFO - mozversion application_name: Firefox 10:30:03 INFO - mozversion application_remotingname: firefox-dev 10:30:03 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 10:30:03 INFO - mozversion application_vendor: Mozilla 10:30:03 INFO - mozversion application_version: 54.0 10:30:03 INFO - mozversion platform_buildid: 20170525093444 10:30:03 INFO - mozversion platform_changeset: d3ce3e3812c9372a12b6416f0ceedd2e768b5277 10:30:03 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 10:30:03 INFO - mozversion platform_version: 54.0 10:30:03 INFO - using testdate: 1495733403 10:30:03 INFO - actual date: 1495733403 10:30:03 INFO - starting webserver on 'localhost:49224' 10:30:03 INFO - SUITE-START | Running 2 tests 10:30:03 INFO - TEST-START | dromaeo_css 10:30:03 INFO - operating with platform_type : mac_ 10:30:03 INFO - Initialising browser for dromaeo_css test... 10:30:03 INFO - TEST-INFO | started process 1640 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/tmp3uDviI/profile http://localhost:49224/getInfo.html) 10:30:05 INFO - PROCESS | 1640 | __metrics Screen width/height:1600/1200 10:30:05 INFO - PROCESS | 1640 | colorDepth:24 10:30:05 INFO - PROCESS | 1640 | Browser inner width/height: 1024/689 10:30:05 INFO - PROCESS | 1640 | __metrics 10:30:05 INFO - PROCESS | 1640 | 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" 10:30:05 INFO - PROCESS | 1640 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 10:30:06 INFO - TEST-INFO | 1640: exit 0 10:30:06 INFO - Browser initialized. 10:30:06 INFO - Running cycle 1/1 for dromaeo_css test... 10:30:06 INFO - TEST-INFO | started process 1645 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/tmp3uDviI/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:30:18 INFO - PROCESS | 1645 | RSS: Main: 193941504 10:30:18 INFO - PROCESS | 1645 | 10:33:23 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49224/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49224/tests/dromaeo/cssquery-ext.html) 10:33:24 INFO - PROCESS | 1645 | RSS: Main: 335687680 10:33:24 INFO - PROCESS | 1645 | 10:36:27 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49224/tests/dromaeo/cssquery-ext.html (next: http://localhost:49224/tests/dromaeo/cssquery-jquery.html) 10:36:28 INFO - PROCESS | 1645 | RSS: Main: 331821056 10:36:28 INFO - PROCESS | 1645 | 10:39:30 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49224/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49224/tests/dromaeo/cssquery-mootools.html) 10:39:31 INFO - PROCESS | 1645 | RSS: Main: 293097472 10:39:31 INFO - PROCESS | 1645 | 10:42:33 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49224/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49224/tests/dromaeo/cssquery-prototype.html) 10:42:34 INFO - PROCESS | 1645 | RSS: Main: 294137856 10:42:34 INFO - PROCESS | 1645 | 10:45:37 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49224/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49224/tests/dromaeo/cssquery-yui.html) 10:45:38 INFO - PROCESS | 1645 | RSS: Main: 294457344 10:45:38 INFO - PROCESS | 1645 | 10:48:41 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49224/tests/dromaeo/cssquery-yui.html (next: http://localhost:49224/tests/dromaeo/cssquery-dojo.html) 10:48:42 INFO - PROCESS | 1645 | RSS: Main: 428281856 10:48:42 INFO - PROCESS | 1645 | 10:48:42 INFO - PROCESS | 1645 | __start_tp_report 10:48:42 INFO - PROCESS | 1645 | _x_x_mozilla_page_load 10:48:42 INFO - PROCESS | 1645 | _x_x_mozilla_page_load_details 10:48:42 INFO - PROCESS | 1645 | |i|pagename|runs| 10:48:42 INFO - PROCESS | 1645 | |0;dojo.html;3391.13526050857;3429.3141371725646;3432.2105915639386;3461.4807778833188;3477.843497042634;5931.288245410546;5940.405959404057;5949.9107513387335;5991;6014.639121652704;5119.436861945183;5745.856353591163;5918.378570250126;5979.3123790764075;5979.641221526701;5295.867603309919;5320.733963301839;5342.809572326723;5385.542228910547;5433;5255.159174532076;5257.185136303879;5329.120695085307;5465.9726701366435;5486.561075114001;5387.434319396446;5392.649477783932;5398.595105367093;5407.3781515125675;5422.810201642943;5452;5478.561715062805;5486.039943009957;5490.505854473116;5492.313460817398;5329.307190065267;5415.512603865632;5418.7019713915715;5419.458054194589;5455.399906010336;5706.457886500776;5780.7687692492245;5821;6018.217631707894;6032.396760323976;5235.214717792323;6339.936600633981;6368.586041907308;6372.203474565679;6391.041343798421;4913.729744864031;6373.904391434132;6395.968020159869;6397.8400539986405;6403.295637479873;5938.940610593839;6096.69516524172;6113.480354169901;6149.169862068564;6156.938430615726;6334.271558770801;6343.651099189497;6390.041493775971;6407.807765767035;6430.64631445275;6379.872402551923;6382.808515744535;6395.232572091379;6397.10440538325;6397.616143031433;6335.986242201226;6354.17395738551;6374.553781235361;6416;6417.550771445954;6225.501959843202;6295.811125666238;6333.619982801047;6370.617762934239;6394.648294343763;6278.623282603058;6333.1766870306565;6340.904886426707;6344.841378965562;6384.616922984543;4866.586340161055;4888.071266459361;4888.2178851383605;4915.459299477055;4918.43438004634;4392.956070439319;4424.80088396023;4441.888952776207;4450.799714012813;4482.551744825491;5574.219609254707;5607.579431542569;5650.378458369576;5739.196512488255;5757.251557297608;6366.713497892606;6404.7117879695525;6410.391012853772;6431.935680643133;6432.774852880127;5820.505257053198;5824.213731146326;5830.242068531149;5839.970800146057;5861.824145275563;5306.8407947761625;5548.250986116904;5554.750036248379;5555.777768889199;5568.9721551392795;5376.731163441734;6340.239171299474;6353.142325786144;6485.189351331083;6567.113439685581;6314.652694101868;6346.746130154742;6396.29640739509;6398.5521013528605;6416.101745755691;6548.443382312556;6565.835854103686;6571.211454625475;6580;6593.538452308292;6151.507879369729;6222.595531290452;6314.55798094129;6335.049742538656;6384.968075159594;6373.968130159319;6394;6400.583962042452;6410.134631824644;6412;6353.96823015882;6363.936360636334;6377.96811015942;6378.489720822416;6388.329225431263;5993.700314984146;6109.236345456818;6237.1891654084675;6272.655003974825;6314.684265786784;6235.251769787473;6246.500279977682;6264.843378915563;6278.121063051085;6283.59156654816;5038.697678139323;6350.523710721622;6385.553011289165;6386.978083506617;6394.392532709385;6068.817935461943;6112.144299797943;6120.632762034292;6122.357152499102;6136.23297087864 10:48:42 INFO - PROCESS | 1645 | |1;ext.html;720.7801620505745;768.3123604374085;821.3552361396305;885.5749240364629;900.0639335091505;28563.42873142536;41599;41969.7901510492;42006;42086.15827683448;41869.953251168736;42058.369124463155;42065;42128.36807447891;42143.78928105363;7647.579383133925;9391.389559678617;9526.142647161754;9540.427574345527;9553.426794392324;7695.268949449794;11536.884631153707;11838.348890811001;11984.46069926851;11988.760224795498;11770.7645847083;11803;11812.232204906697;11817.527298908077;11867.406629668525;11735.354555499443;11739.7065073373;11740.471678774391;11757.58848440309;11769.588064417747;11500.884991150066;11567.595134170264;11590.478428470738;11596.478158482889;11600.941995290053;40958;40987.59012409898;41049.97375065617;41072.58927410717;41110.38334424986;3915.569287378386;3919.941200881989;3957.2679054375035;3968.86108986187;4002.899927501807;27782.722172778416;27967.440651186862;29136.70863291361;30035.549466758017;30117.548236776467;3168.8415579221178;3181.9204519886957;3199.9520007199912;3327.633960264369;3330.283988942365;358099;362856.18571907235;363057;363098.18450908107;363293;53049;53089.67275818058;53183.93632127336;53234.73382633062;53284.467155328726;56722;56839.431605683414;56852;56872.431275686715;57065.71467142721;14696.41214351414;15000.69998600022;15013.24933753308;15021.69956600862;15057.17185554805;11263.60577379804;11424;12906.741865162645;12908.354582271037;12916.741665166644;12321.199122057038;12621.873781262253;12753.298568578632;12791.872081279254;12793.61619151427;4603.286490593963;5352.598555108382;5359.732013399314;5365.865853353697;5379.112446446293;1005.6932635546083;1102.3496137278883;1128.683968488808;1161.8605767307977;1203.2599951030054;26898.92404303806;27055.927609024893;27605;27684.30789230245;27851;1224.375568460074;1232.1498166265428;1245.875412458765;1274.4074005587447;1278.5652878021517;13837.792433113511;14103.365348559339;14133.939954503248;14228.075175113585;14494.275286235646;462617;464667.73662921635;468251;468541;469352;57871.71064144652;58016;58126.41873581295;58160.836783264094;58333.833323333296;1282.3203702037936;1440.4526280013529;1478.7633978563379;1486.9256537173098;1488.3153749275455;25697.486050279265;25902.35244118912;26353.496417840273;26904.596431053942;27284.772185251313;46833;47183;47287.290690638976;47369;47476.7626161867;385602;388337;388889;389943;390223.2933012014;27836.860815695793;29163.270918227216;29444.705552944197;29724;30939.535906960962;31799.364012719154;32085.197870053435;33800.830995844866;42364.36453453139;42721.7183484496;27724.30689232785;28456.715432845405;28465.861365545145;29038.7096129036;29831.552526711683;24063.759362406152;24365.756342436347;24809.62785558182;29804.552931706476;29869.402611947204;33188;33210.5018424719;33290.66709332875;33823;33939.321213576084;28761;29057.982970596775;29424.852875735483;29774.70225297806;30000.849995750756;19758.20967161355;21271.7872821276;23219.955102020787;23310.184143554896;23394.47935884162 10:48:42 INFO - PROCESS | 1645 | |2;jquery.html;419.1073014479159;429.5146484472547;430.29385565685527;437.17592338442034;438.3731264292061;21857.453563660896;22511.662325065117;22659;23622.763772362272;23878.880605596947;22517.32448026558;23027.539449211046;23396.415089622726;23555.29334119979;23994.520109597797;5921.615095018831;6646.9667651661675;6900.413464855494;6993.370596646301;6998.615076170809;5993.250843644544;6772.8984065239065;6932.861342773142;6942.930570694279;7625.8474830503355;7673.577953212571;7727.995360603142;7747.109082455533;7751.263629955173;7759.224077592224;7393.926060739404;7631.046119235096;7692.230776922319;7705.6917723291;7711.460197786158;7232.1683006454405;7468.738594149178;7468.738594149233;7540.886886696704;7550.433717471212;25886;26412.867935660197;27121.593176102375;27137.86431067832;27315.863420682967;2822.1956742328543;2854.006586169048;2936.985315073411;3017.9547306790414;3027.273454370957;16343.83656163435;16362.427315043917;16396.180190990402;17136.228869700768;17324.826751732573;1973.1614064022876;2023.3323003408839;2119.7138386317806;2161.827053835689;2187.223535644839;134405.3279733605;136803.31598342044;137616.93574596388;148997;154166;16101.27544260511;16296.42962496307;18900;20123;20233.98830058491;31505.8424707875;31947;32009.679903201137;32104;32170.67829321677;10437.634682786065;10464.058234758908;10506.73733156662;10667.733306667395;10856.945715271373;8956.552172391355;8971.461712297283;8999.82000359989;9167.44995300284;9176.036516165706;8411.789705257295;8514.659413623509;8987.640494380152;9337.626494940127;9389.920159181645;3465.462853257749;3904.0674865940973;3918.9810649230835;4041.6766658667825;4041.8383264669083;950.9001554836781;1067.32225037102;1085.2132204151926;1106.1040557148735;1110.6001839337832;20501.872397018305;21260.78739212588;21485.785142148692;21636.24273150432;21755.765173017633;924.930630202724;1090.7818436312768;1092.2299778656188;1138.8804175561613;1154.5670373609896;10940.507677154546;11339.149563782585;11355.034822040054;11359.432028398547;11360.318380897173;762021;766260;766797;782945;805492;89735;122799;152805;164863;170500;1234.5802427174804;1282.4229096906242;1324.4040181918176;1328.8471825740164;1333.9466421343038;19201.807981920003;19647.31234406789;20119.798802011792;20132.29536966199;20141.597168056265;48702;62640;64078;64743.67628161829;65270;89677.55161224153;89788;90313;92345.53827230821;92366.69083272971;11109.777804444027;11300.94349528247;11387;11712.765744684888;11758;12328.568500102454;12523.123381363217;12661.746765064463;12783.680407989503;12877.484900603871;10512.369257844553;10729.785404291715;10825.133989280996;11219.49512271966;11255.493502792231;9291.907080929104;9557.713268601952;9833.065858743408;10130.240231982778;10205.897941020496;10973;11102.2783519071;11221.60724374643;11419.429028548373;11561.595344162914;11067.557297708001;11225.270357426742;11241.831372529256;11293.943530282297;11440.542378305108;10770.353778773298;11029.724256893322;11089.390083545482;11442.82835757481;11732.530698771954 10:48:42 INFO - PROCESS | 1645 | |3;mootools.html;1623.553522781235;1624.3583784405168;1650.8596769274611;1654.5698118489197;1664.7586100015496;10853.67438976831;11457.770844583103;11466.312021278729;11469.942650286737;11474.483648235828;9980.800383992315;10246.231532635045;10370.792584148312;10385.792284154313;11182.273152245098;2309.180241014437;2338.9532209355802;2339.988300058506;2341.309313752439;2341.672165896776;2273.170292843117;2315.7452680205165;2366.3965688749345;2370.691810064689;2373.4066483379156;2301.3326135420707;2354.6585745066955;2355.8939847706893;2362.9527409451803;2372.5847976604027;2296.0700916128994;2316.3630001749484;2340.0756701103055;2343.4492894170025;2346.2492002559275;2312.0289478418936;2324.4421338878715;2325.1164557468055;2326.2320636316354;2326.7789559991775;11175.105991520659;11278;11295;11304.434778261055;11313.943430282796;5480;5615.887682246333;5803.984302747002;5817.418258174191;5831.154482600042;3341.7159541439005;3413.163774875147;3555.1112221944513;3557.928841423157;3575.2670702505925;1126.7690123524658;1149.3046706742468;1165.6503049085325;1182.0071140242153;1195.0320240605138;13097.148685335424;14133.081349712236;14413.567592972333;15685.843141568552;15736.921315393463;4618.491965883817;4722.504098040792;4812.975935120372;4841.564259216617;4858.854234372974;12935.223886566837;13134;13341.466341346431;13375.264360460269;13408.597742067754;5785.508231800344;5879.617824841457;5905.852353691193;5906.173135760997;5923.614965027259;4888.951110488921;4894.559489645949;5140.30605868203;5153.433122356538;5223.164293712987;3594.227241143167;3735.5704094029534;3886.6696330812197;4001.159756451178;4027.436158937751;1046.7540128069897;1068.5405275731355;1070.710908054821;1081.4214395298634;1082.2045796339685;949.8432758594893;964.1900803325241;973.5083782689696;975.9947254328054;991.9208050429214;6607.702653380513;6745.258021617618;6820.420264277493;7007.8598428031155;7018.122734658168;991.1723710701644;1025.6153942271649;1033.9534720937577;1072.420892717939;1076.4294923690456;3468.4103702370658;4055.9391609125887;4079.7756123413496;4279.893002674958;4518.390017347681;13379.665508362368;13951.093178943336;13968.231747253998;13986.02097853161;14008;11251.493682784294;13678.042537022511;13756;13994.16035037901;14016.789748153786;10332.483375831178;10398.324108933046;10410.427426491464;10439.478026098664;10458.26792124544;5669.829905102853;6914.585124892523;7020.859582808417;7073.115860517435;7129.857402852018;12186.51253949832;12189.085818563466;12306.630801075982;12447.315397653216;12544.184627999151;12486.37568121572;12595.307258100893;12613.117081804186;12760.29818359999;12820.358982050673;8617.009043959773;8757.824843503222;8790.780230494289;9177.54112294396;9582.089701478348;10690.679279621623;10776.353418794897;10854.728631784268;10870.293430926964;10897.455127243764;9063.546822658709;9161;9164.221041211571;9173.082691730773;9257.981622021438;8823.602937867683;8959.1936725695;8979.057198994273;9147.039560846015;9201.815963680556;7963.084245311863;8051.35589152878;8080.030396352476;8175.959120204361;8231.46495477792;6012.188354572252;6089.056196289582;6243.937560624336;6260.593061450991;6264.404881536247;6057.515398768;6093.2078829751845;6235.189425374672;6277.591956522811;6334.144890439732 10:48:42 INFO - PROCESS | 1645 | |4;prototype.html;338.76117337277225;380.8781190019194;382.8124219132624;384.2046962787032;390.7889739540647;4430.712003719756;4865.078294073839;5040.4959504049575;5119.590432765379;5150.665206761567;4366.737995720259;4470;4577.2218722817115;4654.510556621883;5104.770285337159;3772.622737726219;4240.978795106035;4258.680598955075;4258.786245919913;4320.56794320567;3762.548494180702;4259.190753756776;4265.360195970598;4283.764392958386;4295;3302.735781137503;4307.418498502693;4311.5472875348105;4355.842014539449;4364.279893817532;3555.520004799371;4051.3517837146064;4272.252355837716;4272.423222864904;4335.479742430897;3317.6350601433824;3888.911104890635;3947.4276229946495;3949.664278536328;3983.1635356575152;4375.474943006828;4484.78910694114;4584.885377865547;4833.371661683958;5037.546620804109;4216.51510076342;4769.2307692307695;4841.806327746921;4892.266160075981;4967.994363410121;5421.783128674888;5470.717569458333;5514.476124768141;5514.862128446781;5558.833235002957;4270.508891477489;4414.801333939949;4422.6240769534625;4433;4492.14649216651;4953.331300274453;5047.49525047496;5107.029664363759;5638.943610563882;5861.5603829712945;4133.111381053088;4152.854650087233;4480.439945006874;4543.45478542577;4547.295169248771;3789.5642001170218;4450.910981780411;4624.635732458933;4681.882952926136;4696.694714843524;4418.204723149864;4610.832908532984;4889.1421259921;4927.211646136601;5030.496950304941;4416.447944006999;4952.4304704959395;5306.787728490895;5371.5702743781185;5398.217258497496;1325.9469621215237;1494.880409567232;1536.5159974607964;1538.707326084149;1542.1672296959741;1008.1934452438167;1032.3805716569998;1032.7573020340214;1164.7204670879098;1194.3669854976877;4116.91766164681;4608.847788052987;4682.2508398656055;4891.828785992544;4979.352684151109;4876.829310974099;5149.098907691214;5150.922736158961;5688.971555142198;5741.483266506035;3822.4839646647347;4822.903541929142;4905.779239934212;4943.826966056171;4974.850754477371;2564.756348146923;2588.327034970921;2645.3915599411857;2662.5606774882317;2740.6022928306306;4798.904021919612;5837.161294192316;5850.9414905851245;5887.705614719247;5889.146073819272;4895.98036819607;5143.948560514422;5299.39057008449;5714.200011998323;5824.912626310609;5191.766370513336;5719.742611582489;5939.376365481735;5957.434043765922;5958.970205148947;4718.4337879453315;5243.295928823224;5305.442928492607;5357.517823395913;5728.942710572841;4908.521196795738;5015.244664367442;5396.084390041636;5570.498655121059;5631.971840140937;5056.266841307916;5597.468240517144;5620.662760234399;5730.255066841284;5755.7122143893475;4031.581051369155;4265.402843601836;4399.560043995575;4477.283634618446;4492.393526873961;4603.562661547282;4917.827876024322;4948.727819969792;5324.920126198188;5710.286913925888;4563.543645635543;5362.9463705364005;5468.72656367188;5498.862528436821;5610.43895610452;4678.689966809299;4780.131920847463;5473.917891231715;5525.751341189576;5644.238027866186;5093.4651861554;5397.298351214317;5412.702301373462;5431.664384314625;5468;5243.292155558951;5409.540189083972;5430.669485975961;5454.690874190086;5489.286392768914;4729.8108075677965;4787.54518320759;5314.4951229633125;5318.60662701778;5410.485064182035 10:48:42 INFO - PROCESS | 1645 | |5;yui.html;736.7936977646261;781.1056340490139;784.5920121536801;842.5871323051706;843.4812590256992;14123.78814317786;15577.532674019783;16310.51068467945;16357.427490037851;16514.83485165148;14928.178950157764;15240.390384384638;15308.770368444455;15477.071375717465;15477.148756818371;968.9127978481953;1010.9140723038514;1013.9138173255283;1016.1972042086743;1018.221060888421;978.9265805064613;1004.3772860826261;1007.0835539658912;1008.5562352564884;1009.8586197932259;1061.5010944855906;1061.5806756331244;1062.3254233561668;1063.3247887591397;1064.4145719854089;1049.968500944973;1053.410090349403;1053.6628278950739;1055.5672174408455;1059.3379138038727;787.1577412168981;788.1330536409961;788.9921100789034;789.2659826361481;791.1732239809413;16252;16642.75035874463;16699.248533816008;16762.83237167637;17078.146092695315;14314.498992535211;14636.34136463851;14656;14661.780073298909;14672.559823205322;11686;11844.952253409208;11989.520419183222;12024.939875300655;12165.20926139807;3404.740246108931;3488.61625221228;3874.8256328465286;3912.8239229234755;3945.4673619061346;103771.54741431001;115980;116626;117486.8251317493;117895.99999999914;9230.446173229628;9934.304598678023;10643.095336896296;11173.994340509393;11250.549978000952;23299.53400931972;23545.764542354358;23700.644490332314;23721.525569488513;23755.762442375355;7968.960155199303;8033.3974951878845;8099.797505062303;8118.981467650526;8349.123342049119;4450.265706158512;4481.260592002348;4557.407537020166;4657.417822772153;4738.076075165379;3441.1225137589754;3498.2478767065204;3549.112721819545;3568.4647302904255;3586.0138461922656;595.5206059122362;606.1756011823916;609.2688773471851;613.7330261336333;618.1654766065775;584.5820238529471;677.7119724117231;682.7098483144644;706.3042902740797;749.318120510333;8478.23695867375;9191.494467804336;9942.602295908227;10369.481525923673;10393.921215756878;689.1557841643946;693.1785833786979;713.9492798532499;716.7570461282289;719.2376081353782;4807.519248075235;4975.925361119586;5020.347354843847;5028.497150284942;5033.270175824485;96721.61474348365;96868.51565742126;98411.50794245982;98436;99328.50335748275;68799.65600171968;80600;81159.59420202862;82229.17770822335;87015.56492217498;73274.6336268326;76243.47513049709;76618;76629.23370766333;78381.4323713534;10682.249525522175;11048.723781905517;11123.110151188048;11151.832722509007;11359.659210223706;45548.95029723604;45824.54175458203;47092;48008.5199148018;48346.5165348342;60728.6963565197;61014.08478872732;69019;70107.64946175237;70949.64525177548;12906.612801615967;13041.934790326368;13164.210147391188;13623;13757.724845502833;11758;14743.041702289316;15051.548453546411;15356.38574457054;15971.121588312755;12808.807867881802;13065.281409522568;13162.40769165409;13237.271950042456;13623.659408514866;13335.66660833448;13742.725145496834;14026.438942442595;14446.494372696905;14788.778168327699;13119.868801311864;13214.669633258862;13803.792943106062;13804.792928105886;13909.165450073027;8215.835683286421;8484.321254299517;9159.22146617545;9560.713178604654;9625.181859542017;9484.814398200226;10660.680179594625;11274.101211361789;11485.942570287096;11493.195476316578 10:48:42 INFO - PROCESS | 1645 | __end_tp_report 10:48:42 INFO - PROCESS | 1645 | __start_cc_report 10:48:42 INFO - PROCESS | 1645 | _x_x_mozilla_cycle_collect,1624 10:48:42 INFO - PROCESS | 1645 | __end_cc_report 10:48:42 INFO - PROCESS | 1645 | __startTimestamp1495734522154__endTimestamp 10:48:42 INFO - PROCESS | 1645 | 10:48:42 INFO - PROCESS | 1645 | ------- Summary: start ------- 10:48:42 INFO - PROCESS | 1645 | Number of tests: 6 10:48:42 INFO - PROCESS | 1645 | 10:48:42 INFO - PROCESS | 1645 | [#0] dojo.html Cycles:165 Average:5873.15 Median:6122.36 stddev:677.82 (11.1%) stddev-sans-first:651.33 10:48:42 INFO - PROCESS | 1645 | Values: 3391.1 3429.3 3432.2 3461.5 3477.8 5931.3 5940.4 5949.9 5991.0 6014.6 5119.4 5745.9 5918.4 5979.3 5979.6 5295.9 5320.7 5342.8 5385.5 5433.0 5255.2 5257.2 5329.1 5466.0 5486.6 5387.4 5392.6 5398.6 5407.4 5422.8 5452.0 5478.6 5486.0 5490.5 5492.3 5329.3 5415.5 5418.7 5419.5 5455.4 5706.5 5780.8 5821.0 6018.2 6032.4 5235.2 6339.9 6368.6 6372.2 6391.0 4913.7 6373.9 6396.0 6397.8 6403.3 5938.9 6096.7 6113.5 6149.2 6156.9 6334.3 6343.7 6390.0 6407.8 6430.6 6379.9 6382.8 6395.2 6397.1 6397.6 6336.0 6354.2 6374.6 6416.0 6417.6 6225.5 6295.8 6333.6 6370.6 6394.6 6278.6 6333.2 6340.9 6344.8 6384.6 4866.6 4888.1 4888.2 4915.5 4918.4 4393.0 4424.8 4441.9 4450.8 4482.6 5574.2 5607.6 5650.4 5739.2 5757.3 6366.7 6404.7 6410.4 6431.9 6432.8 5820.5 5824.2 5830.2 5840.0 5861.8 5306.8 5548.3 5554.8 5555.8 5569.0 5376.7 6340.2 6353.1 6485.2 6567.1 6314.7 6346.7 6396.3 6398.6 6416.1 6548.4 6565.8 6571.2 6580.0 6593.5 6151.5 6222.6 6314.6 6335.0 6385.0 6374.0 6394.0 6400.6 6410.1 6412.0 6354.0 6363.9 6378.0 6378.5 6388.3 5993.7 6109.2 6237.2 6272.7 6314.7 6235.3 6246.5 6264.8 6278.1 6283.6 5038.7 6350.5 6385.6 6387.0 6394.4 6068.8 6112.1 6120.6 6122.4 6136.2 10:48:42 INFO - PROCESS | 1645 | 10:48:42 INFO - PROCESS | 1645 | [#1] ext.html Cycles:180 Average:54739.91 Median:29935.13 stddev:108144.69 (361.3%) stddev-sans-first:108372.01 10:48:42 INFO - PROCESS | 1645 | Values: 720.8 768.3 821.4 885.6 900.1 28563.4 41599.0 41969.8 42006.0 42086.2 41870.0 42058.4 42065.0 42128.4 42143.8 7647.6 9391.4 9526.1 9540.4 9553.4 7695.3 11536.9 11838.3 11984.5 11988.8 11770.8 11803.0 11812.2 11817.5 11867.4 11735.4 11739.7 11740.5 11757.6 11769.6 11500.9 11567.6 11590.5 11596.5 11600.9 40958.0 40987.6 41050.0 41072.6 41110.4 3915.6 3919.9 3957.3 3968.9 4002.9 27782.7 27967.4 29136.7 30035.5 30117.5 3168.8 3181.9 3200.0 3327.6 3330.3 358099.0 362856.2 363057.0 363098.2 363293.0 53049.0 53089.7 53183.9 53234.7 53284.5 56722.0 56839.4 56852.0 56872.4 57065.7 14696.4 15000.7 15013.2 15021.7 15057.2 11263.6 11424.0 12906.7 12908.4 12916.7 12321.2 12621.9 12753.3 12791.9 12793.6 4603.3 5352.6 5359.7 5365.9 5379.1 1005.7 1102.3 1128.7 1161.9 1203.3 26898.9 27055.9 27605.0 27684.3 27851.0 1224.4 1232.1 1245.9 1274.4 1278.6 13837.8 14103.4 14133.9 14228.1 14494.3 462617.0 464667.7 468251.0 468541.0 469352.0 57871.7 58016.0 58126.4 58160.8 58333.8 1282.3 1440.5 1478.8 1486.9 1488.3 25697.5 25902.4 26353.5 26904.6 27284.8 46833.0 47183.0 47287.3 47369.0 47476.8 385602.0 388337.0 388889.0 389943.0 390223.3 27836.9 29163.3 29444.7 29724.0 30939.5 31799.4 32085.2 33800.8 42364.4 42721.7 27724.3 28456.7 28465.9 29038.7 29831.6 24063.8 24365.8 24809.6 29804.6 29869.4 33188.0 33210.5 33290.7 33823.0 33939.3 28761.0 29058.0 29424.9 29774.7 30000.8 19758.2 21271.8 23220.0 23310.2 23394.5 10:48:42 INFO - PROCESS | 1645 | 10:48:42 INFO - PROCESS | 1645 | [#2] jquery.html Cycles:180 Average:43329.66 Median:22514.49 stddev:128888.00 (572.5%) stddev-sans-first:129209.29 10:48:42 INFO - PROCESS | 1645 | Values: 419.1 429.5 430.3 437.2 438.4 21857.5 22511.7 22659.0 23622.8 23878.9 22517.3 23027.5 23396.4 23555.3 23994.5 5921.6 6647.0 6900.4 6993.4 6998.6 5993.3 6772.9 6932.9 6942.9 7625.8 7673.6 7728.0 7747.1 7751.3 7759.2 7393.9 7631.0 7692.2 7705.7 7711.5 7232.2 7468.7 7468.7 7540.9 7550.4 25886.0 26412.9 27121.6 27137.9 27315.9 2822.2 2854.0 2937.0 3018.0 3027.3 16343.8 16362.4 16396.2 17136.2 17324.8 1973.2 2023.3 2119.7 2161.8 2187.2 134405.3 136803.3 137616.9 148997.0 154166.0 16101.3 16296.4 18900.0 20123.0 20234.0 31505.8 31947.0 32009.7 32104.0 32170.7 10437.6 10464.1 10506.7 10667.7 10856.9 8956.6 8971.5 8999.8 9167.4 9176.0 8411.8 8514.7 8987.6 9337.6 9389.9 3465.5 3904.1 3919.0 4041.7 4041.8 950.9 1067.3 1085.2 1106.1 1110.6 20501.9 21260.8 21485.8 21636.2 21755.8 924.9 1090.8 1092.2 1138.9 1154.6 10940.5 11339.1 11355.0 11359.4 11360.3 762021.0 766260.0 766797.0 782945.0 805492.0 89735.0 122799.0 152805.0 164863.0 170500.0 1234.6 1282.4 1324.4 1328.8 1333.9 19201.8 19647.3 20119.8 20132.3 20141.6 48702.0 62640.0 64078.0 64743.7 65270.0 89677.6 89788.0 90313.0 92345.5 92366.7 11109.8 11300.9 11387.0 11712.8 11758.0 12328.6 12523.1 12661.7 12783.7 12877.5 10512.4 10729.8 10825.1 11219.5 11255.5 9291.9 9557.7 9833.1 10130.2 10205.9 10973.0 11102.3 11221.6 11419.4 11561.6 11067.6 11225.3 11241.8 11293.9 11440.5 10770.4 11029.7 11089.4 11442.8 11732.5 10:48:42 INFO - PROCESS | 1645 | 10:48:42 INFO - PROCESS | 1645 | [#3] mootools.html Cycles:180 Average:6832.37 Median:2350.45 stddev:4287.80 (182.4%) stddev-sans-first:4281.97 10:48:42 INFO - PROCESS | 1645 | Values: 1623.6 1624.4 1650.9 1654.6 1664.8 10853.7 11457.8 11466.3 11469.9 11474.5 9980.8 10246.2 10370.8 10385.8 11182.3 2309.2 2339.0 2340.0 2341.3 2341.7 2273.2 2315.7 2366.4 2370.7 2373.4 2301.3 2354.7 2355.9 2363.0 2372.6 2296.1 2316.4 2340.1 2343.4 2346.2 2312.0 2324.4 2325.1 2326.2 2326.8 11175.1 11278.0 11295.0 11304.4 11313.9 5480.0 5615.9 5804.0 5817.4 5831.2 3341.7 3413.2 3555.1 3557.9 3575.3 1126.8 1149.3 1165.7 1182.0 1195.0 13097.1 14133.1 14413.6 15685.8 15736.9 4618.5 4722.5 4813.0 4841.6 4858.9 12935.2 13134.0 13341.5 13375.3 13408.6 5785.5 5879.6 5905.9 5906.2 5923.6 4889.0 4894.6 5140.3 5153.4 5223.2 3594.2 3735.6 3886.7 4001.2 4027.4 1046.8 1068.5 1070.7 1081.4 1082.2 949.8 964.2 973.5 976.0 991.9 6607.7 6745.3 6820.4 7007.9 7018.1 991.2 1025.6 1034.0 1072.4 1076.4 3468.4 4055.9 4079.8 4279.9 4518.4 13379.7 13951.1 13968.2 13986.0 14008.0 11251.5 13678.0 13756.0 13994.2 14016.8 10332.5 10398.3 10410.4 10439.5 10458.3 5669.8 6914.6 7020.9 7073.1 7129.9 12186.5 12189.1 12306.6 12447.3 12544.2 12486.4 12595.3 12613.1 12760.3 12820.4 8617.0 8757.8 8790.8 9177.5 9582.1 10690.7 10776.4 10854.7 10870.3 10897.5 9063.5 9161.0 9164.2 9173.1 9258.0 8823.6 8959.2 8979.1 9147.0 9201.8 7963.1 8051.4 8080.0 8176.0 8231.5 6012.2 6089.1 6243.9 6260.6 6264.4 6057.5 6093.2 6235.2 6277.6 6334.1 10:48:42 INFO - PROCESS | 1645 | 10:48:42 INFO - PROCESS | 1645 | [#4] prototype.html Cycles:180 Average:4507.13 Median:4828.14 stddev:1268.49 (26.3%) stddev-sans-first:1232.86 10:48:42 INFO - PROCESS | 1645 | Values: 338.8 380.9 382.8 384.2 390.8 4430.7 4865.1 5040.5 5119.6 5150.7 4366.7 4470.0 4577.2 4654.5 5104.8 3772.6 4241.0 4258.7 4258.8 4320.6 3762.5 4259.2 4265.4 4283.8 4295.0 3302.7 4307.4 4311.5 4355.8 4364.3 3555.5 4051.4 4272.3 4272.4 4335.5 3317.6 3888.9 3947.4 3949.7 3983.2 4375.5 4484.8 4584.9 4833.4 5037.5 4216.5 4769.2 4841.8 4892.3 4968.0 5421.8 5470.7 5514.5 5514.9 5558.8 4270.5 4414.8 4422.6 4433.0 4492.1 4953.3 5047.5 5107.0 5638.9 5861.6 4133.1 4152.9 4480.4 4543.5 4547.3 3789.6 4450.9 4624.6 4681.9 4696.7 4418.2 4610.8 4889.1 4927.2 5030.5 4416.4 4952.4 5306.8 5371.6 5398.2 1325.9 1494.9 1536.5 1538.7 1542.2 1008.2 1032.4 1032.8 1164.7 1194.4 4116.9 4608.8 4682.3 4891.8 4979.4 4876.8 5149.1 5150.9 5689.0 5741.5 3822.5 4822.9 4905.8 4943.8 4974.9 2564.8 2588.3 2645.4 2662.6 2740.6 4798.9 5837.2 5850.9 5887.7 5889.1 4896.0 5143.9 5299.4 5714.2 5824.9 5191.8 5719.7 5939.4 5957.4 5959.0 4718.4 5243.3 5305.4 5357.5 5728.9 4908.5 5015.2 5396.1 5570.5 5632.0 5056.3 5597.5 5620.7 5730.3 5755.7 4031.6 4265.4 4399.6 4477.3 4492.4 4603.6 4917.8 4948.7 5324.9 5710.3 4563.5 5362.9 5468.7 5498.9 5610.4 4678.7 4780.1 5473.9 5525.8 5644.2 5093.5 5397.3 5412.7 5431.7 5468.0 5243.3 5409.5 5430.7 5454.7 5489.3 4729.8 4787.5 5314.5 5318.6 5410.5 10:48:42 INFO - PROCESS | 1645 | 10:48:42 INFO - PROCESS | 1645 | [#5] yui.html Cycles:180 Average:20336.36 Median:23738.64 stddev:28939.15 (121.9%) stddev-sans-first:28982.91 10:48:42 INFO - PROCESS | 1645 | Values: 736.8 781.1 784.6 842.6 843.5 14123.8 15577.5 16310.5 16357.4 16514.8 14928.2 15240.4 15308.8 15477.1 15477.1 968.9 1010.9 1013.9 1016.2 1018.2 978.9 1004.4 1007.1 1008.6 1009.9 1061.5 1061.6 1062.3 1063.3 1064.4 1050.0 1053.4 1053.7 1055.6 1059.3 787.2 788.1 789.0 789.3 791.2 16252.0 16642.8 16699.2 16762.8 17078.1 14314.5 14636.3 14656.0 14661.8 14672.6 11686.0 11845.0 11989.5 12024.9 12165.2 3404.7 3488.6 3874.8 3912.8 3945.5 103771.5 115980.0 116626.0 117486.8 117896.0 9230.4 9934.3 10643.1 11174.0 11250.5 23299.5 23545.8 23700.6 23721.5 23755.8 7969.0 8033.4 8099.8 8119.0 8349.1 4450.3 4481.3 4557.4 4657.4 4738.1 3441.1 3498.2 3549.1 3568.5 3586.0 595.5 606.2 609.3 613.7 618.2 584.6 677.7 682.7 706.3 749.3 8478.2 9191.5 9942.6 10369.5 10393.9 689.2 693.2 713.9 716.8 719.2 4807.5 4975.9 5020.3 5028.5 5033.3 96721.6 96868.5 98411.5 98436.0 99328.5 68799.7 80600.0 81159.6 82229.2 87015.6 73274.6 76243.5 76618.0 76629.2 78381.4 10682.2 11048.7 11123.1 11151.8 11359.7 45549.0 45824.5 47092.0 48008.5 48346.5 60728.7 61014.1 69019.0 70107.6 70949.6 12906.6 13041.9 13164.2 13623.0 13757.7 11758.0 14743.0 15051.5 15356.4 15971.1 12808.8 13065.3 13162.4 13237.3 13623.7 13335.7 13742.7 14026.4 14446.5 14788.8 13119.9 13214.7 13803.8 13804.8 13909.2 8215.8 8484.3 9159.2 9560.7 9625.2 9484.8 10660.7 11274.1 11485.9 11493.2 10:48:42 INFO - PROCESS | 1645 | -------- Summary: end -------- 10:48:42 INFO - PROCESS | 1645 | 10:48:42 INFO - TEST-INFO | 1645: exit 0 10:48:42 INFO - TEST-OK | dromaeo_css | took 1119230ms 10:48:42 INFO - TEST-START | kraken 10:48:42 INFO - operating with platform_type : mac_ 10:48:42 INFO - Initialising browser for kraken test... 10:48:42 INFO - TEST-INFO | started process 1655 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/tmpWv9Eu4/profile http://localhost:49224/getInfo.html) 10:48:45 INFO - PROCESS | 1655 | __metrics Screen width/height:1600/1200 10:48:45 INFO - PROCESS | 1655 | colorDepth:24 10:48:45 INFO - PROCESS | 1655 | Browser inner width/height: 1024/689 10:48:45 INFO - PROCESS | 1655 | __metrics 10:48:45 INFO - PROCESS | 1655 | 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" 10:48:45 INFO - PROCESS | 1655 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 10:48:45 INFO - TEST-INFO | 1655: exit 0 10:48:45 INFO - Browser initialized. 10:48:45 INFO - Running cycle 1/1 for kraken test... 10:48:45 INFO - TEST-INFO | started process 1657 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/tmpWv9Eu4/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:48:57 INFO - PROCESS | 1657 | RSS: Main: 193945600 10:48:57 INFO - PROCESS | 1657 | 10:49:00 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49224/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49224/tests/kraken/driver.html?testName=audio-beat-detection) 10:49:01 INFO - PROCESS | 1657 | RSS: Main: 244563968 10:49:01 INFO - PROCESS | 1657 | 10:49:04 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49224/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49224/tests/kraken/driver.html?testName=audio-dft) 10:49:04 INFO - PROCESS | 1657 | RSS: Main: 258908160 10:49:04 INFO - PROCESS | 1657 | 10:49:07 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49224/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49224/tests/kraken/driver.html?testName=audio-fft) 10:49:08 INFO - PROCESS | 1657 | RSS: Main: 357244928 10:49:08 INFO - PROCESS | 1657 | 10:49:10 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49224/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49224/tests/kraken/driver.html?testName=audio-oscillator) 10:49:11 INFO - PROCESS | 1657 | RSS: Main: 258949120 10:49:11 INFO - PROCESS | 1657 | 10:49:13 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49224/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49224/tests/kraken/driver.html?testName=imaging-gaussian-blur) 10:49:13 INFO - PROCESS | 1657 | RSS: Main: 260464640 10:49:13 INFO - PROCESS | 1657 | 10:49:16 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49224/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49224/tests/kraken/driver.html?testName=imaging-darkroom) 10:49:17 INFO - PROCESS | 1657 | RSS: Main: 301551616 10:49:17 INFO - PROCESS | 1657 | 10:49:20 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49224/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49224/tests/kraken/driver.html?testName=imaging-desaturate) 10:49:21 INFO - PROCESS | 1657 | RSS: Main: 300105728 10:49:21 INFO - PROCESS | 1657 | 10:49:24 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49224/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49224/tests/kraken/driver.html?testName=json-parse-financial) 10:49:24 INFO - PROCESS | 1657 | RSS: Main: 298164224 10:49:24 INFO - PROCESS | 1657 | 10:49:26 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49224/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49224/tests/kraken/driver.html?testName=json-stringify-tinderbox) 10:49:26 INFO - PROCESS | 1657 | RSS: Main: 277032960 10:49:26 INFO - PROCESS | 1657 | 10:49:28 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49224/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49224/tests/kraken/driver.html?testName=stanford-crypto-aes) 10:49:29 INFO - PROCESS | 1657 | RSS: Main: 278134784 10:49:29 INFO - PROCESS | 1657 | 10:49:31 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49224/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49224/tests/kraken/driver.html?testName=stanford-crypto-ccm) 10:49:32 INFO - PROCESS | 1657 | RSS: Main: 282124288 10:49:32 INFO - PROCESS | 1657 | 10:49:35 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49224/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49224/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 10:49:36 INFO - PROCESS | 1657 | RSS: Main: 298348544 10:49:36 INFO - PROCESS | 1657 | 10:49:38 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49224/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49224/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 10:49:39 INFO - PROCESS | 1657 | RSS: Main: 292089856 10:49:39 INFO - PROCESS | 1657 | 10:49:41 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49224/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49224/tests/kraken/driver.html?testName=ai-astar) 10:49:41 INFO - PROCESS | 1657 | RSS: Main: 336609280 10:49:41 INFO - PROCESS | 1657 | 10:49:41 INFO - PROCESS | 1657 | __start_tp_report 10:49:41 INFO - PROCESS | 1657 | _x_x_mozilla_page_load 10:49:41 INFO - PROCESS | 1657 | _x_x_mozilla_page_load_details 10:49:41 INFO - PROCESS | 1657 | |i|pagename|runs| 10:49:41 INFO - PROCESS | 1657 | |0;ai-astar;103;114;115;114;114;115;114;116;112;114 10:49:41 INFO - PROCESS | 1657 | |1;audio-beat-detection;144;109;116;173;137;126;106;108;152;136 10:49:41 INFO - PROCESS | 1657 | |2;audio-dft;185;184;196;264;201;183;184;184;184;184 10:49:41 INFO - PROCESS | 1657 | |3;audio-fft;84;76;83;116;75;60;60;60;75;58 10:49:41 INFO - PROCESS | 1657 | |4;audio-oscillator;88;71;86;69;68;71;81;69;69;83 10:49:41 INFO - PROCESS | 1657 | |5;imaging-gaussian-blur;86;87;87;87;87;87;88;88;87;87 10:49:41 INFO - PROCESS | 1657 | |6;imaging-darkroom;94;95;101;147;96;94;95;95;102;127 10:49:41 INFO - PROCESS | 1657 | |7;imaging-desaturate;85;86;84;84;86;83;84;82;85;87 10:49:41 INFO - PROCESS | 1657 | |8;json-parse-financial;60;62;62;65;63;63;65;63;65;63 10:49:41 INFO - PROCESS | 1657 | |9;json-stringify-tinderbox;56;63;90;88;87;74;58;64;58;59 10:49:41 INFO - PROCESS | 1657 | |10;stanford-crypto-aes;86;84;82;85;72;79;91;97;99;83 10:49:41 INFO - PROCESS | 1657 | |11;stanford-crypto-ccm;135;136;137;135;134;167;159;144;181;136 10:49:41 INFO - PROCESS | 1657 | |12;stanford-crypto-pbkdf2;162;162;162;164;162;163;162;159;164;162 10:49:41 INFO - PROCESS | 1657 | |13;stanford-crypto-sha256-iterative;65;65;63;64;64;63;65;63;61;63 10:49:41 INFO - PROCESS | 1657 | __end_tp_report 10:49:41 INFO - PROCESS | 1657 | __start_cc_report 10:49:41 INFO - PROCESS | 1657 | _x_x_mozilla_cycle_collect,2133 10:49:41 INFO - PROCESS | 1657 | __end_cc_report 10:49:41 INFO - PROCESS | 1657 | __startTimestamp1495734581438__endTimestamp 10:49:41 INFO - PROCESS | 1657 | 10:49:41 INFO - PROCESS | 1657 | ------- Summary: start ------- 10:49:41 INFO - PROCESS | 1657 | Number of tests: 14 10:49:41 INFO - PROCESS | 1657 | 10:49:41 INFO - PROCESS | 1657 | [#0] ai-astar Cycles:10 Average:113.10 Median:114.00 stddev:3.70 (3.2%) stddev-sans-first:1.09 10:49:41 INFO - PROCESS | 1657 | Values: 103.0 114.0 115.0 114.0 114.0 115.0 114.0 116.0 112.0 114.0 10:49:41 INFO - PROCESS | 1657 | 10:49:41 INFO - PROCESS | 1657 | [#1] audio-beat-detection Cycles:10 Average:130.70 Median:136.50 stddev:21.91 (16.1%) stddev-sans-first:22.71 10:49:41 INFO - PROCESS | 1657 | Values: 144.0 109.0 116.0 173.0 137.0 126.0 106.0 108.0 152.0 136.0 10:49:41 INFO - PROCESS | 1657 | 10:49:41 INFO - PROCESS | 1657 | [#2] audio-dft Cycles:10 Average:194.90 Median:184.50 stddev:25.05 (13.6%) stddev-sans-first:26.31 10:49:41 INFO - PROCESS | 1657 | Values: 185.0 184.0 196.0 264.0 201.0 183.0 184.0 184.0 184.0 184.0 10:49:41 INFO - PROCESS | 1657 | 10:49:41 INFO - PROCESS | 1657 | [#3] audio-fft Cycles:10 Average:74.70 Median:75.00 stddev:17.61 (23.5%) stddev-sans-first:18.35 10:49:41 INFO - PROCESS | 1657 | Values: 84.0 76.0 83.0 116.0 75.0 60.0 60.0 60.0 75.0 58.0 10:49:41 INFO - PROCESS | 1657 | 10:49:41 INFO - PROCESS | 1657 | [#4] audio-oscillator Cycles:10 Average:75.50 Median:76.00 stddev:8.00 (10.5%) stddev-sans-first:7.10 10:49:41 INFO - PROCESS | 1657 | Values: 88.0 71.0 86.0 69.0 68.0 71.0 81.0 69.0 69.0 83.0 10:49:41 INFO - PROCESS | 1657 | 10:49:41 INFO - PROCESS | 1657 | [#5] imaging-gaussian-blur Cycles:10 Average:87.10 Median:87.00 stddev:0.57 (0.7%) stddev-sans-first:0.44 10:49:41 INFO - PROCESS | 1657 | Values: 86.0 87.0 87.0 87.0 87.0 87.0 88.0 88.0 87.0 87.0 10:49:41 INFO - PROCESS | 1657 | 10:49:41 INFO - PROCESS | 1657 | [#6] imaging-darkroom Cycles:10 Average:104.60 Median:94.50 stddev:17.93 (19.0%) stddev-sans-first:18.61 10:49:41 INFO - PROCESS | 1657 | Values: 94.0 95.0 101.0 147.0 96.0 94.0 95.0 95.0 102.0 127.0 10:49:41 INFO - PROCESS | 1657 | 10:49:41 INFO - PROCESS | 1657 | [#7] imaging-desaturate Cycles:10 Average:84.60 Median:85.00 stddev:1.51 (1.8%) stddev-sans-first:1.59 10:49:41 INFO - PROCESS | 1657 | Values: 85.0 86.0 84.0 84.0 86.0 83.0 84.0 82.0 85.0 87.0 10:49:41 INFO - PROCESS | 1657 | 10:49:41 INFO - PROCESS | 1657 | [#8] json-parse-financial Cycles:10 Average:63.10 Median:63.00 stddev:1.60 (2.5%) stddev-sans-first:1.24 10:49:41 INFO - PROCESS | 1657 | Values: 60.0 62.0 62.0 65.0 63.0 63.0 65.0 63.0 65.0 63.0 10:49:41 INFO - PROCESS | 1657 | 10:49:41 INFO - PROCESS | 1657 | [#9] json-stringify-tinderbox Cycles:10 Average:69.70 Median:69.00 stddev:13.82 (20.0%) stddev-sans-first:13.74 10:49:41 INFO - PROCESS | 1657 | Values: 56.0 63.0 90.0 88.0 87.0 74.0 58.0 64.0 58.0 59.0 10:49:41 INFO - PROCESS | 1657 | 10:49:41 INFO - PROCESS | 1657 | [#10] stanford-crypto-aes Cycles:10 Average:85.80 Median:85.50 stddev:8.09 (9.5%) stddev-sans-first:8.58 10:49:41 INFO - PROCESS | 1657 | Values: 86.0 84.0 82.0 85.0 72.0 79.0 91.0 97.0 99.0 83.0 10:49:41 INFO - PROCESS | 1657 | 10:49:41 INFO - PROCESS | 1657 | [#11] stanford-crypto-ccm Cycles:10 Average:146.40 Median:140.50 stddev:16.68 (11.9%) stddev-sans-first:17.18 10:49:41 INFO - PROCESS | 1657 | Values: 135.0 136.0 137.0 135.0 134.0 167.0 159.0 144.0 181.0 136.0 10:49:41 INFO - PROCESS | 1657 | 10:49:41 INFO - PROCESS | 1657 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:162.20 Median:162.00 stddev:1.40 (0.9%) stddev-sans-first:1.48 10:49:41 INFO - PROCESS | 1657 | Values: 162.0 162.0 162.0 164.0 162.0 163.0 162.0 159.0 164.0 162.0 10:49:41 INFO - PROCESS | 1657 | 10:49:41 INFO - PROCESS | 1657 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:63.60 Median:64.00 stddev:1.26 (2.0%) stddev-sans-first:1.24 10:49:41 INFO - PROCESS | 1657 | Values: 65.0 65.0 63.0 64.0 64.0 63.0 65.0 63.0 61.0 63.0 10:49:41 INFO - PROCESS | 1657 | -------- Summary: end -------- 10:49:41 INFO - PROCESS | 1657 | 10:49:42 INFO - TEST-INFO | 1657: exit 0 10:49:42 INFO - TEST-OK | kraken | took 59483ms 10:49:42 INFO - SUITE-END | took 1178s 10:49:42 INFO - Completed test suite (00:19:39) 10:49:42 INFO - javascript benchmark 10:49:42 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7737.860436294478, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3391.13526050857, 3429.3141371725646, 3432.2105915639386, 3461.4807778833188, 3477.843497042634, 5931.288245410546, 5940.405959404057, 5949.9107513387335, 5991.0, 6014.639121652704, 5119.436861945183, 5745.856353591163, 5918.378570250126, 5979.3123790764075, 5979.641221526701, 5295.867603309919, 5320.733963301839, 5342.809572326723, 5385.542228910547, 5433.0, 5255.159174532076, 5257.185136303879, 5329.120695085307, 5465.9726701366435, 5486.561075114001, 5387.434319396446, 5392.649477783932, 5398.595105367093, 5407.3781515125675, 5422.810201642943, 5452.0, 5478.561715062805, 5486.039943009957, 5490.505854473116, 5492.313460817398, 5329.307190065267, 5415.512603865632, 5418.7019713915715, 5419.458054194589, 5455.399906010336, 5706.457886500776, 5780.7687692492245, 5821.0, 6018.217631707894, 6032.396760323976, 5235.214717792323, 6339.936600633981, 6368.586041907308, 6372.203474565679, 6391.041343798421, 4913.729744864031, 6373.904391434132, 6395.968020159869, 6397.8400539986405, 6403.295637479873, 5938.940610593839, 6096.69516524172, 6113.480354169901, 6149.169862068564, 6156.938430615726, 6334.271558770801, 6343.651099189497, 6390.041493775971, 6407.807765767035, 6430.64631445275, 6379.872402551923, 6382.808515744535, 6395.232572091379, 6397.10440538325, 6397.616143031433, 6335.986242201226, 6354.17395738551, 6374.553781235361, 6416.0, 6417.550771445954, 6225.501959843202, 6295.811125666238, 6333.619982801047, 6370.617762934239, 6394.648294343763, 6278.623282603058, 6333.1766870306565, 6340.904886426707, 6344.841378965562, 6384.616922984543, 4866.586340161055, 4888.071266459361, 4888.2178851383605, 4915.459299477055, 4918.43438004634, 4392.956070439319, 4424.80088396023, 4441.888952776207, 4450.799714012813, 4482.551744825491, 5574.219609254707, 5607.579431542569, 5650.378458369576, 5739.196512488255, 5757.251557297608, 6366.713497892606, 6404.7117879695525, 6410.391012853772, 6431.935680643133, 6432.774852880127, 5820.505257053198, 5824.213731146326, 5830.242068531149, 5839.970800146057, 5861.824145275563, 5306.8407947761625, 5548.250986116904, 5554.750036248379, 5555.777768889199, 5568.9721551392795, 5376.731163441734, 6340.239171299474, 6353.142325786144, 6485.189351331083, 6567.113439685581, 6314.652694101868, 6346.746130154742, 6396.29640739509, 6398.5521013528605, 6416.101745755691, 6548.443382312556, 6565.835854103686, 6571.211454625475, 6580.0, 6593.538452308292, 6151.507879369729, 6222.595531290452, 6314.55798094129, 6335.049742538656, 6384.968075159594, 6373.968130159319, 6394.0, 6400.583962042452, 6410.134631824644, 6412.0, 6353.96823015882, 6363.936360636334, 6377.96811015942, 6378.489720822416, 6388.329225431263, 5993.700314984146, 6109.236345456818, 6237.1891654084675, 6272.655003974825, 6314.684265786784, 6235.251769787473, 6246.500279977682, 6264.843378915563, 6278.121063051085, 6283.59156654816, 5038.697678139323, 6350.523710721622, 6385.553011289165, 6386.978083506617, 6394.392532709385, 6068.817935461943, 6112.144299797943, 6120.632762034292, 6122.357152499102, 6136.23297087864], "value": 5830.308608858486, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [720.7801620505745, 768.3123604374085, 821.3552361396305, 885.5749240364629, 900.0639335091505, 28563.42873142536, 41599.0, 41969.7901510492, 42006.0, 42086.15827683448, 41869.953251168736, 42058.369124463155, 42065.0, 42128.36807447891, 42143.78928105363, 7647.579383133925, 9391.389559678617, 9526.142647161754, 9540.427574345527, 9553.426794392324, 7695.268949449794, 11536.884631153707, 11838.348890811001, 11984.46069926851, 11988.760224795498, 11770.7645847083, 11803.0, 11812.232204906697, 11817.527298908077, 11867.406629668525, 11735.354555499443, 11739.7065073373, 11740.471678774391, 11757.58848440309, 11769.588064417747, 11500.884991150066, 11567.595134170264, 11590.478428470738, 11596.478158482889, 11600.941995290053, 40958.0, 40987.59012409898, 41049.97375065617, 41072.58927410717, 41110.38334424986, 3915.569287378386, 3919.941200881989, 3957.2679054375035, 3968.86108986187, 4002.899927501807, 27782.722172778416, 27967.440651186862, 29136.70863291361, 30035.549466758017, 30117.548236776467, 3168.8415579221178, 3181.9204519886957, 3199.9520007199912, 3327.633960264369, 3330.283988942365, 358099.0, 362856.18571907235, 363057.0, 363098.18450908107, 363293.0, 53049.0, 53089.67275818058, 53183.93632127336, 53234.73382633062, 53284.467155328726, 56722.0, 56839.431605683414, 56852.0, 56872.431275686715, 57065.71467142721, 14696.41214351414, 15000.69998600022, 15013.24933753308, 15021.69956600862, 15057.17185554805, 11263.60577379804, 11424.0, 12906.741865162645, 12908.354582271037, 12916.741665166644, 12321.199122057038, 12621.873781262253, 12753.298568578632, 12791.872081279254, 12793.61619151427, 4603.286490593963, 5352.598555108382, 5359.732013399314, 5365.865853353697, 5379.112446446293, 1005.6932635546083, 1102.3496137278883, 1128.683968488808, 1161.8605767307977, 1203.2599951030054, 26898.92404303806, 27055.927609024893, 27605.0, 27684.30789230245, 27851.0, 1224.375568460074, 1232.1498166265428, 1245.875412458765, 1274.4074005587447, 1278.5652878021517, 13837.792433113511, 14103.365348559339, 14133.939954503248, 14228.075175113585, 14494.275286235646, 462617.0, 464667.73662921635, 468251.0, 468541.0, 469352.0, 57871.71064144652, 58016.0, 58126.41873581295, 58160.836783264094, 58333.833323333296, 1282.3203702037936, 1440.4526280013529, 1478.7633978563379, 1486.9256537173098, 1488.3153749275455, 25697.486050279265, 25902.35244118912, 26353.496417840273, 26904.596431053942, 27284.772185251313, 46833.0, 47183.0, 47287.290690638976, 47369.0, 47476.7626161867, 385602.0, 388337.0, 388889.0, 389943.0, 390223.2933012014, 27836.860815695793, 29163.270918227216, 29444.705552944197, 29724.0, 30939.535906960962, 31799.364012719154, 32085.197870053435, 33800.830995844866, 42364.36453453139, 42721.7183484496, 27724.30689232785, 28456.715432845405, 28465.861365545145, 29038.7096129036, 29831.552526711683, 24063.759362406152, 24365.756342436347, 24809.62785558182, 29804.552931706476, 29869.402611947204, 33188.0, 33210.5018424719, 33290.66709332875, 33823.0, 33939.321213576084, 28761.0, 29057.982970596775, 29424.852875735483, 29774.70225297806, 30000.849995750756, 19758.20967161355, 21271.7872821276, 23219.955102020787, 23310.184143554896, 23394.47935884162], "value": 18851.250196045614, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [419.1073014479159, 429.5146484472547, 430.29385565685527, 437.17592338442034, 438.3731264292061, 21857.453563660896, 22511.662325065117, 22659.0, 23622.763772362272, 23878.880605596947, 22517.32448026558, 23027.539449211046, 23396.415089622726, 23555.29334119979, 23994.520109597797, 5921.615095018831, 6646.9667651661675, 6900.413464855494, 6993.370596646301, 6998.615076170809, 5993.250843644544, 6772.8984065239065, 6932.861342773142, 6942.930570694279, 7625.8474830503355, 7673.577953212571, 7727.995360603142, 7747.109082455533, 7751.263629955173, 7759.224077592224, 7393.926060739404, 7631.046119235096, 7692.230776922319, 7705.6917723291, 7711.460197786158, 7232.1683006454405, 7468.738594149178, 7468.738594149233, 7540.886886696704, 7550.433717471212, 25886.0, 26412.867935660197, 27121.593176102375, 27137.86431067832, 27315.863420682967, 2822.1956742328543, 2854.006586169048, 2936.985315073411, 3017.9547306790414, 3027.273454370957, 16343.83656163435, 16362.427315043917, 16396.180190990402, 17136.228869700768, 17324.826751732573, 1973.1614064022876, 2023.3323003408839, 2119.7138386317806, 2161.827053835689, 2187.223535644839, 134405.3279733605, 136803.31598342044, 137616.93574596388, 148997.0, 154166.0, 16101.27544260511, 16296.42962496307, 18900.0, 20123.0, 20233.98830058491, 31505.8424707875, 31947.0, 32009.679903201137, 32104.0, 32170.67829321677, 10437.634682786065, 10464.058234758908, 10506.73733156662, 10667.733306667395, 10856.945715271373, 8956.552172391355, 8971.461712297283, 8999.82000359989, 9167.44995300284, 9176.036516165706, 8411.789705257295, 8514.659413623509, 8987.640494380152, 9337.626494940127, 9389.920159181645, 3465.462853257749, 3904.0674865940973, 3918.9810649230835, 4041.6766658667825, 4041.8383264669083, 950.9001554836781, 1067.32225037102, 1085.2132204151926, 1106.1040557148735, 1110.6001839337832, 20501.872397018305, 21260.78739212588, 21485.785142148692, 21636.24273150432, 21755.765173017633, 924.930630202724, 1090.7818436312768, 1092.2299778656188, 1138.8804175561613, 1154.5670373609896, 10940.507677154546, 11339.149563782585, 11355.034822040054, 11359.432028398547, 11360.318380897173, 762021.0, 766260.0, 766797.0, 782945.0, 805492.0, 89735.0, 122799.0, 152805.0, 164863.0, 170500.0, 1234.5802427174804, 1282.4229096906242, 1324.4040181918176, 1328.8471825740164, 1333.9466421343038, 19201.807981920003, 19647.31234406789, 20119.798802011792, 20132.29536966199, 20141.597168056265, 48702.0, 62640.0, 64078.0, 64743.67628161829, 65270.0, 89677.55161224153, 89788.0, 90313.0, 92345.53827230821, 92366.69083272971, 11109.777804444027, 11300.94349528247, 11387.0, 11712.765744684888, 11758.0, 12328.568500102454, 12523.123381363217, 12661.746765064463, 12783.680407989503, 12877.484900603871, 10512.369257844553, 10729.785404291715, 10825.133989280996, 11219.49512271966, 11255.493502792231, 9291.907080929104, 9557.713268601952, 9833.065858743408, 10130.240231982778, 10205.897941020496, 10973.0, 11102.2783519071, 11221.60724374643, 11419.429028548373, 11561.595344162914, 11067.557297708001, 11225.270357426742, 11241.831372529256, 11293.943530282297, 11440.542378305108, 10770.353778773298, 11029.724256893322, 11089.390083545482, 11442.82835757481, 11732.530698771954], "value": 11753.009417959793, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1623.553522781235, 1624.3583784405168, 1650.8596769274611, 1654.5698118489197, 1664.7586100015496, 10853.67438976831, 11457.770844583103, 11466.312021278729, 11469.942650286737, 11474.483648235828, 9980.800383992315, 10246.231532635045, 10370.792584148312, 10385.792284154313, 11182.273152245098, 2309.180241014437, 2338.9532209355802, 2339.988300058506, 2341.309313752439, 2341.672165896776, 2273.170292843117, 2315.7452680205165, 2366.3965688749345, 2370.691810064689, 2373.4066483379156, 2301.3326135420707, 2354.6585745066955, 2355.8939847706893, 2362.9527409451803, 2372.5847976604027, 2296.0700916128994, 2316.3630001749484, 2340.0756701103055, 2343.4492894170025, 2346.2492002559275, 2312.0289478418936, 2324.4421338878715, 2325.1164557468055, 2326.2320636316354, 2326.7789559991775, 11175.105991520659, 11278.0, 11295.0, 11304.434778261055, 11313.943430282796, 5480.0, 5615.887682246333, 5803.984302747002, 5817.418258174191, 5831.154482600042, 3341.7159541439005, 3413.163774875147, 3555.1112221944513, 3557.928841423157, 3575.2670702505925, 1126.7690123524658, 1149.3046706742468, 1165.6503049085325, 1182.0071140242153, 1195.0320240605138, 13097.148685335424, 14133.081349712236, 14413.567592972333, 15685.843141568552, 15736.921315393463, 4618.491965883817, 4722.504098040792, 4812.975935120372, 4841.564259216617, 4858.854234372974, 12935.223886566837, 13134.0, 13341.466341346431, 13375.264360460269, 13408.597742067754, 5785.508231800344, 5879.617824841457, 5905.852353691193, 5906.173135760997, 5923.614965027259, 4888.951110488921, 4894.559489645949, 5140.30605868203, 5153.433122356538, 5223.164293712987, 3594.227241143167, 3735.5704094029534, 3886.6696330812197, 4001.159756451178, 4027.436158937751, 1046.7540128069897, 1068.5405275731355, 1070.710908054821, 1081.4214395298634, 1082.2045796339685, 949.8432758594893, 964.1900803325241, 973.5083782689696, 975.9947254328054, 991.9208050429214, 6607.702653380513, 6745.258021617618, 6820.420264277493, 7007.8598428031155, 7018.122734658168, 991.1723710701644, 1025.6153942271649, 1033.9534720937577, 1072.420892717939, 1076.4294923690456, 3468.4103702370658, 4055.9391609125887, 4079.7756123413496, 4279.893002674958, 4518.390017347681, 13379.665508362368, 13951.093178943336, 13968.231747253998, 13986.02097853161, 14008.0, 11251.493682784294, 13678.042537022511, 13756.0, 13994.16035037901, 14016.789748153786, 10332.483375831178, 10398.324108933046, 10410.427426491464, 10439.478026098664, 10458.26792124544, 5669.829905102853, 6914.585124892523, 7020.859582808417, 7073.115860517435, 7129.857402852018, 12186.51253949832, 12189.085818563466, 12306.630801075982, 12447.315397653216, 12544.184627999151, 12486.37568121572, 12595.307258100893, 12613.117081804186, 12760.29818359999, 12820.358982050673, 8617.009043959773, 8757.824843503222, 8790.780230494289, 9177.54112294396, 9582.089701478348, 10690.679279621623, 10776.353418794897, 10854.728631784268, 10870.293430926964, 10897.455127243764, 9063.546822658709, 9161.0, 9164.221041211571, 9173.082691730773, 9257.981622021438, 8823.602937867683, 8959.1936725695, 8979.057198994273, 9147.039560846015, 9201.815963680556, 7963.084245311863, 8051.35589152878, 8080.030396352476, 8175.959120204361, 8231.46495477792, 6012.188354572252, 6089.056196289582, 6243.937560624336, 6260.593061450991, 6264.404881536247, 6057.515398768, 6093.2078829751845, 6235.189425374672, 6277.591956522811, 6334.144890439732], "value": 5193.49996872396, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [338.76117337277225, 380.8781190019194, 382.8124219132624, 384.2046962787032, 390.7889739540647, 4430.712003719756, 4865.078294073839, 5040.4959504049575, 5119.590432765379, 5150.665206761567, 4366.737995720259, 4470.0, 4577.2218722817115, 4654.510556621883, 5104.770285337159, 3772.622737726219, 4240.978795106035, 4258.680598955075, 4258.786245919913, 4320.56794320567, 3762.548494180702, 4259.190753756776, 4265.360195970598, 4283.764392958386, 4295.0, 3302.735781137503, 4307.418498502693, 4311.5472875348105, 4355.842014539449, 4364.279893817532, 3555.520004799371, 4051.3517837146064, 4272.252355837716, 4272.423222864904, 4335.479742430897, 3317.6350601433824, 3888.911104890635, 3947.4276229946495, 3949.664278536328, 3983.1635356575152, 4375.474943006828, 4484.78910694114, 4584.885377865547, 4833.371661683958, 5037.546620804109, 4216.51510076342, 4769.2307692307695, 4841.806327746921, 4892.266160075981, 4967.994363410121, 5421.783128674888, 5470.717569458333, 5514.476124768141, 5514.862128446781, 5558.833235002957, 4270.508891477489, 4414.801333939949, 4422.6240769534625, 4433.0, 4492.14649216651, 4953.331300274453, 5047.49525047496, 5107.029664363759, 5638.943610563882, 5861.5603829712945, 4133.111381053088, 4152.854650087233, 4480.439945006874, 4543.45478542577, 4547.295169248771, 3789.5642001170218, 4450.910981780411, 4624.635732458933, 4681.882952926136, 4696.694714843524, 4418.204723149864, 4610.832908532984, 4889.1421259921, 4927.211646136601, 5030.496950304941, 4416.447944006999, 4952.4304704959395, 5306.787728490895, 5371.5702743781185, 5398.217258497496, 1325.9469621215237, 1494.880409567232, 1536.5159974607964, 1538.707326084149, 1542.1672296959741, 1008.1934452438167, 1032.3805716569998, 1032.7573020340214, 1164.7204670879098, 1194.3669854976877, 4116.91766164681, 4608.847788052987, 4682.2508398656055, 4891.828785992544, 4979.352684151109, 4876.829310974099, 5149.098907691214, 5150.922736158961, 5688.971555142198, 5741.483266506035, 3822.4839646647347, 4822.903541929142, 4905.779239934212, 4943.826966056171, 4974.850754477371, 2564.756348146923, 2588.327034970921, 2645.3915599411857, 2662.5606774882317, 2740.6022928306306, 4798.904021919612, 5837.161294192316, 5850.9414905851245, 5887.705614719247, 5889.146073819272, 4895.98036819607, 5143.948560514422, 5299.39057008449, 5714.200011998323, 5824.912626310609, 5191.766370513336, 5719.742611582489, 5939.376365481735, 5957.434043765922, 5958.970205148947, 4718.4337879453315, 5243.295928823224, 5305.442928492607, 5357.517823395913, 5728.942710572841, 4908.521196795738, 5015.244664367442, 5396.084390041636, 5570.498655121059, 5631.971840140937, 5056.266841307916, 5597.468240517144, 5620.662760234399, 5730.255066841284, 5755.7122143893475, 4031.581051369155, 4265.402843601836, 4399.560043995575, 4477.283634618446, 4492.393526873961, 4603.562661547282, 4917.827876024322, 4948.727819969792, 5324.920126198188, 5710.286913925888, 4563.543645635543, 5362.9463705364005, 5468.72656367188, 5498.862528436821, 5610.43895610452, 4678.689966809299, 4780.131920847463, 5473.917891231715, 5525.751341189576, 5644.238027866186, 5093.4651861554, 5397.298351214317, 5412.702301373462, 5431.664384314625, 5468.0, 5243.292155558951, 5409.540189083972, 5430.669485975961, 5454.690874190086, 5489.286392768914, 4729.8108075677965, 4787.54518320759, 5314.4951229633125, 5318.60662701778, 5410.485064182035], "value": 4136.811026554693, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [736.7936977646261, 781.1056340490139, 784.5920121536801, 842.5871323051706, 843.4812590256992, 14123.78814317786, 15577.532674019783, 16310.51068467945, 16357.427490037851, 16514.83485165148, 14928.178950157764, 15240.390384384638, 15308.770368444455, 15477.071375717465, 15477.148756818371, 968.9127978481953, 1010.9140723038514, 1013.9138173255283, 1016.1972042086743, 1018.221060888421, 978.9265805064613, 1004.3772860826261, 1007.0835539658912, 1008.5562352564884, 1009.8586197932259, 1061.5010944855906, 1061.5806756331244, 1062.3254233561668, 1063.3247887591397, 1064.4145719854089, 1049.968500944973, 1053.410090349403, 1053.6628278950739, 1055.5672174408455, 1059.3379138038727, 787.1577412168981, 788.1330536409961, 788.9921100789034, 789.2659826361481, 791.1732239809413, 16252.0, 16642.75035874463, 16699.248533816008, 16762.83237167637, 17078.146092695315, 14314.498992535211, 14636.34136463851, 14656.0, 14661.780073298909, 14672.559823205322, 11686.0, 11844.952253409208, 11989.520419183222, 12024.939875300655, 12165.20926139807, 3404.740246108931, 3488.61625221228, 3874.8256328465286, 3912.8239229234755, 3945.4673619061346, 103771.54741431001, 115980.0, 116626.0, 117486.8251317493, 117895.99999999914, 9230.446173229628, 9934.304598678023, 10643.095336896296, 11173.994340509393, 11250.549978000952, 23299.53400931972, 23545.764542354358, 23700.644490332314, 23721.525569488513, 23755.762442375355, 7968.960155199303, 8033.3974951878845, 8099.797505062303, 8118.981467650526, 8349.123342049119, 4450.265706158512, 4481.260592002348, 4557.407537020166, 4657.417822772153, 4738.076075165379, 3441.1225137589754, 3498.2478767065204, 3549.112721819545, 3568.4647302904255, 3586.0138461922656, 595.5206059122362, 606.1756011823916, 609.2688773471851, 613.7330261336333, 618.1654766065775, 584.5820238529471, 677.7119724117231, 682.7098483144644, 706.3042902740797, 749.318120510333, 8478.23695867375, 9191.494467804336, 9942.602295908227, 10369.481525923673, 10393.921215756878, 689.1557841643946, 693.1785833786979, 713.9492798532499, 716.7570461282289, 719.2376081353782, 4807.519248075235, 4975.925361119586, 5020.347354843847, 5028.497150284942, 5033.270175824485, 96721.61474348365, 96868.51565742126, 98411.50794245982, 98436.0, 99328.50335748275, 68799.65600171968, 80600.0, 81159.59420202862, 82229.17770822335, 87015.56492217498, 73274.6336268326, 76243.47513049709, 76618.0, 76629.23370766333, 78381.4323713534, 10682.249525522175, 11048.723781905517, 11123.110151188048, 11151.832722509007, 11359.659210223706, 45548.95029723604, 45824.54175458203, 47092.0, 48008.5199148018, 48346.5165348342, 60728.6963565197, 61014.08478872732, 69019.0, 70107.64946175237, 70949.64525177548, 12906.612801615967, 13041.934790326368, 13164.210147391188, 13623.0, 13757.724845502833, 11758.0, 14743.041702289316, 15051.548453546411, 15356.38574457054, 15971.121588312755, 12808.807867881802, 13065.281409522568, 13162.40769165409, 13237.271950042456, 13623.659408514866, 13335.66660833448, 13742.725145496834, 14026.438942442595, 14446.494372696905, 14788.778168327699, 13119.868801311864, 13214.669633258862, 13803.792943106062, 13804.792928105886, 13909.165450073027, 8215.835683286421, 8484.321254299517, 9159.22146617545, 9560.713178604654, 9625.181859542017, 9484.814398200226, 10660.680179594625, 11274.101211361789, 11485.942570287096, 11493.195476316578], "value": 7733.50334747731, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1456.0000000000002, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [103.0, 114.0, 115.0, 114.0, 114.0, 115.0, 114.0, 116.0, 112.0, 114.0], "value": 113.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 109.0, 116.0, 173.0, 137.0, 126.0, 106.0, 108.0, 152.0, 136.0], "value": 130.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 184.0, 196.0, 264.0, 201.0, 183.0, 184.0, 184.0, 184.0, 184.0], "value": 194.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 76.0, 83.0, 116.0, 75.0, 60.0, 60.0, 60.0, 75.0, 58.0], "value": 74.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 71.0, 86.0, 69.0, 68.0, 71.0, 81.0, 69.0, 69.0, 83.0], "value": 75.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 87.0, 87.0, 87.0, 87.0, 87.0, 88.0, 88.0, 87.0, 87.0], "value": 87.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 95.0, 101.0, 147.0, 96.0, 94.0, 95.0, 95.0, 102.0, 127.0], "value": 104.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 86.0, 84.0, 84.0, 86.0, 83.0, 84.0, 82.0, 85.0, 87.0], "value": 84.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 62.0, 62.0, 65.0, 63.0, 63.0, 65.0, 63.0, 65.0, 63.0], "value": 63.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 63.0, 90.0, 88.0, 87.0, 74.0, 58.0, 64.0, 58.0, 59.0], "value": 69.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 84.0, 82.0, 85.0, 72.0, 79.0, 91.0, 97.0, 99.0, 83.0], "value": 85.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [135.0, 136.0, 137.0, 135.0, 134.0, 167.0, 159.0, 144.0, 181.0, 136.0], "value": 146.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 162.0, 162.0, 164.0, 162.0, 163.0, 162.0, 159.0, 164.0, 162.0], "value": 162.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 65.0, 63.0, 64.0, 64.0, 63.0, 65.0, 63.0, 61.0, 63.0], "value": 63.6, "unit": "score"}]}]} 10:49:42 INFO - Return code: 0 10:49:42 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 10:49:42 INFO - # TBPL SUCCESS # 10:49:42 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 10:49:42 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 10:49:42 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 10:49:42 INFO - 2017-05-25 10:49:42.738 screenresolution[1661:10343] starting screenresolution argv=screenresolution get 10:49:42 INFO - 2017-05-25 10:49:42.741 screenresolution[1661:10343] Display 0: 1600x1200x32@60 10:49:42 INFO - 2017-05-25 10:49:42.747 screenresolution[1662:10346] starting screenresolution argv=screenresolution list 10:49:42 INFO - Available Modes on Display 0 10:49:42 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 10:49:42 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 10:49:42 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 10:49:42 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 10:49:42 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 10:49:42 INFO - Intel Iris: 10:49:42 INFO - Chipset Model: Intel Iris 10:49:42 INFO - Type: GPU 10:49:42 INFO - Bus: Built-In 10:49:42 INFO - VRAM (Dynamic, Max): 1536 MB 10:49:42 INFO - Vendor: Intel (0x8086) 10:49:42 INFO - Device ID: 0x0a2e 10:49:42 INFO - Revision ID: 0x0009 10:49:42 INFO - Displays: 10:49:42 INFO - Display: 10:49:42 INFO - Resolution: 1600 x 1200 @ 60 Hz 10:49:42 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 10:49:42 INFO - Display Serial Number: 1600x1200 60 10:49:42 INFO - Main Display: Yes 10:49:42 INFO - Mirror: Off 10:49:42 INFO - Online: Yes 10:49:42 INFO - Rotation: Supported 10:49:42 INFO - Return code: 0 10:49:42 INFO - Running post-action listener: _resource_record_post_action 10:49:42 INFO - [mozharness: 2017-05-25 17:49:42.988322Z] Finished run-tests step (success) 10:49:42 INFO - Running post-run listener: _resource_record_post_run 10:49:43 INFO - Total resource usage - Wall time: 1200s; CPU: 54.0%; Read bytes: 39277568; Write bytes: 516664320; Read time: 257; Write time: 1577 10:49:43 INFO - TinderboxPrint: CPU usage
53.8% 10:49:43 INFO - TinderboxPrint: I/O read bytes / time
39,277,568 / 257 10:49:43 INFO - TinderboxPrint: I/O write bytes / time
516,664,320 / 1,577 10:49:43 INFO - TinderboxPrint: CPU idle
2,217.5 (46.2%) 10:49:43 INFO - TinderboxPrint: CPU system
957.7 (19.9%) 10:49:43 INFO - TinderboxPrint: CPU user
1,626.5 (33.9%) 10:49:43 INFO - TinderboxPrint: Swap in / out
247,197,696 / 0 10:49:43 INFO - install - Wall time: 20s; CPU: 54.0%; Read bytes: 206393856; Write bytes: 199700480; Read time: 15697; Write time: 412 10:49:43 INFO - run-tests - Wall time: 1180s; CPU: 54.0%; Read bytes: 37078016; Write bytes: 300971008; Read time: 234; Write time: 1108 10:49:43 INFO - Running post-run listener: _upload_blobber_files 10:49:43 INFO - Blob upload gear active. 10:49:43 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 10:49:43 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 10:49:43 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', 'Mozilla-Beta', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 10:49:43 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 Mozilla-Beta -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 10:49:43 INFO - (blobuploader) - INFO - Open directory for files ... 10:49:43 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 10:49:43 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:49:43 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:49:44 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 10:49:44 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:49:44 INFO - (blobuploader) - INFO - Done attempting. 10:49:44 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 10:49:44 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:49:44 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:49:45 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 10:49:45 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:49:45 INFO - (blobuploader) - INFO - Done attempting. 10:49:45 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 10:49:45 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:49:45 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:49:46 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 10:49:46 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:49:46 INFO - (blobuploader) - INFO - Done attempting. 10:49:46 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 10:49:46 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:49:46 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:49:49 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 10:49:49 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:49:49 INFO - (blobuploader) - INFO - Done attempting. 10:49:49 INFO - (blobuploader) - INFO - Iteration through files over. 10:49:49 INFO - Return code: 0 10:49:49 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 10:49:49 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 10:49:49 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/70619495b0f37988822ec3dfb1bff0cc2a7e651da1af59c0e78137383838cb66fb8fdb756ddf59c9dee090de84c28b971b518115f4d9226fab70319bef79bed5", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/8757b56dc8035735f938c963004b155f25e3b083a9568db57e43b0cca65a248331c217692aa63ff649ed363395b034d1762ae6cc20a883947bd7c91591e15680", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/3f3e3e04c67ec41cf55e28876c9f96d154304c9c7cc52128b1a93c4655f7cc230c8c15948deb7826bcc59fd2f620008e8e9afa93f85c0ff28c91449ce22aebf1", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/a6a35f72796d1891840ddd5fe3b2ee0450da18acf5f4be56b6b27a9bfb3ed84f4a6354baa08f4bfb13c8adc857ca4d2b2b22806d1316c38e314fa4de6ab1eef4"} 10:49:49 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 10:49:49 INFO - Writing to file /builds/slave/test/properties/blobber_files 10:49:49 INFO - Contents: 10:49:49 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/70619495b0f37988822ec3dfb1bff0cc2a7e651da1af59c0e78137383838cb66fb8fdb756ddf59c9dee090de84c28b971b518115f4d9226fab70319bef79bed5", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/8757b56dc8035735f938c963004b155f25e3b083a9568db57e43b0cca65a248331c217692aa63ff649ed363395b034d1762ae6cc20a883947bd7c91591e15680", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/3f3e3e04c67ec41cf55e28876c9f96d154304c9c7cc52128b1a93c4655f7cc230c8c15948deb7826bcc59fd2f620008e8e9afa93f85c0ff28c91449ce22aebf1", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/a6a35f72796d1891840ddd5fe3b2ee0450da18acf5f4be56b6b27a9bfb3ed84f4a6354baa08f4bfb13c8adc857ca4d2b2b22806d1316c38e314fa4de6ab1eef4"} 10:49:49 INFO - Running post-run listener: copy_logs_to_upload_dir 10:49:49 INFO - Copying logs to upload dir... 10:49:49 INFO - mkdir: /builds/slave/test/build/upload/logs 10:49:49 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1275.412543 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2017-05-25 10:49:49.553563) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-25 10:49:49.556817) ========= 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.d28168JV24/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.6KEp177nmv/Listeners TMPDIR=/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/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/Mozilla-Beta/sha512/70619495b0f37988822ec3dfb1bff0cc2a7e651da1af59c0e78137383838cb66fb8fdb756ddf59c9dee090de84c28b971b518115f4d9226fab70319bef79bed5", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/8757b56dc8035735f938c963004b155f25e3b083a9568db57e43b0cca65a248331c217692aa63ff649ed363395b034d1762ae6cc20a883947bd7c91591e15680", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/3f3e3e04c67ec41cf55e28876c9f96d154304c9c7cc52128b1a93c4655f7cc230c8c15948deb7826bcc59fd2f620008e8e9afa93f85c0ff28c91449ce22aebf1", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/a6a35f72796d1891840ddd5fe3b2ee0450da18acf5f4be56b6b27a9bfb3ed84f4a6354baa08f4bfb13c8adc857ca4d2b2b22806d1316c38e314fa4de6ab1eef4"} build_url:https://queue.taskcluster.net/v1/task/KlIGQZy6RByOmf-6GVai7w/artifacts/public/build/firefox-54.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011065 build_url: 'https://queue.taskcluster.net/v1/task/KlIGQZy6RByOmf-6GVai7w/artifacts/public/build/firefox-54.0.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/70619495b0f37988822ec3dfb1bff0cc2a7e651da1af59c0e78137383838cb66fb8fdb756ddf59c9dee090de84c28b971b518115f4d9226fab70319bef79bed5", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/8757b56dc8035735f938c963004b155f25e3b083a9568db57e43b0cca65a248331c217692aa63ff649ed363395b034d1762ae6cc20a883947bd7c91591e15680", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/3f3e3e04c67ec41cf55e28876c9f96d154304c9c7cc52128b1a93c4655f7cc230c8c15948deb7826bcc59fd2f620008e8e9afa93f85c0ff28c91449ce22aebf1", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/a6a35f72796d1891840ddd5fe3b2ee0450da18acf5f4be56b6b27a9bfb3ed84f4a6354baa08f4bfb13c8adc857ca4d2b2b22806d1316c38e314fa4de6ab1eef4"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-25 10:49:49.589627) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-25 10:49:49.589968) ========= 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.d28168JV24/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.6KEp177nmv/Listeners TMPDIR=/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/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.005206 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-25 10:49:49.638294) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-25 10:49:49.638627) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-25 10:49:49.638940) ========= ========= Total master_lag: 0.17 =========