执行tidevice xctest报错为xctrunner quited about taobao-iphone-device

[codeskyblue]

这退出的够快的啊
用下面的这个命令跑跑看看

DEBUG=true tidevice xctest  

[xiaoxiaoshao]

debug了下，发现貌似没有print ServerURLHere ,我看代码注释说In low iOS versions, 'Using singleton test manager' may not be printed... mark wda launch status = True if server url has been printed，但是我的版本蛮高的14.4.1，有没有可能我wda包有问题~

[codeskyblue]

你好像装错包了

[xiaoxiaoshao]

debug了下，发现貌似没有print ServerURLHere ,我看代码注释说In low iOS versions, 'Using singleton test manager' may not be printed... mark wda launch status = True if server url has been printed，但是我的版本蛮高的14.4.1，有没有可能我wda包有问题~

现在可以正常启动了，果然装的包有问题，xcode版本太高，在网上找攻略修复了下，谢谢大佬

[Zekylly]

debug了下，发现貌似没有print ServerURLHere ,我看代码注释说In low iOS versions, 'Using singleton test manager' may not be printed... mark wda launch status = True if server url has been printed，但是我的版本蛮高的14.4.1，有没有可能我wda包有问题~

现在可以正常启动了，果然装的包有问题，xcode版本太高，在网上找攻略修复了下，谢谢大佬

我换了低版本的xcode11.6，还是有这个问题，不知道怎么解决
zekylldeMacBook-Pro:Project zekyll$ tidevice xctest
[I 210414 13:35:20 _device:990] BundleID: com.facebook.IntegrationTestszek.xctrunner
[I 210414 13:35:20 _device:992] DeviceIdentifier: 5975536dd9ce12889a2873ce82ecb302f8347a9f
[I 210414 13:35:20 _device:834] SignIdentity: 'Apple Development: [email protected] ()'
[I 210414 13:35:20 _device:840] CFBundleExecutable: IntegrationTests_3-Runner
[I 210414 13:35:20 _device:871] AppContainer: /private/var/mobile/Containers/Data/Application/643BE368-247F-4E53-B1C8-5A221FA0F27B
[I 210414 13:35:20 _device:911] Launch 'com.facebook.IntegrationTestszek.xctrunner' pid: 893
[I 210414 13:35:20 _device:1086] ProductVersion: 12.2
[I 210414 13:35:20 _device:1116] xctrunner quited

[codeskyblue]

CFBundleExecutable: IntegrationTests_3-Runner 这个就不太正常啊
应该是WebDriverAgent-Runner才对

[FiveMiles]

安装好wda后，在window中执行tidevice xctest报错为xctrunner quited，麻烦大佬帮忙看下

大佬解决了嘛？

[Abcefg123]

debug了下，发现貌似没有print ServerURLHere ,我看代码注释说In low iOS versions, 'Using singleton test manager' may not be printed... mark wda launch status = True if server url has been printed，但是我的版本蛮高的14.4.1，有没有可能我wda包有问题~

现在可以正常启动了，果然装的包有问题，xcode版本太高，在网上找攻略修复了下，谢谢大佬

请问咋修复的，是换个低版本的xcode重新编译一次facebook的那个WDA吗？

[jeniva]

我这边手机有wda但是ios手机连不上，只有重启电脑才能恢复，，有啥办法能解决吗？我输入下面的命令提示[xctrunner quited]

DEBUG=true tidevice wdaproxy 

[D 220316 11:09:32 _safe_socket:82] Socket <tidevice._safe_socket.PlistSocket object at 0x0490B930> closed
[D 220316 11:09:32 main:72] AutoComplete udid 00008020-000D78103499002E
[D 220316 11:09:32 _safe_socket:82] Socket <tidevice._safe_socket.PlistSocket object at 0x0490B930> closed
[I 220316 11:09:32 _wdaproxy:125] [00008020-000D78103499002E] WDA check every 30.0 seconds
[D 220316 11:09:32 _safe_socket:82] Socket <tidevice._safe_socket.PlistSocket object at 0x0490BFD0> closed
[D 220316 11:09:32 _wdaproxy:131] [00008020-000D78103499002E] launch WDA
[D 220316 11:09:32 _safe_socket:82] Socket <tidevice._safe_socket.PlistSocket object at 0x0490BAB0> closed
[D 220316 11:09:32 _device:281] Send payload: {'DeviceID': 9, 'MessageType': 'Connect', 'PortNumber': 42015, 'ProgName': 'tidevice'}
[D 220316 11:09:32 _device:283] connect port: 42015
[D 220316 11:09:32 _safe_socket:82] Socket <tidevice._safe_socket.PlistSocket object at 0x0491F370> closed
[D 220316 11:09:32 _device:281] Send payload: {'DeviceID': 9, 'MessageType': 'Connect', 'PortNumber': 42015, 'ProgName': 'tidevice'}
[D 220316 11:09:32 _device:283] connect port: 42015
[D 220316 11:09:37 _safe_socket:82] Socket <tidevice._safe_socket.PlistSocket object at 0x05789BB0> closed
[D 220316 11:09:37 safe_socket:82] Socket <tidevice.safe_socket.PlistSocket object at 0x05789B50> closed
Traceback (most recent call last):
File "manis-tidevice.py", line 7, in
File "tidevice_main.py", line 809, in main
File "tidevice_main.py", line 399, in cmd_relay
File "tidevice_relay.py", line 84, in relay
File "tornado\tcpserver.py", line 151, in listen
File "tornado\netutil.py", line 161, in bind_sockets
OSError: [WinError 10048] ͨ▒▒ÿ▒▒▒׽▒▒ֵ▒ַ(Э▒▒/▒▒▒▒▒ַ/▒˿▒)ֻ▒▒▒▒ʹ▒▒һ▒Ρ▒
[21100] Failed to execute script 'manis-tidevice' due to unhandled exception!
[D 220316 11:09:37 _safe_socket:82] Socket <tidevice._safe_socket.PlistSocket object at 0x04839D10> closed
[D 220316 11:09:37 _safe_socket:82] Socket <tidevice._safe_socket.PlistSocket object at 0x04839CB0> closed
[D 220316 11:09:37 _device:281] Send payload: {'DeviceID': 9, 'MessageType': 'Connect', 'PortNumber': 32498, 'ProgName': 'tidevice'}
[D 220316 11:09:37 _device:283] connect port: 32498
[D 220316 11:09:37 _safe_socket:149] Recv pair record data ...
[D 220316 11:09:37 _safe_socket:82] Socket <tidevice._safe_socket.PlistSocket object at 0x0484B370> closed
[D 220316 11:09:38 _safe_socket:82] Socket <tidevice._safe_socket.PlistSocket object at 0x0484B350> closed
[D 220316 11:09:38 _device:281] Send payload: {'DeviceID': 9, 'MessageType': 'Connect', 'PortNumber': 33527, 'ProgName': 'tidevice'}
[D 220316 11:09:38 _device:283] connect port: 33527
[I 220316 11:09:38 _device:958] BundleID: com.ezviz.WebDriverAgentRunner.xctrunner
[D 220316 11:09:38 _device:281] Send payload: {'DeviceID': 9, 'MessageType': 'Connect', 'PortNumber': 32498, 'ProgName': 'tidevice'}
[D 220316 11:09:38 _device:283] connect port: 32498
[D 220316 11:09:38 _safe_socket:82] Socket <tidevice._safe_socket.PlistSocket object at 0x04839EF0> closed
[I 220316 11:09:38 _device:961] ProductVersion: 14.6
[I 220316 11:09:38 _device:963] DeviceIdentifier: 00008020-000D78103499002E
[D 220316 11:09:38 _device:281] Send payload: {'DeviceID': 9, 'MessageType': 'Connect', 'PortNumber': 32498, 'ProgName': 'tidevice'}
[D 220316 11:09:38 _device:283] connect port: 32498
[D 220316 11:09:38 _safe_socket:82] Socket <tidevice._safe_socket.PlistSocket object at 0x0484BE10> closed
[D 220316 11:09:38 _device:929] ProductVersion: 14.6
[D 220316 11:09:38 _device:281] Send payload: {'DeviceID': 9, 'MessageType': 'Connect', 'PortNumber': 32498, 'ProgName': 'tidevice'}
[D 220316 11:09:38 _device:283] connect port: 32498
[D 220316 11:09:38 _safe_socket:82] Socket <tidevice._safe_socket.PlistSocket object at 0x0484B810> closed
[D 220316 11:09:38 _device:281] Send payload: {'DeviceID': 9, 'MessageType': 'Connect', 'PortNumber': 34807, 'ProgName': 'tidevice'}
[D 220316 11:09:38 _device:283] connect port: 34807
[D 220316 11:09:38 _instruments:591] DTXMessage: expects_reply:0 flags:2 ('_notifyOfPublishedCapabilities:', [{'com.apple.private.DTXBlockCompression': 2, 'com.apple.private.DTXConnection': 1}])
[D 220316 11:09:38 _instruments:591] DTXMessage: expects_reply:0 flags:0 None
[D 220316 11:09:38 _device:281] Send payload: {'DeviceID': 9, 'MessageType': 'Connect', 'PortNumber': 32498, 'ProgName': 'tidevice'}
[D 220316 11:09:38 _device:283] connect port: 32498
[D 220316 11:09:39 _safe_socket:82] Socket <tidevice._safe_socket.PlistSocket object at 0x048579B0> closed
[D 220316 11:09:39 _device:929] ProductVersion: 14.6
[D 220316 11:09:39 _instruments:591] DTXMessage: expects_reply:0 flags:3 36
[D 220316 11:09:39 _device:281] Send payload: {'DeviceID': 9, 'MessageType': 'Connect', 'PortNumber': 32498, 'ProgName': 'tidevice'}
[D 220316 11:09:39 _device:283] connect port: 32498
[D 220316 11:09:39 _safe_socket:82] Socket <tidevice._safe_socket.PlistSocket object at 0x0486F8B0> closed
[D 220316 11:09:39 _device:929] ProductVersion: 14.6
[D 220316 11:09:39 _device:281] Send payload: {'DeviceID': 9, 'MessageType': 'Connect', 'PortNumber': 32498, 'ProgName': 'tidevice'}
[D 220316 11:09:39 _device:283] connect port: 32498
[D 220316 11:09:39 _safe_socket:82] Socket <tidevice._safe_socket.PlistSocket object at 0x0486FB50> closed
[D 220316 11:09:39 _device:281] Send payload: {'DeviceID': 9, 'MessageType': 'Connect', 'PortNumber': 36087, 'ProgName': 'tidevice'}
[D 220316 11:09:39 _device:283] connect port: 36087
[D 220316 11:09:39 _instruments:591] DTXMessage: expects_reply:0 flags:2 ('_notifyOfPublishedCapabilities:', [{'com.apple.private.DTXBlockCompression': 2, 'com.apple.private.DTXConnection': 1}])
[D 220316 11:09:39 _instruments:591] DTXMessage: expects_reply:0 flags:0 None
[D 220316 11:09:39 _instruments:591] DTXMessage: expects_reply:0 flags:3 36
[D 220316 11:09:39 _device:281] Send payload: {'DeviceID': 9, 'MessageType': 'Connect', 'PortNumber': 32498, 'ProgName': 'tidevice'}
[D 220316 11:09:39 _device:283] connect port: 32498
[D 220316 11:09:39 _safe_socket:82] Socket <tidevice._safe_socket.PlistSocket object at 0x04879950> closed
[D 220316 11:09:39 _device:281] Send payload: {'DeviceID': 9, 'MessageType': 'Connect', 'PortNumber': 36855, 'ProgName': 'tidevice'}
[D 220316 11:09:39 _device:283] connect port: 36855
[I 220316 11:09:39 _device:802] SignIdentity: 'Apple Development: Molly Fukuchi (35PHN77S4R)'
[I 220316 11:09:39 _device:808] CFBundleExecutable: WebDriverAgentRunner-Runner
[D 220316 11:09:39 _device:281] Send payload: {'DeviceID': 9, 'MessageType': 'Connect', 'PortNumber': 32498, 'ProgName': 'tidevice'}
[D 220316 11:09:39 _device:283] connect port: 32498

donglijuan@pc-hz20087034 MINGW64 /d/Users/donglijuan/AppData/Local/Programs/Manis UTest/libs/manisRunner
$ [D 220316 11:09:39 _safe_socket:82] Socket <tidevice._safe_socket.PlistSocket object at 0x04879950> closed
[D 220316 11:09:39 _device:281] Send payload: {'DeviceID': 9, 'MessageType': 'Connect', 'PortNumber': 37623, 'ProgName': 'tidevice'}
[D 220316 11:09:39 _device:283] connect port: 37623
[D 220316 11:09:39 _device:822] remove /tmp/WebDriverAgentRunner-ADBE7B02-8C87-47B6-89FC-1F171468BDFA.xctestconfiguration
[D 220316 11:09:39 _device:281] Send payload: {'DeviceID': 9, 'MessageType': 'Connect', 'PortNumber': 32498, 'ProgName': 'tidevice'}
[D 220316 11:09:39 _device:283] connect port: 32498
[D 220316 11:09:40 _safe_socket:82] Socket <tidevice._safe_socket.PlistSocket object at 0x04879970> closed
[D 220316 11:09:40 _device:929] ProductVersion: 14.6
[D 220316 11:09:40 _device:281] Send payload: {'DeviceID': 9, 'MessageType': 'Connect', 'PortNumber': 32498, 'ProgName': 'tidevice'}
[D 220316 11:09:40 _device:283] connect port: 32498
[D 220316 11:09:40 _safe_socket:82] Socket <tidevice._safe_socket.PlistSocket object at 0x047C7450> closed
[D 220316 11:09:40 _device:281] Send payload: {'DeviceID': 9, 'MessageType': 'Connect', 'PortNumber': 38647, 'ProgName': 'tidevice'}
[D 220316 11:09:40 _device:283] connect port: 38647
[D 220316 11:09:40 _instruments:591] DTXMessage: expects_reply:0 flags:2 ('_notifyOfPublishedCapabilities:', [{'com.apple.instruments.server.services.processcontrolbydictionary': 4, 'com.apple.instruments.server.services.graphics.coreanimation.immediate': 1, 'com.apple.instruments.server.services.assets': 4, 'com.apple.instruments.server.services.coreprofilesessiontap.config': 1, 'com.apple.instruments.target.ios': 140600, 'com.apple.instruments.server.services.coreprofilesessiontap.pmc': 2, 'com.apple.instruments.server.services.deviceinfo.dyld-tracing': 1, 'com.apple.instruments.server.services.activitytracetap.windowed': 1, 'com.apple.instruments.server.services.device.xpccontrol': 2, 'com.apple.instruments.server.services.activitytracetap': 6, 'com.apple.instruments.target.physical-cpus': 6, 'com.apple.instruments.server.services.sysmontap.system': 1, 'com.apple.instruments.server.services.sysmontap.immediate': 1, 'com.apple.instruments.server.services.objectalloc.zombies': 1, 'com.apple.instruments.server.services.deviceinfo.gcd-perf': 1, 'com.apple.instruments.server.services.activitytracetap.immediate': 1, 'com.apple.xcode.debug-gauge-data-providers.procinfo': 1, 'com.apple.instruments.target.user-page-size': 16384, 'com.apple.instruments.server.services.deviceinfo.recordOptions': 1, 'com.apple.instruments.server.services.storekit': 2, 'com.apple.instruments.server.services.graphics.coreanimation.deferred': 1, 'com.apple.instruments.server.services.deviceinfo.metal': 1, 'com.apple.instruments.server.services.device.applictionListing': 1, 'com.apple.instruments.server.services.coreprofilesessiontap': 2, 'com.apple.instruments.server.services.coreprofilesessiontap.multipleTimeTriggers': 1, 'com.apple.instruments.server.services.sampling': 11, 'com.apple.instruments.server.services.coreprofilesessiontap.windowed': 1, 'com.apple.instruments.server.services.vmtracking.deferred': 1, 'com.apple.xcode.debug-gauge-data-providers.Energy': 1, 'com.apple.instruments.server.services.processcontrol.capability.signal': 1, 'com.apple.dt.Instruments.inlineCapabilities': 1, 'com.apple.instruments.server.services.objectalloc.immediate': 1, 'com.apple.instruments.server.services.deviceinfo': 111, 'com.apple.instruments.server.services.coreprofilesessiontap.pmi': 1, 'com.apple.instruments.server.services.gpu.immediate': 1, 'com.apple.instruments.server.services.networking': 2, 'com.apple.instruments.server.services.networking.immediate': 1, 'com.apple.instruments.server.services.filetransfer': 1, 'com.apple.instruments.server.services.deviceinfo.devicesymbolication': 1, 'com.apple.instruments.server.services.sysmontap.deferred': 1, 'com.apple.instruments.target.logical-cpus': 6, 'com.apple.instruments.server.services.deviceinfo.gpu-allocation': 1, 'com.apple.instruments.server.services.deviceinfo.energytracing.location': 1, 'com.apple.instruments.server.services.power.immediate': 1, 'com.apple.instruments.server.services.deviceinfo.condition-inducer': 1, 'com.apple.instruments.server.services.ultraviolet.preview': 1, 'com.apple.instruments.server.services.sysmontap': 3, 'com.apple.instruments.server.services.gpu': 1, 'com.apple.instruments.server.services.vmtracking': 1, 'com.apple.instruments.server.services.objectalloc': 5, 'com.apple.xcode.debug-gauge-data-providers.NetworkStatistics': 1, 'com.apple.instruments.server.services.coreprofilesessiontap.deferred': 1, 'com.apple.instruments.target.mtb.numer': 125, 'com.apple.xcode.debug-gauge-data-providers.SpriteKit': 1, 'com.apple.instruments.server.services.remoteleaks.deferred': 1, 'com.apple.dt.Xcode.WatchProcessControl': 3, 'com.apple.instruments.server.services.power.deferred': 1, 'com.apple.instruments.server.services.graphics.coreanimation': 1, 'com.apple.instruments.server.services.coresampling': 10, 'com.apple.instruments.server.services.sampling.deferred': 1, 'com.apple.instruments.server.services.gpu.counters': 4, 'com.apple.instruments.server.services.remoteleaks': 6, 'com.apple.instruments.server.services.gpu.shaderprofiler': 1, 'com.apple.instruments.server.services.sysmontap.windowed': 1, 'com.apple.instruments.server.services.objectalloc.deferred': 1, 'com.apple.instruments.server.services.graphics.opengl.immediate': 1, 'com.apple.instruments.server.services.networking.deferred': 1, 'com.apple.instruments.target.mtb.denom': 3, 'com.apple.instruments.server.services.remoteleaks.immediate': 1, 'com.apple.instruments.server.services.activitytracetap.deferred': 1, 'com.apple.instruments.server.services.graphics.opengl.deferred': 1, 'com.apple.xcode.resource-control': 1, 'com.apple.instruments.server.services.sampling.immediate': 1, 'com.apple.xcode.debug-gauge-data-providers.SceneKit': 1, 'com.apple.instruments.server.services.processcontrol': 107, 'com.apple.instruments.server.services.ultraviolet.renderer': 1, 'com.apple.instruments.server.services.screenshot': 2, 'com.apple.instruments.server.services.ConditionInducer': 1, 'com.apple.instruments.server.services.deviceinfo.app-life-cycle': 1, 'com.apple.instruments.server.services.deviceinfo.systemversion': 140600, 'com.apple.instruments.server.services.processcontrol.feature.deviceio': 103, 'com.apple.instruments.server.services.assets.response': 2, 'com.apple.instruments.server.services.deviceinfo.scenekit-tracing': 1, 'com.apple.instruments.server.services.sysmontap.processes': 1, 'com.apple.instruments.server.services.coreprofilesessiontap.immediate': 1, 'com.apple.dt.services.capabilities.vmtracking': 1, 'com.apple.private.DTXConnection': 1, 'com.apple.instruments.server.services.graphics.opengl': 1, 'com.apple.instruments.server.services.vmtracking.immediate': 1, 'com.apple.instruments.server.services.mobilenotifications': 1, 'com.apple.xcode.debug-gauge-data-providers.resources': 1, 'com.apple.private.DTXBlockCompression': 2, 'com.apple.instruments.server.services.filetransfer.debuginbox': 1, 'com.apple.instruments.server.services.power': 1, 'com.apple.instruments.server.services.gpu.deferred': 1}])
[D 220316 11:09:40 _instruments:591] DTXMessage: expects_reply:0 flags:0 None
[D 220316 11:09:40 _instruments:591] DTXMessage: expects_reply:0 flags:3 892
[D 220316 11:09:40 _device:838] AppPath: /private/var/containers/Bundle/Application/758C4795-F053-4F90-8057-4D9CBD24D998/WebDriverAgentRunner-Runner.app
[I 220316 11:09:40 _device:839] AppContainer: /private/var/mobile/Containers/Data/Application/01DC9AEE-E637-4F73-AA07-24074CDA2439
[D 220316 11:09:40 _device:281] Send payload: {'DeviceID': 9, 'MessageType': 'Connect', 'PortNumber': 32498, 'ProgName': 'tidevice'}
[D 220316 11:09:40 _device:283] connect port: 32498
[D 220316 11:09:40 _safe_socket:82] Socket <tidevice._safe_socket.PlistSocket object at 0x0484BF30> closed
[D 220316 11:09:40 _device:929] ProductVersion: 14.6
[D 220316 11:09:40 _device:281] Send payload: {'DeviceID': 9, 'MessageType': 'Connect', 'PortNumber': 32498, 'ProgName': 'tidevice'}
[D 220316 11:09:40 _device:283] connect port: 32498
[D 220316 11:09:40 _safe_socket:82] Socket <tidevice._safe_socket.PlistSocket object at 0x0484B5B0> closed
[D 220316 11:09:40 _device:929] ProductVersion: 14.6
[D 220316 11:09:40 _instruments:591] DTXMessage: expects_reply:0 flags:3 896
[I 220316 11:09:40 _device:879] Launch 'com.ezviz.WebDriverAgentRunner.xctrunner' pid: 896
[D 220316 11:09:40 _instruments:591] DTXMessage: expects_reply:0 flags:0 None
[D 220316 11:09:40 _device:281] Send payload: {'DeviceID': 9, 'MessageType': 'Connect', 'PortNumber': 32498, 'ProgName': 'tidevice'}
[D 220316 11:09:40 _device:283] connect port: 32498
[D 220316 11:09:40 _safe_socket:82] Socket <tidevice._safe_socket.PlistSocket object at 0x0484BDB0> closed
[D 220316 11:09:40 _instruments:591] DTXMessage: expects_reply:0 flags:2 ('outputReceived:fromProcess:atTime:', ['2022-03-16 11:09:42.069944+0800 WebDriverAgentRunner-Runner[896:440555] Running tests...\n', 896, 1154197077930])
[D 220316 11:09:40 _device:912] logProcess: 2022-03-16 11:09:42.069944+0800 WebDriverAgentRunner-Runner[896:440555] Running tests...
[D 220316 11:09:40 _device:929] ProductVersion: 14.6
[D 220316 11:09:40 _instruments:606] Ignore notification from server: 8, 0x2, ('outputReceived:fromProcess:atTime:', ['2022-03-16 11:09:42.069944+0800 WebDriverAgentRunner-Runner[896:440555] Running tests...\n', 896, 1154197077930])
[D 220316 11:09:40 _instruments:591] DTXMessage: expects_reply:0 flags:3 True
[D 220316 11:09:40 _instruments:591] DTXMessage: expects_reply:1 flags:2 ('_requestChannelWithCode:identifier:', [1, 'dtxproxy:XCTestDriverInterface:XCTestManager_IDEInterface'])
[D 220316 11:09:40 _instruments:591] DTXMessage: expects_reply:0 flags:2 ('_notifyOfPublishedCapabilities:', [{'com.apple.private.DTXBlockCompression': 2, 'com.apple.private.DTXConnection': 1}])
[D 220316 11:09:40 _instruments:591] DTXMessage: expects_reply:1 flags:2 ('_XCT_logDebugMessage:', ['11:09:42.073 WebDriverAgentRunner-Runner[896:440555] Linked XCTest.framework from /var/containers/Bundle/Application/758C4795-F053-4F90-8057-4D9CBD24D998/WebDriverAgentRunner-Runner.app/Frameworks/XCTest.framework/XCTest, built with Xcode 1250(12E249), modified on 2021▒▒12▒▒24▒▒ ▒▒▒▒▒▒ ▒й▒▒▒׼ʱ▒▒ ▒▒▒▒10:57:27\nXCTest.framework bundle version: 18141\n'])
[D 220316 11:09:40 _instruments:591] DTXMessage: expects_reply:1 flags:2 ('_XCT_logDebugMessage:', ['11:09:42.074 WebDriverAgentRunner-Runner[896:440555] System uptime: 13:21:31\n'])
[D 220316 11:09:40 _instruments:591] DTXMessage: expects_reply:1 flags:2 ('_XCT_logDebugMessage:', ['11:09:42.074 WebDriverAgentRunner-Runner[896:440555] Process arguments: (\n "/var/containers/Bundle/Application/758C4795-F053-4F90-8057-4D9CBD24D998/WebDriverAgentRunner-Runner.app/WebDriverAgentRunner-Runner",\n "-NSTreatUnknownArgumentsAsOpen",\n NO,\n "-ApplePersistenceIgnoreState",\n YES\n)\n'])
[D 220316 11:09:40 _instruments:591] DTXMessage: expects_reply:1 flags:2 ('_XCT_logDebugMessage:', ['11:09:42.074 WebDriverAgentRunner-Runner[896:440555] Process environment: {\n "CA_ASSERT_MAIN_THREAD_TRANSACTIONS" = 0;\n "CA_DEBUG_TRANSACTIONS" = 0;\n "CFFIXED_USER_HOME" = "/private/var/mobile/Containers/Data/Application/01DC9AEE-E637-4F73-AA07-24074CDA2439";\n "DYLD_FRAMEWORK_PATH" = "/private/var/containers/Bundle/Application/758C4795-F053-4F90-8057-4D9CBD24D998/WebDriverAgentRunner-Runner.app/Frameworks:";\n "DYLD_LIBRARY_PATH" = "/private/var/containers/Bundle/Application/758C4795-F053-4F90-8057-4D9CBD24D998/WebDriverAgentRunner-Runner.app/Frameworks";\n HOME = "/private/var/mobile/Containers/Data/Application/01DC9AEE-E637-4F73-AA07-24074CDA2439";\n "LLVM_PROFILE_FILE" = "/private/var/mobile/Containers/Data/Application/01DC9AEE-E637-4F73-AA07-24074CDA2439/tmp/%p.profraw";\n LOGNAME = mobile;\n "MJPEG_SERVER_PORT" = "";\n NSUnbufferedIO = YES;\n "OS_ACTIVITY_DT_MODE" = YES;\n PATH = "/usr/bin:/bin:/usr/sbin:/sbin";\n SHELL = "/bin/sh";\n "SQLITE_ENABLE_THREAD_ASSERTIONS" = 1;\n TMPDIR = "/private/var/mobile/Containers/Data/Application/01DC9AEE-E637-4F73-AA07-24074CDA2439/tmp/";\n USER = mobile;\n "USE_PORT" = "";\n "WDA_PRODUCT_BUNDLE_IDENTIFIER" = "";\n "XCODE_DBG_XPC_EXCLUSIONS" = "com.apple.dt.xctestSymbolicator";\n XCTestConfigurationFilePath = "/private/var/mobile/Containers/Data/Application/01DC9AEE-E637-4F73-AA07-24074CDA2439/tmp/WebDriverAgentRunner-5C3511C4-4C81-4C9E-8489-955A2D2D2C9C.xctestconfiguration";\n "XPC_FLAGS" = 0x0;\n "XPC_SERVICE_NAME" = "UIKitApplication:com.ezviz.WebDriverAgentRunner.xctrunner[ff89][rb-legacy]";\n "__CF_USER_TEXT_ENCODING" = "0x1F5:0:0";\n}\n'])
[D 220316 11:09:40 _instruments:591] DTXMessage: expects_reply:1 flags:2 ('_XCT_logDebugMessage:', ['11:09:42.074 WebDriverAgentRunner-Runner[896:440555] App Sandbox active: YES\n'])
[D 220316 11:09:40 _instruments:591] DTXMessage: expects_reply:1 flags:2 ('_XCT_logDebugMessage:', ["11:09:42.074 WebDriverAgentRunner-Runner[896:440555] Creating future for 'preparing IDE session' with timeout 600.00\n"])
[D 220316 11:09:40 _instruments:591] DTXMessage: expects_reply:1 flags:2 ('_XCT_logDebugMessage:', ['11:09:42.075 WebDriverAgentRunner-Runner[896:440555] Exchanging capabilities with testmanagerd\n'])
[D 220316 11:09:40 _instruments:591] DTXMessage: expects_reply:1 flags:2 ('_XCT_logDebugMessage:', ['11:09:42.075 WebDriverAgentRunner-Runner[896:440555] <XCTWaiter: 0x283b75420, state: XCTWaiterStateInProgress> entering wait loop for 600.00s with expectations: preparing IDE session\n'])
[D 220316 11:09:40 _instruments:591] DTXMessage: expects_reply:1 flags:2 ('_XCT_logDebugMessage:', ['11:09:42.077 WebDriverAgentRunner-Runner[896:440571] Received capabilities for testmanagerd: <XCTCapabilities: 0x281274680>: {\n "Siri request with audio URL capability" = 1;\n "Siri request with text capability" = 1;\n XCTProtectedResourceBluetoothIdentifier = 1;\n XCTProtectedResourceCalendarIdentifier = 1;\n XCTProtectedResourceCameraIdentifier = 1;\n XCTProtectedResourceContactsIdentifier = 1;\n XCTProtectedResourceHealthIdentifier = 1;\n XCTProtectedResourceHomeKitIdentifier = 1;\n XCTProtectedResourceKeyboardNetworkIdentifier = 1;\n XCTProtectedResourceLocationIdentifier = 1;\n XCTProtectedResourceMediaLibraryIdentifier = 1;\n XCTProtectedResourceMicrophoneIdentifier = 1;\n XCTProtectedResourcePhotosIdentifier = 1;\n XCTProtectedResourceRemindersIdentifier = 1;\n "element at point capability" = 1;\n "enable faux collection view cells capability" = 1;\n "fetch element attributes capability" = 2;\n "fetch parameterized attribute capability" = 1;\n "get Siri enabled status capability" = 1;\n "get device orientation capability" = 1;\n "get scale for screen capability" = 1;\n "get screen identifiers capability" = 1;\n "inject assistant recognition audio paths capability" = 1;\n "inject assistant recognition strings capability" = 1;\n "launch application capability" = 1;\n "load accessibility capability" = 1;\n "look up bundle id capability" = 1;\n "monitor application capability" = 1;\n "override implicit event confirmation interval" = 1;\n "perform AX action capability" = 1;\n "perform device event capability" = 1;\n "press with pressure capability" = 1;\n "register for AX notification capability" = 1;\n "request DT service hub connection capability" = 2;\n "request a specific image format/XCTCapabilityImageFormat_HEIC" = 1;\n "request a specific image format/XCTCapabilityImageFormat_JPEG" = 1;\n "request a specific image format/XCTCapabilityImageFormat_PNG" = 1;\n "request automation session capability" = 2;\n "request background assertion capability" = 2;\n "request element snapshot capability" = 3;\n "request screenshot capability" = 3;\n "request spindump capability" = 1;\n "request unsupported bundle identifiers for automation sessions" = 1;\n "send string capability" = 1;\n "set AX timeout capability" = 1;\n "set device orientation capability" = 1;\n "set element attribute capability" = 1;\n "snapshot request runtime issue reporting" = 1;\n "snapshot request runtime issue reporting/automation type mismatch" = 1;\n "synthesize event capability" = 1;\n "system provides kAXXCAttributeBannerIsStickyAttribute" = 1;\n "terminate application capability" = 1;\n "toggle localizable strings data gathering capability" = 1;\n "transfer harness connection socket capability" = 2;\n "unregister for AX notification capability" = 1;\n}\n'])
[D 220316 11:09:40 _instruments:591] DTXMessage: expects_reply:1 flags:2 ('_XCT_logDebugMessage:', ['11:09:42.078 WebDriverAgentRunner-Runner[896:440571] Requesting serialized transport wrapper for IDE connection\n'])
[D 220316 11:09:40 _instruments:591] DTXMessage: expects_reply:1 flags:2 ('_XCT_logDebugMessage:', ['11:09:42.078 WebDriverAgentRunner-Runner[896:440571] Creating transport with received transport data\n'])
[D 220316 11:09:40 _instruments:591] DTXMessage: expects_reply:1 flags:2 ('_XCT_logDebugMessage:', ['11:09:42.079 WebDriverAgentRunner-Runner[896:440571] Creating connection with transport <DTXFileDescriptorTransport: 0x283b74c40>\n'])
[D 220316 11:09:40 _instruments:591] DTXMessage: expects_reply:1 flags:2 ('_XCT_logDebugMessage:', ['11:09:42.079 WebDriverAgentRunner-Runner[896:440571] Creating the proxy channel with connection <DTXConnection 0x282c76200 : x1>\n'])
[D 220316 11:09:40 _instruments:591] DTXMessage: expects_reply:1 flags:2 ('_XCT_logDebugMessage:', ['11:09:42.079 WebDriverAgentRunner-Runner[896:440555] Returning result XCTWaiterResultCompleted for waiter <XCTWaiter: 0x283b75420, state: XCTWaiterStateFinished>\n'])
[D 220316 11:09:40 _instruments:591] DTXMessage: expects_reply:1 flags:2 ('_XCT_logDebugMessage:', ['11:09:42.079 WebDriverAgentRunner-Runner[896:440555] Loading test bundle at: file:///private/var/containers/Bundle/Application/758C4795-F053-4F90-8057-4D9CBD24D998/WebDriverAgentRunner-Runner.app/PlugIns/WebDriverAgentRunner.xctest\n'])
[D 220316 11:09:40 _instruments:591] DTXMessage: expects_reply:1 flags:2 ('_XCT_logDebugMessage:', ['11:09:42.086 WebDriverAgentRunner-Runner[896:440555] Finished loading test bundle\n'])
[D 220316 11:09:40 _instruments:591] DTXMessage: expects_reply:1 flags:2 ('_XCT_logDebugMessage:', ['11:09:42.086 WebDriverAgentRunner-Runner[896:440555] Running tests with active test configuration: <XCTestConfiguration: 0x282c75100>\n\t testBundleURL:file:///private/var/containers/Bundle/Application/758C4795-F053-4F90-8057-4D9CBD24D998/WebDriverAgentRunner-Runner.app/PlugIns/WebDriverAgentRunner.xctest\n\t testBundleRelativePath:(null)\n\t productModuleName:WebDriverAgentRunner\n\t testsToSkip:\n\t testsToRun:(null)\n\t reportResultsToIDE:YES\n\t testsDrivenByIDE:no\n\t sessionIdentifier:5C3511C4-4C81-4C9E-8489-955A2D2D2C9C\n\t disablePerformanceMetrics:no\n\ttreatMissingBaselinesAsFailures:no\n\t baselineFileURL:(null)\n\t baselineFileRelativePath:(null)\n\t targetApplicationPath:/whatever-it-does-not-matter/but-should-not-be-empty\n\t targetApplicationBundleID:(null)\n\t testApplicationDependencies:\n{\n}\n\t testApplicationUserOverrides:(null)\n\t targetApplicationArguments:\n\t targetApplicationEnvironment:(null)\n\t reportActivities:YES\n\t testsMustRunOnMainThread:YES\n\t initializeForUITesting:YES\n\t traceCollectionEnabled:(null)\n\t aggregateStatisticsBeforeCrash:\n{\n XCSuiteRecordsKey = {\n };\n}\n\t automationFrameworkPath:/Developer/Library/PrivateFrameworks/XCTAutomationSupport.framework\n\t emitOSLogs:no\n\t systemAttachmentLifetime:2\n\t userAttachmentLifetime:1\n\t gatherLocalizableStringsData:no\n\t testExecutionOrdering:alphabetical\n\t randomExecutionOrderingSeed:(null)\n\t rerunPolicy:<XCTRerunPolicy: 0x28104de40>\n\t rerunMode:None\n\t maximumIterations:1\n\n\t testTimeoutsEnabled:NO\n\t defaultTestExecutionTimeAllowance:600\n\t maximumTestExecutionTimeAllowance:0\n\t IDECapabilities:(null)\n\n'])
[D 220316 11:09:40 _instruments:591] DTXMessage: expects_reply:1 flags:2 ('_XCT_logDebugMessage:', ["11:09:42.087 WebDriverAgentRunner-Runner[896:440555] Creating future for 'requesting test configuration' with timeout 600.00\n"])
[D 220316 11:09:40 _instruments:591] DTXMessage: expects_reply:1 flags:2 ('_XCT_logDebugMessage:', ['11:09:42.087 WebDriverAgentRunner-Runner[896:440555] Notifying test runner ready\n'])
[D 220316 11:09:40 _instruments:591] DTXMessage: expects_reply:1 flags:2 ('_XCT_testRunnerReadyWithCapabilities:', [None])
[D 220316 11:09:40 _instruments:591] DTXMessage: expects_reply:1 flags:2 ('_XCT_logDebugMessage:', ['11:09:42.087 WebDriverAgentRunner-Runner[896:440555] <XCTWaiter: 0x283b75880, state: XCTWaiterStateInProgress> entering wait loop for 600.00s with expectations: requesting test configuration\n'])
[D 220316 11:09:40 _instruments:591] DTXMessage: expects_reply:1 flags:2 ('_XCT_logDebugMessage:', ['11:09:42.102 WebDriverAgentRunner-Runner[896:440567] Received test runner ready reply with error: (null)\n'])
[I 220316 11:09:40 _device:1012] Test runner ready detected
[I 220316 11:09:40 _device:1005] Start execute test plan with IDE version: 29
[D 220316 11:09:40 _instruments:591] DTXMessage: expects_reply:1 flags:2 ('_XCT_logDebugMessage:', ['11:09:42.102 WebDriverAgentRunner-Runner[896:440555] Returning result XCTWaiterResultCompleted for waiter <XCTWaiter: 0x283b75880, state: XCTWaiterStateFinished>\n'])
[D 220316 11:09:40 _instruments:591] DTXMessage: expects_reply:1 flags:2 ('_XCT_logDebugMessage:', ['11:09:42.102 WebDriverAgentRunner-Runner[896:440555] <XCTWaiter: 0x283b758f0, state: XCTWaiterStateInProgress> entering wait loop for 600.00s with expectations: requesting ready for testing\n'])
[D 220316 11:09:40 _instruments:591] DTXMessage: expects_reply:1 flags:2 ('_XCT_logDebugMessage:', ['11:09:42.104 WebDriverAgentRunner-Runner[896:440571] _IDE_startExecutingTestPlanWithProtocolVersion:29\n'])
[D 220316 11:09:40 _instruments:591] DTXMessage: expects_reply:1 flags:2 ('_XCT_logDebugMessage:', ['11:09:42.104 WebDriverAgentRunner-Runner[896:440555] Returning result XCTWaiterResultCompleted for waiter <XCTWaiter: 0x283b758f0, state: XCTWaiterStateFinished>\n'])
[D 220316 11:09:40 _instruments:591] DTXMessage: expects_reply:1 flags:2 ('_XCT_didBeginExecutingTestPlan', None)
[D 220316 11:09:40 _instruments:591] DTXMessage: expects_reply:1 flags:2 ('_XCT_didBeginInitializingForUITesting', None)
[D 220316 11:09:40 _instruments:591] DTXMessage: expects_reply:1 flags:2 ('_XCT_logDebugMessage:', ['11:09:42.105 WebDriverAgentRunner-Runner[896:440555] Initializing for UI testing.\n'])
[D 220316 11:09:40 _instruments:591] DTXMessage: expects_reply:1 flags:2 ('_XCT_logDebugMessage:', ['11:09:42.106 WebDriverAgentRunner-Runner[896:440555] Linked XCTAutomationSupport.framework from /var/containers/Bundle/Application/758C4795-F053-4F90-8057-4D9CBD24D998/WebDriverAgentRunner-Runner.app/Frameworks/XCTAutomationSupport.framework/XCTAutomationSupport, built with Xcode 1250(12E249), modified on 2021▒▒12▒▒24▒▒ ▒▒▒▒▒▒ ▒й▒▒▒׼ʱ▒▒ ▒▒▒▒10:57:27\nXCTAutomationSupport.framework bundle version: 18141\n'])
[D 220316 11:09:40 _instruments:591] DTXMessage: expects_reply:1 flags:2 ('_XCT_logDebugMessage:', ['11:09:42.106 WebDriverAgentRunner-Runner[896:440555] <XCTWaiter: 0x283b64620, state: XCTWaiterStateResolved> entering wait loop for 60.00s with expectations: initiating daemon session\n'])
[D 220316 11:09:40 _instruments:591] DTXMessage: expects_reply:1 flags:2 ('_XCT_logDebugMessage:', ['11:09:42.106 WebDriverAgentRunner-Runner[896:440555] Returning result XCTWaiterResultCompleted for waiter <XCTWaiter: 0x283b64620, state: XCTWaiterStateFinished>\n'])
[D 220316 11:09:40 _instruments:591] DTXMessage: expects_reply:1 flags:2 ('_XCT_logDebugMessage:', ["11:09:42.106 WebDriverAgentRunner-Runner[896:440555] Creating future for 'loading Accessibility' with timeout 70.00\n"])
[D 220316 11:09:40 _instruments:591] DTXMessage: expects_reply:1 flags:2 ('_XCT_logDebugMessage:', ['11:09:42.106 WebDriverAgentRunner-Runner[896:440555] <XCTWaiter: 0x283b64700, state: XCTWaiterStateInProgress> entering wait loop for 70.00s with expectations: loading Accessibility\n'])
[D 220316 11:09:41 _instruments:591] DTXMessage: expects_reply:1 flags:2 ('_XCT_logDebugMessage:', ['11:09:42.482 WebDriverAgentRunner-Runner[896:440555] Returning result XCTWaiterResultCompleted for waiter <XCTWaiter: 0x283b64700, state: XCTWaiterStateFinished>\n'])
[D 220316 11:09:41 _instruments:591] DTXMessage: expects_reply:1 flags:2 ('_XCT_logDebugMessage:', ["11:09:42.482 WebDriverAgentRunner-Runner[896:440555] Creating future for 'enabling localized string gathering' with timeout 70.00\n"])
[D 220316 11:09:41 _instruments:591] DTXMessage: expects_reply:1 flags:2 ('_XCT_logDebugMessage:', ['11:09:42.482 WebDriverAgentRunner-Runner[896:440555] <XCTWaiter: 0x283b6ad80, state: XCTWaiterStateInProgress> entering wait loop for 70.00s with expectations: enabling localized string gathering\n'])
[D 220316 11:09:41 _instruments:591] DTXMessage: expects_reply:1 flags:2 ('_XCT_logDebugMessage:', ['11:09:42.483 WebDriverAgentRunner-Runner[896:440555] Returning result XCTWaiterResultCompleted for waiter <XCTWaiter: 0x283b6ad80, state: XCTWaiterStateFinished>\n'])
[D 220316 11:09:41 _instruments:591] DTXMessage: expects_reply:1 flags:2 ('_XCT_logDebugMessage:', ["11:09:42.483 WebDriverAgentRunner-Runner[896:440555] Creating future for 'setting AX timeout to 60.00s' with timeout 65.00\n"])
[D 220316 11:09:41 _instruments:591] DTXMessage: expects_reply:1 flags:2 ('_XCT_logDebugMessage:', ['11:09:42.483 WebDriverAgentRunner-Runner[896:440555] <XCTWaiter: 0x283b6ad80, state: XCTWaiterStateInProgress> entering wait loop for 65.00s with expectations: setting AX timeout to 60.00s\n'])
[D 220316 11:09:41 _instruments:591] DTXMessage: expects_reply:1 flags:2 ('_XCT_logDebugMessage:', ['11:09:42.484 WebDriverAgentRunner-Runner[896:440555] Returning result XCTWaiterResultCompleted for waiter <XCTWaiter: 0x283b6ad80, state: XCTWaiterStateFinished>\n'])
[D 220316 11:09:41 _instruments:591] DTXMessage: expects_reply:1 flags:2 ('_XCT_logDebugMessage:', ["11:09:42.484 WebDriverAgentRunner-Runner[896:440555] Creating future for 'registering for kAXUserTestingNotification' with timeout 65.00\n"])
[D 220316 11:09:41 _instruments:591] DTXMessage: expects_reply:1 flags:2 ('_XCT_logDebugMessage:', ['11:09:42.484 WebDriverAgentRunner-Runner[896:440555] <XCTWaiter: 0x283b6ad80, state: XCTWaiterStateInProgress> entering wait loop for 65.00s with expectations: registering for kAXUserTestingNotification\n'])
[D 220316 11:09:41 _instruments:591] DTXMessage: expects_reply:1 flags:2 ('_XCT_logDebugMessage:', ['11:09:42.484 WebDriverAgentRunner-Runner[896:440555] Returning result XCTWaiterResultCompleted for waiter <XCTWaiter: 0x283b6ad80, state: XCTWaiterStateFinished>\n'])
[D 220316 11:09:41 _instruments:591] DTXMessage: expects_reply:1 flags:2 ('_XCT_logDebugMessage:', ["11:09:42.484 WebDriverAgentRunner-Runner[896:440555] Creating future for 'registering for kAXAlertNotification' with timeout 65.00\n"])
[D 220316 11:09:41 _instruments:591] DTXMessage: expects_reply:1 flags:2 ('_XCT_logDebugMessage:', ['11:09:42.484 WebDriverAgentRunner-Runner[896:440555] <XCTWaiter: 0x283b64700, state: XCTWaiterStateInProgress> entering wait loop for 65.00s with expectations: registering for kAXAlertNotification\n'])
[D 220316 11:09:41 _instruments:591] DTXMessage: expects_reply:1 flags:2 ('_XCT_logDebugMessage:', ['11:09:42.485 WebDriverAgentRunner-Runner[896:440555] Returning result XCTWaiterResultCompleted for waiter <XCTWaiter: 0x283b64700, state: XCTWaiterStateFinished>\n'])
[D 220316 11:09:41 _instruments:591] DTXMessage: expects_reply:1 flags:2 ('_XCT_logDebugMessage:', ["11:09:42.485 WebDriverAgentRunner-Runner[896:440555] Creating future for 'registering for kAXPidStatusChangedNotification' with timeout 65.00\n"])
[D 220316 11:09:41 _instruments:591] DTXMessage: expects_reply:1 flags:2 ('_XCT_logDebugMessage:', ['11:09:42.485 WebDriverAgentRunner-Runner[896:440555] <XCTWaiter: 0x283b6ad80, state: XCTWaiterStateInProgress> entering wait loop for 65.00s with expectations: registering for kAXPidStatusChangedNotification\n'])
[D 220316 11:09:41 _instruments:591] DTXMessage: expects_reply:1 flags:2 ('_XCT_logDebugMessage:', ['11:09:42.485 WebDriverAgentRunner-Runner[896:440555] Returning result XCTWaiterResultCompleted for waiter <XCTWaiter: 0x283b6ad80, state: XCTWaiterStateFinished>\n'])
[D 220316 11:09:41 _instruments:591] DTXMessage: expects_reply:1 flags:2 ('_XCT_logDebugMessage:', ["11:09:42.485 WebDriverAgentRunner-Runner[896:440555] Creating future for 'requesting background assertion for test process' with timeout 30.00\n"])
[D 220316 11:09:41 _instruments:591] DTXMessage: expects_reply:1 flags:2 ('_XCT_logDebugMessage:', ['11:09:42.485 WebDriverAgentRunner-Runner[896:440555] <XCTWaiter: 0x283b64700, state: XCTWaiterStateInProgress> entering wait loop for 30.00s with expectations: requesting background assertion for test process\n'])
[D 220316 11:09:41 _instruments:591] DTXMessage: expects_reply:0 flags:2 ('outputReceived:fromProcess:atTime:', ["Test Suite 'All tests' started at 2022-03-16 11:09:42.501\nXCTestOutputBarrier", 896, 1154207433571])
[D 220316 11:09:41 _device:912] logProcess: Test Suite 'All tests' started at 2022-03-16 11:09:42.501
XCTestOutputBarrier
[D 220316 11:09:41 _instruments:591] DTXMessage: expects_reply:1 flags:2 ('_XCT_logDebugMessage:', ['11:09:42.487 WebDriverAgentRunner-Runner[896:440571] Got reply for background assertion for 896, acquired: YES.\n'])
[D 220316 11:09:41 _instruments:606] Ignore notification from server: 9, 0x2, ('outputReceived:fromProcess:atTime:', ["Test Suite 'All tests' started at 2022-03-16 11:09:42.501\nXCTestOutputBarrier", 896, 1154207433571])
[D 220316 11:09:41 _instruments:591] DTXMessage: expects_reply:0 flags:2 ('outputReceived:fromProcess:atTime:', ["Test Suite 'WebDriverAgentRunner.xctest' started at 2022-03-16 11:09:42.502\nXCTestOutputBarrier", 896, 1154207439664])
[D 220316 11:09:41 _instruments:591] DTXMessage: expects_reply:1 flags:2 ('_XCT_logDebugMessage:', ['11:09:42.487 WebDriverAgentRunner-Runner[896:440555] Returning result XCTWaiterResultCompleted for waiter <XCTWaiter: 0x283b64700, state: XCTWaiterStateFinished>\n'])
[D 220316 11:09:41 _device:912] logProcess: Test Suite 'WebDriverAgentRunner.xctest' started at 2022-03-16 11:09:42.502
XCTestOutputBarrier
[D 220316 11:09:41 _instruments:606] Ignore notification from server: 10, 0x2, ('outputReceived:fromProcess:atTime:', ["Test Suite 'WebDriverAgentRunner.xctest' started at 2022-03-16 11:09:42.502\nXCTestOutputBarrier", 896, 1154207439664])
[D 220316 11:09:41 _instruments:591] DTXMessage: expects_reply:0 flags:2 ('outputReceived:fromProcess:atTime:', ["Test Suite 'UITestingUITests' started at 2022-03-16 11:09:42.502\nXCTestOutputBarrier", 896, 1154207443160])
[D 220316 11:09:41 _instruments:591] DTXMessage: expects_reply:1 flags:2 ('_XCT_logDebugMessage:', ['11:09:42.487 WebDriverAgentRunner-Runner[896:440555] Test runner is already executing in the background.\n'])
[D 220316 11:09:41 _device:912] logProcess: Test Suite 'UITestingUITests' started at 2022-03-16 11:09:42.502
XCTestOutputBarrier
[D 220316 11:09:41 _instruments:606] Ignore notification from server: 11, 0x2, ('outputReceived:fromProcess:atTime:', ["Test Suite 'UITestingUITests' started at 2022-03-16 11:09:42.502\nXCTestOutputBarrier", 896, 1154207443160])
[D 220316 11:09:41 _instruments:591] DTXMessage: expects_reply:1 flags:2 ('_XCT_logDebugMessage:', ['11:09:42.487 WebDriverAgentRunner-Runner[896:440555] Done initializing for UI testing.\n'])
[D 220316 11:09:41 _instruments:591] DTXMessage: expects_reply:0 flags:2 ('outputReceived:fromProcess:atTime:', [' t = nans Suite Set Up\n', 896, 1154207446877])
[D 220316 11:09:41 _device:912] logProcess: t = nans Suite Set Up
[D 220316 11:09:41 _instruments:591] DTXMessage: expects_reply:1 flags:2 ('_XCT_logDebugMessage:', ['11:09:42.487 WebDriverAgentRunner-Runner[896:440555] Entering test execution event loop\n'])
[D 220316 11:09:41 _instruments:606] Ignore notification from server: 12, 0x2, ('outputReceived:fromProcess:atTime:', [' t = nans Suite Set Up\n', 896, 1154207446877])
[D 220316 11:09:41 _instruments:591] DTXMessage: expects_reply:1 flags:2 ('_XCT_logDebugMessage:', ['11:09:42.488 WebDriverAgentRunner-Runner[896:440555] -[XCTRunnerIDESession testBundleWillStart:]\n'])
[D 220316 11:09:41 _instruments:591] DTXMessage: expects_reply:1 flags:2 ('_XCT_logDebugMessage:', ['11:09:42.488 WebDriverAgentRunner-Runner[896:440555] Loading test suite...\n'])
[D 220316 11:09:41 _instruments:591] DTXMessage: expects_reply:1 flags:2 ('_XCT_logDebugMessage:', ['11:09:42.501 WebDriverAgentRunner-Runner[896:440555] Finished loading test suite, took 0.014s\n'])
[D 220316 11:09:41 _instruments:591] DTXMessage: expects_reply:1 flags:2 ('_XCT_logDebugMessage:', ['11:09:42.501 WebDriverAgentRunner-Runner[896:440555] Running suite...\n'])
[D 220316 11:09:41 _instruments:591] DTXMessage: expects_reply:1 flags:2 ('_XCT_logDebugMessage:', ['11:09:42.501 WebDriverAgentRunner-Runner[896:440555] -[XCTRunnerIDESession testSuiteWillStart:]\n'])
[D 220316 11:09:41 _instruments:591] DTXMessage: expects_reply:1 flags:2 ('_XCT_testSuite:didStartAt:', ['All tests', '2022-03-16 03:09:42 +0000'])
[D 220316 11:09:41 _instruments:591] DTXMessage: expects_reply:1 flags:2 ('_XCT_logDebugMessage:', ['11:09:42.502 WebDriverAgentRunner-Runner[896:440555] -[XCTRunnerIDESession testSuiteWillStart:]\n'])
[D 220316 11:09:41 _instruments:591] DTXMessage: expects_reply:1 flags:2 ('_XCT_testSuite:didStartAt:', ['WebDriverAgentRunner.xctest', '2022-03-16 03:09:42 +0000'])
[D 220316 11:09:41 _instruments:591] DTXMessage: expects_reply:1 flags:2 ('_XCT_logDebugMessage:', ['11:09:42.502 WebDriverAgentRunner-Runner[896:440555] -[XCTRunnerIDESession testSuiteWillStart:]\n'])
[D 220316 11:09:41 _instruments:591] DTXMessage: expects_reply:1 flags:2 ('_XCT_testSuite:didStartAt:', ['UITestingUITests', '2022-03-16 03:09:42 +0000'])
[D 220316 11:09:41 _instruments:591] DTXMessage: expects_reply:1 flags:2 ('_XCT_logDebugMessage:', ['11:09:42.502 WebDriverAgentRunner-Runner[896:440555] <XCTContext: 0x28052db40> started activity <XCActivityRecord: 0x28335f6b0> 2022-03-16 03:09:42 +0000: Suite Set Up\n'])
[D 220316 11:09:41 _instruments:591] DTXMessage: expects_reply:1 flags:2 ('_XCT_testCase:method:willStartActivity:', ['none', 'none', XCActivityRecord(activityType=com.apple.dt.xctest.activity-type.internal, attachments=[], finish=None, start=2022-03-16 03:09:42.501854, title=Suite Set Up, uuid=b8d23d2c-fdd4-43de-99b7-a70aa365dee9)])
[D 220316 11:09:41 _instruments:591] DTXMessage: expects_reply:0 flags:2 ('outputReceived:fromProcess:atTime:', ["2022-03-16 11:09:42.520712+0800 WebDriverAgentRunner-Runner[896:440555] [User Defaults] Couldn't write values for keys (\n KeyboardAutocorrection\n) in CFPrefsPlistSource<0x283e66300> (Domain: com.apple.Preferences, User: kCFPreferencesCurrentUser, ByHost: No, Container: (null), Contents Need Refresh: No): setting preferences outside an application's container requires user-preference-write or file-write-data sandbox access\n", 896, 1154207897342])
[D 220316 11:09:41 _device:912] logProcess: 2022-03-16 11:09:42.520712+0800 WebDriverAgentRunner-Runner[896:440555] [User Defaults] Couldn't write values for keys (
KeyboardAutocorrection
) in CFPrefsPlistSource<0x283e66300> (Domain: com.apple.Preferences, User: kCFPreferencesCurrentUser, ByHost: No, Container: (null), Contents Need Refresh: No): setting preferences outside an application's container requires user-preference-write or file-write-data sandbox access
[D 220316 11:09:41 _instruments:606] Ignore notification from server: 13, 0x2, ('outputReceived:fromProcess:atTime:', ["2022-03-16 11:09:42.520712+0800 WebDriverAgentRunner-Runner[896:440555] [User Defaults] Couldn't write values for keys (\n KeyboardAutocorrection\n) in CFPrefsPlistSource<0x283e66300> (Domain: com.apple.Preferences, User: kCFPreferencesCurrentUser, ByHost: No, Container: (null), Contents Need Refresh: No): setting preferences outside an application's container requires user-preference-write or file-write-data sandbox access\n", 896, 1154207897342])
[D 220316 11:09:41 _instruments:591] DTXMessage: expects_reply:0 flags:2 ('outputReceived:fromProcess:atTime:', ["2022-03-16 11:09:42.529988+0800 WebDriverAgentRunner-Runner[896:440555] [User Defaults] Couldn't write values for keys (\n KeyboardPrediction\n) in CFPrefsPlistSource<0x283e66300> (Domain: com.apple.Preferences, User: kCFPreferencesCurrentUser, ByHost: No, Container: (null), Contents Need Refresh: No): setting preferences outside an application's container requires user-preference-write or file-write-data sandbox access\n", 896, 1154208119556])
[D 220316 11:09:41 _device:912] logProcess: 2022-03-16 11:09:42.529988+0800 WebDriverAgentRunner-Runner[896:440555] [User Defaults] Couldn't write values for keys (
KeyboardPrediction
) in CFPrefsPlistSource<0x283e66300> (Domain: com.apple.Preferences, User: kCFPreferencesCurrentUser, ByHost: No, Container: (null), Contents Need Refresh: No): setting preferences outside an application's container requires user-preference-write or file-write-data sandbox access
[D 220316 11:09:41 _instruments:606] Ignore notification from server: 14, 0x2, ('outputReceived:fromProcess:atTime:', ["2022-03-16 11:09:42.529988+0800 WebDriverAgentRunner-Runner[896:440555] [User Defaults] Couldn't write values for keys (\n KeyboardPrediction\n) in CFPrefsPlistSource<0x283e66300> (Domain: com.apple.Preferences, User: kCFPreferencesCurrentUser, ByHost: No, Container: (null), Contents Need Refresh: No): setting preferences outside an application's container requires user-preference-write or file-write-data sandbox access\n", 896, 1154208119556])
[D 220316 11:09:41 _instruments:591] DTXMessage: expects_reply:0 flags:2 ('outputReceived:fromProcess:atTime:', ["2022-03-16 11:09:42.531061+0800 WebDriverAgentRunner-Runner[896:440555] [User Defaults] Couldn't write values for keys (\n KeyboardShowPredictionBar\n) in CFPrefsPlistSource<0x283e66300> (Domain: com.apple.Preferences, User: kCFPreferencesCurrentUser, ByHost: No, Container: (null), Contents Need Refresh: No): setting preferences outside an application's container requires user-preference-write or file-write-data sandbox access\n", 896, 1154208145021])
[D 220316 11:09:41 _device:912] logProcess: 2022-03-16 11:09:42.531061+0800 WebDriverAgentRunner-Runner[896:440555] [User Defaults] Couldn't write values for keys (
KeyboardShowPredictionBar
) in CFPrefsPlistSource<0x283e66300> (Domain: com.apple.Preferences, User: kCFPreferencesCurrentUser, ByHost: No, Container: (null), Contents Need Refresh: No): setting preferences outside an application's container requires user-preference-write or file-write-data sandbox access
[D 220316 11:09:41 _instruments:606] Ignore notification from server: 15, 0x2, ('outputReceived:fromProcess:atTime:', ["2022-03-16 11:09:42.531061+0800 WebDriverAgentRunner-Runner[896:440555] [User Defaults] Couldn't write values for keys (\n KeyboardShowPredictionBar\n) in CFPrefsPlistSource<0x283e66300> (Domain: com.apple.Preferences, User: kCFPreferencesCurrentUser, ByHost: No, Container: (null), Contents Need Refresh: No): setting preferences outside an application's container requires user-preference-write or file-write-data sandbox access\n", 896, 1154208145021])
[D 220316 11:09:41 _instruments:591] DTXMessage: expects_reply:0 flags:2 ('outputReceived:fromProcess:atTime:', ["2022-03-16 11:09:42.533536+0800 WebDriverAgentRunner-Runner[896:440555] [User Defaults] Couldn't write values for keys (\n DidShowGestureKeyboardIntroduction\n) in CFPrefsPlistSource<0x283e66300> (Domain: com.apple.Preferences, User: kCFPreferencesCurrentUser, ByHost: No, Container: (null), Contents Need Refresh: No): setting preferences outside an application's container requires user-preference-write or file-write-data sandbox access\n", 896, 1154208204837])
[D 220316 11:09:41 _device:912] logProcess: 2022-03-16 11:09:42.533536+0800 WebDriverAgentRunner-Runner[896:440555] [User Defaults] Couldn't write values for keys (
DidShowGestureKeyboardIntroduction
) in CFPrefsPlistSource<0x283e66300> (Domain: com.apple.Preferences, User: kCFPreferencesCurrentUser, ByHost: No, Container: (null), Contents Need Refresh: No): setting preferences outside an application's container requires user-preference-write or file-write-data sandbox access
[D 220316 11:09:41 _instruments:606] Ignore notification from server: 16, 0x2, ('outputReceived:fromProcess:atTime:', ["2022-03-16 11:09:42.533536+0800 WebDriverAgentRunner-Runner[896:440555] [User Defaults] Couldn't write values for keys (\n DidShowGestureKeyboardIntroduction\n) in CFPrefsPlistSource<0x283e66300> (Domain: com.apple.Preferences, User: kCFPreferencesCurrentUser, ByHost: No, Container: (null), Contents Need Refresh: No): setting preferences outside an application's container requires user-preference-write or file-write-data sandbox access\n", 896, 1154208204837])
[D 220316 11:09:41 _instruments:591] DTXMessage: expects_reply:1 flags:2 ('_XCT_logDebugMessage:', ['11:09:42.536 WebDriverAgentRunner-Runner[896:440555] <XCTContext: 0x28052db40> finished activity <XCActivityRecord: 0x28335f6b0> 2022-03-16 03:09:42 +0000: Suite Set Up (0.033969s)\n'])
[D 220316 11:09:41 _instruments:591] DTXMessage: expects_reply:1 flags:2 ('_XCT_testCase:method:didFinishActivity:', ['none', 'none', XCActivityRecord(activityType=com.apple.dt.xctest.activity-type.internal, attachments=[], finish=2022-03-16 03:09:42.535823, start=2022-03-16 03:09:42.501854, title=Suite Set Up, uuid=b8d23d2c-fdd4-43de-99b7-a70aa365dee9)])
[D 220316 11:09:41 _instruments:591] DTXMessage: expects_reply:0 flags:2 ('outputReceived:fromProcess:atTime:', ["Test Case '-[UITestingUITests testRunner]' started.\n", 896, 1154208267392])
[D 220316 11:09:41 _device:912] logProcess: Test Case '-[UITestingUITests testRunner]' started.
[D 220316 11:09:41 _instruments:606] Ignore notification from server: 17, 0x2, ('outputReceived:fromProcess:atTime:', ["Test Case '-[UITestingUITests testRunner]' started.\n", 896, 1154208267392])
[D 220316 11:09:41 _instruments:591] DTXMessage: expects_reply:0 flags:2 ('outputReceived:fromProcess:atTime:', ['XCTestOutputBarrier', 896, 1154208272285])
[D 220316 11:09:41 _device:912] logProcess: XCTestOutputBarrier
[D 220316 11:09:41 _instruments:606] Ignore notification from server: 18, 0x2, ('outputReceived:fromProcess:atTime:', ['XCTestOutputBarrier', 896, 1154208272285])
[D 220316 11:09:41 _instruments:591] DTXMessage: expects_reply:0 flags:2 ('outputReceived:fromProcess:atTime:', [' t = 0.00s Start Test at 2022-03-16 11:09:42.537\n', 896, 1154208278782])
[D 220316 11:09:41 _device:912] logProcess: t = 0.00s Start Test at 2022-03-16 11:09:42.537
[D 220316 11:09:41 _instruments:606] Ignore notification from server: 19, 0x2, ('outputReceived:fromProcess:atTime:', [' t = 0.00s Start Test at 2022-03-16 11:09:42.537\n', 896, 1154208278782])
[D 220316 11:09:41 _instruments:591] DTXMessage: expects_reply:0 flags:2 ('outputReceived:fromProcess:atTime:', [' t = 0.00s Set Up\n', 896, 1154208295112])
[D 220316 11:09:41 _device:912] logProcess: t = 0.00s Set Up
[D 220316 11:09:41 _instruments:606] Ignore notification from server: 20, 0x2, ('outputReceived:fromProcess:atTime:', [' t = 0.00s Set Up\n', 896, 1154208295112])
[D 220316 11:09:41 _instruments:591] DTXMessage: expects_reply:0 flags:2 ('outputReceived:fromProcess:atTime:', ['2022-03-16 11:09:42.537733+0800 WebDriverAgentRunner-Runner[896:440555] Built at Dec 24 2021 10:56:41\n', 896, 1154208305393])
[D 220316 11:09:41 _device:912] logProcess: 2022-03-16 11:09:42.537733+0800 WebDriverAgentRunner-Runner[896:440555] Built at Dec 24 2021 10:56:41
[D 220316 11:09:41 _instruments:606] Ignore notification from server: 21, 0x2, ('outputReceived:fromProcess:atTime:', ['2022-03-16 11:09:42.537733+0800 WebDriverAgentRunner-Runner[896:440555] Built at Dec 24 2021 10:56:41\n', 896, 1154208305393])
[D 220316 11:09:41 _instruments:591] DTXMessage: expects_reply:1 flags:2 ('_XCT_logDebugMessage:', ['11:09:42.536 WebDriverAgentRunner-Runner[896:440555] -[XCTRunnerIDESession testCaseWillStart:] UITestingUITests testRunner\n'])
[D 220316 11:09:41 _instruments:591] DTXMessage: expects_reply:1 flags:2 ('_XCT_testCaseDidStartForTestClass:method:', ['UITestingUITests', 'testRunner'])
[D 220316 11:09:41 _instruments:591] DTXMessage: expects_reply:1 flags:2 ('_XCT_logDebugMessage:', ['11:09:42.537 WebDriverAgentRunner-Runner[896:440555] <XCTContext: 0x28051b780> started activity <XCActivityRecord: 0x283330690> 2022-03-16 03:09:42 +0000: Start Test at 2022-03-16 11:09:42.537\n'])
[D 220316 11:09:41 _instruments:591] DTXMessage: expects_reply:1 flags:2 ('_XCT_testCase:method:willStartActivity:', ['none', 'none', XCActivityRecord(activityType=com.apple.dt.xctest.activity-type.internal, attachments=[], finish=None, start=2022-03-16 03:09:42.536616, title=Start Test at 2022-03-16 11:09:42.537, uuid=8b0cfb8a-e0a7-4c18-bfa7-d07a286b0fcd)])
[D 220316 11:09:41 _instruments:591] DTXMessage: expects_reply:1 flags:2 ('_XCT_logDebugMessage:', ["11:09:42.537 WebDriverAgentRunner-Runner[896:440555] Creating future for 'getting screen identifiers' with timeout 30.00\n"])
[D 220316 11:09:41 _instruments:591] DTXMessage: expects_reply:1 flags:2 ('_XCT_logDebugMessage:', ['11:09:42.537 WebDriverAgentRunner-Runner[896:440555] <XCTWaiter: 0x283b69110, state: XCTWaiterStateResolved> entering wait loop for 30.00s with expectations: getting screen identifiers\n'])
[D 220316 11:09:41 _instruments:591] DTXMessage: expects_reply:0 flags:2 ('outputReceived:fromProcess:atTime:', ['2022-03-16 11:09:42.545734+0800 WebDriverAgentRunner-Runner[896:440555] ServerURLHere->http://192.168.7.117:8100<-ServerURLHere\n', 896, 1154208497191])
[D 220316 11:09:41 _instruments:591] DTXMessage: expects_reply:1 flags:2 ('_XCT_logDebugMessage:', ['11:09:42.537 WebDriverAgentRunner-Runner[896:440555] Returning result XCTWaiterResultCompleted for waiter <XCTWaiter: 0x283b69110, state: XCTWaiterStateFinished>\n'])
[D 220316 11:09:41 _device:912] logProcess: 2022-03-16 11:09:42.545734+0800 WebDriverAgentRunner-Runner[896:440555] ServerURLHere->http://192.168.7.117:8100<-ServerURLHere
[D 220316 11:09:41 _instruments:591] DTXMessage: expects_reply:1 flags:2 ('_XCT_logDebugMessage:', ['11:09:42.537 WebDriverAgentRunner-Runner[896:440555] <XCTContext: 0x28051b780> finished activity <XCActivityRecord: 0x283330690> 2022-03-16 03:09:42 +0000: Start Test at 2022-03-16 11:09:42.537 (0.000501037s)\n'])
[I 220316 11:09:41 _device:915] WebDriverAgent start successfully
[D 220316 11:09:41 _instruments:606] Ignore notification from server: 22, 0x2, ('outputReceived:fromProcess:atTime:', ['2022-03-16 11:09:42.545734+0800 WebDriverAgentRunner-Runner[896:440555] ServerURLHere->http://192.168.7.117:8100<-ServerURLHere\n', 896, 1154208497191])
[D 220316 11:09:41 _instruments:591] DTXMessage: expects_reply:1 flags:2 ('_XCT_testCase:method:didFinishActivity:', ['none', 'none', XCActivityRecord(activityType=com.apple.dt.xctest.activity-type.internal, attachments=[], finish=2022-03-16 03:09:42.537117, start=2022-03-16 03:09:42.536616, title=Start Test at 2022-03-16 11:09:42.537, uuid=8b0cfb8a-e0a7-4c18-bfa7-d07a286b0fcd)])
[D 220316 11:09:41 _instruments:591] DTXMessage: expects_reply:0 flags:2 ('outputReceived:fromProcess:atTime:', ['2022-03-16 11:09:42.546078+0800 WebDriverAgentRunner-Runner[896:440567] Using singleton test manager\n', 896, 1154208506484])
[D 220316 11:09:41 _device:912] logProcess: 2022-03-16 11:09:42.546078+0800 WebDriverAgentRunner-Runner[896:440567] Using singleton test manager
[D 220316 11:09:41 _instruments:606] Ignore notification from server: 23, 0x2, ('outputReceived:fromProcess:atTime:', ['2022-03-16 11:09:42.546078+0800 WebDriverAgentRunner-Runner[896:440567] Using singleton test manager\n', 896, 1154208506484])
[D 220316 11:09:41 _instruments:591] DTXMessage: expects_reply:1 flags:2 ('_XCT_logDebugMessage:', ['11:09:42.537 WebDriverAgentRunner-Runner[896:440555] <XCTContext: 0x28051b780> started activity <XCActivityRecord: 0x283344cd0> 2022-03-16 03:09:42 +0000: Set Up\n'])
[D 220316 11:09:41 _instruments:591] DTXMessage: expects_reply:1 flags:2 ('_XCT_testCase:method:willStartActivity:', ['none', 'none', XCActivityRecord(activityType=com.apple.dt.xctest.activity-type.internal, attachments=[], finish=None, start=2022-03-16 03:09:42.537340, title=Set Up, uuid=dcfaea61-2889-45cd-9495-fb44d5830550)])
[D 220316 11:09:41 _instruments:591] DTXMessage: expects_reply:1 flags:2 ('_XCT_logDebugMessage:', ['11:09:42.538 WebDriverAgentRunner-Runner[896:440555] <XCTContext: 0x28051b780> finished activity <XCActivityRecord: 0x283344cd0> 2022-03-16 03:09:42 +0000: Set Up (0.0001719s)\n'])
[D 220316 11:09:41 _instruments:591] DTXMessage: expects_reply:1 flags:2 ('_XCT_testCase:method:didFinishActivity:', ['none', 'none', XCActivityRecord(activityType=com.apple.dt.xctest.activity-type.internal, attachments=[], finish=2022-03-16 03:09:42.537512, start=2022-03-16 03:09:42.537340, title=Set Up, uuid=dcfaea61-2889-45cd-9495-fb44d5830550)])
[D 220316 11:09:41 _instruments:591] DTXMessage: expects_reply:1 flags:2 ('_XCT_logDebugMessage:', ['11:09:42.538 WebDriverAgentRunner-Runner[896:440555] _assertObjectsInvalidAfterScope\n'])
[D 220316 11:09:41 _instruments:591] DTXMessage: expects_reply:1 flags:2 ('_XCT_logDebugMessage:', ["11:09:42.546 WebDriverAgentRunner-Runner[896:440555] Creating future for 'getting screen identifiers' with timeout 30.00\n"])
[D 220316 11:09:41 _instruments:591] DTXMessage: expects_reply:1 flags:2 ('_XCT_logDebugMessage:', ['11:09:42.546 WebDriverAgentRunner-Runner[896:440555] <XCTWaiter: 0x283b6b170, state: XCTWaiterStateResolved> entering wait loop for 30.00s with expectations: getting screen identifiers\n'])
[D 220316 11:09:41 _instruments:591] DTXMessage: expects_reply:1 flags:2 ('_XCT_logDebugMessage:', ['11:09:42.546 WebDriverAgentRunner-Runner[896:440555] Returning result XCTWaiterResultCompleted for waiter <XCTWaiter: 0x283b6b170, state: XCTWaiterStateFinished>\n'])
[D 220316 11:09:45 _instruments:569] dtxm socket closed
[I 220316 11:09:45 _device:1084] xctrunner quited
[D 220316 11:09:45 _instruments:591] DTXMessage: expects_reply:0 flags:2 ('pidDiedCallback:', [896])
[D 220316 11:09:45 _instruments:606] Ignore notification from server: 24, 0x2, ('pidDiedCallback:', [896])

[jeniva]

$ DEBUG=true tidevice.exe xctest -B com.xxx.WebDriverAgentRunner.xctrunner
[D 220316 11:13:59 _safe_socket:82] Socket <tidevice._safe_socket.PlistSocket object at 0x0473B910> closed
[D 220316 11:13:59 main:72] AutoComplete udid 00008020-000D78103499002E
[D 220316 11:13:59 _safe_socket:82] Socket <tidevice._safe_socket.PlistSocket object at 0x0473B8B0> closed
[D 220316 11:13:59 _device:281] Send payload: {'DeviceID': 9, 'MessageType': 'Connect', 'PortNumber': 32498, 'ProgName': 'tidevice'}
[D 220316 11:13:59 _device:283] connect port: 32498
[D 220316 11:13:59 _safe_socket:149] Recv pair record data ...
[D 220316 11:13:59 _safe_socket:82] Socket <tidevice._safe_socket.PlistSocket object at 0x0473B910> closed
[D 220316 11:13:59 _safe_socket:82] Socket <tidevice._safe_socket.PlistSocket object at 0x0473BF30> closed
[D 220316 11:13:59 _device:281] Send payload: {'DeviceID': 9, 'MessageType': 'Connect', 'PortNumber': 22776, 'ProgName': 'tidevice'}
[D 220316 11:13:59 _device:283] connect port: 22776
[I 220316 11:13:59 _device:958] BundleID: com.ezviz.WebDriverAgentRunner.xctrunner
[D 220316 11:13:59 _device:281] Send payload: {'DeviceID': 9, 'MessageType': 'Connect', 'PortNumber': 32498, 'ProgName': 'tidevice'}
[D 220316 11:13:59 _device:283] connect port: 32498
[D 220316 11:13:59 _safe_socket:82] Socket <tidevice._safe_socket.PlistSocket object at 0x0473BF10> closed
[I 220316 11:13:59 _device:961] ProductVersion: 14.6
[I 220316 11:13:59 _device:963] DeviceIdentifier: 00008020-000D78103499002E
[D 220316 11:13:59 _device:281] Send payload: {'DeviceID': 9, 'MessageType': 'Connect', 'PortNumber': 32498, 'ProgName': 'tidevice'}
[D 220316 11:13:59 _device:283] connect port: 32498
[D 220316 11:13:59 _safe_socket:82] Socket <tidevice._safe_socket.PlistSocket object at 0x0475E790> closed
[D 220316 11:13:59 _device:929] ProductVersion: 14.6
[D 220316 11:13:59 _device:281] Send payload: {'DeviceID': 9, 'MessageType': 'Connect', 'PortNumber': 32498, 'ProgName': 'tidevice'}
[D 220316 11:13:59 _device:283] connect port: 32498
[D 220316 11:14:00 _safe_socket:82] Socket <tidevice._safe_socket.PlistSocket object at 0x0475EF70> closed
[D 220316 11:14:00 _device:281] Send payload: {'DeviceID': 9, 'MessageType': 'Connect', 'PortNumber': 24312, 'ProgName': 'tidevice'}
[D 220316 11:14:00 _device:283] connect port: 24312
[D 220316 11:14:00 _instruments:591] DTXMessage: expects_reply:0 flags:2 ('_notifyOfPublishedCapabilities:', [{'com.apple.private.DTXBlockCompression': 2, 'com.apple.private.DTXConnection': 1}])
[D 220316 11:14:00 _instruments:591] DTXMessage: expects_reply:0 flags:0 None
[D 220316 11:14:00 _device:281] Send payload: {'DeviceID': 9, 'MessageType': 'Connect', 'PortNumber': 32498, 'ProgName': 'tidevice'}
[D 220316 11:14:00 _device:283] connect port: 32498
[D 220316 11:14:00 _safe_socket:82] Socket <tidevice._safe_socket.PlistSocket object at 0x0475E790> closed
[D 220316 11:14:00 _device:929] ProductVersion: 14.6
[D 220316 11:14:00 _instruments:591] DTXMessage: expects_reply:0 flags:3 36
[D 220316 11:14:00 _device:281] Send payload: {'DeviceID': 9, 'MessageType': 'Connect', 'PortNumber': 32498, 'ProgName': 'tidevice'}
[D 220316 11:14:00 _device:283] connect port: 32498
[D 220316 11:14:00 _safe_socket:82] Socket <tidevice._safe_socket.PlistSocket object at 0x0474C450> closed
[D 220316 11:14:00 _device:929] ProductVersion: 14.6
[D 220316 11:14:00 _device:281] Send payload: {'DeviceID': 9, 'MessageType': 'Connect', 'PortNumber': 32498, 'ProgName': 'tidevice'}
[D 220316 11:14:00 _device:283] connect port: 32498
[D 220316 11:14:00 _safe_socket:82] Socket <tidevice._safe_socket.PlistSocket object at 0x0472BCF0> closed
[D 220316 11:14:00 _device:281] Send payload: {'DeviceID': 9, 'MessageType': 'Connect', 'PortNumber': 25592, 'ProgName': 'tidevice'}
[D 220316 11:14:00 _device:283] connect port: 25592
[D 220316 11:14:00 _instruments:591] DTXMessage: expects_reply:0 flags:2 ('_notifyOfPublishedCapabilities:', [{'com.apple.private.DTXBlockCompression': 2, 'com.apple.private.DTXConnection': 1}])
[D 220316 11:14:00 _instruments:591] DTXMessage: expects_reply:0 flags:0 None
[D 220316 11:14:00 _instruments:591] DTXMessage: expects_reply:0 flags:3 36
[D 220316 11:14:00 _device:281] Send payload: {'DeviceID': 9, 'MessageType': 'Connect', 'PortNumber': 32498, 'ProgName': 'tidevice'}
[D 220316 11:14:00 _device:283] connect port: 32498
[D 220316 11:14:00 _safe_socket:82] Socket <tidevice._safe_socket.PlistSocket object at 0x0477C4B0> closed
[D 220316 11:14:00 _device:281] Send payload: {'DeviceID': 9, 'MessageType': 'Connect', 'PortNumber': 26360, 'ProgName': 'tidevice'}
[D 220316 11:14:00 _device:283] connect port: 26360
[I 220316 11:14:00 _device:802] SignIdentity: 'Apple Development: Molly Fukuchi (35PHN77S4R)'
[I 220316 11:14:00 _device:808] CFBundleExecutable: WebDriverAgentRunner-Runner
[D 220316 11:14:00 _device:281] Send payload: {'DeviceID': 9, 'MessageType': 'Connect', 'PortNumber': 32498, 'ProgName': 'tidevice'}
[D 220316 11:14:00 _device:283] connect port: 32498
[D 220316 11:14:00 _safe_socket:82] Socket <tidevice._safe_socket.PlistSocket object at 0x04767DD0> closed
[D 220316 11:14:00 _device:281] Send payload: {'DeviceID': 9, 'MessageType': 'Connect', 'PortNumber': 27128, 'ProgName': 'tidevice'}
[D 220316 11:14:00 _device:283] connect port: 27128
[D 220316 11:14:00 _device:822] remove /tmp/WebDriverAgentRunner-1D62DA06-2890-419A-8B56-826E3582671C.xctestconfiguration
[D 220316 11:14:00 _device:281] Send payload: {'DeviceID': 9, 'MessageType': 'Connect', 'PortNumber': 32498, 'ProgName': 'tidevice'}
[D 220316 11:14:00 _device:283] connect port: 32498
[D 220316 11:14:00 _safe_socket:82] Socket <tidevice._safe_socket.PlistSocket object at 0x0473BBF0> closed
[D 220316 11:14:00 _device:929] ProductVersion: 14.6
[D 220316 11:14:00 _device:281] Send payload: {'DeviceID': 9, 'MessageType': 'Connect', 'PortNumber': 32498, 'ProgName': 'tidevice'}
[D 220316 11:14:00 _device:283] connect port: 32498
[D 220316 11:14:00 _safe_socket:82] Socket <tidevice._safe_socket.PlistSocket object at 0x04257D30> closed
[D 220316 11:14:00 _device:281] Send payload: {'DeviceID': 9, 'MessageType': 'Connect', 'PortNumber': 28152, 'ProgName': 'tidevice'}
[D 220316 11:14:00 _device:283] connect port: 28152
[D 220316 11:14:00 _instruments:591] DTXMessage: expects_reply:0 flags:2 ('_notifyOfPublishedCapabilities:', [{'com.apple.instruments.server.services.processcontrolbydictionary': 4, 'com.apple.instruments.server.services.graphics.coreanimation.immediate': 1, 'com.apple.instruments.server.services.assets': 4, 'com.apple.instruments.server.services.coreprofilesessiontap.config': 1, 'com.apple.instruments.target.ios': 140600, 'com.apple.instruments.server.services.coreprofilesessiontap.pmc': 2, 'com.apple.instruments.server.services.deviceinfo.dyld-tracing': 1, 'com.apple.instruments.server.services.activitytracetap.windowed': 1, 'com.apple.instruments.server.services.device.xpccontrol': 2, 'com.apple.instruments.server.services.activitytracetap': 6, 'com.apple.instruments.target.physical-cpus': 6, 'com.apple.instruments.server.services.sysmontap.system': 1, 'com.apple.instruments.server.services.sysmontap.immediate': 1, 'com.apple.instruments.server.services.objectalloc.zombies': 1, 'com.apple.instruments.server.services.deviceinfo.gcd-perf': 1, 'com.apple.instruments.server.services.activitytracetap.immediate': 1, 'com.apple.xcode.debug-gauge-data-providers.procinfo': 1, 'com.apple.instruments.target.user-page-size': 16384, 'com.apple.instruments.server.services.deviceinfo.recordOptions': 1, 'com.apple.instruments.server.services.storekit': 2, 'com.apple.instruments.server.services.graphics.coreanimation.deferred': 1, 'com.apple.instruments.server.services.deviceinfo.metal': 1, 'com.apple.instruments.server.services.device.applictionListing': 1, 'com.apple.instruments.server.services.coreprofilesessiontap': 2, 'com.apple.instruments.server.services.coreprofilesessiontap.multipleTimeTriggers': 1, 'com.apple.instruments.server.services.sampling': 11, 'com.apple.instruments.server.services.coreprofilesessiontap.windowed': 1, 'com.apple.instruments.server.services.vmtracking.deferred': 1, 'com.apple.xcode.debug-gauge-data-providers.Energy': 1, 'com.apple.instruments.server.services.processcontrol.capability.signal': 1, 'com.apple.dt.Instruments.inlineCapabilities': 1, 'com.apple.instruments.server.services.objectalloc.immediate': 1, 'com.apple.instruments.server.services.deviceinfo': 111, 'com.apple.instruments.server.services.coreprofilesessiontap.pmi': 1, 'com.apple.instruments.server.services.gpu.immediate': 1, 'com.apple.instruments.server.services.networking': 2, 'com.apple.instruments.server.services.networking.immediate': 1, 'com.apple.instruments.server.services.filetransfer': 1, 'com.apple.instruments.server.services.deviceinfo.devicesymbolication': 1, 'com.apple.instruments.server.services.sysmontap.deferred': 1, 'com.apple.instruments.target.logical-cpus': 6, 'com.apple.instruments.server.services.deviceinfo.gpu-allocation': 1, 'com.apple.instruments.server.services.deviceinfo.energytracing.location': 1, 'com.apple.instruments.server.services.power.immediate': 1, 'com.apple.instruments.server.services.deviceinfo.condition-inducer': 1, 'com.apple.instruments.server.services.ultraviolet.preview': 1, 'com.apple.instruments.server.services.sysmontap': 3, 'com.apple.instruments.server.services.gpu': 1, 'com.apple.instruments.server.services.vmtracking': 1, 'com.apple.instruments.server.services.objectalloc': 5, 'com.apple.xcode.debug-gauge-data-providers.NetworkStatistics': 1, 'com.apple.instruments.server.services.coreprofilesessiontap.deferred': 1, 'com.apple.instruments.target.mtb.numer': 125, 'com.apple.xcode.debug-gauge-data-providers.SpriteKit': 1, 'com.apple.instruments.server.services.remoteleaks.deferred': 1, 'com.apple.dt.Xcode.WatchProcessControl': 3, 'com.apple.instruments.server.services.power.deferred': 1, 'com.apple.instruments.server.services.graphics.coreanimation': 1, 'com.apple.instruments.server.services.coresampling': 10, 'com.apple.instruments.server.services.sampling.deferred': 1, 'com.apple.instruments.server.services.gpu.counters': 4, 'com.apple.instruments.server.services.remoteleaks': 6, 'com.apple.instruments.server.services.gpu.shaderprofiler': 1, 'com.apple.instruments.server.services.sysmontap.windowed': 1, 'com.apple.instruments.server.services.objectalloc.deferred': 1, 'com.apple.instruments.server.services.graphics.opengl.immediate': 1, 'com.apple.instruments.server.services.networking.deferred': 1, 'com.apple.instruments.target.mtb.denom': 3, 'com.apple.instruments.server.services.remoteleaks.immediate': 1, 'com.apple.instruments.server.services.activitytracetap.deferred': 1, 'com.apple.instruments.server.services.graphics.opengl.deferred': 1, 'com.apple.xcode.resource-control': 1, 'com.apple.instruments.server.services.sampling.immediate': 1, 'com.apple.xcode.debug-gauge-data-providers.SceneKit': 1, 'com.apple.instruments.server.services.processcontrol': 107, 'com.apple.instruments.server.services.ultraviolet.renderer': 1, 'com.apple.instruments.server.services.screenshot': 2, 'com.apple.instruments.server.services.ConditionInducer': 1, 'com.apple.instruments.server.services.deviceinfo.app-life-cycle': 1, 'com.apple.instruments.server.services.deviceinfo.systemversion': 140600, 'com.apple.instruments.server.services.processcontrol.feature.deviceio': 103, 'com.apple.instruments.server.services.assets.response': 2, 'com.apple.instruments.server.services.deviceinfo.scenekit-tracing': 1, 'com.apple.instruments.server.services.sysmontap.processes': 1, 'com.apple.instruments.server.services.coreprofilesessiontap.immediate': 1, 'com.apple.dt.services.capabilities.vmtracking': 1, 'com.apple.private.DTXConnection': 1, 'com.apple.instruments.server.services.graphics.opengl': 1, 'com.apple.instruments.server.services.vmtracking.immediate': 1, 'com.apple.instruments.server.services.mobilenotifications': 1, 'com.apple.xcode.debug-gauge-data-providers.resources': 1, 'com.apple.private.DTXBlockCompression': 2, 'com.apple.instruments.server.services.filetransfer.debuginbox': 1, 'com.apple.instruments.server.services.power': 1, 'com.apple.instruments.server.services.gpu.deferred': 1}])
[D 220316 11:14:00 _instruments:591] DTXMessage: expects_reply:0 flags:0 None
[D 220316 11:14:00 _instruments:591] DTXMessage: expects_reply:0 flags:3 905
[D 220316 11:14:00 _device:838] AppPath: /private/var/containers/Bundle/Application/758C4795-F053-4F90-8057-4D9CBD24D998/WebDriverAgentRunner-Runner.app
[I 220316 11:14:00 _device:839] AppContainer: /private/var/mobile/Containers/Data/Application/01DC9AEE-E637-4F73-AA07-24074CDA2439
[D 220316 11:14:00 _device:281] Send payload: {'DeviceID': 9, 'MessageType': 'Connect', 'PortNumber': 32498, 'ProgName': 'tidevice'}
[D 220316 11:14:00 _device:283] connect port: 32498
[D 220316 11:14:01 _safe_socket:82] Socket <tidevice._safe_socket.PlistSocket object at 0x0477C750> closed
[D 220316 11:14:01 _device:929] ProductVersion: 14.6
[D 220316 11:14:01 _device:281] Send payload: {'DeviceID': 9, 'MessageType': 'Connect', 'PortNumber': 32498, 'ProgName': 'tidevice'}
[D 220316 11:14:01 _device:283] connect port: 32498
[D 220316 11:14:01 _safe_socket:82] Socket <tidevice._safe_socket.PlistSocket object at 0x0477C8B0> closed
[D 220316 11:14:01 _device:929] ProductVersion: 14.6
[D 220316 11:14:01 _instruments:591] DTXMessage: expects_reply:0 flags:3 910
[I 220316 11:14:01 _device:879] Launch 'com.ezviz.WebDriverAgentRunner.xctrunner' pid: 910
[D 220316 11:14:01 _instruments:591] DTXMessage: expects_reply:0 flags:0 None
[D 220316 11:14:01 _device:281] Send payload: {'DeviceID': 9, 'MessageType': 'Connect', 'PortNumber': 32498, 'ProgName': 'tidevice'}
[D 220316 11:14:01 _device:283] connect port: 32498
[D 220316 11:14:01 _instruments:591] DTXMessage: expects_reply:0 flags:2 ('outputReceived:fromProcess:atTime:', ['2022-03-16 11:14:02.898383+0800 WebDriverAgentRunner-Runner[910:442309] Running tests...\n', 910, 1160459983031])
[D 220316 11:14:01 _device:912] logProcess: 2022-03-16 11:14:02.898383+0800 WebDriverAgentRunner-Runner[910:442309] Running tests...
[D 220316 11:14:01 _instruments:606] Ignore notification from server: 8, 0x2, ('outputReceived:fromProcess:atTime:', ['2022-03-16 11:14:02.898383+0800 WebDriverAgentRunner-Runner[910:442309] Running tests...\n', 910, 1160459983031])
[D 220316 11:14:01 _instruments:591] DTXMessage: expects_reply:1 flags:2 ('_requestChannelWithCode:identifier:', [1, 'dtxproxy:XCTestDriverInterface:XCTestManager_IDEInterface'])
[D 220316 11:14:01 _instruments:591] DTXMessage: expects_reply:0 flags:2 ('_notifyOfPublishedCapabilities:', [{'com.apple.private.DTXBlockCompression': 2, 'com.apple.private.DTXConnection': 1}])
[D 220316 11:14:01 _instruments:591] DTXMessage: expects_reply:1 flags:2 ('_XCT_logDebugMessage:', ['11:14:02.901 WebDriverAgentRunner-Runner[910:442309] Linked XCTest.framework from /var/containers/Bundle/Application/758C4795-F053-4F90-8057-4D9CBD24D998/WebDriverAgentRunner-Runner.app/Frameworks/XCTest.framework/XCTest, built with Xcode 1250(12E249), modified on 2021▒▒12▒▒24▒▒ ▒▒▒▒▒▒ ▒й▒▒▒׼ʱ▒▒ ▒▒▒▒10:57:27\nXCTest.framework bundle version: 18141\n'])
[D 220316 11:14:01 _instruments:591] DTXMessage: expects_reply:1 flags:2 ('_XCT_logDebugMessage:', ['11:14:02.902 WebDriverAgentRunner-Runner[910:442309] System uptime: 13:25:52\n'])
[D 220316 11:14:01 _instruments:591] DTXMessage: expects_reply:1 flags:2 ('_XCT_logDebugMessage:', ['11:14:02.902 WebDriverAgentRunner-Runner[910:442309] Process arguments: (\n "/var/containers/Bundle/Application/758C4795-F053-4F90-8057-4D9CBD24D998/WebDriverAgentRunner-Runner.app/WebDriverAgentRunner-Runner",\n "-NSTreatUnknownArgumentsAsOpen",\n NO,\n "-ApplePersistenceIgnoreState",\n YES\n)\n'])
[D 220316 11:14:01 _instruments:591] DTXMessage: expects_reply:1 flags:2 ('_XCT_logDebugMessage:', ['11:14:02.902 WebDriverAgentRunner-Runner[910:442309] Process environment: {\n "CA_ASSERT_MAIN_THREAD_TRANSACTIONS" = 0;\n "CA_DEBUG_TRANSACTIONS" = 0;\n "CFFIXED_USER_HOME" = "/private/var/mobile/Containers/Data/Application/01DC9AEE-E637-4F73-AA07-24074CDA2439";\n "DYLD_FRAMEWORK_PATH" = "/private/var/containers/Bundle/Application/758C4795-F053-4F90-8057-4D9CBD24D998/WebDriverAgentRunner-Runner.app/Frameworks:";\n "DYLD_LIBRARY_PATH" = "/private/var/containers/Bundle/Application/758C4795-F053-4F90-8057-4D9CBD24D998/WebDriverAgentRunner-Runner.app/Frameworks";\n HOME = "/private/var/mobile/Containers/Data/Application/01DC9AEE-E637-4F73-AA07-24074CDA2439";\n "LLVM_PROFILE_FILE" = "/private/var/mobile/Containers/Data/Application/01DC9AEE-E637-4F73-AA07-24074CDA2439/tmp/%p.profraw";\n LOGNAME = mobile;\n "MJPEG_SERVER_PORT" = "";\n NSUnbufferedIO = YES;\n "OS_ACTIVITY_DT_MODE" = YES;\n PATH = "/usr/bin:/bin:/usr/sbin:/sbin";\n SHELL = "/bin/sh";\n "SQLITE_ENABLE_THREAD_ASSERTIONS" = 1;\n TMPDIR = "/private/var/mobile/Containers/Data/Application/01DC9AEE-E637-4F73-AA07-24074CDA2439/tmp/";\n USER = mobile;\n "USE_PORT" = "";\n "WDA_PRODUCT_BUNDLE_IDENTIFIER" = "";\n "XCODE_DBG_XPC_EXCLUSIONS" = "com.apple.dt.xctestSymbolicator";\n XCTestConfigurationFilePath = "/private/var/mobile/Containers/Data/Application/01DC9AEE-E637-4F73-AA07-24074CDA2439/tmp/WebDriverAgentRunner-3FF42007-293A-42C4-ADCA-E5A59F051CFE.xctestconfiguration";\n "XPC_FLAGS" = 0x0;\n "XPC_SERVICE_NAME" = "UIKitApplication:com.ezviz.WebDriverAgentRunner.xctrunner[a9ce][rb-legacy]";\n "__CF_USER_TEXT_ENCODING" = "0x1F5:0:0";\n}\n'])
[D 220316 11:14:01 _instruments:591] DTXMessage: expects_reply:1 flags:2 ('_XCT_logDebugMessage:', ['11:14:02.902 WebDriverAgentRunner-Runner[910:442309] App Sandbox active: YES\n'])
[D 220316 11:14:01 _instruments:591] DTXMessage: expects_reply:1 flags:2 ('_XCT_logDebugMessage:', ["11:14:02.902 WebDriverAgentRunner-Runner[910:442309] Creating future for 'preparing IDE session' with timeout 600.00\n"])
[D 220316 11:14:01 _instruments:591] DTXMessage: expects_reply:1 flags:2 ('_XCT_logDebugMessage:', ['11:14:02.903 WebDriverAgentRunner-Runner[910:442309] Exchanging capabilities with testmanagerd\n'])
[D 220316 11:14:01 _instruments:591] DTXMessage: expects_reply:1 flags:2 ('_XCT_logDebugMessage:', ['11:14:02.903 WebDriverAgentRunner-Runner[910:442309] <XCTWaiter: 0x280b3d570, state: XCTWaiterStateInProgress> entering wait loop for 600.00s with expectations: preparing IDE session\n'])
[D 220316 11:14:01 _instruments:591] DTXMessage: expects_reply:1 flags:2 ('_XCT_logDebugMessage:', ['11:14:02.905 WebDriverAgentRunner-Runner[910:442320] Received capabilities for testmanagerd: <XCTCapabilities: 0x2822306c0>: {\n "Siri request with audio URL capability" = 1;\n "Siri request with text capability" = 1;\n XCTProtectedResourceBluetoothIdentifier = 1;\n XCTProtectedResourceCalendarIdentifier = 1;\n XCTProtectedResourceCameraIdentifier = 1;\n XCTProtectedResourceContactsIdentifier = 1;\n XCTProtectedResourceHealthIdentifier = 1;\n XCTProtectedResourceHomeKitIdentifier = 1;\n XCTProtectedResourceKeyboardNetworkIdentifier = 1;\n XCTProtectedResourceLocationIdentifier = 1;\n XCTProtectedResourceMediaLibraryIdentifier = 1;\n XCTProtectedResourceMicrophoneIdentifier = 1;\n XCTProtectedResourcePhotosIdentifier = 1;\n XCTProtectedResourceRemindersIdentifier = 1;\n "element at point capability" = 1;\n "enable faux collection view cells capability" = 1;\n "fetch element attributes capability" = 2;\n "fetch parameterized attribute capability" = 1;\n "get Siri enabled status capability" = 1;\n "get device orientation capability" = 1;\n "get scale for screen capability" = 1;\n "get screen identifiers capability" = 1;\n "inject assistant recognition audio paths capability" = 1;\n "inject assistant recognition strings capability" = 1;\n "launch application capability" = 1;\n "load accessibility capability" = 1;\n "look up bundle id capability" = 1;\n "monitor application capability" = 1;\n "override implicit event confirmation interval" = 1;\n "perform AX action capability" = 1;\n "perform device event capability" = 1;\n "press with pressure capability" = 1;\n "register for AX notification capability" = 1;\n "request DT service hub connection capability" = 2;\n "request a specific image format/XCTCapabilityImageFormat_HEIC" = 1;\n "request a specific image format/XCTCapabilityImageFormat_JPEG" = 1;\n "request a specific image format/XCTCapabilityImageFormat_PNG" = 1;\n "request automation session capability" = 2;\n "request background assertion capability" = 2;\n "request element snapshot capability" = 3;\n "request screenshot capability" = 3;\n "request spindump capability" = 1;\n "request unsupported bundle identifiers for automation sessions" = 1;\n "send string capability" = 1;\n "set AX timeout capability" = 1;\n "set device orientation capability" = 1;\n "set element attribute capability" = 1;\n "snapshot request runtime issue reporting" = 1;\n "snapshot request runtime issue reporting/automation type mismatch" = 1;\n "synthesize event capability" = 1;\n "system provides kAXXCAttributeBannerIsStickyAttribute" = 1;\n "terminate application capability" = 1;\n "toggle localizable strings data gathering capability" = 1;\n "transfer harness connection socket capability" = 2;\n "unregister for AX notification capability" = 1;\n}\n'])
[D 220316 11:14:01 _instruments:591] DTXMessage: expects_reply:1 flags:2 ('_XCT_logDebugMessage:', ['11:14:02.906 WebDriverAgentRunner-Runner[910:442320] Requesting serialized transport wrapper for IDE connection\n'])
[D 220316 11:14:01 _instruments:591] DTXMessage: expects_reply:1 flags:2 ('_XCT_logDebugMessage:', ['11:14:02.907 WebDriverAgentRunner-Runner[910:442321] Creating transport with received transport data\n'])
[D 220316 11:14:01 _instruments:591] DTXMessage: expects_reply:1 flags:2 ('_XCT_logDebugMessage:', ['11:14:02.907 WebDriverAgentRunner-Runner[910:442321] Creating connection with transport <DTXFileDescriptorTransport: 0x280b3d1f0>\n'])
[D 220316 11:14:01 _instruments:591] DTXMessage: expects_reply:1 flags:2 ('_XCT_logDebugMessage:', ['11:14:02.907 WebDriverAgentRunner-Runner[910:442321] Creating the proxy channel with connection <DTXConnection 0x281c3e300 : x1>\n'])
[D 220316 11:14:01 _instruments:591] DTXMessage: expects_reply:1 flags:2 ('_XCT_logDebugMessage:', ['11:14:02.908 WebDriverAgentRunner-Runner[910:442309] Returning result XCTWaiterResultCompleted for waiter <XCTWaiter: 0x280b3d570, state: XCTWaiterStateFinished>\n'])
[D 220316 11:14:01 _instruments:591] DTXMessage: expects_reply:1 flags:2 ('_XCT_logDebugMessage:', ['11:14:02.908 WebDriverAgentRunner-Runner[910:442309] Loading test bundle at: file:///private/var/containers/Bundle/Application/758C4795-F053-4F90-8057-4D9CBD24D998/WebDriverAgentRunner-Runner.app/PlugIns/WebDriverAgentRunner.xctest\n'])
[D 220316 11:14:01 _instruments:591] DTXMessage: expects_reply:1 flags:2 ('_XCT_logDebugMessage:', ['11:14:02.915 WebDriverAgentRunner-Runner[910:442309] Finished loading test bundle\n'])
[D 220316 11:14:01 _instruments:591] DTXMessage: expects_reply:1 flags:2 ('_XCT_logDebugMessage:', ['11:14:02.915 WebDriverAgentRunner-Runner[910:442309] Running tests with active test configuration: <XCTestConfiguration: 0x281c38100>\n\t testBundleURL:file:///private/var/containers/Bundle/Application/758C4795-F053-4F90-8057-4D9CBD24D998/WebDriverAgentRunner-Runner.app/PlugIns/WebDriverAgentRunner.xctest\n\t testBundleRelativePath:(null)\n\t productModuleName:WebDriverAgentRunner\n\t testsToSkip:\n\t testsToRun:(null)\n\t reportResultsToIDE:YES\n\t testsDrivenByIDE:no\n\t sessionIdentifier:3FF42007-293A-42C4-ADCA-E5A59F051CFE\n\t disablePerformanceMetrics:no\n\ttreatMissingBaselinesAsFailures:no\n\t baselineFileURL:(null)\n\t baselineFileRelativePath:(null)\n\t targetApplicationPath:/whatever-it-does-not-matter/but-should-not-be-empty\n\t targetApplicationBundleID:(null)\n\t testApplicationDependencies:\n{\n}\n\t testApplicationUserOverrides:(null)\n\t targetApplicationArguments:\n\t targetApplicationEnvironment:(null)\n\t reportActivities:YES\n\t testsMustRunOnMainThread:YES\n\t initializeForUITesting:YES\n\t traceCollectionEnabled:(null)\n\t aggregateStatisticsBeforeCrash:\n{\n XCSuiteRecordsKey = {\n };\n}\n\t automationFrameworkPath:/Developer/Library/PrivateFrameworks/XCTAutomationSupport.framework\n\t emitOSLogs:no\n\t systemAttachmentLifetime:2\n\t userAttachmentLifetime:1\n\t gatherLocalizableStringsData:no\n\t testExecutionOrdering:alphabetical\n\t randomExecutionOrderingSeed:(null)\n\t rerunPolicy:<XCTRerunPolicy: 0x282016e60>\n\t rerunMode:None\n\t maximumIterations:1\n\n\t testTimeoutsEnabled:NO\n\t defaultTestExecutionTimeAllowance:600\n\t maximumTestExecutionTimeAllowance:0\n\t IDECapabilities:(null)\n\n'])
[D 220316 11:14:01 _instruments:591] DTXMessage: expects_reply:1 flags:2 ('_XCT_logDebugMessage:', ["11:14:02.915 WebDriverAgentRunner-Runner[910:442309] Creating future for 'requesting test configuration' with timeout 600.00\n"])
[D 220316 11:14:01 _instruments:591] DTXMessage: expects_reply:1 flags:2 ('_XCT_logDebugMessage:', ['11:14:02.915 WebDriverAgentRunner-Runner[910:442309] Notifying test runner ready\n'])
[D 220316 11:14:01 _instruments:591] DTXMessage: expects_reply:1 flags:2 ('_XCT_testRunnerReadyWithCapabilities:', [None])
[D 220316 11:14:01 _instruments:591] DTXMessage: expects_reply:1 flags:2 ('_XCT_logDebugMessage:', ['11:14:02.916 WebDriverAgentRunner-Runner[910:442309] <XCTWaiter: 0x280b3d650, state: XCTWaiterStateInProgress> entering wait loop for 600.00s with expectations: requesting test configuration\n'])
[D 220316 11:14:01 _instruments:591] DTXMessage: expects_reply:1 flags:2 ('_XCT_logDebugMessage:', ['11:14:02.939 WebDriverAgentRunner-Runner[910:442321] Received test runner ready reply with error: (null)\n'])
[I 220316 11:14:01 _device:1012] Test runner ready detected
[I 220316 11:14:01 _device:1005] Start execute test plan with IDE version: 29
[D 220316 11:14:01 _instruments:591] DTXMessage: expects_reply:1 flags:2 ('_XCT_logDebugMessage:', ['11:14:02.939 WebDriverAgentRunner-Runner[910:442309] Returning result XCTWaiterResultCompleted for waiter <XCTWaiter: 0x280b3d650, state: XCTWaiterStateFinished>\n'])
[D 220316 11:14:01 _instruments:591] DTXMessage: expects_reply:1 flags:2 ('_XCT_logDebugMessage:', ['11:14:02.939 WebDriverAgentRunner-Runner[910:442309] <XCTWaiter: 0x280b3d5e0, state: XCTWaiterStateInProgress> entering wait loop for 600.00s with expectations: requesting ready for testing\n'])
[D 220316 11:14:01 _instruments:591] DTXMessage: expects_reply:1 flags:2 ('_XCT_logDebugMessage:', ['11:14:02.941 WebDriverAgentRunner-Runner[910:442321] _IDE_startExecutingTestPlanWithProtocolVersion:29\n'])
[D 220316 11:14:01 _instruments:591] DTXMessage: expects_reply:1 flags:2 ('_XCT_logDebugMessage:', ['11:14:02.941 WebDriverAgentRunner-Runner[910:442309] Returning result XCTWaiterResultCompleted for waiter <XCTWaiter: 0x280b3d5e0, state: XCTWaiterStateFinished>\n'])
[D 220316 11:14:01 _instruments:591] DTXMessage: expects_reply:1 flags:2 ('_XCT_didBeginExecutingTestPlan', None)
[D 220316 11:14:01 _instruments:591] DTXMessage: expects_reply:1 flags:2 ('_XCT_didBeginInitializingForUITesting', None)
[D 220316 11:14:01 _instruments:591] DTXMessage: expects_reply:1 flags:2 ('_XCT_logDebugMessage:', ['11:14:02.941 WebDriverAgentRunner-Runner[910:442309] Initializing for UI testing.\n'])
[D 220316 11:14:01 _instruments:591] DTXMessage: expects_reply:1 flags:2 ('_XCT_logDebugMessage:', ['11:14:02.942 WebDriverAgentRunner-Runner[910:442309] Linked XCTAutomationSupport.framework from /var/containers/Bundle/Application/758C4795-F053-4F90-8057-4D9CBD24D998/WebDriverAgentRunner-Runner.app/Frameworks/XCTAutomationSupport.framework/XCTAutomationSupport, built with Xcode 1250(12E249), modified on 2021▒▒12▒▒24▒▒ ▒▒▒▒▒▒ ▒й▒▒▒׼ʱ▒▒ ▒▒▒▒10:57:27\nXCTAutomationSupport.framework bundle version: 18141\n'])
[D 220316 11:14:01 _instruments:591] DTXMessage: expects_reply:1 flags:2 ('_XCT_logDebugMessage:', ['11:14:02.942 WebDriverAgentRunner-Runner[910:442309] <XCTWaiter: 0x280b28380, state: XCTWaiterStateResolved> entering wait loop for 60.00s with expectations: initiating daemon session\n'])
[D 220316 11:14:01 _instruments:591] DTXMessage: expects_reply:1 flags:2 ('_XCT_logDebugMessage:', ['11:14:02.942 WebDriverAgentRunner-Runner[910:442309] Returning result XCTWaiterResultCompleted for waiter <XCTWaiter: 0x280b28380, state: XCTWaiterStateFinished>\n'])
[D 220316 11:14:01 _instruments:591] DTXMessage: expects_reply:1 flags:2 ('_XCT_logDebugMessage:', ["11:14:02.942 WebDriverAgentRunner-Runner[910:442309] Creating future for 'loading Accessibility' with timeout 70.00\n"])
[D 220316 11:14:01 _instruments:591] DTXMessage: expects_reply:1 flags:2 ('_XCT_logDebugMessage:', ['11:14:02.943 WebDriverAgentRunner-Runner[910:442309] <XCTWaiter: 0x280b3d6c0, state: XCTWaiterStateInProgress> entering wait loop for 70.00s with expectations: loading Accessibility\n'])
[D 220316 11:14:01 _instruments:591] DTXMessage: expects_reply:1 flags:2 ('_XCT_logDebugMessage:', ['11:14:02.943 WebDriverAgentRunner-Runner[910:442309] Returning result XCTWaiterResultCompleted for waiter <XCTWaiter: 0x280b3d6c0, state: XCTWaiterStateFinished>\n'])
[D 220316 11:14:01 _instruments:591] DTXMessage: expects_reply:1 flags:2 ('_XCT_logDebugMessage:', ['11:14:02.943 WebDriverAgentRunner-Runner[910:442309] Error from daemon in loading AX: Not authorized for performing UI testing actions.\n'])
[D 220316 11:14:01 _instruments:591] DTXMessage: expects_reply:1 flags:2 ('_XCT_logDebugMessage:', ['11:14:02.943 WebDriverAgentRunner-Runner[910:442309] Error capturing sample for process backboardd:\nError Domain=XCTDaemonErrorDomain Code=20 "Failed to spawn process for sample: 35 - Resource temporarily unavailable" UserInfo={NSLocalizedDescription=Failed to spawn process for sample: 35 - Resource temporarily unavailable}\n'])
[D 220316 11:14:01 _instruments:591] DTXMessage: expects_reply:1 flags:2 ('_XCT_logDebugMessage:', ['11:14:02.943 WebDriverAgentRunner-Runner[910:442309] Error capturing sample for process SpringBoard:\nError Domain=XCTDaemonErrorDomain Code=20 "Failed to spawn process for sample: 35 - Resource temporarily unavailable" UserInfo={NSLocalizedDescription=Failed to spawn process for sample: 35 - Resource temporarily unavailable}\n'])
[D 220316 11:14:01 _instruments:591] DTXMessage: expects_reply:1 flags:2 ('_XCT_logDebugMessage:', ['11:14:02.943 WebDriverAgentRunner-Runner[910:442309] Failed to load accessibility.\n'])
[D 220316 11:14:01 _instruments:591] DTXMessage: expects_reply:1 flags:2 ('_XCT_logDebugMessage:', ['11:14:02.943 WebDriverAgentRunner-Runner[910:442309] Failed to initialize for UI testing: Error Domain=XCTDaemonErrorDomain Code=41 "Not authorized for performing UI testing actions." UserInfo={samples={\n SpringBoard = "Error Domain=XCTDaemonErrorDomain Code=20 \"Failed to spawn process for sample: 35 - Resource temporarily unavailable\" UserInfo={NSLocalizedDescription=Failed to spawn process for sample: 35 - Resource temporarily unavailable}";\n backboardd = "Error Domain=XCTDaemonErrorDomain Code=20 \"Failed to spawn process for sample: 35 - Resource temporarily unavailable\" UserInfo={NSLocalizedDescription=Failed to spawn process for sample: 35 - Resource temporarily unavailable}";\n}, NSLocalizedDescription=Not authorized for performing UI testing actions.}\n'])
[D 220316 11:14:01 _instruments:591] DTXMessage: expects_reply:1 flags:2 ('_XCT_initializationForUITestingDidFailWithError:', [NSError(CODE:41 DOMAIN:XCTDaemonErrorDomain INFO:{'NSLocalizedDescription': 'Not authorized for performing UI testing actions.',
'samples': {'SpringBoard': NSError(CODE:20 DOMAIN:XCTDaemonErrorDomain INFO:{'NSLocalizedDescription': 'Failed to spawn process for sample: 35 - Resource '
'temporarily unavailable'}),
'backboardd': NSError(CODE:20 DOMAIN:XCTDaemonErrorDomain INFO:{'NSLocalizedDescription': 'Failed to spawn process for sample: 35 - Resource '
'temporarily unavailable'})}})])
[D 220316 11:14:01 _instruments:591] DTXMessage: expects_reply:1 flags:2 ('_XCT_logDebugMessage:', ["11:14:02.944 WebDriverAgentRunner-Runner[910:442309] Creating future for 'flushing telemetry' with timeout 10.00\n"])
[D 220316 11:14:01 _instruments:591] DTXMessage: expects_reply:1 flags:2 ('_XCT_logDebugMessage:', ['11:14:02.944 WebDriverAgentRunner-Runner[910:442309] <XCTWaiter: 0x280b3d6c0, state: XCTWaiterStateResolved> entering wait loop for 10.00s with expectations: flushing telemetry\n'])
[D 220316 11:14:01 _instruments:591] DTXMessage: expects_reply:1 flags:2 ('_XCT_didFinishExecutingTestPlan', None)
[D 220316 11:14:01 _instruments:591] DTXMessage: expects_reply:1 flags:2 ('_XCT_logDebugMessage:', ['11:14:02.944 WebDriverAgentRunner-Runner[910:442309] Returning result XCTWaiterResultCompleted for waiter <XCTWaiter: 0x280b3d6c0, state: XCTWaiterStateFinished>\n'])
[D 220316 11:14:01 _instruments:591] DTXMessage: expects_reply:1 flags:2 ('_XCT_logDebugMessage:', ['11:14:02.944 WebDriverAgentRunner-Runner[910:442309] Calling completion.\n'])
[D 220316 11:14:01 _instruments:591] DTXMessage: expects_reply:1 flags:2 ('_XCT_logDebugMessage:', ['11:14:02.944 WebDriverAgentRunner-Runner[910:442309] <XCTWaiter: 0x280b28b60, state: XCTWaiterStateInProgress> entering wait loop for 1800.00s with expectations: IDE confirmed the end of session\n'])
[D 220316 11:14:01 _instruments:569] dtxm socket closed
[D 220316 11:14:01 _instruments:591] DTXMessage: expects_reply:0 flags:2 ('pidDiedCallback:', [910])
[D 220316 11:14:01 _instruments:606] Ignore notification from server: 9, 0x2, ('pidDiedCallback:', [910])
[D 220316 11:14:01 _safe_socket:82] Socket <tidevice._safe_socket.PlistSocket object at 0x0477C910> closed
[D 220316 11:14:01 _device:929] ProductVersion: 14.6
[D 220316 11:14:01 _instruments:591] DTXMessage: expects_reply:0 flags:3 True
[I 220316 11:14:01 _device:1084] xctrunner quited

donglijuan@pc-hz20087034 MINGW64 /d/Users/donglijuan/AppData/Local/Programs/Manis UTest/libs/manisRunner
$ DEBUG=true '/d/Users/donglijuan/AppData/Local/Programs/Manis UTest/libs/manisRunner/manis-tidevice.exe' xctest -B com.ezviz.WebDriverAgentRunner.xctrunner
[D 220316 14:24:59 _safe_socket:82] Socket <tidevice._safe_socket.PlistSocket object at 0x04C0A9F0> closed
[D 220316 14:24:59 main:72] AutoComplete udid 00008020-000D78103499002E
[D 220316 14:24:59 _safe_socket:82] Socket <tidevice._safe_socket.PlistSocket object at 0x04C0A9F0> closed
[D 220316 14:24:59 _device:281] Send payload: {'DeviceID': 10, 'MessageType': 'Connect', 'PortNumber': 32498, 'ProgName': 'tidevice'}
[D 220316 14:24:59 _device:283] connect port: 32498
[D 220316 14:24:59 _safe_socket:149] Recv pair record data ...
[D 220316 14:24:59 _safe_socket:82] Socket <tidevice._safe_socket.PlistSocket object at 0x04C1C050> closed
[D 220316 14:24:59 _safe_socket:82] Socket <tidevice._safe_socket.PlistSocket object at 0x04C1C030> closed
[D 220316 14:24:59 _device:281] Send payload: {'DeviceID': 10, 'MessageType': 'Connect', 'PortNumber': 13507, 'ProgName': 'tidevice'}
[D 220316 14:24:59 _device:283] connect port: 13507
[I 220316 14:24:59 _device:958] BundleID: com.ezviz.WebDriverAgentRunner.xctrunner
[D 220316 14:24:59 _device:281] Send payload: {'DeviceID': 10, 'MessageType': 'Connect', 'PortNumber': 32498, 'ProgName': 'tidevice'}
[D 220316 14:24:59 _device:283] connect port: 32498
[D 220316 14:24:59 _safe_socket:82] Socket <tidevice._safe_socket.PlistSocket object at 0x04C1C030> closed
[I 220316 14:24:59 _device:961] ProductVersion: 14.6
[I 220316 14:24:59 _device:963] DeviceIdentifier: 00008020-000D78103499002E
[D 220316 14:24:59 _device:281] Send payload: {'DeviceID': 10, 'MessageType': 'Connect', 'PortNumber': 32498, 'ProgName': 'tidevice'}
[D 220316 14:24:59 _device:283] connect port: 32498
[D 220316 14:24:59 _safe_socket:82] Socket <tidevice._safe_socket.PlistSocket object at 0x04C2E850> closed
[D 220316 14:24:59 _device:929] ProductVersion: 14.6
[D 220316 14:24:59 _device:281] Send payload: {'DeviceID': 10, 'MessageType': 'Connect', 'PortNumber': 32498, 'ProgName': 'tidevice'}
[D 220316 14:24:59 _device:283] connect port: 32498
[D 220316 14:24:59 _safe_socket:82] Socket <tidevice._safe_socket.PlistSocket object at 0x04C2ED30> closed
[D 220316 14:24:59 _device:281] Send payload: {'DeviceID': 10, 'MessageType': 'Connect', 'PortNumber': 14787, 'ProgName': 'tidevice'}
[D 220316 14:24:59 _device:283] connect port: 14787
[D 220316 14:25:00 _instruments:591] DTXMessage: expects_reply:0 flags:2 ('_notifyOfPublishedCapabilities:', [{'com.apple.private.DTXBlockCompression': 2, 'com.apple.private.DTXConnection': 1}])
[D 220316 14:25:00 _instruments:591] DTXMessage: expects_reply:0 flags:0 None
[D 220316 14:25:00 _device:281] Send payload: {'DeviceID': 10, 'MessageType': 'Connect', 'PortNumber': 32498, 'ProgName': 'tidevice'}
[D 220316 14:25:00 _device:283] connect port: 32498
[D 220316 14:25:00 _safe_socket:82] Socket <tidevice._safe_socket.PlistSocket object at 0x04C2E850> closed
[D 220316 14:25:00 _device:929] ProductVersion: 14.6
[D 220316 14:25:00 _instruments:591] DTXMessage: expects_reply:0 flags:3 36
[D 220316 14:25:00 _device:281] Send payload: {'DeviceID': 10, 'MessageType': 'Connect', 'PortNumber': 32498, 'ProgName': 'tidevice'}
[D 220316 14:25:00 _device:283] connect port: 32498
[D 220316 14:25:00 _safe_socket:82] Socket <tidevice._safe_socket.PlistSocket object at 0x04C1C5D0> closed
[D 220316 14:25:00 _device:929] ProductVersion: 14.6
[D 220316 14:25:00 _device:281] Send payload: {'DeviceID': 10, 'MessageType': 'Connect', 'PortNumber': 32498, 'ProgName': 'tidevice'}
[D 220316 14:25:00 _device:283] connect port: 32498
[D 220316 14:25:00 _safe_socket:82] Socket <tidevice._safe_socket.PlistSocket object at 0x04C0A790> closed
[D 220316 14:25:00 _device:281] Send payload: {'DeviceID': 10, 'MessageType': 'Connect', 'PortNumber': 16323, 'ProgName': 'tidevice'}
[D 220316 14:25:00 _device:283] connect port: 16323
[D 220316 14:25:00 _instruments:591] DTXMessage: expects_reply:0 flags:2 ('_notifyOfPublishedCapabilities:', [{'com.apple.private.DTXBlockCompression': 2, 'com.apple.private.DTXConnection': 1}])
[D 220316 14:25:00 _instruments:591] DTXMessage: expects_reply:0 flags:0 None
[D 220316 14:25:00 _instruments:591] DTXMessage: expects_reply:0 flags:3 36
[D 220316 14:25:00 _device:281] Send payload: {'DeviceID': 10, 'MessageType': 'Connect', 'PortNumber': 32498, 'ProgName': 'tidevice'}
[D 220316 14:25:00 _device:283] connect port: 32498
[D 220316 14:25:00 _safe_socket:82] Socket <tidevice._safe_socket.PlistSocket object at 0x04C4C570> closed
[D 220316 14:25:00 _device:281] Send payload: {'DeviceID': 10, 'MessageType': 'Connect', 'PortNumber': 17091, 'ProgName': 'tidevice'}
[D 220316 14:25:00 _device:283] connect port: 17091
[I 220316 14:25:00 _device:802] SignIdentity: 'Apple Development: Molly Fukuchi (35PHN77S4R)'
[I 220316 14:25:00 _device:808] CFBundleExecutable: WebDriverAgentRunner-Runner
[D 220316 14:25:00 _device:281] Send payload: {'DeviceID': 10, 'MessageType': 'Connect', 'PortNumber': 32498, 'ProgName': 'tidevice'}
[D 220316 14:25:00 _device:283] connect port: 32498
[D 220316 14:25:00 _safe_socket:82] Socket <tidevice._safe_socket.PlistSocket object at 0x04C36FD0> closed
[D 220316 14:25:00 _device:281] Send payload: {'DeviceID': 10, 'MessageType': 'Connect', 'PortNumber': 17859, 'ProgName': 'tidevice'}
[D 220316 14:25:00 _device:283] connect port: 17859
[D 220316 14:25:00 _device:822] remove /tmp/WebDriverAgentRunner-90B7ECE9-160D-4031-AFF6-5396BB57045E.xctestconfiguration
[D 220316 14:25:00 _device:281] Send payload: {'DeviceID': 10, 'MessageType': 'Connect', 'PortNumber': 32498, 'ProgName': 'tidevice'}
[D 220316 14:25:00 _device:283] connect port: 32498
[D 220316 14:25:00 _safe_socket:82] Socket <tidevice._safe_socket.PlistSocket object at 0x04C0AD70> closed
[D 220316 14:25:00 _device:929] ProductVersion: 14.6
[D 220316 14:25:00 _device:281] Send payload: {'DeviceID': 10, 'MessageType': 'Connect', 'PortNumber': 32498, 'ProgName': 'tidevice'}
[D 220316 14:25:00 _device:283] connect port: 32498
[D 220316 14:25:00 _safe_socket:82] Socket <tidevice._safe_socket.PlistSocket object at 0x04C36050> closed
[D 220316 14:25:00 _device:281] Send payload: {'DeviceID': 10, 'MessageType': 'Connect', 'PortNumber': 18883, 'ProgName': 'tidevice'}
[D 220316 14:25:00 _device:283] connect port: 18883
[D 220316 14:25:00 _instruments:591] DTXMessage: expects_reply:0 flags:2 ('_notifyOfPublishedCapabilities:', [{'com.apple.instruments.server.services.processcontrolbydictionary': 4, 'com.apple.instruments.server.services.graphics.coreanimation.immediate': 1, 'com.apple.instruments.server.services.assets': 4, 'com.apple.instruments.server.services.coreprofilesessiontap.config': 1, 'com.apple.instruments.target.ios': 140600, 'com.apple.instruments.server.services.coreprofilesessiontap.pmc': 2, 'com.apple.instruments.server.services.deviceinfo.dyld-tracing': 1, 'com.apple.instruments.server.services.activitytracetap.windowed': 1, 'com.apple.instruments.server.services.device.xpccontrol': 2, 'com.apple.instruments.server.services.activitytracetap': 6, 'com.apple.instruments.target.physical-cpus': 6, 'com.apple.instruments.server.services.sysmontap.system': 1, 'com.apple.instruments.server.services.sysmontap.immediate': 1, 'com.apple.instruments.server.services.objectalloc.zombies': 1, 'com.apple.instruments.server.services.deviceinfo.gcd-perf': 1, 'com.apple.instruments.server.services.activitytracetap.immediate': 1, 'com.apple.xcode.debug-gauge-data-providers.procinfo': 1, 'com.apple.instruments.target.user-page-size': 16384, 'com.apple.instruments.server.services.deviceinfo.recordOptions': 1, 'com.apple.instruments.server.services.storekit': 2, 'com.apple.instruments.server.services.graphics.coreanimation.deferred': 1, 'com.apple.instruments.server.services.deviceinfo.metal': 1, 'com.apple.instruments.server.services.device.applictionListing': 1, 'com.apple.instruments.server.services.coreprofilesessiontap': 2, 'com.apple.instruments.server.services.coreprofilesessiontap.multipleTimeTriggers': 1, 'com.apple.instruments.server.services.sampling': 11, 'com.apple.instruments.server.services.coreprofilesessiontap.windowed': 1, 'com.apple.instruments.server.services.vmtracking.deferred': 1, 'com.apple.xcode.debug-gauge-data-providers.Energy': 1, 'com.apple.instruments.server.services.processcontrol.capability.signal': 1, 'com.apple.dt.Instruments.inlineCapabilities': 1, 'com.apple.instruments.server.services.objectalloc.immediate': 1, 'com.apple.instruments.server.services.deviceinfo': 111, 'com.apple.instruments.server.services.coreprofilesessiontap.pmi': 1, 'com.apple.instruments.server.services.gpu.immediate': 1, 'com.apple.instruments.server.services.networking': 2, 'com.apple.instruments.server.services.networking.immediate': 1, 'com.apple.instruments.server.services.filetransfer': 1, 'com.apple.instruments.server.services.deviceinfo.devicesymbolication': 1, 'com.apple.instruments.server.services.sysmontap.deferred': 1, 'com.apple.instruments.target.logical-cpus': 6, 'com.apple.instruments.server.services.deviceinfo.gpu-allocation': 1, 'com.apple.instruments.server.services.deviceinfo.energytracing.location': 1, 'com.apple.instruments.server.services.power.immediate': 1, 'com.apple.instruments.server.services.deviceinfo.condition-inducer': 1, 'com.apple.instruments.server.services.ultraviolet.preview': 1, 'com.apple.instruments.server.services.sysmontap': 3, 'com.apple.instruments.server.services.gpu': 1, 'com.apple.instruments.server.services.vmtracking': 1, 'com.apple.instruments.server.services.objectalloc': 5, 'com.apple.xcode.debug-gauge-data-providers.NetworkStatistics': 1, 'com.apple.instruments.server.services.coreprofilesessiontap.deferred': 1, 'com.apple.instruments.target.mtb.numer': 125, 'com.apple.xcode.debug-gauge-data-providers.SpriteKit': 1, 'com.apple.instruments.server.services.remoteleaks.deferred': 1, 'com.apple.dt.Xcode.WatchProcessControl': 3, 'com.apple.instruments.server.services.power.deferred': 1, 'com.apple.instruments.server.services.graphics.coreanimation': 1, 'com.apple.instruments.server.services.coresampling': 10, 'com.apple.instruments.server.services.sampling.deferred': 1, 'com.apple.instruments.server.services.gpu.counters': 4, 'com.apple.instruments.server.services.remoteleaks': 6, 'com.apple.instruments.server.services.gpu.shaderprofiler': 1, 'com.apple.instruments.server.services.sysmontap.windowed': 1, 'com.apple.instruments.server.services.objectalloc.deferred': 1, 'com.apple.instruments.server.services.graphics.opengl.immediate': 1, 'com.apple.instruments.server.services.networking.deferred': 1, 'com.apple.instruments.target.mtb.denom': 3, 'com.apple.instruments.server.services.remoteleaks.immediate': 1, 'com.apple.instruments.server.services.activitytracetap.deferred': 1, 'com.apple.instruments.server.services.graphics.opengl.deferred': 1, 'com.apple.xcode.resource-control': 1, 'com.apple.instruments.server.services.sampling.immediate': 1, 'com.apple.xcode.debug-gauge-data-providers.SceneKit': 1, 'com.apple.instruments.server.services.processcontrol': 107, 'com.apple.instruments.server.services.ultraviolet.renderer': 1, 'com.apple.instruments.server.services.screenshot': 2, 'com.apple.instruments.server.services.ConditionInducer': 1, 'com.apple.instruments.server.services.deviceinfo.app-life-cycle': 1, 'com.apple.instruments.server.services.deviceinfo.systemversion': 140600, 'com.apple.instruments.server.services.processcontrol.feature.deviceio': 103, 'com.apple.instruments.server.services.assets.response': 2, 'com.apple.instruments.server.services.deviceinfo.scenekit-tracing': 1, 'com.apple.instruments.server.services.sysmontap.processes': 1, 'com.apple.instruments.server.services.coreprofilesessiontap.immediate': 1, 'com.apple.dt.services.capabilities.vmtracking': 1, 'com.apple.private.DTXConnection': 1, 'com.apple.instruments.server.services.graphics.opengl': 1, 'com.apple.instruments.server.services.vmtracking.immediate': 1, 'com.apple.instruments.server.services.mobilenotifications': 1, 'com.apple.xcode.debug-gauge-data-providers.resources': 1, 'com.apple.private.DTXBlockCompression': 2, 'com.apple.instruments.server.services.filetransfer.debuginbox': 1, 'com.apple.instruments.server.services.power': 1, 'com.apple.instruments.server.services.gpu.deferred': 1}])
[D 220316 14:25:00 _instruments:591] DTXMessage: expects_reply:0 flags:0 None
[D 220316 14:25:00 _instruments:591] DTXMessage: expects_reply:0 flags:3 282
[D 220316 14:25:00 _device:838] AppPath: /private/var/containers/Bundle/Application/758C4795-F053-4F90-8057-4D9CBD24D998/WebDriverAgentRunner-Runner.app
[I 220316 14:25:00 _device:839] AppContainer: /private/var/mobile/Containers/Data/Application/01DC9AEE-E637-4F73-AA07-24074CDA2439
[D 220316 14:25:00 _device:281] Send payload: {'DeviceID': 10, 'MessageType': 'Connect', 'PortNumber': 32498, 'ProgName': 'tidevice'}
[D 220316 14:25:00 _device:283] connect port: 32498
[D 220316 14:25:01 _safe_socket:82] Socket <tidevice._safe_socket.PlistSocket object at 0x04C4CAF0> closed
[D 220316 14:25:01 _device:929] ProductVersion: 14.6
[D 220316 14:25:01 _device:281] Send payload: {'DeviceID': 10, 'MessageType': 'Connect', 'PortNumber': 32498, 'ProgName': 'tidevice'}
[D 220316 14:25:01 _device:283] connect port: 32498
[D 220316 14:25:01 _safe_socket:82] Socket <tidevice._safe_socket.PlistSocket object at 0x04C4C530> closed
[D 220316 14:25:01 _device:929] ProductVersion: 14.6
[D 220316 14:25:01 _instruments:591] DTXMessage: expects_reply:0 flags:3 338
[I 220316 14:25:01 _device:879] Launch 'com.ezviz.WebDriverAgentRunner.xctrunner' pid: 338
[D 220316 14:25:01 _instruments:591] DTXMessage: expects_reply:0 flags:0 None
[D 220316 14:25:01 _device:281] Send payload: {'DeviceID': 10, 'MessageType': 'Connect', 'PortNumber': 32498, 'ProgName': 'tidevice'}
[D 220316 14:25:01 _device:283] connect port: 32498
[D 220316 14:25:01 _instruments:591] DTXMessage: expects_reply:0 flags:2 ('outputReceived:fromProcess:atTime:', ['2022-03-16 14:25:03.131009+0800 WebDriverAgentRunner-Runner[338:11138] Running tests...\n', 338, 36638365270])
[D 220316 14:25:01 _device:912] logProcess: 2022-03-16 14:25:03.131009+0800 WebDriverAgentRunner-Runner[338:11138] Running tests...
[D 220316 14:25:01 _instruments:606] Ignore notification from server: 8, 0x2, ('outputReceived:fromProcess:atTime:', ['2022-03-16 14:25:03.131009+0800 WebDriverAgentRunner-Runner[338:11138] Running tests...\n', 338, 36638365270])
[D 220316 14:25:01 _instruments:591] DTXMessage: expects_reply:1 flags:2 ('_requestChannelWithCode:identifier:', [1, 'dtxproxy:XCTestDriverInterface:XCTestManager_IDEInterface'])
[D 220316 14:25:01 _instruments:591] DTXMessage: expects_reply:0 flags:2 ('_notifyOfPublishedCapabilities:', [{'com.apple.private.DTXBlockCompression': 2, 'com.apple.private.DTXConnection': 1}])
[D 220316 14:25:01 _instruments:591] DTXMessage: expects_reply:1 flags:2 ('_XCT_logDebugMessage:', ['14:25:03.136 WebDriverAgentRunner-Runner[338:11138] Linked XCTest.framework from /var/containers/Bundle/Application/758C4795-F053-4F90-8057-4D9CBD24D998/WebDriverAgentRunner-Runner.app/Frameworks/XCTest.framework/XCTest, built with Xcode 1250(12E249), modified on 2021▒▒12▒▒24▒▒ ▒▒▒▒▒▒ ▒й▒▒▒׼ʱ▒▒ ▒▒▒▒10:57:27\nXCTest.framework bundle version: 18141\n'])
[D 220316 14:25:01 _instruments:591] DTXMessage: expects_reply:1 flags:2 ('_XCT_logDebugMessage:', ['14:25:03.136 WebDriverAgentRunner-Runner[338:11138] System uptime: 25:26\n'])
[D 220316 14:25:01 _instruments:591] DTXMessage: expects_reply:1 flags:2 ('_XCT_logDebugMessage:', ['14:25:03.136 WebDriverAgentRunner-Runner[338:11138] Process arguments: (\n "/var/containers/Bundle/Application/758C4795-F053-4F90-8057-4D9CBD24D998/WebDriverAgentRunner-Runner.app/WebDriverAgentRunner-Runner",\n "-NSTreatUnknownArgumentsAsOpen",\n NO,\n "-ApplePersistenceIgnoreState",\n YES\n)\n'])
[D 220316 14:25:01 _instruments:591] DTXMessage: expects_reply:1 flags:2 ('_XCT_logDebugMessage:', ['14:25:03.136 WebDriverAgentRunner-Runner[338:11138] Process environment: {\n "CA_ASSERT_MAIN_THREAD_TRANSACTIONS" = 0;\n "CA_DEBUG_TRANSACTIONS" = 0;\n "CFFIXED_USER_HOME" = "/private/var/mobile/Containers/Data/Application/01DC9AEE-E637-4F73-AA07-24074CDA2439";\n "DYLD_FRAMEWORK_PATH" = "/private/var/containers/Bundle/Application/758C4795-F053-4F90-8057-4D9CBD24D998/WebDriverAgentRunner-Runner.app/Frameworks:";\n "DYLD_LIBRARY_PATH" = "/private/var/containers/Bundle/Application/758C4795-F053-4F90-8057-4D9CBD24D998/WebDriverAgentRunner-Runner.app/Frameworks";\n HOME = "/private/var/mobile/Containers/Data/Application/01DC9AEE-E637-4F73-AA07-24074CDA2439";\n "LLVM_PROFILE_FILE" = "/private/var/mobile/Containers/Data/Application/01DC9AEE-E637-4F73-AA07-24074CDA2439/tmp/%p.profraw";\n LOGNAME = mobile;\n "MJPEG_SERVER_PORT" = "";\n NSUnbufferedIO = YES;\n "OS_ACTIVITY_DT_MODE" = YES;\n PATH = "/usr/bin:/bin:/usr/sbin:/sbin";\n SHELL = "/bin/sh";\n "SQLITE_ENABLE_THREAD_ASSERTIONS" = 1;\n TMPDIR = "/private/var/mobile/Containers/Data/Application/01DC9AEE-E637-4F73-AA07-24074CDA2439/tmp/";\n USER = mobile;\n "USE_PORT" = "";\n "WDA_PRODUCT_BUNDLE_IDENTIFIER" = "";\n "XCODE_DBG_XPC_EXCLUSIONS" = "com.apple.dt.xctestSymbolicator";\n XCTestConfigurationFilePath = "/private/var/mobile/Containers/Data/Application/01DC9AEE-E637-4F73-AA07-24074CDA2439/tmp/WebDriverAgentRunner-488D4E9E-6F21-45D1-9EC2-A1F41E0A0671.xctestconfiguration";\n "XPC_FLAGS" = 0x0;\n "XPC_SERVICE_NAME" = "UIKitApplication:com.ezviz.WebDriverAgentRunner.xctrunner[d408][rb-legacy]";\n "__CF_USER_TEXT_ENCODING" = "0x1F5:0:0";\n}\n'])
[D 220316 14:25:01 _instruments:591] DTXMessage: expects_reply:1 flags:2 ('_XCT_logDebugMessage:', ['14:25:03.136 WebDriverAgentRunner-Runner[338:11138] App Sandbox active: YES\n'])
[D 220316 14:25:01 _instruments:591] DTXMessage: expects_reply:1 flags:2 ('_XCT_logDebugMessage:', ["14:25:03.136 WebDriverAgentRunner-Runner[338:11138] Creating future for 'preparing IDE session' with timeout 600.00\n"])
[D 220316 14:25:01 _instruments:591] DTXMessage: expects_reply:1 flags:2 ('_XCT_logDebugMessage:', ['14:25:03.137 WebDriverAgentRunner-Runner[338:11138] Exchanging capabilities with testmanagerd\n'])
[D 220316 14:25:01 _instruments:591] DTXMessage: expects_reply:1 flags:2 ('_XCT_logDebugMessage:', ['14:25:03.138 WebDriverAgentRunner-Runner[338:11138] <XCTWaiter: 0x2822a81c0, state: XCTWaiterStateInProgress> entering wait loop for 600.00s with expectations: preparing IDE session\n'])
[D 220316 14:25:01 _instruments:591] DTXMessage: expects_reply:1 flags:2 ('_XCT_logDebugMessage:', ['14:25:03.140 WebDriverAgentRunner-Runner[338:11154] Received capabilities for testmanagerd: <XCTCapabilities: 0x280bb1910>: {\n "Siri request with audio URL capability" = 1;\n "Siri request with text capability" = 1;\n XCTProtectedResourceBluetoothIdentifier = 1;\n XCTProtectedResourceCalendarIdentifier = 1;\n XCTProtectedResourceCameraIdentifier = 1;\n XCTProtectedResourceContactsIdentifier = 1;\n XCTProtectedResourceHealthIdentifier = 1;\n XCTProtectedResourceHomeKitIdentifier = 1;\n XCTProtectedResourceKeyboardNetworkIdentifier = 1;\n XCTProtectedResourceLocationIdentifier = 1;\n XCTProtectedResourceMediaLibraryIdentifier = 1;\n XCTProtectedResourceMicrophoneIdentifier = 1;\n XCTProtectedResourcePhotosIdentifier = 1;\n XCTProtectedResourceRemindersIdentifier = 1;\n "element at point capability" = 1;\n "enable faux collection view cells capability" = 1;\n "fetch element attributes capability" = 2;\n "fetch parameterized attribute capability" = 1;\n "get Siri enabled status capability" = 1;\n "get device orientation capability" = 1;\n "get scale for screen capability" = 1;\n "get screen identifiers capability" = 1;\n "inject assistant recognition audio paths capability" = 1;\n "inject assistant recognition strings capability" = 1;\n "launch application capability" = 1;\n "load accessibility capability" = 1;\n "look up bundle id capability" = 1;\n "monitor application capability" = 1;\n "override implicit event confirmation interval" = 1;\n "perform AX action capability" = 1;\n "perform device event capability" = 1;\n "press with pressure capability" = 1;\n "register for AX notification capability" = 1;\n "request DT service hub connection capability" = 2;\n "request a specific image format/XCTCapabilityImageFormat_HEIC" = 1;\n "request a specific image format/XCTCapabilityImageFormat_JPEG" = 1;\n "request a specific image format/XCTCapabilityImageFormat_PNG" = 1;\n "request automation session capability" = 2;\n "request background assertion capability" = 2;\n "request element snapshot capability" = 3;\n "request screenshot capability" = 3;\n "request spindump capability" = 1;\n "request unsupported bundle identifiers for automation sessions" = 1;\n "send string capability" = 1;\n "set AX timeout capability" = 1;\n "set device orientation capability" = 1;\n "set element attribute capability" = 1;\n "snapshot request runtime issue reporting" = 1;\n "snapshot request runtime issue reporting/automation type mismatch" = 1;\n "synthesize event capability" = 1;\n "system provides kAXXCAttributeBannerIsStickyAttribute" = 1;\n "terminate application capability" = 1;\n "toggle localizable strings data gathering capability" = 1;\n "transfer harness connection socket capability" = 2;\n "unregister for AX notification capability" = 1;\n}\n'])
[D 220316 14:25:01 _instruments:591] DTXMessage: expects_reply:1 flags:2 ('_XCT_logDebugMessage:', ['14:25:03.141 WebDriverAgentRunner-Runner[338:11154] Requesting serialized transport wrapper for IDE connection\n'])
[D 220316 14:25:01 _instruments:591] DTXMessage: expects_reply:1 flags:2 ('_XCT_logDebugMessage:', ['14:25:03.142 WebDriverAgentRunner-Runner[338:11154] Creating transport with received transport data\n'])
[D 220316 14:25:01 _instruments:591] DTXMessage: expects_reply:1 flags:2 ('_XCT_logDebugMessage:', ['14:25:03.142 WebDriverAgentRunner-Runner[338:11154] Creating connection with transport <DTXFileDescriptorTransport: 0x2822b98f0>\n'])
[D 220316 14:25:01 _instruments:591] DTXMessage: expects_reply:1 flags:2 ('_XCT_logDebugMessage:', ['14:25:03.142 WebDriverAgentRunner-Runner[338:11154] Creating the proxy channel with connection <DTXConnection 0x2835bdc00 : x1>\n'])
[D 220316 14:25:01 _instruments:591] DTXMessage: expects_reply:1 flags:2 ('_XCT_logDebugMessage:', ['14:25:03.142 WebDriverAgentRunner-Runner[338:11138] Returning result XCTWaiterResultCompleted for waiter <XCTWaiter: 0x2822a81c0, state: XCTWaiterStateFinished>\n'])
[D 220316 14:25:01 _instruments:591] DTXMessage: expects_reply:1 flags:2 ('_XCT_logDebugMessage:', ['14:25:03.142 WebDriverAgentRunner-Runner[338:11138] Loading test bundle at: file:///private/var/containers/Bundle/Application/758C4795-F053-4F90-8057-4D9CBD24D998/WebDriverAgentRunner-Runner.app/PlugIns/WebDriverAgentRunner.xctest\n'])
[D 220316 14:25:01 _instruments:591] DTXMessage: expects_reply:1 flags:2 ('_XCT_logDebugMessage:', ['14:25:03.150 WebDriverAgentRunner-Runner[338:11138] Finished loading test bundle\n'])
[D 220316 14:25:01 _instruments:591] DTXMessage: expects_reply:1 flags:2 ('_XCT_logDebugMessage:', ['14:25:03.151 WebDriverAgentRunner-Runner[338:11138] Running tests with active test configuration: <XCTestConfiguration: 0x2835b9700>\n\t testBundleURL:file:///private/var/containers/Bundle/Application/758C4795-F053-4F90-8057-4D9CBD24D998/WebDriverAgentRunner-Runner.app/PlugIns/WebDriverAgentRunner.xctest\n\t testBundleRelativePath:(null)\n\t productModuleName:WebDriverAgentRunner\n\t testsToSkip:\n\t testsToRun:(null)\n\t reportResultsToIDE:YES\n\t testsDrivenByIDE:no\n\t sessionIdentifier:488D4E9E-6F21-45D1-9EC2-A1F41E0A0671\n\t disablePerformanceMetrics:no\n\ttreatMissingBaselinesAsFailures:no\n\t baselineFileURL:(null)\n\t baselineFileRelativePath:(null)\n\t targetApplicationPath:/whatever-it-does-not-matter/but-should-not-be-empty\n\t targetApplicationBundleID:(null)\n\t testApplicationDependencies:\n{\n}\n\t testApplicationUserOverrides:(null)\n\t targetApplicationArguments:\n\t targetApplicationEnvironment:(null)\n\t reportActivities:YES\n\t testsMustRunOnMainThread:YES\n\t initializeForUITesting:YES\n\t traceCollectionEnabled:(null)\n\t aggregateStatisticsBeforeCrash:\n{\n XCSuiteRecordsKey = {\n };\n}\n\t automationFrameworkPath:/Developer/Library/PrivateFrameworks/XCTAutomationSupport.framework\n\t emitOSLogs:no\n\t systemAttachmentLifetime:2\n\t userAttachmentLifetime:1\n\t gatherLocalizableStringsData:no\n\t testExecutionOrdering:alphabetical\n\t randomExecutionOrderingSeed:(null)\n\t rerunPolicy:<XCTRerunPolicy: 0x28098b200>\n\t rerunMode:None\n\t maximumIterations:1\n\n\t testTimeoutsEnabled:NO\n\t defaultTestExecutionTimeAllowance:600\n\t maximumTestExecutionTimeAllowance:0\n\t IDECapabilities:(null)\n\n'])
[D 220316 14:25:01 _instruments:591] DTXMessage: expects_reply:1 flags:2 ('_XCT_logDebugMessage:', ["14:25:03.151 WebDriverAgentRunner-Runner[338:11138] Creating future for 'requesting test configuration' with timeout 600.00\n"])
[D 220316 14:25:01 _instruments:591] DTXMessage: expects_reply:1 flags:2 ('_XCT_logDebugMessage:', ['14:25:03.151 WebDriverAgentRunner-Runner[338:11138] Notifying test runner ready\n'])
[D 220316 14:25:01 _instruments:591] DTXMessage: expects_reply:1 flags:2 ('_XCT_testRunnerReadyWithCapabilities:', [None])
[D 220316 14:25:01 _instruments:591] DTXMessage: expects_reply:1 flags:2 ('_XCT_logDebugMessage:', ['14:25:03.151 WebDriverAgentRunner-Runner[338:11138] <XCTWaiter: 0x2822a8690, state: XCTWaiterStateInProgress> entering wait loop for 600.00s with expectations: requesting test configuration\n'])
[D 220316 14:25:01 _instruments:591] DTXMessage: expects_reply:1 flags:2 ('_XCT_logDebugMessage:', ['14:25:03.178 WebDriverAgentRunner-Runner[338:11156] Received test runner ready reply with error: (null)\n'])
[I 220316 14:25:01 _device:1012] Test runner ready detected
[I 220316 14:25:01 _device:1005] Start execute test plan with IDE version: 29
[D 220316 14:25:01 _instruments:591] DTXMessage: expects_reply:1 flags:2 ('_XCT_logDebugMessage:', ['14:25:03.178 WebDriverAgentRunner-Runner[338:11138] Returning result XCTWaiterResultCompleted for waiter <XCTWaiter: 0x2822a8690, state: XCTWaiterStateFinished>\n'])
[D 220316 14:25:01 _instruments:591] DTXMessage: expects_reply:1 flags:2 ('_XCT_logDebugMessage:', ['14:25:03.178 WebDriverAgentRunner-Runner[338:11138] <XCTWaiter: 0x2822b9a40, state: XCTWaiterStateInProgress> entering wait loop for 600.00s with expectations: requesting ready for testing\n'])
[D 220316 14:25:01 _instruments:591] DTXMessage: expects_reply:1 flags:2 ('_XCT_logDebugMessage:', ['14:25:03.181 WebDriverAgentRunner-Runner[338:11154] _IDE_startExecutingTestPlanWithProtocolVersion:29\n'])
[D 220316 14:25:01 _instruments:591] DTXMessage: expects_reply:1 flags:2 ('_XCT_logDebugMessage:', ['14:25:03.181 WebDriverAgentRunner-Runner[338:11138] Returning result XCTWaiterResultCompleted for waiter <XCTWaiter: 0x2822b9a40, state: XCTWaiterStateFinished>\n'])
[D 220316 14:25:01 _instruments:591] DTXMessage: expects_reply:1 flags:2 ('_XCT_didBeginExecutingTestPlan', None)
[D 220316 14:25:01 _instruments:591] DTXMessage: expects_reply:1 flags:2 ('_XCT_didBeginInitializingForUITesting', None)
[D 220316 14:25:01 _instruments:591] DTXMessage: expects_reply:1 flags:2 ('_XCT_logDebugMessage:', ['14:25:03.181 WebDriverAgentRunner-Runner[338:11138] Initializing for UI testing.\n'])
[D 220316 14:25:01 _instruments:591] DTXMessage: expects_reply:1 flags:2 ('_XCT_logDebugMessage:', ['14:25:03.183 WebDriverAgentRunner-Runner[338:11138] Linked XCTAutomationSupport.framework from /var/containers/Bundle/Application/758C4795-F053-4F90-8057-4D9CBD24D998/WebDriverAgentRunner-Runner.app/Frameworks/XCTAutomationSupport.framework/XCTAutomationSupport, built with Xcode 1250(12E249), modified on 2021▒▒12▒▒24▒▒ ▒▒▒▒▒▒ ▒й▒▒▒׼ʱ▒▒ ▒▒▒▒10:57:27\nXCTAutomationSupport.framework bundle version: 18141\n'])
[D 220316 14:25:01 _instruments:591] DTXMessage: expects_reply:1 flags:2 ('_XCT_logDebugMessage:', ['14:25:03.183 WebDriverAgentRunner-Runner[338:11138] <XCTWaiter: 0x2822b9880, state: XCTWaiterStateResolved> entering wait loop for 60.00s with expectations: initiating daemon session\n'])
[D 220316 14:25:01 _instruments:591] DTXMessage: expects_reply:1 flags:2 ('_XCT_logDebugMessage:', ['14:25:03.183 WebDriverAgentRunner-Runner[338:11138] Returning result XCTWaiterResultCompleted for waiter <XCTWaiter: 0x2822b9880, state: XCTWaiterStateFinished>\n'])
[D 220316 14:25:01 _instruments:591] DTXMessage: expects_reply:1 flags:2 ('_XCT_logDebugMessage:', ["14:25:03.183 WebDriverAgentRunner-Runner[338:11138] Creating future for 'loading Accessibility' with timeout 70.00\n"])
[D 220316 14:25:01 _instruments:591] DTXMessage: expects_reply:1 flags:2 ('_XCT_logDebugMessage:', ['14:25:03.183 WebDriverAgentRunner-Runner[338:11138] <XCTWaiter: 0x2822b99d0, state: XCTWaiterStateInProgress> entering wait loop for 70.00s with expectations: loading Accessibility\n'])
[D 220316 14:25:01 _instruments:591] DTXMessage: expects_reply:1 flags:2 ('_XCT_logDebugMessage:', ['14:25:03.185 WebDriverAgentRunner-Runner[338:11138] Returning result XCTWaiterResultCompleted for waiter <XCTWaiter: 0x2822b99d0, state: XCTWaiterStateFinished>\n'])
[D 220316 14:25:01 _instruments:591] DTXMessage: expects_reply:1 flags:2 ('_XCT_logDebugMessage:', ['14:25:03.185 WebDriverAgentRunner-Runner[338:11138] Error from daemon in loading AX: Not authorized for performing UI testing actions.\n'])
[D 220316 14:25:01 _instruments:591] DTXMessage: expects_reply:1 flags:2 ('_XCT_logDebugMessage:', ['14:25:03.185 WebDriverAgentRunner-Runner[338:11138] Error capturing sample for process backboardd:\nError Domain=XCTDaemonErrorDomain Code=20 "Failed to spawn process for sample: 35 - Resource temporarily unavailable" UserInfo={NSLocalizedDescription=Failed to spawn process for sample: 35 - Resource temporarily unavailable}\n'])
[D 220316 14:25:01 _instruments:591] DTXMessage: expects_reply:1 flags:2 ('_XCT_logDebugMessage:', ['14:25:03.186 WebDriverAgentRunner-Runner[338:11138] Error capturing sample for process SpringBoard:\nError Domain=XCTDaemonErrorDomain Code=20 "Failed to spawn process for sample: 35 - Resource temporarily unavailable" UserInfo={NSLocalizedDescription=Failed to spawn process for sample: 35 - Resource temporarily unavailable}\n'])
[D 220316 14:25:01 _instruments:591] DTXMessage: expects_reply:1 flags:2 ('_XCT_logDebugMessage:', ['14:25:03.186 WebDriverAgentRunner-Runner[338:11138] Failed to load accessibility.\n'])
[D 220316 14:25:01 _instruments:591] DTXMessage: expects_reply:1 flags:2 ('_XCT_initializationForUITestingDidFailWithError:', [NSError(CODE:41 DOMAIN:XCTDaemonErrorDomain INFO:{'NSLocalizedDescription': 'Not authorized for performing UI testing actions.',
'samples': {'SpringBoard': NSError(CODE:20 DOMAIN:XCTDaemonErrorDomain INFO:{'NSLocalizedDescription': 'Failed to spawn process for sample: 35 - Resource '
'temporarily unavailable'}),
'backboardd': NSError(CODE:20 DOMAIN:XCTDaemonErrorDomain INFO:{'NSLocalizedDescription': 'Failed to spawn process for sample: 35 - Resource '
'temporarily unavailable'})}})])
[D 220316 14:25:01 _instruments:591] DTXMessage: expects_reply:1 flags:2 ('_XCT_logDebugMessage:', ['14:25:03.186 WebDriverAgentRunner-Runner[338:11138] Failed to initialize for UI testing: Error Domain=XCTDaemonErrorDomain Code=41 "Not authorized for performing UI testing actions." UserInfo={samples={\n SpringBoard = "Error Domain=XCTDaemonErrorDomain Code=20 \"Failed to spawn process for sample: 35 - Resource temporarily unavailable\" UserInfo={NSLocalizedDescription=Failed to spawn process for sample: 35 - Resource temporarily unavailable}";\n backboardd = "Error Domain=XCTDaemonErrorDomain Code=20 \"Failed to spawn process for sample: 35 - Resource temporarily unavailable\" UserInfo={NSLocalizedDescription=Failed to spawn process for sample: 35 - Resource temporarily unavailable}";\n}, NSLocalizedDescription=Not authorized for performing UI testing actions.}\n'])
[D 220316 14:25:01 _instruments:591] DTXMessage: expects_reply:1 flags:2 ('_XCT_logDebugMessage:', ["14:25:03.186 WebDriverAgentRunner-Runner[338:11138] Creating future for 'flushing telemetry' with timeout 10.00\n"])
[D 220316 14:25:01 _instruments:591] DTXMessage: expects_reply:1 flags:2 ('_XCT_logDebugMessage:', ['14:25:03.186 WebDriverAgentRunner-Runner[338:11138] <XCTWaiter: 0x2822a87e0, state: XCTWaiterStateInProgress> entering wait loop for 10.00s with expectations: flushing telemetry\n'])
[D 220316 14:25:01 _instruments:591] DTXMessage: expects_reply:1 flags:2 ('_XCT_logDebugMessage:', ['14:25:03.186 WebDriverAgentRunner-Runner[338:11138] Returning result XCTWaiterResultCompleted for waiter <XCTWaiter: 0x2822a87e0, state: XCTWaiterStateFinished>\n'])
[D 220316 14:25:01 _instruments:591] DTXMessage: expects_reply:1 flags:2 ('_XCT_didFinishExecutingTestPlan', None)
[D 220316 14:25:01 _instruments:591] DTXMessage: expects_reply:1 flags:2 ('_XCT_logDebugMessage:', ['14:25:03.186 WebDriverAgentRunner-Runner[338:11138] Calling completion.\n'])
[D 220316 14:25:01 _instruments:591] DTXMessage: expects_reply:1 flags:2 ('_XCT_logDebugMessage:', ['14:25:03.186 WebDriverAgentRunner-Runner[338:11138] <XCTWaiter: 0x2822a87e0, state: XCTWaiterStateInProgress> entering wait loop for 1800.00s with expectations: IDE confirmed the end of session\n'])
[D 220316 14:25:01 _instruments:569] dtxm socket closed
[D 220316 14:25:01 _instruments:591] DTXMessage: expects_reply:0 flags:2 ('pidDiedCallback:', [338])
[D 220316 14:25:01 _instruments:606] Ignore notification from server: 9, 0x2, ('pidDiedCallback:', [338])
[D 220316 14:25:01 _safe_socket:82] Socket <tidevice._safe_socket.PlistSocket object at 0x04C4C8B0> closed
[D 220316 14:25:01 _device:929] ProductVersion: 14.6
[D 220316 14:25:01 _instruments:591] DTXMessage: expects_reply:0 flags:3 True
[I 220316 14:25:01 _device:1084] xctrunner quited