builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0023 starttime: 1495454775.34 results: success (0) revision: b4a8c54c2b056a5df90bf2284a25c4bec75bb1be ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-05-22 05:06:15.341127) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-05-22 05:06:15.341597) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-22 05:06:15.341870) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-22 05:06:15.365406) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-22 05:06:15.365793) ========= 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.ytbCQnQzGm/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.vc1iQUwSdk/Listeners TMPDIR=/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/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-22 05:06:15-- 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% 8.25M=0.001s 2017-05-22 05:06:15 (8.25 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.114551 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-22 05:06:15.502528) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-22 05:06:15.502865) ========= 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.ytbCQnQzGm/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.vc1iQUwSdk/Listeners TMPDIR=/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/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.021535 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-22 05:06:15.552231) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-05-22 05:06:15.552582) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev b4a8c54c2b056a5df90bf2284a25c4bec75bb1be --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 b4a8c54c2b056a5df90bf2284a25c4bec75bb1be --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.ytbCQnQzGm/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.vc1iQUwSdk/Listeners TMPDIR=/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/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-22 05:06:15,631 truncating revision to first 12 chars 2017-05-22 05:06:15,632 Setting DEBUG logging. 2017-05-22 05:06:15,632 attempt 1/10 2017-05-22 05:06:15,632 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/b4a8c54c2b05?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-05-22 05:06:17,398 unpacking tar archive at: mozilla-central-b4a8c54c2b05/testing/mozharness/ program finished with exit code 0 elapsedTime=2.030058 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-05-22 05:06:17.604408) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-22 05:06:17.604729) ========= script_repo_revision: b4a8c54c2b056a5df90bf2284a25c4bec75bb1be ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-22 05:06:17.605092) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-22 05:06:17.605433) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-22 05:06:17.624374) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2017-05-22 05:06:17.624767) ========= /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.ytbCQnQzGm/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.vc1iQUwSdk/Listeners TMPDIR=/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/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 05:06:17 INFO - MultiFileLogger online at 20170522 05:06:17 in /builds/slave/test 05:06:17 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 05:06:17 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:06:17 INFO - {'append_to_log': False, 05:06:17 INFO - 'base_work_dir': '/builds/slave/test', 05:06:17 INFO - 'blob_upload_branch': 'Firefox', 05:06:17 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:06:17 INFO - 'branch': 'Firefox', 05:06:17 INFO - 'buildbot_json_path': 'buildprops.json', 05:06:17 INFO - 'config_files': ('talos/mac_config.py',), 05:06:17 INFO - 'default_actions': ('clobber', 05:06:17 INFO - 'read-buildbot-config', 05:06:17 INFO - 'download-and-extract', 05:06:17 INFO - 'populate-webroot', 05:06:17 INFO - 'create-virtualenv', 05:06:17 INFO - 'install', 05:06:17 INFO - 'run-tests'), 05:06:17 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:06:17 INFO - 'download_minidump_stackwalk': True, 05:06:17 INFO - 'download_symbols': 'ondemand', 05:06:17 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:06:17 INFO - 'tooltool.py': '/tools/tooltool.py', 05:06:17 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:06:17 INFO - '/tools/misc-python/virtualenv.py')}, 05:06:17 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:06:17 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:06:17 INFO - 'gecko_profile': False, 05:06:17 INFO - 'gecko_profile_interval': 0, 05:06:17 INFO - 'installer_path': 'installer.exe', 05:06:17 INFO - 'log_level': 'info', 05:06:17 INFO - 'log_name': 'talos', 05:06:17 INFO - 'log_to_console': True, 05:06:17 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 05:06:17 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 05:06:17 INFO - 'opt_config_files': (), 05:06:17 INFO - 'pip_index': False, 05:06:17 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:06:17 INFO - 'cmd': ('bash', 05:06:17 INFO - '-c', 05:06:17 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:06:17 INFO - 'enabled': True, 05:06:17 INFO - 'halt_on_failure': False, 05:06:17 INFO - 'name': 'check_screen_resolution'},), 05:06:17 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:06:17 INFO - 'cmd': ('bash', 05:06:17 INFO - '-c', 05:06:17 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:06:17 INFO - 'enabled': True, 05:06:17 INFO - 'halt_on_failure': False, 05:06:17 INFO - 'name': 'check_screen_resolution'},), 05:06:17 INFO - 'run_cmd_checks_enabled': True, 05:06:17 INFO - 'suite': 'dromaeojs-e10s', 05:06:17 INFO - 'system_bits': '32', 05:06:17 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:06:17 INFO - 'title': 't-yosemite-r7-0023', 05:06:17 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:06:17 INFO - 'use_talos_json': True, 05:06:17 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:06:17 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:06:17 INFO - 'work_dir': 'build'} 05:06:17 INFO - [mozharness: 2017-05-22 12:06:17.830406Z] Running clobber step. 05:06:17 INFO - Running pre-action listener: _resource_record_pre_action 05:06:17 INFO - Running main action method: clobber 05:06:17 INFO - rmtree: /builds/slave/test/build 05:06:17 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:06:18 INFO - Running post-action listener: _resource_record_post_action 05:06:18 INFO - [mozharness: 2017-05-22 12:06:18.458690Z] Finished clobber step (success) 05:06:18 INFO - [mozharness: 2017-05-22 12:06:18.458820Z] Running read-buildbot-config step. 05:06:18 INFO - Running pre-action listener: _resource_record_pre_action 05:06:18 INFO - Running main action method: read_buildbot_config 05:06:18 INFO - Using buildbot properties: 05:06:18 INFO - { 05:06:18 INFO - "project": "", 05:06:18 INFO - "product": "firefox", 05:06:18 INFO - "who": "cbook@mozilla.com", 05:06:18 INFO - "installer_path": "public/build/firefox-55.0a1.en-US.mac.dmg", 05:06:18 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 05:06:18 INFO - "repository": "https://hg.mozilla.org/mozilla-central/", 05:06:18 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 05:06:18 INFO - "stage_platform": "macosx64", 05:06:18 INFO - "basedir": "/builds/slave/test", 05:06:18 INFO - "buildnumber": 73, 05:06:18 INFO - "platform": "yosemite_r7", 05:06:18 INFO - "parent_task_id": "HN_nkN2KQgqZBc02j7G2Rw", 05:06:18 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 05:06:18 INFO - "slavebuilddir": "test", 05:06:18 INFO - "taskId": "OahoPZDqQv6F1O5wx3Pnrg", 05:06:18 INFO - "branch": "mozilla-central", 05:06:18 INFO - "script_repo_revision": "production", 05:06:18 INFO - "revision": "b4a8c54c2b056a5df90bf2284a25c4bec75bb1be", 05:06:18 INFO - "slavename": "t-yosemite-r7-0023", 05:06:18 INFO - "repo_path": "mozilla-central" 05:06:18 INFO - } 05:06:18 INFO - Finding installer, test and symbols from parent task. 05:06:18 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/OahoPZDqQv6F1O5wx3Pnrg'}, attempt #1 05:06:19 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/OahoPZDqQv6F1O5wx3Pnrg'}, attempt #1 05:06:19 INFO - Task dependencies: TP6fihwUSOip-VCoTEXn5g HN_nkN2KQgqZBc02j7G2Rw 05:06:19 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/HN_nkN2KQgqZBc02j7G2Rw'}, attempt #1 05:06:20 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/HN_nkN2KQgqZBc02j7G2Rw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 05:06:20 INFO - Set test_url: https://queue.taskcluster.net/v1/task/HN_nkN2KQgqZBc02j7G2Rw/artifacts/public/build/test_packages.json 05:06:20 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/HN_nkN2KQgqZBc02j7G2Rw/artifacts/public/build/target.crashreporter-symbols.zip 05:06:20 INFO - Running post-action listener: _resource_record_post_action 05:06:20 INFO - [mozharness: 2017-05-22 12:06:20.305297Z] Finished read-buildbot-config step (success) 05:06:20 INFO - [mozharness: 2017-05-22 12:06:20.305435Z] Running download-and-extract step. 05:06:20 INFO - Running pre-action listener: _resource_record_pre_action 05:06:20 INFO - Running main action method: download_and_extract 05:06:20 INFO - mkdir: /builds/slave/test/build/tests 05:06:20 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')]} 05:06:20 INFO - trying https://queue.taskcluster.net/v1/task/HN_nkN2KQgqZBc02j7G2Rw/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 05:06:20 INFO - Downloading https://queue.taskcluster.net/v1/task/HN_nkN2KQgqZBc02j7G2Rw/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 05:06:20 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/HN_nkN2KQgqZBc02j7G2Rw/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 05:06:21 INFO - Downloaded 1325 bytes. 05:06:21 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 05:06:21 INFO - Using the following test package requirements: 05:06:21 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 05:06:21 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 05:06:21 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 05:06:21 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 05:06:21 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 05:06:21 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 05:06:21 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 05:06:21 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 05:06:21 INFO - u'jsshell-mac.zip'], 05:06:21 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 05:06:21 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 05:06:21 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 05:06:21 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 05:06:21 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 05:06:21 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 05:06:21 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 05:06:21 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 05:06:21 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 05:06:21 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 05:06:21 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 05:06:21 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 05:06:21 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/HN_nkN2KQgqZBc02j7G2Rw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 05:06:21 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/HN_nkN2KQgqZBc02j7G2Rw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 05:06:21 INFO - Fetch https://queue.taskcluster.net/v1/task/HN_nkN2KQgqZBc02j7G2Rw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 05:06:22 INFO - Content-Length response header: 57622760 05:06:22 INFO - Bytes received: 57622760 05:06:41 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 05:06:41 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/HN_nkN2KQgqZBc02j7G2Rw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 05:06:41 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/HN_nkN2KQgqZBc02j7G2Rw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 05:06:41 INFO - Fetch https://queue.taskcluster.net/v1/task/HN_nkN2KQgqZBc02j7G2Rw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 05:06:43 INFO - Content-Length response header: 57622760 05:06:43 INFO - Bytes received: 57622760 05:07:00 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/HN_nkN2KQgqZBc02j7G2Rw/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 05:07:00 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/HN_nkN2KQgqZBc02j7G2Rw/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 05:07:00 INFO - Fetch https://queue.taskcluster.net/v1/task/HN_nkN2KQgqZBc02j7G2Rw/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 05:07:01 INFO - Content-Length response header: 14410000 05:07:01 INFO - Bytes received: 14410000 05:07:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:07:02 INFO - trying https://queue.taskcluster.net/v1/task/HN_nkN2KQgqZBc02j7G2Rw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 05:07:02 INFO - Downloading https://queue.taskcluster.net/v1/task/HN_nkN2KQgqZBc02j7G2Rw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 05:07:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/HN_nkN2KQgqZBc02j7G2Rw/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 05:07:04 INFO - Downloaded 68003260 bytes. 05:07:04 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/HN_nkN2KQgqZBc02j7G2Rw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 05:07:04 INFO - mkdir: /builds/slave/test/properties 05:07:04 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:07:04 INFO - Writing to file /builds/slave/test/properties/build_url 05:07:04 INFO - Contents: 05:07:04 INFO - build_url:https://queue.taskcluster.net/v1/task/HN_nkN2KQgqZBc02j7G2Rw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 05:07:04 INFO - Running post-action listener: _resource_record_post_action 05:07:04 INFO - Running post-action listener: set_extra_try_arguments 05:07:04 INFO - [mozharness: 2017-05-22 12:07:04.044333Z] Finished download-and-extract step (success) 05:07:04 INFO - [mozharness: 2017-05-22 12:07:04.044485Z] Running populate-webroot step. 05:07:04 INFO - Running pre-action listener: _resource_record_pre_action 05:07:04 INFO - Running main action method: populate_webroot 05:07:04 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:07:04 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:07:04 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:07:04 INFO - '--tppagecycles', 05:07:04 INFO - '7'], 05:07:04 INFO - 'tests': ['tsvgm']}}, 05:07:04 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 05:07:04 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:07:04 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:07:04 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 05:07:04 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:07:04 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:07:04 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 05:07:04 INFO - 'talos_options': ['--disable-e10s'], 05:07:04 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:07:04 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 05:07:04 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:07:04 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 05:07:04 INFO - 'talos_options': ['--disable-e10s'], 05:07:04 INFO - 'tests': ['damp', 'tps']}, 05:07:04 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 05:07:04 INFO - 'tests': ['damp', 'tps']}, 05:07:04 INFO - 'g3': {'talos_options': ['--disable-e10s'], 05:07:04 INFO - 'tests': ['dromaeo_dom']}, 05:07:04 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:07:04 INFO - 'g4': {'talos_options': ['--disable-e10s'], 05:07:04 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 05:07:04 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 05:07:04 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 05:07:04 INFO - 'tests': ['ts_paint', 'tp5o']}, 05:07:04 INFO - 'other': {'talos_options': ['--disable-e10s'], 05:07:04 INFO - 'tests': ['a11yr', 05:07:04 INFO - 'ts_paint', 05:07:04 INFO - 'tpaint', 05:07:04 INFO - 'sessionrestore', 05:07:04 INFO - 'sessionrestore_no_auto_restore', 05:07:04 INFO - 'tabpaint']}, 05:07:04 INFO - 'other-e10s': {'tests': ['a11yr', 05:07:04 INFO - 'ts_paint', 05:07:04 INFO - 'tpaint', 05:07:04 INFO - 'sessionrestore', 05:07:04 INFO - 'sessionrestore_no_auto_restore', 05:07:04 INFO - 'tabpaint']}, 05:07:04 INFO - 'other-e10s_l64': {'tests': ['a11yr', 05:07:04 INFO - 'ts_paint', 05:07:04 INFO - 'tpaint', 05:07:04 INFO - 'sessionrestore', 05:07:04 INFO - 'sessionrestore_no_auto_restore', 05:07:04 INFO - 'tabpaint']}, 05:07:04 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 05:07:04 INFO - 'ts_paint', 05:07:04 INFO - 'tpaint', 05:07:04 INFO - 'sessionrestore', 05:07:04 INFO - 'sessionrestore_no_auto_restore', 05:07:04 INFO - 'tabpaint']}, 05:07:04 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 05:07:04 INFO - 'tests': ['a11yr', 05:07:04 INFO - 'ts_paint', 05:07:04 INFO - 'tpaint', 05:07:04 INFO - 'sessionrestore', 05:07:04 INFO - 'sessionrestore_no_auto_restore', 05:07:04 INFO - 'tabpaint']}, 05:07:04 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 05:07:04 INFO - 'tests': ['a11yr', 05:07:04 INFO - 'ts_paint', 05:07:04 INFO - 'tpaint', 05:07:04 INFO - 'sessionrestore', 05:07:04 INFO - 'sessionrestore_no_auto_restore', 05:07:04 INFO - 'tabpaint']}, 05:07:04 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 05:07:04 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 05:07:04 INFO - 'bloom_basic_ref']}, 05:07:04 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 05:07:04 INFO - 'tests': ['tsvgx', 05:07:04 INFO - 'tsvgr_opacity', 05:07:04 INFO - 'tart', 05:07:04 INFO - 'tscrollx', 05:07:04 INFO - 'cart', 05:07:04 INFO - 'tsvg_static']}, 05:07:04 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:07:04 INFO - 'tsvgr_opacity', 05:07:04 INFO - 'tart', 05:07:04 INFO - 'tscrollx', 05:07:04 INFO - 'cart', 05:07:04 INFO - 'tsvg_static']}, 05:07:04 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 05:07:04 INFO - 'talos_options': ['--disable-e10s'], 05:07:04 INFO - 'tests': ['tp5o']}, 05:07:04 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 05:07:04 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 05:07:04 INFO - 'talos_options': ['--disable-e10s', 05:07:04 INFO - '--xperf_path', 05:07:04 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:07:04 INFO - 'tests': ['tp5n']}, 05:07:04 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 05:07:04 INFO - 'talos_options': ['--xperf_path', 05:07:04 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:07:04 INFO - 'tests': ['tp5n']}}, 05:07:04 INFO - 'talos.zip': {'path': '', 05:07:04 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:07:04 INFO - Running post-action listener: _resource_record_post_action 05:07:04 INFO - [mozharness: 2017-05-22 12:07:04.065348Z] Finished populate-webroot step (success) 05:07:04 INFO - [mozharness: 2017-05-22 12:07:04.065456Z] Running create-virtualenv step. 05:07:04 INFO - Running pre-action listener: _resource_record_pre_action 05:07:04 INFO - Running main action method: create_virtualenv 05:07:04 INFO - Creating virtualenv /builds/slave/test/build/venv 05:07:04 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 05:07:04 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:07:04 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:07:04 INFO - Using real prefix '/tools/python27' 05:07:04 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:07:04 INFO - Installing distribute.............................................................................................................................................................................................done. 05:07:07 INFO - Installing pip.................done. 05:07:07 INFO - Return code: 0 05:07:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 05:07:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 05:07:08 INFO - Reading from file tmpfile_stdout 05:07:08 INFO - Output received: 05:07:08 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) 05:07:08 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:07:08 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')]} 05:07:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:07:08 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')]} 05:07:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:07:08 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 0x10a055b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a059768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd63b49e8c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109d65d30>, '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 0x109d19db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109d5abe8>, '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.ytbCQnQzGm/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.vc1iQUwSdk/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/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 05:07:08 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 05:07:08 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 05:07:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ytbCQnQzGm/Render', 05:07:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:07:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:07:08 INFO - 'HOME': '/Users/cltbld', 05:07:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:07:08 INFO - 'LOGNAME': 'cltbld', 05:07:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:07:08 INFO - 'MOZ_NO_REMOTE': '1', 05:07:08 INFO - 'NO_EM_RESTART': '1', 05:07:08 INFO - 'PAGER': '/bin/cat', 05:07:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:07:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:07:08 INFO - 'PWD': '/builds/slave/test', 05:07:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:07:08 INFO - 'SHELL': '/bin/bash', 05:07:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.vc1iQUwSdk/Listeners', 05:07:08 INFO - 'TMPDIR': '/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/', 05:07:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:07:08 INFO - 'USER': 'cltbld', 05:07:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:07:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:07:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:07:08 INFO - 'XPC_FLAGS': '0x0', 05:07:08 INFO - 'XPC_SERVICE_NAME': '0', 05:07:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:07:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:07:08 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 05:07:08 INFO - Cleaning up... 05:07:08 INFO - Return code: 0 05:07:08 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 05:07:08 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')]} 05:07:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:07:08 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')]} 05:07:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:07:08 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 0x10a055b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a059768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd63b49e8c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109d65d30>, '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 0x109d19db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109d5abe8>, '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.ytbCQnQzGm/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.vc1iQUwSdk/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/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 05:07:08 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 05:07:08 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 05:07:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ytbCQnQzGm/Render', 05:07:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:07:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:07:08 INFO - 'HOME': '/Users/cltbld', 05:07:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:07:08 INFO - 'LOGNAME': 'cltbld', 05:07:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:07:08 INFO - 'MOZ_NO_REMOTE': '1', 05:07:08 INFO - 'NO_EM_RESTART': '1', 05:07:08 INFO - 'PAGER': '/bin/cat', 05:07:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:07:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:07:08 INFO - 'PWD': '/builds/slave/test', 05:07:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:07:08 INFO - 'SHELL': '/bin/bash', 05:07:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.vc1iQUwSdk/Listeners', 05:07:08 INFO - 'TMPDIR': '/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/', 05:07:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:07:08 INFO - 'USER': 'cltbld', 05:07:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:07:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:07:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:07:08 INFO - 'XPC_FLAGS': '0x0', 05:07:08 INFO - 'XPC_SERVICE_NAME': '0', 05:07:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:07:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:07:08 INFO - Downloading/unpacking psutil>=3.1.1 05:07:08 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 05:07:08 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 05:07:08 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 05:07:08 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 05:07:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:07:10 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:07:10 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:07:10 INFO - Installing collected packages: psutil 05:07:10 INFO - Running setup.py install for psutil 05:07:10 INFO - building 'psutil._psutil_osx' extension 05:07:10 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 05:07:11 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 05:07:11 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 05:07:11 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 05:07:11 INFO - building 'psutil._psutil_posix' extension 05:07:11 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 05:07:11 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 05:07:11 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 05:07:11 INFO - ^ 05:07:11 INFO - 1 warning generated. 05:07:11 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 05:07:11 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:07:11 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:07:11 INFO - Successfully installed psutil 05:07:11 INFO - Cleaning up... 05:07:11 INFO - Return code: 0 05:07:11 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 05:07:11 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')]} 05:07:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:07:11 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')]} 05:07:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:07:11 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 0x10a055b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a059768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd63b49e8c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109d65d30>, '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 0x109d19db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109d5abe8>, '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.ytbCQnQzGm/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.vc1iQUwSdk/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/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 05:07:11 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 05:07:11 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 05:07:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ytbCQnQzGm/Render', 05:07:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:07:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:07:11 INFO - 'HOME': '/Users/cltbld', 05:07:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:07:11 INFO - 'LOGNAME': 'cltbld', 05:07:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:07:11 INFO - 'MOZ_NO_REMOTE': '1', 05:07:11 INFO - 'NO_EM_RESTART': '1', 05:07:11 INFO - 'PAGER': '/bin/cat', 05:07:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:07:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:07:11 INFO - 'PWD': '/builds/slave/test', 05:07:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:07:11 INFO - 'SHELL': '/bin/bash', 05:07:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.vc1iQUwSdk/Listeners', 05:07:11 INFO - 'TMPDIR': '/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/', 05:07:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:07:11 INFO - 'USER': 'cltbld', 05:07:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:07:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:07:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:07:11 INFO - 'XPC_FLAGS': '0x0', 05:07:11 INFO - 'XPC_SERVICE_NAME': '0', 05:07:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:07:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:07:11 INFO - Downloading/unpacking mozsystemmonitor==0.3 05:07:11 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 05:07:11 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 05:07:11 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 05:07:11 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 05:07:13 INFO - Downloading mozsystemmonitor-0.3.tar.gz 05:07:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:07:13 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 05:07:13 INFO - Installing collected packages: mozsystemmonitor 05:07:13 INFO - Running setup.py install for mozsystemmonitor 05:07:13 INFO - Successfully installed mozsystemmonitor 05:07:13 INFO - Cleaning up... 05:07:13 INFO - Return code: 0 05:07:13 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 05:07:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:07:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:07:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:07:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:07:13 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 0x10a055b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a059768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd63b49e8c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109d65d30>, '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 0x109d19db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109d5abe8>, '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.ytbCQnQzGm/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.vc1iQUwSdk/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/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 05:07:13 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 05:07:13 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 05:07:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ytbCQnQzGm/Render', 05:07:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:07:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:07:13 INFO - 'HOME': '/Users/cltbld', 05:07:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:07:13 INFO - 'LOGNAME': 'cltbld', 05:07:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:07:13 INFO - 'MOZ_NO_REMOTE': '1', 05:07:13 INFO - 'NO_EM_RESTART': '1', 05:07:13 INFO - 'PAGER': '/bin/cat', 05:07:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:07:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:07:13 INFO - 'PWD': '/builds/slave/test', 05:07:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:07:13 INFO - 'SHELL': '/bin/bash', 05:07:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.vc1iQUwSdk/Listeners', 05:07:13 INFO - 'TMPDIR': '/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/', 05:07:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:07:13 INFO - 'USER': 'cltbld', 05:07:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:07:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:07:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:07:13 INFO - 'XPC_FLAGS': '0x0', 05:07:13 INFO - 'XPC_SERVICE_NAME': '0', 05:07:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:07:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:07:14 INFO - Downloading/unpacking jsonschema==2.5.1 05:07:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:07:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:07:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:07:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:07:15 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:07:15 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 05:07:15 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 05:07:15 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 05:07:15 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 05:07:15 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 05:07:15 INFO - Downloading functools32-3.2.3-2.tar.gz 05:07:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:07:15 INFO - warning: no files found matching '*.txt' 05:07:15 INFO - no previously-included directories found matching 'build' 05:07:15 INFO - no previously-included directories found matching 'dist' 05:07:15 INFO - no previously-included directories found matching '.git*' 05:07:15 INFO - Installing collected packages: jsonschema, functools32 05:07:15 INFO - Running setup.py install for functools32 05:07:15 INFO - warning: no files found matching '*.txt' 05:07:15 INFO - no previously-included directories found matching 'build' 05:07:15 INFO - no previously-included directories found matching 'dist' 05:07:15 INFO - no previously-included directories found matching '.git*' 05:07:16 INFO - Successfully installed jsonschema functools32 05:07:16 INFO - Cleaning up... 05:07:16 INFO - Return code: 0 05:07:16 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 05:07:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:07:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:07:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:07:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:07:16 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10a055b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a059768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd63b49e8c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109d65d30>, '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 0x109d19db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109d5abe8>, '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.ytbCQnQzGm/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.vc1iQUwSdk/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/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 05:07:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 05:07:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 05:07:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ytbCQnQzGm/Render', 05:07:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:07:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:07:16 INFO - 'HOME': '/Users/cltbld', 05:07:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:07:16 INFO - 'LOGNAME': 'cltbld', 05:07:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:07:16 INFO - 'MOZ_NO_REMOTE': '1', 05:07:16 INFO - 'NO_EM_RESTART': '1', 05:07:16 INFO - 'PAGER': '/bin/cat', 05:07:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:07:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:07:16 INFO - 'PWD': '/builds/slave/test', 05:07:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:07:16 INFO - 'SHELL': '/bin/bash', 05:07:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.vc1iQUwSdk/Listeners', 05:07:16 INFO - 'TMPDIR': '/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/', 05:07:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:07:16 INFO - 'USER': 'cltbld', 05:07:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:07:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:07:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:07:16 INFO - 'XPC_FLAGS': '0x0', 05:07:16 INFO - 'XPC_SERVICE_NAME': '0', 05:07:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:07:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:07:16 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 05:07:16 INFO - Cleaning up... 05:07:16 INFO - Return code: 0 05:07:16 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:07:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:07:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:07:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:07:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:07:16 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10a055b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a059768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd63b49e8c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109d65d30>, '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 0x109d19db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109d5abe8>, '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.ytbCQnQzGm/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.vc1iQUwSdk/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/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 05:07:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 05:07:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 05:07:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ytbCQnQzGm/Render', 05:07:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:07:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:07:16 INFO - 'HOME': '/Users/cltbld', 05:07:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:07:16 INFO - 'LOGNAME': 'cltbld', 05:07:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:07:16 INFO - 'MOZ_NO_REMOTE': '1', 05:07:16 INFO - 'NO_EM_RESTART': '1', 05:07:16 INFO - 'PAGER': '/bin/cat', 05:07:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:07:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:07:16 INFO - 'PWD': '/builds/slave/test', 05:07:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:07:16 INFO - 'SHELL': '/bin/bash', 05:07:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.vc1iQUwSdk/Listeners', 05:07:16 INFO - 'TMPDIR': '/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/', 05:07:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:07:16 INFO - 'USER': 'cltbld', 05:07:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:07:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:07:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:07:16 INFO - 'XPC_FLAGS': '0x0', 05:07:16 INFO - 'XPC_SERVICE_NAME': '0', 05:07:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:07:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:07:16 INFO - Downloading/unpacking blobuploader==1.2.4 05:07:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:07:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:07:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:07:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:07:17 INFO - Downloading blobuploader-1.2.4.tar.gz 05:07:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:07:18 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:07:18 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 05:07:18 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 05:07:18 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 05:07:18 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 05:07:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:07:18 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:07:18 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 05:07:18 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 05:07:18 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 05:07:18 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 05:07:18 INFO - Downloading docopt-0.6.1.tar.gz 05:07:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:07:18 INFO - Installing collected packages: blobuploader, requests, docopt 05:07:18 INFO - Running setup.py install for blobuploader 05:07:19 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:07:19 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:07:19 INFO - Running setup.py install for requests 05:07:19 INFO - Running setup.py install for docopt 05:07:19 INFO - Successfully installed blobuploader requests docopt 05:07:19 INFO - Cleaning up... 05:07:19 INFO - Return code: 0 05:07:19 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:07:19 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')]} 05:07:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:07:19 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')]} 05:07:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:07:19 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 0x10a055b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a059768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd63b49e8c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109d65d30>, '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 0x109d19db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109d5abe8>, '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.ytbCQnQzGm/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.vc1iQUwSdk/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/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 05:07:19 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 05:07:19 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 05:07:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ytbCQnQzGm/Render', 05:07:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:07:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:07:19 INFO - 'HOME': '/Users/cltbld', 05:07:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:07:19 INFO - 'LOGNAME': 'cltbld', 05:07:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:07:19 INFO - 'MOZ_NO_REMOTE': '1', 05:07:19 INFO - 'NO_EM_RESTART': '1', 05:07:19 INFO - 'PAGER': '/bin/cat', 05:07:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:07:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:07:19 INFO - 'PWD': '/builds/slave/test', 05:07:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:07:19 INFO - 'SHELL': '/bin/bash', 05:07:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.vc1iQUwSdk/Listeners', 05:07:19 INFO - 'TMPDIR': '/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/', 05:07:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:07:19 INFO - 'USER': 'cltbld', 05:07:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:07:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:07:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:07:19 INFO - 'XPC_FLAGS': '0x0', 05:07:19 INFO - 'XPC_SERVICE_NAME': '0', 05:07:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:07:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:07:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:07:20 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-v9h1Yy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:07:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:07:20 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-iF01RL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:07:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:07:20 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-kwUETf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:07:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:07:20 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-LSb2gv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:07:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:07:20 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-lhadrj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:07:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:07:20 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-P9y7kI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:07:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:07:20 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-1wie0j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:07:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:07:20 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-LDO2rn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:07:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:07:20 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-ogQbQB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:07:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:07:20 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-kmOeFN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:07:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:07:21 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-5lYPHf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:07:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:07:21 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-Bf8nEB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:07:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:07:21 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-Mxnqrp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:07:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:07:21 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-6sMcTZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:07:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:07:21 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-mMGwJU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:07:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:07:21 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-nx5bpr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:07:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:07:21 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-65NcQ6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:07:21 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:07:21 INFO - Running setup.py install for manifestparser 05:07:21 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:07:21 INFO - Running setup.py install for mozcrash 05:07:22 INFO - Running setup.py install for mozdebug 05:07:22 INFO - Running setup.py install for mozdevice 05:07:22 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:07:22 INFO - Running setup.py install for mozfile 05:07:22 INFO - Running setup.py install for mozhttpd 05:07:22 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:07:22 INFO - Running setup.py install for mozinfo 05:07:22 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:07:22 INFO - Running setup.py install for mozInstall 05:07:22 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:07:22 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:07:22 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:07:22 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:07:22 INFO - Running setup.py install for mozleak 05:07:23 INFO - Running setup.py install for mozlog 05:07:23 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:07:23 INFO - Running setup.py install for moznetwork 05:07:23 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:07:23 INFO - Running setup.py install for mozprocess 05:07:23 INFO - Running setup.py install for mozprofile 05:07:23 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:07:23 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:07:23 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:07:23 INFO - Running setup.py install for mozrunner 05:07:23 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:07:23 INFO - Running setup.py install for mozscreenshot 05:07:24 INFO - Running setup.py install for moztest 05:07:24 INFO - Running setup.py install for mozversion 05:07:24 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:07:24 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:07:24 INFO - Cleaning up... 05:07:24 INFO - Return code: 0 05:07:24 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:07: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')]} 05:07:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:07: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')]} 05:07:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:07:24 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 0x10a055b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a059768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd63b49e8c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109d65d30>, '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 0x109d19db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109d5abe8>, '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.ytbCQnQzGm/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.vc1iQUwSdk/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/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 05:07:24 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 05:07:24 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 05:07:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ytbCQnQzGm/Render', 05:07:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:07:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:07:24 INFO - 'HOME': '/Users/cltbld', 05:07:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:07:24 INFO - 'LOGNAME': 'cltbld', 05:07:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:07:24 INFO - 'MOZ_NO_REMOTE': '1', 05:07:24 INFO - 'NO_EM_RESTART': '1', 05:07:24 INFO - 'PAGER': '/bin/cat', 05:07:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:07:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:07:24 INFO - 'PWD': '/builds/slave/test', 05:07:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:07:24 INFO - 'SHELL': '/bin/bash', 05:07:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.vc1iQUwSdk/Listeners', 05:07:24 INFO - 'TMPDIR': '/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/', 05:07:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:07:24 INFO - 'USER': 'cltbld', 05:07:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:07:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:07:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:07:24 INFO - 'XPC_FLAGS': '0x0', 05:07:24 INFO - 'XPC_SERVICE_NAME': '0', 05:07:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:07:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:07:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:07:24 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-DTn3mb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:07:24 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)) 05:07:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:07:24 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-KAWdaA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:07:24 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)) 05:07:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:07:24 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-dNstMZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:07:24 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)) 05:07:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:07:24 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-D9TRN7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:07:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.50 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:07:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:07:25 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-V3C02O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:07:25 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)) 05:07:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:07:25 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-bvnr9w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:07:25 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)) 05:07:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:07:25 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-61g5Cv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:07:25 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)) 05:07:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:07:25 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-U3BjCD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:07:25 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)) 05:07:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:07:25 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-8tF30y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:07:25 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)) 05:07:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:07:25 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-eNBYkO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:07:25 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)) 05:07:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:07:25 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-fqKVsT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:07:25 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)) 05:07:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:07:25 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-GvisDy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:07:25 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)) 05:07:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:07:25 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-nqADT7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:07:26 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)) 05:07:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:07:26 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-dwXNSN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:07:26 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)) 05:07:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:07:26 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-R6lfD6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:07:26 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)) 05:07:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:07:26 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-rL3PQl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:07:26 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)) 05:07:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:07:26 INFO - Running setup.py (path:/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/pip-w0tYB3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:07:26 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)) 05:07:26 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)) 05:07:26 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)) 05:07:26 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)) 05:07:26 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.50->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:07:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.50->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:07:26 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:07:26 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 05:07:26 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 05:07:26 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 05:07:26 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 05:07:27 INFO - Downloading blessings-1.6.tar.gz 05:07:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:07:27 INFO - Installing collected packages: blessings 05:07:27 INFO - Running setup.py install for blessings 05:07:28 INFO - Successfully installed blessings 05:07:28 INFO - Cleaning up... 05:07:28 INFO - Return code: 0 05:07:28 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:07:28 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:07:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:07:28 INFO - Reading from file tmpfile_stdout 05:07:28 INFO - Current package versions: 05:07:28 INFO - blessings == 1.6 05:07:28 INFO - blobuploader == 1.2.4 05:07:28 INFO - docopt == 0.6.1 05:07:28 INFO - functools32 == 3.2.3-2 05:07:28 INFO - jsonschema == 2.5.1 05:07:28 INFO - manifestparser == 1.1 05:07:28 INFO - mozInstall == 1.12 05:07:28 INFO - mozcrash == 1.0 05:07:28 INFO - mozdebug == 0.1 05:07:28 INFO - mozdevice == 0.50 05:07:28 INFO - mozfile == 1.2 05:07:28 INFO - mozhttpd == 0.7 05:07:28 INFO - mozinfo == 0.9 05:07:28 INFO - mozleak == 0.1 05:07:28 INFO - mozlog == 3.4 05:07:28 INFO - moznetwork == 0.27 05:07:28 INFO - mozprocess == 0.25 05:07:28 INFO - mozprofile == 0.28 05:07:28 INFO - mozrunner == 6.13 05:07:28 INFO - mozscreenshot == 0.1 05:07:28 INFO - mozsystemmonitor == 0.3 05:07:28 INFO - moztest == 0.8 05:07:28 INFO - mozversion == 1.4 05:07:28 INFO - psutil == 3.1.1 05:07:28 INFO - requests == 1.2.3 05:07:28 INFO - wsgiref == 0.1.2 05:07:28 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:07:28 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')]} 05:07:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:07:28 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')]} 05:07:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:07:28 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 0x10a055b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a059768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd63b49e8c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109d65d30>, '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 0x109d19db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109d5abe8>, '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.ytbCQnQzGm/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.vc1iQUwSdk/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/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 05:07:28 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 05:07:28 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 05:07:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ytbCQnQzGm/Render', 05:07:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:07:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:07:28 INFO - 'HOME': '/Users/cltbld', 05:07:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:07:28 INFO - 'LOGNAME': 'cltbld', 05:07:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:07:28 INFO - 'MOZ_NO_REMOTE': '1', 05:07:28 INFO - 'NO_EM_RESTART': '1', 05:07:28 INFO - 'PAGER': '/bin/cat', 05:07:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:07:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:07:28 INFO - 'PWD': '/builds/slave/test', 05:07:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:07:28 INFO - 'SHELL': '/bin/bash', 05:07:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.vc1iQUwSdk/Listeners', 05:07:28 INFO - 'TMPDIR': '/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/', 05:07:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:07:28 INFO - 'USER': 'cltbld', 05:07:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:07:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:07:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:07:28 INFO - 'XPC_FLAGS': '0x0', 05:07:28 INFO - 'XPC_SERVICE_NAME': '0', 05:07:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:07:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:07:28 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)) 05:07:28 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)) 05:07:28 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)) 05:07:28 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)) 05:07:28 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)) 05:07:28 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)) 05:07:28 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)) 05:07:28 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)) 05:07:28 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)) 05:07:28 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)) 05:07:28 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)) 05:07:28 INFO - Cleaning up... 05:07:28 INFO - Return code: 0 05:07:28 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:07:28 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')]} 05:07:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:07:28 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')]} 05:07:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:07:28 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 0x10a055b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a059768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd63b49e8c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109d65d30>, '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 0x109d19db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109d5abe8>, '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.ytbCQnQzGm/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.vc1iQUwSdk/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/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 05:07:28 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 05:07:28 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 05:07:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ytbCQnQzGm/Render', 05:07:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:07:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:07:28 INFO - 'HOME': '/Users/cltbld', 05:07:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:07:28 INFO - 'LOGNAME': 'cltbld', 05:07:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:07:28 INFO - 'MOZ_NO_REMOTE': '1', 05:07:28 INFO - 'NO_EM_RESTART': '1', 05:07:28 INFO - 'PAGER': '/bin/cat', 05:07:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:07:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:07:28 INFO - 'PWD': '/builds/slave/test', 05:07:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:07:28 INFO - 'SHELL': '/bin/bash', 05:07:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.vc1iQUwSdk/Listeners', 05:07:28 INFO - 'TMPDIR': '/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/', 05:07:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:07:28 INFO - 'USER': 'cltbld', 05:07:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:07:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:07:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:07:28 INFO - 'XPC_FLAGS': '0x0', 05:07:28 INFO - 'XPC_SERVICE_NAME': '0', 05:07:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:07:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:07:29 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 05:07:29 INFO - Cleaning up... 05:07:29 INFO - Return code: 0 05:07:29 INFO - Running post-action listener: _resource_record_post_action 05:07:29 INFO - Running post-action listener: _start_resource_monitoring 05:07:29 INFO - Starting resource monitoring. 05:07:29 INFO - [mozharness: 2017-05-22 12:07:29.044733Z] Finished create-virtualenv step (success) 05:07:29 INFO - [mozharness: 2017-05-22 12:07:29.045735Z] Running install step. 05:07:29 INFO - Running pre-action listener: _resource_record_pre_action 05:07:29 INFO - Running main action method: install 05:07:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:07:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:07:29 INFO - Reading from file tmpfile_stdout 05:07:29 INFO - Detecting whether we're running mozinstall >=1.0... 05:07:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:07:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:07:29 INFO - Reading from file tmpfile_stdout 05:07:29 INFO - Output received: 05:07:29 INFO - Usage: mozinstall [options] installer 05:07:29 INFO - Options: 05:07:29 INFO - -h, --help show this help message and exit 05:07:29 INFO - -d DEST, --destination=DEST 05:07:29 INFO - Directory to install application into. [default: 05:07:29 INFO - "/builds/slave/test"] 05:07:29 INFO - --app=APP Application being installed. [default: firefox] 05:07:29 INFO - mkdir: /builds/slave/test/build/application 05:07:29 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'] 05:07:29 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 05:07:50 INFO - Reading from file tmpfile_stdout 05:07:50 INFO - Output received: 05:07:50 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 05:07:50 INFO - Running post-action listener: _resource_record_post_action 05:07:50 INFO - [mozharness: 2017-05-22 12:07:50.641329Z] Finished install step (success) 05:07:50 INFO - [mozharness: 2017-05-22 12:07:50.641459Z] Running run-tests step. 05:07:50 INFO - Running pre-action listener: _resource_record_pre_action 05:07:50 INFO - Running main action method: run_tests 05:07:50 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:07:50 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:07:50 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:07:50 INFO - 2017-05-22 05:07:50.656 screenresolution[6499:115826] starting screenresolution argv=screenresolution get 05:07:50 INFO - 2017-05-22 05:07:50.659 screenresolution[6499:115826] Display 0: 1600x1200x32@60 05:07:50 INFO - 2017-05-22 05:07:50.665 screenresolution[6500:115829] starting screenresolution argv=screenresolution list 05:07:50 INFO - Available Modes on Display 0 05:07:50 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:07:50 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:07:50 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:07:50 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:07:50 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:07:50 INFO - Intel Iris: 05:07:50 INFO - Chipset Model: Intel Iris 05:07:50 INFO - Type: GPU 05:07:50 INFO - Bus: Built-In 05:07:50 INFO - VRAM (Dynamic, Max): 1536 MB 05:07:50 INFO - Vendor: Intel (0x8086) 05:07:50 INFO - Device ID: 0x0a2e 05:07:50 INFO - Revision ID: 0x0009 05:07:50 INFO - Displays: 05:07:50 INFO - Display: 05:07:50 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:07:50 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:07:50 INFO - Display Serial Number: 1600x1200 60 05:07:50 INFO - Main Display: Yes 05:07:50 INFO - Mirror: Off 05:07:50 INFO - Online: Yes 05:07:50 INFO - Rotation: Supported 05:07:50 INFO - Return code: 0 05:07:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:07:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:07:50 INFO - Python 2.7.3 05:07:50 INFO - Return code: 0 05:07:50 INFO - grabbing minidump binary from tooltool 05:07: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')]} 05:07:50 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 0x109d65d30>, '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 0x109d19db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109d5abe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:07:50 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 05:07:50 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 05:07:50 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 05:07:50 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:07:51 INFO - Return code: 0 05:07:51 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 05:07:51 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:07:51 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:07:51 INFO - ENV: RUST_BACKTRACE is now 1 05:07:51 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 05:07:51 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:07:51 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/HN_nkN2KQgqZBc02j7G2Rw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0023', '--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 05:07:51 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/HN_nkN2KQgqZBc02j7G2Rw/artifacts/public/build/target.crashreporter-symbols.zip --title t-yosemite-r7-0023 --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 05:07:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ytbCQnQzGm/Render', 05:07:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:07:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:07:51 INFO - 'HOME': '/Users/cltbld', 05:07:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:07:51 INFO - 'LOGNAME': 'cltbld', 05:07:51 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:07:51 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 05:07:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:07:51 INFO - 'MOZ_NO_REMOTE': '1', 05:07:51 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:07:51 INFO - 'NO_EM_RESTART': '1', 05:07:51 INFO - 'PAGER': '/bin/cat', 05:07:51 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:07:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:07:51 INFO - 'PWD': '/builds/slave/test', 05:07:51 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:07:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:07:51 INFO - 'RUST_BACKTRACE': '1', 05:07:51 INFO - 'SHELL': '/bin/bash', 05:07:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.vc1iQUwSdk/Listeners', 05:07:51 INFO - 'TMPDIR': '/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/', 05:07:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:07:51 INFO - 'USER': 'cltbld', 05:07:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:07:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:07:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:07:51 INFO - 'XPC_FLAGS': '0x0', 05:07:51 INFO - 'XPC_SERVICE_NAME': '0', 05:07:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:07:51 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/HN_nkN2KQgqZBc02j7G2Rw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0023', '--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 05:07:51 INFO - mozversion application_buildid: 20170522040024 05:07:51 INFO - mozversion application_changeset: b4a8c54c2b056a5df90bf2284a25c4bec75bb1be 05:07:51 INFO - mozversion application_display_name: Nightly 05:07:51 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:07:51 INFO - mozversion application_name: Firefox 05:07:51 INFO - mozversion application_remotingname: firefox 05:07:51 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 05:07:51 INFO - mozversion application_vendor: Mozilla 05:07:51 INFO - mozversion application_version: 55.0a1 05:07:51 INFO - mozversion platform_buildid: 20170522040024 05:07:51 INFO - mozversion platform_changeset: b4a8c54c2b056a5df90bf2284a25c4bec75bb1be 05:07:51 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 05:07:51 INFO - mozversion platform_version: 55.0a1 05:07:51 INFO - using testdate: 1495454871 05:07:51 INFO - actual date: 1495454871 05:07:51 INFO - starting webserver on 'localhost:56840' 05:07:51 INFO - SUITE-START | Running 2 tests 05:07:51 INFO - TEST-START | dromaeo_css 05:07:51 INFO - operating with platform_type : mac_ 05:07:51 INFO - Initialising browser for dromaeo_css test... 05:07:51 INFO - TEST-INFO | started process 6510 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/tmpH9tjB8/profile http://localhost:56840/getInfo.html) 05:07:52 INFO - PID 6510 | JavaScript warning: resource://gre/modules/addons/XPIProvider.jsm, line 2978: String.localeCompare is deprecated; use String.prototype.localeCompare instead 05:07:54 INFO - PID 6510 | 2017-05-22 05:07:54.008 plugin-container[6513:115998] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9b2f, name = 'com.apple.tsm.portname' 05:07:54 INFO - PID 6510 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:07:54 INFO - PID 6510 | 2017-05-22 05:07:54.011 plugin-container[6513:115998] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0xa303, name = 'com.apple.CFPasteboardClient' 05:07:54 INFO - PID 6510 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:07:54 INFO - PID 6510 | 2017-05-22 05:07:54.011 plugin-container[6513:115998] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 05:07:54 INFO - PID 6510 | __metrics Screen width/height:1600/1200 05:07:54 INFO - PID 6510 | colorDepth:24 05:07:54 INFO - PID 6510 | Browser inner width/height: 1024/691 05:07:54 INFO - PID 6510 | __metrics 05:07:55 INFO - TEST-INFO | 6510: exit 0 05:07:55 INFO - Browser initialized. 05:07:55 INFO - Running cycle 1/1 for dromaeo_css test... 05:07:55 INFO - TEST-INFO | started process 6516 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/tmpH9tjB8/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:07:57 INFO - PID 6516 | 2017-05-22 05:07:57.316 plugin-container[6517:116152] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9c57, name = 'com.apple.tsm.portname' 05:07:57 INFO - PID 6516 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:07:57 INFO - PID 6516 | 2017-05-22 05:07:57.317 plugin-container[6517:116152] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9e0b, name = 'com.apple.CFPasteboardClient' 05:07:57 INFO - PID 6516 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:07:57 INFO - PID 6516 | 2017-05-22 05:07:57.317 plugin-container[6517:116152] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 05:07:58 INFO - PID 6516 | 2017-05-22 05:07:58.000 plugin-container[6518:116198] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x943f, name = 'com.apple.tsm.portname' 05:07:58 INFO - PID 6516 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:07:58 INFO - PID 6516 | 2017-05-22 05:07:58.001 plugin-container[6518:116198] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9a0b, name = 'com.apple.CFPasteboardClient' 05:07:58 INFO - PID 6516 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:07:58 INFO - PID 6516 | 2017-05-22 05:07:58.001 plugin-container[6518:116198] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 05:08:07 INFO - PID 6516 | RSS: Main: 242524160 05:08:07 INFO - PID 6516 | 05:11:11 INFO - PID 6516 | Cycle 1(1): loaded http://localhost:56840/tests/dromaeo/cssquery-dojo.html (next: http://localhost:56840/tests/dromaeo/cssquery-ext.html) 05:11:12 INFO - PID 6516 | RSS: Main: 261345280 05:11:12 INFO - PID 6516 | 05:14:14 INFO - PID 6516 | Cycle 1(1): loaded http://localhost:56840/tests/dromaeo/cssquery-ext.html (next: http://localhost:56840/tests/dromaeo/cssquery-jquery.html) 05:14:14 INFO - PID 6516 | RSS: Main: 269631488 05:14:14 INFO - PID 6516 | 05:17:16 INFO - PID 6516 | Cycle 1(1): loaded http://localhost:56840/tests/dromaeo/cssquery-jquery.html (next: http://localhost:56840/tests/dromaeo/cssquery-mootools.html) 05:17:16 INFO - PID 6516 | RSS: Main: 273829888 05:17:16 INFO - PID 6516 | 05:20:18 INFO - PID 6516 | Cycle 1(1): loaded http://localhost:56840/tests/dromaeo/cssquery-mootools.html (next: http://localhost:56840/tests/dromaeo/cssquery-prototype.html) 05:20:19 INFO - PID 6516 | RSS: Main: 271716352 05:20:19 INFO - PID 6516 | 05:23:22 INFO - PID 6516 | Cycle 1(1): loaded http://localhost:56840/tests/dromaeo/cssquery-prototype.html (next: http://localhost:56840/tests/dromaeo/cssquery-yui.html) 05:23:22 INFO - PID 6516 | RSS: Main: 274321408 05:23:22 INFO - PID 6516 | 05:26:24 INFO - PID 6516 | Cycle 1(1): loaded http://localhost:56840/tests/dromaeo/cssquery-yui.html (next: http://localhost:56840/tests/dromaeo/cssquery-dojo.html) 05:26:24 INFO - PID 6516 | RSS: Main: 275501056 05:26:24 INFO - PID 6516 | 05:26:24 INFO - PID 6516 | __start_tp_report 05:26:24 INFO - PID 6516 | _x_x_mozilla_page_load 05:26:24 INFO - PID 6516 | _x_x_mozilla_page_load_details 05:26:24 INFO - PID 6516 | |i|pagename|runs| 05:26:24 INFO - PID 6516 | |0;dojo.html;3047.588575542302;3577.3918433866256;3604.5314109165806;3610.801405922673;3634.145975695711;5205.843824685261;5210.931758989403;6181.165542651741;6319.1153238546585;6331.651759153245;5553.7223138843;6129.467633091727;6287.2140982377205;6313.147725057116;6332.651704156269;5477.452254774531;5501.944980550184;5533.253010843545;5552.888942221153;5640.153976903456;5445.591580631449;5612.831615051555;5676.801311954083;5720.2849643794525;5726.1697053927155;5586.301712285965;5626.156076588503;5633.267675202218;5677.545796336304;5690.63010904292;5438.374586922515;5493.835184944458;5494.1758736189895;5542.63972841768;5571.4428557143965;4694.671373003891;5459.727013649302;5534.446555344473;5554.472325129106;5585.888282234332;5564.8330550083565;5930.673812940287;6359.395857393541;6378.4578310843635;6390.840228994266;6297.149884765589;6320.273168585625;6602.141721576212;6859.525202081527;6861.588304701684;6881.587104773729;6938.930610693879;6957.9304206958295;6963;6964.4080253178545;5367.919481207785;6409.711562979677;6426.0360945857565;6451.064595633654;6452.677366131675;6149.13912052313;6832.248452670202;6936.895946560806;7105.147382314155;7113.253108423644;6635.170603674541;6659.86680266392;6675.432588229958;6736.5958042517605;6742.696578653972;6839.794806155824;6935.479839012094;6947.027416161742;6961.825954351182;6974.68613912365;6112.837854638568;6626.900596491057;6656.8668626627195;6741.561798483083;6826.590404575642;6800.863982720318;6830.282820303896;6853.143357080365;6857.245702972669;6881.41507971818;5415.0687120485845;5427.782888684487;5432.511074003359;5454.509094181542;5464.508194262456;3538.858445662196;4787.784549695272;4854.732989685601;4875.366202393666;4911.484294149063;6345.175127233522;6393.168888044617;6404.359564043559;6412.454941330039;6441.967790161113;6911.136107986502;6927.445804335641;6960.686769095403;6961.338672826073;6971.163460384685;6383.3616638336725;6465.2564955030775;6496.675166241669;6500.967495162494;6509.967450162719;5968.910466342922;6022.337542870281;6069.908951365733;6071.423214794588;6123.693815309217;5885.3820348862755;7098.680559374738;7125.8574828502105;7127.251638578082;7132.786016419515;6860.5540639858245;6915.343042410963;6940.41006514452;6952.5480843745;6996.685149168198;6915.89626155598;6966.582005079711;6996.300369962963;7123.180834203926;7142.107236595426;6768.357006084414;6776.796696099125;6814.250432452525;6846.246912839484;6850.314968503109;6860.313968603299;6936.861262774817;6952.30476952321;6959.408450281783;6971.224711072931;5897.761470091157;6489.805305840832;6516.185476815398;6681.365270299314;6737.62943038118;6541.509386795914;6609.801705948829;6647.302033286435;6676.49926255523;6701.765438209639;6508.219013718384;6550.279469258282;6589.4728421725185;6608.471322294301;6623.172103487064;6410.935890641033;6535.052417399545;6611.041398997022;6641.601503909781;6670.733170673118;5781.335146458211;6329.7468101275435;6448.613083215022;6478.1578394808375;6526.282108967915 05:26:24 INFO - PID 6516 | |1;ext.html;669.4610838275188;795.550513959613;802.1978021978022;1169.4269807794183;1226.1478272600546;37123.81438092809;38978;39019.82940511782;39026.41460378097;39127.60872391279;39376.01559961002;39678.0080497987;39690.404643930364;39776.602233977654;39932.201355972866;6671.266160722316;7163.283671632821;7759.689612415496;8290.253877151055;8509.48943063415;8447.831043379128;8491.490510569385;8513.106123856998;8565.186307300828;8571.885654864858;8508.106648801879;8546.188112129337;8589.35579831512;8589.61346739395;8597.656093756243;8504.617292221865;8527.18991695788;8551.358648101355;8554.52950087745;8557.828843423158;8251.793705157359;8314.70898518555;8335.583220838933;8340.29107525861;8342.207490288412;29862;36743.63256367429;36983.445248321295;36994;37017;3625.075605720551;3643.41705327149;3664.157243833933;3666.138457462494;3670.3576874047203;27550.862245688844;29234.830606775704;30603.387932241454;30656;30765;3118.8284644344553;3136.9686303136905;3153.0225630054756;3153.4008538377634;3167.129039514152;253963;255096;268471;305794;308373;45840;46130.077398451846;46200.7689961548;46237.38169164063;46264.53735462669;47400;47622.76188619035;47928.76035619869;48019.51980480151;48086.759566201945;13926.30368481592;13987.37056832445;14027.088239264414;14037;14110.294485275901;10237.283390162765;11332.773344533063;11436.428178591037;11486.885131148749;11502.482388292547;12110;12182;13250.933745331211;13264.336783160803;13283.335833208494;5835.914859598298;5859.912101318484;5873.236479257755;5873.706314684248;5958.344582095966;906.5104843384498;907.9409838360617;1017.7760892603616;1038.5845661735366;1075.870895492546;17686.469405917844;22900.31299061031;24102.638460423106;24280.63579046316;24280.75719242785;883.4919921045424;1004.2668851738272;1116.2855772305732;1124.9887501125047;1165.2891736041008;10210.846837297446;11710.180287379972;11850.644480665595;12376.62870113898;12383.823536763994;317139;318422;321221;321919;322087;52189.7390513045;52397.7380113097;52656.47107053781;52661;52741;1529.6328881068464;1533.8159420869345;1573.103331101284;1576.929038193284;1583.8812089093365;23927.521449570562;24164.516709665357;24177.879110604335;24185.63721544143;24286.878565607058;37181.44227836639;37328.813355933045;37453.4381984265;37571.2485750278;37626.81186594049;278686;279128;279336;279601;279848;32974.67025329812;33239.83660571869;33325.66674333226;33404;33455;41479.96300092522;43038.35442468303;43320;43353.56646433495;43413.565864340955;22968;23105.88447057754;25545.61681574815;27263.727362726913;30746.079122483327;20114.59770804605;24749.876250619353;30414.23964400819;30554.84722576373;30758.846205768827;35883.64116358803;36793.448098278015;37058.8147059263;37204.25591488101;37396.252074958895;30118.397632047676;30880.845595771876;30905.69094309118;31129.37741245208;31202.375952481278;22941;24111.276661700176;24304.635430468203;24341.803229605128;24495 05:26:24 INFO - PID 6516 | |2;jquery.html;380.9942850857237;428.8456155783915;436.10815881522285;441.22564898602946;456.93831332770066;16983.745243821322;19294.517637059063;20899.26852560161;21028.474288142766;21646.89176554117;18484.353047643333;22078;22450.88774556129;22698.54602907945;23683.81580920956;5792.71036448178;6072.483838873701;6124.418180272889;6125.601835880676;6134.355892631276;5838.883222335551;5931.6441013539325;6018.759249630009;6023.3976602339635;6028.367021462749;5942.524598032169;5969.850753731148;5976.2230909981645;5976.5517586180995;5987.850303742398;5880.7353669085;5910.852228694295;5992.460678538909;6023.397660233942;6028.788992385245;5276.498732620395;5344.278522399465;5353.625246232766;5354.598405119593;5365.651232669864;26434.206973790817;26686.33284167892;26719.73280267211;26897.86551067232;26958.056468023526;2121.6711409731515;2127.8297736181066;2141.946451338729;2151.655735082395;2176.5864485747816;16307.755383669255;17944.641107177915;18358.26566937321;18364.63270734578;18383.632327353513;2129.5740851829696;2142.2930432957087;2142.389419015589;2166.804987551128;2171.9674204886937;103464.99999999924;119439;139312;140595;141660.58339416576;12040.81938770919;12336.136470446983;12410.193337433038;12421.323121378678;12484.75030499385;13120.540781072808;15721.921390392974;15910.681786364208;15916.283767230503;15937.601559960862;7348.412127029825;8522.36082293818;8734.126587341216;8761.474311541328;8865.423747456523;8359.289460395812;8387.119352468026;8459.450135741157;8542.658293668308;8550;6402.423781859655;7640.350570201484;7704.075510938723;7793.337566306814;7833.764987050397;1907.484979055675;1960.451073699338;2228.3871935217944;2251.8311126665567;2253.5943530164727;973.78576713123;980.3970558106713;988.619381538113;998.6804222648828;1027.2244455436098;20378.286759963332;20737.377878663665;20841.062152203187;20846;21109.68335474969;992.3450522655013;1001.834697274956;1022.3201570955232;1027.712240572641;1042.4891803016467;10305.639302624522;10724.302920310154;10800.891991080145;10811.35131892089;10824.67525974222;497368.5131574319;498713;502338.8128949675;508236;510659;114152.42923785494;118928;121572;130756;136491.31754341166;1201.6094769200045;1237.2823762217893;1238.0219626495148;1261.1298204239229;1261.211742660837;19274.61450771005;21334.946360191512;22237.888810555844;22274;22403.887980559994;57403.42596573981;57513.42486575248;57854;58696;58977.70511147417;77335;77917.220827791;78908;79278;79310;7217.458690598121;8668.263197628272;8684.478931264144;8782.253508451853;9029.593668285075;13748.862511375159;14283.928580357031;14403.711925761636;14543.345549450512;14715.190664513555;11840.467178976794;12087.576934807239;12209;12210.145289829627;12251.9585835205;10860.891391086305;11338.979491845774;11498.77002459963;11567.942160289145;11952.64142075739;11812.940935295268;12132.636020919386;12195.939020304842;12223.022158227142;12282.201656892274;7419.517731347445;8689.826203476021;8846.690365837165;8886.688965886164;8977.147171018743;11614.88385116138;11812.645620631394;11916.821247681464;12028.63914082579;12075.215111017756 05:26:24 INFO - PID 6516 | |3;mootools.html;1422.6656735667118;1472.8748056415204;1493.1563666528411;1499.5276487906308;1503.4662694743365;10869.347839129658;11364.431778411077;11417.771644567114;11660.41697915105;12180.512779488832;11675.708107297321;11770;11804.055675545957;11919.463624136913;11927.403629818518;2497.2757900208917;2517.0435234610914;2535.467551814121;2539.987300063497;2546.032507647101;2463.9137630182945;2507.987460062697;2510.924672259835;2514.245726282117;2514.6856642919633;2482.1684735613503;2511.962320565193;2527.5324065048026;2534.4550921551863;2537.175417989152;2029.4824819670964;2479.5536803375476;2492.538880307149;2499.5002998201116;2503.9374015649573;2448.179859746987;2475.0471068638703;2484.8757562121823;2499.950000999988;2523.570992931188;11553.480093395818;11593.884061159364;11627.302361858316;11656.475458604298;11672.649820505398;6262.091996660459;6470.061841033071;6479.870402591969;6484.059811327378;6507.967460162717;3727.8136093195503;3943.6253555912144;3945.546262179829;4119.402686610455;4181.958180418218;1014.9086582207636;1248.4881198708485;1277.9840027178416;1285.768561658901;1297.9935100324437;15309.693806123927;15742.685146297124;15890.443834465737;15919.92040039804;16094.75857862133;4590.311453281967;4707.552782485658;5141.511556402136;5316.388615309212;5359.330083739533;9291.070892910655;11493;11495;11524.020458260975;11530.654080377602;5633.436656334334;5953;6294.559380843389;6326;6344.777932772331;5910.970445147833;5912.506495063696;6000.939990600125;6009.06859436788;6114.2662880453745;4204.600562946515;4279.507856596531;4312.956870431255;4326.956730432718;4335.111302183045;1122.9556512443505;1232.211384713784;1242.0932719115096;1244.1974926172568;1290.9285293206617;803.2289002557493;804.2681160144241;831.1771346367053;844.8563744163507;866.3242670716851;5006.973570418056;6119.5410344224365;6195.566310358232;6196.876062478816;6321.65230912295;522.7334059629617;592.6414519215813;592.69476219746;605.4097255176168;622.8567429491243;3785.621437856192;3822.445745366906;3834.654881060718;3869.916423401452;4133.958660413357;13555.932220339033;14898.776518352233;14977.176255306063;15013;15018.249087545579;10465.47672616366;14290.49983250602;14423.567292981228;14661.780073298909;14939.925300373428;6805.591664500146;7220.60921707731;8529.914700852913;8562.357823163164;8596.355273354524;6228.750849965951;6229.688515574294;6630.448816629592;6827.829304267433;6971.093757811452;12738.172018818746;12843.935780321039;13305.66735831612;13371.06402551812;13454.66363340885;10852.945735271272;13085;13155.802662959872;13176.670583235496;13180.275084870424;7954.681812727426;8048.315893149148;8066.395020373378;8067.515949043076;8069.878951815845;8103;10233.795324093328;10320.741981450525;10564.788704226026;10637.787244255227;9010.774730631787;9081.273498120267;9140.725778226664;9161.404508706913;9192.402493837879;8822.294216462538;8859.557022148738;8919.73240802777;8962.910370896469;9043.366964312698;8016.398770092383;8039.67841286342;8115.959420202861;8130.593470326342;8155.388345874202;5605.943940560543;5890;5971.641701497924;6084.90872636902;6136.20229370179;5987.670678112571;6137.693115344304;6145.784897528565;6163.445289923929;6345.777897773556 05:26:24 INFO - PID 6516 | |4;prototype.html;341.21009862169075;397.9940300895485;398.6392314954967;402.2879503279196;409.3123552431913;5168.508991645793;6062.635572126874;6079.848003799902;6224.502039836813;6396.592749595094;5096.4648711885275;6109.572329936895;6223.284322302937;6251.2498500180045;6256.743256743257;3981.3231750602467;4311.504177019636;4514.638828893689;4553.726776393411;4573.108243892442;4001.75989440633;4135.421041054255;4414.668899832509;4566.771661416932;4613.907721845561;3991.560928297885;4534.523874993128;4543.204939135655;4546.227141385956;4548.931766023512;4309.762963037032;4480;4508.6618503612035;4542.750148741818;4558.544145585448;3831.6934645228316;3848.807559622008;3858.9228215435814;3871.477350557667;3891.3384724596604;4841.8547443576745;6133.957227271375;6142.201513803177;6147.569670123094;6177.289611694668;4406.272964960777;5202.829363393244;5410.702411367373;5455.454454554552;5466.851961088176;5358.4641535846495;5969.791057313017;6520.739170433177;6677.966610166918;6742.314421394652;4386.188555117282;4390.675184942828;4409.200157899725;4417.558244175589;4422.601965823092;5195.844124676228;6547.083408322838;6898.413634841045;6964.930350696479;7271.20016798157;3917;4454.532274111237;4458;4476.171908196994;4505.639548836085;4547.454305483363;4558.156741002925;4558.3162525620755;4566.49768525462;4588.839390621312;5272.92090618641;5364.91952620711;5407.810726624628;5444.265024221759;5454.7272636368025;6459.223713478831;6762.459003279726;6779.694913728894;6796.524243302922;6799.218089919624;1250.6998320403038;1308.5616318533207;1385.752822459774;1551.015105408074;1566.9971218420221;994.4033579852031;997.1792936315492;1003.8996100389902;1008.445355054717;1020.4642562654666;5494.780208791683;5890.734916928748;6402.903956440658;6419.679016049179;6436.967815160894;5962.612430192024;6041.2750469943885;6911.9308806912295;6981.162260528769;7042.190148133031;5338.839834804962;5835.883282334329;5894.528437724972;6379.947308694106;6383.234011918506;3198.4722520784276;3616.6437585905287;3660.6888414485065;3666.633336666312;3677.099110717891;5397.838064857982;5931.377205393458;6283.6858157091965;7159.8568028640175;7271.800662526319;6177.69111544421;6743.325667433315;6829.863402732017;7214.531055481377;7254.492185546961;6858.965705171442;6924.822780127391;6936.618485983218;7265.837466005414;7271.454640901848;5491;6975.988806436421;7002.474814388839;7033.788986330418;7051.894221586783;5542.533572560172;5631;6049.637021778707;6741.943707169165;6980.755673551402;6348.714307856065;6638.435732962559;6914.8617027660175;7302.3792977597495;7359.77920662381;3732.4587934748765;4437.556244375537;4460.553944605514;4467.687261891637;4481.148581769454;6745.494087943326;6901;7018.5788852669;7035.366816986496;7104.928950710427;5126.948730512647;5215.5827533797965;5699.487046165865;6645.903425934666;6804.897926531007;6504.544681872224;6663.000549917551;6752.426043786137;6753.527253092237;6772;6110.358412366815;6627.276908003857;6648.16897887764;6662.83342916431;6673.466122710269;6619.238787539494;6658.5671931324305;6749.527533072835;6787.966060169668;6839.179298484214;6276.811695649138;6282.937170628235;6586.509150150428;6600.669966501559;6611.504137189826 05:26:24 INFO - PID 6516 | |5;yui.html;788.5662885413021;829.3033851564685;841.9579021048947;859.7034023261972;865.2083343740478;16300.755488667664;16666.166691665414;16745.33018679253;18652.160652770624;19376.128074236658;16293.348266069373;16433.42483013095;17290.308387664478;18089.45731628053;18196.363127290548;1003.9548220330084;1036.2073014144187;1037.289456722144;1039.7192757955347;1044.1542350695922;1037.9931466477506;1042.5204405973261;1043.5512729526301;1045.007243119036;1045.7542477517777;1082.1613249731442;1087.8585783848089;1094.6168840905698;1100.0759362135805;1100.9229353945227;1083.7290677330627;1090.2967585907086;1094.7482079121808;1094.7591529863416;1095.9259925273277;773.3928865840288;788.3968763895624;794.2375319693101;810.440795850867;812.7521106062649;16581.502554923372;16594.83405165945;16700.66598668032;16742.497725068268;16937.491875243762;17032.148392580322;17159.313627454885;17395.1302434879;17477.825221747746;17543.122843857884;13931.721365572632;14083.929580352033;14435.927820360936;14458.277086145756;14479.927600362036;3289.5723555937816;3327.600687917441;3364.9831750841336;3367.461206206995;3379.493076038589;108126.3781043285;119580;120034;120888;121268;13704.65738356549;13803.654908627364;13816.96372772026;13820.032597718064;13858.930705346409;20786.48033799167;24858.751412486006;24905.875470622894;25407.856646450953;25584.23247302584;6075.33171351151;7896.644650990719;8070;8074.878876816852;8081.232282933112;3952.565217826037;3968.523777146703;4005.1589166274825;4025.677945764332;4028.4561584186345;4329.696921215547;4348.891277718082;4451.77741112943;4452.755098469615;4456.353828694886;912.8083102548406;926.9397489163183;946.068122898944;948.9051094890593;949.2074118111316;648.8410339466861;672.1396612336217;675.7263308360122;678.725116327888;688.3357559954668;11050.613228536962;11802.996745276576;12008.159428839897;12011.498562679664;12181.147319687538;582.6911736779514;640.7789312687115;646.1567654211206;649.4966401039138;655.0959675647575;5354.063038968165;5517.5585953123655;5570.969370666439;5648.943510564924;5752.453516915886;99242.5037874806;105354.47322763337;106238.46880765547;106483.46758266314;108740;90949.54525227463;91778.54110729403;93281;94781;95603.56594651086;59641.105383419286;78179.05493231065;80258;80724;81834.13580524654;12086.033117350767;12773.36133193318;12820.423080961193;12828.679283017625;12842.486300547873;32998.83500582578;38878;39375.40936885892;40448.79775601221;40889;47866;53826.73086634542;73728.63135684287;73951.26048739444;74972.62513687396;16012.679746404774;16121.838781612503;16171.353145874504;16221.837781622033;16339;16993.74509382385;17443.651126977642;17447.38934137299;17499.475015749547;17560.82439175592;15904.204789760235;16104.67790644204;16762.748558771385;16812;16824.74762878533;15813.05121692702;16455.177241138135;17026.063566503963;17260.82739172592;17286.394976175772;12738.936305318413;16326.346946122023;16668.249928752994;16711.415100471426;17000.234989425753;11038.779224415628;11411.25826821254;11652.825207622061;12038.157328987238;12102.757944840878;12652.17760845542;12715;13375.264360460269;13484.86515134836;14020.579382618538 05:26:24 INFO - PID 6516 | __end_tp_report 05:26:24 INFO - PID 6516 | __start_cc_report 05:26:24 INFO - PID 6516 | _x_x_mozilla_cycle_collect,341 05:26:24 INFO - PID 6516 | __end_cc_report 05:26:24 INFO - PID 6516 | __startTimestamp1495455984604__endTimestamp 05:26:24 INFO - PID 6516 | 05:26:24 INFO - PID 6516 | ------- Summary: start ------- 05:26:24 INFO - PID 6516 | Number of tests: 6 05:26:24 INFO - PID 6516 | 05:26:24 INFO - PID 6516 | [#0] dojo.html Cycles:165 Average:6241.54 Median:6478.16 stddev:797.93 (12.3%) stddev-sans-first:760.02 05:26:24 INFO - PID 6516 | Values: 3047.6 3577.4 3604.5 3610.8 3634.1 5205.8 5210.9 6181.2 6319.1 6331.7 5553.7 6129.5 6287.2 6313.1 6332.7 5477.5 5501.9 5533.3 5552.9 5640.2 5445.6 5612.8 5676.8 5720.3 5726.2 5586.3 5626.2 5633.3 5677.5 5690.6 5438.4 5493.8 5494.2 5542.6 5571.4 4694.7 5459.7 5534.4 5554.5 5585.9 5564.8 5930.7 6359.4 6378.5 6390.8 6297.1 6320.3 6602.1 6859.5 6861.6 6881.6 6938.9 6957.9 6963.0 6964.4 5367.9 6409.7 6426.0 6451.1 6452.7 6149.1 6832.2 6936.9 7105.1 7113.3 6635.2 6659.9 6675.4 6736.6 6742.7 6839.8 6935.5 6947.0 6961.8 6974.7 6112.8 6626.9 6656.9 6741.6 6826.6 6800.9 6830.3 6853.1 6857.2 6881.4 5415.1 5427.8 5432.5 5454.5 5464.5 3538.9 4787.8 4854.7 4875.4 4911.5 6345.2 6393.2 6404.4 6412.5 6442.0 6911.1 6927.4 6960.7 6961.3 6971.2 6383.4 6465.3 6496.7 6501.0 6510.0 5968.9 6022.3 6069.9 6071.4 6123.7 5885.4 7098.7 7125.9 7127.3 7132.8 6860.6 6915.3 6940.4 6952.5 6996.7 6915.9 6966.6 6996.3 7123.2 7142.1 6768.4 6776.8 6814.3 6846.2 6850.3 6860.3 6936.9 6952.3 6959.4 6971.2 5897.8 6489.8 6516.2 6681.4 6737.6 6541.5 6609.8 6647.3 6676.5 6701.8 6508.2 6550.3 6589.5 6608.5 6623.2 6410.9 6535.1 6611.0 6641.6 6670.7 5781.3 6329.7 6448.6 6478.2 6526.3 05:26:24 INFO - PID 6516 | 05:26:24 INFO - PID 6516 | [#1] ext.html Cycles:180 Average:43942.50 Median:33107.25 stddev:77004.97 (232.6%) stddev-sans-first:77152.44 05:26:24 INFO - PID 6516 | Values: 669.5 795.6 802.2 1169.4 1226.1 37123.8 38978.0 39019.8 39026.4 39127.6 39376.0 39678.0 39690.4 39776.6 39932.2 6671.3 7163.3 7759.7 8290.3 8509.5 8447.8 8491.5 8513.1 8565.2 8571.9 8508.1 8546.2 8589.4 8589.6 8597.7 8504.6 8527.2 8551.4 8554.5 8557.8 8251.8 8314.7 8335.6 8340.3 8342.2 29862.0 36743.6 36983.4 36994.0 37017.0 3625.1 3643.4 3664.2 3666.1 3670.4 27550.9 29234.8 30603.4 30656.0 30765.0 3118.8 3137.0 3153.0 3153.4 3167.1 253963.0 255096.0 268471.0 305794.0 308373.0 45840.0 46130.1 46200.8 46237.4 46264.5 47400.0 47622.8 47928.8 48019.5 48086.8 13926.3 13987.4 14027.1 14037.0 14110.3 10237.3 11332.8 11436.4 11486.9 11502.5 12110.0 12182.0 13250.9 13264.3 13283.3 5835.9 5859.9 5873.2 5873.7 5958.3 906.5 907.9 1017.8 1038.6 1075.9 17686.5 22900.3 24102.6 24280.6 24280.8 883.5 1004.3 1116.3 1125.0 1165.3 10210.8 11710.2 11850.6 12376.6 12383.8 317139.0 318422.0 321221.0 321919.0 322087.0 52189.7 52397.7 52656.5 52661.0 52741.0 1529.6 1533.8 1573.1 1576.9 1583.9 23927.5 24164.5 24177.9 24185.6 24286.9 37181.4 37328.8 37453.4 37571.2 37626.8 278686.0 279128.0 279336.0 279601.0 279848.0 32974.7 33239.8 33325.7 33404.0 33455.0 41480.0 43038.4 43320.0 43353.6 43413.6 22968.0 23105.9 25545.6 27263.7 30746.1 20114.6 24749.9 30414.2 30554.8 30758.8 35883.6 36793.4 37058.8 37204.3 37396.3 30118.4 30880.8 30905.7 31129.4 31202.4 22941.0 24111.3 24304.6 24341.8 24495.0 05:26:24 INFO - PID 6516 | 05:26:24 INFO - PID 6516 | [#2] jquery.html Cycles:180 Average:33422.50 Median:22157.94 stddev:85193.88 (384.5%) stddev-sans-first:85396.75 05:26:24 INFO - PID 6516 | Values: 381.0 428.8 436.1 441.2 456.9 16983.7 19294.5 20899.3 21028.5 21646.9 18484.4 22078.0 22450.9 22698.5 23683.8 5792.7 6072.5 6124.4 6125.6 6134.4 5838.9 5931.6 6018.8 6023.4 6028.4 5942.5 5969.9 5976.2 5976.6 5987.9 5880.7 5910.9 5992.5 6023.4 6028.8 5276.5 5344.3 5353.6 5354.6 5365.7 26434.2 26686.3 26719.7 26897.9 26958.1 2121.7 2127.8 2141.9 2151.7 2176.6 16307.8 17944.6 18358.3 18364.6 18383.6 2129.6 2142.3 2142.4 2166.8 2172.0 103465.0 119439.0 139312.0 140595.0 141660.6 12040.8 12336.1 12410.2 12421.3 12484.8 13120.5 15721.9 15910.7 15916.3 15937.6 7348.4 8522.4 8734.1 8761.5 8865.4 8359.3 8387.1 8459.5 8542.7 8550.0 6402.4 7640.4 7704.1 7793.3 7833.8 1907.5 1960.5 2228.4 2251.8 2253.6 973.8 980.4 988.6 998.7 1027.2 20378.3 20737.4 20841.1 20846.0 21109.7 992.3 1001.8 1022.3 1027.7 1042.5 10305.6 10724.3 10800.9 10811.4 10824.7 497368.5 498713.0 502338.8 508236.0 510659.0 114152.4 118928.0 121572.0 130756.0 136491.3 1201.6 1237.3 1238.0 1261.1 1261.2 19274.6 21334.9 22237.9 22274.0 22403.9 57403.4 57513.4 57854.0 58696.0 58977.7 77335.0 77917.2 78908.0 79278.0 79310.0 7217.5 8668.3 8684.5 8782.3 9029.6 13748.9 14283.9 14403.7 14543.3 14715.2 11840.5 12087.6 12209.0 12210.1 12252.0 10860.9 11339.0 11498.8 11567.9 11952.6 11812.9 12132.6 12195.9 12223.0 12282.2 7419.5 8689.8 8846.7 8886.7 8977.1 11614.9 11812.6 11916.8 12028.6 12075.2 05:26:24 INFO - PID 6516 | 05:26:24 INFO - PID 6516 | [#3] mootools.html Cycles:180 Average:6842.11 Median:3828.55 stddev:4337.44 (113.3%) stddev-sans-first:4330.49 05:26:24 INFO - PID 6516 | Values: 1422.7 1472.9 1493.2 1499.5 1503.5 10869.3 11364.4 11417.8 11660.4 12180.5 11675.7 11770.0 11804.1 11919.5 11927.4 2497.3 2517.0 2535.5 2540.0 2546.0 2463.9 2508.0 2510.9 2514.2 2514.7 2482.2 2512.0 2527.5 2534.5 2537.2 2029.5 2479.6 2492.5 2499.5 2503.9 2448.2 2475.0 2484.9 2500.0 2523.6 11553.5 11593.9 11627.3 11656.5 11672.6 6262.1 6470.1 6479.9 6484.1 6508.0 3727.8 3943.6 3945.5 4119.4 4182.0 1014.9 1248.5 1278.0 1285.8 1298.0 15309.7 15742.7 15890.4 15919.9 16094.8 4590.3 4707.6 5141.5 5316.4 5359.3 9291.1 11493.0 11495.0 11524.0 11530.7 5633.4 5953.0 6294.6 6326.0 6344.8 5911.0 5912.5 6000.9 6009.1 6114.3 4204.6 4279.5 4313.0 4327.0 4335.1 1123.0 1232.2 1242.1 1244.2 1290.9 803.2 804.3 831.2 844.9 866.3 5007.0 6119.5 6195.6 6196.9 6321.7 522.7 592.6 592.7 605.4 622.9 3785.6 3822.4 3834.7 3869.9 4134.0 13555.9 14898.8 14977.2 15013.0 15018.2 10465.5 14290.5 14423.6 14661.8 14939.9 6805.6 7220.6 8529.9 8562.4 8596.4 6228.8 6229.7 6630.4 6827.8 6971.1 12738.2 12843.9 13305.7 13371.1 13454.7 10852.9 13085.0 13155.8 13176.7 13180.3 7954.7 8048.3 8066.4 8067.5 8069.9 8103.0 10233.8 10320.7 10564.8 10637.8 9010.8 9081.3 9140.7 9161.4 9192.4 8822.3 8859.6 8919.7 8962.9 9043.4 8016.4 8039.7 8116.0 8130.6 8155.4 5605.9 5890.0 5971.6 6084.9 6136.2 5987.7 6137.7 6145.8 6163.4 6345.8 05:26:24 INFO - PID 6516 | 05:26:24 INFO - PID 6516 | [#4] prototype.html Cycles:180 Average:5322.51 Median:5946.99 stddev:1708.55 (28.7%) stddev-sans-first:1671.94 05:26:24 INFO - PID 6516 | Values: 341.2 398.0 398.6 402.3 409.3 5168.5 6062.6 6079.8 6224.5 6396.6 5096.5 6109.6 6223.3 6251.2 6256.7 3981.3 4311.5 4514.6 4553.7 4573.1 4001.8 4135.4 4414.7 4566.8 4613.9 3991.6 4534.5 4543.2 4546.2 4548.9 4309.8 4480.0 4508.7 4542.8 4558.5 3831.7 3848.8 3858.9 3871.5 3891.3 4841.9 6134.0 6142.2 6147.6 6177.3 4406.3 5202.8 5410.7 5455.5 5466.9 5358.5 5969.8 6520.7 6678.0 6742.3 4386.2 4390.7 4409.2 4417.6 4422.6 5195.8 6547.1 6898.4 6964.9 7271.2 3917.0 4454.5 4458.0 4476.2 4505.6 4547.5 4558.2 4558.3 4566.5 4588.8 5272.9 5364.9 5407.8 5444.3 5454.7 6459.2 6762.5 6779.7 6796.5 6799.2 1250.7 1308.6 1385.8 1551.0 1567.0 994.4 997.2 1003.9 1008.4 1020.5 5494.8 5890.7 6402.9 6419.7 6437.0 5962.6 6041.3 6911.9 6981.2 7042.2 5338.8 5835.9 5894.5 6379.9 6383.2 3198.5 3616.6 3660.7 3666.6 3677.1 5397.8 5931.4 6283.7 7159.9 7271.8 6177.7 6743.3 6829.9 7214.5 7254.5 6859.0 6924.8 6936.6 7265.8 7271.5 5491.0 6976.0 7002.5 7033.8 7051.9 5542.5 5631.0 6049.6 6741.9 6980.8 6348.7 6638.4 6914.9 7302.4 7359.8 3732.5 4437.6 4460.6 4467.7 4481.1 6745.5 6901.0 7018.6 7035.4 7104.9 5126.9 5215.6 5699.5 6645.9 6804.9 6504.5 6663.0 6752.4 6753.5 6772.0 6110.4 6627.3 6648.2 6662.8 6673.5 6619.2 6658.6 6749.5 6788.0 6839.2 6276.8 6282.9 6586.5 6600.7 6611.5 05:26:24 INFO - PID 6516 | 05:26:24 INFO - PID 6516 | [#5] yui.html Cycles:180 Average:21733.04 Median:17273.61 stddev:30235.24 (175.0%) stddev-sans-first:30279.16 05:26:24 INFO - PID 6516 | Values: 788.6 829.3 842.0 859.7 865.2 16300.8 16666.2 16745.3 18652.2 19376.1 16293.3 16433.4 17290.3 18089.5 18196.4 1004.0 1036.2 1037.3 1039.7 1044.2 1038.0 1042.5 1043.6 1045.0 1045.8 1082.2 1087.9 1094.6 1100.1 1100.9 1083.7 1090.3 1094.7 1094.8 1095.9 773.4 788.4 794.2 810.4 812.8 16581.5 16594.8 16700.7 16742.5 16937.5 17032.1 17159.3 17395.1 17477.8 17543.1 13931.7 14083.9 14435.9 14458.3 14479.9 3289.6 3327.6 3365.0 3367.5 3379.5 108126.4 119580.0 120034.0 120888.0 121268.0 13704.7 13803.7 13817.0 13820.0 13858.9 20786.5 24858.8 24905.9 25407.9 25584.2 6075.3 7896.6 8070.0 8074.9 8081.2 3952.6 3968.5 4005.2 4025.7 4028.5 4329.7 4348.9 4451.8 4452.8 4456.4 912.8 926.9 946.1 948.9 949.2 648.8 672.1 675.7 678.7 688.3 11050.6 11803.0 12008.2 12011.5 12181.1 582.7 640.8 646.2 649.5 655.1 5354.1 5517.6 5571.0 5648.9 5752.5 99242.5 105354.5 106238.5 106483.5 108740.0 90949.5 91778.5 93281.0 94781.0 95603.6 59641.1 78179.1 80258.0 80724.0 81834.1 12086.0 12773.4 12820.4 12828.7 12842.5 32998.8 38878.0 39375.4 40448.8 40889.0 47866.0 53826.7 73728.6 73951.3 74972.6 16012.7 16121.8 16171.4 16221.8 16339.0 16993.7 17443.7 17447.4 17499.5 17560.8 15904.2 16104.7 16762.7 16812.0 16824.7 15813.1 16455.2 17026.1 17260.8 17286.4 12738.9 16326.3 16668.2 16711.4 17000.2 11038.8 11411.3 11652.8 12038.2 12102.8 12652.2 12715.0 13375.3 13484.9 14020.6 05:26:24 INFO - PID 6516 | -------- Summary: end -------- 05:26:24 INFO - PID 6516 | 05:26:25 INFO - TEST-INFO | 6516: exit 0 05:26:25 INFO - TEST-OK | dromaeo_css | took 1114028ms 05:26:25 INFO - TEST-START | kraken 05:26:25 INFO - operating with platform_type : mac_ 05:26:25 INFO - Initialising browser for kraken test... 05:26:25 INFO - TEST-INFO | started process 6523 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/tmpeoFqwV/profile http://localhost:56840/getInfo.html) 05:26:25 INFO - PID 6523 | JavaScript warning: resource://gre/modules/addons/XPIProvider.jsm, line 2978: String.localeCompare is deprecated; use String.prototype.localeCompare instead 05:26:27 INFO - PID 6523 | 2017-05-22 05:26:27.823 plugin-container[6524:118372] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x972f, name = 'com.apple.tsm.portname' 05:26:27 INFO - PID 6523 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:26:27 INFO - PID 6523 | 2017-05-22 05:26:27.827 plugin-container[6524:118372] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x454f, name = 'com.apple.CFPasteboardClient' 05:26:27 INFO - PID 6523 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:26:27 INFO - PID 6523 | 2017-05-22 05:26:27.827 plugin-container[6524:118372] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 05:26:27 INFO - PID 6523 | __metrics Screen width/height:1600/1200 05:26:27 INFO - PID 6523 | colorDepth:24 05:26:27 INFO - PID 6523 | Browser inner width/height: 1024/691 05:26:27 INFO - PID 6523 | __metrics 05:26:28 INFO - PID 6523 | 2017-05-22 05:26:28.414 plugin-container[6525:118425] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x933f, name = 'com.apple.tsm.portname' 05:26:28 INFO - PID 6523 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:26:28 INFO - PID 6523 | 2017-05-22 05:26:28.415 plugin-container[6525:118425] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9a0b, name = 'com.apple.CFPasteboardClient' 05:26:28 INFO - PID 6523 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:26:28 INFO - PID 6523 | 2017-05-22 05:26:28.415 plugin-container[6525:118425] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 05:26:29 INFO - TEST-INFO | 6523: exit 0 05:26:29 INFO - Browser initialized. 05:26:29 INFO - Running cycle 1/1 for kraken test... 05:26:29 INFO - TEST-INFO | started process 6526 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/kh/fb8m_90s3z33bzxps44y314800000w/T/tmpeoFqwV/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:26:31 INFO - PID 6526 | 2017-05-22 05:26:31.007 plugin-container[6527:118514] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9637, name = 'com.apple.tsm.portname' 05:26:31 INFO - PID 6526 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:26:31 INFO - PID 6526 | 2017-05-22 05:26:31.008 plugin-container[6527:118514] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9e0b, name = 'com.apple.CFPasteboardClient' 05:26:31 INFO - PID 6526 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:26:31 INFO - PID 6526 | 2017-05-22 05:26:31.008 plugin-container[6527:118514] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 05:26:31 INFO - PID 6526 | 2017-05-22 05:26:31.778 plugin-container[6528:118559] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x454b, name = 'com.apple.tsm.portname' 05:26:31 INFO - PID 6526 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:26:31 INFO - PID 6526 | 2017-05-22 05:26:31.778 plugin-container[6528:118559] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x480f, name = 'com.apple.CFPasteboardClient' 05:26:31 INFO - PID 6526 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:26:31 INFO - PID 6526 | 2017-05-22 05:26:31.778 plugin-container[6528:118559] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 05:26:41 INFO - PID 6526 | RSS: Main: 242163712 05:26:41 INFO - PID 6526 | 05:26:43 INFO - PID 6526 | Cycle 1(1): loaded http://localhost:56840/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:56840/tests/kraken/driver.html?testName=audio-beat-detection) 05:26:44 INFO - PID 6526 | RSS: Main: 254435328 05:26:44 INFO - PID 6526 | 05:26:47 INFO - PID 6526 | Cycle 1(1): loaded http://localhost:56840/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:56840/tests/kraken/driver.html?testName=audio-dft) 05:26:47 INFO - PID 6526 | RSS: Main: 263524352 05:26:47 INFO - PID 6526 | 05:26:51 INFO - PID 6526 | Cycle 1(1): loaded http://localhost:56840/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:56840/tests/kraken/driver.html?testName=audio-fft) 05:26:51 INFO - PID 6526 | RSS: Main: 265682944 05:26:51 INFO - PID 6526 | 05:26:53 INFO - PID 6526 | Cycle 1(1): loaded http://localhost:56840/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:56840/tests/kraken/driver.html?testName=audio-oscillator) 05:26:54 INFO - PID 6526 | RSS: Main: 266489856 05:26:54 INFO - PID 6526 | 05:26:56 INFO - PID 6526 | Cycle 1(1): loaded http://localhost:56840/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:56840/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:26:56 INFO - PID 6526 | RSS: Main: 268271616 05:26:56 INFO - PID 6526 | 05:26:59 INFO - PID 6526 | Cycle 1(1): loaded http://localhost:56840/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:56840/tests/kraken/driver.html?testName=imaging-darkroom) 05:27:00 INFO - PID 6526 | RSS: Main: 277692416 05:27:00 INFO - PID 6526 | 05:27:03 INFO - PID 6526 | Cycle 1(1): loaded http://localhost:56840/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:56840/tests/kraken/driver.html?testName=imaging-desaturate) 05:27:03 INFO - PID 6526 | RSS: Main: 277299200 05:27:03 INFO - PID 6526 | 05:27:06 INFO - PID 6526 | Cycle 1(1): loaded http://localhost:56840/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:56840/tests/kraken/driver.html?testName=json-parse-financial) 05:27:07 INFO - PID 6526 | RSS: Main: 276594688 05:27:07 INFO - PID 6526 | 05:27:09 INFO - PID 6526 | Cycle 1(1): loaded http://localhost:56840/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:56840/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:27:09 INFO - PID 6526 | RSS: Main: 276131840 05:27:09 INFO - PID 6526 | 05:27:11 INFO - PID 6526 | Cycle 1(1): loaded http://localhost:56840/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:56840/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:27:12 INFO - PID 6526 | RSS: Main: 275988480 05:27:12 INFO - PID 6526 | 05:27:14 INFO - PID 6526 | Cycle 1(1): loaded http://localhost:56840/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:56840/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:27:14 INFO - PID 6526 | RSS: Main: 275976192 05:27:14 INFO - PID 6526 | 05:27:17 INFO - PID 6526 | Cycle 1(1): loaded http://localhost:56840/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:56840/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:27:18 INFO - PID 6526 | RSS: Main: 275709952 05:27:18 INFO - PID 6526 | 05:27:21 INFO - PID 6526 | Cycle 1(1): loaded http://localhost:56840/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:56840/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:27:22 INFO - PID 6526 | RSS: Main: 276017152 05:27:22 INFO - PID 6526 | 05:27:23 INFO - PID 6526 | Cycle 1(1): loaded http://localhost:56840/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:56840/tests/kraken/driver.html?testName=ai-astar) 05:27:24 INFO - PID 6526 | RSS: Main: 277712896 05:27:24 INFO - PID 6526 | 05:27:24 INFO - PID 6526 | __start_tp_report 05:27:24 INFO - PID 6526 | _x_x_mozilla_page_load 05:27:24 INFO - PID 6526 | _x_x_mozilla_page_load_details 05:27:24 INFO - PID 6526 | |i|pagename|runs| 05:27:24 INFO - PID 6526 | |0;ai-astar;103;115;112;114;114;114;115;116;135;113 05:27:24 INFO - PID 6526 | |1;audio-beat-detection;135;109;108;144;111;113;115;108;135;112 05:27:24 INFO - PID 6526 | |2;audio-dft;197;204;230;258;204;217;206;197;197;198 05:27:24 INFO - PID 6526 | |3;audio-fft;82;59;58;86;89;89;92;62;80;56 05:27:24 INFO - PID 6526 | |4;audio-oscillator;88;69;84;72;69;69;87;70;66;88 05:27:24 INFO - PID 6526 | |5;imaging-gaussian-blur;88;90;88;93;88;89;89;90;94;89 05:27:24 INFO - PID 6526 | |6;imaging-darkroom;96;107;96;95;95;96;96;96;95;120 05:27:24 INFO - PID 6526 | |7;imaging-desaturate;93;94;92;92;95;110;91;103;90;90 05:27:24 INFO - PID 6526 | |8;json-parse-financial;47;49;49;49;56;57;80;76;79;59 05:27:24 INFO - PID 6526 | |9;json-stringify-tinderbox;54;59;56;60;62;59;58;57;56;58 05:27:24 INFO - PID 6526 | |10;stanford-crypto-aes;80;97;96;106;81;86;81;84;84;83 05:27:24 INFO - PID 6526 | |11;stanford-crypto-ccm;138;138;137;136;135;138;141;166;209;141 05:27:24 INFO - PID 6526 | |12;stanford-crypto-pbkdf2;182;188;187;182;222;183;187;182;186;183 05:27:24 INFO - PID 6526 | |13;stanford-crypto-sha256-iterative;70;66;69;70;69;69;70;73;98;69 05:27:24 INFO - PID 6526 | __end_tp_report 05:27:24 INFO - PID 6526 | __start_cc_report 05:27:24 INFO - PID 6526 | _x_x_mozilla_cycle_collect,911 05:27:24 INFO - PID 6526 | __end_cc_report 05:27:24 INFO - PID 6526 | __startTimestamp1495456044181__endTimestamp 05:27:24 INFO - PID 6526 | 05:27:24 INFO - PID 6526 | ------- Summary: start ------- 05:27:24 INFO - PID 6526 | Number of tests: 14 05:27:24 INFO - PID 6526 | 05:27:24 INFO - PID 6526 | [#0] ai-astar Cycles:10 Average:115.10 Median:114.50 stddev:7.89 (6.9%) stddev-sans-first:7.06 05:27:24 INFO - PID 6526 | Values: 103.0 115.0 112.0 114.0 114.0 114.0 115.0 116.0 135.0 113.0 05:27:24 INFO - PID 6526 | 05:27:24 INFO - PID 6526 | [#1] audio-beat-detection Cycles:10 Average:119.00 Median:114.00 stddev:13.52 (11.9%) stddev-sans-first:13.04 05:27:24 INFO - PID 6526 | Values: 135.0 109.0 108.0 144.0 111.0 113.0 115.0 108.0 135.0 112.0 05:27:24 INFO - PID 6526 | 05:27:24 INFO - PID 6526 | [#2] audio-dft Cycles:10 Average:210.80 Median:205.00 stddev:19.68 (9.6%) stddev-sans-first:20.23 05:27:24 INFO - PID 6526 | Values: 197.0 204.0 230.0 258.0 204.0 217.0 206.0 197.0 197.0 198.0 05:27:24 INFO - PID 6526 | 05:27:24 INFO - PID 6526 | [#3] audio-fft Cycles:10 Average:75.30 Median:84.00 stddev:14.72 (17.5%) stddev-sans-first:15.41 05:27:24 INFO - PID 6526 | Values: 82.0 59.0 58.0 86.0 89.0 89.0 92.0 62.0 80.0 56.0 05:27:24 INFO - PID 6526 | 05:27:24 INFO - PID 6526 | [#4] audio-oscillator Cycles:10 Average:76.20 Median:78.00 stddev:9.26 (11.9%) stddev-sans-first:8.78 05:27:24 INFO - PID 6526 | Values: 88.0 69.0 84.0 72.0 69.0 69.0 87.0 70.0 66.0 88.0 05:27:24 INFO - PID 6526 | 05:27:24 INFO - PID 6526 | [#5] imaging-gaussian-blur Cycles:10 Average:89.80 Median:89.50 stddev:2.10 (2.3%) stddev-sans-first:2.12 05:27:24 INFO - PID 6526 | Values: 88.0 90.0 88.0 93.0 88.0 89.0 89.0 90.0 94.0 89.0 05:27:24 INFO - PID 6526 | 05:27:24 INFO - PID 6526 | [#6] imaging-darkroom Cycles:10 Average:99.20 Median:96.00 stddev:8.15 (8.5%) stddev-sans-first:8.56 05:27:24 INFO - PID 6526 | Values: 96.0 107.0 96.0 95.0 95.0 96.0 96.0 96.0 95.0 120.0 05:27:24 INFO - PID 6526 | 05:27:24 INFO - PID 6526 | [#7] imaging-desaturate Cycles:10 Average:95.00 Median:92.00 stddev:6.48 (7.0%) stddev-sans-first:6.83 05:27:24 INFO - PID 6526 | Values: 93.0 94.0 92.0 92.0 95.0 110.0 91.0 103.0 90.0 90.0 05:27:24 INFO - PID 6526 | 05:27:24 INFO - PID 6526 | [#8] json-parse-financial Cycles:10 Average:60.10 Median:58.00 stddev:13.23 (22.8%) stddev-sans-first:13.15 05:27:24 INFO - PID 6526 | Values: 47.0 49.0 49.0 49.0 56.0 57.0 80.0 76.0 79.0 59.0 05:27:24 INFO - PID 6526 | 05:27:24 INFO - PID 6526 | [#9] json-stringify-tinderbox Cycles:10 Average:57.90 Median:58.50 stddev:2.28 (3.9%) stddev-sans-first:1.94 05:27:24 INFO - PID 6526 | Values: 54.0 59.0 56.0 60.0 62.0 59.0 58.0 57.0 56.0 58.0 05:27:24 INFO - PID 6526 | 05:27:24 INFO - PID 6526 | [#10] stanford-crypto-aes Cycles:10 Average:87.80 Median:84.00 stddev:8.77 (10.4%) stddev-sans-first:8.83 05:27:24 INFO - PID 6526 | Values: 80.0 97.0 96.0 106.0 81.0 86.0 81.0 84.0 84.0 83.0 05:27:24 INFO - PID 6526 | 05:27:24 INFO - PID 6526 | [#11] stanford-crypto-ccm Cycles:10 Average:147.90 Median:139.50 stddev:23.28 (16.7%) stddev-sans-first:24.41 05:27:24 INFO - PID 6526 | Values: 138.0 138.0 137.0 136.0 135.0 138.0 141.0 166.0 209.0 141.0 05:27:24 INFO - PID 6526 | 05:27:24 INFO - PID 6526 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:188.20 Median:186.50 stddev:12.11 (6.5%) stddev-sans-first:12.63 05:27:24 INFO - PID 6526 | Values: 182.0 188.0 187.0 182.0 222.0 183.0 187.0 182.0 186.0 183.0 05:27:24 INFO - PID 6526 | 05:27:24 INFO - PID 6526 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:72.30 Median:70.00 stddev:9.19 (13.1%) stddev-sans-first:9.71 05:27:24 INFO - PID 6526 | Values: 70.0 66.0 69.0 70.0 69.0 69.0 70.0 73.0 98.0 69.0 05:27:24 INFO - PID 6526 | -------- Summary: end -------- 05:27:24 INFO - PID 6526 | 05:27:24 INFO - TEST-INFO | 6526: exit 0 05:27:25 INFO - TEST-OK | kraken | took 59752ms 05:27:25 INFO - SUITE-END | took 1173s 05:27:25 INFO - Completed test suite (00:19:34) 05:27:25 INFO - javascript benchmark 05:27:25 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7812.410196141392, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3047.588575542302, 3577.3918433866256, 3604.5314109165806, 3610.801405922673, 3634.145975695711, 5205.843824685261, 5210.931758989403, 6181.165542651741, 6319.1153238546585, 6331.651759153245, 5553.7223138843, 6129.467633091727, 6287.2140982377205, 6313.147725057116, 6332.651704156269, 5477.452254774531, 5501.944980550184, 5533.253010843545, 5552.888942221153, 5640.153976903456, 5445.591580631449, 5612.831615051555, 5676.801311954083, 5720.2849643794525, 5726.1697053927155, 5586.301712285965, 5626.156076588503, 5633.267675202218, 5677.545796336304, 5690.63010904292, 5438.374586922515, 5493.835184944458, 5494.1758736189895, 5542.63972841768, 5571.4428557143965, 4694.671373003891, 5459.727013649302, 5534.446555344473, 5554.472325129106, 5585.888282234332, 5564.8330550083565, 5930.673812940287, 6359.395857393541, 6378.4578310843635, 6390.840228994266, 6297.149884765589, 6320.273168585625, 6602.141721576212, 6859.525202081527, 6861.588304701684, 6881.587104773729, 6938.930610693879, 6957.9304206958295, 6963.0, 6964.4080253178545, 5367.919481207785, 6409.711562979677, 6426.0360945857565, 6451.064595633654, 6452.677366131675, 6149.13912052313, 6832.248452670202, 6936.895946560806, 7105.147382314155, 7113.253108423644, 6635.170603674541, 6659.86680266392, 6675.432588229958, 6736.5958042517605, 6742.696578653972, 6839.794806155824, 6935.479839012094, 6947.027416161742, 6961.825954351182, 6974.68613912365, 6112.837854638568, 6626.900596491057, 6656.8668626627195, 6741.561798483083, 6826.590404575642, 6800.863982720318, 6830.282820303896, 6853.143357080365, 6857.245702972669, 6881.41507971818, 5415.0687120485845, 5427.782888684487, 5432.511074003359, 5454.509094181542, 5464.508194262456, 3538.858445662196, 4787.784549695272, 4854.732989685601, 4875.366202393666, 4911.484294149063, 6345.175127233522, 6393.168888044617, 6404.359564043559, 6412.454941330039, 6441.967790161113, 6911.136107986502, 6927.445804335641, 6960.686769095403, 6961.338672826073, 6971.163460384685, 6383.3616638336725, 6465.2564955030775, 6496.675166241669, 6500.967495162494, 6509.967450162719, 5968.910466342922, 6022.337542870281, 6069.908951365733, 6071.423214794588, 6123.693815309217, 5885.3820348862755, 7098.680559374738, 7125.8574828502105, 7127.251638578082, 7132.786016419515, 6860.5540639858245, 6915.343042410963, 6940.41006514452, 6952.5480843745, 6996.685149168198, 6915.89626155598, 6966.582005079711, 6996.300369962963, 7123.180834203926, 7142.107236595426, 6768.357006084414, 6776.796696099125, 6814.250432452525, 6846.246912839484, 6850.314968503109, 6860.313968603299, 6936.861262774817, 6952.30476952321, 6959.408450281783, 6971.224711072931, 5897.761470091157, 6489.805305840832, 6516.185476815398, 6681.365270299314, 6737.62943038118, 6541.509386795914, 6609.801705948829, 6647.302033286435, 6676.49926255523, 6701.765438209639, 6508.219013718384, 6550.279469258282, 6589.4728421725185, 6608.471322294301, 6623.172103487064, 6410.935890641033, 6535.052417399545, 6611.041398997022, 6641.601503909781, 6670.733170673118, 5781.335146458211, 6329.7468101275435, 6448.613083215022, 6478.1578394808375, 6526.282108967915], "value": 6186.027552185196, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [669.4610838275188, 795.550513959613, 802.1978021978022, 1169.4269807794183, 1226.1478272600546, 37123.81438092809, 38978.0, 39019.82940511782, 39026.41460378097, 39127.60872391279, 39376.01559961002, 39678.0080497987, 39690.404643930364, 39776.602233977654, 39932.201355972866, 6671.266160722316, 7163.283671632821, 7759.689612415496, 8290.253877151055, 8509.48943063415, 8447.831043379128, 8491.490510569385, 8513.106123856998, 8565.186307300828, 8571.885654864858, 8508.106648801879, 8546.188112129337, 8589.35579831512, 8589.61346739395, 8597.656093756243, 8504.617292221865, 8527.18991695788, 8551.358648101355, 8554.52950087745, 8557.828843423158, 8251.793705157359, 8314.70898518555, 8335.583220838933, 8340.29107525861, 8342.207490288412, 29862.0, 36743.63256367429, 36983.445248321295, 36994.0, 37017.0, 3625.075605720551, 3643.41705327149, 3664.157243833933, 3666.138457462494, 3670.3576874047203, 27550.862245688844, 29234.830606775704, 30603.387932241454, 30656.0, 30765.0, 3118.8284644344553, 3136.9686303136905, 3153.0225630054756, 3153.4008538377634, 3167.129039514152, 253963.0, 255096.0, 268471.0, 305794.0, 308373.0, 45840.0, 46130.077398451846, 46200.7689961548, 46237.38169164063, 46264.53735462669, 47400.0, 47622.76188619035, 47928.76035619869, 48019.51980480151, 48086.759566201945, 13926.30368481592, 13987.37056832445, 14027.088239264414, 14037.0, 14110.294485275901, 10237.283390162765, 11332.773344533063, 11436.428178591037, 11486.885131148749, 11502.482388292547, 12110.0, 12182.0, 13250.933745331211, 13264.336783160803, 13283.335833208494, 5835.914859598298, 5859.912101318484, 5873.236479257755, 5873.706314684248, 5958.344582095966, 906.5104843384498, 907.9409838360617, 1017.7760892603616, 1038.5845661735366, 1075.870895492546, 17686.469405917844, 22900.31299061031, 24102.638460423106, 24280.63579046316, 24280.75719242785, 883.4919921045424, 1004.2668851738272, 1116.2855772305732, 1124.9887501125047, 1165.2891736041008, 10210.846837297446, 11710.180287379972, 11850.644480665595, 12376.62870113898, 12383.823536763994, 317139.0, 318422.0, 321221.0, 321919.0, 322087.0, 52189.7390513045, 52397.7380113097, 52656.47107053781, 52661.0, 52741.0, 1529.6328881068464, 1533.8159420869345, 1573.103331101284, 1576.929038193284, 1583.8812089093365, 23927.521449570562, 24164.516709665357, 24177.879110604335, 24185.63721544143, 24286.878565607058, 37181.44227836639, 37328.813355933045, 37453.4381984265, 37571.2485750278, 37626.81186594049, 278686.0, 279128.0, 279336.0, 279601.0, 279848.0, 32974.67025329812, 33239.83660571869, 33325.66674333226, 33404.0, 33455.0, 41479.96300092522, 43038.35442468303, 43320.0, 43353.56646433495, 43413.565864340955, 22968.0, 23105.88447057754, 25545.61681574815, 27263.727362726913, 30746.079122483327, 20114.59770804605, 24749.876250619353, 30414.23964400819, 30554.84722576373, 30758.846205768827, 35883.64116358803, 36793.448098278015, 37058.8147059263, 37204.25591488101, 37396.252074958895, 30118.397632047676, 30880.845595771876, 30905.69094309118, 31129.37741245208, 31202.375952481278, 22941.0, 24111.276661700176, 24304.635430468203, 24341.803229605128, 24495.0], "value": 17123.475729833342, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [380.9942850857237, 428.8456155783915, 436.10815881522285, 441.22564898602946, 456.93831332770066, 16983.745243821322, 19294.517637059063, 20899.26852560161, 21028.474288142766, 21646.89176554117, 18484.353047643333, 22078.0, 22450.88774556129, 22698.54602907945, 23683.81580920956, 5792.71036448178, 6072.483838873701, 6124.418180272889, 6125.601835880676, 6134.355892631276, 5838.883222335551, 5931.6441013539325, 6018.759249630009, 6023.3976602339635, 6028.367021462749, 5942.524598032169, 5969.850753731148, 5976.2230909981645, 5976.5517586180995, 5987.850303742398, 5880.7353669085, 5910.852228694295, 5992.460678538909, 6023.397660233942, 6028.788992385245, 5276.498732620395, 5344.278522399465, 5353.625246232766, 5354.598405119593, 5365.651232669864, 26434.206973790817, 26686.33284167892, 26719.73280267211, 26897.86551067232, 26958.056468023526, 2121.6711409731515, 2127.8297736181066, 2141.946451338729, 2151.655735082395, 2176.5864485747816, 16307.755383669255, 17944.641107177915, 18358.26566937321, 18364.63270734578, 18383.632327353513, 2129.5740851829696, 2142.2930432957087, 2142.389419015589, 2166.804987551128, 2171.9674204886937, 103464.99999999924, 119439.0, 139312.0, 140595.0, 141660.58339416576, 12040.81938770919, 12336.136470446983, 12410.193337433038, 12421.323121378678, 12484.75030499385, 13120.540781072808, 15721.921390392974, 15910.681786364208, 15916.283767230503, 15937.601559960862, 7348.412127029825, 8522.36082293818, 8734.126587341216, 8761.474311541328, 8865.423747456523, 8359.289460395812, 8387.119352468026, 8459.450135741157, 8542.658293668308, 8550.0, 6402.423781859655, 7640.350570201484, 7704.075510938723, 7793.337566306814, 7833.764987050397, 1907.484979055675, 1960.451073699338, 2228.3871935217944, 2251.8311126665567, 2253.5943530164727, 973.78576713123, 980.3970558106713, 988.619381538113, 998.6804222648828, 1027.2244455436098, 20378.286759963332, 20737.377878663665, 20841.062152203187, 20846.0, 21109.68335474969, 992.3450522655013, 1001.834697274956, 1022.3201570955232, 1027.712240572641, 1042.4891803016467, 10305.639302624522, 10724.302920310154, 10800.891991080145, 10811.35131892089, 10824.67525974222, 497368.5131574319, 498713.0, 502338.8128949675, 508236.0, 510659.0, 114152.42923785494, 118928.0, 121572.0, 130756.0, 136491.31754341166, 1201.6094769200045, 1237.2823762217893, 1238.0219626495148, 1261.1298204239229, 1261.211742660837, 19274.61450771005, 21334.946360191512, 22237.888810555844, 22274.0, 22403.887980559994, 57403.42596573981, 57513.42486575248, 57854.0, 58696.0, 58977.70511147417, 77335.0, 77917.220827791, 78908.0, 79278.0, 79310.0, 7217.458690598121, 8668.263197628272, 8684.478931264144, 8782.253508451853, 9029.593668285075, 13748.862511375159, 14283.928580357031, 14403.711925761636, 14543.345549450512, 14715.190664513555, 11840.467178976794, 12087.576934807239, 12209.0, 12210.145289829627, 12251.9585835205, 10860.891391086305, 11338.979491845774, 11498.77002459963, 11567.942160289145, 11952.64142075739, 11812.940935295268, 12132.636020919386, 12195.939020304842, 12223.022158227142, 12282.201656892274, 7419.517731347445, 8689.826203476021, 8846.690365837165, 8886.688965886164, 8977.147171018743, 11614.88385116138, 11812.645620631394, 11916.821247681464, 12028.63914082579, 12075.215111017756], "value": 10290.511749991127, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1422.6656735667118, 1472.8748056415204, 1493.1563666528411, 1499.5276487906308, 1503.4662694743365, 10869.347839129658, 11364.431778411077, 11417.771644567114, 11660.41697915105, 12180.512779488832, 11675.708107297321, 11770.0, 11804.055675545957, 11919.463624136913, 11927.403629818518, 2497.2757900208917, 2517.0435234610914, 2535.467551814121, 2539.987300063497, 2546.032507647101, 2463.9137630182945, 2507.987460062697, 2510.924672259835, 2514.245726282117, 2514.6856642919633, 2482.1684735613503, 2511.962320565193, 2527.5324065048026, 2534.4550921551863, 2537.175417989152, 2029.4824819670964, 2479.5536803375476, 2492.538880307149, 2499.5002998201116, 2503.9374015649573, 2448.179859746987, 2475.0471068638703, 2484.8757562121823, 2499.950000999988, 2523.570992931188, 11553.480093395818, 11593.884061159364, 11627.302361858316, 11656.475458604298, 11672.649820505398, 6262.091996660459, 6470.061841033071, 6479.870402591969, 6484.059811327378, 6507.967460162717, 3727.8136093195503, 3943.6253555912144, 3945.546262179829, 4119.402686610455, 4181.958180418218, 1014.9086582207636, 1248.4881198708485, 1277.9840027178416, 1285.768561658901, 1297.9935100324437, 15309.693806123927, 15742.685146297124, 15890.443834465737, 15919.92040039804, 16094.75857862133, 4590.311453281967, 4707.552782485658, 5141.511556402136, 5316.388615309212, 5359.330083739533, 9291.070892910655, 11493.0, 11495.0, 11524.020458260975, 11530.654080377602, 5633.436656334334, 5953.0, 6294.559380843389, 6326.0, 6344.777932772331, 5910.970445147833, 5912.506495063696, 6000.939990600125, 6009.06859436788, 6114.2662880453745, 4204.600562946515, 4279.507856596531, 4312.956870431255, 4326.956730432718, 4335.111302183045, 1122.9556512443505, 1232.211384713784, 1242.0932719115096, 1244.1974926172568, 1290.9285293206617, 803.2289002557493, 804.2681160144241, 831.1771346367053, 844.8563744163507, 866.3242670716851, 5006.973570418056, 6119.5410344224365, 6195.566310358232, 6196.876062478816, 6321.65230912295, 522.7334059629617, 592.6414519215813, 592.69476219746, 605.4097255176168, 622.8567429491243, 3785.621437856192, 3822.445745366906, 3834.654881060718, 3869.916423401452, 4133.958660413357, 13555.932220339033, 14898.776518352233, 14977.176255306063, 15013.0, 15018.249087545579, 10465.47672616366, 14290.49983250602, 14423.567292981228, 14661.780073298909, 14939.925300373428, 6805.591664500146, 7220.60921707731, 8529.914700852913, 8562.357823163164, 8596.355273354524, 6228.750849965951, 6229.688515574294, 6630.448816629592, 6827.829304267433, 6971.093757811452, 12738.172018818746, 12843.935780321039, 13305.66735831612, 13371.06402551812, 13454.66363340885, 10852.945735271272, 13085.0, 13155.802662959872, 13176.670583235496, 13180.275084870424, 7954.681812727426, 8048.315893149148, 8066.395020373378, 8067.515949043076, 8069.878951815845, 8103.0, 10233.795324093328, 10320.741981450525, 10564.788704226026, 10637.787244255227, 9010.774730631787, 9081.273498120267, 9140.725778226664, 9161.404508706913, 9192.402493837879, 8822.294216462538, 8859.557022148738, 8919.73240802777, 8962.910370896469, 9043.366964312698, 8016.398770092383, 8039.67841286342, 8115.959420202861, 8130.593470326342, 8155.388345874202, 5605.943940560543, 5890.0, 5971.641701497924, 6084.90872636902, 6136.20229370179, 5987.670678112571, 6137.693115344304, 6145.784897528565, 6163.445289923929, 6345.777897773556], "value": 5160.957877535071, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [341.21009862169075, 397.9940300895485, 398.6392314954967, 402.2879503279196, 409.3123552431913, 5168.508991645793, 6062.635572126874, 6079.848003799902, 6224.502039836813, 6396.592749595094, 5096.4648711885275, 6109.572329936895, 6223.284322302937, 6251.2498500180045, 6256.743256743257, 3981.3231750602467, 4311.504177019636, 4514.638828893689, 4553.726776393411, 4573.108243892442, 4001.75989440633, 4135.421041054255, 4414.668899832509, 4566.771661416932, 4613.907721845561, 3991.560928297885, 4534.523874993128, 4543.204939135655, 4546.227141385956, 4548.931766023512, 4309.762963037032, 4480.0, 4508.6618503612035, 4542.750148741818, 4558.544145585448, 3831.6934645228316, 3848.807559622008, 3858.9228215435814, 3871.477350557667, 3891.3384724596604, 4841.8547443576745, 6133.957227271375, 6142.201513803177, 6147.569670123094, 6177.289611694668, 4406.272964960777, 5202.829363393244, 5410.702411367373, 5455.454454554552, 5466.851961088176, 5358.4641535846495, 5969.791057313017, 6520.739170433177, 6677.966610166918, 6742.314421394652, 4386.188555117282, 4390.675184942828, 4409.200157899725, 4417.558244175589, 4422.601965823092, 5195.844124676228, 6547.083408322838, 6898.413634841045, 6964.930350696479, 7271.20016798157, 3917.0, 4454.532274111237, 4458.0, 4476.171908196994, 4505.639548836085, 4547.454305483363, 4558.156741002925, 4558.3162525620755, 4566.49768525462, 4588.839390621312, 5272.92090618641, 5364.91952620711, 5407.810726624628, 5444.265024221759, 5454.7272636368025, 6459.223713478831, 6762.459003279726, 6779.694913728894, 6796.524243302922, 6799.218089919624, 1250.6998320403038, 1308.5616318533207, 1385.752822459774, 1551.015105408074, 1566.9971218420221, 994.4033579852031, 997.1792936315492, 1003.8996100389902, 1008.445355054717, 1020.4642562654666, 5494.780208791683, 5890.734916928748, 6402.903956440658, 6419.679016049179, 6436.967815160894, 5962.612430192024, 6041.2750469943885, 6911.9308806912295, 6981.162260528769, 7042.190148133031, 5338.839834804962, 5835.883282334329, 5894.528437724972, 6379.947308694106, 6383.234011918506, 3198.4722520784276, 3616.6437585905287, 3660.6888414485065, 3666.633336666312, 3677.099110717891, 5397.838064857982, 5931.377205393458, 6283.6858157091965, 7159.8568028640175, 7271.800662526319, 6177.69111544421, 6743.325667433315, 6829.863402732017, 7214.531055481377, 7254.492185546961, 6858.965705171442, 6924.822780127391, 6936.618485983218, 7265.837466005414, 7271.454640901848, 5491.0, 6975.988806436421, 7002.474814388839, 7033.788986330418, 7051.894221586783, 5542.533572560172, 5631.0, 6049.637021778707, 6741.943707169165, 6980.755673551402, 6348.714307856065, 6638.435732962559, 6914.8617027660175, 7302.3792977597495, 7359.77920662381, 3732.4587934748765, 4437.556244375537, 4460.553944605514, 4467.687261891637, 4481.148581769454, 6745.494087943326, 6901.0, 7018.5788852669, 7035.366816986496, 7104.928950710427, 5126.948730512647, 5215.5827533797965, 5699.487046165865, 6645.903425934666, 6804.897926531007, 6504.544681872224, 6663.000549917551, 6752.426043786137, 6753.527253092237, 6772.0, 6110.358412366815, 6627.276908003857, 6648.16897887764, 6662.83342916431, 6673.466122710269, 6619.238787539494, 6658.5671931324305, 6749.527533072835, 6787.966060169668, 6839.179298484214, 6276.811695649138, 6282.937170628235, 6586.509150150428, 6600.669966501559, 6611.504137189826], "value": 4784.950967807095, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [788.5662885413021, 829.3033851564685, 841.9579021048947, 859.7034023261972, 865.2083343740478, 16300.755488667664, 16666.166691665414, 16745.33018679253, 18652.160652770624, 19376.128074236658, 16293.348266069373, 16433.42483013095, 17290.308387664478, 18089.45731628053, 18196.363127290548, 1003.9548220330084, 1036.2073014144187, 1037.289456722144, 1039.7192757955347, 1044.1542350695922, 1037.9931466477506, 1042.5204405973261, 1043.5512729526301, 1045.007243119036, 1045.7542477517777, 1082.1613249731442, 1087.8585783848089, 1094.6168840905698, 1100.0759362135805, 1100.9229353945227, 1083.7290677330627, 1090.2967585907086, 1094.7482079121808, 1094.7591529863416, 1095.9259925273277, 773.3928865840288, 788.3968763895624, 794.2375319693101, 810.440795850867, 812.7521106062649, 16581.502554923372, 16594.83405165945, 16700.66598668032, 16742.497725068268, 16937.491875243762, 17032.148392580322, 17159.313627454885, 17395.1302434879, 17477.825221747746, 17543.122843857884, 13931.721365572632, 14083.929580352033, 14435.927820360936, 14458.277086145756, 14479.927600362036, 3289.5723555937816, 3327.600687917441, 3364.9831750841336, 3367.461206206995, 3379.493076038589, 108126.3781043285, 119580.0, 120034.0, 120888.0, 121268.0, 13704.65738356549, 13803.654908627364, 13816.96372772026, 13820.032597718064, 13858.930705346409, 20786.48033799167, 24858.751412486006, 24905.875470622894, 25407.856646450953, 25584.23247302584, 6075.33171351151, 7896.644650990719, 8070.0, 8074.878876816852, 8081.232282933112, 3952.565217826037, 3968.523777146703, 4005.1589166274825, 4025.677945764332, 4028.4561584186345, 4329.696921215547, 4348.891277718082, 4451.77741112943, 4452.755098469615, 4456.353828694886, 912.8083102548406, 926.9397489163183, 946.068122898944, 948.9051094890593, 949.2074118111316, 648.8410339466861, 672.1396612336217, 675.7263308360122, 678.725116327888, 688.3357559954668, 11050.613228536962, 11802.996745276576, 12008.159428839897, 12011.498562679664, 12181.147319687538, 582.6911736779514, 640.7789312687115, 646.1567654211206, 649.4966401039138, 655.0959675647575, 5354.063038968165, 5517.5585953123655, 5570.969370666439, 5648.943510564924, 5752.453516915886, 99242.5037874806, 105354.47322763337, 106238.46880765547, 106483.46758266314, 108740.0, 90949.54525227463, 91778.54110729403, 93281.0, 94781.0, 95603.56594651086, 59641.105383419286, 78179.05493231065, 80258.0, 80724.0, 81834.13580524654, 12086.033117350767, 12773.36133193318, 12820.423080961193, 12828.679283017625, 12842.486300547873, 32998.83500582578, 38878.0, 39375.40936885892, 40448.79775601221, 40889.0, 47866.0, 53826.73086634542, 73728.63135684287, 73951.26048739444, 74972.62513687396, 16012.679746404774, 16121.838781612503, 16171.353145874504, 16221.837781622033, 16339.0, 16993.74509382385, 17443.651126977642, 17447.38934137299, 17499.475015749547, 17560.82439175592, 15904.204789760235, 16104.67790644204, 16762.748558771385, 16812.0, 16824.74762878533, 15813.05121692702, 16455.177241138135, 17026.063566503963, 17260.82739172592, 17286.394976175772, 12738.936305318413, 16326.346946122023, 16668.249928752994, 16711.415100471426, 17000.234989425753, 11038.779224415628, 11411.25826821254, 11652.825207622061, 12038.157328987238, 12102.757944840878, 12652.17760845542, 12715.0, 13375.264360460269, 13484.86515134836, 14020.579382618538], "value": 8445.61189633255, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1494.6000000000001, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [103.0, 115.0, 112.0, 114.0, 114.0, 114.0, 115.0, 116.0, 135.0, 113.0], "value": 115.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [135.0, 109.0, 108.0, 144.0, 111.0, 113.0, 115.0, 108.0, 135.0, 112.0], "value": 119.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [197.0, 204.0, 230.0, 258.0, 204.0, 217.0, 206.0, 197.0, 197.0, 198.0], "value": 210.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 59.0, 58.0, 86.0, 89.0, 89.0, 92.0, 62.0, 80.0, 56.0], "value": 75.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 69.0, 84.0, 72.0, 69.0, 69.0, 87.0, 70.0, 66.0, 88.0], "value": 76.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 90.0, 88.0, 93.0, 88.0, 89.0, 89.0, 90.0, 94.0, 89.0], "value": 89.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 107.0, 96.0, 95.0, 95.0, 96.0, 96.0, 96.0, 95.0, 120.0], "value": 99.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 94.0, 92.0, 92.0, 95.0, 110.0, 91.0, 103.0, 90.0, 90.0], "value": 95.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [47.0, 49.0, 49.0, 49.0, 56.0, 57.0, 80.0, 76.0, 79.0, 59.0], "value": 60.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 59.0, 56.0, 60.0, 62.0, 59.0, 58.0, 57.0, 56.0, 58.0], "value": 57.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 97.0, 96.0, 106.0, 81.0, 86.0, 81.0, 84.0, 84.0, 83.0], "value": 87.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [138.0, 138.0, 137.0, 136.0, 135.0, 138.0, 141.0, 166.0, 209.0, 141.0], "value": 147.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [182.0, 188.0, 187.0, 182.0, 222.0, 183.0, 187.0, 182.0, 186.0, 183.0], "value": 188.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 66.0, 69.0, 70.0, 69.0, 69.0, 70.0, 73.0, 98.0, 69.0], "value": 72.3, "unit": "score"}]}]} 05:27:25 INFO - Return code: 0 05:27:25 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 05:27:25 INFO - # TBPL SUCCESS # 05:27:25 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:27:25 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:27:25 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:27:25 INFO - 2017-05-22 05:27:25.501 screenresolution[6533:118935] starting screenresolution argv=screenresolution get 05:27:25 INFO - 2017-05-22 05:27:25.504 screenresolution[6533:118935] Display 0: 1600x1200x32@60 05:27:25 INFO - 2017-05-22 05:27:25.510 screenresolution[6534:118938] starting screenresolution argv=screenresolution list 05:27:25 INFO - Available Modes on Display 0 05:27:25 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:27:25 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:27:25 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:27:25 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:27:25 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:27:25 INFO - Intel Iris: 05:27:25 INFO - Chipset Model: Intel Iris 05:27:25 INFO - Type: GPU 05:27:25 INFO - Bus: Built-In 05:27:25 INFO - VRAM (Dynamic, Max): 1536 MB 05:27:25 INFO - Vendor: Intel (0x8086) 05:27:25 INFO - Device ID: 0x0a2e 05:27:25 INFO - Revision ID: 0x0009 05:27:25 INFO - Displays: 05:27:25 INFO - Display: 05:27:25 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:27:25 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:27:25 INFO - Display Serial Number: 1600x1200 60 05:27:25 INFO - Main Display: Yes 05:27:25 INFO - Mirror: Off 05:27:25 INFO - Online: Yes 05:27:25 INFO - Rotation: Supported 05:27:25 INFO - Return code: 0 05:27:25 INFO - Running post-action listener: _resource_record_post_action 05:27:25 INFO - [mozharness: 2017-05-22 12:27:25.745920Z] Finished run-tests step (success) 05:27:25 INFO - Running post-run listener: _resource_record_post_run 05:27:25 INFO - Total resource usage - Wall time: 1196s; CPU: 54.0%; Read bytes: 2100736; Write bytes: 566561280; Read time: 29; Write time: 1800 05:27:25 INFO - TinderboxPrint: CPU usage
54.3% 05:27:25 INFO - TinderboxPrint: I/O read bytes / time
2,100,736 / 29 05:27:25 INFO - TinderboxPrint: I/O write bytes / time
566,561,280 / 1,800 05:27:25 INFO - TinderboxPrint: CPU idle
2,185.3 (45.7%) 05:27:25 INFO - TinderboxPrint: CPU system
972.8 (20.3%) 05:27:25 INFO - TinderboxPrint: CPU user
1,627.6 (34.0%) 05:27:25 INFO - TinderboxPrint: Swap in / out
248,049,664 / 0 05:27:25 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 233925120; Write bytes: 229464064; Read time: 16877; Write time: 458 05:27:25 INFO - run-tests - Wall time: 1175s; CPU: 54.0%; Read bytes: 2100736; Write bytes: 317796864; Read time: 29; Write time: 1296 05:27:26 INFO - Running post-run listener: _upload_blobber_files 05:27:26 INFO - Blob upload gear active. 05:27:26 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:27:26 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 05:27:26 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'] 05:27:26 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 05:27:26 INFO - (blobuploader) - INFO - Open directory for files ... 05:27:26 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 05:27:26 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:27:26 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:27:28 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 05:27:28 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:27:28 INFO - (blobuploader) - INFO - Done attempting. 05:27:28 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 05:27:28 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:27:28 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:27:28 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 05:27:28 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:27:28 INFO - (blobuploader) - INFO - Done attempting. 05:27:28 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 05:27:28 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:27:28 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:27:29 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 05:27:29 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:27:29 INFO - (blobuploader) - INFO - Done attempting. 05:27:29 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 05:27:29 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:27:29 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:27:31 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 05:27:31 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:27:31 INFO - (blobuploader) - INFO - Done attempting. 05:27:31 INFO - (blobuploader) - INFO - Iteration through files over. 05:27:31 INFO - Return code: 0 05:27:31 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:27:31 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:27:31 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b7498be97ec3d4f2180839457538af377942bc22fee4e9c4bf56a00f75193bc95e8b668a980dfcca4cc1cd43c9a0c3b67308ecfc5279fcc7c6fd0af3ebc052e3", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/77fb78a54878a3aeacbfe6cf1181ac5ac7d75e243bf7cb787b44af61ed8c820f72f0d9faf9b5d4b43a1fbb011d4c14488a1de6d638cf74dcb458993ebe291824", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ea4801c7b33c1cb50c9ca9eb47e9a8180a155a20b02cd629d1167dfffb7b00e05e876e0a1b79760120c981a4e882cbf8db9390632bf6bea217dd737a581344a2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3f58a209ab9ee7c6858e0453271dffc2ebe6686dadf1633a3ab1dfd7f4290f03bf7ef74cf488757ee6058b8876de400500836d9ea699141c7b390fd8dcff65bf"} 05:27:31 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:27:31 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:27:31 INFO - Contents: 05:27:31 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b7498be97ec3d4f2180839457538af377942bc22fee4e9c4bf56a00f75193bc95e8b668a980dfcca4cc1cd43c9a0c3b67308ecfc5279fcc7c6fd0af3ebc052e3", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/77fb78a54878a3aeacbfe6cf1181ac5ac7d75e243bf7cb787b44af61ed8c820f72f0d9faf9b5d4b43a1fbb011d4c14488a1de6d638cf74dcb458993ebe291824", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ea4801c7b33c1cb50c9ca9eb47e9a8180a155a20b02cd629d1167dfffb7b00e05e876e0a1b79760120c981a4e882cbf8db9390632bf6bea217dd737a581344a2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3f58a209ab9ee7c6858e0453271dffc2ebe6686dadf1633a3ab1dfd7f4290f03bf7ef74cf488757ee6058b8876de400500836d9ea699141c7b390fd8dcff65bf"} 05:27:31 INFO - Running post-run listener: copy_logs_to_upload_dir 05:27:31 INFO - Copying logs to upload dir... 05:27:31 INFO - mkdir: /builds/slave/test/build/upload/logs 05:27:31 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1274.175004 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2017-05-22 05:27:31.838056) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-22 05:27:31.841835) ========= 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.ytbCQnQzGm/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.vc1iQUwSdk/Listeners TMPDIR=/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/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/b7498be97ec3d4f2180839457538af377942bc22fee4e9c4bf56a00f75193bc95e8b668a980dfcca4cc1cd43c9a0c3b67308ecfc5279fcc7c6fd0af3ebc052e3", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/77fb78a54878a3aeacbfe6cf1181ac5ac7d75e243bf7cb787b44af61ed8c820f72f0d9faf9b5d4b43a1fbb011d4c14488a1de6d638cf74dcb458993ebe291824", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ea4801c7b33c1cb50c9ca9eb47e9a8180a155a20b02cd629d1167dfffb7b00e05e876e0a1b79760120c981a4e882cbf8db9390632bf6bea217dd737a581344a2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3f58a209ab9ee7c6858e0453271dffc2ebe6686dadf1633a3ab1dfd7f4290f03bf7ef74cf488757ee6058b8876de400500836d9ea699141c7b390fd8dcff65bf"} build_url:https://queue.taskcluster.net/v1/task/HN_nkN2KQgqZBc02j7G2Rw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011389 build_url: 'https://queue.taskcluster.net/v1/task/HN_nkN2KQgqZBc02j7G2Rw/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/b7498be97ec3d4f2180839457538af377942bc22fee4e9c4bf56a00f75193bc95e8b668a980dfcca4cc1cd43c9a0c3b67308ecfc5279fcc7c6fd0af3ebc052e3", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/77fb78a54878a3aeacbfe6cf1181ac5ac7d75e243bf7cb787b44af61ed8c820f72f0d9faf9b5d4b43a1fbb011d4c14488a1de6d638cf74dcb458993ebe291824", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ea4801c7b33c1cb50c9ca9eb47e9a8180a155a20b02cd629d1167dfffb7b00e05e876e0a1b79760120c981a4e882cbf8db9390632bf6bea217dd737a581344a2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3f58a209ab9ee7c6858e0453271dffc2ebe6686dadf1633a3ab1dfd7f4290f03bf7ef74cf488757ee6058b8876de400500836d9ea699141c7b390fd8dcff65bf"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-22 05:27:31.875134) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-22 05:27:31.875488) ========= 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.ytbCQnQzGm/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.vc1iQUwSdk/Listeners TMPDIR=/var/folders/kh/fb8m_90s3z33bzxps44y314800000w/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.005486 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-22 05:27:31.924360) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-22 05:27:31.924686) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-22 05:27:31.925018) ========= ========= Total master_lag: 0.18 =========