builder: cedar_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0186 starttime: 1496203188.2 results: success (0) revision: 9317f9df5c2adeebbf9bdf307136c9037f869c8f ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-05-30 20:59:48.205114) ========= master: http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-05-30 20:59:48.205555) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-30 20:59:48.205827) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-30 20:59:48.231252) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-30 20:59:48.231628) ========= 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.bwdBITHyJB/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.tB1KVNSeg3/Listeners TMPDIR=/var/folders/np/gxqtlmy15cvfysz9zj9x958r00000w/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-30 20:59:48-- 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% 39.1M=0s 2017-05-30 20:59:48 (39.1 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.157621 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-30 20:59:48.409953) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-30 20:59:48.410281) ========= 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.bwdBITHyJB/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.tB1KVNSeg3/Listeners TMPDIR=/var/folders/np/gxqtlmy15cvfysz9zj9x958r00000w/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.028945 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-30 20:59:48.463303) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-30 20:59:48.463648) ========= bash -c 'python archiver_client.py mozharness --repo projects/cedar --rev 9317f9df5c2adeebbf9bdf307136c9037f869c8f --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'python archiver_client.py mozharness --repo projects/cedar --rev 9317f9df5c2adeebbf9bdf307136c9037f869c8f --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.bwdBITHyJB/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.tB1KVNSeg3/Listeners TMPDIR=/var/folders/np/gxqtlmy15cvfysz9zj9x958r00000w/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-30 20:59:48,518 truncating revision to first 12 chars 2017-05-30 20:59:48,518 Setting DEBUG logging. 2017-05-30 20:59:48,518 attempt 1/10 2017-05-30 20:59:48,518 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/projects/cedar/9317f9df5c2a?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-05-30 20:59:49,535 unpacking tar archive at: cedar-9317f9df5c2a/testing/mozharness/ program finished with exit code 0 elapsedTime=1.278839 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-30 20:59:49.764193) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-30 20:59:49.764517) ========= script_repo_revision: 9317f9df5c2adeebbf9bdf307136c9037f869c8f ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-30 20:59:49.764879) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-30 20:59:49.765157) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-30 20:59:49.784507) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 19 secs) (at 2017-05-30 20:59:49.784930) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Cedar --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Cedar in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Cedar', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Cedar'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.bwdBITHyJB/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.tB1KVNSeg3/Listeners TMPDIR=/var/folders/np/gxqtlmy15cvfysz9zj9x958r00000w/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 20:59:49 INFO - MultiFileLogger online at 20170530 20:59:49 in /builds/slave/test 20:59:49 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Cedar --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Cedar 20:59:49 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 20:59:49 INFO - {'append_to_log': False, 20:59:49 INFO - 'base_work_dir': '/builds/slave/test', 20:59:49 INFO - 'blob_upload_branch': 'Cedar', 20:59:49 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 20:59:49 INFO - 'branch': 'Cedar', 20:59:49 INFO - 'buildbot_json_path': 'buildprops.json', 20:59:49 INFO - 'config_files': ('talos/mac_config.py',), 20:59:49 INFO - 'default_actions': ('clobber', 20:59:49 INFO - 'read-buildbot-config', 20:59:49 INFO - 'download-and-extract', 20:59:49 INFO - 'populate-webroot', 20:59:49 INFO - 'create-virtualenv', 20:59:49 INFO - 'install', 20:59:49 INFO - 'setup-mitmproxy', 20:59:49 INFO - 'run-tests'), 20:59:49 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 20:59:49 INFO - 'download_minidump_stackwalk': True, 20:59:49 INFO - 'download_symbols': 'ondemand', 20:59:49 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 20:59:49 INFO - 'tooltool.py': '/tools/tooltool.py', 20:59:49 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 20:59:49 INFO - '/tools/misc-python/virtualenv.py')}, 20:59:49 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 20:59:49 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 20:59:49 INFO - 'gecko_profile': False, 20:59:49 INFO - 'gecko_profile_interval': 0, 20:59:49 INFO - 'installer_path': 'installer.exe', 20:59:49 INFO - 'log_level': 'info', 20:59:49 INFO - 'log_name': 'talos', 20:59:49 INFO - 'log_to_console': True, 20:59:49 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 20:59:49 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 20:59:49 INFO - 'opt_config_files': (), 20:59:49 INFO - 'pip_index': False, 20:59:49 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 20:59:49 INFO - 'cmd': ('bash', 20:59:49 INFO - '-c', 20:59:49 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 20:59:49 INFO - 'enabled': True, 20:59:49 INFO - 'halt_on_failure': False, 20:59:49 INFO - 'name': 'check_screen_resolution'},), 20:59:49 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 20:59:49 INFO - 'cmd': ('bash', 20:59:49 INFO - '-c', 20:59:49 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 20:59:49 INFO - 'enabled': True, 20:59:49 INFO - 'halt_on_failure': False, 20:59:49 INFO - 'name': 'check_screen_resolution'},), 20:59:49 INFO - 'run_cmd_checks_enabled': True, 20:59:49 INFO - 'suite': 'dromaeojs-e10s', 20:59:49 INFO - 'system_bits': '32', 20:59:49 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 20:59:49 INFO - 'title': 't-yosemite-r7-0186', 20:59:49 INFO - 'tooltool_cache': '/builds/tooltool_cache', 20:59:49 INFO - 'use_talos_json': True, 20:59:49 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 20:59:49 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 20:59:49 INFO - 'work_dir': 'build'} 20:59:49 INFO - [mozharness: 2017-05-31 03:59:49.955158Z] Running clobber step. 20:59:49 INFO - Running pre-action listener: _resource_record_pre_action 20:59:49 INFO - Running main action method: clobber 20:59:49 INFO - rmtree: /builds/slave/test/build 20:59:49 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 20:59:52 INFO - Running post-action listener: _resource_record_post_action 20:59:52 INFO - [mozharness: 2017-05-31 03:59:52.523269Z] Finished clobber step (success) 20:59:52 INFO - [mozharness: 2017-05-31 03:59:52.523398Z] Running read-buildbot-config step. 20:59:52 INFO - Running pre-action listener: _resource_record_pre_action 20:59:52 INFO - Running main action method: read_buildbot_config 20:59:52 INFO - Using buildbot properties: 20:59:52 INFO - { 20:59:52 INFO - "project": "", 20:59:52 INFO - "product": "firefox", 20:59:52 INFO - "who": "mconley@mozilla.com", 20:59:52 INFO - "installer_path": "public/build/firefox-55.0a1.en-US.mac.dmg", 20:59:52 INFO - "builddir": "cedar_yosemite_r7_test-dromaeojs-e10s", 20:59:52 INFO - "repository": "https://hg.mozilla.org/projects/cedar/", 20:59:52 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 cedar talos dromaeojs-e10s", 20:59:52 INFO - "stage_platform": "macosx64", 20:59:52 INFO - "basedir": "/builds/slave/test", 20:59:52 INFO - "buildnumber": 0, 20:59:52 INFO - "platform": "yosemite_r7", 20:59:52 INFO - "parent_task_id": "HvkzD0iDSg-C_qCUHhn9Kg", 20:59:52 INFO - "master": "http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/", 20:59:52 INFO - "slavebuilddir": "test", 20:59:52 INFO - "taskId": "QsBMO0zZR7-cocasvtQpfw", 20:59:52 INFO - "branch": "cedar", 20:59:52 INFO - "script_repo_revision": "default", 20:59:52 INFO - "revision": "9317f9df5c2adeebbf9bdf307136c9037f869c8f", 20:59:52 INFO - "slavename": "t-yosemite-r7-0186", 20:59:52 INFO - "repo_path": "projects/cedar" 20:59:52 INFO - } 20:59:52 INFO - Finding installer, test and symbols from parent task. 20:59:52 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/QsBMO0zZR7-cocasvtQpfw'}, attempt #1 20:59:52 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/QsBMO0zZR7-cocasvtQpfw'}, attempt #1 20:59:53 INFO - Task dependencies: PZpW3fniTtyV6bpq9d5CrA HvkzD0iDSg-C_qCUHhn9Kg 20:59:53 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/HvkzD0iDSg-C_qCUHhn9Kg'}, attempt #1 20:59:53 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/HvkzD0iDSg-C_qCUHhn9Kg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 20:59:53 INFO - Set test_url: https://queue.taskcluster.net/v1/task/HvkzD0iDSg-C_qCUHhn9Kg/artifacts/public/build/test_packages.json 20:59:53 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/HvkzD0iDSg-C_qCUHhn9Kg/artifacts/public/build/target.crashreporter-symbols.zip 20:59:53 INFO - Running post-action listener: _resource_record_post_action 20:59:53 INFO - [mozharness: 2017-05-31 03:59:53.889792Z] Finished read-buildbot-config step (success) 20:59:53 INFO - [mozharness: 2017-05-31 03:59:53.889941Z] Running download-and-extract step. 20:59:53 INFO - Running pre-action listener: _resource_record_pre_action 20:59:53 INFO - Running main action method: download_and_extract 20:59:53 INFO - mkdir: /builds/slave/test/build/tests 20:59: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')]} 20:59:53 INFO - trying https://queue.taskcluster.net/v1/task/HvkzD0iDSg-C_qCUHhn9Kg/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 20:59:53 INFO - Downloading https://queue.taskcluster.net/v1/task/HvkzD0iDSg-C_qCUHhn9Kg/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 20:59:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/HvkzD0iDSg-C_qCUHhn9Kg/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 20:59:54 INFO - Downloaded 1325 bytes. 20:59:54 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 20:59:54 INFO - Using the following test package requirements: 20:59:54 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 20:59:54 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 20:59:54 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 20:59:54 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 20:59:54 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 20:59:54 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 20:59:54 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 20:59:54 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 20:59:54 INFO - u'jsshell-mac.zip'], 20:59:54 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 20:59:54 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 20:59:54 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 20:59:54 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 20:59:54 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 20:59:54 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 20:59:54 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 20:59:54 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 20:59:54 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 20:59:54 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 20:59:54 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 20:59:54 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 20:59:54 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/HvkzD0iDSg-C_qCUHhn9Kg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 20:59:54 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/HvkzD0iDSg-C_qCUHhn9Kg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 20:59:54 INFO - Fetch https://queue.taskcluster.net/v1/task/HvkzD0iDSg-C_qCUHhn9Kg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 20:59:56 INFO - Content-Length response header: 57628394 20:59:56 INFO - Bytes received: 57628394 21:00:14 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 21:00:14 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/HvkzD0iDSg-C_qCUHhn9Kg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 21:00:14 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/HvkzD0iDSg-C_qCUHhn9Kg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 21:00:14 INFO - Fetch https://queue.taskcluster.net/v1/task/HvkzD0iDSg-C_qCUHhn9Kg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 21:00:16 INFO - Content-Length response header: 57628394 21:00:16 INFO - Bytes received: 57628394 21:00:33 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/HvkzD0iDSg-C_qCUHhn9Kg/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 21:00:33 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/HvkzD0iDSg-C_qCUHhn9Kg/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 21:00:33 INFO - Fetch https://queue.taskcluster.net/v1/task/HvkzD0iDSg-C_qCUHhn9Kg/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 21:00:34 INFO - Content-Length response header: 14447425 21:00:34 INFO - Bytes received: 14447425 21:00:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:00:35 INFO - trying https://queue.taskcluster.net/v1/task/HvkzD0iDSg-C_qCUHhn9Kg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 21:00:35 INFO - Downloading https://queue.taskcluster.net/v1/task/HvkzD0iDSg-C_qCUHhn9Kg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 21:00:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/HvkzD0iDSg-C_qCUHhn9Kg/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 21:00:36 INFO - Downloaded 67861896 bytes. 21:00:36 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/HvkzD0iDSg-C_qCUHhn9Kg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 21:00:36 INFO - mkdir: /builds/slave/test/properties 21:00:36 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 21:00:36 INFO - Writing to file /builds/slave/test/properties/build_url 21:00:36 INFO - Contents: 21:00:36 INFO - build_url:https://queue.taskcluster.net/v1/task/HvkzD0iDSg-C_qCUHhn9Kg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 21:00:36 INFO - Running post-action listener: _resource_record_post_action 21:00:36 INFO - Running post-action listener: set_extra_try_arguments 21:00:36 INFO - [mozharness: 2017-05-31 04:00:36.987155Z] Finished download-and-extract step (success) 21:00:36 INFO - [mozharness: 2017-05-31 04:00:36.987299Z] Running populate-webroot step. 21:00:36 INFO - Running pre-action listener: _resource_record_pre_action 21:00:36 INFO - Running main action method: populate_webroot 21:00:36 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 21:00:36 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 21:00:36 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 21:00:36 INFO - '--tppagecycles', 21:00:36 INFO - '7'], 21:00:36 INFO - 'tests': ['tsvgm']}}, 21:00:36 INFO - 'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 21:00:36 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 21:00:36 INFO - 'tests': ['dromaeo_css', 'kraken']}, 21:00:36 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 21:00:36 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 21:00:36 INFO - 'talos_options': ['--disable-e10s'], 21:00:36 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 21:00:36 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 21:00:36 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 21:00:36 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 21:00:36 INFO - 'talos_options': ['--disable-e10s'], 21:00:36 INFO - 'tests': ['damp', 'tps']}, 21:00:36 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 21:00:36 INFO - 'tests': ['damp', 'tps']}, 21:00:36 INFO - 'g3': {'talos_options': ['--disable-e10s'], 21:00:36 INFO - 'tests': ['dromaeo_dom']}, 21:00:36 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 21:00:36 INFO - 'g4': {'talos_options': ['--disable-e10s'], 21:00:37 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 21:00:37 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 21:00:37 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 21:00:37 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 21:00:37 INFO - 'other': {'talos_options': ['--disable-e10s'], 21:00:37 INFO - 'tests': ['a11yr', 21:00:37 INFO - 'ts_paint', 21:00:37 INFO - 'tpaint', 21:00:37 INFO - 'sessionrestore', 21:00:37 INFO - 'sessionrestore_no_auto_restore', 21:00:37 INFO - 'tabpaint']}, 21:00:37 INFO - 'other-e10s': {'tests': ['a11yr', 21:00:37 INFO - 'ts_paint', 21:00:37 INFO - 'tpaint', 21:00:37 INFO - 'sessionrestore', 21:00:37 INFO - 'sessionrestore_no_auto_restore', 21:00:37 INFO - 'tabpaint']}, 21:00:37 INFO - 'other-e10s_l64': {'tests': ['a11yr', 21:00:37 INFO - 'ts_paint', 21:00:37 INFO - 'tpaint', 21:00:37 INFO - 'sessionrestore', 21:00:37 INFO - 'sessionrestore_no_auto_restore', 21:00:37 INFO - 'tabpaint']}, 21:00:37 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 21:00:37 INFO - 'ts_paint', 21:00:37 INFO - 'tpaint', 21:00:37 INFO - 'sessionrestore', 21:00:37 INFO - 'sessionrestore_no_auto_restore', 21:00:37 INFO - 'tabpaint']}, 21:00:37 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 21:00:37 INFO - 'tests': ['a11yr', 21:00:37 INFO - 'ts_paint', 21:00:37 INFO - 'tpaint', 21:00:37 INFO - 'sessionrestore', 21:00:37 INFO - 'sessionrestore_no_auto_restore', 21:00:37 INFO - 'tabpaint']}, 21:00:37 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 21:00:37 INFO - 'tests': ['a11yr', 21:00:37 INFO - 'ts_paint', 21:00:37 INFO - 'tpaint', 21:00:37 INFO - 'sessionrestore', 21:00:37 INFO - 'sessionrestore_no_auto_restore', 21:00:37 INFO - 'tabpaint']}, 21:00:37 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 21:00:37 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 21:00:37 INFO - 'bloom_basic_ref']}, 21:00:37 INFO - 'quantum-pageload-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set.zip', 21:00:37 INFO - 'talos_options': ['--mitmproxy', 21:00:37 INFO - 'mitmproxy-recording-1.mp', 21:00:37 INFO - '--firstNonBlankPaint'], 21:00:37 INFO - 'tests': ['Quantum_1']}, 21:00:37 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 21:00:37 INFO - 'tests': ['tsvgx', 21:00:37 INFO - 'tsvgr_opacity', 21:00:37 INFO - 'tart', 21:00:37 INFO - 'tscrollx', 21:00:37 INFO - 'cart', 21:00:37 INFO - 'tsvg_static']}, 21:00:37 INFO - 'svgr-e10s': {'tests': ['tsvgx', 21:00:37 INFO - 'tsvgr_opacity', 21:00:37 INFO - 'tart', 21:00:37 INFO - 'tscrollx', 21:00:37 INFO - 'cart', 21:00:37 INFO - 'tsvg_static']}, 21:00:37 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 21:00:37 INFO - 'talos_options': ['--disable-e10s'], 21:00:37 INFO - 'tests': ['tp5o']}, 21:00:37 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 21:00:37 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 21:00:37 INFO - 'talos_options': ['--disable-e10s', 21:00:37 INFO - '--xperf_path', 21:00:37 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 21:00:37 INFO - 'tests': ['tp5n']}, 21:00:37 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 21:00:37 INFO - 'talos_options': ['--xperf_path', 21:00:37 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 21:00:37 INFO - 'tests': ['tp5n']}}, 21:00:37 INFO - 'talos.zip': {'path': '', 21:00:37 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 21:00:37 INFO - Running post-action listener: _resource_record_post_action 21:00:37 INFO - [mozharness: 2017-05-31 04:00:37.009492Z] Finished populate-webroot step (success) 21:00:37 INFO - [mozharness: 2017-05-31 04:00:37.009632Z] Running create-virtualenv step. 21:00:37 INFO - Running pre-action listener: _resource_record_pre_action 21:00:37 INFO - Running main action method: create_virtualenv 21:00:37 INFO - Creating virtualenv /builds/slave/test/build/venv 21:00:37 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 21:00:37 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 21:00:37 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 21:00:37 INFO - Using real prefix '/tools/python27' 21:00:37 INFO - New python executable in /builds/slave/test/build/venv/bin/python 21:00:37 INFO - Installing distribute.............................................................................................................................................................................................done. 21:00:40 INFO - Installing pip.................done. 21:00:40 INFO - Return code: 0 21:00:40 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 21:00:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 21:00:41 INFO - Reading from file tmpfile_stdout 21:00:41 INFO - Output received: 21:00:41 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) 21:00:41 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 21:00:41 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')]} 21:00:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:00:41 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')]} 21:00:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:00:41 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 0x107638c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10763c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9481579400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1075c1c60>, '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 0x107356cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10737fbe8>, '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.bwdBITHyJB/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.tB1KVNSeg3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/np/gxqtlmy15cvfysz9zj9x958r00000w/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 21:00:41 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 21:00:41 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 21:00:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bwdBITHyJB/Render', 21:00:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:00:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:00:41 INFO - 'HOME': '/Users/cltbld', 21:00:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:00:41 INFO - 'LOGNAME': 'cltbld', 21:00:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:00:41 INFO - 'MOZ_NO_REMOTE': '1', 21:00:41 INFO - 'NO_EM_RESTART': '1', 21:00:41 INFO - 'PAGER': '/bin/cat', 21:00:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:00:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:00:41 INFO - 'PWD': '/builds/slave/test', 21:00:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:00:41 INFO - 'SHELL': '/bin/bash', 21:00:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tB1KVNSeg3/Listeners', 21:00:41 INFO - 'TMPDIR': '/var/folders/np/gxqtlmy15cvfysz9zj9x958r00000w/T/', 21:00:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:00:41 INFO - 'USER': 'cltbld', 21:00:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:00:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:00:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:00:41 INFO - 'XPC_FLAGS': '0x0', 21:00:41 INFO - 'XPC_SERVICE_NAME': '0', 21:00:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:00:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:00:42 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 21:00:42 INFO - Cleaning up... 21:00:42 INFO - Return code: 0 21:00:42 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 21:00:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:00:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:00:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:00:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:00:42 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 0x107638c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10763c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9481579400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1075c1c60>, '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 0x107356cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10737fbe8>, '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.bwdBITHyJB/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.tB1KVNSeg3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/np/gxqtlmy15cvfysz9zj9x958r00000w/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 21:00:42 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 21:00:42 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 21:00:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bwdBITHyJB/Render', 21:00:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:00:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:00:42 INFO - 'HOME': '/Users/cltbld', 21:00:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:00:42 INFO - 'LOGNAME': 'cltbld', 21:00:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:00:42 INFO - 'MOZ_NO_REMOTE': '1', 21:00:42 INFO - 'NO_EM_RESTART': '1', 21:00:42 INFO - 'PAGER': '/bin/cat', 21:00:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:00:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:00:42 INFO - 'PWD': '/builds/slave/test', 21:00:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:00:42 INFO - 'SHELL': '/bin/bash', 21:00:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tB1KVNSeg3/Listeners', 21:00:42 INFO - 'TMPDIR': '/var/folders/np/gxqtlmy15cvfysz9zj9x958r00000w/T/', 21:00:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:00:42 INFO - 'USER': 'cltbld', 21:00:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:00:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:00:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:00:42 INFO - 'XPC_FLAGS': '0x0', 21:00:42 INFO - 'XPC_SERVICE_NAME': '0', 21:00:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:00:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:00:42 INFO - Downloading/unpacking psutil>=3.1.1 21:00:42 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 21:00:42 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 21:00:42 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 21:00:42 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 21:00:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 21:00:45 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 21:00:45 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 21:00:45 INFO - Installing collected packages: psutil 21:00:45 INFO - Running setup.py install for psutil 21:00:45 INFO - building 'psutil._psutil_osx' extension 21:00:45 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 21:00:45 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 21:00:45 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 21:00:45 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 21:00:45 INFO - building 'psutil._psutil_posix' extension 21:00:45 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 21:00:46 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 21:00:46 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 21:00:46 INFO - ^ 21:00:46 INFO - 1 warning generated. 21:00:46 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 21:00:46 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 21:00:46 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 21:00:46 INFO - Successfully installed psutil 21:00:46 INFO - Cleaning up... 21:00:46 INFO - Return code: 0 21:00:46 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 21:00:46 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')]} 21:00:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:00:46 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')]} 21:00:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:00:46 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 0x107638c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10763c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9481579400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1075c1c60>, '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 0x107356cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10737fbe8>, '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.bwdBITHyJB/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.tB1KVNSeg3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/np/gxqtlmy15cvfysz9zj9x958r00000w/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 21:00:46 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 21:00:46 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 21:00:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bwdBITHyJB/Render', 21:00:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:00:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:00:46 INFO - 'HOME': '/Users/cltbld', 21:00:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:00:46 INFO - 'LOGNAME': 'cltbld', 21:00:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:00:46 INFO - 'MOZ_NO_REMOTE': '1', 21:00:46 INFO - 'NO_EM_RESTART': '1', 21:00:46 INFO - 'PAGER': '/bin/cat', 21:00:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:00:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:00:46 INFO - 'PWD': '/builds/slave/test', 21:00:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:00:46 INFO - 'SHELL': '/bin/bash', 21:00:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tB1KVNSeg3/Listeners', 21:00:46 INFO - 'TMPDIR': '/var/folders/np/gxqtlmy15cvfysz9zj9x958r00000w/T/', 21:00:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:00:46 INFO - 'USER': 'cltbld', 21:00:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:00:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:00:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:00:46 INFO - 'XPC_FLAGS': '0x0', 21:00:46 INFO - 'XPC_SERVICE_NAME': '0', 21:00:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:00:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:00:46 INFO - Downloading/unpacking mozsystemmonitor==0.3 21:00:46 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 21:00:46 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 21:00:46 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 21:00:46 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 21:00:47 INFO - Downloading mozsystemmonitor-0.3.tar.gz 21:00:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 21:00:48 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 21:00:48 INFO - Installing collected packages: mozsystemmonitor 21:00:48 INFO - Running setup.py install for mozsystemmonitor 21:00:48 INFO - Successfully installed mozsystemmonitor 21:00:48 INFO - Cleaning up... 21:00:48 INFO - Return code: 0 21:00:48 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 21:00:48 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')]} 21:00:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:00:48 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')]} 21:00:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:00:48 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 0x107638c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10763c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9481579400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1075c1c60>, '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 0x107356cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10737fbe8>, '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.bwdBITHyJB/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.tB1KVNSeg3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/np/gxqtlmy15cvfysz9zj9x958r00000w/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 21:00:48 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 21:00:48 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 21:00:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bwdBITHyJB/Render', 21:00:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:00:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:00:48 INFO - 'HOME': '/Users/cltbld', 21:00:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:00:48 INFO - 'LOGNAME': 'cltbld', 21:00:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:00:48 INFO - 'MOZ_NO_REMOTE': '1', 21:00:48 INFO - 'NO_EM_RESTART': '1', 21:00:48 INFO - 'PAGER': '/bin/cat', 21:00:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:00:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:00:48 INFO - 'PWD': '/builds/slave/test', 21:00:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:00:48 INFO - 'SHELL': '/bin/bash', 21:00:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tB1KVNSeg3/Listeners', 21:00:48 INFO - 'TMPDIR': '/var/folders/np/gxqtlmy15cvfysz9zj9x958r00000w/T/', 21:00:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:00:48 INFO - 'USER': 'cltbld', 21:00:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:00:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:00:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:00:48 INFO - 'XPC_FLAGS': '0x0', 21:00:48 INFO - 'XPC_SERVICE_NAME': '0', 21:00:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:00:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:00:48 INFO - Downloading/unpacking jsonschema==2.5.1 21:00:48 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 21:00:48 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 21:00:48 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 21:00:48 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 21:00:49 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 21:00:49 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 21:00:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:00:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:00:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:00:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:00:50 INFO - Downloading functools32-3.2.3-2.tar.gz 21:00:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 21:00:50 INFO - warning: no files found matching '*.txt' 21:00:50 INFO - no previously-included directories found matching 'build' 21:00:50 INFO - no previously-included directories found matching 'dist' 21:00:50 INFO - no previously-included directories found matching '.git*' 21:00:50 INFO - Installing collected packages: jsonschema, functools32 21:00:50 INFO - Running setup.py install for functools32 21:00:50 INFO - warning: no files found matching '*.txt' 21:00:50 INFO - no previously-included directories found matching 'build' 21:00:50 INFO - no previously-included directories found matching 'dist' 21:00:50 INFO - no previously-included directories found matching '.git*' 21:00:50 INFO - Successfully installed jsonschema functools32 21:00:50 INFO - Cleaning up... 21:00:50 INFO - Return code: 0 21:00:50 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 21:00:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:00:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:00:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:00:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:00:50 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 0x107638c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10763c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9481579400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1075c1c60>, '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 0x107356cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10737fbe8>, '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.bwdBITHyJB/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.tB1KVNSeg3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/np/gxqtlmy15cvfysz9zj9x958r00000w/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 21:00:50 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 21:00:50 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 21:00:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bwdBITHyJB/Render', 21:00:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:00:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:00:50 INFO - 'HOME': '/Users/cltbld', 21:00:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:00:50 INFO - 'LOGNAME': 'cltbld', 21:00:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:00:50 INFO - 'MOZ_NO_REMOTE': '1', 21:00:50 INFO - 'NO_EM_RESTART': '1', 21:00:50 INFO - 'PAGER': '/bin/cat', 21:00:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:00:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:00:50 INFO - 'PWD': '/builds/slave/test', 21:00:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:00:50 INFO - 'SHELL': '/bin/bash', 21:00:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tB1KVNSeg3/Listeners', 21:00:50 INFO - 'TMPDIR': '/var/folders/np/gxqtlmy15cvfysz9zj9x958r00000w/T/', 21:00:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:00:50 INFO - 'USER': 'cltbld', 21:00:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:00:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:00:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:00:50 INFO - 'XPC_FLAGS': '0x0', 21:00:50 INFO - 'XPC_SERVICE_NAME': '0', 21:00:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:00:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:00:50 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 21:00:50 INFO - Cleaning up... 21:00:50 INFO - Return code: 0 21:00:50 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 21:00:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:00:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:00:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:00:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:00:50 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 0x107638c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10763c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9481579400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1075c1c60>, '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 0x107356cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10737fbe8>, '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.bwdBITHyJB/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.tB1KVNSeg3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/np/gxqtlmy15cvfysz9zj9x958r00000w/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 21:00:50 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 21:00:50 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 21:00:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bwdBITHyJB/Render', 21:00:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:00:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:00:50 INFO - 'HOME': '/Users/cltbld', 21:00:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:00:50 INFO - 'LOGNAME': 'cltbld', 21:00:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:00:50 INFO - 'MOZ_NO_REMOTE': '1', 21:00:50 INFO - 'NO_EM_RESTART': '1', 21:00:50 INFO - 'PAGER': '/bin/cat', 21:00:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:00:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:00:50 INFO - 'PWD': '/builds/slave/test', 21:00:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:00:50 INFO - 'SHELL': '/bin/bash', 21:00:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tB1KVNSeg3/Listeners', 21:00:50 INFO - 'TMPDIR': '/var/folders/np/gxqtlmy15cvfysz9zj9x958r00000w/T/', 21:00:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:00:50 INFO - 'USER': 'cltbld', 21:00:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:00:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:00:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:00:50 INFO - 'XPC_FLAGS': '0x0', 21:00:50 INFO - 'XPC_SERVICE_NAME': '0', 21:00:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:00:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:00:50 INFO - Downloading/unpacking blobuploader==1.2.4 21:00:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:00:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:00:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:00:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:00:52 INFO - Downloading blobuploader-1.2.4.tar.gz 21:00:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 21:00:52 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 21:00:52 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 21:00:52 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 21:00:52 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 21:00:52 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 21:00:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 21:00:52 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 21:00:52 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 21:00:52 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 21:00:52 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 21:00:52 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 21:00:53 INFO - Downloading docopt-0.6.1.tar.gz 21:00:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 21:00:53 INFO - Installing collected packages: blobuploader, requests, docopt 21:00:53 INFO - Running setup.py install for blobuploader 21:00:53 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 21:00:53 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 21:00:53 INFO - Running setup.py install for requests 21:00:53 INFO - Running setup.py install for docopt 21:00:54 INFO - Successfully installed blobuploader requests docopt 21:00:54 INFO - Cleaning up... 21:00:54 INFO - Return code: 0 21:00:54 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:00:54 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')]} 21:00:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:00:54 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')]} 21:00:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:00:54 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 0x107638c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10763c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9481579400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1075c1c60>, '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 0x107356cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10737fbe8>, '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.bwdBITHyJB/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.tB1KVNSeg3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/np/gxqtlmy15cvfysz9zj9x958r00000w/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 21:00:54 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 21:00:54 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 21:00:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bwdBITHyJB/Render', 21:00:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:00:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:00:54 INFO - 'HOME': '/Users/cltbld', 21:00:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:00:54 INFO - 'LOGNAME': 'cltbld', 21:00:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:00:54 INFO - 'MOZ_NO_REMOTE': '1', 21:00:54 INFO - 'NO_EM_RESTART': '1', 21:00:54 INFO - 'PAGER': '/bin/cat', 21:00:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:00:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:00:54 INFO - 'PWD': '/builds/slave/test', 21:00:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:00:54 INFO - 'SHELL': '/bin/bash', 21:00:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tB1KVNSeg3/Listeners', 21:00:54 INFO - 'TMPDIR': '/var/folders/np/gxqtlmy15cvfysz9zj9x958r00000w/T/', 21:00:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:00:54 INFO - 'USER': 'cltbld', 21:00:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:00:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:00:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:00:54 INFO - 'XPC_FLAGS': '0x0', 21:00:54 INFO - 'XPC_SERVICE_NAME': '0', 21:00:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:00:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:00:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 21:00:54 INFO - Running setup.py (path:/var/folders/np/gxqtlmy15cvfysz9zj9x958r00000w/T/pip-8GSamq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 21:00:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 21:00:54 INFO - Running setup.py (path:/var/folders/np/gxqtlmy15cvfysz9zj9x958r00000w/T/pip-WCH5Mg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 21:00:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 21:00:54 INFO - Running setup.py (path:/var/folders/np/gxqtlmy15cvfysz9zj9x958r00000w/T/pip-nBC6ep-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 21:00:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 21:00:54 INFO - Running setup.py (path:/var/folders/np/gxqtlmy15cvfysz9zj9x958r00000w/T/pip-ilIM6N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 21:00:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 21:00:54 INFO - Running setup.py (path:/var/folders/np/gxqtlmy15cvfysz9zj9x958r00000w/T/pip-vmKpk7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 21:00:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 21:00:54 INFO - Running setup.py (path:/var/folders/np/gxqtlmy15cvfysz9zj9x958r00000w/T/pip-CRcVRe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 21:00:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 21:00:55 INFO - Running setup.py (path:/var/folders/np/gxqtlmy15cvfysz9zj9x958r00000w/T/pip-anIv6O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 21:00:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 21:00:55 INFO - Running setup.py (path:/var/folders/np/gxqtlmy15cvfysz9zj9x958r00000w/T/pip-2UQ4XY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 21:00:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 21:00:55 INFO - Running setup.py (path:/var/folders/np/gxqtlmy15cvfysz9zj9x958r00000w/T/pip-d5MfQp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 21:00:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 21:00:55 INFO - Running setup.py (path:/var/folders/np/gxqtlmy15cvfysz9zj9x958r00000w/T/pip-eCsT4J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 21:00:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 21:00:55 INFO - Running setup.py (path:/var/folders/np/gxqtlmy15cvfysz9zj9x958r00000w/T/pip-Cgen0U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 21:00:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 21:00:55 INFO - Running setup.py (path:/var/folders/np/gxqtlmy15cvfysz9zj9x958r00000w/T/pip-4XLBFr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 21:00:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 21:00:55 INFO - Running setup.py (path:/var/folders/np/gxqtlmy15cvfysz9zj9x958r00000w/T/pip-9XhyDL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 21:00:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 21:00:55 INFO - Running setup.py (path:/var/folders/np/gxqtlmy15cvfysz9zj9x958r00000w/T/pip-UjZHID-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 21:00:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 21:00:55 INFO - Running setup.py (path:/var/folders/np/gxqtlmy15cvfysz9zj9x958r00000w/T/pip-kxzaoW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 21:00:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 21:00:55 INFO - Running setup.py (path:/var/folders/np/gxqtlmy15cvfysz9zj9x958r00000w/T/pip-_WiB8o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 21:00:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 21:00:56 INFO - Running setup.py (path:/var/folders/np/gxqtlmy15cvfysz9zj9x958r00000w/T/pip-VTvitJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 21:00:56 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 21:00:56 INFO - Running setup.py install for manifestparser 21:00:56 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 21:00:56 INFO - Running setup.py install for mozcrash 21:00:56 INFO - Running setup.py install for mozdebug 21:00:56 INFO - Running setup.py install for mozdevice 21:00:56 INFO - Installing dm script to /builds/slave/test/build/venv/bin 21:00:56 INFO - Running setup.py install for mozfile 21:00:56 INFO - Running setup.py install for mozhttpd 21:00:57 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 21:00:57 INFO - Running setup.py install for mozinfo 21:00:57 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 21:00:57 INFO - Running setup.py install for mozInstall 21:00:57 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 21:00:57 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 21:00:57 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 21:00:57 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 21:00:57 INFO - Running setup.py install for mozleak 21:00:57 INFO - Running setup.py install for mozlog 21:00:57 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 21:00:57 INFO - Running setup.py install for moznetwork 21:00:57 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 21:00:57 INFO - Running setup.py install for mozprocess 21:00:57 INFO - Running setup.py install for mozprofile 21:00:58 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 21:00:58 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 21:00:58 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 21:00:58 INFO - Running setup.py install for mozrunner 21:00:58 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 21:00:58 INFO - Running setup.py install for mozscreenshot 21:00:58 INFO - Running setup.py install for moztest 21:00:58 INFO - Running setup.py install for mozversion 21:00:58 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 21:00:58 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 21:00:58 INFO - Cleaning up... 21:00:58 INFO - Return code: 0 21:00:58 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:00:58 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')]} 21:00:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:00:58 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')]} 21:00:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:00:58 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 0x107638c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10763c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9481579400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1075c1c60>, '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 0x107356cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10737fbe8>, '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.bwdBITHyJB/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.tB1KVNSeg3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/np/gxqtlmy15cvfysz9zj9x958r00000w/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 21:00:58 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 21:00:58 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 21:00:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bwdBITHyJB/Render', 21:00:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:00:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:00:58 INFO - 'HOME': '/Users/cltbld', 21:00:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:00:58 INFO - 'LOGNAME': 'cltbld', 21:00:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:00:58 INFO - 'MOZ_NO_REMOTE': '1', 21:00:58 INFO - 'NO_EM_RESTART': '1', 21:00:58 INFO - 'PAGER': '/bin/cat', 21:00:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:00:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:00:58 INFO - 'PWD': '/builds/slave/test', 21:00:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:00:58 INFO - 'SHELL': '/bin/bash', 21:00:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tB1KVNSeg3/Listeners', 21:00:58 INFO - 'TMPDIR': '/var/folders/np/gxqtlmy15cvfysz9zj9x958r00000w/T/', 21:00:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:00:58 INFO - 'USER': 'cltbld', 21:00:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:00:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:00:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:00:58 INFO - 'XPC_FLAGS': '0x0', 21:00:58 INFO - 'XPC_SERVICE_NAME': '0', 21:00:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:00:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:00:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 21:00:59 INFO - Running setup.py (path:/var/folders/np/gxqtlmy15cvfysz9zj9x958r00000w/T/pip-QS3LcD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 21:00:59 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)) 21:00:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 21:00:59 INFO - Running setup.py (path:/var/folders/np/gxqtlmy15cvfysz9zj9x958r00000w/T/pip-dZka46-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 21:00:59 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)) 21:00:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 21:00:59 INFO - Running setup.py (path:/var/folders/np/gxqtlmy15cvfysz9zj9x958r00000w/T/pip-8kSjmV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 21:00:59 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)) 21:00:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 21:00:59 INFO - Running setup.py (path:/var/folders/np/gxqtlmy15cvfysz9zj9x958r00000w/T/pip-7sDrKd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 21:00:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.51 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 21:00:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 21:00:59 INFO - Running setup.py (path:/var/folders/np/gxqtlmy15cvfysz9zj9x958r00000w/T/pip-j3_Lzs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 21:00:59 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)) 21:00:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 21:00:59 INFO - Running setup.py (path:/var/folders/np/gxqtlmy15cvfysz9zj9x958r00000w/T/pip-uZzOYk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 21:00:59 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)) 21:00:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 21:00:59 INFO - Running setup.py (path:/var/folders/np/gxqtlmy15cvfysz9zj9x958r00000w/T/pip-pCJtSw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 21:00:59 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)) 21:00:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 21:00:59 INFO - Running setup.py (path:/var/folders/np/gxqtlmy15cvfysz9zj9x958r00000w/T/pip-zkcvSs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 21:00:59 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)) 21:00:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 21:00:59 INFO - Running setup.py (path:/var/folders/np/gxqtlmy15cvfysz9zj9x958r00000w/T/pip-q5Hcnz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 21:00:59 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)) 21:00:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 21:00:59 INFO - Running setup.py (path:/var/folders/np/gxqtlmy15cvfysz9zj9x958r00000w/T/pip-q7Tak_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 21:01:00 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)) 21:01:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 21:01:00 INFO - Running setup.py (path:/var/folders/np/gxqtlmy15cvfysz9zj9x958r00000w/T/pip-zc6Dpo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 21:01:00 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)) 21:01:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 21:01:00 INFO - Running setup.py (path:/var/folders/np/gxqtlmy15cvfysz9zj9x958r00000w/T/pip-2Drdvg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 21:01:00 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)) 21:01:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 21:01:00 INFO - Running setup.py (path:/var/folders/np/gxqtlmy15cvfysz9zj9x958r00000w/T/pip-nNGYTe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 21:01:00 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)) 21:01:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 21:01:00 INFO - Running setup.py (path:/var/folders/np/gxqtlmy15cvfysz9zj9x958r00000w/T/pip-Hpnnkh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 21:01:00 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)) 21:01:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 21:01:00 INFO - Running setup.py (path:/var/folders/np/gxqtlmy15cvfysz9zj9x958r00000w/T/pip-hQXRi7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 21:01:00 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)) 21:01:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 21:01:00 INFO - Running setup.py (path:/var/folders/np/gxqtlmy15cvfysz9zj9x958r00000w/T/pip-Y7CsDl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 21:01:00 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)) 21:01:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 21:01:00 INFO - Running setup.py (path:/var/folders/np/gxqtlmy15cvfysz9zj9x958r00000w/T/pip-vweV2B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 21:01:00 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)) 21:01:00 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)) 21:01:00 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)) 21:01:00 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)) 21:01:00 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 21:01:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 21:01:00 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 21:01:00 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 21:01:00 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 21:01:00 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 21:01:00 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 21:01:02 INFO - Downloading blessings-1.6.tar.gz 21:01:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 21:01:02 INFO - Installing collected packages: blessings 21:01:02 INFO - Running setup.py install for blessings 21:01:02 INFO - Successfully installed blessings 21:01:02 INFO - Cleaning up... 21:01:02 INFO - Return code: 0 21:01:02 INFO - Done creating virtualenv /builds/slave/test/build/venv. 21:01:02 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 21:01:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 21:01:02 INFO - Reading from file tmpfile_stdout 21:01:02 INFO - Current package versions: 21:01:02 INFO - blessings == 1.6 21:01:02 INFO - blobuploader == 1.2.4 21:01:02 INFO - docopt == 0.6.1 21:01:02 INFO - functools32 == 3.2.3-2 21:01:02 INFO - jsonschema == 2.5.1 21:01:02 INFO - manifestparser == 1.1 21:01:02 INFO - mozInstall == 1.12 21:01:02 INFO - mozcrash == 1.0 21:01:02 INFO - mozdebug == 0.1 21:01:02 INFO - mozdevice == 0.51 21:01:02 INFO - mozfile == 1.2 21:01:02 INFO - mozhttpd == 0.7 21:01:02 INFO - mozinfo == 0.9 21:01:02 INFO - mozleak == 0.1 21:01:02 INFO - mozlog == 3.4 21:01:02 INFO - moznetwork == 0.27 21:01:02 INFO - mozprocess == 0.25 21:01:02 INFO - mozprofile == 0.28 21:01:02 INFO - mozrunner == 6.13 21:01:02 INFO - mozscreenshot == 0.1 21:01:02 INFO - mozsystemmonitor == 0.3 21:01:02 INFO - moztest == 0.8 21:01:02 INFO - mozversion == 1.4 21:01:02 INFO - psutil == 3.1.1 21:01:02 INFO - requests == 1.2.3 21:01:02 INFO - wsgiref == 0.1.2 21:01:02 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:01:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:01:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:01:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:01:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:01:02 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 0x107638c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10763c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9481579400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1075c1c60>, '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 0x107356cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10737fbe8>, '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.bwdBITHyJB/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.tB1KVNSeg3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/np/gxqtlmy15cvfysz9zj9x958r00000w/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 21:01:02 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 21:01:02 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 21:01:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bwdBITHyJB/Render', 21:01:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:01:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:01:02 INFO - 'HOME': '/Users/cltbld', 21:01:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:01:02 INFO - 'LOGNAME': 'cltbld', 21:01:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:01:02 INFO - 'MOZ_NO_REMOTE': '1', 21:01:02 INFO - 'NO_EM_RESTART': '1', 21:01:02 INFO - 'PAGER': '/bin/cat', 21:01:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:01:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:01:02 INFO - 'PWD': '/builds/slave/test', 21:01:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:01:02 INFO - 'SHELL': '/bin/bash', 21:01:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tB1KVNSeg3/Listeners', 21:01:02 INFO - 'TMPDIR': '/var/folders/np/gxqtlmy15cvfysz9zj9x958r00000w/T/', 21:01:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:01:02 INFO - 'USER': 'cltbld', 21:01:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:01:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:01:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:01:02 INFO - 'XPC_FLAGS': '0x0', 21:01:02 INFO - 'XPC_SERVICE_NAME': '0', 21:01:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:01:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:01:03 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)) 21:01:03 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)) 21:01:03 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)) 21:01:03 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)) 21:01:03 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)) 21:01:03 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)) 21:01:03 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)) 21:01:03 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)) 21:01:03 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)) 21:01:03 INFO - Downloading/unpacking marionette-driver>=1.4.0 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 21:01:03 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 21:01:03 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 21:01:03 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 21:01:03 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 21:01:04 INFO - Downloading marionette_driver-1.4.0.tar.gz 21:01:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/marionette-driver/setup.py) egg_info for package marionette-driver 21:01:04 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)) 21:01:04 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)) 21:01:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner>=6.9 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from marionette-driver>=1.4.0->-r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 21:01:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice>=0.37 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozrunner>=6.9->marionette-driver>=1.4.0->-r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 21:01:04 INFO - Installing collected packages: marionette-driver 21:01:04 INFO - Running setup.py install for marionette-driver 21:01:04 INFO - Successfully installed marionette-driver 21:01:04 INFO - Cleaning up... 21:01:04 INFO - Return code: 0 21:01:04 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 21:01:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:01:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:01:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:01:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:01:04 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x107638c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10763c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9481579400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1075c1c60>, '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 0x107356cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10737fbe8>, '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.bwdBITHyJB/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.tB1KVNSeg3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/np/gxqtlmy15cvfysz9zj9x958r00000w/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 21:01:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 21:01:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 21:01:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bwdBITHyJB/Render', 21:01:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:01:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:01:04 INFO - 'HOME': '/Users/cltbld', 21:01:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:01:04 INFO - 'LOGNAME': 'cltbld', 21:01:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:01:04 INFO - 'MOZ_NO_REMOTE': '1', 21:01:04 INFO - 'NO_EM_RESTART': '1', 21:01:04 INFO - 'PAGER': '/bin/cat', 21:01:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:01:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:01:04 INFO - 'PWD': '/builds/slave/test', 21:01:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:01:04 INFO - 'SHELL': '/bin/bash', 21:01:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tB1KVNSeg3/Listeners', 21:01:04 INFO - 'TMPDIR': '/var/folders/np/gxqtlmy15cvfysz9zj9x958r00000w/T/', 21:01:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:01:04 INFO - 'USER': 'cltbld', 21:01:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:01:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:01:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:01:04 INFO - 'XPC_FLAGS': '0x0', 21:01:04 INFO - 'XPC_SERVICE_NAME': '0', 21:01:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:01:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:01:05 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 21:01:05 INFO - Cleaning up... 21:01:05 INFO - Return code: 0 21:01:05 INFO - Running post-action listener: _resource_record_post_action 21:01:05 INFO - Running post-action listener: _start_resource_monitoring 21:01:05 INFO - Starting resource monitoring. 21:01:05 INFO - [mozharness: 2017-05-31 04:01:05.173078Z] Finished create-virtualenv step (success) 21:01:05 INFO - [mozharness: 2017-05-31 04:01:05.174166Z] Running install step. 21:01:05 INFO - Running pre-action listener: _resource_record_pre_action 21:01:05 INFO - Running main action method: install 21:01:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 21:01:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 21:01:05 INFO - Reading from file tmpfile_stdout 21:01:05 INFO - Detecting whether we're running mozinstall >=1.0... 21:01:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 21:01:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 21:01:05 INFO - Reading from file tmpfile_stdout 21:01:05 INFO - Output received: 21:01:05 INFO - Usage: mozinstall [options] installer 21:01:05 INFO - Options: 21:01:05 INFO - -h, --help show this help message and exit 21:01:05 INFO - -d DEST, --destination=DEST 21:01:05 INFO - Directory to install application into. [default: 21:01:05 INFO - "/builds/slave/test"] 21:01:05 INFO - --app=APP Application being installed. [default: firefox] 21:01:05 INFO - mkdir: /builds/slave/test/build/application 21:01:05 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'] 21:01:05 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 21:01:26 INFO - Reading from file tmpfile_stdout 21:01:26 INFO - Output received: 21:01:26 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 21:01:26 INFO - Running post-action listener: _resource_record_post_action 21:01:26 INFO - [mozharness: 2017-05-31 04:01:26.865511Z] Finished install step (success) 21:01:26 INFO - [mozharness: 2017-05-31 04:01:26.865660Z] Running setup-mitmproxy step. 21:01:26 INFO - Running pre-action listener: _resource_record_pre_action 21:01:26 INFO - Running main action method: setup_mitmproxy 21:01:26 INFO - Skipping: mitmproxy is not required 21:01:26 INFO - Running post-action listener: _resource_record_post_action 21:01:26 INFO - [mozharness: 2017-05-31 04:01:26.866328Z] Finished setup-mitmproxy step (success) 21:01:26 INFO - [mozharness: 2017-05-31 04:01:26.866467Z] Running run-tests step. 21:01:26 INFO - Running pre-action listener: _resource_record_pre_action 21:01:26 INFO - Running main action method: run_tests 21:01:26 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 21:01:26 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 21:01:26 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 21:01:26 INFO - 2017-05-30 21:01:26.880 screenresolution[1633:7611] starting screenresolution argv=screenresolution get 21:01:26 INFO - 2017-05-30 21:01:26.883 screenresolution[1633:7611] Display 0: 1600x1200x32@60 21:01:26 INFO - 2017-05-30 21:01:26.889 screenresolution[1634:7614] starting screenresolution argv=screenresolution list 21:01:26 INFO - Available Modes on Display 0 21:01:26 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 21:01:26 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 21:01:26 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 21:01:26 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 21:01:27 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 21:01:27 INFO - Intel Iris: 21:01:27 INFO - Chipset Model: Intel Iris 21:01:27 INFO - Type: GPU 21:01:27 INFO - Bus: Built-In 21:01:27 INFO - VRAM (Dynamic, Max): 1536 MB 21:01:27 INFO - Vendor: Intel (0x8086) 21:01:27 INFO - Device ID: 0x0a2e 21:01:27 INFO - Revision ID: 0x0009 21:01:27 INFO - Displays: 21:01:27 INFO - Display: 21:01:27 INFO - Resolution: 1600 x 1200 @ 60 Hz 21:01:27 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 21:01:27 INFO - Display Serial Number: 1600x1200 60 21:01:27 INFO - Main Display: Yes 21:01:27 INFO - Mirror: Off 21:01:27 INFO - Online: Yes 21:01:27 INFO - Rotation: Supported 21:01:27 INFO - Return code: 0 21:01:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 21:01:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 21:01:27 INFO - Python 2.7.3 21:01:27 INFO - Return code: 0 21:01:27 INFO - grabbing minidump binary from tooltool 21:01:27 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')]} 21:01:27 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 0x1075c1c60>, '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 0x107356cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10737fbe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 21:01:27 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 21:01:27 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 21:01:27 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 21:01:27 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 21:01:27 INFO - Return code: 0 21:01:27 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 21:01:27 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 21:01:27 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 21:01:27 INFO - ENV: RUST_BACKTRACE is now 1 21:01:27 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 21:01:27 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 21:01:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Cedar', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/HvkzD0iDSg-C_qCUHhn9Kg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0186', '--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 21:01:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Cedar --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/HvkzD0iDSg-C_qCUHhn9Kg/artifacts/public/build/target.crashreporter-symbols.zip --title t-yosemite-r7-0186 --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 21:01:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bwdBITHyJB/Render', 21:01:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:01:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:01:27 INFO - 'HOME': '/Users/cltbld', 21:01:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:01:27 INFO - 'LOGNAME': 'cltbld', 21:01:27 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 21:01:27 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 21:01:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:01:27 INFO - 'MOZ_NO_REMOTE': '1', 21:01:27 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 21:01:27 INFO - 'NO_EM_RESTART': '1', 21:01:27 INFO - 'PAGER': '/bin/cat', 21:01:27 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:01:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:01:27 INFO - 'PWD': '/builds/slave/test', 21:01:27 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 21:01:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:01:27 INFO - 'RUST_BACKTRACE': '1', 21:01:27 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 21:01:27 INFO - 'SHELL': '/bin/bash', 21:01:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tB1KVNSeg3/Listeners', 21:01:27 INFO - 'TMPDIR': '/var/folders/np/gxqtlmy15cvfysz9zj9x958r00000w/T/', 21:01:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:01:27 INFO - 'USER': 'cltbld', 21:01:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:01:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:01:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:01:27 INFO - 'XPC_FLAGS': '0x0', 21:01:27 INFO - 'XPC_SERVICE_NAME': '0', 21:01:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:01:27 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Cedar', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/HvkzD0iDSg-C_qCUHhn9Kg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0186', '--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 21:01:27 INFO - mozversion application_buildid: 20170530195347 21:01:27 INFO - mozversion application_changeset: 9317f9df5c2adeebbf9bdf307136c9037f869c8f 21:01:27 INFO - mozversion application_display_name: Nightly 21:01:27 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 21:01:27 INFO - mozversion application_name: Firefox 21:01:27 INFO - mozversion application_remotingname: firefox 21:01:27 INFO - mozversion application_repository: https://hg.mozilla.org/projects/cedar 21:01:27 INFO - mozversion application_vendor: Mozilla 21:01:27 INFO - mozversion application_version: 55.0a1 21:01:27 INFO - mozversion platform_buildid: 20170530195347 21:01:27 INFO - mozversion platform_changeset: 9317f9df5c2adeebbf9bdf307136c9037f869c8f 21:01:27 INFO - mozversion platform_repository: https://hg.mozilla.org/projects/cedar 21:01:27 INFO - mozversion platform_version: 55.0a1 21:01:27 INFO - using testdate: 1496203287 21:01:27 INFO - actual date: 1496203287 21:01:27 INFO - starting webserver on 'localhost:49231' 21:01:27 INFO - SUITE-START | Running 2 tests 21:01:27 INFO - TEST-START | dromaeo_css 21:01:27 INFO - operating with platform_type : mac_ 21:01:27 INFO - Initialising browser for dromaeo_css test... 21:01:27 INFO - Application command: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49231/getInfo.html -foreground -profile /var/folders/np/gxqtlmy15cvfysz9zj9x958r00000w/T/tmpTqJ48C/profile 21:01:27 INFO - TEST-INFO | started process 1644 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49231/getInfo.html -foreground) 21:02:01 INFO - TEST-INFO | 1644: killed by SIGTERM 21:02:01 INFO - Browser initialized. 21:02:01 INFO - Running cycle 1/1 for dromaeo_css test... 21:02:01 INFO - TEST-INFO | started process 1654 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/np/gxqtlmy15cvfysz9zj9x958r00000w/T/tmpTqJ48C/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 21:02:03 INFO - PID 1654 | 2017-05-30 21:02:03.613 plugin-container[1655:8327] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9737, name = 'com.apple.tsm.portname' 21:02:03 INFO - PID 1654 | See /usr/include/servers/bootstrap_defs.h for the error codes. 21:02:03 INFO - PID 1654 | 2017-05-30 21:02:03.614 plugin-container[1655:8327] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9653, name = 'com.apple.CFPasteboardClient' 21:02:03 INFO - PID 1654 | See /usr/include/servers/bootstrap_defs.h for the error codes. 21:02:03 INFO - PID 1654 | 2017-05-30 21:02:03.614 plugin-container[1655:8327] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 21:02:04 INFO - PID 1654 | 2017-05-30 21:02:04.119 plugin-container[1656:8371] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x952f, name = 'com.apple.tsm.portname' 21:02:04 INFO - PID 1654 | See /usr/include/servers/bootstrap_defs.h for the error codes. 21:02:04 INFO - PID 1654 | 2017-05-30 21:02:04.120 plugin-container[1656:8371] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x981f, name = 'com.apple.CFPasteboardClient' 21:02:04 INFO - PID 1654 | See /usr/include/servers/bootstrap_defs.h for the error codes. 21:02:04 INFO - PID 1654 | 2017-05-30 21:02:04.120 plugin-container[1656:8371] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 21:02:13 INFO - PID 1654 | RSS: Main: 268414976 21:02:13 INFO - PID 1654 | 21:05:15 INFO - PID 1654 | Cycle 1(1): loaded http://localhost:49231/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49231/tests/dromaeo/cssquery-ext.html) 21:05:16 INFO - PID 1654 | RSS: Main: 271368192 21:05:16 INFO - PID 1654 | 21:08:18 INFO - PID 1654 | Cycle 1(1): loaded http://localhost:49231/tests/dromaeo/cssquery-ext.html (next: http://localhost:49231/tests/dromaeo/cssquery-jquery.html) 21:08:19 INFO - PID 1654 | RSS: Main: 284295168 21:08:19 INFO - PID 1654 | 21:11:21 INFO - PID 1654 | Cycle 1(1): loaded http://localhost:49231/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49231/tests/dromaeo/cssquery-mootools.html) 21:11:21 INFO - PID 1654 | RSS: Main: 283508736 21:11:21 INFO - PID 1654 | 21:14:23 INFO - PID 1654 | Cycle 1(1): loaded http://localhost:49231/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49231/tests/dromaeo/cssquery-prototype.html) 21:14:24 INFO - PID 1654 | RSS: Main: 287272960 21:14:24 INFO - PID 1654 | 21:17:27 INFO - PID 1654 | Cycle 1(1): loaded http://localhost:49231/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49231/tests/dromaeo/cssquery-yui.html) 21:17:27 INFO - PID 1654 | RSS: Main: 287272960 21:17:27 INFO - PID 1654 | 21:20:29 INFO - PID 1654 | Cycle 1(1): loaded http://localhost:49231/tests/dromaeo/cssquery-yui.html (next: http://localhost:49231/tests/dromaeo/cssquery-dojo.html) 21:20:29 INFO - PID 1654 | RSS: Main: 288817152 21:20:29 INFO - PID 1654 | 21:20:29 INFO - PID 1654 | __start_tp_report 21:20:29 INFO - PID 1654 | _x_x_mozilla_page_load 21:20:29 INFO - PID 1654 | _x_x_mozilla_page_load_details 21:20:29 INFO - PID 1654 | |i|pagename|runs| 21:20:29 INFO - PID 1654 | |0;dojo.html;2962.8962986295483;3042.528408096745;3247.723943464805;3592.8562857485704;3614.132608174039;4837.419509658837;4933.851984440464;6178.135061091451;6213.192285002943;6295.43341099301;6206.596571222867;6216.595921265114;6223.284322302937;6266.811995640138;6276.05859121132;5241.711705856176;5471.699056551888;5477.233187353773;5478.780848766045;5580.414056524047;5573.940951219276;5576.888462230753;5579.69311687857;5581.6371935824045;5588.050031494636;4510.909781804378;4853.320535125085;5376.301080859464;5423.322084739408;5462.371827239878;5504.954058728828;5522.91715624266;5530.2810634617645;5540.085885828834;5540.390557038722;5453.100238460649;5456.91814622781;5466.835994920159;5480.506754392124;5512.6692398456225;6213.502919766408;6255.812325630239;6266.624002559861;6300.59046161998;6319.241690997063;6582.901256481156;6618.238902526223;6729.93270067298;6750.459963202981;6761.0872532208505;6665.233498147727;6787.966060169717;6826.317368263183;6827.556208846409;6843.863122737517;6321.146645202884;6338.7464501420345;6338.873222535523;6347.174867267272;6354;6733.494987875929;6828.112345395067;6838.418734407631;6931.202911665122;6965.756198533027;6342.651154186564;6422.6467544285515;6447.80656580294;6600.570962887493;6614.338566143347;6688.063671086052;6699.598024118569;6746.865062698718;6791.660416979132;6795.116634837538;6691.5650482718465;6698.765543205965;6699.196096468455;6704.229013663394;6718.160229971254;6478;6526.314736952647;6599.175103112111;6640.103586015923;6734.090897728842;5384.138537833927;5390.434004429557;5403.432639572867;5411.485908838654;5434.864128396822;4031.596840315945;4776.522347765195;4781.6891902026255;4811.182099043171;4816.301636262722;5737.8565535861935;5754.280714910637;6243.125962365272;6283.3402492737605;6307.747690092437;6784.864302713918;6837.487188460886;6856.965715171392;6877.2091209510545;6883.931160688329;5209.4009188943755;6175.135481032659;6303.873922521524;6305.873882522415;6315.36846315374;5875;5913.201717768132;6041.697915104227;6048.395160484004;6054.485368743618;5803.535717142534;6846.520743547904;6864.622445765531;6976.127984002;7011.6494175292055;5643.35101463337;6772.187337519529;6826.761063362758;6855.211650660238;6875;6947.201071876799;6988.196357418758;6997.7200911962955;7011.018457415864;7037.014817925392;6485.740570377321;6588.835279117869;6653.866922661423;6676.599404035774;6773.695183716646;5708.315002199596;6893.586384816927;6896.413804826445;6908.274631163656;6910.44716422695;5484.91772623403;6423.9036414452885;6503.154589903283;6581.967090164519;6628.53600247978;6581.473482121514;6613.305602911625;6623.470122390294;6647.966760166169;6652.234992975869;5396.7571459283645;5671.858203544944;6499.935000649933;6536.869262614816;6541.672916354259;6433.420992110732;6478.967605161944;6657.068010478633;6661.766838160641;6689.531732778853;6395.232572091379;6412.1023056771155;6439.130717353098;6477.643729594917;6490.837729056811 21:20:29 INFO - PID 1654 | |1;ext.html;728.4864170759613;796.2395911904132;853.4026181672829;1265.9746805063894;1324.7549203397366;39512.012199695026;41247.79376103119;41373.17253654925;41381.9654508637;41390.58609413905;41340;41713;41719;41722.16555668885;41859;7051.823704407405;7942.086660034098;8107.959460202691;8349.582520873962;8546;8433.156684331549;8538.487690738544;8580.570971451434;8589.312854971588;8591.140885911422;8552.059273479912;8577.871331930026;8579.142085791433;8580.356473264499;8596.656133754643;8547.35894807892;8560.571971401405;8563.44337618059;8571.871421928676;8579.270761985239;8286.378521610903;8350.665973361058;8356.456830306011;8375.120612335679;8381;38606.80696596527;38609.42085868714;38650.613493864985;38656.61343386558;38835;3532.293541291752;3562.786232826013;3571.196480791801;3578.4095624221973;3580.15866271426;31603.36793264145;31739.206519836956;31748.20629484258;31756.682433175836;31902.680973190203;2818.605395244647;2938.676745557987;3133.6396314423678;3139.5133754268127;3153.274746808224;253501;257312.71343643163;287713.5614321936;306881;307656;49210.26184607234;49396.259056114184;49443.75278123586;49464.50535494599;49578.76053098701;50530;51103.23345149826;52271;52333.47666523362;52577.7371113142;14692.412303507954;14762.40950361995;14768.40926362955;14777.26113694311;14790.482333118289;11640.359780212;11719.179657423898;11796.233244839059;11801.881981180079;11828.88171118295;14229.359678814479;14258.928705356408;14276.357563909442;14305.356258968372;14358.282085895664;6134.110553969738;6177.536684748662;6189.907151392733;6213;6218.22272215973;899.4288626722079;905.9320550958704;995.7510622344414;997.546116516983;1002.6340385759291;24261.39346516316;25309;25315.240542783748;25338.493230135293;25398.873005634854;1024.3085917005992;1078.0459293525287;1095.2169199022737;1097.9890201098046;1167.6613782003124;11440.684321303157;12555.560555380518;12788.936055319664;12971;12974;328868;331007;331696;332398;332588;58249.4175058244;58418.70790646019;58434;58666;58682.7065864668;1509.8263699674458;1511.0631408526783;1552.0765144738862;1554.5103292462838;1554.9533513994597;24436.51126977415;24686;24734.752652473246;24753.62869556922;24937.87531062333;51918.740406299235;53403.19895201497;53408.93182136258;53422.465775343306;53482;230831.84584076973;250379;299051;300872;302492;37322;37414.129293535756;37512;37527.43708844424;37580.43629345507;45343.09313813639;46375.84060398517;47803.043939121715;48089.5191048085;48299.517004829504;31075.378492429572;32021.8398908004;32341.51487727639;32491.512627311084;32840.50739238865;31711;32497;32571.837140814143;32913.848015320305;32929.847455338946;37513;37555.81222093981;38720.612793872824;38900.6109938897;39208;30153.698463015087;32531.34937301288;32681;33015.50476742899;33345;22792.886035569714;24950.0019999198;24953.500929980935;25100.74899250984;25510 21:20:29 INFO - PID 1654 | |2;jquery.html;386.3335745838429;408.37518596547295;422.2062522457779;436.8143538995926;449.3439578215805;21865.453363665896;23287.650685239736;23299.184528541504;23342.183023594178;23355.76644233557;23859.64210536843;24891.875540622317;25045.248642540708;25053.248402547906;25093.874530627367;6179.81460556184;6334.683265836689;6336.904946425784;6339.714712837934;6343.555951083426;6276.780312689057;6292.150559674431;6311.895418301779;6327.746890124389;6329.082283068952;6101.0848372744;6246.437820596146;6283.842903927392;6307.400796924285;6314.905276420857;6207.596506227103;6259.123722678829;6262.749490020394;6270.623762574214;6277.68611569422;5584.4136365681425;5585.664860108366;5586.162075688639;5588.273524441796;5593.105103183511;29549.70450295491;29898;29899.551506727417;29939.850300748356;29941.40117197644;2137.7007218989197;2211.226070875197;2232.397252741767;2256.751757306711;2267;18812.341568045053;18968.5257868553;19115.90442047795;19154.318395424652;19168.90415547927;2063.566651003276;2120.109553987329;2165.058199683143;2181.6618424144285;2186.5408264264524;154880.22559887127;161326;200324;202228.99999999852;208639;11313.83029254562;12154.756904861853;13099.869001310055;13231.536896208587;13397.933010334886;17312.48062558125;17326.3069477222;17366.47900562985;17385.47843564695;17436.302547897943;9093.727188184364;9154;9197.908020919705;9266.258699304039;9319.860202096974;8671.653133874574;8767.868481972775;8770;8820.647174112964;8831.205191532792;8322.54226017575;8386.119457457002;8431.451955622864;8439.831203375898;8462.069172391031;2251.9211827585955;2252.0653928619768;2266.784655457729;2268.637018077133;2439.8292119551816;879.595386122378;934.5654270764128;966.990330096704;968.941863488193;989.7129832348683;16730.74903876443;17274.96350218991;20823.375298741063;20850.89574552148;21456.356309310744;995.4922989275522;1009.5255230041869;1015.4821041268858;1023.8310678738073;1037.9896201037893;10564.418956957288;10672.46637668129;11416.37209953444;11430.599929002612;11469.942650286695;535356;543036;547222.5833225009;553376;556141;138180;138356;142872;143773.28113359577;146287;986.5511192407438;1048.444324508012;1117.6144230240716;1164.5400066973498;1190.743990042145;22801.77198228063;23949;23983.92072356783;24035.399115022265;24098.313118081565;68236;69820.6508967452;69833.65083174552;71551.28448715447;71745.28254717386;92672;93238;93326;93703.594446082;93838.53080734552;10452.595902942518;10479.633212837514;10525.421101839165;10576.20678449104;10583.206259530412;17182.91408542999;18140.90929545344;18452.90773546124;18511.629767404305;18514.629707405507;15083.4720784772;15253.542393728207;15299.847001529843;15376.23118844031;15383.846211534403;11338.943305283421;13646;13952.79070813959;14018;14133.575992720234;15467.922660386626;15487.225638718244;15672.373105075669;15693.529194124194;15769.369225230863;10269.229807764297;10312.329698569569;10338.707661542307;10358.948205258925;10402;14692.632684182981;14899.851001489846;15015.699686006437;15046.699066018837;15128.016678915836 21:20:29 INFO - PID 1654 | |3;mootools.html;1332.780091284938;1350.1426594112738;1409.337611322678;1409.5912185466216;1419.950301739439;10238.488075596219;11726.530938762438;11838.7632247355;11849.17055806094;12075.97354224892;9601.711948641534;11593.420328983559;11646.359450230255;11721.58974435895;11742.354170520639;2513.195777351248;2554.846709197445;2555.5527782638055;2558.2325302409204;2561.7566331198504;2473.862023469206;2514.308565144591;2526.557852375836;2526.6462695222685;2530.101813856082;2483.255023492954;2501.9749802502015;2503.987480062597;2504.336350867022;2506.348349429152;2441.4994926040117;2452.362385779701;2466.8766561671932;2469.802415806731;2470.22188010776;2404.627282771173;2448.4001419652122;2465.112559478604;2472.6909136357954;2511.962320565193;11406.657800265923;11508.13688973322;11508.942455287754;11526.942365288203;11570.53717851285;6428.903566446507;6676.332366763285;6676.833079173011;6687.832804179934;6689.565178263398;3559.199180184464;3710.128119891823;4145.274576949022;4215.3044747616605;4216.936745948813;1224.791785396476;1231.6181983585027;1233.3401630127928;1255.4413286087695;1258.4399942025802;15065;15482.303296351578;16219.351225950948;16281.185940702917;16297.918510407491;5297.973510132425;5359.276497672764;5565.2486914266865;5568.860778480571;5575.721213939287;11512.654620361403;11632.302061876315;11637.592684256011;11644.0102591279;11653.18427710069;5719.3422756382715;6110.633361998294;6330.145430366933;6396.0405939109505;6415.390537898892;5960.986632272524;6068.302145253264;6069;6144.969275153685;6191.535634827406;4276.5295817460055;4338.544452832407;4353.956460435355;4369.191699535596;4447.955520444819;1223.7246619510538;1254.6737848159544;1265.9050571207197;1275.661949583361;1276.929768862703;752.0449029732209;781.355381810009;800.9479383840031;803.1045384396473;812.3257696112213;6106.6336019838955;6158.261008678897;6272.560920735596;6294.339094395023;6349.555531112777;547.7151881021925;582.2750675409059;585.3210276079727;591.7662523303271;601.5909181756448;3900.531936167678;4156.127213285184;4248.171606536695;4324.913501729948;4338.82644694215;14042.789358159636;14848.331825067899;14954.775678364833;14969.251537423303;15206.695866082617;14477.927610361881;14851.034682745587;14861.108333499807;14864.62838429048;15157.242137893281;8484.957575212084;8610.397272190887;8655.610497527627;8687.6524939003;8690.608922598498;5931.851703707442;6717.798466046026;6959.686814093277;7058.188308344401;7154.356107950101;12792.36038198105;12833.294168820805;13057.673558161123;13494.662633434242;13709.383077761724;11579.305241685526;12905.41925613331;13590.660233494242;13691.24698141572;13768.449262029406;9289.814203716023;9471.19494842946;9550.426974381558;9608.423494590346;9618.422894626345;10742.570297188026;10870.61952831647;10871.771286886327;10887.727806804893;10910.72723181895;8949.642014319354;9224.262059035394;9277;9282.118198771106;9440.433573985583;8898.510581918057;8935.419197752126;9077.04691007461;9142.040085791163;9185.219256363283;7993.400494962785;8086.353091752763;8142.959285203536;8153.510789352657;8167.264946154874;6009.519238461;6054.969725151346;6063.575549711478;6082.665453400105;6184.350643182581;4968.925466118083;6082.239720034996;6135.7238924249405;6168.907466388097;6212.378762123933 21:20:29 INFO - PID 1654 | |4;prototype.html;387.62400471542605;396.05738342744263;397.23532200514;400.06385058962024;405.38988821823153;4477.753723545204;5709.77160913564;6452.677366131692;6486.146095717881;6526;6181.721822517998;6260.499160067195;6305.306416294204;6315.1790267265305;6347.841303967403;4211.410402543639;4488.23699971004;4615.584597386234;4619.3071039344195;4653.418322709661;4003.8198281077275;4126.649234815044;4139.6895232857505;4260.7656578888145;4373.978130109345;4358.389825424427;4553.840615578455;4587.839425620104;4592.150452166343;4617.5382461753725;4578.175928332898;4579.931301030487;4609.907801843978;4612.792424340896;4638.628909687251;3932.2135572885536;3934.1344904121047;3935.4490371348033;3953.98023009886;3992.441058251847;5421.105517589593;5803.8549036274435;6244.812655620339;6252.874022832213;6257.405546473078;5462.552423607747;5473.534749546254;5486.588505862076;5493.297077905822;5537.501624853742;5564.721763911788;6645.833854153637;6778.593284402951;6786.762463313809;6800.795976120724;4085.8763839943954;4149.33610622302;4471.843485478025;4481.462224533045;4490.146872094291;5365.865853353658;7127.861641507527;7195.748148814819;7245.7826265212125;7297.963510182468;4049.817758200888;4112.259793237186;4213.431186789805;4227.682923780729;4512.932306015412;4581.541845815458;4587.518310577341;4587.770611469413;4592.334111553806;4604.7927843247135;4769.976150119227;5281.630285880028;5393.031543486678;5434.858679677233;5444.673319600836;6653.833654158685;6781.6270105144695;6793.762218322335;6818.318168183142;6835.350641689031;1390.7774756038984;1445.7686770116732;1448.449589156114;1491.6351538342512;1539.6766678997535;866.5883705240086;1010.1060561403211;1025.9897401026044;1037.190991027;1037.5382954585289;5386.91919621206;5412.188171774186;5537.944620553743;6169.198004259507;6449.451796597342;6164.291106522717;6908.447324214051;7032.753853615099;7040.295970402919;7071.434285257167;5586.994341018656;5950.226470558886;6349.809505714744;6360.745570177141;6367.777127800598;3299.703026727606;3468.976651887699;3666.4500324950936;3683.650053244937;3695.427208782643;7235.6020418876415;7237.963810180915;7241.710331586783;7244.891326630105;7282.927170728225;7257.818554536178;7276.708931642781;7285.581618285796;7287.27127287267;7313.867664769495;6977.778888694356;7105.396041336441;7136.823268902931;7209.2069872314005;7285.854282914418;5882.352941176553;6412.39082287182;6956.304369563003;7052.788416347517;7064.576125432491;6614.173228346457;6940;6977.895331569929;6978.546394484342;6991.335823096798;5678.6876721779045;6072;6489.707963141575;6800.761973330909;6991.44068474531;3957.802109894436;3972.9404058939717;4354.583206320523;4506.256467682796;4536.72779633223;6654.73381064752;6809.829754256183;7016.684249208696;7113.751018714527;7154.785356439315;6735.393814556714;6771.356721111487;6773.424258937848;6798.558093723892;6812.557183783038;5614.522765564972;6221.377862213742;6355.491560675227;6561.934380656323;6832.760853370109;5385.434529374359;6352.650604216812;6516.57642253252;6706.228783689938;6783.559068660521;5491.176323551499;6048.455638992512;6810.353016463428;6812.625305608239;6849.794506164823;5292.867678308073;6084.539710469367;6657.53397262187;6666.866662666816;6669.866602668017 21:20:29 INFO - PID 1654 | |5;yui.html;833.4291010657704;841.4194205997861;842.8567143585586;866.7789713623025;867.5835598912522;10478.738031549216;13623.591292261248;14115.659012393819;17279.222434990425;17419;17662.293508259656;18302.542436439096;18353.990530520816;18373.173207205673;18415.44753657389;937.779621788879;968.922486201104;988.4662282367549;1008.2287050406445;1022.8925962773914;1019.3985548526368;1022.6727447216894;1023.0945613132394;1024.3137098144261;1027.291169093327;824.2252282854128;1055.3931489393592;1056.5245639462273;1057.3391630231106;1058.090042563395;1044.650042235852;1046.947652617366;1047.0942634621074;1051.8474821150967;1061.6178175856685;692.8960655901604;746.6932867481906;780.3250188586888;781.5467029123095;790.7232468635989;17300.826991730046;17956;18124.18441170137;18125.4562363128;18330.541736456562;17962.1018949052;17973.550661233443;18116.275348986026;18325.358612448632;18339.899606023548;14935;15042.473513426978;15246.771298430533;15260.465883694129;15276.618084547865;3556.1643013891717;3568.8750893718857;3592.2995015971896;3593.1556084320164;3626.582942961567;155411.2229438857;155749;157053.2147339256;158896;159391.0608939097;13461.326933653474;13714.108582942277;13741.312934353442;13898.374573144232;13913.652158696113;26277.34306642311;26296;27085.729142708322;27355.589666155025;27624.58563121555;6829.590224586541;8107.270345668918;8245.216704413071;8253.8349233015;8261.545614991233;3476.756627036083;3910.7262491625315;3964.524257089168;3977.8607748728655;3998.28030954431;4256.127493863751;4342.696011279243;4360.520342762294;4365.279728844768;4381.6494680425485;897.5601955042113;934.0239449775002;934.7522906429801;948.6158105967095;949.4303417949313;583.8715482593823;649.8862699027651;669.6317025635881;674.8144260328351;677.9932200678029;11146.386948717736;11673.182877198515;12478.75042499145;13819.819190199785;14114.435422583187;649.243631169682;655.885220086483;668.7157957867887;672.0389842525236;682.276786606199;5983.700814959235;6010.939890601038;6033.3664965178905;6466.935330646727;6546.312637173029;146902;148581;149597.50402495835;150686.24656876645;151906.24046879695;111815;131138.34430827785;131324;132826;133978.33010835078;102203;102241.97758022622;103730;104215;104482.47758761157;12919.935400323315;13311.86688133145;13329.466821327038;13351.998600105226;13356.332183390987;33329.83335083309;34919.82540087283;45943.3108503366;45954.31068533908;46852.531474684816;63931.04103438545;64044;64334;64409.03386449113;65182;16860.1569921501;17155.571110722332;17561.82438175653;17671.381501647895;17827.643447131246;14908.701825963637;15418.691626167638;15726.09067322549;15809.288582014067;18777.248910043447;17690.646187076443;17834.821651783317;17871.73192402141;18095.185716642518;18484.44546663602;14825;17595.20821563007;18266.995315257787;18340.724889126406;18418.631627367646;17291.654166916338;18145.364912228535;18157;18280.90859545747;18294.90852545729;10187.745306367104;11839.1712580122;11968.085106382938;12018.0385569156;12497.750044999231;10704.411257380607;13550.322483875963;13585;13783.37974791157;13827.170369777845 21:20:29 INFO - PID 1654 | __end_tp_report 21:20:29 INFO - PID 1654 | __start_cc_report 21:20:29 INFO - PID 1654 | _x_x_mozilla_cycle_collect,451 21:20:29 INFO - PID 1654 | __end_cc_report 21:20:29 INFO - PID 1654 | __startTimestamp1496204429811__endTimestamp 21:20:29 INFO - PID 1654 | 21:20:29 INFO - PID 1654 | ------- Summary: start ------- 21:20:29 INFO - PID 1654 | Number of tests: 6 21:20:29 INFO - PID 1654 | 21:20:29 INFO - PID 1654 | [#0] dojo.html Cycles:165 Average:6138.21 Median:6395.23 stddev:801.70 (12.5%) stddev-sans-first:764.48 21:20:29 INFO - PID 1654 | Values: 2962.9 3042.5 3247.7 3592.9 3614.1 4837.4 4933.9 6178.1 6213.2 6295.4 6206.6 6216.6 6223.3 6266.8 6276.1 5241.7 5471.7 5477.2 5478.8 5580.4 5573.9 5576.9 5579.7 5581.6 5588.1 4510.9 4853.3 5376.3 5423.3 5462.4 5505.0 5522.9 5530.3 5540.1 5540.4 5453.1 5456.9 5466.8 5480.5 5512.7 6213.5 6255.8 6266.6 6300.6 6319.2 6582.9 6618.2 6729.9 6750.5 6761.1 6665.2 6788.0 6826.3 6827.6 6843.9 6321.1 6338.7 6338.9 6347.2 6354.0 6733.5 6828.1 6838.4 6931.2 6965.8 6342.7 6422.6 6447.8 6600.6 6614.3 6688.1 6699.6 6746.9 6791.7 6795.1 6691.6 6698.8 6699.2 6704.2 6718.2 6478.0 6526.3 6599.2 6640.1 6734.1 5384.1 5390.4 5403.4 5411.5 5434.9 4031.6 4776.5 4781.7 4811.2 4816.3 5737.9 5754.3 6243.1 6283.3 6307.7 6784.9 6837.5 6857.0 6877.2 6883.9 5209.4 6175.1 6303.9 6305.9 6315.4 5875.0 5913.2 6041.7 6048.4 6054.5 5803.5 6846.5 6864.6 6976.1 7011.6 5643.4 6772.2 6826.8 6855.2 6875.0 6947.2 6988.2 6997.7 7011.0 7037.0 6485.7 6588.8 6653.9 6676.6 6773.7 5708.3 6893.6 6896.4 6908.3 6910.4 5484.9 6423.9 6503.2 6582.0 6628.5 6581.5 6613.3 6623.5 6648.0 6652.2 5396.8 5671.9 6499.9 6536.9 6541.7 6433.4 6479.0 6657.1 6661.8 6689.5 6395.2 6412.1 6439.1 6477.6 6490.8 21:20:29 INFO - PID 1654 | 21:20:29 INFO - PID 1654 | [#1] ext.html Cycles:180 Average:46217.53 Median:3574.80 stddev:78265.53 (2189.4%) stddev-sans-first:78410.56 21:20:29 INFO - PID 1654 | Values: 728.5 796.2 853.4 1266.0 1324.8 39512.0 41247.8 41373.2 41382.0 41390.6 41340.0 41713.0 41719.0 41722.2 41859.0 7051.8 7942.1 8108.0 8349.6 8546.0 8433.2 8538.5 8580.6 8589.3 8591.1 8552.1 8577.9 8579.1 8580.4 8596.7 8547.4 8560.6 8563.4 8571.9 8579.3 8286.4 8350.7 8356.5 8375.1 8381.0 38606.8 38609.4 38650.6 38656.6 38835.0 3532.3 3562.8 3571.2 3578.4 3580.2 31603.4 31739.2 31748.2 31756.7 31902.7 2818.6 2938.7 3133.6 3139.5 3153.3 253501.0 257312.7 287713.6 306881.0 307656.0 49210.3 49396.3 49443.8 49464.5 49578.8 50530.0 51103.2 52271.0 52333.5 52577.7 14692.4 14762.4 14768.4 14777.3 14790.5 11640.4 11719.2 11796.2 11801.9 11828.9 14229.4 14258.9 14276.4 14305.4 14358.3 6134.1 6177.5 6189.9 6213.0 6218.2 899.4 905.9 995.8 997.5 1002.6 24261.4 25309.0 25315.2 25338.5 25398.9 1024.3 1078.0 1095.2 1098.0 1167.7 11440.7 12555.6 12788.9 12971.0 12974.0 328868.0 331007.0 331696.0 332398.0 332588.0 58249.4 58418.7 58434.0 58666.0 58682.7 1509.8 1511.1 1552.1 1554.5 1555.0 24436.5 24686.0 24734.8 24753.6 24937.9 51918.7 53403.2 53408.9 53422.5 53482.0 230831.8 250379.0 299051.0 300872.0 302492.0 37322.0 37414.1 37512.0 37527.4 37580.4 45343.1 46375.8 47803.0 48089.5 48299.5 31075.4 32021.8 32341.5 32491.5 32840.5 31711.0 32497.0 32571.8 32913.8 32929.8 37513.0 37555.8 38720.6 38900.6 39208.0 30153.7 32531.3 32681.0 33015.5 33345.0 22792.9 24950.0 24953.5 25100.7 25510.0 21:20:29 INFO - PID 1654 | 21:20:29 INFO - PID 1654 | [#2] jquery.html Cycles:180 Average:38459.78 Median:2266.89 stddev:94625.47 (4174.2%) stddev-sans-first:94847.73 21:20:29 INFO - PID 1654 | Values: 386.3 408.4 422.2 436.8 449.3 21865.5 23287.7 23299.2 23342.2 23355.8 23859.6 24891.9 25045.2 25053.2 25093.9 6179.8 6334.7 6336.9 6339.7 6343.6 6276.8 6292.2 6311.9 6327.7 6329.1 6101.1 6246.4 6283.8 6307.4 6314.9 6207.6 6259.1 6262.7 6270.6 6277.7 5584.4 5585.7 5586.2 5588.3 5593.1 29549.7 29898.0 29899.6 29939.9 29941.4 2137.7 2211.2 2232.4 2256.8 2267.0 18812.3 18968.5 19115.9 19154.3 19168.9 2063.6 2120.1 2165.1 2181.7 2186.5 154880.2 161326.0 200324.0 202229.0 208639.0 11313.8 12154.8 13099.9 13231.5 13397.9 17312.5 17326.3 17366.5 17385.5 17436.3 9093.7 9154.0 9197.9 9266.3 9319.9 8671.7 8767.9 8770.0 8820.6 8831.2 8322.5 8386.1 8431.5 8439.8 8462.1 2251.9 2252.1 2266.8 2268.6 2439.8 879.6 934.6 967.0 968.9 989.7 16730.7 17275.0 20823.4 20850.9 21456.4 995.5 1009.5 1015.5 1023.8 1038.0 10564.4 10672.5 11416.4 11430.6 11469.9 535356.0 543036.0 547222.6 553376.0 556141.0 138180.0 138356.0 142872.0 143773.3 146287.0 986.6 1048.4 1117.6 1164.5 1190.7 22801.8 23949.0 23983.9 24035.4 24098.3 68236.0 69820.7 69833.7 71551.3 71745.3 92672.0 93238.0 93326.0 93703.6 93838.5 10452.6 10479.6 10525.4 10576.2 10583.2 17182.9 18140.9 18452.9 18511.6 18514.6 15083.5 15253.5 15299.8 15376.2 15383.8 11338.9 13646.0 13952.8 14018.0 14133.6 15467.9 15487.2 15672.4 15693.5 15769.4 10269.2 10312.3 10338.7 10358.9 10402.0 14692.6 14899.9 15015.7 15046.7 15128.0 21:20:29 INFO - PID 1654 | 21:20:29 INFO - PID 1654 | [#3] mootools.html Cycles:180 Average:7020.80 Median:3805.33 stddev:4433.71 (116.5%) stddev-sans-first:4425.54 21:20:29 INFO - PID 1654 | Values: 1332.8 1350.1 1409.3 1409.6 1420.0 10238.5 11726.5 11838.8 11849.2 12076.0 9601.7 11593.4 11646.4 11721.6 11742.4 2513.2 2554.8 2555.6 2558.2 2561.8 2473.9 2514.3 2526.6 2526.6 2530.1 2483.3 2502.0 2504.0 2504.3 2506.3 2441.5 2452.4 2466.9 2469.8 2470.2 2404.6 2448.4 2465.1 2472.7 2512.0 11406.7 11508.1 11508.9 11526.9 11570.5 6428.9 6676.3 6676.8 6687.8 6689.6 3559.2 3710.1 4145.3 4215.3 4216.9 1224.8 1231.6 1233.3 1255.4 1258.4 15065.0 15482.3 16219.4 16281.2 16297.9 5298.0 5359.3 5565.2 5568.9 5575.7 11512.7 11632.3 11637.6 11644.0 11653.2 5719.3 6110.6 6330.1 6396.0 6415.4 5961.0 6068.3 6069.0 6145.0 6191.5 4276.5 4338.5 4354.0 4369.2 4448.0 1223.7 1254.7 1265.9 1275.7 1276.9 752.0 781.4 800.9 803.1 812.3 6106.6 6158.3 6272.6 6294.3 6349.6 547.7 582.3 585.3 591.8 601.6 3900.5 4156.1 4248.2 4324.9 4338.8 14042.8 14848.3 14954.8 14969.3 15206.7 14477.9 14851.0 14861.1 14864.6 15157.2 8485.0 8610.4 8655.6 8687.7 8690.6 5931.9 6717.8 6959.7 7058.2 7154.4 12792.4 12833.3 13057.7 13494.7 13709.4 11579.3 12905.4 13590.7 13691.2 13768.4 9289.8 9471.2 9550.4 9608.4 9618.4 10742.6 10870.6 10871.8 10887.7 10910.7 8949.6 9224.3 9277.0 9282.1 9440.4 8898.5 8935.4 9077.0 9142.0 9185.2 7993.4 8086.4 8143.0 8153.5 8167.3 6009.5 6055.0 6063.6 6082.7 6184.4 4968.9 6082.2 6135.7 6168.9 6212.4 21:20:29 INFO - PID 1654 | 21:20:29 INFO - PID 1654 | [#4] prototype.html Cycles:180 Average:5394.90 Median:5999.34 stddev:1747.71 (29.1%) stddev-sans-first:1711.73 21:20:29 INFO - PID 1654 | Values: 387.6 396.1 397.2 400.1 405.4 4477.8 5709.8 6452.7 6486.1 6526.0 6181.7 6260.5 6305.3 6315.2 6347.8 4211.4 4488.2 4615.6 4619.3 4653.4 4003.8 4126.6 4139.7 4260.8 4374.0 4358.4 4553.8 4587.8 4592.2 4617.5 4578.2 4579.9 4609.9 4612.8 4638.6 3932.2 3934.1 3935.4 3954.0 3992.4 5421.1 5803.9 6244.8 6252.9 6257.4 5462.6 5473.5 5486.6 5493.3 5537.5 5564.7 6645.8 6778.6 6786.8 6800.8 4085.9 4149.3 4471.8 4481.5 4490.1 5365.9 7127.9 7195.7 7245.8 7298.0 4049.8 4112.3 4213.4 4227.7 4512.9 4581.5 4587.5 4587.8 4592.3 4604.8 4770.0 5281.6 5393.0 5434.9 5444.7 6653.8 6781.6 6793.8 6818.3 6835.4 1390.8 1445.8 1448.4 1491.6 1539.7 866.6 1010.1 1026.0 1037.2 1037.5 5386.9 5412.2 5537.9 6169.2 6449.5 6164.3 6908.4 7032.8 7040.3 7071.4 5587.0 5950.2 6349.8 6360.7 6367.8 3299.7 3469.0 3666.5 3683.7 3695.4 7235.6 7238.0 7241.7 7244.9 7282.9 7257.8 7276.7 7285.6 7287.3 7313.9 6977.8 7105.4 7136.8 7209.2 7285.9 5882.4 6412.4 6956.3 7052.8 7064.6 6614.2 6940.0 6977.9 6978.5 6991.3 5678.7 6072.0 6489.7 6800.8 6991.4 3957.8 3972.9 4354.6 4506.3 4536.7 6654.7 6809.8 7016.7 7113.8 7154.8 6735.4 6771.4 6773.4 6798.6 6812.6 5614.5 6221.4 6355.5 6561.9 6832.8 5385.4 6352.7 6516.6 6706.2 6783.6 5491.2 6048.5 6810.4 6812.6 6849.8 5292.9 6084.5 6657.5 6666.9 6669.9 21:20:29 INFO - PID 1654 | 21:20:29 INFO - PID 1654 | [#5] yui.html Cycles:180 Average:26126.99 Median:18034.37 stddev:41040.21 (227.6%) stddev-sans-first:41111.39 21:20:29 INFO - PID 1654 | Values: 833.4 841.4 842.9 866.8 867.6 10478.7 13623.6 14115.7 17279.2 17419.0 17662.3 18302.5 18354.0 18373.2 18415.4 937.8 968.9 988.5 1008.2 1022.9 1019.4 1022.7 1023.1 1024.3 1027.3 824.2 1055.4 1056.5 1057.3 1058.1 1044.7 1046.9 1047.1 1051.8 1061.6 692.9 746.7 780.3 781.5 790.7 17300.8 17956.0 18124.2 18125.5 18330.5 17962.1 17973.6 18116.3 18325.4 18339.9 14935.0 15042.5 15246.8 15260.5 15276.6 3556.2 3568.9 3592.3 3593.2 3626.6 155411.2 155749.0 157053.2 158896.0 159391.1 13461.3 13714.1 13741.3 13898.4 13913.7 26277.3 26296.0 27085.7 27355.6 27624.6 6829.6 8107.3 8245.2 8253.8 8261.5 3476.8 3910.7 3964.5 3977.9 3998.3 4256.1 4342.7 4360.5 4365.3 4381.6 897.6 934.0 934.8 948.6 949.4 583.9 649.9 669.6 674.8 678.0 11146.4 11673.2 12478.8 13819.8 14114.4 649.2 655.9 668.7 672.0 682.3 5983.7 6010.9 6033.4 6466.9 6546.3 146902.0 148581.0 149597.5 150686.2 151906.2 111815.0 131138.3 131324.0 132826.0 133978.3 102203.0 102242.0 103730.0 104215.0 104482.5 12919.9 13311.9 13329.5 13352.0 13356.3 33329.8 34919.8 45943.3 45954.3 46852.5 63931.0 64044.0 64334.0 64409.0 65182.0 16860.2 17155.6 17561.8 17671.4 17827.6 14908.7 15418.7 15726.1 15809.3 18777.2 17690.6 17834.8 17871.7 18095.2 18484.4 14825.0 17595.2 18267.0 18340.7 18418.6 17291.7 18145.4 18157.0 18280.9 18294.9 10187.7 11839.2 11968.1 12018.0 12497.8 10704.4 13550.3 13585.0 13783.4 13827.2 21:20:29 INFO - PID 1654 | -------- Summary: end -------- 21:20:29 INFO - PID 1654 | 21:20:30 INFO - TEST-INFO | 1654: exit 0 21:20:30 INFO - TEST-OK | dromaeo_css | took 1142835ms 21:20:30 INFO - TEST-START | kraken 21:20:30 INFO - operating with platform_type : mac_ 21:20:30 INFO - Initialising browser for kraken test... 21:20:30 INFO - Application command: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49231/getInfo.html -foreground -profile /var/folders/np/gxqtlmy15cvfysz9zj9x958r00000w/T/tmpRjNSKv/profile 21:20:30 INFO - TEST-INFO | started process 1664 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49231/getInfo.html -foreground) 21:21:04 INFO - TEST-INFO | 1664: killed by SIGTERM 21:21:04 INFO - Browser initialized. 21:21:04 INFO - Running cycle 1/1 for kraken test... 21:21:04 INFO - TEST-INFO | started process 1668 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/np/gxqtlmy15cvfysz9zj9x958r00000w/T/tmpRjNSKv/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 21:21:06 INFO - PID 1668 | 2017-05-30 21:21:06.189 plugin-container[1669:10934] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4847, name = 'com.apple.tsm.portname' 21:21:06 INFO - PID 1668 | See /usr/include/servers/bootstrap_defs.h for the error codes. 21:21:06 INFO - PID 1668 | 2017-05-30 21:21:06.189 plugin-container[1669:10934] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9a17, name = 'com.apple.CFPasteboardClient' 21:21:06 INFO - PID 1668 | See /usr/include/servers/bootstrap_defs.h for the error codes. 21:21:06 INFO - PID 1668 | 2017-05-30 21:21:06.189 plugin-container[1669:10934] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 21:21:06 INFO - PID 1668 | 2017-05-30 21:21:06.970 plugin-container[1670:10979] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4647, name = 'com.apple.tsm.portname' 21:21:06 INFO - PID 1668 | See /usr/include/servers/bootstrap_defs.h for the error codes. 21:21:06 INFO - PID 1668 | 2017-05-30 21:21:06.970 plugin-container[1670:10979] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4917, name = 'com.apple.CFPasteboardClient' 21:21:06 INFO - PID 1668 | See /usr/include/servers/bootstrap_defs.h for the error codes. 21:21:06 INFO - PID 1668 | 2017-05-30 21:21:06.970 plugin-container[1670:10979] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 21:21:16 INFO - PID 1668 | RSS: Main: 259506176 21:21:16 INFO - PID 1668 | 21:21:19 INFO - PID 1668 | Cycle 1(1): loaded http://localhost:49231/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49231/tests/kraken/driver.html?testName=audio-beat-detection) 21:21:20 INFO - PID 1668 | RSS: Main: 270798848 21:21:20 INFO - PID 1668 | 21:21:22 INFO - PID 1668 | Cycle 1(1): loaded http://localhost:49231/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49231/tests/kraken/driver.html?testName=audio-dft) 21:21:23 INFO - PID 1668 | RSS: Main: 278294528 21:21:23 INFO - PID 1668 | 21:21:26 INFO - PID 1668 | Cycle 1(1): loaded http://localhost:49231/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49231/tests/kraken/driver.html?testName=audio-fft) 21:21:27 INFO - PID 1668 | RSS: Main: 280870912 21:21:27 INFO - PID 1668 | 21:21:29 INFO - PID 1668 | Cycle 1(1): loaded http://localhost:49231/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49231/tests/kraken/driver.html?testName=audio-oscillator) 21:21:29 INFO - PID 1668 | RSS: Main: 283664384 21:21:29 INFO - PID 1668 | 21:21:31 INFO - PID 1668 | Cycle 1(1): loaded http://localhost:49231/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49231/tests/kraken/driver.html?testName=imaging-gaussian-blur) 21:21:32 INFO - PID 1668 | RSS: Main: 283959296 21:21:32 INFO - PID 1668 | 21:21:35 INFO - PID 1668 | Cycle 1(1): loaded http://localhost:49231/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49231/tests/kraken/driver.html?testName=imaging-darkroom) 21:21:35 INFO - PID 1668 | RSS: Main: 289026048 21:21:35 INFO - PID 1668 | 21:21:38 INFO - PID 1668 | Cycle 1(1): loaded http://localhost:49231/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49231/tests/kraken/driver.html?testName=imaging-desaturate) 21:21:39 INFO - PID 1668 | RSS: Main: 291926016 21:21:39 INFO - PID 1668 | 21:21:42 INFO - PID 1668 | Cycle 1(1): loaded http://localhost:49231/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49231/tests/kraken/driver.html?testName=json-parse-financial) 21:21:43 INFO - PID 1668 | RSS: Main: 290545664 21:21:43 INFO - PID 1668 | 21:21:44 INFO - PID 1668 | Cycle 1(1): loaded http://localhost:49231/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49231/tests/kraken/driver.html?testName=json-stringify-tinderbox) 21:21:45 INFO - PID 1668 | RSS: Main: 290029568 21:21:45 INFO - PID 1668 | 21:21:46 INFO - PID 1668 | Cycle 1(1): loaded http://localhost:49231/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49231/tests/kraken/driver.html?testName=stanford-crypto-aes) 21:21:47 INFO - PID 1668 | RSS: Main: 289906688 21:21:47 INFO - PID 1668 | 21:21:49 INFO - PID 1668 | Cycle 1(1): loaded http://localhost:49231/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49231/tests/kraken/driver.html?testName=stanford-crypto-ccm) 21:21:50 INFO - PID 1668 | RSS: Main: 289890304 21:21:50 INFO - PID 1668 | 21:21:53 INFO - PID 1668 | Cycle 1(1): loaded http://localhost:49231/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49231/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 21:21:54 INFO - PID 1668 | RSS: Main: 289906688 21:21:54 INFO - PID 1668 | 21:21:57 INFO - PID 1668 | Cycle 1(1): loaded http://localhost:49231/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49231/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 21:21:58 INFO - PID 1668 | RSS: Main: 287633408 21:21:58 INFO - PID 1668 | 21:22:00 INFO - PID 1668 | Cycle 1(1): loaded http://localhost:49231/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49231/tests/kraken/driver.html?testName=ai-astar) 21:22:00 INFO - PID 1668 | RSS: Main: 289529856 21:22:00 INFO - PID 1668 | 21:22:00 INFO - PID 1668 | __start_tp_report 21:22:00 INFO - PID 1668 | _x_x_mozilla_page_load 21:22:00 INFO - PID 1668 | _x_x_mozilla_page_load_details 21:22:00 INFO - PID 1668 | |i|pagename|runs| 21:22:00 INFO - PID 1668 | |0;ai-astar;111;154;114;117;116;113;113;115;114;114 21:22:00 INFO - PID 1668 | |1;audio-beat-detection;138;111;111;150;109;109;106;118;133;128 21:22:00 INFO - PID 1668 | |2;audio-dft;197;200;196;216;220;232;269;221;201;195 21:22:00 INFO - PID 1668 | |3;audio-fft;77;59;60;84;60;65;77;89;83;60 21:22:00 INFO - PID 1668 | |4;audio-oscillator;87;69;82;70;73;70;85;69;69;95 21:22:00 INFO - PID 1668 | |5;imaging-gaussian-blur;89;90;89;89;89;95;136;105;89;89 21:22:00 INFO - PID 1668 | |6;imaging-darkroom;95;96;95;96;95;96;95;95;96;96 21:22:00 INFO - PID 1668 | |7;imaging-desaturate;91;96;90;92;90;120;87;144;107;91 21:22:00 INFO - PID 1668 | |8;json-parse-financial;51;52;50;52;52;52;51;51;53;53 21:22:00 INFO - PID 1668 | |9;json-stringify-tinderbox;54;56;62;56;64;61;54;55;55;58 21:22:00 INFO - PID 1668 | |10;stanford-crypto-aes;89;113;80;73;88;108;114;111;121;86 21:22:00 INFO - PID 1668 | |11;stanford-crypto-ccm;141;154;156;143;136;125;135;152;152;177 21:22:00 INFO - PID 1668 | |12;stanford-crypto-pbkdf2;185;213;191;243;236;198;238;220;243;240 21:22:00 INFO - PID 1668 | |13;stanford-crypto-sha256-iterative;70;64;71;69;59;60;71;94;94;103 21:22:00 INFO - PID 1668 | __end_tp_report 21:22:00 INFO - PID 1668 | __start_cc_report 21:22:00 INFO - PID 1668 | _x_x_mozilla_cycle_collect,1193 21:22:00 INFO - PID 1668 | __end_cc_report 21:22:00 INFO - PID 1668 | __startTimestamp1496204520368__endTimestamp 21:22:00 INFO - PID 1668 | 21:22:00 INFO - PID 1668 | ------- Summary: start ------- 21:22:00 INFO - PID 1668 | Number of tests: 14 21:22:00 INFO - PID 1668 | 21:22:00 INFO - PID 1668 | [#0] ai-astar Cycles:10 Average:118.10 Median:114.50 stddev:12.72 (11.1%) stddev-sans-first:13.23 21:22:00 INFO - PID 1668 | Values: 111.0 154.0 114.0 117.0 116.0 113.0 113.0 115.0 114.0 114.0 21:22:00 INFO - PID 1668 | 21:22:00 INFO - PID 1668 | [#1] audio-beat-detection Cycles:10 Average:121.30 Median:123.00 stddev:15.07 (12.3%) stddev-sans-first:14.72 21:22:00 INFO - PID 1668 | Values: 138.0 111.0 111.0 150.0 109.0 109.0 106.0 118.0 133.0 128.0 21:22:00 INFO - PID 1668 | 21:22:00 INFO - PID 1668 | [#2] audio-dft Cycles:10 Average:214.70 Median:218.00 stddev:23.03 (10.6%) stddev-sans-first:23.52 21:22:00 INFO - PID 1668 | Values: 197.0 200.0 196.0 216.0 220.0 232.0 269.0 221.0 201.0 195.0 21:22:00 INFO - PID 1668 | 21:22:00 INFO - PID 1668 | [#3] audio-fft Cycles:10 Average:71.40 Median:77.00 stddev:11.79 (15.3%) stddev-sans-first:12.33 21:22:00 INFO - PID 1668 | Values: 77.0 59.0 60.0 84.0 60.0 65.0 77.0 89.0 83.0 60.0 21:22:00 INFO - PID 1668 | 21:22:00 INFO - PID 1668 | [#4] audio-oscillator Cycles:10 Average:76.90 Median:77.50 stddev:9.54 (12.3%) stddev-sans-first:9.39 21:22:00 INFO - PID 1668 | Values: 87.0 69.0 82.0 70.0 73.0 70.0 85.0 69.0 69.0 95.0 21:22:00 INFO - PID 1668 | 21:22:00 INFO - PID 1668 | [#5] imaging-gaussian-blur Cycles:10 Average:96.00 Median:89.00 stddev:14.95 (16.8%) stddev-sans-first:15.64 21:22:00 INFO - PID 1668 | Values: 89.0 90.0 89.0 89.0 89.0 95.0 136.0 105.0 89.0 89.0 21:22:00 INFO - PID 1668 | 21:22:00 INFO - PID 1668 | [#6] imaging-darkroom Cycles:10 Average:95.50 Median:96.00 stddev:0.53 (0.5%) stddev-sans-first:0.53 21:22:00 INFO - PID 1668 | Values: 95.0 96.0 95.0 96.0 95.0 96.0 95.0 95.0 96.0 96.0 21:22:00 INFO - PID 1668 | 21:22:00 INFO - PID 1668 | [#7] imaging-desaturate Cycles:10 Average:100.80 Median:90.50 stddev:18.23 (20.1%) stddev-sans-first:18.98 21:22:00 INFO - PID 1668 | Values: 91.0 96.0 90.0 92.0 90.0 120.0 87.0 144.0 107.0 91.0 21:22:00 INFO - PID 1668 | 21:22:00 INFO - PID 1668 | [#8] json-parse-financial Cycles:10 Average:51.70 Median:52.00 stddev:0.95 (1.8%) stddev-sans-first:0.97 21:22:00 INFO - PID 1668 | Values: 51.0 52.0 50.0 52.0 52.0 52.0 51.0 51.0 53.0 53.0 21:22:00 INFO - PID 1668 | 21:22:00 INFO - PID 1668 | [#9] json-stringify-tinderbox Cycles:10 Average:57.50 Median:57.00 stddev:3.60 (6.3%) stddev-sans-first:3.59 21:22:00 INFO - PID 1668 | Values: 54.0 56.0 62.0 56.0 64.0 61.0 54.0 55.0 55.0 58.0 21:22:00 INFO - PID 1668 | 21:22:00 INFO - PID 1668 | [#10] stanford-crypto-aes Cycles:10 Average:98.30 Median:76.50 stddev:16.84 (22.0%) stddev-sans-first:17.52 21:22:00 INFO - PID 1668 | Values: 89.0 113.0 80.0 73.0 88.0 108.0 114.0 111.0 121.0 86.0 21:22:00 INFO - PID 1668 | 21:22:00 INFO - PID 1668 | [#11] stanford-crypto-ccm Cycles:10 Average:147.10 Median:152.00 stddev:14.46 (9.5%) stddev-sans-first:15.16 21:22:00 INFO - PID 1668 | Values: 141.0 154.0 156.0 143.0 136.0 125.0 135.0 152.0 152.0 177.0 21:22:00 INFO - PID 1668 | 21:22:00 INFO - PID 1668 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:220.70 Median:237.00 stddev:22.69 (9.6%) stddev-sans-first:20.05 21:22:00 INFO - PID 1668 | Values: 185.0 213.0 191.0 243.0 236.0 198.0 238.0 220.0 243.0 240.0 21:22:00 INFO - PID 1668 | 21:22:00 INFO - PID 1668 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:75.50 Median:70.50 stddev:15.63 (22.2%) stddev-sans-first:16.45 21:22:00 INFO - PID 1668 | Values: 70.0 64.0 71.0 69.0 59.0 60.0 71.0 94.0 94.0 103.0 21:22:00 INFO - PID 1668 | -------- Summary: end -------- 21:22:00 INFO - PID 1668 | 21:22:01 INFO - TEST-INFO | 1668: exit 0 21:22:01 INFO - TEST-OK | kraken | took 90799ms 21:22:01 INFO - SUITE-END | took 1233s 21:22:01 INFO - Completed test suite (00:20:33) 21:22:01 INFO - javascript benchmark 21:22:01 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8105.604857629456, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2962.8962986295483, 3042.528408096745, 3247.723943464805, 3592.8562857485704, 3614.132608174039, 4837.419509658837, 4933.851984440464, 6178.135061091451, 6213.192285002943, 6295.43341099301, 6206.596571222867, 6216.595921265114, 6223.284322302937, 6266.811995640138, 6276.05859121132, 5241.711705856176, 5471.699056551888, 5477.233187353773, 5478.780848766045, 5580.414056524047, 5573.940951219276, 5576.888462230753, 5579.69311687857, 5581.6371935824045, 5588.050031494636, 4510.909781804378, 4853.320535125085, 5376.301080859464, 5423.322084739408, 5462.371827239878, 5504.954058728828, 5522.91715624266, 5530.2810634617645, 5540.085885828834, 5540.390557038722, 5453.100238460649, 5456.91814622781, 5466.835994920159, 5480.506754392124, 5512.6692398456225, 6213.502919766408, 6255.812325630239, 6266.624002559861, 6300.59046161998, 6319.241690997063, 6582.901256481156, 6618.238902526223, 6729.93270067298, 6750.459963202981, 6761.0872532208505, 6665.233498147727, 6787.966060169717, 6826.317368263183, 6827.556208846409, 6843.863122737517, 6321.146645202884, 6338.7464501420345, 6338.873222535523, 6347.174867267272, 6354.0, 6733.494987875929, 6828.112345395067, 6838.418734407631, 6931.202911665122, 6965.756198533027, 6342.651154186564, 6422.6467544285515, 6447.80656580294, 6600.570962887493, 6614.338566143347, 6688.063671086052, 6699.598024118569, 6746.865062698718, 6791.660416979132, 6795.116634837538, 6691.5650482718465, 6698.765543205965, 6699.196096468455, 6704.229013663394, 6718.160229971254, 6478.0, 6526.314736952647, 6599.175103112111, 6640.103586015923, 6734.090897728842, 5384.138537833927, 5390.434004429557, 5403.432639572867, 5411.485908838654, 5434.864128396822, 4031.596840315945, 4776.522347765195, 4781.6891902026255, 4811.182099043171, 4816.301636262722, 5737.8565535861935, 5754.280714910637, 6243.125962365272, 6283.3402492737605, 6307.747690092437, 6784.864302713918, 6837.487188460886, 6856.965715171392, 6877.2091209510545, 6883.931160688329, 5209.4009188943755, 6175.135481032659, 6303.873922521524, 6305.873882522415, 6315.36846315374, 5875.0, 5913.201717768132, 6041.697915104227, 6048.395160484004, 6054.485368743618, 5803.535717142534, 6846.520743547904, 6864.622445765531, 6976.127984002, 7011.6494175292055, 5643.35101463337, 6772.187337519529, 6826.761063362758, 6855.211650660238, 6875.0, 6947.201071876799, 6988.196357418758, 6997.7200911962955, 7011.018457415864, 7037.014817925392, 6485.740570377321, 6588.835279117869, 6653.866922661423, 6676.599404035774, 6773.695183716646, 5708.315002199596, 6893.586384816927, 6896.413804826445, 6908.274631163656, 6910.44716422695, 5484.91772623403, 6423.9036414452885, 6503.154589903283, 6581.967090164519, 6628.53600247978, 6581.473482121514, 6613.305602911625, 6623.470122390294, 6647.966760166169, 6652.234992975869, 5396.7571459283645, 5671.858203544944, 6499.935000649933, 6536.869262614816, 6541.672916354259, 6433.420992110732, 6478.967605161944, 6657.068010478633, 6661.766838160641, 6689.531732778853, 6395.232572091379, 6412.1023056771155, 6439.130717353098, 6477.643729594917, 6490.837729056811], "value": 6081.080362021281, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [728.4864170759613, 796.2395911904132, 853.4026181672829, 1265.9746805063894, 1324.7549203397366, 39512.012199695026, 41247.79376103119, 41373.17253654925, 41381.9654508637, 41390.58609413905, 41340.0, 41713.0, 41719.0, 41722.16555668885, 41859.0, 7051.823704407405, 7942.086660034098, 8107.959460202691, 8349.582520873962, 8546.0, 8433.156684331549, 8538.487690738544, 8580.570971451434, 8589.312854971588, 8591.140885911422, 8552.059273479912, 8577.871331930026, 8579.142085791433, 8580.356473264499, 8596.656133754643, 8547.35894807892, 8560.571971401405, 8563.44337618059, 8571.871421928676, 8579.270761985239, 8286.378521610903, 8350.665973361058, 8356.456830306011, 8375.120612335679, 8381.0, 38606.80696596527, 38609.42085868714, 38650.613493864985, 38656.61343386558, 38835.0, 3532.293541291752, 3562.786232826013, 3571.196480791801, 3578.4095624221973, 3580.15866271426, 31603.36793264145, 31739.206519836956, 31748.20629484258, 31756.682433175836, 31902.680973190203, 2818.605395244647, 2938.676745557987, 3133.6396314423678, 3139.5133754268127, 3153.274746808224, 253501.0, 257312.71343643163, 287713.5614321936, 306881.0, 307656.0, 49210.26184607234, 49396.259056114184, 49443.75278123586, 49464.50535494599, 49578.76053098701, 50530.0, 51103.23345149826, 52271.0, 52333.47666523362, 52577.7371113142, 14692.412303507954, 14762.40950361995, 14768.40926362955, 14777.26113694311, 14790.482333118289, 11640.359780212, 11719.179657423898, 11796.233244839059, 11801.881981180079, 11828.88171118295, 14229.359678814479, 14258.928705356408, 14276.357563909442, 14305.356258968372, 14358.282085895664, 6134.110553969738, 6177.536684748662, 6189.907151392733, 6213.0, 6218.22272215973, 899.4288626722079, 905.9320550958704, 995.7510622344414, 997.546116516983, 1002.6340385759291, 24261.39346516316, 25309.0, 25315.240542783748, 25338.493230135293, 25398.873005634854, 1024.3085917005992, 1078.0459293525287, 1095.2169199022737, 1097.9890201098046, 1167.6613782003124, 11440.684321303157, 12555.560555380518, 12788.936055319664, 12971.0, 12974.0, 328868.0, 331007.0, 331696.0, 332398.0, 332588.0, 58249.4175058244, 58418.70790646019, 58434.0, 58666.0, 58682.7065864668, 1509.8263699674458, 1511.0631408526783, 1552.0765144738862, 1554.5103292462838, 1554.9533513994597, 24436.51126977415, 24686.0, 24734.752652473246, 24753.62869556922, 24937.87531062333, 51918.740406299235, 53403.19895201497, 53408.93182136258, 53422.465775343306, 53482.0, 230831.84584076973, 250379.0, 299051.0, 300872.0, 302492.0, 37322.0, 37414.129293535756, 37512.0, 37527.43708844424, 37580.43629345507, 45343.09313813639, 46375.84060398517, 47803.043939121715, 48089.5191048085, 48299.517004829504, 31075.378492429572, 32021.8398908004, 32341.51487727639, 32491.512627311084, 32840.50739238865, 31711.0, 32497.0, 32571.837140814143, 32913.848015320305, 32929.847455338946, 37513.0, 37555.81222093981, 38720.612793872824, 38900.6109938897, 39208.0, 30153.698463015087, 32531.34937301288, 32681.0, 33015.50476742899, 33345.0, 22792.886035569714, 24950.0019999198, 24953.500929980935, 25100.74899250984, 25510.0], "value": 18160.648612738692, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [386.3335745838429, 408.37518596547295, 422.2062522457779, 436.8143538995926, 449.3439578215805, 21865.453363665896, 23287.650685239736, 23299.184528541504, 23342.183023594178, 23355.76644233557, 23859.64210536843, 24891.875540622317, 25045.248642540708, 25053.248402547906, 25093.874530627367, 6179.81460556184, 6334.683265836689, 6336.904946425784, 6339.714712837934, 6343.555951083426, 6276.780312689057, 6292.150559674431, 6311.895418301779, 6327.746890124389, 6329.082283068952, 6101.0848372744, 6246.437820596146, 6283.842903927392, 6307.400796924285, 6314.905276420857, 6207.596506227103, 6259.123722678829, 6262.749490020394, 6270.623762574214, 6277.68611569422, 5584.4136365681425, 5585.664860108366, 5586.162075688639, 5588.273524441796, 5593.105103183511, 29549.70450295491, 29898.0, 29899.551506727417, 29939.850300748356, 29941.40117197644, 2137.7007218989197, 2211.226070875197, 2232.397252741767, 2256.751757306711, 2267.0, 18812.341568045053, 18968.5257868553, 19115.90442047795, 19154.318395424652, 19168.90415547927, 2063.566651003276, 2120.109553987329, 2165.058199683143, 2181.6618424144285, 2186.5408264264524, 154880.22559887127, 161326.0, 200324.0, 202228.99999999852, 208639.0, 11313.83029254562, 12154.756904861853, 13099.869001310055, 13231.536896208587, 13397.933010334886, 17312.48062558125, 17326.3069477222, 17366.47900562985, 17385.47843564695, 17436.302547897943, 9093.727188184364, 9154.0, 9197.908020919705, 9266.258699304039, 9319.860202096974, 8671.653133874574, 8767.868481972775, 8770.0, 8820.647174112964, 8831.205191532792, 8322.54226017575, 8386.119457457002, 8431.451955622864, 8439.831203375898, 8462.069172391031, 2251.9211827585955, 2252.0653928619768, 2266.784655457729, 2268.637018077133, 2439.8292119551816, 879.595386122378, 934.5654270764128, 966.990330096704, 968.941863488193, 989.7129832348683, 16730.74903876443, 17274.96350218991, 20823.375298741063, 20850.89574552148, 21456.356309310744, 995.4922989275522, 1009.5255230041869, 1015.4821041268858, 1023.8310678738073, 1037.9896201037893, 10564.418956957288, 10672.46637668129, 11416.37209953444, 11430.599929002612, 11469.942650286695, 535356.0, 543036.0, 547222.5833225009, 553376.0, 556141.0, 138180.0, 138356.0, 142872.0, 143773.28113359577, 146287.0, 986.5511192407438, 1048.444324508012, 1117.6144230240716, 1164.5400066973498, 1190.743990042145, 22801.77198228063, 23949.0, 23983.92072356783, 24035.399115022265, 24098.313118081565, 68236.0, 69820.6508967452, 69833.65083174552, 71551.28448715447, 71745.28254717386, 92672.0, 93238.0, 93326.0, 93703.594446082, 93838.53080734552, 10452.595902942518, 10479.633212837514, 10525.421101839165, 10576.20678449104, 10583.206259530412, 17182.91408542999, 18140.90929545344, 18452.90773546124, 18511.629767404305, 18514.629707405507, 15083.4720784772, 15253.542393728207, 15299.847001529843, 15376.23118844031, 15383.846211534403, 11338.943305283421, 13646.0, 13952.79070813959, 14018.0, 14133.575992720234, 15467.922660386626, 15487.225638718244, 15672.373105075669, 15693.529194124194, 15769.369225230863, 10269.229807764297, 10312.329698569569, 10338.707661542307, 10358.948205258925, 10402.0, 14692.632684182981, 14899.851001489846, 15015.699686006437, 15046.699066018837, 15128.016678915836], "value": 11346.912060159813, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1332.780091284938, 1350.1426594112738, 1409.337611322678, 1409.5912185466216, 1419.950301739439, 10238.488075596219, 11726.530938762438, 11838.7632247355, 11849.17055806094, 12075.97354224892, 9601.711948641534, 11593.420328983559, 11646.359450230255, 11721.58974435895, 11742.354170520639, 2513.195777351248, 2554.846709197445, 2555.5527782638055, 2558.2325302409204, 2561.7566331198504, 2473.862023469206, 2514.308565144591, 2526.557852375836, 2526.6462695222685, 2530.101813856082, 2483.255023492954, 2501.9749802502015, 2503.987480062597, 2504.336350867022, 2506.348349429152, 2441.4994926040117, 2452.362385779701, 2466.8766561671932, 2469.802415806731, 2470.22188010776, 2404.627282771173, 2448.4001419652122, 2465.112559478604, 2472.6909136357954, 2511.962320565193, 11406.657800265923, 11508.13688973322, 11508.942455287754, 11526.942365288203, 11570.53717851285, 6428.903566446507, 6676.332366763285, 6676.833079173011, 6687.832804179934, 6689.565178263398, 3559.199180184464, 3710.128119891823, 4145.274576949022, 4215.3044747616605, 4216.936745948813, 1224.791785396476, 1231.6181983585027, 1233.3401630127928, 1255.4413286087695, 1258.4399942025802, 15065.0, 15482.303296351578, 16219.351225950948, 16281.185940702917, 16297.918510407491, 5297.973510132425, 5359.276497672764, 5565.2486914266865, 5568.860778480571, 5575.721213939287, 11512.654620361403, 11632.302061876315, 11637.592684256011, 11644.0102591279, 11653.18427710069, 5719.3422756382715, 6110.633361998294, 6330.145430366933, 6396.0405939109505, 6415.390537898892, 5960.986632272524, 6068.302145253264, 6069.0, 6144.969275153685, 6191.535634827406, 4276.5295817460055, 4338.544452832407, 4353.956460435355, 4369.191699535596, 4447.955520444819, 1223.7246619510538, 1254.6737848159544, 1265.9050571207197, 1275.661949583361, 1276.929768862703, 752.0449029732209, 781.355381810009, 800.9479383840031, 803.1045384396473, 812.3257696112213, 6106.6336019838955, 6158.261008678897, 6272.560920735596, 6294.339094395023, 6349.555531112777, 547.7151881021925, 582.2750675409059, 585.3210276079727, 591.7662523303271, 601.5909181756448, 3900.531936167678, 4156.127213285184, 4248.171606536695, 4324.913501729948, 4338.82644694215, 14042.789358159636, 14848.331825067899, 14954.775678364833, 14969.251537423303, 15206.695866082617, 14477.927610361881, 14851.034682745587, 14861.108333499807, 14864.62838429048, 15157.242137893281, 8484.957575212084, 8610.397272190887, 8655.610497527627, 8687.6524939003, 8690.608922598498, 5931.851703707442, 6717.798466046026, 6959.686814093277, 7058.188308344401, 7154.356107950101, 12792.36038198105, 12833.294168820805, 13057.673558161123, 13494.662633434242, 13709.383077761724, 11579.305241685526, 12905.41925613331, 13590.660233494242, 13691.24698141572, 13768.449262029406, 9289.814203716023, 9471.19494842946, 9550.426974381558, 9608.423494590346, 9618.422894626345, 10742.570297188026, 10870.61952831647, 10871.771286886327, 10887.727806804893, 10910.72723181895, 8949.642014319354, 9224.262059035394, 9277.0, 9282.118198771106, 9440.433573985583, 8898.510581918057, 8935.419197752126, 9077.04691007461, 9142.040085791163, 9185.219256363283, 7993.400494962785, 8086.353091752763, 8142.959285203536, 8153.510789352657, 8167.264946154874, 6009.519238461, 6054.969725151346, 6063.575549711478, 6082.665453400105, 6184.350643182581, 4968.925466118083, 6082.239720034996, 6135.7238924249405, 6168.907466388097, 6212.378762123933], "value": 5262.726763925904, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [387.62400471542605, 396.05738342744263, 397.23532200514, 400.06385058962024, 405.38988821823153, 4477.753723545204, 5709.77160913564, 6452.677366131692, 6486.146095717881, 6526.0, 6181.721822517998, 6260.499160067195, 6305.306416294204, 6315.1790267265305, 6347.841303967403, 4211.410402543639, 4488.23699971004, 4615.584597386234, 4619.3071039344195, 4653.418322709661, 4003.8198281077275, 4126.649234815044, 4139.6895232857505, 4260.7656578888145, 4373.978130109345, 4358.389825424427, 4553.840615578455, 4587.839425620104, 4592.150452166343, 4617.5382461753725, 4578.175928332898, 4579.931301030487, 4609.907801843978, 4612.792424340896, 4638.628909687251, 3932.2135572885536, 3934.1344904121047, 3935.4490371348033, 3953.98023009886, 3992.441058251847, 5421.105517589593, 5803.8549036274435, 6244.812655620339, 6252.874022832213, 6257.405546473078, 5462.552423607747, 5473.534749546254, 5486.588505862076, 5493.297077905822, 5537.501624853742, 5564.721763911788, 6645.833854153637, 6778.593284402951, 6786.762463313809, 6800.795976120724, 4085.8763839943954, 4149.33610622302, 4471.843485478025, 4481.462224533045, 4490.146872094291, 5365.865853353658, 7127.861641507527, 7195.748148814819, 7245.7826265212125, 7297.963510182468, 4049.817758200888, 4112.259793237186, 4213.431186789805, 4227.682923780729, 4512.932306015412, 4581.541845815458, 4587.518310577341, 4587.770611469413, 4592.334111553806, 4604.7927843247135, 4769.976150119227, 5281.630285880028, 5393.031543486678, 5434.858679677233, 5444.673319600836, 6653.833654158685, 6781.6270105144695, 6793.762218322335, 6818.318168183142, 6835.350641689031, 1390.7774756038984, 1445.7686770116732, 1448.449589156114, 1491.6351538342512, 1539.6766678997535, 866.5883705240086, 1010.1060561403211, 1025.9897401026044, 1037.190991027, 1037.5382954585289, 5386.91919621206, 5412.188171774186, 5537.944620553743, 6169.198004259507, 6449.451796597342, 6164.291106522717, 6908.447324214051, 7032.753853615099, 7040.295970402919, 7071.434285257167, 5586.994341018656, 5950.226470558886, 6349.809505714744, 6360.745570177141, 6367.777127800598, 3299.703026727606, 3468.976651887699, 3666.4500324950936, 3683.650053244937, 3695.427208782643, 7235.6020418876415, 7237.963810180915, 7241.710331586783, 7244.891326630105, 7282.927170728225, 7257.818554536178, 7276.708931642781, 7285.581618285796, 7287.27127287267, 7313.867664769495, 6977.778888694356, 7105.396041336441, 7136.823268902931, 7209.2069872314005, 7285.854282914418, 5882.352941176553, 6412.39082287182, 6956.304369563003, 7052.788416347517, 7064.576125432491, 6614.173228346457, 6940.0, 6977.895331569929, 6978.546394484342, 6991.335823096798, 5678.6876721779045, 6072.0, 6489.707963141575, 6800.761973330909, 6991.44068474531, 3957.802109894436, 3972.9404058939717, 4354.583206320523, 4506.256467682796, 4536.72779633223, 6654.73381064752, 6809.829754256183, 7016.684249208696, 7113.751018714527, 7154.785356439315, 6735.393814556714, 6771.356721111487, 6773.424258937848, 6798.558093723892, 6812.557183783038, 5614.522765564972, 6221.377862213742, 6355.491560675227, 6561.934380656323, 6832.760853370109, 5385.434529374359, 6352.650604216812, 6516.57642253252, 6706.228783689938, 6783.559068660521, 5491.176323551499, 6048.455638992512, 6810.353016463428, 6812.625305608239, 6849.794506164823, 5292.867678308073, 6084.539710469367, 6657.53397262187, 6666.866662666816, 6669.866602668017], "value": 4843.345422524158, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [833.4291010657704, 841.4194205997861, 842.8567143585586, 866.7789713623025, 867.5835598912522, 10478.738031549216, 13623.591292261248, 14115.659012393819, 17279.222434990425, 17419.0, 17662.293508259656, 18302.542436439096, 18353.990530520816, 18373.173207205673, 18415.44753657389, 937.779621788879, 968.922486201104, 988.4662282367549, 1008.2287050406445, 1022.8925962773914, 1019.3985548526368, 1022.6727447216894, 1023.0945613132394, 1024.3137098144261, 1027.291169093327, 824.2252282854128, 1055.3931489393592, 1056.5245639462273, 1057.3391630231106, 1058.090042563395, 1044.650042235852, 1046.947652617366, 1047.0942634621074, 1051.8474821150967, 1061.6178175856685, 692.8960655901604, 746.6932867481906, 780.3250188586888, 781.5467029123095, 790.7232468635989, 17300.826991730046, 17956.0, 18124.18441170137, 18125.4562363128, 18330.541736456562, 17962.1018949052, 17973.550661233443, 18116.275348986026, 18325.358612448632, 18339.899606023548, 14935.0, 15042.473513426978, 15246.771298430533, 15260.465883694129, 15276.618084547865, 3556.1643013891717, 3568.8750893718857, 3592.2995015971896, 3593.1556084320164, 3626.582942961567, 155411.2229438857, 155749.0, 157053.2147339256, 158896.0, 159391.0608939097, 13461.326933653474, 13714.108582942277, 13741.312934353442, 13898.374573144232, 13913.652158696113, 26277.34306642311, 26296.0, 27085.729142708322, 27355.589666155025, 27624.58563121555, 6829.590224586541, 8107.270345668918, 8245.216704413071, 8253.8349233015, 8261.545614991233, 3476.756627036083, 3910.7262491625315, 3964.524257089168, 3977.8607748728655, 3998.28030954431, 4256.127493863751, 4342.696011279243, 4360.520342762294, 4365.279728844768, 4381.6494680425485, 897.5601955042113, 934.0239449775002, 934.7522906429801, 948.6158105967095, 949.4303417949313, 583.8715482593823, 649.8862699027651, 669.6317025635881, 674.8144260328351, 677.9932200678029, 11146.386948717736, 11673.182877198515, 12478.75042499145, 13819.819190199785, 14114.435422583187, 649.243631169682, 655.885220086483, 668.7157957867887, 672.0389842525236, 682.276786606199, 5983.700814959235, 6010.939890601038, 6033.3664965178905, 6466.935330646727, 6546.312637173029, 146902.0, 148581.0, 149597.50402495835, 150686.24656876645, 151906.24046879695, 111815.0, 131138.34430827785, 131324.0, 132826.0, 133978.33010835078, 102203.0, 102241.97758022622, 103730.0, 104215.0, 104482.47758761157, 12919.935400323315, 13311.86688133145, 13329.466821327038, 13351.998600105226, 13356.332183390987, 33329.83335083309, 34919.82540087283, 45943.3108503366, 45954.31068533908, 46852.531474684816, 63931.04103438545, 64044.0, 64334.0, 64409.03386449113, 65182.0, 16860.1569921501, 17155.571110722332, 17561.82438175653, 17671.381501647895, 17827.643447131246, 14908.701825963637, 15418.691626167638, 15726.09067322549, 15809.288582014067, 18777.248910043447, 17690.646187076443, 17834.821651783317, 17871.73192402141, 18095.185716642518, 18484.44546663602, 14825.0, 17595.20821563007, 18266.995315257787, 18340.724889126406, 18418.631627367646, 17291.654166916338, 18145.364912228535, 18157.0, 18280.90859545747, 18294.90852545729, 10187.745306367104, 11839.1712580122, 11968.085106382938, 12018.0385569156, 12497.750044999231, 10704.411257380607, 13550.322483875963, 13585.0, 13783.37974791157, 13827.170369777845], "value": 8878.37102034568, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1545.5, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 154.0, 114.0, 117.0, 116.0, 113.0, 113.0, 115.0, 114.0, 114.0], "value": 118.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [138.0, 111.0, 111.0, 150.0, 109.0, 109.0, 106.0, 118.0, 133.0, 128.0], "value": 121.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [197.0, 200.0, 196.0, 216.0, 220.0, 232.0, 269.0, 221.0, 201.0, 195.0], "value": 214.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 59.0, 60.0, 84.0, 60.0, 65.0, 77.0, 89.0, 83.0, 60.0], "value": 71.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 69.0, 82.0, 70.0, 73.0, 70.0, 85.0, 69.0, 69.0, 95.0], "value": 76.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 90.0, 89.0, 89.0, 89.0, 95.0, 136.0, 105.0, 89.0, 89.0], "value": 96.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 96.0, 95.0, 96.0, 95.0, 96.0, 95.0, 95.0, 96.0, 96.0], "value": 95.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 96.0, 90.0, 92.0, 90.0, 120.0, 87.0, 144.0, 107.0, 91.0], "value": 100.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [51.0, 52.0, 50.0, 52.0, 52.0, 52.0, 51.0, 51.0, 53.0, 53.0], "value": 51.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 56.0, 62.0, 56.0, 64.0, 61.0, 54.0, 55.0, 55.0, 58.0], "value": 57.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 113.0, 80.0, 73.0, 88.0, 108.0, 114.0, 111.0, 121.0, 86.0], "value": 98.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 154.0, 156.0, 143.0, 136.0, 125.0, 135.0, 152.0, 152.0, 177.0], "value": 147.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 213.0, 191.0, 243.0, 236.0, 198.0, 238.0, 220.0, 243.0, 240.0], "value": 220.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 64.0, 71.0, 69.0, 59.0, 60.0, 71.0, 94.0, 94.0, 103.0], "value": 75.5, "unit": "score"}]}]} 21:22:01 INFO - Return code: 0 21:22:01 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 21:22:01 INFO - # TBPL SUCCESS # 21:22:01 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 21:22:01 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 21:22:01 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 21:22:01 INFO - 2017-05-30 21:22:01.664 screenresolution[1676:11526] starting screenresolution argv=screenresolution get 21:22:01 INFO - 2017-05-30 21:22:01.666 screenresolution[1676:11526] Display 0: 1600x1200x32@60 21:22:01 INFO - 2017-05-30 21:22:01.672 screenresolution[1677:11529] starting screenresolution argv=screenresolution list 21:22:01 INFO - Available Modes on Display 0 21:22:01 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 21:22:01 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 21:22:01 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 21:22:01 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 21:22:01 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 21:22:01 INFO - Intel Iris: 21:22:01 INFO - Chipset Model: Intel Iris 21:22:01 INFO - Type: GPU 21:22:01 INFO - Bus: Built-In 21:22:01 INFO - VRAM (Dynamic, Max): 1536 MB 21:22:01 INFO - Vendor: Intel (0x8086) 21:22:01 INFO - Device ID: 0x0a2e 21:22:01 INFO - Revision ID: 0x0009 21:22:01 INFO - Displays: 21:22:01 INFO - Display: 21:22:01 INFO - Resolution: 1600 x 1200 @ 60 Hz 21:22:01 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 21:22:01 INFO - Display Serial Number: 1600x1200 60 21:22:01 INFO - Main Display: Yes 21:22:01 INFO - Mirror: Off 21:22:01 INFO - Online: Yes 21:22:01 INFO - Rotation: Supported 21:22:01 INFO - Return code: 0 21:22:01 INFO - Running post-action listener: _resource_record_post_action 21:22:01 INFO - [mozharness: 2017-05-31 04:22:01.906558Z] Finished run-tests step (success) 21:22:01 INFO - Running post-run listener: _resource_record_post_run 21:22:01 INFO - Total resource usage - Wall time: 1256s; CPU: 53.0%; Read bytes: 40662528; Write bytes: 567810048; Read time: 409; Write time: 1889 21:22:01 INFO - TinderboxPrint: CPU usage
53.4% 21:22:01 INFO - TinderboxPrint: I/O read bytes / time
40,662,528 / 409 21:22:01 INFO - TinderboxPrint: I/O write bytes / time
567,810,048 / 1,889 21:22:01 INFO - TinderboxPrint: CPU idle
2,344.1 (46.6%) 21:22:01 INFO - TinderboxPrint: CPU system
1,016.8 (20.2%) 21:22:01 INFO - TinderboxPrint: CPU user
1,664.7 (33.1%) 21:22:01 INFO - TinderboxPrint: Swap in / out
287,100,928 / 0 21:22:01 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 230961664; Write bytes: 230033408; Read time: 16779; Write time: 465 21:22:01 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 21:22:01 INFO - run-tests - Wall time: 1235s; CPU: 53.0%; Read bytes: 39699968; Write bytes: 319311872; Read time: 393; Write time: 1379 21:22:02 INFO - Running post-run listener: _upload_blobber_files 21:22:02 INFO - Blob upload gear active. 21:22:02 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 21:22:02 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 21:22:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Cedar', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 21:22:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Cedar -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 21:22:02 INFO - (blobuploader) - INFO - Open directory for files ... 21:22:02 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 21:22:02 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:22:02 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:22:03 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 21:22:03 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:22:03 INFO - (blobuploader) - INFO - Done attempting. 21:22:03 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 21:22:03 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:22:03 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:22:04 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 21:22:04 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:22:04 INFO - (blobuploader) - INFO - Done attempting. 21:22:04 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 21:22:04 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:22:04 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:22:05 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 21:22:05 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:22:05 INFO - (blobuploader) - INFO - Done attempting. 21:22:05 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 21:22:05 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:22:05 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:22:08 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 21:22:08 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:22:08 INFO - (blobuploader) - INFO - Done attempting. 21:22:08 INFO - (blobuploader) - INFO - Iteration through files over. 21:22:08 INFO - Return code: 0 21:22:08 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 21:22:08 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 21:22:08 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/34107aa5981aa2dca0c68ba0b025f51d057fcbf157dc796de4d4653bfe71641716563673ec3329821ca19f1746c081510c7fe945547a4827de176f8a6519e03c", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/15dcbd99b78ed1488099e680a0a16dc2b039088bb6b8a2c4d5528f715143497e0c476a64b4277591181c5720be05735531bba5a3c4953583be93b566d429584d", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/df2829bd01686125f35972f509232024803dd79d064a8cc6b09862785554b929571f1e4d3eaf174a788afb0c075137312309206b7204d1c8277c7698774a547b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/68425ee66903185d97412591c165dd6ea61e9b978234f55a01032f2f168eb5468542878c0bb41c10669b656232219f7e47ed75f856204e3a8b0c8ebfd4daceb4"} 21:22:08 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 21:22:08 INFO - Writing to file /builds/slave/test/properties/blobber_files 21:22:08 INFO - Contents: 21:22:08 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/34107aa5981aa2dca0c68ba0b025f51d057fcbf157dc796de4d4653bfe71641716563673ec3329821ca19f1746c081510c7fe945547a4827de176f8a6519e03c", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/15dcbd99b78ed1488099e680a0a16dc2b039088bb6b8a2c4d5528f715143497e0c476a64b4277591181c5720be05735531bba5a3c4953583be93b566d429584d", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/df2829bd01686125f35972f509232024803dd79d064a8cc6b09862785554b929571f1e4d3eaf174a788afb0c075137312309206b7204d1c8277c7698774a547b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/68425ee66903185d97412591c165dd6ea61e9b978234f55a01032f2f168eb5468542878c0bb41c10669b656232219f7e47ed75f856204e3a8b0c8ebfd4daceb4"} 21:22:08 INFO - Running post-run listener: copy_logs_to_upload_dir 21:22:08 INFO - Copying logs to upload dir... 21:22:08 INFO - mkdir: /builds/slave/test/build/upload/logs 21:22:08 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1339.045667 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 19 secs) (at 2017-05-30 21:22:08.864693) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-30 21:22:08.870326) ========= 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.bwdBITHyJB/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.tB1KVNSeg3/Listeners TMPDIR=/var/folders/np/gxqtlmy15cvfysz9zj9x958r00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/34107aa5981aa2dca0c68ba0b025f51d057fcbf157dc796de4d4653bfe71641716563673ec3329821ca19f1746c081510c7fe945547a4827de176f8a6519e03c", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/15dcbd99b78ed1488099e680a0a16dc2b039088bb6b8a2c4d5528f715143497e0c476a64b4277591181c5720be05735531bba5a3c4953583be93b566d429584d", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/df2829bd01686125f35972f509232024803dd79d064a8cc6b09862785554b929571f1e4d3eaf174a788afb0c075137312309206b7204d1c8277c7698774a547b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/68425ee66903185d97412591c165dd6ea61e9b978234f55a01032f2f168eb5468542878c0bb41c10669b656232219f7e47ed75f856204e3a8b0c8ebfd4daceb4"} build_url:https://queue.taskcluster.net/v1/task/HvkzD0iDSg-C_qCUHhn9Kg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011013 build_url: 'https://queue.taskcluster.net/v1/task/HvkzD0iDSg-C_qCUHhn9Kg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/34107aa5981aa2dca0c68ba0b025f51d057fcbf157dc796de4d4653bfe71641716563673ec3329821ca19f1746c081510c7fe945547a4827de176f8a6519e03c", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/15dcbd99b78ed1488099e680a0a16dc2b039088bb6b8a2c4d5528f715143497e0c476a64b4277591181c5720be05735531bba5a3c4953583be93b566d429584d", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/df2829bd01686125f35972f509232024803dd79d064a8cc6b09862785554b929571f1e4d3eaf174a788afb0c075137312309206b7204d1c8277c7698774a547b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/68425ee66903185d97412591c165dd6ea61e9b978234f55a01032f2f168eb5468542878c0bb41c10669b656232219f7e47ed75f856204e3a8b0c8ebfd4daceb4"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-30 21:22:08.903760) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-30 21:22:08.904104) ========= 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.bwdBITHyJB/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.tB1KVNSeg3/Listeners TMPDIR=/var/folders/np/gxqtlmy15cvfysz9zj9x958r00000w/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.005100 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-30 21:22:08.952750) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-30 21:22:08.953074) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-30 21:22:08.953426) ========= ========= Total master_lag: 0.17 =========