Announcement

Collapse
No announcement yet.

BC4 Scripting Bug?

Collapse
X
 
  • Filter
  • Time
  • Show
Clear All
new posts

  • BC4 Scripting Bug?

    I created a new script that loads 7 different saved sessions of grabbing data via FTP off of my site for backup. Had been doing this manually for years. I have a "log normal" at the start. But after the first load, it turns on what I can only guess is "verbose" logging. I see every little FTP session message going back and forth. Only when the second script starts is that turned off. Here is the pseudo command script:
    log normal "Log_bc4_Hostgator_Weekly_Backup.txt"
    option confirm:yes-to-all
    load "site bak backup (DB) Scripted"
    sync update:left->right
    load "site gen backup Scripted"

    .... (and on to load and sync the additional files)


    With the log then generated (edited a bit for brevity and privacy):
    4/30/2020 9:34:10 AM >> #
    4/30/2020 9:34:10 AM >> log normal append:"C:\Users\Randy\Documents\Backup_Scripts\Lo g_bc4_Hostgator_Weekly_Backup.txt"
    4/30/2020 9:34:10 AM >> option confirm:yes-to-all
    4/30/2020 9:34:10 AM >>
    4/30/2020 9:34:10 AM >> load "Modiharr.com bak backup (DB) Scripted"
    4/30/2020 9:34:11 AM Stat> Connected.
    4/30/2020 9:34:11 AM Recv> 220---------- Welcome to Pure-FTPd [privsep] [TLS] ----------
    4/30/2020 9:34:11 AM 220-You are user number 2 of 150 allowed.
    4/30/2020 9:34:11 AM 220-Local time is now 08:34. Server port: 21.
    4/30/2020 9:34:11 AM 220-IPv6 connections are also welcome on this server.
    4/30/2020 9:34:11 AM 220 You will be disconnected after 15 minutes of inactivity.
    4/30/2020 9:34:11 AM Sent> HOST server.com
    4/30/2020 9:34:11 AM Recv> 530 You aren't logged in
    4/30/2020 9:34:11 AM Sent> USER username
    4/30/2020 9:34:11 AM Recv> 331 User username OK. Password required
    4/30/2020 9:34:11 AM Sent> PASS ********
    4/30/2020 9:34:11 AM Recv> 230 OK. Current restricted directory is /
    4/30/2020 9:34:11 AM Sent> FEAT
    4/30/2020 9:34:11 AM Recv> 211-Extensions supported:
    4/30/2020 9:34:11 AM EPRT
    4/30/2020 9:34:11 AM IDLE
    4/30/2020 9:34:11 AM MDTM
    4/30/2020 9:34:11 AM SIZE
    4/30/2020 9:34:11 AM MFMT
    4/30/2020 9:34:11 AM REST STREAM
    4/30/2020 9:34:11 AM MLST type*;size*;sizd*;modify*;UNIX.mode*;UNIX.uid*;UNI X.gid*;unique*;
    4/30/2020 9:34:11 AM MLSD
    4/30/2020 9:34:11 AM AUTH TLS
    4/30/2020 9:34:11 AM PBSZ
    4/30/2020 9:34:11 AM PROT
    4/30/2020 9:34:11 AM UTF8
    4/30/2020 9:34:11 AM TVFS
    4/30/2020 9:34:11 AM ESTA
    4/30/2020 9:34:11 AM PASV
    4/30/2020 9:34:11 AM EPSV
    4/30/2020 9:34:11 AM SPSV
    4/30/2020 9:34:11 AM ESTP
    4/30/2020 9:34:11 AM 211 End.
    4/30/2020 9:34:11 AM Sent> OPTS UTF8 ON
    4/30/2020 9:34:11 AM Recv> 200 OK, UTF-8 enabled
    4/30/2020 9:34:11 AM Sent> TYPE A
    4/30/2020 9:34:11 AM Recv> 200 TYPE is now ASCII
    4/30/2020 9:34:11 AM Sent> SYST
    4/30/2020 9:34:11 AM Recv> 215 UNIX Type: L8
    4/30/2020 9:34:11 AM Sent> TYPE A
    4/30/2020 9:34:11 AM Recv> 200 TYPE is now ASCII
    4/30/2020 9:34:11 AM Sent> PWD
    4/30/2020 9:34:11 AM Recv> 257 "/" is your current location
    4/30/2020 9:34:12 AM Sent> REST 1
    4/30/2020 9:34:12 AM Recv> 350 Restarting at 1. But we're in ASCII mode
    4/30/2020 9:34:12 AM Sent> REST 0
    4/30/2020 9:34:12 AM Recv> 350 Restarting at 0
    4/30/2020 9:34:12 AM Sent> CWD bak

    .... another 70 thousand or so messages
    4/30/2020 10:20:05 AM Sent> PASV
    4/30/2020 10:20:05 AM Recv> 227 Entering Passive Mode (192,185,5,62,122,60)
    4/30/2020 10:20:05 AM Sent> RETR write.lock.file
    4/30/2020 10:20:05 AM Recv> 150 Accepted data connection
    4/30/2020 10:20:05 AM Recv> 226 File successfully transferred
    4/30/2020 10:20:05 AM Successfully synchronized 42 items. Failed to synchronize 1 items. Completed in 10.8 seconds.
    4/30/2020 10:20:05 AM >>
    4/30/2020 10:20:05 AM >> load "D:\Archive" "\\Backup\d\Archive"
    4/30/2020 10:20:59 AM Load comparison: D:\Archive <-> \\BACKUP\d\Archive
    4/30/2020 10:20:59 AM >> sync mirror:left->right
    4/30/2020 10:20:59 AM >>
    4/30/2020 10:20:59 AM >> load "D:\Downloads" "\\Backup\d\Downloads"
    4/30/2020 10:21:01 AM Load comparison: D:\Downloads <-> \\BACKUP\d\Downloads
    4/30/2020 10:21:01 AM >> sync mirror:left->right

    ... and on it goes for the rest of the scripts with no apparent verbose messages (only another 30 or so more log lines added before the script finishes)
    4/30/2020 10:34:44 AM Successfully synchronized 887 items. Failed to synchronize 2 items. Completed in 37.4 seconds.
    4/30/2020 10:34:44 AM >>
    4/30/2020 10:34:44 AM Script completed in 1 hours, 0 minutes


    Is this somehow expected? Do I have to turn off logging for the first script for some reason? This is consistent between runs.
    Last edited by SurplusGadgets; 30-Apr-2020, 11:47 AM.

  • #2
    Hello,

    The FTP messages should be present in both logs, as Normal includes the full FTP send/recv messages. The difference between Normal and Verbose would be if BC4's logging itself shows the summary at the end of how many files, or includes a "Copied file from here to here" message after each transfer.

    Unfortunately, the edits made to this example exclude where the differences would occur. If you would like to email us privately at support@scootersoftware.com (along with a link back to this thread for our reference) we could review a log that includes at least a few dozen file examples. Perhaps a full log performed on Test folders?
    Aaron P Scooter Software

    Comment


    • #3
      Originally posted by Aaron View Post
      Hello,
      The FTP messages should be present in both logs, as Normal includes the full FTP send/recv messages. The difference between Normal and Verbose would be if BC4's logging itself shows the summary at the end of how many files, or includes a "Copied file from here to here" message after each transfer.
      Well that is a bummer. 70k entries in the log for a simple check of a dozen files in a directory seems excessive and definitely verbose. But then does not explain why no other messages are seen for the other loads. One of which checks and retrieves a tree of over 150,000 files and over 10,000 directories / folders. And yet not a single FTP message there and in fact just a simple "Successfully synchronized 887 items ..." as shown above.

      Originally posted by Aaron View Post
      Unfortunately, the edits made to this example exclude where the differences would occur. If you would like to email us privately at support@scootersoftware.com (along with a link back to this thread for our reference) we could review a log that includes at least a few dozen file examples. Perhaps a full log performed on Test folders?
      Will email privately but, as mentioned, I did not take out any messages. Just the additional load statements that all appeared the same. Will send the script, the log and the loaded command files. And report back here on any findings; if you do not do so first.

      Comment


      • #4
        Hello,

        I'll keep an eye on the Inbox. If you've already sent it, please try sending another email with no attachment to see if that gets through, and let us know here.
        Aaron P Scooter Software

        Comment

        Working...
        X