builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0342 starttime: 1496228072.88 results: success (0) revision: 23243677c7dd56781ccccb70b748cec412780516 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-05-31 03:54:32.878395) ========= master: http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-05-31 03:54:32.878873) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-31 03:54:32.879200) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-31 03:54:32.946090) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-31 03:54:32.946479) ========= 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.zKfgE3tRXU/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.yyiFmqEUdl/Listeners TMPDIR=/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-05-31 03:54:32-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 18.4M=0.001s 2017-05-31 03:54:33 (18.4 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.510536 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-31 03:54:33.490826) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-31 03:54:33.491107) ========= 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.zKfgE3tRXU/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.yyiFmqEUdl/Listeners TMPDIR=/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/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.040934 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-31 03:54:33.556656) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-05-31 03:54:33.557004) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 23243677c7dd56781ccccb70b748cec412780516 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'python archiver_client.py mozharness --repo mozilla-central --rev 23243677c7dd56781ccccb70b748cec412780516 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.zKfgE3tRXU/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.yyiFmqEUdl/Listeners TMPDIR=/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-05-31 03:54:33,652 truncating revision to first 12 chars 2017-05-31 03:54:33,652 Setting DEBUG logging. 2017-05-31 03:54:33,652 attempt 1/10 2017-05-31 03:54:33,652 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/23243677c7dd?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-05-31 03:54:35,525 unpacking tar archive at: mozilla-central-23243677c7dd/testing/mozharness/ program finished with exit code 0 elapsedTime=2.146370 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-05-31 03:54:35.725517) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-31 03:54:35.725858) ========= script_repo_revision: 23243677c7dd56781ccccb70b748cec412780516 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-31 03:54:35.726248) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-31 03:54:35.726520) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-31 03:54:35.745861) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 18 secs) (at 2017-05-31 03:54:35.746141) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.zKfgE3tRXU/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.yyiFmqEUdl/Listeners TMPDIR=/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/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 03:54:35 INFO - MultiFileLogger online at 20170531 03:54:35 in /builds/slave/test 03:54:35 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 03:54:35 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 03:54:35 INFO - {'append_to_log': False, 03:54:35 INFO - 'base_work_dir': '/builds/slave/test', 03:54:35 INFO - 'blob_upload_branch': 'Firefox', 03:54:35 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 03:54:35 INFO - 'branch': 'Firefox', 03:54:35 INFO - 'buildbot_json_path': 'buildprops.json', 03:54:35 INFO - 'config_files': ('talos/mac_config.py',), 03:54:35 INFO - 'default_actions': ('clobber', 03:54:35 INFO - 'read-buildbot-config', 03:54:35 INFO - 'download-and-extract', 03:54:35 INFO - 'populate-webroot', 03:54:35 INFO - 'create-virtualenv', 03:54:35 INFO - 'install', 03:54:35 INFO - 'setup-mitmproxy', 03:54:35 INFO - 'run-tests'), 03:54:35 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 03:54:35 INFO - 'download_minidump_stackwalk': True, 03:54:35 INFO - 'download_symbols': 'ondemand', 03:54:35 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 03:54:35 INFO - 'tooltool.py': '/tools/tooltool.py', 03:54:35 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 03:54:35 INFO - '/tools/misc-python/virtualenv.py')}, 03:54:35 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 03:54:35 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 03:54:35 INFO - 'gecko_profile': False, 03:54:35 INFO - 'gecko_profile_interval': 0, 03:54:35 INFO - 'installer_path': 'installer.exe', 03:54:35 INFO - 'log_level': 'info', 03:54:35 INFO - 'log_name': 'talos', 03:54:35 INFO - 'log_to_console': True, 03:54:35 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 03:54:35 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 03:54:35 INFO - 'opt_config_files': (), 03:54:35 INFO - 'pip_index': False, 03:54:35 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 03:54:35 INFO - 'cmd': ('bash', 03:54:35 INFO - '-c', 03:54:35 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 03:54:35 INFO - 'enabled': True, 03:54:35 INFO - 'halt_on_failure': False, 03:54:35 INFO - 'name': 'check_screen_resolution'},), 03:54:35 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 03:54:35 INFO - 'cmd': ('bash', 03:54:35 INFO - '-c', 03:54:35 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 03:54:35 INFO - 'enabled': True, 03:54:35 INFO - 'halt_on_failure': False, 03:54:35 INFO - 'name': 'check_screen_resolution'},), 03:54:35 INFO - 'run_cmd_checks_enabled': True, 03:54:35 INFO - 'suite': 'dromaeojs-e10s', 03:54:35 INFO - 'system_bits': '32', 03:54:35 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 03:54:35 INFO - 'title': 't-yosemite-r7-0342', 03:54:35 INFO - 'tooltool_cache': '/builds/tooltool_cache', 03:54:35 INFO - 'use_talos_json': True, 03:54:35 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 03:54:35 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 03:54:35 INFO - 'work_dir': 'build'} 03:54:35 INFO - [mozharness: 2017-05-31 10:54:35.951367Z] Running clobber step. 03:54:35 INFO - Running pre-action listener: _resource_record_pre_action 03:54:35 INFO - Running main action method: clobber 03:54:35 INFO - rmtree: /builds/slave/test/build 03:54:35 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 03:54:38 INFO - Running post-action listener: _resource_record_post_action 03:54:38 INFO - [mozharness: 2017-05-31 10:54:38.389188Z] Finished clobber step (success) 03:54:38 INFO - [mozharness: 2017-05-31 10:54:38.389307Z] Running read-buildbot-config step. 03:54:38 INFO - Running pre-action listener: _resource_record_pre_action 03:54:38 INFO - Running main action method: read_buildbot_config 03:54:38 INFO - Using buildbot properties: 03:54:38 INFO - { 03:54:38 INFO - "project": "", 03:54:38 INFO - "product": "firefox", 03:54:38 INFO - "who": "cbook@mozilla.com", 03:54:38 INFO - "installer_path": "public/build/firefox-55.0a1.en-US.mac.dmg", 03:54:38 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 03:54:38 INFO - "repository": "https://hg.mozilla.org/mozilla-central/", 03:54:38 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 03:54:38 INFO - "stage_platform": "macosx64", 03:54:38 INFO - "basedir": "/builds/slave/test", 03:54:38 INFO - "buildnumber": 83, 03:54:38 INFO - "platform": "yosemite_r7", 03:54:38 INFO - "parent_task_id": "azuV_t__TF2AkFnJB7Fikw", 03:54:38 INFO - "master": "http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/", 03:54:38 INFO - "slavebuilddir": "test", 03:54:38 INFO - "taskId": "QEwy3yhsSoi8gblqTbp8uw", 03:54:38 INFO - "branch": "mozilla-central", 03:54:38 INFO - "script_repo_revision": "production", 03:54:38 INFO - "revision": "23243677c7dd56781ccccb70b748cec412780516", 03:54:38 INFO - "slavename": "t-yosemite-r7-0342", 03:54:38 INFO - "repo_path": "mozilla-central" 03:54:38 INFO - } 03:54:38 INFO - Finding installer, test and symbols from parent task. 03:54:38 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/QEwy3yhsSoi8gblqTbp8uw'}, attempt #1 03:54:39 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/QEwy3yhsSoi8gblqTbp8uw'}, attempt #1 03:54:39 INFO - Task dependencies: HC4sZQY8TB-i4rT0pwKikA azuV_t__TF2AkFnJB7Fikw 03:54:39 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/azuV_t__TF2AkFnJB7Fikw'}, attempt #1 03:54:40 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/azuV_t__TF2AkFnJB7Fikw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 03:54:40 INFO - Set test_url: https://queue.taskcluster.net/v1/task/azuV_t__TF2AkFnJB7Fikw/artifacts/public/build/test_packages.json 03:54:40 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/azuV_t__TF2AkFnJB7Fikw/artifacts/public/build/target.crashreporter-symbols.zip 03:54:40 INFO - Running post-action listener: _resource_record_post_action 03:54:40 INFO - [mozharness: 2017-05-31 10:54:40.153757Z] Finished read-buildbot-config step (success) 03:54:40 INFO - [mozharness: 2017-05-31 10:54:40.153901Z] Running download-and-extract step. 03:54:40 INFO - Running pre-action listener: _resource_record_pre_action 03:54:40 INFO - Running main action method: download_and_extract 03:54:40 INFO - mkdir: /builds/slave/test/build/tests 03:54:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:54:40 INFO - trying https://queue.taskcluster.net/v1/task/azuV_t__TF2AkFnJB7Fikw/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 03:54:40 INFO - Downloading https://queue.taskcluster.net/v1/task/azuV_t__TF2AkFnJB7Fikw/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 03:54:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/azuV_t__TF2AkFnJB7Fikw/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 03:54:41 INFO - Downloaded 1325 bytes. 03:54:41 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 03:54:41 INFO - Using the following test package requirements: 03:54:41 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 03:54:41 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 03:54:41 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 03:54:41 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 03:54:41 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 03:54:41 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 03:54:41 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 03:54:41 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 03:54:41 INFO - u'jsshell-mac.zip'], 03:54:41 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 03:54:41 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 03:54:41 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 03:54:41 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 03:54:41 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 03:54:41 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 03:54:41 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 03:54:41 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 03:54:41 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 03:54:41 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 03:54:41 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 03:54:41 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 03:54:41 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/azuV_t__TF2AkFnJB7Fikw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 03:54:41 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/azuV_t__TF2AkFnJB7Fikw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 03:54:41 INFO - Fetch https://queue.taskcluster.net/v1/task/azuV_t__TF2AkFnJB7Fikw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 03:54:42 INFO - Content-Length response header: 57630951 03:54:42 INFO - Bytes received: 57630951 03:55:00 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 03:55:00 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/azuV_t__TF2AkFnJB7Fikw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 03:55:00 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/azuV_t__TF2AkFnJB7Fikw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 03:55:00 INFO - Fetch https://queue.taskcluster.net/v1/task/azuV_t__TF2AkFnJB7Fikw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 03:55:02 INFO - Content-Length response header: 57630951 03:55:02 INFO - Bytes received: 57630951 03:55:19 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/azuV_t__TF2AkFnJB7Fikw/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 03:55:19 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/azuV_t__TF2AkFnJB7Fikw/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 03:55:19 INFO - Fetch https://queue.taskcluster.net/v1/task/azuV_t__TF2AkFnJB7Fikw/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 03:55:21 INFO - Content-Length response header: 14447425 03:55:21 INFO - Bytes received: 14447425 03:55:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:55:21 INFO - trying https://queue.taskcluster.net/v1/task/azuV_t__TF2AkFnJB7Fikw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 03:55:21 INFO - Downloading https://queue.taskcluster.net/v1/task/azuV_t__TF2AkFnJB7Fikw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 03:55:21 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/azuV_t__TF2AkFnJB7Fikw/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 03:55:23 INFO - Downloaded 67793318 bytes. 03:55:23 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/azuV_t__TF2AkFnJB7Fikw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 03:55:23 INFO - mkdir: /builds/slave/test/properties 03:55:23 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 03:55:23 INFO - Writing to file /builds/slave/test/properties/build_url 03:55:23 INFO - Contents: 03:55:23 INFO - build_url:https://queue.taskcluster.net/v1/task/azuV_t__TF2AkFnJB7Fikw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 03:55:23 INFO - Running post-action listener: _resource_record_post_action 03:55:23 INFO - Running post-action listener: set_extra_try_arguments 03:55:23 INFO - [mozharness: 2017-05-31 10:55:23.384146Z] Finished download-and-extract step (success) 03:55:23 INFO - [mozharness: 2017-05-31 10:55:23.384288Z] Running populate-webroot step. 03:55:23 INFO - Running pre-action listener: _resource_record_pre_action 03:55:23 INFO - Running main action method: populate_webroot 03:55:23 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 03:55:23 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 03:55:23 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 03:55:23 INFO - '--tppagecycles', 03:55:23 INFO - '7'], 03:55:23 INFO - 'tests': ['tsvgm']}}, 03:55:23 INFO - 'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 03:55:23 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 03:55:23 INFO - 'tests': ['dromaeo_css', 'kraken']}, 03:55:23 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 03:55:23 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 03:55:23 INFO - 'talos_options': ['--disable-e10s'], 03:55:23 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:55:23 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 03:55:23 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:55:23 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 03:55:23 INFO - 'talos_options': ['--disable-e10s'], 03:55:23 INFO - 'tests': ['damp', 'tps']}, 03:55:23 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 03:55:23 INFO - 'tests': ['damp', 'tps']}, 03:55:23 INFO - 'g3': {'talos_options': ['--disable-e10s'], 03:55:23 INFO - 'tests': ['dromaeo_dom']}, 03:55:23 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 03:55:23 INFO - 'g4': {'talos_options': ['--disable-e10s'], 03:55:23 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 03:55:23 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 03:55:23 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 03:55:23 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 03:55:23 INFO - 'other': {'talos_options': ['--disable-e10s'], 03:55:23 INFO - 'tests': ['a11yr', 03:55:23 INFO - 'ts_paint', 03:55:23 INFO - 'tpaint', 03:55:23 INFO - 'sessionrestore', 03:55:23 INFO - 'sessionrestore_no_auto_restore', 03:55:23 INFO - 'tabpaint']}, 03:55:23 INFO - 'other-e10s': {'tests': ['a11yr', 03:55:23 INFO - 'ts_paint', 03:55:23 INFO - 'tpaint', 03:55:23 INFO - 'sessionrestore', 03:55:23 INFO - 'sessionrestore_no_auto_restore', 03:55:23 INFO - 'tabpaint']}, 03:55:23 INFO - 'other-e10s_l64': {'tests': ['a11yr', 03:55:23 INFO - 'ts_paint', 03:55:23 INFO - 'tpaint', 03:55:23 INFO - 'sessionrestore', 03:55:23 INFO - 'sessionrestore_no_auto_restore', 03:55:23 INFO - 'tabpaint']}, 03:55:23 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 03:55:23 INFO - 'ts_paint', 03:55:23 INFO - 'tpaint', 03:55:23 INFO - 'sessionrestore', 03:55:23 INFO - 'sessionrestore_no_auto_restore', 03:55:23 INFO - 'tabpaint']}, 03:55:23 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 03:55:23 INFO - 'tests': ['a11yr', 03:55:23 INFO - 'ts_paint', 03:55:23 INFO - 'tpaint', 03:55:23 INFO - 'sessionrestore', 03:55:23 INFO - 'sessionrestore_no_auto_restore', 03:55:23 INFO - 'tabpaint']}, 03:55:23 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 03:55:23 INFO - 'tests': ['a11yr', 03:55:23 INFO - 'ts_paint', 03:55:23 INFO - 'tpaint', 03:55:23 INFO - 'sessionrestore', 03:55:23 INFO - 'sessionrestore_no_auto_restore', 03:55:23 INFO - 'tabpaint']}, 03:55:23 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 03:55:23 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 03:55:23 INFO - 'bloom_basic_ref']}, 03:55:23 INFO - 'quantum-pageload-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set.zip', 03:55:23 INFO - 'talos_options': ['--mitmproxy', 03:55:23 INFO - 'mitmproxy-recording-1.mp', 03:55:23 INFO - '--firstNonBlankPaint'], 03:55:23 INFO - 'tests': ['Quantum_1']}, 03:55:23 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 03:55:23 INFO - 'tests': ['tsvgx', 03:55:23 INFO - 'tsvgr_opacity', 03:55:23 INFO - 'tart', 03:55:23 INFO - 'tscrollx', 03:55:23 INFO - 'cart', 03:55:23 INFO - 'tsvg_static']}, 03:55:23 INFO - 'svgr-e10s': {'tests': ['tsvgx', 03:55:23 INFO - 'tsvgr_opacity', 03:55:23 INFO - 'tart', 03:55:23 INFO - 'tscrollx', 03:55:23 INFO - 'cart', 03:55:23 INFO - 'tsvg_static']}, 03:55:23 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 03:55:23 INFO - 'talos_options': ['--disable-e10s'], 03:55:23 INFO - 'tests': ['tp5o']}, 03:55:23 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 03:55:23 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 03:55:23 INFO - 'talos_options': ['--disable-e10s', 03:55:23 INFO - '--xperf_path', 03:55:23 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:55:23 INFO - 'tests': ['tp5n']}, 03:55:23 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 03:55:23 INFO - 'talos_options': ['--xperf_path', 03:55:23 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:55:23 INFO - 'tests': ['tp5n']}}, 03:55:23 INFO - 'talos.zip': {'path': '', 03:55:23 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 03:55:23 INFO - Running post-action listener: _resource_record_post_action 03:55:23 INFO - [mozharness: 2017-05-31 10:55:23.406272Z] Finished populate-webroot step (success) 03:55:23 INFO - [mozharness: 2017-05-31 10:55:23.406408Z] Running create-virtualenv step. 03:55:23 INFO - Running pre-action listener: _resource_record_pre_action 03:55:23 INFO - Running main action method: create_virtualenv 03:55:23 INFO - Creating virtualenv /builds/slave/test/build/venv 03:55:23 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 03:55:23 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 03:55:23 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 03:55:23 INFO - Using real prefix '/tools/python27' 03:55:23 INFO - New python executable in /builds/slave/test/build/venv/bin/python 03:55:24 INFO - Installing distribute.............................................................................................................................................................................................done. 03:55:27 INFO - Installing pip.................done. 03:55:27 INFO - Return code: 0 03:55:27 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 03:55:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 03:55:27 INFO - Reading from file tmpfile_stdout 03:55:27 INFO - Output received: 03:55:27 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) 03:55:27 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 03:55:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:55:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:55:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:55:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:55:27 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 0x1094a6c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1094aa768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd769576970>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10942fc60>, '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 0x1091c4cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1091edbe8>, '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.zKfgE3tRXU/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.yyiFmqEUdl/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/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 03:55:27 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 03:55:27 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 03:55:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zKfgE3tRXU/Render', 03:55:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:55:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:55:27 INFO - 'HOME': '/Users/cltbld', 03:55:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:55:27 INFO - 'LOGNAME': 'cltbld', 03:55:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:55:27 INFO - 'MOZ_NO_REMOTE': '1', 03:55:27 INFO - 'NO_EM_RESTART': '1', 03:55:27 INFO - 'PAGER': '/bin/cat', 03:55:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:55:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:55:27 INFO - 'PWD': '/builds/slave/test', 03:55:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:55:27 INFO - 'SHELL': '/bin/bash', 03:55:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yyiFmqEUdl/Listeners', 03:55:27 INFO - 'TMPDIR': '/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/', 03:55:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:55:27 INFO - 'USER': 'cltbld', 03:55:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:55:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:55:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:55:27 INFO - 'XPC_FLAGS': '0x0', 03:55:27 INFO - 'XPC_SERVICE_NAME': '0', 03:55:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:55:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:55:27 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 03:55:27 INFO - Cleaning up... 03:55:27 INFO - Return code: 0 03:55:27 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 03:55:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:55:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:55:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:55:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:55:27 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 0x1094a6c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1094aa768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd769576970>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10942fc60>, '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 0x1091c4cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1091edbe8>, '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.zKfgE3tRXU/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.yyiFmqEUdl/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/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 03:55:27 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 03:55:27 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 03:55:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zKfgE3tRXU/Render', 03:55:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:55:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:55:27 INFO - 'HOME': '/Users/cltbld', 03:55:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:55:27 INFO - 'LOGNAME': 'cltbld', 03:55:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:55:27 INFO - 'MOZ_NO_REMOTE': '1', 03:55:27 INFO - 'NO_EM_RESTART': '1', 03:55:27 INFO - 'PAGER': '/bin/cat', 03:55:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:55:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:55:27 INFO - 'PWD': '/builds/slave/test', 03:55:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:55:27 INFO - 'SHELL': '/bin/bash', 03:55:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yyiFmqEUdl/Listeners', 03:55:27 INFO - 'TMPDIR': '/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/', 03:55:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:55:27 INFO - 'USER': 'cltbld', 03:55:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:55:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:55:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:55:27 INFO - 'XPC_FLAGS': '0x0', 03:55:27 INFO - 'XPC_SERVICE_NAME': '0', 03:55:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:55:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:55:27 INFO - Downloading/unpacking psutil>=3.1.1 03:55:27 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 03:55:27 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 03:55:27 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 03:55:27 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 03:55:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 03:55:30 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:55:30 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:55:30 INFO - Installing collected packages: psutil 03:55:30 INFO - Running setup.py install for psutil 03:55:30 INFO - building 'psutil._psutil_osx' extension 03:55:30 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 03:55:30 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 03:55:30 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 03:55:31 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 03:55:31 INFO - building 'psutil._psutil_posix' extension 03:55:31 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 03:55:31 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 03:55:31 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 03:55:31 INFO - ^ 03:55:31 INFO - 1 warning generated. 03:55:31 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 03:55:31 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:55:31 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:55:31 INFO - Successfully installed psutil 03:55:31 INFO - Cleaning up... 03:55:31 INFO - Return code: 0 03:55:31 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 03:55:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:55:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:55:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:55:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:55:31 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1094a6c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1094aa768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd769576970>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10942fc60>, '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 0x1091c4cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1091edbe8>, '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.zKfgE3tRXU/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.yyiFmqEUdl/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/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 03:55:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 03:55:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 03:55:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zKfgE3tRXU/Render', 03:55:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:55:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:55:31 INFO - 'HOME': '/Users/cltbld', 03:55:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:55:31 INFO - 'LOGNAME': 'cltbld', 03:55:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:55:31 INFO - 'MOZ_NO_REMOTE': '1', 03:55:31 INFO - 'NO_EM_RESTART': '1', 03:55:31 INFO - 'PAGER': '/bin/cat', 03:55:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:55:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:55:31 INFO - 'PWD': '/builds/slave/test', 03:55:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:55:31 INFO - 'SHELL': '/bin/bash', 03:55:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yyiFmqEUdl/Listeners', 03:55:31 INFO - 'TMPDIR': '/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/', 03:55:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:55:31 INFO - 'USER': 'cltbld', 03:55:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:55:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:55:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:55:31 INFO - 'XPC_FLAGS': '0x0', 03:55:31 INFO - 'XPC_SERVICE_NAME': '0', 03:55:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:55:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:55:31 INFO - Downloading/unpacking mozsystemmonitor==0.3 03:55:31 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 03:55:31 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 03:55:31 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 03:55:31 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 03:55:33 INFO - Downloading mozsystemmonitor-0.3.tar.gz 03:55:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 03:55:33 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 03:55:33 INFO - Installing collected packages: mozsystemmonitor 03:55:33 INFO - Running setup.py install for mozsystemmonitor 03:55:33 INFO - Successfully installed mozsystemmonitor 03:55:33 INFO - Cleaning up... 03:55:33 INFO - Return code: 0 03:55:33 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 03:55:33 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')]} 03:55:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:55:33 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')]} 03:55:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:55:33 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 0x1094a6c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1094aa768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd769576970>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10942fc60>, '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 0x1091c4cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1091edbe8>, '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.zKfgE3tRXU/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.yyiFmqEUdl/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/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 03:55:33 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 03:55:33 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 03:55:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zKfgE3tRXU/Render', 03:55:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:55:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:55:33 INFO - 'HOME': '/Users/cltbld', 03:55:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:55:33 INFO - 'LOGNAME': 'cltbld', 03:55:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:55:33 INFO - 'MOZ_NO_REMOTE': '1', 03:55:33 INFO - 'NO_EM_RESTART': '1', 03:55:33 INFO - 'PAGER': '/bin/cat', 03:55:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:55:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:55:33 INFO - 'PWD': '/builds/slave/test', 03:55:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:55:33 INFO - 'SHELL': '/bin/bash', 03:55:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yyiFmqEUdl/Listeners', 03:55:33 INFO - 'TMPDIR': '/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/', 03:55:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:55:33 INFO - 'USER': 'cltbld', 03:55:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:55:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:55:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:55:33 INFO - 'XPC_FLAGS': '0x0', 03:55:33 INFO - 'XPC_SERVICE_NAME': '0', 03:55:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:55:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:55:33 INFO - Downloading/unpacking jsonschema==2.5.1 03:55:33 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 03:55:33 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 03:55:33 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 03:55:33 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 03:55:34 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 03:55:34 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 03:55:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:55:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:55:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:55:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:55:35 INFO - Downloading functools32-3.2.3-2.tar.gz 03:55:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 03:55:35 INFO - warning: no files found matching '*.txt' 03:55:35 INFO - no previously-included directories found matching 'build' 03:55:35 INFO - no previously-included directories found matching 'dist' 03:55:35 INFO - no previously-included directories found matching '.git*' 03:55:35 INFO - Installing collected packages: jsonschema, functools32 03:55:35 INFO - Running setup.py install for functools32 03:55:35 INFO - warning: no files found matching '*.txt' 03:55:35 INFO - no previously-included directories found matching 'build' 03:55:35 INFO - no previously-included directories found matching 'dist' 03:55:35 INFO - no previously-included directories found matching '.git*' 03:55:35 INFO - Successfully installed jsonschema functools32 03:55:35 INFO - Cleaning up... 03:55:35 INFO - Return code: 0 03:55:35 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 03:55:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:55:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:55:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:55:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:55:35 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1094a6c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1094aa768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd769576970>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10942fc60>, '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 0x1091c4cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1091edbe8>, '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.zKfgE3tRXU/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.yyiFmqEUdl/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/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 03:55:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 03:55:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 03:55:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zKfgE3tRXU/Render', 03:55:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:55:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:55:35 INFO - 'HOME': '/Users/cltbld', 03:55:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:55:35 INFO - 'LOGNAME': 'cltbld', 03:55:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:55:35 INFO - 'MOZ_NO_REMOTE': '1', 03:55:35 INFO - 'NO_EM_RESTART': '1', 03:55:35 INFO - 'PAGER': '/bin/cat', 03:55:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:55:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:55:35 INFO - 'PWD': '/builds/slave/test', 03:55:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:55:35 INFO - 'SHELL': '/bin/bash', 03:55:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yyiFmqEUdl/Listeners', 03:55:35 INFO - 'TMPDIR': '/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/', 03:55:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:55:35 INFO - 'USER': 'cltbld', 03:55:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:55:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:55:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:55:35 INFO - 'XPC_FLAGS': '0x0', 03:55:35 INFO - 'XPC_SERVICE_NAME': '0', 03:55:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:55:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:55:35 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 03:55:35 INFO - Cleaning up... 03:55:35 INFO - Return code: 0 03:55:35 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 03:55:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:55:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:55:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:55:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:55:35 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1094a6c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1094aa768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd769576970>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10942fc60>, '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 0x1091c4cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1091edbe8>, '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.zKfgE3tRXU/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.yyiFmqEUdl/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/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 03:55:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 03:55:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 03:55:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zKfgE3tRXU/Render', 03:55:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:55:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:55:35 INFO - 'HOME': '/Users/cltbld', 03:55:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:55:35 INFO - 'LOGNAME': 'cltbld', 03:55:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:55:35 INFO - 'MOZ_NO_REMOTE': '1', 03:55:35 INFO - 'NO_EM_RESTART': '1', 03:55:35 INFO - 'PAGER': '/bin/cat', 03:55:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:55:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:55:35 INFO - 'PWD': '/builds/slave/test', 03:55:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:55:35 INFO - 'SHELL': '/bin/bash', 03:55:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yyiFmqEUdl/Listeners', 03:55:35 INFO - 'TMPDIR': '/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/', 03:55:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:55:35 INFO - 'USER': 'cltbld', 03:55:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:55:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:55:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:55:35 INFO - 'XPC_FLAGS': '0x0', 03:55:35 INFO - 'XPC_SERVICE_NAME': '0', 03:55:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:55:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:55:36 INFO - Downloading/unpacking blobuploader==1.2.4 03:55:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:55:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:55:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:55:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:55:37 INFO - Downloading blobuploader-1.2.4.tar.gz 03:55:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 03:55:37 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 03:55:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:55:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:55:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:55:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:55:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 03:55:38 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 03:55:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:55:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:55:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:55:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:55:38 INFO - Downloading docopt-0.6.1.tar.gz 03:55:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 03:55:38 INFO - Installing collected packages: blobuploader, requests, docopt 03:55:38 INFO - Running setup.py install for blobuploader 03:55:38 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 03:55:38 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 03:55:38 INFO - Running setup.py install for requests 03:55:39 INFO - Running setup.py install for docopt 03:55:39 INFO - Successfully installed blobuploader requests docopt 03:55:39 INFO - Cleaning up... 03:55:39 INFO - Return code: 0 03:55:39 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:55:39 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')]} 03:55:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:55:39 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')]} 03:55:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:55:39 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 0x1094a6c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1094aa768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd769576970>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10942fc60>, '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 0x1091c4cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1091edbe8>, '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.zKfgE3tRXU/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.yyiFmqEUdl/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/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 03:55:39 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 03:55:39 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 03:55:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zKfgE3tRXU/Render', 03:55:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:55:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:55:39 INFO - 'HOME': '/Users/cltbld', 03:55:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:55:39 INFO - 'LOGNAME': 'cltbld', 03:55:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:55:39 INFO - 'MOZ_NO_REMOTE': '1', 03:55:39 INFO - 'NO_EM_RESTART': '1', 03:55:39 INFO - 'PAGER': '/bin/cat', 03:55:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:55:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:55:39 INFO - 'PWD': '/builds/slave/test', 03:55:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:55:39 INFO - 'SHELL': '/bin/bash', 03:55:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yyiFmqEUdl/Listeners', 03:55:39 INFO - 'TMPDIR': '/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/', 03:55:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:55:39 INFO - 'USER': 'cltbld', 03:55:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:55:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:55:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:55:39 INFO - 'XPC_FLAGS': '0x0', 03:55:39 INFO - 'XPC_SERVICE_NAME': '0', 03:55:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:55:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:55:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:55:39 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-Twni3C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:55:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:55:39 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-Plokn1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:55:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:55:39 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-fTqTMr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:55:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:55:39 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-ygWTn9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:55:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:55:39 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-yBMKBL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:55:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:55:40 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-fMED1M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:55:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:55:40 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-oW3Rja-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:55:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:55:40 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-Zhn7YA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:55:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:55:40 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-qTDKAh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:55:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:55:40 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-qEkAtM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:55:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:55:40 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-QSoM9D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:55:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:55:40 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-Y272lB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:55:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:55:40 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-k4eTl2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:55:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:55:40 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-VSo9NL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:55:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:55:41 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-qHhxv0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:55:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:55:41 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-uoiEfu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:55:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:55:41 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-J9HEVA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:55:41 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 03:55:41 INFO - Running setup.py install for manifestparser 03:55:41 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 03:55:41 INFO - Running setup.py install for mozcrash 03:55:41 INFO - Running setup.py install for mozdebug 03:55:41 INFO - Running setup.py install for mozdevice 03:55:41 INFO - Installing dm script to /builds/slave/test/build/venv/bin 03:55:41 INFO - Running setup.py install for mozfile 03:55:42 INFO - Running setup.py install for mozhttpd 03:55:42 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 03:55:42 INFO - Running setup.py install for mozinfo 03:55:42 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 03:55:42 INFO - Running setup.py install for mozInstall 03:55:42 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 03:55:42 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 03:55:42 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 03:55:42 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 03:55:42 INFO - Running setup.py install for mozleak 03:55:42 INFO - Running setup.py install for mozlog 03:55:42 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 03:55:42 INFO - Running setup.py install for moznetwork 03:55:42 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 03:55:42 INFO - Running setup.py install for mozprocess 03:55:43 INFO - Running setup.py install for mozprofile 03:55:43 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 03:55:43 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 03:55:43 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 03:55:43 INFO - Running setup.py install for mozrunner 03:55:43 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 03:55:43 INFO - Running setup.py install for mozscreenshot 03:55:43 INFO - Running setup.py install for moztest 03:55:43 INFO - Running setup.py install for mozversion 03:55:43 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 03:55:43 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 03:55:43 INFO - Cleaning up... 03:55:43 INFO - Return code: 0 03:55:43 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:55: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')]} 03:55:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:55: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')]} 03:55:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:55:43 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1094a6c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1094aa768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd769576970>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10942fc60>, '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 0x1091c4cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1091edbe8>, '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.zKfgE3tRXU/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.yyiFmqEUdl/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/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 03:55:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 03:55:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 03:55:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zKfgE3tRXU/Render', 03:55:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:55:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:55:43 INFO - 'HOME': '/Users/cltbld', 03:55:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:55:43 INFO - 'LOGNAME': 'cltbld', 03:55:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:55:43 INFO - 'MOZ_NO_REMOTE': '1', 03:55:43 INFO - 'NO_EM_RESTART': '1', 03:55:43 INFO - 'PAGER': '/bin/cat', 03:55:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:55:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:55:43 INFO - 'PWD': '/builds/slave/test', 03:55:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:55:43 INFO - 'SHELL': '/bin/bash', 03:55:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yyiFmqEUdl/Listeners', 03:55:43 INFO - 'TMPDIR': '/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/', 03:55:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:55:43 INFO - 'USER': 'cltbld', 03:55:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:55:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:55:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:55:43 INFO - 'XPC_FLAGS': '0x0', 03:55:43 INFO - 'XPC_SERVICE_NAME': '0', 03:55:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:55:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:55:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:55:44 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-9Ojiz9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:55:44 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)) 03:55:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:55:44 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-3WAFot-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:55:44 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)) 03:55:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:55:44 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-oHxOtc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:55:44 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)) 03:55:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:55:44 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-iHNneF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:55:44 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)) 03:55:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:55:44 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-N2n7JM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:55:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 03:55:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:55:44 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-hO4CnO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:55:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 03:55:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:55:44 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-3ba2oZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:55:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 03:55:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:55:44 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-bmaf3z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:55:45 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)) 03:55:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:55:45 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-vrKyyO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:55:45 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)) 03:55:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:55:45 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-ncPT0f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:55:45 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)) 03:55:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:55:45 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-xGPLvr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:55:45 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)) 03:55:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:55:45 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-Y8wA8G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:55:45 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)) 03:55:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:55:45 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-f2SMep-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:55:45 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)) 03:55:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:55:45 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-rA4bxJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:55:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 03:55:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:55:45 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-6Veedm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:55:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 03:55:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:55:45 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-YjWsL_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:55:45 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 03:55:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:55:45 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-gzl_2j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:55:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 03:55:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 03:55:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 03:55:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 03:55:45 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 03:55:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 03:55:45 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 03:55:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:55:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:55:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:55:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:55:47 INFO - Downloading blessings-1.6.tar.gz 03:55:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 03:55:47 INFO - Installing collected packages: blessings 03:55:47 INFO - Running setup.py install for blessings 03:55:47 INFO - Successfully installed blessings 03:55:47 INFO - Cleaning up... 03:55:47 INFO - Return code: 0 03:55:47 INFO - Done creating virtualenv /builds/slave/test/build/venv. 03:55:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:55:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:55:47 INFO - Reading from file tmpfile_stdout 03:55:47 INFO - Current package versions: 03:55:47 INFO - blessings == 1.6 03:55:47 INFO - blobuploader == 1.2.4 03:55:47 INFO - docopt == 0.6.1 03:55:47 INFO - functools32 == 3.2.3-2 03:55:47 INFO - jsonschema == 2.5.1 03:55:47 INFO - manifestparser == 1.1 03:55:47 INFO - mozInstall == 1.12 03:55:47 INFO - mozcrash == 1.0 03:55:47 INFO - mozdebug == 0.1 03:55:47 INFO - mozdevice == 0.51 03:55:47 INFO - mozfile == 1.2 03:55:47 INFO - mozhttpd == 0.7 03:55:47 INFO - mozinfo == 0.9 03:55:47 INFO - mozleak == 0.1 03:55:47 INFO - mozlog == 3.4 03:55:47 INFO - moznetwork == 0.27 03:55:47 INFO - mozprocess == 0.25 03:55:47 INFO - mozprofile == 0.28 03:55:47 INFO - mozrunner == 6.13 03:55:47 INFO - mozscreenshot == 0.1 03:55:47 INFO - mozsystemmonitor == 0.3 03:55:47 INFO - moztest == 0.8 03:55:47 INFO - mozversion == 1.4 03:55:47 INFO - psutil == 3.1.1 03:55:47 INFO - requests == 1.2.3 03:55:47 INFO - wsgiref == 0.1.2 03:55:47 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:55: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')]} 03:55:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:55: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')]} 03:55:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:55:47 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1094a6c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1094aa768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd769576970>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10942fc60>, '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 0x1091c4cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1091edbe8>, '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.zKfgE3tRXU/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.yyiFmqEUdl/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/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 03:55:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 03:55:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 03:55:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zKfgE3tRXU/Render', 03:55:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:55:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:55:47 INFO - 'HOME': '/Users/cltbld', 03:55:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:55:47 INFO - 'LOGNAME': 'cltbld', 03:55:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:55:47 INFO - 'MOZ_NO_REMOTE': '1', 03:55:47 INFO - 'NO_EM_RESTART': '1', 03:55:47 INFO - 'PAGER': '/bin/cat', 03:55:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:55:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:55:47 INFO - 'PWD': '/builds/slave/test', 03:55:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:55:47 INFO - 'SHELL': '/bin/bash', 03:55:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yyiFmqEUdl/Listeners', 03:55:47 INFO - 'TMPDIR': '/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/', 03:55:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:55:47 INFO - 'USER': 'cltbld', 03:55:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:55:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:55:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:55:47 INFO - 'XPC_FLAGS': '0x0', 03:55:47 INFO - 'XPC_SERVICE_NAME': '0', 03:55:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:55:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:55:48 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)) 03:55:48 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)) 03:55:48 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)) 03:55:48 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)) 03:55:48 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)) 03:55:48 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)) 03:55:48 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)) 03:55:48 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)) 03:55:48 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)) 03:55:48 INFO - Downloading/unpacking marionette-driver>=1.4.0 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 03:55:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:55:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:55:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:55:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:55:49 INFO - Downloading marionette_driver-1.4.0.tar.gz 03:55:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/marionette-driver/setup.py) egg_info for package marionette-driver 03:55:49 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)) 03:55:49 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)) 03:55:49 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)) 03:55:49 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)) 03:55:49 INFO - Installing collected packages: marionette-driver 03:55:49 INFO - Running setup.py install for marionette-driver 03:55:49 INFO - Successfully installed marionette-driver 03:55:49 INFO - Cleaning up... 03:55:49 INFO - Return code: 0 03:55:49 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 03:55: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')]} 03:55:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:55: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')]} 03:55:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:55: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']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1094a6c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1094aa768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd769576970>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10942fc60>, '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 0x1091c4cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1091edbe8>, '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.zKfgE3tRXU/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.yyiFmqEUdl/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/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 03:55: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'] in /builds/slave/test/build 03:55: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 03:55:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zKfgE3tRXU/Render', 03:55:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:55:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:55:49 INFO - 'HOME': '/Users/cltbld', 03:55:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:55:49 INFO - 'LOGNAME': 'cltbld', 03:55:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:55:49 INFO - 'MOZ_NO_REMOTE': '1', 03:55:49 INFO - 'NO_EM_RESTART': '1', 03:55:49 INFO - 'PAGER': '/bin/cat', 03:55:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:55:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:55:49 INFO - 'PWD': '/builds/slave/test', 03:55:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:55:49 INFO - 'SHELL': '/bin/bash', 03:55:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yyiFmqEUdl/Listeners', 03:55:49 INFO - 'TMPDIR': '/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/', 03:55:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:55:49 INFO - 'USER': 'cltbld', 03:55:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:55:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:55:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:55:49 INFO - 'XPC_FLAGS': '0x0', 03:55:49 INFO - 'XPC_SERVICE_NAME': '0', 03:55:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:55:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:55:50 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 03:55:50 INFO - Cleaning up... 03:55:50 INFO - Return code: 0 03:55:50 INFO - Running post-action listener: _resource_record_post_action 03:55:50 INFO - Running post-action listener: _start_resource_monitoring 03:55:50 INFO - Starting resource monitoring. 03:55:50 INFO - [mozharness: 2017-05-31 10:55:50.239260Z] Finished create-virtualenv step (success) 03:55:50 INFO - [mozharness: 2017-05-31 10:55:50.240216Z] Running install step. 03:55:50 INFO - Running pre-action listener: _resource_record_pre_action 03:55:50 INFO - Running main action method: install 03:55:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:55:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:55:50 INFO - Reading from file tmpfile_stdout 03:55:50 INFO - Detecting whether we're running mozinstall >=1.0... 03:55:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 03:55:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 03:55:50 INFO - Reading from file tmpfile_stdout 03:55:50 INFO - Output received: 03:55:50 INFO - Usage: mozinstall [options] installer 03:55:50 INFO - Options: 03:55:50 INFO - -h, --help show this help message and exit 03:55:50 INFO - -d DEST, --destination=DEST 03:55:50 INFO - Directory to install application into. [default: 03:55:50 INFO - "/builds/slave/test"] 03:55:50 INFO - --app=APP Application being installed. [default: firefox] 03:55:50 INFO - mkdir: /builds/slave/test/build/application 03:55:50 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'] 03:55:50 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 03:56:12 INFO - Reading from file tmpfile_stdout 03:56:12 INFO - Output received: 03:56:12 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 03:56:12 INFO - Running post-action listener: _resource_record_post_action 03:56:12 INFO - [mozharness: 2017-05-31 10:56:12.071111Z] Finished install step (success) 03:56:12 INFO - [mozharness: 2017-05-31 10:56:12.071261Z] Running setup-mitmproxy step. 03:56:12 INFO - Running pre-action listener: _resource_record_pre_action 03:56:12 INFO - Running main action method: setup_mitmproxy 03:56:12 INFO - Skipping: mitmproxy is not required 03:56:12 INFO - Running post-action listener: _resource_record_post_action 03:56:12 INFO - [mozharness: 2017-05-31 10:56:12.071726Z] Finished setup-mitmproxy step (success) 03:56:12 INFO - [mozharness: 2017-05-31 10:56:12.071815Z] Running run-tests step. 03:56:12 INFO - Running pre-action listener: _resource_record_pre_action 03:56:12 INFO - Running main action method: run_tests 03:56:12 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 03:56:12 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 03:56:12 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 03:56:12 INFO - 2017-05-31 03:56:12.084 screenresolution[1662:8846] starting screenresolution argv=screenresolution get 03:56:12 INFO - 2017-05-31 03:56:12.086 screenresolution[1662:8846] Display 0: 1600x1200x32@60 03:56:12 INFO - 2017-05-31 03:56:12.091 screenresolution[1663:8849] starting screenresolution argv=screenresolution list 03:56:12 INFO - Available Modes on Display 0 03:56:12 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 03:56:12 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 03:56:12 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 03:56:12 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 03:56:12 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 03:56:12 INFO - Intel Iris: 03:56:12 INFO - Chipset Model: Intel Iris 03:56:12 INFO - Type: GPU 03:56:12 INFO - Bus: Built-In 03:56:12 INFO - VRAM (Dynamic, Max): 1536 MB 03:56:12 INFO - Vendor: Intel (0x8086) 03:56:12 INFO - Device ID: 0x0a2e 03:56:12 INFO - Revision ID: 0x0009 03:56:12 INFO - Displays: 03:56:12 INFO - Display: 03:56:12 INFO - Resolution: 1600 x 1200 @ 60 Hz 03:56:12 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 03:56:12 INFO - Display Serial Number: 1600x1200 60 03:56:12 INFO - Main Display: Yes 03:56:12 INFO - Mirror: Off 03:56:12 INFO - Online: Yes 03:56:12 INFO - Rotation: Supported 03:56:12 INFO - Return code: 0 03:56:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 03:56:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 03:56:12 INFO - Python 2.7.3 03:56:12 INFO - Return code: 0 03:56:12 INFO - grabbing minidump binary from tooltool 03:56:12 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')]} 03:56:12 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 0x10942fc60>, '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 0x1091c4cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1091edbe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 03:56:12 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 03:56:12 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 03:56:12 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 03:56:12 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 03:56:12 INFO - Return code: 0 03:56:12 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 03:56:12 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 03:56:12 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 03:56:12 INFO - ENV: RUST_BACKTRACE is now 1 03:56:12 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 03:56:12 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 03:56:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/azuV_t__TF2AkFnJB7Fikw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0342', '--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 03:56:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/azuV_t__TF2AkFnJB7Fikw/artifacts/public/build/target.crashreporter-symbols.zip --title t-yosemite-r7-0342 --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 03:56:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zKfgE3tRXU/Render', 03:56:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:56:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:56:12 INFO - 'HOME': '/Users/cltbld', 03:56:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:56:12 INFO - 'LOGNAME': 'cltbld', 03:56:12 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:56:12 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 03:56:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:56:12 INFO - 'MOZ_NO_REMOTE': '1', 03:56:12 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:56:12 INFO - 'NO_EM_RESTART': '1', 03:56:12 INFO - 'PAGER': '/bin/cat', 03:56:12 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:56:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:56:12 INFO - 'PWD': '/builds/slave/test', 03:56:12 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:56:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:56:12 INFO - 'RUST_BACKTRACE': '1', 03:56:12 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 03:56:12 INFO - 'SHELL': '/bin/bash', 03:56:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yyiFmqEUdl/Listeners', 03:56:12 INFO - 'TMPDIR': '/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/', 03:56:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:56:12 INFO - 'USER': 'cltbld', 03:56:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:56:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:56:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:56:12 INFO - 'XPC_FLAGS': '0x0', 03:56:12 INFO - 'XPC_SERVICE_NAME': '0', 03:56:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:56:12 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/azuV_t__TF2AkFnJB7Fikw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0342', '--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 03:56:12 INFO - mozversion application_buildid: 20170531022823 03:56:12 INFO - mozversion application_changeset: 23243677c7dd56781ccccb70b748cec412780516 03:56:12 INFO - mozversion application_display_name: Nightly 03:56:12 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 03:56:12 INFO - mozversion application_name: Firefox 03:56:12 INFO - mozversion application_remotingname: firefox 03:56:12 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 03:56:12 INFO - mozversion application_vendor: Mozilla 03:56:12 INFO - mozversion application_version: 55.0a1 03:56:12 INFO - mozversion platform_buildid: 20170531022823 03:56:12 INFO - mozversion platform_changeset: 23243677c7dd56781ccccb70b748cec412780516 03:56:12 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 03:56:12 INFO - mozversion platform_version: 55.0a1 03:56:12 INFO - using testdate: 1496228172 03:56:12 INFO - actual date: 1496228172 03:56:12 INFO - starting webserver on 'localhost:49228' 03:56:12 INFO - SUITE-START | Running 2 tests 03:56:12 INFO - TEST-START | dromaeo_css 03:56:12 INFO - operating with platform_type : mac_ 03:56:12 INFO - Initialising browser for dromaeo_css test... 03:56:12 INFO - Application command: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49228/getInfo.html -foreground -profile /var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/tmpyp_Q4g/profile 03:56:12 INFO - TEST-INFO | started process 1673 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49228/getInfo.html -foreground) 03:56:46 INFO - TEST-INFO | 1673: killed by SIGTERM 03:56:46 INFO - Browser initialized. 03:56:46 INFO - Running cycle 1/1 for dromaeo_css test... 03:56:46 INFO - TEST-INFO | started process 1681 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/tmpyp_Q4g/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:56:48 INFO - PID 1681 | 2017-05-31 03:56:48.729 plugin-container[1682:9348] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x973f, name = 'com.apple.tsm.portname' 03:56:48 INFO - PID 1681 | See /usr/include/servers/bootstrap_defs.h for the error codes. 03:56:48 INFO - PID 1681 | 2017-05-31 03:56:48.729 plugin-container[1682:9348] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9f0b, name = 'com.apple.CFPasteboardClient' 03:56:48 INFO - PID 1681 | See /usr/include/servers/bootstrap_defs.h for the error codes. 03:56:48 INFO - PID 1681 | 2017-05-31 03:56:48.729 plugin-container[1682:9348] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 03:56:49 INFO - PID 1681 | 2017-05-31 03:56:49.212 plugin-container[1683:9394] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x973f, name = 'com.apple.tsm.portname' 03:56:49 INFO - PID 1681 | See /usr/include/servers/bootstrap_defs.h for the error codes. 03:56:49 INFO - PID 1681 | 2017-05-31 03:56:49.212 plugin-container[1683:9394] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x461b, name = 'com.apple.CFPasteboardClient' 03:56:49 INFO - PID 1681 | See /usr/include/servers/bootstrap_defs.h for the error codes. 03:56:49 INFO - PID 1681 | 2017-05-31 03:56:49.212 plugin-container[1683:9394] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 03:56:58 INFO - PID 1681 | RSS: Main: 269066240 03:56:58 INFO - PID 1681 | 04:00:00 INFO - PID 1681 | Cycle 1(1): loaded http://localhost:49228/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49228/tests/dromaeo/cssquery-ext.html) 04:00:02 INFO - PID 1681 | RSS: Main: 272293888 04:00:02 INFO - PID 1681 | 04:03:04 INFO - PID 1681 | Cycle 1(1): loaded http://localhost:49228/tests/dromaeo/cssquery-ext.html (next: http://localhost:49228/tests/dromaeo/cssquery-jquery.html) 04:03:05 INFO - PID 1681 | RSS: Main: 283549696 04:03:05 INFO - PID 1681 | 04:06:07 INFO - PID 1681 | Cycle 1(1): loaded http://localhost:49228/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49228/tests/dromaeo/cssquery-mootools.html) 04:06:07 INFO - PID 1681 | RSS: Main: 282587136 04:06:07 INFO - PID 1681 | 04:09:09 INFO - PID 1681 | Cycle 1(1): loaded http://localhost:49228/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49228/tests/dromaeo/cssquery-prototype.html) 04:09:10 INFO - PID 1681 | RSS: Main: 286674944 04:09:10 INFO - PID 1681 | 04:12:12 INFO - PID 1681 | Cycle 1(1): loaded http://localhost:49228/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49228/tests/dromaeo/cssquery-yui.html) 04:12:13 INFO - PID 1681 | RSS: Main: 286691328 04:12:13 INFO - PID 1681 | 04:15:15 INFO - PID 1681 | Cycle 1(1): loaded http://localhost:49228/tests/dromaeo/cssquery-yui.html (next: http://localhost:49228/tests/dromaeo/cssquery-dojo.html) 04:15:15 INFO - PID 1681 | RSS: Main: 286605312 04:15:15 INFO - PID 1681 | 04:15:15 INFO - PID 1681 | __start_tp_report 04:15:15 INFO - PID 1681 | _x_x_mozilla_page_load 04:15:15 INFO - PID 1681 | _x_x_mozilla_page_load_details 04:15:15 INFO - PID 1681 | |i|pagename|runs| 04:15:15 INFO - PID 1681 | |0;dojo.html;2972.390659914717;3563.3407819553386;3594.604593494716;3595.047312462198;3595.352836489434;4629.629629629621;5314.813981510648;5909.758950620375;6200.565960382775;6212.5029997600195;6136.5803491640345;6190.535709821759;6236.781712640059;6243.843903902405;6312.968435157818;5321;5427.348718153827;5432.619716619838;5442.428545002777;5473.753681084341;5488.725563721818;5566.832995010136;5572.192032155335;5583.134614134795;5618.073017952053;5344.43883392246;5408.2698835657075;5468.015757163709;5479.205515200274;5489;4522.16339977102;5420.105682562373;5450.754716037788;5503.642263252876;5512.4211957744665;4464.709793863389;4614.192516309632;5242.501962313574;5397.163439666858;5491.478309560585;4936.777844996984;5790.957627627025;6144.863200307957;6253.624782513064;6290.779822706183;6507.739690412378;6625.867482650368;6631.966840165816;6662;6681.298463661343;6753.763618273337;6759.053732477408;6777.762778302736;6779.559328643622;6788.7623933162595;6146.938530614726;6278.591891527036;6280.591761535486;6287.245530536365;6309.116723658692;6733.9326606734285;6787.898181527281;6886.380225779704;6912;6930.306969303029;6285.968570157212;6375.872482550323;6469.159009328851;6516.2506311773805;6522.15212022434;6646.335366463315;6691.899621505681;6702;6727.159105111861;6745.325467453314;6619.86760264792;6628.96685516579;6636.800895973129;6641.767538136142;6668.899966500507;6330.620162790247;6381.074744162163;6521.543491955613;6585.111010013587;6623.834404139839;5336;5340.118880384771;5350.8127215547265;5351.331083614548;5381.219723140122;4746.216874215785;4760.785764640599;4765.1660959331975;4766;4797.592204662643;6061.454469097803;6252.562320637512;6278.215223097113;6293.842653933597;6333.366663333722;6752.493562982796;6783.457323414115;6803.897941530881;6818;6831.316868313129;6204.968975155095;6304.760003991581;6308.369163083655;6328.746850125944;6331.2085989251345;5733.426657334233;5807.332156801937;5914.408559144051;5930.080837470199;5947.345791962881;6821.727130914807;6910.101686780687;6912.997615345847;6932.48006399512;6935.2717964615;6505.967470162619;6521.934780652323;6522.543421960607;6540.215174179129;6548.8035358939305;6651.035599838093;6661.900071499028;6675.899861501985;6697.497687673346;6795.7961261162245;6448.419642232222;6518.641474718746;6555.901661474986;6560.573562718408;6590.373914478117;6722.932770672231;6746.156730408699;6756.054152418567;6759.087523184307;6775.62734049632;5425.186222066722;6311.147995020613;6340.270868850142;6411.294757576756;6475.481961442979;6443.291237963726;6515.348465153446;6534.803955881332;6557.737690492327;6566.868662626816;5105.642605017613;6381.649009304533;6392.64840433762;6404;6406.263279722892;6307.148534947723;6381.457576106083;6401.199850018747;6543.476521878333;6568.9014664781025;6314.147590075465;6364.6181229126405;6374;6375.776847810304;6410.839729006812 04:15:15 INFO - PID 1681 | |1;ext.html;785.8153333966517;840.8486472434962;847.1316900177316;1267.6640690217093;1342.8388593368798;39656.405153922715;40822.795886020525;40826.59173408265;40877.59122408775;41114.58885411145;38324.80837595815;41749.37375939356;41765.37351939716;41889.16221675572;41945.58054419455;7858.410619203568;8007.799805004893;8192.098869124422;8440.240378365947;8441.788955276106;8288.295494882943;8421.831563368729;8433.114522975091;8435.24082832545;8435.746927592181;8395.874061889077;8444.071152173286;8459.154084591522;8481.915180848206;8485.533295668736;8313.459625124407;8377.45546539476;8407.789805254857;8420.578971051484;8446.070932197423;6885.483588730815;8077.071136819282;8142.674293028272;8158.5104893705975;8201.343892488587;37930.81034594809;38157.618423815686;38248.85253442401;38357.424638630444;38379;3600.2979419013304;3618.185908170667;3618.475321078429;3636.163682353047;3637.7999210043436;31039.37921241585;31043.534346984812;31044.223894402596;31077.689223107707;31101.688983110107;2796;2903.477374072666;2942.837579156221;3055.8235079494557;3123.453395655751;250062;252824;253926;254651;266737.66631166916;48989.02021959541;49166.75416622966;49183.50816491861;49198.50801491939;49280.75359623179;50577.49422505801;50680.746596266785;50752.70107443004;55332.51003704843;56376.43623563794;14673.77989330161;14685.48600798967;14689.632759180893;14756.335964881606;14808.407663693548;11675.88324116748;11753.706157346134;11761.411929403666;11773.705657358634;11791.646250612495;14159.008869379044;14229.28853557318;14259.572212833631;14296.499622513371;14300.49948251827;6327.209098862642;6328.272248691367;6338.524610654219;6402.743890244431;6416.807495775134;883.8585826267766;1012.4785735346303;1026.0560284538242;1056.2395075545594;1111.377628528027;24636.89133988975;24780.38049048788;24983.251172417757;25001.124960626654;25128;1051.5530899367739;1152.5965911930925;1160.721426857565;1167.5563285951284;1195.9043276537857;10529.631462898762;12603.747925041447;12626.368681565886;12675.17611355259;12684.04869634781;278427;330432;332595;332719;332957;51944.02643695948;52189.47810521846;52218;52271;52474.95050098977;1350.6218258887332;1483.3028476615973;1528.9603069912343;1562.8359022302723;1567.2242240091227;23855.522889541764;23995.040198391867;24066.759332407153;24158.758412416355;24185.879070604533;38753.612463875;38915.2216955665;38954;39062.414063789634;39126;288015;288044;288691;289363;290005;34854.30291394107;36148;36171.019292540645;36488.27023459569;36535.26929461449;44649.77675111734;45755.77122114368;46548.767256163504;46575.602731918094;46614;29558.40883182367;31199.688003119678;31434.685653143177;31832.5225121628;32146.357072857943;30694.386112278076;31154;31312;31405.057848264587;31588.73645054172;38015.61984380121;38259.85220443391;38284.2343153141;38368.42447363347;38405.80797095997;28925.7107428923;29768.958086467737;31213.84393078111;31645.20886977844;32065;17250.223739931986;23513.882430588423;24745.505089897742;24855.751442486067;25180.74819251784 04:15:15 INFO - PID 1681 | |2;jquery.html;363.33691013899625;425.40499044061187;428.6742076022222;430.35231975876303;443.3991940920053;22267.77732222677;22930.770692293074;22988.425289367755;23462.530749385;24561.1403600874;24464;24852.50294994099;24957.75211239435;24959.50080998379;24964.625530617057;6187.876242475148;6333.809985700436;6339.746410143589;6344.111824344595;6360.522960777937;6266.5300102492265;6270.372962703739;6289.905651415233;6306.590071645352;6468.708908099124;6840;6854.691538880737;6876.346747059021;6890.862182756367;6908.827279318032;6892.965535172342;6899.551529150614;6900.171979362509;6904.275051119661;6910.48171387148;5700.287464066992;6713.731450742013;6758.797236082925;6763.526553141282;6773.966130169367;31016.844915775502;31231.72066932962;31847.04458866238;31860.362792744247;32060.358792824012;2255.2783109404995;2272.5114100468477;2278.362058623588;2292.7821856923565;2321.117975169425;19781.604367912703;20141.49646258855;20282.797172028386;20345.084471198683;20368.981550922395;2283.246528645543;2283.360659015478;2332.6734257204007;2347.718273807154;2384.5350156719446;169576.15211923985;171362;178324;203124;210965;22129.778702213094;24739;26708.866455667598;26832.59751103735;27729.861350693118;42433.57566424296;43098.56901431008;43112;43184;43225.27098916008;9470.857937130948;9956.705628268279;10706.197035222389;11462.312261264351;11577.131715121399;7431.665575049135;8940.821183576292;9112.589933453011;9153.176214140625;9191.908080919238;10768.56925722978;12351.752964940652;12499.312537810325;12690.42893069814;12762.298073605854;4983.227599722049;5138.2806407103035;5145.742712864342;5158.432572417031;5171.896562068738;852.5098068610574;939.7415710679618;971.2812518736084;1002.6991902429243;1004.4475538453821;19735.703964440545;21309.68035479469;21327.68008479874;21347.25284615031;21467.78532214689;989.5052473763119;997.5983742841174;1005.6932635546228;1021.4994652620163;1022.1005515146772;10672.57309707603;11067.284570891525;11134.16493762971;11170.832437513443;11217.887821121847;536280;540276;545825;552234;552815;137621;139317.9102313466;141490;153394;160291;903.2728653433917;1164.6564263542107;1221.590767093016;1243.1484433163312;1249.650097972569;20184.697229541864;21022.57954840864;21998.6800791953;23025.65461518112;23558.52882942366;67497.6500469978;68022.63954720976;68121;70904;71456.64271678608;86979.1302086971;87552.1244787544;88016;88314.67527987214;88437.55781221052;10310.32982856112;10356.275060745675;10370.740731481774;10427.426491542734;10487.16102711766;18159.909200453912;18238.08809559543;18359.90820045891;18461.16924738417;18484.537886552946;14699.11805291686;14759.483418080315;15089.924550377178;15098.018628789094;15128.924355378593;11508.251963622337;12873.678158046123;13537.72924541484;13663.863361366259;13841.930790345983;15131.54605361841;15225.923870380577;15308.387664493295;15321.004134731207;15339.539813805604;10188.898111018794;10302.22733294991;10343.586256549654;10381.740456488345;10382.740431489274;14536.491222807152;14818.03682760617;14909.403623854925;15043.623909402353;15051.849481505484 04:15:15 INFO - PID 1681 | |3;mootools.html;1225.3750587200527;1501.2193659297159;1509.08700236357;1512.8940974131815;1524.3064405695409;11778.175527713052;12121.757564848698;12235.938820305886;12248.571300004502;12469.750604987894;11863.347515886622;11889.64331070067;11986.220895641778;12052.276863388202;12060.638180854567;2540.517301712673;2569;2593.314345675317;2593.4035171910473;2596.5585850405478;2517.7356377580363;2557.2200478853943;2561.5645340292194;2561.679790026247;2562.3466016165853;2513.9371515712164;2520.836145650536;2534.847909125449;2542.3898264416594;2545.7199708032103;2484.7391023942496;2513.8868750906163;2516.069054449856;2518.622206668996;2521.936951576207;1910.7420498232839;2285.2572913802915;2288.039023610088;2447.706275246982;2567.3966617844976;11668.474918628683;11738.413079346084;11752.118591105702;11755.412229388496;11761.64715058541;6477.514186436036;6732.966335168293;6739.258681545026;6741.561798483083;6742.865142697118;3589.587197472298;4118.464599602033;4318.330658747899;4330.13397320534;4381.189479946188;1089.9400532970683;1264.3930913161641;1271.4899713467016;1274.7004453953314;1287.9935600322033;14533.27333633314;15861.365545378056;16649.66700665992;16739.246733897005;16790;4771.475137734847;5070.61970352225;5579.860503487445;5678.744456499468;5733.197352370671;11989.520419183134;12091.637250882311;12150.45322960469;12165.5133794649;12181.817272740916;6400.743970241231;6408.455281301049;6520.706568204442;6536.379043990813;6546.312637173123;6106.603070800473;6159.969200153971;6198.659073750987;6215.192025036807;6233.781817636452;4436.689431739812;4494.325851122358;4527.47933987589;4533.297338912474;4564.338170965258;1231.1259006105586;1258.2890666773426;1273.153926806135;1294.0424086176163;1294.4078783096843;691.6714560583682;780.1262585903823;811.1766556944706;813.8982627171604;816.3306089006958;6685.933140668629;7010.894836577456;7033.980072889402;7055.3650171484815;7161.785146445615;813.507827764206;897.3359713811732;951.4671783801093;951.8952915179325;953.4851180362526;3889;4400.9119817604105;4431.028192454327;4499.3251012347755;4526.479454862667;14160.291985400894;15142.394304227928;15396.307166177548;15398.615034624;15437.30532126057;12603.747925041447;14793;15118.546443606709;15257.237138143048;15309.846901530842;8608.569571521399;8620.52587107715;8722.215000649845;8729.127087291221;8847.646094156291;5132.8460146195675;6579.309172536815;6792.388685018174;7070.292970702889;7181;13003.609891703263;13144.802827957397;13158.60524184276;13396.26320552379;13939.790903136663;13462.394192261567;14049.157050576589;14087.929560352133;14116.223607701675;14126.78809817874;7848.587254294282;9454.101860323259;9599.280053995839;9607.318719224248;9686.75783105428;10850.891491084989;11072.723181920517;11074.058705010164;11077;11113.22207445471;9176;9281.118293762082;9318.48748318849;9446.905530944603;9472.668456603986;9111.681091161778;9185.586648600962;9223.815523689624;9285.62857485693;9286.30352723535;8032.156623554442;8342.416030877781;8366.66533338684;8420.747377578682;8445.873311900203;6037.245344331958;6057.727402266821;6216.844578885383;6221.222347206599;6243.063540468966;5363.5440987516495;5863.94136058651;6018.849528761816;6268.937310626835;6287 04:15:15 INFO - PID 1681 | |4;prototype.html;376.5801131738111;376.58951742600595;381.9407991761276;407.67997122259027;412.28057040464387;5752.424757524245;6249.5625306228585;6391.45672617828;6406.935930640692;6505.80482585522;4338.566143385668;4808.495108013661;6215.19202503675;6265;6375.617462952227;3764.8705388383514;4542.205114105035;4572.15415148197;4576.473705523874;4601.585857272845;4074.4295798588223;4104.40486129511;4587.954120458803;4632.606228470584;4644.558766917137;3934.803259836997;4528.411306530146;4535.7278563286145;4545.659075569329;4551.613112885409;3629.800360980145;4545.886352841206;4550.49944506104;4563.224251877189;4615.423072115986;3416.265502916885;3856.4408160816524;3876.577139287945;3891.4941057662604;3897.337452633059;4483.820647174109;6153.123297394293;6158.107074474176;6191.380861913818;6192.318844927055;4666.6500012498855;5240.449752775941;5390.838274851761;5398.91901621476;5438.83683489496;6087.604305720159;6706.530542862001;6718.849968010239;6731.1922569292;6734.629595372253;3693.833777479993;4168.686379059621;4234.470691163605;4377.671674624384;4389.4513185851765;5573.888522229533;6815.909454487308;6973.267806880306;7126.928730712679;7206.279372062804;3345.5483509725877;4119.505659320901;4183.226103170923;4399.670024748157;4451.488078870972;3887.6889848812593;4531.210171982048;4535.818567257339;4539.84110556129;4554.246615053162;5243.187305967581;5354.250404943311;5358.356997160366;5364.9731751341;5422.566194704415;5220.686758794484;6409.55133140685;6665.566738162004;6693.129893113962;6718.227403848522;1548;1553.2932515705552;1553.7747026681293;1558.096304143594;1562.1486289971992;894.4186278918755;987.9703608891745;1002.6039714312806;1011.3224139826334;1019.2559431614962;5674.460926212003;6280.9685951569945;6338.873222535523;6360.52296077796;6410.583312084699;7018.6484379684925;7021.754238601625;7026.297370262932;7030.613316267654;7034.753783617549;6206.255249370104;6339.809805705836;6389.370710468801;6419.711112999927;6450.677466126769;2788.5538313869683;2827.3638431352783;3260.4620237660993;3650.6166852480637;3690.8339124738914;6900.516963812485;7236.937472820221;7243.718228757924;7249;7274.272572742683;6307.684615769193;7219.602921839244;7235.914612808121;7241.384482319062;7295.671694773748;6457.967710161513;6720.32796720324;6800.08198893153;7243.637818109073;7261.491695581259;6988.615626140611;6991.811392063463;6995.79012629622;6996.685149168401;7013.824654383681;5952.910706339322;6080.847978800565;6261.211966760045;6905.136857892763;6928.965355173192;6149;6501.317361676955;7128.6792094356915;7209.243029481829;7222.422206223595;4432.623227025739;4457.732536047847;4473.865784026484;4477.634321531964;4502.50472448037;6135.570510064253;6206.038064100072;7021.543599666005;7051.647417628996;7053.576785392893;5809.796657116981;5937.762489500545;6635;6723.865522689616;6761.019652150312;5823.94176058234;6177.258728952374;6546.083548303337;6650.767223147167;6719.563228390334;5404;5969.343372228965;6520.695860827758;6594.439472644879;6594.604323740399;5622.437756224344;5625.437456254342;5942.762289508544;6739.865202696017;6754.8649027020165;6538.855700252532;6544.2474115475425;6556.147700798866;6623.31755638967;6634.303398143125 04:15:15 INFO - PID 1681 | |5;yui.html;729.5768454296508;836.6987884361632;843.8523258429773;861.9008813986394;876.7457437343171;14743.705125897475;15377.538673839776;16390.91804540977;16829.83170168298;16876.493705188863;13794.172349659002;17784.46646600604;17825.93044417336;17827.197776100074;17867.374641887538;991.2714155095998;1000.7898341348325;1019.3425240719731;1040.8490768838515;1041.1878734586996;1044.8171569975261;1046.2257929132463;1046.4349251404233;1047.2302857399804;1048.6172547020321;879.8592225243963;1069.3370110531482;1074.2265568790458;1076.6931424544007;1077.0737166037202;1060.9681709548727;1061.7451811565206;1061.7982583309224;1063.4470075560741;1066.3388699006666;777.9105402878685;797.7048492057976;802.9437939344248;804.6097642643314;806.3589446390134;17070.975741455553;17492.38776642824;17591.296348146057;17766.37817676388;18118;15210;17729.645407091914;17766.822331776646;18130.365437209766;18140.183691733902;14704.117752934859;14936.701265974729;14969.326380312912;14999.400023999027;15164.545063648107;3500.597431295408;3521.2253304273277;3526.894193174209;3554.182538016245;3628.618995005539;90122.99999999935;125942.37028814797;127366.72633273642;127562;129065.70934290631;13287.601371958857;13906.58280251594;14044;14113.858861411254;14151;20563.280285190245;26589.734102658727;27166.1850144496;27446.862765686044;27776.58335124975;6862.416694581017;7035.613041282779;7890.289873911375;7942.404319676048;7992.560409177551;3479.6694314040124;3823.235352929425;3828.272628200633;3831.3295173344554;3832.080300727861;4319.935200971988;4387.517373088957;4392.538783427694;4419.756913369732;4421.7789110544345;813.251808336332;956.8995255498212;963.6386355116831;979.8530220466987;984.1142971325721;627.7457629659995;642.9678516074177;645.5674697952289;650.3139188156446;670.4703284405265;11392.772144557062;11536.942315288537;12416.81374779379;12531.060170487068;12558.309292988974;571.9199312096309;635.2186810223359;640.1517988665038;655.9212894452696;673.2896793882501;5679.784883895284;5684.431556844283;5782.277215348082;5816.5055970243;5819.301683797971;117339.41330293464;144560;147460;148468.25765871102;151568.2421587907;133185;133755;134340.08667876237;134847;136410.3179484116;110636;111337.32994005096;111964;112264;112786.43606781913;10958.45207739594;11443.313401195956;11450.255733377637;11457.828132578185;11635.709107272385;42249.577504224566;44522.55477445184;45412.54587454083;45603.315950261436;47779.52220477751;69028;85862;86320;87503.24993499967;89339;17833.554161146076;17940.820591793916;18090.366837160636;18158.45524634263;18269;17115.828841711424;18144.45566633003;19421.708674370177;19442.12510437062;19536;16033.8396616037;16561.92347497409;16726.247318870923;16758.58103547421;18343.908280458512;17113.828861711223;17356.82643173552;17831;18026.90986545059;18132.728009079612;13212;17377.739333909747;17644.470665880042;17826.91086544611;17901.641967160846;11840.881591183977;11987.40062996864;12217;12309;12404.81392779091;13321.533746318832;13498.730025399633;13533;14025.29873506341;14418.837174423921 04:15:15 INFO - PID 1681 | __end_tp_report 04:15:15 INFO - PID 1681 | __start_cc_report 04:15:15 INFO - PID 1681 | _x_x_mozilla_cycle_collect,461 04:15:15 INFO - PID 1681 | __end_cc_report 04:15:15 INFO - PID 1681 | __startTimestamp1496229315605__endTimestamp 04:15:15 INFO - PID 1681 | 04:15:15 INFO - PID 1681 | ------- Summary: start ------- 04:15:15 INFO - PID 1681 | Number of tests: 6 04:15:15 INFO - PID 1681 | 04:15:15 INFO - PID 1681 | [#0] dojo.html Cycles:165 Average:6095.13 Median:6340.27 stddev:761.75 (12.0%) stddev-sans-first:723.63 04:15:15 INFO - PID 1681 | Values: 2972.4 3563.3 3594.6 3595.0 3595.4 4629.6 5314.8 5909.8 6200.6 6212.5 6136.6 6190.5 6236.8 6243.8 6313.0 5321.0 5427.3 5432.6 5442.4 5473.8 5488.7 5566.8 5572.2 5583.1 5618.1 5344.4 5408.3 5468.0 5479.2 5489.0 4522.2 5420.1 5450.8 5503.6 5512.4 4464.7 4614.2 5242.5 5397.2 5491.5 4936.8 5791.0 6144.9 6253.6 6290.8 6507.7 6625.9 6632.0 6662.0 6681.3 6753.8 6759.1 6777.8 6779.6 6788.8 6146.9 6278.6 6280.6 6287.2 6309.1 6733.9 6787.9 6886.4 6912.0 6930.3 6286.0 6375.9 6469.2 6516.3 6522.2 6646.3 6691.9 6702.0 6727.2 6745.3 6619.9 6629.0 6636.8 6641.8 6668.9 6330.6 6381.1 6521.5 6585.1 6623.8 5336.0 5340.1 5350.8 5351.3 5381.2 4746.2 4760.8 4765.2 4766.0 4797.6 6061.5 6252.6 6278.2 6293.8 6333.4 6752.5 6783.5 6803.9 6818.0 6831.3 6205.0 6304.8 6308.4 6328.7 6331.2 5733.4 5807.3 5914.4 5930.1 5947.3 6821.7 6910.1 6913.0 6932.5 6935.3 6506.0 6521.9 6522.5 6540.2 6548.8 6651.0 6661.9 6675.9 6697.5 6795.8 6448.4 6518.6 6555.9 6560.6 6590.4 6722.9 6746.2 6756.1 6759.1 6775.6 5425.2 6311.1 6340.3 6411.3 6475.5 6443.3 6515.3 6534.8 6557.7 6566.9 5105.6 6381.6 6392.6 6404.0 6406.3 6307.1 6381.5 6401.2 6543.5 6568.9 6314.1 6364.6 6374.0 6375.8 6410.8 04:15:15 INFO - PID 1681 | 04:15:15 INFO - PID 1681 | [#1] ext.html Cycles:180 Average:44654.07 Median:183905.65 stddev:75978.24 (41.3%) stddev-sans-first:76119.98 04:15:15 INFO - PID 1681 | Values: 785.8 840.8 847.1 1267.7 1342.8 39656.4 40822.8 40826.6 40877.6 41114.6 38324.8 41749.4 41765.4 41889.2 41945.6 7858.4 8007.8 8192.1 8440.2 8441.8 8288.3 8421.8 8433.1 8435.2 8435.7 8395.9 8444.1 8459.2 8481.9 8485.5 8313.5 8377.5 8407.8 8420.6 8446.1 6885.5 8077.1 8142.7 8158.5 8201.3 37930.8 38157.6 38248.9 38357.4 38379.0 3600.3 3618.2 3618.5 3636.2 3637.8 31039.4 31043.5 31044.2 31077.7 31101.7 2796.0 2903.5 2942.8 3055.8 3123.5 250062.0 252824.0 253926.0 254651.0 266737.7 48989.0 49166.8 49183.5 49198.5 49280.8 50577.5 50680.7 50752.7 55332.5 56376.4 14673.8 14685.5 14689.6 14756.3 14808.4 11675.9 11753.7 11761.4 11773.7 11791.6 14159.0 14229.3 14259.6 14296.5 14300.5 6327.2 6328.3 6338.5 6402.7 6416.8 883.9 1012.5 1026.1 1056.2 1111.4 24636.9 24780.4 24983.3 25001.1 25128.0 1051.6 1152.6 1160.7 1167.6 1195.9 10529.6 12603.7 12626.4 12675.2 12684.0 278427.0 330432.0 332595.0 332719.0 332957.0 51944.0 52189.5 52218.0 52271.0 52475.0 1350.6 1483.3 1529.0 1562.8 1567.2 23855.5 23995.0 24066.8 24158.8 24185.9 38753.6 38915.2 38954.0 39062.4 39126.0 288015.0 288044.0 288691.0 289363.0 290005.0 34854.3 36148.0 36171.0 36488.3 36535.3 44649.8 45755.8 46548.8 46575.6 46614.0 29558.4 31199.7 31434.7 31832.5 32146.4 30694.4 31154.0 31312.0 31405.1 31588.7 38015.6 38259.9 38284.2 38368.4 38405.8 28925.7 29769.0 31213.8 31645.2 32065.0 17250.2 23513.9 24745.5 24855.8 25180.7 04:15:15 INFO - PID 1681 | 04:15:15 INFO - PID 1681 | [#2] jquery.html Cycles:180 Average:39812.67 Median:12673.39 stddev:94222.11 (743.5%) stddev-sans-first:94439.87 04:15:15 INFO - PID 1681 | Values: 363.3 425.4 428.7 430.4 443.4 22267.8 22930.8 22988.4 23462.5 24561.1 24464.0 24852.5 24957.8 24959.5 24964.6 6187.9 6333.8 6339.7 6344.1 6360.5 6266.5 6270.4 6289.9 6306.6 6468.7 6840.0 6854.7 6876.3 6890.9 6908.8 6893.0 6899.6 6900.2 6904.3 6910.5 5700.3 6713.7 6758.8 6763.5 6774.0 31016.8 31231.7 31847.0 31860.4 32060.4 2255.3 2272.5 2278.4 2292.8 2321.1 19781.6 20141.5 20282.8 20345.1 20369.0 2283.2 2283.4 2332.7 2347.7 2384.5 169576.2 171362.0 178324.0 203124.0 210965.0 22129.8 24739.0 26708.9 26832.6 27729.9 42433.6 43098.6 43112.0 43184.0 43225.3 9470.9 9956.7 10706.2 11462.3 11577.1 7431.7 8940.8 9112.6 9153.2 9191.9 10768.6 12351.8 12499.3 12690.4 12762.3 4983.2 5138.3 5145.7 5158.4 5171.9 852.5 939.7 971.3 1002.7 1004.4 19735.7 21309.7 21327.7 21347.3 21467.8 989.5 997.6 1005.7 1021.5 1022.1 10672.6 11067.3 11134.2 11170.8 11217.9 536280.0 540276.0 545825.0 552234.0 552815.0 137621.0 139317.9 141490.0 153394.0 160291.0 903.3 1164.7 1221.6 1243.1 1249.7 20184.7 21022.6 21998.7 23025.7 23558.5 67497.7 68022.6 68121.0 70904.0 71456.6 86979.1 87552.1 88016.0 88314.7 88437.6 10310.3 10356.3 10370.7 10427.4 10487.2 18159.9 18238.1 18359.9 18461.2 18484.5 14699.1 14759.5 15089.9 15098.0 15128.9 11508.3 12873.7 13537.7 13663.9 13841.9 15131.5 15225.9 15308.4 15321.0 15339.5 10188.9 10302.2 10343.6 10381.7 10382.7 14536.5 14818.0 14909.4 15043.6 15051.8 04:15:15 INFO - PID 1681 | 04:15:15 INFO - PID 1681 | [#3] mootools.html Cycles:180 Average:7180.68 Median:3739.29 stddev:4532.95 (121.2%) stddev-sans-first:4523.58 04:15:15 INFO - PID 1681 | Values: 1225.4 1501.2 1509.1 1512.9 1524.3 11778.2 12121.8 12235.9 12248.6 12469.8 11863.3 11889.6 11986.2 12052.3 12060.6 2540.5 2569.0 2593.3 2593.4 2596.6 2517.7 2557.2 2561.6 2561.7 2562.3 2513.9 2520.8 2534.8 2542.4 2545.7 2484.7 2513.9 2516.1 2518.6 2521.9 1910.7 2285.3 2288.0 2447.7 2567.4 11668.5 11738.4 11752.1 11755.4 11761.6 6477.5 6733.0 6739.3 6741.6 6742.9 3589.6 4118.5 4318.3 4330.1 4381.2 1089.9 1264.4 1271.5 1274.7 1288.0 14533.3 15861.4 16649.7 16739.2 16790.0 4771.5 5070.6 5579.9 5678.7 5733.2 11989.5 12091.6 12150.5 12165.5 12181.8 6400.7 6408.5 6520.7 6536.4 6546.3 6106.6 6160.0 6198.7 6215.2 6233.8 4436.7 4494.3 4527.5 4533.3 4564.3 1231.1 1258.3 1273.2 1294.0 1294.4 691.7 780.1 811.2 813.9 816.3 6685.9 7010.9 7034.0 7055.4 7161.8 813.5 897.3 951.5 951.9 953.5 3889.0 4400.9 4431.0 4499.3 4526.5 14160.3 15142.4 15396.3 15398.6 15437.3 12603.7 14793.0 15118.5 15257.2 15309.8 8608.6 8620.5 8722.2 8729.1 8847.6 5132.8 6579.3 6792.4 7070.3 7181.0 13003.6 13144.8 13158.6 13396.3 13939.8 13462.4 14049.2 14087.9 14116.2 14126.8 7848.6 9454.1 9599.3 9607.3 9686.8 10850.9 11072.7 11074.1 11077.0 11113.2 9176.0 9281.1 9318.5 9446.9 9472.7 9111.7 9185.6 9223.8 9285.6 9286.3 8032.2 8342.4 8366.7 8420.7 8445.9 6037.2 6057.7 6216.8 6221.2 6243.1 5363.5 5863.9 6018.8 6268.9 6287.0 04:15:15 INFO - PID 1681 | 04:15:15 INFO - PID 1681 | [#4] prototype.html Cycles:180 Average:5335.26 Median:6111.59 stddev:1748.63 (28.6%) stddev-sans-first:1713.46 04:15:15 INFO - PID 1681 | Values: 376.6 376.6 381.9 407.7 412.3 5752.4 6249.6 6391.5 6406.9 6505.8 4338.6 4808.5 6215.2 6265.0 6375.6 3764.9 4542.2 4572.2 4576.5 4601.6 4074.4 4104.4 4588.0 4632.6 4644.6 3934.8 4528.4 4535.7 4545.7 4551.6 3629.8 4545.9 4550.5 4563.2 4615.4 3416.3 3856.4 3876.6 3891.5 3897.3 4483.8 6153.1 6158.1 6191.4 6192.3 4666.7 5240.4 5390.8 5398.9 5438.8 6087.6 6706.5 6718.8 6731.2 6734.6 3693.8 4168.7 4234.5 4377.7 4389.5 5573.9 6815.9 6973.3 7126.9 7206.3 3345.5 4119.5 4183.2 4399.7 4451.5 3887.7 4531.2 4535.8 4539.8 4554.2 5243.2 5354.3 5358.4 5365.0 5422.6 5220.7 6409.6 6665.6 6693.1 6718.2 1548.0 1553.3 1553.8 1558.1 1562.1 894.4 988.0 1002.6 1011.3 1019.3 5674.5 6281.0 6338.9 6360.5 6410.6 7018.6 7021.8 7026.3 7030.6 7034.8 6206.3 6339.8 6389.4 6419.7 6450.7 2788.6 2827.4 3260.5 3650.6 3690.8 6900.5 7236.9 7243.7 7249.0 7274.3 6307.7 7219.6 7235.9 7241.4 7295.7 6458.0 6720.3 6800.1 7243.6 7261.5 6988.6 6991.8 6995.8 6996.7 7013.8 5952.9 6080.8 6261.2 6905.1 6929.0 6149.0 6501.3 7128.7 7209.2 7222.4 4432.6 4457.7 4473.9 4477.6 4502.5 6135.6 6206.0 7021.5 7051.6 7053.6 5809.8 5937.8 6635.0 6723.9 6761.0 5823.9 6177.3 6546.1 6650.8 6719.6 5404.0 5969.3 6520.7 6594.4 6594.6 5622.4 5625.4 5942.8 6739.9 6754.9 6538.9 6544.2 6556.1 6623.3 6634.3 04:15:15 INFO - PID 1681 | 04:15:15 INFO - PID 1681 | [#5] yui.html Cycles:180 Average:25841.86 Median:17805.20 stddev:39241.06 (220.4%) stddev-sans-first:39305.84 04:15:15 INFO - PID 1681 | Values: 729.6 836.7 843.9 861.9 876.7 14743.7 15377.5 16390.9 16829.8 16876.5 13794.2 17784.5 17825.9 17827.2 17867.4 991.3 1000.8 1019.3 1040.8 1041.2 1044.8 1046.2 1046.4 1047.2 1048.6 879.9 1069.3 1074.2 1076.7 1077.1 1061.0 1061.7 1061.8 1063.4 1066.3 777.9 797.7 802.9 804.6 806.4 17071.0 17492.4 17591.3 17766.4 18118.0 15210.0 17729.6 17766.8 18130.4 18140.2 14704.1 14936.7 14969.3 14999.4 15164.5 3500.6 3521.2 3526.9 3554.2 3628.6 90123.0 125942.4 127366.7 127562.0 129065.7 13287.6 13906.6 14044.0 14113.9 14151.0 20563.3 26589.7 27166.2 27446.9 27776.6 6862.4 7035.6 7890.3 7942.4 7992.6 3479.7 3823.2 3828.3 3831.3 3832.1 4319.9 4387.5 4392.5 4419.8 4421.8 813.3 956.9 963.6 979.9 984.1 627.7 643.0 645.6 650.3 670.5 11392.8 11536.9 12416.8 12531.1 12558.3 571.9 635.2 640.2 655.9 673.3 5679.8 5684.4 5782.3 5816.5 5819.3 117339.4 144560.0 147460.0 148468.3 151568.2 133185.0 133755.0 134340.1 134847.0 136410.3 110636.0 111337.3 111964.0 112264.0 112786.4 10958.5 11443.3 11450.3 11457.8 11635.7 42249.6 44522.6 45412.5 45603.3 47779.5 69028.0 85862.0 86320.0 87503.2 89339.0 17833.6 17940.8 18090.4 18158.5 18269.0 17115.8 18144.5 19421.7 19442.1 19536.0 16033.8 16561.9 16726.2 16758.6 18343.9 17113.8 17356.8 17831.0 18026.9 18132.7 13212.0 17377.7 17644.5 17826.9 17901.6 11840.9 11987.4 12217.0 12309.0 12404.8 13321.5 13498.7 13533.0 14025.3 14418.8 04:15:15 INFO - PID 1681 | -------- Summary: end -------- 04:15:15 INFO - PID 1681 | 04:15:16 INFO - TEST-INFO | 1681: exit 0 04:15:16 INFO - TEST-OK | dromaeo_css | took 1143388ms 04:15:16 INFO - TEST-START | kraken 04:15:16 INFO - operating with platform_type : mac_ 04:15:16 INFO - Initialising browser for kraken test... 04:15:16 INFO - Application command: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49228/getInfo.html -foreground -profile /var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/tmpYy3NKp/profile 04:15:16 INFO - TEST-INFO | started process 1689 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49228/getInfo.html -foreground) 04:15:49 INFO - TEST-INFO | 1689: killed by SIGTERM 04:15:49 INFO - Browser initialized. 04:15:49 INFO - Running cycle 1/1 for kraken test... 04:15:49 INFO - TEST-INFO | started process 1693 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/tmpYy3NKp/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:15:51 INFO - PID 1693 | 2017-05-31 04:15:51.823 plugin-container[1694:11856] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4947, name = 'com.apple.tsm.portname' 04:15:51 INFO - PID 1693 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:15:51 INFO - PID 1693 | 2017-05-31 04:15:51.824 plugin-container[1694:11856] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0xa00b, name = 'com.apple.CFPasteboardClient' 04:15:51 INFO - PID 1693 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:15:51 INFO - PID 1693 | 2017-05-31 04:15:51.824 plugin-container[1694:11856] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 04:15:52 INFO - PID 1693 | 2017-05-31 04:15:52.594 plugin-container[1695:11905] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4947, name = 'com.apple.tsm.portname' 04:15:52 INFO - PID 1693 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:15:52 INFO - PID 1693 | 2017-05-31 04:15:52.594 plugin-container[1695:11905] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9b23, name = 'com.apple.CFPasteboardClient' 04:15:52 INFO - PID 1693 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:15:52 INFO - PID 1693 | 2017-05-31 04:15:52.594 plugin-container[1695:11905] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 04:16:01 INFO - PID 1693 | RSS: Main: 260689920 04:16:01 INFO - PID 1693 | 04:16:04 INFO - PID 1693 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49228/tests/kraken/driver.html?testName=audio-beat-detection) 04:16:05 INFO - PID 1693 | RSS: Main: 270802944 04:16:05 INFO - PID 1693 | 04:16:08 INFO - PID 1693 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49228/tests/kraken/driver.html?testName=audio-dft) 04:16:08 INFO - PID 1693 | RSS: Main: 279826432 04:16:08 INFO - PID 1693 | 04:16:12 INFO - PID 1693 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49228/tests/kraken/driver.html?testName=audio-fft) 04:16:12 INFO - PID 1693 | RSS: Main: 281419776 04:16:12 INFO - PID 1693 | 04:16:14 INFO - PID 1693 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49228/tests/kraken/driver.html?testName=audio-oscillator) 04:16:15 INFO - PID 1693 | RSS: Main: 284254208 04:16:15 INFO - PID 1693 | 04:16:17 INFO - PID 1693 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49228/tests/kraken/driver.html?testName=imaging-gaussian-blur) 04:16:17 INFO - PID 1693 | RSS: Main: 284266496 04:16:17 INFO - PID 1693 | 04:16:20 INFO - PID 1693 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49228/tests/kraken/driver.html?testName=imaging-darkroom) 04:16:21 INFO - PID 1693 | RSS: Main: 288784384 04:16:21 INFO - PID 1693 | 04:16:24 INFO - PID 1693 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49228/tests/kraken/driver.html?testName=imaging-desaturate) 04:16:25 INFO - PID 1693 | RSS: Main: 291790848 04:16:25 INFO - PID 1693 | 04:16:28 INFO - PID 1693 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49228/tests/kraken/driver.html?testName=json-parse-financial) 04:16:28 INFO - PID 1693 | RSS: Main: 291180544 04:16:28 INFO - PID 1693 | 04:16:30 INFO - PID 1693 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49228/tests/kraken/driver.html?testName=json-stringify-tinderbox) 04:16:30 INFO - PID 1693 | RSS: Main: 290623488 04:16:30 INFO - PID 1693 | 04:16:32 INFO - PID 1693 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49228/tests/kraken/driver.html?testName=stanford-crypto-aes) 04:16:33 INFO - PID 1693 | RSS: Main: 290574336 04:16:33 INFO - PID 1693 | 04:16:35 INFO - PID 1693 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49228/tests/kraken/driver.html?testName=stanford-crypto-ccm) 04:16:36 INFO - PID 1693 | RSS: Main: 290390016 04:16:36 INFO - PID 1693 | 04:16:39 INFO - PID 1693 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49228/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 04:16:39 INFO - PID 1693 | RSS: Main: 290275328 04:16:39 INFO - PID 1693 | 04:16:42 INFO - PID 1693 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49228/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 04:16:43 INFO - PID 1693 | RSS: Main: 290209792 04:16:43 INFO - PID 1693 | 04:16:45 INFO - PID 1693 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49228/tests/kraken/driver.html?testName=ai-astar) 04:16:45 INFO - PID 1693 | RSS: Main: 292044800 04:16:45 INFO - PID 1693 | 04:16:45 INFO - PID 1693 | __start_tp_report 04:16:45 INFO - PID 1693 | _x_x_mozilla_page_load 04:16:45 INFO - PID 1693 | _x_x_mozilla_page_load_details 04:16:45 INFO - PID 1693 | |i|pagename|runs| 04:16:45 INFO - PID 1693 | |0;ai-astar;103;116;115;115;114;114;115;114;135;117 04:16:45 INFO - PID 1693 | |1;audio-beat-detection;144;110;120;192;147;132;110;113;137;110 04:16:45 INFO - PID 1693 | |2;audio-dft;197;199;197;199;209;216;212;213;195;197 04:16:45 INFO - PID 1693 | |3;audio-fft;71;60;61;89;86;85;58;59;81;63 04:16:45 INFO - PID 1693 | |4;audio-oscillator;77;69;85;72;70;69;90;70;68;91 04:16:45 INFO - PID 1693 | |5;imaging-gaussian-blur;91;90;89;90;90;90;90;98;90;98 04:16:45 INFO - PID 1693 | |6;imaging-darkroom;97;97;123;152;97;96;97;96;121;111 04:16:45 INFO - PID 1693 | |7;imaging-desaturate;93;94;91;91;124;134;91;88;90;93 04:16:45 INFO - PID 1693 | |8;json-parse-financial;51;51;52;51;51;52;46;46;76;78 04:16:45 INFO - PID 1693 | |9;json-stringify-tinderbox;55;62;69;69;64;62;59;66;81;82 04:16:45 INFO - PID 1693 | |10;stanford-crypto-aes;85;86;90;88;88;85;89;89;132;90 04:16:45 INFO - PID 1693 | |11;stanford-crypto-ccm;139;172;155;128;153;157;138;147;130;178 04:16:45 INFO - PID 1693 | |12;stanford-crypto-pbkdf2;192;188;203;241;204;186;184;187;187;186 04:16:45 INFO - PID 1693 | |13;stanford-crypto-sha256-iterative;67;67;64;66;69;69;67;67;71;68 04:16:45 INFO - PID 1693 | __end_tp_report 04:16:45 INFO - PID 1693 | __start_cc_report 04:16:45 INFO - PID 1693 | _x_x_mozilla_cycle_collect,1187 04:16:45 INFO - PID 1693 | __end_cc_report 04:16:45 INFO - PID 1693 | __startTimestamp1496229405556__endTimestamp 04:16:45 INFO - PID 1693 | 04:16:45 INFO - PID 1693 | ------- Summary: start ------- 04:16:45 INFO - PID 1693 | Number of tests: 14 04:16:45 INFO - PID 1693 | 04:16:45 INFO - PID 1693 | [#0] ai-astar Cycles:10 Average:115.80 Median:115.00 stddev:7.79 (6.8%) stddev-sans-first:6.74 04:16:45 INFO - PID 1693 | Values: 103.0 116.0 115.0 115.0 114.0 114.0 115.0 114.0 135.0 117.0 04:16:45 INFO - PID 1693 | 04:16:45 INFO - PID 1693 | [#1] audio-beat-detection Cycles:10 Average:131.50 Median:134.50 stddev:25.71 (19.1%) stddev-sans-first:26.87 04:16:45 INFO - PID 1693 | Values: 144.0 110.0 120.0 192.0 147.0 132.0 110.0 113.0 137.0 110.0 04:16:45 INFO - PID 1693 | 04:16:45 INFO - PID 1693 | [#2] audio-dft Cycles:10 Average:203.40 Median:204.00 stddev:8.09 (4.0%) stddev-sans-first:8.24 04:16:45 INFO - PID 1693 | Values: 197.0 199.0 197.0 199.0 209.0 216.0 212.0 213.0 195.0 197.0 04:16:45 INFO - PID 1693 | 04:16:45 INFO - PID 1693 | [#3] audio-fft Cycles:10 Average:71.30 Median:76.00 stddev:12.66 (16.7%) stddev-sans-first:13.43 04:16:45 INFO - PID 1693 | Values: 71.0 60.0 61.0 89.0 86.0 85.0 58.0 59.0 81.0 63.0 04:16:45 INFO - PID 1693 | 04:16:45 INFO - PID 1693 | [#4] audio-oscillator Cycles:10 Average:76.10 Median:74.50 stddev:9.15 (12.3%) stddev-sans-first:9.70 04:16:45 INFO - PID 1693 | Values: 77.0 69.0 85.0 72.0 70.0 69.0 90.0 70.0 68.0 91.0 04:16:45 INFO - PID 1693 | 04:16:45 INFO - PID 1693 | [#5] imaging-gaussian-blur Cycles:10 Average:91.60 Median:90.00 stddev:3.41 (3.8%) stddev-sans-first:3.61 04:16:45 INFO - PID 1693 | Values: 91.0 90.0 89.0 90.0 90.0 90.0 90.0 98.0 90.0 98.0 04:16:45 INFO - PID 1693 | 04:16:45 INFO - PID 1693 | [#6] imaging-darkroom Cycles:10 Average:108.70 Median:96.50 stddev:18.58 (19.3%) stddev-sans-first:19.22 04:16:45 INFO - PID 1693 | Values: 97.0 97.0 123.0 152.0 97.0 96.0 97.0 96.0 121.0 111.0 04:16:45 INFO - PID 1693 | 04:16:45 INFO - PID 1693 | [#7] imaging-desaturate Cycles:10 Average:98.90 Median:91.00 stddev:16.13 (17.7%) stddev-sans-first:16.96 04:16:45 INFO - PID 1693 | Values: 93.0 94.0 91.0 91.0 124.0 134.0 91.0 88.0 90.0 93.0 04:16:45 INFO - PID 1693 | 04:16:45 INFO - PID 1693 | [#8] json-parse-financial Cycles:10 Average:55.40 Median:51.50 stddev:11.61 (22.5%) stddev-sans-first:12.20 04:16:45 INFO - PID 1693 | Values: 51.0 51.0 52.0 51.0 51.0 52.0 46.0 46.0 76.0 78.0 04:16:45 INFO - PID 1693 | 04:16:45 INFO - PID 1693 | [#9] json-stringify-tinderbox Cycles:10 Average:66.90 Median:67.50 stddev:8.80 (13.0%) stddev-sans-first:8.21 04:16:45 INFO - PID 1693 | Values: 55.0 62.0 69.0 69.0 64.0 62.0 59.0 66.0 81.0 82.0 04:16:45 INFO - PID 1693 | 04:16:45 INFO - PID 1693 | [#10] stanford-crypto-aes Cycles:10 Average:92.20 Median:88.50 stddev:14.11 (15.9%) stddev-sans-first:14.72 04:16:45 INFO - PID 1693 | Values: 85.0 86.0 90.0 88.0 88.0 85.0 89.0 89.0 132.0 90.0 04:16:45 INFO - PID 1693 | 04:16:45 INFO - PID 1693 | [#11] stanford-crypto-ccm Cycles:10 Average:149.70 Median:154.00 stddev:16.69 (10.8%) stddev-sans-first:17.25 04:16:45 INFO - PID 1693 | Values: 139.0 172.0 155.0 128.0 153.0 157.0 138.0 147.0 130.0 178.0 04:16:45 INFO - PID 1693 | 04:16:45 INFO - PID 1693 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:195.80 Median:190.00 stddev:17.40 (9.2%) stddev-sans-first:18.40 04:16:45 INFO - PID 1693 | Values: 192.0 188.0 203.0 241.0 204.0 186.0 184.0 187.0 187.0 186.0 04:16:45 INFO - PID 1693 | 04:16:45 INFO - PID 1693 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.50 Median:67.50 stddev:1.90 (2.8%) stddev-sans-first:2.01 04:16:45 INFO - PID 1693 | Values: 67.0 67.0 64.0 66.0 69.0 69.0 67.0 67.0 71.0 68.0 04:16:45 INFO - PID 1693 | -------- Summary: end -------- 04:16:45 INFO - PID 1693 | 04:16:46 INFO - TEST-INFO | 1693: exit 0 04:16:46 INFO - TEST-OK | kraken | took 90252ms 04:16:46 INFO - SUITE-END | took 1233s 04:16:46 INFO - Completed test suite (00:20:33) 04:16:46 INFO - javascript benchmark 04:16:46 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8218.976585103283, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2972.390659914717, 3563.3407819553386, 3594.604593494716, 3595.047312462198, 3595.352836489434, 4629.629629629621, 5314.813981510648, 5909.758950620375, 6200.565960382775, 6212.5029997600195, 6136.5803491640345, 6190.535709821759, 6236.781712640059, 6243.843903902405, 6312.968435157818, 5321.0, 5427.348718153827, 5432.619716619838, 5442.428545002777, 5473.753681084341, 5488.725563721818, 5566.832995010136, 5572.192032155335, 5583.134614134795, 5618.073017952053, 5344.43883392246, 5408.2698835657075, 5468.015757163709, 5479.205515200274, 5489.0, 4522.16339977102, 5420.105682562373, 5450.754716037788, 5503.642263252876, 5512.4211957744665, 4464.709793863389, 4614.192516309632, 5242.501962313574, 5397.163439666858, 5491.478309560585, 4936.777844996984, 5790.957627627025, 6144.863200307957, 6253.624782513064, 6290.779822706183, 6507.739690412378, 6625.867482650368, 6631.966840165816, 6662.0, 6681.298463661343, 6753.763618273337, 6759.053732477408, 6777.762778302736, 6779.559328643622, 6788.7623933162595, 6146.938530614726, 6278.591891527036, 6280.591761535486, 6287.245530536365, 6309.116723658692, 6733.9326606734285, 6787.898181527281, 6886.380225779704, 6912.0, 6930.306969303029, 6285.968570157212, 6375.872482550323, 6469.159009328851, 6516.2506311773805, 6522.15212022434, 6646.335366463315, 6691.899621505681, 6702.0, 6727.159105111861, 6745.325467453314, 6619.86760264792, 6628.96685516579, 6636.800895973129, 6641.767538136142, 6668.899966500507, 6330.620162790247, 6381.074744162163, 6521.543491955613, 6585.111010013587, 6623.834404139839, 5336.0, 5340.118880384771, 5350.8127215547265, 5351.331083614548, 5381.219723140122, 4746.216874215785, 4760.785764640599, 4765.1660959331975, 4766.0, 4797.592204662643, 6061.454469097803, 6252.562320637512, 6278.215223097113, 6293.842653933597, 6333.366663333722, 6752.493562982796, 6783.457323414115, 6803.897941530881, 6818.0, 6831.316868313129, 6204.968975155095, 6304.760003991581, 6308.369163083655, 6328.746850125944, 6331.2085989251345, 5733.426657334233, 5807.332156801937, 5914.408559144051, 5930.080837470199, 5947.345791962881, 6821.727130914807, 6910.101686780687, 6912.997615345847, 6932.48006399512, 6935.2717964615, 6505.967470162619, 6521.934780652323, 6522.543421960607, 6540.215174179129, 6548.8035358939305, 6651.035599838093, 6661.900071499028, 6675.899861501985, 6697.497687673346, 6795.7961261162245, 6448.419642232222, 6518.641474718746, 6555.901661474986, 6560.573562718408, 6590.373914478117, 6722.932770672231, 6746.156730408699, 6756.054152418567, 6759.087523184307, 6775.62734049632, 5425.186222066722, 6311.147995020613, 6340.270868850142, 6411.294757576756, 6475.481961442979, 6443.291237963726, 6515.348465153446, 6534.803955881332, 6557.737690492327, 6566.868662626816, 5105.642605017613, 6381.649009304533, 6392.64840433762, 6404.0, 6406.263279722892, 6307.148534947723, 6381.457576106083, 6401.199850018747, 6543.476521878333, 6568.9014664781025, 6314.147590075465, 6364.6181229126405, 6374.0, 6375.776847810304, 6410.839729006812], "value": 6043.48685659721, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [785.8153333966517, 840.8486472434962, 847.1316900177316, 1267.6640690217093, 1342.8388593368798, 39656.405153922715, 40822.795886020525, 40826.59173408265, 40877.59122408775, 41114.58885411145, 38324.80837595815, 41749.37375939356, 41765.37351939716, 41889.16221675572, 41945.58054419455, 7858.410619203568, 8007.799805004893, 8192.098869124422, 8440.240378365947, 8441.788955276106, 8288.295494882943, 8421.831563368729, 8433.114522975091, 8435.24082832545, 8435.746927592181, 8395.874061889077, 8444.071152173286, 8459.154084591522, 8481.915180848206, 8485.533295668736, 8313.459625124407, 8377.45546539476, 8407.789805254857, 8420.578971051484, 8446.070932197423, 6885.483588730815, 8077.071136819282, 8142.674293028272, 8158.5104893705975, 8201.343892488587, 37930.81034594809, 38157.618423815686, 38248.85253442401, 38357.424638630444, 38379.0, 3600.2979419013304, 3618.185908170667, 3618.475321078429, 3636.163682353047, 3637.7999210043436, 31039.37921241585, 31043.534346984812, 31044.223894402596, 31077.689223107707, 31101.688983110107, 2796.0, 2903.477374072666, 2942.837579156221, 3055.8235079494557, 3123.453395655751, 250062.0, 252824.0, 253926.0, 254651.0, 266737.66631166916, 48989.02021959541, 49166.75416622966, 49183.50816491861, 49198.50801491939, 49280.75359623179, 50577.49422505801, 50680.746596266785, 50752.70107443004, 55332.51003704843, 56376.43623563794, 14673.77989330161, 14685.48600798967, 14689.632759180893, 14756.335964881606, 14808.407663693548, 11675.88324116748, 11753.706157346134, 11761.411929403666, 11773.705657358634, 11791.646250612495, 14159.008869379044, 14229.28853557318, 14259.572212833631, 14296.499622513371, 14300.49948251827, 6327.209098862642, 6328.272248691367, 6338.524610654219, 6402.743890244431, 6416.807495775134, 883.8585826267766, 1012.4785735346303, 1026.0560284538242, 1056.2395075545594, 1111.377628528027, 24636.89133988975, 24780.38049048788, 24983.251172417757, 25001.124960626654, 25128.0, 1051.5530899367739, 1152.5965911930925, 1160.721426857565, 1167.5563285951284, 1195.9043276537857, 10529.631462898762, 12603.747925041447, 12626.368681565886, 12675.17611355259, 12684.04869634781, 278427.0, 330432.0, 332595.0, 332719.0, 332957.0, 51944.02643695948, 52189.47810521846, 52218.0, 52271.0, 52474.95050098977, 1350.6218258887332, 1483.3028476615973, 1528.9603069912343, 1562.8359022302723, 1567.2242240091227, 23855.522889541764, 23995.040198391867, 24066.759332407153, 24158.758412416355, 24185.879070604533, 38753.612463875, 38915.2216955665, 38954.0, 39062.414063789634, 39126.0, 288015.0, 288044.0, 288691.0, 289363.0, 290005.0, 34854.30291394107, 36148.0, 36171.019292540645, 36488.27023459569, 36535.26929461449, 44649.77675111734, 45755.77122114368, 46548.767256163504, 46575.602731918094, 46614.0, 29558.40883182367, 31199.688003119678, 31434.685653143177, 31832.5225121628, 32146.357072857943, 30694.386112278076, 31154.0, 31312.0, 31405.057848264587, 31588.73645054172, 38015.61984380121, 38259.85220443391, 38284.2343153141, 38368.42447363347, 38405.80797095997, 28925.7107428923, 29768.958086467737, 31213.84393078111, 31645.20886977844, 32065.0, 17250.223739931986, 23513.882430588423, 24745.505089897742, 24855.751442486067, 25180.74819251784], "value": 17745.029882943007, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [363.33691013899625, 425.40499044061187, 428.6742076022222, 430.35231975876303, 443.3991940920053, 22267.77732222677, 22930.770692293074, 22988.425289367755, 23462.530749385, 24561.1403600874, 24464.0, 24852.50294994099, 24957.75211239435, 24959.50080998379, 24964.625530617057, 6187.876242475148, 6333.809985700436, 6339.746410143589, 6344.111824344595, 6360.522960777937, 6266.5300102492265, 6270.372962703739, 6289.905651415233, 6306.590071645352, 6468.708908099124, 6840.0, 6854.691538880737, 6876.346747059021, 6890.862182756367, 6908.827279318032, 6892.965535172342, 6899.551529150614, 6900.171979362509, 6904.275051119661, 6910.48171387148, 5700.287464066992, 6713.731450742013, 6758.797236082925, 6763.526553141282, 6773.966130169367, 31016.844915775502, 31231.72066932962, 31847.04458866238, 31860.362792744247, 32060.358792824012, 2255.2783109404995, 2272.5114100468477, 2278.362058623588, 2292.7821856923565, 2321.117975169425, 19781.604367912703, 20141.49646258855, 20282.797172028386, 20345.084471198683, 20368.981550922395, 2283.246528645543, 2283.360659015478, 2332.6734257204007, 2347.718273807154, 2384.5350156719446, 169576.15211923985, 171362.0, 178324.0, 203124.0, 210965.0, 22129.778702213094, 24739.0, 26708.866455667598, 26832.59751103735, 27729.861350693118, 42433.57566424296, 43098.56901431008, 43112.0, 43184.0, 43225.27098916008, 9470.857937130948, 9956.705628268279, 10706.197035222389, 11462.312261264351, 11577.131715121399, 7431.665575049135, 8940.821183576292, 9112.589933453011, 9153.176214140625, 9191.908080919238, 10768.56925722978, 12351.752964940652, 12499.312537810325, 12690.42893069814, 12762.298073605854, 4983.227599722049, 5138.2806407103035, 5145.742712864342, 5158.432572417031, 5171.896562068738, 852.5098068610574, 939.7415710679618, 971.2812518736084, 1002.6991902429243, 1004.4475538453821, 19735.703964440545, 21309.68035479469, 21327.68008479874, 21347.25284615031, 21467.78532214689, 989.5052473763119, 997.5983742841174, 1005.6932635546228, 1021.4994652620163, 1022.1005515146772, 10672.57309707603, 11067.284570891525, 11134.16493762971, 11170.832437513443, 11217.887821121847, 536280.0, 540276.0, 545825.0, 552234.0, 552815.0, 137621.0, 139317.9102313466, 141490.0, 153394.0, 160291.0, 903.2728653433917, 1164.6564263542107, 1221.590767093016, 1243.1484433163312, 1249.650097972569, 20184.697229541864, 21022.57954840864, 21998.6800791953, 23025.65461518112, 23558.52882942366, 67497.6500469978, 68022.63954720976, 68121.0, 70904.0, 71456.64271678608, 86979.1302086971, 87552.1244787544, 88016.0, 88314.67527987214, 88437.55781221052, 10310.32982856112, 10356.275060745675, 10370.740731481774, 10427.426491542734, 10487.16102711766, 18159.909200453912, 18238.08809559543, 18359.90820045891, 18461.16924738417, 18484.537886552946, 14699.11805291686, 14759.483418080315, 15089.924550377178, 15098.018628789094, 15128.924355378593, 11508.251963622337, 12873.678158046123, 13537.72924541484, 13663.863361366259, 13841.930790345983, 15131.54605361841, 15225.923870380577, 15308.387664493295, 15321.004134731207, 15339.539813805604, 10188.898111018794, 10302.22733294991, 10343.586256549654, 10381.740456488345, 10382.740431489274, 14536.491222807152, 14818.03682760617, 14909.403623854925, 15043.623909402353, 15051.849481505484], "value": 12484.046518029905, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1225.3750587200527, 1501.2193659297159, 1509.08700236357, 1512.8940974131815, 1524.3064405695409, 11778.175527713052, 12121.757564848698, 12235.938820305886, 12248.571300004502, 12469.750604987894, 11863.347515886622, 11889.64331070067, 11986.220895641778, 12052.276863388202, 12060.638180854567, 2540.517301712673, 2569.0, 2593.314345675317, 2593.4035171910473, 2596.5585850405478, 2517.7356377580363, 2557.2200478853943, 2561.5645340292194, 2561.679790026247, 2562.3466016165853, 2513.9371515712164, 2520.836145650536, 2534.847909125449, 2542.3898264416594, 2545.7199708032103, 2484.7391023942496, 2513.8868750906163, 2516.069054449856, 2518.622206668996, 2521.936951576207, 1910.7420498232839, 2285.2572913802915, 2288.039023610088, 2447.706275246982, 2567.3966617844976, 11668.474918628683, 11738.413079346084, 11752.118591105702, 11755.412229388496, 11761.64715058541, 6477.514186436036, 6732.966335168293, 6739.258681545026, 6741.561798483083, 6742.865142697118, 3589.587197472298, 4118.464599602033, 4318.330658747899, 4330.13397320534, 4381.189479946188, 1089.9400532970683, 1264.3930913161641, 1271.4899713467016, 1274.7004453953314, 1287.9935600322033, 14533.27333633314, 15861.365545378056, 16649.66700665992, 16739.246733897005, 16790.0, 4771.475137734847, 5070.61970352225, 5579.860503487445, 5678.744456499468, 5733.197352370671, 11989.520419183134, 12091.637250882311, 12150.45322960469, 12165.5133794649, 12181.817272740916, 6400.743970241231, 6408.455281301049, 6520.706568204442, 6536.379043990813, 6546.312637173123, 6106.603070800473, 6159.969200153971, 6198.659073750987, 6215.192025036807, 6233.781817636452, 4436.689431739812, 4494.325851122358, 4527.47933987589, 4533.297338912474, 4564.338170965258, 1231.1259006105586, 1258.2890666773426, 1273.153926806135, 1294.0424086176163, 1294.4078783096843, 691.6714560583682, 780.1262585903823, 811.1766556944706, 813.8982627171604, 816.3306089006958, 6685.933140668629, 7010.894836577456, 7033.980072889402, 7055.3650171484815, 7161.785146445615, 813.507827764206, 897.3359713811732, 951.4671783801093, 951.8952915179325, 953.4851180362526, 3889.0, 4400.9119817604105, 4431.028192454327, 4499.3251012347755, 4526.479454862667, 14160.291985400894, 15142.394304227928, 15396.307166177548, 15398.615034624, 15437.30532126057, 12603.747925041447, 14793.0, 15118.546443606709, 15257.237138143048, 15309.846901530842, 8608.569571521399, 8620.52587107715, 8722.215000649845, 8729.127087291221, 8847.646094156291, 5132.8460146195675, 6579.309172536815, 6792.388685018174, 7070.292970702889, 7181.0, 13003.609891703263, 13144.802827957397, 13158.60524184276, 13396.26320552379, 13939.790903136663, 13462.394192261567, 14049.157050576589, 14087.929560352133, 14116.223607701675, 14126.78809817874, 7848.587254294282, 9454.101860323259, 9599.280053995839, 9607.318719224248, 9686.75783105428, 10850.891491084989, 11072.723181920517, 11074.058705010164, 11077.0, 11113.22207445471, 9176.0, 9281.118293762082, 9318.48748318849, 9446.905530944603, 9472.668456603986, 9111.681091161778, 9185.586648600962, 9223.815523689624, 9285.62857485693, 9286.30352723535, 8032.156623554442, 8342.416030877781, 8366.66533338684, 8420.747377578682, 8445.873311900203, 6037.245344331958, 6057.727402266821, 6216.844578885383, 6221.222347206599, 6243.063540468966, 5363.5440987516495, 5863.94136058651, 6018.849528761816, 6268.937310626835, 6287.0], "value": 5423.050170667024, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [376.5801131738111, 376.58951742600595, 381.9407991761276, 407.67997122259027, 412.28057040464387, 5752.424757524245, 6249.5625306228585, 6391.45672617828, 6406.935930640692, 6505.80482585522, 4338.566143385668, 4808.495108013661, 6215.19202503675, 6265.0, 6375.617462952227, 3764.8705388383514, 4542.205114105035, 4572.15415148197, 4576.473705523874, 4601.585857272845, 4074.4295798588223, 4104.40486129511, 4587.954120458803, 4632.606228470584, 4644.558766917137, 3934.803259836997, 4528.411306530146, 4535.7278563286145, 4545.659075569329, 4551.613112885409, 3629.800360980145, 4545.886352841206, 4550.49944506104, 4563.224251877189, 4615.423072115986, 3416.265502916885, 3856.4408160816524, 3876.577139287945, 3891.4941057662604, 3897.337452633059, 4483.820647174109, 6153.123297394293, 6158.107074474176, 6191.380861913818, 6192.318844927055, 4666.6500012498855, 5240.449752775941, 5390.838274851761, 5398.91901621476, 5438.83683489496, 6087.604305720159, 6706.530542862001, 6718.849968010239, 6731.1922569292, 6734.629595372253, 3693.833777479993, 4168.686379059621, 4234.470691163605, 4377.671674624384, 4389.4513185851765, 5573.888522229533, 6815.909454487308, 6973.267806880306, 7126.928730712679, 7206.279372062804, 3345.5483509725877, 4119.505659320901, 4183.226103170923, 4399.670024748157, 4451.488078870972, 3887.6889848812593, 4531.210171982048, 4535.818567257339, 4539.84110556129, 4554.246615053162, 5243.187305967581, 5354.250404943311, 5358.356997160366, 5364.9731751341, 5422.566194704415, 5220.686758794484, 6409.55133140685, 6665.566738162004, 6693.129893113962, 6718.227403848522, 1548.0, 1553.2932515705552, 1553.7747026681293, 1558.096304143594, 1562.1486289971992, 894.4186278918755, 987.9703608891745, 1002.6039714312806, 1011.3224139826334, 1019.2559431614962, 5674.460926212003, 6280.9685951569945, 6338.873222535523, 6360.52296077796, 6410.583312084699, 7018.6484379684925, 7021.754238601625, 7026.297370262932, 7030.613316267654, 7034.753783617549, 6206.255249370104, 6339.809805705836, 6389.370710468801, 6419.711112999927, 6450.677466126769, 2788.5538313869683, 2827.3638431352783, 3260.4620237660993, 3650.6166852480637, 3690.8339124738914, 6900.516963812485, 7236.937472820221, 7243.718228757924, 7249.0, 7274.272572742683, 6307.684615769193, 7219.602921839244, 7235.914612808121, 7241.384482319062, 7295.671694773748, 6457.967710161513, 6720.32796720324, 6800.08198893153, 7243.637818109073, 7261.491695581259, 6988.615626140611, 6991.811392063463, 6995.79012629622, 6996.685149168401, 7013.824654383681, 5952.910706339322, 6080.847978800565, 6261.211966760045, 6905.136857892763, 6928.965355173192, 6149.0, 6501.317361676955, 7128.6792094356915, 7209.243029481829, 7222.422206223595, 4432.623227025739, 4457.732536047847, 4473.865784026484, 4477.634321531964, 4502.50472448037, 6135.570510064253, 6206.038064100072, 7021.543599666005, 7051.647417628996, 7053.576785392893, 5809.796657116981, 5937.762489500545, 6635.0, 6723.865522689616, 6761.019652150312, 5823.94176058234, 6177.258728952374, 6546.083548303337, 6650.767223147167, 6719.563228390334, 5404.0, 5969.343372228965, 6520.695860827758, 6594.439472644879, 6594.604323740399, 5622.437756224344, 5625.437456254342, 5942.762289508544, 6739.865202696017, 6754.8649027020165, 6538.855700252532, 6544.2474115475425, 6556.147700798866, 6623.31755638967, 6634.303398143125], "value": 4788.865670503725, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [729.5768454296508, 836.6987884361632, 843.8523258429773, 861.9008813986394, 876.7457437343171, 14743.705125897475, 15377.538673839776, 16390.91804540977, 16829.83170168298, 16876.493705188863, 13794.172349659002, 17784.46646600604, 17825.93044417336, 17827.197776100074, 17867.374641887538, 991.2714155095998, 1000.7898341348325, 1019.3425240719731, 1040.8490768838515, 1041.1878734586996, 1044.8171569975261, 1046.2257929132463, 1046.4349251404233, 1047.2302857399804, 1048.6172547020321, 879.8592225243963, 1069.3370110531482, 1074.2265568790458, 1076.6931424544007, 1077.0737166037202, 1060.9681709548727, 1061.7451811565206, 1061.7982583309224, 1063.4470075560741, 1066.3388699006666, 777.9105402878685, 797.7048492057976, 802.9437939344248, 804.6097642643314, 806.3589446390134, 17070.975741455553, 17492.38776642824, 17591.296348146057, 17766.37817676388, 18118.0, 15210.0, 17729.645407091914, 17766.822331776646, 18130.365437209766, 18140.183691733902, 14704.117752934859, 14936.701265974729, 14969.326380312912, 14999.400023999027, 15164.545063648107, 3500.597431295408, 3521.2253304273277, 3526.894193174209, 3554.182538016245, 3628.618995005539, 90122.99999999935, 125942.37028814797, 127366.72633273642, 127562.0, 129065.70934290631, 13287.601371958857, 13906.58280251594, 14044.0, 14113.858861411254, 14151.0, 20563.280285190245, 26589.734102658727, 27166.1850144496, 27446.862765686044, 27776.58335124975, 6862.416694581017, 7035.613041282779, 7890.289873911375, 7942.404319676048, 7992.560409177551, 3479.6694314040124, 3823.235352929425, 3828.272628200633, 3831.3295173344554, 3832.080300727861, 4319.935200971988, 4387.517373088957, 4392.538783427694, 4419.756913369732, 4421.7789110544345, 813.251808336332, 956.8995255498212, 963.6386355116831, 979.8530220466987, 984.1142971325721, 627.7457629659995, 642.9678516074177, 645.5674697952289, 650.3139188156446, 670.4703284405265, 11392.772144557062, 11536.942315288537, 12416.81374779379, 12531.060170487068, 12558.309292988974, 571.9199312096309, 635.2186810223359, 640.1517988665038, 655.9212894452696, 673.2896793882501, 5679.784883895284, 5684.431556844283, 5782.277215348082, 5816.5055970243, 5819.301683797971, 117339.41330293464, 144560.0, 147460.0, 148468.25765871102, 151568.2421587907, 133185.0, 133755.0, 134340.08667876237, 134847.0, 136410.3179484116, 110636.0, 111337.32994005096, 111964.0, 112264.0, 112786.43606781913, 10958.45207739594, 11443.313401195956, 11450.255733377637, 11457.828132578185, 11635.709107272385, 42249.577504224566, 44522.55477445184, 45412.54587454083, 45603.315950261436, 47779.52220477751, 69028.0, 85862.0, 86320.0, 87503.24993499967, 89339.0, 17833.554161146076, 17940.820591793916, 18090.366837160636, 18158.45524634263, 18269.0, 17115.828841711424, 18144.45566633003, 19421.708674370177, 19442.12510437062, 19536.0, 16033.8396616037, 16561.92347497409, 16726.247318870923, 16758.58103547421, 18343.908280458512, 17113.828861711223, 17356.82643173552, 17831.0, 18026.90986545059, 18132.728009079612, 13212.0, 17377.739333909747, 17644.470665880042, 17826.91086544611, 17901.641967160846, 11840.881591183977, 11987.40062996864, 12217.0, 12309.0, 12404.81392779091, 13321.533746318832, 13498.730025399633, 13533.0, 14025.29873506341, 14418.837174423921], "value": 8864.957390636437, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1524.8, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [103.0, 116.0, 115.0, 115.0, 114.0, 114.0, 115.0, 114.0, 135.0, 117.0], "value": 115.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 110.0, 120.0, 192.0, 147.0, 132.0, 110.0, 113.0, 137.0, 110.0], "value": 131.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [197.0, 199.0, 197.0, 199.0, 209.0, 216.0, 212.0, 213.0, 195.0, 197.0], "value": 203.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 60.0, 61.0, 89.0, 86.0, 85.0, 58.0, 59.0, 81.0, 63.0], "value": 71.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 69.0, 85.0, 72.0, 70.0, 69.0, 90.0, 70.0, 68.0, 91.0], "value": 76.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 90.0, 89.0, 90.0, 90.0, 90.0, 90.0, 98.0, 90.0, 98.0], "value": 91.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 97.0, 123.0, 152.0, 97.0, 96.0, 97.0, 96.0, 121.0, 111.0], "value": 108.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 94.0, 91.0, 91.0, 124.0, 134.0, 91.0, 88.0, 90.0, 93.0], "value": 98.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [51.0, 51.0, 52.0, 51.0, 51.0, 52.0, 46.0, 46.0, 76.0, 78.0], "value": 55.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 62.0, 69.0, 69.0, 64.0, 62.0, 59.0, 66.0, 81.0, 82.0], "value": 66.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 86.0, 90.0, 88.0, 88.0, 85.0, 89.0, 89.0, 132.0, 90.0], "value": 92.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [139.0, 172.0, 155.0, 128.0, 153.0, 157.0, 138.0, 147.0, 130.0, 178.0], "value": 149.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [192.0, 188.0, 203.0, 241.0, 204.0, 186.0, 184.0, 187.0, 187.0, 186.0], "value": 195.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 67.0, 64.0, 66.0, 69.0, 69.0, 67.0, 67.0, 71.0, 68.0], "value": 67.5, "unit": "score"}]}]} 04:16:46 INFO - Return code: 0 04:16:46 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 04:16:46 INFO - # TBPL SUCCESS # 04:16:46 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 04:16:46 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 04:16:46 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 04:16:46 INFO - 2017-05-31 04:16:46.867 screenresolution[1701:12285] starting screenresolution argv=screenresolution get 04:16:46 INFO - 2017-05-31 04:16:46.869 screenresolution[1701:12285] Display 0: 1600x1200x32@60 04:16:46 INFO - 2017-05-31 04:16:46.875 screenresolution[1702:12288] starting screenresolution argv=screenresolution list 04:16:46 INFO - Available Modes on Display 0 04:16:46 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 04:16:46 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 04:16:46 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 04:16:46 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 04:16:47 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 04:16:47 INFO - Intel Iris: 04:16:47 INFO - Chipset Model: Intel Iris 04:16:47 INFO - Type: GPU 04:16:47 INFO - Bus: Built-In 04:16:47 INFO - VRAM (Dynamic, Max): 1536 MB 04:16:47 INFO - Vendor: Intel (0x8086) 04:16:47 INFO - Device ID: 0x0a2e 04:16:47 INFO - Revision ID: 0x0009 04:16:47 INFO - Displays: 04:16:47 INFO - Display: 04:16:47 INFO - Resolution: 1600 x 1200 @ 60 Hz 04:16:47 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 04:16:47 INFO - Display Serial Number: 1600x1200 60 04:16:47 INFO - Main Display: Yes 04:16:47 INFO - Mirror: Off 04:16:47 INFO - Online: Yes 04:16:47 INFO - Rotation: Supported 04:16:47 INFO - Return code: 0 04:16:47 INFO - Running post-action listener: _resource_record_post_action 04:16:47 INFO - [mozharness: 2017-05-31 11:16:47.113213Z] Finished run-tests step (success) 04:16:47 INFO - Running post-run listener: _resource_record_post_run 04:16:47 INFO - Total resource usage - Wall time: 1256s; CPU: 53.0%; Read bytes: 34673664; Write bytes: 570938368; Read time: 217; Write time: 1796 04:16:47 INFO - TinderboxPrint: CPU usage
53.3% 04:16:47 INFO - TinderboxPrint: I/O read bytes / time
34,673,664 / 217 04:16:47 INFO - TinderboxPrint: I/O write bytes / time
570,938,368 / 1,796 04:16:47 INFO - TinderboxPrint: CPU idle
2,347.2 (46.7%) 04:16:47 INFO - TinderboxPrint: CPU system
1,014.4 (20.2%) 04:16:47 INFO - TinderboxPrint: CPU user
1,664.2 (33.1%) 04:16:47 INFO - TinderboxPrint: Swap in / out
276,799,488 / 0 04:16:47 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 229458432; Write bytes: 229056512; Read time: 16750; Write time: 488 04:16:47 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 04:16:47 INFO - run-tests - Wall time: 1235s; CPU: 53.0%; Read bytes: 33682432; Write bytes: 323417088; Read time: 205; Write time: 1257 04:16:47 INFO - Running post-run listener: _upload_blobber_files 04:16:47 INFO - Blob upload gear active. 04:16:47 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 04:16:47 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 04:16:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 04:16:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 04:16:47 INFO - (blobuploader) - INFO - Open directory for files ... 04:16:47 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 04:16:47 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:16:48 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:16:49 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 04:16:49 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:16:49 INFO - (blobuploader) - INFO - Done attempting. 04:16:49 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 04:16:49 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:16:49 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:16:50 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 04:16:50 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:16:50 INFO - (blobuploader) - INFO - Done attempting. 04:16:50 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 04:16:50 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:16:50 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:16:51 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 04:16:51 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:16:51 INFO - (blobuploader) - INFO - Done attempting. 04:16:51 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 04:16:51 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:16:51 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:16:53 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 04:16:53 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:16:53 INFO - (blobuploader) - INFO - Done attempting. 04:16:53 INFO - (blobuploader) - INFO - Iteration through files over. 04:16:53 INFO - Return code: 0 04:16:53 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 04:16:53 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 04:16:53 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/13ca3ef5fcf107f75d3d3e718ddb482cd395283adcceda835a17fdb1510af6fc25cc87b336e3d083f32f5ba2dad678897e67f8d147eaf5976e34f59889d9354b", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/fa0a89b7e77d5a55a136e8428781e8f5de318074f3d326e432e150265f100cdf3fedc897814ec5fecf1f236a06990f9c1f785e974e3d2a1e310e89c5832a22ed", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b0654d35c426faa2c0af169011f1f98a112400a81713e64d1ce63165ac7367d9595e6029bbbf67dd465867d4a7dba44e79406a4daf14a38856cf2bfeab49c50a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/55f3c463586e975bc6f6d7e053e2c256152fc9af4416c36711074ed8e7efc6f807093ca0021680d24b05dc5292b49626b0c6ac720b8cce0c4b5e76b3bf2d0942"} 04:16:53 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 04:16:53 INFO - Writing to file /builds/slave/test/properties/blobber_files 04:16:53 INFO - Contents: 04:16:53 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/13ca3ef5fcf107f75d3d3e718ddb482cd395283adcceda835a17fdb1510af6fc25cc87b336e3d083f32f5ba2dad678897e67f8d147eaf5976e34f59889d9354b", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/fa0a89b7e77d5a55a136e8428781e8f5de318074f3d326e432e150265f100cdf3fedc897814ec5fecf1f236a06990f9c1f785e974e3d2a1e310e89c5832a22ed", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b0654d35c426faa2c0af169011f1f98a112400a81713e64d1ce63165ac7367d9595e6029bbbf67dd465867d4a7dba44e79406a4daf14a38856cf2bfeab49c50a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/55f3c463586e975bc6f6d7e053e2c256152fc9af4416c36711074ed8e7efc6f807093ca0021680d24b05dc5292b49626b0c6ac720b8cce0c4b5e76b3bf2d0942"} 04:16:53 INFO - Running post-run listener: copy_logs_to_upload_dir 04:16:53 INFO - Copying logs to upload dir... 04:16:53 INFO - mkdir: /builds/slave/test/build/upload/logs 04:16:53 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1338.085106 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 18 secs) (at 2017-05-31 04:16:53.869896) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-31 04:16:53.873010) ========= 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.zKfgE3tRXU/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.yyiFmqEUdl/Listeners TMPDIR=/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/13ca3ef5fcf107f75d3d3e718ddb482cd395283adcceda835a17fdb1510af6fc25cc87b336e3d083f32f5ba2dad678897e67f8d147eaf5976e34f59889d9354b", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/fa0a89b7e77d5a55a136e8428781e8f5de318074f3d326e432e150265f100cdf3fedc897814ec5fecf1f236a06990f9c1f785e974e3d2a1e310e89c5832a22ed", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b0654d35c426faa2c0af169011f1f98a112400a81713e64d1ce63165ac7367d9595e6029bbbf67dd465867d4a7dba44e79406a4daf14a38856cf2bfeab49c50a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/55f3c463586e975bc6f6d7e053e2c256152fc9af4416c36711074ed8e7efc6f807093ca0021680d24b05dc5292b49626b0c6ac720b8cce0c4b5e76b3bf2d0942"} build_url:https://queue.taskcluster.net/v1/task/azuV_t__TF2AkFnJB7Fikw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011092 build_url: 'https://queue.taskcluster.net/v1/task/azuV_t__TF2AkFnJB7Fikw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/13ca3ef5fcf107f75d3d3e718ddb482cd395283adcceda835a17fdb1510af6fc25cc87b336e3d083f32f5ba2dad678897e67f8d147eaf5976e34f59889d9354b", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/fa0a89b7e77d5a55a136e8428781e8f5de318074f3d326e432e150265f100cdf3fedc897814ec5fecf1f236a06990f9c1f785e974e3d2a1e310e89c5832a22ed", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b0654d35c426faa2c0af169011f1f98a112400a81713e64d1ce63165ac7367d9595e6029bbbf67dd465867d4a7dba44e79406a4daf14a38856cf2bfeab49c50a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/55f3c463586e975bc6f6d7e053e2c256152fc9af4416c36711074ed8e7efc6f807093ca0021680d24b05dc5292b49626b0c6ac720b8cce0c4b5e76b3bf2d0942"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-31 04:16:53.907053) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-31 04:16:53.907434) ========= 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.zKfgE3tRXU/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.yyiFmqEUdl/Listeners TMPDIR=/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/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.005251 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-31 04:16:53.956858) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-31 04:16:53.957247) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-31 04:16:53.957621) ========= ========= Total master_lag: 0.19 =========