# # # # # # # sloginfo Time Sev Major Minor Args Jan 01 00:00:02 2 6 0 BT Script load complete Jan 01 00:00:06 2 19 900 devb-umass 1.00A (Jun 20 2012 13:41:11) Jan 01 00:00:06 2 5 0 libcam.so (Jun 20 2012 13:40:11) bver 6050001 Jan 01 00:00:06 1 7 0 pCTRL_DLL_INIT: mcasp_card Jan 01 00:00:06 1 7 0 mcasp_card: Using MCASP0 Jan 01 00:00:06 1 7 0 mcasp_card: clk mode = Master Jan 01 00:00:06 1 7 0 mcasp: protocol set to tdm_i2s Jan 01 00:00:06 1 7 0 mcasp: Invalid rx channels, using rx_voices=2 Jan 01 00:00:06 1 7 0 mcasp_card: Enabling AFIFO Jan 01 00:00:06 1 7 0 pCTRL_DLL_INIT: mcasp_card Jan 01 00:00:06 1 7 0 mcasp_card: Using MCASP1 Jan 01 00:00:06 1 7 0 mcasp_card: clk mode = Slave Jan 01 00:00:06 1 7 0 mcasp: protocol set to tdm_i2s Jan 01 00:00:06 1 7 0 mcasp_card: Enabling AFIFO Jan 01 00:00:06 1 7 0 pCTRL_DLL_INIT: mcasp_card Jan 01 00:00:06 1 7 0 mcasp_card: Using MCASP2 Jan 01 00:00:06 1 7 0 mcasp_card: clk mode = Master Jan 01 00:00:06 1 7 0 mcasp: protocol set to tdm_i2s Jan 01 00:00:06 1 7 0 mcasp: Invalid tx channels, using tx_voices=2 Jan 01 00:00:06 1 7 0 mcasp_card: Enabling AFIFO Jan 01 00:00:06 1 7 0 mount card: [mcbsp-dm814x] [mclk=49152000,sample_size=32,rate=48000] Jan 01 00:00:06 1 7 0 init_card: Jan 01 00:00:06 1 7 0 CTRL_DLL_INIT: mcbsp Jan 01 00:00:06 1 7 0 mcbsp_parse_commandline: Using McBSP1, base addr = 0x47000100 Jan 01 00:00:06 1 7 0 set_mcbsp_clock_rate: 48000 (master) Jan 01 00:00:06 1 7 0 set_mcbsp_clock_rate: frame_width 64 divider 16 Jan 01 00:00:06 1 7 0 Initializing McBSP Mixer Jan 01 00:00:06 1 7 0 pCTRL_DLL_INIT: mcasp_card Jan 01 00:00:06 1 7 0 mcasp_card: Using MCASP3 Jan 01 00:00:06 1 7 0 mcasp_card: clk mode = Slave Jan 01 00:00:06 1 7 0 mcasp: protocol set to tdm_i2s Jan 01 00:00:06 1 7 0 mcasp: Invalid rx channels, using rx_voices=2 Jan 01 00:00:06 1 7 0 card_config_restore: Jan 01 00:00:06 1 7 0 mcasp_card: Enabling AFIFO Jan 01 00:00:06 1 7 0 card_config_restore: unable to dlopen deva-util-restore.so Jan 01 00:00:06 1 7 0 start_card: Jan 01 00:00:06 1 7 0 pCTRL_DLL_INIT: mcasp_card Jan 01 00:00:06 1 7 0 mcasp_card: Using MCASP4 Jan 01 00:00:06 1 7 0 mcasp_card: clk mode = Slave Jan 01 00:00:06 1 7 0 mcasp: protocol set to tdm_i2s Jan 01 00:00:06 1 7 0 mcasp_card: Enabling AFIFO Jan 01 00:00:06 1 7 0 pCTRL_DLL_INIT: mcasp_card Jan 01 00:00:06 1 7 0 mcasp_card: Using MCASP5 Jan 01 00:00:06 1 7 0 mcasp_card: clk mode = Slave Jan 01 00:00:06 1 7 0 mcasp: protocol set to tdm_i2s Jan 01 00:00:06 1 7 0 mcasp: Invalid rx channels, using rx_voices=2 Jan 01 00:00:06 1 7 0 mcasp_card: Enabling AFIFO Jan 01 00:00:06 5 14 0 tcpip starting Jan 01 00:00:06 3 14 0 Unable to attach to pci server: No such file or directory Jan 01 00:00:06 3 14 0 Using pseudo random generator. See "random" option Jan 01 00:00:06 5 14 0 initializing IPsec... done Jan 01 00:00:06 5 14 0 IPsec: Initialized Security Association Processing. Jan 01 00:00:06 2 10 0 ti814x_attach:814 - Attaching if=dm0 unit=0 ti814x=280040 sizeof(ti814x)=3200 Jan 01 00:00:06 6 10 0 ti814x_read_mac_addr:212 - read_mac_addr for devidx=0 Jan 01 00:00:06 6 10 0 ti814x_read_mac_addr - MAC taken from NVRAM [00-18-30-02-c5-00] for devid=0 Jan 01 00:00:06 5 14 0 ti814x Jan 01 00:00:06 5 14 0 LanIdx .............. 0 Jan 01 00:00:06 5 14 0 DevIdx .............. 0 Jan 01 00:00:06 5 14 0 Vendor .............. 0x0 Jan 01 00:00:06 5 14 0 Device .............. 0x0 Jan 01 00:00:06 5 14 0 Revision ............ 0x0 Jan 01 00:00:06 5 14 0 I/O port base ....... 0x4a100000 Jan 01 00:00:06 5 14 0 I/O port base ....... 0x48140000 Jan 01 00:00:06 5 14 0 Interrupt ........... 0x29 Jan 01 00:00:06 5 14 0 MAC address ......... 001830 02c500 Jan 01 00:00:06 2 10 0 ti814x_init:947 - Start devidx=0 Jan 01 00:00:06 5 10 0 ti814x_findphy:371: an_capable 0x8, force_link -1, media_rate -1, duplex -1 Jan 01 00:00:06 2 10 0 ti814x_findphy:448 - Link is up link=0x00000001 phyid=0 Jan 01 00:00:09 5 10 0 ti814x_mdi_callback:193 - Link up (100 BaseT Full Duplex) if=0 Jan 01 00:00:37 3 26 998 QDB: No script registered for handling corrupt database. Jan 01 00:00:37 3 26 998 QDB: created missing database mme Jan 01 00:00:41 5 27 200 MDP:mdp_mgr_init(270): Starting metadata plug-in initialization. Jan 01 00:00:41 5 27 0 MME:dbs_init(663): Starting database synchronizer initialization. Jan 01 00:00:56 5 27 0 MME:mmpl_disconnect(370): Thread 11 disconnecting from database. Jan 01 00:00:56 5 27 0 MME:ocb_free(127): Connection to MME closed (ocb 239d90). Jan 01 00:01:14 5 27 0 MME:mmpl_connect(241): Thread 11 attempting connection to database. Jan 01 00:01:14 5 27 0 MME:mmpl_connect(250): Thread 11 connected to database. Jan 01 00:01:14 5 27 0 MME:ocb_calloc(71): Connection to MME opened (ocb 241d90). Jan 01 00:01:14 5 27 0 MME:io_handle_msg(484): RCV(ccid 1) -> MME_IOMSG_TYPE_SETTIMENOTIFICATION: "time=1000" Jan 01 00:01:14 5 27 0 MME:io_handle_msg(510): RCV(ccid 1) -> MME_IOMSG_TYPE_REGISTER: "class=65535" Jan 01 00:01:39 2 12 0 CLASS_ExtractDevice: no parent, bus 1, port 0 Jan 01 00:01:39 2 12 0 CLASS_EnumerateDevice: bus 1, parent 0, port 0, speed 2 Jan 01 00:01:39 2 12 0 CLASS_EnumerateDevice: Get device descriptor Jan 01 00:01:39 2 12 0 CLASS_EnumerateDevice: Set address 1 Jan 01 00:01:39 2 12 0 CLASS_EnumerateDevice: Get full device descriptor Jan 01 00:01:39 2 12 0 CLASS_EnumerateDeviceConfiguration: Get full config descriptor Jan 01 00:01:39 2 12 0 USB_SelectConfiguration: Set config devno 1, cfg 1 Jan 01 00:01:39 2 12 0 CLASS_EnumerateDevice: bus 1 dno 1, vid 18a5 Jan 01 00:01:39 2 5 100 cam-disk.so (Jun 20 2012 13:40:18) Jan 01 00:01:41 2 5 0 scsi_interpret_sense (umass ptl-0:0:0): cam_status=c4, scsi_status=2, flag=00200040, vuflag=0008, cmd=25, error=70, sense=6, asc=28, ascq=0 Jan 01 00:01:41 5 27 0 MME:handle_insert(384): Device "/fs/usb0" inserted active. Jan 01 00:01:41 5 27 0 MME:handle_insert(374): No more data on "/dev/mcd/INSERTED". Jan 01 00:01:41 5 27 0 MME:mmpl_connect(241): Thread 13 attempting connection to database. Jan 01 00:01:41 5 27 0 MME:mmpl_connect(241): Thread 14 attempting connection to database. Jan 01 00:01:41 5 27 0 MME:mmpl_connect(250): Thread 13 connected to database. Jan 01 00:01:41 5 27 0 MME:mmpl_connect(250): Thread 14 connected to database. Jan 01 00:01:41 5 27 0 MME:ms_inserted(3461): Media store at "/fs/usb0:" is being inserted (state is active). Jan 01 00:01:41 5 27 0 MME:ms_mss_info_get(4370): MSS 'devb' rated "/fs/usb0" 1 (current is 0). Jan 01 00:01:41 5 27 0 MME:ms_mss_info_get(4370): MSS 'audiocd' rated "/fs/usb0" 0 (current is 1). Jan 01 00:01:41 5 27 0 MME:ms_mss_info_get(4370): MSS 'dvdvideo' rated "/fs/usb0" 0 (current is 1). Jan 01 00:01:41 5 27 0 MME:ms_mss_info_get(4370): MSS 'dvdaudio' rated "/fs/usb0" 0 (current is 1). Jan 01 00:01:41 5 27 0 MME:ms_mss_info_get(4370): MSS 'vcd' rated "/fs/usb0" 0 (current is 1). Jan 01 00:01:41 5 27 0 MME:ms_mss_info_get(4370): MSS 'ipod' rated "/fs/usb0" 0 (current is 1). Jan 01 00:01:41 5 27 0 MME:ms_mss_info_get(4370): MSS 'mediafs' rated "/fs/usb0" 0 (current is 1). Jan 01 00:01:41 5 27 0 MME:ms_mss_info_get(4370): MSS 'mediafs2wire' rated "/fs/usb0" 0 (current is 1). Jan 01 00:01:41 5 27 0 MME:ms_mss_info_get(4370): MSS 'nosync' rated "/fs/usb0" 0 (current is 1). Jan 01 00:01:41 5 27 0 MME:ms_mss_info_get(4370): MSS 'internet' rated "/fs/usb0" 0 (current is 1). Jan 01 00:01:41 5 27 0 MME:ms_mss_info_get(4370): MSS 'snd' rated "/fs/usb0" 0 (current is 1). Jan 01 00:01:41 5 27 0 MME:ms_mss_info_get(4385): MSS 'devb' chosen for media store at "/fs/usb0". Jan 01 00:01:42 5 27 0 MME:ms_inserted(3693): Inserted as active media store has identifier "35654E5DA9D3AE45D184660DA55E2636",MSS "devb" and storage type 2 and name "STORE N GO" Jan 01 00:01:42 5 27 0 MME:ms_active_find(3165): No media store entry found. Jan 01 00:01:42 5 27 0 MME:ms_create(2820): Created new media store entry at "/fs/usb0" with ID 1. Jan 01 00:01:42 5 27 0 MME:ms_inserted(3849): Initial state of media store with ID 1 is non-existent. Jan 01 00:01:42 5 27 0 MME:ms_state_change_internal(2313): Media store 1 state change: non-existent->active; location ""->"". Jan 01 00:01:42 5 27 0 MME:mediastores_entry_update(622): Set database state to active for media store with ID 1. Jan 01 00:01:42 5 27 0 MME:slots_table_set_active(511): Set slot 2 active for media store with ID 1. Jan 01 00:01:42 5 27 0 MME:ntfy_log_event(859): MME_EVENT_MS_STATECHANGE(Media store 1; non-existent->active; device type 1, storage type 2) Jan 01 00:01:42 5 27 0 MME:mmpl_connect(241): Thread 15 attempting connection to database. Jan 01 00:01:42 5 27 0 MME:mmpl_connect(250): Thread 15 connected to database. Jan 01 00:01:42 5 27 0 MME:mme_ms_lock(552): Lock call for media store 1 of type sync. Jan 01 00:01:42 5 27 0 MME:ntfy_log_event(698): MME_EVENT_MS_SYNC_STARTED(media store 1, operation ID 0) Jan 01 00:01:42 5 27 0 MME:mmpl_ms_sync_thread(1364): Media store 1 is prunable. Jan 01 00:01:42 5 27 0 MME:ntfy_log_event(736): MME_EVENT_MS_SYNCFIRSTFID(2 on mediastore 1) Jan 01 00:01:42 5 27 0 MME:ntfy_log_event(661): MME_EVENT_MS_UPDATE(Flags 1; Op ID: 0; Mediastore 1; files 2/2; folders 0/0; playlists 0/0, time 102348000000.) Jan 01 00:01:42 5 27 0 MME:ntfy_log_event(704): MME_EVENT_MS_1PASSCOMPLETE(media store 1, operation ID 0) Jan 01 00:01:42 5 27 0 MME:mss_fsrecurse_sync_metadata(205): msid=1 -- Second PASS -- Jan 01 00:01:42 5 27 0 MME:mss_bfs_sync_pass2(290): Attemping second pass sync on media store 1, folder 1. Jan 01 00:01:42 5 27 0 MME:_mss_bfsrecurse_sync_file_2(557): Requesting metadata group set 00000002 for "/fs/usb0/Barfi.mp3" with filter "". Jan 01 00:01:42 5 27 0 MME:_mss_bfsrecurse_sync_file_2(557): Requesting metadata group set 00000002 for "/fs/usb0/Main Kya Karoon.mp3" with filter "". Jan 01 00:01:42 5 27 0 MME:ntfy_log_event(661): MME_EVENT_MS_UPDATE(Flags 2; Op ID: 0; Mediastore 1; files 2/2; folders 0/0; playlists 0/0, time 102766000000.) Jan 01 00:01:42 5 27 0 MME:ntfy_log_event(710): MME_EVENT_MS_2PASSCOMPLETE(media store 1, operation ID 0) Jan 01 00:01:42 5 27 0 MME:mss_fsrecurse_syncplaylists(503): msid=1 -- Third PASS -- Jan 01 00:01:42 5 27 0 MME:ntfy_log_event(716): MME_EVENT_MS_3PASSCOMPLETE(media store 1, operation ID 0) Jan 01 00:01:42 5 27 0 MME:ntfy_log_event(934): MS_DB_SYNC_COMPLETE(media store 1, operation ID 0) Jan 01 00:01:42 5 27 0 MME:mme_ms_unlock(713): Unlock call for media store 1 of type sync. Jan 01 00:01:42 5 27 0 MME:ntfy_log_event(722): MME_EVENT_MS_SYNCCOMPLETE(media store 1, operation ID 0) Jan 01 00:01:42 5 27 0 MME:mmpl_disconnect(370): Thread 15 disconnecting from database. Jan 01 00:01:42 5 27 0 MME:cleanup_thread(291): Database clean-up is complete. Jan 01 00:01:44 2 26 998 QDB: prepare: rc 1, ext 1 Jan 01 00:01:44 5 27 0 MME:io_devctl(139): RCV(ccid 1) -> MME_DEVCTL_NEWTRKSESSION: "statement=SELECT fid FROM library WHERE msid=1 and ftype=1" Jan 01 00:01:44 5 27 0 MME:io_handle_msg(376): RCV(ccid 1) -> MME_IOMSG_TYPE_SETTRKSESSION: "trksessionid=1" Jan 01 00:01:44 5 27 0 MME:ntfy_log_event(614): MME_EVENT_PLAYSTATE(playstate=7,speed=1000) Jan 01 00:01:44 1 27 0 MME:ocb_lock(150): Concurrent MME handle usage pid 512039, tid 57! Jan 01 00:01:44 5 27 0 MME:ntfy_log_event(846): MME_EVENT_TRKSESSIONVIEW_UPDATE(1) Jan 01 00:01:44 5 27 0 MME:ntfy_log_event(842): MME_EVENT_TRKSESSIONVIEW_COMPLETE(1) Jan 01 00:01:44 1 27 0 MME:ocb_lock(150): Concurrent MME handle usage pid 512039, tid 57! Jan 01 00:01:44 1 27 0 MME:ocb_lock(150): Concurrent MME handle usage pid 512039, tid 57! Jan 01 00:01:44 5 27 0 MME:ntfy_log_event(798): MME_EVENT_TRKSESSION(1) Jan 01 00:01:44 5 27 0 MME:io_handle_msg(331): RCV(ccid 1) -> MME_IOMSG_TYPE_ATTACHOUTPUT: "zoneid=1 outputid=1" Jan 01 00:01:44 1 27 0 MME:ocb_lock(150): Concurrent MME handle usage pid 512039, tid 57! Jan 01 00:01:44 5 27 0 MME:_msgattachoutput(2257): Output 1 is already attached to zone 1. Jan 01 00:01:44 5 27 0 MME:ntfy_log_event(948): ZONEOUTPUT_CHANGE(zoneid 1, outputid 1, op 0, error 0) Jan 01 00:01:44 5 27 0 MME:io_handle_msg(119): RCV(ccid 1) -> MME_IOMSG_TYPE_PLAY: "fid=0" Jan 01 00:01:44 1 27 0 MME:ocb_lock(150): Concurrent MME handle usage pid 512039, tid 57! Jan 01 00:01:44 5 27 0 MME:mme_ms_lock(552): Lock call for media store 1 of type playback. Jan 01 00:01:44 5 20 1 io-media-generic/mmf: writer hint snd:* found Jan 01 00:01:44 5 27 0 MME:_attachoutputs(3775): Attached outputdevice snd:/dev/snd/pcmC2D0p to controlcontext default. id=0 Jan 01 00:01:44 5 20 1 io-media-generic/trackplayer: Skipping video subgraph because there is no video device specified. Jan 01 00:01:44 5 20 1 io-media-generic/aoi: audio_writer setting AOR_TYPE_POINTER resources PcmQueueFilter to 155f40 Jan 01 00:01:44 5 20 1 io-media-generic/mmf: Configuring the queue for -1 bytes, lw = 0, hw = 0, thr = -1. Jan 01 00:01:44 5 20 1 io-media-generic/trackplayer: Skipping video subgraph because there is no device specified. Jan 01 00:01:44 5 20 1 io-media-generic/mmf: Configuring the queue for 384000 bytes, lw = 76800, hw = 307200, thr = -1. Jan 01 00:01:44 5 20 1 io-media-generic/mmf: Configuring the queue for 176400 bytes, lw = 17640, hw = 158760, thr = -1. Jan 01 00:01:44 5 20 1 io-media-generic/aoi: audio_writer using output device: '/dev/snd/pcmC2D0p' Jan 01 00:01:44 5 20 1 io-media-generic/aoi: audio_writer asking for Signed 16-bit Little Endian, 1..2x17640B frags, 44100Hz, bps=4, 2ch Jan 01 00:01:44 5 20 1 io-media-generic/aoi: audio_writer settings: Signed 16-bit Little Endian, 3x17640B frags, 44100Hz, bps=4, 2ch, Q time 300ms Jan 01 00:01:44 5 20 1 io-media-generic/aoi: audio_writer PGA_StreamerCreate() push mode = 0 adjusting chunk_size from 17640bytes to 17640bytes Jan 01 00:01:44 5 20 1 io-media-generic/aoi: audio_writer using mixer device '/dev/snd/mixerC2D0' Jan 01 00:01:44 5 20 1 io-media-generic/trackplayer: Skipping subgraph 1 because there is no device specified. Jan 01 00:01:44 3 20 1 io-media-generic/trackplayer: Couldn't subscribe to the MM_EV_USER event. Jan 01 00:01:44 5 27 0 MME:_attachinput(3862): Attached input url (/fs/usb0///Main Kya Karoon.mp3) Jan 01 00:01:44 5 27 0 MME:ntfy_log_event(605): MME_EVENT_TRACKCHANGE(actual=2,req=2,offset=0) Jan 01 00:01:44 5 27 0 MME:ntfy_log_event(862): MME_EVENT_NOWPLAYING_METADATA() Jan 01 00:01:44 5 27 0 MME:_checkiomevents(1151): io-media METADATA received. Jan 01 00:01:44 5 27 0 MME:_checkiomevents(1101): io-media STATUS: id 0, state 1, speed 1000 Jan 01 00:01:44 5 27 0 MME:ntfy_log_event(614): MME_EVENT_PLAYSTATE(playstate=2,speed=1000) Jan 01 00:01:44 5 27 0 MME:_checkiomevents(1101): io-media STATUS: id 0, state 3, speed 1000 Jan 01 00:01:44 5 27 0 MME:_checkiomevents(1151): io-media METADATA received. Jan 01 00:01:45 5 20 1 io-media-generic/aoi: MediaClock Resume() Jan 01 00:01:45 5 27 0 MME:_checkiomevents(1101): io-media STATUS: id 0, state 4, speed 1000 Jan 01 00:02:37 5 27 0 MME:io_handle_msg(128): RCV(ccid 1) -> MME_IOMSG_TYPE_SETSPEED: "Speed=0" Jan 01 00:02:37 5 27 0 MME:_msgsetspeed(4142): Setting speed to 0 Jan 01 00:02:37 5 27 0 MME:io_handle_msg(123): RCV(ccid 1) -> MME_IOMSG_TYPE_STOP Jan 01 00:02:37 5 27 0 MME:_checkiomevents(1101): io-media STATUS: id 0, state 2, speed 0 Jan 01 00:02:37 5 27 0 MME:ntfy_log_event(614): MME_EVENT_PLAYSTATE(playstate=3,speed=0) Jan 01 00:02:37 5 27 0 MME:mme_ms_unlock(713): Unlock call for media store 1 of type playback. Jan 01 00:02:37 1 27 0 MME:ocb_lock(150): Concurrent MME handle usage pid 512039, tid 57! Jan 01 00:02:37 5 27 0 MME:ntfy_log_event(614): MME_EVENT_PLAYSTATE(playstate=7,speed=0) Jan 01 00:02:37 5 27 0 MME:io_handle_msg(376): RCV(ccid 1) -> MME_IOMSG_TYPE_SETTRKSESSION: "trksessionid=0" Jan 01 00:02:37 5 27 0 MME:ntfy_log_event(850): MME_EVENT_TRKSESSIONVIEW_INVALID(1) Jan 01 00:02:37 5 27 0 MME:ntfy_log_event(798): MME_EVENT_TRKSESSION(0) Jan 01 00:02:37 5 27 0 MME:io_handle_msg(371): RCV(ccid 1) -> MME_IOMSG_TYPE_RMTRKSESSION: "trksessionid=1" Jan 01 00:04:32 2 12 0 CLASS_ExtractDevice: dno 1, vid 18a5, parent 0, port 0, openings 1 Jan 01 00:04:32 2 12 0 CLASS_ExtractDevice: holdoff port 0 Jan 01 00:04:32 2 19 900 umass_removal: path 1, devno 1, vid 18a5, did 240, class 8, sclass 6, proto 50, tflags 3000 Jan 01 00:04:32 2 12 0 udi_detach: defered removal, dno 1 Jan 01 00:04:32 2 12 0 CLASS_ExtractDeviceCleanup: dno 1 Jan 01 00:04:32 5 27 0 MME:handle_eject(472): Device "/fs/usb0" ejected. Jan 01 00:04:32 5 27 0 MME:ms_ejected(2549): Media store at "/fs/usb0" with ID 1 is being ejected. Jan 01 00:04:32 5 27 0 MME:ms_state_change_internal(2313): Media store 1 state change: active->unavailable; location ""->"". Jan 01 00:04:32 5 27 0 MME:handle_eject(463): No more data on "/dev/mcd/EJECTED". Jan 01 00:04:32 5 27 0 MME:mediastores_entry_update(622): Set database state to unavailable for media store with ID 1. Jan 01 00:04:32 5 27 0 MME:slots_table_set_inactive(479): Set slot inactive for media store with ID 1. Jan 01 00:04:32 5 27 0 MME:ntfy_log_event(859): MME_EVENT_MS_STATECHANGE(Media store 1; active->unavailable; device type 1, storage type 2) #