builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0064 starttime: 1495431014.6 results: success (0) revision: 9851fcb0bf4d855c36729d7de19f0fa5c9f69776 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-05-21 22:30:14.604137) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-05-21 22:30:14.604628) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-21 22:30:14.604918) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-21 22:30:14.629700) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-21 22:30:14.630088) ========= 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.rNRNCuS2dh/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.sLaJ1JWk69/Listeners TMPDIR=/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-05-21 22:30:14-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 14.7M=0.001s 2017-05-21 22:30:14 (14.7 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.201792 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-21 22:30:14.854312) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-21 22:30:14.854621) ========= 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.rNRNCuS2dh/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.sLaJ1JWk69/Listeners TMPDIR=/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/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.028535 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-21 22:30:14.903779) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-21 22:30:14.904123) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 9851fcb0bf4d855c36729d7de19f0fa5c9f69776 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'python archiver_client.py mozharness --repo mozilla-central --rev 9851fcb0bf4d855c36729d7de19f0fa5c9f69776 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.rNRNCuS2dh/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.sLaJ1JWk69/Listeners TMPDIR=/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-05-21 22:30:14,997 truncating revision to first 12 chars 2017-05-21 22:30:14,998 Setting DEBUG logging. 2017-05-21 22:30:14,998 attempt 1/10 2017-05-21 22:30:14,998 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/9851fcb0bf4d?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-05-21 22:30:16,271 unpacking tar archive at: mozilla-central-9851fcb0bf4d/testing/mozharness/ program finished with exit code 0 elapsedTime=1.571933 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-21 22:30:16.497402) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-21 22:30:16.497733) ========= script_repo_revision: 9851fcb0bf4d855c36729d7de19f0fa5c9f69776 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-21 22:30:16.498238) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-21 22:30:16.498508) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-21 22:30:16.517086) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 18 secs) (at 2017-05-21 22:30:16.517530) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 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', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.rNRNCuS2dh/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.sLaJ1JWk69/Listeners TMPDIR=/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/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 22:30:16 INFO - MultiFileLogger online at 20170521 22:30:16 in /builds/slave/test 22:30:16 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 22:30:16 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 22:30:16 INFO - {'append_to_log': False, 22:30:16 INFO - 'base_work_dir': '/builds/slave/test', 22:30:16 INFO - 'blob_upload_branch': 'Firefox', 22:30:16 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 22:30:16 INFO - 'branch': 'Firefox', 22:30:16 INFO - 'buildbot_json_path': 'buildprops.json', 22:30:16 INFO - 'config_files': ('talos/mac_config.py',), 22:30:16 INFO - 'default_actions': ('clobber', 22:30:16 INFO - 'read-buildbot-config', 22:30:16 INFO - 'download-and-extract', 22:30:16 INFO - 'populate-webroot', 22:30:16 INFO - 'create-virtualenv', 22:30:16 INFO - 'install', 22:30:16 INFO - 'run-tests'), 22:30:16 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 22:30:16 INFO - 'download_minidump_stackwalk': True, 22:30:16 INFO - 'download_symbols': 'ondemand', 22:30:16 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 22:30:16 INFO - 'tooltool.py': '/tools/tooltool.py', 22:30:16 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 22:30:16 INFO - '/tools/misc-python/virtualenv.py')}, 22:30:16 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 22:30:16 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 22:30:16 INFO - 'gecko_profile': False, 22:30:16 INFO - 'gecko_profile_interval': 0, 22:30:16 INFO - 'installer_path': 'installer.exe', 22:30:16 INFO - 'log_level': 'info', 22:30:16 INFO - 'log_name': 'talos', 22:30:16 INFO - 'log_to_console': True, 22:30:16 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 22:30:16 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 22:30:16 INFO - 'opt_config_files': (), 22:30:16 INFO - 'pip_index': False, 22:30:16 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 22:30:16 INFO - 'cmd': ('bash', 22:30:16 INFO - '-c', 22:30:16 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 22:30:16 INFO - 'enabled': True, 22:30:16 INFO - 'halt_on_failure': False, 22:30:16 INFO - 'name': 'check_screen_resolution'},), 22:30:16 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 22:30:16 INFO - 'cmd': ('bash', 22:30:16 INFO - '-c', 22:30:16 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 22:30:16 INFO - 'enabled': True, 22:30:16 INFO - 'halt_on_failure': False, 22:30:16 INFO - 'name': 'check_screen_resolution'},), 22:30:16 INFO - 'run_cmd_checks_enabled': True, 22:30:16 INFO - 'suite': 'dromaeojs-e10s', 22:30:16 INFO - 'system_bits': '32', 22:30:16 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 22:30:16 INFO - 'title': 't-yosemite-r7-0064', 22:30:16 INFO - 'tooltool_cache': '/builds/tooltool_cache', 22:30:16 INFO - 'use_talos_json': True, 22:30:16 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 22:30:16 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 22:30:16 INFO - 'work_dir': 'build'} 22:30:16 INFO - [mozharness: 2017-05-22 05:30:16.722807Z] Running clobber step. 22:30:16 INFO - Running pre-action listener: _resource_record_pre_action 22:30:16 INFO - Running main action method: clobber 22:30:16 INFO - rmtree: /builds/slave/test/build 22:30:16 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 22:30:19 INFO - Running post-action listener: _resource_record_post_action 22:30:19 INFO - [mozharness: 2017-05-22 05:30:19.318361Z] Finished clobber step (success) 22:30:19 INFO - [mozharness: 2017-05-22 05:30:19.318583Z] Running read-buildbot-config step. 22:30:19 INFO - Running pre-action listener: _resource_record_pre_action 22:30:19 INFO - Running main action method: read_buildbot_config 22:30:19 INFO - Using buildbot properties: 22:30:19 INFO - { 22:30:19 INFO - "project": "", 22:30:19 INFO - "product": "firefox", 22:30:19 INFO - "who": "ihsiao@mozilla.com", 22:30:19 INFO - "installer_path": "public/build/firefox-55.0a1.en-US.mac.dmg", 22:30:19 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 22:30:19 INFO - "repository": "https://hg.mozilla.org/mozilla-central/", 22:30:19 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 22:30:19 INFO - "stage_platform": "macosx64", 22:30:19 INFO - "basedir": "/builds/slave/test", 22:30:19 INFO - "buildnumber": 78, 22:30:19 INFO - "platform": "yosemite_r7", 22:30:19 INFO - "parent_task_id": "XONplRHCRcu_7ZVZsv4DnQ", 22:30:19 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 22:30:19 INFO - "slavebuilddir": "test", 22:30:19 INFO - "taskId": "eO4njxGLT8mSJ0TizQCB7g", 22:30:19 INFO - "branch": "mozilla-central", 22:30:19 INFO - "script_repo_revision": "production", 22:30:19 INFO - "revision": "9851fcb0bf4d855c36729d7de19f0fa5c9f69776", 22:30:19 INFO - "slavename": "t-yosemite-r7-0064", 22:30:19 INFO - "repo_path": "mozilla-central" 22:30:19 INFO - } 22:30:19 INFO - Finding installer, test and symbols from parent task. 22:30:19 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/eO4njxGLT8mSJ0TizQCB7g'}, attempt #1 22:30:20 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/eO4njxGLT8mSJ0TizQCB7g'}, attempt #1 22:30:20 INFO - Task dependencies: R_myZOAWSG-UcFrlrJaNYA XONplRHCRcu_7ZVZsv4DnQ 22:30:20 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/XONplRHCRcu_7ZVZsv4DnQ'}, attempt #1 22:30:21 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/XONplRHCRcu_7ZVZsv4DnQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 22:30:21 INFO - Set test_url: https://queue.taskcluster.net/v1/task/XONplRHCRcu_7ZVZsv4DnQ/artifacts/public/build/test_packages.json 22:30:21 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/XONplRHCRcu_7ZVZsv4DnQ/artifacts/public/build/target.crashreporter-symbols.zip 22:30:21 INFO - Running post-action listener: _resource_record_post_action 22:30:21 INFO - [mozharness: 2017-05-22 05:30:21.048658Z] Finished read-buildbot-config step (success) 22:30:21 INFO - [mozharness: 2017-05-22 05:30:21.048788Z] Running download-and-extract step. 22:30:21 INFO - Running pre-action listener: _resource_record_pre_action 22:30:21 INFO - Running main action method: download_and_extract 22:30:21 INFO - mkdir: /builds/slave/test/build/tests 22:30:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:30:21 INFO - trying https://queue.taskcluster.net/v1/task/XONplRHCRcu_7ZVZsv4DnQ/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 22:30:21 INFO - Downloading https://queue.taskcluster.net/v1/task/XONplRHCRcu_7ZVZsv4DnQ/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 22:30:21 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/XONplRHCRcu_7ZVZsv4DnQ/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 22:30:22 INFO - Downloaded 1325 bytes. 22:30:22 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 22:30:22 INFO - Using the following test package requirements: 22:30:22 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 22:30:22 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 22:30:22 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 22:30:22 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 22:30:22 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 22:30:22 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 22:30:22 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 22:30:22 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 22:30:22 INFO - u'jsshell-mac.zip'], 22:30:22 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 22:30:22 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 22:30:22 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 22:30:22 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 22:30:22 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 22:30:22 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 22:30:22 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 22:30:22 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 22:30:22 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 22:30:22 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 22:30:22 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 22:30:22 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 22:30:22 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/XONplRHCRcu_7ZVZsv4DnQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 22:30:22 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/XONplRHCRcu_7ZVZsv4DnQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 22:30:22 INFO - Fetch https://queue.taskcluster.net/v1/task/XONplRHCRcu_7ZVZsv4DnQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 22:30:23 INFO - Content-Length response header: 57626817 22:30:23 INFO - Bytes received: 57626817 22:30:42 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 22:30:42 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/XONplRHCRcu_7ZVZsv4DnQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 22:30:42 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/XONplRHCRcu_7ZVZsv4DnQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 22:30:42 INFO - Fetch https://queue.taskcluster.net/v1/task/XONplRHCRcu_7ZVZsv4DnQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 22:30:44 INFO - Content-Length response header: 57626817 22:30:44 INFO - Bytes received: 57626817 22:31:01 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/XONplRHCRcu_7ZVZsv4DnQ/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 22:31:01 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/XONplRHCRcu_7ZVZsv4DnQ/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 22:31:01 INFO - Fetch https://queue.taskcluster.net/v1/task/XONplRHCRcu_7ZVZsv4DnQ/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 22:31:03 INFO - Content-Length response header: 14410000 22:31:03 INFO - Bytes received: 14410000 22:31:03 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')]} 22:31:03 INFO - trying https://queue.taskcluster.net/v1/task/XONplRHCRcu_7ZVZsv4DnQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 22:31:03 INFO - Downloading https://queue.taskcluster.net/v1/task/XONplRHCRcu_7ZVZsv4DnQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 22:31:03 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/XONplRHCRcu_7ZVZsv4DnQ/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 22:31:05 INFO - Downloaded 68065423 bytes. 22:31:05 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/XONplRHCRcu_7ZVZsv4DnQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 22:31:05 INFO - mkdir: /builds/slave/test/properties 22:31:05 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 22:31:05 INFO - Writing to file /builds/slave/test/properties/build_url 22:31:05 INFO - Contents: 22:31:05 INFO - build_url:https://queue.taskcluster.net/v1/task/XONplRHCRcu_7ZVZsv4DnQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 22:31:05 INFO - Running post-action listener: _resource_record_post_action 22:31:05 INFO - Running post-action listener: set_extra_try_arguments 22:31:05 INFO - [mozharness: 2017-05-22 05:31:05.746179Z] Finished download-and-extract step (success) 22:31:05 INFO - [mozharness: 2017-05-22 05:31:05.746323Z] Running populate-webroot step. 22:31:05 INFO - Running pre-action listener: _resource_record_pre_action 22:31:05 INFO - Running main action method: populate_webroot 22:31:05 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 22:31:05 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 22:31:05 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 22:31:05 INFO - '--tppagecycles', 22:31:05 INFO - '7'], 22:31:05 INFO - 'tests': ['tsvgm']}}, 22:31:05 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 22:31:05 INFO - 'tests': ['tresize', 'tcanvasmark']}, 22:31:05 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 22:31:05 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 22:31:05 INFO - 'tests': ['dromaeo_css', 'kraken']}, 22:31:05 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 22:31:05 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 22:31:05 INFO - 'talos_options': ['--disable-e10s'], 22:31:05 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:31:05 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 22:31:05 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:31:05 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 22:31:05 INFO - 'talos_options': ['--disable-e10s'], 22:31:05 INFO - 'tests': ['damp', 'tps']}, 22:31:05 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 22:31:05 INFO - 'tests': ['damp', 'tps']}, 22:31:05 INFO - 'g3': {'talos_options': ['--disable-e10s'], 22:31:05 INFO - 'tests': ['dromaeo_dom']}, 22:31:05 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 22:31:05 INFO - 'g4': {'talos_options': ['--disable-e10s'], 22:31:05 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 22:31:05 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 22:31:05 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 22:31:05 INFO - 'tests': ['ts_paint', 'tp5o']}, 22:31:05 INFO - 'other': {'talos_options': ['--disable-e10s'], 22:31:05 INFO - 'tests': ['a11yr', 22:31:05 INFO - 'ts_paint', 22:31:05 INFO - 'tpaint', 22:31:05 INFO - 'sessionrestore', 22:31:05 INFO - 'sessionrestore_no_auto_restore', 22:31:05 INFO - 'tabpaint']}, 22:31:05 INFO - 'other-e10s': {'tests': ['a11yr', 22:31:05 INFO - 'ts_paint', 22:31:05 INFO - 'tpaint', 22:31:05 INFO - 'sessionrestore', 22:31:05 INFO - 'sessionrestore_no_auto_restore', 22:31:05 INFO - 'tabpaint']}, 22:31:05 INFO - 'other-e10s_l64': {'tests': ['a11yr', 22:31:05 INFO - 'ts_paint', 22:31:05 INFO - 'tpaint', 22:31:05 INFO - 'sessionrestore', 22:31:05 INFO - 'sessionrestore_no_auto_restore', 22:31:05 INFO - 'tabpaint']}, 22:31:05 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 22:31:05 INFO - 'ts_paint', 22:31:05 INFO - 'tpaint', 22:31:05 INFO - 'sessionrestore', 22:31:05 INFO - 'sessionrestore_no_auto_restore', 22:31:05 INFO - 'tabpaint']}, 22:31:05 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 22:31:05 INFO - 'tests': ['a11yr', 22:31:05 INFO - 'ts_paint', 22:31:05 INFO - 'tpaint', 22:31:05 INFO - 'sessionrestore', 22:31:05 INFO - 'sessionrestore_no_auto_restore', 22:31:05 INFO - 'tabpaint']}, 22:31:05 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 22:31:05 INFO - 'tests': ['a11yr', 22:31:05 INFO - 'ts_paint', 22:31:05 INFO - 'tpaint', 22:31:05 INFO - 'sessionrestore', 22:31:05 INFO - 'sessionrestore_no_auto_restore', 22:31:05 INFO - 'tabpaint']}, 22:31:05 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 22:31:05 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 22:31:05 INFO - 'bloom_basic_ref']}, 22:31:05 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 22:31:05 INFO - 'tests': ['tsvgx', 22:31:05 INFO - 'tsvgr_opacity', 22:31:05 INFO - 'tart', 22:31:05 INFO - 'tscrollx', 22:31:05 INFO - 'cart', 22:31:05 INFO - 'tsvg_static']}, 22:31:05 INFO - 'svgr-e10s': {'tests': ['tsvgx', 22:31:05 INFO - 'tsvgr_opacity', 22:31:05 INFO - 'tart', 22:31:05 INFO - 'tscrollx', 22:31:05 INFO - 'cart', 22:31:05 INFO - 'tsvg_static']}, 22:31:05 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 22:31:05 INFO - 'talos_options': ['--disable-e10s'], 22:31:05 INFO - 'tests': ['tp5o']}, 22:31:05 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 22:31:05 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 22:31:05 INFO - 'talos_options': ['--disable-e10s', 22:31:05 INFO - '--xperf_path', 22:31:05 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:31:05 INFO - 'tests': ['tp5n']}, 22:31:05 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 22:31:05 INFO - 'talos_options': ['--xperf_path', 22:31:05 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:31:05 INFO - 'tests': ['tp5n']}}, 22:31:05 INFO - 'talos.zip': {'path': '', 22:31:05 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 22:31:05 INFO - Running post-action listener: _resource_record_post_action 22:31:05 INFO - [mozharness: 2017-05-22 05:31:05.767894Z] Finished populate-webroot step (success) 22:31:05 INFO - [mozharness: 2017-05-22 05:31:05.767973Z] Running create-virtualenv step. 22:31:05 INFO - Running pre-action listener: _resource_record_pre_action 22:31:05 INFO - Running main action method: create_virtualenv 22:31:05 INFO - Creating virtualenv /builds/slave/test/build/venv 22:31:05 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 22:31:05 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 22:31:05 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 22:31:05 INFO - Using real prefix '/tools/python27' 22:31:05 INFO - New python executable in /builds/slave/test/build/venv/bin/python 22:31:06 INFO - Installing distribute.............................................................................................................................................................................................done. 22:31:09 INFO - Installing pip.................done. 22:31:09 INFO - Return code: 0 22:31:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 22:31:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 22:31:09 INFO - Reading from file tmpfile_stdout 22:31:09 INFO - Output received: 22:31:09 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) 22:31:09 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 22:31:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:31:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:31:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:31:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:31:09 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1041a8b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1041ac768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdc90cbb760>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103eb8d30>, '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 0x103e6cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103eadbe8>, '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.rNRNCuS2dh/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.sLaJ1JWk69/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/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 22:31:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 22:31:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 22:31:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rNRNCuS2dh/Render', 22:31:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:31:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:31:09 INFO - 'HOME': '/Users/cltbld', 22:31:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:31:09 INFO - 'LOGNAME': 'cltbld', 22:31:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:31:09 INFO - 'MOZ_NO_REMOTE': '1', 22:31:09 INFO - 'NO_EM_RESTART': '1', 22:31:09 INFO - 'PAGER': '/bin/cat', 22:31:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:31:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:31:09 INFO - 'PWD': '/builds/slave/test', 22:31:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:31:09 INFO - 'SHELL': '/bin/bash', 22:31:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.sLaJ1JWk69/Listeners', 22:31:09 INFO - 'TMPDIR': '/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/T/', 22:31:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:31:09 INFO - 'USER': 'cltbld', 22:31:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:31:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:31:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:31:09 INFO - 'XPC_FLAGS': '0x0', 22:31:09 INFO - 'XPC_SERVICE_NAME': '0', 22:31:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:31:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:31:10 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 22:31:10 INFO - Cleaning up... 22:31:10 INFO - Return code: 0 22:31:10 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 22:31:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:31:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:31:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:31:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:31:10 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1041a8b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1041ac768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdc90cbb760>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103eb8d30>, '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 0x103e6cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103eadbe8>, '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.rNRNCuS2dh/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.sLaJ1JWk69/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/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 22:31:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 22:31:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 22:31:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rNRNCuS2dh/Render', 22:31:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:31:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:31:10 INFO - 'HOME': '/Users/cltbld', 22:31:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:31:10 INFO - 'LOGNAME': 'cltbld', 22:31:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:31:10 INFO - 'MOZ_NO_REMOTE': '1', 22:31:10 INFO - 'NO_EM_RESTART': '1', 22:31:10 INFO - 'PAGER': '/bin/cat', 22:31:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:31:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:31:10 INFO - 'PWD': '/builds/slave/test', 22:31:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:31:10 INFO - 'SHELL': '/bin/bash', 22:31:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.sLaJ1JWk69/Listeners', 22:31:10 INFO - 'TMPDIR': '/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/T/', 22:31:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:31:10 INFO - 'USER': 'cltbld', 22:31:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:31:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:31:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:31:10 INFO - 'XPC_FLAGS': '0x0', 22:31:10 INFO - 'XPC_SERVICE_NAME': '0', 22:31:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:31:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:31:10 INFO - Downloading/unpacking psutil>=3.1.1 22:31:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:31:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:31:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:31:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:31:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 22:31:12 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:31:12 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:31:12 INFO - Installing collected packages: psutil 22:31:12 INFO - Running setup.py install for psutil 22:31:12 INFO - building 'psutil._psutil_osx' extension 22:31:12 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 22:31:12 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 22:31:12 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 22:31:13 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 22:31:13 INFO - building 'psutil._psutil_posix' extension 22:31:13 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 22:31:13 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 22:31:13 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 22:31:13 INFO - ^ 22:31:13 INFO - 1 warning generated. 22:31:13 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 22:31:13 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:31:13 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:31:13 INFO - Successfully installed psutil 22:31:13 INFO - Cleaning up... 22:31:13 INFO - Return code: 0 22:31:13 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 22:31:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:31:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:31:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:31:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:31:14 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 0x1041a8b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1041ac768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdc90cbb760>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103eb8d30>, '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 0x103e6cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103eadbe8>, '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.rNRNCuS2dh/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.sLaJ1JWk69/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/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 22:31:14 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 22:31:14 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 22:31:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rNRNCuS2dh/Render', 22:31:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:31:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:31:14 INFO - 'HOME': '/Users/cltbld', 22:31:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:31:14 INFO - 'LOGNAME': 'cltbld', 22:31:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:31:14 INFO - 'MOZ_NO_REMOTE': '1', 22:31:14 INFO - 'NO_EM_RESTART': '1', 22:31:14 INFO - 'PAGER': '/bin/cat', 22:31:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:31:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:31:14 INFO - 'PWD': '/builds/slave/test', 22:31:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:31:14 INFO - 'SHELL': '/bin/bash', 22:31:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.sLaJ1JWk69/Listeners', 22:31:14 INFO - 'TMPDIR': '/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/T/', 22:31:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:31:14 INFO - 'USER': 'cltbld', 22:31:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:31:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:31:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:31:14 INFO - 'XPC_FLAGS': '0x0', 22:31:14 INFO - 'XPC_SERVICE_NAME': '0', 22:31:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:31:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:31:14 INFO - Downloading/unpacking mozsystemmonitor==0.3 22:31:14 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 22:31:14 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 22:31:14 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 22:31:14 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 22:31:15 INFO - Downloading mozsystemmonitor-0.3.tar.gz 22:31:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 22:31:15 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 22:31:15 INFO - Installing collected packages: mozsystemmonitor 22:31:15 INFO - Running setup.py install for mozsystemmonitor 22:31:16 INFO - Successfully installed mozsystemmonitor 22:31:16 INFO - Cleaning up... 22:31:16 INFO - Return code: 0 22:31:16 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 22:31:16 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')]} 22:31:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:31:16 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')]} 22:31:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:31:16 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 0x1041a8b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1041ac768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdc90cbb760>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103eb8d30>, '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 0x103e6cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103eadbe8>, '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.rNRNCuS2dh/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.sLaJ1JWk69/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/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 22:31:16 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 22:31:16 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 22:31:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rNRNCuS2dh/Render', 22:31:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:31:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:31:16 INFO - 'HOME': '/Users/cltbld', 22:31:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:31:16 INFO - 'LOGNAME': 'cltbld', 22:31:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:31:16 INFO - 'MOZ_NO_REMOTE': '1', 22:31:16 INFO - 'NO_EM_RESTART': '1', 22:31:16 INFO - 'PAGER': '/bin/cat', 22:31:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:31:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:31:16 INFO - 'PWD': '/builds/slave/test', 22:31:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:31:16 INFO - 'SHELL': '/bin/bash', 22:31:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.sLaJ1JWk69/Listeners', 22:31:16 INFO - 'TMPDIR': '/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/T/', 22:31:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:31:16 INFO - 'USER': 'cltbld', 22:31:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:31:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:31:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:31:16 INFO - 'XPC_FLAGS': '0x0', 22:31:16 INFO - 'XPC_SERVICE_NAME': '0', 22:31:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:31:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:31:16 INFO - Downloading/unpacking jsonschema==2.5.1 22:31:16 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 22:31:16 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 22:31:16 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 22:31:16 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 22:31:17 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 22:31:17 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 22:31:17 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 22:31:17 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 22:31:17 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 22:31:17 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 22:31:18 INFO - Downloading functools32-3.2.3-2.tar.gz 22:31:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 22:31:18 INFO - warning: no files found matching '*.txt' 22:31:18 INFO - no previously-included directories found matching 'build' 22:31:18 INFO - no previously-included directories found matching 'dist' 22:31:18 INFO - no previously-included directories found matching '.git*' 22:31:18 INFO - Installing collected packages: jsonschema, functools32 22:31:18 INFO - Running setup.py install for functools32 22:31:18 INFO - warning: no files found matching '*.txt' 22:31:18 INFO - no previously-included directories found matching 'build' 22:31:18 INFO - no previously-included directories found matching 'dist' 22:31:18 INFO - no previously-included directories found matching '.git*' 22:31:18 INFO - Successfully installed jsonschema functools32 22:31:18 INFO - Cleaning up... 22:31:18 INFO - Return code: 0 22:31:18 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 22:31:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:31:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:31:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:31:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:31:18 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 0x1041a8b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1041ac768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdc90cbb760>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103eb8d30>, '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 0x103e6cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103eadbe8>, '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.rNRNCuS2dh/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.sLaJ1JWk69/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/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 22:31:18 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 22:31:18 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 22:31:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rNRNCuS2dh/Render', 22:31:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:31:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:31:18 INFO - 'HOME': '/Users/cltbld', 22:31:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:31:18 INFO - 'LOGNAME': 'cltbld', 22:31:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:31:18 INFO - 'MOZ_NO_REMOTE': '1', 22:31:18 INFO - 'NO_EM_RESTART': '1', 22:31:18 INFO - 'PAGER': '/bin/cat', 22:31:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:31:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:31:18 INFO - 'PWD': '/builds/slave/test', 22:31:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:31:18 INFO - 'SHELL': '/bin/bash', 22:31:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.sLaJ1JWk69/Listeners', 22:31:18 INFO - 'TMPDIR': '/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/T/', 22:31:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:31:18 INFO - 'USER': 'cltbld', 22:31:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:31:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:31:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:31:18 INFO - 'XPC_FLAGS': '0x0', 22:31:18 INFO - 'XPC_SERVICE_NAME': '0', 22:31:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:31:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:31:18 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 22:31:18 INFO - Cleaning up... 22:31:18 INFO - Return code: 0 22:31:18 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 22:31:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:31:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:31:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:31:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:31:18 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 0x1041a8b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1041ac768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdc90cbb760>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103eb8d30>, '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 0x103e6cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103eadbe8>, '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.rNRNCuS2dh/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.sLaJ1JWk69/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/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 22:31:18 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 22:31:18 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 22:31:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rNRNCuS2dh/Render', 22:31:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:31:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:31:18 INFO - 'HOME': '/Users/cltbld', 22:31:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:31:18 INFO - 'LOGNAME': 'cltbld', 22:31:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:31:18 INFO - 'MOZ_NO_REMOTE': '1', 22:31:18 INFO - 'NO_EM_RESTART': '1', 22:31:18 INFO - 'PAGER': '/bin/cat', 22:31:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:31:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:31:18 INFO - 'PWD': '/builds/slave/test', 22:31:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:31:18 INFO - 'SHELL': '/bin/bash', 22:31:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.sLaJ1JWk69/Listeners', 22:31:18 INFO - 'TMPDIR': '/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/T/', 22:31:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:31:18 INFO - 'USER': 'cltbld', 22:31:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:31:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:31:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:31:18 INFO - 'XPC_FLAGS': '0x0', 22:31:18 INFO - 'XPC_SERVICE_NAME': '0', 22:31:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:31:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:31:19 INFO - Downloading/unpacking blobuploader==1.2.4 22:31:19 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 22:31:19 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 22:31:19 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 22:31:19 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 22:31:20 INFO - Downloading blobuploader-1.2.4.tar.gz 22:31:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 22:31:20 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 22:31:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:31:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:31:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:31:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:31:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 22:31:21 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 22:31:21 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 22:31:21 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 22:31:21 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 22:31:21 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 22:31:21 INFO - Downloading docopt-0.6.1.tar.gz 22:31:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 22:31:21 INFO - Installing collected packages: blobuploader, requests, docopt 22:31:21 INFO - Running setup.py install for blobuploader 22:31:21 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 22:31:21 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 22:31:21 INFO - Running setup.py install for requests 22:31:22 INFO - Running setup.py install for docopt 22:31:22 INFO - Successfully installed blobuploader requests docopt 22:31:22 INFO - Cleaning up... 22:31:22 INFO - Return code: 0 22:31:22 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:31: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')]} 22:31:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:31: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')]} 22:31:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:31:22 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 0x1041a8b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1041ac768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdc90cbb760>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103eb8d30>, '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 0x103e6cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103eadbe8>, '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.rNRNCuS2dh/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.sLaJ1JWk69/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/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 22:31:22 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 22:31:22 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 22:31:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rNRNCuS2dh/Render', 22:31:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:31:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:31:22 INFO - 'HOME': '/Users/cltbld', 22:31:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:31:22 INFO - 'LOGNAME': 'cltbld', 22:31:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:31:22 INFO - 'MOZ_NO_REMOTE': '1', 22:31:22 INFO - 'NO_EM_RESTART': '1', 22:31:22 INFO - 'PAGER': '/bin/cat', 22:31:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:31:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:31:22 INFO - 'PWD': '/builds/slave/test', 22:31:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:31:22 INFO - 'SHELL': '/bin/bash', 22:31:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.sLaJ1JWk69/Listeners', 22:31:22 INFO - 'TMPDIR': '/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/T/', 22:31:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:31:22 INFO - 'USER': 'cltbld', 22:31:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:31:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:31:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:31:22 INFO - 'XPC_FLAGS': '0x0', 22:31:22 INFO - 'XPC_SERVICE_NAME': '0', 22:31:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:31:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:31:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:31:22 INFO - Running setup.py (path:/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/T/pip-4_6rs3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:31:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:31:22 INFO - Running setup.py (path:/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/T/pip-QcJhCv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:31:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:31:22 INFO - Running setup.py (path:/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/T/pip-0EIhrD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:31:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:31:22 INFO - Running setup.py (path:/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/T/pip-VO9TuV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:31:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:31:22 INFO - Running setup.py (path:/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/T/pip-qSuOWL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:31:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:31:23 INFO - Running setup.py (path:/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/T/pip-1Tg2gn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:31:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:31:23 INFO - Running setup.py (path:/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/T/pip-J4cFxz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:31:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:31:23 INFO - Running setup.py (path:/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/T/pip-YIPJXp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:31:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:31:23 INFO - Running setup.py (path:/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/T/pip-ks4J5N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:31:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:31:23 INFO - Running setup.py (path:/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/T/pip-8ZKrlA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:31:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:31:23 INFO - Running setup.py (path:/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/T/pip-w2_s7r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:31:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:31:23 INFO - Running setup.py (path:/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/T/pip-oPYVf3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:31:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:31:23 INFO - Running setup.py (path:/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/T/pip-MMbiWj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:31:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:31:23 INFO - Running setup.py (path:/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/T/pip-PSd2Qo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:31:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:31:23 INFO - Running setup.py (path:/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/T/pip-GmOzZ8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:31:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:31:24 INFO - Running setup.py (path:/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/T/pip-g5LU9S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:31:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:31:24 INFO - Running setup.py (path:/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/T/pip-BfUBiv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:31:24 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 22:31:24 INFO - Running setup.py install for manifestparser 22:31:24 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 22:31:24 INFO - Running setup.py install for mozcrash 22:31:24 INFO - Running setup.py install for mozdebug 22:31:24 INFO - Running setup.py install for mozdevice 22:31:24 INFO - Installing dm script to /builds/slave/test/build/venv/bin 22:31:24 INFO - Running setup.py install for mozfile 22:31:25 INFO - Running setup.py install for mozhttpd 22:31:25 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 22:31:25 INFO - Running setup.py install for mozinfo 22:31:25 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 22:31:25 INFO - Running setup.py install for mozInstall 22:31:25 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 22:31:25 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 22:31:25 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 22:31:25 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 22:31:25 INFO - Running setup.py install for mozleak 22:31:25 INFO - Running setup.py install for mozlog 22:31:25 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 22:31:25 INFO - Running setup.py install for moznetwork 22:31:25 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 22:31:25 INFO - Running setup.py install for mozprocess 22:31:26 INFO - Running setup.py install for mozprofile 22:31:26 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 22:31:26 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 22:31:26 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 22:31:26 INFO - Running setup.py install for mozrunner 22:31:26 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 22:31:26 INFO - Running setup.py install for mozscreenshot 22:31:26 INFO - Running setup.py install for moztest 22:31:26 INFO - Running setup.py install for mozversion 22:31:26 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 22:31:26 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 22:31:26 INFO - Cleaning up... 22:31:26 INFO - Return code: 0 22:31:26 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:31:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:31:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:31:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:31:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:31:26 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 0x1041a8b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1041ac768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdc90cbb760>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103eb8d30>, '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 0x103e6cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103eadbe8>, '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.rNRNCuS2dh/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.sLaJ1JWk69/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/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 22:31:26 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 22:31:26 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 22:31:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rNRNCuS2dh/Render', 22:31:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:31:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:31:26 INFO - 'HOME': '/Users/cltbld', 22:31:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:31:26 INFO - 'LOGNAME': 'cltbld', 22:31:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:31:26 INFO - 'MOZ_NO_REMOTE': '1', 22:31:26 INFO - 'NO_EM_RESTART': '1', 22:31:26 INFO - 'PAGER': '/bin/cat', 22:31:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:31:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:31:26 INFO - 'PWD': '/builds/slave/test', 22:31:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:31:26 INFO - 'SHELL': '/bin/bash', 22:31:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.sLaJ1JWk69/Listeners', 22:31:26 INFO - 'TMPDIR': '/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/T/', 22:31:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:31:26 INFO - 'USER': 'cltbld', 22:31:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:31:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:31:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:31:26 INFO - 'XPC_FLAGS': '0x0', 22:31:26 INFO - 'XPC_SERVICE_NAME': '0', 22:31:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:31:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:31:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:31:27 INFO - Running setup.py (path:/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/T/pip-eFdimn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:31:27 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)) 22:31:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:31:27 INFO - Running setup.py (path:/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/T/pip-bf1EfA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:31:27 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)) 22:31:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:31:27 INFO - Running setup.py (path:/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/T/pip-2Pxm8J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:31:27 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)) 22:31:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:31:27 INFO - Running setup.py (path:/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/T/pip-ck9ONL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:31:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.50 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)) 22:31:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:31:27 INFO - Running setup.py (path:/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/T/pip-XCs83I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:31:27 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)) 22:31:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:31:27 INFO - Running setup.py (path:/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/T/pip-FiqpXL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:31:27 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)) 22:31:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:31:27 INFO - Running setup.py (path:/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/T/pip-r7NZQz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:31:27 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)) 22:31:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:31:27 INFO - Running setup.py (path:/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/T/pip-7uvMcD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:31:27 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)) 22:31:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:31:27 INFO - Running setup.py (path:/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/T/pip-Z7RCFq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:31:28 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)) 22:31:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:31:28 INFO - Running setup.py (path:/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/T/pip-iiNL_D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:31:28 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)) 22:31:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:31:28 INFO - Running setup.py (path:/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/T/pip-Ef05Wt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:31:28 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)) 22:31:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:31:28 INFO - Running setup.py (path:/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/T/pip-_lW8mK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:31:28 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)) 22:31:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:31:28 INFO - Running setup.py (path:/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/T/pip-is9J6E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:31:28 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)) 22:31:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:31:28 INFO - Running setup.py (path:/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/T/pip-HRq1xH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:31:28 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)) 22:31:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:31:28 INFO - Running setup.py (path:/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/T/pip-LbjP5C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:31:28 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)) 22:31:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:31:28 INFO - Running setup.py (path:/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/T/pip-IcY_UJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:31:28 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)) 22:31:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:31:28 INFO - Running setup.py (path:/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/T/pip-g9lOwD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:31:28 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)) 22:31:28 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)) 22:31:28 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)) 22:31:28 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)) 22:31:28 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.50->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 22:31:28 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.50->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 22:31:28 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 22:31: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 22:31: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 22:31: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 22:31: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 22:31:30 INFO - Downloading blessings-1.6.tar.gz 22:31:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 22:31:30 INFO - Installing collected packages: blessings 22:31:30 INFO - Running setup.py install for blessings 22:31:30 INFO - Successfully installed blessings 22:31:30 INFO - Cleaning up... 22:31:30 INFO - Return code: 0 22:31:30 INFO - Done creating virtualenv /builds/slave/test/build/venv. 22:31:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:31:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:31:30 INFO - Reading from file tmpfile_stdout 22:31:30 INFO - Current package versions: 22:31:30 INFO - blessings == 1.6 22:31:30 INFO - blobuploader == 1.2.4 22:31:30 INFO - docopt == 0.6.1 22:31:30 INFO - functools32 == 3.2.3-2 22:31:30 INFO - jsonschema == 2.5.1 22:31:30 INFO - manifestparser == 1.1 22:31:30 INFO - mozInstall == 1.12 22:31:30 INFO - mozcrash == 1.0 22:31:30 INFO - mozdebug == 0.1 22:31:30 INFO - mozdevice == 0.50 22:31:30 INFO - mozfile == 1.2 22:31:30 INFO - mozhttpd == 0.7 22:31:30 INFO - mozinfo == 0.9 22:31:30 INFO - mozleak == 0.1 22:31:30 INFO - mozlog == 3.4 22:31:30 INFO - moznetwork == 0.27 22:31:30 INFO - mozprocess == 0.25 22:31:30 INFO - mozprofile == 0.28 22:31:30 INFO - mozrunner == 6.13 22:31:30 INFO - mozscreenshot == 0.1 22:31:30 INFO - mozsystemmonitor == 0.3 22:31:30 INFO - moztest == 0.8 22:31:30 INFO - mozversion == 1.4 22:31:30 INFO - psutil == 3.1.1 22:31:30 INFO - requests == 1.2.3 22:31:30 INFO - wsgiref == 0.1.2 22:31:30 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:31:30 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')]} 22:31:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:31:30 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')]} 22:31:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:31:30 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 0x1041a8b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1041ac768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdc90cbb760>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103eb8d30>, '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 0x103e6cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103eadbe8>, '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.rNRNCuS2dh/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.sLaJ1JWk69/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/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 22:31:30 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 22:31:30 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 22:31:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rNRNCuS2dh/Render', 22:31:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:31:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:31:30 INFO - 'HOME': '/Users/cltbld', 22:31:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:31:30 INFO - 'LOGNAME': 'cltbld', 22:31:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:31:30 INFO - 'MOZ_NO_REMOTE': '1', 22:31:30 INFO - 'NO_EM_RESTART': '1', 22:31:30 INFO - 'PAGER': '/bin/cat', 22:31:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:31:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:31:30 INFO - 'PWD': '/builds/slave/test', 22:31:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:31:30 INFO - 'SHELL': '/bin/bash', 22:31:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.sLaJ1JWk69/Listeners', 22:31:30 INFO - 'TMPDIR': '/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/T/', 22:31:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:31:30 INFO - 'USER': 'cltbld', 22:31:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:31:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:31:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:31:30 INFO - 'XPC_FLAGS': '0x0', 22:31:30 INFO - 'XPC_SERVICE_NAME': '0', 22:31:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:31:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:31:31 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)) 22:31:31 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)) 22:31:31 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)) 22:31:31 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)) 22:31:31 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)) 22:31:31 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)) 22:31:31 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)) 22:31:31 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)) 22:31:31 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)) 22:31:31 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)) 22:31:31 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)) 22:31:31 INFO - Cleaning up... 22:31:31 INFO - Return code: 0 22:31:31 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 22:31:31 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')]} 22:31:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:31:31 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')]} 22:31:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:31:31 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 0x1041a8b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1041ac768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdc90cbb760>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103eb8d30>, '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 0x103e6cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103eadbe8>, '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.rNRNCuS2dh/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.sLaJ1JWk69/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/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 22:31:31 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 22:31:31 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 22:31:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rNRNCuS2dh/Render', 22:31:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:31:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:31:31 INFO - 'HOME': '/Users/cltbld', 22:31:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:31:31 INFO - 'LOGNAME': 'cltbld', 22:31:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:31:31 INFO - 'MOZ_NO_REMOTE': '1', 22:31:31 INFO - 'NO_EM_RESTART': '1', 22:31:31 INFO - 'PAGER': '/bin/cat', 22:31:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:31:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:31:31 INFO - 'PWD': '/builds/slave/test', 22:31:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:31:31 INFO - 'SHELL': '/bin/bash', 22:31:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.sLaJ1JWk69/Listeners', 22:31:31 INFO - 'TMPDIR': '/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/T/', 22:31:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:31:31 INFO - 'USER': 'cltbld', 22:31:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:31:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:31:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:31:31 INFO - 'XPC_FLAGS': '0x0', 22:31:31 INFO - 'XPC_SERVICE_NAME': '0', 22:31:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:31:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:31:31 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 22:31:31 INFO - Cleaning up... 22:31:31 INFO - Return code: 0 22:31:31 INFO - Running post-action listener: _resource_record_post_action 22:31:31 INFO - Running post-action listener: _start_resource_monitoring 22:31:31 INFO - Starting resource monitoring. 22:31:31 INFO - [mozharness: 2017-05-22 05:31:31.519613Z] Finished create-virtualenv step (success) 22:31:31 INFO - [mozharness: 2017-05-22 05:31:31.520560Z] Running install step. 22:31:31 INFO - Running pre-action listener: _resource_record_pre_action 22:31:31 INFO - Running main action method: install 22:31:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:31:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:31:31 INFO - Reading from file tmpfile_stdout 22:31:31 INFO - Detecting whether we're running mozinstall >=1.0... 22:31:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 22:31:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 22:31:31 INFO - Reading from file tmpfile_stdout 22:31:31 INFO - Output received: 22:31:31 INFO - Usage: mozinstall [options] installer 22:31:31 INFO - Options: 22:31:31 INFO - -h, --help show this help message and exit 22:31:31 INFO - -d DEST, --destination=DEST 22:31:31 INFO - Directory to install application into. [default: 22:31:31 INFO - "/builds/slave/test"] 22:31:31 INFO - --app=APP Application being installed. [default: firefox] 22:31:31 INFO - mkdir: /builds/slave/test/build/application 22:31:31 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'] 22:31:31 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 22:31:53 INFO - Reading from file tmpfile_stdout 22:31:53 INFO - Output received: 22:31:53 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 22:31:53 INFO - Running post-action listener: _resource_record_post_action 22:31:53 INFO - [mozharness: 2017-05-22 05:31:53.623247Z] Finished install step (success) 22:31:53 INFO - [mozharness: 2017-05-22 05:31:53.623357Z] Running run-tests step. 22:31:53 INFO - Running pre-action listener: _resource_record_pre_action 22:31:53 INFO - Running main action method: run_tests 22:31:53 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 22:31:53 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 22:31:53 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 22:31:53 INFO - 2017-05-21 22:31:53.638 screenresolution[1621:7590] starting screenresolution argv=screenresolution get 22:31:53 INFO - 2017-05-21 22:31:53.640 screenresolution[1621:7590] Display 0: 1600x1200x32@60 22:31:53 INFO - 2017-05-21 22:31:53.646 screenresolution[1622:7593] starting screenresolution argv=screenresolution list 22:31:53 INFO - Available Modes on Display 0 22:31:53 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 22:31:53 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 22:31:53 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 22:31:53 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 22:31:53 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 22:31:53 INFO - Intel Iris: 22:31:53 INFO - Chipset Model: Intel Iris 22:31:53 INFO - Type: GPU 22:31:53 INFO - Bus: Built-In 22:31:53 INFO - VRAM (Dynamic, Max): 1536 MB 22:31:53 INFO - Vendor: Intel (0x8086) 22:31:53 INFO - Device ID: 0x0a2e 22:31:53 INFO - Revision ID: 0x0009 22:31:53 INFO - Displays: 22:31:53 INFO - Display: 22:31:53 INFO - Resolution: 1600 x 1200 @ 60 Hz 22:31:53 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 22:31:53 INFO - Display Serial Number: 1600x1200 60 22:31:53 INFO - Main Display: Yes 22:31:53 INFO - Mirror: Off 22:31:53 INFO - Online: Yes 22:31:53 INFO - Rotation: Supported 22:31:53 INFO - Return code: 0 22:31:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 22:31:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 22:31:53 INFO - Python 2.7.3 22:31:53 INFO - Return code: 0 22:31:53 INFO - grabbing minidump binary from tooltool 22:31:53 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')]} 22:31:53 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 0x103eb8d30>, '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 0x103e6cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103eadbe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 22:31:53 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 22:31:53 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 22:31:53 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 22:31:53 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 22:31:54 INFO - Return code: 0 22:31:54 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 22:31:54 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 22:31:54 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 22:31:54 INFO - ENV: RUST_BACKTRACE is now 1 22:31:54 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 22:31:54 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 22:31:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/XONplRHCRcu_7ZVZsv4DnQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0064', '--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 22:31:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/XONplRHCRcu_7ZVZsv4DnQ/artifacts/public/build/target.crashreporter-symbols.zip --title t-yosemite-r7-0064 --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 22:31:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rNRNCuS2dh/Render', 22:31:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:31:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:31:54 INFO - 'HOME': '/Users/cltbld', 22:31:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:31:54 INFO - 'LOGNAME': 'cltbld', 22:31:54 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 22:31:54 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 22:31:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:31:54 INFO - 'MOZ_NO_REMOTE': '1', 22:31:54 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 22:31:54 INFO - 'NO_EM_RESTART': '1', 22:31:54 INFO - 'PAGER': '/bin/cat', 22:31:54 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:31:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:31:54 INFO - 'PWD': '/builds/slave/test', 22:31:54 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 22:31:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:31:54 INFO - 'RUST_BACKTRACE': '1', 22:31:54 INFO - 'SHELL': '/bin/bash', 22:31:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.sLaJ1JWk69/Listeners', 22:31:54 INFO - 'TMPDIR': '/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/T/', 22:31:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:31:54 INFO - 'USER': 'cltbld', 22:31:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:31:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:31:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:31:54 INFO - 'XPC_FLAGS': '0x0', 22:31:54 INFO - 'XPC_SERVICE_NAME': '0', 22:31:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:31:54 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/XONplRHCRcu_7ZVZsv4DnQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0064', '--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 22:31:54 INFO - mozversion application_buildid: 20170521203522 22:31:54 INFO - mozversion application_changeset: 9851fcb0bf4d855c36729d7de19f0fa5c9f69776 22:31:54 INFO - mozversion application_display_name: Nightly 22:31:54 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 22:31:54 INFO - mozversion application_name: Firefox 22:31:54 INFO - mozversion application_remotingname: firefox 22:31:54 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 22:31:54 INFO - mozversion application_vendor: Mozilla 22:31:54 INFO - mozversion application_version: 55.0a1 22:31:54 INFO - mozversion platform_buildid: 20170521203522 22:31:54 INFO - mozversion platform_changeset: 9851fcb0bf4d855c36729d7de19f0fa5c9f69776 22:31:54 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 22:31:54 INFO - mozversion platform_version: 55.0a1 22:31:54 INFO - using testdate: 1495431114 22:31:54 INFO - actual date: 1495431114 22:31:54 INFO - starting webserver on 'localhost:49227' 22:31:54 INFO - SUITE-START | Running 2 tests 22:31:54 INFO - TEST-START | dromaeo_css 22:31:54 INFO - operating with platform_type : mac_ 22:31:54 INFO - Initialising browser for dromaeo_css test... 22:31:54 INFO - TEST-INFO | started process 1632 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/T/tmp5iOdTQ/profile http://localhost:49227/getInfo.html) 22:31:54 INFO - PID 1632 | JavaScript warning: resource://gre/modules/addons/XPIProvider.jsm, line 2978: String.localeCompare is deprecated; use String.prototype.localeCompare instead 22:31:56 INFO - PID 1632 | 2017-05-21 22:31:56.921 plugin-container[1634:7764] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4b4b, name = 'com.apple.tsm.portname' 22:31:56 INFO - PID 1632 | See /usr/include/servers/bootstrap_defs.h for the error codes. 22:31:56 INFO - PID 1632 | 2017-05-21 22:31:56.924 plugin-container[1634:7764] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0xa303, name = 'com.apple.CFPasteboardClient' 22:31:56 INFO - PID 1632 | See /usr/include/servers/bootstrap_defs.h for the error codes. 22:31:56 INFO - PID 1632 | 2017-05-21 22:31:56.924 plugin-container[1634:7764] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 22:31:57 INFO - PID 1632 | __metrics Screen width/height:1600/1200 22:31:57 INFO - PID 1632 | colorDepth:24 22:31:57 INFO - PID 1632 | Browser inner width/height: 1024/691 22:31:57 INFO - PID 1632 | __metrics 22:31:57 INFO - PID 1632 | 22:31:57 INFO - PID 1632 | ###!!! [Child][MessageChannel] Error: (msgtype=0x7C0029,name=PHal::Msg_EnableWakeLockNotifications) Channel closing: too late to send/recv, messages will be lost 22:31:57 INFO - PID 1632 | 22:31:57 INFO - PID 1632 | 2017-05-21 22:31:57.615 plugin-container[1635:7823] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x943f, name = 'com.apple.tsm.portname' 22:31:57 INFO - PID 1632 | See /usr/include/servers/bootstrap_defs.h for the error codes. 22:31:57 INFO - PID 1632 | 2017-05-21 22:31:57.616 plugin-container[1635:7823] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4823, name = 'com.apple.CFPasteboardClient' 22:31:57 INFO - PID 1632 | See /usr/include/servers/bootstrap_defs.h for the error codes. 22:31:57 INFO - PID 1632 | 2017-05-21 22:31:57.616 plugin-container[1635:7823] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 22:31:58 INFO - TEST-INFO | 1632: exit 0 22:31:58 INFO - Browser initialized. 22:31:58 INFO - Running cycle 1/1 for dromaeo_css test... 22:31:58 INFO - TEST-INFO | started process 1636 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/T/tmp5iOdTQ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:32:00 INFO - PID 1636 | 2017-05-21 22:32:00.157 plugin-container[1637:7921] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x454b, name = 'com.apple.tsm.portname' 22:32:00 INFO - PID 1636 | See /usr/include/servers/bootstrap_defs.h for the error codes. 22:32:00 INFO - PID 1636 | 2017-05-21 22:32:00.158 plugin-container[1637:7921] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x481f, name = 'com.apple.CFPasteboardClient' 22:32:00 INFO - PID 1636 | See /usr/include/servers/bootstrap_defs.h for the error codes. 22:32:00 INFO - PID 1636 | 2017-05-21 22:32:00.158 plugin-container[1637:7921] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 22:32:00 INFO - PID 1636 | 2017-05-21 22:32:00.850 plugin-container[1638:7970] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4547, name = 'com.apple.tsm.portname' 22:32:00 INFO - PID 1636 | See /usr/include/servers/bootstrap_defs.h for the error codes. 22:32:00 INFO - PID 1636 | 2017-05-21 22:32:00.851 plugin-container[1638:7970] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9c0b, name = 'com.apple.CFPasteboardClient' 22:32:00 INFO - PID 1636 | See /usr/include/servers/bootstrap_defs.h for the error codes. 22:32:00 INFO - PID 1636 | 2017-05-21 22:32:00.851 plugin-container[1638:7970] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 22:32:10 INFO - PID 1636 | RSS: Main: 239214592 22:32:10 INFO - PID 1636 | 22:35:13 INFO - PID 1636 | Cycle 1(1): loaded http://localhost:49227/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49227/tests/dromaeo/cssquery-ext.html) 22:35:14 INFO - PID 1636 | RSS: Main: 261398528 22:35:14 INFO - PID 1636 | 22:38:16 INFO - PID 1636 | Cycle 1(1): loaded http://localhost:49227/tests/dromaeo/cssquery-ext.html (next: http://localhost:49227/tests/dromaeo/cssquery-jquery.html) 22:38:17 INFO - PID 1636 | RSS: Main: 267808768 22:38:17 INFO - PID 1636 | 22:41:19 INFO - PID 1636 | Cycle 1(1): loaded http://localhost:49227/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49227/tests/dromaeo/cssquery-mootools.html) 22:41:19 INFO - PID 1636 | RSS: Main: 272207872 22:41:19 INFO - PID 1636 | 22:44:21 INFO - PID 1636 | Cycle 1(1): loaded http://localhost:49227/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49227/tests/dromaeo/cssquery-prototype.html) 22:44:22 INFO - PID 1636 | RSS: Main: 270168064 22:44:22 INFO - PID 1636 | 22:47:24 INFO - PID 1636 | Cycle 1(1): loaded http://localhost:49227/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49227/tests/dromaeo/cssquery-yui.html) 22:47:25 INFO - PID 1636 | RSS: Main: 271675392 22:47:25 INFO - PID 1636 | 22:50:27 INFO - PID 1636 | Cycle 1(1): loaded http://localhost:49227/tests/dromaeo/cssquery-yui.html (next: http://localhost:49227/tests/dromaeo/cssquery-dojo.html) 22:50:27 INFO - PID 1636 | RSS: Main: 272826368 22:50:27 INFO - PID 1636 | 22:50:27 INFO - PID 1636 | __start_tp_report 22:50:27 INFO - PID 1636 | _x_x_mozilla_page_load 22:50:27 INFO - PID 1636 | _x_x_mozilla_page_load_details 22:50:27 INFO - PID 1636 | |i|pagename|runs| 22:50:27 INFO - PID 1636 | |0;dojo.html;3535.8762443314486;3558.2171922177126;3583.838727257273;3607.8015709135993;3611.9097022574433;6137.959273351912;6153.415425534573;6202.10069539917;6207.658578778165;6233.283172435172;5199.922001169976;6108.584917381041;6111.652512877634;6132.110769107974;6146.7541298348015;4461;5391.056565101111;5424.864378390533;5475.863103422406;5533.723313834313;5354.5716342692685;5483.985462689415;5533.833984980457;5536.667799931997;5560.944390556083;4619.74591397473;5454.781808727646;5480.369757477902;5516.862078448051;5525.696086715229;5358.33020872391;5424.159255315432;5433.701146436984;5498.890022199574;5513.751881165357;5447.073997420448;5470.699111548864;5474.507294343488;5507.862303442446;5512.952539017614;5178.689278643255;6044.6399560098625;6064.4845188158615;6333.87332253357;6335.778247761306;6432.131662225586;6469.74121035158;6677.93322066778;6689.665516724193;6708.664566771642;6623.668816559201;6715.2613212546585;6749.527533072687;6761.188657361099;6765.695543700496;6338.873222535523;6374.553781235269;6374.745010199586;6376.649284289409;6391.936080639227;6690.83272918181;6811.4210292124735;6920.8961865572055;6929.960505924152;6971.6862741176765;5282.947170528322;6254.437100660963;6387.712552935036;6419.518536109717;6498.935010649833;6712.597244165366;6746.763863264762;6750.561213521106;6772.932270677329;6783.491238157059;6365.809025729235;6387.361263873576;6544.770933017321;6604.834879127964;6636.8672626547195;6415.647139407284;6556;6597.670116494156;6621.702023408958;6656.400923916871;5337.6797392156595;5341.839744807662;5348.919766203432;5351;5398.460153984649;4702.130105930414;4735.810567577259;4754.5245475452175;4795.112904112751;4806.591439727592;6298.275698294663;6330.493560515148;6345.428911397997;6357.4596159326975;6385.457236134887;6740.258571557125;6741.629210393476;6749.190097188369;6756.425703815231;6765.35729105744;6282.748690052346;6322.968385158045;6377.521685873486;6385.042243663488;6394.520410969196;5754.4533269339345;5807.245058142415;5826.592138550261;5893.823185304448;5905.793297234576;6886.311368863174;6940.895886561807;6944.479164062816;6944.826379340557;6955.374016338655;6677.29888361715;6737.326267373223;6759.053732477359;6767.695453704497;6794.830129246809;6692.665366731741;6750.35871592198;6879.337027607289;6927.238003819475;6930.757423490154;5717.542596592346;6554.442872355903;6563.957145567289;6602.801915942529;6620.404163625297;6605.471562275103;6693.8326541834895;6776.559523630948;6794.626295553792;6799.898001529882;6334.144890439732;6548.8362790930605;6553.5084868635995;6565.737370505126;6568.1789776277965;6372.968135159294;6427.967860160669;6479.611223326616;6491.5131365148745;6506.154199953976;4923.556879880828;6162.537809664203;6349.206349206349;6374.840628984312;6405.551611387158;6266.874662506815;6337.683115844282;6339.270983836917;6345.333739957423;6489.994050922114;5449.7275136243825;6346.746130154742;6359.554831161774;6383.36166383358;6413.390727880844 22:50:27 INFO - PID 1636 | |1;ext.html;745.716627681481;772.8638900815803;808.9595520223987;1071.807074726549;1262.7916393795024;37649.81175094124;38484.615153848456;38538.61461385385;38651.613483865156;38683.22633547331;38637.647682331124;38925.8053709731;39021;39065.21869562607;39101.41347879777;7715.6913723451;8195.836083278331;8420.368472364567;8434;8450.704225352114;8238.423310368276;8308.626111824982;8334.333253339719;8335.624896879624;8343.45767525112;8221.630026648785;8231.917680823204;8267.2972797312;8305.66777328906;8336.833263334729;7091.468139889503;8308.833823323499;8314.625841837102;8320.001599808002;8328.66685332589;8145.551994640293;8185.8362832743605;8207;8259.669613215525;8264.83470330596;36876.26247475062;37047.814760926296;37079.62920370789;37181.442278365845;37202.81398593017;3486.4595987621706;3522.982385088058;3529.8058606776617;3531.134871956387;3541.008517615072;29683.257918551993;29736.55395169074;29879.701202987908;29886.85056574725;29945.550816737767;2956.364381657954;2976.4642364374404;2995.6105706258263;3001.4597372472945;3003.2191630176094;241129;244741;253052.734736327;296082;296547;47294.76352618284;47575.28637070447;47590.28614570784;47628.523714763105;47656.5234347659;49261.261081083816;49473.25790113151;49680.75159624251;49685.0062998738;49759.502404976214;14109.647258818406;14254.928725356307;14265.286735663383;14276.928615356857;14285.142891426547;11524.769504609862;11532.36571988532;11537.596184133516;11539.942300288445;11549;13191.47234110644;13272;13354.998375121904;13361.866381336256;13493;4998.825041123543;6024.789132380346;6072.696365181723;6114.6942652868065;6139.662318572521;972.4602845420859;998.5356809083665;1013.2400699475394;1038.8597539332245;1060.7772367802693;22939.541209175724;23785.453945493835;23849;23871.403214919417;24162.91266892994;1111.3165403276926;1115.8326251062242;1145.9713507162387;1146.2835727670206;1155.2259985809528;11707.531698732126;12048.578299759467;12235.449404776806;12236.265824050586;12369.897117490049;320895;323152;324151;324453;326634.10048849287;51899.48100519022;52321.7383913078;52430;52446.47553524416;52571;1459.6569806095558;1488.6724920517252;1521.117751704009;1523.3601887207178;1534.647031182834;19843.305484308556;20645.967701615158;23258.255630827418;23385.532289353778;23825.04699811988;37538.81230593939;46606;48738.75630621966;49199.754001231195;49528.75235623799;246051;248398;248808;249003;249197;26736.866315668296;32679.836600816845;33083.669163308055;33329.00012999614;33381.83309083439;38926.4161037579;42350.788246058575;42837;44433.555664442945;44621.55378446174;25996.480070398866;30051.34717590466;30387.2403189922;30468.542971855884;30813.845930770203;23051;27435.86282068657;30450.086497405115;30692.232694182825;31068.533971989986;37077.443838341904;37168.44247336346;37249.627503724616;37337.81331093436;37347.81326093461;29411.11766647004;30102.548461772654;30231.69768302289;30835.84582077075;30900.69099309068;20491.430683696737;22468;24342.878285608458;24525.264242072768;24677.753222467545 22:50:27 INFO - PID 1636 | |2;jquery.html;403.00256864260956;414.5253684531212;418.7215501691375;422.76536522230157;439.4836067620545;18755;20728.792712072875;21135.577288454242;21403.03686334115;21612.783872161275;21525.784742152573;22018.44953876154;22124.55750884981;22746.431339216528;23031.884840575814;5714.342850572186;5948.167256584082;5964.701764911759;5973.253343332083;5980;5662.17898404731;5846.912296315559;5849.700470154433;5860.560457965648;5884.852878678046;5830.3586605473365;5843.240378750777;5845.824625261228;5849.912251316234;5853.004989151833;5720.713964301789;5817.7091145442555;5959.642421454727;5969.432903874125;6001.729922153513;5175.171972484384;5269.499397557226;5302.072137375943;5324.866878328034;5331.306930099101;27424.725752742415;27782.444351112863;28016.439671206463;28300.71699283022;28309.292267693265;2057.372501387076;2058.2487392101925;2161.2867753641417;2183.7379514458366;2209.9116035358566;15096;17256.050917199547;17588.91205543964;17822.821771782375;18327;2141.764405915348;2156.6657168138963;2157.244964262511;2182.4434769133927;2183.617866873307;147576;155679.22160389126;160383.59424608524;161705.3829461714;163224;9786.804263914682;11614;11910.821337679941;12041.277523348626;12124;14775.261236938326;14927.552173434815;14941.477048303257;14961.401543938338;14995.400183992519;6931.7227310907;8722.258608018263;8754.124587541195;8829.16122968317;8876.689315873913;8382.203690649378;8431.578421079044;8480.024797148284;8614.353923455767;8688.91311086881;7536.472446928662;7638.618069096523;7646.847063058819;7652.693892244248;7679.157002319404;2150.8924553772563;2169.3817262080233;2171.7936796004356;2175.1408193763373;2303.986246051732;857.4340934983006;926.4672813132476;973.3284034016506;981.6073570571829;1013.6401577439902;20263.392098237076;20545.280915168198;20669;20740.962951852347;20930.127926333676;987.1855719031828;993.4436715439233;1012.2307046644603;1023.0945613132283;1032.1381646325403;10286.639967601248;10531.52608132636;10585.9470702646;10620.468976551141;10654.414007229521;514571;524034;524456;528717.3564132232;531422;116349.09127271886;128608.35695821648;130295.34852325867;131662;133714.66285337217;1093.4969913839723;1172.6364826903687;1207.5047486141746;1255.171586752739;1257.3147634539016;18349.990750508317;21222.151113955268;21626;21645.917704115047;21760.89119554392;55949;58415;59027.81944361003;59353.40646593652;59671.70164149151;56074.87850243054;79842;80344;80860;81355.59322203351;8462.449940753831;8652.961644602688;8845.955770221108;8878.822423551623;8920.420172688755;12936.870631293943;13432.328383580587;13640.454381825013;14240.786388203978;14348.426062957366;10278.743908394279;12068.517259309529;12096.637100886988;12123.393830308625;12148.453319600816;9978.900210997797;11299.830502542633;11505.482253298454;11696.47365868555;12078.36942012817;11154.498047587716;11510.194286400207;12286.87713122893;12361;12442.75114497723;8441.071482137082;8737.432066915884;8952.343816393988;9010.414323068979;9056.230220431336;11011;11412.31526108436;11760.82358764602;12075.51697932073;12181.330026848254 22:50:27 INFO - PID 1636 | |3;mootools.html;1402.8807551358134;1460.4377314733824;1461.9415223391065;1467.9192644404561;1472.7938088667588;11066;11526.193166478339;11526.711832204179;11541.769164616713;11930;11493.195476316661;11514.884851151486;11679.124065695065;11698.883011169886;11709.297442153476;2503.624456331556;2549.852566345143;2551.706553746315;2553.9106131285407;2566.2942690760005;2418.1899063813644;2449.448874003353;2470.7405722399158;2476.2199907029317;2477.9876100619563;2508.8871000804916;2522.6594409754725;2553.221267513408;2560.0527804712283;2560.219133164384;2472;2495.238952119612;2525.873706314677;2533.1513942829174;2533.316004678745;2426.5146970605774;2511.234073607567;2518.0809004713146;2522;2538.352720056392;11179;11294.265872718248;11302.265352752045;11307.321560706385;11314.094872410187;5334.27987221724;6541.476681865487;6558.540902136852;6560.606363618151;6573.1783527059115;3746.83139258734;4006.859759908389;4061.3298805697023;4074.3073677474604;4123.484564429446;1199.8800119988018;1202.8616709078392;1214.7509760499076;1223.6145614131608;1250.6310638361706;15160;15444.92277538605;15479.380824767108;15512.767308490382;15575.610609734847;5238.554722848525;5330.440303768126;5443;5487;5513.8345849624575;11229.213955023233;11242.269252498561;11247.66257012291;11254.943725281484;11255;6330.113784070234;6341.302456729756;6341.904871426932;6343.111964324999;6360.332165122595;5830.53355731549;5874.412558744177;5940.970295148497;6029.547783916224;6045.78839740607;3595.7303202259936;4098.200850834059;4212.262854000538;4220.345846393814;4224.345226489899;1239.6157191270736;1242.9254244745343;1249.0881656390886;1259.7732408166435;1260.155695683894;662.9668516574152;766.2835249042164;792.9801754956172;803.6785285885693;805.0942689474341;6448.322926092693;6501.1548498694865;6571.441427478718;6616.503762217853;6730.865382692319;870.1106055929982;870.5060250530984;938.9624415023459;939.9342046056711;940.4545363689044;3352.245744707421;3963.960360396417;4326.329418940069;4564.989779129454;4605.769711514411;14013.159210447202;14021;14213.928930355281;14259.714805703828;14265.286735663176;13703.177809331472;14009.649758756113;14028.438862445591;14170.008099433147;14180.645483862985;6482.902756458657;8154.714584989497;8359.247667709935;8364.958175209207;8387.245147936716;5884.882302354014;6640.302768209269;6815.216250131299;6845.349691779273;6921.377076063179;12126.636200913987;12535.373231338579;12615.621531354074;12820.487180512675;12846.229226246454;11131.276467029616;12229.69425764363;12482.251064936132;12514.812277816021;12919.935400322938;8791;8977.865332019894;9107.316951228551;9169.587368568564;9227.400218985744;10562.366258024236;10654;10757.731056723644;10784;10787.784244315228;8440.029396619222;8756.474611523328;8823.205911467998;8883.600237989434;8977.057408972225;8547.358948078794;8689.435186713097;8720.520371379635;8848.115188481357;8988.820223595621;6777.1189745332795;7939;8077.717279895175;8103.351731861555;8107.270345668682;5295.232191332312;5429.864253393696;5808.273965754281;5824.708764561839;6017.007193813147;5930.851728706644;5957.16599676037;6079.3008803988105;6101.572889897665;6259.655718935503 22:50:27 INFO - PID 1636 | |4;prototype.html;328.0045063233087;342.41788958770087;377.1438833847167;402.58131543195094;405.98985025374344;6132.754689812402;6212.1924149860515;6266.373362663737;6285.8628066457395;6355.269144048436;6031.24609423822;6131.54013448992;6144.3855614438535;6174.8147555573405;6175.413335733109;3515.8242087895633;3887.300285948528;4401.767505098577;4485;4488.630967554895;4026.7986600669997;4423.0711550574415;4435.981628359105;4448.666350023745;4487.259602165655;3460.82695865207;3849.441830934513;4436.866893993185;4457.84397546086;4518.774061296922;4456.3538286948215;4459.977700111495;4465.464144302705;4479.574440428154;4494.123645889053;3175.491921292582;3765.359888818907;3799.4680744695765;3844.3272427325105;3845.807709614536;4970.602351811883;5280.339957505312;5724.9427505724825;5980.641161530279;5984.401559844025;4554.838516178386;5140.614453915934;5330.227117068042;5342.492463216027;5353.00969320673;6374.235091789015;6512.869742605121;6570.342965703439;6624.73501059957;6654.334566543307;3446.8965931022108;4316.913661726748;4326.978365108186;4334.609885110323;4344.218040752663;6145.907811382833;6308.116863639094;6880.759173428956;6954.930450695479;7034.824129396755;4250.42619246404;4368.847090351886;4376.12477504494;4376.321670141069;4380.759058251827;4443.511213766483;4451.599356057905;4453.22068637987;4458.398116254329;4480.059187570647;5134.409542902614;5255.264263003182;5272.420033796279;5291.497307755757;5338.199270109515;6248.843778905472;6510.3489651034515;6573.704183311666;6593.011048342787;6608.768693095814;1489.8212214534326;1511.1235483419591;1516.257034053328;1519.2631573686754;1520.9847901521064;835.2983493865182;927.8283517549276;983.5180761426849;991.5339790298548;994.3884511025667;5184.351956005499;6256.499480041586;6266.342034086447;6278.560500764994;6279.748810047547;5959.880802383928;5973.850653733604;6805.149356330458;6815.693293801791;6821.386075253152;5164.14791559396;5953.523718102628;6052;6199.442050215503;6267.6866156692895;2987.641483022008;2995.7004299570303;3257.8533965971587;3471.7222622190166;3576.213233088716;5529.557635389159;5790.681512516852;5994.310654274815;6960.477964152708;7071.222165561784;5985.581009329392;7046.577205367695;7050.875237985733;7067.116610423697;7090.192001039805;6189.978653522118;6380.840478988062;6546.967265163644;6574.539782215199;6605.438537724252;6321.652309122858;6739.932600674127;6862.4853136016;6879;6900.309969003059;5696.829095127153;6708.597484150967;6766.93233067663;6782.762603308861;6789.422899053636;6957.199922008831;7066.646667666699;7074.327938845801;7111.608861512666;7140.821479463055;4320.848770293024;4331.45856767904;4360.738355698668;4386.210482113251;4387.780610969503;6864;6874.6562671867205;6875.518713689994;6883;7055.082839230867;5367.865803354947;5607.691576963143;5755.395683453177;6260;6518.5111116667385;5512.5589952804485;6172.351903050115;6347.460465860454;6442.967785161044;6623.768168114093;6178.258608966953;6314.284857511314;6394.176429876623;6475.320091390525;6490.448311893542;6557.344265573595;6632.602043877383;6657.367550082734;6674.899876501953;6689.632070235989;5398.811041613525;5439.939211853809;5504.91742623869;6432.163818703539;6482.189726284215 22:50:27 INFO - PID 1636 | |5;yui.html;764.4648745877885;824.3322908444164;842.0400743152807;861.8580380995182;865.3120768988651;13972.441102355906;16344.427945021927;16692.41576544821;18567.350142745006;19146.904265478654;17708.734368984442;18090.185941632626;18267.177976991035;18319;18492.982885941266;960.6109525642108;974.2060220919963;978.5449765858874;988.5946761827652;991.3952488981715;987.5704068730089;1005.1857995024018;1009.5709323537504;1012.8835183953865;1014.2899970020978;1043.118564812732;1049.7270709615516;1053.3469249065552;1055.8046761349137;1061.5170097605571;1039.953202105907;1043.2644985285356;1050.1808589300358;1051.337657275919;1051.948051948052;747.3572727454385;750.0362034785329;752.8833030880222;761.3794757272805;769.1731388757053;16896.915515422468;16900.492985210465;17011.234494447657;17055.48833534996;17077.060761658104;16610.418635347825;16668.166591670488;16979.235934382858;17071.061091639956;17074.97550146995;12533;14093.85906140946;14357.210353430557;14390.352434140488;14396.136231826124;3461.6884480396634;3481.599616044162;3543.5216245807;3548.3790336690977;3549.1836877518317;113406.29890551648;114271.1432214193;117691;128207;129849;13296.534621288209;13543.119697219649;13605.183688978694;13632.659183520293;13706.24615646149;23686.407839804142;24139.39651508691;24198.879005605213;24546.404483708502;24694.13570525023;7593;7869.213078692085;7869.2130786922;7872.842543149105;7872.960635196902;3830.731848770615;3876.4185372194397;3909.3940439231965;3915.960840391617;3916.412538119248;3985.322495175827;4148.979255103705;4168.812403441852;4169.728967617095;4185.790710464465;796.9760907172794;915.7115508614766;942.2979879989451;951.2675240065112;955.8279509688323;646.2923099206362;667.813012356541;676.1075380497696;676.9255381907986;692.2489099327287;10191.184705223712;11146.718127415452;11942.686304506497;12007.519699211934;12037.87962120385;660.9405153536205;662.3178126529682;668.866226754651;671.5064427645676;674.5817593092314;5021.698698078127;5418.187271909245;5511.751971161308;5518.144687573352;5683.403242659544;90919.09080909239;100655.49672251592;102037.48981255046;102191.48904255431;102336.46495302576;78104.60947695225;86144.56927715447;87589;88291.11708882957;88445;68193;80542.19457805464;80692.59653701694;81463.96340091545;81522;10515.316504427188;11923.701907452036;12579.308138052495;12626.873731262569;12730.427130779322;35266.413011414035;36672.99999999893;38864.6113538861;39911.2017759649;41235.587644124374;51840.92636294506;52303.73848130735;73339.26660733538;74279;75588;15570.84429155694;15778.68442631164;16091.195440228175;16105.516834494983;16637.584060398585;16645.41741039058;16992.479703177753;17247.7412838805;17380;17501.47495575135;15892.68214635724;16416;16764.32942682328;17402.47792566225;17507;15727.370905163665;16425.917870410573;16579.419720310212;16597.58506037359;16740.665186696442;15831.604209894846;16252.918735406247;16799.664006720042;16926;17070.48788536346;10066.798664026532;10502.579896804347;10578.836328004067;11109.00018998294;12098.516059357878;12868.678283042998;13503.39234734459;13637;13653.180809151483;13770.311484425538 22:50:27 INFO - PID 1636 | __end_tp_report 22:50:27 INFO - PID 1636 | __start_cc_report 22:50:27 INFO - PID 1636 | _x_x_mozilla_cycle_collect,332 22:50:27 INFO - PID 1636 | __end_cc_report 22:50:27 INFO - PID 1636 | __startTimestamp1495432227285__endTimestamp 22:50:27 INFO - PID 1636 | 22:50:27 INFO - PID 1636 | ------- Summary: start ------- 22:50:27 INFO - PID 1636 | Number of tests: 6 22:50:27 INFO - PID 1636 | 22:50:27 INFO - PID 1636 | [#0] dojo.html Cycles:165 Average:6117.35 Median:6372.97 stddev:740.33 (11.6%) stddev-sans-first:714.36 22:50:27 INFO - PID 1636 | Values: 3535.9 3558.2 3583.8 3607.8 3611.9 6138.0 6153.4 6202.1 6207.7 6233.3 5199.9 6108.6 6111.7 6132.1 6146.8 4461.0 5391.1 5424.9 5475.9 5533.7 5354.6 5484.0 5533.8 5536.7 5560.9 4619.7 5454.8 5480.4 5516.9 5525.7 5358.3 5424.2 5433.7 5498.9 5513.8 5447.1 5470.7 5474.5 5507.9 5513.0 5178.7 6044.6 6064.5 6333.9 6335.8 6432.1 6469.7 6677.9 6689.7 6708.7 6623.7 6715.3 6749.5 6761.2 6765.7 6338.9 6374.6 6374.7 6376.6 6391.9 6690.8 6811.4 6920.9 6930.0 6971.7 5282.9 6254.4 6387.7 6419.5 6498.9 6712.6 6746.8 6750.6 6772.9 6783.5 6365.8 6387.4 6544.8 6604.8 6636.9 6415.6 6556.0 6597.7 6621.7 6656.4 5337.7 5341.8 5348.9 5351.0 5398.5 4702.1 4735.8 4754.5 4795.1 4806.6 6298.3 6330.5 6345.4 6357.5 6385.5 6740.3 6741.6 6749.2 6756.4 6765.4 6282.7 6323.0 6377.5 6385.0 6394.5 5754.5 5807.2 5826.6 5893.8 5905.8 6886.3 6940.9 6944.5 6944.8 6955.4 6677.3 6737.3 6759.1 6767.7 6794.8 6692.7 6750.4 6879.3 6927.2 6930.8 5717.5 6554.4 6564.0 6602.8 6620.4 6605.5 6693.8 6776.6 6794.6 6799.9 6334.1 6548.8 6553.5 6565.7 6568.2 6373.0 6428.0 6479.6 6491.5 6506.2 4923.6 6162.5 6349.2 6374.8 6405.6 6266.9 6337.7 6339.3 6345.3 6490.0 5449.7 6346.7 6359.6 6383.4 6413.4 22:50:27 INFO - PID 1636 | 22:50:27 INFO - PID 1636 | [#1] ext.html Cycles:180 Average:43253.03 Median:179656.97 stddev:73824.86 (41.1%) stddev-sans-first:73962.97 22:50:27 INFO - PID 1636 | Values: 745.7 772.9 809.0 1071.8 1262.8 37649.8 38484.6 38538.6 38651.6 38683.2 38637.6 38925.8 39021.0 39065.2 39101.4 7715.7 8195.8 8420.4 8434.0 8450.7 8238.4 8308.6 8334.3 8335.6 8343.5 8221.6 8231.9 8267.3 8305.7 8336.8 7091.5 8308.8 8314.6 8320.0 8328.7 8145.6 8185.8 8207.0 8259.7 8264.8 36876.3 37047.8 37079.6 37181.4 37202.8 3486.5 3523.0 3529.8 3531.1 3541.0 29683.3 29736.6 29879.7 29886.9 29945.6 2956.4 2976.5 2995.6 3001.5 3003.2 241129.0 244741.0 253052.7 296082.0 296547.0 47294.8 47575.3 47590.3 47628.5 47656.5 49261.3 49473.3 49680.8 49685.0 49759.5 14109.6 14254.9 14265.3 14276.9 14285.1 11524.8 11532.4 11537.6 11539.9 11549.0 13191.5 13272.0 13355.0 13361.9 13493.0 4998.8 6024.8 6072.7 6114.7 6139.7 972.5 998.5 1013.2 1038.9 1060.8 22939.5 23785.5 23849.0 23871.4 24162.9 1111.3 1115.8 1146.0 1146.3 1155.2 11707.5 12048.6 12235.4 12236.3 12369.9 320895.0 323152.0 324151.0 324453.0 326634.1 51899.5 52321.7 52430.0 52446.5 52571.0 1459.7 1488.7 1521.1 1523.4 1534.6 19843.3 20646.0 23258.3 23385.5 23825.0 37538.8 46606.0 48738.8 49199.8 49528.8 246051.0 248398.0 248808.0 249003.0 249197.0 26736.9 32679.8 33083.7 33329.0 33381.8 38926.4 42350.8 42837.0 44433.6 44621.6 25996.5 30051.3 30387.2 30468.5 30813.8 23051.0 27435.9 30450.1 30692.2 31068.5 37077.4 37168.4 37249.6 37337.8 37347.8 29411.1 30102.5 30231.7 30835.8 30900.7 20491.4 22468.0 24342.9 24525.3 24677.8 22:50:27 INFO - PID 1636 | 22:50:27 INFO - PID 1636 | [#2] jquery.html Cycles:180 Average:34799.05 Median:12101.09 stddev:89521.98 (739.8%) stddev-sans-first:89735.86 22:50:27 INFO - PID 1636 | Values: 403.0 414.5 418.7 422.8 439.5 18755.0 20728.8 21135.6 21403.0 21612.8 21525.8 22018.4 22124.6 22746.4 23031.9 5714.3 5948.2 5964.7 5973.3 5980.0 5662.2 5846.9 5849.7 5860.6 5884.9 5830.4 5843.2 5845.8 5849.9 5853.0 5720.7 5817.7 5959.6 5969.4 6001.7 5175.2 5269.5 5302.1 5324.9 5331.3 27424.7 27782.4 28016.4 28300.7 28309.3 2057.4 2058.2 2161.3 2183.7 2209.9 15096.0 17256.1 17588.9 17822.8 18327.0 2141.8 2156.7 2157.2 2182.4 2183.6 147576.0 155679.2 160383.6 161705.4 163224.0 9786.8 11614.0 11910.8 12041.3 12124.0 14775.3 14927.6 14941.5 14961.4 14995.4 6931.7 8722.3 8754.1 8829.2 8876.7 8382.2 8431.6 8480.0 8614.4 8688.9 7536.5 7638.6 7646.8 7652.7 7679.2 2150.9 2169.4 2171.8 2175.1 2304.0 857.4 926.5 973.3 981.6 1013.6 20263.4 20545.3 20669.0 20741.0 20930.1 987.2 993.4 1012.2 1023.1 1032.1 10286.6 10531.5 10585.9 10620.5 10654.4 514571.0 524034.0 524456.0 528717.4 531422.0 116349.1 128608.4 130295.3 131662.0 133714.7 1093.5 1172.6 1207.5 1255.2 1257.3 18350.0 21222.2 21626.0 21645.9 21760.9 55949.0 58415.0 59027.8 59353.4 59671.7 56074.9 79842.0 80344.0 80860.0 81355.6 8462.4 8653.0 8846.0 8878.8 8920.4 12936.9 13432.3 13640.5 14240.8 14348.4 10278.7 12068.5 12096.6 12123.4 12148.5 9978.9 11299.8 11505.5 11696.5 12078.4 11154.5 11510.2 12286.9 12361.0 12442.8 8441.1 8737.4 8952.3 9010.4 9056.2 11011.0 11412.3 11760.8 12075.5 12181.3 22:50:27 INFO - PID 1636 | 22:50:27 INFO - PID 1636 | [#3] mootools.html Cycles:180 Average:6847.18 Median:3473.99 stddev:4237.31 (122.0%) stddev-sans-first:4229.44 22:50:27 INFO - PID 1636 | Values: 1402.9 1460.4 1461.9 1467.9 1472.8 11066.0 11526.2 11526.7 11541.8 11930.0 11493.2 11514.9 11679.1 11698.9 11709.3 2503.6 2549.9 2551.7 2553.9 2566.3 2418.2 2449.4 2470.7 2476.2 2478.0 2508.9 2522.7 2553.2 2560.1 2560.2 2472.0 2495.2 2525.9 2533.2 2533.3 2426.5 2511.2 2518.1 2522.0 2538.4 11179.0 11294.3 11302.3 11307.3 11314.1 5334.3 6541.5 6558.5 6560.6 6573.2 3746.8 4006.9 4061.3 4074.3 4123.5 1199.9 1202.9 1214.8 1223.6 1250.6 15160.0 15444.9 15479.4 15512.8 15575.6 5238.6 5330.4 5443.0 5487.0 5513.8 11229.2 11242.3 11247.7 11254.9 11255.0 6330.1 6341.3 6341.9 6343.1 6360.3 5830.5 5874.4 5941.0 6029.5 6045.8 3595.7 4098.2 4212.3 4220.3 4224.3 1239.6 1242.9 1249.1 1259.8 1260.2 663.0 766.3 793.0 803.7 805.1 6448.3 6501.2 6571.4 6616.5 6730.9 870.1 870.5 939.0 939.9 940.5 3352.2 3964.0 4326.3 4565.0 4605.8 14013.2 14021.0 14213.9 14259.7 14265.3 13703.2 14009.6 14028.4 14170.0 14180.6 6482.9 8154.7 8359.2 8365.0 8387.2 5884.9 6640.3 6815.2 6845.3 6921.4 12126.6 12535.4 12615.6 12820.5 12846.2 11131.3 12229.7 12482.3 12514.8 12919.9 8791.0 8977.9 9107.3 9169.6 9227.4 10562.4 10654.0 10757.7 10784.0 10787.8 8440.0 8756.5 8823.2 8883.6 8977.1 8547.4 8689.4 8720.5 8848.1 8988.8 6777.1 7939.0 8077.7 8103.4 8107.3 5295.2 5429.9 5808.3 5824.7 6017.0 5930.9 5957.2 6079.3 6101.6 6259.7 22:50:27 INFO - PID 1636 | 22:50:27 INFO - PID 1636 | [#4] prototype.html Cycles:180 Average:5239.14 Median:5989.95 stddev:1693.54 (28.3%) stddev-sans-first:1657.68 22:50:27 INFO - PID 1636 | Values: 328.0 342.4 377.1 402.6 406.0 6132.8 6212.2 6266.4 6285.9 6355.3 6031.2 6131.5 6144.4 6174.8 6175.4 3515.8 3887.3 4401.8 4485.0 4488.6 4026.8 4423.1 4436.0 4448.7 4487.3 3460.8 3849.4 4436.9 4457.8 4518.8 4456.4 4460.0 4465.5 4479.6 4494.1 3175.5 3765.4 3799.5 3844.3 3845.8 4970.6 5280.3 5724.9 5980.6 5984.4 4554.8 5140.6 5330.2 5342.5 5353.0 6374.2 6512.9 6570.3 6624.7 6654.3 3446.9 4316.9 4327.0 4334.6 4344.2 6145.9 6308.1 6880.8 6954.9 7034.8 4250.4 4368.8 4376.1 4376.3 4380.8 4443.5 4451.6 4453.2 4458.4 4480.1 5134.4 5255.3 5272.4 5291.5 5338.2 6248.8 6510.3 6573.7 6593.0 6608.8 1489.8 1511.1 1516.3 1519.3 1521.0 835.3 927.8 983.5 991.5 994.4 5184.4 6256.5 6266.3 6278.6 6279.7 5959.9 5973.9 6805.1 6815.7 6821.4 5164.1 5953.5 6052.0 6199.4 6267.7 2987.6 2995.7 3257.9 3471.7 3576.2 5529.6 5790.7 5994.3 6960.5 7071.2 5985.6 7046.6 7050.9 7067.1 7090.2 6190.0 6380.8 6547.0 6574.5 6605.4 6321.7 6739.9 6862.5 6879.0 6900.3 5696.8 6708.6 6766.9 6782.8 6789.4 6957.2 7066.6 7074.3 7111.6 7140.8 4320.8 4331.5 4360.7 4386.2 4387.8 6864.0 6874.7 6875.5 6883.0 7055.1 5367.9 5607.7 5755.4 6260.0 6518.5 5512.6 6172.4 6347.5 6443.0 6623.8 6178.3 6314.3 6394.2 6475.3 6490.4 6557.3 6632.6 6657.4 6674.9 6689.6 5398.8 5439.9 5504.9 6432.2 6482.2 22:50:27 INFO - PID 1636 | 22:50:27 INFO - PID 1636 | [#5] yui.html Cycles:180 Average:21464.11 Median:17607.87 stddev:29742.17 (168.9%) stddev-sans-first:29784.99 22:50:27 INFO - PID 1636 | Values: 764.5 824.3 842.0 861.9 865.3 13972.4 16344.4 16692.4 18567.4 19146.9 17708.7 18090.2 18267.2 18319.0 18493.0 960.6 974.2 978.5 988.6 991.4 987.6 1005.2 1009.6 1012.9 1014.3 1043.1 1049.7 1053.3 1055.8 1061.5 1040.0 1043.3 1050.2 1051.3 1051.9 747.4 750.0 752.9 761.4 769.2 16896.9 16900.5 17011.2 17055.5 17077.1 16610.4 16668.2 16979.2 17071.1 17075.0 12533.0 14093.9 14357.2 14390.4 14396.1 3461.7 3481.6 3543.5 3548.4 3549.2 113406.3 114271.1 117691.0 128207.0 129849.0 13296.5 13543.1 13605.2 13632.7 13706.2 23686.4 24139.4 24198.9 24546.4 24694.1 7593.0 7869.2 7869.2 7872.8 7873.0 3830.7 3876.4 3909.4 3916.0 3916.4 3985.3 4149.0 4168.8 4169.7 4185.8 797.0 915.7 942.3 951.3 955.8 646.3 667.8 676.1 676.9 692.2 10191.2 11146.7 11942.7 12007.5 12037.9 660.9 662.3 668.9 671.5 674.6 5021.7 5418.2 5511.8 5518.1 5683.4 90919.1 100655.5 102037.5 102191.5 102336.5 78104.6 86144.6 87589.0 88291.1 88445.0 68193.0 80542.2 80692.6 81464.0 81522.0 10515.3 11923.7 12579.3 12626.9 12730.4 35266.4 36673.0 38864.6 39911.2 41235.6 51840.9 52303.7 73339.3 74279.0 75588.0 15570.8 15778.7 16091.2 16105.5 16637.6 16645.4 16992.5 17247.7 17380.0 17501.5 15892.7 16416.0 16764.3 17402.5 17507.0 15727.4 16425.9 16579.4 16597.6 16740.7 15831.6 16252.9 16799.7 16926.0 17070.5 10066.8 10502.6 10578.8 11109.0 12098.5 12868.7 13503.4 13637.0 13653.2 13770.3 22:50:27 INFO - PID 1636 | -------- Summary: end -------- 22:50:27 INFO - PID 1636 | 22:50:27 INFO - TEST-INFO | 1636: exit 0 22:50:27 INFO - TEST-OK | dromaeo_css | took 1113649ms 22:50:27 INFO - TEST-START | kraken 22:50:27 INFO - operating with platform_type : mac_ 22:50:27 INFO - Initialising browser for kraken test... 22:50:27 INFO - TEST-INFO | started process 1649 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/T/tmpRDQVDq/profile http://localhost:49227/getInfo.html) 22:50:28 INFO - PID 1649 | JavaScript warning: resource://gre/modules/addons/XPIProvider.jsm, line 2978: String.localeCompare is deprecated; use String.prototype.localeCompare instead 22:50:30 INFO - PID 1649 | 2017-05-21 22:50:30.410 plugin-container[1650:10328] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x963f, name = 'com.apple.tsm.portname' 22:50:30 INFO - PID 1649 | See /usr/include/servers/bootstrap_defs.h for the error codes. 22:50:30 INFO - PID 1649 | 2017-05-21 22:50:30.414 plugin-container[1650:10328] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x471f, name = 'com.apple.CFPasteboardClient' 22:50:30 INFO - PID 1649 | See /usr/include/servers/bootstrap_defs.h for the error codes. 22:50:30 INFO - PID 1649 | 2017-05-21 22:50:30.415 plugin-container[1650:10328] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 22:50:30 INFO - PID 1649 | __metrics Screen width/height:1600/1200 22:50:30 INFO - PID 1649 | colorDepth:24 22:50:30 INFO - PID 1649 | Browser inner width/height: 1024/691 22:50:30 INFO - PID 1649 | __metrics 22:50:31 INFO - PID 1649 | 2017-05-21 22:50:31.031 plugin-container[1651:10385] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x953f, name = 'com.apple.tsm.portname' 22:50:31 INFO - PID 1649 | See /usr/include/servers/bootstrap_defs.h for the error codes. 22:50:31 INFO - PID 1649 | 2017-05-21 22:50:31.032 plugin-container[1651:10385] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9b0b, name = 'com.apple.CFPasteboardClient' 22:50:31 INFO - PID 1649 | See /usr/include/servers/bootstrap_defs.h for the error codes. 22:50:31 INFO - PID 1649 | 2017-05-21 22:50:31.032 plugin-container[1651:10385] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 22:50:31 INFO - TEST-INFO | 1649: exit 0 22:50:31 INFO - Browser initialized. 22:50:31 INFO - Running cycle 1/1 for kraken test... 22:50:31 INFO - TEST-INFO | started process 1652 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/T/tmpRDQVDq/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:50:33 INFO - PID 1652 | 2017-05-21 22:50:33.549 plugin-container[1653:10478] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9a3f, name = 'com.apple.tsm.portname' 22:50:33 INFO - PID 1652 | See /usr/include/servers/bootstrap_defs.h for the error codes. 22:50:33 INFO - PID 1652 | 2017-05-21 22:50:33.551 plugin-container[1653:10478] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4813, name = 'com.apple.CFPasteboardClient' 22:50:33 INFO - PID 1652 | See /usr/include/servers/bootstrap_defs.h for the error codes. 22:50:33 INFO - PID 1652 | 2017-05-21 22:50:33.551 plugin-container[1653:10478] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 22:50:34 INFO - PID 1652 | 2017-05-21 22:50:34.199 plugin-container[1654:10524] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4947, name = 'com.apple.tsm.portname' 22:50:34 INFO - PID 1652 | See /usr/include/servers/bootstrap_defs.h for the error codes. 22:50:34 INFO - PID 1652 | 2017-05-21 22:50:34.200 plugin-container[1654:10524] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9723, name = 'com.apple.CFPasteboardClient' 22:50:34 INFO - PID 1652 | See /usr/include/servers/bootstrap_defs.h for the error codes. 22:50:34 INFO - PID 1652 | 2017-05-21 22:50:34.200 plugin-container[1654:10524] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 22:50:43 INFO - PID 1652 | RSS: Main: 241954816 22:50:43 INFO - PID 1652 | 22:50:46 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49227/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49227/tests/kraken/driver.html?testName=audio-beat-detection) 22:50:47 INFO - PID 1652 | RSS: Main: 254345216 22:50:47 INFO - PID 1652 | 22:50:49 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49227/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49227/tests/kraken/driver.html?testName=audio-dft) 22:50:50 INFO - PID 1652 | RSS: Main: 262238208 22:50:50 INFO - PID 1652 | 22:50:53 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49227/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49227/tests/kraken/driver.html?testName=audio-fft) 22:50:54 INFO - PID 1652 | RSS: Main: 264892416 22:50:54 INFO - PID 1652 | 22:50:56 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49227/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49227/tests/kraken/driver.html?testName=audio-oscillator) 22:50:57 INFO - PID 1652 | RSS: Main: 267214848 22:50:57 INFO - PID 1652 | 22:50:58 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49227/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49227/tests/kraken/driver.html?testName=imaging-gaussian-blur) 22:50:59 INFO - PID 1652 | RSS: Main: 267624448 22:50:59 INFO - PID 1652 | 22:51:02 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49227/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49227/tests/kraken/driver.html?testName=imaging-darkroom) 22:51:03 INFO - PID 1652 | RSS: Main: 277721088 22:51:03 INFO - PID 1652 | 22:51:06 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49227/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49227/tests/kraken/driver.html?testName=imaging-desaturate) 22:51:06 INFO - PID 1652 | RSS: Main: 277135360 22:51:06 INFO - PID 1652 | 22:51:09 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49227/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49227/tests/kraken/driver.html?testName=json-parse-financial) 22:51:10 INFO - PID 1652 | RSS: Main: 276459520 22:51:10 INFO - PID 1652 | 22:51:11 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49227/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49227/tests/kraken/driver.html?testName=json-stringify-tinderbox) 22:51:12 INFO - PID 1652 | RSS: Main: 276209664 22:51:12 INFO - PID 1652 | 22:51:14 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49227/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49227/tests/kraken/driver.html?testName=stanford-crypto-aes) 22:51:15 INFO - PID 1652 | RSS: Main: 275931136 22:51:15 INFO - PID 1652 | 22:51:17 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49227/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49227/tests/kraken/driver.html?testName=stanford-crypto-ccm) 22:51:17 INFO - PID 1652 | RSS: Main: 275513344 22:51:17 INFO - PID 1652 | 22:51:20 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49227/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49227/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 22:51:21 INFO - PID 1652 | RSS: Main: 275599360 22:51:21 INFO - PID 1652 | 22:51:24 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49227/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49227/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 22:51:25 INFO - PID 1652 | RSS: Main: 275869696 22:51:25 INFO - PID 1652 | 22:51:26 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49227/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49227/tests/kraken/driver.html?testName=ai-astar) 22:51:27 INFO - PID 1652 | RSS: Main: 277524480 22:51:27 INFO - PID 1652 | 22:51:27 INFO - PID 1652 | __start_tp_report 22:51:27 INFO - PID 1652 | _x_x_mozilla_page_load 22:51:27 INFO - PID 1652 | _x_x_mozilla_page_load_details 22:51:27 INFO - PID 1652 | |i|pagename|runs| 22:51:27 INFO - PID 1652 | |0;ai-astar;103;131;98;106;137;172;145;132;171;136 22:51:27 INFO - PID 1652 | |1;audio-beat-detection;145;112;113;147;114;114;109;93;158;145 22:51:27 INFO - PID 1652 | |2;audio-dft;201;196;204;205;209;211;200;199;199;197 22:51:27 INFO - PID 1652 | |3;audio-fft;84;49;59;94;89;84;58;58;80;58 22:51:27 INFO - PID 1652 | |4;audio-oscillator;89;70;83;70;74;71;86;71;71;89 22:51:27 INFO - PID 1652 | |5;imaging-gaussian-blur;91;101;108;91;90;90;90;99;136;99 22:51:27 INFO - PID 1652 | |6;imaging-darkroom;97;97;97;98;99;106;119;97;104;109 22:51:27 INFO - PID 1652 | |7;imaging-desaturate;94;97;91;93;95;90;116;86;143;90 22:51:27 INFO - PID 1652 | |8;json-parse-financial;50;49;48;41;45;71;82;81;67;50 22:51:27 INFO - PID 1652 | |9;json-stringify-tinderbox;58;59;54;72;63;77;82;57;55;57 22:51:27 INFO - PID 1652 | |10;stanford-crypto-aes;83;84;84;85;83;84;85;84;83;85 22:51:27 INFO - PID 1652 | |11;stanford-crypto-ccm;150;166;136;138;131;150;157;154;221;152 22:51:27 INFO - PID 1652 | |12;stanford-crypto-pbkdf2;187;184;183;186;187;210;231;187;180;183 22:51:27 INFO - PID 1652 | |13;stanford-crypto-sha256-iterative;72;70;68;70;69;69;70;72;68;73 22:51:27 INFO - PID 1652 | __end_tp_report 22:51:27 INFO - PID 1652 | __start_cc_report 22:51:27 INFO - PID 1652 | _x_x_mozilla_cycle_collect,907 22:51:27 INFO - PID 1652 | __end_cc_report 22:51:27 INFO - PID 1652 | __startTimestamp1495432287176__endTimestamp 22:51:27 INFO - PID 1652 | 22:51:27 INFO - PID 1652 | ------- Summary: start ------- 22:51:27 INFO - PID 1652 | Number of tests: 14 22:51:27 INFO - PID 1652 | 22:51:27 INFO - PID 1652 | [#0] ai-astar Cycles:10 Average:133.10 Median:141.00 stddev:25.80 (18.3%) stddev-sans-first:24.97 22:51:27 INFO - PID 1652 | Values: 103.0 131.0 98.0 106.0 137.0 172.0 145.0 132.0 171.0 136.0 22:51:27 INFO - PID 1652 | 22:51:27 INFO - PID 1652 | [#1] audio-beat-detection Cycles:10 Average:125.00 Median:145.00 stddev:21.62 (14.9%) stddev-sans-first:21.69 22:51:27 INFO - PID 1652 | Values: 145.0 112.0 113.0 147.0 114.0 114.0 109.0 93.0 158.0 145.0 22:51:27 INFO - PID 1652 | 22:51:27 INFO - PID 1652 | [#2] audio-dft Cycles:10 Average:202.10 Median:202.50 stddev:5.02 (2.5%) stddev-sans-first:5.31 22:51:27 INFO - PID 1652 | Values: 201.0 196.0 204.0 205.0 209.0 211.0 200.0 199.0 199.0 197.0 22:51:27 INFO - PID 1652 | 22:51:27 INFO - PID 1652 | [#3] audio-fft Cycles:10 Average:71.30 Median:82.00 stddev:16.35 (19.9%) stddev-sans-first:16.68 22:51:27 INFO - PID 1652 | Values: 84.0 49.0 59.0 94.0 89.0 84.0 58.0 58.0 80.0 58.0 22:51:27 INFO - PID 1652 | 22:51:27 INFO - PID 1652 | [#4] audio-oscillator Cycles:10 Average:77.40 Median:78.50 stddev:8.29 (10.6%) stddev-sans-first:7.66 22:51:27 INFO - PID 1652 | Values: 89.0 70.0 83.0 70.0 74.0 71.0 86.0 71.0 71.0 89.0 22:51:27 INFO - PID 1652 | 22:51:27 INFO - PID 1652 | [#5] imaging-gaussian-blur Cycles:10 Average:99.50 Median:90.50 stddev:14.23 (15.7%) stddev-sans-first:14.76 22:51:27 INFO - PID 1652 | Values: 91.0 101.0 108.0 91.0 90.0 90.0 90.0 99.0 136.0 99.0 22:51:27 INFO - PID 1652 | 22:51:27 INFO - PID 1652 | [#6] imaging-darkroom Cycles:10 Average:102.30 Median:97.00 stddev:7.32 (7.5%) stddev-sans-first:7.51 22:51:27 INFO - PID 1652 | Values: 97.0 97.0 97.0 98.0 99.0 106.0 119.0 97.0 104.0 109.0 22:51:27 INFO - PID 1652 | 22:51:27 INFO - PID 1652 | [#7] imaging-desaturate Cycles:10 Average:99.50 Median:92.00 stddev:17.32 (18.8%) stddev-sans-first:18.25 22:51:27 INFO - PID 1652 | Values: 94.0 97.0 91.0 93.0 95.0 90.0 116.0 86.0 143.0 90.0 22:51:27 INFO - PID 1652 | 22:51:27 INFO - PID 1652 | [#8] json-parse-financial Cycles:10 Average:58.40 Median:58.50 stddev:15.35 (26.2%) stddev-sans-first:15.98 22:51:27 INFO - PID 1652 | Values: 50.0 49.0 48.0 41.0 45.0 71.0 82.0 81.0 67.0 50.0 22:51:27 INFO - PID 1652 | 22:51:27 INFO - PID 1652 | [#9] json-stringify-tinderbox Cycles:10 Average:63.40 Median:61.00 stddev:9.97 (16.3%) stddev-sans-first:10.38 22:51:27 INFO - PID 1652 | Values: 58.0 59.0 54.0 72.0 63.0 77.0 82.0 57.0 55.0 57.0 22:51:27 INFO - PID 1652 | 22:51:27 INFO - PID 1652 | [#10] stanford-crypto-aes Cycles:10 Average:84.00 Median:84.00 stddev:0.82 (1.0%) stddev-sans-first:0.78 22:51:27 INFO - PID 1652 | Values: 83.0 84.0 84.0 85.0 83.0 84.0 85.0 84.0 83.0 85.0 22:51:27 INFO - PID 1652 | 22:51:27 INFO - PID 1652 | [#11] stanford-crypto-ccm Cycles:10 Average:155.50 Median:153.00 stddev:25.31 (16.5%) stddev-sans-first:26.76 22:51:27 INFO - PID 1652 | Values: 150.0 166.0 136.0 138.0 131.0 150.0 157.0 154.0 221.0 152.0 22:51:27 INFO - PID 1652 | 22:51:27 INFO - PID 1652 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:191.80 Median:187.00 stddev:16.07 (8.6%) stddev-sans-first:16.96 22:51:27 INFO - PID 1652 | Values: 187.0 184.0 183.0 186.0 187.0 210.0 231.0 187.0 180.0 183.0 22:51:27 INFO - PID 1652 | 22:51:27 INFO - PID 1652 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:70.10 Median:70.00 stddev:1.73 (2.5%) stddev-sans-first:1.69 22:51:27 INFO - PID 1652 | Values: 72.0 70.0 68.0 70.0 69.0 69.0 70.0 72.0 68.0 73.0 22:51:27 INFO - PID 1652 | -------- Summary: end -------- 22:51:27 INFO - PID 1652 | 22:51:27 INFO - TEST-INFO | 1652: exit 0 22:51:27 INFO - TEST-OK | kraken | took 60113ms 22:51:27 INFO - SUITE-END | took 1173s 22:51:28 INFO - Completed test suite (00:19:34) 22:51:28 INFO - javascript benchmark 22:51:28 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7772.149422696176, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3535.8762443314486, 3558.2171922177126, 3583.838727257273, 3607.8015709135993, 3611.9097022574433, 6137.959273351912, 6153.415425534573, 6202.10069539917, 6207.658578778165, 6233.283172435172, 5199.922001169976, 6108.584917381041, 6111.652512877634, 6132.110769107974, 6146.7541298348015, 4461.0, 5391.056565101111, 5424.864378390533, 5475.863103422406, 5533.723313834313, 5354.5716342692685, 5483.985462689415, 5533.833984980457, 5536.667799931997, 5560.944390556083, 4619.74591397473, 5454.781808727646, 5480.369757477902, 5516.862078448051, 5525.696086715229, 5358.33020872391, 5424.159255315432, 5433.701146436984, 5498.890022199574, 5513.751881165357, 5447.073997420448, 5470.699111548864, 5474.507294343488, 5507.862303442446, 5512.952539017614, 5178.689278643255, 6044.6399560098625, 6064.4845188158615, 6333.87332253357, 6335.778247761306, 6432.131662225586, 6469.74121035158, 6677.93322066778, 6689.665516724193, 6708.664566771642, 6623.668816559201, 6715.2613212546585, 6749.527533072687, 6761.188657361099, 6765.695543700496, 6338.873222535523, 6374.553781235269, 6374.745010199586, 6376.649284289409, 6391.936080639227, 6690.83272918181, 6811.4210292124735, 6920.8961865572055, 6929.960505924152, 6971.6862741176765, 5282.947170528322, 6254.437100660963, 6387.712552935036, 6419.518536109717, 6498.935010649833, 6712.597244165366, 6746.763863264762, 6750.561213521106, 6772.932270677329, 6783.491238157059, 6365.809025729235, 6387.361263873576, 6544.770933017321, 6604.834879127964, 6636.8672626547195, 6415.647139407284, 6556.0, 6597.670116494156, 6621.702023408958, 6656.400923916871, 5337.6797392156595, 5341.839744807662, 5348.919766203432, 5351.0, 5398.460153984649, 4702.130105930414, 4735.810567577259, 4754.5245475452175, 4795.112904112751, 4806.591439727592, 6298.275698294663, 6330.493560515148, 6345.428911397997, 6357.4596159326975, 6385.457236134887, 6740.258571557125, 6741.629210393476, 6749.190097188369, 6756.425703815231, 6765.35729105744, 6282.748690052346, 6322.968385158045, 6377.521685873486, 6385.042243663488, 6394.520410969196, 5754.4533269339345, 5807.245058142415, 5826.592138550261, 5893.823185304448, 5905.793297234576, 6886.311368863174, 6940.895886561807, 6944.479164062816, 6944.826379340557, 6955.374016338655, 6677.29888361715, 6737.326267373223, 6759.053732477359, 6767.695453704497, 6794.830129246809, 6692.665366731741, 6750.35871592198, 6879.337027607289, 6927.238003819475, 6930.757423490154, 5717.542596592346, 6554.442872355903, 6563.957145567289, 6602.801915942529, 6620.404163625297, 6605.471562275103, 6693.8326541834895, 6776.559523630948, 6794.626295553792, 6799.898001529882, 6334.144890439732, 6548.8362790930605, 6553.5084868635995, 6565.737370505126, 6568.1789776277965, 6372.968135159294, 6427.967860160669, 6479.611223326616, 6491.5131365148745, 6506.154199953976, 4923.556879880828, 6162.537809664203, 6349.206349206349, 6374.840628984312, 6405.551611387158, 6266.874662506815, 6337.683115844282, 6339.270983836917, 6345.333739957423, 6489.994050922114, 5449.7275136243825, 6346.746130154742, 6359.554831161774, 6383.36166383358, 6413.390727880844], "value": 6069.430687472318, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [745.716627681481, 772.8638900815803, 808.9595520223987, 1071.807074726549, 1262.7916393795024, 37649.81175094124, 38484.615153848456, 38538.61461385385, 38651.613483865156, 38683.22633547331, 38637.647682331124, 38925.8053709731, 39021.0, 39065.21869562607, 39101.41347879777, 7715.6913723451, 8195.836083278331, 8420.368472364567, 8434.0, 8450.704225352114, 8238.423310368276, 8308.626111824982, 8334.333253339719, 8335.624896879624, 8343.45767525112, 8221.630026648785, 8231.917680823204, 8267.2972797312, 8305.66777328906, 8336.833263334729, 7091.468139889503, 8308.833823323499, 8314.625841837102, 8320.001599808002, 8328.66685332589, 8145.551994640293, 8185.8362832743605, 8207.0, 8259.669613215525, 8264.83470330596, 36876.26247475062, 37047.814760926296, 37079.62920370789, 37181.442278365845, 37202.81398593017, 3486.4595987621706, 3522.982385088058, 3529.8058606776617, 3531.134871956387, 3541.008517615072, 29683.257918551993, 29736.55395169074, 29879.701202987908, 29886.85056574725, 29945.550816737767, 2956.364381657954, 2976.4642364374404, 2995.6105706258263, 3001.4597372472945, 3003.2191630176094, 241129.0, 244741.0, 253052.734736327, 296082.0, 296547.0, 47294.76352618284, 47575.28637070447, 47590.28614570784, 47628.523714763105, 47656.5234347659, 49261.261081083816, 49473.25790113151, 49680.75159624251, 49685.0062998738, 49759.502404976214, 14109.647258818406, 14254.928725356307, 14265.286735663383, 14276.928615356857, 14285.142891426547, 11524.769504609862, 11532.36571988532, 11537.596184133516, 11539.942300288445, 11549.0, 13191.47234110644, 13272.0, 13354.998375121904, 13361.866381336256, 13493.0, 4998.825041123543, 6024.789132380346, 6072.696365181723, 6114.6942652868065, 6139.662318572521, 972.4602845420859, 998.5356809083665, 1013.2400699475394, 1038.8597539332245, 1060.7772367802693, 22939.541209175724, 23785.453945493835, 23849.0, 23871.403214919417, 24162.91266892994, 1111.3165403276926, 1115.8326251062242, 1145.9713507162387, 1146.2835727670206, 1155.2259985809528, 11707.531698732126, 12048.578299759467, 12235.449404776806, 12236.265824050586, 12369.897117490049, 320895.0, 323152.0, 324151.0, 324453.0, 326634.10048849287, 51899.48100519022, 52321.7383913078, 52430.0, 52446.47553524416, 52571.0, 1459.6569806095558, 1488.6724920517252, 1521.117751704009, 1523.3601887207178, 1534.647031182834, 19843.305484308556, 20645.967701615158, 23258.255630827418, 23385.532289353778, 23825.04699811988, 37538.81230593939, 46606.0, 48738.75630621966, 49199.754001231195, 49528.75235623799, 246051.0, 248398.0, 248808.0, 249003.0, 249197.0, 26736.866315668296, 32679.836600816845, 33083.669163308055, 33329.00012999614, 33381.83309083439, 38926.4161037579, 42350.788246058575, 42837.0, 44433.555664442945, 44621.55378446174, 25996.480070398866, 30051.34717590466, 30387.2403189922, 30468.542971855884, 30813.845930770203, 23051.0, 27435.86282068657, 30450.086497405115, 30692.232694182825, 31068.533971989986, 37077.443838341904, 37168.44247336346, 37249.627503724616, 37337.81331093436, 37347.81326093461, 29411.11766647004, 30102.548461772654, 30231.69768302289, 30835.84582077075, 30900.69099309068, 20491.430683696737, 22468.0, 24342.878285608458, 24525.264242072768, 24677.753222467545], "value": 17230.46362017244, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [403.00256864260956, 414.5253684531212, 418.7215501691375, 422.76536522230157, 439.4836067620545, 18755.0, 20728.792712072875, 21135.577288454242, 21403.03686334115, 21612.783872161275, 21525.784742152573, 22018.44953876154, 22124.55750884981, 22746.431339216528, 23031.884840575814, 5714.342850572186, 5948.167256584082, 5964.701764911759, 5973.253343332083, 5980.0, 5662.17898404731, 5846.912296315559, 5849.700470154433, 5860.560457965648, 5884.852878678046, 5830.3586605473365, 5843.240378750777, 5845.824625261228, 5849.912251316234, 5853.004989151833, 5720.713964301789, 5817.7091145442555, 5959.642421454727, 5969.432903874125, 6001.729922153513, 5175.171972484384, 5269.499397557226, 5302.072137375943, 5324.866878328034, 5331.306930099101, 27424.725752742415, 27782.444351112863, 28016.439671206463, 28300.71699283022, 28309.292267693265, 2057.372501387076, 2058.2487392101925, 2161.2867753641417, 2183.7379514458366, 2209.9116035358566, 15096.0, 17256.050917199547, 17588.91205543964, 17822.821771782375, 18327.0, 2141.764405915348, 2156.6657168138963, 2157.244964262511, 2182.4434769133927, 2183.617866873307, 147576.0, 155679.22160389126, 160383.59424608524, 161705.3829461714, 163224.0, 9786.804263914682, 11614.0, 11910.821337679941, 12041.277523348626, 12124.0, 14775.261236938326, 14927.552173434815, 14941.477048303257, 14961.401543938338, 14995.400183992519, 6931.7227310907, 8722.258608018263, 8754.124587541195, 8829.16122968317, 8876.689315873913, 8382.203690649378, 8431.578421079044, 8480.024797148284, 8614.353923455767, 8688.91311086881, 7536.472446928662, 7638.618069096523, 7646.847063058819, 7652.693892244248, 7679.157002319404, 2150.8924553772563, 2169.3817262080233, 2171.7936796004356, 2175.1408193763373, 2303.986246051732, 857.4340934983006, 926.4672813132476, 973.3284034016506, 981.6073570571829, 1013.6401577439902, 20263.392098237076, 20545.280915168198, 20669.0, 20740.962951852347, 20930.127926333676, 987.1855719031828, 993.4436715439233, 1012.2307046644603, 1023.0945613132283, 1032.1381646325403, 10286.639967601248, 10531.52608132636, 10585.9470702646, 10620.468976551141, 10654.414007229521, 514571.0, 524034.0, 524456.0, 528717.3564132232, 531422.0, 116349.09127271886, 128608.35695821648, 130295.34852325867, 131662.0, 133714.66285337217, 1093.4969913839723, 1172.6364826903687, 1207.5047486141746, 1255.171586752739, 1257.3147634539016, 18349.990750508317, 21222.151113955268, 21626.0, 21645.917704115047, 21760.89119554392, 55949.0, 58415.0, 59027.81944361003, 59353.40646593652, 59671.70164149151, 56074.87850243054, 79842.0, 80344.0, 80860.0, 81355.59322203351, 8462.449940753831, 8652.961644602688, 8845.955770221108, 8878.822423551623, 8920.420172688755, 12936.870631293943, 13432.328383580587, 13640.454381825013, 14240.786388203978, 14348.426062957366, 10278.743908394279, 12068.517259309529, 12096.637100886988, 12123.393830308625, 12148.453319600816, 9978.900210997797, 11299.830502542633, 11505.482253298454, 11696.47365868555, 12078.36942012817, 11154.498047587716, 11510.194286400207, 12286.87713122893, 12361.0, 12442.75114497723, 8441.071482137082, 8737.432066915884, 8952.343816393988, 9010.414323068979, 9056.230220431336, 11011.0, 11412.31526108436, 11760.82358764602, 12075.51697932073, 12181.330026848254], "value": 10280.094615215916, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1402.8807551358134, 1460.4377314733824, 1461.9415223391065, 1467.9192644404561, 1472.7938088667588, 11066.0, 11526.193166478339, 11526.711832204179, 11541.769164616713, 11930.0, 11493.195476316661, 11514.884851151486, 11679.124065695065, 11698.883011169886, 11709.297442153476, 2503.624456331556, 2549.852566345143, 2551.706553746315, 2553.9106131285407, 2566.2942690760005, 2418.1899063813644, 2449.448874003353, 2470.7405722399158, 2476.2199907029317, 2477.9876100619563, 2508.8871000804916, 2522.6594409754725, 2553.221267513408, 2560.0527804712283, 2560.219133164384, 2472.0, 2495.238952119612, 2525.873706314677, 2533.1513942829174, 2533.316004678745, 2426.5146970605774, 2511.234073607567, 2518.0809004713146, 2522.0, 2538.352720056392, 11179.0, 11294.265872718248, 11302.265352752045, 11307.321560706385, 11314.094872410187, 5334.27987221724, 6541.476681865487, 6558.540902136852, 6560.606363618151, 6573.1783527059115, 3746.83139258734, 4006.859759908389, 4061.3298805697023, 4074.3073677474604, 4123.484564429446, 1199.8800119988018, 1202.8616709078392, 1214.7509760499076, 1223.6145614131608, 1250.6310638361706, 15160.0, 15444.92277538605, 15479.380824767108, 15512.767308490382, 15575.610609734847, 5238.554722848525, 5330.440303768126, 5443.0, 5487.0, 5513.8345849624575, 11229.213955023233, 11242.269252498561, 11247.66257012291, 11254.943725281484, 11255.0, 6330.113784070234, 6341.302456729756, 6341.904871426932, 6343.111964324999, 6360.332165122595, 5830.53355731549, 5874.412558744177, 5940.970295148497, 6029.547783916224, 6045.78839740607, 3595.7303202259936, 4098.200850834059, 4212.262854000538, 4220.345846393814, 4224.345226489899, 1239.6157191270736, 1242.9254244745343, 1249.0881656390886, 1259.7732408166435, 1260.155695683894, 662.9668516574152, 766.2835249042164, 792.9801754956172, 803.6785285885693, 805.0942689474341, 6448.322926092693, 6501.1548498694865, 6571.441427478718, 6616.503762217853, 6730.865382692319, 870.1106055929982, 870.5060250530984, 938.9624415023459, 939.9342046056711, 940.4545363689044, 3352.245744707421, 3963.960360396417, 4326.329418940069, 4564.989779129454, 4605.769711514411, 14013.159210447202, 14021.0, 14213.928930355281, 14259.714805703828, 14265.286735663176, 13703.177809331472, 14009.649758756113, 14028.438862445591, 14170.008099433147, 14180.645483862985, 6482.902756458657, 8154.714584989497, 8359.247667709935, 8364.958175209207, 8387.245147936716, 5884.882302354014, 6640.302768209269, 6815.216250131299, 6845.349691779273, 6921.377076063179, 12126.636200913987, 12535.373231338579, 12615.621531354074, 12820.487180512675, 12846.229226246454, 11131.276467029616, 12229.69425764363, 12482.251064936132, 12514.812277816021, 12919.935400322938, 8791.0, 8977.865332019894, 9107.316951228551, 9169.587368568564, 9227.400218985744, 10562.366258024236, 10654.0, 10757.731056723644, 10784.0, 10787.784244315228, 8440.029396619222, 8756.474611523328, 8823.205911467998, 8883.600237989434, 8977.057408972225, 8547.358948078794, 8689.435186713097, 8720.520371379635, 8848.115188481357, 8988.820223595621, 6777.1189745332795, 7939.0, 8077.717279895175, 8103.351731861555, 8107.270345668682, 5295.232191332312, 5429.864253393696, 5808.273965754281, 5824.708764561839, 6017.007193813147, 5930.851728706644, 5957.16599676037, 6079.3008803988105, 6101.572889897665, 6259.655718935503], "value": 5233.358387072228, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [328.0045063233087, 342.41788958770087, 377.1438833847167, 402.58131543195094, 405.98985025374344, 6132.754689812402, 6212.1924149860515, 6266.373362663737, 6285.8628066457395, 6355.269144048436, 6031.24609423822, 6131.54013448992, 6144.3855614438535, 6174.8147555573405, 6175.413335733109, 3515.8242087895633, 3887.300285948528, 4401.767505098577, 4485.0, 4488.630967554895, 4026.7986600669997, 4423.0711550574415, 4435.981628359105, 4448.666350023745, 4487.259602165655, 3460.82695865207, 3849.441830934513, 4436.866893993185, 4457.84397546086, 4518.774061296922, 4456.3538286948215, 4459.977700111495, 4465.464144302705, 4479.574440428154, 4494.123645889053, 3175.491921292582, 3765.359888818907, 3799.4680744695765, 3844.3272427325105, 3845.807709614536, 4970.602351811883, 5280.339957505312, 5724.9427505724825, 5980.641161530279, 5984.401559844025, 4554.838516178386, 5140.614453915934, 5330.227117068042, 5342.492463216027, 5353.00969320673, 6374.235091789015, 6512.869742605121, 6570.342965703439, 6624.73501059957, 6654.334566543307, 3446.8965931022108, 4316.913661726748, 4326.978365108186, 4334.609885110323, 4344.218040752663, 6145.907811382833, 6308.116863639094, 6880.759173428956, 6954.930450695479, 7034.824129396755, 4250.42619246404, 4368.847090351886, 4376.12477504494, 4376.321670141069, 4380.759058251827, 4443.511213766483, 4451.599356057905, 4453.22068637987, 4458.398116254329, 4480.059187570647, 5134.409542902614, 5255.264263003182, 5272.420033796279, 5291.497307755757, 5338.199270109515, 6248.843778905472, 6510.3489651034515, 6573.704183311666, 6593.011048342787, 6608.768693095814, 1489.8212214534326, 1511.1235483419591, 1516.257034053328, 1519.2631573686754, 1520.9847901521064, 835.2983493865182, 927.8283517549276, 983.5180761426849, 991.5339790298548, 994.3884511025667, 5184.351956005499, 6256.499480041586, 6266.342034086447, 6278.560500764994, 6279.748810047547, 5959.880802383928, 5973.850653733604, 6805.149356330458, 6815.693293801791, 6821.386075253152, 5164.14791559396, 5953.523718102628, 6052.0, 6199.442050215503, 6267.6866156692895, 2987.641483022008, 2995.7004299570303, 3257.8533965971587, 3471.7222622190166, 3576.213233088716, 5529.557635389159, 5790.681512516852, 5994.310654274815, 6960.477964152708, 7071.222165561784, 5985.581009329392, 7046.577205367695, 7050.875237985733, 7067.116610423697, 7090.192001039805, 6189.978653522118, 6380.840478988062, 6546.967265163644, 6574.539782215199, 6605.438537724252, 6321.652309122858, 6739.932600674127, 6862.4853136016, 6879.0, 6900.309969003059, 5696.829095127153, 6708.597484150967, 6766.93233067663, 6782.762603308861, 6789.422899053636, 6957.199922008831, 7066.646667666699, 7074.327938845801, 7111.608861512666, 7140.821479463055, 4320.848770293024, 4331.45856767904, 4360.738355698668, 4386.210482113251, 4387.780610969503, 6864.0, 6874.6562671867205, 6875.518713689994, 6883.0, 7055.082839230867, 5367.865803354947, 5607.691576963143, 5755.395683453177, 6260.0, 6518.5111116667385, 5512.5589952804485, 6172.351903050115, 6347.460465860454, 6442.967785161044, 6623.768168114093, 6178.258608966953, 6314.284857511314, 6394.176429876623, 6475.320091390525, 6490.448311893542, 6557.344265573595, 6632.602043877383, 6657.367550082734, 6674.899876501953, 6689.632070235989, 5398.811041613525, 5439.939211853809, 5504.91742623869, 6432.163818703539, 6482.189726284215], "value": 4699.924822800001, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [764.4648745877885, 824.3322908444164, 842.0400743152807, 861.8580380995182, 865.3120768988651, 13972.441102355906, 16344.427945021927, 16692.41576544821, 18567.350142745006, 19146.904265478654, 17708.734368984442, 18090.185941632626, 18267.177976991035, 18319.0, 18492.982885941266, 960.6109525642108, 974.2060220919963, 978.5449765858874, 988.5946761827652, 991.3952488981715, 987.5704068730089, 1005.1857995024018, 1009.5709323537504, 1012.8835183953865, 1014.2899970020978, 1043.118564812732, 1049.7270709615516, 1053.3469249065552, 1055.8046761349137, 1061.5170097605571, 1039.953202105907, 1043.2644985285356, 1050.1808589300358, 1051.337657275919, 1051.948051948052, 747.3572727454385, 750.0362034785329, 752.8833030880222, 761.3794757272805, 769.1731388757053, 16896.915515422468, 16900.492985210465, 17011.234494447657, 17055.48833534996, 17077.060761658104, 16610.418635347825, 16668.166591670488, 16979.235934382858, 17071.061091639956, 17074.97550146995, 12533.0, 14093.85906140946, 14357.210353430557, 14390.352434140488, 14396.136231826124, 3461.6884480396634, 3481.599616044162, 3543.5216245807, 3548.3790336690977, 3549.1836877518317, 113406.29890551648, 114271.1432214193, 117691.0, 128207.0, 129849.0, 13296.534621288209, 13543.119697219649, 13605.183688978694, 13632.659183520293, 13706.24615646149, 23686.407839804142, 24139.39651508691, 24198.879005605213, 24546.404483708502, 24694.13570525023, 7593.0, 7869.213078692085, 7869.2130786922, 7872.842543149105, 7872.960635196902, 3830.731848770615, 3876.4185372194397, 3909.3940439231965, 3915.960840391617, 3916.412538119248, 3985.322495175827, 4148.979255103705, 4168.812403441852, 4169.728967617095, 4185.790710464465, 796.9760907172794, 915.7115508614766, 942.2979879989451, 951.2675240065112, 955.8279509688323, 646.2923099206362, 667.813012356541, 676.1075380497696, 676.9255381907986, 692.2489099327287, 10191.184705223712, 11146.718127415452, 11942.686304506497, 12007.519699211934, 12037.87962120385, 660.9405153536205, 662.3178126529682, 668.866226754651, 671.5064427645676, 674.5817593092314, 5021.698698078127, 5418.187271909245, 5511.751971161308, 5518.144687573352, 5683.403242659544, 90919.09080909239, 100655.49672251592, 102037.48981255046, 102191.48904255431, 102336.46495302576, 78104.60947695225, 86144.56927715447, 87589.0, 88291.11708882957, 88445.0, 68193.0, 80542.19457805464, 80692.59653701694, 81463.96340091545, 81522.0, 10515.316504427188, 11923.701907452036, 12579.308138052495, 12626.873731262569, 12730.427130779322, 35266.413011414035, 36672.99999999893, 38864.6113538861, 39911.2017759649, 41235.587644124374, 51840.92636294506, 52303.73848130735, 73339.26660733538, 74279.0, 75588.0, 15570.84429155694, 15778.68442631164, 16091.195440228175, 16105.516834494983, 16637.584060398585, 16645.41741039058, 16992.479703177753, 17247.7412838805, 17380.0, 17501.47495575135, 15892.68214635724, 16416.0, 16764.32942682328, 17402.47792566225, 17507.0, 15727.370905163665, 16425.917870410573, 16579.419720310212, 16597.58506037359, 16740.665186696442, 15831.604209894846, 16252.918735406247, 16799.664006720042, 16926.0, 17070.48788536346, 10066.798664026532, 10502.579896804347, 10578.836328004067, 11109.00018998294, 12098.516059357878, 12868.678283042998, 13503.39234734459, 13637.0, 13653.180809151483, 13770.311484425538], "value": 8334.934787133876, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1533.3999999999999, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [103.0, 131.0, 98.0, 106.0, 137.0, 172.0, 145.0, 132.0, 171.0, 136.0], "value": 133.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 112.0, 113.0, 147.0, 114.0, 114.0, 109.0, 93.0, 158.0, 145.0], "value": 125.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [201.0, 196.0, 204.0, 205.0, 209.0, 211.0, 200.0, 199.0, 199.0, 197.0], "value": 202.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 49.0, 59.0, 94.0, 89.0, 84.0, 58.0, 58.0, 80.0, 58.0], "value": 71.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 70.0, 83.0, 70.0, 74.0, 71.0, 86.0, 71.0, 71.0, 89.0], "value": 77.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 101.0, 108.0, 91.0, 90.0, 90.0, 90.0, 99.0, 136.0, 99.0], "value": 99.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 97.0, 97.0, 98.0, 99.0, 106.0, 119.0, 97.0, 104.0, 109.0], "value": 102.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 97.0, 91.0, 93.0, 95.0, 90.0, 116.0, 86.0, 143.0, 90.0], "value": 99.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [50.0, 49.0, 48.0, 41.0, 45.0, 71.0, 82.0, 81.0, 67.0, 50.0], "value": 58.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 59.0, 54.0, 72.0, 63.0, 77.0, 82.0, 57.0, 55.0, 57.0], "value": 63.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 84.0, 84.0, 85.0, 83.0, 84.0, 85.0, 84.0, 83.0, 85.0], "value": 84.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 166.0, 136.0, 138.0, 131.0, 150.0, 157.0, 154.0, 221.0, 152.0], "value": 155.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [187.0, 184.0, 183.0, 186.0, 187.0, 210.0, 231.0, 187.0, 180.0, 183.0], "value": 191.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 70.0, 68.0, 70.0, 69.0, 69.0, 70.0, 72.0, 68.0, 73.0], "value": 70.1, "unit": "score"}]}]} 22:51:28 INFO - Return code: 0 22:51:28 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 22:51:28 INFO - # TBPL SUCCESS # 22:51:28 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 22:51:28 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 22:51:28 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 22:51:28 INFO - 2017-05-21 22:51:28.476 screenresolution[1659:10908] starting screenresolution argv=screenresolution get 22:51:28 INFO - 2017-05-21 22:51:28.479 screenresolution[1659:10908] Display 0: 1600x1200x32@60 22:51:28 INFO - 2017-05-21 22:51:28.485 screenresolution[1660:10911] starting screenresolution argv=screenresolution list 22:51:28 INFO - Available Modes on Display 0 22:51:28 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 22:51:28 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 22:51:28 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 22:51:28 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 22:51:28 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 22:51:28 INFO - Intel Iris: 22:51:28 INFO - Chipset Model: Intel Iris 22:51:28 INFO - Type: GPU 22:51:28 INFO - Bus: Built-In 22:51:28 INFO - VRAM (Dynamic, Max): 1536 MB 22:51:28 INFO - Vendor: Intel (0x8086) 22:51:28 INFO - Device ID: 0x0a2e 22:51:28 INFO - Revision ID: 0x0009 22:51:28 INFO - Displays: 22:51:28 INFO - Display: 22:51:28 INFO - Resolution: 1600 x 1200 @ 60 Hz 22:51:28 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 22:51:28 INFO - Display Serial Number: 1600x1200 60 22:51:28 INFO - Main Display: Yes 22:51:28 INFO - Mirror: Off 22:51:28 INFO - Online: Yes 22:51:28 INFO - Rotation: Supported 22:51:28 INFO - Return code: 0 22:51:28 INFO - Running post-action listener: _resource_record_post_action 22:51:28 INFO - [mozharness: 2017-05-22 05:51:28.722963Z] Finished run-tests step (success) 22:51:28 INFO - Running post-run listener: _resource_record_post_run 22:51:28 INFO - Total resource usage - Wall time: 1196s; CPU: 54.0%; Read bytes: 45546496; Write bytes: 567718912; Read time: 490; Write time: 1725 22:51:28 INFO - TinderboxPrint: CPU usage
54.4% 22:51:28 INFO - TinderboxPrint: I/O read bytes / time
45,546,496 / 490 22:51:28 INFO - TinderboxPrint: I/O write bytes / time
567,718,912 / 1,725 22:51:28 INFO - TinderboxPrint: CPU idle
2,181.0 (45.6%) 22:51:28 INFO - TinderboxPrint: CPU system
970.9 (20.3%) 22:51:28 INFO - TinderboxPrint: CPU user
1,633.8 (34.1%) 22:51:28 INFO - TinderboxPrint: Swap in / out
285,777,920 / 0 22:51:28 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 246295040; Write bytes: 249772032; Read time: 18360; Write time: 512 22:51:28 INFO - run-tests - Wall time: 1175s; CPU: 54.0%; Read bytes: 40188928; Write bytes: 316513280; Read time: 452; Write time: 1204 22:51:29 INFO - Running post-run listener: _upload_blobber_files 22:51:29 INFO - Blob upload gear active. 22:51:29 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 22:51:29 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 22:51:29 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', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 22:51:29 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 Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 22:51:29 INFO - (blobuploader) - INFO - Open directory for files ... 22:51:29 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 22:51:29 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:51:29 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:51:30 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 22:51:30 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:51:30 INFO - (blobuploader) - INFO - Done attempting. 22:51:30 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 22:51:30 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:51:30 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:51:31 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 22:51:31 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:51:31 INFO - (blobuploader) - INFO - Done attempting. 22:51:31 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 22:51:31 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:51:31 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:51:32 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 22:51:32 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:51:32 INFO - (blobuploader) - INFO - Done attempting. 22:51:32 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 22:51:32 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:51:32 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:51:34 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 22:51:34 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:51:34 INFO - (blobuploader) - INFO - Done attempting. 22:51:34 INFO - (blobuploader) - INFO - Iteration through files over. 22:51:34 INFO - Return code: 0 22:51:34 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 22:51:34 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 22:51:34 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d535b607f43679c187422621a330d4b6073e800ba56de1079b0c01cbd6b943df503c0dd3de4e136ab87d52131cd10e331e8a21cd5942cc331e877aac00a53770", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8e5b01d64bd4468fa102a6d73157fc40b3f90c039dde34c5bef83f97bf0576b1aca2e537082e49904bbfe8c051f10c01a848b5e8135a6fe19def26462ea76443", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5b46902410d9a5f2ba28eb40bc683ed890b0e5576dcbdc0ef4922b701fe40282a251a4c76c02ee37b959f6bb48731878b5e2ae077a740ced87be302a68d3eb8f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1049b8b6a2be8d76eacf8325dfbfd8fba7e5b1dddff11e08e53522ae6c64b78f6aa0c64c53bda66c708f03b33d87e2b3ca2e15fe0123a1873d0b07757e2cf254"} 22:51:34 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 22:51:34 INFO - Writing to file /builds/slave/test/properties/blobber_files 22:51:34 INFO - Contents: 22:51:34 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d535b607f43679c187422621a330d4b6073e800ba56de1079b0c01cbd6b943df503c0dd3de4e136ab87d52131cd10e331e8a21cd5942cc331e877aac00a53770", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8e5b01d64bd4468fa102a6d73157fc40b3f90c039dde34c5bef83f97bf0576b1aca2e537082e49904bbfe8c051f10c01a848b5e8135a6fe19def26462ea76443", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5b46902410d9a5f2ba28eb40bc683ed890b0e5576dcbdc0ef4922b701fe40282a251a4c76c02ee37b959f6bb48731878b5e2ae077a740ced87be302a68d3eb8f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1049b8b6a2be8d76eacf8325dfbfd8fba7e5b1dddff11e08e53522ae6c64b78f6aa0c64c53bda66c708f03b33d87e2b3ca2e15fe0123a1873d0b07757e2cf254"} 22:51:34 INFO - Running post-run listener: copy_logs_to_upload_dir 22:51:34 INFO - Copying logs to upload dir... 22:51:34 INFO - mkdir: /builds/slave/test/build/upload/logs 22:51:34 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1278.322807 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 18 secs) (at 2017-05-21 22:51:34.887924) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-21 22:51:34.891210) ========= 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.rNRNCuS2dh/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.sLaJ1JWk69/Listeners TMPDIR=/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/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/Firefox/sha512/d535b607f43679c187422621a330d4b6073e800ba56de1079b0c01cbd6b943df503c0dd3de4e136ab87d52131cd10e331e8a21cd5942cc331e877aac00a53770", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8e5b01d64bd4468fa102a6d73157fc40b3f90c039dde34c5bef83f97bf0576b1aca2e537082e49904bbfe8c051f10c01a848b5e8135a6fe19def26462ea76443", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5b46902410d9a5f2ba28eb40bc683ed890b0e5576dcbdc0ef4922b701fe40282a251a4c76c02ee37b959f6bb48731878b5e2ae077a740ced87be302a68d3eb8f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1049b8b6a2be8d76eacf8325dfbfd8fba7e5b1dddff11e08e53522ae6c64b78f6aa0c64c53bda66c708f03b33d87e2b3ca2e15fe0123a1873d0b07757e2cf254"} build_url:https://queue.taskcluster.net/v1/task/XONplRHCRcu_7ZVZsv4DnQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010798 build_url: 'https://queue.taskcluster.net/v1/task/XONplRHCRcu_7ZVZsv4DnQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d535b607f43679c187422621a330d4b6073e800ba56de1079b0c01cbd6b943df503c0dd3de4e136ab87d52131cd10e331e8a21cd5942cc331e877aac00a53770", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8e5b01d64bd4468fa102a6d73157fc40b3f90c039dde34c5bef83f97bf0576b1aca2e537082e49904bbfe8c051f10c01a848b5e8135a6fe19def26462ea76443", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5b46902410d9a5f2ba28eb40bc683ed890b0e5576dcbdc0ef4922b701fe40282a251a4c76c02ee37b959f6bb48731878b5e2ae077a740ced87be302a68d3eb8f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1049b8b6a2be8d76eacf8325dfbfd8fba7e5b1dddff11e08e53522ae6c64b78f6aa0c64c53bda66c708f03b33d87e2b3ca2e15fe0123a1873d0b07757e2cf254"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-21 22:51:34.926357) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-21 22:51:34.926691) ========= 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.rNRNCuS2dh/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.sLaJ1JWk69/Listeners TMPDIR=/var/folders/l9/8sm1y5b96px5fvkn2jwjp59h00000w/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.005117 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-21 22:51:34.977290) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-21 22:51:34.977955) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-21 22:51:34.978548) ========= ========= Total master_lag: 0.18 =========