builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0356 starttime: 1496141924.49 results: success (0) revision: 286f71223256cbb3a769432fd860f563c4886e81 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-05-30 03:58:44.492903) ========= master: http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-05-30 03:58:44.493427) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-30 03:58:44.493912) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-30 03:58:44.563369) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-30 03:58:44.563719) ========= 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.spmqfx40Ql/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.6M32lOAEkw/Listeners TMPDIR=/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-05-30 03:58:44-- 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% 164M=0s 2017-05-30 03:58:45 (164 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.645785 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-30 03:58:45.233159) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-30 03:58:45.233480) ========= 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.spmqfx40Ql/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.6M32lOAEkw/Listeners TMPDIR=/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/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.027039 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-30 03:58:45.281715) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-30 03:58:45.282073) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 286f71223256cbb3a769432fd860f563c4886e81 --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 286f71223256cbb3a769432fd860f563c4886e81 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.spmqfx40Ql/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.6M32lOAEkw/Listeners TMPDIR=/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-05-30 03:58:45,378 truncating revision to first 12 chars 2017-05-30 03:58:45,378 Setting DEBUG logging. 2017-05-30 03:58:45,378 attempt 1/10 2017-05-30 03:58:45,378 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/286f71223256?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-05-30 03:58:46,172 unpacking tar archive at: mozilla-central-286f71223256/testing/mozharness/ program finished with exit code 0 elapsedTime=1.069803 ========= master_lag: 0.14 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-30 03:58:46.489390) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-30 03:58:46.490208) ========= script_repo_revision: 286f71223256cbb3a769432fd860f563c4886e81 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-30 03:58:46.491138) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-30 03:58:46.491799) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-30 03:58:46.584706) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 18 secs) (at 2017-05-30 03:58:46.585070) ========= /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.spmqfx40Ql/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.6M32lOAEkw/Listeners TMPDIR=/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/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:58:46 INFO - MultiFileLogger online at 20170530 03:58:46 in /builds/slave/test 03:58:46 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:58:46 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 03:58:46 INFO - {'append_to_log': False, 03:58:46 INFO - 'base_work_dir': '/builds/slave/test', 03:58:46 INFO - 'blob_upload_branch': 'Firefox', 03:58:46 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 03:58:46 INFO - 'branch': 'Firefox', 03:58:46 INFO - 'buildbot_json_path': 'buildprops.json', 03:58:46 INFO - 'config_files': ('talos/mac_config.py',), 03:58:46 INFO - 'default_actions': ('clobber', 03:58:46 INFO - 'read-buildbot-config', 03:58:46 INFO - 'download-and-extract', 03:58:46 INFO - 'populate-webroot', 03:58:46 INFO - 'create-virtualenv', 03:58:46 INFO - 'install', 03:58:46 INFO - 'setup-mitmproxy', 03:58:46 INFO - 'run-tests'), 03:58:46 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 03:58:46 INFO - 'download_minidump_stackwalk': True, 03:58:46 INFO - 'download_symbols': 'ondemand', 03:58:46 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 03:58:46 INFO - 'tooltool.py': '/tools/tooltool.py', 03:58:46 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 03:58:46 INFO - '/tools/misc-python/virtualenv.py')}, 03:58:46 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 03:58:46 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 03:58:46 INFO - 'gecko_profile': False, 03:58:46 INFO - 'gecko_profile_interval': 0, 03:58:46 INFO - 'installer_path': 'installer.exe', 03:58:46 INFO - 'log_level': 'info', 03:58:46 INFO - 'log_name': 'talos', 03:58:46 INFO - 'log_to_console': True, 03:58:46 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 03:58:46 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 03:58:46 INFO - 'opt_config_files': (), 03:58:46 INFO - 'pip_index': False, 03:58:46 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 03:58:46 INFO - 'cmd': ('bash', 03:58:46 INFO - '-c', 03:58:46 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 03:58:46 INFO - 'enabled': True, 03:58:46 INFO - 'halt_on_failure': False, 03:58:46 INFO - 'name': 'check_screen_resolution'},), 03:58:46 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 03:58:46 INFO - 'cmd': ('bash', 03:58:46 INFO - '-c', 03:58:46 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 03:58:46 INFO - 'enabled': True, 03:58:46 INFO - 'halt_on_failure': False, 03:58:46 INFO - 'name': 'check_screen_resolution'},), 03:58:46 INFO - 'run_cmd_checks_enabled': True, 03:58:46 INFO - 'suite': 'dromaeojs-e10s', 03:58:46 INFO - 'system_bits': '32', 03:58:46 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 03:58:46 INFO - 'title': 't-yosemite-r7-0356', 03:58:46 INFO - 'tooltool_cache': '/builds/tooltool_cache', 03:58:46 INFO - 'use_talos_json': True, 03:58:46 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 03:58:46 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 03:58:46 INFO - 'work_dir': 'build'} 03:58:46 INFO - [mozharness: 2017-05-30 10:58:46.798314Z] Running clobber step. 03:58:46 INFO - Running pre-action listener: _resource_record_pre_action 03:58:46 INFO - Running main action method: clobber 03:58:46 INFO - rmtree: /builds/slave/test/build 03:58:46 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 03:58:49 INFO - Running post-action listener: _resource_record_post_action 03:58:49 INFO - [mozharness: 2017-05-30 10:58:49.203211Z] Finished clobber step (success) 03:58:49 INFO - [mozharness: 2017-05-30 10:58:49.203337Z] Running read-buildbot-config step. 03:58:49 INFO - Running pre-action listener: _resource_record_pre_action 03:58:49 INFO - Running main action method: read_buildbot_config 03:58:49 INFO - Using buildbot properties: 03:58:49 INFO - { 03:58:49 INFO - "project": "", 03:58:49 INFO - "product": "firefox", 03:58:49 INFO - "who": "cbook@mozilla.com", 03:58:49 INFO - "installer_path": "public/build/firefox-55.0a1.en-US.mac.dmg", 03:58:49 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 03:58:49 INFO - "repository": "https://hg.mozilla.org/mozilla-central/", 03:58:49 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 03:58:49 INFO - "stage_platform": "macosx64", 03:58:49 INFO - "basedir": "/builds/slave/test", 03:58:49 INFO - "buildnumber": 37, 03:58:49 INFO - "platform": "yosemite_r7", 03:58:49 INFO - "parent_task_id": "KPnkVo3nQGeAD9kc138Kew", 03:58:49 INFO - "master": "http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/", 03:58:49 INFO - "slavebuilddir": "test", 03:58:49 INFO - "taskId": "MmTO6waPTdWow7QBNL9rsA", 03:58:49 INFO - "branch": "mozilla-central", 03:58:49 INFO - "script_repo_revision": "production", 03:58:49 INFO - "revision": "286f71223256cbb3a769432fd860f563c4886e81", 03:58:49 INFO - "slavename": "t-yosemite-r7-0356", 03:58:49 INFO - "repo_path": "mozilla-central" 03:58:49 INFO - } 03:58:49 INFO - Finding installer, test and symbols from parent task. 03:58:49 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/MmTO6waPTdWow7QBNL9rsA'}, attempt #1 03:58:49 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/MmTO6waPTdWow7QBNL9rsA'}, attempt #1 03:58:50 INFO - Task dependencies: LGSsYDEKSjOc4eBOKXS1xA KPnkVo3nQGeAD9kc138Kew 03:58:50 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/KPnkVo3nQGeAD9kc138Kew'}, attempt #1 03:58:50 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/KPnkVo3nQGeAD9kc138Kew/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 03:58:50 INFO - Set test_url: https://queue.taskcluster.net/v1/task/KPnkVo3nQGeAD9kc138Kew/artifacts/public/build/test_packages.json 03:58:50 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/KPnkVo3nQGeAD9kc138Kew/artifacts/public/build/target.crashreporter-symbols.zip 03:58:50 INFO - Running post-action listener: _resource_record_post_action 03:58:50 INFO - [mozharness: 2017-05-30 10:58:50.879803Z] Finished read-buildbot-config step (success) 03:58:50 INFO - [mozharness: 2017-05-30 10:58:50.879890Z] Running download-and-extract step. 03:58:50 INFO - Running pre-action listener: _resource_record_pre_action 03:58:50 INFO - Running main action method: download_and_extract 03:58:50 INFO - mkdir: /builds/slave/test/build/tests 03:58:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:58:50 INFO - trying https://queue.taskcluster.net/v1/task/KPnkVo3nQGeAD9kc138Kew/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 03:58:50 INFO - Downloading https://queue.taskcluster.net/v1/task/KPnkVo3nQGeAD9kc138Kew/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:58:50 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/KPnkVo3nQGeAD9kc138Kew/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:58:51 INFO - Downloaded 1325 bytes. 03:58:51 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 03:58:51 INFO - Using the following test package requirements: 03:58:51 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 03:58:51 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 03:58:51 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 03:58:51 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 03:58:51 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 03:58:51 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 03:58:51 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 03:58:51 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 03:58:51 INFO - u'jsshell-mac.zip'], 03:58:51 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 03:58:51 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 03:58:51 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 03:58:51 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 03:58:51 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 03:58:51 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 03:58:51 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 03:58:51 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 03:58:51 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 03:58:51 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 03:58:51 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 03:58:51 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 03:58:51 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/KPnkVo3nQGeAD9kc138Kew/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 03:58:51 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/KPnkVo3nQGeAD9kc138Kew/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 03:58:51 INFO - Fetch https://queue.taskcluster.net/v1/task/KPnkVo3nQGeAD9kc138Kew/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 03:58:53 INFO - Content-Length response header: 57633413 03:58:53 INFO - Bytes received: 57633413 03:59:12 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:59:12 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/KPnkVo3nQGeAD9kc138Kew/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 03:59:12 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/KPnkVo3nQGeAD9kc138Kew/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 03:59:12 INFO - Fetch https://queue.taskcluster.net/v1/task/KPnkVo3nQGeAD9kc138Kew/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 03:59:14 INFO - Content-Length response header: 57633413 03:59:14 INFO - Bytes received: 57633413 03:59:31 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/KPnkVo3nQGeAD9kc138Kew/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 03:59:31 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/KPnkVo3nQGeAD9kc138Kew/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 03:59:31 INFO - Fetch https://queue.taskcluster.net/v1/task/KPnkVo3nQGeAD9kc138Kew/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 03:59:32 INFO - Content-Length response header: 14447425 03:59:32 INFO - Bytes received: 14447425 03:59: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:59:33 INFO - trying https://queue.taskcluster.net/v1/task/KPnkVo3nQGeAD9kc138Kew/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 03:59:33 INFO - Downloading https://queue.taskcluster.net/v1/task/KPnkVo3nQGeAD9kc138Kew/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 03:59:33 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/KPnkVo3nQGeAD9kc138Kew/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:59:35 INFO - Downloaded 67894184 bytes. 03:59:35 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/KPnkVo3nQGeAD9kc138Kew/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 03:59:35 INFO - mkdir: /builds/slave/test/properties 03:59:35 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 03:59:35 INFO - Writing to file /builds/slave/test/properties/build_url 03:59:35 INFO - Contents: 03:59:35 INFO - build_url:https://queue.taskcluster.net/v1/task/KPnkVo3nQGeAD9kc138Kew/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 03:59:35 INFO - Running post-action listener: _resource_record_post_action 03:59:35 INFO - Running post-action listener: set_extra_try_arguments 03:59:35 INFO - [mozharness: 2017-05-30 10:59:35.115813Z] Finished download-and-extract step (success) 03:59:35 INFO - [mozharness: 2017-05-30 10:59:35.115959Z] Running populate-webroot step. 03:59:35 INFO - Running pre-action listener: _resource_record_pre_action 03:59:35 INFO - Running main action method: populate_webroot 03:59:35 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 03:59:35 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 03:59:35 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 03:59:35 INFO - '--tppagecycles', 03:59:35 INFO - '7'], 03:59:35 INFO - 'tests': ['tsvgm']}}, 03:59:35 INFO - 'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 03:59:35 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 03:59:35 INFO - 'tests': ['dromaeo_css', 'kraken']}, 03:59:35 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 03:59:35 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 03:59:35 INFO - 'talos_options': ['--disable-e10s'], 03:59:35 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:59:35 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 03:59:35 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:59:35 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 03:59:35 INFO - 'talos_options': ['--disable-e10s'], 03:59:35 INFO - 'tests': ['damp', 'tps']}, 03:59:35 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 03:59:35 INFO - 'tests': ['damp', 'tps']}, 03:59:35 INFO - 'g3': {'talos_options': ['--disable-e10s'], 03:59:35 INFO - 'tests': ['dromaeo_dom']}, 03:59:35 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 03:59:35 INFO - 'g4': {'talos_options': ['--disable-e10s'], 03:59:35 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 03:59:35 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 03:59:35 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 03:59:35 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 03:59:35 INFO - 'other': {'talos_options': ['--disable-e10s'], 03:59:35 INFO - 'tests': ['a11yr', 03:59:35 INFO - 'ts_paint', 03:59:35 INFO - 'tpaint', 03:59:35 INFO - 'sessionrestore', 03:59:35 INFO - 'sessionrestore_no_auto_restore', 03:59:35 INFO - 'tabpaint']}, 03:59:35 INFO - 'other-e10s': {'tests': ['a11yr', 03:59:35 INFO - 'ts_paint', 03:59:35 INFO - 'tpaint', 03:59:35 INFO - 'sessionrestore', 03:59:35 INFO - 'sessionrestore_no_auto_restore', 03:59:35 INFO - 'tabpaint']}, 03:59:35 INFO - 'other-e10s_l64': {'tests': ['a11yr', 03:59:35 INFO - 'ts_paint', 03:59:35 INFO - 'tpaint', 03:59:35 INFO - 'sessionrestore', 03:59:35 INFO - 'sessionrestore_no_auto_restore', 03:59:35 INFO - 'tabpaint']}, 03:59:35 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 03:59:35 INFO - 'ts_paint', 03:59:35 INFO - 'tpaint', 03:59:35 INFO - 'sessionrestore', 03:59:35 INFO - 'sessionrestore_no_auto_restore', 03:59:35 INFO - 'tabpaint']}, 03:59:35 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 03:59:35 INFO - 'tests': ['a11yr', 03:59:35 INFO - 'ts_paint', 03:59:35 INFO - 'tpaint', 03:59:35 INFO - 'sessionrestore', 03:59:35 INFO - 'sessionrestore_no_auto_restore', 03:59:35 INFO - 'tabpaint']}, 03:59:35 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 03:59:35 INFO - 'tests': ['a11yr', 03:59:35 INFO - 'ts_paint', 03:59:35 INFO - 'tpaint', 03:59:35 INFO - 'sessionrestore', 03:59:35 INFO - 'sessionrestore_no_auto_restore', 03:59:35 INFO - 'tabpaint']}, 03:59:35 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 03:59:35 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 03:59:35 INFO - 'bloom_basic_ref']}, 03:59:35 INFO - 'quantum-pageload-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set.zip', 03:59:35 INFO - 'talos_options': ['--mitmproxy', 03:59:35 INFO - 'mitmproxy-recording-1.mp', 03:59:35 INFO - '--firstNonBlankPaint'], 03:59:35 INFO - 'tests': ['Quantum_1']}, 03:59:35 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 03:59:35 INFO - 'tests': ['tsvgx', 03:59:35 INFO - 'tsvgr_opacity', 03:59:35 INFO - 'tart', 03:59:35 INFO - 'tscrollx', 03:59:35 INFO - 'cart', 03:59:35 INFO - 'tsvg_static']}, 03:59:35 INFO - 'svgr-e10s': {'tests': ['tsvgx', 03:59:35 INFO - 'tsvgr_opacity', 03:59:35 INFO - 'tart', 03:59:35 INFO - 'tscrollx', 03:59:35 INFO - 'cart', 03:59:35 INFO - 'tsvg_static']}, 03:59:35 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 03:59:35 INFO - 'talos_options': ['--disable-e10s'], 03:59:35 INFO - 'tests': ['tp5o']}, 03:59:35 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 03:59:35 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 03:59:35 INFO - 'talos_options': ['--disable-e10s', 03:59:35 INFO - '--xperf_path', 03:59:35 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:59:35 INFO - 'tests': ['tp5n']}, 03:59:35 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 03:59:35 INFO - 'talos_options': ['--xperf_path', 03:59:35 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:59:35 INFO - 'tests': ['tp5n']}}, 03:59:35 INFO - 'talos.zip': {'path': '', 03:59:35 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 03:59:35 INFO - Running post-action listener: _resource_record_post_action 03:59:35 INFO - [mozharness: 2017-05-30 10:59:35.137482Z] Finished populate-webroot step (success) 03:59:35 INFO - [mozharness: 2017-05-30 10:59:35.137605Z] Running create-virtualenv step. 03:59:35 INFO - Running pre-action listener: _resource_record_pre_action 03:59:35 INFO - Running main action method: create_virtualenv 03:59:35 INFO - Creating virtualenv /builds/slave/test/build/venv 03:59:35 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:59:35 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 03:59:35 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 03:59:35 INFO - Using real prefix '/tools/python27' 03:59:35 INFO - New python executable in /builds/slave/test/build/venv/bin/python 03:59:36 INFO - Installing distribute.............................................................................................................................................................................................done. 03:59:38 INFO - Installing pip.................done. 03:59:38 INFO - Return code: 0 03:59:38 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 03:59:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 03:59:39 INFO - Reading from file tmpfile_stdout 03:59:39 INFO - Output received: 03:59:39 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:59:39 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 03:59: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:59:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:59: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:59:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:59:39 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 0x1077a2c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1077a6768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fca5bdd7f40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10772bc60>, '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 0x1074c0cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1074e9be8>, '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.spmqfx40Ql/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.6M32lOAEkw/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/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:59:39 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:59:39 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:59:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.spmqfx40Ql/Render', 03:59:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:59:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:59:39 INFO - 'HOME': '/Users/cltbld', 03:59:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:59:39 INFO - 'LOGNAME': 'cltbld', 03:59:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:59:39 INFO - 'MOZ_NO_REMOTE': '1', 03:59:39 INFO - 'NO_EM_RESTART': '1', 03:59:39 INFO - 'PAGER': '/bin/cat', 03:59:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:59:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:59:39 INFO - 'PWD': '/builds/slave/test', 03:59:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:59:39 INFO - 'SHELL': '/bin/bash', 03:59:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6M32lOAEkw/Listeners', 03:59:39 INFO - 'TMPDIR': '/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/', 03:59:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:59:39 INFO - 'USER': 'cltbld', 03:59:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:59:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:59:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:59:39 INFO - 'XPC_FLAGS': '0x0', 03:59:39 INFO - 'XPC_SERVICE_NAME': '0', 03:59:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:59:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:59:39 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:59:39 INFO - Cleaning up... 03:59:39 INFO - Return code: 0 03:59:39 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 03:59: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:59:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:59: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:59:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:59:39 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 0x1077a2c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1077a6768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fca5bdd7f40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10772bc60>, '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 0x1074c0cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1074e9be8>, '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.spmqfx40Ql/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.6M32lOAEkw/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/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:59:39 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:59:39 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:59:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.spmqfx40Ql/Render', 03:59:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:59:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:59:39 INFO - 'HOME': '/Users/cltbld', 03:59:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:59:39 INFO - 'LOGNAME': 'cltbld', 03:59:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:59:39 INFO - 'MOZ_NO_REMOTE': '1', 03:59:39 INFO - 'NO_EM_RESTART': '1', 03:59:39 INFO - 'PAGER': '/bin/cat', 03:59:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:59:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:59:39 INFO - 'PWD': '/builds/slave/test', 03:59:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:59:39 INFO - 'SHELL': '/bin/bash', 03:59:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6M32lOAEkw/Listeners', 03:59:39 INFO - 'TMPDIR': '/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/', 03:59:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:59:39 INFO - 'USER': 'cltbld', 03:59:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:59:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:59:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:59:39 INFO - 'XPC_FLAGS': '0x0', 03:59:39 INFO - 'XPC_SERVICE_NAME': '0', 03:59:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:59:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:59:39 INFO - Downloading/unpacking psutil>=3.1.1 03:59:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:59:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:59:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:59:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:59:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 03:59:41 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:59:41 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:59:41 INFO - Installing collected packages: psutil 03:59:41 INFO - Running setup.py install for psutil 03:59:42 INFO - building 'psutil._psutil_osx' extension 03:59:42 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:59:42 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:59:42 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:59:42 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:59:42 INFO - building 'psutil._psutil_posix' extension 03:59:42 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:59:42 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 03:59:42 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 03:59:42 INFO - ^ 03:59:42 INFO - 1 warning generated. 03:59:42 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:59:43 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:59:43 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:59:43 INFO - Successfully installed psutil 03:59:43 INFO - Cleaning up... 03:59:43 INFO - Return code: 0 03:59:43 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 03:59: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:59:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:59: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:59:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:59:43 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1077a2c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1077a6768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fca5bdd7f40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10772bc60>, '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 0x1074c0cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1074e9be8>, '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.spmqfx40Ql/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.6M32lOAEkw/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/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:59:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 03:59:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 03:59:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.spmqfx40Ql/Render', 03:59:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:59:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:59:43 INFO - 'HOME': '/Users/cltbld', 03:59:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:59:43 INFO - 'LOGNAME': 'cltbld', 03:59:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:59:43 INFO - 'MOZ_NO_REMOTE': '1', 03:59:43 INFO - 'NO_EM_RESTART': '1', 03:59:43 INFO - 'PAGER': '/bin/cat', 03:59:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:59:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:59:43 INFO - 'PWD': '/builds/slave/test', 03:59:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:59:43 INFO - 'SHELL': '/bin/bash', 03:59:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6M32lOAEkw/Listeners', 03:59:43 INFO - 'TMPDIR': '/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/', 03:59:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:59:43 INFO - 'USER': 'cltbld', 03:59:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:59:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:59:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:59:43 INFO - 'XPC_FLAGS': '0x0', 03:59:43 INFO - 'XPC_SERVICE_NAME': '0', 03:59:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:59:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:59:43 INFO - Downloading/unpacking mozsystemmonitor==0.3 03:59:43 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:59:43 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:59:43 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:59:43 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:59:44 INFO - Downloading mozsystemmonitor-0.3.tar.gz 03:59:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 03:59:44 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 03:59:44 INFO - Installing collected packages: mozsystemmonitor 03:59:44 INFO - Running setup.py install for mozsystemmonitor 03:59:45 INFO - Successfully installed mozsystemmonitor 03:59:45 INFO - Cleaning up... 03:59:45 INFO - Return code: 0 03:59:45 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 03:59:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:59:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:59:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:59:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:59:45 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 0x1077a2c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1077a6768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fca5bdd7f40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10772bc60>, '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 0x1074c0cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1074e9be8>, '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.spmqfx40Ql/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.6M32lOAEkw/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/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:59:45 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:59:45 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:59:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.spmqfx40Ql/Render', 03:59:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:59:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:59:45 INFO - 'HOME': '/Users/cltbld', 03:59:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:59:45 INFO - 'LOGNAME': 'cltbld', 03:59:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:59:45 INFO - 'MOZ_NO_REMOTE': '1', 03:59:45 INFO - 'NO_EM_RESTART': '1', 03:59:45 INFO - 'PAGER': '/bin/cat', 03:59:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:59:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:59:45 INFO - 'PWD': '/builds/slave/test', 03:59:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:59:45 INFO - 'SHELL': '/bin/bash', 03:59:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6M32lOAEkw/Listeners', 03:59:45 INFO - 'TMPDIR': '/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/', 03:59:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:59:45 INFO - 'USER': 'cltbld', 03:59:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:59:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:59:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:59:45 INFO - 'XPC_FLAGS': '0x0', 03:59:45 INFO - 'XPC_SERVICE_NAME': '0', 03:59:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:59:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:59:45 INFO - Downloading/unpacking jsonschema==2.5.1 03:59: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:59: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:59: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:59: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:59:46 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 03:59:46 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 03:59:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:59:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:59:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:59:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:59:46 INFO - Downloading functools32-3.2.3-2.tar.gz 03:59:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 03:59:47 INFO - warning: no files found matching '*.txt' 03:59:47 INFO - no previously-included directories found matching 'build' 03:59:47 INFO - no previously-included directories found matching 'dist' 03:59:47 INFO - no previously-included directories found matching '.git*' 03:59:47 INFO - Installing collected packages: jsonschema, functools32 03:59:47 INFO - Running setup.py install for functools32 03:59:47 INFO - warning: no files found matching '*.txt' 03:59:47 INFO - no previously-included directories found matching 'build' 03:59:47 INFO - no previously-included directories found matching 'dist' 03:59:47 INFO - no previously-included directories found matching '.git*' 03:59:47 INFO - Successfully installed jsonschema functools32 03:59:47 INFO - Cleaning up... 03:59:47 INFO - Return code: 0 03:59:47 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 03:59: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:59:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:59: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:59:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:59:47 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1077a2c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1077a6768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fca5bdd7f40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10772bc60>, '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 0x1074c0cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1074e9be8>, '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.spmqfx40Ql/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.6M32lOAEkw/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/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:59:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 03:59:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 03:59:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.spmqfx40Ql/Render', 03:59:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:59:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:59:47 INFO - 'HOME': '/Users/cltbld', 03:59:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:59:47 INFO - 'LOGNAME': 'cltbld', 03:59:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:59:47 INFO - 'MOZ_NO_REMOTE': '1', 03:59:47 INFO - 'NO_EM_RESTART': '1', 03:59:47 INFO - 'PAGER': '/bin/cat', 03:59:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:59:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:59:47 INFO - 'PWD': '/builds/slave/test', 03:59:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:59:47 INFO - 'SHELL': '/bin/bash', 03:59:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6M32lOAEkw/Listeners', 03:59:47 INFO - 'TMPDIR': '/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/', 03:59:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:59:47 INFO - 'USER': 'cltbld', 03:59:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:59:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:59:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:59:47 INFO - 'XPC_FLAGS': '0x0', 03:59:47 INFO - 'XPC_SERVICE_NAME': '0', 03:59:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:59:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:59:47 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 03:59:47 INFO - Cleaning up... 03:59:47 INFO - Return code: 0 03:59:47 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 03:59: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:59:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:59: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:59:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:59:47 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1077a2c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1077a6768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fca5bdd7f40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10772bc60>, '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 0x1074c0cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1074e9be8>, '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.spmqfx40Ql/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.6M32lOAEkw/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/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:59:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 03:59:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 03:59:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.spmqfx40Ql/Render', 03:59:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:59:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:59:47 INFO - 'HOME': '/Users/cltbld', 03:59:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:59:47 INFO - 'LOGNAME': 'cltbld', 03:59:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:59:47 INFO - 'MOZ_NO_REMOTE': '1', 03:59:47 INFO - 'NO_EM_RESTART': '1', 03:59:47 INFO - 'PAGER': '/bin/cat', 03:59:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:59:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:59:47 INFO - 'PWD': '/builds/slave/test', 03:59:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:59:47 INFO - 'SHELL': '/bin/bash', 03:59:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6M32lOAEkw/Listeners', 03:59:47 INFO - 'TMPDIR': '/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/', 03:59:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:59:47 INFO - 'USER': 'cltbld', 03:59:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:59:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:59:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:59:47 INFO - 'XPC_FLAGS': '0x0', 03:59:47 INFO - 'XPC_SERVICE_NAME': '0', 03:59:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:59:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:59:47 INFO - Downloading/unpacking blobuploader==1.2.4 03:59:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:59:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:59:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:59:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:59:49 INFO - Downloading blobuploader-1.2.4.tar.gz 03:59:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 03:59:49 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 03:59:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:59:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:59:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:59:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:59:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 03:59:50 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 03:59:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:59:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:59:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:59:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:59:50 INFO - Downloading docopt-0.6.1.tar.gz 03:59:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 03:59:50 INFO - Installing collected packages: blobuploader, requests, docopt 03:59:50 INFO - Running setup.py install for blobuploader 03:59:50 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 03:59:50 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 03:59:50 INFO - Running setup.py install for requests 03:59:51 INFO - Running setup.py install for docopt 03:59:51 INFO - Successfully installed blobuploader requests docopt 03:59:51 INFO - Cleaning up... 03:59:51 INFO - Return code: 0 03:59:51 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:59:51 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:59:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:59:51 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:59:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:59:51 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 0x1077a2c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1077a6768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fca5bdd7f40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10772bc60>, '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 0x1074c0cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1074e9be8>, '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.spmqfx40Ql/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.6M32lOAEkw/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/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:59:51 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:59:51 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:59:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.spmqfx40Ql/Render', 03:59:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:59:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:59:51 INFO - 'HOME': '/Users/cltbld', 03:59:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:59:51 INFO - 'LOGNAME': 'cltbld', 03:59:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:59:51 INFO - 'MOZ_NO_REMOTE': '1', 03:59:51 INFO - 'NO_EM_RESTART': '1', 03:59:51 INFO - 'PAGER': '/bin/cat', 03:59:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:59:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:59:51 INFO - 'PWD': '/builds/slave/test', 03:59:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:59:51 INFO - 'SHELL': '/bin/bash', 03:59:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6M32lOAEkw/Listeners', 03:59:51 INFO - 'TMPDIR': '/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/', 03:59:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:59:51 INFO - 'USER': 'cltbld', 03:59:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:59:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:59:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:59:51 INFO - 'XPC_FLAGS': '0x0', 03:59:51 INFO - 'XPC_SERVICE_NAME': '0', 03:59:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:59:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:59:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:59:51 INFO - Running setup.py (path:/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/pip-S0ljFr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:59:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:59:51 INFO - Running setup.py (path:/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/pip-5A_QTs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:59:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:59:51 INFO - Running setup.py (path:/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/pip-_5gNTR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:59:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:59:51 INFO - Running setup.py (path:/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/pip-I1Soay-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:59:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:59:51 INFO - Running setup.py (path:/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/pip-6MsgZZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:59:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:59:51 INFO - Running setup.py (path:/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/pip-il_Ppt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:59:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:59:52 INFO - Running setup.py (path:/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/pip-ISG23t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:59:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:59:52 INFO - Running setup.py (path:/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/pip-medISZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:59:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:59:52 INFO - Running setup.py (path:/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/pip-8Y0rPY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:59:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:59:52 INFO - Running setup.py (path:/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/pip-DNqJKh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:59:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:59:52 INFO - Running setup.py (path:/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/pip-rGX4du-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:59:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:59:52 INFO - Running setup.py (path:/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/pip-EGLeOh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:59:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:59:52 INFO - Running setup.py (path:/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/pip-YCcKXB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:59:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:59:52 INFO - Running setup.py (path:/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/pip-LwdUU9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:59:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:59:52 INFO - Running setup.py (path:/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/pip-uFUN0O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:59:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:59:53 INFO - Running setup.py (path:/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/pip-57p87h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:59:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:59:53 INFO - Running setup.py (path:/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/pip-UgOGtD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:59:53 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 03:59:53 INFO - Running setup.py install for manifestparser 03:59:53 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 03:59:53 INFO - Running setup.py install for mozcrash 03:59:53 INFO - Running setup.py install for mozdebug 03:59:53 INFO - Running setup.py install for mozdevice 03:59:53 INFO - Installing dm script to /builds/slave/test/build/venv/bin 03:59:53 INFO - Running setup.py install for mozfile 03:59:53 INFO - Running setup.py install for mozhttpd 03:59:54 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 03:59:54 INFO - Running setup.py install for mozinfo 03:59:54 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 03:59:54 INFO - Running setup.py install for mozInstall 03:59:54 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 03:59:54 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 03:59:54 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 03:59:54 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 03:59:54 INFO - Running setup.py install for mozleak 03:59:54 INFO - Running setup.py install for mozlog 03:59:54 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 03:59:54 INFO - Running setup.py install for moznetwork 03:59:54 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 03:59:54 INFO - Running setup.py install for mozprocess 03:59:55 INFO - Running setup.py install for mozprofile 03:59:55 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 03:59:55 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 03:59:55 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 03:59:55 INFO - Running setup.py install for mozrunner 03:59:55 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 03:59:55 INFO - Running setup.py install for mozscreenshot 03:59:55 INFO - Running setup.py install for moztest 03:59:55 INFO - Running setup.py install for mozversion 03:59:55 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 03:59:55 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 03:59:55 INFO - Cleaning up... 03:59:55 INFO - Return code: 0 03:59:55 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:59:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:59:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:59:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:59:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:59:55 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 0x1077a2c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1077a6768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fca5bdd7f40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10772bc60>, '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 0x1074c0cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1074e9be8>, '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.spmqfx40Ql/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.6M32lOAEkw/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/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:59:55 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:59:55 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:59:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.spmqfx40Ql/Render', 03:59:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:59:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:59:55 INFO - 'HOME': '/Users/cltbld', 03:59:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:59:55 INFO - 'LOGNAME': 'cltbld', 03:59:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:59:55 INFO - 'MOZ_NO_REMOTE': '1', 03:59:55 INFO - 'NO_EM_RESTART': '1', 03:59:55 INFO - 'PAGER': '/bin/cat', 03:59:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:59:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:59:55 INFO - 'PWD': '/builds/slave/test', 03:59:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:59:55 INFO - 'SHELL': '/bin/bash', 03:59:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6M32lOAEkw/Listeners', 03:59:55 INFO - 'TMPDIR': '/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/', 03:59:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:59:55 INFO - 'USER': 'cltbld', 03:59:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:59:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:59:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:59:55 INFO - 'XPC_FLAGS': '0x0', 03:59:55 INFO - 'XPC_SERVICE_NAME': '0', 03:59:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:59:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:59:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:59:56 INFO - Running setup.py (path:/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/pip-4FmwW4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:59:56 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:59:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:59:56 INFO - Running setup.py (path:/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/pip-91VavU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:59:56 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:59:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:59:56 INFO - Running setup.py (path:/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/pip-0yghyG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:59:56 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:59:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:59:56 INFO - Running setup.py (path:/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/pip-IpkgEg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:59:56 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:59:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:59:56 INFO - Running setup.py (path:/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/pip-Sy_Jko-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:59:56 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:59:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:59:56 INFO - Running setup.py (path:/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/pip-3nmeki-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:59:56 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:59:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:59:56 INFO - Running setup.py (path:/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/pip-te9QXJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:59:56 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:59:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:59:56 INFO - Running setup.py (path:/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/pip-z1Xxxu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:59:56 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:59:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:59:56 INFO - Running setup.py (path:/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/pip-1rYBxs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:59:57 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:59:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:59:57 INFO - Running setup.py (path:/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/pip-nVWdnv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:59:57 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:59:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:59:57 INFO - Running setup.py (path:/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/pip-2ZxLWV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:59:57 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:59:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:59:57 INFO - Running setup.py (path:/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/pip-tGicKb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:59:57 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:59:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:59:57 INFO - Running setup.py (path:/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/pip-nhDmmk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:59:57 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:59:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:59:57 INFO - Running setup.py (path:/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/pip-xc1NXz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:59:57 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:59:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:59:57 INFO - Running setup.py (path:/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/pip-vZLpf9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:59:57 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:59:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:59:57 INFO - Running setup.py (path:/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/pip-qaKBiH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:59:57 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:59:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:59:57 INFO - Running setup.py (path:/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/pip-cNUpa0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:59:57 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:59:57 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:59:57 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:59:57 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:59:57 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:59:57 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:59:57 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 03:59:57 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:59:57 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:59:57 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:59:57 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:59:59 INFO - Downloading blessings-1.6.tar.gz 03:59:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 03:59:59 INFO - Installing collected packages: blessings 03:59:59 INFO - Running setup.py install for blessings 03:59:59 INFO - Successfully installed blessings 03:59:59 INFO - Cleaning up... 03:59:59 INFO - Return code: 0 03:59:59 INFO - Done creating virtualenv /builds/slave/test/build/venv. 03:59:59 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:59:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:59:59 INFO - Reading from file tmpfile_stdout 03:59:59 INFO - Current package versions: 03:59:59 INFO - blessings == 1.6 03:59:59 INFO - blobuploader == 1.2.4 03:59:59 INFO - docopt == 0.6.1 03:59:59 INFO - functools32 == 3.2.3-2 03:59:59 INFO - jsonschema == 2.5.1 03:59:59 INFO - manifestparser == 1.1 03:59:59 INFO - mozInstall == 1.12 03:59:59 INFO - mozcrash == 1.0 03:59:59 INFO - mozdebug == 0.1 03:59:59 INFO - mozdevice == 0.51 03:59:59 INFO - mozfile == 1.2 03:59:59 INFO - mozhttpd == 0.7 03:59:59 INFO - mozinfo == 0.9 03:59:59 INFO - mozleak == 0.1 03:59:59 INFO - mozlog == 3.4 03:59:59 INFO - moznetwork == 0.27 03:59:59 INFO - mozprocess == 0.25 03:59:59 INFO - mozprofile == 0.28 03:59:59 INFO - mozrunner == 6.13 03:59:59 INFO - mozscreenshot == 0.1 03:59:59 INFO - mozsystemmonitor == 0.3 03:59:59 INFO - moztest == 0.8 03:59:59 INFO - mozversion == 1.4 03:59:59 INFO - psutil == 3.1.1 03:59:59 INFO - requests == 1.2.3 03:59:59 INFO - wsgiref == 0.1.2 03:59:59 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:59:59 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:59:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:59:59 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:59:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:59:59 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 0x1077a2c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1077a6768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fca5bdd7f40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10772bc60>, '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 0x1074c0cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1074e9be8>, '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.spmqfx40Ql/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.6M32lOAEkw/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/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:59:59 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:59:59 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:59:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.spmqfx40Ql/Render', 03:59:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:59:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:59:59 INFO - 'HOME': '/Users/cltbld', 03:59:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:59:59 INFO - 'LOGNAME': 'cltbld', 03:59:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:59:59 INFO - 'MOZ_NO_REMOTE': '1', 03:59:59 INFO - 'NO_EM_RESTART': '1', 03:59:59 INFO - 'PAGER': '/bin/cat', 03:59:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:59:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:59:59 INFO - 'PWD': '/builds/slave/test', 03:59:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:59:59 INFO - 'SHELL': '/bin/bash', 03:59:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6M32lOAEkw/Listeners', 03:59:59 INFO - 'TMPDIR': '/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/', 03:59:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:59:59 INFO - 'USER': 'cltbld', 03:59:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:59:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:59:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:59:59 INFO - 'XPC_FLAGS': '0x0', 03:59:59 INFO - 'XPC_SERVICE_NAME': '0', 03:59:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:00:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:00:00 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)) 04:00:00 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)) 04:00:00 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)) 04:00:00 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)) 04:00:00 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)) 04:00:00 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)) 04:00:00 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)) 04:00:00 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)) 04:00:00 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)) 04:00:00 INFO - Downloading/unpacking marionette-driver>=1.4.0 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 04:00:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:00:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:00:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:00:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:00:01 INFO - Downloading marionette_driver-1.4.0.tar.gz 04:00:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/marionette-driver/setup.py) egg_info for package marionette-driver 04:00:01 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)) 04:00:01 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)) 04:00:01 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)) 04:00:01 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)) 04:00:01 INFO - Installing collected packages: marionette-driver 04:00:01 INFO - Running setup.py install for marionette-driver 04:00:01 INFO - Successfully installed marionette-driver 04:00:01 INFO - Cleaning up... 04:00:01 INFO - Return code: 0 04:00:01 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 04:00:01 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')]} 04:00:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:00:01 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')]} 04:00:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:00:01 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 0x1077a2c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1077a6768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fca5bdd7f40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10772bc60>, '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 0x1074c0cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1074e9be8>, '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.spmqfx40Ql/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.6M32lOAEkw/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/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 04:00:01 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 04:00:01 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 04:00:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.spmqfx40Ql/Render', 04:00:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:00:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:00:01 INFO - 'HOME': '/Users/cltbld', 04:00:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:00:01 INFO - 'LOGNAME': 'cltbld', 04:00:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:00:01 INFO - 'MOZ_NO_REMOTE': '1', 04:00:01 INFO - 'NO_EM_RESTART': '1', 04:00:01 INFO - 'PAGER': '/bin/cat', 04:00:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:00:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:00:01 INFO - 'PWD': '/builds/slave/test', 04:00:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:00:01 INFO - 'SHELL': '/bin/bash', 04:00:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6M32lOAEkw/Listeners', 04:00:01 INFO - 'TMPDIR': '/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/', 04:00:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:00:01 INFO - 'USER': 'cltbld', 04:00:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:00:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:00:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:00:01 INFO - 'XPC_FLAGS': '0x0', 04:00:01 INFO - 'XPC_SERVICE_NAME': '0', 04:00:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:00:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:00:02 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 04:00:02 INFO - Cleaning up... 04:00:02 INFO - Return code: 0 04:00:02 INFO - Running post-action listener: _resource_record_post_action 04:00:02 INFO - Running post-action listener: _start_resource_monitoring 04:00:02 INFO - Starting resource monitoring. 04:00:02 INFO - [mozharness: 2017-05-30 11:00:02.148440Z] Finished create-virtualenv step (success) 04:00:02 INFO - [mozharness: 2017-05-30 11:00:02.149411Z] Running install step. 04:00:02 INFO - Running pre-action listener: _resource_record_pre_action 04:00:02 INFO - Running main action method: install 04:00:02 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:00:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:00:02 INFO - Reading from file tmpfile_stdout 04:00:02 INFO - Detecting whether we're running mozinstall >=1.0... 04:00:02 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 04:00:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 04:00:02 INFO - Reading from file tmpfile_stdout 04:00:02 INFO - Output received: 04:00:02 INFO - Usage: mozinstall [options] installer 04:00:02 INFO - Options: 04:00:02 INFO - -h, --help show this help message and exit 04:00:02 INFO - -d DEST, --destination=DEST 04:00:02 INFO - Directory to install application into. [default: 04:00:02 INFO - "/builds/slave/test"] 04:00:02 INFO - --app=APP Application being installed. [default: firefox] 04:00:02 INFO - mkdir: /builds/slave/test/build/application 04:00:02 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'] 04:00:02 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 04:00:23 INFO - Reading from file tmpfile_stdout 04:00:23 INFO - Output received: 04:00:23 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 04:00:23 INFO - Running post-action listener: _resource_record_post_action 04:00:23 INFO - [mozharness: 2017-05-30 11:00:23.839989Z] Finished install step (success) 04:00:23 INFO - [mozharness: 2017-05-30 11:00:23.840130Z] Running setup-mitmproxy step. 04:00:23 INFO - Running pre-action listener: _resource_record_pre_action 04:00:23 INFO - Running main action method: setup_mitmproxy 04:00:23 INFO - Skipping: mitmproxy is not required 04:00:23 INFO - Running post-action listener: _resource_record_post_action 04:00:23 INFO - [mozharness: 2017-05-30 11:00:23.840802Z] Finished setup-mitmproxy step (success) 04:00:23 INFO - [mozharness: 2017-05-30 11:00:23.840918Z] Running run-tests step. 04:00:23 INFO - Running pre-action listener: _resource_record_pre_action 04:00:23 INFO - Running main action method: run_tests 04:00:23 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 04:00:23 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 04:00:23 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 04:00:23 INFO - 2017-05-30 04:00:23.855 screenresolution[1631:7928] starting screenresolution argv=screenresolution get 04:00:23 INFO - 2017-05-30 04:00:23.858 screenresolution[1631:7928] Display 0: 1600x1200x32@60 04:00:23 INFO - 2017-05-30 04:00:23.864 screenresolution[1632:7930] starting screenresolution argv=screenresolution list 04:00:23 INFO - Available Modes on Display 0 04:00:23 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 04:00:23 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 04:00:23 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 04:00:23 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 04:00:24 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 04:00:24 INFO - Intel Iris: 04:00:24 INFO - Chipset Model: Intel Iris 04:00:24 INFO - Type: GPU 04:00:24 INFO - Bus: Built-In 04:00:24 INFO - VRAM (Dynamic, Max): 1536 MB 04:00:24 INFO - Vendor: Intel (0x8086) 04:00:24 INFO - Device ID: 0x0a2e 04:00:24 INFO - Revision ID: 0x0009 04:00:24 INFO - Displays: 04:00:24 INFO - Display: 04:00:24 INFO - Resolution: 1600 x 1200 @ 60 Hz 04:00:24 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 04:00:24 INFO - Display Serial Number: 1600x1200 60 04:00:24 INFO - Main Display: Yes 04:00:24 INFO - Mirror: Off 04:00:24 INFO - Online: Yes 04:00:24 INFO - Rotation: Supported 04:00:24 INFO - Return code: 0 04:00:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 04:00:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 04:00:24 INFO - Python 2.7.3 04:00:24 INFO - Return code: 0 04:00:24 INFO - grabbing minidump binary from tooltool 04:00:24 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')]} 04:00:24 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 0x10772bc60>, '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 0x1074c0cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1074e9be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 04:00:24 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 04:00:24 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 04:00:24 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 04:00:24 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 04:00:24 INFO - Return code: 0 04:00:24 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 04:00:24 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 04:00:24 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 04:00:24 INFO - ENV: RUST_BACKTRACE is now 1 04:00:24 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 04:00:24 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 04:00:24 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/KPnkVo3nQGeAD9kc138Kew/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0356', '--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 04:00:24 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/KPnkVo3nQGeAD9kc138Kew/artifacts/public/build/target.crashreporter-symbols.zip --title t-yosemite-r7-0356 --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 04:00:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.spmqfx40Ql/Render', 04:00:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:00:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:00:24 INFO - 'HOME': '/Users/cltbld', 04:00:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:00:24 INFO - 'LOGNAME': 'cltbld', 04:00:24 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 04:00:24 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 04:00:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:00:24 INFO - 'MOZ_NO_REMOTE': '1', 04:00:24 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 04:00:24 INFO - 'NO_EM_RESTART': '1', 04:00:24 INFO - 'PAGER': '/bin/cat', 04:00:24 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:00:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:00:24 INFO - 'PWD': '/builds/slave/test', 04:00:24 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 04:00:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:00:24 INFO - 'RUST_BACKTRACE': '1', 04:00:24 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 04:00:24 INFO - 'SHELL': '/bin/bash', 04:00:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6M32lOAEkw/Listeners', 04:00:24 INFO - 'TMPDIR': '/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/', 04:00:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:00:24 INFO - 'USER': 'cltbld', 04:00:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:00:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:00:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:00:24 INFO - 'XPC_FLAGS': '0x0', 04:00:24 INFO - 'XPC_SERVICE_NAME': '0', 04:00:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:00:24 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/KPnkVo3nQGeAD9kc138Kew/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0356', '--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 04:00:24 INFO - mozversion application_buildid: 20170530024235 04:00:24 INFO - mozversion application_changeset: 286f71223256cbb3a769432fd860f563c4886e81 04:00:24 INFO - mozversion application_display_name: Nightly 04:00:24 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 04:00:24 INFO - mozversion application_name: Firefox 04:00:24 INFO - mozversion application_remotingname: firefox 04:00:24 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 04:00:24 INFO - mozversion application_vendor: Mozilla 04:00:24 INFO - mozversion application_version: 55.0a1 04:00:24 INFO - mozversion platform_buildid: 20170530024235 04:00:24 INFO - mozversion platform_changeset: 286f71223256cbb3a769432fd860f563c4886e81 04:00:24 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 04:00:24 INFO - mozversion platform_version: 55.0a1 04:00:24 INFO - using testdate: 1496142024 04:00:24 INFO - actual date: 1496142024 04:00:24 INFO - starting webserver on 'localhost:49227' 04:00:24 INFO - SUITE-START | Running 2 tests 04:00:24 INFO - TEST-START | dromaeo_css 04:00:24 INFO - operating with platform_type : mac_ 04:00:24 INFO - Initialising browser for dromaeo_css test... 04:00:24 INFO - Application command: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49227/getInfo.html -foreground -profile /var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/tmpapwQ3D/profile 04:00:24 INFO - TEST-INFO | started process 1642 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49227/getInfo.html -foreground) 04:00:58 INFO - TEST-INFO | 1642: killed by SIGTERM 04:00:58 INFO - Browser initialized. 04:00:58 INFO - Running cycle 1/1 for dromaeo_css test... 04:00:58 INFO - TEST-INFO | started process 1650 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/tmpapwQ3D/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:01:00 INFO - PID 1650 | 2017-05-30 04:01:00.952 plugin-container[1651:8427] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4943, name = 'com.apple.tsm.portname' 04:01:00 INFO - PID 1650 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:01:00 INFO - PID 1650 | 2017-05-30 04:01:00.952 plugin-container[1651:8427] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9e0b, name = 'com.apple.CFPasteboardClient' 04:01:00 INFO - PID 1650 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:01:00 INFO - PID 1650 | 2017-05-30 04:01:00.952 plugin-container[1651:8427] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 04:01:01 INFO - PID 1650 | 2017-05-30 04:01:01.522 plugin-container[1652:8471] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x454b, name = 'com.apple.tsm.portname' 04:01:01 INFO - PID 1650 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:01:01 INFO - PID 1650 | 2017-05-30 04:01:01.522 plugin-container[1652:8471] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x981b, name = 'com.apple.CFPasteboardClient' 04:01:01 INFO - PID 1650 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:01:01 INFO - PID 1650 | 2017-05-30 04:01:01.523 plugin-container[1652:8471] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 04:01:10 INFO - PID 1650 | RSS: Main: 262033408 04:01:10 INFO - PID 1650 | 04:04:13 INFO - PID 1650 | Cycle 1(1): loaded http://localhost:49227/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49227/tests/dromaeo/cssquery-ext.html) 04:04:14 INFO - PID 1650 | RSS: Main: 267657216 04:04:14 INFO - PID 1650 | 04:07:16 INFO - PID 1650 | Cycle 1(1): loaded http://localhost:49227/tests/dromaeo/cssquery-ext.html (next: http://localhost:49227/tests/dromaeo/cssquery-jquery.html) 04:07:17 INFO - PID 1650 | RSS: Main: 281772032 04:07:17 INFO - PID 1650 | 04:10:18 INFO - PID 1650 | Cycle 1(1): loaded http://localhost:49227/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49227/tests/dromaeo/cssquery-mootools.html) 04:10:19 INFO - PID 1650 | RSS: Main: 282161152 04:10:19 INFO - PID 1650 | 04:13:21 INFO - PID 1650 | Cycle 1(1): loaded http://localhost:49227/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49227/tests/dromaeo/cssquery-prototype.html) 04:13:21 INFO - PID 1650 | RSS: Main: 285884416 04:13:21 INFO - PID 1650 | 04:16:24 INFO - PID 1650 | Cycle 1(1): loaded http://localhost:49227/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49227/tests/dromaeo/cssquery-yui.html) 04:16:25 INFO - PID 1650 | RSS: Main: 287158272 04:16:25 INFO - PID 1650 | 04:19:27 INFO - PID 1650 | Cycle 1(1): loaded http://localhost:49227/tests/dromaeo/cssquery-yui.html (next: http://localhost:49227/tests/dromaeo/cssquery-dojo.html) 04:19:27 INFO - PID 1650 | RSS: Main: 287416320 04:19:27 INFO - PID 1650 | 04:19:27 INFO - PID 1650 | __start_tp_report 04:19:27 INFO - PID 1650 | _x_x_mozilla_page_load 04:19:27 INFO - PID 1650 | _x_x_mozilla_page_load_details 04:19:27 INFO - PID 1650 | |i|pagename|runs| 04:19:27 INFO - PID 1650 | |0;dojo.html;3562.323158599864;3565.4651802229664;3589.3718599245126;3595.6224596417374;3596.172880237545;4801.711897286156;5655.462731040555;6103.877922441548;6157.1687822143995;6225.657588832612;6136.702895113906;6154.353792851742;6157.38426157384;6161.784337548187;6163.660998645073;4314.741115533062;5300.337457817773;5346.973265133688;5391.029614669358;5419.674819510822;5274.789008439658;5425.6202065855405;5428.9185662215095;5430.91853622196;5505.394406615269;4885.584725298354;5420;5423.945760542383;5425.403205647375;5480.232767412565;5292.788288468457;5312.4687531246955;5336.039512887679;5366.060939335601;5401.351837779453;4443.266860967937;5349.090654588729;5359.38367087786;5382.515573598355;5433.429489903543;6089.695515224221;6231.501479881598;6243.1883855099;6254;6271.749130034838;6404.1674582304;6455.548111632188;6477.4170324671;6569.244536878273;6589.901151482732;6717.899231511482;6738.326167383271;6751.324867513209;6763.830904227384;6773.45812335012;5154.149565321717;6286.717097730613;6292.930201865603;6308.747650093991;6312.116303717483;6670.599764014175;6708.161479815023;6859.314068593101;6870.038194652652;6898.551594146365;5499.175123731472;6299.401556852092;6378.681065946684;6476.255230648441;6515.446187074152;5638.407967163389;6522.478201743849;6548.738050478023;6577.835554111186;6732.3267673232285;6467.223933128054;6565.113709649232;6575.342465753386;6594.175728033996;6609.801705948829;6553.901691474632;6558.901616475757;6571.67141642916;6587.63767992765;6593.307702691149;4458;4806.423229212447;5009.847735020966;5189.636725429184;5232;4750.429948406144;4780.641451891122;4796.784144713496;4804.111239420718;4810.5670489656095;6211.596246243979;6227.377262273736;6290.119383286344;6293.685315734195;6307.684615769285;6535.575187612885;6645.60126392418;6656;6772.52592318533;6773.93226067733;6181.505479561714;6194.969025154845;6226.377362263737;6227.346128656516;6234.3453937336835;5733.168690539848;5970.492508136857;5975.43233392827;6007.249093863267;6021.458068773832;6657.06801047844;6834.897476537856;6869.347411995853;6886.8622627547165;6902.06822079033;6578.638174900427;6668.099806526251;6743.2582415935185;6764.492663050192;6794.184697836291;6791.762288319885;6873.725050998104;6890.069840571458;6901.654917254017;6927.515073944977;6597.901031484627;6613.933860661332;6617.404433600999;6628.53600247978;6634.668266586747;6456.063870738811;6618.735250590115;6642.468602511691;6709.530332876652;6750.45996320303;6421.165248517663;6472.255690595642;6477.935220647733;6488.643124628002;6512.6092434454085;6243.875122497434;6246.750129994931;6483.513736469689;6497.090407342881;6507.381798729113;6313.43179113882;6393.424591786761;6396.48828093742;6405.583637063576;6410.967945160244;6316.905246421399;6428.164338635947;6466.0947467355545;6550.901736474052;6559.212894452697;5130.12787826078;6230.968845155745;6275.592086514362;6279.058141278662;6300.873982520416 04:19:27 INFO - PID 1650 | |1;ext.html;669.8693754717832;788.1921030943286;814.4258297899981;1233.3709807997925;1297.7209899871536;37861;38744;38759.80620096899;38831.805840970825;38869;32028.839855800747;38101.428478572845;38440.423393649115;38461.80769096158;38485.03787405316;7912.723054693087;8235.29999950005;8433.704820331259;8439.95780021099;8451.619677114544;8263.173682631748;8383.95808020959;8394.622241999125;8401.3698972577;8407.70573029944;7322.853542929138;8092.2312380324065;8421.326293896474;8430.072692003874;8446.028706698748;8392.748217553482;8427.367947403969;8436.620352084172;8439.451435656663;8455.45039572432;8211.8357632847;8248;8263.95868020662;8336.708215212499;8352.039515455679;36157;36295.4555681665;36335;36455.81772091149;36538.35577399023;3434.1414646338417;3441.8451169697423;3469.1673998240494;3471.75697701161;3487.163080860575;30129.246768830737;30166.24584385386;30215.546766798296;30223.546646800314;30235.848820755757;3079.707427794356;3113.7820352575327;3120.8439578021234;3137.4666306727913;3155.2427417419885;227044;231326;247133.52866471466;280429;281158;45790.542094579294;45984.5401545987;46025.30962035572;46107.61677149691;46288.8427789308;45821.31268030982;46435.6069317921;46437.76781116073;46475.76762116168;46543.5345646546;14347.426102955766;14534.927325363517;14541.41834326615;14550.708985820223;14616.707665846836;11690.006349460391;11785.292882426911;11789.115816313812;11804.350760708072;11843.526258949718;13388.799168012487;13509.59471215865;13593.912487001016;13600;13730.862691373159;5358.223057656618;6057.273127224761;6142.201513803177;6159.538034647419;6201.503879689614;984.389678399397;1008.566316483919;1015.2994433840627;1040.921930855189;1059.6079450603172;23748.287551373487;24013;24016.039358425816;24028.87985560096;24096.27711168668;1012.1902478017556;1105.9889401106047;1122.7586068995206;1143.2911594811103;1145.4673576786834;10274.743131421774;11556.537738490366;12283.508659653693;12285.1400401971;12373;297375;298103;298373;299399;299411;50782;51051.7447412768;51213.97572048612;51324.716882078246;51334.743326283875;1484.6956373943315;1502.6694127292196;1545.3741234799925;1547.489328521585;1548.1872017191065;18024.549386265448;22943;23765.52468950646;23834.04663813428;23847.880760596086;36478.45282320714;36534.4519832208;36579.817100914326;36625.45061824128;36638;250080;250771;250972;251168;252525.73737131196;34254.28728563512;34281.657183427844;34508.13729656779;34534;34701.65298347085;41131.383029253986;41649.58350416457;42866.5501482894;43206.13587728165;43340.78329608332;28711.71288287174;29676.961306354173;29836.552451713676;30035;30680.232994174432;27899.16302510928;29430.411391771617;29975.55036674408;30211.848940755157;30402.543961840147;31957.6804231964;32772.83613581917;35824.82087589546;35935.460968084975;36023;28294.15117546477;29260.707392925797;29591.26021849471;30008.549871751504;30060.09819705412;23691.763082369645;23721.406964825328;23867.641985369886;24036.639450407907;24063.759362406152 04:19:27 INFO - PID 1650 | |2;jquery.html;422.1909119423272;427.2181907109989;437.4339863289423;444.4266895704539;448.5962633629733;20797.68803467947;21125.366239012816;21432;21515.67726484104;22009;21511.462213444673;21820.345389638296;21921.561568768615;21936.89031554844;22082.33752987409;5823.2138661280715;6048.0625503047095;6106.938930610681;6119.755209791625;6127.4791642710425;5817.272840894888;5953.285605727318;5988.401159883999;6038.245219347582;6099.176611157482;5941.7029148542615;5964.2246507953905;5968.641881487125;5972.9701351493395;5981.940180598203;5733.512651424613;5922.230110085683;5950.5834591578605;5955.255593050869;6032.939670603281;5335.493128152819;5355.384130824966;5365.061114304981;5368.946310536884;5371.623986320959;26638.33404164892;27106;27119.593206101923;27146;27402;2000.1199472232317;2152.967705484419;2173.913043478259;2174.152080688517;2182.5743979923923;17763.555911102325;17997;18062.729059064124;18079.36722214716;18392.724109138373;1830.4508647405726;2137.95724085519;2164.0910817456547;2174.4889950861534;2176.6952626632283;147142;147557;147691.2615436916;149377;151769.48230517664;12351.38243087842;12672.936635316948;12682.49270029207;12687.19384184741;12696.746065078647;13401.195928244142;14702.852971470362;15311.770323445156;15774.974626649231;16655;8851.247643950208;8990.865137022949;9040.18638322541;9102.408343457653;9158.633654653739;8534.658613655509;8566.61450234741;8586.356023298278;8696.869546956801;8704.26013788835;7900.644470998819;8019.719309824128;8079.272865442138;8097.797555061053;8124.675012999532;1972;2120.5652841167525;2193.561287742458;2261.943451413695;2275.340151356088;993.8658281131954;1002.9699109026741;1003.8845532763679;1028.8096702109983;1029.7785976015043;20543.178272868918;21460.57078858414;21566.460838479165;21604.35186944394;21884;1020.5305559442739;1022.8977102289712;1059.925805193629;1060.6817954613584;1064.9414282214552;10756.623518176826;11100;11114.499847506882;11218.158638102175;11262.662120136245;542703;547868;548613;569261;570351;129294;129417;130454;130961.34519327342;131217.0317445239;1186.7211205366732;1192.5110704611068;1227.533537255837;1228.8586812516676;1233.9321337326353;17036.97778133316;17516.737248941023;18096;18503.722444163617;18723;33215.83392083024;57659;57710.422895770505;57892.71053644705;58426.415735843795;74976.62511687407;75311;75705;75708.62145689236;76238.61880690561;7223.927760722326;8759.773631691442;8997.505137217517;9161.175494205554;9238.769030774285;12523.185992910432;13431.395587198404;14108.224047677477;14409.06341087826;14541.41834326615;9998.750031249278;10180.440075795903;11702.35637039971;12178.756424871275;12297.815532766837;10080.395176289447;10867;11422.372854243737;11502.367369794742;11699;9547.856782148134;11985.161038727205;12059.095567832623;12315.82999615035;12338.198017128858;8907.866382004146;8966;8995.640174392951;9082.818343633222;9179.449233046038;9871;11143.554257829595;11236;11905.166638335233;12133.270014247077 04:19:27 INFO - PID 1650 | |3;mootools.html;1178.3872386359094;1243.7636848998688;1273.242420759648;1363.570475300281;1412.7810189420636;10322;11928.224665396743;12091.818622720655;12097.879021209796;12225;11255.831162532568;11355.261907975975;11429.42852857358;11433.59982400614;11607.361595112265;2530.708968468631;2559.1938539360044;2562.8334158279745;2564.0513010186255;2571.6014017827265;2485.7762801347876;2502.174282486775;2530.165045534978;2536.048981631888;2537.568613335728;2487.44032592667;2508.9749102509013;2514.786243169324;2517.748225177477;2518.508890766301;2396.3050715292543;2414.541237164933;2426.8058555315533;2430.428849220432;2433.4768024874647;2431.6474111253947;2435.768601982809;2438.207582535665;2532.9240122796346;2540.695116586003;11646.184767066308;11720.062395008465;11730.824037639442;11757.294562326288;11760.588379406765;6474.5791523550815;6629;6687.130673012526;6701.463882889358;6703.597784132968;4007.83968641254;4211.768352740598;4253.149370125956;4266.0827921996915;4268.253055715238;1207.0826172109173;1237.4431505822415;1239.114033466067;1242.3601845049716;1263.1410640764277;14910;15340.079595224212;16247.59381015484;16341.26464309109;16427;4519.457665080211;5516.255305533782;5578.30271216098;5693.544516438675;5698.800541981011;11664.475098620582;11774.999125074293;11814.704632384259;11821.349825759666;11822.940885295518;6446.77436289737;6462.644554549545;6542;6542.967285163639;6546.738130474823;6044.727987240584;6134.723937422827;6184.350643182401;6234.937650623436;6244.594101383395;4335.089631177425;4380.605745482858;4405.801738921757;4450.910981780346;4492.281235002384;1251.1367156661875;1266.8416447944007;1277.2145130744711;1286.1125823181974;1291.7481091187317;714.4034730999673;727.6943683652878;788.491423032141;810.5096416667949;818.2635627934907;6184.639379336539;6681.699323530453;6860.451163906875;6953.61755103464;6994.930050699428;810.5298926622545;898.2006014646969;936.798588303518;959.0265880131672;982.3025651787311;3678.3746763050344;3963.8612648557164;4104.97375656086;4388.758618276025;4400.669949753781;14666.193359365125;15216.695666086616;15315.92342038305;15328.463503777537;15346.155961422006;14376.35306411214;15263.236838158047;15265.694686106215;15273.618159545878;15294.923525382525;8549;8694.608742606599;8712.91287087121;8726.389152759246;8754.037055923844;6093.421124993119;6834.487413443912;7008.369246767816;7037.190723066913;7115.75094871677;11715.589954351557;11777.52889884395;12133.817992730294;13683.384247709077;13702.040857140304;11762.764744704888;13794.448222071405;14114.2942852859;14122.576322710334;14305.070170438888;8035.517868927883;8958;9298.488583127993;9413;9428.669996550088;10832.837507437553;10888.782224355627;10928.071113955402;10936.343819370863;10950.890491095306;8863.246624037029;9126.589303481493;9140.314476414163;9148;9209.539523023688;8976.057513960941;9074.909250907407;9123.133302336268;9137.360384773003;9167.44995300284;6981.616011119438;7833.647485863035;8227.71203007892;8338.791530211793;8400.285975692135;6069.878602427839;6176.166217560752;6199.884020876286;6205.100260462298;6263.812085637438;6055.788047418319;6066.211392518944;6188.288346840171;6240.7503699851495;6342 04:19:27 INFO - PID 1650 | |4;prototype.html;375.67879463059086;392.94498770172174;398.2890540385413;399.2972368631208;406.11669618579583;5488.655279456529;5534.086875665515;6025.578209525335;6236.469900058489;6329.4619957303685;6071.666058366788;6202.782902598398;6206.565540412173;6211.565190436671;6245.281792593842;3754.718396120288;3879.573246942834;4483.654903528936;4507.887302817439;4566.70316429431;3880.3985382265796;4503.3245013248115;4514.841980530682;4532.682712210147;4542.818287268522;4254.6170844623975;4467.910641787163;4490.550944905516;4502.144592527426;4520.9095818083615;4457.904784751162;4474.248337272554;4486.151293928363;4498.527654596286;4522.133575792699;3722.8883133506038;3745.925081498382;3815.7138214634015;3863.9806800965816;3864.845406183749;5709.687576531512;6009.879802403927;6042.456178943916;6066.6663333516635;6090.695465226765;4473.932891006638;4711.8350857719815;5347.82347883465;5356.4107948125675;5360.517553420172;6508.967455162741;6590.703418346186;6594.30759770222;6658.13444252249;6688.866222675568;4145.274576949022;4385.956140438587;4408.889777755549;4420.911581768379;4460.174867649495;5978.521718262528;6145.262568491765;6305.59013664115;6377.139086223393;7097.361237488599;4074.1851629674184;4438.955610443919;4445.2887537993765;4449.243628583149;4452.777361131931;3801.6958643308467;4386.824527018947;4472.508024117345;4538.795754191069;4540.591346778806;5152.234183380433;5298.867528311823;5347.67376191358;5349.331333583302;5402.51377376038;6280.5603607747935;6402.071699603624;6637.3338793693365;6642.63465509392;6651.33486651331;1307.8495972963042;1512.4328376858678;1522.0715363628178;1542.6066353080116;1547.8004546476345;789.735438628066;969.4764826993486;999.7003894936554;1015.8069966706303;1022.3559157730582;5308.601854860901;5976.581639285208;6239.438450539473;6280.748770049238;6321.747130114744;6512.75310018043;6660.433863121592;6871.4502839773695;6907.13660792401;6911.2397636259975;6237.500999919996;6259.217597800275;6290.559660823698;6296.992481202986;6297.338779428185;3472.3055388922317;3618;3632.54593175853;3640.745147839679;3641.1625326175126;7014.247377653033;7082.716691332289;7085.326893945067;7085.787426377217;7103.822404439827;5610.579206559524;6502.707378167994;7104.609246491493;7113.181984071794;7114.430845532345;5583.55331573465;6964.31873728608;7062.470314726416;7067.92932070683;7078;5738.68437235956;6070.332263450929;6856.794296171123;6938.687759050956;6943.583384996917;6413.390727880844;6550.213974323112;6656.83357916056;6664.733990443078;6752.69612867432;6218.595791273553;6801.251862295045;7091.60996145217;7119.110111236096;7131.786046418616;4421.712588681726;4449.265871131227;4449.799759010917;4450.463235955474;4453.36849676333;6637.734490620321;6939.514234003773;7036.894446583407;7037.507374483942;7039.366457018893;6164.6917654118015;6740.9325906740305;6745.831354216184;6752.3922846944015;6760.053592497153;5321.600879934098;6468.967655161694;6718.798436046926;6722.495812813961;6763.086983257197;5762.971185144047;6563.409293163638;6576.539642224999;6605.47156227491;6623.437007854386;5489.231507588861;6437.549371544134;6494.545381823227;6707.530472867048;6755.459563235027;6508.9349106508325;6539.4768418525455;6547.672616369067;6548.443382312556;6561.96719016421 04:19:27 INFO - PID 1650 | |5;yui.html;765.9770206893793;798.2695833312512;852.1563651984537;872.9350192764829;886.6453418632548;13455.461781528751;13871.959603029787;16521.917390413048;16994.5751356216;17004;17129.657406851886;17704.937703737785;17779.37772177974;17820.910895445504;17821.37625183119;857.764114868413;935.5415846235363;952.8904176019777;970.4177493503912;974.512743628186;995.975100622483;1001.1690297053465;1001.7044971733357;1001.9879921279487;1003.0571263012781;678.6233640329615;696.5611664651262;998.4009594243469;1015.6749840051186;1017.1354348803532;1006.7382480555035;1007.1942446043137;1008.7881544875548;1009.2834087797672;1015;740.3854800515613;741.3698356397019;742.2206682982846;743.4127039638679;743.5427212264473;16113.758293625606;16723.581910452336;16923.661526769396;17033.7444938326;17096.658066838718;16111.597210069842;16538.917305413517;16595.253213605418;16601.252943617445;16645.25096370666;13990.650233744136;14504.709905801825;14536.418543258256;14612.634684132876;14623.78064329036;3457.896263112111;3467.393206188907;3489.9825500872334;3491.0748651607346;3497.702695270905;134286;137412.625873741;137860.31069844688;138379;138396.23207535793;12027.579034733742;12052.096092793076;12252.938735306267;12255.387230638433;12296.569620063256;16442.260098295606;20635.17459301641;20776.06507707157;20986.685199722015;21012.684809727867;6708.698108585126;6918.8616227676175;7569.47013709035;7609.2010338913615;7674.040744906887;3690.132818787583;3873.7869417182324;3904.90237744053;3915.7454765440157;3927.56796752357;4152.958470415318;4241.851535196253;4247.086876321606;4252.957470425318;4263.8081286342185;907.668700924158;941.6045260990401;947.8436058050482;949.9905000949902;952.2239374909427;629.9212598425197;650;658.4403257231411;666.3070406776898;687.0724521895501;11200.271982321123;11250.381229032482;12166.435561585256;12295.508179672892;12666.873331266754;630.1146888621403;631.6715308039885;645.6094063091856;648.3937518420292;657.9867004812535;4166.208420400114;5198.8768838487385;5212.921806172911;5333.040052790535;5475.890482190334;100025.49987250018;116485.8351416492;116524.41737791257;118311;118346.81653183358;105733.47133264285;107370;109261;122343;125128.37435812762;83954;98110.5094474523;99516;101098.494507527;101720.49139754254;11301.491432885376;12223;12349.629511114681;12479.87520124787;12501.624951251477;37978.0505487365;40760.59239407568;42516.149677006906;43094.35358469688;43888.780556097016;59628;60115;60462.39537604568;71910.92133618104;76368.61815690887;17172.828271717124;17408.303667853652;17456.82543174552;17460;17483.650326993644;15553.84446155524;16890.40883569118;18240.332623709455;19122.235110595422;19182.61634767269;15633.921830390775;16738.58153546171;17140.885842420204;18247.996360199784;18320.45038648843;16856.747148792532;16874.915625421796;17815.732764008808;18125.36561220351;18193.090345482407;16519.421820236235;16651.833481665028;16661;16767.664646707242;16785.99284042961;11784.941075294568;11851.051915846883;11953.521859125885;12346.938265308616;12527.749445011232;13292.867071329163;14203.786943196066;14236.928815355857;14245.572632821031;14518.346674399889 04:19:27 INFO - PID 1650 | __end_tp_report 04:19:27 INFO - PID 1650 | __start_cc_report 04:19:27 INFO - PID 1650 | _x_x_mozilla_cycle_collect,452 04:19:27 INFO - PID 1650 | __end_cc_report 04:19:27 INFO - PID 1650 | __startTimestamp1496143167415__endTimestamp 04:19:27 INFO - PID 1650 | 04:19:27 INFO - PID 1650 | ------- Summary: start ------- 04:19:27 INFO - PID 1650 | Number of tests: 6 04:19:27 INFO - PID 1650 | 04:19:27 INFO - PID 1650 | [#0] dojo.html Cycles:165 Average:6070.44 Median:6307.68 stddev:759.64 (12.0%) stddev-sans-first:736.05 04:19:27 INFO - PID 1650 | Values: 3562.3 3565.5 3589.4 3595.6 3596.2 4801.7 5655.5 6103.9 6157.2 6225.7 6136.7 6154.4 6157.4 6161.8 6163.7 4314.7 5300.3 5347.0 5391.0 5419.7 5274.8 5425.6 5428.9 5430.9 5505.4 4885.6 5420.0 5423.9 5425.4 5480.2 5292.8 5312.5 5336.0 5366.1 5401.4 4443.3 5349.1 5359.4 5382.5 5433.4 6089.7 6231.5 6243.2 6254.0 6271.7 6404.2 6455.5 6477.4 6569.2 6589.9 6717.9 6738.3 6751.3 6763.8 6773.5 5154.1 6286.7 6292.9 6308.7 6312.1 6670.6 6708.2 6859.3 6870.0 6898.6 5499.2 6299.4 6378.7 6476.3 6515.4 5638.4 6522.5 6548.7 6577.8 6732.3 6467.2 6565.1 6575.3 6594.2 6609.8 6553.9 6558.9 6571.7 6587.6 6593.3 4458.0 4806.4 5009.8 5189.6 5232.0 4750.4 4780.6 4796.8 4804.1 4810.6 6211.6 6227.4 6290.1 6293.7 6307.7 6535.6 6645.6 6656.0 6772.5 6773.9 6181.5 6195.0 6226.4 6227.3 6234.3 5733.2 5970.5 5975.4 6007.2 6021.5 6657.1 6834.9 6869.3 6886.9 6902.1 6578.6 6668.1 6743.3 6764.5 6794.2 6791.8 6873.7 6890.1 6901.7 6927.5 6597.9 6613.9 6617.4 6628.5 6634.7 6456.1 6618.7 6642.5 6709.5 6750.5 6421.2 6472.3 6477.9 6488.6 6512.6 6243.9 6246.8 6483.5 6497.1 6507.4 6313.4 6393.4 6396.5 6405.6 6411.0 6316.9 6428.2 6466.1 6550.9 6559.2 5130.1 6231.0 6275.6 6279.1 6300.9 04:19:27 INFO - PID 1650 | 04:19:27 INFO - PID 1650 | [#1] ext.html Cycles:180 Average:41841.33 Median:17556.46 stddev:70273.59 (400.3%) stddev-sans-first:70402.74 04:19:27 INFO - PID 1650 | Values: 669.9 788.2 814.4 1233.4 1297.7 37861.0 38744.0 38759.8 38831.8 38869.0 32028.8 38101.4 38440.4 38461.8 38485.0 7912.7 8235.3 8433.7 8440.0 8451.6 8263.2 8384.0 8394.6 8401.4 8407.7 7322.9 8092.2 8421.3 8430.1 8446.0 8392.7 8427.4 8436.6 8439.5 8455.5 8211.8 8248.0 8264.0 8336.7 8352.0 36157.0 36295.5 36335.0 36455.8 36538.4 3434.1 3441.8 3469.2 3471.8 3487.2 30129.2 30166.2 30215.5 30223.5 30235.8 3079.7 3113.8 3120.8 3137.5 3155.2 227044.0 231326.0 247133.5 280429.0 281158.0 45790.5 45984.5 46025.3 46107.6 46288.8 45821.3 46435.6 46437.8 46475.8 46543.5 14347.4 14534.9 14541.4 14550.7 14616.7 11690.0 11785.3 11789.1 11804.4 11843.5 13388.8 13509.6 13593.9 13600.0 13730.9 5358.2 6057.3 6142.2 6159.5 6201.5 984.4 1008.6 1015.3 1040.9 1059.6 23748.3 24013.0 24016.0 24028.9 24096.3 1012.2 1106.0 1122.8 1143.3 1145.5 10274.7 11556.5 12283.5 12285.1 12373.0 297375.0 298103.0 298373.0 299399.0 299411.0 50782.0 51051.7 51214.0 51324.7 51334.7 1484.7 1502.7 1545.4 1547.5 1548.2 18024.5 22943.0 23765.5 23834.0 23847.9 36478.5 36534.5 36579.8 36625.5 36638.0 250080.0 250771.0 250972.0 251168.0 252525.7 34254.3 34281.7 34508.1 34534.0 34701.7 41131.4 41649.6 42866.6 43206.1 43340.8 28711.7 29677.0 29836.6 30035.0 30680.2 27899.2 29430.4 29975.6 30211.8 30402.5 31957.7 32772.8 35824.8 35935.5 36023.0 28294.2 29260.7 29591.3 30008.5 30060.1 23691.8 23721.4 23867.6 24036.6 24063.8 04:19:27 INFO - PID 1650 | 04:19:27 INFO - PID 1650 | [#2] jquery.html Cycles:180 Average:35327.88 Median:2179.63 stddev:94002.26 (4312.8%) stddev-sans-first:94229.43 04:19:27 INFO - PID 1650 | Values: 422.2 427.2 437.4 444.4 448.6 20797.7 21125.4 21432.0 21515.7 22009.0 21511.5 21820.3 21921.6 21936.9 22082.3 5823.2 6048.1 6106.9 6119.8 6127.5 5817.3 5953.3 5988.4 6038.2 6099.2 5941.7 5964.2 5968.6 5973.0 5981.9 5733.5 5922.2 5950.6 5955.3 6032.9 5335.5 5355.4 5365.1 5368.9 5371.6 26638.3 27106.0 27119.6 27146.0 27402.0 2000.1 2153.0 2173.9 2174.2 2182.6 17763.6 17997.0 18062.7 18079.4 18392.7 1830.5 2138.0 2164.1 2174.5 2176.7 147142.0 147557.0 147691.3 149377.0 151769.5 12351.4 12672.9 12682.5 12687.2 12696.7 13401.2 14702.9 15311.8 15775.0 16655.0 8851.2 8990.9 9040.2 9102.4 9158.6 8534.7 8566.6 8586.4 8696.9 8704.3 7900.6 8019.7 8079.3 8097.8 8124.7 1972.0 2120.6 2193.6 2261.9 2275.3 993.9 1003.0 1003.9 1028.8 1029.8 20543.2 21460.6 21566.5 21604.4 21884.0 1020.5 1022.9 1059.9 1060.7 1064.9 10756.6 11100.0 11114.5 11218.2 11262.7 542703.0 547868.0 548613.0 569261.0 570351.0 129294.0 129417.0 130454.0 130961.3 131217.0 1186.7 1192.5 1227.5 1228.9 1233.9 17037.0 17516.7 18096.0 18503.7 18723.0 33215.8 57659.0 57710.4 57892.7 58426.4 74976.6 75311.0 75705.0 75708.6 76238.6 7223.9 8759.8 8997.5 9161.2 9238.8 12523.2 13431.4 14108.2 14409.1 14541.4 9998.8 10180.4 11702.4 12178.8 12297.8 10080.4 10867.0 11422.4 11502.4 11699.0 9547.9 11985.2 12059.1 12315.8 12338.2 8907.9 8966.0 8995.6 9082.8 9179.4 9871.0 11143.6 11236.0 11905.2 12133.3 04:19:27 INFO - PID 1650 | 04:19:27 INFO - PID 1650 | [#3] mootools.html Cycles:180 Average:7086.09 Median:3821.12 stddev:4460.57 (116.7%) stddev-sans-first:4450.99 04:19:27 INFO - PID 1650 | Values: 1178.4 1243.8 1273.2 1363.6 1412.8 10322.0 11928.2 12091.8 12097.9 12225.0 11255.8 11355.3 11429.4 11433.6 11607.4 2530.7 2559.2 2562.8 2564.1 2571.6 2485.8 2502.2 2530.2 2536.0 2537.6 2487.4 2509.0 2514.8 2517.7 2518.5 2396.3 2414.5 2426.8 2430.4 2433.5 2431.6 2435.8 2438.2 2532.9 2540.7 11646.2 11720.1 11730.8 11757.3 11760.6 6474.6 6629.0 6687.1 6701.5 6703.6 4007.8 4211.8 4253.1 4266.1 4268.3 1207.1 1237.4 1239.1 1242.4 1263.1 14910.0 15340.1 16247.6 16341.3 16427.0 4519.5 5516.3 5578.3 5693.5 5698.8 11664.5 11775.0 11814.7 11821.3 11822.9 6446.8 6462.6 6542.0 6543.0 6546.7 6044.7 6134.7 6184.4 6234.9 6244.6 4335.1 4380.6 4405.8 4450.9 4492.3 1251.1 1266.8 1277.2 1286.1 1291.7 714.4 727.7 788.5 810.5 818.3 6184.6 6681.7 6860.5 6953.6 6994.9 810.5 898.2 936.8 959.0 982.3 3678.4 3963.9 4105.0 4388.8 4400.7 14666.2 15216.7 15315.9 15328.5 15346.2 14376.4 15263.2 15265.7 15273.6 15294.9 8549.0 8694.6 8712.9 8726.4 8754.0 6093.4 6834.5 7008.4 7037.2 7115.8 11715.6 11777.5 12133.8 13683.4 13702.0 11762.8 13794.4 14114.3 14122.6 14305.1 8035.5 8958.0 9298.5 9413.0 9428.7 10832.8 10888.8 10928.1 10936.3 10950.9 8863.2 9126.6 9140.3 9148.0 9209.5 8976.1 9074.9 9123.1 9137.4 9167.4 6981.6 7833.6 8227.7 8338.8 8400.3 6069.9 6176.2 6199.9 6205.1 6263.8 6055.8 6066.2 6188.3 6240.8 6342.0 04:19:27 INFO - PID 1650 | 04:19:27 INFO - PID 1650 | [#4] prototype.html Cycles:180 Average:5338.92 Median:6117.98 stddev:1705.91 (27.9%) stddev-sans-first:1669.52 04:19:27 INFO - PID 1650 | Values: 375.7 392.9 398.3 399.3 406.1 5488.7 5534.1 6025.6 6236.5 6329.5 6071.7 6202.8 6206.6 6211.6 6245.3 3754.7 3879.6 4483.7 4507.9 4566.7 3880.4 4503.3 4514.8 4532.7 4542.8 4254.6 4467.9 4490.6 4502.1 4520.9 4457.9 4474.2 4486.2 4498.5 4522.1 3722.9 3745.9 3815.7 3864.0 3864.8 5709.7 6009.9 6042.5 6066.7 6090.7 4473.9 4711.8 5347.8 5356.4 5360.5 6509.0 6590.7 6594.3 6658.1 6688.9 4145.3 4386.0 4408.9 4420.9 4460.2 5978.5 6145.3 6305.6 6377.1 7097.4 4074.2 4439.0 4445.3 4449.2 4452.8 3801.7 4386.8 4472.5 4538.8 4540.6 5152.2 5298.9 5347.7 5349.3 5402.5 6280.6 6402.1 6637.3 6642.6 6651.3 1307.8 1512.4 1522.1 1542.6 1547.8 789.7 969.5 999.7 1015.8 1022.4 5308.6 5976.6 6239.4 6280.7 6321.7 6512.8 6660.4 6871.5 6907.1 6911.2 6237.5 6259.2 6290.6 6297.0 6297.3 3472.3 3618.0 3632.5 3640.7 3641.2 7014.2 7082.7 7085.3 7085.8 7103.8 5610.6 6502.7 7104.6 7113.2 7114.4 5583.6 6964.3 7062.5 7067.9 7078.0 5738.7 6070.3 6856.8 6938.7 6943.6 6413.4 6550.2 6656.8 6664.7 6752.7 6218.6 6801.3 7091.6 7119.1 7131.8 4421.7 4449.3 4449.8 4450.5 4453.4 6637.7 6939.5 7036.9 7037.5 7039.4 6164.7 6740.9 6745.8 6752.4 6760.1 5321.6 6469.0 6718.8 6722.5 6763.1 5763.0 6563.4 6576.5 6605.5 6623.4 5489.2 6437.5 6494.5 6707.5 6755.5 6508.9 6539.5 6547.7 6548.4 6562.0 04:19:27 INFO - PID 1650 | 04:19:27 INFO - PID 1650 | [#5] yui.html Cycles:180 Average:23639.80 Median:17471.83 stddev:35251.65 (201.8%) stddev-sans-first:35308.70 04:19:27 INFO - PID 1650 | Values: 766.0 798.3 852.2 872.9 886.6 13455.5 13872.0 16521.9 16994.6 17004.0 17129.7 17704.9 17779.4 17820.9 17821.4 857.8 935.5 952.9 970.4 974.5 996.0 1001.2 1001.7 1002.0 1003.1 678.6 696.6 998.4 1015.7 1017.1 1006.7 1007.2 1008.8 1009.3 1015.0 740.4 741.4 742.2 743.4 743.5 16113.8 16723.6 16923.7 17033.7 17096.7 16111.6 16538.9 16595.3 16601.3 16645.3 13990.7 14504.7 14536.4 14612.6 14623.8 3457.9 3467.4 3490.0 3491.1 3497.7 134286.0 137412.6 137860.3 138379.0 138396.2 12027.6 12052.1 12252.9 12255.4 12296.6 16442.3 20635.2 20776.1 20986.7 21012.7 6708.7 6918.9 7569.5 7609.2 7674.0 3690.1 3873.8 3904.9 3915.7 3927.6 4153.0 4241.9 4247.1 4253.0 4263.8 907.7 941.6 947.8 950.0 952.2 629.9 650.0 658.4 666.3 687.1 11200.3 11250.4 12166.4 12295.5 12666.9 630.1 631.7 645.6 648.4 658.0 4166.2 5198.9 5212.9 5333.0 5475.9 100025.5 116485.8 116524.4 118311.0 118346.8 105733.5 107370.0 109261.0 122343.0 125128.4 83954.0 98110.5 99516.0 101098.5 101720.5 11301.5 12223.0 12349.6 12479.9 12501.6 37978.1 40760.6 42516.1 43094.4 43888.8 59628.0 60115.0 60462.4 71910.9 76368.6 17172.8 17408.3 17456.8 17460.0 17483.7 15553.8 16890.4 18240.3 19122.2 19182.6 15633.9 16738.6 17140.9 18248.0 18320.5 16856.7 16874.9 17815.7 18125.4 18193.1 16519.4 16651.8 16661.0 16767.7 16786.0 11784.9 11851.1 11953.5 12346.9 12527.7 13292.9 14203.8 14236.9 14245.6 14518.3 04:19:27 INFO - PID 1650 | -------- Summary: end -------- 04:19:27 INFO - PID 1650 | 04:19:27 INFO - TEST-INFO | 1650: exit 0 04:19:27 INFO - TEST-OK | dromaeo_css | took 1143487ms 04:19:27 INFO - TEST-START | kraken 04:19:27 INFO - operating with platform_type : mac_ 04:19:27 INFO - Initialising browser for kraken test... 04:19:28 INFO - Application command: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49227/getInfo.html -foreground -profile /var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/tmp3bnlTA/profile 04:19:28 INFO - TEST-INFO | started process 1658 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49227/getInfo.html -foreground) 04:20:01 INFO - TEST-INFO | 1658: killed by SIGTERM 04:20:01 INFO - Browser initialized. 04:20:01 INFO - Running cycle 1/1 for kraken test... 04:20:01 INFO - TEST-INFO | started process 1662 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/tmp3bnlTA/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:20:04 INFO - PID 1662 | 2017-05-30 04:20:04.072 plugin-container[1663:10922] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4847, name = 'com.apple.tsm.portname' 04:20:04 INFO - PID 1662 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:20:04 INFO - PID 1662 | 2017-05-30 04:20:04.073 plugin-container[1663:10922] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9a23, name = 'com.apple.CFPasteboardClient' 04:20:04 INFO - PID 1662 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:20:04 INFO - PID 1662 | 2017-05-30 04:20:04.073 plugin-container[1663:10922] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 04:20:04 INFO - PID 1662 | 2017-05-30 04:20:04.830 plugin-container[1664:10962] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4647, name = 'com.apple.tsm.portname' 04:20:04 INFO - PID 1662 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:20:04 INFO - PID 1662 | 2017-05-30 04:20:04.831 plugin-container[1664:10962] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9c0b, name = 'com.apple.CFPasteboardClient' 04:20:04 INFO - PID 1662 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:20:04 INFO - PID 1662 | 2017-05-30 04:20:04.831 plugin-container[1664:10962] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 04:20:14 INFO - PID 1662 | RSS: Main: 262418432 04:20:14 INFO - PID 1662 | 04:20:16 INFO - PID 1662 | Cycle 1(1): loaded http://localhost:49227/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49227/tests/kraken/driver.html?testName=audio-beat-detection) 04:20:17 INFO - PID 1662 | RSS: Main: 271884288 04:20:17 INFO - PID 1662 | 04:20:20 INFO - PID 1662 | Cycle 1(1): loaded http://localhost:49227/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49227/tests/kraken/driver.html?testName=audio-dft) 04:20:20 INFO - PID 1662 | RSS: Main: 279846912 04:20:20 INFO - PID 1662 | 04:20:24 INFO - PID 1662 | Cycle 1(1): loaded http://localhost:49227/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49227/tests/kraken/driver.html?testName=audio-fft) 04:20:25 INFO - PID 1662 | RSS: Main: 282075136 04:20:25 INFO - PID 1662 | 04:20:27 INFO - PID 1662 | Cycle 1(1): loaded http://localhost:49227/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49227/tests/kraken/driver.html?testName=audio-oscillator) 04:20:27 INFO - PID 1662 | RSS: Main: 282869760 04:20:27 INFO - PID 1662 | 04:20:29 INFO - PID 1662 | Cycle 1(1): loaded http://localhost:49227/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49227/tests/kraken/driver.html?testName=imaging-gaussian-blur) 04:20:30 INFO - PID 1662 | RSS: Main: 284717056 04:20:30 INFO - PID 1662 | 04:20:33 INFO - PID 1662 | Cycle 1(1): loaded http://localhost:49227/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49227/tests/kraken/driver.html?testName=imaging-darkroom) 04:20:33 INFO - PID 1662 | RSS: Main: 288907264 04:20:33 INFO - PID 1662 | 04:20:36 INFO - PID 1662 | Cycle 1(1): loaded http://localhost:49227/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49227/tests/kraken/driver.html?testName=imaging-desaturate) 04:20:37 INFO - PID 1662 | RSS: Main: 291790848 04:20:37 INFO - PID 1662 | 04:20:40 INFO - PID 1662 | Cycle 1(1): loaded http://localhost:49227/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49227/tests/kraken/driver.html?testName=json-parse-financial) 04:20:41 INFO - PID 1662 | RSS: Main: 290512896 04:20:41 INFO - PID 1662 | 04:20:42 INFO - PID 1662 | Cycle 1(1): loaded http://localhost:49227/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49227/tests/kraken/driver.html?testName=json-stringify-tinderbox) 04:20:43 INFO - PID 1662 | RSS: Main: 290570240 04:20:43 INFO - PID 1662 | 04:20:45 INFO - PID 1662 | Cycle 1(1): loaded http://localhost:49227/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49227/tests/kraken/driver.html?testName=stanford-crypto-aes) 04:20:45 INFO - PID 1662 | RSS: Main: 290500608 04:20:45 INFO - PID 1662 | 04:20:48 INFO - PID 1662 | Cycle 1(1): loaded http://localhost:49227/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49227/tests/kraken/driver.html?testName=stanford-crypto-ccm) 04:20:48 INFO - PID 1662 | RSS: Main: 290353152 04:20:48 INFO - PID 1662 | 04:20:51 INFO - PID 1662 | Cycle 1(1): loaded http://localhost:49227/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49227/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 04:20:52 INFO - PID 1662 | RSS: Main: 290131968 04:20:52 INFO - PID 1662 | 04:20:55 INFO - PID 1662 | Cycle 1(1): loaded http://localhost:49227/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49227/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 04:20:56 INFO - PID 1662 | RSS: Main: 290148352 04:20:56 INFO - PID 1662 | 04:20:57 INFO - PID 1662 | Cycle 1(1): loaded http://localhost:49227/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49227/tests/kraken/driver.html?testName=ai-astar) 04:20:58 INFO - PID 1662 | RSS: Main: 291979264 04:20:58 INFO - PID 1662 | 04:20:58 INFO - PID 1662 | __start_tp_report 04:20:58 INFO - PID 1662 | _x_x_mozilla_page_load 04:20:58 INFO - PID 1662 | _x_x_mozilla_page_load_details 04:20:58 INFO - PID 1662 | |i|pagename|runs| 04:20:58 INFO - PID 1662 | |0;ai-astar;97;123;112;113;115;113;113;114;114;114 04:20:58 INFO - PID 1662 | |1;audio-beat-detection;136;109;107;173;105;98;132;111;169;143 04:20:58 INFO - PID 1662 | |2;audio-dft;188;230;236;239;247;247;276;204;193;198 04:20:58 INFO - PID 1662 | |3;audio-fft;79;78;87;109;74;56;60;62;76;59 04:20:58 INFO - PID 1662 | |4;audio-oscillator;80;69;83;71;71;66;85;71;85;116 04:20:58 INFO - PID 1662 | |5;imaging-gaussian-blur;90;98;112;90;90;96;89;108;90;90 04:20:58 INFO - PID 1662 | |6;imaging-darkroom;124;97;98;97;106;120;99;96;97;98 04:20:58 INFO - PID 1662 | |7;imaging-desaturate;89;95;93;91;92;88;88;130;133;91 04:20:58 INFO - PID 1662 | |8;json-parse-financial;52;52;52;50;61;51;51;50;53;54 04:20:58 INFO - PID 1662 | |9;json-stringify-tinderbox;56;54;61;60;65;63;61;65;80;57 04:20:58 INFO - PID 1662 | |10;stanford-crypto-aes;87;86;86;97;87;87;89;92;127;113 04:20:58 INFO - PID 1662 | |11;stanford-crypto-ccm;149;136;146;140;137;141;131;162;151;169 04:20:58 INFO - PID 1662 | |12;stanford-crypto-pbkdf2;184;188;187;186;230;194;183;185;184;188 04:20:58 INFO - PID 1662 | |13;stanford-crypto-sha256-iterative;71;81;68;58;77;83;67;68;68;69 04:20:58 INFO - PID 1662 | __end_tp_report 04:20:58 INFO - PID 1662 | __start_cc_report 04:20:58 INFO - PID 1662 | _x_x_mozilla_cycle_collect,1186 04:20:58 INFO - PID 1662 | __end_cc_report 04:20:58 INFO - PID 1662 | __startTimestamp1496143258018__endTimestamp 04:20:58 INFO - PID 1662 | 04:20:58 INFO - PID 1662 | ------- Summary: start ------- 04:20:58 INFO - PID 1662 | Number of tests: 14 04:20:58 INFO - PID 1662 | 04:20:58 INFO - PID 1662 | [#0] ai-astar Cycles:10 Average:112.80 Median:114.00 stddev:6.36 (5.6%) stddev-sans-first:3.28 04:20:58 INFO - PID 1662 | Values: 97.0 123.0 112.0 113.0 115.0 113.0 113.0 114.0 114.0 114.0 04:20:58 INFO - PID 1662 | 04:20:58 INFO - PID 1662 | [#1] audio-beat-detection Cycles:10 Average:128.30 Median:139.50 stddev:26.94 (19.3%) stddev-sans-first:28.43 04:20:58 INFO - PID 1662 | Values: 136.0 109.0 107.0 173.0 105.0 98.0 132.0 111.0 169.0 143.0 04:20:58 INFO - PID 1662 | 04:20:58 INFO - PID 1662 | [#2] audio-dft Cycles:10 Average:225.80 Median:237.50 stddev:28.81 (12.1%) stddev-sans-first:27.11 04:20:58 INFO - PID 1662 | Values: 188.0 230.0 236.0 239.0 247.0 247.0 276.0 204.0 193.0 198.0 04:20:58 INFO - PID 1662 | 04:20:58 INFO - PID 1662 | [#3] audio-fft Cycles:10 Average:74.00 Median:75.00 stddev:16.08 (21.4%) stddev-sans-first:16.96 04:20:58 INFO - PID 1662 | Values: 79.0 78.0 87.0 109.0 74.0 56.0 60.0 62.0 76.0 59.0 04:20:58 INFO - PID 1662 | 04:20:58 INFO - PID 1662 | [#4] audio-oscillator Cycles:10 Average:79.70 Median:75.50 stddev:14.58 (19.3%) stddev-sans-first:15.47 04:20:58 INFO - PID 1662 | Values: 80.0 69.0 83.0 71.0 71.0 66.0 85.0 71.0 85.0 116.0 04:20:58 INFO - PID 1662 | 04:20:58 INFO - PID 1662 | [#5] imaging-gaussian-blur Cycles:10 Average:95.30 Median:90.00 stddev:8.35 (9.3%) stddev-sans-first:8.64 04:20:58 INFO - PID 1662 | Values: 90.0 98.0 112.0 90.0 90.0 96.0 89.0 108.0 90.0 90.0 04:20:58 INFO - PID 1662 | 04:20:58 INFO - PID 1662 | [#6] imaging-darkroom Cycles:10 Average:103.20 Median:97.00 stddev:10.34 (10.7%) stddev-sans-first:7.75 04:20:58 INFO - PID 1662 | Values: 124.0 97.0 98.0 97.0 106.0 120.0 99.0 96.0 97.0 98.0 04:20:58 INFO - PID 1662 | 04:20:58 INFO - PID 1662 | [#7] imaging-desaturate Cycles:10 Average:99.00 Median:91.00 stddev:17.28 (19.0%) stddev-sans-first:17.95 04:20:58 INFO - PID 1662 | Values: 89.0 95.0 93.0 91.0 92.0 88.0 88.0 130.0 133.0 91.0 04:20:58 INFO - PID 1662 | 04:20:58 INFO - PID 1662 | [#8] json-parse-financial Cycles:10 Average:52.60 Median:52.00 stddev:3.20 (6.2%) stddev-sans-first:3.39 04:20:58 INFO - PID 1662 | Values: 52.0 52.0 52.0 50.0 61.0 51.0 51.0 50.0 53.0 54.0 04:20:58 INFO - PID 1662 | 04:20:58 INFO - PID 1662 | [#9] json-stringify-tinderbox Cycles:10 Average:62.20 Median:62.00 stddev:7.25 (11.7%) stddev-sans-first:7.34 04:20:58 INFO - PID 1662 | Values: 56.0 54.0 61.0 60.0 65.0 63.0 61.0 65.0 80.0 57.0 04:20:58 INFO - PID 1662 | 04:20:58 INFO - PID 1662 | [#10] stanford-crypto-aes Cycles:10 Average:95.10 Median:87.00 stddev:13.95 (16.0%) stddev-sans-first:14.48 04:20:58 INFO - PID 1662 | Values: 87.0 86.0 86.0 97.0 87.0 87.0 89.0 92.0 127.0 113.0 04:20:58 INFO - PID 1662 | 04:20:58 INFO - PID 1662 | [#11] stanford-crypto-ccm Cycles:10 Average:146.20 Median:147.50 stddev:11.95 (8.1%) stddev-sans-first:12.63 04:20:58 INFO - PID 1662 | Values: 149.0 136.0 146.0 140.0 137.0 141.0 131.0 162.0 151.0 169.0 04:20:58 INFO - PID 1662 | 04:20:58 INFO - PID 1662 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:190.90 Median:187.50 stddev:14.09 (7.5%) stddev-sans-first:14.72 04:20:58 INFO - PID 1662 | Values: 184.0 188.0 187.0 186.0 230.0 194.0 183.0 185.0 184.0 188.0 04:20:58 INFO - PID 1662 | 04:20:58 INFO - PID 1662 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:71.00 Median:70.00 stddev:7.42 (10.6%) stddev-sans-first:7.87 04:20:58 INFO - PID 1662 | Values: 71.0 81.0 68.0 58.0 77.0 83.0 67.0 68.0 68.0 69.0 04:20:58 INFO - PID 1662 | -------- Summary: end -------- 04:20:58 INFO - PID 1662 | 04:20:58 INFO - TEST-INFO | 1662: exit 0 04:20:58 INFO - TEST-OK | kraken | took 90829ms 04:20:58 INFO - SUITE-END | took 1234s 04:20:59 INFO - Completed test suite (00:20:34) 04:20:59 INFO - javascript benchmark 04:20:59 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7829.400116842458, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3562.323158599864, 3565.4651802229664, 3589.3718599245126, 3595.6224596417374, 3596.172880237545, 4801.711897286156, 5655.462731040555, 6103.877922441548, 6157.1687822143995, 6225.657588832612, 6136.702895113906, 6154.353792851742, 6157.38426157384, 6161.784337548187, 6163.660998645073, 4314.741115533062, 5300.337457817773, 5346.973265133688, 5391.029614669358, 5419.674819510822, 5274.789008439658, 5425.6202065855405, 5428.9185662215095, 5430.91853622196, 5505.394406615269, 4885.584725298354, 5420.0, 5423.945760542383, 5425.403205647375, 5480.232767412565, 5292.788288468457, 5312.4687531246955, 5336.039512887679, 5366.060939335601, 5401.351837779453, 4443.266860967937, 5349.090654588729, 5359.38367087786, 5382.515573598355, 5433.429489903543, 6089.695515224221, 6231.501479881598, 6243.1883855099, 6254.0, 6271.749130034838, 6404.1674582304, 6455.548111632188, 6477.4170324671, 6569.244536878273, 6589.901151482732, 6717.899231511482, 6738.326167383271, 6751.324867513209, 6763.830904227384, 6773.45812335012, 5154.149565321717, 6286.717097730613, 6292.930201865603, 6308.747650093991, 6312.116303717483, 6670.599764014175, 6708.161479815023, 6859.314068593101, 6870.038194652652, 6898.551594146365, 5499.175123731472, 6299.401556852092, 6378.681065946684, 6476.255230648441, 6515.446187074152, 5638.407967163389, 6522.478201743849, 6548.738050478023, 6577.835554111186, 6732.3267673232285, 6467.223933128054, 6565.113709649232, 6575.342465753386, 6594.175728033996, 6609.801705948829, 6553.901691474632, 6558.901616475757, 6571.67141642916, 6587.63767992765, 6593.307702691149, 4458.0, 4806.423229212447, 5009.847735020966, 5189.636725429184, 5232.0, 4750.429948406144, 4780.641451891122, 4796.784144713496, 4804.111239420718, 4810.5670489656095, 6211.596246243979, 6227.377262273736, 6290.119383286344, 6293.685315734195, 6307.684615769285, 6535.575187612885, 6645.60126392418, 6656.0, 6772.52592318533, 6773.93226067733, 6181.505479561714, 6194.969025154845, 6226.377362263737, 6227.346128656516, 6234.3453937336835, 5733.168690539848, 5970.492508136857, 5975.43233392827, 6007.249093863267, 6021.458068773832, 6657.06801047844, 6834.897476537856, 6869.347411995853, 6886.8622627547165, 6902.06822079033, 6578.638174900427, 6668.099806526251, 6743.2582415935185, 6764.492663050192, 6794.184697836291, 6791.762288319885, 6873.725050998104, 6890.069840571458, 6901.654917254017, 6927.515073944977, 6597.901031484627, 6613.933860661332, 6617.404433600999, 6628.53600247978, 6634.668266586747, 6456.063870738811, 6618.735250590115, 6642.468602511691, 6709.530332876652, 6750.45996320303, 6421.165248517663, 6472.255690595642, 6477.935220647733, 6488.643124628002, 6512.6092434454085, 6243.875122497434, 6246.750129994931, 6483.513736469689, 6497.090407342881, 6507.381798729113, 6313.43179113882, 6393.424591786761, 6396.48828093742, 6405.583637063576, 6410.967945160244, 6316.905246421399, 6428.164338635947, 6466.0947467355545, 6550.901736474052, 6559.212894452697, 5130.12787826078, 6230.968845155745, 6275.592086514362, 6279.058141278662, 6300.873982520416], "value": 6019.953327254984, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [669.8693754717832, 788.1921030943286, 814.4258297899981, 1233.3709807997925, 1297.7209899871536, 37861.0, 38744.0, 38759.80620096899, 38831.805840970825, 38869.0, 32028.839855800747, 38101.428478572845, 38440.423393649115, 38461.80769096158, 38485.03787405316, 7912.723054693087, 8235.29999950005, 8433.704820331259, 8439.95780021099, 8451.619677114544, 8263.173682631748, 8383.95808020959, 8394.622241999125, 8401.3698972577, 8407.70573029944, 7322.853542929138, 8092.2312380324065, 8421.326293896474, 8430.072692003874, 8446.028706698748, 8392.748217553482, 8427.367947403969, 8436.620352084172, 8439.451435656663, 8455.45039572432, 8211.8357632847, 8248.0, 8263.95868020662, 8336.708215212499, 8352.039515455679, 36157.0, 36295.4555681665, 36335.0, 36455.81772091149, 36538.35577399023, 3434.1414646338417, 3441.8451169697423, 3469.1673998240494, 3471.75697701161, 3487.163080860575, 30129.246768830737, 30166.24584385386, 30215.546766798296, 30223.546646800314, 30235.848820755757, 3079.707427794356, 3113.7820352575327, 3120.8439578021234, 3137.4666306727913, 3155.2427417419885, 227044.0, 231326.0, 247133.52866471466, 280429.0, 281158.0, 45790.542094579294, 45984.5401545987, 46025.30962035572, 46107.61677149691, 46288.8427789308, 45821.31268030982, 46435.6069317921, 46437.76781116073, 46475.76762116168, 46543.5345646546, 14347.426102955766, 14534.927325363517, 14541.41834326615, 14550.708985820223, 14616.707665846836, 11690.006349460391, 11785.292882426911, 11789.115816313812, 11804.350760708072, 11843.526258949718, 13388.799168012487, 13509.59471215865, 13593.912487001016, 13600.0, 13730.862691373159, 5358.223057656618, 6057.273127224761, 6142.201513803177, 6159.538034647419, 6201.503879689614, 984.389678399397, 1008.566316483919, 1015.2994433840627, 1040.921930855189, 1059.6079450603172, 23748.287551373487, 24013.0, 24016.039358425816, 24028.87985560096, 24096.27711168668, 1012.1902478017556, 1105.9889401106047, 1122.7586068995206, 1143.2911594811103, 1145.4673576786834, 10274.743131421774, 11556.537738490366, 12283.508659653693, 12285.1400401971, 12373.0, 297375.0, 298103.0, 298373.0, 299399.0, 299411.0, 50782.0, 51051.7447412768, 51213.97572048612, 51324.716882078246, 51334.743326283875, 1484.6956373943315, 1502.6694127292196, 1545.3741234799925, 1547.489328521585, 1548.1872017191065, 18024.549386265448, 22943.0, 23765.52468950646, 23834.04663813428, 23847.880760596086, 36478.45282320714, 36534.4519832208, 36579.817100914326, 36625.45061824128, 36638.0, 250080.0, 250771.0, 250972.0, 251168.0, 252525.73737131196, 34254.28728563512, 34281.657183427844, 34508.13729656779, 34534.0, 34701.65298347085, 41131.383029253986, 41649.58350416457, 42866.5501482894, 43206.13587728165, 43340.78329608332, 28711.71288287174, 29676.961306354173, 29836.552451713676, 30035.0, 30680.232994174432, 27899.16302510928, 29430.411391771617, 29975.55036674408, 30211.848940755157, 30402.543961840147, 31957.6804231964, 32772.83613581917, 35824.82087589546, 35935.460968084975, 36023.0, 28294.15117546477, 29260.707392925797, 29591.26021849471, 30008.549871751504, 30060.09819705412, 23691.763082369645, 23721.406964825328, 23867.641985369886, 24036.639450407907, 24063.759362406152], "value": 17076.43649293823, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [422.1909119423272, 427.2181907109989, 437.4339863289423, 444.4266895704539, 448.5962633629733, 20797.68803467947, 21125.366239012816, 21432.0, 21515.67726484104, 22009.0, 21511.462213444673, 21820.345389638296, 21921.561568768615, 21936.89031554844, 22082.33752987409, 5823.2138661280715, 6048.0625503047095, 6106.938930610681, 6119.755209791625, 6127.4791642710425, 5817.272840894888, 5953.285605727318, 5988.401159883999, 6038.245219347582, 6099.176611157482, 5941.7029148542615, 5964.2246507953905, 5968.641881487125, 5972.9701351493395, 5981.940180598203, 5733.512651424613, 5922.230110085683, 5950.5834591578605, 5955.255593050869, 6032.939670603281, 5335.493128152819, 5355.384130824966, 5365.061114304981, 5368.946310536884, 5371.623986320959, 26638.33404164892, 27106.0, 27119.593206101923, 27146.0, 27402.0, 2000.1199472232317, 2152.967705484419, 2173.913043478259, 2174.152080688517, 2182.5743979923923, 17763.555911102325, 17997.0, 18062.729059064124, 18079.36722214716, 18392.724109138373, 1830.4508647405726, 2137.95724085519, 2164.0910817456547, 2174.4889950861534, 2176.6952626632283, 147142.0, 147557.0, 147691.2615436916, 149377.0, 151769.48230517664, 12351.38243087842, 12672.936635316948, 12682.49270029207, 12687.19384184741, 12696.746065078647, 13401.195928244142, 14702.852971470362, 15311.770323445156, 15774.974626649231, 16655.0, 8851.247643950208, 8990.865137022949, 9040.18638322541, 9102.408343457653, 9158.633654653739, 8534.658613655509, 8566.61450234741, 8586.356023298278, 8696.869546956801, 8704.26013788835, 7900.644470998819, 8019.719309824128, 8079.272865442138, 8097.797555061053, 8124.675012999532, 1972.0, 2120.5652841167525, 2193.561287742458, 2261.943451413695, 2275.340151356088, 993.8658281131954, 1002.9699109026741, 1003.8845532763679, 1028.8096702109983, 1029.7785976015043, 20543.178272868918, 21460.57078858414, 21566.460838479165, 21604.35186944394, 21884.0, 1020.5305559442739, 1022.8977102289712, 1059.925805193629, 1060.6817954613584, 1064.9414282214552, 10756.623518176826, 11100.0, 11114.499847506882, 11218.158638102175, 11262.662120136245, 542703.0, 547868.0, 548613.0, 569261.0, 570351.0, 129294.0, 129417.0, 130454.0, 130961.34519327342, 131217.0317445239, 1186.7211205366732, 1192.5110704611068, 1227.533537255837, 1228.8586812516676, 1233.9321337326353, 17036.97778133316, 17516.737248941023, 18096.0, 18503.722444163617, 18723.0, 33215.83392083024, 57659.0, 57710.422895770505, 57892.71053644705, 58426.415735843795, 74976.62511687407, 75311.0, 75705.0, 75708.62145689236, 76238.61880690561, 7223.927760722326, 8759.773631691442, 8997.505137217517, 9161.175494205554, 9238.769030774285, 12523.185992910432, 13431.395587198404, 14108.224047677477, 14409.06341087826, 14541.41834326615, 9998.750031249278, 10180.440075795903, 11702.35637039971, 12178.756424871275, 12297.815532766837, 10080.395176289447, 10867.0, 11422.372854243737, 11502.367369794742, 11699.0, 9547.856782148134, 11985.161038727205, 12059.095567832623, 12315.82999615035, 12338.198017128858, 8907.866382004146, 8966.0, 8995.640174392951, 9082.818343633222, 9179.449233046038, 9871.0, 11143.554257829595, 11236.0, 11905.166638335233, 12133.270014247077], "value": 10340.81990895541, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1178.3872386359094, 1243.7636848998688, 1273.242420759648, 1363.570475300281, 1412.7810189420636, 10322.0, 11928.224665396743, 12091.818622720655, 12097.879021209796, 12225.0, 11255.831162532568, 11355.261907975975, 11429.42852857358, 11433.59982400614, 11607.361595112265, 2530.708968468631, 2559.1938539360044, 2562.8334158279745, 2564.0513010186255, 2571.6014017827265, 2485.7762801347876, 2502.174282486775, 2530.165045534978, 2536.048981631888, 2537.568613335728, 2487.44032592667, 2508.9749102509013, 2514.786243169324, 2517.748225177477, 2518.508890766301, 2396.3050715292543, 2414.541237164933, 2426.8058555315533, 2430.428849220432, 2433.4768024874647, 2431.6474111253947, 2435.768601982809, 2438.207582535665, 2532.9240122796346, 2540.695116586003, 11646.184767066308, 11720.062395008465, 11730.824037639442, 11757.294562326288, 11760.588379406765, 6474.5791523550815, 6629.0, 6687.130673012526, 6701.463882889358, 6703.597784132968, 4007.83968641254, 4211.768352740598, 4253.149370125956, 4266.0827921996915, 4268.253055715238, 1207.0826172109173, 1237.4431505822415, 1239.114033466067, 1242.3601845049716, 1263.1410640764277, 14910.0, 15340.079595224212, 16247.59381015484, 16341.26464309109, 16427.0, 4519.457665080211, 5516.255305533782, 5578.30271216098, 5693.544516438675, 5698.800541981011, 11664.475098620582, 11774.999125074293, 11814.704632384259, 11821.349825759666, 11822.940885295518, 6446.77436289737, 6462.644554549545, 6542.0, 6542.967285163639, 6546.738130474823, 6044.727987240584, 6134.723937422827, 6184.350643182401, 6234.937650623436, 6244.594101383395, 4335.089631177425, 4380.605745482858, 4405.801738921757, 4450.910981780346, 4492.281235002384, 1251.1367156661875, 1266.8416447944007, 1277.2145130744711, 1286.1125823181974, 1291.7481091187317, 714.4034730999673, 727.6943683652878, 788.491423032141, 810.5096416667949, 818.2635627934907, 6184.639379336539, 6681.699323530453, 6860.451163906875, 6953.61755103464, 6994.930050699428, 810.5298926622545, 898.2006014646969, 936.798588303518, 959.0265880131672, 982.3025651787311, 3678.3746763050344, 3963.8612648557164, 4104.97375656086, 4388.758618276025, 4400.669949753781, 14666.193359365125, 15216.695666086616, 15315.92342038305, 15328.463503777537, 15346.155961422006, 14376.35306411214, 15263.236838158047, 15265.694686106215, 15273.618159545878, 15294.923525382525, 8549.0, 8694.608742606599, 8712.91287087121, 8726.389152759246, 8754.037055923844, 6093.421124993119, 6834.487413443912, 7008.369246767816, 7037.190723066913, 7115.75094871677, 11715.589954351557, 11777.52889884395, 12133.817992730294, 13683.384247709077, 13702.040857140304, 11762.764744704888, 13794.448222071405, 14114.2942852859, 14122.576322710334, 14305.070170438888, 8035.517868927883, 8958.0, 9298.488583127993, 9413.0, 9428.669996550088, 10832.837507437553, 10888.782224355627, 10928.071113955402, 10936.343819370863, 10950.890491095306, 8863.246624037029, 9126.589303481493, 9140.314476414163, 9148.0, 9209.539523023688, 8976.057513960941, 9074.909250907407, 9123.133302336268, 9137.360384773003, 9167.44995300284, 6981.616011119438, 7833.647485863035, 8227.71203007892, 8338.791530211793, 8400.285975692135, 6069.878602427839, 6176.166217560752, 6199.884020876286, 6205.100260462298, 6263.812085637438, 6055.788047418319, 6066.211392518944, 6188.288346840171, 6240.7503699851495, 6342.0], "value": 5358.043613031789, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [375.67879463059086, 392.94498770172174, 398.2890540385413, 399.2972368631208, 406.11669618579583, 5488.655279456529, 5534.086875665515, 6025.578209525335, 6236.469900058489, 6329.4619957303685, 6071.666058366788, 6202.782902598398, 6206.565540412173, 6211.565190436671, 6245.281792593842, 3754.718396120288, 3879.573246942834, 4483.654903528936, 4507.887302817439, 4566.70316429431, 3880.3985382265796, 4503.3245013248115, 4514.841980530682, 4532.682712210147, 4542.818287268522, 4254.6170844623975, 4467.910641787163, 4490.550944905516, 4502.144592527426, 4520.9095818083615, 4457.904784751162, 4474.248337272554, 4486.151293928363, 4498.527654596286, 4522.133575792699, 3722.8883133506038, 3745.925081498382, 3815.7138214634015, 3863.9806800965816, 3864.845406183749, 5709.687576531512, 6009.879802403927, 6042.456178943916, 6066.6663333516635, 6090.695465226765, 4473.932891006638, 4711.8350857719815, 5347.82347883465, 5356.4107948125675, 5360.517553420172, 6508.967455162741, 6590.703418346186, 6594.30759770222, 6658.13444252249, 6688.866222675568, 4145.274576949022, 4385.956140438587, 4408.889777755549, 4420.911581768379, 4460.174867649495, 5978.521718262528, 6145.262568491765, 6305.59013664115, 6377.139086223393, 7097.361237488599, 4074.1851629674184, 4438.955610443919, 4445.2887537993765, 4449.243628583149, 4452.777361131931, 3801.6958643308467, 4386.824527018947, 4472.508024117345, 4538.795754191069, 4540.591346778806, 5152.234183380433, 5298.867528311823, 5347.67376191358, 5349.331333583302, 5402.51377376038, 6280.5603607747935, 6402.071699603624, 6637.3338793693365, 6642.63465509392, 6651.33486651331, 1307.8495972963042, 1512.4328376858678, 1522.0715363628178, 1542.6066353080116, 1547.8004546476345, 789.735438628066, 969.4764826993486, 999.7003894936554, 1015.8069966706303, 1022.3559157730582, 5308.601854860901, 5976.581639285208, 6239.438450539473, 6280.748770049238, 6321.747130114744, 6512.75310018043, 6660.433863121592, 6871.4502839773695, 6907.13660792401, 6911.2397636259975, 6237.500999919996, 6259.217597800275, 6290.559660823698, 6296.992481202986, 6297.338779428185, 3472.3055388922317, 3618.0, 3632.54593175853, 3640.745147839679, 3641.1625326175126, 7014.247377653033, 7082.716691332289, 7085.326893945067, 7085.787426377217, 7103.822404439827, 5610.579206559524, 6502.707378167994, 7104.609246491493, 7113.181984071794, 7114.430845532345, 5583.55331573465, 6964.31873728608, 7062.470314726416, 7067.92932070683, 7078.0, 5738.68437235956, 6070.332263450929, 6856.794296171123, 6938.687759050956, 6943.583384996917, 6413.390727880844, 6550.213974323112, 6656.83357916056, 6664.733990443078, 6752.69612867432, 6218.595791273553, 6801.251862295045, 7091.60996145217, 7119.110111236096, 7131.786046418616, 4421.712588681726, 4449.265871131227, 4449.799759010917, 4450.463235955474, 4453.36849676333, 6637.734490620321, 6939.514234003773, 7036.894446583407, 7037.507374483942, 7039.366457018893, 6164.6917654118015, 6740.9325906740305, 6745.831354216184, 6752.3922846944015, 6760.053592497153, 5321.600879934098, 6468.967655161694, 6718.798436046926, 6722.495812813961, 6763.086983257197, 5762.971185144047, 6563.409293163638, 6576.539642224999, 6605.47156227491, 6623.437007854386, 5489.231507588861, 6437.549371544134, 6494.545381823227, 6707.530472867048, 6755.459563235027, 6508.9349106508325, 6539.4768418525455, 6547.672616369067, 6548.443382312556, 6561.96719016421], "value": 4796.917460549019, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [765.9770206893793, 798.2695833312512, 852.1563651984537, 872.9350192764829, 886.6453418632548, 13455.461781528751, 13871.959603029787, 16521.917390413048, 16994.5751356216, 17004.0, 17129.657406851886, 17704.937703737785, 17779.37772177974, 17820.910895445504, 17821.37625183119, 857.764114868413, 935.5415846235363, 952.8904176019777, 970.4177493503912, 974.512743628186, 995.975100622483, 1001.1690297053465, 1001.7044971733357, 1001.9879921279487, 1003.0571263012781, 678.6233640329615, 696.5611664651262, 998.4009594243469, 1015.6749840051186, 1017.1354348803532, 1006.7382480555035, 1007.1942446043137, 1008.7881544875548, 1009.2834087797672, 1015.0, 740.3854800515613, 741.3698356397019, 742.2206682982846, 743.4127039638679, 743.5427212264473, 16113.758293625606, 16723.581910452336, 16923.661526769396, 17033.7444938326, 17096.658066838718, 16111.597210069842, 16538.917305413517, 16595.253213605418, 16601.252943617445, 16645.25096370666, 13990.650233744136, 14504.709905801825, 14536.418543258256, 14612.634684132876, 14623.78064329036, 3457.896263112111, 3467.393206188907, 3489.9825500872334, 3491.0748651607346, 3497.702695270905, 134286.0, 137412.625873741, 137860.31069844688, 138379.0, 138396.23207535793, 12027.579034733742, 12052.096092793076, 12252.938735306267, 12255.387230638433, 12296.569620063256, 16442.260098295606, 20635.17459301641, 20776.06507707157, 20986.685199722015, 21012.684809727867, 6708.698108585126, 6918.8616227676175, 7569.47013709035, 7609.2010338913615, 7674.040744906887, 3690.132818787583, 3873.7869417182324, 3904.90237744053, 3915.7454765440157, 3927.56796752357, 4152.958470415318, 4241.851535196253, 4247.086876321606, 4252.957470425318, 4263.8081286342185, 907.668700924158, 941.6045260990401, 947.8436058050482, 949.9905000949902, 952.2239374909427, 629.9212598425197, 650.0, 658.4403257231411, 666.3070406776898, 687.0724521895501, 11200.271982321123, 11250.381229032482, 12166.435561585256, 12295.508179672892, 12666.873331266754, 630.1146888621403, 631.6715308039885, 645.6094063091856, 648.3937518420292, 657.9867004812535, 4166.208420400114, 5198.8768838487385, 5212.921806172911, 5333.040052790535, 5475.890482190334, 100025.49987250018, 116485.8351416492, 116524.41737791257, 118311.0, 118346.81653183358, 105733.47133264285, 107370.0, 109261.0, 122343.0, 125128.37435812762, 83954.0, 98110.5094474523, 99516.0, 101098.494507527, 101720.49139754254, 11301.491432885376, 12223.0, 12349.629511114681, 12479.87520124787, 12501.624951251477, 37978.0505487365, 40760.59239407568, 42516.149677006906, 43094.35358469688, 43888.780556097016, 59628.0, 60115.0, 60462.39537604568, 71910.92133618104, 76368.61815690887, 17172.828271717124, 17408.303667853652, 17456.82543174552, 17460.0, 17483.650326993644, 15553.84446155524, 16890.40883569118, 18240.332623709455, 19122.235110595422, 19182.61634767269, 15633.921830390775, 16738.58153546171, 17140.885842420204, 18247.996360199784, 18320.45038648843, 16856.747148792532, 16874.915625421796, 17815.732764008808, 18125.36561220351, 18193.090345482407, 16519.421820236235, 16651.833481665028, 16661.0, 16767.664646707242, 16785.99284042961, 11784.941075294568, 11851.051915846883, 11953.521859125885, 12346.938265308616, 12527.749445011232, 13292.867071329163, 14203.786943196066, 14236.928815355857, 14245.572632821031, 14518.346674399889], "value": 8429.99342493355, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1536.1000000000001, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 123.0, 112.0, 113.0, 115.0, 113.0, 113.0, 114.0, 114.0, 114.0], "value": 112.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [136.0, 109.0, 107.0, 173.0, 105.0, 98.0, 132.0, 111.0, 169.0, 143.0], "value": 128.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [188.0, 230.0, 236.0, 239.0, 247.0, 247.0, 276.0, 204.0, 193.0, 198.0], "value": 225.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 78.0, 87.0, 109.0, 74.0, 56.0, 60.0, 62.0, 76.0, 59.0], "value": 74.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 69.0, 83.0, 71.0, 71.0, 66.0, 85.0, 71.0, 85.0, 116.0], "value": 79.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 98.0, 112.0, 90.0, 90.0, 96.0, 89.0, 108.0, 90.0, 90.0], "value": 95.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [124.0, 97.0, 98.0, 97.0, 106.0, 120.0, 99.0, 96.0, 97.0, 98.0], "value": 103.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 95.0, 93.0, 91.0, 92.0, 88.0, 88.0, 130.0, 133.0, 91.0], "value": 99.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [52.0, 52.0, 52.0, 50.0, 61.0, 51.0, 51.0, 50.0, 53.0, 54.0], "value": 52.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 54.0, 61.0, 60.0, 65.0, 63.0, 61.0, 65.0, 80.0, 57.0], "value": 62.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 86.0, 86.0, 97.0, 87.0, 87.0, 89.0, 92.0, 127.0, 113.0], "value": 95.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 136.0, 146.0, 140.0, 137.0, 141.0, 131.0, 162.0, 151.0, 169.0], "value": 146.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 188.0, 187.0, 186.0, 230.0, 194.0, 183.0, 185.0, 184.0, 188.0], "value": 190.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 81.0, 68.0, 58.0, 77.0, 83.0, 67.0, 68.0, 68.0, 69.0], "value": 71.0, "unit": "score"}]}]} 04:20:59 INFO - Return code: 0 04:20:59 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 04:20:59 INFO - # TBPL SUCCESS # 04:20:59 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 04:20:59 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 04:20:59 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 04:20:59 INFO - 2017-05-30 04:20:59.324 screenresolution[1670:11346] starting screenresolution argv=screenresolution get 04:20:59 INFO - 2017-05-30 04:20:59.326 screenresolution[1670:11346] Display 0: 1600x1200x32@60 04:20:59 INFO - 2017-05-30 04:20:59.332 screenresolution[1671:11349] starting screenresolution argv=screenresolution list 04:20:59 INFO - Available Modes on Display 0 04:20:59 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 04:20:59 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 04:20:59 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 04:20:59 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 04:20:59 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 04:20:59 INFO - Intel Iris: 04:20:59 INFO - Chipset Model: Intel Iris 04:20:59 INFO - Type: GPU 04:20:59 INFO - Bus: Built-In 04:20:59 INFO - VRAM (Dynamic, Max): 1536 MB 04:20:59 INFO - Vendor: Intel (0x8086) 04:20:59 INFO - Device ID: 0x0a2e 04:20:59 INFO - Revision ID: 0x0009 04:20:59 INFO - Displays: 04:20:59 INFO - Display: 04:20:59 INFO - Resolution: 1600 x 1200 @ 60 Hz 04:20:59 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 04:20:59 INFO - Display Serial Number: 1600x1200 60 04:20:59 INFO - Main Display: Yes 04:20:59 INFO - Mirror: Off 04:20:59 INFO - Online: Yes 04:20:59 INFO - Rotation: Supported 04:20:59 INFO - Return code: 0 04:20:59 INFO - Running post-action listener: _resource_record_post_action 04:20:59 INFO - [mozharness: 2017-05-30 11:20:59.569800Z] Finished run-tests step (success) 04:20:59 INFO - Running post-run listener: _resource_record_post_run 04:20:59 INFO - Total resource usage - Wall time: 1256s; CPU: 53.0%; Read bytes: 34341888; Write bytes: 570485760; Read time: 210; Write time: 1805 04:20:59 INFO - TinderboxPrint: CPU usage
53.4% 04:20:59 INFO - TinderboxPrint: I/O read bytes / time
34,341,888 / 210 04:20:59 INFO - TinderboxPrint: I/O write bytes / time
570,485,760 / 1,805 04:20:59 INFO - TinderboxPrint: CPU idle
2,340.6 (46.6%) 04:20:59 INFO - TinderboxPrint: CPU system
1,017.6 (20.2%) 04:20:59 INFO - TinderboxPrint: CPU user
1,667.5 (33.2%) 04:20:59 INFO - TinderboxPrint: Swap in / out
276,905,984 / 0 04:20:59 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 230916608; Write bytes: 231942144; Read time: 16762; Write time: 483 04:20:59 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 04:20:59 INFO - run-tests - Wall time: 1236s; CPU: 53.0%; Read bytes: 33412096; Write bytes: 320078848; Read time: 199; Write time: 1271 04:21:00 INFO - Running post-run listener: _upload_blobber_files 04:21:00 INFO - Blob upload gear active. 04:21:00 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 04:21:00 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:21:00 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:21:00 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:21:00 INFO - (blobuploader) - INFO - Open directory for files ... 04:21:00 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 04:21:00 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:21:00 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:21:01 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 04:21:01 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:21:01 INFO - (blobuploader) - INFO - Done attempting. 04:21:01 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 04:21:01 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:21:01 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:21:02 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 04:21:02 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:21:02 INFO - (blobuploader) - INFO - Done attempting. 04:21:02 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 04:21:02 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:21:02 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:21:03 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 04:21:03 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:21:03 INFO - (blobuploader) - INFO - Done attempting. 04:21:03 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 04:21:03 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:21:03 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:21:04 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 04:21:04 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:21:04 INFO - (blobuploader) - INFO - Done attempting. 04:21:04 INFO - (blobuploader) - INFO - Iteration through files over. 04:21:04 INFO - Return code: 0 04:21:04 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 04:21:04 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 04:21:04 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/055eafb0e6a744a40a773a14223e6467a3ce432d7b044b33f283c9eb0d39904f6bef266ffd142ac021d4ddf4e9d28e128153f18843768ac37f79e013345ed4ae", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/64bd8dbf30d6ac30af62bc8a906cc18de7d57f93bb99789dcf55ed608e455d8a03d04a2d9324a07c2aa698cb9a6dcede4bb7d4c900c7ca4fae3d8324a69e2c5f", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4b66d479bf24577704b4a0a9cce33e655d875b0fe518df9086cfaec7ec77e5600620231254029e1dc34be0a8ed6eac007e6f113f70062cb33832f68aa297e6f4", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d0df71076f9666a308618635eb3e686537ad7e1aa103bccf72f1d83eaa8f684e9fe5ab357b937e3d047ebadd2ac1991d880fd7a13bf7924cb121563f4caf2ed1"} 04:21:04 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 04:21:04 INFO - Writing to file /builds/slave/test/properties/blobber_files 04:21:04 INFO - Contents: 04:21:04 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/055eafb0e6a744a40a773a14223e6467a3ce432d7b044b33f283c9eb0d39904f6bef266ffd142ac021d4ddf4e9d28e128153f18843768ac37f79e013345ed4ae", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/64bd8dbf30d6ac30af62bc8a906cc18de7d57f93bb99789dcf55ed608e455d8a03d04a2d9324a07c2aa698cb9a6dcede4bb7d4c900c7ca4fae3d8324a69e2c5f", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4b66d479bf24577704b4a0a9cce33e655d875b0fe518df9086cfaec7ec77e5600620231254029e1dc34be0a8ed6eac007e6f113f70062cb33832f68aa297e6f4", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d0df71076f9666a308618635eb3e686537ad7e1aa103bccf72f1d83eaa8f684e9fe5ab357b937e3d047ebadd2ac1991d880fd7a13bf7924cb121563f4caf2ed1"} 04:21:04 INFO - Running post-run listener: copy_logs_to_upload_dir 04:21:04 INFO - Copying logs to upload dir... 04:21:04 INFO - mkdir: /builds/slave/test/build/upload/logs 04:21:04 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1338.332535 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 18 secs) (at 2017-05-30 04:21:04.958016) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-30 04:21:04.961427) ========= 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.spmqfx40Ql/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.6M32lOAEkw/Listeners TMPDIR=/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/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/055eafb0e6a744a40a773a14223e6467a3ce432d7b044b33f283c9eb0d39904f6bef266ffd142ac021d4ddf4e9d28e128153f18843768ac37f79e013345ed4ae", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/64bd8dbf30d6ac30af62bc8a906cc18de7d57f93bb99789dcf55ed608e455d8a03d04a2d9324a07c2aa698cb9a6dcede4bb7d4c900c7ca4fae3d8324a69e2c5f", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4b66d479bf24577704b4a0a9cce33e655d875b0fe518df9086cfaec7ec77e5600620231254029e1dc34be0a8ed6eac007e6f113f70062cb33832f68aa297e6f4", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d0df71076f9666a308618635eb3e686537ad7e1aa103bccf72f1d83eaa8f684e9fe5ab357b937e3d047ebadd2ac1991d880fd7a13bf7924cb121563f4caf2ed1"} build_url:https://queue.taskcluster.net/v1/task/KPnkVo3nQGeAD9kc138Kew/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011154 build_url: 'https://queue.taskcluster.net/v1/task/KPnkVo3nQGeAD9kc138Kew/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/055eafb0e6a744a40a773a14223e6467a3ce432d7b044b33f283c9eb0d39904f6bef266ffd142ac021d4ddf4e9d28e128153f18843768ac37f79e013345ed4ae", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/64bd8dbf30d6ac30af62bc8a906cc18de7d57f93bb99789dcf55ed608e455d8a03d04a2d9324a07c2aa698cb9a6dcede4bb7d4c900c7ca4fae3d8324a69e2c5f", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4b66d479bf24577704b4a0a9cce33e655d875b0fe518df9086cfaec7ec77e5600620231254029e1dc34be0a8ed6eac007e6f113f70062cb33832f68aa297e6f4", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d0df71076f9666a308618635eb3e686537ad7e1aa103bccf72f1d83eaa8f684e9fe5ab357b937e3d047ebadd2ac1991d880fd7a13bf7924cb121563f4caf2ed1"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-30 04:21:04.994726) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-30 04:21:04.995052) ========= 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.spmqfx40Ql/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.6M32lOAEkw/Listeners TMPDIR=/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/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.005248 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-30 04:21:05.044504) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-30 04:21:05.044901) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-30 04:21:05.045309) ========= ========= Total master_lag: 0.29 =========