builder: mozilla-beta_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0366 starttime: 1494557440.92 results: success (0) buildid: 20170511184347 builduid: 46757ae1bb1640c5b1d76a392452b31e revision: 4e354dd1049072b47680a37eeed391c1f2177dbf ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-05-11 19:50:40.921680) ========= master: http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-05-11 19:50:40.922157) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-11 19:50:40.922496) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-11 19:50:40.948729) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-11 19:50:40.949081) ========= 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.3kBE4oMqWs/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.0lC6xc825h/Listeners TMPDIR=/var/folders/fg/pfrf4nhd1hz969r_0mf1rb2400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-05-11 19:50:40-- 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% 18.8M=0.001s 2017-05-11 19:50:41 (18.8 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.117891 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-11 19:50:41.091578) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-11 19:50:41.092082) ========= 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.3kBE4oMqWs/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.0lC6xc825h/Listeners TMPDIR=/var/folders/fg/pfrf4nhd1hz969r_0mf1rb2400000w/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.024673 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-11 19:50:41.142132) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-11 19:50:41.142697) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 4e354dd1049072b47680a37eeed391c1f2177dbf --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 4e354dd1049072b47680a37eeed391c1f2177dbf --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.3kBE4oMqWs/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.0lC6xc825h/Listeners TMPDIR=/var/folders/fg/pfrf4nhd1hz969r_0mf1rb2400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-05-11 19:50:41,248 truncating revision to first 12 chars 2017-05-11 19:50:41,249 Setting DEBUG logging. 2017-05-11 19:50:41,249 attempt 1/10 2017-05-11 19:50:41,249 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/4e354dd10490?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-05-11 19:50:42,547 unpacking tar archive at: mozilla-beta-4e354dd10490/testing/mozharness/ program finished with exit code 0 elapsedTime=1.948313 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-11 19:50:43.114186) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-11 19:50:43.114547) ========= script_repo_revision: 4e354dd1049072b47680a37eeed391c1f2177dbf ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-11 19:50:43.114933) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-11 19:50:43.115214) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-11 19:50:43.133779) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 7 secs) (at 2017-05-11 19:50:43.134547) ========= /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.3kBE4oMqWs/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.0lC6xc825h/Listeners TMPDIR=/var/folders/fg/pfrf4nhd1hz969r_0mf1rb2400000w/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 19:50:43 INFO - MultiFileLogger online at 20170511 19:50:43 in /builds/slave/test 19:50:43 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 19:50:43 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 19:50:43 INFO - {'append_to_log': False, 19:50:43 INFO - 'base_work_dir': '/builds/slave/test', 19:50:43 INFO - 'blob_upload_branch': 'Mozilla-Beta', 19:50:43 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 19:50:43 INFO - 'branch': 'Mozilla-Beta', 19:50:43 INFO - 'buildbot_json_path': 'buildprops.json', 19:50:43 INFO - 'config_files': ('talos/mac_config.py',), 19:50:43 INFO - 'default_actions': ('clobber', 19:50:43 INFO - 'read-buildbot-config', 19:50:43 INFO - 'download-and-extract', 19:50:43 INFO - 'populate-webroot', 19:50:43 INFO - 'create-virtualenv', 19:50:43 INFO - 'install', 19:50:43 INFO - 'run-tests'), 19:50:43 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 19:50:43 INFO - 'download_minidump_stackwalk': True, 19:50:43 INFO - 'download_symbols': 'ondemand', 19:50:43 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 19:50:43 INFO - 'tooltool.py': '/tools/tooltool.py', 19:50:43 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 19:50:43 INFO - '/tools/misc-python/virtualenv.py')}, 19:50:43 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 19:50:43 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 19:50:43 INFO - 'gecko_profile': False, 19:50:43 INFO - 'gecko_profile_interval': 0, 19:50:43 INFO - 'installer_path': 'installer.exe', 19:50:43 INFO - 'log_level': 'info', 19:50:43 INFO - 'log_name': 'talos', 19:50:43 INFO - 'log_to_console': True, 19:50:43 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 19:50:43 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 19:50:43 INFO - 'opt_config_files': (), 19:50:43 INFO - 'pip_index': False, 19:50:43 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 19:50:43 INFO - 'cmd': ('bash', 19:50:43 INFO - '-c', 19:50:43 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 19:50:43 INFO - 'enabled': True, 19:50:43 INFO - 'halt_on_failure': False, 19:50:43 INFO - 'name': 'check_screen_resolution'},), 19:50:43 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 19:50:43 INFO - 'cmd': ('bash', 19:50:43 INFO - '-c', 19:50:43 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 19:50:43 INFO - 'enabled': True, 19:50:43 INFO - 'halt_on_failure': False, 19:50:43 INFO - 'name': 'check_screen_resolution'},), 19:50:43 INFO - 'run_cmd_checks_enabled': True, 19:50:43 INFO - 'suite': 'dromaeojs-e10s', 19:50:43 INFO - 'system_bits': '32', 19:50:43 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 19:50:43 INFO - 'title': 't-yosemite-r7-0366', 19:50:43 INFO - 'tooltool_cache': '/builds/tooltool_cache', 19:50:43 INFO - 'use_talos_json': True, 19:50:43 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 19:50:43 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 19:50:43 INFO - 'work_dir': 'build'} 19:50:43 INFO - [mozharness: 2017-05-12 02:50:43.346517Z] Running clobber step. 19:50:43 INFO - Running pre-action listener: _resource_record_pre_action 19:50:43 INFO - Running main action method: clobber 19:50:43 INFO - rmtree: /builds/slave/test/build 19:50:43 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 19:50:45 INFO - Running post-action listener: _resource_record_post_action 19:50:45 INFO - [mozharness: 2017-05-12 02:50:45.886257Z] Finished clobber step (success) 19:50:45 INFO - [mozharness: 2017-05-12 02:50:45.886405Z] Running read-buildbot-config step. 19:50:45 INFO - Running pre-action listener: _resource_record_pre_action 19:50:45 INFO - Running main action method: read_buildbot_config 19:50:45 INFO - Using buildbot properties: 19:50:45 INFO - { 19:50:45 INFO - "project": "", 19:50:45 INFO - "product": "firefox", 19:50:45 INFO - "script_repo_revision": "production", 19:50:45 INFO - "builddir": "mozilla-beta_yosemite_r7_test-dromaeojs-e10s", 19:50:45 INFO - "repository": "", 19:50:45 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-beta talos dromaeojs-e10s", 19:50:45 INFO - "buildid": "20170511184347", 19:50:45 INFO - "pgo_build": "False", 19:50:45 INFO - "basedir": "/builds/slave/test", 19:50:45 INFO - "buildnumber": 4, 19:50:45 INFO - "slavename": "t-yosemite-r7-0366", 19:50:45 INFO - "master": "http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/", 19:50:45 INFO - "platform": "yosemite_r7", 19:50:45 INFO - "scheduler": "tests-mozilla-beta-macosx64-talos", 19:50:45 INFO - "branch": "mozilla-beta", 19:50:45 INFO - "repo_path": "releases/mozilla-beta", 19:50:45 INFO - "revision": "4e354dd1049072b47680a37eeed391c1f2177dbf", 19:50:45 INFO - "stage_platform": "macosx64", 19:50:45 INFO - "builduid": "46757ae1bb1640c5b1d76a392452b31e", 19:50:45 INFO - "slavebuilddir": "test" 19:50:45 INFO - } 19:50:45 INFO - Found installer url https://queue.taskcluster.net/v1/task/BxrKAfh4QBmnGyFUhUrbEA/artifacts/public/build/firefox-54.0.en-US.mac.dmg. 19:50:45 INFO - Running post-action listener: _resource_record_post_action 19:50:45 INFO - [mozharness: 2017-05-12 02:50:45.889769Z] Finished read-buildbot-config step (success) 19:50:45 INFO - [mozharness: 2017-05-12 02:50:45.889906Z] Running download-and-extract step. 19:50:45 INFO - Running pre-action listener: _resource_record_pre_action 19:50:45 INFO - Running main action method: download_and_extract 19:50:45 INFO - mkdir: /builds/slave/test/build/tests 19:50:45 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')]} 19:50:45 INFO - trying https://queue.taskcluster.net/v1/task/BxrKAfh4QBmnGyFUhUrbEA/artifacts/public/build/firefox-54.0.en-US.mac.test_packages.json 19:50:45 INFO - Downloading https://queue.taskcluster.net/v1/task/BxrKAfh4QBmnGyFUhUrbEA/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 19:50:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/BxrKAfh4QBmnGyFUhUrbEA/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 19:50:46 INFO - Downloaded 1287 bytes. 19:50:46 INFO - Reading from file /builds/slave/test/build/firefox-54.0.en-US.mac.test_packages.json 19:50:46 INFO - Using the following test package requirements: 19:50:46 INFO - {u'awsy': [u'firefox-54.0.en-US.mac.common.tests.zip', 19:50:46 INFO - u'firefox-54.0.en-US.mac.awsy.tests.zip'], 19:50:46 INFO - u'common': [u'firefox-54.0.en-US.mac.common.tests.zip'], 19:50:46 INFO - u'cppunittest': [u'firefox-54.0.en-US.mac.common.tests.zip', 19:50:46 INFO - u'firefox-54.0.en-US.mac.cppunittest.tests.zip'], 19:50:46 INFO - u'gtest': [u'firefox-54.0.en-US.mac.common.tests.zip', 19:50:46 INFO - u'firefox-54.0.en-US.mac.gtest.tests.zip'], 19:50:46 INFO - u'jittest': [u'firefox-54.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 19:50:46 INFO - u'mochitest': [u'firefox-54.0.en-US.mac.common.tests.zip', 19:50:46 INFO - u'firefox-54.0.en-US.mac.mochitest.tests.zip'], 19:50:46 INFO - u'mozbase': [u'firefox-54.0.en-US.mac.common.tests.zip'], 19:50:46 INFO - u'reftest': [u'firefox-54.0.en-US.mac.common.tests.zip', 19:50:46 INFO - u'firefox-54.0.en-US.mac.reftest.tests.zip'], 19:50:46 INFO - u'talos': [u'firefox-54.0.en-US.mac.common.tests.zip', 19:50:46 INFO - u'firefox-54.0.en-US.mac.talos.tests.zip'], 19:50:46 INFO - u'web-platform': [u'firefox-54.0.en-US.mac.common.tests.zip', 19:50:46 INFO - u'firefox-54.0.en-US.mac.web-platform.tests.zip'], 19:50:46 INFO - u'xpcshell': [u'firefox-54.0.en-US.mac.common.tests.zip', 19:50:46 INFO - u'firefox-54.0.en-US.mac.xpcshell.tests.zip']} 19:50:46 INFO - Downloading packages: [u'firefox-54.0.en-US.mac.common.tests.zip'] for test suite category: common 19:50:46 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/BxrKAfh4QBmnGyFUhUrbEA/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip 19:50:46 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/BxrKAfh4QBmnGyFUhUrbEA/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip'}, attempt #1 19:50:46 INFO - Fetch https://queue.taskcluster.net/v1/task/BxrKAfh4QBmnGyFUhUrbEA/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip into memory 19:50:48 INFO - Expected file size: 51228065 19:50:48 INFO - Obtained file size: 51228065 19:51:04 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 19:51:04 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/BxrKAfh4QBmnGyFUhUrbEA/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip 19:51:04 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/BxrKAfh4QBmnGyFUhUrbEA/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip'}, attempt #1 19:51:04 INFO - Fetch https://queue.taskcluster.net/v1/task/BxrKAfh4QBmnGyFUhUrbEA/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip into memory 19:51:06 INFO - Expected file size: 51228065 19:51:06 INFO - Obtained file size: 51228065 19:51:21 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/BxrKAfh4QBmnGyFUhUrbEA/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip 19:51:21 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/BxrKAfh4QBmnGyFUhUrbEA/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip'}, attempt #1 19:51:21 INFO - Fetch https://queue.taskcluster.net/v1/task/BxrKAfh4QBmnGyFUhUrbEA/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip into memory 19:51:22 INFO - Expected file size: 14404449 19:51:22 INFO - Obtained file size: 14404449 19:51: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')]} 19:51:22 INFO - trying https://queue.taskcluster.net/v1/task/BxrKAfh4QBmnGyFUhUrbEA/artifacts/public/build/firefox-54.0.en-US.mac.dmg 19:51:22 INFO - Downloading https://queue.taskcluster.net/v1/task/BxrKAfh4QBmnGyFUhUrbEA/artifacts/public/build/firefox-54.0.en-US.mac.dmg to /builds/slave/test/build/firefox-54.0.en-US.mac.dmg 19:51:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/BxrKAfh4QBmnGyFUhUrbEA/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 19:51:24 INFO - Downloaded 59562334 bytes. 19:51:24 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/BxrKAfh4QBmnGyFUhUrbEA/artifacts/public/build/firefox-54.0.en-US.mac.dmg 19:51:24 INFO - mkdir: /builds/slave/test/properties 19:51:24 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 19:51:24 INFO - Writing to file /builds/slave/test/properties/build_url 19:51:24 INFO - Contents: 19:51:24 INFO - build_url:https://queue.taskcluster.net/v1/task/BxrKAfh4QBmnGyFUhUrbEA/artifacts/public/build/firefox-54.0.en-US.mac.dmg 19:51:25 INFO - Running post-action listener: _resource_record_post_action 19:51:25 INFO - Running post-action listener: set_extra_try_arguments 19:51:25 INFO - [mozharness: 2017-05-12 02:51:25.167010Z] Finished download-and-extract step (success) 19:51:25 INFO - [mozharness: 2017-05-12 02:51:25.167163Z] Running populate-webroot step. 19:51:25 INFO - Running pre-action listener: _resource_record_pre_action 19:51:25 INFO - Running main action method: populate_webroot 19:51:25 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 19:51:25 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 19:51:25 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 19:51:25 INFO - '--tppagecycles', 19:51:25 INFO - '7'], 19:51:25 INFO - 'tests': ['tsvgm']}}, 19:51:25 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 19:51:25 INFO - 'tests': ['tresize', 'tcanvasmark']}, 19:51:25 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 19:51:25 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 19:51:25 INFO - 'tests': ['dromaeo_css', 'kraken']}, 19:51:25 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 19:51:25 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 19:51:25 INFO - 'talos_options': ['--disable-e10s'], 19:51:25 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:51:25 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 19:51:25 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:51:25 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 19:51:25 INFO - 'talos_options': ['--disable-e10s'], 19:51:25 INFO - 'tests': ['damp', 'tps']}, 19:51:25 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 19:51:25 INFO - 'tests': ['damp', 'tps']}, 19:51:25 INFO - 'g3': {'talos_options': ['--disable-e10s'], 19:51:25 INFO - 'tests': ['dromaeo_dom']}, 19:51:25 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 19:51:25 INFO - 'g4': {'talos_options': ['--disable-e10s'], 19:51:25 INFO - 'tests': ['basic_compositor_video']}, 19:51:25 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 19:51:25 INFO - 'other': {'talos_options': ['--disable-e10s'], 19:51:25 INFO - 'tests': ['a11yr', 19:51:25 INFO - 'ts_paint', 19:51:25 INFO - 'tpaint', 19:51:25 INFO - 'sessionrestore', 19:51:25 INFO - 'sessionrestore_no_auto_restore', 19:51:25 INFO - 'tabpaint']}, 19:51:25 INFO - 'other-e10s': {'tests': ['a11yr', 19:51:25 INFO - 'ts_paint', 19:51:25 INFO - 'tpaint', 19:51:25 INFO - 'sessionrestore', 19:51:25 INFO - 'sessionrestore_no_auto_restore', 19:51:25 INFO - 'tabpaint']}, 19:51:25 INFO - 'other-e10s_l64': {'tests': ['a11yr', 19:51:25 INFO - 'ts_paint', 19:51:25 INFO - 'tpaint', 19:51:25 INFO - 'sessionrestore', 19:51:25 INFO - 'sessionrestore_no_auto_restore', 19:51:25 INFO - 'tabpaint']}, 19:51:25 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 19:51:25 INFO - 'ts_paint', 19:51:25 INFO - 'tpaint', 19:51:25 INFO - 'sessionrestore', 19:51:25 INFO - 'sessionrestore_no_auto_restore', 19:51:25 INFO - 'tabpaint']}, 19:51:25 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 19:51:25 INFO - 'tests': ['a11yr', 19:51:25 INFO - 'ts_paint', 19:51:25 INFO - 'tpaint', 19:51:25 INFO - 'sessionrestore', 19:51:25 INFO - 'sessionrestore_no_auto_restore', 19:51:25 INFO - 'tabpaint']}, 19:51:25 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 19:51:25 INFO - 'tests': ['a11yr', 19:51:25 INFO - 'ts_paint', 19:51:25 INFO - 'tpaint', 19:51:25 INFO - 'sessionrestore', 19:51:25 INFO - 'sessionrestore_no_auto_restore', 19:51:25 INFO - 'tabpaint']}, 19:51:25 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 19:51:25 INFO - 'tests': ['tsvgx', 19:51:25 INFO - 'tsvgr_opacity', 19:51:25 INFO - 'tart', 19:51:25 INFO - 'tscrollx', 19:51:25 INFO - 'cart', 19:51:25 INFO - 'tsvg_static']}, 19:51:25 INFO - 'svgr-e10s': {'tests': ['tsvgx', 19:51:25 INFO - 'tsvgr_opacity', 19:51:25 INFO - 'tart', 19:51:25 INFO - 'tscrollx', 19:51:25 INFO - 'cart', 19:51:25 INFO - 'tsvg_static']}, 19:51:25 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 19:51:25 INFO - 'talos_options': ['--disable-e10s'], 19:51:25 INFO - 'tests': ['tp5o']}, 19:51:25 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 19:51:25 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 19:51:25 INFO - 'talos_options': ['--disable-e10s', 19:51:25 INFO - '--xperf_path', 19:51:25 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:51:25 INFO - 'tests': ['tp5n']}, 19:51:25 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 19:51:25 INFO - 'talos_options': ['--xperf_path', 19:51:25 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:51:25 INFO - 'tests': ['tp5n']}}, 19:51:25 INFO - 'talos.zip': {'path': '', 19:51:25 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 19:51:25 INFO - Running post-action listener: _resource_record_post_action 19:51:25 INFO - [mozharness: 2017-05-12 02:51:25.185637Z] Finished populate-webroot step (success) 19:51:25 INFO - [mozharness: 2017-05-12 02:51:25.185777Z] Running create-virtualenv step. 19:51:25 INFO - Running pre-action listener: _resource_record_pre_action 19:51:25 INFO - Running main action method: create_virtualenv 19:51:25 INFO - Creating virtualenv /builds/slave/test/build/venv 19:51:25 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 19:51:25 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 19:51:25 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 19:51:25 INFO - Using real prefix '/tools/python27' 19:51:25 INFO - New python executable in /builds/slave/test/build/venv/bin/python 19:51:26 INFO - Installing distribute.............................................................................................................................................................................................done. 19:51:28 INFO - Installing pip.................done. 19:51:28 INFO - Return code: 0 19:51:28 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 19:51:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 19:51:29 INFO - Reading from file tmpfile_stdout 19:51:29 INFO - Output received: 19:51:29 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) 19:51:29 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 19:51:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:51:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:51:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:51:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:51:29 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10a488b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a48b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb739c89210>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a266d30>, '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 0x10a18ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a25bbe8>, '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.3kBE4oMqWs/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.0lC6xc825h/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fg/pfrf4nhd1hz969r_0mf1rb2400000w/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 19:51:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 19:51:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 19:51:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3kBE4oMqWs/Render', 19:51:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:51:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:51:29 INFO - 'HOME': '/Users/cltbld', 19:51:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:51:29 INFO - 'LOGNAME': 'cltbld', 19:51:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:51:29 INFO - 'MOZ_NO_REMOTE': '1', 19:51:29 INFO - 'NO_EM_RESTART': '1', 19:51:29 INFO - 'PAGER': '/bin/cat', 19:51:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:51:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:51:29 INFO - 'PWD': '/builds/slave/test', 19:51:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:51:29 INFO - 'SHELL': '/bin/bash', 19:51:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0lC6xc825h/Listeners', 19:51:29 INFO - 'TMPDIR': '/var/folders/fg/pfrf4nhd1hz969r_0mf1rb2400000w/T/', 19:51:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:51:29 INFO - 'USER': 'cltbld', 19:51:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:51:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:51:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:51:29 INFO - 'XPC_FLAGS': '0x0', 19:51:29 INFO - 'XPC_SERVICE_NAME': '0', 19:51:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:51:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:51:29 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 19:51:29 INFO - Cleaning up... 19:51:29 INFO - Return code: 0 19:51:29 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 19:51:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:51:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:51:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:51:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:51:29 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10a488b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a48b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb739c89210>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a266d30>, '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 0x10a18ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a25bbe8>, '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.3kBE4oMqWs/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.0lC6xc825h/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fg/pfrf4nhd1hz969r_0mf1rb2400000w/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 19:51:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 19:51:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 19:51:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3kBE4oMqWs/Render', 19:51:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:51:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:51:29 INFO - 'HOME': '/Users/cltbld', 19:51:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:51:29 INFO - 'LOGNAME': 'cltbld', 19:51:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:51:29 INFO - 'MOZ_NO_REMOTE': '1', 19:51:29 INFO - 'NO_EM_RESTART': '1', 19:51:29 INFO - 'PAGER': '/bin/cat', 19:51:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:51:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:51:29 INFO - 'PWD': '/builds/slave/test', 19:51:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:51:29 INFO - 'SHELL': '/bin/bash', 19:51:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0lC6xc825h/Listeners', 19:51:29 INFO - 'TMPDIR': '/var/folders/fg/pfrf4nhd1hz969r_0mf1rb2400000w/T/', 19:51:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:51:29 INFO - 'USER': 'cltbld', 19:51:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:51:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:51:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:51:29 INFO - 'XPC_FLAGS': '0x0', 19:51:29 INFO - 'XPC_SERVICE_NAME': '0', 19:51:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:51:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:51:29 INFO - Downloading/unpacking psutil>=3.1.1 19:51:29 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 19:51:29 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 19:51:29 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 19:51:29 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 19:51:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 19:51:31 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:51:31 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:51:31 INFO - Installing collected packages: psutil 19:51:31 INFO - Running setup.py install for psutil 19:51:31 INFO - building 'psutil._psutil_osx' extension 19:51:31 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 19:51:32 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 19:51:32 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 19:51:32 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 19:51:32 INFO - building 'psutil._psutil_posix' extension 19:51:32 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 19:51:32 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 19:51:32 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 19:51:32 INFO - ^ 19:51:32 INFO - 1 warning generated. 19:51:32 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 19:51:32 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:51:32 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:51:32 INFO - Successfully installed psutil 19:51:32 INFO - Cleaning up... 19:51:32 INFO - Return code: 0 19:51:32 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 19:51:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:51:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:51:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:51:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:51:32 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10a488b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a48b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb739c89210>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a266d30>, '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 0x10a18ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a25bbe8>, '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.3kBE4oMqWs/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.0lC6xc825h/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fg/pfrf4nhd1hz969r_0mf1rb2400000w/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 19:51:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 19:51:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 19:51:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3kBE4oMqWs/Render', 19:51:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:51:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:51:32 INFO - 'HOME': '/Users/cltbld', 19:51:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:51:32 INFO - 'LOGNAME': 'cltbld', 19:51:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:51:32 INFO - 'MOZ_NO_REMOTE': '1', 19:51:32 INFO - 'NO_EM_RESTART': '1', 19:51:32 INFO - 'PAGER': '/bin/cat', 19:51:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:51:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:51:32 INFO - 'PWD': '/builds/slave/test', 19:51:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:51:32 INFO - 'SHELL': '/bin/bash', 19:51:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0lC6xc825h/Listeners', 19:51:32 INFO - 'TMPDIR': '/var/folders/fg/pfrf4nhd1hz969r_0mf1rb2400000w/T/', 19:51:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:51:32 INFO - 'USER': 'cltbld', 19:51:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:51:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:51:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:51:32 INFO - 'XPC_FLAGS': '0x0', 19:51:32 INFO - 'XPC_SERVICE_NAME': '0', 19:51:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:51:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:51:33 INFO - Downloading/unpacking mozsystemmonitor==0.3 19:51:33 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 19:51:33 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 19:51:33 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 19:51:33 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 19:51:34 INFO - Downloading mozsystemmonitor-0.3.tar.gz 19:51:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 19:51:34 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 19:51:34 INFO - Installing collected packages: mozsystemmonitor 19:51:34 INFO - Running setup.py install for mozsystemmonitor 19:51:34 INFO - Successfully installed mozsystemmonitor 19:51:34 INFO - Cleaning up... 19:51:34 INFO - Return code: 0 19:51:34 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 19:51:34 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')]} 19:51:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:51:34 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')]} 19:51:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:51:34 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 0x10a488b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a48b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb739c89210>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a266d30>, '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 0x10a18ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a25bbe8>, '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.3kBE4oMqWs/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.0lC6xc825h/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fg/pfrf4nhd1hz969r_0mf1rb2400000w/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 19:51:34 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 19:51:34 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 19:51:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3kBE4oMqWs/Render', 19:51:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:51:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:51:34 INFO - 'HOME': '/Users/cltbld', 19:51:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:51:34 INFO - 'LOGNAME': 'cltbld', 19:51:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:51:34 INFO - 'MOZ_NO_REMOTE': '1', 19:51:34 INFO - 'NO_EM_RESTART': '1', 19:51:34 INFO - 'PAGER': '/bin/cat', 19:51:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:51:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:51:34 INFO - 'PWD': '/builds/slave/test', 19:51:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:51:34 INFO - 'SHELL': '/bin/bash', 19:51:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0lC6xc825h/Listeners', 19:51:34 INFO - 'TMPDIR': '/var/folders/fg/pfrf4nhd1hz969r_0mf1rb2400000w/T/', 19:51:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:51:34 INFO - 'USER': 'cltbld', 19:51:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:51:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:51:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:51:34 INFO - 'XPC_FLAGS': '0x0', 19:51:34 INFO - 'XPC_SERVICE_NAME': '0', 19:51:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:51:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:51:34 INFO - Downloading/unpacking jsonschema==2.5.1 19:51:34 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 19:51:34 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 19:51:34 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 19:51:34 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 19:51:36 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 19:51:36 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 19:51:36 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 19:51:36 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 19:51:36 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 19:51:36 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 19:51:36 INFO - Downloading functools32-3.2.3-2.tar.gz 19:51:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 19:51:36 INFO - warning: no files found matching '*.txt' 19:51:36 INFO - no previously-included directories found matching 'build' 19:51:36 INFO - no previously-included directories found matching 'dist' 19:51:36 INFO - no previously-included directories found matching '.git*' 19:51:36 INFO - Installing collected packages: jsonschema, functools32 19:51:36 INFO - Running setup.py install for functools32 19:51:36 INFO - warning: no files found matching '*.txt' 19:51:36 INFO - no previously-included directories found matching 'build' 19:51:36 INFO - no previously-included directories found matching 'dist' 19:51:36 INFO - no previously-included directories found matching '.git*' 19:51:36 INFO - Successfully installed jsonschema functools32 19:51:36 INFO - Cleaning up... 19:51:36 INFO - Return code: 0 19:51:36 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 19:51:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:51:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:51:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:51:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:51:37 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10a488b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a48b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb739c89210>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a266d30>, '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 0x10a18ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a25bbe8>, '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.3kBE4oMqWs/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.0lC6xc825h/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fg/pfrf4nhd1hz969r_0mf1rb2400000w/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 19:51:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 19:51:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 19:51:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3kBE4oMqWs/Render', 19:51:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:51:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:51:37 INFO - 'HOME': '/Users/cltbld', 19:51:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:51:37 INFO - 'LOGNAME': 'cltbld', 19:51:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:51:37 INFO - 'MOZ_NO_REMOTE': '1', 19:51:37 INFO - 'NO_EM_RESTART': '1', 19:51:37 INFO - 'PAGER': '/bin/cat', 19:51:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:51:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:51:37 INFO - 'PWD': '/builds/slave/test', 19:51:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:51:37 INFO - 'SHELL': '/bin/bash', 19:51:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0lC6xc825h/Listeners', 19:51:37 INFO - 'TMPDIR': '/var/folders/fg/pfrf4nhd1hz969r_0mf1rb2400000w/T/', 19:51:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:51:37 INFO - 'USER': 'cltbld', 19:51:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:51:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:51:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:51:37 INFO - 'XPC_FLAGS': '0x0', 19:51:37 INFO - 'XPC_SERVICE_NAME': '0', 19:51:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:51:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:51:38 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 19:51:38 INFO - Cleaning up... 19:51:38 INFO - Return code: 0 19:51:38 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 19:51: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')]} 19:51:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:51: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')]} 19:51:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:51:38 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 0x10a488b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a48b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb739c89210>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a266d30>, '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 0x10a18ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a25bbe8>, '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.3kBE4oMqWs/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.0lC6xc825h/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fg/pfrf4nhd1hz969r_0mf1rb2400000w/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 19:51:38 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 19:51:38 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 19:51:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3kBE4oMqWs/Render', 19:51:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:51:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:51:38 INFO - 'HOME': '/Users/cltbld', 19:51:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:51:38 INFO - 'LOGNAME': 'cltbld', 19:51:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:51:38 INFO - 'MOZ_NO_REMOTE': '1', 19:51:38 INFO - 'NO_EM_RESTART': '1', 19:51:38 INFO - 'PAGER': '/bin/cat', 19:51:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:51:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:51:38 INFO - 'PWD': '/builds/slave/test', 19:51:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:51:38 INFO - 'SHELL': '/bin/bash', 19:51:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0lC6xc825h/Listeners', 19:51:38 INFO - 'TMPDIR': '/var/folders/fg/pfrf4nhd1hz969r_0mf1rb2400000w/T/', 19:51:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:51:38 INFO - 'USER': 'cltbld', 19:51:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:51:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:51:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:51:38 INFO - 'XPC_FLAGS': '0x0', 19:51:38 INFO - 'XPC_SERVICE_NAME': '0', 19:51:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:51:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:51:38 INFO - Downloading/unpacking blobuploader==1.2.4 19:51:38 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 19:51:38 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 19:51:38 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 19:51:38 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 19:51:40 INFO - Downloading blobuploader-1.2.4.tar.gz 19:51:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 19:51:40 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 19:51:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:51:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:51:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:51:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:51:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 19:51:41 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 19:51:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:51:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:51:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:51:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:51:41 INFO - Downloading docopt-0.6.1.tar.gz 19:51:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 19:51:41 INFO - Installing collected packages: blobuploader, requests, docopt 19:51:41 INFO - Running setup.py install for blobuploader 19:51:41 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 19:51:41 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 19:51:41 INFO - Running setup.py install for requests 19:51:42 INFO - Running setup.py install for docopt 19:51:42 INFO - Successfully installed blobuploader requests docopt 19:51:42 INFO - Cleaning up... 19:51:42 INFO - Return code: 0 19:51:42 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:51:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:51:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:51:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:51:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:51:42 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 0x10a488b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a48b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb739c89210>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a266d30>, '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 0x10a18ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a25bbe8>, '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.3kBE4oMqWs/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.0lC6xc825h/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fg/pfrf4nhd1hz969r_0mf1rb2400000w/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 19:51:42 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 19:51:42 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 19:51:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3kBE4oMqWs/Render', 19:51:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:51:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:51:42 INFO - 'HOME': '/Users/cltbld', 19:51:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:51:42 INFO - 'LOGNAME': 'cltbld', 19:51:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:51:42 INFO - 'MOZ_NO_REMOTE': '1', 19:51:42 INFO - 'NO_EM_RESTART': '1', 19:51:42 INFO - 'PAGER': '/bin/cat', 19:51:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:51:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:51:42 INFO - 'PWD': '/builds/slave/test', 19:51:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:51:42 INFO - 'SHELL': '/bin/bash', 19:51:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0lC6xc825h/Listeners', 19:51:42 INFO - 'TMPDIR': '/var/folders/fg/pfrf4nhd1hz969r_0mf1rb2400000w/T/', 19:51:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:51:42 INFO - 'USER': 'cltbld', 19:51:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:51:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:51:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:51:42 INFO - 'XPC_FLAGS': '0x0', 19:51:42 INFO - 'XPC_SERVICE_NAME': '0', 19:51:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:51:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:51:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:51:42 INFO - Running setup.py (path:/var/folders/fg/pfrf4nhd1hz969r_0mf1rb2400000w/T/pip-dA4Y0y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:51:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:51:42 INFO - Running setup.py (path:/var/folders/fg/pfrf4nhd1hz969r_0mf1rb2400000w/T/pip-98pNcP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:51:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:51:42 INFO - Running setup.py (path:/var/folders/fg/pfrf4nhd1hz969r_0mf1rb2400000w/T/pip-Mjsb7e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:51:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:51:43 INFO - Running setup.py (path:/var/folders/fg/pfrf4nhd1hz969r_0mf1rb2400000w/T/pip-2u7dYV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:51:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:51:43 INFO - Running setup.py (path:/var/folders/fg/pfrf4nhd1hz969r_0mf1rb2400000w/T/pip-Z67mA0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:51:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:51:43 INFO - Running setup.py (path:/var/folders/fg/pfrf4nhd1hz969r_0mf1rb2400000w/T/pip-_Sgk2D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:51:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:51:43 INFO - Running setup.py (path:/var/folders/fg/pfrf4nhd1hz969r_0mf1rb2400000w/T/pip-RaNzNM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:51:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:51:43 INFO - Running setup.py (path:/var/folders/fg/pfrf4nhd1hz969r_0mf1rb2400000w/T/pip-2xReOr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:51:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:51:43 INFO - Running setup.py (path:/var/folders/fg/pfrf4nhd1hz969r_0mf1rb2400000w/T/pip-E3UVK_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:51:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:51:43 INFO - Running setup.py (path:/var/folders/fg/pfrf4nhd1hz969r_0mf1rb2400000w/T/pip-esgI7k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:51:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:51:43 INFO - Running setup.py (path:/var/folders/fg/pfrf4nhd1hz969r_0mf1rb2400000w/T/pip-VBNC2k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:51:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:51:43 INFO - Running setup.py (path:/var/folders/fg/pfrf4nhd1hz969r_0mf1rb2400000w/T/pip-fv9NEa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:51:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:51:44 INFO - Running setup.py (path:/var/folders/fg/pfrf4nhd1hz969r_0mf1rb2400000w/T/pip-cLhJ3t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:51:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:51:44 INFO - Running setup.py (path:/var/folders/fg/pfrf4nhd1hz969r_0mf1rb2400000w/T/pip-dNQcxA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:51:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:51:44 INFO - Running setup.py (path:/var/folders/fg/pfrf4nhd1hz969r_0mf1rb2400000w/T/pip-dLB7Rb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:51:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:51:44 INFO - Running setup.py (path:/var/folders/fg/pfrf4nhd1hz969r_0mf1rb2400000w/T/pip-LG68S5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:51:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:51:44 INFO - Running setup.py (path:/var/folders/fg/pfrf4nhd1hz969r_0mf1rb2400000w/T/pip-vXxwGa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:51:44 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 19:51:44 INFO - Running setup.py install for manifestparser 19:51:44 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 19:51:44 INFO - Running setup.py install for mozcrash 19:51:44 INFO - Running setup.py install for mozdebug 19:51:44 INFO - Running setup.py install for mozdevice 19:51:45 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 19:51:45 INFO - Installing dm script to /builds/slave/test/build/venv/bin 19:51:45 INFO - Running setup.py install for mozfile 19:51:45 INFO - Running setup.py install for mozhttpd 19:51:45 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 19:51:45 INFO - Running setup.py install for mozinfo 19:51:45 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 19:51:45 INFO - Running setup.py install for mozInstall 19:51:45 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 19:51:45 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 19:51:45 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 19:51:45 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 19:51:45 INFO - Running setup.py install for mozleak 19:51:45 INFO - Running setup.py install for mozlog 19:51:45 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 19:51:46 INFO - Running setup.py install for moznetwork 19:51:46 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 19:51:46 INFO - Running setup.py install for mozprocess 19:51:46 INFO - Running setup.py install for mozprofile 19:51:46 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 19:51:46 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 19:51:46 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 19:51:46 INFO - Running setup.py install for mozrunner 19:51:46 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 19:51:46 INFO - Running setup.py install for mozscreenshot 19:51:46 INFO - Running setup.py install for moztest 19:51:46 INFO - Running setup.py install for mozversion 19:51:47 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 19:51:47 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 19:51:47 INFO - Cleaning up... 19:51:47 INFO - Return code: 0 19:51:47 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:51:47 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')]} 19:51:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:51:47 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')]} 19:51:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:51:47 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 0x10a488b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a48b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb739c89210>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a266d30>, '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 0x10a18ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a25bbe8>, '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.3kBE4oMqWs/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.0lC6xc825h/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fg/pfrf4nhd1hz969r_0mf1rb2400000w/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 19:51:47 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 19:51:47 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 19:51:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3kBE4oMqWs/Render', 19:51:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:51:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:51:47 INFO - 'HOME': '/Users/cltbld', 19:51:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:51:47 INFO - 'LOGNAME': 'cltbld', 19:51:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:51:47 INFO - 'MOZ_NO_REMOTE': '1', 19:51:47 INFO - 'NO_EM_RESTART': '1', 19:51:47 INFO - 'PAGER': '/bin/cat', 19:51:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:51:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:51:47 INFO - 'PWD': '/builds/slave/test', 19:51:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:51:47 INFO - 'SHELL': '/bin/bash', 19:51:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0lC6xc825h/Listeners', 19:51:47 INFO - 'TMPDIR': '/var/folders/fg/pfrf4nhd1hz969r_0mf1rb2400000w/T/', 19:51:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:51:47 INFO - 'USER': 'cltbld', 19:51:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:51:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:51:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:51:47 INFO - 'XPC_FLAGS': '0x0', 19:51:47 INFO - 'XPC_SERVICE_NAME': '0', 19:51:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:51:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:51:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:51:47 INFO - Running setup.py (path:/var/folders/fg/pfrf4nhd1hz969r_0mf1rb2400000w/T/pip-ZUua2H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:51:47 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)) 19:51:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:51:47 INFO - Running setup.py (path:/var/folders/fg/pfrf4nhd1hz969r_0mf1rb2400000w/T/pip-_x6IH2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:51:47 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)) 19:51:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:51:47 INFO - Running setup.py (path:/var/folders/fg/pfrf4nhd1hz969r_0mf1rb2400000w/T/pip-7Pvnw5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:51:47 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)) 19:51:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:51:47 INFO - Running setup.py (path:/var/folders/fg/pfrf4nhd1hz969r_0mf1rb2400000w/T/pip-CmQRWZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:51:47 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)) 19:51:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:51:47 INFO - Running setup.py (path:/var/folders/fg/pfrf4nhd1hz969r_0mf1rb2400000w/T/pip-XZXaKr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:51:47 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)) 19:51:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:51:47 INFO - Running setup.py (path:/var/folders/fg/pfrf4nhd1hz969r_0mf1rb2400000w/T/pip-4n0BWV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:51:47 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)) 19:51:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:51:47 INFO - Running setup.py (path:/var/folders/fg/pfrf4nhd1hz969r_0mf1rb2400000w/T/pip-gxVb4P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:51:48 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)) 19:51:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:51:48 INFO - Running setup.py (path:/var/folders/fg/pfrf4nhd1hz969r_0mf1rb2400000w/T/pip-hvs7ez-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:51:48 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)) 19:51:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:51:48 INFO - Running setup.py (path:/var/folders/fg/pfrf4nhd1hz969r_0mf1rb2400000w/T/pip-JvKQTg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:51:48 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)) 19:51:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:51:48 INFO - Running setup.py (path:/var/folders/fg/pfrf4nhd1hz969r_0mf1rb2400000w/T/pip-k41zV2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:51:48 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)) 19:51:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:51:48 INFO - Running setup.py (path:/var/folders/fg/pfrf4nhd1hz969r_0mf1rb2400000w/T/pip-yeIOtO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:51:48 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)) 19:51:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:51:48 INFO - Running setup.py (path:/var/folders/fg/pfrf4nhd1hz969r_0mf1rb2400000w/T/pip-JtnK6h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:51:48 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)) 19:51:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:51:48 INFO - Running setup.py (path:/var/folders/fg/pfrf4nhd1hz969r_0mf1rb2400000w/T/pip-5Lk98T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:51:48 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)) 19:51:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:51:48 INFO - Running setup.py (path:/var/folders/fg/pfrf4nhd1hz969r_0mf1rb2400000w/T/pip-cwhUnJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:51:48 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)) 19:51:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:51:48 INFO - Running setup.py (path:/var/folders/fg/pfrf4nhd1hz969r_0mf1rb2400000w/T/pip-Y2aSlL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:51:48 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)) 19:51:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:51:48 INFO - Running setup.py (path:/var/folders/fg/pfrf4nhd1hz969r_0mf1rb2400000w/T/pip-nz3QCm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:51:49 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)) 19:51:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:51:49 INFO - Running setup.py (path:/var/folders/fg/pfrf4nhd1hz969r_0mf1rb2400000w/T/pip-xgb9Ke-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:51:49 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)) 19:51:49 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)) 19:51:49 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)) 19:51:49 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)) 19:51:49 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)) 19:51:49 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)) 19:51:49 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 19:51:49 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 19:51:49 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 19:51:49 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 19:51:49 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 19:51:50 INFO - Downloading blessings-1.6.tar.gz 19:51:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 19:51:50 INFO - Installing collected packages: blessings 19:51:50 INFO - Running setup.py install for blessings 19:51:50 INFO - Successfully installed blessings 19:51:50 INFO - Cleaning up... 19:51:50 INFO - Return code: 0 19:51:50 INFO - Done creating virtualenv /builds/slave/test/build/venv. 19:51:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:51:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:51:51 INFO - Reading from file tmpfile_stdout 19:51:51 INFO - Current package versions: 19:51:51 INFO - blessings == 1.6 19:51:51 INFO - blobuploader == 1.2.4 19:51:51 INFO - docopt == 0.6.1 19:51:51 INFO - functools32 == 3.2.3-2 19:51:51 INFO - jsonschema == 2.5.1 19:51:51 INFO - manifestparser == 1.1 19:51:51 INFO - mozInstall == 1.12 19:51:51 INFO - mozcrash == 1.0 19:51:51 INFO - mozdebug == 0.1 19:51:51 INFO - mozdevice == 0.49 19:51:51 INFO - mozfile == 1.2 19:51:51 INFO - mozhttpd == 0.7 19:51:51 INFO - mozinfo == 0.9 19:51:51 INFO - mozleak == 0.1 19:51:51 INFO - mozlog == 3.4 19:51:51 INFO - moznetwork == 0.27 19:51:51 INFO - mozprocess == 0.25 19:51:51 INFO - mozprofile == 0.28 19:51:51 INFO - mozrunner == 6.13 19:51:51 INFO - mozscreenshot == 0.1 19:51:51 INFO - mozsystemmonitor == 0.3 19:51:51 INFO - moztest == 0.8 19:51:51 INFO - mozversion == 1.4 19:51:51 INFO - psutil == 3.1.1 19:51:51 INFO - requests == 1.2.3 19:51:51 INFO - wsgiref == 0.1.2 19:51:51 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:51:51 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')]} 19:51:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:51:51 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')]} 19:51:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:51:51 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 0x10a488b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a48b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb739c89210>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a266d30>, '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 0x10a18ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a25bbe8>, '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.3kBE4oMqWs/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.0lC6xc825h/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fg/pfrf4nhd1hz969r_0mf1rb2400000w/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 19:51:51 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 19:51:51 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 19:51:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3kBE4oMqWs/Render', 19:51:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:51:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:51:51 INFO - 'HOME': '/Users/cltbld', 19:51:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:51:51 INFO - 'LOGNAME': 'cltbld', 19:51:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:51:51 INFO - 'MOZ_NO_REMOTE': '1', 19:51:51 INFO - 'NO_EM_RESTART': '1', 19:51:51 INFO - 'PAGER': '/bin/cat', 19:51:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:51:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:51:51 INFO - 'PWD': '/builds/slave/test', 19:51:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:51:51 INFO - 'SHELL': '/bin/bash', 19:51:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0lC6xc825h/Listeners', 19:51:51 INFO - 'TMPDIR': '/var/folders/fg/pfrf4nhd1hz969r_0mf1rb2400000w/T/', 19:51:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:51:51 INFO - 'USER': 'cltbld', 19:51:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:51:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:51:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:51:51 INFO - 'XPC_FLAGS': '0x0', 19:51:51 INFO - 'XPC_SERVICE_NAME': '0', 19:51:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:51:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:51:51 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)) 19:51:51 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)) 19:51:51 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)) 19:51:51 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)) 19:51:51 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)) 19:51:51 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)) 19:51:51 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)) 19:51:51 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)) 19:51:51 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)) 19:51:51 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)) 19:51:51 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)) 19:51:51 INFO - Cleaning up... 19:51:51 INFO - Return code: 0 19:51:51 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 19:51:51 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')]} 19:51:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:51:51 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')]} 19:51:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:51:51 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 0x10a488b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a48b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb739c89210>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a266d30>, '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 0x10a18ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a25bbe8>, '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.3kBE4oMqWs/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.0lC6xc825h/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fg/pfrf4nhd1hz969r_0mf1rb2400000w/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 19:51:51 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 19:51:51 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 19:51:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3kBE4oMqWs/Render', 19:51:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:51:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:51:51 INFO - 'HOME': '/Users/cltbld', 19:51:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:51:51 INFO - 'LOGNAME': 'cltbld', 19:51:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:51:51 INFO - 'MOZ_NO_REMOTE': '1', 19:51:51 INFO - 'NO_EM_RESTART': '1', 19:51:51 INFO - 'PAGER': '/bin/cat', 19:51:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:51:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:51:51 INFO - 'PWD': '/builds/slave/test', 19:51:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:51:51 INFO - 'SHELL': '/bin/bash', 19:51:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0lC6xc825h/Listeners', 19:51:51 INFO - 'TMPDIR': '/var/folders/fg/pfrf4nhd1hz969r_0mf1rb2400000w/T/', 19:51:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:51:51 INFO - 'USER': 'cltbld', 19:51:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:51:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:51:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:51:51 INFO - 'XPC_FLAGS': '0x0', 19:51:51 INFO - 'XPC_SERVICE_NAME': '0', 19:51:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:51:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:51:51 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 19:51:51 INFO - Cleaning up... 19:51:51 INFO - Return code: 0 19:51:51 INFO - Running post-action listener: _resource_record_post_action 19:51:51 INFO - Running post-action listener: _start_resource_monitoring 19:51:51 INFO - Starting resource monitoring. 19:51:51 INFO - [mozharness: 2017-05-12 02:51:51.709347Z] Finished create-virtualenv step (success) 19:51:51 INFO - [mozharness: 2017-05-12 02:51:51.710290Z] Running install step. 19:51:51 INFO - Running pre-action listener: _resource_record_pre_action 19:51:51 INFO - Running main action method: install 19:51:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:51:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:51:51 INFO - Reading from file tmpfile_stdout 19:51:51 INFO - Detecting whether we're running mozinstall >=1.0... 19:51:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 19:51:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 19:51:52 INFO - Reading from file tmpfile_stdout 19:51:52 INFO - Output received: 19:51:52 INFO - Usage: mozinstall [options] installer 19:51:52 INFO - Options: 19:51:52 INFO - -h, --help show this help message and exit 19:51:52 INFO - -d DEST, --destination=DEST 19:51:52 INFO - Directory to install application into. [default: 19:51:52 INFO - "/builds/slave/test"] 19:51:52 INFO - --app=APP Application being installed. [default: firefox] 19:51:52 INFO - mkdir: /builds/slave/test/build/application 19:51:52 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'] 19:51:52 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 19:52:06 INFO - Reading from file tmpfile_stdout 19:52:06 INFO - Output received: 19:52:06 INFO - /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox 19:52:06 INFO - Running post-action listener: _resource_record_post_action 19:52:06 INFO - [mozharness: 2017-05-12 02:52:06.761469Z] Finished install step (success) 19:52:06 INFO - [mozharness: 2017-05-12 02:52:06.761624Z] Running run-tests step. 19:52:06 INFO - Running pre-action listener: _resource_record_pre_action 19:52:06 INFO - Running main action method: run_tests 19:52:06 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 19:52:06 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 19:52:06 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 19:52:06 INFO - 2017-05-11 19:52:06.776 screenresolution[1626:7311] starting screenresolution argv=screenresolution get 19:52:06 INFO - 2017-05-11 19:52:06.779 screenresolution[1626:7311] Display 0: 1600x1200x32@60 19:52:06 INFO - 2017-05-11 19:52:06.784 screenresolution[1627:7313] starting screenresolution argv=screenresolution list 19:52:06 INFO - Available Modes on Display 0 19:52:06 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 19:52:06 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 19:52:06 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 19:52:06 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 19:52:07 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 19:52:07 INFO - Intel Iris: 19:52:07 INFO - Chipset Model: Intel Iris 19:52:07 INFO - Type: GPU 19:52:07 INFO - Bus: Built-In 19:52:07 INFO - VRAM (Dynamic, Max): 1536 MB 19:52:07 INFO - Vendor: Intel (0x8086) 19:52:07 INFO - Device ID: 0x0a2e 19:52:07 INFO - Revision ID: 0x0009 19:52:07 INFO - Displays: 19:52:07 INFO - Display: 19:52:07 INFO - Resolution: 1600 x 1200 @ 60 Hz 19:52:07 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 19:52:07 INFO - Display Serial Number: 1600x1200 60 19:52:07 INFO - Main Display: Yes 19:52:07 INFO - Mirror: Off 19:52:07 INFO - Online: Yes 19:52:07 INFO - Rotation: Supported 19:52:07 INFO - Return code: 0 19:52:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 19:52:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 19:52:07 INFO - Python 2.7.3 19:52:07 INFO - Return code: 0 19:52:07 INFO - grabbing minidump binary from tooltool 19:52: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')]} 19:52:07 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 0x10a266d30>, '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 0x10a18ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a25bbe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 19:52:07 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 19:52:07 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 19:52:07 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 19:52:07 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 19:52:07 INFO - Return code: 0 19:52:07 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 19:52:07 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 19:52:07 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 19:52:07 INFO - ENV: RUST_BACKTRACE is now 1 19:52:07 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 19:52:07 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 19:52:07 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/BxrKAfh4QBmnGyFUhUrbEA/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0366', '--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 19:52:07 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/BxrKAfh4QBmnGyFUhUrbEA/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0366 --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 19:52:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3kBE4oMqWs/Render', 19:52:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:52:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:52:07 INFO - 'HOME': '/Users/cltbld', 19:52:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:52:07 INFO - 'LOGNAME': 'cltbld', 19:52:07 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 19:52:07 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 19:52:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:52:07 INFO - 'MOZ_NO_REMOTE': '1', 19:52:07 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 19:52:07 INFO - 'NO_EM_RESTART': '1', 19:52:07 INFO - 'PAGER': '/bin/cat', 19:52:07 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:52:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:52:07 INFO - 'PWD': '/builds/slave/test', 19:52:07 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 19:52:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:52:07 INFO - 'RUST_BACKTRACE': '1', 19:52:07 INFO - 'SHELL': '/bin/bash', 19:52:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0lC6xc825h/Listeners', 19:52:07 INFO - 'TMPDIR': '/var/folders/fg/pfrf4nhd1hz969r_0mf1rb2400000w/T/', 19:52:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:52:07 INFO - 'USER': 'cltbld', 19:52:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:52:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:52:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:52:07 INFO - 'XPC_FLAGS': '0x0', 19:52:07 INFO - 'XPC_SERVICE_NAME': '0', 19:52:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:52:07 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/BxrKAfh4QBmnGyFUhUrbEA/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0366', '--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 19:52:07 INFO - mozversion application_buildid: 20170511184347 19:52:07 INFO - mozversion application_changeset: 4e354dd1049072b47680a37eeed391c1f2177dbf 19:52:07 INFO - mozversion application_display_name: Firefox 19:52:07 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 19:52:07 INFO - mozversion application_name: Firefox 19:52:07 INFO - mozversion application_remotingname: firefox 19:52:07 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 19:52:07 INFO - mozversion application_vendor: Mozilla 19:52:07 INFO - mozversion application_version: 54.0 19:52:07 INFO - mozversion platform_buildid: 20170511184347 19:52:07 INFO - mozversion platform_changeset: 4e354dd1049072b47680a37eeed391c1f2177dbf 19:52:07 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 19:52:07 INFO - mozversion platform_version: 54.0 19:52:07 INFO - using testdate: 1494557527 19:52:07 INFO - actual date: 1494557527 19:52:07 INFO - starting webserver on 'localhost:49229' 19:52:07 INFO - SUITE-START | Running 2 tests 19:52:07 INFO - TEST-START | dromaeo_css 19:52:07 INFO - operating with platform_type : mac_ 19:52:07 INFO - Initialising browser for dromaeo_css test... 19:52:07 INFO - TEST-INFO | started process 1637 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/fg/pfrf4nhd1hz969r_0mf1rb2400000w/T/tmpXz9_y5/profile http://localhost:49229/getInfo.html) 19:52:10 INFO - PROCESS | 1637 | 2017-05-11 19:52:10.412 plugin-container[1640:7484] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4a53, name = 'com.apple.tsm.portname' 19:52:10 INFO - PROCESS | 1637 | See /usr/include/servers/bootstrap_defs.h for the error codes. 19:52:10 INFO - PROCESS | 1637 | 2017-05-11 19:52:10.413 plugin-container[1640:7484] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4d17, name = 'com.apple.CFPasteboardClient' 19:52:10 INFO - PROCESS | 1637 | See /usr/include/servers/bootstrap_defs.h for the error codes. 19:52:10 INFO - PROCESS | 1637 | 2017-05-11 19:52:10.413 plugin-container[1640:7484] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 19:52:10 INFO - PROCESS | 1637 | __metrics Screen width/height:1600/1200 19:52:10 INFO - PROCESS | 1637 | colorDepth:24 19:52:10 INFO - PROCESS | 1637 | Browser inner width/height: 1024/689 19:52:10 INFO - PROCESS | 1637 | __metrics 19:52:10 ERROR - PROCESS | 1637 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3371: TypeError: this.tabs is undefined 19:52:11 INFO - TEST-INFO | 1637: exit 0 19:52:11 INFO - Browser initialized. 19:52:11 INFO - Running cycle 1/1 for dromaeo_css test... 19:52:11 INFO - TEST-INFO | started process 1641 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/fg/pfrf4nhd1hz969r_0mf1rb2400000w/T/tmpXz9_y5/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:52:13 INFO - PROCESS | 1641 | 2017-05-11 19:52:13.454 plugin-container[1642:7613] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4247, name = 'com.apple.tsm.portname' 19:52:13 INFO - PROCESS | 1641 | See /usr/include/servers/bootstrap_defs.h for the error codes. 19:52:13 INFO - PROCESS | 1641 | 2017-05-11 19:52:13.456 plugin-container[1642:7613] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8617, name = 'com.apple.CFPasteboardClient' 19:52:13 INFO - PROCESS | 1641 | See /usr/include/servers/bootstrap_defs.h for the error codes. 19:52:13 INFO - PROCESS | 1641 | 2017-05-11 19:52:13.456 plugin-container[1642:7613] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 19:52:23 INFO - PROCESS | 1641 | RSS: Main: 174510080 19:52:23 INFO - PROCESS | 1641 | 19:55:25 INFO - PROCESS | 1641 | Cycle 1(1): loaded http://localhost:49229/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49229/tests/dromaeo/cssquery-ext.html) 19:55:27 INFO - PROCESS | 1641 | RSS: Main: 189480960 19:55:27 INFO - PROCESS | 1641 | 19:58:30 INFO - PROCESS | 1641 | Cycle 1(1): loaded http://localhost:49229/tests/dromaeo/cssquery-ext.html (next: http://localhost:49229/tests/dromaeo/cssquery-jquery.html) 19:58:30 INFO - PROCESS | 1641 | RSS: Main: 205328384 19:58:30 INFO - PROCESS | 1641 | 20:01:32 INFO - PROCESS | 1641 | Cycle 1(1): loaded http://localhost:49229/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49229/tests/dromaeo/cssquery-mootools.html) 20:01:33 INFO - PROCESS | 1641 | RSS: Main: 208756736 20:01:33 INFO - PROCESS | 1641 | 20:04:35 INFO - PROCESS | 1641 | Cycle 1(1): loaded http://localhost:49229/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49229/tests/dromaeo/cssquery-prototype.html) 20:04:35 INFO - PROCESS | 1641 | RSS: Main: 211828736 20:04:35 INFO - PROCESS | 1641 | 20:07:38 INFO - PROCESS | 1641 | Cycle 1(1): loaded http://localhost:49229/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49229/tests/dromaeo/cssquery-yui.html) 20:07:39 INFO - PROCESS | 1641 | RSS: Main: 214343680 20:07:39 INFO - PROCESS | 1641 | 20:10:42 INFO - PROCESS | 1641 | Cycle 1(1): loaded http://localhost:49229/tests/dromaeo/cssquery-yui.html (next: http://localhost:49229/tests/dromaeo/cssquery-dojo.html) 20:10:42 INFO - PROCESS | 1641 | RSS: Main: 212520960 20:10:42 INFO - PROCESS | 1641 | 20:10:42 INFO - PROCESS | 1641 | __start_tp_report 20:10:42 INFO - PROCESS | 1641 | _x_x_mozilla_page_load 20:10:42 INFO - PROCESS | 1641 | _x_x_mozilla_page_load_details 20:10:42 INFO - PROCESS | 1641 | |i|pagename|runs| 20:10:42 INFO - PROCESS | 1641 | |0;dojo.html;3639.5814481334655;3642.7814331140116;3673.5224420825293;3678.558572971244;3688.114852435416;5228.686278823273;6198.752049918004;6264.937350626498;6292.402221788934;6313.337099604544;6009.489193418564;6177.814665560029;6204;6231.844203894905;6241.438270555649;5494.2857428534235;5531.502164805167;5593.580481463886;5596.0206963781375;5662.801801936933;5651.886962260753;5671.06427439472;5678.46054624812;5685.232493613351;5688.886222275552;5441.374241962166;5479.986202552521;5651.208830763697;5651.660900345972;5710.1434784782405;5532.529734972532;5544.362398324204;5552.750126244328;5557.694326812024;5558.861028474321;4709.246520556729;5362.436944120889;5415.620906536544;5509.393966663663;5555.083411237141;6278.843028924313;6297.685115744194;6303.243610766692;6310.337414571496;6333.96833015832;5706.315242170925;5922.526197904157;6626.171728533934;6634.800955971328;6651.966740166316;6470.708818103197;6482.254540727782;6652.800415987528;6746.763863264811;6802.795916122524;6211.596246243979;6290.961991271434;6301.77943771975;6308.936910630835;6319.14691516644;6877.4841886857685;6913.10129683138;6954.965225173943;6961.408280296231;6976.407005404496;6314.62112273265;6324.3043265240785;6556.901646475307;6571.5399922005;6576.44100251484;6607.240167380813;6609.801705948829;6642.535022548375;6672.165979252593;6701.731930722814;6599.274079851212;6605.24039735427;6622.635755033423;6628.370304821035;6637;6331.461825744863;6553.574017688835;6557.082008518812;6621.271660117383;6653.434458071021;5229.320188375486;5238.5023422774775;5262.105442074857;5281.0758117329315;5323.680579165262;4588.2888152336445;4625.259958406638;4645.442546894395;4699.130660827689;4719.1033703596895;5629.789595237044;5859.1797148399255;6307.810765676945;6325.683715814283;6326.2092238470195;6596.86806263872;6721.395074443323;6725.831854203684;6747.561408508432;6758.83102922421;6237.532185086136;6279.340669229757;6292.937070629327;6354.205724284465;6367.713452894631;4939.950600493949;5928.555358348055;5939.3763654816485;5955.225820643375;5957.880842383128;6935.618540980194;6972.477064220102;6973.651317434006;6975.581465112109;7000.9649951750935;5306.310179676618;5589.972050139886;6446.258680251643;6726.966365168143;6731.730730770714;6681.398674119353;6820.045193672989;6858;6913.481488888454;6926.722931082701;6631.502637302126;6637.668116594247;6643.136392269167;6650.90023649636;6652.900206497004;6573.5398522103;6717.429018533284;6740.865182696417;6757.155355580552;6760.019797129487;5360.758765855624;5367.785288588413;6263.311035786151;6390.7763228286785;6444.1622589065;6494.772682956074;6508.479321654351;6516.478681705357;6522.043386983981;6532.608043517404;6241.407066328691;6303.401176888188;6365.363463653597;6368.554201205871;6401.359864013748;6260.217472815898;6297.055441683784;6374.553781235269;6396.744130234739;6531.804045878631;5314.946850531445;6319.652419116809;6363.427291543783;6364.140840986408;6364.236291645032 20:10:42 INFO - PROCESS | 1641 | |1;ext.html;767.8771396576549;836.8619177835658;855.7518319687291;881.7531091294431;1004.1213937804416;38218.853434396944;39294;40591.39112913309;40630.59369406305;40715.99999999996;39211;39377;39397.81806545808;39419.0145246369;39511.814645560604;8248.46384984977;8367.581620918929;8394.202550757698;11451.541938322458;11689.181757276994;11280.097592192646;11315.603953861617;11321.20751547388;11324.943375283112;11329.490172942196;11324.263922844988;11326.263792853439;11349.886501135006;11350.545978160864;11371.147163962694;11281.37952412617;11282.984531392172;11295.435228238637;11307.604233851776;11315.547378104908;11076.335419874753;11133;11134;11135.109191264679;11151.442427878656;35257.29485410303;35310.29379412397;35526.82236588826;35555.64444355575;35591.28817423637;3985.1830374773103;3994.540627827808;4009.218202450523;4034.23349563582;4056.5132184137797;22860.542789144125;26469.470610587872;27969.160925172277;27971.16086517408;28087;3145.2294187924103;3270.2314955985325;3280.8687652493873;3322.069820450278;3391.8982430527126;348216;349157;351119;351311.2434437837;359848;43856.3421548677;43937.78031109888;44107.55892441099;44144.779276103414;44185.779071105084;38752.806235969205;46823.76588117037;46887;46965.76517117392;47085;12128.514859405701;14588.416463341347;14631;14702.117872927658;14703.117812931258;10400.947995259976;12642.367881605884;12708.3010434427;12735.108542401942;12746.044046696536;11662.533498660128;12244.081693872995;12264.693382665326;12282.815757763641;12374.566890158801;4781.015878410421;5645.294338207724;5693.572982026365;5704.543636509069;5724.484796368347;1000.7648202672366;1042.9478526073665;1101.7990390474422;1124.966251012471;1223.9388030598434;25637.846296916683;27090.864545677145;27472.862635687092;27696.723032769816;27782.722172778012;960.5101398286924;1105.9612913547987;1198.2151690642643;1291.1607455154037;1329.5280175537546;13919.512817051354;15303.387864485296;16425.83574164267;16533.751993720103;16834.663306733797;480272;606921;615101;616895;618630;43332.78333608312;50442.747786260836;50751;50775.730606735124;50824.98350033053;1312.6980794417339;1469.4195792662053;1500.3473489032306;1505.3150816378522;1517.6357674158344;25067.49865002726;26840.194794156207;28376.179304147496;28640.85679571589;28658.570121447778;41328.58671413247;41511;41560.376594351714;41627.79186104171;41775.16449670929;432610;434705;435195.94412167685;435682.82158589;437693;23621.409464763517;29829;30024.399512010074;30336.69663303339;30455.238619034702;38646.806765967114;39232.60767392404;39315.80342098271;39317.2136557273;39343.80328098341;27968.461734604793;28360.85819570889;28543;28896;29440.11679649614;23249;23687.289381318587;27893.72106278911;28270.293242669097;28876.855615721786;29343.413131737674;32815.34369312648;32859.83570082134;34399.82800085984;34918.825405872805;29488.557671634513;29705.85147074251;29743.70256297496;30385.544216837206;30437.086887393412;24093;24132.51734965326;24277.757222428256;24608.6308705366;24650.87674561616 20:10:42 INFO - PROCESS | 1641 | |2;jquery.html;427.7519038957405;442.54417949512003;442.69454076687083;448.08143306222235;456.86522475869623;18095;21451.463713407153;22026.88986555067;22284.442888927784;22479.876006199705;22485.988130534126;23277.95249213785;23814.64278035831;23918.684472354013;24241.909114089864;6808.795736127911;6945.89581156283;7001.159860816709;7616.961915190416;7917.722879699211;7580.393568514533;7932.682692692314;7963.522188668699;7977.202279772035;7995.880061799077;8006.399520035991;8061.355091592659;8062.113167551564;8097.635606397726;8175.95912020439;7794.688212471494;7981.9201807982045;8057.435979481439;8071.313938315221;8071.394645401619;7567;7792.532448053135;7792.688292468294;7854.450188486809;7862.253085956825;27490;27897.88408463659;28013.57979630307;28068;28347.149585512467;2889.8555072246513;2950.572167035768;3024.3195281061585;3040.832754198518;3068.6471055828642;16254.918725406298;16932.40736574214;17019.65960680792;17138.22877970482;17365.65268694619;1860.646477169347;2028.4218997585765;2183.6570509136927;2193.243331050785;2247.5617254635354;162834;163200;170435;170577;173850.3922441177;21092.78907210908;21093.578128437654;21204.46988825261;21297.722136671848;21473.248436304966;31786.841065794524;31888.043358699277;32144.678553214635;32163;32241.355172896412;10720.785584288271;10880.945595271973;11058.723531911766;11064.502097405637;11088.445577721082;9133.725988220363;9152.679656212;9173.40372875761;9321.974582795887;9337.112974267433;9563;9608.95195524018;9679.225661947168;9780.95109524462;9794.95102524497;4026.5570787213383;4084.203580301872;4141.875743727693;4143.8964025899595;4152.480939882515;1066.3015724700333;1074.8495210670512;1088.3905013192636;1102.2835157147917;1125.2517076144436;21838.563228735653;22012.889935550218;22016.77983220179;22089.668654970188;22112.33662990113;1094.141099237095;1106.4136007915818;1110.9611163609236;1129.4578602270956;1139.629620373382;11285.153613479022;11543.307401555934;11637.825432618518;11666.824997625043;11682.415879206006;768050.1597491977;768165;769986;780096;794814;146018;147063;155296;158283.20858395635;159995;1158.6639874436487;1167.9065674746;1195.5397172088635;1202.212550779241;1210.2798834693344;18067.45797626073;18227.270909163486;19121.904390477957;19257;19498.8050119497;48605.055797767694;49950.50049499459;58046.7097664509;63732.68133659302;64820.703585927076;57351.71324143353;73749.89375159485;74503;75911.62044189744;76126;8657.740267791976;11547.960683538522;11726.179167458537;11774.234674746114;11948.70128246766;11874.346910919983;12760.489580416679;12966.611001669966;13096.410661520446;13311.600651980456;9766.21870250364;10436.425996570262;11245.98786109254;11422.885771142182;11576.247543909956;10602.681919542427;10605.363678179334;10717.35695858251;10862.293950893167;10996.010359067723;9418.340716150078;11551.711207219887;11822.704432389259;11879.049676025725;12025.45885435175;11514.481848316678;11558.165254090336;11677.532898683958;11819.11356648272;11892.881071189177;11412.771744565229;11626.82559761621;11745.412729363668;11764.294142351486;11833.34916579562 20:10:42 INFO - PROCESS | 1641 | |3;mootools.html;1587.5792914877557;1604.9438269660563;1639.2541393665883;1657.651893102449;1661.7341225403934;11045.447727613628;11433.314001159937;11697.298162110268;11707.882921170796;11780.175387722862;11367.204295699305;11500.309981401122;11556.959873611373;11569.768604627903;11602.709932251699;2467.4078221226864;2507.8119141064403;2512.906885504802;2515.383730985902;2516.584763514018;2455.693038370204;2476.4675594747127;2511.183865243794;2515.71069327027;2515.8993640254366;2039.6226698060832;2464.0759715106833;2485.2544236729;2493.4514406830467;2507.047322017631;2039.4799326171806;2421.3704436846547;2464.2114523352625;2471.7281099079087;2489.8630575318352;2448.081969261527;2463.790577800889;2470.629405589158;2476.554220240356;2476.839005464639;11490.080793536581;11634.243774154653;11752;11758.412079395996;11759.823602645967;5930.9406905931255;6075.574709770318;6127.785527506516;6129.356417576135;6133.080037994337;3639.94540081899;3711.2391959648207;3761;3761.6426439488205;3846.557645870733;1294.4563283420987;1904.457229689532;1952.4142757172792;1980.22741442271;2020.737304150451;13429.194248345228;14258.786118208236;14595.41618335276;15969;16162.838371616252;4769.260764581495;4810.855674329776;4877.219644856806;4926.852194434173;4936;12060.819087713691;12085.879141208652;12098.637040888787;12120.515179392902;12124.454399552042;6075.027995520695;6077.361877002851;6083.8174854754425;6086.117512960596;6095.695215239309;5296.179092240709;5439.428859969726;5519.613627046148;5546.084895992116;5572.331320241597;3952.584978577208;4061.6750659947725;4071.633552980246;4093;4125.855595054159;913.2328843771264;933.5565606336936;1084.6366467233408;1093.748437859297;1103.7351035751522;834.995825020871;957.3346524165638;965.2278177458005;979.8790851139145;980.612658000086;6726.327367263332;6760.932390676128;6905.205901321412;6970.93029069713;7006.824829379204;981.5484876956538;988.4365911430559;1013.3160116921196;1029.459533744775;1075.930064545802;3447.844846981892;4033.4554835097474;4263.914721705549;4467.977660111678;4541.795619197144;13024.41390137446;14545.854541454662;14562.126272423688;14593.41626334956;14747.852521474862;10425.374477531372;14174.503892363718;14372.85627143736;14494.34775435108;14515;10577.418241996764;10598.682039538826;10677.199210059276;10680.412577308167;10685.946570267255;6667.633280169637;6685.799426017227;6807.421369183675;6863.176418829772;6926.688299026657;10717.571297148339;12951.352432378155;12953.481860725466;12957.676058098623;12959.352032398154;12752.234865908074;12845.871541284841;12928.741425171633;12988.675283117998;13046.673833154247;9287.442753434816;9604.9039509604;9611.951940240255;9616;9620.326577139533;10523.94738026305;10552.366857988545;10569.260151789302;10649.30779499361;10674.839877402;8975.910240897507;9074.45553266806;9144.497052662167;9145.679901203426;9243.260539156985;8734.17025382613;8739.91260087391;8755.737327880173;8781.560921954004;8952.507612081137;8139.186081391814;8162.347012238888;8247.721603151282;8266.13205613402;8268.297194738514;5950.494207992196;5957.434043765836;5961.701914904324;6122.724477398617;6181.598196117238;5252.9737351313;6046.365131661289;6175.073738939195;6236.126942228001;6353.491720662243 20:10:42 INFO - PROCESS | 1641 | |4;prototype.html;339.7638641144404;351.34298861129673;390.1202787713705;392.1666458775103;407.51098681582107;4340.739555626661;4475.686701930867;5246.816361427351;5270.894582108361;5363.731813409334;4435.889102772432;4703.365045718827;5164.354455693038;5282.5245727884485;5357.892842143155;3401.809366721652;3918.5885482024405;4036.777977211252;4279.229738647043;4417.403650507173;3583.94624080639;3932.348413666253;4352.477702675684;4382.758948257844;4433.778311084449;4401.317795741649;4407.906244533837;4436.534163912791;4440.444944381952;4466.5980061794435;3683.1087596236825;3976.363781794928;4152.345935576688;4245.002424430257;4271.765052922088;3252.7560432967384;3717.981410092932;4020.698447616411;4024.4969378827645;4037.798110094513;4501.7974191161475;5004.574611158056;5145;5161.5870730341485;5168.819091331823;4545.49999500058;4824.348712923746;4833.29917162013;5045.5459008689395;5176.275321454999;5063.670861393997;5390.442308076651;5667.574931880125;5676.971615141898;5745.265577374698;3848.843947962672;4347.152460204432;4487.640988720895;4617.930731039037;4634.146341463442;5624.971875140598;5787.913181302284;5990.8502287442725;6000.729967151445;6056.788012419544;4123.8556650517085;4473.642108631302;4588.426446694163;4712.2224832902875;4728.595411303856;4707.835225767081;4746.572808447257;4753.762311884392;4810.542998415145;4818.072770891671;4542.364069030338;4717.834875779331;4977.352944117241;5079.5428411443145;5109.744512774347;4875.172989203458;5175.842735931079;5574.414686457944;5719.914201286984;5749.3963133871175;1543.772700702944;1610.4443966831425;1632.812226593957;1633.9509814705577;1637.8116516600744;1118.5358076398365;1165.2018367418345;1223.1286132217544;1224.6528818299798;1253.4296894912793;5031.754518335726;5076.18780995039;5388.703621300788;5408.864778380572;5451;5093.261477085802;5232.947670523322;5629.930313240471;5832.562557808096;5910.404190868441;4580.509920538221;4875.658703890694;4896.290037944478;5102.448376402945;5455.018096742545;2727;2783.5685468752376;2823.6329277194245;3060.4491191585284;3069.8181200237623;5460.781568737206;5890.823275301748;5973.701314934236;6041.794744918073;6171.352008039181;5441.966026454961;5854.121881717776;5882.44116808911;5917.31950825652;5998.670073145931;5547.1679248113105;5662.8301150965535;5794.159846822272;5843.853903652443;5973.910391344133;5340.866478338073;5637;5645.774169033357;5924.555658325729;5942.0391270879645;4796.5203479651755;4949.787302111006;5017.377062277758;5423.83728488146;5771.653700777967;5562;5675.347335056356;5797.710114494343;5996.2504686914135;6038.456538911519;3839.7120215984473;4249.405083288404;4367.45406824147;4561.429821272341;4574.99350142968;4862.568715642179;5518.641288316247;5625.746841392229;5641.787015791624;5791.884162316815;4600.079984003146;4717.952820471752;5520.779168833363;5550.973069982067;5746.816209840234;4826.607786913892;5059.620528460307;5098.646421257738;5630.493255607015;5637.534241097263;4377.424127314101;5055.898882022266;5504.311961004874;5594.545418191218;5658.377578466283;5352.866178345573;5450.8909821802545;5705.572082093777;5718.999175144416;5748.223989761329;5055.823046193365;5529.391766905556;5554.75003624846;5580.832575022756;5665.773369065356 20:10:42 INFO - PROCESS | 1641 | |5;yui.html;758.9392848572111;766.1380946435261;802.6227672993691;883.2404132446095;885.8184072265186;15729.449469268578;17398.565035874108;17805.198766055528;17971.371002014934;18038.098095095258;16082.919585402085;16114.919425402857;16595.336186552522;16700.164991750393;16724.66550668986;978.9706308810747;996.0985308296003;1004.5328922051244;1007.1741172238767;1008.500792107904;976.1702552830109;991.0188912976138;993.9304248702593;1020.1226944827442;1035.7824856780048;1070.9571617135305;1079;1085.7719878825417;1086.8369744538304;1088.086007753487;1076.6500887211648;1077.6713102503695;1081.0378762900946;1081.0972837680547;1082.182951871336;797.8723404255292;802.6709049289764;802.7230605441114;803.120582961656;805.1304591041661;14490;17286.394976175772;17390.56523586908;17559.20983555743;18347.082645867788;14364.20996845173;14656;14840.406383744637;15017.549473515812;15201.467948621745;11792.469338879771;12078.33569153705;12154.524967145142;12323.445444955;12444.942179914719;3785.3701096547934;3810.485584446092;3836.7506112102624;3856.478655889173;3930.213957208541;116831;118385.40807295995;119435.4028229862;120791.3960430201;122341.38829305797;11249.887501124884;11387.475567359457;11535.52026537143;11726.94136529312;11796.94101529487;23919.52160956806;23945.52108957811;23955.880220599134;23980.04079836822;24029.51940961171;7663.770086897291;8016.238457346542;8113.837723245502;8317.708880189164;8404.957975210207;4582.564656357641;4603.240465323252;4632.698874573142;4652.488226295105;4807.711537307773;3006.188329151117;3535.505029295901;3607.8737244196327;3627.4014787560286;3628.238070005276;633.0915136778738;659.1266571792394;660.0824853453703;661.2065521374274;672.7376323233942;636.6530241018656;643.462708638283;673.2022553274386;680.6936878404738;704.2781149321884;10228.668806721285;10243.539040743035;10358.223133264884;10540.736481588021;10604.310719803343;659.8911179655399;671.4058058618158;699.6047233313263;705.2418649951322;716.8458781362032;3629.7277704172293;4920.852374428773;4962.454130045692;4984.975075124674;5009.89980200394;89582.55208723915;93595.06404936;95257.523712381;96304;96345.51827240818;56161;69122.65438672775;72077;78416;79258;70070;71797.92303115354;76381;77636.22363776402;78170;10117.949410252902;11339.376334301693;11492.597759078726;11593.594224202112;11657.475413606238;45971;47066;49532.25701614551;49552;51633.48366516437;56133;64691;69624.30375696179;69960.65019675072;73032;13252.337383130998;13253.07228493996;13289.13620614657;13475.865241347461;14061.789073164115;14560.271986400849;14801.777973330194;14857.331420085906;14950.401983920521;15154.696906062038;13752.243626600233;14145.36345864418;14300.427982880985;14562.980591358502;14969.625759356104;13834.37745301484;14425.78361324602;14463.49377771773;14467.565973020826;14468.782968255275;13436.596902092953;13545.59363219105;14014.509492167725;14170.433182672577;14467.49363772263;10018.749531261778;10538.262321637412;10851.294665846695;10903.727406814893;10907.781844363228;11442;11682.47428865686;11698.94150529242;11707.590234341757;11720.413979301171 20:10:42 INFO - PROCESS | 1641 | __end_tp_report 20:10:42 INFO - PROCESS | 1641 | __start_cc_report 20:10:42 INFO - PROCESS | 1641 | _x_x_mozilla_cycle_collect,393 20:10:42 INFO - PROCESS | 1641 | __end_cc_report 20:10:42 INFO - PROCESS | 1641 | __startTimestamp1494558642416__endTimestamp 20:10:42 INFO - PROCESS | 1641 | 20:10:42 INFO - PROCESS | 1641 | ------- Summary: start ------- 20:10:42 INFO - PROCESS | 1641 | Number of tests: 6 20:10:42 INFO - PROCESS | 1641 | 20:10:42 INFO - PROCESS | 1641 | [#0] dojo.html Cycles:165 Average:6114.25 Median:6319.15 stddev:714.96 (11.3%) stddev-sans-first:690.29 20:10:42 INFO - PROCESS | 1641 | Values: 3639.6 3642.8 3673.5 3678.6 3688.1 5228.7 6198.8 6264.9 6292.4 6313.3 6009.5 6177.8 6204.0 6231.8 6241.4 5494.3 5531.5 5593.6 5596.0 5662.8 5651.9 5671.1 5678.5 5685.2 5688.9 5441.4 5480.0 5651.2 5651.7 5710.1 5532.5 5544.4 5552.8 5557.7 5558.9 4709.2 5362.4 5415.6 5509.4 5555.1 6278.8 6297.7 6303.2 6310.3 6334.0 5706.3 5922.5 6626.2 6634.8 6652.0 6470.7 6482.3 6652.8 6746.8 6802.8 6211.6 6291.0 6301.8 6308.9 6319.1 6877.5 6913.1 6955.0 6961.4 6976.4 6314.6 6324.3 6556.9 6571.5 6576.4 6607.2 6609.8 6642.5 6672.2 6701.7 6599.3 6605.2 6622.6 6628.4 6637.0 6331.5 6553.6 6557.1 6621.3 6653.4 5229.3 5238.5 5262.1 5281.1 5323.7 4588.3 4625.3 4645.4 4699.1 4719.1 5629.8 5859.2 6307.8 6325.7 6326.2 6596.9 6721.4 6725.8 6747.6 6758.8 6237.5 6279.3 6292.9 6354.2 6367.7 4940.0 5928.6 5939.4 5955.2 5957.9 6935.6 6972.5 6973.7 6975.6 7001.0 5306.3 5590.0 6446.3 6727.0 6731.7 6681.4 6820.0 6858.0 6913.5 6926.7 6631.5 6637.7 6643.1 6650.9 6652.9 6573.5 6717.4 6740.9 6757.2 6760.0 5360.8 5367.8 6263.3 6390.8 6444.2 6494.8 6508.5 6516.5 6522.0 6532.6 6241.4 6303.4 6365.4 6368.6 6401.4 6260.2 6297.1 6374.6 6396.7 6531.8 5314.9 6319.7 6363.4 6364.1 6364.2 20:10:42 INFO - PROCESS | 1641 | 20:10:42 INFO - PROCESS | 1641 | [#1] ext.html Cycles:180 Average:57988.29 Median:29724.78 stddev:125402.87 (421.9%) stddev-sans-first:125681.07 20:10:42 INFO - PROCESS | 1641 | Values: 767.9 836.9 855.8 881.8 1004.1 38218.9 39294.0 40591.4 40630.6 40716.0 39211.0 39377.0 39397.8 39419.0 39511.8 8248.5 8367.6 8394.2 11451.5 11689.2 11280.1 11315.6 11321.2 11324.9 11329.5 11324.3 11326.3 11349.9 11350.5 11371.1 11281.4 11283.0 11295.4 11307.6 11315.5 11076.3 11133.0 11134.0 11135.1 11151.4 35257.3 35310.3 35526.8 35555.6 35591.3 3985.2 3994.5 4009.2 4034.2 4056.5 22860.5 26469.5 27969.2 27971.2 28087.0 3145.2 3270.2 3280.9 3322.1 3391.9 348216.0 349157.0 351119.0 351311.2 359848.0 43856.3 43937.8 44107.6 44144.8 44185.8 38752.8 46823.8 46887.0 46965.8 47085.0 12128.5 14588.4 14631.0 14702.1 14703.1 10400.9 12642.4 12708.3 12735.1 12746.0 11662.5 12244.1 12264.7 12282.8 12374.6 4781.0 5645.3 5693.6 5704.5 5724.5 1000.8 1042.9 1101.8 1125.0 1223.9 25637.8 27090.9 27472.9 27696.7 27782.7 960.5 1106.0 1198.2 1291.2 1329.5 13919.5 15303.4 16425.8 16533.8 16834.7 480272.0 606921.0 615101.0 616895.0 618630.0 43332.8 50442.7 50751.0 50775.7 50825.0 1312.7 1469.4 1500.3 1505.3 1517.6 25067.5 26840.2 28376.2 28640.9 28658.6 41328.6 41511.0 41560.4 41627.8 41775.2 432610.0 434705.0 435195.9 435682.8 437693.0 23621.4 29829.0 30024.4 30336.7 30455.2 38646.8 39232.6 39315.8 39317.2 39343.8 27968.5 28360.9 28543.0 28896.0 29440.1 23249.0 23687.3 27893.7 28270.3 28876.9 29343.4 32815.3 32859.8 34399.8 34918.8 29488.6 29705.9 29743.7 30385.5 30437.1 24093.0 24132.5 24277.8 24608.6 24650.9 20:10:42 INFO - PROCESS | 1641 | 20:10:42 INFO - PROCESS | 1641 | [#2] jquery.html Cycles:180 Average:43997.35 Median:22021.83 stddev:129389.15 (587.5%) stddev-sans-first:129710.76 20:10:42 INFO - PROCESS | 1641 | Values: 427.8 442.5 442.7 448.1 456.9 18095.0 21451.5 22026.9 22284.4 22479.9 22486.0 23278.0 23814.6 23918.7 24241.9 6808.8 6945.9 7001.2 7617.0 7917.7 7580.4 7932.7 7963.5 7977.2 7995.9 8006.4 8061.4 8062.1 8097.6 8176.0 7794.7 7981.9 8057.4 8071.3 8071.4 7567.0 7792.5 7792.7 7854.5 7862.3 27490.0 27897.9 28013.6 28068.0 28347.1 2889.9 2950.6 3024.3 3040.8 3068.6 16254.9 16932.4 17019.7 17138.2 17365.7 1860.6 2028.4 2183.7 2193.2 2247.6 162834.0 163200.0 170435.0 170577.0 173850.4 21092.8 21093.6 21204.5 21297.7 21473.2 31786.8 31888.0 32144.7 32163.0 32241.4 10720.8 10880.9 11058.7 11064.5 11088.4 9133.7 9152.7 9173.4 9322.0 9337.1 9563.0 9609.0 9679.2 9781.0 9795.0 4026.6 4084.2 4141.9 4143.9 4152.5 1066.3 1074.8 1088.4 1102.3 1125.3 21838.6 22012.9 22016.8 22089.7 22112.3 1094.1 1106.4 1111.0 1129.5 1139.6 11285.2 11543.3 11637.8 11666.8 11682.4 768050.2 768165.0 769986.0 780096.0 794814.0 146018.0 147063.0 155296.0 158283.2 159995.0 1158.7 1167.9 1195.5 1202.2 1210.3 18067.5 18227.3 19121.9 19257.0 19498.8 48605.1 49950.5 58046.7 63732.7 64820.7 57351.7 73749.9 74503.0 75911.6 76126.0 8657.7 11548.0 11726.2 11774.2 11948.7 11874.3 12760.5 12966.6 13096.4 13311.6 9766.2 10436.4 11246.0 11422.9 11576.2 10602.7 10605.4 10717.4 10862.3 10996.0 9418.3 11551.7 11822.7 11879.0 12025.5 11514.5 11558.2 11677.5 11819.1 11892.9 11412.8 11626.8 11745.4 11764.3 11833.3 20:10:42 INFO - PROCESS | 1641 | 20:10:42 INFO - PROCESS | 1641 | [#3] mootools.html Cycles:180 Average:6966.52 Median:2512.05 stddev:4311.66 (171.6%) stddev-sans-first:4304.81 20:10:42 INFO - PROCESS | 1641 | Values: 1587.6 1604.9 1639.3 1657.7 1661.7 11045.4 11433.3 11697.3 11707.9 11780.2 11367.2 11500.3 11557.0 11569.8 11602.7 2467.4 2507.8 2512.9 2515.4 2516.6 2455.7 2476.5 2511.2 2515.7 2515.9 2039.6 2464.1 2485.3 2493.5 2507.0 2039.5 2421.4 2464.2 2471.7 2489.9 2448.1 2463.8 2470.6 2476.6 2476.8 11490.1 11634.2 11752.0 11758.4 11759.8 5930.9 6075.6 6127.8 6129.4 6133.1 3639.9 3711.2 3761.0 3761.6 3846.6 1294.5 1904.5 1952.4 1980.2 2020.7 13429.2 14258.8 14595.4 15969.0 16162.8 4769.3 4810.9 4877.2 4926.9 4936.0 12060.8 12085.9 12098.6 12120.5 12124.5 6075.0 6077.4 6083.8 6086.1 6095.7 5296.2 5439.4 5519.6 5546.1 5572.3 3952.6 4061.7 4071.6 4093.0 4125.9 913.2 933.6 1084.6 1093.7 1103.7 835.0 957.3 965.2 979.9 980.6 6726.3 6760.9 6905.2 6970.9 7006.8 981.5 988.4 1013.3 1029.5 1075.9 3447.8 4033.5 4263.9 4468.0 4541.8 13024.4 14545.9 14562.1 14593.4 14747.9 10425.4 14174.5 14372.9 14494.3 14515.0 10577.4 10598.7 10677.2 10680.4 10685.9 6667.6 6685.8 6807.4 6863.2 6926.7 10717.6 12951.4 12953.5 12957.7 12959.4 12752.2 12845.9 12928.7 12988.7 13046.7 9287.4 9604.9 9612.0 9616.0 9620.3 10523.9 10552.4 10569.3 10649.3 10674.8 8975.9 9074.5 9144.5 9145.7 9243.3 8734.2 8739.9 8755.7 8781.6 8952.5 8139.2 8162.3 8247.7 8266.1 8268.3 5950.5 5957.4 5961.7 6122.7 6181.6 5253.0 6046.4 6175.1 6236.1 6353.5 20:10:42 INFO - PROCESS | 1641 | 20:10:42 INFO - PROCESS | 1641 | [#4] prototype.html Cycles:180 Average:4675.30 Median:5050.68 stddev:1314.70 (26.0%) stddev-sans-first:1277.48 20:10:42 INFO - PROCESS | 1641 | Values: 339.8 351.3 390.1 392.2 407.5 4340.7 4475.7 5246.8 5270.9 5363.7 4435.9 4703.4 5164.4 5282.5 5357.9 3401.8 3918.6 4036.8 4279.2 4417.4 3583.9 3932.3 4352.5 4382.8 4433.8 4401.3 4407.9 4436.5 4440.4 4466.6 3683.1 3976.4 4152.3 4245.0 4271.8 3252.8 3718.0 4020.7 4024.5 4037.8 4501.8 5004.6 5145.0 5161.6 5168.8 4545.5 4824.3 4833.3 5045.5 5176.3 5063.7 5390.4 5667.6 5677.0 5745.3 3848.8 4347.2 4487.6 4617.9 4634.1 5625.0 5787.9 5990.9 6000.7 6056.8 4123.9 4473.6 4588.4 4712.2 4728.6 4707.8 4746.6 4753.8 4810.5 4818.1 4542.4 4717.8 4977.4 5079.5 5109.7 4875.2 5175.8 5574.4 5719.9 5749.4 1543.8 1610.4 1632.8 1634.0 1637.8 1118.5 1165.2 1223.1 1224.7 1253.4 5031.8 5076.2 5388.7 5408.9 5451.0 5093.3 5232.9 5629.9 5832.6 5910.4 4580.5 4875.7 4896.3 5102.4 5455.0 2727.0 2783.6 2823.6 3060.4 3069.8 5460.8 5890.8 5973.7 6041.8 6171.4 5442.0 5854.1 5882.4 5917.3 5998.7 5547.2 5662.8 5794.2 5843.9 5973.9 5340.9 5637.0 5645.8 5924.6 5942.0 4796.5 4949.8 5017.4 5423.8 5771.7 5562.0 5675.3 5797.7 5996.3 6038.5 3839.7 4249.4 4367.5 4561.4 4575.0 4862.6 5518.6 5625.7 5641.8 5791.9 4600.1 4718.0 5520.8 5551.0 5746.8 4826.6 5059.6 5098.6 5630.5 5637.5 4377.4 5055.9 5504.3 5594.5 5658.4 5352.9 5450.9 5705.6 5719.0 5748.2 5055.8 5529.4 5554.8 5580.8 5665.8 20:10:42 INFO - PROCESS | 1641 | 20:10:42 INFO - PROCESS | 1641 | [#5] yui.html Cycles:180 Average:20439.19 Median:17682.20 stddev:28607.71 (161.8%) stddev-sans-first:28649.80 20:10:42 INFO - PROCESS | 1641 | Values: 758.9 766.1 802.6 883.2 885.8 15729.4 17398.6 17805.2 17971.4 18038.1 16082.9 16114.9 16595.3 16700.2 16724.7 979.0 996.1 1004.5 1007.2 1008.5 976.2 991.0 993.9 1020.1 1035.8 1071.0 1079.0 1085.8 1086.8 1088.1 1076.7 1077.7 1081.0 1081.1 1082.2 797.9 802.7 802.7 803.1 805.1 14490.0 17286.4 17390.6 17559.2 18347.1 14364.2 14656.0 14840.4 15017.5 15201.5 11792.5 12078.3 12154.5 12323.4 12444.9 3785.4 3810.5 3836.8 3856.5 3930.2 116831.0 118385.4 119435.4 120791.4 122341.4 11249.9 11387.5 11535.5 11726.9 11796.9 23919.5 23945.5 23955.9 23980.0 24029.5 7663.8 8016.2 8113.8 8317.7 8405.0 4582.6 4603.2 4632.7 4652.5 4807.7 3006.2 3535.5 3607.9 3627.4 3628.2 633.1 659.1 660.1 661.2 672.7 636.7 643.5 673.2 680.7 704.3 10228.7 10243.5 10358.2 10540.7 10604.3 659.9 671.4 699.6 705.2 716.8 3629.7 4920.9 4962.5 4985.0 5009.9 89582.6 93595.1 95257.5 96304.0 96345.5 56161.0 69122.7 72077.0 78416.0 79258.0 70070.0 71797.9 76381.0 77636.2 78170.0 10117.9 11339.4 11492.6 11593.6 11657.5 45971.0 47066.0 49532.3 49552.0 51633.5 56133.0 64691.0 69624.3 69960.7 73032.0 13252.3 13253.1 13289.1 13475.9 14061.8 14560.3 14801.8 14857.3 14950.4 15154.7 13752.2 14145.4 14300.4 14563.0 14969.6 13834.4 14425.8 14463.5 14467.6 14468.8 13436.6 13545.6 14014.5 14170.4 14467.5 10018.7 10538.3 10851.3 10903.7 10907.8 11442.0 11682.5 11698.9 11707.6 11720.4 20:10:42 INFO - PROCESS | 1641 | -------- Summary: end -------- 20:10:42 INFO - PROCESS | 1641 | 20:10:42 INFO - TEST-INFO | 1641: exit 0 20:10:42 INFO - TEST-OK | dromaeo_css | took 1115620ms 20:10:42 INFO - TEST-START | kraken 20:10:42 INFO - operating with platform_type : mac_ 20:10:42 INFO - Initialising browser for kraken test... 20:10:43 INFO - TEST-INFO | started process 1652 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/fg/pfrf4nhd1hz969r_0mf1rb2400000w/T/tmpzree7A/profile http://localhost:49229/getInfo.html) 20:10:45 INFO - PROCESS | 1652 | 2017-05-11 20:10:45.357 plugin-container[1654:10080] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x884b, name = 'com.apple.tsm.portname' 20:10:45 INFO - PROCESS | 1652 | See /usr/include/servers/bootstrap_defs.h for the error codes. 20:10:45 INFO - PROCESS | 1652 | 2017-05-11 20:10:45.358 plugin-container[1654:10080] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8c0f, name = 'com.apple.CFPasteboardClient' 20:10:45 INFO - PROCESS | 1652 | See /usr/include/servers/bootstrap_defs.h for the error codes. 20:10:45 INFO - PROCESS | 1652 | 2017-05-11 20:10:45.358 plugin-container[1654:10080] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 20:10:45 INFO - PROCESS | 1652 | __metrics Screen width/height:1600/1200 20:10:45 INFO - PROCESS | 1652 | colorDepth:24 20:10:45 INFO - PROCESS | 1652 | Browser inner width/height: 1024/689 20:10:45 INFO - PROCESS | 1652 | __metrics 20:10:45 ERROR - PROCESS | 1652 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3371: TypeError: this.tabs is undefined 20:10:46 INFO - TEST-INFO | 1652: exit 0 20:10:46 INFO - Browser initialized. 20:10:46 INFO - Running cycle 1/1 for kraken test... 20:10:46 INFO - TEST-INFO | started process 1655 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/fg/pfrf4nhd1hz969r_0mf1rb2400000w/T/tmpzree7A/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 20:10:48 INFO - PROCESS | 1655 | 2017-05-11 20:10:48.273 plugin-container[1656:10205] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8443, name = 'com.apple.tsm.portname' 20:10:48 INFO - PROCESS | 1655 | See /usr/include/servers/bootstrap_defs.h for the error codes. 20:10:48 INFO - PROCESS | 1655 | 2017-05-11 20:10:48.273 plugin-container[1656:10205] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x860b, name = 'com.apple.CFPasteboardClient' 20:10:48 INFO - PROCESS | 1655 | See /usr/include/servers/bootstrap_defs.h for the error codes. 20:10:48 INFO - PROCESS | 1655 | 2017-05-11 20:10:48.273 plugin-container[1656:10205] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 20:10:58 INFO - PROCESS | 1655 | RSS: Main: 174604288 20:10:58 INFO - PROCESS | 1655 | 20:11:01 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49229/tests/kraken/driver.html?testName=audio-beat-detection) 20:11:02 INFO - PROCESS | 1655 | RSS: Main: 187858944 20:11:02 INFO - PROCESS | 1655 | 20:11:04 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49229/tests/kraken/driver.html?testName=audio-dft) 20:11:05 INFO - PROCESS | 1655 | RSS: Main: 196063232 20:11:05 INFO - PROCESS | 1655 | 20:11:08 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49229/tests/kraken/driver.html?testName=audio-fft) 20:11:09 INFO - PROCESS | 1655 | RSS: Main: 199847936 20:11:09 INFO - PROCESS | 1655 | 20:11:11 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49229/tests/kraken/driver.html?testName=audio-oscillator) 20:11:12 INFO - PROCESS | 1655 | RSS: Main: 200081408 20:11:12 INFO - PROCESS | 1655 | 20:11:14 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49229/tests/kraken/driver.html?testName=imaging-gaussian-blur) 20:11:14 INFO - PROCESS | 1655 | RSS: Main: 201367552 20:11:14 INFO - PROCESS | 1655 | 20:11:17 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49229/tests/kraken/driver.html?testName=imaging-darkroom) 20:11:18 INFO - PROCESS | 1655 | RSS: Main: 205029376 20:11:18 INFO - PROCESS | 1655 | 20:11:21 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49229/tests/kraken/driver.html?testName=imaging-desaturate) 20:11:22 INFO - PROCESS | 1655 | RSS: Main: 204197888 20:11:22 INFO - PROCESS | 1655 | 20:11:25 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49229/tests/kraken/driver.html?testName=json-parse-financial) 20:11:25 INFO - PROCESS | 1655 | RSS: Main: 204341248 20:11:25 INFO - PROCESS | 1655 | 20:11:27 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49229/tests/kraken/driver.html?testName=json-stringify-tinderbox) 20:11:27 INFO - PROCESS | 1655 | RSS: Main: 204001280 20:11:27 INFO - PROCESS | 1655 | 20:11:29 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49229/tests/kraken/driver.html?testName=stanford-crypto-aes) 20:11:30 INFO - PROCESS | 1655 | RSS: Main: 204017664 20:11:30 INFO - PROCESS | 1655 | 20:11:32 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49229/tests/kraken/driver.html?testName=stanford-crypto-ccm) 20:11:33 INFO - PROCESS | 1655 | RSS: Main: 204046336 20:11:33 INFO - PROCESS | 1655 | 20:11:36 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49229/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 20:11:36 INFO - PROCESS | 1655 | RSS: Main: 204447744 20:11:36 INFO - PROCESS | 1655 | 20:11:39 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49229/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 20:11:40 INFO - PROCESS | 1655 | RSS: Main: 204193792 20:11:40 INFO - PROCESS | 1655 | 20:11:42 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49229/tests/kraken/driver.html?testName=ai-astar) 20:11:42 INFO - PROCESS | 1655 | RSS: Main: 205930496 20:11:42 INFO - PROCESS | 1655 | 20:11:42 INFO - PROCESS | 1655 | __start_tp_report 20:11:42 INFO - PROCESS | 1655 | _x_x_mozilla_page_load 20:11:42 INFO - PROCESS | 1655 | _x_x_mozilla_page_load_details 20:11:42 INFO - PROCESS | 1655 | |i|pagename|runs| 20:11:42 INFO - PROCESS | 1655 | |0;ai-astar;108;119;120;122;123;177;176;118;120;118 20:11:42 INFO - PROCESS | 1655 | |1;audio-beat-detection;174;138;142;130;135;141;138;94;149;142 20:11:42 INFO - PROCESS | 1655 | |2;audio-dft;193;197;230;219;198;194;195;220;278;207 20:11:42 INFO - PROCESS | 1655 | |3;audio-fft;72;60;61;86;62;66;62;78;99;84 20:11:42 INFO - PROCESS | 1655 | |4;audio-oscillator;92;69;82;72;71;57;93;100;102;113 20:11:42 INFO - PROCESS | 1655 | |5;imaging-gaussian-blur;88;93;113;88;93;92;122;89;131;88 20:11:42 INFO - PROCESS | 1655 | |6;imaging-darkroom;104;121;94;95;94;95;96;95;96;96 20:11:42 INFO - PROCESS | 1655 | |7;imaging-desaturate;90;143;143;91;91;91;91;91;92;91 20:11:42 INFO - PROCESS | 1655 | |8;json-parse-financial;65;65;64;68;70;69;67;68;69;67 20:11:42 INFO - PROCESS | 1655 | |9;json-stringify-tinderbox;65;66;65;62;60;59;59;56;69;86 20:11:42 INFO - PROCESS | 1655 | |10;stanford-crypto-aes;84;82;80;86;82;86;84;86;83;91 20:11:42 INFO - PROCESS | 1655 | |11;stanford-crypto-ccm;159;147;144;145;130;142;148;159;182;134 20:11:42 INFO - PROCESS | 1655 | |12;stanford-crypto-pbkdf2;164;172;163;166;164;217;223;223;172;166 20:11:42 INFO - PROCESS | 1655 | |13;stanford-crypto-sha256-iterative;68;64;61;57;68;67;86;57;81;84 20:11:42 INFO - PROCESS | 1655 | __end_tp_report 20:11:42 INFO - PROCESS | 1655 | __start_cc_report 20:11:42 INFO - PROCESS | 1655 | _x_x_mozilla_cycle_collect,970 20:11:42 INFO - PROCESS | 1655 | __end_cc_report 20:11:42 INFO - PROCESS | 1655 | __startTimestamp1494558702517__endTimestamp 20:11:42 INFO - PROCESS | 1655 | 20:11:42 INFO - PROCESS | 1655 | ------- Summary: start ------- 20:11:42 INFO - PROCESS | 1655 | Number of tests: 14 20:11:42 INFO - PROCESS | 1655 | 20:11:42 INFO - PROCESS | 1655 | [#0] ai-astar Cycles:10 Average:130.10 Median:121.00 stddev:24.79 (20.5%) stddev-sans-first:24.97 20:11:42 INFO - PROCESS | 1655 | Values: 108.0 119.0 120.0 122.0 123.0 177.0 176.0 118.0 120.0 118.0 20:11:42 INFO - PROCESS | 1655 | 20:11:42 INFO - PROCESS | 1655 | [#1] audio-beat-detection Cycles:10 Average:138.30 Median:142.00 stddev:19.62 (13.8%) stddev-sans-first:16.01 20:11:42 INFO - PROCESS | 1655 | Values: 174.0 138.0 142.0 130.0 135.0 141.0 138.0 94.0 149.0 142.0 20:11:42 INFO - PROCESS | 1655 | 20:11:42 INFO - PROCESS | 1655 | [#2] audio-dft Cycles:10 Average:213.10 Median:213.00 stddev:26.25 (12.3%) stddev-sans-first:26.81 20:11:42 INFO - PROCESS | 1655 | Values: 193.0 197.0 230.0 219.0 198.0 194.0 195.0 220.0 278.0 207.0 20:11:42 INFO - PROCESS | 1655 | 20:11:42 INFO - PROCESS | 1655 | [#3] audio-fft Cycles:10 Average:73.00 Median:75.00 stddev:13.32 (17.8%) stddev-sans-first:14.12 20:11:42 INFO - PROCESS | 1655 | Values: 72.0 60.0 61.0 86.0 62.0 66.0 62.0 78.0 99.0 84.0 20:11:42 INFO - PROCESS | 1655 | 20:11:42 INFO - PROCESS | 1655 | [#4] audio-oscillator Cycles:10 Average:85.10 Median:71.50 stddev:17.72 (24.8%) stddev-sans-first:18.61 20:11:42 INFO - PROCESS | 1655 | Values: 92.0 69.0 82.0 72.0 71.0 57.0 93.0 100.0 102.0 113.0 20:11:42 INFO - PROCESS | 1655 | 20:11:42 INFO - PROCESS | 1655 | [#5] imaging-gaussian-blur Cycles:10 Average:99.70 Median:88.50 stddev:16.08 (18.2%) stddev-sans-first:16.49 20:11:42 INFO - PROCESS | 1655 | Values: 88.0 93.0 113.0 88.0 93.0 92.0 122.0 89.0 131.0 88.0 20:11:42 INFO - PROCESS | 1655 | 20:11:42 INFO - PROCESS | 1655 | [#6] imaging-darkroom Cycles:10 Average:98.60 Median:95.00 stddev:8.38 (8.8%) stddev-sans-first:8.66 20:11:42 INFO - PROCESS | 1655 | Values: 104.0 121.0 94.0 95.0 94.0 95.0 96.0 95.0 96.0 96.0 20:11:42 INFO - PROCESS | 1655 | 20:11:42 INFO - PROCESS | 1655 | [#7] imaging-desaturate Cycles:10 Average:101.40 Median:91.00 stddev:21.93 (24.1%) stddev-sans-first:22.87 20:11:42 INFO - PROCESS | 1655 | Values: 90.0 143.0 143.0 91.0 91.0 91.0 91.0 91.0 92.0 91.0 20:11:42 INFO - PROCESS | 1655 | 20:11:42 INFO - PROCESS | 1655 | [#8] json-parse-financial Cycles:10 Average:67.20 Median:68.00 stddev:1.99 (2.9%) stddev-sans-first:1.94 20:11:42 INFO - PROCESS | 1655 | Values: 65.0 65.0 64.0 68.0 70.0 69.0 67.0 68.0 69.0 67.0 20:11:42 INFO - PROCESS | 1655 | 20:11:42 INFO - PROCESS | 1655 | [#9] json-stringify-tinderbox Cycles:10 Average:64.70 Median:65.00 stddev:8.46 (13.0%) stddev-sans-first:8.97 20:11:42 INFO - PROCESS | 1655 | Values: 65.0 66.0 65.0 62.0 60.0 59.0 59.0 56.0 69.0 86.0 20:11:42 INFO - PROCESS | 1655 | 20:11:42 INFO - PROCESS | 1655 | [#10] stanford-crypto-aes Cycles:10 Average:84.40 Median:85.00 stddev:3.06 (3.6%) stddev-sans-first:3.24 20:11:42 INFO - PROCESS | 1655 | Values: 84.0 82.0 80.0 86.0 82.0 86.0 84.0 86.0 83.0 91.0 20:11:42 INFO - PROCESS | 1655 | 20:11:42 INFO - PROCESS | 1655 | [#11] stanford-crypto-ccm Cycles:10 Average:149.00 Median:147.50 stddev:14.79 (10.0%) stddev-sans-first:15.24 20:11:42 INFO - PROCESS | 1655 | Values: 159.0 147.0 144.0 145.0 130.0 142.0 148.0 159.0 182.0 134.0 20:11:42 INFO - PROCESS | 1655 | 20:11:42 INFO - PROCESS | 1655 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:183.00 Median:172.00 stddev:26.45 (15.4%) stddev-sans-first:27.15 20:11:42 INFO - PROCESS | 1655 | Values: 164.0 172.0 163.0 166.0 164.0 217.0 223.0 223.0 172.0 166.0 20:11:42 INFO - PROCESS | 1655 | 20:11:42 INFO - PROCESS | 1655 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.30 Median:68.00 stddev:10.75 (15.8%) stddev-sans-first:11.39 20:11:42 INFO - PROCESS | 1655 | Values: 68.0 64.0 61.0 57.0 68.0 67.0 86.0 57.0 81.0 84.0 20:11:42 INFO - PROCESS | 1655 | -------- Summary: end -------- 20:11:42 INFO - PROCESS | 1655 | 20:11:43 INFO - TEST-INFO | 1655: exit 0 20:11:43 INFO - TEST-OK | kraken | took 60327ms 20:11:43 INFO - SUITE-END | took 1175s 20:11:43 INFO - Completed test suite (00:19:36) 20:11:43 INFO - javascript benchmark 20:11:43 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7921.977511728564, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3639.5814481334655, 3642.7814331140116, 3673.5224420825293, 3678.558572971244, 3688.114852435416, 5228.686278823273, 6198.752049918004, 6264.937350626498, 6292.402221788934, 6313.337099604544, 6009.489193418564, 6177.814665560029, 6204.0, 6231.844203894905, 6241.438270555649, 5494.2857428534235, 5531.502164805167, 5593.580481463886, 5596.0206963781375, 5662.801801936933, 5651.886962260753, 5671.06427439472, 5678.46054624812, 5685.232493613351, 5688.886222275552, 5441.374241962166, 5479.986202552521, 5651.208830763697, 5651.660900345972, 5710.1434784782405, 5532.529734972532, 5544.362398324204, 5552.750126244328, 5557.694326812024, 5558.861028474321, 4709.246520556729, 5362.436944120889, 5415.620906536544, 5509.393966663663, 5555.083411237141, 6278.843028924313, 6297.685115744194, 6303.243610766692, 6310.337414571496, 6333.96833015832, 5706.315242170925, 5922.526197904157, 6626.171728533934, 6634.800955971328, 6651.966740166316, 6470.708818103197, 6482.254540727782, 6652.800415987528, 6746.763863264811, 6802.795916122524, 6211.596246243979, 6290.961991271434, 6301.77943771975, 6308.936910630835, 6319.14691516644, 6877.4841886857685, 6913.10129683138, 6954.965225173943, 6961.408280296231, 6976.407005404496, 6314.62112273265, 6324.3043265240785, 6556.901646475307, 6571.5399922005, 6576.44100251484, 6607.240167380813, 6609.801705948829, 6642.535022548375, 6672.165979252593, 6701.731930722814, 6599.274079851212, 6605.24039735427, 6622.635755033423, 6628.370304821035, 6637.0, 6331.461825744863, 6553.574017688835, 6557.082008518812, 6621.271660117383, 6653.434458071021, 5229.320188375486, 5238.5023422774775, 5262.105442074857, 5281.0758117329315, 5323.680579165262, 4588.2888152336445, 4625.259958406638, 4645.442546894395, 4699.130660827689, 4719.1033703596895, 5629.789595237044, 5859.1797148399255, 6307.810765676945, 6325.683715814283, 6326.2092238470195, 6596.86806263872, 6721.395074443323, 6725.831854203684, 6747.561408508432, 6758.83102922421, 6237.532185086136, 6279.340669229757, 6292.937070629327, 6354.205724284465, 6367.713452894631, 4939.950600493949, 5928.555358348055, 5939.3763654816485, 5955.225820643375, 5957.880842383128, 6935.618540980194, 6972.477064220102, 6973.651317434006, 6975.581465112109, 7000.9649951750935, 5306.310179676618, 5589.972050139886, 6446.258680251643, 6726.966365168143, 6731.730730770714, 6681.398674119353, 6820.045193672989, 6858.0, 6913.481488888454, 6926.722931082701, 6631.502637302126, 6637.668116594247, 6643.136392269167, 6650.90023649636, 6652.900206497004, 6573.5398522103, 6717.429018533284, 6740.865182696417, 6757.155355580552, 6760.019797129487, 5360.758765855624, 5367.785288588413, 6263.311035786151, 6390.7763228286785, 6444.1622589065, 6494.772682956074, 6508.479321654351, 6516.478681705357, 6522.043386983981, 6532.608043517404, 6241.407066328691, 6303.401176888188, 6365.363463653597, 6368.554201205871, 6401.359864013748, 6260.217472815898, 6297.055441683784, 6374.553781235269, 6396.744130234739, 6531.804045878631, 5314.946850531445, 6319.652419116809, 6363.427291543783, 6364.140840986408, 6364.236291645032], "value": 6070.15633385884, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [767.8771396576549, 836.8619177835658, 855.7518319687291, 881.7531091294431, 1004.1213937804416, 38218.853434396944, 39294.0, 40591.39112913309, 40630.59369406305, 40715.99999999996, 39211.0, 39377.0, 39397.81806545808, 39419.0145246369, 39511.814645560604, 8248.46384984977, 8367.581620918929, 8394.202550757698, 11451.541938322458, 11689.181757276994, 11280.097592192646, 11315.603953861617, 11321.20751547388, 11324.943375283112, 11329.490172942196, 11324.263922844988, 11326.263792853439, 11349.886501135006, 11350.545978160864, 11371.147163962694, 11281.37952412617, 11282.984531392172, 11295.435228238637, 11307.604233851776, 11315.547378104908, 11076.335419874753, 11133.0, 11134.0, 11135.109191264679, 11151.442427878656, 35257.29485410303, 35310.29379412397, 35526.82236588826, 35555.64444355575, 35591.28817423637, 3985.1830374773103, 3994.540627827808, 4009.218202450523, 4034.23349563582, 4056.5132184137797, 22860.542789144125, 26469.470610587872, 27969.160925172277, 27971.16086517408, 28087.0, 3145.2294187924103, 3270.2314955985325, 3280.8687652493873, 3322.069820450278, 3391.8982430527126, 348216.0, 349157.0, 351119.0, 351311.2434437837, 359848.0, 43856.3421548677, 43937.78031109888, 44107.55892441099, 44144.779276103414, 44185.779071105084, 38752.806235969205, 46823.76588117037, 46887.0, 46965.76517117392, 47085.0, 12128.514859405701, 14588.416463341347, 14631.0, 14702.117872927658, 14703.117812931258, 10400.947995259976, 12642.367881605884, 12708.3010434427, 12735.108542401942, 12746.044046696536, 11662.533498660128, 12244.081693872995, 12264.693382665326, 12282.815757763641, 12374.566890158801, 4781.015878410421, 5645.294338207724, 5693.572982026365, 5704.543636509069, 5724.484796368347, 1000.7648202672366, 1042.9478526073665, 1101.7990390474422, 1124.966251012471, 1223.9388030598434, 25637.846296916683, 27090.864545677145, 27472.862635687092, 27696.723032769816, 27782.722172778012, 960.5101398286924, 1105.9612913547987, 1198.2151690642643, 1291.1607455154037, 1329.5280175537546, 13919.512817051354, 15303.387864485296, 16425.83574164267, 16533.751993720103, 16834.663306733797, 480272.0, 606921.0, 615101.0, 616895.0, 618630.0, 43332.78333608312, 50442.747786260836, 50751.0, 50775.730606735124, 50824.98350033053, 1312.6980794417339, 1469.4195792662053, 1500.3473489032306, 1505.3150816378522, 1517.6357674158344, 25067.49865002726, 26840.194794156207, 28376.179304147496, 28640.85679571589, 28658.570121447778, 41328.58671413247, 41511.0, 41560.376594351714, 41627.79186104171, 41775.16449670929, 432610.0, 434705.0, 435195.94412167685, 435682.82158589, 437693.0, 23621.409464763517, 29829.0, 30024.399512010074, 30336.69663303339, 30455.238619034702, 38646.806765967114, 39232.60767392404, 39315.80342098271, 39317.2136557273, 39343.80328098341, 27968.461734604793, 28360.85819570889, 28543.0, 28896.0, 29440.11679649614, 23249.0, 23687.289381318587, 27893.72106278911, 28270.293242669097, 28876.855615721786, 29343.413131737674, 32815.34369312648, 32859.83570082134, 34399.82800085984, 34918.825405872805, 29488.557671634513, 29705.85147074251, 29743.70256297496, 30385.544216837206, 30437.086887393412, 24093.0, 24132.51734965326, 24277.757222428256, 24608.6308705366, 24650.87674561616], "value": 18613.416722322105, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [427.7519038957405, 442.54417949512003, 442.69454076687083, 448.08143306222235, 456.86522475869623, 18095.0, 21451.463713407153, 22026.88986555067, 22284.442888927784, 22479.876006199705, 22485.988130534126, 23277.95249213785, 23814.64278035831, 23918.684472354013, 24241.909114089864, 6808.795736127911, 6945.89581156283, 7001.159860816709, 7616.961915190416, 7917.722879699211, 7580.393568514533, 7932.682692692314, 7963.522188668699, 7977.202279772035, 7995.880061799077, 8006.399520035991, 8061.355091592659, 8062.113167551564, 8097.635606397726, 8175.95912020439, 7794.688212471494, 7981.9201807982045, 8057.435979481439, 8071.313938315221, 8071.394645401619, 7567.0, 7792.532448053135, 7792.688292468294, 7854.450188486809, 7862.253085956825, 27490.0, 27897.88408463659, 28013.57979630307, 28068.0, 28347.149585512467, 2889.8555072246513, 2950.572167035768, 3024.3195281061585, 3040.832754198518, 3068.6471055828642, 16254.918725406298, 16932.40736574214, 17019.65960680792, 17138.22877970482, 17365.65268694619, 1860.646477169347, 2028.4218997585765, 2183.6570509136927, 2193.243331050785, 2247.5617254635354, 162834.0, 163200.0, 170435.0, 170577.0, 173850.3922441177, 21092.78907210908, 21093.578128437654, 21204.46988825261, 21297.722136671848, 21473.248436304966, 31786.841065794524, 31888.043358699277, 32144.678553214635, 32163.0, 32241.355172896412, 10720.785584288271, 10880.945595271973, 11058.723531911766, 11064.502097405637, 11088.445577721082, 9133.725988220363, 9152.679656212, 9173.40372875761, 9321.974582795887, 9337.112974267433, 9563.0, 9608.95195524018, 9679.225661947168, 9780.95109524462, 9794.95102524497, 4026.5570787213383, 4084.203580301872, 4141.875743727693, 4143.8964025899595, 4152.480939882515, 1066.3015724700333, 1074.8495210670512, 1088.3905013192636, 1102.2835157147917, 1125.2517076144436, 21838.563228735653, 22012.889935550218, 22016.77983220179, 22089.668654970188, 22112.33662990113, 1094.141099237095, 1106.4136007915818, 1110.9611163609236, 1129.4578602270956, 1139.629620373382, 11285.153613479022, 11543.307401555934, 11637.825432618518, 11666.824997625043, 11682.415879206006, 768050.1597491977, 768165.0, 769986.0, 780096.0, 794814.0, 146018.0, 147063.0, 155296.0, 158283.20858395635, 159995.0, 1158.6639874436487, 1167.9065674746, 1195.5397172088635, 1202.212550779241, 1210.2798834693344, 18067.45797626073, 18227.270909163486, 19121.904390477957, 19257.0, 19498.8050119497, 48605.055797767694, 49950.50049499459, 58046.7097664509, 63732.68133659302, 64820.703585927076, 57351.71324143353, 73749.89375159485, 74503.0, 75911.62044189744, 76126.0, 8657.740267791976, 11547.960683538522, 11726.179167458537, 11774.234674746114, 11948.70128246766, 11874.346910919983, 12760.489580416679, 12966.611001669966, 13096.410661520446, 13311.600651980456, 9766.21870250364, 10436.425996570262, 11245.98786109254, 11422.885771142182, 11576.247543909956, 10602.681919542427, 10605.363678179334, 10717.35695858251, 10862.293950893167, 10996.010359067723, 9418.340716150078, 11551.711207219887, 11822.704432389259, 11879.049676025725, 12025.45885435175, 11514.481848316678, 11558.165254090336, 11677.532898683958, 11819.11356648272, 11892.881071189177, 11412.771744565229, 11626.82559761621, 11745.412729363668, 11764.294142351486, 11833.34916579562], "value": 12026.498377259335, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1587.5792914877557, 1604.9438269660563, 1639.2541393665883, 1657.651893102449, 1661.7341225403934, 11045.447727613628, 11433.314001159937, 11697.298162110268, 11707.882921170796, 11780.175387722862, 11367.204295699305, 11500.309981401122, 11556.959873611373, 11569.768604627903, 11602.709932251699, 2467.4078221226864, 2507.8119141064403, 2512.906885504802, 2515.383730985902, 2516.584763514018, 2455.693038370204, 2476.4675594747127, 2511.183865243794, 2515.71069327027, 2515.8993640254366, 2039.6226698060832, 2464.0759715106833, 2485.2544236729, 2493.4514406830467, 2507.047322017631, 2039.4799326171806, 2421.3704436846547, 2464.2114523352625, 2471.7281099079087, 2489.8630575318352, 2448.081969261527, 2463.790577800889, 2470.629405589158, 2476.554220240356, 2476.839005464639, 11490.080793536581, 11634.243774154653, 11752.0, 11758.412079395996, 11759.823602645967, 5930.9406905931255, 6075.574709770318, 6127.785527506516, 6129.356417576135, 6133.080037994337, 3639.94540081899, 3711.2391959648207, 3761.0, 3761.6426439488205, 3846.557645870733, 1294.4563283420987, 1904.457229689532, 1952.4142757172792, 1980.22741442271, 2020.737304150451, 13429.194248345228, 14258.786118208236, 14595.41618335276, 15969.0, 16162.838371616252, 4769.260764581495, 4810.855674329776, 4877.219644856806, 4926.852194434173, 4936.0, 12060.819087713691, 12085.879141208652, 12098.637040888787, 12120.515179392902, 12124.454399552042, 6075.027995520695, 6077.361877002851, 6083.8174854754425, 6086.117512960596, 6095.695215239309, 5296.179092240709, 5439.428859969726, 5519.613627046148, 5546.084895992116, 5572.331320241597, 3952.584978577208, 4061.6750659947725, 4071.633552980246, 4093.0, 4125.855595054159, 913.2328843771264, 933.5565606336936, 1084.6366467233408, 1093.748437859297, 1103.7351035751522, 834.995825020871, 957.3346524165638, 965.2278177458005, 979.8790851139145, 980.612658000086, 6726.327367263332, 6760.932390676128, 6905.205901321412, 6970.93029069713, 7006.824829379204, 981.5484876956538, 988.4365911430559, 1013.3160116921196, 1029.459533744775, 1075.930064545802, 3447.844846981892, 4033.4554835097474, 4263.914721705549, 4467.977660111678, 4541.795619197144, 13024.41390137446, 14545.854541454662, 14562.126272423688, 14593.41626334956, 14747.852521474862, 10425.374477531372, 14174.503892363718, 14372.85627143736, 14494.34775435108, 14515.0, 10577.418241996764, 10598.682039538826, 10677.199210059276, 10680.412577308167, 10685.946570267255, 6667.633280169637, 6685.799426017227, 6807.421369183675, 6863.176418829772, 6926.688299026657, 10717.571297148339, 12951.352432378155, 12953.481860725466, 12957.676058098623, 12959.352032398154, 12752.234865908074, 12845.871541284841, 12928.741425171633, 12988.675283117998, 13046.673833154247, 9287.442753434816, 9604.9039509604, 9611.951940240255, 9616.0, 9620.326577139533, 10523.94738026305, 10552.366857988545, 10569.260151789302, 10649.30779499361, 10674.839877402, 8975.910240897507, 9074.45553266806, 9144.497052662167, 9145.679901203426, 9243.260539156985, 8734.17025382613, 8739.91260087391, 8755.737327880173, 8781.560921954004, 8952.507612081137, 8139.186081391814, 8162.347012238888, 8247.721603151282, 8266.13205613402, 8268.297194738514, 5950.494207992196, 5957.434043765836, 5961.701914904324, 6122.724477398617, 6181.598196117238, 5252.9737351313, 6046.365131661289, 6175.073738939195, 6236.126942228001, 6353.491720662243], "value": 5352.979048570826, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [339.7638641144404, 351.34298861129673, 390.1202787713705, 392.1666458775103, 407.51098681582107, 4340.739555626661, 4475.686701930867, 5246.816361427351, 5270.894582108361, 5363.731813409334, 4435.889102772432, 4703.365045718827, 5164.354455693038, 5282.5245727884485, 5357.892842143155, 3401.809366721652, 3918.5885482024405, 4036.777977211252, 4279.229738647043, 4417.403650507173, 3583.94624080639, 3932.348413666253, 4352.477702675684, 4382.758948257844, 4433.778311084449, 4401.317795741649, 4407.906244533837, 4436.534163912791, 4440.444944381952, 4466.5980061794435, 3683.1087596236825, 3976.363781794928, 4152.345935576688, 4245.002424430257, 4271.765052922088, 3252.7560432967384, 3717.981410092932, 4020.698447616411, 4024.4969378827645, 4037.798110094513, 4501.7974191161475, 5004.574611158056, 5145.0, 5161.5870730341485, 5168.819091331823, 4545.49999500058, 4824.348712923746, 4833.29917162013, 5045.5459008689395, 5176.275321454999, 5063.670861393997, 5390.442308076651, 5667.574931880125, 5676.971615141898, 5745.265577374698, 3848.843947962672, 4347.152460204432, 4487.640988720895, 4617.930731039037, 4634.146341463442, 5624.971875140598, 5787.913181302284, 5990.8502287442725, 6000.729967151445, 6056.788012419544, 4123.8556650517085, 4473.642108631302, 4588.426446694163, 4712.2224832902875, 4728.595411303856, 4707.835225767081, 4746.572808447257, 4753.762311884392, 4810.542998415145, 4818.072770891671, 4542.364069030338, 4717.834875779331, 4977.352944117241, 5079.5428411443145, 5109.744512774347, 4875.172989203458, 5175.842735931079, 5574.414686457944, 5719.914201286984, 5749.3963133871175, 1543.772700702944, 1610.4443966831425, 1632.812226593957, 1633.9509814705577, 1637.8116516600744, 1118.5358076398365, 1165.2018367418345, 1223.1286132217544, 1224.6528818299798, 1253.4296894912793, 5031.754518335726, 5076.18780995039, 5388.703621300788, 5408.864778380572, 5451.0, 5093.261477085802, 5232.947670523322, 5629.930313240471, 5832.562557808096, 5910.404190868441, 4580.509920538221, 4875.658703890694, 4896.290037944478, 5102.448376402945, 5455.018096742545, 2727.0, 2783.5685468752376, 2823.6329277194245, 3060.4491191585284, 3069.8181200237623, 5460.781568737206, 5890.823275301748, 5973.701314934236, 6041.794744918073, 6171.352008039181, 5441.966026454961, 5854.121881717776, 5882.44116808911, 5917.31950825652, 5998.670073145931, 5547.1679248113105, 5662.8301150965535, 5794.159846822272, 5843.853903652443, 5973.910391344133, 5340.866478338073, 5637.0, 5645.774169033357, 5924.555658325729, 5942.0391270879645, 4796.5203479651755, 4949.787302111006, 5017.377062277758, 5423.83728488146, 5771.653700777967, 5562.0, 5675.347335056356, 5797.710114494343, 5996.2504686914135, 6038.456538911519, 3839.7120215984473, 4249.405083288404, 4367.45406824147, 4561.429821272341, 4574.99350142968, 4862.568715642179, 5518.641288316247, 5625.746841392229, 5641.787015791624, 5791.884162316815, 4600.079984003146, 4717.952820471752, 5520.779168833363, 5550.973069982067, 5746.816209840234, 4826.607786913892, 5059.620528460307, 5098.646421257738, 5630.493255607015, 5637.534241097263, 4377.424127314101, 5055.898882022266, 5504.311961004874, 5594.545418191218, 5658.377578466283, 5352.866178345573, 5450.8909821802545, 5705.572082093777, 5718.999175144416, 5748.223989761329, 5055.823046193365, 5529.391766905556, 5554.75003624846, 5580.832575022756, 5665.773369065356], "value": 4294.087212100374, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [758.9392848572111, 766.1380946435261, 802.6227672993691, 883.2404132446095, 885.8184072265186, 15729.449469268578, 17398.565035874108, 17805.198766055528, 17971.371002014934, 18038.098095095258, 16082.919585402085, 16114.919425402857, 16595.336186552522, 16700.164991750393, 16724.66550668986, 978.9706308810747, 996.0985308296003, 1004.5328922051244, 1007.1741172238767, 1008.500792107904, 976.1702552830109, 991.0188912976138, 993.9304248702593, 1020.1226944827442, 1035.7824856780048, 1070.9571617135305, 1079.0, 1085.7719878825417, 1086.8369744538304, 1088.086007753487, 1076.6500887211648, 1077.6713102503695, 1081.0378762900946, 1081.0972837680547, 1082.182951871336, 797.8723404255292, 802.6709049289764, 802.7230605441114, 803.120582961656, 805.1304591041661, 14490.0, 17286.394976175772, 17390.56523586908, 17559.20983555743, 18347.082645867788, 14364.20996845173, 14656.0, 14840.406383744637, 15017.549473515812, 15201.467948621745, 11792.469338879771, 12078.33569153705, 12154.524967145142, 12323.445444955, 12444.942179914719, 3785.3701096547934, 3810.485584446092, 3836.7506112102624, 3856.478655889173, 3930.213957208541, 116831.0, 118385.40807295995, 119435.4028229862, 120791.3960430201, 122341.38829305797, 11249.887501124884, 11387.475567359457, 11535.52026537143, 11726.94136529312, 11796.94101529487, 23919.52160956806, 23945.52108957811, 23955.880220599134, 23980.04079836822, 24029.51940961171, 7663.770086897291, 8016.238457346542, 8113.837723245502, 8317.708880189164, 8404.957975210207, 4582.564656357641, 4603.240465323252, 4632.698874573142, 4652.488226295105, 4807.711537307773, 3006.188329151117, 3535.505029295901, 3607.8737244196327, 3627.4014787560286, 3628.238070005276, 633.0915136778738, 659.1266571792394, 660.0824853453703, 661.2065521374274, 672.7376323233942, 636.6530241018656, 643.462708638283, 673.2022553274386, 680.6936878404738, 704.2781149321884, 10228.668806721285, 10243.539040743035, 10358.223133264884, 10540.736481588021, 10604.310719803343, 659.8911179655399, 671.4058058618158, 699.6047233313263, 705.2418649951322, 716.8458781362032, 3629.7277704172293, 4920.852374428773, 4962.454130045692, 4984.975075124674, 5009.89980200394, 89582.55208723915, 93595.06404936, 95257.523712381, 96304.0, 96345.51827240818, 56161.0, 69122.65438672775, 72077.0, 78416.0, 79258.0, 70070.0, 71797.92303115354, 76381.0, 77636.22363776402, 78170.0, 10117.949410252902, 11339.376334301693, 11492.597759078726, 11593.594224202112, 11657.475413606238, 45971.0, 47066.0, 49532.25701614551, 49552.0, 51633.48366516437, 56133.0, 64691.0, 69624.30375696179, 69960.65019675072, 73032.0, 13252.337383130998, 13253.07228493996, 13289.13620614657, 13475.865241347461, 14061.789073164115, 14560.271986400849, 14801.777973330194, 14857.331420085906, 14950.401983920521, 15154.696906062038, 13752.243626600233, 14145.36345864418, 14300.427982880985, 14562.980591358502, 14969.625759356104, 13834.37745301484, 14425.78361324602, 14463.49377771773, 14467.565973020826, 14468.782968255275, 13436.596902092953, 13545.59363219105, 14014.509492167725, 14170.433182672577, 14467.49363772263, 10018.749531261778, 10538.262321637412, 10851.294665846695, 10903.727406814893, 10907.781844363228, 11442.0, 11682.47428865686, 11698.94150529242, 11707.590234341757, 11720.413979301171], "value": 7912.81201866138, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1556.9, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [108.0, 119.0, 120.0, 122.0, 123.0, 177.0, 176.0, 118.0, 120.0, 118.0], "value": 130.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [174.0, 138.0, 142.0, 130.0, 135.0, 141.0, 138.0, 94.0, 149.0, 142.0], "value": 138.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [193.0, 197.0, 230.0, 219.0, 198.0, 194.0, 195.0, 220.0, 278.0, 207.0], "value": 213.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 60.0, 61.0, 86.0, 62.0, 66.0, 62.0, 78.0, 99.0, 84.0], "value": 73.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 69.0, 82.0, 72.0, 71.0, 57.0, 93.0, 100.0, 102.0, 113.0], "value": 85.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 93.0, 113.0, 88.0, 93.0, 92.0, 122.0, 89.0, 131.0, 88.0], "value": 99.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 121.0, 94.0, 95.0, 94.0, 95.0, 96.0, 95.0, 96.0, 96.0], "value": 98.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 143.0, 143.0, 91.0, 91.0, 91.0, 91.0, 91.0, 92.0, 91.0], "value": 101.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 65.0, 64.0, 68.0, 70.0, 69.0, 67.0, 68.0, 69.0, 67.0], "value": 67.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 66.0, 65.0, 62.0, 60.0, 59.0, 59.0, 56.0, 69.0, 86.0], "value": 64.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 82.0, 80.0, 86.0, 82.0, 86.0, 84.0, 86.0, 83.0, 91.0], "value": 84.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [159.0, 147.0, 144.0, 145.0, 130.0, 142.0, 148.0, 159.0, 182.0, 134.0], "value": 149.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 172.0, 163.0, 166.0, 164.0, 217.0, 223.0, 223.0, 172.0, 166.0], "value": 183.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 64.0, 61.0, 57.0, 68.0, 67.0, 86.0, 57.0, 81.0, 84.0], "value": 69.3, "unit": "score"}]}]} 20:11:43 INFO - Return code: 0 20:11:43 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 20:11:43 ERROR - # TBPL SUCCESS # 20:11:43 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 20:11:43 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 20:11:43 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 20:11:43 INFO - 2017-05-11 20:11:43.809 screenresolution[1660:10585] starting screenresolution argv=screenresolution get 20:11:43 INFO - 2017-05-11 20:11:43.812 screenresolution[1660:10585] Display 0: 1600x1200x32@60 20:11:43 INFO - 2017-05-11 20:11:43.818 screenresolution[1661:10588] starting screenresolution argv=screenresolution list 20:11:43 INFO - Available Modes on Display 0 20:11:43 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 20:11:43 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 20:11:43 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 20:11:43 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 20:11:44 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 20:11:44 INFO - Intel Iris: 20:11:44 INFO - Chipset Model: Intel Iris 20:11:44 INFO - Type: GPU 20:11:44 INFO - Bus: Built-In 20:11:44 INFO - VRAM (Dynamic, Max): 1536 MB 20:11:44 INFO - Vendor: Intel (0x8086) 20:11:44 INFO - Device ID: 0x0a2e 20:11:44 INFO - Revision ID: 0x0009 20:11:44 INFO - Displays: 20:11:44 INFO - Display: 20:11:44 INFO - Resolution: 1600 x 1200 @ 60 Hz 20:11:44 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 20:11:44 INFO - Display Serial Number: 1600x1200 60 20:11:44 INFO - Main Display: Yes 20:11:44 INFO - Mirror: Off 20:11:44 INFO - Online: Yes 20:11:44 INFO - Rotation: Supported 20:11:44 INFO - Return code: 0 20:11:44 INFO - Running post-action listener: _resource_record_post_action 20:11:44 INFO - [mozharness: 2017-05-12 03:11:44.055725Z] Finished run-tests step (success) 20:11:44 INFO - Running post-run listener: _resource_record_post_run 20:11:44 INFO - Total resource usage - Wall time: 1191s; CPU: 54.0%; Read bytes: 39622656; Write bytes: 424483328; Read time: 251; Write time: 1468 20:11:44 INFO - TinderboxPrint: CPU usage
54.4% 20:11:44 INFO - TinderboxPrint: I/O read bytes / time
39,622,656 / 251 20:11:44 INFO - TinderboxPrint: I/O write bytes / time
424,483,328 / 1,468 20:11:44 INFO - TinderboxPrint: CPU idle
2,172.6 (45.6%) 20:11:44 INFO - TinderboxPrint: CPU system
952.0 (20.0%) 20:11:44 INFO - TinderboxPrint: CPU user
1,641.1 (34.4%) 20:11:44 INFO - TinderboxPrint: Swap in / out
161,906,688 / 0 20:11:44 INFO - install - Wall time: 15s; CPU: 53.0%; Read bytes: 123083264; Write bytes: 122818560; Read time: 11149; Write time: 284 20:11:44 INFO - run-tests - Wall time: 1177s; CPU: 54.0%; Read bytes: 35624960; Write bytes: 300280320; Read time: 226; Write time: 1179 20:11:44 INFO - Running post-run listener: _upload_blobber_files 20:11:44 INFO - Blob upload gear active. 20:11:44 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 20:11:44 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 20:11:44 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'] 20:11:44 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 20:11:44 INFO - (blobuploader) - INFO - Open directory for files ... 20:11:44 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 20:11:44 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:11:44 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:11:46 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 20:11:46 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:11:46 INFO - (blobuploader) - INFO - Done attempting. 20:11:46 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 20:11:46 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:11:46 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:11:47 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 20:11:47 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:11:47 INFO - (blobuploader) - INFO - Done attempting. 20:11:47 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 20:11:47 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:11:47 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:11:48 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 20:11:48 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:11:48 INFO - (blobuploader) - INFO - Done attempting. 20:11:48 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 20:11:48 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:11:48 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:11:50 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 20:11:50 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:11:50 INFO - (blobuploader) - INFO - Done attempting. 20:11:50 INFO - (blobuploader) - INFO - Iteration through files over. 20:11:50 INFO - Return code: 0 20:11:50 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 20:11:50 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 20:11:50 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/5834f041c44e776d774afba008ba23d28e95e07a44f717befbb9fb9a266628dcd9aa9b096808314e4856545564a67af648df46175e0f93e2f3d2304fa4653e24", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/ac93811b0520f160f3709356dc29562edd7879044129d6b23d0f0ff7d889215b89dced40afb559abe5e63f7357e112e35089c5750af67ade6c47b35efa020ae8", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/a7a1e4d1d8cf5c0da31dbc39c17997495bc59de012099c1cd84952a7d151caf06ff9e967bc8bbde167439fd95618f32d70af53cb67c47853047236d5e1fef5eb", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/9244c5f019f136c45e6f738b8e859789d3967e0ba045614a23c80fcda959515b8bfbbbc94dbb3c6d20e34e00fb7129012622a511615f960d04ae452793300d9f"} 20:11:50 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 20:11:50 INFO - Writing to file /builds/slave/test/properties/blobber_files 20:11:50 INFO - Contents: 20:11:50 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/5834f041c44e776d774afba008ba23d28e95e07a44f717befbb9fb9a266628dcd9aa9b096808314e4856545564a67af648df46175e0f93e2f3d2304fa4653e24", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/ac93811b0520f160f3709356dc29562edd7879044129d6b23d0f0ff7d889215b89dced40afb559abe5e63f7357e112e35089c5750af67ade6c47b35efa020ae8", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/a7a1e4d1d8cf5c0da31dbc39c17997495bc59de012099c1cd84952a7d151caf06ff9e967bc8bbde167439fd95618f32d70af53cb67c47853047236d5e1fef5eb", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/9244c5f019f136c45e6f738b8e859789d3967e0ba045614a23c80fcda959515b8bfbbbc94dbb3c6d20e34e00fb7129012622a511615f960d04ae452793300d9f"} 20:11:50 INFO - Running post-run listener: copy_logs_to_upload_dir 20:11:50 INFO - Copying logs to upload dir... 20:11:50 INFO - mkdir: /builds/slave/test/build/upload/logs 20:11:50 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1267.330826 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 7 secs) (at 2017-05-11 20:11:50.504281) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-11 20:11:50.507703) ========= 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.3kBE4oMqWs/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.0lC6xc825h/Listeners TMPDIR=/var/folders/fg/pfrf4nhd1hz969r_0mf1rb2400000w/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/5834f041c44e776d774afba008ba23d28e95e07a44f717befbb9fb9a266628dcd9aa9b096808314e4856545564a67af648df46175e0f93e2f3d2304fa4653e24", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/ac93811b0520f160f3709356dc29562edd7879044129d6b23d0f0ff7d889215b89dced40afb559abe5e63f7357e112e35089c5750af67ade6c47b35efa020ae8", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/a7a1e4d1d8cf5c0da31dbc39c17997495bc59de012099c1cd84952a7d151caf06ff9e967bc8bbde167439fd95618f32d70af53cb67c47853047236d5e1fef5eb", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/9244c5f019f136c45e6f738b8e859789d3967e0ba045614a23c80fcda959515b8bfbbbc94dbb3c6d20e34e00fb7129012622a511615f960d04ae452793300d9f"} build_url:https://queue.taskcluster.net/v1/task/BxrKAfh4QBmnGyFUhUrbEA/artifacts/public/build/firefox-54.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011113 build_url: 'https://queue.taskcluster.net/v1/task/BxrKAfh4QBmnGyFUhUrbEA/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/5834f041c44e776d774afba008ba23d28e95e07a44f717befbb9fb9a266628dcd9aa9b096808314e4856545564a67af648df46175e0f93e2f3d2304fa4653e24", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/ac93811b0520f160f3709356dc29562edd7879044129d6b23d0f0ff7d889215b89dced40afb559abe5e63f7357e112e35089c5750af67ade6c47b35efa020ae8", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/a7a1e4d1d8cf5c0da31dbc39c17997495bc59de012099c1cd84952a7d151caf06ff9e967bc8bbde167439fd95618f32d70af53cb67c47853047236d5e1fef5eb", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/9244c5f019f136c45e6f738b8e859789d3967e0ba045614a23c80fcda959515b8bfbbbc94dbb3c6d20e34e00fb7129012622a511615f960d04ae452793300d9f"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-11 20:11:50.541957) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-11 20:11:50.542328) ========= 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.3kBE4oMqWs/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.0lC6xc825h/Listeners TMPDIR=/var/folders/fg/pfrf4nhd1hz969r_0mf1rb2400000w/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.005080 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-11 20:11:50.591170) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-11 20:11:50.591575) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-11 20:11:50.592006) ========= ========= Total master_lag: 0.18 =========