builder: mozilla-beta_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0013 starttime: 1496348771.31 results: success (0) buildid: 20170601121724 builduid: 2a453b7e0cd543c0b556458cb248305f revision: a85ff8f8399de4eea50c9f3aece921814c98d016 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-06-01 13:26:11.313689) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-06-01 13:26:11.314404) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-01 13:26:11.314698) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-01 13:26:11.443349) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-01 13:26:11.443620) ========= 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.3nh4ao2mdF/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.5Byrb5EkGe/Listeners TMPDIR=/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/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-06-01 13:26:11-- 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% 9.65M=0.001s 2017-06-01 13:26:11 (9.65 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.444459 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-01 13:26:11.914294) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-01 13:26:11.914632) ========= 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.3nh4ao2mdF/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.5Byrb5EkGe/Listeners TMPDIR=/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/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.029595 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-01 13:26:11.968559) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-01 13:26:11.968876) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev a85ff8f8399de4eea50c9f3aece921814c98d016 --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 a85ff8f8399de4eea50c9f3aece921814c98d016 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.3nh4ao2mdF/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.5Byrb5EkGe/Listeners TMPDIR=/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/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-06-01 13:26:12,062 truncating revision to first 12 chars 2017-06-01 13:26:12,063 Setting DEBUG logging. 2017-06-01 13:26:12,063 attempt 1/10 2017-06-01 13:26:12,063 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/a85ff8f8399d?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-06-01 13:26:12,704 unpacking tar archive at: mozilla-beta-a85ff8f8399d/testing/mozharness/ program finished with exit code 0 elapsedTime=0.927729 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-01 13:26:12.920836) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-01 13:26:12.921347) ========= script_repo_revision: a85ff8f8399de4eea50c9f3aece921814c98d016 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-01 13:26:12.921933) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-01 13:26:12.922373) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-01 13:26:12.942098) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 9 secs) (at 2017-06-01 13:26:12.942526) ========= /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.3nh4ao2mdF/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.5Byrb5EkGe/Listeners TMPDIR=/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/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 13:26:13 INFO - MultiFileLogger online at 20170601 13:26:13 in /builds/slave/test 13:26:13 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 13:26:13 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 13:26:13 INFO - {'append_to_log': False, 13:26:13 INFO - 'base_work_dir': '/builds/slave/test', 13:26:13 INFO - 'blob_upload_branch': 'Mozilla-Beta', 13:26:13 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 13:26:13 INFO - 'branch': 'Mozilla-Beta', 13:26:13 INFO - 'buildbot_json_path': 'buildprops.json', 13:26:13 INFO - 'config_files': ('talos/mac_config.py',), 13:26:13 INFO - 'default_actions': ('clobber', 13:26:13 INFO - 'read-buildbot-config', 13:26:13 INFO - 'download-and-extract', 13:26:13 INFO - 'populate-webroot', 13:26:13 INFO - 'create-virtualenv', 13:26:13 INFO - 'install', 13:26:13 INFO - 'run-tests'), 13:26:13 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 13:26:13 INFO - 'download_minidump_stackwalk': True, 13:26:13 INFO - 'download_symbols': 'ondemand', 13:26:13 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 13:26:13 INFO - 'tooltool.py': '/tools/tooltool.py', 13:26:13 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 13:26:13 INFO - '/tools/misc-python/virtualenv.py')}, 13:26:13 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 13:26:13 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 13:26:13 INFO - 'gecko_profile': False, 13:26:13 INFO - 'gecko_profile_interval': 0, 13:26:13 INFO - 'installer_path': 'installer.exe', 13:26:13 INFO - 'log_level': 'info', 13:26:13 INFO - 'log_name': 'talos', 13:26:13 INFO - 'log_to_console': True, 13:26:13 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 13:26:13 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 13:26:13 INFO - 'opt_config_files': (), 13:26:13 INFO - 'pip_index': False, 13:26:13 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 13:26:13 INFO - 'cmd': ('bash', 13:26:13 INFO - '-c', 13:26:13 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 13:26:13 INFO - 'enabled': True, 13:26:13 INFO - 'halt_on_failure': False, 13:26:13 INFO - 'name': 'check_screen_resolution'},), 13:26:13 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 13:26:13 INFO - 'cmd': ('bash', 13:26:13 INFO - '-c', 13:26:13 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 13:26:13 INFO - 'enabled': True, 13:26:13 INFO - 'halt_on_failure': False, 13:26:13 INFO - 'name': 'check_screen_resolution'},), 13:26:13 INFO - 'run_cmd_checks_enabled': True, 13:26:13 INFO - 'suite': 'dromaeojs-e10s', 13:26:13 INFO - 'system_bits': '32', 13:26:13 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 13:26:13 INFO - 'title': 't-yosemite-r7-0013', 13:26:13 INFO - 'tooltool_cache': '/builds/tooltool_cache', 13:26:13 INFO - 'use_talos_json': True, 13:26:13 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 13:26:13 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 13:26:13 INFO - 'work_dir': 'build'} 13:26:13 INFO - [mozharness: 2017-06-01 20:26:13.154238Z] Running clobber step. 13:26:13 INFO - Running pre-action listener: _resource_record_pre_action 13:26:13 INFO - Running main action method: clobber 13:26:13 INFO - rmtree: /builds/slave/test/build 13:26:13 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 13:26:15 INFO - Running post-action listener: _resource_record_post_action 13:26:15 INFO - [mozharness: 2017-06-01 20:26:15.721638Z] Finished clobber step (success) 13:26:15 INFO - [mozharness: 2017-06-01 20:26:15.721800Z] Running read-buildbot-config step. 13:26:15 INFO - Running pre-action listener: _resource_record_pre_action 13:26:15 INFO - Running main action method: read_buildbot_config 13:26:15 INFO - Using buildbot properties: 13:26:15 INFO - { 13:26:15 INFO - "project": "", 13:26:15 INFO - "product": "firefox", 13:26:15 INFO - "script_repo_revision": "production", 13:26:15 INFO - "builddir": "mozilla-beta_yosemite_r7_test-dromaeojs-e10s", 13:26:15 INFO - "repository": "", 13:26:15 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-beta talos dromaeojs-e10s", 13:26:15 INFO - "buildid": "20170601121724", 13:26:15 INFO - "pgo_build": "False", 13:26:15 INFO - "basedir": "/builds/slave/test", 13:26:15 INFO - "buildnumber": 4, 13:26:15 INFO - "slavename": "t-yosemite-r7-0013", 13:26:15 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 13:26:15 INFO - "platform": "yosemite_r7", 13:26:15 INFO - "scheduler": "tests-mozilla-beta-macosx64-talos", 13:26:15 INFO - "branch": "mozilla-beta", 13:26:15 INFO - "repo_path": "releases/mozilla-beta", 13:26:15 INFO - "revision": "a85ff8f8399de4eea50c9f3aece921814c98d016", 13:26:15 INFO - "stage_platform": "macosx64", 13:26:15 INFO - "builduid": "2a453b7e0cd543c0b556458cb248305f", 13:26:15 INFO - "slavebuilddir": "test" 13:26:15 INFO - } 13:26:15 INFO - Found installer url https://queue.taskcluster.net/v1/task/eCXaZQS8TLSkGySN1Pr5nQ/artifacts/public/build/firefox-54.0.en-US.mac.dmg. 13:26:15 INFO - Running post-action listener: _resource_record_post_action 13:26:15 INFO - [mozharness: 2017-06-01 20:26:15.725302Z] Finished read-buildbot-config step (success) 13:26:15 INFO - [mozharness: 2017-06-01 20:26:15.725463Z] Running download-and-extract step. 13:26:15 INFO - Running pre-action listener: _resource_record_pre_action 13:26:15 INFO - Running main action method: download_and_extract 13:26:15 INFO - mkdir: /builds/slave/test/build/tests 13:26:15 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')]} 13:26:15 INFO - trying https://queue.taskcluster.net/v1/task/eCXaZQS8TLSkGySN1Pr5nQ/artifacts/public/build/firefox-54.0.en-US.mac.test_packages.json 13:26:15 INFO - Downloading https://queue.taskcluster.net/v1/task/eCXaZQS8TLSkGySN1Pr5nQ/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 13:26:15 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/eCXaZQS8TLSkGySN1Pr5nQ/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 13:26:16 INFO - Downloaded 1287 bytes. 13:26:16 INFO - Reading from file /builds/slave/test/build/firefox-54.0.en-US.mac.test_packages.json 13:26:16 INFO - Using the following test package requirements: 13:26:16 INFO - {u'awsy': [u'firefox-54.0.en-US.mac.common.tests.zip', 13:26:16 INFO - u'firefox-54.0.en-US.mac.awsy.tests.zip'], 13:26:16 INFO - u'common': [u'firefox-54.0.en-US.mac.common.tests.zip'], 13:26:16 INFO - u'cppunittest': [u'firefox-54.0.en-US.mac.common.tests.zip', 13:26:16 INFO - u'firefox-54.0.en-US.mac.cppunittest.tests.zip'], 13:26:16 INFO - u'gtest': [u'firefox-54.0.en-US.mac.common.tests.zip', 13:26:16 INFO - u'firefox-54.0.en-US.mac.gtest.tests.zip'], 13:26:16 INFO - u'jittest': [u'firefox-54.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 13:26:16 INFO - u'mochitest': [u'firefox-54.0.en-US.mac.common.tests.zip', 13:26:16 INFO - u'firefox-54.0.en-US.mac.mochitest.tests.zip'], 13:26:16 INFO - u'mozbase': [u'firefox-54.0.en-US.mac.common.tests.zip'], 13:26:16 INFO - u'reftest': [u'firefox-54.0.en-US.mac.common.tests.zip', 13:26:16 INFO - u'firefox-54.0.en-US.mac.reftest.tests.zip'], 13:26:16 INFO - u'talos': [u'firefox-54.0.en-US.mac.common.tests.zip', 13:26:16 INFO - u'firefox-54.0.en-US.mac.talos.tests.zip'], 13:26:16 INFO - u'web-platform': [u'firefox-54.0.en-US.mac.common.tests.zip', 13:26:16 INFO - u'firefox-54.0.en-US.mac.web-platform.tests.zip'], 13:26:16 INFO - u'xpcshell': [u'firefox-54.0.en-US.mac.common.tests.zip', 13:26:16 INFO - u'firefox-54.0.en-US.mac.xpcshell.tests.zip']} 13:26:16 INFO - Downloading packages: [u'firefox-54.0.en-US.mac.common.tests.zip'] for test suite category: common 13:26:16 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/eCXaZQS8TLSkGySN1Pr5nQ/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip 13:26:16 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/eCXaZQS8TLSkGySN1Pr5nQ/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip'}, attempt #1 13:26:16 INFO - Fetch https://queue.taskcluster.net/v1/task/eCXaZQS8TLSkGySN1Pr5nQ/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip into memory 13:26:20 INFO - Expected file size: 51393581 13:26:20 INFO - Obtained file size: 51393581 13:26:36 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 13:26:36 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/eCXaZQS8TLSkGySN1Pr5nQ/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip 13:26:36 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/eCXaZQS8TLSkGySN1Pr5nQ/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip'}, attempt #1 13:26:36 INFO - Fetch https://queue.taskcluster.net/v1/task/eCXaZQS8TLSkGySN1Pr5nQ/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip into memory 13:26:38 INFO - Expected file size: 51393581 13:26:38 INFO - Obtained file size: 51393581 13:26:53 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/eCXaZQS8TLSkGySN1Pr5nQ/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip 13:26:53 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/eCXaZQS8TLSkGySN1Pr5nQ/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip'}, attempt #1 13:26:53 INFO - Fetch https://queue.taskcluster.net/v1/task/eCXaZQS8TLSkGySN1Pr5nQ/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip into memory 13:26:54 INFO - Expected file size: 14404461 13:26:54 INFO - Obtained file size: 14404461 13:26:55 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')]} 13:26:55 INFO - trying https://queue.taskcluster.net/v1/task/eCXaZQS8TLSkGySN1Pr5nQ/artifacts/public/build/firefox-54.0.en-US.mac.dmg 13:26:55 INFO - Downloading https://queue.taskcluster.net/v1/task/eCXaZQS8TLSkGySN1Pr5nQ/artifacts/public/build/firefox-54.0.en-US.mac.dmg to /builds/slave/test/build/firefox-54.0.en-US.mac.dmg 13:26:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/eCXaZQS8TLSkGySN1Pr5nQ/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 13:26:57 INFO - Downloaded 59647340 bytes. 13:26:57 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/eCXaZQS8TLSkGySN1Pr5nQ/artifacts/public/build/firefox-54.0.en-US.mac.dmg 13:26:57 INFO - mkdir: /builds/slave/test/properties 13:26:57 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 13:26:57 INFO - Writing to file /builds/slave/test/properties/build_url 13:26:57 INFO - Contents: 13:26:57 INFO - build_url:https://queue.taskcluster.net/v1/task/eCXaZQS8TLSkGySN1Pr5nQ/artifacts/public/build/firefox-54.0.en-US.mac.dmg 13:26:57 INFO - Running post-action listener: _resource_record_post_action 13:26:57 INFO - Running post-action listener: set_extra_try_arguments 13:26:57 INFO - [mozharness: 2017-06-01 20:26:57.650489Z] Finished download-and-extract step (success) 13:26:57 INFO - [mozharness: 2017-06-01 20:26:57.650623Z] Running populate-webroot step. 13:26:57 INFO - Running pre-action listener: _resource_record_pre_action 13:26:57 INFO - Running main action method: populate_webroot 13:26:57 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 13:26:57 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 13:26:57 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 13:26:57 INFO - '--tppagecycles', 13:26:57 INFO - '7'], 13:26:57 INFO - 'tests': ['tsvgm']}}, 13:26:57 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 13:26:57 INFO - 'tests': ['tresize', 'tcanvasmark']}, 13:26:57 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 13:26:57 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 13:26:57 INFO - 'tests': ['dromaeo_css', 'kraken']}, 13:26:57 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 13:26:57 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 13:26:57 INFO - 'talos_options': ['--disable-e10s'], 13:26:57 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 13:26:57 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 13:26:57 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 13:26:57 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 13:26:57 INFO - 'talos_options': ['--disable-e10s'], 13:26:57 INFO - 'tests': ['damp', 'tps']}, 13:26:57 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 13:26:57 INFO - 'tests': ['damp', 'tps']}, 13:26:57 INFO - 'g3': {'talos_options': ['--disable-e10s'], 13:26:57 INFO - 'tests': ['dromaeo_dom']}, 13:26:57 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 13:26:57 INFO - 'g4': {'talos_options': ['--disable-e10s'], 13:26:57 INFO - 'tests': ['basic_compositor_video']}, 13:26:57 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 13:26:57 INFO - 'other': {'talos_options': ['--disable-e10s'], 13:26:57 INFO - 'tests': ['a11yr', 13:26:57 INFO - 'ts_paint', 13:26:57 INFO - 'tpaint', 13:26:57 INFO - 'sessionrestore', 13:26:57 INFO - 'sessionrestore_no_auto_restore', 13:26:57 INFO - 'tabpaint']}, 13:26:57 INFO - 'other-e10s': {'tests': ['a11yr', 13:26:57 INFO - 'ts_paint', 13:26:57 INFO - 'tpaint', 13:26:57 INFO - 'sessionrestore', 13:26:57 INFO - 'sessionrestore_no_auto_restore', 13:26:57 INFO - 'tabpaint']}, 13:26:57 INFO - 'other-e10s_l64': {'tests': ['a11yr', 13:26:57 INFO - 'ts_paint', 13:26:57 INFO - 'tpaint', 13:26:57 INFO - 'sessionrestore', 13:26:57 INFO - 'sessionrestore_no_auto_restore', 13:26:57 INFO - 'tabpaint']}, 13:26:57 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 13:26:57 INFO - 'ts_paint', 13:26:57 INFO - 'tpaint', 13:26:57 INFO - 'sessionrestore', 13:26:57 INFO - 'sessionrestore_no_auto_restore', 13:26:57 INFO - 'tabpaint']}, 13:26:57 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 13:26:57 INFO - 'tests': ['a11yr', 13:26:57 INFO - 'ts_paint', 13:26:57 INFO - 'tpaint', 13:26:57 INFO - 'sessionrestore', 13:26:57 INFO - 'sessionrestore_no_auto_restore', 13:26:57 INFO - 'tabpaint']}, 13:26:57 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 13:26:57 INFO - 'tests': ['a11yr', 13:26:57 INFO - 'ts_paint', 13:26:57 INFO - 'tpaint', 13:26:57 INFO - 'sessionrestore', 13:26:57 INFO - 'sessionrestore_no_auto_restore', 13:26:57 INFO - 'tabpaint']}, 13:26:57 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 13:26:57 INFO - 'tests': ['tsvgx', 13:26:57 INFO - 'tsvgr_opacity', 13:26:57 INFO - 'tart', 13:26:57 INFO - 'tscrollx', 13:26:57 INFO - 'cart', 13:26:57 INFO - 'tsvg_static']}, 13:26:57 INFO - 'svgr-e10s': {'tests': ['tsvgx', 13:26:57 INFO - 'tsvgr_opacity', 13:26:57 INFO - 'tart', 13:26:57 INFO - 'tscrollx', 13:26:57 INFO - 'cart', 13:26:57 INFO - 'tsvg_static']}, 13:26:57 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 13:26:57 INFO - 'talos_options': ['--disable-e10s'], 13:26:57 INFO - 'tests': ['tp5o']}, 13:26:57 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 13:26:57 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 13:26:57 INFO - 'talos_options': ['--disable-e10s', 13:26:57 INFO - '--xperf_path', 13:26:57 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 13:26:57 INFO - 'tests': ['tp5n']}, 13:26:57 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 13:26:57 INFO - 'talos_options': ['--xperf_path', 13:26:57 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 13:26:57 INFO - 'tests': ['tp5n']}}, 13:26:57 INFO - 'talos.zip': {'path': '', 13:26:57 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 13:26:57 INFO - Running post-action listener: _resource_record_post_action 13:26:57 INFO - [mozharness: 2017-06-01 20:26:57.670814Z] Finished populate-webroot step (success) 13:26:57 INFO - [mozharness: 2017-06-01 20:26:57.670945Z] Running create-virtualenv step. 13:26:57 INFO - Running pre-action listener: _resource_record_pre_action 13:26:57 INFO - Running main action method: create_virtualenv 13:26:57 INFO - Creating virtualenv /builds/slave/test/build/venv 13:26:57 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 13:26:57 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 13:26:57 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 13:26:57 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 13:26:57 INFO - Using real prefix '/tools/python27' 13:26:57 INFO - New python executable in /builds/slave/test/build/venv/bin/python 13:26:58 INFO - Installing distribute.............................................................................................................................................................................................done. 13:27:01 INFO - Installing pip.................done. 13:27:01 INFO - Return code: 0 13:27:01 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 13:27:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 13:27:01 INFO - Reading from file tmpfile_stdout 13:27:01 INFO - Output received: 13:27:01 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) 13:27:01 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 13:27:01 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')]} 13:27:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:27:01 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')]} 13:27:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:27:01 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 0x106748b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10674b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa1424d96c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106526d30>, '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 0x10644ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10651bbe8>, '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.3nh4ao2mdF/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.5Byrb5EkGe/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/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 13:27:01 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 13:27:01 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 13:27:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3nh4ao2mdF/Render', 13:27:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:27:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:27:01 INFO - 'HOME': '/Users/cltbld', 13:27:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:27:01 INFO - 'LOGNAME': 'cltbld', 13:27:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:27:01 INFO - 'MOZ_NO_REMOTE': '1', 13:27:01 INFO - 'NO_EM_RESTART': '1', 13:27:01 INFO - 'PAGER': '/bin/cat', 13:27:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:27:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:27:01 INFO - 'PWD': '/builds/slave/test', 13:27:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:27:01 INFO - 'SHELL': '/bin/bash', 13:27:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5Byrb5EkGe/Listeners', 13:27:01 INFO - 'TMPDIR': '/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/T/', 13:27:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:27:01 INFO - 'USER': 'cltbld', 13:27:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:27:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:27:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:27:01 INFO - 'XPC_FLAGS': '0x0', 13:27:01 INFO - 'XPC_SERVICE_NAME': '0', 13:27:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:27:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:27:01 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 13:27:01 INFO - Cleaning up... 13:27:01 INFO - Return code: 0 13:27:01 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 13:27:01 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')]} 13:27:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:27:01 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')]} 13:27:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:27:01 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 0x106748b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10674b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa1424d96c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106526d30>, '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 0x10644ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10651bbe8>, '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.3nh4ao2mdF/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.5Byrb5EkGe/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/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 13:27:01 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 13:27:01 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 13:27:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3nh4ao2mdF/Render', 13:27:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:27:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:27:01 INFO - 'HOME': '/Users/cltbld', 13:27:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:27:01 INFO - 'LOGNAME': 'cltbld', 13:27:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:27:01 INFO - 'MOZ_NO_REMOTE': '1', 13:27:01 INFO - 'NO_EM_RESTART': '1', 13:27:01 INFO - 'PAGER': '/bin/cat', 13:27:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:27:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:27:01 INFO - 'PWD': '/builds/slave/test', 13:27:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:27:01 INFO - 'SHELL': '/bin/bash', 13:27:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5Byrb5EkGe/Listeners', 13:27:01 INFO - 'TMPDIR': '/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/T/', 13:27:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:27:01 INFO - 'USER': 'cltbld', 13:27:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:27:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:27:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:27:01 INFO - 'XPC_FLAGS': '0x0', 13:27:01 INFO - 'XPC_SERVICE_NAME': '0', 13:27:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:27:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:27:02 INFO - Downloading/unpacking psutil>=3.1.1 13:27:02 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 13:27:02 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 13:27:02 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 13:27:02 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 13:27:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 13:27:04 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 13:27:04 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 13:27:04 INFO - Installing collected packages: psutil 13:27:04 INFO - Running setup.py install for psutil 13:27:04 INFO - building 'psutil._psutil_osx' extension 13:27:04 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 13:27:05 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 13:27:05 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 13:27:05 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 13:27:05 INFO - building 'psutil._psutil_posix' extension 13:27:05 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 13:27:05 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 13:27:05 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 13:27:05 INFO - ^ 13:27:05 INFO - 1 warning generated. 13:27:05 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 13:27:05 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 13:27:05 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 13:27:05 INFO - Successfully installed psutil 13:27:05 INFO - Cleaning up... 13:27:05 INFO - Return code: 0 13:27:05 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 13:27:05 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')]} 13:27:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:27:05 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')]} 13:27:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:27:05 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 0x106748b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10674b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa1424d96c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106526d30>, '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 0x10644ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10651bbe8>, '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.3nh4ao2mdF/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.5Byrb5EkGe/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/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 13:27:05 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 13:27:05 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 13:27:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3nh4ao2mdF/Render', 13:27:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:27:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:27:05 INFO - 'HOME': '/Users/cltbld', 13:27:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:27:05 INFO - 'LOGNAME': 'cltbld', 13:27:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:27:05 INFO - 'MOZ_NO_REMOTE': '1', 13:27:05 INFO - 'NO_EM_RESTART': '1', 13:27:05 INFO - 'PAGER': '/bin/cat', 13:27:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:27:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:27:05 INFO - 'PWD': '/builds/slave/test', 13:27:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:27:05 INFO - 'SHELL': '/bin/bash', 13:27:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5Byrb5EkGe/Listeners', 13:27:05 INFO - 'TMPDIR': '/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/T/', 13:27:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:27:05 INFO - 'USER': 'cltbld', 13:27:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:27:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:27:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:27:05 INFO - 'XPC_FLAGS': '0x0', 13:27:05 INFO - 'XPC_SERVICE_NAME': '0', 13:27:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:27:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:27:05 INFO - Downloading/unpacking mozsystemmonitor==0.3 13:27:05 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 13:27:05 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 13:27:05 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 13:27:05 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 13:27:07 INFO - Downloading mozsystemmonitor-0.3.tar.gz 13:27:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 13:27:07 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 13:27:07 INFO - Installing collected packages: mozsystemmonitor 13:27:07 INFO - Running setup.py install for mozsystemmonitor 13:27:07 INFO - Successfully installed mozsystemmonitor 13:27:07 INFO - Cleaning up... 13:27:07 INFO - Return code: 0 13:27:07 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 13:27:07 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')]} 13:27:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:27:07 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')]} 13:27:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:27:07 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/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 0x106748b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10674b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa1424d96c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106526d30>, '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 0x10644ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10651bbe8>, '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.3nh4ao2mdF/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.5Byrb5EkGe/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/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 13:27:07 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 13:27:07 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 13:27:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3nh4ao2mdF/Render', 13:27:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:27:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:27:07 INFO - 'HOME': '/Users/cltbld', 13:27:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:27:07 INFO - 'LOGNAME': 'cltbld', 13:27:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:27:07 INFO - 'MOZ_NO_REMOTE': '1', 13:27:07 INFO - 'NO_EM_RESTART': '1', 13:27:07 INFO - 'PAGER': '/bin/cat', 13:27:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:27:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:27:07 INFO - 'PWD': '/builds/slave/test', 13:27:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:27:07 INFO - 'SHELL': '/bin/bash', 13:27:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5Byrb5EkGe/Listeners', 13:27:07 INFO - 'TMPDIR': '/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/T/', 13:27:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:27:07 INFO - 'USER': 'cltbld', 13:27:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:27:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:27:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:27:07 INFO - 'XPC_FLAGS': '0x0', 13:27:07 INFO - 'XPC_SERVICE_NAME': '0', 13:27:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:27:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:27:07 INFO - Downloading/unpacking jsonschema==2.5.1 13:27:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:27:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:27:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:27:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:27:09 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 13:27:09 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 13:27:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:27:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:27:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:27:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:27:09 INFO - Downloading functools32-3.2.3-2.tar.gz 13:27:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 13:27:09 INFO - warning: no files found matching '*.txt' 13:27:09 INFO - no previously-included directories found matching 'build' 13:27:09 INFO - no previously-included directories found matching 'dist' 13:27:09 INFO - no previously-included directories found matching '.git*' 13:27:09 INFO - Installing collected packages: jsonschema, functools32 13:27:09 INFO - Running setup.py install for functools32 13:27:09 INFO - warning: no files found matching '*.txt' 13:27:09 INFO - no previously-included directories found matching 'build' 13:27:09 INFO - no previously-included directories found matching 'dist' 13:27:09 INFO - no previously-included directories found matching '.git*' 13:27:09 INFO - Successfully installed jsonschema functools32 13:27:09 INFO - Cleaning up... 13:27:09 INFO - Return code: 0 13:27:09 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 13:27:09 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')]} 13:27:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:27:09 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')]} 13:27:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:27:09 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/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 0x106748b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10674b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa1424d96c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106526d30>, '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 0x10644ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10651bbe8>, '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.3nh4ao2mdF/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.5Byrb5EkGe/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/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 13:27:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 13:27:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 13:27:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3nh4ao2mdF/Render', 13:27:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:27:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:27:09 INFO - 'HOME': '/Users/cltbld', 13:27:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:27:09 INFO - 'LOGNAME': 'cltbld', 13:27:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:27:09 INFO - 'MOZ_NO_REMOTE': '1', 13:27:09 INFO - 'NO_EM_RESTART': '1', 13:27:09 INFO - 'PAGER': '/bin/cat', 13:27:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:27:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:27:09 INFO - 'PWD': '/builds/slave/test', 13:27:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:27:09 INFO - 'SHELL': '/bin/bash', 13:27:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5Byrb5EkGe/Listeners', 13:27:09 INFO - 'TMPDIR': '/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/T/', 13:27:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:27:09 INFO - 'USER': 'cltbld', 13:27:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:27:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:27:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:27:09 INFO - 'XPC_FLAGS': '0x0', 13:27:09 INFO - 'XPC_SERVICE_NAME': '0', 13:27:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:27:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:27:10 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 13:27:10 INFO - Cleaning up... 13:27:10 INFO - Return code: 0 13:27:10 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 13:27: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')]} 13:27:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:27: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')]} 13:27:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:27:10 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 0x106748b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10674b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa1424d96c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106526d30>, '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 0x10644ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10651bbe8>, '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.3nh4ao2mdF/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.5Byrb5EkGe/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/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 13:27:10 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 13:27:10 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 13:27:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3nh4ao2mdF/Render', 13:27:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:27:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:27:10 INFO - 'HOME': '/Users/cltbld', 13:27:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:27:10 INFO - 'LOGNAME': 'cltbld', 13:27:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:27:10 INFO - 'MOZ_NO_REMOTE': '1', 13:27:10 INFO - 'NO_EM_RESTART': '1', 13:27:10 INFO - 'PAGER': '/bin/cat', 13:27:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:27:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:27:10 INFO - 'PWD': '/builds/slave/test', 13:27:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:27:10 INFO - 'SHELL': '/bin/bash', 13:27:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5Byrb5EkGe/Listeners', 13:27:10 INFO - 'TMPDIR': '/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/T/', 13:27:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:27:10 INFO - 'USER': 'cltbld', 13:27:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:27:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:27:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:27:10 INFO - 'XPC_FLAGS': '0x0', 13:27:10 INFO - 'XPC_SERVICE_NAME': '0', 13:27:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:27:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:27:10 INFO - Downloading/unpacking blobuploader==1.2.4 13:27:10 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 13:27:10 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 13:27:10 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 13:27:10 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 13:27:11 INFO - Downloading blobuploader-1.2.4.tar.gz 13:27:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 13:27:11 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 13:27:11 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 13:27:11 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 13:27:11 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 13:27:11 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 13:27:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 13:27:12 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 13:27:12 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 13:27:12 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 13:27:12 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 13:27:12 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 13:27:12 INFO - Downloading docopt-0.6.1.tar.gz 13:27:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 13:27:12 INFO - Installing collected packages: blobuploader, requests, docopt 13:27:12 INFO - Running setup.py install for blobuploader 13:27:12 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 13:27:12 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 13:27:12 INFO - Running setup.py install for requests 13:27:13 INFO - Running setup.py install for docopt 13:27:13 INFO - Successfully installed blobuploader requests docopt 13:27:13 INFO - Cleaning up... 13:27:13 INFO - Return code: 0 13:27:13 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:27:13 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')]} 13:27:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:27:13 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')]} 13:27:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:27:13 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 0x106748b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10674b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa1424d96c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106526d30>, '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 0x10644ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10651bbe8>, '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.3nh4ao2mdF/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.5Byrb5EkGe/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/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 13:27:13 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 13:27:13 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 13:27:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3nh4ao2mdF/Render', 13:27:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:27:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:27:13 INFO - 'HOME': '/Users/cltbld', 13:27:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:27:13 INFO - 'LOGNAME': 'cltbld', 13:27:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:27:13 INFO - 'MOZ_NO_REMOTE': '1', 13:27:13 INFO - 'NO_EM_RESTART': '1', 13:27:13 INFO - 'PAGER': '/bin/cat', 13:27:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:27:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:27:13 INFO - 'PWD': '/builds/slave/test', 13:27:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:27:13 INFO - 'SHELL': '/bin/bash', 13:27:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5Byrb5EkGe/Listeners', 13:27:13 INFO - 'TMPDIR': '/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/T/', 13:27:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:27:13 INFO - 'USER': 'cltbld', 13:27:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:27:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:27:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:27:13 INFO - 'XPC_FLAGS': '0x0', 13:27:13 INFO - 'XPC_SERVICE_NAME': '0', 13:27:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:27:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:27:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 13:27:13 INFO - Running setup.py (path:/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/T/pip-A6lFEt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 13:27:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 13:27:13 INFO - Running setup.py (path:/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/T/pip-onkjXx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 13:27:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 13:27:13 INFO - Running setup.py (path:/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/T/pip-uFSKw3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 13:27:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 13:27:14 INFO - Running setup.py (path:/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/T/pip-_pBzgl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 13:27:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 13:27:14 INFO - Running setup.py (path:/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/T/pip-GBqdrP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 13:27:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 13:27:14 INFO - Running setup.py (path:/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/T/pip-o70gd_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 13:27:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 13:27:14 INFO - Running setup.py (path:/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/T/pip-LpMV1T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 13:27:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 13:27:14 INFO - Running setup.py (path:/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/T/pip-_SV7SU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 13:27:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 13:27:14 INFO - Running setup.py (path:/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/T/pip-pTDM03-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 13:27:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 13:27:14 INFO - Running setup.py (path:/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/T/pip-do4yQl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 13:27:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 13:27:14 INFO - Running setup.py (path:/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/T/pip-q5FvMa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 13:27:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 13:27:14 INFO - Running setup.py (path:/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/T/pip-lON9cM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 13:27:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 13:27:15 INFO - Running setup.py (path:/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/T/pip-uWCPzz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 13:27:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 13:27:15 INFO - Running setup.py (path:/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/T/pip-Y9X12t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 13:27:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 13:27:15 INFO - Running setup.py (path:/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/T/pip-hKOTxY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 13:27:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 13:27:15 INFO - Running setup.py (path:/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/T/pip-Eswcx3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 13:27:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 13:27:15 INFO - Running setup.py (path:/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/T/pip-GlAKGi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 13:27:15 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 13:27:15 INFO - Running setup.py install for manifestparser 13:27:15 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 13:27:15 INFO - Running setup.py install for mozcrash 13:27:15 INFO - Running setup.py install for mozdebug 13:27:15 INFO - Running setup.py install for mozdevice 13:27:16 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 13:27:16 INFO - Installing dm script to /builds/slave/test/build/venv/bin 13:27:16 INFO - Running setup.py install for mozfile 13:27:16 INFO - Running setup.py install for mozhttpd 13:27:16 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 13:27:16 INFO - Running setup.py install for mozinfo 13:27:16 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 13:27:16 INFO - Running setup.py install for mozInstall 13:27:16 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 13:27:16 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 13:27:16 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 13:27:16 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 13:27:16 INFO - Running setup.py install for mozleak 13:27:16 INFO - Running setup.py install for mozlog 13:27:17 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 13:27:17 INFO - Running setup.py install for moznetwork 13:27:17 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 13:27:17 INFO - Running setup.py install for mozprocess 13:27:17 INFO - Running setup.py install for mozprofile 13:27:17 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 13:27:17 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 13:27:17 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 13:27:17 INFO - Running setup.py install for mozrunner 13:27:17 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 13:27:17 INFO - Running setup.py install for mozscreenshot 13:27:17 INFO - Running setup.py install for moztest 13:27:17 INFO - Running setup.py install for mozversion 13:27:18 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 13:27:18 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 13:27:18 INFO - Cleaning up... 13:27:18 INFO - Return code: 0 13:27:18 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:27:18 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')]} 13:27:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:27:18 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')]} 13:27:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:27:18 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 0x106748b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10674b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa1424d96c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106526d30>, '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 0x10644ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10651bbe8>, '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.3nh4ao2mdF/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.5Byrb5EkGe/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/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 13:27:18 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 13:27:18 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 13:27:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3nh4ao2mdF/Render', 13:27:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:27:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:27:18 INFO - 'HOME': '/Users/cltbld', 13:27:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:27:18 INFO - 'LOGNAME': 'cltbld', 13:27:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:27:18 INFO - 'MOZ_NO_REMOTE': '1', 13:27:18 INFO - 'NO_EM_RESTART': '1', 13:27:18 INFO - 'PAGER': '/bin/cat', 13:27:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:27:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:27:18 INFO - 'PWD': '/builds/slave/test', 13:27:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:27:18 INFO - 'SHELL': '/bin/bash', 13:27:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5Byrb5EkGe/Listeners', 13:27:18 INFO - 'TMPDIR': '/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/T/', 13:27:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:27:18 INFO - 'USER': 'cltbld', 13:27:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:27:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:27:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:27:18 INFO - 'XPC_FLAGS': '0x0', 13:27:18 INFO - 'XPC_SERVICE_NAME': '0', 13:27:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:27:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:27:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 13:27:18 INFO - Running setup.py (path:/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/T/pip-GKIBxp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 13:27:18 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)) 13:27:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 13:27:18 INFO - Running setup.py (path:/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/T/pip-QP_eLA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 13:27:18 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)) 13:27:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 13:27:18 INFO - Running setup.py (path:/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/T/pip-6zIcoM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 13:27:18 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)) 13:27:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 13:27:18 INFO - Running setup.py (path:/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/T/pip-cOzsF7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 13:27:18 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)) 13:27:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 13:27:18 INFO - Running setup.py (path:/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/T/pip-_rWwlF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 13:27:18 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)) 13:27:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 13:27:18 INFO - Running setup.py (path:/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/T/pip-UQY1Yw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 13:27:19 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)) 13:27:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 13:27:19 INFO - Running setup.py (path:/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/T/pip-9awIQ1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 13:27:19 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)) 13:27:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 13:27:19 INFO - Running setup.py (path:/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/T/pip-k3jCSb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 13:27:19 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)) 13:27:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 13:27:19 INFO - Running setup.py (path:/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/T/pip-ke4DHR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 13:27:19 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)) 13:27:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 13:27:19 INFO - Running setup.py (path:/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/T/pip-S5iyxC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 13:27:19 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)) 13:27:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 13:27:19 INFO - Running setup.py (path:/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/T/pip-8ZnZYS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 13:27:19 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)) 13:27:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 13:27:19 INFO - Running setup.py (path:/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/T/pip-nzJu8V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 13:27:19 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)) 13:27:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 13:27:19 INFO - Running setup.py (path:/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/T/pip-pvExzk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 13:27:19 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)) 13:27:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 13:27:19 INFO - Running setup.py (path:/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/T/pip-CTBt23-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 13:27:19 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)) 13:27:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 13:27:19 INFO - Running setup.py (path:/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/T/pip-KKm0Yc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 13:27:19 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)) 13:27:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 13:27:20 INFO - Running setup.py (path:/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/T/pip-HgAwqR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 13:27:20 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)) 13:27:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 13:27:20 INFO - Running setup.py (path:/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/T/pip-C_cztp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 13:27:20 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)) 13:27:20 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)) 13:27:20 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)) 13:27:20 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)) 13:27:20 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)) 13:27:20 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)) 13:27:20 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 13:27:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:27:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:27:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:27:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:27:21 INFO - Downloading blessings-1.6.tar.gz 13:27:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 13:27:21 INFO - Installing collected packages: blessings 13:27:21 INFO - Running setup.py install for blessings 13:27:21 INFO - Successfully installed blessings 13:27:21 INFO - Cleaning up... 13:27:21 INFO - Return code: 0 13:27:21 INFO - Done creating virtualenv /builds/slave/test/build/venv. 13:27:21 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 13:27:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 13:27:22 INFO - Reading from file tmpfile_stdout 13:27:22 INFO - Current package versions: 13:27:22 INFO - blessings == 1.6 13:27:22 INFO - blobuploader == 1.2.4 13:27:22 INFO - docopt == 0.6.1 13:27:22 INFO - functools32 == 3.2.3-2 13:27:22 INFO - jsonschema == 2.5.1 13:27:22 INFO - manifestparser == 1.1 13:27:22 INFO - mozInstall == 1.12 13:27:22 INFO - mozcrash == 1.0 13:27:22 INFO - mozdebug == 0.1 13:27:22 INFO - mozdevice == 0.49 13:27:22 INFO - mozfile == 1.2 13:27:22 INFO - mozhttpd == 0.7 13:27:22 INFO - mozinfo == 0.9 13:27:22 INFO - mozleak == 0.1 13:27:22 INFO - mozlog == 3.4 13:27:22 INFO - moznetwork == 0.27 13:27:22 INFO - mozprocess == 0.25 13:27:22 INFO - mozprofile == 0.28 13:27:22 INFO - mozrunner == 6.13 13:27:22 INFO - mozscreenshot == 0.1 13:27:22 INFO - mozsystemmonitor == 0.3 13:27:22 INFO - moztest == 0.8 13:27:22 INFO - mozversion == 1.4 13:27:22 INFO - psutil == 3.1.1 13:27:22 INFO - requests == 1.2.3 13:27:22 INFO - wsgiref == 0.1.2 13:27:22 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:27:22 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')]} 13:27:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:27:22 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')]} 13:27:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:27:22 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 0x106748b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10674b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa1424d96c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106526d30>, '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 0x10644ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10651bbe8>, '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.3nh4ao2mdF/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.5Byrb5EkGe/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/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 13:27:22 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 13:27:22 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 13:27:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3nh4ao2mdF/Render', 13:27:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:27:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:27:22 INFO - 'HOME': '/Users/cltbld', 13:27:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:27:22 INFO - 'LOGNAME': 'cltbld', 13:27:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:27:22 INFO - 'MOZ_NO_REMOTE': '1', 13:27:22 INFO - 'NO_EM_RESTART': '1', 13:27:22 INFO - 'PAGER': '/bin/cat', 13:27:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:27:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:27:22 INFO - 'PWD': '/builds/slave/test', 13:27:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:27:22 INFO - 'SHELL': '/bin/bash', 13:27:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5Byrb5EkGe/Listeners', 13:27:22 INFO - 'TMPDIR': '/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/T/', 13:27:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:27:22 INFO - 'USER': 'cltbld', 13:27:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:27:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:27:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:27:22 INFO - 'XPC_FLAGS': '0x0', 13:27:22 INFO - 'XPC_SERVICE_NAME': '0', 13:27:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:27:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:27:22 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)) 13:27:22 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)) 13:27:22 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)) 13:27:22 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)) 13:27:22 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)) 13:27:22 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)) 13:27:22 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)) 13:27:22 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)) 13:27:22 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)) 13:27:22 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)) 13:27:22 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)) 13:27:22 INFO - Cleaning up... 13:27:22 INFO - Return code: 0 13:27:22 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 13:27:22 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')]} 13:27:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:27:22 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')]} 13:27:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:27:22 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 0x106748b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10674b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa1424d96c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106526d30>, '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 0x10644ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10651bbe8>, '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.3nh4ao2mdF/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.5Byrb5EkGe/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/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 13:27:22 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 13:27:22 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 13:27:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3nh4ao2mdF/Render', 13:27:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:27:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:27:22 INFO - 'HOME': '/Users/cltbld', 13:27:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:27:22 INFO - 'LOGNAME': 'cltbld', 13:27:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:27:22 INFO - 'MOZ_NO_REMOTE': '1', 13:27:22 INFO - 'NO_EM_RESTART': '1', 13:27:22 INFO - 'PAGER': '/bin/cat', 13:27:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:27:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:27:22 INFO - 'PWD': '/builds/slave/test', 13:27:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:27:22 INFO - 'SHELL': '/bin/bash', 13:27:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5Byrb5EkGe/Listeners', 13:27:22 INFO - 'TMPDIR': '/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/T/', 13:27:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:27:22 INFO - 'USER': 'cltbld', 13:27:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:27:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:27:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:27:22 INFO - 'XPC_FLAGS': '0x0', 13:27:22 INFO - 'XPC_SERVICE_NAME': '0', 13:27:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:27:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:27:22 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 13:27:22 INFO - Cleaning up... 13:27:22 INFO - Return code: 0 13:27:22 INFO - Running post-action listener: _resource_record_post_action 13:27:22 INFO - Running post-action listener: _start_resource_monitoring 13:27:22 INFO - Starting resource monitoring. 13:27:22 INFO - [mozharness: 2017-06-01 20:27:22.775270Z] Finished create-virtualenv step (success) 13:27:22 INFO - [mozharness: 2017-06-01 20:27:22.776303Z] Running install step. 13:27:22 INFO - Running pre-action listener: _resource_record_pre_action 13:27:22 INFO - Running main action method: install 13:27:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 13:27:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 13:27:23 INFO - Reading from file tmpfile_stdout 13:27:23 INFO - Detecting whether we're running mozinstall >=1.0... 13:27:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 13:27:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 13:27:23 INFO - Reading from file tmpfile_stdout 13:27:23 INFO - Output received: 13:27:23 INFO - Usage: mozinstall [options] installer 13:27:23 INFO - Options: 13:27:23 INFO - -h, --help show this help message and exit 13:27:23 INFO - -d DEST, --destination=DEST 13:27:23 INFO - Directory to install application into. [default: 13:27:23 INFO - "/builds/slave/test"] 13:27:23 INFO - --app=APP Application being installed. [default: firefox] 13:27:23 INFO - mkdir: /builds/slave/test/build/application 13:27:23 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'] 13:27:23 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 13:27:38 INFO - Reading from file tmpfile_stdout 13:27:38 INFO - Output received: 13:27:38 INFO - /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox 13:27:38 INFO - Running post-action listener: _resource_record_post_action 13:27:38 INFO - [mozharness: 2017-06-01 20:27:38.214744Z] Finished install step (success) 13:27:38 INFO - [mozharness: 2017-06-01 20:27:38.214893Z] Running run-tests step. 13:27:38 INFO - Running pre-action listener: _resource_record_pre_action 13:27:38 INFO - Running main action method: run_tests 13:27:38 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 13:27:38 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 13:27:38 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 13:27:38 INFO - 2017-06-01 13:27:38.229 screenresolution[1628:7899] starting screenresolution argv=screenresolution get 13:27:38 INFO - 2017-06-01 13:27:38.232 screenresolution[1628:7899] Display 0: 1600x1200x32@60 13:27:38 INFO - 2017-06-01 13:27:38.238 screenresolution[1629:7901] starting screenresolution argv=screenresolution list 13:27:38 INFO - Available Modes on Display 0 13:27:38 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 13:27:38 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 13:27:38 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 13:27:38 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 13:27:38 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 13:27:38 INFO - Intel Iris: 13:27:38 INFO - Chipset Model: Intel Iris 13:27:38 INFO - Type: GPU 13:27:38 INFO - Bus: Built-In 13:27:38 INFO - VRAM (Dynamic, Max): 1536 MB 13:27:38 INFO - Vendor: Intel (0x8086) 13:27:38 INFO - Device ID: 0x0a2e 13:27:38 INFO - Revision ID: 0x0009 13:27:38 INFO - Displays: 13:27:38 INFO - Display: 13:27:38 INFO - Resolution: 1600 x 1200 @ 60 Hz 13:27:38 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 13:27:38 INFO - Display Serial Number: 1600x1200 60 13:27:38 INFO - Main Display: Yes 13:27:38 INFO - Mirror: Off 13:27:38 INFO - Online: Yes 13:27:38 INFO - Rotation: Supported 13:27:38 INFO - Return code: 0 13:27:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 13:27:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 13:27:38 INFO - Python 2.7.3 13:27:38 INFO - Return code: 0 13:27:38 INFO - grabbing minidump binary from tooltool 13:27:38 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')]} 13:27:38 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 0x106526d30>, '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 0x10644ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10651bbe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 13:27:38 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 13:27:38 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 13:27:38 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 13:27:38 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 13:27:38 INFO - Return code: 0 13:27:38 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 13:27:38 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 13:27:38 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 13:27:38 INFO - ENV: RUST_BACKTRACE is now 1 13:27:38 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 13:27:38 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 13:27:38 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/eCXaZQS8TLSkGySN1Pr5nQ/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0013', '--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 13:27:38 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/eCXaZQS8TLSkGySN1Pr5nQ/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0013 --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 13:27:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3nh4ao2mdF/Render', 13:27:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:27:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:27:38 INFO - 'HOME': '/Users/cltbld', 13:27:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:27:38 INFO - 'LOGNAME': 'cltbld', 13:27:38 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 13:27:38 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 13:27:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:27:38 INFO - 'MOZ_NO_REMOTE': '1', 13:27:38 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 13:27:38 INFO - 'NO_EM_RESTART': '1', 13:27:38 INFO - 'PAGER': '/bin/cat', 13:27:38 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:27:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:27:38 INFO - 'PWD': '/builds/slave/test', 13:27:38 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 13:27:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:27:38 INFO - 'RUST_BACKTRACE': '1', 13:27:38 INFO - 'SHELL': '/bin/bash', 13:27:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5Byrb5EkGe/Listeners', 13:27:38 INFO - 'TMPDIR': '/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/T/', 13:27:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:27:38 INFO - 'USER': 'cltbld', 13:27:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:27:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:27:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:27:38 INFO - 'XPC_FLAGS': '0x0', 13:27:38 INFO - 'XPC_SERVICE_NAME': '0', 13:27:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:27:38 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/eCXaZQS8TLSkGySN1Pr5nQ/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0013', '--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 13:27:38 INFO - mozversion application_buildid: 20170601121724 13:27:38 INFO - mozversion application_changeset: a85ff8f8399de4eea50c9f3aece921814c98d016 13:27:38 INFO - mozversion application_display_name: Firefox 13:27:38 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 13:27:38 INFO - mozversion application_name: Firefox 13:27:38 INFO - mozversion application_remotingname: firefox 13:27:38 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 13:27:38 INFO - mozversion application_vendor: Mozilla 13:27:38 INFO - mozversion application_version: 54.0 13:27:38 INFO - mozversion platform_buildid: 20170601121724 13:27:38 INFO - mozversion platform_changeset: a85ff8f8399de4eea50c9f3aece921814c98d016 13:27:38 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 13:27:38 INFO - mozversion platform_version: 54.0 13:27:38 INFO - using testdate: 1496348858 13:27:38 INFO - actual date: 1496348858 13:27:38 INFO - starting webserver on 'localhost:49219' 13:27:38 INFO - SUITE-START | Running 2 tests 13:27:38 INFO - TEST-START | dromaeo_css 13:27:38 INFO - operating with platform_type : mac_ 13:27:38 INFO - Initialising browser for dromaeo_css test... 13:27:38 INFO - TEST-INFO | started process 1639 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/T/tmp0FmhCu/profile http://localhost:49219/getInfo.html) 13:27:41 INFO - PROCESS | 1639 | 2017-06-01 13:27:41.921 plugin-container[1643:8077] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8f4f, name = 'com.apple.tsm.portname' 13:27:41 INFO - PROCESS | 1639 | See /usr/include/servers/bootstrap_defs.h for the error codes. 13:27:41 INFO - PROCESS | 1639 | 2017-06-01 13:27:41.922 plugin-container[1643:8077] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x930b, name = 'com.apple.CFPasteboardClient' 13:27:41 INFO - PROCESS | 1639 | See /usr/include/servers/bootstrap_defs.h for the error codes. 13:27:41 INFO - PROCESS | 1639 | 2017-06-01 13:27:41.922 plugin-container[1643:8077] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 13:27:41 INFO - PROCESS | 1639 | __metrics Screen width/height:1600/1200 13:27:41 INFO - PROCESS | 1639 | colorDepth:24 13:27:41 INFO - PROCESS | 1639 | Browser inner width/height: 1024/689 13:27:41 INFO - PROCESS | 1639 | __metrics 13:27:42 ERROR - PROCESS | 1639 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3381: TypeError: this.tabs is undefined 13:27:42 INFO - TEST-INFO | 1639: exit 0 13:27:42 INFO - Browser initialized. 13:27:42 INFO - Running cycle 1/1 for dromaeo_css test... 13:27:42 INFO - TEST-INFO | started process 1644 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/T/tmp0FmhCu/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 13:27:45 INFO - PROCESS | 1644 | 2017-06-01 13:27:45.135 plugin-container[1645:8206] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4247, name = 'com.apple.tsm.portname' 13:27:45 INFO - PROCESS | 1644 | See /usr/include/servers/bootstrap_defs.h for the error codes. 13:27:45 INFO - PROCESS | 1644 | 2017-06-01 13:27:45.136 plugin-container[1645:8206] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8b0b, name = 'com.apple.CFPasteboardClient' 13:27:45 INFO - PROCESS | 1644 | See /usr/include/servers/bootstrap_defs.h for the error codes. 13:27:45 INFO - PROCESS | 1644 | 2017-06-01 13:27:45.136 plugin-container[1645:8206] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 13:27:54 INFO - PROCESS | 1644 | RSS: Main: 174530560 13:27:54 INFO - PROCESS | 1644 | 13:30:58 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49219/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49219/tests/dromaeo/cssquery-ext.html) 13:30:59 INFO - PROCESS | 1644 | RSS: Main: 192937984 13:30:59 INFO - PROCESS | 1644 | 13:34:01 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49219/tests/dromaeo/cssquery-ext.html (next: http://localhost:49219/tests/dromaeo/cssquery-jquery.html) 13:34:02 INFO - PROCESS | 1644 | RSS: Main: 206127104 13:34:02 INFO - PROCESS | 1644 | 13:37:04 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49219/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49219/tests/dromaeo/cssquery-mootools.html) 13:37:04 INFO - PROCESS | 1644 | RSS: Main: 207814656 13:37:04 INFO - PROCESS | 1644 | 13:40:06 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49219/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49219/tests/dromaeo/cssquery-prototype.html) 13:40:07 INFO - PROCESS | 1644 | RSS: Main: 210804736 13:40:07 INFO - PROCESS | 1644 | 13:43:10 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49219/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49219/tests/dromaeo/cssquery-yui.html) 13:43:10 INFO - PROCESS | 1644 | RSS: Main: 213344256 13:43:10 INFO - PROCESS | 1644 | 13:46:13 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49219/tests/dromaeo/cssquery-yui.html (next: http://localhost:49219/tests/dromaeo/cssquery-dojo.html) 13:46:13 INFO - PROCESS | 1644 | RSS: Main: 211746816 13:46:13 INFO - PROCESS | 1644 | 13:46:13 INFO - PROCESS | 1644 | __start_tp_report 13:46:13 INFO - PROCESS | 1644 | _x_x_mozilla_page_load 13:46:13 INFO - PROCESS | 1644 | _x_x_mozilla_page_load_details 13:46:13 INFO - PROCESS | 1644 | |i|pagename|runs| 13:46:13 INFO - PROCESS | 1644 | |0;dojo.html;3070.692930706928;3585.0678823505896;3637.618050104739;3641.435577485489;3661.340958627446;6196.442320191182;6196.59722118062;6205.844853878655;6250.249970003594;6272.6863656817095;6143.477804386633;6166.722497487613;6168.784092556762;6190.907136392958;6192.50459963203;5555.944370569599;5603.327600687903;5634.436556344353;5647.237622920915;5706.686132262724;5665.320161580596;5682.26130603021;5703.116017017369;5704.172894930253;5706.828795136132;5455.1544510600725;5460.863478413032;5679.602427830054;5701.971490142564;5725.6278341907855;5573.832785016436;5575.5539556835765;5581.162825576155;5598.916016259759;5613.4386561343945;5464.508194262496;5596.244506991586;5596.664200148004;5613.494785469327;5638.633488823213;5374.973125134388;5547.77808887644;6184.81445556634;6266.780662676784;6277.96861015692;6307.24313082433;6346.460550853183;6366.713497892606;6380.840478988062;6393.648349340784;6589.93410065898;6616;6643.335666433366;6648.900266496006;6667.366600172976;6251.156093927352;6282;6317.46301564363;6344.714487848058;6350.301466838644;5809.448102430262;6436.935630643634;6515.348465153446;6522.119513865568;6722.495812814059;5406.216098665672;6293.527985401113;6325.9051114233325;6366.267879193952;6370.617762934239;6559.934400656028;6560.311167327457;6563.901541476882;6580;6598.7030583623855;6252.874942501125;6488.643124628191;6490.286068532458;6500.089987401768;6529.738810447624;6226.875462490725;6334.461570766444;6368.044793280952;6390.201224846895;6511.446527045254;5230.529252367305;5269.314989051475;5294.708791016454;5302.549283310962;5337.519623233928;4181.958180418218;4667.696599721007;4675.672702910763;4697.4832768395445;4720.575148236675;6122.724477398528;6165.445109940127;6318.968405157945;6360.713767880364;6362.331955144736;6057.57596968208;6393.328700486475;6428.517861160338;6444.065610486547;6493.415592596594;6203.2245969253845;6208.099825525273;6213.192285003011;6223.502119830403;6230.221222347207;5729.28383952006;5757.510611598061;5791.160281759206;5808.738606762706;5828.746998374349;6579.111819904251;6608.50436217276;6762.966185169043;6773.966130169318;6893.862122757417;6665.566738162004;6672.266050734318;6726.058351830846;6729.360710732472;6732.697028633625;6847.897281540681;6890.724371025103;6891.620960847202;6900.102986611709;6903.1716194057035;5351.384590772111;6255.155554000152;6300.558960872695;6446.4520515756685;6450.451711604566;6647.900281495685;6660.267370589135;6663.033860090357;6679;6773.458123350219;5079.847604571869;6229.968850155901;6457.773977910751;6473.676316184078;6506.577072490273;5813.273340832396;6246.812595622138;6256.5620406572925;6485.870282594227;6492.6104433732235;6036.124761909411;6168.845778855564;6230.875382492234;6245.406686364788;6256.499480041494;5273.683578985273;5346.465353464622;6197.845053873509;6295.622262664255;6300.338464461349;5207.557357624644;6209.441150296495;6225.533085018551;6255.906161407674;6256.061590761422 13:46:13 INFO - PROCESS | 1644 | |1;ext.html;779.5283853268772;804.2962407893093;846.6571038729317;983.6901376066543;986.8914419413868;39374.40938385923;40024.599754002455;40984.79507602458;41094.38358424626;41145.7942710286;38927.026824329405;39044.21911561767;39073.21853562927;39140.80429597848;39269;8288.502689838613;8427;11138.053265472445;11345.546178152905;11499.540018399253;11188.38463884486;11249.943750281238;11307.830382544269;11311.264767790068;11326;11197.27217730849;11274.323540587591;11291.887081129207;11321.943390283079;11355.432228388589;11223.66329010131;11277.830832537518;11289.548418063268;11292.774144517063;11293.435328233596;10983.945080274547;10993.780124397466;10998.175136864767;11033.117350611932;11039.282446641024;34510.482342764626;34606;34621;34650.61397544095;34748.47877281843;3959.8614048508166;3985.52530579285;4001.5998400160042;4030.435738996543;4061;25772.097976570916;26408.81160347789;27014;27491.587626185625;27662.585061224097;3173.5239714042846;3225.854836532362;3249.8050116992817;3260.983695081533;3283.5731354923946;332857;336076;337090;337188;337364;45041.32438013433;45054.32418513725;45188.3221751674;45206;45213.773931130134;46140.30789538159;46251.5374846254;46415;46589;46761.29858052132;14321.427142914376;14640.487582934546;14700.117992920459;14706.55880323592;14733.705325893423;12617.49530018789;12696.555620553236;12760.553380631633;12761.744765104831;12785.872141278469;11927.224730392496;12007.219530730474;12029.879701202875;12071.758564828655;12147.5140994361;5065.544101030851;5199.466157483552;5769.9711501442225;5812.273465816773;5826.92201942642;1076.531708706715;1076.6608518316705;1095.2114477576133;1128.8645362556547;1174.213277104342;24614.384640384134;26820;27098.86450567774;27268.590971135447;27450;902.5081330674798;989.6833013435776;1169.923954942926;1177.9469923853446;1236.7959286717592;5939.881202375928;8496.830063398698;12812;14218.644533886736;14445.711085778226;508507;517440;517528;517955.4102229465;518327;39854.80072599676;40277.79861100676;47712.28431573529;47842.28236576454;47853.521464785605;1466.65533599604;1490.5975386645546;1492.2091291615461;1503.812023497063;1512.5235550801463;23915;26746.732532674425;27340.589891151252;27566;27865.721342787125;42135.578644213165;42146.57853421549;42263.366049509896;42298;42363.3645495324;492928;495963;497337.0266297291;497570;498133.50933245104;28997.275068123465;29172.978945737646;29236.122916312546;29273.26816829511;29710.405791884474;37911.62088379081;37923.05192370213;38328.2334353317;38589.80705096456;38642.6135738639;26761.732382675924;26875.73124268732;26965.595516066882;27290.45419091647;28054.43891122207;27045.782939767363;27357.452850943268;27391.863040684668;27738.44523109567;28138.01516946897;31755.52366714455;31771.887983920453;32273.677263228008;32396.514052289705;32441.188970275933;23819;26860.544996565055;27239.86380068087;27346.86326568434;28075.859620701765;21069.262575809775;22536.887315563315;22547.51025944425;22571.322860314216;22667.773322267225 13:46:13 INFO - PROCESS | 1644 | |2;jquery.html;368.3351550451434;403.85780359048806;438.96927215094945;449.1780042522185;454.5931391404691;20046.49883752907;21107;22158;22281.220157294498;22289.777102228974;21020.474488137803;23439.76560234397;23624.346295759304;23826.689532075772;24126.638100428507;6798.388145066943;6916.23921368649;7159.856802863939;7183.281671832801;7198.7840364789145;6030.42710942462;7059.85880282394;7063.258357872426;7070.964645176767;7080.575165490061;7017.26318736533;7027.718891244344;7398.482106252564;7747.883781743279;7844.882326765074;7572;7762.728304509372;7778.494397864149;7807.180246074137;7839.6864125435195;6473.74105035798;7551.24487551246;7576.696932122709;7607.771766847003;7615.771526854203;21211.575768484698;22339;25818.87090564554;25920.09279675221;27096.1871143866;3049;3312.7681062325646;3386.7798593091616;3451.7756345837693;3485.89542313731;16378.918105409397;16398.098104604243;16418.343266269334;16426;16430.424935127212;2113.9154433822628;2196.0666716645524;2274.5450909817937;2297.9425514362106;2301.367124040902;156349.99999999886;164511;165574;165671;165672.34327656688;21167.470813229793;21197.152113915578;21400.464988375104;21475;21648.35054948354;27229.86385068062;32995.34009319848;33104.50343244853;33168;33235.33529329448;10791.892081079246;10795.082417994558;10807.567697292021;11018.338899665885;11138.38493418466;7448.627568621548;9139.49732764691;9150.084991500797;9292.349535532448;9292.674756383494;9605.37565058283;9773.462459564793;9830;9919.702408927744;9940.900590993997;4199.307114326163;4263.87208383749;4278.807453664593;4288.142371525707;4291.828326866953;1043.3948309980194;1047.418682631147;1057.471264367816;1082.0910435234441;1103.7626910214342;21395.358139255848;21718.565628687338;21744.02151903168;21748.782512174992;22324.553508930054;907.419251678926;1059.8463222832645;1077.3266708307308;1095.5289225633055;1104.8342748587777;9288.814223715488;10864.945675271572;11266.830997535044;11336.489857956412;11414.086873050135;666290;667696;668109;672306;672814;152067.2396638032;152899.47100528853;154433;155230;161838;1265.4495294546903;1272.1476610670873;1291.5027714330051;1298.1561984710015;1300.8243887075314;18286.268549257882;18410.815891841445;18665.53336166607;18677.14001608961;18966.05169741535;57535;62925.68537157285;63062.68468657627;64589.67705161444;64744;84701.15298846933;85269.14730852612;86022.56988715017;86400.56799715961;86426.56786716027;11317.830232546683;11334.659960201207;11796.997255233402;11921.225120367148;11982.460789264329;12720.87279127197;12794.616161515169;13172.934135329646;13232.345956755405;13309.00182486298;8946.015938246917;11056.723581910517;11368.658940231806;11619.6514104577;11638.243514171552;9212.124848139416;10071.748206294902;10540.683779486628;10557.472126393495;10820.02619764225;11511.53953841837;11574.537018519502;11783.469743861719;11783.705407364885;11930.522779088738;9401.576929038347;11408.543658253577;11607.067334723197;11718;12035.518579256732;8591.398602098043;9304.976452590345;10071.798564028824;10158.441285729356;10295.897041029493 13:46:13 INFO - PROCESS | 1644 | |3;mootools.html;1561.3520389038547;1564.100642130775;1604.6229136153006;1608.5174447665697;1611.7421212605987;9760.70717878463;10022.148117410028;10985.945070274658;11601.941990290048;11639.010684091852;10889.183311251669;11215.271007384536;11223.551057957693;11234.269772464784;11246.268992515501;2467.605183170693;2488.35302821266;2502.824802263833;2503.4242124311418;2507.260358194337;2461.778439940405;2495.6007038873786;2500.037485568051;2506.1729629222405;2512.736162702917;2140.9464763380884;2465.3343597228736;2478.3060742991993;2482.2925466242123;2494.1395218649536;2448.1431498975394;2456.3981824452944;2456.5086982603552;2463.4580392313665;2466.075221791828;2129.1270579062666;2147.50607360308;2148.366231961575;2149.2692484555328;2154.8686939356808;9833.86910505294;11467.36929468879;11571.53713851445;11571.94214028933;11586.594469193538;4562.612177964844;5966.134910437962;6092.5430592705725;6144.8771024579255;6156.29202641693;3683.7421380503374;3756.9436458453147;3764.830582623761;3809.4285857121376;3837.501124853779;1237.7462620162844;1244.7199380139486;1264.4373253902017;1289.9680255795327;1294.372229468707;16024.198790060567;16031;16073.357065717475;16073.437429690022;16122.838771612369;4434.401355816924;5053.823116190915;5071.771770270347;5109.156989096758;5184.092783762826;13436.731265374834;13446.193228406328;13464.93267533656;13471.932640336736;13485;6133.202683651186;6191.318954914956;6192.349803270592;6196.628202307876;6206.379362063848;4514.6614003949835;5044.243363495505;5508.366537848118;5513.862153446196;5515.365732940763;4103.4050062740735;4203;4217.156568686275;4262.168877069003;4266.786660666954;1189.9048076153888;1191.773563023023;1198.574506050357;1209.449700386322;1213.538855235005;963.6964356227766;969.8836139663285;987.9753006174759;995.9542480395555;998.6155330197781;6237.532185086136;6407.551471396958;6531.0856480092825;6627.602343859384;6850.520463567602;1000.5797565022708;1027.701966429727;1050.558765318568;1062.8033813744482;1067.412922892406;4145;4223.070924396628;4236.004538933348;4344.652427805768;4363.650907927358;11404.48679809394;13219.801702974462;13837.100588461717;13932.582022539138;13945.790813137812;13728.794068088986;13737.656558586115;13804.723905521832;13832.930835345758;13835.446582136601;8816.294696424271;9238.90761092394;9727.951360243154;10268;10374.118199952938;6540;6598.769043083469;6647.368499992493;6669.866602668017;6710.563813352116;10072.496375181065;12315.69210769738;12411.193272437262;12454.875451245372;12593;11411.25826821254;12535.373231338579;12817.935910320388;12850.164739291917;13187.208767473981;8417.747467575982;8836.91163088361;9345.532723363669;9374.062593740571;9403.576839042124;10457.320274182153;10464.686059418229;10532.736681583021;10559.99680030396;10633.308834925705;9021.684241051531;9080;9097.77255568616;9253.953730231306;9260.907390926004;8802.867956980523;8856.247218986458;8867.955660221658;8948.328875334246;9057.68298109563;6762.8985565217545;8036.357091432555;8325.417220794487;8390.328773697967;8414.45306055129;5938.109283607493;5946.762129514944;5962.105684147239;6052.03167493199;6128.785492507741;6005.819825405245;6080.331163571915;6124.112003759341;6183.227096612924;6197.938020619917 13:46:13 INFO - PROCESS | 1644 | |4;prototype.html;335.2339903320912;381.1291199608894;396.7282411548088;401.29172011399885;405.38584045171564;4501.482329532105;4852.733099679521;5249.396319423267;5376.381716102649;5480.972595137018;4516.8156449702965;4539.682222244444;4544.295634176708;5215;5347.7058761768085;4435.536273029892;4591.701539399945;4615.284630882219;4630.421197350332;4649.349091127245;3933.8819835404843;4022.7184097113213;4362.8909277268;4383.254846676056;4725.3384526166365;3970.602939706035;4043.0903046814524;4387.802548885292;4590.472095709003;4661.533846615345;4258.127083947783;4523.525029871849;4580.610648094899;4605.194091034089;4644.373009643688;3562.3765840977726;4055.702175303904;4392.780360981938;4406.713563618355;4430;4395.890102747457;5077.385146649479;5110.616703747197;5147.742612869379;5158.53573178412;4402.273624851896;5107.263530399645;5310.8141215057285;5404.405515393303;5417.972910135424;5455.427180146067;5699.974004679156;5731.512821410185;5745.856353591152;5811.6803575803315;4834.444038935532;4857.271409288635;4857.854264372075;4873.634477414208;4960.933399319128;5197.688138711652;5800.303963524362;5857.443542863463;5904.586678932476;6035.275766907999;4158.584141585878;4671.827600165939;4963.280324352949;4983.6760610560195;5084.847454576368;4645.094206629742;4760.547747963938;5009;5146.044503088877;5146.30524879144;5026.698398096126;5033.6224783141415;5190.377154741455;5204.271402003723;5348.8662783429945;4936.703797772145;5558.499735023867;5750.798722044792;5770.701592141734;5815.41845815415;1557.1506380329856;1757.912104394775;1782.4296225207809;1786.0266154945587;1796.119901248405;1330.6207730796677;1370.390176371505;1379.3861731529573;1392.1647011793045;1416.46174453709;4586.2661974083985;5134.589232861362;5261.71088083422;5380.946190538045;5409.43200963901;5565.6104072714525;5666.744996475169;5872.531867033242;5964.343922168558;5972.85067873298;4482.731036137842;5147.897042059138;5368.040665157189;5381.515663590296;5397.514223719884;2614.6926536731635;2749.5839642584087;2822.096928982706;2933.339998500363;3053.6030316059214;5295.311609490742;5816.738246778905;6017.338092809875;6244.344343843922;6277.748890044347;5208.947910520922;5237.528622424;5443.401225865152;5620.887582248414;5767.279090113736;5178.6116041297055;5252.842414727565;5776.249087618667;6059.757609695651;6251.624902505864;5427.4301198373605;5496.642718223303;5537.8061767837935;5559.610827242054;6008.909866351921;4922.950770492249;5108.876047269595;5335.039692855324;5505.504504594605;5678.290213723285;4858.975705121593;5380.569554435558;5394.892102158014;5604.915926261191;6118.326984031842;4189.581041895786;4211.768352740629;4347.782610869381;4559.9740058487905;4702.905941881075;4905.975470122627;5338.199270109515;5575.665460072409;5820.359760426434;5921.963656360206;5148.845534633967;5180.533751962342;5619.8760247951395;5754.367019627922;5828.475437210672;5272.6309158358545;5278.181881808326;5463.562914966714;5500.889982200253;5722.397728635989;5376.301080859464;5447.618666693294;5591.412901645269;5599.300087489064;5702.885942281214;5773.884522309614;5811.994861772681;5820.505257053198;5843.678597677169;5880.8235752927485;4666.393368862026;4854.75726213695;4858;5425.376081750649;5682.289713785777 13:46:13 INFO - PROCESS | 1644 | |5;yui.html;726.905502284703;771.090113665874;805.178717707938;872.5942436766903;883.350737208152;15879.68240635188;16812.41156559521;17386.913065434688;17637.206325715342;17686.292548298086;16005.839941600581;16265.105419201938;16364.427245046425;16401.42595009175;16562.254698538563;989.2135752077098;1012.0941757127366;1012.5899010900597;1015.2690063154554;1017.0490591297124;939.8402271613842;1029.907308342249;1044.6918159143033;1049.884512703602;1050.159872102319;961.418341903149;1083.0685610375072;1083.3662307550053;1084.6312253833694;1084.9294795838246;1077.7359546911057;1079.4494807648077;1081.0054749630358;1081.3836113415357;1081.5457507846763;798.0622768247287;798.5129071266523;798.6046906781122;798.9520628762293;799.9640016199285;16681.749773753403;17048.914755426267;17100;17662.293508259656;17802.376916807978;14562.271886405637;14569.490067847575;14578.854211457856;14615.561533154023;14703.97072204946;12025.57910473138;12067.758644827054;12226.510939562408;12272.938635306855;12350.5059797608;3855.768653880776;3873.845046198149;3905.9804700976597;3970.7419017763755;3983.6016398359934;114869.70260594742;121520.39239803744;123071;123952;124589.75410245963;11411.600593979338;11501.654950351503;11509.654710358702;11558.248713833575;11755.823662645067;23587.41031474227;23899.880500597734;23902.88048559781;23938.28185154448;24090.879545602158;8149.592520373958;8217.79455513605;8251.2986396157;8255.876161857577;8376.664933402717;3686.668199862025;4004.9199016019516;4591.9081618367445;4595.379623750818;4835.637327200474;3661.212839239523;3709.5919448860604;3748.400255959088;3753.5495740511315;3791.9241615167543;670.8658268346351;671.8622682350107;678.8540463800307;681.0839420978775;691.9861602768017;639.0159154901493;723.1719680965371;732.7325526182905;741.6773703439021;764.7820371194183;8938.68714594986;10005.549750261256;10738.261678911595;10980.670579882617;11024.779504409868;715.9463040272001;752.6575408189261;753.9773806785805;758.2796343473722;767.9884801727978;4678.947236871677;5207.4011488678525;5214.817481388133;5219.530242278137;5221.973890130525;82132.58933705294;96983;101223.49388253012;101952.49023754834;103134;91195;94277;96326;97023;97199.02800972041;63019.6849015752;78977;79833.60083199663;82173;84300.15699842923;8136.796580085545;9956.800863982824;10042.598296068076;10052.095311422006;10058;46431;48039.75980120077;54544;54665.45334546763;55888.161677574055;54122.45877541174;58191;61458.69270653797;61559;62819.6859015702;13314.46742130304;13498.52755153565;13531.797023044466;13534.932325338705;13828.654283642989;15223.695526089637;15620.68758624844;15850.7622385662;15972.44096456665;16200;14022.78965815534;14249;14290.92818038672;14362.210078445785;14569.562913112624;12143.332116733665;13656.931715341758;13955.232462214675;14060.458575603368;14606.123632581654;13448.193108413527;13616.38726257301;13989.440422382992;14048.648783780078;14320.928395357956;9822;10106.646267380607;10202.540885660015;10341.534630941474;10403.947980260353;10038.64864729731;11408.258463199865;11526.020288275591;11656.650300491;11712 13:46:13 INFO - PROCESS | 1644 | __end_tp_report 13:46:13 INFO - PROCESS | 1644 | __start_cc_report 13:46:13 INFO - PROCESS | 1644 | _x_x_mozilla_cycle_collect,393 13:46:13 INFO - PROCESS | 1644 | __end_cc_report 13:46:13 INFO - PROCESS | 1644 | __startTimestamp1496349973877__endTimestamp 13:46:13 INFO - PROCESS | 1644 | 13:46:13 INFO - PROCESS | 1644 | ------- Summary: start ------- 13:46:13 INFO - PROCESS | 1644 | Number of tests: 6 13:46:13 INFO - PROCESS | 1644 | 13:46:13 INFO - PROCESS | 1644 | [#0] dojo.html Cycles:165 Average:6041.34 Median:6250.25 stddev:679.20 (10.9%) stddev-sans-first:640.05 13:46:13 INFO - PROCESS | 1644 | Values: 3070.7 3585.1 3637.6 3641.4 3661.3 6196.4 6196.6 6205.8 6250.2 6272.7 6143.5 6166.7 6168.8 6190.9 6192.5 5555.9 5603.3 5634.4 5647.2 5706.7 5665.3 5682.3 5703.1 5704.2 5706.8 5455.2 5460.9 5679.6 5702.0 5725.6 5573.8 5575.6 5581.2 5598.9 5613.4 5464.5 5596.2 5596.7 5613.5 5638.6 5375.0 5547.8 6184.8 6266.8 6278.0 6307.2 6346.5 6366.7 6380.8 6393.6 6589.9 6616.0 6643.3 6648.9 6667.4 6251.2 6282.0 6317.5 6344.7 6350.3 5809.4 6436.9 6515.3 6522.1 6722.5 5406.2 6293.5 6325.9 6366.3 6370.6 6559.9 6560.3 6563.9 6580.0 6598.7 6252.9 6488.6 6490.3 6500.1 6529.7 6226.9 6334.5 6368.0 6390.2 6511.4 5230.5 5269.3 5294.7 5302.5 5337.5 4182.0 4667.7 4675.7 4697.5 4720.6 6122.7 6165.4 6319.0 6360.7 6362.3 6057.6 6393.3 6428.5 6444.1 6493.4 6203.2 6208.1 6213.2 6223.5 6230.2 5729.3 5757.5 5791.2 5808.7 5828.7 6579.1 6608.5 6763.0 6774.0 6893.9 6665.6 6672.3 6726.1 6729.4 6732.7 6847.9 6890.7 6891.6 6900.1 6903.2 5351.4 6255.2 6300.6 6446.5 6450.5 6647.9 6660.3 6663.0 6679.0 6773.5 5079.8 6230.0 6457.8 6473.7 6506.6 5813.3 6246.8 6256.6 6485.9 6492.6 6036.1 6168.8 6230.9 6245.4 6256.5 5273.7 5346.5 6197.8 6295.6 6300.3 5207.6 6209.4 6225.5 6255.9 6256.1 13:46:13 INFO - PROCESS | 1644 | 13:46:13 INFO - PROCESS | 1644 | [#1] ext.html Cycles:180 Average:56880.40 Median:29204.55 stddev:121791.20 (417.0%) stddev-sans-first:122060.02 13:46:13 INFO - PROCESS | 1644 | Values: 779.5 804.3 846.7 983.7 986.9 39374.4 40024.6 40984.8 41094.4 41145.8 38927.0 39044.2 39073.2 39140.8 39269.0 8288.5 8427.0 11138.1 11345.5 11499.5 11188.4 11249.9 11307.8 11311.3 11326.0 11197.3 11274.3 11291.9 11321.9 11355.4 11223.7 11277.8 11289.5 11292.8 11293.4 10983.9 10993.8 10998.2 11033.1 11039.3 34510.5 34606.0 34621.0 34650.6 34748.5 3959.9 3985.5 4001.6 4030.4 4061.0 25772.1 26408.8 27014.0 27491.6 27662.6 3173.5 3225.9 3249.8 3261.0 3283.6 332857.0 336076.0 337090.0 337188.0 337364.0 45041.3 45054.3 45188.3 45206.0 45213.8 46140.3 46251.5 46415.0 46589.0 46761.3 14321.4 14640.5 14700.1 14706.6 14733.7 12617.5 12696.6 12760.6 12761.7 12785.9 11927.2 12007.2 12029.9 12071.8 12147.5 5065.5 5199.5 5770.0 5812.3 5826.9 1076.5 1076.7 1095.2 1128.9 1174.2 24614.4 26820.0 27098.9 27268.6 27450.0 902.5 989.7 1169.9 1177.9 1236.8 5939.9 8496.8 12812.0 14218.6 14445.7 508507.0 517440.0 517528.0 517955.4 518327.0 39854.8 40277.8 47712.3 47842.3 47853.5 1466.7 1490.6 1492.2 1503.8 1512.5 23915.0 26746.7 27340.6 27566.0 27865.7 42135.6 42146.6 42263.4 42298.0 42363.4 492928.0 495963.0 497337.0 497570.0 498133.5 28997.3 29173.0 29236.1 29273.3 29710.4 37911.6 37923.1 38328.2 38589.8 38642.6 26761.7 26875.7 26965.6 27290.5 28054.4 27045.8 27357.5 27391.9 27738.4 28138.0 31755.5 31771.9 32273.7 32396.5 32441.2 23819.0 26860.5 27239.9 27346.9 28075.9 21069.3 22536.9 22547.5 22571.3 22667.8 13:46:13 INFO - PROCESS | 1644 | 13:46:13 INFO - PROCESS | 1644 | [#2] jquery.html Cycles:180 Average:41251.43 Median:22307.17 stddev:112719.30 (505.3%) stddev-sans-first:112993.70 13:46:13 INFO - PROCESS | 1644 | Values: 368.3 403.9 439.0 449.2 454.6 20046.5 21107.0 22158.0 22281.2 22289.8 21020.5 23439.8 23624.3 23826.7 24126.6 6798.4 6916.2 7159.9 7183.3 7198.8 6030.4 7059.9 7063.3 7071.0 7080.6 7017.3 7027.7 7398.5 7747.9 7844.9 7572.0 7762.7 7778.5 7807.2 7839.7 6473.7 7551.2 7576.7 7607.8 7615.8 21211.6 22339.0 25818.9 25920.1 27096.2 3049.0 3312.8 3386.8 3451.8 3485.9 16378.9 16398.1 16418.3 16426.0 16430.4 2113.9 2196.1 2274.5 2297.9 2301.4 156350.0 164511.0 165574.0 165671.0 165672.3 21167.5 21197.2 21400.5 21475.0 21648.4 27229.9 32995.3 33104.5 33168.0 33235.3 10791.9 10795.1 10807.6 11018.3 11138.4 7448.6 9139.5 9150.1 9292.3 9292.7 9605.4 9773.5 9830.0 9919.7 9940.9 4199.3 4263.9 4278.8 4288.1 4291.8 1043.4 1047.4 1057.5 1082.1 1103.8 21395.4 21718.6 21744.0 21748.8 22324.6 907.4 1059.8 1077.3 1095.5 1104.8 9288.8 10864.9 11266.8 11336.5 11414.1 666290.0 667696.0 668109.0 672306.0 672814.0 152067.2 152899.5 154433.0 155230.0 161838.0 1265.4 1272.1 1291.5 1298.2 1300.8 18286.3 18410.8 18665.5 18677.1 18966.1 57535.0 62925.7 63062.7 64589.7 64744.0 84701.2 85269.1 86022.6 86400.6 86426.6 11317.8 11334.7 11797.0 11921.2 11982.5 12720.9 12794.6 13172.9 13232.3 13309.0 8946.0 11056.7 11368.7 11619.7 11638.2 9212.1 10071.7 10540.7 10557.5 10820.0 11511.5 11574.5 11783.5 11783.7 11930.5 9401.6 11408.5 11607.1 11718.0 12035.5 8591.4 9305.0 10071.8 10158.4 10295.9 13:46:13 INFO - PROCESS | 1644 | 13:46:13 INFO - PROCESS | 1644 | [#3] mootools.html Cycles:180 Average:6871.79 Median:2503.12 stddev:4301.70 (171.9%) stddev-sans-first:4295.26 13:46:13 INFO - PROCESS | 1644 | Values: 1561.4 1564.1 1604.6 1608.5 1611.7 9760.7 10022.1 10985.9 11601.9 11639.0 10889.2 11215.3 11223.6 11234.3 11246.3 2467.6 2488.4 2502.8 2503.4 2507.3 2461.8 2495.6 2500.0 2506.2 2512.7 2140.9 2465.3 2478.3 2482.3 2494.1 2448.1 2456.4 2456.5 2463.5 2466.1 2129.1 2147.5 2148.4 2149.3 2154.9 9833.9 11467.4 11571.5 11571.9 11586.6 4562.6 5966.1 6092.5 6144.9 6156.3 3683.7 3756.9 3764.8 3809.4 3837.5 1237.7 1244.7 1264.4 1290.0 1294.4 16024.2 16031.0 16073.4 16073.4 16122.8 4434.4 5053.8 5071.8 5109.2 5184.1 13436.7 13446.2 13464.9 13471.9 13485.0 6133.2 6191.3 6192.3 6196.6 6206.4 4514.7 5044.2 5508.4 5513.9 5515.4 4103.4 4203.0 4217.2 4262.2 4266.8 1189.9 1191.8 1198.6 1209.4 1213.5 963.7 969.9 988.0 996.0 998.6 6237.5 6407.6 6531.1 6627.6 6850.5 1000.6 1027.7 1050.6 1062.8 1067.4 4145.0 4223.1 4236.0 4344.7 4363.7 11404.5 13219.8 13837.1 13932.6 13945.8 13728.8 13737.7 13804.7 13832.9 13835.4 8816.3 9238.9 9728.0 10268.0 10374.1 6540.0 6598.8 6647.4 6669.9 6710.6 10072.5 12315.7 12411.2 12454.9 12593.0 11411.3 12535.4 12817.9 12850.2 13187.2 8417.7 8836.9 9345.5 9374.1 9403.6 10457.3 10464.7 10532.7 10560.0 10633.3 9021.7 9080.0 9097.8 9254.0 9260.9 8802.9 8856.2 8868.0 8948.3 9057.7 6762.9 8036.4 8325.4 8390.3 8414.5 5938.1 5946.8 5962.1 6052.0 6128.8 6005.8 6080.3 6124.1 6183.2 6197.9 13:46:13 INFO - PROCESS | 1644 | 13:46:13 INFO - PROCESS | 1644 | [#4] prototype.html Cycles:180 Average:4776.50 Median:5153.69 stddev:1284.75 (24.9%) stddev-sans-first:1244.36 13:46:13 INFO - PROCESS | 1644 | Values: 335.2 381.1 396.7 401.3 405.4 4501.5 4852.7 5249.4 5376.4 5481.0 4516.8 4539.7 4544.3 5215.0 5347.7 4435.5 4591.7 4615.3 4630.4 4649.3 3933.9 4022.7 4362.9 4383.3 4725.3 3970.6 4043.1 4387.8 4590.5 4661.5 4258.1 4523.5 4580.6 4605.2 4644.4 3562.4 4055.7 4392.8 4406.7 4430.0 4395.9 5077.4 5110.6 5147.7 5158.5 4402.3 5107.3 5310.8 5404.4 5418.0 5455.4 5700.0 5731.5 5745.9 5811.7 4834.4 4857.3 4857.9 4873.6 4960.9 5197.7 5800.3 5857.4 5904.6 6035.3 4158.6 4671.8 4963.3 4983.7 5084.8 4645.1 4760.5 5009.0 5146.0 5146.3 5026.7 5033.6 5190.4 5204.3 5348.9 4936.7 5558.5 5750.8 5770.7 5815.4 1557.2 1757.9 1782.4 1786.0 1796.1 1330.6 1370.4 1379.4 1392.2 1416.5 4586.3 5134.6 5261.7 5380.9 5409.4 5565.6 5666.7 5872.5 5964.3 5972.9 4482.7 5147.9 5368.0 5381.5 5397.5 2614.7 2749.6 2822.1 2933.3 3053.6 5295.3 5816.7 6017.3 6244.3 6277.7 5208.9 5237.5 5443.4 5620.9 5767.3 5178.6 5252.8 5776.2 6059.8 6251.6 5427.4 5496.6 5537.8 5559.6 6008.9 4923.0 5108.9 5335.0 5505.5 5678.3 4859.0 5380.6 5394.9 5604.9 6118.3 4189.6 4211.8 4347.8 4560.0 4702.9 4906.0 5338.2 5575.7 5820.4 5922.0 5148.8 5180.5 5619.9 5754.4 5828.5 5272.6 5278.2 5463.6 5500.9 5722.4 5376.3 5447.6 5591.4 5599.3 5702.9 5773.9 5812.0 5820.5 5843.7 5880.8 4666.4 4854.8 4858.0 5425.4 5682.3 13:46:13 INFO - PROCESS | 1644 | 13:46:13 INFO - PROCESS | 1644 | [#5] yui.html Cycles:180 Average:21163.26 Median:17243.46 stddev:30414.17 (176.4%) stddev-sans-first:30460.78 13:46:13 INFO - PROCESS | 1644 | Values: 726.9 771.1 805.2 872.6 883.4 15879.7 16812.4 17386.9 17637.2 17686.3 16005.8 16265.1 16364.4 16401.4 16562.3 989.2 1012.1 1012.6 1015.3 1017.0 939.8 1029.9 1044.7 1049.9 1050.2 961.4 1083.1 1083.4 1084.6 1084.9 1077.7 1079.4 1081.0 1081.4 1081.5 798.1 798.5 798.6 799.0 800.0 16681.7 17048.9 17100.0 17662.3 17802.4 14562.3 14569.5 14578.9 14615.6 14704.0 12025.6 12067.8 12226.5 12272.9 12350.5 3855.8 3873.8 3906.0 3970.7 3983.6 114869.7 121520.4 123071.0 123952.0 124589.8 11411.6 11501.7 11509.7 11558.2 11755.8 23587.4 23899.9 23902.9 23938.3 24090.9 8149.6 8217.8 8251.3 8255.9 8376.7 3686.7 4004.9 4591.9 4595.4 4835.6 3661.2 3709.6 3748.4 3753.5 3791.9 670.9 671.9 678.9 681.1 692.0 639.0 723.2 732.7 741.7 764.8 8938.7 10005.5 10738.3 10980.7 11024.8 715.9 752.7 754.0 758.3 768.0 4678.9 5207.4 5214.8 5219.5 5222.0 82132.6 96983.0 101223.5 101952.5 103134.0 91195.0 94277.0 96326.0 97023.0 97199.0 63019.7 78977.0 79833.6 82173.0 84300.2 8136.8 9956.8 10042.6 10052.1 10058.0 46431.0 48039.8 54544.0 54665.5 55888.2 54122.5 58191.0 61458.7 61559.0 62819.7 13314.5 13498.5 13531.8 13534.9 13828.7 15223.7 15620.7 15850.8 15972.4 16200.0 14022.8 14249.0 14290.9 14362.2 14569.6 12143.3 13656.9 13955.2 14060.5 14606.1 13448.2 13616.4 13989.4 14048.6 14320.9 9822.0 10106.6 10202.5 10341.5 10403.9 10038.6 11408.3 11526.0 11656.7 11712.0 13:46:13 INFO - PROCESS | 1644 | -------- Summary: end -------- 13:46:13 INFO - PROCESS | 1644 | 13:46:14 INFO - TEST-INFO | 1644: exit 0 13:46:14 INFO - TEST-OK | dromaeo_css | took 1115657ms 13:46:14 INFO - TEST-START | kraken 13:46:14 INFO - operating with platform_type : mac_ 13:46:14 INFO - Initialising browser for kraken test... 13:46:14 INFO - TEST-INFO | started process 1653 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/T/tmpqgm_Pc/profile http://localhost:49219/getInfo.html) 13:46:16 INFO - PROCESS | 1653 | 2017-06-01 13:46:16.903 plugin-container[1655:10531] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4343, name = 'com.apple.tsm.portname' 13:46:16 INFO - PROCESS | 1653 | See /usr/include/servers/bootstrap_defs.h for the error codes. 13:46:16 INFO - PROCESS | 1653 | 2017-06-01 13:46:16.904 plugin-container[1655:10531] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4617, name = 'com.apple.CFPasteboardClient' 13:46:16 INFO - PROCESS | 1653 | See /usr/include/servers/bootstrap_defs.h for the error codes. 13:46:16 INFO - PROCESS | 1653 | 2017-06-01 13:46:16.904 plugin-container[1655:10531] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 13:46:16 INFO - PROCESS | 1653 | __metrics Screen width/height:1600/1200 13:46:16 INFO - PROCESS | 1653 | colorDepth:24 13:46:16 INFO - PROCESS | 1653 | Browser inner width/height: 1024/689 13:46:16 INFO - PROCESS | 1653 | __metrics 13:46:17 ERROR - PROCESS | 1653 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3381: TypeError: this.tabs is undefined 13:46:17 INFO - TEST-INFO | 1653: exit 0 13:46:17 INFO - Browser initialized. 13:46:17 INFO - Running cycle 1/1 for kraken test... 13:46:17 INFO - TEST-INFO | started process 1656 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/T/tmpqgm_Pc/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 13:46:19 INFO - PROCESS | 1656 | 2017-06-01 13:46:19.827 plugin-container[1657:10653] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4647, name = 'com.apple.tsm.portname' 13:46:19 INFO - PROCESS | 1656 | See /usr/include/servers/bootstrap_defs.h for the error codes. 13:46:19 INFO - PROCESS | 1656 | 2017-06-01 13:46:19.828 plugin-container[1657:10653] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8b0b, name = 'com.apple.CFPasteboardClient' 13:46:19 INFO - PROCESS | 1656 | See /usr/include/servers/bootstrap_defs.h for the error codes. 13:46:19 INFO - PROCESS | 1656 | 2017-06-01 13:46:19.828 plugin-container[1657:10653] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 13:46:29 INFO - PROCESS | 1656 | RSS: Main: 174297088 13:46:29 INFO - PROCESS | 1656 | 13:46:32 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49219/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49219/tests/kraken/driver.html?testName=audio-beat-detection) 13:46:33 INFO - PROCESS | 1656 | RSS: Main: 187559936 13:46:33 INFO - PROCESS | 1656 | 13:46:35 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49219/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49219/tests/kraken/driver.html?testName=audio-dft) 13:46:36 INFO - PROCESS | 1656 | RSS: Main: 195530752 13:46:36 INFO - PROCESS | 1656 | 13:46:39 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49219/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49219/tests/kraken/driver.html?testName=audio-fft) 13:46:40 INFO - PROCESS | 1656 | RSS: Main: 199208960 13:46:40 INFO - PROCESS | 1656 | 13:46:42 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49219/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49219/tests/kraken/driver.html?testName=audio-oscillator) 13:46:42 INFO - PROCESS | 1656 | RSS: Main: 200867840 13:46:42 INFO - PROCESS | 1656 | 13:46:44 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49219/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49219/tests/kraken/driver.html?testName=imaging-gaussian-blur) 13:46:45 INFO - PROCESS | 1656 | RSS: Main: 201035776 13:46:45 INFO - PROCESS | 1656 | 13:46:48 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49219/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49219/tests/kraken/driver.html?testName=imaging-darkroom) 13:46:49 INFO - PROCESS | 1656 | RSS: Main: 205082624 13:46:49 INFO - PROCESS | 1656 | 13:46:51 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49219/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49219/tests/kraken/driver.html?testName=imaging-desaturate) 13:46:52 INFO - PROCESS | 1656 | RSS: Main: 205463552 13:46:52 INFO - PROCESS | 1656 | 13:46:55 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49219/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49219/tests/kraken/driver.html?testName=json-parse-financial) 13:46:56 INFO - PROCESS | 1656 | RSS: Main: 205017088 13:46:56 INFO - PROCESS | 1656 | 13:46:58 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49219/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49219/tests/kraken/driver.html?testName=json-stringify-tinderbox) 13:46:58 INFO - PROCESS | 1656 | RSS: Main: 204447744 13:46:58 INFO - PROCESS | 1656 | 13:47:00 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49219/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49219/tests/kraken/driver.html?testName=stanford-crypto-aes) 13:47:01 INFO - PROCESS | 1656 | RSS: Main: 204468224 13:47:01 INFO - PROCESS | 1656 | 13:47:03 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49219/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49219/tests/kraken/driver.html?testName=stanford-crypto-ccm) 13:47:04 INFO - PROCESS | 1656 | RSS: Main: 204435456 13:47:04 INFO - PROCESS | 1656 | 13:47:07 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49219/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49219/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 13:47:07 INFO - PROCESS | 1656 | RSS: Main: 204787712 13:47:07 INFO - PROCESS | 1656 | 13:47:10 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49219/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49219/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 13:47:11 INFO - PROCESS | 1656 | RSS: Main: 204873728 13:47:11 INFO - PROCESS | 1656 | 13:47:13 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49219/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49219/tests/kraken/driver.html?testName=ai-astar) 13:47:13 INFO - PROCESS | 1656 | RSS: Main: 206639104 13:47:13 INFO - PROCESS | 1656 | 13:47:13 INFO - PROCESS | 1656 | __start_tp_report 13:47:13 INFO - PROCESS | 1656 | _x_x_mozilla_page_load 13:47:13 INFO - PROCESS | 1656 | _x_x_mozilla_page_load_details 13:47:13 INFO - PROCESS | 1656 | |i|pagename|runs| 13:47:13 INFO - PROCESS | 1656 | |0;ai-astar;108;121;119;121;120;120;119;121;120;121 13:47:13 INFO - PROCESS | 1656 | |1;audio-beat-detection;138;110;115;145;115;109;110;110;134;112 13:47:13 INFO - PROCESS | 1656 | |2;audio-dft;201;197;198;200;195;195;195;197;200;200 13:47:13 INFO - PROCESS | 1656 | |3;audio-fft;81;61;60;88;64;62;75;50;88;77 13:47:13 INFO - PROCESS | 1656 | |4;audio-oscillator;91;69;113;98;89;69;81;74;69;76 13:47:13 INFO - PROCESS | 1656 | |5;imaging-gaussian-blur;92;93;91;84;92;81;134;115;94;91 13:47:13 INFO - PROCESS | 1656 | |6;imaging-darkroom;105;98;96;98;97;97;99;96;96;97 13:47:13 INFO - PROCESS | 1656 | |7;imaging-desaturate;147;141;91;97;92;111;90;93;98;90 13:47:13 INFO - PROCESS | 1656 | |8;json-parse-financial;65;63;66;72;97;67;66;69;68;67 13:47:13 INFO - PROCESS | 1656 | |9;json-stringify-tinderbox;58;64;78;82;88;92;53;76;79;83 13:47:13 INFO - PROCESS | 1656 | |10;stanford-crypto-aes;84;87;83;80;84;83;87;86;91;76 13:47:13 INFO - PROCESS | 1656 | |11;stanford-crypto-ccm;182;147;141;127;140;142;146;148;188;180 13:47:13 INFO - PROCESS | 1656 | |12;stanford-crypto-pbkdf2;166;165;165;166;167;165;165;161;168;200 13:47:13 INFO - PROCESS | 1656 | |13;stanford-crypto-sha256-iterative;66;64;71;74;80;82;83;83;65;63 13:47:13 INFO - PROCESS | 1656 | __end_tp_report 13:47:13 INFO - PROCESS | 1656 | __start_cc_report 13:47:13 INFO - PROCESS | 1656 | _x_x_mozilla_cycle_collect,1011 13:47:13 INFO - PROCESS | 1656 | __end_cc_report 13:47:13 INFO - PROCESS | 1656 | __startTimestamp1496350033304__endTimestamp 13:47:13 INFO - PROCESS | 1656 | 13:47:13 INFO - PROCESS | 1656 | ------- Summary: start ------- 13:47:13 INFO - PROCESS | 1656 | Number of tests: 14 13:47:13 INFO - PROCESS | 1656 | 13:47:13 INFO - PROCESS | 1656 | [#0] ai-astar Cycles:10 Average:119.00 Median:120.50 stddev:3.94 (3.3%) stddev-sans-first:0.83 13:47:13 INFO - PROCESS | 1656 | Values: 108.0 121.0 119.0 121.0 120.0 120.0 119.0 121.0 120.0 121.0 13:47:13 INFO - PROCESS | 1656 | 13:47:13 INFO - PROCESS | 1656 | [#1] audio-beat-detection Cycles:10 Average:119.80 Median:115.00 stddev:13.66 (11.9%) stddev-sans-first:12.80 13:47:13 INFO - PROCESS | 1656 | Values: 138.0 110.0 115.0 145.0 115.0 109.0 110.0 110.0 134.0 112.0 13:47:13 INFO - PROCESS | 1656 | 13:47:13 INFO - PROCESS | 1656 | [#2] audio-dft Cycles:10 Average:197.80 Median:199.00 stddev:2.35 (1.2%) stddev-sans-first:2.19 13:47:13 INFO - PROCESS | 1656 | Values: 201.0 197.0 198.0 200.0 195.0 195.0 195.0 197.0 200.0 200.0 13:47:13 INFO - PROCESS | 1656 | 13:47:13 INFO - PROCESS | 1656 | [#3] audio-fft Cycles:10 Average:70.60 Median:76.00 stddev:13.00 (17.1%) stddev-sans-first:13.23 13:47:13 INFO - PROCESS | 1656 | Values: 81.0 61.0 60.0 88.0 64.0 62.0 75.0 50.0 88.0 77.0 13:47:13 INFO - PROCESS | 1656 | 13:47:13 INFO - PROCESS | 1656 | [#4] audio-oscillator Cycles:10 Average:82.90 Median:78.50 stddev:14.71 (18.7%) stddev-sans-first:15.31 13:47:13 INFO - PROCESS | 1656 | Values: 91.0 69.0 113.0 98.0 89.0 69.0 81.0 74.0 69.0 76.0 13:47:13 INFO - PROCESS | 1656 | 13:47:13 INFO - PROCESS | 1656 | [#5] imaging-gaussian-blur Cycles:10 Average:96.70 Median:91.50 stddev:15.86 (17.3%) stddev-sans-first:16.73 13:47:13 INFO - PROCESS | 1656 | Values: 92.0 93.0 91.0 84.0 92.0 81.0 134.0 115.0 94.0 91.0 13:47:13 INFO - PROCESS | 1656 | 13:47:13 INFO - PROCESS | 1656 | [#6] imaging-darkroom Cycles:10 Average:97.90 Median:97.00 stddev:2.69 (2.8%) stddev-sans-first:1.05 13:47:13 INFO - PROCESS | 1656 | Values: 105.0 98.0 96.0 98.0 97.0 97.0 99.0 96.0 96.0 97.0 13:47:13 INFO - PROCESS | 1656 | 13:47:13 INFO - PROCESS | 1656 | [#7] imaging-desaturate Cycles:10 Average:105.00 Median:91.50 stddev:21.52 (23.5%) stddev-sans-first:16.61 13:47:13 INFO - PROCESS | 1656 | Values: 147.0 141.0 91.0 97.0 92.0 111.0 90.0 93.0 98.0 90.0 13:47:13 INFO - PROCESS | 1656 | 13:47:13 INFO - PROCESS | 1656 | [#8] json-parse-financial Cycles:10 Average:70.00 Median:67.50 stddev:9.79 (14.5%) stddev-sans-first:10.21 13:47:13 INFO - PROCESS | 1656 | Values: 65.0 63.0 66.0 72.0 97.0 67.0 66.0 69.0 68.0 67.0 13:47:13 INFO - PROCESS | 1656 | 13:47:13 INFO - PROCESS | 1656 | [#9] json-stringify-tinderbox Cycles:10 Average:75.30 Median:80.50 stddev:12.87 (16.0%) stddev-sans-first:12.03 13:47:13 INFO - PROCESS | 1656 | Values: 58.0 64.0 78.0 82.0 88.0 92.0 53.0 76.0 79.0 83.0 13:47:13 INFO - PROCESS | 1656 | 13:47:13 INFO - PROCESS | 1656 | [#10] stanford-crypto-aes Cycles:10 Average:84.10 Median:85.00 stddev:4.12 (4.8%) stddev-sans-first:4.37 13:47:13 INFO - PROCESS | 1656 | Values: 84.0 87.0 83.0 80.0 84.0 83.0 87.0 86.0 91.0 76.0 13:47:13 INFO - PROCESS | 1656 | 13:47:13 INFO - PROCESS | 1656 | [#11] stanford-crypto-ccm Cycles:10 Average:154.10 Median:147.50 stddev:21.09 (14.3%) stddev-sans-first:19.81 13:47:13 INFO - PROCESS | 1656 | Values: 182.0 147.0 141.0 127.0 140.0 142.0 146.0 148.0 188.0 180.0 13:47:13 INFO - PROCESS | 1656 | 13:47:13 INFO - PROCESS | 1656 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:168.80 Median:166.00 stddev:11.11 (6.7%) stddev-sans-first:11.74 13:47:13 INFO - PROCESS | 1656 | Values: 166.0 165.0 165.0 166.0 167.0 165.0 165.0 161.0 168.0 200.0 13:47:13 INFO - PROCESS | 1656 | 13:47:13 INFO - PROCESS | 1656 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:73.10 Median:77.00 stddev:8.36 (10.9%) stddev-sans-first:8.46 13:47:13 INFO - PROCESS | 1656 | Values: 66.0 64.0 71.0 74.0 80.0 82.0 83.0 83.0 65.0 63.0 13:47:13 INFO - PROCESS | 1656 | -------- Summary: end -------- 13:47:13 INFO - PROCESS | 1656 | 13:47:14 INFO - TEST-INFO | 1656: exit 0 13:47:14 INFO - TEST-OK | kraken | took 59720ms 13:47:14 INFO - SUITE-END | took 1175s 13:47:14 INFO - Completed test suite (00:19:35) 13:47:14 INFO - javascript benchmark 13:47:14 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7890.683834301458, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3070.692930706928, 3585.0678823505896, 3637.618050104739, 3641.435577485489, 3661.340958627446, 6196.442320191182, 6196.59722118062, 6205.844853878655, 6250.249970003594, 6272.6863656817095, 6143.477804386633, 6166.722497487613, 6168.784092556762, 6190.907136392958, 6192.50459963203, 5555.944370569599, 5603.327600687903, 5634.436556344353, 5647.237622920915, 5706.686132262724, 5665.320161580596, 5682.26130603021, 5703.116017017369, 5704.172894930253, 5706.828795136132, 5455.1544510600725, 5460.863478413032, 5679.602427830054, 5701.971490142564, 5725.6278341907855, 5573.832785016436, 5575.5539556835765, 5581.162825576155, 5598.916016259759, 5613.4386561343945, 5464.508194262496, 5596.244506991586, 5596.664200148004, 5613.494785469327, 5638.633488823213, 5374.973125134388, 5547.77808887644, 6184.81445556634, 6266.780662676784, 6277.96861015692, 6307.24313082433, 6346.460550853183, 6366.713497892606, 6380.840478988062, 6393.648349340784, 6589.93410065898, 6616.0, 6643.335666433366, 6648.900266496006, 6667.366600172976, 6251.156093927352, 6282.0, 6317.46301564363, 6344.714487848058, 6350.301466838644, 5809.448102430262, 6436.935630643634, 6515.348465153446, 6522.119513865568, 6722.495812814059, 5406.216098665672, 6293.527985401113, 6325.9051114233325, 6366.267879193952, 6370.617762934239, 6559.934400656028, 6560.311167327457, 6563.901541476882, 6580.0, 6598.7030583623855, 6252.874942501125, 6488.643124628191, 6490.286068532458, 6500.089987401768, 6529.738810447624, 6226.875462490725, 6334.461570766444, 6368.044793280952, 6390.201224846895, 6511.446527045254, 5230.529252367305, 5269.314989051475, 5294.708791016454, 5302.549283310962, 5337.519623233928, 4181.958180418218, 4667.696599721007, 4675.672702910763, 4697.4832768395445, 4720.575148236675, 6122.724477398528, 6165.445109940127, 6318.968405157945, 6360.713767880364, 6362.331955144736, 6057.57596968208, 6393.328700486475, 6428.517861160338, 6444.065610486547, 6493.415592596594, 6203.2245969253845, 6208.099825525273, 6213.192285003011, 6223.502119830403, 6230.221222347207, 5729.28383952006, 5757.510611598061, 5791.160281759206, 5808.738606762706, 5828.746998374349, 6579.111819904251, 6608.50436217276, 6762.966185169043, 6773.966130169318, 6893.862122757417, 6665.566738162004, 6672.266050734318, 6726.058351830846, 6729.360710732472, 6732.697028633625, 6847.897281540681, 6890.724371025103, 6891.620960847202, 6900.102986611709, 6903.1716194057035, 5351.384590772111, 6255.155554000152, 6300.558960872695, 6446.4520515756685, 6450.451711604566, 6647.900281495685, 6660.267370589135, 6663.033860090357, 6679.0, 6773.458123350219, 5079.847604571869, 6229.968850155901, 6457.773977910751, 6473.676316184078, 6506.577072490273, 5813.273340832396, 6246.812595622138, 6256.5620406572925, 6485.870282594227, 6492.6104433732235, 6036.124761909411, 6168.845778855564, 6230.875382492234, 6245.406686364788, 6256.499480041494, 5273.683578985273, 5346.465353464622, 6197.845053873509, 6295.622262664255, 6300.338464461349, 5207.557357624644, 6209.441150296495, 6225.533085018551, 6255.906161407674, 6256.061590761422], "value": 6000.018280398309, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [779.5283853268772, 804.2962407893093, 846.6571038729317, 983.6901376066543, 986.8914419413868, 39374.40938385923, 40024.599754002455, 40984.79507602458, 41094.38358424626, 41145.7942710286, 38927.026824329405, 39044.21911561767, 39073.21853562927, 39140.80429597848, 39269.0, 8288.502689838613, 8427.0, 11138.053265472445, 11345.546178152905, 11499.540018399253, 11188.38463884486, 11249.943750281238, 11307.830382544269, 11311.264767790068, 11326.0, 11197.27217730849, 11274.323540587591, 11291.887081129207, 11321.943390283079, 11355.432228388589, 11223.66329010131, 11277.830832537518, 11289.548418063268, 11292.774144517063, 11293.435328233596, 10983.945080274547, 10993.780124397466, 10998.175136864767, 11033.117350611932, 11039.282446641024, 34510.482342764626, 34606.0, 34621.0, 34650.61397544095, 34748.47877281843, 3959.8614048508166, 3985.52530579285, 4001.5998400160042, 4030.435738996543, 4061.0, 25772.097976570916, 26408.81160347789, 27014.0, 27491.587626185625, 27662.585061224097, 3173.5239714042846, 3225.854836532362, 3249.8050116992817, 3260.983695081533, 3283.5731354923946, 332857.0, 336076.0, 337090.0, 337188.0, 337364.0, 45041.32438013433, 45054.32418513725, 45188.3221751674, 45206.0, 45213.773931130134, 46140.30789538159, 46251.5374846254, 46415.0, 46589.0, 46761.29858052132, 14321.427142914376, 14640.487582934546, 14700.117992920459, 14706.55880323592, 14733.705325893423, 12617.49530018789, 12696.555620553236, 12760.553380631633, 12761.744765104831, 12785.872141278469, 11927.224730392496, 12007.219530730474, 12029.879701202875, 12071.758564828655, 12147.5140994361, 5065.544101030851, 5199.466157483552, 5769.9711501442225, 5812.273465816773, 5826.92201942642, 1076.531708706715, 1076.6608518316705, 1095.2114477576133, 1128.8645362556547, 1174.213277104342, 24614.384640384134, 26820.0, 27098.86450567774, 27268.590971135447, 27450.0, 902.5081330674798, 989.6833013435776, 1169.923954942926, 1177.9469923853446, 1236.7959286717592, 5939.881202375928, 8496.830063398698, 12812.0, 14218.644533886736, 14445.711085778226, 508507.0, 517440.0, 517528.0, 517955.4102229465, 518327.0, 39854.80072599676, 40277.79861100676, 47712.28431573529, 47842.28236576454, 47853.521464785605, 1466.65533599604, 1490.5975386645546, 1492.2091291615461, 1503.812023497063, 1512.5235550801463, 23915.0, 26746.732532674425, 27340.589891151252, 27566.0, 27865.721342787125, 42135.578644213165, 42146.57853421549, 42263.366049509896, 42298.0, 42363.3645495324, 492928.0, 495963.0, 497337.0266297291, 497570.0, 498133.50933245104, 28997.275068123465, 29172.978945737646, 29236.122916312546, 29273.26816829511, 29710.405791884474, 37911.62088379081, 37923.05192370213, 38328.2334353317, 38589.80705096456, 38642.6135738639, 26761.732382675924, 26875.73124268732, 26965.595516066882, 27290.45419091647, 28054.43891122207, 27045.782939767363, 27357.452850943268, 27391.863040684668, 27738.44523109567, 28138.01516946897, 31755.52366714455, 31771.887983920453, 32273.677263228008, 32396.514052289705, 32441.188970275933, 23819.0, 26860.544996565055, 27239.86380068087, 27346.86326568434, 28075.859620701765, 21069.262575809775, 22536.887315563315, 22547.51025944425, 22571.322860314216, 22667.773322267225], "value": 18269.910522822986, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [368.3351550451434, 403.85780359048806, 438.96927215094945, 449.1780042522185, 454.5931391404691, 20046.49883752907, 21107.0, 22158.0, 22281.220157294498, 22289.777102228974, 21020.474488137803, 23439.76560234397, 23624.346295759304, 23826.689532075772, 24126.638100428507, 6798.388145066943, 6916.23921368649, 7159.856802863939, 7183.281671832801, 7198.7840364789145, 6030.42710942462, 7059.85880282394, 7063.258357872426, 7070.964645176767, 7080.575165490061, 7017.26318736533, 7027.718891244344, 7398.482106252564, 7747.883781743279, 7844.882326765074, 7572.0, 7762.728304509372, 7778.494397864149, 7807.180246074137, 7839.6864125435195, 6473.74105035798, 7551.24487551246, 7576.696932122709, 7607.771766847003, 7615.771526854203, 21211.575768484698, 22339.0, 25818.87090564554, 25920.09279675221, 27096.1871143866, 3049.0, 3312.7681062325646, 3386.7798593091616, 3451.7756345837693, 3485.89542313731, 16378.918105409397, 16398.098104604243, 16418.343266269334, 16426.0, 16430.424935127212, 2113.9154433822628, 2196.0666716645524, 2274.5450909817937, 2297.9425514362106, 2301.367124040902, 156349.99999999886, 164511.0, 165574.0, 165671.0, 165672.34327656688, 21167.470813229793, 21197.152113915578, 21400.464988375104, 21475.0, 21648.35054948354, 27229.86385068062, 32995.34009319848, 33104.50343244853, 33168.0, 33235.33529329448, 10791.892081079246, 10795.082417994558, 10807.567697292021, 11018.338899665885, 11138.38493418466, 7448.627568621548, 9139.49732764691, 9150.084991500797, 9292.349535532448, 9292.674756383494, 9605.37565058283, 9773.462459564793, 9830.0, 9919.702408927744, 9940.900590993997, 4199.307114326163, 4263.87208383749, 4278.807453664593, 4288.142371525707, 4291.828326866953, 1043.3948309980194, 1047.418682631147, 1057.471264367816, 1082.0910435234441, 1103.7626910214342, 21395.358139255848, 21718.565628687338, 21744.02151903168, 21748.782512174992, 22324.553508930054, 907.419251678926, 1059.8463222832645, 1077.3266708307308, 1095.5289225633055, 1104.8342748587777, 9288.814223715488, 10864.945675271572, 11266.830997535044, 11336.489857956412, 11414.086873050135, 666290.0, 667696.0, 668109.0, 672306.0, 672814.0, 152067.2396638032, 152899.47100528853, 154433.0, 155230.0, 161838.0, 1265.4495294546903, 1272.1476610670873, 1291.5027714330051, 1298.1561984710015, 1300.8243887075314, 18286.268549257882, 18410.815891841445, 18665.53336166607, 18677.14001608961, 18966.05169741535, 57535.0, 62925.68537157285, 63062.68468657627, 64589.67705161444, 64744.0, 84701.15298846933, 85269.14730852612, 86022.56988715017, 86400.56799715961, 86426.56786716027, 11317.830232546683, 11334.659960201207, 11796.997255233402, 11921.225120367148, 11982.460789264329, 12720.87279127197, 12794.616161515169, 13172.934135329646, 13232.345956755405, 13309.00182486298, 8946.015938246917, 11056.723581910517, 11368.658940231806, 11619.6514104577, 11638.243514171552, 9212.124848139416, 10071.748206294902, 10540.683779486628, 10557.472126393495, 10820.02619764225, 11511.53953841837, 11574.537018519502, 11783.469743861719, 11783.705407364885, 11930.522779088738, 9401.576929038347, 11408.543658253577, 11607.067334723197, 11718.0, 12035.518579256732, 8591.398602098043, 9304.976452590345, 10071.798564028824, 10158.441285729356, 10295.897041029493], "value": 11850.866565275286, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1561.3520389038547, 1564.100642130775, 1604.6229136153006, 1608.5174447665697, 1611.7421212605987, 9760.70717878463, 10022.148117410028, 10985.945070274658, 11601.941990290048, 11639.010684091852, 10889.183311251669, 11215.271007384536, 11223.551057957693, 11234.269772464784, 11246.268992515501, 2467.605183170693, 2488.35302821266, 2502.824802263833, 2503.4242124311418, 2507.260358194337, 2461.778439940405, 2495.6007038873786, 2500.037485568051, 2506.1729629222405, 2512.736162702917, 2140.9464763380884, 2465.3343597228736, 2478.3060742991993, 2482.2925466242123, 2494.1395218649536, 2448.1431498975394, 2456.3981824452944, 2456.5086982603552, 2463.4580392313665, 2466.075221791828, 2129.1270579062666, 2147.50607360308, 2148.366231961575, 2149.2692484555328, 2154.8686939356808, 9833.86910505294, 11467.36929468879, 11571.53713851445, 11571.94214028933, 11586.594469193538, 4562.612177964844, 5966.134910437962, 6092.5430592705725, 6144.8771024579255, 6156.29202641693, 3683.7421380503374, 3756.9436458453147, 3764.830582623761, 3809.4285857121376, 3837.501124853779, 1237.7462620162844, 1244.7199380139486, 1264.4373253902017, 1289.9680255795327, 1294.372229468707, 16024.198790060567, 16031.0, 16073.357065717475, 16073.437429690022, 16122.838771612369, 4434.401355816924, 5053.823116190915, 5071.771770270347, 5109.156989096758, 5184.092783762826, 13436.731265374834, 13446.193228406328, 13464.93267533656, 13471.932640336736, 13485.0, 6133.202683651186, 6191.318954914956, 6192.349803270592, 6196.628202307876, 6206.379362063848, 4514.6614003949835, 5044.243363495505, 5508.366537848118, 5513.862153446196, 5515.365732940763, 4103.4050062740735, 4203.0, 4217.156568686275, 4262.168877069003, 4266.786660666954, 1189.9048076153888, 1191.773563023023, 1198.574506050357, 1209.449700386322, 1213.538855235005, 963.6964356227766, 969.8836139663285, 987.9753006174759, 995.9542480395555, 998.6155330197781, 6237.532185086136, 6407.551471396958, 6531.0856480092825, 6627.602343859384, 6850.520463567602, 1000.5797565022708, 1027.701966429727, 1050.558765318568, 1062.8033813744482, 1067.412922892406, 4145.0, 4223.070924396628, 4236.004538933348, 4344.652427805768, 4363.650907927358, 11404.48679809394, 13219.801702974462, 13837.100588461717, 13932.582022539138, 13945.790813137812, 13728.794068088986, 13737.656558586115, 13804.723905521832, 13832.930835345758, 13835.446582136601, 8816.294696424271, 9238.90761092394, 9727.951360243154, 10268.0, 10374.118199952938, 6540.0, 6598.769043083469, 6647.368499992493, 6669.866602668017, 6710.563813352116, 10072.496375181065, 12315.69210769738, 12411.193272437262, 12454.875451245372, 12593.0, 11411.25826821254, 12535.373231338579, 12817.935910320388, 12850.164739291917, 13187.208767473981, 8417.747467575982, 8836.91163088361, 9345.532723363669, 9374.062593740571, 9403.576839042124, 10457.320274182153, 10464.686059418229, 10532.736681583021, 10559.99680030396, 10633.308834925705, 9021.684241051531, 9080.0, 9097.77255568616, 9253.953730231306, 9260.907390926004, 8802.867956980523, 8856.247218986458, 8867.955660221658, 8948.328875334246, 9057.68298109563, 6762.8985565217545, 8036.357091432555, 8325.417220794487, 8390.328773697967, 8414.45306055129, 5938.109283607493, 5946.762129514944, 5962.105684147239, 6052.03167493199, 6128.785492507741, 6005.819825405245, 6080.331163571915, 6124.112003759341, 6183.227096612924, 6197.938020619917], "value": 5267.59741363084, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [335.2339903320912, 381.1291199608894, 396.7282411548088, 401.29172011399885, 405.38584045171564, 4501.482329532105, 4852.733099679521, 5249.396319423267, 5376.381716102649, 5480.972595137018, 4516.8156449702965, 4539.682222244444, 4544.295634176708, 5215.0, 5347.7058761768085, 4435.536273029892, 4591.701539399945, 4615.284630882219, 4630.421197350332, 4649.349091127245, 3933.8819835404843, 4022.7184097113213, 4362.8909277268, 4383.254846676056, 4725.3384526166365, 3970.602939706035, 4043.0903046814524, 4387.802548885292, 4590.472095709003, 4661.533846615345, 4258.127083947783, 4523.525029871849, 4580.610648094899, 4605.194091034089, 4644.373009643688, 3562.3765840977726, 4055.702175303904, 4392.780360981938, 4406.713563618355, 4430.0, 4395.890102747457, 5077.385146649479, 5110.616703747197, 5147.742612869379, 5158.53573178412, 4402.273624851896, 5107.263530399645, 5310.8141215057285, 5404.405515393303, 5417.972910135424, 5455.427180146067, 5699.974004679156, 5731.512821410185, 5745.856353591152, 5811.6803575803315, 4834.444038935532, 4857.271409288635, 4857.854264372075, 4873.634477414208, 4960.933399319128, 5197.688138711652, 5800.303963524362, 5857.443542863463, 5904.586678932476, 6035.275766907999, 4158.584141585878, 4671.827600165939, 4963.280324352949, 4983.6760610560195, 5084.847454576368, 4645.094206629742, 4760.547747963938, 5009.0, 5146.044503088877, 5146.30524879144, 5026.698398096126, 5033.6224783141415, 5190.377154741455, 5204.271402003723, 5348.8662783429945, 4936.703797772145, 5558.499735023867, 5750.798722044792, 5770.701592141734, 5815.41845815415, 1557.1506380329856, 1757.912104394775, 1782.4296225207809, 1786.0266154945587, 1796.119901248405, 1330.6207730796677, 1370.390176371505, 1379.3861731529573, 1392.1647011793045, 1416.46174453709, 4586.2661974083985, 5134.589232861362, 5261.71088083422, 5380.946190538045, 5409.43200963901, 5565.6104072714525, 5666.744996475169, 5872.531867033242, 5964.343922168558, 5972.85067873298, 4482.731036137842, 5147.897042059138, 5368.040665157189, 5381.515663590296, 5397.514223719884, 2614.6926536731635, 2749.5839642584087, 2822.096928982706, 2933.339998500363, 3053.6030316059214, 5295.311609490742, 5816.738246778905, 6017.338092809875, 6244.344343843922, 6277.748890044347, 5208.947910520922, 5237.528622424, 5443.401225865152, 5620.887582248414, 5767.279090113736, 5178.6116041297055, 5252.842414727565, 5776.249087618667, 6059.757609695651, 6251.624902505864, 5427.4301198373605, 5496.642718223303, 5537.8061767837935, 5559.610827242054, 6008.909866351921, 4922.950770492249, 5108.876047269595, 5335.039692855324, 5505.504504594605, 5678.290213723285, 4858.975705121593, 5380.569554435558, 5394.892102158014, 5604.915926261191, 6118.326984031842, 4189.581041895786, 4211.768352740629, 4347.782610869381, 4559.9740058487905, 4702.905941881075, 4905.975470122627, 5338.199270109515, 5575.665460072409, 5820.359760426434, 5921.963656360206, 5148.845534633967, 5180.533751962342, 5619.8760247951395, 5754.367019627922, 5828.475437210672, 5272.6309158358545, 5278.181881808326, 5463.562914966714, 5500.889982200253, 5722.397728635989, 5376.301080859464, 5447.618666693294, 5591.412901645269, 5599.300087489064, 5702.885942281214, 5773.884522309614, 5811.994861772681, 5820.505257053198, 5843.678597677169, 5880.8235752927485, 4666.393368862026, 4854.75726213695, 4858.0, 5425.376081750649, 5682.289713785777], "value": 4409.909717111352, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [726.905502284703, 771.090113665874, 805.178717707938, 872.5942436766903, 883.350737208152, 15879.68240635188, 16812.41156559521, 17386.913065434688, 17637.206325715342, 17686.292548298086, 16005.839941600581, 16265.105419201938, 16364.427245046425, 16401.42595009175, 16562.254698538563, 989.2135752077098, 1012.0941757127366, 1012.5899010900597, 1015.2690063154554, 1017.0490591297124, 939.8402271613842, 1029.907308342249, 1044.6918159143033, 1049.884512703602, 1050.159872102319, 961.418341903149, 1083.0685610375072, 1083.3662307550053, 1084.6312253833694, 1084.9294795838246, 1077.7359546911057, 1079.4494807648077, 1081.0054749630358, 1081.3836113415357, 1081.5457507846763, 798.0622768247287, 798.5129071266523, 798.6046906781122, 798.9520628762293, 799.9640016199285, 16681.749773753403, 17048.914755426267, 17100.0, 17662.293508259656, 17802.376916807978, 14562.271886405637, 14569.490067847575, 14578.854211457856, 14615.561533154023, 14703.97072204946, 12025.57910473138, 12067.758644827054, 12226.510939562408, 12272.938635306855, 12350.5059797608, 3855.768653880776, 3873.845046198149, 3905.9804700976597, 3970.7419017763755, 3983.6016398359934, 114869.70260594742, 121520.39239803744, 123071.0, 123952.0, 124589.75410245963, 11411.600593979338, 11501.654950351503, 11509.654710358702, 11558.248713833575, 11755.823662645067, 23587.41031474227, 23899.880500597734, 23902.88048559781, 23938.28185154448, 24090.879545602158, 8149.592520373958, 8217.79455513605, 8251.2986396157, 8255.876161857577, 8376.664933402717, 3686.668199862025, 4004.9199016019516, 4591.9081618367445, 4595.379623750818, 4835.637327200474, 3661.212839239523, 3709.5919448860604, 3748.400255959088, 3753.5495740511315, 3791.9241615167543, 670.8658268346351, 671.8622682350107, 678.8540463800307, 681.0839420978775, 691.9861602768017, 639.0159154901493, 723.1719680965371, 732.7325526182905, 741.6773703439021, 764.7820371194183, 8938.68714594986, 10005.549750261256, 10738.261678911595, 10980.670579882617, 11024.779504409868, 715.9463040272001, 752.6575408189261, 753.9773806785805, 758.2796343473722, 767.9884801727978, 4678.947236871677, 5207.4011488678525, 5214.817481388133, 5219.530242278137, 5221.973890130525, 82132.58933705294, 96983.0, 101223.49388253012, 101952.49023754834, 103134.0, 91195.0, 94277.0, 96326.0, 97023.0, 97199.02800972041, 63019.6849015752, 78977.0, 79833.60083199663, 82173.0, 84300.15699842923, 8136.796580085545, 9956.800863982824, 10042.598296068076, 10052.095311422006, 10058.0, 46431.0, 48039.75980120077, 54544.0, 54665.45334546763, 55888.161677574055, 54122.45877541174, 58191.0, 61458.69270653797, 61559.0, 62819.6859015702, 13314.46742130304, 13498.52755153565, 13531.797023044466, 13534.932325338705, 13828.654283642989, 15223.695526089637, 15620.68758624844, 15850.7622385662, 15972.44096456665, 16200.0, 14022.78965815534, 14249.0, 14290.92818038672, 14362.210078445785, 14569.562913112624, 12143.332116733665, 13656.931715341758, 13955.232462214675, 14060.458575603368, 14606.123632581654, 13448.193108413527, 13616.38726257301, 13989.440422382992, 14048.648783780078, 14320.928395357956, 9822.0, 10106.646267380607, 10202.540885660015, 10341.534630941474, 10403.947980260353, 10038.64864729731, 11408.258463199865, 11526.020288275591, 11656.650300491, 11712.0], "value": 7997.765955985893, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1515.0999999999997, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [108.0, 121.0, 119.0, 121.0, 120.0, 120.0, 119.0, 121.0, 120.0, 121.0], "value": 119.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [138.0, 110.0, 115.0, 145.0, 115.0, 109.0, 110.0, 110.0, 134.0, 112.0], "value": 119.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [201.0, 197.0, 198.0, 200.0, 195.0, 195.0, 195.0, 197.0, 200.0, 200.0], "value": 197.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 61.0, 60.0, 88.0, 64.0, 62.0, 75.0, 50.0, 88.0, 77.0], "value": 70.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 69.0, 113.0, 98.0, 89.0, 69.0, 81.0, 74.0, 69.0, 76.0], "value": 82.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 93.0, 91.0, 84.0, 92.0, 81.0, 134.0, 115.0, 94.0, 91.0], "value": 96.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [105.0, 98.0, 96.0, 98.0, 97.0, 97.0, 99.0, 96.0, 96.0, 97.0], "value": 97.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 141.0, 91.0, 97.0, 92.0, 111.0, 90.0, 93.0, 98.0, 90.0], "value": 105.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 63.0, 66.0, 72.0, 97.0, 67.0, 66.0, 69.0, 68.0, 67.0], "value": 70.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 64.0, 78.0, 82.0, 88.0, 92.0, 53.0, 76.0, 79.0, 83.0], "value": 75.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 87.0, 83.0, 80.0, 84.0, 83.0, 87.0, 86.0, 91.0, 76.0], "value": 84.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [182.0, 147.0, 141.0, 127.0, 140.0, 142.0, 146.0, 148.0, 188.0, 180.0], "value": 154.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [166.0, 165.0, 165.0, 166.0, 167.0, 165.0, 165.0, 161.0, 168.0, 200.0], "value": 168.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 64.0, 71.0, 74.0, 80.0, 82.0, 83.0, 83.0, 65.0, 63.0], "value": 73.1, "unit": "score"}]}]} 13:47:14 INFO - Return code: 0 13:47:14 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 13:47:14 ERROR - # TBPL SUCCESS # 13:47:14 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 13:47:14 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 13:47:14 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 13:47:14 INFO - 2017-06-01 13:47:14.651 screenresolution[1661:11039] starting screenresolution argv=screenresolution get 13:47:14 INFO - 2017-06-01 13:47:14.654 screenresolution[1661:11039] Display 0: 1600x1200x32@60 13:47:14 INFO - 2017-06-01 13:47:14.660 screenresolution[1662:11042] starting screenresolution argv=screenresolution list 13:47:14 INFO - Available Modes on Display 0 13:47:14 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 13:47:14 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 13:47:14 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 13:47:14 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 13:47:14 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 13:47:14 INFO - Intel Iris: 13:47:14 INFO - Chipset Model: Intel Iris 13:47:14 INFO - Type: GPU 13:47:14 INFO - Bus: Built-In 13:47:14 INFO - VRAM (Dynamic, Max): 1536 MB 13:47:14 INFO - Vendor: Intel (0x8086) 13:47:14 INFO - Device ID: 0x0a2e 13:47:14 INFO - Revision ID: 0x0009 13:47:14 INFO - Displays: 13:47:14 INFO - Display: 13:47:14 INFO - Resolution: 1600 x 1200 @ 60 Hz 13:47:14 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 13:47:14 INFO - Display Serial Number: 1600x1200 60 13:47:14 INFO - Main Display: Yes 13:47:14 INFO - Mirror: Off 13:47:14 INFO - Online: Yes 13:47:14 INFO - Rotation: Supported 13:47:14 INFO - Return code: 0 13:47:14 INFO - Running post-action listener: _resource_record_post_action 13:47:14 INFO - [mozharness: 2017-06-01 20:47:14.895516Z] Finished run-tests step (success) 13:47:14 INFO - Running post-run listener: _resource_record_post_run 13:47:14 INFO - Total resource usage - Wall time: 1191s; CPU: 55.0%; Read bytes: 35950592; Write bytes: 431872000; Read time: 326; Write time: 1687 13:47:14 INFO - TinderboxPrint: CPU usage
54.7% 13:47:14 INFO - TinderboxPrint: I/O read bytes / time
35,950,592 / 326 13:47:14 INFO - TinderboxPrint: I/O write bytes / time
431,872,000 / 1,687 13:47:14 INFO - TinderboxPrint: CPU idle
2,160.0 (45.3%) 13:47:14 INFO - TinderboxPrint: CPU system
964.6 (20.2%) 13:47:14 INFO - TinderboxPrint: CPU user
1,641.1 (34.4%) 13:47:14 INFO - TinderboxPrint: Swap in / out
154,742,784 / 0 13:47:14 INFO - install - Wall time: 15s; CPU: 54.0%; Read bytes: 117750272; Write bytes: 117979136; Read time: 10717; Write time: 345 13:47:14 INFO - run-tests - Wall time: 1177s; CPU: 55.0%; Read bytes: 33918976; Write bytes: 303034368; Read time: 304; Write time: 1308 13:47:15 INFO - Running post-run listener: _upload_blobber_files 13:47:15 INFO - Blob upload gear active. 13:47:15 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 13:47:15 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 13:47:15 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'] 13:47:15 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 13:47:15 INFO - (blobuploader) - INFO - Open directory for files ... 13:47:15 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 13:47:15 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:47:15 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:47:18 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 13:47:18 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:47:18 INFO - (blobuploader) - INFO - Done attempting. 13:47:18 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 13:47:18 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:47:18 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:47:19 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 13:47:19 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:47:19 INFO - (blobuploader) - INFO - Done attempting. 13:47:19 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 13:47:19 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:47:19 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:47:20 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 13:47:20 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:47:20 INFO - (blobuploader) - INFO - Done attempting. 13:47:20 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 13:47:20 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:47:20 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:47:22 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 13:47:22 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:47:22 INFO - (blobuploader) - INFO - Done attempting. 13:47:22 INFO - (blobuploader) - INFO - Iteration through files over. 13:47:22 INFO - Return code: 0 13:47:22 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 13:47:22 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 13:47:22 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/62798ba703f98b1dcc1bcfdec52db01a0daa1ea7dd196715669ae24367085d4c18d85794c0c4d37c81db03d8caf79943164fa7345b81b2056927e1495ef5aac3", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/747fc302684f6fa17cd8752251e588bef5219dc46f058863c32a3ac328e33b136bca1e6cf5b75f3a50ebe909099fbfed891f7fbf687fa95ad6fa7b24653595e6", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/1a08aa53845bb72987a11cce6f8aea6e82ec832d20436a7cf4d7e63df97ee4668508175e0675545619a5d78b0cc7b4bfe26d83e444a40ca06985d4257a7e4bb8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/87c093cbdb8efd34a407dc1e8453b64aaee9b40507d974c34307713f7aef5311c6c0698a79b0ce3849b7c092032ba2d5ea9a871376890060288801ebdda66f1a"} 13:47:22 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 13:47:22 INFO - Writing to file /builds/slave/test/properties/blobber_files 13:47:22 INFO - Contents: 13:47:22 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/62798ba703f98b1dcc1bcfdec52db01a0daa1ea7dd196715669ae24367085d4c18d85794c0c4d37c81db03d8caf79943164fa7345b81b2056927e1495ef5aac3", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/747fc302684f6fa17cd8752251e588bef5219dc46f058863c32a3ac328e33b136bca1e6cf5b75f3a50ebe909099fbfed891f7fbf687fa95ad6fa7b24653595e6", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/1a08aa53845bb72987a11cce6f8aea6e82ec832d20436a7cf4d7e63df97ee4668508175e0675545619a5d78b0cc7b4bfe26d83e444a40ca06985d4257a7e4bb8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/87c093cbdb8efd34a407dc1e8453b64aaee9b40507d974c34307713f7aef5311c6c0698a79b0ce3849b7c092032ba2d5ea9a871376890060288801ebdda66f1a"} 13:47:22 INFO - Running post-run listener: copy_logs_to_upload_dir 13:47:22 INFO - Copying logs to upload dir... 13:47:22 INFO - mkdir: /builds/slave/test/build/upload/logs 13:47:22 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1269.671724 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 9 secs) (at 2017-06-01 13:47:22.651365) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-01 13:47:22.654439) ========= 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.3nh4ao2mdF/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.5Byrb5EkGe/Listeners TMPDIR=/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/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/62798ba703f98b1dcc1bcfdec52db01a0daa1ea7dd196715669ae24367085d4c18d85794c0c4d37c81db03d8caf79943164fa7345b81b2056927e1495ef5aac3", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/747fc302684f6fa17cd8752251e588bef5219dc46f058863c32a3ac328e33b136bca1e6cf5b75f3a50ebe909099fbfed891f7fbf687fa95ad6fa7b24653595e6", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/1a08aa53845bb72987a11cce6f8aea6e82ec832d20436a7cf4d7e63df97ee4668508175e0675545619a5d78b0cc7b4bfe26d83e444a40ca06985d4257a7e4bb8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/87c093cbdb8efd34a407dc1e8453b64aaee9b40507d974c34307713f7aef5311c6c0698a79b0ce3849b7c092032ba2d5ea9a871376890060288801ebdda66f1a"} build_url:https://queue.taskcluster.net/v1/task/eCXaZQS8TLSkGySN1Pr5nQ/artifacts/public/build/firefox-54.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011139 build_url: 'https://queue.taskcluster.net/v1/task/eCXaZQS8TLSkGySN1Pr5nQ/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/62798ba703f98b1dcc1bcfdec52db01a0daa1ea7dd196715669ae24367085d4c18d85794c0c4d37c81db03d8caf79943164fa7345b81b2056927e1495ef5aac3", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/747fc302684f6fa17cd8752251e588bef5219dc46f058863c32a3ac328e33b136bca1e6cf5b75f3a50ebe909099fbfed891f7fbf687fa95ad6fa7b24653595e6", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/1a08aa53845bb72987a11cce6f8aea6e82ec832d20436a7cf4d7e63df97ee4668508175e0675545619a5d78b0cc7b4bfe26d83e444a40ca06985d4257a7e4bb8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/87c093cbdb8efd34a407dc1e8453b64aaee9b40507d974c34307713f7aef5311c6c0698a79b0ce3849b7c092032ba2d5ea9a871376890060288801ebdda66f1a"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-01 13:47:22.687735) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-01 13:47:22.688109) ========= 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.3nh4ao2mdF/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.5Byrb5EkGe/Listeners TMPDIR=/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/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.005237 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-01 13:47:22.736984) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-01 13:47:22.737357) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-01 13:47:22.737690) ========= ========= Total master_lag: 0.18 =========