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://t...@fw.szakalmetal.hu: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} ---