Hi there,
As log from our server, the trigger functions well. Here are the detailed
logs and the time zone is UTC+8 (except log from ftpsync).
## log on
neomirrors.tuna.tsinghua.edu.cn
login log:
```
Jan 29 01:19:30 localhost sshd[44950]: Accepted publickey for tunasync from 2607:ea00:101:3c0b::1deb:216 port 36693 ssh2: RSA dd:0a:0d:0e:fd:bf:74:48:0d:3b:be:25:6c:e9:c0:6a
Jan 29 01:19:30 localhost sshd[44950]: pam_unix(sshd:session): session opened for user tunasync by (uid=0)
Jan 29 01:19:32 localhost sshd[44995]: Received disconnect from 2607:ea00:101:3c0b::1deb:216: 11: disconnected by user
Jan 29 01:19:32 localhost sshd[44950]: pam_unix(sshd:session): session closed for user tunasync
```
scheduler log:
```
1月 29 01:19:32 neomirrors tunasync[35589]: [NOTICE] Received command: start (debian)
1月 29 01:19:32 neomirrors tunasync[35589]: [NOTICE] start syncing: debian
1月 29 02:56:19 neomirrors tunasync[35589]: [NOTICE] succeeded syncing debian
1月 29 02:56:19 neomirrors tunasync[35589]: [NOTICE] Next scheduled time for debian: 2017-01-29 14:56:19
```
ftpsync-debian.log
```
Jan 28 17:19:35 14ff00e2939d ftpsync-debian[7]: Mirrorsync start
Jan 28 17:19:35 14ff00e2939d ftpsync-debian[7]: Running mirrorsync, update is required, /data/mirrors/debian//
Archive-Update-Required-mirrors.tuna.tsinghua.edu.cn exists
Jan 28 17:19:35 14ff00e2939d ftpsync-debian[7]: Running stage1: rsync -e /ftpsync/bin/rsync-ssl-tunnel -6 --safe-links --bwlimit=0 -prltvHSB8192 --timeout 3600 --stats --filter=
protect_Archive-Update-in-Progress-mirrors.tuna.tsinghua.edu.cn --filter=protect_project/trace/
mirrors.tuna.tsinghua.edu.cn --filter=protect_project/trace/mirrors.tuna.tsinghua.edu.cn-stage1 --exclude=project/trace/_hierarchy --filter=protect_project/trace/_hierarchy --exclude=project/trace/_traces --filter=protect_project/trace/_traces --filter=
protect_Archive-Update-Required-mirrors.tuna.tsinghua.edu.cn --exclude=Packages* --exclude=Sources* --exclude=Release* --exclude=InRelease --include=i18n/by-hash/** --exclude=i18n/* --exclude=ls-lR* --exclude=.~tmp~/ mirrors.tuna.t...@syncproxy.cna.debian.org::debian /data/mirrors/debian/
Jan 28 18:09:35 14ff00e2939d ftpsync-debian[7]: Back from rsync with returncode 0
Jan 28 18:09:36 14ff00e2939d ftpsync-debian[7]: Latest recorded rsync transfer speed: 792 KB/s
Jan 28 18:09:36 14ff00e2939d ftpsync-debian[7]: Running stage2: rsync -e /ftpsync/bin/rsync-ssl-tunnel -6 --safe-links --bwlimit=0 -prltvHSB8192 --timeout 3600 --stats --filter=
protect_Archive-Update-in-Progress-mirrors.tuna.tsinghua.edu.cn --filter=protect_project/trace/
mirrors.tuna.tsinghua.edu.cn --filter=protect_project/trace/mirrors.tuna.tsinghua.edu.cn-stage1 --exclude=project/trace/_hierarchy --filter=protect_project/trace/_hierarchy --exclude=project/trace/_traces --filter=protect_project/trace/_traces --filter=
protect_Archive-Update-Required-mirrors.tuna.tsinghua.edu.cn --max-delete=40000 --delay-updates --delete --delete-excluded --delete-delay --exclude=.~tmp~/ mirrors.tuna.t...@syncproxy.cna.debian.org::debian /data/mirrors/debian/
Jan 28 18:53:51 14ff00e2939d ftpsync-debian[7]: Back from rsync with returncode 0
Jan 28 18:53:51 14ff00e2939d ftpsync-debian[7]: Latest recorded rsync transfer speed: 104 KB/s
Jan 28 18:53:51 14ff00e2939d ftpsync-debian[7]: Creating a full trace file
Jan 28 18:56:19 14ff00e2939d ftpsync-debian[7]: Mirrorsync done
```
In summary, the sync on server
neomirrors.tuna.tsinghua.edu.cn started
immediately when triggered, and finished successfully in one hour and
40 minute.
## log on nano
mirrors.tuna.tsinghua.edu.cn
login log:
```
Jan 29 01:20:16 localhost sshd[3807]: Accepted publickey for tunasync from 2607:ea00:101:3c0b::1deb:216 port 50774 ssh2: RSA dd:0a:0d:0e:fd:bf:74:48:0d:3b:be:25:6c:e9:c0:6a
Jan 29 01:20:16 localhost sshd[3807]: pam_unix(sshd:session): session opened for user tunasync by (uid=0)
Jan 29 01:20:17 localhost sshd[3812]: Received disconnect from 2607:ea00:101:3c0b::1deb:216: 11: disconnected by user
Jan 29 01:20:17 localhost sshd[3807]: pam_unix(sshd:session): session closed for user tunasync
Jan 29 01:20:54 localhost sshd[4171]: Accepted publickey for tunasync from 2607:ea00:101:3c0b::1deb:216 port 39195 ssh2: RSA dd:0a:0d:0e:fd:bf:74:48:0d:3b:be:25:6c:e9:c0:6a
Jan 29 01:20:54 localhost sshd[4171]: pam_unix(sshd:session): session opened for user tunasync by (uid=0)
Jan 29 01:20:55 localhost sshd[4174]: Received disconnect from 2607:ea00:101:3c0b::1deb:216: 11: disconnected by user
Jan 29 01:20:55 localhost sshd[4171]: pam_unix(sshd:session): session closed for user tunasync
Jan 29 01:24:11 localhost sshd[10126]: Accepted publickey for tunasync from 2607:ea00:101:3c0b::1deb:216 port 50120 ssh2: RSA dd:0a:0d:0e:fd:bf:74:48:0d:3b:be:25:6c:e9:c0:6a
Jan 29 01:24:12 localhost sshd[10126]: pam_unix(sshd:session): session opened for user tunasync by (uid=0)
Jan 29 01:24:12 localhost sshd[10196]: Received disconnect from 2607:ea00:101:3c0b::1deb:216: 11: disconnected by user
Jan 29 01:24:12 localhost sshd[10126]: pam_unix(sshd:session): session closed for user tunasync
Jan 29 01:25:34 localhost sshd[14268]: Accepted publickey for tunasync from 2607:ea00:101:3c0b::1deb:216 port 36572 ssh2: RSA dd:0a:0d:0e:fd:bf:74:48:0d:3b:be:25:6c:e9:c0:6a
Jan 29 01:25:34 localhost sshd[14268]: pam_unix(sshd:session): session opened for user tunasync by (uid=0)
Jan 29 01:25:35 localhost sshd[14369]: Received disconnect from 2607:ea00:101:3c0b::1deb:216: 11: disconnected by user
Jan 29 01:25:35 localhost sshd[14268]: pam_unix(sshd:session): session closed for user tunasync
```
scheduler log:
```
1月 28 18:05:33 nanomirrors tunasync[13005]: [NOTICE] start syncing: debian
1月 29 01:20:17 nanomirrors tunasync[13005]: [NOTICE] Received command: start (debian)
1月 29 01:20:54 nanomirrors tunasync[13005]: [NOTICE] Received command: start (debian)
1月 29 01:25:35 nanomirrors tunasync[13005]: [NOTICE] Received command: start (debian)
1月 29 06:55:39 nanomirrors tunasync[13005]: [NOTICE] succeeded syncing debian
1月 29 06:55:39 nanomirrors tunasync[13005]: [NOTICE] Next scheduled time for debian: 2017-01-29 18:55:39
```
ftpsync-debian.log:
```
Jan 28 10:05:33 8b6c293292ae ftpsync-debian[7]: Mirrorsync start
Jan 28 10:05:33 8b6c293292ae ftpsync-debian[7]: Running mirrorsync, update is required, /data/mirrors/debian//
Archive-Update-Required-mirrors.tuna.tsinghua.edu.cn exists
Jan 28 10:05:33 8b6c293292ae ftpsync-debian[7]: Running stage1: rsync -e /ftpsync/bin/rsync-ssl-tunnel -6 --safe-links --bwlimit=0 -prltvHSB8192 --timeout 3600 --stats --filter=
protect_Archive-Update-in-Progress-mirrors.tuna.tsinghua.edu.cn --filter=protect_project/trace/
mirrors.tuna.tsinghua.edu.cn --filter=protect_project/trace/mirrors.tuna.tsinghua.edu.cn-stage1 --exclude=project/trace/_hierarchy --filter=protect_project/trace/_hierarchy --exclude=project/trace/_traces --filter=protect_project/trace/_traces --filter=
protect_Archive-Update-Required-mirrors.tuna.tsinghua.edu.cn --exclude=Packages* --exclude=Sources* --exclude=Release* --exclude=InRelease --include=i18n/by-hash/** --exclude=i18n/* --exclude=ls-lR* --exclude=.~tmp~/ mirrors.tuna.t...@syncproxy.cna.debian.org::debian /data/mirrors/debian/
Jan 28 21:35:27 8b6c293292ae ftpsync-debian[7]: Back from rsync with returncode 0
Jan 28 21:35:27 8b6c293292ae ftpsync-debian[7]: Latest recorded rsync transfer speed: 983 KB/s
Jan 28 21:35:27 8b6c293292ae ftpsync-debian[7]: Running stage2: rsync -e /ftpsync/bin/rsync-ssl-tunnel -6 --safe-links --bwlimit=0 -prltvHSB8192 --timeout 3600 --stats --filter=
protect_Archive-Update-in-Progress-mirrors.tuna.tsinghua.edu.cn --filter=protect_project/trace/
mirrors.tuna.tsinghua.edu.cn --filter=protect_project/trace/mirrors.tuna.tsinghua.edu.cn-stage1 --exclude=project/trace/_hierarchy --filter=protect_project/trace/_hierarchy --exclude=project/trace/_traces --filter=protect_project/trace/_traces --filter=
protect_Archive-Update-Required-mirrors.tuna.tsinghua.edu.cn --max-delete=40000 --delay-updates --delete --delete-excluded --delete-delay --exclude=.~tmp~/ mirrors.tuna.t...@syncproxy.cna.debian.org::debian /data/mirrors/debian/
Jan 28 22:55:21 8b6c293292ae ftpsync-debian[7]: Back from rsync with returncode 0
Jan 28 22:55:21 8b6c293292ae ftpsync-debian[7]: Latest recorded rsync transfer speed: 459 KB/s
Jan 28 22:55:21 8b6c293292ae ftpsync-debian[7]: Creating a full trace file
Jan 28 22:55:39 8b6c293292ae ftpsync-debian[7]: Mirrorsync done
```
In summary, the sync on
neomirrors.tuna.tsinghua.edu.cn was in
progress and the trigger was ignored as a result.
I think by adding the scheduler, the default behavior of `ftpsync`
trigger is not changed. If there is further problem, please do not
hesitate to contact us.
Cheers,
**Please verify the digital signature attached with the e-mail.**
Miao Wang
Department of Computer Science and Technology, Tsinghua University
Add.: Zijing Apartment, Tsinghua University, Peking. P.R.C. 100084