builder: mozilla-beta_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0346 starttime: 1495718445.28 results: success (0) buildid: 20170525051645 builduid: 0d61dfe9656b42958d212a65fd90f261 revision: 02e5b298abedc3fd641a8db70efd59009109ed82 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-05-25 06:20:45.276288) ========= master: http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-05-25 06:20:45.276806) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-25 06:20:45.277100) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-25 06:20:45.300786) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-25 06:20:45.301177) ========= 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.KDVfahnlTt/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.i2dogWExfy/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 06:20:45-- 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% 126M=0s 2017-05-25 06:20:45 (126 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.108746 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-25 06:20:45.432535) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-25 06:20:45.432867) ========= 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.KDVfahnlTt/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.i2dogWExfy/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.021615 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-25 06:20:45.481429) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-25 06:20:45.481782) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 02e5b298abedc3fd641a8db70efd59009109ed82 --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 02e5b298abedc3fd641a8db70efd59009109ed82 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.KDVfahnlTt/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.i2dogWExfy/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 06:20:45,498 truncating revision to first 12 chars 2017-05-25 06:20:45,498 Setting DEBUG logging. 2017-05-25 06:20:45,498 attempt 1/10 2017-05-25 06:20:45,499 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/02e5b298abed?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-05-25 06:20:45,843 unpacking tar archive at: mozilla-beta-02e5b298abed/testing/mozharness/ program finished with exit code 0 elapsedTime=0.609338 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-25 06:20:46.113464) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-25 06:20:46.113774) ========= script_repo_revision: 02e5b298abedc3fd641a8db70efd59009109ed82 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-25 06:20:46.114180) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-25 06:20:46.114471) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-25 06:20:46.133287) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 9 secs) (at 2017-05-25 06:20:46.133672) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --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-e10s', '--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.KDVfahnlTt/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.i2dogWExfy/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 06:20:46 INFO - MultiFileLogger online at 20170525 06:20:46 in /builds/slave/test 06:20:46 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 06:20:46 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 06:20:46 INFO - {'append_to_log': False, 06:20:46 INFO - 'base_work_dir': '/builds/slave/test', 06:20:46 INFO - 'blob_upload_branch': 'Mozilla-Beta', 06:20:46 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 06:20:46 INFO - 'branch': 'Mozilla-Beta', 06:20:46 INFO - 'buildbot_json_path': 'buildprops.json', 06:20:46 INFO - 'config_files': ('talos/mac_config.py',), 06:20:46 INFO - 'default_actions': ('clobber', 06:20:46 INFO - 'read-buildbot-config', 06:20:46 INFO - 'download-and-extract', 06:20:46 INFO - 'populate-webroot', 06:20:46 INFO - 'create-virtualenv', 06:20:46 INFO - 'install', 06:20:46 INFO - 'run-tests'), 06:20:46 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 06:20:46 INFO - 'download_minidump_stackwalk': True, 06:20:46 INFO - 'download_symbols': 'ondemand', 06:20:46 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 06:20:46 INFO - 'tooltool.py': '/tools/tooltool.py', 06:20:46 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 06:20:46 INFO - '/tools/misc-python/virtualenv.py')}, 06:20:46 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 06:20:46 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 06:20:46 INFO - 'gecko_profile': False, 06:20:46 INFO - 'gecko_profile_interval': 0, 06:20:46 INFO - 'installer_path': 'installer.exe', 06:20:46 INFO - 'log_level': 'info', 06:20:46 INFO - 'log_name': 'talos', 06:20:46 INFO - 'log_to_console': True, 06:20:46 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 06:20:46 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 06:20:46 INFO - 'opt_config_files': (), 06:20:46 INFO - 'pip_index': False, 06:20:46 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 06:20:46 INFO - 'cmd': ('bash', 06:20:46 INFO - '-c', 06:20:46 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 06:20:46 INFO - 'enabled': True, 06:20:46 INFO - 'halt_on_failure': False, 06:20:46 INFO - 'name': 'check_screen_resolution'},), 06:20:46 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 06:20:46 INFO - 'cmd': ('bash', 06:20:46 INFO - '-c', 06:20:46 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 06:20:46 INFO - 'enabled': True, 06:20:46 INFO - 'halt_on_failure': False, 06:20:46 INFO - 'name': 'check_screen_resolution'},), 06:20:46 INFO - 'run_cmd_checks_enabled': True, 06:20:46 INFO - 'suite': 'dromaeojs-e10s', 06:20:46 INFO - 'system_bits': '32', 06:20:46 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 06:20:46 INFO - 'title': 't-yosemite-r7-0346', 06:20:46 INFO - 'tooltool_cache': '/builds/tooltool_cache', 06:20:46 INFO - 'use_talos_json': True, 06:20:46 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 06:20:46 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 06:20:46 INFO - 'work_dir': 'build'} 06:20:46 INFO - [mozharness: 2017-05-25 13:20:46.271987Z] Running clobber step. 06:20:46 INFO - Running pre-action listener: _resource_record_pre_action 06:20:46 INFO - Running main action method: clobber 06:20:46 INFO - rmtree: /builds/slave/test/build 06:20:46 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 06:20:46 INFO - Running post-action listener: _resource_record_post_action 06:20:46 INFO - [mozharness: 2017-05-25 13:20:46.923453Z] Finished clobber step (success) 06:20:46 INFO - [mozharness: 2017-05-25 13:20:46.923619Z] Running read-buildbot-config step. 06:20:46 INFO - Running pre-action listener: _resource_record_pre_action 06:20:46 INFO - Running main action method: read_buildbot_config 06:20:46 INFO - Using buildbot properties: 06:20:46 INFO - { 06:20:46 INFO - "project": "", 06:20:46 INFO - "product": "firefox", 06:20:46 INFO - "script_repo_revision": "production", 06:20:46 INFO - "builddir": "mozilla-beta_yosemite_r7_test-dromaeojs-e10s", 06:20:46 INFO - "repository": "", 06:20:46 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-beta talos dromaeojs-e10s", 06:20:46 INFO - "buildid": "20170525051645", 06:20:46 INFO - "pgo_build": "False", 06:20:46 INFO - "basedir": "/builds/slave/test", 06:20:46 INFO - "buildnumber": 6, 06:20:46 INFO - "slavename": "t-yosemite-r7-0346", 06:20:46 INFO - "master": "http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/", 06:20:46 INFO - "platform": "yosemite_r7", 06:20:46 INFO - "scheduler": "tests-mozilla-beta-macosx64-talos", 06:20:46 INFO - "branch": "mozilla-beta", 06:20:46 INFO - "repo_path": "releases/mozilla-beta", 06:20:46 INFO - "revision": "02e5b298abedc3fd641a8db70efd59009109ed82", 06:20:46 INFO - "stage_platform": "macosx64", 06:20:46 INFO - "builduid": "0d61dfe9656b42958d212a65fd90f261", 06:20:46 INFO - "slavebuilddir": "test" 06:20:46 INFO - } 06:20:46 INFO - Found installer url https://queue.taskcluster.net/v1/task/SS9fEveGTiCjigUMTVCNEg/artifacts/public/build/firefox-54.0.en-US.mac.dmg. 06:20:46 INFO - Running post-action listener: _resource_record_post_action 06:20:46 INFO - [mozharness: 2017-05-25 13:20:46.926989Z] Finished read-buildbot-config step (success) 06:20:46 INFO - [mozharness: 2017-05-25 13:20:46.927134Z] Running download-and-extract step. 06:20:46 INFO - Running pre-action listener: _resource_record_pre_action 06:20:46 INFO - Running main action method: download_and_extract 06:20:46 INFO - mkdir: /builds/slave/test/build/tests 06:20:46 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')]} 06:20:46 INFO - trying https://queue.taskcluster.net/v1/task/SS9fEveGTiCjigUMTVCNEg/artifacts/public/build/firefox-54.0.en-US.mac.test_packages.json 06:20:46 INFO - Downloading https://queue.taskcluster.net/v1/task/SS9fEveGTiCjigUMTVCNEg/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 06:20:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/SS9fEveGTiCjigUMTVCNEg/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 06:20:48 INFO - Downloaded 1287 bytes. 06:20:48 INFO - Reading from file /builds/slave/test/build/firefox-54.0.en-US.mac.test_packages.json 06:20:48 INFO - Using the following test package requirements: 06:20:48 INFO - {u'awsy': [u'firefox-54.0.en-US.mac.common.tests.zip', 06:20:48 INFO - u'firefox-54.0.en-US.mac.awsy.tests.zip'], 06:20:48 INFO - u'common': [u'firefox-54.0.en-US.mac.common.tests.zip'], 06:20:48 INFO - u'cppunittest': [u'firefox-54.0.en-US.mac.common.tests.zip', 06:20:48 INFO - u'firefox-54.0.en-US.mac.cppunittest.tests.zip'], 06:20:48 INFO - u'gtest': [u'firefox-54.0.en-US.mac.common.tests.zip', 06:20:48 INFO - u'firefox-54.0.en-US.mac.gtest.tests.zip'], 06:20:48 INFO - u'jittest': [u'firefox-54.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 06:20:48 INFO - u'mochitest': [u'firefox-54.0.en-US.mac.common.tests.zip', 06:20:48 INFO - u'firefox-54.0.en-US.mac.mochitest.tests.zip'], 06:20:48 INFO - u'mozbase': [u'firefox-54.0.en-US.mac.common.tests.zip'], 06:20:48 INFO - u'reftest': [u'firefox-54.0.en-US.mac.common.tests.zip', 06:20:48 INFO - u'firefox-54.0.en-US.mac.reftest.tests.zip'], 06:20:48 INFO - u'talos': [u'firefox-54.0.en-US.mac.common.tests.zip', 06:20:48 INFO - u'firefox-54.0.en-US.mac.talos.tests.zip'], 06:20:48 INFO - u'web-platform': [u'firefox-54.0.en-US.mac.common.tests.zip', 06:20:48 INFO - u'firefox-54.0.en-US.mac.web-platform.tests.zip'], 06:20:48 INFO - u'xpcshell': [u'firefox-54.0.en-US.mac.common.tests.zip', 06:20:48 INFO - u'firefox-54.0.en-US.mac.xpcshell.tests.zip']} 06:20:48 INFO - Downloading packages: [u'firefox-54.0.en-US.mac.common.tests.zip'] for test suite category: common 06:20:48 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/SS9fEveGTiCjigUMTVCNEg/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip 06:20:48 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/SS9fEveGTiCjigUMTVCNEg/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip'}, attempt #1 06:20:48 INFO - Fetch https://queue.taskcluster.net/v1/task/SS9fEveGTiCjigUMTVCNEg/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip into memory 06:20:50 INFO - Expected file size: 51393598 06:20:50 INFO - Obtained file size: 51393598 06:21:06 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 06:21:06 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/SS9fEveGTiCjigUMTVCNEg/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip 06:21:06 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/SS9fEveGTiCjigUMTVCNEg/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip'}, attempt #1 06:21:06 INFO - Fetch https://queue.taskcluster.net/v1/task/SS9fEveGTiCjigUMTVCNEg/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip into memory 06:21:07 INFO - Expected file size: 51393598 06:21:07 INFO - Obtained file size: 51393598 06:21:23 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/SS9fEveGTiCjigUMTVCNEg/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip 06:21:23 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/SS9fEveGTiCjigUMTVCNEg/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip'}, attempt #1 06:21:23 INFO - Fetch https://queue.taskcluster.net/v1/task/SS9fEveGTiCjigUMTVCNEg/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip into memory 06:21:24 INFO - Expected file size: 14404449 06:21:24 INFO - Obtained file size: 14404449 06:21:25 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')]} 06:21:25 INFO - trying https://queue.taskcluster.net/v1/task/SS9fEveGTiCjigUMTVCNEg/artifacts/public/build/firefox-54.0.en-US.mac.dmg 06:21:25 INFO - Downloading https://queue.taskcluster.net/v1/task/SS9fEveGTiCjigUMTVCNEg/artifacts/public/build/firefox-54.0.en-US.mac.dmg to /builds/slave/test/build/firefox-54.0.en-US.mac.dmg 06:21:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/SS9fEveGTiCjigUMTVCNEg/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 06:21:27 INFO - Downloaded 59644731 bytes. 06:21:27 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/SS9fEveGTiCjigUMTVCNEg/artifacts/public/build/firefox-54.0.en-US.mac.dmg 06:21:27 INFO - mkdir: /builds/slave/test/properties 06:21:27 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 06:21:27 INFO - Writing to file /builds/slave/test/properties/build_url 06:21:27 INFO - Contents: 06:21:27 INFO - build_url:https://queue.taskcluster.net/v1/task/SS9fEveGTiCjigUMTVCNEg/artifacts/public/build/firefox-54.0.en-US.mac.dmg 06:21:28 INFO - Running post-action listener: _resource_record_post_action 06:21:28 INFO - Running post-action listener: set_extra_try_arguments 06:21:28 INFO - [mozharness: 2017-05-25 13:21:28.014159Z] Finished download-and-extract step (success) 06:21:28 INFO - [mozharness: 2017-05-25 13:21:28.014317Z] Running populate-webroot step. 06:21:28 INFO - Running pre-action listener: _resource_record_pre_action 06:21:28 INFO - Running main action method: populate_webroot 06:21:28 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 06:21:28 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 06:21:28 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 06:21:28 INFO - '--tppagecycles', 06:21:28 INFO - '7'], 06:21:28 INFO - 'tests': ['tsvgm']}}, 06:21:28 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 06:21:28 INFO - 'tests': ['tresize', 'tcanvasmark']}, 06:21:28 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 06:21:28 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 06:21:28 INFO - 'tests': ['dromaeo_css', 'kraken']}, 06:21:28 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 06:21:28 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 06:21:28 INFO - 'talos_options': ['--disable-e10s'], 06:21:28 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 06:21:28 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 06:21:28 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 06:21:28 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 06:21:28 INFO - 'talos_options': ['--disable-e10s'], 06:21:28 INFO - 'tests': ['damp', 'tps']}, 06:21:28 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 06:21:28 INFO - 'tests': ['damp', 'tps']}, 06:21:28 INFO - 'g3': {'talos_options': ['--disable-e10s'], 06:21:28 INFO - 'tests': ['dromaeo_dom']}, 06:21:28 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 06:21:28 INFO - 'g4': {'talos_options': ['--disable-e10s'], 06:21:28 INFO - 'tests': ['basic_compositor_video']}, 06:21:28 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 06:21:28 INFO - 'other': {'talos_options': ['--disable-e10s'], 06:21:28 INFO - 'tests': ['a11yr', 06:21:28 INFO - 'ts_paint', 06:21:28 INFO - 'tpaint', 06:21:28 INFO - 'sessionrestore', 06:21:28 INFO - 'sessionrestore_no_auto_restore', 06:21:28 INFO - 'tabpaint']}, 06:21:28 INFO - 'other-e10s': {'tests': ['a11yr', 06:21:28 INFO - 'ts_paint', 06:21:28 INFO - 'tpaint', 06:21:28 INFO - 'sessionrestore', 06:21:28 INFO - 'sessionrestore_no_auto_restore', 06:21:28 INFO - 'tabpaint']}, 06:21:28 INFO - 'other-e10s_l64': {'tests': ['a11yr', 06:21:28 INFO - 'ts_paint', 06:21:28 INFO - 'tpaint', 06:21:28 INFO - 'sessionrestore', 06:21:28 INFO - 'sessionrestore_no_auto_restore', 06:21:28 INFO - 'tabpaint']}, 06:21:28 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 06:21:28 INFO - 'ts_paint', 06:21:28 INFO - 'tpaint', 06:21:28 INFO - 'sessionrestore', 06:21:28 INFO - 'sessionrestore_no_auto_restore', 06:21:28 INFO - 'tabpaint']}, 06:21:28 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 06:21:28 INFO - 'tests': ['a11yr', 06:21:28 INFO - 'ts_paint', 06:21:28 INFO - 'tpaint', 06:21:28 INFO - 'sessionrestore', 06:21:28 INFO - 'sessionrestore_no_auto_restore', 06:21:28 INFO - 'tabpaint']}, 06:21:28 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 06:21:28 INFO - 'tests': ['a11yr', 06:21:28 INFO - 'ts_paint', 06:21:28 INFO - 'tpaint', 06:21:28 INFO - 'sessionrestore', 06:21:28 INFO - 'sessionrestore_no_auto_restore', 06:21:28 INFO - 'tabpaint']}, 06:21:28 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 06:21:28 INFO - 'tests': ['tsvgx', 06:21:28 INFO - 'tsvgr_opacity', 06:21:28 INFO - 'tart', 06:21:28 INFO - 'tscrollx', 06:21:28 INFO - 'cart', 06:21:28 INFO - 'tsvg_static']}, 06:21:28 INFO - 'svgr-e10s': {'tests': ['tsvgx', 06:21:28 INFO - 'tsvgr_opacity', 06:21:28 INFO - 'tart', 06:21:28 INFO - 'tscrollx', 06:21:28 INFO - 'cart', 06:21:28 INFO - 'tsvg_static']}, 06:21:28 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 06:21:28 INFO - 'talos_options': ['--disable-e10s'], 06:21:28 INFO - 'tests': ['tp5o']}, 06:21:28 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 06:21:28 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 06:21:28 INFO - 'talos_options': ['--disable-e10s', 06:21:28 INFO - '--xperf_path', 06:21:28 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 06:21:28 INFO - 'tests': ['tp5n']}, 06:21:28 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 06:21:28 INFO - 'talos_options': ['--xperf_path', 06:21:28 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 06:21:28 INFO - 'tests': ['tp5n']}}, 06:21:28 INFO - 'talos.zip': {'path': '', 06:21:28 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 06:21:28 INFO - Running post-action listener: _resource_record_post_action 06:21:28 INFO - [mozharness: 2017-05-25 13:21:28.032705Z] Finished populate-webroot step (success) 06:21:28 INFO - [mozharness: 2017-05-25 13:21:28.032834Z] Running create-virtualenv step. 06:21:28 INFO - Running pre-action listener: _resource_record_pre_action 06:21:28 INFO - Running main action method: create_virtualenv 06:21:28 INFO - Creating virtualenv /builds/slave/test/build/venv 06:21:28 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 06:21:28 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 06:21:28 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 06:21:28 INFO - Using real prefix '/tools/python27' 06:21:28 INFO - New python executable in /builds/slave/test/build/venv/bin/python 06:21:28 INFO - Installing distribute.............................................................................................................................................................................................done. 06:21:31 INFO - Installing pip.................done. 06:21:31 INFO - Return code: 0 06:21:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 06:21:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 06:21:32 INFO - Reading from file tmpfile_stdout 06:21:32 INFO - Output received: 06:21:32 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 06:21:32 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 06:21:32 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')]} 06:21:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:21:32 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')]} 06:21:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:21:32 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/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 0x10b540b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b543768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe4a9d6c4f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b31ed30>, '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 0x10b245db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b313be8>, '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.KDVfahnlTt/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.i2dogWExfy/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 06:21:32 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 06:21:32 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 06:21:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KDVfahnlTt/Render', 06:21:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:21:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:21:32 INFO - 'HOME': '/Users/cltbld', 06:21:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:21:32 INFO - 'LOGNAME': 'cltbld', 06:21:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:21:32 INFO - 'MOZ_NO_REMOTE': '1', 06:21:32 INFO - 'NO_EM_RESTART': '1', 06:21:32 INFO - 'PAGER': '/bin/cat', 06:21:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:21:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:21:32 INFO - 'PWD': '/builds/slave/test', 06:21:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:21:32 INFO - 'SHELL': '/bin/bash', 06:21:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.i2dogWExfy/Listeners', 06:21:32 INFO - 'TMPDIR': '/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/', 06:21:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:21:32 INFO - 'USER': 'cltbld', 06:21:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:21:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:21:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:21:32 INFO - 'XPC_FLAGS': '0x0', 06:21:32 INFO - 'XPC_SERVICE_NAME': '0', 06:21:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:21:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:21:32 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 06:21:32 INFO - Cleaning up... 06:21:32 INFO - Return code: 0 06:21:32 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 06:21:32 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')]} 06:21:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:21:32 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')]} 06:21:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:21:32 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/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 0x10b540b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b543768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe4a9d6c4f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b31ed30>, '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 0x10b245db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b313be8>, '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.KDVfahnlTt/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.i2dogWExfy/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 06:21:32 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 06:21:32 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 06:21:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KDVfahnlTt/Render', 06:21:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:21:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:21:32 INFO - 'HOME': '/Users/cltbld', 06:21:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:21:32 INFO - 'LOGNAME': 'cltbld', 06:21:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:21:32 INFO - 'MOZ_NO_REMOTE': '1', 06:21:32 INFO - 'NO_EM_RESTART': '1', 06:21:32 INFO - 'PAGER': '/bin/cat', 06:21:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:21:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:21:32 INFO - 'PWD': '/builds/slave/test', 06:21:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:21:32 INFO - 'SHELL': '/bin/bash', 06:21:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.i2dogWExfy/Listeners', 06:21:32 INFO - 'TMPDIR': '/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/', 06:21:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:21:32 INFO - 'USER': 'cltbld', 06:21:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:21:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:21:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:21:32 INFO - 'XPC_FLAGS': '0x0', 06:21:32 INFO - 'XPC_SERVICE_NAME': '0', 06:21:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:21:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:21:32 INFO - Downloading/unpacking psutil>=3.1.1 06:21: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 06:21: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 06:21: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 06:21: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 06:21:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 06:21:34 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 06:21:34 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 06:21:34 INFO - Installing collected packages: psutil 06:21:34 INFO - Running setup.py install for psutil 06:21:34 INFO - building 'psutil._psutil_osx' extension 06:21:34 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 06:21:34 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 06:21:35 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 06:21:35 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 06:21:35 INFO - building 'psutil._psutil_posix' extension 06:21:35 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 06:21:35 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 06:21:35 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 06:21:35 INFO - ^ 06:21:35 INFO - 1 warning generated. 06:21:35 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 06:21:35 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 06:21:35 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 06:21:35 INFO - Successfully installed psutil 06:21:35 INFO - Cleaning up... 06:21:35 INFO - Return code: 0 06:21:35 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 06:21:35 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')]} 06:21:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:21:35 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')]} 06:21:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:21:35 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/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 0x10b540b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b543768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe4a9d6c4f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b31ed30>, '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 0x10b245db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b313be8>, '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.KDVfahnlTt/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.i2dogWExfy/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 06:21:35 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 06:21:35 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 06:21:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KDVfahnlTt/Render', 06:21:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:21:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:21:35 INFO - 'HOME': '/Users/cltbld', 06:21:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:21:35 INFO - 'LOGNAME': 'cltbld', 06:21:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:21:35 INFO - 'MOZ_NO_REMOTE': '1', 06:21:35 INFO - 'NO_EM_RESTART': '1', 06:21:35 INFO - 'PAGER': '/bin/cat', 06:21:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:21:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:21:35 INFO - 'PWD': '/builds/slave/test', 06:21:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:21:35 INFO - 'SHELL': '/bin/bash', 06:21:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.i2dogWExfy/Listeners', 06:21:35 INFO - 'TMPDIR': '/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/', 06:21:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:21:35 INFO - 'USER': 'cltbld', 06:21:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:21:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:21:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:21:35 INFO - 'XPC_FLAGS': '0x0', 06:21:35 INFO - 'XPC_SERVICE_NAME': '0', 06:21:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:21:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:21:35 INFO - Downloading/unpacking mozsystemmonitor==0.3 06:21:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:21:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:21:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:21:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:21:37 INFO - Downloading mozsystemmonitor-0.3.tar.gz 06:21:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 06:21:37 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 06:21:37 INFO - Installing collected packages: mozsystemmonitor 06:21:37 INFO - Running setup.py install for mozsystemmonitor 06:21:37 INFO - Successfully installed mozsystemmonitor 06:21:37 INFO - Cleaning up... 06:21:37 INFO - Return code: 0 06:21:37 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 06:21: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')]} 06:21:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:21: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')]} 06:21:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:21:37 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 0x10b540b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b543768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe4a9d6c4f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b31ed30>, '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 0x10b245db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b313be8>, '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.KDVfahnlTt/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.i2dogWExfy/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 06:21:37 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 06:21:37 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 06:21:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KDVfahnlTt/Render', 06:21:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:21:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:21:37 INFO - 'HOME': '/Users/cltbld', 06:21:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:21:37 INFO - 'LOGNAME': 'cltbld', 06:21:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:21:37 INFO - 'MOZ_NO_REMOTE': '1', 06:21:37 INFO - 'NO_EM_RESTART': '1', 06:21:37 INFO - 'PAGER': '/bin/cat', 06:21:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:21:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:21:37 INFO - 'PWD': '/builds/slave/test', 06:21:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:21:37 INFO - 'SHELL': '/bin/bash', 06:21:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.i2dogWExfy/Listeners', 06:21:37 INFO - 'TMPDIR': '/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/', 06:21:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:21:37 INFO - 'USER': 'cltbld', 06:21:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:21:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:21:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:21:37 INFO - 'XPC_FLAGS': '0x0', 06:21:37 INFO - 'XPC_SERVICE_NAME': '0', 06:21:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:21:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:21:37 INFO - Downloading/unpacking jsonschema==2.5.1 06:21:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:21:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:21:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:21:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:21:39 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 06:21:39 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 06:21: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 06:21: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 06:21: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 06:21: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 06:21:39 INFO - Downloading functools32-3.2.3-2.tar.gz 06:21:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 06:21:39 INFO - warning: no files found matching '*.txt' 06:21:39 INFO - no previously-included directories found matching 'build' 06:21:39 INFO - no previously-included directories found matching 'dist' 06:21:39 INFO - no previously-included directories found matching '.git*' 06:21:39 INFO - Installing collected packages: jsonschema, functools32 06:21:39 INFO - Running setup.py install for functools32 06:21:39 INFO - warning: no files found matching '*.txt' 06:21:39 INFO - no previously-included directories found matching 'build' 06:21:39 INFO - no previously-included directories found matching 'dist' 06:21:39 INFO - no previously-included directories found matching '.git*' 06:21:39 INFO - Successfully installed jsonschema functools32 06:21:39 INFO - Cleaning up... 06:21:39 INFO - Return code: 0 06:21:39 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 06:21:39 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')]} 06:21:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:21:39 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')]} 06:21:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:21:39 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 0x10b540b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b543768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe4a9d6c4f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b31ed30>, '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 0x10b245db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b313be8>, '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.KDVfahnlTt/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.i2dogWExfy/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 06:21:39 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 06:21:39 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 06:21:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KDVfahnlTt/Render', 06:21:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:21:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:21:39 INFO - 'HOME': '/Users/cltbld', 06:21:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:21:39 INFO - 'LOGNAME': 'cltbld', 06:21:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:21:39 INFO - 'MOZ_NO_REMOTE': '1', 06:21:39 INFO - 'NO_EM_RESTART': '1', 06:21:39 INFO - 'PAGER': '/bin/cat', 06:21:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:21:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:21:39 INFO - 'PWD': '/builds/slave/test', 06:21:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:21:39 INFO - 'SHELL': '/bin/bash', 06:21:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.i2dogWExfy/Listeners', 06:21:39 INFO - 'TMPDIR': '/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/', 06:21:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:21:39 INFO - 'USER': 'cltbld', 06:21:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:21:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:21:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:21:39 INFO - 'XPC_FLAGS': '0x0', 06:21:39 INFO - 'XPC_SERVICE_NAME': '0', 06:21:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:21:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:21:39 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 06:21:39 INFO - Cleaning up... 06:21:39 INFO - Return code: 0 06:21:39 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 06:21:39 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')]} 06:21:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:21:39 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')]} 06:21:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:21:39 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 0x10b540b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b543768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe4a9d6c4f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b31ed30>, '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 0x10b245db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b313be8>, '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.KDVfahnlTt/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.i2dogWExfy/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 06:21:39 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 06:21:39 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 06:21:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KDVfahnlTt/Render', 06:21:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:21:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:21:39 INFO - 'HOME': '/Users/cltbld', 06:21:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:21:39 INFO - 'LOGNAME': 'cltbld', 06:21:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:21:39 INFO - 'MOZ_NO_REMOTE': '1', 06:21:39 INFO - 'NO_EM_RESTART': '1', 06:21:39 INFO - 'PAGER': '/bin/cat', 06:21:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:21:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:21:39 INFO - 'PWD': '/builds/slave/test', 06:21:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:21:39 INFO - 'SHELL': '/bin/bash', 06:21:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.i2dogWExfy/Listeners', 06:21:39 INFO - 'TMPDIR': '/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/', 06:21:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:21:39 INFO - 'USER': 'cltbld', 06:21:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:21:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:21:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:21:39 INFO - 'XPC_FLAGS': '0x0', 06:21:39 INFO - 'XPC_SERVICE_NAME': '0', 06:21:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:21:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:21:40 INFO - Downloading/unpacking blobuploader==1.2.4 06:21:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:21:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:21:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:21:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:21:41 INFO - Downloading blobuploader-1.2.4.tar.gz 06:21:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 06:21:41 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 06:21:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:21:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:21:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:21:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:21:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 06:21:42 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 06:21:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:21:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:21:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:21:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:21:42 INFO - Downloading docopt-0.6.1.tar.gz 06:21:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 06:21:42 INFO - Installing collected packages: blobuploader, requests, docopt 06:21:42 INFO - Running setup.py install for blobuploader 06:21:42 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 06:21:42 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 06:21:42 INFO - Running setup.py install for requests 06:21:43 INFO - Running setup.py install for docopt 06:21:43 INFO - Successfully installed blobuploader requests docopt 06:21:43 INFO - Cleaning up... 06:21:43 INFO - Return code: 0 06:21:43 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:21:43 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')]} 06:21:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:21:43 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')]} 06:21:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:21:44 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 0x10b540b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b543768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe4a9d6c4f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b31ed30>, '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 0x10b245db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b313be8>, '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.KDVfahnlTt/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.i2dogWExfy/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 06:21:44 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 06:21:44 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 06:21:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KDVfahnlTt/Render', 06:21:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:21:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:21:44 INFO - 'HOME': '/Users/cltbld', 06:21:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:21:44 INFO - 'LOGNAME': 'cltbld', 06:21:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:21:44 INFO - 'MOZ_NO_REMOTE': '1', 06:21:44 INFO - 'NO_EM_RESTART': '1', 06:21:44 INFO - 'PAGER': '/bin/cat', 06:21:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:21:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:21:44 INFO - 'PWD': '/builds/slave/test', 06:21:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:21:44 INFO - 'SHELL': '/bin/bash', 06:21:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.i2dogWExfy/Listeners', 06:21:44 INFO - 'TMPDIR': '/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/', 06:21:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:21:44 INFO - 'USER': 'cltbld', 06:21:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:21:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:21:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:21:44 INFO - 'XPC_FLAGS': '0x0', 06:21:44 INFO - 'XPC_SERVICE_NAME': '0', 06:21:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:21:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:21:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 06:21:44 INFO - Running setup.py (path:/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/pip-F66z7Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 06:21:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 06:21:44 INFO - Running setup.py (path:/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/pip-Kk3_rZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 06:21:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 06:21:44 INFO - Running setup.py (path:/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/pip-oDBCzX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 06:21:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 06:21:44 INFO - Running setup.py (path:/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/pip-BYH_vw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 06:21:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 06:21:44 INFO - Running setup.py (path:/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/pip-jBMgaZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 06:21:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 06:21:45 INFO - Running setup.py (path:/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/pip-YZTl2A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 06:21:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 06:21:45 INFO - Running setup.py (path:/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/pip-mSMrbI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 06:21:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 06:21:45 INFO - Running setup.py (path:/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/pip-55bzk4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 06:21:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 06:21:45 INFO - Running setup.py (path:/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/pip-oDOoB_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 06:21:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 06:21:45 INFO - Running setup.py (path:/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/pip-MVuKDY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 06:21:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 06:21:45 INFO - Running setup.py (path:/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/pip-g0rOvZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 06:21:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 06:21:45 INFO - Running setup.py (path:/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/pip-VCUi7p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 06:21:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 06:21:45 INFO - Running setup.py (path:/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/pip-KeKuah-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 06:21:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 06:21:45 INFO - Running setup.py (path:/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/pip-Nk7Mxr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 06:21:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 06:21:46 INFO - Running setup.py (path:/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/pip-nvrhaw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 06:21:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 06:21:46 INFO - Running setup.py (path:/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/pip-_yAnpi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 06:21:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 06:21:46 INFO - Running setup.py (path:/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/pip-8_bGlJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 06:21:46 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 06:21:46 INFO - Running setup.py install for manifestparser 06:21:46 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 06:21:46 INFO - Running setup.py install for mozcrash 06:21:46 INFO - Running setup.py install for mozdebug 06:21:46 INFO - Running setup.py install for mozdevice 06:21:46 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 06:21:46 INFO - Installing dm script to /builds/slave/test/build/venv/bin 06:21:46 INFO - Running setup.py install for mozfile 06:21:47 INFO - Running setup.py install for mozhttpd 06:21:47 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 06:21:47 INFO - Running setup.py install for mozinfo 06:21:47 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 06:21:47 INFO - Running setup.py install for mozInstall 06:21:47 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 06:21:47 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 06:21:47 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 06:21:47 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 06:21:47 INFO - Running setup.py install for mozleak 06:21:47 INFO - Running setup.py install for mozlog 06:21:47 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 06:21:47 INFO - Running setup.py install for moznetwork 06:21:47 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 06:21:48 INFO - Running setup.py install for mozprocess 06:21:48 INFO - Running setup.py install for mozprofile 06:21:48 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 06:21:48 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 06:21:48 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 06:21:48 INFO - Running setup.py install for mozrunner 06:21:48 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 06:21:48 INFO - Running setup.py install for mozscreenshot 06:21:48 INFO - Running setup.py install for moztest 06:21:48 INFO - Running setup.py install for mozversion 06:21:48 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 06:21:48 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 06:21:48 INFO - Cleaning up... 06:21:48 INFO - Return code: 0 06:21:48 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:21:48 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')]} 06:21:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:21:48 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')]} 06:21:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:21:49 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 0x10b540b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b543768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe4a9d6c4f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b31ed30>, '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 0x10b245db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b313be8>, '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.KDVfahnlTt/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.i2dogWExfy/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 06:21:49 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 06:21:49 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 06:21:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KDVfahnlTt/Render', 06:21:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:21:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:21:49 INFO - 'HOME': '/Users/cltbld', 06:21:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:21:49 INFO - 'LOGNAME': 'cltbld', 06:21:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:21:49 INFO - 'MOZ_NO_REMOTE': '1', 06:21:49 INFO - 'NO_EM_RESTART': '1', 06:21:49 INFO - 'PAGER': '/bin/cat', 06:21:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:21:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:21:49 INFO - 'PWD': '/builds/slave/test', 06:21:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:21:49 INFO - 'SHELL': '/bin/bash', 06:21:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.i2dogWExfy/Listeners', 06:21:49 INFO - 'TMPDIR': '/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/', 06:21:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:21:49 INFO - 'USER': 'cltbld', 06:21:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:21:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:21:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:21:49 INFO - 'XPC_FLAGS': '0x0', 06:21:49 INFO - 'XPC_SERVICE_NAME': '0', 06:21:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:21:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:21:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 06:21:49 INFO - Running setup.py (path:/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/pip-0rRzLQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 06:21:49 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 06:21:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 06:21:49 INFO - Running setup.py (path:/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/pip-kkBMmH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 06:21:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 06:21:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 06:21:49 INFO - Running setup.py (path:/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/pip-1ERKQh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 06:21:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 06:21:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 06:21:49 INFO - Running setup.py (path:/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/pip-7nSXfR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 06:21:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 06:21:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 06:21:50 INFO - Running setup.py (path:/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/pip-3p2YW5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 06:21:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 06:21:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 06:21:50 INFO - Running setup.py (path:/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/pip-1kJCB_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 06:21:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 06:21:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 06:21:50 INFO - Running setup.py (path:/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/pip-ROEbXN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 06:21:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 06:21:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 06:21:50 INFO - Running setup.py (path:/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/pip-ynMNTC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 06:21:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 06:21:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 06:21:50 INFO - Running setup.py (path:/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/pip-0JdJNL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 06:21:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 06:21:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 06:21:50 INFO - Running setup.py (path:/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/pip-l37Qx7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 06:21:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 06:21:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 06:21:50 INFO - Running setup.py (path:/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/pip-jxfc2K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 06:21:50 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 06:21:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 06:21:50 INFO - Running setup.py (path:/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/pip-LQ6lbn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 06:21:50 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)) 06:21:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 06:21:50 INFO - Running setup.py (path:/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/pip-K3KpRl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 06:21:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 06:21:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 06:21:51 INFO - Running setup.py (path:/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/pip-mgbPlO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 06:21:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 06:21:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 06:21:51 INFO - Running setup.py (path:/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/pip-axhhsm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 06:21:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 06:21:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 06:21:51 INFO - Running setup.py (path:/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/pip-lw4yLx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 06:21:51 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)) 06:21:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 06:21:51 INFO - Running setup.py (path:/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/pip-Q6KYRI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 06:21:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 06:21:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 06:21:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 06:21:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 06:21:51 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 06:21:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 06:21:51 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 06:21:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:21:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:21:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:21:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:21:53 INFO - Downloading blessings-1.6.tar.gz 06:21:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 06:21:53 INFO - Installing collected packages: blessings 06:21:53 INFO - Running setup.py install for blessings 06:21:53 INFO - Successfully installed blessings 06:21:53 INFO - Cleaning up... 06:21:53 INFO - Return code: 0 06:21:53 INFO - Done creating virtualenv /builds/slave/test/build/venv. 06:21:53 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 06:21:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 06:21:53 INFO - Reading from file tmpfile_stdout 06:21:53 INFO - Current package versions: 06:21:53 INFO - blessings == 1.6 06:21:53 INFO - blobuploader == 1.2.4 06:21:53 INFO - docopt == 0.6.1 06:21:53 INFO - functools32 == 3.2.3-2 06:21:53 INFO - jsonschema == 2.5.1 06:21:53 INFO - manifestparser == 1.1 06:21:53 INFO - mozInstall == 1.12 06:21:53 INFO - mozcrash == 1.0 06:21:53 INFO - mozdebug == 0.1 06:21:53 INFO - mozdevice == 0.49 06:21:53 INFO - mozfile == 1.2 06:21:53 INFO - mozhttpd == 0.7 06:21:53 INFO - mozinfo == 0.9 06:21:53 INFO - mozleak == 0.1 06:21:53 INFO - mozlog == 3.4 06:21:53 INFO - moznetwork == 0.27 06:21:53 INFO - mozprocess == 0.25 06:21:53 INFO - mozprofile == 0.28 06:21:53 INFO - mozrunner == 6.13 06:21:53 INFO - mozscreenshot == 0.1 06:21:53 INFO - mozsystemmonitor == 0.3 06:21:53 INFO - moztest == 0.8 06:21:53 INFO - mozversion == 1.4 06:21:53 INFO - psutil == 3.1.1 06:21:53 INFO - requests == 1.2.3 06:21:53 INFO - wsgiref == 0.1.2 06:21:53 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:21:53 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')]} 06:21:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:21:53 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')]} 06:21:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:21:53 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 0x10b540b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b543768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe4a9d6c4f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b31ed30>, '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 0x10b245db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b313be8>, '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.KDVfahnlTt/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.i2dogWExfy/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 06:21:53 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 06:21:53 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 06:21:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KDVfahnlTt/Render', 06:21:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:21:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:21:53 INFO - 'HOME': '/Users/cltbld', 06:21:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:21:53 INFO - 'LOGNAME': 'cltbld', 06:21:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:21:53 INFO - 'MOZ_NO_REMOTE': '1', 06:21:53 INFO - 'NO_EM_RESTART': '1', 06:21:53 INFO - 'PAGER': '/bin/cat', 06:21:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:21:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:21:53 INFO - 'PWD': '/builds/slave/test', 06:21:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:21:53 INFO - 'SHELL': '/bin/bash', 06:21:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.i2dogWExfy/Listeners', 06:21:53 INFO - 'TMPDIR': '/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/', 06:21:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:21:53 INFO - 'USER': 'cltbld', 06:21:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:21:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:21:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:21:53 INFO - 'XPC_FLAGS': '0x0', 06:21:53 INFO - 'XPC_SERVICE_NAME': '0', 06:21:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:21:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:21:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 06:21:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 06:21:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 06:21:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 06:21:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 06:21:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 06:21:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 06:21:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 06:21:54 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 06:21:54 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 06:21:54 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 06:21:54 INFO - Cleaning up... 06:21:54 INFO - Return code: 0 06:21:54 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 06:21:54 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')]} 06:21:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:21:54 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')]} 06:21:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:21:54 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 0x10b540b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b543768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe4a9d6c4f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b31ed30>, '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 0x10b245db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b313be8>, '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.KDVfahnlTt/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.i2dogWExfy/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 06:21:54 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 06:21:54 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 06:21:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KDVfahnlTt/Render', 06:21:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:21:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:21:54 INFO - 'HOME': '/Users/cltbld', 06:21:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:21:54 INFO - 'LOGNAME': 'cltbld', 06:21:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:21:54 INFO - 'MOZ_NO_REMOTE': '1', 06:21:54 INFO - 'NO_EM_RESTART': '1', 06:21:54 INFO - 'PAGER': '/bin/cat', 06:21:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:21:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:21:54 INFO - 'PWD': '/builds/slave/test', 06:21:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:21:54 INFO - 'SHELL': '/bin/bash', 06:21:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.i2dogWExfy/Listeners', 06:21:54 INFO - 'TMPDIR': '/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/', 06:21:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:21:54 INFO - 'USER': 'cltbld', 06:21:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:21:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:21:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:21:54 INFO - 'XPC_FLAGS': '0x0', 06:21:54 INFO - 'XPC_SERVICE_NAME': '0', 06:21:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:21:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:21:54 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 06:21:54 INFO - Cleaning up... 06:21:54 INFO - Return code: 0 06:21:54 INFO - Running post-action listener: _resource_record_post_action 06:21:54 INFO - Running post-action listener: _start_resource_monitoring 06:21:54 INFO - Starting resource monitoring. 06:21:54 INFO - [mozharness: 2017-05-25 13:21:54.489129Z] Finished create-virtualenv step (success) 06:21:54 INFO - [mozharness: 2017-05-25 13:21:54.490191Z] Running install step. 06:21:54 INFO - Running pre-action listener: _resource_record_pre_action 06:21:54 INFO - Running main action method: install 06:21:54 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 06:21:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 06:21:54 INFO - Reading from file tmpfile_stdout 06:21:54 INFO - Detecting whether we're running mozinstall >=1.0... 06:21:54 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 06:21:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 06:21:54 INFO - Reading from file tmpfile_stdout 06:21:54 INFO - Output received: 06:21:54 INFO - Usage: mozinstall [options] installer 06:21:54 INFO - Options: 06:21:54 INFO - -h, --help show this help message and exit 06:21:54 INFO - -d DEST, --destination=DEST 06:21:54 INFO - Directory to install application into. [default: 06:21:54 INFO - "/builds/slave/test"] 06:21:54 INFO - --app=APP Application being installed. [default: firefox] 06:21:54 INFO - mkdir: /builds/slave/test/build/application 06:21:54 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'] 06:21:54 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 06:22:09 INFO - Reading from file tmpfile_stdout 06:22:09 INFO - Output received: 06:22:09 INFO - /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox 06:22:09 INFO - Running post-action listener: _resource_record_post_action 06:22:09 INFO - [mozharness: 2017-05-25 13:22:09.882409Z] Finished install step (success) 06:22:09 INFO - [mozharness: 2017-05-25 13:22:09.882522Z] Running run-tests step. 06:22:09 INFO - Running pre-action listener: _resource_record_pre_action 06:22:09 INFO - Running main action method: run_tests 06:22:09 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 06:22:09 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 06:22:09 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 06:22:09 INFO - 2017-05-25 06:22:09.897 screenresolution[6636:122990] starting screenresolution argv=screenresolution get 06:22:09 INFO - 2017-05-25 06:22:09.900 screenresolution[6636:122990] Display 0: 1600x1200x32@60 06:22:09 INFO - 2017-05-25 06:22:09.905 screenresolution[6637:122992] starting screenresolution argv=screenresolution list 06:22:09 INFO - Available Modes on Display 0 06:22:09 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 06:22:09 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 06:22:09 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 06:22:09 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 06:22:10 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 06:22:10 INFO - Intel Iris: 06:22:10 INFO - Chipset Model: Intel Iris 06:22:10 INFO - Type: GPU 06:22:10 INFO - Bus: Built-In 06:22:10 INFO - VRAM (Dynamic, Max): 1536 MB 06:22:10 INFO - Vendor: Intel (0x8086) 06:22:10 INFO - Device ID: 0x0a2e 06:22:10 INFO - Revision ID: 0x0009 06:22:10 INFO - Displays: 06:22:10 INFO - Display: 06:22:10 INFO - Resolution: 1600 x 1200 @ 60 Hz 06:22:10 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 06:22:10 INFO - Display Serial Number: 1600x1200 60 06:22:10 INFO - Main Display: Yes 06:22:10 INFO - Mirror: Off 06:22:10 INFO - Online: Yes 06:22:10 INFO - Rotation: Supported 06:22:10 INFO - Return code: 0 06:22:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 06:22:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 06:22:10 INFO - Python 2.7.3 06:22:10 INFO - Return code: 0 06:22:10 INFO - grabbing minidump binary from tooltool 06:22:10 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')]} 06:22:10 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 0x10b31ed30>, '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 0x10b245db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b313be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 06:22:10 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 06:22:10 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 06:22:10 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 06:22:10 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 06:22:10 INFO - Return code: 0 06:22:10 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 06:22:10 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 06:22:10 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 06:22:10 INFO - ENV: RUST_BACKTRACE is now 1 06:22:10 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 06:22:10 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 06:22:10 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-e10s', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/SS9fEveGTiCjigUMTVCNEg/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-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 06:22:10 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-e10s --executablePath /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/SS9fEveGTiCjigUMTVCNEg/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-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 06:22:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KDVfahnlTt/Render', 06:22:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:22:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:22:10 INFO - 'HOME': '/Users/cltbld', 06:22:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:22:10 INFO - 'LOGNAME': 'cltbld', 06:22:10 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 06:22:10 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 06:22:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:22:10 INFO - 'MOZ_NO_REMOTE': '1', 06:22:10 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 06:22:10 INFO - 'NO_EM_RESTART': '1', 06:22:10 INFO - 'PAGER': '/bin/cat', 06:22:10 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:22:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:22:10 INFO - 'PWD': '/builds/slave/test', 06:22:10 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 06:22:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:22:10 INFO - 'RUST_BACKTRACE': '1', 06:22:10 INFO - 'SHELL': '/bin/bash', 06:22:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.i2dogWExfy/Listeners', 06:22:10 INFO - 'TMPDIR': '/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/', 06:22:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:22:10 INFO - 'USER': 'cltbld', 06:22:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:22:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:22:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:22:10 INFO - 'XPC_FLAGS': '0x0', 06:22:10 INFO - 'XPC_SERVICE_NAME': '0', 06:22:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:22:10 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/SS9fEveGTiCjigUMTVCNEg/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-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 06:22:10 INFO - mozversion application_buildid: 20170525051645 06:22:10 INFO - mozversion application_changeset: 02e5b298abedc3fd641a8db70efd59009109ed82 06:22:10 INFO - mozversion application_display_name: Firefox 06:22:10 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 06:22:10 INFO - mozversion application_name: Firefox 06:22:10 INFO - mozversion application_remotingname: firefox 06:22:10 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 06:22:10 INFO - mozversion application_vendor: Mozilla 06:22:10 INFO - mozversion application_version: 54.0 06:22:10 INFO - mozversion platform_buildid: 20170525051645 06:22:10 INFO - mozversion platform_changeset: 02e5b298abedc3fd641a8db70efd59009109ed82 06:22:10 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 06:22:10 INFO - mozversion platform_version: 54.0 06:22:10 INFO - using testdate: 1495718530 06:22:10 INFO - actual date: 1495718530 06:22:10 INFO - starting webserver on 'localhost:57125' 06:22:10 INFO - SUITE-START | Running 2 tests 06:22:10 INFO - TEST-START | dromaeo_css 06:22:10 INFO - operating with platform_type : mac_ 06:22:10 INFO - Initialising browser for dromaeo_css test... 06:22:10 INFO - TEST-INFO | started process 6647 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/tmpzzyjp2/profile http://localhost:57125/getInfo.html) 06:22:13 INFO - PROCESS | 6647 | 2017-05-25 06:22:13.345 plugin-container[6650:123145] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8b3f, name = 'com.apple.tsm.portname' 06:22:13 INFO - PROCESS | 6647 | See /usr/include/servers/bootstrap_defs.h for the error codes. 06:22:13 INFO - PROCESS | 6647 | 2017-05-25 06:22:13.345 plugin-container[6650:123145] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x910b, name = 'com.apple.CFPasteboardClient' 06:22:13 INFO - PROCESS | 6647 | See /usr/include/servers/bootstrap_defs.h for the error codes. 06:22:13 INFO - PROCESS | 6647 | 2017-05-25 06:22:13.347 plugin-container[6650:123145] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 06:22:13 INFO - PROCESS | 6647 | __metrics Screen width/height:1600/1200 06:22:13 INFO - PROCESS | 6647 | colorDepth:24 06:22:13 INFO - PROCESS | 6647 | Browser inner width/height: 1024/689 06:22:13 INFO - PROCESS | 6647 | __metrics 06:22:13 ERROR - PROCESS | 6647 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3371: TypeError: this.tabs is undefined 06:22:14 INFO - TEST-INFO | 6647: exit 0 06:22:14 INFO - Browser initialized. 06:22:14 INFO - Running cycle 1/1 for dromaeo_css test... 06:22:14 INFO - TEST-INFO | started process 6652 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/tmpzzyjp2/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 06:22:16 INFO - PROCESS | 6652 | 2017-05-25 06:22:16.581 plugin-container[6653:123283] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x443b, name = 'com.apple.tsm.portname' 06:22:16 INFO - PROCESS | 6652 | See /usr/include/servers/bootstrap_defs.h for the error codes. 06:22:16 INFO - PROCESS | 6652 | 2017-05-25 06:22:16.582 plugin-container[6653:123283] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8b0b, name = 'com.apple.CFPasteboardClient' 06:22:16 INFO - PROCESS | 6652 | See /usr/include/servers/bootstrap_defs.h for the error codes. 06:22:16 INFO - PROCESS | 6652 | 2017-05-25 06:22:16.582 plugin-container[6653:123283] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 06:22:26 INFO - PROCESS | 6652 | RSS: Main: 174706688 06:22:26 INFO - PROCESS | 6652 | 06:25:29 INFO - PROCESS | 6652 | Cycle 1(1): loaded http://localhost:57125/tests/dromaeo/cssquery-dojo.html (next: http://localhost:57125/tests/dromaeo/cssquery-ext.html) 06:25:31 INFO - PROCESS | 6652 | RSS: Main: 189632512 06:25:31 INFO - PROCESS | 6652 | 06:28:33 INFO - PROCESS | 6652 | Cycle 1(1): loaded http://localhost:57125/tests/dromaeo/cssquery-ext.html (next: http://localhost:57125/tests/dromaeo/cssquery-jquery.html) 06:28:33 INFO - PROCESS | 6652 | RSS: Main: 202739712 06:28:33 INFO - PROCESS | 6652 | 06:31:35 INFO - PROCESS | 6652 | Cycle 1(1): loaded http://localhost:57125/tests/dromaeo/cssquery-jquery.html (next: http://localhost:57125/tests/dromaeo/cssquery-mootools.html) 06:31:36 INFO - PROCESS | 6652 | RSS: Main: 204316672 06:31:36 INFO - PROCESS | 6652 | 06:34:38 INFO - PROCESS | 6652 | Cycle 1(1): loaded http://localhost:57125/tests/dromaeo/cssquery-mootools.html (next: http://localhost:57125/tests/dromaeo/cssquery-prototype.html) 06:34:39 INFO - PROCESS | 6652 | RSS: Main: 207568896 06:34:39 INFO - PROCESS | 6652 | 06:37:42 INFO - PROCESS | 6652 | Cycle 1(1): loaded http://localhost:57125/tests/dromaeo/cssquery-prototype.html (next: http://localhost:57125/tests/dromaeo/cssquery-yui.html) 06:37:42 INFO - PROCESS | 6652 | RSS: Main: 211042304 06:37:42 INFO - PROCESS | 6652 | 06:40:45 INFO - PROCESS | 6652 | Cycle 1(1): loaded http://localhost:57125/tests/dromaeo/cssquery-yui.html (next: http://localhost:57125/tests/dromaeo/cssquery-dojo.html) 06:40:45 INFO - PROCESS | 6652 | RSS: Main: 209289216 06:40:45 INFO - PROCESS | 6652 | 06:40:45 INFO - PROCESS | 6652 | __start_tp_report 06:40:45 INFO - PROCESS | 6652 | _x_x_mozilla_page_load 06:40:45 INFO - PROCESS | 6652 | _x_x_mozilla_page_load_details 06:40:45 INFO - PROCESS | 6652 | |i|pagename|runs| 06:40:45 INFO - PROCESS | 6652 | |0;dojo.html;3599.694026007789;3601.5137956375884;3625.7824530528187;3640.053586067623;3643.471696603991;6132.478739307165;6158.969205153973;6159.414855588718;6172.598781079227;6190.009598464247;6120.724567394467;6156.56904016719;6166.81499555014;6169.0437982112735;6173.074038894169;5628.352739434956;5687.431256874321;5762.596618236725;5795.594308398414;5852.67810270435;5814.534837213034;5816.185733997244;5823.184754134425;5845;5870.090136028901;5435.184722291669;5467.371252305976;5798.9130163047585;5806.622569532967;5828.504577110951;4666.53334666534;5675.801346952858;5686.658800471964;5716;5774.220480235156;5533.723313834292;5664.801731939362;5666.065099258617;5670.659760414347;5702.3157221133315;5955.791547295824;6169.96915015422;6235.594686345373;6239.968800156015;6241.843953901189;6076.392360763933;6130.846728831771;6312.810615681537;6460.160179176724;6474.643894585796;6676.031975363545;6722.764703235363;6740.056392105109;6751.054852320679;6760.492963027793;6261.8747625047245;6270.592411493239;6274.152989346426;6279.686015699197;6288.088227207029;6306.621602703761;6450.0647406125845;6692.230393504712;6694.933050669529;6717.1267735194115;6443.323451037667;6445.51658625605;6453.9354606453335;6471.7734879279;6480.740770369132;6549.705263263069;6629.270780214172;6651.600903945779;6655.567388119756;6693;6259.123722678829;6275.058741188858;6447.097406363113;6467.4179323860135;6558.213014438203;6230;6568.1789776277965;6575.901361479581;6592.76925307612;6607.405333520006;5151.5878729701535;5212.270282160424;5241.16141417379;5275.947240527622;5303.7348132593215;4679.64902632304;4694.6009589185305;4710.175719249118;4738.218193997952;4751.59611433032;6078.179445774852;6111.865232675556;6133.233345831771;6177.62934223948;6209.534284928558;6712.496562757813;6724.865502690017;6730.192376914802;6741.359570840762;6745.3591908768585;6270.905936410957;6271.78048768291;6279.058141278845;6283.748650053946;6300.243970723543;5575.4424557544735;5591.217229587861;5760.164776107441;5857.58996870215;5878.588498805043;6726.562773419712;6845.828854278683;6848.657567121723;6856.211535673461;6862.039314496074;5540.695261760642;6527.738890444329;6667.466602671678;6689.397954184147;6700.865982680221;6468.062130990886;6515;6821.1814582250445;6869.587824730532;6884.827879303058;6486.805395837943;6499;6535.379138981789;6558.672066396565;6575.2767195607485;6686.732530698718;6696.497762667722;6714.865702685821;6718.496112791658;6720.966395167993;6204.565680402328;6437.678116094083;6464.838379040561;6474.1907261592305;6538.1827271591055;6262.091996660368;6391.58454700443;6407.9038814418755;6495.967520162369;6501.99219121037;5851.765929362778;6316.242050953915;6322.652254126067;6360.236771587439;6363.109164717036;6183.350748171557;6205.100260462298;6376.904346434714;6478.28738838718;6489.318621544669;6263.467605253604;6266.279377871603;6292.905606415999;6295.559310848196;6311.905321420274 06:40:45 INFO - PROCESS | 6652 | |1;ext.html;763.8739607964686;824.1758241758242;843.9703561654779;987.8765154355706;988.7874107066979;39146.41280380793;40254.59108931188;41979.790101049526;42042.57957420425;42126.78936605316;40485.59514404855;40590.39114413279;40619;40855.77432677017;40985.18029639406;8285;8425.07324194338;11170.441477926111;11475.942620286887;11573.189876708633;11212.215144939857;11304.660860174208;11321.377324247163;11324.32054076754;11344.546218151265;11293.548258069668;11314;11361.034312083482;11380.601678941239;11381.886181138207;11220.831687524693;11257.099432045417;11300.095992320677;11300.830487542693;11335.943320283428;8899.822003559893;10020.84760252573;11096.1123110151;11096.667099987013;11129.332240065622;34630.826845865864;34683.82658086693;34701.826490867636;34786.65213347885;34873.47689784655;3929.0963078491823;3947.782871942042;3970.1265721541213;3987.601239876018;4037.9192416151513;25635;25644.48711025769;25763.742362576322;26704.465910681873;27356.58965115525;3124.8906288280027;3209.983950080258;3266.395716792401;3288.7862288951383;3320.369129865342;358519.2074039639;365540;366391;367148;368149;39169.6083039166;45501;45557.54442455533;45608.3158752619;45634.08731825345;45371.77314113409;45876.311855322194;46064;46239.537604623525;46281.53718462772;14591.708165836624;14686.55940321792;14819.481318153812;14821.925890370694;14826;12577.93087587547;12685.809712854314;12721.918636915781;12729.172603780724;12752.617421477371;10752;11891.345975971231;11897.345645989553;11907.464164112636;12045.277283363026;4763.595094417014;5586.13414920688;5614.1859430382365;5638.182463542846;5666.801661941812;946.6450081219542;1112.6161474291357;1121.7139629394453;1143.7941170589374;1169.1465230381868;21965.682059076506;22281.888590556944;23290.06839726426;27734.5839812403;28050.579241311396;961.4570308413519;1024.8206563851297;1196.8264601632713;1212.3695678247261;1247.2641141726424;11454.828177577343;13498.055136140572;14616.049956752777;14831.851681483047;14964.775528367083;502669;504673;504995;504996;506323;47968.520314797104;48258.758706206245;48377.5162248373;49447.75276123596;50366.740831478775;1436.8935919342048;1462.510059130182;1477.4902658582987;1481.703659268129;1484.6140003599141;26531;28133;28560.14319570416;28865.278368040967;29014.12957611275;41852.790736046125;42343.153136937704;42514;42520.78739606406;42558.787206063775;491370;496466;496926;497445;500519;30900.381992359577;30985.91549295764;31236.9067082651;31477;33113.33773324568;39898.800505997286;40801.1839763209;40881;41302.9674258146;41507.37738933979;28392.29019274452;28939.85530072336;28956.855215723786;29164.562531562467;29799.70200298056;27510.17469475919;29102.563461548518;29151.562726559543;29591.816327346663;29854.850725746233;33075.83462082674;34012;34300.456479457986;34639.826800865834;34837;28868.566971495864;29530.261743456587;29567.55648665315;29777.404451910406;29862.85068574643;19457.805421946166;20733.89633051825;22568.097276108772;23942;24191.274261772174 06:40:45 INFO - PROCESS | 6652 | |2;jquery.html;416.508519946463;428.9571042895711;429.9226139294927;435.6813329912427;445.48323944224705;20566.794332056674;22107.894605269732;22828.885855570697;22888.542229155406;22902;22396.440088997784;23192.30423087306;23739.762602374016;23869.880650596722;24110;6689.364510371532;6806;6931.480138989596;7061.364477197051;7072.504924655276;6068.453839154476;6844.212915514705;6908.896366554506;6960.234374218832;6965.930340696605;5851.210086638313;6723.630200338979;6917.446604271646;6997.195322537897;6999.685014174374;6626;6928.653567321639;6934.47991400642;6957.060796792444;7002.859942801141;6771.593704377753;6820.556663816886;6821.079154314154;6845;6868.381845633867;26906.730932690618;28049.579256310968;28234.29414264639;28245.01142460004;28430.147095587166;2943.278896670308;3010.819350838957;3015.306479509703;3080.86136123875;3084.3522860199387;16505.752413713803;16841.242144103544;16960;17230.396936107176;17841.840280381864;2133.6266153423244;2145.8497905146646;2205.6470964645578;2254.627986382245;2255.2783109404995;157318;158872.41127588807;164695;166282.16858915627;168185;12225.777422257703;18955.620887582172;22240.443988900082;22607.660885086738;22615.88692056529;33126.67493300241;33226;33832.98501044972;33909.49135762965;33946.83026584901;10934.617288394868;11053.834192487118;11096.667099987013;11272.718182045515;11429.914158154961;9440.95279523598;9445.386049906734;9447.433154010781;9637.084476974676;9643.324967252225;9724.367916085432;9971.750706232257;9993.050660187271;10028.749281268027;10033.749156271151;3975.483187185705;4072.1041370898356;4102.743491079894;4107.8973025674595;4129.339305711072;1023.3501726403787;1038.2628333883029;1066.9306495077794;1072;1077.8113830079706;22527;22798.202062927718;22899.656505152103;22910.65634015491;23009.849507524556;956.9521523923776;1079.9146857408412;1103.9227254092298;1105.950232239551;1120.1374941295157;11089.27919685218;11546.538138474367;11595.826062609067;11657.18399712029;11745.060395168366;779293;779644;782384;789584;802120;123398.76601234052;153151;158149;159784;162160;1205.5479195301762;1218.2264262193394;1240.8448943882015;1253.2355263289387;1255.6923553729212;18126.456206313833;18952.43142705721;19507.85413604474;19571.70642440336;19606.90196549008;36879;54777;59640.70179649074;64131.35868641254;64621;54757.72621136869;73399.63300183679;73885;73974.63012684902;75304.24695752973;11386.487608057823;11789.76420471603;12178.878211217774;12243.265404075784;12354.320512371547;12016.759664806481;13122.409491572711;13467.326633668474;13527.594172174851;13795.724085518434;11454.739978602514;11531.077513799044;11690.357030363411;11760.823587646364;11929.522819087138;10610.575576976835;10753.569857205937;10764.51559679832;10878.728031798952;11100.944495277472;11649;11826.930287199877;11913.463894124612;11924.523019079139;11955.820662690241;11296.096312294832;11953.820692689791;12097;12154.1492095556;12415.193012454161;11496.712582185512;11718.88281117212;11813.468393922467;11913.761724765629;12044.698882528008 06:40:45 INFO - PROCESS | 6652 | |3;mootools.html;1572.0881888504668;1624.9525597746836;1628;1641.0481920486116;1645.1445248470795;9750.41497510149;10979.61571345002;11588.82616760747;11925.165238433303;11947.34289614073;11565.248258863168;11665.358405287727;11777.646670599874;11793.351365674884;11806.055515558755;2418.2382549496942;2455.7544245575477;2467.568175569268;2470.3330100872845;2474.072222916406;2398.4927177933046;2414.2033129067368;2464.346948058766;2464.987675061622;2470.542949554338;2427.3810178404483;2427.951341303582;2454.4232105455203;2472.938176545592;2477.1949116537107;2057.5473395852964;2376.607859703147;2414.9758502414925;2429.0405289910473;2432.4891772727747;2386.3079706884982;2413.263954493879;2426.2842461473906;2439.9634005489934;2445.9143929962365;10087.596496140146;10862.565497380096;11134.721631959184;11351.26216795914;11365.829512557319;5864.208331875185;5943.4056594340655;6037.245344331958;6052.364501727343;6055.969720151371;3574.946375804365;3631.564212294515;3646.4894914711963;3659.8536058557625;3681.7606855554304;1225.6139316115396;1253.5863165155477;1276.0557187681143;1281.0904257976847;1304.8564657887625;14425.134491930517;14728.852711472855;15058.32237549302;15987.92006039974;16154.596135096599;3975.483187185705;4590.632749380042;4686.625069994392;4698.107359601665;4832.540908613676;11990.760184796429;12125.96929261005;12134.939325303318;12152.392380380945;12157.574484892986;5863.384344643836;5903.911441328383;5908.14331921869;5927.347991720907;5955.225820643375;5244.947550524522;5316.548093412103;5320.467953204648;5325.707086110224;5366.758495867695;3868.709846761504;3955.940660890089;3970.2059588082498;3986.7807270600397;4018.678505719535;1039.8440233964814;1040.843873418978;1043.968680939573;1050.401271275381;1053.4153544782719;959.3764053365229;976.702105857706;983.3657291047377;1002.674130907458;1016.425719468498;6744.5616034957575;6754;6948.722051117898;6969.756058538028;7039.296070392919;985.8816941966867;990.1187942730975;1020.5152552537634;1036.6164519127972;1066.6746642274027;3618.782873027627;3843.2889915365745;4143.129942712064;4204.558521355275;4219.810108545123;13291.268980205989;14133.293335333397;14253;14280.143191408548;14298;13866.098703584235;14236.85763142376;14252;14352.995290329576;14484.420623175165;9075.319351048698;10573.259871809054;10652.09457196147;10711.83932241017;10855.023047925566;6308.27454842699;6664.5334826561675;6763.2898545652;6948.062011628567;6985.720571177096;8640.697575584825;11576.47905844256;11733.120015998664;11756.177067605186;11939.403029848647;12103.152779305716;12289.508419663114;12328.81506777417;12488;12517.74964500723;8284.295834854105;8353.122922093093;8354.707585234732;8443.788905277417;8454.873176902229;10164.33931794431;10173.643922462677;10176.084152426318;10272.691819245434;10282.331648442827;7871.48835325702;8600.483970961763;8771.824563508822;8870.822583548164;8916.465012099035;7236.529625574321;8562.657493700432;8569.442986205877;8672.78318042054;8708.78228044304;7913.841723165389;7971.083325417651;8066.717664881936;8078.596070196584;8083.393745469231;5788.652680839163;5863.1791549184;5869.476533038822;5927.792527261525;6027.487663548647;5271.472852714851;5908;6008.819735407945;6078;6136.417040381157 06:40:45 INFO - PROCESS | 6652 | |4;prototype.html;305.70346763639276;390.9824057917393;399.66627865732113;401.72052014334344;407.6820080337337;4816.54242846929;4907.901841963158;5551.9722401387935;5636.491761166282;5718.112353261602;4762.1428142934255;5150.3047088643025;5309.991101690676;5361.624686271963;5455.481729235727;4483.865484035484;4498.920259137816;4511.653939951223;4534.410526631534;4537.205988951937;3989.660878825289;4392.758398288093;4414.978295907362;4541.6837412919485;4568.954310456887;3651.4157734762443;3706.518152640153;4431.490378606453;4439.8466743629315;4450.221211288028;3717.8698745543775;4218.704690671654;4459.910801783946;4475.881029742564;4484.484284307281;3571.4642803579413;3957.52509698838;4112.712110152291;4151.750894946313;4160.1679664067005;4309.504406993205;4903.65674402792;5212.940888349119;5215.817446389358;5247.265382846366;4299.441072660534;4707.6233901287815;4838.975805120952;4961.553460188564;5027.798888044474;5214.670259083947;5470.699111548864;5648.971755141198;5804.332501762309;5853.912191317134;4081.2449696806184;4574.9020235143325;4619.5611416915335;4649.721016738973;4675.672702910798;4975.731188546897;5445.482679145475;5746.109353050284;6006.336134957673;6008.497875531161;4520.95479045212;4534.0302102122505;4690.663160999099;4710.8351207707565;4722.787474563653;4320.891977700519;4474.740256170613;4488.842890498817;4634.559716826896;4838.7338696372035;4732.958749075198;4815.807367705253;4866.489018653061;4875.878103047452;5197.324347834833;5224.719943613839;5366.363259205979;5498.257735205776;5801;5882.617629854131;1529.6940611877446;1542.3136704166764;1564.6870625874872;1615.9192040397934;1626.211287525549;1070.593174593665;1102.5755084292605;1131.9207655464204;1248.5942068827485;1263.589333466627;4918.458969513351;5366.409694933554;5398.838034858961;5401.864953376118;5402.675839449567;5077.283653245496;5422.53908417781;5517.972410137923;5882.264731904099;5968.850778730566;4781.737004464788;5120.769565369567;5171.3277273954145;5398.352197736297;5404.702741349185;3001.564773107887;3137.7803553751473;3148.511980643004;3152.4010438016703;3193.744500439959;5282.867928301746;5655.840552686689;5705.174344209896;6238.189035425366;6238.719257633417;6028.125921741411;6152.600080994721;6161.4146656067605;6258.655773932477;6259.655718935411;5060.84817455477;5709.514691251207;5929.644221346733;6257.436830685169;6276.46650034743;5662.490375866027;5931.436513531207;6013.9699301503215;6057.5159086024205;6105.908411373922;4775.976120119377;5143.074246635641;5477.863053423696;5939.940600594112;6043.637381757108;5806.535477161732;5971.611845230046;6076.33160352352;6125.540584456273;6275.305667469849;3776.4901738264984;4619.396870441062;4627.264775709109;4756.310335001474;4808.302796094476;5660.235868157911;5677.5457963362005;5990.760369585168;6058.242719660043;6073.635581865102;5593.776248949996;5787.39232380594;5826.621269617462;5882.852928676817;5883.822985495394;4962.280469331999;5582.8325150245555;5816.767329306949;5854.590178687451;5880.529557635294;5310.707911064928;5343.438938911514;5624.625224865048;5683.630564013326;5710.859027534756;5644.096944488862;5819.399665091964;5835.591508594358;5861.706914654335;5868;4791.952080479151;5174.120399532013;5560.972195138998;5600.971995139998;5752.820671762195 06:40:45 INFO - PROCESS | 6652 | |5;yui.html;704.3308856586244;757.6704133701838;784.8979632647762;815.5432957543773;920.5397301349325;15631.749460043198;15770.684586308282;16361.345546178138;16719.331226750914;16783.664326713475;14105.435782568686;15926.362945482197;16347.59131021725;16362.590935226624;16401.42595009175;961.8172547216042;999.7800483893532;1010.4745532323187;1029.531563138774;1034.5241189053006;1016.6746641074859;1018.9490525473734;1019.0777346501428;1021.4228960637216;1022.3508072374021;1060.6870973062926;1085.039739830248;1086.0876863434714;1086.657702823612;1087.9782404351909;1060.2631171335925;1079.7084787107515;1084.2356138922041;1084.5824357622294;1086.538221255967;792.480924994124;797.5613412623034;798.6446031515977;799.8520273749375;801.286854699318;16758.58103547409;16990.405335813186;17251.65496690059;18117.728234076498;18233.7264941026;14574.635634109127;14586;14639.268036598127;14656.193909334983;14712.999999999893;11810.29138251699;11990.760184796256;12082;12183.695407614881;12201.145919785618;3822.7132965027454;3938;3956.36698128301;4006.6794656427414;4043.9191216175514;109208.63309352566;118775;119220;121338;121379;9966.20270378368;11003.17476189289;11113.555457781598;11548.133889958119;12097.939510302393;23865.164719234745;24093;24096.638550421758;24103.03587856466;24108.758912411;8299.751007469786;8302.584870756438;8372.790680233044;8377.478996612661;8462.746117616482;4503.076869241798;4535.115652447741;4577.679562430598;4647.279671650899;4662.0209755950955;3593.9101522462147;3622.7826330420257;3674.2835147146584;3692.9815350923072;3703.055720791181;665.1487885144957;667.9031540426611;673.515069150211;675.321302091395;681.6012632609978;686.3308274432388;687.6321168175102;713.73591771044;714.7284032067885;744.385881647632;10541.409490543141;10741.087007604285;10745.78508429827;10909.890901090888;11017.504212310465;679.2290749998688;707.1372925031557;733.6075199768098;738.896554482373;739.5673530984434;4322.333047031308;4958.603311735053;5149.433562308143;5179.119549676564;5212.061828870839;103951;107157;107897;109057;109677;59389.96046959779;76621.61689191518;87288;88295.55852220826;90286;64676.67661661756;70187;73045.90431143537;77882.61058694897;78496.43007139825;10681.412522311348;10957.671269861918;11136.276142050741;11269.66191014271;11337.829932550854;47592;48549.757251213516;50277.99444011026;51806.48193518168;51859.74070129625;59031.70484147552;60194.194174174845;64767.35232647613;66261.66869165623;66985.3301466979;13172.275524846225;13193.868061319265;13244.867551324749;13320.600381988555;13671.79492307636;14707.55877323682;14757.92621036888;14833.184174870486;15061.623459413602;15444.691106177588;13974;14193.219372934587;14217.786733198804;14286.714265714836;14651.780223296446;13029.348532573144;13538.796918046433;14157.716845662822;14290.142591444546;14689.92655036718;13413.530526431918;14262.857371426153;14509.709805804036;14591.658372575459;14683.706325873209;10166.644167454104;10403.479826008821;10570.312929659547;10578.047975682226;10693.465326733787;9325.533723313943;10439.410412602068;10458.68623941283;11291.92726690996;11533.365664888512 06:40:45 INFO - PROCESS | 6652 | __end_tp_report 06:40:45 INFO - PROCESS | 6652 | __start_cc_report 06:40:45 INFO - PROCESS | 6652 | _x_x_mozilla_cycle_collect,391 06:40:45 INFO - PROCESS | 6652 | __end_cc_report 06:40:45 INFO - PROCESS | 6652 | __startTimestamp1495719645798__endTimestamp 06:40:45 INFO - PROCESS | 6652 | 06:40:45 INFO - PROCESS | 6652 | ------- Summary: start ------- 06:40:45 INFO - PROCESS | 6652 | Number of tests: 6 06:40:45 INFO - PROCESS | 6652 | 06:40:45 INFO - PROCESS | 6652 | [#0] dojo.html Cycles:165 Average:6125.48 Median:6275.06 stddev:658.25 (10.5%) stddev-sans-first:629.74 06:40:45 INFO - PROCESS | 6652 | Values: 3599.7 3601.5 3625.8 3640.1 3643.5 6132.5 6159.0 6159.4 6172.6 6190.0 6120.7 6156.6 6166.8 6169.0 6173.1 5628.4 5687.4 5762.6 5795.6 5852.7 5814.5 5816.2 5823.2 5845.0 5870.1 5435.2 5467.4 5798.9 5806.6 5828.5 4666.5 5675.8 5686.7 5716.0 5774.2 5533.7 5664.8 5666.1 5670.7 5702.3 5955.8 6170.0 6235.6 6240.0 6241.8 6076.4 6130.8 6312.8 6460.2 6474.6 6676.0 6722.8 6740.1 6751.1 6760.5 6261.9 6270.6 6274.2 6279.7 6288.1 6306.6 6450.1 6692.2 6694.9 6717.1 6443.3 6445.5 6453.9 6471.8 6480.7 6549.7 6629.3 6651.6 6655.6 6693.0 6259.1 6275.1 6447.1 6467.4 6558.2 6230.0 6568.2 6575.9 6592.8 6607.4 5151.6 5212.3 5241.2 5275.9 5303.7 4679.6 4694.6 4710.2 4738.2 4751.6 6078.2 6111.9 6133.2 6177.6 6209.5 6712.5 6724.9 6730.2 6741.4 6745.4 6270.9 6271.8 6279.1 6283.7 6300.2 5575.4 5591.2 5760.2 5857.6 5878.6 6726.6 6845.8 6848.7 6856.2 6862.0 5540.7 6527.7 6667.5 6689.4 6700.9 6468.1 6515.0 6821.2 6869.6 6884.8 6486.8 6499.0 6535.4 6558.7 6575.3 6686.7 6696.5 6714.9 6718.5 6721.0 6204.6 6437.7 6464.8 6474.2 6538.2 6262.1 6391.6 6407.9 6496.0 6502.0 5851.8 6316.2 6322.7 6360.2 6363.1 6183.4 6205.1 6376.9 6478.3 6489.3 6263.5 6266.3 6292.9 6295.6 6311.9 06:40:45 INFO - PROCESS | 6652 | 06:40:45 INFO - PROCESS | 6652 | [#1] ext.html Cycles:180 Average:57911.15 Median:30943.15 stddev:122487.84 (395.8%) stddev-sans-first:122756.30 06:40:45 INFO - PROCESS | 6652 | Values: 763.9 824.2 844.0 987.9 988.8 39146.4 40254.6 41979.8 42042.6 42126.8 40485.6 40590.4 40619.0 40855.8 40985.2 8285.0 8425.1 11170.4 11475.9 11573.2 11212.2 11304.7 11321.4 11324.3 11344.5 11293.5 11314.0 11361.0 11380.6 11381.9 11220.8 11257.1 11300.1 11300.8 11335.9 8899.8 10020.8 11096.1 11096.7 11129.3 34630.8 34683.8 34701.8 34786.7 34873.5 3929.1 3947.8 3970.1 3987.6 4037.9 25635.0 25644.5 25763.7 26704.5 27356.6 3124.9 3210.0 3266.4 3288.8 3320.4 358519.2 365540.0 366391.0 367148.0 368149.0 39169.6 45501.0 45557.5 45608.3 45634.1 45371.8 45876.3 46064.0 46239.5 46281.5 14591.7 14686.6 14819.5 14821.9 14826.0 12577.9 12685.8 12721.9 12729.2 12752.6 10752.0 11891.3 11897.3 11907.5 12045.3 4763.6 5586.1 5614.2 5638.2 5666.8 946.6 1112.6 1121.7 1143.8 1169.1 21965.7 22281.9 23290.1 27734.6 28050.6 961.5 1024.8 1196.8 1212.4 1247.3 11454.8 13498.1 14616.0 14831.9 14964.8 502669.0 504673.0 504995.0 504996.0 506323.0 47968.5 48258.8 48377.5 49447.8 50366.7 1436.9 1462.5 1477.5 1481.7 1484.6 26531.0 28133.0 28560.1 28865.3 29014.1 41852.8 42343.2 42514.0 42520.8 42558.8 491370.0 496466.0 496926.0 497445.0 500519.0 30900.4 30985.9 31236.9 31477.0 33113.3 39898.8 40801.2 40881.0 41303.0 41507.4 28392.3 28939.9 28956.9 29164.6 29799.7 27510.2 29102.6 29151.6 29591.8 29854.9 33075.8 34012.0 34300.5 34639.8 34837.0 28868.6 29530.3 29567.6 29777.4 29862.9 19457.8 20733.9 22568.1 23942.0 24191.3 06:40:45 INFO - PROCESS | 6652 | 06:40:45 INFO - PROCESS | 6652 | [#2] jquery.html Cycles:180 Average:44055.67 Median:12390.81 stddev:130871.17 (1056.2%) stddev-sans-first:131197.27 06:40:45 INFO - PROCESS | 6652 | Values: 416.5 429.0 429.9 435.7 445.5 20566.8 22107.9 22828.9 22888.5 22902.0 22396.4 23192.3 23739.8 23869.9 24110.0 6689.4 6806.0 6931.5 7061.4 7072.5 6068.5 6844.2 6908.9 6960.2 6965.9 5851.2 6723.6 6917.4 6997.2 6999.7 6626.0 6928.7 6934.5 6957.1 7002.9 6771.6 6820.6 6821.1 6845.0 6868.4 26906.7 28049.6 28234.3 28245.0 28430.1 2943.3 3010.8 3015.3 3080.9 3084.4 16505.8 16841.2 16960.0 17230.4 17841.8 2133.6 2145.8 2205.6 2254.6 2255.3 157318.0 158872.4 164695.0 166282.2 168185.0 12225.8 18955.6 22240.4 22607.7 22615.9 33126.7 33226.0 33833.0 33909.5 33946.8 10934.6 11053.8 11096.7 11272.7 11429.9 9441.0 9445.4 9447.4 9637.1 9643.3 9724.4 9971.8 9993.1 10028.7 10033.7 3975.5 4072.1 4102.7 4107.9 4129.3 1023.4 1038.3 1066.9 1072.0 1077.8 22527.0 22798.2 22899.7 22910.7 23009.8 957.0 1079.9 1103.9 1106.0 1120.1 11089.3 11546.5 11595.8 11657.2 11745.1 779293.0 779644.0 782384.0 789584.0 802120.0 123398.8 153151.0 158149.0 159784.0 162160.0 1205.5 1218.2 1240.8 1253.2 1255.7 18126.5 18952.4 19507.9 19571.7 19606.9 36879.0 54777.0 59640.7 64131.4 64621.0 54757.7 73399.6 73885.0 73974.6 75304.2 11386.5 11789.8 12178.9 12243.3 12354.3 12016.8 13122.4 13467.3 13527.6 13795.7 11454.7 11531.1 11690.4 11760.8 11929.5 10610.6 10753.6 10764.5 10878.7 11100.9 11649.0 11826.9 11913.5 11924.5 11955.8 11296.1 11953.8 12097.0 12154.1 12415.2 11496.7 11718.9 11813.5 11913.8 12044.7 06:40:45 INFO - PROCESS | 6652 | 06:40:45 INFO - PROCESS | 6652 | [#3] mootools.html Cycles:180 Average:6773.67 Median:2466.28 stddev:4250.55 (172.3%) stddev-sans-first:4244.51 06:40:45 INFO - PROCESS | 6652 | Values: 1572.1 1625.0 1628.0 1641.0 1645.1 9750.4 10979.6 11588.8 11925.2 11947.3 11565.2 11665.4 11777.6 11793.4 11806.1 2418.2 2455.8 2467.6 2470.3 2474.1 2398.5 2414.2 2464.3 2465.0 2470.5 2427.4 2428.0 2454.4 2472.9 2477.2 2057.5 2376.6 2415.0 2429.0 2432.5 2386.3 2413.3 2426.3 2440.0 2445.9 10087.6 10862.6 11134.7 11351.3 11365.8 5864.2 5943.4 6037.2 6052.4 6056.0 3574.9 3631.6 3646.5 3659.9 3681.8 1225.6 1253.6 1276.1 1281.1 1304.9 14425.1 14728.9 15058.3 15987.9 16154.6 3975.5 4590.6 4686.6 4698.1 4832.5 11990.8 12126.0 12134.9 12152.4 12157.6 5863.4 5903.9 5908.1 5927.3 5955.2 5244.9 5316.5 5320.5 5325.7 5366.8 3868.7 3955.9 3970.2 3986.8 4018.7 1039.8 1040.8 1044.0 1050.4 1053.4 959.4 976.7 983.4 1002.7 1016.4 6744.6 6754.0 6948.7 6969.8 7039.3 985.9 990.1 1020.5 1036.6 1066.7 3618.8 3843.3 4143.1 4204.6 4219.8 13291.3 14133.3 14253.0 14280.1 14298.0 13866.1 14236.9 14252.0 14353.0 14484.4 9075.3 10573.3 10652.1 10711.8 10855.0 6308.3 6664.5 6763.3 6948.1 6985.7 8640.7 11576.5 11733.1 11756.2 11939.4 12103.2 12289.5 12328.8 12488.0 12517.7 8284.3 8353.1 8354.7 8443.8 8454.9 10164.3 10173.6 10176.1 10272.7 10282.3 7871.5 8600.5 8771.8 8870.8 8916.5 7236.5 8562.7 8569.4 8672.8 8708.8 7913.8 7971.1 8066.7 8078.6 8083.4 5788.7 5863.2 5869.5 5927.8 6027.5 5271.5 5908.0 6008.8 6078.0 6136.4 06:40:45 INFO - PROCESS | 6652 | 06:40:45 INFO - PROCESS | 6652 | [#4] prototype.html Cycles:180 Average:4809.05 Median:5172.72 stddev:1354.84 (26.2%) stddev-sans-first:1315.80 06:40:45 INFO - PROCESS | 6652 | Values: 305.7 391.0 399.7 401.7 407.7 4816.5 4907.9 5552.0 5636.5 5718.1 4762.1 5150.3 5310.0 5361.6 5455.5 4483.9 4498.9 4511.7 4534.4 4537.2 3989.7 4392.8 4415.0 4541.7 4569.0 3651.4 3706.5 4431.5 4439.8 4450.2 3717.9 4218.7 4459.9 4475.9 4484.5 3571.5 3957.5 4112.7 4151.8 4160.2 4309.5 4903.7 5212.9 5215.8 5247.3 4299.4 4707.6 4839.0 4961.6 5027.8 5214.7 5470.7 5649.0 5804.3 5853.9 4081.2 4574.9 4619.6 4649.7 4675.7 4975.7 5445.5 5746.1 6006.3 6008.5 4521.0 4534.0 4690.7 4710.8 4722.8 4320.9 4474.7 4488.8 4634.6 4838.7 4733.0 4815.8 4866.5 4875.9 5197.3 5224.7 5366.4 5498.3 5801.0 5882.6 1529.7 1542.3 1564.7 1615.9 1626.2 1070.6 1102.6 1131.9 1248.6 1263.6 4918.5 5366.4 5398.8 5401.9 5402.7 5077.3 5422.5 5518.0 5882.3 5968.9 4781.7 5120.8 5171.3 5398.4 5404.7 3001.6 3137.8 3148.5 3152.4 3193.7 5282.9 5655.8 5705.2 6238.2 6238.7 6028.1 6152.6 6161.4 6258.7 6259.7 5060.8 5709.5 5929.6 6257.4 6276.5 5662.5 5931.4 6014.0 6057.5 6105.9 4776.0 5143.1 5477.9 5939.9 6043.6 5806.5 5971.6 6076.3 6125.5 6275.3 3776.5 4619.4 4627.3 4756.3 4808.3 5660.2 5677.5 5990.8 6058.2 6073.6 5593.8 5787.4 5826.6 5882.9 5883.8 4962.3 5582.8 5816.8 5854.6 5880.5 5310.7 5343.4 5624.6 5683.6 5710.9 5644.1 5819.4 5835.6 5861.7 5868.0 4792.0 5174.1 5561.0 5601.0 5752.8 06:40:45 INFO - PROCESS | 6652 | 06:40:45 INFO - PROCESS | 6652 | [#5] yui.html Cycles:180 Average:20856.02 Median:16354.47 stddev:29814.45 (182.3%) stddev-sans-first:29859.69 06:40:45 INFO - PROCESS | 6652 | Values: 704.3 757.7 784.9 815.5 920.5 15631.7 15770.7 16361.3 16719.3 16783.7 14105.4 15926.4 16347.6 16362.6 16401.4 961.8 999.8 1010.5 1029.5 1034.5 1016.7 1018.9 1019.1 1021.4 1022.4 1060.7 1085.0 1086.1 1086.7 1088.0 1060.3 1079.7 1084.2 1084.6 1086.5 792.5 797.6 798.6 799.9 801.3 16758.6 16990.4 17251.7 18117.7 18233.7 14574.6 14586.0 14639.3 14656.2 14713.0 11810.3 11990.8 12082.0 12183.7 12201.1 3822.7 3938.0 3956.4 4006.7 4043.9 109208.6 118775.0 119220.0 121338.0 121379.0 9966.2 11003.2 11113.6 11548.1 12097.9 23865.2 24093.0 24096.6 24103.0 24108.8 8299.8 8302.6 8372.8 8377.5 8462.7 4503.1 4535.1 4577.7 4647.3 4662.0 3593.9 3622.8 3674.3 3693.0 3703.1 665.1 667.9 673.5 675.3 681.6 686.3 687.6 713.7 714.7 744.4 10541.4 10741.1 10745.8 10909.9 11017.5 679.2 707.1 733.6 738.9 739.6 4322.3 4958.6 5149.4 5179.1 5212.1 103951.0 107157.0 107897.0 109057.0 109677.0 59390.0 76621.6 87288.0 88295.6 90286.0 64676.7 70187.0 73045.9 77882.6 78496.4 10681.4 10957.7 11136.3 11269.7 11337.8 47592.0 48549.8 50278.0 51806.5 51859.7 59031.7 60194.2 64767.4 66261.7 66985.3 13172.3 13193.9 13244.9 13320.6 13671.8 14707.6 14757.9 14833.2 15061.6 15444.7 13974.0 14193.2 14217.8 14286.7 14651.8 13029.3 13538.8 14157.7 14290.1 14689.9 13413.5 14262.9 14509.7 14591.7 14683.7 10166.6 10403.5 10570.3 10578.0 10693.5 9325.5 10439.4 10458.7 11291.9 11533.4 06:40:45 INFO - PROCESS | 6652 | -------- Summary: end -------- 06:40:45 INFO - PROCESS | 6652 | 06:40:46 INFO - TEST-INFO | 6652: exit 0 06:40:46 INFO - TEST-OK | dromaeo_css | took 1115955ms 06:40:46 INFO - TEST-START | kraken 06:40:46 INFO - operating with platform_type : mac_ 06:40:46 INFO - Initialising browser for kraken test... 06:40:46 INFO - TEST-INFO | started process 6658 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/tmpmmXxL9/profile http://localhost:57125/getInfo.html) 06:40:48 INFO - PROCESS | 6658 | 2017-05-25 06:40:48.945 plugin-container[6660:125500] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4643, name = 'com.apple.tsm.portname' 06:40:48 INFO - PROCESS | 6658 | See /usr/include/servers/bootstrap_defs.h for the error codes. 06:40:48 INFO - PROCESS | 6658 | 2017-05-25 06:40:48.946 plugin-container[6660:125500] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8c0b, name = 'com.apple.CFPasteboardClient' 06:40:48 INFO - PROCESS | 6658 | See /usr/include/servers/bootstrap_defs.h for the error codes. 06:40:48 INFO - PROCESS | 6658 | 2017-05-25 06:40:48.946 plugin-container[6660:125500] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 06:40:49 INFO - PROCESS | 6658 | __metrics Screen width/height:1600/1200 06:40:49 INFO - PROCESS | 6658 | colorDepth:24 06:40:49 INFO - PROCESS | 6658 | Browser inner width/height: 1024/689 06:40:49 INFO - PROCESS | 6658 | __metrics 06:40:49 ERROR - PROCESS | 6658 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3371: TypeError: this.tabs is undefined 06:40:49 INFO - TEST-INFO | 6658: exit 0 06:40:49 INFO - Browser initialized. 06:40:49 INFO - Running cycle 1/1 for kraken test... 06:40:49 INFO - TEST-INFO | started process 6661 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/tmpmmXxL9/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 06:40:52 INFO - PROCESS | 6661 | 2017-05-25 06:40:52.139 plugin-container[6662:125625] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4547, name = 'com.apple.tsm.portname' 06:40:52 INFO - PROCESS | 6661 | See /usr/include/servers/bootstrap_defs.h for the error codes. 06:40:52 INFO - PROCESS | 6661 | 2017-05-25 06:40:52.140 plugin-container[6662:125625] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x881b, name = 'com.apple.CFPasteboardClient' 06:40:52 INFO - PROCESS | 6661 | See /usr/include/servers/bootstrap_defs.h for the error codes. 06:40:52 INFO - PROCESS | 6661 | 2017-05-25 06:40:52.140 plugin-container[6662:125625] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 06:41:01 INFO - PROCESS | 6661 | RSS: Main: 174379008 06:41:01 INFO - PROCESS | 6661 | 06:41:04 INFO - PROCESS | 6661 | Cycle 1(1): loaded http://localhost:57125/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:57125/tests/kraken/driver.html?testName=audio-beat-detection) 06:41:05 INFO - PROCESS | 6661 | RSS: Main: 188100608 06:41:05 INFO - PROCESS | 6661 | 06:41:08 INFO - PROCESS | 6661 | Cycle 1(1): loaded http://localhost:57125/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:57125/tests/kraken/driver.html?testName=audio-dft) 06:41:09 INFO - PROCESS | 6661 | RSS: Main: 196042752 06:41:09 INFO - PROCESS | 6661 | 06:41:12 INFO - PROCESS | 6661 | Cycle 1(1): loaded http://localhost:57125/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:57125/tests/kraken/driver.html?testName=audio-fft) 06:41:13 INFO - PROCESS | 6661 | RSS: Main: 199655424 06:41:13 INFO - PROCESS | 6661 | 06:41:15 INFO - PROCESS | 6661 | Cycle 1(1): loaded http://localhost:57125/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:57125/tests/kraken/driver.html?testName=audio-oscillator) 06:41:15 INFO - PROCESS | 6661 | RSS: Main: 200155136 06:41:15 INFO - PROCESS | 6661 | 06:41:17 INFO - PROCESS | 6661 | Cycle 1(1): loaded http://localhost:57125/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:57125/tests/kraken/driver.html?testName=imaging-gaussian-blur) 06:41:18 INFO - PROCESS | 6661 | RSS: Main: 200257536 06:41:18 INFO - PROCESS | 6661 | 06:41:21 INFO - PROCESS | 6661 | Cycle 1(1): loaded http://localhost:57125/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:57125/tests/kraken/driver.html?testName=imaging-darkroom) 06:41:22 INFO - PROCESS | 6661 | RSS: Main: 205332480 06:41:22 INFO - PROCESS | 6661 | 06:41:25 INFO - PROCESS | 6661 | Cycle 1(1): loaded http://localhost:57125/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:57125/tests/kraken/driver.html?testName=imaging-desaturate) 06:41:25 INFO - PROCESS | 6661 | RSS: Main: 204824576 06:41:25 INFO - PROCESS | 6661 | 06:41:28 INFO - PROCESS | 6661 | Cycle 1(1): loaded http://localhost:57125/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:57125/tests/kraken/driver.html?testName=json-parse-financial) 06:41:29 INFO - PROCESS | 6661 | RSS: Main: 205000704 06:41:29 INFO - PROCESS | 6661 | 06:41:31 INFO - PROCESS | 6661 | Cycle 1(1): loaded http://localhost:57125/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:57125/tests/kraken/driver.html?testName=json-stringify-tinderbox) 06:41:31 INFO - PROCESS | 6661 | RSS: Main: 204652544 06:41:31 INFO - PROCESS | 6661 | 06:41:33 INFO - PROCESS | 6661 | Cycle 1(1): loaded http://localhost:57125/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:57125/tests/kraken/driver.html?testName=stanford-crypto-aes) 06:41:34 INFO - PROCESS | 6661 | RSS: Main: 204668928 06:41:34 INFO - PROCESS | 6661 | 06:41:36 INFO - PROCESS | 6661 | Cycle 1(1): loaded http://localhost:57125/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:57125/tests/kraken/driver.html?testName=stanford-crypto-ccm) 06:41:37 INFO - PROCESS | 6661 | RSS: Main: 204652544 06:41:37 INFO - PROCESS | 6661 | 06:41:40 INFO - PROCESS | 6661 | Cycle 1(1): loaded http://localhost:57125/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:57125/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 06:41:40 INFO - PROCESS | 6661 | RSS: Main: 205074432 06:41:40 INFO - PROCESS | 6661 | 06:41:43 INFO - PROCESS | 6661 | Cycle 1(1): loaded http://localhost:57125/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:57125/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 06:41:44 INFO - PROCESS | 6661 | RSS: Main: 204808192 06:41:44 INFO - PROCESS | 6661 | 06:41:46 INFO - PROCESS | 6661 | Cycle 1(1): loaded http://localhost:57125/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:57125/tests/kraken/driver.html?testName=ai-astar) 06:41:46 INFO - PROCESS | 6661 | RSS: Main: 206938112 06:41:46 INFO - PROCESS | 6661 | 06:41:46 INFO - PROCESS | 6661 | __start_tp_report 06:41:46 INFO - PROCESS | 6661 | _x_x_mozilla_page_load 06:41:46 INFO - PROCESS | 6661 | _x_x_mozilla_page_load_details 06:41:46 INFO - PROCESS | 6661 | |i|pagename|runs| 06:41:46 INFO - PROCESS | 6661 | |0;ai-astar;108;120;120;121;119;119;121;139;172;153 06:41:46 INFO - PROCESS | 6661 | |1;audio-beat-detection;153;146;96;135;133;140;146;122;128;110 06:41:46 INFO - PROCESS | 6661 | |2;audio-dft;209;247;196;201;200;197;198;199;200;197 06:41:46 INFO - PROCESS | 6661 | |3;audio-fft;119;82;84;110;61;58;60;59;72;58 06:41:46 INFO - PROCESS | 6661 | |4;audio-oscillator;84;69;81;92;102;98;109;74;71;82 06:41:46 INFO - PROCESS | 6661 | |5;imaging-gaussian-blur;97;90;90;90;90;110;92;90;93;130 06:41:46 INFO - PROCESS | 6661 | |6;imaging-darkroom;103;153;111;98;97;104;117;98;97;98 06:41:46 INFO - PROCESS | 6661 | |7;imaging-desaturate;92;112;92;139;144;91;92;91;92;90 06:41:46 INFO - PROCESS | 6661 | |8;json-parse-financial;65;66;66;65;67;67;75;98;102;102 06:41:46 INFO - PROCESS | 6661 | |9;json-stringify-tinderbox;57;64;62;72;66;59;59;58;57;57 06:41:46 INFO - PROCESS | 6661 | |10;stanford-crypto-aes;82;81;83;82;86;83;94;96;83;83 06:41:46 INFO - PROCESS | 6661 | |11;stanford-crypto-ccm;138;143;142;137;134;167;157;154;202;176 06:41:46 INFO - PROCESS | 6661 | |12;stanford-crypto-pbkdf2;168;167;167;218;167;176;214;163;164;169 06:41:46 INFO - PROCESS | 6661 | |13;stanford-crypto-sha256-iterative;65;65;65;66;62;65;65;61;76;80 06:41:46 INFO - PROCESS | 6661 | __end_tp_report 06:41:46 INFO - PROCESS | 6661 | __start_cc_report 06:41:46 INFO - PROCESS | 6661 | _x_x_mozilla_cycle_collect,1006 06:41:46 INFO - PROCESS | 6661 | __end_cc_report 06:41:46 INFO - PROCESS | 6661 | __startTimestamp1495719706405__endTimestamp 06:41:46 INFO - PROCESS | 6661 | 06:41:46 INFO - PROCESS | 6661 | ------- Summary: start ------- 06:41:46 INFO - PROCESS | 6661 | Number of tests: 14 06:41:46 INFO - PROCESS | 6661 | 06:41:46 INFO - PROCESS | 6661 | [#0] ai-astar Cycles:10 Average:129.20 Median:121.00 stddev:19.59 (16.2%) stddev-sans-first:19.22 06:41:46 INFO - PROCESS | 6661 | Values: 108.0 120.0 120.0 121.0 119.0 119.0 121.0 139.0 172.0 153.0 06:41:46 INFO - PROCESS | 6661 | 06:41:46 INFO - PROCESS | 6661 | [#1] audio-beat-detection Cycles:10 Average:130.90 Median:143.00 stddev:17.61 (12.3%) stddev-sans-first:16.76 06:41:46 INFO - PROCESS | 6661 | Values: 153.0 146.0 96.0 135.0 133.0 140.0 146.0 122.0 128.0 110.0 06:41:46 INFO - PROCESS | 6661 | 06:41:46 INFO - PROCESS | 6661 | [#2] audio-dft Cycles:10 Average:204.40 Median:200.00 stddev:15.41 (7.7%) stddev-sans-first:16.25 06:41:46 INFO - PROCESS | 6661 | Values: 209.0 247.0 196.0 201.0 200.0 197.0 198.0 199.0 200.0 197.0 06:41:46 INFO - PROCESS | 6661 | 06:41:46 INFO - PROCESS | 6661 | [#3] audio-fft Cycles:10 Average:76.30 Median:60.50 stddev:22.46 (37.1%) stddev-sans-first:17.72 06:41:46 INFO - PROCESS | 6661 | Values: 119.0 82.0 84.0 110.0 61.0 58.0 60.0 59.0 72.0 58.0 06:41:46 INFO - PROCESS | 6661 | 06:41:46 INFO - PROCESS | 6661 | [#4] audio-oscillator Cycles:10 Average:86.20 Median:81.50 stddev:13.61 (16.7%) stddev-sans-first:14.41 06:41:46 INFO - PROCESS | 6661 | Values: 84.0 69.0 81.0 92.0 102.0 98.0 109.0 74.0 71.0 82.0 06:41:46 INFO - PROCESS | 6661 | 06:41:46 INFO - PROCESS | 6661 | [#5] imaging-gaussian-blur Cycles:10 Average:97.20 Median:90.00 stddev:13.10 (14.6%) stddev-sans-first:13.89 06:41:46 INFO - PROCESS | 6661 | Values: 97.0 90.0 90.0 90.0 90.0 110.0 92.0 90.0 93.0 130.0 06:41:46 INFO - PROCESS | 6661 | 06:41:46 INFO - PROCESS | 6661 | [#6] imaging-darkroom Cycles:10 Average:107.60 Median:97.00 stddev:17.31 (17.8%) stddev-sans-first:18.28 06:41:46 INFO - PROCESS | 6661 | Values: 103.0 153.0 111.0 98.0 97.0 104.0 117.0 98.0 97.0 98.0 06:41:46 INFO - PROCESS | 6661 | 06:41:46 INFO - PROCESS | 6661 | [#7] imaging-desaturate Cycles:10 Average:103.50 Median:91.50 stddev:21.07 (23.0%) stddev-sans-first:21.94 06:41:46 INFO - PROCESS | 6661 | Values: 92.0 112.0 92.0 139.0 144.0 91.0 92.0 91.0 92.0 90.0 06:41:46 INFO - PROCESS | 6661 | 06:41:46 INFO - PROCESS | 6661 | [#8] json-parse-financial Cycles:10 Average:77.30 Median:66.50 stddev:16.41 (24.7%) stddev-sans-first:16.79 06:41:46 INFO - PROCESS | 6661 | Values: 65.0 66.0 66.0 65.0 67.0 67.0 75.0 98.0 102.0 102.0 06:41:46 INFO - PROCESS | 6661 | 06:41:46 INFO - PROCESS | 6661 | [#9] json-stringify-tinderbox Cycles:10 Average:61.10 Median:60.50 stddev:4.95 (8.2%) stddev-sans-first:5.03 06:41:46 INFO - PROCESS | 6661 | Values: 57.0 64.0 62.0 72.0 66.0 59.0 59.0 58.0 57.0 57.0 06:41:46 INFO - PROCESS | 6661 | 06:41:46 INFO - PROCESS | 6661 | [#10] stanford-crypto-aes Cycles:10 Average:85.30 Median:83.00 stddev:5.29 (6.4%) stddev-sans-first:5.48 06:41:46 INFO - PROCESS | 6661 | Values: 82.0 81.0 83.0 82.0 86.0 83.0 94.0 96.0 83.0 83.0 06:41:46 INFO - PROCESS | 6661 | 06:41:46 INFO - PROCESS | 6661 | [#11] stanford-crypto-ccm Cycles:10 Average:155.00 Median:155.50 stddev:21.51 (13.8%) stddev-sans-first:21.92 06:41:46 INFO - PROCESS | 6661 | Values: 138.0 143.0 142.0 137.0 134.0 167.0 157.0 154.0 202.0 176.0 06:41:46 INFO - PROCESS | 6661 | 06:41:46 INFO - PROCESS | 6661 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:177.30 Median:168.50 stddev:20.71 (12.3%) stddev-sans-first:21.69 06:41:46 INFO - PROCESS | 6661 | Values: 168.0 167.0 167.0 218.0 167.0 176.0 214.0 163.0 164.0 169.0 06:41:46 INFO - PROCESS | 6661 | 06:41:46 INFO - PROCESS | 6661 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.00 Median:65.00 stddev:6.07 (9.3%) stddev-sans-first:6.40 06:41:46 INFO - PROCESS | 6661 | Values: 65.0 65.0 65.0 66.0 62.0 65.0 65.0 61.0 76.0 80.0 06:41:46 INFO - PROCESS | 6661 | -------- Summary: end -------- 06:41:46 INFO - PROCESS | 6661 | 06:41:47 INFO - TEST-INFO | 6661: exit 0 06:41:47 INFO - TEST-OK | kraken | took 60777ms 06:41:47 INFO - SUITE-END | took 1176s 06:41:47 INFO - Completed test suite (00:19:37) 06:41:47 INFO - javascript benchmark 06:41:47 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7914.577252832769, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3599.694026007789, 3601.5137956375884, 3625.7824530528187, 3640.053586067623, 3643.471696603991, 6132.478739307165, 6158.969205153973, 6159.414855588718, 6172.598781079227, 6190.009598464247, 6120.724567394467, 6156.56904016719, 6166.81499555014, 6169.0437982112735, 6173.074038894169, 5628.352739434956, 5687.431256874321, 5762.596618236725, 5795.594308398414, 5852.67810270435, 5814.534837213034, 5816.185733997244, 5823.184754134425, 5845.0, 5870.090136028901, 5435.184722291669, 5467.371252305976, 5798.9130163047585, 5806.622569532967, 5828.504577110951, 4666.53334666534, 5675.801346952858, 5686.658800471964, 5716.0, 5774.220480235156, 5533.723313834292, 5664.801731939362, 5666.065099258617, 5670.659760414347, 5702.3157221133315, 5955.791547295824, 6169.96915015422, 6235.594686345373, 6239.968800156015, 6241.843953901189, 6076.392360763933, 6130.846728831771, 6312.810615681537, 6460.160179176724, 6474.643894585796, 6676.031975363545, 6722.764703235363, 6740.056392105109, 6751.054852320679, 6760.492963027793, 6261.8747625047245, 6270.592411493239, 6274.152989346426, 6279.686015699197, 6288.088227207029, 6306.621602703761, 6450.0647406125845, 6692.230393504712, 6694.933050669529, 6717.1267735194115, 6443.323451037667, 6445.51658625605, 6453.9354606453335, 6471.7734879279, 6480.740770369132, 6549.705263263069, 6629.270780214172, 6651.600903945779, 6655.567388119756, 6693.0, 6259.123722678829, 6275.058741188858, 6447.097406363113, 6467.4179323860135, 6558.213014438203, 6230.0, 6568.1789776277965, 6575.901361479581, 6592.76925307612, 6607.405333520006, 5151.5878729701535, 5212.270282160424, 5241.16141417379, 5275.947240527622, 5303.7348132593215, 4679.64902632304, 4694.6009589185305, 4710.175719249118, 4738.218193997952, 4751.59611433032, 6078.179445774852, 6111.865232675556, 6133.233345831771, 6177.62934223948, 6209.534284928558, 6712.496562757813, 6724.865502690017, 6730.192376914802, 6741.359570840762, 6745.3591908768585, 6270.905936410957, 6271.78048768291, 6279.058141278845, 6283.748650053946, 6300.243970723543, 5575.4424557544735, 5591.217229587861, 5760.164776107441, 5857.58996870215, 5878.588498805043, 6726.562773419712, 6845.828854278683, 6848.657567121723, 6856.211535673461, 6862.039314496074, 5540.695261760642, 6527.738890444329, 6667.466602671678, 6689.397954184147, 6700.865982680221, 6468.062130990886, 6515.0, 6821.1814582250445, 6869.587824730532, 6884.827879303058, 6486.805395837943, 6499.0, 6535.379138981789, 6558.672066396565, 6575.2767195607485, 6686.732530698718, 6696.497762667722, 6714.865702685821, 6718.496112791658, 6720.966395167993, 6204.565680402328, 6437.678116094083, 6464.838379040561, 6474.1907261592305, 6538.1827271591055, 6262.091996660368, 6391.58454700443, 6407.9038814418755, 6495.967520162369, 6501.99219121037, 5851.765929362778, 6316.242050953915, 6322.652254126067, 6360.236771587439, 6363.109164717036, 6183.350748171557, 6205.100260462298, 6376.904346434714, 6478.28738838718, 6489.318621544669, 6263.467605253604, 6266.279377871603, 6292.905606415999, 6295.559310848196, 6311.905321420274], "value": 6085.061791019583, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [763.8739607964686, 824.1758241758242, 843.9703561654779, 987.8765154355706, 988.7874107066979, 39146.41280380793, 40254.59108931188, 41979.790101049526, 42042.57957420425, 42126.78936605316, 40485.59514404855, 40590.39114413279, 40619.0, 40855.77432677017, 40985.18029639406, 8285.0, 8425.07324194338, 11170.441477926111, 11475.942620286887, 11573.189876708633, 11212.215144939857, 11304.660860174208, 11321.377324247163, 11324.32054076754, 11344.546218151265, 11293.548258069668, 11314.0, 11361.034312083482, 11380.601678941239, 11381.886181138207, 11220.831687524693, 11257.099432045417, 11300.095992320677, 11300.830487542693, 11335.943320283428, 8899.822003559893, 10020.84760252573, 11096.1123110151, 11096.667099987013, 11129.332240065622, 34630.826845865864, 34683.82658086693, 34701.826490867636, 34786.65213347885, 34873.47689784655, 3929.0963078491823, 3947.782871942042, 3970.1265721541213, 3987.601239876018, 4037.9192416151513, 25635.0, 25644.48711025769, 25763.742362576322, 26704.465910681873, 27356.58965115525, 3124.8906288280027, 3209.983950080258, 3266.395716792401, 3288.7862288951383, 3320.369129865342, 358519.2074039639, 365540.0, 366391.0, 367148.0, 368149.0, 39169.6083039166, 45501.0, 45557.54442455533, 45608.3158752619, 45634.08731825345, 45371.77314113409, 45876.311855322194, 46064.0, 46239.537604623525, 46281.53718462772, 14591.708165836624, 14686.55940321792, 14819.481318153812, 14821.925890370694, 14826.0, 12577.93087587547, 12685.809712854314, 12721.918636915781, 12729.172603780724, 12752.617421477371, 10752.0, 11891.345975971231, 11897.345645989553, 11907.464164112636, 12045.277283363026, 4763.595094417014, 5586.13414920688, 5614.1859430382365, 5638.182463542846, 5666.801661941812, 946.6450081219542, 1112.6161474291357, 1121.7139629394453, 1143.7941170589374, 1169.1465230381868, 21965.682059076506, 22281.888590556944, 23290.06839726426, 27734.5839812403, 28050.579241311396, 961.4570308413519, 1024.8206563851297, 1196.8264601632713, 1212.3695678247261, 1247.2641141726424, 11454.828177577343, 13498.055136140572, 14616.049956752777, 14831.851681483047, 14964.775528367083, 502669.0, 504673.0, 504995.0, 504996.0, 506323.0, 47968.520314797104, 48258.758706206245, 48377.5162248373, 49447.75276123596, 50366.740831478775, 1436.8935919342048, 1462.510059130182, 1477.4902658582987, 1481.703659268129, 1484.6140003599141, 26531.0, 28133.0, 28560.14319570416, 28865.278368040967, 29014.12957611275, 41852.790736046125, 42343.153136937704, 42514.0, 42520.78739606406, 42558.787206063775, 491370.0, 496466.0, 496926.0, 497445.0, 500519.0, 30900.381992359577, 30985.91549295764, 31236.9067082651, 31477.0, 33113.33773324568, 39898.800505997286, 40801.1839763209, 40881.0, 41302.9674258146, 41507.37738933979, 28392.29019274452, 28939.85530072336, 28956.855215723786, 29164.562531562467, 29799.70200298056, 27510.17469475919, 29102.563461548518, 29151.562726559543, 29591.816327346663, 29854.850725746233, 33075.83462082674, 34012.0, 34300.456479457986, 34639.826800865834, 34837.0, 28868.566971495864, 29530.261743456587, 29567.55648665315, 29777.404451910406, 29862.85068574643, 19457.805421946166, 20733.89633051825, 22568.097276108772, 23942.0, 24191.274261772174], "value": 18623.98031871646, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [416.508519946463, 428.9571042895711, 429.9226139294927, 435.6813329912427, 445.48323944224705, 20566.794332056674, 22107.894605269732, 22828.885855570697, 22888.542229155406, 22902.0, 22396.440088997784, 23192.30423087306, 23739.762602374016, 23869.880650596722, 24110.0, 6689.364510371532, 6806.0, 6931.480138989596, 7061.364477197051, 7072.504924655276, 6068.453839154476, 6844.212915514705, 6908.896366554506, 6960.234374218832, 6965.930340696605, 5851.210086638313, 6723.630200338979, 6917.446604271646, 6997.195322537897, 6999.685014174374, 6626.0, 6928.653567321639, 6934.47991400642, 6957.060796792444, 7002.859942801141, 6771.593704377753, 6820.556663816886, 6821.079154314154, 6845.0, 6868.381845633867, 26906.730932690618, 28049.579256310968, 28234.29414264639, 28245.01142460004, 28430.147095587166, 2943.278896670308, 3010.819350838957, 3015.306479509703, 3080.86136123875, 3084.3522860199387, 16505.752413713803, 16841.242144103544, 16960.0, 17230.396936107176, 17841.840280381864, 2133.6266153423244, 2145.8497905146646, 2205.6470964645578, 2254.627986382245, 2255.2783109404995, 157318.0, 158872.41127588807, 164695.0, 166282.16858915627, 168185.0, 12225.777422257703, 18955.620887582172, 22240.443988900082, 22607.660885086738, 22615.88692056529, 33126.67493300241, 33226.0, 33832.98501044972, 33909.49135762965, 33946.83026584901, 10934.617288394868, 11053.834192487118, 11096.667099987013, 11272.718182045515, 11429.914158154961, 9440.95279523598, 9445.386049906734, 9447.433154010781, 9637.084476974676, 9643.324967252225, 9724.367916085432, 9971.750706232257, 9993.050660187271, 10028.749281268027, 10033.749156271151, 3975.483187185705, 4072.1041370898356, 4102.743491079894, 4107.8973025674595, 4129.339305711072, 1023.3501726403787, 1038.2628333883029, 1066.9306495077794, 1072.0, 1077.8113830079706, 22527.0, 22798.202062927718, 22899.656505152103, 22910.65634015491, 23009.849507524556, 956.9521523923776, 1079.9146857408412, 1103.9227254092298, 1105.950232239551, 1120.1374941295157, 11089.27919685218, 11546.538138474367, 11595.826062609067, 11657.18399712029, 11745.060395168366, 779293.0, 779644.0, 782384.0, 789584.0, 802120.0, 123398.76601234052, 153151.0, 158149.0, 159784.0, 162160.0, 1205.5479195301762, 1218.2264262193394, 1240.8448943882015, 1253.2355263289387, 1255.6923553729212, 18126.456206313833, 18952.43142705721, 19507.85413604474, 19571.70642440336, 19606.90196549008, 36879.0, 54777.0, 59640.70179649074, 64131.35868641254, 64621.0, 54757.72621136869, 73399.63300183679, 73885.0, 73974.63012684902, 75304.24695752973, 11386.487608057823, 11789.76420471603, 12178.878211217774, 12243.265404075784, 12354.320512371547, 12016.759664806481, 13122.409491572711, 13467.326633668474, 13527.594172174851, 13795.724085518434, 11454.739978602514, 11531.077513799044, 11690.357030363411, 11760.823587646364, 11929.522819087138, 10610.575576976835, 10753.569857205937, 10764.51559679832, 10878.728031798952, 11100.944495277472, 11649.0, 11826.930287199877, 11913.463894124612, 11924.523019079139, 11955.820662690241, 11296.096312294832, 11953.820692689791, 12097.0, 12154.1492095556, 12415.193012454161, 11496.712582185512, 11718.88281117212, 11813.468393922467, 11913.761724765629, 12044.698882528008], "value": 11912.800809137165, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1572.0881888504668, 1624.9525597746836, 1628.0, 1641.0481920486116, 1645.1445248470795, 9750.41497510149, 10979.61571345002, 11588.82616760747, 11925.165238433303, 11947.34289614073, 11565.248258863168, 11665.358405287727, 11777.646670599874, 11793.351365674884, 11806.055515558755, 2418.2382549496942, 2455.7544245575477, 2467.568175569268, 2470.3330100872845, 2474.072222916406, 2398.4927177933046, 2414.2033129067368, 2464.346948058766, 2464.987675061622, 2470.542949554338, 2427.3810178404483, 2427.951341303582, 2454.4232105455203, 2472.938176545592, 2477.1949116537107, 2057.5473395852964, 2376.607859703147, 2414.9758502414925, 2429.0405289910473, 2432.4891772727747, 2386.3079706884982, 2413.263954493879, 2426.2842461473906, 2439.9634005489934, 2445.9143929962365, 10087.596496140146, 10862.565497380096, 11134.721631959184, 11351.26216795914, 11365.829512557319, 5864.208331875185, 5943.4056594340655, 6037.245344331958, 6052.364501727343, 6055.969720151371, 3574.946375804365, 3631.564212294515, 3646.4894914711963, 3659.8536058557625, 3681.7606855554304, 1225.6139316115396, 1253.5863165155477, 1276.0557187681143, 1281.0904257976847, 1304.8564657887625, 14425.134491930517, 14728.852711472855, 15058.32237549302, 15987.92006039974, 16154.596135096599, 3975.483187185705, 4590.632749380042, 4686.625069994392, 4698.107359601665, 4832.540908613676, 11990.760184796429, 12125.96929261005, 12134.939325303318, 12152.392380380945, 12157.574484892986, 5863.384344643836, 5903.911441328383, 5908.14331921869, 5927.347991720907, 5955.225820643375, 5244.947550524522, 5316.548093412103, 5320.467953204648, 5325.707086110224, 5366.758495867695, 3868.709846761504, 3955.940660890089, 3970.2059588082498, 3986.7807270600397, 4018.678505719535, 1039.8440233964814, 1040.843873418978, 1043.968680939573, 1050.401271275381, 1053.4153544782719, 959.3764053365229, 976.702105857706, 983.3657291047377, 1002.674130907458, 1016.425719468498, 6744.5616034957575, 6754.0, 6948.722051117898, 6969.756058538028, 7039.296070392919, 985.8816941966867, 990.1187942730975, 1020.5152552537634, 1036.6164519127972, 1066.6746642274027, 3618.782873027627, 3843.2889915365745, 4143.129942712064, 4204.558521355275, 4219.810108545123, 13291.268980205989, 14133.293335333397, 14253.0, 14280.143191408548, 14298.0, 13866.098703584235, 14236.85763142376, 14252.0, 14352.995290329576, 14484.420623175165, 9075.319351048698, 10573.259871809054, 10652.09457196147, 10711.83932241017, 10855.023047925566, 6308.27454842699, 6664.5334826561675, 6763.2898545652, 6948.062011628567, 6985.720571177096, 8640.697575584825, 11576.47905844256, 11733.120015998664, 11756.177067605186, 11939.403029848647, 12103.152779305716, 12289.508419663114, 12328.81506777417, 12488.0, 12517.74964500723, 8284.295834854105, 8353.122922093093, 8354.707585234732, 8443.788905277417, 8454.873176902229, 10164.33931794431, 10173.643922462677, 10176.084152426318, 10272.691819245434, 10282.331648442827, 7871.48835325702, 8600.483970961763, 8771.824563508822, 8870.822583548164, 8916.465012099035, 7236.529625574321, 8562.657493700432, 8569.442986205877, 8672.78318042054, 8708.78228044304, 7913.841723165389, 7971.083325417651, 8066.717664881936, 8078.596070196584, 8083.393745469231, 5788.652680839163, 5863.1791549184, 5869.476533038822, 5927.792527261525, 6027.487663548647, 5271.472852714851, 5908.0, 6008.819735407945, 6078.0, 6136.417040381157], "value": 5186.314555842571, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [305.70346763639276, 390.9824057917393, 399.66627865732113, 401.72052014334344, 407.6820080337337, 4816.54242846929, 4907.901841963158, 5551.9722401387935, 5636.491761166282, 5718.112353261602, 4762.1428142934255, 5150.3047088643025, 5309.991101690676, 5361.624686271963, 5455.481729235727, 4483.865484035484, 4498.920259137816, 4511.653939951223, 4534.410526631534, 4537.205988951937, 3989.660878825289, 4392.758398288093, 4414.978295907362, 4541.6837412919485, 4568.954310456887, 3651.4157734762443, 3706.518152640153, 4431.490378606453, 4439.8466743629315, 4450.221211288028, 3717.8698745543775, 4218.704690671654, 4459.910801783946, 4475.881029742564, 4484.484284307281, 3571.4642803579413, 3957.52509698838, 4112.712110152291, 4151.750894946313, 4160.1679664067005, 4309.504406993205, 4903.65674402792, 5212.940888349119, 5215.817446389358, 5247.265382846366, 4299.441072660534, 4707.6233901287815, 4838.975805120952, 4961.553460188564, 5027.798888044474, 5214.670259083947, 5470.699111548864, 5648.971755141198, 5804.332501762309, 5853.912191317134, 4081.2449696806184, 4574.9020235143325, 4619.5611416915335, 4649.721016738973, 4675.672702910798, 4975.731188546897, 5445.482679145475, 5746.109353050284, 6006.336134957673, 6008.497875531161, 4520.95479045212, 4534.0302102122505, 4690.663160999099, 4710.8351207707565, 4722.787474563653, 4320.891977700519, 4474.740256170613, 4488.842890498817, 4634.559716826896, 4838.7338696372035, 4732.958749075198, 4815.807367705253, 4866.489018653061, 4875.878103047452, 5197.324347834833, 5224.719943613839, 5366.363259205979, 5498.257735205776, 5801.0, 5882.617629854131, 1529.6940611877446, 1542.3136704166764, 1564.6870625874872, 1615.9192040397934, 1626.211287525549, 1070.593174593665, 1102.5755084292605, 1131.9207655464204, 1248.5942068827485, 1263.589333466627, 4918.458969513351, 5366.409694933554, 5398.838034858961, 5401.864953376118, 5402.675839449567, 5077.283653245496, 5422.53908417781, 5517.972410137923, 5882.264731904099, 5968.850778730566, 4781.737004464788, 5120.769565369567, 5171.3277273954145, 5398.352197736297, 5404.702741349185, 3001.564773107887, 3137.7803553751473, 3148.511980643004, 3152.4010438016703, 3193.744500439959, 5282.867928301746, 5655.840552686689, 5705.174344209896, 6238.189035425366, 6238.719257633417, 6028.125921741411, 6152.600080994721, 6161.4146656067605, 6258.655773932477, 6259.655718935411, 5060.84817455477, 5709.514691251207, 5929.644221346733, 6257.436830685169, 6276.46650034743, 5662.490375866027, 5931.436513531207, 6013.9699301503215, 6057.5159086024205, 6105.908411373922, 4775.976120119377, 5143.074246635641, 5477.863053423696, 5939.940600594112, 6043.637381757108, 5806.535477161732, 5971.611845230046, 6076.33160352352, 6125.540584456273, 6275.305667469849, 3776.4901738264984, 4619.396870441062, 4627.264775709109, 4756.310335001474, 4808.302796094476, 5660.235868157911, 5677.5457963362005, 5990.760369585168, 6058.242719660043, 6073.635581865102, 5593.776248949996, 5787.39232380594, 5826.621269617462, 5882.852928676817, 5883.822985495394, 4962.280469331999, 5582.8325150245555, 5816.767329306949, 5854.590178687451, 5880.529557635294, 5310.707911064928, 5343.438938911514, 5624.625224865048, 5683.630564013326, 5710.859027534756, 5644.096944488862, 5819.399665091964, 5835.591508594358, 5861.706914654335, 5868.0, 4791.952080479151, 5174.120399532013, 5560.972195138998, 5600.971995139998, 5752.820671762195], "value": 4408.252130967175, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [704.3308856586244, 757.6704133701838, 784.8979632647762, 815.5432957543773, 920.5397301349325, 15631.749460043198, 15770.684586308282, 16361.345546178138, 16719.331226750914, 16783.664326713475, 14105.435782568686, 15926.362945482197, 16347.59131021725, 16362.590935226624, 16401.42595009175, 961.8172547216042, 999.7800483893532, 1010.4745532323187, 1029.531563138774, 1034.5241189053006, 1016.6746641074859, 1018.9490525473734, 1019.0777346501428, 1021.4228960637216, 1022.3508072374021, 1060.6870973062926, 1085.039739830248, 1086.0876863434714, 1086.657702823612, 1087.9782404351909, 1060.2631171335925, 1079.7084787107515, 1084.2356138922041, 1084.5824357622294, 1086.538221255967, 792.480924994124, 797.5613412623034, 798.6446031515977, 799.8520273749375, 801.286854699318, 16758.58103547409, 16990.405335813186, 17251.65496690059, 18117.728234076498, 18233.7264941026, 14574.635634109127, 14586.0, 14639.268036598127, 14656.193909334983, 14712.999999999893, 11810.29138251699, 11990.760184796256, 12082.0, 12183.695407614881, 12201.145919785618, 3822.7132965027454, 3938.0, 3956.36698128301, 4006.6794656427414, 4043.9191216175514, 109208.63309352566, 118775.0, 119220.0, 121338.0, 121379.0, 9966.20270378368, 11003.17476189289, 11113.555457781598, 11548.133889958119, 12097.939510302393, 23865.164719234745, 24093.0, 24096.638550421758, 24103.03587856466, 24108.758912411, 8299.751007469786, 8302.584870756438, 8372.790680233044, 8377.478996612661, 8462.746117616482, 4503.076869241798, 4535.115652447741, 4577.679562430598, 4647.279671650899, 4662.0209755950955, 3593.9101522462147, 3622.7826330420257, 3674.2835147146584, 3692.9815350923072, 3703.055720791181, 665.1487885144957, 667.9031540426611, 673.515069150211, 675.321302091395, 681.6012632609978, 686.3308274432388, 687.6321168175102, 713.73591771044, 714.7284032067885, 744.385881647632, 10541.409490543141, 10741.087007604285, 10745.78508429827, 10909.890901090888, 11017.504212310465, 679.2290749998688, 707.1372925031557, 733.6075199768098, 738.896554482373, 739.5673530984434, 4322.333047031308, 4958.603311735053, 5149.433562308143, 5179.119549676564, 5212.061828870839, 103951.0, 107157.0, 107897.0, 109057.0, 109677.0, 59389.96046959779, 76621.61689191518, 87288.0, 88295.55852220826, 90286.0, 64676.67661661756, 70187.0, 73045.90431143537, 77882.61058694897, 78496.43007139825, 10681.412522311348, 10957.671269861918, 11136.276142050741, 11269.66191014271, 11337.829932550854, 47592.0, 48549.757251213516, 50277.99444011026, 51806.48193518168, 51859.74070129625, 59031.70484147552, 60194.194174174845, 64767.35232647613, 66261.66869165623, 66985.3301466979, 13172.275524846225, 13193.868061319265, 13244.867551324749, 13320.600381988555, 13671.79492307636, 14707.55877323682, 14757.92621036888, 14833.184174870486, 15061.623459413602, 15444.691106177588, 13974.0, 14193.219372934587, 14217.786733198804, 14286.714265714836, 14651.780223296446, 13029.348532573144, 13538.796918046433, 14157.716845662822, 14290.142591444546, 14689.92655036718, 13413.530526431918, 14262.857371426153, 14509.709805804036, 14591.658372575459, 14683.706325873209, 10166.644167454104, 10403.479826008821, 10570.312929659547, 10578.047975682226, 10693.465326733787, 9325.533723313943, 10439.410412602068, 10458.68623941283, 11291.92726690996, 11533.365664888512], "value": 7962.528130871803, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1558.3, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [108.0, 120.0, 120.0, 121.0, 119.0, 119.0, 121.0, 139.0, 172.0, 153.0], "value": 129.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 146.0, 96.0, 135.0, 133.0, 140.0, 146.0, 122.0, 128.0, 110.0], "value": 130.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [209.0, 247.0, 196.0, 201.0, 200.0, 197.0, 198.0, 199.0, 200.0, 197.0], "value": 204.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [119.0, 82.0, 84.0, 110.0, 61.0, 58.0, 60.0, 59.0, 72.0, 58.0], "value": 76.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 69.0, 81.0, 92.0, 102.0, 98.0, 109.0, 74.0, 71.0, 82.0], "value": 86.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 90.0, 90.0, 90.0, 90.0, 110.0, 92.0, 90.0, 93.0, 130.0], "value": 97.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [103.0, 153.0, 111.0, 98.0, 97.0, 104.0, 117.0, 98.0, 97.0, 98.0], "value": 107.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 112.0, 92.0, 139.0, 144.0, 91.0, 92.0, 91.0, 92.0, 90.0], "value": 103.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 66.0, 66.0, 65.0, 67.0, 67.0, 75.0, 98.0, 102.0, 102.0], "value": 77.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 64.0, 62.0, 72.0, 66.0, 59.0, 59.0, 58.0, 57.0, 57.0], "value": 61.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 81.0, 83.0, 82.0, 86.0, 83.0, 94.0, 96.0, 83.0, 83.0], "value": 85.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [138.0, 143.0, 142.0, 137.0, 134.0, 167.0, 157.0, 154.0, 202.0, 176.0], "value": 155.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [168.0, 167.0, 167.0, 218.0, 167.0, 176.0, 214.0, 163.0, 164.0, 169.0], "value": 177.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 65.0, 65.0, 66.0, 62.0, 65.0, 65.0, 61.0, 76.0, 80.0], "value": 67.0, "unit": "score"}]}]} 06:41:47 INFO - Return code: 0 06:41:47 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 06:41:47 ERROR - # TBPL SUCCESS # 06:41:47 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 06:41:47 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 06:41:47 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 06:41:47 INFO - 2017-05-25 06:41:47.744 screenresolution[6666:126004] starting screenresolution argv=screenresolution get 06:41:47 INFO - 2017-05-25 06:41:47.746 screenresolution[6666:126004] Display 0: 1600x1200x32@60 06:41:47 INFO - 2017-05-25 06:41:47.752 screenresolution[6667:126007] starting screenresolution argv=screenresolution list 06:41:47 INFO - Available Modes on Display 0 06:41:47 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 06:41:47 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 06:41:47 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 06:41:47 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 06:41:47 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 06:41:47 INFO - Intel Iris: 06:41:47 INFO - Chipset Model: Intel Iris 06:41:47 INFO - Type: GPU 06:41:47 INFO - Bus: Built-In 06:41:47 INFO - VRAM (Dynamic, Max): 1536 MB 06:41:47 INFO - Vendor: Intel (0x8086) 06:41:47 INFO - Device ID: 0x0a2e 06:41:47 INFO - Revision ID: 0x0009 06:41:47 INFO - Displays: 06:41:47 INFO - Display: 06:41:47 INFO - Resolution: 1600 x 1200 @ 60 Hz 06:41:47 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 06:41:47 INFO - Display Serial Number: 1600x1200 60 06:41:47 INFO - Main Display: Yes 06:41:47 INFO - Mirror: Off 06:41:47 INFO - Online: Yes 06:41:47 INFO - Rotation: Supported 06:41:47 INFO - Return code: 0 06:41:47 INFO - Running post-action listener: _resource_record_post_action 06:41:47 INFO - [mozharness: 2017-05-25 13:41:47.990506Z] Finished run-tests step (success) 06:41:47 INFO - Running post-run listener: _resource_record_post_run 06:41:48 INFO - Total resource usage - Wall time: 1192s; CPU: 55.0%; Read bytes: 2018304; Write bytes: 425659392; Read time: 24; Write time: 1442 06:41:48 INFO - TinderboxPrint: CPU usage
54.6% 06:41:48 INFO - TinderboxPrint: I/O read bytes / time
2,018,304 / 24 06:41:48 INFO - TinderboxPrint: I/O write bytes / time
425,659,392 / 1,442 06:41:48 INFO - TinderboxPrint: CPU idle
2,165.0 (45.4%) 06:41:48 INFO - TinderboxPrint: CPU system
963.4 (20.2%) 06:41:48 INFO - TinderboxPrint: CPU user
1,641.3 (34.4%) 06:41:48 INFO - TinderboxPrint: Swap in / out
125,468,672 / 0 06:41:48 INFO - install - Wall time: 15s; CPU: 54.0%; Read bytes: 119538176; Write bytes: 113127424; Read time: 10833; Write time: 246 06:41:48 INFO - run-tests - Wall time: 1178s; CPU: 55.0%; Read bytes: 2018304; Write bytes: 301538304; Read time: 24; Write time: 1157 06:41:48 INFO - Running post-run listener: _upload_blobber_files 06:41:48 INFO - Blob upload gear active. 06:41:48 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 06:41:48 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 06:41:48 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'] 06:41:48 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 06:41:48 INFO - (blobuploader) - INFO - Open directory for files ... 06:41:48 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 06:41:48 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:41:48 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:41:51 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 06:41:51 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:41:51 INFO - (blobuploader) - INFO - Done attempting. 06:41:51 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 06:41:51 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:41:51 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:41:52 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 06:41:52 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:41:52 INFO - (blobuploader) - INFO - Done attempting. 06:41:52 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 06:41:52 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:41:52 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:41:53 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 06:41:53 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:41:53 INFO - (blobuploader) - INFO - Done attempting. 06:41:53 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 06:41:53 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:41:53 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:41:55 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 06:41:55 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:41:55 INFO - (blobuploader) - INFO - Done attempting. 06:41:55 INFO - (blobuploader) - INFO - Iteration through files over. 06:41:55 INFO - Return code: 0 06:41:55 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 06:41:55 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 06:41:55 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/29c35fc4bf6fd1ae9263aec4ee872ab63e7154173f35aef335070e721d7b479bcf3526c057374d81584a72bf7b6248f0ed6122e8b6723546aed3e941e8f7d944", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/4bce56498e7d52f03f99866a2c991f2fb7bbef463cc7af0a95d1b231cc4dfa0e06db0873710e5ef396cc05963cd261f87f625e26b520396f4c722f0c2a340e81", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/c0ecf402080ee01462330899e1164462e5133ab55915a9878c55850c7ee614141a7d05bfd0b413c139225419267b998ee4e91ef4aa24132bd8e6db60c8c6e831", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/b0e8d027183621b5c565b463ee1d95597e379e2430af60dd48b2818cef6b4b0f79e7f80e0ec1dfa78e3f13d5f85bcea3a63c34b5114d53c0f143d857f03c3783"} 06:41:55 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 06:41:55 INFO - Writing to file /builds/slave/test/properties/blobber_files 06:41:55 INFO - Contents: 06:41:55 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/29c35fc4bf6fd1ae9263aec4ee872ab63e7154173f35aef335070e721d7b479bcf3526c057374d81584a72bf7b6248f0ed6122e8b6723546aed3e941e8f7d944", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/4bce56498e7d52f03f99866a2c991f2fb7bbef463cc7af0a95d1b231cc4dfa0e06db0873710e5ef396cc05963cd261f87f625e26b520396f4c722f0c2a340e81", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/c0ecf402080ee01462330899e1164462e5133ab55915a9878c55850c7ee614141a7d05bfd0b413c139225419267b998ee4e91ef4aa24132bd8e6db60c8c6e831", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/b0e8d027183621b5c565b463ee1d95597e379e2430af60dd48b2818cef6b4b0f79e7f80e0ec1dfa78e3f13d5f85bcea3a63c34b5114d53c0f143d857f03c3783"} 06:41:55 INFO - Running post-run listener: copy_logs_to_upload_dir 06:41:55 INFO - Copying logs to upload dir... 06:41:55 INFO - mkdir: /builds/slave/test/build/upload/logs 06:41:55 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1269.119730 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 9 secs) (at 2017-05-25 06:41:55.268339) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-25 06:41:55.271857) ========= 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.KDVfahnlTt/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.i2dogWExfy/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-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/29c35fc4bf6fd1ae9263aec4ee872ab63e7154173f35aef335070e721d7b479bcf3526c057374d81584a72bf7b6248f0ed6122e8b6723546aed3e941e8f7d944", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/4bce56498e7d52f03f99866a2c991f2fb7bbef463cc7af0a95d1b231cc4dfa0e06db0873710e5ef396cc05963cd261f87f625e26b520396f4c722f0c2a340e81", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/c0ecf402080ee01462330899e1164462e5133ab55915a9878c55850c7ee614141a7d05bfd0b413c139225419267b998ee4e91ef4aa24132bd8e6db60c8c6e831", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/b0e8d027183621b5c565b463ee1d95597e379e2430af60dd48b2818cef6b4b0f79e7f80e0ec1dfa78e3f13d5f85bcea3a63c34b5114d53c0f143d857f03c3783"} build_url:https://queue.taskcluster.net/v1/task/SS9fEveGTiCjigUMTVCNEg/artifacts/public/build/firefox-54.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011381 build_url: 'https://queue.taskcluster.net/v1/task/SS9fEveGTiCjigUMTVCNEg/artifacts/public/build/firefox-54.0.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/29c35fc4bf6fd1ae9263aec4ee872ab63e7154173f35aef335070e721d7b479bcf3526c057374d81584a72bf7b6248f0ed6122e8b6723546aed3e941e8f7d944", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/4bce56498e7d52f03f99866a2c991f2fb7bbef463cc7af0a95d1b231cc4dfa0e06db0873710e5ef396cc05963cd261f87f625e26b520396f4c722f0c2a340e81", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/c0ecf402080ee01462330899e1164462e5133ab55915a9878c55850c7ee614141a7d05bfd0b413c139225419267b998ee4e91ef4aa24132bd8e6db60c8c6e831", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/b0e8d027183621b5c565b463ee1d95597e379e2430af60dd48b2818cef6b4b0f79e7f80e0ec1dfa78e3f13d5f85bcea3a63c34b5114d53c0f143d857f03c3783"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-25 06:41:55.305824) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-25 06:41:55.306152) ========= 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.KDVfahnlTt/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.i2dogWExfy/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.005620 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-25 06:41:55.355327) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-25 06:41:55.355674) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-25 06:41:55.356001) ========= ========= Total master_lag: 0.15 =========