Bug#793473: marked as done (squid3: segfault when ftp passive mode is not available)

Sun, 18 Dec 2016 16:09:29 -0800 

Your message dated Mon, 19 Dec 2016 00:07:24 +0000
with message-id <[email protected]>
and subject line Bug#793473: fixed in squid3 3.5.23-1
has caused the Debian Bug report #793473,
regarding squid3: segfault when ftp passive mode is not available
to be marked as done.

This means that you claim that the problem has been dealt with.
If this is not the case it is now your responsibility to reopen the
Bug report if necessary, and/or fix the problem forthwith.

(NB: If you are a system administrator and have no idea what this
message is talking about, this may indicate a serious mail system
misconfiguration somewhere. Please contact [email protected]
immediately.)


-- 
793473: http://bugs.debian.org/cgi-bin/bugreport.cgi?bug=793473
Debian Bug Tracking System
Contact [email protected] with problems
--- Begin Message --- 
Package: squid3
Version: 3.4.8-6
Severity: normal

Dear Maintainer,

The squid3 exits with segfault when trying to retrieve content from remote FTP
site where the PASV and EPSV commands are forbidden.

The default configuration file is not modified, just the "localnet".





-- System Information:
Debian Release: 8.1
  APT prefers proposed-updates
  APT policy: (500, 'proposed-updates'), (500, 'stable')
Architecture: i386 (i686)

Kernel: Linux 3.2.0-4-686-pae (SMP w/2 CPU cores)
Locale: LANG=en_US.UTF-8, LC_CTYPE=en_US.UTF-8 (charmap=UTF-8)
Shell: /bin/sh linked to /bin/bash
Init: sysvinit (via /sbin/init)

Versions of packages squid3 depends on:
ii  adduser                  3.113+nmu3
ii  libc6                    2.19-18
ii  libcap2                  1:2.24-8
ii  libcomerr2               1.42.12-1.1
ii  libdb5.3                 5.3.28-9
ii  libecap2                 0.2.0-3
ii  libexpat1                2.1.0-6+b3
ii  libgcc1                  1:4.9.2-10
ii  libgssapi-krb5-2         1.12.1+dfsg-19
ii  libk5crypto3             1.12.1+dfsg-19
ii  libkrb5-3                1.12.1+dfsg-19
ii  libldap-2.4-2            2.4.40+dfsg-1
ii  libltdl7                 2.4.2-1.11
ii  libnetfilter-conntrack3  1.0.4-1
ii  libnettle4               2.7.1-5
ii  libpam0g                 1.1.8-3.1
ii  libsasl2-2               2.1.26.dfsg1-13
ii  libstdc++6               4.9.2-10
ii  libxml2                  2.9.1+dfsg1-5
ii  logrotate                3.8.7-1+b1
ii  lsb-base                 4.1+Debian13+nmu1
ii  netbase                  5.3
ii  squid3-common            3.4.8-6

squid3 recommends no packages.

Versions of packages squid3 suggests:
pn  resolvconf   <none>
ii  smbclient    2:4.1.17+dfsg-2
pn  squid-cgi    <none>
pn  squid-purge  <none>
pn  squidclient  <none>
pn  ufw          <none>
pn  winbindd     <none>

-- Configuration Files:
/etc/squid3/squid.conf changed [not included]

[pid  4356] write(5, "2015/07/24 12:45:19.729 kid1| Write.cc(60) HandleWrite: 
local=<localip>:54238 remote=<remoteip>:2121 FD 12 flags=1: off 0, sz 15.\n", 
132) = 132
[pid  4356] write(12, "SIZE test.txt\r\n", 15) = 15
[pid  4356] gettimeofday({1437734719, 729624}, NULL) = 0
[pid  4356] stat64("/etc/localtime", {st_mode=S_IFREG|0644, st_size=2379, ...}) 
= 0
[pid  4356] write(5, "2015/07/24 12:45:19.729 kid1| Write.cc(100) HandleWrite: 
write() returns 15\n", 76) = 76
[pid  4356] gettimeofday({1437734719, 729862}, NULL) = 0
[pid  4356] stat64("/etc/localtime", {st_mode=S_IFREG|0644, st_size=2379, ...}) 
= 0
[pid  4356] write(5, "2015/07/24 12:45:19.729 kid1| IoCallback.cc(108) finish: 
called for local=<localip>:54238 remote=<remoteip>:2121 FD 12 flags=1 (0, 
0)\n", 136) = 136
[pid  4356] gettimeofday({1437734719, 730082}, NULL) = 0
[pid  4356] stat64("/etc/localtime", {st_mode=S_IFREG|0644, st_size=2379, ...}) 
= 0
[pid  4356] write(5, "2015/07/24 12:45:19.730 kid1| AsyncCall.cc(85) 
ScheduleCall: IoCallback.cc(127) will call 
FtpStateData::ftpWriteCommandCallback(local=<localip>:54238 
remote=<remoteip>:2121 FD 12 flags=1, data=0xb8590810, size=15, buf=0xb81e0d98) 
[call214]\n", 242) = 242
[pid  4356] gettimeofday({1437734719, 730377}, NULL) = 0
[pid  4356] gettimeofday({1437734719, 730454}, NULL) = 0
[pid  4356] stat64("/etc/localtime", {st_mode=S_IFREG|0644, st_size=2379, ...}) 
= 0
[pid  4356] write(5, "2015/07/24 12:45:19.730 kid1| AsyncCallQueue.cc(51) 
fireNext: entering FtpStateData::ftpWriteCommandCallback(local=<localip>:54238 
remote=<remoteip>:2121 FD 12 flags=1, data=0xb8590810, size=15, 
buf=0xb81e0d98)\n", 213) = 213
[pid  4356] gettimeofday({1437734719, 730836}, NULL) = 0
[pid  4356] stat64("/etc/localtime", {st_mode=S_IFREG|0644, st_size=2379, ...}) 
= 0
[pid  4356] write(5, "2015/07/24 12:45:19.730 kid1| AsyncCall.cc(30) make: make 
call FtpStateData::ftpWriteCommandCallback [call214]\n", 111) = 111
[pid  4356] gettimeofday({1437734719, 731047}, NULL) = 0
[pid  4356] stat64("/etc/localtime", {st_mode=S_IFREG|0644, st_size=2379, ...}) 
= 0
[pid  4356] write(5, "2015/07/24 12:45:19.731 kid1| AsyncJob.cc(117) callStart: 
FtpStateData status in: [ job5]\n", 90) = 90
[pid  4356] gettimeofday({1437734719, 731239}, NULL) = 0
[pid  4356] stat64("/etc/localtime", {st_mode=S_IFREG|0644, st_size=2379, ...}) 
= 0
[pid  4356] write(5, "2015/07/24 12:45:19.731 kid1| ftp.cc(1597) 
ftpWriteCommandCallback: ftpWriteCommandCallback: wrote 15 bytes\n", 108) = 108
[pid  4356] gettimeofday({1437734719, 731454}, NULL) = 0
[pid  4356] stat64("/etc/localtime", {st_mode=S_IFREG|0644, st_size=2379, ...}) 
= 0
[pid  4356] write(5, "2015/07/24 12:45:19.731 kid1| AsyncJob.cc(146) callEnd: 
FtpStateData status out: [ job5]\n", 89) = 89
[pid  4356] gettimeofday({1437734719, 731667}, NULL) = 0
[pid  4356] stat64("/etc/localtime", {st_mode=S_IFREG|0644, st_size=2379, ...}) 
= 0
[pid  4356] write(5, "2015/07/24 12:45:19.731 kid1| AsyncCallQueue.cc(53) 
fireNext: leaving FtpStateData::ftpWriteCommandCallback(local=<localip>:54238 
remote=<remoteip>:2121 FD 12 flags=1, data=0xb8590810, size=15, 
buf=0xb81e0d98)\n", 212) = 212
[pid  4356] epoll_wait(6, {{EPOLLIN|EPOLLOUT, {u32=12, 
u64=13176301056662962188}}}, 4096, 104) = 1
[pid  4356] gettimeofday({1437734719, 731916}, NULL) = 0
[pid  4356] read(12, "213 5\r\n", 4096) = 7
[pid  4356] gettimeofday({1437734719, 731980}, NULL) = 0
[pid  4356] stat64("/etc/localtime", {st_mode=S_IFREG|0644, st_size=2379, ...}) 
= 0
[pid  4356] write(5, "2015/07/24 12:45:19.731 kid1| comm.cc(138) 
commHandleRead: comm_read_try: FD 12, size 4096, retval 7, errno 0\n", 110) = 
110
[pid  4356] gettimeofday({1437734719, 732084}, NULL) = 0
[pid  4356] stat64("/etc/localtime", {st_mode=S_IFREG|0644, st_size=2379, ...}) 
= 0
[pid  4356] write(5, "2015/07/24 12:45:19.732 kid1| IoCallback.cc(108) finish: 
called for local=<localip>:54238 remote=<remoteip>:2121 FD 12 flags=1 (0, 
0)\n", 136) = 136
[pid  4356] gettimeofday({1437734719, 732190}, NULL) = 0
[pid  4356] stat64("/etc/localtime", {st_mode=S_IFREG|0644, st_size=2379, ...}) 
= 0
[pid  4356] write(5, "2015/07/24 12:45:19.732 kid1| AsyncCall.cc(85) 
ScheduleCall: IoCallback.cc(127) will call 
FtpStateData::ftpReadControlReply(local=<localip>:54238 remote=<remoteip>:2121 
FD 12 flags=1, data=0xb8590810, size=7, buf=0xb85949f0) [call216]\n", 237) = 237
[pid  4356] gettimeofday({1437734719, 732358}, NULL) = 0
[pid  4356] stat64("/etc/localtime", {st_mode=S_IFREG|0644, st_size=2379, ...}) 
= 0
[pid  4356] write(5, "2015/07/24 12:45:19.732 kid1| ModEpoll.cc(139) SetSelect: 
FD 12, type=2, handler=0, client_data=0, timeout=0\n", 109) = 109
[pid  4356] epoll_ctl(6, EPOLL_CTL_MOD, 12, {EPOLLIN|EPOLLERR|EPOLLHUP, 
{u32=12, u64=12}}) = 0
[pid  4356] gettimeofday({1437734719, 732656}, NULL) = 0
[pid  4356] gettimeofday({1437734719, 732735}, NULL) = 0
[pid  4356] stat64("/etc/localtime", {st_mode=S_IFREG|0644, st_size=2379, ...}) 
= 0
[pid  4356] write(5, "2015/07/24 12:45:19.732 kid1| AsyncCallQueue.cc(51) 
fireNext: entering FtpStateData::ftpReadControlReply(local=<localip>:54238 
remote=<remoteip>:2121 FD 12 flags=1, data=0xb8590810, size=7, 
buf=0xb85949f0)\n", 208) = 208
[pid  4356] gettimeofday({1437734719, 732960}, NULL) = 0
[pid  4356] stat64("/etc/localtime", {st_mode=S_IFREG|0644, st_size=2379, ...}) 
= 0
[pid  4356] write(5, "2015/07/24 12:45:19.732 kid1| AsyncCall.cc(30) make: make 
call FtpStateData::ftpReadControlReply [call216]\n", 107) = 107
[pid  4356] gettimeofday({1437734719, 733187}, NULL) = 0
[pid  4356] stat64("/etc/localtime", {st_mode=S_IFREG|0644, st_size=2379, ...}) 
= 0
[pid  4356] write(5, "2015/07/24 12:45:19.733 kid1| AsyncJob.cc(117) callStart: 
FtpStateData status in: [ job5]\n", 90) = 90
[pid  4356] gettimeofday({1437734719, 733407}, NULL) = 0
[pid  4356] stat64("/etc/localtime", {st_mode=S_IFREG|0644, st_size=2379, ...}) 
= 0
[pid  4356] write(5, "2015/07/24 12:45:19.733 kid1| ftp.cc(1739) 
ftpReadControlReply: ftpReadControlReply: FD 12, Read 7 bytes\n", 105) = 105
[pid  4356] gettimeofday({1437734719, 734462}, NULL) = 0
[pid  4356] stat64("/etc/localtime", {st_mode=S_IFREG|0644, st_size=2379, ...}) 
= 0
[pid  4356] write(5, "2015/07/24 12:45:19.734 kid1| ftp.cc(1630) 
ftpParseControlReply: \n", 66) = 66
[pid  4356] gettimeofday({1437734719, 734710}, NULL) = 0
[pid  4356] stat64("/etc/localtime", {st_mode=S_IFREG|0644, st_size=2379, ...}) 
= 0
[pid  4356] write(5, "2015/07/24 12:45:19.734 kid1| ftp.cc(1643) 
ftpParseControlReply: usable = 6\n", 76) = 76
[pid  4356] gettimeofday({1437734719, 734931}, NULL) = 0
[pid  4356] stat64("/etc/localtime", {st_mode=S_IFREG|0644, st_size=2379, ...}) 
= 0
[pid  4356] write(5, "2015/07/24 12:45:19.734 kid1| ftp.cc(1651) 
ftpParseControlReply: 7 bytes to play with\n", 86) = 86
[pid  4356] gettimeofday({1437734719, 735177}, NULL) = 0
[pid  4356] stat64("/etc/localtime", {st_mode=S_IFREG|0644, st_size=2379, ...}) 
= 0
[pid  4356] write(5, "2015/07/24 12:45:19.735 kid1| ftp.cc(1660) 
ftpParseControlReply: s = {213 5\r\n}\n", 79) = 79
[pid  4356] gettimeofday({1437734719, 735408}, NULL) = 0
[pid  4356] stat64("/etc/localtime", {st_mode=S_IFREG|0644, st_size=2379, ...}) 
= 0
[pid  4356] write(5, "2015/07/24 12:45:19.735 kid1| ftp.cc(1686) 
ftpParseControlReply: ftp>> 213 5\n", 77) = 77
[pid  4356] gettimeofday({1437734719, 735643}, NULL) = 0
[pid  4356] stat64("/etc/localtime", {st_mode=S_IFREG|0644, st_size=2379, ...}) 
= 0
[pid  4356] write(5, "2015/07/24 12:45:19.735 kid1| ftp.cc(1837) 
handleControlReply: state=5, code=213\n", 81) = 81
[pid  4356] gettimeofday({1437734719, 735837}, NULL) = 0
[pid  4356] stat64("/etc/localtime", {st_mode=S_IFREG|0644, st_size=2379, ...}) 
= 0
[pid  4356] write(5, "2015/07/24 12:45:19.735 kid1| ftp.cc(2329) ftpReadSize: 
\n", 57) = 57
[pid  4356] gettimeofday({1437734719, 736033}, NULL) = 0
[pid  4356] stat64("/etc/localtime", {st_mode=S_IFREG|0644, st_size=2379, ...}) 
= 0
[pid  4356] write(5, "2015/07/24 12:45:19.736 kid1| ftp.cc(3394) unhack: \n", 
52) = 52
[pid  4356] gettimeofday({1437734719, 736234}, NULL) = 0
[pid  4356] stat64("/etc/localtime", {st_mode=S_IFREG|0644, st_size=2379, ...}) 
= 0
[pid  4356] write(5, "2015/07/24 12:45:19.736 kid1| ftp.cc(2495) 
ftpSendPassive: \n", 60) = 60
[pid  4356] gettimeofday({1437734719, 736418}, NULL) = 0
[pid  4356] stat64("/etc/localtime", {st_mode=S_IFREG|0644, st_size=2379, ...}) 
= 0
[pid  4356] write(5, "2015/07/24 12:45:19.736 kid1| ftp.cc(2582) 
ftpSendPassive: Channel (<remoteip>:2121). Sending default EPSV 1\n", 110) = 110
[pid  4356] gettimeofday({1437734719, 736608}, NULL) = 0
[pid  4356] stat64("/etc/localtime", {st_mode=S_IFREG|0644, st_size=2379, ...}) 
= 0
[pid  4356] write(5, "2015/07/24 12:45:19.736 kid1| ftp.cc(1567) writeCommand: 
ftp<< EPSV 1\r\n\n", 72) = 72
[pid  4356] gettimeofday({1437734719, 736805}, NULL) = 0
[pid  4356] stat64("/etc/localtime", {st_mode=S_IFREG|0644, st_size=2379, ...}) 
= 0
[pid  4356] write(5, "2015/07/24 12:45:19.736 kid1| AsyncCall.cc(18) AsyncCall: 
The AsyncCall FtpStateData::ftpWriteCommandCallback constructed, 
this=0xb8596a00 [call217]\n", 149) = 149
[pid  4356] gettimeofday({1437734719, 736994}, NULL) = 0
[pid  4356] stat64("/etc/localtime", {st_mode=S_IFREG|0644, st_size=2379, ...}) 
= 0
[pid  4356] write(5, "2015/07/24 12:45:19.736 kid1| Write.cc(29) Write: 
local=<localip>:54238 remote=<remoteip>:2121 FD 12 flags=1: sz 8: asynCall 
0xb8596a00*1\n", 140) = 140
[pid  4356] gettimeofday({1437734719, 737203}, NULL) = 0
[pid  4356] stat64("/etc/localtime", {st_mode=S_IFREG|0644, st_size=2379, ...}) 
= 0
[pid  4356] write(5, "2015/07/24 12:45:19.737 kid1| ModEpoll.cc(139) SetSelect: 
FD 12, type=2, handler=1, client_data=0xb4fe4424, timeout=0\n", 118) = 118
[pid  4356] epoll_ctl(6, EPOLL_CTL_MOD, 12, 
{EPOLLIN|EPOLLOUT|EPOLLERR|EPOLLHUP, {u32=12, u64=13176301056662962188}}) = 0
[pid  4356] gettimeofday({1437734719, 737463}, NULL) = 0
[pid  4356] stat64("/etc/localtime", {st_mode=S_IFREG|0644, st_size=2379, ...}) 
= 0
[pid  4356] write(5, "2015/07/24 12:45:19.737 kid1| ftp.cc(1713) 
scheduleReadControlReply: local=<localip>:54238 remote=<remoteip>:2121 FD 12 
flags=1\n", 130) = 130
[pid  4356] gettimeofday({1437734719, 737666}, NULL) = 0
[pid  4356] stat64("/etc/localtime", {st_mode=S_IFREG|0644, st_size=2379, ...}) 
= 0
[pid  4356] write(5, "2015/07/24 12:45:19.737 kid1| AsyncCall.cc(18) AsyncCall: 
The AsyncCall FtpStateData::ftpTimeout constructed, this=0xb8596ac8 
[call218]\n", 136) = 136
[pid  4356] gettimeofday({1437734719, 737851}, NULL) = 0
[pid  4356] stat64("/etc/localtime", {st_mode=S_IFREG|0644, st_size=2379, ...}) 
= 0
[pid  4356] write(5, "2015/07/24 12:45:19.737 kid1| comm.cc(768) 
commSetConnTimeout: local=<localip>:54238 remote=<remoteip>:2121 FD 12 flags=1 
timeout 900\n", 136) = 136
[pid  4356] gettimeofday({1437734719, 738032}, NULL) = 0
[pid  4356] stat64("/etc/localtime", {st_mode=S_IFREG|0644, st_size=2379, ...}) 
= 0
[pid  4356] write(5, "2015/07/24 12:45:19.738 kid1| AsyncCall.cc(18) AsyncCall: 
The AsyncCall FtpStateData::ftpReadControlReply constructed, this=0xb8596b18 
[call219]\n", 145) = 145
[pid  4356] gettimeofday({1437734719, 738235}, NULL) = 0
[pid  4356] stat64("/etc/localtime", {st_mode=S_IFREG|0644, st_size=2379, ...}) 
= 0
[pid  4356] write(5, "2015/07/24 12:45:19.738 kid1| comm.cc(167) comm_read: 
comm_read, queueing read for local=<localip>:54238 remote=<remoteip>:2121 FD 12 
flags=1; asynCall 0xb8596b18*1\n", 167) = 167
[pid  4356] gettimeofday({1437734719, 738414}, NULL) = 0
[pid  4356] stat64("/etc/localtime", {st_mode=S_IFREG|0644, st_size=2379, ...}) 
= 0
[pid  4356] write(5, "2015/07/24 12:45:19.738 kid1| ModEpoll.cc(139) SetSelect: 
FD 12, type=1, handler=1, client_data=0xb4fe43fc, timeout=0\n", 118) = 118
[pid  4356] gettimeofday({1437734719, 738610}, NULL) = 0
[pid  4356] stat64("/etc/localtime", {st_mode=S_IFREG|0644, st_size=2379, ...}) 
= 0
[pid  4356] write(5, "2015/07/24 12:45:19.738 kid1| AsyncCall.cc(18) AsyncCall: 
The AsyncCall FtpStateData::ftpTimeout constructed, this=0xb8596b70 
[call220]\n", 136) = 136
[pid  4356] gettimeofday({1437734719, 738793}, NULL) = 0
[pid  4356] stat64("/etc/localtime", {st_mode=S_IFREG|0644, st_size=2379, ...}) 
= 0
[pid  4356] write(5, "2015/07/24 12:45:19.738 kid1| comm.cc(768) 
commSetConnTimeout: local=<localip>:54238 remote=<remoteip>:2121 FD 12 flags=1 
timeout 60\n", 135) = 135
[pid  4356] gettimeofday({1437734719, 738985}, NULL) = 0
[pid  4356] stat64("/etc/localtime", {st_mode=S_IFREG|0644, st_size=2379, ...}) 
= 0
[pid  4356] write(5, "2015/07/24 12:45:19.738 kid1| AsyncJob.cc(146) callEnd: 
FtpStateData status out: [ job5]\n", 89) = 89
[pid  4356] gettimeofday({1437734719, 739172}, NULL) = 0
[pid  4356] stat64("/etc/localtime", {st_mode=S_IFREG|0644, st_size=2379, ...}) 
= 0
[pid  4356] write(5, "2015/07/24 12:45:19.739 kid1| AsyncCallQueue.cc(53) 
fireNext: leaving FtpStateData::ftpReadControlReply(local=<localip>:54238 
remote=<remoteip>:2121 FD 12 flags=1, data=0xb8590810, size=7, 
buf=0xb85949f0)\n", 207) = 207
[pid  4356] epoll_wait(6, {{EPOLLOUT, {u32=12, u64=13176301056662962188}}}, 
4096, 96) = 1
[pid  4356] gettimeofday({1437734719, 739430}, NULL) = 0
[pid  4356] gettimeofday({1437734719, 739489}, NULL) = 0
[pid  4356] stat64("/etc/localtime", {st_mode=S_IFREG|0644, st_size=2379, ...}) 
= 0
[pid  4356] write(5, "2015/07/24 12:45:19.739 kid1| Write.cc(60) HandleWrite: 
local=<localip>:54238 remote=<remoteip>:2121 FD 12 flags=1: off 0, sz 8.\n", 
131) = 131
[pid  4356] write(12, "EPSV 1\r\n", 8)  = 8
[pid  4356] gettimeofday({1437734719, 739747}, NULL) = 0
[pid  4356] stat64("/etc/localtime", {st_mode=S_IFREG|0644, st_size=2379, ...}) 
= 0
[pid  4356] write(5, "2015/07/24 12:45:19.739 kid1| Write.cc(100) HandleWrite: 
write() returns 8\n", 75) = 75
[pid  4356] gettimeofday({1437734719, 739992}, NULL) = 0
[pid  4356] stat64("/etc/localtime", {st_mode=S_IFREG|0644, st_size=2379, ...}) 
= 0
[pid  4356] write(5, "2015/07/24 12:45:19.739 kid1| IoCallback.cc(108) finish: 
called for local=<localip>:54238 remote=<remoteip>:2121 FD 12 flags=1 (0, 
0)\n", 136) = 136
[pid  4356] gettimeofday({1437734719, 740195}, NULL) = 0
[pid  4356] stat64("/etc/localtime", {st_mode=S_IFREG|0644, st_size=2379, ...}) 
= 0
[pid  4356] write(5, "2015/07/24 12:45:19.740 kid1| AsyncCall.cc(85) 
ScheduleCall: IoCallback.cc(127) will call 
FtpStateData::ftpWriteCommandCallback(local=<localip>:54238 
remote=<remoteip>:2121 FD 12 flags=1, data=0xb8590810, size=8, buf=0xb8590460) 
[call217]\n", 241) = 241
[pid  4356] gettimeofday({1437734719, 740389}, NULL) = 0
[pid  4356] gettimeofday({1437734719, 740446}, NULL) = 0
[pid  4356] stat64("/etc/localtime", {st_mode=S_IFREG|0644, st_size=2379, ...}) 
= 0
[pid  4356] write(5, "2015/07/24 12:45:19.740 kid1| AsyncCallQueue.cc(51) 
fireNext: entering FtpStateData::ftpWriteCommandCallback(local=<localip>:54238 
remote=<remoteip>:2121 FD 12 flags=1, data=0xb8590810, size=8, 
buf=0xb8590460)\n", 212) = 212
[pid  4356] gettimeofday({1437734719, 740646}, NULL) = 0
[pid  4356] stat64("/etc/localtime", {st_mode=S_IFREG|0644, st_size=2379, ...}) 
= 0
[pid  4356] write(5, "2015/07/24 12:45:19.740 kid1| AsyncCall.cc(30) make: make 
call FtpStateData::ftpWriteCommandCallback [call217]\n", 111) = 111
[pid  4356] gettimeofday({1437734719, 740877}, NULL) = 0
[pid  4356] stat64("/etc/localtime", {st_mode=S_IFREG|0644, st_size=2379, ...}) 
= 0
[pid  4356] write(5, "2015/07/24 12:45:19.740 kid1| AsyncJob.cc(117) callStart: 
FtpStateData status in: [ job5]\n", 90) = 90
[pid  4356] gettimeofday({1437734719, 741062}, NULL) = 0
[pid  4356] stat64("/etc/localtime", {st_mode=S_IFREG|0644, st_size=2379, ...}) 
= 0
[pid  4356] write(5, "2015/07/24 12:45:19.741 kid1| ftp.cc(1597) 
ftpWriteCommandCallback: ftpWriteCommandCallback: wrote 8 bytes\n", 107) = 107
[pid  4356] gettimeofday({1437734719, 741265}, NULL) = 0
[pid  4356] stat64("/etc/localtime", {st_mode=S_IFREG|0644, st_size=2379, ...}) 
= 0
[pid  4356] write(5, "2015/07/24 12:45:19.741 kid1| AsyncJob.cc(146) callEnd: 
FtpStateData status out: [ job5]\n", 89) = 89
[pid  4356] gettimeofday({1437734719, 741512}, NULL) = 0
[pid  4356] stat64("/etc/localtime", {st_mode=S_IFREG|0644, st_size=2379, ...}) 
= 0
[pid  4356] write(5, "2015/07/24 12:45:19.741 kid1| AsyncCallQueue.cc(53) 
fireNext: leaving FtpStateData::ftpWriteCommandCallback(local=<localip>:54238 
remote=<remoteip>:2121 FD 12 flags=1, data=0xb8590810, size=8, 
buf=0xb8590460)\n", 211) = 211
[pid  4356] epoll_wait(6, {{EPOLLIN|EPOLLOUT, {u32=12, 
u64=13176301056662962188}}}, 4096, 94) = 1
[pid  4356] gettimeofday({1437734719, 741826}, NULL) = 0
[pid  4356] read(12, "501 EPSV: Operation not permitted\r\n", 4096) = 35
[pid  4356] gettimeofday({1437734719, 741984}, NULL) = 0
[pid  4356] stat64("/etc/localtime", {st_mode=S_IFREG|0644, st_size=2379, ...}) 
= 0
[pid  4356] write(5, "2015/07/24 12:45:19.741 kid1| comm.cc(138) 
commHandleRead: comm_read_try: FD 12, size 4096, retval 35, errno 0\n", 111) = 
111
[pid  4356] gettimeofday({1437734719, 742186}, NULL) = 0
[pid  4356] stat64("/etc/localtime", {st_mode=S_IFREG|0644, st_size=2379, ...}) 
= 0
[pid  4356] write(5, "2015/07/24 12:45:19.742 kid1| IoCallback.cc(108) finish: 
called for local=<localip>:54238 remote=<remoteip>:2121 FD 12 flags=1 (0, 
0)\n", 136) = 136
[pid  4356] gettimeofday({1437734719, 742435}, NULL) = 0
[pid  4356] stat64("/etc/localtime", {st_mode=S_IFREG|0644, st_size=2379, ...}) 
= 0
[pid  4356] write(5, "2015/07/24 12:45:19.742 kid1| AsyncCall.cc(85) 
ScheduleCall: IoCallback.cc(127) will call 
FtpStateData::ftpReadControlReply(local=<localip>:54238 remote=<remoteip>:2121 
FD 12 flags=1, data=0xb8590810, size=35, buf=0xb85949f0) [call219]\n", 238) = 
238
[pid  4356] gettimeofday({1437734719, 742627}, NULL) = 0
[pid  4356] stat64("/etc/localtime", {st_mode=S_IFREG|0644, st_size=2379, ...}) 
= 0
[pid  4356] write(5, "2015/07/24 12:45:19.742 kid1| ModEpoll.cc(139) SetSelect: 
FD 12, type=2, handler=0, client_data=0, timeout=0\n", 109) = 109
[pid  4356] epoll_ctl(6, EPOLL_CTL_MOD, 12, {EPOLLIN|EPOLLERR|EPOLLHUP, 
{u32=12, u64=12}}) = 0
[pid  4356] gettimeofday({1437734719, 742883}, NULL) = 0
[pid  4356] gettimeofday({1437734719, 742945}, NULL) = 0
[pid  4356] stat64("/etc/localtime", {st_mode=S_IFREG|0644, st_size=2379, ...}) 
= 0
[pid  4356] write(5, "2015/07/24 12:45:19.742 kid1| AsyncCallQueue.cc(51) 
fireNext: entering FtpStateData::ftpReadControlReply(local=<localip>:54238 
remote=<remoteip>:2121 FD 12 flags=1, data=0xb8590810, size=35, 
buf=0xb85949f0)\n", 209) = 209
[pid  4356] gettimeofday({1437734719, 743151}, NULL) = 0
[pid  4356] stat64("/etc/localtime", {st_mode=S_IFREG|0644, st_size=2379, ...}) 
= 0
[pid  4356] write(5, "2015/07/24 12:45:19.743 kid1| AsyncCall.cc(30) make: make 
call FtpStateData::ftpReadControlReply [call219]\n", 107) = 107
[pid  4356] gettimeofday({1437734719, 743365}, NULL) = 0
[pid  4356] stat64("/etc/localtime", {st_mode=S_IFREG|0644, st_size=2379, ...}) 
= 0
[pid  4356] write(5, "2015/07/24 12:45:19.743 kid1| AsyncJob.cc(117) callStart: 
FtpStateData status in: [ job5]\n", 90) = 90
[pid  4356] gettimeofday({1437734719, 743587}, NULL) = 0
[pid  4356] stat64("/etc/localtime", {st_mode=S_IFREG|0644, st_size=2379, ...}) 
= 0
[pid  4356] write(5, "2015/07/24 12:45:19.743 kid1| ftp.cc(1739) 
ftpReadControlReply: ftpReadControlReply: FD 12, Read 35 bytes\n", 106) = 106
[pid  4356] gettimeofday({1437734719, 743807}, NULL) = 0
[pid  4356] stat64("/etc/localtime", {st_mode=S_IFREG|0644, st_size=2379, ...}) 
= 0
[pid  4356] write(5, "2015/07/24 12:45:19.743 kid1| ftp.cc(1630) 
ftpParseControlReply: \n", 66) = 66
[pid  4356] gettimeofday({1437734719, 744014}, NULL) = 0
[pid  4356] stat64("/etc/localtime", {st_mode=S_IFREG|0644, st_size=2379, ...}) 
= 0
[pid  4356] write(5, "2015/07/24 12:45:19.744 kid1| ftp.cc(1643) 
ftpParseControlReply: usable = 34\n", 77) = 77
[pid  4356] gettimeofday({1437734719, 744116}, NULL) = 0
[pid  4356] stat64("/etc/localtime", {st_mode=S_IFREG|0644, st_size=2379, ...}) 
= 0
[pid  4356] write(5, "2015/07/24 12:45:19.744 kid1| ftp.cc(1651) 
ftpParseControlReply: 35 bytes to play with\n", 87) = 87
[pid  4356] gettimeofday({1437734719, 744216}, NULL) = 0
[pid  4356] stat64("/etc/localtime", {st_mode=S_IFREG|0644, st_size=2379, ...}) 
= 0
[pid  4356] write(5, "2015/07/24 12:45:19.744 kid1| ftp.cc(1660) 
ftpParseControlReply: s = {501 EPSV: Operation not permitted\r\n}\n", 107) = 107
[pid  4356] gettimeofday({1437734719, 744315}, NULL) = 0
[pid  4356] stat64("/etc/localtime", {st_mode=S_IFREG|0644, st_size=2379, ...}) 
= 0
[pid  4356] write(5, "2015/07/24 12:45:19.744 kid1| ftp.cc(1686) 
ftpParseControlReply: ftp>> 501 EPSV: Operation not permitted\n", 105) = 105
[pid  4356] gettimeofday({1437734719, 744413}, NULL) = 0
[pid  4356] stat64("/etc/localtime", {st_mode=S_IFREG|0644, st_size=2379, ...}) 
= 0
[pid  4356] write(5, "2015/07/24 12:45:19.744 kid1| ftp.cc(1837) 
handleControlReply: state=9, code=501\n", 81) = 81
[pid  4356] gettimeofday({1437734719, 744578}, NULL) = 0
[pid  4356] stat64("/etc/localtime", {st_mode=S_IFREG|0644, st_size=2379, ...}) 
= 0
[pid  4356] write(5, "2015/07/24 12:45:19.744 kid1| ftp.cc(2358) ftpReadEPSV: 
\n", 57) = 57
[pid  4356] gettimeofday({1437734719, 744674}, NULL) = 0
[pid  4356] stat64("/etc/localtime", {st_mode=S_IFREG|0644, st_size=2379, ...}) 
= 0
[pid  4356] write(5, "2015/07/24 12:45:19.744 kid1| ftp.cc(2367) ftpReadEPSV: 
EPSV not supported by remote end\n", 89) = 89
[pid  4356] gettimeofday({1437734719, 744770}, NULL) = 0
[pid  4356] stat64("/etc/localtime", {st_mode=S_IFREG|0644, st_size=2379, ...}) 
= 0
[pid  4356] write(5, "2015/07/24 12:45:19.744 kid1| ftp.cc(2495) 
ftpSendPassive: \n", 60) = 60
[pid  4356] gettimeofday({1437734719, 744868}, NULL) = 0
[pid  4356] stat64("/etc/localtime", {st_mode=S_IFREG|0644, st_size=2379, ...}) 
= 0
[pid  4356] write(5, "2015/07/24 12:45:19.744 kid1| ftp.cc(2559) 
ftpSendPassive: FTP Channel (<remoteip>:2121) rejects EPSV connection attempts. 
Trying PASV instead.\n", 145) = 145
[pid  4356] gettimeofday({1437734719, 744976}, NULL) = 0
[pid  4356] stat64("/etc/localtime", {st_mode=S_IFREG|0644, st_size=2379, ...}) 
= 0
[pid  4356] write(5, "2015/07/24 12:45:19.744 kid1| ftp.cc(1567) writeCommand: 
ftp<< PASV\r\n\n", 70) = 70
[pid  4356] gettimeofday({1437734719, 745075}, NULL) = 0
[pid  4356] stat64("/etc/localtime", {st_mode=S_IFREG|0644, st_size=2379, ...}) 
= 0
[pid  4356] write(5, "2015/07/24 12:45:19.745 kid1| AsyncCall.cc(18) AsyncCall: 
The AsyncCall FtpStateData::ftpWriteCommandCallback constructed, 
this=0xb8596a00 [call221]\n", 149) = 149
[pid  4356] gettimeofday({1437734719, 745416}, NULL) = 0
[pid  4356] stat64("/etc/localtime", {st_mode=S_IFREG|0644, st_size=2379, ...}) 
= 0
[pid  4356] write(5, "2015/07/24 12:45:19.745 kid1| Write.cc(29) Write: 
local=<localip>:54238 remote=<remoteip>:2121 FD 12 flags=1: sz 6: asynCall 
0xb8596a00*1\n", 140) = 140
[pid  4356] gettimeofday({1437734719, 745692}, NULL) = 0
[pid  4356] stat64("/etc/localtime", {st_mode=S_IFREG|0644, st_size=2379, ...}) 
= 0
[pid  4356] write(5, "2015/07/24 12:45:19.745 kid1| ModEpoll.cc(139) SetSelect: 
FD 12, type=2, handler=1, client_data=0xb4fe4424, timeout=0\n", 118) = 118
[pid  4356] epoll_ctl(6, EPOLL_CTL_MOD, 12, 
{EPOLLIN|EPOLLOUT|EPOLLERR|EPOLLHUP, {u32=12, u64=13176301056662962188}}) = 0
[pid  4356] gettimeofday({1437734719, 746023}, NULL) = 0
[pid  4356] stat64("/etc/localtime", {st_mode=S_IFREG|0644, st_size=2379, ...}) 
= 0
[pid  4356] write(5, "2015/07/24 12:45:19.746 kid1| ftp.cc(1713) 
scheduleReadControlReply: local=<localip>:54238 remote=<remoteip>:2121 FD 12 
flags=1\n", 130) = 130
[pid  4356] gettimeofday({1437734719, 746295}, NULL) = 0
[pid  4356] stat64("/etc/localtime", {st_mode=S_IFREG|0644, st_size=2379, ...}) 
= 0
[pid  4356] write(5, "2015/07/24 12:45:19.746 kid1| AsyncCall.cc(18) AsyncCall: 
The AsyncCall FtpStateData::ftpTimeout constructed, this=0xb8596a58 
[call222]\n", 136) = 136
[pid  4356] gettimeofday({1437734719, 746549}, NULL) = 0
[pid  4356] stat64("/etc/localtime", {st_mode=S_IFREG|0644, st_size=2379, ...}) 
= 0
[pid  4356] write(5, "2015/07/24 12:45:19.746 kid1| comm.cc(768) 
commSetConnTimeout: local=<localip>:54238 remote=<remoteip>:2121 FD 12 flags=1 
timeout 900\n", 136) = 136
[pid  4356] gettimeofday({1437734719, 746777}, NULL) = 0
[pid  4356] stat64("/etc/localtime", {st_mode=S_IFREG|0644, st_size=2379, ...}) 
= 0
[pid  4356] write(5, "2015/07/24 12:45:19.746 kid1| AsyncCall.cc(18) AsyncCall: 
The AsyncCall FtpStateData::ftpReadControlReply constructed, this=0xb8596aa8 
[call223]\n", 145) = 145
[pid  4356] gettimeofday({1437734719, 747003}, NULL) = 0
[pid  4356] stat64("/etc/localtime", {st_mode=S_IFREG|0644, st_size=2379, ...}) 
= 0
[pid  4356] write(5, "2015/07/24 12:45:19.747 kid1| comm.cc(167) comm_read: 
comm_read, queueing read for local=<localip>:54238 remote=<remoteip>:2121 FD 12 
flags=1; asynCall 0xb8596aa8*1\n", 167) = 167
[pid  4356] gettimeofday({1437734719, 747228}, NULL) = 0
[pid  4356] stat64("/etc/localtime", {st_mode=S_IFREG|0644, st_size=2379, ...}) 
= 0
[pid  4356] write(5, "2015/07/24 12:45:19.747 kid1| ModEpoll.cc(139) SetSelect: 
FD 12, type=1, handler=1, client_data=0xb4fe43fc, timeout=0\n", 118) = 118
[pid  4356] gettimeofday({1437734719, 747509}, NULL) = 0
[pid  4356] stat64("/etc/localtime", {st_mode=S_IFREG|0644, st_size=2379, ...}) 
= 0
[pid  4356] write(5, "2015/07/24 12:45:19.747 kid1| AsyncCall.cc(18) AsyncCall: 
The AsyncCall FtpStateData::ftpTimeout constructed, this=0xb8596b70 
[call224]\n", 136) = 136
[pid  4356] gettimeofday({1437734719, 747751}, NULL) = 0
[pid  4356] stat64("/etc/localtime", {st_mode=S_IFREG|0644, st_size=2379, ...}) 
= 0
[pid  4356] write(5, "2015/07/24 12:45:19.747 kid1| comm.cc(768) 
commSetConnTimeout: local=<localip>:54238 remote=<remoteip>:2121 FD 12 flags=1 
timeout 60\n", 135) = 135
[pid  4356] gettimeofday({1437734719, 748017}, NULL) = 0
[pid  4356] stat64("/etc/localtime", {st_mode=S_IFREG|0644, st_size=2379, ...}) 
= 0
[pid  4356] write(5, "2015/07/24 12:45:19.748 kid1| AsyncJob.cc(146) callEnd: 
FtpStateData status out: [ job5]\n", 89) = 89
[pid  4356] gettimeofday({1437734719, 748207}, NULL) = 0
[pid  4356] stat64("/etc/localtime", {st_mode=S_IFREG|0644, st_size=2379, ...}) 
= 0
[pid  4356] write(5, "2015/07/24 12:45:19.748 kid1| AsyncCallQueue.cc(53) 
fireNext: leaving FtpStateData::ftpReadControlReply(local=<localip>:54238 
remote=<remoteip>:2121 FD 12 flags=1, data=0xb8590810, size=35, 
buf=0xb85949f0)\n", 208) = 208
[pid  4356] epoll_wait(6, {{EPOLLOUT, {u32=12, u64=13176301056662962188}}}, 
4096, 87) = 1
[pid  4356] gettimeofday({1437734719, 748500}, NULL) = 0
[pid  4356] gettimeofday({1437734719, 748577}, NULL) = 0
[pid  4356] stat64("/etc/localtime", {st_mode=S_IFREG|0644, st_size=2379, ...}) 
= 0
[pid  4356] write(5, "2015/07/24 12:45:19.748 kid1| Write.cc(60) HandleWrite: 
local=<localip>:54238 remote=<remoteip>:2121 FD 12 flags=1: off 0, sz 6.\n", 
131) = 131
[pid  4356] write(12, "PASV\r\n", 6)    = 6
[pid  4356] gettimeofday({1437734719, 748859}, NULL) = 0
[pid  4356] stat64("/etc/localtime", {st_mode=S_IFREG|0644, st_size=2379, ...}) 
= 0
[pid  4356] write(5, "2015/07/24 12:45:19.748 kid1| Write.cc(100) HandleWrite: 
write() returns 6\n", 75) = 75
[pid  4356] gettimeofday({1437734719, 749080}, NULL) = 0
[pid  4356] stat64("/etc/localtime", {st_mode=S_IFREG|0644, st_size=2379, ...}) 
= 0
[pid  4356] write(5, "2015/07/24 12:45:19.749 kid1| IoCallback.cc(108) finish: 
called for local=<localip>:54238 remote=<remoteip>:2121 FD 12 flags=1 (0, 
0)\n", 136) = 136
[pid  4356] gettimeofday({1437734719, 749326}, NULL) = 0
[pid  4356] stat64("/etc/localtime", {st_mode=S_IFREG|0644, st_size=2379, ...}) 
= 0
[pid  4356] write(5, "2015/07/24 12:45:19.749 kid1| AsyncCall.cc(85) 
ScheduleCall: IoCallback.cc(127) will call 
FtpStateData::ftpWriteCommandCallback(local=<localip>:54238 
remote=<remoteip>:2121 FD 12 flags=1, data=0xb8590810, size=6, buf=0xb81e05e0) 
[call221]\n", 241) = 241
[pid  4356] gettimeofday({1437734719, 749501}, NULL) = 0
[pid  4356] gettimeofday({1437734719, 749557}, NULL) = 0
[pid  4356] stat64("/etc/localtime", {st_mode=S_IFREG|0644, st_size=2379, ...}) 
= 0
[pid  4356] write(5, "2015/07/24 12:45:19.749 kid1| AsyncCallQueue.cc(51) 
fireNext: entering FtpStateData::ftpWriteCommandCallback(local=<localip>:54238 
remote=<remoteip>:2121 FD 12 flags=1, data=0xb8590810, size=6, 
buf=0xb81e05e0)\n", 212) = 212
[pid  4356] gettimeofday({1437734719, 749788}, NULL) = 0
[pid  4356] stat64("/etc/localtime", {st_mode=S_IFREG|0644, st_size=2379, ...}) 
= 0
[pid  4356] write(5, "2015/07/24 12:45:19.749 kid1| AsyncCall.cc(30) make: make 
call FtpStateData::ftpWriteCommandCallback [call221]\n", 111) = 111
[pid  4356] gettimeofday({1437734719, 750087}, NULL) = 0
[pid  4356] stat64("/etc/localtime", {st_mode=S_IFREG|0644, st_size=2379, ...}) 
= 0
[pid  4356] write(5, "2015/07/24 12:45:19.750 kid1| AsyncJob.cc(117) callStart: 
FtpStateData status in: [ job5]\n", 90) = 90
[pid  4356] gettimeofday({1437734719, 750299}, NULL) = 0
[pid  4356] stat64("/etc/localtime", {st_mode=S_IFREG|0644, st_size=2379, ...}) 
= 0
[pid  4356] write(5, "2015/07/24 12:45:19.750 kid1| ftp.cc(1597) 
ftpWriteCommandCallback: ftpWriteCommandCallback: wrote 6 bytes\n", 107) = 107
[pid  4356] gettimeofday({1437734719, 750500}, NULL) = 0
[pid  4356] stat64("/etc/localtime", {st_mode=S_IFREG|0644, st_size=2379, ...}) 
= 0
[pid  4356] write(5, "2015/07/24 12:45:19.750 kid1| AsyncJob.cc(146) callEnd: 
FtpStateData status out: [ job5]\n", 89) = 89
[pid  4356] gettimeofday({1437734719, 750699}, NULL) = 0
[pid  4356] stat64("/etc/localtime", {st_mode=S_IFREG|0644, st_size=2379, ...}) 
= 0
[pid  4356] write(5, "2015/07/24 12:45:19.750 kid1| AsyncCallQueue.cc(53) 
fireNext: leaving FtpStateData::ftpWriteCommandCallback(local=<localip>:54238 
remote=<remoteip>:2121 FD 12 flags=1, data=0xb8590810, size=6, 
buf=0xb81e05e0)\n", 211) = 211
[pid  4356] epoll_wait(6, {{EPOLLIN|EPOLLOUT, {u32=12, 
u64=13176301056662962188}}}, 4096, 85) = 1
[pid  4356] gettimeofday({1437734719, 750993}, NULL) = 0
[pid  4356] read(12, "501 PASV: Operation not permitted\r\n", 4096) = 35
[pid  4356] gettimeofday({1437734719, 751139}, NULL) = 0
[pid  4356] stat64("/etc/localtime", {st_mode=S_IFREG|0644, st_size=2379, ...}) 
= 0
[pid  4356] write(5, "2015/07/24 12:45:19.751 kid1| comm.cc(138) 
commHandleRead: comm_read_try: FD 12, size 4096, retval 35, errno 0\n", 111) = 
111
[pid  4356] gettimeofday({1437734719, 751348}, NULL) = 0
[pid  4356] stat64("/etc/localtime", {st_mode=S_IFREG|0644, st_size=2379, ...}) 
= 0
[pid  4356] write(5, "2015/07/24 12:45:19.751 kid1| IoCallback.cc(108) finish: 
called for local=<localip>:54238 remote=<remoteip>:2121 FD 12 flags=1 (0, 
0)\n", 136) = 136
[pid  4356] gettimeofday({1437734719, 751538}, NULL) = 0
[pid  4356] stat64("/etc/localtime", {st_mode=S_IFREG|0644, st_size=2379, ...}) 
= 0
[pid  4356] write(5, "2015/07/24 12:45:19.751 kid1| AsyncCall.cc(85) 
ScheduleCall: IoCallback.cc(127) will call 
FtpStateData::ftpReadControlReply(local=<localip>:54238 remote=<remoteip>:2121 
FD 12 flags=1, data=0xb8590810, size=35, buf=0xb85949f0) [call223]\n", 238) = 
238
[pid  4356] gettimeofday({1437734719, 751808}, NULL) = 0
[pid  4356] stat64("/etc/localtime", {st_mode=S_IFREG|0644, st_size=2379, ...}) 
= 0
[pid  4356] write(5, "2015/07/24 12:45:19.751 kid1| ModEpoll.cc(139) SetSelect: 
FD 12, type=2, handler=0, client_data=0, timeout=0\n", 109) = 109
[pid  4356] epoll_ctl(6, EPOLL_CTL_MOD, 12, {EPOLLIN|EPOLLERR|EPOLLHUP, 
{u32=12, u64=12}}) = 0
[pid  4356] gettimeofday({1437734719, 751989}, NULL) = 0
[pid  4356] gettimeofday({1437734719, 752030}, NULL) = 0
[pid  4356] stat64("/etc/localtime", {st_mode=S_IFREG|0644, st_size=2379, ...}) 
= 0
[pid  4356] write(5, "2015/07/24 12:45:19.752 kid1| AsyncCallQueue.cc(51) 
fireNext: entering FtpStateData::ftpReadControlReply(local=<localip>:54238 
remote=<remoteip>:2121 FD 12 flags=1, data=0xb8590810, size=35, 
buf=0xb85949f0)\n", 209) = 209
[pid  4356] gettimeofday({1437734719, 752237}, NULL) = 0
[pid  4356] stat64("/etc/localtime", {st_mode=S_IFREG|0644, st_size=2379, ...}) 
= 0
[pid  4356] write(5, "2015/07/24 12:45:19.752 kid1| AsyncCall.cc(30) make: make 
call FtpStateData::ftpReadControlReply [call223]\n", 107) = 107
[pid  4356] gettimeofday({1437734719, 752419}, NULL) = 0
[pid  4356] stat64("/etc/localtime", {st_mode=S_IFREG|0644, st_size=2379, ...}) 
= 0
[pid  4356] write(5, "2015/07/24 12:45:19.752 kid1| AsyncJob.cc(117) callStart: 
FtpStateData status in: [ job5]\n", 90) = 90
[pid  4356] gettimeofday({1437734719, 752603}, NULL) = 0
[pid  4356] stat64("/etc/localtime", {st_mode=S_IFREG|0644, st_size=2379, ...}) 
= 0
[pid  4356] write(5, "2015/07/24 12:45:19.752 kid1| ftp.cc(1739) 
ftpReadControlReply: ftpReadControlReply: FD 12, Read 35 bytes\n", 106) = 106
[pid  4356] gettimeofday({1437734719, 752816}, NULL) = 0
[pid  4356] stat64("/etc/localtime", {st_mode=S_IFREG|0644, st_size=2379, ...}) 
= 0
[pid  4356] write(5, "2015/07/24 12:45:19.752 kid1| ftp.cc(1630) 
ftpParseControlReply: \n", 66) = 66
[pid  4356] gettimeofday({1437734719, 753018}, NULL) = 0
[pid  4356] stat64("/etc/localtime", {st_mode=S_IFREG|0644, st_size=2379, ...}) 
= 0
[pid  4356] write(5, "2015/07/24 12:45:19.753 kid1| ftp.cc(1643) 
ftpParseControlReply: usable = 34\n", 77) = 77
[pid  4356] gettimeofday({1437734719, 753179}, NULL) = 0
[pid  4356] stat64("/etc/localtime", {st_mode=S_IFREG|0644, st_size=2379, ...}) 
= 0
[pid  4356] write(5, "2015/07/24 12:45:19.753 kid1| ftp.cc(1651) 
ftpParseControlReply: 35 bytes to play with\n", 87) = 87
[pid  4356] gettimeofday({1437734719, 753431}, NULL) = 0
[pid  4356] stat64("/etc/localtime", {st_mode=S_IFREG|0644, st_size=2379, ...}) 
= 0
[pid  4356] write(5, "2015/07/24 12:45:19.753 kid1| ftp.cc(1660) 
ftpParseControlReply: s = {501 PASV: Operation not permitted\r\n}\n", 107) = 107
[pid  4356] gettimeofday({1437734719, 753670}, NULL) = 0
[pid  4356] stat64("/etc/localtime", {st_mode=S_IFREG|0644, st_size=2379, ...}) 
= 0
[pid  4356] write(5, "2015/07/24 12:45:19.753 kid1| ftp.cc(1686) 
ftpParseControlReply: ftp>> 501 PASV: Operation not permitted\n", 105) = 105
[pid  4356] gettimeofday({1437734719, 753864}, NULL) = 0
[pid  4356] stat64("/etc/localtime", {st_mode=S_IFREG|0644, st_size=2379, ...}) 
= 0
[pid  4356] write(5, "2015/07/24 12:45:19.753 kid1| ftp.cc(1837) 
handleControlReply: state=11, code=501\n", 82) = 82
[pid  4356] gettimeofday({1437734719, 754025}, NULL) = 0
[pid  4356] stat64("/etc/localtime", {st_mode=S_IFREG|0644, st_size=2379, ...}) 
= 0
[pid  4356] write(5, "2015/07/24 12:45:19.754 kid1| ftp.cc(2642) ftpReadPasv: 
\n", 57) = 57
[pid  4356] gettimeofday({1437734719, 754280}, NULL) = 0
[pid  4356] stat64("/etc/localtime", {st_mode=S_IFREG|0644, st_size=2379, ...}) 
= 0
[pid  4356] write(5, "2015/07/24 12:45:19.754 kid1| ftp.cc(2645) ftpReadPasv: 
PASV not supported by remote end\n", 89) = 89
[pid  4356] gettimeofday({1437734719, 754515}, NULL) = 0
[pid  4356] stat64("/etc/localtime", {st_mode=S_IFREG|0644, st_size=2379, ...}) 
= 0
[pid  4356] write(5, "2015/07/24 12:45:19.754 kid1| ftp.cc(2870) ftpSendEPRT: 
\n", 57) = 57
[pid  4356] gettimeofday({1437734719, 754745}, NULL) = 0
[pid  4356] stat64("/etc/localtime", {st_mode=S_IFREG|0644, st_size=2379, ...}) 
= 0
[pid  4356] write(5, "2015/07/24 12:45:19.754 kid1| AsyncCall.cc(18) AsyncCall: 
The AsyncCall FtpStateData::ftpAcceptDataConnection constructed, 
this=0xb8596b00 [call225]\n", 149) = 149
[pid  4356] gettimeofday({1437734719, 754991}, NULL) = 0
[pid  4356] stat64("/etc/localtime", {st_mode=S_IFREG|0644, st_size=2379, ...}) 
= 0
[pid  4356] write(5, "2015/07/24 12:45:19.754 kid1| comm.cc(549) comm_openex: 
comm_openex: Attempt open socket for: <localip>\n", 105) = 105
[pid  4356] socket(PF_INET, SOCK_STREAM, IPPROTO_TCP) = 14
[pid  4356] gettimeofday({1437734719, 755346}, NULL) = 0
[pid  4356] stat64("/etc/localtime", {st_mode=S_IFREG|0644, st_size=2379, ...}) 
= 0
[pid  4356] write(5, "2015/07/24 12:45:19.755 kid1| comm.cc(590) comm_openex: 
comm_openex: Opened socket local=<localip> remote=[::] FD 14 flags=1 : 
family=2, type=1, protocol=6\n", 157) = 157
[pid  4356] gettimeofday({1437734719, 755602}, NULL) = 0
[pid  4356] stat64("/etc/localtime", {st_mode=S_IFREG|0644, st_size=2379, ...}) 
= 0
[pid  4356] write(5, "2015/07/24 12:45:19.755 kid1| comm.cc(632) 
comm_init_opened: local=<localip> remote=[::] FD 14 flags=1 is a new socket\n", 
120) = 120
[pid  4356] gettimeofday({1437734719, 755809}, NULL) = 0
[pid  4356] stat64("/etc/localtime", {st_mode=S_IFREG|0644, st_size=2379, ...}) 
= 0
[pid  4356] write(5, "2015/07/24 12:45:19.755 kid1| fd.cc(221) fd_open: 
fd_open() FD 14 ftp://[email protected]:2121/test.txt;type=i\n";, 116) = 116
[pid  4356] fcntl64(14, F_GETFD)        = 0
[pid  4356] fcntl64(14, F_SETFD, FD_CLOEXEC) = 0
[pid  4356] bind(14, {sa_family=AF_INET, sin_port=htons(0), 
sin_addr=inet_addr("<localip>")}, 16) = 0
[pid  4356] gettimeofday({1437734719, 756004}, NULL) = 0
[pid  4356] stat64("/etc/localtime", {st_mode=S_IFREG|0644, st_size=2379, ...}) 
= 0
[pid  4356] write(5, "2015/07/24 12:45:19.756 kid1| comm.cc(409) commBind: 
commBind: bind socket FD 14 to <localip>\n", 95) = 95
[pid  4356] fcntl64(14, F_GETFL)        = 0x2 (flags O_RDWR)
[pid  4356] fcntl64(14, F_SETFL, O_RDWR|O_NONBLOCK) = 0
[pid  4356] setsockopt(14, SOL_TCP, TCP_NODELAY, [1], 4) = 0
[pid  4356] gettimeofday({1437734719, 756194}, NULL) = 0
[pid  4356] stat64("/etc/localtime", {st_mode=S_IFREG|0644, st_size=2379, ...}) 
= 0
[pid  4356] write(5, "2015/07/24 12:45:19.756 kid1| ftp.cc(644) 
listenForDataChannel: Unconnected data socket created on local=<localip> 
remote=[::] FD 14 flags=1\n", 142) = 142
[pid  4356] gettimeofday({1437734719, 756298}, NULL) = 0
[pid  4356] stat64("/etc/localtime", {st_mode=S_IFREG|0644, st_size=2379, ...}) 
= 0
[pid  4356] write(5, "2015/07/24 12:45:19.756 kid1| AsyncJob.cc(28) AsyncJob: 
AsyncJob constructed, this=0xb8596a10 type=Comm::TcpAcceptor [job6]\n", 124) = 
124
[pid  4356] gettimeofday({1437734719, 756400}, NULL) = 0
[pid  4356] stat64("/etc/localtime", {st_mode=S_IFREG|0644, st_size=2379, ...}) 
= 0
[pid  4356] write(5, "2015/07/24 12:45:19.756 kid1| AsyncCall.cc(18) AsyncCall: 
The AsyncCall AsyncJob::start constructed, this=0xb8596a40 [call226]\n", 127) = 
127
[pid  4356] gettimeofday({1437734719, 756524}, NULL) = 0
[pid  4356] stat64("/etc/localtime", {st_mode=S_IFREG|0644, st_size=2379, ...}) 
= 0
[pid  4356] write(5, "2015/07/24 12:45:19.756 kid1| AsyncCall.cc(85) 
ScheduleCall: AsyncJob.cc(20) will call AsyncJob::start() [call226]\n", 115) = 
115
[pid  4356] gettimeofday({1437734719, 756636}, NULL) = 0
[pid  4356] stat64("/etc/localtime", {st_mode=S_IFREG|0644, st_size=2379, ...}) 
= 0
[pid  4356] write(5, "2015/07/24 12:45:19.756 kid1| AsyncCall.cc(18) AsyncCall: 
The AsyncCall FtpStateData::dataClosed constructed, this=0xb8596c70 
[call227]\n", 136) = 136
[pid  4356] gettimeofday({1437734719, 756858}, NULL) = 0
[pid  4356] stat64("/etc/localtime", {st_mode=S_IFREG|0644, st_size=2379, ...}) 
= 0
[pid  4356] write(5, "2015/07/24 12:45:19.756 kid1| comm.cc(1208) 
comm_add_close_handler: comm_add_close_handler: FD 14, 
AsyncCall=0xb8596c70*2\n", 122) = 122
[pid  4356] gettimeofday({1437734719, 756970}, NULL) = 0
[pid  4356] stat64("/etc/localtime", {st_mode=S_IFREG|0644, st_size=2379, ...}) 
= 0
[pid  4356] write(5, "2015/07/24 12:45:19.756 kid1| comm.cc(794) 
commUnsetConnTimeout: Remove timeout for local=<localip>:54238 
remote=<remoteip>:2121 FD 12 flags=1\n", 145) = 145
[pid  4356] gettimeofday({1437734719, 757072}, NULL) = 0
[pid  4356] stat64("/etc/localtime", {st_mode=S_IFREG|0644, st_size=2379, ...}) 
= 0
[pid  4356] write(5, "2015/07/24 12:45:19.757 kid1| comm.cc(768) 
commSetConnTimeout: local=<localip>:54238 remote=<remoteip>:2121 FD 12 flags=1 
timeout -1\n", 135) = 135
[pid  4356] gettimeofday({1437734719, 757177}, NULL) = 0
[pid  4356] stat64("/etc/localtime", {st_mode=S_IFREG|0644, st_size=2379, ...}) 
= 0
[pid  4356] write(5, "2015/07/24 12:45:19.757 kid1| AsyncCall.cc(18) AsyncCall: 
The AsyncCall FtpStateData::ftpTimeout constructed, this=0xb8596b58 
[call228]\n", 136) = 136
[pid  4356] gettimeofday({1437734719, 757301}, NULL) = 0
[pid  4356] stat64("/etc/localtime", {st_mode=S_IFREG|0644, st_size=2379, ...}) 
= 0
[pid  4356] write(5, "2015/07/24 12:45:19.757 kid1| comm.cc(768) 
commSetConnTimeout: local=<localip> remote=[::] FD 14 flags=1 timeout 900\n", 
118) = 118
[pid  4356] gettimeofday({1437734719, 757407}, NULL) = 0
[pid  4356] stat64("/etc/localtime", {st_mode=S_IFREG|0644, st_size=2379, ...}) 
= 0
[pid  4356] write(5, "2015/07/24 12:45:19.757 kid1| ftp.cc(2874) ftpSendEPRT: 
Listening for FTP data connection with FD local=<localip> remote=[::] FD 14 
flags=1\n", 141) = 141
[pid  4356] --- SIGSEGV {si_signo=SIGSEGV, si_code=SEGV_MAPERR, si_addr=0x6} ---

--- End Message ---
--- Begin Message --- 
Source: squid3
Source-Version: 3.5.23-1

We believe that the bug you reported is fixed in the latest version of
squid3, which is due to be installed in the Debian FTP archive.

A summary of the changes between this version and the previous one is
attached.

Thank you for reporting the bug, which will now be closed.  If you
have further comments please address them to [email protected],
and the maintainer will reopen the bug report if appropriate.

Debian distribution maintenance software
pp.
Luigi Gangitano <[email protected]> (supplier of updated squid3 package)

(This message was generated automatically at their request; if you
believe that there is a problem with it please contact the archive
administrators by mailing [email protected])


-----BEGIN PGP SIGNED MESSAGE-----
Hash: SHA512

Format: 1.8
Date: Sun, 18 Dec 2016 23:39:24 +0200
Source: squid3
Binary: squid3 squid squid-dbg squid-common squidclient squid-cgi squid-purge
Architecture: source amd64 all
Version: 3.5.23-1
Distribution: unstable
Urgency: high
Maintainer: Luigi Gangitano <[email protected]>
Changed-By: Luigi Gangitano <[email protected]>
Description:
 squid      - Full featured Web Proxy cache (HTTP proxy)
 squid-cgi  - Full featured Web Proxy cache (HTTP proxy) - control CGI
 squid-common - Full featured Web Proxy cache (HTTP proxy) - common files
 squid-dbg  - Full featured Web Proxy cache (HTTP proxy) - Debug symbols
 squid-purge - Full featured Web Proxy cache (HTTP proxy) - control utility
 squid3     - Transitional package
 squidclient - Full featured Web Proxy cache (HTTP proxy) - control utility
Closes: 793473 822952 848491 848493
Changes:
 squid3 (3.5.23-1) unstable; urgency=high
 .
   [ Amos Jeffries <[email protected]> ]
   * New Upstream Release (Closes: #793473, #822952)
     - Fixes security issue SQUID-2016:10 (CVE-2016-10003) (Closes: #848491)
     - Fixes security issue SQUID-2016:11 (CVE-2016-10002) (Closes: #848493)
 .
   * debian/patches/
     - Remove patch included upstream
 .
   * debian/tests/
     - Use package build-deps when testing so the make commands will work
Checksums-Sha1:
 197134d8ace06ae54284c6d4196019150be0082d 2397 squid3_3.5.23-1.dsc
 6b0b2091896e7874024e5f1e28eeccb0acd7e962 4730792 squid3_3.5.23.orig.tar.gz
 49f45d0160c7aa823fd23198cd5aaaee0db6ac78 25460 squid3_3.5.23-1.debian.tar.xz
 5f2e8ae27cbb4c93eebf781013389737906c8b6c 164508 squid-cgi_3.5.23-1_amd64.deb
 5acd567346d5f80b25011436debb99424be28807 284030 squid-common_3.5.23-1_all.deb
 68f59153994461f5fd833427a7b29526f3c1f3bf 21562690 squid-dbg_3.5.23-1_amd64.deb
 deebbb55e525a7efeebf47ec914453549d31e79d 157000 squid-purge_3.5.23-1_amd64.deb
 386b2bca052a123a27fa9738e886f74adcae3c50 138348 squid3_3.5.23-1_all.deb
 96125c04c3582c1e391f9023b6e1c536296c208a 8377 squid3_3.5.23-1_amd64.buildinfo
 2ce1eb847e2392ed82a6b72b7dfb1d4972404f24 2311344 squid_3.5.23-1_amd64.deb
 c8e0e90e1b9e862a37b89b93a43c0d4c4cb985e2 168126 squidclient_3.5.23-1_amd64.deb
Checksums-Sha256:
 38d1ffe9c150c24c98705a5cf15ffa2775319995a18b3d45034e7c052e2bb0ae 2397 
squid3_3.5.23-1.dsc
 f81eeee0fb046ad636566b51fe4f72b8bc66d454d7082ef38e273c3f4b09f6db 4730792 
squid3_3.5.23.orig.tar.gz
 a143ad91de14a1eb9f1d822a26f2b77a91015897f3e06bbed0bdfa50bdcbc7cd 25460 
squid3_3.5.23-1.debian.tar.xz
 1038c7f95c6f764689781c150571f388194cca9a9b1687b7aa2d1cc8619c2940 164508 
squid-cgi_3.5.23-1_amd64.deb
 d632cdb07913459be218fdf09c8b9b661b176881848a4be5c9a8531cf3f58bc0 284030 
squid-common_3.5.23-1_all.deb
 ab6f1c4c846788d4a2329e81367c1e42ef5e4693b75e7a6ef5796a5fb4fcbd86 21562690 
squid-dbg_3.5.23-1_amd64.deb
 82c9d6468126d1b146d9c1f259d4e3989fd6f58e96158c4edf5f576696dbb650 157000 
squid-purge_3.5.23-1_amd64.deb
 9739ddddef3ba4780d577efd6cb09de81388c8ca1ce0037cff8cae83b9900b80 138348 
squid3_3.5.23-1_all.deb
 ea5f09d8ffc02c82f6177d7997487596307d46cfaeb203ba0c303adcc86992b6 8377 
squid3_3.5.23-1_amd64.buildinfo
 09f8a830164bc6f705dc786245222a00d8683fc1267899055c512ec02808aca3 2311344 
squid_3.5.23-1_amd64.deb
 6e92d0bc65177acd410b80caf864bb34af0c727beddaed32319f5d24c767bf80 168126 
squidclient_3.5.23-1_amd64.deb
Files:
 3da6149d1248ae7e24d7e95e27619ca8 2397 web optional squid3_3.5.23-1.dsc
 49d790ddee8c611ee2992e66eb8e9ae9 4730792 web optional squid3_3.5.23.orig.tar.gz
 afc0191c3af8ea1ef58254b1d832c9cd 25460 web optional 
squid3_3.5.23-1.debian.tar.xz
 d2842282a031b893cbdda3634cada080 164508 web optional 
squid-cgi_3.5.23-1_amd64.deb
 84be34482e662640e02cb0e6357d582e 284030 web optional 
squid-common_3.5.23-1_all.deb
 e1649262c944019fbee49dd7b02f399f 21562690 debug extra 
squid-dbg_3.5.23-1_amd64.deb
 e1a929456c79c177167c404e83059d28 157000 web optional 
squid-purge_3.5.23-1_amd64.deb
 969b49f6e0d5f0cb413aa1977c65ac6c 138348 oldlibs extra squid3_3.5.23-1_all.deb
 38ce40aa262827e466e12a42cb3819db 8377 web optional 
squid3_3.5.23-1_amd64.buildinfo
 acc42f6db2c2a2044496ddfb3954169b 2311344 web optional squid_3.5.23-1_amd64.deb
 0e13622997986323b0904b86d3859ecf 168126 web optional 
squidclient_3.5.23-1_amd64.deb

-----BEGIN PGP SIGNATURE-----

iQIcBAEBCgAGBQJYVx76AAoJEAKE8gwrqXztssQQAKAhLZ7xeMqSN4lKX3kZuS1D
2CTj+56IsEigpJBzCHhEXN07so4F/4Qn0z8DdBctkBawFJwH7rWRWjM98Zzx40az
0G/QM4cCAjSCOWiNX57frWfVU10sd6/sSvwJ0jyvajlBIzIzgqJ6a0i5NG41JH0Z
CBQEE9fWajsP93iJY4JrVbSylUenBhkETdtVmBhHohHTqjAn8Wl6Pnjfs9+eCilG
NiNQEeMltqeR2A5aqC6JCu/7ytg3Jnd/xPgQWXq6VtynHhTRsFYLLgzGWZEZGgBM
g9nDMx9AhDYfc7xPmiFPnfStf+c7dExhqKEzHH5rLcKAUptvSM6wHYr8xVN+IEX9
V6wUGNDnZqv+lQNk5ZcXLc0uC25LXXyFKbRhcdviL44G3Zb/sy9YJtzxEIobDrHB
lYaAVgOUnFx/cCQdqCIhvHeOetUWgrAexbeskovQonPegCTLXIY467BsUtxB2cdF
wOmpDGju9gop2HCqIGbkty7Fa5HGbbqrveiDBqye4J2Tz9F856W/nS/iiLy0ma71
S5ClK+U/XFRDmyr0yhAKybaAI4EeiYjN3E+4LSqr++N0KhlawUgxIoQr4aEOXtsM
dD8MFIteaZQ5iFYaP3F3riNxiDTsSn43WxfIm07htDDSj9PyG5R78eIA7U3qroi5
KDMpL1rAP1T/uSyZUpAP
=A9jc
-----END PGP SIGNATURE-----

--- End Message ---

Reply via email to