09:07:01.589 -> DFPlayer Analyzer 1.0 - Starting up...
09:07:01.589 ->
09:07:01.658 -> [60] Packet sent (->): 7E FF 6 42 0 0 0 FE B9 EF -> Cmd: 42 (GetStatus), arg: 0
09:07:01.769 -> [171] Packet received (<-): 7E FF 6 42 0 0 0 FE B9 EF <- Msg: 42 (GetStatus), arg: 0
09:07:01.769 -> -------------------------------------------------------
09:07:01.769 -> Test Case "TestConnectivity" FINISHED
09:07:01.769 -> -------------------------------------------------------
09:07:01.769 ->
09:07:01.769 ->
09:07:01.769 -> -------------------------------------------------------
09:07:01.769 -> Running Test Case "TestDiscoverDevices"
09:07:01.802 -> -------------------------------------------------------
09:07:01.802 -> [209] Packet sent (->): 7E FF 6 C 0 0 0 FE EF EF -> Cmd: C (Reset), arg: 0
09:07:02.606 -> [1014] Packet received (<-): 7E FF 6 3F 0 0 2 FE BA EF <- Msg: 3F (StorageDevices), arg: 2
09:07:02.640 -> Callback OnCardOnline: 2
09:07:03.934 -> [2330] Packet sent (->): 7E FF 6 7 0 0 0 FE F4 EF -> Cmd: 7 (SetEQ), arg: 0
09:07:04.045 -> [2442] Packet sent (->): 7E FF 6 1A 0 0 0 FE E1 EF -> Cmd: 1A (SetDAC), arg: 0
09:07:04.289 -> [2705] Packet sent (->): 7E FF 6 6 0 0 1 FE F4 EF -> Cmd: 6 (SetVolume), arg: 1
09:07:04.355 -> [2767] Packet sent (->): 7E FF 6 C 0 0 0 FE EF EF -> Cmd: C (Reset), arg: 0
09:07:06.369 -> [4781] Packet sent (->): 7E FF 6 9 0 0 2 FE F0 EF -> Cmd: 9 (SetPlaybackDevice), arg: 2
09:07:08.380 -> [6795] Packet sent (->): 7E FF 6 48 0 0 0 FE B3 EF -> Cmd: 48 (GetNoTracksSD), arg: 0
09:07:08.519 -> [6905] Packet received (<-): 7E FF 6 48 0 0 3 FE B0 EF <- Msg: 48 (GetNoTracksSD), arg: 3
09:07:08.519 -> [6919] Packet sent (->): 7E FF 6 9 0 0 1 FE F1 EF -> Cmd: 9 (SetPlaybackDevice), arg: 1
09:07:10.540 -> [8935] Packet sent (->): 7E FF 6 47 0 0 0 FE B4 EF -> Cmd: 47 (GetNoTracksUSB), arg: 0
09:07:10.650 -> [9046] Packet received (<-): 7E FF 6 47 0 0 0 FE B4 EF <- Msg: 47 (GetNoTracksUSB), arg: 0
09:07:11.669 -> [10059] Packet sent (->): 7E FF 6 9 0 0 5 FE ED EF -> Cmd: 9 (SetPlaybackDevice), arg: 5
09:07:13.680 -> [12072] Packet sent (->): 7E FF 6 9 0 0 2 FE F0 EF -> Cmd: 9 (SetPlaybackDevice), arg: 2
09:07:13.680 -> -------------------------------------------------------
09:07:13.714 -> Test Case "TestDiscoverDevices" FINISHED
09:07:13.714 -> -------------------------------------------------------
09:07:13.714 ->
09:07:13.714 ->
09:07:13.714 -> -------------------------------------------------------
09:07:13.714 -> Running Test Case "TestReaction3F"
09:07:13.714 -> -------------------------------------------------------
09:07:13.887 -> [12286] Packet sent (->): 7E FF 6 C 0 0 0 FE EF EF -> Cmd: C (Reset), arg: 0
09:07:14.684 -> [13088] Packet received (<-): 7E FF 6 3F 0 0 2 FE BA EF <- Msg: 3F (StorageDevices), arg: 2
09:07:14.720 -> Callback OnCardOnline: 2
09:07:16.037 -> [14404] Packet sent (->): 7E FF 6 7 0 0 0 FE F4 EF -> Cmd: 7 (SetEQ), arg: 0
09:07:16.147 -> [14517] Packet sent (->): 7E FF 6 1A 0 0 0 FE E1 EF -> Cmd: 1A (SetDAC), arg: 0
09:07:16.396 -> [14779] Packet sent (->): 7E FF 6 6 0 0 1 FE F4 EF -> Cmd: 6 (SetVolume), arg: 1
09:07:16.466 -> [14842] Packet sent (->): 7E FF 6 3F 0 0 0 FE BC EF -> Cmd: 3F (StorageDevices), arg: 0
09:07:17.946 -> --------------
09:07:17.946 -> ERROR 129
09:07:17.946 -> --------------
09:07:17.979 -> -------------------------------------------------------
09:07:17.979 -> Test Case "TestReaction3F" FINISHED
09:07:17.979 -> -------------------------------------------------------
09:07:17.979 ->
09:07:17.979 ->
09:07:17.979 -> -------------------------------------------------------
09:07:17.979 -> Running Test Case "TestGetFolderTrackCount"
09:07:18.013 -> -------------------------------------------------------
09:07:18.013 -> [16379] Packet sent (->): 7E FF 6 C 0 0 0 FE EF EF -> Cmd: C (Reset), arg: 0
09:07:18.806 -> [17184] Packet received (<-): 7E FF 6 3F 0 0 2 FE BA EF <- Msg: 3F (StorageDevices), arg: 2
09:07:18.806 -> Callback OnCardOnline: 2
09:07:20.120 -> [18500] Packet sent (->): 7E FF 6 7 0 0 0 FE F4 EF -> Cmd: 7 (SetEQ), arg: 0
09:07:20.220 -> [18613] Packet sent (->): 7E FF 6 1A 0 0 0 FE E1 EF -> Cmd: 1A (SetDAC), arg: 0
09:07:20.501 -> [18875] Packet sent (->): 7E FF 6 6 0 0 1 FE F4 EF -> Cmd: 6 (SetVolume), arg: 1
09:07:20.569 -> [18937] Packet sent (->): 7E FF 6 9 0 0 2 FE F0 EF -> Cmd: 9 (SetPlaybackDevice), arg: 2
09:07:21.564 -> [19951] Packet sent (->): 7E FF 6 4E 0 0 1 FE AC EF -> Cmd: 4E (GetNoTracksFolder), arg: 1
09:07:22.302 -> [20688] Packet received (<-): 7E FF 6 4E 0 0 2 FE AB EF <- Msg: 4E (GetNoTracksFolder), arg: 2
09:07:22.335 -> -------------------------------------------------------
09:07:22.335 -> Test Case "TestGetFolderTrackCount" FINISHED
09:07:22.335 -> -------------------------------------------------------
09:07:22.335 ->
09:07:22.335 ->
09:07:22.335 -> -------------------------------------------------------
09:07:22.335 -> Running Test Case "TestGetCurrentTrack"
09:07:22.335 -> -------------------------------------------------------
09:07:22.368 -> [20728] Packet sent (->): 7E FF 6 C 0 0 0 FE EF EF -> Cmd: C (Reset), arg: 0
09:07:23.175 -> [21533] Packet received (<-): 7E FF 6 3F 0 0 2 FE BA EF <- Msg: 3F (StorageDevices), arg: 2
09:07:23.175 -> Callback OnCardOnline: 2
09:07:24.484 -> [22849] Packet sent (->): 7E FF 6 7 0 0 0 FE F4 EF -> Cmd: 7 (SetEQ), arg: 0
09:07:24.592 -> [22962] Packet sent (->): 7E FF 6 1A 0 0 0 FE E1 EF -> Cmd: 1A (SetDAC), arg: 0
09:07:24.872 -> [23224] Packet sent (->): 7E FF 6 6 0 0 1 FE F4 EF -> Cmd: 6 (SetVolume), arg: 1
09:07:24.906 -> [23286] Packet sent (->): 7E FF 6 9 0 0 2 FE F0 EF -> Cmd: 9 (SetPlaybackDevice), arg: 2
09:07:25.121 -> [23500] Packet sent (->): 7E FF 6 F 0 1 2 FE E9 EF -> Cmd: F (PlayFolderTrack), arg: 258
09:07:27.150 -> [25513] Packet sent (->): 7E FF 6 4C 0 0 0 FE AF EF -> Cmd: 4C (GetCurrentTrackSD), arg: 0
09:07:27.218 -> [25574] Packet received (<-): 7E FF 6 4C 0 0 2 FE AD EF <- Msg: 4C (GetCurrentTrackSD), arg: 2
09:07:27.218 -> [25587] Packet sent (->): 7E FF 6 F 0 1 1 FE EA EF -> Cmd: F (PlayFolderTrack), arg: 257
09:07:27.285 -> [25654] Packet sent (->): 7E FF 6 4C 0 0 0 FE AF EF -> Cmd: 4C (GetCurrentTrackSD), arg: 0
09:07:28.792 -> --------------
09:07:28.792 -> ERROR 129
09:07:28.792 -> --------------
09:07:28.830 -> [27203] Packet sent (->): 7E FF 6 4C 0 0 0 FE AF EF -> Cmd: 4C (GetCurrentTrackSD), arg: 0
09:07:28.902 -> [27258] Packet received (<-): 7E FF 6 4C 0 0 1 FE AE EF <- Msg: 4C (GetCurrentTrackSD), arg: 1
09:07:28.902 -> GetCurrentTrack for SD returns correct value 1600ms after start of track
09:07:28.902 -> [27279] Packet sent (->): 7E FF 6 16 0 0 0 FE E5 EF -> Cmd: 16 (Stop), arg: 0
09:07:28.939 -> -------------------------------------------------------
09:07:28.939 -> Test Case "TestGetCurrentTrack" FINISHED
09:07:28.939 -> -------------------------------------------------------
09:07:28.939 ->
09:07:28.939 ->
09:07:28.939 -> -------------------------------------------------------
09:07:28.939 -> Running Test Case "TestTrackFinishedCallback"
09:07:28.939 -> -------------------------------------------------------
09:07:28.975 -> [27343] Packet sent (->): 7E FF 6 C 0 0 0 FE EF EF -> Cmd: C (Reset), arg: 0
09:07:29.740 -> [28095] Packet received (<-): 7E FF 6 3F 0 0 2 FE BA EF <- Msg: 3F (StorageDevices), arg: 2
09:07:29.740 -> Callback OnCardOnline: 2
09:07:31.091 -> [29461] Packet sent (->): 7E FF 6 7 0 0 0 FE F4 EF -> Cmd: 7 (SetEQ), arg: 0
09:07:31.200 -> [29574] Packet sent (->): 7E FF 6 1A 0 0 0 FE E1 EF -> Cmd: 1A (SetDAC), arg: 0
09:07:31.484 -> [29836] Packet sent (->): 7E FF 6 6 0 0 1 FE F4 EF -> Cmd: 6 (SetVolume), arg: 1
09:07:31.553 -> [29898] Packet sent (->): 7E FF 6 9 0 0 2 FE F0 EF -> Cmd: 9 (SetPlaybackDevice), arg: 2
09:07:32.559 -> [30912] Packet sent (->): 7E FF 6 F 0 1 2 FE E9 EF -> Cmd: F (PlayFolderTrack), arg: 258
09:07:34.551 -> [32926] Packet sent (->): 7E FF 6 4C 0 0 0 FE AF EF -> Cmd: 4C (GetCurrentTrackSD), arg: 0
09:07:34.622 -> [32983] Packet received (<-): 7E FF 6 4C 0 0 2 FE AD EF <- Msg: 4C (GetCurrentTrackSD), arg: 2
09:07:45.488 -> [43838] Packet received (<-): 7E FF 6 3D 0 0 2 FE BC EF <- Msg: 3D (TrackFinishedSD), arg: 2
09:07:45.521 -> Callback global track finished: 2
09:07:47.802 -> Received 1 callback(s) on SD track end
09:07:47.802 -> -------------------------------------------------------
09:07:47.837 -> Test Case "TestTrackFinishedCallback" FINISHED
09:07:47.837 -> -------------------------------------------------------
09:07:47.837 ->
09:07:47.837 ->
09:07:47.837 -> -------------------------------------------------------
09:07:47.837 -> Running Test Case "TestContinuePlaybackAfterAd"
09:07:47.837 -> -------------------------------------------------------
09:07:47.837 -> [46181] Packet sent (->): 7E FF 6 C 0 0 0 FE EF EF -> Cmd: C (Reset), arg: 0
09:07:48.588 -> [46936] Packet received (<-): 7E FF 6 3F 0 0 2 FE BA EF <- Msg: 3F (StorageDevices), arg: 2
09:07:48.622 -> Callback OnCardOnline: 2
09:07:49.963 -> [48302] Packet sent (->): 7E FF 6 7 0 0 0 FE F4 EF -> Cmd: 7 (SetEQ), arg: 0
09:07:50.071 -> [48413] Packet sent (->): 7E FF 6 1A 0 0 0 FE E1 EF -> Cmd: 1A (SetDAC), arg: 0
09:07:50.356 -> [48676] Packet sent (->): 7E FF 6 6 0 0 1 FE F4 EF -> Cmd: 6 (SetVolume), arg: 1
09:07:50.422 -> [48738] Packet sent (->): 7E FF 6 9 0 0 2 FE F0 EF -> Cmd: 9 (SetPlaybackDevice), arg: 2
09:07:51.479 -> [49801] Packet sent (->): 7E FF 6 F 0 1 2 FE E9 EF -> Cmd: F (PlayFolderTrack), arg: 258
09:07:53.504 -> [51815] Packet sent (->): 7E FF 6 4C 0 0 0 FE AF EF -> Cmd: 4C (GetCurrentTrackSD), arg: 0
09:07:53.571 -> [51871] Packet received (<-): 7E FF 6 4C 0 0 2 FE AD EF <- Msg: 4C (GetCurrentTrackSD), arg: 2
09:07:53.571 -> [51885] Packet sent (->): 7E FF 6 13 0 0 64 FE 84 EF -> Cmd: 13 (PlayAd), arg: 100
09:07:54.109 -> [52451] Packet sent (->): 7E FF 6 4C 0 0 0 FE AF EF -> Cmd: 4C (GetCurrentTrackSD), arg: 0
09:07:54.175 -> [52506] Packet received (<-): 7E FF 6 4C 0 0 3 FE AC EF <- Msg: 4C (GetCurrentTrackSD), arg: 3
09:07:54.600 -> [52909] Packet received (<-): 7E FF 6 3D 0 0 3 FE BB EF <- Msg: 3D (TrackFinishedSD), arg: 3
09:07:54.600 -> Callback global track finished: 3
09:07:55.305 -> [53625] Packet sent (->): 7E FF 6 42 0 0 0 FE B9 EF -> Cmd: 42 (GetStatus), arg: 0
09:07:55.372 -> [53682] Packet received (<-): 7E FF 6 42 0 0 1 FE B8 EF <- Msg: 42 (GetStatus), arg: 1
09:08:05.319 -> [63637] Packet received (<-): 7E FF 6 3D 0 0 2 FE BC EF <- Msg: 3D (TrackFinishedSD), arg: 2
09:08:05.353 -> Callback global track finished: 2
09:08:05.424 -> -------------------------------------------------------
09:08:05.424 -> Test Case "TestContinuePlaybackAfterAd" FINISHED
09:08:05.462 -> -------------------------------------------------------
09:08:05.462 ->
09:08:05.462 ->
09:08:05.462 -> -------------------------------------------------------
09:08:05.462 -> Running Test Case "TestWakeupAfterSleep"
09:08:05.462 -> -------------------------------------------------------
09:08:05.462 -> [63775] Packet sent (->): 7E FF 6 C 0 0 0 FE EF EF -> Cmd: C (Reset), arg: 0
09:08:06.215 -> [64530] Packet received (<-): 7E FF 6 3F 0 0 2 FE BA EF <- Msg: 3F (StorageDevices), arg: 2
09:08:06.251 -> Callback OnCardOnline: 2
09:08:07.600 -> [65896] Packet sent (->): 7E FF 6 7 0 0 0 FE F4 EF -> Cmd: 7 (SetEQ), arg: 0
09:08:07.705 -> [66008] Packet sent (->): 7E FF 6 1A 0 0 0 FE E1 EF -> Cmd: 1A (SetDAC), arg: 0
09:08:07.988 -> [66271] Packet sent (->): 7E FF 6 6 0 0 1 FE F4 EF -> Cmd: 6 (SetVolume), arg: 1
09:08:08.022 -> [66334] Packet sent (->): 7E FF 6 9 0 0 2 FE F0 EF -> Cmd: 9 (SetPlaybackDevice), arg: 2
09:08:08.258 -> [66546] Packet sent (->): 7E FF 6 6 0 0 0 FE F5 EF -> Cmd: 6 (SetVolume), arg: 0
09:08:08.325 -> [66609] Packet sent (->): 7E FF 6 16 0 0 0 FE E5 EF -> Cmd: 16 (Stop), arg: 0
09:08:08.504 -> [66822] Packet sent (->): 7E FF 6 1A 0 0 1 FE E0 EF -> Cmd: 1A (SetDAC), arg: 1
09:08:08.739 -> [67034] Packet sent (->): 7E FF 6 A 0 0 0 FE F1 EF -> Cmd: A (Sleep), arg: 0
09:08:10.954 -> [69246] Packet sent (->): 7E FF 6 C 0 0 0 FE EF EF -> Cmd: C (Reset), arg: 0
09:08:13.058 -> [71359] Packet sent (->): 7E FF 6 7 0 0 0 FE F4 EF -> Cmd: 7 (SetEQ), arg: 0
09:08:13.193 -> [71472] Packet sent (->): 7E FF 6 1A 0 0 0 FE E1 EF -> Cmd: 1A (SetDAC), arg: 0
09:08:13.433 -> [71734] Packet sent (->): 7E FF 6 6 0 0 1 FE F4 EF -> Cmd: 6 (SetVolume), arg: 1
09:08:13.504 -> [71796] Packet sent (->): 7E FF 6 9 0 0 2 FE F0 EF -> Cmd: 9 (SetPlaybackDevice), arg: 2
09:08:13.717 -> [72009] Packet sent (->): 7E FF 6 F 0 1 2 FE E9 EF -> Cmd: F (PlayFolderTrack), arg: 258
09:08:15.726 -> [74023] Packet sent (->): 7E FF 6 42 0 0 0 FE B9 EF -> Cmd: 42 (GetStatus), arg: 0
09:08:17.250 -> --------------
09:08:17.250 -> ERROR 129
09:08:17.250 -> --------------
09:08:17.358 -> [75635] Packet sent (->): 7E FF 6 42 0 0 0 FE B9 EF -> Cmd: 42 (GetStatus), arg: 0
09:08:18.839 -> --------------
09:08:18.839 -> ERROR 129
09:08:18.874 -> --------------
09:08:18.978 -> [77248] Packet sent (->): 7E FF 6 42 0 0 0 FE B9 EF -> Cmd: 42 (GetStatus), arg: 0
09:08:20.497 -> --------------
09:08:20.497 -> ERROR 129
09:08:20.497 -> --------------
09:08:20.566 -> [78861] Packet sent (->): 7E FF 6 16 0 0 0 FE E5 EF -> Cmd: 16 (Stop), arg: 0
09:08:20.633 -> [78924] Packet sent (->): 7E FF 6 6 0 0 0 FE F5 EF -> Cmd: 6 (SetVolume), arg: 0
09:08:20.699 -> [78987] Packet sent (->): 7E FF 6 16 0 0 0 FE E5 EF -> Cmd: 16 (Stop), arg: 0
09:08:20.902 -> [79199] Packet sent (->): 7E FF 6 1A 0 0 1 FE E0 EF -> Cmd: 1A (SetDAC), arg: 1
09:08:21.145 -> [79412] Packet sent (->): 7E FF 6 A 0 0 0 FE F1 EF -> Cmd: A (Sleep), arg: 0
09:08:23.355 -> [81624] Packet sent (->): 7E FF 6 9 0 0 2 FE F0 EF -> Cmd: 9 (SetPlaybackDevice), arg: 2
09:08:25.462 -> [83737] Packet sent (->): 7E FF 6 7 0 0 0 FE F4 EF -> Cmd: 7 (SetEQ), arg: 0
09:08:25.570 -> [83850] Packet sent (->): 7E FF 6 1A 0 0 0 FE E1 EF -> Cmd: 1A (SetDAC), arg: 0
09:08:25.850 -> [84112] Packet sent (->): 7E FF 6 6 0 0 1 FE F4 EF -> Cmd: 6 (SetVolume), arg: 1
09:08:25.917 -> [84174] Packet sent (->): 7E FF 6 F 0 1 2 FE E9 EF -> Cmd: F (PlayFolderTrack), arg: 258
09:08:27.903 -> [86189] Packet sent (->): 7E FF 6 42 0 0 0 FE B9 EF -> Cmd: 42 (GetStatus), arg: 0
09:08:29.440 -> --------------
09:08:29.440 -> ERROR 129
09:08:29.440 -> --------------
09:08:29.550 -> [87801] Packet sent (->): 7E FF 6 42 0 0 0 FE B9 EF -> Cmd: 42 (GetStatus), arg: 0
09:08:31.028 -> --------------
09:08:31.028 -> ERROR 129
09:08:31.065 -> --------------
09:08:31.134 -> [89413] Packet sent (->): 7E FF 6 42 0 0 0 FE B9 EF -> Cmd: 42 (GetStatus), arg: 0
09:08:32.650 -> --------------
09:08:32.687 -> ERROR 129
09:08:32.687 -> --------------
09:08:32.757 -> [91026] Packet sent (->): 7E FF 6 16 0 0 0 FE E5 EF -> Cmd: 16 (Stop), arg: 0
09:08:32.757 -> -------------------------------------------------------
09:08:32.792 -> Test Case "TestWakeupAfterSleep" FINISHED
09:08:32.792 -> -------------------------------------------------------
09:08:32.792 ->
09:08:32.792 ->
09:08:32.792 -> -------------------------------------------------------
09:08:32.792 -> Profile of this DFPlayer device
09:08:32.792 -> -------------------------------------------------------
09:08:32.792 -> The following devices have been discovered:
09:08:32.792 -> -> SD, discovered by getTotalTrackCount
09:08:32.792 -> Does NOT react on setPlaysourceFlash
09:08:32.830 -> Does NOT react on 0x3F queries
09:08:32.830 -> getFolderTrackCount...
09:08:32.830 -> -> for SD returned 2 files in Folder /01/
09:08:32.830 -> GetCurrentTrack for SD returns correct value 1600ms after start of track
09:08:32.830 -> Sends 1 callback(s) on SD track end
09:08:32.830 -> Continue playback after Ad for SD works
09:08:32.830 -> Wakeup from sleep by reset does NOT work
09:08:32.830 -> Wakeup from sleep by setting playsource SD does NOT work
09:08:32.830 ->
09:08:32.830 ->
09:08:32.830 -> -------------------------------------------------------------------------------
09:08:32.865 -> ALL TESTS COMPLETED!
09:08:32.865 ->
09:08:32.865 -> Please report your results in an issue at
09:08:32.865 ->
09:08:32.865 -> https://github.com/ghmartin77/DFPlayerAnalyzer/issues
09:08:32.865 ->
09:08:32.865 -> Please state the player's chip name in the title of the issue entry.
09:08:32.865 -> Thanks for your support!
09:08:32.865 -> -------------------------------------------------------------------------------
09:07:01.589 -> DFPlayer Analyzer 1.0 - Starting up... 09:07:01.589 -> 09:07:01.658 -> [60] Packet sent (->): 7E FF 6 42 0 0 0 FE B9 EF -> Cmd: 42 (GetStatus), arg: 0 09:07:01.769 -> [171] Packet received (<-): 7E FF 6 42 0 0 0 FE B9 EF <- Msg: 42 (GetStatus), arg: 0 09:07:01.769 -> ------------------------------------------------------- 09:07:01.769 -> Test Case "TestConnectivity" FINISHED 09:07:01.769 -> ------------------------------------------------------- 09:07:01.769 -> 09:07:01.769 -> 09:07:01.769 -> ------------------------------------------------------- 09:07:01.769 -> Running Test Case "TestDiscoverDevices" 09:07:01.802 -> ------------------------------------------------------- 09:07:01.802 -> [209] Packet sent (->): 7E FF 6 C 0 0 0 FE EF EF -> Cmd: C (Reset), arg: 0 09:07:02.606 -> [1014] Packet received (<-): 7E FF 6 3F 0 0 2 FE BA EF <- Msg: 3F (StorageDevices), arg: 2 09:07:02.640 -> Callback OnCardOnline: 2 09:07:03.934 -> [2330] Packet sent (->): 7E FF 6 7 0 0 0 FE F4 EF -> Cmd: 7 (SetEQ), arg: 0 09:07:04.045 -> [2442] Packet sent (->): 7E FF 6 1A 0 0 0 FE E1 EF -> Cmd: 1A (SetDAC), arg: 0 09:07:04.289 -> [2705] Packet sent (->): 7E FF 6 6 0 0 1 FE F4 EF -> Cmd: 6 (SetVolume), arg: 1 09:07:04.355 -> [2767] Packet sent (->): 7E FF 6 C 0 0 0 FE EF EF -> Cmd: C (Reset), arg: 0 09:07:06.369 -> [4781] Packet sent (->): 7E FF 6 9 0 0 2 FE F0 EF -> Cmd: 9 (SetPlaybackDevice), arg: 2 09:07:08.380 -> [6795] Packet sent (->): 7E FF 6 48 0 0 0 FE B3 EF -> Cmd: 48 (GetNoTracksSD), arg: 0 09:07:08.519 -> [6905] Packet received (<-): 7E FF 6 48 0 0 3 FE B0 EF <- Msg: 48 (GetNoTracksSD), arg: 3 09:07:08.519 -> [6919] Packet sent (->): 7E FF 6 9 0 0 1 FE F1 EF -> Cmd: 9 (SetPlaybackDevice), arg: 1 09:07:10.540 -> [8935] Packet sent (->): 7E FF 6 47 0 0 0 FE B4 EF -> Cmd: 47 (GetNoTracksUSB), arg: 0 09:07:10.650 -> [9046] Packet received (<-): 7E FF 6 47 0 0 0 FE B4 EF <- Msg: 47 (GetNoTracksUSB), arg: 0 09:07:11.669 -> [10059] Packet sent (->): 7E FF 6 9 0 0 5 FE ED EF -> Cmd: 9 (SetPlaybackDevice), arg: 5 09:07:13.680 -> [12072] Packet sent (->): 7E FF 6 9 0 0 2 FE F0 EF -> Cmd: 9 (SetPlaybackDevice), arg: 2 09:07:13.680 -> ------------------------------------------------------- 09:07:13.714 -> Test Case "TestDiscoverDevices" FINISHED 09:07:13.714 -> ------------------------------------------------------- 09:07:13.714 -> 09:07:13.714 -> 09:07:13.714 -> ------------------------------------------------------- 09:07:13.714 -> Running Test Case "TestReaction3F" 09:07:13.714 -> ------------------------------------------------------- 09:07:13.887 -> [12286] Packet sent (->): 7E FF 6 C 0 0 0 FE EF EF -> Cmd: C (Reset), arg: 0 09:07:14.684 -> [13088] Packet received (<-): 7E FF 6 3F 0 0 2 FE BA EF <- Msg: 3F (StorageDevices), arg: 2 09:07:14.720 -> Callback OnCardOnline: 2 09:07:16.037 -> [14404] Packet sent (->): 7E FF 6 7 0 0 0 FE F4 EF -> Cmd: 7 (SetEQ), arg: 0 09:07:16.147 -> [14517] Packet sent (->): 7E FF 6 1A 0 0 0 FE E1 EF -> Cmd: 1A (SetDAC), arg: 0 09:07:16.396 -> [14779] Packet sent (->): 7E FF 6 6 0 0 1 FE F4 EF -> Cmd: 6 (SetVolume), arg: 1 09:07:16.466 -> [14842] Packet sent (->): 7E FF 6 3F 0 0 0 FE BC EF -> Cmd: 3F (StorageDevices), arg: 0 09:07:17.946 -> -------------- 09:07:17.946 -> ERROR 129 09:07:17.946 -> -------------- 09:07:17.979 -> ------------------------------------------------------- 09:07:17.979 -> Test Case "TestReaction3F" FINISHED 09:07:17.979 -> ------------------------------------------------------- 09:07:17.979 -> 09:07:17.979 -> 09:07:17.979 -> ------------------------------------------------------- 09:07:17.979 -> Running Test Case "TestGetFolderTrackCount" 09:07:18.013 -> ------------------------------------------------------- 09:07:18.013 -> [16379] Packet sent (->): 7E FF 6 C 0 0 0 FE EF EF -> Cmd: C (Reset), arg: 0 09:07:18.806 -> [17184] Packet received (<-): 7E FF 6 3F 0 0 2 FE BA EF <- Msg: 3F (StorageDevices), arg: 2 09:07:18.806 -> Callback OnCardOnline: 2 09:07:20.120 -> [18500] Packet sent (->): 7E FF 6 7 0 0 0 FE F4 EF -> Cmd: 7 (SetEQ), arg: 0 09:07:20.220 -> [18613] Packet sent (->): 7E FF 6 1A 0 0 0 FE E1 EF -> Cmd: 1A (SetDAC), arg: 0 09:07:20.501 -> [18875] Packet sent (->): 7E FF 6 6 0 0 1 FE F4 EF -> Cmd: 6 (SetVolume), arg: 1 09:07:20.569 -> [18937] Packet sent (->): 7E FF 6 9 0 0 2 FE F0 EF -> Cmd: 9 (SetPlaybackDevice), arg: 2 09:07:21.564 -> [19951] Packet sent (->): 7E FF 6 4E 0 0 1 FE AC EF -> Cmd: 4E (GetNoTracksFolder), arg: 1 09:07:22.302 -> [20688] Packet received (<-): 7E FF 6 4E 0 0 2 FE AB EF <- Msg: 4E (GetNoTracksFolder), arg: 2 09:07:22.335 -> ------------------------------------------------------- 09:07:22.335 -> Test Case "TestGetFolderTrackCount" FINISHED 09:07:22.335 -> ------------------------------------------------------- 09:07:22.335 -> 09:07:22.335 -> 09:07:22.335 -> ------------------------------------------------------- 09:07:22.335 -> Running Test Case "TestGetCurrentTrack" 09:07:22.335 -> ------------------------------------------------------- 09:07:22.368 -> [20728] Packet sent (->): 7E FF 6 C 0 0 0 FE EF EF -> Cmd: C (Reset), arg: 0 09:07:23.175 -> [21533] Packet received (<-): 7E FF 6 3F 0 0 2 FE BA EF <- Msg: 3F (StorageDevices), arg: 2 09:07:23.175 -> Callback OnCardOnline: 2 09:07:24.484 -> [22849] Packet sent (->): 7E FF 6 7 0 0 0 FE F4 EF -> Cmd: 7 (SetEQ), arg: 0 09:07:24.592 -> [22962] Packet sent (->): 7E FF 6 1A 0 0 0 FE E1 EF -> Cmd: 1A (SetDAC), arg: 0 09:07:24.872 -> [23224] Packet sent (->): 7E FF 6 6 0 0 1 FE F4 EF -> Cmd: 6 (SetVolume), arg: 1 09:07:24.906 -> [23286] Packet sent (->): 7E FF 6 9 0 0 2 FE F0 EF -> Cmd: 9 (SetPlaybackDevice), arg: 2 09:07:25.121 -> [23500] Packet sent (->): 7E FF 6 F 0 1 2 FE E9 EF -> Cmd: F (PlayFolderTrack), arg: 258 09:07:27.150 -> [25513] Packet sent (->): 7E FF 6 4C 0 0 0 FE AF EF -> Cmd: 4C (GetCurrentTrackSD), arg: 0 09:07:27.218 -> [25574] Packet received (<-): 7E FF 6 4C 0 0 2 FE AD EF <- Msg: 4C (GetCurrentTrackSD), arg: 2 09:07:27.218 -> [25587] Packet sent (->): 7E FF 6 F 0 1 1 FE EA EF -> Cmd: F (PlayFolderTrack), arg: 257 09:07:27.285 -> [25654] Packet sent (->): 7E FF 6 4C 0 0 0 FE AF EF -> Cmd: 4C (GetCurrentTrackSD), arg: 0 09:07:28.792 -> -------------- 09:07:28.792 -> ERROR 129 09:07:28.792 -> -------------- 09:07:28.830 -> [27203] Packet sent (->): 7E FF 6 4C 0 0 0 FE AF EF -> Cmd: 4C (GetCurrentTrackSD), arg: 0 09:07:28.902 -> [27258] Packet received (<-): 7E FF 6 4C 0 0 1 FE AE EF <- Msg: 4C (GetCurrentTrackSD), arg: 1 09:07:28.902 -> GetCurrentTrack for SD returns correct value 1600ms after start of track 09:07:28.902 -> [27279] Packet sent (->): 7E FF 6 16 0 0 0 FE E5 EF -> Cmd: 16 (Stop), arg: 0 09:07:28.939 -> ------------------------------------------------------- 09:07:28.939 -> Test Case "TestGetCurrentTrack" FINISHED 09:07:28.939 -> ------------------------------------------------------- 09:07:28.939 -> 09:07:28.939 -> 09:07:28.939 -> ------------------------------------------------------- 09:07:28.939 -> Running Test Case "TestTrackFinishedCallback" 09:07:28.939 -> ------------------------------------------------------- 09:07:28.975 -> [27343] Packet sent (->): 7E FF 6 C 0 0 0 FE EF EF -> Cmd: C (Reset), arg: 0 09:07:29.740 -> [28095] Packet received (<-): 7E FF 6 3F 0 0 2 FE BA EF <- Msg: 3F (StorageDevices), arg: 2 09:07:29.740 -> Callback OnCardOnline: 2 09:07:31.091 -> [29461] Packet sent (->): 7E FF 6 7 0 0 0 FE F4 EF -> Cmd: 7 (SetEQ), arg: 0 09:07:31.200 -> [29574] Packet sent (->): 7E FF 6 1A 0 0 0 FE E1 EF -> Cmd: 1A (SetDAC), arg: 0 09:07:31.484 -> [29836] Packet sent (->): 7E FF 6 6 0 0 1 FE F4 EF -> Cmd: 6 (SetVolume), arg: 1 09:07:31.553 -> [29898] Packet sent (->): 7E FF 6 9 0 0 2 FE F0 EF -> Cmd: 9 (SetPlaybackDevice), arg: 2 09:07:32.559 -> [30912] Packet sent (->): 7E FF 6 F 0 1 2 FE E9 EF -> Cmd: F (PlayFolderTrack), arg: 258 09:07:34.551 -> [32926] Packet sent (->): 7E FF 6 4C 0 0 0 FE AF EF -> Cmd: 4C (GetCurrentTrackSD), arg: 0 09:07:34.622 -> [32983] Packet received (<-): 7E FF 6 4C 0 0 2 FE AD EF <- Msg: 4C (GetCurrentTrackSD), arg: 2 09:07:45.488 -> [43838] Packet received (<-): 7E FF 6 3D 0 0 2 FE BC EF <- Msg: 3D (TrackFinishedSD), arg: 2 09:07:45.521 -> Callback global track finished: 2 09:07:47.802 -> Received 1 callback(s) on SD track end 09:07:47.802 -> ------------------------------------------------------- 09:07:47.837 -> Test Case "TestTrackFinishedCallback" FINISHED 09:07:47.837 -> ------------------------------------------------------- 09:07:47.837 -> 09:07:47.837 -> 09:07:47.837 -> ------------------------------------------------------- 09:07:47.837 -> Running Test Case "TestContinuePlaybackAfterAd" 09:07:47.837 -> ------------------------------------------------------- 09:07:47.837 -> [46181] Packet sent (->): 7E FF 6 C 0 0 0 FE EF EF -> Cmd: C (Reset), arg: 0 09:07:48.588 -> [46936] Packet received (<-): 7E FF 6 3F 0 0 2 FE BA EF <- Msg: 3F (StorageDevices), arg: 2 09:07:48.622 -> Callback OnCardOnline: 2 09:07:49.963 -> [48302] Packet sent (->): 7E FF 6 7 0 0 0 FE F4 EF -> Cmd: 7 (SetEQ), arg: 0 09:07:50.071 -> [48413] Packet sent (->): 7E FF 6 1A 0 0 0 FE E1 EF -> Cmd: 1A (SetDAC), arg: 0 09:07:50.356 -> [48676] Packet sent (->): 7E FF 6 6 0 0 1 FE F4 EF -> Cmd: 6 (SetVolume), arg: 1 09:07:50.422 -> [48738] Packet sent (->): 7E FF 6 9 0 0 2 FE F0 EF -> Cmd: 9 (SetPlaybackDevice), arg: 2 09:07:51.479 -> [49801] Packet sent (->): 7E FF 6 F 0 1 2 FE E9 EF -> Cmd: F (PlayFolderTrack), arg: 258 09:07:53.504 -> [51815] Packet sent (->): 7E FF 6 4C 0 0 0 FE AF EF -> Cmd: 4C (GetCurrentTrackSD), arg: 0 09:07:53.571 -> [51871] Packet received (<-): 7E FF 6 4C 0 0 2 FE AD EF <- Msg: 4C (GetCurrentTrackSD), arg: 2 09:07:53.571 -> [51885] Packet sent (->): 7E FF 6 13 0 0 64 FE 84 EF -> Cmd: 13 (PlayAd), arg: 100 09:07:54.109 -> [52451] Packet sent (->): 7E FF 6 4C 0 0 0 FE AF EF -> Cmd: 4C (GetCurrentTrackSD), arg: 0 09:07:54.175 -> [52506] Packet received (<-): 7E FF 6 4C 0 0 3 FE AC EF <- Msg: 4C (GetCurrentTrackSD), arg: 3 09:07:54.600 -> [52909] Packet received (<-): 7E FF 6 3D 0 0 3 FE BB EF <- Msg: 3D (TrackFinishedSD), arg: 3 09:07:54.600 -> Callback global track finished: 3 09:07:55.305 -> [53625] Packet sent (->): 7E FF 6 42 0 0 0 FE B9 EF -> Cmd: 42 (GetStatus), arg: 0 09:07:55.372 -> [53682] Packet received (<-): 7E FF 6 42 0 0 1 FE B8 EF <- Msg: 42 (GetStatus), arg: 1 09:08:05.319 -> [63637] Packet received (<-): 7E FF 6 3D 0 0 2 FE BC EF <- Msg: 3D (TrackFinishedSD), arg: 2 09:08:05.353 -> Callback global track finished: 2 09:08:05.424 -> ------------------------------------------------------- 09:08:05.424 -> Test Case "TestContinuePlaybackAfterAd" FINISHED 09:08:05.462 -> ------------------------------------------------------- 09:08:05.462 -> 09:08:05.462 -> 09:08:05.462 -> ------------------------------------------------------- 09:08:05.462 -> Running Test Case "TestWakeupAfterSleep" 09:08:05.462 -> ------------------------------------------------------- 09:08:05.462 -> [63775] Packet sent (->): 7E FF 6 C 0 0 0 FE EF EF -> Cmd: C (Reset), arg: 0 09:08:06.215 -> [64530] Packet received (<-): 7E FF 6 3F 0 0 2 FE BA EF <- Msg: 3F (StorageDevices), arg: 2 09:08:06.251 -> Callback OnCardOnline: 2 09:08:07.600 -> [65896] Packet sent (->): 7E FF 6 7 0 0 0 FE F4 EF -> Cmd: 7 (SetEQ), arg: 0 09:08:07.705 -> [66008] Packet sent (->): 7E FF 6 1A 0 0 0 FE E1 EF -> Cmd: 1A (SetDAC), arg: 0 09:08:07.988 -> [66271] Packet sent (->): 7E FF 6 6 0 0 1 FE F4 EF -> Cmd: 6 (SetVolume), arg: 1 09:08:08.022 -> [66334] Packet sent (->): 7E FF 6 9 0 0 2 FE F0 EF -> Cmd: 9 (SetPlaybackDevice), arg: 2 09:08:08.258 -> [66546] Packet sent (->): 7E FF 6 6 0 0 0 FE F5 EF -> Cmd: 6 (SetVolume), arg: 0 09:08:08.325 -> [66609] Packet sent (->): 7E FF 6 16 0 0 0 FE E5 EF -> Cmd: 16 (Stop), arg: 0 09:08:08.504 -> [66822] Packet sent (->): 7E FF 6 1A 0 0 1 FE E0 EF -> Cmd: 1A (SetDAC), arg: 1 09:08:08.739 -> [67034] Packet sent (->): 7E FF 6 A 0 0 0 FE F1 EF -> Cmd: A (Sleep), arg: 0 09:08:10.954 -> [69246] Packet sent (->): 7E FF 6 C 0 0 0 FE EF EF -> Cmd: C (Reset), arg: 0 09:08:13.058 -> [71359] Packet sent (->): 7E FF 6 7 0 0 0 FE F4 EF -> Cmd: 7 (SetEQ), arg: 0 09:08:13.193 -> [71472] Packet sent (->): 7E FF 6 1A 0 0 0 FE E1 EF -> Cmd: 1A (SetDAC), arg: 0 09:08:13.433 -> [71734] Packet sent (->): 7E FF 6 6 0 0 1 FE F4 EF -> Cmd: 6 (SetVolume), arg: 1 09:08:13.504 -> [71796] Packet sent (->): 7E FF 6 9 0 0 2 FE F0 EF -> Cmd: 9 (SetPlaybackDevice), arg: 2 09:08:13.717 -> [72009] Packet sent (->): 7E FF 6 F 0 1 2 FE E9 EF -> Cmd: F (PlayFolderTrack), arg: 258 09:08:15.726 -> [74023] Packet sent (->): 7E FF 6 42 0 0 0 FE B9 EF -> Cmd: 42 (GetStatus), arg: 0 09:08:17.250 -> -------------- 09:08:17.250 -> ERROR 129 09:08:17.250 -> -------------- 09:08:17.358 -> [75635] Packet sent (->): 7E FF 6 42 0 0 0 FE B9 EF -> Cmd: 42 (GetStatus), arg: 0 09:08:18.839 -> -------------- 09:08:18.839 -> ERROR 129 09:08:18.874 -> -------------- 09:08:18.978 -> [77248] Packet sent (->): 7E FF 6 42 0 0 0 FE B9 EF -> Cmd: 42 (GetStatus), arg: 0 09:08:20.497 -> -------------- 09:08:20.497 -> ERROR 129 09:08:20.497 -> -------------- 09:08:20.566 -> [78861] Packet sent (->): 7E FF 6 16 0 0 0 FE E5 EF -> Cmd: 16 (Stop), arg: 0 09:08:20.633 -> [78924] Packet sent (->): 7E FF 6 6 0 0 0 FE F5 EF -> Cmd: 6 (SetVolume), arg: 0 09:08:20.699 -> [78987] Packet sent (->): 7E FF 6 16 0 0 0 FE E5 EF -> Cmd: 16 (Stop), arg: 0 09:08:20.902 -> [79199] Packet sent (->): 7E FF 6 1A 0 0 1 FE E0 EF -> Cmd: 1A (SetDAC), arg: 1 09:08:21.145 -> [79412] Packet sent (->): 7E FF 6 A 0 0 0 FE F1 EF -> Cmd: A (Sleep), arg: 0 09:08:23.355 -> [81624] Packet sent (->): 7E FF 6 9 0 0 2 FE F0 EF -> Cmd: 9 (SetPlaybackDevice), arg: 2 09:08:25.462 -> [83737] Packet sent (->): 7E FF 6 7 0 0 0 FE F4 EF -> Cmd: 7 (SetEQ), arg: 0 09:08:25.570 -> [83850] Packet sent (->): 7E FF 6 1A 0 0 0 FE E1 EF -> Cmd: 1A (SetDAC), arg: 0 09:08:25.850 -> [84112] Packet sent (->): 7E FF 6 6 0 0 1 FE F4 EF -> Cmd: 6 (SetVolume), arg: 1 09:08:25.917 -> [84174] Packet sent (->): 7E FF 6 F 0 1 2 FE E9 EF -> Cmd: F (PlayFolderTrack), arg: 258 09:08:27.903 -> [86189] Packet sent (->): 7E FF 6 42 0 0 0 FE B9 EF -> Cmd: 42 (GetStatus), arg: 0 09:08:29.440 -> -------------- 09:08:29.440 -> ERROR 129 09:08:29.440 -> -------------- 09:08:29.550 -> [87801] Packet sent (->): 7E FF 6 42 0 0 0 FE B9 EF -> Cmd: 42 (GetStatus), arg: 0 09:08:31.028 -> -------------- 09:08:31.028 -> ERROR 129 09:08:31.065 -> -------------- 09:08:31.134 -> [89413] Packet sent (->): 7E FF 6 42 0 0 0 FE B9 EF -> Cmd: 42 (GetStatus), arg: 0 09:08:32.650 -> -------------- 09:08:32.687 -> ERROR 129 09:08:32.687 -> -------------- 09:08:32.757 -> [91026] Packet sent (->): 7E FF 6 16 0 0 0 FE E5 EF -> Cmd: 16 (Stop), arg: 0 09:08:32.757 -> ------------------------------------------------------- 09:08:32.792 -> Test Case "TestWakeupAfterSleep" FINISHED 09:08:32.792 -> ------------------------------------------------------- 09:08:32.792 -> 09:08:32.792 -> 09:08:32.792 -> ------------------------------------------------------- 09:08:32.792 -> Profile of this DFPlayer device 09:08:32.792 -> ------------------------------------------------------- 09:08:32.792 -> The following devices have been discovered: 09:08:32.792 -> -> SD, discovered by getTotalTrackCount 09:08:32.792 -> Does NOT react on setPlaysourceFlash 09:08:32.830 -> Does NOT react on 0x3F queries 09:08:32.830 -> getFolderTrackCount... 09:08:32.830 -> -> for SD returned 2 files in Folder /01/ 09:08:32.830 -> GetCurrentTrack for SD returns correct value 1600ms after start of track 09:08:32.830 -> Sends 1 callback(s) on SD track end 09:08:32.830 -> Continue playback after Ad for SD works 09:08:32.830 -> Wakeup from sleep by reset does NOT work 09:08:32.830 -> Wakeup from sleep by setting playsource SD does NOT work 09:08:32.830 -> 09:08:32.830 -> 09:08:32.830 -> ------------------------------------------------------------------------------- 09:08:32.865 -> ALL TESTS COMPLETED! 09:08:32.865 -> 09:08:32.865 -> Please report your results in an issue at 09:08:32.865 -> 09:08:32.865 -> https://github.com/ghmartin77/DFPlayerAnalyzer/issues 09:08:32.865 -> 09:08:32.865 -> Please state the player's chip name in the title of the issue entry. 09:08:32.865 -> Thanks for your support! 09:08:32.865 -> -------------------------------------------------------------------------------