builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0276 starttime: 1496339164.66 results: success (0) revision: 96b243f2267710b81f5908c7de98fa046c715610 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-06-01 10:46:04.661144) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-06-01 10:46:04.661631) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-01 10:46:04.661926) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-01 10:46:04.687472) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-01 10:46:04.687876) ========= 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.chpjD09hH9/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.u3Qo9TppPN/Listeners TMPDIR=/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-06-01 10:46:04-- 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% 71.7M=0s 2017-06-01 10:46:05 (71.7 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.345806 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-01 10:46:05.057898) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-01 10:46:05.058219) ========= 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.chpjD09hH9/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.u3Qo9TppPN/Listeners TMPDIR=/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/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.026236 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-01 10:46:05.106717) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-06-01 10:46:05.107072) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 96b243f2267710b81f5908c7de98fa046c715610 --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 96b243f2267710b81f5908c7de98fa046c715610 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.chpjD09hH9/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.u3Qo9TppPN/Listeners TMPDIR=/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-06-01 10:46:05,204 truncating revision to first 12 chars 2017-06-01 10:46:05,204 Setting DEBUG logging. 2017-06-01 10:46:05,204 attempt 1/10 2017-06-01 10:46:05,204 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/96b243f22677?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-06-01 10:46:06,222 unpacking tar archive at: mozilla-central-96b243f22677/testing/mozharness/ program finished with exit code 0 elapsedTime=1.293239 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-06-01 10:46:06.422092) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-01 10:46:06.422459) ========= script_repo_revision: 96b243f2267710b81f5908c7de98fa046c715610 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-01 10:46:06.422875) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-01 10:46:06.423151) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-01 10:46:06.442157) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 19 secs) (at 2017-06-01 10:46:06.442663) ========= /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.chpjD09hH9/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.u3Qo9TppPN/Listeners TMPDIR=/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/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 10:46:06 INFO - MultiFileLogger online at 20170601 10:46:06 in /builds/slave/test 10:46:06 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 10:46:06 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 10:46:06 INFO - {'append_to_log': False, 10:46:06 INFO - 'base_work_dir': '/builds/slave/test', 10:46:06 INFO - 'blob_upload_branch': 'Firefox', 10:46:06 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 10:46:06 INFO - 'branch': 'Firefox', 10:46:06 INFO - 'buildbot_json_path': 'buildprops.json', 10:46:06 INFO - 'config_files': ('talos/mac_config.py',), 10:46:06 INFO - 'default_actions': ('clobber', 10:46:06 INFO - 'read-buildbot-config', 10:46:06 INFO - 'download-and-extract', 10:46:06 INFO - 'populate-webroot', 10:46:06 INFO - 'create-virtualenv', 10:46:06 INFO - 'install', 10:46:06 INFO - 'setup-mitmproxy', 10:46:06 INFO - 'run-tests'), 10:46:06 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 10:46:06 INFO - 'download_minidump_stackwalk': True, 10:46:06 INFO - 'download_symbols': 'ondemand', 10:46:06 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 10:46:06 INFO - 'tooltool.py': '/tools/tooltool.py', 10:46:06 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 10:46:06 INFO - '/tools/misc-python/virtualenv.py')}, 10:46:06 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 10:46:06 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 10:46:06 INFO - 'gecko_profile': False, 10:46:06 INFO - 'gecko_profile_interval': 0, 10:46:06 INFO - 'installer_path': 'installer.exe', 10:46:06 INFO - 'log_level': 'info', 10:46:06 INFO - 'log_name': 'talos', 10:46:06 INFO - 'log_to_console': True, 10:46:06 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 10:46:06 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 10:46:06 INFO - 'opt_config_files': (), 10:46:06 INFO - 'pip_index': False, 10:46:06 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 10:46:06 INFO - 'cmd': ('bash', 10:46:06 INFO - '-c', 10:46:06 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 10:46:06 INFO - 'enabled': True, 10:46:06 INFO - 'halt_on_failure': False, 10:46:06 INFO - 'name': 'check_screen_resolution'},), 10:46:06 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 10:46:06 INFO - 'cmd': ('bash', 10:46:06 INFO - '-c', 10:46:06 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 10:46:06 INFO - 'enabled': True, 10:46:06 INFO - 'halt_on_failure': False, 10:46:06 INFO - 'name': 'check_screen_resolution'},), 10:46:06 INFO - 'run_cmd_checks_enabled': True, 10:46:06 INFO - 'suite': 'dromaeojs-e10s', 10:46:06 INFO - 'system_bits': '32', 10:46:06 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 10:46:06 INFO - 'title': 't-yosemite-r7-0276', 10:46:06 INFO - 'tooltool_cache': '/builds/tooltool_cache', 10:46:06 INFO - 'use_talos_json': True, 10:46:06 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 10:46:06 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 10:46:06 INFO - 'work_dir': 'build'} 10:46:06 INFO - [mozharness: 2017-06-01 17:46:06.647256Z] Running clobber step. 10:46:06 INFO - Running pre-action listener: _resource_record_pre_action 10:46:06 INFO - Running main action method: clobber 10:46:06 INFO - rmtree: /builds/slave/test/build 10:46:06 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 10:46:09 INFO - Running post-action listener: _resource_record_post_action 10:46:09 INFO - [mozharness: 2017-06-01 17:46:09.152333Z] Finished clobber step (success) 10:46:09 INFO - [mozharness: 2017-06-01 17:46:09.152468Z] Running read-buildbot-config step. 10:46:09 INFO - Running pre-action listener: _resource_record_pre_action 10:46:09 INFO - Running main action method: read_buildbot_config 10:46:09 INFO - Using buildbot properties: 10:46:09 INFO - { 10:46:09 INFO - "project": "", 10:46:09 INFO - "product": "firefox", 10:46:09 INFO - "who": "ffxbld@noreply.mozilla.org", 10:46:09 INFO - "installer_path": "public/build/firefox-55.0a1.en-US.mac.dmg", 10:46:09 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 10:46:09 INFO - "repository": "https://hg.mozilla.org/mozilla-central/", 10:46:09 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 10:46:09 INFO - "stage_platform": "macosx64", 10:46:09 INFO - "basedir": "/builds/slave/test", 10:46:09 INFO - "buildnumber": 84, 10:46:09 INFO - "platform": "yosemite_r7", 10:46:09 INFO - "parent_task_id": "alTWZOyQSKKYdui0xaoH4w", 10:46:09 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 10:46:09 INFO - "slavebuilddir": "test", 10:46:09 INFO - "taskId": "XMmjbgMtTCyyy28gyI09wQ", 10:46:09 INFO - "branch": "mozilla-central", 10:46:09 INFO - "script_repo_revision": "production", 10:46:09 INFO - "revision": "96b243f2267710b81f5908c7de98fa046c715610", 10:46:09 INFO - "slavename": "t-yosemite-r7-0276", 10:46:09 INFO - "repo_path": "mozilla-central" 10:46:09 INFO - } 10:46:09 INFO - Finding installer, test and symbols from parent task. 10:46:09 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/XMmjbgMtTCyyy28gyI09wQ'}, attempt #1 10:46:10 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/XMmjbgMtTCyyy28gyI09wQ'}, attempt #1 10:46:10 INFO - Task dependencies: HZ0XouZDS3K2WWY8PRgR5w alTWZOyQSKKYdui0xaoH4w 10:46:10 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/alTWZOyQSKKYdui0xaoH4w'}, attempt #1 10:46:10 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/alTWZOyQSKKYdui0xaoH4w/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 10:46:10 INFO - Set test_url: https://queue.taskcluster.net/v1/task/alTWZOyQSKKYdui0xaoH4w/artifacts/public/build/test_packages.json 10:46:10 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/alTWZOyQSKKYdui0xaoH4w/artifacts/public/build/target.crashreporter-symbols.zip 10:46:10 INFO - Running post-action listener: _resource_record_post_action 10:46:10 INFO - [mozharness: 2017-06-01 17:46:10.931471Z] Finished read-buildbot-config step (success) 10:46:10 INFO - [mozharness: 2017-06-01 17:46:10.931640Z] Running download-and-extract step. 10:46:10 INFO - Running pre-action listener: _resource_record_pre_action 10:46:10 INFO - Running main action method: download_and_extract 10:46:10 INFO - mkdir: /builds/slave/test/build/tests 10:46:10 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')]} 10:46:10 INFO - trying https://queue.taskcluster.net/v1/task/alTWZOyQSKKYdui0xaoH4w/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 10:46:10 INFO - Downloading https://queue.taskcluster.net/v1/task/alTWZOyQSKKYdui0xaoH4w/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 10:46:10 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/alTWZOyQSKKYdui0xaoH4w/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 10:46:11 INFO - Downloaded 1325 bytes. 10:46:11 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 10:46:11 INFO - Using the following test package requirements: 10:46:11 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 10:46:11 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 10:46:11 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 10:46:11 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 10:46:11 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 10:46:11 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 10:46:11 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 10:46:11 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 10:46:11 INFO - u'jsshell-mac.zip'], 10:46:11 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 10:46:11 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 10:46:11 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 10:46:11 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 10:46:11 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 10:46:11 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 10:46:11 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 10:46:11 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 10:46:11 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 10:46:11 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 10:46:11 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 10:46:11 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 10:46:11 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/alTWZOyQSKKYdui0xaoH4w/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 10:46:11 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/alTWZOyQSKKYdui0xaoH4w/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 10:46:11 INFO - Fetch https://queue.taskcluster.net/v1/task/alTWZOyQSKKYdui0xaoH4w/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 10:46:13 INFO - Content-Length response header: 57630214 10:46:13 INFO - Bytes received: 57630214 10:46:31 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 10:46:31 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/alTWZOyQSKKYdui0xaoH4w/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 10:46:31 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/alTWZOyQSKKYdui0xaoH4w/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 10:46:31 INFO - Fetch https://queue.taskcluster.net/v1/task/alTWZOyQSKKYdui0xaoH4w/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 10:46:34 INFO - Content-Length response header: 57630214 10:46:34 INFO - Bytes received: 57630214 10:46:51 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/alTWZOyQSKKYdui0xaoH4w/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 10:46:51 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/alTWZOyQSKKYdui0xaoH4w/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 10:46:51 INFO - Fetch https://queue.taskcluster.net/v1/task/alTWZOyQSKKYdui0xaoH4w/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 10:46:52 INFO - Content-Length response header: 14447425 10:46:52 INFO - Bytes received: 14447425 10:46:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:46:52 INFO - trying https://queue.taskcluster.net/v1/task/alTWZOyQSKKYdui0xaoH4w/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 10:46:52 INFO - Downloading https://queue.taskcluster.net/v1/task/alTWZOyQSKKYdui0xaoH4w/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 10:46:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/alTWZOyQSKKYdui0xaoH4w/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 10:46:54 INFO - Downloaded 67867097 bytes. 10:46:54 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/alTWZOyQSKKYdui0xaoH4w/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 10:46:54 INFO - mkdir: /builds/slave/test/properties 10:46:54 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 10:46:54 INFO - Writing to file /builds/slave/test/properties/build_url 10:46:54 INFO - Contents: 10:46:54 INFO - build_url:https://queue.taskcluster.net/v1/task/alTWZOyQSKKYdui0xaoH4w/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 10:46:54 INFO - Running post-action listener: _resource_record_post_action 10:46:54 INFO - Running post-action listener: set_extra_try_arguments 10:46:54 INFO - [mozharness: 2017-06-01 17:46:54.448223Z] Finished download-and-extract step (success) 10:46:54 INFO - [mozharness: 2017-06-01 17:46:54.448371Z] Running populate-webroot step. 10:46:54 INFO - Running pre-action listener: _resource_record_pre_action 10:46:54 INFO - Running main action method: populate_webroot 10:46:54 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 10:46:54 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 10:46:54 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 10:46:54 INFO - '--tppagecycles', 10:46:54 INFO - '7'], 10:46:54 INFO - 'tests': ['tsvgm']}}, 10:46:54 INFO - 'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 10:46:54 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 10:46:54 INFO - 'tests': ['dromaeo_css', 'kraken']}, 10:46:54 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 10:46:54 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 10:46:54 INFO - 'talos_options': ['--disable-e10s'], 10:46:54 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:46:54 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 10:46:54 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:46:54 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 10:46:54 INFO - 'talos_options': ['--disable-e10s'], 10:46:54 INFO - 'tests': ['damp', 'tps']}, 10:46:54 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 10:46:54 INFO - 'tests': ['damp', 'tps']}, 10:46:54 INFO - 'g3': {'talos_options': ['--disable-e10s'], 10:46:54 INFO - 'tests': ['dromaeo_dom']}, 10:46:54 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 10:46:54 INFO - 'g4': {'talos_options': ['--disable-e10s'], 10:46:54 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 10:46:54 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 10:46:54 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 10:46:54 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 10:46:54 INFO - 'other': {'talos_options': ['--disable-e10s'], 10:46:54 INFO - 'tests': ['a11yr', 10:46:54 INFO - 'ts_paint', 10:46:54 INFO - 'tpaint', 10:46:54 INFO - 'sessionrestore', 10:46:54 INFO - 'sessionrestore_no_auto_restore', 10:46:54 INFO - 'tabpaint']}, 10:46:54 INFO - 'other-e10s': {'tests': ['a11yr', 10:46:54 INFO - 'ts_paint', 10:46:54 INFO - 'tpaint', 10:46:54 INFO - 'sessionrestore', 10:46:54 INFO - 'sessionrestore_no_auto_restore', 10:46:54 INFO - 'tabpaint']}, 10:46:54 INFO - 'other-e10s_l64': {'tests': ['a11yr', 10:46:54 INFO - 'ts_paint', 10:46:54 INFO - 'tpaint', 10:46:54 INFO - 'sessionrestore', 10:46:54 INFO - 'sessionrestore_no_auto_restore', 10:46:54 INFO - 'tabpaint']}, 10:46:54 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 10:46:54 INFO - 'ts_paint', 10:46:54 INFO - 'tpaint', 10:46:54 INFO - 'sessionrestore', 10:46:54 INFO - 'sessionrestore_no_auto_restore', 10:46:54 INFO - 'tabpaint']}, 10:46:54 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 10:46:54 INFO - 'tests': ['a11yr', 10:46:54 INFO - 'ts_paint', 10:46:54 INFO - 'tpaint', 10:46:54 INFO - 'sessionrestore', 10:46:54 INFO - 'sessionrestore_no_auto_restore', 10:46:54 INFO - 'tabpaint']}, 10:46:54 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 10:46:54 INFO - 'tests': ['a11yr', 10:46:54 INFO - 'ts_paint', 10:46:54 INFO - 'tpaint', 10:46:54 INFO - 'sessionrestore', 10:46:54 INFO - 'sessionrestore_no_auto_restore', 10:46:54 INFO - 'tabpaint']}, 10:46:54 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 10:46:54 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 10:46:54 INFO - 'bloom_basic_ref']}, 10:46:54 INFO - 'quantum-pageload-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set.zip', 10:46:54 INFO - 'talos_options': ['--mitmproxy', 10:46:54 INFO - 'mitmproxy-recording-1.mp', 10:46:54 INFO - '--firstNonBlankPaint'], 10:46:54 INFO - 'tests': ['Quantum_1']}, 10:46:54 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 10:46:54 INFO - 'tests': ['tsvgx', 10:46:54 INFO - 'tsvgr_opacity', 10:46:54 INFO - 'tart', 10:46:54 INFO - 'tscrollx', 10:46:54 INFO - 'cart', 10:46:54 INFO - 'tsvg_static']}, 10:46:54 INFO - 'svgr-e10s': {'tests': ['tsvgx', 10:46:54 INFO - 'tsvgr_opacity', 10:46:54 INFO - 'tart', 10:46:54 INFO - 'tscrollx', 10:46:54 INFO - 'cart', 10:46:54 INFO - 'tsvg_static']}, 10:46:54 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 10:46:54 INFO - 'talos_options': ['--disable-e10s'], 10:46:54 INFO - 'tests': ['tp5o']}, 10:46:54 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 10:46:54 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 10:46:54 INFO - 'talos_options': ['--disable-e10s', 10:46:54 INFO - '--xperf_path', 10:46:54 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:46:54 INFO - 'tests': ['tp5n']}, 10:46:54 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 10:46:54 INFO - 'talos_options': ['--xperf_path', 10:46:54 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:46:54 INFO - 'tests': ['tp5n']}}, 10:46:54 INFO - 'talos.zip': {'path': '', 10:46:54 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 10:46:54 INFO - Running post-action listener: _resource_record_post_action 10:46:54 INFO - [mozharness: 2017-06-01 17:46:54.471179Z] Finished populate-webroot step (success) 10:46:54 INFO - [mozharness: 2017-06-01 17:46:54.471310Z] Running create-virtualenv step. 10:46:54 INFO - Running pre-action listener: _resource_record_pre_action 10:46:54 INFO - Running main action method: create_virtualenv 10:46:54 INFO - Creating virtualenv /builds/slave/test/build/venv 10:46:54 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 10:46:54 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 10:46:54 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 10:46:54 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 10:46:54 INFO - Using real prefix '/tools/python27' 10:46:54 INFO - New python executable in /builds/slave/test/build/venv/bin/python 10:46:55 INFO - Installing distribute.............................................................................................................................................................................................done. 10:46:58 INFO - Installing pip.................done. 10:46:58 INFO - Return code: 0 10:46:58 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 10:46:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 10:46:58 INFO - Reading from file tmpfile_stdout 10:46:58 INFO - Output received: 10:46:58 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) 10:46:58 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 10:46:58 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')]} 10:46:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:46:58 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')]} 10:46:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:46:58 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 0x10b971c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b975768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa8d84b1440>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b8fac60>, '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 0x10b68fcd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b6b8be8>, '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.chpjD09hH9/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.u3Qo9TppPN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/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 10:46:58 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 10:46:58 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 10:46:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.chpjD09hH9/Render', 10:46:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:46:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:46:58 INFO - 'HOME': '/Users/cltbld', 10:46:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:46:58 INFO - 'LOGNAME': 'cltbld', 10:46:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:46:58 INFO - 'MOZ_NO_REMOTE': '1', 10:46:58 INFO - 'NO_EM_RESTART': '1', 10:46:58 INFO - 'PAGER': '/bin/cat', 10:46:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:46:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:46:58 INFO - 'PWD': '/builds/slave/test', 10:46:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:46:58 INFO - 'SHELL': '/bin/bash', 10:46:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.u3Qo9TppPN/Listeners', 10:46:58 INFO - 'TMPDIR': '/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/', 10:46:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:46:58 INFO - 'USER': 'cltbld', 10:46:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:46:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:46:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:46:58 INFO - 'XPC_FLAGS': '0x0', 10:46:58 INFO - 'XPC_SERVICE_NAME': '0', 10:46:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:46:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:46:58 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 10:46:58 INFO - Cleaning up... 10:46:58 INFO - Return code: 0 10:46:58 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 10:46:58 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')]} 10:46:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:46:58 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')]} 10:46:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:46:58 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 0x10b971c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b975768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa8d84b1440>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b8fac60>, '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 0x10b68fcd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b6b8be8>, '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.chpjD09hH9/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.u3Qo9TppPN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/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 10:46:58 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 10:46:58 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 10:46:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.chpjD09hH9/Render', 10:46:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:46:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:46:58 INFO - 'HOME': '/Users/cltbld', 10:46:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:46:58 INFO - 'LOGNAME': 'cltbld', 10:46:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:46:58 INFO - 'MOZ_NO_REMOTE': '1', 10:46:58 INFO - 'NO_EM_RESTART': '1', 10:46:58 INFO - 'PAGER': '/bin/cat', 10:46:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:46:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:46:58 INFO - 'PWD': '/builds/slave/test', 10:46:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:46:58 INFO - 'SHELL': '/bin/bash', 10:46:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.u3Qo9TppPN/Listeners', 10:46:58 INFO - 'TMPDIR': '/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/', 10:46:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:46:58 INFO - 'USER': 'cltbld', 10:46:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:46:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:46:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:46:58 INFO - 'XPC_FLAGS': '0x0', 10:46:58 INFO - 'XPC_SERVICE_NAME': '0', 10:46:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:46:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:46:59 INFO - Downloading/unpacking psutil>=3.1.1 10:46:59 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 10:46:59 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 10:46:59 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 10:46:59 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 10:47:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 10:47:01 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:47:01 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:47:01 INFO - Installing collected packages: psutil 10:47:01 INFO - Running setup.py install for psutil 10:47:01 INFO - building 'psutil._psutil_osx' extension 10:47:01 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 10:47:01 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 10:47:01 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 10:47:01 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 10:47:01 INFO - building 'psutil._psutil_posix' extension 10:47:01 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 10:47:02 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 10:47:02 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 10:47:02 INFO - ^ 10:47:02 INFO - 1 warning generated. 10:47:02 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 10:47:02 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:47:02 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:47:02 INFO - Successfully installed psutil 10:47:02 INFO - Cleaning up... 10:47:02 INFO - Return code: 0 10:47:02 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 10:47:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:47:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:47:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:47:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:47:02 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 0x10b971c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b975768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa8d84b1440>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b8fac60>, '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 0x10b68fcd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b6b8be8>, '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.chpjD09hH9/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.u3Qo9TppPN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/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 10:47:02 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 10:47:02 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 10:47:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.chpjD09hH9/Render', 10:47:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:47:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:47:02 INFO - 'HOME': '/Users/cltbld', 10:47:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:47:02 INFO - 'LOGNAME': 'cltbld', 10:47:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:47:02 INFO - 'MOZ_NO_REMOTE': '1', 10:47:02 INFO - 'NO_EM_RESTART': '1', 10:47:02 INFO - 'PAGER': '/bin/cat', 10:47:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:47:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:47:02 INFO - 'PWD': '/builds/slave/test', 10:47:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:47:02 INFO - 'SHELL': '/bin/bash', 10:47:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.u3Qo9TppPN/Listeners', 10:47:02 INFO - 'TMPDIR': '/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/', 10:47:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:47:02 INFO - 'USER': 'cltbld', 10:47:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:47:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:47:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:47:02 INFO - 'XPC_FLAGS': '0x0', 10:47:02 INFO - 'XPC_SERVICE_NAME': '0', 10:47:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:47:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:47:03 INFO - Downloading/unpacking mozsystemmonitor==0.3 10:47:03 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 10:47:03 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 10:47:03 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 10:47:03 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 10:47:04 INFO - Downloading mozsystemmonitor-0.3.tar.gz 10:47:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 10:47:05 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 10:47:05 INFO - Installing collected packages: mozsystemmonitor 10:47:05 INFO - Running setup.py install for mozsystemmonitor 10:47:05 INFO - Successfully installed mozsystemmonitor 10:47:05 INFO - Cleaning up... 10:47:05 INFO - Return code: 0 10:47:05 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 10:47:05 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')]} 10:47:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:47:05 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')]} 10:47:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:47:05 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 0x10b971c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b975768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa8d84b1440>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b8fac60>, '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 0x10b68fcd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b6b8be8>, '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.chpjD09hH9/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.u3Qo9TppPN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/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 10:47:05 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 10:47:05 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 10:47:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.chpjD09hH9/Render', 10:47:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:47:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:47:05 INFO - 'HOME': '/Users/cltbld', 10:47:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:47:05 INFO - 'LOGNAME': 'cltbld', 10:47:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:47:05 INFO - 'MOZ_NO_REMOTE': '1', 10:47:05 INFO - 'NO_EM_RESTART': '1', 10:47:05 INFO - 'PAGER': '/bin/cat', 10:47:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:47:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:47:05 INFO - 'PWD': '/builds/slave/test', 10:47:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:47:05 INFO - 'SHELL': '/bin/bash', 10:47:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.u3Qo9TppPN/Listeners', 10:47:05 INFO - 'TMPDIR': '/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/', 10:47:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:47:05 INFO - 'USER': 'cltbld', 10:47:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:47:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:47:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:47:05 INFO - 'XPC_FLAGS': '0x0', 10:47:05 INFO - 'XPC_SERVICE_NAME': '0', 10:47:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:47:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:47:05 INFO - Downloading/unpacking jsonschema==2.5.1 10:47:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:47:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:47:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:47:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:47:06 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 10:47:06 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 10:47: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 10:47: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 10:47: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 10:47: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 10:47:07 INFO - Downloading functools32-3.2.3-2.tar.gz 10:47:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 10:47:07 INFO - warning: no files found matching '*.txt' 10:47:07 INFO - no previously-included directories found matching 'build' 10:47:07 INFO - no previously-included directories found matching 'dist' 10:47:07 INFO - no previously-included directories found matching '.git*' 10:47:07 INFO - Installing collected packages: jsonschema, functools32 10:47:07 INFO - Running setup.py install for functools32 10:47:07 INFO - warning: no files found matching '*.txt' 10:47:07 INFO - no previously-included directories found matching 'build' 10:47:07 INFO - no previously-included directories found matching 'dist' 10:47:07 INFO - no previously-included directories found matching '.git*' 10:47:07 INFO - Successfully installed jsonschema functools32 10:47:07 INFO - Cleaning up... 10:47:07 INFO - Return code: 0 10:47:07 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 10:47:07 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')]} 10:47:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:47:07 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')]} 10:47:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:47:07 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 0x10b971c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b975768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa8d84b1440>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b8fac60>, '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 0x10b68fcd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b6b8be8>, '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.chpjD09hH9/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.u3Qo9TppPN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/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 10:47:07 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 10:47:07 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 10:47:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.chpjD09hH9/Render', 10:47:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:47:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:47:07 INFO - 'HOME': '/Users/cltbld', 10:47:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:47:07 INFO - 'LOGNAME': 'cltbld', 10:47:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:47:07 INFO - 'MOZ_NO_REMOTE': '1', 10:47:07 INFO - 'NO_EM_RESTART': '1', 10:47:07 INFO - 'PAGER': '/bin/cat', 10:47:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:47:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:47:07 INFO - 'PWD': '/builds/slave/test', 10:47:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:47:07 INFO - 'SHELL': '/bin/bash', 10:47:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.u3Qo9TppPN/Listeners', 10:47:07 INFO - 'TMPDIR': '/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/', 10:47:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:47:07 INFO - 'USER': 'cltbld', 10:47:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:47:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:47:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:47:07 INFO - 'XPC_FLAGS': '0x0', 10:47:07 INFO - 'XPC_SERVICE_NAME': '0', 10:47:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:47:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:47:07 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 10:47:07 INFO - Cleaning up... 10:47:07 INFO - Return code: 0 10:47:07 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 10:47:07 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')]} 10:47:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:47:07 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')]} 10:47:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:47:07 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 0x10b971c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b975768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa8d84b1440>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b8fac60>, '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 0x10b68fcd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b6b8be8>, '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.chpjD09hH9/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.u3Qo9TppPN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/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 10:47:07 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 10:47:07 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 10:47:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.chpjD09hH9/Render', 10:47:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:47:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:47:07 INFO - 'HOME': '/Users/cltbld', 10:47:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:47:07 INFO - 'LOGNAME': 'cltbld', 10:47:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:47:07 INFO - 'MOZ_NO_REMOTE': '1', 10:47:07 INFO - 'NO_EM_RESTART': '1', 10:47:07 INFO - 'PAGER': '/bin/cat', 10:47:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:47:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:47:07 INFO - 'PWD': '/builds/slave/test', 10:47:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:47:07 INFO - 'SHELL': '/bin/bash', 10:47:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.u3Qo9TppPN/Listeners', 10:47:07 INFO - 'TMPDIR': '/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/', 10:47:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:47:07 INFO - 'USER': 'cltbld', 10:47:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:47:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:47:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:47:07 INFO - 'XPC_FLAGS': '0x0', 10:47:07 INFO - 'XPC_SERVICE_NAME': '0', 10:47:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:47:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:47:08 INFO - Downloading/unpacking blobuploader==1.2.4 10:47:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:47:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:47:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:47:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:47:09 INFO - Downloading blobuploader-1.2.4.tar.gz 10:47:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 10:47:09 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 10:47:09 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 10:47:09 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 10:47:09 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 10:47:09 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 10:47:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 10:47:10 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 10:47:10 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 10:47:10 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 10:47:10 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 10:47:10 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 10:47:10 INFO - Downloading docopt-0.6.1.tar.gz 10:47:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 10:47:10 INFO - Installing collected packages: blobuploader, requests, docopt 10:47:10 INFO - Running setup.py install for blobuploader 10:47:10 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 10:47:10 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 10:47:10 INFO - Running setup.py install for requests 10:47:11 INFO - Running setup.py install for docopt 10:47:11 INFO - Successfully installed blobuploader requests docopt 10:47:11 INFO - Cleaning up... 10:47:11 INFO - Return code: 0 10:47:11 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:47:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:47:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:47:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:47:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:47:11 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 0x10b971c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b975768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa8d84b1440>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b8fac60>, '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 0x10b68fcd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b6b8be8>, '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.chpjD09hH9/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.u3Qo9TppPN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/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 10:47:11 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 10:47:11 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 10:47:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.chpjD09hH9/Render', 10:47:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:47:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:47:11 INFO - 'HOME': '/Users/cltbld', 10:47:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:47:11 INFO - 'LOGNAME': 'cltbld', 10:47:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:47:11 INFO - 'MOZ_NO_REMOTE': '1', 10:47:11 INFO - 'NO_EM_RESTART': '1', 10:47:11 INFO - 'PAGER': '/bin/cat', 10:47:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:47:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:47:11 INFO - 'PWD': '/builds/slave/test', 10:47:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:47:11 INFO - 'SHELL': '/bin/bash', 10:47:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.u3Qo9TppPN/Listeners', 10:47:11 INFO - 'TMPDIR': '/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/', 10:47:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:47:11 INFO - 'USER': 'cltbld', 10:47:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:47:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:47:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:47:11 INFO - 'XPC_FLAGS': '0x0', 10:47:11 INFO - 'XPC_SERVICE_NAME': '0', 10:47:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:47:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:47:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:47:11 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-62yM5I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:47:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:47:11 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-omVNm1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:47:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:47:11 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-6FnvVp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:47:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:47:11 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-R7GLIM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:47:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:47:11 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-T9zEPh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:47:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:47:12 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-ZCazwI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:47:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:47:12 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-wyz4GF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:47:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:47:12 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-qK7Ski-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:47:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:47:12 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-uaUW3B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:47:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:47:12 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-6G7WIy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:47:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:47:12 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-jc_Fek-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:47:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:47:12 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-sKjzBB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:47:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:47:12 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-aIrY63-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:47:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:47:12 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-dEnP1w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:47:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:47:12 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-SF3exn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:47:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:47:13 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-EDW3KG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:47:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:47:13 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-R2sAp3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:47:13 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 10:47:13 INFO - Running setup.py install for manifestparser 10:47:13 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 10:47:13 INFO - Running setup.py install for mozcrash 10:47:13 INFO - Running setup.py install for mozdebug 10:47:13 INFO - Running setup.py install for mozdevice 10:47:13 INFO - Installing dm script to /builds/slave/test/build/venv/bin 10:47:13 INFO - Running setup.py install for mozfile 10:47:14 INFO - Running setup.py install for mozhttpd 10:47:14 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 10:47:14 INFO - Running setup.py install for mozinfo 10:47:14 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 10:47:14 INFO - Running setup.py install for mozInstall 10:47:14 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 10:47:14 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 10:47:14 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 10:47:14 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 10:47:14 INFO - Running setup.py install for mozleak 10:47:14 INFO - Running setup.py install for mozlog 10:47:14 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 10:47:14 INFO - Running setup.py install for moznetwork 10:47:14 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 10:47:14 INFO - Running setup.py install for mozprocess 10:47:15 INFO - Running setup.py install for mozprofile 10:47:15 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 10:47:15 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 10:47:15 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 10:47:15 INFO - Running setup.py install for mozrunner 10:47:15 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 10:47:15 INFO - Running setup.py install for mozscreenshot 10:47:15 INFO - Running setup.py install for moztest 10:47:15 INFO - Running setup.py install for mozversion 10:47:15 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 10:47:15 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 10:47:15 INFO - Cleaning up... 10:47:15 INFO - Return code: 0 10:47:15 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:47:15 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')]} 10:47:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:47:15 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')]} 10:47:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:47:15 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 0x10b971c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b975768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa8d84b1440>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b8fac60>, '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 0x10b68fcd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b6b8be8>, '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.chpjD09hH9/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.u3Qo9TppPN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/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 10:47:15 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 10:47:15 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 10:47:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.chpjD09hH9/Render', 10:47:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:47:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:47:15 INFO - 'HOME': '/Users/cltbld', 10:47:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:47:15 INFO - 'LOGNAME': 'cltbld', 10:47:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:47:15 INFO - 'MOZ_NO_REMOTE': '1', 10:47:15 INFO - 'NO_EM_RESTART': '1', 10:47:15 INFO - 'PAGER': '/bin/cat', 10:47:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:47:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:47:15 INFO - 'PWD': '/builds/slave/test', 10:47:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:47:15 INFO - 'SHELL': '/bin/bash', 10:47:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.u3Qo9TppPN/Listeners', 10:47:15 INFO - 'TMPDIR': '/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/', 10:47:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:47:15 INFO - 'USER': 'cltbld', 10:47:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:47:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:47:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:47:15 INFO - 'XPC_FLAGS': '0x0', 10:47:15 INFO - 'XPC_SERVICE_NAME': '0', 10:47:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:47:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:47:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:47:16 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-AAO_t8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:47:16 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)) 10:47:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:47:16 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-0DfhyY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:47:16 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)) 10:47:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:47:16 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-ay64rR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:47:16 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)) 10:47:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:47:16 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-nTt7Yw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:47:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.51 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 10:47:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:47:16 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-9yYtDo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:47:16 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)) 10:47:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:47:16 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-Rgs_b0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:47:16 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)) 10:47:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:47:16 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-fozeFf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:47:16 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)) 10:47:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:47:16 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-9bVH0e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:47:16 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)) 10:47:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:47:16 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-MnHXWC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:47:17 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)) 10:47:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:47:17 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-MtsZDc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:47:17 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)) 10:47:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:47:17 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-hURaPY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:47:17 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)) 10:47:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:47:17 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-3dnXO4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:47:17 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)) 10:47:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:47:17 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-wX23Nj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:47:17 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)) 10:47:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:47:17 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-KUw1_r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:47:17 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)) 10:47:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:47:17 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-UuP7U2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:47:17 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)) 10:47:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:47:17 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-peK92k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:47:17 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)) 10:47:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:47:17 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-P75Fsj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:47:17 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)) 10:47:17 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)) 10:47:17 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)) 10:47:17 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)) 10:47:17 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 10:47:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 10:47:17 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 10:47:17 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 10:47:17 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 10:47:17 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 10:47:17 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 10:47:19 INFO - Downloading blessings-1.6.tar.gz 10:47:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 10:47:19 INFO - Installing collected packages: blessings 10:47:19 INFO - Running setup.py install for blessings 10:47:19 INFO - Successfully installed blessings 10:47:19 INFO - Cleaning up... 10:47:19 INFO - Return code: 0 10:47:19 INFO - Done creating virtualenv /builds/slave/test/build/venv. 10:47:19 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:47:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:47:20 INFO - Reading from file tmpfile_stdout 10:47:20 INFO - Current package versions: 10:47:20 INFO - blessings == 1.6 10:47:20 INFO - blobuploader == 1.2.4 10:47:20 INFO - docopt == 0.6.1 10:47:20 INFO - functools32 == 3.2.3-2 10:47:20 INFO - jsonschema == 2.5.1 10:47:20 INFO - manifestparser == 1.1 10:47:20 INFO - mozInstall == 1.12 10:47:20 INFO - mozcrash == 1.0 10:47:20 INFO - mozdebug == 0.1 10:47:20 INFO - mozdevice == 0.51 10:47:20 INFO - mozfile == 1.2 10:47:20 INFO - mozhttpd == 0.7 10:47:20 INFO - mozinfo == 0.9 10:47:20 INFO - mozleak == 0.1 10:47:20 INFO - mozlog == 3.4 10:47:20 INFO - moznetwork == 0.27 10:47:20 INFO - mozprocess == 0.25 10:47:20 INFO - mozprofile == 0.28 10:47:20 INFO - mozrunner == 6.13 10:47:20 INFO - mozscreenshot == 0.1 10:47:20 INFO - mozsystemmonitor == 0.3 10:47:20 INFO - moztest == 0.8 10:47:20 INFO - mozversion == 1.4 10:47:20 INFO - psutil == 3.1.1 10:47:20 INFO - requests == 1.2.3 10:47:20 INFO - wsgiref == 0.1.2 10:47:20 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:47:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:47:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:47:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:47:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:47:20 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 0x10b971c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b975768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa8d84b1440>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b8fac60>, '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 0x10b68fcd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b6b8be8>, '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.chpjD09hH9/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.u3Qo9TppPN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/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 10:47:20 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 10:47:20 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 10:47:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.chpjD09hH9/Render', 10:47:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:47:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:47:20 INFO - 'HOME': '/Users/cltbld', 10:47:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:47:20 INFO - 'LOGNAME': 'cltbld', 10:47:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:47:20 INFO - 'MOZ_NO_REMOTE': '1', 10:47:20 INFO - 'NO_EM_RESTART': '1', 10:47:20 INFO - 'PAGER': '/bin/cat', 10:47:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:47:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:47:20 INFO - 'PWD': '/builds/slave/test', 10:47:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:47:20 INFO - 'SHELL': '/bin/bash', 10:47:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.u3Qo9TppPN/Listeners', 10:47:20 INFO - 'TMPDIR': '/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/', 10:47:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:47:20 INFO - 'USER': 'cltbld', 10:47:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:47:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:47:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:47:20 INFO - 'XPC_FLAGS': '0x0', 10:47:20 INFO - 'XPC_SERVICE_NAME': '0', 10:47:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:47:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:47:20 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)) 10:47:20 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)) 10:47:20 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)) 10:47:20 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)) 10:47:20 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)) 10:47:20 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)) 10:47:20 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)) 10:47:20 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)) 10:47:20 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)) 10:47:20 INFO - Downloading/unpacking marionette-driver>=1.4.0 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 10:47:20 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 10:47:20 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 10:47:20 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 10:47:20 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 10:47:21 INFO - Downloading marionette_driver-1.4.0.tar.gz 10:47:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/marionette-driver/setup.py) egg_info for package marionette-driver 10:47:21 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)) 10:47:21 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)) 10:47:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner>=6.9 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from marionette-driver>=1.4.0->-r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 10:47:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice>=0.37 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozrunner>=6.9->marionette-driver>=1.4.0->-r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 10:47:21 INFO - Installing collected packages: marionette-driver 10:47:21 INFO - Running setup.py install for marionette-driver 10:47:21 INFO - Successfully installed marionette-driver 10:47:21 INFO - Cleaning up... 10:47:22 INFO - Return code: 0 10:47:22 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 10:47:22 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')]} 10:47:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:47:22 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')]} 10:47:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:47:22 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 0x10b971c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b975768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa8d84b1440>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b8fac60>, '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 0x10b68fcd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b6b8be8>, '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.chpjD09hH9/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.u3Qo9TppPN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/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 10:47:22 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 10:47:22 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 10:47:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.chpjD09hH9/Render', 10:47:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:47:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:47:22 INFO - 'HOME': '/Users/cltbld', 10:47:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:47:22 INFO - 'LOGNAME': 'cltbld', 10:47:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:47:22 INFO - 'MOZ_NO_REMOTE': '1', 10:47:22 INFO - 'NO_EM_RESTART': '1', 10:47:22 INFO - 'PAGER': '/bin/cat', 10:47:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:47:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:47:22 INFO - 'PWD': '/builds/slave/test', 10:47:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:47:22 INFO - 'SHELL': '/bin/bash', 10:47:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.u3Qo9TppPN/Listeners', 10:47:22 INFO - 'TMPDIR': '/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/', 10:47:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:47:22 INFO - 'USER': 'cltbld', 10:47:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:47:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:47:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:47:22 INFO - 'XPC_FLAGS': '0x0', 10:47:22 INFO - 'XPC_SERVICE_NAME': '0', 10:47:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:47:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:47:22 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 10:47:22 INFO - Cleaning up... 10:47:22 INFO - Return code: 0 10:47:22 INFO - Running post-action listener: _resource_record_post_action 10:47:22 INFO - Running post-action listener: _start_resource_monitoring 10:47:22 INFO - Starting resource monitoring. 10:47:22 INFO - [mozharness: 2017-06-01 17:47:22.328848Z] Finished create-virtualenv step (success) 10:47:22 INFO - [mozharness: 2017-06-01 17:47:22.329799Z] Running install step. 10:47:22 INFO - Running pre-action listener: _resource_record_pre_action 10:47:22 INFO - Running main action method: install 10:47:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:47:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:47:22 INFO - Reading from file tmpfile_stdout 10:47:22 INFO - Detecting whether we're running mozinstall >=1.0... 10:47:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 10:47:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 10:47:22 INFO - Reading from file tmpfile_stdout 10:47:22 INFO - Output received: 10:47:22 INFO - Usage: mozinstall [options] installer 10:47:22 INFO - Options: 10:47:22 INFO - -h, --help show this help message and exit 10:47:22 INFO - -d DEST, --destination=DEST 10:47:22 INFO - Directory to install application into. [default: 10:47:22 INFO - "/builds/slave/test"] 10:47:22 INFO - --app=APP Application being installed. [default: firefox] 10:47:22 INFO - mkdir: /builds/slave/test/build/application 10:47:22 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'] 10:47:22 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 10:47:44 INFO - Reading from file tmpfile_stdout 10:47:44 INFO - Output received: 10:47:44 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 10:47:44 INFO - Running post-action listener: _resource_record_post_action 10:47:44 INFO - [mozharness: 2017-06-01 17:47:44.468499Z] Finished install step (success) 10:47:44 INFO - [mozharness: 2017-06-01 17:47:44.468650Z] Running setup-mitmproxy step. 10:47:44 INFO - Running pre-action listener: _resource_record_pre_action 10:47:44 INFO - Running main action method: setup_mitmproxy 10:47:44 INFO - Skipping: mitmproxy is not required 10:47:44 INFO - Running post-action listener: _resource_record_post_action 10:47:44 INFO - [mozharness: 2017-06-01 17:47:44.469263Z] Finished setup-mitmproxy step (success) 10:47:44 INFO - [mozharness: 2017-06-01 17:47:44.469409Z] Running run-tests step. 10:47:44 INFO - Running pre-action listener: _resource_record_pre_action 10:47:44 INFO - Running main action method: run_tests 10:47:44 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 10:47:44 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 10:47:44 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 10:47:44 INFO - 2017-06-01 10:47:44.484 screenresolution[1623:7302] starting screenresolution argv=screenresolution get 10:47:44 INFO - 2017-06-01 10:47:44.486 screenresolution[1623:7302] Display 0: 1600x1200x32@60 10:47:44 INFO - 2017-06-01 10:47:44.492 screenresolution[1624:7304] starting screenresolution argv=screenresolution list 10:47:44 INFO - Available Modes on Display 0 10:47:44 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 10:47:44 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 10:47:44 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 10:47:44 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 10:47:44 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 10:47:44 INFO - Intel Iris: 10:47:44 INFO - Chipset Model: Intel Iris 10:47:44 INFO - Type: GPU 10:47:44 INFO - Bus: Built-In 10:47:44 INFO - VRAM (Dynamic, Max): 1536 MB 10:47:44 INFO - Vendor: Intel (0x8086) 10:47:44 INFO - Device ID: 0x0a2e 10:47:44 INFO - Revision ID: 0x0009 10:47:44 INFO - Displays: 10:47:44 INFO - Display: 10:47:44 INFO - Resolution: 1600 x 1200 @ 60 Hz 10:47:44 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 10:47:44 INFO - Display Serial Number: 1600x1200 60 10:47:44 INFO - Main Display: Yes 10:47:44 INFO - Mirror: Off 10:47:44 INFO - Online: Yes 10:47:44 INFO - Rotation: Supported 10:47:44 INFO - Return code: 0 10:47:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 10:47:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 10:47:44 INFO - Python 2.7.3 10:47:44 INFO - Return code: 0 10:47:44 INFO - grabbing minidump binary from tooltool 10:47:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:47:44 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 0x10b8fac60>, '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 0x10b68fcd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b6b8be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 10:47:44 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 10:47:44 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 10:47:44 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 10:47:44 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 10:47:44 INFO - Return code: 0 10:47:44 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 10:47:44 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 10:47:44 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 10:47:44 INFO - ENV: RUST_BACKTRACE is now 1 10:47:44 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 10:47:44 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 10:47:44 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/alTWZOyQSKKYdui0xaoH4w/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0276', '--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 10:47:44 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/alTWZOyQSKKYdui0xaoH4w/artifacts/public/build/target.crashreporter-symbols.zip --title t-yosemite-r7-0276 --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 10:47:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.chpjD09hH9/Render', 10:47:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:47:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:47:44 INFO - 'HOME': '/Users/cltbld', 10:47:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:47:44 INFO - 'LOGNAME': 'cltbld', 10:47:44 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 10:47:44 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 10:47:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:47:44 INFO - 'MOZ_NO_REMOTE': '1', 10:47:44 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 10:47:44 INFO - 'NO_EM_RESTART': '1', 10:47:44 INFO - 'PAGER': '/bin/cat', 10:47:44 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:47:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:47:44 INFO - 'PWD': '/builds/slave/test', 10:47:44 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 10:47:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:47:44 INFO - 'RUST_BACKTRACE': '1', 10:47:44 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 10:47:44 INFO - 'SHELL': '/bin/bash', 10:47:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.u3Qo9TppPN/Listeners', 10:47:44 INFO - 'TMPDIR': '/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/', 10:47:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:47:44 INFO - 'USER': 'cltbld', 10:47:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:47:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:47:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:47:44 INFO - 'XPC_FLAGS': '0x0', 10:47:44 INFO - 'XPC_SERVICE_NAME': '0', 10:47:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:47:44 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/alTWZOyQSKKYdui0xaoH4w/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0276', '--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 10:47:45 INFO - mozversion application_buildid: 20170601082524 10:47:45 INFO - mozversion application_changeset: 96b243f2267710b81f5908c7de98fa046c715610 10:47:45 INFO - mozversion application_display_name: Nightly 10:47:45 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 10:47:45 INFO - mozversion application_name: Firefox 10:47:45 INFO - mozversion application_remotingname: firefox 10:47:45 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 10:47:45 INFO - mozversion application_vendor: Mozilla 10:47:45 INFO - mozversion application_version: 55.0a1 10:47:45 INFO - mozversion platform_buildid: 20170601082524 10:47:45 INFO - mozversion platform_changeset: 96b243f2267710b81f5908c7de98fa046c715610 10:47:45 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 10:47:45 INFO - mozversion platform_version: 55.0a1 10:47:45 INFO - using testdate: 1496339265 10:47:45 INFO - actual date: 1496339265 10:47:45 INFO - starting webserver on 'localhost:49222' 10:47:45 INFO - SUITE-START | Running 2 tests 10:47:45 INFO - TEST-START | dromaeo_css 10:47:45 INFO - operating with platform_type : mac_ 10:47:45 INFO - Initialising browser for dromaeo_css test... 10:47:45 INFO - Application command: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49222/getInfo.html -foreground -profile /var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/tmp46SwIF/profile 10:47:45 INFO - TEST-INFO | started process 1634 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49222/getInfo.html -foreground) 10:48:18 INFO - TEST-INFO | 1634: killed by SIGTERM 10:48:18 INFO - Browser initialized. 10:48:18 INFO - Running cycle 1/1 for dromaeo_css test... 10:48:18 INFO - TEST-INFO | started process 1643 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/tmp46SwIF/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:48:20 INFO - PID 1643 | 2017-06-01 10:48:20.833 plugin-container[1644:7858] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x983f, name = 'com.apple.tsm.portname' 10:48:20 INFO - PID 1643 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:48:20 INFO - PID 1643 | 2017-06-01 10:48:20.834 plugin-container[1644:7858] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9f0f, name = 'com.apple.CFPasteboardClient' 10:48:20 INFO - PID 1643 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:48:20 INFO - PID 1643 | 2017-06-01 10:48:20.834 plugin-container[1644:7858] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 10:48:21 INFO - PID 1643 | 2017-06-01 10:48:21.500 plugin-container[1645:7905] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x454b, name = 'com.apple.tsm.portname' 10:48:21 INFO - PID 1643 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:48:21 INFO - PID 1643 | 2017-06-01 10:48:21.501 plugin-container[1645:7905] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4917, name = 'com.apple.CFPasteboardClient' 10:48:21 INFO - PID 1643 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:48:21 INFO - PID 1643 | 2017-06-01 10:48:21.501 plugin-container[1645:7905] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 10:48:30 INFO - PID 1643 | RSS: Main: 259706880 10:48:30 INFO - PID 1643 | 10:51:33 INFO - PID 1643 | Cycle 1(1): loaded http://localhost:49222/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49222/tests/dromaeo/cssquery-ext.html) 10:51:34 INFO - PID 1643 | RSS: Main: 270204928 10:51:34 INFO - PID 1643 | 10:54:36 INFO - PID 1643 | Cycle 1(1): loaded http://localhost:49222/tests/dromaeo/cssquery-ext.html (next: http://localhost:49222/tests/dromaeo/cssquery-jquery.html) 10:54:37 INFO - PID 1643 | RSS: Main: 282869760 10:54:37 INFO - PID 1643 | 10:57:39 INFO - PID 1643 | Cycle 1(1): loaded http://localhost:49222/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49222/tests/dromaeo/cssquery-mootools.html) 10:57:39 INFO - PID 1643 | RSS: Main: 283172864 10:57:39 INFO - PID 1643 | 11:00:41 INFO - PID 1643 | Cycle 1(1): loaded http://localhost:49222/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49222/tests/dromaeo/cssquery-prototype.html) 11:00:42 INFO - PID 1643 | RSS: Main: 293191680 11:00:42 INFO - PID 1643 | 11:03:45 INFO - PID 1643 | Cycle 1(1): loaded http://localhost:49222/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49222/tests/dromaeo/cssquery-yui.html) 11:03:45 INFO - PID 1643 | RSS: Main: 293208064 11:03:45 INFO - PID 1643 | 11:06:47 INFO - PID 1643 | Cycle 1(1): loaded http://localhost:49222/tests/dromaeo/cssquery-yui.html (next: http://localhost:49222/tests/dromaeo/cssquery-dojo.html) 11:06:47 INFO - PID 1643 | RSS: Main: 293146624 11:06:47 INFO - PID 1643 | 11:06:47 INFO - PID 1643 | __start_tp_report 11:06:47 INFO - PID 1643 | _x_x_mozilla_page_load 11:06:47 INFO - PID 1643 | _x_x_mozilla_page_load_details 11:06:47 INFO - PID 1643 | |i|pagename|runs| 11:06:47 INFO - PID 1643 | |0;dojo.html;3564.572251329839;3576.0880975351283;3611.422172452408;3625.655562721544;3627.474016267641;5173.793048278064;5211.218317252409;5963.194968679232;6134.344744984532;6227;6146.139540464339;6168.722407491662;6198.256209254896;6206.813795586139;6300.653464059464;4145.647619952308;5293.444188360225;5375.7580908859;5458.099413596752;5465.562754979612;5536.667799931997;5538.335399752035;5570.944290557083;5586.078297080977;5586.8603284918;5363.410024897258;5412.783488660449;5444.972775136139;5469.179623056553;5491.697956612385;5476.3702174250075;5484.91772623411;5502.0921547944545;5504.587155963319;5548.72256387179;4204.810783514749;5396.379416367089;5461.890762184774;5488.506034456878;5522.392536820987;6125.937554508974;6155.384461553854;6203.534734894856;6248.250209974832;6282.748690052393;6569.310222426624;6677.399034086907;6681.064650948871;6682.264950855402;6690.230623478314;6699.330066993261;6782.796516104525;6789.287124851869;6790.5925644461495;6792.388685018323;6184.783532576338;6292.842678933063;6297.748090076391;6314.400131987454;6334.144890439778;6784.898226526606;6849.417799487;6906.654667266616;6934.757283495154;6949.096617439701;6237.906431403532;6413.198350206224;6430.3891130342545;6534;6586.8353291168105;6665.633390163587;6773.762918297836;6774.153230846145;6783;6794.796156115324;6605.801825945229;6625.801225963229;6631.535792494479;6655.367740064686;6672.0325552794575;6399.744010239631;6440.162778838721;6557.213134423804;6598.934010659928;6663.933360666429;5306.442823503554;5311.070562651521;5313.123334649739;5339.092354299701;5361.195820626859;4696.929546056812;4704.623630109583;4719.339292499053;4751.619870410359;4762.190427627312;4941.25881117828;6106.430331452215;6282.937170628235;6309.179806625201;6341.587796793194;5963.880722385528;6797.388235058868;6818.420434263143;6823.590584564942;6834.1457317835275;6287.96856015717;6294.276158241769;6333.050042493571;6333.20834895638;6335.68321583919;5757.136429535603;5820.82537523875;5865.530757539386;5943.494802941712;5972.40275972408;6928.757493487704;6935.375816176568;6935.514513984073;6947.930520694728;6956.165260168812;6798.8640227196165;6806.115205023315;6810.31896810315;6813.318668133345;6834.794956151323;6730.764423245163;6863.450923926173;6945.166580010229;6965.7561985332295;6982.162140543167;6448.709808058555;6582.11141495911;6632;6662.601463473243;6680.933190668031;6770.221424536241;6799.660016999229;6799.728010879509;6814.386705196358;6821.89767153503;6176.752929882934;6377.681115944091;6478.773242936474;6495.0906873036865;6571.80284591463;6529.281779004211;6592.0111983202905;6593.044008618819;6595.274519802721;6609.900851487136;6319.052142178526;6419.293877673359;6428.774992875227;6433.099366088845;6450.3549645037;6335.3981371769605;6422.325655806073;6446.065320528591;6567.638779867153;6577.901331480128;6381.138546296191;6382.680865956777;6385.584936979081;6403.359664033559;6421.389967953037 11:06:47 INFO - PID 1643 | |1;ext.html;784.5253621558957;820.9014918209815;846.953417562034;1250.224860586436;1327.482281910056;38406;39373.80313098438;39440;39533;39561.40657890134;39549.8022509887;39633.8109856704;39904.40143397851;39905.800470997674;39934;7767.106782720003;7927.762167134966;8162.510249385056;8427.241548260656;8433.789155271137;8230.670773169066;8249.175082491764;8326.875096873551;8328.916710832875;8359.916400836006;8266.751997440086;8351;8365.330773538133;8373.371997100241;8373.958130209341;8244.8351032979;8282.1717828217;8311.501309921394;8340.791480212982;8344.582770861432;8147.796305092361;8197;8247.95876020622;8255.628496717662;8261.504309741435;36776.632233677585;36933.26133477315;37029.07427314312;37145.62854371449;37306.626933730586;3619.855205791765;3657.140571965586;3661.8169091545583;3666.0651533858963;3667.523221981152;30313.69686303131;30347.544786828214;30433.391332173454;30463.390732185453;30506;3026.4854974654363;3032.9090127296217;3068.4964367460007;3076.7538596912195;3086.3981523602906;234271;237665;262484.6875765628;287148;288323;46055.5394446058;46102.30846537305;46408;46528.30207546889;46535;49811.75094124506;49913.50086499161;50088.499115009115;54089.72955135278;54586.08948686777;14451.927740361441;14576.198309093103;14587.854121458862;14590.051646643147;14594.124352538882;11463.828042579367;11585.420728963518;11672.774358692384;11681.182317237712;11705.356205408785;11281;11550.59572914944;13952.86047139536;14107.929460352632;14117.223552704494;6203.565750397519;6220.875582488325;6259.1550140730415;6259.96870015647;6266.686665666698;985.5614251657944;1006.6677996261217;1013.9036791504795;1056.3609016544879;1070.9678709638567;18189.636207276046;24344.26967190987;24735.62896556517;24751.25746227616;24770;1070.9023251167616;1076.6554702495127;1115.9051480624075;1139.3505701749923;1173.23153334566;12582.496700132075;12764.978801695843;12772.361381930867;12773.10588258813;12795.488180472677;318807;336986.63013370044;337187;337725.6227437743;338748;49147.77130571693;49340.25989610159;49518.50481495211;54188.187177192376;54595;1509.6075020494532;1533.7699345098324;1568.3256199833952;1573.8662213711962;1580.194101008494;18466.630667386846;24170.274891753277;24340.878295608407;24437.38906527351;24545.263642090766;38386.80806595949;38418.42372364361;38467.845964621105;38479.23041539209;38483.80758096304;273318;274332.25667743065;275030;275260;275539;29302;35605.465918010734;35623.64376356307;35878.74424395238;35992.820035899655;42698;44260.11479770451;46073.30890036585;46130.76934615306;46222.7688861567;29455.410891782474;30460;30640;31499.52750708787;31786.52320215245;29342.853285733436;30253.48732563407;30456.847715762167;31257.84371078221;31703.682963170995;36408.45387319139;37343;37694.64024338425;38088.238235235694;38206.61793382031;30399.848000759855;31253.218669533442;31473;31556.684433155373;32264.193395165308;22234.332970010924;22434.214802481834;24298.028078876647;24389.878050610343;24627.753722462545 11:06:47 INFO - PID 1643 | |2;jquery.html;422.8646833013435;424.92563801334757;437.306868613248;444.45110288793353;451.8170141092857;21181.576368472623;21999.670004949938;22100.22649207278;22125.446863828412;22156.667649985222;23478.530429391445;24440.333949907523;24585.631215531783;24749.62875556868;24752.75247247527;6091.878162436748;6217.875642487147;6244.93836047873;6253.405926437004;6274.654893980829;6106.877862442749;6148.723307451153;6203.720832562545;6208.068789681539;6219.284782250032;6077.817665470021;6104.603200791957;6116.08258761185;6157.7229024694;6388.744450221986;6708.262091169968;6825.829354266133;6827.419669328113;6827.556208846409;6832.897506537406;6649.035889796;6675.299093595199;6681.732730690766;6682.231543372477;6714.362135597111;29136;29161;30251.394972100654;30747.538786918216;30783.538246926313;2241;2291.873946932918;2300.378897697629;2301.7007788987494;2312.97687023131;19440.70838937417;20019.39941801748;20039.79960200394;20089;20094.39716808498;2258.6837842702034;2266.5013696986634;2311.872846993415;2317.4901521665206;2360.8701521416315;164944.17527912284;166627;173815;193721;197725.0227497721;24522.509549808903;24873.253802385956;25656.74343256581;25941.22176334713;26170.6074408884;34678.82660586681;42893.57106428896;42990.35514467285;42992;43012;11288.0969522438;11357.204995650225;11407;11431.59989400367;11433;9033.954830225939;9063.54682265884;9073.909260907307;9127.726168214964;9151.908480915106;10163.94918025405;12023.27860328383;12206.633800985806;12226.20529665569;12318;4388.88083538702;4641.2109941310055;4783.234682450791;4801.68789028712;4921.114199444134;906.2885634776669;935.2517985611483;969.1519920069939;981.6760469045198;1010.1514727628827;21500.354989350344;22151.113955441924;22217;22306.330810075724;22606.773932260796;891.41612243979;977.5210147027905;1014.4471263161595;1022.3303736052898;1056.6671498477954;11050.392228427514;11329.923657252548;11347.659570212907;11358.829617555743;11374.601888933848;574712;581102;581231;586903;588155.0592247098;151451;153907.46092539156;155329;156674.2166289184;158382;1166.6383421139474;1176.5411489519095;1243.2602601452122;1256.9685757856128;1262.1291308996763;22212.88893555522;22720;23358.88320558454;23535.646965295877;23556.175533856233;65312;67569.3243067563;67711.64576708537;68048;69107.30892691009;91085.25775309173;91487;91654.08345916455;92231.53884230535;94878;10355.585776569154;10383.428911409945;10502.94748526283;10510.579576816841;10513.632022879163;18028.36900708469;18158.364457243933;18209.81636193643;18224;18375.816241837412;14729.852701473277;14795;14898.255087245378;14917.955743097878;14931.701365972836;12701.60282368957;13657.658558536115;13741.381637826522;13846;13869.583912482642;12946.482140714266;14162.858371416567;15445.613859653598;15496.605305522557;15712.685746285239;9128.77178070527;9965.601375944882;10154.136898363722;10354.637587684394;10407.531661075118;14499.710005799614;14608.415663373346;14681.70636587241;14684.265786710408;14705.852941470876 11:06:47 INFO - PID 1643 | |3;mootools.html;1294.9093563450556;1448.3844366144385;1455.366915391804;1456.3009755317448;1457.6720237946463;10821.783564328709;11047.563816703818;11678.766424671512;11890.048796096313;12168.904798568126;11683.481147450819;11750.471228794704;11791.587294444696;11871.643850684472;11888.643340699771;2188.190369563256;2552.1705445730117;2552.6936767587918;2552.9489410211786;2553.668023156987;2474.2206205045354;2522.2433270019014;2531.620256961461;2546.2106746908426;2550.311415917701;2142.700021996922;2507.849529028264;2532.177042461198;2535.150724507292;2539.415934335104;2486.950260994779;2502.299356180263;2507.135038411754;2507.636392723045;2514.7736703696573;2421.5156968606348;2484.788792952583;2498.7001559812757;2508.6613307203475;2542.4533725248975;11586.304821710724;11676.1826672133;11692.883071169264;11708.297502149813;11722.238054526428;6391.296957334689;6578.8026359208825;6622.569532980389;6628.43658289046;6638.80083597488;3455.585329760445;4227.154569086164;4244.108737165199;4338.045629961403;4365.712114926073;1210.6126039667222;1220.5666988219143;1244.6514975806788;1249.2816630437535;1255.3723138430785;15086.24568771568;15499.612509687235;16328.75506867398;16403.58991025222;16479.258433370527;5082.263071854561;5566.471185237396;5666.518345940642;5699.230603868507;5738.512226460714;11980.460879260454;12045.879541204651;12060.095492837896;12074.396280185956;12101;5712.171735098471;6490.513211509061;6521.315261897433;6534.803955881332;6539.08452816606;5183;6025.548083893638;6054.666993315322;6109.877802443926;6201.162843016225;4316.482022157361;4365.236083685342;4474.597286244254;4500.977495112569;4536.820426689084;1270.866559011309;1285.042643230799;1292;1294.9935250323688;1307.1960744142284;736.4108713029663;751.7181057103586;773.6402572803673;806.3065763443434;813.6777736178709;6098.72555734993;6146.279041868284;6424.74301027963;6659.367360100783;7112.644367781693;897.1297841094129;937.1378331934636;960.2078285414561;961.7451375385529;983.9803203935882;4324.589164029475;4396.516383197845;4432.955670443319;4569.246074397754;4592.931106033412;14674.853251467563;14895.329710163069;15023.924880375529;15069.09585424878;15135;12280.815787763191;15059.623509412353;15364.00133991287;15367.538973830578;15372.385104595915;8142.267195952392;8466.872996905051;8575.228229459379;8586.93913460765;8596.742097737078;6332.778352757631;6878.896816547655;7095.432365410651;7117.75087871922;7196.748113815991;12867.613971580868;12935.353232338157;12966.935165324114;13153.934230328787;13204.933975330061;13091.214527128403;13284.800727988895;13306.201627902357;13317.866821331663;13481.460741570227;9566.712998610054;9602.66390676323;9665.71002869915;9704.272179586418;9773.657921972697;10724.624638137628;10829.51267192994;10842.241043126905;10849.403282819521;10867.076298514714;9103.180713735796;9192.172704456363;9264.490453025148;9416.152546270594;9484.762880928032;8964.506952117696;8995.955020224857;9002.099790020946;9089.272858171462;9146.085391461067;7179.210286868336;8117.959410202911;8219.054808696837;8290.046644635944;8453.28147107478;5756.539476841759;5980.461758441761;6114.48026917717;6198;6290.182276304051;5629.88740225185;5685.886282274414;5736.913946290725;5826.621269617462;5903.675297858659 11:06:47 INFO - PID 1643 | |4;prototype.html;352.1706381471634;378.47581100176257;398.6173273657289;399.68824317032727;412.0399469236678;4775.403074615673;4975.004999000196;5628.409017053206;6052.57631965762;6326.525510586707;5665.716714164286;5845.567835880215;6126.172966649512;6228.314885362606;6304.5955184200475;4438.001449673822;4450.399196108532;4633.212353899845;4640.46634637016;4667.486576476585;3840.7503512271614;3847.615238476158;4625.8149674013;4687.343771871941;4690.179218636742;3965.691321863778;4595.954040459586;4596.724196548218;4629.953700463003;4639.86080417588;4544.590986811187;4613.054323863616;4634.2353511670535;4641.489436162019;4691.859244222679;3335.8999230023137;3983.521977362706;3984.6613037891525;3990.900227494307;4006.9782205537404;5422.864428389283;6282.780102696383;6284.402981716729;6292.402221788922;6298.842528936768;4576.931346029813;5453.563714902798;5507.642003269774;5519.530839878616;5531.700050488132;5050.6212034097225;6110.419510146529;6152.477039451652;6548.967255163741;6765.188177418741;3969.3450580654157;4141.5237247716295;4296.947247924248;4519.706219095749;4529.116822219668;5913;6307.369263073701;6328.367163283635;7126.144862616467;7245.094363204599;4083.5916408358926;4309.913801723948;4347.804348804311;4546.931796023062;4615.14619795339;4646.303054541846;4672.345871577982;4685.929711054337;4690.273007683814;4715.3869996900185;5050.84847454577;5344.866378340573;5466.835994920159;5473.343198816167;5494.09347457665;6763.7632682855865;6778.288279730656;6834.726610935606;6844.691988860513;6849.589024658537;1478.5194811686245;1774.5829730013436;1774.943908374515;1777.520069581206;1778.941529789773;1035.985009157182;1178.3283528388736;1199.9340036298088;1246.389269258057;1246.5823948977195;5503.449655034545;6347.809565713036;6380.968095159587;6408.084321633645;6528.67356632176;5632.127020311858;5958.990709691578;5966.224390829165;6952.374286314256;7065.57606543609;5609.607327487037;6358.618482890949;6379.298277189506;6388.584741991848;6393.840153996187;3234.029791062672;3465.5841299044278;3661.6521430464063;3687.4284485904727;3709.221063576679;7065.010898474217;7244.855102897913;7247.492675512769;7257.237990011078;7263.164736055315;6065.878682426327;6455.419012288917;7170.282971702893;7185.712571497186;7199.532030418006;6459.449732064365;6540.771073012422;7244.492885497964;7276.745313913988;7314.707411703579;5675.829725108253;6166.630002199882;6719.731210751711;7041.788746337618;7043.154821421463;6910.585364878123;6913.609857740271;6940.548864323803;6945.895811562729;6950.687219075255;6626.768063117768;7174.784756457315;7190.460715446257;7240.384567311838;7242.239564845826;3878.7672739635714;4255.446791917031;4581.702189357681;4618.237990731492;4618.584327410549;6076.574639775174;6496.675166241764;6709.664516774239;7089.751858684921;7185.892211616934;6647;6705.865882682417;6714.798556043326;6736.865262694817;6737.395493411002;6668.699908504226;6689.431398331197;6723.4285085766305;6745.5952642841585;6753.932460675527;5507.972460137674;5667.489925906523;6437.806865794034;6598.241202261801;6599.208095028627;6718.193816742022;6721.93278067213;6726.553790934972;6745.561538499982;6759.425448836904;6484.351564843667;6547.672616369067;6585.176852893388;6591.571547849375;6594.967025164843 11:06:47 INFO - PID 1643 | |5;yui.html;842.7303262955853;849.5285116760195;852.936029797765;881.4006475596595;889.0664801957945;14639.853601463983;15200.239988000583;16824.579385515353;16855.241514131863;17148.74276885846;16133.919330403362;17969.91015044926;18118.275268989226;18186.636267274647;18208.271669133253;959.9616015359395;968.8837339519267;970.4468452981803;971.4899677669245;972.6936015155203;649.6589290622437;825.1913125137339;984.9606015759362;987.1609132237612;995.0397866059251;589.7051474262869;996.227923359395;1029.171516928872;1030.8969103089705;1033.338663255517;1026.5072765072807;1027.604372316656;1029.3000759483548;1029.4904022508906;1032.5095579599706;763.6449051191167;764.7399884039397;764.793505753449;766.7239793674273;769.3037800790294;15883.047017178891;15891.284892179765;16822.915885420494;16969.57576060596;17296.481105566854;17071.23179456915;17109.486715398558;17227.310907563682;17371.30514779407;17543.473695789147;14743.77884331736;14760.26198690072;14846.70306593873;14888.62778430537;14947;3263.2005158736024;3310.2882880180878;3397.779144355609;3474.652534746505;3481.495183198447;120379;140156;148035.25982370126;148583.25708371497;149096;12141.271523708605;12158.635240942785;12214.206076604993;12368.44342004612;12424.627261182179;17669.38157164493;18123.637527249382;20592.27927022547;21182.576348473252;21260;6411.294757576756;6540.05169250456;6725.361090696376;7153.320434558708;7378.74174403904;3828.1769419574925;3882;3905.726599138033;3906.7069969752383;3910.7262491625315;4132.2561938851295;4206.927233555424;4212.251915455052;4214.473190851144;4639.536046395401;956.7464621875208;965.7151140413595;982.111189373618;982.7297493189287;983.8130755156498;644.7324360390479;662.8210383196509;670.0485310858593;676.4149011105359;681.9872489353306;11472.139589530818;11723.062155027577;12152.513899443924;12252.387380631111;12395.256284622952;644.2719726708789;651.5341530805402;654.4339146638124;669.7856685860478;684.1516519515765;5582.441755824385;5599.216109744639;5822.417758224144;5887.322957859816;5927.881442371128;149726.25136874247;149744.25127874292;150110.748338775;151414;153346;126643;127294.72705272828;127958.24187911567;128840;132816;103857.48071259595;105274;106191.4690426543;106633;106801;10422.947885260524;11122.944385278022;11600.477978491157;11644.2431241969;11813.76372472563;46579;46811.5101583745;47582;47624;48160.27759583679;62257.37742622516;62397;62852.057219140836;63438.92488600993;63871.6806415965;14510.492132775302;14610.780838287646;14928.402863885323;14997.250137492863;15013.249337533298;15653.530394088195;16125.032498050154;16310.429134980694;16356.18219089017;16397.91801040987;14872.925635371754;15070.061338038977;15301.08193508393;15370.23148842531;15379.38482460689;13689.589312320646;14963.925180374028;16461.67076658484;17140.228689708743;17775.022373769567;12889.355532223539;14563.490277840649;15072.54782356531;15117.848821511645;15208.391664333303;11577.826332605186;11602.013828824645;12144.392780361124;12330.691732706395;12460.750784984431;11810.704732381759;13616.795748063589;13669.726605468033;14133.434662613792;14135.363908623931 11:06:47 INFO - PID 1643 | __end_tp_report 11:06:47 INFO - PID 1643 | __start_cc_report 11:06:47 INFO - PID 1643 | _x_x_mozilla_cycle_collect,451 11:06:47 INFO - PID 1643 | __end_cc_report 11:06:47 INFO - PID 1643 | __startTimestamp1496340407922__endTimestamp 11:06:47 INFO - PID 1643 | 11:06:47 INFO - PID 1643 | ------- Summary: start ------- 11:06:47 INFO - PID 1643 | Number of tests: 6 11:06:47 INFO - PID 1643 | 11:06:47 INFO - PID 1643 | [#0] dojo.html Cycles:165 Average:6145.56 Median:6382.68 stddev:759.22 (11.9%) stddev-sans-first:734.05 11:06:47 INFO - PID 1643 | Values: 3564.6 3576.1 3611.4 3625.7 3627.5 5173.8 5211.2 5963.2 6134.3 6227.0 6146.1 6168.7 6198.3 6206.8 6300.7 4145.6 5293.4 5375.8 5458.1 5465.6 5536.7 5538.3 5570.9 5586.1 5586.9 5363.4 5412.8 5445.0 5469.2 5491.7 5476.4 5484.9 5502.1 5504.6 5548.7 4204.8 5396.4 5461.9 5488.5 5522.4 6125.9 6155.4 6203.5 6248.3 6282.7 6569.3 6677.4 6681.1 6682.3 6690.2 6699.3 6782.8 6789.3 6790.6 6792.4 6184.8 6292.8 6297.7 6314.4 6334.1 6784.9 6849.4 6906.7 6934.8 6949.1 6237.9 6413.2 6430.4 6534.0 6586.8 6665.6 6773.8 6774.2 6783.0 6794.8 6605.8 6625.8 6631.5 6655.4 6672.0 6399.7 6440.2 6557.2 6598.9 6663.9 5306.4 5311.1 5313.1 5339.1 5361.2 4696.9 4704.6 4719.3 4751.6 4762.2 4941.3 6106.4 6282.9 6309.2 6341.6 5963.9 6797.4 6818.4 6823.6 6834.1 6288.0 6294.3 6333.1 6333.2 6335.7 5757.1 5820.8 5865.5 5943.5 5972.4 6928.8 6935.4 6935.5 6947.9 6956.2 6798.9 6806.1 6810.3 6813.3 6834.8 6730.8 6863.5 6945.2 6965.8 6982.2 6448.7 6582.1 6632.0 6662.6 6680.9 6770.2 6799.7 6799.7 6814.4 6821.9 6176.8 6377.7 6478.8 6495.1 6571.8 6529.3 6592.0 6593.0 6595.3 6609.9 6319.1 6419.3 6428.8 6433.1 6450.4 6335.4 6422.3 6446.1 6567.6 6577.9 6381.1 6382.7 6385.6 6403.4 6421.4 11:06:47 INFO - PID 1643 | 11:06:47 INFO - PID 1643 | [#1] ext.html Cycles:180 Average:44327.77 Median:338236.81 stddev:76598.46 (22.6%) stddev-sans-first:76743.57 11:06:47 INFO - PID 1643 | Values: 784.5 820.9 847.0 1250.2 1327.5 38406.0 39373.8 39440.0 39533.0 39561.4 39549.8 39633.8 39904.4 39905.8 39934.0 7767.1 7927.8 8162.5 8427.2 8433.8 8230.7 8249.2 8326.9 8328.9 8359.9 8266.8 8351.0 8365.3 8373.4 8374.0 8244.8 8282.2 8311.5 8340.8 8344.6 8147.8 8197.0 8248.0 8255.6 8261.5 36776.6 36933.3 37029.1 37145.6 37306.6 3619.9 3657.1 3661.8 3666.1 3667.5 30313.7 30347.5 30433.4 30463.4 30506.0 3026.5 3032.9 3068.5 3076.8 3086.4 234271.0 237665.0 262484.7 287148.0 288323.0 46055.5 46102.3 46408.0 46528.3 46535.0 49811.8 49913.5 50088.5 54089.7 54586.1 14451.9 14576.2 14587.9 14590.1 14594.1 11463.8 11585.4 11672.8 11681.2 11705.4 11281.0 11550.6 13952.9 14107.9 14117.2 6203.6 6220.9 6259.2 6260.0 6266.7 985.6 1006.7 1013.9 1056.4 1071.0 18189.6 24344.3 24735.6 24751.3 24770.0 1070.9 1076.7 1115.9 1139.4 1173.2 12582.5 12765.0 12772.4 12773.1 12795.5 318807.0 336986.6 337187.0 337725.6 338748.0 49147.8 49340.3 49518.5 54188.2 54595.0 1509.6 1533.8 1568.3 1573.9 1580.2 18466.6 24170.3 24340.9 24437.4 24545.3 38386.8 38418.4 38467.8 38479.2 38483.8 273318.0 274332.3 275030.0 275260.0 275539.0 29302.0 35605.5 35623.6 35878.7 35992.8 42698.0 44260.1 46073.3 46130.8 46222.8 29455.4 30460.0 30640.0 31499.5 31786.5 29342.9 30253.5 30456.8 31257.8 31703.7 36408.5 37343.0 37694.6 38088.2 38206.6 30399.8 31253.2 31473.0 31556.7 32264.2 22234.3 22434.2 24298.0 24389.9 24627.8 11:06:47 INFO - PID 1643 | 11:06:47 INFO - PID 1643 | [#2] jquery.html Cycles:180 Average:40832.54 Median:2312.42 stddev:99803.66 (4316.0%) stddev-sans-first:100037.52 11:06:47 INFO - PID 1643 | Values: 422.9 424.9 437.3 444.5 451.8 21181.6 21999.7 22100.2 22125.4 22156.7 23478.5 24440.3 24585.6 24749.6 24752.8 6091.9 6217.9 6244.9 6253.4 6274.7 6106.9 6148.7 6203.7 6208.1 6219.3 6077.8 6104.6 6116.1 6157.7 6388.7 6708.3 6825.8 6827.4 6827.6 6832.9 6649.0 6675.3 6681.7 6682.2 6714.4 29136.0 29161.0 30251.4 30747.5 30783.5 2241.0 2291.9 2300.4 2301.7 2313.0 19440.7 20019.4 20039.8 20089.0 20094.4 2258.7 2266.5 2311.9 2317.5 2360.9 164944.2 166627.0 173815.0 193721.0 197725.0 24522.5 24873.3 25656.7 25941.2 26170.6 34678.8 42893.6 42990.4 42992.0 43012.0 11288.1 11357.2 11407.0 11431.6 11433.0 9034.0 9063.5 9073.9 9127.7 9151.9 10163.9 12023.3 12206.6 12226.2 12318.0 4388.9 4641.2 4783.2 4801.7 4921.1 906.3 935.3 969.2 981.7 1010.2 21500.4 22151.1 22217.0 22306.3 22606.8 891.4 977.5 1014.4 1022.3 1056.7 11050.4 11329.9 11347.7 11358.8 11374.6 574712.0 581102.0 581231.0 586903.0 588155.1 151451.0 153907.5 155329.0 156674.2 158382.0 1166.6 1176.5 1243.3 1257.0 1262.1 22212.9 22720.0 23358.9 23535.6 23556.2 65312.0 67569.3 67711.6 68048.0 69107.3 91085.3 91487.0 91654.1 92231.5 94878.0 10355.6 10383.4 10502.9 10510.6 10513.6 18028.4 18158.4 18209.8 18224.0 18375.8 14729.9 14795.0 14898.3 14918.0 14931.7 12701.6 13657.7 13741.4 13846.0 13869.6 12946.5 14162.9 15445.6 15496.6 15712.7 9128.8 9965.6 10154.1 10354.6 10407.5 14499.7 14608.4 14681.7 14684.3 14705.9 11:06:47 INFO - PID 1643 | 11:06:47 INFO - PID 1643 | [#3] mootools.html Cycles:180 Average:7086.32 Median:3841.37 stddev:4449.05 (115.8%) stddev-sans-first:4440.24 11:06:47 INFO - PID 1643 | Values: 1294.9 1448.4 1455.4 1456.3 1457.7 10821.8 11047.6 11678.8 11890.0 12168.9 11683.5 11750.5 11791.6 11871.6 11888.6 2188.2 2552.2 2552.7 2552.9 2553.7 2474.2 2522.2 2531.6 2546.2 2550.3 2142.7 2507.8 2532.2 2535.2 2539.4 2487.0 2502.3 2507.1 2507.6 2514.8 2421.5 2484.8 2498.7 2508.7 2542.5 11586.3 11676.2 11692.9 11708.3 11722.2 6391.3 6578.8 6622.6 6628.4 6638.8 3455.6 4227.2 4244.1 4338.0 4365.7 1210.6 1220.6 1244.7 1249.3 1255.4 15086.2 15499.6 16328.8 16403.6 16479.3 5082.3 5566.5 5666.5 5699.2 5738.5 11980.5 12045.9 12060.1 12074.4 12101.0 5712.2 6490.5 6521.3 6534.8 6539.1 5183.0 6025.5 6054.7 6109.9 6201.2 4316.5 4365.2 4474.6 4501.0 4536.8 1270.9 1285.0 1292.0 1295.0 1307.2 736.4 751.7 773.6 806.3 813.7 6098.7 6146.3 6424.7 6659.4 7112.6 897.1 937.1 960.2 961.7 984.0 4324.6 4396.5 4433.0 4569.2 4592.9 14674.9 14895.3 15023.9 15069.1 15135.0 12280.8 15059.6 15364.0 15367.5 15372.4 8142.3 8466.9 8575.2 8586.9 8596.7 6332.8 6878.9 7095.4 7117.8 7196.7 12867.6 12935.4 12966.9 13153.9 13204.9 13091.2 13284.8 13306.2 13317.9 13481.5 9566.7 9602.7 9665.7 9704.3 9773.7 10724.6 10829.5 10842.2 10849.4 10867.1 9103.2 9192.2 9264.5 9416.2 9484.8 8964.5 8996.0 9002.1 9089.3 9146.1 7179.2 8118.0 8219.1 8290.0 8453.3 5756.5 5980.5 6114.5 6198.0 6290.2 5629.9 5685.9 5736.9 5826.6 5903.7 11:06:47 INFO - PID 1643 | 11:06:47 INFO - PID 1643 | [#4] prototype.html Cycles:180 Average:5396.97 Median:6009.40 stddev:1696.76 (28.2%) stddev-sans-first:1658.74 11:06:47 INFO - PID 1643 | Values: 352.2 378.5 398.6 399.7 412.0 4775.4 4975.0 5628.4 6052.6 6326.5 5665.7 5845.6 6126.2 6228.3 6304.6 4438.0 4450.4 4633.2 4640.5 4667.5 3840.8 3847.6 4625.8 4687.3 4690.2 3965.7 4596.0 4596.7 4630.0 4639.9 4544.6 4613.1 4634.2 4641.5 4691.9 3335.9 3983.5 3984.7 3990.9 4007.0 5422.9 6282.8 6284.4 6292.4 6298.8 4576.9 5453.6 5507.6 5519.5 5531.7 5050.6 6110.4 6152.5 6549.0 6765.2 3969.3 4141.5 4296.9 4519.7 4529.1 5913.0 6307.4 6328.4 7126.1 7245.1 4083.6 4309.9 4347.8 4546.9 4615.1 4646.3 4672.3 4685.9 4690.3 4715.4 5050.8 5344.9 5466.8 5473.3 5494.1 6763.8 6778.3 6834.7 6844.7 6849.6 1478.5 1774.6 1774.9 1777.5 1778.9 1036.0 1178.3 1199.9 1246.4 1246.6 5503.4 6347.8 6381.0 6408.1 6528.7 5632.1 5959.0 5966.2 6952.4 7065.6 5609.6 6358.6 6379.3 6388.6 6393.8 3234.0 3465.6 3661.7 3687.4 3709.2 7065.0 7244.9 7247.5 7257.2 7263.2 6065.9 6455.4 7170.3 7185.7 7199.5 6459.4 6540.8 7244.5 7276.7 7314.7 5675.8 6166.6 6719.7 7041.8 7043.2 6910.6 6913.6 6940.5 6945.9 6950.7 6626.8 7174.8 7190.5 7240.4 7242.2 3878.8 4255.4 4581.7 4618.2 4618.6 6076.6 6496.7 6709.7 7089.8 7185.9 6647.0 6705.9 6714.8 6736.9 6737.4 6668.7 6689.4 6723.4 6745.6 6753.9 5508.0 5667.5 6437.8 6598.2 6599.2 6718.2 6721.9 6726.6 6745.6 6759.4 6484.4 6547.7 6585.2 6591.6 6595.0 11:06:47 INFO - PID 1643 | 11:06:47 INFO - PID 1643 | [#5] yui.html Cycles:180 Average:25248.27 Median:17090.36 stddev:40137.95 (234.9%) stddev-sans-first:40208.72 11:06:47 INFO - PID 1643 | Values: 842.7 849.5 852.9 881.4 889.1 14639.9 15200.2 16824.6 16855.2 17148.7 16133.9 17969.9 18118.3 18186.6 18208.3 960.0 968.9 970.4 971.5 972.7 649.7 825.2 985.0 987.2 995.0 589.7 996.2 1029.2 1030.9 1033.3 1026.5 1027.6 1029.3 1029.5 1032.5 763.6 764.7 764.8 766.7 769.3 15883.0 15891.3 16822.9 16969.6 17296.5 17071.2 17109.5 17227.3 17371.3 17543.5 14743.8 14760.3 14846.7 14888.6 14947.0 3263.2 3310.3 3397.8 3474.7 3481.5 120379.0 140156.0 148035.3 148583.3 149096.0 12141.3 12158.6 12214.2 12368.4 12424.6 17669.4 18123.6 20592.3 21182.6 21260.0 6411.3 6540.1 6725.4 7153.3 7378.7 3828.2 3882.0 3905.7 3906.7 3910.7 4132.3 4206.9 4212.3 4214.5 4639.5 956.7 965.7 982.1 982.7 983.8 644.7 662.8 670.0 676.4 682.0 11472.1 11723.1 12152.5 12252.4 12395.3 644.3 651.5 654.4 669.8 684.2 5582.4 5599.2 5822.4 5887.3 5927.9 149726.3 149744.3 150110.7 151414.0 153346.0 126643.0 127294.7 127958.2 128840.0 132816.0 103857.5 105274.0 106191.5 106633.0 106801.0 10422.9 11122.9 11600.5 11644.2 11813.8 46579.0 46811.5 47582.0 47624.0 48160.3 62257.4 62397.0 62852.1 63438.9 63871.7 14510.5 14610.8 14928.4 14997.3 15013.2 15653.5 16125.0 16310.4 16356.2 16397.9 14872.9 15070.1 15301.1 15370.2 15379.4 13689.6 14963.9 16461.7 17140.2 17775.0 12889.4 14563.5 15072.5 15117.8 15208.4 11577.8 11602.0 12144.4 12330.7 12460.8 11810.7 13616.8 13669.7 14133.4 14135.4 11:06:47 INFO - PID 1643 | -------- Summary: end -------- 11:06:47 INFO - PID 1643 | 11:06:48 INFO - TEST-INFO | 1643: exit 0 11:06:48 INFO - TEST-OK | dromaeo_css | took 1143351ms 11:06:48 INFO - TEST-START | kraken 11:06:48 INFO - operating with platform_type : mac_ 11:06:48 INFO - Initialising browser for kraken test... 11:06:48 INFO - Application command: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49222/getInfo.html -foreground -profile /var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/tmp8hR_Ig/profile 11:06:48 INFO - TEST-INFO | started process 1653 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49222/getInfo.html -foreground) 11:07:22 INFO - TEST-INFO | 1653: killed by SIGTERM 11:07:22 INFO - Browser initialized. 11:07:22 INFO - Running cycle 1/1 for kraken test... 11:07:22 INFO - TEST-INFO | started process 1657 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/tmp8hR_Ig/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 11:07:24 INFO - PID 1657 | 2017-06-01 11:07:24.423 plugin-container[1658:10486] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x973f, name = 'com.apple.tsm.portname' 11:07:24 INFO - PID 1657 | See /usr/include/servers/bootstrap_defs.h for the error codes. 11:07:24 INFO - PID 1657 | 2017-06-01 11:07:24.424 plugin-container[1658:10486] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9d0b, name = 'com.apple.CFPasteboardClient' 11:07:24 INFO - PID 1657 | See /usr/include/servers/bootstrap_defs.h for the error codes. 11:07:24 INFO - PID 1657 | 2017-06-01 11:07:24.424 plugin-container[1658:10486] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 11:07:24 INFO - PID 1657 | 2017-06-01 11:07:24.962 plugin-container[1659:10534] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x474b, name = 'com.apple.tsm.portname' 11:07:24 INFO - PID 1657 | See /usr/include/servers/bootstrap_defs.h for the error codes. 11:07:24 INFO - PID 1657 | 2017-06-01 11:07:24.963 plugin-container[1659:10534] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x474f, name = 'com.apple.CFPasteboardClient' 11:07:24 INFO - PID 1657 | See /usr/include/servers/bootstrap_defs.h for the error codes. 11:07:24 INFO - PID 1657 | 2017-06-01 11:07:24.963 plugin-container[1659:10534] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 11:07:34 INFO - PID 1657 | RSS: Main: 268509184 11:07:34 INFO - PID 1657 | 11:07:36 INFO - PID 1657 | Cycle 1(1): loaded http://localhost:49222/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49222/tests/kraken/driver.html?testName=audio-beat-detection) 11:07:37 INFO - PID 1657 | RSS: Main: 276688896 11:07:37 INFO - PID 1657 | 11:07:40 INFO - PID 1657 | Cycle 1(1): loaded http://localhost:49222/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49222/tests/kraken/driver.html?testName=audio-dft) 11:07:41 INFO - PID 1657 | RSS: Main: 284368896 11:07:41 INFO - PID 1657 | 11:07:44 INFO - PID 1657 | Cycle 1(1): loaded http://localhost:49222/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49222/tests/kraken/driver.html?testName=audio-fft) 11:07:45 INFO - PID 1657 | RSS: Main: 282103808 11:07:45 INFO - PID 1657 | 11:07:47 INFO - PID 1657 | Cycle 1(1): loaded http://localhost:49222/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49222/tests/kraken/driver.html?testName=audio-oscillator) 11:07:47 INFO - PID 1657 | RSS: Main: 283983872 11:07:47 INFO - PID 1657 | 11:07:49 INFO - PID 1657 | Cycle 1(1): loaded http://localhost:49222/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49222/tests/kraken/driver.html?testName=imaging-gaussian-blur) 11:07:50 INFO - PID 1657 | RSS: Main: 284753920 11:07:50 INFO - PID 1657 | 11:07:53 INFO - PID 1657 | Cycle 1(1): loaded http://localhost:49222/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49222/tests/kraken/driver.html?testName=imaging-darkroom) 11:07:54 INFO - PID 1657 | RSS: Main: 289013760 11:07:54 INFO - PID 1657 | 11:07:57 INFO - PID 1657 | Cycle 1(1): loaded http://localhost:49222/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49222/tests/kraken/driver.html?testName=imaging-desaturate) 11:07:57 INFO - PID 1657 | RSS: Main: 292171776 11:07:57 INFO - PID 1657 | 11:08:00 INFO - PID 1657 | Cycle 1(1): loaded http://localhost:49222/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49222/tests/kraken/driver.html?testName=json-parse-financial) 11:08:01 INFO - PID 1657 | RSS: Main: 291430400 11:08:01 INFO - PID 1657 | 11:08:02 INFO - PID 1657 | Cycle 1(1): loaded http://localhost:49222/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49222/tests/kraken/driver.html?testName=json-stringify-tinderbox) 11:08:03 INFO - PID 1657 | RSS: Main: 290586624 11:08:03 INFO - PID 1657 | 11:08:05 INFO - PID 1657 | Cycle 1(1): loaded http://localhost:49222/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49222/tests/kraken/driver.html?testName=stanford-crypto-aes) 11:08:05 INFO - PID 1657 | RSS: Main: 290476032 11:08:05 INFO - PID 1657 | 11:08:08 INFO - PID 1657 | Cycle 1(1): loaded http://localhost:49222/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49222/tests/kraken/driver.html?testName=stanford-crypto-ccm) 11:08:08 INFO - PID 1657 | RSS: Main: 290373632 11:08:08 INFO - PID 1657 | 11:08:11 INFO - PID 1657 | Cycle 1(1): loaded http://localhost:49222/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49222/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 11:08:12 INFO - PID 1657 | RSS: Main: 290312192 11:08:12 INFO - PID 1657 | 11:08:15 INFO - PID 1657 | Cycle 1(1): loaded http://localhost:49222/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49222/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 11:08:16 INFO - PID 1657 | RSS: Main: 290693120 11:08:16 INFO - PID 1657 | 11:08:18 INFO - PID 1657 | Cycle 1(1): loaded http://localhost:49222/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49222/tests/kraken/driver.html?testName=ai-astar) 11:08:18 INFO - PID 1657 | RSS: Main: 291983360 11:08:18 INFO - PID 1657 | 11:08:18 INFO - PID 1657 | __start_tp_report 11:08:18 INFO - PID 1657 | _x_x_mozilla_page_load 11:08:18 INFO - PID 1657 | _x_x_mozilla_page_load_details 11:08:18 INFO - PID 1657 | |i|pagename|runs| 11:08:18 INFO - PID 1657 | |0;ai-astar;102;116;115;120;118;114;114;115;114;115 11:08:18 INFO - PID 1657 | |1;audio-beat-detection;145;137;147;182;142;95;133;149;159;118 11:08:18 INFO - PID 1657 | |2;audio-dft;200;197;197;218;220;211;210;196;196;213 11:08:18 INFO - PID 1657 | |3;audio-fft;77;57;63;89;64;61;67;80;85;61 11:08:18 INFO - PID 1657 | |4;audio-oscillator;88;70;82;70;72;70;87;69;74;77 11:08:18 INFO - PID 1657 | |5;imaging-gaussian-blur;108;130;138;90;90;90;89;104;137;90 11:08:18 INFO - PID 1657 | |6;imaging-darkroom;98;98;98;98;97;96;99;96;97;96 11:08:18 INFO - PID 1657 | |7;imaging-desaturate;92;96;92;93;92;92;90;94;92;91 11:08:18 INFO - PID 1657 | |8;json-parse-financial;52;52;51;53;54;53;52;51;52;51 11:08:18 INFO - PID 1657 | |9;json-stringify-tinderbox;57;63;75;82;90;60;54;57;53;61 11:08:18 INFO - PID 1657 | |10;stanford-crypto-aes;89;89;92;90;91;89;90;90;123;87 11:08:18 INFO - PID 1657 | |11;stanford-crypto-ccm;147;156;127;135;159;155;149;148;139;163 11:08:18 INFO - PID 1657 | |12;stanford-crypto-pbkdf2;190;187;193;216;188;184;190;189;187;187 11:08:18 INFO - PID 1657 | |13;stanford-crypto-sha256-iterative;67;71;69;71;86;89;96;72;70;68 11:08:18 INFO - PID 1657 | __end_tp_report 11:08:18 INFO - PID 1657 | __start_cc_report 11:08:18 INFO - PID 1657 | _x_x_mozilla_cycle_collect,1206 11:08:18 INFO - PID 1657 | __end_cc_report 11:08:18 INFO - PID 1657 | __startTimestamp1496340498271__endTimestamp 11:08:18 INFO - PID 1657 | 11:08:18 INFO - PID 1657 | ------- Summary: start ------- 11:08:18 INFO - PID 1657 | Number of tests: 14 11:08:18 INFO - PID 1657 | 11:08:18 INFO - PID 1657 | [#0] ai-astar Cycles:10 Average:114.30 Median:115.00 stddev:4.74 (4.1%) stddev-sans-first:2.06 11:08:18 INFO - PID 1657 | Values: 102.0 116.0 115.0 120.0 118.0 114.0 114.0 115.0 114.0 115.0 11:08:18 INFO - PID 1657 | 11:08:18 INFO - PID 1657 | [#1] audio-beat-detection Cycles:10 Average:140.70 Median:148.00 stddev:23.20 (15.7%) stddev-sans-first:24.56 11:08:18 INFO - PID 1657 | Values: 145.0 137.0 147.0 182.0 142.0 95.0 133.0 149.0 159.0 118.0 11:08:18 INFO - PID 1657 | 11:08:18 INFO - PID 1657 | [#2] audio-dft Cycles:10 Average:205.80 Median:210.50 stddev:9.59 (4.6%) stddev-sans-first:9.94 11:08:18 INFO - PID 1657 | Values: 200.0 197.0 197.0 218.0 220.0 211.0 210.0 196.0 196.0 213.0 11:08:18 INFO - PID 1657 | 11:08:18 INFO - PID 1657 | [#3] audio-fft Cycles:10 Average:70.40 Median:72.00 stddev:11.35 (15.8%) stddev-sans-first:11.78 11:08:18 INFO - PID 1657 | Values: 77.0 57.0 63.0 89.0 64.0 61.0 67.0 80.0 85.0 61.0 11:08:18 INFO - PID 1657 | 11:08:18 INFO - PID 1657 | [#4] audio-oscillator Cycles:10 Average:75.90 Median:75.50 stddev:7.29 (9.7%) stddev-sans-first:6.29 11:08:18 INFO - PID 1657 | Values: 88.0 70.0 82.0 70.0 72.0 70.0 87.0 69.0 74.0 77.0 11:08:18 INFO - PID 1657 | 11:08:18 INFO - PID 1657 | [#5] imaging-gaussian-blur Cycles:10 Average:106.60 Median:89.50 stddev:20.76 (23.2%) stddev-sans-first:22.01 11:08:18 INFO - PID 1657 | Values: 108.0 130.0 138.0 90.0 90.0 90.0 89.0 104.0 137.0 90.0 11:08:18 INFO - PID 1657 | 11:08:18 INFO - PID 1657 | [#6] imaging-darkroom Cycles:10 Average:97.30 Median:98.00 stddev:1.06 (1.1%) stddev-sans-first:1.09 11:08:18 INFO - PID 1657 | Values: 98.0 98.0 98.0 98.0 97.0 96.0 99.0 96.0 97.0 96.0 11:08:18 INFO - PID 1657 | 11:08:18 INFO - PID 1657 | [#7] imaging-desaturate Cycles:10 Average:92.40 Median:92.00 stddev:1.65 (1.8%) stddev-sans-first:1.74 11:08:18 INFO - PID 1657 | Values: 92.0 96.0 92.0 93.0 92.0 92.0 90.0 94.0 92.0 91.0 11:08:18 INFO - PID 1657 | 11:08:18 INFO - PID 1657 | [#8] json-parse-financial Cycles:10 Average:52.10 Median:52.00 stddev:0.99 (1.9%) stddev-sans-first:1.05 11:08:18 INFO - PID 1657 | Values: 52.0 52.0 51.0 53.0 54.0 53.0 52.0 51.0 52.0 51.0 11:08:18 INFO - PID 1657 | 11:08:18 INFO - PID 1657 | [#9] json-stringify-tinderbox Cycles:10 Average:65.20 Median:62.00 stddev:12.70 (20.5%) stddev-sans-first:13.12 11:08:18 INFO - PID 1657 | Values: 57.0 63.0 75.0 82.0 90.0 60.0 54.0 57.0 53.0 61.0 11:08:18 INFO - PID 1657 | 11:08:18 INFO - PID 1657 | [#10] stanford-crypto-aes Cycles:10 Average:93.00 Median:90.00 stddev:10.62 (11.8%) stddev-sans-first:11.17 11:08:18 INFO - PID 1657 | Values: 89.0 89.0 92.0 90.0 91.0 89.0 90.0 90.0 123.0 87.0 11:08:18 INFO - PID 1657 | 11:08:18 INFO - PID 1657 | [#11] stanford-crypto-ccm Cycles:10 Average:147.80 Median:152.00 stddev:11.31 (7.4%) stddev-sans-first:11.99 11:08:18 INFO - PID 1657 | Values: 147.0 156.0 127.0 135.0 159.0 155.0 149.0 148.0 139.0 163.0 11:08:18 INFO - PID 1657 | 11:08:18 INFO - PID 1657 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:191.10 Median:189.50 stddev:9.07 (4.8%) stddev-sans-first:9.61 11:08:18 INFO - PID 1657 | Values: 190.0 187.0 193.0 216.0 188.0 184.0 190.0 189.0 187.0 187.0 11:08:18 INFO - PID 1657 | 11:08:18 INFO - PID 1657 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:75.90 Median:71.50 stddev:10.35 (14.5%) stddev-sans-first:10.47 11:08:18 INFO - PID 1657 | Values: 67.0 71.0 69.0 71.0 86.0 89.0 96.0 72.0 70.0 68.0 11:08:18 INFO - PID 1657 | -------- Summary: end -------- 11:08:18 INFO - PID 1657 | 11:08:19 INFO - TEST-INFO | 1657: exit 0 11:08:19 INFO - TEST-OK | kraken | took 90631ms 11:08:19 INFO - SUITE-END | took 1233s 11:08:19 INFO - Completed test suite (00:20:34) 11:08:19 INFO - javascript benchmark 11:08:19 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8161.8498267512805, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3564.572251329839, 3576.0880975351283, 3611.422172452408, 3625.655562721544, 3627.474016267641, 5173.793048278064, 5211.218317252409, 5963.194968679232, 6134.344744984532, 6227.0, 6146.139540464339, 6168.722407491662, 6198.256209254896, 6206.813795586139, 6300.653464059464, 4145.647619952308, 5293.444188360225, 5375.7580908859, 5458.099413596752, 5465.562754979612, 5536.667799931997, 5538.335399752035, 5570.944290557083, 5586.078297080977, 5586.8603284918, 5363.410024897258, 5412.783488660449, 5444.972775136139, 5469.179623056553, 5491.697956612385, 5476.3702174250075, 5484.91772623411, 5502.0921547944545, 5504.587155963319, 5548.72256387179, 4204.810783514749, 5396.379416367089, 5461.890762184774, 5488.506034456878, 5522.392536820987, 6125.937554508974, 6155.384461553854, 6203.534734894856, 6248.250209974832, 6282.748690052393, 6569.310222426624, 6677.399034086907, 6681.064650948871, 6682.264950855402, 6690.230623478314, 6699.330066993261, 6782.796516104525, 6789.287124851869, 6790.5925644461495, 6792.388685018323, 6184.783532576338, 6292.842678933063, 6297.748090076391, 6314.400131987454, 6334.144890439778, 6784.898226526606, 6849.417799487, 6906.654667266616, 6934.757283495154, 6949.096617439701, 6237.906431403532, 6413.198350206224, 6430.3891130342545, 6534.0, 6586.8353291168105, 6665.633390163587, 6773.762918297836, 6774.153230846145, 6783.0, 6794.796156115324, 6605.801825945229, 6625.801225963229, 6631.535792494479, 6655.367740064686, 6672.0325552794575, 6399.744010239631, 6440.162778838721, 6557.213134423804, 6598.934010659928, 6663.933360666429, 5306.442823503554, 5311.070562651521, 5313.123334649739, 5339.092354299701, 5361.195820626859, 4696.929546056812, 4704.623630109583, 4719.339292499053, 4751.619870410359, 4762.190427627312, 4941.25881117828, 6106.430331452215, 6282.937170628235, 6309.179806625201, 6341.587796793194, 5963.880722385528, 6797.388235058868, 6818.420434263143, 6823.590584564942, 6834.1457317835275, 6287.96856015717, 6294.276158241769, 6333.050042493571, 6333.20834895638, 6335.68321583919, 5757.136429535603, 5820.82537523875, 5865.530757539386, 5943.494802941712, 5972.40275972408, 6928.757493487704, 6935.375816176568, 6935.514513984073, 6947.930520694728, 6956.165260168812, 6798.8640227196165, 6806.115205023315, 6810.31896810315, 6813.318668133345, 6834.794956151323, 6730.764423245163, 6863.450923926173, 6945.166580010229, 6965.7561985332295, 6982.162140543167, 6448.709808058555, 6582.11141495911, 6632.0, 6662.601463473243, 6680.933190668031, 6770.221424536241, 6799.660016999229, 6799.728010879509, 6814.386705196358, 6821.89767153503, 6176.752929882934, 6377.681115944091, 6478.773242936474, 6495.0906873036865, 6571.80284591463, 6529.281779004211, 6592.0111983202905, 6593.044008618819, 6595.274519802721, 6609.900851487136, 6319.052142178526, 6419.293877673359, 6428.774992875227, 6433.099366088845, 6450.3549645037, 6335.3981371769605, 6422.325655806073, 6446.065320528591, 6567.638779867153, 6577.901331480128, 6381.138546296191, 6382.680865956777, 6385.584936979081, 6403.359664033559, 6421.389967953037], "value": 6094.589142025303, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [784.5253621558957, 820.9014918209815, 846.953417562034, 1250.224860586436, 1327.482281910056, 38406.0, 39373.80313098438, 39440.0, 39533.0, 39561.40657890134, 39549.8022509887, 39633.8109856704, 39904.40143397851, 39905.800470997674, 39934.0, 7767.106782720003, 7927.762167134966, 8162.510249385056, 8427.241548260656, 8433.789155271137, 8230.670773169066, 8249.175082491764, 8326.875096873551, 8328.916710832875, 8359.916400836006, 8266.751997440086, 8351.0, 8365.330773538133, 8373.371997100241, 8373.958130209341, 8244.8351032979, 8282.1717828217, 8311.501309921394, 8340.791480212982, 8344.582770861432, 8147.796305092361, 8197.0, 8247.95876020622, 8255.628496717662, 8261.504309741435, 36776.632233677585, 36933.26133477315, 37029.07427314312, 37145.62854371449, 37306.626933730586, 3619.855205791765, 3657.140571965586, 3661.8169091545583, 3666.0651533858963, 3667.523221981152, 30313.69686303131, 30347.544786828214, 30433.391332173454, 30463.390732185453, 30506.0, 3026.4854974654363, 3032.9090127296217, 3068.4964367460007, 3076.7538596912195, 3086.3981523602906, 234271.0, 237665.0, 262484.6875765628, 287148.0, 288323.0, 46055.5394446058, 46102.30846537305, 46408.0, 46528.30207546889, 46535.0, 49811.75094124506, 49913.50086499161, 50088.499115009115, 54089.72955135278, 54586.08948686777, 14451.927740361441, 14576.198309093103, 14587.854121458862, 14590.051646643147, 14594.124352538882, 11463.828042579367, 11585.420728963518, 11672.774358692384, 11681.182317237712, 11705.356205408785, 11281.0, 11550.59572914944, 13952.86047139536, 14107.929460352632, 14117.223552704494, 6203.565750397519, 6220.875582488325, 6259.1550140730415, 6259.96870015647, 6266.686665666698, 985.5614251657944, 1006.6677996261217, 1013.9036791504795, 1056.3609016544879, 1070.9678709638567, 18189.636207276046, 24344.26967190987, 24735.62896556517, 24751.25746227616, 24770.0, 1070.9023251167616, 1076.6554702495127, 1115.9051480624075, 1139.3505701749923, 1173.23153334566, 12582.496700132075, 12764.978801695843, 12772.361381930867, 12773.10588258813, 12795.488180472677, 318807.0, 336986.63013370044, 337187.0, 337725.6227437743, 338748.0, 49147.77130571693, 49340.25989610159, 49518.50481495211, 54188.187177192376, 54595.0, 1509.6075020494532, 1533.7699345098324, 1568.3256199833952, 1573.8662213711962, 1580.194101008494, 18466.630667386846, 24170.274891753277, 24340.878295608407, 24437.38906527351, 24545.263642090766, 38386.80806595949, 38418.42372364361, 38467.845964621105, 38479.23041539209, 38483.80758096304, 273318.0, 274332.25667743065, 275030.0, 275260.0, 275539.0, 29302.0, 35605.465918010734, 35623.64376356307, 35878.74424395238, 35992.820035899655, 42698.0, 44260.11479770451, 46073.30890036585, 46130.76934615306, 46222.7688861567, 29455.410891782474, 30460.0, 30640.0, 31499.52750708787, 31786.52320215245, 29342.853285733436, 30253.48732563407, 30456.847715762167, 31257.84371078221, 31703.682963170995, 36408.45387319139, 37343.0, 37694.64024338425, 38088.238235235694, 38206.61793382031, 30399.848000759855, 31253.218669533442, 31473.0, 31556.684433155373, 32264.193395165308, 22234.332970010924, 22434.214802481834, 24298.028078876647, 24389.878050610343, 24627.753722462545], "value": 17529.0385278913, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [422.8646833013435, 424.92563801334757, 437.306868613248, 444.45110288793353, 451.8170141092857, 21181.576368472623, 21999.670004949938, 22100.22649207278, 22125.446863828412, 22156.667649985222, 23478.530429391445, 24440.333949907523, 24585.631215531783, 24749.62875556868, 24752.75247247527, 6091.878162436748, 6217.875642487147, 6244.93836047873, 6253.405926437004, 6274.654893980829, 6106.877862442749, 6148.723307451153, 6203.720832562545, 6208.068789681539, 6219.284782250032, 6077.817665470021, 6104.603200791957, 6116.08258761185, 6157.7229024694, 6388.744450221986, 6708.262091169968, 6825.829354266133, 6827.419669328113, 6827.556208846409, 6832.897506537406, 6649.035889796, 6675.299093595199, 6681.732730690766, 6682.231543372477, 6714.362135597111, 29136.0, 29161.0, 30251.394972100654, 30747.538786918216, 30783.538246926313, 2241.0, 2291.873946932918, 2300.378897697629, 2301.7007788987494, 2312.97687023131, 19440.70838937417, 20019.39941801748, 20039.79960200394, 20089.0, 20094.39716808498, 2258.6837842702034, 2266.5013696986634, 2311.872846993415, 2317.4901521665206, 2360.8701521416315, 164944.17527912284, 166627.0, 173815.0, 193721.0, 197725.0227497721, 24522.509549808903, 24873.253802385956, 25656.74343256581, 25941.22176334713, 26170.6074408884, 34678.82660586681, 42893.57106428896, 42990.35514467285, 42992.0, 43012.0, 11288.0969522438, 11357.204995650225, 11407.0, 11431.59989400367, 11433.0, 9033.954830225939, 9063.54682265884, 9073.909260907307, 9127.726168214964, 9151.908480915106, 10163.94918025405, 12023.27860328383, 12206.633800985806, 12226.20529665569, 12318.0, 4388.88083538702, 4641.2109941310055, 4783.234682450791, 4801.68789028712, 4921.114199444134, 906.2885634776669, 935.2517985611483, 969.1519920069939, 981.6760469045198, 1010.1514727628827, 21500.354989350344, 22151.113955441924, 22217.0, 22306.330810075724, 22606.773932260796, 891.41612243979, 977.5210147027905, 1014.4471263161595, 1022.3303736052898, 1056.6671498477954, 11050.392228427514, 11329.923657252548, 11347.659570212907, 11358.829617555743, 11374.601888933848, 574712.0, 581102.0, 581231.0, 586903.0, 588155.0592247098, 151451.0, 153907.46092539156, 155329.0, 156674.2166289184, 158382.0, 1166.6383421139474, 1176.5411489519095, 1243.2602601452122, 1256.9685757856128, 1262.1291308996763, 22212.88893555522, 22720.0, 23358.88320558454, 23535.646965295877, 23556.175533856233, 65312.0, 67569.3243067563, 67711.64576708537, 68048.0, 69107.30892691009, 91085.25775309173, 91487.0, 91654.08345916455, 92231.53884230535, 94878.0, 10355.585776569154, 10383.428911409945, 10502.94748526283, 10510.579576816841, 10513.632022879163, 18028.36900708469, 18158.364457243933, 18209.81636193643, 18224.0, 18375.816241837412, 14729.852701473277, 14795.0, 14898.255087245378, 14917.955743097878, 14931.701365972836, 12701.60282368957, 13657.658558536115, 13741.381637826522, 13846.0, 13869.583912482642, 12946.482140714266, 14162.858371416567, 15445.613859653598, 15496.605305522557, 15712.685746285239, 9128.77178070527, 9965.601375944882, 10154.136898363722, 10354.637587684394, 10407.531661075118, 14499.710005799614, 14608.415663373346, 14681.70636587241, 14684.265786710408, 14705.852941470876], "value": 12454.470243241407, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1294.9093563450556, 1448.3844366144385, 1455.366915391804, 1456.3009755317448, 1457.6720237946463, 10821.783564328709, 11047.563816703818, 11678.766424671512, 11890.048796096313, 12168.904798568126, 11683.481147450819, 11750.471228794704, 11791.587294444696, 11871.643850684472, 11888.643340699771, 2188.190369563256, 2552.1705445730117, 2552.6936767587918, 2552.9489410211786, 2553.668023156987, 2474.2206205045354, 2522.2433270019014, 2531.620256961461, 2546.2106746908426, 2550.311415917701, 2142.700021996922, 2507.849529028264, 2532.177042461198, 2535.150724507292, 2539.415934335104, 2486.950260994779, 2502.299356180263, 2507.135038411754, 2507.636392723045, 2514.7736703696573, 2421.5156968606348, 2484.788792952583, 2498.7001559812757, 2508.6613307203475, 2542.4533725248975, 11586.304821710724, 11676.1826672133, 11692.883071169264, 11708.297502149813, 11722.238054526428, 6391.296957334689, 6578.8026359208825, 6622.569532980389, 6628.43658289046, 6638.80083597488, 3455.585329760445, 4227.154569086164, 4244.108737165199, 4338.045629961403, 4365.712114926073, 1210.6126039667222, 1220.5666988219143, 1244.6514975806788, 1249.2816630437535, 1255.3723138430785, 15086.24568771568, 15499.612509687235, 16328.75506867398, 16403.58991025222, 16479.258433370527, 5082.263071854561, 5566.471185237396, 5666.518345940642, 5699.230603868507, 5738.512226460714, 11980.460879260454, 12045.879541204651, 12060.095492837896, 12074.396280185956, 12101.0, 5712.171735098471, 6490.513211509061, 6521.315261897433, 6534.803955881332, 6539.08452816606, 5183.0, 6025.548083893638, 6054.666993315322, 6109.877802443926, 6201.162843016225, 4316.482022157361, 4365.236083685342, 4474.597286244254, 4500.977495112569, 4536.820426689084, 1270.866559011309, 1285.042643230799, 1292.0, 1294.9935250323688, 1307.1960744142284, 736.4108713029663, 751.7181057103586, 773.6402572803673, 806.3065763443434, 813.6777736178709, 6098.72555734993, 6146.279041868284, 6424.74301027963, 6659.367360100783, 7112.644367781693, 897.1297841094129, 937.1378331934636, 960.2078285414561, 961.7451375385529, 983.9803203935882, 4324.589164029475, 4396.516383197845, 4432.955670443319, 4569.246074397754, 4592.931106033412, 14674.853251467563, 14895.329710163069, 15023.924880375529, 15069.09585424878, 15135.0, 12280.815787763191, 15059.623509412353, 15364.00133991287, 15367.538973830578, 15372.385104595915, 8142.267195952392, 8466.872996905051, 8575.228229459379, 8586.93913460765, 8596.742097737078, 6332.778352757631, 6878.896816547655, 7095.432365410651, 7117.75087871922, 7196.748113815991, 12867.613971580868, 12935.353232338157, 12966.935165324114, 13153.934230328787, 13204.933975330061, 13091.214527128403, 13284.800727988895, 13306.201627902357, 13317.866821331663, 13481.460741570227, 9566.712998610054, 9602.66390676323, 9665.71002869915, 9704.272179586418, 9773.657921972697, 10724.624638137628, 10829.51267192994, 10842.241043126905, 10849.403282819521, 10867.076298514714, 9103.180713735796, 9192.172704456363, 9264.490453025148, 9416.152546270594, 9484.762880928032, 8964.506952117696, 8995.955020224857, 9002.099790020946, 9089.272858171462, 9146.085391461067, 7179.210286868336, 8117.959410202911, 8219.054808696837, 8290.046644635944, 8453.28147107478, 5756.539476841759, 5980.461758441761, 6114.48026917717, 6198.0, 6290.182276304051, 5629.88740225185, 5685.886282274414, 5736.913946290725, 5826.621269617462, 5903.675297858659], "value": 5374.96847300984, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [352.1706381471634, 378.47581100176257, 398.6173273657289, 399.68824317032727, 412.0399469236678, 4775.403074615673, 4975.004999000196, 5628.409017053206, 6052.57631965762, 6326.525510586707, 5665.716714164286, 5845.567835880215, 6126.172966649512, 6228.314885362606, 6304.5955184200475, 4438.001449673822, 4450.399196108532, 4633.212353899845, 4640.46634637016, 4667.486576476585, 3840.7503512271614, 3847.615238476158, 4625.8149674013, 4687.343771871941, 4690.179218636742, 3965.691321863778, 4595.954040459586, 4596.724196548218, 4629.953700463003, 4639.86080417588, 4544.590986811187, 4613.054323863616, 4634.2353511670535, 4641.489436162019, 4691.859244222679, 3335.8999230023137, 3983.521977362706, 3984.6613037891525, 3990.900227494307, 4006.9782205537404, 5422.864428389283, 6282.780102696383, 6284.402981716729, 6292.402221788922, 6298.842528936768, 4576.931346029813, 5453.563714902798, 5507.642003269774, 5519.530839878616, 5531.700050488132, 5050.6212034097225, 6110.419510146529, 6152.477039451652, 6548.967255163741, 6765.188177418741, 3969.3450580654157, 4141.5237247716295, 4296.947247924248, 4519.706219095749, 4529.116822219668, 5913.0, 6307.369263073701, 6328.367163283635, 7126.144862616467, 7245.094363204599, 4083.5916408358926, 4309.913801723948, 4347.804348804311, 4546.931796023062, 4615.14619795339, 4646.303054541846, 4672.345871577982, 4685.929711054337, 4690.273007683814, 4715.3869996900185, 5050.84847454577, 5344.866378340573, 5466.835994920159, 5473.343198816167, 5494.09347457665, 6763.7632682855865, 6778.288279730656, 6834.726610935606, 6844.691988860513, 6849.589024658537, 1478.5194811686245, 1774.5829730013436, 1774.943908374515, 1777.520069581206, 1778.941529789773, 1035.985009157182, 1178.3283528388736, 1199.9340036298088, 1246.389269258057, 1246.5823948977195, 5503.449655034545, 6347.809565713036, 6380.968095159587, 6408.084321633645, 6528.67356632176, 5632.127020311858, 5958.990709691578, 5966.224390829165, 6952.374286314256, 7065.57606543609, 5609.607327487037, 6358.618482890949, 6379.298277189506, 6388.584741991848, 6393.840153996187, 3234.029791062672, 3465.5841299044278, 3661.6521430464063, 3687.4284485904727, 3709.221063576679, 7065.010898474217, 7244.855102897913, 7247.492675512769, 7257.237990011078, 7263.164736055315, 6065.878682426327, 6455.419012288917, 7170.282971702893, 7185.712571497186, 7199.532030418006, 6459.449732064365, 6540.771073012422, 7244.492885497964, 7276.745313913988, 7314.707411703579, 5675.829725108253, 6166.630002199882, 6719.731210751711, 7041.788746337618, 7043.154821421463, 6910.585364878123, 6913.609857740271, 6940.548864323803, 6945.895811562729, 6950.687219075255, 6626.768063117768, 7174.784756457315, 7190.460715446257, 7240.384567311838, 7242.239564845826, 3878.7672739635714, 4255.446791917031, 4581.702189357681, 4618.237990731492, 4618.584327410549, 6076.574639775174, 6496.675166241764, 6709.664516774239, 7089.751858684921, 7185.892211616934, 6647.0, 6705.865882682417, 6714.798556043326, 6736.865262694817, 6737.395493411002, 6668.699908504226, 6689.431398331197, 6723.4285085766305, 6745.5952642841585, 6753.932460675527, 5507.972460137674, 5667.489925906523, 6437.806865794034, 6598.241202261801, 6599.208095028627, 6718.193816742022, 6721.93278067213, 6726.553790934972, 6745.561538499982, 6759.425448836904, 6484.351564843667, 6547.672616369067, 6585.176852893388, 6591.571547849375, 6594.967025164843], "value": 4881.406504988491, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [842.7303262955853, 849.5285116760195, 852.936029797765, 881.4006475596595, 889.0664801957945, 14639.853601463983, 15200.239988000583, 16824.579385515353, 16855.241514131863, 17148.74276885846, 16133.919330403362, 17969.91015044926, 18118.275268989226, 18186.636267274647, 18208.271669133253, 959.9616015359395, 968.8837339519267, 970.4468452981803, 971.4899677669245, 972.6936015155203, 649.6589290622437, 825.1913125137339, 984.9606015759362, 987.1609132237612, 995.0397866059251, 589.7051474262869, 996.227923359395, 1029.171516928872, 1030.8969103089705, 1033.338663255517, 1026.5072765072807, 1027.604372316656, 1029.3000759483548, 1029.4904022508906, 1032.5095579599706, 763.6449051191167, 764.7399884039397, 764.793505753449, 766.7239793674273, 769.3037800790294, 15883.047017178891, 15891.284892179765, 16822.915885420494, 16969.57576060596, 17296.481105566854, 17071.23179456915, 17109.486715398558, 17227.310907563682, 17371.30514779407, 17543.473695789147, 14743.77884331736, 14760.26198690072, 14846.70306593873, 14888.62778430537, 14947.0, 3263.2005158736024, 3310.2882880180878, 3397.779144355609, 3474.652534746505, 3481.495183198447, 120379.0, 140156.0, 148035.25982370126, 148583.25708371497, 149096.0, 12141.271523708605, 12158.635240942785, 12214.206076604993, 12368.44342004612, 12424.627261182179, 17669.38157164493, 18123.637527249382, 20592.27927022547, 21182.576348473252, 21260.0, 6411.294757576756, 6540.05169250456, 6725.361090696376, 7153.320434558708, 7378.74174403904, 3828.1769419574925, 3882.0, 3905.726599138033, 3906.7069969752383, 3910.7262491625315, 4132.2561938851295, 4206.927233555424, 4212.251915455052, 4214.473190851144, 4639.536046395401, 956.7464621875208, 965.7151140413595, 982.111189373618, 982.7297493189287, 983.8130755156498, 644.7324360390479, 662.8210383196509, 670.0485310858593, 676.4149011105359, 681.9872489353306, 11472.139589530818, 11723.062155027577, 12152.513899443924, 12252.387380631111, 12395.256284622952, 644.2719726708789, 651.5341530805402, 654.4339146638124, 669.7856685860478, 684.1516519515765, 5582.441755824385, 5599.216109744639, 5822.417758224144, 5887.322957859816, 5927.881442371128, 149726.25136874247, 149744.25127874292, 150110.748338775, 151414.0, 153346.0, 126643.0, 127294.72705272828, 127958.24187911567, 128840.0, 132816.0, 103857.48071259595, 105274.0, 106191.4690426543, 106633.0, 106801.0, 10422.947885260524, 11122.944385278022, 11600.477978491157, 11644.2431241969, 11813.76372472563, 46579.0, 46811.5101583745, 47582.0, 47624.0, 48160.27759583679, 62257.37742622516, 62397.0, 62852.057219140836, 63438.92488600993, 63871.6806415965, 14510.492132775302, 14610.780838287646, 14928.402863885323, 14997.250137492863, 15013.249337533298, 15653.530394088195, 16125.032498050154, 16310.429134980694, 16356.18219089017, 16397.91801040987, 14872.925635371754, 15070.061338038977, 15301.08193508393, 15370.23148842531, 15379.38482460689, 13689.589312320646, 14963.925180374028, 16461.67076658484, 17140.228689708743, 17775.022373769567, 12889.355532223539, 14563.490277840649, 15072.54782356531, 15117.848821511645, 15208.391664333303, 11577.826332605186, 11602.013828824645, 12144.392780361124, 12330.691732706395, 12460.750784984431, 11810.704732381759, 13616.795748063589, 13669.726605468033, 14133.434662613792, 14135.363908623931], "value": 8467.402780890254, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1528.5, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [102.0, 116.0, 115.0, 120.0, 118.0, 114.0, 114.0, 115.0, 114.0, 115.0], "value": 114.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 137.0, 147.0, 182.0, 142.0, 95.0, 133.0, 149.0, 159.0, 118.0], "value": 140.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [200.0, 197.0, 197.0, 218.0, 220.0, 211.0, 210.0, 196.0, 196.0, 213.0], "value": 205.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 57.0, 63.0, 89.0, 64.0, 61.0, 67.0, 80.0, 85.0, 61.0], "value": 70.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 70.0, 82.0, 70.0, 72.0, 70.0, 87.0, 69.0, 74.0, 77.0], "value": 75.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [108.0, 130.0, 138.0, 90.0, 90.0, 90.0, 89.0, 104.0, 137.0, 90.0], "value": 106.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [98.0, 98.0, 98.0, 98.0, 97.0, 96.0, 99.0, 96.0, 97.0, 96.0], "value": 97.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 96.0, 92.0, 93.0, 92.0, 92.0, 90.0, 94.0, 92.0, 91.0], "value": 92.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [52.0, 52.0, 51.0, 53.0, 54.0, 53.0, 52.0, 51.0, 52.0, 51.0], "value": 52.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 63.0, 75.0, 82.0, 90.0, 60.0, 54.0, 57.0, 53.0, 61.0], "value": 65.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 89.0, 92.0, 90.0, 91.0, 89.0, 90.0, 90.0, 123.0, 87.0], "value": 93.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 156.0, 127.0, 135.0, 159.0, 155.0, 149.0, 148.0, 139.0, 163.0], "value": 147.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [190.0, 187.0, 193.0, 216.0, 188.0, 184.0, 190.0, 189.0, 187.0, 187.0], "value": 191.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 71.0, 69.0, 71.0, 86.0, 89.0, 96.0, 72.0, 70.0, 68.0], "value": 75.9, "unit": "score"}]}]} 11:08:19 INFO - Return code: 0 11:08:19 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 11:08:19 INFO - # TBPL SUCCESS # 11:08:19 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 11:08:19 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 11:08:19 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 11:08:19 INFO - 2017-06-01 11:08:19.584 screenresolution[1665:10926] starting screenresolution argv=screenresolution get 11:08:19 INFO - 2017-06-01 11:08:19.587 screenresolution[1665:10926] Display 0: 1600x1200x32@60 11:08:19 INFO - 2017-06-01 11:08:19.592 screenresolution[1666:10929] starting screenresolution argv=screenresolution list 11:08:19 INFO - Available Modes on Display 0 11:08:19 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 11:08:19 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 11:08:19 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 11:08:19 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 11:08:19 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 11:08:19 INFO - Intel Iris: 11:08:19 INFO - Chipset Model: Intel Iris 11:08:19 INFO - Type: GPU 11:08:19 INFO - Bus: Built-In 11:08:19 INFO - VRAM (Dynamic, Max): 1536 MB 11:08:19 INFO - Vendor: Intel (0x8086) 11:08:19 INFO - Device ID: 0x0a2e 11:08:19 INFO - Revision ID: 0x0009 11:08:19 INFO - Displays: 11:08:19 INFO - Display: 11:08:19 INFO - Resolution: 1600 x 1200 @ 60 Hz 11:08:19 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 11:08:19 INFO - Display Serial Number: 1600x1200 60 11:08:19 INFO - Main Display: Yes 11:08:19 INFO - Mirror: Off 11:08:19 INFO - Online: Yes 11:08:19 INFO - Rotation: Supported 11:08:19 INFO - Return code: 0 11:08:19 INFO - Running post-action listener: _resource_record_post_action 11:08:19 INFO - [mozharness: 2017-06-01 18:08:19.827167Z] Finished run-tests step (success) 11:08:19 INFO - Running post-run listener: _resource_record_post_run 11:08:19 INFO - Total resource usage - Wall time: 1257s; CPU: 54.0%; Read bytes: 39114240; Write bytes: 567867904; Read time: 286; Write time: 1769 11:08:19 INFO - TinderboxPrint: CPU usage
53.6% 11:08:19 INFO - TinderboxPrint: I/O read bytes / time
39,114,240 / 286 11:08:19 INFO - TinderboxPrint: I/O write bytes / time
567,867,904 / 1,769 11:08:19 INFO - TinderboxPrint: CPU idle
2,332.8 (46.4%) 11:08:19 INFO - TinderboxPrint: CPU system
1,020.7 (20.3%) 11:08:19 INFO - TinderboxPrint: CPU user
1,676.2 (33.3%) 11:08:19 INFO - TinderboxPrint: Swap in / out
284,618,752 / 0 11:08:19 INFO - install - Wall time: 22s; CPU: 55.0%; Read bytes: 247038464; Write bytes: 247866880; Read time: 17991; Write time: 514 11:08:19 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 11:08:19 INFO - run-tests - Wall time: 1235s; CPU: 54.0%; Read bytes: 36763136; Write bytes: 318620672; Read time: 261; Write time: 1248 11:08:20 INFO - Running post-run listener: _upload_blobber_files 11:08:20 INFO - Blob upload gear active. 11:08:20 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 11:08:20 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:08:20 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:08:20 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:08:20 INFO - (blobuploader) - INFO - Open directory for files ... 11:08:20 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 11:08:20 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:08:20 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:08:21 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 11:08:21 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:08:21 INFO - (blobuploader) - INFO - Done attempting. 11:08:21 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 11:08:21 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:08:21 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:08:22 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 11:08:22 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:08:22 INFO - (blobuploader) - INFO - Done attempting. 11:08:22 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 11:08:22 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:08:22 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:08:23 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 11:08:23 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:08:23 INFO - (blobuploader) - INFO - Done attempting. 11:08:23 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 11:08:23 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:08:23 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:08:25 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 11:08:25 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:08:25 INFO - (blobuploader) - INFO - Done attempting. 11:08:25 INFO - (blobuploader) - INFO - Iteration through files over. 11:08:25 INFO - Return code: 0 11:08:25 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 11:08:25 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 11:08:25 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/cc390ba3ad4e7923bf53f4925b683bdc6ddeca1b4dbe58951ac1238bd248994807ff028768d177ab3d68c6a065f70bded15c145b90e47072ab3ee9697469b7f2", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/037f6d6e9872ab070c1e972fdf8cbafd78a4a0bb254a273f7b9d8658a6730906bfd4b05bc4fc212dc32d36b050b00d9bc5341c7423977bb40e33ad0a019a3cbb", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/820d8167948c5d13d2c89c8cb780f2cf01adb4724944873d9ef5f4751f740d383ffc6800d2b6b903289bb07e41102ffdc50d6026e18419b312dc32344ee6b93d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/94d495ea84c0e223f40f212d51d7f5200fc8911d6eefc9b18f340e86d05507e7f267d60735fa72aa526a918eaf8b509524ef5bcf843ee808bb21af8e2a71d6dd"} 11:08:25 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 11:08:25 INFO - Writing to file /builds/slave/test/properties/blobber_files 11:08:25 INFO - Contents: 11:08:25 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/cc390ba3ad4e7923bf53f4925b683bdc6ddeca1b4dbe58951ac1238bd248994807ff028768d177ab3d68c6a065f70bded15c145b90e47072ab3ee9697469b7f2", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/037f6d6e9872ab070c1e972fdf8cbafd78a4a0bb254a273f7b9d8658a6730906bfd4b05bc4fc212dc32d36b050b00d9bc5341c7423977bb40e33ad0a019a3cbb", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/820d8167948c5d13d2c89c8cb780f2cf01adb4724944873d9ef5f4751f740d383ffc6800d2b6b903289bb07e41102ffdc50d6026e18419b312dc32344ee6b93d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/94d495ea84c0e223f40f212d51d7f5200fc8911d6eefc9b18f340e86d05507e7f267d60735fa72aa526a918eaf8b509524ef5bcf843ee808bb21af8e2a71d6dd"} 11:08:25 INFO - Running post-run listener: copy_logs_to_upload_dir 11:08:25 INFO - Copying logs to upload dir... 11:08:25 INFO - mkdir: /builds/slave/test/build/upload/logs 11:08:25 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1339.232578 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 19 secs) (at 2017-06-01 11:08:25.720234) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-01 11:08:25.724792) ========= 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.chpjD09hH9/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.u3Qo9TppPN/Listeners TMPDIR=/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/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/cc390ba3ad4e7923bf53f4925b683bdc6ddeca1b4dbe58951ac1238bd248994807ff028768d177ab3d68c6a065f70bded15c145b90e47072ab3ee9697469b7f2", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/037f6d6e9872ab070c1e972fdf8cbafd78a4a0bb254a273f7b9d8658a6730906bfd4b05bc4fc212dc32d36b050b00d9bc5341c7423977bb40e33ad0a019a3cbb", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/820d8167948c5d13d2c89c8cb780f2cf01adb4724944873d9ef5f4751f740d383ffc6800d2b6b903289bb07e41102ffdc50d6026e18419b312dc32344ee6b93d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/94d495ea84c0e223f40f212d51d7f5200fc8911d6eefc9b18f340e86d05507e7f267d60735fa72aa526a918eaf8b509524ef5bcf843ee808bb21af8e2a71d6dd"} build_url:https://queue.taskcluster.net/v1/task/alTWZOyQSKKYdui0xaoH4w/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011261 build_url: 'https://queue.taskcluster.net/v1/task/alTWZOyQSKKYdui0xaoH4w/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/cc390ba3ad4e7923bf53f4925b683bdc6ddeca1b4dbe58951ac1238bd248994807ff028768d177ab3d68c6a065f70bded15c145b90e47072ab3ee9697469b7f2", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/037f6d6e9872ab070c1e972fdf8cbafd78a4a0bb254a273f7b9d8658a6730906bfd4b05bc4fc212dc32d36b050b00d9bc5341c7423977bb40e33ad0a019a3cbb", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/820d8167948c5d13d2c89c8cb780f2cf01adb4724944873d9ef5f4751f740d383ffc6800d2b6b903289bb07e41102ffdc50d6026e18419b312dc32344ee6b93d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/94d495ea84c0e223f40f212d51d7f5200fc8911d6eefc9b18f340e86d05507e7f267d60735fa72aa526a918eaf8b509524ef5bcf843ee808bb21af8e2a71d6dd"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-01 11:08:25.759265) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-01 11:08:25.759604) ========= 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.chpjD09hH9/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.u3Qo9TppPN/Listeners TMPDIR=/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/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.005122 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-01 11:08:25.807741) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-01 11:08:25.808067) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-01 11:08:25.808409) ========= ========= Total master_lag: 0.18 =========