Hold your horses :/
After switching my port from 100 to 10 mbps full duplex, I know can see the Empeg on the network, but cannot connect to it. Emplode fails at :downloading from unit. I rebuilt the drive with the builder and reinstalled the consumer 1.03. Now when I connect and it synchronizes, it hangs in the middle of transferring the first song onto a completely empty drive. Note that I have to use the task manager to end the Emplode process, or it will just sit there forever trying to copy a single file.
Here's a log at the point of the hang:
Wed 01 Aug 2001 23:49:37.272 Logfile opened
23:49:38.538 protocolclient.cpp(873) : I [041c] ProtocolClient::IsUnitConnected()
23:49:38.538 packets.cpp(49) : I [041c] NEW request
23:49:38.538 packets.cpp(140) : I [041c] Request::Ping
23:49:38.538 packets.cpp(1061) : I [041c] FLUSH
23:49:38.538 packets.cpp(99) : I [041c] Sending response.
23:49:38.538 packets.cpp(106) : I [041c] Sending packet with id=77
23:49:38.538 packets.cpp(688) : I [041c] Request::WaitForReply(header_return=0012F51C, packet_id=77)
23:49:38.538 packets.cpp(699) : I [041c] WaitForReply loop
23:49:38.538 packets.cpp(701) : I [041c] Waiting for reply with id 77 and timeout 2
23:49:38.538 packets.cpp(827) : I [041c] Request::Receive(h = 0012F360, timeout=2)
23:49:38.538 packets.cpp(840) : I [041c] Trying to receive bytes.
23:49:38.538 packets.cpp(851) : I [041c] Got 95 bytes from connection->Receive
23:49:38.538 packets.cpp(954) : I [041c] Got a PSOH
23:49:38.538 packets.cpp(998) : I [041c] Got packet length: 84 bytes.
23:49:38.538 packets.cpp(1018) : I [041c] We've read all the data we were expecting, reading CRC.
23:49:38.538 packets.cpp(1041) : I [041c] CRC is good.
23:49:38.538 packets.cpp(911) : I [041c] We got a valid packet!
23:49:38.538 packets.cpp(916) : I [041c] End of packet, pushing back 0 bytes
23:49:38.538 packets.cpp(928) : I [041c] Processing complete: result is 0
23:49:38.538 packets.cpp(929) : I [041c] Request::Receive(D) = 0x0
23:49:38.538 packets.cpp(704) : I [041c] Receive result was 0
23:49:38.538 packets.cpp(784) : I [041c] Got a response to packet 77
23:49:38.538 packets.cpp(790) : I [041c] We have a progress reply.
23:49:38.538 packets.cpp(796) : I [041c] Progress reply, new timeout is 15
23:49:38.538 packets.cpp(699) : I [041c] WaitForReply loop
23:49:38.538 packets.cpp(701) : I [041c] Waiting for reply with id 77 and timeout 15
23:49:38.538 packets.cpp(827) : I [041c] Request::Receive(h = 0012F360, timeout=15)
23:49:38.538 packets.cpp(840) : I [041c] Trying to receive bytes.
23:49:38.538 packets.cpp(851) : I [041c] Got 95 bytes from connection->Receive
23:49:38.538 packets.cpp(954) : I [041c] Got a PSOH
23:49:38.538 packets.cpp(998) : I [041c] Got packet length: 84 bytes.
23:49:38.538 packets.cpp(1018) : I [041c] We've read all the data we were expecting, reading CRC.
23:49:38.538 packets.cpp(1041) : I [041c] CRC is good.
23:49:38.538 packets.cpp(911) : I [041c] We got a valid packet!
23:49:38.538 packets.cpp(916) : I [041c] End of packet, pushing back 0 bytes
23:49:38.538 packets.cpp(928) : I [041c] Processing complete: result is 0
23:49:38.538 packets.cpp(929) : I [041c] Request::Receive(D) = 0x0
23:49:38.538 packets.cpp(704) : I [041c] Receive result was 0
23:49:38.538 packets.cpp(784) : I [041c] Got a response to packet 77
23:49:38.538 packets.cpp(790) : I [041c] We have a progress reply.
23:49:38.538 packets.cpp(796) : I [041c] Progress reply, new timeout is 15
23:49:38.538 packets.cpp(699) : I [041c] WaitForReply loop
23:49:38.538 packets.cpp(701) : I [041c] Waiting for reply with id 77 and timeout 15
23:49:38.538 packets.cpp(827) : I [041c] Request::Receive(h = 0012F360, timeout=15)
23:49:38.538 packets.cpp(840) : I [041c] Trying to receive bytes.
23:49:38.538 packets.cpp(851) : I [041c] Got 15 bytes from connection->Receive
23:49:38.538 packets.cpp(954) : I [041c] Got a PSOH
23:49:38.538 packets.cpp(998) : I [041c] Got packet length: 4 bytes.
23:49:38.538 packets.cpp(1018) : I [041c] We've read all the data we were expecting, reading CRC.
23:49:38.538 packets.cpp(1041) : I [041c] CRC is good.
23:49:38.538 packets.cpp(911) : I [041c] We got a valid packet!
23:49:38.538 packets.cpp(916) : I [041c] End of packet, pushing back 0 bytes
23:49:38.538 packets.cpp(928) : I [041c] Processing complete: result is 0
23:49:38.538 packets.cpp(929) : I [041c] Request::Receive(D) = 0x0
23:49:38.538 packets.cpp(704) : I [041c] Receive result was 0
23:49:38.538 packets.cpp(784) : I [041c] Got a response to packet 77
23:49:38.538 packets.cpp(810) : I [041c] Got a real reply.
23:49:38.538 packets.cpp(814) : I [041c] Packet::WaitForReply(G) = 0x6
23:49:38.538 packets.cpp(217) : I [041c] Request::Ping(D) = 0x0
23:49:38.538 protocolclient.cpp(876) : I [041c] ProtocolClient::IsUnitConnected()=1
23:49:38.538 DatabaseSync.cpp(245) : I [041c] Disabling power management
23:49:38.538 DatabaseUpload.cpp(84) : I [041c] Verify Player Identity...
23:49:38.538 protocolclient.cpp(211) : I [041c] ProtocolClient::ReadFidToMemory(1, 0012F4E0, 0012F4E4)
23:49:38.538 packets.cpp(49) : I [041c] NEW request
23:49:38.538 DatabaseSyncProtocolObserver.cpp(74) : I [041c] ProgressProtocolObserver::ReportProgress(client=00C5AAD0, activity=3, current=0, maximum=1)
23:49:38.538 DatabaseSyncProtocolObserver.cpp(80) : I [041c] Progress of activity 3 set to 0/1 in CProgressProtocolObserver::ReportProgress.
23:49:38.538 DatabaseSyncProtocolObserver.cpp(86) : I [041c] ProgressProtocolObserver::ReportProgress()
23:49:38.538 packets.cpp(99) : I [041c] Sending response.
23:49:38.538 packets.cpp(106) : I [041c] Sending packet with id=78
23:49:38.538 packets.cpp(688) : I [041c] Request::WaitForReply(header_return=0012F454, packet_id=78)
23:49:38.538 packets.cpp(699) : I [041c] WaitForReply loop
23:49:38.538 packets.cpp(701) : I [041c] Waiting for reply with id 78 and timeout 2
23:49:38.538 packets.cpp(827) : I [041c] Request::Receive(h = 0012F29C, timeout=2)
23:49:38.538 packets.cpp(840) : I [041c] Trying to receive bytes.
23:49:38.538 packets.cpp(851) : I [041c] Got 19 bytes from connection->Receive
23:49:38.538 packets.cpp(954) : I [041c] Got a PSOH
23:49:38.538 packets.cpp(998) : I [041c] Got packet length: 8 bytes.
23:49:38.538 packets.cpp(1018) : I [041c] We've read all the data we were expecting, reading CRC.
23:49:38.538 packets.cpp(1041) : I [041c] CRC is good.
23:49:38.538 packets.cpp(911) : I [041c] We got a valid packet!
23:49:38.538 packets.cpp(916) : I [041c] End of packet, pushing back 0 bytes
23:49:38.538 packets.cpp(928) : I [041c] Processing complete: result is 0
23:49:38.538 packets.cpp(929) : I [041c] Request::Receive(D) = 0x0
23:49:38.538 packets.cpp(704) : I [041c] Receive result was 0
23:49:38.538 packets.cpp(784) : I [041c] Got a response to packet 78
23:49:38.538 packets.cpp(810) : I [041c] Got a real reply.
23:49:38.538 packets.cpp(814) : I [041c] Packet::WaitForReply(G) = 0x6
23:49:38.538 packets.cpp(49) : I [041c] NEW request
23:49:38.538 DatabaseSyncProtocolObserver.cpp(74) : I [041c] ProgressProtocolObserver::ReportProgress(client=00C5AAD0, activity=3, current=1, maximum=1)
23:49:38.538 DatabaseSyncProtocolObserver.cpp(80) : I [041c] Progress of activity 3 set to 1/1 in CProgressProtocolObserver::ReportProgress.
23:49:38.538 DatabaseSyncProtocolObserver.cpp(86) : I [041c] ProgressProtocolObserver::ReportProgress()
23:49:38.538 DatabaseSyncProtocolObserver.cpp(74) : I [041c] ProgressProtocolObserver::ReportProgress(client=00C5AAD0, activity=1, current=0, maximum=145)
23:49:38.538 DatabaseSyncProtocolObserver.cpp(80) : I [041c] Progress of activity 1 set to 0/145 in CProgressProtocolObserver::ReportProgress.
23:49:38.538 DatabaseSyncProtocolObserver.cpp(86) : I [041c] ProgressProtocolObserver::ReportProgress()
23:49:38.538 packets.cpp(99) : I [041c] Sending response.
23:49:38.538 packets.cpp(106) : I [041c] Sending packet with id=79
23:49:38.538 packets.cpp(688) : I [041c] Request::WaitForReply(header_return=0012F448, packet_id=79)
23:49:38.538 packets.cpp(699) : I [041c] WaitForReply loop
23:49:38.538 packets.cpp(701) : I [041c] Waiting for reply with id 79 and timeout 2
23:49:38.538 packets.cpp(827) : I [041c] Request::Receive(h = 0012F284, timeout=2)
23:49:38.538 packets.cpp(840) : I [041c] Trying to receive bytes.
23:49:38.538 packets.cpp(851) : I [041c] Got 95 bytes from connection->Receive
23:49:38.538 packets.cpp(954) : I [041c] Got a PSOH
23:49:38.538 packets.cpp(998) : I [041c] Got packet length: 84 bytes.
23:49:38.553 packets.cpp(1018) : I [041c] We've read all the data we were expecting, reading CRC.
23:49:38.553 packets.cpp(1041) : I [041c] CRC is good.
23:49:38.553 packets.cpp(911) : I [041c] We got a valid packet!
23:49:38.553 packets.cpp(916) : I [041c] End of packet, pushing back 0 bytes
23:49:38.553 packets.cpp(928) : I [041c] Processing complete: result is 0
23:49:38.553 packets.cpp(929) : I [041c] Request::Receive(D) = 0x0
23:49:38.553 packets.cpp(704) : I [041c] Receive result was 0
23:49:38.553 packets.cpp(784) : I [041c] Got a response to packet 79
23:49:38.553 packets.cpp(790) : I [041c] We have a progress reply.
23:49:38.553 packets.cpp(796) : I [041c] Progress reply, new timeout is 20
23:49:38.553 packets.cpp(699) : I [041c] WaitForReply loop
23:49:38.553 packets.cpp(701) : I [041c] Waiting for reply with id 79 and timeout 20
23:49:38.553 packets.cpp(827) : I [041c] Request::Receive(h = 0012F284, timeout=20)
23:49:38.553 packets.cpp(840) : I [041c] Trying to receive bytes.
23:49:38.553 packets.cpp(851) : I [041c] Got 168 bytes from connection->Receive
23:49:38.553 packets.cpp(954) : I [041c] Got a PSOH
23:49:38.553 packets.cpp(998) : I [041c] Got packet length: 157 bytes.
23:49:38.553 packets.cpp(1018) : I [041c] We've read all the data we were expecting, reading CRC.
23:49:38.553 packets.cpp(1041) : I [041c] CRC is good.
23:49:38.553 packets.cpp(911) : I [041c] We got a valid packet!
23:49:38.553 packets.cpp(916) : I [041c] End of packet, pushing back 0 bytes
23:49:38.553 packets.cpp(928) : I [041c] Processing complete: result is 0
23:49:38.553 packets.cpp(929) : I [041c] Request::Receive(D) = 0x0
23:49:38.553 packets.cpp(704) : I [041c] Receive result was 0
23:49:38.553 packets.cpp(784) : I [041c] Got a response to packet 79
23:49:38.553 packets.cpp(810) : I [041c] Got a real reply.
23:49:38.553 packets.cpp(814) : I [041c] Packet::WaitForReply(G) = 0x6
23:49:38.553 packets.cpp(49) : I [041c] NEW request
23:49:38.553 DatabaseSyncProtocolObserver.cpp(74) : I [041c] ProgressProtocolObserver::ReportProgress(client=00C5AAD0, activity=1, current=145, maximum=145)
23:49:38.553 DatabaseSyncProtocolObserver.cpp(80) : I [041c] Progress of activity 1 set to 145/145 in CProgressProtocolObserver::ReportProgress.
23:49:38.553 DatabaseSyncProtocolObserver.cpp(86) : I [041c] ProgressProtocolObserver::ReportProgress()
23:49:38.553 DatabaseSyncProtocolObserver.cpp(74) : I [041c] ProgressProtocolObserver::ReportProgress(client=00C5AAD0, activity=1, current=145, maximum=145)
23:49:38.553 DatabaseSyncProtocolObserver.cpp(80) : I [041c] Progress of activity 1 set to 145/145 in CProgressProtocolObserver::ReportProgress.
23:49:38.553 DatabaseSyncProtocolObserver.cpp(86) : I [041c] ProgressProtocolObserver::ReportProgress()
23:49:38.553 protocolclient.cpp(295) : I [041c] ProtocolClient::ReadFidToMemory() = 0
23:49:38.553 protocolclient.cpp(648) : I [041c] ProtocolClient::FreeFidMemory(ppdest=0012F4E0)
23:49:38.553 protocolclient.cpp(649) : I [041c] *ppdest=00C59428
23:49:38.553 protocolclient.cpp(653) : I [041c] ProtocolClient::FreeFidMemory()
23:49:38.553 DatabaseUpload.cpp(96) : I [041c] Player serial = 80000377, expecting 80000377
23:49:38.553 protocolclient.cpp(1316) : I [041c] ProtocolClient::GetPlayerConfiguration(s=0012F530)
23:49:38.553 protocolclient.cpp(211) : I [041c] ProtocolClient::ReadFidToMemory(6, 0012F4B4, 0012F4B8)
23:49:38.553 packets.cpp(49) : I [041c] NEW request
23:49:38.553 DatabaseSyncProtocolObserver.cpp(74) : I [041c] ProgressProtocolObserver::ReportProgress(client=00C5AAD0, activity=3, current=0, maximum=1)
23:49:38.553 DatabaseSyncProtocolObserver.cpp(80) : I [041c] Progress of activity 3 set to 0/1 in CProgressProtocolObserver::ReportProgress.
23:49:38.553 DatabaseSyncProtocolObserver.cpp(86) : I [041c] ProgressProtocolObserver::ReportProgress()
23:49:38.553 packets.cpp(99) : I [041c] Sending response.
23:49:38.553 packets.cpp(106) : I [041c] Sending packet with id=81
23:49:38.553 packets.cpp(688) : I [041c] Request::WaitForReply(header_return=0012F420, packet_id=81)
23:49:38.553 packets.cpp(699) : I [041c] WaitForReply loop
23:49:38.553 packets.cpp(701) : I [041c] Waiting for reply with id 81 and timeout 2
23:49:38.553 packets.cpp(827) : I [041c] Request::Receive(h = 0012F268, timeout=2)
23:49:38.553 packets.cpp(840) : I [041c] Trying to receive bytes.
23:49:38.553 packets.cpp(851) : I [041c] Got 19 bytes from connection->Receive
23:49:38.553 packets.cpp(954) : I [041c] Got a PSOH
23:49:38.553 packets.cpp(998) : I [041c] Got packet length: 8 bytes.
23:49:38.553 packets.cpp(1018) : I [041c] We've read all the data we were expecting, reading CRC.
23:49:38.553 packets.cpp(1041) : I [041c] CRC is good.
23:49:38.553 packets.cpp(911) : I [041c] We got a valid packet!
23:49:38.553 packets.cpp(916) : I [041c] End of packet, pushing back 0 bytes
23:49:38.553 packets.cpp(928) : I [041c] Processing complete: result is 0
23:49:38.553 packets.cpp(929) : I [041c] Request::Receive(D) = 0x0
23:49:38.553 packets.cpp(704) : I [041c] Receive result was 0
23:49:38.553 packets.cpp(784) : I [041c] Got a response to packet 81
23:49:38.553 packets.cpp(810) : I [041c] Got a real reply.
23:49:38.553 packets.cpp(814) : I [041c] Packet::WaitForReply(G) = 0x6
23:49:38.553 packets.cpp(49) : I [041c] NEW request
23:49:38.553 DatabaseSyncProtocolObserver.cpp(74) : I [041c] ProgressProtocolObserver::ReportProgress(client=00C5AAD0, activity=3, current=1, maximum=1)
23:49:38.553 DatabaseSyncProtocolObserver.cpp(80) : I [041c] Progress of activity 3 set to 1/1 in CProgressProtocolObserver::ReportProgress.
23:49:38.553 DatabaseSyncProtocolObserver.cpp(86) : I [041c] ProgressProtocolObserver::ReportProgress()
23:49:38.553 DatabaseSyncProtocolObserver.cpp(74) : I [041c] ProgressProtocolObserver::ReportProgress(client=00C5AAD0, activity=1, current=0, maximum=334)
23:49:38.553 DatabaseSyncProtocolObserver.cpp(80) : I [041c] Progress of activity 1 set to 0/334 in CProgressProtocolObserver::ReportProgress.
23:49:38.553 DatabaseSyncProtocolObserver.cpp(86) : I [041c] ProgressProtocolObserver::ReportProgress()
23:49:38.553 packets.cpp(99) : I [041c] Sending response.
23:49:38.553 packets.cpp(106) : I [041c] Sending packet with id=82
23:49:38.553 packets.cpp(688) : I [041c] Request::WaitForReply(header_return=0012F414, packet_id=82)
23:49:38.553 packets.cpp(699) : I [041c] WaitForReply loop
23:49:38.553 packets.cpp(701) : I [041c] Waiting for reply with id 82 and timeout 2
23:49:38.553 packets.cpp(827) : I [041c] Request::Receive(h = 0012F250, timeout=2)
23:49:38.553 packets.cpp(840) : I [041c] Trying to receive bytes.
23:49:38.553 packets.cpp(851) : I [041c] Got 95 bytes from connection->Receive
23:49:38.553 packets.cpp(954) : I [041c] Got a PSOH
23:49:38.553 packets.cpp(998) : I [041c] Got packet length: 84 bytes.
23:49:38.553 packets.cpp(1018) : I [041c] We've read all the data we were expecting, reading CRC.
23:49:38.553 packets.cpp(1041) : I [041c] CRC is good.
23:49:38.553 packets.cpp(911) : I [041c] We got a valid packet!
23:49:38.553 packets.cpp(916) : I [041c] End of packet, pushing back 0 bytes
23:49:38.553 packets.cpp(928) : I [041c] Processing complete: result is 0
23:49:38.553 packets.cpp(929) : I [041c] Request::Receive(D) = 0x0
23:49:38.553 packets.cpp(704) : I [041c] Receive result was 0
23:49:38.553 packets.cpp(784) : I [041c] Got a response to packet 82
23:49:38.553 packets.cpp(790) : I [041c] We have a progress reply.
23:49:38.553 packets.cpp(796) : I [041c] Progress reply, new timeout is 20
23:49:38.553 packets.cpp(699) : I [041c] WaitForReply loop
23:49:38.553 packets.cpp(701) : I [041c] Waiting for reply with id 82 and timeout 20
23:49:38.553 packets.cpp(827) : I [041c] Request::Receive(h = 0012F250, timeout=20)
23:49:38.553 packets.cpp(840) : I [041c] Trying to receive bytes.
23:49:38.553 packets.cpp(851) : I [041c] Got 357 bytes from connection->Receive
23:49:38.553 packets.cpp(954) : I [041c] Got a PSOH
23:49:38.553 packets.cpp(998) : I [041c] Got packet length: 346 bytes.
23:49:38.553 packets.cpp(1018) : I [041c] We've read all the data we were expecting, reading CRC.
23:49:38.553 packets.cpp(1041) : I [041c] CRC is good.
23:49:38.553 packets.cpp(911) : I [041c] We got a valid packet!
23:49:38.553 packets.cpp(916) : I [041c] End of packet, pushing back 0 bytes
23:49:38.553 packets.cpp(928) : I [041c] Processing complete: result is 0
23:49:38.553 packets.cpp(929) : I [041c] Request::Receive(D) = 0x0
23:49:38.553 packets.cpp(704) : I [041c] Receive result was 0
23:49:38.553 packets.cpp(784) : I [041c] Got a response to packet 82
23:49:38.553 packets.cpp(810) : I [041c] Got a real reply.
23:49:38.553 packets.cpp(814) : I [041c] Packet::WaitForReply(G) = 0x6
23:49:38.553 packets.cpp(49) : I [041c] NEW request
23:49:38.553 DatabaseSyncProtocolObserver.cpp(74) : I [041c] ProgressProtocolObserver::ReportProgress(client=00C5AAD0, activity=1, current=334, maximum=334)
23:49:38.569 DatabaseSyncProtocolObserver.cpp(80) : I [041c] Progress of activity 1 set to 334/334 in CProgressProtocolObserver::ReportProgress.
23:49:38.569 DatabaseSyncProtocolObserver.cpp(86) : I [041c] ProgressProtocolObserver::ReportProgress()
23:49:38.569 DatabaseSyncProtocolObserver.cpp(74) : I [041c] ProgressProtocolObserver::ReportProgress(client=00C5AAD0, activity=1, current=334, maximum=334)
23:49:38.569 DatabaseSyncProtocolObserver.cpp(80) : I [041c] Progress of activity 1 set to 334/334 in CProgressProtocolObserver::ReportProgress.
23:49:38.569 DatabaseSyncProtocolObserver.cpp(86) : I [041c] ProgressProtocolObserver::ReportProgress()
23:49:38.569 protocolclient.cpp(295) : I [041c] ProtocolClient::ReadFidToMemory() = 0
23:49:38.569 protocolclient.cpp(648) : I [041c] ProtocolClient::FreeFidMemory(ppdest=0012F4B4)
23:49:38.569 protocolclient.cpp(649) : I [041c] *ppdest=00C59040
23:49:38.569 protocolclient.cpp(653) : I [041c] ProtocolClient::FreeFidMemory()
23:49:38.569 protocolclient.cpp(1334) : I [041c] ProtocolClient::GetPlayerConfiguration() = 0
23:49:38.569 DatabaseUpload.cpp(110) : I [041c] Database seq number = 12, expecting 12
23:49:38.569 DatabaseUpload.cpp(138) : I [041c] Entering stage 1.
23:49:38.569 protocolclient.cpp(1202) : I [041c] ProtocolClient::LockUI(1)
23:49:38.569 protocolclient.cpp(1143) : I [041c] ProtocolClient::SendCommand(command=1, p0=1, p1=0, p2=00000000
23:49:38.569 packets.cpp(49) : I [041c] NEW request
23:49:38.569 packets.cpp(1061) : I [041c] FLUSH
23:49:38.569 packets.cpp(99) : I [041c] Sending response.
23:49:38.569 packets.cpp(106) : I [041c] Sending packet with id=84
23:49:38.569 packets.cpp(688) : I [041c] Request::WaitForReply(header_return=0012F394, packet_id=84)
23:49:38.569 packets.cpp(699) : I [041c] WaitForReply loop
23:49:38.569 packets.cpp(701) : I [041c] Waiting for reply with id 84 and timeout 2
23:49:38.569 packets.cpp(827) : I [041c] Request::Receive(h = 0012F1E8, timeout=2)
23:49:38.569 packets.cpp(840) : I [041c] Trying to receive bytes.
23:49:38.819 packets.cpp(851) : I [041c] Got -110 bytes from connection->Receive
23:49:38.819 packets.cpp(886) : I [041c] It timed out - trying again
23:49:38.819 packets.cpp(840) : I [041c] Trying to receive bytes.
23:49:38.913 packets.cpp(851) : I [041c] Got 15 bytes from connection->Receive
23:49:38.913 packets.cpp(954) : I [041c] Got a PSOH
23:49:38.913 packets.cpp(998) : I [041c] Got packet length: 4 bytes.
23:49:38.913 packets.cpp(1018) : I [041c] We've read all the data we were expecting, reading CRC.
23:49:38.913 packets.cpp(1041) : I [041c] CRC is good.
23:49:38.913 packets.cpp(911) : I [041c] We got a valid packet!
23:49:38.913 packets.cpp(916) : I [041c] End of packet, pushing back 0 bytes
23:49:38.913 packets.cpp(928) : I [041c] Processing complete: result is 0
23:49:38.913 packets.cpp(929) : I [041c] Request::Receive(D) = 0x0
23:49:38.913 packets.cpp(704) : I [041c] Receive result was 0
23:49:38.913 packets.cpp(784) : I [041c] Got a response to packet 84
23:49:38.913 packets.cpp(810) : I [041c] Got a real reply.
23:49:38.913 packets.cpp(814) : I [041c] Packet::WaitForReply(G) = 0x6
23:49:38.913 protocolclient.cpp(1157) : I [041c] ProtocolClient::SendCommand()=0
23:49:38.913 protocolclient.cpp(1204) : I [041c] ProtocolClient::LockUI()=0
23:49:38.913 DatabaseUpload.cpp(144) : I [041c] Entering stage 2.
23:49:38.913 protocolclient.cpp(1121) : I [041c] ProtocolClient::CheckMedia()
23:49:38.913 protocolclient.cpp(1052) : I [041c] ProtocolClient::Fsck(drive='/dev/hda4')
23:49:38.913 packets.cpp(49) : I [041c] NEW request
23:49:38.913 packets.cpp(1061) : I [041c] FLUSH
23:49:38.913 packets.cpp(99) : I [041c] Sending response.
23:49:38.913 packets.cpp(106) : I [041c] Sending packet with id=85
23:49:38.913 packets.cpp(688) : I [041c] Request::WaitForReply(header_return=0012F4B0, packet_id=85)
23:49:38.913 packets.cpp(699) : I [041c] WaitForReply loop
23:49:38.913 packets.cpp(701) : I [041c] Waiting for reply with id 85 and timeout 2
23:49:38.913 packets.cpp(827) : I [041c] Request::Receive(h = 0012F2E4, timeout=2)
23:49:38.913 packets.cpp(840) : I [041c] Trying to receive bytes.
23:49:38.913 packets.cpp(851) : I [041c] Got 95 bytes from connection->Receive
23:49:38.913 packets.cpp(954) : I [041c] Got a PSOH
23:49:38.913 packets.cpp(998) : I [041c] Got packet length: 84 bytes.
23:49:38.913 packets.cpp(1018) : I [041c] We've read all the data we were expecting, reading CRC.
23:49:38.913 packets.cpp(1041) : I [041c] CRC is good.
23:49:38.913 packets.cpp(911) : I [041c] We got a valid packet!
23:49:38.913 packets.cpp(916) : I [041c] End of packet, pushing back 0 bytes
23:49:38.913 packets.cpp(928) : I [041c] Processing complete: result is 0
23:49:38.913 packets.cpp(929) : I [041c] Request::Receive(D) = 0x0
23:49:38.913 packets.cpp(704) : I [041c] Receive result was 0
23:49:38.913 packets.cpp(784) : I [041c] Got a response to packet 85
23:49:38.913 packets.cpp(790) : I [041c] We have a progress reply.
23:49:38.913 packets.cpp(796) : I [041c] Progress reply, new timeout is 25
23:49:38.913 DatabaseSyncProtocolObserver.cpp(74) : I [041c] ProgressProtocolObserver::ReportProgress(client=00C5AAD0, activity=7, current=-5, maximum=0)
23:49:38.913 DatabaseSyncProtocolObserver.cpp(80) : I [041c] Progress of activity 7 set to -5/0 in CProgressProtocolObserver::ReportProgress.
23:49:38.913 DatabaseSyncProtocolObserver.cpp(86) : I [041c] ProgressProtocolObserver::ReportProgress()
23:49:38.913 packets.cpp(699) : I [041c] WaitForReply loop
23:49:38.913 packets.cpp(701) : I [041c] Waiting for reply with id 85 and timeout 25
23:49:38.913 packets.cpp(827) : I [041c] Request::Receive(h = 0012F2E4, timeout=25)
23:49:38.913 packets.cpp(840) : I [041c] Trying to receive bytes.
23:49:38.944 packets.cpp(851) : I [041c] Got 15 bytes from connection->Receive
23:49:38.944 packets.cpp(954) : I [041c] Got a PSOH
23:49:38.944 packets.cpp(998) : I [041c] Got packet length: 4 bytes.
23:49:38.944 packets.cpp(1018) : I [041c] We've read all the data we were expecting, reading CRC.
23:49:38.944 packets.cpp(1041) : I [041c] CRC is good.
23:49:38.944 packets.cpp(911) : I [041c] We got a valid packet!
23:49:38.944 packets.cpp(916) : I [041c] End of packet, pushing back 0 bytes
23:49:38.944 packets.cpp(928) : I [041c] Processing complete: result is 0
23:49:38.944 packets.cpp(929) : I [041c] Request::Receive(D) = 0x0
23:49:38.944 packets.cpp(704) : I [041c] Receive result was 0
23:49:38.944 packets.cpp(784) : I [041c] Got a response to packet 85
23:49:38.944 packets.cpp(810) : I [041c] Got a real reply.
23:49:38.944 packets.cpp(814) : I [041c] Packet::WaitForReply(G) = 0x6
23:49:38.944 protocolclient.cpp(1077) : I [041c] FSCK result: 0
23:49:38.944 protocolclient.cpp(1115) : I [041c] ProtocolClient::Fsck()=0
23:49:38.944 protocolclient.cpp(1052) : I [041c] ProtocolClient::Fsck(drive='/dev/hdc4')
23:49:38.944 packets.cpp(49) : I [041c] NEW request
23:49:38.944 packets.cpp(1061) : I [041c] FLUSH
23:49:38.944 packets.cpp(99) : I [041c] Sending response.
23:49:38.944 packets.cpp(106) : I [041c] Sending packet with id=86
23:49:38.944 packets.cpp(688) : I [041c] Request::WaitForReply(header_return=0012F4B0, packet_id=86)
23:49:38.944 packets.cpp(699) : I [041c] WaitForReply loop
23:49:38.944 packets.cpp(701) : I [041c] Waiting for reply with id 86 and timeout 2
23:49:38.944 packets.cpp(827) : I [041c] Request::Receive(h = 0012F2E4, timeout=2)
23:49:38.944 packets.cpp(840) : I [041c] Trying to receive bytes.
23:49:38.944 packets.cpp(851) : I [041c] Got 95 bytes from connection->Receive
23:49:38.944 packets.cpp(954) : I [041c] Got a PSOH
23:49:38.944 packets.cpp(998) : I [041c] Got packet length: 84 bytes.
23:49:38.944 packets.cpp(1018) : I [041c] We've read all the data we were expecting, reading CRC.
23:49:38.944 packets.cpp(1041) : I [041c] CRC is good.
23:49:38.944 packets.cpp(911) : I [041c] We got a valid packet!
23:49:38.944 packets.cpp(916) : I [041c] End of packet, pushing back 0 bytes
23:49:38.944 packets.cpp(928) : I [041c] Processing complete: result is 0
23:49:38.944 packets.cpp(929) : I [041c] Request::Receive(D) = 0x0
23:49:38.944 packets.cpp(704) : I [041c] Receive result was 0
23:49:38.944 packets.cpp(784) : I [041c] Got a response to packet 86
23:49:38.944 packets.cpp(790) : I [041c] We have a progress reply.
23:49:38.944 packets.cpp(796) : I [041c] Progress reply, new timeout is 25
23:49:38.944 DatabaseSyncProtocolObserver.cpp(74) : I [041c] ProgressProtocolObserver::ReportProgress(client=00C5AAD0, activity=7, current=-5, maximum=0)
23:49:38.944 DatabaseSyncProtocolObserver.cpp(80) : I [041c] Progress of activity 7 set to -5/0 in CProgressProtocolObserver::ReportProgress.
23:49:38.944 DatabaseSyncProtocolObserver.cpp(86) : I [041c] ProgressProtocolObserver::ReportProgress()
23:49:38.944 packets.cpp(699) : I [041c] WaitForReply loop
23:49:38.944 packets.cpp(701) : I [041c] Waiting for reply with id 86 and timeout 25
23:49:38.944 packets.cpp(827) : I [041c] Request::Receive(h = 0012F2E4, timeout=25)
23:49:38.944 packets.cpp(840) : I [041c] Trying to receive bytes.
23:49:38.944 packets.cpp(851) : I [041c] Got 15 bytes from connection->Receive
23:49:38.944 packets.cpp(954) : I [041c] Got a PSOH
23:49:38.944 packets.cpp(998) : I [041c] Got packet length: 4 bytes.
23:49:38.944 packets.cpp(1018) : I [041c] We've read all the data we were expecting, reading CRC.
23:49:38.944 packets.cpp(1041) : I [041c] CRC is good.
23:49:38.960 packets.cpp(911) : I [041c] We got a valid packet!
23:49:38.960 packets.cpp(916) : I [041c] End of packet, pushing back 0 bytes
23:49:38.960 packets.cpp(928) : I [041c] Processing complete: result is 0
23:49:38.960 packets.cpp(929) : I [041c] Request::Receive(D) = 0x0
23:49:38.960 packets.cpp(704) : I [041c] Receive result was 0
23:49:38.960 packets.cpp(784) : I [041c] Got a response to packet 86
23:49:38.960 packets.cpp(810) : I [041c] Got a real reply.
23:49:38.960 packets.cpp(814) : I [041c] Packet::WaitForReply(G) = 0x6
23:49:38.960 protocolclient.cpp(1077) : I [041c] FSCK result: 512
23:49:38.960 protocolclient.cpp(1109) : I [041c] FSCK reported disk is not present on drive /dev/hdc4
23:49:38.960 protocolclient.cpp(1115) : I [041c] ProtocolClient::Fsck()=512
23:49:38.960 protocolclient.cpp(1136) : I [041c] ProtocolClient::CheckMedia()=512
23:49:38.960 DatabaseUpload.cpp(156) : I [041c] Entering stage 3.
23:49:38.960 protocolclient.cpp(936) : I [041c] ProtocolClient::EnableWrite(b=1)
23:49:38.960 packets.cpp(49) : I [041c] NEW request
23:49:38.960 DatabaseSyncProtocolObserver.cpp(74) : I [041c] ProgressProtocolObserver::ReportProgress(client=00C5AAD0, activity=8, current=0, maximum=1)
23:49:38.960 DatabaseSyncProtocolObserver.cpp(80) : I [041c] Progress of activity 8 set to 0/1 in CProgressProtocolObserver::ReportProgress.
23:49:38.960 DatabaseSyncProtocolObserver.cpp(86) : I [041c] ProgressProtocolObserver::ReportProgress()
23:49:38.960 packets.cpp(1061) : I [041c] FLUSH
23:49:38.960 packets.cpp(99) : I [041c] Sending response.
23:49:38.960 packets.cpp(106) : I [041c] Sending packet with id=87
23:49:38.960 packets.cpp(688) : I [041c] Request::WaitForReply(header_return=0012F50C, packet_id=87)
23:49:38.960 packets.cpp(699) : I [041c] WaitForReply loop
23:49:38.960 packets.cpp(701) : I [041c] Waiting for reply with id 87 and timeout 2
23:49:38.960 packets.cpp(827) : I [041c] Request::Receive(h = 0012F350, timeout=2)
23:49:38.960 packets.cpp(840) : I [041c] Trying to receive bytes.
23:49:38.960 packets.cpp(851) : I [041c] Got 95 bytes from connection->Receive
23:49:38.960 packets.cpp(954) : I [041c] Got a PSOH
23:49:38.960 packets.cpp(998) : I [041c] Got packet length: 84 bytes.
23:49:38.960 packets.cpp(1018) : I [041c] We've read all the data we were expecting, reading CRC.
23:49:38.960 packets.cpp(1041) : I [041c] CRC is good.
23:49:38.960 packets.cpp(911) : I [041c] We got a valid packet!
23:49:38.960 packets.cpp(916) : I [041c] End of packet, pushing back 0 bytes
23:49:38.960 packets.cpp(928) : I [041c] Processing complete: result is 0
23:49:38.960 packets.cpp(929) : I [041c] Request::Receive(D) = 0x0
23:49:38.960 packets.cpp(704) : I [041c] Receive result was 0
23:49:38.960 packets.cpp(784) : I [041c] Got a response to packet 87
23:49:38.960 packets.cpp(790) : I [041c] We have a progress reply.
23:49:38.960 packets.cpp(796) : I [041c] Progress reply, new timeout is 60
23:49:38.960 packets.cpp(699) : I [041c] WaitForReply loop
23:49:38.960 packets.cpp(701) : I [041c] Waiting for reply with id 87 and timeout 60
23:49:38.960 packets.cpp(827) : I [041c] Request::Receive(h = 0012F350, timeout=60)
23:49:38.960 packets.cpp(840) : I [041c] Trying to receive bytes.
23:49:38.960 packets.cpp(851) : I [041c] Got 15 bytes from connection->Receive
23:49:38.960 packets.cpp(954) : I [041c] Got a PSOH
23:49:38.960 packets.cpp(998) : I [041c] Got packet length: 4 bytes.
23:49:38.960 packets.cpp(1018) : I [041c] We've read all the data we were expecting, reading CRC.
23:49:38.960 packets.cpp(1041) : I [041c] CRC is good.
23:49:38.960 packets.cpp(911) : I [041c] We got a valid packet!
23:49:38.960 packets.cpp(916) : I [041c] End of packet, pushing back 0 bytes
23:49:38.960 packets.cpp(928) : I [041c] Processing complete: result is 0
23:49:38.960 packets.cpp(929) : I [041c] Request::Receive(D) = 0x0
23:49:38.960 packets.cpp(704) : I [041c] Receive result was 0
23:49:38.960 packets.cpp(784) : I [041c] Got a response to packet 87
23:49:38.960 packets.cpp(810) : I [041c] Got a real reply.
23:49:38.960 packets.cpp(814) : I [041c] Packet::WaitForReply(G) = 0x6
23:49:38.960 DatabaseSyncProtocolObserver.cpp(74) : I [041c] ProgressProtocolObserver::ReportProgress(client=00C5AAD0, activity=8, current=1, maximum=1)
23:49:38.960 DatabaseSyncProtocolObserver.cpp(80) : I [041c] Progress of activity 8 set to 1/1 in CProgressProtocolObserver::ReportProgress.
23:49:38.960 DatabaseSyncProtocolObserver.cpp(86) : I [041c] ProgressProtocolObserver::ReportProgress()
23:49:38.960 protocolclient.cpp(952) : I [041c] ProtocolClient::EnableWrite()=0
23:49:38.960 DatabaseUpload.cpp(222) : I [041c] Stage 4: Delete player databases
23:49:38.960 protocolclient.cpp(775) : I [041c] ProtocolClient::DeleteDatabases()
23:49:38.960 protocolclient.cpp(985) : I [041c] ProtocolClient::DeleteFid(fid=3, mask=0xffff)
23:49:38.960 packets.cpp(49) : I [041c] NEW request
23:49:38.960 DatabaseSyncProtocolObserver.cpp(74) : I [041c] ProgressProtocolObserver::ReportProgress(client=00C5AAD0, activity=6, current=0, maximum=1)
23:49:38.960 DatabaseSyncProtocolObserver.cpp(80) : I [041c] Progress of activity 6 set to 0/1 in CProgressProtocolObserver::ReportProgress.
23:49:38.960 DatabaseSyncProtocolObserver.cpp(86) : I [041c] ProgressProtocolObserver::ReportProgress()
23:49:38.960 packets.cpp(99) : I [041c] Sending response.
23:49:38.960 packets.cpp(106) : I [041c] Sending packet with id=88
23:49:38.960 packets.cpp(688) : I [041c] Request::WaitForReply(header_return=0012F480, packet_id=88)
23:49:38.960 packets.cpp(699) : I [041c] WaitForReply loop
23:49:38.960 packets.cpp(701) : I [041c] Waiting for reply with id 88 and timeout 2
23:49:38.960 packets.cpp(827) : I [041c] Request::Receive(h = 0012F2C4, timeout=2)
23:49:38.960 packets.cpp(840) : I [041c] Trying to receive bytes.
23:49:38.960 packets.cpp(851) : I [041c] Got 95 bytes from connection->Receive
23:49:38.960 packets.cpp(954) : I [041c] Got a PSOH
23:49:38.960 packets.cpp(998) : I [041c] Got packet length: 84 bytes.
23:49:38.960 packets.cpp(1018) : I [041c] We've read all the data we were expecting, reading CRC.
23:49:38.960 packets.cpp(1041) : I [041c] CRC is good.
23:49:38.960 packets.cpp(911) : I [041c] We got a valid packet!
23:49:38.960 packets.cpp(916) : I [041c] End of packet, pushing back 0 bytes
23:49:38.960 packets.cpp(928) : I [041c] Processing complete: result is 0
23:49:38.960 packets.cpp(929) : I [041c] Request::Receive(D) = 0x0
23:49:38.960 packets.cpp(704) : I [041c] Receive result was 0
23:49:38.960 packets.cpp(784) : I [041c] Got a response to packet 88
23:49:38.960 packets.cpp(790) : I [041c] We have a progress reply.
23:49:38.960 packets.cpp(796) : I [041c] Progress reply, new timeout is 2
23:49:38.960 packets.cpp(699) : I [041c] WaitForReply loop
23:49:38.960 packets.cpp(701) : I [041c] Waiting for reply with id 88 and timeout 2
23:49:38.960 packets.cpp(827) : I [041c] Request::Receive(h = 0012F2C4, timeout=2)
23:49:38.960 packets.cpp(840) : I [041c] Trying to receive bytes.
23:49:38.960 packets.cpp(851) : I [041c] Got 15 bytes from connection->Receive
23:49:38.960 packets.cpp(954) : I [041c] Got a PSOH
23:49:38.960 packets.cpp(998) : I [041c] Got packet length: 4 bytes.
23:49:38.960 packets.cpp(1018) : I [041c] We've read all the data we were expecting, reading CRC.
23:49:38.975 packets.cpp(1041) : I [041c] CRC is good.
23:49:38.975 packets.cpp(911) : I [041c] We got a valid packet!
23:49:38.975 packets.cpp(916) : I [041c] End of packet, pushing back 0 bytes
23:49:38.975 packets.cpp(928) : I [041c] Processing complete: result is 0
23:49:38.975 packets.cpp(929) : I [041c] Request::Receive(D) = 0x0
23:49:38.975 packets.cpp(704) : I [041c] Receive result was 0
23:49:38.975 packets.cpp(784) : I [041c] Got a response to packet 88
23:49:38.975 packets.cpp(810) : I [041c] Got a real reply.
23:49:38.975 packets.cpp(814) : I [041c] Packet::WaitForReply(G) = 0x6
23:49:38.975 DatabaseSyncProtocolObserver.cpp(74) : I [041c] ProgressProtocolObserver::ReportProgress(client=00C5AAD0, activity=6, current=1, maximum=1)
23:49:38.975 DatabaseSyncProtocolObserver.cpp(80) : I [041c] Progress of activity 6 set to 1/1 in CProgressProtocolObserver::ReportProgress.
23:49:38.975 DatabaseSyncProtocolObserver.cpp(86) : I [041c] ProgressProtocolObserver::ReportProgress()
23:49:38.975 protocolclient.cpp(1002) : I [041c] ProtocolClient::DeleteFid()=0
23:49:38.975 protocolclient.cpp(985) : I [041c] ProtocolClient::DeleteFid(fid=2, mask=0xffff)
23:49:38.975 packets.cpp(49) : I [041c] NEW request
23:49:38.975 DatabaseSyncProtocolObserver.cpp(74) : I [041c] ProgressProtocolObserver::ReportProgress(client=00C5AAD0, activity=6, current=0, maximum=1)
23:49:38.975 DatabaseSyncProtocolObserver.cpp(80) : I [041c] Progress of activity 6 set to 0/1 in CProgressProtocolObserver::ReportProgress.
23:49:38.975 DatabaseSyncProtocolObserver.cpp(86) : I [041c] ProgressProtocolObserver::ReportProgress()
23:49:38.975 packets.cpp(99) : I [041c] Sending response.
23:49:38.975 packets.cpp(106) : I [041c] Sending packet with id=89
23:49:38.975 packets.cpp(688) : I [041c] Request::WaitForReply(header_return=0012F480, packet_id=89)
23:49:38.975 packets.cpp(699) : I [041c] WaitForReply loop
23:49:38.975 packets.cpp(701) : I [041c] Waiting for reply with id 89 and timeout 2
23:49:38.975 packets.cpp(827) : I [041c] Request::Receive(h = 0012F2C4, timeout=2)
23:49:38.975 packets.cpp(840) : I [041c] Trying to receive bytes.
23:49:38.975 packets.cpp(851) : I [041c] Got 95 bytes from connection->Receive
23:49:38.975 packets.cpp(954) : I [041c] Got a PSOH
23:49:38.975 packets.cpp(998) : I [041c] Got packet length: 84 bytes.
23:49:38.975 packets.cpp(1018) : I [041c] We've read all the data we were expecting, reading CRC.
23:49:38.975 packets.cpp(1041) : I [041c] CRC is good.
23:49:38.975 packets.cpp(911) : I [041c] We got a valid packet!
23:49:38.975 packets.cpp(916) : I [041c] End of packet, pushing back 0 bytes
23:49:38.975 packets.cpp(928) : I [041c] Processing complete: result is 0
23:49:38.975 packets.cpp(929) : I [041c] Request::Receive(D) = 0x0
23:49:38.975 packets.cpp(704) : I [041c] Receive result was 0
23:49:38.975 packets.cpp(784) : I [041c] Got a response to packet 89
23:49:38.975 packets.cpp(790) : I [041c] We have a progress reply.
23:49:38.975 packets.cpp(796) : I [041c] Progress reply, new timeout is 2
23:49:38.975 packets.cpp(699) : I [041c] WaitForReply loop
23:49:38.975 packets.cpp(701) : I [041c] Waiting for reply with id 89 and timeout 2
23:49:38.975 packets.cpp(827) : I [041c] Request::Receive(h = 0012F2C4, timeout=2)
23:49:38.975 packets.cpp(840) : I [041c] Trying to receive bytes.
23:49:38.975 packets.cpp(851) : I [041c] Got 15 bytes from connection->Receive
23:49:38.975 packets.cpp(954) : I [041c] Got a PSOH
23:49:38.975 packets.cpp(998) : I [041c] Got packet length: 4 bytes.
23:49:38.975 packets.cpp(1018) : I [041c] We've read all the data we were expecting, reading CRC.
23:49:38.975 packets.cpp(1041) : I [041c] CRC is good.
23:49:38.975 packets.cpp(911) : I [041c] We got a valid packet!
23:49:38.975 packets.cpp(916) : I [041c] End of packet, pushing back 0 bytes
23:49:38.975 packets.cpp(928) : I [041c] Processing complete: result is 0
23:49:38.975 packets.cpp(929) : I [041c] Request::Receive(D) = 0x0
23:49:38.975 packets.cpp(704) : I [041c] Receive result was 0
23:49:38.975 packets.cpp(784) : I [041c] Got a response to packet 89
23:49:38.975 packets.cpp(810) : I [041c] Got a real reply.
23:49:38.975 packets.cpp(814) : I [041c] Packet::WaitForReply(G) = 0x6
23:49:38.975 DatabaseSyncProtocolObserver.cpp(74) : I [041c] ProgressProtocolObserver::ReportProgress(client=00C5AAD0, activity=6, current=1, maximum=1)
23:49:38.975 DatabaseSyncProtocolObserver.cpp(80) : I [041c] Progress of activity 6 set to 1/1 in CProgressProtocolObserver::ReportProgress.
23:49:38.975 DatabaseSyncProtocolObserver.cpp(86) : I [041c] ProgressProtocolObserver::ReportProgress()
23:49:38.975 protocolclient.cpp(1002) : I [041c] ProtocolClient::DeleteFid()=0
23:49:38.975 protocolclient.cpp(985) : I [041c] ProtocolClient::DeleteFid(fid=5, mask=0xffff)
23:49:38.975 packets.cpp(49) : I [041c] NEW request
23:49:38.975 DatabaseSyncProtocolObserver.cpp(74) : I [041c] ProgressProtocolObserver::ReportProgress(client=00C5AAD0, activity=6, current=0, maximum=1)
23:49:38.975 DatabaseSyncProtocolObserver.cpp(80) : I [041c] Progress of activity 6 set to 0/1 in CProgressProtocolObserver::ReportProgress.
23:49:38.975 DatabaseSyncProtocolObserver.cpp(86) : I [041c] ProgressProtocolObserver::ReportProgress()
23:49:38.975 packets.cpp(99) : I [041c] Sending response.
23:49:38.975 packets.cpp(106) : I [041c] Sending packet with id=90
23:49:38.975 packets.cpp(688) : I [041c] Request::WaitForReply(header_return=0012F480, packet_id=90)
23:49:38.975 packets.cpp(699) : I [041c] WaitForReply loop
23:49:38.975 packets.cpp(701) : I [041c] Waiting for reply with id 90 and timeout 2
23:49:38.975 packets.cpp(827) : I [041c] Request::Receive(h = 0012F2C4, timeout=2)
23:49:38.991 packets.cpp(840) : I [041c] Trying to receive bytes.
23:49:38.991 packets.cpp(851) : I [041c] Got 95 bytes from connection->Receive
23:49:38.991 packets.cpp(954) : I [041c] Got a PSOH
23:49:38.991 packets.cpp(998) : I [041c] Got packet length: 84 bytes.
23:49:38.991 packets.cpp(1018) : I [041c] We've read all the data we were expecting, reading CRC.
23:49:38.991 packets.cpp(1041) : I [041c] CRC is good.
23:49:38.991 packets.cpp(911) : I [041c] We got a valid packet!
23:49:38.991 packets.cpp(916) : I [041c] End of packet, pushing back 0 bytes
23:49:38.991 packets.cpp(928) : I [041c] Processing complete: result is 0
23:49:38.991 packets.cpp(929) : I [041c] Request::Receive(D) = 0x0
23:49:38.991 packets.cpp(704) : I [041c] Receive result was 0
23:49:38.991 packets.cpp(784) : I [041c] Got a response to packet 90
23:49:38.991 packets.cpp(790) : I [041c] We have a progress reply.
23:49:38.991 packets.cpp(796) : I [041c] Progress reply, new timeout is 2
23:49:38.991 packets.cpp(699) : I [041c] WaitForReply loop
23:49:38.991 packets.cpp(701) : I [041c] Waiting for reply with id 90 and timeout 2
23:49:38.991 packets.cpp(827) : I [041c] Request::Receive(h = 0012F2C4, timeout=2)
23:49:38.991 packets.cpp(840) : I [041c] Trying to receive bytes.
23:49:38.991 packets.cpp(851) : I [041c] Got 15 bytes from connection->Receive
23:49:38.991 packets.cpp(954) : I [041c] Got a PSOH
23:49:38.991 packets.cpp(998) : I [041c] Got packet length: 4 bytes.
23:49:38.991 packets.cpp(1018) : I [041c] We've read all the data we were expecting, reading CRC.
23:49:38.991 packets.cpp(1041) : I [041c] CRC is good.
23:49:38.991 packets.cpp(911) : I [041c] We got a valid packet!
23:49:38.991 packets.cpp(916) : I [041c] End of packet, pushing back 0 bytes
23:49:38.991 packets.cpp(928) : I [041c] Processing complete: result is 0
23:49:38.991 packets.cpp(929) : I [041c] Request::Receive(D) = 0x0
23:49:38.991 packets.cpp(704) : I [041c] Receive result was 0
23:49:38.991 packets.cpp(784) : I [041c] Got a response to packet 90
23:49:38.991 packets.cpp(810) : I [041c] Got a real reply.
23:49:38.991 packets.cpp(814) : I [041c] Packet::WaitForReply(G) = 0x6
23:49:38.991 DatabaseSyncProtocolObserver.cpp(74) : I [041c] ProgressProtocolObserver::ReportProgress(client=00C5AAD0, activity=6, current=1, maximum=1)
23:49:38.991 DatabaseSyncProtocolObserver.cpp(80) : I [041c] Progress of activity 6 set to 1/1 in CProgressProtocolObserver::ReportProgress.
23:49:38.991 DatabaseSyncProtocolObserver.cpp(86) : I [041c] ProgressProtocolObserver::ReportProgress()
23:49:38.991 protocolclient.cpp(1002) : I [041c] ProtocolClient::DeleteFid()=0
23:49:38.991 protocolclient.cpp(787) : I [041c] ProtocolClient::DeleteDatabases() = 0
23:49:38.991 DatabaseUpload.cpp(228) : I [041c] Stage 5: Synchronise Modified Fids
23:49:38.991 DatabaseUpload.cpp(294) : I [041c] CSyncDialog::SynchroniseModifiedFids(client = 00C5AAD0)
23:49:38.991 DatabaseUpload.cpp(380) : I [041c] Synchronise is required for FID 120
23:49:38.991 Items.cpp(240) : I [041c] CCompoundItem::Synchronise(fid=120, client=00C5AAD0)
23:49:38.991 protocolclient.cpp(326) : I [041c] ProtocolClient::WriteFidFromMemory(fid=288, psource=00C5AC28, size=4)
23:49:38.991 packets.cpp(49) : I [041c] NEW request
23:49:38.991 DatabaseSyncProtocolObserver.cpp(92) : I [041c] PlaylistProtocolObserver::ReportProgress(client=00C5AAD0, activity=4, current=0, maximum=5)
23:49:38.991 DatabaseSyncProtocolObserver.cpp(100) : I [041c] Progress of activity 4 set to 0/2000 in CPlaylistProtocolObserver::ReportProgress.
23:49:38.991 DatabaseSyncProtocolObserver.cpp(102) : I [041c] Based on values: current_playlist=0, maximum_playlist=2, current=0, maximum=5.
23:49:38.991 DatabaseSyncProtocolObserver.cpp(108) : I [041c] PlaylistProtocolObserver::ReportProgress()
23:49:38.991 packets.cpp(99) : I [041c] Sending response.
23:49:38.991 packets.cpp(106) : I [041c] Sending packet with id=91
23:49:38.991 packets.cpp(688) : I [041c] Request::WaitForReply(header_return=0012F33C, packet_id=91)
23:49:38.991 packets.cpp(699) : I [041c] WaitForReply loop
23:49:38.991 packets.cpp(701) : I [041c] Waiting for reply with id 91 and timeout 2
23:49:38.991 packets.cpp(827) : I [041c] Request::Receive(h = 0012F17C, timeout=2)
23:49:38.991 packets.cpp(840) : I [041c] Trying to receive bytes.
23:49:38.991 packets.cpp(851) : I [041c] Got 95 bytes from connection->Receive
23:49:38.991 packets.cpp(954) : I [041c] Got a PSOH
23:49:38.991 packets.cpp(998) : I [041c] Got packet length: 84 bytes.
23:49:38.991 packets.cpp(1018) : I [041c] We've read all the data we were expecting, reading CRC.
23:49:38.991 packets.cpp(1041) : I [041c] CRC is good.
23:49:38.991 packets.cpp(911) : I [041c] We got a valid packet!
23:49:38.991 packets.cpp(916) : I [041c] End of packet, pushing back 0 bytes
23:49:38.991 packets.cpp(928) : I [041c] Processing complete: result is 0
23:49:38.991 packets.cpp(929) : I [041c] Request::Receive(D) = 0x0
23:49:38.991 packets.cpp(704) : I [041c] Receive result was 0
23:49:38.991 packets.cpp(784) : I [041c] Got a response to packet 91
23:49:38.991 packets.cpp(790) : I [041c] We have a progress reply.
23:49:38.991 packets.cpp(796) : I [041c] Progress reply, new timeout is 2
23:49:38.991 packets.cpp(699) : I [041c] WaitForReply loop
23:49:38.991 packets.cpp(701) : I [041c] Waiting for reply with id 91 and timeout 2
23:49:38.991 packets.cpp(827) : I [041c] Request::Receive(h = 0012F17C, timeout=2)
23:49:38.991 packets.cpp(840) : I [041c] Trying to receive bytes.
23:49:39.006 packets.cpp(851) : I [041c] Got 23 bytes from connection->Receive
23:49:39.006 packets.cpp(954) : I [041c] Got a PSOH
23:49:39.006 packets.cpp(998) : I [041c] Got packet length: 12 bytes.
23:49:39.006 packets.cpp(1018) : I [041c] We've read all the data we were expecting, reading CRC.
23:49:39.006 packets.cpp(1041) : I [041c] CRC is good.
23:49:39.006 packets.cpp(911) : I [041c] We got a valid packet!
23:49:39.006 packets.cpp(916) : I [041c] End of packet, pushing back 0 bytes
23:49:39.006 packets.cpp(928) : I [041c] Processing complete: result is 0
23:49:39.006 packets.cpp(929) : I [041c] Request::Receive(D) = 0x0
23:49:39.006 packets.cpp(704) : I [041c] Receive result was 0
23:49:39.006 packets.cpp(784) : I [041c] Got a response to packet 91
23:49:39.006 packets.cpp(810) : I [041c] Got a real reply.
23:49:39.006 packets.cpp(814) : I [041c] Packet::WaitForReply(G) = 0x6
23:49:39.006 DatabaseSyncProtocolObserver.cpp(92) : I [041c] PlaylistProtocolObserver::ReportProgress(client=00C5AAD0, activity=4, current=1, maximum=5)
23:49:39.006 DatabaseSyncProtocolObserver.cpp(100) : I [041c] Progress of activity 4 set to 200/2000 in CPlaylistProtocolObserver::ReportProgress.
23:49:39.006 DatabaseSyncProtocolObserver.cpp(102) : I [041c] Based on values: current_playlist=0, maximum_playlist=2, current=1, maximum=5.
23:49:39.006 DatabaseSyncProtocolObserver.cpp(108) : I [041c] PlaylistProtocolObserver::ReportProgress()
23:49:39.006 packets.cpp(49) : I [041c] NEW request
23:49:39.006 packets.cpp(318) : I [041c] Request::WriteFid(fid=0x120, offset=0x0, size=0x4, buffer=00C5AC28)
23:49:39.006 packets.cpp(341) : I [041c] Request::GenericWrite(h=0012B224)
23:49:39.006 packets.cpp(99) : I [041c] Sending response.
23:49:39.006 packets.cpp(106) : I [041c] Sending packet with id=92
23:49:39.006 packets.cpp(688) : I [041c] Request::WaitForReply(header_return=0012B1BC, packet_id=92)
23:49:39.022 packets.cpp(699) : I [041c] WaitForReply loop
23:49:39.022 packets.cpp(701) : I [041c] Waiting for reply with id 92 and timeout 2
23:49:39.022 packets.cpp(827) : I [041c] Request::Receive(h = 0012B008, timeout=2)
23:49:39.022 packets.cpp(840) : I [041c] Trying to receive bytes.
23:49:39.022 packets.cpp(851) : I [041c] Got 23 bytes from connection->Receive
23:49:39.022 packets.cpp(954) : I [041c] Got a PSOH
23:49:39.022 packets.cpp(998) : I [041c] Got packet length: 12 bytes.
23:49:39.022 packets.cpp(1018) : I [041c] We've read all the data we were expecting, reading CRC.
23:49:39.022 packets.cpp(1041) : I [041c] CRC is good.
23:49:39.022 packets.cpp(911) : I [041c] We got a valid packet!
23:49:39.022 packets.cpp(916) : I [041c] End of packet, pushing back 0 bytes
23:49:39.022 packets.cpp(928) : I [041c] Processing complete: result is 0
23:49:39.022 packets.cpp(929) : I [041c] Request::Receive(D) = 0x0
23:49:39.022 packets.cpp(704) : I [041c] Receive result was 0
23:49:39.022 packets.cpp(784) : I [041c] Got a response to packet 92
23:49:39.022 packets.cpp(810) : I [041c] Got a real reply.
23:49:39.022 packets.cpp(814) : I [041c] Packet::WaitForReply(G) = 0x6
23:49:39.022 packets.cpp(382) : I [041c] Request::GenericWrite(C) = 4 (0x4)
23:49:39.022 packets.cpp(335) : I [041c] Request::WriteFid() = 4 (0x4)
23:49:39.022 packets.cpp(49) : I [041c] NEW request
23:49:39.022 DatabaseSyncProtocolObserver.cpp(92) : I [041c] PlaylistProtocolObserver::ReportProgress(client=00C5AAD0, activity=2, current=5, maximum=5)
23:49:39.022 DatabaseSyncProtocolObserver.cpp(100) : I [041c] Progress of activity 2 set to 1000/2000 in CPlaylistProtocolObserver::ReportProgress.
23:49:39.022 DatabaseSyncProtocolObserver.cpp(102) : I [041c] Based on values: current_playlist=0, maximum_playlist=2, current=5, maximum=5.
23:49:39.022 DatabaseSyncProtocolObserver.cpp(108) : I [041c] PlaylistProtocolObserver::ReportProgress()
23:49:39.022 DatabaseSyncProtocolObserver.cpp(92) : I [041c] PlaylistProtocolObserver::ReportProgress(client=00C5AAD0, activity=2, current=5, maximum=5)
23:49:39.022 DatabaseSyncProtocolObserver.cpp(100) : I [041c] Progress of activity 2 set to 1000/2000 in CPlaylistProtocolObserver::ReportProgress.
23:49:39.022 DatabaseSyncProtocolObserver.cpp(102) : I [041c] Based on values: current_playlist=0, maximum_playlist=2, current=5, maximum=5.
23:49:39.022 DatabaseSyncProtocolObserver.cpp(108) : I [041c] PlaylistProtocolObserver::ReportProgress()
23:49:39.022 protocolclient.cpp(398) : I [041c] ProtocolClient::WriteFidFromMemory() = 0
23:49:39.022 Items.cpp(28) : I [041c] CPlayableItem::Synchronise(fid=120, client=00C5AAD0)
23:49:39.022 protocolclient.cpp(326) : I [041c] ProtocolClient::WriteFidFromMemory(fid=289, psource=00C59CC9, size=34)
23:49:39.022 packets.cpp(49) : I [041c] NEW request
23:49:39.022 DatabaseSyncProtocolObserver.cpp(92) : I [041c] PlaylistProtocolObserver::ReportProgress(client=00C5AAD0, activity=4, current=0, maximum=42)
23:49:39.022 DatabaseSyncProtocolObserver.cpp(100) : I [041c] Progress of activity 4 set to 0/2000 in CPlaylistProtocolObserver::ReportProgress.
23:49:39.022 DatabaseSyncProtocolObserver.cpp(102) : I [041c] Based on values: current_playlist=0, maximum_playlist=2, current=0, maximum=42.
23:49:39.022 DatabaseSyncProtocolObserver.cpp(108) : I [041c] PlaylistProtocolObserver::ReportProgress()
23:49:39.022 packets.cpp(99) : I [041c] Sending response.
23:49:39.022 packets.cpp(106) : I [041c] Sending packet with id=94
23:49:39.022 packets.cpp(688) : I [041c] Request::WaitForReply(header_return=0012F2F4, packet_id=94)
23:49:39.022 packets.cpp(699) : I [041c] WaitForReply loop
23:49:39.022 packets.cpp(701) : I [041c] Waiting for reply with id 94 and timeout 2
23:49:39.022 packets.cpp(827) : I [041c] Request::Receive(h = 0012F134, timeout=2)
23:49:39.022 packets.cpp(840) : I [041c] Trying to receive bytes.
23:49:39.022 packets.cpp(851) : I [041c] Got 95 bytes from connection->Receive
23:49:39.022 packets.cpp(954) : I [041c] Got a PSOH
23:49:39.022 packets.cpp(998) : I [041c] Got packet length: 84 bytes.
23:49:39.022 packets.cpp(1018) : I [041c] We've read all the data we were expecting, reading CRC.
23:49:39.022 packets.cpp(1041) : I [041c] CRC is good.
23:49:39.022 packets.cpp(911) : I [041c] We got a valid packet!
23:49:39.022 packets.cpp(916) : I [041c] End of packet, pushing back 0 bytes
23:49:39.022 packets.cpp(928) : I [041c] Processing complete: result is 0
23:49:39.022 packets.cpp(929) : I [041c] Request::Receive(D) = 0x0
23:49:39.022 packets.cpp(704) : I [041c] Receive result was 0
23:49:39.022 packets.cpp(784) : I [041c] Got a response to packet 94
23:49:39.022 packets.cpp(790) : I [041c] We have a progress reply.
23:49:39.022 packets.cpp(796) : I [041c] Progress reply, new timeout is 2
23:49:39.022 packets.cpp(699) : I [041c] WaitForReply loop
23:49:39.022 packets.cpp(701) : I [041c] Waiting for reply with id 94 and timeout 2
23:49:39.022 packets.cpp(827) : I [041c] Request::Receive(h = 0012F134, timeout=2)
23:49:39.022 packets.cpp(840) : I [041c] Trying to receive bytes.
23:49:39.038 packets.cpp(851) : I [041c] Got 23 bytes from connection->Receive
23:49:39.038 packets.cpp(954) : I [041c] Got a PSOH
23:49:39.038 packets.cpp(998) : I [041c] Got packet length: 12 bytes.
23:49:39.038 packets.cpp(1018) : I [041c] We've read all the data we were expecting, reading CRC.
23:49:39.038 packets.cpp(1041) : I [041c] CRC is good.
23:49:39.038 packets.cpp(911) : I [041c] We got a valid packet!
23:49:39.038 packets.cpp(916) : I [041c] End of packet, pushing back 0 bytes
23:49:39.038 packets.cpp(928) : I [041c] Processing complete: result is 0
23:49:39.038 packets.cpp(929) : I [041c] Request::Receive(D) = 0x0
23:49:39.038 packets.cpp(704) : I [041c] Receive result was 0
23:49:39.038 packets.cpp(784) : I [041c] Got a response to packet 94
23:49:39.038 packets.cpp(810) : I [041c] Got a real reply.
23:49:39.038 packets.cpp(814) : I [041c] Packet::WaitForReply(G) = 0x6
23:49:39.038 DatabaseSyncProtocolObserver.cpp(92) : I [041c] PlaylistProtocolObserver::ReportProgress(client=00C5AAD0, activity=4, current=8, maximum=42)
23:49:39.038 DatabaseSyncProtocolObserver.cpp(100) : I [041c] Progress of activity 4 set to 190/2000 in CPlaylistProtocolObserver::ReportProgress.
23:49:39.038 DatabaseSyncProtocolObserver.cpp(102) : I [041c] Based on values: current_playlist=0, maximum_playlist=2, current=8, maximum=42.
23:49:39.038 DatabaseSyncProtocolObserver.cpp(108) : I [041c] PlaylistProtocolObserver::ReportProgress()
23:49:39.038 packets.cpp(49) : I [041c] NEW request
23:49:39.038 packets.cpp(318) : I [041c] Request::WriteFid(fid=0x121, offset=0x0, size=0x22, buffer=00C59CC9)
23:49:39.038 packets.cpp(341) : I [041c] Request::GenericWrite(h=0012B1DC)
23:49:39.038 packets.cpp(99) : I [041c] Sending response.
23:49:39.038 packets.cpp(106) : I [041c] Sending packet with id=95
23:49:39.038 packets.cpp(688) : I [041c] Request::WaitForReply(header_return=0012B174, packet_id=95)
23:49:39.038 packets.cpp(699) : I [041c] WaitForReply loop
23:49:39.038 packets.cpp(701) : I [041c] Waiting for reply with id 95 and timeout 2
23:49:39.038 packets.cpp(827) : I [041c] Request::Receive(h = 0012AFC0, timeout=2)
23:49:39.038 packets.cpp(840) : I [041c] Trying to receive bytes.
23:49:39.038 packets.cpp(851) : I [041c] Got 23 bytes from connection->Receive
23:49:39.038 packets.cpp(954) : I [041c] Got a PSOH
23:49:39.038 packets.cpp(998) : I [041c] Got packet length: 12 bytes.
23:49:39.038 packets.cpp(1018) : I [041c] We've read all the data we were expecting, reading CRC.
23:49:39.038 packets.cpp(1041) : I [041c] CRC is good.
23:49:39.038 packets.cpp(911) : I [041c] We got a valid packet!
23:49:39.038 packets.cpp(916) : I [041c] End of packet, pushing back 0 bytes
23:49:39.038 packets.cpp(928) : I [041c] Processing complete: result is 0
23:49:39.038 packets.cpp(929) : I [041c] Request::Receive(D) = 0x0
23:49:39.038 packets.cpp(704) : I [041c] Receive result was 0
23:49:39.038 packets.cpp(784) : I [041c] Got a response to packet 95
23:49:39.038 packets.cpp(810) : I [041c] Got a real reply.
23:49:39.038 packets.cpp(814) : I [041c] Packet::WaitForReply(G) = 0x6
23:49:39.038 packets.cpp(382) : I [041c] Request::GenericWrite(C) = 34 (0x22)
23:49:39.038 packets.cpp(335) : I [041c] Request::WriteFid() = 34 (0x22)
23:49:39.038 packets.cpp(49) : I [041c] NEW request
23:49:39.038 DatabaseSyncProtocolObserver.cpp(92) : I [041c] PlaylistProtocolObserver::ReportProgress(client=00C5AAD0, activity=2, current=42, maximum=42)
23:49:39.053 DatabaseSyncProtocolObserver.cpp(100) : I [041c] Progress of activity 2 set to 1000/2000 in CPlaylistProtocolObserver::ReportProgress.
23:49:39.053 DatabaseSyncProtocolObserver.cpp(102) : I [041c] Based on values: current_playlist=0, maximum_playlist=2, current=42, maximum=42.
23:49:39.053 DatabaseSyncProtocolObserver.cpp(108) : I [041c] PlaylistProtocolObserver::ReportProgress()
23:49:39.053 DatabaseSyncProtocolObserver.cpp(92) : I [041c] PlaylistProtocolObserver::ReportProgress(client=00C5AAD0, activity=2, current=42, maximum=42)
23:49:39.053 DatabaseSyncProtocolObserver.cpp(100) : I [041c] Progress of activity 2 set to 1000/2000 in CPlaylistProtocolObserver::ReportProgress.
23:49:39.053 DatabaseSyncProtocolObserver.cpp(102) : I [041c] Based on values: current_playlist=0, maximum_playlist=2, current=42, maximum=42.
23:49:39.053 DatabaseSyncProtocolObserver.cpp(108) : I [041c] PlaylistProtocolObserver::ReportProgress()
23:49:39.053 protocolclient.cpp(398) : I [041c] ProtocolClient::WriteFidFromMemory() = 0
23:49:39.053 Items.cpp(44) : I [041c] CPlayableItem::Synchronise() = 0
23:49:39.053 Items.cpp(268) : I [041c] CCompoundItem::Synchronise() = 0
23:49:39.053 DatabaseUpload.cpp(380) : I [041c] Synchronise is required for FID 130
23:49:39.053 protocolclient.cpp(415) : I [041c] ProtocolClient::WriteFidFromFile(fid=304, filename=J:\mp3\Less Than Jake\(Less Than Jake) - Dopeman(Beastie Boys Remix).mp3)
23:49:39.053 DatabaseSyncProtocolObserver.cpp(92) : I [041c] PlaylistProtocolObserver::ReportProgress(client=00C5AAD0, activity=4, current=1, maximum=4)
23:49:39.053 DatabaseSyncProtocolObserver.cpp(100) : I [041c] Progress of activity 4 set to 1250/2000 in CPlaylistProtocolObserver::ReportProgress.
23:49:39.053 DatabaseSyncProtocolObserver.cpp(102) : I [041c] Based on values: current_playlist=1, maximum_playlist=2, current=1, maximum=4.
23:49:39.053 DatabaseSyncProtocolObserver.cpp(108) : I [041c] PlaylistProtocolObserver::ReportProgress()
23:49:39.053 DatabaseSyncProtocolObserver.cpp(92) : I [041c] PlaylistProtocolObserver::ReportProgress(client=00C5AAD0, activity=4, current=0, maximum=3041280)
23:49:39.053 DatabaseSyncProtocolObserver.cpp(100) : I [041c] Progress of activity 4 set to 1000/2000 in CPlaylistProtocolObserver::ReportProgress.
23:49:39.053 DatabaseSyncProtocolObserver.cpp(102) : I [041c] Based on values: current_playlist=1, maximum_playlist=2, current=0, maximum=3041280.
23:49:39.053 DatabaseSyncProtocolObserver.cpp(108) : I [041c] PlaylistProtocolObserver::ReportProgress()
23:49:39.053 packets.cpp(49) : I [041c] NEW request
23:49:39.053 packets.cpp(99) : I [041c] Sending response.
23:49:39.053 packets.cpp(106) : I [041c] Sending packet with id=97
23:49:39.053 packets.cpp(688) : I [041c] Request::WaitForReply(header_return=0012F3CC, packet_id=97)
23:49:39.053 packets.cpp(699) : I [041c] WaitForReply loop
23:49:39.053 packets.cpp(701) : I [041c] Waiting for reply with id 97 and timeout 2
23:49:39.053 packets.cpp(827) : I [041c] Request::Receive(h = 0012F20C, timeout=2)
23:49:39.053 packets.cpp(840) : I [041c] Trying to receive bytes.
23:49:39.053 packets.cpp(851) : I [041c] Got 95 bytes from connection->Receive
23:49:39.053 packets.cpp(954) : I [041c] Got a PSOH
23:49:39.053 packets.cpp(998) : I [041c] Got packet length: 84 bytes.
23:49:39.053 packets.cpp(1018) : I [041c] We've read all the data we were expecting, reading CRC.
23:49:39.053 packets.cpp(1041) : I [041c] CRC is good.
23:49:39.053 packets.cpp(911) : I [041c] We got a valid packet!
23:49:39.053 packets.cpp(916) : I [041c] End of packet, pushing back 0 bytes
23:49:39.053 packets.cpp(928) : I [041c] Processing complete: result is 0
23:49:39.053 packets.cpp(929) : I [041c] Request::Receive(D) = 0x0
23:49:39.053 packets.cpp(704) : I [041c] Receive result was 0
23:49:39.053 packets.cpp(784) : I [041c] Got a response to packet 97
23:49:39.053 packets.cpp(790) : I [041c] We have a progress reply.
23:49:39.053 packets.cpp(796) : I [041c] Progress reply, new timeout is 2
23:49:39.053 packets.cpp(699) : I [041c] WaitForReply loop
23:49:39.053 packets.cpp(701) : I [041c] Waiting for reply with id 97 and timeout 2
23:49:39.053 packets.cpp(827) : I [041c] Request::Receive(h = 0012F20C, timeout=2)
23:49:39.053 packets.cpp(840) : I [041c] Trying to receive bytes.
23:49:39.069 packets.cpp(851) : I [041c] Got 23 bytes from connection->Receive
23:49:39.069 packets.cpp(954) : I [041c] Got a PSOH
23:49:39.069 packets.cpp(998) : I [041c] Got packet length: 12 bytes.
23:49:39.069 packets.cpp(1018) : I [041c] We've read all the data we were expecting, reading CRC.
23:49:39.069 packets.cpp(1041) : I [041c] CRC is good.
23:49:39.069 packets.cpp(911) : I [041c] We got a valid packet!
23:49:39.069 packets.cpp(916) : I [041c] End of packet, pushing back 0 bytes
23:49:39.069 packets.cpp(928) : I [041c] Processing complete: result is 0
23:49:39.069 packets.cpp(929) : I [041c] Request::Receive(D) = 0x0
23:49:39.069 packets.cpp(704) : I [041c] Receive result was 0
23:49:39.069 packets.cpp(784) : I [041c] Got a response to packet 97
23:49:39.069 packets.cpp(810) : I [041c] Got a real reply.
23:49:39.069 packets.cpp(814) : I [041c] Packet::WaitForReply(G) = 0x6
23:49:39.069 DatabaseSyncProtocolObserver.cpp(92) : I [041c] PlaylistProtocolObserver::ReportProgress(client=00C5AAD0,