builder: mozilla-beta_yosemite_r7_devedition_test-dromaeojs-e10s slave: t-yosemite-r7-0363 starttime: 1496056978.15 results: success (0) buildid: 20170529025115 builduid: d7dbdec079b24dfbad3997e1e98e8b26 revision: 715d88d0bd826c9e4a5403d6561a2fcc229dd208 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-05-29 04:22:58.153555) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-05-29 04:22:58.153964) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-29 04:22:58.154258) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-29 04:22:58.368814) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-29 04:22:58.369091) ========= 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.KPYi6TKEVE/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.GuSMEHnOYN/Listeners TMPDIR=/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-05-29 04:22:58-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 7.89M=0.001s 2017-05-29 04:22:58 (7.89 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.616424 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-29 04:22:59.015904) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-29 04:22:59.016237) ========= 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.KPYi6TKEVE/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.GuSMEHnOYN/Listeners TMPDIR=/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/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.021849 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-29 04:22:59.069679) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-05-29 04:22:59.070224) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 715d88d0bd826c9e4a5403d6561a2fcc229dd208 --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 715d88d0bd826c9e4a5403d6561a2fcc229dd208 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.KPYi6TKEVE/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.GuSMEHnOYN/Listeners TMPDIR=/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-05-29 04:22:59,152 truncating revision to first 12 chars 2017-05-29 04:22:59,152 Setting DEBUG logging. 2017-05-29 04:22:59,152 attempt 1/10 2017-05-29 04:22:59,152 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/715d88d0bd82?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-05-29 04:23:00,840 unpacking tar archive at: mozilla-beta-715d88d0bd82/testing/mozharness/ program finished with exit code 0 elapsedTime=2.277291 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-05-29 04:23:01.372872) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-29 04:23:01.373192) ========= script_repo_revision: 715d88d0bd826c9e4a5403d6561a2fcc229dd208 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-29 04:23:01.373649) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-29 04:23:01.373962) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-29 04:23:01.398212) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 48 secs) (at 2017-05-29 04:23:01.398556) ========= /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.KPYi6TKEVE/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.GuSMEHnOYN/Listeners TMPDIR=/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/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 04:23:01 INFO - MultiFileLogger online at 20170529 04:23:01 in /builds/slave/test 04:23:01 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 04:23:01 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 04:23:01 INFO - {'append_to_log': False, 04:23:01 INFO - 'base_work_dir': '/builds/slave/test', 04:23:01 INFO - 'blob_upload_branch': 'Mozilla-Beta', 04:23:01 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 04:23:01 INFO - 'branch': 'Mozilla-Beta', 04:23:01 INFO - 'buildbot_json_path': 'buildprops.json', 04:23:01 INFO - 'config_files': ('talos/mac_config.py',), 04:23:01 INFO - 'default_actions': ('clobber', 04:23:01 INFO - 'read-buildbot-config', 04:23:01 INFO - 'download-and-extract', 04:23:01 INFO - 'populate-webroot', 04:23:01 INFO - 'create-virtualenv', 04:23:01 INFO - 'install', 04:23:01 INFO - 'run-tests'), 04:23:01 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 04:23:01 INFO - 'download_minidump_stackwalk': True, 04:23:01 INFO - 'download_symbols': 'ondemand', 04:23:01 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 04:23:01 INFO - 'tooltool.py': '/tools/tooltool.py', 04:23:01 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 04:23:01 INFO - '/tools/misc-python/virtualenv.py')}, 04:23:01 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 04:23:01 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 04:23:01 INFO - 'gecko_profile': False, 04:23:01 INFO - 'gecko_profile_interval': 0, 04:23:01 INFO - 'installer_path': 'installer.exe', 04:23:01 INFO - 'log_level': 'info', 04:23:01 INFO - 'log_name': 'talos', 04:23:01 INFO - 'log_to_console': True, 04:23:01 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 04:23:01 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 04:23:01 INFO - 'opt_config_files': (), 04:23:01 INFO - 'pip_index': False, 04:23:01 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:23:01 INFO - 'cmd': ('bash', 04:23:01 INFO - '-c', 04:23:01 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:23:01 INFO - 'enabled': True, 04:23:01 INFO - 'halt_on_failure': False, 04:23:01 INFO - 'name': 'check_screen_resolution'},), 04:23:01 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:23:01 INFO - 'cmd': ('bash', 04:23:01 INFO - '-c', 04:23:01 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:23:01 INFO - 'enabled': True, 04:23:01 INFO - 'halt_on_failure': False, 04:23:01 INFO - 'name': 'check_screen_resolution'},), 04:23:01 INFO - 'run_cmd_checks_enabled': True, 04:23:01 INFO - 'suite': 'dromaeojs-e10s', 04:23:01 INFO - 'system_bits': '32', 04:23:01 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 04:23:01 INFO - 'title': 't-yosemite-r7-0363', 04:23:01 INFO - 'tooltool_cache': '/builds/tooltool_cache', 04:23:01 INFO - 'use_talos_json': True, 04:23:01 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 04:23:01 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 04:23:01 INFO - 'work_dir': 'build'} 04:23:01 INFO - [mozharness: 2017-05-29 11:23:01.600073Z] Running clobber step. 04:23:01 INFO - Running pre-action listener: _resource_record_pre_action 04:23:01 INFO - Running main action method: clobber 04:23:01 INFO - rmtree: /builds/slave/test/build 04:23:01 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 04:23:02 INFO - Running post-action listener: _resource_record_post_action 04:23:02 INFO - [mozharness: 2017-05-29 11:23:02.251397Z] Finished clobber step (success) 04:23:02 INFO - [mozharness: 2017-05-29 11:23:02.251676Z] Running read-buildbot-config step. 04:23:02 INFO - Running pre-action listener: _resource_record_pre_action 04:23:02 INFO - Running main action method: read_buildbot_config 04:23:02 INFO - Using buildbot properties: 04:23:02 INFO - { 04:23:02 INFO - "project": "", 04:23:02 INFO - "product": "firefox", 04:23:02 INFO - "script_repo_revision": "production", 04:23:02 INFO - "builddir": "mozilla-beta_yosemite_r7_devedition_test-dromaeojs-e10s", 04:23:02 INFO - "repository": "", 04:23:02 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 DevEdition mozilla-beta talos dromaeojs-e10s", 04:23:02 INFO - "buildid": "20170529025115", 04:23:02 INFO - "pgo_build": "False", 04:23:02 INFO - "basedir": "/builds/slave/test", 04:23:02 INFO - "buildnumber": 2, 04:23:02 INFO - "slavename": "t-yosemite-r7-0363", 04:23:02 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 04:23:02 INFO - "platform": "yosemite_r7_devedition", 04:23:02 INFO - "scheduler": "tests-mozilla-beta-macosx64-devedition-talos", 04:23:02 INFO - "branch": "mozilla-beta", 04:23:02 INFO - "repo_path": "releases/mozilla-beta", 04:23:02 INFO - "revision": "715d88d0bd826c9e4a5403d6561a2fcc229dd208", 04:23:02 INFO - "stage_platform": "macosx64-devedition", 04:23:02 INFO - "builduid": "d7dbdec079b24dfbad3997e1e98e8b26", 04:23:02 INFO - "slavebuilddir": "test" 04:23:02 INFO - } 04:23:02 INFO - Found installer url https://queue.taskcluster.net/v1/task/JUNOfCotQ22MMyMNx_CQqg/artifacts/public/build/firefox-54.0.en-US.mac.dmg. 04:23:02 INFO - Running post-action listener: _resource_record_post_action 04:23:02 INFO - [mozharness: 2017-05-29 11:23:02.257289Z] Finished read-buildbot-config step (success) 04:23:02 INFO - [mozharness: 2017-05-29 11:23:02.257503Z] Running download-and-extract step. 04:23:02 INFO - Running pre-action listener: _resource_record_pre_action 04:23:02 INFO - Running main action method: download_and_extract 04:23:02 INFO - mkdir: /builds/slave/test/build/tests 04:23:02 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')]} 04:23:02 INFO - trying https://queue.taskcluster.net/v1/task/JUNOfCotQ22MMyMNx_CQqg/artifacts/public/build/firefox-54.0.en-US.mac.test_packages.json 04:23:02 INFO - Downloading https://queue.taskcluster.net/v1/task/JUNOfCotQ22MMyMNx_CQqg/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 04:23:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/JUNOfCotQ22MMyMNx_CQqg/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 04:23:04 INFO - Downloaded 1287 bytes. 04:23:04 INFO - Reading from file /builds/slave/test/build/firefox-54.0.en-US.mac.test_packages.json 04:23:04 INFO - Using the following test package requirements: 04:23:04 INFO - {u'awsy': [u'firefox-54.0.en-US.mac.common.tests.zip', 04:23:04 INFO - u'firefox-54.0.en-US.mac.awsy.tests.zip'], 04:23:04 INFO - u'common': [u'firefox-54.0.en-US.mac.common.tests.zip'], 04:23:04 INFO - u'cppunittest': [u'firefox-54.0.en-US.mac.common.tests.zip', 04:23:04 INFO - u'firefox-54.0.en-US.mac.cppunittest.tests.zip'], 04:23:04 INFO - u'gtest': [u'firefox-54.0.en-US.mac.common.tests.zip', 04:23:04 INFO - u'firefox-54.0.en-US.mac.gtest.tests.zip'], 04:23:04 INFO - u'jittest': [u'firefox-54.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 04:23:04 INFO - u'mochitest': [u'firefox-54.0.en-US.mac.common.tests.zip', 04:23:04 INFO - u'firefox-54.0.en-US.mac.mochitest.tests.zip'], 04:23:04 INFO - u'mozbase': [u'firefox-54.0.en-US.mac.common.tests.zip'], 04:23:04 INFO - u'reftest': [u'firefox-54.0.en-US.mac.common.tests.zip', 04:23:04 INFO - u'firefox-54.0.en-US.mac.reftest.tests.zip'], 04:23:04 INFO - u'talos': [u'firefox-54.0.en-US.mac.common.tests.zip', 04:23:04 INFO - u'firefox-54.0.en-US.mac.talos.tests.zip'], 04:23:04 INFO - u'web-platform': [u'firefox-54.0.en-US.mac.common.tests.zip', 04:23:04 INFO - u'firefox-54.0.en-US.mac.web-platform.tests.zip'], 04:23:04 INFO - u'xpcshell': [u'firefox-54.0.en-US.mac.common.tests.zip', 04:23:04 INFO - u'firefox-54.0.en-US.mac.xpcshell.tests.zip']} 04:23:04 INFO - Downloading packages: [u'firefox-54.0.en-US.mac.common.tests.zip'] for test suite category: common 04:23:04 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/JUNOfCotQ22MMyMNx_CQqg/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip 04:23:04 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/JUNOfCotQ22MMyMNx_CQqg/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip'}, attempt #1 04:23:04 INFO - Fetch https://queue.taskcluster.net/v1/task/JUNOfCotQ22MMyMNx_CQqg/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip into memory 04:23:05 INFO - Expected file size: 51963674 04:23:05 INFO - Obtained file size: 51963674 04:23:21 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 04:23:21 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/JUNOfCotQ22MMyMNx_CQqg/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip 04:23:21 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/JUNOfCotQ22MMyMNx_CQqg/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip'}, attempt #1 04:23:21 INFO - Fetch https://queue.taskcluster.net/v1/task/JUNOfCotQ22MMyMNx_CQqg/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip into memory 04:23:42 INFO - Expected file size: 51963674 04:23:42 INFO - Obtained file size: 51963674 04:23:57 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/JUNOfCotQ22MMyMNx_CQqg/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip 04:23:57 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/JUNOfCotQ22MMyMNx_CQqg/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip'}, attempt #1 04:23:57 INFO - Fetch https://queue.taskcluster.net/v1/task/JUNOfCotQ22MMyMNx_CQqg/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip into memory 04:24:14 INFO - Expected file size: 14404461 04:24:14 INFO - Obtained file size: 14404461 04:24:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:24:14 INFO - trying https://queue.taskcluster.net/v1/task/JUNOfCotQ22MMyMNx_CQqg/artifacts/public/build/firefox-54.0.en-US.mac.dmg 04:24:14 INFO - Downloading https://queue.taskcluster.net/v1/task/JUNOfCotQ22MMyMNx_CQqg/artifacts/public/build/firefox-54.0.en-US.mac.dmg to /builds/slave/test/build/firefox-54.0.en-US.mac.dmg 04:24:14 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/JUNOfCotQ22MMyMNx_CQqg/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 04:24:17 INFO - Downloaded 74895525 bytes. 04:24:17 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/JUNOfCotQ22MMyMNx_CQqg/artifacts/public/build/firefox-54.0.en-US.mac.dmg 04:24:17 INFO - mkdir: /builds/slave/test/properties 04:24:17 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 04:24:17 INFO - Writing to file /builds/slave/test/properties/build_url 04:24:17 INFO - Contents: 04:24:17 INFO - build_url:https://queue.taskcluster.net/v1/task/JUNOfCotQ22MMyMNx_CQqg/artifacts/public/build/firefox-54.0.en-US.mac.dmg 04:24:18 INFO - Running post-action listener: _resource_record_post_action 04:24:18 INFO - Running post-action listener: set_extra_try_arguments 04:24:18 INFO - [mozharness: 2017-05-29 11:24:18.800952Z] Finished download-and-extract step (success) 04:24:18 INFO - [mozharness: 2017-05-29 11:24:18.801082Z] Running populate-webroot step. 04:24:18 INFO - Running pre-action listener: _resource_record_pre_action 04:24:18 INFO - Running main action method: populate_webroot 04:24:18 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 04:24:18 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 04:24:18 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 04:24:18 INFO - '--tppagecycles', 04:24:18 INFO - '7'], 04:24:18 INFO - 'tests': ['tsvgm']}}, 04:24:18 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 04:24:18 INFO - 'tests': ['tresize', 'tcanvasmark']}, 04:24:18 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 04:24:18 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 04:24:18 INFO - 'tests': ['dromaeo_css', 'kraken']}, 04:24:18 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 04:24:18 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 04:24:18 INFO - 'talos_options': ['--disable-e10s'], 04:24:18 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:24:18 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 04:24:18 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:24:18 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 04:24:18 INFO - 'talos_options': ['--disable-e10s'], 04:24:18 INFO - 'tests': ['damp', 'tps']}, 04:24:18 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 04:24:18 INFO - 'tests': ['damp', 'tps']}, 04:24:18 INFO - 'g3': {'talos_options': ['--disable-e10s'], 04:24:18 INFO - 'tests': ['dromaeo_dom']}, 04:24:18 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 04:24:18 INFO - 'g4': {'talos_options': ['--disable-e10s'], 04:24:18 INFO - 'tests': ['basic_compositor_video']}, 04:24:18 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 04:24:18 INFO - 'other': {'talos_options': ['--disable-e10s'], 04:24:18 INFO - 'tests': ['a11yr', 04:24:18 INFO - 'ts_paint', 04:24:18 INFO - 'tpaint', 04:24:18 INFO - 'sessionrestore', 04:24:18 INFO - 'sessionrestore_no_auto_restore', 04:24:18 INFO - 'tabpaint']}, 04:24:18 INFO - 'other-e10s': {'tests': ['a11yr', 04:24:18 INFO - 'ts_paint', 04:24:18 INFO - 'tpaint', 04:24:18 INFO - 'sessionrestore', 04:24:18 INFO - 'sessionrestore_no_auto_restore', 04:24:18 INFO - 'tabpaint']}, 04:24:18 INFO - 'other-e10s_l64': {'tests': ['a11yr', 04:24:18 INFO - 'ts_paint', 04:24:18 INFO - 'tpaint', 04:24:18 INFO - 'sessionrestore', 04:24:18 INFO - 'sessionrestore_no_auto_restore', 04:24:18 INFO - 'tabpaint']}, 04:24:18 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 04:24:18 INFO - 'ts_paint', 04:24:18 INFO - 'tpaint', 04:24:18 INFO - 'sessionrestore', 04:24:18 INFO - 'sessionrestore_no_auto_restore', 04:24:18 INFO - 'tabpaint']}, 04:24:18 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 04:24:18 INFO - 'tests': ['a11yr', 04:24:18 INFO - 'ts_paint', 04:24:18 INFO - 'tpaint', 04:24:18 INFO - 'sessionrestore', 04:24:18 INFO - 'sessionrestore_no_auto_restore', 04:24:18 INFO - 'tabpaint']}, 04:24:18 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 04:24:18 INFO - 'tests': ['a11yr', 04:24:18 INFO - 'ts_paint', 04:24:18 INFO - 'tpaint', 04:24:18 INFO - 'sessionrestore', 04:24:18 INFO - 'sessionrestore_no_auto_restore', 04:24:18 INFO - 'tabpaint']}, 04:24:18 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 04:24:18 INFO - 'tests': ['tsvgx', 04:24:18 INFO - 'tsvgr_opacity', 04:24:18 INFO - 'tart', 04:24:18 INFO - 'tscrollx', 04:24:18 INFO - 'cart', 04:24:18 INFO - 'tsvg_static']}, 04:24:18 INFO - 'svgr-e10s': {'tests': ['tsvgx', 04:24:18 INFO - 'tsvgr_opacity', 04:24:18 INFO - 'tart', 04:24:18 INFO - 'tscrollx', 04:24:18 INFO - 'cart', 04:24:18 INFO - 'tsvg_static']}, 04:24:18 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 04:24:18 INFO - 'talos_options': ['--disable-e10s'], 04:24:18 INFO - 'tests': ['tp5o']}, 04:24:18 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 04:24:18 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 04:24:18 INFO - 'talos_options': ['--disable-e10s', 04:24:18 INFO - '--xperf_path', 04:24:18 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:24:18 INFO - 'tests': ['tp5n']}, 04:24:18 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 04:24:18 INFO - 'talos_options': ['--xperf_path', 04:24:18 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:24:18 INFO - 'tests': ['tp5n']}}, 04:24:18 INFO - 'talos.zip': {'path': '', 04:24:18 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 04:24:18 INFO - Running post-action listener: _resource_record_post_action 04:24:18 INFO - [mozharness: 2017-05-29 11:24:18.819331Z] Finished populate-webroot step (success) 04:24:18 INFO - [mozharness: 2017-05-29 11:24:18.819427Z] Running create-virtualenv step. 04:24:18 INFO - Running pre-action listener: _resource_record_pre_action 04:24:18 INFO - Running main action method: create_virtualenv 04:24:18 INFO - Creating virtualenv /builds/slave/test/build/venv 04:24:18 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 04:24:18 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 04:24:18 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 04:24:18 INFO - Using real prefix '/tools/python27' 04:24:18 INFO - New python executable in /builds/slave/test/build/venv/bin/python 04:24:19 INFO - Installing distribute.............................................................................................................................................................................................done. 04:24:22 INFO - Installing pip.................done. 04:24:22 INFO - Return code: 0 04:24:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 04:24:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 04:24:22 INFO - Reading from file tmpfile_stdout 04:24:22 INFO - Output received: 04:24:22 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) 04:24:22 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 04:24:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:24:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:24:23 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')]} 04:24:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:24:23 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 0x1078a9b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1078ac768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9fe9c77520>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107687d30>, '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 0x1075aedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10767cbe8>, '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.KPYi6TKEVE/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.GuSMEHnOYN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/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 04:24:23 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 04:24:23 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 04:24:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KPYi6TKEVE/Render', 04:24:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:24:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:24:23 INFO - 'HOME': '/Users/cltbld', 04:24:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:24:23 INFO - 'LOGNAME': 'cltbld', 04:24:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:24:23 INFO - 'MOZ_NO_REMOTE': '1', 04:24:23 INFO - 'NO_EM_RESTART': '1', 04:24:23 INFO - 'PAGER': '/bin/cat', 04:24:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:24:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:24:23 INFO - 'PWD': '/builds/slave/test', 04:24:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:24:23 INFO - 'SHELL': '/bin/bash', 04:24:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.GuSMEHnOYN/Listeners', 04:24:23 INFO - 'TMPDIR': '/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/', 04:24:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:24:23 INFO - 'USER': 'cltbld', 04:24:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:24:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:24:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:24:23 INFO - 'XPC_FLAGS': '0x0', 04:24:23 INFO - 'XPC_SERVICE_NAME': '0', 04:24:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:24:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:24:23 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 04:24:23 INFO - Cleaning up... 04:24:23 INFO - Return code: 0 04:24:23 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 04:24:23 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')]} 04:24:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:24:23 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')]} 04:24:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:24:23 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 0x1078a9b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1078ac768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9fe9c77520>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107687d30>, '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 0x1075aedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10767cbe8>, '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.KPYi6TKEVE/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.GuSMEHnOYN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/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 04:24:23 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 04:24:23 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 04:24:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KPYi6TKEVE/Render', 04:24:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:24:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:24:23 INFO - 'HOME': '/Users/cltbld', 04:24:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:24:23 INFO - 'LOGNAME': 'cltbld', 04:24:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:24:23 INFO - 'MOZ_NO_REMOTE': '1', 04:24:23 INFO - 'NO_EM_RESTART': '1', 04:24:23 INFO - 'PAGER': '/bin/cat', 04:24:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:24:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:24:23 INFO - 'PWD': '/builds/slave/test', 04:24:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:24:23 INFO - 'SHELL': '/bin/bash', 04:24:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.GuSMEHnOYN/Listeners', 04:24:23 INFO - 'TMPDIR': '/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/', 04:24:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:24:23 INFO - 'USER': 'cltbld', 04:24:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:24:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:24:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:24:23 INFO - 'XPC_FLAGS': '0x0', 04:24:23 INFO - 'XPC_SERVICE_NAME': '0', 04:24:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:24:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:24:23 INFO - Downloading/unpacking psutil>=3.1.1 04:24: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 04:24: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 04:24: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 04:24: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 04:24:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 04:24:28 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:24:28 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:24:28 INFO - Installing collected packages: psutil 04:24:28 INFO - Running setup.py install for psutil 04:24:28 INFO - building 'psutil._psutil_osx' extension 04:24:28 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 04:24:28 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 04:24:28 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 04:24:28 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 04:24:28 INFO - building 'psutil._psutil_posix' extension 04:24:28 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 04:24:29 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 04:24:29 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 04:24:29 INFO - ^ 04:24:29 INFO - 1 warning generated. 04:24:29 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 04:24:29 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:24:29 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:24:29 INFO - Successfully installed psutil 04:24:29 INFO - Cleaning up... 04:24:29 INFO - Return code: 0 04:24:29 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 04:24:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:24:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:24:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:24:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:24:29 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1078a9b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1078ac768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9fe9c77520>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107687d30>, '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 0x1075aedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10767cbe8>, '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.KPYi6TKEVE/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.GuSMEHnOYN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/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 04:24:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 04:24:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 04:24:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KPYi6TKEVE/Render', 04:24:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:24:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:24:29 INFO - 'HOME': '/Users/cltbld', 04:24:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:24:29 INFO - 'LOGNAME': 'cltbld', 04:24:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:24:29 INFO - 'MOZ_NO_REMOTE': '1', 04:24:29 INFO - 'NO_EM_RESTART': '1', 04:24:29 INFO - 'PAGER': '/bin/cat', 04:24:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:24:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:24:29 INFO - 'PWD': '/builds/slave/test', 04:24:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:24:29 INFO - 'SHELL': '/bin/bash', 04:24:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.GuSMEHnOYN/Listeners', 04:24:29 INFO - 'TMPDIR': '/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/', 04:24:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:24:29 INFO - 'USER': 'cltbld', 04:24:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:24:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:24:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:24:29 INFO - 'XPC_FLAGS': '0x0', 04:24:29 INFO - 'XPC_SERVICE_NAME': '0', 04:24:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:24:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:24:29 INFO - Downloading/unpacking mozsystemmonitor==0.3 04:24:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:24:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:24:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:24:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:24:32 INFO - Downloading mozsystemmonitor-0.3.tar.gz 04:24:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 04:24:32 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 04:24:32 INFO - Installing collected packages: mozsystemmonitor 04:24:32 INFO - Running setup.py install for mozsystemmonitor 04:24:32 INFO - Successfully installed mozsystemmonitor 04:24:32 INFO - Cleaning up... 04:24:32 INFO - Return code: 0 04:24:32 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 04:24:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:24:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:24:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:24:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:24:32 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1078a9b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1078ac768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9fe9c77520>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107687d30>, '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 0x1075aedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10767cbe8>, '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.KPYi6TKEVE/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.GuSMEHnOYN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/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 04:24:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 04:24:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 04:24:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KPYi6TKEVE/Render', 04:24:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:24:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:24:32 INFO - 'HOME': '/Users/cltbld', 04:24:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:24:32 INFO - 'LOGNAME': 'cltbld', 04:24:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:24:32 INFO - 'MOZ_NO_REMOTE': '1', 04:24:32 INFO - 'NO_EM_RESTART': '1', 04:24:32 INFO - 'PAGER': '/bin/cat', 04:24:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:24:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:24:32 INFO - 'PWD': '/builds/slave/test', 04:24:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:24:32 INFO - 'SHELL': '/bin/bash', 04:24:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.GuSMEHnOYN/Listeners', 04:24:32 INFO - 'TMPDIR': '/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/', 04:24:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:24:32 INFO - 'USER': 'cltbld', 04:24:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:24:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:24:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:24:32 INFO - 'XPC_FLAGS': '0x0', 04:24:32 INFO - 'XPC_SERVICE_NAME': '0', 04:24:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:24:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:24:32 INFO - Downloading/unpacking jsonschema==2.5.1 04:24:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:24:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:24:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:24:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:24:34 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 04:24:34 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 04:24:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:24:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:24:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:24:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:24:34 INFO - Downloading functools32-3.2.3-2.tar.gz 04:24:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 04:24:34 INFO - warning: no files found matching '*.txt' 04:24:34 INFO - no previously-included directories found matching 'build' 04:24:34 INFO - no previously-included directories found matching 'dist' 04:24:34 INFO - no previously-included directories found matching '.git*' 04:24:34 INFO - Installing collected packages: jsonschema, functools32 04:24:34 INFO - Running setup.py install for functools32 04:24:34 INFO - warning: no files found matching '*.txt' 04:24:34 INFO - no previously-included directories found matching 'build' 04:24:34 INFO - no previously-included directories found matching 'dist' 04:24:34 INFO - no previously-included directories found matching '.git*' 04:24:34 INFO - Successfully installed jsonschema functools32 04:24:34 INFO - Cleaning up... 04:24:34 INFO - Return code: 0 04:24:34 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 04:24:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:24:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:24:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:24:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:24:34 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1078a9b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1078ac768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9fe9c77520>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107687d30>, '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 0x1075aedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10767cbe8>, '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.KPYi6TKEVE/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.GuSMEHnOYN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/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 04:24:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 04:24:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 04:24:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KPYi6TKEVE/Render', 04:24:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:24:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:24:34 INFO - 'HOME': '/Users/cltbld', 04:24:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:24:34 INFO - 'LOGNAME': 'cltbld', 04:24:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:24:34 INFO - 'MOZ_NO_REMOTE': '1', 04:24:34 INFO - 'NO_EM_RESTART': '1', 04:24:34 INFO - 'PAGER': '/bin/cat', 04:24:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:24:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:24:34 INFO - 'PWD': '/builds/slave/test', 04:24:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:24:34 INFO - 'SHELL': '/bin/bash', 04:24:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.GuSMEHnOYN/Listeners', 04:24:34 INFO - 'TMPDIR': '/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/', 04:24:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:24:34 INFO - 'USER': 'cltbld', 04:24:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:24:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:24:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:24:34 INFO - 'XPC_FLAGS': '0x0', 04:24:34 INFO - 'XPC_SERVICE_NAME': '0', 04:24:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:24:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:24:35 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 04:24:35 INFO - Cleaning up... 04:24:35 INFO - Return code: 0 04:24:35 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 04:24:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:24:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:24:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:24:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:24:35 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1078a9b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1078ac768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9fe9c77520>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107687d30>, '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 0x1075aedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10767cbe8>, '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.KPYi6TKEVE/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.GuSMEHnOYN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/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 04:24:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 04:24:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 04:24:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KPYi6TKEVE/Render', 04:24:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:24:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:24:35 INFO - 'HOME': '/Users/cltbld', 04:24:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:24:35 INFO - 'LOGNAME': 'cltbld', 04:24:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:24:35 INFO - 'MOZ_NO_REMOTE': '1', 04:24:35 INFO - 'NO_EM_RESTART': '1', 04:24:35 INFO - 'PAGER': '/bin/cat', 04:24:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:24:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:24:35 INFO - 'PWD': '/builds/slave/test', 04:24:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:24:35 INFO - 'SHELL': '/bin/bash', 04:24:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.GuSMEHnOYN/Listeners', 04:24:35 INFO - 'TMPDIR': '/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/', 04:24:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:24:35 INFO - 'USER': 'cltbld', 04:24:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:24:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:24:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:24:35 INFO - 'XPC_FLAGS': '0x0', 04:24:35 INFO - 'XPC_SERVICE_NAME': '0', 04:24:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:24:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:24:35 INFO - Downloading/unpacking blobuploader==1.2.4 04:24:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:24:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:24:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:24:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:24:36 INFO - Downloading blobuploader-1.2.4.tar.gz 04:24:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 04:24:36 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 04:24:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:24:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:24:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:24:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:24:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 04:24:37 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 04:24:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:24:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:24:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:24:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:24:37 INFO - Downloading docopt-0.6.1.tar.gz 04:24:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 04:24:37 INFO - Installing collected packages: blobuploader, requests, docopt 04:24:37 INFO - Running setup.py install for blobuploader 04:24:37 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 04:24:37 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 04:24:37 INFO - Running setup.py install for requests 04:24:38 INFO - Running setup.py install for docopt 04:24:38 INFO - Successfully installed blobuploader requests docopt 04:24:38 INFO - Cleaning up... 04:24:38 INFO - Return code: 0 04:24:38 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:24:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:24:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:24:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:24:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:24:38 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 0x1078a9b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1078ac768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9fe9c77520>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107687d30>, '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 0x1075aedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10767cbe8>, '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.KPYi6TKEVE/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.GuSMEHnOYN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/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 04:24:38 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 04:24:38 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 04:24:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KPYi6TKEVE/Render', 04:24:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:24:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:24:38 INFO - 'HOME': '/Users/cltbld', 04:24:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:24:38 INFO - 'LOGNAME': 'cltbld', 04:24:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:24:38 INFO - 'MOZ_NO_REMOTE': '1', 04:24:38 INFO - 'NO_EM_RESTART': '1', 04:24:38 INFO - 'PAGER': '/bin/cat', 04:24:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:24:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:24:38 INFO - 'PWD': '/builds/slave/test', 04:24:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:24:38 INFO - 'SHELL': '/bin/bash', 04:24:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.GuSMEHnOYN/Listeners', 04:24:38 INFO - 'TMPDIR': '/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/', 04:24:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:24:38 INFO - 'USER': 'cltbld', 04:24:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:24:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:24:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:24:38 INFO - 'XPC_FLAGS': '0x0', 04:24:38 INFO - 'XPC_SERVICE_NAME': '0', 04:24:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:24:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:24:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:24:38 INFO - Running setup.py (path:/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/pip-AZccUR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:24:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:24:38 INFO - Running setup.py (path:/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/pip-IimQyd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:24:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:24:39 INFO - Running setup.py (path:/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/pip-6zueBZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:24:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:24:39 INFO - Running setup.py (path:/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/pip-4dfUaO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:24:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:24:39 INFO - Running setup.py (path:/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/pip-wwG_Og-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:24:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:24:39 INFO - Running setup.py (path:/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/pip-AdUQYF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:24:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:24:39 INFO - Running setup.py (path:/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/pip-ooKPu2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:24:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:24:39 INFO - Running setup.py (path:/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/pip-7DSFUa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:24:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:24:39 INFO - Running setup.py (path:/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/pip-CTs4RK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:24:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:24:39 INFO - Running setup.py (path:/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/pip-8Kf1u_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:24:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:24:39 INFO - Running setup.py (path:/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/pip-cpw3hY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:24:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:24:40 INFO - Running setup.py (path:/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/pip-q2DaAY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:24:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:24:40 INFO - Running setup.py (path:/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/pip-cmdEs5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:24:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:24:40 INFO - Running setup.py (path:/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/pip-AqPe4j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:24:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:24:40 INFO - Running setup.py (path:/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/pip-Yng1ga-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:24:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:24:40 INFO - Running setup.py (path:/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/pip-hJCvzo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:24:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:24:40 INFO - Running setup.py (path:/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/pip-FQQMfh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:24:40 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 04:24:40 INFO - Running setup.py install for manifestparser 04:24:40 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 04:24:40 INFO - Running setup.py install for mozcrash 04:24:40 INFO - Running setup.py install for mozdebug 04:24:41 INFO - Running setup.py install for mozdevice 04:24:41 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 04:24:41 INFO - Installing dm script to /builds/slave/test/build/venv/bin 04:24:41 INFO - Running setup.py install for mozfile 04:24:41 INFO - Running setup.py install for mozhttpd 04:24:41 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 04:24:41 INFO - Running setup.py install for mozinfo 04:24:41 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 04:24:41 INFO - Running setup.py install for mozInstall 04:24:41 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 04:24:41 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 04:24:41 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 04:24:41 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 04:24:41 INFO - Running setup.py install for mozleak 04:24:41 INFO - Running setup.py install for mozlog 04:24:42 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 04:24:42 INFO - Running setup.py install for moznetwork 04:24:42 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 04:24:42 INFO - Running setup.py install for mozprocess 04:24:42 INFO - Running setup.py install for mozprofile 04:24:42 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 04:24:42 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 04:24:42 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 04:24:42 INFO - Running setup.py install for mozrunner 04:24:42 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 04:24:42 INFO - Running setup.py install for mozscreenshot 04:24:42 INFO - Running setup.py install for moztest 04:24:43 INFO - Running setup.py install for mozversion 04:24:43 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 04:24:43 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 04:24:43 INFO - Cleaning up... 04:24:43 INFO - Return code: 0 04:24:43 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:24:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:24:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:24:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:24:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:24:43 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 0x1078a9b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1078ac768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9fe9c77520>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107687d30>, '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 0x1075aedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10767cbe8>, '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.KPYi6TKEVE/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.GuSMEHnOYN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/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 04:24:43 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 04:24:43 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 04:24:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KPYi6TKEVE/Render', 04:24:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:24:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:24:43 INFO - 'HOME': '/Users/cltbld', 04:24:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:24:43 INFO - 'LOGNAME': 'cltbld', 04:24:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:24:43 INFO - 'MOZ_NO_REMOTE': '1', 04:24:43 INFO - 'NO_EM_RESTART': '1', 04:24:43 INFO - 'PAGER': '/bin/cat', 04:24:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:24:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:24:43 INFO - 'PWD': '/builds/slave/test', 04:24:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:24:43 INFO - 'SHELL': '/bin/bash', 04:24:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.GuSMEHnOYN/Listeners', 04:24:43 INFO - 'TMPDIR': '/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/', 04:24:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:24:43 INFO - 'USER': 'cltbld', 04:24:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:24:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:24:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:24:43 INFO - 'XPC_FLAGS': '0x0', 04:24:43 INFO - 'XPC_SERVICE_NAME': '0', 04:24:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:24:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:24:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:24:43 INFO - Running setup.py (path:/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/pip-MNGorR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:24:43 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)) 04:24:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:24:43 INFO - Running setup.py (path:/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/pip-uUmsoN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:24:43 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)) 04:24:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:24:43 INFO - Running setup.py (path:/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/pip-YggGki-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:24:43 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)) 04:24:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:24:43 INFO - Running setup.py (path:/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/pip-vCTYlK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:24:43 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)) 04:24:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:24:43 INFO - Running setup.py (path:/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/pip-zYxJf3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:24:44 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)) 04:24:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:24:44 INFO - Running setup.py (path:/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/pip-Vx8Q4s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:24:44 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)) 04:24:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:24:44 INFO - Running setup.py (path:/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/pip-xAa9pa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:24:44 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)) 04:24:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:24:44 INFO - Running setup.py (path:/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/pip-PCGp9e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:24:44 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)) 04:24:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:24:44 INFO - Running setup.py (path:/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/pip-BsbbPi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:24:44 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)) 04:24:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:24:44 INFO - Running setup.py (path:/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/pip-bv6ccX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:24:44 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)) 04:24:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:24:44 INFO - Running setup.py (path:/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/pip-0oTHQl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:24:44 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)) 04:24:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:24:44 INFO - Running setup.py (path:/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/pip-6b7eBi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:24:44 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)) 04:24:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:24:44 INFO - Running setup.py (path:/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/pip-Y4BOns-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:24:44 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)) 04:24:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:24:44 INFO - Running setup.py (path:/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/pip-lC7wGm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:24:45 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)) 04:24:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:24:45 INFO - Running setup.py (path:/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/pip-PdJa6E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:24:45 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)) 04:24:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:24:45 INFO - Running setup.py (path:/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/pip-OkQiPy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:24:45 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)) 04:24:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:24:45 INFO - Running setup.py (path:/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/pip-Cmuoyz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:24:45 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)) 04:24:45 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)) 04:24:45 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)) 04:24:45 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)) 04:24:45 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)) 04:24:45 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)) 04:24:45 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 04:24:45 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 04:24:45 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 04:24:45 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 04:24:45 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 04:24:46 INFO - Downloading blessings-1.6.tar.gz 04:24:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 04:24:46 INFO - Installing collected packages: blessings 04:24:46 INFO - Running setup.py install for blessings 04:24:47 INFO - Successfully installed blessings 04:24:47 INFO - Cleaning up... 04:24:47 INFO - Return code: 0 04:24:47 INFO - Done creating virtualenv /builds/slave/test/build/venv. 04:24:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:24:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:24:47 INFO - Reading from file tmpfile_stdout 04:24:47 INFO - Current package versions: 04:24:47 INFO - blessings == 1.6 04:24:47 INFO - blobuploader == 1.2.4 04:24:47 INFO - docopt == 0.6.1 04:24:47 INFO - functools32 == 3.2.3-2 04:24:47 INFO - jsonschema == 2.5.1 04:24:47 INFO - manifestparser == 1.1 04:24:47 INFO - mozInstall == 1.12 04:24:47 INFO - mozcrash == 1.0 04:24:47 INFO - mozdebug == 0.1 04:24:47 INFO - mozdevice == 0.49 04:24:47 INFO - mozfile == 1.2 04:24:47 INFO - mozhttpd == 0.7 04:24:47 INFO - mozinfo == 0.9 04:24:47 INFO - mozleak == 0.1 04:24:47 INFO - mozlog == 3.4 04:24:47 INFO - moznetwork == 0.27 04:24:47 INFO - mozprocess == 0.25 04:24:47 INFO - mozprofile == 0.28 04:24:47 INFO - mozrunner == 6.13 04:24:47 INFO - mozscreenshot == 0.1 04:24:47 INFO - mozsystemmonitor == 0.3 04:24:47 INFO - moztest == 0.8 04:24:47 INFO - mozversion == 1.4 04:24:47 INFO - psutil == 3.1.1 04:24:47 INFO - requests == 1.2.3 04:24:47 INFO - wsgiref == 0.1.2 04:24:47 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:24: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')]} 04:24:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:24: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')]} 04:24:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:24:47 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 0x1078a9b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1078ac768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9fe9c77520>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107687d30>, '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 0x1075aedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10767cbe8>, '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.KPYi6TKEVE/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.GuSMEHnOYN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/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 04:24:47 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 04:24:47 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 04:24:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KPYi6TKEVE/Render', 04:24:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:24:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:24:47 INFO - 'HOME': '/Users/cltbld', 04:24:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:24:47 INFO - 'LOGNAME': 'cltbld', 04:24:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:24:47 INFO - 'MOZ_NO_REMOTE': '1', 04:24:47 INFO - 'NO_EM_RESTART': '1', 04:24:47 INFO - 'PAGER': '/bin/cat', 04:24:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:24:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:24:47 INFO - 'PWD': '/builds/slave/test', 04:24:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:24:47 INFO - 'SHELL': '/bin/bash', 04:24:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.GuSMEHnOYN/Listeners', 04:24:47 INFO - 'TMPDIR': '/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/', 04:24:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:24:47 INFO - 'USER': 'cltbld', 04:24:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:24:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:24:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:24:47 INFO - 'XPC_FLAGS': '0x0', 04:24:47 INFO - 'XPC_SERVICE_NAME': '0', 04:24:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:24:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:24:47 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)) 04:24:47 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)) 04:24:47 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)) 04:24:47 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)) 04:24:47 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)) 04:24:47 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)) 04:24:47 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)) 04:24:47 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)) 04:24:47 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)) 04:24:47 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)) 04:24:47 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)) 04:24:47 INFO - Cleaning up... 04:24:47 INFO - Return code: 0 04:24:47 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 04:24: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')]} 04:24:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:24: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')]} 04:24:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:24:47 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 0x1078a9b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1078ac768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9fe9c77520>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107687d30>, '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 0x1075aedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10767cbe8>, '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.KPYi6TKEVE/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.GuSMEHnOYN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/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 04:24:47 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 04:24:47 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 04:24:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KPYi6TKEVE/Render', 04:24:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:24:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:24:47 INFO - 'HOME': '/Users/cltbld', 04:24:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:24:47 INFO - 'LOGNAME': 'cltbld', 04:24:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:24:47 INFO - 'MOZ_NO_REMOTE': '1', 04:24:47 INFO - 'NO_EM_RESTART': '1', 04:24:47 INFO - 'PAGER': '/bin/cat', 04:24:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:24:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:24:47 INFO - 'PWD': '/builds/slave/test', 04:24:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:24:47 INFO - 'SHELL': '/bin/bash', 04:24:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.GuSMEHnOYN/Listeners', 04:24:47 INFO - 'TMPDIR': '/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/', 04:24:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:24:47 INFO - 'USER': 'cltbld', 04:24:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:24:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:24:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:24:47 INFO - 'XPC_FLAGS': '0x0', 04:24:47 INFO - 'XPC_SERVICE_NAME': '0', 04:24:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:24:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:24:47 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 04:24:47 INFO - Cleaning up... 04:24:47 INFO - Return code: 0 04:24:47 INFO - Running post-action listener: _resource_record_post_action 04:24:47 INFO - Running post-action listener: _start_resource_monitoring 04:24:47 INFO - Starting resource monitoring. 04:24:47 INFO - [mozharness: 2017-05-29 11:24:47.945553Z] Finished create-virtualenv step (success) 04:24:47 INFO - [mozharness: 2017-05-29 11:24:47.946531Z] Running install step. 04:24:47 INFO - Running pre-action listener: _resource_record_pre_action 04:24:47 INFO - Running main action method: install 04:24:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:24:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:24:48 INFO - Reading from file tmpfile_stdout 04:24:48 INFO - Detecting whether we're running mozinstall >=1.0... 04:24:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 04:24:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 04:24:48 INFO - Reading from file tmpfile_stdout 04:24:48 INFO - Output received: 04:24:48 INFO - Usage: mozinstall [options] installer 04:24:48 INFO - Options: 04:24:48 INFO - -h, --help show this help message and exit 04:24:48 INFO - -d DEST, --destination=DEST 04:24:48 INFO - Directory to install application into. [default: 04:24:48 INFO - "/builds/slave/test"] 04:24:48 INFO - --app=APP Application being installed. [default: firefox] 04:24:48 INFO - mkdir: /builds/slave/test/build/application 04:24:48 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'] 04:24:48 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 04:25:08 INFO - Reading from file tmpfile_stdout 04:25:08 INFO - Output received: 04:25:08 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 04:25:08 INFO - Running post-action listener: _resource_record_post_action 04:25:08 INFO - [mozharness: 2017-05-29 11:25:08.022108Z] Finished install step (success) 04:25:08 INFO - [mozharness: 2017-05-29 11:25:08.022325Z] Running run-tests step. 04:25:08 INFO - Running pre-action listener: _resource_record_pre_action 04:25:08 INFO - Running main action method: run_tests 04:25:08 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 04:25:08 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 04:25:08 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 04:25:08 INFO - 2017-05-29 04:25:08.037 screenresolution[30013:249323] starting screenresolution argv=screenresolution get 04:25:08 INFO - 2017-05-29 04:25:08.039 screenresolution[30013:249323] Display 0: 1600x1200x32@60 04:25:08 INFO - 2017-05-29 04:25:08.045 screenresolution[30014:249325] starting screenresolution argv=screenresolution list 04:25:08 INFO - Available Modes on Display 0 04:25:08 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 04:25:08 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 04:25:08 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 04:25:08 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 04:25:08 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 04:25:08 INFO - Intel Iris: 04:25:08 INFO - Chipset Model: Intel Iris 04:25:08 INFO - Type: GPU 04:25:08 INFO - Bus: Built-In 04:25:08 INFO - VRAM (Dynamic, Max): 1536 MB 04:25:08 INFO - Vendor: Intel (0x8086) 04:25:08 INFO - Device ID: 0x0a2e 04:25:08 INFO - Revision ID: 0x0009 04:25:08 INFO - Displays: 04:25:08 INFO - Display: 04:25:08 INFO - Resolution: 1600 x 1200 @ 60 Hz 04:25:08 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 04:25:08 INFO - Display Serial Number: 1600x1200 60 04:25:08 INFO - Main Display: Yes 04:25:08 INFO - Mirror: Off 04:25:08 INFO - Online: Yes 04:25:08 INFO - Rotation: Supported 04:25:08 INFO - Return code: 0 04:25:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 04:25:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 04:25:08 INFO - Python 2.7.3 04:25:08 INFO - Return code: 0 04:25:08 INFO - grabbing minidump binary from tooltool 04:25:08 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')]} 04:25:08 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 0x107687d30>, '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 0x1075aedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10767cbe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 04:25:08 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 04:25:08 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 04:25:08 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 04:25:08 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 04:25:08 INFO - Return code: 0 04:25:08 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 04:25:08 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 04:25:08 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 04:25:08 INFO - ENV: RUST_BACKTRACE is now 1 04:25:08 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 04:25:08 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 04:25:08 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/JUNOfCotQ22MMyMNx_CQqg/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0363', '--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 04:25:08 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/JUNOfCotQ22MMyMNx_CQqg/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0363 --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 04:25:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KPYi6TKEVE/Render', 04:25:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:25:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:25:08 INFO - 'HOME': '/Users/cltbld', 04:25:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:25:08 INFO - 'LOGNAME': 'cltbld', 04:25:08 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 04:25:08 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 04:25:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:25:08 INFO - 'MOZ_NO_REMOTE': '1', 04:25:08 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 04:25:08 INFO - 'NO_EM_RESTART': '1', 04:25:08 INFO - 'PAGER': '/bin/cat', 04:25:08 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:25:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:25:08 INFO - 'PWD': '/builds/slave/test', 04:25:08 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 04:25:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:25:08 INFO - 'RUST_BACKTRACE': '1', 04:25:08 INFO - 'SHELL': '/bin/bash', 04:25:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.GuSMEHnOYN/Listeners', 04:25:08 INFO - 'TMPDIR': '/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/', 04:25:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:25:08 INFO - 'USER': 'cltbld', 04:25:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:25:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:25:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:25:08 INFO - 'XPC_FLAGS': '0x0', 04:25:08 INFO - 'XPC_SERVICE_NAME': '0', 04:25:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:25:08 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/JUNOfCotQ22MMyMNx_CQqg/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0363', '--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 04:25:08 INFO - mozversion application_buildid: 20170529025115 04:25:08 INFO - mozversion application_changeset: 715d88d0bd826c9e4a5403d6561a2fcc229dd208 04:25:08 INFO - mozversion application_display_name: FirefoxDeveloperEdition 04:25:08 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 04:25:08 INFO - mozversion application_name: Firefox 04:25:08 INFO - mozversion application_remotingname: firefox-dev 04:25:08 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 04:25:08 INFO - mozversion application_vendor: Mozilla 04:25:08 INFO - mozversion application_version: 54.0 04:25:08 INFO - mozversion platform_buildid: 20170529025115 04:25:08 INFO - mozversion platform_changeset: 715d88d0bd826c9e4a5403d6561a2fcc229dd208 04:25:08 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 04:25:08 INFO - mozversion platform_version: 54.0 04:25:08 INFO - using testdate: 1496057108 04:25:08 INFO - actual date: 1496057108 04:25:08 INFO - starting webserver on 'localhost:49265' 04:25:08 INFO - SUITE-START | Running 2 tests 04:25:08 INFO - TEST-START | dromaeo_css 04:25:08 INFO - operating with platform_type : mac_ 04:25:08 INFO - Initialising browser for dromaeo_css test... 04:25:08 INFO - TEST-INFO | started process 30024 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/tmpoe6gHb/profile http://localhost:49265/getInfo.html) 04:25:11 INFO - PROCESS | 30024 | 2017-05-29 04:25:11.444 plugin-container[30028:249511] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8d43, name = 'com.apple.tsm.portname' 04:25:11 INFO - PROCESS | 30024 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:25:11 INFO - PROCESS | 30024 | 2017-05-29 04:25:11.445 plugin-container[30028:249511] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4d07, name = 'com.apple.CFPasteboardClient' 04:25:11 INFO - PROCESS | 30024 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:25:11 INFO - PROCESS | 30024 | 2017-05-29 04:25:11.445 plugin-container[30028:249511] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 04:25:11 INFO - PROCESS | 30024 | __metrics Screen width/height:1600/1200 04:25:11 INFO - PROCESS | 30024 | colorDepth:24 04:25:11 INFO - PROCESS | 30024 | Browser inner width/height: 1024/689 04:25:11 INFO - PROCESS | 30024 | __metrics 04:25:11 ERROR - PROCESS | 30024 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3371: TypeError: this.tabs is undefined 04:25:12 INFO - TEST-INFO | 30024: exit 0 04:25:12 INFO - Browser initialized. 04:25:12 INFO - Running cycle 1/1 for dromaeo_css test... 04:25:12 INFO - TEST-INFO | started process 30029 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/tmpoe6gHb/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:25:14 INFO - PROCESS | 30029 | 2017-05-29 04:25:14.342 plugin-container[30030:249636] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4543, name = 'com.apple.tsm.portname' 04:25:14 INFO - PROCESS | 30029 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:25:14 INFO - PROCESS | 30029 | 2017-05-29 04:25:14.342 plugin-container[30030:249636] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8c0b, name = 'com.apple.CFPasteboardClient' 04:25:14 INFO - PROCESS | 30029 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:25:14 INFO - PROCESS | 30029 | 2017-05-29 04:25:14.342 plugin-container[30030:249636] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 04:25:24 INFO - PROCESS | 30029 | RSS: Main: 202829824 04:25:24 INFO - PROCESS | 30029 | 04:28:26 INFO - PROCESS | 30029 | Cycle 1(1): loaded http://localhost:49265/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49265/tests/dromaeo/cssquery-ext.html) 04:28:28 INFO - PROCESS | 30029 | RSS: Main: 215810048 04:28:28 INFO - PROCESS | 30029 | 04:31:30 INFO - PROCESS | 30029 | Cycle 1(1): loaded http://localhost:49265/tests/dromaeo/cssquery-ext.html (next: http://localhost:49265/tests/dromaeo/cssquery-jquery.html) 04:31:31 INFO - PROCESS | 30029 | RSS: Main: 228818944 04:31:31 INFO - PROCESS | 30029 | 04:34:33 INFO - PROCESS | 30029 | Cycle 1(1): loaded http://localhost:49265/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49265/tests/dromaeo/cssquery-mootools.html) 04:34:33 INFO - PROCESS | 30029 | RSS: Main: 233349120 04:34:33 INFO - PROCESS | 30029 | 04:37:35 INFO - PROCESS | 30029 | Cycle 1(1): loaded http://localhost:49265/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49265/tests/dromaeo/cssquery-prototype.html) 04:37:36 INFO - PROCESS | 30029 | RSS: Main: 236802048 04:37:36 INFO - PROCESS | 30029 | 04:40:39 INFO - PROCESS | 30029 | Cycle 1(1): loaded http://localhost:49265/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49265/tests/dromaeo/cssquery-yui.html) 04:40:39 INFO - PROCESS | 30029 | RSS: Main: 238931968 04:40:39 INFO - PROCESS | 30029 | 04:43:42 INFO - PROCESS | 30029 | Cycle 1(1): loaded http://localhost:49265/tests/dromaeo/cssquery-yui.html (next: http://localhost:49265/tests/dromaeo/cssquery-dojo.html) 04:43:42 INFO - PROCESS | 30029 | RSS: Main: 236969984 04:43:42 INFO - PROCESS | 30029 | 04:43:42 INFO - PROCESS | 30029 | __start_tp_report 04:43:42 INFO - PROCESS | 30029 | _x_x_mozilla_page_load 04:43:42 INFO - PROCESS | 30029 | _x_x_mozilla_page_load_details 04:43:42 INFO - PROCESS | 30029 | |i|pagename|runs| 04:43:42 INFO - PROCESS | 30029 | |0;dojo.html;2926.6780654128042;3381.340638575479;3453.8445769940354;3462.047936817375;3472.0798988268098;5826.650400975939;5870.970645146774;5875.529957603392;5893.116032595113;5902.203202567657;5859.472647461727;5861.824145275638;5883.205767221424;5927.644341339523;5949.315828679703;4845.200541910581;5141.023205590945;5335.1997200419855;5374.811881584145;5421.430749771276;5424.213489044087;5444.292242008553;5444.5918051912995;5446.428125046872;5493.945060549383;5246.606504512158;5280.815171468999;5436;5438.211459338394;5444.7822087116665;5300.257963885059;5305.973470132663;5322.2282768998475;5344.625876188668;5415.864603384947;5207.427183009866;5313.57491400687;5367.221752845854;5372.382176049765;5393.83818485446;5843.707814609253;5895.970520147414;5897.43974322443;5935.10973353996;5944.375840536724;6168.19813424252;6242.063690446424;6268.529860260499;6295.527835412362;6362.427381535637;6326.462250708695;6395.20059992501;6398;6401.455876250524;6405.51958603102;6057.394260573951;6061.333253342217;6061.939380606182;6062.545309101791;6073.696315184223;6284.151639528696;6312.905306420407;6423.646699431576;6462.030695395634;6510.348965103546;6245.094461303085;6282.4974002079725;6366.4588509977175;6372.171617689671;6390.8721825563225;5041.647084704109;5602.943970560324;6399.944009238517;6405.711742971577;6412.198475190601;6159.692015399212;6268.937310626927;6326.367363263729;6353.745850165941;6393.073004414334;6296.212973378328;6322.525810564134;6344.619322840645;6358;6378.521610879203;4763.523647635208;4785.712857228577;4846.103470857832;4900.313956046157;4901.705897646153;4390.385346051556;4414.271645178574;4422.800973956179;4430.13612345596;4435.667324950641;5764.164196191528;5803.593748437654;5825.359210486843;5845.707714614337;5855.209546711225;5235.502627250406;6193.1329613854095;6247;6264.248290205113;6354.237491500957;5819.0689489681445;5820.156077368758;5820.214271073435;5826.883462330729;5836.88326233473;5508.6970216637665;5512.944870551323;5518.44815518453;5542.528885044736;5551.444855514416;6284.748610055638;6325.588836725598;6374.394432528902;6506.869862602722;6644.767433139817;6351.237851457855;6373.776917807854;6378.330275321025;6379.5215358847345;6385.297617262005;6455.7740479083;6495.772647957298;6523.380278873499;6532.542722009413;6566.6388348639375;6138.723757430837;6218.253809542884;6355.396237357444;6356.618602883656;6371.39471750183;6263.4362907338555;6324.936750632435;6328.683565821599;6332.683365831782;6472.482201423969;5165.9524911536855;6107.6640784757265;6248.218972628421;6332.651704156131;6339.96830015847;6055.212822333068;6254.061890716429;6291.3708629138555;6312.368763123651;6321.841953951188;6010.188624535632;6036.185115009417;6105.45050945417;6152.630842149485;6153.292371377349;6292.685365731787;6306.148669929685;6321.11504389395;6331.050342448485;6331.461825744863;5887.4406931341455;5965.582409231312;6080.878382432238;6119.663418512025;6166.969165154145 04:43:42 INFO - PROCESS | 30029 | |1;ext.html;781.8475397297527;782.3780094824614;841.8400503904257;941.8022215334784;981.4062492192219;39020.60979390209;39915.002124946855;41525.58474415255;41549.58450415495;41770.37344439828;40042.99892502689;40263;40289.798551007276;40329.79835100828;40349;8029.478083924555;8223.54770487623;10936.234463587552;11216.383098929555;11368.829467557993;11058.396532502782;11131.499082541264;11136.554537818478;11143.498542565603;11160.386178760165;11140.665780026571;11147.72130696735;11149.72125696856;11169.66491005267;11190.888091119106;11086.833697494543;11120.221584489089;11150.386728729916;11152.052075573627;11158.721031974184;10685.198610104273;10694.037536621741;10743.086837618812;10760.623378181726;10766.838497422545;33911.98264052082;33964;34005.489917651255;34020;34050;3757.8684746034032;3822.2928758179823;3831.4061320495366;3833.674137698299;3846.4807251021034;25059.749402506106;25398.873005634854;25411.61882571763;25711.61432578513;26394.73605263942;3104.363605460875;3129.374125174951;3147.49640057592;3173.4922412414135;3197.3605278944306;364611;368164;389857;391499;458578.70710646565;42224;42463.787681061396;42615.57384426178;42713.1457370857;42785.57214427816;44321.335179971684;44350.669479915654;44526.10947781091;44683;44741;13250.536231231861;13326;13359.866401335863;13382.866171338357;13681.726365472634;11611.88388116108;11831.88168118325;11851.940740296242;11860.932516073595;11862.991645710212;11469.426528673534;11534.653960381202;11561.88438115625;11603;11604.6518604442;4319.676024298127;5044.520770526794;5753.741081651336;5761.711914404263;5814.651120932757;1131.2477202660139;1159.513004538096;1159.5941420502757;1171.7246447084929;1220.1885745978825;26355.736442635327;26652.20043398701;26663;26770.19689409321;26889.596656050177;1066.4347895615335;1284.7901609232106;1294.3981048812348;1310.1657679248938;1323.5573225184596;12622.684432889067;13806.17162970225;13910.652233694238;13926.094803837717;14021.859781402056;434870;437674;438447;439161.8041909834;441187;53988.46011539913;54703;55026.44973550293;55061.72469137709;55193.896122077334;1410.633235358814;1452.9564113076624;1456.4829485532696;1460.2698650674874;1468.2365170111696;24330.87834560816;24956.75043249617;25718.971241150146;25860.353491162117;25932.61101083523;38531.42202866903;38838.029049273995;38891;39015.80492097521;39107.80446097751;377092;377280;377867;378650;379410.20589793747;30550.08349749511;30909.381812364078;31011.224719382197;31115.910943116884;31144.065678029696;33847.49228761521;42016.78991605023;42838.35742463928;43009.56990430181;43023;25858;26889.865550672122;28447;29403.11790646284;29492.705072948997;28511.572326414705;29330.70669293365;29672;29672.813087476257;30212.395752085275;33135.668643314224;33554;33926.830365848014;34047.4892876612;34297.142571435914;26471;26608.866955665097;28753.712462875104;28886.277843054093;28936.710632893402;22241.555168896208;23086.42283942915;23308.417289567897;23425;23455.882720586287 04:43:42 INFO - PROCESS | 30029 | |2;jquery.html;419.78171350897514;429.90327176385307;434.3962878862671;435.5405047674704;439.70759444969093;20474.89762551187;21540.569188616217;22201.44496387591;22245.888770556165;22316.442088947762;21161.470963225926;21537.138514459402;22265.888670556626;22301.44246393841;22447.663285050738;6535.281119076898;6555.311692272327;6852.828679283033;6905.620190889499;6916.723331066752;5840.766369345221;6622.701978410983;6755.4933379996455;6757.695903684346;6817.5227734058635;6464.870702585946;6715.899261511081;6726.293739157391;6756.358145976124;6803.081583986148;6663.866722665568;6693.163354580677;6726.730930762764;6754.831129221809;6761.729530818786;6555.737770489222;6571.638559879205;6580.76967306142;6587.275399706028;6589.835254118638;25382.984680612753;25730;25756.712164391894;26010.959561617514;26393.472130557475;2950.4836653585535;2974.122633823006;3053.297741519441;3053.633563972315;3062.8315442650646;16571.91714041422;16632.584185395343;16801.747973780402;16831.41090061842;17419.73870391945;2060.3715866660664;2112.7359080114984;2126.606577783115;2128.5955668423103;2132.7334083239593;121934;122206;122788;124264.37867810694;126948;20857.852818094845;22829.543409131726;22910;22945.967431465626;23065.7693423067;34621;34760.30479390398;34832.47751283733;35730.28539429197;35774.642253577134;10587.470626468647;10845.620403285848;10976.286541374786;11041.116710663287;11215.495302711397;8791.428557143765;8860.202581767671;8895.06601806801;9005.189532942066;9072.410293330913;10593.735156621146;10607.090723669813;10655.413952232702;10696.893031069745;10771.676849694364;4274.016976095515;4345.826626810744;4384.627306678968;4384.8246070157475;4390.868273951786;920.0293690156815;936.4755736787421;944.6740874398322;979.7942432089341;980.4117529482254;21854;23209.651855222186;23222.954967026188;23240.837958102027;23287.41781455477;747.7233423633181;950.4534892436876;958.8897276813256;983.1495756171004;989.9803202701229;11591;12113.33376664292;12220;12265.26408415498;12343.567975120828;421974;427505;427903;441381;802076;113465.4326728361;119744;142143;152906.23546882195;167637.1618141926;1285.3252042677668;1355.2952464718292;1381;1381.9239941803298;1388.611188867119;18866;19644.312449064786;19893.204271828963;19943.900280499085;19946.501337466678;61851;63512.36487635064;64059.35940640534;64827;65334.346656534726;65573;93981;94344;95573.5221323889;95675.52162239145;10456.32033917793;11116.49975751077;11143.219974602025;11292.604758833402;11560.248583842023;12587.433565489759;12785;12832;12953.287569183785;13028.348582571023;8899.466032038099;10955.945220273848;10986.121110311316;11207.159463040141;11244;8681.522516261666;10399.896001040195;10421.166306695295;10604.098651614699;10662.520186591777;10248.333858299187;11122.110231181648;11279.943600282275;11285;11495.367754773233;11193.72015699614;11278.210525263481;11312.94343528277;11481.703659268347;11521.020713239133;10893.618723344645;11187.552497900318;11288.20982531215;11351.772964540829;11409.828852567052 04:43:42 INFO - PROCESS | 30029 | |3;mootools.html;1578.4238752855197;1596.0503500417249;1614.9757753633698;1616.1434439746934;1633.901965882047;9789.070038346355;10110.191184705225;10212.846807297896;10218.386896786198;10412.958704129584;10057.346272492283;10215.38707677538;10217.744556386095;10239.232057595691;10244.692659220216;2205.382492901986;2244.1808739809935;2250.0662225176616;2251.0770584060538;2256.435891027243;1915.9521011974673;2168.9566208675815;2227.5544891021777;2229.264342766883;2238.4963383238805;1911.1208843931809;1953.3163392812473;2215.4461384653837;2226.0428015953134;2234.273860995175;1980.4949737816837;2204.272590045281;2205.746339171;2212.524307273936;2213.756486786452;2156.4393257753095;2178.237616834111;2186.693862859201;2187.114218741412;2188.245055455865;9729.610815567368;9902.702918912444;9915.801683966352;9919.107280344731;9921.851172232422;4866.7566621668775;5000.149974504343;5008.223725322581;5022.196448568212;5028.069807085701;3005.834679092649;3118.750499959998;3154.810711357326;3200.695933886277;3217.855196516139;1060.2525219720078;1081.6268387406333;1132.4394424759796;1137.6530158301714;1146.9139814513944;12107.93946030273;12915.67710807228;13270.469181232738;13336.86663133366;13465.663358416117;3972.483577134954;3994.2211268802885;4116.835326586963;4122.752634841859;4123.072308730571;10501.632442864464;10540.631077912236;10549.261551691461;10561.260711750258;10566.735831604117;5036.471170527042;5054.949450505522;5057.620678449058;5061.898762024813;5064.18972964324;4554.203014472454;4589.403377560896;4608.9308660370125;4640.2807564826835;4641.466231383367;2911.8252904825777;3408.897733068012;3430.433978393587;3490.616032236452;3537.2748586539697;878.5036454403241;880.423322723617;889.5196593839254;890.2699786175274;894.0121166111353;804.927556519916;807.9192080791873;817.5094943034251;824.826786374856;832.6253186066174;5147.588192944556;5402;5845.766169353264;6000.099985002197;6106.358832322631;1004.6031817432073;1004.6563291861163;1021.6271061062658;1032.7710025070141;1051.8264486359665;4028.194361127786;4071.3485842265095;4091.9055775559023;4174.791260436966;4175.561566035522;11829;12343.321117338633;12373.381330933596;12391.566295179626;12395.380230988414;12334.444949977093;12334.938325308316;12372.319522426173;12372.81440778371;12395.566155184706;9170.266378689688;9285.164335209729;9304.488253146006;9373.953130234442;9379.48412837287;5176.886969301619;6272.780452684134;6553.901691474536;6565.212174539086;6654.401103900672;10766.138708903425;10780.191485638765;10817.242793004414;10822.350658960173;10830.350178989285;9073.637054517745;10969.122470202525;10996;11060.668179954615;11176.329420234812;7746;7868.84262314739;7921.841563168819;7923.405744569065;7926.326262267541;9160.862587061067;9461.479618621042;9522.428654280766;9539.618415263312;9558.808823823623;8153.836923261383;8174.100848906496;8210.917890821016;8218.383621228551;8234.423590348619;7903.582684441281;8036.4776289541;8058.033036035713;8069.7175598853755;8141.3893957952205;7320.743773967885;7465.552066876005;7466.813329666802;7470.3276705096805;7488.363489103487;5106.693598384109;5163.4578369272185;5238.476152384884;5364.946350536445;5406.486383793534;5226.059309324358;5337.759800809051;5353.652012619167;5380.704061276668;5512.5314348280845 04:43:42 INFO - PROCESS | 30029 | |4;prototype.html;374.0667035745815;391.1472988884232;393.5415241243951;398.49192279843203;400.63342042031525;4107.650849677773;4955.454899961011;5354.439297866914;5386.299781028466;5398.055340315449;4220.43024191734;4640.380503207495;4645.837395691143;5014.740418727829;5162.690238585687;4275.572442755715;4567.442232349887;4604.608608268301;4623.69945953512;4637.791299391519;4030.979845100785;4149.464698061722;4473.284274516078;4646.86059418218;4665.86002419928;4538.162044372035;4539.273716205408;4567.543245675439;4577.56513131252;4636.582707556319;4088.7342322748927;4540.54594540545;4589.50840976684;4594.517575654541;4604.953950460486;3548.782011242973;4084.36692312692;4177.644900183488;4439.489458712224;4450.844220452301;4237.978810105929;5042.42012168598;5139.58883289336;5148.613853960968;5215.322008138917;4600.35595016698;4727.149113159629;4832.661713680044;5272.314599102092;5347.51872331492;4860.205381905418;5029.994001199738;5702.287214098238;5713.571482138815;5729.713514324267;4161.731856138412;4471.306947423122;4475.641948644101;4494.280915053576;4524.23088075028;5743.018658562789;5842.766289348421;5893.469587737124;5927.844070406272;5969.343911748842;3781.905452363713;4508.436445444319;4521.84173553924;4553.476350219701;4626.768661566908;3933.3116704576582;4044.8876558945935;4507.842225522091;4651.441826980784;4740.928886066712;4675.041616468616;4923.335349727812;4989.426215985136;5001.624878134082;5018.849434516897;4493.483249426293;5004.324416203766;5037.748112594356;5508.338999320107;5588.2455868458055;1349.6423447786149;1506.1399172022543;1553.3612039298423;1570.285520088357;1595.9920200398924;915.6566287642135;1034.110664828247;1157.9131565132648;1157.9942100289445;1181.952721891132;4331.4152589400655;4566.109608626286;4830.903381932341;5012.448630650625;5019.272205530177;5110;5124.846254612367;5541.6977010402525;5859.062549991981;5933.1903769350765;4751.382320298339;4959.330095729135;5002.948790995674;5025.321581586512;5098.668586541863;2971.8216906985217;2984.6120004399722;2985.253626628447;3000.939981200364;3053.26721586822;5195.480451954774;5458.45415458459;5643.746031428595;5921.7631294747725;5941.465268125889;5506.513241424873;5816.82549523515;5833.241678581842;5852.590318677737;5870.236869206976;4694.600958918462;5290.867728306823;5853.64878107315;5902.940970590239;5937.120496559165;5423.810166644306;5436.836894893159;5469.507744302873;5722.656640601577;5790.392008839011;5310.654807437504;5419.972900135474;5432.864178395572;5670.60305778608;5700.090469692699;5336.012837625051;5521.337439507124;5641.802536911188;5767.480926716616;6081.513478921587;3902.9219415612097;4431.135928493913;4551.903287041557;4585.220512512814;4687.640584230535;4971.527704868032;5284.260203571426;5495;5757.251557297524;5782.913256301074;4604.355390245301;4736.010875432396;4916.631252656137;4982.6512144149565;5597.944020559742;5272.7100009499845;5633.915491267552;5708.743106560298;5715.257016587817;5715.771369145187;4633.4208223972;5305.946940530545;5471.6169868108855;5559.833205003856;5593.272874526286;5344.919826202532;5430.864228394322;5473.850491396851;5593.916091258716;5608;4881.487443818348;4952.380952380952;4993.675411098267;5444.945550544444;5477.698726570077 04:43:42 INFO - PROCESS | 30029 | |5;yui.html;692.3837784371907;739.2570466680986;779;808.2564041082204;854.9675795897542;13629.863701362983;15496.38014479421;16322.265498052586;17753.20110595023;18671.71992420115;18022.008789516603;18151.81848181518;18201.81798182018;18208.908955455205;18216.453506394828;961.0533624379963;993.2649839119072;997.1623835977778;997.840345544713;1000.4547521600708;966.4974213409058;1000.634768309569;1008.1984822066474;1021.438208985059;1022.4325499347859;1042.7341028037877;1046.8063408269495;1048.2295512798087;1049.4175732468477;1049.428061706368;1023.554753682147;1041.2398948767404;1043.342694102718;1045.8326667733202;1046.9948849104792;765.8583162114971;782.4913806026071;784.4548039112819;785.8664027115404;786.8859015442729;15920.203989800464;16069.598260043476;16227.594310142342;16922.984620922784;17147.828521714873;13610.727785444335;13887.375068121959;13958.232297223649;13996.090254133449;14067.226302553356;11677.299362038304;11878.227915185571;11914.821277680841;11922.642320730392;11984.820227696591;3612.7651702639246;3644.817759112034;3697;3717.925641487155;3844.2119365529998;109116.4544177282;113233;116852.66294674059;117311;118181.40909295484;10813.56745730162;10827.350358978487;10911.563537458413;11052.115830733523;11324.943375283236;22571.210007649654;22585.661215081785;22724.97737601812;22800.31599052031;22801.429964251027;7750.844983100306;7776.494527855672;7790.9220907790195;7806.57063861493;7929.643166057541;4184.811683474251;4403.493598236244;4457.598816106566;4458;4513.796879140446;3414.2488652496413;3457.2566898116524;3512.438009918401;3514.2444374459615;3520.8415621297104;626.7399029402837;630.3790766095392;637.0698779781478;637.5793865909363;638.1990601794718;666.7266420767462;679.9388055074968;687.7489716253532;710.3358359933376;730.9875821986587;9431.575579098957;10428.530716117793;10492.842607360895;10581.682549523526;10603.310784798963;590.2209084009066;599.3646734461398;605.7910021042733;611;619.5291578400448;4350.456192975878;4562.292844609091;4633.62930965522;4995.900081998339;5005.599552035901;103607;103788.48105759574;106344.46827765812;106782.93217067885;107505.4624726887;59333.40666593365;62484.687576561824;70172.64913675399;72670.909936351;89034.55482722545;63917.36082639114;68647.31352686409;69220.65389673121;69559.95660065002;70046.64976675084;10741.570337186426;10747.408892510986;11129;11156.609518666808;11322.60370887015;37758;46104;47896.5210347906;49550.50449495459;50671;61203;62794;64243.357566423736;64255.35744642681;64454.03318950126;12230.44962976686;12303.876961230631;12390.380480975735;12639.115261931944;12662.620121396372;14319.28403579796;14414.351354189297;14609.707805843611;14694.191819449605;15217.163056032025;12853.871461285642;13468.93265533666;13487.662808429868;13664.931675341957;14203.928980355033;13241.536546220836;13494.257815820225;13549.525766598123;14067.437302507786;14202.076865003743;13195.60413187606;13488;13581;14026.649333766738;14156.575302740934;9290.535473226177;9340.168414537295;9447.291453140904;9459.195968342769;9495.905040949778;9976.114371768015;11192.776144476902;11305.547778089114;11509.654710358702;11602.18784685064 04:43:42 INFO - PROCESS | 30029 | __end_tp_report 04:43:42 INFO - PROCESS | 30029 | __start_cc_report 04:43:42 INFO - PROCESS | 30029 | _x_x_mozilla_cycle_collect,372 04:43:42 INFO - PROCESS | 30029 | __end_cc_report 04:43:42 INFO - PROCESS | 30029 | __startTimestamp1496058222867__endTimestamp 04:43:42 INFO - PROCESS | 30029 | 04:43:42 INFO - PROCESS | 30029 | ------- Summary: start ------- 04:43:42 INFO - PROCESS | 30029 | Number of tests: 6 04:43:42 INFO - PROCESS | 30029 | 04:43:42 INFO - PROCESS | 30029 | [#0] dojo.html Cycles:165 Average:5847.18 Median:6062.55 stddev:680.90 (11.2%) stddev-sans-first:643.29 04:43:42 INFO - PROCESS | 30029 | Values: 2926.7 3381.3 3453.8 3462.0 3472.1 5826.7 5871.0 5875.5 5893.1 5902.2 5859.5 5861.8 5883.2 5927.6 5949.3 4845.2 5141.0 5335.2 5374.8 5421.4 5424.2 5444.3 5444.6 5446.4 5493.9 5246.6 5280.8 5436.0 5438.2 5444.8 5300.3 5306.0 5322.2 5344.6 5415.9 5207.4 5313.6 5367.2 5372.4 5393.8 5843.7 5896.0 5897.4 5935.1 5944.4 6168.2 6242.1 6268.5 6295.5 6362.4 6326.5 6395.2 6398.0 6401.5 6405.5 6057.4 6061.3 6061.9 6062.5 6073.7 6284.2 6312.9 6423.6 6462.0 6510.3 6245.1 6282.5 6366.5 6372.2 6390.9 5041.6 5602.9 6399.9 6405.7 6412.2 6159.7 6268.9 6326.4 6353.7 6393.1 6296.2 6322.5 6344.6 6358.0 6378.5 4763.5 4785.7 4846.1 4900.3 4901.7 4390.4 4414.3 4422.8 4430.1 4435.7 5764.2 5803.6 5825.4 5845.7 5855.2 5235.5 6193.1 6247.0 6264.2 6354.2 5819.1 5820.2 5820.2 5826.9 5836.9 5508.7 5512.9 5518.4 5542.5 5551.4 6284.7 6325.6 6374.4 6506.9 6644.8 6351.2 6373.8 6378.3 6379.5 6385.3 6455.8 6495.8 6523.4 6532.5 6566.6 6138.7 6218.3 6355.4 6356.6 6371.4 6263.4 6324.9 6328.7 6332.7 6472.5 5166.0 6107.7 6248.2 6332.7 6340.0 6055.2 6254.1 6291.4 6312.4 6321.8 6010.2 6036.2 6105.5 6152.6 6153.3 6292.7 6306.1 6321.1 6331.1 6331.5 5887.4 5965.6 6080.9 6119.7 6167.0 04:43:42 INFO - PROCESS | 30029 | 04:43:42 INFO - PROCESS | 30029 | [#1] ext.html Cycles:180 Average:53334.31 Median:29672.41 stddev:107305.96 (361.6%) stddev-sans-first:107534.44 04:43:42 INFO - PROCESS | 30029 | Values: 781.8 782.4 841.8 941.8 981.4 39020.6 39915.0 41525.6 41549.6 41770.4 40043.0 40263.0 40289.8 40329.8 40349.0 8029.5 8223.5 10936.2 11216.4 11368.8 11058.4 11131.5 11136.6 11143.5 11160.4 11140.7 11147.7 11149.7 11169.7 11190.9 11086.8 11120.2 11150.4 11152.1 11158.7 10685.2 10694.0 10743.1 10760.6 10766.8 33912.0 33964.0 34005.5 34020.0 34050.0 3757.9 3822.3 3831.4 3833.7 3846.5 25059.7 25398.9 25411.6 25711.6 26394.7 3104.4 3129.4 3147.5 3173.5 3197.4 364611.0 368164.0 389857.0 391499.0 458578.7 42224.0 42463.8 42615.6 42713.1 42785.6 44321.3 44350.7 44526.1 44683.0 44741.0 13250.5 13326.0 13359.9 13382.9 13681.7 11611.9 11831.9 11851.9 11860.9 11863.0 11469.4 11534.7 11561.9 11603.0 11604.7 4319.7 5044.5 5753.7 5761.7 5814.7 1131.2 1159.5 1159.6 1171.7 1220.2 26355.7 26652.2 26663.0 26770.2 26889.6 1066.4 1284.8 1294.4 1310.2 1323.6 12622.7 13806.2 13910.7 13926.1 14021.9 434870.0 437674.0 438447.0 439161.8 441187.0 53988.5 54703.0 55026.4 55061.7 55193.9 1410.6 1453.0 1456.5 1460.3 1468.2 24330.9 24956.8 25719.0 25860.4 25932.6 38531.4 38838.0 38891.0 39015.8 39107.8 377092.0 377280.0 377867.0 378650.0 379410.2 30550.1 30909.4 31011.2 31115.9 31144.1 33847.5 42016.8 42838.4 43009.6 43023.0 25858.0 26889.9 28447.0 29403.1 29492.7 28511.6 29330.7 29672.0 29672.8 30212.4 33135.7 33554.0 33926.8 34047.5 34297.1 26471.0 26608.9 28753.7 28886.3 28936.7 22241.6 23086.4 23308.4 23425.0 23455.9 04:43:42 INFO - PROCESS | 30029 | 04:43:42 INFO - PROCESS | 30029 | [#2] jquery.html Cycles:180 Average:35392.62 Median:22869.77 stddev:89177.67 (389.9%) stddev-sans-first:89389.18 04:43:42 INFO - PROCESS | 30029 | Values: 419.8 429.9 434.4 435.5 439.7 20474.9 21540.6 22201.4 22245.9 22316.4 21161.5 21537.1 22265.9 22301.4 22447.7 6535.3 6555.3 6852.8 6905.6 6916.7 5840.8 6622.7 6755.5 6757.7 6817.5 6464.9 6715.9 6726.3 6756.4 6803.1 6663.9 6693.2 6726.7 6754.8 6761.7 6555.7 6571.6 6580.8 6587.3 6589.8 25383.0 25730.0 25756.7 26011.0 26393.5 2950.5 2974.1 3053.3 3053.6 3062.8 16571.9 16632.6 16801.7 16831.4 17419.7 2060.4 2112.7 2126.6 2128.6 2132.7 121934.0 122206.0 122788.0 124264.4 126948.0 20857.9 22829.5 22910.0 22946.0 23065.8 34621.0 34760.3 34832.5 35730.3 35774.6 10587.5 10845.6 10976.3 11041.1 11215.5 8791.4 8860.2 8895.1 9005.2 9072.4 10593.7 10607.1 10655.4 10696.9 10771.7 4274.0 4345.8 4384.6 4384.8 4390.9 920.0 936.5 944.7 979.8 980.4 21854.0 23209.7 23223.0 23240.8 23287.4 747.7 950.5 958.9 983.1 990.0 11591.0 12113.3 12220.0 12265.3 12343.6 421974.0 427505.0 427903.0 441381.0 802076.0 113465.4 119744.0 142143.0 152906.2 167637.2 1285.3 1355.3 1381.0 1381.9 1388.6 18866.0 19644.3 19893.2 19943.9 19946.5 61851.0 63512.4 64059.4 64827.0 65334.3 65573.0 93981.0 94344.0 95573.5 95675.5 10456.3 11116.5 11143.2 11292.6 11560.2 12587.4 12785.0 12832.0 12953.3 13028.3 8899.5 10955.9 10986.1 11207.2 11244.0 8681.5 10399.9 10421.2 10604.1 10662.5 10248.3 11122.1 11279.9 11285.0 11495.4 11193.7 11278.2 11312.9 11481.7 11521.0 10893.6 11187.6 11288.2 11351.8 11409.8 04:43:42 INFO - PROCESS | 30029 | 04:43:42 INFO - PROCESS | 30029 | [#3] mootools.html Cycles:180 Average:6065.49 Median:4049.77 stddev:3755.40 (92.7%) stddev-sans-first:3750.80 04:43:42 INFO - PROCESS | 30029 | Values: 1578.4 1596.1 1615.0 1616.1 1633.9 9789.1 10110.2 10212.8 10218.4 10413.0 10057.3 10215.4 10217.7 10239.2 10244.7 2205.4 2244.2 2250.1 2251.1 2256.4 1916.0 2169.0 2227.6 2229.3 2238.5 1911.1 1953.3 2215.4 2226.0 2234.3 1980.5 2204.3 2205.7 2212.5 2213.8 2156.4 2178.2 2186.7 2187.1 2188.2 9729.6 9902.7 9915.8 9919.1 9921.9 4866.8 5000.1 5008.2 5022.2 5028.1 3005.8 3118.8 3154.8 3200.7 3217.9 1060.3 1081.6 1132.4 1137.7 1146.9 12107.9 12915.7 13270.5 13336.9 13465.7 3972.5 3994.2 4116.8 4122.8 4123.1 10501.6 10540.6 10549.3 10561.3 10566.7 5036.5 5054.9 5057.6 5061.9 5064.2 4554.2 4589.4 4608.9 4640.3 4641.5 2911.8 3408.9 3430.4 3490.6 3537.3 878.5 880.4 889.5 890.3 894.0 804.9 807.9 817.5 824.8 832.6 5147.6 5402.0 5845.8 6000.1 6106.4 1004.6 1004.7 1021.6 1032.8 1051.8 4028.2 4071.3 4091.9 4174.8 4175.6 11829.0 12343.3 12373.4 12391.6 12395.4 12334.4 12334.9 12372.3 12372.8 12395.6 9170.3 9285.2 9304.5 9374.0 9379.5 5176.9 6272.8 6553.9 6565.2 6654.4 10766.1 10780.2 10817.2 10822.4 10830.4 9073.6 10969.1 10996.0 11060.7 11176.3 7746.0 7868.8 7921.8 7923.4 7926.3 9160.9 9461.5 9522.4 9539.6 9558.8 8153.8 8174.1 8210.9 8218.4 8234.4 7903.6 8036.5 8058.0 8069.7 8141.4 7320.7 7465.6 7466.8 7470.3 7488.4 5106.7 5163.5 5238.5 5364.9 5406.5 5226.1 5337.8 5353.7 5380.7 5512.5 04:43:42 INFO - PROCESS | 30029 | 04:43:42 INFO - PROCESS | 30029 | [#4] prototype.html Cycles:180 Average:4658.01 Median:4986.04 stddev:1281.51 (25.7%) stddev-sans-first:1244.11 04:43:42 INFO - PROCESS | 30029 | Values: 374.1 391.1 393.5 398.5 400.6 4107.7 4955.5 5354.4 5386.3 5398.1 4220.4 4640.4 4645.8 5014.7 5162.7 4275.6 4567.4 4604.6 4623.7 4637.8 4031.0 4149.5 4473.3 4646.9 4665.9 4538.2 4539.3 4567.5 4577.6 4636.6 4088.7 4540.5 4589.5 4594.5 4605.0 3548.8 4084.4 4177.6 4439.5 4450.8 4238.0 5042.4 5139.6 5148.6 5215.3 4600.4 4727.1 4832.7 5272.3 5347.5 4860.2 5030.0 5702.3 5713.6 5729.7 4161.7 4471.3 4475.6 4494.3 4524.2 5743.0 5842.8 5893.5 5927.8 5969.3 3781.9 4508.4 4521.8 4553.5 4626.8 3933.3 4044.9 4507.8 4651.4 4740.9 4675.0 4923.3 4989.4 5001.6 5018.8 4493.5 5004.3 5037.7 5508.3 5588.2 1349.6 1506.1 1553.4 1570.3 1596.0 915.7 1034.1 1157.9 1158.0 1182.0 4331.4 4566.1 4830.9 5012.4 5019.3 5110.0 5124.8 5541.7 5859.1 5933.2 4751.4 4959.3 5002.9 5025.3 5098.7 2971.8 2984.6 2985.3 3000.9 3053.3 5195.5 5458.5 5643.7 5921.8 5941.5 5506.5 5816.8 5833.2 5852.6 5870.2 4694.6 5290.9 5853.6 5902.9 5937.1 5423.8 5436.8 5469.5 5722.7 5790.4 5310.7 5420.0 5432.9 5670.6 5700.1 5336.0 5521.3 5641.8 5767.5 6081.5 3902.9 4431.1 4551.9 4585.2 4687.6 4971.5 5284.3 5495.0 5757.3 5782.9 4604.4 4736.0 4916.6 4982.7 5597.9 5272.7 5633.9 5708.7 5715.3 5715.8 4633.4 5305.9 5471.6 5559.8 5593.3 5344.9 5430.9 5473.9 5593.9 5608.0 4881.5 4952.4 4993.7 5444.9 5477.7 04:43:42 INFO - PROCESS | 30029 | 04:43:42 INFO - PROCESS | 30029 | [#5] yui.html Cycles:180 Average:20038.03 Median:18212.68 stddev:28579.50 (156.9%) stddev-sans-first:28622.76 04:43:42 INFO - PROCESS | 30029 | Values: 692.4 739.3 779.0 808.3 855.0 13629.9 15496.4 16322.3 17753.2 18671.7 18022.0 18151.8 18201.8 18208.9 18216.5 961.1 993.3 997.2 997.8 1000.5 966.5 1000.6 1008.2 1021.4 1022.4 1042.7 1046.8 1048.2 1049.4 1049.4 1023.6 1041.2 1043.3 1045.8 1047.0 765.9 782.5 784.5 785.9 786.9 15920.2 16069.6 16227.6 16923.0 17147.8 13610.7 13887.4 13958.2 13996.1 14067.2 11677.3 11878.2 11914.8 11922.6 11984.8 3612.8 3644.8 3697.0 3717.9 3844.2 109116.5 113233.0 116852.7 117311.0 118181.4 10813.6 10827.4 10911.6 11052.1 11324.9 22571.2 22585.7 22725.0 22800.3 22801.4 7750.8 7776.5 7790.9 7806.6 7929.6 4184.8 4403.5 4457.6 4458.0 4513.8 3414.2 3457.3 3512.4 3514.2 3520.8 626.7 630.4 637.1 637.6 638.2 666.7 679.9 687.7 710.3 731.0 9431.6 10428.5 10492.8 10581.7 10603.3 590.2 599.4 605.8 611.0 619.5 4350.5 4562.3 4633.6 4995.9 5005.6 103607.0 103788.5 106344.5 106782.9 107505.5 59333.4 62484.7 70172.6 72670.9 89034.6 63917.4 68647.3 69220.7 69560.0 70046.6 10741.6 10747.4 11129.0 11156.6 11322.6 37758.0 46104.0 47896.5 49550.5 50671.0 61203.0 62794.0 64243.4 64255.4 64454.0 12230.4 12303.9 12390.4 12639.1 12662.6 14319.3 14414.4 14609.7 14694.2 15217.2 12853.9 13468.9 13487.7 13664.9 14203.9 13241.5 13494.3 13549.5 14067.4 14202.1 13195.6 13488.0 13581.0 14026.6 14156.6 9290.5 9340.2 9447.3 9459.2 9495.9 9976.1 11192.8 11305.5 11509.7 11602.2 04:43:42 INFO - PROCESS | 30029 | -------- Summary: end -------- 04:43:42 INFO - PROCESS | 30029 | 04:43:43 INFO - TEST-INFO | 30029: exit 0 04:43:43 INFO - TEST-OK | dromaeo_css | took 1114701ms 04:43:43 INFO - TEST-START | kraken 04:43:43 INFO - operating with platform_type : mac_ 04:43:43 INFO - Initialising browser for kraken test... 04:43:43 INFO - TEST-INFO | started process 30038 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/tmpB_W75s/profile http://localhost:49265/getInfo.html) 04:43:46 INFO - PROCESS | 30038 | 2017-05-29 04:43:46.052 plugin-container[30040:252023] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4647, name = 'com.apple.tsm.portname' 04:43:46 INFO - PROCESS | 30038 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:43:46 INFO - PROCESS | 30038 | 2017-05-29 04:43:46.052 plugin-container[30040:252023] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8d0b, name = 'com.apple.CFPasteboardClient' 04:43:46 INFO - PROCESS | 30038 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:43:46 INFO - PROCESS | 30038 | 2017-05-29 04:43:46.052 plugin-container[30040:252023] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 04:43:46 INFO - PROCESS | 30038 | __metrics Screen width/height:1600/1200 04:43:46 INFO - PROCESS | 30038 | colorDepth:24 04:43:46 INFO - PROCESS | 30038 | Browser inner width/height: 1024/689 04:43:46 INFO - PROCESS | 30038 | __metrics 04:43:46 ERROR - PROCESS | 30038 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3371: TypeError: this.tabs is undefined 04:43:46 INFO - TEST-INFO | 30038: exit 0 04:43:46 INFO - Browser initialized. 04:43:46 INFO - Running cycle 1/1 for kraken test... 04:43:46 INFO - TEST-INFO | started process 30041 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/tmpB_W75s/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:43:49 INFO - PROCESS | 30041 | 2017-05-29 04:43:49.071 plugin-container[30042:252148] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4447, name = 'com.apple.tsm.portname' 04:43:49 INFO - PROCESS | 30041 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:43:49 INFO - PROCESS | 30041 | 2017-05-29 04:43:49.071 plugin-container[30042:252148] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4517, name = 'com.apple.CFPasteboardClient' 04:43:49 INFO - PROCESS | 30041 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:43:49 INFO - PROCESS | 30041 | 2017-05-29 04:43:49.071 plugin-container[30042:252148] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 04:43:58 INFO - PROCESS | 30041 | RSS: Main: 202743808 04:43:58 INFO - PROCESS | 30041 | 04:44:01 INFO - PROCESS | 30041 | Cycle 1(1): loaded http://localhost:49265/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49265/tests/kraken/driver.html?testName=audio-beat-detection) 04:44:02 INFO - PROCESS | 30041 | RSS: Main: 216236032 04:44:02 INFO - PROCESS | 30041 | 04:44:05 INFO - PROCESS | 30041 | Cycle 1(1): loaded http://localhost:49265/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49265/tests/kraken/driver.html?testName=audio-dft) 04:44:05 INFO - PROCESS | 30041 | RSS: Main: 224210944 04:44:05 INFO - PROCESS | 30041 | 04:44:09 INFO - PROCESS | 30041 | Cycle 1(1): loaded http://localhost:49265/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49265/tests/kraken/driver.html?testName=audio-fft) 04:44:09 INFO - PROCESS | 30041 | RSS: Main: 227778560 04:44:09 INFO - PROCESS | 30041 | 04:44:11 INFO - PROCESS | 30041 | Cycle 1(1): loaded http://localhost:49265/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49265/tests/kraken/driver.html?testName=audio-oscillator) 04:44:12 INFO - PROCESS | 30041 | RSS: Main: 229257216 04:44:12 INFO - PROCESS | 30041 | 04:44:14 INFO - PROCESS | 30041 | Cycle 1(1): loaded http://localhost:49265/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49265/tests/kraken/driver.html?testName=imaging-gaussian-blur) 04:44:14 INFO - PROCESS | 30041 | RSS: Main: 229613568 04:44:14 INFO - PROCESS | 30041 | 04:44:17 INFO - PROCESS | 30041 | Cycle 1(1): loaded http://localhost:49265/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49265/tests/kraken/driver.html?testName=imaging-darkroom) 04:44:18 INFO - PROCESS | 30041 | RSS: Main: 233496576 04:44:18 INFO - PROCESS | 30041 | 04:44:21 INFO - PROCESS | 30041 | Cycle 1(1): loaded http://localhost:49265/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49265/tests/kraken/driver.html?testName=imaging-desaturate) 04:44:22 INFO - PROCESS | 30041 | RSS: Main: 234250240 04:44:22 INFO - PROCESS | 30041 | 04:44:25 INFO - PROCESS | 30041 | Cycle 1(1): loaded http://localhost:49265/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49265/tests/kraken/driver.html?testName=json-parse-financial) 04:44:25 INFO - PROCESS | 30041 | RSS: Main: 233582592 04:44:25 INFO - PROCESS | 30041 | 04:44:27 INFO - PROCESS | 30041 | Cycle 1(1): loaded http://localhost:49265/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49265/tests/kraken/driver.html?testName=json-stringify-tinderbox) 04:44:28 INFO - PROCESS | 30041 | RSS: Main: 232509440 04:44:28 INFO - PROCESS | 30041 | 04:44:30 INFO - PROCESS | 30041 | Cycle 1(1): loaded http://localhost:49265/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49265/tests/kraken/driver.html?testName=stanford-crypto-aes) 04:44:30 INFO - PROCESS | 30041 | RSS: Main: 232501248 04:44:30 INFO - PROCESS | 30041 | 04:44:32 INFO - PROCESS | 30041 | Cycle 1(1): loaded http://localhost:49265/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49265/tests/kraken/driver.html?testName=stanford-crypto-ccm) 04:44:33 INFO - PROCESS | 30041 | RSS: Main: 233037824 04:44:33 INFO - PROCESS | 30041 | 04:44:36 INFO - PROCESS | 30041 | Cycle 1(1): loaded http://localhost:49265/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49265/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 04:44:37 INFO - PROCESS | 30041 | RSS: Main: 232886272 04:44:37 INFO - PROCESS | 30041 | 04:44:40 INFO - PROCESS | 30041 | Cycle 1(1): loaded http://localhost:49265/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49265/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 04:44:41 INFO - PROCESS | 30041 | RSS: Main: 232972288 04:44:41 INFO - PROCESS | 30041 | 04:44:42 INFO - PROCESS | 30041 | Cycle 1(1): loaded http://localhost:49265/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49265/tests/kraken/driver.html?testName=ai-astar) 04:44:43 INFO - PROCESS | 30041 | RSS: Main: 234536960 04:44:43 INFO - PROCESS | 30041 | 04:44:43 INFO - PROCESS | 30041 | __start_tp_report 04:44:43 INFO - PROCESS | 30041 | _x_x_mozilla_page_load 04:44:43 INFO - PROCESS | 30041 | _x_x_mozilla_page_load_details 04:44:43 INFO - PROCESS | 30041 | |i|pagename|runs| 04:44:43 INFO - PROCESS | 30041 | |0;ai-astar;108;119;118;119;120;120;121;122;128;177 04:44:43 INFO - PROCESS | 30041 | |1;audio-beat-detection;144;107;112;147;110;115;114;99;149;147 04:44:43 INFO - PROCESS | 30041 | |2;audio-dft;199;202;194;197;195;195;197;196;203;196 04:44:43 INFO - PROCESS | 30041 | |3;audio-fft;84;62;72;91;62;62;60;58;73;63 04:44:43 INFO - PROCESS | 30041 | |4;audio-oscillator;92;71;87;72;70;70;82;70;72;83 04:44:43 INFO - PROCESS | 30041 | |5;imaging-gaussian-blur;88;88;88;88;88;88;92;88;91;88 04:44:43 INFO - PROCESS | 30041 | |6;imaging-darkroom;95;97;118;156;96;96;106;93;98;108 04:44:43 INFO - PROCESS | 30041 | |7;imaging-desaturate;86;142;132;139;74;74;73;93;146;105 04:44:43 INFO - PROCESS | 30041 | |8;json-parse-financial;65;64;63;64;70;68;68;71;91;106 04:44:43 INFO - PROCESS | 30041 | |9;json-stringify-tinderbox;57;62;64;59;57;58;60;61;62;61 04:44:43 INFO - PROCESS | 30041 | |10;stanford-crypto-aes;84;84;85;84;105;77;96;77;87;72 04:44:43 INFO - PROCESS | 30041 | |11;stanford-crypto-ccm;139;150;139;135;133;170;155;156;211;176 04:44:43 INFO - PROCESS | 30041 | |12;stanford-crypto-pbkdf2;169;168;165;169;171;226;222;164;163;217 04:44:43 INFO - PROCESS | 30041 | |13;stanford-crypto-sha256-iterative;67;65;73;86;86;64;59;70;55;71 04:44:43 INFO - PROCESS | 30041 | __end_tp_report 04:44:43 INFO - PROCESS | 30041 | __start_cc_report 04:44:43 INFO - PROCESS | 30041 | _x_x_mozilla_cycle_collect,996 04:44:43 INFO - PROCESS | 30041 | __end_cc_report 04:44:43 INFO - PROCESS | 30041 | __startTimestamp1496058283042__endTimestamp 04:44:43 INFO - PROCESS | 30041 | 04:44:43 INFO - PROCESS | 30041 | ------- Summary: start ------- 04:44:43 INFO - PROCESS | 30041 | Number of tests: 14 04:44:43 INFO - PROCESS | 30041 | 04:44:43 INFO - PROCESS | 30041 | [#0] ai-astar Cycles:10 Average:125.20 Median:120.50 stddev:18.85 (15.6%) stddev-sans-first:18.94 04:44:43 INFO - PROCESS | 30041 | Values: 108.0 119.0 118.0 119.0 120.0 120.0 121.0 122.0 128.0 177.0 04:44:43 INFO - PROCESS | 30041 | 04:44:43 INFO - PROCESS | 30041 | [#1] audio-beat-detection Cycles:10 Average:124.40 Median:145.50 stddev:19.77 (13.6%) stddev-sans-first:19.65 04:44:43 INFO - PROCESS | 30041 | Values: 144.0 107.0 112.0 147.0 110.0 115.0 114.0 99.0 149.0 147.0 04:44:43 INFO - PROCESS | 30041 | 04:44:43 INFO - PROCESS | 30041 | [#2] audio-dft Cycles:10 Average:197.40 Median:197.00 stddev:3.03 (1.5%) stddev-sans-first:3.15 04:44:43 INFO - PROCESS | 30041 | Values: 199.0 202.0 194.0 197.0 195.0 195.0 197.0 196.0 203.0 196.0 04:44:43 INFO - PROCESS | 30041 | 04:44:43 INFO - PROCESS | 30041 | [#3] audio-fft Cycles:10 Average:68.70 Median:67.50 stddev:11.15 (16.5%) stddev-sans-first:10.36 04:44:43 INFO - PROCESS | 30041 | Values: 84.0 62.0 72.0 91.0 62.0 62.0 60.0 58.0 73.0 63.0 04:44:43 INFO - PROCESS | 30041 | 04:44:43 INFO - PROCESS | 30041 | [#4] audio-oscillator Cycles:10 Average:76.90 Median:77.00 stddev:8.29 (10.8%) stddev-sans-first:6.76 04:44:43 INFO - PROCESS | 30041 | Values: 92.0 71.0 87.0 72.0 70.0 70.0 82.0 70.0 72.0 83.0 04:44:43 INFO - PROCESS | 30041 | 04:44:43 INFO - PROCESS | 30041 | [#5] imaging-gaussian-blur Cycles:10 Average:88.70 Median:88.00 stddev:1.49 (1.7%) stddev-sans-first:1.56 04:44:43 INFO - PROCESS | 30041 | Values: 88.0 88.0 88.0 88.0 88.0 88.0 92.0 88.0 91.0 88.0 04:44:43 INFO - PROCESS | 30041 | 04:44:43 INFO - PROCESS | 30041 | [#6] imaging-darkroom Cycles:10 Average:106.30 Median:95.50 stddev:19.10 (20.0%) stddev-sans-first:19.81 04:44:43 INFO - PROCESS | 30041 | Values: 95.0 97.0 118.0 156.0 96.0 96.0 106.0 93.0 98.0 108.0 04:44:43 INFO - PROCESS | 30041 | 04:44:43 INFO - PROCESS | 30041 | [#7] imaging-desaturate Cycles:10 Average:106.40 Median:73.50 stddev:30.49 (41.5%) stddev-sans-first:31.43 04:44:43 INFO - PROCESS | 30041 | Values: 86.0 142.0 132.0 139.0 74.0 74.0 73.0 93.0 146.0 105.0 04:44:43 INFO - PROCESS | 30041 | 04:44:43 INFO - PROCESS | 30041 | [#8] json-parse-financial Cycles:10 Average:73.00 Median:68.00 stddev:14.15 (20.8%) stddev-sans-first:14.71 04:44:43 INFO - PROCESS | 30041 | Values: 65.0 64.0 63.0 64.0 70.0 68.0 68.0 71.0 91.0 106.0 04:44:43 INFO - PROCESS | 30041 | 04:44:43 INFO - PROCESS | 30041 | [#9] json-stringify-tinderbox Cycles:10 Average:60.10 Median:61.00 stddev:2.33 (3.8%) stddev-sans-first:2.19 04:44:43 INFO - PROCESS | 30041 | Values: 57.0 62.0 64.0 59.0 57.0 58.0 60.0 61.0 62.0 61.0 04:44:43 INFO - PROCESS | 30041 | 04:44:43 INFO - PROCESS | 30041 | [#10] stanford-crypto-aes Cycles:10 Average:85.10 Median:84.00 stddev:9.57 (11.4%) stddev-sans-first:10.15 04:44:43 INFO - PROCESS | 30041 | Values: 84.0 84.0 85.0 84.0 105.0 77.0 96.0 77.0 87.0 72.0 04:44:43 INFO - PROCESS | 30041 | 04:44:43 INFO - PROCESS | 30041 | [#11] stanford-crypto-ccm Cycles:10 Average:156.40 Median:155.50 stddev:24.05 (15.5%) stddev-sans-first:24.67 04:44:43 INFO - PROCESS | 30041 | Values: 139.0 150.0 139.0 135.0 133.0 170.0 155.0 156.0 211.0 176.0 04:44:43 INFO - PROCESS | 30041 | 04:44:43 INFO - PROCESS | 30041 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:183.40 Median:170.00 stddev:26.60 (15.6%) stddev-sans-first:27.70 04:44:43 INFO - PROCESS | 30041 | Values: 169.0 168.0 165.0 169.0 171.0 226.0 222.0 164.0 163.0 217.0 04:44:43 INFO - PROCESS | 30041 | 04:44:43 INFO - PROCESS | 30041 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.60 Median:70.50 stddev:10.20 (14.5%) stddev-sans-first:10.78 04:44:43 INFO - PROCESS | 30041 | Values: 67.0 65.0 73.0 86.0 86.0 64.0 59.0 70.0 55.0 71.0 04:44:43 INFO - PROCESS | 30041 | -------- Summary: end -------- 04:44:43 INFO - PROCESS | 30041 | 04:44:43 INFO - TEST-INFO | 30041: exit 0 04:44:43 INFO - TEST-OK | kraken | took 60339ms 04:44:43 INFO - SUITE-END | took 1175s 04:44:43 INFO - Completed test suite (00:19:35) 04:44:43 INFO - javascript benchmark 04:44:43 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7562.9666856301255, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2926.6780654128042, 3381.340638575479, 3453.8445769940354, 3462.047936817375, 3472.0798988268098, 5826.650400975939, 5870.970645146774, 5875.529957603392, 5893.116032595113, 5902.203202567657, 5859.472647461727, 5861.824145275638, 5883.205767221424, 5927.644341339523, 5949.315828679703, 4845.200541910581, 5141.023205590945, 5335.1997200419855, 5374.811881584145, 5421.430749771276, 5424.213489044087, 5444.292242008553, 5444.5918051912995, 5446.428125046872, 5493.945060549383, 5246.606504512158, 5280.815171468999, 5436.0, 5438.211459338394, 5444.7822087116665, 5300.257963885059, 5305.973470132663, 5322.2282768998475, 5344.625876188668, 5415.864603384947, 5207.427183009866, 5313.57491400687, 5367.221752845854, 5372.382176049765, 5393.83818485446, 5843.707814609253, 5895.970520147414, 5897.43974322443, 5935.10973353996, 5944.375840536724, 6168.19813424252, 6242.063690446424, 6268.529860260499, 6295.527835412362, 6362.427381535637, 6326.462250708695, 6395.20059992501, 6398.0, 6401.455876250524, 6405.51958603102, 6057.394260573951, 6061.333253342217, 6061.939380606182, 6062.545309101791, 6073.696315184223, 6284.151639528696, 6312.905306420407, 6423.646699431576, 6462.030695395634, 6510.348965103546, 6245.094461303085, 6282.4974002079725, 6366.4588509977175, 6372.171617689671, 6390.8721825563225, 5041.647084704109, 5602.943970560324, 6399.944009238517, 6405.711742971577, 6412.198475190601, 6159.692015399212, 6268.937310626927, 6326.367363263729, 6353.745850165941, 6393.073004414334, 6296.212973378328, 6322.525810564134, 6344.619322840645, 6358.0, 6378.521610879203, 4763.523647635208, 4785.712857228577, 4846.103470857832, 4900.313956046157, 4901.705897646153, 4390.385346051556, 4414.271645178574, 4422.800973956179, 4430.13612345596, 4435.667324950641, 5764.164196191528, 5803.593748437654, 5825.359210486843, 5845.707714614337, 5855.209546711225, 5235.502627250406, 6193.1329613854095, 6247.0, 6264.248290205113, 6354.237491500957, 5819.0689489681445, 5820.156077368758, 5820.214271073435, 5826.883462330729, 5836.88326233473, 5508.6970216637665, 5512.944870551323, 5518.44815518453, 5542.528885044736, 5551.444855514416, 6284.748610055638, 6325.588836725598, 6374.394432528902, 6506.869862602722, 6644.767433139817, 6351.237851457855, 6373.776917807854, 6378.330275321025, 6379.5215358847345, 6385.297617262005, 6455.7740479083, 6495.772647957298, 6523.380278873499, 6532.542722009413, 6566.6388348639375, 6138.723757430837, 6218.253809542884, 6355.396237357444, 6356.618602883656, 6371.39471750183, 6263.4362907338555, 6324.936750632435, 6328.683565821599, 6332.683365831782, 6472.482201423969, 5165.9524911536855, 6107.6640784757265, 6248.218972628421, 6332.651704156131, 6339.96830015847, 6055.212822333068, 6254.061890716429, 6291.3708629138555, 6312.368763123651, 6321.841953951188, 6010.188624535632, 6036.185115009417, 6105.45050945417, 6152.630842149485, 6153.292371377349, 6292.685365731787, 6306.148669929685, 6321.11504389395, 6331.050342448485, 6331.461825744863, 5887.4406931341455, 5965.582409231312, 6080.878382432238, 6119.663418512025, 6166.969165154145], "value": 5802.122953390169, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [781.8475397297527, 782.3780094824614, 841.8400503904257, 941.8022215334784, 981.4062492192219, 39020.60979390209, 39915.002124946855, 41525.58474415255, 41549.58450415495, 41770.37344439828, 40042.99892502689, 40263.0, 40289.798551007276, 40329.79835100828, 40349.0, 8029.478083924555, 8223.54770487623, 10936.234463587552, 11216.383098929555, 11368.829467557993, 11058.396532502782, 11131.499082541264, 11136.554537818478, 11143.498542565603, 11160.386178760165, 11140.665780026571, 11147.72130696735, 11149.72125696856, 11169.66491005267, 11190.888091119106, 11086.833697494543, 11120.221584489089, 11150.386728729916, 11152.052075573627, 11158.721031974184, 10685.198610104273, 10694.037536621741, 10743.086837618812, 10760.623378181726, 10766.838497422545, 33911.98264052082, 33964.0, 34005.489917651255, 34020.0, 34050.0, 3757.8684746034032, 3822.2928758179823, 3831.4061320495366, 3833.674137698299, 3846.4807251021034, 25059.749402506106, 25398.873005634854, 25411.61882571763, 25711.61432578513, 26394.73605263942, 3104.363605460875, 3129.374125174951, 3147.49640057592, 3173.4922412414135, 3197.3605278944306, 364611.0, 368164.0, 389857.0, 391499.0, 458578.70710646565, 42224.0, 42463.787681061396, 42615.57384426178, 42713.1457370857, 42785.57214427816, 44321.335179971684, 44350.669479915654, 44526.10947781091, 44683.0, 44741.0, 13250.536231231861, 13326.0, 13359.866401335863, 13382.866171338357, 13681.726365472634, 11611.88388116108, 11831.88168118325, 11851.940740296242, 11860.932516073595, 11862.991645710212, 11469.426528673534, 11534.653960381202, 11561.88438115625, 11603.0, 11604.6518604442, 4319.676024298127, 5044.520770526794, 5753.741081651336, 5761.711914404263, 5814.651120932757, 1131.2477202660139, 1159.513004538096, 1159.5941420502757, 1171.7246447084929, 1220.1885745978825, 26355.736442635327, 26652.20043398701, 26663.0, 26770.19689409321, 26889.596656050177, 1066.4347895615335, 1284.7901609232106, 1294.3981048812348, 1310.1657679248938, 1323.5573225184596, 12622.684432889067, 13806.17162970225, 13910.652233694238, 13926.094803837717, 14021.859781402056, 434870.0, 437674.0, 438447.0, 439161.8041909834, 441187.0, 53988.46011539913, 54703.0, 55026.44973550293, 55061.72469137709, 55193.896122077334, 1410.633235358814, 1452.9564113076624, 1456.4829485532696, 1460.2698650674874, 1468.2365170111696, 24330.87834560816, 24956.75043249617, 25718.971241150146, 25860.353491162117, 25932.61101083523, 38531.42202866903, 38838.029049273995, 38891.0, 39015.80492097521, 39107.80446097751, 377092.0, 377280.0, 377867.0, 378650.0, 379410.20589793747, 30550.08349749511, 30909.381812364078, 31011.224719382197, 31115.910943116884, 31144.065678029696, 33847.49228761521, 42016.78991605023, 42838.35742463928, 43009.56990430181, 43023.0, 25858.0, 26889.865550672122, 28447.0, 29403.11790646284, 29492.705072948997, 28511.572326414705, 29330.70669293365, 29672.0, 29672.813087476257, 30212.395752085275, 33135.668643314224, 33554.0, 33926.830365848014, 34047.4892876612, 34297.142571435914, 26471.0, 26608.866955665097, 28753.712462875104, 28886.277843054093, 28936.710632893402, 22241.555168896208, 23086.42283942915, 23308.417289567897, 23425.0, 23455.882720586287], "value": 18250.062222769622, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [419.78171350897514, 429.90327176385307, 434.3962878862671, 435.5405047674704, 439.70759444969093, 20474.89762551187, 21540.569188616217, 22201.44496387591, 22245.888770556165, 22316.442088947762, 21161.470963225926, 21537.138514459402, 22265.888670556626, 22301.44246393841, 22447.663285050738, 6535.281119076898, 6555.311692272327, 6852.828679283033, 6905.620190889499, 6916.723331066752, 5840.766369345221, 6622.701978410983, 6755.4933379996455, 6757.695903684346, 6817.5227734058635, 6464.870702585946, 6715.899261511081, 6726.293739157391, 6756.358145976124, 6803.081583986148, 6663.866722665568, 6693.163354580677, 6726.730930762764, 6754.831129221809, 6761.729530818786, 6555.737770489222, 6571.638559879205, 6580.76967306142, 6587.275399706028, 6589.835254118638, 25382.984680612753, 25730.0, 25756.712164391894, 26010.959561617514, 26393.472130557475, 2950.4836653585535, 2974.122633823006, 3053.297741519441, 3053.633563972315, 3062.8315442650646, 16571.91714041422, 16632.584185395343, 16801.747973780402, 16831.41090061842, 17419.73870391945, 2060.3715866660664, 2112.7359080114984, 2126.606577783115, 2128.5955668423103, 2132.7334083239593, 121934.0, 122206.0, 122788.0, 124264.37867810694, 126948.0, 20857.852818094845, 22829.543409131726, 22910.0, 22945.967431465626, 23065.7693423067, 34621.0, 34760.30479390398, 34832.47751283733, 35730.28539429197, 35774.642253577134, 10587.470626468647, 10845.620403285848, 10976.286541374786, 11041.116710663287, 11215.495302711397, 8791.428557143765, 8860.202581767671, 8895.06601806801, 9005.189532942066, 9072.410293330913, 10593.735156621146, 10607.090723669813, 10655.413952232702, 10696.893031069745, 10771.676849694364, 4274.016976095515, 4345.826626810744, 4384.627306678968, 4384.8246070157475, 4390.868273951786, 920.0293690156815, 936.4755736787421, 944.6740874398322, 979.7942432089341, 980.4117529482254, 21854.0, 23209.651855222186, 23222.954967026188, 23240.837958102027, 23287.41781455477, 747.7233423633181, 950.4534892436876, 958.8897276813256, 983.1495756171004, 989.9803202701229, 11591.0, 12113.33376664292, 12220.0, 12265.26408415498, 12343.567975120828, 421974.0, 427505.0, 427903.0, 441381.0, 802076.0, 113465.4326728361, 119744.0, 142143.0, 152906.23546882195, 167637.1618141926, 1285.3252042677668, 1355.2952464718292, 1381.0, 1381.9239941803298, 1388.611188867119, 18866.0, 19644.312449064786, 19893.204271828963, 19943.900280499085, 19946.501337466678, 61851.0, 63512.36487635064, 64059.35940640534, 64827.0, 65334.346656534726, 65573.0, 93981.0, 94344.0, 95573.5221323889, 95675.52162239145, 10456.32033917793, 11116.49975751077, 11143.219974602025, 11292.604758833402, 11560.248583842023, 12587.433565489759, 12785.0, 12832.0, 12953.287569183785, 13028.348582571023, 8899.466032038099, 10955.945220273848, 10986.121110311316, 11207.159463040141, 11244.0, 8681.522516261666, 10399.896001040195, 10421.166306695295, 10604.098651614699, 10662.520186591777, 10248.333858299187, 11122.110231181648, 11279.943600282275, 11285.0, 11495.367754773233, 11193.72015699614, 11278.210525263481, 11312.94343528277, 11481.703659268347, 11521.020713239133, 10893.618723344645, 11187.552497900318, 11288.20982531215, 11351.772964540829, 11409.828852567052], "value": 11570.666089202974, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1578.4238752855197, 1596.0503500417249, 1614.9757753633698, 1616.1434439746934, 1633.901965882047, 9789.070038346355, 10110.191184705225, 10212.846807297896, 10218.386896786198, 10412.958704129584, 10057.346272492283, 10215.38707677538, 10217.744556386095, 10239.232057595691, 10244.692659220216, 2205.382492901986, 2244.1808739809935, 2250.0662225176616, 2251.0770584060538, 2256.435891027243, 1915.9521011974673, 2168.9566208675815, 2227.5544891021777, 2229.264342766883, 2238.4963383238805, 1911.1208843931809, 1953.3163392812473, 2215.4461384653837, 2226.0428015953134, 2234.273860995175, 1980.4949737816837, 2204.272590045281, 2205.746339171, 2212.524307273936, 2213.756486786452, 2156.4393257753095, 2178.237616834111, 2186.693862859201, 2187.114218741412, 2188.245055455865, 9729.610815567368, 9902.702918912444, 9915.801683966352, 9919.107280344731, 9921.851172232422, 4866.7566621668775, 5000.149974504343, 5008.223725322581, 5022.196448568212, 5028.069807085701, 3005.834679092649, 3118.750499959998, 3154.810711357326, 3200.695933886277, 3217.855196516139, 1060.2525219720078, 1081.6268387406333, 1132.4394424759796, 1137.6530158301714, 1146.9139814513944, 12107.93946030273, 12915.67710807228, 13270.469181232738, 13336.86663133366, 13465.663358416117, 3972.483577134954, 3994.2211268802885, 4116.835326586963, 4122.752634841859, 4123.072308730571, 10501.632442864464, 10540.631077912236, 10549.261551691461, 10561.260711750258, 10566.735831604117, 5036.471170527042, 5054.949450505522, 5057.620678449058, 5061.898762024813, 5064.18972964324, 4554.203014472454, 4589.403377560896, 4608.9308660370125, 4640.2807564826835, 4641.466231383367, 2911.8252904825777, 3408.897733068012, 3430.433978393587, 3490.616032236452, 3537.2748586539697, 878.5036454403241, 880.423322723617, 889.5196593839254, 890.2699786175274, 894.0121166111353, 804.927556519916, 807.9192080791873, 817.5094943034251, 824.826786374856, 832.6253186066174, 5147.588192944556, 5402.0, 5845.766169353264, 6000.099985002197, 6106.358832322631, 1004.6031817432073, 1004.6563291861163, 1021.6271061062658, 1032.7710025070141, 1051.8264486359665, 4028.194361127786, 4071.3485842265095, 4091.9055775559023, 4174.791260436966, 4175.561566035522, 11829.0, 12343.321117338633, 12373.381330933596, 12391.566295179626, 12395.380230988414, 12334.444949977093, 12334.938325308316, 12372.319522426173, 12372.81440778371, 12395.566155184706, 9170.266378689688, 9285.164335209729, 9304.488253146006, 9373.953130234442, 9379.48412837287, 5176.886969301619, 6272.780452684134, 6553.901691474536, 6565.212174539086, 6654.401103900672, 10766.138708903425, 10780.191485638765, 10817.242793004414, 10822.350658960173, 10830.350178989285, 9073.637054517745, 10969.122470202525, 10996.0, 11060.668179954615, 11176.329420234812, 7746.0, 7868.84262314739, 7921.841563168819, 7923.405744569065, 7926.326262267541, 9160.862587061067, 9461.479618621042, 9522.428654280766, 9539.618415263312, 9558.808823823623, 8153.836923261383, 8174.100848906496, 8210.917890821016, 8218.383621228551, 8234.423590348619, 7903.582684441281, 8036.4776289541, 8058.033036035713, 8069.7175598853755, 8141.3893957952205, 7320.743773967885, 7465.552066876005, 7466.813329666802, 7470.3276705096805, 7488.363489103487, 5106.693598384109, 5163.4578369272185, 5238.476152384884, 5364.946350536445, 5406.486383793534, 5226.059309324358, 5337.759800809051, 5353.652012619167, 5380.704061276668, 5512.5314348280845], "value": 4652.649736664027, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [374.0667035745815, 391.1472988884232, 393.5415241243951, 398.49192279843203, 400.63342042031525, 4107.650849677773, 4955.454899961011, 5354.439297866914, 5386.299781028466, 5398.055340315449, 4220.43024191734, 4640.380503207495, 4645.837395691143, 5014.740418727829, 5162.690238585687, 4275.572442755715, 4567.442232349887, 4604.608608268301, 4623.69945953512, 4637.791299391519, 4030.979845100785, 4149.464698061722, 4473.284274516078, 4646.86059418218, 4665.86002419928, 4538.162044372035, 4539.273716205408, 4567.543245675439, 4577.56513131252, 4636.582707556319, 4088.7342322748927, 4540.54594540545, 4589.50840976684, 4594.517575654541, 4604.953950460486, 3548.782011242973, 4084.36692312692, 4177.644900183488, 4439.489458712224, 4450.844220452301, 4237.978810105929, 5042.42012168598, 5139.58883289336, 5148.613853960968, 5215.322008138917, 4600.35595016698, 4727.149113159629, 4832.661713680044, 5272.314599102092, 5347.51872331492, 4860.205381905418, 5029.994001199738, 5702.287214098238, 5713.571482138815, 5729.713514324267, 4161.731856138412, 4471.306947423122, 4475.641948644101, 4494.280915053576, 4524.23088075028, 5743.018658562789, 5842.766289348421, 5893.469587737124, 5927.844070406272, 5969.343911748842, 3781.905452363713, 4508.436445444319, 4521.84173553924, 4553.476350219701, 4626.768661566908, 3933.3116704576582, 4044.8876558945935, 4507.842225522091, 4651.441826980784, 4740.928886066712, 4675.041616468616, 4923.335349727812, 4989.426215985136, 5001.624878134082, 5018.849434516897, 4493.483249426293, 5004.324416203766, 5037.748112594356, 5508.338999320107, 5588.2455868458055, 1349.6423447786149, 1506.1399172022543, 1553.3612039298423, 1570.285520088357, 1595.9920200398924, 915.6566287642135, 1034.110664828247, 1157.9131565132648, 1157.9942100289445, 1181.952721891132, 4331.4152589400655, 4566.109608626286, 4830.903381932341, 5012.448630650625, 5019.272205530177, 5110.0, 5124.846254612367, 5541.6977010402525, 5859.062549991981, 5933.1903769350765, 4751.382320298339, 4959.330095729135, 5002.948790995674, 5025.321581586512, 5098.668586541863, 2971.8216906985217, 2984.6120004399722, 2985.253626628447, 3000.939981200364, 3053.26721586822, 5195.480451954774, 5458.45415458459, 5643.746031428595, 5921.7631294747725, 5941.465268125889, 5506.513241424873, 5816.82549523515, 5833.241678581842, 5852.590318677737, 5870.236869206976, 4694.600958918462, 5290.867728306823, 5853.64878107315, 5902.940970590239, 5937.120496559165, 5423.810166644306, 5436.836894893159, 5469.507744302873, 5722.656640601577, 5790.392008839011, 5310.654807437504, 5419.972900135474, 5432.864178395572, 5670.60305778608, 5700.090469692699, 5336.012837625051, 5521.337439507124, 5641.802536911188, 5767.480926716616, 6081.513478921587, 3902.9219415612097, 4431.135928493913, 4551.903287041557, 4585.220512512814, 4687.640584230535, 4971.527704868032, 5284.260203571426, 5495.0, 5757.251557297524, 5782.913256301074, 4604.355390245301, 4736.010875432396, 4916.631252656137, 4982.6512144149565, 5597.944020559742, 5272.7100009499845, 5633.915491267552, 5708.743106560298, 5715.257016587817, 5715.771369145187, 4633.4208223972, 5305.946940530545, 5471.6169868108855, 5559.833205003856, 5593.272874526286, 5344.919826202532, 5430.864228394322, 5473.850491396851, 5593.916091258716, 5608.0, 4881.487443818348, 4952.380952380952, 4993.675411098267, 5444.945550544444, 5477.698726570077], "value": 4280.934006729468, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [692.3837784371907, 739.2570466680986, 779.0, 808.2564041082204, 854.9675795897542, 13629.863701362983, 15496.38014479421, 16322.265498052586, 17753.20110595023, 18671.71992420115, 18022.008789516603, 18151.81848181518, 18201.81798182018, 18208.908955455205, 18216.453506394828, 961.0533624379963, 993.2649839119072, 997.1623835977778, 997.840345544713, 1000.4547521600708, 966.4974213409058, 1000.634768309569, 1008.1984822066474, 1021.438208985059, 1022.4325499347859, 1042.7341028037877, 1046.8063408269495, 1048.2295512798087, 1049.4175732468477, 1049.428061706368, 1023.554753682147, 1041.2398948767404, 1043.342694102718, 1045.8326667733202, 1046.9948849104792, 765.8583162114971, 782.4913806026071, 784.4548039112819, 785.8664027115404, 786.8859015442729, 15920.203989800464, 16069.598260043476, 16227.594310142342, 16922.984620922784, 17147.828521714873, 13610.727785444335, 13887.375068121959, 13958.232297223649, 13996.090254133449, 14067.226302553356, 11677.299362038304, 11878.227915185571, 11914.821277680841, 11922.642320730392, 11984.820227696591, 3612.7651702639246, 3644.817759112034, 3697.0, 3717.925641487155, 3844.2119365529998, 109116.4544177282, 113233.0, 116852.66294674059, 117311.0, 118181.40909295484, 10813.56745730162, 10827.350358978487, 10911.563537458413, 11052.115830733523, 11324.943375283236, 22571.210007649654, 22585.661215081785, 22724.97737601812, 22800.31599052031, 22801.429964251027, 7750.844983100306, 7776.494527855672, 7790.9220907790195, 7806.57063861493, 7929.643166057541, 4184.811683474251, 4403.493598236244, 4457.598816106566, 4458.0, 4513.796879140446, 3414.2488652496413, 3457.2566898116524, 3512.438009918401, 3514.2444374459615, 3520.8415621297104, 626.7399029402837, 630.3790766095392, 637.0698779781478, 637.5793865909363, 638.1990601794718, 666.7266420767462, 679.9388055074968, 687.7489716253532, 710.3358359933376, 730.9875821986587, 9431.575579098957, 10428.530716117793, 10492.842607360895, 10581.682549523526, 10603.310784798963, 590.2209084009066, 599.3646734461398, 605.7910021042733, 611.0, 619.5291578400448, 4350.456192975878, 4562.292844609091, 4633.62930965522, 4995.900081998339, 5005.599552035901, 103607.0, 103788.48105759574, 106344.46827765812, 106782.93217067885, 107505.4624726887, 59333.40666593365, 62484.687576561824, 70172.64913675399, 72670.909936351, 89034.55482722545, 63917.36082639114, 68647.31352686409, 69220.65389673121, 69559.95660065002, 70046.64976675084, 10741.570337186426, 10747.408892510986, 11129.0, 11156.609518666808, 11322.60370887015, 37758.0, 46104.0, 47896.5210347906, 49550.50449495459, 50671.0, 61203.0, 62794.0, 64243.357566423736, 64255.35744642681, 64454.03318950126, 12230.44962976686, 12303.876961230631, 12390.380480975735, 12639.115261931944, 12662.620121396372, 14319.28403579796, 14414.351354189297, 14609.707805843611, 14694.191819449605, 15217.163056032025, 12853.871461285642, 13468.93265533666, 13487.662808429868, 13664.931675341957, 14203.928980355033, 13241.536546220836, 13494.257815820225, 13549.525766598123, 14067.437302507786, 14202.076865003743, 13195.60413187606, 13488.0, 13581.0, 14026.649333766738, 14156.575302740934, 9290.535473226177, 9340.168414537295, 9447.291453140904, 9459.195968342769, 9495.905040949778, 9976.114371768015, 11192.776144476902, 11305.547778089114, 11509.654710358702, 11602.18784685064], "value": 7667.636831596528, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1521.6, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [108.0, 119.0, 118.0, 119.0, 120.0, 120.0, 121.0, 122.0, 128.0, 177.0], "value": 125.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 107.0, 112.0, 147.0, 110.0, 115.0, 114.0, 99.0, 149.0, 147.0], "value": 124.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [199.0, 202.0, 194.0, 197.0, 195.0, 195.0, 197.0, 196.0, 203.0, 196.0], "value": 197.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 62.0, 72.0, 91.0, 62.0, 62.0, 60.0, 58.0, 73.0, 63.0], "value": 68.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 71.0, 87.0, 72.0, 70.0, 70.0, 82.0, 70.0, 72.0, 83.0], "value": 76.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 88.0, 88.0, 88.0, 88.0, 88.0, 92.0, 88.0, 91.0, 88.0], "value": 88.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 97.0, 118.0, 156.0, 96.0, 96.0, 106.0, 93.0, 98.0, 108.0], "value": 106.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 142.0, 132.0, 139.0, 74.0, 74.0, 73.0, 93.0, 146.0, 105.0], "value": 106.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 64.0, 63.0, 64.0, 70.0, 68.0, 68.0, 71.0, 91.0, 106.0], "value": 73.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 62.0, 64.0, 59.0, 57.0, 58.0, 60.0, 61.0, 62.0, 61.0], "value": 60.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 84.0, 85.0, 84.0, 105.0, 77.0, 96.0, 77.0, 87.0, 72.0], "value": 85.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [139.0, 150.0, 139.0, 135.0, 133.0, 170.0, 155.0, 156.0, 211.0, 176.0], "value": 156.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [169.0, 168.0, 165.0, 169.0, 171.0, 226.0, 222.0, 164.0, 163.0, 217.0], "value": 183.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 65.0, 73.0, 86.0, 86.0, 64.0, 59.0, 70.0, 55.0, 71.0], "value": 69.6, "unit": "score"}]}]} 04:44:43 INFO - Return code: 0 04:44:43 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 04:44:43 ERROR - # TBPL SUCCESS # 04:44:43 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 04:44:43 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 04:44:43 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 04:44:43 INFO - 2017-05-29 04:44:43.887 screenresolution[30046:252537] starting screenresolution argv=screenresolution get 04:44:43 INFO - 2017-05-29 04:44:43.889 screenresolution[30046:252537] Display 0: 1600x1200x32@60 04:44:43 INFO - 2017-05-29 04:44:43.896 screenresolution[30047:252540] starting screenresolution argv=screenresolution list 04:44:43 INFO - Available Modes on Display 0 04:44:43 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 04:44:43 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 04:44:43 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 04:44:43 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 04:44:44 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 04:44:44 INFO - Intel Iris: 04:44:44 INFO - Chipset Model: Intel Iris 04:44:44 INFO - Type: GPU 04:44:44 INFO - Bus: Built-In 04:44:44 INFO - VRAM (Dynamic, Max): 1536 MB 04:44:44 INFO - Vendor: Intel (0x8086) 04:44:44 INFO - Device ID: 0x0a2e 04:44:44 INFO - Revision ID: 0x0009 04:44:44 INFO - Displays: 04:44:44 INFO - Display: 04:44:44 INFO - Resolution: 1600 x 1200 @ 60 Hz 04:44:44 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 04:44:44 INFO - Display Serial Number: 1600x1200 60 04:44:44 INFO - Main Display: Yes 04:44:44 INFO - Mirror: Off 04:44:44 INFO - Online: Yes 04:44:44 INFO - Rotation: Supported 04:44:44 INFO - Return code: 0 04:44:44 INFO - Running post-action listener: _resource_record_post_action 04:44:44 INFO - [mozharness: 2017-05-29 11:44:44.139845Z] Finished run-tests step (success) 04:44:44 INFO - Running post-run listener: _resource_record_post_run 04:44:44 INFO - Total resource usage - Wall time: 1195s; CPU: 55.0%; Read bytes: 31903744; Write bytes: 515535872; Read time: 189; Write time: 1605 04:44:44 INFO - TinderboxPrint: CPU usage
54.7% 04:44:44 INFO - TinderboxPrint: I/O read bytes / time
31,903,744 / 189 04:44:44 INFO - TinderboxPrint: I/O write bytes / time
515,535,872 / 1,605 04:44:44 INFO - TinderboxPrint: CPU idle
2,166.6 (45.3%) 04:44:44 INFO - TinderboxPrint: CPU system
960.4 (20.1%) 04:44:44 INFO - TinderboxPrint: CPU user
1,654.7 (34.6%) 04:44:44 INFO - TinderboxPrint: Swap in / out
235,024,384 / 0 04:44:44 INFO - install - Wall time: 20s; CPU: 54.0%; Read bytes: 209607168; Write bytes: 214423552; Read time: 15945; Write time: 455 04:44:44 INFO - run-tests - Wall time: 1176s; CPU: 55.0%; Read bytes: 29548544; Write bytes: 299756544; Read time: 178; Write time: 1144 04:44:44 INFO - Running post-run listener: _upload_blobber_files 04:44:44 INFO - Blob upload gear active. 04:44:44 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 04:44:44 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 04:44:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Beta', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 04:44:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Beta -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 04:44:45 INFO - (blobuploader) - INFO - Open directory for files ... 04:44:45 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 04:44:45 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:44:45 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:44:45 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 04:44:45 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:44:45 INFO - (blobuploader) - INFO - Done attempting. 04:44:45 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 04:44:45 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:44:45 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:44:46 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 04:44:46 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:44:46 INFO - (blobuploader) - INFO - Done attempting. 04:44:46 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 04:44:46 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:44:46 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:44:47 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 04:44:47 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:44:47 INFO - (blobuploader) - INFO - Done attempting. 04:44:47 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 04:44:47 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:44:47 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:44:49 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 04:44:49 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:44:49 INFO - (blobuploader) - INFO - Done attempting. 04:44:49 INFO - (blobuploader) - INFO - Iteration through files over. 04:44:49 INFO - Return code: 0 04:44:49 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 04:44:49 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 04:44:49 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/d05ad93b8197070b4bd2613802629c278769e575510754f52cb742d16ef3bc115fe039a8d1da88af44aea7dddf70f93c42833cefe82528d37670fd9cccb200d3", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/73cb6948dea084ff690067fd9b3f6f44e15083898f133deda8fcd805f1d7bceac29324481771c39d9754cf9f6a1969aaa2f036c01bd7bb6ae08344f3b58689c7", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/737dfa4a51b4de57d270095b7d3607eb35f9a987c64c5367a97943c3b7c258bdb9756c19716f3dab9b549a78e373bca100c3aa00060bb7b3ee00551720a83ded", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/2e04134069d5a33cd9e4385af66d6c42cf7f8e58eea70901bf7fe0ea4fe359bc80a25779fc7c0186a6c27b6ba4bf34800dcf40c3818f6233917d735942d58f75"} 04:44:49 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 04:44:49 INFO - Writing to file /builds/slave/test/properties/blobber_files 04:44:49 INFO - Contents: 04:44:49 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/d05ad93b8197070b4bd2613802629c278769e575510754f52cb742d16ef3bc115fe039a8d1da88af44aea7dddf70f93c42833cefe82528d37670fd9cccb200d3", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/73cb6948dea084ff690067fd9b3f6f44e15083898f133deda8fcd805f1d7bceac29324481771c39d9754cf9f6a1969aaa2f036c01bd7bb6ae08344f3b58689c7", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/737dfa4a51b4de57d270095b7d3607eb35f9a987c64c5367a97943c3b7c258bdb9756c19716f3dab9b549a78e373bca100c3aa00060bb7b3ee00551720a83ded", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/2e04134069d5a33cd9e4385af66d6c42cf7f8e58eea70901bf7fe0ea4fe359bc80a25779fc7c0186a6c27b6ba4bf34800dcf40c3818f6233917d735942d58f75"} 04:44:49 INFO - Running post-run listener: copy_logs_to_upload_dir 04:44:49 INFO - Copying logs to upload dir... 04:44:49 INFO - mkdir: /builds/slave/test/build/upload/logs 04:44:49 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1308.205856 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 48 secs) (at 2017-05-29 04:44:49.648883) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-29 04:44:49.652323) ========= 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.KPYi6TKEVE/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.GuSMEHnOYN/Listeners TMPDIR=/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/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/d05ad93b8197070b4bd2613802629c278769e575510754f52cb742d16ef3bc115fe039a8d1da88af44aea7dddf70f93c42833cefe82528d37670fd9cccb200d3", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/73cb6948dea084ff690067fd9b3f6f44e15083898f133deda8fcd805f1d7bceac29324481771c39d9754cf9f6a1969aaa2f036c01bd7bb6ae08344f3b58689c7", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/737dfa4a51b4de57d270095b7d3607eb35f9a987c64c5367a97943c3b7c258bdb9756c19716f3dab9b549a78e373bca100c3aa00060bb7b3ee00551720a83ded", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/2e04134069d5a33cd9e4385af66d6c42cf7f8e58eea70901bf7fe0ea4fe359bc80a25779fc7c0186a6c27b6ba4bf34800dcf40c3818f6233917d735942d58f75"} build_url:https://queue.taskcluster.net/v1/task/JUNOfCotQ22MMyMNx_CQqg/artifacts/public/build/firefox-54.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011396 build_url: 'https://queue.taskcluster.net/v1/task/JUNOfCotQ22MMyMNx_CQqg/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/d05ad93b8197070b4bd2613802629c278769e575510754f52cb742d16ef3bc115fe039a8d1da88af44aea7dddf70f93c42833cefe82528d37670fd9cccb200d3", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/73cb6948dea084ff690067fd9b3f6f44e15083898f133deda8fcd805f1d7bceac29324481771c39d9754cf9f6a1969aaa2f036c01bd7bb6ae08344f3b58689c7", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/737dfa4a51b4de57d270095b7d3607eb35f9a987c64c5367a97943c3b7c258bdb9756c19716f3dab9b549a78e373bca100c3aa00060bb7b3ee00551720a83ded", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/2e04134069d5a33cd9e4385af66d6c42cf7f8e58eea70901bf7fe0ea4fe359bc80a25779fc7c0186a6c27b6ba4bf34800dcf40c3818f6233917d735942d58f75"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-29 04:44:49.690181) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-29 04:44:49.690995) ========= 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.KPYi6TKEVE/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.GuSMEHnOYN/Listeners TMPDIR=/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/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.005384 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-29 04:44:49.744964) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-29 04:44:49.745629) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-29 04:44:49.746360) ========= ========= Total master_lag: 0.21 =========