10:17:03.945 -> 10:17:03.945 -> 10:17:03.945 -> DFPlayer Analyzer 1.0 - Starting up... 10:17:03.945 -> 10:17:04.013 -> [60] Packet sent (->): 7E FF 6 42 0 0 0 FE B9 EF -> Cmd: 42 (GetStatus), arg: 0 10:17:04.013 -> [72] Packet received (<-): 7E FF 6 42 0 2 0 FE B7 EF <- Msg: 42 (GetStatus), arg: 512 10:17:04.046 -> ------------------------------------------------------- 10:17:04.046 -> Test Case "TestConnectivity" FINISHED 10:17:04.046 -> ------------------------------------------------------- 10:17:04.046 -> 10:17:04.046 -> 10:17:04.046 -> ------------------------------------------------------- 10:17:04.046 -> Running Test Case "TestDiscoverDevices" 10:17:04.046 -> ------------------------------------------------------- 10:17:04.081 -> [133] Packet sent (->): 7E FF 6 C 0 0 0 FE EF EF -> Cmd: C (Reset), arg: 0 10:17:04.730 -> [785] Packet received (<-): 7E FF 6 3F 0 0 2 FE BA EF <- Msg: 3F (StorageDevices), arg: 2 10:17:04.730 -> Callback OnCardOnline: 2 10:17:06.194 -> [2251] Packet sent (->): 7E FF 6 7 0 0 0 FE F4 EF -> Cmd: 7 (SetEQ), arg: 0 10:17:06.329 -> [2363] Packet sent (->): 7E FF 6 1A 0 0 0 FE E1 EF -> Cmd: 1A (SetDAC), arg: 0 10:17:06.568 -> [2625] Packet sent (->): 7E FF 6 6 0 0 1 FE F4 EF -> Cmd: 6 (SetVolume), arg: 1 10:17:06.636 -> [2688] Packet sent (->): 7E FF 6 C 0 0 0 FE EF EF -> Cmd: C (Reset), arg: 0 10:17:07.278 -> [3340] Packet received (<-): 7E FF 6 3F 0 0 2 FE BA EF <- Msg: 3F (StorageDevices), arg: 2 10:17:07.313 -> Callback OnCardOnline: 2 10:17:08.642 -> [4706] Packet sent (->): 7E FF 6 9 0 0 2 FE F0 EF -> Cmd: 9 (SetPlaybackDevice), arg: 2 10:17:10.684 -> [6721] Packet sent (->): 7E FF 6 48 0 0 0 FE B3 EF -> Cmd: 48 (GetNoTracksSD), arg: 0 10:17:10.684 -> [6731] Packet received (<-): 7E FF 6 48 0 0 72 FE 41 EF <- Msg: 48 (GetNoTracksSD), arg: 114 10:17:10.752 -> [6793] Packet sent (->): 7E FF 6 9 0 0 1 FE F1 EF -> Cmd: 9 (SetPlaybackDevice), arg: 1 10:17:12.742 -> [8808] Packet sent (->): 7E FF 6 47 0 0 0 FE B4 EF -> Cmd: 47 (GetNoTracksUSB), arg: 0 10:17:12.776 -> [8818] Packet received (<-): 7E FF 6 47 0 0 0 FE B4 EF <- Msg: 47 (GetNoTracksUSB), arg: 0 10:17:13.801 -> [9837] Packet sent (->): 7E FF 6 9 0 0 5 FE ED EF -> Cmd: 9 (SetPlaybackDevice), arg: 5 10:17:13.938 -> [9990] Packet received (<-): 7E FF 6 3F 0 0 2 FE BA EF <- Msg: 3F (StorageDevices), arg: 2 10:17:13.938 -> Callback OnCardOnline: 2 10:17:15.817 -> [11856] Packet sent (->): 7E FF 6 9 0 0 2 FE F0 EF -> Cmd: 9 (SetPlaybackDevice), arg: 2 10:17:15.817 -> ------------------------------------------------------- 10:17:15.817 -> Test Case "TestDiscoverDevices" FINISHED 10:17:15.817 -> ------------------------------------------------------- 10:17:15.817 -> 10:17:15.817 -> 10:17:15.817 -> ------------------------------------------------------- 10:17:15.817 -> Running Test Case "TestReaction3F" 10:17:15.852 -> ------------------------------------------------------- 10:17:16.022 -> [12069] Packet sent (->): 7E FF 6 C 0 0 0 FE EF EF -> Cmd: C (Reset), arg: 0 10:17:16.671 -> [12722] Packet received (<-): 7E FF 6 3F 0 0 2 FE BA EF <- Msg: 3F (StorageDevices), arg: 2 10:17:16.671 -> Callback OnCardOnline: 2 10:17:18.136 -> [14188] Packet sent (->): 7E FF 6 7 0 0 0 FE F4 EF -> Cmd: 7 (SetEQ), arg: 0 10:17:18.238 -> [14300] Packet sent (->): 7E FF 6 1A 0 0 0 FE E1 EF -> Cmd: 1A (SetDAC), arg: 0 10:17:18.509 -> [14563] Packet sent (->): 7E FF 6 6 0 0 1 FE F4 EF -> Cmd: 6 (SetVolume), arg: 1 10:17:18.577 -> [14625] Packet sent (->): 7E FF 6 3F 0 0 0 FE BC EF -> Cmd: 3F (StorageDevices), arg: 0 10:17:20.080 -> -------------- 10:17:20.080 -> ERROR 129 10:17:20.080 -> -------------- 10:17:20.080 -> ------------------------------------------------------- 10:17:20.080 -> Test Case "TestReaction3F" FINISHED 10:17:20.080 -> ------------------------------------------------------- 10:17:20.080 -> 10:17:20.080 -> 10:17:20.080 -> ------------------------------------------------------- 10:17:20.114 -> Running Test Case "TestGetFolderTrackCount" 10:17:20.114 -> ------------------------------------------------------- 10:17:20.114 -> [16162] Packet sent (->): 7E FF 6 C 0 0 0 FE EF EF -> Cmd: C (Reset), arg: 0 10:17:20.761 -> [16818] Packet received (<-): 7E FF 6 3F 0 0 2 FE BA EF <- Msg: 3F (StorageDevices), arg: 2 10:17:20.761 -> Callback OnCardOnline: 2 10:17:22.227 -> [18284] Packet sent (->): 7E FF 6 7 0 0 0 FE F4 EF -> Cmd: 7 (SetEQ), arg: 0 10:17:22.364 -> [18396] Packet sent (->): 7E FF 6 1A 0 0 0 FE E1 EF -> Cmd: 1A (SetDAC), arg: 0 10:17:22.606 -> [18659] Packet sent (->): 7E FF 6 6 0 0 1 FE F4 EF -> Cmd: 6 (SetVolume), arg: 1 10:17:22.673 -> [18721] Packet sent (->): 7E FF 6 9 0 0 2 FE F0 EF -> Cmd: 9 (SetPlaybackDevice), arg: 2 10:17:23.697 -> [19735] Packet sent (->): 7E FF 6 4E 0 0 1 FE AC EF -> Cmd: 4E (GetNoTracksFolder), arg: 1 10:17:23.732 -> [19769] Packet received (<-): 7E FF 6 4E 0 0 12 FE 9B EF <- Msg: 4E (GetNoTracksFolder), arg: 18 10:17:23.732 -> ------------------------------------------------------- 10:17:23.732 -> Test Case "TestGetFolderTrackCount" FINISHED 10:17:23.732 -> ------------------------------------------------------- 10:17:23.732 -> 10:17:23.732 -> 10:17:23.732 -> ------------------------------------------------------- 10:17:23.732 -> Running Test Case "TestGetCurrentTrack" 10:17:23.771 -> ------------------------------------------------------- 10:17:23.771 -> [19809] Packet sent (->): 7E FF 6 C 0 0 0 FE EF EF -> Cmd: C (Reset), arg: 0 10:17:24.422 -> [20464] Packet received (<-): 7E FF 6 3F 0 0 2 FE BA EF <- Msg: 3F (StorageDevices), arg: 2 10:17:24.422 -> Callback OnCardOnline: 2 10:17:25.886 -> [21931] Packet sent (->): 7E FF 6 7 0 0 0 FE F4 EF -> Cmd: 7 (SetEQ), arg: 0 10:17:25.989 -> [22043] Packet sent (->): 7E FF 6 1A 0 0 0 FE E1 EF -> Cmd: 1A (SetDAC), arg: 0 10:17:26.261 -> [22305] Packet sent (->): 7E FF 6 6 0 0 1 FE F4 EF -> Cmd: 6 (SetVolume), arg: 1 10:17:26.329 -> [22369] Packet sent (->): 7E FF 6 9 0 0 2 FE F0 EF -> Cmd: 9 (SetPlaybackDevice), arg: 2 10:17:26.533 -> [22582] Packet sent (->): 7E FF 6 F 0 1 2 FE E9 EF -> Cmd: F (PlayFolderTrack), arg: 258 10:17:28.546 -> [24597] Packet sent (->): 7E FF 6 4C 0 0 0 FE AF EF -> Cmd: 4C (GetCurrentTrackSD), arg: 0 10:17:28.580 -> [24608] Packet received (<-): 7E FF 6 4C 0 0 B FE A4 EF <- Msg: 4C (GetCurrentTrackSD), arg: 11 10:17:28.615 -> [24670] Packet sent (->): 7E FF 6 F 0 1 1 FE EA EF -> Cmd: F (PlayFolderTrack), arg: 257 10:17:28.683 -> [24733] Packet sent (->): 7E FF 6 4C 0 0 0 FE AF EF -> Cmd: 4C (GetCurrentTrackSD), arg: 0 10:17:28.717 -> [24744] Packet received (<-): 7E FF 6 4C 0 0 9 FE A6 EF <- Msg: 4C (GetCurrentTrackSD), arg: 9 10:17:28.717 -> GetCurrentTrack for SD returns correct value 0ms after start of track 10:17:28.752 -> [24804] Packet sent (->): 7E FF 6 16 0 0 0 FE E5 EF -> Cmd: 16 (Stop), arg: 0 10:17:28.752 -> ------------------------------------------------------- 10:17:28.752 -> Test Case "TestGetCurrentTrack" FINISHED 10:17:28.785 -> ------------------------------------------------------- 10:17:28.785 -> 10:17:28.785 -> 10:17:28.785 -> ------------------------------------------------------- 10:17:28.785 -> Running Test Case "TestTrackFinishedCallback" 10:17:28.785 -> ------------------------------------------------------- 10:17:28.820 -> [24867] Packet sent (->): 7E FF 6 C 0 0 0 FE EF EF -> Cmd: C (Reset), arg: 0 10:17:29.466 -> [25520] Packet received (<-): 7E FF 6 3F 0 0 2 FE BA EF <- Msg: 3F (StorageDevices), arg: 2 10:17:29.466 -> Callback OnCardOnline: 2 10:17:30.936 -> [26985] Packet sent (->): 7E FF 6 7 0 0 0 FE F4 EF -> Cmd: 7 (SetEQ), arg: 0 10:17:31.037 -> [27098] Packet sent (->): 7E FF 6 1A 0 0 0 FE E1 EF -> Cmd: 1A (SetDAC), arg: 0 10:17:31.309 -> [27361] Packet sent (->): 7E FF 6 6 0 0 1 FE F4 EF -> Cmd: 6 (SetVolume), arg: 1 10:17:31.378 -> [27423] Packet sent (->): 7E FF 6 9 0 0 2 FE F0 EF -> Cmd: 9 (SetPlaybackDevice), arg: 2 10:17:32.401 -> [28437] Packet sent (->): 7E FF 6 F 0 1 2 FE E9 EF -> Cmd: F (PlayFolderTrack), arg: 258 10:17:34.411 -> [30452] Packet sent (->): 7E FF 6 4C 0 0 0 FE AF EF -> Cmd: 4C (GetCurrentTrackSD), arg: 0 10:17:34.411 -> [30464] Packet received (<-): 7E FF 6 4C 0 0 B FE A4 EF <- Msg: 4C (GetCurrentTrackSD), arg: 11 10:20:11.360 -> [187407] Packet received (<-): 7E FF 6 3D 0 0 B FE B3 EF <- Msg: 3D (TrackFinishedSD), arg: 11 10:20:11.360 -> Callback global track finished: 11 10:20:11.360 -> [187413] Packet received (<-): 7E FF 6 3D 0 0 B FE B3 EF <- Msg: 3D (TrackFinishedSD), arg: 11 10:20:11.360 -> Callback global track finished: 11 10:20:13.516 -> Received 2 callback(s) on SD track end 10:20:13.516 -> ------------------------------------------------------- 10:20:13.516 -> Test Case "TestTrackFinishedCallback" FINISHED 10:20:13.516 -> ------------------------------------------------------- 10:20:13.550 -> 10:20:13.550 -> 10:20:13.550 -> ------------------------------------------------------- 10:20:13.550 -> Running Test Case "TestContinuePlaybackAfterAd" 10:20:13.550 -> ------------------------------------------------------- 10:20:13.550 -> [189612] Packet sent (->): 7E FF 6 C 0 0 0 FE EF EF -> Cmd: C (Reset), arg: 0 10:20:14.198 -> [190267] Packet received (<-): 7E FF 6 3F 0 0 2 FE BA EF <- Msg: 3F (StorageDevices), arg: 2 10:20:14.232 -> Callback OnCardOnline: 2 10:20:15.667 -> [191733] Packet sent (->): 7E FF 6 7 0 0 0 FE F4 EF -> Cmd: 7 (SetEQ), arg: 0 10:20:15.805 -> [191846] Packet sent (->): 7E FF 6 1A 0 0 0 FE E1 EF -> Cmd: 1A (SetDAC), arg: 0 10:20:16.045 -> [192108] Packet sent (->): 7E FF 6 6 0 0 1 FE F4 EF -> Cmd: 6 (SetVolume), arg: 1 10:20:16.113 -> [192171] Packet sent (->): 7E FF 6 9 0 0 2 FE F0 EF -> Cmd: 9 (SetPlaybackDevice), arg: 2 10:20:17.139 -> [193184] Packet sent (->): 7E FF 6 F 0 1 2 FE E9 EF -> Cmd: F (PlayFolderTrack), arg: 258 10:20:19.127 -> [195200] Packet sent (->): 7E FF 6 4C 0 0 0 FE AF EF -> Cmd: 4C (GetCurrentTrackSD), arg: 0 10:20:19.162 -> [195212] Packet received (<-): 7E FF 6 4C 0 0 B FE A4 EF <- Msg: 4C (GetCurrentTrackSD), arg: 11 10:20:19.229 -> [195271] Packet sent (->): 7E FF 6 13 0 0 64 FE 84 EF -> Cmd: 13 (PlayAd), arg: 100 10:20:19.710 -> [195785] Packet sent (->): 7E FF 6 4C 0 0 0 FE AF EF -> Cmd: 4C (GetCurrentTrackSD), arg: 0 10:20:19.744 -> [195796] Packet received (<-): 7E FF 6 4C 0 0 B FE A4 EF <- Msg: 4C (GetCurrentTrackSD), arg: 11 10:22:57.673 -> [353742] Packet received (<-): 7E FF 6 3D 0 0 B FE B3 EF <- Msg: 3D (TrackFinishedSD), arg: 11 10:22:57.673 -> Callback global track finished: 11 10:22:57.673 -> [353748] Packet received (<-): 7E FF 6 3D 0 0 B FE B3 EF <- Msg: 3D (TrackFinishedSD), arg: 11 10:22:57.708 -> Callback global track finished: 11 10:22:58.250 -> [354322] Packet sent (->): 7E FF 6 42 0 0 0 FE B9 EF -> Cmd: 42 (GetStatus), arg: 0 10:22:58.285 -> [354332] Packet received (<-): 7E FF 6 42 0 2 0 FE B7 EF <- Msg: 42 (GetStatus), arg: 512 10:22:58.285 -> ------------------------------------------------------- 10:22:58.285 -> Test Case "TestContinuePlaybackAfterAd" FINISHED 10:22:58.285 -> ------------------------------------------------------- 10:22:58.285 -> 10:22:58.285 -> 10:22:58.285 -> ------------------------------------------------------- 10:22:58.285 -> Running Test Case "TestWakeupAfterSleep" 10:22:58.285 -> ------------------------------------------------------- 10:22:58.319 -> [354393] Packet sent (->): 7E FF 6 C 0 0 0 FE EF EF -> Cmd: C (Reset), arg: 0 10:22:58.968 -> [355045] Packet received (<-): 7E FF 6 3F 0 0 2 FE BA EF <- Msg: 3F (StorageDevices), arg: 2 10:22:59.003 -> Callback OnCardOnline: 2 10:23:00.438 -> [356511] Packet sent (->): 7E FF 6 7 0 0 0 FE F4 EF -> Cmd: 7 (SetEQ), arg: 0 10:23:00.541 -> [356624] Packet sent (->): 7E FF 6 1A 0 0 0 FE E1 EF -> Cmd: 1A (SetDAC), arg: 0 10:23:00.813 -> [356886] Packet sent (->): 7E FF 6 6 0 0 1 FE F4 EF -> Cmd: 6 (SetVolume), arg: 1 10:23:00.883 -> [356948] Packet sent (->): 7E FF 6 9 0 0 2 FE F0 EF -> Cmd: 9 (SetPlaybackDevice), arg: 2 10:23:01.088 -> [357164] Packet sent (->): 7E FF 6 6 0 0 0 FE F5 EF -> Cmd: 6 (SetVolume), arg: 0 10:23:01.157 -> [357226] Packet sent (->): 7E FF 6 16 0 0 0 FE E5 EF -> Cmd: 16 (Stop), arg: 0 10:23:01.361 -> [357438] Packet sent (->): 7E FF 6 1A 0 0 1 FE E0 EF -> Cmd: 1A (SetDAC), arg: 1 10:23:01.598 -> [357651] Packet sent (->): 7E FF 6 A 0 0 0 FE F1 EF -> Cmd: A (Sleep), arg: 0 10:23:03.791 -> [359865] Packet sent (->): 7E FF 6 C 0 0 0 FE EF EF -> Cmd: C (Reset), arg: 0 10:23:03.825 -> [359875] Packet received (<-): 7E FF 6 40 0 0 2 FE B9 EF <- Msg: 40 (Error), arg: 2 10:23:03.825 -> -------------- 10:23:03.825 -> ERROR 2 10:23:03.825 -> -------------- 10:23:05.836 -> [361899] Packet sent (->): 7E FF 6 6 0 0 0 FE F5 EF -> Cmd: 6 (SetVolume), arg: 0 10:23:05.905 -> [361970] Packet sent (->): 7E FF 6 16 0 0 0 FE E5 EF -> Cmd: 16 (Stop), arg: 0 10:23:06.108 -> [362192] Packet sent (->): 7E FF 6 1A 0 0 1 FE E0 EF -> Cmd: 1A (SetDAC), arg: 1 10:23:06.347 -> [362414] Packet sent (->): 7E FF 6 A 0 0 0 FE F1 EF -> Cmd: A (Sleep), arg: 0 10:23:08.567 -> [364636] Packet sent (->): 7E FF 6 9 0 0 2 FE F0 EF -> Cmd: 9 (SetPlaybackDevice), arg: 2 10:23:10.672 -> [366749] Packet sent (->): 7E FF 6 7 0 0 0 FE F4 EF -> Cmd: 7 (SetEQ), arg: 0 10:23:10.809 -> [366862] Packet sent (->): 7E FF 6 1A 0 0 0 FE E1 EF -> Cmd: 1A (SetDAC), arg: 0 10:23:11.047 -> [367124] Packet sent (->): 7E FF 6 6 0 0 1 FE F4 EF -> Cmd: 6 (SetVolume), arg: 1 10:23:11.047 -> [367126] Packet received (<-): 7E FF 6 40 0 0 2 FE B9 EF <- Msg: 40 (Error), arg: 2 10:23:11.081 -> -------------- 10:23:11.081 -> ERROR 2 10:23:11.081 -> -------------- 10:23:11.081 -> [367137] Packet received (<-): 7E FF 6 40 0 0 2 FE B9 EF <- Msg: 40 (Error), arg: 2 10:23:11.081 -> -------------- 10:23:11.081 -> ERROR 2 10:23:11.081 -> -------------- 10:23:11.081 -> [367149] Packet received (<-): 7E FF 6 40 0 0 2 FE B9 EF <- Msg: 40 (Error), arg: 2 10:23:11.081 -> -------------- 10:23:11.081 -> ERROR 2 10:23:11.081 -> -------------- 10:23:11.081 -> [367159] Packet received (<-): 7E FF 6 40 0 0 2 FE B9 EF <- Msg: 40 (Error), arg: 2 10:23:11.115 -> -------------- 10:23:11.115 -> ERROR 2 10:23:11.115 -> -------------- 10:23:11.115 -> [367186] Packet sent (->): 7E FF 6 F 0 1 2 FE E9 EF -> Cmd: F (PlayFolderTrack), arg: 258 10:23:13.131 -> [369202] Packet sent (->): 7E FF 6 42 0 0 0 FE B9 EF -> Cmd: 42 (GetStatus), arg: 0 10:23:13.164 -> [369213] Packet received (<-): 7E FF 6 42 0 2 1 FE B6 EF <- Msg: 42 (GetStatus), arg: 513 10:23:13.198 -> [369274] Packet sent (->): 7E FF 6 16 0 0 0 FE E5 EF -> Cmd: 16 (Stop), arg: 0 10:23:13.198 -> ------------------------------------------------------- 10:23:13.232 -> Test Case "TestWakeupAfterSleep" FINISHED 10:23:13.232 -> ------------------------------------------------------- 10:23:13.232 -> 10:23:13.232 -> 10:23:13.232 -> ------------------------------------------------------- 10:23:13.232 -> Profile of this DFPlayer device 10:23:13.232 -> ------------------------------------------------------- 10:23:13.232 -> The following devices have been discovered: 10:23:13.232 -> -> SD, announced on reset, discovered by getTotalTrackCount, announced on setPlaysourceFlash 10:23:13.267 -> Does NOT react on 0x3F queries 10:23:13.267 -> getFolderTrackCount... 10:23:13.267 -> -> for SD returned 18 files in Folder /01/ 10:23:13.267 -> GetCurrentTrack for SD returns correct value 0ms after start of track 10:23:13.267 -> Sends 2 callback(s) on SD track end 10:23:13.267 -> Continue playback after Ad for SD works 10:23:13.267 -> Wakeup from sleep by reset does NOT work 10:23:13.267 -> Wakeup from sleep by setting playsource SD works 10:23:13.267 -> 10:23:13.267 -> 10:23:13.267 -> ------------------------------------------------------------------------------- 10:23:13.301 -> ALL TESTS COMPLETED! 10:23:13.301 -> 10:23:13.301 -> Please report your results in an issue at 10:23:13.301 -> 10:23:13.301 -> https://github.com/ghmartin77/DFPlayerAnalyzer/issues 10:23:13.301 -> 10:23:13.301 -> Please state the player's chip name in the title of the issue entry. 10:23:13.301 -> Thanks for your support! 10:23:13.301 -> -------------------------------------------------------------------------------