Mandi! Josh Fisher via Bacula-users In chel di` si favelave... > 'Log Level = LOG_DEBUG' in the vchanger.conf file. That will log everything
'log level = 7', do you mean, right? I've found an installation where i've forgot a log level = 7, so, last friday: Mar 15 07:00:01: [6661]: restored state of magazine 2 Mar 15 07:00:01: [6661]: filesystem 9cf163ae-a762-4af7-9a11-e4f325ec2c7f has udev assigned device /dev/sdc1 Mar 15 07:00:01: [6661]: filesystem 9cf163ae-a762-4af7-9a11-e4f325ec2c7f (device /dev/sdc1) mounted at /mnt/vchanger/9cf163ae-a762-4af7-9a11-e4f325ec2c7f Mar 15 07:00:01: [6661]: magazine 0 is not mounted Mar 15 07:00:01: [6661]: magazine 1 is not mounted Mar 15 07:00:01: [6661]: magazine 2 has 10 volumes on /mnt/vchanger/9cf163ae-a762-4af7-9a11-e4f325ec2c7f Mar 15 07:00:01: [6661]: 10 volumes on magazine 2 assigned slots 1-10 Mar 15 07:00:01: [6661]: saved state of magazine 2 Mar 15 07:00:01: [6661]: saved dynamic configuration (max used slot: 10) Mar 15 07:00:01: [6661]: found symlink for drive 0 -> /mnt/vchanger/9cf163ae-a762-4af7-9a11-e4f325ec2c7f/SDPVE2RDX_0002_0006 Mar 15 07:00:01: [6661]: drive 0 previously loaded from slot 7 (SDPVE2RDX_0002_0006) Mar 15 07:00:01: [6661]: found symlink for drive 1 -> /mnt/vchanger/9cf163ae-a762-4af7-9a11-e4f325ec2c7f/SDPVE2RDX_0002_0003 Mar 15 07:00:01: [6661]: drive 1 previously loaded from slot 4 (SDPVE2RDX_0002_0003) Mar 15 07:00:01: [6661]: found symlink for drive 2 -> /mnt/vchanger/9cf163ae-a762-4af7-9a11-e4f325ec2c7f/SDPVE2RDX_0002_0005 Mar 15 07:00:01: [6661]: drive 2 previously loaded from slot 6 (SDPVE2RDX_0002_0005) Mar 15 07:00:01: [6661]: ==== preforming UNLOAD command Mar 15 07:00:01: [6661]: deleted symlink for drive 0 Mar 15 07:00:01: [6661]: deleted state file for drive 0 Mar 15 07:00:01: [6661]: unloaded drive 0 Mar 15 07:00:01: [6661]: SUCCESS unloading slot 7 from drive 0 Mar 15 07:00:05: [6778]: restored state of magazine 2 Mar 15 07:00:05: [6778]: filesystem 9cf163ae-a762-4af7-9a11-e4f325ec2c7f has udev assigned device /dev/sdc1 Mar 15 07:00:05: [6778]: device /dev/sdc1 not found in system mounts, searching all udev device aliases Mar 15 07:00:05: [6778]: filesystem 9cf163ae-a762-4af7-9a11-e4f325ec2c7f (device /dev/sdc1) not mounted Mar 15 07:00:05: [6778]: magazine 0 is not mounted Mar 15 07:00:05: [6778]: magazine 1 is not mounted Mar 15 07:00:05: [6778]: magazine 2 is not mounted Mar 15 07:00:05: [6778]: update slots needed. magazine 2 no longer mounted; previous: 10 volumes in slots 1-10 Mar 15 07:00:05: [6778]: saved dynamic configuration (max used slot: 10) Mar 15 07:00:05: [6778]: drive 0 previously unloaded Mar 15 07:00:05: [6778]: volume SDPVE2RDX_0002_0003 no longer available, unloading drive 1 Mar 15 07:00:05: [6778]: deleted symlink for drive 1 Mar 15 07:00:05: [6778]: volume SDPVE2RDX_0002_0005 no longer available, unloading drive 2 Mar 15 07:00:05: [6778]: deleted symlink for drive 2 Mar 15 07:00:05: [6778]: ==== preforming REFRESH command Mar 15 07:00:05: [6778]: running '/usr/sbin/bconsole -n -u 30' Mar 15 07:00:05: [6778]: popen: child stdin uses pipe (4 -> 5) Mar 15 07:00:05: [6778]: popen: child stdout uses pipe (6 -> 7) Mar 15 07:00:05: [6778]: popen: forking now Mar 15 07:00:05: [6778]: popen: parent closing pipe ends 4,7,-1 used by child Mar 15 07:00:05: [6778]: popen: parent writes child's stdin to 5 Mar 15 07:00:05: [6778]: popen: parent reads child's stdout from 6 Mar 15 07:00:05: [6778]: popen: parent returning pid=6779 of child Mar 15 07:00:05: [6778]: sending bconsole command 'update slots storage="SDPVE2RDX" drive="0"' Mar 15 07:00:05: [6779]: popen: child closing pipe ends 5,6,-1 used by parent Mar 15 07:00:05: [6779]: popen: child will read stdin from 4 Mar 15 07:00:05: [6779]: popen: child will write stdout to 7 Mar 15 07:00:05: [6779]: popen: child executing '/usr/sbin/bconsole' Mar 15 07:00:05: [6782]: filesystem 9cf163ae-a762-4af7-9a11-e4f325ec2c7f has udev assigned device /dev/sdc1 Mar 15 07:00:05: [6782]: device /dev/sdc1 not found in system mounts, searching all udev device aliases Mar 15 07:00:05: [6782]: filesystem 9cf163ae-a762-4af7-9a11-e4f325ec2c7f (device /dev/sdc1) not mounted Mar 15 07:00:05: [6782]: magazine 0 is not mounted Mar 15 07:00:05: [6782]: magazine 1 is not mounted Mar 15 07:00:05: [6782]: magazine 2 is not mounted Mar 15 07:00:05: [6782]: saved dynamic configuration (max used slot: 10) Mar 15 07:00:05: [6782]: drive 0 previously unloaded Mar 15 07:00:05: [6782]: ==== preforming SLOTS command Mar 15 07:00:05: [6782]: SUCCESS reporting 10 slots Mar 15 07:00:06: [6784]: filesystem 9cf163ae-a762-4af7-9a11-e4f325ec2c7f has udev assigned device /dev/sdc1 Mar 15 07:00:06: [6784]: device /dev/sdc1 not found in system mounts, searching all udev device aliases Mar 15 07:00:06: [6784]: filesystem 9cf163ae-a762-4af7-9a11-e4f325ec2c7f (device /dev/sdc1) not mounted Mar 15 07:00:06: [6784]: magazine 0 is not mounted Mar 15 07:00:06: [6784]: magazine 1 is not mounted Mar 15 07:00:06: [6784]: magazine 2 is not mounted Mar 15 07:00:06: [6784]: saved dynamic configuration (max used slot: 10) Mar 15 07:00:06: [6784]: drive 0 previously unloaded Mar 15 07:00:06: [6784]: ==== preforming LOADED command Mar 15 07:00:06: [6784]: SUCCESS reporting drive 0 loaded from slot 0 Mar 15 07:00:06: [6785]: filesystem 9cf163ae-a762-4af7-9a11-e4f325ec2c7f has udev assigned device /dev/sdc1 Mar 15 07:00:06: [6785]: device /dev/sdc1 not found in system mounts, searching all udev device aliases Mar 15 07:00:06: [6785]: filesystem 9cf163ae-a762-4af7-9a11-e4f325ec2c7f (device /dev/sdc1) not mounted Mar 15 07:00:06: [6785]: magazine 0 is not mounted Mar 15 07:00:06: [6785]: magazine 1 is not mounted Mar 15 07:00:06: [6785]: magazine 2 is not mounted Mar 15 07:00:06: [6785]: saved dynamic configuration (max used slot: 10) Mar 15 07:00:06: [6785]: drive 0 previously unloaded Mar 15 07:00:06: [6785]: ==== preforming LIST command Mar 15 07:00:06: [6785]: SUCCESS sent list to stdout Mar 15 07:00:06: [6778]: bconsole: bconsole terminated normally Mar 15 07:00:06: [6778]: bconsole output: Connecting to Director bacula.lnf.it:9101 1000 OK: 103 lnfbacula-dir Version: 9.4.2 (04 February 2019) Enter a period to cancel a command. update slots storage="SDPVE2RDX" drive="0" Automatically selected Catalog: BaculaLNF Using Catalog "BaculaLNF" Connecting to Storage daemon SDPVE2RDX at sdpve2.sd.lnf.it:9103 ... 3306 Issuing autochanger "slots" command. Device "RDXAutochanger" has 10 slots. Connecting to Storage daemon SDPVE2RDX at sdpve2.sd.lnf.it:9103 ... 3306 Issuing autochanger "list" command. No Volumes found to label, or no barcodes. You have messages. Mar 15 07:00:06: [6778]: bconsole update slots command success Mar 15 07:00:12: [6912]: magazine 0 is not mounted Mar 15 07:00:12: [6912]: magazine 1 is not mounted Mar 15 07:00:12: [6912]: magazine 2 is not mounted Mar 15 07:00:12: [6912]: saved dynamic configuration (max used slot: 10) Mar 15 07:00:12: [6912]: drive 0 previously unloaded Mar 15 07:00:12: [6912]: ==== preforming REFRESH command And then the operator insert the new cartdrige: Mar 15 11:01:59: [31055]: filesystem 5ba3d8a2-80e2-4322-9d83-839d2f6dc8b4 has udev assigned device /dev/sdc1 Mar 15 11:01:59: [31055]: filesystem 5ba3d8a2-80e2-4322-9d83-839d2f6dc8b4 (device /dev/sdc1) mounted at /mnt/vchanger/5ba3d8a2-80e2-4322-9d83-839d2f6dc8b4 Mar 15 11:01:59: [31055]: magazine 0 has 10 volumes on /mnt/vchanger/5ba3d8a2-80e2-4322-9d83-839d2f6dc8b4 Mar 15 11:01:59: [31055]: update slots needed. magazine 0 has 10 volumes, previously had 0 Mar 15 11:01:59: [31055]: magazine 1 is not mounted Mar 15 11:01:59: [31055]: magazine 2 is not mounted Mar 15 11:01:59: [31055]: 10 volumes on magazine 0 assigned slots 1-10 Mar 15 11:01:59: [31055]: saved state of magazine 0 Mar 15 11:01:59: [31055]: saved dynamic configuration (max used slot: 10) Mar 15 11:01:59: [31055]: drive 0 previously unloaded Mar 15 11:01:59: [31055]: ==== preforming REFRESH command Mar 15 11:01:59: [31055]: running '/usr/sbin/bconsole -n -u 30' Mar 15 11:01:59: [31055]: popen: child stdin uses pipe (4 -> 5) Mar 15 11:01:59: [31055]: popen: child stdout uses pipe (6 -> 7) Mar 15 11:01:59: [31055]: popen: forking now Mar 15 11:01:59: [31055]: popen: parent closing pipe ends 4,7,-1 used by child Mar 15 11:01:59: [31055]: popen: parent writes child's stdin to 5 Mar 15 11:01:59: [31055]: popen: parent reads child's stdout from 6 Mar 15 11:01:59: [31055]: popen: parent returning pid=31056 of child Mar 15 11:01:59: [31055]: sending bconsole command 'update slots storage="SDPVE2RDX" drive="0"' Mar 15 11:01:59: [31056]: popen: child closing pipe ends 5,6,-1 used by parent Mar 15 11:01:59: [31056]: popen: child will read stdin from 4 Mar 15 11:01:59: [31056]: popen: child will write stdout to 7 Mar 15 11:01:59: [31056]: popen: child executing '/usr/sbin/bconsole' Mar 15 11:02:00: [31076]: restored state of magazine 0 Mar 15 11:02:00: [31076]: filesystem 5ba3d8a2-80e2-4322-9d83-839d2f6dc8b4 has udev assigned device /dev/sdc1 Mar 15 11:02:00: [31076]: filesystem 5ba3d8a2-80e2-4322-9d83-839d2f6dc8b4 (device /dev/sdc1) mounted at /mnt/vchanger/5ba3d8a2-80e2-4322-9d83-839d2f6dc8b4 Mar 15 11:02:00: [31076]: magazine 0 has 10 volumes on /mnt/vchanger/5ba3d8a2-80e2-4322-9d83-839d2f6dc8b4 Mar 15 11:02:00: [31076]: 10 volumes on magazine 0 assigned slots 1-10 Mar 15 11:02:00: [31076]: magazine 1 is not mounted Mar 15 11:02:00: [31076]: magazine 2 is not mounted Mar 15 11:02:00: [31076]: saved state of magazine 0 Mar 15 11:02:00: [31076]: saved dynamic configuration (max used slot: 10) Mar 15 11:02:00: [31076]: drive 0 previously unloaded Mar 15 11:02:00: [31076]: ==== preforming SLOTS command Mar 15 11:02:00: [31076]: SUCCESS reporting 10 slots Mar 15 11:02:00: [31078]: restored state of magazine 0 Mar 15 11:02:00: [31078]: filesystem 5ba3d8a2-80e2-4322-9d83-839d2f6dc8b4 has udev assigned device /dev/sdc1 Mar 15 11:02:00: [31078]: filesystem 5ba3d8a2-80e2-4322-9d83-839d2f6dc8b4 (device /dev/sdc1) mounted at /mnt/vchanger/5ba3d8a2-80e2-4322-9d83-839d2f6dc8b4 Mar 15 11:02:00: [31078]: magazine 0 has 10 volumes on /mnt/vchanger/5ba3d8a2-80e2-4322-9d83-839d2f6dc8b4 Mar 15 11:02:00: [31078]: 10 volumes on magazine 0 assigned slots 1-10 Mar 15 11:02:00: [31078]: magazine 1 is not mounted Mar 15 11:02:00: [31078]: magazine 2 is not mounted Mar 15 11:02:00: [31078]: saved state of magazine 0 Mar 15 11:02:00: [31078]: saved dynamic configuration (max used slot: 10) Mar 15 11:02:00: [31078]: drive 0 previously unloaded Mar 15 11:02:00: [31078]: ==== preforming LOADED command Mar 15 11:02:00: [31078]: SUCCESS reporting drive 0 loaded from slot 0 Mar 15 11:02:00: [31079]: restored state of magazine 0 Mar 15 11:02:01: [31079]: filesystem 5ba3d8a2-80e2-4322-9d83-839d2f6dc8b4 has udev assigned device /dev/sdc1 Mar 15 11:02:01: [31079]: filesystem 5ba3d8a2-80e2-4322-9d83-839d2f6dc8b4 (device /dev/sdc1) mounted at /mnt/vchanger/5ba3d8a2-80e2-4322-9d83-839d2f6dc8b4 Mar 15 11:02:01: [31079]: magazine 0 has 10 volumes on /mnt/vchanger/5ba3d8a2-80e2-4322-9d83-839d2f6dc8b4 Mar 15 11:02:01: [31079]: 10 volumes on magazine 0 assigned slots 1-10 Mar 15 11:02:01: [31079]: magazine 1 is not mounted Mar 15 11:02:01: [31079]: magazine 2 is not mounted Mar 15 11:02:01: [31079]: saved state of magazine 0 Mar 15 11:02:01: [31079]: saved dynamic configuration (max used slot: 10) Mar 15 11:02:01: [31079]: drive 0 previously unloaded Mar 15 11:02:01: [31079]: ==== preforming LIST command Mar 15 11:02:01: [31079]: SUCCESS sent list to stdout Mar 15 11:02:01: [31055]: bconsole: bconsole terminated normally Mar 15 11:02:01: [31055]: bconsole output: Connecting to Director bacula.lnf.it:9101 1000 OK: 103 lnfbacula-dir Version: 9.4.2 (04 February 2019) Enter a period to cancel a command. update slots storage="SDPVE2RDX" drive="0" Automatically selected Catalog: BaculaLNF Using Catalog "BaculaLNF" Connecting to Storage daemon SDPVE2RDX at sdpve2.sd.lnf.it:9103 ... 3306 Issuing autochanger "slots" command. Device "RDXAutochanger" has 10 slots. Connecting to Storage daemon SDPVE2RDX at sdpve2.sd.lnf.it:9103 ... 3306 Issuing autochanger "list" command. Catalog record for Volume "SDPVE2RDX_0000_0000" updated to reference slot 1. Catalog record for Volume "SDPVE2RDX_0000_0001" updated to reference slot 2. Catalog record for Volume "SDPVE2RDX_0000_0002" updated to reference slot 3. Catalog record for Volume "SDPVE2RDX_0000_0003" updated to reference slot 4. Catalog record for Volume "SDPVE2RDX_0000_0004" updated to reference slot 5. Catalog record for Volume "SDPVE2RDX_0000_0005" updated to reference slot 6. Catalog record for Volume "SDPVE2RDX_0000_0006" updated to reference slot 7. Catalog record for Volume "SDPVE2RDX_0000_0007" updated to reference slot 8. Catalog record for Volume "SDPVE2RDX_0000_0008" updated to reference slot 9. Catalog record for Volume "SDPVE2RDX_0000_0009" updated to reference slot 10. You have messages. Mar 15 11:02:01: [31055]: bconsole update slots command success > that vchanger does. The udev script will run vchanger with the REFRESH > command. > If you don't see a REFRESH command being logged in the vchanger log file > when > the cartridge is removed, then Bill is correct, the RDX device is not > generating an ACTION="remove" event in udev when the cartridge is removed. > > >> >> Simply they are not purgeable, so bacula start to purge volumes in cartdrige >> 1 (right) and mount them (wrong), puting them on error. >> > > [-- text/html, encoding 8bit, charset: UTF-8, 66 lines --] > > [-- text/plain, encoding 7bit, charset: us-ascii, 1 lines --] > > > [-- text/plain, encoding 7bit, charset: us-ascii, 5 lines --] > > _______________________________________________ > Bacula-users mailing list > Bacula-users@lists.sourceforge.net > https://lists.sourceforge.net/lists/listinfo/bacula-users > -- Le camere deliberano lo stato di guerra e conferiscono al Governo i poteri necessari. (art. 78 Costituzione) _______________________________________________ Bacula-users mailing list Bacula-users@lists.sourceforge.net https://lists.sourceforge.net/lists/listinfo/bacula-users
