builder: cedar_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0316 starttime: 1496937891.87 results: success (0) revision: 50d53cf0e52cd201878cf19b4f9d540c69a6c3a4 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-06-08 09:04:51.868140) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-06-08 09:04:51.868581) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-08 09:04:51.868857) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-08 09:04:51.892199) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-08 09:04:51.892532) ========= 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.qnpBEZjfrH/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.SqnH5amz20/Listeners TMPDIR=/var/folders/tg/9cs16h3j7pn5rd3v0kkz9lgc00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-06-08 09:04:51-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 45.7M=0s 2017-06-08 09:04:51 (45.7 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.094278 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-08 09:04:52.008910) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-08 09:04:52.009244) ========= 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.qnpBEZjfrH/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.SqnH5amz20/Listeners TMPDIR=/var/folders/tg/9cs16h3j7pn5rd3v0kkz9lgc00000w/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.020539 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-08 09:04:52.058069) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-06-08 09:04:52.058423) ========= bash -c 'python archiver_client.py mozharness --repo projects/cedar --rev 50d53cf0e52cd201878cf19b4f9d540c69a6c3a4 --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 50d53cf0e52cd201878cf19b4f9d540c69a6c3a4 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.qnpBEZjfrH/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.SqnH5amz20/Listeners TMPDIR=/var/folders/tg/9cs16h3j7pn5rd3v0kkz9lgc00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-06-08 09:04:52,142 truncating revision to first 12 chars 2017-06-08 09:04:52,142 Setting DEBUG logging. 2017-06-08 09:04:52,142 attempt 1/10 2017-06-08 09:04:52,142 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/projects/cedar/50d53cf0e52c?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-06-08 09:04:53,469 unpacking tar archive at: cedar-50d53cf0e52c/testing/mozharness/ program finished with exit code 0 elapsedTime=1.604802 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-06-08 09:04:53.684456) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-08 09:04:53.684803) ========= script_repo_revision: 50d53cf0e52cd201878cf19b4f9d540c69a6c3a4 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-08 09:04:53.685178) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-08 09:04:53.685448) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-08 09:04:53.703742) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 18 secs) (at 2017-06-08 09:04:53.704037) ========= /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.qnpBEZjfrH/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.SqnH5amz20/Listeners TMPDIR=/var/folders/tg/9cs16h3j7pn5rd3v0kkz9lgc00000w/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 09:04:53 INFO - MultiFileLogger online at 20170608 09:04:53 in /builds/slave/test 09:04:53 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 09:04:53 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:04:53 INFO - {'append_to_log': False, 09:04:53 INFO - 'base_work_dir': '/builds/slave/test', 09:04:53 INFO - 'blob_upload_branch': 'Cedar', 09:04:53 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:04:53 INFO - 'branch': 'Cedar', 09:04:53 INFO - 'buildbot_json_path': 'buildprops.json', 09:04:53 INFO - 'config_files': ('talos/mac_config.py',), 09:04:53 INFO - 'default_actions': ('clobber', 09:04:53 INFO - 'read-buildbot-config', 09:04:53 INFO - 'download-and-extract', 09:04:53 INFO - 'populate-webroot', 09:04:53 INFO - 'create-virtualenv', 09:04:53 INFO - 'install', 09:04:53 INFO - 'setup-mitmproxy', 09:04:53 INFO - 'run-tests'), 09:04:53 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:04:53 INFO - 'download_minidump_stackwalk': True, 09:04:53 INFO - 'download_symbols': 'ondemand', 09:04:53 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:04:53 INFO - 'tooltool.py': '/tools/tooltool.py', 09:04:53 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:04:53 INFO - '/tools/misc-python/virtualenv.py')}, 09:04:53 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:04:53 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:04:53 INFO - 'gecko_profile': False, 09:04:53 INFO - 'gecko_profile_interval': 0, 09:04:53 INFO - 'installer_path': 'installer.exe', 09:04:53 INFO - 'log_level': 'info', 09:04:53 INFO - 'log_name': 'talos', 09:04:53 INFO - 'log_to_console': True, 09:04:53 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 09:04:53 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 09:04:53 INFO - 'opt_config_files': (), 09:04:53 INFO - 'pip_index': False, 09:04:53 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:04:53 INFO - 'cmd': ('bash', 09:04:53 INFO - '-c', 09:04:53 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:04:53 INFO - 'enabled': True, 09:04:53 INFO - 'halt_on_failure': False, 09:04:53 INFO - 'name': 'check_screen_resolution'},), 09:04:53 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:04:53 INFO - 'cmd': ('bash', 09:04:53 INFO - '-c', 09:04:53 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:04:53 INFO - 'enabled': True, 09:04:53 INFO - 'halt_on_failure': False, 09:04:53 INFO - 'name': 'check_screen_resolution'},), 09:04:53 INFO - 'run_cmd_checks_enabled': True, 09:04:53 INFO - 'suite': 'dromaeojs-e10s', 09:04:53 INFO - 'system_bits': '32', 09:04:53 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:04:53 INFO - 'title': 't-yosemite-r7-0316', 09:04:53 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:04:53 INFO - 'use_talos_json': True, 09:04:53 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:04:53 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:04:53 INFO - 'work_dir': 'build'} 09:04:53 INFO - [mozharness: 2017-06-08 16:04:53.892083Z] Running clobber step. 09:04:53 INFO - Running pre-action listener: _resource_record_pre_action 09:04:53 INFO - Running main action method: clobber 09:04:53 INFO - rmtree: /builds/slave/test/build 09:04:53 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:04:54 INFO - Running post-action listener: _resource_record_post_action 09:04:54 INFO - [mozharness: 2017-06-08 16:04:54.061556Z] Finished clobber step (success) 09:04:54 INFO - [mozharness: 2017-06-08 16:04:54.061718Z] Running read-buildbot-config step. 09:04:54 INFO - Running pre-action listener: _resource_record_pre_action 09:04:54 INFO - Running main action method: read_buildbot_config 09:04:54 INFO - Using buildbot properties: 09:04:54 INFO - { 09:04:54 INFO - "project": "", 09:04:54 INFO - "product": "firefox", 09:04:54 INFO - "who": "mconley@mozilla.com", 09:04:54 INFO - "installer_path": "public/build/firefox-55.0a1.en-US.mac.dmg", 09:04:54 INFO - "builddir": "cedar_yosemite_r7_test-dromaeojs-e10s", 09:04:54 INFO - "repository": "https://hg.mozilla.org/projects/cedar/", 09:04:54 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 cedar talos dromaeojs-e10s", 09:04:54 INFO - "stage_platform": "macosx64", 09:04:54 INFO - "basedir": "/builds/slave/test", 09:04:54 INFO - "buildnumber": 1, 09:04:54 INFO - "platform": "yosemite_r7", 09:04:54 INFO - "parent_task_id": "H5-1TngUQ0eCUF0rZe-uxw", 09:04:54 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 09:04:54 INFO - "slavebuilddir": "test", 09:04:54 INFO - "taskId": "IBy5f8XNTOaHlErHQRrtzg", 09:04:54 INFO - "branch": "cedar", 09:04:54 INFO - "script_repo_revision": "default", 09:04:54 INFO - "revision": "50d53cf0e52cd201878cf19b4f9d540c69a6c3a4", 09:04:54 INFO - "slavename": "t-yosemite-r7-0316", 09:04:54 INFO - "repo_path": "projects/cedar" 09:04:54 INFO - } 09:04:54 INFO - Finding installer, test and symbols from parent task. 09:04:54 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/IBy5f8XNTOaHlErHQRrtzg'}, attempt #1 09:04:54 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/IBy5f8XNTOaHlErHQRrtzg'}, attempt #1 09:04:55 INFO - Task dependencies: Gro4L2doSuSZ-RM5sfIgTA H5-1TngUQ0eCUF0rZe-uxw 09:04:55 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/H5-1TngUQ0eCUF0rZe-uxw'}, attempt #1 09:04:55 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/H5-1TngUQ0eCUF0rZe-uxw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 09:04:55 INFO - Set test_url: https://queue.taskcluster.net/v1/task/H5-1TngUQ0eCUF0rZe-uxw/artifacts/public/build/test_packages.json 09:04:55 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/H5-1TngUQ0eCUF0rZe-uxw/artifacts/public/build/target.crashreporter-symbols.zip 09:04:55 INFO - Running post-action listener: _resource_record_post_action 09:04:55 INFO - [mozharness: 2017-06-08 16:04:55.787095Z] Finished read-buildbot-config step (success) 09:04:55 INFO - [mozharness: 2017-06-08 16:04:55.787220Z] Running download-and-extract step. 09:04:55 INFO - Running pre-action listener: _resource_record_pre_action 09:04:55 INFO - Running main action method: download_and_extract 09:04:55 INFO - mkdir: /builds/slave/test/build/tests 09:04:55 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')]} 09:04:55 INFO - trying https://queue.taskcluster.net/v1/task/H5-1TngUQ0eCUF0rZe-uxw/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 09:04:55 INFO - Downloading https://queue.taskcluster.net/v1/task/H5-1TngUQ0eCUF0rZe-uxw/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 09:04:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/H5-1TngUQ0eCUF0rZe-uxw/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 09:04:56 INFO - Downloaded 1328 bytes. 09:04:56 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 09:04:56 INFO - Using the following test package requirements: 09:04:56 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:04:56 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 09:04:56 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 09:04:56 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:04:56 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 09:04:56 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:04:56 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 09:04:56 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:04:56 INFO - u'jsshell-mac.zip'], 09:04:56 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:04:56 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 09:04:56 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 09:04:56 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:04:56 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 09:04:56 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:04:56 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 09:04:56 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:04:56 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.tar.gz'], 09:04:56 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:04:56 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 09:04:56 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 09:04:56 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/H5-1TngUQ0eCUF0rZe-uxw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 09:04:56 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/H5-1TngUQ0eCUF0rZe-uxw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 09:04:56 INFO - Fetch https://queue.taskcluster.net/v1/task/H5-1TngUQ0eCUF0rZe-uxw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 09:04:58 INFO - Content-Length response header: 59548805 09:04:58 INFO - Bytes received: 59548805 09:05:16 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 09:05:16 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/H5-1TngUQ0eCUF0rZe-uxw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 09:05:16 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/H5-1TngUQ0eCUF0rZe-uxw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 09:05:16 INFO - Fetch https://queue.taskcluster.net/v1/task/H5-1TngUQ0eCUF0rZe-uxw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 09:05:19 INFO - Content-Length response header: 59548805 09:05:19 INFO - Bytes received: 59548805 09:05:35 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/H5-1TngUQ0eCUF0rZe-uxw/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 09:05:35 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/H5-1TngUQ0eCUF0rZe-uxw/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 09:05:35 INFO - Fetch https://queue.taskcluster.net/v1/task/H5-1TngUQ0eCUF0rZe-uxw/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 09:05:37 INFO - Content-Length response header: 14447568 09:05:37 INFO - Bytes received: 14447568 09:05:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:05:37 INFO - trying https://queue.taskcluster.net/v1/task/H5-1TngUQ0eCUF0rZe-uxw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 09:05:37 INFO - Downloading https://queue.taskcluster.net/v1/task/H5-1TngUQ0eCUF0rZe-uxw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 09:05:37 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/H5-1TngUQ0eCUF0rZe-uxw/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 09:05:39 INFO - Downloaded 68127400 bytes. 09:05:39 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/H5-1TngUQ0eCUF0rZe-uxw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 09:05:39 INFO - mkdir: /builds/slave/test/properties 09:05:39 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:05:39 INFO - Writing to file /builds/slave/test/properties/build_url 09:05:39 INFO - Contents: 09:05:39 INFO - build_url:https://queue.taskcluster.net/v1/task/H5-1TngUQ0eCUF0rZe-uxw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 09:05:39 INFO - Running post-action listener: _resource_record_post_action 09:05:39 INFO - Running post-action listener: set_extra_try_arguments 09:05:39 INFO - [mozharness: 2017-06-08 16:05:39.598953Z] Finished download-and-extract step (success) 09:05:39 INFO - [mozharness: 2017-06-08 16:05:39.599043Z] Running populate-webroot step. 09:05:39 INFO - Running pre-action listener: _resource_record_pre_action 09:05:39 INFO - Running main action method: populate_webroot 09:05:39 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:05:39 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:05:39 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:05:39 INFO - '--tppagecycles', 09:05:39 INFO - '7'], 09:05:39 INFO - 'tests': ['tsvgm']}}, 09:05:39 INFO - 'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:05:39 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:05:39 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:05:39 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:05:39 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 09:05:39 INFO - 'talos_options': ['--disable-e10s'], 09:05:39 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:05:39 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 09:05:39 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:05:39 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 09:05:39 INFO - 'talos_options': ['--disable-e10s'], 09:05:39 INFO - 'tests': ['damp', 'tps']}, 09:05:39 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 09:05:39 INFO - 'tests': ['damp', 'tps']}, 09:05:39 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:05:39 INFO - 'tests': ['dromaeo_dom']}, 09:05:39 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:05:39 INFO - 'g4': {'talos_options': ['--disable-e10s'], 09:05:39 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 09:05:39 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 09:05:39 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 09:05:39 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 09:05:39 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:05:39 INFO - 'tests': ['a11yr', 09:05:39 INFO - 'ts_paint', 09:05:39 INFO - 'tpaint', 09:05:39 INFO - 'sessionrestore', 09:05:39 INFO - 'sessionrestore_no_auto_restore', 09:05:39 INFO - 'tabpaint']}, 09:05:39 INFO - 'other-e10s': {'tests': ['a11yr', 09:05:39 INFO - 'ts_paint', 09:05:39 INFO - 'tpaint', 09:05:39 INFO - 'sessionrestore', 09:05:39 INFO - 'sessionrestore_no_auto_restore', 09:05:39 INFO - 'tabpaint']}, 09:05:39 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:05:39 INFO - 'ts_paint', 09:05:39 INFO - 'tpaint', 09:05:39 INFO - 'sessionrestore', 09:05:39 INFO - 'sessionrestore_no_auto_restore', 09:05:39 INFO - 'tabpaint']}, 09:05:39 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:05:39 INFO - 'ts_paint', 09:05:39 INFO - 'tpaint', 09:05:39 INFO - 'sessionrestore', 09:05:39 INFO - 'sessionrestore_no_auto_restore', 09:05:39 INFO - 'tabpaint']}, 09:05:39 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:05:39 INFO - 'tests': ['a11yr', 09:05:39 INFO - 'ts_paint', 09:05:39 INFO - 'tpaint', 09:05:39 INFO - 'sessionrestore', 09:05:39 INFO - 'sessionrestore_no_auto_restore', 09:05:39 INFO - 'tabpaint']}, 09:05:39 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:05:39 INFO - 'tests': ['a11yr', 09:05:39 INFO - 'ts_paint', 09:05:39 INFO - 'tpaint', 09:05:39 INFO - 'sessionrestore', 09:05:39 INFO - 'sessionrestore_no_auto_restore', 09:05:39 INFO - 'tabpaint']}, 09:05:39 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 09:05:39 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 09:05:39 INFO - 'bloom_basic_ref']}, 09:05:39 INFO - 'quantum-pageload-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set.zip', 09:05:39 INFO - 'talos_options': ['--mitmproxy', 09:05:39 INFO - 'mitmproxy-recording-1.mp', 09:05:39 INFO - '--firstNonBlankPaint'], 09:05:39 INFO - 'tests': ['Quantum_1']}, 09:05:39 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:05:39 INFO - 'tests': ['tsvgx', 09:05:39 INFO - 'tsvgr_opacity', 09:05:39 INFO - 'tart', 09:05:39 INFO - 'tscrollx', 09:05:39 INFO - 'cart', 09:05:39 INFO - 'tsvg_static']}, 09:05:39 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:05:39 INFO - 'tsvgr_opacity', 09:05:39 INFO - 'tart', 09:05:39 INFO - 'tscrollx', 09:05:39 INFO - 'cart', 09:05:39 INFO - 'tsvg_static']}, 09:05:39 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 09:05:39 INFO - 'talos_options': ['--disable-e10s'], 09:05:39 INFO - 'tests': ['tp5o']}, 09:05:39 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 09:05:39 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 09:05:39 INFO - 'talos_options': ['--disable-e10s', 09:05:39 INFO - '--xperf_path', 09:05:39 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:05:39 INFO - 'tests': ['tp5n']}, 09:05:39 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 09:05:39 INFO - 'talos_options': ['--xperf_path', 09:05:39 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:05:39 INFO - 'tests': ['tp5n']}}, 09:05:39 INFO - 'talos.zip': {'path': '', 09:05:39 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:05:39 INFO - Running post-action listener: _resource_record_post_action 09:05:39 INFO - [mozharness: 2017-06-08 16:05:39.620662Z] Finished populate-webroot step (success) 09:05:39 INFO - [mozharness: 2017-06-08 16:05:39.620768Z] Running create-virtualenv step. 09:05:39 INFO - Running pre-action listener: _resource_record_pre_action 09:05:39 INFO - Running main action method: create_virtualenv 09:05:39 INFO - Creating virtualenv /builds/slave/test/build/venv 09:05:39 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 09:05:39 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:05:39 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 09:05:39 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:05:39 INFO - Using real prefix '/tools/python27' 09:05:39 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:05:40 INFO - Installing distribute.............................................................................................................................................................................................done. 09:05:43 INFO - Installing pip.................done. 09:05:43 INFO - Return code: 0 09:05:43 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 09:05:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 09:05:43 INFO - Reading from file tmpfile_stdout 09:05:43 INFO - Output received: 09:05:43 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) 09:05:43 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:05:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:05:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:05:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:05:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:05:43 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 0x10b15ac10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b15e768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbdca4d6fd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aeb0d30>, '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 0x10ae7acd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10aea1be8>, '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.qnpBEZjfrH/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.SqnH5amz20/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tg/9cs16h3j7pn5rd3v0kkz9lgc00000w/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 09:05:43 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 09:05:43 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 09:05:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qnpBEZjfrH/Render', 09:05:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:05:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:05:43 INFO - 'HOME': '/Users/cltbld', 09:05:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:05:43 INFO - 'LOGNAME': 'cltbld', 09:05:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:05:43 INFO - 'MOZ_NO_REMOTE': '1', 09:05:43 INFO - 'NO_EM_RESTART': '1', 09:05:43 INFO - 'PAGER': '/bin/cat', 09:05:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:05:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:05:43 INFO - 'PWD': '/builds/slave/test', 09:05:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:05:43 INFO - 'SHELL': '/bin/bash', 09:05:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SqnH5amz20/Listeners', 09:05:43 INFO - 'TMPDIR': '/var/folders/tg/9cs16h3j7pn5rd3v0kkz9lgc00000w/T/', 09:05:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:05:43 INFO - 'USER': 'cltbld', 09:05:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:05:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:05:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:05:43 INFO - 'XPC_FLAGS': '0x0', 09:05:43 INFO - 'XPC_SERVICE_NAME': '0', 09:05:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:05:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:05:44 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 09:05:44 INFO - Cleaning up... 09:05:44 INFO - Return code: 0 09:05:44 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:05:44 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')]} 09:05:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:05:44 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')]} 09:05:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:05:44 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 0x10b15ac10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b15e768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbdca4d6fd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aeb0d30>, '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 0x10ae7acd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10aea1be8>, '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.qnpBEZjfrH/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.SqnH5amz20/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tg/9cs16h3j7pn5rd3v0kkz9lgc00000w/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 09:05:44 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 09:05:44 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 09:05:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qnpBEZjfrH/Render', 09:05:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:05:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:05:44 INFO - 'HOME': '/Users/cltbld', 09:05:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:05:44 INFO - 'LOGNAME': 'cltbld', 09:05:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:05:44 INFO - 'MOZ_NO_REMOTE': '1', 09:05:44 INFO - 'NO_EM_RESTART': '1', 09:05:44 INFO - 'PAGER': '/bin/cat', 09:05:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:05:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:05:44 INFO - 'PWD': '/builds/slave/test', 09:05:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:05:44 INFO - 'SHELL': '/bin/bash', 09:05:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SqnH5amz20/Listeners', 09:05:44 INFO - 'TMPDIR': '/var/folders/tg/9cs16h3j7pn5rd3v0kkz9lgc00000w/T/', 09:05:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:05:44 INFO - 'USER': 'cltbld', 09:05:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:05:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:05:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:05:44 INFO - 'XPC_FLAGS': '0x0', 09:05:44 INFO - 'XPC_SERVICE_NAME': '0', 09:05:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:05:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:05:44 INFO - Downloading/unpacking psutil>=3.1.1 09:05:44 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 09:05:44 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 09:05:44 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 09:05:44 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 09:05:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:05:46 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:05:46 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:05:46 INFO - Installing collected packages: psutil 09:05:46 INFO - Running setup.py install for psutil 09:05:46 INFO - building 'psutil._psutil_osx' extension 09:05:46 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 09:05:46 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 09:05:46 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 09:05:46 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 09:05:46 INFO - building 'psutil._psutil_posix' extension 09:05:46 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 09:05:46 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 09:05:46 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 09:05:46 INFO - ^ 09:05:46 INFO - 1 warning generated. 09:05: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 09:05:46 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:05:46 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:05:47 INFO - Successfully installed psutil 09:05:47 INFO - Cleaning up... 09:05:47 INFO - Return code: 0 09:05:47 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:05:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:05:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:05:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:05:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:05:47 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10b15ac10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b15e768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbdca4d6fd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aeb0d30>, '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 0x10ae7acd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10aea1be8>, '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.qnpBEZjfrH/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.SqnH5amz20/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tg/9cs16h3j7pn5rd3v0kkz9lgc00000w/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 09:05:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 09:05:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 09:05:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qnpBEZjfrH/Render', 09:05:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:05:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:05:47 INFO - 'HOME': '/Users/cltbld', 09:05:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:05:47 INFO - 'LOGNAME': 'cltbld', 09:05:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:05:47 INFO - 'MOZ_NO_REMOTE': '1', 09:05:47 INFO - 'NO_EM_RESTART': '1', 09:05:47 INFO - 'PAGER': '/bin/cat', 09:05:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:05:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:05:47 INFO - 'PWD': '/builds/slave/test', 09:05:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:05:47 INFO - 'SHELL': '/bin/bash', 09:05:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SqnH5amz20/Listeners', 09:05:47 INFO - 'TMPDIR': '/var/folders/tg/9cs16h3j7pn5rd3v0kkz9lgc00000w/T/', 09:05:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:05:47 INFO - 'USER': 'cltbld', 09:05:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:05:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:05:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:05:47 INFO - 'XPC_FLAGS': '0x0', 09:05:47 INFO - 'XPC_SERVICE_NAME': '0', 09:05:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:05:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:05:47 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:05:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:05:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:05:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:05:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:05:48 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:05:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:05:48 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:05:48 INFO - Installing collected packages: mozsystemmonitor 09:05:48 INFO - Running setup.py install for mozsystemmonitor 09:05:48 INFO - Successfully installed mozsystemmonitor 09:05:48 INFO - Cleaning up... 09:05:48 INFO - Return code: 0 09:05:48 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:05: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')]} 09:05:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:05:49 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')]} 09:05:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:05:49 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 0x10b15ac10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b15e768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbdca4d6fd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aeb0d30>, '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 0x10ae7acd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10aea1be8>, '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.qnpBEZjfrH/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.SqnH5amz20/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tg/9cs16h3j7pn5rd3v0kkz9lgc00000w/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 09:05:49 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 09:05:49 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 09:05:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qnpBEZjfrH/Render', 09:05:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:05:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:05:49 INFO - 'HOME': '/Users/cltbld', 09:05:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:05:49 INFO - 'LOGNAME': 'cltbld', 09:05:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:05:49 INFO - 'MOZ_NO_REMOTE': '1', 09:05:49 INFO - 'NO_EM_RESTART': '1', 09:05:49 INFO - 'PAGER': '/bin/cat', 09:05:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:05:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:05:49 INFO - 'PWD': '/builds/slave/test', 09:05:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:05:49 INFO - 'SHELL': '/bin/bash', 09:05:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SqnH5amz20/Listeners', 09:05:49 INFO - 'TMPDIR': '/var/folders/tg/9cs16h3j7pn5rd3v0kkz9lgc00000w/T/', 09:05:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:05:49 INFO - 'USER': 'cltbld', 09:05:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:05:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:05:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:05:49 INFO - 'XPC_FLAGS': '0x0', 09:05:49 INFO - 'XPC_SERVICE_NAME': '0', 09:05:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:05:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:05:50 INFO - Downloading/unpacking jsonschema==2.5.1 09:05: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 09:05: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 09:05: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 09:05: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 09:05:51 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:05:51 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:05:51 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 09:05:51 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 09:05:51 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 09:05:51 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 09:05:52 INFO - Downloading functools32-3.2.3-2.tar.gz 09:05:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:05:52 INFO - warning: no files found matching '*.txt' 09:05:52 INFO - no previously-included directories found matching 'build' 09:05:52 INFO - no previously-included directories found matching 'dist' 09:05:52 INFO - no previously-included directories found matching '.git*' 09:05:52 INFO - Installing collected packages: jsonschema, functools32 09:05:52 INFO - Running setup.py install for functools32 09:05:52 INFO - warning: no files found matching '*.txt' 09:05:52 INFO - no previously-included directories found matching 'build' 09:05:52 INFO - no previously-included directories found matching 'dist' 09:05:52 INFO - no previously-included directories found matching '.git*' 09:05:52 INFO - Successfully installed jsonschema functools32 09:05:52 INFO - Cleaning up... 09:05:52 INFO - Return code: 0 09:05:52 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 09:05:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:05:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:05:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:05:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:05:52 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10b15ac10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b15e768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbdca4d6fd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aeb0d30>, '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 0x10ae7acd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10aea1be8>, '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.qnpBEZjfrH/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.SqnH5amz20/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tg/9cs16h3j7pn5rd3v0kkz9lgc00000w/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 09:05:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 09:05:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 09:05:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qnpBEZjfrH/Render', 09:05:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:05:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:05:52 INFO - 'HOME': '/Users/cltbld', 09:05:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:05:52 INFO - 'LOGNAME': 'cltbld', 09:05:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:05:52 INFO - 'MOZ_NO_REMOTE': '1', 09:05:52 INFO - 'NO_EM_RESTART': '1', 09:05:52 INFO - 'PAGER': '/bin/cat', 09:05:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:05:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:05:52 INFO - 'PWD': '/builds/slave/test', 09:05:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:05:52 INFO - 'SHELL': '/bin/bash', 09:05:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SqnH5amz20/Listeners', 09:05:52 INFO - 'TMPDIR': '/var/folders/tg/9cs16h3j7pn5rd3v0kkz9lgc00000w/T/', 09:05:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:05:52 INFO - 'USER': 'cltbld', 09:05:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:05:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:05:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:05:52 INFO - 'XPC_FLAGS': '0x0', 09:05:52 INFO - 'XPC_SERVICE_NAME': '0', 09:05:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:05:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:05:52 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 09:05:52 INFO - Cleaning up... 09:05:52 INFO - Return code: 0 09:05:52 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:05:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:05:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:05:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:05:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:05:52 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10b15ac10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b15e768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbdca4d6fd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aeb0d30>, '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 0x10ae7acd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10aea1be8>, '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.qnpBEZjfrH/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.SqnH5amz20/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tg/9cs16h3j7pn5rd3v0kkz9lgc00000w/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 09:05:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 09:05:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 09:05:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qnpBEZjfrH/Render', 09:05:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:05:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:05:52 INFO - 'HOME': '/Users/cltbld', 09:05:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:05:52 INFO - 'LOGNAME': 'cltbld', 09:05:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:05:52 INFO - 'MOZ_NO_REMOTE': '1', 09:05:52 INFO - 'NO_EM_RESTART': '1', 09:05:52 INFO - 'PAGER': '/bin/cat', 09:05:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:05:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:05:52 INFO - 'PWD': '/builds/slave/test', 09:05:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:05:52 INFO - 'SHELL': '/bin/bash', 09:05:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SqnH5amz20/Listeners', 09:05:52 INFO - 'TMPDIR': '/var/folders/tg/9cs16h3j7pn5rd3v0kkz9lgc00000w/T/', 09:05:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:05:52 INFO - 'USER': 'cltbld', 09:05:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:05:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:05:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:05:52 INFO - 'XPC_FLAGS': '0x0', 09:05:52 INFO - 'XPC_SERVICE_NAME': '0', 09:05:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:05:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:05:52 INFO - Downloading/unpacking blobuploader==1.2.4 09:05: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 09:05: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 09:05: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 09:05: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 09:05:54 INFO - Downloading blobuploader-1.2.4.tar.gz 09:05:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:05:54 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:05:54 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 09:05:54 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 09:05:54 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 09:05:54 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 09:05:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:05:54 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:05:54 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 09:05:54 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 09:05:54 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 09:05:54 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 09:05:55 INFO - Downloading docopt-0.6.1.tar.gz 09:05:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:05:55 INFO - Installing collected packages: blobuploader, requests, docopt 09:05:55 INFO - Running setup.py install for blobuploader 09:05:55 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:05:55 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:05:55 INFO - Running setup.py install for requests 09:05:55 INFO - Running setup.py install for docopt 09:05:56 INFO - Successfully installed blobuploader requests docopt 09:05:56 INFO - Cleaning up... 09:05:56 INFO - Return code: 0 09:05:56 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:05:56 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')]} 09:05:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:05:56 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')]} 09:05:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:05:56 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 0x10b15ac10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b15e768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbdca4d6fd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aeb0d30>, '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 0x10ae7acd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10aea1be8>, '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.qnpBEZjfrH/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.SqnH5amz20/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tg/9cs16h3j7pn5rd3v0kkz9lgc00000w/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 09:05:56 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 09:05:56 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 09:05:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qnpBEZjfrH/Render', 09:05:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:05:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:05:56 INFO - 'HOME': '/Users/cltbld', 09:05:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:05:56 INFO - 'LOGNAME': 'cltbld', 09:05:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:05:56 INFO - 'MOZ_NO_REMOTE': '1', 09:05:56 INFO - 'NO_EM_RESTART': '1', 09:05:56 INFO - 'PAGER': '/bin/cat', 09:05:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:05:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:05:56 INFO - 'PWD': '/builds/slave/test', 09:05:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:05:56 INFO - 'SHELL': '/bin/bash', 09:05:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SqnH5amz20/Listeners', 09:05:56 INFO - 'TMPDIR': '/var/folders/tg/9cs16h3j7pn5rd3v0kkz9lgc00000w/T/', 09:05:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:05:56 INFO - 'USER': 'cltbld', 09:05:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:05:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:05:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:05:56 INFO - 'XPC_FLAGS': '0x0', 09:05:56 INFO - 'XPC_SERVICE_NAME': '0', 09:05:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:05:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:05:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:05:56 INFO - Running setup.py (path:/var/folders/tg/9cs16h3j7pn5rd3v0kkz9lgc00000w/T/pip-qm_C1v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:05:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:05:56 INFO - Running setup.py (path:/var/folders/tg/9cs16h3j7pn5rd3v0kkz9lgc00000w/T/pip-cUVE27-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:05:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:05:56 INFO - Running setup.py (path:/var/folders/tg/9cs16h3j7pn5rd3v0kkz9lgc00000w/T/pip-u1EjWd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:05:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:05:56 INFO - Running setup.py (path:/var/folders/tg/9cs16h3j7pn5rd3v0kkz9lgc00000w/T/pip-B9q4ae-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:05:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:05:56 INFO - Running setup.py (path:/var/folders/tg/9cs16h3j7pn5rd3v0kkz9lgc00000w/T/pip-1_Rk4U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:05:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:05:56 INFO - Running setup.py (path:/var/folders/tg/9cs16h3j7pn5rd3v0kkz9lgc00000w/T/pip-0GkrtA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:05:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:05:57 INFO - Running setup.py (path:/var/folders/tg/9cs16h3j7pn5rd3v0kkz9lgc00000w/T/pip-m4MHxw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:05:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:05:57 INFO - Running setup.py (path:/var/folders/tg/9cs16h3j7pn5rd3v0kkz9lgc00000w/T/pip-J8qN76-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:05:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:05:57 INFO - Running setup.py (path:/var/folders/tg/9cs16h3j7pn5rd3v0kkz9lgc00000w/T/pip-oO0MHM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:05:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:05:57 INFO - Running setup.py (path:/var/folders/tg/9cs16h3j7pn5rd3v0kkz9lgc00000w/T/pip-2K54CC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:05:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:05:57 INFO - Running setup.py (path:/var/folders/tg/9cs16h3j7pn5rd3v0kkz9lgc00000w/T/pip-AtVtLR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:05:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:05:57 INFO - Running setup.py (path:/var/folders/tg/9cs16h3j7pn5rd3v0kkz9lgc00000w/T/pip-sdbhPu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:05:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:05:57 INFO - Running setup.py (path:/var/folders/tg/9cs16h3j7pn5rd3v0kkz9lgc00000w/T/pip-iT9YI9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:05:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:05:57 INFO - Running setup.py (path:/var/folders/tg/9cs16h3j7pn5rd3v0kkz9lgc00000w/T/pip-2X9Vci-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:05:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:05:57 INFO - Running setup.py (path:/var/folders/tg/9cs16h3j7pn5rd3v0kkz9lgc00000w/T/pip-9pqSrm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:05:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:05:57 INFO - Running setup.py (path:/var/folders/tg/9cs16h3j7pn5rd3v0kkz9lgc00000w/T/pip-_dCpeO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:05:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:05:58 INFO - Running setup.py (path:/var/folders/tg/9cs16h3j7pn5rd3v0kkz9lgc00000w/T/pip-SoDXpS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:05:58 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:05:58 INFO - Running setup.py install for manifestparser 09:05:58 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:05:58 INFO - Running setup.py install for mozcrash 09:05:58 INFO - Running setup.py install for mozdebug 09:05:58 INFO - Running setup.py install for mozdevice 09:05:58 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:05:58 INFO - Running setup.py install for mozfile 09:05:58 INFO - Running setup.py install for mozhttpd 09:05:59 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:05:59 INFO - Running setup.py install for mozinfo 09:05:59 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:05:59 INFO - Running setup.py install for mozInstall 09:05:59 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:05:59 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:05:59 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:05:59 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:05:59 INFO - Running setup.py install for mozleak 09:05:59 INFO - Running setup.py install for mozlog 09:05:59 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:05:59 INFO - Running setup.py install for moznetwork 09:05:59 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:05:59 INFO - Running setup.py install for mozprocess 09:05:59 INFO - Running setup.py install for mozprofile 09:06:00 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:06:00 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:06:00 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:06:00 INFO - Running setup.py install for mozrunner 09:06:00 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:06:00 INFO - Running setup.py install for mozscreenshot 09:06:00 INFO - Running setup.py install for moztest 09:06:00 INFO - Running setup.py install for mozversion 09:06:00 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:06:00 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:06:00 INFO - Cleaning up... 09:06:00 INFO - Return code: 0 09:06:00 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:06:00 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')]} 09:06:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:06:00 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')]} 09:06:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:06:00 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 0x10b15ac10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b15e768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbdca4d6fd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aeb0d30>, '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 0x10ae7acd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10aea1be8>, '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.qnpBEZjfrH/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.SqnH5amz20/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tg/9cs16h3j7pn5rd3v0kkz9lgc00000w/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 09:06:00 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 09:06:00 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 09:06:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qnpBEZjfrH/Render', 09:06:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:06:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:06:00 INFO - 'HOME': '/Users/cltbld', 09:06:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:06:00 INFO - 'LOGNAME': 'cltbld', 09:06:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:06:00 INFO - 'MOZ_NO_REMOTE': '1', 09:06:00 INFO - 'NO_EM_RESTART': '1', 09:06:00 INFO - 'PAGER': '/bin/cat', 09:06:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:06:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:06:00 INFO - 'PWD': '/builds/slave/test', 09:06:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:06:00 INFO - 'SHELL': '/bin/bash', 09:06:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SqnH5amz20/Listeners', 09:06:00 INFO - 'TMPDIR': '/var/folders/tg/9cs16h3j7pn5rd3v0kkz9lgc00000w/T/', 09:06:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:06:00 INFO - 'USER': 'cltbld', 09:06:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:06:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:06:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:06:00 INFO - 'XPC_FLAGS': '0x0', 09:06:00 INFO - 'XPC_SERVICE_NAME': '0', 09:06:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:06:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:06:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:06:01 INFO - Running setup.py (path:/var/folders/tg/9cs16h3j7pn5rd3v0kkz9lgc00000w/T/pip-g9xVI_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:06:01 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)) 09:06:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:06:01 INFO - Running setup.py (path:/var/folders/tg/9cs16h3j7pn5rd3v0kkz9lgc00000w/T/pip-nPTXpX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:06:01 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)) 09:06:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:06:01 INFO - Running setup.py (path:/var/folders/tg/9cs16h3j7pn5rd3v0kkz9lgc00000w/T/pip-uSx7v9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:06:01 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)) 09:06:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:06:01 INFO - Running setup.py (path:/var/folders/tg/9cs16h3j7pn5rd3v0kkz9lgc00000w/T/pip-3cmg7u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:06:01 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)) 09:06:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:06:01 INFO - Running setup.py (path:/var/folders/tg/9cs16h3j7pn5rd3v0kkz9lgc00000w/T/pip-Ilt2Qq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:06:01 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)) 09:06:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:06:01 INFO - Running setup.py (path:/var/folders/tg/9cs16h3j7pn5rd3v0kkz9lgc00000w/T/pip-qz0Ie9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:06:01 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)) 09:06:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:06:01 INFO - Running setup.py (path:/var/folders/tg/9cs16h3j7pn5rd3v0kkz9lgc00000w/T/pip-mXwpEw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:06:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.10 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)) 09:06:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:06:01 INFO - Running setup.py (path:/var/folders/tg/9cs16h3j7pn5rd3v0kkz9lgc00000w/T/pip-prfhDI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:06:01 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)) 09:06:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:06:01 INFO - Running setup.py (path:/var/folders/tg/9cs16h3j7pn5rd3v0kkz9lgc00000w/T/pip-YA3mqW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:06:01 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)) 09:06:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:06:01 INFO - Running setup.py (path:/var/folders/tg/9cs16h3j7pn5rd3v0kkz9lgc00000w/T/pip-GHOb3z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:06:02 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)) 09:06:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:06:02 INFO - Running setup.py (path:/var/folders/tg/9cs16h3j7pn5rd3v0kkz9lgc00000w/T/pip-l05Q6r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:06:02 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)) 09:06:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:06:02 INFO - Running setup.py (path:/var/folders/tg/9cs16h3j7pn5rd3v0kkz9lgc00000w/T/pip-vfvcsF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:06:02 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)) 09:06:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:06:02 INFO - Running setup.py (path:/var/folders/tg/9cs16h3j7pn5rd3v0kkz9lgc00000w/T/pip-SyR5jP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:06:02 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)) 09:06:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:06:02 INFO - Running setup.py (path:/var/folders/tg/9cs16h3j7pn5rd3v0kkz9lgc00000w/T/pip-2nLzyD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:06:02 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)) 09:06:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:06:02 INFO - Running setup.py (path:/var/folders/tg/9cs16h3j7pn5rd3v0kkz9lgc00000w/T/pip-L6pnbp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:06:02 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)) 09:06:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:06:02 INFO - Running setup.py (path:/var/folders/tg/9cs16h3j7pn5rd3v0kkz9lgc00000w/T/pip-oAPH9R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:06:02 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)) 09:06:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:06:02 INFO - Running setup.py (path:/var/folders/tg/9cs16h3j7pn5rd3v0kkz9lgc00000w/T/pip-sMxO3r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:06:02 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)) 09:06:02 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)) 09:06:02 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)) 09:06:02 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)) 09:06:02 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)) 09:06:02 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)) 09:06:02 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:06:02 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 09:06:02 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 09:06:02 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 09:06:02 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 09:06:04 INFO - Downloading blessings-1.6.tar.gz 09:06:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:06:04 INFO - Installing collected packages: blessings 09:06:04 INFO - Running setup.py install for blessings 09:06:04 INFO - Successfully installed blessings 09:06:04 INFO - Cleaning up... 09:06:04 INFO - Return code: 0 09:06:04 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:06:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:06:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:06:04 INFO - Reading from file tmpfile_stdout 09:06:04 INFO - Current package versions: 09:06:04 INFO - blessings == 1.6 09:06:04 INFO - blobuploader == 1.2.4 09:06:04 INFO - docopt == 0.6.1 09:06:04 INFO - functools32 == 3.2.3-2 09:06:04 INFO - jsonschema == 2.5.1 09:06:04 INFO - manifestparser == 1.1 09:06:04 INFO - mozInstall == 1.12 09:06:04 INFO - mozcrash == 1.0 09:06:04 INFO - mozdebug == 0.1 09:06:04 INFO - mozdevice == 0.51 09:06:04 INFO - mozfile == 1.2 09:06:04 INFO - mozhttpd == 0.7 09:06:04 INFO - mozinfo == 0.10 09:06:04 INFO - mozleak == 0.1 09:06:04 INFO - mozlog == 3.4 09:06:04 INFO - moznetwork == 0.27 09:06:04 INFO - mozprocess == 0.25 09:06:04 INFO - mozprofile == 0.28 09:06:04 INFO - mozrunner == 6.13 09:06:04 INFO - mozscreenshot == 0.1 09:06:04 INFO - mozsystemmonitor == 0.3 09:06:04 INFO - moztest == 0.8 09:06:04 INFO - mozversion == 1.4 09:06:04 INFO - psutil == 3.1.1 09:06:04 INFO - requests == 1.2.3 09:06:04 INFO - wsgiref == 0.1.2 09:06:04 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:06: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')]} 09:06:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:06: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')]} 09:06:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:06:04 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 0x10b15ac10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b15e768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbdca4d6fd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aeb0d30>, '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 0x10ae7acd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10aea1be8>, '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.qnpBEZjfrH/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.SqnH5amz20/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tg/9cs16h3j7pn5rd3v0kkz9lgc00000w/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 09:06:04 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 09:06:04 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 09:06:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qnpBEZjfrH/Render', 09:06:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:06:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:06:04 INFO - 'HOME': '/Users/cltbld', 09:06:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:06:04 INFO - 'LOGNAME': 'cltbld', 09:06:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:06:04 INFO - 'MOZ_NO_REMOTE': '1', 09:06:04 INFO - 'NO_EM_RESTART': '1', 09:06:04 INFO - 'PAGER': '/bin/cat', 09:06:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:06:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:06:04 INFO - 'PWD': '/builds/slave/test', 09:06:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:06:04 INFO - 'SHELL': '/bin/bash', 09:06:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SqnH5amz20/Listeners', 09:06:04 INFO - 'TMPDIR': '/var/folders/tg/9cs16h3j7pn5rd3v0kkz9lgc00000w/T/', 09:06:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:06:04 INFO - 'USER': 'cltbld', 09:06:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:06:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:06:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:06:04 INFO - 'XPC_FLAGS': '0x0', 09:06:04 INFO - 'XPC_SERVICE_NAME': '0', 09:06:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:06:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:06:05 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)) 09:06:05 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)) 09:06:05 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)) 09:06:05 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)) 09:06:05 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)) 09:06:05 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)) 09:06:05 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)) 09:06:05 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)) 09:06:05 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)) 09:06:05 INFO - Downloading/unpacking marionette-driver>=1.4.0 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 09:06:05 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 09:06:05 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 09:06:05 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 09:06:05 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 09:06:06 INFO - Downloading marionette_driver-1.4.0.tar.gz 09:06:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/marionette-driver/setup.py) egg_info for package marionette-driver 09:06:06 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)) 09:06:06 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)) 09:06:06 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)) 09:06:06 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)) 09:06:06 INFO - Installing collected packages: marionette-driver 09:06:06 INFO - Running setup.py install for marionette-driver 09:06:06 INFO - Successfully installed marionette-driver 09:06:06 INFO - Cleaning up... 09:06:06 INFO - Return code: 0 09:06:06 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:06:06 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')]} 09:06:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:06:06 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')]} 09:06:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:06:06 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 0x10b15ac10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b15e768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbdca4d6fd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aeb0d30>, '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 0x10ae7acd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10aea1be8>, '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.qnpBEZjfrH/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.SqnH5amz20/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tg/9cs16h3j7pn5rd3v0kkz9lgc00000w/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 09:06:06 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 09:06:06 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 09:06:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qnpBEZjfrH/Render', 09:06:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:06:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:06:06 INFO - 'HOME': '/Users/cltbld', 09:06:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:06:06 INFO - 'LOGNAME': 'cltbld', 09:06:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:06:06 INFO - 'MOZ_NO_REMOTE': '1', 09:06:06 INFO - 'NO_EM_RESTART': '1', 09:06:06 INFO - 'PAGER': '/bin/cat', 09:06:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:06:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:06:06 INFO - 'PWD': '/builds/slave/test', 09:06:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:06:06 INFO - 'SHELL': '/bin/bash', 09:06:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SqnH5amz20/Listeners', 09:06:06 INFO - 'TMPDIR': '/var/folders/tg/9cs16h3j7pn5rd3v0kkz9lgc00000w/T/', 09:06:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:06:06 INFO - 'USER': 'cltbld', 09:06:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:06:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:06:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:06:06 INFO - 'XPC_FLAGS': '0x0', 09:06:06 INFO - 'XPC_SERVICE_NAME': '0', 09:06:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:06:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:06:07 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:06:07 INFO - Cleaning up... 09:06:07 INFO - Return code: 0 09:06:07 INFO - Running post-action listener: _resource_record_post_action 09:06:07 INFO - Running post-action listener: _start_resource_monitoring 09:06:07 INFO - Starting resource monitoring. 09:06:07 INFO - [mozharness: 2017-06-08 16:06:07.110563Z] Finished create-virtualenv step (success) 09:06:07 INFO - [mozharness: 2017-06-08 16:06:07.111703Z] Running install step. 09:06:07 INFO - Running pre-action listener: _resource_record_pre_action 09:06:07 INFO - Running main action method: install 09:06:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:06:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:06:07 INFO - Reading from file tmpfile_stdout 09:06:07 INFO - Detecting whether we're running mozinstall >=1.0... 09:06:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:06:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:06:07 INFO - Reading from file tmpfile_stdout 09:06:07 INFO - Output received: 09:06:07 INFO - Usage: mozinstall [options] installer 09:06:07 INFO - Options: 09:06:07 INFO - -h, --help show this help message and exit 09:06:07 INFO - -d DEST, --destination=DEST 09:06:07 INFO - Directory to install application into. [default: 09:06:07 INFO - "/builds/slave/test"] 09:06:07 INFO - --app=APP Application being installed. [default: firefox] 09:06:07 INFO - mkdir: /builds/slave/test/build/application 09:06:07 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'] 09:06:07 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 09:06:29 INFO - Reading from file tmpfile_stdout 09:06:29 INFO - Output received: 09:06:29 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 09:06:29 INFO - Running post-action listener: _resource_record_post_action 09:06:29 INFO - [mozharness: 2017-06-08 16:06:29.219863Z] Finished install step (success) 09:06:29 INFO - [mozharness: 2017-06-08 16:06:29.219983Z] Running setup-mitmproxy step. 09:06:29 INFO - Running pre-action listener: _resource_record_pre_action 09:06:29 INFO - Running main action method: setup_mitmproxy 09:06:29 INFO - Skipping: mitmproxy is not required 09:06:29 INFO - Running post-action listener: _resource_record_post_action 09:06:29 INFO - [mozharness: 2017-06-08 16:06:29.220572Z] Finished setup-mitmproxy step (success) 09:06:29 INFO - [mozharness: 2017-06-08 16:06:29.220701Z] Running run-tests step. 09:06:29 INFO - Running pre-action listener: _resource_record_pre_action 09:06:29 INFO - Running main action method: run_tests 09:06:29 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:06:29 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:06:29 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:06:29 INFO - 2017-06-08 09:06:29.235 screenresolution[1885:8774] starting screenresolution argv=screenresolution get 09:06:29 INFO - 2017-06-08 09:06:29.238 screenresolution[1885:8774] Display 0: 1600x1200x32@60 09:06:29 INFO - 2017-06-08 09:06:29.244 screenresolution[1886:8777] starting screenresolution argv=screenresolution list 09:06:29 INFO - Available Modes on Display 0 09:06:29 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:06:29 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:06:29 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:06:29 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:06:29 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:06:29 INFO - Intel Iris: 09:06:29 INFO - Chipset Model: Intel Iris 09:06:29 INFO - Type: GPU 09:06:29 INFO - Bus: Built-In 09:06:29 INFO - VRAM (Dynamic, Max): 1536 MB 09:06:29 INFO - Vendor: Intel (0x8086) 09:06:29 INFO - Device ID: 0x0a2e 09:06:29 INFO - Revision ID: 0x0009 09:06:29 INFO - Displays: 09:06:29 INFO - Display: 09:06:29 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:06:29 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:06:29 INFO - Display Serial Number: 1600x1200 60 09:06:29 INFO - Main Display: Yes 09:06:29 INFO - Mirror: Off 09:06:29 INFO - Online: Yes 09:06:29 INFO - Rotation: Supported 09:06:29 INFO - Return code: 0 09:06:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:06:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:06:29 INFO - Python 2.7.3 09:06:29 INFO - Return code: 0 09:06:29 INFO - grabbing minidump binary from tooltool 09:06:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:06:29 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 0x10aeb0d30>, '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 0x10ae7acd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10aea1be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:06:29 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 09:06:29 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 09:06:29 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 09:06:29 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:06:29 INFO - Return code: 0 09:06:29 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 09:06:29 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:06:29 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:06:29 INFO - ENV: RUST_BACKTRACE is now 1 09:06:29 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 09:06:29 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:06:29 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/H5-1TngUQ0eCUF0rZe-uxw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0316', '--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 09:06:29 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/H5-1TngUQ0eCUF0rZe-uxw/artifacts/public/build/target.crashreporter-symbols.zip --title t-yosemite-r7-0316 --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 09:06:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qnpBEZjfrH/Render', 09:06:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:06:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:06:29 INFO - 'HOME': '/Users/cltbld', 09:06:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:06:29 INFO - 'LOGNAME': 'cltbld', 09:06:29 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:06:29 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 09:06:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:06:29 INFO - 'MOZ_NO_REMOTE': '1', 09:06:29 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:06:29 INFO - 'NO_EM_RESTART': '1', 09:06:29 INFO - 'PAGER': '/bin/cat', 09:06:29 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:06:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:06:29 INFO - 'PWD': '/builds/slave/test', 09:06:29 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:06:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:06:29 INFO - 'RUST_BACKTRACE': '1', 09:06:29 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 09:06:29 INFO - 'SHELL': '/bin/bash', 09:06:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SqnH5amz20/Listeners', 09:06:29 INFO - 'TMPDIR': '/var/folders/tg/9cs16h3j7pn5rd3v0kkz9lgc00000w/T/', 09:06:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:06:29 INFO - 'USER': 'cltbld', 09:06:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:06:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:06:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:06:29 INFO - 'XPC_FLAGS': '0x0', 09:06:29 INFO - 'XPC_SERVICE_NAME': '0', 09:06:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:06:29 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/H5-1TngUQ0eCUF0rZe-uxw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0316', '--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 09:06:29 INFO - mozversion application_buildid: 20170608073855 09:06:29 INFO - mozversion application_changeset: 50d53cf0e52cd201878cf19b4f9d540c69a6c3a4 09:06:29 INFO - mozversion application_display_name: Nightly 09:06:29 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:06:29 INFO - mozversion application_name: Firefox 09:06:29 INFO - mozversion application_remotingname: firefox 09:06:29 INFO - mozversion application_repository: https://hg.mozilla.org/projects/cedar 09:06:29 INFO - mozversion application_vendor: Mozilla 09:06:29 INFO - mozversion application_version: 55.0a1 09:06:29 INFO - mozversion platform_buildid: 20170608073855 09:06:29 INFO - mozversion platform_changeset: 50d53cf0e52cd201878cf19b4f9d540c69a6c3a4 09:06:29 INFO - mozversion platform_repository: https://hg.mozilla.org/projects/cedar 09:06:29 INFO - mozversion platform_version: 55.0a1 09:06:29 INFO - using testdate: 1496937989 09:06:29 INFO - actual date: 1496937989 09:06:29 INFO - starting webserver on 'localhost:49354' 09:06:29 INFO - SUITE-START | Running 2 tests 09:06:29 INFO - TEST-START | dromaeo_css 09:06:29 INFO - operating with platform_type : mac_ 09:06:29 INFO - Initialising browser for dromaeo_css test... 09:06:29 INFO - Application command: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49354/getInfo.html -foreground -profile /var/folders/tg/9cs16h3j7pn5rd3v0kkz9lgc00000w/T/tmpcQ0SCU/profile 09:06:29 INFO - TEST-INFO | started process 1896 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49354/getInfo.html -foreground) 09:07:03 INFO - TEST-INFO | 1896: killed by SIGTERM 09:07:03 INFO - Browser initialized. 09:07:03 INFO - Running cycle 1/1 for dromaeo_css test... 09:07:03 INFO - TEST-INFO | started process 1903 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/tg/9cs16h3j7pn5rd3v0kkz9lgc00000w/T/tmpcQ0SCU/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:07:05 INFO - PID 1903 | 2017-06-08 09:07:05.808 plugin-container[1904:9255] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9743, name = 'com.apple.tsm.portname' 09:07:05 INFO - PID 1903 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:07:05 INFO - PID 1903 | 2017-06-08 09:07:05.808 plugin-container[1904:9255] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9e0b, name = 'com.apple.CFPasteboardClient' 09:07:05 INFO - PID 1903 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:07:05 INFO - PID 1903 | 2017-06-08 09:07:05.808 plugin-container[1904:9255] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:07:06 INFO - PID 1903 | 2017-06-08 09:07:06.251 plugin-container[1905:9302] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x953f, name = 'com.apple.tsm.portname' 09:07:06 INFO - PID 1903 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:07:06 INFO - PID 1903 | 2017-06-08 09:07:06.252 plugin-container[1905:9302] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4807, name = 'com.apple.CFPasteboardClient' 09:07:06 INFO - PID 1903 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:07:06 INFO - PID 1903 | 2017-06-08 09:07:06.252 plugin-container[1905:9302] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:07:15 INFO - PID 1903 | RSS: Main: 254251008 09:07:15 INFO - PID 1903 | 09:10:19 INFO - PID 1903 | Cycle 1(1): loaded http://localhost:49354/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49354/tests/dromaeo/cssquery-ext.html) 09:10:21 INFO - PID 1903 | RSS: Main: 271785984 09:10:21 INFO - PID 1903 | 09:13:23 INFO - PID 1903 | Cycle 1(1): loaded http://localhost:49354/tests/dromaeo/cssquery-ext.html (next: http://localhost:49354/tests/dromaeo/cssquery-jquery.html) 09:13:23 INFO - PID 1903 | RSS: Main: 280420352 09:13:23 INFO - PID 1903 | 09:16:25 INFO - PID 1903 | Cycle 1(1): loaded http://localhost:49354/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49354/tests/dromaeo/cssquery-mootools.html) 09:16:26 INFO - PID 1903 | RSS: Main: 282963968 09:16:26 INFO - PID 1903 | 09:19:27 INFO - PID 1903 | Cycle 1(1): loaded http://localhost:49354/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49354/tests/dromaeo/cssquery-prototype.html) 09:19:28 INFO - PID 1903 | RSS: Main: 284397568 09:19:28 INFO - PID 1903 | 09:22:31 INFO - PID 1903 | Cycle 1(1): loaded http://localhost:49354/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49354/tests/dromaeo/cssquery-yui.html) 09:22:31 INFO - PID 1903 | RSS: Main: 286367744 09:22:31 INFO - PID 1903 | 09:25:33 INFO - PID 1903 | Cycle 1(1): loaded http://localhost:49354/tests/dromaeo/cssquery-yui.html (next: http://localhost:49354/tests/dromaeo/cssquery-dojo.html) 09:25:33 INFO - PID 1903 | RSS: Main: 284954624 09:25:33 INFO - PID 1903 | 09:25:33 INFO - PID 1903 | __start_tp_report 09:25:33 INFO - PID 1903 | _x_x_mozilla_page_load 09:25:33 INFO - PID 1903 | _x_x_mozilla_page_load_details 09:25:33 INFO - PID 1903 | |i|pagename|runs| 09:25:33 INFO - PID 1903 | |0;dojo.html;3245.8777352761977;3612.6567976042274;3637.4180131179;3643.4899114124028;3689.9262014759706;4531.6601254905845;5679.630823996437;5863.76544938203;6244.313125556185;6250.781222657208;6178.629282243057;6208.751649934008;6214.06788981653;6237.968810155954;6240.594361366508;5439.211314359435;5451.509364157235;5460;5463.235147079392;5609.102543593026;5552.833414997556;5569.805056823012;5586.13414920686;5595.972020139893;5600;5544.445555444443;5551.2228288039705;5576.944230557703;5590.496855283024;5594.58040646951;5478.506934375925;5517.117261238222;5543.750531226085;5551.278333816598;5580.358258800249;5475.6440831345835;5556.6943818089985;5572.749226284786;5581.330240371181;5592.133219351007;5130.230465430215;6033.551947532605;6355.555111142222;6415.230172379344;6425.614463132227;6637.269900310962;6751.696173672148;6753.797386078426;6885.931140688579;6914.688839002257;6795.048693182958;6837.2820853810135;6838.760643377507;6845.247022827485;6848.417884479825;6277.843053923642;6335.746570137235;6351.80944571659;6354.2692590352235;6366.236051673829;5183.429822719498;6958.547694399847;7106.182788979334;7147.428205743528;7165.104361954755;6592.143021407186;6785.694643741043;6790.932090679129;6792.592444453348;6792.694328755218;6954.165500140016;6978.79063628092;6980.197277313073;7000.08998830159;7019.8596028079155;6527.118838956775;6600.910849709744;6628.701708423133;6643.56816806906;6649.268580456146;4954.826581069718;6842.623655698984;6875.862482750317;6878.484113691493;6911.447084233249;5291.444398338196;5298.894022119536;5340.145576707708;5367.5169234769055;5380.354357477127;4759.643026773006;4807.014562014849;4826.7827947742435;4848.9757551212015;4869.610431165498;5337.599680024014;6141.201643786279;6277.780277690259;6324.367563243639;6344.873102537924;6853.520253582301;6854.931450685429;6858.5199036068;6862.142232220973;6880.724771009203;6217.626942383471;6334.778282760081;6381.1066450696935;6424.871502569922;6429.38920802523;5900.704964751745;5902.498287645598;6025.457708806229;6095.085737139376;6113.449789518964;6930.722771089302;6968.825779355557;6979.476539259677;6987.545809522365;6992.3357281058215;5184.844454666366;6641.26946035926;6791.422729068085;6818.147731533559;6850.554713943578;4282.935755963601;5941.37615550361;6928.653567321513;7144.714211431692;7193.856122877409;5708.429157084258;6867.896981545181;6889.173299203927;6895.207051188977;6941.2364639888565;5785.30576330843;7018.33325834045;7025.613591252531;7041.190263119602;7056.858862822817;6568.967155164193;6660.034295027097;6672.266050734513;6677.399034086956;6751.324867513209;6564.737410503526;6615.4046135848;6633.767818126343;6634.137562117087;6650.634215118215;5009.298698182331;6532.542722009413;6653.833654158685;6684.331566843277;6687.398134167948;6745.12313399255;6774.153230846145;6801.829954251183;6808.183018037867;6817;6414;6488.2538508072175;6660.200775906922;6662.267150613332;6671.566348187353 09:25:33 INFO - PID 1903 | |1;ext.html;761.094297785635;814.3892080939296;816.7998840284131;843.6709683223544;1107.7895199912011;25871.74128258717;26636.20091397259;30363.240918977037;36852.710155144574;37373;37927.431088533696;38415.807920960426;38431.231375372474;38439.61560384395;38553;8182.345412366996;8362.540060296682;8365.330773538133;8368.288695460893;8384.371172162082;7500.699972001114;8208.343332533414;8239.382046346518;8330.916690833075;8354;7364.33720965113;7774.2614451627005;8324;8499.872501912445;8535.231829135377;8434.915650843473;8488;8488.575571221476;8502.192291732275;8504.404691671585;8306.54314012729;8343.874841877378;8356.039055508634;8362.498250104953;8384.496930184208;36330.45504317437;36755.44866827;36871.26257474862;36895.815520922224;37012.81493592542;3439.071450708295;3469.5489586353865;3474.8262586870555;3477.5479187705437;3486.877959271413;29803.95686150974;30305.393892122032;30323.090307290597;30356.696433035828;30427.543586846215;2988.7310142087317;3060.3420264642987;3082.090783218933;3090.428270769915;3109.176068341891;243849;246314.9999999982;246734;292354.0764592348;294476;38684.41973370402;38749.225015499534;44000;45379.546204538194;47343.28985065227;49903.75048124736;49993.25010124851;50067;50073;50077.49922500801;13867.791983120262;13930.233837138852;14059.437622494986;14075.944304177228;14092.013559050973;11283;11341.546338146547;11366.602168924048;11400.828987565194;11402.20184587071;11319.151063670257;13198.406071726795;13421.664458388423;13465.922726182078;13511.932440337736;5814.401039714113;5884.676342801102;5891.882162356729;5925.377835327228;5950.899083669535;974.2157563161722;981.3130808434124;983.680303901235;1035.891231420705;1039.4023436524028;21889.67165492519;22806.973686184163;23069;23116.422089447562;23140.305790826304;1070.2347821307803;1085.4301491716753;1094.3871431998107;1123.685368096934;1126.2172789911097;11382.747897731242;11584.42077896102;11741.88258117425;11816.05471562273;12152.817707734392;283748;296517;297424;298501;298510.01489985257;52221;52239;52536.73731631317;52767.472325276256;52974.73512632489;1288.2592509307185;1317.6244770240435;1493.529538195465;1532.7930729351617;1534.8465153484785;16797.244124014203;17982.820171798114;22287.77712222857;22618;23342.949567269854;36248.456273155396;37227.441588376736;37298.813505932296;37300.62699372972;37363.439548406255;246250;248882;249045;249182;249438;27023.783929723602;27714.861425692743;30330.696693032787;33800.323993520484;33838.66161338355;36087.63912360843;38099.619003810716;39185.60814391934;39621.80189099036;39745.2050958985;26894.73105268922;27192.864035679693;27310.726892730818;27579.44841103207;28726.42547149087;27227.86386068057;28558.857205713837;28569.714302857537;28762;29488.705112949454;31804.933393321327;33142.5028624566;33680.83159584186;33790.831045844614;33819.3236135271;26899.462010760068;28688.713112868605;28716.994905179054;29823.850880745456;31359.529607056367;24175.274741757774;24227.273181804576;24309.635355469327;24350;24523.896424660576 09:25:33 INFO - PID 1903 | |2;jquery.html;500.41201588133936;504.8914483386071;507.5837812993345;516.8397796683028;530.3105962249075;21680;21813.345599631997;22641.54716905661;22681.886590567046;23229.070837166535;24136.758632413672;24661.506769864638;24685;24735.257942261716;24773.380665483328;6440.935590644092;6604.504662150334;6610.570312929644;6614.50391220658;6625.072489851401;5794.71026448678;6114.663693496856;6382.585131966431;6508.772192973247;6595.835104122388;6342.143810585558;6377.8405539861415;6384;6394.520410969173;6403.903941440882;6379.808605741835;6398.808035758934;6407.006913928349;6408.198975128085;6422.325655806143;5900.852478688067;5903.3801450847905;5904.675242861641;5911.113333000041;5919.112133180014;29317.41365172706;30735.692643073507;30914.381712365852;31516.36967260665;31659.683403165905;2208.6355751301016;2259.706238189025;2272.670462782887;2274.6019446596947;2278.3392816083315;19176.328828490936;19706.90146549258;19740.605187896304;19751.012449377475;19752.901235493875;2180.356794745538;2231.9107235710553;2253.2001139595372;2273.2498275569023;2279.2478482101033;145660;152910;153281;154050;202574;11423.314601123959;14077.788833167511;14252.714945701027;14868;15546.533603991898;20657.690134647994;20687.58624827495;20696.275630353168;20716.792832071787;20723;9571.760705982406;9697.418154910727;9714.271429642806;9824.607015719292;9939.155171810333;9073.54632268384;9109.681161159328;9111.635534578543;9151.35940484173;9163.358564900393;8944.50805205707;8947.776305592412;9055.637774489078;9070.727878163665;9084.364094513321;2608.6478325426206;2608.704340180629;2620.6331113644105;2622.475504899028;2753.0777189641294;933.673214374977;934.5093825741404;935.6725146198912;985.8176237395961;1004.9547770350206;21190.576188476145;21479.78520214809;21647.242346517796;21985.890070549864;22036.228731994303;836.2807985132781;867.8568036274071;968.3560432312433;974.6880998080546;1064.3667018124204;9642.951785241028;10924.672259832218;10935.890641093647;10974.341539507655;11293.54825806975;529995.5362341321;549308;557840;560713;564035;146034;146555;146991;147548;149520;1014.4775440648073;1028.6965345223177;1068.9198310126617;1075.4837677914488;1196.0730433913614;20146.194152233747;20361.796382036584;23672.28983130509;24126.75873241245;24253.878730606233;67586.66206668936;69031;70036.64981675059;70561.29438705547;71314.64342678254;88641;92068;92583.53708231416;94027.52986235025;96209;11596.94201528987;11709.063274937815;11763.294202347886;11814.763704725685;11865.525378984745;16025.759613606038;17737.911310443364;18201.90788552691;18820.717689234945;19341.806581934565;14138.717225655224;15580.298886549906;15587.142707150762;15591.220438977778;15796.92101539485;13018.349082546025;13240.735185296433;13696.31518424055;14406.279686015867;14414.42342306296;14226.573202803933;15814.288357024192;15896.523104306889;15981.44064957721;16088.758668620214;11848.585299514476;11940.462679179283;11957.880421195676;11960.58137965167;11986.76026479448;14667.853321467075;15279.624833765014;15485.303161357991;15567.922160389578;15626.609334766266 09:25:33 INFO - PID 1903 | |3;mootools.html;1393.707321462493;1462.7293950619144;1471.0585225455704;1481.570344600066;1513.5988962924823;12757.425915833786;13270.469181232751;13662.795058074136;13864.445422183113;13884.722305553883;10288.845667314996;11269.09847212223;11272.661820145388;11280.210385273014;11283.548658053689;2285.8514196577253;2347.1667558016916;2350.3654013416367;2581.4707984863057;2677.571588545833;2747.917562473129;2749.9450010999767;2789.888404463819;2790.5674620433865;2795.4688609164295;2277.521720438702;2713.3894873653367;2754.6969833318317;2758.600002999564;2761.337279053033;2651.708312085669;2683.2218656589566;2688.502627014009;2691.111933062085;2695.3665888516184;1692.898426094426;1778.5197996540992;2308.376738280672;2550.4389034412397;2660.0689758584535;11121.165912556591;11144.941230583163;11165.497552610152;11259.099272058216;12972.87027129726;6824;7085.078939737853;7218;7588.089429268468;7596.734114305973;4030.45588845505;4536.492105565262;4773.976130119362;4882.804687812519;4904.6321525885705;1355.3629793996904;1375.0786972728297;1402.3689339797131;1406.0790182430524;1407.655124494495;15713.450029249036;19774.505637359038;19943.700844487343;20051.79948200514;20085.995700215077;6736.2590115087305;6865.794026179223;6894.551854129466;7003.494248736546;7015.859682806417;16283.918580407259;16316.592085197728;16317.59206019859;16320;16344.427945022106;7524.5861477619255;7636.847263054708;7642.08295004603;7642.656080476392;7672.079350477867;6344.111824344595;6779.118714567173;6860.931390686029;6866.793996180123;6907.585544867324;4219.514755803122;5174.741262936838;5258.106121959276;5334.333208348957;5449.5640348772;1373.9450421983208;1382.8478867324586;1653;1660.2362913059885;1678.5048410718866;625.8060001399581;836.2766207230702;837.6440012994453;859.9742007739778;927.9211267042242;6892.793216203522;7100;7304.5252058616015;7363.926360736324;7378.483506154517;954.3319676226669;956.9234461243084;966.4104896013426;998.8601595776597;1012.832882574367;4277.721948073366;4288.699791014599;4319.460067491564;4395.912081758347;4603.3785438965315;17464.039477828588;18145.00202488876;18164.45506634803;18199.545011374557;18220.27118915255;17830.46508604744;17983;18038.549036273937;18080.819191808176;18087;7805.921940780633;8637.568121593895;9358.064193580587;9483.620655173854;9530.1899338557;7597.278258565428;7705.460617756703;7760.611969401621;7994.5203287802915;8059.556724380215;14640.780388293972;15609.21953902323;15743.842561574238;15903.602409939844;15928.681426371639;15163;15358.38566457374;15918.044917304998;15980.360785568912;16764.24560894787;10399.636012739518;10448.164146868081;10542.314749541256;10545.10366618846;10563.841542376716;12200.084993625336;12539.561115360919;12621.621351359474;12626.116171867881;12629.17910335862;9812.460314682476;9862.112409883144;10074.748131296776;10228.641997530349;10264.973502649675;7467.813304667427;9680.080392362714;9686.660966866126;9712.611495540383;9813.9018609813;9277.768055798659;9342.859857102285;9382.81234375295;9384.859227111463;9399.953000234955;6532.138340572914;6716.496262780409;6720.663966801542;6725.293844146294;6954.547954382949;6726.260111387645;6832.829179270359;6928.584284942919;7096.432285417051;7131.037309963089 09:25:33 INFO - PID 1903 | |4;prototype.html;395.9267535505931;430.73940266139005;440.8765545647218;471.516695387228;472.7210945542129;5755.1942728018;6344.333844946272;6427.935720642792;6471.935280647198;6542.967285163573;5603.1058414401905;5997.52019838413;6192.5974811637325;6193.69031548423;6210.192674952261;4651.232546629802;4812.32627432158;4955.380577427822;4961.255811628266;4983.177775667029;4706.152892479369;4818.903621927577;4972.552470277675;4993.226049962262;4994.300797888299;4979.502049795028;4981.37732783402;4998.800047998076;5001.299818025479;5004.249362595603;4082.4284600156;4711.316859055418;4974.0797952378925;4991.900161996758;5027.597792176617;4258.659307255412;4504.684672072956;4520.502744698113;4532.592066713979;4551.908961820745;5144.279800827887;6162.322144564139;6189.195404597373;6229.159063526411;6295.842603934892;4968.87577810554;5168.457311982263;5511.366192887789;5975.342712301687;6010.879782404371;6205.610839826654;6566.606003639797;6914.585124892523;7003.089598352232;7030.121234845645;4516.932246016312;4735.928961065587;4803.855884323476;5219.373675158968;5221.582273418117;7125.465590080713;7190.173130090055;7214.422846172293;7251.92748072523;7254.963725181393;4691.9530804692195;4811.302361157683;5076.314697515862;5402.972985135049;5403.594730395236;4843.273508973612;5464.7814087436855;5499.422560631157;5500.312460942382;5508.55931525477;5997.520198384119;6002.529380301854;6022.25987175834;6033.215681961405;6039.607425517327;6931.445484361239;6949.548279361826;6955.295952491672;6964.373206411447;6964.965175174193;1494.3350209157038;1857.452051644768;1865.7574515313195;1881.2286962345372;1894.1665667106429;1180.2682336950973;1230.4585982167996;1279.878411550901;1332.6135420728074;1350.4328182163515;5793.7392817323325;6360.332165122688;6380.936190638127;6523.8695226095215;6554.606723596599;6763.323667633197;6823.404808057346;7091.184513780878;7097.680604372816;7119.4660400470175;6379.71291291893;6397.424231819158;6399.200099987502;6402.775902843471;6406.775762848371;3522.225110475691;3606.9819650902105;3625.4743062256352;3640.9635903641156;3664.790619095692;7007.5795452272005;7178.641067946582;7185.353318201387;7227.710891564384;7314.634268286671;6686.53194276396;7241.565506069653;7283.198848126702;7285.744998925118;7326.780196594111;6476.369395881873;7026.191987921352;7243.927560724431;7318;7325.926740732738;5386.973065134649;5883.470487656132;5903.439173278532;6750.561213521106;7127.322904324081;6939.271376505596;6963.373296403348;6983.336583024605;6987.580745155307;7000.719971201095;6134.969325153345;7253.093363329584;7299.5620262784405;7304.356519783219;7415.3326200642205;5392.299001129828;5396.920615876918;5397.460253974728;5404.7297635118875;5442.591805614516;6807.460120737288;7092.5389849659605;7134.215236324104;7135.894911001371;7146.32109949554;5474.753636086306;5610.158476228598;5830.096335068071;6855.794326170223;6874.106366172366;5618.410066942912;6808.5914845109455;6841.93158068413;6849.589024658537;6850.862982740417;6010.939890601038;6493.415592596689;6612.768553100618;6721.126253587003;6721.462283017249;5445.50990410865;5928.140419639214;6299.716512756845;6809.319068093151;6959.96520017417;6657.671164417791;6662.533622646368;6670.232923213893;6691.498137639599;6703.463722902058 09:25:33 INFO - PID 1903 | |5;yui.html;824.4640983360815;834.4659417972495;841.7516832534405;870.8476016697076;873.5326600268853;16761.7485737714;17243.051632160226;17380.913095434506;17459.476215713516;17464.12679366033;8614.612342444605;11277.37974411407;11654.592089276877;12708.36458177092;13665.975051871099;651.9250286217099;660.9669516524178;667.7329068372666;946.049220533363;952.8332541805191;1005.36661903001;1006.9546870390814;1007.6976906927893;1009.7980403919208;1010.4745532323187;1034.782695633914;1046.8953104689547;1047.706642140202;1048.072455876551;1049.6168898352123;679.6907407129744;1023.8361862102065;1036.8548403223556;1040.6669865642998;1044.843273508976;509.0988949559296;513.5968264912026;514.1567828760798;514.9613778966592;727.6434547071949;17407.912960435242;17515.8614690046;17740.379086731904;17763.023033733138;17801.46595602134;16963.406280780237;17017.319307227695;17615.207315670825;17672.646547068987;17809.554761130945;13906.58280251594;14704.632384190374;14739.926300368537;14870;15042.849571504254;3575.4994300797907;3607.729420293462;3612.8554857805657;3614.765040272374;3617.186133120027;100949;152725.23637381854;153479;160852.19573902056;163207.18396407942;12938.094333396572;13081.672958175932;13164.802527962089;13259;14234.715305694035;26022.73977260203;26648.333791655365;26669;26681.33296667599;26725.19824405271;7844.607769611496;8026.43814932949;8048.557329346942;8081.757547273592;8096.473729207582;3618.8962366478477;3734.775913445202;3975.801209939549;4041.4948131483566;4055.371417430303;4643.257078867433;4695.835645752382;4703.529647035338;4708.011317623337;4713.67004309695;871.9564021798884;973.9172170365457;1019.6278358399131;1032.1897310611284;1036.4662198967537;647.6819671967618;663.575311800448;663.8937769956784;681.9147606549182;682.8907374820005;11163.94418027921;11239.10818165917;12160.513579456723;12649.62051138449;12878.67803304925;634.6826586706646;641.7561326695826;643.2698886763557;663.6084710021111;673.9755571531246;5327.573794096463;5636.239107720406;5657.377688454267;5815.127730840323;5953.255843019622;87791.85457017948;116918;118002;118726.4063679676;122955;101278.49360753149;105504;106086;111977.44011279891;113098;83665;89680;91688;96441.5533766994;99073.00926990926;9134.908650913672;11532.769344613229;12154.878451215374;12227.938860305641;12534.558525769653;36726.53093876215;42503.5749642512;45211.773941130086;45421.54578454305;45931.08137837292;58706;59546.809063819346;62178;62841.68579157075;63093.422664433754;16487.505374838773;16567.585810354838;17041.062741548838;17051.48845534636;17292.308307668056;17817.73273400874;18147.092645367942;19023.23907043758;19023.904880476064;19316.93756843387;17265.39571115005;17972.460826175236;18024.188911499277;18530.44408667742;18590.07049647485;17242.137893105544;17734.290628375238;17738.5565360867;17863;18033.639327213645;16058.919705401398;16431.26059327309;16474.06342933561;16747.497575072768;17709.114544272994;11579.247348922294;11883.465244064211;12113.454894529592;12131.575394861138;12523.74952500963;11910.880891190976;12933.741325173256;13339.399727012114;13438.177982623758;13604.659883502596 09:25:33 INFO - PID 1903 | __end_tp_report 09:25:33 INFO - PID 1903 | __start_cc_report 09:25:33 INFO - PID 1903 | _x_x_mozilla_cycle_collect,476 09:25:33 INFO - PID 1903 | __end_cc_report 09:25:33 INFO - PID 1903 | __startTimestamp1496939133882__endTimestamp 09:25:33 INFO - PID 1903 | 09:25:33 INFO - PID 1903 | ------- Summary: start ------- 09:25:33 INFO - PID 1903 | Number of tests: 6 09:25:33 INFO - PID 1903 | 09:25:33 INFO - PID 1903 | [#0] dojo.html Cycles:165 Average:6194.45 Median:6425.61 stddev:808.05 (12.6%) stddev-sans-first:776.71 09:25:33 INFO - PID 1903 | Values: 3245.9 3612.7 3637.4 3643.5 3689.9 4531.7 5679.6 5863.8 6244.3 6250.8 6178.6 6208.8 6214.1 6238.0 6240.6 5439.2 5451.5 5460.0 5463.2 5609.1 5552.8 5569.8 5586.1 5596.0 5600.0 5544.4 5551.2 5576.9 5590.5 5594.6 5478.5 5517.1 5543.8 5551.3 5580.4 5475.6 5556.7 5572.7 5581.3 5592.1 5130.2 6033.6 6355.6 6415.2 6425.6 6637.3 6751.7 6753.8 6885.9 6914.7 6795.0 6837.3 6838.8 6845.2 6848.4 6277.8 6335.7 6351.8 6354.3 6366.2 5183.4 6958.5 7106.2 7147.4 7165.1 6592.1 6785.7 6790.9 6792.6 6792.7 6954.2 6978.8 6980.2 7000.1 7019.9 6527.1 6600.9 6628.7 6643.6 6649.3 4954.8 6842.6 6875.9 6878.5 6911.4 5291.4 5298.9 5340.1 5367.5 5380.4 4759.6 4807.0 4826.8 4849.0 4869.6 5337.6 6141.2 6277.8 6324.4 6344.9 6853.5 6854.9 6858.5 6862.1 6880.7 6217.6 6334.8 6381.1 6424.9 6429.4 5900.7 5902.5 6025.5 6095.1 6113.4 6930.7 6968.8 6979.5 6987.5 6992.3 5184.8 6641.3 6791.4 6818.1 6850.6 4282.9 5941.4 6928.7 7144.7 7193.9 5708.4 6867.9 6889.2 6895.2 6941.2 5785.3 7018.3 7025.6 7041.2 7056.9 6569.0 6660.0 6672.3 6677.4 6751.3 6564.7 6615.4 6633.8 6634.1 6650.6 5009.3 6532.5 6653.8 6684.3 6687.4 6745.1 6774.2 6801.8 6808.2 6817.0 6414.0 6488.3 6660.2 6662.3 6671.6 09:25:33 INFO - PID 1903 | 09:25:33 INFO - PID 1903 | [#1] ext.html Cycles:180 Average:41418.31 Median:3086.26 stddev:70801.31 (2294.1%) stddev-sans-first:70934.12 09:25:33 INFO - PID 1903 | Values: 761.1 814.4 816.8 843.7 1107.8 25871.7 26636.2 30363.2 36852.7 37373.0 37927.4 38415.8 38431.2 38439.6 38553.0 8182.3 8362.5 8365.3 8368.3 8384.4 7500.7 8208.3 8239.4 8330.9 8354.0 7364.3 7774.3 8324.0 8499.9 8535.2 8434.9 8488.0 8488.6 8502.2 8504.4 8306.5 8343.9 8356.0 8362.5 8384.5 36330.5 36755.4 36871.3 36895.8 37012.8 3439.1 3469.5 3474.8 3477.5 3486.9 29804.0 30305.4 30323.1 30356.7 30427.5 2988.7 3060.3 3082.1 3090.4 3109.2 243849.0 246315.0 246734.0 292354.1 294476.0 38684.4 38749.2 44000.0 45379.5 47343.3 49903.8 49993.3 50067.0 50073.0 50077.5 13867.8 13930.2 14059.4 14075.9 14092.0 11283.0 11341.5 11366.6 11400.8 11402.2 11319.2 13198.4 13421.7 13465.9 13511.9 5814.4 5884.7 5891.9 5925.4 5950.9 974.2 981.3 983.7 1035.9 1039.4 21889.7 22807.0 23069.0 23116.4 23140.3 1070.2 1085.4 1094.4 1123.7 1126.2 11382.7 11584.4 11741.9 11816.1 12152.8 283748.0 296517.0 297424.0 298501.0 298510.0 52221.0 52239.0 52536.7 52767.5 52974.7 1288.3 1317.6 1493.5 1532.8 1534.8 16797.2 17982.8 22287.8 22618.0 23342.9 36248.5 37227.4 37298.8 37300.6 37363.4 246250.0 248882.0 249045.0 249182.0 249438.0 27023.8 27714.9 30330.7 33800.3 33838.7 36087.6 38099.6 39185.6 39621.8 39745.2 26894.7 27192.9 27310.7 27579.4 28726.4 27227.9 28558.9 28569.7 28762.0 29488.7 31804.9 33142.5 33680.8 33790.8 33819.3 26899.5 28688.7 28717.0 29823.9 31359.5 24175.3 24227.3 24309.6 24350.0 24523.9 09:25:33 INFO - PID 1903 | 09:25:33 INFO - PID 1903 | [#2] jquery.html Cycles:180 Average:38409.82 Median:2278.79 stddev:94552.43 (4149.2%) stddev-sans-first:94774.83 09:25:33 INFO - PID 1903 | Values: 500.4 504.9 507.6 516.8 530.3 21680.0 21813.3 22641.5 22681.9 23229.1 24136.8 24661.5 24685.0 24735.3 24773.4 6440.9 6604.5 6610.6 6614.5 6625.1 5794.7 6114.7 6382.6 6508.8 6595.8 6342.1 6377.8 6384.0 6394.5 6403.9 6379.8 6398.8 6407.0 6408.2 6422.3 5900.9 5903.4 5904.7 5911.1 5919.1 29317.4 30735.7 30914.4 31516.4 31659.7 2208.6 2259.7 2272.7 2274.6 2278.3 19176.3 19706.9 19740.6 19751.0 19752.9 2180.4 2231.9 2253.2 2273.2 2279.2 145660.0 152910.0 153281.0 154050.0 202574.0 11423.3 14077.8 14252.7 14868.0 15546.5 20657.7 20687.6 20696.3 20716.8 20723.0 9571.8 9697.4 9714.3 9824.6 9939.2 9073.5 9109.7 9111.6 9151.4 9163.4 8944.5 8947.8 9055.6 9070.7 9084.4 2608.6 2608.7 2620.6 2622.5 2753.1 933.7 934.5 935.7 985.8 1005.0 21190.6 21479.8 21647.2 21985.9 22036.2 836.3 867.9 968.4 974.7 1064.4 9643.0 10924.7 10935.9 10974.3 11293.5 529995.5 549308.0 557840.0 560713.0 564035.0 146034.0 146555.0 146991.0 147548.0 149520.0 1014.5 1028.7 1068.9 1075.5 1196.1 20146.2 20361.8 23672.3 24126.8 24253.9 67586.7 69031.0 70036.6 70561.3 71314.6 88641.0 92068.0 92583.5 94027.5 96209.0 11596.9 11709.1 11763.3 11814.8 11865.5 16025.8 17737.9 18201.9 18820.7 19341.8 14138.7 15580.3 15587.1 15591.2 15796.9 13018.3 13240.7 13696.3 14406.3 14414.4 14226.6 15814.3 15896.5 15981.4 16088.8 11848.6 11940.5 11957.9 11960.6 11986.8 14667.9 15279.6 15485.3 15567.9 15626.6 09:25:33 INFO - PID 1903 | 09:25:33 INFO - PID 1903 | [#3] mootools.html Cycles:180 Average:8053.61 Median:2730.65 stddev:5346.63 (195.8%) stddev-sans-first:5338.21 09:25:33 INFO - PID 1903 | Values: 1393.7 1462.7 1471.1 1481.6 1513.6 12757.4 13270.5 13662.8 13864.4 13884.7 10288.8 11269.1 11272.7 11280.2 11283.5 2285.9 2347.2 2350.4 2581.5 2677.6 2747.9 2749.9 2789.9 2790.6 2795.5 2277.5 2713.4 2754.7 2758.6 2761.3 2651.7 2683.2 2688.5 2691.1 2695.4 1692.9 1778.5 2308.4 2550.4 2660.1 11121.2 11144.9 11165.5 11259.1 12972.9 6824.0 7085.1 7218.0 7588.1 7596.7 4030.5 4536.5 4774.0 4882.8 4904.6 1355.4 1375.1 1402.4 1406.1 1407.7 15713.5 19774.5 19943.7 20051.8 20086.0 6736.3 6865.8 6894.6 7003.5 7015.9 16283.9 16316.6 16317.6 16320.0 16344.4 7524.6 7636.8 7642.1 7642.7 7672.1 6344.1 6779.1 6860.9 6866.8 6907.6 4219.5 5174.7 5258.1 5334.3 5449.6 1373.9 1382.8 1653.0 1660.2 1678.5 625.8 836.3 837.6 860.0 927.9 6892.8 7100.0 7304.5 7363.9 7378.5 954.3 956.9 966.4 998.9 1012.8 4277.7 4288.7 4319.5 4395.9 4603.4 17464.0 18145.0 18164.5 18199.5 18220.3 17830.5 17983.0 18038.5 18080.8 18087.0 7805.9 8637.6 9358.1 9483.6 9530.2 7597.3 7705.5 7760.6 7994.5 8059.6 14640.8 15609.2 15743.8 15903.6 15928.7 15163.0 15358.4 15918.0 15980.4 16764.2 10399.6 10448.2 10542.3 10545.1 10563.8 12200.1 12539.6 12621.6 12626.1 12629.2 9812.5 9862.1 10074.7 10228.6 10265.0 7467.8 9680.1 9686.7 9712.6 9813.9 9277.8 9342.9 9382.8 9384.9 9400.0 6532.1 6716.5 6720.7 6725.3 6954.5 6726.3 6832.8 6928.6 7096.4 7131.0 09:25:33 INFO - PID 1903 | 09:25:33 INFO - PID 1903 | [#4] prototype.html Cycles:180 Average:5647.94 Median:6148.65 stddev:1655.27 (26.9%) stddev-sans-first:1612.29 09:25:33 INFO - PID 1903 | Values: 395.9 430.7 440.9 471.5 472.7 5755.2 6344.3 6427.9 6471.9 6543.0 5603.1 5997.5 6192.6 6193.7 6210.2 4651.2 4812.3 4955.4 4961.3 4983.2 4706.2 4818.9 4972.6 4993.2 4994.3 4979.5 4981.4 4998.8 5001.3 5004.2 4082.4 4711.3 4974.1 4991.9 5027.6 4258.7 4504.7 4520.5 4532.6 4551.9 5144.3 6162.3 6189.2 6229.2 6295.8 4968.9 5168.5 5511.4 5975.3 6010.9 6205.6 6566.6 6914.6 7003.1 7030.1 4516.9 4735.9 4803.9 5219.4 5221.6 7125.5 7190.2 7214.4 7251.9 7255.0 4692.0 4811.3 5076.3 5403.0 5403.6 4843.3 5464.8 5499.4 5500.3 5508.6 5997.5 6002.5 6022.3 6033.2 6039.6 6931.4 6949.5 6955.3 6964.4 6965.0 1494.3 1857.5 1865.8 1881.2 1894.2 1180.3 1230.5 1279.9 1332.6 1350.4 5793.7 6360.3 6380.9 6523.9 6554.6 6763.3 6823.4 7091.2 7097.7 7119.5 6379.7 6397.4 6399.2 6402.8 6406.8 3522.2 3607.0 3625.5 3641.0 3664.8 7007.6 7178.6 7185.4 7227.7 7314.6 6686.5 7241.6 7283.2 7285.7 7326.8 6476.4 7026.2 7243.9 7318.0 7325.9 5387.0 5883.5 5903.4 6750.6 7127.3 6939.3 6963.4 6983.3 6987.6 7000.7 6135.0 7253.1 7299.6 7304.4 7415.3 5392.3 5396.9 5397.5 5404.7 5442.6 6807.5 7092.5 7134.2 7135.9 7146.3 5474.8 5610.2 5830.1 6855.8 6874.1 5618.4 6808.6 6841.9 6849.6 6850.9 6010.9 6493.4 6612.8 6721.1 6721.5 5445.5 5928.1 6299.7 6809.3 6960.0 6657.7 6662.5 6670.2 6691.5 6703.5 09:25:33 INFO - PID 1903 | 09:25:33 INFO - PID 1903 | [#5] yui.html Cycles:180 Average:23659.65 Median:17782.24 stddev:35397.57 (199.1%) stddev-sans-first:35455.34 09:25:33 INFO - PID 1903 | Values: 824.5 834.5 841.8 870.8 873.5 16761.7 17243.1 17380.9 17459.5 17464.1 8614.6 11277.4 11654.6 12708.4 13666.0 651.9 661.0 667.7 946.0 952.8 1005.4 1007.0 1007.7 1009.8 1010.5 1034.8 1046.9 1047.7 1048.1 1049.6 679.7 1023.8 1036.9 1040.7 1044.8 509.1 513.6 514.2 515.0 727.6 17407.9 17515.9 17740.4 17763.0 17801.5 16963.4 17017.3 17615.2 17672.6 17809.6 13906.6 14704.6 14739.9 14870.0 15042.8 3575.5 3607.7 3612.9 3614.8 3617.2 100949.0 152725.2 153479.0 160852.2 163207.2 12938.1 13081.7 13164.8 13259.0 14234.7 26022.7 26648.3 26669.0 26681.3 26725.2 7844.6 8026.4 8048.6 8081.8 8096.5 3618.9 3734.8 3975.8 4041.5 4055.4 4643.3 4695.8 4703.5 4708.0 4713.7 872.0 973.9 1019.6 1032.2 1036.5 647.7 663.6 663.9 681.9 682.9 11163.9 11239.1 12160.5 12649.6 12878.7 634.7 641.8 643.3 663.6 674.0 5327.6 5636.2 5657.4 5815.1 5953.3 87791.9 116918.0 118002.0 118726.4 122955.0 101278.5 105504.0 106086.0 111977.4 113098.0 83665.0 89680.0 91688.0 96441.6 99073.0 9134.9 11532.8 12154.9 12227.9 12534.6 36726.5 42503.6 45211.8 45421.5 45931.1 58706.0 59546.8 62178.0 62841.7 63093.4 16487.5 16567.6 17041.1 17051.5 17292.3 17817.7 18147.1 19023.2 19023.9 19316.9 17265.4 17972.5 18024.2 18530.4 18590.1 17242.1 17734.3 17738.6 17863.0 18033.6 16058.9 16431.3 16474.1 16747.5 17709.1 11579.2 11883.5 12113.5 12131.6 12523.7 11910.9 12933.7 13339.4 13438.2 13604.7 09:25:33 INFO - PID 1903 | -------- Summary: end -------- 09:25:33 INFO - PID 1903 | 09:25:34 INFO - TEST-INFO | 1903: exit 0 09:25:34 INFO - TEST-OK | dromaeo_css | took 1144615ms 09:25:34 INFO - TEST-START | kraken 09:25:34 INFO - operating with platform_type : mac_ 09:25:34 INFO - Initialising browser for kraken test... 09:25:34 INFO - Application command: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49354/getInfo.html -foreground -profile /var/folders/tg/9cs16h3j7pn5rd3v0kkz9lgc00000w/T/tmpjTOlt3/profile 09:25:34 INFO - TEST-INFO | started process 1912 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49354/getInfo.html -foreground) 09:26:08 INFO - TEST-INFO | 1912: killed by SIGTERM 09:26:08 INFO - Browser initialized. 09:26:08 INFO - Running cycle 1/1 for kraken test... 09:26:08 INFO - TEST-INFO | started process 1916 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/tg/9cs16h3j7pn5rd3v0kkz9lgc00000w/T/tmpjTOlt3/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:26:09 INFO - PID 1916 | 2017-06-08 09:26:09.969 plugin-container[1917:11697] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x973f, name = 'com.apple.tsm.portname' 09:26:09 INFO - PID 1916 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:26:09 INFO - PID 1916 | 2017-06-08 09:26:09.970 plugin-container[1917:11697] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4927, name = 'com.apple.CFPasteboardClient' 09:26:09 INFO - PID 1916 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:26:09 INFO - PID 1916 | 2017-06-08 09:26:09.970 plugin-container[1917:11697] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:26:10 INFO - PID 1916 | 2017-06-08 09:26:10.744 plugin-container[1918:11742] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x963f, name = 'com.apple.tsm.portname' 09:26:10 INFO - PID 1916 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:26:10 INFO - PID 1916 | 2017-06-08 09:26:10.745 plugin-container[1918:11742] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9a0b, name = 'com.apple.CFPasteboardClient' 09:26:10 INFO - PID 1916 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:26:10 INFO - PID 1916 | 2017-06-08 09:26:10.745 plugin-container[1918:11742] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:26:20 INFO - PID 1916 | RSS: Main: 251854848 09:26:20 INFO - PID 1916 | 09:26:22 INFO - PID 1916 | Cycle 1(1): loaded http://localhost:49354/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49354/tests/kraken/driver.html?testName=audio-beat-detection) 09:26:23 INFO - PID 1916 | RSS: Main: 263516160 09:26:23 INFO - PID 1916 | 09:26:26 INFO - PID 1916 | Cycle 1(1): loaded http://localhost:49354/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49354/tests/kraken/driver.html?testName=audio-dft) 09:26:26 INFO - PID 1916 | RSS: Main: 272654336 09:26:26 INFO - PID 1916 | 09:26:30 INFO - PID 1916 | Cycle 1(1): loaded http://localhost:49354/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49354/tests/kraken/driver.html?testName=audio-fft) 09:26:30 INFO - PID 1916 | RSS: Main: 275460096 09:26:30 INFO - PID 1916 | 09:26:32 INFO - PID 1916 | Cycle 1(1): loaded http://localhost:49354/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49354/tests/kraken/driver.html?testName=audio-oscillator) 09:26:33 INFO - PID 1916 | RSS: Main: 276713472 09:26:33 INFO - PID 1916 | 09:26:35 INFO - PID 1916 | Cycle 1(1): loaded http://localhost:49354/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49354/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:26:36 INFO - PID 1916 | RSS: Main: 278876160 09:26:36 INFO - PID 1916 | 09:26:39 INFO - PID 1916 | Cycle 1(1): loaded http://localhost:49354/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49354/tests/kraken/driver.html?testName=imaging-darkroom) 09:26:39 INFO - PID 1916 | RSS: Main: 283996160 09:26:39 INFO - PID 1916 | 09:26:42 INFO - PID 1916 | Cycle 1(1): loaded http://localhost:49354/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49354/tests/kraken/driver.html?testName=imaging-desaturate) 09:26:43 INFO - PID 1916 | RSS: Main: 286167040 09:26:43 INFO - PID 1916 | 09:26:46 INFO - PID 1916 | Cycle 1(1): loaded http://localhost:49354/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49354/tests/kraken/driver.html?testName=json-parse-financial) 09:26:46 INFO - PID 1916 | RSS: Main: 284938240 09:26:46 INFO - PID 1916 | 09:26:48 INFO - PID 1916 | Cycle 1(1): loaded http://localhost:49354/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49354/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:26:48 INFO - PID 1916 | RSS: Main: 284581888 09:26:48 INFO - PID 1916 | 09:26:50 INFO - PID 1916 | Cycle 1(1): loaded http://localhost:49354/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49354/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:26:51 INFO - PID 1916 | RSS: Main: 284532736 09:26:51 INFO - PID 1916 | 09:26:53 INFO - PID 1916 | Cycle 1(1): loaded http://localhost:49354/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49354/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:26:54 INFO - PID 1916 | RSS: Main: 284246016 09:26:54 INFO - PID 1916 | 09:26:56 INFO - PID 1916 | Cycle 1(1): loaded http://localhost:49354/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49354/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:26:57 INFO - PID 1916 | RSS: Main: 284119040 09:26:57 INFO - PID 1916 | 09:27:00 INFO - PID 1916 | Cycle 1(1): loaded http://localhost:49354/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49354/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:27:01 INFO - PID 1916 | RSS: Main: 284200960 09:27:01 INFO - PID 1916 | 09:27:03 INFO - PID 1916 | Cycle 1(1): loaded http://localhost:49354/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49354/tests/kraken/driver.html?testName=ai-astar) 09:27:03 INFO - PID 1916 | RSS: Main: 285450240 09:27:03 INFO - PID 1916 | 09:27:03 INFO - PID 1916 | __start_tp_report 09:27:03 INFO - PID 1916 | _x_x_mozilla_page_load 09:27:03 INFO - PID 1916 | _x_x_mozilla_page_load_details 09:27:03 INFO - PID 1916 | |i|pagename|runs| 09:27:03 INFO - PID 1916 | |0;ai-astar;103;115;137;128;126;114;114;118;115;117 09:27:03 INFO - PID 1916 | |1;audio-beat-detection;129;126;144;135;110;137;129;112;153;110 09:27:03 INFO - PID 1916 | |2;audio-dft;196;200;196;198;209;221;229;208;240;195 09:27:03 INFO - PID 1916 | |3;audio-fft;81;81;73;125;52;53;60;89;166;69 09:27:03 INFO - PID 1916 | |4;audio-oscillator;73;99;128;58;98;104;107;72;107;147 09:27:03 INFO - PID 1916 | |5;imaging-gaussian-blur;90;90;89;89;90;88;89;90;90;88 09:27:03 INFO - PID 1916 | |6;imaging-darkroom;97;96;97;96;97;95;96;96;97;96 09:27:03 INFO - PID 1916 | |7;imaging-desaturate;91;91;91;91;95;90;92;93;91;91 09:27:03 INFO - PID 1916 | |8;json-parse-financial;51;49;50;52;53;54;53;52;53;53 09:27:03 INFO - PID 1916 | |9;json-stringify-tinderbox;58;57;57;61;58;62;54;58;55;60 09:27:03 INFO - PID 1916 | |10;stanford-crypto-aes;82;80;85;82;84;88;85;92;130;88 09:27:03 INFO - PID 1916 | |11;stanford-crypto-ccm;136;150;121;144;149;155;147;147;136;151 09:27:03 INFO - PID 1916 | |12;stanford-crypto-pbkdf2;220;212;203;226;232;184;184;189;177;171 09:27:03 INFO - PID 1916 | |13;stanford-crypto-sha256-iterative;66;66;93;95;96;77;66;75;68;65 09:27:03 INFO - PID 1916 | __end_tp_report 09:27:03 INFO - PID 1916 | __start_cc_report 09:27:03 INFO - PID 1916 | _x_x_mozilla_cycle_collect,1186 09:27:03 INFO - PID 1916 | __end_cc_report 09:27:03 INFO - PID 1916 | __startTimestamp1496939223529__endTimestamp 09:27:03 INFO - PID 1916 | 09:27:03 INFO - PID 1916 | ------- Summary: start ------- 09:27:03 INFO - PID 1916 | Number of tests: 14 09:27:03 INFO - PID 1916 | 09:27:03 INFO - PID 1916 | [#0] ai-astar Cycles:10 Average:118.70 Median:117.50 stddev:9.41 (8.0%) stddev-sans-first:8.08 09:27:03 INFO - PID 1916 | Values: 103.0 115.0 137.0 128.0 126.0 114.0 114.0 118.0 115.0 117.0 09:27:03 INFO - PID 1916 | 09:27:03 INFO - PID 1916 | [#1] audio-beat-detection Cycles:10 Average:128.50 Median:132.00 stddev:14.60 (11.1%) stddev-sans-first:15.48 09:27:03 INFO - PID 1916 | Values: 129.0 126.0 144.0 135.0 110.0 137.0 129.0 112.0 153.0 110.0 09:27:03 INFO - PID 1916 | 09:27:03 INFO - PID 1916 | [#2] audio-dft Cycles:10 Average:209.20 Median:208.50 stddev:15.78 (7.6%) stddev-sans-first:16.00 09:27:03 INFO - PID 1916 | Values: 196.0 200.0 196.0 198.0 209.0 221.0 229.0 208.0 240.0 195.0 09:27:03 INFO - PID 1916 | 09:27:03 INFO - PID 1916 | [#3] audio-fft Cycles:10 Average:84.90 Median:71.00 stddev:35.51 (50.0%) stddev-sans-first:37.63 09:27:03 INFO - PID 1916 | Values: 81.0 81.0 73.0 125.0 52.0 53.0 60.0 89.0 166.0 69.0 09:27:03 INFO - PID 1916 | 09:27:03 INFO - PID 1916 | [#4] audio-oscillator Cycles:10 Average:99.30 Median:65.00 stddev:26.63 (41.0%) stddev-sans-first:26.49 09:27:03 INFO - PID 1916 | Values: 73.0 99.0 128.0 58.0 98.0 104.0 107.0 72.0 107.0 147.0 09:27:03 INFO - PID 1916 | 09:27:03 INFO - PID 1916 | [#5] imaging-gaussian-blur Cycles:10 Average:89.30 Median:90.00 stddev:0.82 (0.9%) stddev-sans-first:0.83 09:27:03 INFO - PID 1916 | Values: 90.0 90.0 89.0 89.0 90.0 88.0 89.0 90.0 90.0 88.0 09:27:03 INFO - PID 1916 | 09:27:03 INFO - PID 1916 | [#6] imaging-darkroom Cycles:10 Average:96.30 Median:96.50 stddev:0.67 (0.7%) stddev-sans-first:0.67 09:27:03 INFO - PID 1916 | Values: 97.0 96.0 97.0 96.0 97.0 95.0 96.0 96.0 97.0 96.0 09:27:03 INFO - PID 1916 | 09:27:03 INFO - PID 1916 | [#7] imaging-desaturate Cycles:10 Average:91.60 Median:91.00 stddev:1.43 (1.6%) stddev-sans-first:1.50 09:27:03 INFO - PID 1916 | Values: 91.0 91.0 91.0 91.0 95.0 90.0 92.0 93.0 91.0 91.0 09:27:03 INFO - PID 1916 | 09:27:03 INFO - PID 1916 | [#8] json-parse-financial Cycles:10 Average:52.00 Median:53.00 stddev:1.56 (2.9%) stddev-sans-first:1.62 09:27:03 INFO - PID 1916 | Values: 51.0 49.0 50.0 52.0 53.0 54.0 53.0 52.0 53.0 53.0 09:27:03 INFO - PID 1916 | 09:27:03 INFO - PID 1916 | [#9] json-stringify-tinderbox Cycles:10 Average:58.00 Median:58.00 stddev:2.49 (4.3%) stddev-sans-first:2.65 09:27:03 INFO - PID 1916 | Values: 58.0 57.0 57.0 61.0 58.0 62.0 54.0 58.0 55.0 60.0 09:27:03 INFO - PID 1916 | 09:27:03 INFO - PID 1916 | [#10] stanford-crypto-aes Cycles:10 Average:89.60 Median:85.00 stddev:14.62 (17.2%) stddev-sans-first:15.25 09:27:03 INFO - PID 1916 | Values: 82.0 80.0 85.0 82.0 84.0 88.0 85.0 92.0 130.0 88.0 09:27:03 INFO - PID 1916 | 09:27:03 INFO - PID 1916 | [#11] stanford-crypto-ccm Cycles:10 Average:143.60 Median:148.00 stddev:10.02 (6.8%) stddev-sans-first:10.25 09:27:03 INFO - PID 1916 | Values: 136.0 150.0 121.0 144.0 149.0 155.0 147.0 147.0 136.0 151.0 09:27:03 INFO - PID 1916 | 09:27:03 INFO - PID 1916 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:199.80 Median:207.50 stddev:21.74 (10.5%) stddev-sans-first:21.80 09:27:03 INFO - PID 1916 | Values: 220.0 212.0 203.0 226.0 232.0 184.0 184.0 189.0 177.0 171.0 09:27:03 INFO - PID 1916 | 09:27:03 INFO - PID 1916 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:76.70 Median:76.00 stddev:13.05 (17.2%) stddev-sans-first:13.25 09:27:03 INFO - PID 1916 | Values: 66.0 66.0 93.0 95.0 96.0 77.0 66.0 75.0 68.0 65.0 09:27:03 INFO - PID 1916 | -------- Summary: end -------- 09:27:03 INFO - PID 1916 | 09:27:04 INFO - TEST-INFO | 1916: exit 0 09:27:04 INFO - TEST-OK | kraken | took 90061ms 09:27:04 INFO - SUITE-END | took 1234s 09:27:04 INFO - Completed test suite (00:20:34) 09:27:04 INFO - javascript benchmark 09:27:04 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8232.356908957852, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3245.8777352761977, 3612.6567976042274, 3637.4180131179, 3643.4899114124028, 3689.9262014759706, 4531.6601254905845, 5679.630823996437, 5863.76544938203, 6244.313125556185, 6250.781222657208, 6178.629282243057, 6208.751649934008, 6214.06788981653, 6237.968810155954, 6240.594361366508, 5439.211314359435, 5451.509364157235, 5460.0, 5463.235147079392, 5609.102543593026, 5552.833414997556, 5569.805056823012, 5586.13414920686, 5595.972020139893, 5600.0, 5544.445555444443, 5551.2228288039705, 5576.944230557703, 5590.496855283024, 5594.58040646951, 5478.506934375925, 5517.117261238222, 5543.750531226085, 5551.278333816598, 5580.358258800249, 5475.6440831345835, 5556.6943818089985, 5572.749226284786, 5581.330240371181, 5592.133219351007, 5130.230465430215, 6033.551947532605, 6355.555111142222, 6415.230172379344, 6425.614463132227, 6637.269900310962, 6751.696173672148, 6753.797386078426, 6885.931140688579, 6914.688839002257, 6795.048693182958, 6837.2820853810135, 6838.760643377507, 6845.247022827485, 6848.417884479825, 6277.843053923642, 6335.746570137235, 6351.80944571659, 6354.2692590352235, 6366.236051673829, 5183.429822719498, 6958.547694399847, 7106.182788979334, 7147.428205743528, 7165.104361954755, 6592.143021407186, 6785.694643741043, 6790.932090679129, 6792.592444453348, 6792.694328755218, 6954.165500140016, 6978.79063628092, 6980.197277313073, 7000.08998830159, 7019.8596028079155, 6527.118838956775, 6600.910849709744, 6628.701708423133, 6643.56816806906, 6649.268580456146, 4954.826581069718, 6842.623655698984, 6875.862482750317, 6878.484113691493, 6911.447084233249, 5291.444398338196, 5298.894022119536, 5340.145576707708, 5367.5169234769055, 5380.354357477127, 4759.643026773006, 4807.014562014849, 4826.7827947742435, 4848.9757551212015, 4869.610431165498, 5337.599680024014, 6141.201643786279, 6277.780277690259, 6324.367563243639, 6344.873102537924, 6853.520253582301, 6854.931450685429, 6858.5199036068, 6862.142232220973, 6880.724771009203, 6217.626942383471, 6334.778282760081, 6381.1066450696935, 6424.871502569922, 6429.38920802523, 5900.704964751745, 5902.498287645598, 6025.457708806229, 6095.085737139376, 6113.449789518964, 6930.722771089302, 6968.825779355557, 6979.476539259677, 6987.545809522365, 6992.3357281058215, 5184.844454666366, 6641.26946035926, 6791.422729068085, 6818.147731533559, 6850.554713943578, 4282.935755963601, 5941.37615550361, 6928.653567321513, 7144.714211431692, 7193.856122877409, 5708.429157084258, 6867.896981545181, 6889.173299203927, 6895.207051188977, 6941.2364639888565, 5785.30576330843, 7018.33325834045, 7025.613591252531, 7041.190263119602, 7056.858862822817, 6568.967155164193, 6660.034295027097, 6672.266050734513, 6677.399034086956, 6751.324867513209, 6564.737410503526, 6615.4046135848, 6633.767818126343, 6634.137562117087, 6650.634215118215, 5009.298698182331, 6532.542722009413, 6653.833654158685, 6684.331566843277, 6687.398134167948, 6745.12313399255, 6774.153230846145, 6801.829954251183, 6808.183018037867, 6817.0, 6414.0, 6488.2538508072175, 6660.200775906922, 6662.267150613332, 6671.566348187353], "value": 6145.681947948573, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [761.094297785635, 814.3892080939296, 816.7998840284131, 843.6709683223544, 1107.7895199912011, 25871.74128258717, 26636.20091397259, 30363.240918977037, 36852.710155144574, 37373.0, 37927.431088533696, 38415.807920960426, 38431.231375372474, 38439.61560384395, 38553.0, 8182.345412366996, 8362.540060296682, 8365.330773538133, 8368.288695460893, 8384.371172162082, 7500.699972001114, 8208.343332533414, 8239.382046346518, 8330.916690833075, 8354.0, 7364.33720965113, 7774.2614451627005, 8324.0, 8499.872501912445, 8535.231829135377, 8434.915650843473, 8488.0, 8488.575571221476, 8502.192291732275, 8504.404691671585, 8306.54314012729, 8343.874841877378, 8356.039055508634, 8362.498250104953, 8384.496930184208, 36330.45504317437, 36755.44866827, 36871.26257474862, 36895.815520922224, 37012.81493592542, 3439.071450708295, 3469.5489586353865, 3474.8262586870555, 3477.5479187705437, 3486.877959271413, 29803.95686150974, 30305.393892122032, 30323.090307290597, 30356.696433035828, 30427.543586846215, 2988.7310142087317, 3060.3420264642987, 3082.090783218933, 3090.428270769915, 3109.176068341891, 243849.0, 246314.9999999982, 246734.0, 292354.0764592348, 294476.0, 38684.41973370402, 38749.225015499534, 44000.0, 45379.546204538194, 47343.28985065227, 49903.75048124736, 49993.25010124851, 50067.0, 50073.0, 50077.49922500801, 13867.791983120262, 13930.233837138852, 14059.437622494986, 14075.944304177228, 14092.013559050973, 11283.0, 11341.546338146547, 11366.602168924048, 11400.828987565194, 11402.20184587071, 11319.151063670257, 13198.406071726795, 13421.664458388423, 13465.922726182078, 13511.932440337736, 5814.401039714113, 5884.676342801102, 5891.882162356729, 5925.377835327228, 5950.899083669535, 974.2157563161722, 981.3130808434124, 983.680303901235, 1035.891231420705, 1039.4023436524028, 21889.67165492519, 22806.973686184163, 23069.0, 23116.422089447562, 23140.305790826304, 1070.2347821307803, 1085.4301491716753, 1094.3871431998107, 1123.685368096934, 1126.2172789911097, 11382.747897731242, 11584.42077896102, 11741.88258117425, 11816.05471562273, 12152.817707734392, 283748.0, 296517.0, 297424.0, 298501.0, 298510.01489985257, 52221.0, 52239.0, 52536.73731631317, 52767.472325276256, 52974.73512632489, 1288.2592509307185, 1317.6244770240435, 1493.529538195465, 1532.7930729351617, 1534.8465153484785, 16797.244124014203, 17982.820171798114, 22287.77712222857, 22618.0, 23342.949567269854, 36248.456273155396, 37227.441588376736, 37298.813505932296, 37300.62699372972, 37363.439548406255, 246250.0, 248882.0, 249045.0, 249182.0, 249438.0, 27023.783929723602, 27714.861425692743, 30330.696693032787, 33800.323993520484, 33838.66161338355, 36087.63912360843, 38099.619003810716, 39185.60814391934, 39621.80189099036, 39745.2050958985, 26894.73105268922, 27192.864035679693, 27310.726892730818, 27579.44841103207, 28726.42547149087, 27227.86386068057, 28558.857205713837, 28569.714302857537, 28762.0, 29488.705112949454, 31804.933393321327, 33142.5028624566, 33680.83159584186, 33790.831045844614, 33819.3236135271, 26899.462010760068, 28688.713112868605, 28716.994905179054, 29823.850880745456, 31359.529607056367, 24175.274741757774, 24227.273181804576, 24309.635355469327, 24350.0, 24523.896424660576], "value": 16634.38791418999, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [500.41201588133936, 504.8914483386071, 507.5837812993345, 516.8397796683028, 530.3105962249075, 21680.0, 21813.345599631997, 22641.54716905661, 22681.886590567046, 23229.070837166535, 24136.758632413672, 24661.506769864638, 24685.0, 24735.257942261716, 24773.380665483328, 6440.935590644092, 6604.504662150334, 6610.570312929644, 6614.50391220658, 6625.072489851401, 5794.71026448678, 6114.663693496856, 6382.585131966431, 6508.772192973247, 6595.835104122388, 6342.143810585558, 6377.8405539861415, 6384.0, 6394.520410969173, 6403.903941440882, 6379.808605741835, 6398.808035758934, 6407.006913928349, 6408.198975128085, 6422.325655806143, 5900.852478688067, 5903.3801450847905, 5904.675242861641, 5911.113333000041, 5919.112133180014, 29317.41365172706, 30735.692643073507, 30914.381712365852, 31516.36967260665, 31659.683403165905, 2208.6355751301016, 2259.706238189025, 2272.670462782887, 2274.6019446596947, 2278.3392816083315, 19176.328828490936, 19706.90146549258, 19740.605187896304, 19751.012449377475, 19752.901235493875, 2180.356794745538, 2231.9107235710553, 2253.2001139595372, 2273.2498275569023, 2279.2478482101033, 145660.0, 152910.0, 153281.0, 154050.0, 202574.0, 11423.314601123959, 14077.788833167511, 14252.714945701027, 14868.0, 15546.533603991898, 20657.690134647994, 20687.58624827495, 20696.275630353168, 20716.792832071787, 20723.0, 9571.760705982406, 9697.418154910727, 9714.271429642806, 9824.607015719292, 9939.155171810333, 9073.54632268384, 9109.681161159328, 9111.635534578543, 9151.35940484173, 9163.358564900393, 8944.50805205707, 8947.776305592412, 9055.637774489078, 9070.727878163665, 9084.364094513321, 2608.6478325426206, 2608.704340180629, 2620.6331113644105, 2622.475504899028, 2753.0777189641294, 933.673214374977, 934.5093825741404, 935.6725146198912, 985.8176237395961, 1004.9547770350206, 21190.576188476145, 21479.78520214809, 21647.242346517796, 21985.890070549864, 22036.228731994303, 836.2807985132781, 867.8568036274071, 968.3560432312433, 974.6880998080546, 1064.3667018124204, 9642.951785241028, 10924.672259832218, 10935.890641093647, 10974.341539507655, 11293.54825806975, 529995.5362341321, 549308.0, 557840.0, 560713.0, 564035.0, 146034.0, 146555.0, 146991.0, 147548.0, 149520.0, 1014.4775440648073, 1028.6965345223177, 1068.9198310126617, 1075.4837677914488, 1196.0730433913614, 20146.194152233747, 20361.796382036584, 23672.28983130509, 24126.75873241245, 24253.878730606233, 67586.66206668936, 69031.0, 70036.64981675059, 70561.29438705547, 71314.64342678254, 88641.0, 92068.0, 92583.53708231416, 94027.52986235025, 96209.0, 11596.94201528987, 11709.063274937815, 11763.294202347886, 11814.763704725685, 11865.525378984745, 16025.759613606038, 17737.911310443364, 18201.90788552691, 18820.717689234945, 19341.806581934565, 14138.717225655224, 15580.298886549906, 15587.142707150762, 15591.220438977778, 15796.92101539485, 13018.349082546025, 13240.735185296433, 13696.31518424055, 14406.279686015867, 14414.42342306296, 14226.573202803933, 15814.288357024192, 15896.523104306889, 15981.44064957721, 16088.758668620214, 11848.585299514476, 11940.462679179283, 11957.880421195676, 11960.58137965167, 11986.76026479448, 14667.853321467075, 15279.624833765014, 15485.303161357991, 15567.922160389578, 15626.609334766266], "value": 11712.144336880343, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1393.707321462493, 1462.7293950619144, 1471.0585225455704, 1481.570344600066, 1513.5988962924823, 12757.425915833786, 13270.469181232751, 13662.795058074136, 13864.445422183113, 13884.722305553883, 10288.845667314996, 11269.09847212223, 11272.661820145388, 11280.210385273014, 11283.548658053689, 2285.8514196577253, 2347.1667558016916, 2350.3654013416367, 2581.4707984863057, 2677.571588545833, 2747.917562473129, 2749.9450010999767, 2789.888404463819, 2790.5674620433865, 2795.4688609164295, 2277.521720438702, 2713.3894873653367, 2754.6969833318317, 2758.600002999564, 2761.337279053033, 2651.708312085669, 2683.2218656589566, 2688.502627014009, 2691.111933062085, 2695.3665888516184, 1692.898426094426, 1778.5197996540992, 2308.376738280672, 2550.4389034412397, 2660.0689758584535, 11121.165912556591, 11144.941230583163, 11165.497552610152, 11259.099272058216, 12972.87027129726, 6824.0, 7085.078939737853, 7218.0, 7588.089429268468, 7596.734114305973, 4030.45588845505, 4536.492105565262, 4773.976130119362, 4882.804687812519, 4904.6321525885705, 1355.3629793996904, 1375.0786972728297, 1402.3689339797131, 1406.0790182430524, 1407.655124494495, 15713.450029249036, 19774.505637359038, 19943.700844487343, 20051.79948200514, 20085.995700215077, 6736.2590115087305, 6865.794026179223, 6894.551854129466, 7003.494248736546, 7015.859682806417, 16283.918580407259, 16316.592085197728, 16317.59206019859, 16320.0, 16344.427945022106, 7524.5861477619255, 7636.847263054708, 7642.08295004603, 7642.656080476392, 7672.079350477867, 6344.111824344595, 6779.118714567173, 6860.931390686029, 6866.793996180123, 6907.585544867324, 4219.514755803122, 5174.741262936838, 5258.106121959276, 5334.333208348957, 5449.5640348772, 1373.9450421983208, 1382.8478867324586, 1653.0, 1660.2362913059885, 1678.5048410718866, 625.8060001399581, 836.2766207230702, 837.6440012994453, 859.9742007739778, 927.9211267042242, 6892.793216203522, 7100.0, 7304.5252058616015, 7363.926360736324, 7378.483506154517, 954.3319676226669, 956.9234461243084, 966.4104896013426, 998.8601595776597, 1012.832882574367, 4277.721948073366, 4288.699791014599, 4319.460067491564, 4395.912081758347, 4603.3785438965315, 17464.039477828588, 18145.00202488876, 18164.45506634803, 18199.545011374557, 18220.27118915255, 17830.46508604744, 17983.0, 18038.549036273937, 18080.819191808176, 18087.0, 7805.921940780633, 8637.568121593895, 9358.064193580587, 9483.620655173854, 9530.1899338557, 7597.278258565428, 7705.460617756703, 7760.611969401621, 7994.5203287802915, 8059.556724380215, 14640.780388293972, 15609.21953902323, 15743.842561574238, 15903.602409939844, 15928.681426371639, 15163.0, 15358.38566457374, 15918.044917304998, 15980.360785568912, 16764.24560894787, 10399.636012739518, 10448.164146868081, 10542.314749541256, 10545.10366618846, 10563.841542376716, 12200.084993625336, 12539.561115360919, 12621.621351359474, 12626.116171867881, 12629.17910335862, 9812.460314682476, 9862.112409883144, 10074.748131296776, 10228.641997530349, 10264.973502649675, 7467.813304667427, 9680.080392362714, 9686.660966866126, 9712.611495540383, 9813.9018609813, 9277.768055798659, 9342.859857102285, 9382.81234375295, 9384.859227111463, 9399.953000234955, 6532.138340572914, 6716.496262780409, 6720.663966801542, 6725.293844146294, 6954.547954382949, 6726.260111387645, 6832.829179270359, 6928.584284942919, 7096.432285417051, 7131.037309963089], "value": 5964.306380631072, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [395.9267535505931, 430.73940266139005, 440.8765545647218, 471.516695387228, 472.7210945542129, 5755.1942728018, 6344.333844946272, 6427.935720642792, 6471.935280647198, 6542.967285163573, 5603.1058414401905, 5997.52019838413, 6192.5974811637325, 6193.69031548423, 6210.192674952261, 4651.232546629802, 4812.32627432158, 4955.380577427822, 4961.255811628266, 4983.177775667029, 4706.152892479369, 4818.903621927577, 4972.552470277675, 4993.226049962262, 4994.300797888299, 4979.502049795028, 4981.37732783402, 4998.800047998076, 5001.299818025479, 5004.249362595603, 4082.4284600156, 4711.316859055418, 4974.0797952378925, 4991.900161996758, 5027.597792176617, 4258.659307255412, 4504.684672072956, 4520.502744698113, 4532.592066713979, 4551.908961820745, 5144.279800827887, 6162.322144564139, 6189.195404597373, 6229.159063526411, 6295.842603934892, 4968.87577810554, 5168.457311982263, 5511.366192887789, 5975.342712301687, 6010.879782404371, 6205.610839826654, 6566.606003639797, 6914.585124892523, 7003.089598352232, 7030.121234845645, 4516.932246016312, 4735.928961065587, 4803.855884323476, 5219.373675158968, 5221.582273418117, 7125.465590080713, 7190.173130090055, 7214.422846172293, 7251.92748072523, 7254.963725181393, 4691.9530804692195, 4811.302361157683, 5076.314697515862, 5402.972985135049, 5403.594730395236, 4843.273508973612, 5464.7814087436855, 5499.422560631157, 5500.312460942382, 5508.55931525477, 5997.520198384119, 6002.529380301854, 6022.25987175834, 6033.215681961405, 6039.607425517327, 6931.445484361239, 6949.548279361826, 6955.295952491672, 6964.373206411447, 6964.965175174193, 1494.3350209157038, 1857.452051644768, 1865.7574515313195, 1881.2286962345372, 1894.1665667106429, 1180.2682336950973, 1230.4585982167996, 1279.878411550901, 1332.6135420728074, 1350.4328182163515, 5793.7392817323325, 6360.332165122688, 6380.936190638127, 6523.8695226095215, 6554.606723596599, 6763.323667633197, 6823.404808057346, 7091.184513780878, 7097.680604372816, 7119.4660400470175, 6379.71291291893, 6397.424231819158, 6399.200099987502, 6402.775902843471, 6406.775762848371, 3522.225110475691, 3606.9819650902105, 3625.4743062256352, 3640.9635903641156, 3664.790619095692, 7007.5795452272005, 7178.641067946582, 7185.353318201387, 7227.710891564384, 7314.634268286671, 6686.53194276396, 7241.565506069653, 7283.198848126702, 7285.744998925118, 7326.780196594111, 6476.369395881873, 7026.191987921352, 7243.927560724431, 7318.0, 7325.926740732738, 5386.973065134649, 5883.470487656132, 5903.439173278532, 6750.561213521106, 7127.322904324081, 6939.271376505596, 6963.373296403348, 6983.336583024605, 6987.580745155307, 7000.719971201095, 6134.969325153345, 7253.093363329584, 7299.5620262784405, 7304.356519783219, 7415.3326200642205, 5392.299001129828, 5396.920615876918, 5397.460253974728, 5404.7297635118875, 5442.591805614516, 6807.460120737288, 7092.5389849659605, 7134.215236324104, 7135.894911001371, 7146.32109949554, 5474.753636086306, 5610.158476228598, 5830.096335068071, 6855.794326170223, 6874.106366172366, 5618.410066942912, 6808.5914845109455, 6841.93158068413, 6849.589024658537, 6850.862982740417, 6010.939890601038, 6493.415592596689, 6612.768553100618, 6721.126253587003, 6721.462283017249, 5445.50990410865, 5928.140419639214, 6299.716512756845, 6809.319068093151, 6959.96520017417, 6657.671164417791, 6662.533622646368, 6670.232923213893, 6691.498137639599, 6703.463722902058], "value": 5151.170009457672, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [824.4640983360815, 834.4659417972495, 841.7516832534405, 870.8476016697076, 873.5326600268853, 16761.7485737714, 17243.051632160226, 17380.913095434506, 17459.476215713516, 17464.12679366033, 8614.612342444605, 11277.37974411407, 11654.592089276877, 12708.36458177092, 13665.975051871099, 651.9250286217099, 660.9669516524178, 667.7329068372666, 946.049220533363, 952.8332541805191, 1005.36661903001, 1006.9546870390814, 1007.6976906927893, 1009.7980403919208, 1010.4745532323187, 1034.782695633914, 1046.8953104689547, 1047.706642140202, 1048.072455876551, 1049.6168898352123, 679.6907407129744, 1023.8361862102065, 1036.8548403223556, 1040.6669865642998, 1044.843273508976, 509.0988949559296, 513.5968264912026, 514.1567828760798, 514.9613778966592, 727.6434547071949, 17407.912960435242, 17515.8614690046, 17740.379086731904, 17763.023033733138, 17801.46595602134, 16963.406280780237, 17017.319307227695, 17615.207315670825, 17672.646547068987, 17809.554761130945, 13906.58280251594, 14704.632384190374, 14739.926300368537, 14870.0, 15042.849571504254, 3575.4994300797907, 3607.729420293462, 3612.8554857805657, 3614.765040272374, 3617.186133120027, 100949.0, 152725.23637381854, 153479.0, 160852.19573902056, 163207.18396407942, 12938.094333396572, 13081.672958175932, 13164.802527962089, 13259.0, 14234.715305694035, 26022.73977260203, 26648.333791655365, 26669.0, 26681.33296667599, 26725.19824405271, 7844.607769611496, 8026.43814932949, 8048.557329346942, 8081.757547273592, 8096.473729207582, 3618.8962366478477, 3734.775913445202, 3975.801209939549, 4041.4948131483566, 4055.371417430303, 4643.257078867433, 4695.835645752382, 4703.529647035338, 4708.011317623337, 4713.67004309695, 871.9564021798884, 973.9172170365457, 1019.6278358399131, 1032.1897310611284, 1036.4662198967537, 647.6819671967618, 663.575311800448, 663.8937769956784, 681.9147606549182, 682.8907374820005, 11163.94418027921, 11239.10818165917, 12160.513579456723, 12649.62051138449, 12878.67803304925, 634.6826586706646, 641.7561326695826, 643.2698886763557, 663.6084710021111, 673.9755571531246, 5327.573794096463, 5636.239107720406, 5657.377688454267, 5815.127730840323, 5953.255843019622, 87791.85457017948, 116918.0, 118002.0, 118726.4063679676, 122955.0, 101278.49360753149, 105504.0, 106086.0, 111977.44011279891, 113098.0, 83665.0, 89680.0, 91688.0, 96441.5533766994, 99073.00926990926, 9134.908650913672, 11532.769344613229, 12154.878451215374, 12227.938860305641, 12534.558525769653, 36726.53093876215, 42503.5749642512, 45211.773941130086, 45421.54578454305, 45931.08137837292, 58706.0, 59546.809063819346, 62178.0, 62841.68579157075, 63093.422664433754, 16487.505374838773, 16567.585810354838, 17041.062741548838, 17051.48845534636, 17292.308307668056, 17817.73273400874, 18147.092645367942, 19023.23907043758, 19023.904880476064, 19316.93756843387, 17265.39571115005, 17972.460826175236, 18024.188911499277, 18530.44408667742, 18590.07049647485, 17242.137893105544, 17734.290628375238, 17738.5565360867, 17863.0, 18033.639327213645, 16058.919705401398, 16431.26059327309, 16474.06342933561, 16747.497575072768, 17709.114544272994, 11579.247348922294, 11883.465244064211, 12113.454894529592, 12131.575394861138, 12523.74952500963, 11910.880891190976, 12933.741325173256, 13339.399727012114, 13438.177982623758, 13604.659883502596], "value": 8461.360921194046, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1537.4999999999995, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [103.0, 115.0, 137.0, 128.0, 126.0, 114.0, 114.0, 118.0, 115.0, 117.0], "value": 118.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [129.0, 126.0, 144.0, 135.0, 110.0, 137.0, 129.0, 112.0, 153.0, 110.0], "value": 128.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [196.0, 200.0, 196.0, 198.0, 209.0, 221.0, 229.0, 208.0, 240.0, 195.0], "value": 209.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 81.0, 73.0, 125.0, 52.0, 53.0, 60.0, 89.0, 166.0, 69.0], "value": 84.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 99.0, 128.0, 58.0, 98.0, 104.0, 107.0, 72.0, 107.0, 147.0], "value": 99.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 90.0, 89.0, 89.0, 90.0, 88.0, 89.0, 90.0, 90.0, 88.0], "value": 89.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 96.0, 97.0, 96.0, 97.0, 95.0, 96.0, 96.0, 97.0, 96.0], "value": 96.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 91.0, 91.0, 91.0, 95.0, 90.0, 92.0, 93.0, 91.0, 91.0], "value": 91.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [51.0, 49.0, 50.0, 52.0, 53.0, 54.0, 53.0, 52.0, 53.0, 53.0], "value": 52.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 57.0, 57.0, 61.0, 58.0, 62.0, 54.0, 58.0, 55.0, 60.0], "value": 58.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 80.0, 85.0, 82.0, 84.0, 88.0, 85.0, 92.0, 130.0, 88.0], "value": 89.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [136.0, 150.0, 121.0, 144.0, 149.0, 155.0, 147.0, 147.0, 136.0, 151.0], "value": 143.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [220.0, 212.0, 203.0, 226.0, 232.0, 184.0, 184.0, 189.0, 177.0, 171.0], "value": 199.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 66.0, 93.0, 95.0, 96.0, 77.0, 66.0, 75.0, 68.0, 65.0], "value": 76.7, "unit": "score"}]}]} 09:27:04 INFO - Return code: 0 09:27:04 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:27:04 INFO - # TBPL SUCCESS # 09:27:04 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:27:04 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:27:04 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:27:04 INFO - 2017-06-08 09:27:04.937 screenresolution[1924:12128] starting screenresolution argv=screenresolution get 09:27:04 INFO - 2017-06-08 09:27:04.939 screenresolution[1924:12128] Display 0: 1600x1200x32@60 09:27:04 INFO - 2017-06-08 09:27:04.945 screenresolution[1925:12131] starting screenresolution argv=screenresolution list 09:27:04 INFO - Available Modes on Display 0 09:27:04 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:27:04 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:27:04 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:27:04 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:27:05 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:27:05 INFO - Intel Iris: 09:27:05 INFO - Chipset Model: Intel Iris 09:27:05 INFO - Type: GPU 09:27:05 INFO - Bus: Built-In 09:27:05 INFO - VRAM (Dynamic, Max): 1536 MB 09:27:05 INFO - Vendor: Intel (0x8086) 09:27:05 INFO - Device ID: 0x0a2e 09:27:05 INFO - Revision ID: 0x0009 09:27:05 INFO - Displays: 09:27:05 INFO - Display: 09:27:05 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:27:05 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:27:05 INFO - Display Serial Number: 1600x1200 60 09:27:05 INFO - Main Display: Yes 09:27:05 INFO - Mirror: Off 09:27:05 INFO - Online: Yes 09:27:05 INFO - Rotation: Supported 09:27:05 INFO - Return code: 0 09:27:05 INFO - Running post-action listener: _resource_record_post_action 09:27:05 INFO - [mozharness: 2017-06-08 16:27:05.181768Z] Finished run-tests step (success) 09:27:05 INFO - Running post-run listener: _resource_record_post_run 09:27:05 INFO - Total resource usage - Wall time: 1257s; CPU: 53.0%; Read bytes: 3323904; Write bytes: 568656896; Read time: 31; Write time: 1746 09:27:05 INFO - TinderboxPrint: CPU usage
53.0% 09:27:05 INFO - TinderboxPrint: I/O read bytes / time
3,323,904 / 31 09:27:05 INFO - TinderboxPrint: I/O write bytes / time
568,656,896 / 1,746 09:27:05 INFO - TinderboxPrint: CPU idle
2,364.7 (47.0%) 09:27:05 INFO - TinderboxPrint: CPU system
1,013.2 (20.1%) 09:27:05 INFO - TinderboxPrint: CPU user
1,651.8 (32.8%) 09:27:05 INFO - TinderboxPrint: Swap in / out
250,978,304 / 0 09:27:05 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 247886336; Write bytes: 250558464; Read time: 18091; Write time: 536 09:27:05 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 09:27:05 INFO - run-tests - Wall time: 1236s; CPU: 53.0%; Read bytes: 2414592; Write bytes: 316783616; Read time: 27; Write time: 1200 09:27:05 INFO - Running post-run listener: _upload_blobber_files 09:27:05 INFO - Blob upload gear active. 09:27:05 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:27:05 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 09:27:05 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'] 09:27:05 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 09:27:06 INFO - (blobuploader) - INFO - Open directory for files ... 09:27:06 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 09:27:06 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:27:06 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:27:06 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 09:27:06 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:27:06 INFO - (blobuploader) - INFO - Done attempting. 09:27:06 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 09:27:06 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:27:06 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:27:08 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 09:27:08 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:27:08 INFO - (blobuploader) - INFO - Done attempting. 09:27:08 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 09:27:08 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:27:08 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:27:09 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 09:27:09 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:27:09 INFO - (blobuploader) - INFO - Done attempting. 09:27:09 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:27:09 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:27:09 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:27:11 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:27:11 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:27:11 INFO - (blobuploader) - INFO - Done attempting. 09:27:11 INFO - (blobuploader) - INFO - Iteration through files over. 09:27:11 INFO - Return code: 0 09:27:11 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:27:11 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:27:11 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/823e34666bc672fb8da4de54c0a33656f57b4eb6403524e817952ded69e40dbc062a8ac617611f5237ffb2de6c2cb7860a774ae9ccd3ab91bd99be28d230fbd7", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/0dc7ecf44026329dce130081c81b98ebaabb04f52c47b968e90349e3d15a0eb6e7d695b04e94a332e9825b0ff4fe893733f0e06cb6ae02881e61e7c54673f2e3", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/ef1f77c117429346605b08b29faf4cdefbb401c25d69c00d3293cbc54dd7aa7e5232a998bfc014b9b7839c5336e840e4ea7c73d053390d0ff45571f5210b4634", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/ed4915693c1a226d8eefe8be6de2e640b6256db104a03440df7684bb0382b386f42e70987efac2458fef632bbf6342314d54d2d05d49f1d73a9869b85c6416df"} 09:27:11 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:27:11 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:27:11 INFO - Contents: 09:27:11 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/823e34666bc672fb8da4de54c0a33656f57b4eb6403524e817952ded69e40dbc062a8ac617611f5237ffb2de6c2cb7860a774ae9ccd3ab91bd99be28d230fbd7", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/0dc7ecf44026329dce130081c81b98ebaabb04f52c47b968e90349e3d15a0eb6e7d695b04e94a332e9825b0ff4fe893733f0e06cb6ae02881e61e7c54673f2e3", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/ef1f77c117429346605b08b29faf4cdefbb401c25d69c00d3293cbc54dd7aa7e5232a998bfc014b9b7839c5336e840e4ea7c73d053390d0ff45571f5210b4634", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/ed4915693c1a226d8eefe8be6de2e640b6256db104a03440df7684bb0382b386f42e70987efac2458fef632bbf6342314d54d2d05d49f1d73a9869b85c6416df"} 09:27:11 INFO - Running post-run listener: copy_logs_to_upload_dir 09:27:11 INFO - Copying logs to upload dir... 09:27:11 INFO - mkdir: /builds/slave/test/build/upload/logs 09:27:11 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1338.253223 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 18 secs) (at 2017-06-08 09:27:12.010464) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-08 09:27:12.016730) ========= 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.qnpBEZjfrH/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.SqnH5amz20/Listeners TMPDIR=/var/folders/tg/9cs16h3j7pn5rd3v0kkz9lgc00000w/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/823e34666bc672fb8da4de54c0a33656f57b4eb6403524e817952ded69e40dbc062a8ac617611f5237ffb2de6c2cb7860a774ae9ccd3ab91bd99be28d230fbd7", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/0dc7ecf44026329dce130081c81b98ebaabb04f52c47b968e90349e3d15a0eb6e7d695b04e94a332e9825b0ff4fe893733f0e06cb6ae02881e61e7c54673f2e3", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/ef1f77c117429346605b08b29faf4cdefbb401c25d69c00d3293cbc54dd7aa7e5232a998bfc014b9b7839c5336e840e4ea7c73d053390d0ff45571f5210b4634", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/ed4915693c1a226d8eefe8be6de2e640b6256db104a03440df7684bb0382b386f42e70987efac2458fef632bbf6342314d54d2d05d49f1d73a9869b85c6416df"} build_url:https://queue.taskcluster.net/v1/task/H5-1TngUQ0eCUF0rZe-uxw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010686 build_url: 'https://queue.taskcluster.net/v1/task/H5-1TngUQ0eCUF0rZe-uxw/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/823e34666bc672fb8da4de54c0a33656f57b4eb6403524e817952ded69e40dbc062a8ac617611f5237ffb2de6c2cb7860a774ae9ccd3ab91bd99be28d230fbd7", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/0dc7ecf44026329dce130081c81b98ebaabb04f52c47b968e90349e3d15a0eb6e7d695b04e94a332e9825b0ff4fe893733f0e06cb6ae02881e61e7c54673f2e3", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/ef1f77c117429346605b08b29faf4cdefbb401c25d69c00d3293cbc54dd7aa7e5232a998bfc014b9b7839c5336e840e4ea7c73d053390d0ff45571f5210b4634", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/ed4915693c1a226d8eefe8be6de2e640b6256db104a03440df7684bb0382b386f42e70987efac2458fef632bbf6342314d54d2d05d49f1d73a9869b85c6416df"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-08 09:27:12.052622) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-08 09:27:12.052923) ========= 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.qnpBEZjfrH/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.SqnH5amz20/Listeners TMPDIR=/var/folders/tg/9cs16h3j7pn5rd3v0kkz9lgc00000w/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.005065 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-08 09:27:12.101458) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-08 09:27:12.101750) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-08 09:27:12.102036) ========= ========= Total master_lag: 0.19 =========