builder: cedar_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0214 starttime: 1497285058.43 results: success (0) revision: 506241d8f012cfded7147ec1fc0b7f501886a8c3 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-06-12 09:30:58.433198) ========= master: http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-06-12 09:30:58.433676) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-12 09:30:58.433986) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-12 09:30:58.460591) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-12 09:30:58.460970) ========= 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.NmfLK4p76s/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.oG48nOVXTR/Listeners TMPDIR=/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-06-12 09:30:58-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 7.38M=0.002s 2017-06-12 09:30:58 (7.38 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.130442 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-12 09:30:58.617199) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-12 09:30:58.617579) ========= 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.NmfLK4p76s/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.oG48nOVXTR/Listeners TMPDIR=/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/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.021305 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-12 09:30:58.666505) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-12 09:30:58.666907) ========= bash -c 'python archiver_client.py mozharness --repo projects/cedar --rev 506241d8f012cfded7147ec1fc0b7f501886a8c3 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'python archiver_client.py mozharness --repo projects/cedar --rev 506241d8f012cfded7147ec1fc0b7f501886a8c3 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.NmfLK4p76s/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.oG48nOVXTR/Listeners TMPDIR=/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-06-12 09:30:58,751 truncating revision to first 12 chars 2017-06-12 09:30:58,751 Setting DEBUG logging. 2017-06-12 09:30:58,751 attempt 1/10 2017-06-12 09:30:58,751 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/projects/cedar/506241d8f012?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-06-12 09:30:59,408 unpacking tar archive at: cedar-506241d8f012/testing/mozharness/ program finished with exit code 0 elapsedTime=0.920421 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-12 09:30:59.612351) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-12 09:30:59.612723) ========= script_repo_revision: 506241d8f012cfded7147ec1fc0b7f501886a8c3 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-12 09:30:59.613129) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-12 09:30:59.613460) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-12 09:30:59.635390) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 21 secs) (at 2017-06-12 09:30:59.635726) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Cedar --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Cedar 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', 'Cedar', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Cedar'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.NmfLK4p76s/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.oG48nOVXTR/Listeners TMPDIR=/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/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 09:30:59 INFO - MultiFileLogger online at 20170612 09:30:59 in /builds/slave/test 09:30:59 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Cedar --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Cedar 09:30:59 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:30:59 INFO - {'append_to_log': False, 09:30:59 INFO - 'base_work_dir': '/builds/slave/test', 09:30:59 INFO - 'blob_upload_branch': 'Cedar', 09:30:59 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:30:59 INFO - 'branch': 'Cedar', 09:30:59 INFO - 'buildbot_json_path': 'buildprops.json', 09:30:59 INFO - 'config_files': ('talos/mac_config.py',), 09:30:59 INFO - 'default_actions': ('clobber', 09:30:59 INFO - 'read-buildbot-config', 09:30:59 INFO - 'download-and-extract', 09:30:59 INFO - 'populate-webroot', 09:30:59 INFO - 'create-virtualenv', 09:30:59 INFO - 'install', 09:30:59 INFO - 'setup-mitmproxy', 09:30:59 INFO - 'run-tests'), 09:30:59 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:30:59 INFO - 'download_minidump_stackwalk': True, 09:30:59 INFO - 'download_symbols': 'ondemand', 09:30:59 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:30:59 INFO - 'tooltool.py': '/tools/tooltool.py', 09:30:59 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:30:59 INFO - '/tools/misc-python/virtualenv.py')}, 09:30:59 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:30:59 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:30:59 INFO - 'gecko_profile': False, 09:30:59 INFO - 'gecko_profile_interval': 0, 09:30:59 INFO - 'installer_path': 'installer.exe', 09:30:59 INFO - 'log_level': 'info', 09:30:59 INFO - 'log_name': 'talos', 09:30:59 INFO - 'log_to_console': True, 09:30:59 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 09:30:59 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 09:30:59 INFO - 'opt_config_files': (), 09:30:59 INFO - 'pip_index': False, 09:30:59 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:30:59 INFO - 'cmd': ('bash', 09:30:59 INFO - '-c', 09:30:59 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:30:59 INFO - 'enabled': True, 09:30:59 INFO - 'halt_on_failure': False, 09:30:59 INFO - 'name': 'check_screen_resolution'},), 09:30:59 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:30:59 INFO - 'cmd': ('bash', 09:30:59 INFO - '-c', 09:30:59 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:30:59 INFO - 'enabled': True, 09:30:59 INFO - 'halt_on_failure': False, 09:30:59 INFO - 'name': 'check_screen_resolution'},), 09:30:59 INFO - 'run_cmd_checks_enabled': True, 09:30:59 INFO - 'suite': 'dromaeojs-e10s', 09:30:59 INFO - 'system_bits': '32', 09:30:59 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:30:59 INFO - 'title': 't-yosemite-r7-0214', 09:30:59 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:30:59 INFO - 'use_talos_json': True, 09:30:59 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:30:59 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:30:59 INFO - 'work_dir': 'build'} 09:30:59 INFO - [mozharness: 2017-06-12 16:30:59.839670Z] Running clobber step. 09:30:59 INFO - Running pre-action listener: _resource_record_pre_action 09:30:59 INFO - Running main action method: clobber 09:30:59 INFO - rmtree: /builds/slave/test/build 09:30:59 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:31:01 INFO - Running post-action listener: _resource_record_post_action 09:31:01 INFO - [mozharness: 2017-06-12 16:31:01.752017Z] Finished clobber step (success) 09:31:01 INFO - [mozharness: 2017-06-12 16:31:01.752214Z] Running read-buildbot-config step. 09:31:01 INFO - Running pre-action listener: _resource_record_pre_action 09:31:01 INFO - Running main action method: read_buildbot_config 09:31:01 INFO - Using buildbot properties: 09:31:01 INFO - { 09:31:01 INFO - "project": "", 09:31:01 INFO - "product": "firefox", 09:31:01 INFO - "who": "mconley@mozilla.com", 09:31:01 INFO - "installer_path": "public/build/firefox-56.0a1.en-US.mac.dmg", 09:31:01 INFO - "builddir": "cedar_yosemite_r7_test-dromaeojs-e10s", 09:31:01 INFO - "repository": "https://hg.mozilla.org/projects/cedar/", 09:31:01 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 cedar talos dromaeojs-e10s", 09:31:01 INFO - "stage_platform": "macosx64", 09:31:01 INFO - "basedir": "/builds/slave/test", 09:31:01 INFO - "buildnumber": 0, 09:31:01 INFO - "platform": "yosemite_r7", 09:31:01 INFO - "parent_task_id": "YR3vUOswSfKRSevxqb17gg", 09:31:01 INFO - "master": "http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/", 09:31:01 INFO - "slavebuilddir": "test", 09:31:01 INFO - "taskId": "BrcAjAi8R-uV78HrGKPbew", 09:31:01 INFO - "branch": "cedar", 09:31:01 INFO - "script_repo_revision": "default", 09:31:01 INFO - "revision": "506241d8f012cfded7147ec1fc0b7f501886a8c3", 09:31:01 INFO - "slavename": "t-yosemite-r7-0214", 09:31:01 INFO - "repo_path": "projects/cedar" 09:31:01 INFO - } 09:31:01 INFO - Finding installer, test and symbols from parent task. 09:31:01 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/BrcAjAi8R-uV78HrGKPbew'}, attempt #1 09:31:02 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/BrcAjAi8R-uV78HrGKPbew'}, attempt #1 09:31:03 INFO - Task dependencies: BD1VvemUT0-ghBTijLZgiw YR3vUOswSfKRSevxqb17gg 09:31:03 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/YR3vUOswSfKRSevxqb17gg'}, attempt #1 09:31:03 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/YR3vUOswSfKRSevxqb17gg/artifacts/public/build/firefox-56.0a1.en-US.mac.dmg 09:31:03 INFO - Set test_url: https://queue.taskcluster.net/v1/task/YR3vUOswSfKRSevxqb17gg/artifacts/public/build/test_packages.json 09:31:03 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/YR3vUOswSfKRSevxqb17gg/artifacts/public/build/target.crashreporter-symbols.zip 09:31:03 INFO - Running post-action listener: _resource_record_post_action 09:31:03 INFO - [mozharness: 2017-06-12 16:31:03.626453Z] Finished read-buildbot-config step (success) 09:31:03 INFO - [mozharness: 2017-06-12 16:31:03.626600Z] Running download-and-extract step. 09:31:03 INFO - Running pre-action listener: _resource_record_pre_action 09:31:03 INFO - Running main action method: download_and_extract 09:31:03 INFO - mkdir: /builds/slave/test/build/tests 09:31: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')]} 09:31:03 INFO - trying https://queue.taskcluster.net/v1/task/YR3vUOswSfKRSevxqb17gg/artifacts/public/build/firefox-56.0a1.en-US.mac.test_packages.json 09:31:03 INFO - Downloading https://queue.taskcluster.net/v1/task/YR3vUOswSfKRSevxqb17gg/artifacts/public/build/firefox-56.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-56.0a1.en-US.mac.test_packages.json 09:31:03 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/YR3vUOswSfKRSevxqb17gg/artifacts/public/build/firefox-56.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-56.0a1.en-US.mac.test_packages.json'}, attempt #1 09:31:04 INFO - Downloaded 1328 bytes. 09:31:04 INFO - Reading from file /builds/slave/test/build/firefox-56.0a1.en-US.mac.test_packages.json 09:31:04 INFO - Using the following test package requirements: 09:31:04 INFO - {u'awsy': [u'firefox-56.0a1.en-US.mac.common.tests.zip', 09:31:04 INFO - u'firefox-56.0a1.en-US.mac.awsy.tests.zip'], 09:31:04 INFO - u'common': [u'firefox-56.0a1.en-US.mac.common.tests.zip'], 09:31:04 INFO - u'cppunittest': [u'firefox-56.0a1.en-US.mac.common.tests.zip', 09:31:04 INFO - u'firefox-56.0a1.en-US.mac.cppunittest.tests.zip'], 09:31:04 INFO - u'gtest': [u'firefox-56.0a1.en-US.mac.common.tests.zip', 09:31:04 INFO - u'firefox-56.0a1.en-US.mac.gtest.tests.zip'], 09:31:04 INFO - u'jittest': [u'firefox-56.0a1.en-US.mac.common.tests.zip', 09:31:04 INFO - u'jsshell-mac.zip'], 09:31:04 INFO - u'mochitest': [u'firefox-56.0a1.en-US.mac.common.tests.zip', 09:31:04 INFO - u'firefox-56.0a1.en-US.mac.mochitest.tests.zip'], 09:31:04 INFO - u'mozbase': [u'firefox-56.0a1.en-US.mac.common.tests.zip'], 09:31:04 INFO - u'reftest': [u'firefox-56.0a1.en-US.mac.common.tests.zip', 09:31:04 INFO - u'firefox-56.0a1.en-US.mac.reftest.tests.zip'], 09:31:04 INFO - u'talos': [u'firefox-56.0a1.en-US.mac.common.tests.zip', 09:31:04 INFO - u'firefox-56.0a1.en-US.mac.talos.tests.zip'], 09:31:04 INFO - u'web-platform': [u'firefox-56.0a1.en-US.mac.common.tests.zip', 09:31:04 INFO - u'firefox-56.0a1.en-US.mac.web-platform.tests.tar.gz'], 09:31:04 INFO - u'xpcshell': [u'firefox-56.0a1.en-US.mac.common.tests.zip', 09:31:04 INFO - u'firefox-56.0a1.en-US.mac.xpcshell.tests.zip']} 09:31:04 INFO - Downloading packages: [u'firefox-56.0a1.en-US.mac.common.tests.zip'] for test suite category: common 09:31:04 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/YR3vUOswSfKRSevxqb17gg/artifacts/public/build/firefox-56.0a1.en-US.mac.common.tests.zip 09:31:04 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/YR3vUOswSfKRSevxqb17gg/artifacts/public/build/firefox-56.0a1.en-US.mac.common.tests.zip'}, attempt #1 09:31:04 INFO - Fetch https://queue.taskcluster.net/v1/task/YR3vUOswSfKRSevxqb17gg/artifacts/public/build/firefox-56.0a1.en-US.mac.common.tests.zip into memory 09:31:06 INFO - Content-Length response header: 59540477 09:31:06 INFO - Bytes received: 59540477 09:31:24 INFO - Downloading packages: [u'firefox-56.0a1.en-US.mac.common.tests.zip', u'firefox-56.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 09:31:24 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/YR3vUOswSfKRSevxqb17gg/artifacts/public/build/firefox-56.0a1.en-US.mac.common.tests.zip 09:31:24 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/YR3vUOswSfKRSevxqb17gg/artifacts/public/build/firefox-56.0a1.en-US.mac.common.tests.zip'}, attempt #1 09:31:24 INFO - Fetch https://queue.taskcluster.net/v1/task/YR3vUOswSfKRSevxqb17gg/artifacts/public/build/firefox-56.0a1.en-US.mac.common.tests.zip into memory 09:31:26 INFO - Content-Length response header: 59540477 09:31:26 INFO - Bytes received: 59540477 09:31:43 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/YR3vUOswSfKRSevxqb17gg/artifacts/public/build/firefox-56.0a1.en-US.mac.talos.tests.zip 09:31:43 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/YR3vUOswSfKRSevxqb17gg/artifacts/public/build/firefox-56.0a1.en-US.mac.talos.tests.zip'}, attempt #1 09:31:43 INFO - Fetch https://queue.taskcluster.net/v1/task/YR3vUOswSfKRSevxqb17gg/artifacts/public/build/firefox-56.0a1.en-US.mac.talos.tests.zip into memory 09:31:45 INFO - Content-Length response header: 14450010 09:31:45 INFO - Bytes received: 14450010 09:31:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:31:45 INFO - trying https://queue.taskcluster.net/v1/task/YR3vUOswSfKRSevxqb17gg/artifacts/public/build/firefox-56.0a1.en-US.mac.dmg 09:31:45 INFO - Downloading https://queue.taskcluster.net/v1/task/YR3vUOswSfKRSevxqb17gg/artifacts/public/build/firefox-56.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-56.0a1.en-US.mac.dmg 09:31:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/YR3vUOswSfKRSevxqb17gg/artifacts/public/build/firefox-56.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-56.0a1.en-US.mac.dmg'}, attempt #1 09:31:47 INFO - Downloaded 69305316 bytes. 09:31:47 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/YR3vUOswSfKRSevxqb17gg/artifacts/public/build/firefox-56.0a1.en-US.mac.dmg 09:31:47 INFO - mkdir: /builds/slave/test/properties 09:31:47 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:31:47 INFO - Writing to file /builds/slave/test/properties/build_url 09:31:47 INFO - Contents: 09:31:47 INFO - build_url:https://queue.taskcluster.net/v1/task/YR3vUOswSfKRSevxqb17gg/artifacts/public/build/firefox-56.0a1.en-US.mac.dmg 09:31:47 INFO - Running post-action listener: _resource_record_post_action 09:31:47 INFO - Running post-action listener: set_extra_try_arguments 09:31:47 INFO - [mozharness: 2017-06-12 16:31:47.388805Z] Finished download-and-extract step (success) 09:31:47 INFO - [mozharness: 2017-06-12 16:31:47.388967Z] Running populate-webroot step. 09:31:47 INFO - Running pre-action listener: _resource_record_pre_action 09:31:47 INFO - Running main action method: populate_webroot 09:31:47 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:31:47 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:31:47 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:31:47 INFO - '--tppagecycles', 09:31:47 INFO - '7'], 09:31:47 INFO - 'tests': ['tsvgm']}}, 09:31:47 INFO - 'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:31:47 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:31:47 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:31:47 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:31:47 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 09:31:47 INFO - 'talos_options': ['--disable-e10s'], 09:31:47 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:31:47 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 09:31:47 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:31:47 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 09:31:47 INFO - 'talos_options': ['--disable-e10s'], 09:31:47 INFO - 'tests': ['damp', 'tps']}, 09:31:47 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 09:31:47 INFO - 'tests': ['damp', 'tps']}, 09:31:47 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:31:47 INFO - 'tests': ['dromaeo_dom']}, 09:31:47 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:31:47 INFO - 'g4': {'talos_options': ['--disable-e10s'], 09:31:47 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 09:31:47 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 09:31:47 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 09:31:47 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 09:31:47 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:31:47 INFO - 'tests': ['a11yr', 09:31:47 INFO - 'ts_paint', 09:31:47 INFO - 'tpaint', 09:31:47 INFO - 'sessionrestore', 09:31:47 INFO - 'sessionrestore_no_auto_restore', 09:31:47 INFO - 'tabpaint']}, 09:31:47 INFO - 'other-e10s': {'tests': ['a11yr', 09:31:47 INFO - 'ts_paint', 09:31:47 INFO - 'tpaint', 09:31:47 INFO - 'sessionrestore', 09:31:47 INFO - 'sessionrestore_no_auto_restore', 09:31:47 INFO - 'tabpaint']}, 09:31:47 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:31:47 INFO - 'ts_paint', 09:31:47 INFO - 'tpaint', 09:31:47 INFO - 'sessionrestore', 09:31:47 INFO - 'sessionrestore_no_auto_restore', 09:31:47 INFO - 'tabpaint']}, 09:31:47 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:31:47 INFO - 'ts_paint', 09:31:47 INFO - 'tpaint', 09:31:47 INFO - 'sessionrestore', 09:31:47 INFO - 'sessionrestore_no_auto_restore', 09:31:47 INFO - 'tabpaint']}, 09:31:47 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:31:47 INFO - 'tests': ['a11yr', 09:31:47 INFO - 'ts_paint', 09:31:47 INFO - 'tpaint', 09:31:47 INFO - 'sessionrestore', 09:31:47 INFO - 'sessionrestore_no_auto_restore', 09:31:47 INFO - 'tabpaint']}, 09:31:47 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:31:47 INFO - 'tests': ['a11yr', 09:31:47 INFO - 'ts_paint', 09:31:47 INFO - 'tpaint', 09:31:47 INFO - 'sessionrestore', 09:31:47 INFO - 'sessionrestore_no_auto_restore', 09:31:47 INFO - 'tabpaint']}, 09:31:47 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 09:31:47 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 09:31:47 INFO - 'bloom_basic_ref']}, 09:31:47 INFO - 'quantum-pageload-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 09:31:47 INFO - 'talos_options': ['--mitmproxy', 09:31:47 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 09:31:47 INFO - '--firstNonBlankPaint'], 09:31:47 INFO - 'tests': ['quantum_pageload_google', 09:31:47 INFO - 'quantum_pageload_youtube', 09:31:47 INFO - 'quantum_pageload_amazon', 09:31:47 INFO - 'quantum_pageload_facebook']}, 09:31:47 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:31:47 INFO - 'tests': ['tsvgx', 09:31:47 INFO - 'tsvgr_opacity', 09:31:47 INFO - 'tart', 09:31:47 INFO - 'tscrollx', 09:31:47 INFO - 'cart', 09:31:47 INFO - 'tsvg_static']}, 09:31:47 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:31:47 INFO - 'tsvgr_opacity', 09:31:47 INFO - 'tart', 09:31:47 INFO - 'tscrollx', 09:31:47 INFO - 'cart', 09:31:47 INFO - 'tsvg_static']}, 09:31:47 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 09:31:47 INFO - 'talos_options': ['--disable-e10s'], 09:31:47 INFO - 'tests': ['tp5o']}, 09:31:47 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 09:31:47 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 09:31:47 INFO - 'talos_options': ['--disable-e10s', 09:31:47 INFO - '--xperf_path', 09:31:47 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:31:47 INFO - 'tests': ['tp5n']}, 09:31:47 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 09:31:47 INFO - 'talos_options': ['--xperf_path', 09:31:47 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:31:47 INFO - 'tests': ['tp5n']}}, 09:31:47 INFO - 'talos.zip': {'path': '', 09:31:47 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:31:47 INFO - Running post-action listener: _resource_record_post_action 09:31:47 INFO - [mozharness: 2017-06-12 16:31:47.411511Z] Finished populate-webroot step (success) 09:31:47 INFO - [mozharness: 2017-06-12 16:31:47.411617Z] Running create-virtualenv step. 09:31:47 INFO - Running pre-action listener: _resource_record_pre_action 09:31:47 INFO - Running main action method: create_virtualenv 09:31:47 INFO - Creating virtualenv /builds/slave/test/build/venv 09:31:47 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 09:31:47 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:31:47 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 09:31:47 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:31:47 INFO - Using real prefix '/tools/python27' 09:31:47 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:31:48 INFO - Installing distribute.............................................................................................................................................................................................done. 09:31:51 INFO - Installing pip.................done. 09:31:51 INFO - Return code: 0 09:31:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 09:31:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 09:31:51 INFO - Reading from file tmpfile_stdout 09:31:51 INFO - Output received: 09:31:51 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) 09:31:51 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:31:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:31:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:31:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:31:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:31:51 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10c6b9c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c6bd768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcab8db4db0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c40fd30>, '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 0x10c3d9cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c400be8>, '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.NmfLK4p76s/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.oG48nOVXTR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/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 09:31:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 09:31:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 09:31:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NmfLK4p76s/Render', 09:31:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:31:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:31:51 INFO - 'HOME': '/Users/cltbld', 09:31:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:31:51 INFO - 'LOGNAME': 'cltbld', 09:31:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:31:51 INFO - 'MOZ_NO_REMOTE': '1', 09:31:51 INFO - 'NO_EM_RESTART': '1', 09:31:51 INFO - 'PAGER': '/bin/cat', 09:31:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:31:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:31:51 INFO - 'PWD': '/builds/slave/test', 09:31:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:31:51 INFO - 'SHELL': '/bin/bash', 09:31:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.oG48nOVXTR/Listeners', 09:31:51 INFO - 'TMPDIR': '/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/T/', 09:31:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:31:51 INFO - 'USER': 'cltbld', 09:31:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:31:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:31:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:31:51 INFO - 'XPC_FLAGS': '0x0', 09:31:51 INFO - 'XPC_SERVICE_NAME': '0', 09:31:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:31:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:31:51 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 09:31:51 INFO - Cleaning up... 09:31:51 INFO - Return code: 0 09:31:51 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:31:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:31:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:31:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:31:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:31:51 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10c6b9c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c6bd768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcab8db4db0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c40fd30>, '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 0x10c3d9cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c400be8>, '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.NmfLK4p76s/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.oG48nOVXTR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/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 09:31:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 09:31:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 09:31:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NmfLK4p76s/Render', 09:31:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:31:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:31:51 INFO - 'HOME': '/Users/cltbld', 09:31:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:31:51 INFO - 'LOGNAME': 'cltbld', 09:31:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:31:51 INFO - 'MOZ_NO_REMOTE': '1', 09:31:51 INFO - 'NO_EM_RESTART': '1', 09:31:51 INFO - 'PAGER': '/bin/cat', 09:31:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:31:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:31:51 INFO - 'PWD': '/builds/slave/test', 09:31:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:31:51 INFO - 'SHELL': '/bin/bash', 09:31:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.oG48nOVXTR/Listeners', 09:31:51 INFO - 'TMPDIR': '/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/T/', 09:31:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:31:51 INFO - 'USER': 'cltbld', 09:31:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:31:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:31:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:31:51 INFO - 'XPC_FLAGS': '0x0', 09:31:51 INFO - 'XPC_SERVICE_NAME': '0', 09:31:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:31:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:31:51 INFO - Downloading/unpacking psutil>=3.1.1 09:31:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:31:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:31:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:31:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:31:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:31:53 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:31:53 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:31:53 INFO - Installing collected packages: psutil 09:31:53 INFO - Running setup.py install for psutil 09:31:54 INFO - building 'psutil._psutil_osx' extension 09:31:54 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 09:31:54 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 09:31:54 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 09:31:54 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 09:31:54 INFO - building 'psutil._psutil_posix' extension 09:31:54 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 09:31:54 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 09:31:54 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 09:31:54 INFO - ^ 09:31:54 INFO - 1 warning generated. 09:31:54 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 09:31:54 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:31:54 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:31:54 INFO - Successfully installed psutil 09:31:54 INFO - Cleaning up... 09:31:54 INFO - Return code: 0 09:31:54 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:31: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')]} 09:31:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:31:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:31:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:31: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', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10c6b9c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c6bd768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcab8db4db0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c40fd30>, '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 0x10c3d9cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c400be8>, '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.NmfLK4p76s/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.oG48nOVXTR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/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 09:31: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', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 09:31: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 mozsystemmonitor==0.3 09:31:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NmfLK4p76s/Render', 09:31:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:31:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:31:55 INFO - 'HOME': '/Users/cltbld', 09:31:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:31:55 INFO - 'LOGNAME': 'cltbld', 09:31:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:31:55 INFO - 'MOZ_NO_REMOTE': '1', 09:31:55 INFO - 'NO_EM_RESTART': '1', 09:31:55 INFO - 'PAGER': '/bin/cat', 09:31:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:31:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:31:55 INFO - 'PWD': '/builds/slave/test', 09:31:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:31:55 INFO - 'SHELL': '/bin/bash', 09:31:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.oG48nOVXTR/Listeners', 09:31:55 INFO - 'TMPDIR': '/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/T/', 09:31:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:31:55 INFO - 'USER': 'cltbld', 09:31:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:31:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:31:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:31:55 INFO - 'XPC_FLAGS': '0x0', 09:31:55 INFO - 'XPC_SERVICE_NAME': '0', 09:31:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:31:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:31:55 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:31: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 09:31: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 09:31: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 09:31: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 09:31:57 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:31:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:31:57 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:31:57 INFO - Installing collected packages: mozsystemmonitor 09:31:57 INFO - Running setup.py install for mozsystemmonitor 09:31:58 INFO - Successfully installed mozsystemmonitor 09:31:58 INFO - Cleaning up... 09:31:58 INFO - Return code: 0 09:31:58 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:31: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')]} 09:31:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:31: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')]} 09:31:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:31: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', '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 0x10c6b9c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c6bd768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcab8db4db0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c40fd30>, '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 0x10c3d9cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c400be8>, '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.NmfLK4p76s/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.oG48nOVXTR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/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 09:31: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', 'jsonschema==2.5.1'] in /builds/slave/test/build 09:31: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 jsonschema==2.5.1 09:31:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NmfLK4p76s/Render', 09:31:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:31:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:31:58 INFO - 'HOME': '/Users/cltbld', 09:31:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:31:58 INFO - 'LOGNAME': 'cltbld', 09:31:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:31:58 INFO - 'MOZ_NO_REMOTE': '1', 09:31:58 INFO - 'NO_EM_RESTART': '1', 09:31:58 INFO - 'PAGER': '/bin/cat', 09:31:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:31:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:31:58 INFO - 'PWD': '/builds/slave/test', 09:31:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:31:58 INFO - 'SHELL': '/bin/bash', 09:31:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.oG48nOVXTR/Listeners', 09:31:58 INFO - 'TMPDIR': '/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/T/', 09:31:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:31:58 INFO - 'USER': 'cltbld', 09:31:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:31:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:31:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:31:58 INFO - 'XPC_FLAGS': '0x0', 09:31:58 INFO - 'XPC_SERVICE_NAME': '0', 09:31:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:31:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:31:58 INFO - Downloading/unpacking jsonschema==2.5.1 09:31: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 09:31: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 09:31: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 09:31: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 09:31:59 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:31:59 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:31: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 09:31: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 09:31: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 09:31: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 09:31:59 INFO - Downloading functools32-3.2.3-2.tar.gz 09:31:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:32:00 INFO - warning: no files found matching '*.txt' 09:32:00 INFO - no previously-included directories found matching 'build' 09:32:00 INFO - no previously-included directories found matching 'dist' 09:32:00 INFO - no previously-included directories found matching '.git*' 09:32:00 INFO - Installing collected packages: jsonschema, functools32 09:32:00 INFO - Running setup.py install for functools32 09:32:00 INFO - warning: no files found matching '*.txt' 09:32:00 INFO - no previously-included directories found matching 'build' 09:32:00 INFO - no previously-included directories found matching 'dist' 09:32:00 INFO - no previously-included directories found matching '.git*' 09:32:00 INFO - Successfully installed jsonschema functools32 09:32:00 INFO - Cleaning up... 09:32:00 INFO - Return code: 0 09:32:00 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 09:32:00 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')]} 09:32:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:32:00 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')]} 09:32:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:32:00 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 0x10c6b9c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c6bd768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcab8db4db0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c40fd30>, '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 0x10c3d9cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c400be8>, '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.NmfLK4p76s/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.oG48nOVXTR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/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 09:32:00 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 09:32:00 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 09:32:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NmfLK4p76s/Render', 09:32:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:32:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:32:00 INFO - 'HOME': '/Users/cltbld', 09:32:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:32:00 INFO - 'LOGNAME': 'cltbld', 09:32:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:32:00 INFO - 'MOZ_NO_REMOTE': '1', 09:32:00 INFO - 'NO_EM_RESTART': '1', 09:32:00 INFO - 'PAGER': '/bin/cat', 09:32:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:32:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:32:00 INFO - 'PWD': '/builds/slave/test', 09:32:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:32:00 INFO - 'SHELL': '/bin/bash', 09:32:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.oG48nOVXTR/Listeners', 09:32:00 INFO - 'TMPDIR': '/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/T/', 09:32:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:32:00 INFO - 'USER': 'cltbld', 09:32:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:32:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:32:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:32:00 INFO - 'XPC_FLAGS': '0x0', 09:32:00 INFO - 'XPC_SERVICE_NAME': '0', 09:32:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:32:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:32:00 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 09:32:00 INFO - Cleaning up... 09:32:00 INFO - Return code: 0 09:32:00 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:32:00 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')]} 09:32:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:32:00 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')]} 09:32:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:32:00 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 0x10c6b9c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c6bd768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcab8db4db0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c40fd30>, '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 0x10c3d9cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c400be8>, '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.NmfLK4p76s/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.oG48nOVXTR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/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 09:32:00 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 09:32:00 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 09:32:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NmfLK4p76s/Render', 09:32:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:32:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:32:00 INFO - 'HOME': '/Users/cltbld', 09:32:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:32:00 INFO - 'LOGNAME': 'cltbld', 09:32:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:32:00 INFO - 'MOZ_NO_REMOTE': '1', 09:32:00 INFO - 'NO_EM_RESTART': '1', 09:32:00 INFO - 'PAGER': '/bin/cat', 09:32:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:32:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:32:00 INFO - 'PWD': '/builds/slave/test', 09:32:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:32:00 INFO - 'SHELL': '/bin/bash', 09:32:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.oG48nOVXTR/Listeners', 09:32:00 INFO - 'TMPDIR': '/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/T/', 09:32:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:32:00 INFO - 'USER': 'cltbld', 09:32:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:32:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:32:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:32:00 INFO - 'XPC_FLAGS': '0x0', 09:32:00 INFO - 'XPC_SERVICE_NAME': '0', 09:32:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:32:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:32:00 INFO - Downloading/unpacking blobuploader==1.2.4 09:32: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 09:32: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 09:32: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 09:32: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 09:32:02 INFO - Downloading blobuploader-1.2.4.tar.gz 09:32:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:32:02 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:32:02 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 09:32:02 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 09:32:02 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 09:32:02 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 09:32:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:32:02 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:32:02 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 09:32:02 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 09:32:02 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 09:32:02 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 09:32:03 INFO - Downloading docopt-0.6.1.tar.gz 09:32:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:32:03 INFO - Installing collected packages: blobuploader, requests, docopt 09:32:03 INFO - Running setup.py install for blobuploader 09:32:03 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:32:03 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:32:03 INFO - Running setup.py install for requests 09:32:03 INFO - Running setup.py install for docopt 09:32:03 INFO - Successfully installed blobuploader requests docopt 09:32:03 INFO - Cleaning up... 09:32:04 INFO - Return code: 0 09:32:04 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:32:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:32:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:32:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:32:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:32:04 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 0x10c6b9c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c6bd768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcab8db4db0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c40fd30>, '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 0x10c3d9cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c400be8>, '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.NmfLK4p76s/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.oG48nOVXTR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/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 09:32:04 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 09:32:04 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 09:32:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NmfLK4p76s/Render', 09:32:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:32:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:32:04 INFO - 'HOME': '/Users/cltbld', 09:32:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:32:04 INFO - 'LOGNAME': 'cltbld', 09:32:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:32:04 INFO - 'MOZ_NO_REMOTE': '1', 09:32:04 INFO - 'NO_EM_RESTART': '1', 09:32:04 INFO - 'PAGER': '/bin/cat', 09:32:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:32:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:32:04 INFO - 'PWD': '/builds/slave/test', 09:32:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:32:04 INFO - 'SHELL': '/bin/bash', 09:32:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.oG48nOVXTR/Listeners', 09:32:04 INFO - 'TMPDIR': '/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/T/', 09:32:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:32:04 INFO - 'USER': 'cltbld', 09:32:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:32:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:32:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:32:04 INFO - 'XPC_FLAGS': '0x0', 09:32:04 INFO - 'XPC_SERVICE_NAME': '0', 09:32:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:32:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:32:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:32:04 INFO - Running setup.py (path:/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/T/pip-UrqVKo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:32:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:32:04 INFO - Running setup.py (path:/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/T/pip-uZrols-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:32:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:32:04 INFO - Running setup.py (path:/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/T/pip-uFThyu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:32:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:32:04 INFO - Running setup.py (path:/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/T/pip-j_Cwkq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:32:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:32:04 INFO - Running setup.py (path:/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/T/pip-nmDagY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:32:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:32:04 INFO - Running setup.py (path:/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/T/pip-vN7Qxn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:32:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:32:04 INFO - Running setup.py (path:/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/T/pip-YXc6mH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:32:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:32:05 INFO - Running setup.py (path:/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/T/pip-bhuFBv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:32:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:32:05 INFO - Running setup.py (path:/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/T/pip-7jPXI7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:32:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:32:05 INFO - Running setup.py (path:/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/T/pip-fzBkW4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:32:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:32:05 INFO - Running setup.py (path:/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/T/pip-PEk4B4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:32:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:32:05 INFO - Running setup.py (path:/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/T/pip-Dhc75B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:32:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:32:05 INFO - Running setup.py (path:/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/T/pip-KZBtxm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:32:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:32:05 INFO - Running setup.py (path:/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/T/pip-SSFxc1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:32:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:32:05 INFO - Running setup.py (path:/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/T/pip-UDNRyI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:32:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:32:05 INFO - Running setup.py (path:/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/T/pip-Fa5mPd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:32:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:32:05 INFO - Running setup.py (path:/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/T/pip-HzL9Bl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:32:06 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:32:06 INFO - Running setup.py install for manifestparser 09:32:06 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:32:06 INFO - Running setup.py install for mozcrash 09:32:06 INFO - Running setup.py install for mozdebug 09:32:06 INFO - Running setup.py install for mozdevice 09:32:06 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:32:06 INFO - Running setup.py install for mozfile 09:32:06 INFO - Running setup.py install for mozhttpd 09:32:06 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:32:06 INFO - Running setup.py install for mozinfo 09:32:07 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:32:07 INFO - Running setup.py install for mozInstall 09:32:07 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:32:07 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:32:07 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:32:07 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:32:07 INFO - Running setup.py install for mozleak 09:32:07 INFO - Running setup.py install for mozlog 09:32:07 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:32:07 INFO - Running setup.py install for moznetwork 09:32:07 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:32:07 INFO - Running setup.py install for mozprocess 09:32:07 INFO - Running setup.py install for mozprofile 09:32:08 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:32:08 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:32:08 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:32:08 INFO - Running setup.py install for mozrunner 09:32:08 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:32:08 INFO - Running setup.py install for mozscreenshot 09:32:08 INFO - Running setup.py install for moztest 09:32:08 INFO - Running setup.py install for mozversion 09:32:08 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:32:08 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:32:08 INFO - Cleaning up... 09:32:08 INFO - Return code: 0 09:32:08 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:32:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:32:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:32:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:32:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:32:08 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 0x10c6b9c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c6bd768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcab8db4db0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c40fd30>, '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 0x10c3d9cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c400be8>, '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.NmfLK4p76s/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.oG48nOVXTR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/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 09:32:08 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 09:32:08 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 09:32:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NmfLK4p76s/Render', 09:32:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:32:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:32:08 INFO - 'HOME': '/Users/cltbld', 09:32:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:32:08 INFO - 'LOGNAME': 'cltbld', 09:32:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:32:08 INFO - 'MOZ_NO_REMOTE': '1', 09:32:08 INFO - 'NO_EM_RESTART': '1', 09:32:08 INFO - 'PAGER': '/bin/cat', 09:32:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:32:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:32:08 INFO - 'PWD': '/builds/slave/test', 09:32:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:32:08 INFO - 'SHELL': '/bin/bash', 09:32:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.oG48nOVXTR/Listeners', 09:32:08 INFO - 'TMPDIR': '/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/T/', 09:32:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:32:08 INFO - 'USER': 'cltbld', 09:32:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:32:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:32:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:32:08 INFO - 'XPC_FLAGS': '0x0', 09:32:08 INFO - 'XPC_SERVICE_NAME': '0', 09:32:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:32:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:32:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:32:08 INFO - Running setup.py (path:/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/T/pip-m1kxbL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:32:09 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)) 09:32:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:32:09 INFO - Running setup.py (path:/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/T/pip-7l8aG7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:32:09 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)) 09:32:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:32:09 INFO - Running setup.py (path:/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/T/pip-6iAExI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:32:09 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)) 09:32:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:32:09 INFO - Running setup.py (path:/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/T/pip-k4hPXP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:32:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.51 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:32:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:32:09 INFO - Running setup.py (path:/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/T/pip-9_vdBV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:32:09 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)) 09:32:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:32:09 INFO - Running setup.py (path:/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/T/pip-zAYyTO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:32:09 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)) 09:32:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:32:09 INFO - Running setup.py (path:/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/T/pip-I4SYHg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:32:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.10 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)) 09:32:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:32:09 INFO - Running setup.py (path:/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/T/pip-_aNC80-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:32:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.13 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)) 09:32:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:32:09 INFO - Running setup.py (path:/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/T/pip-sIXkE1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:32:09 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)) 09:32:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:32:09 INFO - Running setup.py (path:/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/T/pip-AbguuX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:32:10 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)) 09:32:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:32:10 INFO - Running setup.py (path:/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/T/pip-T5nhwe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:32:10 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)) 09:32:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:32:10 INFO - Running setup.py (path:/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/T/pip-nOTY3S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:32:10 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)) 09:32:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:32:10 INFO - Running setup.py (path:/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/T/pip-FUeefy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:32:10 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)) 09:32:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:32:10 INFO - Running setup.py (path:/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/T/pip-ENTI35-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:32:10 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)) 09:32:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:32:10 INFO - Running setup.py (path:/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/T/pip-_Eb2Xb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:32:10 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)) 09:32:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:32:10 INFO - Running setup.py (path:/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/T/pip-JkoXzz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:32:10 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)) 09:32:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:32:10 INFO - Running setup.py (path:/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/T/pip-lW4WiN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:32:10 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)) 09:32:10 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)) 09:32:10 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)) 09:32:10 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)) 09:32:10 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:32:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:32:10 INFO - Requirement already satisfied (use --upgrade to upgrade): requests in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozInstall==1.13->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 09:32:10 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:32:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:32:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:32:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:32:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:32:12 INFO - Downloading blessings-1.6.tar.gz 09:32:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:32:12 INFO - Installing collected packages: blessings 09:32:12 INFO - Running setup.py install for blessings 09:32:12 INFO - Successfully installed blessings 09:32:12 INFO - Cleaning up... 09:32:12 INFO - Return code: 0 09:32:12 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:32:12 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:32:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:32:12 INFO - Reading from file tmpfile_stdout 09:32:12 INFO - Current package versions: 09:32:12 INFO - blessings == 1.6 09:32:12 INFO - blobuploader == 1.2.4 09:32:12 INFO - docopt == 0.6.1 09:32:12 INFO - functools32 == 3.2.3-2 09:32:12 INFO - jsonschema == 2.5.1 09:32:12 INFO - manifestparser == 1.1 09:32:12 INFO - mozInstall == 1.13 09:32:12 INFO - mozcrash == 1.0 09:32:12 INFO - mozdebug == 0.1 09:32:12 INFO - mozdevice == 0.51 09:32:12 INFO - mozfile == 1.2 09:32:12 INFO - mozhttpd == 0.7 09:32:12 INFO - mozinfo == 0.10 09:32:12 INFO - mozleak == 0.1 09:32:12 INFO - mozlog == 3.4 09:32:12 INFO - moznetwork == 0.27 09:32:12 INFO - mozprocess == 0.25 09:32:12 INFO - mozprofile == 0.28 09:32:12 INFO - mozrunner == 6.13 09:32:12 INFO - mozscreenshot == 0.1 09:32:12 INFO - mozsystemmonitor == 0.3 09:32:12 INFO - moztest == 0.8 09:32:12 INFO - mozversion == 1.4 09:32:12 INFO - psutil == 3.1.1 09:32:12 INFO - requests == 1.2.3 09:32:12 INFO - wsgiref == 0.1.2 09:32:12 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:32:12 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')]} 09:32:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:32:12 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')]} 09:32:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:32:12 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 0x10c6b9c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c6bd768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcab8db4db0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c40fd30>, '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 0x10c3d9cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c400be8>, '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.NmfLK4p76s/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.oG48nOVXTR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/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 09:32:12 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 09:32:12 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 09:32:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NmfLK4p76s/Render', 09:32:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:32:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:32:12 INFO - 'HOME': '/Users/cltbld', 09:32:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:32:12 INFO - 'LOGNAME': 'cltbld', 09:32:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:32:12 INFO - 'MOZ_NO_REMOTE': '1', 09:32:12 INFO - 'NO_EM_RESTART': '1', 09:32:12 INFO - 'PAGER': '/bin/cat', 09:32:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:32:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:32:12 INFO - 'PWD': '/builds/slave/test', 09:32:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:32:12 INFO - 'SHELL': '/bin/bash', 09:32:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.oG48nOVXTR/Listeners', 09:32:12 INFO - 'TMPDIR': '/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/T/', 09:32:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:32:12 INFO - 'USER': 'cltbld', 09:32:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:32:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:32:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:32:12 INFO - 'XPC_FLAGS': '0x0', 09:32:12 INFO - 'XPC_SERVICE_NAME': '0', 09:32:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:32:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:32:13 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)) 09:32:13 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)) 09:32:13 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)) 09:32:13 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)) 09:32:13 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)) 09:32:13 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)) 09:32:13 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)) 09:32:13 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)) 09:32:13 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)) 09:32:13 INFO - Downloading/unpacking marionette-driver>=1.4.0 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 09:32:13 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 09:32:13 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 09:32:13 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 09:32:13 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 09:32:14 INFO - Downloading marionette_driver-1.4.0.tar.gz 09:32:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/marionette-driver/setup.py) egg_info for package marionette-driver 09:32:14 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)) 09:32:14 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)) 09:32:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner>=6.9 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from marionette-driver>=1.4.0->-r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 09:32:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice>=0.37 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozrunner>=6.9->marionette-driver>=1.4.0->-r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 09:32:14 INFO - Installing collected packages: marionette-driver 09:32:14 INFO - Running setup.py install for marionette-driver 09:32:14 INFO - Successfully installed marionette-driver 09:32:14 INFO - Cleaning up... 09:32:14 INFO - Return code: 0 09:32:14 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:32:14 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')]} 09:32:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:32:14 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')]} 09:32:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:32:14 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 0x10c6b9c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c6bd768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcab8db4db0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c40fd30>, '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 0x10c3d9cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c400be8>, '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.NmfLK4p76s/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.oG48nOVXTR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/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 09:32:14 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 09:32:14 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 09:32:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NmfLK4p76s/Render', 09:32:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:32:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:32:14 INFO - 'HOME': '/Users/cltbld', 09:32:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:32:14 INFO - 'LOGNAME': 'cltbld', 09:32:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:32:14 INFO - 'MOZ_NO_REMOTE': '1', 09:32:14 INFO - 'NO_EM_RESTART': '1', 09:32:14 INFO - 'PAGER': '/bin/cat', 09:32:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:32:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:32:14 INFO - 'PWD': '/builds/slave/test', 09:32:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:32:14 INFO - 'SHELL': '/bin/bash', 09:32:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.oG48nOVXTR/Listeners', 09:32:14 INFO - 'TMPDIR': '/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/T/', 09:32:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:32:14 INFO - 'USER': 'cltbld', 09:32:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:32:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:32:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:32:14 INFO - 'XPC_FLAGS': '0x0', 09:32:14 INFO - 'XPC_SERVICE_NAME': '0', 09:32:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:32:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:32:14 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:32:14 INFO - Cleaning up... 09:32:15 INFO - Return code: 0 09:32:15 INFO - Running post-action listener: _resource_record_post_action 09:32:15 INFO - Running post-action listener: _start_resource_monitoring 09:32:15 INFO - Starting resource monitoring. 09:32:15 INFO - [mozharness: 2017-06-12 16:32:15.038067Z] Finished create-virtualenv step (success) 09:32:15 INFO - [mozharness: 2017-06-12 16:32:15.039108Z] Running install step. 09:32:15 INFO - Running pre-action listener: _resource_record_pre_action 09:32:15 INFO - Running main action method: install 09:32:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:32:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:32:15 INFO - Reading from file tmpfile_stdout 09:32:15 INFO - Detecting whether we're running mozinstall >=1.0... 09:32:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:32:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:32:15 INFO - Reading from file tmpfile_stdout 09:32:15 INFO - Output received: 09:32:15 INFO - Usage: mozinstall [options] installer 09:32:15 INFO - Options: 09:32:15 INFO - -h, --help show this help message and exit 09:32:15 INFO - -d DEST, --destination=DEST 09:32:15 INFO - Directory to install application into. [default: 09:32:15 INFO - "/builds/slave/test"] 09:32:15 INFO - --app=APP Application being installed. [default: firefox] 09:32:15 INFO - mkdir: /builds/slave/test/build/application 09:32:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-56.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 09:32:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-56.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 09:32:37 INFO - Reading from file tmpfile_stdout 09:32:37 INFO - Output received: 09:32:37 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 09:32:37 INFO - Running post-action listener: _resource_record_post_action 09:32:37 INFO - [mozharness: 2017-06-12 16:32:37.483508Z] Finished install step (success) 09:32:37 INFO - [mozharness: 2017-06-12 16:32:37.483657Z] Running setup-mitmproxy step. 09:32:37 INFO - Running pre-action listener: _resource_record_pre_action 09:32:37 INFO - Running main action method: setup_mitmproxy 09:32:37 INFO - Skipping: mitmproxy is not required 09:32:37 INFO - Running post-action listener: _resource_record_post_action 09:32:37 INFO - [mozharness: 2017-06-12 16:32:37.484432Z] Finished setup-mitmproxy step (success) 09:32:37 INFO - [mozharness: 2017-06-12 16:32:37.484577Z] Running run-tests step. 09:32:37 INFO - Running pre-action listener: _resource_record_pre_action 09:32:37 INFO - Running main action method: run_tests 09:32:37 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:32:37 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:32:37 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:32:37 INFO - 2017-06-12 09:32:37.499 screenresolution[2415:26357] starting screenresolution argv=screenresolution get 09:32:37 INFO - 2017-06-12 09:32:37.501 screenresolution[2415:26357] Display 0: 1600x1200x32@60 09:32:37 INFO - 2017-06-12 09:32:37.507 screenresolution[2416:26359] starting screenresolution argv=screenresolution list 09:32:37 INFO - Available Modes on Display 0 09:32:37 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:32:37 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:32:37 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:32:37 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:32:37 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:32:37 INFO - Intel Iris: 09:32:37 INFO - Chipset Model: Intel Iris 09:32:37 INFO - Type: GPU 09:32:37 INFO - Bus: Built-In 09:32:37 INFO - VRAM (Dynamic, Max): 1536 MB 09:32:37 INFO - Vendor: Intel (0x8086) 09:32:37 INFO - Device ID: 0x0a2e 09:32:37 INFO - Revision ID: 0x0009 09:32:37 INFO - Displays: 09:32:37 INFO - Display: 09:32:37 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:32:37 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:32:37 INFO - Display Serial Number: 1600x1200 60 09:32:37 INFO - Main Display: Yes 09:32:37 INFO - Mirror: Off 09:32:37 INFO - Online: Yes 09:32:37 INFO - Rotation: Supported 09:32:37 INFO - Return code: 0 09:32:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:32:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:32:37 INFO - Python 2.7.3 09:32:37 INFO - Return code: 0 09:32:37 INFO - grabbing minidump binary from tooltool 09:32:37 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')]} 09:32:37 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 0x10c40fd30>, '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 0x10c3d9cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c400be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:32:37 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 09:32:37 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 09:32:37 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 09:32:37 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:32:37 INFO - Return code: 0 09:32:37 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 09:32:37 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:32:37 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:32:37 INFO - ENV: RUST_BACKTRACE is now 1 09:32:37 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 09:32:37 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:32:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Cedar', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/YR3vUOswSfKRSevxqb17gg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0214', '--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 09:32:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Cedar --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/YR3vUOswSfKRSevxqb17gg/artifacts/public/build/target.crashreporter-symbols.zip --title t-yosemite-r7-0214 --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 09:32:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NmfLK4p76s/Render', 09:32:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:32:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:32:37 INFO - 'HOME': '/Users/cltbld', 09:32:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:32:37 INFO - 'LOGNAME': 'cltbld', 09:32:37 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:32:37 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 09:32:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:32:37 INFO - 'MOZ_NO_REMOTE': '1', 09:32:37 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:32:37 INFO - 'NO_EM_RESTART': '1', 09:32:37 INFO - 'PAGER': '/bin/cat', 09:32:37 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:32:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:32:37 INFO - 'PWD': '/builds/slave/test', 09:32:37 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:32:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:32:37 INFO - 'RUST_BACKTRACE': '1', 09:32:37 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 09:32:37 INFO - 'SHELL': '/bin/bash', 09:32:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.oG48nOVXTR/Listeners', 09:32:37 INFO - 'TMPDIR': '/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/T/', 09:32:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:32:37 INFO - 'USER': 'cltbld', 09:32:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:32:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:32:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:32:37 INFO - 'XPC_FLAGS': '0x0', 09:32:37 INFO - 'XPC_SERVICE_NAME': '0', 09:32:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:32:37 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Cedar', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/YR3vUOswSfKRSevxqb17gg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0214', '--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 09:32:38 INFO - mozversion application_buildid: 20170612070827 09:32:38 INFO - mozversion application_changeset: 506241d8f012cfded7147ec1fc0b7f501886a8c3 09:32:38 INFO - mozversion application_display_name: Nightly 09:32:38 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:32:38 INFO - mozversion application_name: Firefox 09:32:38 INFO - mozversion application_remotingname: firefox 09:32:38 INFO - mozversion application_repository: https://hg.mozilla.org/projects/cedar 09:32:38 INFO - mozversion application_vendor: Mozilla 09:32:38 INFO - mozversion application_version: 56.0a1 09:32:38 INFO - mozversion platform_buildid: 20170612070827 09:32:38 INFO - mozversion platform_changeset: 506241d8f012cfded7147ec1fc0b7f501886a8c3 09:32:38 INFO - mozversion platform_repository: https://hg.mozilla.org/projects/cedar 09:32:38 INFO - mozversion platform_version: 56.0a1 09:32:38 INFO - using testdate: 1497285158 09:32:38 INFO - actual date: 1497285158 09:32:38 INFO - starting webserver on 'localhost:56264' 09:32:38 INFO - SUITE-START | Running 2 tests 09:32:38 INFO - TEST-START | dromaeo_css 09:32:38 INFO - operating with platform_type : mac_ 09:32:38 INFO - Initialising browser for dromaeo_css test... 09:32:38 INFO - Application command: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:56264/getInfo.html -foreground -profile /var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/T/tmpOa7X7M/profile 09:32:38 INFO - TEST-INFO | started process 2426 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:56264/getInfo.html -foreground) 09:33:12 INFO - TEST-INFO | 2426: killed by SIGTERM 09:33:12 INFO - Browser initialized. 09:33:12 INFO - Running cycle 1/1 for dromaeo_css test... 09:33:12 INFO - TEST-INFO | started process 2434 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/T/tmpOa7X7M/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:33:13 INFO - PID 2434 | 2017-06-12 09:33:13.906 plugin-container[2435:26864] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4947, name = 'com.apple.tsm.portname' 09:33:13 INFO - PID 2434 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:33:13 INFO - PID 2434 | 2017-06-12 09:33:13.906 plugin-container[2435:26864] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9f0b, name = 'com.apple.CFPasteboardClient' 09:33:13 INFO - PID 2434 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:33:13 INFO - PID 2434 | 2017-06-12 09:33:13.906 plugin-container[2435:26864] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:33:14 INFO - PID 2434 | 2017-06-12 09:33:14.676 plugin-container[2436:26911] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x953f, name = 'com.apple.tsm.portname' 09:33:14 INFO - PID 2434 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:33:14 INFO - PID 2434 | 2017-06-12 09:33:14.676 plugin-container[2436:26911] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x461f, name = 'com.apple.CFPasteboardClient' 09:33:14 INFO - PID 2434 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:33:14 INFO - PID 2434 | 2017-06-12 09:33:14.676 plugin-container[2436:26911] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:33:24 INFO - PID 2434 | RSS: Main: 252727296 09:33:24 INFO - PID 2434 | 09:36:27 INFO - PID 2434 | Cycle 1(1): loaded http://localhost:56264/tests/dromaeo/cssquery-dojo.html (next: http://localhost:56264/tests/dromaeo/cssquery-ext.html) 09:36:29 INFO - PID 2434 | RSS: Main: 265633792 09:36:29 INFO - PID 2434 | 09:39:30 INFO - PID 2434 | Cycle 1(1): loaded http://localhost:56264/tests/dromaeo/cssquery-ext.html (next: http://localhost:56264/tests/dromaeo/cssquery-jquery.html) 09:39:31 INFO - PID 2434 | RSS: Main: 274477056 09:39:31 INFO - PID 2434 | 09:42:33 INFO - PID 2434 | Cycle 1(1): loaded http://localhost:56264/tests/dromaeo/cssquery-jquery.html (next: http://localhost:56264/tests/dromaeo/cssquery-mootools.html) 09:42:33 INFO - PID 2434 | RSS: Main: 276774912 09:42:33 INFO - PID 2434 | 09:45:35 INFO - PID 2434 | Cycle 1(1): loaded http://localhost:56264/tests/dromaeo/cssquery-mootools.html (next: http://localhost:56264/tests/dromaeo/cssquery-prototype.html) 09:45:35 INFO - PID 2434 | RSS: Main: 277188608 09:45:35 INFO - PID 2434 | 09:48:38 INFO - PID 2434 | Cycle 1(1): loaded http://localhost:56264/tests/dromaeo/cssquery-prototype.html (next: http://localhost:56264/tests/dromaeo/cssquery-yui.html) 09:48:39 INFO - PID 2434 | RSS: Main: 279175168 09:48:39 INFO - PID 2434 | 09:51:41 INFO - PID 2434 | Cycle 1(1): loaded http://localhost:56264/tests/dromaeo/cssquery-yui.html (next: http://localhost:56264/tests/dromaeo/cssquery-dojo.html) 09:51:41 INFO - PID 2434 | RSS: Main: 277053440 09:51:41 INFO - PID 2434 | 09:51:41 INFO - PID 2434 | __start_tp_report 09:51:41 INFO - PID 2434 | _x_x_mozilla_page_load 09:51:41 INFO - PID 2434 | _x_x_mozilla_page_load_details 09:51:41 INFO - PID 2434 | |i|pagename|runs| 09:51:41 INFO - PID 2434 | |0;dojo.html;3420.042388131321;3508.1755787389966;3555.3244883472153;3561.4479755637885;3570.0717813368524;4872.902541949159;5198.298229738984;5966.46301832835;6105.236845394325;6113.847153821157;5908.704564771755;5994.160817485545;6075.908861367072;6092.086187071942;6097.847553811157;5221.582273418117;5230.476952304777;5270;5288.15389537674;5363.651362661434;4868.853934381974;5001.374828146481;5300.939812037589;5352.5182733553975;5372.167314066326;5298.1522956327;5313.282706834567;5323.307969963899;5339.305890234265;5340.439253878345;5293.6294459387855;5325.094733895208;5369.677819330853;5374.5700343972385;5392.811251606175;5274.498922602346;5305.257263983045;5312.6281160318795;5348.652337598043;5367.409584945653;4987.750612469398;6040.472663244903;6073.969630151865;6113.816585502397;6219.440250377488;6349.333320001379;6389.520785941073;6446.871062578723;6498.8050358488845;6506.21925368954;6422.775202867924;6527.151470308877;6601.603903765741;6647.335266473314;6670.09953656255;6071.180390647294;6331.39851714091;6346.27017892939;6368.076628888832;6368.745250189987;6352.2694890087305;6764.932350676529;6768.56004359715;6776.28848970851;6878.209005964278;5735.340435849847;6116.623759654131;6449.645269510128;6459.806205813833;6487.740490380332;6406.199225096863;6428.389303016206;6430.196225471816;6437.25971513282;6457.709403076873;6016.217891674054;6369.108324834527;6461.321561236096;6489.8702025960165;6499.05763664266;6468.514861385415;6487.286398496161;6487.740490380426;6490.545661803629;6505.21937367519;5090;5123.692578445305;5126.333576635088;5136.486351364908;5151.7681704323395;3992.261431635157;4638.5593368629925;4662.906741865144;4707.741074240949;4719.787609557509;6161.876762464815;6283.340249273851;6322.304546499881;6334.683265836689;6357.523185760994;6579.769708060195;6600.80197594073;6617.8014659560295;6638.369354911276;6650.102236198148;6115.296740874767;6139.3388173831;6164.6917654117115;6177.81466556004;6287.402696743802;4787.473377928425;5654.321481422256;5702.344230413472;5724.969505489052;5732.6847023413275;5642.8839185305515;6673.466122710074;6730.899036514456;6747.190337159474;6786.660666966731;6571.5399922005;6614.900776488453;6615.536912416084;6629.270780214076;6650.268470468148;6728.226253980659;6739.797806065826;6746.898796517958;6768.4585233182215;6780.084688566981;6437.356264373525;6469.159009328757;6483.675816209265;6495.675216239264;6495.740170393131;6385.393387628168;6440.6135631862235;6459.709313080829;6468.320826313357;6519.282878883414;6347.174867267226;6368.108464814837;6380.649064301508;6382;6397.392247736458;6376.681165941777;6385.425311721781;6386.840328991812;6390.808275751735;6417.679116044085;6289;6365.363463653597;6373.936260637334;6374.649394283359;6412.166418365583;6306.211723534559;6312.495000399865;6392.456641185551;6408.679566021773;6413.935860641333;5285.392179899361;6185.567010309235;6208.937910620836;6228.252609686867;6347.777827776005 09:51:41 INFO - PID 2434 | |1;ext.html;760.296725528886;794.9244821741933;813.7762115418259;836.5649862071723;851.7870532366908;38419.61580384195;39315.80342098289;39319.0170245744;39320;39405;38654.06729663513;38815;38998.415023774665;39008.41487377685;39035.609643903554;7433.219511951248;7566;7567.810804729871;7582.241775822429;7601.543907365548;7407.259274072577;7508.474406791527;7514.661840217176;7523.285287897641;7543.207963163871;7446.590437525934;7448.068991376078;7461.738839140631;7471.887921681179;7494.587797671126;7416.8887466687775;7470.962645186739;7471.028766260379;7473.962630186841;7483.176850546435;6751.324867513258;6785.185777706657;6812.557183783038;6813.454923606099;6813.466969931776;34771.65228347735;34876.47685284723;35060.64939350599;35138.47292290618;35181.29637407263;3335.199552107501;3361.8991430256888;3362.646922073196;3366.8316584170693;3376.9493457598155;27105.593416098578;27359.316017099532;27467.45065098687;27484.763185656695;27517.86241068802;2712.403271280335;2765.1704488653527;2830.1933948824694;2831.6602007758993;2864.1264414353614;230959;234244.82877585673;234547;295754;295877;37679.11604419768;45707.77146114248;45725.54274457213;45961;46574.30138547857;49812.0037599246;49938.50061499338;49939.25091123636;49999.75000125049;50007.74996125069;13932.860671393359;14038.929805350908;14225.430982760781;14246.287685615678;14247.145171289756;11233.269837460539;11338.582677165354;11420.714482138013;11523.366214858263;11570.305781653127;13560.186388816703;13628.65928351799;13635;13635.795463068062;13744.725105498033;5113.693178409308;5327.81352652663;5820.748539064121;6017.789377371859;6114.938850611526;1014.3254735600743;1028.4189432970347;1030.8144533983805;1082.37222411003;1087.8912108789057;23306.24388490622;23385.649215261783;23498.882505587364;23605.7639423607;24741.62887556688;1058.9788204235872;1099.780043991205;1113.6714669172613;1153.7750138722868;1154;11512.712182195344;11676.299422034705;11920.46357913896;12020.45907934145;12359.32023738685;312091;314931;316243;316395;316727;54603;54684.72657636766;54786;54850.17724734132;54984.725076375165;1354.9322533873465;1495.5438591229563;1542.228885557199;1551.1468692219235;1554.1995872125865;18435.631287374446;21884.219301442576;22401.66397504006;22716;24074.759252407952;38395.6160438392;38541.65104221339;38641.8407447777;38666.22667546577;38704.41943370795;229979;232565;278115;278253;278865;29729.405411892072;31833.045008649777;32284;33130.834345828116;33547;39889.80055099706;40608;41999.32002719979;42742;43001;27251.72748272492;27702.307442314104;29171.41657166802;30548.847255764467;31521.52717709282;27370.31574210581;30463.238419039702;30755.53866692046;30906;31502.842485787423;37528.624713753605;37588.248235035695;37608.81195594114;37649.81175094107;37698.2460350786;26708.732912670625;28759;29966.550501742055;31293.374132517678;31456.528152078194;23461.413464662837;24760.25719228426;24828.00687972461;25011;25209.873950630128 09:51:41 INFO - PID 2434 | |2;jquery.html;500.09982032341776;500.70208226105456;506.5112166759077;510.77781165193136;530.9017831701138;20942;21226.46933826655;21504.92475376231;22008.33974980751;22229.888850555766;23164.536709265845;23823.28530144094;23846.165384211556;24020.75979240207;24020.87989560054;5996.8200953971345;6262.3111457739615;6276.717547710364;6277.995520716686;6296.150019747344;6128.6322820630685;6222.533310001768;6232.034034724602;6233.532485063638;6234.688265586725;5684;5809.941900581003;5994.760209591611;6027.126066720323;6059;6075.270967483908;6126.081087836816;6127.693615319216;6146.1395404642935;6148.784792532262;5590.720463976785;5591.021571225019;5608.2428872102155;5610.859728506779;5613;25843.870780646164;27456.45087098267;27540.862295688392;27633.447331053467;27831.443371132667;2148.4628842789302;2241.9887900560393;2246.1576908659254;2265.1732117777055;2271.636538153904;15111.622209444851;15175.62060948474;17859.374921877803;18403.171857266454;18429.539261518436;1808.5297822566097;1934.1006432009042;2143.6355819510723;2206.6910632511463;2208.03950281628;159519.20240398723;165277.17361413236;167377;167631.3236867628;190113;12791;13480.730385392433;14335.78496322556;14683.92658036703;14712.632184195267;17365.479035628952;18177.63644727098;19206.327778527684;19279.80720192808;19356.61286774285;9327.347085704072;9390.85913711295;9399.90600094004;9436;9501.382410143318;8696.260817830429;8709.390342676079;8711.259542938795;8782.736517904474;8847;7197.676104575306;8601.483910965362;8658.91341086581;8694.304455643532;8713.825723485621;2196.2313190383557;2586.7801236894697;2597.7921766258655;2603.114940920096;2605.0751983046125;976.6958586908091;986.5954958466995;987.807377561383;1034.9586016559404;1052.7210289273348;20018.299359522345;20285.188592456136;20462.283820066525;20873.89563052175;21026.158953641683;923.6582464488182;987.8468837330082;1028.7788125553043;1032.7727899862018;1062.7715041266165;9182.770430739285;9646.565904534313;10108.350158926829;10535.947320263349;10584.153267738562;578728;589357;597487;606119;608178;144161;156296.21851890668;156880;156951;157014;1028.5628607841786;1077.138289368502;1082.53992053377;1085.8154113800672;1211.1521934645784;18785.6242875139;20505.794942050987;23074.769252307262;23382.64926026145;23504.76495235026;69944;74047;75146.12134696676;75418;75840.24159758553;90325.09674903167;91972;92679.60980585431;94362;95041.5247923756;11281.548738050386;11294.152938529807;11388.601398950997;11420.029297509473;11438.771224575628;17631.559211019827;17972.10139493046;18070.909645452215;18235.0882455874;18271;12162.695932601755;13994.300284985506;14752;14858.702825943205;14957.252137392868;12635;12877.871221288042;12928.676783080122;13466.596002119952;13547.051706380458;13216.603501894959;13338.732820382049;13478.258695771827;13509.527166549124;13530.79703804424;11211;11255.380954047607;11272.605458808903;11349.4892729829;11365;11773;12916.22502649844;12927.418266177856;12959;14206.21865797391 09:51:41 INFO - PID 2434 | |3;mootools.html;1218.750156217975;1269.3272565540267;1359.857214992425;1447.5729659750373;1462.8464011278816;10364.948175259122;13468.461261549539;13501.392437340319;13514.121582097157;13588.524401645944;11998.400079996009;12045.457954392052;13042.413091410886;13308.46766129356;13407.396667149977;2570.4859028194437;2729.672439307286;2767.51568475517;2771.210205091549;2771.5426954552477;2710.9728902711017;2750.7111753265917;2754.2838861895852;2756.324700448393;2764.2398340456334;2250.3923940536047;2317.2353123469215;2333.311673056444;2690.6771187457534;2744.108164846423;2296.8851557422145;2659.2288236411514;2736.0560606590693;2746.134967485246;2750.174947515757;2720.306321887906;2724.00573790567;2728.4679487499943;2730.085421383819;2730.9726902730918;13120.934395327962;13298.86701132986;13303.201807891557;13312.733745325137;13314.80027799574;7350.669219885118;7602.2397760224085;7662.65518051689;7668.156502784689;7672.386209103258;3995.9400608990886;4481.395011673415;4778.569928706398;4784.712917224942;4798.856034318976;1357.5438475857436;1387.8750912417931;1411.3648858013935;1412.2656218766183;1413.258039529245;19450.70823937628;19544.31594894186;19689.015549222626;19742.60514789696;19785.307514236934;5455.536279416215;5500.972495137498;6276.403741644536;6518.1200537927725;6554.508411869129;11604.535818567332;13866;13953.162810231215;13961.581152565439;14001.789973150411;7230.855382892418;7298.854022919512;7353.485256032026;7379.114506259283;7393.297636724503;6588.40704336612;6751.831204219934;6764.627945463047;6789.0834737310815;6834.8633027340165;4708.505606911293;4868.902621947541;4933.629977751683;4943.456219815817;4959.801607935714;1441.2865631512468;1463.6121427821636;1464.9707005859823;1486.0637798187072;1487.5537338798533;805.8912046873597;815.8822413293707;819.4018366592302;829.2785276809094;854.7136709202487;6918.308169183142;7129.322714342129;7160.749373771997;7174.174969878426;7181.671390792616;644.3331152257484;672.7073722930443;710.722818100941;712.7042277454901;714.3749219432996;4567.862964111016;4575.81696732127;4757.215059515142;5079.238114282887;5590.161475778666;17280.106971615693;17871.642567148843;17941.7308740369;17952.640947181244;18386.264549417872;17490.387836425663;17847.643047139245;18163.818361816477;18177.81822181761;18307.63384732298;9034.503102329303;9252.028537003653;9405.717828465156;9444.244460443286;9479.573419196153;7389.8522029558035;7723.922760772545;7970.641321140679;7991.360691144579;8078.474899131537;16160.757588635945;16247.187640618158;16248.26882790301;16905.830941690427;16922.900011499212;15750.133742643806;16514.091724955244;16962;16988.405405810736;17008.319667213174;10316;10664.733381665521;10674.839877402;10857.782844343226;10875.129989600655;12502.499900004263;12624.368781561068;12636.30500322491;12637.620871373874;12650.683732906751;9486.33595648298;9832.950835245778;9837.85243221338;10103.898961010296;10188.898111018794;9536.713898583053;9645.553167025002;9661.420314781135;9875.222459957276;9917.702468925943;7578.924210757822;8438.746837594881;8906.643734250558;8962.058983806606;8963.775905602412;6479.5788273762055;6639.601623902581;6863.210730765826;6992.300769922967;7059.25877782846;6727.764528241488;6794.966025169842;6945.548539344926;7096.822579435556;7105.218425973036 09:51:41 INFO - PID 2434 | |4;prototype.html;421.7511668115812;428.47725774555033;441.67316186022333;455.8928651766834;466.53113620811104;6426.389492998163;6431.807045788634;6440.488209598255;6485.805425837232;6499.057636642689;5092.745362731858;5393.973030134854;5804.738786754596;6269.275949113984;6277.968610156954;3927.56796752357;4524.5701658342405;4709.835155769549;4728.858134255978;4735.905281894368;4582.816687332519;4663.673542852001;4702.882427939295;4729.976350118244;4738.2892566115015;4679.947011922307;4711.623070154379;4713.198756211452;4713.528647135293;4715.174844402233;4397.692161548693;4465.173942820572;4537.160625284333;4595.586397224266;4606.401167848192;3665.52348194733;4067.5729048449784;4077.225327187825;4084.7140700151003;4099.815508302134;5808.941910580882;6069.939300606981;6085.117657939574;6094.725737341831;6187.752489900398;5617.354004289518;5622.269105016322;5627.465390787868;5648.999999999959;5666.433356664342;6799.660016999131;6814.420774234196;6815.931840681579;6826.624535650537;6871.690773915186;4712.6229901607785;4723;4730.172219861545;4731.077439899221;4737.715737055753;5702.45826646468;6797.218319893226;7216.8556628867655;7231.192201949512;7254.129504459446;4799.448063472745;4818.734969576707;4828.95171048285;4830.710157390497;4836.153673107263;4231.597998190167;4852.320675105488;4909.459959404463;4913.508649135058;4918.803247870045;5655.858603534863;5664.376918538957;5667.744951477193;5684.37471878093;5695.94624994369;6858.3827455529245;6861.451083913275;6864.107666003388;6865;6878.140232470942;1531.4716422834103;1674.3135314520987;1674.9280460505286;1676.6898123847127;1699.2862997541063;951.8857737071596;1116.9050630696481;1117.944102794857;1130.7229728716518;1136.8010598145293;5536.114221724585;6137.84655383619;6293.37066293367;6300.747970081237;6407.935920640827;5667.631603945731;6963.7562685305775;7066.929330706627;7091;7125.857482850418;5057.822976195889;5408.675479471244;6156.26124865019;6442.903356449657;6452.580582262138;3510.7542472027226;3626.2747450510005;3636.5999740028574;3639.52686150804;3646.252518233756;7186.029885965328;7200.747973820891;7211.639418029077;7226.6386680665755;7227.168875579376;7250.709971601077;7279.381252593589;7303.7443689471675;7317.743878964211;7320.341169294789;6337.904931426125;7185.6766445510075;7260.745873894389;7262.854742905112;7269.854602908018;6388.936110638834;6634.535582509371;7023.981522679285;7090.97180908755;7132.572045677276;5796.478316951495;6457.741690332522;6693.732250709917;6835.658217089225;6968.466937273792;6599.505037122139;7256.7460138894885;7261.49169558147;7264;7283.156700793913;3973.821178046937;4332.548596220176;4558.612517936012;4771.618270538279;4774.40319960005;6162.630242185483;6942.750304945158;7062.8940565890525;7065.385807251145;7094.858102838018;5335.171669638983;5935.910961335497;6604.339566043357;6783.861844274889;6786.830329241611;5852.239208902815;6186.566940314135;6221.502279817513;6753.459723222229;6777.762778302736;5153.922691159561;5274.736263186902;6529.249136349381;6602.599661174595;6642.335766423319;6750.5274630775375;6758.628275444898;6759.222689390783;6782.287859774653;6866.725330986705;5541.916871246854;6267.655278959701;6437.871242575216;6478.5141114415665;6686.866262674816 09:51:41 INFO - PID 2434 | |5;yui.html;722.6567380494263;808.5674164322081;816.3673153306189;846.9872951905722;851.1190917400492;16740.246688899;17027.829721702794;17281.91359043206;17304.82695173048;17326.220320085595;12989.480420783157;13414.128081674684;13605.183688978668;16020.919895400535;16857.325706971737;977.7506735782365;978.2223132609557;979.7697541077841;988.5502096546054;990.2078337330107;970.3353203055894;995.945223012734;996.0836030851634;997.1623835977778;1007.1137399088792;1038.6728180623118;1051.3586712105648;1054.125076186767;1057.370864335719;1064.4252103863905;939.3189937295474;974.3763991045737;1035.4046423306554;1035.8912314207014;1044.394251334232;679.0527214535745;750.740994356946;751.6204316820025;760.7793739815447;766.2299389113905;16287;17336.479905602853;17384.913075434542;17500.737488937677;17531.386401475887;17196.656066878717;17360.392386266547;17431.912840435845;17543.035133067675;17576.38482653114;14461.421543138262;14718.116912985255;14800.481983130647;14811.629709257248;14894.180820054893;3580.2481478889463;3589.6589823966683;3618.077390265492;3619.1314084619767;3620.2759448110482;101778;135509;147244;156283.2185839075;159219.40780592276;13416.798748018788;13440.32798360078;13497.730045399037;13504.932475337757;13683.657908552366;26321.73678263231;26674.466510669678;26846.926122955254;26891.596626050625;26926.865365673046;7419.591922444318;7677.577733224614;7783.260590244031;7974.122846486882;7974.843647671055;3687.354712925281;3956.723029387915;3957.742746721454;3959.703022273341;3976.4830572025453;4516.412866327357;4570.337301091323;4613.146567884952;4626.583607475343;4629.027904140168;1001.979960400788;1025.195221750922;1025.6410256410197;1026.0765311219961;1027.8355463125863;622.0748829953251;650.9869802603921;651.4560342114293;667.7762949411904;669.8425869920565;11343.829842552368;11635.900452620659;11776.88223117775;12169;12398;606.8907596632648;645.502962718704;667.2926697700453;668.5153263883743;694.5277211496131;4597.218472859622;5322.281491998608;5524.75138618755;5807.912881306784;5858.443447872445;105062;115256;124777;124897.37551312185;138163;113354.43322783333;115282.42358788152;115573.4221328888;115589.26616100765;123326.38336808438;93965.06034939563;95618.52190739002;97645;99765;104119;10956.671299861016;11072.889271107186;11151.386673733023;11185.38480383554;11331.150163737917;42406;42608;45134.77432612816;45612.77193614011;46221.53778462172;76147.61926190555;76973.61513192399;77281;85320;85339;16485.928414653008;17603.647927041133;17727.29090836352;17931.462056138338;17948.012859292863;17118.400855970478;18370.72443913369;18396.080195989878;19225.51936201606;19279.903600481906;17249.568760780578;17499;18313.3590324338;18367.632647347247;18405.355812546495;17116.572085697957;17491.300347985936;17522.474325770247;17644;17646.647067058842;16304.918475407547;17436.389726360023;17510.124493775515;17765.73351399756;17809.198586063398;11650.610084290858;11957.880421196025;12510.874891251335;12525.123241373016;12721.55474558386;11288.9435552825;11350.864913508583;12299.200551964093;12564;13156.934215328862 09:51:41 INFO - PID 2434 | __end_tp_report 09:51:41 INFO - PID 2434 | __start_cc_report 09:51:41 INFO - PID 2434 | _x_x_mozilla_cycle_collect,474 09:51:41 INFO - PID 2434 | __end_cc_report 09:51:41 INFO - PID 2434 | __startTimestamp1497286301361__endTimestamp 09:51:41 INFO - PID 2434 | 09:51:41 INFO - PID 2434 | ------- Summary: start ------- 09:51:41 INFO - PID 2434 | Number of tests: 6 09:51:41 INFO - PID 2434 | 09:51:41 INFO - PID 2434 | [#0] dojo.html Cycles:165 Average:5992.09 Median:6346.27 stddev:733.27 (11.6%) stddev-sans-first:707.21 09:51:41 INFO - PID 2434 | Values: 3420.0 3508.2 3555.3 3561.4 3570.1 4872.9 5198.3 5966.5 6105.2 6113.8 5908.7 5994.2 6075.9 6092.1 6097.8 5221.6 5230.5 5270.0 5288.2 5363.7 4868.9 5001.4 5300.9 5352.5 5372.2 5298.2 5313.3 5323.3 5339.3 5340.4 5293.6 5325.1 5369.7 5374.6 5392.8 5274.5 5305.3 5312.6 5348.7 5367.4 4987.8 6040.5 6074.0 6113.8 6219.4 6349.3 6389.5 6446.9 6498.8 6506.2 6422.8 6527.2 6601.6 6647.3 6670.1 6071.2 6331.4 6346.3 6368.1 6368.7 6352.3 6764.9 6768.6 6776.3 6878.2 5735.3 6116.6 6449.6 6459.8 6487.7 6406.2 6428.4 6430.2 6437.3 6457.7 6016.2 6369.1 6461.3 6489.9 6499.1 6468.5 6487.3 6487.7 6490.5 6505.2 5090.0 5123.7 5126.3 5136.5 5151.8 3992.3 4638.6 4662.9 4707.7 4719.8 6161.9 6283.3 6322.3 6334.7 6357.5 6579.8 6600.8 6617.8 6638.4 6650.1 6115.3 6139.3 6164.7 6177.8 6287.4 4787.5 5654.3 5702.3 5725.0 5732.7 5642.9 6673.5 6730.9 6747.2 6786.7 6571.5 6614.9 6615.5 6629.3 6650.3 6728.2 6739.8 6746.9 6768.5 6780.1 6437.4 6469.2 6483.7 6495.7 6495.7 6385.4 6440.6 6459.7 6468.3 6519.3 6347.2 6368.1 6380.6 6382.0 6397.4 6376.7 6385.4 6386.8 6390.8 6417.7 6289.0 6365.4 6373.9 6374.6 6412.2 6306.2 6312.5 6392.5 6408.7 6413.9 5285.4 6185.6 6208.9 6228.3 6347.8 09:51:41 INFO - PID 2434 | 09:51:41 INFO - PID 2434 | [#1] ext.html Cycles:180 Average:42669.20 Median:174280.02 stddev:73398.81 (42.1%) stddev-sans-first:73537.27 09:51:41 INFO - PID 2434 | Values: 760.3 794.9 813.8 836.6 851.8 38419.6 39315.8 39319.0 39320.0 39405.0 38654.1 38815.0 38998.4 39008.4 39035.6 7433.2 7566.0 7567.8 7582.2 7601.5 7407.3 7508.5 7514.7 7523.3 7543.2 7446.6 7448.1 7461.7 7471.9 7494.6 7416.9 7471.0 7471.0 7474.0 7483.2 6751.3 6785.2 6812.6 6813.5 6813.5 34771.7 34876.5 35060.6 35138.5 35181.3 3335.2 3361.9 3362.6 3366.8 3376.9 27105.6 27359.3 27467.5 27484.8 27517.9 2712.4 2765.2 2830.2 2831.7 2864.1 230959.0 234244.8 234547.0 295754.0 295877.0 37679.1 45707.8 45725.5 45961.0 46574.3 49812.0 49938.5 49939.3 49999.8 50007.7 13932.9 14038.9 14225.4 14246.3 14247.1 11233.3 11338.6 11420.7 11523.4 11570.3 13560.2 13628.7 13635.0 13635.8 13744.7 5113.7 5327.8 5820.7 6017.8 6114.9 1014.3 1028.4 1030.8 1082.4 1087.9 23306.2 23385.6 23498.9 23605.8 24741.6 1059.0 1099.8 1113.7 1153.8 1154.0 11512.7 11676.3 11920.5 12020.5 12359.3 312091.0 314931.0 316243.0 316395.0 316727.0 54603.0 54684.7 54786.0 54850.2 54984.7 1354.9 1495.5 1542.2 1551.1 1554.2 18435.6 21884.2 22401.7 22716.0 24074.8 38395.6 38541.7 38641.8 38666.2 38704.4 229979.0 232565.0 278115.0 278253.0 278865.0 29729.4 31833.0 32284.0 33130.8 33547.0 39889.8 40608.0 41999.3 42742.0 43001.0 27251.7 27702.3 29171.4 30548.8 31521.5 27370.3 30463.2 30755.5 30906.0 31502.8 37528.6 37588.2 37608.8 37649.8 37698.2 26708.7 28759.0 29966.6 31293.4 31456.5 23461.4 24760.3 24828.0 25011.0 25209.9 09:51:41 INFO - PID 2434 | 09:51:41 INFO - PID 2434 | [#2] jquery.html Cycles:180 Average:39533.40 Median:12218.96 stddev:101846.32 (833.5%) stddev-sans-first:102089.85 09:51:41 INFO - PID 2434 | Values: 500.1 500.7 506.5 510.8 530.9 20942.0 21226.5 21504.9 22008.3 22229.9 23164.5 23823.3 23846.2 24020.8 24020.9 5996.8 6262.3 6276.7 6278.0 6296.2 6128.6 6222.5 6232.0 6233.5 6234.7 5684.0 5809.9 5994.8 6027.1 6059.0 6075.3 6126.1 6127.7 6146.1 6148.8 5590.7 5591.0 5608.2 5610.9 5613.0 25843.9 27456.5 27540.9 27633.4 27831.4 2148.5 2242.0 2246.2 2265.2 2271.6 15111.6 15175.6 17859.4 18403.2 18429.5 1808.5 1934.1 2143.6 2206.7 2208.0 159519.2 165277.2 167377.0 167631.3 190113.0 12791.0 13480.7 14335.8 14683.9 14712.6 17365.5 18177.6 19206.3 19279.8 19356.6 9327.3 9390.9 9399.9 9436.0 9501.4 8696.3 8709.4 8711.3 8782.7 8847.0 7197.7 8601.5 8658.9 8694.3 8713.8 2196.2 2586.8 2597.8 2603.1 2605.1 976.7 986.6 987.8 1035.0 1052.7 20018.3 20285.2 20462.3 20873.9 21026.2 923.7 987.8 1028.8 1032.8 1062.8 9182.8 9646.6 10108.4 10535.9 10584.2 578728.0 589357.0 597487.0 606119.0 608178.0 144161.0 156296.2 156880.0 156951.0 157014.0 1028.6 1077.1 1082.5 1085.8 1211.2 18785.6 20505.8 23074.8 23382.6 23504.8 69944.0 74047.0 75146.1 75418.0 75840.2 90325.1 91972.0 92679.6 94362.0 95041.5 11281.5 11294.2 11388.6 11420.0 11438.8 17631.6 17972.1 18070.9 18235.1 18271.0 12162.7 13994.3 14752.0 14858.7 14957.3 12635.0 12877.9 12928.7 13466.6 13547.1 13216.6 13338.7 13478.3 13509.5 13530.8 11211.0 11255.4 11272.6 11349.5 11365.0 11773.0 12916.2 12927.4 12959.0 14206.2 09:51:41 INFO - PID 2434 | 09:51:41 INFO - PID 2434 | [#3] mootools.html Cycles:180 Average:8102.06 Median:2752.50 stddev:5400.49 (196.2%) stddev-sans-first:5390.87 09:51:41 INFO - PID 2434 | Values: 1218.8 1269.3 1359.9 1447.6 1462.8 10364.9 13468.5 13501.4 13514.1 13588.5 11998.4 12045.5 13042.4 13308.5 13407.4 2570.5 2729.7 2767.5 2771.2 2771.5 2711.0 2750.7 2754.3 2756.3 2764.2 2250.4 2317.2 2333.3 2690.7 2744.1 2296.9 2659.2 2736.1 2746.1 2750.2 2720.3 2724.0 2728.5 2730.1 2731.0 13120.9 13298.9 13303.2 13312.7 13314.8 7350.7 7602.2 7662.7 7668.2 7672.4 3995.9 4481.4 4778.6 4784.7 4798.9 1357.5 1387.9 1411.4 1412.3 1413.3 19450.7 19544.3 19689.0 19742.6 19785.3 5455.5 5501.0 6276.4 6518.1 6554.5 11604.5 13866.0 13953.2 13961.6 14001.8 7230.9 7298.9 7353.5 7379.1 7393.3 6588.4 6751.8 6764.6 6789.1 6834.9 4708.5 4868.9 4933.6 4943.5 4959.8 1441.3 1463.6 1465.0 1486.1 1487.6 805.9 815.9 819.4 829.3 854.7 6918.3 7129.3 7160.7 7174.2 7181.7 644.3 672.7 710.7 712.7 714.4 4567.9 4575.8 4757.2 5079.2 5590.2 17280.1 17871.6 17941.7 17952.6 18386.3 17490.4 17847.6 18163.8 18177.8 18307.6 9034.5 9252.0 9405.7 9444.2 9479.6 7389.9 7723.9 7970.6 7991.4 8078.5 16160.8 16247.2 16248.3 16905.8 16922.9 15750.1 16514.1 16962.0 16988.4 17008.3 10316.0 10664.7 10674.8 10857.8 10875.1 12502.5 12624.4 12636.3 12637.6 12650.7 9486.3 9833.0 9837.9 10103.9 10188.9 9536.7 9645.6 9661.4 9875.2 9917.7 7578.9 8438.7 8906.6 8962.1 8963.8 6479.6 6639.6 6863.2 6992.3 7059.3 6727.8 6795.0 6945.5 7096.8 7105.2 09:51:41 INFO - PID 2434 | 09:51:41 INFO - PID 2434 | [#4] prototype.html Cycles:180 Average:5491.44 Median:6077.53 stddev:1702.02 (28.0%) stddev-sans-first:1663.72 09:51:41 INFO - PID 2434 | Values: 421.8 428.5 441.7 455.9 466.5 6426.4 6431.8 6440.5 6485.8 6499.1 5092.7 5394.0 5804.7 6269.3 6278.0 3927.6 4524.6 4709.8 4728.9 4735.9 4582.8 4663.7 4702.9 4730.0 4738.3 4679.9 4711.6 4713.2 4713.5 4715.2 4397.7 4465.2 4537.2 4595.6 4606.4 3665.5 4067.6 4077.2 4084.7 4099.8 5808.9 6069.9 6085.1 6094.7 6187.8 5617.4 5622.3 5627.5 5649.0 5666.4 6799.7 6814.4 6815.9 6826.6 6871.7 4712.6 4723.0 4730.2 4731.1 4737.7 5702.5 6797.2 7216.9 7231.2 7254.1 4799.4 4818.7 4829.0 4830.7 4836.2 4231.6 4852.3 4909.5 4913.5 4918.8 5655.9 5664.4 5667.7 5684.4 5695.9 6858.4 6861.5 6864.1 6865.0 6878.1 1531.5 1674.3 1674.9 1676.7 1699.3 951.9 1116.9 1117.9 1130.7 1136.8 5536.1 6137.8 6293.4 6300.7 6407.9 5667.6 6963.8 7066.9 7091.0 7125.9 5057.8 5408.7 6156.3 6442.9 6452.6 3510.8 3626.3 3636.6 3639.5 3646.3 7186.0 7200.7 7211.6 7226.6 7227.2 7250.7 7279.4 7303.7 7317.7 7320.3 6337.9 7185.7 7260.7 7262.9 7269.9 6388.9 6634.5 7024.0 7091.0 7132.6 5796.5 6457.7 6693.7 6835.7 6968.5 6599.5 7256.7 7261.5 7264.0 7283.2 3973.8 4332.5 4558.6 4771.6 4774.4 6162.6 6942.8 7062.9 7065.4 7094.9 5335.2 5935.9 6604.3 6783.9 6786.8 5852.2 6186.6 6221.5 6753.5 6777.8 5153.9 5274.7 6529.2 6602.6 6642.3 6750.5 6758.6 6759.2 6782.3 6866.7 5541.9 6267.7 6437.9 6478.5 6686.9 09:51:41 INFO - PID 2434 | 09:51:41 INFO - PID 2434 | [#5] yui.html Cycles:180 Average:24774.11 Median:17623.82 stddev:37021.94 (210.1%) stddev-sans-first:37081.75 09:51:41 INFO - PID 2434 | Values: 722.7 808.6 816.4 847.0 851.1 16740.2 17027.8 17281.9 17304.8 17326.2 12989.5 13414.1 13605.2 16020.9 16857.3 977.8 978.2 979.8 988.6 990.2 970.3 995.9 996.1 997.2 1007.1 1038.7 1051.4 1054.1 1057.4 1064.4 939.3 974.4 1035.4 1035.9 1044.4 679.1 750.7 751.6 760.8 766.2 16287.0 17336.5 17384.9 17500.7 17531.4 17196.7 17360.4 17431.9 17543.0 17576.4 14461.4 14718.1 14800.5 14811.6 14894.2 3580.2 3589.7 3618.1 3619.1 3620.3 101778.0 135509.0 147244.0 156283.2 159219.4 13416.8 13440.3 13497.7 13504.9 13683.7 26321.7 26674.5 26846.9 26891.6 26926.9 7419.6 7677.6 7783.3 7974.1 7974.8 3687.4 3956.7 3957.7 3959.7 3976.5 4516.4 4570.3 4613.1 4626.6 4629.0 1002.0 1025.2 1025.6 1026.1 1027.8 622.1 651.0 651.5 667.8 669.8 11343.8 11635.9 11776.9 12169.0 12398.0 606.9 645.5 667.3 668.5 694.5 4597.2 5322.3 5524.8 5807.9 5858.4 105062.0 115256.0 124777.0 124897.4 138163.0 113354.4 115282.4 115573.4 115589.3 123326.4 93965.1 95618.5 97645.0 99765.0 104119.0 10956.7 11072.9 11151.4 11185.4 11331.2 42406.0 42608.0 45134.8 45612.8 46221.5 76147.6 76973.6 77281.0 85320.0 85339.0 16485.9 17603.6 17727.3 17931.5 17948.0 17118.4 18370.7 18396.1 19225.5 19279.9 17249.6 17499.0 18313.4 18367.6 18405.4 17116.6 17491.3 17522.5 17644.0 17646.6 16304.9 17436.4 17510.1 17765.7 17809.2 11650.6 11957.9 12510.9 12525.1 12721.6 11288.9 11350.9 12299.2 12564.0 13156.9 09:51:41 INFO - PID 2434 | -------- Summary: end -------- 09:51:41 INFO - PID 2434 | 09:51:42 INFO - TEST-INFO | 2434: exit 0 09:51:42 INFO - TEST-OK | dromaeo_css | took 1143983ms 09:51:42 INFO - TEST-START | kraken 09:51:42 INFO - operating with platform_type : mac_ 09:51:42 INFO - Initialising browser for kraken test... 09:51:42 INFO - Application command: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:56264/getInfo.html -foreground -profile /var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/T/tmpK1pf2n/profile 09:51:42 INFO - TEST-INFO | started process 2440 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:56264/getInfo.html -foreground) 09:52:16 INFO - TEST-INFO | 2440: killed by SIGTERM 09:52:16 INFO - Browser initialized. 09:52:16 INFO - Running cycle 1/1 for kraken test... 09:52:16 INFO - TEST-INFO | started process 2444 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/T/tmpK1pf2n/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:52:17 INFO - PID 2444 | 2017-06-12 09:52:17.966 plugin-container[2445:29217] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9937, name = 'com.apple.tsm.portname' 09:52:17 INFO - PID 2444 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:52:17 INFO - PID 2444 | 2017-06-12 09:52:17.967 plugin-container[2445:29217] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9a1b, name = 'com.apple.CFPasteboardClient' 09:52:17 INFO - PID 2444 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:52:17 INFO - PID 2444 | 2017-06-12 09:52:17.967 plugin-container[2445:29217] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:52:18 INFO - PID 2444 | 2017-06-12 09:52:18.789 plugin-container[2446:29265] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x953f, name = 'com.apple.tsm.portname' 09:52:18 INFO - PID 2444 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:52:18 INFO - PID 2444 | 2017-06-12 09:52:18.790 plugin-container[2446:29265] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9a0b, name = 'com.apple.CFPasteboardClient' 09:52:18 INFO - PID 2444 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:52:18 INFO - PID 2444 | 2017-06-12 09:52:18.790 plugin-container[2446:29265] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:52:28 INFO - PID 2444 | RSS: Main: 253247488 09:52:28 INFO - PID 2444 | 09:52:30 INFO - PID 2444 | Cycle 1(1): loaded http://localhost:56264/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:56264/tests/kraken/driver.html?testName=audio-beat-detection) 09:52:31 INFO - PID 2444 | RSS: Main: 264925184 09:52:31 INFO - PID 2444 | 09:52:34 INFO - PID 2444 | Cycle 1(1): loaded http://localhost:56264/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:56264/tests/kraken/driver.html?testName=audio-dft) 09:52:34 INFO - PID 2444 | RSS: Main: 274067456 09:52:34 INFO - PID 2444 | 09:52:38 INFO - PID 2444 | Cycle 1(1): loaded http://localhost:56264/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:56264/tests/kraken/driver.html?testName=audio-fft) 09:52:39 INFO - PID 2444 | RSS: Main: 276840448 09:52:39 INFO - PID 2444 | 09:52:41 INFO - PID 2444 | Cycle 1(1): loaded http://localhost:56264/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:56264/tests/kraken/driver.html?testName=audio-oscillator) 09:52:41 INFO - PID 2444 | RSS: Main: 279773184 09:52:41 INFO - PID 2444 | 09:52:43 INFO - PID 2444 | Cycle 1(1): loaded http://localhost:56264/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:56264/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:52:44 INFO - PID 2444 | RSS: Main: 280469504 09:52:44 INFO - PID 2444 | 09:52:47 INFO - PID 2444 | Cycle 1(1): loaded http://localhost:56264/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:56264/tests/kraken/driver.html?testName=imaging-darkroom) 09:52:48 INFO - PID 2444 | RSS: Main: 285917184 09:52:48 INFO - PID 2444 | 09:52:51 INFO - PID 2444 | Cycle 1(1): loaded http://localhost:56264/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:56264/tests/kraken/driver.html?testName=imaging-desaturate) 09:52:51 INFO - PID 2444 | RSS: Main: 286003200 09:52:51 INFO - PID 2444 | 09:52:54 INFO - PID 2444 | Cycle 1(1): loaded http://localhost:56264/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:56264/tests/kraken/driver.html?testName=json-parse-financial) 09:52:55 INFO - PID 2444 | RSS: Main: 286076928 09:52:55 INFO - PID 2444 | 09:52:57 INFO - PID 2444 | Cycle 1(1): loaded http://localhost:56264/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:56264/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:52:57 INFO - PID 2444 | RSS: Main: 285265920 09:52:57 INFO - PID 2444 | 09:52:59 INFO - PID 2444 | Cycle 1(1): loaded http://localhost:56264/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:56264/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:53:00 INFO - PID 2444 | RSS: Main: 285179904 09:53:00 INFO - PID 2444 | 09:53:02 INFO - PID 2444 | Cycle 1(1): loaded http://localhost:56264/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:56264/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:53:02 INFO - PID 2444 | RSS: Main: 285655040 09:53:02 INFO - PID 2444 | 09:53:05 INFO - PID 2444 | Cycle 1(1): loaded http://localhost:56264/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:56264/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:53:06 INFO - PID 2444 | RSS: Main: 285245440 09:53:06 INFO - PID 2444 | 09:53:09 INFO - PID 2444 | Cycle 1(1): loaded http://localhost:56264/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:56264/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:53:10 INFO - PID 2444 | RSS: Main: 285192192 09:53:10 INFO - PID 2444 | 09:53:12 INFO - PID 2444 | Cycle 1(1): loaded http://localhost:56264/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:56264/tests/kraken/driver.html?testName=ai-astar) 09:53:12 INFO - PID 2444 | RSS: Main: 287002624 09:53:12 INFO - PID 2444 | 09:53:12 INFO - PID 2444 | __start_tp_report 09:53:12 INFO - PID 2444 | _x_x_mozilla_page_load 09:53:12 INFO - PID 2444 | _x_x_mozilla_page_load_details 09:53:12 INFO - PID 2444 | |i|pagename|runs| 09:53:12 INFO - PID 2444 | |0;ai-astar;108;118;118;126;118;118;115;116;116;159 09:53:12 INFO - PID 2444 | |1;audio-beat-detection;134;113;151;138;112;132;132;114;156;110 09:53:12 INFO - PID 2444 | |2;audio-dft;204;203;200;220;227;220;224;205;215;249 09:53:12 INFO - PID 2444 | |3;audio-fft;69;83;74;149;80;64;69;91;99;66 09:53:12 INFO - PID 2444 | |4;audio-oscillator;72;100;150;86;59;57;92;62;96;144 09:53:12 INFO - PID 2444 | |5;imaging-gaussian-blur;90;89;92;126;81;133;135;90;90;91 09:53:12 INFO - PID 2444 | |6;imaging-darkroom;96;98;121;147;148;96;97;97;99;100 09:53:12 INFO - PID 2444 | |7;imaging-desaturate;98;93;93;98;90;91;92;92;91;93 09:53:12 INFO - PID 2444 | |8;json-parse-financial;50;50;54;51;53;47;52;60;69;76 09:53:12 INFO - PID 2444 | |9;json-stringify-tinderbox;53;59;86;91;98;64;59;57;57;56 09:53:12 INFO - PID 2444 | |10;stanford-crypto-aes;76;79;79;82;79;83;80;81;80;80 09:53:12 INFO - PID 2444 | |11;stanford-crypto-ccm;138;164;152;139;153;153;156;141;145;175 09:53:12 INFO - PID 2444 | |12;stanford-crypto-pbkdf2;182;185;181;176;176;179;184;179;241;227 09:53:12 INFO - PID 2444 | |13;stanford-crypto-sha256-iterative;65;68;68;81;99;91;97;94;75;72 09:53:12 INFO - PID 2444 | __end_tp_report 09:53:12 INFO - PID 2444 | __start_cc_report 09:53:12 INFO - PID 2444 | _x_x_mozilla_cycle_collect,1190 09:53:12 INFO - PID 2444 | __end_cc_report 09:53:12 INFO - PID 2444 | __startTimestamp1497286392492__endTimestamp 09:53:12 INFO - PID 2444 | 09:53:12 INFO - PID 2444 | ------- Summary: start ------- 09:53:12 INFO - PID 2444 | Number of tests: 14 09:53:12 INFO - PID 2444 | 09:53:12 INFO - PID 2444 | [#0] ai-astar Cycles:10 Average:121.20 Median:118.00 stddev:13.98 (11.8%) stddev-sans-first:13.99 09:53:12 INFO - PID 2444 | Values: 108.0 118.0 118.0 126.0 118.0 118.0 115.0 116.0 116.0 159.0 09:53:12 INFO - PID 2444 | 09:53:12 INFO - PID 2444 | [#1] audio-beat-detection Cycles:10 Average:129.20 Median:133.00 stddev:16.56 (12.4%) stddev-sans-first:17.47 09:53:12 INFO - PID 2444 | Values: 134.0 113.0 151.0 138.0 112.0 132.0 132.0 114.0 156.0 110.0 09:53:12 INFO - PID 2444 | 09:53:12 INFO - PID 2444 | [#2] audio-dft Cycles:10 Average:216.70 Median:220.00 stddev:14.88 (6.8%) stddev-sans-first:15.05 09:53:12 INFO - PID 2444 | Values: 204.0 203.0 200.0 220.0 227.0 220.0 224.0 205.0 215.0 249.0 09:53:12 INFO - PID 2444 | 09:53:12 INFO - PID 2444 | [#3] audio-fft Cycles:10 Average:84.40 Median:77.00 stddev:25.36 (32.9%) stddev-sans-first:26.28 09:53:12 INFO - PID 2444 | Values: 69.0 83.0 74.0 149.0 80.0 64.0 69.0 91.0 99.0 66.0 09:53:12 INFO - PID 2444 | 09:53:12 INFO - PID 2444 | [#4] audio-oscillator Cycles:10 Average:91.80 Median:67.00 stddev:32.99 (49.2%) stddev-sans-first:34.21 09:53:12 INFO - PID 2444 | Values: 72.0 100.0 150.0 86.0 59.0 57.0 92.0 62.0 96.0 144.0 09:53:12 INFO - PID 2444 | 09:53:12 INFO - PID 2444 | [#5] imaging-gaussian-blur Cycles:10 Average:101.70 Median:90.00 stddev:20.78 (23.1%) stddev-sans-first:21.61 09:53:12 INFO - PID 2444 | Values: 90.0 89.0 92.0 126.0 81.0 133.0 135.0 90.0 90.0 91.0 09:53:12 INFO - PID 2444 | 09:53:12 INFO - PID 2444 | [#6] imaging-darkroom Cycles:10 Average:109.90 Median:96.50 stddev:21.16 (21.9%) stddev-sans-first:21.84 09:53:12 INFO - PID 2444 | Values: 96.0 98.0 121.0 147.0 148.0 96.0 97.0 97.0 99.0 100.0 09:53:12 INFO - PID 2444 | 09:53:12 INFO - PID 2444 | [#7] imaging-desaturate Cycles:10 Average:93.10 Median:93.00 stddev:2.77 (3.0%) stddev-sans-first:2.30 09:53:12 INFO - PID 2444 | Values: 98.0 93.0 93.0 98.0 90.0 91.0 92.0 92.0 91.0 93.0 09:53:12 INFO - PID 2444 | 09:53:12 INFO - PID 2444 | [#8] json-parse-financial Cycles:10 Average:56.20 Median:53.50 stddev:9.38 (17.5%) stddev-sans-first:9.68 09:53:12 INFO - PID 2444 | Values: 50.0 50.0 54.0 51.0 53.0 47.0 52.0 60.0 69.0 76.0 09:53:12 INFO - PID 2444 | 09:53:12 INFO - PID 2444 | [#9] json-stringify-tinderbox Cycles:10 Average:68.00 Median:61.50 stddev:16.81 (27.3%) stddev-sans-first:16.93 09:53:12 INFO - PID 2444 | Values: 53.0 59.0 86.0 91.0 98.0 64.0 59.0 57.0 57.0 56.0 09:53:12 INFO - PID 2444 | 09:53:12 INFO - PID 2444 | [#10] stanford-crypto-aes Cycles:10 Average:79.90 Median:80.00 stddev:1.91 (2.4%) stddev-sans-first:1.41 09:53:12 INFO - PID 2444 | Values: 76.0 79.0 79.0 82.0 79.0 83.0 80.0 81.0 80.0 80.0 09:53:12 INFO - PID 2444 | 09:53:12 INFO - PID 2444 | [#11] stanford-crypto-ccm Cycles:10 Average:151.60 Median:153.00 stddev:11.66 (7.6%) stddev-sans-first:11.29 09:53:12 INFO - PID 2444 | Values: 138.0 164.0 152.0 139.0 153.0 153.0 156.0 141.0 145.0 175.0 09:53:12 INFO - PID 2444 | 09:53:12 INFO - PID 2444 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:191.00 Median:183.00 stddev:23.09 (12.6%) stddev-sans-first:24.26 09:53:12 INFO - PID 2444 | Values: 182.0 185.0 181.0 176.0 176.0 179.0 184.0 179.0 241.0 227.0 09:53:12 INFO - PID 2444 | 09:53:12 INFO - PID 2444 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:81.00 Median:86.00 stddev:13.17 (15.3%) stddev-sans-first:12.63 09:53:12 INFO - PID 2444 | Values: 65.0 68.0 68.0 81.0 99.0 91.0 97.0 94.0 75.0 72.0 09:53:12 INFO - PID 2444 | -------- Summary: end -------- 09:53:12 INFO - PID 2444 | 09:53:13 INFO - TEST-INFO | 2444: exit 0 09:53:13 INFO - TEST-OK | kraken | took 91452ms 09:53:13 INFO - SUITE-END | took 1235s 09:53:13 INFO - Completed test suite (00:20:35) 09:53:13 INFO - javascript benchmark 09:53:13 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8130.974949468802, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3420.042388131321, 3508.1755787389966, 3555.3244883472153, 3561.4479755637885, 3570.0717813368524, 4872.902541949159, 5198.298229738984, 5966.46301832835, 6105.236845394325, 6113.847153821157, 5908.704564771755, 5994.160817485545, 6075.908861367072, 6092.086187071942, 6097.847553811157, 5221.582273418117, 5230.476952304777, 5270.0, 5288.15389537674, 5363.651362661434, 4868.853934381974, 5001.374828146481, 5300.939812037589, 5352.5182733553975, 5372.167314066326, 5298.1522956327, 5313.282706834567, 5323.307969963899, 5339.305890234265, 5340.439253878345, 5293.6294459387855, 5325.094733895208, 5369.677819330853, 5374.5700343972385, 5392.811251606175, 5274.498922602346, 5305.257263983045, 5312.6281160318795, 5348.652337598043, 5367.409584945653, 4987.750612469398, 6040.472663244903, 6073.969630151865, 6113.816585502397, 6219.440250377488, 6349.333320001379, 6389.520785941073, 6446.871062578723, 6498.8050358488845, 6506.21925368954, 6422.775202867924, 6527.151470308877, 6601.603903765741, 6647.335266473314, 6670.09953656255, 6071.180390647294, 6331.39851714091, 6346.27017892939, 6368.076628888832, 6368.745250189987, 6352.2694890087305, 6764.932350676529, 6768.56004359715, 6776.28848970851, 6878.209005964278, 5735.340435849847, 6116.623759654131, 6449.645269510128, 6459.806205813833, 6487.740490380332, 6406.199225096863, 6428.389303016206, 6430.196225471816, 6437.25971513282, 6457.709403076873, 6016.217891674054, 6369.108324834527, 6461.321561236096, 6489.8702025960165, 6499.05763664266, 6468.514861385415, 6487.286398496161, 6487.740490380426, 6490.545661803629, 6505.21937367519, 5090.0, 5123.692578445305, 5126.333576635088, 5136.486351364908, 5151.7681704323395, 3992.261431635157, 4638.5593368629925, 4662.906741865144, 4707.741074240949, 4719.787609557509, 6161.876762464815, 6283.340249273851, 6322.304546499881, 6334.683265836689, 6357.523185760994, 6579.769708060195, 6600.80197594073, 6617.8014659560295, 6638.369354911276, 6650.102236198148, 6115.296740874767, 6139.3388173831, 6164.6917654117115, 6177.81466556004, 6287.402696743802, 4787.473377928425, 5654.321481422256, 5702.344230413472, 5724.969505489052, 5732.6847023413275, 5642.8839185305515, 6673.466122710074, 6730.899036514456, 6747.190337159474, 6786.660666966731, 6571.5399922005, 6614.900776488453, 6615.536912416084, 6629.270780214076, 6650.268470468148, 6728.226253980659, 6739.797806065826, 6746.898796517958, 6768.4585233182215, 6780.084688566981, 6437.356264373525, 6469.159009328757, 6483.675816209265, 6495.675216239264, 6495.740170393131, 6385.393387628168, 6440.6135631862235, 6459.709313080829, 6468.320826313357, 6519.282878883414, 6347.174867267226, 6368.108464814837, 6380.649064301508, 6382.0, 6397.392247736458, 6376.681165941777, 6385.425311721781, 6386.840328991812, 6390.808275751735, 6417.679116044085, 6289.0, 6365.363463653597, 6373.936260637334, 6374.649394283359, 6412.166418365583, 6306.211723534559, 6312.495000399865, 6392.456641185551, 6408.679566021773, 6413.935860641333, 5285.392179899361, 6185.567010309235, 6208.937910620836, 6228.252609686867, 6347.777827776005], "value": 5943.534455735895, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [760.296725528886, 794.9244821741933, 813.7762115418259, 836.5649862071723, 851.7870532366908, 38419.61580384195, 39315.80342098289, 39319.0170245744, 39320.0, 39405.0, 38654.06729663513, 38815.0, 38998.415023774665, 39008.41487377685, 39035.609643903554, 7433.219511951248, 7566.0, 7567.810804729871, 7582.241775822429, 7601.543907365548, 7407.259274072577, 7508.474406791527, 7514.661840217176, 7523.285287897641, 7543.207963163871, 7446.590437525934, 7448.068991376078, 7461.738839140631, 7471.887921681179, 7494.587797671126, 7416.8887466687775, 7470.962645186739, 7471.028766260379, 7473.962630186841, 7483.176850546435, 6751.324867513258, 6785.185777706657, 6812.557183783038, 6813.454923606099, 6813.466969931776, 34771.65228347735, 34876.47685284723, 35060.64939350599, 35138.47292290618, 35181.29637407263, 3335.199552107501, 3361.8991430256888, 3362.646922073196, 3366.8316584170693, 3376.9493457598155, 27105.593416098578, 27359.316017099532, 27467.45065098687, 27484.763185656695, 27517.86241068802, 2712.403271280335, 2765.1704488653527, 2830.1933948824694, 2831.6602007758993, 2864.1264414353614, 230959.0, 234244.82877585673, 234547.0, 295754.0, 295877.0, 37679.11604419768, 45707.77146114248, 45725.54274457213, 45961.0, 46574.30138547857, 49812.0037599246, 49938.50061499338, 49939.25091123636, 49999.75000125049, 50007.74996125069, 13932.860671393359, 14038.929805350908, 14225.430982760781, 14246.287685615678, 14247.145171289756, 11233.269837460539, 11338.582677165354, 11420.714482138013, 11523.366214858263, 11570.305781653127, 13560.186388816703, 13628.65928351799, 13635.0, 13635.795463068062, 13744.725105498033, 5113.693178409308, 5327.81352652663, 5820.748539064121, 6017.789377371859, 6114.938850611526, 1014.3254735600743, 1028.4189432970347, 1030.8144533983805, 1082.37222411003, 1087.8912108789057, 23306.24388490622, 23385.649215261783, 23498.882505587364, 23605.7639423607, 24741.62887556688, 1058.9788204235872, 1099.780043991205, 1113.6714669172613, 1153.7750138722868, 1154.0, 11512.712182195344, 11676.299422034705, 11920.46357913896, 12020.45907934145, 12359.32023738685, 312091.0, 314931.0, 316243.0, 316395.0, 316727.0, 54603.0, 54684.72657636766, 54786.0, 54850.17724734132, 54984.725076375165, 1354.9322533873465, 1495.5438591229563, 1542.228885557199, 1551.1468692219235, 1554.1995872125865, 18435.631287374446, 21884.219301442576, 22401.66397504006, 22716.0, 24074.759252407952, 38395.6160438392, 38541.65104221339, 38641.8407447777, 38666.22667546577, 38704.41943370795, 229979.0, 232565.0, 278115.0, 278253.0, 278865.0, 29729.405411892072, 31833.045008649777, 32284.0, 33130.834345828116, 33547.0, 39889.80055099706, 40608.0, 41999.32002719979, 42742.0, 43001.0, 27251.72748272492, 27702.307442314104, 29171.41657166802, 30548.847255764467, 31521.52717709282, 27370.31574210581, 30463.238419039702, 30755.53866692046, 30906.0, 31502.842485787423, 37528.624713753605, 37588.248235035695, 37608.81195594114, 37649.81175094107, 37698.2460350786, 26708.732912670625, 28759.0, 29966.550501742055, 31293.374132517678, 31456.528152078194, 23461.413464662837, 24760.25719228426, 24828.00687972461, 25011.0, 25209.873950630128], "value": 16701.259277649868, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [500.09982032341776, 500.70208226105456, 506.5112166759077, 510.77781165193136, 530.9017831701138, 20942.0, 21226.46933826655, 21504.92475376231, 22008.33974980751, 22229.888850555766, 23164.536709265845, 23823.28530144094, 23846.165384211556, 24020.75979240207, 24020.87989560054, 5996.8200953971345, 6262.3111457739615, 6276.717547710364, 6277.995520716686, 6296.150019747344, 6128.6322820630685, 6222.533310001768, 6232.034034724602, 6233.532485063638, 6234.688265586725, 5684.0, 5809.941900581003, 5994.760209591611, 6027.126066720323, 6059.0, 6075.270967483908, 6126.081087836816, 6127.693615319216, 6146.1395404642935, 6148.784792532262, 5590.720463976785, 5591.021571225019, 5608.2428872102155, 5610.859728506779, 5613.0, 25843.870780646164, 27456.45087098267, 27540.862295688392, 27633.447331053467, 27831.443371132667, 2148.4628842789302, 2241.9887900560393, 2246.1576908659254, 2265.1732117777055, 2271.636538153904, 15111.622209444851, 15175.62060948474, 17859.374921877803, 18403.171857266454, 18429.539261518436, 1808.5297822566097, 1934.1006432009042, 2143.6355819510723, 2206.6910632511463, 2208.03950281628, 159519.20240398723, 165277.17361413236, 167377.0, 167631.3236867628, 190113.0, 12791.0, 13480.730385392433, 14335.78496322556, 14683.92658036703, 14712.632184195267, 17365.479035628952, 18177.63644727098, 19206.327778527684, 19279.80720192808, 19356.61286774285, 9327.347085704072, 9390.85913711295, 9399.90600094004, 9436.0, 9501.382410143318, 8696.260817830429, 8709.390342676079, 8711.259542938795, 8782.736517904474, 8847.0, 7197.676104575306, 8601.483910965362, 8658.91341086581, 8694.304455643532, 8713.825723485621, 2196.2313190383557, 2586.7801236894697, 2597.7921766258655, 2603.114940920096, 2605.0751983046125, 976.6958586908091, 986.5954958466995, 987.807377561383, 1034.9586016559404, 1052.7210289273348, 20018.299359522345, 20285.188592456136, 20462.283820066525, 20873.89563052175, 21026.158953641683, 923.6582464488182, 987.8468837330082, 1028.7788125553043, 1032.7727899862018, 1062.7715041266165, 9182.770430739285, 9646.565904534313, 10108.350158926829, 10535.947320263349, 10584.153267738562, 578728.0, 589357.0, 597487.0, 606119.0, 608178.0, 144161.0, 156296.21851890668, 156880.0, 156951.0, 157014.0, 1028.5628607841786, 1077.138289368502, 1082.53992053377, 1085.8154113800672, 1211.1521934645784, 18785.6242875139, 20505.794942050987, 23074.769252307262, 23382.64926026145, 23504.76495235026, 69944.0, 74047.0, 75146.12134696676, 75418.0, 75840.24159758553, 90325.09674903167, 91972.0, 92679.60980585431, 94362.0, 95041.5247923756, 11281.548738050386, 11294.152938529807, 11388.601398950997, 11420.029297509473, 11438.771224575628, 17631.559211019827, 17972.10139493046, 18070.909645452215, 18235.0882455874, 18271.0, 12162.695932601755, 13994.300284985506, 14752.0, 14858.702825943205, 14957.252137392868, 12635.0, 12877.871221288042, 12928.676783080122, 13466.596002119952, 13547.051706380458, 13216.603501894959, 13338.732820382049, 13478.258695771827, 13509.527166549124, 13530.79703804424, 11211.0, 11255.380954047607, 11272.605458808903, 11349.4892729829, 11365.0, 11773.0, 12916.22502649844, 12927.418266177856, 12959.0, 14206.21865797391], "value": 11294.379435737987, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1218.750156217975, 1269.3272565540267, 1359.857214992425, 1447.5729659750373, 1462.8464011278816, 10364.948175259122, 13468.461261549539, 13501.392437340319, 13514.121582097157, 13588.524401645944, 11998.400079996009, 12045.457954392052, 13042.413091410886, 13308.46766129356, 13407.396667149977, 2570.4859028194437, 2729.672439307286, 2767.51568475517, 2771.210205091549, 2771.5426954552477, 2710.9728902711017, 2750.7111753265917, 2754.2838861895852, 2756.324700448393, 2764.2398340456334, 2250.3923940536047, 2317.2353123469215, 2333.311673056444, 2690.6771187457534, 2744.108164846423, 2296.8851557422145, 2659.2288236411514, 2736.0560606590693, 2746.134967485246, 2750.174947515757, 2720.306321887906, 2724.00573790567, 2728.4679487499943, 2730.085421383819, 2730.9726902730918, 13120.934395327962, 13298.86701132986, 13303.201807891557, 13312.733745325137, 13314.80027799574, 7350.669219885118, 7602.2397760224085, 7662.65518051689, 7668.156502784689, 7672.386209103258, 3995.9400608990886, 4481.395011673415, 4778.569928706398, 4784.712917224942, 4798.856034318976, 1357.5438475857436, 1387.8750912417931, 1411.3648858013935, 1412.2656218766183, 1413.258039529245, 19450.70823937628, 19544.31594894186, 19689.015549222626, 19742.60514789696, 19785.307514236934, 5455.536279416215, 5500.972495137498, 6276.403741644536, 6518.1200537927725, 6554.508411869129, 11604.535818567332, 13866.0, 13953.162810231215, 13961.581152565439, 14001.789973150411, 7230.855382892418, 7298.854022919512, 7353.485256032026, 7379.114506259283, 7393.297636724503, 6588.40704336612, 6751.831204219934, 6764.627945463047, 6789.0834737310815, 6834.8633027340165, 4708.505606911293, 4868.902621947541, 4933.629977751683, 4943.456219815817, 4959.801607935714, 1441.2865631512468, 1463.6121427821636, 1464.9707005859823, 1486.0637798187072, 1487.5537338798533, 805.8912046873597, 815.8822413293707, 819.4018366592302, 829.2785276809094, 854.7136709202487, 6918.308169183142, 7129.322714342129, 7160.749373771997, 7174.174969878426, 7181.671390792616, 644.3331152257484, 672.7073722930443, 710.722818100941, 712.7042277454901, 714.3749219432996, 4567.862964111016, 4575.81696732127, 4757.215059515142, 5079.238114282887, 5590.161475778666, 17280.106971615693, 17871.642567148843, 17941.7308740369, 17952.640947181244, 18386.264549417872, 17490.387836425663, 17847.643047139245, 18163.818361816477, 18177.81822181761, 18307.63384732298, 9034.503102329303, 9252.028537003653, 9405.717828465156, 9444.244460443286, 9479.573419196153, 7389.8522029558035, 7723.922760772545, 7970.641321140679, 7991.360691144579, 8078.474899131537, 16160.757588635945, 16247.187640618158, 16248.26882790301, 16905.830941690427, 16922.900011499212, 15750.133742643806, 16514.091724955244, 16962.0, 16988.405405810736, 17008.319667213174, 10316.0, 10664.733381665521, 10674.839877402, 10857.782844343226, 10875.129989600655, 12502.499900004263, 12624.368781561068, 12636.30500322491, 12637.620871373874, 12650.683732906751, 9486.33595648298, 9832.950835245778, 9837.85243221338, 10103.898961010296, 10188.898111018794, 9536.713898583053, 9645.553167025002, 9661.420314781135, 9875.222459957276, 9917.702468925943, 7578.924210757822, 8438.746837594881, 8906.643734250558, 8962.058983806606, 8963.775905602412, 6479.5788273762055, 6639.601623902581, 6863.210730765826, 6992.300769922967, 7059.25877782846, 6727.764528241488, 6794.966025169842, 6945.548539344926, 7096.822579435556, 7105.218425973036], "value": 5942.515685725066, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [421.7511668115812, 428.47725774555033, 441.67316186022333, 455.8928651766834, 466.53113620811104, 6426.389492998163, 6431.807045788634, 6440.488209598255, 6485.805425837232, 6499.057636642689, 5092.745362731858, 5393.973030134854, 5804.738786754596, 6269.275949113984, 6277.968610156954, 3927.56796752357, 4524.5701658342405, 4709.835155769549, 4728.858134255978, 4735.905281894368, 4582.816687332519, 4663.673542852001, 4702.882427939295, 4729.976350118244, 4738.2892566115015, 4679.947011922307, 4711.623070154379, 4713.198756211452, 4713.528647135293, 4715.174844402233, 4397.692161548693, 4465.173942820572, 4537.160625284333, 4595.586397224266, 4606.401167848192, 3665.52348194733, 4067.5729048449784, 4077.225327187825, 4084.7140700151003, 4099.815508302134, 5808.941910580882, 6069.939300606981, 6085.117657939574, 6094.725737341831, 6187.752489900398, 5617.354004289518, 5622.269105016322, 5627.465390787868, 5648.999999999959, 5666.433356664342, 6799.660016999131, 6814.420774234196, 6815.931840681579, 6826.624535650537, 6871.690773915186, 4712.6229901607785, 4723.0, 4730.172219861545, 4731.077439899221, 4737.715737055753, 5702.45826646468, 6797.218319893226, 7216.8556628867655, 7231.192201949512, 7254.129504459446, 4799.448063472745, 4818.734969576707, 4828.95171048285, 4830.710157390497, 4836.153673107263, 4231.597998190167, 4852.320675105488, 4909.459959404463, 4913.508649135058, 4918.803247870045, 5655.858603534863, 5664.376918538957, 5667.744951477193, 5684.37471878093, 5695.94624994369, 6858.3827455529245, 6861.451083913275, 6864.107666003388, 6865.0, 6878.140232470942, 1531.4716422834103, 1674.3135314520987, 1674.9280460505286, 1676.6898123847127, 1699.2862997541063, 951.8857737071596, 1116.9050630696481, 1117.944102794857, 1130.7229728716518, 1136.8010598145293, 5536.114221724585, 6137.84655383619, 6293.37066293367, 6300.747970081237, 6407.935920640827, 5667.631603945731, 6963.7562685305775, 7066.929330706627, 7091.0, 7125.857482850418, 5057.822976195889, 5408.675479471244, 6156.26124865019, 6442.903356449657, 6452.580582262138, 3510.7542472027226, 3626.2747450510005, 3636.5999740028574, 3639.52686150804, 3646.252518233756, 7186.029885965328, 7200.747973820891, 7211.639418029077, 7226.6386680665755, 7227.168875579376, 7250.709971601077, 7279.381252593589, 7303.7443689471675, 7317.743878964211, 7320.341169294789, 6337.904931426125, 7185.6766445510075, 7260.745873894389, 7262.854742905112, 7269.854602908018, 6388.936110638834, 6634.535582509371, 7023.981522679285, 7090.97180908755, 7132.572045677276, 5796.478316951495, 6457.741690332522, 6693.732250709917, 6835.658217089225, 6968.466937273792, 6599.505037122139, 7256.7460138894885, 7261.49169558147, 7264.0, 7283.156700793913, 3973.821178046937, 4332.548596220176, 4558.612517936012, 4771.618270538279, 4774.40319960005, 6162.630242185483, 6942.750304945158, 7062.8940565890525, 7065.385807251145, 7094.858102838018, 5335.171669638983, 5935.910961335497, 6604.339566043357, 6783.861844274889, 6786.830329241611, 5852.239208902815, 6186.566940314135, 6221.502279817513, 6753.459723222229, 6777.762778302736, 5153.922691159561, 5274.736263186902, 6529.249136349381, 6602.599661174595, 6642.335766423319, 6750.5274630775375, 6758.628275444898, 6759.222689390783, 6782.287859774653, 6866.725330986705, 5541.916871246854, 6267.655278959701, 6437.871242575216, 6478.5141114415665, 6686.866262674816], "value": 4974.42590196474, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [722.6567380494263, 808.5674164322081, 816.3673153306189, 846.9872951905722, 851.1190917400492, 16740.246688899, 17027.829721702794, 17281.91359043206, 17304.82695173048, 17326.220320085595, 12989.480420783157, 13414.128081674684, 13605.183688978668, 16020.919895400535, 16857.325706971737, 977.7506735782365, 978.2223132609557, 979.7697541077841, 988.5502096546054, 990.2078337330107, 970.3353203055894, 995.945223012734, 996.0836030851634, 997.1623835977778, 1007.1137399088792, 1038.6728180623118, 1051.3586712105648, 1054.125076186767, 1057.370864335719, 1064.4252103863905, 939.3189937295474, 974.3763991045737, 1035.4046423306554, 1035.8912314207014, 1044.394251334232, 679.0527214535745, 750.740994356946, 751.6204316820025, 760.7793739815447, 766.2299389113905, 16287.0, 17336.479905602853, 17384.913075434542, 17500.737488937677, 17531.386401475887, 17196.656066878717, 17360.392386266547, 17431.912840435845, 17543.035133067675, 17576.38482653114, 14461.421543138262, 14718.116912985255, 14800.481983130647, 14811.629709257248, 14894.180820054893, 3580.2481478889463, 3589.6589823966683, 3618.077390265492, 3619.1314084619767, 3620.2759448110482, 101778.0, 135509.0, 147244.0, 156283.2185839075, 159219.40780592276, 13416.798748018788, 13440.32798360078, 13497.730045399037, 13504.932475337757, 13683.657908552366, 26321.73678263231, 26674.466510669678, 26846.926122955254, 26891.596626050625, 26926.865365673046, 7419.591922444318, 7677.577733224614, 7783.260590244031, 7974.122846486882, 7974.843647671055, 3687.354712925281, 3956.723029387915, 3957.742746721454, 3959.703022273341, 3976.4830572025453, 4516.412866327357, 4570.337301091323, 4613.146567884952, 4626.583607475343, 4629.027904140168, 1001.979960400788, 1025.195221750922, 1025.6410256410197, 1026.0765311219961, 1027.8355463125863, 622.0748829953251, 650.9869802603921, 651.4560342114293, 667.7762949411904, 669.8425869920565, 11343.829842552368, 11635.900452620659, 11776.88223117775, 12169.0, 12398.0, 606.8907596632648, 645.502962718704, 667.2926697700453, 668.5153263883743, 694.5277211496131, 4597.218472859622, 5322.281491998608, 5524.75138618755, 5807.912881306784, 5858.443447872445, 105062.0, 115256.0, 124777.0, 124897.37551312185, 138163.0, 113354.43322783333, 115282.42358788152, 115573.4221328888, 115589.26616100765, 123326.38336808438, 93965.06034939563, 95618.52190739002, 97645.0, 99765.0, 104119.0, 10956.671299861016, 11072.889271107186, 11151.386673733023, 11185.38480383554, 11331.150163737917, 42406.0, 42608.0, 45134.77432612816, 45612.77193614011, 46221.53778462172, 76147.61926190555, 76973.61513192399, 77281.0, 85320.0, 85339.0, 16485.928414653008, 17603.647927041133, 17727.29090836352, 17931.462056138338, 17948.012859292863, 17118.400855970478, 18370.72443913369, 18396.080195989878, 19225.51936201606, 19279.903600481906, 17249.568760780578, 17499.0, 18313.3590324338, 18367.632647347247, 18405.355812546495, 17116.572085697957, 17491.300347985936, 17522.474325770247, 17644.0, 17646.647067058842, 16304.918475407547, 17436.389726360023, 17510.124493775515, 17765.73351399756, 17809.198586063398, 11650.610084290858, 11957.880421196025, 12510.874891251335, 12525.123241373016, 12721.55474558386, 11288.9435552825, 11350.864913508583, 12299.200551964093, 12564.0, 13156.934215328862], "value": 8718.842136821759, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1575.7, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [108.0, 118.0, 118.0, 126.0, 118.0, 118.0, 115.0, 116.0, 116.0, 159.0], "value": 121.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [134.0, 113.0, 151.0, 138.0, 112.0, 132.0, 132.0, 114.0, 156.0, 110.0], "value": 129.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [204.0, 203.0, 200.0, 220.0, 227.0, 220.0, 224.0, 205.0, 215.0, 249.0], "value": 216.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 83.0, 74.0, 149.0, 80.0, 64.0, 69.0, 91.0, 99.0, 66.0], "value": 84.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 100.0, 150.0, 86.0, 59.0, 57.0, 92.0, 62.0, 96.0, 144.0], "value": 91.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 89.0, 92.0, 126.0, 81.0, 133.0, 135.0, 90.0, 90.0, 91.0], "value": 101.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 98.0, 121.0, 147.0, 148.0, 96.0, 97.0, 97.0, 99.0, 100.0], "value": 109.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [98.0, 93.0, 93.0, 98.0, 90.0, 91.0, 92.0, 92.0, 91.0, 93.0], "value": 93.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [50.0, 50.0, 54.0, 51.0, 53.0, 47.0, 52.0, 60.0, 69.0, 76.0], "value": 56.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 59.0, 86.0, 91.0, 98.0, 64.0, 59.0, 57.0, 57.0, 56.0], "value": 68.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 79.0, 79.0, 82.0, 79.0, 83.0, 80.0, 81.0, 80.0, 80.0], "value": 79.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [138.0, 164.0, 152.0, 139.0, 153.0, 153.0, 156.0, 141.0, 145.0, 175.0], "value": 151.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [182.0, 185.0, 181.0, 176.0, 176.0, 179.0, 184.0, 179.0, 241.0, 227.0], "value": 191.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 68.0, 68.0, 81.0, 99.0, 91.0, 97.0, 94.0, 75.0, 72.0], "value": 81.0, "unit": "score"}]}]} 09:53:13 INFO - Return code: 0 09:53:13 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:53:13 INFO - # TBPL SUCCESS # 09:53:13 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:53:13 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:53:13 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:53:13 INFO - 2017-06-12 09:53:13.855 screenresolution[2452:29656] starting screenresolution argv=screenresolution get 09:53:13 INFO - 2017-06-12 09:53:13.860 screenresolution[2452:29656] Display 0: 1600x1200x32@60 09:53:13 INFO - 2017-06-12 09:53:13.866 screenresolution[2453:29659] starting screenresolution argv=screenresolution list 09:53:13 INFO - Available Modes on Display 0 09:53:13 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:53:13 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:53:13 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:53:13 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:53:14 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:53:14 INFO - Intel Iris: 09:53:14 INFO - Chipset Model: Intel Iris 09:53:14 INFO - Type: GPU 09:53:14 INFO - Bus: Built-In 09:53:14 INFO - VRAM (Dynamic, Max): 1536 MB 09:53:14 INFO - Vendor: Intel (0x8086) 09:53:14 INFO - Device ID: 0x0a2e 09:53:14 INFO - Revision ID: 0x0009 09:53:14 INFO - Displays: 09:53:14 INFO - Display: 09:53:14 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:53:14 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:53:14 INFO - Display Serial Number: 1600x1200 60 09:53:14 INFO - Main Display: Yes 09:53:14 INFO - Mirror: Off 09:53:14 INFO - Online: Yes 09:53:14 INFO - Rotation: Supported 09:53:14 INFO - Return code: 0 09:53:14 INFO - Running post-action listener: _resource_record_post_action 09:53:14 INFO - [mozharness: 2017-06-12 16:53:14.108977Z] Finished run-tests step (success) 09:53:14 INFO - Running post-run listener: _resource_record_post_run 09:53:14 INFO - Total resource usage - Wall time: 1258s; CPU: 53.0%; Read bytes: 1970688; Write bytes: 574759936; Read time: 18; Write time: 1757 09:53:14 INFO - TinderboxPrint: CPU usage
53.4% 09:53:14 INFO - TinderboxPrint: I/O read bytes / time
1,970,688 / 18 09:53:14 INFO - TinderboxPrint: I/O write bytes / time
574,759,936 / 1,757 09:53:14 INFO - TinderboxPrint: CPU idle
2,344.3 (46.6%) 09:53:14 INFO - TinderboxPrint: CPU system
1,027.6 (20.4%) 09:53:14 INFO - TinderboxPrint: CPU user
1,661.8 (33.0%) 09:53:14 INFO - TinderboxPrint: Swap in / out
254,865,408 / 0 09:53:14 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 245631488; Write bytes: 236109824; Read time: 17912; Write time: 510 09:53:14 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 09:53:14 INFO - run-tests - Wall time: 1237s; CPU: 53.0%; Read bytes: 1106432; Write bytes: 320304128; Read time: 15; Write time: 1195 09:53:14 INFO - Running post-run listener: _upload_blobber_files 09:53:14 INFO - Blob upload gear active. 09:53:14 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:53:14 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 09:53:14 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', 'Cedar', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 09:53:14 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 Cedar -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 09:53:15 INFO - (blobuploader) - INFO - Open directory for files ... 09:53:15 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 09:53:15 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:53:15 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:53:16 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 09:53:16 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:53:16 INFO - (blobuploader) - INFO - Done attempting. 09:53:16 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 09:53:16 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:53:16 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:53:17 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 09:53:17 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:53:17 INFO - (blobuploader) - INFO - Done attempting. 09:53:17 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 09:53:17 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:53:17 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:53:18 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 09:53:18 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:53:18 INFO - (blobuploader) - INFO - Done attempting. 09:53:18 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:53:18 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:53:18 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:53:20 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:53:20 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:53:20 INFO - (blobuploader) - INFO - Done attempting. 09:53:20 INFO - (blobuploader) - INFO - Iteration through files over. 09:53:20 INFO - Return code: 0 09:53:20 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:53:20 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:53:20 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/5ab7a291d4e46ddd8ce9a2d9c5e41afa37079bf657a07dd0bb31731355b103fcd41cc564aec5d578a3c3fff7ea2bddbcf4e5d712258bcae26c84e4e49cc5286b", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/549e1ff7cbe1d9830810a14a296131698638ffb9d440cab359ccddd6c1a6867f9c07f0a067ca7032ecfe70f9b646561c80e5ece216c8aeede9e491c48c7e0594", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/ed37f873896c60485036a5a7f6570d366ee4370aa51aa5765680597527e1381aa54219e4525454027b9ba7e6a5e9bf237d2abf72e8e56068397938bcf77f0ba7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/e2b5ed117719b4176d7f6a33275d22e44a3ac36fbe856e8967ebdcd219dafbf05f152bf7371f55f8f669d1eb7b378e6342fbe15933d802446f44c7363e9ef907"} 09:53:20 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:53:20 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:53:20 INFO - Contents: 09:53:20 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/5ab7a291d4e46ddd8ce9a2d9c5e41afa37079bf657a07dd0bb31731355b103fcd41cc564aec5d578a3c3fff7ea2bddbcf4e5d712258bcae26c84e4e49cc5286b", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/549e1ff7cbe1d9830810a14a296131698638ffb9d440cab359ccddd6c1a6867f9c07f0a067ca7032ecfe70f9b646561c80e5ece216c8aeede9e491c48c7e0594", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/ed37f873896c60485036a5a7f6570d366ee4370aa51aa5765680597527e1381aa54219e4525454027b9ba7e6a5e9bf237d2abf72e8e56068397938bcf77f0ba7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/e2b5ed117719b4176d7f6a33275d22e44a3ac36fbe856e8967ebdcd219dafbf05f152bf7371f55f8f669d1eb7b378e6342fbe15933d802446f44c7363e9ef907"} 09:53:20 INFO - Running post-run listener: copy_logs_to_upload_dir 09:53:20 INFO - Copying logs to upload dir... 09:53:20 INFO - mkdir: /builds/slave/test/build/upload/logs 09:53:20 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1341.046842 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 21 secs) (at 2017-06-12 09:53:20.728200) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-12 09:53:20.731651) ========= 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.NmfLK4p76s/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.oG48nOVXTR/Listeners TMPDIR=/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/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/Cedar/sha512/5ab7a291d4e46ddd8ce9a2d9c5e41afa37079bf657a07dd0bb31731355b103fcd41cc564aec5d578a3c3fff7ea2bddbcf4e5d712258bcae26c84e4e49cc5286b", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/549e1ff7cbe1d9830810a14a296131698638ffb9d440cab359ccddd6c1a6867f9c07f0a067ca7032ecfe70f9b646561c80e5ece216c8aeede9e491c48c7e0594", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/ed37f873896c60485036a5a7f6570d366ee4370aa51aa5765680597527e1381aa54219e4525454027b9ba7e6a5e9bf237d2abf72e8e56068397938bcf77f0ba7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/e2b5ed117719b4176d7f6a33275d22e44a3ac36fbe856e8967ebdcd219dafbf05f152bf7371f55f8f669d1eb7b378e6342fbe15933d802446f44c7363e9ef907"} build_url:https://queue.taskcluster.net/v1/task/YR3vUOswSfKRSevxqb17gg/artifacts/public/build/firefox-56.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011114 build_url: 'https://queue.taskcluster.net/v1/task/YR3vUOswSfKRSevxqb17gg/artifacts/public/build/firefox-56.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/5ab7a291d4e46ddd8ce9a2d9c5e41afa37079bf657a07dd0bb31731355b103fcd41cc564aec5d578a3c3fff7ea2bddbcf4e5d712258bcae26c84e4e49cc5286b", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/549e1ff7cbe1d9830810a14a296131698638ffb9d440cab359ccddd6c1a6867f9c07f0a067ca7032ecfe70f9b646561c80e5ece216c8aeede9e491c48c7e0594", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/ed37f873896c60485036a5a7f6570d366ee4370aa51aa5765680597527e1381aa54219e4525454027b9ba7e6a5e9bf237d2abf72e8e56068397938bcf77f0ba7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/e2b5ed117719b4176d7f6a33275d22e44a3ac36fbe856e8967ebdcd219dafbf05f152bf7371f55f8f669d1eb7b378e6342fbe15933d802446f44c7363e9ef907"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-12 09:53:20.770873) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-12 09:53:20.771656) ========= 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.NmfLK4p76s/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.oG48nOVXTR/Listeners TMPDIR=/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/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.005169 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-12 09:53:20.822363) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-12 09:53:20.822721) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-12 09:53:20.823089) ========= ========= Total master_lag: 0.20 =========