builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0365 starttime: 1495117253.29 results: success (0) revision: 2fa6931995b23f1f39752385b6689dc6b8d94c1b ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-05-18 07:20:53.285608) ========= master: http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-05-18 07:20:53.286038) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-18 07:20:53.286306) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-18 07:20:53.311193) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-18 07:20:53.311491) ========= 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.QSPwAg0hvc/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.fBo9SoWbbl/Listeners TMPDIR=/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-05-18 07:20:53-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 18.2M=0.001s 2017-05-18 07:20:53 (18.2 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.121028 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-18 07:20:53.456222) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-18 07:20:53.456730) ========= 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.QSPwAg0hvc/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.fBo9SoWbbl/Listeners TMPDIR=/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/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.026256 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-18 07:20:53.504778) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-18 07:20:53.505073) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 2fa6931995b23f1f39752385b6689dc6b8d94c1b --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 2fa6931995b23f1f39752385b6689dc6b8d94c1b --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.QSPwAg0hvc/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.fBo9SoWbbl/Listeners TMPDIR=/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-05-18 07:20:53,901 truncating revision to first 12 chars 2017-05-18 07:20:53,901 Setting DEBUG logging. 2017-05-18 07:20:53,901 attempt 1/10 2017-05-18 07:20:53,901 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/2fa6931995b2?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-05-18 07:20:55,008 unpacking tar archive at: mozilla-central-2fa6931995b2/testing/mozharness/ program finished with exit code 0 elapsedTime=1.389533 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-18 07:20:54.917803) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-18 07:20:54.918133) ========= script_repo_revision: 2fa6931995b23f1f39752385b6689dc6b8d94c1b ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-18 07:20:54.918509) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-18 07:20:54.918769) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-18 07:20:54.937574) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2017-05-18 07:20:54.937863) ========= /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.QSPwAg0hvc/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.fBo9SoWbbl/Listeners TMPDIR=/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/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 07:20:55 INFO - MultiFileLogger online at 20170518 07:20:55 in /builds/slave/test 07:20:55 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 07:20:55 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 07:20:55 INFO - {'append_to_log': False, 07:20:55 INFO - 'base_work_dir': '/builds/slave/test', 07:20:55 INFO - 'blob_upload_branch': 'Firefox', 07:20:55 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 07:20:55 INFO - 'branch': 'Firefox', 07:20:55 INFO - 'buildbot_json_path': 'buildprops.json', 07:20:55 INFO - 'config_files': ('talos/mac_config.py',), 07:20:55 INFO - 'default_actions': ('clobber', 07:20:55 INFO - 'read-buildbot-config', 07:20:55 INFO - 'download-and-extract', 07:20:55 INFO - 'populate-webroot', 07:20:55 INFO - 'create-virtualenv', 07:20:55 INFO - 'install', 07:20:55 INFO - 'run-tests'), 07:20:55 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 07:20:55 INFO - 'download_minidump_stackwalk': True, 07:20:55 INFO - 'download_symbols': 'ondemand', 07:20:55 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 07:20:55 INFO - 'tooltool.py': '/tools/tooltool.py', 07:20:55 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 07:20:55 INFO - '/tools/misc-python/virtualenv.py')}, 07:20:55 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 07:20:55 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 07:20:55 INFO - 'gecko_profile': False, 07:20:55 INFO - 'gecko_profile_interval': 0, 07:20:55 INFO - 'installer_path': 'installer.exe', 07:20:55 INFO - 'log_level': 'info', 07:20:55 INFO - 'log_name': 'talos', 07:20:55 INFO - 'log_to_console': True, 07:20:55 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 07:20:55 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 07:20:55 INFO - 'opt_config_files': (), 07:20:55 INFO - 'pip_index': False, 07:20:55 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 07:20:55 INFO - 'cmd': ('bash', 07:20:55 INFO - '-c', 07:20:55 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 07:20:55 INFO - 'enabled': True, 07:20:55 INFO - 'halt_on_failure': False, 07:20:55 INFO - 'name': 'check_screen_resolution'},), 07:20:55 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 07:20:55 INFO - 'cmd': ('bash', 07:20:55 INFO - '-c', 07:20:55 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 07:20:55 INFO - 'enabled': True, 07:20:55 INFO - 'halt_on_failure': False, 07:20:55 INFO - 'name': 'check_screen_resolution'},), 07:20:55 INFO - 'run_cmd_checks_enabled': True, 07:20:55 INFO - 'suite': 'dromaeojs-e10s', 07:20:55 INFO - 'system_bits': '32', 07:20:55 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 07:20:55 INFO - 'title': 't-yosemite-r7-0365', 07:20:55 INFO - 'tooltool_cache': '/builds/tooltool_cache', 07:20:55 INFO - 'use_talos_json': True, 07:20:55 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 07:20:55 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 07:20:55 INFO - 'work_dir': 'build'} 07:20:55 INFO - [mozharness: 2017-05-18 14:20:55.443783Z] Running clobber step. 07:20:55 INFO - Running pre-action listener: _resource_record_pre_action 07:20:55 INFO - Running main action method: clobber 07:20:55 INFO - rmtree: /builds/slave/test/build 07:20:55 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 07:20:57 INFO - Running post-action listener: _resource_record_post_action 07:20:57 INFO - [mozharness: 2017-05-18 14:20:57.847155Z] Finished clobber step (success) 07:20:57 INFO - [mozharness: 2017-05-18 14:20:57.847315Z] Running read-buildbot-config step. 07:20:57 INFO - Running pre-action listener: _resource_record_pre_action 07:20:57 INFO - Running main action method: read_buildbot_config 07:20:57 INFO - Using buildbot properties: 07:20:57 INFO - { 07:20:57 INFO - "project": "", 07:20:57 INFO - "product": "firefox", 07:20:57 INFO - "who": "cbook@mozilla.com", 07:20:57 INFO - "installer_path": "public/build/firefox-55.0a1.en-US.mac.dmg", 07:20:57 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 07:20:57 INFO - "repository": "https://hg.mozilla.org/mozilla-central/", 07:20:57 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 07:20:57 INFO - "stage_platform": "macosx64", 07:20:57 INFO - "basedir": "/builds/slave/test", 07:20:57 INFO - "buildnumber": 28, 07:20:57 INFO - "platform": "yosemite_r7", 07:20:57 INFO - "parent_task_id": "F4Q5KJeCSiagQpQf34mxHg", 07:20:57 INFO - "master": "http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/", 07:20:57 INFO - "slavebuilddir": "test", 07:20:57 INFO - "taskId": "XkjLYDAhQyiiqUpzq0VT1A", 07:20:57 INFO - "branch": "mozilla-central", 07:20:57 INFO - "script_repo_revision": "production", 07:20:57 INFO - "revision": "2fa6931995b23f1f39752385b6689dc6b8d94c1b", 07:20:57 INFO - "slavename": "t-yosemite-r7-0365", 07:20:57 INFO - "repo_path": "mozilla-central" 07:20:57 INFO - } 07:20:57 INFO - Finding installer, test and symbols from parent task. 07:20:57 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/XkjLYDAhQyiiqUpzq0VT1A'}, attempt #1 07:20:58 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/XkjLYDAhQyiiqUpzq0VT1A'}, attempt #1 07:20:58 INFO - Task dependencies: JdSKXK5VQ-mB1tlC-tHtyw F4Q5KJeCSiagQpQf34mxHg 07:20:58 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/F4Q5KJeCSiagQpQf34mxHg'}, attempt #1 07:20:59 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/F4Q5KJeCSiagQpQf34mxHg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 07:20:59 INFO - Set test_url: https://queue.taskcluster.net/v1/task/F4Q5KJeCSiagQpQf34mxHg/artifacts/public/build/test_packages.json 07:20:59 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/F4Q5KJeCSiagQpQf34mxHg/artifacts/public/build/target.crashreporter-symbols.zip 07:20:59 INFO - Running post-action listener: _resource_record_post_action 07:20:59 INFO - [mozharness: 2017-05-18 14:20:59.261805Z] Finished read-buildbot-config step (success) 07:20:59 INFO - [mozharness: 2017-05-18 14:20:59.261926Z] Running download-and-extract step. 07:20:59 INFO - Running pre-action listener: _resource_record_pre_action 07:20:59 INFO - Running main action method: download_and_extract 07:20:59 INFO - mkdir: /builds/slave/test/build/tests 07:20:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:20:59 INFO - trying https://queue.taskcluster.net/v1/task/F4Q5KJeCSiagQpQf34mxHg/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 07:20:59 INFO - Downloading https://queue.taskcluster.net/v1/task/F4Q5KJeCSiagQpQf34mxHg/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 07:20:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/F4Q5KJeCSiagQpQf34mxHg/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 07:20:59 INFO - Downloaded 1325 bytes. 07:20:59 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 07:20:59 INFO - Using the following test package requirements: 07:20:59 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 07:20:59 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 07:20:59 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 07:20:59 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 07:20:59 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 07:20:59 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 07:20:59 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 07:20:59 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 07:20:59 INFO - u'jsshell-mac.zip'], 07:20:59 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 07:20:59 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 07:20:59 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 07:20:59 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 07:20:59 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 07:20:59 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 07:20:59 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 07:20:59 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 07:20:59 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 07:20:59 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 07:20:59 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 07:20:59 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 07:20:59 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/F4Q5KJeCSiagQpQf34mxHg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 07:20:59 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/F4Q5KJeCSiagQpQf34mxHg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 07:20:59 INFO - Fetch https://queue.taskcluster.net/v1/task/F4Q5KJeCSiagQpQf34mxHg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 07:21:01 INFO - Content-Length response header: 56568498 07:21:01 INFO - Bytes received: 56568498 07:21:19 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 07:21:19 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/F4Q5KJeCSiagQpQf34mxHg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 07:21:19 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/F4Q5KJeCSiagQpQf34mxHg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 07:21:19 INFO - Fetch https://queue.taskcluster.net/v1/task/F4Q5KJeCSiagQpQf34mxHg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 07:21:21 INFO - Content-Length response header: 56568498 07:21:21 INFO - Bytes received: 56568498 07:21:38 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/F4Q5KJeCSiagQpQf34mxHg/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 07:21:38 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/F4Q5KJeCSiagQpQf34mxHg/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 07:21:38 INFO - Fetch https://queue.taskcluster.net/v1/task/F4Q5KJeCSiagQpQf34mxHg/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 07:21:39 INFO - Content-Length response header: 14410001 07:21:39 INFO - Bytes received: 14410001 07:21:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:21:39 INFO - trying https://queue.taskcluster.net/v1/task/F4Q5KJeCSiagQpQf34mxHg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 07:21:39 INFO - Downloading https://queue.taskcluster.net/v1/task/F4Q5KJeCSiagQpQf34mxHg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 07:21:39 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/F4Q5KJeCSiagQpQf34mxHg/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 07:21:41 INFO - Downloaded 71664047 bytes. 07:21:41 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/F4Q5KJeCSiagQpQf34mxHg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 07:21:41 INFO - mkdir: /builds/slave/test/properties 07:21:41 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 07:21:41 INFO - Writing to file /builds/slave/test/properties/build_url 07:21:41 INFO - Contents: 07:21:41 INFO - build_url:https://queue.taskcluster.net/v1/task/F4Q5KJeCSiagQpQf34mxHg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 07:21:41 INFO - Running post-action listener: _resource_record_post_action 07:21:41 INFO - Running post-action listener: set_extra_try_arguments 07:21:41 INFO - [mozharness: 2017-05-18 14:21:41.905703Z] Finished download-and-extract step (success) 07:21:41 INFO - [mozharness: 2017-05-18 14:21:41.905844Z] Running populate-webroot step. 07:21:41 INFO - Running pre-action listener: _resource_record_pre_action 07:21:41 INFO - Running main action method: populate_webroot 07:21:41 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 07:21:41 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 07:21:41 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 07:21:41 INFO - '--tppagecycles', 07:21:41 INFO - '7'], 07:21:41 INFO - 'tests': ['tsvgm']}}, 07:21:41 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 07:21:41 INFO - 'tests': ['tresize', 'tcanvasmark']}, 07:21:41 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 07:21:41 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 07:21:41 INFO - 'tests': ['dromaeo_css', 'kraken']}, 07:21:41 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 07:21:41 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 07:21:41 INFO - 'talos_options': ['--disable-e10s'], 07:21:41 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:21:41 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 07:21:41 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:21:41 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 07:21:41 INFO - 'talos_options': ['--disable-e10s'], 07:21:41 INFO - 'tests': ['damp', 'tps']}, 07:21:41 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 07:21:41 INFO - 'tests': ['damp', 'tps']}, 07:21:41 INFO - 'g3': {'talos_options': ['--disable-e10s'], 07:21:41 INFO - 'tests': ['dromaeo_dom']}, 07:21:41 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 07:21:41 INFO - 'g4': {'talos_options': ['--disable-e10s'], 07:21:41 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 07:21:41 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 07:21:41 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 07:21:41 INFO - 'tests': ['ts_paint', 'tp5o']}, 07:21:41 INFO - 'other': {'talos_options': ['--disable-e10s'], 07:21:41 INFO - 'tests': ['a11yr', 07:21:41 INFO - 'ts_paint', 07:21:41 INFO - 'tpaint', 07:21:41 INFO - 'sessionrestore', 07:21:41 INFO - 'sessionrestore_no_auto_restore', 07:21:41 INFO - 'tabpaint']}, 07:21:41 INFO - 'other-e10s': {'tests': ['a11yr', 07:21:41 INFO - 'ts_paint', 07:21:41 INFO - 'tpaint', 07:21:41 INFO - 'sessionrestore', 07:21:41 INFO - 'sessionrestore_no_auto_restore', 07:21:41 INFO - 'tabpaint']}, 07:21:41 INFO - 'other-e10s_l64': {'tests': ['a11yr', 07:21:41 INFO - 'ts_paint', 07:21:41 INFO - 'tpaint', 07:21:41 INFO - 'sessionrestore', 07:21:41 INFO - 'sessionrestore_no_auto_restore', 07:21:41 INFO - 'tabpaint']}, 07:21:41 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 07:21:41 INFO - 'ts_paint', 07:21:41 INFO - 'tpaint', 07:21:41 INFO - 'sessionrestore', 07:21:41 INFO - 'sessionrestore_no_auto_restore', 07:21:41 INFO - 'tabpaint']}, 07:21:41 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 07:21:41 INFO - 'tests': ['a11yr', 07:21:41 INFO - 'ts_paint', 07:21:41 INFO - 'tpaint', 07:21:41 INFO - 'sessionrestore', 07:21:41 INFO - 'sessionrestore_no_auto_restore', 07:21:41 INFO - 'tabpaint']}, 07:21:41 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 07:21:41 INFO - 'tests': ['a11yr', 07:21:41 INFO - 'ts_paint', 07:21:41 INFO - 'tpaint', 07:21:41 INFO - 'sessionrestore', 07:21:41 INFO - 'sessionrestore_no_auto_restore', 07:21:41 INFO - 'tabpaint']}, 07:21:41 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 07:21:41 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 07:21:41 INFO - 'bloom_basic_ref']}, 07:21:41 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 07:21:41 INFO - 'tests': ['tsvgx', 07:21:41 INFO - 'tsvgr_opacity', 07:21:41 INFO - 'tart', 07:21:41 INFO - 'tscrollx', 07:21:41 INFO - 'cart', 07:21:41 INFO - 'tsvg_static']}, 07:21:41 INFO - 'svgr-e10s': {'tests': ['tsvgx', 07:21:41 INFO - 'tsvgr_opacity', 07:21:41 INFO - 'tart', 07:21:41 INFO - 'tscrollx', 07:21:41 INFO - 'cart', 07:21:41 INFO - 'tsvg_static']}, 07:21:41 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 07:21:41 INFO - 'talos_options': ['--disable-e10s'], 07:21:41 INFO - 'tests': ['tp5o']}, 07:21:41 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 07:21:41 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 07:21:41 INFO - 'talos_options': ['--disable-e10s', 07:21:41 INFO - '--xperf_path', 07:21:41 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:21:41 INFO - 'tests': ['tp5n']}, 07:21:41 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 07:21:41 INFO - 'talos_options': ['--xperf_path', 07:21:41 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:21:41 INFO - 'tests': ['tp5n']}}, 07:21:41 INFO - 'talos.zip': {'path': '', 07:21:41 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 07:21:41 INFO - Running post-action listener: _resource_record_post_action 07:21:41 INFO - [mozharness: 2017-05-18 14:21:41.927660Z] Finished populate-webroot step (success) 07:21:41 INFO - [mozharness: 2017-05-18 14:21:41.927795Z] Running create-virtualenv step. 07:21:41 INFO - Running pre-action listener: _resource_record_pre_action 07:21:41 INFO - Running main action method: create_virtualenv 07:21:41 INFO - Creating virtualenv /builds/slave/test/build/venv 07:21:41 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 07:21:41 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 07:21:42 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 07:21:42 INFO - Using real prefix '/tools/python27' 07:21:42 INFO - New python executable in /builds/slave/test/build/venv/bin/python 07:21:42 INFO - Installing distribute.............................................................................................................................................................................................done. 07:21:45 INFO - Installing pip.................done. 07:21:45 INFO - Return code: 0 07:21:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 07:21:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 07:21:45 INFO - Reading from file tmpfile_stdout 07:21:45 INFO - Output received: 07:21:45 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) 07:21:45 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 07:21:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:21:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:21:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:21:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:21:45 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1076c1b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1076c5768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc83ac78860>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1073d1d30>, '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 0x107385db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1073c6be8>, '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.QSPwAg0hvc/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.fBo9SoWbbl/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/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 07:21:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 07:21:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 07:21:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QSPwAg0hvc/Render', 07:21:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:21:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:21:45 INFO - 'HOME': '/Users/cltbld', 07:21:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:21:45 INFO - 'LOGNAME': 'cltbld', 07:21:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:21:45 INFO - 'MOZ_NO_REMOTE': '1', 07:21:45 INFO - 'NO_EM_RESTART': '1', 07:21:45 INFO - 'PAGER': '/bin/cat', 07:21:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:21:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:21:45 INFO - 'PWD': '/builds/slave/test', 07:21:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:21:45 INFO - 'SHELL': '/bin/bash', 07:21:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fBo9SoWbbl/Listeners', 07:21:45 INFO - 'TMPDIR': '/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/', 07:21:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:21:45 INFO - 'USER': 'cltbld', 07:21:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:21:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:21:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:21:45 INFO - 'XPC_FLAGS': '0x0', 07:21:45 INFO - 'XPC_SERVICE_NAME': '0', 07:21:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:21:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:21:46 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 07:21:46 INFO - Cleaning up... 07:21:46 INFO - Return code: 0 07:21:46 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 07:21:46 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')]} 07:21:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:21:46 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')]} 07:21:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:21:46 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 0x1076c1b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1076c5768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc83ac78860>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1073d1d30>, '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 0x107385db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1073c6be8>, '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.QSPwAg0hvc/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.fBo9SoWbbl/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/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 07:21:46 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 07:21:46 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 07:21:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QSPwAg0hvc/Render', 07:21:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:21:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:21:46 INFO - 'HOME': '/Users/cltbld', 07:21:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:21:46 INFO - 'LOGNAME': 'cltbld', 07:21:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:21:46 INFO - 'MOZ_NO_REMOTE': '1', 07:21:46 INFO - 'NO_EM_RESTART': '1', 07:21:46 INFO - 'PAGER': '/bin/cat', 07:21:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:21:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:21:46 INFO - 'PWD': '/builds/slave/test', 07:21:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:21:46 INFO - 'SHELL': '/bin/bash', 07:21:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fBo9SoWbbl/Listeners', 07:21:46 INFO - 'TMPDIR': '/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/', 07:21:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:21:46 INFO - 'USER': 'cltbld', 07:21:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:21:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:21:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:21:46 INFO - 'XPC_FLAGS': '0x0', 07:21:46 INFO - 'XPC_SERVICE_NAME': '0', 07:21:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:21:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:21:46 INFO - Downloading/unpacking psutil>=3.1.1 07:21:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:21:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:21:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:21:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:21:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 07:21:48 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:21:48 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:21:48 INFO - Installing collected packages: psutil 07:21:48 INFO - Running setup.py install for psutil 07:21:48 INFO - building 'psutil._psutil_osx' extension 07:21:48 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 07:21:49 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 07:21:49 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 07:21:49 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 07:21:49 INFO - building 'psutil._psutil_posix' extension 07:21:49 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 07:21:49 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 07:21:49 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 07:21:49 INFO - ^ 07:21:49 INFO - 1 warning generated. 07:21:49 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 07:21:49 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:21:49 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:21:49 INFO - Successfully installed psutil 07:21:49 INFO - Cleaning up... 07:21:49 INFO - Return code: 0 07:21:49 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 07:21:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:21:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:21:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:21:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:21:49 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1076c1b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1076c5768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc83ac78860>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1073d1d30>, '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 0x107385db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1073c6be8>, '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.QSPwAg0hvc/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.fBo9SoWbbl/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/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 07:21:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 07:21:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 07:21:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QSPwAg0hvc/Render', 07:21:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:21:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:21:49 INFO - 'HOME': '/Users/cltbld', 07:21:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:21:49 INFO - 'LOGNAME': 'cltbld', 07:21:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:21:49 INFO - 'MOZ_NO_REMOTE': '1', 07:21:49 INFO - 'NO_EM_RESTART': '1', 07:21:49 INFO - 'PAGER': '/bin/cat', 07:21:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:21:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:21:49 INFO - 'PWD': '/builds/slave/test', 07:21:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:21:49 INFO - 'SHELL': '/bin/bash', 07:21:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fBo9SoWbbl/Listeners', 07:21:49 INFO - 'TMPDIR': '/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/', 07:21:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:21:49 INFO - 'USER': 'cltbld', 07:21:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:21:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:21:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:21:49 INFO - 'XPC_FLAGS': '0x0', 07:21:49 INFO - 'XPC_SERVICE_NAME': '0', 07:21:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:21:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:21:50 INFO - Downloading/unpacking mozsystemmonitor==0.3 07:21:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:21:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:21:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:21:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:21:51 INFO - Downloading mozsystemmonitor-0.3.tar.gz 07:21:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 07:21:51 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 07:21:51 INFO - Installing collected packages: mozsystemmonitor 07:21:51 INFO - Running setup.py install for mozsystemmonitor 07:21:51 INFO - Successfully installed mozsystemmonitor 07:21:51 INFO - Cleaning up... 07:21:51 INFO - Return code: 0 07:21:51 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 07:21:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:21:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:21:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:21:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:21:51 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1076c1b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1076c5768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc83ac78860>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1073d1d30>, '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 0x107385db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1073c6be8>, '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.QSPwAg0hvc/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.fBo9SoWbbl/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/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 07:21:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 07:21:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 07:21:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QSPwAg0hvc/Render', 07:21:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:21:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:21:51 INFO - 'HOME': '/Users/cltbld', 07:21:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:21:51 INFO - 'LOGNAME': 'cltbld', 07:21:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:21:51 INFO - 'MOZ_NO_REMOTE': '1', 07:21:51 INFO - 'NO_EM_RESTART': '1', 07:21:51 INFO - 'PAGER': '/bin/cat', 07:21:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:21:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:21:51 INFO - 'PWD': '/builds/slave/test', 07:21:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:21:51 INFO - 'SHELL': '/bin/bash', 07:21:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fBo9SoWbbl/Listeners', 07:21:51 INFO - 'TMPDIR': '/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/', 07:21:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:21:51 INFO - 'USER': 'cltbld', 07:21:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:21:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:21:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:21:51 INFO - 'XPC_FLAGS': '0x0', 07:21:51 INFO - 'XPC_SERVICE_NAME': '0', 07:21:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:21:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:21:52 INFO - Downloading/unpacking jsonschema==2.5.1 07:21:52 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 07:21:52 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 07:21:52 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 07:21:52 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 07:21:53 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 07:21:53 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 07:21:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:21:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:21:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:21:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:21:53 INFO - Downloading functools32-3.2.3-2.tar.gz 07:21:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 07:21:53 INFO - warning: no files found matching '*.txt' 07:21:53 INFO - no previously-included directories found matching 'build' 07:21:53 INFO - no previously-included directories found matching 'dist' 07:21:53 INFO - no previously-included directories found matching '.git*' 07:21:53 INFO - Installing collected packages: jsonschema, functools32 07:21:53 INFO - Running setup.py install for functools32 07:21:54 INFO - warning: no files found matching '*.txt' 07:21:54 INFO - no previously-included directories found matching 'build' 07:21:54 INFO - no previously-included directories found matching 'dist' 07:21:54 INFO - no previously-included directories found matching '.git*' 07:21:54 INFO - Successfully installed jsonschema functools32 07:21:54 INFO - Cleaning up... 07:21:54 INFO - Return code: 0 07:21:54 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 07:21:54 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')]} 07:21:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:21:54 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')]} 07:21:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:21:54 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 0x1076c1b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1076c5768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc83ac78860>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1073d1d30>, '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 0x107385db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1073c6be8>, '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.QSPwAg0hvc/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.fBo9SoWbbl/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/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 07:21:54 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 07:21:54 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 07:21:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QSPwAg0hvc/Render', 07:21:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:21:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:21:54 INFO - 'HOME': '/Users/cltbld', 07:21:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:21:54 INFO - 'LOGNAME': 'cltbld', 07:21:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:21:54 INFO - 'MOZ_NO_REMOTE': '1', 07:21:54 INFO - 'NO_EM_RESTART': '1', 07:21:54 INFO - 'PAGER': '/bin/cat', 07:21:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:21:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:21:54 INFO - 'PWD': '/builds/slave/test', 07:21:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:21:54 INFO - 'SHELL': '/bin/bash', 07:21:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fBo9SoWbbl/Listeners', 07:21:54 INFO - 'TMPDIR': '/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/', 07:21:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:21:54 INFO - 'USER': 'cltbld', 07:21:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:21:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:21:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:21:54 INFO - 'XPC_FLAGS': '0x0', 07:21:54 INFO - 'XPC_SERVICE_NAME': '0', 07:21:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:21:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:21:54 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 07:21:54 INFO - Cleaning up... 07:21:54 INFO - Return code: 0 07:21:54 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 07:21:54 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')]} 07:21:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:21:54 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')]} 07:21:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:21:54 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 0x1076c1b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1076c5768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc83ac78860>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1073d1d30>, '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 0x107385db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1073c6be8>, '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.QSPwAg0hvc/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.fBo9SoWbbl/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/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 07:21:54 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 07:21:54 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 07:21:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QSPwAg0hvc/Render', 07:21:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:21:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:21:54 INFO - 'HOME': '/Users/cltbld', 07:21:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:21:54 INFO - 'LOGNAME': 'cltbld', 07:21:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:21:54 INFO - 'MOZ_NO_REMOTE': '1', 07:21:54 INFO - 'NO_EM_RESTART': '1', 07:21:54 INFO - 'PAGER': '/bin/cat', 07:21:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:21:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:21:54 INFO - 'PWD': '/builds/slave/test', 07:21:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:21:54 INFO - 'SHELL': '/bin/bash', 07:21:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fBo9SoWbbl/Listeners', 07:21:54 INFO - 'TMPDIR': '/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/', 07:21:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:21:54 INFO - 'USER': 'cltbld', 07:21:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:21:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:21:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:21:54 INFO - 'XPC_FLAGS': '0x0', 07:21:54 INFO - 'XPC_SERVICE_NAME': '0', 07:21:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:21:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:21:54 INFO - Downloading/unpacking blobuploader==1.2.4 07:21:54 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 07:21:54 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 07:21:54 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 07:21:54 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 07:21:56 INFO - Downloading blobuploader-1.2.4.tar.gz 07:21:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 07:21:56 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 07:21:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:21:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:21:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:21:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:21:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 07:21:56 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 07:21:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:21:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:21:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:21:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:21:56 INFO - Downloading docopt-0.6.1.tar.gz 07:21:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 07:21:57 INFO - Installing collected packages: blobuploader, requests, docopt 07:21:57 INFO - Running setup.py install for blobuploader 07:21:57 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 07:21:57 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 07:21:57 INFO - Running setup.py install for requests 07:21:57 INFO - Running setup.py install for docopt 07:21:57 INFO - Successfully installed blobuploader requests docopt 07:21:57 INFO - Cleaning up... 07:21:57 INFO - Return code: 0 07:21:57 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:21:57 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')]} 07:21:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:21:57 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')]} 07:21:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:21:57 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 0x1076c1b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1076c5768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc83ac78860>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1073d1d30>, '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 0x107385db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1073c6be8>, '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.QSPwAg0hvc/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.fBo9SoWbbl/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/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 07:21:57 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 07:21:57 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 07:21:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QSPwAg0hvc/Render', 07:21:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:21:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:21:57 INFO - 'HOME': '/Users/cltbld', 07:21:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:21:57 INFO - 'LOGNAME': 'cltbld', 07:21:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:21:57 INFO - 'MOZ_NO_REMOTE': '1', 07:21:57 INFO - 'NO_EM_RESTART': '1', 07:21:57 INFO - 'PAGER': '/bin/cat', 07:21:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:21:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:21:57 INFO - 'PWD': '/builds/slave/test', 07:21:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:21:57 INFO - 'SHELL': '/bin/bash', 07:21:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fBo9SoWbbl/Listeners', 07:21:57 INFO - 'TMPDIR': '/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/', 07:21:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:21:57 INFO - 'USER': 'cltbld', 07:21:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:21:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:21:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:21:57 INFO - 'XPC_FLAGS': '0x0', 07:21:57 INFO - 'XPC_SERVICE_NAME': '0', 07:21:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:21:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:21:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:21:58 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-IeI8ld-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:21:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:21:58 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-WMOCU2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:21:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:21:58 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-Jd1wa_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:21:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:21:58 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-Fy1S46-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:21:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:21:58 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-Jsj2A0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:21:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:21:58 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-YP1pKJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:21:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:21:58 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-pUXWUH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:21:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:21:58 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-B0zZB0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:21:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:21:58 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-yO9D29-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:21:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:21:59 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-8LoKS3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:21:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:21:59 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-C57g_U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:21:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:21:59 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-1Sfqu6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:21:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:21:59 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-NfrEU8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:21:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:21:59 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-tpX1XE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:21:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:21:59 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-KD94au-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:21:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:21:59 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-skZSpQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:21:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:21:59 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-CyXJHK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:21:59 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 07:21:59 INFO - Running setup.py install for manifestparser 07:22:00 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 07:22:00 INFO - Running setup.py install for mozcrash 07:22:00 INFO - Running setup.py install for mozdebug 07:22:00 INFO - Running setup.py install for mozdevice 07:22:00 INFO - Installing dm script to /builds/slave/test/build/venv/bin 07:22:00 INFO - Running setup.py install for mozfile 07:22:00 INFO - Running setup.py install for mozhttpd 07:22:00 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 07:22:00 INFO - Running setup.py install for mozinfo 07:22:00 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 07:22:00 INFO - Running setup.py install for mozInstall 07:22:01 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 07:22:01 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 07:22:01 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 07:22:01 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 07:22:01 INFO - Running setup.py install for mozleak 07:22:01 INFO - Running setup.py install for mozlog 07:22:01 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 07:22:01 INFO - Running setup.py install for moznetwork 07:22:01 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 07:22:01 INFO - Running setup.py install for mozprocess 07:22:01 INFO - Running setup.py install for mozprofile 07:22:02 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 07:22:02 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 07:22:02 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 07:22:02 INFO - Running setup.py install for mozrunner 07:22:02 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 07:22:02 INFO - Running setup.py install for mozscreenshot 07:22:02 INFO - Running setup.py install for moztest 07:22:02 INFO - Running setup.py install for mozversion 07:22:02 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 07:22:02 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 07:22:02 INFO - Cleaning up... 07:22:02 INFO - Return code: 0 07:22:02 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:22: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')]} 07:22:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:22: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')]} 07:22:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:22:02 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 0x1076c1b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1076c5768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc83ac78860>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1073d1d30>, '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 0x107385db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1073c6be8>, '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.QSPwAg0hvc/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.fBo9SoWbbl/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/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 07:22:02 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 07:22:02 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 07:22:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QSPwAg0hvc/Render', 07:22:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:22:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:22:02 INFO - 'HOME': '/Users/cltbld', 07:22:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:22:02 INFO - 'LOGNAME': 'cltbld', 07:22:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:22:02 INFO - 'MOZ_NO_REMOTE': '1', 07:22:02 INFO - 'NO_EM_RESTART': '1', 07:22:02 INFO - 'PAGER': '/bin/cat', 07:22:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:22:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:22:02 INFO - 'PWD': '/builds/slave/test', 07:22:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:22:02 INFO - 'SHELL': '/bin/bash', 07:22:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fBo9SoWbbl/Listeners', 07:22:02 INFO - 'TMPDIR': '/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/', 07:22:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:22:02 INFO - 'USER': 'cltbld', 07:22:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:22:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:22:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:22:02 INFO - 'XPC_FLAGS': '0x0', 07:22:02 INFO - 'XPC_SERVICE_NAME': '0', 07:22:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:22:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:22:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:22:03 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-z3Wv0q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:22:03 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)) 07:22:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:22:03 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-44l8Qv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:22:03 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)) 07:22:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:22:03 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-ezmY9b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:22:03 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)) 07:22:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:22:03 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-1a4XIy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:22:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.50 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 07:22:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:22:03 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-ETUr0u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:22:03 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)) 07:22:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:22:03 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-JCJeE6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:22:03 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)) 07:22:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:22:03 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-fvUkx7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:22:03 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)) 07:22:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:22:03 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-PtLGxc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:22:03 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)) 07:22:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:22:03 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-mIsUdK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:22:03 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)) 07:22:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:22:03 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-L_GtZX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:22:04 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)) 07:22:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:22:04 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-F0OkpB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:22:04 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)) 07:22:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:22:04 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-Wl0hR3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:22:04 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)) 07:22:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:22:04 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-R8Ugm6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:22:04 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)) 07:22:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:22:04 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-fAyeRr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:22:04 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)) 07:22:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:22:04 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-zZJpv5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:22:04 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)) 07:22:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:22:04 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-hLZ2dO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:22:04 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)) 07:22:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:22:04 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-7C0foy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:22:04 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)) 07:22:04 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)) 07:22:04 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)) 07:22:04 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)) 07:22:04 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.50->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 07:22:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.50->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 07:22:04 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 07:22:04 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 07:22:04 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 07:22:04 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 07:22:04 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 07:22:06 INFO - Downloading blessings-1.6.tar.gz 07:22:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 07:22:06 INFO - Installing collected packages: blessings 07:22:06 INFO - Running setup.py install for blessings 07:22:06 INFO - Successfully installed blessings 07:22:06 INFO - Cleaning up... 07:22:06 INFO - Return code: 0 07:22:06 INFO - Done creating virtualenv /builds/slave/test/build/venv. 07:22:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:22:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:22:06 INFO - Reading from file tmpfile_stdout 07:22:06 INFO - Current package versions: 07:22:06 INFO - blessings == 1.6 07:22:06 INFO - blobuploader == 1.2.4 07:22:06 INFO - docopt == 0.6.1 07:22:06 INFO - functools32 == 3.2.3-2 07:22:06 INFO - jsonschema == 2.5.1 07:22:06 INFO - manifestparser == 1.1 07:22:06 INFO - mozInstall == 1.12 07:22:06 INFO - mozcrash == 1.0 07:22:06 INFO - mozdebug == 0.1 07:22:06 INFO - mozdevice == 0.50 07:22:06 INFO - mozfile == 1.2 07:22:06 INFO - mozhttpd == 0.7 07:22:06 INFO - mozinfo == 0.9 07:22:06 INFO - mozleak == 0.1 07:22:06 INFO - mozlog == 3.4 07:22:06 INFO - moznetwork == 0.27 07:22:06 INFO - mozprocess == 0.25 07:22:06 INFO - mozprofile == 0.28 07:22:06 INFO - mozrunner == 6.13 07:22:06 INFO - mozscreenshot == 0.1 07:22:06 INFO - mozsystemmonitor == 0.3 07:22:06 INFO - moztest == 0.8 07:22:06 INFO - mozversion == 1.4 07:22:06 INFO - psutil == 3.1.1 07:22:06 INFO - requests == 1.2.3 07:22:06 INFO - wsgiref == 0.1.2 07:22:06 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:22:06 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')]} 07:22:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:22:06 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')]} 07:22:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:22:06 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 0x1076c1b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1076c5768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc83ac78860>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1073d1d30>, '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 0x107385db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1073c6be8>, '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.QSPwAg0hvc/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.fBo9SoWbbl/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/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 07:22:06 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 07:22:06 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 07:22:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QSPwAg0hvc/Render', 07:22:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:22:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:22:06 INFO - 'HOME': '/Users/cltbld', 07:22:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:22:06 INFO - 'LOGNAME': 'cltbld', 07:22:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:22:06 INFO - 'MOZ_NO_REMOTE': '1', 07:22:06 INFO - 'NO_EM_RESTART': '1', 07:22:06 INFO - 'PAGER': '/bin/cat', 07:22:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:22:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:22:06 INFO - 'PWD': '/builds/slave/test', 07:22:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:22:06 INFO - 'SHELL': '/bin/bash', 07:22:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fBo9SoWbbl/Listeners', 07:22:06 INFO - 'TMPDIR': '/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/', 07:22:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:22:06 INFO - 'USER': 'cltbld', 07:22:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:22:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:22:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:22:06 INFO - 'XPC_FLAGS': '0x0', 07:22:06 INFO - 'XPC_SERVICE_NAME': '0', 07:22:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:22:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:22:07 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)) 07:22:07 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)) 07:22:07 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)) 07:22:07 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)) 07:22:07 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)) 07:22:07 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)) 07:22:07 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)) 07:22:07 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)) 07:22:07 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)) 07:22:07 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)) 07:22:07 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)) 07:22:07 INFO - Cleaning up... 07:22:07 INFO - Return code: 0 07:22:07 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 07:22: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')]} 07:22:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:22: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')]} 07:22:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:22: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', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1076c1b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1076c5768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc83ac78860>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1073d1d30>, '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 0x107385db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1073c6be8>, '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.QSPwAg0hvc/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.fBo9SoWbbl/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/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 07:22: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', 'jsonschema'] in /builds/slave/test/build 07:22: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 jsonschema 07:22:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QSPwAg0hvc/Render', 07:22:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:22:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:22:07 INFO - 'HOME': '/Users/cltbld', 07:22:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:22:07 INFO - 'LOGNAME': 'cltbld', 07:22:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:22:07 INFO - 'MOZ_NO_REMOTE': '1', 07:22:07 INFO - 'NO_EM_RESTART': '1', 07:22:07 INFO - 'PAGER': '/bin/cat', 07:22:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:22:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:22:07 INFO - 'PWD': '/builds/slave/test', 07:22:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:22:07 INFO - 'SHELL': '/bin/bash', 07:22:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fBo9SoWbbl/Listeners', 07:22:07 INFO - 'TMPDIR': '/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/', 07:22:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:22:07 INFO - 'USER': 'cltbld', 07:22:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:22:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:22:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:22:07 INFO - 'XPC_FLAGS': '0x0', 07:22:07 INFO - 'XPC_SERVICE_NAME': '0', 07:22:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:22:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:22:07 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 07:22:07 INFO - Cleaning up... 07:22:07 INFO - Return code: 0 07:22:07 INFO - Running post-action listener: _resource_record_post_action 07:22:07 INFO - Running post-action listener: _start_resource_monitoring 07:22:07 INFO - Starting resource monitoring. 07:22:07 INFO - [mozharness: 2017-05-18 14:22:07.470782Z] Finished create-virtualenv step (success) 07:22:07 INFO - [mozharness: 2017-05-18 14:22:07.471785Z] Running install step. 07:22:07 INFO - Running pre-action listener: _resource_record_pre_action 07:22:07 INFO - Running main action method: install 07:22:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:22:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:22:07 INFO - Reading from file tmpfile_stdout 07:22:07 INFO - Detecting whether we're running mozinstall >=1.0... 07:22:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 07:22:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 07:22:07 INFO - Reading from file tmpfile_stdout 07:22:07 INFO - Output received: 07:22:07 INFO - Usage: mozinstall [options] installer 07:22:07 INFO - Options: 07:22:07 INFO - -h, --help show this help message and exit 07:22:07 INFO - -d DEST, --destination=DEST 07:22:07 INFO - Directory to install application into. [default: 07:22:07 INFO - "/builds/slave/test"] 07:22:07 INFO - --app=APP Application being installed. [default: firefox] 07:22:07 INFO - mkdir: /builds/slave/test/build/application 07:22:07 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'] 07:22:07 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 07:22:30 INFO - Reading from file tmpfile_stdout 07:22:30 INFO - Output received: 07:22:30 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 07:22:30 INFO - Running post-action listener: _resource_record_post_action 07:22:30 INFO - [mozharness: 2017-05-18 14:22:30.462072Z] Finished install step (success) 07:22:30 INFO - [mozharness: 2017-05-18 14:22:30.462220Z] Running run-tests step. 07:22:30 INFO - Running pre-action listener: _resource_record_pre_action 07:22:30 INFO - Running main action method: run_tests 07:22:30 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 07:22:30 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 07:22:30 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 07:22:30 INFO - 2017-05-18 07:22:30.476 screenresolution[1635:7887] starting screenresolution argv=screenresolution get 07:22:30 INFO - 2017-05-18 07:22:30.479 screenresolution[1635:7887] Display 0: 1600x1200x32@60 07:22:30 INFO - 2017-05-18 07:22:30.485 screenresolution[1636:7889] starting screenresolution argv=screenresolution list 07:22:30 INFO - Available Modes on Display 0 07:22:30 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 07:22:30 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 07:22:30 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 07:22:30 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 07:22:30 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 07:22:30 INFO - Intel Iris: 07:22:30 INFO - Chipset Model: Intel Iris 07:22:30 INFO - Type: GPU 07:22:30 INFO - Bus: Built-In 07:22:30 INFO - VRAM (Dynamic, Max): 1536 MB 07:22:30 INFO - Vendor: Intel (0x8086) 07:22:30 INFO - Device ID: 0x0a2e 07:22:30 INFO - Revision ID: 0x0009 07:22:30 INFO - Displays: 07:22:30 INFO - Display: 07:22:30 INFO - Resolution: 1600 x 1200 @ 60 Hz 07:22:30 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 07:22:30 INFO - Display Serial Number: 1600x1200 60 07:22:30 INFO - Main Display: Yes 07:22:30 INFO - Mirror: Off 07:22:30 INFO - Online: Yes 07:22:30 INFO - Rotation: Supported 07:22:30 INFO - Return code: 0 07:22:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 07:22:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 07:22:30 INFO - Python 2.7.3 07:22:30 INFO - Return code: 0 07:22:30 INFO - grabbing minidump binary from tooltool 07:22:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:22:30 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1073d1d30>, '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 0x107385db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1073c6be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 07:22:30 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 07:22:30 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 07:22:30 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 07:22:30 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 07:22:30 INFO - Return code: 0 07:22:30 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 07:22:30 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 07:22:30 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 07:22:30 INFO - ENV: RUST_BACKTRACE is now 1 07:22:30 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 07:22:30 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 07:22:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/F4Q5KJeCSiagQpQf34mxHg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0365', '--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 07:22:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/F4Q5KJeCSiagQpQf34mxHg/artifacts/public/build/target.crashreporter-symbols.zip --title t-yosemite-r7-0365 --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 07:22:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QSPwAg0hvc/Render', 07:22:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:22:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:22:30 INFO - 'HOME': '/Users/cltbld', 07:22:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:22:30 INFO - 'LOGNAME': 'cltbld', 07:22:30 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 07:22:30 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 07:22:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:22:30 INFO - 'MOZ_NO_REMOTE': '1', 07:22:30 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 07:22:30 INFO - 'NO_EM_RESTART': '1', 07:22:30 INFO - 'PAGER': '/bin/cat', 07:22:30 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:22:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:22:30 INFO - 'PWD': '/builds/slave/test', 07:22:30 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 07:22:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:22:30 INFO - 'RUST_BACKTRACE': '1', 07:22:30 INFO - 'SHELL': '/bin/bash', 07:22:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fBo9SoWbbl/Listeners', 07:22:30 INFO - 'TMPDIR': '/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/', 07:22:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:22:30 INFO - 'USER': 'cltbld', 07:22:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:22:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:22:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:22:30 INFO - 'XPC_FLAGS': '0x0', 07:22:30 INFO - 'XPC_SERVICE_NAME': '0', 07:22:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:22:30 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/F4Q5KJeCSiagQpQf34mxHg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0365', '--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 07:22:31 INFO - mozversion application_buildid: 20170518052254 07:22:31 INFO - mozversion application_changeset: 2fa6931995b23f1f39752385b6689dc6b8d94c1b 07:22:31 INFO - mozversion application_display_name: Nightly 07:22:31 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 07:22:31 INFO - mozversion application_name: Firefox 07:22:31 INFO - mozversion application_remotingname: firefox 07:22:31 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 07:22:31 INFO - mozversion application_vendor: Mozilla 07:22:31 INFO - mozversion application_version: 55.0a1 07:22:31 INFO - mozversion platform_buildid: 20170518052254 07:22:31 INFO - mozversion platform_changeset: 2fa6931995b23f1f39752385b6689dc6b8d94c1b 07:22:31 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 07:22:31 INFO - mozversion platform_version: 55.0a1 07:22:31 INFO - using testdate: 1495117351 07:22:31 INFO - actual date: 1495117351 07:22:31 INFO - starting webserver on 'localhost:49224' 07:22:31 INFO - SUITE-START | Running 2 tests 07:22:31 INFO - TEST-START | dromaeo_css 07:22:31 INFO - operating with platform_type : mac_ 07:22:31 INFO - Initialising browser for dromaeo_css test... 07:22:31 INFO - TEST-INFO | started process 1646 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/tmpfwpROl/profile http://localhost:49224/getInfo.html) 07:22:31 INFO - PID 1646 | JavaScript warning: resource://gre/modules/addons/XPIProvider.jsm, line 2941: String.localeCompare is deprecated; use String.prototype.localeCompare instead 07:22:33 INFO - PID 1646 | 2017-05-18 07:22:33.620 plugin-container[1648:8072] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9b3f, name = 'com.apple.tsm.portname' 07:22:33 INFO - PID 1646 | See /usr/include/servers/bootstrap_defs.h for the error codes. 07:22:33 INFO - PID 1646 | 2017-05-18 07:22:33.622 plugin-container[1648:8072] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0xa00b, name = 'com.apple.CFPasteboardClient' 07:22:33 INFO - PID 1646 | See /usr/include/servers/bootstrap_defs.h for the error codes. 07:22:33 INFO - PID 1646 | 2017-05-18 07:22:33.623 plugin-container[1648:8072] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 07:22:33 INFO - PID 1646 | __metrics Screen width/height:1600/1200 07:22:33 INFO - PID 1646 | colorDepth:24 07:22:33 INFO - PID 1646 | Browser inner width/height: 1024/691 07:22:33 INFO - PID 1646 | __metrics 07:22:34 INFO - TEST-INFO | 1646: exit 0 07:22:34 INFO - Browser initialized. 07:22:34 INFO - Running cycle 1/1 for dromaeo_css test... 07:22:34 INFO - TEST-INFO | started process 1650 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/tmpfwpROl/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:22:36 INFO - PID 1650 | 2017-05-18 07:22:36.806 plugin-container[1651:8222] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x953f, name = 'com.apple.tsm.portname' 07:22:36 INFO - PID 1650 | See /usr/include/servers/bootstrap_defs.h for the error codes. 07:22:36 INFO - PID 1650 | 2017-05-18 07:22:36.808 plugin-container[1651:8222] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9b17, name = 'com.apple.CFPasteboardClient' 07:22:36 INFO - PID 1650 | See /usr/include/servers/bootstrap_defs.h for the error codes. 07:22:36 INFO - PID 1650 | 2017-05-18 07:22:36.808 plugin-container[1651:8222] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 07:22:37 INFO - PID 1650 | 2017-05-18 07:22:37.251 plugin-container[1652:8267] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4847, name = 'com.apple.tsm.portname' 07:22:37 INFO - PID 1650 | See /usr/include/servers/bootstrap_defs.h for the error codes. 07:22:37 INFO - PID 1650 | 2017-05-18 07:22:37.252 plugin-container[1652:8267] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x932f, name = 'com.apple.CFPasteboardClient' 07:22:37 INFO - PID 1650 | See /usr/include/servers/bootstrap_defs.h for the error codes. 07:22:37 INFO - PID 1650 | 2017-05-18 07:22:37.252 plugin-container[1652:8267] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 07:22:46 INFO - PID 1650 | RSS: Main: 237961216 07:22:46 INFO - PID 1650 | 07:25:48 INFO - PID 1650 | Cycle 1(1): loaded http://localhost:49224/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49224/tests/dromaeo/cssquery-ext.html) 07:25:50 INFO - PID 1650 | RSS: Main: 262873088 07:25:50 INFO - PID 1650 | 07:28:52 INFO - PID 1650 | Cycle 1(1): loaded http://localhost:49224/tests/dromaeo/cssquery-ext.html (next: http://localhost:49224/tests/dromaeo/cssquery-jquery.html) 07:28:52 INFO - PID 1650 | RSS: Main: 268267520 07:28:52 INFO - PID 1650 | 07:31:54 INFO - PID 1650 | Cycle 1(1): loaded http://localhost:49224/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49224/tests/dromaeo/cssquery-mootools.html) 07:31:55 INFO - PID 1650 | RSS: Main: 272900096 07:31:55 INFO - PID 1650 | 07:34:56 INFO - PID 1650 | Cycle 1(1): loaded http://localhost:49224/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49224/tests/dromaeo/cssquery-prototype.html) 07:34:57 INFO - PID 1650 | RSS: Main: 273735680 07:34:57 INFO - PID 1650 | 07:38:00 INFO - PID 1650 | Cycle 1(1): loaded http://localhost:49224/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49224/tests/dromaeo/cssquery-yui.html) 07:38:00 INFO - PID 1650 | RSS: Main: 275288064 07:38:00 INFO - PID 1650 | 07:41:02 INFO - PID 1650 | Cycle 1(1): loaded http://localhost:49224/tests/dromaeo/cssquery-yui.html (next: http://localhost:49224/tests/dromaeo/cssquery-dojo.html) 07:41:02 INFO - PID 1650 | RSS: Main: 276516864 07:41:02 INFO - PID 1650 | 07:41:02 INFO - PID 1650 | __start_tp_report 07:41:02 INFO - PID 1650 | _x_x_mozilla_page_load 07:41:02 INFO - PID 1650 | _x_x_mozilla_page_load_details 07:41:02 INFO - PID 1650 | |i|pagename|runs| 07:41:02 INFO - PID 1650 | |0;dojo.html;3072.416240914226;3394.6775056369643;3594.5327107476023;3605.188832512685;3615.1865830188226;5672;6147.293061297946;6156.692165391723;6215.937840621598;6258;6243.688825346682;6247;6260.874782504347;6288.528360372979;6317.557770956035;5491.368492623359;5511.090670039439;5527.585431092664;5532.419095994923;5638.351589567191;5604.943950560503;5652.717364131777;5667.838093190907;5675.971620141893;5698.373178950312;4634.39752832133;5444.128939369682;5531.944680553183;5584.748686309126;5658.519025882784;5560.304961879765;5591.440855914417;5603.047481928042;5616.719164041781;5619.719014049281;5527.115661494182;5549.223108764776;5567.192757050205;5580.804671836466;5586.932834851091;5011.899762004739;6219.378062193744;6323.6521991290465;6339.8732025359695;6369.490440764727;6697.3637504437;6744.325567443266;6831.555948863358;6847.726090956356;6850.862982740367;6898.655067246617;6925.515213935027;6948.652567371611;6957.79126626202;6993.055937448481;6357;6366.204224471941;6406.23125224976;6406.615603063831;6413.615183089029;6838.623875686785;6933.168019837652;6989.790306290819;7026.332498412643;7051.717931282794;6669.412097426499;6734.629595372302;6742.797716068526;6756.121704178426;6763.12079429671;6707.53047286695;6895;6913.066735990677;6915.239323674392;6922.203946546211;5784.971075144597;6585.703643336062;6770.492213084039;6784.559003664746;6799.150106236721;5770.394108618619;6809.965950170316;6839.487038472134;6862.519623626398;6885.345892140339;5392.352917649907;5401.540869026098;5425.728713564306;5449.209864569615;5470.617056806065;4790.880227994328;4805.6155507559315;4824.734639594786;4869.074875773592;4899.779509921991;6273.153124328249;6280.811575652738;6305.905411418832;6319.0837328587095;6326.335734747877;6868.175818901764;6886.173659160932;6894.551854129466;6896.413804826646;6904.965475172592;6284.780032698834;6285.811425657146;6290.716917738713;6303.3066362700065;6338.58799178052;4536.6824322297125;6093.725782339805;6145.125736650364;6163.784267550704;6176.8764624707255;7052.471064670171;7078.362947334765;7085.114360704912;7099.964500177466;7108.431325494052;6719;6837.965810171117;6963.651817409211;6965.303469652994;6987.790366289019;6971.477139214478;7022.473314501331;7041.366277035091;7046.260142684944;7066.152061752623;6832.282610326043;6843.555168914005;6856.657167141722;6861.691223894837;6907.896381554381;5313.879401601872;6726.764563240263;6803.795886123424;6904.93095069063;6961.61711105894;5674.943250567607;6634.270230274569;6685.532012759255;6738.561993470409;6765.661716914233;6649.501287403367;6704.865902681821;6735.932640673531;6742;6747.865042699217;6296.779612713716;6369.617822930639;6560.704768285344;6635.071090047494;6636.369544893228;6595.406413422815;6635.6350400728425;6731.865362692816;6734.393904548614;6778.8983165251575;6573.967130164318;6581.308962559054;6590.80227593173;6601.867962640817;6645.468362531083 07:41:02 INFO - PID 1650 | |1;ext.html;784.2197013971096;800.3197282310036;822.543488363958;1061.6655753437672;1249.8250244965711;35927.922162335104;36881.815590922044;36910.07724806881;36930.44604330937;36938.44592331117;37397.43903841444;37791.43312850309;37846.81076594613;37864.62135378645;38043.23913521728;7712.537247765138;7854.803629909241;8069.757907262791;8272.958635206816;8286.088530261666;7441.813954651123;7921.653318935795;8160.5103693778565;8164;8290.419670623029;8191.262786349227;8192.18078192179;8292.087870334259;8292.792680182984;8314.625841837133;8214.671413143496;8240.022394625306;8242.464239824392;8247.051589067214;8286.875696864552;8180.877286840702;8233.670653173866;8242.587870606505;8244.093149753522;8261.710840120568;29430.852845735848;35517.57929682836;35548.75579354736;35562.28875422477;35649.46525802115;3626.111602657366;3668.706503479715;3671.5777685565968;3673.75092468562;3677.6506231907924;29131.563026554617;29144.979925702497;29230.56154157668;29361.559576606367;29395.55906661402;3043.4673932062024;3068.417000769869;3072.2933725243097;3075.56942028114;3082.676318986519;287185;288955;293433;299637.50181249174;301811.49094254605;39368.80315598404;43697.78151109224;43775.78112109419;43817.561824381984;43916.682499524424;45718;48351.516484834705;49043.75478122586;49117;49144;13777.035607507578;13822.861771382359;13940.58178254654;13956.720865582834;13961;11074.335539867634;11250.212485126127;11269.774604507864;11302.491387887565;11311.717207069889;10810.567577296977;12955.417006234742;12958.935205323913;12984.026198035184;13008.739825203444;4923.630727695438;5837.176639340159;5880.7353669085;5893.911591326134;5902.6458412495385;992.8510723391405;1021.0861279155263;1025.7692019295748;1044.5769463367349;1046.2362475392854;22185.889070554545;22929.31212063641;23719.525609487715;23973.940717129575;24093.277201683977;1091.170710260208;1101.862267216598;1135.0919264588297;1137.6871360375796;1145.8281257811395;10156.796864062826;11580.942095289638;11739.06087512997;12228.327441990776;12508.624741257778;238818;279522;280859;280956;281822;49921;50208.74895625572;50397.74005649815;50540.74729626402;50644.49355506471;1416.7591509443419;1439.2156274830033;1488.5608745420127;1498.1685164733678;1519.9012064216013;16996.660066798842;19832.70250946266;23393.649095263245;23400.414989625395;23450.296491105295;35473.82263088771;35554.288914221055;35561.28877422385;35676.46485302671;35752.64247357493;256316;257057;257408;257490;257682;26093.043021773065;30960;31250;31898;32177.356452870343;41361.1727765449;41530.79234603808;41574.1685166301;41591.1681766369;41610.58389416067;27286.590701139896;27771.583426249028;28318.212491564984;30359.23157748636;30448.543271850496;29711.554326684683;29784.702152979058;30200.94296699605;30373.936912207966;30719.2320191997;36252;36276;36358.45462318014;36420;36467.817660911525;29236.853815730785;29284.97502587399;29968.651410686136;30707.385852283274;30872.382552349278;21975;23068.653970190124;24030.27909162728;24182.879085604458;24424 07:41:02 INFO - PID 1650 | |2;jquery.html;406.96540794032506;431.17430121317676;436.08639899410724;438.2746554452381;460.8410098516013;18320.633587328262;19957.301494447678;20614.69077963828;20691.379258622248;20741.274055408063;21273.55354134275;22952.96711647976;23140.190093346693;23672;23955.64066538999;5221.947780522194;5277.868053298679;6226.501879849624;6246.156768836217;6256.781012664557;5983.551233657471;6096.603720758137;6157.476614487774;6165.229346331708;6178.351273116303;5942.257217847769;6136.079588061804;6138.723757430904;6140.07898815179;6141.6007959482495;6015.639061656293;6112.602680825777;6146.17026701394;6152.261728592575;6155.292141403751;5283.101872681654;5377.139657654757;5464.316960379952;5473.042217611902;5493.038718224335;24568.754312457004;25862.482750344887;27542.17373478799;27720.722792772016;27969.720302796915;2173.1416090644266;2259.1754009786473;2259.638457846753;2264.603694353498;2268.88655567221;13873.236971966538;17800.732989005177;17896.731549026776;18238.726419103725;18290.890548606636;2137.369476004567;2162.1891790578534;2197.8681279123143;2200.119952019189;2207.2826331442348;136694;136916;137475;137606.30346420567;144927;11843.703907402383;12277.140600158082;12279.956203722784;12323.815142772866;12348.197367171106;14253.857461425254;14568.05307654999;15615.609609759847;15623.765643515357;15671.529854104396;7702.8844567331535;8251.8349633007;8602.91397086021;8883.022867484571;8904.510251936206;8415.326773858076;8485.278751306209;8582.725577721909;8655.610497527501;8671.739847804576;7074.9646251769445;7543.321101100927;7620.035566093679;7732.458727888994;7949.125596184415;2119.470132466883;2175.5757627262847;2179.2808373236794;2188.982123312667;2195.538936823253;889.6174644902754;961.0293603460556;975.6341371985505;980.2990861534038;993.1707024634367;19195.904020480088;20923.163073477193;21445.67831482529;21546;21591.46021349479;867.3494878840869;954.1889394015031;1008.0473952115327;1015.2132097624252;1037.5123691864962;10922.508487118099;11171.218014739054;11254.606088786853;11262.042726368187;11275.210735248618;528492;532870;538117;540750;540801;119991;122251.55496890012;124807.3759631196;126313;127549;1142.9257098288751;1182.5842974145144;1192.7495226002636;1198.6703656494533;1199.7900367435664;16307.184640768151;18098.185581648595;18430.81569184291;18584.52642626621;18632.254709811456;52555.10946897957;53949;54520.45479545312;54950.45049549613;55316.72341638266;72542.37288135677;72607;72748.08856323594;73350;74214.62892685502;8743.91256087431;8834.072422395557;8865.778355541162;9221.585028673591;9294.767630809283;10847.620333288298;14074.507392241223;14232.074915130897;14403;14407.279636017947;10718;11719.179657423898;11899.583514576949;12134.150609457607;12175.391230438265;8552.828943420973;10273.486325683836;10586;11320.37737924422;11491.597794077166;9287.396319239499;12091.51633934668;12131.21147125434;12137.939310303393;12422.316772577595;7629.961850190713;7787.4548781584745;8656.394052416523;8772.254358379609;9036.23192028685;11718.179727419;12014.699632509257;12019.158658893793;12058.939705301418;12155 07:41:02 INFO - PID 1650 | |3;mootools.html;1404.641816336834;1457.7520924671192;1474.6239708874236;1478.4825311141099;1487.8363380028202;11602.59390921318;11934;12114.515419383226;12190.756184876309;12283.44724487398;11984.34086125265;11996.880031199686;12139.21095128816;12190.69523261919;12210.084243681715;2602.4404752978107;2633.5654616988268;2643.695974962875;2647.9602805957925;2654.734526547349;2552.961705574418;2612.32079659289;2616.9084082057134;2623.7376262373705;2624.7506486883717;2572.768450839424;2598.8440693558446;2625.4749050189944;2628.1195799407224;2628.4480259145603;2549.821512494126;2566.3199252198183;2610.282172402595;2610.9347266318305;2611.830231034977;2568.704598971105;2592.5722255827764;2597.402597402589;2617.659704238456;2624.39638883058;11756.764864702658;11961.760764784742;11969.940150299193;11971.880281197251;11994.22037567564;6607.5374723769355;6854.931450685479;6857.177138743383;6858.622775747332;6864.141982252218;4018.819153138116;4047.959520404788;4330.870073897788;4378.912421751547;4388.341748737683;1209.776191404584;1236.190295356543;1261.9369031548385;1282.1025282302426;1282.160185078775;15953.521394358188;16524.339026438927;16540.6691866162;16663.16684165799;16729.996200228026;5522.751476183581;5580.441955804387;5735.2257445245195;5756.539476841843;5778.248827652463;11756.94121529404;11771.058315334752;11773.058155347722;11780.764384712258;11812.055035597132;6644.302348253364;6646.401823835878;6652.600843949379;6654.800355989328;6763.154605674291;6037.698115094227;6152.077188421683;6171.938280617136;6211.65835879031;6484.643344616092;3464.8440820163155;3823.311803875329;4361.80371883266;4420.977895110504;4426.623736982393;1201.4113084588664;1210.2133613151345;1285.9292738899264;1293.9741205175844;1305.340802894532;796.323125343465;803.1606970715616;818.3902992270796;829.6432534010434;851.7955690634327;5664.158656117401;6182.598131121463;6390.840228994312;6396.264429590564;6421.2615549211505;591.5740666719955;600.4895838537209;603.9214902062704;607.1499900139839;618.1716499890168;3861.4787003754695;3867.4778904847485;3889.144388234584;3928.489296391451;3943.7042221833476;14564.85435145656;14664.706705865823;15058.096514208963;15062.774058389134;15102.848971510364;12313.50745970169;14793.408263669548;14892.553223403098;14955.700885982436;15009.474668386554;7495.662695178621;7528.736494222567;7584.596849582845;8060.677572897136;8590.012148602866;6621.9337806621315;7171.641417929187;7306.780796576111;7310.6710198040155;7333.523320984119;12648.039553869165;12700.745985080432;12762;12816.6795830105;13211.603651890458;13596.524121655695;13596.592102236553;13630.454781808616;13695.178289302674;13947.511837085653;8064.959675201587;8213.753587392388;8221.218984196492;8233.958830205811;8242.381821363302;10595.629152979609;10638.521266543179;10738.355698658106;10762.3542587445;10981.890181098086;7723.343515800995;7855.410844186823;9181.908180918106;9247.907520924704;9407.717768466957;8990.01109877926;9055.185033346766;9088.863667044867;9113.3165012623;9217.124373184537;8242.134575869448;8269.917300827155;8400;8424.915750842412;8427.19941605571;5245.527902488794;6026.638401695912;6194.0708893666315;6219.346968568416;6323.747050118128;6180.752769889334;6191.938080619136;6298.590591611531;6362.745490180526;6430.2926678066315 07:41:02 INFO - PID 1650 | |4;prototype.html;383.1474968195765;401.604419646648;406.7925358067385;409.42475821470805;412.0995624560337;5387.4073851876265;5606.831795046145;6381.712822922968;6399.816148919379;6403.199600049994;4822.638302127346;6181.227346581677;6203.844903877405;6209.223847019122;6212.161358216651;4488.438945131858;4525.275955847065;4527.841525546606;4539.4098767160385;4562.836476698437;3639.909002274951;4394.47266328041;4487.596116349528;4524.366588677572;4557.475890272612;4467.441569803775;4483.7758112094425;4484.215262329096;4484.9460376811585;4490.899729566261;3769.736118471694;3935.1145992151824;4311.310190369526;4498.460184777831;4523.683342166049;3767.7174211934216;3826.349520581508;3862.7102967277565;3863.826127824255;3866.7819636814315;6035.7887473938645;6052.03167493199;6054.909176362358;6070.817875463743;6085.208922840003;4230.428892099558;5164.561012313958;5352.143657014859;5371.865703357408;5382.219578161184;5552.916706249369;6318.2733985591785;6608.669566521655;6691.59850408977;6711.49663775214;3687.889363319078;3784.9402167393173;4405.515393306734;4405.801738921724;4408.316710909814;5904.380040095771;6844;7090.113735783028;7176.892346614805;7176.96411517939;4449.377087207794;4450.176717307244;4450.310201918708;4456.844010459618;4488.910221795546;4537.705049171794;4538.410006699108;4542.6593005524055;4557.589816916494;4564.356831540643;4134.421181034658;5174.482551744795;5386.596005299618;5393.865153371197;5403.702796346239;6190.628562286277;6740.561863478858;6743.763968261087;6792.388685018372;6869.278725733826;1189.7858385490522;1213.3933033483258;1454.8181477315336;1498.8983097423386;1537.085434166669;991.177322822056;996.9800603987879;997.7804882925744;1002.6941782756184;1011.8229309870743;5559.027170245191;6193.721282542297;6357.904631430532;6384.6169229846355;6390.265119511223;6530.379613936763;6748.932510674928;6978.511504194657;7002.264762199998;7035.959571724245;6035.547333949883;6239.188905442355;6274.529410294246;6288.151099601495;6378.330275321117;3031.9848400757855;3087.5831762711746;3546.733994950389;3638.672519473207;3649.1606930406156;7140.57156570597;7170.856582868313;7197.640117994079;7207.13514378278;7219.783406497813;6983.650817459208;7172.677229524684;7186.209516953131;7191.7842464726145;7223.92776072243;6389.690113526716;6455.515836312202;7144.8928266076055;7206.135263768277;7206.828995242076;5937.435943585352;6893.483878384454;7020.052292940388;7071.257517960746;7077.221505634273;6738.191417029988;6781.847085995294;6865.077778222106;6904.032350780004;6934.9250866115835;7179.174394944439;7183.389411899838;7190.280971902768;7214.531055481377;7226.241244669444;4356.368326592689;4457.933131003102;4508.45898492183;4510.571495707903;4512.887177820581;7015.824604384931;7072.929270707433;7084.64576771149;7112.50212485121;7152.141742990666;5706.885862282648;6825.590464571943;6834.692438840165;6841.076454678754;6854.862902741817;6694.29709880475;6696.1629796275465;6724;6765.54540773736;6772.661366931732;6592.967035164794;6594.274629790815;6622;6635.966820165868;6652.511677865877;5569.582281328835;6560.195946114856;6740.090087838079;6744.0895479109695;6799.184097908283;6472.288048314776;6607.834804129935;6627.255600585654;6662.300458451987;6673.499487538551 07:41:02 INFO - PID 1650 | |5;yui.html;832.2468166309492;843.4264700003997;862.7670528957178;864.7319331007388;874.895012598488;16621.252043658034;16631.584210394733;17043.82956170438;17962.461126166236;18976.05119744014;17374.305027798906;17802.82197178028;18078.367257146;18144.274229030823;18436.907815460905;996.8255555277833;998.3660375661452;1014.2798612984752;1016.6289304403913;1017.9083882450578;954.9999999999965;988.9505524723771;1011.4993078426153;1011.6358111080006;1018.399144504742;1044.634377967713;1047.2983101322131;1049.9737506562358;1050.658535975811;1051.1748277602085;1048.0672201740458;1050.8423736439595;1051.0855555666542;1051.952662130206;1053.6628278950739;756.701103064292;764.4190415284423;785.3324674027089;785.7210690204952;789.5973053742575;16469.58826029347;16688.165591720488;16705.99764014163;17130.400435984804;17349.13254337278;16851.241694123793;17038.403655871985;17374.305027798873;17405.303787848472;17570.736438953427;12276.570320038845;14330.92834535831;14421.351039203157;14755.04092234012;14882.255887205596;3255.462848629973;3666.0651533858695;3680.3743363628246;3681.5582130144553;3682.4660424238605;105279;106914;108470.74587762379;117198;119090;13354.666133346744;13560.254186019863;13716.051049024507;13719.794203086962;13740.038197326292;24713;25166.622500662506;25780.74219257821;26099.869500652756;26128.738712613012;7709.190534993857;7878.1334053254095;7885.2114788520685;7889.6844126235455;7959.9602001989615;3754.8685795997008;3893.124047089439;3916.5691773904846;3954.60453954608;3989.401589761501;4132.318167502329;4221.746695198237;4232.470941132358;4238.491381034234;4246.7027308088755;913.9131782480654;950.7242899559188;953.6709835106877;961.9711408657752;962.0523784072683;644.3234603666225;657.7599176300712;659.9670016499156;661.2395744893411;684.1004079635363;10922.508487118099;11700.414979251003;12049.578264760692;13195.868041319463;13288.800667989988;660.9339066093352;665.896785998171;671.1677519875319;676.4655921821804;696.3350000749273;4019.477467929209;4407.669424793154;5373.516383525502;5436.891262174734;5622.746976386073;90464;90729.54635226782;92093.5395323019;92332;93169.13661726862;75933.24066759372;76792;78110.21889781143;78818.6059069701;79133.60433197797;71624;72157.6392118036;72504.63747681228;72878;73090.9036364455;11012.284201526874;11033.393163376091;11040.227184097035;11300.49147788368;11317.603883864025;33542.65829366798;36406.27187456183;37040.81479592585;37952.810235948644;40116.59883401129;53033.93932121413;64306.35693643191;65385.34614653792;67447;68257.65871170613;13768.724625507233;14150.929245354118;14334.283285835874;15633.374665013273;16869.746953795457;17543.912280438515;17972.910135449238;18121.90939045296;18377.264909403475;18592;16502.587435313733;16765.413210537572;16973;17322.047287399313;17376.73934891003;16752;17156.39952601653;17162.8114319682;17372;17381.82618173853;14885.479008234659;14977.326020329328;15419.306131224346;15462.690746185239;15499.767503487232;10425.0096240857;10440.373577585066;10610.416427096583;10731.517081731185;10794.298370605886;11081.72295692582;12020.75958480808;12048.698782530157;12103.515859365527;12207.75584488323 07:41:02 INFO - PID 1650 | __end_tp_report 07:41:02 INFO - PID 1650 | __start_cc_report 07:41:02 INFO - PID 1650 | _x_x_mozilla_cycle_collect,353 07:41:02 INFO - PID 1650 | __end_cc_report 07:41:02 INFO - PID 1650 | __startTimestamp1495118462795__endTimestamp 07:41:02 INFO - PID 1650 | 07:41:02 INFO - PID 1650 | ------- Summary: start ------- 07:41:02 INFO - PID 1650 | Number of tests: 6 07:41:02 INFO - PID 1650 | 07:41:02 INFO - PID 1650 | [#0] dojo.html Cycles:165 Average:6257.08 Median:6573.97 stddev:791.36 (12.0%) stddev-sans-first:753.32 07:41:02 INFO - PID 1650 | Values: 3072.4 3394.7 3594.5 3605.2 3615.2 5672.0 6147.3 6156.7 6215.9 6258.0 6243.7 6247.0 6260.9 6288.5 6317.6 5491.4 5511.1 5527.6 5532.4 5638.4 5604.9 5652.7 5667.8 5676.0 5698.4 4634.4 5444.1 5531.9 5584.7 5658.5 5560.3 5591.4 5603.0 5616.7 5619.7 5527.1 5549.2 5567.2 5580.8 5586.9 5011.9 6219.4 6323.7 6339.9 6369.5 6697.4 6744.3 6831.6 6847.7 6850.9 6898.7 6925.5 6948.7 6957.8 6993.1 6357.0 6366.2 6406.2 6406.6 6413.6 6838.6 6933.2 6989.8 7026.3 7051.7 6669.4 6734.6 6742.8 6756.1 6763.1 6707.5 6895.0 6913.1 6915.2 6922.2 5785.0 6585.7 6770.5 6784.6 6799.2 5770.4 6810.0 6839.5 6862.5 6885.3 5392.4 5401.5 5425.7 5449.2 5470.6 4790.9 4805.6 4824.7 4869.1 4899.8 6273.2 6280.8 6305.9 6319.1 6326.3 6868.2 6886.2 6894.6 6896.4 6905.0 6284.8 6285.8 6290.7 6303.3 6338.6 4536.7 6093.7 6145.1 6163.8 6176.9 7052.5 7078.4 7085.1 7100.0 7108.4 6719.0 6838.0 6963.7 6965.3 6987.8 6971.5 7022.5 7041.4 7046.3 7066.2 6832.3 6843.6 6856.7 6861.7 6907.9 5313.9 6726.8 6803.8 6904.9 6961.6 5674.9 6634.3 6685.5 6738.6 6765.7 6649.5 6704.9 6735.9 6742.0 6747.9 6296.8 6369.6 6560.7 6635.1 6636.4 6595.4 6635.6 6731.9 6734.4 6778.9 6574.0 6581.3 6590.8 6601.9 6645.5 07:41:02 INFO - PID 1650 | 07:41:02 INFO - PID 1650 | [#1] ext.html Cycles:180 Average:41994.63 Median:3079.12 stddev:72026.94 (2339.2%) stddev-sans-first:72162.54 07:41:02 INFO - PID 1650 | Values: 784.2 800.3 822.5 1061.7 1249.8 35927.9 36881.8 36910.1 36930.4 36938.4 37397.4 37791.4 37846.8 37864.6 38043.2 7712.5 7854.8 8069.8 8273.0 8286.1 7441.8 7921.7 8160.5 8164.0 8290.4 8191.3 8192.2 8292.1 8292.8 8314.6 8214.7 8240.0 8242.5 8247.1 8286.9 8180.9 8233.7 8242.6 8244.1 8261.7 29430.9 35517.6 35548.8 35562.3 35649.5 3626.1 3668.7 3671.6 3673.8 3677.7 29131.6 29145.0 29230.6 29361.6 29395.6 3043.5 3068.4 3072.3 3075.6 3082.7 287185.0 288955.0 293433.0 299637.5 301811.5 39368.8 43697.8 43775.8 43817.6 43916.7 45718.0 48351.5 49043.8 49117.0 49144.0 13777.0 13822.9 13940.6 13956.7 13961.0 11074.3 11250.2 11269.8 11302.5 11311.7 10810.6 12955.4 12958.9 12984.0 13008.7 4923.6 5837.2 5880.7 5893.9 5902.6 992.9 1021.1 1025.8 1044.6 1046.2 22185.9 22929.3 23719.5 23973.9 24093.3 1091.2 1101.9 1135.1 1137.7 1145.8 10156.8 11580.9 11739.1 12228.3 12508.6 238818.0 279522.0 280859.0 280956.0 281822.0 49921.0 50208.7 50397.7 50540.7 50644.5 1416.8 1439.2 1488.6 1498.2 1519.9 16996.7 19832.7 23393.6 23400.4 23450.3 35473.8 35554.3 35561.3 35676.5 35752.6 256316.0 257057.0 257408.0 257490.0 257682.0 26093.0 30960.0 31250.0 31898.0 32177.4 41361.2 41530.8 41574.2 41591.2 41610.6 27286.6 27771.6 28318.2 30359.2 30448.5 29711.6 29784.7 30200.9 30373.9 30719.2 36252.0 36276.0 36358.5 36420.0 36467.8 29236.9 29285.0 29968.7 30707.4 30872.4 21975.0 23068.7 24030.3 24182.9 24424.0 07:41:02 INFO - PID 1650 | 07:41:02 INFO - PID 1650 | [#2] jquery.html Cycles:180 Average:34224.32 Median:2266.75 stddev:90440.26 (3989.9%) stddev-sans-first:90658.33 07:41:02 INFO - PID 1650 | Values: 407.0 431.2 436.1 438.3 460.8 18320.6 19957.3 20614.7 20691.4 20741.3 21273.6 22953.0 23140.2 23672.0 23955.6 5221.9 5277.9 6226.5 6246.2 6256.8 5983.6 6096.6 6157.5 6165.2 6178.4 5942.3 6136.1 6138.7 6140.1 6141.6 6015.6 6112.6 6146.2 6152.3 6155.3 5283.1 5377.1 5464.3 5473.0 5493.0 24568.8 25862.5 27542.2 27720.7 27969.7 2173.1 2259.2 2259.6 2264.6 2268.9 13873.2 17800.7 17896.7 18238.7 18290.9 2137.4 2162.2 2197.9 2200.1 2207.3 136694.0 136916.0 137475.0 137606.3 144927.0 11843.7 12277.1 12280.0 12323.8 12348.2 14253.9 14568.1 15615.6 15623.8 15671.5 7702.9 8251.8 8602.9 8883.0 8904.5 8415.3 8485.3 8582.7 8655.6 8671.7 7075.0 7543.3 7620.0 7732.5 7949.1 2119.5 2175.6 2179.3 2189.0 2195.5 889.6 961.0 975.6 980.3 993.2 19195.9 20923.2 21445.7 21546.0 21591.5 867.3 954.2 1008.0 1015.2 1037.5 10922.5 11171.2 11254.6 11262.0 11275.2 528492.0 532870.0 538117.0 540750.0 540801.0 119991.0 122251.6 124807.4 126313.0 127549.0 1142.9 1182.6 1192.7 1198.7 1199.8 16307.2 18098.2 18430.8 18584.5 18632.3 52555.1 53949.0 54520.5 54950.5 55316.7 72542.4 72607.0 72748.1 73350.0 74214.6 8743.9 8834.1 8865.8 9221.6 9294.8 10847.6 14074.5 14232.1 14403.0 14407.3 10718.0 11719.2 11899.6 12134.2 12175.4 8552.8 10273.5 10586.0 11320.4 11491.6 9287.4 12091.5 12131.2 12137.9 12422.3 7630.0 7787.5 8656.4 8772.3 9036.2 11718.2 12014.7 12019.2 12058.9 12155.0 07:41:02 INFO - PID 1650 | 07:41:02 INFO - PID 1650 | [#3] mootools.html Cycles:180 Average:7076.10 Median:3644.08 stddev:4466.59 (122.6%) stddev-sans-first:4458.79 07:41:02 INFO - PID 1650 | Values: 1404.6 1457.8 1474.6 1478.5 1487.8 11602.6 11934.0 12114.5 12190.8 12283.4 11984.3 11996.9 12139.2 12190.7 12210.1 2602.4 2633.6 2643.7 2648.0 2654.7 2553.0 2612.3 2616.9 2623.7 2624.8 2572.8 2598.8 2625.5 2628.1 2628.4 2549.8 2566.3 2610.3 2610.9 2611.8 2568.7 2592.6 2597.4 2617.7 2624.4 11756.8 11961.8 11969.9 11971.9 11994.2 6607.5 6854.9 6857.2 6858.6 6864.1 4018.8 4048.0 4330.9 4378.9 4388.3 1209.8 1236.2 1261.9 1282.1 1282.2 15953.5 16524.3 16540.7 16663.2 16730.0 5522.8 5580.4 5735.2 5756.5 5778.2 11756.9 11771.1 11773.1 11780.8 11812.1 6644.3 6646.4 6652.6 6654.8 6763.2 6037.7 6152.1 6171.9 6211.7 6484.6 3464.8 3823.3 4361.8 4421.0 4426.6 1201.4 1210.2 1285.9 1294.0 1305.3 796.3 803.2 818.4 829.6 851.8 5664.2 6182.6 6390.8 6396.3 6421.3 591.6 600.5 603.9 607.1 618.2 3861.5 3867.5 3889.1 3928.5 3943.7 14564.9 14664.7 15058.1 15062.8 15102.8 12313.5 14793.4 14892.6 14955.7 15009.5 7495.7 7528.7 7584.6 8060.7 8590.0 6621.9 7171.6 7306.8 7310.7 7333.5 12648.0 12700.7 12762.0 12816.7 13211.6 13596.5 13596.6 13630.5 13695.2 13947.5 8065.0 8213.8 8221.2 8234.0 8242.4 10595.6 10638.5 10738.4 10762.4 10981.9 7723.3 7855.4 9181.9 9247.9 9407.7 8990.0 9055.2 9088.9 9113.3 9217.1 8242.1 8269.9 8400.0 8424.9 8427.2 5245.5 6026.6 6194.1 6219.3 6323.7 6180.8 6191.9 6298.6 6362.7 6430.3 07:41:02 INFO - PID 1650 | 07:41:02 INFO - PID 1650 | [#4] prototype.html Cycles:180 Average:5409.74 Median:6206.53 stddev:1779.12 (28.7%) stddev-sans-first:1743.65 07:41:02 INFO - PID 1650 | Values: 383.1 401.6 406.8 409.4 412.1 5387.4 5606.8 6381.7 6399.8 6403.2 4822.6 6181.2 6203.8 6209.2 6212.2 4488.4 4525.3 4527.8 4539.4 4562.8 3639.9 4394.5 4487.6 4524.4 4557.5 4467.4 4483.8 4484.2 4484.9 4490.9 3769.7 3935.1 4311.3 4498.5 4523.7 3767.7 3826.3 3862.7 3863.8 3866.8 6035.8 6052.0 6054.9 6070.8 6085.2 4230.4 5164.6 5352.1 5371.9 5382.2 5552.9 6318.3 6608.7 6691.6 6711.5 3687.9 3784.9 4405.5 4405.8 4408.3 5904.4 6844.0 7090.1 7176.9 7177.0 4449.4 4450.2 4450.3 4456.8 4488.9 4537.7 4538.4 4542.7 4557.6 4564.4 4134.4 5174.5 5386.6 5393.9 5403.7 6190.6 6740.6 6743.8 6792.4 6869.3 1189.8 1213.4 1454.8 1498.9 1537.1 991.2 997.0 997.8 1002.7 1011.8 5559.0 6193.7 6357.9 6384.6 6390.3 6530.4 6748.9 6978.5 7002.3 7036.0 6035.5 6239.2 6274.5 6288.2 6378.3 3032.0 3087.6 3546.7 3638.7 3649.2 7140.6 7170.9 7197.6 7207.1 7219.8 6983.7 7172.7 7186.2 7191.8 7223.9 6389.7 6455.5 7144.9 7206.1 7206.8 5937.4 6893.5 7020.1 7071.3 7077.2 6738.2 6781.8 6865.1 6904.0 6934.9 7179.2 7183.4 7190.3 7214.5 7226.2 4356.4 4457.9 4508.5 4510.6 4512.9 7015.8 7072.9 7084.6 7112.5 7152.1 5706.9 6825.6 6834.7 6841.1 6854.9 6694.3 6696.2 6724.0 6765.5 6772.7 6593.0 6594.3 6622.0 6636.0 6652.5 5569.6 6560.2 6740.1 6744.1 6799.2 6472.3 6607.8 6627.3 6662.3 6673.5 07:41:02 INFO - PID 1650 | 07:41:02 INFO - PID 1650 | [#5] yui.html Cycles:180 Average:20495.06 Median:18133.09 stddev:27353.20 (150.8%) stddev-sans-first:27390.09 07:41:02 INFO - PID 1650 | Values: 832.2 843.4 862.8 864.7 874.9 16621.3 16631.6 17043.8 17962.5 18976.1 17374.3 17802.8 18078.4 18144.3 18436.9 996.8 998.4 1014.3 1016.6 1017.9 955.0 989.0 1011.5 1011.6 1018.4 1044.6 1047.3 1050.0 1050.7 1051.2 1048.1 1050.8 1051.1 1052.0 1053.7 756.7 764.4 785.3 785.7 789.6 16469.6 16688.2 16706.0 17130.4 17349.1 16851.2 17038.4 17374.3 17405.3 17570.7 12276.6 14330.9 14421.4 14755.0 14882.3 3255.5 3666.1 3680.4 3681.6 3682.5 105279.0 106914.0 108470.7 117198.0 119090.0 13354.7 13560.3 13716.1 13719.8 13740.0 24713.0 25166.6 25780.7 26099.9 26128.7 7709.2 7878.1 7885.2 7889.7 7960.0 3754.9 3893.1 3916.6 3954.6 3989.4 4132.3 4221.7 4232.5 4238.5 4246.7 913.9 950.7 953.7 962.0 962.1 644.3 657.8 660.0 661.2 684.1 10922.5 11700.4 12049.6 13195.9 13288.8 660.9 665.9 671.2 676.5 696.3 4019.5 4407.7 5373.5 5436.9 5622.7 90464.0 90729.5 92093.5 92332.0 93169.1 75933.2 76792.0 78110.2 78818.6 79133.6 71624.0 72157.6 72504.6 72878.0 73090.9 11012.3 11033.4 11040.2 11300.5 11317.6 33542.7 36406.3 37040.8 37952.8 40116.6 53033.9 64306.4 65385.3 67447.0 68257.7 13768.7 14150.9 14334.3 15633.4 16869.7 17543.9 17972.9 18121.9 18377.3 18592.0 16502.6 16765.4 16973.0 17322.0 17376.7 16752.0 17156.4 17162.8 17372.0 17381.8 14885.5 14977.3 15419.3 15462.7 15499.8 10425.0 10440.4 10610.4 10731.5 10794.3 11081.7 12020.8 12048.7 12103.5 12207.8 07:41:02 INFO - PID 1650 | -------- Summary: end -------- 07:41:02 INFO - PID 1650 | 07:41:03 INFO - TEST-INFO | 1650: exit 0 07:41:03 INFO - TEST-OK | dromaeo_css | took 1112341ms 07:41:03 INFO - TEST-START | kraken 07:41:03 INFO - operating with platform_type : mac_ 07:41:03 INFO - Initialising browser for kraken test... 07:41:03 INFO - TEST-INFO | started process 1662 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/tmp5ZaLle/profile http://localhost:49224/getInfo.html) 07:41:03 INFO - PID 1662 | JavaScript warning: resource://gre/modules/addons/XPIProvider.jsm, line 2941: String.localeCompare is deprecated; use String.prototype.localeCompare instead 07:41:05 INFO - PID 1662 | 2017-05-18 07:41:05.828 plugin-container[1663:10756] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x963f, name = 'com.apple.tsm.portname' 07:41:05 INFO - PID 1662 | See /usr/include/servers/bootstrap_defs.h for the error codes. 07:41:05 INFO - PID 1662 | 2017-05-18 07:41:05.834 plugin-container[1663:10756] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9c0b, name = 'com.apple.CFPasteboardClient' 07:41:05 INFO - PID 1662 | See /usr/include/servers/bootstrap_defs.h for the error codes. 07:41:05 INFO - PID 1662 | 2017-05-18 07:41:05.835 plugin-container[1663:10756] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 07:41:05 INFO - PID 1662 | __metrics Screen width/height:1600/1200 07:41:05 INFO - PID 1662 | colorDepth:24 07:41:05 INFO - PID 1662 | Browser inner width/height: 1024/691 07:41:05 INFO - PID 1662 | __metrics 07:41:07 INFO - TEST-INFO | 1662: exit 0 07:41:07 INFO - Browser initialized. 07:41:07 INFO - Running cycle 1/1 for kraken test... 07:41:07 INFO - TEST-INFO | started process 1665 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/tmp5ZaLle/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:41:09 INFO - PID 1665 | 2017-05-18 07:41:09.154 plugin-container[1666:10894] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x454f, name = 'com.apple.tsm.portname' 07:41:09 INFO - PID 1665 | See /usr/include/servers/bootstrap_defs.h for the error codes. 07:41:09 INFO - PID 1665 | 2017-05-18 07:41:09.154 plugin-container[1666:10894] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4823, name = 'com.apple.CFPasteboardClient' 07:41:09 INFO - PID 1665 | See /usr/include/servers/bootstrap_defs.h for the error codes. 07:41:09 INFO - PID 1665 | 2017-05-18 07:41:09.154 plugin-container[1666:10894] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 07:41:09 INFO - PID 1665 | 2017-05-18 07:41:09.706 plugin-container[1667:10941] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x953f, name = 'com.apple.tsm.portname' 07:41:09 INFO - PID 1665 | See /usr/include/servers/bootstrap_defs.h for the error codes. 07:41:09 INFO - PID 1665 | 2017-05-18 07:41:09.707 plugin-container[1667:10941] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9a17, name = 'com.apple.CFPasteboardClient' 07:41:09 INFO - PID 1665 | See /usr/include/servers/bootstrap_defs.h for the error codes. 07:41:09 INFO - PID 1665 | 2017-05-18 07:41:09.707 plugin-container[1667:10941] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 07:41:19 INFO - PID 1665 | RSS: Main: 240615424 07:41:19 INFO - PID 1665 | 07:41:21 INFO - PID 1665 | Cycle 1(1): loaded http://localhost:49224/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49224/tests/kraken/driver.html?testName=audio-beat-detection) 07:41:22 INFO - PID 1665 | RSS: Main: 252768256 07:41:22 INFO - PID 1665 | 07:41:25 INFO - PID 1665 | Cycle 1(1): loaded http://localhost:49224/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49224/tests/kraken/driver.html?testName=audio-dft) 07:41:25 INFO - PID 1665 | RSS: Main: 261353472 07:41:25 INFO - PID 1665 | 07:41:29 INFO - PID 1665 | Cycle 1(1): loaded http://localhost:49224/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49224/tests/kraken/driver.html?testName=audio-fft) 07:41:29 INFO - PID 1665 | RSS: Main: 263249920 07:41:29 INFO - PID 1665 | 07:41:31 INFO - PID 1665 | Cycle 1(1): loaded http://localhost:49224/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49224/tests/kraken/driver.html?testName=audio-oscillator) 07:41:32 INFO - PID 1665 | RSS: Main: 265273344 07:41:32 INFO - PID 1665 | 07:41:34 INFO - PID 1665 | Cycle 1(1): loaded http://localhost:49224/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49224/tests/kraken/driver.html?testName=imaging-gaussian-blur) 07:41:35 INFO - PID 1665 | RSS: Main: 265793536 07:41:35 INFO - PID 1665 | 07:41:37 INFO - PID 1665 | Cycle 1(1): loaded http://localhost:49224/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49224/tests/kraken/driver.html?testName=imaging-darkroom) 07:41:38 INFO - PID 1665 | RSS: Main: 271372288 07:41:38 INFO - PID 1665 | 07:41:41 INFO - PID 1665 | Cycle 1(1): loaded http://localhost:49224/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49224/tests/kraken/driver.html?testName=imaging-desaturate) 07:41:42 INFO - PID 1665 | RSS: Main: 271548416 07:41:42 INFO - PID 1665 | 07:41:45 INFO - PID 1665 | Cycle 1(1): loaded http://localhost:49224/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49224/tests/kraken/driver.html?testName=json-parse-financial) 07:41:45 INFO - PID 1665 | RSS: Main: 270405632 07:41:45 INFO - PID 1665 | 07:41:47 INFO - PID 1665 | Cycle 1(1): loaded http://localhost:49224/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49224/tests/kraken/driver.html?testName=json-stringify-tinderbox) 07:41:48 INFO - PID 1665 | RSS: Main: 270630912 07:41:48 INFO - PID 1665 | 07:41:49 INFO - PID 1665 | Cycle 1(1): loaded http://localhost:49224/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49224/tests/kraken/driver.html?testName=stanford-crypto-aes) 07:41:50 INFO - PID 1665 | RSS: Main: 270307328 07:41:50 INFO - PID 1665 | 07:41:52 INFO - PID 1665 | Cycle 1(1): loaded http://localhost:49224/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49224/tests/kraken/driver.html?testName=stanford-crypto-ccm) 07:41:53 INFO - PID 1665 | RSS: Main: 269910016 07:41:53 INFO - PID 1665 | 07:41:55 INFO - PID 1665 | Cycle 1(1): loaded http://localhost:49224/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49224/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 07:41:56 INFO - PID 1665 | RSS: Main: 270012416 07:41:56 INFO - PID 1665 | 07:41:59 INFO - PID 1665 | Cycle 1(1): loaded http://localhost:49224/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49224/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 07:42:00 INFO - PID 1665 | RSS: Main: 270266368 07:42:00 INFO - PID 1665 | 07:42:02 INFO - PID 1665 | Cycle 1(1): loaded http://localhost:49224/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49224/tests/kraken/driver.html?testName=ai-astar) 07:42:02 INFO - PID 1665 | RSS: Main: 272158720 07:42:02 INFO - PID 1665 | 07:42:02 INFO - PID 1665 | __start_tp_report 07:42:02 INFO - PID 1665 | _x_x_mozilla_page_load 07:42:02 INFO - PID 1665 | _x_x_mozilla_page_load_details 07:42:02 INFO - PID 1665 | |i|pagename|runs| 07:42:02 INFO - PID 1665 | |0;ai-astar;105;115;116;114;114;116;114;120;115;114 07:42:02 INFO - PID 1665 | |1;audio-beat-detection;170;132;109;147;111;112;114;114;152;179 07:42:02 INFO - PID 1665 | |2;audio-dft;201;210;200;205;205;214;248;252;198;198 07:42:02 INFO - PID 1665 | |3;audio-fft;81;74;52;91;77;84;88;88;90;59 07:42:02 INFO - PID 1665 | |4;audio-oscillator;82;70;84;72;70;70;88;68;71;107 07:42:02 INFO - PID 1665 | |5;imaging-gaussian-blur;89;89;89;89;111;119;89;89;92;97 07:42:02 INFO - PID 1665 | |6;imaging-darkroom;96;123;100;151;148;96;104;150;108;145 07:42:02 INFO - PID 1665 | |7;imaging-desaturate;90;93;91;93;91;92;91;88;94;97 07:42:02 INFO - PID 1665 | |8;json-parse-financial;52;51;50;49;50;50;49;50;51;49 07:42:02 INFO - PID 1665 | |9;json-stringify-tinderbox;61;57;56;62;65;59;56;57;56;58 07:42:02 INFO - PID 1665 | |10;stanford-crypto-aes;82;83;83;84;84;82;84;95;96;74 07:42:02 INFO - PID 1665 | |11;stanford-crypto-ccm;136;138;138;137;135;152;139;136;181;137 07:42:02 INFO - PID 1665 | |12;stanford-crypto-pbkdf2;212;232;193;187;184;189;178;186;184;185 07:42:02 INFO - PID 1665 | |13;stanford-crypto-sha256-iterative;67;69;69;70;71;69;69;73;70;71 07:42:02 INFO - PID 1665 | __end_tp_report 07:42:02 INFO - PID 1665 | __start_cc_report 07:42:02 INFO - PID 1665 | _x_x_mozilla_cycle_collect,924 07:42:02 INFO - PID 1665 | __end_cc_report 07:42:02 INFO - PID 1665 | __startTimestamp1495118522402__endTimestamp 07:42:02 INFO - PID 1665 | 07:42:02 INFO - PID 1665 | ------- Summary: start ------- 07:42:02 INFO - PID 1665 | Number of tests: 14 07:42:02 INFO - PID 1665 | 07:42:02 INFO - PID 1665 | [#0] ai-astar Cycles:10 Average:114.30 Median:115.00 stddev:3.74 (3.3%) stddev-sans-first:1.94 07:42:02 INFO - PID 1665 | Values: 105.0 115.0 116.0 114.0 114.0 116.0 114.0 120.0 115.0 114.0 07:42:02 INFO - PID 1665 | 07:42:02 INFO - PID 1665 | [#1] audio-beat-detection Cycles:10 Average:134.00 Median:139.50 stddev:26.36 (18.9%) stddev-sans-first:24.54 07:42:02 INFO - PID 1665 | Values: 170.0 132.0 109.0 147.0 111.0 112.0 114.0 114.0 152.0 179.0 07:42:02 INFO - PID 1665 | 07:42:02 INFO - PID 1665 | [#2] audio-dft Cycles:10 Average:213.10 Median:207.50 stddev:20.13 (9.7%) stddev-sans-first:20.87 07:42:02 INFO - PID 1665 | Values: 201.0 210.0 200.0 205.0 205.0 214.0 248.0 252.0 198.0 198.0 07:42:02 INFO - PID 1665 | 07:42:02 INFO - PID 1665 | [#3] audio-fft Cycles:10 Average:78.40 Median:86.00 stddev:13.38 (15.6%) stddev-sans-first:14.15 07:42:02 INFO - PID 1665 | Values: 81.0 74.0 52.0 91.0 77.0 84.0 88.0 88.0 90.0 59.0 07:42:02 INFO - PID 1665 | 07:42:02 INFO - PID 1665 | [#4] audio-oscillator Cycles:10 Average:78.20 Median:71.50 stddev:12.34 (17.3%) stddev-sans-first:13.01 07:42:02 INFO - PID 1665 | Values: 82.0 70.0 84.0 72.0 70.0 70.0 88.0 68.0 71.0 107.0 07:42:02 INFO - PID 1665 | 07:42:02 INFO - PID 1665 | [#5] imaging-gaussian-blur Cycles:10 Average:95.30 Median:89.00 stddev:10.85 (12.2%) stddev-sans-first:11.27 07:42:02 INFO - PID 1665 | Values: 89.0 89.0 89.0 89.0 111.0 119.0 89.0 89.0 92.0 97.0 07:42:02 INFO - PID 1665 | 07:42:02 INFO - PID 1665 | [#6] imaging-darkroom Cycles:10 Average:122.10 Median:149.00 stddev:24.01 (16.1%) stddev-sans-first:23.53 07:42:02 INFO - PID 1665 | Values: 96.0 123.0 100.0 151.0 148.0 96.0 104.0 150.0 108.0 145.0 07:42:02 INFO - PID 1665 | 07:42:02 INFO - PID 1665 | [#7] imaging-desaturate Cycles:10 Average:92.00 Median:92.50 stddev:2.45 (2.6%) stddev-sans-first:2.49 07:42:02 INFO - PID 1665 | Values: 90.0 93.0 91.0 93.0 91.0 92.0 91.0 88.0 94.0 97.0 07:42:02 INFO - PID 1665 | 07:42:02 INFO - PID 1665 | [#8] json-parse-financial Cycles:10 Average:50.10 Median:50.00 stddev:0.99 (2.0%) stddev-sans-first:0.78 07:42:02 INFO - PID 1665 | Values: 52.0 51.0 50.0 49.0 50.0 50.0 49.0 50.0 51.0 49.0 07:42:02 INFO - PID 1665 | 07:42:02 INFO - PID 1665 | [#9] json-stringify-tinderbox Cycles:10 Average:58.70 Median:58.50 stddev:3.06 (5.2%) stddev-sans-first:3.13 07:42:02 INFO - PID 1665 | Values: 61.0 57.0 56.0 62.0 65.0 59.0 56.0 57.0 56.0 58.0 07:42:02 INFO - PID 1665 | 07:42:02 INFO - PID 1665 | [#10] stanford-crypto-aes Cycles:10 Average:84.70 Median:84.00 stddev:6.41 (7.6%) stddev-sans-first:6.73 07:42:02 INFO - PID 1665 | Values: 82.0 83.0 83.0 84.0 84.0 82.0 84.0 95.0 96.0 74.0 07:42:02 INFO - PID 1665 | 07:42:02 INFO - PID 1665 | [#11] stanford-crypto-ccm Cycles:10 Average:142.90 Median:138.00 stddev:14.24 (10.3%) stddev-sans-first:14.88 07:42:02 INFO - PID 1665 | Values: 136.0 138.0 138.0 137.0 135.0 152.0 139.0 136.0 181.0 137.0 07:42:02 INFO - PID 1665 | 07:42:02 INFO - PID 1665 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:193.00 Median:188.00 stddev:16.45 (8.7%) stddev-sans-first:15.94 07:42:02 INFO - PID 1665 | Values: 212.0 232.0 193.0 187.0 184.0 189.0 178.0 186.0 184.0 185.0 07:42:02 INFO - PID 1665 | 07:42:02 INFO - PID 1665 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.80 Median:70.00 stddev:1.62 (2.3%) stddev-sans-first:1.36 07:42:02 INFO - PID 1665 | Values: 67.0 69.0 69.0 70.0 71.0 69.0 69.0 73.0 70.0 71.0 07:42:02 INFO - PID 1665 | -------- Summary: end -------- 07:42:02 INFO - PID 1665 | 07:42:03 INFO - TEST-INFO | 1665: exit 0 07:42:03 INFO - TEST-OK | kraken | took 59960ms 07:42:03 INFO - SUITE-END | took 1172s 07:42:03 INFO - Completed test suite (00:19:32) 07:42:03 INFO - javascript benchmark 07:42:03 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7805.999264274205, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3072.416240914226, 3394.6775056369643, 3594.5327107476023, 3605.188832512685, 3615.1865830188226, 5672.0, 6147.293061297946, 6156.692165391723, 6215.937840621598, 6258.0, 6243.688825346682, 6247.0, 6260.874782504347, 6288.528360372979, 6317.557770956035, 5491.368492623359, 5511.090670039439, 5527.585431092664, 5532.419095994923, 5638.351589567191, 5604.943950560503, 5652.717364131777, 5667.838093190907, 5675.971620141893, 5698.373178950312, 4634.39752832133, 5444.128939369682, 5531.944680553183, 5584.748686309126, 5658.519025882784, 5560.304961879765, 5591.440855914417, 5603.047481928042, 5616.719164041781, 5619.719014049281, 5527.115661494182, 5549.223108764776, 5567.192757050205, 5580.804671836466, 5586.932834851091, 5011.899762004739, 6219.378062193744, 6323.6521991290465, 6339.8732025359695, 6369.490440764727, 6697.3637504437, 6744.325567443266, 6831.555948863358, 6847.726090956356, 6850.862982740367, 6898.655067246617, 6925.515213935027, 6948.652567371611, 6957.79126626202, 6993.055937448481, 6357.0, 6366.204224471941, 6406.23125224976, 6406.615603063831, 6413.615183089029, 6838.623875686785, 6933.168019837652, 6989.790306290819, 7026.332498412643, 7051.717931282794, 6669.412097426499, 6734.629595372302, 6742.797716068526, 6756.121704178426, 6763.12079429671, 6707.53047286695, 6895.0, 6913.066735990677, 6915.239323674392, 6922.203946546211, 5784.971075144597, 6585.703643336062, 6770.492213084039, 6784.559003664746, 6799.150106236721, 5770.394108618619, 6809.965950170316, 6839.487038472134, 6862.519623626398, 6885.345892140339, 5392.352917649907, 5401.540869026098, 5425.728713564306, 5449.209864569615, 5470.617056806065, 4790.880227994328, 4805.6155507559315, 4824.734639594786, 4869.074875773592, 4899.779509921991, 6273.153124328249, 6280.811575652738, 6305.905411418832, 6319.0837328587095, 6326.335734747877, 6868.175818901764, 6886.173659160932, 6894.551854129466, 6896.413804826646, 6904.965475172592, 6284.780032698834, 6285.811425657146, 6290.716917738713, 6303.3066362700065, 6338.58799178052, 4536.6824322297125, 6093.725782339805, 6145.125736650364, 6163.784267550704, 6176.8764624707255, 7052.471064670171, 7078.362947334765, 7085.114360704912, 7099.964500177466, 7108.431325494052, 6719.0, 6837.965810171117, 6963.651817409211, 6965.303469652994, 6987.790366289019, 6971.477139214478, 7022.473314501331, 7041.366277035091, 7046.260142684944, 7066.152061752623, 6832.282610326043, 6843.555168914005, 6856.657167141722, 6861.691223894837, 6907.896381554381, 5313.879401601872, 6726.764563240263, 6803.795886123424, 6904.93095069063, 6961.61711105894, 5674.943250567607, 6634.270230274569, 6685.532012759255, 6738.561993470409, 6765.661716914233, 6649.501287403367, 6704.865902681821, 6735.932640673531, 6742.0, 6747.865042699217, 6296.779612713716, 6369.617822930639, 6560.704768285344, 6635.071090047494, 6636.369544893228, 6595.406413422815, 6635.6350400728425, 6731.865362692816, 6734.393904548614, 6778.8983165251575, 6573.967130164318, 6581.308962559054, 6590.80227593173, 6601.867962640817, 6645.468362531083], "value": 6202.634535625002, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [784.2197013971096, 800.3197282310036, 822.543488363958, 1061.6655753437672, 1249.8250244965711, 35927.922162335104, 36881.815590922044, 36910.07724806881, 36930.44604330937, 36938.44592331117, 37397.43903841444, 37791.43312850309, 37846.81076594613, 37864.62135378645, 38043.23913521728, 7712.537247765138, 7854.803629909241, 8069.757907262791, 8272.958635206816, 8286.088530261666, 7441.813954651123, 7921.653318935795, 8160.5103693778565, 8164.0, 8290.419670623029, 8191.262786349227, 8192.18078192179, 8292.087870334259, 8292.792680182984, 8314.625841837133, 8214.671413143496, 8240.022394625306, 8242.464239824392, 8247.051589067214, 8286.875696864552, 8180.877286840702, 8233.670653173866, 8242.587870606505, 8244.093149753522, 8261.710840120568, 29430.852845735848, 35517.57929682836, 35548.75579354736, 35562.28875422477, 35649.46525802115, 3626.111602657366, 3668.706503479715, 3671.5777685565968, 3673.75092468562, 3677.6506231907924, 29131.563026554617, 29144.979925702497, 29230.56154157668, 29361.559576606367, 29395.55906661402, 3043.4673932062024, 3068.417000769869, 3072.2933725243097, 3075.56942028114, 3082.676318986519, 287185.0, 288955.0, 293433.0, 299637.50181249174, 301811.49094254605, 39368.80315598404, 43697.78151109224, 43775.78112109419, 43817.561824381984, 43916.682499524424, 45718.0, 48351.516484834705, 49043.75478122586, 49117.0, 49144.0, 13777.035607507578, 13822.861771382359, 13940.58178254654, 13956.720865582834, 13961.0, 11074.335539867634, 11250.212485126127, 11269.774604507864, 11302.491387887565, 11311.717207069889, 10810.567577296977, 12955.417006234742, 12958.935205323913, 12984.026198035184, 13008.739825203444, 4923.630727695438, 5837.176639340159, 5880.7353669085, 5893.911591326134, 5902.6458412495385, 992.8510723391405, 1021.0861279155263, 1025.7692019295748, 1044.5769463367349, 1046.2362475392854, 22185.889070554545, 22929.31212063641, 23719.525609487715, 23973.940717129575, 24093.277201683977, 1091.170710260208, 1101.862267216598, 1135.0919264588297, 1137.6871360375796, 1145.8281257811395, 10156.796864062826, 11580.942095289638, 11739.06087512997, 12228.327441990776, 12508.624741257778, 238818.0, 279522.0, 280859.0, 280956.0, 281822.0, 49921.0, 50208.74895625572, 50397.74005649815, 50540.74729626402, 50644.49355506471, 1416.7591509443419, 1439.2156274830033, 1488.5608745420127, 1498.1685164733678, 1519.9012064216013, 16996.660066798842, 19832.70250946266, 23393.649095263245, 23400.414989625395, 23450.296491105295, 35473.82263088771, 35554.288914221055, 35561.28877422385, 35676.46485302671, 35752.64247357493, 256316.0, 257057.0, 257408.0, 257490.0, 257682.0, 26093.043021773065, 30960.0, 31250.0, 31898.0, 32177.356452870343, 41361.1727765449, 41530.79234603808, 41574.1685166301, 41591.1681766369, 41610.58389416067, 27286.590701139896, 27771.583426249028, 28318.212491564984, 30359.23157748636, 30448.543271850496, 29711.554326684683, 29784.702152979058, 30200.94296699605, 30373.936912207966, 30719.2320191997, 36252.0, 36276.0, 36358.45462318014, 36420.0, 36467.817660911525, 29236.853815730785, 29284.97502587399, 29968.651410686136, 30707.385852283274, 30872.382552349278, 21975.0, 23068.653970190124, 24030.27909162728, 24182.879085604458, 24424.0], "value": 16793.72538256245, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [406.96540794032506, 431.17430121317676, 436.08639899410724, 438.2746554452381, 460.8410098516013, 18320.633587328262, 19957.301494447678, 20614.69077963828, 20691.379258622248, 20741.274055408063, 21273.55354134275, 22952.96711647976, 23140.190093346693, 23672.0, 23955.64066538999, 5221.947780522194, 5277.868053298679, 6226.501879849624, 6246.156768836217, 6256.781012664557, 5983.551233657471, 6096.603720758137, 6157.476614487774, 6165.229346331708, 6178.351273116303, 5942.257217847769, 6136.079588061804, 6138.723757430904, 6140.07898815179, 6141.6007959482495, 6015.639061656293, 6112.602680825777, 6146.17026701394, 6152.261728592575, 6155.292141403751, 5283.101872681654, 5377.139657654757, 5464.316960379952, 5473.042217611902, 5493.038718224335, 24568.754312457004, 25862.482750344887, 27542.17373478799, 27720.722792772016, 27969.720302796915, 2173.1416090644266, 2259.1754009786473, 2259.638457846753, 2264.603694353498, 2268.88655567221, 13873.236971966538, 17800.732989005177, 17896.731549026776, 18238.726419103725, 18290.890548606636, 2137.369476004567, 2162.1891790578534, 2197.8681279123143, 2200.119952019189, 2207.2826331442348, 136694.0, 136916.0, 137475.0, 137606.30346420567, 144927.0, 11843.703907402383, 12277.140600158082, 12279.956203722784, 12323.815142772866, 12348.197367171106, 14253.857461425254, 14568.05307654999, 15615.609609759847, 15623.765643515357, 15671.529854104396, 7702.8844567331535, 8251.8349633007, 8602.91397086021, 8883.022867484571, 8904.510251936206, 8415.326773858076, 8485.278751306209, 8582.725577721909, 8655.610497527501, 8671.739847804576, 7074.9646251769445, 7543.321101100927, 7620.035566093679, 7732.458727888994, 7949.125596184415, 2119.470132466883, 2175.5757627262847, 2179.2808373236794, 2188.982123312667, 2195.538936823253, 889.6174644902754, 961.0293603460556, 975.6341371985505, 980.2990861534038, 993.1707024634367, 19195.904020480088, 20923.163073477193, 21445.67831482529, 21546.0, 21591.46021349479, 867.3494878840869, 954.1889394015031, 1008.0473952115327, 1015.2132097624252, 1037.5123691864962, 10922.508487118099, 11171.218014739054, 11254.606088786853, 11262.042726368187, 11275.210735248618, 528492.0, 532870.0, 538117.0, 540750.0, 540801.0, 119991.0, 122251.55496890012, 124807.3759631196, 126313.0, 127549.0, 1142.9257098288751, 1182.5842974145144, 1192.7495226002636, 1198.6703656494533, 1199.7900367435664, 16307.184640768151, 18098.185581648595, 18430.81569184291, 18584.52642626621, 18632.254709811456, 52555.10946897957, 53949.0, 54520.45479545312, 54950.45049549613, 55316.72341638266, 72542.37288135677, 72607.0, 72748.08856323594, 73350.0, 74214.62892685502, 8743.91256087431, 8834.072422395557, 8865.778355541162, 9221.585028673591, 9294.767630809283, 10847.620333288298, 14074.507392241223, 14232.074915130897, 14403.0, 14407.279636017947, 10718.0, 11719.179657423898, 11899.583514576949, 12134.150609457607, 12175.391230438265, 8552.828943420973, 10273.486325683836, 10586.0, 11320.37737924422, 11491.597794077166, 9287.396319239499, 12091.51633934668, 12131.21147125434, 12137.939310303393, 12422.316772577595, 7629.961850190713, 7787.4548781584745, 8656.394052416523, 8772.254358379609, 9036.23192028685, 11718.179727419, 12014.699632509257, 12019.158658893793, 12058.939705301418, 12155.0], "value": 10217.902445004991, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1404.641816336834, 1457.7520924671192, 1474.6239708874236, 1478.4825311141099, 1487.8363380028202, 11602.59390921318, 11934.0, 12114.515419383226, 12190.756184876309, 12283.44724487398, 11984.34086125265, 11996.880031199686, 12139.21095128816, 12190.69523261919, 12210.084243681715, 2602.4404752978107, 2633.5654616988268, 2643.695974962875, 2647.9602805957925, 2654.734526547349, 2552.961705574418, 2612.32079659289, 2616.9084082057134, 2623.7376262373705, 2624.7506486883717, 2572.768450839424, 2598.8440693558446, 2625.4749050189944, 2628.1195799407224, 2628.4480259145603, 2549.821512494126, 2566.3199252198183, 2610.282172402595, 2610.9347266318305, 2611.830231034977, 2568.704598971105, 2592.5722255827764, 2597.402597402589, 2617.659704238456, 2624.39638883058, 11756.764864702658, 11961.760764784742, 11969.940150299193, 11971.880281197251, 11994.22037567564, 6607.5374723769355, 6854.931450685479, 6857.177138743383, 6858.622775747332, 6864.141982252218, 4018.819153138116, 4047.959520404788, 4330.870073897788, 4378.912421751547, 4388.341748737683, 1209.776191404584, 1236.190295356543, 1261.9369031548385, 1282.1025282302426, 1282.160185078775, 15953.521394358188, 16524.339026438927, 16540.6691866162, 16663.16684165799, 16729.996200228026, 5522.751476183581, 5580.441955804387, 5735.2257445245195, 5756.539476841843, 5778.248827652463, 11756.94121529404, 11771.058315334752, 11773.058155347722, 11780.764384712258, 11812.055035597132, 6644.302348253364, 6646.401823835878, 6652.600843949379, 6654.800355989328, 6763.154605674291, 6037.698115094227, 6152.077188421683, 6171.938280617136, 6211.65835879031, 6484.643344616092, 3464.8440820163155, 3823.311803875329, 4361.80371883266, 4420.977895110504, 4426.623736982393, 1201.4113084588664, 1210.2133613151345, 1285.9292738899264, 1293.9741205175844, 1305.340802894532, 796.323125343465, 803.1606970715616, 818.3902992270796, 829.6432534010434, 851.7955690634327, 5664.158656117401, 6182.598131121463, 6390.840228994312, 6396.264429590564, 6421.2615549211505, 591.5740666719955, 600.4895838537209, 603.9214902062704, 607.1499900139839, 618.1716499890168, 3861.4787003754695, 3867.4778904847485, 3889.144388234584, 3928.489296391451, 3943.7042221833476, 14564.85435145656, 14664.706705865823, 15058.096514208963, 15062.774058389134, 15102.848971510364, 12313.50745970169, 14793.408263669548, 14892.553223403098, 14955.700885982436, 15009.474668386554, 7495.662695178621, 7528.736494222567, 7584.596849582845, 8060.677572897136, 8590.012148602866, 6621.9337806621315, 7171.641417929187, 7306.780796576111, 7310.6710198040155, 7333.523320984119, 12648.039553869165, 12700.745985080432, 12762.0, 12816.6795830105, 13211.603651890458, 13596.524121655695, 13596.592102236553, 13630.454781808616, 13695.178289302674, 13947.511837085653, 8064.959675201587, 8213.753587392388, 8221.218984196492, 8233.958830205811, 8242.381821363302, 10595.629152979609, 10638.521266543179, 10738.355698658106, 10762.3542587445, 10981.890181098086, 7723.343515800995, 7855.410844186823, 9181.908180918106, 9247.907520924704, 9407.717768466957, 8990.01109877926, 9055.185033346766, 9088.863667044867, 9113.3165012623, 9217.124373184537, 8242.134575869448, 8269.917300827155, 8400.0, 8424.915750842412, 8427.19941605571, 5245.527902488794, 6026.638401695912, 6194.0708893666315, 6219.346968568416, 6323.747050118128, 6180.752769889334, 6191.938080619136, 6298.590591611531, 6362.745490180526, 6430.2926678066315], "value": 5330.429016046659, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [383.1474968195765, 401.604419646648, 406.7925358067385, 409.42475821470805, 412.0995624560337, 5387.4073851876265, 5606.831795046145, 6381.712822922968, 6399.816148919379, 6403.199600049994, 4822.638302127346, 6181.227346581677, 6203.844903877405, 6209.223847019122, 6212.161358216651, 4488.438945131858, 4525.275955847065, 4527.841525546606, 4539.4098767160385, 4562.836476698437, 3639.909002274951, 4394.47266328041, 4487.596116349528, 4524.366588677572, 4557.475890272612, 4467.441569803775, 4483.7758112094425, 4484.215262329096, 4484.9460376811585, 4490.899729566261, 3769.736118471694, 3935.1145992151824, 4311.310190369526, 4498.460184777831, 4523.683342166049, 3767.7174211934216, 3826.349520581508, 3862.7102967277565, 3863.826127824255, 3866.7819636814315, 6035.7887473938645, 6052.03167493199, 6054.909176362358, 6070.817875463743, 6085.208922840003, 4230.428892099558, 5164.561012313958, 5352.143657014859, 5371.865703357408, 5382.219578161184, 5552.916706249369, 6318.2733985591785, 6608.669566521655, 6691.59850408977, 6711.49663775214, 3687.889363319078, 3784.9402167393173, 4405.515393306734, 4405.801738921724, 4408.316710909814, 5904.380040095771, 6844.0, 7090.113735783028, 7176.892346614805, 7176.96411517939, 4449.377087207794, 4450.176717307244, 4450.310201918708, 4456.844010459618, 4488.910221795546, 4537.705049171794, 4538.410006699108, 4542.6593005524055, 4557.589816916494, 4564.356831540643, 4134.421181034658, 5174.482551744795, 5386.596005299618, 5393.865153371197, 5403.702796346239, 6190.628562286277, 6740.561863478858, 6743.763968261087, 6792.388685018372, 6869.278725733826, 1189.7858385490522, 1213.3933033483258, 1454.8181477315336, 1498.8983097423386, 1537.085434166669, 991.177322822056, 996.9800603987879, 997.7804882925744, 1002.6941782756184, 1011.8229309870743, 5559.027170245191, 6193.721282542297, 6357.904631430532, 6384.6169229846355, 6390.265119511223, 6530.379613936763, 6748.932510674928, 6978.511504194657, 7002.264762199998, 7035.959571724245, 6035.547333949883, 6239.188905442355, 6274.529410294246, 6288.151099601495, 6378.330275321117, 3031.9848400757855, 3087.5831762711746, 3546.733994950389, 3638.672519473207, 3649.1606930406156, 7140.57156570597, 7170.856582868313, 7197.640117994079, 7207.13514378278, 7219.783406497813, 6983.650817459208, 7172.677229524684, 7186.209516953131, 7191.7842464726145, 7223.92776072243, 6389.690113526716, 6455.515836312202, 7144.8928266076055, 7206.135263768277, 7206.828995242076, 5937.435943585352, 6893.483878384454, 7020.052292940388, 7071.257517960746, 7077.221505634273, 6738.191417029988, 6781.847085995294, 6865.077778222106, 6904.032350780004, 6934.9250866115835, 7179.174394944439, 7183.389411899838, 7190.280971902768, 7214.531055481377, 7226.241244669444, 4356.368326592689, 4457.933131003102, 4508.45898492183, 4510.571495707903, 4512.887177820581, 7015.824604384931, 7072.929270707433, 7084.64576771149, 7112.50212485121, 7152.141742990666, 5706.885862282648, 6825.590464571943, 6834.692438840165, 6841.076454678754, 6854.862902741817, 6694.29709880475, 6696.1629796275465, 6724.0, 6765.54540773736, 6772.661366931732, 6592.967035164794, 6594.274629790815, 6622.0, 6635.966820165868, 6652.511677865877, 5569.582281328835, 6560.195946114856, 6740.090087838079, 6744.0895479109695, 6799.184097908283, 6472.288048314776, 6607.834804129935, 6627.255600585654, 6662.300458451987, 6673.499487538551], "value": 4842.796834070394, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [832.2468166309492, 843.4264700003997, 862.7670528957178, 864.7319331007388, 874.895012598488, 16621.252043658034, 16631.584210394733, 17043.82956170438, 17962.461126166236, 18976.05119744014, 17374.305027798906, 17802.82197178028, 18078.367257146, 18144.274229030823, 18436.907815460905, 996.8255555277833, 998.3660375661452, 1014.2798612984752, 1016.6289304403913, 1017.9083882450578, 954.9999999999965, 988.9505524723771, 1011.4993078426153, 1011.6358111080006, 1018.399144504742, 1044.634377967713, 1047.2983101322131, 1049.9737506562358, 1050.658535975811, 1051.1748277602085, 1048.0672201740458, 1050.8423736439595, 1051.0855555666542, 1051.952662130206, 1053.6628278950739, 756.701103064292, 764.4190415284423, 785.3324674027089, 785.7210690204952, 789.5973053742575, 16469.58826029347, 16688.165591720488, 16705.99764014163, 17130.400435984804, 17349.13254337278, 16851.241694123793, 17038.403655871985, 17374.305027798873, 17405.303787848472, 17570.736438953427, 12276.570320038845, 14330.92834535831, 14421.351039203157, 14755.04092234012, 14882.255887205596, 3255.462848629973, 3666.0651533858695, 3680.3743363628246, 3681.5582130144553, 3682.4660424238605, 105279.0, 106914.0, 108470.74587762379, 117198.0, 119090.0, 13354.666133346744, 13560.254186019863, 13716.051049024507, 13719.794203086962, 13740.038197326292, 24713.0, 25166.622500662506, 25780.74219257821, 26099.869500652756, 26128.738712613012, 7709.190534993857, 7878.1334053254095, 7885.2114788520685, 7889.6844126235455, 7959.9602001989615, 3754.8685795997008, 3893.124047089439, 3916.5691773904846, 3954.60453954608, 3989.401589761501, 4132.318167502329, 4221.746695198237, 4232.470941132358, 4238.491381034234, 4246.7027308088755, 913.9131782480654, 950.7242899559188, 953.6709835106877, 961.9711408657752, 962.0523784072683, 644.3234603666225, 657.7599176300712, 659.9670016499156, 661.2395744893411, 684.1004079635363, 10922.508487118099, 11700.414979251003, 12049.578264760692, 13195.868041319463, 13288.800667989988, 660.9339066093352, 665.896785998171, 671.1677519875319, 676.4655921821804, 696.3350000749273, 4019.477467929209, 4407.669424793154, 5373.516383525502, 5436.891262174734, 5622.746976386073, 90464.0, 90729.54635226782, 92093.5395323019, 92332.0, 93169.13661726862, 75933.24066759372, 76792.0, 78110.21889781143, 78818.6059069701, 79133.60433197797, 71624.0, 72157.6392118036, 72504.63747681228, 72878.0, 73090.9036364455, 11012.284201526874, 11033.393163376091, 11040.227184097035, 11300.49147788368, 11317.603883864025, 33542.65829366798, 36406.27187456183, 37040.81479592585, 37952.810235948644, 40116.59883401129, 53033.93932121413, 64306.35693643191, 65385.34614653792, 67447.0, 68257.65871170613, 13768.724625507233, 14150.929245354118, 14334.283285835874, 15633.374665013273, 16869.746953795457, 17543.912280438515, 17972.910135449238, 18121.90939045296, 18377.264909403475, 18592.0, 16502.587435313733, 16765.413210537572, 16973.0, 17322.047287399313, 17376.73934891003, 16752.0, 17156.39952601653, 17162.8114319682, 17372.0, 17381.82618173853, 14885.479008234659, 14977.326020329328, 15419.306131224346, 15462.690746185239, 15499.767503487232, 10425.0096240857, 10440.373577585066, 10610.416427096583, 10731.517081731185, 10794.298370605886, 11081.72295692582, 12020.75958480808, 12048.698782530157, 12103.515859365527, 12207.75584488323], "value": 8233.778372326407, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1526.6000000000001, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [105.0, 115.0, 116.0, 114.0, 114.0, 116.0, 114.0, 120.0, 115.0, 114.0], "value": 114.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [170.0, 132.0, 109.0, 147.0, 111.0, 112.0, 114.0, 114.0, 152.0, 179.0], "value": 134.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [201.0, 210.0, 200.0, 205.0, 205.0, 214.0, 248.0, 252.0, 198.0, 198.0], "value": 213.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 74.0, 52.0, 91.0, 77.0, 84.0, 88.0, 88.0, 90.0, 59.0], "value": 78.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 70.0, 84.0, 72.0, 70.0, 70.0, 88.0, 68.0, 71.0, 107.0], "value": 78.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 89.0, 89.0, 89.0, 111.0, 119.0, 89.0, 89.0, 92.0, 97.0], "value": 95.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 123.0, 100.0, 151.0, 148.0, 96.0, 104.0, 150.0, 108.0, 145.0], "value": 122.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 93.0, 91.0, 93.0, 91.0, 92.0, 91.0, 88.0, 94.0, 97.0], "value": 92.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [52.0, 51.0, 50.0, 49.0, 50.0, 50.0, 49.0, 50.0, 51.0, 49.0], "value": 50.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 57.0, 56.0, 62.0, 65.0, 59.0, 56.0, 57.0, 56.0, 58.0], "value": 58.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 83.0, 83.0, 84.0, 84.0, 82.0, 84.0, 95.0, 96.0, 74.0], "value": 84.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [136.0, 138.0, 138.0, 137.0, 135.0, 152.0, 139.0, 136.0, 181.0, 137.0], "value": 142.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [212.0, 232.0, 193.0, 187.0, 184.0, 189.0, 178.0, 186.0, 184.0, 185.0], "value": 193.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 69.0, 69.0, 70.0, 71.0, 69.0, 69.0, 73.0, 70.0, 71.0], "value": 69.8, "unit": "score"}]}]} 07:42:03 INFO - Return code: 0 07:42:03 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 07:42:03 INFO - # TBPL SUCCESS # 07:42:03 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 07:42:03 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 07:42:03 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 07:42:03 INFO - 2017-05-18 07:42:03.755 screenresolution[1672:11322] starting screenresolution argv=screenresolution get 07:42:03 INFO - 2017-05-18 07:42:03.758 screenresolution[1672:11322] Display 0: 1600x1200x32@60 07:42:03 INFO - 2017-05-18 07:42:03.764 screenresolution[1673:11325] starting screenresolution argv=screenresolution list 07:42:03 INFO - Available Modes on Display 0 07:42:03 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 07:42:03 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 07:42:03 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 07:42:03 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 07:42:03 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 07:42:03 INFO - Intel Iris: 07:42:03 INFO - Chipset Model: Intel Iris 07:42:03 INFO - Type: GPU 07:42:03 INFO - Bus: Built-In 07:42:03 INFO - VRAM (Dynamic, Max): 1536 MB 07:42:03 INFO - Vendor: Intel (0x8086) 07:42:03 INFO - Device ID: 0x0a2e 07:42:03 INFO - Revision ID: 0x0009 07:42:03 INFO - Displays: 07:42:03 INFO - Display: 07:42:03 INFO - Resolution: 1600 x 1200 @ 60 Hz 07:42:03 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 07:42:03 INFO - Display Serial Number: 1600x1200 60 07:42:03 INFO - Main Display: Yes 07:42:03 INFO - Mirror: Off 07:42:03 INFO - Online: Yes 07:42:03 INFO - Rotation: Supported 07:42:04 INFO - Return code: 0 07:42:04 INFO - Running post-action listener: _resource_record_post_action 07:42:04 INFO - [mozharness: 2017-05-18 14:42:04.002130Z] Finished run-tests step (success) 07:42:04 INFO - Running post-run listener: _resource_record_post_run 07:42:04 INFO - Total resource usage - Wall time: 1196s; CPU: 54.0%; Read bytes: 35492864; Write bytes: 578670592; Read time: 227; Write time: 1746 07:42:04 INFO - TinderboxPrint: CPU usage
54.2% 07:42:04 INFO - TinderboxPrint: I/O read bytes / time
35,492,864 / 227 07:42:04 INFO - TinderboxPrint: I/O write bytes / time
578,670,592 / 1,746 07:42:04 INFO - TinderboxPrint: CPU idle
2,191.1 (45.8%) 07:42:04 INFO - TinderboxPrint: CPU system
965.3 (20.2%) 07:42:04 INFO - TinderboxPrint: CPU user
1,629.3 (34.0%) 07:42:04 INFO - TinderboxPrint: Swap in / out
290,160,640 / 0 07:42:04 INFO - install - Wall time: 23s; CPU: 54.0%; Read bytes: 236904960; Write bytes: 232835072; Read time: 17535; Write time: 486 07:42:04 INFO - run-tests - Wall time: 1174s; CPU: 54.0%; Read bytes: 34513920; Write bytes: 315525120; Read time: 214; Write time: 1191 07:42:04 INFO - Running post-run listener: _upload_blobber_files 07:42:04 INFO - Blob upload gear active. 07:42:04 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 07:42:04 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 07:42:04 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'] 07:42:04 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 07:42:04 INFO - (blobuploader) - INFO - Open directory for files ... 07:42:04 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 07:42:04 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:42:04 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:42:06 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 07:42:06 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:42:06 INFO - (blobuploader) - INFO - Done attempting. 07:42:06 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 07:42:06 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:42:06 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:42:06 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 07:42:06 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:42:06 INFO - (blobuploader) - INFO - Done attempting. 07:42:06 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 07:42:06 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:42:06 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:42:07 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 07:42:07 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:42:07 INFO - (blobuploader) - INFO - Done attempting. 07:42:07 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 07:42:07 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:42:07 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:42:10 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 07:42:10 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:42:10 INFO - (blobuploader) - INFO - Done attempting. 07:42:10 INFO - (blobuploader) - INFO - Iteration through files over. 07:42:10 INFO - Return code: 0 07:42:10 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 07:42:10 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 07:42:10 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/79fdc6a8949854b7f2723ba9aab47fdb167a6fb6289845c9cbfdf894d9ad9783d9a39e8bcc31aa9a6f0cef3b00166c3a33bb532b24eb2c89ad099ed6d98bef9b", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e6fa62376f0c8d65c7a296bcc3e80b28c14c28f631cbe7dc191ecbf6e2b7e8597f2a87b0f5aa5761c1c81a2cbe9979429c15923db02378b7925c6c60cf797450", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/246fac8194ece5d8534612c0f50bd495df8fb100712b89a6865d04716a7b25571c695dbafc925a8ea19a599136960cc67cc56e44d48ba477c7be579bcc7d588f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/586d63b590440d38d4d2bd7231308a2cb86a4c866639ec60a159e7b6e903c3894d47fd6fd8479cf596f34aab0767b52e18f84b650c5d70de953c56c1c81c4fcf"} 07:42:10 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 07:42:10 INFO - Writing to file /builds/slave/test/properties/blobber_files 07:42:10 INFO - Contents: 07:42:10 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/79fdc6a8949854b7f2723ba9aab47fdb167a6fb6289845c9cbfdf894d9ad9783d9a39e8bcc31aa9a6f0cef3b00166c3a33bb532b24eb2c89ad099ed6d98bef9b", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e6fa62376f0c8d65c7a296bcc3e80b28c14c28f631cbe7dc191ecbf6e2b7e8597f2a87b0f5aa5761c1c81a2cbe9979429c15923db02378b7925c6c60cf797450", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/246fac8194ece5d8534612c0f50bd495df8fb100712b89a6865d04716a7b25571c695dbafc925a8ea19a599136960cc67cc56e44d48ba477c7be579bcc7d588f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/586d63b590440d38d4d2bd7231308a2cb86a4c866639ec60a159e7b6e903c3894d47fd6fd8479cf596f34aab0767b52e18f84b650c5d70de953c56c1c81c4fcf"} 07:42:10 INFO - Running post-run listener: copy_logs_to_upload_dir 07:42:10 INFO - Copying logs to upload dir... 07:42:10 INFO - mkdir: /builds/slave/test/build/upload/logs 07:42:10 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1274.933670 ========= master_lag: 0.07 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2017-05-18 07:42:09.938544) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-18 07:42:09.941838) ========= 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.QSPwAg0hvc/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.fBo9SoWbbl/Listeners TMPDIR=/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/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/79fdc6a8949854b7f2723ba9aab47fdb167a6fb6289845c9cbfdf894d9ad9783d9a39e8bcc31aa9a6f0cef3b00166c3a33bb532b24eb2c89ad099ed6d98bef9b", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e6fa62376f0c8d65c7a296bcc3e80b28c14c28f631cbe7dc191ecbf6e2b7e8597f2a87b0f5aa5761c1c81a2cbe9979429c15923db02378b7925c6c60cf797450", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/246fac8194ece5d8534612c0f50bd495df8fb100712b89a6865d04716a7b25571c695dbafc925a8ea19a599136960cc67cc56e44d48ba477c7be579bcc7d588f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/586d63b590440d38d4d2bd7231308a2cb86a4c866639ec60a159e7b6e903c3894d47fd6fd8479cf596f34aab0767b52e18f84b650c5d70de953c56c1c81c4fcf"} build_url:https://queue.taskcluster.net/v1/task/F4Q5KJeCSiagQpQf34mxHg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011274 build_url: 'https://queue.taskcluster.net/v1/task/F4Q5KJeCSiagQpQf34mxHg/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/79fdc6a8949854b7f2723ba9aab47fdb167a6fb6289845c9cbfdf894d9ad9783d9a39e8bcc31aa9a6f0cef3b00166c3a33bb532b24eb2c89ad099ed6d98bef9b", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e6fa62376f0c8d65c7a296bcc3e80b28c14c28f631cbe7dc191ecbf6e2b7e8597f2a87b0f5aa5761c1c81a2cbe9979429c15923db02378b7925c6c60cf797450", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/246fac8194ece5d8534612c0f50bd495df8fb100712b89a6865d04716a7b25571c695dbafc925a8ea19a599136960cc67cc56e44d48ba477c7be579bcc7d588f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/586d63b590440d38d4d2bd7231308a2cb86a4c866639ec60a159e7b6e903c3894d47fd6fd8479cf596f34aab0767b52e18f84b650c5d70de953c56c1c81c4fcf"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-18 07:42:09.974879) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-18 07:42:09.975212) ========= 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.QSPwAg0hvc/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.fBo9SoWbbl/Listeners TMPDIR=/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/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.005309 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-18 07:42:10.024650) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-18 07:42:10.025063) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-18 07:42:10.025423) ========= ========= Total master_lag: 0.20 =========