builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0054 starttime: 1495154267.59 results: success (0) revision: 3801e9bd76ad91b5754faacd3fec9ad4bc3fc782 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-05-18 17:37:47.592883) ========= master: http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-05-18 17:37:47.593450) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-18 17:37:47.593757) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-18 17:37:47.619759) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-18 17:37:47.620221) ========= 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.gJZZz7AtDy/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.FWk9oAC2TD/Listeners TMPDIR=/var/folders/fp/_7br9k0s4j30pf06c5ky859c00000w/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 17:37:47-- 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% 15.3M=0.001s 2017-05-18 17:37:47 (15.3 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.143045 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-18 17:37:47.787003) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-18 17:37:47.787457) ========= 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.gJZZz7AtDy/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.FWk9oAC2TD/Listeners TMPDIR=/var/folders/fp/_7br9k0s4j30pf06c5ky859c00000w/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.028031 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-18 17:37:47.837406) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-18 17:37:47.837859) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 3801e9bd76ad91b5754faacd3fec9ad4bc3fc782 --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 3801e9bd76ad91b5754faacd3fec9ad4bc3fc782 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.gJZZz7AtDy/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.FWk9oAC2TD/Listeners TMPDIR=/var/folders/fp/_7br9k0s4j30pf06c5ky859c00000w/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 17:37:47,945 truncating revision to first 12 chars 2017-05-18 17:37:47,945 Setting DEBUG logging. 2017-05-18 17:37:47,945 attempt 1/10 2017-05-18 17:37:47,945 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/3801e9bd76ad?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-05-18 17:37:48,905 unpacking tar archive at: mozilla-central-3801e9bd76ad/testing/mozharness/ program finished with exit code 0 elapsedTime=1.240402 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-18 17:37:49.100563) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-18 17:37:49.100973) ========= script_repo_revision: 3801e9bd76ad91b5754faacd3fec9ad4bc3fc782 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-18 17:37:49.101376) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-18 17:37:49.101688) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-18 17:37:49.121040) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 17 secs) (at 2017-05-18 17:37:49.121479) ========= /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.gJZZz7AtDy/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.FWk9oAC2TD/Listeners TMPDIR=/var/folders/fp/_7br9k0s4j30pf06c5ky859c00000w/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 17:37:49 INFO - MultiFileLogger online at 20170518 17:37:49 in /builds/slave/test 17:37:49 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 17:37:49 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 17:37:49 INFO - {'append_to_log': False, 17:37:49 INFO - 'base_work_dir': '/builds/slave/test', 17:37:49 INFO - 'blob_upload_branch': 'Firefox', 17:37:49 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 17:37:49 INFO - 'branch': 'Firefox', 17:37:49 INFO - 'buildbot_json_path': 'buildprops.json', 17:37:49 INFO - 'config_files': ('talos/mac_config.py',), 17:37:49 INFO - 'default_actions': ('clobber', 17:37:49 INFO - 'read-buildbot-config', 17:37:49 INFO - 'download-and-extract', 17:37:49 INFO - 'populate-webroot', 17:37:49 INFO - 'create-virtualenv', 17:37:49 INFO - 'install', 17:37:49 INFO - 'run-tests'), 17:37:49 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 17:37:49 INFO - 'download_minidump_stackwalk': True, 17:37:49 INFO - 'download_symbols': 'ondemand', 17:37:49 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 17:37:49 INFO - 'tooltool.py': '/tools/tooltool.py', 17:37:49 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 17:37:49 INFO - '/tools/misc-python/virtualenv.py')}, 17:37:49 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 17:37:49 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 17:37:49 INFO - 'gecko_profile': False, 17:37:49 INFO - 'gecko_profile_interval': 0, 17:37:49 INFO - 'installer_path': 'installer.exe', 17:37:49 INFO - 'log_level': 'info', 17:37:49 INFO - 'log_name': 'talos', 17:37:49 INFO - 'log_to_console': True, 17:37:49 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 17:37:49 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 17:37:49 INFO - 'opt_config_files': (), 17:37:49 INFO - 'pip_index': False, 17:37:49 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 17:37:49 INFO - 'cmd': ('bash', 17:37:49 INFO - '-c', 17:37:49 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 17:37:49 INFO - 'enabled': True, 17:37:49 INFO - 'halt_on_failure': False, 17:37:49 INFO - 'name': 'check_screen_resolution'},), 17:37:49 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 17:37:49 INFO - 'cmd': ('bash', 17:37:49 INFO - '-c', 17:37:49 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 17:37:49 INFO - 'enabled': True, 17:37:49 INFO - 'halt_on_failure': False, 17:37:49 INFO - 'name': 'check_screen_resolution'},), 17:37:49 INFO - 'run_cmd_checks_enabled': True, 17:37:49 INFO - 'suite': 'dromaeojs-e10s', 17:37:49 INFO - 'system_bits': '32', 17:37:49 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 17:37:49 INFO - 'title': 't-yosemite-r7-0054', 17:37:49 INFO - 'tooltool_cache': '/builds/tooltool_cache', 17:37:49 INFO - 'use_talos_json': True, 17:37:49 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 17:37:49 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 17:37:49 INFO - 'work_dir': 'build'} 17:37:49 INFO - [mozharness: 2017-05-19 00:37:49.337776Z] Running clobber step. 17:37:49 INFO - Running pre-action listener: _resource_record_pre_action 17:37:49 INFO - Running main action method: clobber 17:37:49 INFO - rmtree: /builds/slave/test/build 17:37:49 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 17:37:51 INFO - Running post-action listener: _resource_record_post_action 17:37:51 INFO - [mozharness: 2017-05-19 00:37:51.987774Z] Finished clobber step (success) 17:37:51 INFO - [mozharness: 2017-05-19 00:37:51.987900Z] Running read-buildbot-config step. 17:37:51 INFO - Running pre-action listener: _resource_record_pre_action 17:37:51 INFO - Running main action method: read_buildbot_config 17:37:51 INFO - Using buildbot properties: 17:37:51 INFO - { 17:37:51 INFO - "project": "", 17:37:51 INFO - "product": "firefox", 17:37:51 INFO - "who": "kwierso@gmail.com", 17:37:51 INFO - "installer_path": "public/build/firefox-55.0a1.en-US.mac.dmg", 17:37:51 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 17:37:51 INFO - "repository": "https://hg.mozilla.org/mozilla-central/", 17:37:51 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 17:37:51 INFO - "stage_platform": "macosx64", 17:37:51 INFO - "basedir": "/builds/slave/test", 17:37:51 INFO - "buildnumber": 29, 17:37:51 INFO - "platform": "yosemite_r7", 17:37:51 INFO - "parent_task_id": "MeRoayxHQWuuEagbAeMxAA", 17:37:51 INFO - "master": "http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/", 17:37:51 INFO - "slavebuilddir": "test", 17:37:51 INFO - "taskId": "W8VzVbTKR762kam1naIbwQ", 17:37:51 INFO - "branch": "mozilla-central", 17:37:51 INFO - "script_repo_revision": "production", 17:37:51 INFO - "revision": "3801e9bd76ad91b5754faacd3fec9ad4bc3fc782", 17:37:51 INFO - "slavename": "t-yosemite-r7-0054", 17:37:51 INFO - "repo_path": "mozilla-central" 17:37:51 INFO - } 17:37:51 INFO - Finding installer, test and symbols from parent task. 17:37:51 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/W8VzVbTKR762kam1naIbwQ'}, attempt #1 17:37:52 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/W8VzVbTKR762kam1naIbwQ'}, attempt #1 17:37:53 INFO - Task dependencies: K4GaMSGDQne_U2jnb4R4hg MeRoayxHQWuuEagbAeMxAA 17:37:53 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/MeRoayxHQWuuEagbAeMxAA'}, attempt #1 17:37:53 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/MeRoayxHQWuuEagbAeMxAA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 17:37:53 INFO - Set test_url: https://queue.taskcluster.net/v1/task/MeRoayxHQWuuEagbAeMxAA/artifacts/public/build/test_packages.json 17:37:53 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/MeRoayxHQWuuEagbAeMxAA/artifacts/public/build/target.crashreporter-symbols.zip 17:37:53 INFO - Running post-action listener: _resource_record_post_action 17:37:53 INFO - [mozharness: 2017-05-19 00:37:53.696138Z] Finished read-buildbot-config step (success) 17:37:53 INFO - [mozharness: 2017-05-19 00:37:53.696261Z] Running download-and-extract step. 17:37:53 INFO - Running pre-action listener: _resource_record_pre_action 17:37:53 INFO - Running main action method: download_and_extract 17:37:53 INFO - mkdir: /builds/slave/test/build/tests 17:37:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:37:53 INFO - trying https://queue.taskcluster.net/v1/task/MeRoayxHQWuuEagbAeMxAA/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 17:37:53 INFO - Downloading https://queue.taskcluster.net/v1/task/MeRoayxHQWuuEagbAeMxAA/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 17:37:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/MeRoayxHQWuuEagbAeMxAA/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 17:37:54 INFO - Downloaded 1325 bytes. 17:37:54 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 17:37:54 INFO - Using the following test package requirements: 17:37:54 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 17:37:54 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 17:37:54 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 17:37:54 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 17:37:54 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 17:37:54 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 17:37:54 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 17:37:54 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 17:37:54 INFO - u'jsshell-mac.zip'], 17:37:54 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 17:37:54 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 17:37:54 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 17:37:54 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 17:37:54 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 17:37:54 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 17:37:54 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 17:37:54 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 17:37:54 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 17:37:54 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 17:37:54 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 17:37:54 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 17:37:54 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/MeRoayxHQWuuEagbAeMxAA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 17:37:54 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/MeRoayxHQWuuEagbAeMxAA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 17:37:54 INFO - Fetch https://queue.taskcluster.net/v1/task/MeRoayxHQWuuEagbAeMxAA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 17:37:56 INFO - Content-Length response header: 56570387 17:37:56 INFO - Bytes received: 56570387 17:38:14 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 17:38:14 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/MeRoayxHQWuuEagbAeMxAA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 17:38:14 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/MeRoayxHQWuuEagbAeMxAA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 17:38:14 INFO - Fetch https://queue.taskcluster.net/v1/task/MeRoayxHQWuuEagbAeMxAA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 17:38:15 INFO - Content-Length response header: 56570387 17:38:15 INFO - Bytes received: 56570387 17:38:32 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/MeRoayxHQWuuEagbAeMxAA/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 17:38:32 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/MeRoayxHQWuuEagbAeMxAA/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 17:38:32 INFO - Fetch https://queue.taskcluster.net/v1/task/MeRoayxHQWuuEagbAeMxAA/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 17:38:33 INFO - Content-Length response header: 14410001 17:38:33 INFO - Bytes received: 14410001 17:38:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:38:33 INFO - trying https://queue.taskcluster.net/v1/task/MeRoayxHQWuuEagbAeMxAA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 17:38:33 INFO - Downloading https://queue.taskcluster.net/v1/task/MeRoayxHQWuuEagbAeMxAA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 17:38:33 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/MeRoayxHQWuuEagbAeMxAA/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 17:38:35 INFO - Downloaded 71620093 bytes. 17:38:35 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/MeRoayxHQWuuEagbAeMxAA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 17:38:35 INFO - mkdir: /builds/slave/test/properties 17:38:35 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 17:38:35 INFO - Writing to file /builds/slave/test/properties/build_url 17:38:35 INFO - Contents: 17:38:35 INFO - build_url:https://queue.taskcluster.net/v1/task/MeRoayxHQWuuEagbAeMxAA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 17:38:35 INFO - Running post-action listener: _resource_record_post_action 17:38:35 INFO - Running post-action listener: set_extra_try_arguments 17:38:35 INFO - [mozharness: 2017-05-19 00:38:35.743276Z] Finished download-and-extract step (success) 17:38:35 INFO - [mozharness: 2017-05-19 00:38:35.743393Z] Running populate-webroot step. 17:38:35 INFO - Running pre-action listener: _resource_record_pre_action 17:38:35 INFO - Running main action method: populate_webroot 17:38:35 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 17:38:35 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 17:38:35 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 17:38:35 INFO - '--tppagecycles', 17:38:35 INFO - '7'], 17:38:35 INFO - 'tests': ['tsvgm']}}, 17:38:35 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 17:38:35 INFO - 'tests': ['tresize', 'tcanvasmark']}, 17:38:35 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 17:38:35 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 17:38:35 INFO - 'tests': ['dromaeo_css', 'kraken']}, 17:38:35 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 17:38:35 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 17:38:35 INFO - 'talos_options': ['--disable-e10s'], 17:38:35 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:38:35 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 17:38:35 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:38:35 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 17:38:35 INFO - 'talos_options': ['--disable-e10s'], 17:38:35 INFO - 'tests': ['damp', 'tps']}, 17:38:35 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 17:38:35 INFO - 'tests': ['damp', 'tps']}, 17:38:35 INFO - 'g3': {'talos_options': ['--disable-e10s'], 17:38:35 INFO - 'tests': ['dromaeo_dom']}, 17:38:35 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 17:38:35 INFO - 'g4': {'talos_options': ['--disable-e10s'], 17:38:35 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 17:38:35 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 17:38:35 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 17:38:35 INFO - 'tests': ['ts_paint', 'tp5o']}, 17:38:35 INFO - 'other': {'talos_options': ['--disable-e10s'], 17:38:35 INFO - 'tests': ['a11yr', 17:38:35 INFO - 'ts_paint', 17:38:35 INFO - 'tpaint', 17:38:35 INFO - 'sessionrestore', 17:38:35 INFO - 'sessionrestore_no_auto_restore', 17:38:35 INFO - 'tabpaint']}, 17:38:35 INFO - 'other-e10s': {'tests': ['a11yr', 17:38:35 INFO - 'ts_paint', 17:38:35 INFO - 'tpaint', 17:38:35 INFO - 'sessionrestore', 17:38:35 INFO - 'sessionrestore_no_auto_restore', 17:38:35 INFO - 'tabpaint']}, 17:38:35 INFO - 'other-e10s_l64': {'tests': ['a11yr', 17:38:35 INFO - 'ts_paint', 17:38:35 INFO - 'tpaint', 17:38:35 INFO - 'sessionrestore', 17:38:35 INFO - 'sessionrestore_no_auto_restore', 17:38:35 INFO - 'tabpaint']}, 17:38:35 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 17:38:35 INFO - 'ts_paint', 17:38:35 INFO - 'tpaint', 17:38:35 INFO - 'sessionrestore', 17:38:35 INFO - 'sessionrestore_no_auto_restore', 17:38:35 INFO - 'tabpaint']}, 17:38:35 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 17:38:35 INFO - 'tests': ['a11yr', 17:38:35 INFO - 'ts_paint', 17:38:35 INFO - 'tpaint', 17:38:35 INFO - 'sessionrestore', 17:38:35 INFO - 'sessionrestore_no_auto_restore', 17:38:35 INFO - 'tabpaint']}, 17:38:35 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 17:38:35 INFO - 'tests': ['a11yr', 17:38:35 INFO - 'ts_paint', 17:38:35 INFO - 'tpaint', 17:38:35 INFO - 'sessionrestore', 17:38:35 INFO - 'sessionrestore_no_auto_restore', 17:38:35 INFO - 'tabpaint']}, 17:38:35 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 17:38:35 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 17:38:35 INFO - 'bloom_basic_ref']}, 17:38:35 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 17:38:35 INFO - 'tests': ['tsvgx', 17:38:35 INFO - 'tsvgr_opacity', 17:38:35 INFO - 'tart', 17:38:35 INFO - 'tscrollx', 17:38:35 INFO - 'cart', 17:38:35 INFO - 'tsvg_static']}, 17:38:35 INFO - 'svgr-e10s': {'tests': ['tsvgx', 17:38:35 INFO - 'tsvgr_opacity', 17:38:35 INFO - 'tart', 17:38:35 INFO - 'tscrollx', 17:38:35 INFO - 'cart', 17:38:35 INFO - 'tsvg_static']}, 17:38:35 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 17:38:35 INFO - 'talos_options': ['--disable-e10s'], 17:38:35 INFO - 'tests': ['tp5o']}, 17:38:35 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 17:38:35 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 17:38:35 INFO - 'talos_options': ['--disable-e10s', 17:38:35 INFO - '--xperf_path', 17:38:35 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:38:35 INFO - 'tests': ['tp5n']}, 17:38:35 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 17:38:35 INFO - 'talos_options': ['--xperf_path', 17:38:35 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:38:35 INFO - 'tests': ['tp5n']}}, 17:38:35 INFO - 'talos.zip': {'path': '', 17:38:35 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 17:38:35 INFO - Running post-action listener: _resource_record_post_action 17:38:35 INFO - [mozharness: 2017-05-19 00:38:35.764868Z] Finished populate-webroot step (success) 17:38:35 INFO - [mozharness: 2017-05-19 00:38:35.765023Z] Running create-virtualenv step. 17:38:35 INFO - Running pre-action listener: _resource_record_pre_action 17:38:35 INFO - Running main action method: create_virtualenv 17:38:35 INFO - Creating virtualenv /builds/slave/test/build/venv 17:38:35 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 17:38:35 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 17:38:35 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 17:38:35 INFO - Using real prefix '/tools/python27' 17:38:35 INFO - New python executable in /builds/slave/test/build/venv/bin/python 17:38:36 INFO - Installing distribute.............................................................................................................................................................................................done. 17:38:39 INFO - Installing pip.................done. 17:38:39 INFO - Return code: 0 17:38:39 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 17:38:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 17:38:39 INFO - Reading from file tmpfile_stdout 17:38:39 INFO - Output received: 17:38:39 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 17:38:39 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 17:38: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')]} 17:38:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:38: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')]} 17:38:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:38:39 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1069beb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1069c2768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd552d805e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1066ced30>, '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 0x106682db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1066c3be8>, '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.gJZZz7AtDy/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.FWk9oAC2TD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fp/_7br9k0s4j30pf06c5ky859c00000w/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 17:38:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 17:38:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 17:38:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gJZZz7AtDy/Render', 17:38:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:38:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:38:39 INFO - 'HOME': '/Users/cltbld', 17:38:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:38:39 INFO - 'LOGNAME': 'cltbld', 17:38:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:38:39 INFO - 'MOZ_NO_REMOTE': '1', 17:38:39 INFO - 'NO_EM_RESTART': '1', 17:38:39 INFO - 'PAGER': '/bin/cat', 17:38:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:38:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:38:39 INFO - 'PWD': '/builds/slave/test', 17:38:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:38:39 INFO - 'SHELL': '/bin/bash', 17:38:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FWk9oAC2TD/Listeners', 17:38:39 INFO - 'TMPDIR': '/var/folders/fp/_7br9k0s4j30pf06c5ky859c00000w/T/', 17:38:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:38:39 INFO - 'USER': 'cltbld', 17:38:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:38:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:38:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:38:39 INFO - 'XPC_FLAGS': '0x0', 17:38:39 INFO - 'XPC_SERVICE_NAME': '0', 17:38:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:38:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:38:40 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 17:38:40 INFO - Cleaning up... 17:38:40 INFO - Return code: 0 17:38:40 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 17:38:40 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')]} 17:38:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:38:40 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')]} 17:38:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:38:40 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 0x1069beb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1069c2768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd552d805e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1066ced30>, '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 0x106682db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1066c3be8>, '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.gJZZz7AtDy/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.FWk9oAC2TD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fp/_7br9k0s4j30pf06c5ky859c00000w/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 17:38:40 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 17:38:40 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 17:38:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gJZZz7AtDy/Render', 17:38:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:38:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:38:40 INFO - 'HOME': '/Users/cltbld', 17:38:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:38:40 INFO - 'LOGNAME': 'cltbld', 17:38:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:38:40 INFO - 'MOZ_NO_REMOTE': '1', 17:38:40 INFO - 'NO_EM_RESTART': '1', 17:38:40 INFO - 'PAGER': '/bin/cat', 17:38:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:38:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:38:40 INFO - 'PWD': '/builds/slave/test', 17:38:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:38:40 INFO - 'SHELL': '/bin/bash', 17:38:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FWk9oAC2TD/Listeners', 17:38:40 INFO - 'TMPDIR': '/var/folders/fp/_7br9k0s4j30pf06c5ky859c00000w/T/', 17:38:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:38:40 INFO - 'USER': 'cltbld', 17:38:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:38:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:38:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:38:40 INFO - 'XPC_FLAGS': '0x0', 17:38:40 INFO - 'XPC_SERVICE_NAME': '0', 17:38:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:38:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:38:40 INFO - Downloading/unpacking psutil>=3.1.1 17:38:40 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 17:38:40 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 17:38:40 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 17:38:40 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 17:38:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 17:38:42 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:38:42 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:38:42 INFO - Installing collected packages: psutil 17:38:42 INFO - Running setup.py install for psutil 17:38:42 INFO - building 'psutil._psutil_osx' extension 17:38:42 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 17:38:43 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 17:38:43 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 17:38:43 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 17:38:43 INFO - building 'psutil._psutil_posix' extension 17:38:43 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 17:38:43 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 17:38:43 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 17:38:43 INFO - ^ 17:38:43 INFO - 1 warning generated. 17:38:43 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 17:38:43 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:38:43 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:38:43 INFO - Successfully installed psutil 17:38:43 INFO - Cleaning up... 17:38:43 INFO - Return code: 0 17:38:43 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 17:38:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:38:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:38:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:38:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:38:44 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 0x1069beb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1069c2768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd552d805e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1066ced30>, '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 0x106682db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1066c3be8>, '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.gJZZz7AtDy/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.FWk9oAC2TD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fp/_7br9k0s4j30pf06c5ky859c00000w/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 17:38:44 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 17:38:44 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 17:38:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gJZZz7AtDy/Render', 17:38:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:38:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:38:44 INFO - 'HOME': '/Users/cltbld', 17:38:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:38:44 INFO - 'LOGNAME': 'cltbld', 17:38:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:38:44 INFO - 'MOZ_NO_REMOTE': '1', 17:38:44 INFO - 'NO_EM_RESTART': '1', 17:38:44 INFO - 'PAGER': '/bin/cat', 17:38:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:38:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:38:44 INFO - 'PWD': '/builds/slave/test', 17:38:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:38:44 INFO - 'SHELL': '/bin/bash', 17:38:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FWk9oAC2TD/Listeners', 17:38:44 INFO - 'TMPDIR': '/var/folders/fp/_7br9k0s4j30pf06c5ky859c00000w/T/', 17:38:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:38:44 INFO - 'USER': 'cltbld', 17:38:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:38:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:38:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:38:44 INFO - 'XPC_FLAGS': '0x0', 17:38:44 INFO - 'XPC_SERVICE_NAME': '0', 17:38:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:38:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:38:44 INFO - Downloading/unpacking mozsystemmonitor==0.3 17:38:44 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 17:38:44 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 17:38:44 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 17:38:44 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 17:38:46 INFO - Downloading mozsystemmonitor-0.3.tar.gz 17:38:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 17:38:46 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 17:38:46 INFO - Installing collected packages: mozsystemmonitor 17:38:46 INFO - Running setup.py install for mozsystemmonitor 17:38:46 INFO - Successfully installed mozsystemmonitor 17:38:46 INFO - Cleaning up... 17:38:46 INFO - Return code: 0 17:38:46 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 17:38: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')]} 17:38:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:38:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:38:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:38:47 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1069beb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1069c2768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd552d805e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1066ced30>, '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 0x106682db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1066c3be8>, '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.gJZZz7AtDy/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.FWk9oAC2TD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fp/_7br9k0s4j30pf06c5ky859c00000w/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 17:38:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 17:38:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 17:38:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gJZZz7AtDy/Render', 17:38:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:38:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:38:47 INFO - 'HOME': '/Users/cltbld', 17:38:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:38:47 INFO - 'LOGNAME': 'cltbld', 17:38:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:38:47 INFO - 'MOZ_NO_REMOTE': '1', 17:38:47 INFO - 'NO_EM_RESTART': '1', 17:38:47 INFO - 'PAGER': '/bin/cat', 17:38:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:38:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:38:47 INFO - 'PWD': '/builds/slave/test', 17:38:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:38:47 INFO - 'SHELL': '/bin/bash', 17:38:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FWk9oAC2TD/Listeners', 17:38:47 INFO - 'TMPDIR': '/var/folders/fp/_7br9k0s4j30pf06c5ky859c00000w/T/', 17:38:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:38:47 INFO - 'USER': 'cltbld', 17:38:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:38:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:38:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:38:47 INFO - 'XPC_FLAGS': '0x0', 17:38:47 INFO - 'XPC_SERVICE_NAME': '0', 17:38:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:38:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:38:47 INFO - Downloading/unpacking jsonschema==2.5.1 17:38:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:38:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:38:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:38:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:38:48 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 17:38:48 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 17:38:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:38:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:38:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:38:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:38:49 INFO - Downloading functools32-3.2.3-2.tar.gz 17:38:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 17:38:49 INFO - warning: no files found matching '*.txt' 17:38:49 INFO - no previously-included directories found matching 'build' 17:38:49 INFO - no previously-included directories found matching 'dist' 17:38:49 INFO - no previously-included directories found matching '.git*' 17:38:49 INFO - Installing collected packages: jsonschema, functools32 17:38:49 INFO - Running setup.py install for functools32 17:38:49 INFO - warning: no files found matching '*.txt' 17:38:49 INFO - no previously-included directories found matching 'build' 17:38:49 INFO - no previously-included directories found matching 'dist' 17:38:49 INFO - no previously-included directories found matching '.git*' 17:38:49 INFO - Successfully installed jsonschema functools32 17:38:49 INFO - Cleaning up... 17:38:49 INFO - Return code: 0 17:38:49 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 17:38: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')]} 17:38:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:38: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')]} 17:38:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:38: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', '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 0x1069beb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1069c2768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd552d805e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1066ced30>, '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 0x106682db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1066c3be8>, '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.gJZZz7AtDy/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.FWk9oAC2TD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fp/_7br9k0s4j30pf06c5ky859c00000w/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 17:38: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', 'functools32==3.2.3-2'] in /builds/slave/test/build 17:38: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 functools32==3.2.3-2 17:38:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gJZZz7AtDy/Render', 17:38:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:38:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:38:49 INFO - 'HOME': '/Users/cltbld', 17:38:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:38:49 INFO - 'LOGNAME': 'cltbld', 17:38:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:38:49 INFO - 'MOZ_NO_REMOTE': '1', 17:38:49 INFO - 'NO_EM_RESTART': '1', 17:38:49 INFO - 'PAGER': '/bin/cat', 17:38:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:38:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:38:49 INFO - 'PWD': '/builds/slave/test', 17:38:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:38:49 INFO - 'SHELL': '/bin/bash', 17:38:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FWk9oAC2TD/Listeners', 17:38:49 INFO - 'TMPDIR': '/var/folders/fp/_7br9k0s4j30pf06c5ky859c00000w/T/', 17:38:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:38:49 INFO - 'USER': 'cltbld', 17:38:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:38:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:38:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:38:49 INFO - 'XPC_FLAGS': '0x0', 17:38:49 INFO - 'XPC_SERVICE_NAME': '0', 17:38:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:38:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:38:49 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 17:38:49 INFO - Cleaning up... 17:38:49 INFO - Return code: 0 17:38:49 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 17:38: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')]} 17:38:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:38: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')]} 17:38:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:38: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', '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 0x1069beb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1069c2768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd552d805e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1066ced30>, '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 0x106682db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1066c3be8>, '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.gJZZz7AtDy/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.FWk9oAC2TD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fp/_7br9k0s4j30pf06c5ky859c00000w/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 17:38: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', 'blobuploader==1.2.4'] in /builds/slave/test/build 17:38: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 blobuploader==1.2.4 17:38:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gJZZz7AtDy/Render', 17:38:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:38:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:38:49 INFO - 'HOME': '/Users/cltbld', 17:38:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:38:49 INFO - 'LOGNAME': 'cltbld', 17:38:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:38:49 INFO - 'MOZ_NO_REMOTE': '1', 17:38:49 INFO - 'NO_EM_RESTART': '1', 17:38:49 INFO - 'PAGER': '/bin/cat', 17:38:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:38:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:38:49 INFO - 'PWD': '/builds/slave/test', 17:38:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:38:49 INFO - 'SHELL': '/bin/bash', 17:38:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FWk9oAC2TD/Listeners', 17:38:49 INFO - 'TMPDIR': '/var/folders/fp/_7br9k0s4j30pf06c5ky859c00000w/T/', 17:38:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:38:49 INFO - 'USER': 'cltbld', 17:38:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:38:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:38:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:38:49 INFO - 'XPC_FLAGS': '0x0', 17:38:49 INFO - 'XPC_SERVICE_NAME': '0', 17:38:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:38:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:38:49 INFO - Downloading/unpacking blobuploader==1.2.4 17:38:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:38:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:38:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:38:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:38:51 INFO - Downloading blobuploader-1.2.4.tar.gz 17:38:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 17:38:51 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 17:38:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:38:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:38:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:38:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:38:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 17:38:51 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 17:38:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:38:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:38:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:38:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:38:52 INFO - Downloading docopt-0.6.1.tar.gz 17:38:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 17:38:52 INFO - Installing collected packages: blobuploader, requests, docopt 17:38:52 INFO - Running setup.py install for blobuploader 17:38:52 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 17:38:52 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 17:38:52 INFO - Running setup.py install for requests 17:38:52 INFO - Running setup.py install for docopt 17:38:53 INFO - Successfully installed blobuploader requests docopt 17:38:53 INFO - Cleaning up... 17:38:53 INFO - Return code: 0 17:38:53 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:38:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:38:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:38:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:38:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:38:53 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 0x1069beb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1069c2768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd552d805e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1066ced30>, '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 0x106682db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1066c3be8>, '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.gJZZz7AtDy/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.FWk9oAC2TD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fp/_7br9k0s4j30pf06c5ky859c00000w/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 17:38:53 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 17:38:53 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 17:38:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gJZZz7AtDy/Render', 17:38:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:38:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:38:53 INFO - 'HOME': '/Users/cltbld', 17:38:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:38:53 INFO - 'LOGNAME': 'cltbld', 17:38:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:38:53 INFO - 'MOZ_NO_REMOTE': '1', 17:38:53 INFO - 'NO_EM_RESTART': '1', 17:38:53 INFO - 'PAGER': '/bin/cat', 17:38:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:38:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:38:53 INFO - 'PWD': '/builds/slave/test', 17:38:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:38:53 INFO - 'SHELL': '/bin/bash', 17:38:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FWk9oAC2TD/Listeners', 17:38:53 INFO - 'TMPDIR': '/var/folders/fp/_7br9k0s4j30pf06c5ky859c00000w/T/', 17:38:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:38:53 INFO - 'USER': 'cltbld', 17:38:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:38:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:38:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:38:53 INFO - 'XPC_FLAGS': '0x0', 17:38:53 INFO - 'XPC_SERVICE_NAME': '0', 17:38:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:38:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:38:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:38:53 INFO - Running setup.py (path:/var/folders/fp/_7br9k0s4j30pf06c5ky859c00000w/T/pip-bkQpLY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:38:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:38:53 INFO - Running setup.py (path:/var/folders/fp/_7br9k0s4j30pf06c5ky859c00000w/T/pip-g8M92e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:38:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:38:53 INFO - Running setup.py (path:/var/folders/fp/_7br9k0s4j30pf06c5ky859c00000w/T/pip-A6ormy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:38:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:38:53 INFO - Running setup.py (path:/var/folders/fp/_7br9k0s4j30pf06c5ky859c00000w/T/pip-Ftqdtv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:38:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:38:53 INFO - Running setup.py (path:/var/folders/fp/_7br9k0s4j30pf06c5ky859c00000w/T/pip-2kNnYN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:38:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:38:53 INFO - Running setup.py (path:/var/folders/fp/_7br9k0s4j30pf06c5ky859c00000w/T/pip-KAhrJa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:38:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:38:54 INFO - Running setup.py (path:/var/folders/fp/_7br9k0s4j30pf06c5ky859c00000w/T/pip-4o9yJx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:38:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:38:54 INFO - Running setup.py (path:/var/folders/fp/_7br9k0s4j30pf06c5ky859c00000w/T/pip-ioRJOI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:38:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:38:54 INFO - Running setup.py (path:/var/folders/fp/_7br9k0s4j30pf06c5ky859c00000w/T/pip-IFyilA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:38:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:38:54 INFO - Running setup.py (path:/var/folders/fp/_7br9k0s4j30pf06c5ky859c00000w/T/pip-gw1jNU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:38:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:38:54 INFO - Running setup.py (path:/var/folders/fp/_7br9k0s4j30pf06c5ky859c00000w/T/pip-1998P4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:38:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:38:54 INFO - Running setup.py (path:/var/folders/fp/_7br9k0s4j30pf06c5ky859c00000w/T/pip-oWEKk5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:38:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:38:54 INFO - Running setup.py (path:/var/folders/fp/_7br9k0s4j30pf06c5ky859c00000w/T/pip-74WXMk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:38:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:38:54 INFO - Running setup.py (path:/var/folders/fp/_7br9k0s4j30pf06c5ky859c00000w/T/pip-9u5eGL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:38:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:38:54 INFO - Running setup.py (path:/var/folders/fp/_7br9k0s4j30pf06c5ky859c00000w/T/pip-8pBPOW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:38:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:38:54 INFO - Running setup.py (path:/var/folders/fp/_7br9k0s4j30pf06c5ky859c00000w/T/pip-xDl2vf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:38:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:38:55 INFO - Running setup.py (path:/var/folders/fp/_7br9k0s4j30pf06c5ky859c00000w/T/pip-Suho3e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:38:55 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 17:38:55 INFO - Running setup.py install for manifestparser 17:38:55 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 17:38:55 INFO - Running setup.py install for mozcrash 17:38:55 INFO - Running setup.py install for mozdebug 17:38:55 INFO - Running setup.py install for mozdevice 17:38:55 INFO - Installing dm script to /builds/slave/test/build/venv/bin 17:38:55 INFO - Running setup.py install for mozfile 17:38:55 INFO - Running setup.py install for mozhttpd 17:38:56 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 17:38:56 INFO - Running setup.py install for mozinfo 17:38:56 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 17:38:56 INFO - Running setup.py install for mozInstall 17:38:56 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 17:38:56 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 17:38:56 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 17:38:56 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 17:38:56 INFO - Running setup.py install for mozleak 17:38:56 INFO - Running setup.py install for mozlog 17:38:56 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 17:38:56 INFO - Running setup.py install for moznetwork 17:38:56 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 17:38:56 INFO - Running setup.py install for mozprocess 17:38:56 INFO - Running setup.py install for mozprofile 17:38:57 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 17:38:57 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 17:38:57 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 17:38:57 INFO - Running setup.py install for mozrunner 17:38:57 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 17:38:57 INFO - Running setup.py install for mozscreenshot 17:38:57 INFO - Running setup.py install for moztest 17:38:57 INFO - Running setup.py install for mozversion 17:38:57 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 17:38:57 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 17:38:57 INFO - Cleaning up... 17:38:57 INFO - Return code: 0 17:38:57 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:38: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')]} 17:38:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:38: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')]} 17:38:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:38:57 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 0x1069beb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1069c2768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd552d805e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1066ced30>, '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 0x106682db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1066c3be8>, '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.gJZZz7AtDy/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.FWk9oAC2TD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fp/_7br9k0s4j30pf06c5ky859c00000w/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 17:38:57 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 17:38:57 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 17:38:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gJZZz7AtDy/Render', 17:38:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:38:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:38:57 INFO - 'HOME': '/Users/cltbld', 17:38:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:38:57 INFO - 'LOGNAME': 'cltbld', 17:38:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:38:57 INFO - 'MOZ_NO_REMOTE': '1', 17:38:57 INFO - 'NO_EM_RESTART': '1', 17:38:57 INFO - 'PAGER': '/bin/cat', 17:38:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:38:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:38:57 INFO - 'PWD': '/builds/slave/test', 17:38:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:38:57 INFO - 'SHELL': '/bin/bash', 17:38:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FWk9oAC2TD/Listeners', 17:38:57 INFO - 'TMPDIR': '/var/folders/fp/_7br9k0s4j30pf06c5ky859c00000w/T/', 17:38:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:38:57 INFO - 'USER': 'cltbld', 17:38:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:38:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:38:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:38:57 INFO - 'XPC_FLAGS': '0x0', 17:38:57 INFO - 'XPC_SERVICE_NAME': '0', 17:38:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:38:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:38:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:38:57 INFO - Running setup.py (path:/var/folders/fp/_7br9k0s4j30pf06c5ky859c00000w/T/pip-t9E0yj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:38:58 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)) 17:38:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:38:58 INFO - Running setup.py (path:/var/folders/fp/_7br9k0s4j30pf06c5ky859c00000w/T/pip-AX3LgI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:38:58 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)) 17:38:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:38:58 INFO - Running setup.py (path:/var/folders/fp/_7br9k0s4j30pf06c5ky859c00000w/T/pip-KK2TaU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:38:58 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)) 17:38:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:38:58 INFO - Running setup.py (path:/var/folders/fp/_7br9k0s4j30pf06c5ky859c00000w/T/pip-BcRXen-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:38:58 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)) 17:38:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:38:58 INFO - Running setup.py (path:/var/folders/fp/_7br9k0s4j30pf06c5ky859c00000w/T/pip-1dDvTy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:38:58 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)) 17:38:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:38:58 INFO - Running setup.py (path:/var/folders/fp/_7br9k0s4j30pf06c5ky859c00000w/T/pip-W1QSWp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:38:58 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)) 17:38:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:38:58 INFO - Running setup.py (path:/var/folders/fp/_7br9k0s4j30pf06c5ky859c00000w/T/pip-rvscSb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:38:58 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)) 17:38:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:38:58 INFO - Running setup.py (path:/var/folders/fp/_7br9k0s4j30pf06c5ky859c00000w/T/pip-PuAwxG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:38:58 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)) 17:38:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:38:58 INFO - Running setup.py (path:/var/folders/fp/_7br9k0s4j30pf06c5ky859c00000w/T/pip-8l5gkW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:38:58 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)) 17:38:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:38:58 INFO - Running setup.py (path:/var/folders/fp/_7br9k0s4j30pf06c5ky859c00000w/T/pip-m080rB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:38:59 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)) 17:38:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:38:59 INFO - Running setup.py (path:/var/folders/fp/_7br9k0s4j30pf06c5ky859c00000w/T/pip-LllRy8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:38:59 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)) 17:38:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:38:59 INFO - Running setup.py (path:/var/folders/fp/_7br9k0s4j30pf06c5ky859c00000w/T/pip-6OvMyL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:38:59 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)) 17:38:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:38:59 INFO - Running setup.py (path:/var/folders/fp/_7br9k0s4j30pf06c5ky859c00000w/T/pip-zYkf2f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:38:59 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)) 17:38:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:38:59 INFO - Running setup.py (path:/var/folders/fp/_7br9k0s4j30pf06c5ky859c00000w/T/pip-i_mgVE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:38:59 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)) 17:38:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:38:59 INFO - Running setup.py (path:/var/folders/fp/_7br9k0s4j30pf06c5ky859c00000w/T/pip-Bza2Py-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:38:59 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)) 17:38:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:38:59 INFO - Running setup.py (path:/var/folders/fp/_7br9k0s4j30pf06c5ky859c00000w/T/pip-nMSx10-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:38:59 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)) 17:38:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:38:59 INFO - Running setup.py (path:/var/folders/fp/_7br9k0s4j30pf06c5ky859c00000w/T/pip-2Ks6Dv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:38:59 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)) 17:38:59 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)) 17:38:59 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)) 17:38:59 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)) 17:38:59 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)) 17:38:59 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)) 17:38:59 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 17:38:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:38:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:38:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:38:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:39:01 INFO - Downloading blessings-1.6.tar.gz 17:39:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 17:39:01 INFO - Installing collected packages: blessings 17:39:01 INFO - Running setup.py install for blessings 17:39:01 INFO - Successfully installed blessings 17:39:01 INFO - Cleaning up... 17:39:01 INFO - Return code: 0 17:39:01 INFO - Done creating virtualenv /builds/slave/test/build/venv. 17:39:01 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:39:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:39:01 INFO - Reading from file tmpfile_stdout 17:39:01 INFO - Current package versions: 17:39:01 INFO - blessings == 1.6 17:39:01 INFO - blobuploader == 1.2.4 17:39:01 INFO - docopt == 0.6.1 17:39:01 INFO - functools32 == 3.2.3-2 17:39:01 INFO - jsonschema == 2.5.1 17:39:01 INFO - manifestparser == 1.1 17:39:01 INFO - mozInstall == 1.12 17:39:01 INFO - mozcrash == 1.0 17:39:01 INFO - mozdebug == 0.1 17:39:01 INFO - mozdevice == 0.50 17:39:01 INFO - mozfile == 1.2 17:39:01 INFO - mozhttpd == 0.7 17:39:01 INFO - mozinfo == 0.9 17:39:01 INFO - mozleak == 0.1 17:39:01 INFO - mozlog == 3.4 17:39:01 INFO - moznetwork == 0.27 17:39:01 INFO - mozprocess == 0.25 17:39:01 INFO - mozprofile == 0.28 17:39:01 INFO - mozrunner == 6.13 17:39:01 INFO - mozscreenshot == 0.1 17:39:01 INFO - mozsystemmonitor == 0.3 17:39:01 INFO - moztest == 0.8 17:39:01 INFO - mozversion == 1.4 17:39:01 INFO - psutil == 3.1.1 17:39:01 INFO - requests == 1.2.3 17:39:01 INFO - wsgiref == 0.1.2 17:39:01 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:39:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:39:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:39:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:39:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:39:01 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 0x1069beb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1069c2768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd552d805e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1066ced30>, '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 0x106682db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1066c3be8>, '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.gJZZz7AtDy/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.FWk9oAC2TD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fp/_7br9k0s4j30pf06c5ky859c00000w/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 17:39:01 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 17:39:01 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 17:39:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gJZZz7AtDy/Render', 17:39:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:39:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:39:01 INFO - 'HOME': '/Users/cltbld', 17:39:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:39:01 INFO - 'LOGNAME': 'cltbld', 17:39:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:39:01 INFO - 'MOZ_NO_REMOTE': '1', 17:39:01 INFO - 'NO_EM_RESTART': '1', 17:39:01 INFO - 'PAGER': '/bin/cat', 17:39:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:39:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:39:01 INFO - 'PWD': '/builds/slave/test', 17:39:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:39:01 INFO - 'SHELL': '/bin/bash', 17:39:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FWk9oAC2TD/Listeners', 17:39:01 INFO - 'TMPDIR': '/var/folders/fp/_7br9k0s4j30pf06c5ky859c00000w/T/', 17:39:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:39:01 INFO - 'USER': 'cltbld', 17:39:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:39:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:39:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:39:01 INFO - 'XPC_FLAGS': '0x0', 17:39:01 INFO - 'XPC_SERVICE_NAME': '0', 17:39:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:39:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:39:02 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)) 17:39:02 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)) 17:39:02 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)) 17:39:02 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)) 17:39:02 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)) 17:39:02 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)) 17:39:02 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)) 17:39:02 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)) 17:39:02 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)) 17:39:02 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)) 17:39:02 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)) 17:39:02 INFO - Cleaning up... 17:39:02 INFO - Return code: 0 17:39:02 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 17:39: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')]} 17:39:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:39: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')]} 17:39:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:39:02 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1069beb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1069c2768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd552d805e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1066ced30>, '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 0x106682db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1066c3be8>, '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.gJZZz7AtDy/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.FWk9oAC2TD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fp/_7br9k0s4j30pf06c5ky859c00000w/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 17:39:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 17:39:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 17:39:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gJZZz7AtDy/Render', 17:39:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:39:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:39:02 INFO - 'HOME': '/Users/cltbld', 17:39:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:39:02 INFO - 'LOGNAME': 'cltbld', 17:39:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:39:02 INFO - 'MOZ_NO_REMOTE': '1', 17:39:02 INFO - 'NO_EM_RESTART': '1', 17:39:02 INFO - 'PAGER': '/bin/cat', 17:39:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:39:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:39:02 INFO - 'PWD': '/builds/slave/test', 17:39:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:39:02 INFO - 'SHELL': '/bin/bash', 17:39:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FWk9oAC2TD/Listeners', 17:39:02 INFO - 'TMPDIR': '/var/folders/fp/_7br9k0s4j30pf06c5ky859c00000w/T/', 17:39:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:39:02 INFO - 'USER': 'cltbld', 17:39:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:39:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:39:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:39:02 INFO - 'XPC_FLAGS': '0x0', 17:39:02 INFO - 'XPC_SERVICE_NAME': '0', 17:39:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:39:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:39:02 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 17:39:02 INFO - Cleaning up... 17:39:02 INFO - Return code: 0 17:39:02 INFO - Running post-action listener: _resource_record_post_action 17:39:02 INFO - Running post-action listener: _start_resource_monitoring 17:39:02 INFO - Starting resource monitoring. 17:39:02 INFO - [mozharness: 2017-05-19 00:39:02.349202Z] Finished create-virtualenv step (success) 17:39:02 INFO - [mozharness: 2017-05-19 00:39:02.350149Z] Running install step. 17:39:02 INFO - Running pre-action listener: _resource_record_pre_action 17:39:02 INFO - Running main action method: install 17:39:02 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:39:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:39:02 INFO - Reading from file tmpfile_stdout 17:39:02 INFO - Detecting whether we're running mozinstall >=1.0... 17:39:02 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 17:39:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 17:39:02 INFO - Reading from file tmpfile_stdout 17:39:02 INFO - Output received: 17:39:02 INFO - Usage: mozinstall [options] installer 17:39:02 INFO - Options: 17:39:02 INFO - -h, --help show this help message and exit 17:39:02 INFO - -d DEST, --destination=DEST 17:39:02 INFO - Directory to install application into. [default: 17:39:02 INFO - "/builds/slave/test"] 17:39:02 INFO - --app=APP Application being installed. [default: firefox] 17:39:02 INFO - mkdir: /builds/slave/test/build/application 17:39:02 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 17:39:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 17:39:25 INFO - Reading from file tmpfile_stdout 17:39:25 INFO - Output received: 17:39:25 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 17:39:25 INFO - Running post-action listener: _resource_record_post_action 17:39:25 INFO - [mozharness: 2017-05-19 00:39:25.814458Z] Finished install step (success) 17:39:25 INFO - [mozharness: 2017-05-19 00:39:25.814613Z] Running run-tests step. 17:39:25 INFO - Running pre-action listener: _resource_record_pre_action 17:39:25 INFO - Running main action method: run_tests 17:39:25 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 17:39:25 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 17:39:25 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 17:39:25 INFO - 2017-05-18 17:39:25.829 screenresolution[1625:7671] starting screenresolution argv=screenresolution get 17:39:25 INFO - 2017-05-18 17:39:25.831 screenresolution[1625:7671] Display 0: 1600x1200x32@60 17:39:25 INFO - 2017-05-18 17:39:25.837 screenresolution[1626:7673] starting screenresolution argv=screenresolution list 17:39:25 INFO - Available Modes on Display 0 17:39:25 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 17:39:25 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 17:39:25 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 17:39:25 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 17:39:26 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 17:39:26 INFO - Intel Iris: 17:39:26 INFO - Chipset Model: Intel Iris 17:39:26 INFO - Type: GPU 17:39:26 INFO - Bus: Built-In 17:39:26 INFO - VRAM (Dynamic, Max): 1536 MB 17:39:26 INFO - Vendor: Intel (0x8086) 17:39:26 INFO - Device ID: 0x0a2e 17:39:26 INFO - Revision ID: 0x0009 17:39:26 INFO - Displays: 17:39:26 INFO - Display: 17:39:26 INFO - Resolution: 1600 x 1200 @ 60 Hz 17:39:26 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 17:39:26 INFO - Display Serial Number: 1600x1200 60 17:39:26 INFO - Main Display: Yes 17:39:26 INFO - Mirror: Off 17:39:26 INFO - Online: Yes 17:39:26 INFO - Rotation: Supported 17:39:26 INFO - Return code: 0 17:39:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 17:39:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 17:39:26 INFO - Python 2.7.3 17:39:26 INFO - Return code: 0 17:39:26 INFO - grabbing minidump binary from tooltool 17:39:26 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')]} 17:39:26 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 0x1066ced30>, '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 0x106682db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1066c3be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 17:39:26 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 17:39:26 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 17:39:26 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 17:39:26 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 17:39:26 INFO - Return code: 0 17:39:26 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 17:39:26 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 17:39:26 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 17:39:26 INFO - ENV: RUST_BACKTRACE is now 1 17:39:26 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 17:39:26 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 17:39:26 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/MeRoayxHQWuuEagbAeMxAA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0054', '--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 17:39:26 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/MeRoayxHQWuuEagbAeMxAA/artifacts/public/build/target.crashreporter-symbols.zip --title t-yosemite-r7-0054 --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 17:39:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gJZZz7AtDy/Render', 17:39:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:39:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:39:26 INFO - 'HOME': '/Users/cltbld', 17:39:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:39:26 INFO - 'LOGNAME': 'cltbld', 17:39:26 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 17:39:26 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 17:39:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:39:26 INFO - 'MOZ_NO_REMOTE': '1', 17:39:26 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 17:39:26 INFO - 'NO_EM_RESTART': '1', 17:39:26 INFO - 'PAGER': '/bin/cat', 17:39:26 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:39:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:39:26 INFO - 'PWD': '/builds/slave/test', 17:39:26 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 17:39:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:39:26 INFO - 'RUST_BACKTRACE': '1', 17:39:26 INFO - 'SHELL': '/bin/bash', 17:39:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FWk9oAC2TD/Listeners', 17:39:26 INFO - 'TMPDIR': '/var/folders/fp/_7br9k0s4j30pf06c5ky859c00000w/T/', 17:39:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:39:26 INFO - 'USER': 'cltbld', 17:39:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:39:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:39:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:39:26 INFO - 'XPC_FLAGS': '0x0', 17:39:26 INFO - 'XPC_SERVICE_NAME': '0', 17:39:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:39:26 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/MeRoayxHQWuuEagbAeMxAA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0054', '--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 17:39:26 INFO - mozversion application_buildid: 20170518162514 17:39:26 INFO - mozversion application_changeset: 3801e9bd76ad91b5754faacd3fec9ad4bc3fc782 17:39:26 INFO - mozversion application_display_name: Nightly 17:39:26 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 17:39:26 INFO - mozversion application_name: Firefox 17:39:26 INFO - mozversion application_remotingname: firefox 17:39:26 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 17:39:26 INFO - mozversion application_vendor: Mozilla 17:39:26 INFO - mozversion application_version: 55.0a1 17:39:26 INFO - mozversion platform_buildid: 20170518162514 17:39:26 INFO - mozversion platform_changeset: 3801e9bd76ad91b5754faacd3fec9ad4bc3fc782 17:39:26 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 17:39:26 INFO - mozversion platform_version: 55.0a1 17:39:26 INFO - using testdate: 1495154366 17:39:26 INFO - actual date: 1495154366 17:39:26 INFO - starting webserver on 'localhost:49221' 17:39:26 INFO - SUITE-START | Running 2 tests 17:39:26 INFO - TEST-START | dromaeo_css 17:39:26 INFO - operating with platform_type : mac_ 17:39:26 INFO - Initialising browser for dromaeo_css test... 17:39:26 INFO - TEST-INFO | started process 1636 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/fp/_7br9k0s4j30pf06c5ky859c00000w/T/tmpzGn5wb/profile http://localhost:49221/getInfo.html) 17:39:26 INFO - PID 1636 | JavaScript warning: resource://gre/modules/addons/XPIProvider.jsm, line 2941: String.localeCompare is deprecated; use String.prototype.localeCompare instead 17:39:28 INFO - PID 1636 | 2017-05-18 17:39:28.961 plugin-container[1638:7859] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9e3b, name = 'com.apple.tsm.portname' 17:39:28 INFO - PID 1636 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:39:28 INFO - PID 1636 | 2017-05-18 17:39:28.966 plugin-container[1638:7859] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4e1f, name = 'com.apple.CFPasteboardClient' 17:39:28 INFO - PID 1636 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:39:28 INFO - PID 1636 | 2017-05-18 17:39:28.966 plugin-container[1638:7859] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 17:39:29 INFO - PID 1636 | __metrics Screen width/height:1600/1200 17:39:29 INFO - PID 1636 | colorDepth:24 17:39:29 INFO - PID 1636 | Browser inner width/height: 1024/691 17:39:29 INFO - PID 1636 | __metrics 17:39:30 INFO - TEST-INFO | 1636: exit 0 17:39:30 INFO - Browser initialized. 17:39:30 INFO - Running cycle 1/1 for dromaeo_css test... 17:39:30 INFO - TEST-INFO | started process 1640 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/fp/_7br9k0s4j30pf06c5ky859c00000w/T/tmpzGn5wb/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 17:39:31 INFO - PID 1640 | 2017-05-18 17:39:31.781 plugin-container[1641:8003] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9747, name = 'com.apple.tsm.portname' 17:39:31 INFO - PID 1640 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:39:31 INFO - PID 1640 | 2017-05-18 17:39:31.782 plugin-container[1641:8003] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9e03, name = 'com.apple.CFPasteboardClient' 17:39:31 INFO - PID 1640 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:39:31 INFO - PID 1640 | 2017-05-18 17:39:31.782 plugin-container[1641:8003] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 17:39:32 INFO - PID 1640 | 2017-05-18 17:39:32.504 plugin-container[1642:8049] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x932f, name = 'com.apple.tsm.portname' 17:39:32 INFO - PID 1640 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:39:32 INFO - PID 1640 | 2017-05-18 17:39:32.504 plugin-container[1642:8049] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4813, name = 'com.apple.CFPasteboardClient' 17:39:32 INFO - PID 1640 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:39:32 INFO - PID 1640 | 2017-05-18 17:39:32.504 plugin-container[1642:8049] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 17:39:41 INFO - PID 1640 | RSS: Main: 238276608 17:39:41 INFO - PID 1640 | 17:42:44 INFO - PID 1640 | Cycle 1(1): loaded http://localhost:49221/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49221/tests/dromaeo/cssquery-ext.html) 17:42:46 INFO - PID 1640 | RSS: Main: 266297344 17:42:46 INFO - PID 1640 | 17:45:48 INFO - PID 1640 | Cycle 1(1): loaded http://localhost:49221/tests/dromaeo/cssquery-ext.html (next: http://localhost:49221/tests/dromaeo/cssquery-jquery.html) 17:45:48 INFO - PID 1640 | RSS: Main: 269938688 17:45:48 INFO - PID 1640 | 17:48:50 INFO - PID 1640 | Cycle 1(1): loaded http://localhost:49221/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49221/tests/dromaeo/cssquery-mootools.html) 17:48:50 INFO - PID 1640 | RSS: Main: 273694720 17:48:50 INFO - PID 1640 | 17:51:52 INFO - PID 1640 | Cycle 1(1): loaded http://localhost:49221/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49221/tests/dromaeo/cssquery-prototype.html) 17:51:53 INFO - PID 1640 | RSS: Main: 271474688 17:51:53 INFO - PID 1640 | 17:54:56 INFO - PID 1640 | Cycle 1(1): loaded http://localhost:49221/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49221/tests/dromaeo/cssquery-yui.html) 17:54:56 INFO - PID 1640 | RSS: Main: 272977920 17:54:56 INFO - PID 1640 | 17:57:58 INFO - PID 1640 | Cycle 1(1): loaded http://localhost:49221/tests/dromaeo/cssquery-yui.html (next: http://localhost:49221/tests/dromaeo/cssquery-dojo.html) 17:57:58 INFO - PID 1640 | RSS: Main: 274116608 17:57:58 INFO - PID 1640 | 17:57:58 INFO - PID 1640 | __start_tp_report 17:57:58 INFO - PID 1640 | _x_x_mozilla_page_load 17:57:58 INFO - PID 1640 | _x_x_mozilla_page_load_details 17:57:58 INFO - PID 1640 | |i|pagename|runs| 17:57:58 INFO - PID 1640 | |0;dojo.html;3330.2173989112553;3440.8279586020694;3591.9640803591965;3631.4371272452763;3635.527381440413;5595.4124816894255;5617.634853734505;6114.3886108817405;6221.284552276489;6275.623462592236;4846.806127754894;6221.0979407985815;6230.096635987779;6234.7506099756065;6278.68606569672;5079.7714102865275;5470.972645136769;5516.806911758089;5517.779288828462;5637.830865074034;4875.878103047435;4910.337104490902;5550.639208451437;5627;5633.085005920866;5381.650192737459;5542.501174894239;5563.443655634444;5614.719264036782;5629.549636029108;5400.405955344909;5485.506304432581;5488.643238189505;5505.752241149158;5534.833954981357;5452.2094296327205;5483.890322193533;5487.725613719298;5507.394186639466;5517.062099443105;5137.948620513785;5691.943080569225;6196.2874269459135;6265.968670156665;6279.0895320178315;5534.640248383883;5824.679642619654;6485;6542.607443553402;6751.088603038576;6407.551471397004;6867.519273650846;6871.587704737732;6893.7931862044215;6908.274631163706;6386.904196437057;6391.520635952322;6405.871882562323;6431.903521447182;6432.131662225634;6745.696443660047;6831.760888368884;6922.619255940972;6990.930090699129;7013.684384202723;6676.699548520328;6693.665316734241;6715.832104197337;6740.764073257413;6759.5606285591275;6799.456043516507;6902.344277293649;6903.516753827284;6910.7581234656545;6931.965340173368;6473.255575608771;6547.803565893031;6577.638229897306;6709.094272273278;6722.092517510171;6758.290379510179;6781.932180678229;6783.491238157158;6792.72829086831;6807.591544507345;5403.000444917703;5415.945840541623;5426.131818908955;5430.185472179126;5455.399906010336;4761.023990082025;4798.304245884327;4799.856004319876;4859.635527335393;4863.878403039882;5319.505018259564;6366.108744775735;6372.7132279047555;6389.936100639027;6413.134226879405;5922.7927022554;6675.998600210008;6894.8621027580175;6897.655117244118;6908.930910690829;5194.532492075731;6206.224221972254;6249.281332646804;6332.081848131995;6364.968175159187;5878.177055212273;5935.73289201987;6047.365026672136;6064.332923378425;6126.264848218153;7069.469789765788;7103.82240443993;7110.4667149965;7118.679659415235;7126.786196414116;5464.972675136599;6355.809325720235;6368.267649220399;6923.9653801730665;6964.721411143498;6811.591304521745;6893.655317234218;6994.055802466602;7042.267558328466;7096.396806271318;5637.88724225505;6696.497762667722;6706.429953454011;6744.763933262312;6758.797236082925;6680;6827.283135270725;6833.487488438288;6899.586024838526;6907.620080895599;5277.630565860506;6104.511639068775;6665.133532640726;6690.765823196165;6706.195256569243;5809.302883653989;6512.05575191604;6523.11937888379;6554.213494380514;6620.470362370903;6468.935310647022;6500.154979852589;6532.314107018695;6576.901346479903;6577.671116444063;6378.52161087911;6573.309802470863;6583.967080164569;6604.669766511559;6742.831429214309;6524.249711283073;6545.443637290884;6555.180602424697;6559.8032059038305;6568.474522038321 17:57:58 INFO - PID 1640 | |1;ext.html;738.2838646512882;784.6351446577343;793.3177467378057;1121.7828655908338;1242.4595301044053;38573.228535429276;39310.803445982805;39331.410028849554;39367.40948885762;39368.409473857915;39359.606403935955;39520.01199970002;39695.40456893149;39724.20551588966;39783;7882.1329653738185;8170;8386.287165590902;8392.664293428255;8394.748157555252;8477.364197685167;8534.61594228258;8582.613782379776;8583.313334933222;8594.140585941419;8530.914690853075;8545.74362769118;8607.440516366447;8607.698730544433;8615.095414981399;8535.57322133894;8557.700480483216;8563.186497282779;8598.570071496431;8607.913920860836;8380.497170169809;8431.367647426407;8434.367422443342;8434.78913027173;8449.36629752771;33219.50170747416;34517.13707157316;37176.62823371759;37397;37425;3571.607123216444;3572.142685755426;3605.891823245307;3605.94591081134;3609.476625889258;30519.389612207633;30586.929457468883;30646.54030189549;30752;30810.383792324254;2929.1066224801425;2944.911652650424;2970.1089673097986;2974.8363839988792;2974.985125074361;245776;250458.49541504533;252047.47952520606;287825;289180;47663.80840479016;47995.80010499765;48068;48093.27860082102;48129.5187048125;48503.75748121237;48722.32027238465;48748.5125148751;48847.02305953861;48873;13976.51082212138;14083.647908802363;14094.436222551189;14174.929125354307;14197.645058873612;10100.797984040279;11623.302601843747;11626.476808543635;11651.417429128509;11658.650240492629;13123.737525249442;13266.270355130368;13304.600861974157;13312.600621981355;13407.262600556867;6089.604175728652;6124.5100391968535;6152.600080994721;6152.953997820381;6159.630422174594;843.42225575481;994.5623925472769;1012.3116280929039;1047.1413440978324;1053.986885049567;19499.902500487406;21251.574968500543;23835.04659813588;24073.879630601736;24452;994.8211369527133;1111.6442738323658;1121.170333952869;1129.2546919033398;1144.7481554058095;11026.779464410667;11378.601748938747;12336.75326493465;12411.131220814637;12422.81365779514;330045;331501;331795;333354;333665;52136.47863521392;52288.73855630698;52408.737956309975;52537.211941820904;52660.73669631627;1505.4279373837871;1549.2099029495039;1581.936722531086;1582.1377349344634;1585.2153184173678;20567.8971605141;23715.762842372045;23781.048758049485;23857.164999224944;23913.402164946016;37824.43263350997;37853.81073094727;37920;37926.810365948;38011;278867;280944;281276.5936170306;281517.5924120366;282129;33083.17292067621;34060.14849628779;34221.65778342185;34694.785682500995;34851.30297394089;41995.74012779621;44737.657870263945;44740;44879.775601121786;45279.54720452753;25520.10679626204;29831.403371932007;30048.399032019675;30871.536926946563;31332.53001204938;30056.24859378533;30791;30899.382012360078;30907;31034.379312414076;36536.634633653324;36639.45040824443;37400;37585.81207094056;37618.623813761515;29402.558961615166;30288.84855575708;31012.379752405275;31114.222144445663;31152.37695246037;21657.783422165576;22840.543189136457;23147.65278520857;23374;24500.75499244985 17:57:58 INFO - PID 1640 | |2;jquery.html;376.4503824416352;433.26345213137677;437.975911324877;441.82989549023614;460.7880375027487;19184.424467266006;20750.688739668898;21170.89414552927;21290.57418851622;21514.892425537888;21888.8905555472;22032.44918877025;22265.888670556666;22361.664575031387;22511.437214069658;5786.537077033849;6064.241969753781;6068.180795592605;6075.119107729377;6079.392060793907;4992.950070499285;5868.090445980858;5916.2900451945825;5932.466078052975;5934.228550288456;5833.7083145842535;5883.8234852954265;5883.823485295448;5887.970560147193;5900.557458190631;5669.773209071632;5878.382769809173;5889.411058894077;5931.377205393414;5944.583879128484;5216.400113986902;5272.789088436458;5291.259223708684;5295.179247216649;5295.629305948585;20961.63749356287;24150.39624009414;26071.348216294555;26627.334316642045;26719.465610687872;2009.3168322770327;2244.1584405847807;2286.0741399733133;2289.519200967799;2290.690756747851;12725.936370318183;13997.790033149511;16244.837551624569;16642.50072497827;17421;2096.2453516733963;2100.8214301784365;2151.8278537716947;2153.7630860605323;2162.28644547299;117549.82450175475;146208;146240.24650862347;146607.26696366558;147861;12165.5133794649;12327.260364377986;12341.814872776915;12364.134510584352;12587.223832850108;13371.799423008662;16103.597410064607;16250.51248462548;16326;16372.83627163713;7552.8867066994035;8521.659133634708;8895.4662720237;8973.775655608531;9061.13919177677;8304.335653147733;8408.957955210184;8482.363822713321;8553.486790792447;8572.3999320047;7705.075390953121;7818.726344577913;7822.726204582926;7857.646405911748;7861.960690196513;2157.3959291398432;2174.6846707227364;2186.9343919682433;2189.39791557323;2204.702365180712;853.5049671190818;1006.4866917871792;1024.3341827811983;1034;1054.0513537816028;15223.125961749853;20067;20722.689159662616;20830.791692082887;21004.684929726067;1027.8406846938738;1032.4631191780381;1053.647028245531;1067.2475904487317;1069.8395240713955;10246.948765256275;10687.679369618923;10694.037536621741;10713.303635263683;10755.462226888625;566539;567653;569168.4624730633;572706;580443;93297;122477;128158;128621;128713;1042.9374237545771;1214.5688280660415;1229.8647148813802;1234.5432190089725;1252.4426630149496;20842.68735968992;21144.788552114896;21260.468488287916;21260.51176417636;21263.8936805315;31939.201519961258;51316.486835132666;51467.74266128645;51481.742591286806;51692.93228270827;66986.99519507309;70336;70536;70777.6461117691;71020;6987.895181572178;8603.483790972561;8791.472511649321;8824.514651693962;9034.509305964442;13482;13615.319234038061;13773.242471664154;14011.929940350234;14065.367058482188;11529.827052594386;11698;12044.036477081987;12064.215825971283;12095;10052.597896084075;11096;11137.777244455228;11313.71715707114;11454.94272528632;10790.622328218475;11144.777104457704;12220.205686630341;12289.938550307192;12293.877061229274;8733.257673097603;8858.202761751474;8983.966843813045;8997.82004359896;9005.099490050943;11693.824592631288;11822.204544324635;11978.76042479128;12012;12108.75782484363 17:57:58 INFO - PID 1640 | |3;mootools.html;1201.8978386837118;1481.081729327817;1485.4206859324865;1489.3595753825857;1497.191516581046;11488.310701357925;11848.111391645618;11869.584564540242;12013.819792703116;12022.819657705131;11764.117691173153;12016;12019;12020.398980050984;12026.69933251667;2559.4497183105627;2615.712271650117;2619.8559079250635;2621.790256779453;2623.3048242215827;2528.279440359498;2576.0726138590185;2580.7290234525385;2585.1339801166537;2586.0560895273184;2545.2745967399296;2560.2831207261997;2591.676040494938;2595.4549544595657;2598.0257403473697;2541.07250853439;2558.667373241476;2590.883410246534;2593.572060609997;2597.7662010418962;2497.887595058227;2550.413404916861;2581.7934565234923;2599.284472248326;2603.5053339865367;11741.412929353584;11768.470418831173;11809.40952952349;11812.40937953099;11818.527258909633;6653.866922661519;6881.13985751781;6912.723491060352;6934.687939042755;6963.512554121214;3880.9029774255587;4207.663386929038;4228.556001619816;4266.701330906838;4330.437043184397;1174.2543484887074;1181.2381014245764;1222.2483172848633;1227.0919519555464;1236.771197328497;12612.873871261354;15440.227988600638;16098.953568018042;16414.589635259093;16426.83573164265;5508.6779172998195;5773.509251713567;5935.228420305332;6073.119397687399;6260.686965651699;13846.446142154202;13922.651933701738;13944.442222310994;13958.09272397291;13961.79057314141;6078.665673387918;6749.9537571676465;6896.344847239605;6912.619805910723;6941.895871561931;6133.619935514474;6160.168377269101;6271.592346497463;6294.370562943761;6310.305866354697;4611.400517932647;4725.220338644085;4731.858044258678;4785.473597904228;4792.257200133915;1260.9432575534122;1466.3767898643034;1508.2308022908396;1512.8335883052855;1514.1596413990342;799.3844739550514;803.9196080391914;823.4770920465547;830.1283652165203;854.6666799948025;4843.39457567804;6113.510919126459;6236.844078898064;6285.748570057147;6314.778982735674;593.7239183779564;596.1832289763053;609.1441524657863;616.3004989337045;621.0932039222695;4010.4934415990006;4344.913101737948;4409.162259170747;4468.173387923244;4556.794944227518;14297.78553321701;14323.641908952359;14819.333130009176;14853.331600078023;14861.034032787835;14356.353964071643;14778.18719970412;14888.404463821325;14949;15028.17345046033;6775.390214880685;8172.795680107926;8445.788855278668;8533.658653653783;8553.95723021381;6984.387018150241;7018.473614479037;7256.9274307258365;7292.307230813064;7316.780496585111;12905.677358066123;13200.471981120647;13320.800187997382;13481.46074157062;13517.324133793074;13229.867701323248;13768;13841.653958650712;13852.861471385157;13885;10393.636222732168;10774.622888198875;10796.89203107959;10801.837972430263;10829.891701082888;10641.98901104394;10650.041496265374;10680.732981675521;10821.891781082404;10849.511771970123;9060.728178154666;9108.772280693036;9187.586558604746;9256.861147082664;9323.72028839136;8897.28821694276;8915.37592368528;9130.452172869649;9165;9187.127222913812;8143.79640508992;8248.13394593559;8273.46222495536;8288;8292.792680182802;6056.182415373868;6224.1597384354345;6230.7507699691505;6265.154204182377;6284.391977329357;6133.907991380043;6172.8765424690355;6186.907196392148;6331.113644089924;6375.266844312778 17:57:58 INFO - PID 1640 | |4;prototype.html;353.35159981434066;381.1424295335493;403.44526276369993;403.68310875962374;407.67386091127094;5382.91925621116;5808.941910580898;6243.1292598588825;6386;6414.166158399407;4558.997020655459;5538.421549858291;5769.788344447671;6068.726907289171;6395.008773640082;3321.4021476134285;4556.516698399364;4586.564276393738;4599.609033232179;4602.4937256901885;3768.4724138620613;3889.630485103911;4502.122086193193;4511.436070491189;4596.552086092886;3879.7478163919395;4097.282975479294;4488.8877778055485;4497.392851964992;4528.890421846636;4398.824047038114;4542.523035081302;4562.54374562546;4566.840160594396;4568.314752787109;3760.7179461540495;3875.360565506688;3897.4738410314526;3910.6508254652385;3923.7253392262555;6109.694515274218;6115.816525504242;6134.2332208473945;6152.400375902253;6175.4442100210745;5386.676799392049;5415.810446634388;5418.5369950113645;5424.105022671254;5444.700541470217;6528.836779080513;6638.10385597943;6650.334966503359;6740.427063699592;6748.595084294958;3667.541557305337;4375.221734198775;4392.626626736731;4396.868093957154;4398.912021759579;6974.476914231453;7103.431725461951;7130.6078165700865;7167.283271672791;7207.495475316678;4396.890077748082;4429.737524805415;4451.198784218872;4466.508684044752;4501.347304640809;4523.457185137804;4532.9546704533195;4553.658475614276;4558.3162525620755;4560.703554268962;4585.128825523207;5211.895762084813;5295.179247216687;5304.23088652151;5438.45615438453;4961.801527938842;6771.814932386813;6778.186617605918;6794.966025169842;6830.829229269308;1464.9964774129755;1535.6391248056698;1548.807418287912;1548.9380424782883;1555.673308605183;984.2519685039279;1010.4442556593991;1010.6008126789877;1014.3305418423805;1016.5869441476353;5349.5720342372515;5444.7822087116865;5515.117581186991;6322.178116844781;6407.732056651142;5900.262467191601;6035.465104656055;7016.859662806715;7024.5434046786795;7046.6176235406265;5420.810271640474;6219.626822390671;6358.3641635836975;6360.109584658151;6367.936320636827;2968.93118477348;3072.7695422843376;3105.1616063662686;3531.399662057456;3661.761985470936;5748.971255143698;5867.354590994987;6507.349265073455;7210.351068403764;7210.819729506804;7142.4286057115305;7145.535540189871;7205.927940720526;7208.873382352219;7218.314260145278;6803.298750730526;7049.436045116276;7155.284471552803;7231.758154971975;7262.237465066198;6935.479839012195;6981.616011119438;6984.742746305714;7014.333638304353;7019.543729657556;6205.720742566505;6365.7135428904885;6637.2699003108655;6864.4165245954655;6968.547044442298;6381.3937675920715;6492.31830657793;6695.263521012587;7245.673944672397;7303.042267881517;4824.734639594856;4886.656169553259;4981.4769449207315;5132.178851383761;5148.716820574904;6612.70242839083;7041.788746337618;7072.469564782765;7117;7153.713851445884;5429.592780541554;5726.599138060295;6009.909851352146;6454.806355809334;6776.983452482167;6528.934710653023;6783.59298442095;6846.5892046477375;6850.191421517154;6885.483588730765;5285.814996475105;6508.707108180049;6717.1267735194115;6751.493637977073;6763.396092504323;5742.138679198154;6170.290416602148;6211.782587609412;6797.6941037654415;6858.079737673459;6145.832291864532;6165.815025549241;6668.83327916806;6670.633115178712;6729.226138993882 17:57:58 INFO - PID 1640 | |5;yui.html;820.2330820682666;839.7732612194708;853.7268074216254;858.944168629039;869.5131325433508;14346.354414051366;14904.552863414088;15318.310676019579;16806;16819.243134058983;12490.188137771038;13030.739385212313;17095.230714617843;17161.914190429063;17235.965842049456;750.9774706758806;936.7845395559026;957.9425234485918;968.9757756056084;971.7424882406168;946.7775072857872;949.6913503111482;950.581744032627;951.690700522333;951.914327710506;978.6770365779312;996.6611352140271;1023.7235946294534;1024.6874703215517;1028.9382637041765;995.6515219673129;1017.6234793126516;1019.7348689340788;1020.5968642386252;1020.7346090016575;724.409606170969;741.125471943107;742.480263815326;750.6134340814485;750.8873668949701;15851.524454266391;16262;16336.591585210346;16994.49016529506;17354;17067;17067.65864682712;17230.138493075297;17261.913690431593;17654;13152.40814163365;14162.575122746333;14216.928915355356;14474.059186152972;14657.04729192599;3607.549056367954;3629.963700362989;3637.4543818427446;3642.0530662028063;3686.318031164243;120282;138584;138854;142431.28784356013;142815;13449.117123602704;13767.655808604664;13773.724525509433;13779.448822047207;14136.08115472491;25655.10207142741;25887;26111.08611198599;26231.868840655676;26285.868570657407;7807.297343239135;7938.801529961682;8079;8087.555184464911;8091.231333023354;3932.6853851691794;3966.781826999543;3982.880513584597;3996.820143093568;4009.676806653797;4199.454070970754;4277.144571085733;4304.569543045671;4313.84901528445;4317.395564620955;929.1173385284008;941.5104145844123;957.2820384711466;960.5389413081759;961.077365728894;573.4466240078344;638.6683764351379;645.5094128462308;668.1547841979899;669.9196096468455;10171.694849154537;10903.236773425784;11022.50398732059;11972;12708.745825083632;642.7493277621732;648.5881465269588;661.4741280681774;661.6592454885738;666.5334266013713;5023.296738456619;5155.304033955368;5565.332160140728;5585.329760428694;5625.043742563774;101522;102176.77078178745;105933.47033264785;107175.92824071816;113296;93336.53331733299;96052.51973740087;96940;98181;99410.50884236742;82968;83021;84289.57855210685;84407;85435;9419.670311539337;11444.656660300205;11804.822927656263;12635.1787133836;12861.871381286068;29645;31661.15049645543;39560.802195989985;40311;40735;59126;61660.76678466495;71240.28759712544;76262.23737762551;77176;12924.547640832901;14072.788908166181;15959.601009974844;16328.673426531166;16514.917425412797;17716.64566708633;18006.90996545009;18161.81838181654;18212.908935455238;18391.81608183901;16197.271122799739;16356.591085222964;16560.585985349982;16790.412335568195;16845.83154168492;16158.111303878399;16208.432704855273;16492.505224843273;16617.916910415854;16768.496945091665;13242.271675057964;15662.373505060126;16130.67738645244;16141.435049773201;16202.756958645394;11515.884841151481;11615.128865334964;11988.940055300016;12162.756744864877;12277.631671049525;11415.771684566429;12511.124221304786;13359.66600834948;13634.659133521345;14047.297635118408 17:57:58 INFO - PID 1640 | __end_tp_report 17:57:58 INFO - PID 1640 | __start_cc_report 17:57:58 INFO - PID 1640 | _x_x_mozilla_cycle_collect,340 17:57:58 INFO - PID 1640 | __end_cc_report 17:57:58 INFO - PID 1640 | __startTimestamp1495155478697__endTimestamp 17:57:58 INFO - PID 1640 | 17:57:58 INFO - PID 1640 | ------- Summary: start ------- 17:57:58 INFO - PID 1640 | Number of tests: 6 17:57:58 INFO - PID 1640 | 17:57:58 INFO - PID 1640 | [#0] dojo.html Cycles:165 Average:6160.90 Median:6405.87 stddev:778.33 (12.2%) stddev-sans-first:748.37 17:57:58 INFO - PID 1640 | Values: 3330.2 3440.8 3592.0 3631.4 3635.5 5595.4 5617.6 6114.4 6221.3 6275.6 4846.8 6221.1 6230.1 6234.8 6278.7 5079.8 5471.0 5516.8 5517.8 5637.8 4875.9 4910.3 5550.6 5627.0 5633.1 5381.7 5542.5 5563.4 5614.7 5629.5 5400.4 5485.5 5488.6 5505.8 5534.8 5452.2 5483.9 5487.7 5507.4 5517.1 5137.9 5691.9 6196.3 6266.0 6279.1 5534.6 5824.7 6485.0 6542.6 6751.1 6407.6 6867.5 6871.6 6893.8 6908.3 6386.9 6391.5 6405.9 6431.9 6432.1 6745.7 6831.8 6922.6 6990.9 7013.7 6676.7 6693.7 6715.8 6740.8 6759.6 6799.5 6902.3 6903.5 6910.8 6932.0 6473.3 6547.8 6577.6 6709.1 6722.1 6758.3 6781.9 6783.5 6792.7 6807.6 5403.0 5415.9 5426.1 5430.2 5455.4 4761.0 4798.3 4799.9 4859.6 4863.9 5319.5 6366.1 6372.7 6389.9 6413.1 5922.8 6676.0 6894.9 6897.7 6908.9 5194.5 6206.2 6249.3 6332.1 6365.0 5878.2 5935.7 6047.4 6064.3 6126.3 7069.5 7103.8 7110.5 7118.7 7126.8 5465.0 6355.8 6368.3 6924.0 6964.7 6811.6 6893.7 6994.1 7042.3 7096.4 5637.9 6696.5 6706.4 6744.8 6758.8 6680.0 6827.3 6833.5 6899.6 6907.6 5277.6 6104.5 6665.1 6690.8 6706.2 5809.3 6512.1 6523.1 6554.2 6620.5 6468.9 6500.2 6532.3 6576.9 6577.7 6378.5 6573.3 6584.0 6604.7 6742.8 6524.2 6545.4 6555.2 6559.8 6568.5 17:57:58 INFO - PID 1640 | 17:57:58 INFO - PID 1640 | [#1] ext.html Cycles:180 Average:44339.92 Median:183862.57 stddev:77168.90 (42.0%) stddev-sans-first:77315.94 17:57:58 INFO - PID 1640 | Values: 738.3 784.6 793.3 1121.8 1242.5 38573.2 39310.8 39331.4 39367.4 39368.4 39359.6 39520.0 39695.4 39724.2 39783.0 7882.1 8170.0 8386.3 8392.7 8394.7 8477.4 8534.6 8582.6 8583.3 8594.1 8530.9 8545.7 8607.4 8607.7 8615.1 8535.6 8557.7 8563.2 8598.6 8607.9 8380.5 8431.4 8434.4 8434.8 8449.4 33219.5 34517.1 37176.6 37397.0 37425.0 3571.6 3572.1 3605.9 3605.9 3609.5 30519.4 30586.9 30646.5 30752.0 30810.4 2929.1 2944.9 2970.1 2974.8 2975.0 245776.0 250458.5 252047.5 287825.0 289180.0 47663.8 47995.8 48068.0 48093.3 48129.5 48503.8 48722.3 48748.5 48847.0 48873.0 13976.5 14083.6 14094.4 14174.9 14197.6 10100.8 11623.3 11626.5 11651.4 11658.7 13123.7 13266.3 13304.6 13312.6 13407.3 6089.6 6124.5 6152.6 6153.0 6159.6 843.4 994.6 1012.3 1047.1 1054.0 19499.9 21251.6 23835.0 24073.9 24452.0 994.8 1111.6 1121.2 1129.3 1144.7 11026.8 11378.6 12336.8 12411.1 12422.8 330045.0 331501.0 331795.0 333354.0 333665.0 52136.5 52288.7 52408.7 52537.2 52660.7 1505.4 1549.2 1581.9 1582.1 1585.2 20567.9 23715.8 23781.0 23857.2 23913.4 37824.4 37853.8 37920.0 37926.8 38011.0 278867.0 280944.0 281276.6 281517.6 282129.0 33083.2 34060.1 34221.7 34694.8 34851.3 41995.7 44737.7 44740.0 44879.8 45279.5 25520.1 29831.4 30048.4 30871.5 31332.5 30056.2 30791.0 30899.4 30907.0 31034.4 36536.6 36639.5 37400.0 37585.8 37618.6 29402.6 30288.8 31012.4 31114.2 31152.4 21657.8 22840.5 23147.7 23374.0 24500.8 17:57:58 INFO - PID 1640 | 17:57:58 INFO - PID 1640 | [#2] jquery.html Cycles:180 Average:34839.44 Median:2168.49 stddev:95861.80 (4420.7%) stddev-sans-first:96095.79 17:57:58 INFO - PID 1640 | Values: 376.5 433.3 438.0 441.8 460.8 19184.4 20750.7 21170.9 21290.6 21514.9 21888.9 22032.4 22265.9 22361.7 22511.4 5786.5 6064.2 6068.2 6075.1 6079.4 4993.0 5868.1 5916.3 5932.5 5934.2 5833.7 5883.8 5883.8 5888.0 5900.6 5669.8 5878.4 5889.4 5931.4 5944.6 5216.4 5272.8 5291.3 5295.2 5295.6 20961.6 24150.4 26071.3 26627.3 26719.5 2009.3 2244.2 2286.1 2289.5 2290.7 12725.9 13997.8 16244.8 16642.5 17421.0 2096.2 2100.8 2151.8 2153.8 2162.3 117549.8 146208.0 146240.2 146607.3 147861.0 12165.5 12327.3 12341.8 12364.1 12587.2 13371.8 16103.6 16250.5 16326.0 16372.8 7552.9 8521.7 8895.5 8973.8 9061.1 8304.3 8409.0 8482.4 8553.5 8572.4 7705.1 7818.7 7822.7 7857.6 7862.0 2157.4 2174.7 2186.9 2189.4 2204.7 853.5 1006.5 1024.3 1034.0 1054.1 15223.1 20067.0 20722.7 20830.8 21004.7 1027.8 1032.5 1053.6 1067.2 1069.8 10246.9 10687.7 10694.0 10713.3 10755.5 566539.0 567653.0 569168.5 572706.0 580443.0 93297.0 122477.0 128158.0 128621.0 128713.0 1042.9 1214.6 1229.9 1234.5 1252.4 20842.7 21144.8 21260.5 21260.5 21263.9 31939.2 51316.5 51467.7 51481.7 51692.9 66987.0 70336.0 70536.0 70777.6 71020.0 6987.9 8603.5 8791.5 8824.5 9034.5 13482.0 13615.3 13773.2 14011.9 14065.4 11529.8 11698.0 12044.0 12064.2 12095.0 10052.6 11096.0 11137.8 11313.7 11454.9 10790.6 11144.8 12220.2 12289.9 12293.9 8733.3 8858.2 8984.0 8997.8 9005.1 11693.8 11822.2 11978.8 12012.0 12108.8 17:57:58 INFO - PID 1640 | 17:57:58 INFO - PID 1640 | [#3] mootools.html Cycles:180 Average:7233.64 Median:2609.61 stddev:4517.81 (173.1%) stddev-sans-first:4507.74 17:57:58 INFO - PID 1640 | Values: 1201.9 1481.1 1485.4 1489.4 1497.2 11488.3 11848.1 11869.6 12013.8 12022.8 11764.1 12016.0 12019.0 12020.4 12026.7 2559.4 2615.7 2619.9 2621.8 2623.3 2528.3 2576.1 2580.7 2585.1 2586.1 2545.3 2560.3 2591.7 2595.5 2598.0 2541.1 2558.7 2590.9 2593.6 2597.8 2497.9 2550.4 2581.8 2599.3 2603.5 11741.4 11768.5 11809.4 11812.4 11818.5 6653.9 6881.1 6912.7 6934.7 6963.5 3880.9 4207.7 4228.6 4266.7 4330.4 1174.3 1181.2 1222.2 1227.1 1236.8 12612.9 15440.2 16099.0 16414.6 16426.8 5508.7 5773.5 5935.2 6073.1 6260.7 13846.4 13922.7 13944.4 13958.1 13961.8 6078.7 6750.0 6896.3 6912.6 6941.9 6133.6 6160.2 6271.6 6294.4 6310.3 4611.4 4725.2 4731.9 4785.5 4792.3 1260.9 1466.4 1508.2 1512.8 1514.2 799.4 803.9 823.5 830.1 854.7 4843.4 6113.5 6236.8 6285.7 6314.8 593.7 596.2 609.1 616.3 621.1 4010.5 4344.9 4409.2 4468.2 4556.8 14297.8 14323.6 14819.3 14853.3 14861.0 14356.4 14778.2 14888.4 14949.0 15028.2 6775.4 8172.8 8445.8 8533.7 8554.0 6984.4 7018.5 7256.9 7292.3 7316.8 12905.7 13200.5 13320.8 13481.5 13517.3 13229.9 13768.0 13841.7 13852.9 13885.0 10393.6 10774.6 10796.9 10801.8 10829.9 10642.0 10650.0 10680.7 10821.9 10849.5 9060.7 9108.8 9187.6 9256.9 9323.7 8897.3 8915.4 9130.5 9165.0 9187.1 8143.8 8248.1 8273.5 8288.0 8292.8 6056.2 6224.2 6230.8 6265.2 6284.4 6133.9 6172.9 6186.9 6331.1 6375.3 17:57:58 INFO - PID 1640 | 17:57:58 INFO - PID 1640 | [#4] prototype.html Cycles:180 Average:5367.38 Median:5955.09 stddev:1735.93 (29.2%) stddev-sans-first:1699.51 17:57:58 INFO - PID 1640 | Values: 353.4 381.1 403.4 403.7 407.7 5382.9 5808.9 6243.1 6386.0 6414.2 4559.0 5538.4 5769.8 6068.7 6395.0 3321.4 4556.5 4586.6 4599.6 4602.5 3768.5 3889.6 4502.1 4511.4 4596.6 3879.7 4097.3 4488.9 4497.4 4528.9 4398.8 4542.5 4562.5 4566.8 4568.3 3760.7 3875.4 3897.5 3910.7 3923.7 6109.7 6115.8 6134.2 6152.4 6175.4 5386.7 5415.8 5418.5 5424.1 5444.7 6528.8 6638.1 6650.3 6740.4 6748.6 3667.5 4375.2 4392.6 4396.9 4398.9 6974.5 7103.4 7130.6 7167.3 7207.5 4396.9 4429.7 4451.2 4466.5 4501.3 4523.5 4533.0 4553.7 4558.3 4560.7 4585.1 5211.9 5295.2 5304.2 5438.5 4961.8 6771.8 6778.2 6795.0 6830.8 1465.0 1535.6 1548.8 1548.9 1555.7 984.3 1010.4 1010.6 1014.3 1016.6 5349.6 5444.8 5515.1 6322.2 6407.7 5900.3 6035.5 7016.9 7024.5 7046.6 5420.8 6219.6 6358.4 6360.1 6367.9 2968.9 3072.8 3105.2 3531.4 3661.8 5749.0 5867.4 6507.3 7210.4 7210.8 7142.4 7145.5 7205.9 7208.9 7218.3 6803.3 7049.4 7155.3 7231.8 7262.2 6935.5 6981.6 6984.7 7014.3 7019.5 6205.7 6365.7 6637.3 6864.4 6968.5 6381.4 6492.3 6695.3 7245.7 7303.0 4824.7 4886.7 4981.5 5132.2 5148.7 6612.7 7041.8 7072.5 7117.0 7153.7 5429.6 5726.6 6009.9 6454.8 6777.0 6528.9 6783.6 6846.6 6850.2 6885.5 5285.8 6508.7 6717.1 6751.5 6763.4 5742.1 6170.3 6211.8 6797.7 6858.1 6145.8 6165.8 6668.8 6670.6 6729.2 17:57:58 INFO - PID 1640 | 17:57:58 INFO - PID 1640 | [#5] yui.html Cycles:180 Average:22509.79 Median:22023.46 stddev:32815.01 (149.0%) stddev-sans-first:32866.65 17:57:58 INFO - PID 1640 | Values: 820.2 839.8 853.7 858.9 869.5 14346.4 14904.6 15318.3 16806.0 16819.2 12490.2 13030.7 17095.2 17161.9 17236.0 751.0 936.8 957.9 969.0 971.7 946.8 949.7 950.6 951.7 951.9 978.7 996.7 1023.7 1024.7 1028.9 995.7 1017.6 1019.7 1020.6 1020.7 724.4 741.1 742.5 750.6 750.9 15851.5 16262.0 16336.6 16994.5 17354.0 17067.0 17067.7 17230.1 17261.9 17654.0 13152.4 14162.6 14216.9 14474.1 14657.0 3607.5 3630.0 3637.5 3642.1 3686.3 120282.0 138584.0 138854.0 142431.3 142815.0 13449.1 13767.7 13773.7 13779.4 14136.1 25655.1 25887.0 26111.1 26231.9 26285.9 7807.3 7938.8 8079.0 8087.6 8091.2 3932.7 3966.8 3982.9 3996.8 4009.7 4199.5 4277.1 4304.6 4313.8 4317.4 929.1 941.5 957.3 960.5 961.1 573.4 638.7 645.5 668.2 669.9 10171.7 10903.2 11022.5 11972.0 12708.7 642.7 648.6 661.5 661.7 666.5 5023.3 5155.3 5565.3 5585.3 5625.0 101522.0 102176.8 105933.5 107175.9 113296.0 93336.5 96052.5 96940.0 98181.0 99410.5 82968.0 83021.0 84289.6 84407.0 85435.0 9419.7 11444.7 11804.8 12635.2 12861.9 29645.0 31661.2 39560.8 40311.0 40735.0 59126.0 61660.8 71240.3 76262.2 77176.0 12924.5 14072.8 15959.6 16328.7 16514.9 17716.6 18006.9 18161.8 18212.9 18391.8 16197.3 16356.6 16560.6 16790.4 16845.8 16158.1 16208.4 16492.5 16617.9 16768.5 13242.3 15662.4 16130.7 16141.4 16202.8 11515.9 11615.1 11988.9 12162.8 12277.6 11415.8 12511.1 13359.7 13634.7 14047.3 17:57:58 INFO - PID 1640 | -------- Summary: end -------- 17:57:58 INFO - PID 1640 | 17:57:59 INFO - TEST-INFO | 1640: exit 0 17:57:59 INFO - TEST-OK | dromaeo_css | took 1112893ms 17:57:59 INFO - TEST-START | kraken 17:57:59 INFO - operating with platform_type : mac_ 17:57:59 INFO - Initialising browser for kraken test... 17:57:59 INFO - TEST-INFO | started process 1656 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/fp/_7br9k0s4j30pf06c5ky859c00000w/T/tmpNgN1iL/profile http://localhost:49221/getInfo.html) 17:57:59 INFO - PID 1656 | JavaScript warning: resource://gre/modules/addons/XPIProvider.jsm, line 2941: String.localeCompare is deprecated; use String.prototype.localeCompare instead 17:58:01 INFO - PID 1656 | 2017-05-18 17:58:01.672 plugin-container[1657:10523] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4947, name = 'com.apple.tsm.portname' 17:58:01 INFO - PID 1656 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:58:01 INFO - PID 1656 | 2017-05-18 17:58:01.675 plugin-container[1657:10523] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9e0b, name = 'com.apple.CFPasteboardClient' 17:58:01 INFO - PID 1656 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:58:01 INFO - PID 1656 | 2017-05-18 17:58:01.675 plugin-container[1657:10523] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 17:58:01 INFO - PID 1656 | __metrics Screen width/height:1600/1200 17:58:01 INFO - PID 1656 | colorDepth:24 17:58:01 INFO - PID 1656 | Browser inner width/height: 1024/691 17:58:01 INFO - PID 1656 | __metrics 17:58:02 INFO - PID 1656 | 17:58:02 INFO - PID 1656 | ###!!! [Child][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost 17:58:02 INFO - PID 1656 | 17:58:02 INFO - TEST-INFO | 1656: exit 0 17:58:02 INFO - Browser initialized. 17:58:02 INFO - Running cycle 1/1 for kraken test... 17:58:02 INFO - TEST-INFO | started process 1659 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/fp/_7br9k0s4j30pf06c5ky859c00000w/T/tmpNgN1iL/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 17:58:04 INFO - PID 1659 | 2017-05-18 17:58:04.663 plugin-container[1660:10665] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4543, name = 'com.apple.tsm.portname' 17:58:04 INFO - PID 1659 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:58:04 INFO - PID 1659 | 2017-05-18 17:58:04.663 plugin-container[1660:10665] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x963f, name = 'com.apple.CFPasteboardClient' 17:58:04 INFO - PID 1659 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:58:04 INFO - PID 1659 | 2017-05-18 17:58:04.663 plugin-container[1660:10665] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 17:58:05 INFO - PID 1659 | 2017-05-18 17:58:05.274 plugin-container[1661:10711] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x454b, name = 'com.apple.tsm.portname' 17:58:05 INFO - PID 1659 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:58:05 INFO - PID 1659 | 2017-05-18 17:58:05.274 plugin-container[1661:10711] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9c0b, name = 'com.apple.CFPasteboardClient' 17:58:05 INFO - PID 1659 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:58:05 INFO - PID 1659 | 2017-05-18 17:58:05.274 plugin-container[1661:10711] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 17:58:14 INFO - PID 1659 | RSS: Main: 240058368 17:58:14 INFO - PID 1659 | 17:58:17 INFO - PID 1659 | Cycle 1(1): loaded http://localhost:49221/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49221/tests/kraken/driver.html?testName=audio-beat-detection) 17:58:18 INFO - PID 1659 | RSS: Main: 251514880 17:58:18 INFO - PID 1659 | 17:58:21 INFO - PID 1659 | Cycle 1(1): loaded http://localhost:49221/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49221/tests/kraken/driver.html?testName=audio-dft) 17:58:21 INFO - PID 1659 | RSS: Main: 259522560 17:58:21 INFO - PID 1659 | 17:58:25 INFO - PID 1659 | Cycle 1(1): loaded http://localhost:49221/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49221/tests/kraken/driver.html?testName=audio-fft) 17:58:25 INFO - PID 1659 | RSS: Main: 262496256 17:58:25 INFO - PID 1659 | 17:58:27 INFO - PID 1659 | Cycle 1(1): loaded http://localhost:49221/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49221/tests/kraken/driver.html?testName=audio-oscillator) 17:58:28 INFO - PID 1659 | RSS: Main: 263401472 17:58:28 INFO - PID 1659 | 17:58:30 INFO - PID 1659 | Cycle 1(1): loaded http://localhost:49221/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49221/tests/kraken/driver.html?testName=imaging-gaussian-blur) 17:58:30 INFO - PID 1659 | RSS: Main: 263888896 17:58:30 INFO - PID 1659 | 17:58:33 INFO - PID 1659 | Cycle 1(1): loaded http://localhost:49221/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49221/tests/kraken/driver.html?testName=imaging-darkroom) 17:58:34 INFO - PID 1659 | RSS: Main: 271093760 17:58:34 INFO - PID 1659 | 17:58:37 INFO - PID 1659 | Cycle 1(1): loaded http://localhost:49221/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49221/tests/kraken/driver.html?testName=imaging-desaturate) 17:58:38 INFO - PID 1659 | RSS: Main: 271056896 17:58:38 INFO - PID 1659 | 17:58:40 INFO - PID 1659 | Cycle 1(1): loaded http://localhost:49221/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49221/tests/kraken/driver.html?testName=json-parse-financial) 17:58:41 INFO - PID 1659 | RSS: Main: 270163968 17:58:41 INFO - PID 1659 | 17:58:43 INFO - PID 1659 | Cycle 1(1): loaded http://localhost:49221/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49221/tests/kraken/driver.html?testName=json-stringify-tinderbox) 17:58:43 INFO - PID 1659 | RSS: Main: 270147584 17:58:43 INFO - PID 1659 | 17:58:45 INFO - PID 1659 | Cycle 1(1): loaded http://localhost:49221/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49221/tests/kraken/driver.html?testName=stanford-crypto-aes) 17:58:46 INFO - PID 1659 | RSS: Main: 269774848 17:58:46 INFO - PID 1659 | 17:58:48 INFO - PID 1659 | Cycle 1(1): loaded http://localhost:49221/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49221/tests/kraken/driver.html?testName=stanford-crypto-ccm) 17:58:49 INFO - PID 1659 | RSS: Main: 269385728 17:58:49 INFO - PID 1659 | 17:58:51 INFO - PID 1659 | Cycle 1(1): loaded http://localhost:49221/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49221/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 17:58:52 INFO - PID 1659 | RSS: Main: 269742080 17:58:52 INFO - PID 1659 | 17:58:56 INFO - PID 1659 | Cycle 1(1): loaded http://localhost:49221/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49221/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 17:58:56 INFO - PID 1659 | RSS: Main: 267280384 17:58:56 INFO - PID 1659 | 17:58:58 INFO - PID 1659 | Cycle 1(1): loaded http://localhost:49221/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49221/tests/kraken/driver.html?testName=ai-astar) 17:58:58 INFO - PID 1659 | RSS: Main: 268832768 17:58:58 INFO - PID 1659 | 17:58:58 INFO - PID 1659 | __start_tp_report 17:58:58 INFO - PID 1659 | _x_x_mozilla_page_load 17:58:58 INFO - PID 1659 | _x_x_mozilla_page_load_details 17:58:58 INFO - PID 1659 | |i|pagename|runs| 17:58:58 INFO - PID 1659 | |0;ai-astar;114;171;114;114;113;111;113;115;115;115 17:58:58 INFO - PID 1659 | |1;audio-beat-detection;137;111;116;154;128;149;146;112;160;149 17:58:58 INFO - PID 1659 | |2;audio-dft;286;255;198;204;210;208;197;200;198;199 17:58:58 INFO - PID 1659 | |3;audio-fft;78;57;59;87;64;72;81;60;76;59 17:58:58 INFO - PID 1659 | |4;audio-oscillator;80;72;83;71;69;74;88;70;56;94 17:58:58 INFO - PID 1659 | |5;imaging-gaussian-blur;90;90;85;91;91;89;89;90;89;89 17:58:58 INFO - PID 1659 | |6;imaging-darkroom;97;106;95;107;156;96;107;158;98;97 17:58:58 INFO - PID 1659 | |7;imaging-desaturate;100;94;90;95;95;91;89;93;91;90 17:58:58 INFO - PID 1659 | |8;json-parse-financial;52;49;78;49;52;46;46;73;77;77 17:58:58 INFO - PID 1659 | |9;json-stringify-tinderbox;57;60;60;66;65;60;55;60;58;58 17:58:58 INFO - PID 1659 | |10;stanford-crypto-aes;99;108;99;85;83;86;85;82;85;86 17:58:58 INFO - PID 1659 | |11;stanford-crypto-ccm;150;159;154;160;157;138;141;135;186;131 17:58:58 INFO - PID 1659 | |12;stanford-crypto-pbkdf2;245;246;187;249;167;239;192;236;241;188 17:58:58 INFO - PID 1659 | |13;stanford-crypto-sha256-iterative;70;70;66;71;70;71;70;67;83;93 17:58:58 INFO - PID 1659 | __end_tp_report 17:58:58 INFO - PID 1659 | __start_cc_report 17:58:58 INFO - PID 1659 | _x_x_mozilla_cycle_collect,911 17:58:58 INFO - PID 1659 | __end_cc_report 17:58:58 INFO - PID 1659 | __startTimestamp1495155538725__endTimestamp 17:58:58 INFO - PID 1659 | 17:58:58 INFO - PID 1659 | ------- Summary: start ------- 17:58:58 INFO - PID 1659 | Number of tests: 14 17:58:58 INFO - PID 1659 | 17:58:58 INFO - PID 1659 | [#0] ai-astar Cycles:10 Average:119.50 Median:114.50 stddev:18.14 (15.8%) stddev-sans-first:19.13 17:58:58 INFO - PID 1659 | Values: 114.0 171.0 114.0 114.0 113.0 111.0 113.0 115.0 115.0 115.0 17:58:58 INFO - PID 1659 | 17:58:58 INFO - PID 1659 | [#1] audio-beat-detection Cycles:10 Average:136.20 Median:147.50 stddev:18.27 (12.4%) stddev-sans-first:19.37 17:58:58 INFO - PID 1659 | Values: 137.0 111.0 116.0 154.0 128.0 149.0 146.0 112.0 160.0 149.0 17:58:58 INFO - PID 1659 | 17:58:58 INFO - PID 1659 | [#2] audio-dft Cycles:10 Average:215.50 Median:206.00 stddev:30.21 (14.7%) stddev-sans-first:18.35 17:58:58 INFO - PID 1659 | Values: 286.0 255.0 198.0 204.0 210.0 208.0 197.0 200.0 198.0 199.0 17:58:58 INFO - PID 1659 | 17:58:58 INFO - PID 1659 | [#3] audio-fft Cycles:10 Average:69.30 Median:74.00 stddev:10.83 (14.6%) stddev-sans-first:11.02 17:58:58 INFO - PID 1659 | Values: 78.0 57.0 59.0 87.0 64.0 72.0 81.0 60.0 76.0 59.0 17:58:58 INFO - PID 1659 | 17:58:58 INFO - PID 1659 | [#4] audio-oscillator Cycles:10 Average:75.70 Median:77.00 stddev:10.86 (14.1%) stddev-sans-first:11.41 17:58:58 INFO - PID 1659 | Values: 80.0 72.0 83.0 71.0 69.0 74.0 88.0 70.0 56.0 94.0 17:58:58 INFO - PID 1659 | 17:58:58 INFO - PID 1659 | [#5] imaging-gaussian-blur Cycles:10 Average:89.30 Median:90.00 stddev:1.70 (1.9%) stddev-sans-first:1.79 17:58:58 INFO - PID 1659 | Values: 90.0 90.0 85.0 91.0 91.0 89.0 89.0 90.0 89.0 89.0 17:58:58 INFO - PID 1659 | 17:58:58 INFO - PID 1659 | [#6] imaging-darkroom Cycles:10 Average:111.70 Median:95.50 stddev:24.33 (25.5%) stddev-sans-first:25.22 17:58:58 INFO - PID 1659 | Values: 97.0 106.0 95.0 107.0 156.0 96.0 107.0 158.0 98.0 97.0 17:58:58 INFO - PID 1659 | 17:58:58 INFO - PID 1659 | [#7] imaging-desaturate Cycles:10 Average:92.80 Median:92.00 stddev:3.33 (3.6%) stddev-sans-first:2.29 17:58:58 INFO - PID 1659 | Values: 100.0 94.0 90.0 95.0 95.0 91.0 89.0 93.0 91.0 90.0 17:58:58 INFO - PID 1659 | 17:58:58 INFO - PID 1659 | [#8] json-parse-financial Cycles:10 Average:59.90 Median:62.50 stddev:14.27 (22.8%) stddev-sans-first:14.85 17:58:58 INFO - PID 1659 | Values: 52.0 49.0 78.0 49.0 52.0 46.0 46.0 73.0 77.0 77.0 17:58:58 INFO - PID 1659 | 17:58:58 INFO - PID 1659 | [#9] json-stringify-tinderbox Cycles:10 Average:59.90 Median:60.00 stddev:3.38 (5.6%) stddev-sans-first:3.42 17:58:58 INFO - PID 1659 | Values: 57.0 60.0 60.0 66.0 65.0 60.0 55.0 60.0 58.0 58.0 17:58:58 INFO - PID 1659 | 17:58:58 INFO - PID 1659 | [#10] stanford-crypto-aes Cycles:10 Average:89.80 Median:85.50 stddev:8.85 (10.4%) stddev-sans-first:8.74 17:58:58 INFO - PID 1659 | Values: 99.0 108.0 99.0 85.0 83.0 86.0 85.0 82.0 85.0 86.0 17:58:58 INFO - PID 1659 | 17:58:58 INFO - PID 1659 | [#11] stanford-crypto-ccm Cycles:10 Average:151.10 Median:155.50 stddev:16.13 (10.4%) stddev-sans-first:17.10 17:58:58 INFO - PID 1659 | Values: 150.0 159.0 154.0 160.0 157.0 138.0 141.0 135.0 186.0 131.0 17:58:58 INFO - PID 1659 | 17:58:58 INFO - PID 1659 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:219.00 Median:240.00 stddev:31.44 (13.1%) stddev-sans-first:31.91 17:58:58 INFO - PID 1659 | Values: 245.0 246.0 187.0 249.0 167.0 239.0 192.0 236.0 241.0 188.0 17:58:58 INFO - PID 1659 | 17:58:58 INFO - PID 1659 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:73.10 Median:70.50 stddev:8.36 (11.9%) stddev-sans-first:8.79 17:58:58 INFO - PID 1659 | Values: 70.0 70.0 66.0 71.0 70.0 71.0 70.0 67.0 83.0 93.0 17:58:58 INFO - PID 1659 | -------- Summary: end -------- 17:58:58 INFO - PID 1659 | 17:58:59 INFO - TEST-INFO | 1659: exit 0 17:58:59 INFO - TEST-OK | kraken | took 60224ms 17:58:59 INFO - SUITE-END | took 1173s 17:58:59 INFO - Completed test suite (00:19:33) 17:58:59 INFO - javascript benchmark 17:58:59 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7864.388048597262, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3330.2173989112553, 3440.8279586020694, 3591.9640803591965, 3631.4371272452763, 3635.527381440413, 5595.4124816894255, 5617.634853734505, 6114.3886108817405, 6221.284552276489, 6275.623462592236, 4846.806127754894, 6221.0979407985815, 6230.096635987779, 6234.7506099756065, 6278.68606569672, 5079.7714102865275, 5470.972645136769, 5516.806911758089, 5517.779288828462, 5637.830865074034, 4875.878103047435, 4910.337104490902, 5550.639208451437, 5627.0, 5633.085005920866, 5381.650192737459, 5542.501174894239, 5563.443655634444, 5614.719264036782, 5629.549636029108, 5400.405955344909, 5485.506304432581, 5488.643238189505, 5505.752241149158, 5534.833954981357, 5452.2094296327205, 5483.890322193533, 5487.725613719298, 5507.394186639466, 5517.062099443105, 5137.948620513785, 5691.943080569225, 6196.2874269459135, 6265.968670156665, 6279.0895320178315, 5534.640248383883, 5824.679642619654, 6485.0, 6542.607443553402, 6751.088603038576, 6407.551471397004, 6867.519273650846, 6871.587704737732, 6893.7931862044215, 6908.274631163706, 6386.904196437057, 6391.520635952322, 6405.871882562323, 6431.903521447182, 6432.131662225634, 6745.696443660047, 6831.760888368884, 6922.619255940972, 6990.930090699129, 7013.684384202723, 6676.699548520328, 6693.665316734241, 6715.832104197337, 6740.764073257413, 6759.5606285591275, 6799.456043516507, 6902.344277293649, 6903.516753827284, 6910.7581234656545, 6931.965340173368, 6473.255575608771, 6547.803565893031, 6577.638229897306, 6709.094272273278, 6722.092517510171, 6758.290379510179, 6781.932180678229, 6783.491238157158, 6792.72829086831, 6807.591544507345, 5403.000444917703, 5415.945840541623, 5426.131818908955, 5430.185472179126, 5455.399906010336, 4761.023990082025, 4798.304245884327, 4799.856004319876, 4859.635527335393, 4863.878403039882, 5319.505018259564, 6366.108744775735, 6372.7132279047555, 6389.936100639027, 6413.134226879405, 5922.7927022554, 6675.998600210008, 6894.8621027580175, 6897.655117244118, 6908.930910690829, 5194.532492075731, 6206.224221972254, 6249.281332646804, 6332.081848131995, 6364.968175159187, 5878.177055212273, 5935.73289201987, 6047.365026672136, 6064.332923378425, 6126.264848218153, 7069.469789765788, 7103.82240443993, 7110.4667149965, 7118.679659415235, 7126.786196414116, 5464.972675136599, 6355.809325720235, 6368.267649220399, 6923.9653801730665, 6964.721411143498, 6811.591304521745, 6893.655317234218, 6994.055802466602, 7042.267558328466, 7096.396806271318, 5637.88724225505, 6696.497762667722, 6706.429953454011, 6744.763933262312, 6758.797236082925, 6680.0, 6827.283135270725, 6833.487488438288, 6899.586024838526, 6907.620080895599, 5277.630565860506, 6104.511639068775, 6665.133532640726, 6690.765823196165, 6706.195256569243, 5809.302883653989, 6512.05575191604, 6523.11937888379, 6554.213494380514, 6620.470362370903, 6468.935310647022, 6500.154979852589, 6532.314107018695, 6576.901346479903, 6577.671116444063, 6378.52161087911, 6573.309802470863, 6583.967080164569, 6604.669766511559, 6742.831429214309, 6524.249711283073, 6545.443637290884, 6555.180602424697, 6559.8032059038305, 6568.474522038321], "value": 6110.583159097521, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [738.2838646512882, 784.6351446577343, 793.3177467378057, 1121.7828655908338, 1242.4595301044053, 38573.228535429276, 39310.803445982805, 39331.410028849554, 39367.40948885762, 39368.409473857915, 39359.606403935955, 39520.01199970002, 39695.40456893149, 39724.20551588966, 39783.0, 7882.1329653738185, 8170.0, 8386.287165590902, 8392.664293428255, 8394.748157555252, 8477.364197685167, 8534.61594228258, 8582.613782379776, 8583.313334933222, 8594.140585941419, 8530.914690853075, 8545.74362769118, 8607.440516366447, 8607.698730544433, 8615.095414981399, 8535.57322133894, 8557.700480483216, 8563.186497282779, 8598.570071496431, 8607.913920860836, 8380.497170169809, 8431.367647426407, 8434.367422443342, 8434.78913027173, 8449.36629752771, 33219.50170747416, 34517.13707157316, 37176.62823371759, 37397.0, 37425.0, 3571.607123216444, 3572.142685755426, 3605.891823245307, 3605.94591081134, 3609.476625889258, 30519.389612207633, 30586.929457468883, 30646.54030189549, 30752.0, 30810.383792324254, 2929.1066224801425, 2944.911652650424, 2970.1089673097986, 2974.8363839988792, 2974.985125074361, 245776.0, 250458.49541504533, 252047.47952520606, 287825.0, 289180.0, 47663.80840479016, 47995.80010499765, 48068.0, 48093.27860082102, 48129.5187048125, 48503.75748121237, 48722.32027238465, 48748.5125148751, 48847.02305953861, 48873.0, 13976.51082212138, 14083.647908802363, 14094.436222551189, 14174.929125354307, 14197.645058873612, 10100.797984040279, 11623.302601843747, 11626.476808543635, 11651.417429128509, 11658.650240492629, 13123.737525249442, 13266.270355130368, 13304.600861974157, 13312.600621981355, 13407.262600556867, 6089.604175728652, 6124.5100391968535, 6152.600080994721, 6152.953997820381, 6159.630422174594, 843.42225575481, 994.5623925472769, 1012.3116280929039, 1047.1413440978324, 1053.986885049567, 19499.902500487406, 21251.574968500543, 23835.04659813588, 24073.879630601736, 24452.0, 994.8211369527133, 1111.6442738323658, 1121.170333952869, 1129.2546919033398, 1144.7481554058095, 11026.779464410667, 11378.601748938747, 12336.75326493465, 12411.131220814637, 12422.81365779514, 330045.0, 331501.0, 331795.0, 333354.0, 333665.0, 52136.47863521392, 52288.73855630698, 52408.737956309975, 52537.211941820904, 52660.73669631627, 1505.4279373837871, 1549.2099029495039, 1581.936722531086, 1582.1377349344634, 1585.2153184173678, 20567.8971605141, 23715.762842372045, 23781.048758049485, 23857.164999224944, 23913.402164946016, 37824.43263350997, 37853.81073094727, 37920.0, 37926.810365948, 38011.0, 278867.0, 280944.0, 281276.5936170306, 281517.5924120366, 282129.0, 33083.17292067621, 34060.14849628779, 34221.65778342185, 34694.785682500995, 34851.30297394089, 41995.74012779621, 44737.657870263945, 44740.0, 44879.775601121786, 45279.54720452753, 25520.10679626204, 29831.403371932007, 30048.399032019675, 30871.536926946563, 31332.53001204938, 30056.24859378533, 30791.0, 30899.382012360078, 30907.0, 31034.379312414076, 36536.634633653324, 36639.45040824443, 37400.0, 37585.81207094056, 37618.623813761515, 29402.558961615166, 30288.84855575708, 31012.379752405275, 31114.222144445663, 31152.37695246037, 21657.783422165576, 22840.543189136457, 23147.65278520857, 23374.0, 24500.75499244985], "value": 17369.66854852385, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [376.4503824416352, 433.26345213137677, 437.975911324877, 441.82989549023614, 460.7880375027487, 19184.424467266006, 20750.688739668898, 21170.89414552927, 21290.57418851622, 21514.892425537888, 21888.8905555472, 22032.44918877025, 22265.888670556666, 22361.664575031387, 22511.437214069658, 5786.537077033849, 6064.241969753781, 6068.180795592605, 6075.119107729377, 6079.392060793907, 4992.950070499285, 5868.090445980858, 5916.2900451945825, 5932.466078052975, 5934.228550288456, 5833.7083145842535, 5883.8234852954265, 5883.823485295448, 5887.970560147193, 5900.557458190631, 5669.773209071632, 5878.382769809173, 5889.411058894077, 5931.377205393414, 5944.583879128484, 5216.400113986902, 5272.789088436458, 5291.259223708684, 5295.179247216649, 5295.629305948585, 20961.63749356287, 24150.39624009414, 26071.348216294555, 26627.334316642045, 26719.465610687872, 2009.3168322770327, 2244.1584405847807, 2286.0741399733133, 2289.519200967799, 2290.690756747851, 12725.936370318183, 13997.790033149511, 16244.837551624569, 16642.50072497827, 17421.0, 2096.2453516733963, 2100.8214301784365, 2151.8278537716947, 2153.7630860605323, 2162.28644547299, 117549.82450175475, 146208.0, 146240.24650862347, 146607.26696366558, 147861.0, 12165.5133794649, 12327.260364377986, 12341.814872776915, 12364.134510584352, 12587.223832850108, 13371.799423008662, 16103.597410064607, 16250.51248462548, 16326.0, 16372.83627163713, 7552.8867066994035, 8521.659133634708, 8895.4662720237, 8973.775655608531, 9061.13919177677, 8304.335653147733, 8408.957955210184, 8482.363822713321, 8553.486790792447, 8572.3999320047, 7705.075390953121, 7818.726344577913, 7822.726204582926, 7857.646405911748, 7861.960690196513, 2157.3959291398432, 2174.6846707227364, 2186.9343919682433, 2189.39791557323, 2204.702365180712, 853.5049671190818, 1006.4866917871792, 1024.3341827811983, 1034.0, 1054.0513537816028, 15223.125961749853, 20067.0, 20722.689159662616, 20830.791692082887, 21004.684929726067, 1027.8406846938738, 1032.4631191780381, 1053.647028245531, 1067.2475904487317, 1069.8395240713955, 10246.948765256275, 10687.679369618923, 10694.037536621741, 10713.303635263683, 10755.462226888625, 566539.0, 567653.0, 569168.4624730633, 572706.0, 580443.0, 93297.0, 122477.0, 128158.0, 128621.0, 128713.0, 1042.9374237545771, 1214.5688280660415, 1229.8647148813802, 1234.5432190089725, 1252.4426630149496, 20842.68735968992, 21144.788552114896, 21260.468488287916, 21260.51176417636, 21263.8936805315, 31939.201519961258, 51316.486835132666, 51467.74266128645, 51481.742591286806, 51692.93228270827, 66986.99519507309, 70336.0, 70536.0, 70777.6461117691, 71020.0, 6987.895181572178, 8603.483790972561, 8791.472511649321, 8824.514651693962, 9034.509305964442, 13482.0, 13615.319234038061, 13773.242471664154, 14011.929940350234, 14065.367058482188, 11529.827052594386, 11698.0, 12044.036477081987, 12064.215825971283, 12095.0, 10052.597896084075, 11096.0, 11137.777244455228, 11313.71715707114, 11454.94272528632, 10790.622328218475, 11144.777104457704, 12220.205686630341, 12289.938550307192, 12293.877061229274, 8733.257673097603, 8858.202761751474, 8983.966843813045, 8997.82004359896, 9005.099490050943, 11693.824592631288, 11822.204544324635, 11978.76042479128, 12012.0, 12108.75782484363], "value": 10171.152513095769, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1201.8978386837118, 1481.081729327817, 1485.4206859324865, 1489.3595753825857, 1497.191516581046, 11488.310701357925, 11848.111391645618, 11869.584564540242, 12013.819792703116, 12022.819657705131, 11764.117691173153, 12016.0, 12019.0, 12020.398980050984, 12026.69933251667, 2559.4497183105627, 2615.712271650117, 2619.8559079250635, 2621.790256779453, 2623.3048242215827, 2528.279440359498, 2576.0726138590185, 2580.7290234525385, 2585.1339801166537, 2586.0560895273184, 2545.2745967399296, 2560.2831207261997, 2591.676040494938, 2595.4549544595657, 2598.0257403473697, 2541.07250853439, 2558.667373241476, 2590.883410246534, 2593.572060609997, 2597.7662010418962, 2497.887595058227, 2550.413404916861, 2581.7934565234923, 2599.284472248326, 2603.5053339865367, 11741.412929353584, 11768.470418831173, 11809.40952952349, 11812.40937953099, 11818.527258909633, 6653.866922661519, 6881.13985751781, 6912.723491060352, 6934.687939042755, 6963.512554121214, 3880.9029774255587, 4207.663386929038, 4228.556001619816, 4266.701330906838, 4330.437043184397, 1174.2543484887074, 1181.2381014245764, 1222.2483172848633, 1227.0919519555464, 1236.771197328497, 12612.873871261354, 15440.227988600638, 16098.953568018042, 16414.589635259093, 16426.83573164265, 5508.6779172998195, 5773.509251713567, 5935.228420305332, 6073.119397687399, 6260.686965651699, 13846.446142154202, 13922.651933701738, 13944.442222310994, 13958.09272397291, 13961.79057314141, 6078.665673387918, 6749.9537571676465, 6896.344847239605, 6912.619805910723, 6941.895871561931, 6133.619935514474, 6160.168377269101, 6271.592346497463, 6294.370562943761, 6310.305866354697, 4611.400517932647, 4725.220338644085, 4731.858044258678, 4785.473597904228, 4792.257200133915, 1260.9432575534122, 1466.3767898643034, 1508.2308022908396, 1512.8335883052855, 1514.1596413990342, 799.3844739550514, 803.9196080391914, 823.4770920465547, 830.1283652165203, 854.6666799948025, 4843.39457567804, 6113.510919126459, 6236.844078898064, 6285.748570057147, 6314.778982735674, 593.7239183779564, 596.1832289763053, 609.1441524657863, 616.3004989337045, 621.0932039222695, 4010.4934415990006, 4344.913101737948, 4409.162259170747, 4468.173387923244, 4556.794944227518, 14297.78553321701, 14323.641908952359, 14819.333130009176, 14853.331600078023, 14861.034032787835, 14356.353964071643, 14778.18719970412, 14888.404463821325, 14949.0, 15028.17345046033, 6775.390214880685, 8172.795680107926, 8445.788855278668, 8533.658653653783, 8553.95723021381, 6984.387018150241, 7018.473614479037, 7256.9274307258365, 7292.307230813064, 7316.780496585111, 12905.677358066123, 13200.471981120647, 13320.800187997382, 13481.46074157062, 13517.324133793074, 13229.867701323248, 13768.0, 13841.653958650712, 13852.861471385157, 13885.0, 10393.636222732168, 10774.622888198875, 10796.89203107959, 10801.837972430263, 10829.891701082888, 10641.98901104394, 10650.041496265374, 10680.732981675521, 10821.891781082404, 10849.511771970123, 9060.728178154666, 9108.772280693036, 9187.586558604746, 9256.861147082664, 9323.72028839136, 8897.28821694276, 8915.37592368528, 9130.452172869649, 9165.0, 9187.127222913812, 8143.79640508992, 8248.13394593559, 8273.46222495536, 8288.0, 8292.792680182802, 6056.182415373868, 6224.1597384354345, 6230.7507699691505, 6265.154204182377, 6284.391977329357, 6133.907991380043, 6172.8765424690355, 6186.907196392148, 6331.113644089924, 6375.266844312778], "value": 5446.332298830733, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [353.35159981434066, 381.1424295335493, 403.44526276369993, 403.68310875962374, 407.67386091127094, 5382.91925621116, 5808.941910580898, 6243.1292598588825, 6386.0, 6414.166158399407, 4558.997020655459, 5538.421549858291, 5769.788344447671, 6068.726907289171, 6395.008773640082, 3321.4021476134285, 4556.516698399364, 4586.564276393738, 4599.609033232179, 4602.4937256901885, 3768.4724138620613, 3889.630485103911, 4502.122086193193, 4511.436070491189, 4596.552086092886, 3879.7478163919395, 4097.282975479294, 4488.8877778055485, 4497.392851964992, 4528.890421846636, 4398.824047038114, 4542.523035081302, 4562.54374562546, 4566.840160594396, 4568.314752787109, 3760.7179461540495, 3875.360565506688, 3897.4738410314526, 3910.6508254652385, 3923.7253392262555, 6109.694515274218, 6115.816525504242, 6134.2332208473945, 6152.400375902253, 6175.4442100210745, 5386.676799392049, 5415.810446634388, 5418.5369950113645, 5424.105022671254, 5444.700541470217, 6528.836779080513, 6638.10385597943, 6650.334966503359, 6740.427063699592, 6748.595084294958, 3667.541557305337, 4375.221734198775, 4392.626626736731, 4396.868093957154, 4398.912021759579, 6974.476914231453, 7103.431725461951, 7130.6078165700865, 7167.283271672791, 7207.495475316678, 4396.890077748082, 4429.737524805415, 4451.198784218872, 4466.508684044752, 4501.347304640809, 4523.457185137804, 4532.9546704533195, 4553.658475614276, 4558.3162525620755, 4560.703554268962, 4585.128825523207, 5211.895762084813, 5295.179247216687, 5304.23088652151, 5438.45615438453, 4961.801527938842, 6771.814932386813, 6778.186617605918, 6794.966025169842, 6830.829229269308, 1464.9964774129755, 1535.6391248056698, 1548.807418287912, 1548.9380424782883, 1555.673308605183, 984.2519685039279, 1010.4442556593991, 1010.6008126789877, 1014.3305418423805, 1016.5869441476353, 5349.5720342372515, 5444.7822087116865, 5515.117581186991, 6322.178116844781, 6407.732056651142, 5900.262467191601, 6035.465104656055, 7016.859662806715, 7024.5434046786795, 7046.6176235406265, 5420.810271640474, 6219.626822390671, 6358.3641635836975, 6360.109584658151, 6367.936320636827, 2968.93118477348, 3072.7695422843376, 3105.1616063662686, 3531.399662057456, 3661.761985470936, 5748.971255143698, 5867.354590994987, 6507.349265073455, 7210.351068403764, 7210.819729506804, 7142.4286057115305, 7145.535540189871, 7205.927940720526, 7208.873382352219, 7218.314260145278, 6803.298750730526, 7049.436045116276, 7155.284471552803, 7231.758154971975, 7262.237465066198, 6935.479839012195, 6981.616011119438, 6984.742746305714, 7014.333638304353, 7019.543729657556, 6205.720742566505, 6365.7135428904885, 6637.2699003108655, 6864.4165245954655, 6968.547044442298, 6381.3937675920715, 6492.31830657793, 6695.263521012587, 7245.673944672397, 7303.042267881517, 4824.734639594856, 4886.656169553259, 4981.4769449207315, 5132.178851383761, 5148.716820574904, 6612.70242839083, 7041.788746337618, 7072.469564782765, 7117.0, 7153.713851445884, 5429.592780541554, 5726.599138060295, 6009.909851352146, 6454.806355809334, 6776.983452482167, 6528.934710653023, 6783.59298442095, 6846.5892046477375, 6850.191421517154, 6885.483588730765, 5285.814996475105, 6508.707108180049, 6717.1267735194115, 6751.493637977073, 6763.396092504323, 5742.138679198154, 6170.290416602148, 6211.782587609412, 6797.6941037654415, 6858.079737673459, 6145.832291864532, 6165.815025549241, 6668.83327916806, 6670.633115178712, 6729.226138993882], "value": 4822.1418567563005, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [820.2330820682666, 839.7732612194708, 853.7268074216254, 858.944168629039, 869.5131325433508, 14346.354414051366, 14904.552863414088, 15318.310676019579, 16806.0, 16819.243134058983, 12490.188137771038, 13030.739385212313, 17095.230714617843, 17161.914190429063, 17235.965842049456, 750.9774706758806, 936.7845395559026, 957.9425234485918, 968.9757756056084, 971.7424882406168, 946.7775072857872, 949.6913503111482, 950.581744032627, 951.690700522333, 951.914327710506, 978.6770365779312, 996.6611352140271, 1023.7235946294534, 1024.6874703215517, 1028.9382637041765, 995.6515219673129, 1017.6234793126516, 1019.7348689340788, 1020.5968642386252, 1020.7346090016575, 724.409606170969, 741.125471943107, 742.480263815326, 750.6134340814485, 750.8873668949701, 15851.524454266391, 16262.0, 16336.591585210346, 16994.49016529506, 17354.0, 17067.0, 17067.65864682712, 17230.138493075297, 17261.913690431593, 17654.0, 13152.40814163365, 14162.575122746333, 14216.928915355356, 14474.059186152972, 14657.04729192599, 3607.549056367954, 3629.963700362989, 3637.4543818427446, 3642.0530662028063, 3686.318031164243, 120282.0, 138584.0, 138854.0, 142431.28784356013, 142815.0, 13449.117123602704, 13767.655808604664, 13773.724525509433, 13779.448822047207, 14136.08115472491, 25655.10207142741, 25887.0, 26111.08611198599, 26231.868840655676, 26285.868570657407, 7807.297343239135, 7938.801529961682, 8079.0, 8087.555184464911, 8091.231333023354, 3932.6853851691794, 3966.781826999543, 3982.880513584597, 3996.820143093568, 4009.676806653797, 4199.454070970754, 4277.144571085733, 4304.569543045671, 4313.84901528445, 4317.395564620955, 929.1173385284008, 941.5104145844123, 957.2820384711466, 960.5389413081759, 961.077365728894, 573.4466240078344, 638.6683764351379, 645.5094128462308, 668.1547841979899, 669.9196096468455, 10171.694849154537, 10903.236773425784, 11022.50398732059, 11972.0, 12708.745825083632, 642.7493277621732, 648.5881465269588, 661.4741280681774, 661.6592454885738, 666.5334266013713, 5023.296738456619, 5155.304033955368, 5565.332160140728, 5585.329760428694, 5625.043742563774, 101522.0, 102176.77078178745, 105933.47033264785, 107175.92824071816, 113296.0, 93336.53331733299, 96052.51973740087, 96940.0, 98181.0, 99410.50884236742, 82968.0, 83021.0, 84289.57855210685, 84407.0, 85435.0, 9419.670311539337, 11444.656660300205, 11804.822927656263, 12635.1787133836, 12861.871381286068, 29645.0, 31661.15049645543, 39560.802195989985, 40311.0, 40735.0, 59126.0, 61660.76678466495, 71240.28759712544, 76262.23737762551, 77176.0, 12924.547640832901, 14072.788908166181, 15959.601009974844, 16328.673426531166, 16514.917425412797, 17716.64566708633, 18006.90996545009, 18161.81838181654, 18212.908935455238, 18391.81608183901, 16197.271122799739, 16356.591085222964, 16560.585985349982, 16790.412335568195, 16845.83154168492, 16158.111303878399, 16208.432704855273, 16492.505224843273, 16617.916910415854, 16768.496945091665, 13242.271675057964, 15662.373505060126, 16130.67738645244, 16141.435049773201, 16202.756958645394, 11515.884841151481, 11615.128865334964, 11988.940055300016, 12162.756744864877, 12277.631671049525, 11415.771684566429, 12511.124221304786, 13359.66600834948, 13634.659133521345, 14047.297635118408], "value": 8343.953041464345, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1562.7999999999997, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 171.0, 114.0, 114.0, 113.0, 111.0, 113.0, 115.0, 115.0, 115.0], "value": 119.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [137.0, 111.0, 116.0, 154.0, 128.0, 149.0, 146.0, 112.0, 160.0, 149.0], "value": 136.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [286.0, 255.0, 198.0, 204.0, 210.0, 208.0, 197.0, 200.0, 198.0, 199.0], "value": 215.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 57.0, 59.0, 87.0, 64.0, 72.0, 81.0, 60.0, 76.0, 59.0], "value": 69.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 72.0, 83.0, 71.0, 69.0, 74.0, 88.0, 70.0, 56.0, 94.0], "value": 75.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 90.0, 85.0, 91.0, 91.0, 89.0, 89.0, 90.0, 89.0, 89.0], "value": 89.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 106.0, 95.0, 107.0, 156.0, 96.0, 107.0, 158.0, 98.0, 97.0], "value": 111.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [100.0, 94.0, 90.0, 95.0, 95.0, 91.0, 89.0, 93.0, 91.0, 90.0], "value": 92.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [52.0, 49.0, 78.0, 49.0, 52.0, 46.0, 46.0, 73.0, 77.0, 77.0], "value": 59.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 60.0, 60.0, 66.0, 65.0, 60.0, 55.0, 60.0, 58.0, 58.0], "value": 59.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [99.0, 108.0, 99.0, 85.0, 83.0, 86.0, 85.0, 82.0, 85.0, 86.0], "value": 89.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 159.0, 154.0, 160.0, 157.0, 138.0, 141.0, 135.0, 186.0, 131.0], "value": 151.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [245.0, 246.0, 187.0, 249.0, 167.0, 239.0, 192.0, 236.0, 241.0, 188.0], "value": 219.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 70.0, 66.0, 71.0, 70.0, 71.0, 70.0, 67.0, 83.0, 93.0], "value": 73.1, "unit": "score"}]}]} 17:58:59 INFO - Return code: 0 17:59:00 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 17:59:00 INFO - # TBPL SUCCESS # 17:59:00 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 17:59:00 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 17:59:00 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 17:59:00 INFO - 2017-05-18 17:59:00.042 screenresolution[1666:11093] starting screenresolution argv=screenresolution get 17:59:00 INFO - 2017-05-18 17:59:00.044 screenresolution[1666:11093] Display 0: 1600x1200x32@60 17:59:00 INFO - 2017-05-18 17:59:00.050 screenresolution[1667:11096] starting screenresolution argv=screenresolution list 17:59:00 INFO - Available Modes on Display 0 17:59:00 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 17:59:00 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 17:59:00 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 17:59:00 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 17:59:00 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 17:59:00 INFO - Intel Iris: 17:59:00 INFO - Chipset Model: Intel Iris 17:59:00 INFO - Type: GPU 17:59:00 INFO - Bus: Built-In 17:59:00 INFO - VRAM (Dynamic, Max): 1536 MB 17:59:00 INFO - Vendor: Intel (0x8086) 17:59:00 INFO - Device ID: 0x0a2e 17:59:00 INFO - Revision ID: 0x0009 17:59:00 INFO - Displays: 17:59:00 INFO - Display: 17:59:00 INFO - Resolution: 1600 x 1200 @ 60 Hz 17:59:00 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 17:59:00 INFO - Display Serial Number: 1600x1200 60 17:59:00 INFO - Main Display: Yes 17:59:00 INFO - Mirror: Off 17:59:00 INFO - Online: Yes 17:59:00 INFO - Rotation: Supported 17:59:00 INFO - Return code: 0 17:59:00 INFO - Running post-action listener: _resource_record_post_action 17:59:00 INFO - [mozharness: 2017-05-19 00:59:00.286769Z] Finished run-tests step (success) 17:59:00 INFO - Running post-run listener: _resource_record_post_run 17:59:00 INFO - Total resource usage - Wall time: 1197s; CPU: 54.0%; Read bytes: 38173696; Write bytes: 575915008; Read time: 388; Write time: 1969 17:59:00 INFO - TinderboxPrint: CPU usage
54.1% 17:59:00 INFO - TinderboxPrint: I/O read bytes / time
38,173,696 / 388 17:59:00 INFO - TinderboxPrint: I/O write bytes / time
575,915,008 / 1,969 17:59:00 INFO - TinderboxPrint: CPU idle
2,198.8 (45.9%) 17:59:00 INFO - TinderboxPrint: CPU system
963.9 (20.1%) 17:59:00 INFO - TinderboxPrint: CPU user
1,626.9 (34.0%) 17:59:00 INFO - TinderboxPrint: Swap in / out
297,926,656 / 0 17:59:00 INFO - install - Wall time: 23s; CPU: 54.0%; Read bytes: 251560448; Write bytes: 245340160; Read time: 18958; Write time: 482 17:59:00 INFO - run-tests - Wall time: 1174s; CPU: 54.0%; Read bytes: 36178944; Write bytes: 312974336; Read time: 362; Write time: 1447 17:59:00 INFO - Running post-run listener: _upload_blobber_files 17:59:00 INFO - Blob upload gear active. 17:59:00 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 17:59:00 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 17:59:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 17:59:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 17:59:01 INFO - (blobuploader) - INFO - Open directory for files ... 17:59:01 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 17:59:01 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:59:01 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:59:01 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 17:59:01 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:59:01 INFO - (blobuploader) - INFO - Done attempting. 17:59:01 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 17:59:01 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:59:01 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:59:02 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 17:59:02 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:59:02 INFO - (blobuploader) - INFO - Done attempting. 17:59:02 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 17:59:02 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:59:02 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:59:03 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 17:59:03 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:59:03 INFO - (blobuploader) - INFO - Done attempting. 17:59:03 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 17:59:04 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:59:04 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:59:06 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 17:59:06 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:59:06 INFO - (blobuploader) - INFO - Done attempting. 17:59:06 INFO - (blobuploader) - INFO - Iteration through files over. 17:59:06 INFO - Return code: 0 17:59:06 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 17:59:06 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 17:59:06 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/28bbf3317d7795c2a294c37dd2462dde044603e9f50c2b42cc758548bea2b5c0dfa0f1111554c0e6138a778286e29f01450cc884a4a996c2879bc28e3ece2551", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/077f36178c3a078b3ff15de5a4a10b6dd5c10ce9b13f24d62300c8ce563754ff57e46a7f595e20425e643e9100350017e041e0c6c442da40b9a495ed214658a3", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/825dfb896465cb2d089c7bbf8189517e52bfa6590c267c3b99e0d39e00efa19e934da101d1ec929377b3cc8ca55d5d9dd05095142c5eefcd80524260ad9d5e63", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d71965673be60d26dbef6a4115142fd63fcf64634ceb7db8b077eb9f5298ca760934d34f2f3c3cea21d8fae2128e384432edb3504659cafe540c966b4ab67679"} 17:59:06 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 17:59:06 INFO - Writing to file /builds/slave/test/properties/blobber_files 17:59:06 INFO - Contents: 17:59:06 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/28bbf3317d7795c2a294c37dd2462dde044603e9f50c2b42cc758548bea2b5c0dfa0f1111554c0e6138a778286e29f01450cc884a4a996c2879bc28e3ece2551", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/077f36178c3a078b3ff15de5a4a10b6dd5c10ce9b13f24d62300c8ce563754ff57e46a7f595e20425e643e9100350017e041e0c6c442da40b9a495ed214658a3", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/825dfb896465cb2d089c7bbf8189517e52bfa6590c267c3b99e0d39e00efa19e934da101d1ec929377b3cc8ca55d5d9dd05095142c5eefcd80524260ad9d5e63", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d71965673be60d26dbef6a4115142fd63fcf64634ceb7db8b077eb9f5298ca760934d34f2f3c3cea21d8fae2128e384432edb3504659cafe540c966b4ab67679"} 17:59:06 INFO - Running post-run listener: copy_logs_to_upload_dir 17:59:06 INFO - Copying logs to upload dir... 17:59:06 INFO - mkdir: /builds/slave/test/build/upload/logs 17:59:06 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1277.143628 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 17 secs) (at 2017-05-18 17:59:06.310555) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-18 17:59:06.314365) ========= 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.gJZZz7AtDy/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.FWk9oAC2TD/Listeners TMPDIR=/var/folders/fp/_7br9k0s4j30pf06c5ky859c00000w/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/28bbf3317d7795c2a294c37dd2462dde044603e9f50c2b42cc758548bea2b5c0dfa0f1111554c0e6138a778286e29f01450cc884a4a996c2879bc28e3ece2551", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/077f36178c3a078b3ff15de5a4a10b6dd5c10ce9b13f24d62300c8ce563754ff57e46a7f595e20425e643e9100350017e041e0c6c442da40b9a495ed214658a3", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/825dfb896465cb2d089c7bbf8189517e52bfa6590c267c3b99e0d39e00efa19e934da101d1ec929377b3cc8ca55d5d9dd05095142c5eefcd80524260ad9d5e63", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d71965673be60d26dbef6a4115142fd63fcf64634ceb7db8b077eb9f5298ca760934d34f2f3c3cea21d8fae2128e384432edb3504659cafe540c966b4ab67679"} build_url:https://queue.taskcluster.net/v1/task/MeRoayxHQWuuEagbAeMxAA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011112 build_url: 'https://queue.taskcluster.net/v1/task/MeRoayxHQWuuEagbAeMxAA/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/28bbf3317d7795c2a294c37dd2462dde044603e9f50c2b42cc758548bea2b5c0dfa0f1111554c0e6138a778286e29f01450cc884a4a996c2879bc28e3ece2551", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/077f36178c3a078b3ff15de5a4a10b6dd5c10ce9b13f24d62300c8ce563754ff57e46a7f595e20425e643e9100350017e041e0c6c442da40b9a495ed214658a3", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/825dfb896465cb2d089c7bbf8189517e52bfa6590c267c3b99e0d39e00efa19e934da101d1ec929377b3cc8ca55d5d9dd05095142c5eefcd80524260ad9d5e63", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d71965673be60d26dbef6a4115142fd63fcf64634ceb7db8b077eb9f5298ca760934d34f2f3c3cea21d8fae2128e384432edb3504659cafe540c966b4ab67679"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-18 17:59:06.352383) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-18 17:59:06.352752) ========= 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.gJZZz7AtDy/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.FWk9oAC2TD/Listeners TMPDIR=/var/folders/fp/_7br9k0s4j30pf06c5ky859c00000w/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.005093 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-18 17:59:06.401508) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-18 17:59:06.401845) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-18 17:59:06.402191) ========= ========= Total master_lag: 0.18 =========