builder: mozilla-beta_yosemite_r7_devedition_test-dromaeojs-e10s slave: t-yosemite-r7-0060 starttime: 1496698155.1 results: success (0) buildid: 20170605125726 builduid: 208641a058e84fe3b3883ceef091d1e9 revision: 97767088522e789dd04cf06b24478f052946d1c9 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-06-05 14:29:15.101463) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-06-05 14:29:15.101899) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-05 14:29:15.102173) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-05 14:29:15.127201) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-05 14:29:15.127511) ========= 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.6cRNlmN2PX/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.cOOoHa1dqP/Listeners TMPDIR=/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-06-05 14:29:15-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 15.5M=0.001s 2017-06-05 14:29:15 (15.5 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.092521 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-05 14:29:15.244097) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-05 14:29:15.244482) ========= 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.6cRNlmN2PX/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.cOOoHa1dqP/Listeners TMPDIR=/var/folders/9c/2dh985pd15l19fywb0pq404000000w/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.028234 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-05 14:29:15.293397) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-06-05 14:29:15.293743) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 97767088522e789dd04cf06b24478f052946d1c9 --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 97767088522e789dd04cf06b24478f052946d1c9 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.6cRNlmN2PX/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.cOOoHa1dqP/Listeners TMPDIR=/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-06-05 14:29:15,387 truncating revision to first 12 chars 2017-06-05 14:29:15,387 Setting DEBUG logging. 2017-06-05 14:29:15,388 attempt 1/10 2017-06-05 14:29:15,388 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/97767088522e?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-06-05 14:29:16,371 unpacking tar archive at: mozilla-beta-97767088522e/testing/mozharness/ program finished with exit code 0 elapsedTime=1.270778 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-06-05 14:29:16.587607) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-05 14:29:16.587938) ========= script_repo_revision: 97767088522e789dd04cf06b24478f052946d1c9 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-05 14:29:16.588374) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-05 14:29:16.588645) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-05 14:29:16.605678) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2017-06-05 14:29:16.605988) ========= /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.6cRNlmN2PX/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.cOOoHa1dqP/Listeners TMPDIR=/var/folders/9c/2dh985pd15l19fywb0pq404000000w/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 14:29:16 INFO - MultiFileLogger online at 20170605 14:29:16 in /builds/slave/test 14:29:16 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 14:29:16 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 14:29:16 INFO - {'append_to_log': False, 14:29:16 INFO - 'base_work_dir': '/builds/slave/test', 14:29:16 INFO - 'blob_upload_branch': 'Mozilla-Beta', 14:29:16 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 14:29:16 INFO - 'branch': 'Mozilla-Beta', 14:29:16 INFO - 'buildbot_json_path': 'buildprops.json', 14:29:16 INFO - 'config_files': ('talos/mac_config.py',), 14:29:16 INFO - 'default_actions': ('clobber', 14:29:16 INFO - 'read-buildbot-config', 14:29:16 INFO - 'download-and-extract', 14:29:16 INFO - 'populate-webroot', 14:29:16 INFO - 'create-virtualenv', 14:29:16 INFO - 'install', 14:29:16 INFO - 'run-tests'), 14:29:16 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 14:29:16 INFO - 'download_minidump_stackwalk': True, 14:29:16 INFO - 'download_symbols': 'ondemand', 14:29:16 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 14:29:16 INFO - 'tooltool.py': '/tools/tooltool.py', 14:29:16 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 14:29:16 INFO - '/tools/misc-python/virtualenv.py')}, 14:29:16 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 14:29:16 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 14:29:16 INFO - 'gecko_profile': False, 14:29:16 INFO - 'gecko_profile_interval': 0, 14:29:16 INFO - 'installer_path': 'installer.exe', 14:29:16 INFO - 'log_level': 'info', 14:29:16 INFO - 'log_name': 'talos', 14:29:16 INFO - 'log_to_console': True, 14:29:16 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 14:29:16 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 14:29:16 INFO - 'opt_config_files': (), 14:29:16 INFO - 'pip_index': False, 14:29:16 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 14:29:16 INFO - 'cmd': ('bash', 14:29:16 INFO - '-c', 14:29:16 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 14:29:16 INFO - 'enabled': True, 14:29:16 INFO - 'halt_on_failure': False, 14:29:16 INFO - 'name': 'check_screen_resolution'},), 14:29:16 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 14:29:16 INFO - 'cmd': ('bash', 14:29:16 INFO - '-c', 14:29:16 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 14:29:16 INFO - 'enabled': True, 14:29:16 INFO - 'halt_on_failure': False, 14:29:16 INFO - 'name': 'check_screen_resolution'},), 14:29:16 INFO - 'run_cmd_checks_enabled': True, 14:29:16 INFO - 'suite': 'dromaeojs-e10s', 14:29:16 INFO - 'system_bits': '32', 14:29:16 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 14:29:16 INFO - 'title': 't-yosemite-r7-0060', 14:29:16 INFO - 'tooltool_cache': '/builds/tooltool_cache', 14:29:16 INFO - 'use_talos_json': True, 14:29:16 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 14:29:16 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 14:29:16 INFO - 'work_dir': 'build'} 14:29:16 INFO - [mozharness: 2017-06-05 21:29:16.805864Z] Running clobber step. 14:29:16 INFO - Running pre-action listener: _resource_record_pre_action 14:29:16 INFO - Running main action method: clobber 14:29:16 INFO - rmtree: /builds/slave/test/build 14:29:16 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 14:29:19 INFO - Running post-action listener: _resource_record_post_action 14:29:19 INFO - [mozharness: 2017-06-05 21:29:19.264954Z] Finished clobber step (success) 14:29:19 INFO - [mozharness: 2017-06-05 21:29:19.265124Z] Running read-buildbot-config step. 14:29:19 INFO - Running pre-action listener: _resource_record_pre_action 14:29:19 INFO - Running main action method: read_buildbot_config 14:29:19 INFO - Using buildbot properties: 14:29:19 INFO - { 14:29:19 INFO - "project": "", 14:29:19 INFO - "product": "firefox", 14:29:19 INFO - "script_repo_revision": "production", 14:29:19 INFO - "builddir": "mozilla-beta_yosemite_r7_devedition_test-dromaeojs-e10s", 14:29:19 INFO - "repository": "", 14:29:19 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 DevEdition mozilla-beta talos dromaeojs-e10s", 14:29:19 INFO - "buildid": "20170605125726", 14:29:19 INFO - "pgo_build": "False", 14:29:19 INFO - "basedir": "/builds/slave/test", 14:29:19 INFO - "buildnumber": 4, 14:29:19 INFO - "slavename": "t-yosemite-r7-0060", 14:29:19 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 14:29:19 INFO - "platform": "yosemite_r7_devedition", 14:29:19 INFO - "scheduler": "tests-mozilla-beta-macosx64-devedition-talos", 14:29:19 INFO - "branch": "mozilla-beta", 14:29:19 INFO - "repo_path": "releases/mozilla-beta", 14:29:19 INFO - "revision": "97767088522e789dd04cf06b24478f052946d1c9", 14:29:19 INFO - "stage_platform": "macosx64-devedition", 14:29:19 INFO - "builduid": "208641a058e84fe3b3883ceef091d1e9", 14:29:19 INFO - "slavebuilddir": "test" 14:29:19 INFO - } 14:29:19 INFO - Found installer url https://queue.taskcluster.net/v1/task/bnAGbk4hQnS3mTOwsq7YQw/artifacts/public/build/firefox-54.0.en-US.mac.dmg. 14:29:19 INFO - Running post-action listener: _resource_record_post_action 14:29:19 INFO - [mozharness: 2017-06-05 21:29:19.268539Z] Finished read-buildbot-config step (success) 14:29:19 INFO - [mozharness: 2017-06-05 21:29:19.268652Z] Running download-and-extract step. 14:29:19 INFO - Running pre-action listener: _resource_record_pre_action 14:29:19 INFO - Running main action method: download_and_extract 14:29:19 INFO - mkdir: /builds/slave/test/build/tests 14:29:19 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')]} 14:29:19 INFO - trying https://queue.taskcluster.net/v1/task/bnAGbk4hQnS3mTOwsq7YQw/artifacts/public/build/firefox-54.0.en-US.mac.test_packages.json 14:29:19 INFO - Downloading https://queue.taskcluster.net/v1/task/bnAGbk4hQnS3mTOwsq7YQw/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 14:29:19 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/bnAGbk4hQnS3mTOwsq7YQw/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 14:29:20 INFO - Downloaded 1287 bytes. 14:29:20 INFO - Reading from file /builds/slave/test/build/firefox-54.0.en-US.mac.test_packages.json 14:29:20 INFO - Using the following test package requirements: 14:29:20 INFO - {u'awsy': [u'firefox-54.0.en-US.mac.common.tests.zip', 14:29:20 INFO - u'firefox-54.0.en-US.mac.awsy.tests.zip'], 14:29:20 INFO - u'common': [u'firefox-54.0.en-US.mac.common.tests.zip'], 14:29:20 INFO - u'cppunittest': [u'firefox-54.0.en-US.mac.common.tests.zip', 14:29:20 INFO - u'firefox-54.0.en-US.mac.cppunittest.tests.zip'], 14:29:20 INFO - u'gtest': [u'firefox-54.0.en-US.mac.common.tests.zip', 14:29:20 INFO - u'firefox-54.0.en-US.mac.gtest.tests.zip'], 14:29:20 INFO - u'jittest': [u'firefox-54.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 14:29:20 INFO - u'mochitest': [u'firefox-54.0.en-US.mac.common.tests.zip', 14:29:20 INFO - u'firefox-54.0.en-US.mac.mochitest.tests.zip'], 14:29:20 INFO - u'mozbase': [u'firefox-54.0.en-US.mac.common.tests.zip'], 14:29:20 INFO - u'reftest': [u'firefox-54.0.en-US.mac.common.tests.zip', 14:29:20 INFO - u'firefox-54.0.en-US.mac.reftest.tests.zip'], 14:29:20 INFO - u'talos': [u'firefox-54.0.en-US.mac.common.tests.zip', 14:29:20 INFO - u'firefox-54.0.en-US.mac.talos.tests.zip'], 14:29:20 INFO - u'web-platform': [u'firefox-54.0.en-US.mac.common.tests.zip', 14:29:20 INFO - u'firefox-54.0.en-US.mac.web-platform.tests.zip'], 14:29:20 INFO - u'xpcshell': [u'firefox-54.0.en-US.mac.common.tests.zip', 14:29:20 INFO - u'firefox-54.0.en-US.mac.xpcshell.tests.zip']} 14:29:20 INFO - Downloading packages: [u'firefox-54.0.en-US.mac.common.tests.zip'] for test suite category: common 14:29:20 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/bnAGbk4hQnS3mTOwsq7YQw/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip 14:29:20 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/bnAGbk4hQnS3mTOwsq7YQw/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip'}, attempt #1 14:29:20 INFO - Fetch https://queue.taskcluster.net/v1/task/bnAGbk4hQnS3mTOwsq7YQw/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip into memory 14:29:22 INFO - Expected file size: 51964058 14:29:22 INFO - Obtained file size: 51964058 14:29:38 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 14:29:38 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/bnAGbk4hQnS3mTOwsq7YQw/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip 14:29:38 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/bnAGbk4hQnS3mTOwsq7YQw/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip'}, attempt #1 14:29:38 INFO - Fetch https://queue.taskcluster.net/v1/task/bnAGbk4hQnS3mTOwsq7YQw/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip into memory 14:29:40 INFO - Expected file size: 51964058 14:29:40 INFO - Obtained file size: 51964058 14:29:55 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/bnAGbk4hQnS3mTOwsq7YQw/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip 14:29:55 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/bnAGbk4hQnS3mTOwsq7YQw/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip'}, attempt #1 14:29:55 INFO - Fetch https://queue.taskcluster.net/v1/task/bnAGbk4hQnS3mTOwsq7YQw/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip into memory 14:29:56 INFO - Expected file size: 14404461 14:29:56 INFO - Obtained file size: 14404461 14:29:57 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')]} 14:29:57 INFO - trying https://queue.taskcluster.net/v1/task/bnAGbk4hQnS3mTOwsq7YQw/artifacts/public/build/firefox-54.0.en-US.mac.dmg 14:29:57 INFO - Downloading https://queue.taskcluster.net/v1/task/bnAGbk4hQnS3mTOwsq7YQw/artifacts/public/build/firefox-54.0.en-US.mac.dmg to /builds/slave/test/build/firefox-54.0.en-US.mac.dmg 14:29:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/bnAGbk4hQnS3mTOwsq7YQw/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 14:29:59 INFO - Downloaded 74889776 bytes. 14:29:59 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/bnAGbk4hQnS3mTOwsq7YQw/artifacts/public/build/firefox-54.0.en-US.mac.dmg 14:29:59 INFO - mkdir: /builds/slave/test/properties 14:29:59 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 14:29:59 INFO - Writing to file /builds/slave/test/properties/build_url 14:29:59 INFO - Contents: 14:29:59 INFO - build_url:https://queue.taskcluster.net/v1/task/bnAGbk4hQnS3mTOwsq7YQw/artifacts/public/build/firefox-54.0.en-US.mac.dmg 14:30:00 INFO - Running post-action listener: _resource_record_post_action 14:30:00 INFO - Running post-action listener: set_extra_try_arguments 14:30:00 INFO - [mozharness: 2017-06-05 21:30:00.057552Z] Finished download-and-extract step (success) 14:30:00 INFO - [mozharness: 2017-06-05 21:30:00.057730Z] Running populate-webroot step. 14:30:00 INFO - Running pre-action listener: _resource_record_pre_action 14:30:00 INFO - Running main action method: populate_webroot 14:30:00 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 14:30:00 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 14:30:00 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 14:30:00 INFO - '--tppagecycles', 14:30:00 INFO - '7'], 14:30:00 INFO - 'tests': ['tsvgm']}}, 14:30:00 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 14:30:00 INFO - 'tests': ['tresize', 'tcanvasmark']}, 14:30:00 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 14:30:00 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 14:30:00 INFO - 'tests': ['dromaeo_css', 'kraken']}, 14:30:00 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 14:30:00 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 14:30:00 INFO - 'talos_options': ['--disable-e10s'], 14:30:00 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:30:00 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 14:30:00 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:30:00 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 14:30:00 INFO - 'talos_options': ['--disable-e10s'], 14:30:00 INFO - 'tests': ['damp', 'tps']}, 14:30:00 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 14:30:00 INFO - 'tests': ['damp', 'tps']}, 14:30:00 INFO - 'g3': {'talos_options': ['--disable-e10s'], 14:30:00 INFO - 'tests': ['dromaeo_dom']}, 14:30:00 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 14:30:00 INFO - 'g4': {'talos_options': ['--disable-e10s'], 14:30:00 INFO - 'tests': ['basic_compositor_video']}, 14:30:00 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 14:30:00 INFO - 'other': {'talos_options': ['--disable-e10s'], 14:30:00 INFO - 'tests': ['a11yr', 14:30:00 INFO - 'ts_paint', 14:30:00 INFO - 'tpaint', 14:30:00 INFO - 'sessionrestore', 14:30:00 INFO - 'sessionrestore_no_auto_restore', 14:30:00 INFO - 'tabpaint']}, 14:30:00 INFO - 'other-e10s': {'tests': ['a11yr', 14:30:00 INFO - 'ts_paint', 14:30:00 INFO - 'tpaint', 14:30:00 INFO - 'sessionrestore', 14:30:00 INFO - 'sessionrestore_no_auto_restore', 14:30:00 INFO - 'tabpaint']}, 14:30:00 INFO - 'other-e10s_l64': {'tests': ['a11yr', 14:30:00 INFO - 'ts_paint', 14:30:00 INFO - 'tpaint', 14:30:00 INFO - 'sessionrestore', 14:30:00 INFO - 'sessionrestore_no_auto_restore', 14:30:00 INFO - 'tabpaint']}, 14:30:00 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 14:30:00 INFO - 'ts_paint', 14:30:00 INFO - 'tpaint', 14:30:00 INFO - 'sessionrestore', 14:30:00 INFO - 'sessionrestore_no_auto_restore', 14:30:00 INFO - 'tabpaint']}, 14:30:00 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 14:30:00 INFO - 'tests': ['a11yr', 14:30:00 INFO - 'ts_paint', 14:30:00 INFO - 'tpaint', 14:30:00 INFO - 'sessionrestore', 14:30:00 INFO - 'sessionrestore_no_auto_restore', 14:30:00 INFO - 'tabpaint']}, 14:30:00 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 14:30:00 INFO - 'tests': ['a11yr', 14:30:00 INFO - 'ts_paint', 14:30:00 INFO - 'tpaint', 14:30:00 INFO - 'sessionrestore', 14:30:00 INFO - 'sessionrestore_no_auto_restore', 14:30:00 INFO - 'tabpaint']}, 14:30:00 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 14:30:00 INFO - 'tests': ['tsvgx', 14:30:00 INFO - 'tsvgr_opacity', 14:30:00 INFO - 'tart', 14:30:00 INFO - 'tscrollx', 14:30:00 INFO - 'cart', 14:30:00 INFO - 'tsvg_static']}, 14:30:00 INFO - 'svgr-e10s': {'tests': ['tsvgx', 14:30:00 INFO - 'tsvgr_opacity', 14:30:00 INFO - 'tart', 14:30:00 INFO - 'tscrollx', 14:30:00 INFO - 'cart', 14:30:00 INFO - 'tsvg_static']}, 14:30:00 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 14:30:00 INFO - 'talos_options': ['--disable-e10s'], 14:30:00 INFO - 'tests': ['tp5o']}, 14:30:00 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 14:30:00 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 14:30:00 INFO - 'talos_options': ['--disable-e10s', 14:30:00 INFO - '--xperf_path', 14:30:00 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:30:00 INFO - 'tests': ['tp5n']}, 14:30:00 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 14:30:00 INFO - 'talos_options': ['--xperf_path', 14:30:00 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:30:00 INFO - 'tests': ['tp5n']}}, 14:30:00 INFO - 'talos.zip': {'path': '', 14:30:00 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 14:30:00 INFO - Running post-action listener: _resource_record_post_action 14:30:00 INFO - [mozharness: 2017-06-05 21:30:00.076583Z] Finished populate-webroot step (success) 14:30:00 INFO - [mozharness: 2017-06-05 21:30:00.076717Z] Running create-virtualenv step. 14:30:00 INFO - Running pre-action listener: _resource_record_pre_action 14:30:00 INFO - Running main action method: create_virtualenv 14:30:00 INFO - Creating virtualenv /builds/slave/test/build/venv 14:30:00 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 14:30:00 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 14:30:00 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 14:30:00 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 14:30:00 INFO - Using real prefix '/tools/python27' 14:30:00 INFO - New python executable in /builds/slave/test/build/venv/bin/python 14:30:00 INFO - Installing distribute.............................................................................................................................................................................................done. 14:30:03 INFO - Installing pip.................done. 14:30:03 INFO - Return code: 0 14:30:03 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 14:30:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 14:30:04 INFO - Reading from file tmpfile_stdout 14:30:04 INFO - Output received: 14:30:04 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) 14:30:04 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 14:30:04 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')]} 14:30:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:30:04 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')]} 14:30:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:30:04 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 0x105603b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105606768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe28bccfef0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1053e1d30>, '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 0x105308db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1053d6be8>, '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.6cRNlmN2PX/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.cOOoHa1dqP/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9c/2dh985pd15l19fywb0pq404000000w/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 14:30:04 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 14:30:04 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 14:30:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6cRNlmN2PX/Render', 14:30:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:30:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:30:04 INFO - 'HOME': '/Users/cltbld', 14:30:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:30:04 INFO - 'LOGNAME': 'cltbld', 14:30:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:30:04 INFO - 'MOZ_NO_REMOTE': '1', 14:30:04 INFO - 'NO_EM_RESTART': '1', 14:30:04 INFO - 'PAGER': '/bin/cat', 14:30:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:30:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:30:04 INFO - 'PWD': '/builds/slave/test', 14:30:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:30:04 INFO - 'SHELL': '/bin/bash', 14:30:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cOOoHa1dqP/Listeners', 14:30:04 INFO - 'TMPDIR': '/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/', 14:30:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:30:04 INFO - 'USER': 'cltbld', 14:30:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:30:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:30:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:30:04 INFO - 'XPC_FLAGS': '0x0', 14:30:04 INFO - 'XPC_SERVICE_NAME': '0', 14:30:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:30:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:30:04 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 14:30:04 INFO - Cleaning up... 14:30:04 INFO - Return code: 0 14:30:04 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 14:30:04 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')]} 14:30:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:30:04 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')]} 14:30:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:30:04 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 0x105603b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105606768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe28bccfef0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1053e1d30>, '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 0x105308db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1053d6be8>, '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.6cRNlmN2PX/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.cOOoHa1dqP/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9c/2dh985pd15l19fywb0pq404000000w/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 14:30:04 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 14:30:04 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 14:30:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6cRNlmN2PX/Render', 14:30:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:30:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:30:04 INFO - 'HOME': '/Users/cltbld', 14:30:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:30:04 INFO - 'LOGNAME': 'cltbld', 14:30:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:30:04 INFO - 'MOZ_NO_REMOTE': '1', 14:30:04 INFO - 'NO_EM_RESTART': '1', 14:30:04 INFO - 'PAGER': '/bin/cat', 14:30:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:30:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:30:04 INFO - 'PWD': '/builds/slave/test', 14:30:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:30:04 INFO - 'SHELL': '/bin/bash', 14:30:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cOOoHa1dqP/Listeners', 14:30:04 INFO - 'TMPDIR': '/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/', 14:30:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:30:04 INFO - 'USER': 'cltbld', 14:30:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:30:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:30:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:30:04 INFO - 'XPC_FLAGS': '0x0', 14:30:04 INFO - 'XPC_SERVICE_NAME': '0', 14:30:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:30:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:30:04 INFO - Downloading/unpacking psutil>=3.1.1 14:30:04 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 14:30:04 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 14:30:04 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 14:30:04 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 14:30:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 14:30:06 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:30:06 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:30:06 INFO - Installing collected packages: psutil 14:30:06 INFO - Running setup.py install for psutil 14:30:06 INFO - building 'psutil._psutil_osx' extension 14:30:06 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 14:30:07 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 14:30:07 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 14:30:07 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 14:30:07 INFO - building 'psutil._psutil_posix' extension 14:30:07 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 14:30:07 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 14:30:07 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 14:30:07 INFO - ^ 14:30:07 INFO - 1 warning generated. 14:30:07 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 14:30:07 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:30:07 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:30:07 INFO - Successfully installed psutil 14:30:07 INFO - Cleaning up... 14:30:07 INFO - Return code: 0 14:30:07 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 14:30: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')]} 14:30:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:30: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')]} 14:30:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:30:07 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x105603b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105606768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe28bccfef0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1053e1d30>, '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 0x105308db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1053d6be8>, '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.6cRNlmN2PX/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.cOOoHa1dqP/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9c/2dh985pd15l19fywb0pq404000000w/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 14:30:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 14:30:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 14:30:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6cRNlmN2PX/Render', 14:30:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:30:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:30:07 INFO - 'HOME': '/Users/cltbld', 14:30:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:30:07 INFO - 'LOGNAME': 'cltbld', 14:30:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:30:07 INFO - 'MOZ_NO_REMOTE': '1', 14:30:07 INFO - 'NO_EM_RESTART': '1', 14:30:07 INFO - 'PAGER': '/bin/cat', 14:30:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:30:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:30:07 INFO - 'PWD': '/builds/slave/test', 14:30:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:30:07 INFO - 'SHELL': '/bin/bash', 14:30:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cOOoHa1dqP/Listeners', 14:30:07 INFO - 'TMPDIR': '/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/', 14:30:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:30:07 INFO - 'USER': 'cltbld', 14:30:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:30:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:30:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:30:07 INFO - 'XPC_FLAGS': '0x0', 14:30:07 INFO - 'XPC_SERVICE_NAME': '0', 14:30:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:30:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:30:08 INFO - Downloading/unpacking mozsystemmonitor==0.3 14:30:08 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 14:30:08 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 14:30:08 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 14:30:08 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 14:30:09 INFO - Downloading mozsystemmonitor-0.3.tar.gz 14:30:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 14:30:09 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 14:30:09 INFO - Installing collected packages: mozsystemmonitor 14:30:09 INFO - Running setup.py install for mozsystemmonitor 14:30:09 INFO - Successfully installed mozsystemmonitor 14:30:09 INFO - Cleaning up... 14:30:09 INFO - Return code: 0 14:30:09 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 14:30:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:30:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:30:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:30:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:30:09 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x105603b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105606768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe28bccfef0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1053e1d30>, '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 0x105308db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1053d6be8>, '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.6cRNlmN2PX/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.cOOoHa1dqP/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9c/2dh985pd15l19fywb0pq404000000w/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 14:30:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 14:30:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 14:30:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6cRNlmN2PX/Render', 14:30:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:30:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:30:09 INFO - 'HOME': '/Users/cltbld', 14:30:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:30:09 INFO - 'LOGNAME': 'cltbld', 14:30:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:30:09 INFO - 'MOZ_NO_REMOTE': '1', 14:30:09 INFO - 'NO_EM_RESTART': '1', 14:30:09 INFO - 'PAGER': '/bin/cat', 14:30:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:30:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:30:09 INFO - 'PWD': '/builds/slave/test', 14:30:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:30:09 INFO - 'SHELL': '/bin/bash', 14:30:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cOOoHa1dqP/Listeners', 14:30:09 INFO - 'TMPDIR': '/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/', 14:30:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:30:09 INFO - 'USER': 'cltbld', 14:30:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:30:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:30:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:30:09 INFO - 'XPC_FLAGS': '0x0', 14:30:09 INFO - 'XPC_SERVICE_NAME': '0', 14:30:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:30:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:30:10 INFO - Downloading/unpacking jsonschema==2.5.1 14:30:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:30:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:30:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:30:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:30:11 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 14:30:11 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 14:30:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:30:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:30:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:30:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:30:11 INFO - Downloading functools32-3.2.3-2.tar.gz 14:30:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 14:30:11 INFO - warning: no files found matching '*.txt' 14:30:11 INFO - no previously-included directories found matching 'build' 14:30:11 INFO - no previously-included directories found matching 'dist' 14:30:11 INFO - no previously-included directories found matching '.git*' 14:30:11 INFO - Installing collected packages: jsonschema, functools32 14:30:11 INFO - Running setup.py install for functools32 14:30:11 INFO - warning: no files found matching '*.txt' 14:30:11 INFO - no previously-included directories found matching 'build' 14:30:11 INFO - no previously-included directories found matching 'dist' 14:30:11 INFO - no previously-included directories found matching '.git*' 14:30:11 INFO - Successfully installed jsonschema functools32 14:30:11 INFO - Cleaning up... 14:30:11 INFO - Return code: 0 14:30:11 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 14:30:11 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')]} 14:30:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:30:12 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')]} 14:30:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:30:12 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/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 0x105603b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105606768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe28bccfef0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1053e1d30>, '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 0x105308db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1053d6be8>, '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.6cRNlmN2PX/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.cOOoHa1dqP/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9c/2dh985pd15l19fywb0pq404000000w/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 14:30:12 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 14:30:12 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 14:30:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6cRNlmN2PX/Render', 14:30:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:30:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:30:12 INFO - 'HOME': '/Users/cltbld', 14:30:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:30:12 INFO - 'LOGNAME': 'cltbld', 14:30:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:30:12 INFO - 'MOZ_NO_REMOTE': '1', 14:30:12 INFO - 'NO_EM_RESTART': '1', 14:30:12 INFO - 'PAGER': '/bin/cat', 14:30:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:30:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:30:12 INFO - 'PWD': '/builds/slave/test', 14:30:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:30:12 INFO - 'SHELL': '/bin/bash', 14:30:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cOOoHa1dqP/Listeners', 14:30:12 INFO - 'TMPDIR': '/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/', 14:30:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:30:12 INFO - 'USER': 'cltbld', 14:30:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:30:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:30:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:30:12 INFO - 'XPC_FLAGS': '0x0', 14:30:12 INFO - 'XPC_SERVICE_NAME': '0', 14:30:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:30:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:30:12 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 14:30:12 INFO - Cleaning up... 14:30:12 INFO - Return code: 0 14:30:12 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 14:30:12 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')]} 14:30:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:30:12 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')]} 14:30:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:30:13 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 0x105603b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105606768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe28bccfef0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1053e1d30>, '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 0x105308db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1053d6be8>, '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.6cRNlmN2PX/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.cOOoHa1dqP/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9c/2dh985pd15l19fywb0pq404000000w/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 14:30:13 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 14:30:13 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 14:30:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6cRNlmN2PX/Render', 14:30:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:30:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:30:13 INFO - 'HOME': '/Users/cltbld', 14:30:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:30:13 INFO - 'LOGNAME': 'cltbld', 14:30:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:30:13 INFO - 'MOZ_NO_REMOTE': '1', 14:30:13 INFO - 'NO_EM_RESTART': '1', 14:30:13 INFO - 'PAGER': '/bin/cat', 14:30:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:30:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:30:13 INFO - 'PWD': '/builds/slave/test', 14:30:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:30:13 INFO - 'SHELL': '/bin/bash', 14:30:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cOOoHa1dqP/Listeners', 14:30:13 INFO - 'TMPDIR': '/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/', 14:30:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:30:13 INFO - 'USER': 'cltbld', 14:30:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:30:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:30:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:30:13 INFO - 'XPC_FLAGS': '0x0', 14:30:13 INFO - 'XPC_SERVICE_NAME': '0', 14:30:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:30:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:30:13 INFO - Downloading/unpacking blobuploader==1.2.4 14:30:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:30:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:30:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:30:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:30:15 INFO - Downloading blobuploader-1.2.4.tar.gz 14:30:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 14:30:15 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 14:30:15 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 14:30:15 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 14:30:15 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 14:30:15 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 14:30:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 14:30:15 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 14:30:15 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 14:30:15 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 14:30:15 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 14:30:15 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 14:30:16 INFO - Downloading docopt-0.6.1.tar.gz 14:30:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 14:30:16 INFO - Installing collected packages: blobuploader, requests, docopt 14:30:16 INFO - Running setup.py install for blobuploader 14:30:16 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 14:30:16 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 14:30:16 INFO - Running setup.py install for requests 14:30:16 INFO - Running setup.py install for docopt 14:30:17 INFO - Successfully installed blobuploader requests docopt 14:30:17 INFO - Cleaning up... 14:30:17 INFO - Return code: 0 14:30:17 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:30:17 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')]} 14:30:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:30:17 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')]} 14:30:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:30:17 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 0x105603b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105606768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe28bccfef0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1053e1d30>, '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 0x105308db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1053d6be8>, '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.6cRNlmN2PX/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.cOOoHa1dqP/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9c/2dh985pd15l19fywb0pq404000000w/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 14:30:17 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 14:30:17 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 14:30:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6cRNlmN2PX/Render', 14:30:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:30:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:30:17 INFO - 'HOME': '/Users/cltbld', 14:30:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:30:17 INFO - 'LOGNAME': 'cltbld', 14:30:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:30:17 INFO - 'MOZ_NO_REMOTE': '1', 14:30:17 INFO - 'NO_EM_RESTART': '1', 14:30:17 INFO - 'PAGER': '/bin/cat', 14:30:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:30:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:30:17 INFO - 'PWD': '/builds/slave/test', 14:30:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:30:17 INFO - 'SHELL': '/bin/bash', 14:30:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cOOoHa1dqP/Listeners', 14:30:17 INFO - 'TMPDIR': '/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/', 14:30:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:30:17 INFO - 'USER': 'cltbld', 14:30:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:30:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:30:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:30:17 INFO - 'XPC_FLAGS': '0x0', 14:30:17 INFO - 'XPC_SERVICE_NAME': '0', 14:30:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:30:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:30:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:30:17 INFO - Running setup.py (path:/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/pip-tfU_4x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:30:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:30:17 INFO - Running setup.py (path:/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/pip-0XByg0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:30:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:30:17 INFO - Running setup.py (path:/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/pip-t4bJIv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:30:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:30:17 INFO - Running setup.py (path:/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/pip-cOMP6x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:30:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:30:17 INFO - Running setup.py (path:/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/pip-b6FUgr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:30:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:30:17 INFO - Running setup.py (path:/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/pip-wh1wi_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:30:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:30:18 INFO - Running setup.py (path:/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/pip-_4uKcm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:30:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:30:18 INFO - Running setup.py (path:/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/pip-dRvppI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:30:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:30:18 INFO - Running setup.py (path:/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/pip-kHpNJr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:30:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:30:18 INFO - Running setup.py (path:/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/pip-4c_P9k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:30:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:30:18 INFO - Running setup.py (path:/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/pip-AMcItl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:30:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:30:18 INFO - Running setup.py (path:/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/pip-wFi1Iv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:30:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:30:18 INFO - Running setup.py (path:/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/pip-a7NCjb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:30:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:30:18 INFO - Running setup.py (path:/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/pip-GsV9Q8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:30:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:30:18 INFO - Running setup.py (path:/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/pip-yH9OKx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:30:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:30:18 INFO - Running setup.py (path:/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/pip-cZm9SK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:30:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:30:19 INFO - Running setup.py (path:/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/pip-kgN9Vm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:30:19 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 14:30:19 INFO - Running setup.py install for manifestparser 14:30:19 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 14:30:19 INFO - Running setup.py install for mozcrash 14:30:19 INFO - Running setup.py install for mozdebug 14:30:19 INFO - Running setup.py install for mozdevice 14:30:19 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 14:30:19 INFO - Installing dm script to /builds/slave/test/build/venv/bin 14:30:19 INFO - Running setup.py install for mozfile 14:30:19 INFO - Running setup.py install for mozhttpd 14:30:20 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 14:30:20 INFO - Running setup.py install for mozinfo 14:30:20 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 14:30:20 INFO - Running setup.py install for mozInstall 14:30:20 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 14:30:20 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 14:30:20 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 14:30:20 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 14:30:20 INFO - Running setup.py install for mozleak 14:30:20 INFO - Running setup.py install for mozlog 14:30:20 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 14:30:20 INFO - Running setup.py install for moznetwork 14:30:20 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 14:30:20 INFO - Running setup.py install for mozprocess 14:30:20 INFO - Running setup.py install for mozprofile 14:30:21 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 14:30:21 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 14:30:21 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 14:30:21 INFO - Running setup.py install for mozrunner 14:30:21 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 14:30:21 INFO - Running setup.py install for mozscreenshot 14:30:21 INFO - Running setup.py install for moztest 14:30:21 INFO - Running setup.py install for mozversion 14:30:21 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 14:30:21 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 14:30:21 INFO - Cleaning up... 14:30:21 INFO - Return code: 0 14:30:21 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:30:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:30:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:30:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:30:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:30:21 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 0x105603b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105606768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe28bccfef0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1053e1d30>, '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 0x105308db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1053d6be8>, '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.6cRNlmN2PX/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.cOOoHa1dqP/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9c/2dh985pd15l19fywb0pq404000000w/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 14:30:21 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 14:30:21 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 14:30:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6cRNlmN2PX/Render', 14:30:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:30:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:30:21 INFO - 'HOME': '/Users/cltbld', 14:30:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:30:21 INFO - 'LOGNAME': 'cltbld', 14:30:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:30:21 INFO - 'MOZ_NO_REMOTE': '1', 14:30:21 INFO - 'NO_EM_RESTART': '1', 14:30:21 INFO - 'PAGER': '/bin/cat', 14:30:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:30:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:30:21 INFO - 'PWD': '/builds/slave/test', 14:30:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:30:21 INFO - 'SHELL': '/bin/bash', 14:30:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cOOoHa1dqP/Listeners', 14:30:21 INFO - 'TMPDIR': '/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/', 14:30:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:30:21 INFO - 'USER': 'cltbld', 14:30:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:30:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:30:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:30:21 INFO - 'XPC_FLAGS': '0x0', 14:30:21 INFO - 'XPC_SERVICE_NAME': '0', 14:30:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:30:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:30:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:30:22 INFO - Running setup.py (path:/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/pip-2YkIlh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:30:22 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)) 14:30:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:30:22 INFO - Running setup.py (path:/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/pip-E_yoDw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:30:22 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)) 14:30:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:30:22 INFO - Running setup.py (path:/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/pip-dikw0S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:30:22 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)) 14:30:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:30:22 INFO - Running setup.py (path:/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/pip-agJGpd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:30:22 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)) 14:30:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:30:22 INFO - Running setup.py (path:/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/pip-GxeWzC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:30:22 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)) 14:30:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:30:22 INFO - Running setup.py (path:/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/pip-xLKF6k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:30:22 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)) 14:30:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:30:22 INFO - Running setup.py (path:/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/pip-uPVfDy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:30:22 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)) 14:30:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:30:22 INFO - Running setup.py (path:/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/pip-NKa6Ir-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:30:22 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)) 14:30:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:30:22 INFO - Running setup.py (path:/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/pip-Iyxi2T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:30:23 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)) 14:30:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:30:23 INFO - Running setup.py (path:/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/pip-iM0oCD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:30:23 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)) 14:30:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:30:23 INFO - Running setup.py (path:/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/pip-DDLMvI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:30:23 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)) 14:30:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:30:23 INFO - Running setup.py (path:/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/pip-AfLiwn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:30:23 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)) 14:30:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:30:23 INFO - Running setup.py (path:/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/pip-ma9n0E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:30:23 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)) 14:30:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:30:23 INFO - Running setup.py (path:/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/pip-kDLq8u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:30:23 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)) 14:30:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:30:23 INFO - Running setup.py (path:/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/pip-htVPHN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:30:23 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)) 14:30:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:30:23 INFO - Running setup.py (path:/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/pip-XMv26T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:30:23 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)) 14:30:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:30:23 INFO - Running setup.py (path:/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/pip-adJs0s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:30:23 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)) 14:30:23 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)) 14:30:23 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)) 14:30:23 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)) 14:30:23 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)) 14:30:23 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)) 14:30:23 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 14:30:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:30:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:30:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:30:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:30:25 INFO - Downloading blessings-1.6.tar.gz 14:30:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 14:30:25 INFO - Installing collected packages: blessings 14:30:25 INFO - Running setup.py install for blessings 14:30:25 INFO - Successfully installed blessings 14:30:25 INFO - Cleaning up... 14:30:25 INFO - Return code: 0 14:30:25 INFO - Done creating virtualenv /builds/slave/test/build/venv. 14:30:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:30:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:30:25 INFO - Reading from file tmpfile_stdout 14:30:25 INFO - Current package versions: 14:30:25 INFO - blessings == 1.6 14:30:25 INFO - blobuploader == 1.2.4 14:30:25 INFO - docopt == 0.6.1 14:30:25 INFO - functools32 == 3.2.3-2 14:30:25 INFO - jsonschema == 2.5.1 14:30:25 INFO - manifestparser == 1.1 14:30:25 INFO - mozInstall == 1.12 14:30:25 INFO - mozcrash == 1.0 14:30:25 INFO - mozdebug == 0.1 14:30:25 INFO - mozdevice == 0.49 14:30:25 INFO - mozfile == 1.2 14:30:25 INFO - mozhttpd == 0.7 14:30:25 INFO - mozinfo == 0.9 14:30:25 INFO - mozleak == 0.1 14:30:25 INFO - mozlog == 3.4 14:30:25 INFO - moznetwork == 0.27 14:30:25 INFO - mozprocess == 0.25 14:30:25 INFO - mozprofile == 0.28 14:30:25 INFO - mozrunner == 6.13 14:30:25 INFO - mozscreenshot == 0.1 14:30:25 INFO - mozsystemmonitor == 0.3 14:30:25 INFO - moztest == 0.8 14:30:25 INFO - mozversion == 1.4 14:30:25 INFO - psutil == 3.1.1 14:30:25 INFO - requests == 1.2.3 14:30:25 INFO - wsgiref == 0.1.2 14:30:25 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:30:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:30:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:30:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:30:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:30:25 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 0x105603b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105606768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe28bccfef0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1053e1d30>, '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 0x105308db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1053d6be8>, '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.6cRNlmN2PX/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.cOOoHa1dqP/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9c/2dh985pd15l19fywb0pq404000000w/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 14:30:25 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 14:30:25 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 14:30:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6cRNlmN2PX/Render', 14:30:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:30:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:30:25 INFO - 'HOME': '/Users/cltbld', 14:30:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:30:25 INFO - 'LOGNAME': 'cltbld', 14:30:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:30:25 INFO - 'MOZ_NO_REMOTE': '1', 14:30:25 INFO - 'NO_EM_RESTART': '1', 14:30:25 INFO - 'PAGER': '/bin/cat', 14:30:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:30:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:30:25 INFO - 'PWD': '/builds/slave/test', 14:30:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:30:25 INFO - 'SHELL': '/bin/bash', 14:30:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cOOoHa1dqP/Listeners', 14:30:25 INFO - 'TMPDIR': '/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/', 14:30:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:30:25 INFO - 'USER': 'cltbld', 14:30:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:30:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:30:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:30:25 INFO - 'XPC_FLAGS': '0x0', 14:30:25 INFO - 'XPC_SERVICE_NAME': '0', 14:30:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:30:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:30:26 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)) 14:30:26 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)) 14:30:26 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)) 14:30:26 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)) 14:30:26 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)) 14:30:26 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)) 14:30:26 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)) 14:30:26 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)) 14:30:26 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)) 14:30:26 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)) 14:30:26 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)) 14:30:26 INFO - Cleaning up... 14:30:26 INFO - Return code: 0 14:30:26 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 14:30:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:30:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:30:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:30:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:30:26 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x105603b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105606768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe28bccfef0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1053e1d30>, '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 0x105308db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1053d6be8>, '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.6cRNlmN2PX/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.cOOoHa1dqP/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9c/2dh985pd15l19fywb0pq404000000w/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 14:30:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 14:30:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 14:30:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6cRNlmN2PX/Render', 14:30:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:30:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:30:26 INFO - 'HOME': '/Users/cltbld', 14:30:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:30:26 INFO - 'LOGNAME': 'cltbld', 14:30:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:30:26 INFO - 'MOZ_NO_REMOTE': '1', 14:30:26 INFO - 'NO_EM_RESTART': '1', 14:30:26 INFO - 'PAGER': '/bin/cat', 14:30:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:30:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:30:26 INFO - 'PWD': '/builds/slave/test', 14:30:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:30:26 INFO - 'SHELL': '/bin/bash', 14:30:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cOOoHa1dqP/Listeners', 14:30:26 INFO - 'TMPDIR': '/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/', 14:30:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:30:26 INFO - 'USER': 'cltbld', 14:30:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:30:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:30:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:30:26 INFO - 'XPC_FLAGS': '0x0', 14:30:26 INFO - 'XPC_SERVICE_NAME': '0', 14:30:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:30:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:30:26 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 14:30:26 INFO - Cleaning up... 14:30:26 INFO - Return code: 0 14:30:26 INFO - Running post-action listener: _resource_record_post_action 14:30:26 INFO - Running post-action listener: _start_resource_monitoring 14:30:26 INFO - Starting resource monitoring. 14:30:26 INFO - [mozharness: 2017-06-05 21:30:26.459608Z] Finished create-virtualenv step (success) 14:30:26 INFO - [mozharness: 2017-06-05 21:30:26.460607Z] Running install step. 14:30:26 INFO - Running pre-action listener: _resource_record_pre_action 14:30:26 INFO - Running main action method: install 14:30:26 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:30:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:30:26 INFO - Reading from file tmpfile_stdout 14:30:26 INFO - Detecting whether we're running mozinstall >=1.0... 14:30:26 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 14:30:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 14:30:26 INFO - Reading from file tmpfile_stdout 14:30:26 INFO - Output received: 14:30:26 INFO - Usage: mozinstall [options] installer 14:30:26 INFO - Options: 14:30:26 INFO - -h, --help show this help message and exit 14:30:26 INFO - -d DEST, --destination=DEST 14:30:26 INFO - Directory to install application into. [default: 14:30:26 INFO - "/builds/slave/test"] 14:30:26 INFO - --app=APP Application being installed. [default: firefox] 14:30:26 INFO - mkdir: /builds/slave/test/build/application 14:30:26 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'] 14:30:26 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 14:30:46 INFO - Reading from file tmpfile_stdout 14:30:46 INFO - Output received: 14:30:46 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 14:30:46 INFO - Running post-action listener: _resource_record_post_action 14:30:46 INFO - [mozharness: 2017-06-05 21:30:46.843808Z] Finished install step (success) 14:30:46 INFO - [mozharness: 2017-06-05 21:30:46.843954Z] Running run-tests step. 14:30:46 INFO - Running pre-action listener: _resource_record_pre_action 14:30:46 INFO - Running main action method: run_tests 14:30:46 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 14:30:46 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 14:30:46 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 14:30:46 INFO - 2017-06-05 14:30:46.858 screenresolution[1628:7422] starting screenresolution argv=screenresolution get 14:30:46 INFO - 2017-06-05 14:30:46.861 screenresolution[1628:7422] Display 0: 1600x1200x32@60 14:30:46 INFO - 2017-06-05 14:30:46.867 screenresolution[1629:7425] starting screenresolution argv=screenresolution list 14:30:46 INFO - Available Modes on Display 0 14:30:46 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 14:30:46 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 14:30:46 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 14:30:46 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 14:30:47 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 14:30:47 INFO - Intel Iris: 14:30:47 INFO - Chipset Model: Intel Iris 14:30:47 INFO - Type: GPU 14:30:47 INFO - Bus: Built-In 14:30:47 INFO - VRAM (Dynamic, Max): 1536 MB 14:30:47 INFO - Vendor: Intel (0x8086) 14:30:47 INFO - Device ID: 0x0a2e 14:30:47 INFO - Revision ID: 0x0009 14:30:47 INFO - Displays: 14:30:47 INFO - Display: 14:30:47 INFO - Resolution: 1600 x 1200 @ 60 Hz 14:30:47 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 14:30:47 INFO - Display Serial Number: 1600x1200 60 14:30:47 INFO - Main Display: Yes 14:30:47 INFO - Mirror: Off 14:30:47 INFO - Online: Yes 14:30:47 INFO - Rotation: Supported 14:30:47 INFO - Return code: 0 14:30:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 14:30:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 14:30:47 INFO - Python 2.7.3 14:30:47 INFO - Return code: 0 14:30:47 INFO - grabbing minidump binary from tooltool 14:30: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')]} 14:30:47 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 0x1053e1d30>, '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 0x105308db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1053d6be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 14:30:47 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 14:30:47 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 14:30:47 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 14:30:47 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 14:30:47 INFO - Return code: 0 14:30:47 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 14:30:47 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 14:30:47 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 14:30:47 INFO - ENV: RUST_BACKTRACE is now 1 14:30:47 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 14:30:47 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 14:30:47 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/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/bnAGbk4hQnS3mTOwsq7YQw/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0060', '--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 14:30:47 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/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/bnAGbk4hQnS3mTOwsq7YQw/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0060 --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 14:30:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6cRNlmN2PX/Render', 14:30:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:30:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:30:47 INFO - 'HOME': '/Users/cltbld', 14:30:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:30:47 INFO - 'LOGNAME': 'cltbld', 14:30:47 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 14:30:47 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 14:30:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:30:47 INFO - 'MOZ_NO_REMOTE': '1', 14:30:47 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 14:30:47 INFO - 'NO_EM_RESTART': '1', 14:30:47 INFO - 'PAGER': '/bin/cat', 14:30:47 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:30:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:30:47 INFO - 'PWD': '/builds/slave/test', 14:30:47 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 14:30:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:30:47 INFO - 'RUST_BACKTRACE': '1', 14:30:47 INFO - 'SHELL': '/bin/bash', 14:30:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cOOoHa1dqP/Listeners', 14:30:47 INFO - 'TMPDIR': '/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/', 14:30:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:30:47 INFO - 'USER': 'cltbld', 14:30:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:30:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:30:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:30:47 INFO - 'XPC_FLAGS': '0x0', 14:30:47 INFO - 'XPC_SERVICE_NAME': '0', 14:30:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:30:47 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/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/bnAGbk4hQnS3mTOwsq7YQw/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0060', '--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 14:30:47 INFO - mozversion application_buildid: 20170605125726 14:30:47 INFO - mozversion application_changeset: 97767088522e789dd04cf06b24478f052946d1c9 14:30:47 INFO - mozversion application_display_name: FirefoxDeveloperEdition 14:30:47 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 14:30:47 INFO - mozversion application_name: Firefox 14:30:47 INFO - mozversion application_remotingname: firefox-dev 14:30:47 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 14:30:47 INFO - mozversion application_vendor: Mozilla 14:30:47 INFO - mozversion application_version: 54.0 14:30:47 INFO - mozversion platform_buildid: 20170605125726 14:30:47 INFO - mozversion platform_changeset: 97767088522e789dd04cf06b24478f052946d1c9 14:30:47 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 14:30:47 INFO - mozversion platform_version: 54.0 14:30:47 INFO - using testdate: 1496698247 14:30:47 INFO - actual date: 1496698247 14:30:47 INFO - starting webserver on 'localhost:49224' 14:30:47 INFO - SUITE-START | Running 2 tests 14:30:47 INFO - TEST-START | dromaeo_css 14:30:47 INFO - operating with platform_type : mac_ 14:30:47 INFO - Initialising browser for dromaeo_css test... 14:30:47 INFO - TEST-INFO | started process 1639 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/tmpZRdeX7/profile http://localhost:49224/getInfo.html) 14:30:50 INFO - PROCESS | 1639 | 2017-06-05 14:30:50.358 plugin-container[1642:7609] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4d47, name = 'com.apple.tsm.portname' 14:30:50 INFO - PROCESS | 1639 | See /usr/include/servers/bootstrap_defs.h for the error codes. 14:30:50 INFO - PROCESS | 1639 | 2017-06-05 14:30:50.360 plugin-container[1642:7609] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x940b, name = 'com.apple.CFPasteboardClient' 14:30:50 INFO - PROCESS | 1639 | See /usr/include/servers/bootstrap_defs.h for the error codes. 14:30:50 INFO - PROCESS | 1639 | 2017-06-05 14:30:50.360 plugin-container[1642:7609] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 14:30:50 INFO - PROCESS | 1639 | __metrics Screen width/height:1600/1200 14:30:50 INFO - PROCESS | 1639 | colorDepth:24 14:30:50 INFO - PROCESS | 1639 | Browser inner width/height: 1024/689 14:30:50 INFO - PROCESS | 1639 | __metrics 14:30:50 ERROR - PROCESS | 1639 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3381: TypeError: this.tabs is undefined 14:30:51 INFO - TEST-INFO | 1639: exit 0 14:30:51 INFO - Browser initialized. 14:30:51 INFO - Running cycle 1/1 for dromaeo_css test... 14:30:51 INFO - TEST-INFO | started process 1643 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/tmpZRdeX7/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 14:30:53 INFO - PROCESS | 1643 | 2017-06-05 14:30:53.519 plugin-container[1644:7733] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x854b, name = 'com.apple.tsm.portname' 14:30:53 INFO - PROCESS | 1643 | See /usr/include/servers/bootstrap_defs.h for the error codes. 14:30:53 INFO - PROCESS | 1643 | 2017-06-05 14:30:53.521 plugin-container[1644:7733] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8c0f, name = 'com.apple.CFPasteboardClient' 14:30:53 INFO - PROCESS | 1643 | See /usr/include/servers/bootstrap_defs.h for the error codes. 14:30:53 INFO - PROCESS | 1643 | 2017-06-05 14:30:53.521 plugin-container[1644:7733] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 14:31:03 INFO - PROCESS | 1643 | RSS: Main: 203169792 14:31:03 INFO - PROCESS | 1643 | 14:34:06 INFO - PROCESS | 1643 | Cycle 1(1): loaded http://localhost:49224/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49224/tests/dromaeo/cssquery-ext.html) 14:34:08 INFO - PROCESS | 1643 | RSS: Main: 216850432 14:34:08 INFO - PROCESS | 1643 | 14:37:10 INFO - PROCESS | 1643 | Cycle 1(1): loaded http://localhost:49224/tests/dromaeo/cssquery-ext.html (next: http://localhost:49224/tests/dromaeo/cssquery-jquery.html) 14:37:11 INFO - PROCESS | 1643 | RSS: Main: 230014976 14:37:11 INFO - PROCESS | 1643 | 14:40:12 INFO - PROCESS | 1643 | Cycle 1(1): loaded http://localhost:49224/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49224/tests/dromaeo/cssquery-mootools.html) 14:40:13 INFO - PROCESS | 1643 | RSS: Main: 231825408 14:40:13 INFO - PROCESS | 1643 | 14:43:15 INFO - PROCESS | 1643 | Cycle 1(1): loaded http://localhost:49224/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49224/tests/dromaeo/cssquery-prototype.html) 14:43:15 INFO - PROCESS | 1643 | RSS: Main: 235204608 14:43:15 INFO - PROCESS | 1643 | 14:46:18 INFO - PROCESS | 1643 | Cycle 1(1): loaded http://localhost:49224/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49224/tests/dromaeo/cssquery-yui.html) 14:46:19 INFO - PROCESS | 1643 | RSS: Main: 237309952 14:46:19 INFO - PROCESS | 1643 | 14:49:22 INFO - PROCESS | 1643 | Cycle 1(1): loaded http://localhost:49224/tests/dromaeo/cssquery-yui.html (next: http://localhost:49224/tests/dromaeo/cssquery-dojo.html) 14:49:22 INFO - PROCESS | 1643 | RSS: Main: 235511808 14:49:22 INFO - PROCESS | 1643 | 14:49:22 INFO - PROCESS | 1643 | __start_tp_report 14:49:22 INFO - PROCESS | 1643 | _x_x_mozilla_page_load 14:49:22 INFO - PROCESS | 1643 | _x_x_mozilla_page_load_details 14:49:22 INFO - PROCESS | 1643 | |i|pagename|runs| 14:49:22 INFO - PROCESS | 1643 | |0;dojo.html;3313.2876431567215;3401.710854577361;3490.476428535718;3506.9654451936676;3521.859125634973;5306.283651707024;5906.616069955455;6031.668258245806;6031.849203769908;6064.909026364597;5972.19375384323;5988;6033.818985430433;6039.788607398731;6040.063790112539;5339.385970613381;5409.783608655649;5424.294841670597;5463.13903404355;5492.340919089715;4505.887352816189;5465.480779325958;5476.205950137228;5489.560835133199;5502.312210973629;5266.710330931797;5297.311349524556;5344.4922732340365;5475.206095116206;5547.583931205156;5358.892822143535;5374.48942350476;5389.865253368658;5430.131179011379;5452.754626041798;5302.204669299597;5378.273933019032;5408.323959505062;5424.945750542483;5430.15832545956;5684.204211410406;5932.851678708023;5988.790392336291;6069.696515174268;6082.1180928765525;6283.02613094971;6283.748650053993;6286.685665716695;6324.588901721373;6326.746930122789;5760.452756988079;6180.196574445338;6448.45188159007;6464.838379040561;6469.094326794253;6140.600860944024;6183.258009038854;6197.783077592263;6204.441600255998;6218.689065546704;5188.9740551297755;6237.251529816451;6335.68321583919;6431.485481161496;6438.130852334875;6274.435300822948;6323.557350985387;6324.6521441321165;6338.619682819046;6344;6419.582727122722;6442.549021568445;6451.870962580816;6455.225372955276;6462.838429039311;6188.1027251048345;6249.312575616678;6257.624542527463;6262.091996660459;6315.494760419156;6240.126382306481;6318.052292156213;6357.554971151975;6375.2987171411105;6383.744650213939;4742.407199100113;4857.562819346276;4901;4948.628852836052;4949.183384741549;4416.66874984371;4431.911361772746;4445.59989600931;4469.776511174428;4475.26158183896;5766.106253530709;5809.651420914759;5830.067189249785;5986.790462333797;5992.490638295706;5278.868028299246;6237.313895471494;6304.936950630527;6318.968405157945;6385.5210859185745;5947.1079338099635;5957.136215248851;5965.55258355625;6007.858506883678;6025.15647809307;5603.047481928082;5605.579581531401;5615;5618.073017951992;5619.578531610146;6333.271673757485;6379.489640828815;6478.902816457851;6482.935170648422;6591.571547849375;5477.315335583052;6447.0329450582785;6457.966725323926;6471.611703297817;6475.352464753487;4959.950400496094;6417.005364168562;6434.703751501072;6597.340265973365;6627.834304142242;5684.8010319638615;5747.683877386784;6269.467095296951;6275.278342990615;6374.936250637435;6479.222493300834;6486.286508483969;6511.023346498063;6516.57642253252;6534.803955881332;6265.655388953652;6279.623222606659;6283.37166283368;6301.495880329471;6312.400321969406;5219.895602088013;6125.998614523495;6176.197094377702;6181.443670069715;6234.913717709582;6111.926012480351;6124.418180272867;6238.563300568917;6247.156633854372;6263.467605253604;6265.968670156803;6290.144407399919;6298.338674439118;6301.527385446018;6419.133416988647;5487.5609951203005;5991.281046274475;6062.545309101747;6161.630302181883;6187.504999600112 14:49:22 INFO - PROCESS | 1643 | |1;ext.html;711.697528550366;773.4353921637205;839.2907992746132;974.3179774158091;982.7788747531811;38305.425418618746;39149.80425097874;41127.971800704996;41289;41299.587004129986;40025;40128;40149;40188.794336169885;40217.396739048934;6864.553804002736;8205.425620206559;11046.005859472645;11340.206185567014;11526.19316647835;11195.27230730004;11267.774644507104;11306.773864522705;11315.207935444521;11330.376829274386;9857.802843943153;11023.9527244912;11304;11305.943470282678;11345.43272836359;11265.887341126565;11291.378974156418;11292.604758833442;11314.547418103266;11329;10904.291221070605;10965.342079475176;10968.890311096866;10976.835347469794;10982.450877456095;35273.47089793655;35446.82276588627;35466.11334716627;35503.82248088743;35595.46606800874;3358.916027099342;3857.498525191735;3893.27974324748;3894.844206231747;3925.7055720820767;24683.5063298733;24917.750822491726;26376.81304341309;26570.33574160642;27307.86346068277;3062.6784187660196;3124.375124974991;3158.210447388153;3180.2367431816197;3297.73618110553;270393;336954;359986;363462;392431.99999999715;44873.77563112229;45170;45227.54772452233;45231.77384113058;45237.77381113073;46095.76952115218;46169.76915115403;46360.5363946363;46390.07219855584;46404;13873;13923.791143132861;13936.58190254294;13942.930285348508;14026.29868506591;12023.27860328383;12156.452959616838;12157.756844863054;12198.57304994321;12225.960793332666;11934.641960741192;11953.581624643095;11989.280643161439;12010.219335743148;12068.974137198436;5368.872536767322;5427.511523962862;5824.912626310609;5866.090755932837;5884;966.2318456826839;1053.8355117594947;1116.972075698114;1180.1620849196995;1221.2977537915733;24705.382365441008;29551.113466596038;30592;31051.068467946;31389.529157062207;1045.1951996962296;1051.153821173947;1091.792559413709;1100.4442756408123;1105.5246244115108;15222.695546089017;15644.608884777745;15729.842701573067;15755.369785208693;16048.19759012068;229474;229661;230123.849380752;230930;230993;46555;46617;46647;46924.296135558;47023.52976470192;1357.4434481862388;1415.6531649745878;1509.6603264265673;1512.3345727879919;1519.118911031599;25306.493870122864;27653.234676532185;27917.441651166457;29739.71377012646;29954.550681740227;47745;47999.280010799164;48244;48298.27552586644;48320;455220;504645;506314;507641;508580;31011.224719382197;31589.052328430185;32379.838100809346;32434.189145271557;32829;40007;40750;41404.58595414007;41466.792666036476;42204.78897605492;23285.41786455352;28297.858510707316;29190.708092918798;29788.10635680933;29832;27940.882364705183;29059;29726.851365743034;29756.553651695674;29891;28148;33793.83103084469;34312;34656;34765.82617086898;26659.86670066637;27366.589501157898;28933.56599650965;29437.26406839846;30112.096637100924;17819.821801781814;19649.01754912277;22378;23747.931343089258;23897.52204955926 14:49:22 INFO - PROCESS | 1643 | |2;jquery.html;414.19645886979276;426.6991770801585;430.7524003946122;438.1915366149455;441.8718571614232;20291;20909.372718818442;22168;22366.440838979033;22403.887980560095;21605.919704014777;21840.344789656338;22493.887530562366;22611.773882261172;23299.184528541464;6827;6894.172699276081;7101.076860008217;7144.428445724356;7155.284471552829;6853.143357080365;6995.475339349569;7051.224365319837;7053.329933656294;7066.540674856144;6974.197967233782;7044.964775176142;7440.702371905118;7808.570528620923;7970.043594768636;7695.923040769577;7816.257455541714;7910.288074073333;7912.248336408032;8021.639026243833;6533.901991470132;7696.9615151924445;7711.652975616054;7713.421493387962;7715.537067775951;19032;20993.89503052475;25819.483610327876;26397.34006649849;26695;2706.418120104187;2860.713928607123;2922.4301261254063;2993.176876358997;3014.3368458939;15696.450624228097;15766.290516926765;16395.016299021976;16459.506214813573;17052.658946820993;2047.1811275489774;2087.6137914485867;2094.2565389286738;2109.504266497372;2117.0890026213956;174325;181358;184723;184769;185775;21521.569568608542;21657.91710414473;21751.564968700855;21783.23758668439;21806.563868722536;31430.371392572022;31681.36637267242;31925.52111718326;32094;32144.678553214635;8507.617157227816;9618.663346782969;9856.767904011998;9872.407655540546;10115.291929565006;8145.307648849795;8200.26197642215;8236.794080147924;8371.497710137412;8388.706395276136;7997;9603.231741460666;9607.375520591138;9763.316567840184;9817.852732209023;3640.7087433005295;4325.589069038437;4380.627646650007;4407.911841763147;4417.885390799543;878.1043335797451;894.2488309819782;897.5646811296516;929.5445231836487;942.7643089227693;20992.265270715452;21499.57000859974;21521.569568608542;21537.892310538347;21620.56758864814;816.8325493273865;928.4150984879483;944.7779771753633;950.8763860698065;1001.7545701303081;10194.43930583825;11021.944890275498;11038.944805275922;11071.944640276746;11092.946170113826;644855;648474;726916;730374;773637;170105;170978;171750;172626.27373726355;178128;1189.8691143974156;1208.3958020989505;1234.4383305595932;1236.1717649175075;1243.185713357752;18279.994600297112;18567.350142744937;18916.959567223385;18971.43085707431;19132.71300930515;63249.051264231995;66135;66354.66822665856;67015.66492167508;67083.32916670771;72266.63866680634;92743.53628232086;94738.52630736803;95257.04742952481;98654.50672746591;8975.820483590422;11195.989734679486;11302;11484.792488638133;11704.824427633423;12881.162724422882;13002.95976321873;13129.803052954023;13208.603741887759;13279.93760499177;10914.945425273141;11371.260868043551;11479.196456247982;11502.252353597325;11596.884031159918;10158.13655839262;10312.639057633247;10388.532516036948;10509.579616815241;10638.89361106379;11159.944200279271;11248;11403;11542.653720388402;11620;11188.595531326462;11233.101351891992;11251.043661288883;11275.492602833056;11565.653030409101;11164.44177791091;11206.439678015904;11265.943670281596;11361.147913906656;11378.54485820558 14:49:22 INFO - PROCESS | 1643 | |3;mootools.html;1498.176003198241;1582.351235993243;1606.7429211326182;1612.7419612861945;1621.0759866875883;9480.288978326618;9666.839979202488;10595.947020264897;11202.271852329593;11306.660800175998;10750.731231719212;10996.2852414593;11011.83482247767;11026;11049.613263535777;2141.9464513387134;2200.6478963365867;2432.722820519228;2472.159465781634;2475.10896077412;2417.492326611414;2451.5464639041834;2452.828302018859;2453.865037422941;2456.9754302457013;2419.733829278778;2420.7095148582193;2427.963580546293;2438.17102185257;2442.3039433761382;1877.399232245682;2145.463634091485;2392.928212153638;2396.0296080087505;2404.1825779235146;2344.34358379654;2359.433735903388;2360.44529535559;2366.0417530900013;2375.263668262844;10826.188035897261;10895.073918716918;10918.181136414722;10975.725606859813;11019;5811.82564523065;5823.67969761663;5878.147668588073;5903.203067585907;5918.733656985403;3628.872989445357;3698.0385099873956;3703.7222208334483;3745.3445647011936;3771.057235691077;1225.9448324825314;1227.061298106949;1253.5612535612554;1273.808928660699;1276.6744480157593;15501.767473487795;15505.689886202326;15506.302216400289;15508.92245538765;15535;4804.615630749532;4937.308776771255;4981.501849815062;5025.422076461181;5071.18860982241;11290.604828830952;12963.870361296455;12980.221186728826;13001.674958126123;13027.47890084405;5937.7921772737745;5973.970130149321;6048.092786082123;6066.514678825683;6087.878242435127;5168.8449346519665;5287.391949925731;5354.063038968165;5377.78488860449;5416.864578385493;3796.1648437343742;3923.3330333843314;3932.075962148893;3952.6442620164325;3980.5820388859333;1051.784384201237;1053.4943227250963;1066.5200659703164;1066.6213494209444;1067.6263307842194;800.491687778265;940.3088729783603;943.5046600534637;954.61815273891;968.9680490277832;6515.641639709766;6632.62270699058;6676.26561078281;6776.7628133015105;6794.388505034571;1008.1279693065445;1030.6804890483975;1052.1109662335314;1088.77135801481;1109.8335249712607;4117.649999750055;4234.25900467417;4426.645868330525;4430.556944305544;4443.622292105208;13197.538086166938;13875.791863122062;13894.235817029958;13896.51362202318;13905.374258158407;13481.123726957716;13575.52485662997;13758.31208439574;13913.373898174606;13920.095193812369;8771.736847894574;10129.290949633454;10141.645042423479;10281.331713438753;10373.688789336333;6615.305392933673;6649.867002660017;6676.432503237279;6774.830629234309;6793.218779840382;12106.878931210575;12195.329256890602;12252;12252.693682658004;12387.75224495523;12096.697582560155;12321.876781232431;12335.629931102081;12760.42578084007;12805.679858003625;9022;9263.44419334815;9447.102525260088;9447.81104377895;9451.196648284973;10030.548625312025;10302.02130797573;10380.792384152426;10381.844272336071;10515.05364517197;8827.344469418733;8951.955240223757;8964.820703586021;9087.227585655293;9089.409188402733;8625.137486251324;8709.433886797338;8730.388872778845;8781.25359344463;8792;7912.248336408032;8004.679812807422;8077.111517733162;8087.231712987257;8104.27061564462;5758.193852860519;5811.418858114154;5859.648421094748;5984.431479009487;6017.819465416044;5210.817621383233;5792.623479473821;5859.355470898113;5883.146943693226;5986.970065149646 14:49:22 INFO - PROCESS | 1643 | |4;prototype.html;347.8034910275694;384.2008622066103;385.92860320840646;389.6746216908881;395.2135250850806;5022.698638081713;5275.55157811586;5427.9185812212845;5464.644798088121;5521.69630670314;4630.522670371343;4765.523447655232;5014.674046187004;5384.40771515133;5453.754581043852;4066.9796651016704;4207.936880946788;4265.530791612928;4430.446194225722;4500.907078208632;4516.54834516549;4520.728756274618;4530.071335376257;4553.4535855697195;4560.361549383072;3861.362875125601;4535.637149028086;4546.295324224734;4547.886302842422;4574.428196475441;3889.4749208857006;4033.657139143176;4459.531749166355;4476.7761611919595;4477.171723231213;3457.533233013536;4125;4132.690048246393;4140.991619064751;4145.523264824554;4878.365812444395;5037;5085.770535709814;5322.680639161663;5336.599755018389;4502.482214545336;4566.1552612766745;4862.298195631501;5122.591287395959;5434.429384914606;5461.4811592899005;5865.941340586583;5869.647821130746;5901;5998.490128339096;4472.5303843096335;4548.772561371951;4795.088933102734;5047.570956468668;5069.8732531687;6051.576389652726;6106.328303886613;6139.846503837395;6211.919007132616;6225.28409232935;4492.797824097924;5189.143791274473;5257.421683614799;5257.423862307518;5311.2033195020285;4442.644588432918;5351.25082488452;5360.410354860962;5364.892702145935;5367.651102678314;5250.238715386247;5252.868678282997;5436.439132368305;5492.615516913777;5546.750396232179;5247.527722504993;5491.615586908958;5777.493555027002;5886.470217680429;5896.262967129109;1577.6371434570112;1583.8064280970939;1658.477579562434;1834.5046837353839;1842.2630947620792;1341.684704094537;1392.3873495661876;1435.7630990886387;1479.681868398278;1482.8507906372647;4706.482286948433;5235.712035838065;5249.658772179796;5271.42014378418;5287.4448182941005;5255.975084858416;5417.458254174551;5423.566114710814;5457.727113644302;6065.363136870653;4510;5009.44896061433;5142.8971420572125;5274.630775845731;5327.094393953037;2578.948421031569;2786.972130278712;2891.773334532398;3085.8302793346584;3141.7643676724338;5413.945860541344;6228.314885362606;6236.282827474808;6239.594426362272;6245.313015568284;4999.900001999939;5301.54936830366;5753.798617048383;6128.724207410677;6164.445199932027;5265.736713164327;5672.347680016768;5883.61756485827;5890.4993075589055;6201.534884883562;5585.609007369608;5862.472377486047;5990.430909063632;6074.90887636677;6080.604760690541;5146.819861304836;5281.728856591702;5518.696471694096;5673.921725239584;5971.492423144082;5229.006488767122;5559.027170245272;5815.3893841147765;6008.369121242206;6195.876082478415;4978.655762943912;5134.486551344836;5198.558122559473;5221.9216711748595;5242.606804489602;5407.405185429679;5424.620276580601;5977.850553736192;6025.427584379477;6026.939730602638;4737.692050016738;5261.158594492051;5264.2630031794815;5574.414686457863;5826.883462330815;5436.058857328405;5522.889542209214;5688.899549745316;5741.483266506035;5793.364019371092;5151.101988223538;5367.248585198154;5485.434467596095;5516.834494965157;5684.857878553069;4960.5287497689105;5384.488473595002;5736.856578585569;5835.416458354131;5849.151872978309;4855.781489832895;5269.0252303324005;5417.864553386197;5565.30433695788;5579.442055794388 14:49:22 INFO - PROCESS | 1643 | |5;yui.html;734.4234775701073;741.5402450480702;781.7967328494593;840.8192238668686;858.609332753597;15501.06993580386;16132.596685082879;16456.917715411437;16639.83360166398;16752.246148923296;15167.924160379183;15534.456294029711;15541;15724.135172565504;15730.528084157466;929.8884133903924;969.5297780576415;970.7864269860619;972.19793670222;974.1330216107634;830.3689196210893;898.6809682562695;964.045594861086;964.4550828781751;977.5698692575244;885.9911400885973;1034.1933292032227;1034.2191645307782;1037.761314897574;1039.833626619741;1026.199564339816;1027.6660085472215;1028.033648370533;1028.4497793680346;1030.1861529391772;759.7151068349369;768;770.7803276066351;771.7607541662103;772.4940164192462;14871;15365.693916017151;16852.157392130466;16939.91530042354;17667.469975900614;12587.37063146839;14003.019788614802;14271.35778889942;14356;14459.855401445957;10322.22583306255;11458.083353331713;11765.411729413496;11911.88604033916;12099.274043557414;3626.510421093145;3739.6821270192067;3755.2114056048263;3759.248150369937;3767.321882061228;104291.9141617171;112626;115200.84799151985;115379.42310288394;118948.21577676342;10664.68005959807;11044.558217671203;11099.056580190774;11306.94346528262;11493.42532873353;19549.804501955085;23270.069197231922;23601.881990589936;23661.7633823663;23685.526289474114;7988.121306656263;7991.160928102582;8020.5588692621295;8171.06032806223;8221.301189398968;4186.665066794649;4211.642010429148;4283.421738065384;4293.592108749664;4316.115196384733;3552.662497062775;3632.891013269606;3651.9817400913357;3764;3933.547642021147;627.1533429869639;631.4695655649276;634.4195061518659;642.8167972127922;643.8486955565438;673.3233100733731;677.5155763629028;698.7309885693943;701.8631366883511;729.3982464466836;10052.69841904744;10514;10611.893881061245;10697.893021069845;10844.837327440095;674.6087269383746;675.6756756756686;693.227051837208;701.9964900175466;705.5167210460851;4175.874723758292;5082.440931497532;5209.6353255272525;5248.396434410015;5252.474752524793;87066;91552;102747;103567;104153.91692166115;89975.10024899799;93353;93576.53211734034;95159;96393;80052.5997370021;81682.18317816865;82557;82569.58715206386;82576;8286.46138001028;9177.265818734351;9874.079919792528;9946.502674866373;9966.352187107816;48345.03309933711;51422.22866656928;51527.484725152266;52124.34150636584;53085.20372194343;66318;68097;68170.97743533751;68881.65559172172;68960;11386.316820990767;11524.078073753912;11602.303861767983;11755;11946.163768536118;12941.54704585335;13016.544420945222;13134.146280491737;13137.474501019853;13801.51694690681;12159.57441489579;12183;12346.938265308616;12371.628851134481;12752.74494510123;11978.401079945794;11997.324679828858;12212.694682633006;12330.19853709506;12448.937755311166;12060.276383417024;12139.033701816914;12214.816777748163;12583.245005299712;12586.622401327608;7596.164421913474;8780.648774048967;9176.862347064933;9183.403078799856;9266.596667789216;8747.343949203963;10130.08829205375;10261.333013354108;10261.589536418758;10273.794524109326 14:49:22 INFO - PROCESS | 1643 | __end_tp_report 14:49:22 INFO - PROCESS | 1643 | __start_cc_report 14:49:22 INFO - PROCESS | 1643 | _x_x_mozilla_cycle_collect,379 14:49:22 INFO - PROCESS | 1643 | __end_cc_report 14:49:22 INFO - PROCESS | 1643 | __startTimestamp1496699362303__endTimestamp 14:49:22 INFO - PROCESS | 1643 | 14:49:22 INFO - PROCESS | 1643 | ------- Summary: start ------- 14:49:22 INFO - PROCESS | 1643 | Number of tests: 6 14:49:22 INFO - PROCESS | 1643 | 14:49:22 INFO - PROCESS | 1643 | [#0] dojo.html Cycles:165 Average:5866.08 Median:6124.42 stddev:673.30 (11.0%) stddev-sans-first:644.90 14:49:22 INFO - PROCESS | 1643 | Values: 3313.3 3401.7 3490.5 3507.0 3521.9 5306.3 5906.6 6031.7 6031.8 6064.9 5972.2 5988.0 6033.8 6039.8 6040.1 5339.4 5409.8 5424.3 5463.1 5492.3 4505.9 5465.5 5476.2 5489.6 5502.3 5266.7 5297.3 5344.5 5475.2 5547.6 5358.9 5374.5 5389.9 5430.1 5452.8 5302.2 5378.3 5408.3 5424.9 5430.2 5684.2 5932.9 5988.8 6069.7 6082.1 6283.0 6283.7 6286.7 6324.6 6326.7 5760.5 6180.2 6448.5 6464.8 6469.1 6140.6 6183.3 6197.8 6204.4 6218.7 5189.0 6237.3 6335.7 6431.5 6438.1 6274.4 6323.6 6324.7 6338.6 6344.0 6419.6 6442.5 6451.9 6455.2 6462.8 6188.1 6249.3 6257.6 6262.1 6315.5 6240.1 6318.1 6357.6 6375.3 6383.7 4742.4 4857.6 4901.0 4948.6 4949.2 4416.7 4431.9 4445.6 4469.8 4475.3 5766.1 5809.7 5830.1 5986.8 5992.5 5278.9 6237.3 6304.9 6319.0 6385.5 5947.1 5957.1 5965.6 6007.9 6025.2 5603.0 5605.6 5615.0 5618.1 5619.6 6333.3 6379.5 6478.9 6482.9 6591.6 5477.3 6447.0 6458.0 6471.6 6475.4 4960.0 6417.0 6434.7 6597.3 6627.8 5684.8 5747.7 6269.5 6275.3 6374.9 6479.2 6486.3 6511.0 6516.6 6534.8 6265.7 6279.6 6283.4 6301.5 6312.4 5219.9 6126.0 6176.2 6181.4 6234.9 6111.9 6124.4 6238.6 6247.2 6263.5 6266.0 6290.1 6298.3 6301.5 6419.1 5487.6 5991.3 6062.5 6161.6 6187.5 14:49:22 INFO - PROCESS | 1643 | 14:49:22 INFO - PROCESS | 1643 | [#1] ext.html Cycles:180 Average:49887.50 Median:29810.05 stddev:99505.31 (333.8%) stddev-sans-first:99715.94 14:49:22 INFO - PROCESS | 1643 | Values: 711.7 773.4 839.3 974.3 982.8 38305.4 39149.8 41128.0 41289.0 41299.6 40025.0 40128.0 40149.0 40188.8 40217.4 6864.6 8205.4 11046.0 11340.2 11526.2 11195.3 11267.8 11306.8 11315.2 11330.4 9857.8 11024.0 11304.0 11305.9 11345.4 11265.9 11291.4 11292.6 11314.5 11329.0 10904.3 10965.3 10968.9 10976.8 10982.5 35273.5 35446.8 35466.1 35503.8 35595.5 3358.9 3857.5 3893.3 3894.8 3925.7 24683.5 24917.8 26376.8 26570.3 27307.9 3062.7 3124.4 3158.2 3180.2 3297.7 270393.0 336954.0 359986.0 363462.0 392432.0 44873.8 45170.0 45227.5 45231.8 45237.8 46095.8 46169.8 46360.5 46390.1 46404.0 13873.0 13923.8 13936.6 13942.9 14026.3 12023.3 12156.5 12157.8 12198.6 12226.0 11934.6 11953.6 11989.3 12010.2 12069.0 5368.9 5427.5 5824.9 5866.1 5884.0 966.2 1053.8 1117.0 1180.2 1221.3 24705.4 29551.1 30592.0 31051.1 31389.5 1045.2 1051.2 1091.8 1100.4 1105.5 15222.7 15644.6 15729.8 15755.4 16048.2 229474.0 229661.0 230123.8 230930.0 230993.0 46555.0 46617.0 46647.0 46924.3 47023.5 1357.4 1415.7 1509.7 1512.3 1519.1 25306.5 27653.2 27917.4 29739.7 29954.6 47745.0 47999.3 48244.0 48298.3 48320.0 455220.0 504645.0 506314.0 507641.0 508580.0 31011.2 31589.1 32379.8 32434.2 32829.0 40007.0 40750.0 41404.6 41466.8 42204.8 23285.4 28297.9 29190.7 29788.1 29832.0 27940.9 29059.0 29726.9 29756.6 29891.0 28148.0 33793.8 34312.0 34656.0 34765.8 26659.9 27366.6 28933.6 29437.3 30112.1 17819.8 19649.0 22378.0 23747.9 23897.5 14:49:22 INFO - PROCESS | 1643 | 14:49:22 INFO - PROCESS | 1643 | [#2] jquery.html Cycles:180 Average:43380.74 Median:22552.83 stddev:119708.47 (530.8%) stddev-sans-first:120000.81 14:49:22 INFO - PROCESS | 1643 | Values: 414.2 426.7 430.8 438.2 441.9 20291.0 20909.4 22168.0 22366.4 22403.9 21605.9 21840.3 22493.9 22611.8 23299.2 6827.0 6894.2 7101.1 7144.4 7155.3 6853.1 6995.5 7051.2 7053.3 7066.5 6974.2 7045.0 7440.7 7808.6 7970.0 7695.9 7816.3 7910.3 7912.2 8021.6 6533.9 7697.0 7711.7 7713.4 7715.5 19032.0 20993.9 25819.5 26397.3 26695.0 2706.4 2860.7 2922.4 2993.2 3014.3 15696.5 15766.3 16395.0 16459.5 17052.7 2047.2 2087.6 2094.3 2109.5 2117.1 174325.0 181358.0 184723.0 184769.0 185775.0 21521.6 21657.9 21751.6 21783.2 21806.6 31430.4 31681.4 31925.5 32094.0 32144.7 8507.6 9618.7 9856.8 9872.4 10115.3 8145.3 8200.3 8236.8 8371.5 8388.7 7997.0 9603.2 9607.4 9763.3 9817.9 3640.7 4325.6 4380.6 4407.9 4417.9 878.1 894.2 897.6 929.5 942.8 20992.3 21499.6 21521.6 21537.9 21620.6 816.8 928.4 944.8 950.9 1001.8 10194.4 11021.9 11038.9 11071.9 11092.9 644855.0 648474.0 726916.0 730374.0 773637.0 170105.0 170978.0 171750.0 172626.3 178128.0 1189.9 1208.4 1234.4 1236.2 1243.2 18280.0 18567.4 18917.0 18971.4 19132.7 63249.1 66135.0 66354.7 67015.7 67083.3 72266.6 92743.5 94738.5 95257.0 98654.5 8975.8 11196.0 11302.0 11484.8 11704.8 12881.2 13003.0 13129.8 13208.6 13279.9 10914.9 11371.3 11479.2 11502.3 11596.9 10158.1 10312.6 10388.5 10509.6 10638.9 11159.9 11248.0 11403.0 11542.7 11620.0 11188.6 11233.1 11251.0 11275.5 11565.7 11164.4 11206.4 11265.9 11361.1 11378.5 14:49:22 INFO - PROCESS | 1643 | 14:49:22 INFO - PROCESS | 1643 | [#3] mootools.html Cycles:180 Average:6802.20 Median:2446.93 stddev:4248.69 (173.6%) stddev-sans-first:4241.92 14:49:22 INFO - PROCESS | 1643 | Values: 1498.2 1582.4 1606.7 1612.7 1621.1 9480.3 9666.8 10595.9 11202.3 11306.7 10750.7 10996.3 11011.8 11026.0 11049.6 2141.9 2200.6 2432.7 2472.2 2475.1 2417.5 2451.5 2452.8 2453.9 2457.0 2419.7 2420.7 2428.0 2438.2 2442.3 1877.4 2145.5 2392.9 2396.0 2404.2 2344.3 2359.4 2360.4 2366.0 2375.3 10826.2 10895.1 10918.2 10975.7 11019.0 5811.8 5823.7 5878.1 5903.2 5918.7 3628.9 3698.0 3703.7 3745.3 3771.1 1225.9 1227.1 1253.6 1273.8 1276.7 15501.8 15505.7 15506.3 15508.9 15535.0 4804.6 4937.3 4981.5 5025.4 5071.2 11290.6 12963.9 12980.2 13001.7 13027.5 5937.8 5974.0 6048.1 6066.5 6087.9 5168.8 5287.4 5354.1 5377.8 5416.9 3796.2 3923.3 3932.1 3952.6 3980.6 1051.8 1053.5 1066.5 1066.6 1067.6 800.5 940.3 943.5 954.6 969.0 6515.6 6632.6 6676.3 6776.8 6794.4 1008.1 1030.7 1052.1 1088.8 1109.8 4117.6 4234.3 4426.6 4430.6 4443.6 13197.5 13875.8 13894.2 13896.5 13905.4 13481.1 13575.5 13758.3 13913.4 13920.1 8771.7 10129.3 10141.6 10281.3 10373.7 6615.3 6649.9 6676.4 6774.8 6793.2 12106.9 12195.3 12252.0 12252.7 12387.8 12096.7 12321.9 12335.6 12760.4 12805.7 9022.0 9263.4 9447.1 9447.8 9451.2 10030.5 10302.0 10380.8 10381.8 10515.1 8827.3 8952.0 8964.8 9087.2 9089.4 8625.1 8709.4 8730.4 8781.3 8792.0 7912.2 8004.7 8077.1 8087.2 8104.3 5758.2 5811.4 5859.6 5984.4 6017.8 5210.8 5792.6 5859.4 5883.1 5987.0 14:49:22 INFO - PROCESS | 1643 | 14:49:22 INFO - PROCESS | 1643 | [#4] prototype.html Cycles:180 Average:4864.01 Median:5267.38 stddev:1320.70 (25.1%) stddev-sans-first:1280.17 14:49:22 INFO - PROCESS | 1643 | Values: 347.8 384.2 385.9 389.7 395.2 5022.7 5275.6 5427.9 5464.6 5521.7 4630.5 4765.5 5014.7 5384.4 5453.8 4067.0 4207.9 4265.5 4430.4 4500.9 4516.5 4520.7 4530.1 4553.5 4560.4 3861.4 4535.6 4546.3 4547.9 4574.4 3889.5 4033.7 4459.5 4476.8 4477.2 3457.5 4125.0 4132.7 4141.0 4145.5 4878.4 5037.0 5085.8 5322.7 5336.6 4502.5 4566.2 4862.3 5122.6 5434.4 5461.5 5865.9 5869.6 5901.0 5998.5 4472.5 4548.8 4795.1 5047.6 5069.9 6051.6 6106.3 6139.8 6211.9 6225.3 4492.8 5189.1 5257.4 5257.4 5311.2 4442.6 5351.3 5360.4 5364.9 5367.7 5250.2 5252.9 5436.4 5492.6 5546.8 5247.5 5491.6 5777.5 5886.5 5896.3 1577.6 1583.8 1658.5 1834.5 1842.3 1341.7 1392.4 1435.8 1479.7 1482.9 4706.5 5235.7 5249.7 5271.4 5287.4 5256.0 5417.5 5423.6 5457.7 6065.4 4510.0 5009.4 5142.9 5274.6 5327.1 2578.9 2787.0 2891.8 3085.8 3141.8 5413.9 6228.3 6236.3 6239.6 6245.3 4999.9 5301.5 5753.8 6128.7 6164.4 5265.7 5672.3 5883.6 5890.5 6201.5 5585.6 5862.5 5990.4 6074.9 6080.6 5146.8 5281.7 5518.7 5673.9 5971.5 5229.0 5559.0 5815.4 6008.4 6195.9 4978.7 5134.5 5198.6 5221.9 5242.6 5407.4 5424.6 5977.9 6025.4 6026.9 4737.7 5261.2 5264.3 5574.4 5826.9 5436.1 5522.9 5688.9 5741.5 5793.4 5151.1 5367.2 5485.4 5516.8 5684.9 4960.5 5384.5 5736.9 5835.4 5849.2 4855.8 5269.0 5417.9 5565.3 5579.4 14:49:22 INFO - PROCESS | 1643 | 14:49:22 INFO - PROCESS | 1643 | [#5] yui.html Cycles:180 Average:20730.44 Median:3747.45 stddev:30267.67 (807.7%) stddev-sans-first:30315.34 14:49:22 INFO - PROCESS | 1643 | Values: 734.4 741.5 781.8 840.8 858.6 15501.1 16132.6 16456.9 16639.8 16752.2 15167.9 15534.5 15541.0 15724.1 15730.5 929.9 969.5 970.8 972.2 974.1 830.4 898.7 964.0 964.5 977.6 886.0 1034.2 1034.2 1037.8 1039.8 1026.2 1027.7 1028.0 1028.4 1030.2 759.7 768.0 770.8 771.8 772.5 14871.0 15365.7 16852.2 16939.9 17667.5 12587.4 14003.0 14271.4 14356.0 14459.9 10322.2 11458.1 11765.4 11911.9 12099.3 3626.5 3739.7 3755.2 3759.2 3767.3 104291.9 112626.0 115200.8 115379.4 118948.2 10664.7 11044.6 11099.1 11306.9 11493.4 19549.8 23270.1 23601.9 23661.8 23685.5 7988.1 7991.2 8020.6 8171.1 8221.3 4186.7 4211.6 4283.4 4293.6 4316.1 3552.7 3632.9 3652.0 3764.0 3933.5 627.2 631.5 634.4 642.8 643.8 673.3 677.5 698.7 701.9 729.4 10052.7 10514.0 10611.9 10697.9 10844.8 674.6 675.7 693.2 702.0 705.5 4175.9 5082.4 5209.6 5248.4 5252.5 87066.0 91552.0 102747.0 103567.0 104153.9 89975.1 93353.0 93576.5 95159.0 96393.0 80052.6 81682.2 82557.0 82569.6 82576.0 8286.5 9177.3 9874.1 9946.5 9966.4 48345.0 51422.2 51527.5 52124.3 53085.2 66318.0 68097.0 68171.0 68881.7 68960.0 11386.3 11524.1 11602.3 11755.0 11946.2 12941.5 13016.5 13134.1 13137.5 13801.5 12159.6 12183.0 12346.9 12371.6 12752.7 11978.4 11997.3 12212.7 12330.2 12448.9 12060.3 12139.0 12214.8 12583.2 12586.6 7596.2 8780.6 9176.9 9183.4 9266.6 8747.3 10130.1 10261.3 10261.6 10273.8 14:49:22 INFO - PROCESS | 1643 | -------- Summary: end -------- 14:49:22 INFO - PROCESS | 1643 | 14:49:22 INFO - TEST-INFO | 1643: exit 0 14:49:22 INFO - TEST-OK | dromaeo_css | took 1115280ms 14:49:22 INFO - TEST-START | kraken 14:49:22 INFO - operating with platform_type : mac_ 14:49:22 INFO - Initialising browser for kraken test... 14:49:22 INFO - TEST-INFO | started process 1655 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/tmp6e3wtP/profile http://localhost:49224/getInfo.html) 14:49:25 INFO - PROCESS | 1655 | 2017-06-05 14:49:25.467 plugin-container[1657:10211] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8643, name = 'com.apple.tsm.portname' 14:49:25 INFO - PROCESS | 1655 | See /usr/include/servers/bootstrap_defs.h for the error codes. 14:49:25 INFO - PROCESS | 1655 | 2017-06-05 14:49:25.468 plugin-container[1657:10211] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x890b, name = 'com.apple.CFPasteboardClient' 14:49:25 INFO - PROCESS | 1655 | See /usr/include/servers/bootstrap_defs.h for the error codes. 14:49:25 INFO - PROCESS | 1655 | 2017-06-05 14:49:25.468 plugin-container[1657:10211] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 14:49:25 INFO - PROCESS | 1655 | __metrics Screen width/height:1600/1200 14:49:25 INFO - PROCESS | 1655 | colorDepth:24 14:49:25 INFO - PROCESS | 1655 | Browser inner width/height: 1024/689 14:49:25 INFO - PROCESS | 1655 | __metrics 14:49:25 ERROR - PROCESS | 1655 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3381: TypeError: this.tabs is undefined 14:49:26 INFO - TEST-INFO | 1655: exit 0 14:49:26 INFO - Browser initialized. 14:49:26 INFO - Running cycle 1/1 for kraken test... 14:49:26 INFO - TEST-INFO | started process 1658 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/tmp6e3wtP/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 14:49:28 INFO - PROCESS | 1658 | 2017-06-05 14:49:28.656 plugin-container[1659:10332] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4647, name = 'com.apple.tsm.portname' 14:49:28 INFO - PROCESS | 1658 | See /usr/include/servers/bootstrap_defs.h for the error codes. 14:49:28 INFO - PROCESS | 1658 | 2017-06-05 14:49:28.656 plugin-container[1659:10332] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8c0b, name = 'com.apple.CFPasteboardClient' 14:49:28 INFO - PROCESS | 1658 | See /usr/include/servers/bootstrap_defs.h for the error codes. 14:49:28 INFO - PROCESS | 1658 | 2017-06-05 14:49:28.656 plugin-container[1659:10332] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 14:49:38 INFO - PROCESS | 1658 | RSS: Main: 203153408 14:49:38 INFO - PROCESS | 1658 | 14:49:41 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49224/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49224/tests/kraken/driver.html?testName=audio-beat-detection) 14:49:42 INFO - PROCESS | 1658 | RSS: Main: 216539136 14:49:42 INFO - PROCESS | 1658 | 14:49:44 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49224/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49224/tests/kraken/driver.html?testName=audio-dft) 14:49:45 INFO - PROCESS | 1658 | RSS: Main: 224428032 14:49:45 INFO - PROCESS | 1658 | 14:49:49 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49224/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49224/tests/kraken/driver.html?testName=audio-fft) 14:49:49 INFO - PROCESS | 1658 | RSS: Main: 225902592 14:49:49 INFO - PROCESS | 1658 | 14:49:51 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49224/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49224/tests/kraken/driver.html?testName=audio-oscillator) 14:49:52 INFO - PROCESS | 1658 | RSS: Main: 227717120 14:49:52 INFO - PROCESS | 1658 | 14:49:54 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49224/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49224/tests/kraken/driver.html?testName=imaging-gaussian-blur) 14:49:54 INFO - PROCESS | 1658 | RSS: Main: 229347328 14:49:54 INFO - PROCESS | 1658 | 14:49:57 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49224/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49224/tests/kraken/driver.html?testName=imaging-darkroom) 14:49:58 INFO - PROCESS | 1658 | RSS: Main: 233943040 14:49:58 INFO - PROCESS | 1658 | 14:50:01 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49224/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49224/tests/kraken/driver.html?testName=imaging-desaturate) 14:50:02 INFO - PROCESS | 1658 | RSS: Main: 232919040 14:50:02 INFO - PROCESS | 1658 | 14:50:04 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49224/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49224/tests/kraken/driver.html?testName=json-parse-financial) 14:50:05 INFO - PROCESS | 1658 | RSS: Main: 233013248 14:50:05 INFO - PROCESS | 1658 | 14:50:07 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49224/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49224/tests/kraken/driver.html?testName=json-stringify-tinderbox) 14:50:07 INFO - PROCESS | 1658 | RSS: Main: 232550400 14:50:07 INFO - PROCESS | 1658 | 14:50:09 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49224/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49224/tests/kraken/driver.html?testName=stanford-crypto-aes) 14:50:10 INFO - PROCESS | 1658 | RSS: Main: 232587264 14:50:10 INFO - PROCESS | 1658 | 14:50:12 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49224/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49224/tests/kraken/driver.html?testName=stanford-crypto-ccm) 14:50:13 INFO - PROCESS | 1658 | RSS: Main: 233132032 14:50:13 INFO - PROCESS | 1658 | 14:50:16 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49224/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49224/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 14:50:16 INFO - PROCESS | 1658 | RSS: Main: 233312256 14:50:16 INFO - PROCESS | 1658 | 14:50:19 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49224/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49224/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 14:50:20 INFO - PROCESS | 1658 | RSS: Main: 233074688 14:50:20 INFO - PROCESS | 1658 | 14:50:22 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49224/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49224/tests/kraken/driver.html?testName=ai-astar) 14:50:22 INFO - PROCESS | 1658 | RSS: Main: 235229184 14:50:22 INFO - PROCESS | 1658 | 14:50:22 INFO - PROCESS | 1658 | __start_tp_report 14:50:22 INFO - PROCESS | 1658 | _x_x_mozilla_page_load 14:50:22 INFO - PROCESS | 1658 | _x_x_mozilla_page_load_details 14:50:22 INFO - PROCESS | 1658 | |i|pagename|runs| 14:50:22 INFO - PROCESS | 1658 | |0;ai-astar;107;118;119;117;119;119;118;118;119;119 14:50:22 INFO - PROCESS | 1658 | |1;audio-beat-detection;141;110;110;168;139;114;113;119;161;93 14:50:22 INFO - PROCESS | 1658 | |2;audio-dft;203;196;198;198;197;287;275;268;284;201 14:50:22 INFO - PROCESS | 1658 | |3;audio-fft;86;61;62;87;63;61;60;61;74;60 14:50:22 INFO - PROCESS | 1658 | |4;audio-oscillator;89;71;84;72;71;70;109;101;102;97 14:50:22 INFO - PROCESS | 1658 | |5;imaging-gaussian-blur;88;88;92;87;88;89;89;87;88;109 14:50:22 INFO - PROCESS | 1658 | |6;imaging-darkroom;96;95;96;95;96;96;96;97;96;95 14:50:22 INFO - PROCESS | 1658 | |7;imaging-desaturate;90;95;89;91;92;89;104;89;91;78 14:50:22 INFO - PROCESS | 1658 | |8;json-parse-financial;67;64;65;68;70;68;71;73;68;70 14:50:22 INFO - PROCESS | 1658 | |9;json-stringify-tinderbox;57;64;58;69;62;60;57;58;59;58 14:50:22 INFO - PROCESS | 1658 | |10;stanford-crypto-aes;86;83;85;85;87;84;101;106;103;103 14:50:22 INFO - PROCESS | 1658 | |11;stanford-crypto-ccm;137;142;146;146;147;148;142;147;198;159 14:50:22 INFO - PROCESS | 1658 | |12;stanford-crypto-pbkdf2;214;189;165;215;167;151;189;220;189;167 14:50:22 INFO - PROCESS | 1658 | |13;stanford-crypto-sha256-iterative;66;65;65;65;66;67;63;65;64;66 14:50:22 INFO - PROCESS | 1658 | __end_tp_report 14:50:22 INFO - PROCESS | 1658 | __start_cc_report 14:50:22 INFO - PROCESS | 1658 | _x_x_mozilla_cycle_collect,984 14:50:22 INFO - PROCESS | 1658 | __end_cc_report 14:50:22 INFO - PROCESS | 1658 | __startTimestamp1496699422437__endTimestamp 14:50:22 INFO - PROCESS | 1658 | 14:50:22 INFO - PROCESS | 1658 | ------- Summary: start ------- 14:50:22 INFO - PROCESS | 1658 | Number of tests: 14 14:50:22 INFO - PROCESS | 1658 | 14:50:22 INFO - PROCESS | 1658 | [#0] ai-astar Cycles:10 Average:117.30 Median:119.00 stddev:3.68 (3.1%) stddev-sans-first:0.73 14:50:22 INFO - PROCESS | 1658 | Values: 107.0 118.0 119.0 117.0 119.0 119.0 118.0 118.0 119.0 119.0 14:50:22 INFO - PROCESS | 1658 | 14:50:22 INFO - PROCESS | 1658 | [#1] audio-beat-detection Cycles:10 Average:126.80 Median:140.00 stddev:24.36 (17.4%) stddev-sans-first:25.29 14:50:22 INFO - PROCESS | 1658 | Values: 141.0 110.0 110.0 168.0 139.0 114.0 113.0 119.0 161.0 93.0 14:50:22 INFO - PROCESS | 1658 | 14:50:22 INFO - PROCESS | 1658 | [#2] audio-dft Cycles:10 Average:230.70 Median:235.50 stddev:41.49 (17.6%) stddev-sans-first:42.78 14:50:22 INFO - PROCESS | 1658 | Values: 203.0 196.0 198.0 198.0 197.0 287.0 275.0 268.0 284.0 201.0 14:50:22 INFO - PROCESS | 1658 | 14:50:22 INFO - PROCESS | 1658 | [#3] audio-fft Cycles:10 Average:67.50 Median:62.50 stddev:10.82 (17.3%) stddev-sans-first:9.18 14:50:22 INFO - PROCESS | 1658 | Values: 86.0 61.0 62.0 87.0 63.0 61.0 60.0 61.0 74.0 60.0 14:50:22 INFO - PROCESS | 1658 | 14:50:22 INFO - PROCESS | 1658 | [#4] audio-oscillator Cycles:10 Average:86.60 Median:71.50 stddev:15.06 (21.1%) stddev-sans-first:15.95 14:50:22 INFO - PROCESS | 1658 | Values: 89.0 71.0 84.0 72.0 71.0 70.0 109.0 101.0 102.0 97.0 14:50:22 INFO - PROCESS | 1658 | 14:50:22 INFO - PROCESS | 1658 | [#5] imaging-gaussian-blur Cycles:10 Average:90.50 Median:88.00 stddev:6.65 (7.6%) stddev-sans-first:7.00 14:50:22 INFO - PROCESS | 1658 | Values: 88.0 88.0 92.0 87.0 88.0 89.0 89.0 87.0 88.0 109.0 14:50:22 INFO - PROCESS | 1658 | 14:50:22 INFO - PROCESS | 1658 | [#6] imaging-darkroom Cycles:10 Average:95.80 Median:96.00 stddev:0.63 (0.7%) stddev-sans-first:0.67 14:50:22 INFO - PROCESS | 1658 | Values: 96.0 95.0 96.0 95.0 96.0 96.0 96.0 97.0 96.0 95.0 14:50:22 INFO - PROCESS | 1658 | 14:50:22 INFO - PROCESS | 1658 | [#7] imaging-desaturate Cycles:10 Average:90.80 Median:90.50 stddev:6.39 (7.1%) stddev-sans-first:6.77 14:50:22 INFO - PROCESS | 1658 | Values: 90.0 95.0 89.0 91.0 92.0 89.0 104.0 89.0 91.0 78.0 14:50:22 INFO - PROCESS | 1658 | 14:50:22 INFO - PROCESS | 1658 | [#8] json-parse-financial Cycles:10 Average:68.40 Median:69.00 stddev:2.72 (3.9%) stddev-sans-first:2.83 14:50:22 INFO - PROCESS | 1658 | Values: 67.0 64.0 65.0 68.0 70.0 68.0 71.0 73.0 68.0 70.0 14:50:22 INFO - PROCESS | 1658 | 14:50:22 INFO - PROCESS | 1658 | [#9] json-stringify-tinderbox Cycles:10 Average:60.20 Median:59.50 stddev:3.82 (6.4%) stddev-sans-first:3.88 14:50:22 INFO - PROCESS | 1658 | Values: 57.0 64.0 58.0 69.0 62.0 60.0 57.0 58.0 59.0 58.0 14:50:22 INFO - PROCESS | 1658 | 14:50:22 INFO - PROCESS | 1658 | [#10] stanford-crypto-aes Cycles:10 Average:92.30 Median:84.50 stddev:9.56 (11.3%) stddev-sans-first:9.86 14:50:22 INFO - PROCESS | 1658 | Values: 86.0 83.0 85.0 85.0 87.0 84.0 101.0 106.0 103.0 103.0 14:50:22 INFO - PROCESS | 1658 | 14:50:22 INFO - PROCESS | 1658 | [#11] stanford-crypto-ccm Cycles:10 Average:151.20 Median:147.00 stddev:17.39 (11.8%) stddev-sans-first:17.67 14:50:22 INFO - PROCESS | 1658 | Values: 137.0 142.0 146.0 146.0 147.0 148.0 142.0 147.0 198.0 159.0 14:50:22 INFO - PROCESS | 1658 | 14:50:22 INFO - PROCESS | 1658 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:186.60 Median:189.00 stddev:24.02 (12.7%) stddev-sans-first:23.34 14:50:22 INFO - PROCESS | 1658 | Values: 214.0 189.0 165.0 215.0 167.0 151.0 189.0 220.0 189.0 167.0 14:50:22 INFO - PROCESS | 1658 | 14:50:22 INFO - PROCESS | 1658 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.20 Median:65.50 stddev:1.14 (1.7%) stddev-sans-first:1.17 14:50:22 INFO - PROCESS | 1658 | Values: 66.0 65.0 65.0 65.0 66.0 67.0 63.0 65.0 64.0 66.0 14:50:22 INFO - PROCESS | 1658 | -------- Summary: end -------- 14:50:22 INFO - PROCESS | 1658 | 14:50:23 INFO - TEST-INFO | 1658: exit 0 14:50:23 INFO - TEST-OK | kraken | took 60396ms 14:50:23 INFO - SUITE-END | took 1175s 14:50:23 INFO - Completed test suite (00:19:36) 14:50:23 INFO - javascript benchmark 14:50:23 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7775.536222709658, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3313.2876431567215, 3401.710854577361, 3490.476428535718, 3506.9654451936676, 3521.859125634973, 5306.283651707024, 5906.616069955455, 6031.668258245806, 6031.849203769908, 6064.909026364597, 5972.19375384323, 5988.0, 6033.818985430433, 6039.788607398731, 6040.063790112539, 5339.385970613381, 5409.783608655649, 5424.294841670597, 5463.13903404355, 5492.340919089715, 4505.887352816189, 5465.480779325958, 5476.205950137228, 5489.560835133199, 5502.312210973629, 5266.710330931797, 5297.311349524556, 5344.4922732340365, 5475.206095116206, 5547.583931205156, 5358.892822143535, 5374.48942350476, 5389.865253368658, 5430.131179011379, 5452.754626041798, 5302.204669299597, 5378.273933019032, 5408.323959505062, 5424.945750542483, 5430.15832545956, 5684.204211410406, 5932.851678708023, 5988.790392336291, 6069.696515174268, 6082.1180928765525, 6283.02613094971, 6283.748650053993, 6286.685665716695, 6324.588901721373, 6326.746930122789, 5760.452756988079, 6180.196574445338, 6448.45188159007, 6464.838379040561, 6469.094326794253, 6140.600860944024, 6183.258009038854, 6197.783077592263, 6204.441600255998, 6218.689065546704, 5188.9740551297755, 6237.251529816451, 6335.68321583919, 6431.485481161496, 6438.130852334875, 6274.435300822948, 6323.557350985387, 6324.6521441321165, 6338.619682819046, 6344.0, 6419.582727122722, 6442.549021568445, 6451.870962580816, 6455.225372955276, 6462.838429039311, 6188.1027251048345, 6249.312575616678, 6257.624542527463, 6262.091996660459, 6315.494760419156, 6240.126382306481, 6318.052292156213, 6357.554971151975, 6375.2987171411105, 6383.744650213939, 4742.407199100113, 4857.562819346276, 4901.0, 4948.628852836052, 4949.183384741549, 4416.66874984371, 4431.911361772746, 4445.59989600931, 4469.776511174428, 4475.26158183896, 5766.106253530709, 5809.651420914759, 5830.067189249785, 5986.790462333797, 5992.490638295706, 5278.868028299246, 6237.313895471494, 6304.936950630527, 6318.968405157945, 6385.5210859185745, 5947.1079338099635, 5957.136215248851, 5965.55258355625, 6007.858506883678, 6025.15647809307, 5603.047481928082, 5605.579581531401, 5615.0, 5618.073017951992, 5619.578531610146, 6333.271673757485, 6379.489640828815, 6478.902816457851, 6482.935170648422, 6591.571547849375, 5477.315335583052, 6447.0329450582785, 6457.966725323926, 6471.611703297817, 6475.352464753487, 4959.950400496094, 6417.005364168562, 6434.703751501072, 6597.340265973365, 6627.834304142242, 5684.8010319638615, 5747.683877386784, 6269.467095296951, 6275.278342990615, 6374.936250637435, 6479.222493300834, 6486.286508483969, 6511.023346498063, 6516.57642253252, 6534.803955881332, 6265.655388953652, 6279.623222606659, 6283.37166283368, 6301.495880329471, 6312.400321969406, 5219.895602088013, 6125.998614523495, 6176.197094377702, 6181.443670069715, 6234.913717709582, 6111.926012480351, 6124.418180272867, 6238.563300568917, 6247.156633854372, 6263.467605253604, 6265.968670156803, 6290.144407399919, 6298.338674439118, 6301.527385446018, 6419.133416988647, 5487.5609951203005, 5991.281046274475, 6062.545309101747, 6161.630302181883, 6187.504999600112], "value": 5824.836988647941, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [711.697528550366, 773.4353921637205, 839.2907992746132, 974.3179774158091, 982.7788747531811, 38305.425418618746, 39149.80425097874, 41127.971800704996, 41289.0, 41299.587004129986, 40025.0, 40128.0, 40149.0, 40188.794336169885, 40217.396739048934, 6864.553804002736, 8205.425620206559, 11046.005859472645, 11340.206185567014, 11526.19316647835, 11195.27230730004, 11267.774644507104, 11306.773864522705, 11315.207935444521, 11330.376829274386, 9857.802843943153, 11023.9527244912, 11304.0, 11305.943470282678, 11345.43272836359, 11265.887341126565, 11291.378974156418, 11292.604758833442, 11314.547418103266, 11329.0, 10904.291221070605, 10965.342079475176, 10968.890311096866, 10976.835347469794, 10982.450877456095, 35273.47089793655, 35446.82276588627, 35466.11334716627, 35503.82248088743, 35595.46606800874, 3358.916027099342, 3857.498525191735, 3893.27974324748, 3894.844206231747, 3925.7055720820767, 24683.5063298733, 24917.750822491726, 26376.81304341309, 26570.33574160642, 27307.86346068277, 3062.6784187660196, 3124.375124974991, 3158.210447388153, 3180.2367431816197, 3297.73618110553, 270393.0, 336954.0, 359986.0, 363462.0, 392431.99999999715, 44873.77563112229, 45170.0, 45227.54772452233, 45231.77384113058, 45237.77381113073, 46095.76952115218, 46169.76915115403, 46360.5363946363, 46390.07219855584, 46404.0, 13873.0, 13923.791143132861, 13936.58190254294, 13942.930285348508, 14026.29868506591, 12023.27860328383, 12156.452959616838, 12157.756844863054, 12198.57304994321, 12225.960793332666, 11934.641960741192, 11953.581624643095, 11989.280643161439, 12010.219335743148, 12068.974137198436, 5368.872536767322, 5427.511523962862, 5824.912626310609, 5866.090755932837, 5884.0, 966.2318456826839, 1053.8355117594947, 1116.972075698114, 1180.1620849196995, 1221.2977537915733, 24705.382365441008, 29551.113466596038, 30592.0, 31051.068467946, 31389.529157062207, 1045.1951996962296, 1051.153821173947, 1091.792559413709, 1100.4442756408123, 1105.5246244115108, 15222.695546089017, 15644.608884777745, 15729.842701573067, 15755.369785208693, 16048.19759012068, 229474.0, 229661.0, 230123.849380752, 230930.0, 230993.0, 46555.0, 46617.0, 46647.0, 46924.296135558, 47023.52976470192, 1357.4434481862388, 1415.6531649745878, 1509.6603264265673, 1512.3345727879919, 1519.118911031599, 25306.493870122864, 27653.234676532185, 27917.441651166457, 29739.71377012646, 29954.550681740227, 47745.0, 47999.280010799164, 48244.0, 48298.27552586644, 48320.0, 455220.0, 504645.0, 506314.0, 507641.0, 508580.0, 31011.224719382197, 31589.052328430185, 32379.838100809346, 32434.189145271557, 32829.0, 40007.0, 40750.0, 41404.58595414007, 41466.792666036476, 42204.78897605492, 23285.41786455352, 28297.858510707316, 29190.708092918798, 29788.10635680933, 29832.0, 27940.882364705183, 29059.0, 29726.851365743034, 29756.553651695674, 29891.0, 28148.0, 33793.83103084469, 34312.0, 34656.0, 34765.82617086898, 26659.86670066637, 27366.589501157898, 28933.56599650965, 29437.26406839846, 30112.096637100924, 17819.821801781814, 19649.01754912277, 22378.0, 23747.931343089258, 23897.52204955926], "value": 18231.73923971485, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [414.19645886979276, 426.6991770801585, 430.7524003946122, 438.1915366149455, 441.8718571614232, 20291.0, 20909.372718818442, 22168.0, 22366.440838979033, 22403.887980560095, 21605.919704014777, 21840.344789656338, 22493.887530562366, 22611.773882261172, 23299.184528541464, 6827.0, 6894.172699276081, 7101.076860008217, 7144.428445724356, 7155.284471552829, 6853.143357080365, 6995.475339349569, 7051.224365319837, 7053.329933656294, 7066.540674856144, 6974.197967233782, 7044.964775176142, 7440.702371905118, 7808.570528620923, 7970.043594768636, 7695.923040769577, 7816.257455541714, 7910.288074073333, 7912.248336408032, 8021.639026243833, 6533.901991470132, 7696.9615151924445, 7711.652975616054, 7713.421493387962, 7715.537067775951, 19032.0, 20993.89503052475, 25819.483610327876, 26397.34006649849, 26695.0, 2706.418120104187, 2860.713928607123, 2922.4301261254063, 2993.176876358997, 3014.3368458939, 15696.450624228097, 15766.290516926765, 16395.016299021976, 16459.506214813573, 17052.658946820993, 2047.1811275489774, 2087.6137914485867, 2094.2565389286738, 2109.504266497372, 2117.0890026213956, 174325.0, 181358.0, 184723.0, 184769.0, 185775.0, 21521.569568608542, 21657.91710414473, 21751.564968700855, 21783.23758668439, 21806.563868722536, 31430.371392572022, 31681.36637267242, 31925.52111718326, 32094.0, 32144.678553214635, 8507.617157227816, 9618.663346782969, 9856.767904011998, 9872.407655540546, 10115.291929565006, 8145.307648849795, 8200.26197642215, 8236.794080147924, 8371.497710137412, 8388.706395276136, 7997.0, 9603.231741460666, 9607.375520591138, 9763.316567840184, 9817.852732209023, 3640.7087433005295, 4325.589069038437, 4380.627646650007, 4407.911841763147, 4417.885390799543, 878.1043335797451, 894.2488309819782, 897.5646811296516, 929.5445231836487, 942.7643089227693, 20992.265270715452, 21499.57000859974, 21521.569568608542, 21537.892310538347, 21620.56758864814, 816.8325493273865, 928.4150984879483, 944.7779771753633, 950.8763860698065, 1001.7545701303081, 10194.43930583825, 11021.944890275498, 11038.944805275922, 11071.944640276746, 11092.946170113826, 644855.0, 648474.0, 726916.0, 730374.0, 773637.0, 170105.0, 170978.0, 171750.0, 172626.27373726355, 178128.0, 1189.8691143974156, 1208.3958020989505, 1234.4383305595932, 1236.1717649175075, 1243.185713357752, 18279.994600297112, 18567.350142744937, 18916.959567223385, 18971.43085707431, 19132.71300930515, 63249.051264231995, 66135.0, 66354.66822665856, 67015.66492167508, 67083.32916670771, 72266.63866680634, 92743.53628232086, 94738.52630736803, 95257.04742952481, 98654.50672746591, 8975.820483590422, 11195.989734679486, 11302.0, 11484.792488638133, 11704.824427633423, 12881.162724422882, 13002.95976321873, 13129.803052954023, 13208.603741887759, 13279.93760499177, 10914.945425273141, 11371.260868043551, 11479.196456247982, 11502.252353597325, 11596.884031159918, 10158.13655839262, 10312.639057633247, 10388.532516036948, 10509.579616815241, 10638.89361106379, 11159.944200279271, 11248.0, 11403.0, 11542.653720388402, 11620.0, 11188.595531326462, 11233.101351891992, 11251.043661288883, 11275.492602833056, 11565.653030409101, 11164.44177791091, 11206.439678015904, 11265.943670281596, 11361.147913906656, 11378.54485820558], "value": 11757.194244582844, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1498.176003198241, 1582.351235993243, 1606.7429211326182, 1612.7419612861945, 1621.0759866875883, 9480.288978326618, 9666.839979202488, 10595.947020264897, 11202.271852329593, 11306.660800175998, 10750.731231719212, 10996.2852414593, 11011.83482247767, 11026.0, 11049.613263535777, 2141.9464513387134, 2200.6478963365867, 2432.722820519228, 2472.159465781634, 2475.10896077412, 2417.492326611414, 2451.5464639041834, 2452.828302018859, 2453.865037422941, 2456.9754302457013, 2419.733829278778, 2420.7095148582193, 2427.963580546293, 2438.17102185257, 2442.3039433761382, 1877.399232245682, 2145.463634091485, 2392.928212153638, 2396.0296080087505, 2404.1825779235146, 2344.34358379654, 2359.433735903388, 2360.44529535559, 2366.0417530900013, 2375.263668262844, 10826.188035897261, 10895.073918716918, 10918.181136414722, 10975.725606859813, 11019.0, 5811.82564523065, 5823.67969761663, 5878.147668588073, 5903.203067585907, 5918.733656985403, 3628.872989445357, 3698.0385099873956, 3703.7222208334483, 3745.3445647011936, 3771.057235691077, 1225.9448324825314, 1227.061298106949, 1253.5612535612554, 1273.808928660699, 1276.6744480157593, 15501.767473487795, 15505.689886202326, 15506.302216400289, 15508.92245538765, 15535.0, 4804.615630749532, 4937.308776771255, 4981.501849815062, 5025.422076461181, 5071.18860982241, 11290.604828830952, 12963.870361296455, 12980.221186728826, 13001.674958126123, 13027.47890084405, 5937.7921772737745, 5973.970130149321, 6048.092786082123, 6066.514678825683, 6087.878242435127, 5168.8449346519665, 5287.391949925731, 5354.063038968165, 5377.78488860449, 5416.864578385493, 3796.1648437343742, 3923.3330333843314, 3932.075962148893, 3952.6442620164325, 3980.5820388859333, 1051.784384201237, 1053.4943227250963, 1066.5200659703164, 1066.6213494209444, 1067.6263307842194, 800.491687778265, 940.3088729783603, 943.5046600534637, 954.61815273891, 968.9680490277832, 6515.641639709766, 6632.62270699058, 6676.26561078281, 6776.7628133015105, 6794.388505034571, 1008.1279693065445, 1030.6804890483975, 1052.1109662335314, 1088.77135801481, 1109.8335249712607, 4117.649999750055, 4234.25900467417, 4426.645868330525, 4430.556944305544, 4443.622292105208, 13197.538086166938, 13875.791863122062, 13894.235817029958, 13896.51362202318, 13905.374258158407, 13481.123726957716, 13575.52485662997, 13758.31208439574, 13913.373898174606, 13920.095193812369, 8771.736847894574, 10129.290949633454, 10141.645042423479, 10281.331713438753, 10373.688789336333, 6615.305392933673, 6649.867002660017, 6676.432503237279, 6774.830629234309, 6793.218779840382, 12106.878931210575, 12195.329256890602, 12252.0, 12252.693682658004, 12387.75224495523, 12096.697582560155, 12321.876781232431, 12335.629931102081, 12760.42578084007, 12805.679858003625, 9022.0, 9263.44419334815, 9447.102525260088, 9447.81104377895, 9451.196648284973, 10030.548625312025, 10302.02130797573, 10380.792384152426, 10381.844272336071, 10515.05364517197, 8827.344469418733, 8951.955240223757, 8964.820703586021, 9087.227585655293, 9089.409188402733, 8625.137486251324, 8709.433886797338, 8730.388872778845, 8781.25359344463, 8792.0, 7912.248336408032, 8004.679812807422, 8077.111517733162, 8087.231712987257, 8104.27061564462, 5758.193852860519, 5811.418858114154, 5859.648421094748, 5984.431479009487, 6017.819465416044, 5210.817621383233, 5792.623479473821, 5859.355470898113, 5883.146943693226, 5986.970065149646], "value": 5194.930947705378, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [347.8034910275694, 384.2008622066103, 385.92860320840646, 389.6746216908881, 395.2135250850806, 5022.698638081713, 5275.55157811586, 5427.9185812212845, 5464.644798088121, 5521.69630670314, 4630.522670371343, 4765.523447655232, 5014.674046187004, 5384.40771515133, 5453.754581043852, 4066.9796651016704, 4207.936880946788, 4265.530791612928, 4430.446194225722, 4500.907078208632, 4516.54834516549, 4520.728756274618, 4530.071335376257, 4553.4535855697195, 4560.361549383072, 3861.362875125601, 4535.637149028086, 4546.295324224734, 4547.886302842422, 4574.428196475441, 3889.4749208857006, 4033.657139143176, 4459.531749166355, 4476.7761611919595, 4477.171723231213, 3457.533233013536, 4125.0, 4132.690048246393, 4140.991619064751, 4145.523264824554, 4878.365812444395, 5037.0, 5085.770535709814, 5322.680639161663, 5336.599755018389, 4502.482214545336, 4566.1552612766745, 4862.298195631501, 5122.591287395959, 5434.429384914606, 5461.4811592899005, 5865.941340586583, 5869.647821130746, 5901.0, 5998.490128339096, 4472.5303843096335, 4548.772561371951, 4795.088933102734, 5047.570956468668, 5069.8732531687, 6051.576389652726, 6106.328303886613, 6139.846503837395, 6211.919007132616, 6225.28409232935, 4492.797824097924, 5189.143791274473, 5257.421683614799, 5257.423862307518, 5311.2033195020285, 4442.644588432918, 5351.25082488452, 5360.410354860962, 5364.892702145935, 5367.651102678314, 5250.238715386247, 5252.868678282997, 5436.439132368305, 5492.615516913777, 5546.750396232179, 5247.527722504993, 5491.615586908958, 5777.493555027002, 5886.470217680429, 5896.262967129109, 1577.6371434570112, 1583.8064280970939, 1658.477579562434, 1834.5046837353839, 1842.2630947620792, 1341.684704094537, 1392.3873495661876, 1435.7630990886387, 1479.681868398278, 1482.8507906372647, 4706.482286948433, 5235.712035838065, 5249.658772179796, 5271.42014378418, 5287.4448182941005, 5255.975084858416, 5417.458254174551, 5423.566114710814, 5457.727113644302, 6065.363136870653, 4510.0, 5009.44896061433, 5142.8971420572125, 5274.630775845731, 5327.094393953037, 2578.948421031569, 2786.972130278712, 2891.773334532398, 3085.8302793346584, 3141.7643676724338, 5413.945860541344, 6228.314885362606, 6236.282827474808, 6239.594426362272, 6245.313015568284, 4999.900001999939, 5301.54936830366, 5753.798617048383, 6128.724207410677, 6164.445199932027, 5265.736713164327, 5672.347680016768, 5883.61756485827, 5890.4993075589055, 6201.534884883562, 5585.609007369608, 5862.472377486047, 5990.430909063632, 6074.90887636677, 6080.604760690541, 5146.819861304836, 5281.728856591702, 5518.696471694096, 5673.921725239584, 5971.492423144082, 5229.006488767122, 5559.027170245272, 5815.3893841147765, 6008.369121242206, 6195.876082478415, 4978.655762943912, 5134.486551344836, 5198.558122559473, 5221.9216711748595, 5242.606804489602, 5407.405185429679, 5424.620276580601, 5977.850553736192, 6025.427584379477, 6026.939730602638, 4737.692050016738, 5261.158594492051, 5264.2630031794815, 5574.414686457863, 5826.883462330815, 5436.058857328405, 5522.889542209214, 5688.899549745316, 5741.483266506035, 5793.364019371092, 5151.101988223538, 5367.248585198154, 5485.434467596095, 5516.834494965157, 5684.857878553069, 4960.5287497689105, 5384.488473595002, 5736.856578585569, 5835.416458354131, 5849.151872978309, 4855.781489832895, 5269.0252303324005, 5417.864553386197, 5565.30433695788, 5579.442055794388], "value": 4483.01582621742, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [734.4234775701073, 741.5402450480702, 781.7967328494593, 840.8192238668686, 858.609332753597, 15501.06993580386, 16132.596685082879, 16456.917715411437, 16639.83360166398, 16752.246148923296, 15167.924160379183, 15534.456294029711, 15541.0, 15724.135172565504, 15730.528084157466, 929.8884133903924, 969.5297780576415, 970.7864269860619, 972.19793670222, 974.1330216107634, 830.3689196210893, 898.6809682562695, 964.045594861086, 964.4550828781751, 977.5698692575244, 885.9911400885973, 1034.1933292032227, 1034.2191645307782, 1037.761314897574, 1039.833626619741, 1026.199564339816, 1027.6660085472215, 1028.033648370533, 1028.4497793680346, 1030.1861529391772, 759.7151068349369, 768.0, 770.7803276066351, 771.7607541662103, 772.4940164192462, 14871.0, 15365.693916017151, 16852.157392130466, 16939.91530042354, 17667.469975900614, 12587.37063146839, 14003.019788614802, 14271.35778889942, 14356.0, 14459.855401445957, 10322.22583306255, 11458.083353331713, 11765.411729413496, 11911.88604033916, 12099.274043557414, 3626.510421093145, 3739.6821270192067, 3755.2114056048263, 3759.248150369937, 3767.321882061228, 104291.9141617171, 112626.0, 115200.84799151985, 115379.42310288394, 118948.21577676342, 10664.68005959807, 11044.558217671203, 11099.056580190774, 11306.94346528262, 11493.42532873353, 19549.804501955085, 23270.069197231922, 23601.881990589936, 23661.7633823663, 23685.526289474114, 7988.121306656263, 7991.160928102582, 8020.5588692621295, 8171.06032806223, 8221.301189398968, 4186.665066794649, 4211.642010429148, 4283.421738065384, 4293.592108749664, 4316.115196384733, 3552.662497062775, 3632.891013269606, 3651.9817400913357, 3764.0, 3933.547642021147, 627.1533429869639, 631.4695655649276, 634.4195061518659, 642.8167972127922, 643.8486955565438, 673.3233100733731, 677.5155763629028, 698.7309885693943, 701.8631366883511, 729.3982464466836, 10052.69841904744, 10514.0, 10611.893881061245, 10697.893021069845, 10844.837327440095, 674.6087269383746, 675.6756756756686, 693.227051837208, 701.9964900175466, 705.5167210460851, 4175.874723758292, 5082.440931497532, 5209.6353255272525, 5248.396434410015, 5252.474752524793, 87066.0, 91552.0, 102747.0, 103567.0, 104153.91692166115, 89975.10024899799, 93353.0, 93576.53211734034, 95159.0, 96393.0, 80052.5997370021, 81682.18317816865, 82557.0, 82569.58715206386, 82576.0, 8286.46138001028, 9177.265818734351, 9874.079919792528, 9946.502674866373, 9966.352187107816, 48345.03309933711, 51422.22866656928, 51527.484725152266, 52124.34150636584, 53085.20372194343, 66318.0, 68097.0, 68170.97743533751, 68881.65559172172, 68960.0, 11386.316820990767, 11524.078073753912, 11602.303861767983, 11755.0, 11946.163768536118, 12941.54704585335, 13016.544420945222, 13134.146280491737, 13137.474501019853, 13801.51694690681, 12159.57441489579, 12183.0, 12346.938265308616, 12371.628851134481, 12752.74494510123, 11978.401079945794, 11997.324679828858, 12212.694682633006, 12330.19853709506, 12448.937755311166, 12060.276383417024, 12139.033701816914, 12214.816777748163, 12583.245005299712, 12586.622401327608, 7596.164421913474, 8780.648774048967, 9176.862347064933, 9183.403078799856, 9266.596667789216, 8747.343949203963, 10130.08829205375, 10261.333013354108, 10261.589536418758, 10273.794524109326], "value": 7599.388780089606, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1529.8999999999999, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [107.0, 118.0, 119.0, 117.0, 119.0, 119.0, 118.0, 118.0, 119.0, 119.0], "value": 117.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 110.0, 110.0, 168.0, 139.0, 114.0, 113.0, 119.0, 161.0, 93.0], "value": 126.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [203.0, 196.0, 198.0, 198.0, 197.0, 287.0, 275.0, 268.0, 284.0, 201.0], "value": 230.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 61.0, 62.0, 87.0, 63.0, 61.0, 60.0, 61.0, 74.0, 60.0], "value": 67.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 71.0, 84.0, 72.0, 71.0, 70.0, 109.0, 101.0, 102.0, 97.0], "value": 86.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 88.0, 92.0, 87.0, 88.0, 89.0, 89.0, 87.0, 88.0, 109.0], "value": 90.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 95.0, 96.0, 95.0, 96.0, 96.0, 96.0, 97.0, 96.0, 95.0], "value": 95.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 95.0, 89.0, 91.0, 92.0, 89.0, 104.0, 89.0, 91.0, 78.0], "value": 90.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 64.0, 65.0, 68.0, 70.0, 68.0, 71.0, 73.0, 68.0, 70.0], "value": 68.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 64.0, 58.0, 69.0, 62.0, 60.0, 57.0, 58.0, 59.0, 58.0], "value": 60.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 83.0, 85.0, 85.0, 87.0, 84.0, 101.0, 106.0, 103.0, 103.0], "value": 92.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [137.0, 142.0, 146.0, 146.0, 147.0, 148.0, 142.0, 147.0, 198.0, 159.0], "value": 151.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [214.0, 189.0, 165.0, 215.0, 167.0, 151.0, 189.0, 220.0, 189.0, 167.0], "value": 186.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 65.0, 65.0, 65.0, 66.0, 67.0, 63.0, 65.0, 64.0, 66.0], "value": 65.2, "unit": "score"}]}]} 14:50:23 INFO - Return code: 0 14:50:23 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 14:50:23 ERROR - # TBPL SUCCESS # 14:50:23 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 14:50:23 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 14:50:23 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 14:50:23 INFO - 2017-06-05 14:50:23.785 screenresolution[1663:10717] starting screenresolution argv=screenresolution get 14:50:23 INFO - 2017-06-05 14:50:23.788 screenresolution[1663:10717] Display 0: 1600x1200x32@60 14:50:23 INFO - 2017-06-05 14:50:23.794 screenresolution[1664:10720] starting screenresolution argv=screenresolution list 14:50:23 INFO - Available Modes on Display 0 14:50:23 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 14:50:23 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 14:50:23 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 14:50:23 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 14:50:24 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 14:50:24 INFO - Intel Iris: 14:50:24 INFO - Chipset Model: Intel Iris 14:50:24 INFO - Type: GPU 14:50:24 INFO - Bus: Built-In 14:50:24 INFO - VRAM (Dynamic, Max): 1536 MB 14:50:24 INFO - Vendor: Intel (0x8086) 14:50:24 INFO - Device ID: 0x0a2e 14:50:24 INFO - Revision ID: 0x0009 14:50:24 INFO - Displays: 14:50:24 INFO - Display: 14:50:24 INFO - Resolution: 1600 x 1200 @ 60 Hz 14:50:24 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 14:50:24 INFO - Display Serial Number: 1600x1200 60 14:50:24 INFO - Main Display: Yes 14:50:24 INFO - Mirror: Off 14:50:24 INFO - Online: Yes 14:50:24 INFO - Rotation: Supported 14:50:24 INFO - Return code: 0 14:50:24 INFO - Running post-action listener: _resource_record_post_action 14:50:24 INFO - [mozharness: 2017-06-05 21:50:24.028361Z] Finished run-tests step (success) 14:50:24 INFO - Running post-run listener: _resource_record_post_run 14:50:24 INFO - Total resource usage - Wall time: 1197s; CPU: 55.0%; Read bytes: 39761920; Write bytes: 513265664; Read time: 394; Write time: 1794 14:50:24 INFO - TinderboxPrint: CPU usage
54.5% 14:50:24 INFO - TinderboxPrint: I/O read bytes / time
39,761,920 / 394 14:50:24 INFO - TinderboxPrint: I/O write bytes / time
513,265,664 / 1,794 14:50:24 INFO - TinderboxPrint: CPU idle
2,178.8 (45.5%) 14:50:24 INFO - TinderboxPrint: CPU system
957.2 (20.0%) 14:50:24 INFO - TinderboxPrint: CPU user
1,653.7 (34.5%) 14:50:24 INFO - TinderboxPrint: Swap in / out
248,745,984 / 0 14:50:24 INFO - install - Wall time: 20s; CPU: 54.0%; Read bytes: 206379520; Write bytes: 202594304; Read time: 15745; Write time: 451 14:50:24 INFO - run-tests - Wall time: 1177s; CPU: 55.0%; Read bytes: 37660672; Write bytes: 299599872; Read time: 367; Write time: 1308 14:50:24 INFO - Running post-run listener: _upload_blobber_files 14:50:24 INFO - Blob upload gear active. 14:50:24 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 14:50:24 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 14:50:24 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'] 14:50:24 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 14:50:24 INFO - (blobuploader) - INFO - Open directory for files ... 14:50:24 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 14:50:24 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:50:24 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:50:25 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 14:50:25 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:50:25 INFO - (blobuploader) - INFO - Done attempting. 14:50:25 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 14:50:25 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:50:25 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:50:26 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 14:50:26 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:50:26 INFO - (blobuploader) - INFO - Done attempting. 14:50:26 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 14:50:26 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:50:26 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:50:27 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 14:50:27 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:50:27 INFO - (blobuploader) - INFO - Done attempting. 14:50:27 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 14:50:27 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:50:27 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:50:29 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 14:50:29 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:50:29 INFO - (blobuploader) - INFO - Done attempting. 14:50:29 INFO - (blobuploader) - INFO - Iteration through files over. 14:50:29 INFO - Return code: 0 14:50:29 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 14:50:29 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 14:50:29 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/cfc8d61b91fdc1ab22d80c0c5cd0dd884eacae0ec595b310f8d5d35ef82ad1a21c3140b27ae89ff4e32f532bba2e0d6da0cffb6010396c53f68f5fb97a0a0e4d", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/9317605a8ff8d81d2b22826d07857424aa3b96c2f26ad93e6ed179e5e2116127a0569ee517eb34687daa5e2f4884e9594e7036ab1757110800b1b1e11f0985fe", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/c698144be8ee7cd6c695b371beb89598bd98b2af3a087da7836a6a411f6ea5620cc803edc5822aed6fa49eb15518dd37baf5020d7a21449ca377f701b6784ce0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/1f63043b30f69e3b172b55584ae358077e066e17aeca9932520319cb26a0b26d9d1bc39807ea17ab83c1e8dbf6fc501a76db0345c84821a7655ea299dacb4902"} 14:50:29 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 14:50:29 INFO - Writing to file /builds/slave/test/properties/blobber_files 14:50:29 INFO - Contents: 14:50:29 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/cfc8d61b91fdc1ab22d80c0c5cd0dd884eacae0ec595b310f8d5d35ef82ad1a21c3140b27ae89ff4e32f532bba2e0d6da0cffb6010396c53f68f5fb97a0a0e4d", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/9317605a8ff8d81d2b22826d07857424aa3b96c2f26ad93e6ed179e5e2116127a0569ee517eb34687daa5e2f4884e9594e7036ab1757110800b1b1e11f0985fe", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/c698144be8ee7cd6c695b371beb89598bd98b2af3a087da7836a6a411f6ea5620cc803edc5822aed6fa49eb15518dd37baf5020d7a21449ca377f701b6784ce0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/1f63043b30f69e3b172b55584ae358077e066e17aeca9932520319cb26a0b26d9d1bc39807ea17ab83c1e8dbf6fc501a76db0345c84821a7655ea299dacb4902"} 14:50:29 INFO - Running post-run listener: copy_logs_to_upload_dir 14:50:29 INFO - Copying logs to upload dir... 14:50:29 INFO - mkdir: /builds/slave/test/build/upload/logs 14:50:29 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1273.414991 ========= master_lag: 0.06 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2017-06-05 14:50:30.077493) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-05 14:50:30.080796) ========= 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.6cRNlmN2PX/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.cOOoHa1dqP/Listeners TMPDIR=/var/folders/9c/2dh985pd15l19fywb0pq404000000w/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/cfc8d61b91fdc1ab22d80c0c5cd0dd884eacae0ec595b310f8d5d35ef82ad1a21c3140b27ae89ff4e32f532bba2e0d6da0cffb6010396c53f68f5fb97a0a0e4d", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/9317605a8ff8d81d2b22826d07857424aa3b96c2f26ad93e6ed179e5e2116127a0569ee517eb34687daa5e2f4884e9594e7036ab1757110800b1b1e11f0985fe", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/c698144be8ee7cd6c695b371beb89598bd98b2af3a087da7836a6a411f6ea5620cc803edc5822aed6fa49eb15518dd37baf5020d7a21449ca377f701b6784ce0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/1f63043b30f69e3b172b55584ae358077e066e17aeca9932520319cb26a0b26d9d1bc39807ea17ab83c1e8dbf6fc501a76db0345c84821a7655ea299dacb4902"} build_url:https://queue.taskcluster.net/v1/task/bnAGbk4hQnS3mTOwsq7YQw/artifacts/public/build/firefox-54.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010944 build_url: 'https://queue.taskcluster.net/v1/task/bnAGbk4hQnS3mTOwsq7YQw/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/cfc8d61b91fdc1ab22d80c0c5cd0dd884eacae0ec595b310f8d5d35ef82ad1a21c3140b27ae89ff4e32f532bba2e0d6da0cffb6010396c53f68f5fb97a0a0e4d", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/9317605a8ff8d81d2b22826d07857424aa3b96c2f26ad93e6ed179e5e2116127a0569ee517eb34687daa5e2f4884e9594e7036ab1757110800b1b1e11f0985fe", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/c698144be8ee7cd6c695b371beb89598bd98b2af3a087da7836a6a411f6ea5620cc803edc5822aed6fa49eb15518dd37baf5020d7a21449ca377f701b6784ce0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/1f63043b30f69e3b172b55584ae358077e066e17aeca9932520319cb26a0b26d9d1bc39807ea17ab83c1e8dbf6fc501a76db0345c84821a7655ea299dacb4902"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-05 14:50:30.114784) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-05 14:50:30.115155) ========= 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.6cRNlmN2PX/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.cOOoHa1dqP/Listeners TMPDIR=/var/folders/9c/2dh985pd15l19fywb0pq404000000w/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.005224 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-05 14:50:30.164381) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-05 14:50:30.164717) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-05 14:50:30.165067) ========= ========= Total master_lag: 0.19 =========