builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0052 starttime: 1494958005.44 results: success (0) revision: b8e9b674033bcd1f3a4c59b9d0ee7619c1a17cc5 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-05-16 11:06:45.437518) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-05-16 11:06:45.437961) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-16 11:06:45.438285) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-16 11:06:45.670313) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-05-16 11:06:45.670571) ========= 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.LejAOfT8r6/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.nUkVAnWmBP/Listeners TMPDIR=/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/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-16 11:06:45-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 190M=0s 2017-05-16 11:06:45 (190 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.126843 ========= master_lag: 2.20 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-05-16 11:06:47.999390) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 3 secs) (at 2017-05-16 11:06:47.999676) ========= 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.LejAOfT8r6/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.nUkVAnWmBP/Listeners TMPDIR=/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/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.027937 ========= master_lag: 3.42 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 3 secs) (at 2017-05-16 11:06:51.445713) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 3 secs) (at 2017-05-16 11:06:51.446042) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev b8e9b674033bcd1f3a4c59b9d0ee7619c1a17cc5 --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 b8e9b674033bcd1f3a4c59b9d0ee7619c1a17cc5 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.LejAOfT8r6/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.nUkVAnWmBP/Listeners TMPDIR=/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/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-16 11:06:52,804 truncating revision to first 12 chars 2017-05-16 11:06:52,804 Setting DEBUG logging. 2017-05-16 11:06:52,804 attempt 1/10 2017-05-16 11:06:52,804 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/b8e9b674033b?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-05-16 11:06:54,231 unpacking tar archive at: mozilla-central-b8e9b674033b/testing/mozharness/ program finished with exit code 0 elapsedTime=1.702040 ========= master_lag: 1.33 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 3 secs) (at 2017-05-16 11:06:54.478057) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-16 11:06:54.478412) ========= script_repo_revision: b8e9b674033bcd1f3a4c59b9d0ee7619c1a17cc5 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-16 11:06:54.478890) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-16 11:06:54.479229) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-16 11:06:54.552504) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 16 secs) (at 2017-05-16 11:06:54.552778) ========= /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.LejAOfT8r6/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.nUkVAnWmBP/Listeners TMPDIR=/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/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 11:06:54 INFO - MultiFileLogger online at 20170516 11:06:54 in /builds/slave/test 11:06:54 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 11:06:54 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 11:06:54 INFO - {'append_to_log': False, 11:06:54 INFO - 'base_work_dir': '/builds/slave/test', 11:06:54 INFO - 'blob_upload_branch': 'Firefox', 11:06:54 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 11:06:54 INFO - 'branch': 'Firefox', 11:06:54 INFO - 'buildbot_json_path': 'buildprops.json', 11:06:54 INFO - 'config_files': ('talos/mac_config.py',), 11:06:54 INFO - 'default_actions': ('clobber', 11:06:54 INFO - 'read-buildbot-config', 11:06:54 INFO - 'download-and-extract', 11:06:54 INFO - 'populate-webroot', 11:06:54 INFO - 'create-virtualenv', 11:06:54 INFO - 'install', 11:06:54 INFO - 'run-tests'), 11:06:54 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 11:06:54 INFO - 'download_minidump_stackwalk': True, 11:06:54 INFO - 'download_symbols': 'ondemand', 11:06:54 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 11:06:54 INFO - 'tooltool.py': '/tools/tooltool.py', 11:06:54 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 11:06:54 INFO - '/tools/misc-python/virtualenv.py')}, 11:06:54 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 11:06:54 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 11:06:54 INFO - 'gecko_profile': False, 11:06:54 INFO - 'gecko_profile_interval': 0, 11:06:54 INFO - 'installer_path': 'installer.exe', 11:06:54 INFO - 'log_level': 'info', 11:06:54 INFO - 'log_name': 'talos', 11:06:54 INFO - 'log_to_console': True, 11:06:54 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 11:06:54 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 11:06:54 INFO - 'opt_config_files': (), 11:06:54 INFO - 'pip_index': False, 11:06:54 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 11:06:54 INFO - 'cmd': ('bash', 11:06:54 INFO - '-c', 11:06:54 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 11:06:54 INFO - 'enabled': True, 11:06:54 INFO - 'halt_on_failure': False, 11:06:54 INFO - 'name': 'check_screen_resolution'},), 11:06:54 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 11:06:54 INFO - 'cmd': ('bash', 11:06:54 INFO - '-c', 11:06:54 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 11:06:54 INFO - 'enabled': True, 11:06:54 INFO - 'halt_on_failure': False, 11:06:54 INFO - 'name': 'check_screen_resolution'},), 11:06:54 INFO - 'run_cmd_checks_enabled': True, 11:06:54 INFO - 'suite': 'dromaeojs-e10s', 11:06:54 INFO - 'system_bits': '32', 11:06:54 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 11:06:54 INFO - 'title': 't-yosemite-r7-0052', 11:06:54 INFO - 'tooltool_cache': '/builds/tooltool_cache', 11:06:54 INFO - 'use_talos_json': True, 11:06:54 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 11:06:54 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 11:06:54 INFO - 'work_dir': 'build'} 11:06:54 INFO - [mozharness: 2017-05-16 18:06:54.772079Z] Running clobber step. 11:06:54 INFO - Running pre-action listener: _resource_record_pre_action 11:06:54 INFO - Running main action method: clobber 11:06:54 INFO - rmtree: /builds/slave/test/build 11:06:54 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 11:06:54 INFO - Running post-action listener: _resource_record_post_action 11:06:54 INFO - [mozharness: 2017-05-16 18:06:54.777505Z] Finished clobber step (success) 11:06:54 INFO - [mozharness: 2017-05-16 18:06:54.777638Z] Running read-buildbot-config step. 11:06:54 INFO - Running pre-action listener: _resource_record_pre_action 11:06:54 INFO - Running main action method: read_buildbot_config 11:06:54 INFO - Using buildbot properties: 11:06:54 INFO - { 11:06:54 INFO - "project": "", 11:06:54 INFO - "product": "firefox", 11:06:54 INFO - "who": "maglione.k@gmail.com", 11:06:54 INFO - "installer_path": "public/build/firefox-55.0a1.en-US.mac.dmg", 11:06:54 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 11:06:54 INFO - "repository": "https://hg.mozilla.org/mozilla-central/", 11:06:54 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 11:06:54 INFO - "stage_platform": "macosx64", 11:06:54 INFO - "basedir": "/builds/slave/test", 11:06:54 INFO - "buildnumber": 64, 11:06:54 INFO - "platform": "yosemite_r7", 11:06:54 INFO - "parent_task_id": "IW_fDKRQQTu8bzRdUhdCeA", 11:06:54 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 11:06:54 INFO - "slavebuilddir": "test", 11:06:54 INFO - "taskId": "UP2EkVJLS0-yIujQykG7Hg", 11:06:54 INFO - "branch": "mozilla-central", 11:06:54 INFO - "script_repo_revision": "production", 11:06:54 INFO - "revision": "b8e9b674033bcd1f3a4c59b9d0ee7619c1a17cc5", 11:06:54 INFO - "slavename": "t-yosemite-r7-0052", 11:06:54 INFO - "repo_path": "mozilla-central" 11:06:54 INFO - } 11:06:54 INFO - Finding installer, test and symbols from parent task. 11:06:54 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/UP2EkVJLS0-yIujQykG7Hg'}, attempt #1 11:06:55 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/UP2EkVJLS0-yIujQykG7Hg'}, attempt #1 11:06:56 INFO - Task dependencies: RXk7IudTT7uRPcnYOFDnkw 11:06:56 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/IW_fDKRQQTu8bzRdUhdCeA'}, attempt #1 11:06:56 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/IW_fDKRQQTu8bzRdUhdCeA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 11:06:56 INFO - Set test_url: https://queue.taskcluster.net/v1/task/IW_fDKRQQTu8bzRdUhdCeA/artifacts/public/build/test_packages.json 11:06:56 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/IW_fDKRQQTu8bzRdUhdCeA/artifacts/public/build/target.crashreporter-symbols.zip 11:06:56 INFO - Running post-action listener: _resource_record_post_action 11:06:56 INFO - [mozharness: 2017-05-16 18:06:56.821815Z] Finished read-buildbot-config step (success) 11:06:56 INFO - [mozharness: 2017-05-16 18:06:56.821938Z] Running download-and-extract step. 11:06:56 INFO - Running pre-action listener: _resource_record_pre_action 11:06:56 INFO - Running main action method: download_and_extract 11:06:56 INFO - mkdir: /builds/slave/test/build/tests 11:06:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:06:56 INFO - trying https://queue.taskcluster.net/v1/task/IW_fDKRQQTu8bzRdUhdCeA/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 11:06:56 INFO - Downloading https://queue.taskcluster.net/v1/task/IW_fDKRQQTu8bzRdUhdCeA/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 11:06:56 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/IW_fDKRQQTu8bzRdUhdCeA/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 11:06:57 INFO - Downloaded 1325 bytes. 11:06:57 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 11:06:57 INFO - Using the following test package requirements: 11:06:57 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 11:06:57 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 11:06:57 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 11:06:57 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 11:06:57 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 11:06:57 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 11:06:57 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 11:06:57 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 11:06:57 INFO - u'jsshell-mac.zip'], 11:06:57 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 11:06:57 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 11:06:57 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 11:06:57 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 11:06:57 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 11:06:57 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 11:06:57 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 11:06:57 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 11:06:57 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 11:06:57 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 11:06:57 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 11:06:57 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 11:06:57 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/IW_fDKRQQTu8bzRdUhdCeA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 11:06:57 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/IW_fDKRQQTu8bzRdUhdCeA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 11:06:57 INFO - Fetch https://queue.taskcluster.net/v1/task/IW_fDKRQQTu8bzRdUhdCeA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 11:07:29 INFO - retry: attempt #1 caught SSLError exception: The read operation timed out 11:07:29 INFO - retry: Failed, sleeping 30 seconds before retrying 11:07:59 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/IW_fDKRQQTu8bzRdUhdCeA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #2 11:07:59 INFO - Fetch https://queue.taskcluster.net/v1/task/IW_fDKRQQTu8bzRdUhdCeA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 11:08:02 INFO - Content-Length response header: 56566182 11:08:02 INFO - Bytes received: 56566182 11:08:20 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 11:08:20 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/IW_fDKRQQTu8bzRdUhdCeA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 11:08:20 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/IW_fDKRQQTu8bzRdUhdCeA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 11:08:20 INFO - Fetch https://queue.taskcluster.net/v1/task/IW_fDKRQQTu8bzRdUhdCeA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 11:08:22 INFO - Content-Length response header: 56566182 11:08:22 INFO - Bytes received: 56566182 11:08:39 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/IW_fDKRQQTu8bzRdUhdCeA/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 11:08:39 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/IW_fDKRQQTu8bzRdUhdCeA/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 11:08:39 INFO - Fetch https://queue.taskcluster.net/v1/task/IW_fDKRQQTu8bzRdUhdCeA/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 11:08:41 INFO - Content-Length response header: 14410069 11:08:41 INFO - Bytes received: 14410069 11:08:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:08:41 INFO - trying https://queue.taskcluster.net/v1/task/IW_fDKRQQTu8bzRdUhdCeA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 11:08:41 INFO - Downloading https://queue.taskcluster.net/v1/task/IW_fDKRQQTu8bzRdUhdCeA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 11:08:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/IW_fDKRQQTu8bzRdUhdCeA/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 11:08:43 INFO - Downloaded 75953447 bytes. 11:08:43 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/IW_fDKRQQTu8bzRdUhdCeA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 11:08:43 INFO - mkdir: /builds/slave/test/properties 11:08:43 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 11:08:43 INFO - Writing to file /builds/slave/test/properties/build_url 11:08:43 INFO - Contents: 11:08:43 INFO - build_url:https://queue.taskcluster.net/v1/task/IW_fDKRQQTu8bzRdUhdCeA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 11:08:43 INFO - Running post-action listener: _resource_record_post_action 11:08:43 INFO - Running post-action listener: set_extra_try_arguments 11:08:43 INFO - [mozharness: 2017-05-16 18:08:43.541204Z] Finished download-and-extract step (success) 11:08:43 INFO - [mozharness: 2017-05-16 18:08:43.541323Z] Running populate-webroot step. 11:08:43 INFO - Running pre-action listener: _resource_record_pre_action 11:08:43 INFO - Running main action method: populate_webroot 11:08:43 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 11:08:43 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 11:08:43 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 11:08:43 INFO - '--tppagecycles', 11:08:43 INFO - '7'], 11:08:43 INFO - 'tests': ['tsvgm']}}, 11:08:43 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 11:08:43 INFO - 'tests': ['tresize', 'tcanvasmark']}, 11:08:43 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 11:08:43 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 11:08:43 INFO - 'tests': ['dromaeo_css', 'kraken']}, 11:08:43 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 11:08:43 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 11:08:43 INFO - 'talos_options': ['--disable-e10s'], 11:08:43 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:08:43 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 11:08:43 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:08:43 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 11:08:43 INFO - 'talos_options': ['--disable-e10s'], 11:08:43 INFO - 'tests': ['damp', 'tps']}, 11:08:43 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 11:08:43 INFO - 'tests': ['damp', 'tps']}, 11:08:43 INFO - 'g3': {'talos_options': ['--disable-e10s'], 11:08:43 INFO - 'tests': ['dromaeo_dom']}, 11:08:43 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 11:08:43 INFO - 'g4': {'talos_options': ['--disable-e10s'], 11:08:43 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 11:08:43 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 11:08:43 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 11:08:43 INFO - 'tests': ['ts_paint', 'tp5o']}, 11:08:43 INFO - 'other': {'talos_options': ['--disable-e10s'], 11:08:43 INFO - 'tests': ['a11yr', 11:08:43 INFO - 'ts_paint', 11:08:43 INFO - 'tpaint', 11:08:43 INFO - 'sessionrestore', 11:08:43 INFO - 'sessionrestore_no_auto_restore', 11:08:43 INFO - 'tabpaint']}, 11:08:43 INFO - 'other-e10s': {'tests': ['a11yr', 11:08:43 INFO - 'ts_paint', 11:08:43 INFO - 'tpaint', 11:08:43 INFO - 'sessionrestore', 11:08:43 INFO - 'sessionrestore_no_auto_restore', 11:08:43 INFO - 'tabpaint']}, 11:08:43 INFO - 'other-e10s_l64': {'tests': ['a11yr', 11:08:43 INFO - 'ts_paint', 11:08:43 INFO - 'tpaint', 11:08:43 INFO - 'sessionrestore', 11:08:43 INFO - 'sessionrestore_no_auto_restore', 11:08:43 INFO - 'tabpaint']}, 11:08:43 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 11:08:43 INFO - 'ts_paint', 11:08:43 INFO - 'tpaint', 11:08:43 INFO - 'sessionrestore', 11:08:43 INFO - 'sessionrestore_no_auto_restore', 11:08:43 INFO - 'tabpaint']}, 11:08:43 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 11:08:43 INFO - 'tests': ['a11yr', 11:08:43 INFO - 'ts_paint', 11:08:43 INFO - 'tpaint', 11:08:43 INFO - 'sessionrestore', 11:08:43 INFO - 'sessionrestore_no_auto_restore', 11:08:43 INFO - 'tabpaint']}, 11:08:43 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 11:08:43 INFO - 'tests': ['a11yr', 11:08:43 INFO - 'ts_paint', 11:08:43 INFO - 'tpaint', 11:08:43 INFO - 'sessionrestore', 11:08:43 INFO - 'sessionrestore_no_auto_restore', 11:08:43 INFO - 'tabpaint']}, 11:08:43 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 11:08:43 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 11:08:43 INFO - 'bloom_basic_ref']}, 11:08:43 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 11:08:43 INFO - 'tests': ['tsvgx', 11:08:43 INFO - 'tsvgr_opacity', 11:08:43 INFO - 'tart', 11:08:43 INFO - 'tscrollx', 11:08:43 INFO - 'cart', 11:08:43 INFO - 'tsvg_static']}, 11:08:43 INFO - 'svgr-e10s': {'tests': ['tsvgx', 11:08:43 INFO - 'tsvgr_opacity', 11:08:43 INFO - 'tart', 11:08:43 INFO - 'tscrollx', 11:08:43 INFO - 'cart', 11:08:43 INFO - 'tsvg_static']}, 11:08:43 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 11:08:43 INFO - 'talos_options': ['--disable-e10s'], 11:08:43 INFO - 'tests': ['tp5o']}, 11:08:43 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 11:08:43 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 11:08:43 INFO - 'talos_options': ['--disable-e10s', 11:08:43 INFO - '--xperf_path', 11:08:43 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:08:43 INFO - 'tests': ['tp5n']}, 11:08:43 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 11:08:43 INFO - 'talos_options': ['--xperf_path', 11:08:43 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:08:43 INFO - 'tests': ['tp5n']}}, 11:08:43 INFO - 'talos.zip': {'path': '', 11:08:43 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 11:08:43 INFO - Running post-action listener: _resource_record_post_action 11:08:43 INFO - [mozharness: 2017-05-16 18:08:43.562731Z] Finished populate-webroot step (success) 11:08:43 INFO - [mozharness: 2017-05-16 18:08:43.562874Z] Running create-virtualenv step. 11:08:43 INFO - Running pre-action listener: _resource_record_pre_action 11:08:43 INFO - Running main action method: create_virtualenv 11:08:43 INFO - Creating virtualenv /builds/slave/test/build/venv 11:08:43 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 11:08:43 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 11:08:43 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 11:08:43 INFO - Using real prefix '/tools/python27' 11:08:43 INFO - New python executable in /builds/slave/test/build/venv/bin/python 11:08:44 INFO - Installing distribute.............................................................................................................................................................................................done. 11:08:47 INFO - Installing pip.................done. 11:08:47 INFO - Return code: 0 11:08:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 11:08:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 11:08:47 INFO - Reading from file tmpfile_stdout 11:08:47 INFO - Output received: 11:08:47 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) 11:08:47 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 11:08:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:08:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:08:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:08:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:08:47 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1029f3b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1029f7768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff429560e30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102703d30>, '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 0x1026b7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1026f8be8>, '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.LejAOfT8r6/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.nUkVAnWmBP/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/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 11:08:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 11:08:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 11:08:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LejAOfT8r6/Render', 11:08:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:08:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:08:47 INFO - 'HOME': '/Users/cltbld', 11:08:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:08:47 INFO - 'LOGNAME': 'cltbld', 11:08:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:08:47 INFO - 'MOZ_NO_REMOTE': '1', 11:08:47 INFO - 'NO_EM_RESTART': '1', 11:08:47 INFO - 'PAGER': '/bin/cat', 11:08:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:08:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:08:47 INFO - 'PWD': '/builds/slave/test', 11:08:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:08:47 INFO - 'SHELL': '/bin/bash', 11:08:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.nUkVAnWmBP/Listeners', 11:08:47 INFO - 'TMPDIR': '/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/T/', 11:08:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:08:47 INFO - 'USER': 'cltbld', 11:08:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:08:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:08:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:08:47 INFO - 'XPC_FLAGS': '0x0', 11:08:47 INFO - 'XPC_SERVICE_NAME': '0', 11:08:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:08:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:08:47 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 11:08:47 INFO - Cleaning up... 11:08:47 INFO - Return code: 0 11:08:47 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 11:08:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:08:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:08:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:08:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:08:47 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1029f3b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1029f7768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff429560e30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102703d30>, '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 0x1026b7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1026f8be8>, '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.LejAOfT8r6/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.nUkVAnWmBP/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/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 11:08:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 11:08:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 11:08:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LejAOfT8r6/Render', 11:08:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:08:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:08:47 INFO - 'HOME': '/Users/cltbld', 11:08:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:08:47 INFO - 'LOGNAME': 'cltbld', 11:08:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:08:47 INFO - 'MOZ_NO_REMOTE': '1', 11:08:47 INFO - 'NO_EM_RESTART': '1', 11:08:47 INFO - 'PAGER': '/bin/cat', 11:08:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:08:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:08:47 INFO - 'PWD': '/builds/slave/test', 11:08:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:08:47 INFO - 'SHELL': '/bin/bash', 11:08:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.nUkVAnWmBP/Listeners', 11:08:47 INFO - 'TMPDIR': '/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/T/', 11:08:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:08:47 INFO - 'USER': 'cltbld', 11:08:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:08:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:08:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:08:47 INFO - 'XPC_FLAGS': '0x0', 11:08:47 INFO - 'XPC_SERVICE_NAME': '0', 11:08:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:08:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:08:48 INFO - Downloading/unpacking psutil>=3.1.1 11:08:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:08:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:08:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:08:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:08:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 11:08:50 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:08:50 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:08:50 INFO - Installing collected packages: psutil 11:08:50 INFO - Running setup.py install for psutil 11:08:50 INFO - building 'psutil._psutil_osx' extension 11:08:50 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 11:08:51 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 11:08:51 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 11:08:51 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 11:08:51 INFO - building 'psutil._psutil_posix' extension 11:08:51 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 11:08:51 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 11:08:51 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 11:08:51 INFO - ^ 11:08:51 INFO - 1 warning generated. 11:08:51 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 11:08:51 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:08:51 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:08:51 INFO - Successfully installed psutil 11:08:51 INFO - Cleaning up... 11:08:51 INFO - Return code: 0 11:08:51 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 11:08:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:08:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:08:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:08:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:08:51 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 0x1029f3b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1029f7768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff429560e30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102703d30>, '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 0x1026b7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1026f8be8>, '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.LejAOfT8r6/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.nUkVAnWmBP/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/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 11:08:51 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 11:08:51 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 11:08:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LejAOfT8r6/Render', 11:08:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:08:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:08:51 INFO - 'HOME': '/Users/cltbld', 11:08:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:08:51 INFO - 'LOGNAME': 'cltbld', 11:08:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:08:51 INFO - 'MOZ_NO_REMOTE': '1', 11:08:51 INFO - 'NO_EM_RESTART': '1', 11:08:51 INFO - 'PAGER': '/bin/cat', 11:08:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:08:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:08:51 INFO - 'PWD': '/builds/slave/test', 11:08:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:08:51 INFO - 'SHELL': '/bin/bash', 11:08:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.nUkVAnWmBP/Listeners', 11:08:51 INFO - 'TMPDIR': '/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/T/', 11:08:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:08:51 INFO - 'USER': 'cltbld', 11:08:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:08:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:08:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:08:51 INFO - 'XPC_FLAGS': '0x0', 11:08:51 INFO - 'XPC_SERVICE_NAME': '0', 11:08:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:08:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:08:51 INFO - Downloading/unpacking mozsystemmonitor==0.3 11:08:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:08:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:08:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:08:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:08:53 INFO - Downloading mozsystemmonitor-0.3.tar.gz 11:08:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 11:08:53 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 11:08:53 INFO - Installing collected packages: mozsystemmonitor 11:08:53 INFO - Running setup.py install for mozsystemmonitor 11:08:53 INFO - Successfully installed mozsystemmonitor 11:08:53 INFO - Cleaning up... 11:08:53 INFO - Return code: 0 11:08:53 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 11:08:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:08:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:08:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:08:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:08:53 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 0x1029f3b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1029f7768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff429560e30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102703d30>, '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 0x1026b7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1026f8be8>, '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.LejAOfT8r6/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.nUkVAnWmBP/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/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 11:08:53 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 11:08:53 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 11:08:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LejAOfT8r6/Render', 11:08:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:08:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:08:53 INFO - 'HOME': '/Users/cltbld', 11:08:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:08:53 INFO - 'LOGNAME': 'cltbld', 11:08:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:08:53 INFO - 'MOZ_NO_REMOTE': '1', 11:08:53 INFO - 'NO_EM_RESTART': '1', 11:08:53 INFO - 'PAGER': '/bin/cat', 11:08:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:08:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:08:53 INFO - 'PWD': '/builds/slave/test', 11:08:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:08:53 INFO - 'SHELL': '/bin/bash', 11:08:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.nUkVAnWmBP/Listeners', 11:08:53 INFO - 'TMPDIR': '/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/T/', 11:08:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:08:53 INFO - 'USER': 'cltbld', 11:08:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:08:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:08:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:08:53 INFO - 'XPC_FLAGS': '0x0', 11:08:53 INFO - 'XPC_SERVICE_NAME': '0', 11:08:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:08:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:08:53 INFO - Downloading/unpacking jsonschema==2.5.1 11:08:53 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 11:08:53 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 11:08:53 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 11:08:53 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 11:08:55 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 11:08:55 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 11:08:55 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 11:08:55 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 11:08:55 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 11:08:55 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 11:08:55 INFO - Downloading functools32-3.2.3-2.tar.gz 11:08:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 11:08:55 INFO - warning: no files found matching '*.txt' 11:08:55 INFO - no previously-included directories found matching 'build' 11:08:55 INFO - no previously-included directories found matching 'dist' 11:08:55 INFO - no previously-included directories found matching '.git*' 11:08:55 INFO - Installing collected packages: jsonschema, functools32 11:08:55 INFO - Running setup.py install for functools32 11:08:55 INFO - warning: no files found matching '*.txt' 11:08:55 INFO - no previously-included directories found matching 'build' 11:08:55 INFO - no previously-included directories found matching 'dist' 11:08:55 INFO - no previously-included directories found matching '.git*' 11:08:55 INFO - Successfully installed jsonschema functools32 11:08:55 INFO - Cleaning up... 11:08:55 INFO - Return code: 0 11:08:55 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 11:08:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:08:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:08:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:08:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:08:55 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 0x1029f3b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1029f7768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff429560e30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102703d30>, '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 0x1026b7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1026f8be8>, '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.LejAOfT8r6/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.nUkVAnWmBP/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/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 11:08:55 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 11:08:55 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 11:08:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LejAOfT8r6/Render', 11:08:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:08:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:08:55 INFO - 'HOME': '/Users/cltbld', 11:08:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:08:55 INFO - 'LOGNAME': 'cltbld', 11:08:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:08:55 INFO - 'MOZ_NO_REMOTE': '1', 11:08:55 INFO - 'NO_EM_RESTART': '1', 11:08:55 INFO - 'PAGER': '/bin/cat', 11:08:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:08:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:08:55 INFO - 'PWD': '/builds/slave/test', 11:08:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:08:55 INFO - 'SHELL': '/bin/bash', 11:08:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.nUkVAnWmBP/Listeners', 11:08:55 INFO - 'TMPDIR': '/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/T/', 11:08:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:08:55 INFO - 'USER': 'cltbld', 11:08:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:08:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:08:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:08:55 INFO - 'XPC_FLAGS': '0x0', 11:08:55 INFO - 'XPC_SERVICE_NAME': '0', 11:08:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:08:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:08:56 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 11:08:56 INFO - Cleaning up... 11:08:56 INFO - Return code: 0 11:08:56 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 11:08:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:08:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:08:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:08:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:08:56 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 0x1029f3b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1029f7768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff429560e30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102703d30>, '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 0x1026b7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1026f8be8>, '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.LejAOfT8r6/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.nUkVAnWmBP/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/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 11:08:56 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 11:08:56 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 11:08:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LejAOfT8r6/Render', 11:08:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:08:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:08:56 INFO - 'HOME': '/Users/cltbld', 11:08:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:08:56 INFO - 'LOGNAME': 'cltbld', 11:08:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:08:56 INFO - 'MOZ_NO_REMOTE': '1', 11:08:56 INFO - 'NO_EM_RESTART': '1', 11:08:56 INFO - 'PAGER': '/bin/cat', 11:08:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:08:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:08:56 INFO - 'PWD': '/builds/slave/test', 11:08:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:08:56 INFO - 'SHELL': '/bin/bash', 11:08:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.nUkVAnWmBP/Listeners', 11:08:56 INFO - 'TMPDIR': '/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/T/', 11:08:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:08:56 INFO - 'USER': 'cltbld', 11:08:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:08:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:08:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:08:56 INFO - 'XPC_FLAGS': '0x0', 11:08:56 INFO - 'XPC_SERVICE_NAME': '0', 11:08:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:08:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:08:56 INFO - Downloading/unpacking blobuploader==1.2.4 11:08:56 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 11:08:56 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 11:08:56 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 11:08:56 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 11:08:57 INFO - Downloading blobuploader-1.2.4.tar.gz 11:08:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 11:08:57 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 11:08:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:08:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:08:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:08:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:08:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 11:08:58 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 11:08:58 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 11:08:58 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 11:08:58 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 11:08:58 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 11:08:58 INFO - Downloading docopt-0.6.1.tar.gz 11:08:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 11:08:58 INFO - Installing collected packages: blobuploader, requests, docopt 11:08:58 INFO - Running setup.py install for blobuploader 11:08:58 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 11:08:58 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 11:08:58 INFO - Running setup.py install for requests 11:08:59 INFO - Running setup.py install for docopt 11:08:59 INFO - Successfully installed blobuploader requests docopt 11:08:59 INFO - Cleaning up... 11:08:59 INFO - Return code: 0 11:08:59 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:08:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:08:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:08:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:08:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:08:59 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 0x1029f3b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1029f7768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff429560e30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102703d30>, '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 0x1026b7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1026f8be8>, '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.LejAOfT8r6/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.nUkVAnWmBP/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/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 11:08:59 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 11:08:59 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 11:08:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LejAOfT8r6/Render', 11:08:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:08:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:08:59 INFO - 'HOME': '/Users/cltbld', 11:08:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:08:59 INFO - 'LOGNAME': 'cltbld', 11:08:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:08:59 INFO - 'MOZ_NO_REMOTE': '1', 11:08:59 INFO - 'NO_EM_RESTART': '1', 11:08:59 INFO - 'PAGER': '/bin/cat', 11:08:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:08:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:08:59 INFO - 'PWD': '/builds/slave/test', 11:08:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:08:59 INFO - 'SHELL': '/bin/bash', 11:08:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.nUkVAnWmBP/Listeners', 11:08:59 INFO - 'TMPDIR': '/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/T/', 11:08:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:08:59 INFO - 'USER': 'cltbld', 11:08:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:08:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:08:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:08:59 INFO - 'XPC_FLAGS': '0x0', 11:08:59 INFO - 'XPC_SERVICE_NAME': '0', 11:08:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:08:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:08:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:08:59 INFO - Running setup.py (path:/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/T/pip-I7DA55-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:08:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:08:59 INFO - Running setup.py (path:/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/T/pip-bExB3C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:08:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:09:00 INFO - Running setup.py (path:/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/T/pip-UVR_BN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:09:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:09:00 INFO - Running setup.py (path:/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/T/pip-833SLO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:09:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:09:00 INFO - Running setup.py (path:/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/T/pip-6pPHJ_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:09:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:09:00 INFO - Running setup.py (path:/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/T/pip-xeJQkN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:09:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:09:00 INFO - Running setup.py (path:/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/T/pip-kG5Qqj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:09:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:09:00 INFO - Running setup.py (path:/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/T/pip-1XV7uG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:09:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:09:00 INFO - Running setup.py (path:/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/T/pip-TfUeM7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:09:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:09:00 INFO - Running setup.py (path:/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/T/pip-k0J_LU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:09:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:09:00 INFO - Running setup.py (path:/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/T/pip-3qS1Nx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:09:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:09:00 INFO - Running setup.py (path:/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/T/pip-VCpQrj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:09:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:09:01 INFO - Running setup.py (path:/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/T/pip-6wZh4Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:09:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:09:01 INFO - Running setup.py (path:/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/T/pip-LO17W0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:09:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:09:01 INFO - Running setup.py (path:/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/T/pip-NdUvGS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:09:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:09:01 INFO - Running setup.py (path:/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/T/pip-foKEBy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:09:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:09:01 INFO - Running setup.py (path:/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/T/pip-qJGev4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:09:01 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 11:09:01 INFO - Running setup.py install for manifestparser 11:09:01 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 11:09:01 INFO - Running setup.py install for mozcrash 11:09:01 INFO - Running setup.py install for mozdebug 11:09:01 INFO - Running setup.py install for mozdevice 11:09:02 INFO - Installing dm script to /builds/slave/test/build/venv/bin 11:09:02 INFO - Running setup.py install for mozfile 11:09:02 INFO - Running setup.py install for mozhttpd 11:09:02 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 11:09:02 INFO - Running setup.py install for mozinfo 11:09:02 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 11:09:02 INFO - Running setup.py install for mozInstall 11:09:02 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 11:09:02 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 11:09:02 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 11:09:02 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 11:09:02 INFO - Running setup.py install for mozleak 11:09:02 INFO - Running setup.py install for mozlog 11:09:02 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 11:09:03 INFO - Running setup.py install for moznetwork 11:09:03 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 11:09:03 INFO - Running setup.py install for mozprocess 11:09:03 INFO - Running setup.py install for mozprofile 11:09:03 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 11:09:03 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 11:09:03 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 11:09:03 INFO - Running setup.py install for mozrunner 11:09:03 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 11:09:03 INFO - Running setup.py install for mozscreenshot 11:09:03 INFO - Running setup.py install for moztest 11:09:03 INFO - Running setup.py install for mozversion 11:09:04 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 11:09:04 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 11:09:04 INFO - Cleaning up... 11:09:04 INFO - Return code: 0 11:09:04 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:09:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:09:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:09:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:09:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:09:04 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 0x1029f3b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1029f7768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff429560e30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102703d30>, '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 0x1026b7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1026f8be8>, '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.LejAOfT8r6/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.nUkVAnWmBP/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/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 11:09:04 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 11:09:04 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 11:09:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LejAOfT8r6/Render', 11:09:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:09:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:09:04 INFO - 'HOME': '/Users/cltbld', 11:09:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:09:04 INFO - 'LOGNAME': 'cltbld', 11:09:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:09:04 INFO - 'MOZ_NO_REMOTE': '1', 11:09:04 INFO - 'NO_EM_RESTART': '1', 11:09:04 INFO - 'PAGER': '/bin/cat', 11:09:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:09:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:09:04 INFO - 'PWD': '/builds/slave/test', 11:09:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:09:04 INFO - 'SHELL': '/bin/bash', 11:09:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.nUkVAnWmBP/Listeners', 11:09:04 INFO - 'TMPDIR': '/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/T/', 11:09:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:09:04 INFO - 'USER': 'cltbld', 11:09:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:09:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:09:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:09:04 INFO - 'XPC_FLAGS': '0x0', 11:09:04 INFO - 'XPC_SERVICE_NAME': '0', 11:09:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:09:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:09:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:09:04 INFO - Running setup.py (path:/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/T/pip-CqSdp5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:09:04 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)) 11:09:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:09:04 INFO - Running setup.py (path:/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/T/pip-GbexTt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:09:04 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)) 11:09:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:09:04 INFO - Running setup.py (path:/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/T/pip-79pyXl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:09:04 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)) 11:09:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:09:04 INFO - Running setup.py (path:/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/T/pip-M5QEOu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:09:04 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)) 11:09:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:09:04 INFO - Running setup.py (path:/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/T/pip-ZUULYg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:09:04 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)) 11:09:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:09:04 INFO - Running setup.py (path:/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/T/pip-WsNFpU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:09:04 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)) 11:09:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:09:04 INFO - Running setup.py (path:/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/T/pip-xZaVZM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:09:05 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)) 11:09:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:09:05 INFO - Running setup.py (path:/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/T/pip-ZNG1oc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:09:05 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)) 11:09:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:09:05 INFO - Running setup.py (path:/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/T/pip-uucSYr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:09:05 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)) 11:09:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:09:05 INFO - Running setup.py (path:/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/T/pip-U18a51-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:09:05 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)) 11:09:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:09:05 INFO - Running setup.py (path:/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/T/pip-tJ3hl5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:09:05 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)) 11:09:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:09:05 INFO - Running setup.py (path:/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/T/pip-WngQ32-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:09:05 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)) 11:09:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:09:05 INFO - Running setup.py (path:/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/T/pip-QenYLx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:09:05 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)) 11:09:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:09:05 INFO - Running setup.py (path:/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/T/pip-FGHceP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:09:05 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)) 11:09:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:09:05 INFO - Running setup.py (path:/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/T/pip-ypUwdZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:09:05 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)) 11:09:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:09:05 INFO - Running setup.py (path:/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/T/pip-96DVT5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:09:06 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)) 11:09:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:09:06 INFO - Running setup.py (path:/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/T/pip-MrYFNb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:09:06 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)) 11:09:06 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)) 11:09:06 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)) 11:09:06 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)) 11:09:06 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)) 11:09:06 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)) 11:09:06 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 11:09:06 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 11:09:06 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 11:09:06 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 11:09:06 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 11:09:07 INFO - Downloading blessings-1.6.tar.gz 11:09:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 11:09:07 INFO - Installing collected packages: blessings 11:09:07 INFO - Running setup.py install for blessings 11:09:07 INFO - Successfully installed blessings 11:09:07 INFO - Cleaning up... 11:09:07 INFO - Return code: 0 11:09:07 INFO - Done creating virtualenv /builds/slave/test/build/venv. 11:09:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:09:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:09:08 INFO - Reading from file tmpfile_stdout 11:09:08 INFO - Current package versions: 11:09:08 INFO - blessings == 1.6 11:09:08 INFO - blobuploader == 1.2.4 11:09:08 INFO - docopt == 0.6.1 11:09:08 INFO - functools32 == 3.2.3-2 11:09:08 INFO - jsonschema == 2.5.1 11:09:08 INFO - manifestparser == 1.1 11:09:08 INFO - mozInstall == 1.12 11:09:08 INFO - mozcrash == 1.0 11:09:08 INFO - mozdebug == 0.1 11:09:08 INFO - mozdevice == 0.50 11:09:08 INFO - mozfile == 1.2 11:09:08 INFO - mozhttpd == 0.7 11:09:08 INFO - mozinfo == 0.9 11:09:08 INFO - mozleak == 0.1 11:09:08 INFO - mozlog == 3.4 11:09:08 INFO - moznetwork == 0.27 11:09:08 INFO - mozprocess == 0.25 11:09:08 INFO - mozprofile == 0.28 11:09:08 INFO - mozrunner == 6.13 11:09:08 INFO - mozscreenshot == 0.1 11:09:08 INFO - mozsystemmonitor == 0.3 11:09:08 INFO - moztest == 0.8 11:09:08 INFO - mozversion == 1.4 11:09:08 INFO - psutil == 3.1.1 11:09:08 INFO - requests == 1.2.3 11:09:08 INFO - wsgiref == 0.1.2 11:09:08 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:09: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')]} 11:09:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:09: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')]} 11:09:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:09:08 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 0x1029f3b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1029f7768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff429560e30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102703d30>, '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 0x1026b7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1026f8be8>, '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.LejAOfT8r6/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.nUkVAnWmBP/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/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 11:09:08 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 11:09:08 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 11:09:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LejAOfT8r6/Render', 11:09:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:09:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:09:08 INFO - 'HOME': '/Users/cltbld', 11:09:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:09:08 INFO - 'LOGNAME': 'cltbld', 11:09:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:09:08 INFO - 'MOZ_NO_REMOTE': '1', 11:09:08 INFO - 'NO_EM_RESTART': '1', 11:09:08 INFO - 'PAGER': '/bin/cat', 11:09:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:09:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:09:08 INFO - 'PWD': '/builds/slave/test', 11:09:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:09:08 INFO - 'SHELL': '/bin/bash', 11:09:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.nUkVAnWmBP/Listeners', 11:09:08 INFO - 'TMPDIR': '/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/T/', 11:09:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:09:08 INFO - 'USER': 'cltbld', 11:09:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:09:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:09:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:09:08 INFO - 'XPC_FLAGS': '0x0', 11:09:08 INFO - 'XPC_SERVICE_NAME': '0', 11:09:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:09:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:09:08 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)) 11:09:08 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)) 11:09:08 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)) 11:09:08 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)) 11:09:08 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)) 11:09:08 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)) 11:09:08 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)) 11:09:08 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)) 11:09:08 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)) 11:09:08 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)) 11:09:08 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)) 11:09:08 INFO - Cleaning up... 11:09:08 INFO - Return code: 0 11:09:08 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 11:09: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')]} 11:09:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:09: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')]} 11:09:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:09: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', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1029f3b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1029f7768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff429560e30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102703d30>, '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 0x1026b7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1026f8be8>, '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.LejAOfT8r6/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.nUkVAnWmBP/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/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 11:09: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', 'jsonschema'] in /builds/slave/test/build 11:09: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 jsonschema 11:09:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LejAOfT8r6/Render', 11:09:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:09:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:09:08 INFO - 'HOME': '/Users/cltbld', 11:09:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:09:08 INFO - 'LOGNAME': 'cltbld', 11:09:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:09:08 INFO - 'MOZ_NO_REMOTE': '1', 11:09:08 INFO - 'NO_EM_RESTART': '1', 11:09:08 INFO - 'PAGER': '/bin/cat', 11:09:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:09:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:09:08 INFO - 'PWD': '/builds/slave/test', 11:09:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:09:08 INFO - 'SHELL': '/bin/bash', 11:09:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.nUkVAnWmBP/Listeners', 11:09:08 INFO - 'TMPDIR': '/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/T/', 11:09:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:09:08 INFO - 'USER': 'cltbld', 11:09:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:09:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:09:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:09:08 INFO - 'XPC_FLAGS': '0x0', 11:09:08 INFO - 'XPC_SERVICE_NAME': '0', 11:09:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:09:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:09:08 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 11:09:08 INFO - Cleaning up... 11:09:08 INFO - Return code: 0 11:09:08 INFO - Running post-action listener: _resource_record_post_action 11:09:08 INFO - Running post-action listener: _start_resource_monitoring 11:09:08 INFO - Starting resource monitoring. 11:09:08 INFO - [mozharness: 2017-05-16 18:09:08.754610Z] Finished create-virtualenv step (success) 11:09:08 INFO - [mozharness: 2017-05-16 18:09:08.755543Z] Running install step. 11:09:08 INFO - Running pre-action listener: _resource_record_pre_action 11:09:08 INFO - Running main action method: install 11:09:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:09:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:09:09 INFO - Reading from file tmpfile_stdout 11:09:09 INFO - Detecting whether we're running mozinstall >=1.0... 11:09:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 11:09:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 11:09:09 INFO - Reading from file tmpfile_stdout 11:09:09 INFO - Output received: 11:09:09 INFO - Usage: mozinstall [options] installer 11:09:09 INFO - Options: 11:09:09 INFO - -h, --help show this help message and exit 11:09:09 INFO - -d DEST, --destination=DEST 11:09:09 INFO - Directory to install application into. [default: 11:09:09 INFO - "/builds/slave/test"] 11:09:09 INFO - --app=APP Application being installed. [default: firefox] 11:09:09 INFO - mkdir: /builds/slave/test/build/application 11:09:09 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'] 11:09:09 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 11:09:29 INFO - Reading from file tmpfile_stdout 11:09:29 INFO - Output received: 11:09:29 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 11:09:29 INFO - Running post-action listener: _resource_record_post_action 11:09:29 INFO - [mozharness: 2017-05-16 18:09:29.874095Z] Finished install step (success) 11:09:29 INFO - [mozharness: 2017-05-16 18:09:29.874267Z] Running run-tests step. 11:09:29 INFO - Running pre-action listener: _resource_record_pre_action 11:09:29 INFO - Running main action method: run_tests 11:09:29 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 11:09:29 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 11:09:29 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 11:09:29 INFO - 2017-05-16 11:09:29.889 screenresolution[1634:8091] starting screenresolution argv=screenresolution get 11:09:29 INFO - 2017-05-16 11:09:29.891 screenresolution[1634:8091] Display 0: 1600x1200x32@60 11:09:29 INFO - 2017-05-16 11:09:29.897 screenresolution[1635:8094] starting screenresolution argv=screenresolution list 11:09:29 INFO - Available Modes on Display 0 11:09:29 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 11:09:29 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 11:09:29 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 11:09:29 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 11:09:30 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 11:09:30 INFO - Intel Iris: 11:09:30 INFO - Chipset Model: Intel Iris 11:09:30 INFO - Type: GPU 11:09:30 INFO - Bus: Built-In 11:09:30 INFO - VRAM (Dynamic, Max): 1536 MB 11:09:30 INFO - Vendor: Intel (0x8086) 11:09:30 INFO - Device ID: 0x0a2e 11:09:30 INFO - Revision ID: 0x0009 11:09:30 INFO - Displays: 11:09:30 INFO - Display: 11:09:30 INFO - Resolution: 1600 x 1200 @ 60 Hz 11:09:30 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 11:09:30 INFO - Display Serial Number: 1600x1200 60 11:09:30 INFO - Main Display: Yes 11:09:30 INFO - Mirror: Off 11:09:30 INFO - Online: Yes 11:09:30 INFO - Rotation: Supported 11:09:30 INFO - Return code: 0 11:09:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 11:09:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 11:09:30 INFO - Python 2.7.3 11:09:30 INFO - Return code: 0 11:09:30 INFO - grabbing minidump binary from tooltool 11:09:30 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')]} 11:09:30 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 0x102703d30>, '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 0x1026b7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1026f8be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 11:09:30 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 11:09:30 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 11:09:30 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 11:09:30 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 11:09:30 INFO - Return code: 0 11:09:30 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 11:09:30 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 11:09:30 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 11:09:30 INFO - ENV: RUST_BACKTRACE is now 1 11:09:30 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 11:09:30 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 11:09:30 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/IW_fDKRQQTu8bzRdUhdCeA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0052', '--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 11:09:30 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/IW_fDKRQQTu8bzRdUhdCeA/artifacts/public/build/target.crashreporter-symbols.zip --title t-yosemite-r7-0052 --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 11:09:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LejAOfT8r6/Render', 11:09:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:09:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:09:30 INFO - 'HOME': '/Users/cltbld', 11:09:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:09:30 INFO - 'LOGNAME': 'cltbld', 11:09:30 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 11:09:30 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 11:09:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:09:30 INFO - 'MOZ_NO_REMOTE': '1', 11:09:30 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 11:09:30 INFO - 'NO_EM_RESTART': '1', 11:09:30 INFO - 'PAGER': '/bin/cat', 11:09:30 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:09:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:09:30 INFO - 'PWD': '/builds/slave/test', 11:09:30 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 11:09:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:09:30 INFO - 'RUST_BACKTRACE': '1', 11:09:30 INFO - 'SHELL': '/bin/bash', 11:09:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.nUkVAnWmBP/Listeners', 11:09:30 INFO - 'TMPDIR': '/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/T/', 11:09:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:09:30 INFO - 'USER': 'cltbld', 11:09:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:09:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:09:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:09:30 INFO - 'XPC_FLAGS': '0x0', 11:09:30 INFO - 'XPC_SERVICE_NAME': '0', 11:09:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:09:30 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/IW_fDKRQQTu8bzRdUhdCeA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0052', '--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 11:09:30 INFO - mozversion application_buildid: 20170516093113 11:09:30 INFO - mozversion application_changeset: b8e9b674033bcd1f3a4c59b9d0ee7619c1a17cc5 11:09:30 INFO - mozversion application_display_name: Nightly 11:09:30 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 11:09:30 INFO - mozversion application_name: Firefox 11:09:30 INFO - mozversion application_remotingname: firefox 11:09:30 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 11:09:30 INFO - mozversion application_vendor: Mozilla 11:09:30 INFO - mozversion application_version: 55.0a1 11:09:30 INFO - mozversion platform_buildid: 20170516093113 11:09:30 INFO - mozversion platform_changeset: b8e9b674033bcd1f3a4c59b9d0ee7619c1a17cc5 11:09:30 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 11:09:30 INFO - mozversion platform_version: 55.0a1 11:09:30 INFO - using testdate: 1494958170 11:09:30 INFO - actual date: 1494958170 11:09:30 INFO - starting webserver on 'localhost:49228' 11:09:30 INFO - SUITE-START | Running 2 tests 11:09:30 INFO - TEST-START | dromaeo_css 11:09:30 INFO - operating with platform_type : mac_ 11:09:30 INFO - Initialising browser for dromaeo_css test... 11:09:30 INFO - TEST-INFO | started process 1645 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/T/tmp9QtMAX/profile http://localhost:49228/getInfo.html) 11:09:31 INFO - PID 1645 | JavaScript warning: resource://gre/modules/addons/XPIProvider.jsm, line 2930: String.localeCompare is deprecated; use String.prototype.localeCompare instead 11:09:32 INFO - PID 1645 | 2017-05-16 11:09:32.911 plugin-container[1648:8286] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x973f, name = 'com.apple.tsm.portname' 11:09:32 INFO - PID 1645 | See /usr/include/servers/bootstrap_defs.h for the error codes. 11:09:32 INFO - PID 1645 | 2017-05-16 11:09:32.914 plugin-container[1648:8286] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4917, name = 'com.apple.CFPasteboardClient' 11:09:32 INFO - PID 1645 | See /usr/include/servers/bootstrap_defs.h for the error codes. 11:09:32 INFO - PID 1645 | 2017-05-16 11:09:32.914 plugin-container[1648:8286] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 11:09:33 INFO - PID 1645 | __metrics Screen width/height:1600/1200 11:09:33 INFO - PID 1645 | colorDepth:24 11:09:33 INFO - PID 1645 | Browser inner width/height: 1024/691 11:09:33 INFO - PID 1645 | __metrics 11:09:33 INFO - PID 1645 | 11:09:33 INFO - PID 1645 | ###!!! [Child][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost 11:09:33 INFO - PID 1645 | 11:09:34 INFO - TEST-INFO | 1645: exit 0 11:09:34 INFO - Browser initialized. 11:09:34 INFO - Running cycle 1/1 for dromaeo_css test... 11:09:34 INFO - TEST-INFO | started process 1650 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/T/tmp9QtMAX/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 11:09:35 INFO - PID 1650 | 2017-05-16 11:09:35.777 plugin-container[1651:8433] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4347, name = 'com.apple.tsm.portname' 11:09:35 INFO - PID 1650 | See /usr/include/servers/bootstrap_defs.h for the error codes. 11:09:35 INFO - PID 1650 | 2017-05-16 11:09:35.777 plugin-container[1651:8433] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9a0b, name = 'com.apple.CFPasteboardClient' 11:09:35 INFO - PID 1650 | See /usr/include/servers/bootstrap_defs.h for the error codes. 11:09:35 INFO - PID 1650 | 2017-05-16 11:09:35.777 plugin-container[1651:8433] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 11:09:36 INFO - PID 1650 | 2017-05-16 11:09:36.506 plugin-container[1652:8480] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8f3f, name = 'com.apple.tsm.portname' 11:09:36 INFO - PID 1650 | See /usr/include/servers/bootstrap_defs.h for the error codes. 11:09:36 INFO - PID 1650 | 2017-05-16 11:09:36.507 plugin-container[1652:8480] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x461b, name = 'com.apple.CFPasteboardClient' 11:09:36 INFO - PID 1650 | See /usr/include/servers/bootstrap_defs.h for the error codes. 11:09:36 INFO - PID 1650 | 2017-05-16 11:09:36.507 plugin-container[1652:8480] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 11:09:45 INFO - PID 1650 | RSS: Main: 234790912 11:09:45 INFO - PID 1650 | 11:12:50 INFO - PID 1650 | Cycle 1(1): loaded http://localhost:49228/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49228/tests/dromaeo/cssquery-ext.html) 11:12:51 INFO - PID 1650 | RSS: Main: 264097792 11:12:51 INFO - PID 1650 | 11:15:53 INFO - PID 1650 | Cycle 1(1): loaded http://localhost:49228/tests/dromaeo/cssquery-ext.html (next: http://localhost:49228/tests/dromaeo/cssquery-jquery.html) 11:15:53 INFO - PID 1650 | RSS: Main: 266579968 11:15:53 INFO - PID 1650 | 11:18:55 INFO - PID 1650 | Cycle 1(1): loaded http://localhost:49228/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49228/tests/dromaeo/cssquery-mootools.html) 11:18:56 INFO - PID 1650 | RSS: Main: 270143488 11:18:56 INFO - PID 1650 | 11:21:57 INFO - PID 1650 | Cycle 1(1): loaded http://localhost:49228/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49228/tests/dromaeo/cssquery-prototype.html) 11:21:58 INFO - PID 1650 | RSS: Main: 267907072 11:21:58 INFO - PID 1650 | 11:25:01 INFO - PID 1650 | Cycle 1(1): loaded http://localhost:49228/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49228/tests/dromaeo/cssquery-yui.html) 11:25:02 INFO - PID 1650 | RSS: Main: 269737984 11:25:02 INFO - PID 1650 | 11:28:03 INFO - PID 1650 | Cycle 1(1): loaded http://localhost:49228/tests/dromaeo/cssquery-yui.html (next: http://localhost:49228/tests/dromaeo/cssquery-dojo.html) 11:28:03 INFO - PID 1650 | RSS: Main: 271134720 11:28:03 INFO - PID 1650 | 11:28:03 INFO - PID 1650 | __start_tp_report 11:28:03 INFO - PID 1650 | _x_x_mozilla_page_load 11:28:03 INFO - PID 1650 | _x_x_mozilla_page_load_details 11:28:03 INFO - PID 1650 | |i|pagename|runs| 11:28:03 INFO - PID 1650 | |0;dojo.html;3559.4126969050108;3565.714742820573;3570.9464358034625;3603.513525674033;3628.129248980244;6134.8652196263865;6162.506999440046;6205.255369355678;6208.1308616793685;6230.376962303767;5095.847124586259;6132.355508992489;6212.565120441571;6251.187345645071;6255.593386429879;5405.351357837046;5449.209864569635;5484.61607687462;5506.03644362237;5602.691851948141;5396.622236443451;5492.972535137319;5539.695316757577;5567.638103523258;5614.073677843171;4253.936190957138;4911.828086017008;5467.753951072212;5471.23402723619;5545.584081193906;5400.216968539579;5434.619576629638;5451.018816613008;5454.10007348787;5458.5087342139395;5378.381486129067;5387.703676297802;5391.24522566837;5407.648502847342;5421.268128802601;4678.953210467886;5876.6180198286975;6133.916185115316;6179.783707570258;6185.597936134137;5562.360328562227;6648.135742353512;6679.699413526354;6701.195856497252;6715.6977935993;5762.74067666956;6644.833879153011;6814;6817.352351526647;6833.043373927654;5636.830895073154;6167.074938759222;6271.247450305992;6296.464800492009;6307.022411526266;6382.521310901701;6847.144106986627;6929.480288978347;6934.16789985205;6935.167779866449;5538;6538.117354157223;6567.376099270562;6623.933760662428;6659.300773418819;6477.870442591122;6769.526133170631;6802.93197068023;6812.01225822253;6813.4208592270215;6524.739010439529;6573.934260657332;6650.334966503311;6652.501062420241;6672.766453174116;6667.833304167435;6694.598324100472;6699.933000669931;6701.832454188587;6711.261761206263;4685.601723853442;5121.07820592297;5317.308749862493;5317.813876514381;5322.600804939645;4229.830806767756;4732.858014259577;4748.026654535881;4769.14155452015;4786.617070634341;5003.649744517848;6007.969960150172;6262.3111457739615;6271.717772700239;6336.52476064297;6793.150856142982;6820.147481564804;6826.692798823966;6839.1451068616425;6846.349596788297;6239.78160764371;6357.459615932605;6368.64972426521;6371.649559274284;6417.03744438338;5749.68376739275;5848.122781582797;5965.164876917235;5983.1623572699855;6011.939880601226;6740.157480314961;6809.910414333683;6891.448684105159;6901.344372284625;6942.201646810681;6799.456043516408;6806.387425131762;6822.692978815866;6836.487263455161;6846.281140480178;6927.965360173167;6951.7914462566205;6972.79081627552;6984.161900571964;6990.405815505739;5453.536449401687;6370.8088757337355;6465.579737317059;6577.868442631217;6659.567128136656;6606.306337834651;6693.497987650849;6743.527953043436;6760.08738820253;6764.661766911537;6544.214694236722;6562.212534495891;6569.507286953402;6578.901316480161;6601.438877695257;6283.434490895658;6315.5263355247625;6319.46284565817;6411.935880641134;6569.080328754074;5422.295101636762;6344.809655710336;6380.042993550819;6416.197975253093;6417.2620148681635;6329.335419780857;6450;6478.838029049311;6559.868802624016;6567.671616419256;4997.275395067622;6312.747490100344;6459.483241340776;6473.385028422293;6486.351364863476 11:28:03 INFO - PID 1650 | |1;ext.html;790.8453657659817;803.3653413803095;831.310012689468;1195.3425615911246;1269.435101379885;37820.86537403875;38741.41887871681;38757.80621096898;38826.417603735965;38881.41677874831;39048.414273785915;39271.80364098182;39338.8033059835;39442.40836387449;39528.209435811266;7775.416843736714;7928;8250.46371985822;8470;8503.404761666687;8452.661893524251;8536.359773017017;8600.784980375509;8603.655853765842;8609.26821220197;8495.150484951486;8502.14978502148;8598.914010859906;8600.913990860074;8606.87089693655;8487.957560212159;8520.105388934166;8573.657053717843;8596;8600.526971016592;7069.434445244368;8277.83444331116;8278.668853245863;8280.130586288473;8312.750617481484;36818.815905920295;37001;37031.074223144635;37068.25863482742;37149.442758358644;3554.7156227501996;3600.4059330210484;3605.5132557104716;3605.9098522536883;3608.783472991629;30458.54312185319;30508;30553.694463055308;30634.69365306341;30700.771969121208;3086.6296044474807;3086.8302243376606;3109.4403007458873;3111.175538482304;3127.3276245607303;186419.1358086429;252646;284733;295007;298006.5099674488;47212.347567834964;47551.811204719466;47582;47660.52339476561;47722;49280.753596232506;49563;49613.25580116301;49663;49868;11802.822957655642;14111.435542578387;14132.717345653029;14135.646608834655;14136.646583835487;11405.543778248777;11485.425728713531;11512.481938312796;11560.421978901022;11566.132540059361;11456.797036311234;12980.221186728826;13231.470741170438;13311.600651980456;14759.483418080315;6039.305479869783;6084.391560843968;6121.785737499166;6124.908126378108;6135.5398345124295;996.840505519128;1021.8722659667542;1022.1924679503257;1040.7450174707244;1062.9362238265574;17019.659606808043;20483.795162048486;22513.099476021103;24390.51218975646;24457.899394526932;1109.328856042099;1115.7656892052598;1141.9086473082134;1147.9196456248148;1158.6987383280407;12322.199057061262;12464.688382790322;12521.499140034297;12536.749265014649;12633.93683031579;295895.52052240033;323659;324027;324845;325458.37270813494;52284.47715522872;52296.738516307174;52323.73838130861;52338.95322093613;52509.47490525122;1497.2513743128436;1497.5133081748259;1535.2400561721795;1542.5603702944607;1566.8746500279951;22740.772592274527;23898.04407823737;24043.639345409483;24087.51824963526;24156.396090097183;30732;37390.8130459346;37472.81263593664;37477.62522374741;37525.81237093797;273347;273722;273798;273882;275485.24514754594;32497.750224977313;33343.83328083344;33474.16314592155;33963.49054764131;34155.316893662486;42248.36627450529;44435.33346999733;44590.554094459934;44662.88342791456;44676.55323446854;28992.535634471307;29895.10314690563;30212.69787302099;31155.68844311528;31281.530777037908;30035;30697;30769.538456922717;30931.69068309378;30979.380412391176;35433.82283088568;36129.096772580895;36816.07959801026;36981.63018369889;37061.25877482489;29150.416991660473;29559.704402955696;29978.400431991675;30028;30630.846845765627;23139.65290520677;24241.39396515101;24307.756922430548;24718.505629887663;24756.13353532618 11:28:03 INFO - PID 1650 | |2;jquery.html;429.1716986216601;432.17023315234746;434.5589226934662;448.4730441730966;450.96053596460166;19375.612487750255;19426.83438993658;20069.297574584893;20939.790602093974;21787.673184902218;21434.892825535888;22622.88688556555;22681;23031.848407579637;23258;6067.848303792407;6180.980138277179;6217.813465596039;6240.812775616716;6255.812325630239;6121.479674227674;6165.599236049665;6180.505559555225;6252.34350393209;6258.311585725566;6001.819945401623;6172.69136543171;6204.162438070848;6205.720742566596;6232.158658581102;5264.447233040552;6141.692915354214;6218.098375735515;6241.407066328691;6242.656653884034;4462.709923854972;5416.214648875891;5519.9448005520235;5548.195511650788;5556.111022236423;28220.576691349645;28385.148445546667;28703.995360162295;29152.708472915212;29230.85384573085;1875.7186422036805;2162.6755986602134;2240.171136679423;2241.4172315198;2243.674667173251;17112.401065962757;17112.57218569533;17277.56806079858;17441.127943602896;17715.291388344453;1870.9251629934922;2131.936041918745;2140.165335519149;2175.434387059376;2175.4670105824175;164635;169426.1528692361;171235.14382428132;171572.1421392885;171857;12622.873771262353;12627.494900204072;12823.102382833113;12871.03467239942;13113.934430327978;13974.441022359195;16361.672766544603;16647.250873710713;16647.50057498277;16704.832951670327;8684.826303473896;8980.281577473786;9063;9166.358354915092;9169.954150229207;8473.95763021181;8499;8528.275096616733;8654.091320411266;8660.30717542595;6500.512461565307;8091.838163236703;8101.432899697083;8107;8113.107558168596;2138.5295235048575;2139.5720855828895;2141.5716856628737;2150.860194087379;2308.907643694267;911.9224865886474;946.7254496196026;951.6431338248157;977.8630991661173;1008.2841182760322;20181.293654722307;20772.79227207739;20778.376648700563;20828.1668733252;21038.368848934555;879.7052987249343;970.1365784451843;972.8929817720186;1004.5379125602159;1005.6781829814389;8880.911190888137;10109.696709098738;10179.338343007681;10672.573097076185;10805.729856753644;545361;545767;550385;552637;559062;119034;127331.72668273198;128526;130971;132132.0180197298;1181.710480932177;1225.7548490301974;1238.3313010973973;1245.4893493667553;1246.1277106025818;18719.157637906053;18929.716054258923;18996;21878.67181992303;22269.777302226772;54327;55645.165322521;56839;56995.43004569901;57136.71431642815;75789;76036;76102;76345.23654763382;76388.23611763811;9035.277177825626;9061.68284110053;9185.494797786187;9208.401453905475;9210.447373157363;12137.332446715516;12899.613011609667;14268.500602479278;14464.9151313654;14538.127712337293;11749.647510574696;11822.704432389259;12160.878391216329;12173.817392738938;12195.329256890956;11304.943475282571;11336.829947550958;11373.20387572862;11521.837300819276;11562.826557601811;11835.46740396667;11899.881001189877;12079.094067945114;12167.695807604881;12321.260724356567;8832.29341652679;8923.955380223057;9037.231835294073;9180;9326.114019168168;9098.499582523025;10326.896731032894;12061.216020958609;12086.093542984136;12247.510099595916 11:28:03 INFO - PID 1650 | |3;mootools.html;1370.403874314673;1441.66120961574;1445.2051371745538;1454.7236025155216;1455.294182321573;9700.708978730641;11694.649160525187;11918;12249.816252756216;12279.938600306987;11881.465334059967;11940.283582985026;12108.81836772449;12121.636350909464;12121.696957576065;2574.343542396686;2608.3479130217447;2622.8032897532667;2625.448655782288;2625.842449453039;2526.4820711754137;2578.4585237100137;2590.4689538644534;2595.7404259573987;2603.323135984648;2518.26970178648;2519.5842685956886;2562.7949764018836;2577.059373328731;2578.574535201699;2510.7112682041525;2526.759957804006;2565.1022142250063;2565.512552615016;2572.9742702573017;2140.6146893559153;2203.22886989554;2478.1821998740375;2534.4804315115357;2548.4011257354505;11664.416779161009;11891.405429728478;11901.047916166772;11906.642800715992;11911.523539058428;6553.279139294674;6772.45820334377;6809.148856392951;6813.250542440328;6816.352446517573;4013.9397909031386;4333.328334108187;4384.4519435070615;4423.823047078113;4424.557544245582;1225.154643296123;1244.5333000124954;1264.8245018568048;1287.5686644973946;1294.6828027133317;16179.919100404422;16415.507534773977;16501.504954851374;16569.922955007885;16595.50213493597;5495.560355171657;5606.159076138611;5640.85897852557;5803.622764520377;5864.824055278264;11763.353015584054;11879.28724276529;11884.821727674092;11888.227265227733;11900;6603.141591593062;6610.8016759497295;6612.173478315211;6615.206175258903;6616.172978377703;5064.392272927273;6112.72492737819;6184.536159788034;6239.157713708682;6257.561970662101;4294.527601963781;4393.560643935645;4428.180786554498;4453.242948698729;4455.977720111378;1278.7186818899827;1292.7220647560819;1296.7730647136714;1304.3282709404664;1317.7562151002096;775.8836174573859;790.2334735306744;795.2246559604339;795.9801004974806;801.3749275565068;6018.187544681499;6518.185226846645;6682.732690692415;7027.332403421667;7108.182559005677;887.8712586674895;916.9587368568431;925.7778133247972;928.8792456980684;933.9119925286965;3371.8482668279985;3458.6856994342493;3744.64425879541;3866.130120722815;3928.4696565963227;14605.926970365294;14637.92681036588;15239.3904243829;15245.923770381078;15280.465183718516;14911.70176596462;14991.850081499046;15274.465393711389;15302.234888255543;15309.693806124038;8490.066092729794;8697.608607612672;8727.389082764146;8809.295256379473;8811.251043661361;7116.395106416013;7221.384455360306;7255.2019277880445;7280.96359518199;7306.4520160989205;10511;13163;13697.109687870257;13737.931310343385;13741.519046833313;13678.931605342308;14024.509142179975;14077.648058798612;14132.929335353258;14260;9242.953785231031;9487.383320084173;9524.190443812353;9553.665621703485;9571.808563828823;10727.141828653845;10792.352458852494;10829.18781091437;10862.076723478593;10884.07485363753;9205.907940920504;9223.077692230723;9223.86164207524;9242.537873106183;9442.244620430487;8968.372213944962;8994.145556172152;9024.593893274687;9025.41334813235;9041.276697864287;8159.755207343789;8262.091169971418;8327.666893324442;8347.332213423033;8349.666013359398;5417.593680474059;5998.88002239944;6146.5390095742105;6154.230721159855;6295.401936815995;5994.130851026664;6234.750609975732;6260.906086408617;6326.683665816783;6343.651099189589 11:28:03 INFO - PID 1650 | |4;prototype.html;329.75433302189873;399.0761387388196;408.0451742921565;409.0693671896435;410.83977248872964;5520.972395138028;6126.234220722404;6230.688465576725;6389.329120442345;6459.870802583945;6136.631802091878;6234.906476402857;6251.967795619133;6267.994241842613;6337.936620633793;4423.761346822895;4568.634509239253;4571.817127314904;4574.405327307462;4575.22221222393;4381.912361752763;4496.325551167319;4531.773411329436;4544.4092268005115;4562.475315338729;3891.8443262269475;4467.7542735149555;4484.753338566377;4486.551344865504;4489.25927222008;4267.658587313008;4475.5748203920575;4516.774161291955;4518.390017347649;4530.618161460753;3809.9238015239816;3818.4845045918723;3830.578636349999;3874.1476875087437;3876.0116170376655;4994;5997.310309314441;6072.51419886408;6074.658937584311;6079.392060793885;4896.265560165968;4949.257611358289;5386.326709161355;5413.945860541384;5421.021327215577;5135.974320128375;6412.871742565123;6601.7359305627715;6651.733930642768;6682.599044057372;4326.264535029021;4348.73907565544;4360.860180669913;4371.519132895379;4372.366006928977;5450.672959622435;6347.936520634827;6801.557898736566;6956.826079348006;7096.858062838714;4465.866024019285;4468.687191896601;4474.440694913136;4478.641708663299;4493.303537951622;3602.3155600436357;4296.978515107467;4559.589636932626;4567.725936443758;4579.839705610354;5340.359156901119;5359.598030147755;5436.483534064258;5441.809536666197;5449.618526703172;5487.396386397493;6666.733330666719;6670.966645166744;6711.926091825285;6722.66386680664;1521.186165401505;1532.6934613077428;1535.332130523226;1540.784290199373;1552.8741662294747;991.5092029445326;1001.7746007148334;1006.9848952265722;1008.7931973945324;1021.2953062386929;5321.9356128773625;6108.664023478663;6118.388161183935;6367.904481432783;6389.233292004989;6732.596044237362;6824.317568243136;6911.067005954233;6912.585244885323;6953.443724502028;6282.780102696475;6315.57899472615;6340.922043252658;6365.745370185141;6383.6488993104895;3284.9343013139605;3588.990165507324;3604.017790215371;3611;3611.963880361163;6812.557183783038;6960.155558776928;7064.43484521237;7073.717051317993;7111.53775004623;6922.930770692329;7079.398251148607;7083.574985500886;7163.057490331655;7171.390431813355;5840.970795145997;7074.575525468488;7135.108111486064;7152.642367881585;7209.495335326476;5745.798897038583;6137.079438084323;6410.942194537849;6974.930250697631;7014.123234595676;5947.791827286024;6250.718717657625;6792.796216113525;6870.31296870309;6878.965605171942;6475;7075.929240707527;7161.820954475971;7176.174739904769;7182.712691492491;4147.585241475828;4152.148809494107;4419.999900090424;4429.889252768707;4491.258942274488;7027.297270273137;7035.507514473938;7046.577205367695;7051.717931282691;7062.329078737512;6483.7730679425995;6692.966535167293;6766;6789.456843452611;6810.523263371517;5535.584831137761;6710.429613482714;6728.562643428161;6731.946756239394;6733.192016957996;5913.763449462145;6321.273053598895;6413.594901911783;6472.902906456313;6588.242352129567;5831.271091113611;6700.276305204721;6726.6636668167375;6733.764318248838;6749.898751518829;6006.849828754316;6478.287388387368;6537.313582074004;6560.901586476111;6591.242007169237 11:28:03 INFO - PID 1650 | |5;yui.html;728.730369763187;811.9431639785215;843.6583183810557;847.0555330806153;847.6355167278068;16526.83473165268;16996.320147194096;17024.404145854896;18606.255749769993;19264.325748598803;17814.465566033006;18263;18354;18608.72086918694;18799.62400751984;957.1003256938473;969.418348990607;978.740633732058;979.109010800172;983.1593987140999;968.5641461342389;982.2731178927578;984;985.0789511806483;986.595495846703;1010.6917390195987;1023.8105950399163;1024.4109636958742;1026.3892983674739;1027.039717863796;1001.3541265883464;1007.3150257824676;1021.4994652620237;1021.8824835143942;1024.2727663359024;734.0090877315635;742.6064185981438;755.9622018899033;756.2928661701327;757.1141764135974;16875.324987000506;17246.3963761269;17356.132193390407;17429.738553921703;17440.912795435943;16353.345866165339;17308.91345543264;17633;17672.646547069115;17818.643627127516;14101.71796564063;14199.503017394341;14350.282485875665;14429.062110962754;14462.49381271661;3535.5403797506415;3558.0215440754;3582.1223800168864;3586.0317714217017;3587.1390866192187;85305;135828;137076.62923370837;137590.3120484391;140428.99999999898;13107.082504224803;13235.73528529424;13367.866321336856;13471.326433678278;13493.122947008413;25649.358766030997;25657.845396957182;25840.837162327363;25882.741172588034;25899.482010360065;7638.961805191049;7663.501872378278;7675.654595543214;7716.99679041721;7733.961330193313;3797.2215695782297;3942.1130245695063;3944.2111577684577;3947.072437977073;3952;4362.738235705868;4455.1535208310315;4456.866294011184;4457.3091170868565;4465.660301909479;791.0467886197118;808.0949336742885;810.9026916769907;813.7965508622844;813.8453693798277;594.6877889108166;636.312782195228;652.1391762872962;665.0987911380107;680.1430197950618;9842.557084931052;10160.644377446753;10410.088332866924;11991.700207494709;12046.216995895415;532.7256462921598;630.6184758221302;640.9262934762562;643.9742410303535;661.7353058776432;4184.8744537663915;5423.806762512241;5434.402215756264;5443.101888188484;5488.862778430571;90082.54958725165;100951.99048009573;110787;111452;111526;77706;98074;98499;98702;99257.17467830837;88198;88465;88624.55687721648;88660.5566972174;89839;8989.685361012333;11030.779384412428;11054.392008439292;11244.269122507338;11356.716082098013;39253.01867453336;42756.78621606872;43490.782546087066;43497.56502435062;43957;48943.5105648939;54463.455365445836;55801.72099139641;56316;56798;14078.859211407755;14085;14355.354009069408;14412.13527188372;14480.637984050483;15286.235688215767;15584.766228506809;15754.448594299603;15840.60398490047;15854.7621785671;13988;14418.783718244444;14567.562973110824;14585.854141458874;14645.633859153606;14011.929940350234;14170.00809943294;14486.275686215859;14510.854891451372;14611.269436527919;11427.542898283975;14039.859601404263;14191.858081419467;14340.139591624536;14363.640908977359;9939.403635781877;10187.490625468845;10338.483075846027;10612.893871061191;10638.14894808429;9999.450030248407;10604.787904241717;11601.477933492844;11706.122040847142;11896.464659090188 11:28:03 INFO - PID 1650 | __end_tp_report 11:28:03 INFO - PID 1650 | __start_cc_report 11:28:03 INFO - PID 1650 | _x_x_mozilla_cycle_collect,350 11:28:03 INFO - PID 1650 | __end_cc_report 11:28:03 INFO - PID 1650 | __startTimestamp1494959283936__endTimestamp 11:28:03 INFO - PID 1650 | 11:28:03 INFO - PID 1650 | ------- Summary: start ------- 11:28:03 INFO - PID 1650 | Number of tests: 6 11:28:03 INFO - PID 1650 | 11:28:03 INFO - PID 1650 | [#0] dojo.html Cycles:165 Average:6098.94 Median:6344.81 stddev:778.03 (12.3%) stddev-sans-first:754.48 11:28:03 INFO - PID 1650 | Values: 3559.4 3565.7 3570.9 3603.5 3628.1 6134.9 6162.5 6205.3 6208.1 6230.4 5095.8 6132.4 6212.6 6251.2 6255.6 5405.4 5449.2 5484.6 5506.0 5602.7 5396.6 5493.0 5539.7 5567.6 5614.1 4253.9 4911.8 5467.8 5471.2 5545.6 5400.2 5434.6 5451.0 5454.1 5458.5 5378.4 5387.7 5391.2 5407.6 5421.3 4679.0 5876.6 6133.9 6179.8 6185.6 5562.4 6648.1 6679.7 6701.2 6715.7 5762.7 6644.8 6814.0 6817.4 6833.0 5636.8 6167.1 6271.2 6296.5 6307.0 6382.5 6847.1 6929.5 6934.2 6935.2 5538.0 6538.1 6567.4 6623.9 6659.3 6477.9 6769.5 6802.9 6812.0 6813.4 6524.7 6573.9 6650.3 6652.5 6672.8 6667.8 6694.6 6699.9 6701.8 6711.3 4685.6 5121.1 5317.3 5317.8 5322.6 4229.8 4732.9 4748.0 4769.1 4786.6 5003.6 6008.0 6262.3 6271.7 6336.5 6793.2 6820.1 6826.7 6839.1 6846.3 6239.8 6357.5 6368.6 6371.6 6417.0 5749.7 5848.1 5965.2 5983.2 6011.9 6740.2 6809.9 6891.4 6901.3 6942.2 6799.5 6806.4 6822.7 6836.5 6846.3 6928.0 6951.8 6972.8 6984.2 6990.4 5453.5 6370.8 6465.6 6577.9 6659.6 6606.3 6693.5 6743.5 6760.1 6764.7 6544.2 6562.2 6569.5 6578.9 6601.4 6283.4 6315.5 6319.5 6411.9 6569.1 5422.3 6344.8 6380.0 6416.2 6417.3 6329.3 6450.0 6478.8 6559.9 6567.7 4997.3 6312.7 6459.5 6473.4 6486.4 11:28:03 INFO - PID 1650 | 11:28:03 INFO - PID 1650 | [#1] ext.html Cycles:180 Average:43673.63 Median:178671.38 stddev:75349.56 (42.2%) stddev-sans-first:75492.14 11:28:03 INFO - PID 1650 | Values: 790.8 803.4 831.3 1195.3 1269.4 37820.9 38741.4 38757.8 38826.4 38881.4 39048.4 39271.8 39338.8 39442.4 39528.2 7775.4 7928.0 8250.5 8470.0 8503.4 8452.7 8536.4 8600.8 8603.7 8609.3 8495.2 8502.1 8598.9 8600.9 8606.9 8488.0 8520.1 8573.7 8596.0 8600.5 7069.4 8277.8 8278.7 8280.1 8312.8 36818.8 37001.0 37031.1 37068.3 37149.4 3554.7 3600.4 3605.5 3605.9 3608.8 30458.5 30508.0 30553.7 30634.7 30700.8 3086.6 3086.8 3109.4 3111.2 3127.3 186419.1 252646.0 284733.0 295007.0 298006.5 47212.3 47551.8 47582.0 47660.5 47722.0 49280.8 49563.0 49613.3 49663.0 49868.0 11802.8 14111.4 14132.7 14135.6 14136.6 11405.5 11485.4 11512.5 11560.4 11566.1 11456.8 12980.2 13231.5 13311.6 14759.5 6039.3 6084.4 6121.8 6124.9 6135.5 996.8 1021.9 1022.2 1040.7 1062.9 17019.7 20483.8 22513.1 24390.5 24457.9 1109.3 1115.8 1141.9 1147.9 1158.7 12322.2 12464.7 12521.5 12536.7 12633.9 295895.5 323659.0 324027.0 324845.0 325458.4 52284.5 52296.7 52323.7 52339.0 52509.5 1497.3 1497.5 1535.2 1542.6 1566.9 22740.8 23898.0 24043.6 24087.5 24156.4 30732.0 37390.8 37472.8 37477.6 37525.8 273347.0 273722.0 273798.0 273882.0 275485.2 32497.8 33343.8 33474.2 33963.5 34155.3 42248.4 44435.3 44590.6 44662.9 44676.6 28992.5 29895.1 30212.7 31155.7 31281.5 30035.0 30697.0 30769.5 30931.7 30979.4 35433.8 36129.1 36816.1 36981.6 37061.3 29150.4 29559.7 29978.4 30028.0 30630.8 23139.7 24241.4 24307.8 24718.5 24756.1 11:28:03 INFO - PID 1650 | 11:28:03 INFO - PID 1650 | [#2] jquery.html Cycles:180 Average:35915.82 Median:12433.28 stddev:93891.77 (755.2%) stddev-sans-first:94117.36 11:28:03 INFO - PID 1650 | Values: 429.2 432.2 434.6 448.5 451.0 19375.6 19426.8 20069.3 20939.8 21787.7 21434.9 22622.9 22681.0 23031.8 23258.0 6067.8 6181.0 6217.8 6240.8 6255.8 6121.5 6165.6 6180.5 6252.3 6258.3 6001.8 6172.7 6204.2 6205.7 6232.2 5264.4 6141.7 6218.1 6241.4 6242.7 4462.7 5416.2 5519.9 5548.2 5556.1 28220.6 28385.1 28704.0 29152.7 29230.9 1875.7 2162.7 2240.2 2241.4 2243.7 17112.4 17112.6 17277.6 17441.1 17715.3 1870.9 2131.9 2140.2 2175.4 2175.5 164635.0 169426.2 171235.1 171572.1 171857.0 12622.9 12627.5 12823.1 12871.0 13113.9 13974.4 16361.7 16647.3 16647.5 16704.8 8684.8 8980.3 9063.0 9166.4 9170.0 8474.0 8499.0 8528.3 8654.1 8660.3 6500.5 8091.8 8101.4 8107.0 8113.1 2138.5 2139.6 2141.6 2150.9 2308.9 911.9 946.7 951.6 977.9 1008.3 20181.3 20772.8 20778.4 20828.2 21038.4 879.7 970.1 972.9 1004.5 1005.7 8880.9 10109.7 10179.3 10672.6 10805.7 545361.0 545767.0 550385.0 552637.0 559062.0 119034.0 127331.7 128526.0 130971.0 132132.0 1181.7 1225.8 1238.3 1245.5 1246.1 18719.2 18929.7 18996.0 21878.7 22269.8 54327.0 55645.2 56839.0 56995.4 57136.7 75789.0 76036.0 76102.0 76345.2 76388.2 9035.3 9061.7 9185.5 9208.4 9210.4 12137.3 12899.6 14268.5 14464.9 14538.1 11749.6 11822.7 12160.9 12173.8 12195.3 11304.9 11336.8 11373.2 11521.8 11562.8 11835.5 11899.9 12079.1 12167.7 12321.3 8832.3 8924.0 9037.2 9180.0 9326.1 9098.5 10326.9 12061.2 12086.1 12247.5 11:28:03 INFO - PID 1650 | 11:28:03 INFO - PID 1650 | [#3] mootools.html Cycles:180 Average:7178.87 Median:3601.66 stddev:4536.07 (125.9%) stddev-sans-first:4527.79 11:28:03 INFO - PID 1650 | Values: 1370.4 1441.7 1445.2 1454.7 1455.3 9700.7 11694.6 11918.0 12249.8 12279.9 11881.5 11940.3 12108.8 12121.6 12121.7 2574.3 2608.3 2622.8 2625.4 2625.8 2526.5 2578.5 2590.5 2595.7 2603.3 2518.3 2519.6 2562.8 2577.1 2578.6 2510.7 2526.8 2565.1 2565.5 2573.0 2140.6 2203.2 2478.2 2534.5 2548.4 11664.4 11891.4 11901.0 11906.6 11911.5 6553.3 6772.5 6809.1 6813.3 6816.4 4013.9 4333.3 4384.5 4423.8 4424.6 1225.2 1244.5 1264.8 1287.6 1294.7 16179.9 16415.5 16501.5 16569.9 16595.5 5495.6 5606.2 5640.9 5803.6 5864.8 11763.4 11879.3 11884.8 11888.2 11900.0 6603.1 6610.8 6612.2 6615.2 6616.2 5064.4 6112.7 6184.5 6239.2 6257.6 4294.5 4393.6 4428.2 4453.2 4456.0 1278.7 1292.7 1296.8 1304.3 1317.8 775.9 790.2 795.2 796.0 801.4 6018.2 6518.2 6682.7 7027.3 7108.2 887.9 917.0 925.8 928.9 933.9 3371.8 3458.7 3744.6 3866.1 3928.5 14605.9 14637.9 15239.4 15245.9 15280.5 14911.7 14991.9 15274.5 15302.2 15309.7 8490.1 8697.6 8727.4 8809.3 8811.3 7116.4 7221.4 7255.2 7281.0 7306.5 10511.0 13163.0 13697.1 13737.9 13741.5 13678.9 14024.5 14077.6 14132.9 14260.0 9243.0 9487.4 9524.2 9553.7 9571.8 10727.1 10792.4 10829.2 10862.1 10884.1 9205.9 9223.1 9223.9 9242.5 9442.2 8968.4 8994.1 9024.6 9025.4 9041.3 8159.8 8262.1 8327.7 8347.3 8349.7 5417.6 5998.9 6146.5 6154.2 6295.4 5994.1 6234.8 6260.9 6326.7 6343.7 11:28:03 INFO - PID 1650 | 11:28:03 INFO - PID 1650 | [#4] prototype.html Cycles:180 Average:5378.48 Median:6094.03 stddev:1717.04 (28.2%) stddev-sans-first:1679.52 11:28:03 INFO - PID 1650 | Values: 329.8 399.1 408.0 409.1 410.8 5521.0 6126.2 6230.7 6389.3 6459.9 6136.6 6234.9 6252.0 6268.0 6337.9 4423.8 4568.6 4571.8 4574.4 4575.2 4381.9 4496.3 4531.8 4544.4 4562.5 3891.8 4467.8 4484.8 4486.6 4489.3 4267.7 4475.6 4516.8 4518.4 4530.6 3809.9 3818.5 3830.6 3874.1 3876.0 4994.0 5997.3 6072.5 6074.7 6079.4 4896.3 4949.3 5386.3 5413.9 5421.0 5136.0 6412.9 6601.7 6651.7 6682.6 4326.3 4348.7 4360.9 4371.5 4372.4 5450.7 6347.9 6801.6 6956.8 7096.9 4465.9 4468.7 4474.4 4478.6 4493.3 3602.3 4297.0 4559.6 4567.7 4579.8 5340.4 5359.6 5436.5 5441.8 5449.6 5487.4 6666.7 6671.0 6711.9 6722.7 1521.2 1532.7 1535.3 1540.8 1552.9 991.5 1001.8 1007.0 1008.8 1021.3 5321.9 6108.7 6118.4 6367.9 6389.2 6732.6 6824.3 6911.1 6912.6 6953.4 6282.8 6315.6 6340.9 6365.7 6383.6 3284.9 3589.0 3604.0 3611.0 3612.0 6812.6 6960.2 7064.4 7073.7 7111.5 6922.9 7079.4 7083.6 7163.1 7171.4 5841.0 7074.6 7135.1 7152.6 7209.5 5745.8 6137.1 6410.9 6974.9 7014.1 5947.8 6250.7 6792.8 6870.3 6879.0 6475.0 7075.9 7161.8 7176.2 7182.7 4147.6 4152.1 4420.0 4429.9 4491.3 7027.3 7035.5 7046.6 7051.7 7062.3 6483.8 6693.0 6766.0 6789.5 6810.5 5535.6 6710.4 6728.6 6731.9 6733.2 5913.8 6321.3 6413.6 6472.9 6588.2 5831.3 6700.3 6726.7 6733.8 6749.9 6006.8 6478.3 6537.3 6560.9 6591.2 11:28:03 INFO - PID 1650 | 11:28:03 INFO - PID 1650 | [#5] yui.html Cycles:180 Average:21793.41 Median:25749.34 stddev:31817.85 (123.6%) stddev-sans-first:31867.80 11:28:03 INFO - PID 1650 | Values: 728.7 811.9 843.7 847.1 847.6 16526.8 16996.3 17024.4 18606.3 19264.3 17814.5 18263.0 18354.0 18608.7 18799.6 957.1 969.4 978.7 979.1 983.2 968.6 982.3 984.0 985.1 986.6 1010.7 1023.8 1024.4 1026.4 1027.0 1001.4 1007.3 1021.5 1021.9 1024.3 734.0 742.6 756.0 756.3 757.1 16875.3 17246.4 17356.1 17429.7 17440.9 16353.3 17308.9 17633.0 17672.6 17818.6 14101.7 14199.5 14350.3 14429.1 14462.5 3535.5 3558.0 3582.1 3586.0 3587.1 85305.0 135828.0 137076.6 137590.3 140429.0 13107.1 13235.7 13367.9 13471.3 13493.1 25649.4 25657.8 25840.8 25882.7 25899.5 7639.0 7663.5 7675.7 7717.0 7734.0 3797.2 3942.1 3944.2 3947.1 3952.0 4362.7 4455.2 4456.9 4457.3 4465.7 791.0 808.1 810.9 813.8 813.8 594.7 636.3 652.1 665.1 680.1 9842.6 10160.6 10410.1 11991.7 12046.2 532.7 630.6 640.9 644.0 661.7 4184.9 5423.8 5434.4 5443.1 5488.9 90082.5 100952.0 110787.0 111452.0 111526.0 77706.0 98074.0 98499.0 98702.0 99257.2 88198.0 88465.0 88624.6 88660.6 89839.0 8989.7 11030.8 11054.4 11244.3 11356.7 39253.0 42756.8 43490.8 43497.6 43957.0 48943.5 54463.5 55801.7 56316.0 56798.0 14078.9 14085.0 14355.4 14412.1 14480.6 15286.2 15584.8 15754.4 15840.6 15854.8 13988.0 14418.8 14567.6 14585.9 14645.6 14011.9 14170.0 14486.3 14510.9 14611.3 11427.5 14039.9 14191.9 14340.1 14363.6 9939.4 10187.5 10338.5 10612.9 10638.1 9999.5 10604.8 11601.5 11706.1 11896.5 11:28:03 INFO - PID 1650 | -------- Summary: end -------- 11:28:03 INFO - PID 1650 | 11:28:04 INFO - TEST-INFO | 1650: exit 0 11:28:04 INFO - TEST-OK | dromaeo_css | took 1114108ms 11:28:04 INFO - TEST-START | kraken 11:28:04 INFO - operating with platform_type : mac_ 11:28:04 INFO - Initialising browser for kraken test... 11:28:04 INFO - TEST-INFO | started process 1659 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/T/tmpKkvUBG/profile http://localhost:49228/getInfo.html) 11:28:05 INFO - PID 1659 | JavaScript warning: resource://gre/modules/addons/XPIProvider.jsm, line 2930: String.localeCompare is deprecated; use String.prototype.localeCompare instead 11:28:06 INFO - PID 1659 | 2017-05-16 11:28:06.675 plugin-container[1660:10622] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9337, name = 'com.apple.tsm.portname' 11:28:06 INFO - PID 1659 | See /usr/include/servers/bootstrap_defs.h for the error codes. 11:28:06 INFO - PID 1659 | 2017-05-16 11:28:06.678 plugin-container[1660:10622] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x950b, name = 'com.apple.CFPasteboardClient' 11:28:06 INFO - PID 1659 | See /usr/include/servers/bootstrap_defs.h for the error codes. 11:28:06 INFO - PID 1659 | 2017-05-16 11:28:06.678 plugin-container[1660:10622] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 11:28:06 INFO - PID 1659 | __metrics Screen width/height:1600/1200 11:28:06 INFO - PID 1659 | colorDepth:24 11:28:06 INFO - PID 1659 | Browser inner width/height: 1024/691 11:28:06 INFO - PID 1659 | __metrics 11:28:07 INFO - TEST-INFO | 1659: exit 0 11:28:07 INFO - Browser initialized. 11:28:07 INFO - Running cycle 1/1 for kraken test... 11:28:07 INFO - TEST-INFO | started process 1661 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/T/tmpKkvUBG/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 11:28:09 INFO - PID 1661 | 2017-05-16 11:28:09.553 plugin-container[1662:10734] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x923f, name = 'com.apple.tsm.portname' 11:28:09 INFO - PID 1661 | See /usr/include/servers/bootstrap_defs.h for the error codes. 11:28:09 INFO - PID 1661 | 2017-05-16 11:28:09.554 plugin-container[1662:10734] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9913, name = 'com.apple.CFPasteboardClient' 11:28:09 INFO - PID 1661 | See /usr/include/servers/bootstrap_defs.h for the error codes. 11:28:09 INFO - PID 1661 | 2017-05-16 11:28:09.555 plugin-container[1662:10734] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 11:28:10 INFO - PID 1661 | 2017-05-16 11:28:10.336 plugin-container[1663:10780] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4747, name = 'com.apple.tsm.portname' 11:28:10 INFO - PID 1661 | See /usr/include/servers/bootstrap_defs.h for the error codes. 11:28:10 INFO - PID 1661 | 2017-05-16 11:28:10.336 plugin-container[1663:10780] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x980b, name = 'com.apple.CFPasteboardClient' 11:28:10 INFO - PID 1661 | See /usr/include/servers/bootstrap_defs.h for the error codes. 11:28:10 INFO - PID 1661 | 2017-05-16 11:28:10.337 plugin-container[1663:10780] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 11:28:19 INFO - PID 1661 | RSS: Main: 252985344 11:28:19 INFO - PID 1661 | 11:28:22 INFO - PID 1661 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49228/tests/kraken/driver.html?testName=audio-beat-detection) 11:28:23 INFO - PID 1661 | RSS: Main: 260841472 11:28:23 INFO - PID 1661 | 11:28:25 INFO - PID 1661 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49228/tests/kraken/driver.html?testName=audio-dft) 11:28:26 INFO - PID 1661 | RSS: Main: 268435456 11:28:26 INFO - PID 1661 | 11:28:29 INFO - PID 1661 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49228/tests/kraken/driver.html?testName=audio-fft) 11:28:30 INFO - PID 1661 | RSS: Main: 263602176 11:28:30 INFO - PID 1661 | 11:28:32 INFO - PID 1661 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49228/tests/kraken/driver.html?testName=audio-oscillator) 11:28:33 INFO - PID 1661 | RSS: Main: 265986048 11:28:33 INFO - PID 1661 | 11:28:35 INFO - PID 1661 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49228/tests/kraken/driver.html?testName=imaging-gaussian-blur) 11:28:35 INFO - PID 1661 | RSS: Main: 266416128 11:28:35 INFO - PID 1661 | 11:28:38 INFO - PID 1661 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49228/tests/kraken/driver.html?testName=imaging-darkroom) 11:28:39 INFO - PID 1661 | RSS: Main: 271998976 11:28:39 INFO - PID 1661 | 11:28:42 INFO - PID 1661 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49228/tests/kraken/driver.html?testName=imaging-desaturate) 11:28:42 INFO - PID 1661 | RSS: Main: 271962112 11:28:42 INFO - PID 1661 | 11:28:45 INFO - PID 1661 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49228/tests/kraken/driver.html?testName=json-parse-financial) 11:28:46 INFO - PID 1661 | RSS: Main: 271110144 11:28:46 INFO - PID 1661 | 11:28:47 INFO - PID 1661 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49228/tests/kraken/driver.html?testName=json-stringify-tinderbox) 11:28:48 INFO - PID 1661 | RSS: Main: 270831616 11:28:48 INFO - PID 1661 | 11:28:50 INFO - PID 1661 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49228/tests/kraken/driver.html?testName=stanford-crypto-aes) 11:28:50 INFO - PID 1661 | RSS: Main: 270495744 11:28:50 INFO - PID 1661 | 11:28:53 INFO - PID 1661 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49228/tests/kraken/driver.html?testName=stanford-crypto-ccm) 11:28:53 INFO - PID 1661 | RSS: Main: 270159872 11:28:53 INFO - PID 1661 | 11:28:56 INFO - PID 1661 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49228/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 11:28:57 INFO - PID 1661 | RSS: Main: 270446592 11:28:57 INFO - PID 1661 | 11:29:00 INFO - PID 1661 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49228/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 11:29:01 INFO - PID 1661 | RSS: Main: 270614528 11:29:01 INFO - PID 1661 | 11:29:02 INFO - PID 1661 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49228/tests/kraken/driver.html?testName=ai-astar) 11:29:03 INFO - PID 1661 | RSS: Main: 272916480 11:29:03 INFO - PID 1661 | 11:29:03 INFO - PID 1661 | __start_tp_report 11:29:03 INFO - PID 1661 | _x_x_mozilla_page_load 11:29:03 INFO - PID 1661 | _x_x_mozilla_page_load_details 11:29:03 INFO - PID 1661 | |i|pagename|runs| 11:29:03 INFO - PID 1661 | |0;ai-astar;102;115;116;115;114;116;115;114;116;115 11:29:03 INFO - PID 1661 | |1;audio-beat-detection;138;113;148;173;113;139;146;113;143;119 11:29:03 INFO - PID 1661 | |2;audio-dft;205;199;205;198;202;223;207;205;228;198 11:29:03 INFO - PID 1661 | |3;audio-fft;86;67;89;85;60;60;63;60;78;60 11:29:03 INFO - PID 1661 | |4;audio-oscillator;67;56;76;104;104;107;87;74;70;90 11:29:03 INFO - PID 1661 | |5;imaging-gaussian-blur;92;91;91;91;92;90;92;91;92;104 11:29:03 INFO - PID 1661 | |6;imaging-darkroom;97;99;98;106;98;99;98;98;98;103 11:29:03 INFO - PID 1661 | |7;imaging-desaturate;123;91;93;87;89;95;116;94;94;95 11:29:03 INFO - PID 1661 | |8;json-parse-financial;51;48;50;48;51;50;50;49;48;51 11:29:03 INFO - PID 1661 | |9;json-stringify-tinderbox;56;53;79;88;87;88;55;57;58;59 11:29:03 INFO - PID 1661 | |10;stanford-crypto-aes;93;109;85;86;81;94;105;84;86;93 11:29:03 INFO - PID 1661 | |11;stanford-crypto-ccm;140;142;139;140;150;140;141;141;188;139 11:29:03 INFO - PID 1661 | |12;stanford-crypto-pbkdf2;241;191;187;194;194;219;194;195;217;191 11:29:03 INFO - PID 1661 | |13;stanford-crypto-sha256-iterative;68;71;73;67;71;69;71;71;71;71 11:29:03 INFO - PID 1661 | __end_tp_report 11:29:03 INFO - PID 1661 | __start_cc_report 11:29:03 INFO - PID 1661 | _x_x_mozilla_cycle_collect,937 11:29:03 INFO - PID 1661 | __end_cc_report 11:29:03 INFO - PID 1661 | __startTimestamp1494959343199__endTimestamp 11:29:03 INFO - PID 1661 | 11:29:03 INFO - PID 1661 | ------- Summary: start ------- 11:29:03 INFO - PID 1661 | Number of tests: 14 11:29:03 INFO - PID 1661 | 11:29:03 INFO - PID 1661 | [#0] ai-astar Cycles:10 Average:113.80 Median:115.00 stddev:4.21 (3.7%) stddev-sans-first:0.78 11:29:03 INFO - PID 1661 | Values: 102.0 115.0 116.0 115.0 114.0 116.0 115.0 114.0 116.0 115.0 11:29:03 INFO - PID 1661 | 11:29:03 INFO - PID 1661 | [#1] audio-beat-detection Cycles:10 Average:134.50 Median:141.00 stddev:19.80 (14.0%) stddev-sans-first:20.96 11:29:03 INFO - PID 1661 | Values: 138.0 113.0 148.0 173.0 113.0 139.0 146.0 113.0 143.0 119.0 11:29:03 INFO - PID 1661 | 11:29:03 INFO - PID 1661 | [#2] audio-dft Cycles:10 Average:207.00 Median:205.00 stddev:10.33 (5.0%) stddev-sans-first:10.93 11:29:03 INFO - PID 1661 | Values: 205.0 199.0 205.0 198.0 202.0 223.0 207.0 205.0 228.0 198.0 11:29:03 INFO - PID 1661 | 11:29:03 INFO - PID 1661 | [#3] audio-fft Cycles:10 Average:70.80 Median:72.50 stddev:12.28 (16.9%) stddev-sans-first:11.73 11:29:03 INFO - PID 1661 | Values: 86.0 67.0 89.0 85.0 60.0 60.0 63.0 60.0 78.0 60.0 11:29:03 INFO - PID 1661 | 11:29:03 INFO - PID 1661 | [#4] audio-oscillator Cycles:10 Average:83.50 Median:72.00 stddev:17.65 (24.5%) stddev-sans-first:17.68 11:29:03 INFO - PID 1661 | Values: 67.0 56.0 76.0 104.0 104.0 107.0 87.0 74.0 70.0 90.0 11:29:03 INFO - PID 1661 | 11:29:03 INFO - PID 1661 | [#5] imaging-gaussian-blur Cycles:10 Average:92.60 Median:91.50 stddev:4.06 (4.4%) stddev-sans-first:4.30 11:29:03 INFO - PID 1661 | Values: 92.0 91.0 91.0 91.0 92.0 90.0 92.0 91.0 92.0 104.0 11:29:03 INFO - PID 1661 | 11:29:03 INFO - PID 1661 | [#6] imaging-darkroom Cycles:10 Average:99.40 Median:98.00 stddev:2.84 (2.9%) stddev-sans-first:2.87 11:29:03 INFO - PID 1661 | Values: 97.0 99.0 98.0 106.0 98.0 99.0 98.0 98.0 98.0 103.0 11:29:03 INFO - PID 1661 | 11:29:03 INFO - PID 1661 | [#7] imaging-desaturate Cycles:10 Average:97.70 Median:93.50 stddev:11.90 (12.7%) stddev-sans-first:8.39 11:29:03 INFO - PID 1661 | Values: 123.0 91.0 93.0 87.0 89.0 95.0 116.0 94.0 94.0 95.0 11:29:03 INFO - PID 1661 | 11:29:03 INFO - PID 1661 | [#8] json-parse-financial Cycles:10 Average:49.60 Median:50.00 stddev:1.26 (2.5%) stddev-sans-first:1.24 11:29:03 INFO - PID 1661 | Values: 51.0 48.0 50.0 48.0 51.0 50.0 50.0 49.0 48.0 51.0 11:29:03 INFO - PID 1661 | 11:29:03 INFO - PID 1661 | [#9] json-stringify-tinderbox Cycles:10 Average:68.00 Median:69.00 stddev:15.36 (22.3%) stddev-sans-first:15.66 11:29:03 INFO - PID 1661 | Values: 56.0 53.0 79.0 88.0 87.0 88.0 55.0 57.0 58.0 59.0 11:29:03 INFO - PID 1661 | 11:29:03 INFO - PID 1661 | [#10] stanford-crypto-aes Cycles:10 Average:91.60 Median:86.00 stddev:9.24 (10.7%) stddev-sans-first:9.79 11:29:03 INFO - PID 1661 | Values: 93.0 109.0 85.0 86.0 81.0 94.0 105.0 84.0 86.0 93.0 11:29:03 INFO - PID 1661 | 11:29:03 INFO - PID 1661 | [#11] stanford-crypto-ccm Cycles:10 Average:146.00 Median:141.00 stddev:15.10 (10.7%) stddev-sans-first:15.86 11:29:03 INFO - PID 1661 | Values: 140.0 142.0 139.0 140.0 150.0 140.0 141.0 141.0 188.0 139.0 11:29:03 INFO - PID 1661 | 11:29:03 INFO - PID 1661 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:202.30 Median:194.50 stddev:17.46 (9.0%) stddev-sans-first:11.61 11:29:03 INFO - PID 1661 | Values: 241.0 191.0 187.0 194.0 194.0 219.0 194.0 195.0 217.0 191.0 11:29:03 INFO - PID 1661 | 11:29:03 INFO - PID 1661 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:70.30 Median:71.00 stddev:1.77 (2.5%) stddev-sans-first:1.67 11:29:03 INFO - PID 1661 | Values: 68.0 71.0 73.0 67.0 71.0 69.0 71.0 71.0 71.0 71.0 11:29:03 INFO - PID 1661 | -------- Summary: end -------- 11:29:03 INFO - PID 1661 | 11:29:03 INFO - TEST-INFO | 1661: exit 0 11:29:04 INFO - TEST-OK | kraken | took 59404ms 11:29:04 INFO - SUITE-END | took 1173s 11:29:04 INFO - Completed test suite (00:19:33) 11:29:04 INFO - javascript benchmark 11:29:04 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7846.93595572365, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3559.4126969050108, 3565.714742820573, 3570.9464358034625, 3603.513525674033, 3628.129248980244, 6134.8652196263865, 6162.506999440046, 6205.255369355678, 6208.1308616793685, 6230.376962303767, 5095.847124586259, 6132.355508992489, 6212.565120441571, 6251.187345645071, 6255.593386429879, 5405.351357837046, 5449.209864569635, 5484.61607687462, 5506.03644362237, 5602.691851948141, 5396.622236443451, 5492.972535137319, 5539.695316757577, 5567.638103523258, 5614.073677843171, 4253.936190957138, 4911.828086017008, 5467.753951072212, 5471.23402723619, 5545.584081193906, 5400.216968539579, 5434.619576629638, 5451.018816613008, 5454.10007348787, 5458.5087342139395, 5378.381486129067, 5387.703676297802, 5391.24522566837, 5407.648502847342, 5421.268128802601, 4678.953210467886, 5876.6180198286975, 6133.916185115316, 6179.783707570258, 6185.597936134137, 5562.360328562227, 6648.135742353512, 6679.699413526354, 6701.195856497252, 6715.6977935993, 5762.74067666956, 6644.833879153011, 6814.0, 6817.352351526647, 6833.043373927654, 5636.830895073154, 6167.074938759222, 6271.247450305992, 6296.464800492009, 6307.022411526266, 6382.521310901701, 6847.144106986627, 6929.480288978347, 6934.16789985205, 6935.167779866449, 5538.0, 6538.117354157223, 6567.376099270562, 6623.933760662428, 6659.300773418819, 6477.870442591122, 6769.526133170631, 6802.93197068023, 6812.01225822253, 6813.4208592270215, 6524.739010439529, 6573.934260657332, 6650.334966503311, 6652.501062420241, 6672.766453174116, 6667.833304167435, 6694.598324100472, 6699.933000669931, 6701.832454188587, 6711.261761206263, 4685.601723853442, 5121.07820592297, 5317.308749862493, 5317.813876514381, 5322.600804939645, 4229.830806767756, 4732.858014259577, 4748.026654535881, 4769.14155452015, 4786.617070634341, 5003.649744517848, 6007.969960150172, 6262.3111457739615, 6271.717772700239, 6336.52476064297, 6793.150856142982, 6820.147481564804, 6826.692798823966, 6839.1451068616425, 6846.349596788297, 6239.78160764371, 6357.459615932605, 6368.64972426521, 6371.649559274284, 6417.03744438338, 5749.68376739275, 5848.122781582797, 5965.164876917235, 5983.1623572699855, 6011.939880601226, 6740.157480314961, 6809.910414333683, 6891.448684105159, 6901.344372284625, 6942.201646810681, 6799.456043516408, 6806.387425131762, 6822.692978815866, 6836.487263455161, 6846.281140480178, 6927.965360173167, 6951.7914462566205, 6972.79081627552, 6984.161900571964, 6990.405815505739, 5453.536449401687, 6370.8088757337355, 6465.579737317059, 6577.868442631217, 6659.567128136656, 6606.306337834651, 6693.497987650849, 6743.527953043436, 6760.08738820253, 6764.661766911537, 6544.214694236722, 6562.212534495891, 6569.507286953402, 6578.901316480161, 6601.438877695257, 6283.434490895658, 6315.5263355247625, 6319.46284565817, 6411.935880641134, 6569.080328754074, 5422.295101636762, 6344.809655710336, 6380.042993550819, 6416.197975253093, 6417.2620148681635, 6329.335419780857, 6450.0, 6478.838029049311, 6559.868802624016, 6567.671616419256, 4997.275395067622, 6312.747490100344, 6459.483241340776, 6473.385028422293, 6486.351364863476], "value": 6048.837267710335, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [790.8453657659817, 803.3653413803095, 831.310012689468, 1195.3425615911246, 1269.435101379885, 37820.86537403875, 38741.41887871681, 38757.80621096898, 38826.417603735965, 38881.41677874831, 39048.414273785915, 39271.80364098182, 39338.8033059835, 39442.40836387449, 39528.209435811266, 7775.416843736714, 7928.0, 8250.46371985822, 8470.0, 8503.404761666687, 8452.661893524251, 8536.359773017017, 8600.784980375509, 8603.655853765842, 8609.26821220197, 8495.150484951486, 8502.14978502148, 8598.914010859906, 8600.913990860074, 8606.87089693655, 8487.957560212159, 8520.105388934166, 8573.657053717843, 8596.0, 8600.526971016592, 7069.434445244368, 8277.83444331116, 8278.668853245863, 8280.130586288473, 8312.750617481484, 36818.815905920295, 37001.0, 37031.074223144635, 37068.25863482742, 37149.442758358644, 3554.7156227501996, 3600.4059330210484, 3605.5132557104716, 3605.9098522536883, 3608.783472991629, 30458.54312185319, 30508.0, 30553.694463055308, 30634.69365306341, 30700.771969121208, 3086.6296044474807, 3086.8302243376606, 3109.4403007458873, 3111.175538482304, 3127.3276245607303, 186419.1358086429, 252646.0, 284733.0, 295007.0, 298006.5099674488, 47212.347567834964, 47551.811204719466, 47582.0, 47660.52339476561, 47722.0, 49280.753596232506, 49563.0, 49613.25580116301, 49663.0, 49868.0, 11802.822957655642, 14111.435542578387, 14132.717345653029, 14135.646608834655, 14136.646583835487, 11405.543778248777, 11485.425728713531, 11512.481938312796, 11560.421978901022, 11566.132540059361, 11456.797036311234, 12980.221186728826, 13231.470741170438, 13311.600651980456, 14759.483418080315, 6039.305479869783, 6084.391560843968, 6121.785737499166, 6124.908126378108, 6135.5398345124295, 996.840505519128, 1021.8722659667542, 1022.1924679503257, 1040.7450174707244, 1062.9362238265574, 17019.659606808043, 20483.795162048486, 22513.099476021103, 24390.51218975646, 24457.899394526932, 1109.328856042099, 1115.7656892052598, 1141.9086473082134, 1147.9196456248148, 1158.6987383280407, 12322.199057061262, 12464.688382790322, 12521.499140034297, 12536.749265014649, 12633.93683031579, 295895.52052240033, 323659.0, 324027.0, 324845.0, 325458.37270813494, 52284.47715522872, 52296.738516307174, 52323.73838130861, 52338.95322093613, 52509.47490525122, 1497.2513743128436, 1497.5133081748259, 1535.2400561721795, 1542.5603702944607, 1566.8746500279951, 22740.772592274527, 23898.04407823737, 24043.639345409483, 24087.51824963526, 24156.396090097183, 30732.0, 37390.8130459346, 37472.81263593664, 37477.62522374741, 37525.81237093797, 273347.0, 273722.0, 273798.0, 273882.0, 275485.24514754594, 32497.750224977313, 33343.83328083344, 33474.16314592155, 33963.49054764131, 34155.316893662486, 42248.36627450529, 44435.33346999733, 44590.554094459934, 44662.88342791456, 44676.55323446854, 28992.535634471307, 29895.10314690563, 30212.69787302099, 31155.68844311528, 31281.530777037908, 30035.0, 30697.0, 30769.538456922717, 30931.69068309378, 30979.380412391176, 35433.82283088568, 36129.096772580895, 36816.07959801026, 36981.63018369889, 37061.25877482489, 29150.416991660473, 29559.704402955696, 29978.400431991675, 30028.0, 30630.846845765627, 23139.65290520677, 24241.39396515101, 24307.756922430548, 24718.505629887663, 24756.13353532618], "value": 17360.605488662597, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [429.1716986216601, 432.17023315234746, 434.5589226934662, 448.4730441730966, 450.96053596460166, 19375.612487750255, 19426.83438993658, 20069.297574584893, 20939.790602093974, 21787.673184902218, 21434.892825535888, 22622.88688556555, 22681.0, 23031.848407579637, 23258.0, 6067.848303792407, 6180.980138277179, 6217.813465596039, 6240.812775616716, 6255.812325630239, 6121.479674227674, 6165.599236049665, 6180.505559555225, 6252.34350393209, 6258.311585725566, 6001.819945401623, 6172.69136543171, 6204.162438070848, 6205.720742566596, 6232.158658581102, 5264.447233040552, 6141.692915354214, 6218.098375735515, 6241.407066328691, 6242.656653884034, 4462.709923854972, 5416.214648875891, 5519.9448005520235, 5548.195511650788, 5556.111022236423, 28220.576691349645, 28385.148445546667, 28703.995360162295, 29152.708472915212, 29230.85384573085, 1875.7186422036805, 2162.6755986602134, 2240.171136679423, 2241.4172315198, 2243.674667173251, 17112.401065962757, 17112.57218569533, 17277.56806079858, 17441.127943602896, 17715.291388344453, 1870.9251629934922, 2131.936041918745, 2140.165335519149, 2175.434387059376, 2175.4670105824175, 164635.0, 169426.1528692361, 171235.14382428132, 171572.1421392885, 171857.0, 12622.873771262353, 12627.494900204072, 12823.102382833113, 12871.03467239942, 13113.934430327978, 13974.441022359195, 16361.672766544603, 16647.250873710713, 16647.50057498277, 16704.832951670327, 8684.826303473896, 8980.281577473786, 9063.0, 9166.358354915092, 9169.954150229207, 8473.95763021181, 8499.0, 8528.275096616733, 8654.091320411266, 8660.30717542595, 6500.512461565307, 8091.838163236703, 8101.432899697083, 8107.0, 8113.107558168596, 2138.5295235048575, 2139.5720855828895, 2141.5716856628737, 2150.860194087379, 2308.907643694267, 911.9224865886474, 946.7254496196026, 951.6431338248157, 977.8630991661173, 1008.2841182760322, 20181.293654722307, 20772.79227207739, 20778.376648700563, 20828.1668733252, 21038.368848934555, 879.7052987249343, 970.1365784451843, 972.8929817720186, 1004.5379125602159, 1005.6781829814389, 8880.911190888137, 10109.696709098738, 10179.338343007681, 10672.573097076185, 10805.729856753644, 545361.0, 545767.0, 550385.0, 552637.0, 559062.0, 119034.0, 127331.72668273198, 128526.0, 130971.0, 132132.0180197298, 1181.710480932177, 1225.7548490301974, 1238.3313010973973, 1245.4893493667553, 1246.1277106025818, 18719.157637906053, 18929.716054258923, 18996.0, 21878.67181992303, 22269.777302226772, 54327.0, 55645.165322521, 56839.0, 56995.43004569901, 57136.71431642815, 75789.0, 76036.0, 76102.0, 76345.23654763382, 76388.23611763811, 9035.277177825626, 9061.68284110053, 9185.494797786187, 9208.401453905475, 9210.447373157363, 12137.332446715516, 12899.613011609667, 14268.500602479278, 14464.9151313654, 14538.127712337293, 11749.647510574696, 11822.704432389259, 12160.878391216329, 12173.817392738938, 12195.329256890956, 11304.943475282571, 11336.829947550958, 11373.20387572862, 11521.837300819276, 11562.826557601811, 11835.46740396667, 11899.881001189877, 12079.094067945114, 12167.695807604881, 12321.260724356567, 8832.29341652679, 8923.955380223057, 9037.231835294073, 9180.0, 9326.114019168168, 9098.499582523025, 10326.896731032894, 12061.216020958609, 12086.093542984136, 12247.510099595916], "value": 10443.002181511309, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1370.403874314673, 1441.66120961574, 1445.2051371745538, 1454.7236025155216, 1455.294182321573, 9700.708978730641, 11694.649160525187, 11918.0, 12249.816252756216, 12279.938600306987, 11881.465334059967, 11940.283582985026, 12108.81836772449, 12121.636350909464, 12121.696957576065, 2574.343542396686, 2608.3479130217447, 2622.8032897532667, 2625.448655782288, 2625.842449453039, 2526.4820711754137, 2578.4585237100137, 2590.4689538644534, 2595.7404259573987, 2603.323135984648, 2518.26970178648, 2519.5842685956886, 2562.7949764018836, 2577.059373328731, 2578.574535201699, 2510.7112682041525, 2526.759957804006, 2565.1022142250063, 2565.512552615016, 2572.9742702573017, 2140.6146893559153, 2203.22886989554, 2478.1821998740375, 2534.4804315115357, 2548.4011257354505, 11664.416779161009, 11891.405429728478, 11901.047916166772, 11906.642800715992, 11911.523539058428, 6553.279139294674, 6772.45820334377, 6809.148856392951, 6813.250542440328, 6816.352446517573, 4013.9397909031386, 4333.328334108187, 4384.4519435070615, 4423.823047078113, 4424.557544245582, 1225.154643296123, 1244.5333000124954, 1264.8245018568048, 1287.5686644973946, 1294.6828027133317, 16179.919100404422, 16415.507534773977, 16501.504954851374, 16569.922955007885, 16595.50213493597, 5495.560355171657, 5606.159076138611, 5640.85897852557, 5803.622764520377, 5864.824055278264, 11763.353015584054, 11879.28724276529, 11884.821727674092, 11888.227265227733, 11900.0, 6603.141591593062, 6610.8016759497295, 6612.173478315211, 6615.206175258903, 6616.172978377703, 5064.392272927273, 6112.72492737819, 6184.536159788034, 6239.157713708682, 6257.561970662101, 4294.527601963781, 4393.560643935645, 4428.180786554498, 4453.242948698729, 4455.977720111378, 1278.7186818899827, 1292.7220647560819, 1296.7730647136714, 1304.3282709404664, 1317.7562151002096, 775.8836174573859, 790.2334735306744, 795.2246559604339, 795.9801004974806, 801.3749275565068, 6018.187544681499, 6518.185226846645, 6682.732690692415, 7027.332403421667, 7108.182559005677, 887.8712586674895, 916.9587368568431, 925.7778133247972, 928.8792456980684, 933.9119925286965, 3371.8482668279985, 3458.6856994342493, 3744.64425879541, 3866.130120722815, 3928.4696565963227, 14605.926970365294, 14637.92681036588, 15239.3904243829, 15245.923770381078, 15280.465183718516, 14911.70176596462, 14991.850081499046, 15274.465393711389, 15302.234888255543, 15309.693806124038, 8490.066092729794, 8697.608607612672, 8727.389082764146, 8809.295256379473, 8811.251043661361, 7116.395106416013, 7221.384455360306, 7255.2019277880445, 7280.96359518199, 7306.4520160989205, 10511.0, 13163.0, 13697.109687870257, 13737.931310343385, 13741.519046833313, 13678.931605342308, 14024.509142179975, 14077.648058798612, 14132.929335353258, 14260.0, 9242.953785231031, 9487.383320084173, 9524.190443812353, 9553.665621703485, 9571.808563828823, 10727.141828653845, 10792.352458852494, 10829.18781091437, 10862.076723478593, 10884.07485363753, 9205.907940920504, 9223.077692230723, 9223.86164207524, 9242.537873106183, 9442.244620430487, 8968.372213944962, 8994.145556172152, 9024.593893274687, 9025.41334813235, 9041.276697864287, 8159.755207343789, 8262.091169971418, 8327.666893324442, 8347.332213423033, 8349.666013359398, 5417.593680474059, 5998.88002239944, 6146.5390095742105, 6154.230721159855, 6295.401936815995, 5994.130851026664, 6234.750609975732, 6260.906086408617, 6326.683665816783, 6343.651099189589], "value": 5428.879249559804, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [329.75433302189873, 399.0761387388196, 408.0451742921565, 409.0693671896435, 410.83977248872964, 5520.972395138028, 6126.234220722404, 6230.688465576725, 6389.329120442345, 6459.870802583945, 6136.631802091878, 6234.906476402857, 6251.967795619133, 6267.994241842613, 6337.936620633793, 4423.761346822895, 4568.634509239253, 4571.817127314904, 4574.405327307462, 4575.22221222393, 4381.912361752763, 4496.325551167319, 4531.773411329436, 4544.4092268005115, 4562.475315338729, 3891.8443262269475, 4467.7542735149555, 4484.753338566377, 4486.551344865504, 4489.25927222008, 4267.658587313008, 4475.5748203920575, 4516.774161291955, 4518.390017347649, 4530.618161460753, 3809.9238015239816, 3818.4845045918723, 3830.578636349999, 3874.1476875087437, 3876.0116170376655, 4994.0, 5997.310309314441, 6072.51419886408, 6074.658937584311, 6079.392060793885, 4896.265560165968, 4949.257611358289, 5386.326709161355, 5413.945860541384, 5421.021327215577, 5135.974320128375, 6412.871742565123, 6601.7359305627715, 6651.733930642768, 6682.599044057372, 4326.264535029021, 4348.73907565544, 4360.860180669913, 4371.519132895379, 4372.366006928977, 5450.672959622435, 6347.936520634827, 6801.557898736566, 6956.826079348006, 7096.858062838714, 4465.866024019285, 4468.687191896601, 4474.440694913136, 4478.641708663299, 4493.303537951622, 3602.3155600436357, 4296.978515107467, 4559.589636932626, 4567.725936443758, 4579.839705610354, 5340.359156901119, 5359.598030147755, 5436.483534064258, 5441.809536666197, 5449.618526703172, 5487.396386397493, 6666.733330666719, 6670.966645166744, 6711.926091825285, 6722.66386680664, 1521.186165401505, 1532.6934613077428, 1535.332130523226, 1540.784290199373, 1552.8741662294747, 991.5092029445326, 1001.7746007148334, 1006.9848952265722, 1008.7931973945324, 1021.2953062386929, 5321.9356128773625, 6108.664023478663, 6118.388161183935, 6367.904481432783, 6389.233292004989, 6732.596044237362, 6824.317568243136, 6911.067005954233, 6912.585244885323, 6953.443724502028, 6282.780102696475, 6315.57899472615, 6340.922043252658, 6365.745370185141, 6383.6488993104895, 3284.9343013139605, 3588.990165507324, 3604.017790215371, 3611.0, 3611.963880361163, 6812.557183783038, 6960.155558776928, 7064.43484521237, 7073.717051317993, 7111.53775004623, 6922.930770692329, 7079.398251148607, 7083.574985500886, 7163.057490331655, 7171.390431813355, 5840.970795145997, 7074.575525468488, 7135.108111486064, 7152.642367881585, 7209.495335326476, 5745.798897038583, 6137.079438084323, 6410.942194537849, 6974.930250697631, 7014.123234595676, 5947.791827286024, 6250.718717657625, 6792.796216113525, 6870.31296870309, 6878.965605171942, 6475.0, 7075.929240707527, 7161.820954475971, 7176.174739904769, 7182.712691492491, 4147.585241475828, 4152.148809494107, 4419.999900090424, 4429.889252768707, 4491.258942274488, 7027.297270273137, 7035.507514473938, 7046.577205367695, 7051.717931282691, 7062.329078737512, 6483.7730679425995, 6692.966535167293, 6766.0, 6789.456843452611, 6810.523263371517, 5535.584831137761, 6710.429613482714, 6728.562643428161, 6731.946756239394, 6733.192016957996, 5913.763449462145, 6321.273053598895, 6413.594901911783, 6472.902906456313, 6588.242352129567, 5831.271091113611, 6700.276305204721, 6726.6636668167375, 6733.764318248838, 6749.898751518829, 6006.849828754316, 6478.287388387368, 6537.313582074004, 6560.901586476111, 6591.242007169237], "value": 4836.613580984947, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [728.730369763187, 811.9431639785215, 843.6583183810557, 847.0555330806153, 847.6355167278068, 16526.83473165268, 16996.320147194096, 17024.404145854896, 18606.255749769993, 19264.325748598803, 17814.465566033006, 18263.0, 18354.0, 18608.72086918694, 18799.62400751984, 957.1003256938473, 969.418348990607, 978.740633732058, 979.109010800172, 983.1593987140999, 968.5641461342389, 982.2731178927578, 984.0, 985.0789511806483, 986.595495846703, 1010.6917390195987, 1023.8105950399163, 1024.4109636958742, 1026.3892983674739, 1027.039717863796, 1001.3541265883464, 1007.3150257824676, 1021.4994652620237, 1021.8824835143942, 1024.2727663359024, 734.0090877315635, 742.6064185981438, 755.9622018899033, 756.2928661701327, 757.1141764135974, 16875.324987000506, 17246.3963761269, 17356.132193390407, 17429.738553921703, 17440.912795435943, 16353.345866165339, 17308.91345543264, 17633.0, 17672.646547069115, 17818.643627127516, 14101.71796564063, 14199.503017394341, 14350.282485875665, 14429.062110962754, 14462.49381271661, 3535.5403797506415, 3558.0215440754, 3582.1223800168864, 3586.0317714217017, 3587.1390866192187, 85305.0, 135828.0, 137076.62923370837, 137590.3120484391, 140428.99999999898, 13107.082504224803, 13235.73528529424, 13367.866321336856, 13471.326433678278, 13493.122947008413, 25649.358766030997, 25657.845396957182, 25840.837162327363, 25882.741172588034, 25899.482010360065, 7638.961805191049, 7663.501872378278, 7675.654595543214, 7716.99679041721, 7733.961330193313, 3797.2215695782297, 3942.1130245695063, 3944.2111577684577, 3947.072437977073, 3952.0, 4362.738235705868, 4455.1535208310315, 4456.866294011184, 4457.3091170868565, 4465.660301909479, 791.0467886197118, 808.0949336742885, 810.9026916769907, 813.7965508622844, 813.8453693798277, 594.6877889108166, 636.312782195228, 652.1391762872962, 665.0987911380107, 680.1430197950618, 9842.557084931052, 10160.644377446753, 10410.088332866924, 11991.700207494709, 12046.216995895415, 532.7256462921598, 630.6184758221302, 640.9262934762562, 643.9742410303535, 661.7353058776432, 4184.8744537663915, 5423.806762512241, 5434.402215756264, 5443.101888188484, 5488.862778430571, 90082.54958725165, 100951.99048009573, 110787.0, 111452.0, 111526.0, 77706.0, 98074.0, 98499.0, 98702.0, 99257.17467830837, 88198.0, 88465.0, 88624.55687721648, 88660.5566972174, 89839.0, 8989.685361012333, 11030.779384412428, 11054.392008439292, 11244.269122507338, 11356.716082098013, 39253.01867453336, 42756.78621606872, 43490.782546087066, 43497.56502435062, 43957.0, 48943.5105648939, 54463.455365445836, 55801.72099139641, 56316.0, 56798.0, 14078.859211407755, 14085.0, 14355.354009069408, 14412.13527188372, 14480.637984050483, 15286.235688215767, 15584.766228506809, 15754.448594299603, 15840.60398490047, 15854.7621785671, 13988.0, 14418.783718244444, 14567.562973110824, 14585.854141458874, 14645.633859153606, 14011.929940350234, 14170.00809943294, 14486.275686215859, 14510.854891451372, 14611.269436527919, 11427.542898283975, 14039.859601404263, 14191.858081419467, 14340.139591624536, 14363.640908977359, 9939.403635781877, 10187.490625468845, 10338.483075846027, 10612.893871061191, 10638.14894808429, 9999.450030248407, 10604.787904241717, 11601.477933492844, 11706.122040847142, 11896.464659090188], "value": 8106.9455740582725, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1527.1, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [102.0, 115.0, 116.0, 115.0, 114.0, 116.0, 115.0, 114.0, 116.0, 115.0], "value": 113.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [138.0, 113.0, 148.0, 173.0, 113.0, 139.0, 146.0, 113.0, 143.0, 119.0], "value": 134.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [205.0, 199.0, 205.0, 198.0, 202.0, 223.0, 207.0, 205.0, 228.0, 198.0], "value": 207.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 67.0, 89.0, 85.0, 60.0, 60.0, 63.0, 60.0, 78.0, 60.0], "value": 70.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 56.0, 76.0, 104.0, 104.0, 107.0, 87.0, 74.0, 70.0, 90.0], "value": 83.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 91.0, 91.0, 91.0, 92.0, 90.0, 92.0, 91.0, 92.0, 104.0], "value": 92.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 99.0, 98.0, 106.0, 98.0, 99.0, 98.0, 98.0, 98.0, 103.0], "value": 99.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [123.0, 91.0, 93.0, 87.0, 89.0, 95.0, 116.0, 94.0, 94.0, 95.0], "value": 97.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [51.0, 48.0, 50.0, 48.0, 51.0, 50.0, 50.0, 49.0, 48.0, 51.0], "value": 49.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 53.0, 79.0, 88.0, 87.0, 88.0, 55.0, 57.0, 58.0, 59.0], "value": 68.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 109.0, 85.0, 86.0, 81.0, 94.0, 105.0, 84.0, 86.0, 93.0], "value": 91.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 142.0, 139.0, 140.0, 150.0, 140.0, 141.0, 141.0, 188.0, 139.0], "value": 146.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [241.0, 191.0, 187.0, 194.0, 194.0, 219.0, 194.0, 195.0, 217.0, 191.0], "value": 202.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 71.0, 73.0, 67.0, 71.0, 69.0, 71.0, 71.0, 71.0, 71.0], "value": 70.3, "unit": "score"}]}]} 11:29:04 INFO - Return code: 0 11:29:04 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 11:29:04 INFO - # TBPL SUCCESS # 11:29:04 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 11:29:04 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 11:29:04 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 11:29:04 INFO - 2017-05-16 11:29:04.527 screenresolution[1668:11174] starting screenresolution argv=screenresolution get 11:29:04 INFO - 2017-05-16 11:29:04.529 screenresolution[1668:11174] Display 0: 1600x1200x32@60 11:29:04 INFO - 2017-05-16 11:29:04.535 screenresolution[1669:11177] starting screenresolution argv=screenresolution list 11:29:04 INFO - Available Modes on Display 0 11:29:04 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 11:29:04 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 11:29:04 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 11:29:04 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 11:29:04 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 11:29:04 INFO - Intel Iris: 11:29:04 INFO - Chipset Model: Intel Iris 11:29:04 INFO - Type: GPU 11:29:04 INFO - Bus: Built-In 11:29:04 INFO - VRAM (Dynamic, Max): 1536 MB 11:29:04 INFO - Vendor: Intel (0x8086) 11:29:04 INFO - Device ID: 0x0a2e 11:29:04 INFO - Revision ID: 0x0009 11:29:04 INFO - Displays: 11:29:04 INFO - Display: 11:29:04 INFO - Resolution: 1600 x 1200 @ 60 Hz 11:29:04 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 11:29:04 INFO - Display Serial Number: 1600x1200 60 11:29:04 INFO - Main Display: Yes 11:29:04 INFO - Mirror: Off 11:29:04 INFO - Online: Yes 11:29:04 INFO - Rotation: Supported 11:29:04 INFO - Return code: 0 11:29:04 INFO - Running post-action listener: _resource_record_post_action 11:29:04 INFO - [mozharness: 2017-05-16 18:29:04.776777Z] Finished run-tests step (success) 11:29:04 INFO - Running post-run listener: _resource_record_post_run 11:29:04 INFO - Total resource usage - Wall time: 1195s; CPU: 54.0%; Read bytes: 32625664; Write bytes: 530921472; Read time: 322; Write time: 1732 11:29:04 INFO - TinderboxPrint: CPU usage
54.2% 11:29:04 INFO - TinderboxPrint: I/O read bytes / time
32,625,664 / 322 11:29:04 INFO - TinderboxPrint: I/O write bytes / time
530,921,472 / 1,732 11:29:04 INFO - TinderboxPrint: CPU idle
2,189.7 (45.8%) 11:29:04 INFO - TinderboxPrint: CPU system
961.1 (20.1%) 11:29:04 INFO - TinderboxPrint: CPU user
1,630.8 (34.1%) 11:29:04 INFO - TinderboxPrint: Swap in / out
243,544,064 / 0 11:29:04 INFO - install - Wall time: 21s; CPU: 54.0%; Read bytes: 215810560; Write bytes: 217229312; Read time: 16829; Write time: 475 11:29:04 INFO - run-tests - Wall time: 1175s; CPU: 54.0%; Read bytes: 29918208; Write bytes: 311963648; Read time: 295; Write time: 1250 11:29:05 INFO - Running post-run listener: _upload_blobber_files 11:29:05 INFO - Blob upload gear active. 11:29:05 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 11:29:05 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 11:29:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 11:29:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 11:29:05 INFO - (blobuploader) - INFO - Open directory for files ... 11:29:05 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 11:29:05 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:29:05 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:29:06 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 11:29:06 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:29:06 INFO - (blobuploader) - INFO - Done attempting. 11:29:06 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 11:29:06 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:29:06 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:29:07 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 11:29:07 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:29:07 INFO - (blobuploader) - INFO - Done attempting. 11:29:07 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 11:29:07 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:29:07 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:29:08 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 11:29:08 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:29:08 INFO - (blobuploader) - INFO - Done attempting. 11:29:08 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 11:29:08 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:29:08 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:29:11 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 11:29:11 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:29:11 INFO - (blobuploader) - INFO - Done attempting. 11:29:11 INFO - (blobuploader) - INFO - Iteration through files over. 11:29:11 INFO - Return code: 0 11:29:11 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 11:29:11 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 11:29:11 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c8c7dbb6f8e4432aed32a836a4fd9250e02cd94b69accff95d2c1285f453bdc3150bacb9021ec5d9a7f014e8135e510681047febc97845e04c1e48529dc6dc83", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bfb0d41499f0d92876b6b52c24fdcb9644c7f492ac6d180ef38251f5c0634766d914cccc013fcb0ee9abff24bc231b2127a76c547ad09804f80e5a194ee42089", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4e2fe555fb629272cce2ab405df28f61f4875e95dc3584f261cf9519620350487be1fb5fa131d026c79e3eabdf749814323acbdf4fcf763875872479fa7a013b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/fab4c6cad6b7b2cb7b3e9c708f88131dd98d55c81a18f0649fbb453129463bef7b923a26074b185cb9c2e9cb180adf6fdddd33796f4739f453fa4a4f18c01640"} 11:29:11 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 11:29:11 INFO - Writing to file /builds/slave/test/properties/blobber_files 11:29:11 INFO - Contents: 11:29:11 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c8c7dbb6f8e4432aed32a836a4fd9250e02cd94b69accff95d2c1285f453bdc3150bacb9021ec5d9a7f014e8135e510681047febc97845e04c1e48529dc6dc83", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bfb0d41499f0d92876b6b52c24fdcb9644c7f492ac6d180ef38251f5c0634766d914cccc013fcb0ee9abff24bc231b2127a76c547ad09804f80e5a194ee42089", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4e2fe555fb629272cce2ab405df28f61f4875e95dc3584f261cf9519620350487be1fb5fa131d026c79e3eabdf749814323acbdf4fcf763875872479fa7a013b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/fab4c6cad6b7b2cb7b3e9c708f88131dd98d55c81a18f0649fbb453129463bef7b923a26074b185cb9c2e9cb180adf6fdddd33796f4739f453fa4a4f18c01640"} 11:29:11 INFO - Running post-run listener: copy_logs_to_upload_dir 11:29:11 INFO - Copying logs to upload dir... 11:29:11 INFO - mkdir: /builds/slave/test/build/upload/logs 11:29:11 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1336.488918 ========= master_lag: -0.30 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 16 secs) (at 2017-05-16 11:29:10.741479) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-16 11:29:10.744693) ========= 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.LejAOfT8r6/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.nUkVAnWmBP/Listeners TMPDIR=/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/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/c8c7dbb6f8e4432aed32a836a4fd9250e02cd94b69accff95d2c1285f453bdc3150bacb9021ec5d9a7f014e8135e510681047febc97845e04c1e48529dc6dc83", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bfb0d41499f0d92876b6b52c24fdcb9644c7f492ac6d180ef38251f5c0634766d914cccc013fcb0ee9abff24bc231b2127a76c547ad09804f80e5a194ee42089", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4e2fe555fb629272cce2ab405df28f61f4875e95dc3584f261cf9519620350487be1fb5fa131d026c79e3eabdf749814323acbdf4fcf763875872479fa7a013b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/fab4c6cad6b7b2cb7b3e9c708f88131dd98d55c81a18f0649fbb453129463bef7b923a26074b185cb9c2e9cb180adf6fdddd33796f4739f453fa4a4f18c01640"} build_url:https://queue.taskcluster.net/v1/task/IW_fDKRQQTu8bzRdUhdCeA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010968 build_url: 'https://queue.taskcluster.net/v1/task/IW_fDKRQQTu8bzRdUhdCeA/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/c8c7dbb6f8e4432aed32a836a4fd9250e02cd94b69accff95d2c1285f453bdc3150bacb9021ec5d9a7f014e8135e510681047febc97845e04c1e48529dc6dc83", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bfb0d41499f0d92876b6b52c24fdcb9644c7f492ac6d180ef38251f5c0634766d914cccc013fcb0ee9abff24bc231b2127a76c547ad09804f80e5a194ee42089", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4e2fe555fb629272cce2ab405df28f61f4875e95dc3584f261cf9519620350487be1fb5fa131d026c79e3eabdf749814323acbdf4fcf763875872479fa7a013b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/fab4c6cad6b7b2cb7b3e9c708f88131dd98d55c81a18f0649fbb453129463bef7b923a26074b185cb9c2e9cb180adf6fdddd33796f4739f453fa4a4f18c01640"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-16 11:29:10.778921) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-16 11:29:10.779286) ========= 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.LejAOfT8r6/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.nUkVAnWmBP/Listeners TMPDIR=/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/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.005276 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-16 11:29:10.828208) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-16 11:29:10.828549) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-16 11:29:10.828898) ========= ========= Total master_lag: 6.72 =========