Skip to main content

Backup Transport Timing Out

Comments

15 comments

  • andrew.n
    Maybe connectivity issues? or if its uploading too slowly this could happen. As I recall the timeout value can be adjusted under Backups so it will have more time for the backups to complete.
    0
  • frenziedfox
    Hi @andrew.n Thanks, I did increase the timeout under the cPanel Backup page. Is there a limit on how many 'upload attempts' it will do though? Or will it keep on going until it times out? thanks, Alex
    0
  • andrew.n
    I believe it tries 3 times.
    0
  • frenziedfox
    So it will probably fail again then. Right... may be try upping the timeout again then! :-D Thanks @andrew.n, Alex
    0
  • andrew.n
    Well I would figure out what the issue is first :) Maybe firewall is blocking it? Are you able to manually rsync data from the server to the NAS? maybe the folder on the NAS is not writable. Have a look and these and see if you can spot the issue.
    0
  • frenziedfox
    I think I've found the problem... my faulty brain! The log file for last night seems to have half of the last failed backup job from the 19th and last nights job from the 21st together! Last nights actual result: [QUOTE] [2021-12-21 02:01:05 +0000] info [cpbackup_transporter] Initializing log file [2021-12-21 02:01:05 +0000] info [cpbackup_transporter] cpbackup_transporter - parent starting [2021-12-21 02:01:05 +0000] info [cpbackup_transporter] cpbackup_transporter - child starting [2021-12-21 02:01:05 +0000] info [cpbackup_transporter] cPanel Backup Transporter Queue Daemon started. [2021-12-21 02:01:05 +0000] info [cpbackup_transporter] cpbackup_transporter - started [2021-12-21 02:01:05 +0000] info [cpbackup_transporter] cpbackup_transporter - Checking queue for tasks [2021-12-21 02:01:05 +0000] info [cpbackup_transporter] cpbackup_transporter - Processing next task [2021-12-21 02:01:05 +0000] info [cpbackup_transporter] Instantiating Object [2021-12-21 02:01:07 +0000] info [cpbackup_transporter] Starting a "copy_system_backup" operation on the "QNAP_Rsync" destination ID "d9W0WHPXp_Jmg7vmytbnwboz". [2021-12-21 02:01:07 +0000] info [cpbackup_transporter] Base path for destination is SD_Backup [2021-12-21 02:01:07 +0000] info [cpbackup_transporter] Uploading system backup file /data/backups/weekly/2021-12-21/system_files.tar to QNAP_Rsync [2021-12-21 02:01:07 +0000] info [cpbackup_transporter] Validating destination path SD_Backup/weekly/2021-12-21/system/ [2021-12-21 02:01:07 +0000] info [cpbackup_transporter] Making Path SD_Backup/weekly/2021-12-21/system/ [2021-12-21 02:01:07 +0000] info [cpbackup_transporter] Path creation successful [2021-12-21 02:01:07 +0000] info [cpbackup_transporter] Uploading system backup /data/backups/weekly/2021-12-21/system_files.tar to SD_Backup/weekly/2021-12-21/syste$ [2021-12-21 02:01:07 +0000] info [cpbackup_transporter] Attempting to upload /data/backups/weekly/2021-12-21/system_files.tar to SD_Backup/weekly/2021-12-21/system/s$ [2021-12-21 02:01:07 +0000] info [cpbackup_transporter] Upload attempt #1 starting for /data/backups/weekly/2021-12-21/system_files.tar to SD_Backup/weekly/2021-12-2$ [2021-12-21 02:01:51 +0000] info [cpbackup_transporter] Successful incremental transfer of /data/backups/weekly/2021-12-21/system_files.tar to SD_Backup/weekly/2021-$ [2021-12-21 02:01:51 +0000] info [cpbackup_transporter] cpbackup_transporter - Processing next task [2021-12-21 02:01:51 +0000] info [cpbackup_transporter] Deleting system backup tar file: /data/backups/weekly/2021-12-21/system_files.tar [2021-12-21 02:02:20 +0000] info [cpbackup_transporter] cpbackup_transporter - Waiting up to 270s for new tasks [2021-12-21 02:02:50 +0000] info [cpbackup_transporter] cpbackup_transporter - Waiting up to 240s for new tasks [2021-12-21 02:03:20 +0000] info [cpbackup_transporter] cpbackup_transporter - Waiting up to 210s for new tasks [2021-12-21 02:03:50 +0000] info [cpbackup_transporter] cpbackup_transporter - Waiting up to 180s for new tasks [2021-12-21 02:04:20 +0000] info [cpbackup_transporter] cpbackup_transporter - Waiting up to 150s for new tasks [2021-12-21 02:04:50 +0000] info [cpbackup_transporter] cpbackup_transporter - Waiting up to 120s for new tasks [2021-12-21 02:05:20 +0000] info [cpbackup_transporter] cpbackup_transporter - Waiting up to 90s for new tasks [2021-12-21 02:05:51 +0000] info [cpbackup_transporter] cpbackup_transporter - Waiting up to 60s for new tasks [2021-12-21 02:06:21 +0000] info [cpbackup_transporter] cpbackup_transporter - Waiting up to 30s for new tasks [2021-12-21 02:06:51 +0000] info [cpbackup_transporter] cpbackup_transporter - Waiting up to 0s for new tasks [2021-12-21 02:06:52 +0000] info [cpbackup_transporter] cpbackup_transporter - Exiting - the queue has been emptied; no more work to do after waiting for 300s [2021-12-21 02:06:52 +0000] info [cpbackup_transporter] cPanel Backup Transporter Queue Daemon is being stopped.
    Phew!
    0
  • frenziedfox
    Or maybe it is the 21st log but the date/time is wrong... :confused: [QUOTE] [2021-12-19 04:40:12 +0000] info [cpbackup_transporter] cPanel Backup Transporter Queue Daemon is being stopped. [2021-12-21 02:01:05 +0000] info [cpbackup_transporter] Initializing log file
    0
  • andrew.n
    that seems like completed fine?
    0
  • frenziedfox
    It's missing the /data/backups/weekly/2021-12-21/accounts/* files. It did indeed back them up... but where is the log file for it?
    0
  • cPanelAnthony
    It's missing the /data/backups/weekly/2021-12-21/accounts/* files. It did indeed back them up... but where is the log file for it?

    Hello! This article might help.
    0
  • frenziedfox
    I'm sorry, I had a stroke so it makes explaining stuff hard work. I'll start again. It was doing backups just fine, and then one day it starting failing on cpbackup_transporter. I had a look in the /usr/local/cpanel/logs/cpbackup_transporter/
    directory and upped a couple of timeouts: cpbackup_transporter.1639283724 - this one failed [QUOTE] [2021-12-12 02:01:34 +0000] info [cpbackup_transporter] Initializing log file [2021-12-12 02:01:34 +0000] info [cpbackup_transporter] cpbackup_transporter - parent starting [2021-12-12 02:01:34 +0000] info [cpbackup_transporter] cpbackup_transporter - child starting [2021-12-12 02:01:34 +0000] info [cpbackup_transporter] cPanel Backup Transporter Queue Daemon started. [2021-12-12 02:01:34 +0000] info [cpbackup_transporter] cpbackup_transporter - started [2021-12-12 02:01:34 +0000] info [cpbackup_transporter] cpbackup_transporter - Checking queue for tasks [2021-12-12 02:01:34 +0000] info [cpbackup_transporter] cpbackup_transporter - Processing next task [2021-12-12 02:01:34 +0000] info [cpbackup_transporter] Instantiating Object [2021-12-12 02:01:35 +0000] info [cpbackup_transporter] Starting a "copy_system_backup" operation on the "QNAP_Rsync" destination ID "d9W0WHPXp_Jmg7vmytbnwboz". [2021-12-12 02:01:35 +0000] info [cpbackup_transporter] Base path for destination is SD_Backup [2021-12-12 02:01:35 +0000] info [cpbackup_transporter] Uploading system backup file /data/backups/weekly/2021-12-12/system_files.tar to QNAP_Rsync [2021-12-12 02:01:35 +0000] info [cpbackup_transporter] Validating destination path SD_Backup/weekly/2021-12-12/system/ [2021-12-12 02:01:35 +0000] info [cpbackup_transporter] Making Path SD_Backup/weekly/2021-12-12/system/ [2021-12-12 02:01:35 +0000] info [cpbackup_transporter] Path creation successful [2021-12-12 02:01:35 +0000] info [cpbackup_transporter] Uploading system backup /data/backups/weekly/2021-12-12/system_files.tar to SD_Backup/weekly/2021-12-12/system/system_files.tar (from weekly/2021-12-12/system/system_files.tar) [2021-12-12 02:01:35 +0000] info [cpbackup_transporter] Attempting to upload /data/backups/weekly/2021-12-12/system_files.tar to SD_Backup/weekly/2021-12-12/system/system_files.tar for destination: QNAP_Rsync [2021-12-12 02:01:35 +0000] info [cpbackup_transporter] Upload attempt #1 starting for /data/backups/weekly/2021-12-12/system_files.tar to SD_Backup/weekly/2021-12-12/system/system_files.tar for destination: QNAP_Rsync [2021-12-12 02:02:15 +0000] info [cpbackup_transporter] Successful incremental transfer of /data/backups/weekly/2021-12-12/system_files.tar to SD_Backup/weekly/2021-12-12/system/system_files.tar for destination QNAP_Rsync [2021-12-12 02:02:15 +0000] info [cpbackup_transporter] cpbackup_transporter - Processing next task [2021-12-12 02:02:15 +0000] info [cpbackup_transporter] Deleting system backup tar file: /data/backups/weekly/2021-12-12/system_files.tar [2021-12-12 02:02:44 +0000] info [cpbackup_transporter] cpbackup_transporter - Waiting up to 270s for new tasks [2021-12-12 02:03:05 +0000] info [cpbackup_transporter] cpbackup_transporter - Processing next task [2021-12-12 02:03:05 +0000] info [cpbackup_transporter] Instantiating Object [2021-12-12 02:03:05 +0000] info [cpbackup_transporter] Starting a "copy" operation on the "QNAP_Rsync" destination ID "d9W0WHPXp_Jmg7vmytbnwboz". [2021-12-12 02:03:05 +0000] info [cpbackup_transporter] Base path for destination is SD_Backup [2021-12-12 02:03:05 +0000] info [cpbackup_transporter] Validating destination path SD_Backup/weekly/2021-12-12/accounts/driftnet [2021-12-12 02:03:06 +0000] info [cpbackup_transporter] Making Path SD_Backup/weekly/2021-12-12/accounts/driftnet [2021-12-12 02:03:06 +0000] info [cpbackup_transporter] Path creation successful [2021-12-12 02:03:06 +0000] info [cpbackup_transporter] Uploading account backup /data/backups/weekly/2021-12-12/accounts/driftnet to SD_Backup/weekly/2021-12-12/accounts/driftnet (from weekly/2021-12-12/accounts/driftnet) [2021-12-12 02:03:06 +0000] info [cpbackup_transporter] Attempting to upload /data/backups/weekly/2021-12-12/accounts/driftnet to SD_Backup/weekly/2021-12-12/accounts/driftnet for destination: QNAP_Rsync [2021-12-12 02:03:06 +0000] info [cpbackup_transporter] Upload attempt #1 starting for /data/backups/weekly/2021-12-12/accounts/driftnet to SD_Backup/weekly/2021-12-12/accounts/driftnet for destination: QNAP_Rsync [2021-12-12 02:14:06 +0000] info [cpbackup_transporter] Successful incremental transfer of /data/backups/weekly/2021-12-12/accounts/driftnet to SD_Backup/weekly/2021-12-12/accounts/driftnet for destination QNAP_Rsync [2021-12-12 02:14:35 +0000] info [cpbackup_transporter] cpbackup_transporter - Waiting up to 270s for new tasks [2021-12-12 02:15:05 +0000] info [cpbackup_transporter] cpbackup_transporter - Waiting up to 240s for new tasks [2021-12-12 02:15:18 +0000] info [cpbackup_transporter] cpbackup_transporter - Processing next task [2021-12-12 02:15:18 +0000] info [cpbackup_transporter] Instantiating Object [2021-12-12 02:15:19 +0000] info [cpbackup_transporter] Starting a "copy" operation on the "QNAP_Rsync" destination ID "d9W0WHPXp_Jmg7vmytbnwboz". [2021-12-12 02:15:19 +0000] info [cpbackup_transporter] Base path for destination is SD_Backup [2021-12-12 02:15:19 +0000] info [cpbackup_transporter] Validating destination path SD_Backup/weekly/2021-12-12/accounts/sd [2021-12-12 02:15:19 +0000] info [cpbackup_transporter] Making Path SD_Backup/weekly/2021-12-12/accounts/sd [2021-12-12 02:15:19 +0000] info [cpbackup_transporter] Path creation successful [2021-12-12 02:15:19 +0000] info [cpbackup_transporter] Uploading account backup /data/backups/weekly/2021-12-12/accounts/sd to SD_Backup/weekly/2021-12-12/accounts/sd (from weekly/2021-12-12/accounts/sd) [2021-12-12 02:15:19 +0000] info [cpbackup_transporter] Attempting to upload /data/backups/weekly/2021-12-12/accounts/sd to SD_Backup/weekly/2021-12-12/accounts/sd for destination: QNAP_Rsync [2021-12-12 02:15:19 +0000] info [cpbackup_transporter] Upload attempt #1 starting for /data/backups/weekly/2021-12-12/accounts/sd to SD_Backup/weekly/2021-12-12/accounts/sd for destination: QNAP_Rsync [2021-12-12 03:00:19 +0000] warn [cpbackup_transporter] Upload attempt failed: Time out reached for upload attempt #1 at /usr/local/cpanel/Cpanel/LoggerAdapter.pm line 27. Cpanel::LoggerAdapter::warn(Cpanel::LoggerAdapter=HASH(0x22788a8), "Upload attempt failed: Time out reached for upload attempt #1\x{a}") called at /usr/local/cpanel/Cpanel/Backup/Queue.pm line 633 Cpanel::Backup::Queue::transport_backup::attempt_to_upload_file(Cpanel::Backup::Queue::transport_backup=HASH(0x205a6e8), Cpanel::Transport::Files::Rsync=HASH(0x207bed0), "/data/backups/weekly/2021-12-12/accounts/sd", "SD_Backup/weekly/2021-12-12/accounts/sd", Cpanel::LoggerAdapter=HASH(0x22788a8)) called at /usr/local/cpanel/Cpanel/Backup/Queue.pm line 333 Cpanel::Backup::Queue::transport_backup::process_task(Cpanel::Backup::Queue::transport_backup=HASH(0x205a6e8), Cpanel::TaskQueue::Task=HASH(0x3522f28), Cpanel::LoggerAdapter=HASH(0x22788a8)) called at /usr/local/cpanel/Cpanel/TaskQueue.pm line 635 eval {...} called at /usr/local/cpanel/Cpanel/TaskQueue.pm line 638 Cpanel::TaskQueue::__ANON__() called at /usr/local/cpanel/Cpanel/StateFile.pm line 241 eval {...} called at /usr/local/cpanel/Cpanel/StateFile.pm line 241 Cpanel::StateFile::Guard::call_unlocked(Cpanel::StateFile::Guard=HASH(0x34f5f68), CODE(0x2e1c520)) called at /usr/local/cpanel/Cpanel/TaskQueue.pm line 643 Cpanel::TaskQueue::process_next_task(Cpanel::TaskQueue=HASH(0x207c038)) called at /usr/local/cpanel/bin/cpbackup_transporter line 168 eval {...} called at /usr/local/cpanel/bin/cpbackup_transporter line 166 [2021-12-12 03:00:20 +0000] info [cpbackup_transporter] Upload attempt #2 starting for /data/backups/weekly/2021-12-12/accounts/sd to SD_Backup/weekly/2021-12-12/accounts/sd for destination: QNAP_Rsync [2021-12-12 03:45:20 +0000] warn [cpbackup_transporter] Upload attempt failed: Time out reached for upload attempt #2 at /usr/local/cpanel/Cpanel/LoggerAdapter.pm line 27. Cpanel::LoggerAdapter::warn(Cpanel::LoggerAdapter=HASH(0x22788a8), "Upload attempt failed: Time out reached for upload attempt #2\x{a}") called at /usr/local/cpanel/Cpanel/Backup/Queue.pm line 633 Cpanel::Backup::Queue::transport_backup::attempt_to_upload_file(Cpanel::Backup::Queue::transport_backup=HASH(0x205a6e8), Cpanel::Transport::Files::Rsync=HASH(0x207bed0), "/data/backups/weekly/2021-12-12/accounts/sd", "SD_Backup/weekly/2021-12-12/accounts/sd", Cpanel::LoggerAdapter=HASH(0x22788a8)) called at /usr/local/cpanel/Cpanel/Backup/Queue.pm line 333 Cpanel::Backup::Queue::transport_backup::process_task(Cpanel::Backup::Queue::transport_backup=HASH(0x205a6e8), Cpanel::TaskQueue::Task=HASH(0x3522f28), Cpanel::LoggerAdapter=HASH(0x22788a8)) called at /usr/local/cpanel/Cpanel/TaskQueue.pm line 635 eval {...} called at /usr/local/cpanel/Cpanel/TaskQueue.pm line 638 Cpanel::TaskQueue::__ANON__() called at /usr/local/cpanel/Cpanel/StateFile.pm line 241 eval {...} called at /usr/local/cpanel/Cpanel/StateFile.pm line 241 Cpanel::StateFile::Guard::call_unlocked(Cpanel::StateFile::Guard=HASH(0x34f5f68), CODE(0x2e1c520)) called at /usr/local/cpanel/Cpanel/TaskQueue.pm line 643 Cpanel::TaskQueue::process_next_task(Cpanel::TaskQueue=HASH(0x207c038)) called at /usr/local/cpanel/bin/cpbackup_transporter line 168 eval {...} called at /usr/local/cpanel/bin/cpbackup_transporter line 166 [2021-12-12 03:45:20 +0000] info [cpbackup_transporter] Upload attempt #3 starting for /data/backups/weekly/2021-12-12/accounts/sd to SD_Backup/weekly/2021-12-12/accounts/sd for destination: QNAP_Rsync [2021-12-12 04:30:03 +0000] info [cpbackup_transporter] Successful incremental transfer of /data/backups/weekly/2021-12-12/accounts/sd to SD_Backup/weekly/2021-12-12/accounts/sd for destination QNAP_Rsync [2021-12-12 04:30:05 +0000] info [cpbackup_transporter] cpbackup_transporter - Processing next task [2021-12-12 04:30:06 +0000] info [cpbackup_transporter] Instantiating Object [2021-12-12 04:30:07 +0000] info [cpbackup_transporter] Starting a "copy_backup_metadata" operation on the "QNAP_Rsync" destination ID "d9W0WHPXp_Jmg7vmytbnwboz". [2021-12-12 04:30:07 +0000] info [cpbackup_transporter] Base path for destination is SD_Backup [2021-12-12 04:30:07 +0000] info [cpbackup_transporter] Uploading backup metadata file /data/backups/weekly/2021-12-12/accounts/.master.meta to QNAP_Rsync [2021-12-12 04:30:07 +0000] info [cpbackup_transporter] Validating destination path SD_Backup/weekly/2021-12-12/accounts/ [2021-12-12 04:30:07 +0000] info [cpbackup_transporter] Path exists [2021-12-12 04:30:07 +0000] info [cpbackup_transporter] Uploading backup metadata /data/backups/weekly/2021-12-12/accounts/.master.meta to SD_Backup/weekly/2021-12-12/accounts/.master.meta (from weekly/2021-12-12/accounts/.master.meta) [2021-12-12 04:30:07 +0000] info [cpbackup_transporter] Attempting to upload /data/backups/weekly/2021-12-12/accounts/.master.meta to SD_Backup/weekly/2021-12-12/accounts/.master.meta for destination: QNAP_Rsync [2021-12-12 04:30:07 +0000] info [cpbackup_transporter] Upload attempt #1 starting for /data/backups/weekly/2021-12-12/accounts/.master.meta to SD_Backup/weekly/2021-12-12/accounts/.master.meta for destination: QNAP_Rsync [2021-12-12 04:30:07 +0000] info [cpbackup_transporter] Successful incremental transfer of /data/backups/weekly/2021-12-12/accounts/.master.meta to SD_Backup/weekly/2021-12-12/accounts/.master.meta for destination QNAP_Rsync [2021-12-12 04:30:07 +0000] info [cpbackup_transporter] cpbackup_transporter - Processing next task [2021-12-12 04:30:07 +0000] info [cpbackup_transporter] Instantiating Object [2021-12-12 04:30:08 +0000] info [cpbackup_transporter] Starting a "prune" operation on the "QNAP_Rsync" destination ID "d9W0WHPXp_Jmg7vmytbnwboz". [2021-12-12 04:30:08 +0000] info [cpbackup_transporter] Base path for destination is SD_Backup [2021-12-12 04:30:08 +0000] info [cpbackup_transporter] Performing prune operation, retaining 7 items on: QNAP_Rsync [2021-12-12 04:30:08 +0000] info [cpbackup_transporter] Pruning backup directory: SD_Backup/weekly/2021-10-24, from QNAP_Rsync [2021-12-12 04:30:24 +0000] info [cpbackup_transporter] cpbackup_transporter - Processing next task [2021-12-12 04:30:53 +0000] info [cpbackup_transporter] cpbackup_transporter - Waiting up to 270s for new tasks [2021-12-12 04:31:23 +0000] info [cpbackup_transporter] cpbackup_transporter - Waiting up to 240s for new tasks [2021-12-12 04:31:53 +0000] info [cpbackup_transporter] cpbackup_transporter - Waiting up to 210s for new tasks [2021-12-12 04:32:23 +0000] info [cpbackup_transporter] cpbackup_transporter - Waiting up to 180s for new tasks [2021-12-12 04:32:53 +0000] info [cpbackup_transporter] cpbackup_transporter - Waiting up to 150s for new tasks [2021-12-12 04:33:23 +0000] info [cpbackup_transporter] cpbackup_transporter - Waiting up to 120s for new tasks [2021-12-12 04:33:53 +0000] info [cpbackup_transporter] cpbackup_transporter - Waiting up to 90s for new tasks [2021-12-12 04:34:23 +0000] info [cpbackup_transporter] cpbackup_transporter - Waiting up to 60s for new tasks [2021-12-12 04:34:53 +0000] info [cpbackup_transporter] cpbackup_transporter - Waiting up to 30s for new tasks [2021-12-12 04:35:23 +0000] info [cpbackup_transporter] cpbackup_transporter - Waiting up to 0s for new tasks [2021-12-12 04:35:24 +0000] info [cpbackup_transporter] cpbackup_transporter - Exiting - the queue has been emptied; no more work to do after waiting for 300s [2021-12-12 04:35:24 +0000] info [cpbackup_transporter] cPanel Backup Transporter Queue Daemon is being stopped. [2021-12-19 02:01:40 +0000] info [cpbackup_transporter] Initializing log file [2021-12-19 02:01:40 +0000] info [cpbackup_transporter] cpbackup_transporter - parent starting [2021-12-19 02:01:40 +0000] info [cpbackup_transporter] cpbackup_transporter - child starting [2021-12-19 02:01:40 +0000] info [cpbackup_transporter] cPanel Backup Transporter Queue Daemon started. [2021-12-19 02:01:40 +0000] info [cpbackup_transporter] cpbackup_transporter - started [2021-12-19 02:01:40 +0000] info [cpbackup_transporter] cpbackup_transporter - Checking queue for tasks [2021-12-19 02:01:40 +0000] info [cpbackup_transporter] cpbackup_transporter - Processing next task [2021-12-19 02:01:40 +0000] info [cpbackup_transporter] Instantiating Object [2021-12-19 02:01:41 +0000] info [cpbackup_transporter] Starting a "copy_system_backup" operation on the "QNAP_Rsync" destination ID "d9W0WHPXp_Jmg7vmytbnwboz". [2021-12-19 02:01:41 +0000] info [cpbackup_transporter] Base path for destination is SD_Backup [2021-12-19 02:01:41 +0000] info [cpbackup_transporter] Uploading system backup file /data/backups/weekly/2021-12-19/system_files.tar to QNAP_Rsync [2021-12-19 02:01:41 +0000] info [cpbackup_transporter] Validating destination path SD_Backup/weekly/2021-12-19/system/ [2021-12-19 02:01:42 +0000] info [cpbackup_transporter] Making Path SD_Backup/weekly/2021-12-19/system/ [2021-12-19 02:01:42 +0000] info [cpbackup_transporter] Path creation successful [2021-12-19 02:01:42 +0000] info [cpbackup_transporter] Uploading system backup /data/backups/weekly/2021-12-19/system_files.tar to SD_Backup/weekly/2021-12-19/system/system_files.tar (from weekly/2021-12-19/system/system_files.tar) [2021-12-19 02:01:42 +0000] info [cpbackup_transporter] Attempting to upload /data/backups/weekly/2021-12-19/system_files.tar to SD_Backup/weekly/2021-12-19/system/system_files.tar for destination: QNAP_Rsync [2021-12-19 02:01:42 +0000] info [cpbackup_transporter] Upload attempt #1 starting for /data/backups/weekly/2021-12-19/system_files.tar to SD_Backup/weekly/2021-12-19/system/system_files.tar for destination: QNAP_Rsync [2021-12-19 02:02:23 +0000] info [cpbackup_transporter] Successful incremental transfer of /data/backups/weekly/2021-12-19/system_files.tar to SD_Backup/weekly/2021-12-19/system/system_files.tar for destination QNAP_Rsync [2021-12-19 02:02:23 +0000] info [cpbackup_transporter] cpbackup_transporter - Processing next task [2021-12-19 02:02:23 +0000] info [cpbackup_transporter] Deleting system backup tar file: /data/backups/weekly/2021-12-19/system_files.tar [2021-12-19 02:02:52 +0000] info [cpbackup_transporter] cpbackup_transporter - Waiting up to 270s for new tasks [2021-12-19 02:03:22 +0000] info [cpbackup_transporter] cpbackup_transporter - Waiting up to 240s for new tasks [2021-12-19 02:03:53 +0000] info [cpbackup_transporter] cpbackup_transporter - Waiting up to 210s for new tasks [2021-12-19 02:04:23 +0000] info [cpbackup_transporter] cpbackup_transporter - Waiting up to 180s for new tasks [2021-12-19 02:04:53 +0000] info [cpbackup_transporter] cpbackup_transporter - Waiting up to 150s for new tasks [2021-12-19 02:05:23 +0000] info [cpbackup_transporter] cpbackup_transporter - Waiting up to 120s for new tasks [2021-12-19 02:05:53 +0000] info [cpbackup_transporter] cpbackup_transporter - Waiting up to 90s for new tasks [2021-12-19 02:06:23 +0000] info [cpbackup_transporter] cpbackup_transporter - Waiting up to 60s for new tasks [2021-12-19 02:06:53 +0000] info [cpbackup_transporter] cpbackup_transporter - Waiting up to 30s for new tasks [2021-12-19 02:07:23 +0000] info [cpbackup_transporter] cpbackup_transporter - Waiting up to 0s for new tasks [2021-12-19 02:07:24 +0000] info [cpbackup_transporter] cpbackup_transporter - Exiting - the queue has been emptied; no more work to do after waiting for 300s [2021-12-19 02:07:24 +0000] info [cpbackup_transporter] cPanel Backup Transporter Queue Daemon is being stopped.
    cpbackup_transporter.1639888812 - this one worked again [QUOTE] [2021-12-19 02:11:34 +0000] info [cpbackup_transporter] Initializing log file [2021-12-19 02:11:34 +0000] info [cpbackup_transporter] cpbackup_transporter - parent starting [2021-12-19 02:11:34 +0000] info [cpbackup_transporter] cpbackup_transporter - child starting [2021-12-19 02:11:34 +0000] info [cpbackup_transporter] cPanel Backup Transporter Queue Daemon started. [2021-12-19 02:11:34 +0000] info [cpbackup_transporter] cpbackup_transporter - started [2021-12-19 02:11:34 +0000] info [cpbackup_transporter] cpbackup_transporter - Checking queue for tasks [2021-12-19 02:11:34 +0000] info [cpbackup_transporter] cpbackup_transporter - Processing next task [2021-12-19 02:11:34 +0000] info [cpbackup_transporter] Instantiating Object [2021-12-19 02:11:35 +0000] info [cpbackup_transporter] Starting a "copy" operation on the "QNAP_Rsync" destination ID "d9W0WHPXp_Jmg7vmytbnwboz". [2021-12-19 02:11:35 +0000] info [cpbackup_transporter] Base path for destination is SD_Backup [2021-12-19 02:11:35 +0000] info [cpbackup_transporter] Validating destination path SD_Backup/weekly/2021-12-19/accounts/sd [2021-12-19 02:11:35 +0000] info [cpbackup_transporter] Making Path SD_Backup/weekly/2021-12-19/accounts/sd [2021-12-19 02:11:35 +0000] info [cpbackup_transporter] Path creation successful [2021-12-19 02:11:35 +0000] info [cpbackup_transporter] Uploading account backup /data/backups/weekly/2021-12-19/accounts/sd to SD_Backup/weekly/2021-12-19/accounts/sd (from weekly/2021-12-19/accounts/sd) [2021-12-19 02:11:35 +0000] info [cpbackup_transporter] Attempting to upload /data/backups/weekly/2021-12-19/accounts/sd to SD_Backup/weekly/2021-12-19/accounts/sd for destination: QNAP_Rsync [2021-12-19 02:11:35 +0000] info [cpbackup_transporter] Upload attempt #1 starting for /data/backups/weekly/2021-12-19/accounts/sd to SD_Backup/weekly/2021-12-19/accounts/sd for destination: QNAP_Rsync [2021-12-19 02:56:35 +0000] warn [cpbackup_transporter] Upload attempt failed: Time out reached for upload attempt #1 at /usr/local/cpanel/Cpanel/LoggerAdapter.pm line 27. Cpanel::LoggerAdapter::warn(Cpanel::LoggerAdapter=HASH(0xd25bf8), "Upload attempt failed: Time out reached for upload attempt #1\x{a}") called at /usr/local/cpanel/Cpanel/Backup/Queue.pm line 633 Cpanel::Backup::Queue::transport_backup::attempt_to_upload_file(Cpanel::Backup::Queue::transport_backup=HASH(0xb086e8), Cpanel::Transport::Files::Rsync=HASH(0xb2aa98), "/data/backups/weekly/2021-12-19/accounts/sd", "SD_Backup/weekly/2021-12-19/accounts/sd", Cpanel::LoggerAdapter=HASH(0xd25bf8)) called at /usr/local/cpanel/Cpanel/Backup/Queue.pm line 333 Cpanel::Backup::Queue::transport_backup::process_task(Cpanel::Backup::Queue::transport_backup=HASH(0xb086e8), Cpanel::TaskQueue::Task=HASH(0xb2adb0), Cpanel::LoggerAdapter=HASH(0xd25bf8)) called at /usr/local/cpanel/Cpanel/TaskQueue.pm line 635 eval {...} called at /usr/local/cpanel/Cpanel/TaskQueue.pm line 638 Cpanel::TaskQueue::__ANON__() called at /usr/local/cpanel/Cpanel/StateFile.pm line 241 eval {...} called at /usr/local/cpanel/Cpanel/StateFile.pm line 241 Cpanel::StateFile::Guard::call_unlocked(Cpanel::StateFile::Guard=HASH(0x1b01e30), CODE(0xb2a738)) called at /usr/local/cpanel/Cpanel/TaskQueue.pm line 643 Cpanel::TaskQueue::process_next_task(Cpanel::TaskQueue=HASH(0x1b01c50)) called at /usr/local/cpanel/bin/cpbackup_transporter line 168 eval {...} called at /usr/local/cpanel/bin/cpbackup_transporter line 166 [2021-12-19 02:56:36 +0000] info [cpbackup_transporter] Upload attempt #2 starting for /data/backups/weekly/2021-12-19/accounts/sd to SD_Backup/weekly/2021-12-19/accounts/sd for destination: QNAP_Rsync [2021-12-19 03:41:36 +0000] warn [cpbackup_transporter] Upload attempt failed: Time out reached for upload attempt #2 at /usr/local/cpanel/Cpanel/LoggerAdapter.pm line 27. Cpanel::LoggerAdapter::warn(Cpanel::LoggerAdapter=HASH(0xd25bf8), "Upload attempt failed: Time out reached for upload attempt #2\x{a}") called at /usr/local/cpanel/Cpanel/Backup/Queue.pm line 633 Cpanel::Backup::Queue::transport_backup::attempt_to_upload_file(Cpanel::Backup::Queue::transport_backup=HASH(0xb086e8), Cpanel::Transport::Files::Rsync=HASH(0xb2aa98), "/data/backups/weekly/2021-12-19/accounts/sd", "SD_Backup/weekly/2021-12-19/accounts/sd", Cpanel::LoggerAdapter=HASH(0xd25bf8)) called at /usr/local/cpanel/Cpanel/Backup/Queue.pm line 333 Cpanel::Backup::Queue::transport_backup::process_task(Cpanel::Backup::Queue::transport_backup=HASH(0xb086e8), Cpanel::TaskQueue::Task=HASH(0xb2adb0), Cpanel::LoggerAdapter=HASH(0xd25bf8)) called at /usr/local/cpanel/Cpanel/TaskQueue.pm line 635 eval {...} called at /usr/local/cpanel/Cpanel/TaskQueue.pm line 638 Cpanel::TaskQueue::__ANON__() called at /usr/local/cpanel/Cpanel/StateFile.pm line 241 eval {...} called at /usr/local/cpanel/Cpanel/StateFile.pm line 241 Cpanel::StateFile::Guard::call_unlocked(Cpanel::StateFile::Guard=HASH(0x1b01e30), CODE(0xb2a738)) called at /usr/local/cpanel/Cpanel/TaskQueue.pm line 643 Cpanel::TaskQueue::process_next_task(Cpanel::TaskQueue=HASH(0x1b01c50)) called at /usr/local/cpanel/bin/cpbackup_transporter line 168 eval {...} called at /usr/local/cpanel/bin/cpbackup_transporter line 166 [2021-12-19 03:41:36 +0000] info [cpbackup_transporter] Upload attempt #3 starting for /data/backups/weekly/2021-12-19/accounts/sd to SD_Backup/weekly/2021-12-19/accounts/sd for destination: QNAP_Rsync [2021-12-19 04:26:36 +0000] warn [cpbackup_transporter] Upload attempt failed: Time out reached for upload attempt #3 at /usr/local/cpanel/Cpanel/LoggerAdapter.pm line 27. Cpanel::LoggerAdapter::warn(Cpanel::LoggerAdapter=HASH(0xd25bf8), "Upload attempt failed: Time out reached for upload attempt #3\x{a}") called at /usr/local/cpanel/Cpanel/Backup/Queue.pm line 633 Cpanel::Backup::Queue::transport_backup::attempt_to_upload_file(Cpanel::Backup::Queue::transport_backup=HASH(0xb086e8), Cpanel::Transport::Files::Rsync=HASH(0xb2aa98), "/data/backups/weekly/2021-12-19/accounts/sd", "SD_Backup/weekly/2021-12-19/accounts/sd", Cpanel::LoggerAdapter=HASH(0xd25bf8)) called at /usr/local/cpanel/Cpanel/Backup/Queue.pm line 333 Cpanel::Backup::Queue::transport_backup::process_task(Cpanel::Backup::Queue::transport_backup=HASH(0xb086e8), Cpanel::TaskQueue::Task=HASH(0xb2adb0), Cpanel::LoggerAdapter=HASH(0xd25bf8)) called at /usr/local/cpanel/Cpanel/TaskQueue.pm line 635 eval {...} called at /usr/local/cpanel/Cpanel/TaskQueue.pm line 638 Cpanel::TaskQueue::__ANON__() called at /usr/local/cpanel/Cpanel/StateFile.pm line 241 eval {...} called at /usr/local/cpanel/Cpanel/StateFile.pm line 241 Cpanel::StateFile::Guard::call_unlocked(Cpanel::StateFile::Guard=HASH(0x1b01e30), CODE(0xb2a738)) called at /usr/local/cpanel/Cpanel/TaskQueue.pm line 643 Cpanel::TaskQueue::process_next_task(Cpanel::TaskQueue=HASH(0x1b01c50)) called at /usr/local/cpanel/bin/cpbackup_transporter line 168 eval {...} called at /usr/local/cpanel/bin/cpbackup_transporter line 166 [2021-12-19 04:26:37 +0000] info [cpbackup_transporter] cpbackup_transporter - Processing next task [2021-12-19 04:26:37 +0000] info [cpbackup_transporter] Instantiating Object [2021-12-19 04:26:37 +0000] info [cpbackup_transporter] Starting a "copy" operation on the "QNAP_Rsync" destination ID "d9W0WHPXp_Jmg7vmytbnwboz". [2021-12-19 04:26:37 +0000] info [cpbackup_transporter] Base path for destination is SD_Backup [2021-12-19 04:26:37 +0000] info [cpbackup_transporter] Validating destination path SD_Backup/weekly/2021-12-19/accounts/driftnet [2021-12-19 04:26:37 +0000] info [cpbackup_transporter] Making Path SD_Backup/weekly/2021-12-19/accounts/driftnet [2021-12-19 04:26:37 +0000] info [cpbackup_transporter] Path creation successful [2021-12-19 04:26:37 +0000] info [cpbackup_transporter] Uploading account backup /data/backups/weekly/2021-12-19/accounts/driftnet to SD_Backup/weekly/2021-12-19/accounts/driftnet (from weekly/2021-12-19/accounts/driftnet) [2021-12-19 04:26:37 +0000] info [cpbackup_transporter] Attempting to upload /data/backups/weekly/2021-12-19/accounts/driftnet to SD_Backup/weekly/2021-12-19/accounts/driftnet for destination: QNAP_Rsync [2021-12-19 04:26:37 +0000] info [cpbackup_transporter] Upload attempt #1 starting for /data/backups/weekly/2021-12-19/accounts/driftnet to SD_Backup/weekly/2021-12-19/accounts/driftnet for destination: QNAP_Rsync [2021-12-19 04:34:59 +0000] info [cpbackup_transporter] Successful incremental transfer of /data/backups/weekly/2021-12-19/accounts/driftnet to SD_Backup/weekly/2021-12-19/accounts/driftnet for destination QNAP_Rsync [2021-12-19 04:34:59 +0000] info [cpbackup_transporter] cpbackup_transporter - Processing next task [2021-12-19 04:34:59 +0000] info [cpbackup_transporter] Instantiating Object [2021-12-19 04:34:59 +0000] info [cpbackup_transporter] Starting a "copy_backup_metadata" operation on the "QNAP_Rsync" destination ID "d9W0WHPXp_Jmg7vmytbnwboz". [2021-12-19 04:34:59 +0000] info [cpbackup_transporter] Base path for destination is SD_Backup [2021-12-19 04:34:59 +0000] info [cpbackup_transporter] Uploading backup metadata file /data/backups/weekly/2021-12-19/accounts/.master.meta to QNAP_Rsync [2021-12-19 04:34:59 +0000] info [cpbackup_transporter] Validating destination path SD_Backup/weekly/2021-12-19/accounts/ [2021-12-19 04:35:00 +0000] info [cpbackup_transporter] Path exists [2021-12-19 04:35:00 +0000] info [cpbackup_transporter] Uploading backup metadata /data/backups/weekly/2021-12-19/accounts/.master.meta to SD_Backup/weekly/2021-12-19/accounts/.master.meta (from weekly/2021-12-19/accounts/.master.meta) [2021-12-19 04:35:00 +0000] info [cpbackup_transporter] Attempting to upload /data/backups/weekly/2021-12-19/accounts/.master.meta to SD_Backup/weekly/2021-12-19/accounts/.master.meta for destination: QNAP_Rsync [2021-12-19 04:35:00 +0000] info [cpbackup_transporter] Upload attempt #1 starting for /data/backups/weekly/2021-12-19/accounts/.master.meta to SD_Backup/weekly/2021-12-19/accounts/.master.meta for destination: QNAP_Rsync [2021-12-19 04:35:00 +0000] info [cpbackup_transporter] Successful incremental transfer of /data/backups/weekly/2021-12-19/accounts/.master.meta to SD_Backup/weekly/2021-12-19/accounts/.master.meta for destination QNAP_Rsync [2021-12-19 04:35:00 +0000] info [cpbackup_transporter] cpbackup_transporter - Processing next task [2021-12-19 04:35:00 +0000] info [cpbackup_transporter] Instantiating Object [2021-12-19 04:35:00 +0000] info [cpbackup_transporter] Starting a "prune" operation on the "QNAP_Rsync" destination ID "d9W0WHPXp_Jmg7vmytbnwboz". [2021-12-19 04:35:00 +0000] info [cpbackup_transporter] Base path for destination is SD_Backup [2021-12-19 04:35:00 +0000] info [cpbackup_transporter] Performing prune operation, retaining 7 items on: QNAP_Rsync [2021-12-19 04:35:00 +0000] info [cpbackup_transporter] Pruning backup directory: SD_Backup/weekly/2021-10-31, from QNAP_Rsync [2021-12-19 04:35:11 +0000] info [cpbackup_transporter] cpbackup_transporter - Processing next task [2021-12-19 04:35:40 +0000] info [cpbackup_transporter] cpbackup_transporter - Waiting up to 270s for new tasks [2021-12-19 04:36:10 +0000] info [cpbackup_transporter] cpbackup_transporter - Waiting up to 240s for new tasks [2021-12-19 04:36:40 +0000] info [cpbackup_transporter] cpbackup_transporter - Waiting up to 210s for new tasks [2021-12-19 04:37:10 +0000] info [cpbackup_transporter] cpbackup_transporter - Waiting up to 180s for new tasks [2021-12-19 04:37:40 +0000] info [cpbackup_transporter] cpbackup_transporter - Waiting up to 150s for new tasks [2021-12-19 04:38:10 +0000] info [cpbackup_transporter] cpbackup_transporter - Waiting up to 120s for new tasks [2021-12-19 04:38:40 +0000] info [cpbackup_transporter] cpbackup_transporter - Waiting up to 90s for new tasks [2021-12-19 04:39:10 +0000] info [cpbackup_transporter] cpbackup_transporter - Waiting up to 60s for new tasks [2021-12-19 04:39:40 +0000] info [cpbackup_transporter] cpbackup_transporter - Waiting up to 30s for new tasks [2021-12-19 04:40:11 +0000] info [cpbackup_transporter] cpbackup_transporter - Waiting up to 0s for new tasks [2021-12-19 04:40:12 +0000] info [cpbackup_transporter] cpbackup_transporter - Exiting - the queue has been emptied; no more work to do after waiting for 300s [2021-12-19 04:40:12 +0000] info [cpbackup_transporter] cPanel Backup Transporter Queue Daemon is being stopped. [2021-12-21 02:01:05 +0000] info [cpbackup_transporter] Initializing log file [2021-12-21 02:01:05 +0000] info [cpbackup_transporter] cpbackup_transporter - parent starting [2021-12-21 02:01:05 +0000] info [cpbackup_transporter] cpbackup_transporter - child starting [2021-12-21 02:01:05 +0000] info [cpbackup_transporter] cPanel Backup Transporter Queue Daemon started. [2021-12-21 02:01:05 +0000] info [cpbackup_transporter] cpbackup_transporter - started [2021-12-21 02:01:05 +0000] info [cpbackup_transporter] cpbackup_transporter - Checking queue for tasks [2021-12-21 02:01:05 +0000] info [cpbackup_transporter] cpbackup_transporter - Processing next task [2021-12-21 02:01:05 +0000] info [cpbackup_transporter] Instantiating Object [2021-12-21 02:01:07 +0000] info [cpbackup_transporter] Starting a "copy_system_backup" operation on the "QNAP_Rsync" destination ID "d9W0WHPXp_Jmg7vmytbnwboz". [2021-12-21 02:01:07 +0000] info [cpbackup_transporter] Base path for destination is SD_Backup [2021-12-21 02:01:07 +0000] info [cpbackup_transporter] Uploading system backup file /data/backups/weekly/2021-12-21/system_files.tar to QNAP_Rsync [2021-12-21 02:01:07 +0000] info [cpbackup_transporter] Validating destination path SD_Backup/weekly/2021-12-21/system/ [2021-12-21 02:01:07 +0000] info [cpbackup_transporter] Making Path SD_Backup/weekly/2021-12-21/system/ [2021-12-21 02:01:07 +0000] info [cpbackup_transporter] Path creation successful [2021-12-21 02:01:07 +0000] info [cpbackup_transporter] Uploading system backup /data/backups/weekly/2021-12-21/system_files.tar to SD_Backup/weekly/2021-12-21/system/system_files.tar (from weekly/2021-12-21/system/system_files.tar) [2021-12-21 02:01:07 +0000] info [cpbackup_transporter] Attempting to upload /data/backups/weekly/2021-12-21/system_files.tar to SD_Backup/weekly/2021-12-21/system/system_files.tar for destination: QNAP_Rsync [2021-12-21 02:01:07 +0000] info [cpbackup_transporter] Upload attempt #1 starting for /data/backups/weekly/2021-12-21/system_files.tar to SD_Backup/weekly/2021-12-21/system/system_files.tar for destination: QNAP_Rsync [2021-12-21 02:01:51 +0000] info [cpbackup_transporter] Successful incremental transfer of /data/backups/weekly/2021-12-21/system_files.tar to SD_Backup/weekly/2021-12-21/system/system_files.tar for destination QNAP_Rsync [2021-12-21 02:01:51 +0000] info [cpbackup_transporter] cpbackup_transporter - Processing next task [2021-12-21 02:01:51 +0000] info [cpbackup_transporter] Deleting system backup tar file: /data/backups/weekly/2021-12-21/system_files.tar [2021-12-21 02:02:20 +0000] info [cpbackup_transporter] cpbackup_transporter - Waiting up to 270s for new tasks [2021-12-21 02:02:50 +0000] info [cpbackup_transporter] cpbackup_transporter - Waiting up to 240s for new tasks [2021-12-21 02:03:20 +0000] info [cpbackup_transporter] cpbackup_transporter - Waiting up to 210s for new tasks [2021-12-21 02:03:50 +0000] info [cpbackup_transporter] cpbackup_transporter - Waiting up to 180s for new tasks [2021-12-21 02:04:20 +0000] info [cpbackup_transporter] cpbackup_transporter - Waiting up to 150s for new tasks [2021-12-21 02:04:50 +0000] info [cpbackup_transporter] cpbackup_transporter - Waiting up to 120s for new tasks [2021-12-21 02:05:20 +0000] info [cpbackup_transporter] cpbackup_transporter - Waiting up to 90s for new tasks [2021-12-21 02:05:51 +0000] info [cpbackup_transporter] cpbackup_transporter - Waiting up to 60s for new tasks [2021-12-21 02:06:21 +0000] info [cpbackup_transporter] cpbackup_transporter - Waiting up to 30s for new tasks [2021-12-21 02:06:51 +0000] info [cpbackup_transporter] cpbackup_transporter - Waiting up to 0s for new tasks [2021-12-21 02:06:52 +0000] info [cpbackup_transporter] cpbackup_transporter - Exiting - the queue has been emptied; no more work to do after waiting for 300s [2021-12-21 02:06:52 +0000] info [cpbackup_transporter] cPanel Backup Transporter Queue Daemon is being stopped.
    But just looking at the logs, it seems half the log 's missing until the following week (or the next backup)? Look at timestamp, it's got 2021-12-12 and 2021-12-19 in one file. Is this normal? I know the backup worked for the 21st, but how can I see the 'accounts' backup log for the 21st right away? thanks, Alex
    0
  • andrew.n
    hm each backup session has it's own log so mixing the backup log of 12-19 and 12-21 is weird I believe but hopefully @cPanelAnthony could clarify this. Without access to the server it's a bit difficult to advise here, might be the best to to have a closer look what is going on on the server.
    0
  • cPanelAnthony
    Hello! That does seem out of the ordinary unless somehow the exact same backup session was running on both days. As Andrew suggested, would you be able to submit a support ticket with cPanel and provide me with the ID?
    0
  • frenziedfox
    Your Support Ticket ID is: 94397446 I'm not in a position to sort out access right away, I'm currently travelling home for Christmas! :-D
    0
  • frenziedfox
    In case any one else couldn't find it:
    0

Please sign in to leave a comment.