Re: [squid-users] Re: Video played using vlc media player from a windows media server in not getting stored in cache

From: Pavel Kazlenka <pavel.kazlenka_at_measurement-factory.com>
Date: Tue, 17 Sep 2013 14:22:33 +0300

Ok, these two strings matter here, I guess:

>CheckQuickAbort2: YES bad content length
>storeCheckCachable: NO: release requested

if (curlen > expectlen) {
         debugs(90, 3, "CheckQuickAbort2: YES bad content length");

If I understand correctly, squid downloaded from origin server more,
than the server announced. But developers can correct me/explain what's
wrong.

Can you sniff and compare http traffic on proxy server when you watch
video with vlc and with wmp? I'm curious what is the difference that
causes squid to behave in different manner. Command should be something
like:
sudo tcpdump -i any 'port 3128 or 80' -Afnn -s 1024
(I assume that squid is running on port 3128 here).

On 09/17/2013 01:40 PM, susu wrote:
> Hi Pavel,
>
> Here size of the big file is not an issue. If I am playing the same file
> using windows media player, it's getting cached. I have tried the method
> suggested by you. But those messages are not so clear to me. I am posting
> those messages below. Please have a look and tell if you can make out
> something.
>
>
> 2013/09/17 10:44:02| storeClientCopy: 3AC24A8523C630E0017D538FCF058E6D, seen
> 1893429, want 1893429, size 4096, cb 0x423170, cbdata 0x16d8fc48
> 2013/09/17 10:44:02| storeClientCopy2: 3AC24A8523C630E0017D538FCF058E6D
> 2013/09/17 10:44:02| storeClientCopy3: Copying from memory
> 2013/09/17 10:44:02| storeClientCopy: 3AC24A8523C630E0017D538FCF058E6D, seen
> 1895953, want 1895953, size 4096, cb 0x423170, cbdata 0x16d8fc48
> 2013/09/17 10:44:02| storeClientCopy2: 3AC24A8523C630E0017D538FCF058E6D
> 2013/09/17 10:44:02| storeClientCopy3: Waiting for more
> 2013/09/17 10:44:05| storeClientUnregister: called for
> '3AC24A8523C630E0017D538FCF058E6D'
> 2013/09/17 10:44:05| storeClientUnregister: store_client for
> http://10.102.78.163/Police_122.wmv?MSWMExt=.asf has a callback
> 2013/09/17 10:44:05| storeSwapOutMaintainMemObject: lowest_offset = 1895954
> 2013/09/17 10:44:05| storePendingNClients: returning 0
> 2013/09/17 10:44:05| CheckQuickAbort2: entry=0x16f3e930, mem=0x16cdbc90
> 2013/09/17 10:44:05| CheckQuickAbort2: YES bad content length
> 2013/09/17 10:44:05| storeAbort: 3AC24A8523C630E0017D538FCF058E6D
> 2013/09/17 10:44:05| storeLockObject: (store.c:1397): key
> '3AC24A8523C630E0017D538FCF058E6D' count=6
> 2013/09/17 10:44:05| storeExpireNow: '3AC24A8523C630E0017D538FCF058E6D'
> 2013/09/17 10:44:05| storeReleaseRequest: '3AC24A8523C630E0017D538FCF058E6D'
> 2013/09/17 10:44:05| storeDirSwapLog: SWAP_LOG_DEL
> 3AC24A8523C630E0017D538FCF058E6D 0 0000031E
> 2013/09/17 10:44:05| storeKeyPrivate: GET
> http://10.102.78.163/Police_122.wmv?MSWMExt=.asf
> 2013/09/17 10:44:05| storeHashInsert: Inserting Entry 0x16f3e930 key
> 'D6568710A5766951D833459D605758CE'
> 2013/09/17 10:44:05| InvokeHandlers: D6568710A5766951D833459D605758CE
> 2013/09/17 10:44:05| storeSwapOutFileClose: D6568710A5766951D833459D605758CE
> 2013/09/17 10:44:05| storeSwapOutFileClose: sio = 0x16e04518
> 2013/09/17 10:44:05| storeSwapOutFileClosed: SwapOut complete:
> 'http://10.102.78.163/Police_122.wmv?MSWMExt=.asf' to 0, 0000031E
> 2013/09/17 10:44:05| storeCheckCachable: NO: release requested
> 2013/09/17 10:44:05| storeSwapOutFileClosed: store_swapout.c:371
> 2013/09/17 10:44:05| storeSwapOutMaintainMemObject: lowest_offset = 1895954
> 2013/09/17 10:44:05| storeUnlockObject: (store_swapout.c:375): key
> 'D6568710A5766951D833459D605758CE' count=5
> 2013/09/17 10:44:05| storeUnlockObject: (store.c:1422): key
> 'D6568710A5766951D833459D605758CE' count=4
> 2013/09/17 10:44:05| storeUnlockObject: (store_client.c:575): key
> 'D6568710A5766951D833459D605758CE' count=3
> 2013/09/17 10:44:05| storeUnlockObject: (client_side.c:1271): key
> 'D6568710A5766951D833459D605758CE' count=2
> 2013/09/17 10:44:05| storePendingNClients: returning 0
> 2013/09/17 10:44:05| storeUnlockObject: (forward.c:119): key
> 'D6568710A5766951D833459D605758CE' count=1
> 2013/09/17 10:44:05| storeUnlockObject: (http.c:75): key
> 'D6568710A5766951D833459D605758CE' count=0
> 2013/09/17 10:44:05| storePendingNClients: returning 0
> 2013/09/17 10:44:05| storeRelease: Releasing:
> 'D6568710A5766951D833459D605758CE'
> 2013/09/17 10:44:05| destroy_StoreEntry: destroying 0x16f3e930
> 2013/09/17 10:44:05| ctx: enter level 0:
> 'http://10.102.78.163/Police_122.wmv?MSWMExt=.asf'
> 2013/09/17 10:44:05| destroy_MemObject: destroying 0x16cdbc90
>
> Thanks,
> Susu
>
>
>
> --
> View this message in context: http://squid-web-proxy-cache.1019090.n4.nabble.com/Video-played-using-vlc-media-player-from-a-windows-media-server-in-not-getting-stored-in-cache-tp4662172p4662176.html
> Sent from the Squid - Users mailing list archive at Nabble.com.
Received on Tue Sep 17 2013 - 11:22:48 MDT

This archive was generated by hypermail 2.2.0 : Tue Sep 17 2013 - 12:00:04 MDT