Replication Issue



  • Hello there, Im having a problem with the replication in FOG 1.5.4, I have a node storage on and working perfectly until im upgraded both server and node versions

    the replication never ends… always make one or more parts of images “not matching” as show below

    “[09-24-18 3:56:34 pm] | Sala 204_207 Anexo I: No need to sync d1p2.img.106 file to 2 - Storage (X.X.210.208)
    [09-24-18 3:56:31 pm] | Deleting remote file: /images/Sala204_207AnexoI/d1p2.img.105
    [09-24-18 3:56:31 pm] | Files do not match on server: 2 - Storage (X.X.210.208)
    [09-24-18 3:56:28 pm] | Sala 204_207 Anexo I: No need to sync d1p2.img.104 file to 2 - Storage (X.X.210.208)”

    but the thing is: the source image was never changed since its creation… =/ and anytime I need to deploy a PC with images from this node the windows BSOD, from the ServerNode works fine…

    Any clues?
    thanks in advance, sorry by my english, not my native language.



  • @Sebastian-Roth Hi again, one “dumb” question: why lftp was choosed instead of rsync?

    cheers



  • @Sebastian-Roth ok, theres some defunct processes in the fog server after some replication ( delete parts and etc… )

    17914 ? Z 0:00 [sh] <defunct>
    20702 ? Z 0:00 [sh] <defunct>
    21632 ? Z 0:00 [sh] <defunct>
    24658 ? Z 0:00 [sh] <defunct>
    27305 ? Z 0:00 [sh] <defunct>
    28423 ? Z 0:00 [sh] <defunct>
    31260 pts/0 S+ 0:00 grep defunct



  • @Sebastian-Roth yeap, Debian on server, ubuntu on the storage

    result=> 7174 pts/0 S+ 0:00 grep --color=auto defunct ( edit: I’ll let the replication process make a full round and then take the result here again)


  • Developer

    @mronh You are on Ubuntu on your master node, right? We are tracking down a replication issue but we see this on CentOS and possibly RedHa so far.

    Please run ps ax | grep defunct on your master node and let us know the result of it.



  • @Sebastian-Roth yeah, I see this too, i put my expected “top speed” of the lan in the speed limit of the replication config ( inside fog GUI), thinking its a issue with lftp ( with none value, its take a default value instead of limitless)

    but, as none changed for better (at least)… I’ll see with the infrastructure guy here, maybe someone (with no knowledge) tried to “fix” some switches / bgp configs

    I’ll keep in touch, tks!


  • Developer

    @mronh Ok nothing serious in the apache and php-fpm logs. Revisiting the other logs you posted I just noticed this:

    2018-10-05 13:43:26 /images/Sala209RebootRX/d1p1.img -> ftp://fog@YYY.YY.210.208/%2Fimages/Sala209RebootRX/d1p1.img 0-8733059 1.77 MiB/s
    ...
    2018-10-05 13:53:51 /images/Sala209RebootRX/d1p2.img.084 -> ftp://fog@YYY.YY.210.208/%2Fimages/Sala209RebootRX/d1p2.img.084 0-91065975 1.99 MiB/s
    ....
    2018-10-05 17:39:43 /images/Sala209RebootRX/d1p2.img.084 -> ftp://fog@YYY.YY.210.208/%2Fimages/Sala209RebootRX/d1p2.img.084 0-64085945 101.21 MiB/s
    2018-10-05 17:39:43 /images/Sala209RebootRX/d1p1.img -> ftp://fog@YYY.YY.210.208/%2Fimages/Sala209RebootRX/d1p1.img 0-8290491 82.63 MiB/s
    

    To me it looks like file sizes differ and as well transfer speeds are way different too. Doesn’t add up for me yet.



  • @mronh apache error log pasted here cause has “entity error” when tried to upload (1200 lines of it)

    [Tue Oct 09 00:06:05.071128 2018] [mpm_prefork:notice] [pid 4797] AH00163: Apache/2.4.34 (Ubuntu) OpenSSL/1.1.0h configured -- resuming normal operations
    [Tue Oct 09 00:06:05.071170 2018] [core:notice] [pid 4797] AH00094: Command line: '/usr/sbin/apache2'
    [Tue Oct 09 10:03:55.253531 2018] [proxy_fcgi:error] [pid 16424] [client YYY.YY.211.13:60152] AH01071: Got error 'PHP message: PHP Notice:  A non well formed numeric value encountered in /var/www/fog/status/bandwidth.php on line 109\nPHP message: PHP Notice:  A non well formed numeric value encountered in /var/www/fog/status/bandwidth.php on line 109\nPHP message: PHP Notice:  A non well formed numeric value encountered in /var/www/fog/status/bandwidth.php on line 110\nPHP message: PHP Notice:  A non well formed numeric value encountered in /var/www/fog/status/bandwidth.php on line 110\n'
    [Tue Oct 09 10:03:56.483605 2018] [proxy_fcgi:error] [pid 16420] [client YYY.YY.211.13:60160] AH01071: Got error 'PHP message: PHP Notice:  A non well formed numeric value encountered in /var/www/fog/status/bandwidth.php on line 109\nPHP message: PHP Notice:  A non well formed numeric value encountered in /var/www/fog/status/bandwidth.php on line 109\nPHP message: PHP Notice:  A non well formed numeric value encountered in /var/www/fog/status/bandwidth.php on line 110\nPHP message: PHP Notice:  A non well formed numeric value encountered in /var/www/fog/status/bandwidth.php on line 110\n'
    [Tue Oct 09 10:03:57.679877 2018] [proxy_fcgi:error] [pid 16422] [client YYY.YY.211.13:60168] AH01071: Got error 'PHP message: PHP Notice:  A non well formed numeric value encountered in /var/www/fog/status/bandwidth.php on line 109\nPHP message: PHP Notice:  A non well formed numeric value encountered in /var/www/fog/status/bandwidth.php on line 109\nPHP message: PHP Notice:  A non well formed numeric value encountered in /var/www/fog/status/bandwidth.php on line 110\nPHP message: PHP Notice:  A non well formed numeric value encountered in /var/www/fog/status/bandwidth.php on line 110\n'
    [Tue Oct 09 10:03:58.862615 2018] [proxy_fcgi:error] [pid 16421] [client YYY.YY.211.13:60192] AH01071: Got error 'PHP message: PHP Notice:  A non well formed numeric value encountered in /var/www/fog/status/bandwidth.php on line 109\nPHP message: PHP Notice:  A non well formed numeric value encountered in /var/www/fog/status/bandwidth.php on line 109\nPHP message: PHP Notice:  A non well formed numeric value encountered in /var/www/fog/status/bandwidth.php on line 110\nPHP message: PHP Notice:  A non well formed numeric value encountered in /var/www/fog/status/bandwidth.php on line 110\n'
    [Tue Oct 09 10:04:00.075334 2018] [proxy_fcgi:error] [pid 16423] [client YYY.YY.211.13:60208] AH01071: Got error 'PHP message: PHP Notice:  A non well formed numeric value encountered in /var/www/fog/status/bandwidth.php on line 109\nPHP message: PHP Notice:  A non well formed numeric value encountered in /var/www/fog/status/bandwidth.php on line 109\nPHP message: PHP Notice:  A non well formed numeric value encountered in /var/www/fog/status/bandwidth.php on line 110\nPHP message: PHP Notice:  A non well formed numeric value encountered in /var/www/fog/status/bandwidth.php on line 110\n'
    [Tue Oct 09 10:04:01.293366 2018] [proxy_fcgi:error] [pid 16424] [client YYY.YY.211.13:60224] AH01071: Got error 'PHP message: PHP Notice:  A non well formed numeric value encountered in /var/www/fog/status/bandwidth.php on line 109\nPHP message: PHP Notice:  A non well formed numeric value encountered in /var/www/fog/status/bandwidth.php on line 109\nPHP message: PHP Notice:  A non well formed numeric value encountered in /var/www/fog/status/bandwidth.php on line 110\nPHP message: PHP Notice:  A non well formed numeric value encountered in /var/www/fog/status/bandwidth.php on line 110\n'
    [Tue Oct 09 10:04:02.526186 2018] [proxy_fcgi:error] [pid 16420] [client YYY.YY.211.13:60240] AH01071: Got error 'PHP message: PHP Notice:  A non well formed numeric value encountered in /var/www/fog/status/bandwidth.php on line 109\nPHP message: PHP Notice:  A non well formed numeric value encountered in /var/www/fog/status/bandwidth.php on line 109\nPHP message: PHP Notice:  A non well formed numeric value encountered in /var/www/fog/status/bandwidth.php on line 110\nPHP message: PHP Notice:  A non well formed numeric value encountered in /var/www/fog/status/bandwidth.php on line 110\n'
    [Tue Oct 09 10:04:03.757965 2018] [proxy_fcgi:error] [pid 16422] [client YYY.YY.211.13:60248] AH01071: Got error 'PHP message: PHP Notice:  A non well formed numeric value encountered in /var/www/fog/status/bandwidth.php on line 109\nPHP message: PHP Notice:  A non well formed numeric value encountered in /var/www/fog/status/bandwidth.php on line 109\nPHP message: PHP Notice:  A non well formed numeric value encountered in /var/www/fog/status/bandwidth.php on line 110\nPHP message: PHP Notice:  A non well formed numeric value encountered in /var/www/fog/status/bandwidth.php on line 110\n'
    [Tue Oct 09 10 ........
    
    ......... [Tue Oct 09 13:50:11.661757 2018] [proxy_fcgi:error] [pid 2761] [client YYY.YY.211.13:32864] AH01071: Got error 'PHP message: PHP Notice:  A non well formed numeric value encountered in /var/www/fog/status/bandwidth.php on line 109\nPHP message: PHP Notice:  A non well formed numeric value encountered in /var/www/fog/status/bandwidth.php on line 109\nPHP message: PHP Notice:  A non well formed numeric value encountered in /var/www/fog/status/bandwidth.php on line 110\nPHP message: PHP Notice:  A non well formed numeric value encountered in /var/www/fog/status/bandwidth.php on line 110\n'
    [Tue Oct 09 13:50:12.895096 2018] [proxy_fcgi:error] [pid 1109] [client YYY.YY.211.13:32872] AH01071: Got error 'PHP message: PHP Notice:  A non well formed numeric value encountered in /var/www/fog/status/bandwidth.php on line 109\nPHP message: PHP Notice:  A non well formed numeric value encountered in /var/www/fog/status/bandwidth.php on line 109\nPHP message: PHP Notice:  A non well formed numeric value encountered in /var/www/fog/status/bandwidth.php on line 110\nPHP message: PHP Notice:  A non well formed numeric value encountered in /var/www/fog/status/bandwidth.php on line 110\n'
    [Wed Oct 10 00:06:37.231462 2018] [mpm_prefork:notice] [pid 4797] AH00171: Graceful restart requested, doing restart
    AH00558: apache2: Could not reliably determine the server's fully qualified domain name, using 127.0.1.1. Set the 'ServerName' directive globally to suppress this message
    


  • @Sebastian-Roth Hi man, sorry by the new delay ( end of the year put some wood in the fire here =/ )

    I’ll attach the log u need, i let the storage node inactive till we find the bug/whatever

    thanks in advance dudes
    0_1539706517590_php7.1-fpm.log

    0_1539706561486_php7.1-fpm.2.log


  • Developer

    @mronh Please take a look at the apache and php-fpm logs on the storage node (see my signature on where to find those).

    The master node asks the storage about file size and hash values of the files. I have a feeling that something is going wrong with this check on your servers. And therefore the master node often thinks files are not equal on both nodes and re-transfers.



  • @mronh what im doing now to try to bypass this: set the sleeptime to 7200, and set a bandwitch limit of the replication of the server and storage node to 10000 Kbps ( maybe, just maybe, lftpd set a default value if its not set in the parms, and pull it down limit)

    while this, i’ll seek for some bug in my infra structure…

    cheers



  • @Sebastian-Roth

    Hi there, the problem still, sadly… as asked, bellow the logs ( I guess about the time spend X speed of the lan but guessing)

    cheers :)

    1_1539088564934_fogreplicator.Sala209RebootRX.transfer.2 - Storage (YYY.YY.210.208).log

    0_1539088564933_fogreplicator.log


  • Developer

    @mronh Keeping my fingers crossed it’ll work better this time. Make sure you post full logs here in case you run into issues again.



  • @Sebastian-Roth my apologies by the delay in the answer, I had to rollback to 1.5.2 to get back on the business here ( my boss giving me headaches…)

    about ur questions:

    IMAGEREPSLEEPTIME was 600, put 3600. i’ll try again ( but my net here is a giga conection… a LAN in fact, server and storage in the same switch giga port)

    this weekend i’ll update fog and test it again

    cheers



  • @Sebastian-Roth is right, the logs will tell us more. Also, Tom did fix the issue of the replication sleep cycle maybe two years ago. if lftp processes are still running, no more get started. They have timeouts so no fears of infinite hanging processes.

    Also I’m just going to say that most replication issues I’ve seen people have are normally due to configuration problems. Like a bad fogstorage username & pass, or MySQL remote access being disabled, or firewall on the OS blocking the needed ports, or bad FTP username/pass for the storage node.


  • Developer

    @mronh I just installed fresh clean test nodes to see if I can replicate your issue. For me it’s working perfectly fine. Not saying that our code is perfect but at least with my test setup I cannot see an issue. Replication works fine, only copying files over that actually have changed.

    So again I need to ask you to provide more logs: /var/log/fog/fogreplicator.log and /opt/fog/log/fogreplicator.repl.transfer.x.x.x.x.log for that particular storage node.

    I am wondering if the replication just cannot finish the copy process in time and therefore the image is corrupted because it never gets transferred all the way?! This would explain the BSODs as well.

    Please check the sleep time (FOG configuration -> FOG settings -> FOG Linux Service Sleep Times -> IMAGEREPSLEEPTIME). Is it set to the default 600 seconds? Would that be enough to fully sync the image within your network? As far as I know Tom has worked on the issue where one replication run is not finished yet when the next comes along. I think he’s fixed that but I am not sure exactly. You can try increasing the sleep time to something like 3600 (every hour) and see if that fixes your issue… Please let us know.

    How fast is the link between master node and storage node?


  • Developer

    @mronh I am sorry! Hope that I can figure this one out for you. I’ll try to look into it over the weekend. Though I am not as much a PHP wizz than Tom is.

    Can you please post your logs from the latest try?



  • @Sebastian-Roth
    No good, get the working branch, and still keeping replicating image parts thats already replicate… and worse… deploys made with images in the storage node make corrupted windows inits (goes to the restoration screen)

    now I disabled the storage node… thinking of rollback to 1.5.3 (or 1.5.2)



  • @Sebastian-Roth make the git pull in the server and the node side…

    now happens this with the sync

    “Type: 8, File: /var/www/fog/lib/service/fogservice.class.php, Line: 484, Message: Undefined variable: itemType”

    I’ll make some deploy to test the images

    edit: now the ftp server hangs due the max-conections reaches
    mirror: d1p5.img: Erro fatal: max-retries exceeded (421 There are too many connections from your internet address.)



  • @Sebastian-Roth as you are one of the developer, let me ask what is the “business rule” of the replication…

    its a cron in the server side making checksums of the parts comparing with the cron in the node side, if mismatch delete and "lftp -e "?


 

500
Online

41.9k
Users

12.4k
Topics

116.7k
Posts