backintime debug log

 $ sudo -i backintime backup --debug
DEBUG: [common/backintime.py:584 argParse] Arguments: {'command': 'backup', 'func': <function backup at 0x7f409a976840>, 'debug': True} | unknownArgs: []

Back In Time
Version: 1.2.0~alpha16

Back In Time comes with ABSOLUTELY NO WARRANTY.
This is free software, and you are welcome to redistribute it
under certain conditions; type `backintime --license' for details.

DEBUG: [common/backintime.py:669 getConfig] config file: /root/.config/backintime/config
DEBUG: [common/backintime.py:670 getConfig] share path: /root/.local/share/backintime
DEBUG: [common/backintime.py:672 getConfig] profiles: 1=Main profile
DEBUG: [common/pluginmanager.py:90 PluginManager.load] Register plugin path /usr/share/backintime/plugins
DEBUG: [common/pluginmanager.py:106 PluginManager.load] Add plugin qt4plugin.py
DEBUG: [common/pluginmanager.py:106 PluginManager.load] Add plugin userscriptsplugin.py
DEBUG: [common/pluginmanager.py:106 PluginManager.load] Add plugin notifyplugin.py
DEBUG: [common/snapshots.py:1739 Snapshots.flockExclusive] Set flock /tmp/backintime.lock
DEBUG: [common/snapshots.py:1747 Snapshots.flockExclusive] Set flock permissions /tmp/backintime.lock
INFO: [common/snapshots.py:632 Snapshots.backup] Lock
DEBUG: [common/tools.py:1167 inhibitSuspend] Inhibit Suspend failed because BIT was started as root.
DEBUG: [common/tools.py:795 keyringSupported] No keyring due to import errror.
DEBUG: [common/mount.py:73 Mount.__init__] pw-cache is not running
DEBUG: [common/mount.py:82 Mount.__init__] Call command: /usr/bin/backintime pw-cache start
DEBUG: [common/sshtools.py:224 SSH.startSshAgent] ssh-agent started successful: SSH_AUTH_SOCK=/tmp/ssh-liATQQc6gtns/agent.4843 | SSH_AGENT_PID=4844
DEBUG: [common/sshtools.py:267 SSH.unlockSshAgent] Add private key /root/.ssh/alex_rsa to ssh agent
DEBUG: [common/sshtools.py:272 SSH.unlockSshAgent] Password available: True
DEBUG: [common/mount.py:678 SSH.mountProcessLockAcquire] Acquire mountprocess lock /root/.local/share/backintime/mnt/4830.lock
DEBUG: [common/sshtools.py:494 SSH.checkPingHost] Check ping host
DEBUG: [common/sshtools.py:503 SSH.checkPingHost] Host 192.168.0.107 is available
DEBUG: [common/mount.py:574 SSH.checkFuse] Check fuse
DEBUG: [common/sshtools.py:328 SSH.checkLogin] Check login
DEBUG: [common/sshtools.py:440 SSH.checkRemoteFolder] Check remote folder
DEBUG: [common/sshtools.py:457 SSH.checkRemoteFolder] Call command: ssh -o ServerAliveInterval=240 -o LogLevel=Error -o IdentityFile=/root/.ssh/alex_rsa -p 22 backup@192.168.0.107 PATH=/opt/bin:/opt/sbin:$PATH d=0;test -e "/share/Backup/" || d=1;test $d -eq 1 && mkdir "/share/Backup/"; err=$?;test $d -eq 1 && exit $err;test -d "/share/Backup/" || exit 11;test -w "/share/Backup/" || exit 12;test -x "/share/Backup/" || exit 13;exit 20
DEBUG: [common/sshtools.py:463 SSH.checkRemoteFolder] Command returncode: 20
DEBUG: [common/sshtools.py:155 SSH._mount] Call mount command: sshfs -o ServerAliveInterval=240 -o LogLevel=Error -o IdentityFile=/root/.ssh/alex_rsa -p 22 -o idmap=user -o cache_dir_timeout=2 -o cache_stat_timeout=2 backup@192.168.0.107:/share/Backup/ /root/.local/share/backintime/mnt/C322B638/mountpoint
INFO: [common/mount.py:424 SSH.mount] mount ssh: backup@192.168.0.107:/share/Backup/ on /root/.local/share/backintime/mnt/C322B638/mountpoint
DEBUG: [common/mount.py:705 SSH.mountLockAquire] Set mount lock /root/.local/share/backintime/mnt/C322B638/locks/4830.lock
DEBUG: [common/mount.py:690 SSH.mountProcessLockRelease] Release mountprocess lock /root/.local/share/backintime/mnt/4830.lock
INFO: [common/snapshots.py:664 Snapshots.backup] Take a new snapshot. Profile: 1 Main profile
INFO: [common/snapshots.py:1010 Snapshots.takeSnapshot] Call rsync to take the snapshot
DEBUG: [common/snapshots.py:690 Snapshots.backup] Call command "nocache rsync --recursive --times --devices --specials --hard-links --human-readable --links --perms --executability --group --owner --info=progress2 --no-inc-recursive -y --rsh=ssh -o ServerAliveInterval=240 -o LogLevel=Error -o IdentityFile=/root/.ssh/alex_rsa -p 22 --rsync-path=nice -n 19 rsync --delete --delete-excluded -v -i --out-format=BACKINTIME: %i %n%L --link-dest=../../20170413-094033-905/backup --chmod=Du+wx --exclude=/root/.local/share/backintime/mnt/1_4830 --exclude=/root/.local/share/backintime --exclude=.local/share/backintime/mnt --include=/etc/ --include=/home/ --exclude=.gvfs --exclude=.cache* --exclude=[Cc]ache* --exclude=.thumbnails* --exclude=[Tt]rash* --exclude=*.backup* --exclude=*~ --exclude=/root/Ubuntu One --exclude=.dropbox* --exclude=/proc/* --exclude=/sys/* --exclude=/dev/* --exclude=/run/* --exclude=.seafile* --exclude=.m2/repository --exclude=/home/alex/pugg --exclude=/home/alex/puggNew --exclude=/home/alex/.jdownloader --exclude=/home/alex/.thunderbird --exclude=/home/alex/NoBackup --exclude=/home/alex/.mozilla --exclude=*.class --exclude=/etc/mtab --exclude=lost+found/* --exclude=.cache/* --exclude=/var/cache/apt/archives/*.deb --exclude=/tmp/* --exclude=/var/tmp/* --exclude=/var/backups/* --exclude=.Private --exclude=/home/alex/.config/google-chrome --exclude=/home/alex/VirtualBox VMs --exclude=/home/alex/Downloads --exclude=/home/alex/SeafileSilico/Babushka --exclude=*.git --exclude=*.config/chromium --exclude=*.p2 --exclude=*/bin/eclipse* --exclude=*/bin/netbeans* --exclude=/home/alex/android-sdks --exclude=*/bin/android* --exclude=*/bin/idea* --exclude=*/sources/**/build --exclude=/home/alex/Music --exclude=*/sources/**/bin --exclude=/home/alex/BabyTV12 --exclude=/home/alex/.local/share --exclude=/home/alex/hobby --exclude=/home/alex/.android/avd --exclude=/home/alex/bin/texlive --include=/etc/** --include=/home/** --exclude=* / backup@192.168.0.107:"/share/Backup/backintime/sunny2/root/1/new_snapshot/backup""
notify-send Back In Time (alex) : Main profile Error: rsync: failed to open "/share/Backup/backintime/sunny2/root/1/new_snapshot/backup/../../20170413-094033-905/backup/home/alex/.config/menus/applications-merged/user-chrome-apps.menu", continuing: Permission denied (13)
notify-send Back In Time (alex) : Main profile Error: rsync: failed to open "/share/Backup/backintime/sunny2/root/1/new_snapshot/backup/../../20170413-094033-905/backup/home/alex/.config/menus/applications-merged/xdg-desktop-menu-dummy.menu", continuing: Permission denied (13)
WARNING: [common/snapshots.py:690 Snapshots.backup] Command "nocache rsync --recursive --times --devices --specials --hard-links --human-readable --links --perms --executability --group --owner --info=progress2 --no-inc-recursive -y --rsh=ssh -o ServerAliveInterval=240 -o LogLevel=Error -o IdentityFile=/root/.ssh/alex_rsa -p 22 --rsync-path=nice -n 19 rsync --delete --delete-excluded -v -i --out-format=BACKINTIME: %i %n%L --link-dest=../../20170413-094033-905/backup --chmod=Du+wx --exclude=/root/.local/share/backintime/mnt/1_4830 --exclude=/root/.local/share/backintime --exclude=.local/share/backintime/mnt --include=/etc/ --include=/home/ --exclude=.gvfs --exclude=.cache* --exclude=[Cc]ache* --exclude=.thumbnails* --exclude=[Tt]rash* --exclude=*.backup* --exclude=*~ --exclude=/root/Ubuntu One --exclude=.dropbox* --exclude=/proc/* --exclude=/sys/* --exclude=/dev/* --exclude=/run/* --exclude=.seafile* --exclude=.m2/repository --exclude=/home/alex/pugg --exclude=/home/alex/puggNew --exclude=/home/alex/.jdownloader --exclude=/home/alex/.thunderbird --exclude=/home/alex/NoBackup --exclude=/home/alex/.mozilla --exclude=*.class --exclude=/etc/mtab --exclude=lost+found/* --exclude=.cache/* --exclude=/var/cache/apt/archives/*.deb --exclude=/tmp/* --exclude=/var/tmp/* --exclude=/var/backups/* --exclude=.Private --exclude=/home/alex/.config/google-chrome --exclude=/home/alex/VirtualBox VMs --exclude=/home/alex/Downloads --exclude=/home/alex/SeafileSilico/Babushka --exclude=*.git --exclude=*.config/chromium --exclude=*.p2 --exclude=*/bin/eclipse* --exclude=*/bin/netbeans* --exclude=/home/alex/android-sdks --exclude=*/bin/android* --exclude=*/bin/idea* --exclude=*/sources/**/build --exclude=/home/alex/Music --exclude=*/sources/**/bin --exclude=/home/alex/BabyTV12 --exclude=/home/alex/.local/share --exclude=/home/alex/hobby --exclude=/home/alex/.android/avd --exclude=/home/alex/bin/texlive --include=/etc/** --include=/home/** --exclude=* / backup@192.168.0.107:"/share/Backup/backintime/sunny2/root/1/new_snapshot/backup"" returns 24
DEBUG: [common/snapshots.py:1039 Execute.takeSnapshot] Call command "rsync -a --delete --rsh=ssh -o ServerAliveInterval=240 -o LogLevel=Error -o IdentityFile=/root/.ssh/alex_rsa -p 22 --rsync-path=nice -n 19 rsync /tmp/tmpx88_tnnx/ backup@192.168.0.107:"/share/Backup/backintime/sunny2/root/1/new_snapshot""
DEBUG: [common/snapshots.py:1039 Execute.takeSnapshot] Command "rsync -a --delet..." returns 0
DEBUG: [common/snapshots.py:699 Execute.backup] Call command "rsync -a --delete --rsh=ssh -o ServerAliveInterval=240 -o LogLevel=Error -o IdentityFile=/root/.ssh/alex_rsa -p 22 --rsync-path=nice -n 19 rsync /tmp/tmpnnaq5hst/ backup@192.168.0.107:"/share/Backup/backintime/sunny2/root/1/20170414-091022-296""
DEBUG: [common/snapshots.py:699 Execute.backup] Command "rsync -a --delet..." returns 0
ERROR: [common/snapshots.py:702 Snapshots.backup] Failed to take snapshot !!!
notify-send Back In Time (alex) : Main profile Failed to take snapshot 2017-04-14 09:10:22 !!!
DEBUG: [common/tools.py:795 keyringSupported] No keyring due to import errror.
DEBUG: [common/sshtools.py:206 SSH.startSshAgent] ssh-agent already running. Skip starting a new one.
DEBUG: [common/sshtools.py:319 SSH.unlockSshAgent] Private key /root/.ssh/alex_rsa is already unlocked in ssh agent
DEBUG: [common/mount.py:678 SSH.mountProcessLockAcquire] Acquire mountprocess lock /root/.local/share/backintime/mnt/4830.lock
DEBUG: [common/mount.py:761 SSH.checkLocks] Remove old and invalid lock 30404.lock
INFO: [common/mount.py:463 SSH.umount] unmount ssh: backup@192.168.0.107:/share/Backup/ from /root/.local/share/backintime/mnt/C322B638/mountpoint
DEBUG: [common/mount.py:730 SSH.mountLockRelease] Remove mount lock /root/.local/share/backintime/mnt/C322B638/locks/4830.lock
DEBUG: [common/mount.py:690 SSH.mountProcessLockRelease] Release mountprocess lock /root/.local/share/backintime/mnt/4830.lock
DEBUG: [common/snapshots.py:1755 Snapshots.flockRelease] Release flock /tmp/backintime.lock
INFO: [common/snapshots.py:737 Snapshots.backup] Unlock