Часть лога, сделанный после выполнения на проблемной машине gpupdate /force
20160330.162444.81 db2 [01288 0660] wrk:3 [RepoCache] Total 0 bytes in 0 files dropped
20160330.162444.81 db3 [01288 0660] wrk:3 [LateProc] Delay 10 seconds execution of job 'server::local::ten_sec_job'
20160330.162454.87 db3 [01288 065c] wrk:2 [LateProc] Executing delayed 60 seconds job 'server::local::one_minute_job'
20160330.162454.87 db3 [01288 065c] wrk:2 [Server] Processing one minute event
20160330.162454.87 db3 [01288 065c] wrk:2 [DB] Thread has got connection "approve frozen revisions if needed"
20160330.162454.87 db3 [01288 065c] wrk:2 [DB] Database is free to use
20160330.162454.87 db3 [01288 065c] wrk:2 [SqLite3/1] Statement "BEGIN TRANSACTION"
20160330.162454.87 db3 [01288 065c] wrk:2 [DB] Successful BEGIN transaction, 00.000 wait
20160330.162454.87 db3 [01288 065c] wrk:2 [DB] OK, 00.000, SELECT product,revision,duetime FROM update_srv_delayed WHERE duetime<? ["20160330132454873"]
20160330.162454.87 db3 [01288 065c] wrk:2 [SqLite3/1] Statement "COMMIT"
20160330.162454.87 db3 [01288 065c] wrk:2 [DB] Database has been freed but nobody wants it now
20160330.162454.87 db3 [01288 065c] wrk:2 [DB] Successful COMMIT transaction, 3 statements, 00.000 wait, 00.000 execute, 00.000 commit
20160330.162454.87 db3 [01288 065c] wrk:2 [DB] Successful "approve frozen revisions if needed" transaction committed well
20160330.162454.87 db3 [01288 065c] wrk:2 [DB] Thread has freed connection "approve frozen revisions if needed"
20160330.162454.87 tr0 [01288 065c] wrk:2 [Server] Do not execute "periodic timer" commands from unexistent file "C:/Program Files/DrWeb Server/var/one-minute-commands.drw"
20160330.162454.87 tr0 [01288 065c] wrk:2 [Server] Do not execute "periodic timer" commands from unexistent file "C:/Program Files/DrWeb Server/var/one-minute-commands-once.drw"
20160330.162454.87 db3 [01288 065c] wrk:2 [LateProc] Delay 60 seconds execution of job 'server::local::one_minute_job'
20160330.162454.87 db3 [01288 0664] wrk:4 [LateProc] Executing delayed 10 seconds job 'server::local::ten_sec_job'
20160330.162454.87 db3 [01288 0664] wrk:4 [Server] Processing ten seconds event
20160330.162454.87 db0 [01288 0664] wrk:4 [RepoCache] Clearing loaded files used before 2016/03/30 13:23:24.873
20160330.162454.87 db2 [01288 0664] wrk:4 [RepoCache] Total 0 bytes in 0 files dropped
20160330.162454.87 db3 [01288 0664] wrk:4 [LateProc] Delay 10 seconds execution of job 'server::local::ten_sec_job'
20160330.162500.01 tr2 [01288 02c0] sch:1 [Sch] Regular timer event occured
20160330.162500.01 db3 [01288 02c0] sch:1 [Sch] Next regular event scheduled on 2016-Mar-30 13:26:00 in UTC
20160330.162504.77 db1 [01288 07c8] dbv:1 [Server] DB pool verifier cycle
20160330.162504.77 db3 [01288 07c8] dbv:1 [DB] Thread has got connection "update server status"
20160330.162504.77 db3 [01288 07c8] dbv:1 [DB] Database is free to use
20160330.162504.77 db3 [01288 07c8] dbv:1 [SqLite3/1] Statement "BEGIN TRANSACTION"
20160330.162504.77 db3 [01288 07c8] dbv:1 [DB] Successful BEGIN transaction, 00.000 wait
20160330.162504.77 db2 [01288 07c8] dbv:1 [DB] 1 row changed, 00.000, UPDATE server_status SET marker=?,uptime=?,clients_total=?,clients_maximum=?,total_clients=?,total_agents=?,total_newbies=?,total_installs=?,total_links=?,bytes_in=?,bytes_out=? WHERE id=? [20160330132504774, 1275, 1, 1, 0, 0, 0, 0, 0, 0, 0, "90269110-7df2-e511-14fc-d0207da80db5"]
20160330.162504.77 db3 [01288 07c8] dbv:1 [SqLite3/1] Statement "COMMIT"
20160330.162504.91 db3 [01288 065c] wrk:2 [LateProc] Executing delayed 10 seconds job 'server::local::ten_sec_job'
20160330.162504.91 db3 [01288 065c] wrk:2 [Server] Processing ten seconds event
20160330.162504.91 db0 [01288 065c] wrk:2 [RepoCache] Clearing loaded files used before 2016/03/30 13:23:34.915
20160330.162504.96 db2 [01288 065c] wrk:2 [RepoCache] Total 0 bytes in 0 files dropped
20160330.162504.96 db3 [01288 065c] wrk:2 [LateProc] Delay 10 seconds execution of job 'server::local::ten_sec_job'
20160330.162505.85 db3 [01288 07c8] dbv:1 [DB] Database has been freed but nobody wants it now
20160330.162505.85 db3 [01288 07c8] dbv:1 [DB] Successful COMMIT transaction, 3 statements, 00.000 wait, 01.077 execute, 01.077 commit
20160330.162505.85 db3 [01288 07c8] dbv:1 [DB] Successful "update server status" transaction committed well
20160330.162505.85 db3 [01288 07c8] dbv:1 [DB] Thread has freed connection "update server status"
20160330.162505.85 db2 [01288 07c8] dbv:1 [Server] DB pool verifier scheduled 60 seconds later event
20160330.162515.01 db3 [01288 0664] wrk:4 [LateProc] Executing delayed 10 seconds job 'server::local::ten_sec_job'
20160330.162515.01 db3 [01288 0664] wrk:4 [Server] Processing ten seconds event
20160330.162515.01 db0 [01288 0664] wrk:4 [RepoCache] Clearing loaded files used before 2016/03/30 13:23:45.018
20160330.162515.01 db2 [01288 0664] wrk:4 [RepoCache] Total 0 bytes in 0 files dropped
20160330.162515.01 db3 [01288 0664] wrk:4 [LateProc] Delay 10 seconds execution of job 'server::local::ten_sec_job'
20160330.162525.23 db3 [01288 065c] wrk:2 [LateProc] Executing delayed 10 seconds job 'server::local::ten_sec_job'
20160330.162525.23 db3 [01288 065c] wrk:2 [Server] Processing ten seconds event
20160330.162525.23 db0 [01288 065c] wrk:2 [RepoCache] Clearing loaded files used before 2016/03/30 13:23:55.237
20160330.162525.23 db2 [01288 065c] wrk:2 [RepoCache] Total 0 bytes in 0 files dropped
20160330.162525.23 db3 [01288 065c] wrk:2 [LateProc] Delay 10 seconds execution of job 'server::local::ten_sec_job'
20160330.162535.25 db3 [01288 065c] wrk:2 [LateProc] Executing delayed 10 seconds job 'server::local::ten_sec_job'
20160330.162535.25 db3 [01288 065c] wrk:2 [Server] Processing ten seconds event
20160330.162535.25 db0 [01288 065c] wrk:2 [RepoCache] Clearing loaded files used before 2016/03/30 13:24:05.252
20160330.162535.25 db2 [01288 065c] wrk:2 [RepoCache] Total 0 bytes in 0 files dropped
20160330.162535.25 db3 [01288 065c] wrk:2 [LateProc] Delay 10 seconds execution of job 'server::local::ten_sec_job'
20160330.162545.34 db3 [01288 0664] wrk:4 [LateProc] Executing delayed 10 seconds job 'server::local::ten_sec_job'
20160330.162545.34 db3 [01288 0664] wrk:4 [Server] Processing ten seconds event
20160330.162545.34 db0 [01288 0664] wrk:4 [RepoCache] Clearing loaded files used before 2016/03/30 13:24:15.344
20160330.162545.34 db2 [01288 0664] wrk:4 [RepoCache] Total 0 bytes in 0 files dropped
20160330.162545.34 db3 [01288 0664] wrk:4 [LateProc] Delay 10 seconds execution of job 'server::local::ten_sec_job'
20160330.162555.48 db3 [01288 0668] wrk:5 [LateProc] Executing delayed 60 seconds job 'server::local::one_minute_job'
20160330.162555.48 db3 [01288 0668] wrk:5 [Server] Processing one minute event
20160330.162555.48 db3 [01288 0668] wrk:5 [DB] Thread has got connection "approve frozen revisions if needed"
20160330.162555.48 db3 [01288 0668] wrk:5 [DB] Database is free to use
20160330.162555.48 db3 [01288 0668] wrk:5 [SqLite3/1] Statement "BEGIN TRANSACTION"
20160330.162555.48 db3 [01288 0668] wrk:5 [DB] Successful BEGIN transaction, 00.000 wait
20160330.162555.48 db3 [01288 0668] wrk:5 [DB] OK, 00.000, SELECT product,revision,duetime FROM update_srv_delayed WHERE duetime<? ["20160330132555484"]
20160330.162555.48 db3 [01288 0668] wrk:5 [SqLite3/1] Statement "COMMIT"
20160330.162555.48 db3 [01288 0668] wrk:5 [DB] Database has been freed but nobody wants it now
20160330.162555.48 db3 [01288 0668] wrk:5 [DB] Successful COMMIT transaction, 3 statements, 00.000 wait, 00.000 execute, 00.000 commit
20160330.162555.48 db3 [01288 0668] wrk:5 [DB] Successful "approve frozen revisions if needed" transaction committed well
20160330.162555.48 db3 [01288 0668] wrk:5 [DB] Thread has freed connection "approve frozen revisions if needed"
20160330.162555.48 tr0 [01288 0668] wrk:5 [Server] Do not execute "periodic timer" commands from unexistent file "C:/Program Files/DrWeb Server/var/one-minute-commands.drw"
20160330.162555.48 tr0 [01288 0668] wrk:5 [Server] Do not execute "periodic timer" commands from unexistent file "C:/Program Files/DrWeb Server/var/one-minute-commands-once.drw"
20160330.162555.48 db3 [01288 0668] wrk:5 [LateProc] Delay 60 seconds execution of job 'server::local::one_minute_job'
20160330.162556.18 db3 [01288 065c] wrk:2 [LateProc] Executing delayed 10 seconds job 'server::local::ten_sec_job'
20160330.162556.18 db3 [01288 065c] wrk:2 [Server] Processing ten seconds event
20160330.162556.18 db0 [01288 065c] wrk:2 [RepoCache] Clearing loaded files used before 2016/03/30 13:24:26.187
20160330.162556.18 db2 [01288 065c] wrk:2 [RepoCache] Total 0 bytes in 0 files dropped
20160330.162556.18 db3 [01288 065c] wrk:2 [LateProc] Delay 10 seconds execution of job 'server::local::ten_sec_job'
20160330.162600.46 tr2 [01288 02c0] sch:1 [Sch] Regular timer event occured
20160330.162600.46 db3 [01288 02c0] sch:1 [Sch] Next regular event scheduled on 2016-Mar-30 13:27:00 in UTC
20160330.162606.07 db1 [01288 07c8] dbv:1 [Server] DB pool verifier cycle
20160330.162606.07 db3 [01288 07c8] dbv:1 [DB] Thread has got connection "update server status"
20160330.162606.07 db3 [01288 07c8] dbv:1 [DB] Database is free to use
20160330.162606.07 db3 [01288 07c8] dbv:1 [SqLite3/1] Statement "BEGIN TRANSACTION"
20160330.162606.07 db3 [01288 07c8] dbv:1 [DB] Successful BEGIN transaction, 00.000 wait
20160330.162606.07 db2 [01288 07c8] dbv:1 [DB] 1 row changed, 00.000, UPDATE server_status SET marker=?,uptime=?,clients_total=?,clients_maximum=?,total_clients=?,total_agents=?,total_newbies=?,total_installs=?,total_links=?,bytes_in=?,bytes_out=? WHERE id=? [20160330132606074, 1337, 1, 1, 0, 0, 0, 0, 0, 0, 0, "90269110-7df2-e511-14fc-d0207da80db5"]
20160330.162606.07 db3 [01288 07c8] dbv:1 [SqLite3/1] Statement "COMMIT"
20160330.162606.77 db3 [01288 0668] wrk:5 [LateProc] Executing delayed 10 seconds job 'server::local::ten_sec_job'
20160330.162606.77 db3 [01288 0668] wrk:5 [Server] Processing ten seconds event
20160330.162606.77 db0 [01288 0668] wrk:5 [RepoCache] Clearing loaded files used before 2016/03/30 13:24:36.776
20160330.162606.77 db2 [01288 0668] wrk:5 [RepoCache] Total 0 bytes in 0 files dropped
20160330.162606.77 db3 [01288 0668] wrk:5 [LateProc] Delay 10 seconds execution of job 'server::local::ten_sec_job'
20160330.162609.57 db3 [01288 07c8] dbv:1 [DB] Database has been freed but nobody wants it now
20160330.162609.57 db3 [01288 07c8] dbv:1 [DB] Successful COMMIT transaction, 3 statements, 00.000 wait, 03.496 execute, 03.496 commit
20160330.162609.57 db3 [01288 07c8] dbv:1 [DB] Successful "update server status" transaction committed well
20160330.162609.57 db3 [01288 07c8] dbv:1 [DB] Thread has freed connection "update server status"
20160330.162609.57 db2 [01288 07c8] dbv:1 [Server] DB pool verifier scheduled 60 seconds later event
20160330.162617.28 db3 [01288 065c] wrk:2 [LateProc] Executing delayed 10 seconds job 'server::local::ten_sec_job'
20160330.162617.28 db3 [01288 065c] wrk:2 [Server] Processing ten seconds event
20160330.162617.28 db0 [01288 065c] wrk:2 [RepoCache] Clearing loaded files used before 2016/03/30 13:24:47.282
20160330.162617.28 db2 [01288 065c] wrk:2 [RepoCache] Total 0 bytes in 0 files dropped
20160330.162617.28 db3 [01288 065c] wrk:2 [LateProc] Delay 10 seconds execution of job 'server::local::ten_sec_job'
20160330.162627.69 db3 [01288 0668] wrk:5 [LateProc] Executing delayed 10 seconds job 'server::local::ten_sec_job'
20160330.162627.69 db3 [01288 0668] wrk:5 [Server] Processing ten seconds event
20160330.162627.69 db0 [01288 0668] wrk:5 [RepoCache] Clearing loaded files used before 2016/03/30 13:24:57.695
20160330.162627.69 db2 [01288 0668] wrk:5 [RepoCache] Total 0 bytes in 0 files dropped
20160330.162627.69 db3 [01288 0668] wrk:5 [LateProc] Delay 10 seconds execution of job 'server::local::ten_sec_job'
20160330.162632.36 tr0 [01288 0670] net:2 [Net2/Server] tcp://192.168.98.115:2193: new connection "tcp://192.168.98.93:49383" accepted
20160330.162632.36 db3 [01288 0670] net:2 [Net2] tcp://192.168.98.93:49383: switch off Nagle's algorithm
20160330.162632.36 db3 [01288 0670] net:2 [Net2] tcp://192.168.98.93:49383: set non-blocking socket mode
20160330.162632.36 tr0 [01288 0668] wrk:5 [Layer/Listener] Activation connection #0
20160330.162632.36 ERR [01288 0668] wrk:5 [Layer/Listener] Unable to create initial client instance because of
20160330.162632.36 ERR [01288 0668] wrk:5 unknown protocol <>
20160330.162632.36 ERR [01288 0668] wrk:5 [Layer/Listener] tcp://192.168.98.93:49383: connection dropped because of unable to create initial client instance
20160330.162632.36 tr3 [01288 0668] wrk:5 [Layer/TextProto] tcp://192.168.98.93:49383: snd <0 VIOLATION Please try later! Unable to create client instance>