Why 'fs-thaw' is called right after 'fs-freeze'?

faluyt

Active Member
Oct 15, 2016
7
0
41
36
Hi,

I would like to ask about the mechanism of the backup. Here is the backup log for example:

Code:
()
INFO: starting new backup job: vzdump 101 --prune-backups 'keep-daily=30,keep-last=36' --storage PB022-DS4 --mailnotification failure --quiet 1 --notes-template '{{cluster}}, {{guestname}}, {{node}}, {{vmid}}' --mailto help@fruitcloud.net --mode snapshot
INFO: Starting Backup of VM 101 (qemu)
INFO: Backup started at 2023-03-02 17:00:03
INFO: status = running
INFO: VM Name: BaltFresh
INFO: include disk 'virtio0' 'disk2:101/vm-101-disk-0.qcow2' 170G
INFO: include disk 'virtio1' 'disk2:101/vm-101-disk-1.qcow2' 120G
INFO: exclude disk 'virtio2' 'disk2:101/vm-101-disk-2.qcow2' (backup=no)
INFO: include disk 'virtio3' 'disk2:101/vm-101-disk-3.qcow2' 200G
INFO: backup mode: snapshot
INFO: ionice priority: 7
INFO: creating Proxmox Backup Server archive 'vm/101/2023-03-02T14:00:03Z'
INFO: issuing guest-agent 'fs-freeze' command
INFO: issuing guest-agent 'fs-thaw' command
INFO: started backup task '53e4c9b1-c0ba-46b7-b716-1c6c95ca4732'
INFO: resuming VM again
INFO: virtio0: dirty-bitmap status: OK (27.7 GiB of 170.0 GiB dirty)
INFO: virtio1: dirty-bitmap status: OK (14.0 GiB of 120.0 GiB dirty)
INFO: virtio3: dirty-bitmap status: OK (128.0 MiB of 200.0 GiB dirty)
INFO: using fast incremental mode (dirty-bitmap), 41.8 GiB dirty of 490.0 GiB total
INFO:   2% (1.1 GiB of 41.8 GiB) in 3s, read: 373.3 MiB/s, write: 373.3 MiB/s
INFO:   3% (1.4 GiB of 41.8 GiB) in 37s, read: 9.9 MiB/s, write: 9.8 MiB/s
INFO:   5% (2.2 GiB of 41.8 GiB) in 40s, read: 264.0 MiB/s, write: 262.7 MiB/s
INFO:   7% (3.0 GiB of 41.8 GiB) in 43s, read: 281.3 MiB/s, write: 278.7 MiB/s
INFO:   8% (3.6 GiB of 41.8 GiB) in 57s, read: 40.0 MiB/s, write: 40.0 MiB/s
INFO:  10% (4.6 GiB of 41.8 GiB) in 1m, read: 345.3 MiB/s, write: 344.0 MiB/s
INFO:  11% (5.0 GiB of 41.8 GiB) in 1m 3s, read: 136.0 MiB/s, write: 136.0 MiB/s
INFO:  12% (5.3 GiB of 41.8 GiB) in 1m 36s, read: 8.6 MiB/s, write: 8.6 MiB/s
INFO:  14% (6.1 GiB of 41.8 GiB) in 1m 39s, read: 300.0 MiB/s, write: 297.3 MiB/s
INFO:  17% (7.2 GiB of 41.8 GiB) in 1m 42s, read: 354.7 MiB/s, write: 350.7 MiB/s
INFO:  20% (8.5 GiB of 41.8 GiB) in 1m 45s, read: 452.0 MiB/s, write: 452.0 MiB/s
INFO:  23% (9.8 GiB of 41.8 GiB) in 1m 48s, read: 440.0 MiB/s, write: 440.0 MiB/s
INFO:  25% (10.9 GiB of 41.8 GiB) in 1m 51s, read: 369.3 MiB/s, write: 369.3 MiB/s
INFO:  28% (11.8 GiB of 41.8 GiB) in 1m 54s, read: 333.3 MiB/s, write: 333.3 MiB/s
INFO:  30% (12.7 GiB of 41.8 GiB) in 1m 57s, read: 285.3 MiB/s, write: 285.3 MiB/s
INFO:  32% (13.6 GiB of 41.8 GiB) in 2m, read: 321.3 MiB/s, write: 321.3 MiB/s
INFO:  35% (14.9 GiB of 41.8 GiB) in 2m 3s, read: 438.7 MiB/s, write: 438.7 MiB/s
INFO:  37% (15.8 GiB of 41.8 GiB) in 2m 6s, read: 320.0 MiB/s, write: 320.0 MiB/s
INFO:  40% (17.1 GiB of 41.8 GiB) in 2m 9s, read: 424.0 MiB/s, write: 421.3 MiB/s
INFO:  42% (17.8 GiB of 41.8 GiB) in 2m 12s, read: 252.0 MiB/s, write: 248.0 MiB/s
INFO:  43% (18.1 GiB of 41.8 GiB) in 3m 55s, read: 2.4 MiB/s, write: 2.3 MiB/s
INFO:  44% (18.4 GiB of 41.8 GiB) in 4m 1s, read: 58.0 MiB/s, write: 56.0 MiB/s
INFO:  45% (19.0 GiB of 41.8 GiB) in 4m 7s, read: 104.0 MiB/s, write: 101.3 MiB/s
INFO:  46% (19.3 GiB of 41.8 GiB) in 4m 10s, read: 81.3 MiB/s, write: 80.0 MiB/s
INFO:  47% (19.7 GiB of 41.8 GiB) in 4m 16s, read: 70.0 MiB/s, write: 69.3 MiB/s
INFO:  48% (20.2 GiB of 41.8 GiB) in 4m 27s, read: 52.7 MiB/s, write: 51.6 MiB/s
INFO:  49% (20.7 GiB of 41.8 GiB) in 4m 30s, read: 149.3 MiB/s, write: 144.0 MiB/s
INFO:  50% (21.0 GiB of 41.8 GiB) in 4m 39s, read: 42.2 MiB/s, write: 41.8 MiB/s
INFO:  51% (21.4 GiB of 41.8 GiB) in 4m 42s, read: 113.3 MiB/s, write: 112.0 MiB/s
INFO:  52% (21.8 GiB of 41.8 GiB) in 4m 52s, read: 47.2 MiB/s, write: 46.8 MiB/s
INFO:  53% (22.2 GiB of 41.8 GiB) in 4m 57s, read: 77.6 MiB/s, write: 76.0 MiB/s
INFO:  54% (22.6 GiB of 41.8 GiB) in 5m 11s, read: 31.7 MiB/s, write: 30.9 MiB/s
INFO:  55% (23.0 GiB of 41.8 GiB) in 5m 18s, read: 58.3 MiB/s, write: 57.7 MiB/s
INFO:  56% (23.5 GiB of 41.8 GiB) in 5m 26s, read: 52.0 MiB/s, write: 49.5 MiB/s
INFO:  57% (23.9 GiB of 41.8 GiB) in 5m 40s, read: 30.6 MiB/s, write: 30.0 MiB/s
INFO:  58% (24.3 GiB of 41.8 GiB) in 6m 14s, read: 12.7 MiB/s, write: 12.2 MiB/s
INFO:  59% (24.7 GiB of 41.8 GiB) in 6m 29s, read: 28.8 MiB/s, write: 28.5 MiB/s
INFO:  60% (25.1 GiB of 41.8 GiB) in 6m 48s, read: 23.4 MiB/s, write: 23.2 MiB/s
INFO:  61% (25.6 GiB of 41.8 GiB) in 7m 15s, read: 17.6 MiB/s, write: 17.5 MiB/s
INFO:  62% (26.1 GiB of 41.8 GiB) in 7m 27s, read: 44.7 MiB/s, write: 43.3 MiB/s
INFO:  63% (26.7 GiB of 41.8 GiB) in 7m 30s, read: 180.0 MiB/s, write: 180.0 MiB/s
INFO:  64% (27.0 GiB of 41.8 GiB) in 7m 33s, read: 100.0 MiB/s, write: 98.7 MiB/s
INFO:  65% (27.3 GiB of 41.8 GiB) in 7m 37s, read: 84.0 MiB/s, write: 84.0 MiB/s
INFO:  66% (27.6 GiB of 41.8 GiB) in 7m 41s, read: 93.0 MiB/s, write: 93.0 MiB/s
INFO:  67% (28.2 GiB of 41.8 GiB) in 7m 44s, read: 186.7 MiB/s, write: 185.3 MiB/s
INFO:  68% (28.6 GiB of 41.8 GiB) in 7m 48s, read: 95.0 MiB/s, write: 94.0 MiB/s
INFO:  69% (29.1 GiB of 41.8 GiB) in 7m 51s, read: 180.0 MiB/s, write: 173.3 MiB/s
INFO:  70% (29.5 GiB of 41.8 GiB) in 7m 54s, read: 137.3 MiB/s, write: 134.7 MiB/s
INFO:  71% (29.8 GiB of 41.8 GiB) in 7m 59s, read: 65.6 MiB/s, write: 64.8 MiB/s
INFO:  72% (30.1 GiB of 41.8 GiB) in 8m 2s, read: 112.0 MiB/s, write: 110.7 MiB/s
INFO:  73% (30.6 GiB of 41.8 GiB) in 8m 6s, read: 120.0 MiB/s, write: 120.0 MiB/s
INFO:  74% (31.0 GiB of 41.8 GiB) in 8m 9s, read: 122.7 MiB/s, write: 120.0 MiB/s
INFO:  75% (31.5 GiB of 41.8 GiB) in 8m 12s, read: 170.7 MiB/s, write: 168.0 MiB/s
INFO:  76% (31.9 GiB of 41.8 GiB) in 8m 15s, read: 150.7 MiB/s, write: 148.0 MiB/s
INFO:  77% (32.2 GiB of 41.8 GiB) in 8m 18s, read: 102.7 MiB/s, write: 101.3 MiB/s
INFO:  78% (32.8 GiB of 41.8 GiB) in 8m 25s, read: 78.3 MiB/s, write: 77.1 MiB/s
INFO:  79% (33.2 GiB of 41.8 GiB) in 8m 28s, read: 149.3 MiB/s, write: 146.7 MiB/s
INFO:  80% (33.7 GiB of 41.8 GiB) in 8m 36s, read: 61.5 MiB/s, write: 61.0 MiB/s
INFO:  81% (34.0 GiB of 41.8 GiB) in 8m 40s, read: 81.0 MiB/s, write: 81.0 MiB/s
INFO:  82% (34.6 GiB of 41.8 GiB) in 8m 43s, read: 198.7 MiB/s, write: 197.3 MiB/s
INFO:  83% (35.0 GiB of 41.8 GiB) in 8m 46s, read: 136.0 MiB/s, write: 136.0 MiB/s
INFO:  84% (35.4 GiB of 41.8 GiB) in 8m 49s, read: 145.3 MiB/s, write: 141.3 MiB/s
INFO:  86% (36.2 GiB of 41.8 GiB) in 8m 52s, read: 293.3 MiB/s, write: 292.0 MiB/s
INFO:  87% (36.8 GiB of 41.8 GiB) in 8m 55s, read: 174.7 MiB/s, write: 174.7 MiB/s
INFO:  88% (37.0 GiB of 41.8 GiB) in 8m 58s, read: 82.7 MiB/s, write: 82.7 MiB/s
INFO:  90% (37.8 GiB of 41.8 GiB) in 9m 1s, read: 284.0 MiB/s, write: 284.0 MiB/s
INFO:  91% (38.1 GiB of 41.8 GiB) in 9m 4s, read: 92.0 MiB/s, write: 92.0 MiB/s
INFO:  92% (38.9 GiB of 41.8 GiB) in 9m 7s, read: 261.3 MiB/s, write: 260.0 MiB/s
INFO:  94% (39.3 GiB of 41.8 GiB) in 9m 10s, read: 160.0 MiB/s, write: 160.0 MiB/s
INFO:  95% (39.9 GiB of 41.8 GiB) in 9m 17s, read: 80.0 MiB/s, write: 77.1 MiB/s
INFO:  96% (40.4 GiB of 41.8 GiB) in 9m 21s, read: 127.0 MiB/s, write: 126.0 MiB/s
INFO:  97% (41.0 GiB of 41.8 GiB) in 9m 24s, read: 204.0 MiB/s, write: 202.7 MiB/s
INFO: 100% (41.8 GiB of 41.8 GiB) in 9m 27s, read: 290.7 MiB/s, write: 288.0 MiB/s
INFO: backup is sparse: 8.00 MiB (0%) total zero data
INFO: backup was done incrementally, reused 448.56 GiB (91%)
INFO: transferred 41.83 GiB in 813 seconds (52.7 MiB/s)
INFO: adding notes to backup
storing login ticket failed: $XDG_RUNTIME_DIR must be set
INFO: prune older backups with retention: keep-daily=30, keep-last=36
INFO: running 'proxmox-backup-client prune' for 'vm/101'
storing login ticket failed: $XDG_RUNTIME_DIR must be set
INFO: pruned 1 backup(s) not covered by keep-retention policy
INFO: Finished Backup of VM 101 (00:13:39)
INFO: Backup finished at 2023-03-02 17:13:42
storing login ticket failed: $XDG_RUNTIME_DIR must be set
INFO: Backup job finished successfully
TASK OK

What I wonder here is 'fs-freeze' is called right after 'fs-thaw'. I understand 'fs-freeze' will freeze the file system on the VM to increase consistency. However, 'fs-thaw' is called right after that, BEFORE doing backing up. The time between 2 command is just a few seconds. I thought 'fs-thaw' must be called AFTER doing backup, right?
 
Hi,
essentially, what happens is that in between the freeze and the thaw a "snapshot" (it's a bit different than an actual snapshot) is taken on QEMU's block layer. That one is then used for the backup. The guest surely wouldn't be happy if the filesystem was frozen for a very long time ;)
 
  • Like
Reactions: faluyt

About

The Proxmox community has been around for many years and offers help and support for Proxmox VE, Proxmox Backup Server, and Proxmox Mail Gateway.
We think our community is one of the best thanks to people like you!

Get your subscription!

The Proxmox team works very hard to make sure you are running the best software and getting stable updates and security enhancements, as well as quick enterprise support. Tens of thousands of happy customers have a Proxmox subscription. Get yours easily in our online shop.

Buy now!