builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0365 starttime: 1494598677.07 results: success (0) revision: 1178b701781de2b1a5afb7b7d6b4954a3a7a51ba ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-05-12 07:17:57.067870) ========= master: http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-05-12 07:17:57.068314) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-12 07:17:57.068590) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-12 07:17:57.094280) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-12 07:17:57.094618) ========= 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.ICmWenTBaA/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.07pon4D15Z/Listeners TMPDIR=/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-05-12 07:17:57-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 151M=0s 2017-05-12 07:17:57 (151 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.159223 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-12 07:17:57.276636) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-12 07:17:57.276965) ========= 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.ICmWenTBaA/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.07pon4D15Z/Listeners TMPDIR=/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False program finished with exit code 0 elapsedTime=0.026950 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-12 07:17:57.324769) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-12 07:17:57.325099) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 1178b701781de2b1a5afb7b7d6b4954a3a7a51ba --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 1178b701781de2b1a5afb7b7d6b4954a3a7a51ba --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.ICmWenTBaA/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.07pon4D15Z/Listeners TMPDIR=/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-05-12 07:17:57,431 truncating revision to first 12 chars 2017-05-12 07:17:57,431 Setting DEBUG logging. 2017-05-12 07:17:57,431 attempt 1/10 2017-05-12 07:17:57,431 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/1178b701781d?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-05-12 07:17:58,932 unpacking tar archive at: mozilla-central-1178b701781d/testing/mozharness/ program finished with exit code 0 elapsedTime=1.779326 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-12 07:17:59.126284) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-12 07:17:59.126612) ========= script_repo_revision: 1178b701781de2b1a5afb7b7d6b4954a3a7a51ba ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-12 07:17:59.126999) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-12 07:17:59.127266) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-12 07:17:59.146379) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2017-05-12 07:17:59.146692) ========= /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.ICmWenTBaA/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.07pon4D15Z/Listeners TMPDIR=/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 07:17:59 INFO - MultiFileLogger online at 20170512 07:17:59 in /builds/slave/test 07:17:59 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 07:17:59 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 07:17:59 INFO - {'append_to_log': False, 07:17:59 INFO - 'base_work_dir': '/builds/slave/test', 07:17:59 INFO - 'blob_upload_branch': 'Firefox', 07:17:59 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 07:17:59 INFO - 'branch': 'Firefox', 07:17:59 INFO - 'buildbot_json_path': 'buildprops.json', 07:17:59 INFO - 'config_files': ('talos/mac_config.py',), 07:17:59 INFO - 'default_actions': ('clobber', 07:17:59 INFO - 'read-buildbot-config', 07:17:59 INFO - 'download-and-extract', 07:17:59 INFO - 'populate-webroot', 07:17:59 INFO - 'create-virtualenv', 07:17:59 INFO - 'install', 07:17:59 INFO - 'run-tests'), 07:17:59 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 07:17:59 INFO - 'download_minidump_stackwalk': True, 07:17:59 INFO - 'download_symbols': 'ondemand', 07:17:59 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 07:17:59 INFO - 'tooltool.py': '/tools/tooltool.py', 07:17:59 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 07:17:59 INFO - '/tools/misc-python/virtualenv.py')}, 07:17:59 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 07:17:59 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 07:17:59 INFO - 'gecko_profile': False, 07:17:59 INFO - 'gecko_profile_interval': 0, 07:17:59 INFO - 'installer_path': 'installer.exe', 07:17:59 INFO - 'log_level': 'info', 07:17:59 INFO - 'log_name': 'talos', 07:17:59 INFO - 'log_to_console': True, 07:17:59 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 07:17:59 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 07:17:59 INFO - 'opt_config_files': (), 07:17:59 INFO - 'pip_index': False, 07:17:59 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 07:17:59 INFO - 'cmd': ('bash', 07:17:59 INFO - '-c', 07:17:59 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 07:17:59 INFO - 'enabled': True, 07:17:59 INFO - 'halt_on_failure': False, 07:17:59 INFO - 'name': 'check_screen_resolution'},), 07:17:59 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 07:17:59 INFO - 'cmd': ('bash', 07:17:59 INFO - '-c', 07:17:59 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 07:17:59 INFO - 'enabled': True, 07:17:59 INFO - 'halt_on_failure': False, 07:17:59 INFO - 'name': 'check_screen_resolution'},), 07:17:59 INFO - 'run_cmd_checks_enabled': True, 07:17:59 INFO - 'suite': 'dromaeojs-e10s', 07:17:59 INFO - 'system_bits': '32', 07:17:59 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 07:17:59 INFO - 'title': 't-yosemite-r7-0365', 07:17:59 INFO - 'tooltool_cache': '/builds/tooltool_cache', 07:17:59 INFO - 'use_talos_json': True, 07:17:59 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 07:17:59 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 07:17:59 INFO - 'work_dir': 'build'} 07:17:59 INFO - [mozharness: 2017-05-12 14:17:59.358284Z] Running clobber step. 07:17:59 INFO - Running pre-action listener: _resource_record_pre_action 07:17:59 INFO - Running main action method: clobber 07:17:59 INFO - rmtree: /builds/slave/test/build 07:17:59 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 07:18:01 INFO - Running post-action listener: _resource_record_post_action 07:18:01 INFO - [mozharness: 2017-05-12 14:18:01.408938Z] Finished clobber step (success) 07:18:01 INFO - [mozharness: 2017-05-12 14:18:01.409094Z] Running read-buildbot-config step. 07:18:01 INFO - Running pre-action listener: _resource_record_pre_action 07:18:01 INFO - Running main action method: read_buildbot_config 07:18:01 INFO - Using buildbot properties: 07:18:01 INFO - { 07:18:01 INFO - "project": "", 07:18:01 INFO - "product": "firefox", 07:18:01 INFO - "who": "florian@queze.net", 07:18:01 INFO - "installer_path": "public/build/firefox-55.0a1.en-US.mac.dmg", 07:18:01 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 07:18:01 INFO - "repository": "https://hg.mozilla.org/mozilla-central/", 07:18:01 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 07:18:01 INFO - "stage_platform": "macosx64", 07:18:01 INFO - "basedir": "/builds/slave/test", 07:18:01 INFO - "buildnumber": 24, 07:18:01 INFO - "platform": "yosemite_r7", 07:18:01 INFO - "parent_task_id": "CLbjXho1QNOocJrkaAt-dg", 07:18:01 INFO - "master": "http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/", 07:18:01 INFO - "slavebuilddir": "test", 07:18:01 INFO - "taskId": "H5iCFzHBRkKAznyQyurt6w", 07:18:01 INFO - "branch": "mozilla-central", 07:18:01 INFO - "script_repo_revision": "production", 07:18:01 INFO - "revision": "1178b701781de2b1a5afb7b7d6b4954a3a7a51ba", 07:18:01 INFO - "slavename": "t-yosemite-r7-0365", 07:18:01 INFO - "repo_path": "mozilla-central" 07:18:01 INFO - } 07:18:01 INFO - Finding installer, test and symbols from parent task. 07:18:01 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/H5iCFzHBRkKAznyQyurt6w'}, attempt #1 07:18:02 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/H5iCFzHBRkKAznyQyurt6w'}, attempt #1 07:18:02 INFO - Task dependencies: NUkYc40eTXiHIucqisOEfA 07:18:02 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/CLbjXho1QNOocJrkaAt-dg'}, attempt #1 07:18:03 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/CLbjXho1QNOocJrkaAt-dg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 07:18:03 INFO - Set test_url: https://queue.taskcluster.net/v1/task/CLbjXho1QNOocJrkaAt-dg/artifacts/public/build/test_packages.json 07:18:03 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/CLbjXho1QNOocJrkaAt-dg/artifacts/public/build/target.crashreporter-symbols.zip 07:18:03 INFO - Running post-action listener: _resource_record_post_action 07:18:03 INFO - [mozharness: 2017-05-12 14:18:03.098148Z] Finished read-buildbot-config step (success) 07:18:03 INFO - [mozharness: 2017-05-12 14:18:03.098291Z] Running download-and-extract step. 07:18:03 INFO - Running pre-action listener: _resource_record_pre_action 07:18:03 INFO - Running main action method: download_and_extract 07:18:03 INFO - mkdir: /builds/slave/test/build/tests 07:18:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:18:03 INFO - trying https://queue.taskcluster.net/v1/task/CLbjXho1QNOocJrkaAt-dg/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 07:18:03 INFO - Downloading https://queue.taskcluster.net/v1/task/CLbjXho1QNOocJrkaAt-dg/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 07:18:03 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/CLbjXho1QNOocJrkaAt-dg/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json'}, attempt #1 07:18:03 INFO - Downloaded 1325 bytes. 07:18:03 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 07:18:03 INFO - Using the following test package requirements: 07:18:03 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 07:18:03 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 07:18:03 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 07:18:03 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 07:18:03 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 07:18:03 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 07:18:03 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 07:18:03 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 07:18:03 INFO - u'jsshell-mac.zip'], 07:18:03 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 07:18:03 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 07:18:03 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 07:18:03 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 07:18:03 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 07:18:03 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 07:18:03 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 07:18:03 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 07:18:03 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 07:18:03 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 07:18:03 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 07:18:03 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 07:18:03 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/CLbjXho1QNOocJrkaAt-dg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 07:18:03 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/CLbjXho1QNOocJrkaAt-dg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 07:18:03 INFO - Fetch https://queue.taskcluster.net/v1/task/CLbjXho1QNOocJrkaAt-dg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 07:18:05 INFO - Content-Length response header: 56563698 07:18:05 INFO - Bytes received: 56563698 07:18:22 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip', u'firefox-55.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 07:18:22 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/CLbjXho1QNOocJrkaAt-dg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 07:18:22 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/CLbjXho1QNOocJrkaAt-dg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 07:18:22 INFO - Fetch https://queue.taskcluster.net/v1/task/CLbjXho1QNOocJrkaAt-dg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 07:18:24 INFO - Content-Length response header: 56563698 07:18:24 INFO - Bytes received: 56563698 07:18:41 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/CLbjXho1QNOocJrkaAt-dg/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 07:18:41 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/CLbjXho1QNOocJrkaAt-dg/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 07:18:41 INFO - Fetch https://queue.taskcluster.net/v1/task/CLbjXho1QNOocJrkaAt-dg/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 07:18:42 INFO - Content-Length response header: 14409411 07:18:42 INFO - Bytes received: 14409411 07:18:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:18:42 INFO - trying https://queue.taskcluster.net/v1/task/CLbjXho1QNOocJrkaAt-dg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 07:18:42 INFO - Downloading https://queue.taskcluster.net/v1/task/CLbjXho1QNOocJrkaAt-dg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 07:18:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/CLbjXho1QNOocJrkaAt-dg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg'}, attempt #1 07:18:45 INFO - Downloaded 75906733 bytes. 07:18:45 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/CLbjXho1QNOocJrkaAt-dg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 07:18:45 INFO - mkdir: /builds/slave/test/properties 07:18:45 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 07:18:45 INFO - Writing to file /builds/slave/test/properties/build_url 07:18:45 INFO - Contents: 07:18:45 INFO - build_url:https://queue.taskcluster.net/v1/task/CLbjXho1QNOocJrkaAt-dg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 07:18:45 INFO - Running post-action listener: _resource_record_post_action 07:18:45 INFO - Running post-action listener: set_extra_try_arguments 07:18:45 INFO - [mozharness: 2017-05-12 14:18:45.051025Z] Finished download-and-extract step (success) 07:18:45 INFO - [mozharness: 2017-05-12 14:18:45.051161Z] Running populate-webroot step. 07:18:45 INFO - Running pre-action listener: _resource_record_pre_action 07:18:45 INFO - Running main action method: populate_webroot 07:18:45 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 07:18:45 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 07:18:45 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 07:18:45 INFO - '--tppagecycles', 07:18:45 INFO - '7'], 07:18:45 INFO - 'tests': ['tsvgm']}}, 07:18:45 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 07:18:45 INFO - 'tests': ['tresize', 'tcanvasmark']}, 07:18:45 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 07:18:45 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 07:18:45 INFO - 'tests': ['dromaeo_css', 'kraken']}, 07:18:45 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 07:18:45 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 07:18:45 INFO - 'talos_options': ['--disable-e10s'], 07:18:45 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:18:45 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 07:18:45 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:18:45 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 07:18:45 INFO - 'talos_options': ['--disable-e10s'], 07:18:45 INFO - 'tests': ['damp', 'tps']}, 07:18:45 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 07:18:45 INFO - 'tests': ['damp', 'tps']}, 07:18:45 INFO - 'g3': {'talos_options': ['--disable-e10s'], 07:18:45 INFO - 'tests': ['dromaeo_dom']}, 07:18:45 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 07:18:45 INFO - 'g4': {'talos_options': ['--disable-e10s'], 07:18:45 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 07:18:45 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 07:18:45 INFO - 'other': {'talos_options': ['--disable-e10s'], 07:18:45 INFO - 'tests': ['a11yr', 07:18:45 INFO - 'ts_paint', 07:18:45 INFO - 'tpaint', 07:18:45 INFO - 'sessionrestore', 07:18:45 INFO - 'sessionrestore_no_auto_restore', 07:18:45 INFO - 'tabpaint']}, 07:18:45 INFO - 'other-e10s': {'tests': ['a11yr', 07:18:45 INFO - 'ts_paint', 07:18:45 INFO - 'tpaint', 07:18:45 INFO - 'sessionrestore', 07:18:45 INFO - 'sessionrestore_no_auto_restore', 07:18:45 INFO - 'tabpaint']}, 07:18:45 INFO - 'other-e10s_l64': {'tests': ['a11yr', 07:18:45 INFO - 'ts_paint', 07:18:45 INFO - 'tpaint', 07:18:45 INFO - 'sessionrestore', 07:18:45 INFO - 'sessionrestore_no_auto_restore', 07:18:45 INFO - 'tabpaint']}, 07:18:45 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 07:18:45 INFO - 'ts_paint', 07:18:45 INFO - 'tpaint', 07:18:45 INFO - 'sessionrestore', 07:18:45 INFO - 'sessionrestore_no_auto_restore', 07:18:45 INFO - 'tabpaint']}, 07:18:45 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 07:18:45 INFO - 'tests': ['a11yr', 07:18:45 INFO - 'ts_paint', 07:18:45 INFO - 'tpaint', 07:18:45 INFO - 'sessionrestore', 07:18:45 INFO - 'sessionrestore_no_auto_restore', 07:18:45 INFO - 'tabpaint']}, 07:18:45 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 07:18:45 INFO - 'tests': ['a11yr', 07:18:45 INFO - 'ts_paint', 07:18:45 INFO - 'tpaint', 07:18:45 INFO - 'sessionrestore', 07:18:45 INFO - 'sessionrestore_no_auto_restore', 07:18:45 INFO - 'tabpaint']}, 07:18:45 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 07:18:45 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 07:18:45 INFO - 'bloom_basic_ref']}, 07:18:45 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 07:18:45 INFO - 'tests': ['tsvgx', 07:18:45 INFO - 'tsvgr_opacity', 07:18:45 INFO - 'tart', 07:18:45 INFO - 'tscrollx', 07:18:45 INFO - 'cart', 07:18:45 INFO - 'tsvg_static']}, 07:18:45 INFO - 'svgr-e10s': {'tests': ['tsvgx', 07:18:45 INFO - 'tsvgr_opacity', 07:18:45 INFO - 'tart', 07:18:45 INFO - 'tscrollx', 07:18:45 INFO - 'cart', 07:18:45 INFO - 'tsvg_static']}, 07:18:45 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 07:18:45 INFO - 'talos_options': ['--disable-e10s'], 07:18:45 INFO - 'tests': ['tp5o']}, 07:18:45 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 07:18:45 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 07:18:45 INFO - 'talos_options': ['--disable-e10s', 07:18:45 INFO - '--xperf_path', 07:18:45 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:18:45 INFO - 'tests': ['tp5n']}, 07:18:45 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 07:18:45 INFO - 'talos_options': ['--xperf_path', 07:18:45 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:18:45 INFO - 'tests': ['tp5n']}}, 07:18:45 INFO - 'talos.zip': {'path': '', 07:18:45 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 07:18:45 INFO - Running post-action listener: _resource_record_post_action 07:18:45 INFO - [mozharness: 2017-05-12 14:18:45.071705Z] Finished populate-webroot step (success) 07:18:45 INFO - [mozharness: 2017-05-12 14:18:45.071832Z] Running create-virtualenv step. 07:18:45 INFO - Running pre-action listener: _resource_record_pre_action 07:18:45 INFO - Running main action method: create_virtualenv 07:18:45 INFO - Creating virtualenv /builds/slave/test/build/venv 07:18:45 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 07:18:45 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 07:18:45 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 07:18:45 INFO - Using real prefix '/tools/python27' 07:18:45 INFO - New python executable in /builds/slave/test/build/venv/bin/python 07:18:45 INFO - Installing distribute.............................................................................................................................................................................................done. 07:18:48 INFO - Installing pip.................done. 07:18:48 INFO - Return code: 0 07:18:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 07:18:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 07:18:49 INFO - Reading from file tmpfile_stdout 07:18:49 INFO - Output received: 07:18:49 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 07:18:49 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 07:18:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:18:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:18:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:18:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:18: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x100e8eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100e91768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8e9b5a6a40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100b9fd30>, '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 0x100b52db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100b93be8>, '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.ICmWenTBaA/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.07pon4D15Z/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 07:18: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', 'pip>=1.5'] in /builds/slave/test/build 07:18: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 pip>=1.5 07:18:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ICmWenTBaA/Render', 07:18:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:18:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:18:49 INFO - 'HOME': '/Users/cltbld', 07:18:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:18:49 INFO - 'LOGNAME': 'cltbld', 07:18:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:18:49 INFO - 'MOZ_NO_REMOTE': '1', 07:18:49 INFO - 'NO_EM_RESTART': '1', 07:18:49 INFO - 'PAGER': '/bin/cat', 07:18:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:18:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:18:49 INFO - 'PWD': '/builds/slave/test', 07:18:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:18:49 INFO - 'SHELL': '/bin/bash', 07:18:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.07pon4D15Z/Listeners', 07:18:49 INFO - 'TMPDIR': '/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/', 07:18:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:18:49 INFO - 'USER': 'cltbld', 07:18:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:18:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:18:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:18:49 INFO - 'XPC_FLAGS': '0x0', 07:18:49 INFO - 'XPC_SERVICE_NAME': '0', 07:18:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:18:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:18:49 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 07:18:49 INFO - Cleaning up... 07:18:49 INFO - Return code: 0 07:18:49 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 07:18:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:18:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:18:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:18:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:18: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', '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 0x100e8eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100e91768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8e9b5a6a40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100b9fd30>, '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 0x100b52db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100b93be8>, '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.ICmWenTBaA/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.07pon4D15Z/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 07:18: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', 'psutil>=3.1.1'] in /builds/slave/test/build 07:18: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 psutil>=3.1.1 07:18:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ICmWenTBaA/Render', 07:18:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:18:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:18:49 INFO - 'HOME': '/Users/cltbld', 07:18:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:18:49 INFO - 'LOGNAME': 'cltbld', 07:18:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:18:49 INFO - 'MOZ_NO_REMOTE': '1', 07:18:49 INFO - 'NO_EM_RESTART': '1', 07:18:49 INFO - 'PAGER': '/bin/cat', 07:18:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:18:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:18:49 INFO - 'PWD': '/builds/slave/test', 07:18:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:18:49 INFO - 'SHELL': '/bin/bash', 07:18:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.07pon4D15Z/Listeners', 07:18:49 INFO - 'TMPDIR': '/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/', 07:18:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:18:49 INFO - 'USER': 'cltbld', 07:18:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:18:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:18:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:18:49 INFO - 'XPC_FLAGS': '0x0', 07:18:49 INFO - 'XPC_SERVICE_NAME': '0', 07:18:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:18:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:18:49 INFO - Downloading/unpacking psutil>=3.1.1 07:18: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 07:18: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 07:18: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 07:18: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 07:18:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 07:18:51 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:18:51 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:18:51 INFO - Installing collected packages: psutil 07:18:51 INFO - Running setup.py install for psutil 07:18:51 INFO - building 'psutil._psutil_osx' extension 07:18:51 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 07:18:52 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 07:18:52 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 07:18:52 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 07:18:52 INFO - building 'psutil._psutil_posix' extension 07:18:52 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 07:18:52 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 07:18:52 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 07:18:52 INFO - ^ 07:18:52 INFO - 1 warning generated. 07:18:52 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 07:18:52 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:18:52 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:18:52 INFO - Successfully installed psutil 07:18:52 INFO - Cleaning up... 07:18:52 INFO - Return code: 0 07:18:52 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 07:18:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:18:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:18:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:18:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:18:52 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 0x100e8eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100e91768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8e9b5a6a40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100b9fd30>, '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 0x100b52db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100b93be8>, '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.ICmWenTBaA/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.07pon4D15Z/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 07:18:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 07:18:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 07:18:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ICmWenTBaA/Render', 07:18:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:18:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:18:52 INFO - 'HOME': '/Users/cltbld', 07:18:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:18:52 INFO - 'LOGNAME': 'cltbld', 07:18:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:18:52 INFO - 'MOZ_NO_REMOTE': '1', 07:18:52 INFO - 'NO_EM_RESTART': '1', 07:18:52 INFO - 'PAGER': '/bin/cat', 07:18:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:18:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:18:52 INFO - 'PWD': '/builds/slave/test', 07:18:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:18:52 INFO - 'SHELL': '/bin/bash', 07:18:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.07pon4D15Z/Listeners', 07:18:52 INFO - 'TMPDIR': '/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/', 07:18:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:18:52 INFO - 'USER': 'cltbld', 07:18:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:18:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:18:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:18:52 INFO - 'XPC_FLAGS': '0x0', 07:18:52 INFO - 'XPC_SERVICE_NAME': '0', 07:18:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:18:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:18:52 INFO - Downloading/unpacking mozsystemmonitor==0.3 07:18:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:18:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:18:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:18:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:18:54 INFO - Downloading mozsystemmonitor-0.3.tar.gz 07:18:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 07:18:54 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 07:18:54 INFO - Installing collected packages: mozsystemmonitor 07:18:54 INFO - Running setup.py install for mozsystemmonitor 07:18:54 INFO - Successfully installed mozsystemmonitor 07:18:54 INFO - Cleaning up... 07:18:54 INFO - Return code: 0 07:18:54 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 07:18:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:18:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:18:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:18:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:18:55 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x100e8eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100e91768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8e9b5a6a40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100b9fd30>, '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 0x100b52db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100b93be8>, '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.ICmWenTBaA/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.07pon4D15Z/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 07:18:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 07:18:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 07:18:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ICmWenTBaA/Render', 07:18:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:18:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:18:55 INFO - 'HOME': '/Users/cltbld', 07:18:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:18:55 INFO - 'LOGNAME': 'cltbld', 07:18:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:18:55 INFO - 'MOZ_NO_REMOTE': '1', 07:18:55 INFO - 'NO_EM_RESTART': '1', 07:18:55 INFO - 'PAGER': '/bin/cat', 07:18:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:18:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:18:55 INFO - 'PWD': '/builds/slave/test', 07:18:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:18:55 INFO - 'SHELL': '/bin/bash', 07:18:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.07pon4D15Z/Listeners', 07:18:55 INFO - 'TMPDIR': '/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/', 07:18:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:18:55 INFO - 'USER': 'cltbld', 07:18:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:18:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:18:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:18:55 INFO - 'XPC_FLAGS': '0x0', 07:18:55 INFO - 'XPC_SERVICE_NAME': '0', 07:18:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:18:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:18:55 INFO - Downloading/unpacking jsonschema==2.5.1 07:18:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:18:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:18:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:18:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:18:57 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 07:18:57 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 07:18:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:18:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:18:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:18:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:18:57 INFO - Downloading functools32-3.2.3-2.tar.gz 07:18:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 07:18:57 INFO - warning: no files found matching '*.txt' 07:18:57 INFO - no previously-included directories found matching 'build' 07:18:57 INFO - no previously-included directories found matching 'dist' 07:18:57 INFO - no previously-included directories found matching '.git*' 07:18:57 INFO - Installing collected packages: jsonschema, functools32 07:18:57 INFO - Running setup.py install for functools32 07:18:57 INFO - warning: no files found matching '*.txt' 07:18:57 INFO - no previously-included directories found matching 'build' 07:18:57 INFO - no previously-included directories found matching 'dist' 07:18:57 INFO - no previously-included directories found matching '.git*' 07:18:57 INFO - Successfully installed jsonschema functools32 07:18:57 INFO - Cleaning up... 07:18:57 INFO - Return code: 0 07:18:57 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 07:18:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:18:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:18:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:18:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:18:57 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 0x100e8eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100e91768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8e9b5a6a40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100b9fd30>, '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 0x100b52db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100b93be8>, '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.ICmWenTBaA/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.07pon4D15Z/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 07:18:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 07:18:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 07:18:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ICmWenTBaA/Render', 07:18:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:18:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:18:57 INFO - 'HOME': '/Users/cltbld', 07:18:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:18:57 INFO - 'LOGNAME': 'cltbld', 07:18:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:18:57 INFO - 'MOZ_NO_REMOTE': '1', 07:18:57 INFO - 'NO_EM_RESTART': '1', 07:18:57 INFO - 'PAGER': '/bin/cat', 07:18:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:18:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:18:57 INFO - 'PWD': '/builds/slave/test', 07:18:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:18:57 INFO - 'SHELL': '/bin/bash', 07:18:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.07pon4D15Z/Listeners', 07:18:57 INFO - 'TMPDIR': '/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/', 07:18:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:18:57 INFO - 'USER': 'cltbld', 07:18:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:18:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:18:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:18:57 INFO - 'XPC_FLAGS': '0x0', 07:18:57 INFO - 'XPC_SERVICE_NAME': '0', 07:18:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:18:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:18:58 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 07:18:58 INFO - Cleaning up... 07:18:58 INFO - Return code: 0 07:18:58 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 07:18:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:18:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:18:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:18:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:18:58 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x100e8eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100e91768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8e9b5a6a40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100b9fd30>, '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 0x100b52db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100b93be8>, '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.ICmWenTBaA/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.07pon4D15Z/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 07:18:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 07:18:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 07:18:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ICmWenTBaA/Render', 07:18:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:18:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:18:58 INFO - 'HOME': '/Users/cltbld', 07:18:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:18:58 INFO - 'LOGNAME': 'cltbld', 07:18:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:18:58 INFO - 'MOZ_NO_REMOTE': '1', 07:18:58 INFO - 'NO_EM_RESTART': '1', 07:18:58 INFO - 'PAGER': '/bin/cat', 07:18:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:18:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:18:58 INFO - 'PWD': '/builds/slave/test', 07:18:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:18:58 INFO - 'SHELL': '/bin/bash', 07:18:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.07pon4D15Z/Listeners', 07:18:58 INFO - 'TMPDIR': '/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/', 07:18:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:18:58 INFO - 'USER': 'cltbld', 07:18:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:18:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:18:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:18:58 INFO - 'XPC_FLAGS': '0x0', 07:18:58 INFO - 'XPC_SERVICE_NAME': '0', 07:18:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:18:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:18:58 INFO - Downloading/unpacking blobuploader==1.2.4 07:18:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:18:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:18:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:18:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:18:59 INFO - Downloading blobuploader-1.2.4.tar.gz 07:18:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 07:18:59 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 07:18: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 07:18: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 07:18: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 07:18: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 07:19:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 07:19:00 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 07:19:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:19:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:19:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:19:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:19:00 INFO - Downloading docopt-0.6.1.tar.gz 07:19:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 07:19:00 INFO - Installing collected packages: blobuploader, requests, docopt 07:19:00 INFO - Running setup.py install for blobuploader 07:19:00 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 07:19:00 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 07:19:00 INFO - Running setup.py install for requests 07:19:01 INFO - Running setup.py install for docopt 07:19:01 INFO - Successfully installed blobuploader requests docopt 07:19:01 INFO - Cleaning up... 07:19:01 INFO - Return code: 0 07:19:01 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:19: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')]} 07:19:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:19: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')]} 07:19:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:19:01 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 0x100e8eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100e91768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8e9b5a6a40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100b9fd30>, '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 0x100b52db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100b93be8>, '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.ICmWenTBaA/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.07pon4D15Z/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 07:19:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 07:19:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 07:19:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ICmWenTBaA/Render', 07:19:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:19:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:19:01 INFO - 'HOME': '/Users/cltbld', 07:19:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:19:01 INFO - 'LOGNAME': 'cltbld', 07:19:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:19:01 INFO - 'MOZ_NO_REMOTE': '1', 07:19:01 INFO - 'NO_EM_RESTART': '1', 07:19:01 INFO - 'PAGER': '/bin/cat', 07:19:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:19:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:19:01 INFO - 'PWD': '/builds/slave/test', 07:19:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:19:01 INFO - 'SHELL': '/bin/bash', 07:19:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.07pon4D15Z/Listeners', 07:19:01 INFO - 'TMPDIR': '/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/', 07:19:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:19:01 INFO - 'USER': 'cltbld', 07:19:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:19:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:19:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:19:01 INFO - 'XPC_FLAGS': '0x0', 07:19:01 INFO - 'XPC_SERVICE_NAME': '0', 07:19:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:19:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:19:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:19:01 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-UYD30d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:19:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:19:02 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-mLCqsm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:19:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:19:02 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-aeu4EB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:19:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:19:02 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-T80Url-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:19:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:19:02 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-puYJey-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:19:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:19:02 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-NR1MoM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:19:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:19:02 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-YqgObS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:19:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:19:02 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-9WvHgW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:19:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:19:02 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-t9GUkx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:19:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:19:02 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-AZ7L6E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:19:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:19:02 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-5QmpUT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:19:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:19:03 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-I_BE1G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:19:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:19:03 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-rQu_Zk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:19:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:19:03 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-p6N1I5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:19:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:19:03 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-Gi49BP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:19:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:19:03 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-7DKXXN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:19:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:19:03 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-wyGTe1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:19:03 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 07:19:03 INFO - Running setup.py install for manifestparser 07:19:03 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 07:19:03 INFO - Running setup.py install for mozcrash 07:19:03 INFO - Running setup.py install for mozdebug 07:19:04 INFO - Running setup.py install for mozdevice 07:19:04 INFO - Installing dm script to /builds/slave/test/build/venv/bin 07:19:04 INFO - Running setup.py install for mozfile 07:19:04 INFO - Running setup.py install for mozhttpd 07:19:04 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 07:19:04 INFO - Running setup.py install for mozinfo 07:19:04 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 07:19:04 INFO - Running setup.py install for mozInstall 07:19:04 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 07:19:04 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 07:19:04 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 07:19:04 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 07:19:04 INFO - Running setup.py install for mozleak 07:19:04 INFO - Running setup.py install for mozlog 07:19:05 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 07:19:05 INFO - Running setup.py install for moznetwork 07:19:05 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 07:19:05 INFO - Running setup.py install for mozprocess 07:19:05 INFO - Running setup.py install for mozprofile 07:19:05 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 07:19:05 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 07:19:05 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 07:19:05 INFO - Running setup.py install for mozrunner 07:19:05 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 07:19:05 INFO - Running setup.py install for mozscreenshot 07:19:05 INFO - Running setup.py install for moztest 07:19:06 INFO - Running setup.py install for mozversion 07:19:06 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 07:19:06 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 07:19:06 INFO - Cleaning up... 07:19:06 INFO - Return code: 0 07:19:06 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:19:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:19:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:19:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:19:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:19:06 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 0x100e8eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100e91768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8e9b5a6a40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100b9fd30>, '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 0x100b52db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100b93be8>, '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.ICmWenTBaA/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.07pon4D15Z/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 07:19:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 07:19:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 07:19:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ICmWenTBaA/Render', 07:19:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:19:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:19:06 INFO - 'HOME': '/Users/cltbld', 07:19:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:19:06 INFO - 'LOGNAME': 'cltbld', 07:19:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:19:06 INFO - 'MOZ_NO_REMOTE': '1', 07:19:06 INFO - 'NO_EM_RESTART': '1', 07:19:06 INFO - 'PAGER': '/bin/cat', 07:19:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:19:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:19:06 INFO - 'PWD': '/builds/slave/test', 07:19:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:19:06 INFO - 'SHELL': '/bin/bash', 07:19:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.07pon4D15Z/Listeners', 07:19:06 INFO - 'TMPDIR': '/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/', 07:19:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:19:06 INFO - 'USER': 'cltbld', 07:19:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:19:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:19:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:19:06 INFO - 'XPC_FLAGS': '0x0', 07:19:06 INFO - 'XPC_SERVICE_NAME': '0', 07:19:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:19:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:19:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:19:06 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-DmIHS8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:19:06 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 07:19:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:19:06 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-HVPKpE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:19:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 07:19:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:19:06 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-Z4xaBz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:19:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 07:19:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:19:06 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-UAhg7U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:19:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.50 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 07:19:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:19:06 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-YWALqn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:19:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 07:19:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:19:07 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-IW9JuP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:19:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 07:19:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:19:07 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-KsOrhT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:19:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 07:19:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:19:07 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-eeZ2Wc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:19:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 07:19:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:19:07 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-JceBrr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:19:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 07:19:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:19:07 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-herGvN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:19:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 07:19:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:19:07 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-5jvWvu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:19:07 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 07:19:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:19:07 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-l_HcDL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:19:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 07:19:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:19:07 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-H9tq9W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:19:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 07:19:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:19:07 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-8uU1E9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:19:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 07:19:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:19:07 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-DgnesB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:19:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 07:19:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:19:08 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-bgbmmx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:19:08 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 07:19:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:19:08 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-RHY_kF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:19:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 07:19:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 07:19:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 07:19:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 07:19:08 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.50->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 07:19:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.50->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 07:19:08 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 07:19:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:19:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:19:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:19:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:19:09 INFO - Downloading blessings-1.6.tar.gz 07:19:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 07:19:09 INFO - Installing collected packages: blessings 07:19:09 INFO - Running setup.py install for blessings 07:19:09 INFO - Successfully installed blessings 07:19:09 INFO - Cleaning up... 07:19:10 INFO - Return code: 0 07:19:10 INFO - Done creating virtualenv /builds/slave/test/build/venv. 07:19:10 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:19:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:19:10 INFO - Reading from file tmpfile_stdout 07:19:10 INFO - Current package versions: 07:19:10 INFO - blessings == 1.6 07:19:10 INFO - blobuploader == 1.2.4 07:19:10 INFO - docopt == 0.6.1 07:19:10 INFO - functools32 == 3.2.3-2 07:19:10 INFO - jsonschema == 2.5.1 07:19:10 INFO - manifestparser == 1.1 07:19:10 INFO - mozInstall == 1.12 07:19:10 INFO - mozcrash == 1.0 07:19:10 INFO - mozdebug == 0.1 07:19:10 INFO - mozdevice == 0.50 07:19:10 INFO - mozfile == 1.2 07:19:10 INFO - mozhttpd == 0.7 07:19:10 INFO - mozinfo == 0.9 07:19:10 INFO - mozleak == 0.1 07:19:10 INFO - mozlog == 3.4 07:19:10 INFO - moznetwork == 0.27 07:19:10 INFO - mozprocess == 0.25 07:19:10 INFO - mozprofile == 0.28 07:19:10 INFO - mozrunner == 6.13 07:19:10 INFO - mozscreenshot == 0.1 07:19:10 INFO - mozsystemmonitor == 0.3 07:19:10 INFO - moztest == 0.8 07:19:10 INFO - mozversion == 1.4 07:19:10 INFO - psutil == 3.1.1 07:19:10 INFO - requests == 1.2.3 07:19:10 INFO - wsgiref == 0.1.2 07:19:10 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:19:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:19:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:19:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:19:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:19:10 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 0x100e8eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100e91768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8e9b5a6a40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100b9fd30>, '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 0x100b52db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100b93be8>, '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.ICmWenTBaA/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.07pon4D15Z/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 07:19:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 07:19:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 07:19:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ICmWenTBaA/Render', 07:19:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:19:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:19:10 INFO - 'HOME': '/Users/cltbld', 07:19:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:19:10 INFO - 'LOGNAME': 'cltbld', 07:19:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:19:10 INFO - 'MOZ_NO_REMOTE': '1', 07:19:10 INFO - 'NO_EM_RESTART': '1', 07:19:10 INFO - 'PAGER': '/bin/cat', 07:19:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:19:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:19:10 INFO - 'PWD': '/builds/slave/test', 07:19:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:19:10 INFO - 'SHELL': '/bin/bash', 07:19:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.07pon4D15Z/Listeners', 07:19:10 INFO - 'TMPDIR': '/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/', 07:19:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:19:10 INFO - 'USER': 'cltbld', 07:19:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:19:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:19:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:19:10 INFO - 'XPC_FLAGS': '0x0', 07:19:10 INFO - 'XPC_SERVICE_NAME': '0', 07:19:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:19:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:19:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 07:19:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 07:19:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 07:19:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 07:19:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 07:19:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 07:19:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 07:19:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 07:19:10 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 07:19:10 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 07:19:10 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 07:19:10 INFO - Cleaning up... 07:19:10 INFO - Return code: 0 07:19:10 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 07:19:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:19:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:19:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:19:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:19:10 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 0x100e8eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100e91768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8e9b5a6a40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100b9fd30>, '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 0x100b52db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100b93be8>, '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.ICmWenTBaA/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.07pon4D15Z/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 07:19:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 07:19:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 07:19:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ICmWenTBaA/Render', 07:19:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:19:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:19:10 INFO - 'HOME': '/Users/cltbld', 07:19:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:19:10 INFO - 'LOGNAME': 'cltbld', 07:19:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:19:10 INFO - 'MOZ_NO_REMOTE': '1', 07:19:10 INFO - 'NO_EM_RESTART': '1', 07:19:10 INFO - 'PAGER': '/bin/cat', 07:19:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:19:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:19:10 INFO - 'PWD': '/builds/slave/test', 07:19:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:19:10 INFO - 'SHELL': '/bin/bash', 07:19:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.07pon4D15Z/Listeners', 07:19:10 INFO - 'TMPDIR': '/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/', 07:19:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:19:10 INFO - 'USER': 'cltbld', 07:19:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:19:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:19:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:19:10 INFO - 'XPC_FLAGS': '0x0', 07:19:10 INFO - 'XPC_SERVICE_NAME': '0', 07:19:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:19:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:19:10 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 07:19:10 INFO - Cleaning up... 07:19:10 INFO - Return code: 0 07:19:10 INFO - Running post-action listener: _resource_record_post_action 07:19:10 INFO - Running post-action listener: _start_resource_monitoring 07:19:10 INFO - Starting resource monitoring. 07:19:10 INFO - [mozharness: 2017-05-12 14:19:10.862500Z] Finished create-virtualenv step (success) 07:19:10 INFO - [mozharness: 2017-05-12 14:19:10.863422Z] Running install step. 07:19:10 INFO - Running pre-action listener: _resource_record_pre_action 07:19:10 INFO - Running main action method: install 07:19:10 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:19:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:19:11 INFO - Reading from file tmpfile_stdout 07:19:11 INFO - Detecting whether we're running mozinstall >=1.0... 07:19:11 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 07:19:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 07:19:11 INFO - Reading from file tmpfile_stdout 07:19:11 INFO - Output received: 07:19:11 INFO - Usage: mozinstall [options] installer 07:19:11 INFO - Options: 07:19:11 INFO - -h, --help show this help message and exit 07:19:11 INFO - -d DEST, --destination=DEST 07:19:11 INFO - Directory to install application into. [default: 07:19:11 INFO - "/builds/slave/test"] 07:19:11 INFO - --app=APP Application being installed. [default: firefox] 07:19:11 INFO - mkdir: /builds/slave/test/build/application 07:19:11 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 07:19:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 07:19:31 INFO - Reading from file tmpfile_stdout 07:19:31 INFO - Output received: 07:19:31 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 07:19:31 INFO - Running post-action listener: _resource_record_post_action 07:19:31 INFO - [mozharness: 2017-05-12 14:19:31.445031Z] Finished install step (success) 07:19:31 INFO - [mozharness: 2017-05-12 14:19:31.445193Z] Running run-tests step. 07:19:31 INFO - Running pre-action listener: _resource_record_pre_action 07:19:31 INFO - Running main action method: run_tests 07:19:31 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 07:19:31 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 07:19:31 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 07:19:31 INFO - 2017-05-12 07:19:31.459 screenresolution[1632:7566] starting screenresolution argv=screenresolution get 07:19:31 INFO - 2017-05-12 07:19:31.462 screenresolution[1632:7566] Display 0: 1600x1200x32@60 07:19:31 INFO - 2017-05-12 07:19:31.468 screenresolution[1633:7568] starting screenresolution argv=screenresolution list 07:19:31 INFO - Available Modes on Display 0 07:19:31 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 07:19:31 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 07:19:31 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 07:19:31 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 07:19:31 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 07:19:31 INFO - Intel Iris: 07:19:31 INFO - Chipset Model: Intel Iris 07:19:31 INFO - Type: GPU 07:19:31 INFO - Bus: Built-In 07:19:31 INFO - VRAM (Dynamic, Max): 1536 MB 07:19:31 INFO - Vendor: Intel (0x8086) 07:19:31 INFO - Device ID: 0x0a2e 07:19:31 INFO - Revision ID: 0x0009 07:19:31 INFO - Displays: 07:19:31 INFO - Display: 07:19:31 INFO - Resolution: 1600 x 1200 @ 60 Hz 07:19:31 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 07:19:31 INFO - Display Serial Number: 1600x1200 60 07:19:31 INFO - Main Display: Yes 07:19:31 INFO - Mirror: Off 07:19:31 INFO - Online: Yes 07:19:31 INFO - Rotation: Supported 07:19:31 INFO - Return code: 0 07:19:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 07:19:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 07:19:31 INFO - Python 2.7.3 07:19:31 INFO - Return code: 0 07:19:31 INFO - grabbing minidump binary from tooltool 07:19:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:19:31 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 0x100b9fd30>, '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 0x100b52db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100b93be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 07:19:31 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 07:19:31 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 07:19:31 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 07:19:31 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 07:19:31 INFO - Return code: 0 07:19:31 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 07:19:31 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 07:19:31 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 07:19:31 INFO - ENV: RUST_BACKTRACE is now 1 07:19:31 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 07:19:31 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 07:19:31 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/CLbjXho1QNOocJrkaAt-dg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0365', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 07:19:31 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/CLbjXho1QNOocJrkaAt-dg/artifacts/public/build/target.crashreporter-symbols.zip --title t-yosemite-r7-0365 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 07:19:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ICmWenTBaA/Render', 07:19:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:19:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:19:31 INFO - 'HOME': '/Users/cltbld', 07:19:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:19:31 INFO - 'LOGNAME': 'cltbld', 07:19:31 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 07:19:31 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 07:19:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:19:31 INFO - 'MOZ_NO_REMOTE': '1', 07:19:31 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 07:19:31 INFO - 'NO_EM_RESTART': '1', 07:19:31 INFO - 'PAGER': '/bin/cat', 07:19:31 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:19:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:19:31 INFO - 'PWD': '/builds/slave/test', 07:19:31 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 07:19:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:19:31 INFO - 'RUST_BACKTRACE': '1', 07:19:31 INFO - 'SHELL': '/bin/bash', 07:19:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.07pon4D15Z/Listeners', 07:19:31 INFO - 'TMPDIR': '/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/', 07:19:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:19:31 INFO - 'USER': 'cltbld', 07:19:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:19:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:19:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:19:31 INFO - 'XPC_FLAGS': '0x0', 07:19:31 INFO - 'XPC_SERVICE_NAME': '0', 07:19:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:19:31 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/CLbjXho1QNOocJrkaAt-dg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0365', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 07:19:32 INFO - mozversion application_buildid: 20170512060349 07:19:32 INFO - mozversion application_changeset: 1178b701781de2b1a5afb7b7d6b4954a3a7a51ba 07:19:32 INFO - mozversion application_display_name: Nightly 07:19:32 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 07:19:32 INFO - mozversion application_name: Firefox 07:19:32 INFO - mozversion application_remotingname: firefox 07:19:32 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 07:19:32 INFO - mozversion application_vendor: Mozilla 07:19:32 INFO - mozversion application_version: 55.0a1 07:19:32 INFO - mozversion platform_buildid: 20170512060349 07:19:32 INFO - mozversion platform_changeset: 1178b701781de2b1a5afb7b7d6b4954a3a7a51ba 07:19:32 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 07:19:32 INFO - mozversion platform_version: 55.0a1 07:19:32 INFO - using testdate: 1494598772 07:19:32 INFO - actual date: 1494598772 07:19:32 INFO - starting webserver on 'localhost:49224' 07:19:32 INFO - SUITE-START | Running 2 tests 07:19:32 INFO - TEST-START | dromaeo_css 07:19:32 INFO - operating with platform_type : mac_ 07:19:32 INFO - Initialising browser for dromaeo_css test... 07:19:32 INFO - TEST-INFO | started process 1643 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/tmphCnI_t/profile http://localhost:49224/getInfo.html) 07:19:34 INFO - PID 1643 | 2017-05-12 07:19:34.498 plugin-container[1645:7751] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x973f, name = 'com.apple.tsm.portname' 07:19:34 INFO - PID 1643 | See /usr/include/servers/bootstrap_defs.h for the error codes. 07:19:34 INFO - PID 1643 | 2017-05-12 07:19:34.502 plugin-container[1645:7751] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4b17, name = 'com.apple.CFPasteboardClient' 07:19:34 INFO - PID 1643 | See /usr/include/servers/bootstrap_defs.h for the error codes. 07:19:34 INFO - PID 1643 | 2017-05-12 07:19:34.502 plugin-container[1645:7751] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 07:19:34 INFO - PID 1643 | __metrics Screen width/height:1600/1200 07:19:34 INFO - PID 1643 | colorDepth:24 07:19:34 INFO - PID 1643 | Browser inner width/height: 1024/691 07:19:34 INFO - PID 1643 | __metrics 07:19:35 INFO - PID 1643 | 2017-05-12 07:19:35.160 plugin-container[1646:7808] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x913b, name = 'com.apple.tsm.portname' 07:19:35 INFO - PID 1643 | See /usr/include/servers/bootstrap_defs.h for the error codes. 07:19:35 INFO - PID 1643 | 2017-05-12 07:19:35.161 plugin-container[1646:7808] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x960f, name = 'com.apple.CFPasteboardClient' 07:19:35 INFO - PID 1643 | See /usr/include/servers/bootstrap_defs.h for the error codes. 07:19:35 INFO - PID 1643 | 2017-05-12 07:19:35.161 plugin-container[1646:7808] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 07:19:35 INFO - TEST-INFO | 1643: exit 0 07:19:35 INFO - Browser initialized. 07:19:35 INFO - Running cycle 1/1 for dromaeo_css test... 07:19:35 INFO - TEST-INFO | started process 1647 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/tmphCnI_t/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:19:37 INFO - PID 1647 | 2017-05-12 07:19:37.717 plugin-container[1648:7912] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x923f, name = 'com.apple.tsm.portname' 07:19:37 INFO - PID 1647 | See /usr/include/servers/bootstrap_defs.h for the error codes. 07:19:37 INFO - PID 1647 | 2017-05-12 07:19:37.718 plugin-container[1648:7912] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x970f, name = 'com.apple.CFPasteboardClient' 07:19:37 INFO - PID 1647 | See /usr/include/servers/bootstrap_defs.h for the error codes. 07:19:37 INFO - PID 1647 | 2017-05-12 07:19:37.718 plugin-container[1648:7912] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 07:19:38 INFO - PID 1647 | 2017-05-12 07:19:38.374 plugin-container[1649:7957] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4447, name = 'com.apple.tsm.portname' 07:19:38 INFO - PID 1647 | See /usr/include/servers/bootstrap_defs.h for the error codes. 07:19:38 INFO - PID 1647 | 2017-05-12 07:19:38.374 plugin-container[1649:7957] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x990b, name = 'com.apple.CFPasteboardClient' 07:19:38 INFO - PID 1647 | See /usr/include/servers/bootstrap_defs.h for the error codes. 07:19:38 INFO - PID 1647 | 2017-05-12 07:19:38.374 plugin-container[1649:7957] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 07:19:47 INFO - PID 1647 | RSS: Main: 229699584 07:19:47 INFO - PID 1647 | 07:22:50 INFO - PID 1647 | Cycle 1(1): loaded http://localhost:49224/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49224/tests/dromaeo/cssquery-ext.html) 07:22:52 INFO - PID 1647 | RSS: Main: 252502016 07:22:52 INFO - PID 1647 | 07:25:54 INFO - PID 1647 | Cycle 1(1): loaded http://localhost:49224/tests/dromaeo/cssquery-ext.html (next: http://localhost:49224/tests/dromaeo/cssquery-jquery.html) 07:25:55 INFO - PID 1647 | RSS: Main: 258215936 07:25:55 INFO - PID 1647 | 07:28:56 INFO - PID 1647 | Cycle 1(1): loaded http://localhost:49224/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49224/tests/dromaeo/cssquery-mootools.html) 07:28:57 INFO - PID 1647 | RSS: Main: 267804672 07:28:57 INFO - PID 1647 | 07:31:59 INFO - PID 1647 | Cycle 1(1): loaded http://localhost:49224/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49224/tests/dromaeo/cssquery-prototype.html) 07:32:00 INFO - PID 1647 | RSS: Main: 261124096 07:32:00 INFO - PID 1647 | 07:35:02 INFO - PID 1647 | Cycle 1(1): loaded http://localhost:49224/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49224/tests/dromaeo/cssquery-yui.html) 07:35:03 INFO - PID 1647 | RSS: Main: 264818688 07:35:03 INFO - PID 1647 | 07:38:05 INFO - PID 1647 | Cycle 1(1): loaded http://localhost:49224/tests/dromaeo/cssquery-yui.html (next: http://localhost:49224/tests/dromaeo/cssquery-dojo.html) 07:38:05 INFO - PID 1647 | RSS: Main: 263954432 07:38:05 INFO - PID 1647 | 07:38:05 INFO - PID 1647 | __start_tp_report 07:38:05 INFO - PID 1647 | _x_x_mozilla_page_load 07:38:05 INFO - PID 1647 | _x_x_mozilla_page_load_details 07:38:05 INFO - PID 1647 | |i|pagename|runs| 07:38:05 INFO - PID 1647 | |0;dojo.html;2688.4219892723067;3000.564918086877;3522.7357948153895;3528.382533056716;3562.3053504566606;4784.779881130311;5550.8057217997375;5985.22192115025;6167.876642467148;6171.136040954264;4925.950740492594;6098.886104840108;6112.959454150325;6134.294556126047;6206.224221972254;5298.6555873868265;5371.435999220064;5375.462453754632;5408.945910540893;5483.725813709319;4728.834490792823;5315.681059136464;5427.728613569326;5530.115181570949;5535.640183388087;5380.461953804627;5436.2661040759385;5437.864053398696;5446.455354464561;5460.918086228729;5418.539424148952;5425.457454254582;5429.945700542983;5440.6735595863975;5463.890722185534;4329.891752706208;5345.572354211693;5370.785168593252;5380.031594313022;5387.676739395649;6002.189704389895;6011.48902343297;6118.801925441415;6126.142340072393;6126.203593532812;6346.079818426302;6396.360363963567;6421.839454013687;6438.678066096676;6452.225732912034;6630.73477060917;6658.03458498515;6670.899936500957;6685.966570167118;6688.665566721645;6100.450959413674;6127.988881834447;6228.906566401508;6235.625862448268;6282.528810339243;6693.163354580677;6709.161354830646;6758.155230596176;6759.628220447923;6797.0823938767635;6258.342873998165;6337.96831015842;6446.967765161238;6494.318096599884;6505.186851643544;6495.740170393131;6531.6081035137095;6566.5403421760975;6644.4020038196795;6652.501062420338;6555.278919318871;6559.24568674599;6571.178602674666;6584.209894812653;6588.143541339595;6313.463355614732;6361.9045714314325;6418.74325027003;6572;6597.406233439014;5176.870578235575;5205.6616319939085;5264.341957255343;5279.155335146435;5298.258243845865;4615.653825963066;4654.976725116353;4667.159911215946;4696.9060618787435;4702.012577358724;6137.714085983886;6300.2754683212015;6318.115463835067;6326.46225070865;6331.715072821734;6402.231732192166;6593.5384523083885;6652.900206496906;6672.199336079701;6689.163854518185;4918.90162196754;5189.299444574934;6125.479334256638;6263.3736626337;6297.4017468340435;4391.231534481517;5710.857228569319;5789.334226563915;5811.912821307684;5841.123831425235;5710.3433105193435;6432.742690292336;6658.9001164982565;6795;6854.897176542256;6660.234073081658;6678.7328506859185;6700.932990670226;6708.228553716385;6710.094137291403;5648.632838865625;6891.793246202622;6900.378965893094;6903.827404314932;6907.930920690729;6298.401651843067;6409.102725618324;6460.289368169404;6462.676866156768;6485.708143133476;6673.432758215412;6689.5986240825705;6689.632070236184;6701.463882889259;6701.966490167518;6210.378962103753;6385.776497822554;6429;6450.774222902176;6480.70836812354;5803.9419605803405;5968.612040217372;6277.686115694106;6362.23653161605;6368.840778980562;5295.629305948547;6280.591761535486;6318.368163183645;6320.715567799368;6343.143675603919;6396.360363963567;6456.774012909714;6462.838429039311;6463.25672547663;6478.352164783484;6107.188815080102;6192.040233763774;6193.102000209854;6296.150019747275;6309.305976342507 07:38:05 INFO - PID 1647 | |1;ext.html;736.7806280605596;768.1243382543901;803.196803196803;1203.7411956429366;1247.1332423965337;37551.812240938794;38084.80957595215;38119.047023824416;38164.23671526568;38185.42721859167;37642.8117859411;38053.23893522128;38070.048248793726;38132.42801357982;38168.236635267276;7637.0071890654135;7788.026496687914;8077.151899050603;8249.092599814016;8304.709335173271;8262.256396924276;8330;8348.707795227168;8352.24829765321;8362.456440331389;8290.544020078923;8328.916710832906;8331.708390206344;8358.45670031446;8369.121242269566;8309.127541608135;8327.125651806533;8327.541985190843;8337.708180213724;8344.20730030649;8114.797130071736;8172.836543269161;8190.590470476512;8191.221833925768;8191.959040204821;36396.818015909754;36522;36566.63433365659;36605.450918236245;36692.44961325582;3626.0934766308424;3667.853285868562;3668.1563240454843;3670.889873303805;3683.28176005679;23415.88292058546;24053.87973060141;30090.54864177039;30376.240593985105;30395.848020759975;3095.938081238385;3117.4700300948894;3129.5149251865996;3130.2487403023338;3133.388989147117;217797;222544;276766;296249;297135.514322427;46315.76842115768;46407.30389044167;46561;46588.06823863504;46670;47712.52287477081;47916.76041619839;47922.5207747918;48029;48119.278210826866;12570.685732856751;13276.137051091648;14053.297335133202;14157.362918668685;14157.71684566303;11494.367809770376;11547.884521154681;11569.826452603218;11572.537098516133;11579.710507237387;12932.741345173044;12974.962003039735;13010.414531346112;13031.609051728274;13138.146020508828;5857.355690874;5894.67579283135;5928;5941.732622032019;5953.493953014043;860.9956950215209;986.807572523351;1001.3991605037065;1024.8411496218098;1038.1694644284541;21762.782372176393;24326;24375;24413.145539906018;24606.507869842502;1086.6685660873352;1095.309954728532;1118.116687816632;1125.943702814856;1135.5571327182406;10588.629397971034;12332.938335308267;12387.6283711487;12448.190867593577;12574.559890403792;291841;295674;296376;297032;312724.16396278515;53417;54022.45977540253;54042.729786350814;54223.72888135534;54228;1464.5093893545568;1494.7085318363033;1498.2658497336226;1501.0543357684808;1505.8945873788948;28818.567721484615;29177;29638.555421669123;29802.552961705158;29807.254818629706;30486.695133048386;45183.774081129384;45944.31083533683;45989.54010459853;46038.769806150754;278975;281687;281936;282329.5883520569;282741;33772.66227337794;34233.486497702055;34912.47631285482;35257;35369.46945795764;43363.56636433595;43858.34212486879;44150;44217.77891110524;46254;32448.51327230046;33071.66928330782;33120.50319245165;33550;33748.662513374555;32493;33038.33923321472;33529.994100177035;33775.66224337725;34750;39885.60114398935;40040.59959400369;40329.588464403605;40600.39099413452;40653;27029.25955414044;29059;33395.165120872174;33618.31908404619;34655;19494.902525487283;23846.642300365856;26244;26450;26602.467950641265 07:38:05 INFO - PID 1647 | |2;jquery.html;438.1849759447429;444.9065696203797;445.1386566992869;446.5869071109223;463.83302011275936;18905.527361815963;18940.621187576242;20205.898970505146;20749.688754668692;21017.894910525443;16909.492715218563;17978.370757023506;20874.26940057098;22373.44066398337;22401.215957441495;5844;6123.601965872226;6162.537809664271;6163.938360616404;6175.475084617813;5434.83695489134;5941.643501389931;6087.20866287382;6141.631502109888;6145.446909778119;5243.715289754015;5875.236219291486;6059.878802423949;6082.209312789331;6104.1454196412315;5877.823665290048;5951.940480595182;5965.582409231334;5996.1005849122985;5997;4409.250427427345;5324.653897496651;5328.706921119337;5339.359276886759;5366.758495867656;23189.7681023191;26550.86724566384;26752.331191720168;26889.32776680579;27769.583456248172;2082.4897900014444;2170.1970270999673;2193.484531135198;2196.2971849008322;2202.933911982643;17561;17806.37677681288;17828.286868525243;18002.5499362517;18019.639607207915;2063.123172651617;2162.848600597959;2184.2682701295084;2186.8578542394694;2193.714817073786;122926;124829;156188.21905890512;157214.7810392321;159315;11765.76432484305;12036.45835937385;12166.330851803059;12290.938545307396;12649.87350126487;16169.676606467805;16295.266712997944;16307.103109328864;16315.265813038443;16324.428644997606;7731.458797884094;8751.518666473277;8804.163604457694;8871.822563548822;8908.242799362128;7353.6323183840595;8144.226298501633;8453.661853525913;8537.103604121477;8567.571621418903;7584.051993500812;7660.717028054011;7690.692372305157;7773.5724535149975;7779.649915753805;2013.6073465674347;2080.89945980488;2207.624703800358;2216.3683350245424;2416.2871952773976;936.8875734911854;956.1459044751412;957.3633533700012;984.0602224875227;999.5302207962246;18599.53501162482;20458.795412045987;20459.59080818375;20488;20711.96440177985;822.5928165557967;978.6672531339378;1002.3634991780272;1011.1506334678905;1021.5249908792447;8571.528565928933;9877.442663526115;10364.740881477872;10402.011808878144;10402.5838966442;460739;536482;537451;550960;551084;123769.38115309365;126078;127572.44855102846;128946;130341.69658303485;1130.768192520515;1156.8033434316187;1172.6130376975577;1173.266708307308;1177.8939895409283;17613.855099418495;17980.10099494994;21176.68234976446;21301.89349053245;22840.771592283865;58124.70937645285;59079.70460147672;59184;59276.70361648164;59661;73109;73173.634131829;75340;75747.62126189555;76056;8217.260446559838;8626.223639872442;8655.394122411371;8979.865302020606;9133.634654613741;11560.710982225512;11799.705007374885;13087.541936032194;13717.10838795515;14105.083169593943;11581.710457238636;11621.6513504595;11862.703432413913;11919.761604767682;12052.975497082496;8103.473274237156;10693;10783.676489705322;11083.778324433628;11311.490982905625;11824.881751182722;11891.643250702493;11971;12179.330136842558;12189;8454.619542120743;8500;8653.134686531297;9036.909630903607;9063;11835.82246266324;11897.940510297392;11959.581414650445;12039.63881083569;12042.277463352228 07:38:05 INFO - PID 1647 | |3;mootools.html;1394.8953828462866;1448.627732136456;1472.8748056415204;1482.2662781922943;1483.0508474576272;11742.941285293573;12003;12265.386730663477;12271.325077120755;12285.44715487803;12196.268223906573;12396.628101156957;12400.379981000937;12418.441170147342;12433.316167610801;2319.489712263299;2614.2026681861976;2644.6429731986223;2649.9602505962425;2650.0592289737156;1969.9212031518723;2155.6012137754487;2437.9029436753444;2573.253756410648;2596.584546472565;2553.182981445929;2585.0564543941514;2602.128287023849;2602.6746656667915;2606.2832721001782;2526.2295000024983;2553.3106061363515;2580.5742052561304;2583.315421413327;2584.0826506590265;2536.7843733282693;2563.3976015636313;2581.548229059907;2581.9612705809427;2595;11649.301041937511;11869.287842729464;11871.940640296743;11900.464479098462;11911.583094591735;6591.077249185118;6774;6809.965950170267;6811.080504131928;6818.943063825115;3973.841046358142;4276.48682158143;4348.869533913988;4354.281543545311;4373.803178856959;1019.2966852871494;1232.9445174967148;1268.1186575330148;1269.568346762096;1290.0518119182486;16218.83781162185;16320.75518867218;16432.01407915517;16557.92373495731;17057.976521408633;4744.217204161344;5454.7818087276855;5489;5595.524380427628;5693.829185124453;11680.240784348991;11698.122640801974;11733.943945044986;11756.94121529404;11796.76406471883;6546.312637173123;6547.607143571401;6554.63949482783;6555.606663600104;6565.770198043046;6064.211652485149;6209.720562574695;6217.409346112112;6232.283287421914;6265.4361107500545;4244.511881133709;4369.956300437018;4376.978115109468;4406.603405693503;4423.270160423558;1267.9746405071846;1284.6724085358173;1286.12543470441;1302.6613080599113;1305.5626365167775;738.9150247721581;744.5049042386861;766.6435107674957;768.9077310722749;782.8982232309763;5706.3152421709665;5772.932007578527;5826.096954971986;5991.101334799728;6023.427774361428;571.4428432278495;573.065902578794;595.7229888102054;604.597942368329;605.3915814606293;3343.1474973881504;3528.0297918072224;3568.6074531801482;3578.177019285579;3610.819459027038;14616.26918654063;15276.23618819077;15313.310901009481;15336.693266134615;15353.232338383035;14243.21662308583;14902;14913.328900199516;14928.850711492963;15206.923965380323;7457.216992215847;8493.660253589911;8600.913990860137;8683.478991260545;8714.172153645393;5870.236869206976;6479.352064793483;6673.366030227121;6677;6684.0642310075655;12496.31358749153;12890;12894.290814004944;12909.612711618667;12944.482220711443;13049.543265985645;13083.869161308265;13093.738125237633;13118.803217951929;13360.465581377024;9149;9442.622295108118;9442.622295108393;9457.716268511957;9480.95259523698;10490.737731556772;10732.892671073188;10736.194785391282;10762.246642734934;10939.179561532757;9285;9340.206082483064;9351.39215950961;9367.016463271531;9480.905190948279;8976.730698079067;9042.864357034781;9117.08829117083;9172.770680732769;9186.494742789211;8172.305354044735;8280.58597070132;8291.9585402075;8330.333573314241;8331.708390206313;4538.546145385435;5158.406783219978;5396.271503346998;5439.673619582838;5673.971630141823;4650.651201159859;5718.913406452761;5734.942650573441;5830.912536311874;5866.120081987565 07:38:05 INFO - PID 1647 | |4;prototype.html;315.84997126364965;355.5821909256623;363.7799131525427;376.17242067451616;410.1264307026035;5263.76313065912;5566.165075238716;6083.178770865932;6422.165118534595;6507.739690412383;5058.620603454747;5988.281406231247;6261.311255761862;6282.403171698693;6335.619862808235;4576.748278844662;4833.202521583935;4834.839638486773;4840.903181936359;4846.87882802931;3899.030989350388;3900.3174444472247;4348.826046958134;4456.55434456555;4875.58557522611;3653.885564902717;4180.937285940713;4440.333949907507;4442.822287108528;4459.687821852472;4229.38673892287;4461.509233984243;4486.573775491323;4488.7655894628915;4498.685092043558;3379.9324013519836;3515.789052656849;3695.131644063643;3791.9620803791886;3802.16352402471;6023.849403764941;6056.576039677225;6061.8181454556;6080.422359875804;6099;4417.917610185493;4904.60763138948;5018.7992480300745;5289.9471005290225;5316.3354580677415;5418.674879507203;6533.6695712965875;6561.540692151551;6626.469882409396;6664.600123992527;4281;4301.935470967938;4305.483341998949;4307.461567304087;4325.243082460588;6221.564490485668;6708.530402871801;7061.646917654097;7092.680829362691;7109.36015758579;4422.668299877522;4440.4671439427475;4450.933236001462;4461.375407442961;4508.571685689854;3839.6352346527037;4549.590536851633;4554.0891821635805;4557.111363284194;4563.817447302137;5177.508136727005;5271.3674359076385;5356.866078348073;5365.195220716923;5414.91877621836;6671.666416679147;6685.866282674417;6700.765473208415;6704.66476676174;6711.865762684719;1503.9248037598077;1508.3061791576;1527.8854085943376;1539.8595951627246;1547.2959803289477;889.8754174415587;996.8486398210079;1003.372891942536;1006.8691070160833;1017.6641708236265;5410.48600382963;6113.724882380304;6340.397662222082;6374.661321122523;6374.776882809172;5512.972435137799;6950.513464057569;6957.095577574883;6975.431092634332;6986.685599148051;6407.048352537456;6415.133956915756;6422.398928433991;6447.3552644735155;6459.128017717548;3313.7680362374886;3490.4938783876196;3534.6642069003406;3642.832514653777;3672.1186915140174;7100.751473698396;7237.203907570163;7251.854962900712;7252.673629686677;7266.45501587383;7083.929160708327;7090.822729431702;7182.96408517954;7194.964025179946;7200.351968322876;6258.967270400333;6852.629474105199;6950.617716025465;7221.4222862171955;7223.638818059076;6664.733410663519;6962.268961758942;7073.292670732885;7099.396551293198;7115.335602164992;6661.633610151488;6815.761448349284;6943.201531823703;6954.130733658293;6975.1167184860815;5402.135658294654;6309.589876658002;7025.613591252531;7268.527545709512;7283.963580182065;3767.9826446859743;3783.24335132969;4452.799624016862;4484.484284307216;4559.680822342537;6797.184337879288;6965;7013.964930175521;7023.122109736283;7060.470464715064;6048.546359023003;6369.299377068612;6398.656282180608;6423.454006409507;6781.321867813179;5886.882262354815;6643.867122657616;6762.932370676231;6773.390394864486;6791.0832037676255;6403.8719225614295;6516.120323756232;6652.968789837583;6653.9667301663185;6679.365460281266;6161.781758567002;6611.371919667817;6682.863913134682;6700.631465269457;6739.292374300628;5185.403678577012;6059.606125601822;6322.462590679844;6628.5691430056895;6636.7013484392355 07:38:05 INFO - PID 1647 | |5;yui.html;733.8532293541291;836.6151570277672;839.4249938791932;871.8343514732201;876.7598785104059;14465.493707720232;14930.5520834375;15076.246187690627;15326;15335.309911054;11039.668809935694;13649.727005459885;17501;17521.73717394237;17566.121693915284;823.7240524424295;977.0961860279231;992.9900700993006;995.318207028188;996.681062060141;997.5909876950452;998.5506522065064;999.9300048996573;1000.2698030437784;1001.2890847498285;942.7030485397112;1034.4103860799348;1041.0994489766374;1047.5809676129534;1053.7839742852734;979.1187930862245;1033.534909290822;1038.413296487482;1040.750219947211;1041.250299784154;758.4652819762055;766.4826242286434;767.2787579675088;768.1627026541041;769.7690692792196;16606.75089873653;16896.577585560335;16961;17258.741118883227;17397.39109131187;16976.74534881978;17037.7444338335;17114;17234.741478877826;17466.388676396393;14030.929845350707;14241.71516569673;14340.784888226684;14417.927910360486;14567.708645827024;3653.780773153619;3691.427828686558;3694.131879008431;3694.242680250542;3699.112213068844;109853;110209.44895275553;110576.4471177647;111342;120665.39667301695;12235.510579576896;12447.564335248404;13076;13842.653933651538;13889.097208681404;21886.89056554739;23608.527829443658;26219.737802622112;26705;26794;7935.64289606969;8048.195180481905;8086.43394962347;8097.797555061053;8103.270705636403;3954.3277642800217;3970.5705945860045;4008.5390180129075;4028.073543085107;4032.3749818778138;3807.3717836556657;4403.383526306256;4430.933535996963;4460.30865215892;4483.349914262479;923.1230331185267;948.3551185194134;958.3052287091803;959.7312752429191;963.0754475703263;633.1389310537666;659.120074700271;667.6961982298043;667.7929841748976;693.4833549005922;9569.952150239204;11136.554537818558;12046.819297710541;12177.26936383802;12378.319192444502;649.0945131541473;650.3236633900785;652.5758257132902;654.7970129259945;671.8622682350107;4081.5306239782803;4857.95142048575;4864.513548645178;4919.581835544019;5464.278752193047;84040.57979710185;94078.52960735289;96087.07825843444;109160.4541977301;112711.43644281726;77871.61064194643;82615.586922065;84882.57558712167;87076.56461717651;87744.56127719449;74798.25201748022;75050.62474687591;75691.62154189304;76996;81847;12722.173058751152;13027;13046.217226966412;13050.477980880658;13119.737605248032;40659.59340406558;41008.76973690794;41290.38064429096;42010.579894200666;42254.366184506645;53814.192787107444;54347.72826135844;54736.726316368164;55154;55226;16211.35154593803;16375.263113160174;17257;17993.190306436503;18048.458546243633;18363.908180459013;18788;18871.339503117324;18963.336283230572;19401.51496212606;17721.379751708628;17902.373416930346;18301.816981830012;18397.3560925367;18439.985800781087;17392;18362.724559131355;18440.170192341644;18617.81382186215;18732.625347493245;16485.17574121265;16591.502254932373;16916.577085572466;17087.487375378758;17144.914275428542;11853.111016673956;11912.583059592873;12048.096392770753;12357.629271121881;12403.565875194325;11313.773724525628;12214.022878169548;12317.322547259986;12863.842254197167;13914.652133696738 07:38:05 INFO - PID 1647 | __end_tp_report 07:38:05 INFO - PID 1647 | __start_cc_report 07:38:05 INFO - PID 1647 | _x_x_mozilla_cycle_collect,409 07:38:05 INFO - PID 1647 | __end_cc_report 07:38:05 INFO - PID 1647 | __startTimestamp1494599885678__endTimestamp 07:38:05 INFO - PID 1647 | 07:38:05 INFO - PID 1647 | ------- Summary: start ------- 07:38:05 INFO - PID 1647 | Number of tests: 6 07:38:05 INFO - PID 1647 | 07:38:05 INFO - PID 1647 | [#0] dojo.html Cycles:165 Average:6001.84 Median:6296.15 stddev:773.77 (12.3%) stddev-sans-first:731.18 07:38:05 INFO - PID 1647 | Values: 2688.4 3000.6 3522.7 3528.4 3562.3 4784.8 5550.8 5985.2 6167.9 6171.1 4926.0 6098.9 6113.0 6134.3 6206.2 5298.7 5371.4 5375.5 5408.9 5483.7 4728.8 5315.7 5427.7 5530.1 5535.6 5380.5 5436.3 5437.9 5446.5 5460.9 5418.5 5425.5 5429.9 5440.7 5463.9 4329.9 5345.6 5370.8 5380.0 5387.7 6002.2 6011.5 6118.8 6126.1 6126.2 6346.1 6396.4 6421.8 6438.7 6452.2 6630.7 6658.0 6670.9 6686.0 6688.7 6100.5 6128.0 6228.9 6235.6 6282.5 6693.2 6709.2 6758.2 6759.6 6797.1 6258.3 6338.0 6447.0 6494.3 6505.2 6495.7 6531.6 6566.5 6644.4 6652.5 6555.3 6559.2 6571.2 6584.2 6588.1 6313.5 6361.9 6418.7 6572.0 6597.4 5176.9 5205.7 5264.3 5279.2 5298.3 4615.7 4655.0 4667.2 4696.9 4702.0 6137.7 6300.3 6318.1 6326.5 6331.7 6402.2 6593.5 6652.9 6672.2 6689.2 4918.9 5189.3 6125.5 6263.4 6297.4 4391.2 5710.9 5789.3 5811.9 5841.1 5710.3 6432.7 6658.9 6795.0 6854.9 6660.2 6678.7 6700.9 6708.2 6710.1 5648.6 6891.8 6900.4 6903.8 6907.9 6298.4 6409.1 6460.3 6462.7 6485.7 6673.4 6689.6 6689.6 6701.5 6702.0 6210.4 6385.8 6429.0 6450.8 6480.7 5803.9 5968.6 6277.7 6362.2 6368.8 5295.6 6280.6 6318.4 6320.7 6343.1 6396.4 6456.8 6462.8 6463.3 6478.4 6107.2 6192.0 6193.1 6296.2 6309.3 07:38:05 INFO - PID 1647 | 07:38:05 INFO - PID 1647 | [#1] ext.html Cycles:180 Average:43833.07 Median:34444.24 stddev:73740.28 (214.1%) stddev-sans-first:73876.15 07:38:05 INFO - PID 1647 | Values: 736.8 768.1 803.2 1203.7 1247.1 37551.8 38084.8 38119.0 38164.2 38185.4 37642.8 38053.2 38070.0 38132.4 38168.2 7637.0 7788.0 8077.2 8249.1 8304.7 8262.3 8330.0 8348.7 8352.2 8362.5 8290.5 8328.9 8331.7 8358.5 8369.1 8309.1 8327.1 8327.5 8337.7 8344.2 8114.8 8172.8 8190.6 8191.2 8192.0 36396.8 36522.0 36566.6 36605.5 36692.4 3626.1 3667.9 3668.2 3670.9 3683.3 23415.9 24053.9 30090.5 30376.2 30395.8 3095.9 3117.5 3129.5 3130.2 3133.4 217797.0 222544.0 276766.0 296249.0 297135.5 46315.8 46407.3 46561.0 46588.1 46670.0 47712.5 47916.8 47922.5 48029.0 48119.3 12570.7 13276.1 14053.3 14157.4 14157.7 11494.4 11547.9 11569.8 11572.5 11579.7 12932.7 12975.0 13010.4 13031.6 13138.1 5857.4 5894.7 5928.0 5941.7 5953.5 861.0 986.8 1001.4 1024.8 1038.2 21762.8 24326.0 24375.0 24413.1 24606.5 1086.7 1095.3 1118.1 1125.9 1135.6 10588.6 12332.9 12387.6 12448.2 12574.6 291841.0 295674.0 296376.0 297032.0 312724.2 53417.0 54022.5 54042.7 54223.7 54228.0 1464.5 1494.7 1498.3 1501.1 1505.9 28818.6 29177.0 29638.6 29802.6 29807.3 30486.7 45183.8 45944.3 45989.5 46038.8 278975.0 281687.0 281936.0 282329.6 282741.0 33772.7 34233.5 34912.5 35257.0 35369.5 43363.6 43858.3 44150.0 44217.8 46254.0 32448.5 33071.7 33120.5 33550.0 33748.7 32493.0 33038.3 33530.0 33775.7 34750.0 39885.6 40040.6 40329.6 40600.4 40653.0 27029.3 29059.0 33395.2 33618.3 34655.0 19494.9 23846.6 26244.0 26450.0 26602.5 07:38:05 INFO - PID 1647 | 07:38:05 INFO - PID 1647 | [#2] jquery.html Cycles:180 Average:34319.04 Median:12294.90 stddev:89623.20 (728.9%) stddev-sans-first:89838.51 07:38:05 INFO - PID 1647 | Values: 438.2 444.9 445.1 446.6 463.8 18905.5 18940.6 20205.9 20749.7 21017.9 16909.5 17978.4 20874.3 22373.4 22401.2 5844.0 6123.6 6162.5 6163.9 6175.5 5434.8 5941.6 6087.2 6141.6 6145.4 5243.7 5875.2 6059.9 6082.2 6104.1 5877.8 5951.9 5965.6 5996.1 5997.0 4409.3 5324.7 5328.7 5339.4 5366.8 23189.8 26550.9 26752.3 26889.3 27769.6 2082.5 2170.2 2193.5 2196.3 2202.9 17561.0 17806.4 17828.3 18002.5 18019.6 2063.1 2162.8 2184.3 2186.9 2193.7 122926.0 124829.0 156188.2 157214.8 159315.0 11765.8 12036.5 12166.3 12290.9 12649.9 16169.7 16295.3 16307.1 16315.3 16324.4 7731.5 8751.5 8804.2 8871.8 8908.2 7353.6 8144.2 8453.7 8537.1 8567.6 7584.1 7660.7 7690.7 7773.6 7779.6 2013.6 2080.9 2207.6 2216.4 2416.3 936.9 956.1 957.4 984.1 999.5 18599.5 20458.8 20459.6 20488.0 20712.0 822.6 978.7 1002.4 1011.2 1021.5 8571.5 9877.4 10364.7 10402.0 10402.6 460739.0 536482.0 537451.0 550960.0 551084.0 123769.4 126078.0 127572.4 128946.0 130341.7 1130.8 1156.8 1172.6 1173.3 1177.9 17613.9 17980.1 21176.7 21301.9 22840.8 58124.7 59079.7 59184.0 59276.7 59661.0 73109.0 73173.6 75340.0 75747.6 76056.0 8217.3 8626.2 8655.4 8979.9 9133.6 11560.7 11799.7 13087.5 13717.1 14105.1 11581.7 11621.7 11862.7 11919.8 12053.0 8103.5 10693.0 10783.7 11083.8 11311.5 11824.9 11891.6 11971.0 12179.3 12189.0 8454.6 8500.0 8653.1 9036.9 9063.0 11835.8 11897.9 11959.6 12039.6 12042.3 07:38:05 INFO - PID 1647 | 07:38:05 INFO - PID 1647 | [#3] mootools.html Cycles:180 Average:7055.28 Median:3435.59 stddev:4544.54 (132.3%) stddev-sans-first:4537.38 07:38:05 INFO - PID 1647 | Values: 1394.9 1448.6 1472.9 1482.3 1483.1 11742.9 12003.0 12265.4 12271.3 12285.4 12196.3 12396.6 12400.4 12418.4 12433.3 2319.5 2614.2 2644.6 2650.0 2650.1 1969.9 2155.6 2437.9 2573.3 2596.6 2553.2 2585.1 2602.1 2602.7 2606.3 2526.2 2553.3 2580.6 2583.3 2584.1 2536.8 2563.4 2581.5 2582.0 2595.0 11649.3 11869.3 11871.9 11900.5 11911.6 6591.1 6774.0 6810.0 6811.1 6818.9 3973.8 4276.5 4348.9 4354.3 4373.8 1019.3 1232.9 1268.1 1269.6 1290.1 16218.8 16320.8 16432.0 16557.9 17058.0 4744.2 5454.8 5489.0 5595.5 5693.8 11680.2 11698.1 11733.9 11756.9 11796.8 6546.3 6547.6 6554.6 6555.6 6565.8 6064.2 6209.7 6217.4 6232.3 6265.4 4244.5 4370.0 4377.0 4406.6 4423.3 1268.0 1284.7 1286.1 1302.7 1305.6 738.9 744.5 766.6 768.9 782.9 5706.3 5772.9 5826.1 5991.1 6023.4 571.4 573.1 595.7 604.6 605.4 3343.1 3528.0 3568.6 3578.2 3610.8 14616.3 15276.2 15313.3 15336.7 15353.2 14243.2 14902.0 14913.3 14928.9 15206.9 7457.2 8493.7 8600.9 8683.5 8714.2 5870.2 6479.4 6673.4 6677.0 6684.1 12496.3 12890.0 12894.3 12909.6 12944.5 13049.5 13083.9 13093.7 13118.8 13360.5 9149.0 9442.6 9442.6 9457.7 9481.0 10490.7 10732.9 10736.2 10762.2 10939.2 9285.0 9340.2 9351.4 9367.0 9480.9 8976.7 9042.9 9117.1 9172.8 9186.5 8172.3 8280.6 8292.0 8330.3 8331.7 4538.5 5158.4 5396.3 5439.7 5674.0 4650.7 5718.9 5734.9 5830.9 5866.1 07:38:05 INFO - PID 1647 | 07:38:05 INFO - PID 1647 | [#4] prototype.html Cycles:180 Average:5380.11 Median:6106.36 stddev:1754.78 (28.7%) stddev-sans-first:1718.04 07:38:05 INFO - PID 1647 | Values: 315.8 355.6 363.8 376.2 410.1 5263.8 5566.2 6083.2 6422.2 6507.7 5058.6 5988.3 6261.3 6282.4 6335.6 4576.7 4833.2 4834.8 4840.9 4846.9 3899.0 3900.3 4348.8 4456.6 4875.6 3653.9 4180.9 4440.3 4442.8 4459.7 4229.4 4461.5 4486.6 4488.8 4498.7 3379.9 3515.8 3695.1 3792.0 3802.2 6023.8 6056.6 6061.8 6080.4 6099.0 4417.9 4904.6 5018.8 5289.9 5316.3 5418.7 6533.7 6561.5 6626.5 6664.6 4281.0 4301.9 4305.5 4307.5 4325.2 6221.6 6708.5 7061.6 7092.7 7109.4 4422.7 4440.5 4450.9 4461.4 4508.6 3839.6 4549.6 4554.1 4557.1 4563.8 5177.5 5271.4 5356.9 5365.2 5414.9 6671.7 6685.9 6700.8 6704.7 6711.9 1503.9 1508.3 1527.9 1539.9 1547.3 889.9 996.8 1003.4 1006.9 1017.7 5410.5 6113.7 6340.4 6374.7 6374.8 5513.0 6950.5 6957.1 6975.4 6986.7 6407.0 6415.1 6422.4 6447.4 6459.1 3313.8 3490.5 3534.7 3642.8 3672.1 7100.8 7237.2 7251.9 7252.7 7266.5 7083.9 7090.8 7183.0 7195.0 7200.4 6259.0 6852.6 6950.6 7221.4 7223.6 6664.7 6962.3 7073.3 7099.4 7115.3 6661.6 6815.8 6943.2 6954.1 6975.1 5402.1 6309.6 7025.6 7268.5 7284.0 3768.0 3783.2 4452.8 4484.5 4559.7 6797.2 6965.0 7014.0 7023.1 7060.5 6048.5 6369.3 6398.7 6423.5 6781.3 5886.9 6643.9 6762.9 6773.4 6791.1 6403.9 6516.1 6653.0 6654.0 6679.4 6161.8 6611.4 6682.9 6700.6 6739.3 5185.4 6059.6 6322.5 6628.6 6636.7 07:38:05 INFO - PID 1647 | 07:38:05 INFO - PID 1647 | [#5] yui.html Cycles:180 Average:21031.09 Median:20644.20 stddev:28374.24 (137.4%) stddev-sans-first:28412.91 07:38:05 INFO - PID 1647 | Values: 733.9 836.6 839.4 871.8 876.8 14465.5 14930.6 15076.2 15326.0 15335.3 11039.7 13649.7 17501.0 17521.7 17566.1 823.7 977.1 993.0 995.3 996.7 997.6 998.6 999.9 1000.3 1001.3 942.7 1034.4 1041.1 1047.6 1053.8 979.1 1033.5 1038.4 1040.8 1041.3 758.5 766.5 767.3 768.2 769.8 16606.8 16896.6 16961.0 17258.7 17397.4 16976.7 17037.7 17114.0 17234.7 17466.4 14030.9 14241.7 14340.8 14417.9 14567.7 3653.8 3691.4 3694.1 3694.2 3699.1 109853.0 110209.4 110576.4 111342.0 120665.4 12235.5 12447.6 13076.0 13842.7 13889.1 21886.9 23608.5 26219.7 26705.0 26794.0 7935.6 8048.2 8086.4 8097.8 8103.3 3954.3 3970.6 4008.5 4028.1 4032.4 3807.4 4403.4 4430.9 4460.3 4483.3 923.1 948.4 958.3 959.7 963.1 633.1 659.1 667.7 667.8 693.5 9570.0 11136.6 12046.8 12177.3 12378.3 649.1 650.3 652.6 654.8 671.9 4081.5 4858.0 4864.5 4919.6 5464.3 84040.6 94078.5 96087.1 109160.5 112711.4 77871.6 82615.6 84882.6 87076.6 87744.6 74798.3 75050.6 75691.6 76996.0 81847.0 12722.2 13027.0 13046.2 13050.5 13119.7 40659.6 41008.8 41290.4 42010.6 42254.4 53814.2 54347.7 54736.7 55154.0 55226.0 16211.4 16375.3 17257.0 17993.2 18048.5 18363.9 18788.0 18871.3 18963.3 19401.5 17721.4 17902.4 18301.8 18397.4 18440.0 17392.0 18362.7 18440.2 18617.8 18732.6 16485.2 16591.5 16916.6 17087.5 17144.9 11853.1 11912.6 12048.1 12357.6 12403.6 11313.8 12214.0 12317.3 12863.8 13914.7 07:38:05 INFO - PID 1647 | -------- Summary: end -------- 07:38:05 INFO - PID 1647 | 07:38:06 INFO - TEST-INFO | 1647: exit 0 07:38:06 INFO - TEST-OK | dromaeo_css | took 1114288ms 07:38:06 INFO - TEST-START | kraken 07:38:06 INFO - operating with platform_type : mac_ 07:38:06 INFO - Initialising browser for kraken test... 07:38:06 INFO - TEST-INFO | started process 1662 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/tmpnuq70r/profile http://localhost:49224/getInfo.html) 07:38:08 INFO - PID 1662 | 2017-05-12 07:38:08.781 plugin-container[1663:10416] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x923f, name = 'com.apple.tsm.portname' 07:38:08 INFO - PID 1662 | See /usr/include/servers/bootstrap_defs.h for the error codes. 07:38:08 INFO - PID 1662 | 2017-05-12 07:38:08.786 plugin-container[1663:10416] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4423, name = 'com.apple.CFPasteboardClient' 07:38:08 INFO - PID 1662 | See /usr/include/servers/bootstrap_defs.h for the error codes. 07:38:08 INFO - PID 1662 | 2017-05-12 07:38:08.786 plugin-container[1663:10416] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 07:38:08 INFO - PID 1662 | __metrics Screen width/height:1600/1200 07:38:08 INFO - PID 1662 | colorDepth:24 07:38:08 INFO - PID 1662 | Browser inner width/height: 1024/691 07:38:08 INFO - PID 1662 | __metrics 07:38:09 INFO - PID 1662 | 2017-05-12 07:38:09.315 plugin-container[1664:10466] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x903f, name = 'com.apple.tsm.portname' 07:38:09 INFO - PID 1662 | See /usr/include/servers/bootstrap_defs.h for the error codes. 07:38:09 INFO - PID 1662 | 2017-05-12 07:38:09.316 plugin-container[1664:10466] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x960b, name = 'com.apple.CFPasteboardClient' 07:38:09 INFO - PID 1662 | See /usr/include/servers/bootstrap_defs.h for the error codes. 07:38:09 INFO - PID 1662 | 2017-05-12 07:38:09.316 plugin-container[1664:10466] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 07:38:10 INFO - TEST-INFO | 1662: exit 0 07:38:10 INFO - Browser initialized. 07:38:10 INFO - Running cycle 1/1 for kraken test... 07:38:10 INFO - TEST-INFO | started process 1665 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/tmpnuq70r/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:38:12 INFO - PID 1665 | 2017-05-12 07:38:12.466 plugin-container[1666:10570] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x943b, name = 'com.apple.tsm.portname' 07:38:12 INFO - PID 1665 | See /usr/include/servers/bootstrap_defs.h for the error codes. 07:38:12 INFO - PID 1665 | 2017-05-12 07:38:12.467 plugin-container[1666:10570] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4707, name = 'com.apple.CFPasteboardClient' 07:38:12 INFO - PID 1665 | See /usr/include/servers/bootstrap_defs.h for the error codes. 07:38:12 INFO - PID 1665 | 2017-05-12 07:38:12.467 plugin-container[1666:10570] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 07:38:12 INFO - PID 1665 | 2017-05-12 07:38:12.884 plugin-container[1667:10613] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8f3f, name = 'com.apple.tsm.portname' 07:38:12 INFO - PID 1665 | See /usr/include/servers/bootstrap_defs.h for the error codes. 07:38:12 INFO - PID 1665 | 2017-05-12 07:38:12.884 plugin-container[1667:10613] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9417, name = 'com.apple.CFPasteboardClient' 07:38:12 INFO - PID 1665 | See /usr/include/servers/bootstrap_defs.h for the error codes. 07:38:12 INFO - PID 1665 | 2017-05-12 07:38:12.884 plugin-container[1667:10613] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 07:38:22 INFO - PID 1665 | RSS: Main: 230002688 07:38:22 INFO - PID 1665 | 07:38:24 INFO - PID 1665 | Cycle 1(1): loaded http://localhost:49224/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49224/tests/kraken/driver.html?testName=audio-beat-detection) 07:38:25 INFO - PID 1665 | RSS: Main: 242909184 07:38:25 INFO - PID 1665 | 07:38:28 INFO - PID 1665 | Cycle 1(1): loaded http://localhost:49224/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49224/tests/kraken/driver.html?testName=audio-dft) 07:38:29 INFO - PID 1665 | RSS: Main: 250920960 07:38:29 INFO - PID 1665 | 07:38:32 INFO - PID 1665 | Cycle 1(1): loaded http://localhost:49224/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49224/tests/kraken/driver.html?testName=audio-fft) 07:38:33 INFO - PID 1665 | RSS: Main: 253927424 07:38:33 INFO - PID 1665 | 07:38:35 INFO - PID 1665 | Cycle 1(1): loaded http://localhost:49224/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49224/tests/kraken/driver.html?testName=audio-oscillator) 07:38:35 INFO - PID 1665 | RSS: Main: 255922176 07:38:35 INFO - PID 1665 | 07:38:37 INFO - PID 1665 | Cycle 1(1): loaded http://localhost:49224/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49224/tests/kraken/driver.html?testName=imaging-gaussian-blur) 07:38:38 INFO - PID 1665 | RSS: Main: 256708608 07:38:38 INFO - PID 1665 | 07:38:41 INFO - PID 1665 | Cycle 1(1): loaded http://localhost:49224/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49224/tests/kraken/driver.html?testName=imaging-darkroom) 07:38:42 INFO - PID 1665 | RSS: Main: 262561792 07:38:42 INFO - PID 1665 | 07:38:44 INFO - PID 1665 | Cycle 1(1): loaded http://localhost:49224/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49224/tests/kraken/driver.html?testName=imaging-desaturate) 07:38:45 INFO - PID 1665 | RSS: Main: 264474624 07:38:45 INFO - PID 1665 | 07:38:48 INFO - PID 1665 | Cycle 1(1): loaded http://localhost:49224/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49224/tests/kraken/driver.html?testName=json-parse-financial) 07:38:49 INFO - PID 1665 | RSS: Main: 263589888 07:38:49 INFO - PID 1665 | 07:38:50 INFO - PID 1665 | Cycle 1(1): loaded http://localhost:49224/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49224/tests/kraken/driver.html?testName=json-stringify-tinderbox) 07:38:51 INFO - PID 1665 | RSS: Main: 262639616 07:38:51 INFO - PID 1665 | 07:38:52 INFO - PID 1665 | Cycle 1(1): loaded http://localhost:49224/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49224/tests/kraken/driver.html?testName=stanford-crypto-aes) 07:38:53 INFO - PID 1665 | RSS: Main: 263360512 07:38:53 INFO - PID 1665 | 07:38:55 INFO - PID 1665 | Cycle 1(1): loaded http://localhost:49224/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49224/tests/kraken/driver.html?testName=stanford-crypto-ccm) 07:38:56 INFO - PID 1665 | RSS: Main: 262819840 07:38:56 INFO - PID 1665 | 07:38:59 INFO - PID 1665 | Cycle 1(1): loaded http://localhost:49224/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49224/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 07:38:59 INFO - PID 1665 | RSS: Main: 262836224 07:38:59 INFO - PID 1665 | 07:39:03 INFO - PID 1665 | Cycle 1(1): loaded http://localhost:49224/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49224/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 07:39:03 INFO - PID 1665 | RSS: Main: 260452352 07:39:03 INFO - PID 1665 | 07:39:05 INFO - PID 1665 | Cycle 1(1): loaded http://localhost:49224/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49224/tests/kraken/driver.html?testName=ai-astar) 07:39:05 INFO - PID 1665 | RSS: Main: 262029312 07:39:05 INFO - PID 1665 | 07:39:05 INFO - PID 1665 | __start_tp_report 07:39:05 INFO - PID 1665 | _x_x_mozilla_page_load 07:39:05 INFO - PID 1665 | _x_x_mozilla_page_load_details 07:39:05 INFO - PID 1665 | |i|pagename|runs| 07:39:05 INFO - PID 1665 | |0;ai-astar;103;114;114;113;116;113;116;115;115;114 07:39:05 INFO - PID 1665 | |1;audio-beat-detection;147;116;113;158;146;92;139;147;164;130 07:39:05 INFO - PID 1665 | |2;audio-dft;198;202;201;220;212;205;194;220;264;196 07:39:05 INFO - PID 1665 | |3;audio-fft;97;53;66;105;84;83;58;64;79;86 07:39:05 INFO - PID 1665 | |4;audio-oscillator;91;69;85;74;68;68;84;72;78;97 07:39:05 INFO - PID 1665 | |5;imaging-gaussian-blur;116;92;136;138;88;88;88;90;88;90 07:39:05 INFO - PID 1665 | |6;imaging-darkroom;97;96;94;96;96;95;96;95;95;96 07:39:05 INFO - PID 1665 | |7;imaging-desaturate;92;94;91;90;93;90;89;90;92;112 07:39:05 INFO - PID 1665 | |8;json-parse-financial;51;49;51;52;50;48;51;51;51;52 07:39:05 INFO - PID 1665 | |9;json-stringify-tinderbox;56;61;56;59;65;56;59;57;57;57 07:39:05 INFO - PID 1665 | |10;stanford-crypto-aes;83;84;81;83;83;85;84;85;86;83 07:39:05 INFO - PID 1665 | |11;stanford-crypto-ccm;137;140;146;137;136;147;138;138;185;138 07:39:05 INFO - PID 1665 | |12;stanford-crypto-pbkdf2;238;238;184;187;214;211;187;175;229;159 07:39:05 INFO - PID 1665 | |13;stanford-crypto-sha256-iterative;70;67;70;66;70;67;70;71;92;92 07:39:05 INFO - PID 1665 | __end_tp_report 07:39:05 INFO - PID 1665 | __start_cc_report 07:39:05 INFO - PID 1665 | _x_x_mozilla_cycle_collect,1017 07:39:05 INFO - PID 1665 | __end_cc_report 07:39:05 INFO - PID 1665 | __startTimestamp1494599945762__endTimestamp 07:39:05 INFO - PID 1665 | 07:39:05 INFO - PID 1665 | ------- Summary: start ------- 07:39:05 INFO - PID 1665 | Number of tests: 14 07:39:05 INFO - PID 1665 | 07:39:05 INFO - PID 1665 | [#0] ai-astar Cycles:10 Average:113.30 Median:114.50 stddev:3.77 (3.3%) stddev-sans-first:1.13 07:39:05 INFO - PID 1665 | Values: 103.0 114.0 114.0 113.0 116.0 113.0 116.0 115.0 115.0 114.0 07:39:05 INFO - PID 1665 | 07:39:05 INFO - PID 1665 | [#1] audio-beat-detection Cycles:10 Average:135.20 Median:147.00 stddev:22.39 (15.2%) stddev-sans-first:23.34 07:39:05 INFO - PID 1665 | Values: 147.0 116.0 113.0 158.0 146.0 92.0 139.0 147.0 164.0 130.0 07:39:05 INFO - PID 1665 | 07:39:05 INFO - PID 1665 | [#2] audio-dft Cycles:10 Average:211.20 Median:208.50 stddev:20.74 (9.9%) stddev-sans-first:21.44 07:39:05 INFO - PID 1665 | Values: 198.0 202.0 201.0 220.0 212.0 205.0 194.0 220.0 264.0 196.0 07:39:05 INFO - PID 1665 | 07:39:05 INFO - PID 1665 | [#3] audio-fft Cycles:10 Average:77.50 Median:81.00 stddev:16.93 (20.9%) stddev-sans-first:16.42 07:39:05 INFO - PID 1665 | Values: 97.0 53.0 66.0 105.0 84.0 83.0 58.0 64.0 79.0 86.0 07:39:05 INFO - PID 1665 | 07:39:05 INFO - PID 1665 | [#4] audio-oscillator Cycles:10 Average:78.60 Median:81.00 stddev:10.24 (12.6%) stddev-sans-first:9.83 07:39:05 INFO - PID 1665 | Values: 91.0 69.0 85.0 74.0 68.0 68.0 84.0 72.0 78.0 97.0 07:39:05 INFO - PID 1665 | 07:39:05 INFO - PID 1665 | [#5] imaging-gaussian-blur Cycles:10 Average:101.40 Median:88.00 stddev:20.59 (23.4%) stddev-sans-first:21.15 07:39:05 INFO - PID 1665 | Values: 116.0 92.0 136.0 138.0 88.0 88.0 88.0 90.0 88.0 90.0 07:39:05 INFO - PID 1665 | 07:39:05 INFO - PID 1665 | [#6] imaging-darkroom Cycles:10 Average:95.60 Median:96.00 stddev:0.84 (0.9%) stddev-sans-first:0.73 07:39:05 INFO - PID 1665 | Values: 97.0 96.0 94.0 96.0 96.0 95.0 96.0 95.0 95.0 96.0 07:39:05 INFO - PID 1665 | 07:39:05 INFO - PID 1665 | [#7] imaging-desaturate Cycles:10 Average:93.30 Median:91.50 stddev:6.75 (7.4%) stddev-sans-first:7.14 07:39:05 INFO - PID 1665 | Values: 92.0 94.0 91.0 90.0 93.0 90.0 89.0 90.0 92.0 112.0 07:39:05 INFO - PID 1665 | 07:39:05 INFO - PID 1665 | [#8] json-parse-financial Cycles:10 Average:50.60 Median:51.00 stddev:1.26 (2.5%) stddev-sans-first:1.33 07:39:05 INFO - PID 1665 | Values: 51.0 49.0 51.0 52.0 50.0 48.0 51.0 51.0 51.0 52.0 07:39:05 INFO - PID 1665 | 07:39:05 INFO - PID 1665 | [#9] json-stringify-tinderbox Cycles:10 Average:58.30 Median:58.00 stddev:2.87 (4.9%) stddev-sans-first:2.92 07:39:05 INFO - PID 1665 | Values: 56.0 61.0 56.0 59.0 65.0 56.0 59.0 57.0 57.0 57.0 07:39:05 INFO - PID 1665 | 07:39:05 INFO - PID 1665 | [#10] stanford-crypto-aes Cycles:10 Average:83.70 Median:84.00 stddev:1.42 (1.7%) stddev-sans-first:1.48 07:39:05 INFO - PID 1665 | Values: 83.0 84.0 81.0 83.0 83.0 85.0 84.0 85.0 86.0 83.0 07:39:05 INFO - PID 1665 | 07:39:05 INFO - PID 1665 | [#11] stanford-crypto-ccm Cycles:10 Average:144.20 Median:139.00 stddev:14.83 (10.7%) stddev-sans-first:15.50 07:39:05 INFO - PID 1665 | Values: 137.0 140.0 146.0 137.0 136.0 147.0 138.0 138.0 185.0 138.0 07:39:05 INFO - PID 1665 | 07:39:05 INFO - PID 1665 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:202.20 Median:212.50 stddev:27.68 (13.0%) stddev-sans-first:26.16 07:39:05 INFO - PID 1665 | Values: 238.0 238.0 184.0 187.0 214.0 211.0 187.0 175.0 229.0 159.0 07:39:05 INFO - PID 1665 | 07:39:05 INFO - PID 1665 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:73.50 Median:70.00 stddev:9.89 (14.1%) stddev-sans-first:10.41 07:39:05 INFO - PID 1665 | Values: 70.0 67.0 70.0 66.0 70.0 67.0 70.0 71.0 92.0 92.0 07:39:05 INFO - PID 1665 | -------- Summary: end -------- 07:39:05 INFO - PID 1665 | 07:39:06 INFO - TEST-INFO | 1665: exit 0 07:39:06 INFO - TEST-OK | kraken | took 60344ms 07:39:06 INFO - SUITE-END | took 1174s 07:39:06 INFO - Completed test suite (00:19:34) 07:39:06 INFO - javascript benchmark 07:39:06 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7796.186959124047, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2688.4219892723067, 3000.564918086877, 3522.7357948153895, 3528.382533056716, 3562.3053504566606, 4784.779881130311, 5550.8057217997375, 5985.22192115025, 6167.876642467148, 6171.136040954264, 4925.950740492594, 6098.886104840108, 6112.959454150325, 6134.294556126047, 6206.224221972254, 5298.6555873868265, 5371.435999220064, 5375.462453754632, 5408.945910540893, 5483.725813709319, 4728.834490792823, 5315.681059136464, 5427.728613569326, 5530.115181570949, 5535.640183388087, 5380.461953804627, 5436.2661040759385, 5437.864053398696, 5446.455354464561, 5460.918086228729, 5418.539424148952, 5425.457454254582, 5429.945700542983, 5440.6735595863975, 5463.890722185534, 4329.891752706208, 5345.572354211693, 5370.785168593252, 5380.031594313022, 5387.676739395649, 6002.189704389895, 6011.48902343297, 6118.801925441415, 6126.142340072393, 6126.203593532812, 6346.079818426302, 6396.360363963567, 6421.839454013687, 6438.678066096676, 6452.225732912034, 6630.73477060917, 6658.03458498515, 6670.899936500957, 6685.966570167118, 6688.665566721645, 6100.450959413674, 6127.988881834447, 6228.906566401508, 6235.625862448268, 6282.528810339243, 6693.163354580677, 6709.161354830646, 6758.155230596176, 6759.628220447923, 6797.0823938767635, 6258.342873998165, 6337.96831015842, 6446.967765161238, 6494.318096599884, 6505.186851643544, 6495.740170393131, 6531.6081035137095, 6566.5403421760975, 6644.4020038196795, 6652.501062420338, 6555.278919318871, 6559.24568674599, 6571.178602674666, 6584.209894812653, 6588.143541339595, 6313.463355614732, 6361.9045714314325, 6418.74325027003, 6572.0, 6597.406233439014, 5176.870578235575, 5205.6616319939085, 5264.341957255343, 5279.155335146435, 5298.258243845865, 4615.653825963066, 4654.976725116353, 4667.159911215946, 4696.9060618787435, 4702.012577358724, 6137.714085983886, 6300.2754683212015, 6318.115463835067, 6326.46225070865, 6331.715072821734, 6402.231732192166, 6593.5384523083885, 6652.900206496906, 6672.199336079701, 6689.163854518185, 4918.90162196754, 5189.299444574934, 6125.479334256638, 6263.3736626337, 6297.4017468340435, 4391.231534481517, 5710.857228569319, 5789.334226563915, 5811.912821307684, 5841.123831425235, 5710.3433105193435, 6432.742690292336, 6658.9001164982565, 6795.0, 6854.897176542256, 6660.234073081658, 6678.7328506859185, 6700.932990670226, 6708.228553716385, 6710.094137291403, 5648.632838865625, 6891.793246202622, 6900.378965893094, 6903.827404314932, 6907.930920690729, 6298.401651843067, 6409.102725618324, 6460.289368169404, 6462.676866156768, 6485.708143133476, 6673.432758215412, 6689.5986240825705, 6689.632070236184, 6701.463882889259, 6701.966490167518, 6210.378962103753, 6385.776497822554, 6429.0, 6450.774222902176, 6480.70836812354, 5803.9419605803405, 5968.612040217372, 6277.686115694106, 6362.23653161605, 6368.840778980562, 5295.629305948547, 6280.591761535486, 6318.368163183645, 6320.715567799368, 6343.143675603919, 6396.360363963567, 6456.774012909714, 6462.838429039311, 6463.25672547663, 6478.352164783484, 6107.188815080102, 6192.040233763774, 6193.102000209854, 6296.150019747275, 6309.305976342507], "value": 5948.632330960467, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [736.7806280605596, 768.1243382543901, 803.196803196803, 1203.7411956429366, 1247.1332423965337, 37551.812240938794, 38084.80957595215, 38119.047023824416, 38164.23671526568, 38185.42721859167, 37642.8117859411, 38053.23893522128, 38070.048248793726, 38132.42801357982, 38168.236635267276, 7637.0071890654135, 7788.026496687914, 8077.151899050603, 8249.092599814016, 8304.709335173271, 8262.256396924276, 8330.0, 8348.707795227168, 8352.24829765321, 8362.456440331389, 8290.544020078923, 8328.916710832906, 8331.708390206344, 8358.45670031446, 8369.121242269566, 8309.127541608135, 8327.125651806533, 8327.541985190843, 8337.708180213724, 8344.20730030649, 8114.797130071736, 8172.836543269161, 8190.590470476512, 8191.221833925768, 8191.959040204821, 36396.818015909754, 36522.0, 36566.63433365659, 36605.450918236245, 36692.44961325582, 3626.0934766308424, 3667.853285868562, 3668.1563240454843, 3670.889873303805, 3683.28176005679, 23415.88292058546, 24053.87973060141, 30090.54864177039, 30376.240593985105, 30395.848020759975, 3095.938081238385, 3117.4700300948894, 3129.5149251865996, 3130.2487403023338, 3133.388989147117, 217797.0, 222544.0, 276766.0, 296249.0, 297135.514322427, 46315.76842115768, 46407.30389044167, 46561.0, 46588.06823863504, 46670.0, 47712.52287477081, 47916.76041619839, 47922.5207747918, 48029.0, 48119.278210826866, 12570.685732856751, 13276.137051091648, 14053.297335133202, 14157.362918668685, 14157.71684566303, 11494.367809770376, 11547.884521154681, 11569.826452603218, 11572.537098516133, 11579.710507237387, 12932.741345173044, 12974.962003039735, 13010.414531346112, 13031.609051728274, 13138.146020508828, 5857.355690874, 5894.67579283135, 5928.0, 5941.732622032019, 5953.493953014043, 860.9956950215209, 986.807572523351, 1001.3991605037065, 1024.8411496218098, 1038.1694644284541, 21762.782372176393, 24326.0, 24375.0, 24413.145539906018, 24606.507869842502, 1086.6685660873352, 1095.309954728532, 1118.116687816632, 1125.943702814856, 1135.5571327182406, 10588.629397971034, 12332.938335308267, 12387.6283711487, 12448.190867593577, 12574.559890403792, 291841.0, 295674.0, 296376.0, 297032.0, 312724.16396278515, 53417.0, 54022.45977540253, 54042.729786350814, 54223.72888135534, 54228.0, 1464.5093893545568, 1494.7085318363033, 1498.2658497336226, 1501.0543357684808, 1505.8945873788948, 28818.567721484615, 29177.0, 29638.555421669123, 29802.552961705158, 29807.254818629706, 30486.695133048386, 45183.774081129384, 45944.31083533683, 45989.54010459853, 46038.769806150754, 278975.0, 281687.0, 281936.0, 282329.5883520569, 282741.0, 33772.66227337794, 34233.486497702055, 34912.47631285482, 35257.0, 35369.46945795764, 43363.56636433595, 43858.34212486879, 44150.0, 44217.77891110524, 46254.0, 32448.51327230046, 33071.66928330782, 33120.50319245165, 33550.0, 33748.662513374555, 32493.0, 33038.33923321472, 33529.994100177035, 33775.66224337725, 34750.0, 39885.60114398935, 40040.59959400369, 40329.588464403605, 40600.39099413452, 40653.0, 27029.25955414044, 29059.0, 33395.165120872174, 33618.31908404619, 34655.0, 19494.902525487283, 23846.642300365856, 26244.0, 26450.0, 26602.467950641265], "value": 17544.93819292624, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [438.1849759447429, 444.9065696203797, 445.1386566992869, 446.5869071109223, 463.83302011275936, 18905.527361815963, 18940.621187576242, 20205.898970505146, 20749.688754668692, 21017.894910525443, 16909.492715218563, 17978.370757023506, 20874.26940057098, 22373.44066398337, 22401.215957441495, 5844.0, 6123.601965872226, 6162.537809664271, 6163.938360616404, 6175.475084617813, 5434.83695489134, 5941.643501389931, 6087.20866287382, 6141.631502109888, 6145.446909778119, 5243.715289754015, 5875.236219291486, 6059.878802423949, 6082.209312789331, 6104.1454196412315, 5877.823665290048, 5951.940480595182, 5965.582409231334, 5996.1005849122985, 5997.0, 4409.250427427345, 5324.653897496651, 5328.706921119337, 5339.359276886759, 5366.758495867656, 23189.7681023191, 26550.86724566384, 26752.331191720168, 26889.32776680579, 27769.583456248172, 2082.4897900014444, 2170.1970270999673, 2193.484531135198, 2196.2971849008322, 2202.933911982643, 17561.0, 17806.37677681288, 17828.286868525243, 18002.5499362517, 18019.639607207915, 2063.123172651617, 2162.848600597959, 2184.2682701295084, 2186.8578542394694, 2193.714817073786, 122926.0, 124829.0, 156188.21905890512, 157214.7810392321, 159315.0, 11765.76432484305, 12036.45835937385, 12166.330851803059, 12290.938545307396, 12649.87350126487, 16169.676606467805, 16295.266712997944, 16307.103109328864, 16315.265813038443, 16324.428644997606, 7731.458797884094, 8751.518666473277, 8804.163604457694, 8871.822563548822, 8908.242799362128, 7353.6323183840595, 8144.226298501633, 8453.661853525913, 8537.103604121477, 8567.571621418903, 7584.051993500812, 7660.717028054011, 7690.692372305157, 7773.5724535149975, 7779.649915753805, 2013.6073465674347, 2080.89945980488, 2207.624703800358, 2216.3683350245424, 2416.2871952773976, 936.8875734911854, 956.1459044751412, 957.3633533700012, 984.0602224875227, 999.5302207962246, 18599.53501162482, 20458.795412045987, 20459.59080818375, 20488.0, 20711.96440177985, 822.5928165557967, 978.6672531339378, 1002.3634991780272, 1011.1506334678905, 1021.5249908792447, 8571.528565928933, 9877.442663526115, 10364.740881477872, 10402.011808878144, 10402.5838966442, 460739.0, 536482.0, 537451.0, 550960.0, 551084.0, 123769.38115309365, 126078.0, 127572.44855102846, 128946.0, 130341.69658303485, 1130.768192520515, 1156.8033434316187, 1172.6130376975577, 1173.266708307308, 1177.8939895409283, 17613.855099418495, 17980.10099494994, 21176.68234976446, 21301.89349053245, 22840.771592283865, 58124.70937645285, 59079.70460147672, 59184.0, 59276.70361648164, 59661.0, 73109.0, 73173.634131829, 75340.0, 75747.62126189555, 76056.0, 8217.260446559838, 8626.223639872442, 8655.394122411371, 8979.865302020606, 9133.634654613741, 11560.710982225512, 11799.705007374885, 13087.541936032194, 13717.10838795515, 14105.083169593943, 11581.710457238636, 11621.6513504595, 11862.703432413913, 11919.761604767682, 12052.975497082496, 8103.473274237156, 10693.0, 10783.676489705322, 11083.778324433628, 11311.490982905625, 11824.881751182722, 11891.643250702493, 11971.0, 12179.330136842558, 12189.0, 8454.619542120743, 8500.0, 8653.134686531297, 9036.909630903607, 9063.0, 11835.82246266324, 11897.940510297392, 11959.581414650445, 12039.63881083569, 12042.277463352228], "value": 10191.126675399933, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1394.8953828462866, 1448.627732136456, 1472.8748056415204, 1482.2662781922943, 1483.0508474576272, 11742.941285293573, 12003.0, 12265.386730663477, 12271.325077120755, 12285.44715487803, 12196.268223906573, 12396.628101156957, 12400.379981000937, 12418.441170147342, 12433.316167610801, 2319.489712263299, 2614.2026681861976, 2644.6429731986223, 2649.9602505962425, 2650.0592289737156, 1969.9212031518723, 2155.6012137754487, 2437.9029436753444, 2573.253756410648, 2596.584546472565, 2553.182981445929, 2585.0564543941514, 2602.128287023849, 2602.6746656667915, 2606.2832721001782, 2526.2295000024983, 2553.3106061363515, 2580.5742052561304, 2583.315421413327, 2584.0826506590265, 2536.7843733282693, 2563.3976015636313, 2581.548229059907, 2581.9612705809427, 2595.0, 11649.301041937511, 11869.287842729464, 11871.940640296743, 11900.464479098462, 11911.583094591735, 6591.077249185118, 6774.0, 6809.965950170267, 6811.080504131928, 6818.943063825115, 3973.841046358142, 4276.48682158143, 4348.869533913988, 4354.281543545311, 4373.803178856959, 1019.2966852871494, 1232.9445174967148, 1268.1186575330148, 1269.568346762096, 1290.0518119182486, 16218.83781162185, 16320.75518867218, 16432.01407915517, 16557.92373495731, 17057.976521408633, 4744.217204161344, 5454.7818087276855, 5489.0, 5595.524380427628, 5693.829185124453, 11680.240784348991, 11698.122640801974, 11733.943945044986, 11756.94121529404, 11796.76406471883, 6546.312637173123, 6547.607143571401, 6554.63949482783, 6555.606663600104, 6565.770198043046, 6064.211652485149, 6209.720562574695, 6217.409346112112, 6232.283287421914, 6265.4361107500545, 4244.511881133709, 4369.956300437018, 4376.978115109468, 4406.603405693503, 4423.270160423558, 1267.9746405071846, 1284.6724085358173, 1286.12543470441, 1302.6613080599113, 1305.5626365167775, 738.9150247721581, 744.5049042386861, 766.6435107674957, 768.9077310722749, 782.8982232309763, 5706.3152421709665, 5772.932007578527, 5826.096954971986, 5991.101334799728, 6023.427774361428, 571.4428432278495, 573.065902578794, 595.7229888102054, 604.597942368329, 605.3915814606293, 3343.1474973881504, 3528.0297918072224, 3568.6074531801482, 3578.177019285579, 3610.819459027038, 14616.26918654063, 15276.23618819077, 15313.310901009481, 15336.693266134615, 15353.232338383035, 14243.21662308583, 14902.0, 14913.328900199516, 14928.850711492963, 15206.923965380323, 7457.216992215847, 8493.660253589911, 8600.913990860137, 8683.478991260545, 8714.172153645393, 5870.236869206976, 6479.352064793483, 6673.366030227121, 6677.0, 6684.0642310075655, 12496.31358749153, 12890.0, 12894.290814004944, 12909.612711618667, 12944.482220711443, 13049.543265985645, 13083.869161308265, 13093.738125237633, 13118.803217951929, 13360.465581377024, 9149.0, 9442.622295108118, 9442.622295108393, 9457.716268511957, 9480.95259523698, 10490.737731556772, 10732.892671073188, 10736.194785391282, 10762.246642734934, 10939.179561532757, 9285.0, 9340.206082483064, 9351.39215950961, 9367.016463271531, 9480.905190948279, 8976.730698079067, 9042.864357034781, 9117.08829117083, 9172.770680732769, 9186.494742789211, 8172.305354044735, 8280.58597070132, 8291.9585402075, 8330.333573314241, 8331.708390206313, 4538.546145385435, 5158.406783219978, 5396.271503346998, 5439.673619582838, 5673.971630141823, 4650.651201159859, 5718.913406452761, 5734.942650573441, 5830.912536311874, 5866.120081987565], "value": 5258.112746259791, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [315.84997126364965, 355.5821909256623, 363.7799131525427, 376.17242067451616, 410.1264307026035, 5263.76313065912, 5566.165075238716, 6083.178770865932, 6422.165118534595, 6507.739690412383, 5058.620603454747, 5988.281406231247, 6261.311255761862, 6282.403171698693, 6335.619862808235, 4576.748278844662, 4833.202521583935, 4834.839638486773, 4840.903181936359, 4846.87882802931, 3899.030989350388, 3900.3174444472247, 4348.826046958134, 4456.55434456555, 4875.58557522611, 3653.885564902717, 4180.937285940713, 4440.333949907507, 4442.822287108528, 4459.687821852472, 4229.38673892287, 4461.509233984243, 4486.573775491323, 4488.7655894628915, 4498.685092043558, 3379.9324013519836, 3515.789052656849, 3695.131644063643, 3791.9620803791886, 3802.16352402471, 6023.849403764941, 6056.576039677225, 6061.8181454556, 6080.422359875804, 6099.0, 4417.917610185493, 4904.60763138948, 5018.7992480300745, 5289.9471005290225, 5316.3354580677415, 5418.674879507203, 6533.6695712965875, 6561.540692151551, 6626.469882409396, 6664.600123992527, 4281.0, 4301.935470967938, 4305.483341998949, 4307.461567304087, 4325.243082460588, 6221.564490485668, 6708.530402871801, 7061.646917654097, 7092.680829362691, 7109.36015758579, 4422.668299877522, 4440.4671439427475, 4450.933236001462, 4461.375407442961, 4508.571685689854, 3839.6352346527037, 4549.590536851633, 4554.0891821635805, 4557.111363284194, 4563.817447302137, 5177.508136727005, 5271.3674359076385, 5356.866078348073, 5365.195220716923, 5414.91877621836, 6671.666416679147, 6685.866282674417, 6700.765473208415, 6704.66476676174, 6711.865762684719, 1503.9248037598077, 1508.3061791576, 1527.8854085943376, 1539.8595951627246, 1547.2959803289477, 889.8754174415587, 996.8486398210079, 1003.372891942536, 1006.8691070160833, 1017.6641708236265, 5410.48600382963, 6113.724882380304, 6340.397662222082, 6374.661321122523, 6374.776882809172, 5512.972435137799, 6950.513464057569, 6957.095577574883, 6975.431092634332, 6986.685599148051, 6407.048352537456, 6415.133956915756, 6422.398928433991, 6447.3552644735155, 6459.128017717548, 3313.7680362374886, 3490.4938783876196, 3534.6642069003406, 3642.832514653777, 3672.1186915140174, 7100.751473698396, 7237.203907570163, 7251.854962900712, 7252.673629686677, 7266.45501587383, 7083.929160708327, 7090.822729431702, 7182.96408517954, 7194.964025179946, 7200.351968322876, 6258.967270400333, 6852.629474105199, 6950.617716025465, 7221.4222862171955, 7223.638818059076, 6664.733410663519, 6962.268961758942, 7073.292670732885, 7099.396551293198, 7115.335602164992, 6661.633610151488, 6815.761448349284, 6943.201531823703, 6954.130733658293, 6975.1167184860815, 5402.135658294654, 6309.589876658002, 7025.613591252531, 7268.527545709512, 7283.963580182065, 3767.9826446859743, 3783.24335132969, 4452.799624016862, 4484.484284307216, 4559.680822342537, 6797.184337879288, 6965.0, 7013.964930175521, 7023.122109736283, 7060.470464715064, 6048.546359023003, 6369.299377068612, 6398.656282180608, 6423.454006409507, 6781.321867813179, 5886.882262354815, 6643.867122657616, 6762.932370676231, 6773.390394864486, 6791.0832037676255, 6403.8719225614295, 6516.120323756232, 6652.968789837583, 6653.9667301663185, 6679.365460281266, 6161.781758567002, 6611.371919667817, 6682.863913134682, 6700.631465269457, 6739.292374300628, 5185.403678577012, 6059.606125601822, 6322.462590679844, 6628.5691430056895, 6636.7013484392355], "value": 4816.8823262843125, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [733.8532293541291, 836.6151570277672, 839.4249938791932, 871.8343514732201, 876.7598785104059, 14465.493707720232, 14930.5520834375, 15076.246187690627, 15326.0, 15335.309911054, 11039.668809935694, 13649.727005459885, 17501.0, 17521.73717394237, 17566.121693915284, 823.7240524424295, 977.0961860279231, 992.9900700993006, 995.318207028188, 996.681062060141, 997.5909876950452, 998.5506522065064, 999.9300048996573, 1000.2698030437784, 1001.2890847498285, 942.7030485397112, 1034.4103860799348, 1041.0994489766374, 1047.5809676129534, 1053.7839742852734, 979.1187930862245, 1033.534909290822, 1038.413296487482, 1040.750219947211, 1041.250299784154, 758.4652819762055, 766.4826242286434, 767.2787579675088, 768.1627026541041, 769.7690692792196, 16606.75089873653, 16896.577585560335, 16961.0, 17258.741118883227, 17397.39109131187, 16976.74534881978, 17037.7444338335, 17114.0, 17234.741478877826, 17466.388676396393, 14030.929845350707, 14241.71516569673, 14340.784888226684, 14417.927910360486, 14567.708645827024, 3653.780773153619, 3691.427828686558, 3694.131879008431, 3694.242680250542, 3699.112213068844, 109853.0, 110209.44895275553, 110576.4471177647, 111342.0, 120665.39667301695, 12235.510579576896, 12447.564335248404, 13076.0, 13842.653933651538, 13889.097208681404, 21886.89056554739, 23608.527829443658, 26219.737802622112, 26705.0, 26794.0, 7935.64289606969, 8048.195180481905, 8086.43394962347, 8097.797555061053, 8103.270705636403, 3954.3277642800217, 3970.5705945860045, 4008.5390180129075, 4028.073543085107, 4032.3749818778138, 3807.3717836556657, 4403.383526306256, 4430.933535996963, 4460.30865215892, 4483.349914262479, 923.1230331185267, 948.3551185194134, 958.3052287091803, 959.7312752429191, 963.0754475703263, 633.1389310537666, 659.120074700271, 667.6961982298043, 667.7929841748976, 693.4833549005922, 9569.952150239204, 11136.554537818558, 12046.819297710541, 12177.26936383802, 12378.319192444502, 649.0945131541473, 650.3236633900785, 652.5758257132902, 654.7970129259945, 671.8622682350107, 4081.5306239782803, 4857.95142048575, 4864.513548645178, 4919.581835544019, 5464.278752193047, 84040.57979710185, 94078.52960735289, 96087.07825843444, 109160.4541977301, 112711.43644281726, 77871.61064194643, 82615.586922065, 84882.57558712167, 87076.56461717651, 87744.56127719449, 74798.25201748022, 75050.62474687591, 75691.62154189304, 76996.0, 81847.0, 12722.173058751152, 13027.0, 13046.217226966412, 13050.477980880658, 13119.737605248032, 40659.59340406558, 41008.76973690794, 41290.38064429096, 42010.579894200666, 42254.366184506645, 53814.192787107444, 54347.72826135844, 54736.726316368164, 55154.0, 55226.0, 16211.35154593803, 16375.263113160174, 17257.0, 17993.190306436503, 18048.458546243633, 18363.908180459013, 18788.0, 18871.339503117324, 18963.336283230572, 19401.51496212606, 17721.379751708628, 17902.373416930346, 18301.816981830012, 18397.3560925367, 18439.985800781087, 17392.0, 18362.724559131355, 18440.170192341644, 18617.81382186215, 18732.625347493245, 16485.17574121265, 16591.502254932373, 16916.577085572466, 17087.487375378758, 17144.914275428542, 11853.111016673956, 11912.583059592873, 12048.096392770753, 12357.629271121881, 12403.565875194325, 11313.773724525628, 12214.022878169548, 12317.322547259986, 12863.842254197167, 13914.652133696738], "value": 8334.419857332234, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1518.6000000000001, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [103.0, 114.0, 114.0, 113.0, 116.0, 113.0, 116.0, 115.0, 115.0, 114.0], "value": 113.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 116.0, 113.0, 158.0, 146.0, 92.0, 139.0, 147.0, 164.0, 130.0], "value": 135.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [198.0, 202.0, 201.0, 220.0, 212.0, 205.0, 194.0, 220.0, 264.0, 196.0], "value": 211.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 53.0, 66.0, 105.0, 84.0, 83.0, 58.0, 64.0, 79.0, 86.0], "value": 77.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 69.0, 85.0, 74.0, 68.0, 68.0, 84.0, 72.0, 78.0, 97.0], "value": 78.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 92.0, 136.0, 138.0, 88.0, 88.0, 88.0, 90.0, 88.0, 90.0], "value": 101.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 96.0, 94.0, 96.0, 96.0, 95.0, 96.0, 95.0, 95.0, 96.0], "value": 95.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 94.0, 91.0, 90.0, 93.0, 90.0, 89.0, 90.0, 92.0, 112.0], "value": 93.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [51.0, 49.0, 51.0, 52.0, 50.0, 48.0, 51.0, 51.0, 51.0, 52.0], "value": 50.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 61.0, 56.0, 59.0, 65.0, 56.0, 59.0, 57.0, 57.0, 57.0], "value": 58.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 84.0, 81.0, 83.0, 83.0, 85.0, 84.0, 85.0, 86.0, 83.0], "value": 83.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [137.0, 140.0, 146.0, 137.0, 136.0, 147.0, 138.0, 138.0, 185.0, 138.0], "value": 144.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [238.0, 238.0, 184.0, 187.0, 214.0, 211.0, 187.0, 175.0, 229.0, 159.0], "value": 202.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 67.0, 70.0, 66.0, 70.0, 67.0, 70.0, 71.0, 92.0, 92.0], "value": 73.5, "unit": "score"}]}]} 07:39:07 INFO - Return code: 0 07:39:07 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 07:39:07 INFO - # TBPL SUCCESS # 07:39:07 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 07:39:07 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 07:39:07 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 07:39:07 INFO - 2017-05-12 07:39:07.084 screenresolution[1672:11001] starting screenresolution argv=screenresolution get 07:39:07 INFO - 2017-05-12 07:39:07.088 screenresolution[1672:11001] Display 0: 1600x1200x32@60 07:39:07 INFO - 2017-05-12 07:39:07.094 screenresolution[1673:11004] starting screenresolution argv=screenresolution list 07:39:07 INFO - Available Modes on Display 0 07:39:07 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 07:39:07 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 07:39:07 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 07:39:07 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 07:39:07 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 07:39:07 INFO - Intel Iris: 07:39:07 INFO - Chipset Model: Intel Iris 07:39:07 INFO - Type: GPU 07:39:07 INFO - Bus: Built-In 07:39:07 INFO - VRAM (Dynamic, Max): 1536 MB 07:39:07 INFO - Vendor: Intel (0x8086) 07:39:07 INFO - Device ID: 0x0a2e 07:39:07 INFO - Revision ID: 0x0009 07:39:07 INFO - Displays: 07:39:07 INFO - Display: 07:39:07 INFO - Resolution: 1600 x 1200 @ 60 Hz 07:39:07 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 07:39:07 INFO - Display Serial Number: 1600x1200 60 07:39:07 INFO - Main Display: Yes 07:39:07 INFO - Mirror: Off 07:39:07 INFO - Online: Yes 07:39:07 INFO - Rotation: Supported 07:39:07 INFO - Return code: 0 07:39:07 INFO - Running post-action listener: _resource_record_post_action 07:39:07 INFO - [mozharness: 2017-05-12 14:39:07.330556Z] Finished run-tests step (success) 07:39:07 INFO - Running post-run listener: _resource_record_post_run 07:39:07 INFO - Total resource usage - Wall time: 1196s; CPU: 54.0%; Read bytes: 40229888; Write bytes: 530342912; Read time: 269; Write time: 1634 07:39:07 INFO - TinderboxPrint: CPU usage
54.1% 07:39:07 INFO - TinderboxPrint: I/O read bytes / time
40,229,888 / 269 07:39:07 INFO - TinderboxPrint: I/O write bytes / time
530,342,912 / 1,634 07:39:07 INFO - TinderboxPrint: CPU idle
2,196.2 (45.9%) 07:39:07 INFO - TinderboxPrint: CPU system
958.8 (20.0%) 07:39:07 INFO - TinderboxPrint: CPU user
1,630.6 (34.1%) 07:39:07 INFO - TinderboxPrint: Swap in / out
254,111,744 / 0 07:39:07 INFO - install - Wall time: 21s; CPU: 54.0%; Read bytes: 207919616; Write bytes: 208291840; Read time: 15817; Write time: 432 07:39:07 INFO - run-tests - Wall time: 1176s; CPU: 54.0%; Read bytes: 38247424; Write bytes: 311311360; Read time: 251; Write time: 1167 07:39:07 INFO - Running post-run listener: _upload_blobber_files 07:39:07 INFO - Blob upload gear active. 07:39:07 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 07:39:07 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 07:39:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 07:39:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 07:39:08 INFO - (blobuploader) - INFO - Open directory for files ... 07:39:08 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 07:39:08 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:39:08 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:39:08 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 07:39:08 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:39:08 INFO - (blobuploader) - INFO - Done attempting. 07:39:08 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 07:39:08 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:39:08 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:39:09 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 07:39:09 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:39:09 INFO - (blobuploader) - INFO - Done attempting. 07:39:09 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 07:39:09 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:39:09 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:39:10 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 07:39:10 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:39:10 INFO - (blobuploader) - INFO - Done attempting. 07:39:10 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 07:39:10 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:39:10 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:39:12 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 07:39:12 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:39:12 INFO - (blobuploader) - INFO - Done attempting. 07:39:12 INFO - (blobuploader) - INFO - Iteration through files over. 07:39:12 INFO - Return code: 0 07:39:12 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 07:39:12 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 07:39:12 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/93a415a9bdb003c1cce209f2d0f8faaa00337ea29819a7b9ee0b176730e8b45596f2bedcac8e2c86560ef127c6d463eabf9ec91b2eeecdebd2ac9b85958dc935", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/12d7bd3ac5632de42c57f6e16f423229a1e7bb3ed35b27cd8128442786869cd896b6294871176cd64b1aa21c118e32d5d522564163d1bf315f19e1a204dd141b", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/efa7ced80688c805cf155cf22e45a8150a18954c6d7885e0d5a897d1b97696b9675715ae8d7c2187c1e5e55262c4b8ffadfb76cbfeb2407594bb33b221a1b8a2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/753bb49692aa59ad99f64f4b0511b7f08ac334b041bfd7d433be9ceed772ee83e18dcb7e549a4bb0954ab843d0dce64729990faf3d41682b26fa7682b56c3307"} 07:39:12 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 07:39:12 INFO - Writing to file /builds/slave/test/properties/blobber_files 07:39:12 INFO - Contents: 07:39:12 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/93a415a9bdb003c1cce209f2d0f8faaa00337ea29819a7b9ee0b176730e8b45596f2bedcac8e2c86560ef127c6d463eabf9ec91b2eeecdebd2ac9b85958dc935", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/12d7bd3ac5632de42c57f6e16f423229a1e7bb3ed35b27cd8128442786869cd896b6294871176cd64b1aa21c118e32d5d522564163d1bf315f19e1a204dd141b", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/efa7ced80688c805cf155cf22e45a8150a18954c6d7885e0d5a897d1b97696b9675715ae8d7c2187c1e5e55262c4b8ffadfb76cbfeb2407594bb33b221a1b8a2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/753bb49692aa59ad99f64f4b0511b7f08ac334b041bfd7d433be9ceed772ee83e18dcb7e549a4bb0954ab843d0dce64729990faf3d41682b26fa7682b56c3307"} 07:39:12 INFO - Running post-run listener: copy_logs_to_upload_dir 07:39:12 INFO - Copying logs to upload dir... 07:39:12 INFO - mkdir: /builds/slave/test/build/upload/logs 07:39:12 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1273.636928 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2017-05-12 07:39:12.822726) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-12 07:39:12.826209) ========= 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.ICmWenTBaA/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.07pon4D15Z/Listeners TMPDIR=/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/93a415a9bdb003c1cce209f2d0f8faaa00337ea29819a7b9ee0b176730e8b45596f2bedcac8e2c86560ef127c6d463eabf9ec91b2eeecdebd2ac9b85958dc935", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/12d7bd3ac5632de42c57f6e16f423229a1e7bb3ed35b27cd8128442786869cd896b6294871176cd64b1aa21c118e32d5d522564163d1bf315f19e1a204dd141b", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/efa7ced80688c805cf155cf22e45a8150a18954c6d7885e0d5a897d1b97696b9675715ae8d7c2187c1e5e55262c4b8ffadfb76cbfeb2407594bb33b221a1b8a2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/753bb49692aa59ad99f64f4b0511b7f08ac334b041bfd7d433be9ceed772ee83e18dcb7e549a4bb0954ab843d0dce64729990faf3d41682b26fa7682b56c3307"} build_url:https://queue.taskcluster.net/v1/task/CLbjXho1QNOocJrkaAt-dg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010877 build_url: 'https://queue.taskcluster.net/v1/task/CLbjXho1QNOocJrkaAt-dg/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/93a415a9bdb003c1cce209f2d0f8faaa00337ea29819a7b9ee0b176730e8b45596f2bedcac8e2c86560ef127c6d463eabf9ec91b2eeecdebd2ac9b85958dc935", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/12d7bd3ac5632de42c57f6e16f423229a1e7bb3ed35b27cd8128442786869cd896b6294871176cd64b1aa21c118e32d5d522564163d1bf315f19e1a204dd141b", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/efa7ced80688c805cf155cf22e45a8150a18954c6d7885e0d5a897d1b97696b9675715ae8d7c2187c1e5e55262c4b8ffadfb76cbfeb2407594bb33b221a1b8a2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/753bb49692aa59ad99f64f4b0511b7f08ac334b041bfd7d433be9ceed772ee83e18dcb7e549a4bb0954ab843d0dce64729990faf3d41682b26fa7682b56c3307"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-12 07:39:12.860264) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-12 07:39:12.860616) ========= 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.ICmWenTBaA/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.07pon4D15Z/Listeners TMPDIR=/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False program finished with exit code 0 elapsedTime=0.005056 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-12 07:39:12.910120) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-12 07:39:12.910469) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-12 07:39:12.910922) ========= ========= Total master_lag: 0.17 =========