4.0.7.1510 Android MTP regression [#9959]

[mgillespie]

It doesn't detect my MTP Android device. Reverting to 4.0.6 works, going back to 4.0.7, it's broken again.

Seems bizzare situation that you care more for your iPhone support than anything else.

[Ludek]

Sure, we tested it, but we haven't noticed any issue like this.

I also cannot replicate the issue with my MTP device (Samsung Galaxy Nexus), the device is recognized just fine with 4.0.7.1510 even if I delete the device profile or with fresh install.

What is your MTP device?
Could you generate debug log?

- Download the DbgView application from: http://technet.microsoft.com/en-us/sysi ... s/bb896647
- Plug in your device
- Execute DbgView.
- Execute MediaMonkey.exe (from build 1510_Debug)
- You'll see logs being generated in DbgView, attach it here.

Thank you!

[rusty]

I haven't seen any problems either (tested on Nexus 4 MTP, and Xperio Pro in USB MSC mode). Anyone else seeing a regression?

If yes, PLEASE give us details, and send a debug log as requested by Ludek above.

Thanks.

-Rusty

[Peke]

Hi, Can you please test on more thing.

Can you please confirm that if you connect devices before you start MM and see if MM detect device on startup?

[mgillespie]

Attaching the device (Xperia S) before starring MediaMonkey makes no difference.

Log below:
(I left it until after it did the startup media scan, then disconnected the device and reconnected)

Code: Select all

00000001	0.00000000	[1624] WMDM: Device change message	
00000002	0.00006154	[1624] WMDM: Queueing action id:0	
00000003	0.00010215	[1624] Media: Returned value: 1	
00000004	0.00020102	[1624] WMDM: Starting action id: 0	
00000005	0.00025846	[1624] WMDM: Looking for device changes...	
00000006	0.00033148	[1624] Winamp Playlist sim - message 537 left to the default processing	
00000007	0.00040697	[1624] Winamp simulation window received messsage 537	
00000008	0.00045333	[1624] Winamp sim - message left to the default processing	
00000009	0.00049927	[1624] Winamp sim - message processing finished	
00000010	0.00064245	[1624] WMDM: Is Inserted: , \\?\usb#vid_0fce&pid_5169&mi_00#6&57afabd&0&0000#{f33fdc04-d1ac-4e8e-9a30-19bbd4b108ae}$0!USB@\\?\usb#vid_0fce&pid_5169&mi_00#6&57afabd&0&0000#{f33fdc04-d1ac-4e8e-9a30-19bbd4b108ae}$0, \\?\usb#vid_0fce&pid_5169&mi_00#6&57afabd&0&0000#{f33fdc04-d1ac-4e8e-9a30-19bbd4b108ae}$0	
00000011	0.00067445	[1624] WMDM: Empty device caption, ignore this device.	
00000012	0.00071958	[1624] WMDM: Finished action id: 0	
00000013	0.01669184	[1624] WMDM: Device change notification	
00000014	0.01673573	[1624] WMDM: Queueing action id:0	
00000015	0.01681614	[1624] WMDM: Starting action id: 0	
00000016	0.01684117	[1624] WMDM: Looking for device changes...	
00000017	0.01770146	[1624] WMDM: Finished action id: 0	
00000018	8.01369953	[1624] WMDM: Device change message	
00000019	8.01376820	[1624] WMDM: Queueing action id:0	
00000020	8.01378727	[1624] Media: Returned value: 1	
00000021	8.01388264	[1624] WMDM: Starting action id: 0	
00000022	8.01392841	[1624] Winamp Playlist sim - message 537 left to the default processing	
00000023	8.01396751	[1624] WMDM: Looking for device changes...	
00000024	8.01402760	[1624] Winamp simulation window received messsage 537	
00000025	8.01404667	[1624] Winamp sim - message left to the default processing	
00000026	8.01407528	[1624] WMDM: Finished action id: 0	
00000027	8.01411915	[1624] Winamp sim - message processing finished	
00000028	8.02498055	[1624] WMDM: Device change message	
00000029	8.02511024	[1624] WMDM: Queueing action id:0	
00000030	8.02518272	[1624] Media: Returned value: 1	
00000031	8.02522087	[1624] WMDM: Starting action id: 0	
00000032	8.02529526	[1624] Winamp Playlist sim - message 537 left to the default processing	
00000033	8.02533722	[1624] WMDM: Looking for device changes...	
00000034	8.02537346	[1624] Winamp simulation window received messsage 537	
00000035	8.02541447	[1624] WMDM: Finished action id: 0	
00000036	8.02543831	[1624] Winamp sim - message left to the default processing	
00000037	8.02548122	[1624] Winamp sim - message processing finished	
00000038	8.10839748	[1624] WMDM: Device change message	
00000039	8.10851574	[1624] WMDM: Queueing action id:0	
00000040	8.10857582	[1624] Media: Returned value: 1	
00000041	8.10861111	[1624] WMDM: Starting action id: 0	
00000042	8.10867023	[1624] WMDM: Looking for device changes...	
00000043	8.10873604	[1624] WMDM: Finished action id: 0	
00000044	8.10878658	[1624] Winamp Playlist sim - message 537 left to the default processing	
00000045	8.10882950	[1624] Winamp simulation window received messsage 537	
00000046	8.10886860	[1624] Winamp sim - message left to the default processing	
00000047	8.10891438	[1624] Winamp sim - message processing finished	
00000048	8.39093876	[1624] WMDM: Device change notification	
00000049	8.39096928	[1624] WMDM: Queueing action id:0	
00000050	8.39105701	[1624] WMDM: Starting action id: 0	
00000051	8.39107513	[1624] WMDM: Looking for device changes...	
00000052	8.39568901	[1624] WMDM: Is Inserted: Xperia S, 0:CB511WKRXX!USB@\\?\USB#VID_0FCE&PID_5169&MI_00#6&57afabd&0&0000#{f33fdc04-d1ac-4e8e-9a30-19bbd4b108ae}$0, \\?\USB#VID_0FCE&PID_5169&MI_00#6&57afabd&0&0000#{f33fdc04-d1ac-4e8e-9a30-19bbd4b108ae}$0	
00000053	8.40788841	[1624] WMDM: Device change message	
00000054	8.40797615	[1624] WMDM: Queueing action id:0	
00000055	8.40807819	[1624] Media: Returned value: 1	
00000056	8.40819836	[1624] Winamp Playlist sim - message 537 left to the default processing	
00000057	8.40825939	[1624] Winamp simulation window received messsage 537	
00000058	8.40830421	[1624] Winamp sim - message left to the default processing	
00000059	8.40838051	[1624] Winamp sim - message processing finished	
00000060	8.41727352	[1624] WMDM: Device change message	
00000061	8.41735363	[1624] Media: Returned value: 1	
00000062	8.41749477	[1624] Winamp Playlist sim - message 537 left to the default processing	
00000063	8.41757488	[1624] Winamp simulation window received messsage 537	
00000064	8.41761398	[1624] Winamp sim - message left to the default processing	
00000065	8.41767406	[1624] Winamp sim - message processing finished	
00000066	8.43695068	[1624] Device scan found (PossDev): USB\VID_0FCE&PID_5169&MI_00\6&57AFABD&0&0000, Inst: 6524, Drive: -1	
00000067	8.45388603	[1624] WMDM: Looking for profile for USB\VID_0FCE&PID_5169&MI_00\6&57AFABD&0&0000, drive: 	
00000068	8.45394135	[1624] WMDM: It's a new device - adding	
00000069	8.49184036	[1624] Device scan found (PossDev): USB\VID_0FCE&PID_5169&MI_00\6&57AFABD&0&0000, Inst: 6524, Drive: -1	
00000070	8.55009079	[1624] Device scan found (PossDev): USB\VID_0FCE&PID_5169&MI_00\6&57AFABD&0&0000, Inst: 6524, Drive: -1	
00000071	8.69021988	[1624] Device scan found (PossDev): USB\VID_0FCE&PID_5169&MI_00\6&57AFABD&0&0000, Inst: 6524, Drive: -1	
00000072	8.82555771	[1624] WMDM: Querying device capabilities.	
00000073	8.86674500	[1624] -------------------------------------------------------- 	
00000074	8.86674500	[1624] New exception: 	
00000075	8.86674500	[1624] Exception code: 3221225477 	
00000076	8.86674500	[1624] Exception flags: 2 	
00000077	8.86674500	[1624] Number of parameters: 2 	
00000078	8.86779213	[1624] -------------------------------------------------------- 	
00000079	8.86779213	[1624] New exception: 	
00000080	8.86779213	[1624] Exception code: 2460516 	
00000081	8.86779213	[1624] Exception flags: 1677721600 	
00000082	8.86779213	[1624] Number of parameters: 1360477 	
00000083	8.86808681	[1624] WMDM: Exception on action handling: Access violation at address 0D5E730F in module 'd_WMDM.dll'. Read of address 00000011	
00000084	8.86812687	[1624] WMDM: Finished action id: 0	
00000085	8.86816502	[1624] WMDM: Starting action id: 0	
00000086	8.86820030	[1624] WMDM: Looking for device changes...	
00000087	8.87253761	[1624] WMDM: Is Inserted: Xperia S, 0:CB511WKRXX!USB@\\?\USB#VID_0FCE&PID_5169&MI_00#6&57afabd&0&0000#{f33fdc04-d1ac-4e8e-9a30-19bbd4b108ae}$0, \\?\USB#VID_0FCE&PID_5169&MI_00#6&57afabd&0&0000#{f33fdc04-d1ac-4e8e-9a30-19bbd4b108ae}$0	
00000088	8.91120720	[1624] Device scan found (PossDev): USB\VID_0FCE&PID_5169&MI_00\6&57AFABD&0&0000, Inst: 6524, Drive: -1	
00000089	8.92927170	[1624] WMDM: Looking for profile for USB\VID_0FCE&PID_5169&MI_00\6&57AFABD&0&0000, drive: 	
00000090	8.92934322	[1624] WMDM: It's a new device - adding	
00000091	8.96622372	[1624] Device scan found (PossDev): USB\VID_0FCE&PID_5169&MI_00\6&57AFABD&0&0000, Inst: 6524, Drive: -1	
00000092	9.01723957	[1624] Device scan found (PossDev): USB\VID_0FCE&PID_5169&MI_00\6&57AFABD&0&0000, Inst: 6524, Drive: -1	
00000093	9.06265736	[1624] Device scan found (PossDev): USB\VID_0FCE&PID_5169&MI_00\6&57AFABD&0&0000, Inst: 6524, Drive: -1	
00000094	9.07348633	[1624] WMDM: Querying device capabilities.	
00000095	9.11680031	[1624] -------------------------------------------------------- 	
00000096	9.11680031	[1624] New exception: 	
00000097	9.11680031	[1624] Exception code: 3221225477 	
00000098	9.11680031	[1624] Exception flags: 2 	
00000099	9.11680031	[1624] Number of parameters: 2 	
00000100	9.34063625	[1624] -------------------------------------------------------- 	
00000101	9.34063625	[1624] New exception: 	
00000102	9.34063625	[1624] Exception code: 2460516 	
00000103	9.34063625	[1624] Exception flags: 1677721600 	
00000104	9.34063625	[1624] Number of parameters: 1360477 	
00000105	9.41800880	[1624] WMDM: Exception on action handling: Access violation at address 0D5E730F in module 'd_WMDM.dll'. Read of address 00000011	
00000106	9.41805363	[1624] WMDM: Finished action id: 0	
00000107	11.56916523	[1624] TGenThread.Create: ThreadName: UpdateDriveLetters_Var , Running Threads: Internal: 0, All: 29	
00000108	11.57247734	[1624] Thread 'UpdateDriveLetters_Var' has started 4496	
00000109	11.57253361	[1624] Thread registered, going to initialize 4496	
00000110	11.57257366	[1624] Thread initialized 4496	
00000111	11.58000565	[1624] CDPlayer.ini location: C:\Users\Mark\AppData\Roaming\MediaMonkey\CDPlayer.ini	
00000112	11.58028889	[1624] Going 'UpdateDriveLetters_Var'to make final callback 4496	
00000113	11.58035946	[1624] Thread 'UpdateDriveLetters_Var'has finished 4496	
00000114	41.58427429	[1624] TGenThread.Create: ThreadName: UpdateDriveLetters_Var , Running Threads: Internal: 0, All: 27	
00000115	41.58762360	[1624] Thread 'UpdateDriveLetters_Var' has started 1456	
00000116	41.58767319	[1624] Thread registered, going to initialize 1456	
00000117	41.58770752	[1624] Thread initialized 1456	
00000118	41.59469986	[1624] CDPlayer.ini location: C:\Users\Mark\AppData\Roaming\MediaMonkey\CDPlayer.ini	
00000119	41.59497070	[1624] Going 'UpdateDriveLetters_Var'to make final callback 1456	
00000120	41.59503937	[1624] Thread 'UpdateDriveLetters_Var'has finished 1456	
00000121	71.59758759	[1624] TGenThread.Create: ThreadName: UpdateDriveLetters_Var , Running Threads: Internal: 0, All: 27	
00000122	71.60073853	[1624] Thread 'UpdateDriveLetters_Var' has started 4776	
00000123	71.60077667	[1624] Thread registered, going to initialize 4776	
00000124	71.60080719	[1624] Thread initialized 4776	
00000125	71.60836792	[1624] CDPlayer.ini location: C:\Users\Mark\AppData\Roaming\MediaMonkey\CDPlayer.ini	
00000126	71.60863495	[1624] Going 'UpdateDriveLetters_Var'to make final callback 4776	
00000127	71.60868835	[1624] Thread 'UpdateDriveLetters_Var'has finished 4776	
00000128	101.61176300	[1624] TGenThread.Create: ThreadName: UpdateDriveLetters_Var , Running Threads: Internal: 0, All: 28	
00000129	101.61579132	[1624] Thread 'UpdateDriveLetters_Var' has started 1868	
00000130	101.61585236	[1624] Thread registered, going to initialize 1868	
00000131	101.61589813	[1624] Thread initialized 1868	
00000132	101.62330627	[1624] CDPlayer.ini location: C:\Users\Mark\AppData\Roaming\MediaMonkey\CDPlayer.ini	
00000133	101.62373352	[1624] Going 'UpdateDriveLetters_Var'to make final callback 1868	
00000134	101.62382507	[1624] Thread 'UpdateDriveLetters_Var'has finished 1868	
00000135	107.33834839	[1624] Forms: Received WM_ACTIVATEAPP	
00000136	107.33837128	[1624] Forms: Accepted WM_ACTIVATEAPP +	
00000137	107.33840942	[1624] Forms: RestoreTopMost: Level: 1	
00000138	107.33844757	[1624] Forms: RestoreTopMost: Starting	
00000139	107.33877563	[1624] Winamp sim - ignoring.	
00000140	107.33881378	[1624] Winamp simulation window received messsage 28	
00000141	107.33884430	[1624] Winamp sim - ignoring.	
00000142	107.33936310	[1624] MainForm: NC Activate +	
00000143	108.96921539	[1624] MainForm: NC Activate -	
00000144	108.96948242	[1624] Forms: Received WM_ACTIVATEAPP	
00000145	108.96951294	[1624] Forms: Accepted WM_ACTIVATEAPP -	
00000146	108.96955109	[1624] Forms: NormalizeTopMosts: Level: 0	
00000147	108.97128296	[1624] Forms: NormalizeTopMost: Starting	
00000148	108.97172546	[1624] Winamp sim - ignoring.	
00000149	108.97176361	[1624] Winamp simulation window received messsage 28	
00000150	108.97180176	[1624] Winamp sim - ignoring.	

[Ludek]

Thank you for the log, it will be fixed as http://www.ventismedia.com/mantis/view.php?id=9959 in the next build

[rusty]

Hi,

Can you give this build a try and let us know if it resolves the issue for you? (if not, another debug log would be helpful)
http://www.mediamonkey.com/sw/MediaMonk ... _Debug.exe

Thanks!

-Russell

[mgillespie]

Can confirm that 1511 works correctly and its no longer a regression,

[rusty]

Thanks!

-Rusty