builder: cedar_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0188 starttime: 1496675967.82 results: success (0) revision: abd2dbf7250d742ed375983f79c2dff104a5b428 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-06-05 08:19:27.824577) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-06-05 08:19:27.825019) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-05 08:19:27.825317) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-05 08:19:27.894623) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-05 08:19:27.895017) ========= 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.RgFP1d71XQ/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.5CUoSBfjYo/Listeners TMPDIR=/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-06-05 08:19:27-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 166M=0s 2017-06-05 08:19:28 (166 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.489889 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-05 08:19:28.408623) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-05 08:19:28.412073) ========= 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.RgFP1d71XQ/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.5CUoSBfjYo/Listeners TMPDIR=/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/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.029826 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-05 08:19:28.463056) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-06-05 08:19:28.463400) ========= bash -c 'python archiver_client.py mozharness --repo projects/cedar --rev abd2dbf7250d742ed375983f79c2dff104a5b428 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'python archiver_client.py mozharness --repo projects/cedar --rev abd2dbf7250d742ed375983f79c2dff104a5b428 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.RgFP1d71XQ/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.5CUoSBfjYo/Listeners TMPDIR=/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-06-05 08:19:28,560 truncating revision to first 12 chars 2017-06-05 08:19:28,560 Setting DEBUG logging. 2017-06-05 08:19:28,560 attempt 1/10 2017-06-05 08:19:28,560 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/projects/cedar/abd2dbf7250d?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-06-05 08:19:30,044 unpacking tar archive at: cedar-abd2dbf7250d/testing/mozharness/ program finished with exit code 0 elapsedTime=1.758843 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-06-05 08:19:30.247357) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-05 08:19:30.247683) ========= script_repo_revision: abd2dbf7250d742ed375983f79c2dff104a5b428 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-05 08:19:30.248096) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-05 08:19:30.248374) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-05 08:19:30.270443) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 27 secs) (at 2017-06-05 08:19:30.270841) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Cedar --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Cedar 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', 'Cedar', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Cedar'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.RgFP1d71XQ/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.5CUoSBfjYo/Listeners TMPDIR=/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/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 08:19:30 INFO - MultiFileLogger online at 20170605 08:19:30 in /builds/slave/test 08:19:30 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Cedar --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Cedar 08:19:30 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 08:19:30 INFO - {'append_to_log': False, 08:19:30 INFO - 'base_work_dir': '/builds/slave/test', 08:19:30 INFO - 'blob_upload_branch': 'Cedar', 08:19:30 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 08:19:30 INFO - 'branch': 'Cedar', 08:19:30 INFO - 'buildbot_json_path': 'buildprops.json', 08:19:30 INFO - 'config_files': ('talos/mac_config.py',), 08:19:30 INFO - 'default_actions': ('clobber', 08:19:30 INFO - 'read-buildbot-config', 08:19:30 INFO - 'download-and-extract', 08:19:30 INFO - 'populate-webroot', 08:19:30 INFO - 'create-virtualenv', 08:19:30 INFO - 'install', 08:19:30 INFO - 'setup-mitmproxy', 08:19:30 INFO - 'run-tests'), 08:19:30 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 08:19:30 INFO - 'download_minidump_stackwalk': True, 08:19:30 INFO - 'download_symbols': 'ondemand', 08:19:30 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 08:19:30 INFO - 'tooltool.py': '/tools/tooltool.py', 08:19:30 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 08:19:30 INFO - '/tools/misc-python/virtualenv.py')}, 08:19:30 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 08:19:30 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 08:19:30 INFO - 'gecko_profile': False, 08:19:30 INFO - 'gecko_profile_interval': 0, 08:19:30 INFO - 'installer_path': 'installer.exe', 08:19:30 INFO - 'log_level': 'info', 08:19:30 INFO - 'log_name': 'talos', 08:19:30 INFO - 'log_to_console': True, 08:19:30 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 08:19:30 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 08:19:30 INFO - 'opt_config_files': (), 08:19:30 INFO - 'pip_index': False, 08:19:30 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:19:30 INFO - 'cmd': ('bash', 08:19:30 INFO - '-c', 08:19:30 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:19:30 INFO - 'enabled': True, 08:19:30 INFO - 'halt_on_failure': False, 08:19:30 INFO - 'name': 'check_screen_resolution'},), 08:19:30 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:19:30 INFO - 'cmd': ('bash', 08:19:30 INFO - '-c', 08:19:30 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:19:30 INFO - 'enabled': True, 08:19:30 INFO - 'halt_on_failure': False, 08:19:30 INFO - 'name': 'check_screen_resolution'},), 08:19:30 INFO - 'run_cmd_checks_enabled': True, 08:19:30 INFO - 'suite': 'dromaeojs-e10s', 08:19:30 INFO - 'system_bits': '32', 08:19:30 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 08:19:30 INFO - 'title': 't-yosemite-r7-0188', 08:19:30 INFO - 'tooltool_cache': '/builds/tooltool_cache', 08:19:30 INFO - 'use_talos_json': True, 08:19:30 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 08:19:30 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 08:19:30 INFO - 'work_dir': 'build'} 08:19:30 INFO - [mozharness: 2017-06-05 15:19:30.482776Z] Running clobber step. 08:19:30 INFO - Running pre-action listener: _resource_record_pre_action 08:19:30 INFO - Running main action method: clobber 08:19:30 INFO - rmtree: /builds/slave/test/build 08:19:30 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 08:19:35 INFO - Running post-action listener: _resource_record_post_action 08:19:35 INFO - [mozharness: 2017-06-05 15:19:35.530659Z] Finished clobber step (success) 08:19:35 INFO - [mozharness: 2017-06-05 15:19:35.530784Z] Running read-buildbot-config step. 08:19:35 INFO - Running pre-action listener: _resource_record_pre_action 08:19:35 INFO - Running main action method: read_buildbot_config 08:19:35 INFO - Using buildbot properties: 08:19:35 INFO - { 08:19:35 INFO - "project": "", 08:19:35 INFO - "product": "firefox", 08:19:35 INFO - "who": "mconley@mozilla.com", 08:19:35 INFO - "installer_path": "public/build/firefox-55.0a1.en-US.mac.dmg", 08:19:35 INFO - "builddir": "cedar_yosemite_r7_test-dromaeojs-e10s", 08:19:35 INFO - "repository": "https://hg.mozilla.org/projects/cedar/", 08:19:35 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 cedar talos dromaeojs-e10s", 08:19:35 INFO - "stage_platform": "macosx64", 08:19:35 INFO - "basedir": "/builds/slave/test", 08:19:35 INFO - "buildnumber": 0, 08:19:35 INFO - "platform": "yosemite_r7", 08:19:35 INFO - "parent_task_id": "HU4fY-aZS-KIo0KtEXp7tw", 08:19:35 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 08:19:35 INFO - "slavebuilddir": "test", 08:19:35 INFO - "taskId": "fj7ZvRESSIKfa5gG64bXOw", 08:19:35 INFO - "branch": "cedar", 08:19:35 INFO - "script_repo_revision": "default", 08:19:35 INFO - "revision": "abd2dbf7250d742ed375983f79c2dff104a5b428", 08:19:35 INFO - "slavename": "t-yosemite-r7-0188", 08:19:35 INFO - "repo_path": "projects/cedar" 08:19:35 INFO - } 08:19:35 INFO - Finding installer, test and symbols from parent task. 08:19:35 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/fj7ZvRESSIKfa5gG64bXOw'}, attempt #1 08:19:36 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/fj7ZvRESSIKfa5gG64bXOw'}, attempt #1 08:19:36 INFO - Task dependencies: Rl9Nw6J6Thmw5CHWM6Um2g HU4fY-aZS-KIo0KtEXp7tw 08:19:36 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/HU4fY-aZS-KIo0KtEXp7tw'}, attempt #1 08:19:37 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/HU4fY-aZS-KIo0KtEXp7tw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 08:19:37 INFO - Set test_url: https://queue.taskcluster.net/v1/task/HU4fY-aZS-KIo0KtEXp7tw/artifacts/public/build/test_packages.json 08:19:37 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/HU4fY-aZS-KIo0KtEXp7tw/artifacts/public/build/target.crashreporter-symbols.zip 08:19:37 INFO - Running post-action listener: _resource_record_post_action 08:19:37 INFO - [mozharness: 2017-06-05 15:19:37.381907Z] Finished read-buildbot-config step (success) 08:19:37 INFO - [mozharness: 2017-06-05 15:19:37.382016Z] Running download-and-extract step. 08:19:37 INFO - Running pre-action listener: _resource_record_pre_action 08:19:37 INFO - Running main action method: download_and_extract 08:19:37 INFO - mkdir: /builds/slave/test/build/tests 08:19:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:19:37 INFO - trying https://queue.taskcluster.net/v1/task/HU4fY-aZS-KIo0KtEXp7tw/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 08:19:37 INFO - Downloading https://queue.taskcluster.net/v1/task/HU4fY-aZS-KIo0KtEXp7tw/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 08:19:37 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/HU4fY-aZS-KIo0KtEXp7tw/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json'}, attempt #1 08:19:38 INFO - Downloaded 1328 bytes. 08:19:38 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 08:19:38 INFO - Using the following test package requirements: 08:19:38 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 08:19:38 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 08:19:38 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 08:19:38 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 08:19:38 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 08:19:38 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 08:19:38 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 08:19:38 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 08:19:38 INFO - u'jsshell-mac.zip'], 08:19:38 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 08:19:38 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 08:19:38 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 08:19:38 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 08:19:38 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 08:19:38 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 08:19:38 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 08:19:38 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 08:19:38 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.tar.gz'], 08:19:38 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 08:19:38 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 08:19:38 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 08:19:38 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/HU4fY-aZS-KIo0KtEXp7tw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 08:19:38 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/HU4fY-aZS-KIo0KtEXp7tw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 08:19:38 INFO - Fetch https://queue.taskcluster.net/v1/task/HU4fY-aZS-KIo0KtEXp7tw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 08:19:43 INFO - Content-Length response header: 57632102 08:19:43 INFO - Bytes received: 57632102 08:20:01 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip', u'firefox-55.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 08:20:01 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/HU4fY-aZS-KIo0KtEXp7tw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 08:20:01 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/HU4fY-aZS-KIo0KtEXp7tw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 08:20:01 INFO - Fetch https://queue.taskcluster.net/v1/task/HU4fY-aZS-KIo0KtEXp7tw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 08:20:03 INFO - Content-Length response header: 57632102 08:20:03 INFO - Bytes received: 57632102 08:20:20 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/HU4fY-aZS-KIo0KtEXp7tw/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 08:20:20 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/HU4fY-aZS-KIo0KtEXp7tw/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 08:20:20 INFO - Fetch https://queue.taskcluster.net/v1/task/HU4fY-aZS-KIo0KtEXp7tw/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 08:20:22 INFO - Content-Length response header: 14447425 08:20:22 INFO - Bytes received: 14447425 08:20: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')]} 08:20:22 INFO - trying https://queue.taskcluster.net/v1/task/HU4fY-aZS-KIo0KtEXp7tw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 08:20:22 INFO - Downloading https://queue.taskcluster.net/v1/task/HU4fY-aZS-KIo0KtEXp7tw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 08:20:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/HU4fY-aZS-KIo0KtEXp7tw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg'}, attempt #1 08:20:24 INFO - Downloaded 67978452 bytes. 08:20:24 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/HU4fY-aZS-KIo0KtEXp7tw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 08:20:24 INFO - mkdir: /builds/slave/test/properties 08:20:24 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 08:20:24 INFO - Writing to file /builds/slave/test/properties/build_url 08:20:24 INFO - Contents: 08:20:24 INFO - build_url:https://queue.taskcluster.net/v1/task/HU4fY-aZS-KIo0KtEXp7tw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 08:20:24 INFO - Running post-action listener: _resource_record_post_action 08:20:24 INFO - Running post-action listener: set_extra_try_arguments 08:20:24 INFO - [mozharness: 2017-06-05 15:20:24.298698Z] Finished download-and-extract step (success) 08:20:24 INFO - [mozharness: 2017-06-05 15:20:24.298809Z] Running populate-webroot step. 08:20:24 INFO - Running pre-action listener: _resource_record_pre_action 08:20:24 INFO - Running main action method: populate_webroot 08:20:24 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 08:20:24 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 08:20:24 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 08:20:24 INFO - '--tppagecycles', 08:20:24 INFO - '7'], 08:20:24 INFO - 'tests': ['tsvgm']}}, 08:20:24 INFO - 'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 08:20:24 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 08:20:24 INFO - 'tests': ['dromaeo_css', 'kraken']}, 08:20:24 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 08:20:24 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 08:20:24 INFO - 'talos_options': ['--disable-e10s'], 08:20:24 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:20:24 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 08:20:24 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:20:24 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 08:20:24 INFO - 'talos_options': ['--disable-e10s'], 08:20:24 INFO - 'tests': ['damp', 'tps']}, 08:20:24 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 08:20:24 INFO - 'tests': ['damp', 'tps']}, 08:20:24 INFO - 'g3': {'talos_options': ['--disable-e10s'], 08:20:24 INFO - 'tests': ['dromaeo_dom']}, 08:20:24 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 08:20:24 INFO - 'g4': {'talos_options': ['--disable-e10s'], 08:20:24 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 08:20:24 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 08:20:24 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 08:20:24 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 08:20:24 INFO - 'other': {'talos_options': ['--disable-e10s'], 08:20:24 INFO - 'tests': ['a11yr', 08:20:24 INFO - 'ts_paint', 08:20:24 INFO - 'tpaint', 08:20:24 INFO - 'sessionrestore', 08:20:24 INFO - 'sessionrestore_no_auto_restore', 08:20:24 INFO - 'tabpaint']}, 08:20:24 INFO - 'other-e10s': {'tests': ['a11yr', 08:20:24 INFO - 'ts_paint', 08:20:24 INFO - 'tpaint', 08:20:24 INFO - 'sessionrestore', 08:20:24 INFO - 'sessionrestore_no_auto_restore', 08:20:24 INFO - 'tabpaint']}, 08:20:24 INFO - 'other-e10s_l64': {'tests': ['a11yr', 08:20:24 INFO - 'ts_paint', 08:20:24 INFO - 'tpaint', 08:20:24 INFO - 'sessionrestore', 08:20:24 INFO - 'sessionrestore_no_auto_restore', 08:20:24 INFO - 'tabpaint']}, 08:20:24 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 08:20:24 INFO - 'ts_paint', 08:20:24 INFO - 'tpaint', 08:20:24 INFO - 'sessionrestore', 08:20:24 INFO - 'sessionrestore_no_auto_restore', 08:20:24 INFO - 'tabpaint']}, 08:20:24 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 08:20:24 INFO - 'tests': ['a11yr', 08:20:24 INFO - 'ts_paint', 08:20:24 INFO - 'tpaint', 08:20:24 INFO - 'sessionrestore', 08:20:24 INFO - 'sessionrestore_no_auto_restore', 08:20:24 INFO - 'tabpaint']}, 08:20:24 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 08:20:24 INFO - 'tests': ['a11yr', 08:20:24 INFO - 'ts_paint', 08:20:24 INFO - 'tpaint', 08:20:24 INFO - 'sessionrestore', 08:20:24 INFO - 'sessionrestore_no_auto_restore', 08:20:24 INFO - 'tabpaint']}, 08:20:24 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 08:20:24 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 08:20:24 INFO - 'bloom_basic_ref']}, 08:20:24 INFO - 'quantum-pageload-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set.zip', 08:20:24 INFO - 'talos_options': ['--mitmproxy', 08:20:24 INFO - 'mitmproxy-recording-1.mp', 08:20:24 INFO - '--firstNonBlankPaint'], 08:20:24 INFO - 'tests': ['Quantum_1']}, 08:20:24 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 08:20:24 INFO - 'tests': ['tsvgx', 08:20:24 INFO - 'tsvgr_opacity', 08:20:24 INFO - 'tart', 08:20:24 INFO - 'tscrollx', 08:20:24 INFO - 'cart', 08:20:24 INFO - 'tsvg_static']}, 08:20:24 INFO - 'svgr-e10s': {'tests': ['tsvgx', 08:20:24 INFO - 'tsvgr_opacity', 08:20:24 INFO - 'tart', 08:20:24 INFO - 'tscrollx', 08:20:24 INFO - 'cart', 08:20:24 INFO - 'tsvg_static']}, 08:20:24 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 08:20:24 INFO - 'talos_options': ['--disable-e10s'], 08:20:24 INFO - 'tests': ['tp5o']}, 08:20:24 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 08:20:24 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 08:20:24 INFO - 'talos_options': ['--disable-e10s', 08:20:24 INFO - '--xperf_path', 08:20:24 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:20:24 INFO - 'tests': ['tp5n']}, 08:20:24 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 08:20:24 INFO - 'talos_options': ['--xperf_path', 08:20:24 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:20:24 INFO - 'tests': ['tp5n']}}, 08:20:24 INFO - 'talos.zip': {'path': '', 08:20:24 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 08:20:24 INFO - Running post-action listener: _resource_record_post_action 08:20:24 INFO - [mozharness: 2017-06-05 15:20:24.320202Z] Finished populate-webroot step (success) 08:20:24 INFO - [mozharness: 2017-06-05 15:20:24.320357Z] Running create-virtualenv step. 08:20:24 INFO - Running pre-action listener: _resource_record_pre_action 08:20:24 INFO - Running main action method: create_virtualenv 08:20:24 INFO - Creating virtualenv /builds/slave/test/build/venv 08:20:24 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 08:20:24 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 08:20:24 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 08:20:24 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 08:20:24 INFO - Using real prefix '/tools/python27' 08:20:24 INFO - New python executable in /builds/slave/test/build/venv/bin/python 08:20:25 INFO - Installing distribute.............................................................................................................................................................................................done. 08:20:28 INFO - Installing pip.................done. 08:20:28 INFO - Return code: 0 08:20:28 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 08:20:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 08:20:28 INFO - Reading from file tmpfile_stdout 08:20:28 INFO - Output received: 08:20:28 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) 08:20:28 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 08:20:28 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')]} 08:20:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:20:28 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')]} 08:20:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:20:28 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 0x10cec6c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ceca768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe0424e1b40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ce4fc60>, '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 0x10cbe4cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cc0dbe8>, '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.RgFP1d71XQ/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.5CUoSBfjYo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/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 08:20:28 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 08:20:28 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 08:20:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RgFP1d71XQ/Render', 08:20:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:20:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:20:28 INFO - 'HOME': '/Users/cltbld', 08:20:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:20:28 INFO - 'LOGNAME': 'cltbld', 08:20:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:20:28 INFO - 'MOZ_NO_REMOTE': '1', 08:20:28 INFO - 'NO_EM_RESTART': '1', 08:20:28 INFO - 'PAGER': '/bin/cat', 08:20:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:20:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:20:28 INFO - 'PWD': '/builds/slave/test', 08:20:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:20:28 INFO - 'SHELL': '/bin/bash', 08:20:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5CUoSBfjYo/Listeners', 08:20:28 INFO - 'TMPDIR': '/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/', 08:20:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:20:28 INFO - 'USER': 'cltbld', 08:20:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:20:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:20:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:20:28 INFO - 'XPC_FLAGS': '0x0', 08:20:28 INFO - 'XPC_SERVICE_NAME': '0', 08:20:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:20:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:20:28 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 08:20:28 INFO - Cleaning up... 08:20:28 INFO - Return code: 0 08:20:28 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 08:20:28 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')]} 08:20:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:20:28 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')]} 08:20:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:20:28 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 0x10cec6c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ceca768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe0424e1b40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ce4fc60>, '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 0x10cbe4cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cc0dbe8>, '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.RgFP1d71XQ/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.5CUoSBfjYo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/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 08:20:28 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 08:20:28 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 08:20:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RgFP1d71XQ/Render', 08:20:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:20:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:20:28 INFO - 'HOME': '/Users/cltbld', 08:20:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:20:28 INFO - 'LOGNAME': 'cltbld', 08:20:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:20:28 INFO - 'MOZ_NO_REMOTE': '1', 08:20:28 INFO - 'NO_EM_RESTART': '1', 08:20:28 INFO - 'PAGER': '/bin/cat', 08:20:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:20:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:20:28 INFO - 'PWD': '/builds/slave/test', 08:20:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:20:28 INFO - 'SHELL': '/bin/bash', 08:20:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5CUoSBfjYo/Listeners', 08:20:28 INFO - 'TMPDIR': '/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/', 08:20:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:20:28 INFO - 'USER': 'cltbld', 08:20:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:20:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:20:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:20:28 INFO - 'XPC_FLAGS': '0x0', 08:20:28 INFO - 'XPC_SERVICE_NAME': '0', 08:20:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:20:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:20:28 INFO - Downloading/unpacking psutil>=3.1.1 08:20:28 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 08:20:28 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 08:20:28 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 08:20:28 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 08:20:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 08:20:31 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:20:31 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:20:31 INFO - Installing collected packages: psutil 08:20:31 INFO - Running setup.py install for psutil 08:20:31 INFO - building 'psutil._psutil_osx' extension 08:20:31 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 08:20:32 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 08:20:32 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 08:20:32 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 08:20:32 INFO - building 'psutil._psutil_posix' extension 08:20:32 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 08:20:32 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 08:20:32 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 08:20:32 INFO - ^ 08:20:32 INFO - 1 warning generated. 08:20:32 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 08:20:32 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:20:32 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:20:32 INFO - Successfully installed psutil 08:20:32 INFO - Cleaning up... 08:20:32 INFO - Return code: 0 08:20:32 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 08:20: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')]} 08:20:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:20: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')]} 08:20:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:20:32 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10cec6c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ceca768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe0424e1b40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ce4fc60>, '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 0x10cbe4cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cc0dbe8>, '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.RgFP1d71XQ/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.5CUoSBfjYo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/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 08:20:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 08:20:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 08:20:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RgFP1d71XQ/Render', 08:20:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:20:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:20:32 INFO - 'HOME': '/Users/cltbld', 08:20:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:20:32 INFO - 'LOGNAME': 'cltbld', 08:20:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:20:32 INFO - 'MOZ_NO_REMOTE': '1', 08:20:32 INFO - 'NO_EM_RESTART': '1', 08:20:32 INFO - 'PAGER': '/bin/cat', 08:20:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:20:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:20:32 INFO - 'PWD': '/builds/slave/test', 08:20:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:20:32 INFO - 'SHELL': '/bin/bash', 08:20:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5CUoSBfjYo/Listeners', 08:20:32 INFO - 'TMPDIR': '/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/', 08:20:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:20:32 INFO - 'USER': 'cltbld', 08:20:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:20:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:20:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:20:32 INFO - 'XPC_FLAGS': '0x0', 08:20:32 INFO - 'XPC_SERVICE_NAME': '0', 08:20:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:20:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:20:32 INFO - Downloading/unpacking mozsystemmonitor==0.3 08:20: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 08:20: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 08:20: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 08:20: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 08:20:34 INFO - Downloading mozsystemmonitor-0.3.tar.gz 08:20:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 08:20:34 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 08:20:34 INFO - Installing collected packages: mozsystemmonitor 08:20:34 INFO - Running setup.py install for mozsystemmonitor 08:20:34 INFO - Successfully installed mozsystemmonitor 08:20:34 INFO - Cleaning up... 08:20:34 INFO - Return code: 0 08:20:34 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 08:20: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')]} 08:20:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:20: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')]} 08:20:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:20:34 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10cec6c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ceca768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe0424e1b40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ce4fc60>, '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 0x10cbe4cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cc0dbe8>, '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.RgFP1d71XQ/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.5CUoSBfjYo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/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 08:20:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 08:20:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 08:20:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RgFP1d71XQ/Render', 08:20:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:20:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:20:34 INFO - 'HOME': '/Users/cltbld', 08:20:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:20:34 INFO - 'LOGNAME': 'cltbld', 08:20:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:20:34 INFO - 'MOZ_NO_REMOTE': '1', 08:20:34 INFO - 'NO_EM_RESTART': '1', 08:20:34 INFO - 'PAGER': '/bin/cat', 08:20:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:20:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:20:34 INFO - 'PWD': '/builds/slave/test', 08:20:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:20:34 INFO - 'SHELL': '/bin/bash', 08:20:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5CUoSBfjYo/Listeners', 08:20:34 INFO - 'TMPDIR': '/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/', 08:20:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:20:34 INFO - 'USER': 'cltbld', 08:20:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:20:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:20:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:20:34 INFO - 'XPC_FLAGS': '0x0', 08:20:34 INFO - 'XPC_SERVICE_NAME': '0', 08:20:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:20:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:20:34 INFO - Downloading/unpacking jsonschema==2.5.1 08:20: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 08:20: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 08:20: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 08:20: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 08:20:36 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 08:20:36 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 08:20: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 08:20: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 08:20: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 08:20: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 08:20:36 INFO - Downloading functools32-3.2.3-2.tar.gz 08:20:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 08:20:36 INFO - warning: no files found matching '*.txt' 08:20:36 INFO - no previously-included directories found matching 'build' 08:20:36 INFO - no previously-included directories found matching 'dist' 08:20:36 INFO - no previously-included directories found matching '.git*' 08:20:36 INFO - Installing collected packages: jsonschema, functools32 08:20:36 INFO - Running setup.py install for functools32 08:20:36 INFO - warning: no files found matching '*.txt' 08:20:36 INFO - no previously-included directories found matching 'build' 08:20:36 INFO - no previously-included directories found matching 'dist' 08:20:36 INFO - no previously-included directories found matching '.git*' 08:20:36 INFO - Successfully installed jsonschema functools32 08:20:36 INFO - Cleaning up... 08:20:36 INFO - Return code: 0 08:20:36 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 08:20:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:20:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:20:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:20:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:20:36 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 0x10cec6c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ceca768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe0424e1b40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ce4fc60>, '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 0x10cbe4cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cc0dbe8>, '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.RgFP1d71XQ/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.5CUoSBfjYo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/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 08:20:36 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 08:20:36 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 08:20:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RgFP1d71XQ/Render', 08:20:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:20:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:20:36 INFO - 'HOME': '/Users/cltbld', 08:20:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:20:36 INFO - 'LOGNAME': 'cltbld', 08:20:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:20:36 INFO - 'MOZ_NO_REMOTE': '1', 08:20:36 INFO - 'NO_EM_RESTART': '1', 08:20:36 INFO - 'PAGER': '/bin/cat', 08:20:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:20:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:20:36 INFO - 'PWD': '/builds/slave/test', 08:20:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:20:36 INFO - 'SHELL': '/bin/bash', 08:20:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5CUoSBfjYo/Listeners', 08:20:36 INFO - 'TMPDIR': '/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/', 08:20:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:20:36 INFO - 'USER': 'cltbld', 08:20:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:20:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:20:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:20:36 INFO - 'XPC_FLAGS': '0x0', 08:20:36 INFO - 'XPC_SERVICE_NAME': '0', 08:20:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:20:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:20:37 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 08:20:37 INFO - Cleaning up... 08:20:37 INFO - Return code: 0 08:20:37 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 08:20:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:20:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:20:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:20:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:20:37 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10cec6c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ceca768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe0424e1b40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ce4fc60>, '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 0x10cbe4cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cc0dbe8>, '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.RgFP1d71XQ/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.5CUoSBfjYo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/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 08:20:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 08:20:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 08:20:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RgFP1d71XQ/Render', 08:20:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:20:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:20:37 INFO - 'HOME': '/Users/cltbld', 08:20:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:20:37 INFO - 'LOGNAME': 'cltbld', 08:20:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:20:37 INFO - 'MOZ_NO_REMOTE': '1', 08:20:37 INFO - 'NO_EM_RESTART': '1', 08:20:37 INFO - 'PAGER': '/bin/cat', 08:20:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:20:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:20:37 INFO - 'PWD': '/builds/slave/test', 08:20:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:20:37 INFO - 'SHELL': '/bin/bash', 08:20:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5CUoSBfjYo/Listeners', 08:20:37 INFO - 'TMPDIR': '/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/', 08:20:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:20:37 INFO - 'USER': 'cltbld', 08:20:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:20:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:20:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:20:37 INFO - 'XPC_FLAGS': '0x0', 08:20:37 INFO - 'XPC_SERVICE_NAME': '0', 08:20:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:20:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:20:37 INFO - Downloading/unpacking blobuploader==1.2.4 08:20: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 08:20: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 08:20: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 08:20: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 08:20:38 INFO - Downloading blobuploader-1.2.4.tar.gz 08:20:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 08:20:38 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 08:20:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:20:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:20:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:20:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:20:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 08:20:39 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 08:20:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:20:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:20:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:20:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:20:39 INFO - Downloading docopt-0.6.1.tar.gz 08:20:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 08:20:39 INFO - Installing collected packages: blobuploader, requests, docopt 08:20:39 INFO - Running setup.py install for blobuploader 08:20:39 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 08:20:39 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 08:20:39 INFO - Running setup.py install for requests 08:20:40 INFO - Running setup.py install for docopt 08:20:40 INFO - Successfully installed blobuploader requests docopt 08:20:40 INFO - Cleaning up... 08:20:40 INFO - Return code: 0 08:20:40 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:20:40 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')]} 08:20:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:20:40 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')]} 08:20:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:20:40 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 0x10cec6c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ceca768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe0424e1b40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ce4fc60>, '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 0x10cbe4cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cc0dbe8>, '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.RgFP1d71XQ/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.5CUoSBfjYo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/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 08:20:40 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 08:20:40 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 08:20:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RgFP1d71XQ/Render', 08:20:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:20:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:20:40 INFO - 'HOME': '/Users/cltbld', 08:20:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:20:40 INFO - 'LOGNAME': 'cltbld', 08:20:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:20:40 INFO - 'MOZ_NO_REMOTE': '1', 08:20:40 INFO - 'NO_EM_RESTART': '1', 08:20:40 INFO - 'PAGER': '/bin/cat', 08:20:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:20:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:20:40 INFO - 'PWD': '/builds/slave/test', 08:20:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:20:40 INFO - 'SHELL': '/bin/bash', 08:20:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5CUoSBfjYo/Listeners', 08:20:40 INFO - 'TMPDIR': '/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/', 08:20:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:20:40 INFO - 'USER': 'cltbld', 08:20:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:20:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:20:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:20:40 INFO - 'XPC_FLAGS': '0x0', 08:20:40 INFO - 'XPC_SERVICE_NAME': '0', 08:20:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:20:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:20:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:20:40 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-HShr9A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:20:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:20:40 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-2V1jSf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:20:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:20:41 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-LIK_ji-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:20:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:20:41 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-GZgMHe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:20:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:20:41 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-6lWrQI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:20:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:20:41 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-MZ957E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:20:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:20:41 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-RrEVji-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:20:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:20:41 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-BZLyCc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:20:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:20:41 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-1iOxZA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:20:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:20:41 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-Yk_AZ_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:20:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:20:41 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-KDaY_t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:20:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:20:41 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-ug_E8t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:20:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:20:42 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-ETF1P_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:20:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:20:42 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-kwEcGQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:20:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:20:42 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-uytwi4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:20:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:20:42 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-grr0BU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:20:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:20:42 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-vyaL3K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:20:42 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 08:20:42 INFO - Running setup.py install for manifestparser 08:20:42 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 08:20:42 INFO - Running setup.py install for mozcrash 08:20:42 INFO - Running setup.py install for mozdebug 08:20:43 INFO - Running setup.py install for mozdevice 08:20:43 INFO - Installing dm script to /builds/slave/test/build/venv/bin 08:20:43 INFO - Running setup.py install for mozfile 08:20:43 INFO - Running setup.py install for mozhttpd 08:20:43 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 08:20:43 INFO - Running setup.py install for mozinfo 08:20:43 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 08:20:43 INFO - Running setup.py install for mozInstall 08:20:43 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 08:20:43 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 08:20:43 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 08:20:43 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 08:20:43 INFO - Running setup.py install for mozleak 08:20:43 INFO - Running setup.py install for mozlog 08:20:44 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 08:20:44 INFO - Running setup.py install for moznetwork 08:20:44 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 08:20:44 INFO - Running setup.py install for mozprocess 08:20:44 INFO - Running setup.py install for mozprofile 08:20:44 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 08:20:44 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 08:20:44 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 08:20:44 INFO - Running setup.py install for mozrunner 08:20:44 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 08:20:44 INFO - Running setup.py install for mozscreenshot 08:20:44 INFO - Running setup.py install for moztest 08:20:45 INFO - Running setup.py install for mozversion 08:20:45 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 08:20:45 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 08:20:45 INFO - Cleaning up... 08:20:45 INFO - Return code: 0 08:20:45 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:20:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:20:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:20:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:20:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:20:45 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 0x10cec6c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ceca768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe0424e1b40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ce4fc60>, '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 0x10cbe4cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cc0dbe8>, '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.RgFP1d71XQ/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.5CUoSBfjYo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/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 08:20:45 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 08:20:45 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 08:20:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RgFP1d71XQ/Render', 08:20:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:20:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:20:45 INFO - 'HOME': '/Users/cltbld', 08:20:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:20:45 INFO - 'LOGNAME': 'cltbld', 08:20:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:20:45 INFO - 'MOZ_NO_REMOTE': '1', 08:20:45 INFO - 'NO_EM_RESTART': '1', 08:20:45 INFO - 'PAGER': '/bin/cat', 08:20:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:20:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:20:45 INFO - 'PWD': '/builds/slave/test', 08:20:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:20:45 INFO - 'SHELL': '/bin/bash', 08:20:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5CUoSBfjYo/Listeners', 08:20:45 INFO - 'TMPDIR': '/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/', 08:20:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:20:45 INFO - 'USER': 'cltbld', 08:20:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:20:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:20:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:20:45 INFO - 'XPC_FLAGS': '0x0', 08:20:45 INFO - 'XPC_SERVICE_NAME': '0', 08:20:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:20:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:20:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:20:45 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-1QNW76-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:20:45 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)) 08:20:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:20:45 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-jZkyBw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:20:45 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)) 08:20:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:20:45 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-7O6Dkg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:20:45 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)) 08:20:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:20:45 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-zeA76T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:20:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.51 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)) 08:20:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:20:45 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-dcjGJC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:20:45 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)) 08:20:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:20:45 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-jgx0iz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:20:46 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)) 08:20:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:20:46 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-Z4XYyR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:20:46 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)) 08:20:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:20:46 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-XXvOZ0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:20:46 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)) 08:20:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:20:46 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-UMhJF6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:20:46 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)) 08:20:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:20:46 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-J4UAbU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:20:46 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)) 08:20:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:20:46 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-5RVGO7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:20:46 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)) 08:20:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:20:46 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-pMpCnM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:20:46 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)) 08:20:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:20:46 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-ZhSC_D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:20:46 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)) 08:20:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:20:46 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-_difOT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:20:46 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)) 08:20:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:20:46 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-fwB7m_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:20:47 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)) 08:20:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:20:47 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-8kZKhT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:20:47 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)) 08:20:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:20:47 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-Hgunkc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:20:47 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)) 08:20:47 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)) 08:20:47 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)) 08:20:47 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)) 08:20:47 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.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 08:20:47 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.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 08:20:47 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:20:47 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 08:20:47 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 08:20:47 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 08:20:47 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 08:20:49 INFO - Downloading blessings-1.6.tar.gz 08:20:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 08:20:49 INFO - Installing collected packages: blessings 08:20:49 INFO - Running setup.py install for blessings 08:20:49 INFO - Successfully installed blessings 08:20:49 INFO - Cleaning up... 08:20:49 INFO - Return code: 0 08:20:49 INFO - Done creating virtualenv /builds/slave/test/build/venv. 08:20:49 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:20:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:20:50 INFO - Reading from file tmpfile_stdout 08:20:50 INFO - Current package versions: 08:20:50 INFO - blessings == 1.6 08:20:50 INFO - blobuploader == 1.2.4 08:20:50 INFO - docopt == 0.6.1 08:20:50 INFO - functools32 == 3.2.3-2 08:20:50 INFO - jsonschema == 2.5.1 08:20:50 INFO - manifestparser == 1.1 08:20:50 INFO - mozInstall == 1.12 08:20:50 INFO - mozcrash == 1.0 08:20:50 INFO - mozdebug == 0.1 08:20:50 INFO - mozdevice == 0.51 08:20:50 INFO - mozfile == 1.2 08:20:50 INFO - mozhttpd == 0.7 08:20:50 INFO - mozinfo == 0.9 08:20:50 INFO - mozleak == 0.1 08:20:50 INFO - mozlog == 3.4 08:20:50 INFO - moznetwork == 0.27 08:20:50 INFO - mozprocess == 0.25 08:20:50 INFO - mozprofile == 0.28 08:20:50 INFO - mozrunner == 6.13 08:20:50 INFO - mozscreenshot == 0.1 08:20:50 INFO - mozsystemmonitor == 0.3 08:20:50 INFO - moztest == 0.8 08:20:50 INFO - mozversion == 1.4 08:20:50 INFO - psutil == 3.1.1 08:20:50 INFO - requests == 1.2.3 08:20:50 INFO - wsgiref == 0.1.2 08:20:50 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:20:50 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')]} 08:20:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:20:50 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')]} 08:20:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:20:50 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 0x10cec6c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ceca768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe0424e1b40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ce4fc60>, '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 0x10cbe4cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cc0dbe8>, '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.RgFP1d71XQ/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.5CUoSBfjYo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/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 08:20:50 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 08:20:50 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 08:20:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RgFP1d71XQ/Render', 08:20:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:20:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:20:50 INFO - 'HOME': '/Users/cltbld', 08:20:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:20:50 INFO - 'LOGNAME': 'cltbld', 08:20:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:20:50 INFO - 'MOZ_NO_REMOTE': '1', 08:20:50 INFO - 'NO_EM_RESTART': '1', 08:20:50 INFO - 'PAGER': '/bin/cat', 08:20:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:20:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:20:50 INFO - 'PWD': '/builds/slave/test', 08:20:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:20:50 INFO - 'SHELL': '/bin/bash', 08:20:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5CUoSBfjYo/Listeners', 08:20:50 INFO - 'TMPDIR': '/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/', 08:20:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:20:50 INFO - 'USER': 'cltbld', 08:20:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:20:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:20:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:20:50 INFO - 'XPC_FLAGS': '0x0', 08:20:50 INFO - 'XPC_SERVICE_NAME': '0', 08:20:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:20:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:20:50 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)) 08:20:50 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)) 08:20:50 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)) 08:20:50 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)) 08:20:50 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)) 08:20:50 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)) 08:20:50 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)) 08:20:50 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)) 08:20:50 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)) 08:20:50 INFO - Downloading/unpacking marionette-driver>=1.4.0 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 08:20:50 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 08:20:50 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 08:20:50 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 08:20:50 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 08:20:51 INFO - Downloading marionette_driver-1.4.0.tar.gz 08:20:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/marionette-driver/setup.py) egg_info for package marionette-driver 08:20:51 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 08:20:51 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 08:20:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner>=6.9 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from marionette-driver>=1.4.0->-r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 08:20:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice>=0.37 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozrunner>=6.9->marionette-driver>=1.4.0->-r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 08:20:51 INFO - Installing collected packages: marionette-driver 08:20:51 INFO - Running setup.py install for marionette-driver 08:20:52 INFO - Successfully installed marionette-driver 08:20:52 INFO - Cleaning up... 08:20:52 INFO - Return code: 0 08:20:52 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 08:20:52 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')]} 08:20:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:20:52 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')]} 08:20:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:20:52 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 0x10cec6c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ceca768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe0424e1b40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ce4fc60>, '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 0x10cbe4cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cc0dbe8>, '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.RgFP1d71XQ/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.5CUoSBfjYo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/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 08:20:52 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 08:20:52 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 08:20:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RgFP1d71XQ/Render', 08:20:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:20:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:20:52 INFO - 'HOME': '/Users/cltbld', 08:20:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:20:52 INFO - 'LOGNAME': 'cltbld', 08:20:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:20:52 INFO - 'MOZ_NO_REMOTE': '1', 08:20:52 INFO - 'NO_EM_RESTART': '1', 08:20:52 INFO - 'PAGER': '/bin/cat', 08:20:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:20:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:20:52 INFO - 'PWD': '/builds/slave/test', 08:20:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:20:52 INFO - 'SHELL': '/bin/bash', 08:20:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5CUoSBfjYo/Listeners', 08:20:52 INFO - 'TMPDIR': '/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/', 08:20:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:20:52 INFO - 'USER': 'cltbld', 08:20:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:20:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:20:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:20:52 INFO - 'XPC_FLAGS': '0x0', 08:20:52 INFO - 'XPC_SERVICE_NAME': '0', 08:20:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:20:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:20:52 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 08:20:52 INFO - Cleaning up... 08:20:52 INFO - Return code: 0 08:20:52 INFO - Running post-action listener: _resource_record_post_action 08:20:52 INFO - Running post-action listener: _start_resource_monitoring 08:20:52 INFO - Starting resource monitoring. 08:20:52 INFO - [mozharness: 2017-06-05 15:20:52.515638Z] Finished create-virtualenv step (success) 08:20:52 INFO - [mozharness: 2017-06-05 15:20:52.516618Z] Running install step. 08:20:52 INFO - Running pre-action listener: _resource_record_pre_action 08:20:52 INFO - Running main action method: install 08:20:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:20:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:20:52 INFO - Reading from file tmpfile_stdout 08:20:52 INFO - Detecting whether we're running mozinstall >=1.0... 08:20:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 08:20:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 08:20:52 INFO - Reading from file tmpfile_stdout 08:20:52 INFO - Output received: 08:20:52 INFO - Usage: mozinstall [options] installer 08:20:52 INFO - Options: 08:20:52 INFO - -h, --help show this help message and exit 08:20:52 INFO - -d DEST, --destination=DEST 08:20:52 INFO - Directory to install application into. [default: 08:20:52 INFO - "/builds/slave/test"] 08:20:52 INFO - --app=APP Application being installed. [default: firefox] 08:20:52 INFO - mkdir: /builds/slave/test/build/application 08:20:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 08:20:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 08:21:14 INFO - Reading from file tmpfile_stdout 08:21:14 INFO - Output received: 08:21:14 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 08:21:14 INFO - Running post-action listener: _resource_record_post_action 08:21:14 INFO - [mozharness: 2017-06-05 15:21:14.404105Z] Finished install step (success) 08:21:14 INFO - [mozharness: 2017-06-05 15:21:14.404239Z] Running setup-mitmproxy step. 08:21:14 INFO - Running pre-action listener: _resource_record_pre_action 08:21:14 INFO - Running main action method: setup_mitmproxy 08:21:14 INFO - Skipping: mitmproxy is not required 08:21:14 INFO - Running post-action listener: _resource_record_post_action 08:21:14 INFO - [mozharness: 2017-06-05 15:21:14.404825Z] Finished setup-mitmproxy step (success) 08:21:14 INFO - [mozharness: 2017-06-05 15:21:14.404975Z] Running run-tests step. 08:21:14 INFO - Running pre-action listener: _resource_record_pre_action 08:21:14 INFO - Running main action method: run_tests 08:21:14 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:21:14 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:21:14 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:21:14 INFO - 2017-06-05 08:21:14.419 screenresolution[1630:8134] starting screenresolution argv=screenresolution get 08:21:14 INFO - 2017-06-05 08:21:14.422 screenresolution[1630:8134] Display 0: 1600x1200x32@60 08:21:14 INFO - 2017-06-05 08:21:14.427 screenresolution[1631:8137] starting screenresolution argv=screenresolution list 08:21:14 INFO - Available Modes on Display 0 08:21:14 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:21:14 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:21:14 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:21:14 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:21:14 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:21:14 INFO - Intel Iris: 08:21:14 INFO - Chipset Model: Intel Iris 08:21:14 INFO - Type: GPU 08:21:14 INFO - Bus: Built-In 08:21:14 INFO - VRAM (Dynamic, Max): 1536 MB 08:21:14 INFO - Vendor: Intel (0x8086) 08:21:14 INFO - Device ID: 0x0a2e 08:21:14 INFO - Revision ID: 0x0009 08:21:14 INFO - Displays: 08:21:14 INFO - Display: 08:21:14 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:21:14 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:21:14 INFO - Display Serial Number: 1600x1200 60 08:21:14 INFO - Main Display: Yes 08:21:14 INFO - Mirror: Off 08:21:14 INFO - Online: Yes 08:21:14 INFO - Rotation: Supported 08:21:14 INFO - Return code: 0 08:21:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 08:21:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 08:21:14 INFO - Python 2.7.3 08:21:14 INFO - Return code: 0 08:21:14 INFO - grabbing minidump binary from tooltool 08:21: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')]} 08:21:14 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 0x10ce4fc60>, '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 0x10cbe4cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cc0dbe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 08:21:14 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 08:21:14 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 08:21:14 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 08:21:14 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 08:21:14 INFO - Return code: 0 08:21:14 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 08:21:14 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 08:21:14 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 08:21:14 INFO - ENV: RUST_BACKTRACE is now 1 08:21:14 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 08:21:14 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 08:21:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Cedar', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/HU4fY-aZS-KIo0KtEXp7tw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0188', '--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 08:21:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Cedar --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/HU4fY-aZS-KIo0KtEXp7tw/artifacts/public/build/target.crashreporter-symbols.zip --title t-yosemite-r7-0188 --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 08:21:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RgFP1d71XQ/Render', 08:21:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:21:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:21:14 INFO - 'HOME': '/Users/cltbld', 08:21:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:21:14 INFO - 'LOGNAME': 'cltbld', 08:21:14 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 08:21:14 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 08:21:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:21:14 INFO - 'MOZ_NO_REMOTE': '1', 08:21:14 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 08:21:14 INFO - 'NO_EM_RESTART': '1', 08:21:14 INFO - 'PAGER': '/bin/cat', 08:21:14 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:21:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:21:14 INFO - 'PWD': '/builds/slave/test', 08:21:14 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 08:21:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:21:14 INFO - 'RUST_BACKTRACE': '1', 08:21:14 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 08:21:14 INFO - 'SHELL': '/bin/bash', 08:21:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5CUoSBfjYo/Listeners', 08:21:14 INFO - 'TMPDIR': '/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/', 08:21:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:21:14 INFO - 'USER': 'cltbld', 08:21:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:21:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:21:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:21:14 INFO - 'XPC_FLAGS': '0x0', 08:21:14 INFO - 'XPC_SERVICE_NAME': '0', 08:21:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:21:14 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Cedar', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/HU4fY-aZS-KIo0KtEXp7tw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0188', '--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 08:21:15 INFO - mozversion application_buildid: 20170605065335 08:21:15 INFO - mozversion application_changeset: abd2dbf7250d742ed375983f79c2dff104a5b428 08:21:15 INFO - mozversion application_display_name: Nightly 08:21:15 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 08:21:15 INFO - mozversion application_name: Firefox 08:21:15 INFO - mozversion application_remotingname: firefox 08:21:15 INFO - mozversion application_repository: https://hg.mozilla.org/projects/cedar 08:21:15 INFO - mozversion application_vendor: Mozilla 08:21:15 INFO - mozversion application_version: 55.0a1 08:21:15 INFO - mozversion platform_buildid: 20170605065335 08:21:15 INFO - mozversion platform_changeset: abd2dbf7250d742ed375983f79c2dff104a5b428 08:21:15 INFO - mozversion platform_repository: https://hg.mozilla.org/projects/cedar 08:21:15 INFO - mozversion platform_version: 55.0a1 08:21:15 INFO - using testdate: 1496676075 08:21:15 INFO - actual date: 1496676075 08:21:15 INFO - starting webserver on 'localhost:49228' 08:21:15 INFO - SUITE-START | Running 2 tests 08:21:15 INFO - TEST-START | dromaeo_css 08:21:15 INFO - operating with platform_type : mac_ 08:21:15 INFO - Initialising browser for dromaeo_css test... 08:21:15 INFO - Application command: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49228/getInfo.html -foreground -profile /var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/tmpRDJZML/profile 08:21:15 INFO - TEST-INFO | started process 1641 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49228/getInfo.html -foreground) 08:21:48 INFO - TEST-INFO | 1641: killed by SIGTERM 08:21:48 INFO - Browser initialized. 08:21:48 INFO - Running cycle 1/1 for dromaeo_css test... 08:21:48 INFO - TEST-INFO | started process 1649 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/tmpRDJZML/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:21:50 INFO - PID 1649 | 2017-06-05 08:21:50.955 plugin-container[1650:8747] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x993b, name = 'com.apple.tsm.portname' 08:21:50 INFO - PID 1649 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:21:50 INFO - PID 1649 | 2017-06-05 08:21:50.955 plugin-container[1650:8747] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4547, name = 'com.apple.CFPasteboardClient' 08:21:50 INFO - PID 1649 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:21:50 INFO - PID 1649 | 2017-06-05 08:21:50.955 plugin-container[1650:8747] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 08:21:51 INFO - PID 1649 | 2017-06-05 08:21:51.476 plugin-container[1651:8797] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4647, name = 'com.apple.tsm.portname' 08:21:51 INFO - PID 1649 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:21:51 INFO - PID 1649 | 2017-06-05 08:21:51.477 plugin-container[1651:8797] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9e0b, name = 'com.apple.CFPasteboardClient' 08:21:51 INFO - PID 1649 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:21:51 INFO - PID 1649 | 2017-06-05 08:21:51.477 plugin-container[1651:8797] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 08:22:00 INFO - PID 1649 | RSS: Main: 267706368 08:22:00 INFO - PID 1649 | 08:25:02 INFO - PID 1649 | Cycle 1(1): loaded http://localhost:49228/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49228/tests/dromaeo/cssquery-ext.html) 08:25:04 INFO - PID 1649 | RSS: Main: 268480512 08:25:04 INFO - PID 1649 | 08:28:06 INFO - PID 1649 | Cycle 1(1): loaded http://localhost:49228/tests/dromaeo/cssquery-ext.html (next: http://localhost:49228/tests/dromaeo/cssquery-jquery.html) 08:28:07 INFO - PID 1649 | RSS: Main: 282439680 08:28:07 INFO - PID 1649 | 08:31:08 INFO - PID 1649 | Cycle 1(1): loaded http://localhost:49228/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49228/tests/dromaeo/cssquery-mootools.html) 08:31:09 INFO - PID 1649 | RSS: Main: 281280512 08:31:09 INFO - PID 1649 | 08:34:11 INFO - PID 1649 | Cycle 1(1): loaded http://localhost:49228/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49228/tests/dromaeo/cssquery-prototype.html) 08:34:11 INFO - PID 1649 | RSS: Main: 285511680 08:34:11 INFO - PID 1649 | 08:37:14 INFO - PID 1649 | Cycle 1(1): loaded http://localhost:49228/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49228/tests/dromaeo/cssquery-yui.html) 08:37:15 INFO - PID 1649 | RSS: Main: 286867456 08:37:15 INFO - PID 1649 | 08:40:17 INFO - PID 1649 | Cycle 1(1): loaded http://localhost:49228/tests/dromaeo/cssquery-yui.html (next: http://localhost:49228/tests/dromaeo/cssquery-dojo.html) 08:40:17 INFO - PID 1649 | RSS: Main: 288493568 08:40:17 INFO - PID 1649 | 08:40:17 INFO - PID 1649 | __start_tp_report 08:40:17 INFO - PID 1649 | _x_x_mozilla_page_load 08:40:17 INFO - PID 1649 | _x_x_mozilla_page_load_details 08:40:17 INFO - PID 1649 | |i|pagename|runs| 08:40:17 INFO - PID 1649 | |0;dojo.html;3571.1607772173534;3574.1958059436647;3595.586507551632;3601.7118630509563;3612.4039533476976;6110.346130329522;6198.411150940665;6220.377962203783;6244.157038799761;6255.655938923357;6150.753969841212;6151;6180.103884936681;6225.097360882669;6273.874522509558;4509.391232183662;4996.475370086143;5435.320584926884;5467.261919640838;5478.726063696799;5564.638298510604;5603.523700485463;5611.018071837432;5623.8594035149035;5631.352394474627;5142.871428214325;5388.245645609618;5428.104362780136;5482.506574408282;5534.944650553483;4457.398251236074;4952.628552858514;5518.91721624176;5523.143912693539;5550.16747487876;5469.343678758534;5470.6043340880415;5492.450754924475;5509.696966666832;5555.555555555546;5176;5178.766955486974;5687.488126068673;6229.37706229378;6235.875282494325;6681.365270299314;6692.83267918301;6729.8654026919185;6735.730570777212;6750.257471678112;6788.6945087470685;6827.317268273183;6831.69257383419;6835.043093966849;6845.247022827485;5093.719845408464;6274.529410294246;6335.651539165298;6362.713677884506;6372.936270637327;6874.2094659113845;6922.376986071177;6924;6933.861322773516;6960.234374218832;6312.842178945563;6462.1276127723095;6475.255345635313;6587.670616469158;6621.966890165519;5553.861153471114;6764.0530325754435;6767.018782276542;6791.728330866808;6796.796096117026;6429.839254018687;6552.672366381662;6654.73381064752;6694.698738556679;6698.799036028927;6583.83540411484;6602;6672.366125218096;6701.0953521274005;6752.898706519406;5321.122014867581;5323.760430780624;5325.3609566851455;5354.651947623392;5362.597805164628;3903.6681882039775;4697.506761790031;4769.070031343924;4792.041591681677;4819.542143496362;5403.70279634616;5980.073088671263;6210.937890621126;6241.063840423882;6317.9684101580115;6787.1176747023555;6810.455163586901;6813.488988325896;6819.829504262334;6853.4174595158975;6147.108669242934;6291.55959082869;6298.496120310364;6313.273973493107;6320.27316858558;5754.4533269339345;5827.621204621686;5905;5914.556408269397;5954.404559544011;6965.686544105527;6982.68577913985;6995.580265184105;6997.985292132714;7000.789976300719;6795.252522222453;6803.285655006351;6811;6837.794866154023;6840.31596840312;5669.943300566942;6753.054572359775;6815.863682726417;6917.58494490312;6971.47713921468;6653.367930046637;6674;6710.530262881552;6724.226713927961;6733.42765864907;6608.240052393844;6823.249442561215;6824.317568243335;6835.658217089026;6840.863182736417;6408.455281301141;6511.772087976898;6561.540692151694;6583.177102862142;6588.143541339595;6581.407673309425;6591.406773390226;6596.505262105457;6599.670016499252;6605.306442823435;6446.645434501147;6466.288708241996;6483.740650373932;6483.902741458787;6492.935070649233;6401.487880969418;6459.192600924885;6574.342565743387;6580.473562115115;6597.373249541286;6406.583572067801;6436.517261205522;6447.2263328400895;6488.253850807028;6493.155889734304 08:40:17 INFO - PID 1649 | |1;ext.html;771.3135309574476;812.8008637883718;839.5466448118017;1267.8098285257215;1320.8018797180428;38478.61521384785;39524.2095158097;39525.01187470311;39540.80229598855;39676.60323396765;32676.346473070524;39843.80078099613;40019.39970900431;40514.797426012905;40524.59475405245;7856.17510161432;8190.303824174952;8320.958395208016;8458.196471335244;8492.278156356717;8337.708180213695;8399.286060684819;8419.07390187079;8434.114417986115;8441.366897482683;8355.749327520183;8420.031696354936;8456.196661317164;8472.74581762548;8481.915180848206;8316.209960053786;8355.665773369057;8422.073571907116;8454.112318206562;8472.999999999969;8203.75388738333;8251.587420628945;8271.669133234724;8280.751577452686;8355;37262.25475490502;37413.87758367253;37465.25069498622;37501.06247343811;37573.43639845405;3539.6460353964653;3577.0342007658046;3579.624139465345;3581.068922080252;3582.0865679251624;30694.53958190629;30784.230394240098;30803.537946930814;30843.691563084307;30878.764849405998;3052.267455810612;3082.1678146900213;3088.4903990841485;3098.8450577471262;3110.548970399302;240866.7956660223;244485.33272000935;273008.999999998;294070;294333;38099.61900380961;46813;46867;47197.52802472;47200.7639961798;48246.5175348249;49088;49307.75346123318;55402.61493462696;56121.87756244852;14360.994730368982;14415.990880638465;14518.419263229353;14557.490487832874;14569.271536423348;11352.318860868374;11510.712232194262;11532.88467115335;11664.066874650007;11696.941515292368;9661;14239.074460160056;14263.500777472738;14277.286135693173;14439.494617688331;6248.5313601480075;6253.53098517613;6262.561620686508;6311.558190926683;6316.147320111726;842.9884139033185;959.3524371049514;960.4381436859376;1032.865727455426;1033.7725700345911;15770.369185232692;22488.437789055406;23235.883820581126;24085.916133774022;25048.373790655016;1083.989160108389;1101.091599430473;1134.183387960667;1155.1798223261578;1156.4911438966828;11269.774604507864;12506.49974001048;12535.686607834694;12537.937310313573;12558.623241302776;318939.405302972;319304;320270;323202;323886;54737;55319.446805532236;55424;55530.44469555253;55645.165322520195;1473.5652982370227;1527.0074451606322;1529.9541013769606;1532.7087853307835;1541.352631894607;22777;22995.195168169033;23891.522169556163;24343.51312973766;24793.256202313958;33623;34712.13219669528;37519.81240093782;37902;38107.42838857364;274484.2964133238;276964;277239.6138019297;277707;278414.2555010565;34067.65932340645;36108.63891361053;36122;36160.09599760027;36241;44261;44927.652170433634;46466;46565.76717116528;46576.7671161642;28209;30292.5456118154;30770.846145769126;31535.684643153276;32149;30021.649025793457;31100.533491998092;31250.374992500478;31779.52330714995;31859.840700797275;37160;37252.62747372492;37778.62221377861;38017.429738553394;38090.61909380871;29237.7076229235;30257.24356891095;31829;32036.839815801704;32162.678373215967;22644.66033009473;22790.658140128242;23213.53572928498;23625.645615316127;25085.87457062703 08:40:17 INFO - PID 1649 | |2;jquery.html;368.4988415754572;416.7957700726643;428.5992596921876;450.18291800382303;460.13264995483513;15384;22956.426089347773;22961.655575166365;23032.848357582137;24162.758372416272;25060.498790024234;25387;25684.614730779005;25694.871525642393;25737.35656608586;6853.7943861684225;6938.167419909592;7025.262347453495;7042.873140297554;7045.718171273143;5881.411858814127;6743.35938085881;6841.897371539431;6899.862002759942;6917.550359226634;5782.277215348082;6802.489813264;6830.351116643936;6898.896516552256;6905.067815844872;6861.382475577197;6871.072405225281;6894.5518541294905;6903.896441553381;6905.102336696247;5705.372409035002;6649.135612370409;6728.697208625575;6767.187937447488;6786.796396108075;30693.079207623807;31337.84331078353;31410.37179256402;31582.526262106087;31767.52348714771;2305.0779688124717;2333.346662934381;2337.0885354711677;2353.882305884699;2359.3865594945437;19892.70160947587;20229.291974780957;20424.897875510676;20473.692894606593;20483;2321.721393432782;2353.5645905507536;2416.5408572371193;2418.4316685578874;2418.480026794248;167529;174084.25915740934;190535.09464905172;201866.99066504574;205820;25111.874440628046;26322.47355052888;27548.586771198447;27571.89712411521;27628.30929226745;43709.34435983463;43810.7809460957;43865.78067109644;43953.34069988953;44086;8166.795830104176;9544.809103818023;10984.395858227714;11511.251768635011;11638.534458621729;9157.679481218258;9193.862092068624;9201.21789647874;9315;9362.016988216275;12247.632571022883;12338.876611233773;12374.319412432402;12375.75248495025;12450.25298482094;4838.7338696372035;4962;4970.850874473772;4981.32752078472;5010.624203184703;799.4803377804474;882.7307671160357;924.5793164110448;932.1331162019252;984.8965858584888;20837.79162208389;21061.47346316324;21352.78647213539;21757;21779.45551361197;972.4943029624555;973.5570315506427;1016.5679586175846;1017.2269075502523;1020.6733845169623;9165.625156226486;10560.52477638508;10767.030967212988;10981.505832237568;11149;450675.98648026853;513171;525416;529899;549920.2503987534;149289.2535537337;152171.47828521795;152375;152780.47219527885;152963.47036529714;1140.8117660586076;1160.7272291011604;1184.9407529623657;1189.108168873345;1190.3096204201543;15646.21768911527;17957.461276161735;18965.90517047406;19290.324838630582;19754.308599198957;67899.98150027655;70018.04846588097;70483;71075.93386099316;71344;86118;91393.54303228442;92216.53891730498;95941.56087658831;96391.51804240934;10324.896751032695;10709.785804284027;10723.571057157626;10728.570857165938;10760;18040.00779957115;18507.16717747678;18656.533586659898;18744.250229991194;18749.1562879668;13575;14198.716025679636;15150.3182356792;15238.695226095639;15388.84611153919;10716.571337146426;13190.34048297562;13532.93233533826;13620.250886201355;13878.375473103533;15360.694340981141;15554.533363999099;15743.370265189264;15743.44897928567;15761;10415.791684166426;10533.210009249491;10651.946740266249;10693.358398496115;10714.303570267908;14932.701345973237;15118.09291442517;15120.773188401963;15161.393544258106;15260.542183734506 08:40:17 INFO - PID 1649 | |3;mootools.html;1236.6351926181776;1446.0311591233872;1456.548469974308;1459.5329494561745;1464.7143806957645;9597.328187026911;11786.941065294672;12343.814842777354;12368.567100151497;12405.627831165057;12081.395930203498;12227.877721222785;12240;12240.510379584828;12249.755004899918;2545.974540254597;2586.689597248333;2587.32729490332;2593.209071233273;2597.8441293522355;2140.197425965263;2228.476308067603;2506.686664166979;2524.4193835417864;2598.792096632265;2579.1102069785893;2579.484103179362;2603.2450589329073;2605.6091586262023;2606.335384476956;2115.756687980871;2541.898324067035;2556.207575651554;2556.923292301234;2564.0513010186255;2178.4227179797367;2435.7442468540903;2531.4683916377585;2534.4170840706556;2543.8219324647284;11798;11893.88106118945;12070;12079.818802717966;12081.697957551043;6486;6678.599284042973;6717.193936727624;6756;6770.052192693027;4132.483439570054;4250.341197114421;4270.572942705736;4335.934960975588;4346.326319420495;1064.6380230721513;1263.532492977595;1277.9840027178416;1288.5940928607458;1292.231122482131;16558.83441165585;16752.078635675032;16862.409815656392;16883.40908068224;16912.408065717642;4075.5109386873173;5503.036968530491;5541.362743284493;5583.497485226349;5704.372519022904;12332.50669973191;12430.627081187578;12431.440585173688;12432.316222607844;12439.689007774698;5116.923246151311;6442.226932768098;6551.1155993941165;6558.311377305315;6584;5954.851128721816;6116.26604807426;6195.721192546346;6224.346443623445;6277.121203031579;3847.7306588539095;4333.351258598019;4537.8411755588395;4561;4582.24392975162;1184.1710802438156;1189.8453201083803;1290.490256348737;1306.8105124756858;1312.750577390312;799.3325573146371;813.986586699551;829.0838623321143;835.2107258640526;859.4671303791689;6643.634600096944;7005;7281.1990681025045;7380.298871607189;7521.8119547010665;856.7943693513512;868.900076491199;938.8122375524922;963.4075043847984;963.556763888605;4220.282551966173;4478.232876033863;4655.627549796008;4815.879603009953;4829.6860704054125;14968.40126394932;15307.46423875159;15309.234538273264;15461.5361539154;15462.458813941683;14003.649908752363;14335.56993290203;14500.85499144995;14786.334614942354;14804.925975370053;8102.7569172924905;8357.791055223546;8639.481631102028;8672.56637168139;8674.262687671493;6960.825979350557;6997.440204783707;7457.664405101892;7566.054243219598;7678;13198.07613467048;13344;13462.124961877447;13508.25704586218;13518.72962540724;13849.515266965607;13929.512467064009;13966.441342346192;13975;14153.80616004521;10849.294795838245;10854.29447085937;10909.18181136402;11016.394098324668;11076.335419874833;10856;10909.672709818718;10961.232713709964;10967.232293739362;10974.341539507335;9055.230305424113;9318.254539636677;9377.906220937703;9412.011738767605;9484.288678349289;9092.272618190396;9166.862497062682;9226.35415520907;9277.67528136512;9435.29235307341;8289.33685305162;8341.45780524264;8413.70552030676;8430.789230269047;8433.704820331504;6124.510039196764;6362.554621176473;6442.35576442352;6453;6535.2811190768025;5422.8373148805595;6334.809955701337;6571.441427478527;6637.734490620514;6684.331566843277 08:40:17 INFO - PID 1649 | |4;prototype.html;361.68714062336085;364.4569591308949;385.0777388155368;386.2583839029064;412.83899279281076;5400.324959380077;5541.866381443153;6449.484041276693;6527.673616319189;6582.848001599719;5426.6704657359005;5975.492083172936;6380.872382552347;6384.170057892467;6391.968040159804;3951.308521008812;4857.174280372327;4874.780634871439;4876.488288630536;4880.438749543795;4206.705530612843;4376.97811510942;4557.245460497716;4686.625069994409;4892.902141957176;4661.720296782204;4679.953200468003;4690.130642220165;4690.906181876378;4696.17816882046;3842.5004749382542;4606.3551102845295;4640.651951103647;4646.512116227781;4683.4544600282;3237.352529494087;3504.316658251642;3962.6631736302447;3973.642372186517;3986.3223252046932;6277.497800175975;6289.528285378569;6298.527610429236;6316.210473690789;6343.619382836999;4542.863714088583;5573.27547418837;5589.133684278943;5599.32808063035;5626;5615.5507559395555;6229.6573688447115;6583.012548117773;6900.171979362459;6919.584824910521;4217.511668115796;4572.565606267399;4594.2419500782335;4597.770111494412;4612.293451422995;7282.526635768658;7350.558966462083;7360.852782944311;7370.77887663371;7373.5575865448245;4625;4651.465081515601;4664.253719404946;4680.134175177603;4682.969746655798;4736;4751.714897106184;4759.619230461555;4760.428748550196;4774.7135171889795;4925.729084900365;5079.085764562415;5271.629376362097;5571.526420254243;5623.240862483594;5741.3614450211435;6485.221773387224;6938.653067346612;6943.305669433016;6980.650967451607;1317.0846261847923;1510.6781566129637;1558.7194305025205;1565.4677409680762;1623.683381740573;905.4431524612447;1054.2936232724094;1066.258950029722;1067.2422579968309;1069.8716154061563;6055.364186760326;6468.223813142453;6479.546431749826;6480.902786458207;6495.512836537184;6257.43683068526;7145.178304494945;7179.52819671966;7191.496595238283;7194.712211511585;5092.465291144451;6175.938240617536;6477.093206951031;6487.708053137526;6508.772192973223;3079.476488996876;3718.981405092957;3722.925541489209;3725.9381076393097;3743.21392507571;7298.343149116498;7345.963270183722;7365.742199023009;7379.446541509408;7406.444516661272;7073.135380171039;7282.5630462171375;7363.558186508827;7428.034355533854;7589.8861517077285;6680.265170831205;7182.3894968928225;7388.852222955618;7397.334239918433;7397.8150546236775;7158.355747982707;7167.426605871622;7169.964150179216;7170.565886822552;7187.5687458752645;6316.810495685137;6365.267994180728;6622.1722284714415;6943.6323383158115;7004.7198112076985;7355.448341374311;7362.153352364547;7363.595002274927;7372.839012691031;7393.482456228013;4511.684182107221;4631.212693841988;4668.206404911105;4677.064587082529;4686.367340409138;6670.898666919905;7122.715091396286;7203.8919416207755;7229.63851807397;7284.890726638999;6518.73925043012;6698.698558564779;6945.201301850352;7006;7042.647867606702;4994.675346102492;6267.524327890549;6556.475481961527;6574.685062987326;6690.665466726741;6011.557226265752;6507.414332710079;6572.76995305162;6578.243501997332;6593.340665933561;6419.903701444388;6657.53397262187;6764.594124352555;6766.391024807791;6803.455723542006;6048.5161187105805;6525.1517302750335;6539.705713243011;6547.443467305142;6609.4712423005085 08:40:17 INFO - PID 1649 | |5;yui.html;820.5158956215834;820.7865954851736;826.4710585225453;850.1371108325047;865.3380164174406;14987.700245995073;15447.69104617907;17110.486685399428;17628.91185544072;17733.64532709345;16142.354305827752;17855.285788568475;18097.1856266468;18166.727499087523;18168.09159542021;834.6619619054293;965.1940629574297;986.2603047714215;989.9554520046579;993.8956409576999;965.7247684409945;980.0885176785587;993.6770549571382;996.8903420623726;998.0817647764038;940.0176815228103;1002.1281485107967;1051.1958351860833;1051.416463862556;1060.4379678770265;1026.3892983674739;1037.647199952016;1046.9528871200814;1051.1275641217771;1051.7160366700987;752.3153929923743;755.9848803023908;757.4735558786646;757.9962100189518;759.4911409355744;17401.651966960715;17479.21343539531;17566.82433175665;17640.117994100245;17700.645987080185;17670.293188272455;17804.91097544504;18059.187336569885;18166.90916545409;18213.7267940981;14601.561953141316;14726;14903.776443353358;14903.850961490354;14906.478273260384;3526.1889765354113;3555.2889422115422;3575.249197668493;3597.7407907232523;3663.7435379523445;116903.83096169014;119659.4017029918;136504.31747841297;139406.3029684855;139561.3021934894;13572.1856688599;13701.862981370059;13820.308984550733;13832.792508112387;13878.861211387757;26439.867800660875;26900.865495672395;26919;27177.592336114973;27265;7530.510516816389;7884;7932.920670793334;7967.2431119043595;8010.239027292398;3422.4769977360334;3896.649301562816;3908.5309762828074;3971.6028397160053;3976;4212.347086201644;4297.205017071852;4298.871033868989;4309.245881970643;4312.935305970413;931.7344556801279;944.6788092048737;947.6920000999703;951.6003278622995;954.8472244440856;623.3921926122085;626.4988009592308;636.4939872801134;660.1418156396666;663.9774746889832;11643.88356116445;12852.100352975202;12928.482860685655;13113.868861311455;14227;626.3736263736264;638.5466318913531;645.8837409266283;659.2385794407467;660.7489154121403;5491.697956612425;5555.1667249912025;5582.665040097526;5607.52336051432;5804.129380592861;126814;130040;131218;131670;131896;109732.4513377444;110642.44678776554;110737.44631276792;110749;113103.86896131097;82543.99999999879;93951;96541.06917861744;100373.49066273401;103171.4841425788;8802.163794439519;8871.689490867788;11072.612458563912;11653.825192622287;11759.882401175879;39770.80114599408;41112.38331425091;41261.58738412578;41335;41780.791096044326;56621.58446038882;56643.71678141583;56847.71576142093;56989.71505142448;57829.132563010746;14493.78259326132;14839.480618178311;15197.544073677807;15371.538853834403;15371.538853834403;15701.136437496047;16467.835321647108;16485.34058637641;16838.831611683727;16877.91561042187;13044.869551304746;15191.316390762222;15462.613434664223;15563.688726225639;15593.376264949275;14360.712785744436;14424.990250682351;14441.49454769078;14570.781438278646;14585.562433127023;14582.781258280922;14694.24540588191;14916;15152.393904243707;15298.082115073132;10159.390436573829;10670.253082284165;10754.784904301714;11101.611443599435;11118.332900026024;11813.82279265829;11988.400579971141;12026;12090.06848296869;12112.454939527566 08:40:17 INFO - PID 1649 | __end_tp_report 08:40:17 INFO - PID 1649 | __start_cc_report 08:40:17 INFO - PID 1649 | _x_x_mozilla_cycle_collect,452 08:40:17 INFO - PID 1649 | __end_cc_report 08:40:17 INFO - PID 1649 | __startTimestamp1496677217435__endTimestamp 08:40:17 INFO - PID 1649 | 08:40:17 INFO - PID 1649 | ------- Summary: start ------- 08:40:17 INFO - PID 1649 | Number of tests: 6 08:40:17 INFO - PID 1649 | 08:40:17 INFO - PID 1649 | [#0] dojo.html Cycles:165 Average:6146.82 Median:6436.52 stddev:780.64 (12.1%) stddev-sans-first:756.43 08:40:17 INFO - PID 1649 | Values: 3571.2 3574.2 3595.6 3601.7 3612.4 6110.3 6198.4 6220.4 6244.2 6255.7 6150.8 6151.0 6180.1 6225.1 6273.9 4509.4 4996.5 5435.3 5467.3 5478.7 5564.6 5603.5 5611.0 5623.9 5631.4 5142.9 5388.2 5428.1 5482.5 5534.9 4457.4 4952.6 5518.9 5523.1 5550.2 5469.3 5470.6 5492.5 5509.7 5555.6 5176.0 5178.8 5687.5 6229.4 6235.9 6681.4 6692.8 6729.9 6735.7 6750.3 6788.7 6827.3 6831.7 6835.0 6845.2 5093.7 6274.5 6335.7 6362.7 6372.9 6874.2 6922.4 6924.0 6933.9 6960.2 6312.8 6462.1 6475.3 6587.7 6622.0 5553.9 6764.1 6767.0 6791.7 6796.8 6429.8 6552.7 6654.7 6694.7 6698.8 6583.8 6602.0 6672.4 6701.1 6752.9 5321.1 5323.8 5325.4 5354.7 5362.6 3903.7 4697.5 4769.1 4792.0 4819.5 5403.7 5980.1 6210.9 6241.1 6318.0 6787.1 6810.5 6813.5 6819.8 6853.4 6147.1 6291.6 6298.5 6313.3 6320.3 5754.5 5827.6 5905.0 5914.6 5954.4 6965.7 6982.7 6995.6 6998.0 7000.8 6795.3 6803.3 6811.0 6837.8 6840.3 5669.9 6753.1 6815.9 6917.6 6971.5 6653.4 6674.0 6710.5 6724.2 6733.4 6608.2 6823.2 6824.3 6835.7 6840.9 6408.5 6511.8 6561.5 6583.2 6588.1 6581.4 6591.4 6596.5 6599.7 6605.3 6446.6 6466.3 6483.7 6483.9 6492.9 6401.5 6459.2 6574.3 6580.5 6597.4 6406.6 6436.5 6447.2 6488.3 6493.2 08:40:17 INFO - PID 1649 | 08:40:17 INFO - PID 1649 | [#1] ext.html Cycles:180 Average:44298.96 Median:34389.90 stddev:76074.53 (221.2%) stddev-sans-first:76217.74 08:40:17 INFO - PID 1649 | Values: 771.3 812.8 839.5 1267.8 1320.8 38478.6 39524.2 39525.0 39540.8 39676.6 32676.3 39843.8 40019.4 40514.8 40524.6 7856.2 8190.3 8321.0 8458.2 8492.3 8337.7 8399.3 8419.1 8434.1 8441.4 8355.7 8420.0 8456.2 8472.7 8481.9 8316.2 8355.7 8422.1 8454.1 8473.0 8203.8 8251.6 8271.7 8280.8 8355.0 37262.3 37413.9 37465.3 37501.1 37573.4 3539.6 3577.0 3579.6 3581.1 3582.1 30694.5 30784.2 30803.5 30843.7 30878.8 3052.3 3082.2 3088.5 3098.8 3110.5 240866.8 244485.3 273009.0 294070.0 294333.0 38099.6 46813.0 46867.0 47197.5 47200.8 48246.5 49088.0 49307.8 55402.6 56121.9 14361.0 14416.0 14518.4 14557.5 14569.3 11352.3 11510.7 11532.9 11664.1 11696.9 9661.0 14239.1 14263.5 14277.3 14439.5 6248.5 6253.5 6262.6 6311.6 6316.1 843.0 959.4 960.4 1032.9 1033.8 15770.4 22488.4 23235.9 24085.9 25048.4 1084.0 1101.1 1134.2 1155.2 1156.5 11269.8 12506.5 12535.7 12537.9 12558.6 318939.4 319304.0 320270.0 323202.0 323886.0 54737.0 55319.4 55424.0 55530.4 55645.2 1473.6 1527.0 1530.0 1532.7 1541.4 22777.0 22995.2 23891.5 24343.5 24793.3 33623.0 34712.1 37519.8 37902.0 38107.4 274484.3 276964.0 277239.6 277707.0 278414.3 34067.7 36108.6 36122.0 36160.1 36241.0 44261.0 44927.7 46466.0 46565.8 46576.8 28209.0 30292.5 30770.8 31535.7 32149.0 30021.6 31100.5 31250.4 31779.5 31859.8 37160.0 37252.6 37778.6 38017.4 38090.6 29237.7 30257.2 31829.0 32036.8 32162.7 22644.7 22790.7 23213.5 23625.6 25085.9 08:40:17 INFO - PID 1649 | 08:40:17 INFO - PID 1649 | [#2] jquery.html Cycles:180 Average:39254.50 Median:2387.96 stddev:89989.32 (3768.5%) stddev-sans-first:90194.40 08:40:17 INFO - PID 1649 | Values: 368.5 416.8 428.6 450.2 460.1 15384.0 22956.4 22961.7 23032.8 24162.8 25060.5 25387.0 25684.6 25694.9 25737.4 6853.8 6938.2 7025.3 7042.9 7045.7 5881.4 6743.4 6841.9 6899.9 6917.6 5782.3 6802.5 6830.4 6898.9 6905.1 6861.4 6871.1 6894.6 6903.9 6905.1 5705.4 6649.1 6728.7 6767.2 6786.8 30693.1 31337.8 31410.4 31582.5 31767.5 2305.1 2333.3 2337.1 2353.9 2359.4 19892.7 20229.3 20424.9 20473.7 20483.0 2321.7 2353.6 2416.5 2418.4 2418.5 167529.0 174084.3 190535.1 201867.0 205820.0 25111.9 26322.5 27548.6 27571.9 27628.3 43709.3 43810.8 43865.8 43953.3 44086.0 8166.8 9544.8 10984.4 11511.3 11638.5 9157.7 9193.9 9201.2 9315.0 9362.0 12247.6 12338.9 12374.3 12375.8 12450.3 4838.7 4962.0 4970.9 4981.3 5010.6 799.5 882.7 924.6 932.1 984.9 20837.8 21061.5 21352.8 21757.0 21779.5 972.5 973.6 1016.6 1017.2 1020.7 9165.6 10560.5 10767.0 10981.5 11149.0 450676.0 513171.0 525416.0 529899.0 549920.3 149289.3 152171.5 152375.0 152780.5 152963.5 1140.8 1160.7 1184.9 1189.1 1190.3 15646.2 17957.5 18965.9 19290.3 19754.3 67900.0 70018.0 70483.0 71075.9 71344.0 86118.0 91393.5 92216.5 95941.6 96391.5 10324.9 10709.8 10723.6 10728.6 10760.0 18040.0 18507.2 18656.5 18744.3 18749.2 13575.0 14198.7 15150.3 15238.7 15388.8 10716.6 13190.3 13532.9 13620.3 13878.4 15360.7 15554.5 15743.4 15743.4 15761.0 10415.8 10533.2 10651.9 10693.4 10714.3 14932.7 15118.1 15120.8 15161.4 15260.5 08:40:17 INFO - PID 1649 | 08:40:17 INFO - PID 1649 | [#3] mootools.html Cycles:180 Average:7292.90 Median:2601.02 stddev:4603.14 (177.0%) stddev-sans-first:4593.55 08:40:17 INFO - PID 1649 | Values: 1236.6 1446.0 1456.5 1459.5 1464.7 9597.3 11786.9 12343.8 12368.6 12405.6 12081.4 12227.9 12240.0 12240.5 12249.8 2546.0 2586.7 2587.3 2593.2 2597.8 2140.2 2228.5 2506.7 2524.4 2598.8 2579.1 2579.5 2603.2 2605.6 2606.3 2115.8 2541.9 2556.2 2556.9 2564.1 2178.4 2435.7 2531.5 2534.4 2543.8 11798.0 11893.9 12070.0 12079.8 12081.7 6486.0 6678.6 6717.2 6756.0 6770.1 4132.5 4250.3 4270.6 4335.9 4346.3 1064.6 1263.5 1278.0 1288.6 1292.2 16558.8 16752.1 16862.4 16883.4 16912.4 4075.5 5503.0 5541.4 5583.5 5704.4 12332.5 12430.6 12431.4 12432.3 12439.7 5116.9 6442.2 6551.1 6558.3 6584.0 5954.9 6116.3 6195.7 6224.3 6277.1 3847.7 4333.4 4537.8 4561.0 4582.2 1184.2 1189.8 1290.5 1306.8 1312.8 799.3 814.0 829.1 835.2 859.5 6643.6 7005.0 7281.2 7380.3 7521.8 856.8 868.9 938.8 963.4 963.6 4220.3 4478.2 4655.6 4815.9 4829.7 14968.4 15307.5 15309.2 15461.5 15462.5 14003.6 14335.6 14500.9 14786.3 14804.9 8102.8 8357.8 8639.5 8672.6 8674.3 6960.8 6997.4 7457.7 7566.1 7678.0 13198.1 13344.0 13462.1 13508.3 13518.7 13849.5 13929.5 13966.4 13975.0 14153.8 10849.3 10854.3 10909.2 11016.4 11076.3 10856.0 10909.7 10961.2 10967.2 10974.3 9055.2 9318.3 9377.9 9412.0 9484.3 9092.3 9166.9 9226.4 9277.7 9435.3 8289.3 8341.5 8413.7 8430.8 8433.7 6124.5 6362.6 6442.4 6453.0 6535.3 5422.8 6334.8 6571.4 6637.7 6684.3 08:40:17 INFO - PID 1649 | 08:40:17 INFO - PID 1649 | [#4] prototype.html Cycles:180 Average:5534.44 Median:6294.03 stddev:1782.86 (28.3%) stddev-sans-first:1745.07 08:40:17 INFO - PID 1649 | Values: 361.7 364.5 385.1 386.3 412.8 5400.3 5541.9 6449.5 6527.7 6582.8 5426.7 5975.5 6380.9 6384.2 6392.0 3951.3 4857.2 4874.8 4876.5 4880.4 4206.7 4377.0 4557.2 4686.6 4892.9 4661.7 4680.0 4690.1 4690.9 4696.2 3842.5 4606.4 4640.7 4646.5 4683.5 3237.4 3504.3 3962.7 3973.6 3986.3 6277.5 6289.5 6298.5 6316.2 6343.6 4542.9 5573.3 5589.1 5599.3 5626.0 5615.6 6229.7 6583.0 6900.2 6919.6 4217.5 4572.6 4594.2 4597.8 4612.3 7282.5 7350.6 7360.9 7370.8 7373.6 4625.0 4651.5 4664.3 4680.1 4683.0 4736.0 4751.7 4759.6 4760.4 4774.7 4925.7 5079.1 5271.6 5571.5 5623.2 5741.4 6485.2 6938.7 6943.3 6980.7 1317.1 1510.7 1558.7 1565.5 1623.7 905.4 1054.3 1066.3 1067.2 1069.9 6055.4 6468.2 6479.5 6480.9 6495.5 6257.4 7145.2 7179.5 7191.5 7194.7 5092.5 6175.9 6477.1 6487.7 6508.8 3079.5 3719.0 3722.9 3725.9 3743.2 7298.3 7346.0 7365.7 7379.4 7406.4 7073.1 7282.6 7363.6 7428.0 7589.9 6680.3 7182.4 7388.9 7397.3 7397.8 7158.4 7167.4 7170.0 7170.6 7187.6 6316.8 6365.3 6622.2 6943.6 7004.7 7355.4 7362.2 7363.6 7372.8 7393.5 4511.7 4631.2 4668.2 4677.1 4686.4 6670.9 7122.7 7203.9 7229.6 7284.9 6518.7 6698.7 6945.2 7006.0 7042.6 4994.7 6267.5 6556.5 6574.7 6690.7 6011.6 6507.4 6572.8 6578.2 6593.3 6419.9 6657.5 6764.6 6766.4 6803.5 6048.5 6525.2 6539.7 6547.4 6609.5 08:40:17 INFO - PID 1649 | 08:40:17 INFO - PID 1649 | [#5] yui.html Cycles:180 Average:23485.24 Median:17769.28 stddev:35385.37 (199.1%) stddev-sans-first:35443.71 08:40:17 INFO - PID 1649 | Values: 820.5 820.8 826.5 850.1 865.3 14987.7 15447.7 17110.5 17628.9 17733.6 16142.4 17855.3 18097.2 18166.7 18168.1 834.7 965.2 986.3 990.0 993.9 965.7 980.1 993.7 996.9 998.1 940.0 1002.1 1051.2 1051.4 1060.4 1026.4 1037.6 1047.0 1051.1 1051.7 752.3 756.0 757.5 758.0 759.5 17401.7 17479.2 17566.8 17640.1 17700.6 17670.3 17804.9 18059.2 18166.9 18213.7 14601.6 14726.0 14903.8 14903.9 14906.5 3526.2 3555.3 3575.2 3597.7 3663.7 116903.8 119659.4 136504.3 139406.3 139561.3 13572.2 13701.9 13820.3 13832.8 13878.9 26439.9 26900.9 26919.0 27177.6 27265.0 7530.5 7884.0 7932.9 7967.2 8010.2 3422.5 3896.6 3908.5 3971.6 3976.0 4212.3 4297.2 4298.9 4309.2 4312.9 931.7 944.7 947.7 951.6 954.8 623.4 626.5 636.5 660.1 664.0 11643.9 12852.1 12928.5 13113.9 14227.0 626.4 638.5 645.9 659.2 660.7 5491.7 5555.2 5582.7 5607.5 5804.1 126814.0 130040.0 131218.0 131670.0 131896.0 109732.5 110642.4 110737.4 110749.0 113103.9 82544.0 93951.0 96541.1 100373.5 103171.5 8802.2 8871.7 11072.6 11653.8 11759.9 39770.8 41112.4 41261.6 41335.0 41780.8 56621.6 56643.7 56847.7 56989.7 57829.1 14493.8 14839.5 15197.5 15371.5 15371.5 15701.1 16467.8 16485.3 16838.8 16877.9 13044.9 15191.3 15462.6 15563.7 15593.4 14360.7 14425.0 14441.5 14570.8 14585.6 14582.8 14694.2 14916.0 15152.4 15298.1 10159.4 10670.3 10754.8 11101.6 11118.3 11813.8 11988.4 12026.0 12090.1 12112.5 08:40:17 INFO - PID 1649 | -------- Summary: end -------- 08:40:17 INFO - PID 1649 | 08:40:18 INFO - TEST-INFO | 1649: exit 0 08:40:18 INFO - TEST-OK | dromaeo_css | took 1142967ms 08:40:18 INFO - TEST-START | kraken 08:40:18 INFO - operating with platform_type : mac_ 08:40:18 INFO - Initialising browser for kraken test... 08:40:18 INFO - Application command: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49228/getInfo.html -foreground -profile /var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/tmpqnus3n/profile 08:40:18 INFO - TEST-INFO | started process 1658 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49228/getInfo.html -foreground) 08:40:51 INFO - TEST-INFO | 1658: killed by SIGTERM 08:40:51 INFO - Browser initialized. 08:40:51 INFO - Running cycle 1/1 for kraken test... 08:40:51 INFO - TEST-INFO | started process 1662 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/tmpqnus3n/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:40:53 INFO - PID 1662 | 2017-06-05 08:40:53.452 plugin-container[1663:11258] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x994b, name = 'com.apple.tsm.portname' 08:40:53 INFO - PID 1662 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:40:53 INFO - PID 1662 | 2017-06-05 08:40:53.453 plugin-container[1663:11258] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0xa003, name = 'com.apple.CFPasteboardClient' 08:40:53 INFO - PID 1662 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:40:53 INFO - PID 1662 | 2017-06-05 08:40:53.453 plugin-container[1663:11258] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 08:40:54 INFO - PID 1662 | 2017-06-05 08:40:54.224 plugin-container[1664:11304] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x973f, name = 'com.apple.tsm.portname' 08:40:54 INFO - PID 1662 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:40:54 INFO - PID 1662 | 2017-06-05 08:40:54.225 plugin-container[1664:11304] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4913, name = 'com.apple.CFPasteboardClient' 08:40:54 INFO - PID 1662 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:40:54 INFO - PID 1662 | 2017-06-05 08:40:54.225 plugin-container[1664:11304] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 08:41:03 INFO - PID 1662 | RSS: Main: 260341760 08:41:03 INFO - PID 1662 | 08:41:06 INFO - PID 1662 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49228/tests/kraken/driver.html?testName=audio-beat-detection) 08:41:07 INFO - PID 1662 | RSS: Main: 270491648 08:41:07 INFO - PID 1662 | 08:41:09 INFO - PID 1662 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49228/tests/kraken/driver.html?testName=audio-dft) 08:41:10 INFO - PID 1662 | RSS: Main: 278548480 08:41:10 INFO - PID 1662 | 08:41:13 INFO - PID 1662 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49228/tests/kraken/driver.html?testName=audio-fft) 08:41:14 INFO - PID 1662 | RSS: Main: 281260032 08:41:14 INFO - PID 1662 | 08:41:16 INFO - PID 1662 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49228/tests/kraken/driver.html?testName=audio-oscillator) 08:41:17 INFO - PID 1662 | RSS: Main: 283037696 08:41:17 INFO - PID 1662 | 08:41:18 INFO - PID 1662 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49228/tests/kraken/driver.html?testName=imaging-gaussian-blur) 08:41:19 INFO - PID 1662 | RSS: Main: 283471872 08:41:19 INFO - PID 1662 | 08:41:22 INFO - PID 1662 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49228/tests/kraken/driver.html?testName=imaging-darkroom) 08:41:23 INFO - PID 1662 | RSS: Main: 289193984 08:41:23 INFO - PID 1662 | 08:41:26 INFO - PID 1662 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49228/tests/kraken/driver.html?testName=imaging-desaturate) 08:41:26 INFO - PID 1662 | RSS: Main: 291946496 08:41:26 INFO - PID 1662 | 08:41:29 INFO - PID 1662 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49228/tests/kraken/driver.html?testName=json-parse-financial) 08:41:30 INFO - PID 1662 | RSS: Main: 291233792 08:41:30 INFO - PID 1662 | 08:41:32 INFO - PID 1662 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49228/tests/kraken/driver.html?testName=json-stringify-tinderbox) 08:41:32 INFO - PID 1662 | RSS: Main: 290738176 08:41:32 INFO - PID 1662 | 08:41:34 INFO - PID 1662 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49228/tests/kraken/driver.html?testName=stanford-crypto-aes) 08:41:35 INFO - PID 1662 | RSS: Main: 290414592 08:41:35 INFO - PID 1662 | 08:41:37 INFO - PID 1662 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49228/tests/kraken/driver.html?testName=stanford-crypto-ccm) 08:41:38 INFO - PID 1662 | RSS: Main: 290357248 08:41:38 INFO - PID 1662 | 08:41:41 INFO - PID 1662 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49228/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 08:41:41 INFO - PID 1662 | RSS: Main: 290471936 08:41:41 INFO - PID 1662 | 08:41:45 INFO - PID 1662 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49228/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 08:41:45 INFO - PID 1662 | RSS: Main: 290463744 08:41:45 INFO - PID 1662 | 08:41:47 INFO - PID 1662 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49228/tests/kraken/driver.html?testName=ai-astar) 08:41:47 INFO - PID 1662 | RSS: Main: 291913728 08:41:47 INFO - PID 1662 | 08:41:47 INFO - PID 1662 | __start_tp_report 08:41:47 INFO - PID 1662 | _x_x_mozilla_page_load 08:41:47 INFO - PID 1662 | _x_x_mozilla_page_load_details 08:41:47 INFO - PID 1662 | |i|pagename|runs| 08:41:47 INFO - PID 1662 | |0;ai-astar;106;117;115;114;113;114;112;116;114;113 08:41:47 INFO - PID 1662 | |1;audio-beat-detection;149;112;108;152;108;112;112;111;135;113 08:41:47 INFO - PID 1662 | |2;audio-dft;194;197;198;200;204;208;214;244;277;256 08:41:47 INFO - PID 1662 | |3;audio-fft;87;66;50;83;70;87;86;53;79;80 08:41:47 INFO - PID 1662 | |4;audio-oscillator;82;68;82;70;68;68;86;70;70;89 08:41:47 INFO - PID 1662 | |5;imaging-gaussian-blur;89;91;89;90;91;92;90;91;124;84 08:41:47 INFO - PID 1662 | |6;imaging-darkroom;98;98;98;96;99;96;97;97;99;97 08:41:47 INFO - PID 1662 | |7;imaging-desaturate;91;94;133;87;148;146;91;91;91;89 08:41:47 INFO - PID 1662 | |8;json-parse-financial;51;50;49;68;80;84;82;79;78;54 08:41:47 INFO - PID 1662 | |9;json-stringify-tinderbox;58;67;75;95;96;93;57;55;58;56 08:41:47 INFO - PID 1662 | |10;stanford-crypto-aes;88;89;100;114;114;87;91;89;111;80 08:41:47 INFO - PID 1662 | |11;stanford-crypto-ccm;137;139;139;126;133;154;139;161;136;150 08:41:47 INFO - PID 1662 | |12;stanford-crypto-pbkdf2;186;225;243;229;184;189;207;235;207;179 08:41:47 INFO - PID 1662 | |13;stanford-crypto-sha256-iterative;69;68;69;67;65;66;67;66;69;70 08:41:47 INFO - PID 1662 | __end_tp_report 08:41:47 INFO - PID 1662 | __start_cc_report 08:41:47 INFO - PID 1662 | _x_x_mozilla_cycle_collect,1199 08:41:47 INFO - PID 1662 | __end_cc_report 08:41:47 INFO - PID 1662 | __startTimestamp1496677307642__endTimestamp 08:41:47 INFO - PID 1662 | 08:41:47 INFO - PID 1662 | ------- Summary: start ------- 08:41:47 INFO - PID 1662 | Number of tests: 14 08:41:47 INFO - PID 1662 | 08:41:47 INFO - PID 1662 | [#0] ai-astar Cycles:10 Average:113.40 Median:114.00 stddev:2.99 (2.6%) stddev-sans-first:1.56 08:41:47 INFO - PID 1662 | Values: 106.0 117.0 115.0 114.0 113.0 114.0 112.0 116.0 114.0 113.0 08:41:47 INFO - PID 1662 | 08:41:47 INFO - PID 1662 | [#1] audio-beat-detection Cycles:10 Average:121.20 Median:112.50 stddev:17.27 (15.4%) stddev-sans-first:15.11 08:41:47 INFO - PID 1662 | Values: 149.0 112.0 108.0 152.0 108.0 112.0 112.0 111.0 135.0 113.0 08:41:47 INFO - PID 1662 | 08:41:47 INFO - PID 1662 | [#2] audio-dft Cycles:10 Average:219.20 Median:211.00 stddev:29.13 (13.8%) stddev-sans-first:29.44 08:41:47 INFO - PID 1662 | Values: 194.0 197.0 198.0 200.0 204.0 208.0 214.0 244.0 277.0 256.0 08:41:47 INFO - PID 1662 | 08:41:47 INFO - PID 1662 | [#3] audio-fft Cycles:10 Average:74.10 Median:81.50 stddev:13.83 (17.0%) stddev-sans-first:13.86 08:41:47 INFO - PID 1662 | Values: 87.0 66.0 50.0 83.0 70.0 87.0 86.0 53.0 79.0 80.0 08:41:47 INFO - PID 1662 | 08:41:47 INFO - PID 1662 | [#4] audio-oscillator Cycles:10 Average:75.30 Median:76.00 stddev:8.41 (11.1%) stddev-sans-first:8.56 08:41:47 INFO - PID 1662 | Values: 82.0 68.0 82.0 70.0 68.0 68.0 86.0 70.0 70.0 89.0 08:41:47 INFO - PID 1662 | 08:41:47 INFO - PID 1662 | [#5] imaging-gaussian-blur Cycles:10 Average:93.10 Median:90.50 stddev:11.08 (12.2%) stddev-sans-first:11.65 08:41:47 INFO - PID 1662 | Values: 89.0 91.0 89.0 90.0 91.0 92.0 90.0 91.0 124.0 84.0 08:41:47 INFO - PID 1662 | 08:41:47 INFO - PID 1662 | [#6] imaging-darkroom Cycles:10 Average:97.50 Median:98.00 stddev:1.08 (1.1%) stddev-sans-first:1.13 08:41:47 INFO - PID 1662 | Values: 98.0 98.0 98.0 96.0 99.0 96.0 97.0 97.0 99.0 97.0 08:41:47 INFO - PID 1662 | 08:41:47 INFO - PID 1662 | [#7] imaging-desaturate Cycles:10 Average:106.10 Median:91.00 stddev:25.36 (27.9%) stddev-sans-first:26.30 08:41:47 INFO - PID 1662 | Values: 91.0 94.0 133.0 87.0 148.0 146.0 91.0 91.0 91.0 89.0 08:41:47 INFO - PID 1662 | 08:41:47 INFO - PID 1662 | [#8] json-parse-financial Cycles:10 Average:67.50 Median:78.50 stddev:14.85 (18.9%) stddev-sans-first:14.50 08:41:47 INFO - PID 1662 | Values: 51.0 50.0 49.0 68.0 80.0 84.0 82.0 79.0 78.0 54.0 08:41:47 INFO - PID 1662 | 08:41:47 INFO - PID 1662 | [#9] json-stringify-tinderbox Cycles:10 Average:71.00 Median:71.00 stddev:17.42 (24.5%) stddev-sans-first:17.83 08:41:47 INFO - PID 1662 | Values: 58.0 67.0 75.0 95.0 96.0 93.0 57.0 55.0 58.0 56.0 08:41:47 INFO - PID 1662 | 08:41:47 INFO - PID 1662 | [#10] stanford-crypto-aes Cycles:10 Average:96.30 Median:87.50 stddev:12.53 (14.3%) stddev-sans-first:12.92 08:41:47 INFO - PID 1662 | Values: 88.0 89.0 100.0 114.0 114.0 87.0 91.0 89.0 111.0 80.0 08:41:47 INFO - PID 1662 | 08:41:47 INFO - PID 1662 | [#11] stanford-crypto-ccm Cycles:10 Average:141.40 Median:139.00 stddev:10.49 (7.5%) stddev-sans-first:11.01 08:41:47 INFO - PID 1662 | Values: 137.0 139.0 139.0 126.0 133.0 154.0 139.0 161.0 136.0 150.0 08:41:47 INFO - PID 1662 | 08:41:47 INFO - PID 1662 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:208.40 Median:216.00 stddev:23.44 (10.9%) stddev-sans-first:23.42 08:41:47 INFO - PID 1662 | Values: 186.0 225.0 243.0 229.0 184.0 189.0 207.0 235.0 207.0 179.0 08:41:47 INFO - PID 1662 | 08:41:47 INFO - PID 1662 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.60 Median:68.50 stddev:1.65 (2.4%) stddev-sans-first:1.67 08:41:47 INFO - PID 1662 | Values: 69.0 68.0 69.0 67.0 65.0 66.0 67.0 66.0 69.0 70.0 08:41:47 INFO - PID 1662 | -------- Summary: end -------- 08:41:47 INFO - PID 1662 | 08:41:48 INFO - TEST-INFO | 1662: exit 0 08:41:48 INFO - TEST-OK | kraken | took 90484ms 08:41:48 INFO - SUITE-END | took 1233s 08:41:48 INFO - Completed test suite (00:20:33) 08:41:48 INFO - javascript benchmark 08:41:48 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8209.861261361404, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3571.1607772173534, 3574.1958059436647, 3595.586507551632, 3601.7118630509563, 3612.4039533476976, 6110.346130329522, 6198.411150940665, 6220.377962203783, 6244.157038799761, 6255.655938923357, 6150.753969841212, 6151.0, 6180.103884936681, 6225.097360882669, 6273.874522509558, 4509.391232183662, 4996.475370086143, 5435.320584926884, 5467.261919640838, 5478.726063696799, 5564.638298510604, 5603.523700485463, 5611.018071837432, 5623.8594035149035, 5631.352394474627, 5142.871428214325, 5388.245645609618, 5428.104362780136, 5482.506574408282, 5534.944650553483, 4457.398251236074, 4952.628552858514, 5518.91721624176, 5523.143912693539, 5550.16747487876, 5469.343678758534, 5470.6043340880415, 5492.450754924475, 5509.696966666832, 5555.555555555546, 5176.0, 5178.766955486974, 5687.488126068673, 6229.37706229378, 6235.875282494325, 6681.365270299314, 6692.83267918301, 6729.8654026919185, 6735.730570777212, 6750.257471678112, 6788.6945087470685, 6827.317268273183, 6831.69257383419, 6835.043093966849, 6845.247022827485, 5093.719845408464, 6274.529410294246, 6335.651539165298, 6362.713677884506, 6372.936270637327, 6874.2094659113845, 6922.376986071177, 6924.0, 6933.861322773516, 6960.234374218832, 6312.842178945563, 6462.1276127723095, 6475.255345635313, 6587.670616469158, 6621.966890165519, 5553.861153471114, 6764.0530325754435, 6767.018782276542, 6791.728330866808, 6796.796096117026, 6429.839254018687, 6552.672366381662, 6654.73381064752, 6694.698738556679, 6698.799036028927, 6583.83540411484, 6602.0, 6672.366125218096, 6701.0953521274005, 6752.898706519406, 5321.122014867581, 5323.760430780624, 5325.3609566851455, 5354.651947623392, 5362.597805164628, 3903.6681882039775, 4697.506761790031, 4769.070031343924, 4792.041591681677, 4819.542143496362, 5403.70279634616, 5980.073088671263, 6210.937890621126, 6241.063840423882, 6317.9684101580115, 6787.1176747023555, 6810.455163586901, 6813.488988325896, 6819.829504262334, 6853.4174595158975, 6147.108669242934, 6291.55959082869, 6298.496120310364, 6313.273973493107, 6320.27316858558, 5754.4533269339345, 5827.621204621686, 5905.0, 5914.556408269397, 5954.404559544011, 6965.686544105527, 6982.68577913985, 6995.580265184105, 6997.985292132714, 7000.789976300719, 6795.252522222453, 6803.285655006351, 6811.0, 6837.794866154023, 6840.31596840312, 5669.943300566942, 6753.054572359775, 6815.863682726417, 6917.58494490312, 6971.47713921468, 6653.367930046637, 6674.0, 6710.530262881552, 6724.226713927961, 6733.42765864907, 6608.240052393844, 6823.249442561215, 6824.317568243335, 6835.658217089026, 6840.863182736417, 6408.455281301141, 6511.772087976898, 6561.540692151694, 6583.177102862142, 6588.143541339595, 6581.407673309425, 6591.406773390226, 6596.505262105457, 6599.670016499252, 6605.306442823435, 6446.645434501147, 6466.288708241996, 6483.740650373932, 6483.902741458787, 6492.935070649233, 6401.487880969418, 6459.192600924885, 6574.342565743387, 6580.473562115115, 6597.373249541286, 6406.583572067801, 6436.517261205522, 6447.2263328400895, 6488.253850807028, 6493.155889734304], "value": 6093.767708740759, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [771.3135309574476, 812.8008637883718, 839.5466448118017, 1267.8098285257215, 1320.8018797180428, 38478.61521384785, 39524.2095158097, 39525.01187470311, 39540.80229598855, 39676.60323396765, 32676.346473070524, 39843.80078099613, 40019.39970900431, 40514.797426012905, 40524.59475405245, 7856.17510161432, 8190.303824174952, 8320.958395208016, 8458.196471335244, 8492.278156356717, 8337.708180213695, 8399.286060684819, 8419.07390187079, 8434.114417986115, 8441.366897482683, 8355.749327520183, 8420.031696354936, 8456.196661317164, 8472.74581762548, 8481.915180848206, 8316.209960053786, 8355.665773369057, 8422.073571907116, 8454.112318206562, 8472.999999999969, 8203.75388738333, 8251.587420628945, 8271.669133234724, 8280.751577452686, 8355.0, 37262.25475490502, 37413.87758367253, 37465.25069498622, 37501.06247343811, 37573.43639845405, 3539.6460353964653, 3577.0342007658046, 3579.624139465345, 3581.068922080252, 3582.0865679251624, 30694.53958190629, 30784.230394240098, 30803.537946930814, 30843.691563084307, 30878.764849405998, 3052.267455810612, 3082.1678146900213, 3088.4903990841485, 3098.8450577471262, 3110.548970399302, 240866.7956660223, 244485.33272000935, 273008.999999998, 294070.0, 294333.0, 38099.61900380961, 46813.0, 46867.0, 47197.52802472, 47200.7639961798, 48246.5175348249, 49088.0, 49307.75346123318, 55402.61493462696, 56121.87756244852, 14360.994730368982, 14415.990880638465, 14518.419263229353, 14557.490487832874, 14569.271536423348, 11352.318860868374, 11510.712232194262, 11532.88467115335, 11664.066874650007, 11696.941515292368, 9661.0, 14239.074460160056, 14263.500777472738, 14277.286135693173, 14439.494617688331, 6248.5313601480075, 6253.53098517613, 6262.561620686508, 6311.558190926683, 6316.147320111726, 842.9884139033185, 959.3524371049514, 960.4381436859376, 1032.865727455426, 1033.7725700345911, 15770.369185232692, 22488.437789055406, 23235.883820581126, 24085.916133774022, 25048.373790655016, 1083.989160108389, 1101.091599430473, 1134.183387960667, 1155.1798223261578, 1156.4911438966828, 11269.774604507864, 12506.49974001048, 12535.686607834694, 12537.937310313573, 12558.623241302776, 318939.405302972, 319304.0, 320270.0, 323202.0, 323886.0, 54737.0, 55319.446805532236, 55424.0, 55530.44469555253, 55645.165322520195, 1473.5652982370227, 1527.0074451606322, 1529.9541013769606, 1532.7087853307835, 1541.352631894607, 22777.0, 22995.195168169033, 23891.522169556163, 24343.51312973766, 24793.256202313958, 33623.0, 34712.13219669528, 37519.81240093782, 37902.0, 38107.42838857364, 274484.2964133238, 276964.0, 277239.6138019297, 277707.0, 278414.2555010565, 34067.65932340645, 36108.63891361053, 36122.0, 36160.09599760027, 36241.0, 44261.0, 44927.652170433634, 46466.0, 46565.76717116528, 46576.7671161642, 28209.0, 30292.5456118154, 30770.846145769126, 31535.684643153276, 32149.0, 30021.649025793457, 31100.533491998092, 31250.374992500478, 31779.52330714995, 31859.840700797275, 37160.0, 37252.62747372492, 37778.62221377861, 38017.429738553394, 38090.61909380871, 29237.7076229235, 30257.24356891095, 31829.0, 32036.839815801704, 32162.678373215967, 22644.66033009473, 22790.658140128242, 23213.53572928498, 23625.645615316127, 25085.87457062703], "value": 17512.48160910097, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [368.4988415754572, 416.7957700726643, 428.5992596921876, 450.18291800382303, 460.13264995483513, 15384.0, 22956.426089347773, 22961.655575166365, 23032.848357582137, 24162.758372416272, 25060.498790024234, 25387.0, 25684.614730779005, 25694.871525642393, 25737.35656608586, 6853.7943861684225, 6938.167419909592, 7025.262347453495, 7042.873140297554, 7045.718171273143, 5881.411858814127, 6743.35938085881, 6841.897371539431, 6899.862002759942, 6917.550359226634, 5782.277215348082, 6802.489813264, 6830.351116643936, 6898.896516552256, 6905.067815844872, 6861.382475577197, 6871.072405225281, 6894.5518541294905, 6903.896441553381, 6905.102336696247, 5705.372409035002, 6649.135612370409, 6728.697208625575, 6767.187937447488, 6786.796396108075, 30693.079207623807, 31337.84331078353, 31410.37179256402, 31582.526262106087, 31767.52348714771, 2305.0779688124717, 2333.346662934381, 2337.0885354711677, 2353.882305884699, 2359.3865594945437, 19892.70160947587, 20229.291974780957, 20424.897875510676, 20473.692894606593, 20483.0, 2321.721393432782, 2353.5645905507536, 2416.5408572371193, 2418.4316685578874, 2418.480026794248, 167529.0, 174084.25915740934, 190535.09464905172, 201866.99066504574, 205820.0, 25111.874440628046, 26322.47355052888, 27548.586771198447, 27571.89712411521, 27628.30929226745, 43709.34435983463, 43810.7809460957, 43865.78067109644, 43953.34069988953, 44086.0, 8166.795830104176, 9544.809103818023, 10984.395858227714, 11511.251768635011, 11638.534458621729, 9157.679481218258, 9193.862092068624, 9201.21789647874, 9315.0, 9362.016988216275, 12247.632571022883, 12338.876611233773, 12374.319412432402, 12375.75248495025, 12450.25298482094, 4838.7338696372035, 4962.0, 4970.850874473772, 4981.32752078472, 5010.624203184703, 799.4803377804474, 882.7307671160357, 924.5793164110448, 932.1331162019252, 984.8965858584888, 20837.79162208389, 21061.47346316324, 21352.78647213539, 21757.0, 21779.45551361197, 972.4943029624555, 973.5570315506427, 1016.5679586175846, 1017.2269075502523, 1020.6733845169623, 9165.625156226486, 10560.52477638508, 10767.030967212988, 10981.505832237568, 11149.0, 450675.98648026853, 513171.0, 525416.0, 529899.0, 549920.2503987534, 149289.2535537337, 152171.47828521795, 152375.0, 152780.47219527885, 152963.47036529714, 1140.8117660586076, 1160.7272291011604, 1184.9407529623657, 1189.108168873345, 1190.3096204201543, 15646.21768911527, 17957.461276161735, 18965.90517047406, 19290.324838630582, 19754.308599198957, 67899.98150027655, 70018.04846588097, 70483.0, 71075.93386099316, 71344.0, 86118.0, 91393.54303228442, 92216.53891730498, 95941.56087658831, 96391.51804240934, 10324.896751032695, 10709.785804284027, 10723.571057157626, 10728.570857165938, 10760.0, 18040.00779957115, 18507.16717747678, 18656.533586659898, 18744.250229991194, 18749.1562879668, 13575.0, 14198.716025679636, 15150.3182356792, 15238.695226095639, 15388.84611153919, 10716.571337146426, 13190.34048297562, 13532.93233533826, 13620.250886201355, 13878.375473103533, 15360.694340981141, 15554.533363999099, 15743.370265189264, 15743.44897928567, 15761.0, 10415.791684166426, 10533.210009249491, 10651.946740266249, 10693.358398496115, 10714.303570267908, 14932.701345973237, 15118.09291442517, 15120.773188401963, 15161.393544258106, 15260.542183734506], "value": 12509.429583827272, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1236.6351926181776, 1446.0311591233872, 1456.548469974308, 1459.5329494561745, 1464.7143806957645, 9597.328187026911, 11786.941065294672, 12343.814842777354, 12368.567100151497, 12405.627831165057, 12081.395930203498, 12227.877721222785, 12240.0, 12240.510379584828, 12249.755004899918, 2545.974540254597, 2586.689597248333, 2587.32729490332, 2593.209071233273, 2597.8441293522355, 2140.197425965263, 2228.476308067603, 2506.686664166979, 2524.4193835417864, 2598.792096632265, 2579.1102069785893, 2579.484103179362, 2603.2450589329073, 2605.6091586262023, 2606.335384476956, 2115.756687980871, 2541.898324067035, 2556.207575651554, 2556.923292301234, 2564.0513010186255, 2178.4227179797367, 2435.7442468540903, 2531.4683916377585, 2534.4170840706556, 2543.8219324647284, 11798.0, 11893.88106118945, 12070.0, 12079.818802717966, 12081.697957551043, 6486.0, 6678.599284042973, 6717.193936727624, 6756.0, 6770.052192693027, 4132.483439570054, 4250.341197114421, 4270.572942705736, 4335.934960975588, 4346.326319420495, 1064.6380230721513, 1263.532492977595, 1277.9840027178416, 1288.5940928607458, 1292.231122482131, 16558.83441165585, 16752.078635675032, 16862.409815656392, 16883.40908068224, 16912.408065717642, 4075.5109386873173, 5503.036968530491, 5541.362743284493, 5583.497485226349, 5704.372519022904, 12332.50669973191, 12430.627081187578, 12431.440585173688, 12432.316222607844, 12439.689007774698, 5116.923246151311, 6442.226932768098, 6551.1155993941165, 6558.311377305315, 6584.0, 5954.851128721816, 6116.26604807426, 6195.721192546346, 6224.346443623445, 6277.121203031579, 3847.7306588539095, 4333.351258598019, 4537.8411755588395, 4561.0, 4582.24392975162, 1184.1710802438156, 1189.8453201083803, 1290.490256348737, 1306.8105124756858, 1312.750577390312, 799.3325573146371, 813.986586699551, 829.0838623321143, 835.2107258640526, 859.4671303791689, 6643.634600096944, 7005.0, 7281.1990681025045, 7380.298871607189, 7521.8119547010665, 856.7943693513512, 868.900076491199, 938.8122375524922, 963.4075043847984, 963.556763888605, 4220.282551966173, 4478.232876033863, 4655.627549796008, 4815.879603009953, 4829.6860704054125, 14968.40126394932, 15307.46423875159, 15309.234538273264, 15461.5361539154, 15462.458813941683, 14003.649908752363, 14335.56993290203, 14500.85499144995, 14786.334614942354, 14804.925975370053, 8102.7569172924905, 8357.791055223546, 8639.481631102028, 8672.56637168139, 8674.262687671493, 6960.825979350557, 6997.440204783707, 7457.664405101892, 7566.054243219598, 7678.0, 13198.07613467048, 13344.0, 13462.124961877447, 13508.25704586218, 13518.72962540724, 13849.515266965607, 13929.512467064009, 13966.441342346192, 13975.0, 14153.80616004521, 10849.294795838245, 10854.29447085937, 10909.18181136402, 11016.394098324668, 11076.335419874833, 10856.0, 10909.672709818718, 10961.232713709964, 10967.232293739362, 10974.341539507335, 9055.230305424113, 9318.254539636677, 9377.906220937703, 9412.011738767605, 9484.288678349289, 9092.272618190396, 9166.862497062682, 9226.35415520907, 9277.67528136512, 9435.29235307341, 8289.33685305162, 8341.45780524264, 8413.70552030676, 8430.789230269047, 8433.704820331504, 6124.510039196764, 6362.554621176473, 6442.35576442352, 6453.0, 6535.2811190768025, 5422.8373148805595, 6334.809955701337, 6571.441427478527, 6637.734490620514, 6684.331566843277], "value": 5495.5286093284585, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [361.68714062336085, 364.4569591308949, 385.0777388155368, 386.2583839029064, 412.83899279281076, 5400.324959380077, 5541.866381443153, 6449.484041276693, 6527.673616319189, 6582.848001599719, 5426.6704657359005, 5975.492083172936, 6380.872382552347, 6384.170057892467, 6391.968040159804, 3951.308521008812, 4857.174280372327, 4874.780634871439, 4876.488288630536, 4880.438749543795, 4206.705530612843, 4376.97811510942, 4557.245460497716, 4686.625069994409, 4892.902141957176, 4661.720296782204, 4679.953200468003, 4690.130642220165, 4690.906181876378, 4696.17816882046, 3842.5004749382542, 4606.3551102845295, 4640.651951103647, 4646.512116227781, 4683.4544600282, 3237.352529494087, 3504.316658251642, 3962.6631736302447, 3973.642372186517, 3986.3223252046932, 6277.497800175975, 6289.528285378569, 6298.527610429236, 6316.210473690789, 6343.619382836999, 4542.863714088583, 5573.27547418837, 5589.133684278943, 5599.32808063035, 5626.0, 5615.5507559395555, 6229.6573688447115, 6583.012548117773, 6900.171979362459, 6919.584824910521, 4217.511668115796, 4572.565606267399, 4594.2419500782335, 4597.770111494412, 4612.293451422995, 7282.526635768658, 7350.558966462083, 7360.852782944311, 7370.77887663371, 7373.5575865448245, 4625.0, 4651.465081515601, 4664.253719404946, 4680.134175177603, 4682.969746655798, 4736.0, 4751.714897106184, 4759.619230461555, 4760.428748550196, 4774.7135171889795, 4925.729084900365, 5079.085764562415, 5271.629376362097, 5571.526420254243, 5623.240862483594, 5741.3614450211435, 6485.221773387224, 6938.653067346612, 6943.305669433016, 6980.650967451607, 1317.0846261847923, 1510.6781566129637, 1558.7194305025205, 1565.4677409680762, 1623.683381740573, 905.4431524612447, 1054.2936232724094, 1066.258950029722, 1067.2422579968309, 1069.8716154061563, 6055.364186760326, 6468.223813142453, 6479.546431749826, 6480.902786458207, 6495.512836537184, 6257.43683068526, 7145.178304494945, 7179.52819671966, 7191.496595238283, 7194.712211511585, 5092.465291144451, 6175.938240617536, 6477.093206951031, 6487.708053137526, 6508.772192973223, 3079.476488996876, 3718.981405092957, 3722.925541489209, 3725.9381076393097, 3743.21392507571, 7298.343149116498, 7345.963270183722, 7365.742199023009, 7379.446541509408, 7406.444516661272, 7073.135380171039, 7282.5630462171375, 7363.558186508827, 7428.034355533854, 7589.8861517077285, 6680.265170831205, 7182.3894968928225, 7388.852222955618, 7397.334239918433, 7397.8150546236775, 7158.355747982707, 7167.426605871622, 7169.964150179216, 7170.565886822552, 7187.5687458752645, 6316.810495685137, 6365.267994180728, 6622.1722284714415, 6943.6323383158115, 7004.7198112076985, 7355.448341374311, 7362.153352364547, 7363.595002274927, 7372.839012691031, 7393.482456228013, 4511.684182107221, 4631.212693841988, 4668.206404911105, 4677.064587082529, 4686.367340409138, 6670.898666919905, 7122.715091396286, 7203.8919416207755, 7229.63851807397, 7284.890726638999, 6518.73925043012, 6698.698558564779, 6945.201301850352, 7006.0, 7042.647867606702, 4994.675346102492, 6267.524327890549, 6556.475481961527, 6574.685062987326, 6690.665466726741, 6011.557226265752, 6507.414332710079, 6572.76995305162, 6578.243501997332, 6593.340665933561, 6419.903701444388, 6657.53397262187, 6764.594124352555, 6766.391024807791, 6803.455723542006, 6048.5161187105805, 6525.1517302750335, 6539.705713243011, 6547.443467305142, 6609.4712423005085], "value": 4963.007587718716, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [820.5158956215834, 820.7865954851736, 826.4710585225453, 850.1371108325047, 865.3380164174406, 14987.700245995073, 15447.69104617907, 17110.486685399428, 17628.91185544072, 17733.64532709345, 16142.354305827752, 17855.285788568475, 18097.1856266468, 18166.727499087523, 18168.09159542021, 834.6619619054293, 965.1940629574297, 986.2603047714215, 989.9554520046579, 993.8956409576999, 965.7247684409945, 980.0885176785587, 993.6770549571382, 996.8903420623726, 998.0817647764038, 940.0176815228103, 1002.1281485107967, 1051.1958351860833, 1051.416463862556, 1060.4379678770265, 1026.3892983674739, 1037.647199952016, 1046.9528871200814, 1051.1275641217771, 1051.7160366700987, 752.3153929923743, 755.9848803023908, 757.4735558786646, 757.9962100189518, 759.4911409355744, 17401.651966960715, 17479.21343539531, 17566.82433175665, 17640.117994100245, 17700.645987080185, 17670.293188272455, 17804.91097544504, 18059.187336569885, 18166.90916545409, 18213.7267940981, 14601.561953141316, 14726.0, 14903.776443353358, 14903.850961490354, 14906.478273260384, 3526.1889765354113, 3555.2889422115422, 3575.249197668493, 3597.7407907232523, 3663.7435379523445, 116903.83096169014, 119659.4017029918, 136504.31747841297, 139406.3029684855, 139561.3021934894, 13572.1856688599, 13701.862981370059, 13820.308984550733, 13832.792508112387, 13878.861211387757, 26439.867800660875, 26900.865495672395, 26919.0, 27177.592336114973, 27265.0, 7530.510516816389, 7884.0, 7932.920670793334, 7967.2431119043595, 8010.239027292398, 3422.4769977360334, 3896.649301562816, 3908.5309762828074, 3971.6028397160053, 3976.0, 4212.347086201644, 4297.205017071852, 4298.871033868989, 4309.245881970643, 4312.935305970413, 931.7344556801279, 944.6788092048737, 947.6920000999703, 951.6003278622995, 954.8472244440856, 623.3921926122085, 626.4988009592308, 636.4939872801134, 660.1418156396666, 663.9774746889832, 11643.88356116445, 12852.100352975202, 12928.482860685655, 13113.868861311455, 14227.0, 626.3736263736264, 638.5466318913531, 645.8837409266283, 659.2385794407467, 660.7489154121403, 5491.697956612425, 5555.1667249912025, 5582.665040097526, 5607.52336051432, 5804.129380592861, 126814.0, 130040.0, 131218.0, 131670.0, 131896.0, 109732.4513377444, 110642.44678776554, 110737.44631276792, 110749.0, 113103.86896131097, 82543.99999999879, 93951.0, 96541.06917861744, 100373.49066273401, 103171.4841425788, 8802.163794439519, 8871.689490867788, 11072.612458563912, 11653.825192622287, 11759.882401175879, 39770.80114599408, 41112.38331425091, 41261.58738412578, 41335.0, 41780.791096044326, 56621.58446038882, 56643.71678141583, 56847.71576142093, 56989.71505142448, 57829.132563010746, 14493.78259326132, 14839.480618178311, 15197.544073677807, 15371.538853834403, 15371.538853834403, 15701.136437496047, 16467.835321647108, 16485.34058637641, 16838.831611683727, 16877.91561042187, 13044.869551304746, 15191.316390762222, 15462.613434664223, 15563.688726225639, 15593.376264949275, 14360.712785744436, 14424.990250682351, 14441.49454769078, 14570.781438278646, 14585.562433127023, 14582.781258280922, 14694.24540588191, 14916.0, 15152.393904243707, 15298.082115073132, 10159.390436573829, 10670.253082284165, 10754.784904301714, 11101.611443599435, 11118.332900026024, 11813.82279265829, 11988.400579971141, 12026.0, 12090.06848296869, 12112.454939527566], "value": 8409.261176233189, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1552.1000000000001, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [106.0, 117.0, 115.0, 114.0, 113.0, 114.0, 112.0, 116.0, 114.0, 113.0], "value": 113.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 112.0, 108.0, 152.0, 108.0, 112.0, 112.0, 111.0, 135.0, 113.0], "value": 121.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [194.0, 197.0, 198.0, 200.0, 204.0, 208.0, 214.0, 244.0, 277.0, 256.0], "value": 219.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 66.0, 50.0, 83.0, 70.0, 87.0, 86.0, 53.0, 79.0, 80.0], "value": 74.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 68.0, 82.0, 70.0, 68.0, 68.0, 86.0, 70.0, 70.0, 89.0], "value": 75.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 91.0, 89.0, 90.0, 91.0, 92.0, 90.0, 91.0, 124.0, 84.0], "value": 93.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [98.0, 98.0, 98.0, 96.0, 99.0, 96.0, 97.0, 97.0, 99.0, 97.0], "value": 97.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 94.0, 133.0, 87.0, 148.0, 146.0, 91.0, 91.0, 91.0, 89.0], "value": 106.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [51.0, 50.0, 49.0, 68.0, 80.0, 84.0, 82.0, 79.0, 78.0, 54.0], "value": 67.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 67.0, 75.0, 95.0, 96.0, 93.0, 57.0, 55.0, 58.0, 56.0], "value": 71.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 89.0, 100.0, 114.0, 114.0, 87.0, 91.0, 89.0, 111.0, 80.0], "value": 96.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [137.0, 139.0, 139.0, 126.0, 133.0, 154.0, 139.0, 161.0, 136.0, 150.0], "value": 141.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [186.0, 225.0, 243.0, 229.0, 184.0, 189.0, 207.0, 235.0, 207.0, 179.0], "value": 208.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 68.0, 69.0, 67.0, 65.0, 66.0, 67.0, 66.0, 69.0, 70.0], "value": 67.6, "unit": "score"}]}]} 08:41:48 INFO - Return code: 0 08:41:49 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 08:41:49 INFO - # TBPL SUCCESS # 08:41:49 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:41:49 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:41:49 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:41:49 INFO - 2017-06-05 08:41:49.047 screenresolution[1670:11690] starting screenresolution argv=screenresolution get 08:41:49 INFO - 2017-06-05 08:41:49.050 screenresolution[1670:11690] Display 0: 1600x1200x32@60 08:41:49 INFO - 2017-06-05 08:41:49.056 screenresolution[1671:11693] starting screenresolution argv=screenresolution list 08:41:49 INFO - Available Modes on Display 0 08:41:49 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:41:49 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:41:49 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:41:49 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:41:49 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:41:49 INFO - Intel Iris: 08:41:49 INFO - Chipset Model: Intel Iris 08:41:49 INFO - Type: GPU 08:41:49 INFO - Bus: Built-In 08:41:49 INFO - VRAM (Dynamic, Max): 1536 MB 08:41:49 INFO - Vendor: Intel (0x8086) 08:41:49 INFO - Device ID: 0x0a2e 08:41:49 INFO - Revision ID: 0x0009 08:41:49 INFO - Displays: 08:41:49 INFO - Display: 08:41:49 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:41:49 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:41:49 INFO - Display Serial Number: 1600x1200 60 08:41:49 INFO - Main Display: Yes 08:41:49 INFO - Mirror: Off 08:41:49 INFO - Online: Yes 08:41:49 INFO - Rotation: Supported 08:41:49 INFO - Return code: 0 08:41:49 INFO - Running post-action listener: _resource_record_post_action 08:41:49 INFO - [mozharness: 2017-06-05 15:41:49.291639Z] Finished run-tests step (success) 08:41:49 INFO - Running post-run listener: _resource_record_post_run 08:41:49 INFO - Total resource usage - Wall time: 1256s; CPU: 53.0%; Read bytes: 35550208; Write bytes: 572016640; Read time: 332; Write time: 1793 08:41:49 INFO - TinderboxPrint: CPU usage
53.2% 08:41:49 INFO - TinderboxPrint: I/O read bytes / time
35,550,208 / 332 08:41:49 INFO - TinderboxPrint: I/O write bytes / time
572,016,640 / 1,793 08:41:49 INFO - TinderboxPrint: CPU idle
2,353.4 (46.8%) 08:41:49 INFO - TinderboxPrint: CPU system
1,018.0 (20.3%) 08:41:49 INFO - TinderboxPrint: CPU user
1,654.3 (32.9%) 08:41:49 INFO - TinderboxPrint: Swap in / out
278,118,400 / 0 08:41:49 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 229689856; Write bytes: 230536704; Read time: 16750; Write time: 460 08:41:49 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 08:41:49 INFO - run-tests - Wall time: 1235s; CPU: 53.0%; Read bytes: 34538496; Write bytes: 323039744; Read time: 316; Write time: 1246 08:41:49 INFO - Running post-run listener: _upload_blobber_files 08:41:49 INFO - Blob upload gear active. 08:41:49 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 08:41:49 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 08:41:49 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', 'Cedar', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 08:41:49 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 Cedar -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 08:41:50 INFO - (blobuploader) - INFO - Open directory for files ... 08:41:50 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 08:41:50 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:41:50 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:41:51 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 08:41:51 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:41:51 INFO - (blobuploader) - INFO - Done attempting. 08:41:51 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 08:41:51 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:41:51 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:41:52 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 08:41:52 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:41:52 INFO - (blobuploader) - INFO - Done attempting. 08:41:52 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 08:41:52 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:41:52 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:41:53 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 08:41:53 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:41:53 INFO - (blobuploader) - INFO - Done attempting. 08:41:53 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 08:41:53 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:41:53 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:41:55 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 08:41:55 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:41:55 INFO - (blobuploader) - INFO - Done attempting. 08:41:55 INFO - (blobuploader) - INFO - Iteration through files over. 08:41:55 INFO - Return code: 0 08:41:55 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 08:41:55 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 08:41:55 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/47a59b33c114a2283606a3be3c1d2420d5fb0971a1f97ebdfcad380087efb642eab7c2c5e100125ccb507e07340a2223a49bf1511211f296b7e58ddf8bf34a4a", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/1fbf095963cabd288596b6140b28e7840e6dabd01701cbb5877265928e692f0a8595c17c3b6aa4bcf987a991bd436e794f7c495d2970209377c761886f5a8bc6", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/0562558a549042097e442667b9a78a6d858b1e409dd0b5c64c2fbe1c03427332fa3d512d2a31d6febbb9655f99e999611216c06c6cedd07fd1f6c9de815bf12f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/fdf934ea33d94464682620129a4083509b5fe732b4d3f7de9a365a8a6fd48d402d912d9bc200b2fd1cba893227308cacaa6ed09c85cbd9f7143071c2a04c703a"} 08:41:55 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 08:41:55 INFO - Writing to file /builds/slave/test/properties/blobber_files 08:41:55 INFO - Contents: 08:41:55 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/47a59b33c114a2283606a3be3c1d2420d5fb0971a1f97ebdfcad380087efb642eab7c2c5e100125ccb507e07340a2223a49bf1511211f296b7e58ddf8bf34a4a", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/1fbf095963cabd288596b6140b28e7840e6dabd01701cbb5877265928e692f0a8595c17c3b6aa4bcf987a991bd436e794f7c495d2970209377c761886f5a8bc6", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/0562558a549042097e442667b9a78a6d858b1e409dd0b5c64c2fbe1c03427332fa3d512d2a31d6febbb9655f99e999611216c06c6cedd07fd1f6c9de815bf12f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/fdf934ea33d94464682620129a4083509b5fe732b4d3f7de9a365a8a6fd48d402d912d9bc200b2fd1cba893227308cacaa6ed09c85cbd9f7143071c2a04c703a"} 08:41:55 INFO - Running post-run listener: copy_logs_to_upload_dir 08:41:55 INFO - Copying logs to upload dir... 08:41:55 INFO - mkdir: /builds/slave/test/build/upload/logs 08:41:55 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1345.653338 ========= master_lag: 2.30 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 27 secs) (at 2017-06-05 08:41:58.224408) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-05 08:41:58.230121) ========= 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.RgFP1d71XQ/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.5CUoSBfjYo/Listeners TMPDIR=/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/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/Cedar/sha512/47a59b33c114a2283606a3be3c1d2420d5fb0971a1f97ebdfcad380087efb642eab7c2c5e100125ccb507e07340a2223a49bf1511211f296b7e58ddf8bf34a4a", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/1fbf095963cabd288596b6140b28e7840e6dabd01701cbb5877265928e692f0a8595c17c3b6aa4bcf987a991bd436e794f7c495d2970209377c761886f5a8bc6", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/0562558a549042097e442667b9a78a6d858b1e409dd0b5c64c2fbe1c03427332fa3d512d2a31d6febbb9655f99e999611216c06c6cedd07fd1f6c9de815bf12f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/fdf934ea33d94464682620129a4083509b5fe732b4d3f7de9a365a8a6fd48d402d912d9bc200b2fd1cba893227308cacaa6ed09c85cbd9f7143071c2a04c703a"} build_url:https://queue.taskcluster.net/v1/task/HU4fY-aZS-KIo0KtEXp7tw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011169 build_url: 'https://queue.taskcluster.net/v1/task/HU4fY-aZS-KIo0KtEXp7tw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/47a59b33c114a2283606a3be3c1d2420d5fb0971a1f97ebdfcad380087efb642eab7c2c5e100125ccb507e07340a2223a49bf1511211f296b7e58ddf8bf34a4a", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/1fbf095963cabd288596b6140b28e7840e6dabd01701cbb5877265928e692f0a8595c17c3b6aa4bcf987a991bd436e794f7c495d2970209377c761886f5a8bc6", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/0562558a549042097e442667b9a78a6d858b1e409dd0b5c64c2fbe1c03427332fa3d512d2a31d6febbb9655f99e999611216c06c6cedd07fd1f6c9de815bf12f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/fdf934ea33d94464682620129a4083509b5fe732b4d3f7de9a365a8a6fd48d402d912d9bc200b2fd1cba893227308cacaa6ed09c85cbd9f7143071c2a04c703a"}' ========= master_lag: 0.54 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-05 08:41:58.780764) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-05 08:41:58.781100) ========= 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.RgFP1d71XQ/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.5CUoSBfjYo/Listeners TMPDIR=/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/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.005028 ========= master_lag: 0.02 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-05 08:41:58.810775) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-05 08:41:58.811120) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-05 08:41:58.811447) ========= ========= Total master_lag: 2.93 =========