Skip to content
This repository has been archived by the owner on Jan 5, 2024. It is now read-only.

sd-export-usb vm does not start [investigation] #10

Closed
sssoleileraaa opened this issue Sep 9, 2019 · 10 comments
Closed

sd-export-usb vm does not start [investigation] #10

sssoleileraaa opened this issue Sep 9, 2019 · 10 comments

Comments

@sssoleileraaa
Copy link
Contributor

sssoleileraaa commented Sep 9, 2019

Description

A couple times, when sd-export-usb starts, I've seen these popup notifications with the error message:
Domain sd-export-usb has failed to start: list.remove(x): x not in list

Not sure what's causing this, but this doesn't resolve the issue:

make remove-sd-export
qvm-remove sd-export-template
make sd-export

(Note: make sd-export needs run with a usb device plugged in with config.json containing the correct USB device identifier)

This fixed it once:

  1. kill qui-devices per Device widget not showing attached devices.  QubesOS/qubes-issues#4878 (comment)
  2. run qvm-usb ls with the usb device plugged in, see what its assigned number is (it was a new number for me)
  3. update config.json to the correct usb device number
  4. rerun make sd-export
  5. start sd-export and no longer see a bunch of qube popup notifications

The other time, it did not. I ended up doing a combination of everything mentioned above with rebooting and finally a make clean && make all before the issue was resolved.

See QubesOS/qubes-issues#4849

@sssoleileraaa
Copy link
Contributor Author

I noticed that when things are bad sd-export-usb no longer has a Start option in the qube menu, see below. It actually looks like the vm is using an entirely different template. I think this is an important clue, but still cannot repro.

*BAd
signal-attachment-2019-09-09-163612

Good
signal-attachment-2019-09-09-163607

@sssoleileraaa
Copy link
Contributor Author

Note: sd-export-template is a template for sd-export-usb-dvm is a template for sd-export-usb

@sssoleileraaa
Copy link
Contributor Author

More details

In guest-sd-export-usb.log:

Error: Driver 'pcspkr' is already registered, aborting...
...
sd-export-usb login: ... grsec: denied priority change of process (rtkit-daemon...)
... SMBus Host Controller not enabled!

And this is repeated until you unplug the device.

Possible STR

  1. Plug in thumb drive
  2. Make sure sd-export-usb is shut down
  3. Run qvm-start sd-export-usb
  4. See it connect and then quickly see Domain sd-export-usb has failed to start: list.remove(x): x not in list errors
  5. If you don't see the errors, unplug thumb drive and plug in a keyboard or mouse in the same port
  6. Run make sd-export
  7. Run qvm-start sd-export-usb
  8. Now see errors

@sssoleileraaa sssoleileraaa changed the title sd-export-usb vm becomes unusable [investigation] sd-export-usb vm does not start [investigation] Sep 27, 2019
@eloquence
Copy link
Member

eloquence commented Sep 27, 2019

I'm unable to reproduce this. As expected per freedomofpress/securedrop-workstation#307 I frequently have to update the bus ID config.json during replug event (and once, I think, even during a VM restart event), but as long as the bus ID in config.json is the same reported by sys-usb, sd-export-usb will start and restart just fine for me. If there is a mismatch, the error message is different: "Domain sd-export-usb has failed to start: usb device sys-usb:x-y not available"

One clarification question:

Run make sd-export

Is it safe to do so without first running make remove-sd-export? It works for me either way, but just wanting to make sure that this may not be a contributing factor to the problem.

I'm also curious if you get the "usb device sys-usb:x-y not available" error message I get in case of a USB ID mismatch, or if the "list.remove(x): x not in list" error message you see takes the place of the error message I see.

@sssoleileraaa
Copy link
Contributor Author

Thanks for taking the time to try to repro. I ran through the normal workstation setup procedure this afternoon, and again encountered this error that makes sd-export-usb fail to start. Here's what I did:

  1. make clean in dom0
  2. plug in thumb drive and checked sys-usb which reported Bus 004 Device 005 (also on Bus 004 were root hub, Intel Corp, and Acer, Inc
  3. checked Qubes device menu and saw sys-usb:4-2
  4. checked config.json which has the entry: "usb": { "device": "sys-usb:4-2" }
  5. make all in dom0 which completed without error
  6. with the thumb drive still plugged in, ran qvm-start sd-export-usb
  7. the vm fails to start and the errors start showing.

Note: sys-usb is running Fedora 29. After a clean make install I checked the sd-export-usb Qube settings and see that the Template dropdown is blank. Is that expected?

After duck-duck-go-ing some of the warnings and errors in the logs, I think we can ignore SMBus Host Controller not enabled because I think it's caused by debian always trying to load the smbus module, which a VM does not have? Not sure though.

Error: Driver 'pcspkr' is already registered, aborting... sounds like pcspkr and some other module are both trying to handle the same device, see https://forum.proxmox.com/threads/pve-5-0-driver-pcspkr-is-already-registered-aborting-message.35497/

I'm having a hard time finding anything that makes even a little sense about grsec: denied priority change of process (rtkit-daemon.

I get zero results across search engines for "failed to start: list.remove(x): x not in list"

I will keep trying because not being able to run the export vm prevents me from working on fixing the bus id issue.

@sssoleileraaa
Copy link
Contributor Author

This is not a fix, but this time it worked when I did:

  1. make clean in dom0
  2. plug in thumb drive
  3. checked Qubes device menu and saw sys-usb:2-2
  4. forgot to change config.json which had the entry: "usb": { "device": "sys-usb:4-2" }
  5. make all in dom0 which completed without error
  6. with the thumb drive still plugged in, ran qvm-start sd-export-usb and the vm successfully started but the device did not attach (because of step 4)
  7. updated config.json to sys-usb:2-2
  8. make sd-export
  9. qvm-start sd-export-usb and the vm successfully started with the device attached

Next time someone runs into this issue, please run journalctl in dom0 to see if you get a traceback or some error messages, and compare to: QubesOS/qubes-issues#4849 (comment)

Note: killing qui-devices per QubesOS/qubes-issues#4878 (comment) is not a fix (it was still broken over a dozen times after trying this)

@sssoleileraaa
Copy link
Contributor Author

Export VM logs

Last week I learned that you can view a VMs logs by going to Q menu -> select VM -> select View console logs. This option to view console logs is available during a small window while the Export VM is starting (before it fails).

Here are the logs with warnings and errors I already mentioned above:

Export VM logs
[    7.363314] piix4_smbus 0000:00:01.3: SMBus Host Controller not enabled!
[    8.497097] grsec: time set by /lib/systemd/systemd[systemd:1] uid/euid:0/0 gid/egid:0/0, parent /[swapper/0:0] uid/euid:0/0 gid/egid:0/0
[    9.536602] Error: Driver 'pcspkr' is already registered, aborting...
[   11.717266] grsec: time set by /bin/date[date:654] uid/euid:0/0 gid/egid:0/0, parent /usr/lib/qubes/qubes-sync-clock[qubes-sync-cloc:651] uid/euid:0/0 gid/egid:0/0


Debian GNU/Linux 9 sd-export-usb hvc0

sd-export-usb login: [   13.556143] grsec: denied priority change of process (rtkit-daemon:1098) by /usr/lib/rtkit/rtkit-daemon[rtkit-daemon:1098] uid/euid:108/108 gid/egid:113/113, parent /lib/systemd/systemd[systemd:1] uid/euid:0/0 gid/egid:0/0
[ 3203.496545] grsec: time set by /bin/date[date:2988] uid/euid:0/0 gid/egid:0/0, parent /usr/lib/qubes/qubes-sync-clock[qubes-sync-cloc:2987] uid/euid:0/0 gid/egid:0/0
[ 3233.092875] grsec: time set by /bin/date[date:3052] uid/euid:0/0 gid/egid:0/0, parent /usr/lib/qubes/qubes-sync-clock[qubes-sync-cloc:3051] uid/euid:0/0 gid/egid:0/0
[ 3304.805309] grsec: time set by /bin/date[date:3105] uid/euid:0/0 gid/egid:0/0, parent /usr/lib/qubes/qubes-sync-clock[qubes-sync-cloc:3104] uid/euid:0/0 gid/egid:0/0
[ 3955.313807] grsec: time set by /bin/date[date:3172] uid/euid:0/0 gid/egid:0/0, parent /usr/lib/qubes/qubes-sync-clock[qubes-sync-cloc:3171] uid/euid:0/0 gid/egid:0/0
[    7.255736] piix4_smbus 0000:00:01.3: SMBus Host Controller not enabled!
[    8.305284] grsec: time set by /lib/systemd/systemd[systemd:1] uid/euid:0/0 gid/egid:0/0, parent /[swapper/0:0] uid/euid:0/0 gid/egid:0/0
[    9.354016] Error: Driver 'pcspkr' is already registered, aborting...
[   11.531582] grsec: time set by /bin/date[date:672] uid/euid:0/0 gid/egid:0/0, parent /usr/lib/qubes/qubes-sync-clock[qubes-sync-cloc:667] uid/euid:0/0 gid/egid:0/0


Debian GNU/Linux 9 sd-export-usb hvc0

sd-export-usb login: [   34.204017] INFO: rcu_preempt self-detected stall on CPU
[   34.204041] 	0-...: (5249 ticks this GP) idle=f62/140000000000001/0 softirq=1176/6424 fqs=0 
[   34.204062] 	 (t=5250 jiffies g=342 c=341 q=285)
[   34.204076] rcu_preempt kthread starved for 5250 jiffies! g342 c341 f0x0 RCU_GP_WAIT_FQS(3) ->state=0x402 ->cpu=1
[   60.188020] watchdog: BUG: soft lockup - CPU#0 stuck for 23s! [rtkit-daemon:1091]
[   88.188016] watchdog: BUG: soft lockup - CPU#0 stuck for 23s! [rtkit-daemon:1091]
[   97.216017] INFO: rcu_preempt self-detected stall on CPU
[   97.216042] 	0-...: (21002 ticks this GP) idle=f62/140000000000001/0 softirq=1176/22177 fqs=0 
[   97.216062] 	 (t=21003 jiffies g=342 c=341 q=285)
[   97.216076] rcu_preempt kthread starved for 21003 jiffies! g342 c341 f0x0 RCU_GP_WAIT_FQS(3) ->state=0x200 ->cpu=1
[  124.188050] watchdog: BUG: soft lockup - CPU#0 stuck for 22s! [rtkit-daemon:1091]
[  152.188016] watchdog: BUG: soft lockup - CPU#0 stuck for 22s! [rtkit-daemon:1091]
[  160.228031] INFO: rcu_preempt self-detected stall on CPU
[  160.228057] 	0-...: (36679 ticks this GP) idle=f62/140000000000001/0 softirq=1176/37854 fqs=0 
[  160.228078] 	 (t=36756 jiffies g=342 c=341 q=285)
[  160.228093] rcu_preempt kthread starved for 36756 jiffies! g342 c341 f0x0 RCU_GP_WAIT_FQS(3) ->state=0x200 ->cpu=1
[  188.188034] watchdog: BUG: soft lockup - CPU#0 stuck for 23s! [rtkit-daemon:1091]
[  216.188011] watchdog: BUG: soft lockup - CPU#0 stuck for 23s! [rtkit-daemon:1091]
[  223.240015] INFO: rcu_preempt self-detected stall on CPU
[  223.240040] 	0-...: (52170 ticks this GP) idle=f62/140000000000001/0 softirq=1176/53343 fqs=0 
[  223.240060] 	 (t=52509 jiffies g=342 c=341 q=285)
[  223.240076] rcu_preempt kthread starved for 52509 jiffies! g342 c341 f0x0 RCU_GP_WAIT_FQS(3) ->state=0x200 ->cpu=1
[    7.249540] piix4_smbus 0000:00:01.3: SMBus Host Controller not enabled!
[    8.387701] grsec: time set by /lib/systemd/systemd[systemd:1] uid/euid:0/0 gid/egid:0/0, parent /[swapper/0:0] uid/euid:0/0 gid/egid:0/0
[    9.492255] Error: Driver 'pcspkr' is already registered, aborting...
[   11.701570] grsec: time set by /bin/date[date:658] uid/euid:0/0 gid/egid:0/0, parent /usr/lib/qubes/qubes-sync-clock[qubes-sync-cloc:624] uid/euid:0/0 gid/egid:0/0


Debian GNU/Linux 9 sd-export-usb hvc0

sd-export-usb login: [   14.005141] grsec: denied priority change of process (rtkit-daemon:1053) by /usr/lib/rtkit/rtkit-daemon[rtkit-daemon:1053] uid/euid:108/108 gid/egid:113/113, parent /lib/systemd/systemd[systemd:1] uid/euid:0/0 gid/egid:0/0
[  861.829462] reboot: Power down
[    6.865511] piix4_smbus 0000:00:01.3: SMBus Host Controller not enabled!
[    7.986964] grsec: time set by /lib/systemd/systemd[systemd:1] uid/euid:0/0 gid/egid:0/0, parent /[swapper/0:0] uid/euid:0/0 gid/egid:0/0
[    9.064973] Error: Driver 'pcspkr' is already registered, aborting...
[   11.226429] grsec: time set by /bin/date[date:711] uid/euid:0/0 gid/egid:0/0, parent /usr/lib/qubes/qubes-sync-clock[qubes-sync-cloc:682] uid/euid:0/0 gid/egid:0/0


Debian GNU/Linux 9 sd-export-usb hvc0

sd-export-usb login: [   12.695113] grsec: denied priority change of process (rtkit-daemon:923) by /usr/lib/rtkit/rtkit-daemon[rtkit-daemon:923] uid/euid:108/108 gid/egid:113/113, parent /lib/systemd/systemd[systemd:1] uid/euid:0/0 gid/egid:0/0
[   33.314215] reboot: Power down
[    6.723128] piix4_smbus 0000:00:01.3: SMBus Host Controller not enabled!
[    7.798808] grsec: time set by /lib/systemd/systemd[systemd:1] uid/euid:0/0 gid/egid:0/0, parent /[swapper/0:0] uid/euid:0/0 gid/egid:0/0
[    8.798734] Error: Driver 'pcspkr' is already registered, aborting...
[   11.046353] grsec: time set by /bin/date[date:696] uid/euid:0/0 gid/egid:0/0, parent /usr/lib/qubes/qubes-sync-clock[qubes-sync-cloc:672] uid/euid:0/0 gid/egid:0/0


Debian GNU/Linux 9 sd-export-usb hvc0

sd-export-usb login: [   12.484550] grsec: denied priority change of process (rtkit-daemon:890) by /usr/lib/rtkit/rtkit-daemon[rtkit-daemon:890] uid/euid:108/108 gid/egid:113/113, parent /lib/systemd/systemd[systemd:1] uid/euid:0/0 gid/egid:0/0
[    6.476940] piix4_smbus 0000:00:01.3: SMBus Host Controller not enabled!
[    7.584860] grsec: time set by /lib/systemd/systemd[systemd:1] uid/euid:0/0 gid/egid:0/0, parent /[swapper/0:0] uid/euid:0/0 gid/egid:0/0
[    8.641604] Error: Driver 'pcspkr' is already registered, aborting...
[   10.876562] grsec: time set by /bin/date[date:681] uid/euid:0/0 gid/egid:0/0, parent /usr/lib/qubes/qubes-sync-clock[qubes-sync-cloc:657] uid/euid:0/0 gid/egid:0/0


Debian GNU/Linux 9 sd-export-usb hvc0

sd-export-usb login: [   12.973154] grsec: denied priority change of process (rtkit-daemon:1051) by /usr/lib/rtkit/rtkit-daemon[rtkit-daemon:1051] uid/euid:108/108 gid/egid:113/113, parent /lib/systemd/systemd[systemd:1] uid/euid:0/0 gid/egid:0/0
[    6.699665] piix4_smbus 0000:00:01.3: SMBus Host Controller not enabled!
[    7.766258] grsec: time set by /lib/systemd/systemd[systemd:1] uid/euid:0/0 gid/egid:0/0, parent /[swapper/0:0] uid/euid:0/0 gid/egid:0/0
[    8.931277] Error: Driver 'pcspkr' is already registered, aborting...
[   11.081572] grsec: time set by /bin/date[date:660] uid/euid:0/0 gid/egid:0/0, parent /usr/lib/qubes/qubes-sync-clock[qubes-sync-cloc:638] uid/euid:0/0 gid/egid:0/0


Debian GNU/Linux 9 sd-export-usb hvc0

sd-export-usb login: [    6.776744] piix4_smbus 0000:00:01.3: SMBus Host Controller not enabled!
[    7.841362] grsec: time set by /lib/systemd/systemd[systemd:1] uid/euid:0/0 gid/egid:0/0, parent /[swapper/0:0] uid/euid:0/0 gid/egid:0/0
[    9.111468] Error: Driver 'pcspkr' is already registered, aborting...
[   11.188704] grsec: time set by /bin/date[date:677] uid/euid:0/0 gid/egid:0/0, parent /usr/lib/qubes/qubes-sync-clock[qubes-sync-cloc:657] uid/euid:0/0 gid/egid:0/0
[    6.842749] piix4_smbus 0000:00:01.3: SMBus Host Controller not enabled!
[    7.922889] grsec: time set by /lib/systemd/systemd[systemd:1] uid/euid:0/0 gid/egid:0/0, parent /[swapper/0:0] uid/euid:0/0 gid/egid:0/0
[    9.040554] Error: Driver 'pcspkr' is already registered, aborting...
[   11.213107] grsec: time set by /bin/date[date:658] uid/euid:0/0 gid/egid:0/0, parent /usr/lib/qubes/qubes-sync-clock[qubes-sync-cloc:646] uid/euid:0/0 gid/egid:0/0
[    6.757776] piix4_smbus 0000:00:01.3: SMBus Host Controller not enabled!
[    7.918200] grsec: time set by /lib/systemd/systemd[systemd:1] uid/euid:0/0 gid/egid:0/0, parent /[swapper/0:0] uid/euid:0/0 gid/egid:0/0
[    9.159329] Error: Driver 'pcspkr' is already registered, aborting...
[   11.311846] grsec: time set by /bin/date[date:713] uid/euid:0/0 gid/egid:0/0, parent /usr/lib/qubes/qubes-sync-clock[qubes-sync-cloc:689] uid/euid:0/0 gid/egid:0/0
[    6.711253] piix4_smbus 0000:00:01.3: SMBus Host Controller not enabled!
[    7.784469] grsec: time set by /lib/systemd/systemd[systemd:1] uid/euid:0/0 gid/egid:0/0, parent /[swapper/0:0] uid/euid:0/0 gid/egid:0/0
[    8.947701] Error: Driver 'pcspkr' is already registered, aborting...
[   11.296539] grsec: time set by /bin/date[date:692] uid/euid:0/0 gid/egid:0/0, parent /usr/lib/qubes/qubes-sync-clock[qubes-sync-cloc:667] uid/euid:0/0 gid/egid:0/0
[    6.725795] piix4_smbus 0000:00:01.3: SMBus Host Controller not enabled!
[    7.798876] grsec: time set by /lib/systemd/systemd[systemd:1] uid/euid:0/0 gid/egid:0/0, parent /[swapper/0:0] uid/euid:0/0 gid/egid:0/0
[    9.024089] Error: Driver 'pcspkr' is already registered, aborting...
[   11.064489] grsec: time set by /bin/date[date:657] uid/euid:0/0 gid/egid:0/0, parent /usr/lib/qubes/qubes-sync-clock[qubes-sync-cloc:640] uid/euid:0/0 gid/egid:0/0
[    6.688752] piix4_smbus 0000:00:01.3: SMBus Host Controller not enabled!
[    7.854712] grsec: time set by /lib/systemd/systemd[systemd:1] uid/euid:0/0 gid/egid:0/0, parent /[swapper/0:0] uid/euid:0/0 gid/egid:0/0
[    8.861436] Error: Driver 'pcspkr' is already registered, aborting...
[   11.348190] grsec: time set by /bin/date[date:694] uid/euid:0/0 gid/egid:0/0, parent /usr/lib/qubes/qubes-sync-clock[qubes-sync-cloc:677] uid/euid:0/0 gid/egid:0/0
[    6.748791] piix4_smbus 0000:00:01.3: SMBus Host Controller not enabled!
[    7.835386] grsec: time set by /lib/systemd/systemd[systemd:1] uid/euid:0/0 gid/egid:0/0, parent /[swapper/0:0] uid/euid:0/0 gid/egid:0/0
[    8.931386] Error: Driver 'pcspkr' is already registered, aborting...
[   11.306540] grsec: time set by /bin/date[date:659] uid/euid:0/0 gid/egid:0/0, parent /usr/lib/qubes/qubes-sync-clock[qubes-sync-cloc:616] uid/euid:0/0 gid/egid:0/0
[    6.778525] piix4_smbus 0000:00:01.3: SMBus Host Controller not enabled!
[    7.902513] grsec: time set by /lib/systemd/systemd[systemd:1] uid/euid:0/0 gid/egid:0/0, parent /[swapper/0:0] uid/euid:0/0 gid/egid:0/0
[    8.917374] Error: Driver 'pcspkr' is already registered, aborting...
[   11.383235] grsec: time set by /bin/date[date:680] uid/euid:0/0 gid/egid:0/0, parent /usr/lib/qubes/qubes-sync-clock[qubes-sync-cloc:659] uid/euid:0/0 gid/egid:0/0
[    6.765186] piix4_smbus 0000:00:01.3: SMBus Host Controller not enabled!
[    7.857287] grsec: time set by /lib/systemd/systemd[systemd:1] uid/euid:0/0 gid/egid:0/0, parent /[swapper/0:0] uid/euid:0/0 gid/egid:0/0
[    9.070246] Error: Driver 'pcspkr' is already registered, aborting...
[   11.178268] grsec: time set by /bin/date[date:649] uid/euid:0/0 gid/egid:0/0, parent /usr/lib/qubes/qubes-sync-clock[qubes-sync-cloc:626] uid/euid:0/0 gid/egid:0/0
[    6.782641] piix4_smbus 0000:00:01.3: SMBus Host Controller not enabled!
[    7.849418] grsec: time set by /lib/systemd/systemd[systemd:1] uid/euid:0/0 gid/egid:0/0, parent /[swapper/0:0] uid/euid:0/0 gid/egid:0/0
[    9.076413] Error: Driver 'pcspkr' is already registered, aborting...
[   11.257649] grsec: time set by /bin/date[date:657] uid/euid:0/0 gid/egid:0/0, parent /usr/lib/qubes/qubes-sync-clock[qubes-sync-cloc:631] uid/euid:0/0 gid/egid:0/0
[    6.723167] piix4_smbus 0000:00:01.3: SMBus Host Controller not enabled!
[    7.848817] grsec: time set by /lib/systemd/systemd[systemd:1] uid/euid:0/0 gid/egid:0/0, parent /[swapper/0:0] uid/euid:0/0 gid/egid:0/0
[    8.931478] Error: Driver 'pcspkr' is already registered, aborting...
[   11.251077] grsec: time set by /bin/date[date:691] uid/euid:0/0 gid/egid:0/0, parent /usr/lib/qubes/qubes-sync-clock[qubes-sync-cloc:679] uid/euid:0/0 gid/egid:0/0
[    6.813461] piix4_smbus 0000:00:01.3: SMBus Host Controller not enabled!
[    7.932309] grsec: time set by /lib/systemd/systemd[systemd:1] uid/euid:0/0 gid/egid:0/0, parent /[swapper/0:0] uid/euid:0/0 gid/egid:0/0
[    9.177797] Error: Driver 'pcspkr' is already registered, aborting...
[   11.293677] grsec: time set by /bin/date[date:668] uid/euid:0/0 gid/egid:0/0, parent /usr/lib/qubes/qubes-sync-clock[qubes-sync-cloc:649] uid/euid:0/0 gid/egid:0/0
[    6.775268] piix4_smbus 0000:00:01.3: SMBus Host Controller not enabled!
[    7.898972] grsec: time set by /lib/systemd/systemd[systemd:1] uid/euid:0/0 gid/egid:0/0, parent /[swapper/0:0] uid/euid:0/0 gid/egid:0/0
[    9.075943] Error: Driver 'pcspkr' is already registered, aborting...
[   11.299810] grsec: time set by /bin/date[date:694] uid/euid:0/0 gid/egid:0/0, parent /usr/lib/qubes/qubes-sync-clock[qubes-sync-cloc:680] uid/euid:0/0 gid/egid:0/0
[    6.773293] piix4_smbus 0000:00:01.3: SMBus Host Controller not enabled!
[    7.930464] grsec: time set by /lib/systemd/systemd[systemd:1] uid/euid:0/0 gid/egid:0/0, parent /[swapper/0:0] uid/euid:0/0 gid/egid:0/0
[    9.210232] Error: Driver 'pcspkr' is already registered, aborting...
[   11.353486] grsec: time set by /bin/date[date:697] uid/euid:0/0 gid/egid:0/0, parent /usr/lib/qubes/qubes-sync-clock[qubes-sync-cloc:680] uid/euid:0/0 gid/egid:0/0
[    6.926157] piix4_smbus 0000:00:01.3: SMBus Host Controller not enabled!
[    8.035818] grsec: time set by /lib/systemd/systemd[systemd:1] uid/euid:0/0 gid/egid:0/0, parent /[swapper/0:0] uid/euid:0/0 gid/egid:0/0
[    9.164715] Error: Driver 'pcspkr' is already registered, aborting...
[   11.418366] grsec: time set by /bin/date[date:648] uid/euid:0/0 gid/egid:0/0, parent /usr/lib/qubes/qubes-sync-clock[qubes-sync-cloc:628] uid/euid:0/0 gid/egid:0/0
[    7.671294] piix4_smbus 0000:00:01.3: SMBus Host Controller not enabled!
[    8.799892] grsec: time set by /lib/systemd/systemd[systemd:1] uid/euid:0/0 gid/egid:0/0, parent /[swapper/0:0] uid/euid:0/0 gid/egid:0/0
[    9.975797] Error: Driver 'pcspkr' is already registered, aborting...
[   12.590288] grsec: time set by /bin/date[date:681] uid/euid:0/0 gid/egid:0/0, parent /usr/lib/qubes/qubes-sync-clock[qubes-sync-cloc:649] uid/euid:0/0 gid/egid:0/0


Debian GNU/Linux 9 sd-export-usb hvc0

sd-export-usb login: [   14.763325] grsec: denied priority change of process (rtkit-daemon:924) by /usr/lib/rtkit/rtkit-daemon[rtkit-daemon:924] uid/euid:108/108 gid/egid:113/113, parent /lib/systemd/systemd[systemd:1] uid/euid:0/0 gid/egid:0/0
[  161.329394] reboot: Power down
[    7.180499] piix4_smbus 0000:00:01.3: SMBus Host Controller not enabled!
[    8.223929] grsec: time set by /lib/systemd/systemd[systemd:1] uid/euid:0/0 gid/egid:0/0, parent /[swapper/0:0] uid/euid:0/0 gid/egid:0/0
[    9.378474] Error: Driver 'pcspkr' is already registered, aborting...
[   11.598182] grsec: time set by /bin/date[date:658] uid/euid:0/0 gid/egid:0/0, parent /usr/lib/qubes/qubes-sync-clock[qubes-sync-cloc:637] uid/euid:0/0 gid/egid:0/0
[    7.087334] piix4_smbus 0000:00:01.3: SMBus Host Controller not enabled!
[    8.149900] grsec: time set by /lib/systemd/systemd[systemd:1] uid/euid:0/0 gid/egid:0/0, parent /[swapper/0:0] uid/euid:0/0 gid/egid:0/0
[    9.354475] Error: Driver 'pcspkr' is already registered, aborting...
[   11.534859] grsec: time set by /bin/date[date:660] uid/euid:0/0 gid/egid:0/0, parent /usr/lib/qubes/qubes-sync-clock[qubes-sync-cloc:639] uid/euid:0/0 gid/egid:0/0
[    7.020816] piix4_smbus 0000:00:01.3: SMBus Host Controller not enabled!
[    8.129915] grsec: time set by /lib/systemd/systemd[systemd:1] uid/euid:0/0 gid/egid:0/0, parent /[swapper/0:0] uid/euid:0/0 gid/egid:0/0
[    9.229236] Error: Driver 'pcspkr' is already registered, aborting...
[   11.429946] grsec: time set by /bin/date[date:658] uid/euid:0/0 gid/egid:0/0, parent /usr/lib/qubes/qubes-sync-clock[qubes-sync-cloc:635] uid/euid:0/0 gid/egid:0/0
[    6.977700] piix4_smbus 0000:00:01.3: SMBus Host Controller not enabled!
[    8.090107] grsec: time set by /lib/systemd/systemd[systemd:1] uid/euid:0/0 gid/egid:0/0, parent /[swapper/0:0] uid/euid:0/0 gid/egid:0/0
[    9.212910] Error: Driver 'pcspkr' is already registered, aborting...
[   11.382981] grsec: time set by /bin/date[date:677] uid/euid:0/0 gid/egid:0/0, parent /usr/lib/qubes/qubes-sync-clock[qubes-sync-cloc:647] uid/euid:0/0 gid/egid:0/0
[    7.062175] piix4_smbus 0000:00:01.3: SMBus Host Controller not enabled!
[    8.209965] grsec: time set by /lib/systemd/systemd[systemd:1] uid/euid:0/0 gid/egid:0/0, parent /[swapper/0:0] uid/euid:0/0 gid/egid:0/0
[    9.305980] Error: Driver 'pcspkr' is already registered, aborting...
[   11.534477] grsec: time set by /bin/date[date:679] uid/euid:0/0 gid/egid:0/0, parent /usr/lib/qubes/qubes-sync-clock[qubes-sync-cloc:655] uid/euid:0/0 gid/egid:0/0
[    7.183601] piix4_smbus 0000:00:01.3: SMBus Host Controller not enabled!
[    8.305262] grsec: time set by /lib/systemd/systemd[systemd:1] uid/euid:0/0 gid/egid:0/0, parent /[swapper/0:0] uid/euid:0/0 gid/egid:0/0
[    9.255761] Error: Driver 'pcspkr' is already registered, aborting...
[   11.671772] grsec: time set by /bin/date[date:648] uid/euid:0/0 gid/egid:0/0, parent /usr/lib/qubes/qubes-sync-clock[qubes-sync-cloc:633] uid/euid:0/0 gid/egid:0/0
[    7.304308] piix4_smbus 0000:00:01.3: SMBus Host Controller not enabled!
[    8.327756] grsec: time set by /lib/systemd/systemd[systemd:1] uid/euid:0/0 gid/egid:0/0, parent /[swapper/0:0] uid/euid:0/0 gid/egid:0/0
[    9.591927] Error: Driver 'pcspkr' is already registered, aborting...
[   11.575958] grsec: time set by /bin/date[date:651] uid/euid:0/0 gid/egid:0/0, parent /usr/lib/qubes/qubes-sync-clock[qubes-sync-cloc:634] uid/euid:0/0 gid/egid:0/0
[    6.976979] piix4_smbus 0000:00:01.3: SMBus Host Controller not enabled!
[    8.089942] grsec: time set by /lib/systemd/systemd[systemd:1] uid/euid:0/0 gid/egid:0/0, parent /[swapper/0:0] uid/euid:0/0 gid/egid:0/0
[    9.250256] Error: Driver 'pcspkr' is already registered, aborting...
[   11.383639] grsec: time set by /bin/date[date:661] uid/euid:0/0 gid/egid:0/0, parent /usr/lib/qubes/qubes-sync-clock[qubes-sync-cloc:645] uid/euid:0/0 gid/egid:0/0
[    7.174586] piix4_smbus 0000:00:01.3: SMBus Host Controller not enabled!
[    8.288190] grsec: time set by /lib/systemd/systemd[systemd:1] uid/euid:0/0 gid/egid:0/0, parent /[swapper/0:0] uid/euid:0/0 gid/egid:0/0
[    9.459737] Error: Driver 'pcspkr' is already registered, aborting...
[   11.518518] grsec: time set by /bin/date[date:690] uid/euid:0/0 gid/egid:0/0, parent /usr/lib/qubes/qubes-sync-clock[qubes-sync-cloc:678] uid/euid:0/0 gid/egid:0/0
[    7.165621] piix4_smbus 0000:00:01.3: SMBus Host Controller not enabled!
[    8.280217] grsec: time set by /lib/systemd/systemd[systemd:1] uid/euid:0/0 gid/egid:0/0, parent /[swapper/0:0] uid/euid:0/0 gid/egid:0/0
[    9.348580] Error: Driver 'pcspkr' is already registered, aborting...
[   11.465944] grsec: time set by /bin/date[date:700] uid/euid:0/0 gid/egid:0/0, parent /usr/lib/qubes/qubes-sync-clock[qubes-sync-cloc:674] uid/euid:0/0 gid/egid:0/0
[    7.114395] piix4_smbus 0000:00:01.3: SMBus Host Controller not enabled!
[    8.200718] grsec: time set by /lib/systemd/systemd[systemd:1] uid/euid:0/0 gid/egid:0/0, parent /[swapper/0:0] uid/euid:0/0 gid/egid:0/0
[    9.267549] Error: Driver 'pcspkr' is already registered, aborting...
[   11.463926] grsec: time set by /bin/date[date:682] uid/euid:0/0 gid/egid:0/0, parent /usr/lib/qubes/qubes-sync-clock[qubes-sync-cloc:666] uid/euid:0/0 gid/egid:0/0
[    7.273324] piix4_smbus 0000:00:01.3: SMBus Host Controller not enabled!
[    8.371937] grsec: time set by /lib/systemd/systemd[systemd:1] uid/euid:0/0 gid/egid:0/0, parent /[swapper/0:0] uid/euid:0/0 gid/egid:0/0
[    9.499467] Error: Driver 'pcspkr' is already registered, aborting...
[   11.601583] grsec: time set by /bin/date[date:672] uid/euid:0/0 gid/egid:0/0, parent /usr/lib/qubes/qubes-sync-clock[qubes-sync-cloc:656] uid/euid:0/0 gid/egid:0/0
[    7.288741] piix4_smbus 0000:00:01.3: SMBus Host Controller not enabled!
[    8.259657] grsec: time set by /lib/systemd/systemd[systemd:1] uid/euid:0/0 gid/egid:0/0, parent /[swapper/0:0] uid/euid:0/0 gid/egid:0/0
[    9.454996] Error: Driver 'pcspkr' is already registered, aborting...
[   11.560819] grsec: time set by /bin/date[date:677] uid/euid:0/0 gid/egid:0/0, parent /usr/lib/qubes/qubes-sync-clock[qubes-sync-cloc:646] uid/euid:0/0 gid/egid:0/0
[    7.273653] piix4_smbus 0000:00:01.3: SMBus Host Controller not enabled!
[    8.272459] grsec: time set by /lib/systemd/systemd[systemd:1] uid/euid:0/0 gid/egid:0/0, parent /[swapper/0:0] uid/euid:0/0 gid/egid:0/0
[    9.404635] Error: Driver 'pcspkr' is already registered, aborting...
[   11.580676] grsec: time set by /bin/date[date:668] uid/euid:0/0 gid/egid:0/0, parent /usr/lib/qubes/qubes-sync-clock[qubes-sync-cloc:650] uid/euid:0/0 gid/egid:0/0
[    8.059460] piix4_smbus 0000:00:01.3: SMBus Host Controller not enabled!
[   33.764095] watchdog: BUG: soft lockup - CPU#0 stuck for 24s! [systemd-udevd:87]
[   33.764254] INFO: rcu_preempt detected stalls on CPUs/tasks:
[   33.784253] 	0-...: (1 GPs behind) idle=32e/140000000000001/0 softirq=173/182 fqs=0 
[   33.785877] 	(detected by 1, t=6385 jiffies, g=-159, c=-160, q=54)
[   33.788455] rcu_preempt kthread starved for 6385 jiffies! g18446744073709551457 c18446744073709551456 f0x0 RCU_GP_WAIT_FQS(3) ->state=0x402 ->cpu=1
[   35.083149] grsec: time set by /lib/systemd/systemd[systemd:1] uid/euid:0/0 gid/egid:0/0, parent /[swapper/0:0] uid/euid:0/0 gid/egid:0/0
[   37.552335] Error: Driver 'pcspkr' is already registered, aborting...
[   40.601104] grsec: time set by /bin/date[date:693] uid/euid:0/0 gid/egid:0/0, parent /usr/lib/qubes/qubes-sync-clock[qubes-sync-cloc:664] uid/euid:0/0 gid/egid:0/0


Debian GNU/Linux 9 sd-export-usb hvc0

sd-export-usb login: [   42.292918] grsec: denied priority change of process (rtkit-daemon:951) by /usr/lib/rtkit/rtkit-daemon[rtkit-daemon:951] uid/euid:108/108 gid/egid:113/113, parent /lib/systemd/systemd[systemd:1] uid/euid:0/0 gid/egid:0/0
[  320.664194] reboot: Power down
[    9.218642] piix4_smbus 0000:00:01.3: SMBus Host Controller not enabled!
[   11.440124] grsec: time set by /lib/systemd/systemd[systemd:1] uid/euid:0/0 gid/egid:0/0, parent /[swapper/0:0] uid/euid:0/0 gid/egid:0/0
[   13.453026] Error: Driver 'pcspkr' is already registered, aborting...
[   17.785830] grsec: time set by /bin/date[date:719] uid/euid:0/0 gid/egid:0/0, parent /usr/lib/qubes/qubes-sync-clock[qubes-sync-cloc:676] uid/euid:0/0 gid/egid:0/0


etc.

Dom0 logs

The error logs for the Export VM unfortunately were not very useful in figuring out what was going wrong with the Export VM not starting. One useful tip is to look at Dom0 logs when VMs are misbehaving. To see Dom0 logs, run sudo journalctl -ef:

Dom0 logs
-- Logs begin at Mon 2019-07-22 11:53:33 EDT. --
Oct 08 12:44:53 dom0 kernel: audit: type=1105 audit(1570553093.895:246): pid=7368 uid=0 auid=4294967295 ses=4294967295 msg='op=PAM:session_open grantors=pam_keyinit,pam_limits,pam_unix acct="creviera" exe="/usr/sbin/runuser" hostname=? addr=? terminal=? res=success'
Oct 08 12:44:53 dom0 audit[7368]: USER_START pid=7368 uid=0 auid=4294967295 ses=4294967295 msg='op=PAM:session_open grantors=pam_keyinit,pam_limits,pam_unix acct="creviera" exe="/usr/sbin/runuser" hostname=? addr=? terminal=? res=success'
Oct 08 12:44:54 dom0 qrexec[7372]: qubes.UpdatesProxy: fedora-29 -> : allowed to sys-net
Oct 08 12:44:54 dom0 qrexec[7374]: qubes.UpdatesProxy: fedora-29 -> : allowed to sys-net
Oct 08 12:44:54 dom0 qrexec[7375]: qubes.UpdatesProxy: fedora-29 -> : allowed to sys-net
Oct 08 12:44:54 dom0 qrexec[7376]: qubes.UpdatesProxy: fedora-29 -> : allowed to sys-net
Oct 08 12:45:04 dom0 qrexec[7380]: qubes.UpdatesProxy: fedora-29 -> : allowed to sys-net
Oct 08 12:45:04 dom0 qrexec[7382]: qubes.UpdatesProxy: fedora-29 -> : allowed to sys-net
Oct 08 12:45:08 dom0 kernel: xen-blkback: backend/vbd/15/51712: using 2 queues, protocol 1 (x86_64-abi) persistent grants
Oct 08 12:45:09 dom0 kernel: xen-blkback: backend/vbd/15/51728: using 2 queues, protocol 1 (x86_64-abi) persistent grants
Oct 08 12:45:09 dom0 kernel: xen-blkback: backend/vbd/15/51744: using 2 queues, protocol 1 (x86_64-abi) persistent grants
Oct 08 12:45:11 dom0 runuser[7368]: pam_unix(runuser:session): session closed for user creviera
Oct 08 12:45:11 dom0 audit[7368]: USER_END pid=7368 uid=0 auid=4294967295 ses=4294967295 msg='op=PAM:session_close grantors=pam_keyinit,pam_limits,pam_unix acct="creviera" exe="/usr/sbin/runuser" hostname=? addr=? terminal=? res=success'
Oct 08 12:45:11 dom0 audit[7368]: CRED_DISP pid=7368 uid=0 auid=4294967295 ses=4294967295 msg='op=PAM:setcred grantors=pam_rootok acct="creviera" exe="/usr/sbin/runuser" hostname=? addr=? terminal=? res=success'
Oct 08 12:45:11 dom0 kernel: audit: type=1106 audit(1570553111.513:247): pid=7368 uid=0 auid=4294967295 ses=4294967295 msg='op=PAM:session_close grantors=pam_keyinit,pam_limits,pam_unix acct="creviera" exe="/usr/sbin/runuser" hostname=? addr=? terminal=? res=success'
Oct 08 12:45:11 dom0 kernel: audit: type=1104 audit(1570553111.513:248): pid=7368 uid=0 auid=4294967295 ses=4294967295 msg='op=PAM:setcred grantors=pam_rootok acct="creviera" exe="/usr/sbin/runuser" hostname=? addr=? terminal=? res=success'
Oct 08 12:45:11 dom0 qmemman.daemon.algo[2606]: balance_when_enough_memory(xen_free_memory=32640403, total_mem_pref=5809086131.2, total_available_memory=9170233462.8)
Oct 08 12:45:11 dom0 qmemman.daemon.algo[2606]: left_memory=790582727 acceptors_count=9
Oct 08 12:45:11 dom0 qmemman.systemstate[2606]: stat: dom '13' act=1267788139 pref=439370547.2 last_target=1267788139
Oct 08 12:45:11 dom0 qmemman.systemstate[2606]: stat: dom '14' act=2059414282 pref=740333568.0 last_target=2059414282
Oct 08 12:45:11 dom0 qmemman.systemstate[2606]: stat: dom '0' act=4294967296 pref=1974186470.4 last_target=4294967296
Oct 08 12:45:11 dom0 qmemman.systemstate[2606]: stat: dom '10' act=980709690 pref=330228121.6 last_target=980709690
Oct 08 12:45:11 dom0 qmemman.systemstate[2606]: stat: dom '7' act=1126272764 pref=385568768.0 last_target=1126272764
Oct 08 12:45:11 dom0 qmemman.systemstate[2606]: stat: dom '11' act=1450956991 pref=509008281.6 last_target=1450956991
Oct 08 12:45:11 dom0 qmemman.systemstate[2606]: stat: dom '8' act=1128793821 pref=386527232.0 last_target=1128793821
Oct 08 12:45:11 dom0 qmemman.systemstate[2606]: stat: dom '3' act=1091090002 pref=372192870.40000004 last_target=1091090002
Oct 08 12:45:11 dom0 qmemman.systemstate[2606]: stat: dom '9' act=1145040638 pref=392704000.0 last_target=1145040638
Oct 08 12:45:11 dom0 qmemman.systemstate[2606]: stat: dom '15' act=401645568 pref=278966272.0 last_target=401604608
Oct 08 12:45:11 dom0 qmemman.systemstate[2606]: stat: xenfree=85069203 memset_reqs=[('13', 1219583376), ('14', 1994871081), ('9', 1099369271), ('7', 1080988748), ('11', 1398971791), ('10', 938429963), ('8', 1083457774), ('3', 1046532127), ('0', 4294967296), ('15', 806378252)]
Oct 08 12:45:11 dom0 qmemman.systemstate[2606]: mem-set domain 13 to 1219583376
Oct 08 12:45:11 dom0 qmemman.systemstate[2606]: mem-set domain 14 to 1994871081
Oct 08 12:45:11 dom0 qmemman.systemstate[2606]: mem-set domain 9 to 1099369271
Oct 08 12:45:11 dom0 qmemman.systemstate[2606]: mem-set domain 7 to 1080988748
Oct 08 12:45:11 dom0 qmemman.systemstate[2606]: mem-set domain 11 to 1398971791
Oct 08 12:45:11 dom0 qmemman.systemstate[2606]: mem-set domain 10 to 938429963
Oct 08 12:45:11 dom0 qmemman.systemstate[2606]: mem-set domain 8 to 1083457774
Oct 08 12:45:11 dom0 qmemman.systemstate[2606]: mem-set domain 3 to 1046532127
Oct 08 12:45:11 dom0 qmemman.systemstate[2606]: mem-set domain 0 to 4294967296
Oct 08 12:45:11 dom0 qmemman.systemstate[2606]: mem-set domain 15 to 806378252
Oct 08 12:45:11 dom0 qmemman.daemon.algo[2606]: balance_when_enough_memory(xen_free_memory=11632838, total_mem_pref=5854016793.599999, total_available_memory=9121165723.4)
Oct 08 12:45:11 dom0 qmemman.daemon.algo[2606]: left_memory=864978772 acceptors_count=9
Oct 08 12:45:11 dom0 qrexec[7402]: qubes.GetDate: sd-export-usb -> @default: allowed to dom0
Oct 08 12:45:12 dom0 qrexec[7410]: qubes.USB+4-2: sd-export-usb -> sys-usb: allowed to sys-usb
Oct 08 12:45:12 dom0 qmemman.daemon.algo[2606]: balance_when_enough_memory(xen_free_memory=15769852, total_mem_pref=5896306355.2, total_available_memory=9083013175.8)
Oct 08 12:45:12 dom0 qmemman.daemon.algo[2606]: left_memory=829385795 acceptors_count=9
Oct 08 12:45:12 dom0 qmemman.systemstate[2606]: stat: dom '13' act=1219583376 pref=439370547.2 last_target=1219583376
Oct 08 12:45:12 dom0 qmemman.systemstate[2606]: stat: dom '14' act=1994871081 pref=740333568.0 last_target=1994871081
Oct 08 12:45:12 dom0 qmemman.systemstate[2606]: stat: dom '0' act=4294967296 pref=2019117132.8 last_target=4294967296
Oct 08 12:45:12 dom0 qmemman.systemstate[2606]: stat: dom '10' act=938429963 pref=330228121.6 last_target=938429963
Oct 08 12:45:12 dom0 qmemman.systemstate[2606]: stat: dom '7' act=1080988748 pref=385568768.0 last_target=1080988748
Oct 08 12:45:12 dom0 qmemman.systemstate[2606]: stat: dom '11' act=1398971791 pref=509008281.6 last_target=1398971791
Oct 08 12:45:12 dom0 qmemman.systemstate[2606]: stat: dom '8' act=1083457774 pref=386527232.0 last_target=1083457774
Oct 08 12:45:12 dom0 qmemman.systemstate[2606]: stat: dom '3' act=1046532127 pref=372192870.40000004 last_target=1046532127
Oct 08 12:45:12 dom0 qmemman.systemstate[2606]: stat: dom '9' act=1099369271 pref=392704000.0 last_target=1099369271
Oct 08 12:45:12 dom0 qmemman.systemstate[2606]: stat: dom '15' act=806378252 pref=321255833.6 last_target=806378252
Oct 08 12:45:12 dom0 qmemman.systemstate[2606]: stat: xenfree=68198652 memset_reqs=[('13', 1207148107), ('14', 1970967482), ('9', 1088712251), ('7', 1070603620), ('11', 1383882945), ('10', 930153614), ('8', 1073036123), ('3', 1036656693), ('0', 4294967296), ('15', 907382686)]
Oct 08 12:45:12 dom0 qmemman.systemstate[2606]: mem-set domain 13 to 1207148107
Oct 08 12:45:12 dom0 qmemman.systemstate[2606]: mem-set domain 14 to 1970967482
Oct 08 12:45:12 dom0 qmemman.systemstate[2606]: mem-set domain 9 to 1088712251
Oct 08 12:45:12 dom0 qmemman.systemstate[2606]: mem-set domain 7 to 1070603620
Oct 08 12:45:12 dom0 qmemman.systemstate[2606]: mem-set domain 11 to 1383882945
Oct 08 12:45:12 dom0 qmemman.systemstate[2606]: mem-set domain 10 to 930153614
Oct 08 12:45:12 dom0 qmemman.systemstate[2606]: mem-set domain 8 to 1073036123
Oct 08 12:45:12 dom0 qmemman.systemstate[2606]: mem-set domain 3 to 1036656693
Oct 08 12:45:12 dom0 qmemman.systemstate[2606]: mem-set domain 0 to 4294967296
Oct 08 12:45:12 dom0 qmemman.systemstate[2606]: mem-set domain 15 to 907382686
Oct 08 12:45:13 dom0 qmemman.daemon.algo[2606]: balance_when_enough_memory(xen_free_memory=15809380, total_mem_pref=5937930316.8, total_available_memory=9041389880.199999)
Oct 08 12:45:13 dom0 qmemman.daemon.algo[2606]: left_memory=793465109 acceptors_count=9
Oct 08 12:45:13 dom0 qmemman.systemstate[2606]: stat: dom '13' act=1207148107 pref=439370547.2 last_target=1207148107
Oct 08 12:45:13 dom0 qmemman.systemstate[2606]: stat: dom '14' act=1970967482 pref=781957529.6 last_target=1970967482
Oct 08 12:45:13 dom0 qmemman.systemstate[2606]: stat: dom '0' act=4294967296 pref=2019117132.8 last_target=4294967296
Oct 08 12:45:13 dom0 qmemman.systemstate[2606]: stat: dom '10' act=930153614 pref=330228121.6 last_target=930153614
Oct 08 12:45:13 dom0 qmemman.systemstate[2606]: stat: dom '7' act=1070603620 pref=385568768.0 last_target=1070603620
Oct 08 12:45:13 dom0 qmemman.systemstate[2606]: stat: dom '11' act=1383882945 pref=509008281.6 last_target=1383882945
Oct 08 12:45:13 dom0 qmemman.systemstate[2606]: stat: dom '8' act=1073036123 pref=386527232.0 last_target=1073036123
Oct 08 12:45:13 dom0 qmemman.systemstate[2606]: stat: dom '3' act=1036656693 pref=372192870.40000004 last_target=1036656693
Oct 08 12:45:13 dom0 qmemman.systemstate[2606]: stat: dom '9' act=1088712251 pref=392704000.0 last_target=1088712251
Oct 08 12:45:13 dom0 qmemman.systemstate[2606]: stat: dom '15' act=907382686 pref=321255833.6 last_target=907382686
Oct 08 12:45:13 dom0 qmemman.systemstate[2606]: stat: xenfree=68238180 memset_reqs=[('13', 1195344380), ('9', 1077738736), ('15', 897680254), ('7', 1059757042), ('11', 1370840343), ('10', 920291563), ('8', 1062172494), ('3', 1026048076), ('14', 2058707221), ('0', 4294967296)]
Oct 08 12:45:13 dom0 qmemman.systemstate[2606]: mem-set domain 13 to 1195344380
Oct 08 12:45:13 dom0 qmemman.systemstate[2606]: mem-set domain 9 to 1077738736
Oct 08 12:45:13 dom0 qmemman.systemstate[2606]: mem-set domain 15 to 897680254
Oct 08 12:45:13 dom0 qmemman.systemstate[2606]: mem-set domain 7 to 1059757042
Oct 08 12:45:13 dom0 qmemman.systemstate[2606]: mem-set domain 11 to 1370840343
Oct 08 12:45:13 dom0 qmemman.systemstate[2606]: mem-set domain 10 to 920291563
Oct 08 12:45:13 dom0 qmemman.systemstate[2606]: mem-set domain 8 to 1062172494
Oct 08 12:45:13 dom0 qmemman.systemstate[2606]: mem-set domain 3 to 1026048076
Oct 08 12:45:13 dom0 qmemman.systemstate[2606]: mem-set domain 14 to 2058707221
Oct 08 12:45:13 dom0 qmemman.systemstate[2606]: mem-set domain 0 to 4294967296
Oct 08 12:45:13 dom0 qrexec[7412]: qubes.WindowIconUpdater: sd-export-usb -> dom0: allowed to dom0
Oct 08 12:45:14 dom0 qubesd[2604]: Start failed: list.remove(x): x not in list
Oct 08 12:45:14 dom0 qmemman.daemon.algo[2606]: balance_when_enough_memory(xen_free_memory=182536285, total_mem_pref=5616674483.2, total_available_memory=8631728952.8)
Oct 08 12:45:14 dom0 qmemman.daemon.algo[2606]: left_memory=822015428 acceptors_count=8
Oct 08 12:45:14 dom0 qmemman.systemstate[2606]: stat: dom '13' act=1195344380 pref=439370547.2 last_target=1195344380
Oct 08 12:45:14 dom0 qmemman.systemstate[2606]: stat: dom '14' act=2058707221 pref=781957529.6 last_target=2058707221
Oct 08 12:45:14 dom0 qmemman.systemstate[2606]: stat: dom '0' act=4294967296 pref=2019117132.8 last_target=4294967296
Oct 08 12:45:14 dom0 qmemman.systemstate[2606]: stat: dom '10' act=920291563 pref=330228121.6 last_target=920291563
Oct 08 12:45:14 dom0 qmemman.systemstate[2606]: stat: dom '7' act=1059757042 pref=385568768.0 last_target=1059757042
Oct 08 12:45:14 dom0 qmemman.systemstate[2606]: stat: dom '11' act=1370840343 pref=509008281.6 last_target=1370840343
Oct 08 12:45:14 dom0 qmemman.systemstate[2606]: stat: dom '8' act=1062172494 pref=386527232.0 last_target=1062172494
Oct 08 12:45:14 dom0 qmemman.systemstate[2606]: stat: dom '3' act=1026048076 pref=372192870.40000004 last_target=1026048076
Oct 08 12:45:14 dom0 qmemman.systemstate[2606]: stat: dom '9' act=1077738736 pref=392704000.0 last_target=1077738736
Oct 08 12:45:14 dom0 qmemman.systemstate[2606]: stat: xenfree=234965085 memset_reqs=[('13', 1216131629), ('14', 2084338650), ('0', 4294967296), ('9', 1097866120), ('7', 1079783534), ('11', 1392612269), ('10', 939535538), ('8', 1082212538), ('3', 1045885433)]
Oct 08 12:45:14 dom0 qmemman.systemstate[2606]: mem-set domain 13 to 1216131629
Oct 08 12:45:14 dom0 qmemman.systemstate[2606]: mem-set domain 14 to 2084338650
Oct 08 12:45:14 dom0 qmemman.systemstate[2606]: mem-set domain 0 to 4294967296
Oct 08 12:45:14 dom0 qmemman.systemstate[2606]: mem-set domain 9 to 1097866120
Oct 08 12:45:14 dom0 qmemman.systemstate[2606]: mem-set domain 7 to 1079783534
Oct 08 12:45:14 dom0 qmemman.systemstate[2606]: mem-set domain 11 to 1392612269
Oct 08 12:45:14 dom0 qmemman.systemstate[2606]: mem-set domain 10 to 939535538
Oct 08 12:45:14 dom0 qmemman.systemstate[2606]: mem-set domain 8 to 1082212538
Oct 08 12:45:14 dom0 qmemman.systemstate[2606]: mem-set domain 3 to 1045885433
Oct 08 12:45:14 dom0 qmemman.daemon.algo[2606]: balance_when_enough_memory(xen_free_memory=914054636, total_mem_pref=5616674483.2, total_available_memory=9530713159.8)
Oct 08 12:45:14 dom0 qmemman.daemon.algo[2606]: left_memory=1144864698 acceptors_count=8
Oct 08 12:45:14 dom0 qmemman.systemstate[2606]: stat: dom '13' act=1216131629 pref=439370547.2 last_target=1216131629
Oct 08 12:45:14 dom0 qmemman.systemstate[2606]: stat: dom '14' act=2084338650 pref=781957529.6 last_target=2084338650
Oct 08 12:45:14 dom0 qmemman.systemstate[2606]: stat: dom '0' act=4294967296 pref=2019117132.8 last_target=4294967296
Oct 08 12:45:14 dom0 qmemman.systemstate[2606]: stat: dom '10' act=939535538 pref=330228121.6 last_target=939535538
Oct 08 12:45:14 dom0 qmemman.systemstate[2606]: stat: dom '7' act=1079783534 pref=385568768.0 last_target=1079783534
Oct 08 12:45:14 dom0 qmemman.systemstate[2606]: stat: dom '11' act=1392612269 pref=509008281.6 last_target=1392612269
Oct 08 12:45:14 dom0 qmemman.systemstate[2606]: stat: dom '8' act=1082212538 pref=386527232.0 last_target=1082212538
Oct 08 12:45:14 dom0 qmemman.systemstate[2606]: stat: dom '3' act=1045885433 pref=372192870.40000004 last_target=1045885433
Oct 08 12:45:14 dom0 qmemman.systemstate[2606]: stat: dom '9' act=1097866120 pref=392704000.0 last_target=1097866120
Oct 08 12:45:14 dom0 qmemman.systemstate[2606]: stat: xenfree=966483436 memset_reqs=[('13', 1326701138), ('14', 2249686535), ('0', 4294967296), ('9', 1200973823), ('7', 1181750340), ('11', 1514316591), ('10', 1032653582), ('8', 1184332599), ('3', 1145713483)]
Oct 08 12:45:14 dom0 qmemman.systemstate[2606]: mem-set domain 13 to 1326701138
Oct 08 12:45:14 dom0 qmemman.systemstate[2606]: mem-set domain 14 to 2249686535
Oct 08 12:45:14 dom0 qmemman.systemstate[2606]: mem-set domain 0 to 4294967296
Oct 08 12:45:14 dom0 qmemman.systemstate[2606]: mem-set domain 9 to 1200973823
Oct 08 12:45:14 dom0 qmemman.systemstate[2606]: mem-set domain 7 to 1181750340
Oct 08 12:45:14 dom0 qmemman.systemstate[2606]: mem-set domain 11 to 1514316591
Oct 08 12:45:14 dom0 qmemman.systemstate[2606]: mem-set domain 10 to 1032653582
Oct 08 12:45:14 dom0 qmemman.systemstate[2606]: mem-set domain 8 to 1184332599
Oct 08 12:45:14 dom0 qmemman.systemstate[2606]: mem-set domain 3 to 1145713483
Oct 08 12:45:15 dom0 qubesd[2604]: Starting sd-export-usb
Oct 08 12:45:15 dom0 qmemman.systemstate[2606]: do_balloon(memsize=577765376)
Oct 08 12:45:15 dom0 qmemman.systemstate[2606]: xenfree=78798568
Oct 08 12:45:15 dom0 qmemman.daemon.algo[2606]: balloon: dom 13 has actual memory 1326701138
Oct 08 12:45:15 dom0 qmemman.daemon.algo[2606]: balloon: dom 14 has actual memory 2249686535
Oct 08 12:45:15 dom0 qmemman.daemon.algo[2606]: balloon: dom 0 has actual memory 4294967296
Oct 08 12:45:15 dom0 qmemman.daemon.algo[2606]: balloon: dom 10 has actual memory 1032653582
Oct 08 12:45:15 dom0 qmemman.daemon.algo[2606]: balloon: dom 7 has actual memory 1181750340
Oct 08 12:45:15 dom0 qmemman.daemon.algo[2606]: balloon: dom 11 has actual memory 1514316591
Oct 08 12:45:15 dom0 qmemman.daemon.algo[2606]: balloon: dom 8 has actual memory 1184332599
Oct 08 12:45:15 dom0 qmemman.daemon.algo[2606]: balloon: dom 3 has actual memory 1145713483
Oct 08 12:45:15 dom0 qmemman.daemon.algo[2606]: balloon: dom 9 has actual memory 1200973823
Oct 08 12:45:15 dom0 qmemman.daemon.algo[2606]: req=551395608 avail=9514420903.8 donors=[('13', 887330590.8), ('14', 1467729005.4), ('0', 2275850163.2), ('10', 702425460.4), ('7', 796181572.0), ('11', 1005308309.4), ('8', 797805367.0), ('3', 773520612.5999999), ('9', 808269823.0)]
Oct 08 12:45:15 dom0 qmemman.daemon.algo[2606]: borrow 53995267.32483965 from 13
Oct 08 12:45:15 dom0 qmemman.daemon.algo[2606]: borrow 89313296.33924082 from 14
Oct 08 12:45:15 dom0 qmemman.daemon.algo[2606]: borrow 138488562.46742615 from 0
Oct 08 12:45:15 dom0 qmemman.daemon.algo[2606]: borrow 42743539.89743185 from 10
Oct 08 12:45:15 dom0 qmemman.daemon.algo[2606]: borrow 48448726.1737388 from 7
Oct 08 12:45:15 dom0 qmemman.daemon.algo[2606]: borrow 61174371.16756689 from 11
Oct 08 12:45:15 dom0 qmemman.daemon.algo[2606]: borrow 48547536.29706239 from 8
Oct 08 12:45:15 dom0 qmemman.daemon.algo[2606]: borrow 47069776.12589115 from 3
Oct 08 12:45:15 dom0 qmemman.daemon.algo[2606]: borrow 49184312.60680237 from 9
Oct 08 12:45:15 dom0 qmemman.systemstate[2606]: memset_reqs=[('13', 1272705870), ('14', 2160373238), ('0', 4156478733), ('10', 989910042), ('7', 1133301613), ('11', 1453142219), ('8', 1135785062), ('3', 1098643706), ('9', 1151789510)]
Oct 08 12:45:15 dom0 qmemman.systemstate[2606]: mem-set domain 13 to 1272705870
Oct 08 12:45:15 dom0 qmemman.systemstate[2606]: mem-set domain 14 to 2160373238
Oct 08 12:45:15 dom0 qmemman.systemstate[2606]: mem-set domain 0 to 4156478733
Oct 08 12:45:15 dom0 qmemman.systemstate[2606]: mem-set domain 10 to 989910042
Oct 08 12:45:15 dom0 qmemman.systemstate[2606]: mem-set domain 7 to 1133301613
Oct 08 12:45:15 dom0 qmemman.systemstate[2606]: mem-set domain 11 to 1453142219
Oct 08 12:45:15 dom0 qmemman.systemstate[2606]: mem-set domain 8 to 1135785062
Oct 08 12:45:15 dom0 qmemman.systemstate[2606]: mem-set domain 3 to 1098643706
Oct 08 12:45:15 dom0 qmemman.systemstate[2606]: mem-set domain 9 to 1151789510
Oct 08 12:45:15 dom0 qubesd[2604]: unhandled exception while calling src=b'dom0' meth=b'admin.vm.Start' dest=b'sd-export-usb' arg=b'' len(untrusted_payload)=0
Oct 08 12:45:15 dom0 qubesd[2604]: Traceback (most recent call last):
Oct 08 12:45:15 dom0 qubesd[2604]:   File "/usr/lib/python3.5/site-packages/qubes/api/__init__.py", line 266, in respond
Oct 08 12:45:15 dom0 qubesd[2604]:     untrusted_payload=untrusted_payload)
Oct 08 12:45:15 dom0 qubesd[2604]:   File "/usr/lib64/python3.5/asyncio/futures.py", line 381, in __iter__
Oct 08 12:45:15 dom0 qubesd[2604]:     yield self  # This tells Task to wait for completion.
Oct 08 12:45:15 dom0 qubesd[2604]:   File "/usr/lib64/python3.5/asyncio/tasks.py", line 310, in _wakeup
Oct 08 12:45:15 dom0 qubesd[2604]:     future.result()
Oct 08 12:45:15 dom0 qubesd[2604]:   File "/usr/lib64/python3.5/asyncio/futures.py", line 294, in result
Oct 08 12:45:15 dom0 qubesd[2604]:     raise self._exception
Oct 08 12:45:15 dom0 qubesd[2604]:   File "/usr/lib64/python3.5/asyncio/tasks.py", line 240, in _step
Oct 08 12:45:15 dom0 qubesd[2604]:     result = coro.send(None)
Oct 08 12:45:15 dom0 qubesd[2604]:   File "/usr/lib/python3.5/site-packages/qubes/api/admin.py", line 834, in vm_start
Oct 08 12:45:15 dom0 qubesd[2604]:     yield from self.dest.start()
Oct 08 12:45:15 dom0 qubesd[2604]:   File "/usr/lib/python3.5/site-packages/qubes/vm/dispvm.py", line 220, in start
Oct 08 12:45:15 dom0 qubesd[2604]:     yield from super(DispVM, self).start(**kwargs)
Oct 08 12:45:15 dom0 qubesd[2604]:   File "/usr/lib/python3.5/site-packages/qubes/vm/qubesvm.py", line 1071, in start
Oct 08 12:45:15 dom0 qubesd[2604]:     start_guid=start_guid)
Oct 08 12:45:15 dom0 qubesd[2604]:   File "/usr/lib/python3.5/site-packages/qubes/events.py", line 236, in fire_event_async
Oct 08 12:45:15 dom0 qubesd[2604]:     effect = task.result()
Oct 08 12:45:15 dom0 qubesd[2604]:   File "/usr/lib64/python3.5/asyncio/futures.py", line 294, in result
Oct 08 12:45:15 dom0 qubesd[2604]:     raise self._exception
Oct 08 12:45:15 dom0 qubesd[2604]:   File "/usr/lib64/python3.5/asyncio/tasks.py", line 240, in _step
Oct 08 12:45:15 dom0 qubesd[2604]:     result = coro.send(None)
Oct 08 12:45:15 dom0 qubesd[2604]:   File "/usr/lib/python3.5/site-packages/qubesusbproxy/core3ext.py", line 323, in on_domain_start
Oct 08 12:45:15 dom0 qubesd[2604]:     yield from self.on_device_attach_usb(vm, '', device, options={})
Oct 08 12:45:15 dom0 qubesd[2604]:   File "/usr/lib/python3.5/site-packages/qubesusbproxy/core3ext.py", line 286, in on_device_attach_usb
Oct 08 12:45:15 dom0 qubesd[2604]:     device.backend_domain.name))
Oct 08 12:45:15 dom0 qubesd[2604]: ValueError: list.remove(x): x not in list
Oct 08 12:45:15 dom0 qmemman.systemstate[2606]: xenfree=610809320
Oct 08 12:45:15 dom0 dmeventd[1172]: No longer monitoring thin pool qubes_dom0-pool00-tpool.
Oct 08 12:45:15 dom0 lvm[1172]: Monitoring thin pool qubes_dom0-pool00-tpool.
Oct 08 12:45:16 dom0 dmeventd[1172]: No longer monitoring thin pool qubes_dom0-pool00-tpool.
Oct 08 12:45:16 dom0 lvm[1172]: Monitoring thin pool qubes_dom0-pool00-tpool.
Oct 08 12:45:18 dom0 kernel: xen-blkback: backend/vbd/18/51712: using 1 queues, protocol 1 (x86_64-abi) persistent grants
Oct 08 12:45:18 dom0 kernel: xen-blkback: backend/vbd/18/51728: using 1 queues, protocol 1 (x86_64-abi) persistent grants
Oct 08 12:45:18 dom0 kernel: xen-blkback: backend/vbd/18/51744: using 1 queues, protocol 1 (x86_64-abi) persistent grants
Oct 08 12:45:19 dom0 qmemman.daemon.reqhandler[2606]: EOF
Oct 08 12:45:19 dom0 qubesd[2604]: Setting Qubes DB info for the VM
Oct 08 12:45:19 dom0 qubesd[2604]: Starting Qubes DB
Oct 08 12:45:19 dom0 qmemman.daemon.algo[2606]: balance_when_enough_memory(xen_free_memory=-945083, total_mem_pref=5616674483.2, total_available_memory=8934510426.8)
Oct 08 12:45:19 dom0 qmemman.daemon.algo[2606]: left_memory=930752352 acceptors_count=8
Oct 08 12:45:19 dom0 qmemman.systemstate[2606]: stat: dom '13' act=1272705870 pref=439370547.2 last_target=1272705870
Oct 08 12:45:19 dom0 qmemman.systemstate[2606]: stat: dom '14' act=2160373238 pref=781957529.6 last_target=2160373238
Oct 08 12:45:19 dom0 qmemman.systemstate[2606]: stat: dom '0' act=4156478733 pref=2019117132.8 last_target=4156478733
Oct 08 12:45:19 dom0 qmemman.systemstate[2606]: stat: dom '10' act=989910042 pref=330228121.6 last_target=989910042
Oct 08 12:45:19 dom0 qmemman.systemstate[2606]: stat: dom '7' act=1133301613 pref=385568768.0 last_target=1133301613
Oct 08 12:45:19 dom0 qmemman.systemstate[2606]: stat: dom '11' act=1453142219 pref=509008281.6 last_target=1453142219
Oct 08 12:45:19 dom0 qmemman.systemstate[2606]: stat: dom '8' act=1135785062 pref=386527232.0 last_target=1135785062
Oct 08 12:45:19 dom0 qmemman.systemstate[2606]: stat: dom '3' act=1098643706 pref=372192870.40000004 last_target=1098643706
Oct 08 12:45:19 dom0 audit[7816]: CRED_ACQ pid=7816 uid=0 auid=4294967295 ses=4294967295 msg='op=PAM:setcred grantors=pam_rootok acct="creviera" exe="/usr/sbin/runuser" hostname=? addr=? terminal=? res=success'
Oct 08 12:45:19 dom0 audit[7816]: USER_START pid=7816 uid=0 auid=4294967295 ses=4294967295 msg='op=PAM:session_open grantors=pam_keyinit,pam_limits,pam_unix acct="creviera" exe="/usr/sbin/runuser" hostname=? addr=? terminal=? res=success'
Oct 08 12:45:19 dom0 qmemman.systemstate[2606]: stat: dom '9' act=1151789510 pref=392704000.0 last_target=1151789510
Oct 08 12:45:19 dom0 qmemman.systemstate[2606]: stat: xenfree=51483717 memset_reqs=[('13', 1253371881), ('14', 2140028478), ('9', 1132593206), ('7', 1114126361), ('11', 1433602771), ('10', 970898067), ('8', 1116606982), ('3', 1079507919), ('0', 4294967296)]
Oct 08 12:45:19 dom0 kernel: audit: type=1103 audit(1570553119.232:249): pid=7816 uid=0 auid=4294967295 ses=4294967295 msg='op=PAM:setcred grantors=pam_rootok acct="creviera" exe="/usr/sbin/runuser" hostname=? addr=? terminal=? res=success'
Oct 08 12:45:19 dom0 kernel: audit: type=1105 audit(1570553119.232:250): pid=7816 uid=0 auid=4294967295 ses=4294967295 msg='op=PAM:session_open grantors=pam_keyinit,pam_limits,pam_unix acct="creviera" exe="/usr/sbin/runuser" hostname=? addr=? terminal=? res=success'
Oct 08 12:45:19 dom0 qmemman.systemstate[2606]: mem-set domain 13 to 1253371881
Oct 08 12:45:19 dom0 runuser[7816]: pam_unix(runuser:session): session opened for user creviera by (uid=0)
Oct 08 12:45:19 dom0 qmemman.systemstate[2606]: mem-set domain 14 to 2140028478
Oct 08 12:45:19 dom0 qmemman.systemstate[2606]: mem-set domain 9 to 1132593206
Oct 08 12:45:19 dom0 qmemman.systemstate[2606]: mem-set domain 7 to 1114126361
Oct 08 12:45:19 dom0 qmemman.systemstate[2606]: mem-set domain 11 to 1433602771
Oct 08 12:45:19 dom0 qmemman.systemstate[2606]: mem-set domain 10 to 970898067
Oct 08 12:45:19 dom0 qmemman.systemstate[2606]: mem-set domain 8 to 1116606982
Oct 08 12:45:19 dom0 qmemman.systemstate[2606]: mem-set domain 3 to 1079507919
Oct 08 12:45:19 dom0 qmemman.systemstate[2606]: mem-set domain 0 to 4294967296
Oct 08 12:45:19 dom0 runuser[7816]: pam_unix(runuser:session): session closed for user creviera
Oct 08 12:45:19 dom0 kernel: audit: type=1106 audit(1570553119.342:251): pid=7816 uid=0 auid=4294967295 ses=4294967295 msg='op=PAM:session_close grantors=pam_keyinit,pam_limits,pam_unix acct="creviera" exe="/usr/sbin/runuser" hostname=? addr=? terminal=? res=success'
Oct 08 12:45:19 dom0 kernel: audit: type=1104 audit(1570553119.342:252): pid=7816 uid=0 auid=4294967295 ses=4294967295 msg='op=PAM:setcred grantors=pam_rootok acct="creviera" exe="/usr/sbin/runuser" hostname=? addr=? terminal=? res=success'
Oct 08 12:45:19 dom0 audit[7816]: USER_END pid=7816 uid=0 auid=4294967295 ses=4294967295 msg='op=PAM:session_close grantors=pam_keyinit,pam_limits,pam_unix acct="creviera" exe="/usr/sbin/runuser" hostname=? addr=? terminal=? res=success'
Oct 08 12:45:19 dom0 audit[7816]: CRED_DISP pid=7816 uid=0 auid=4294967295 ses=4294967295 msg='op=PAM:setcred grantors=pam_rootok acct="creviera" exe="/usr/sbin/runuser" hostname=? addr=? terminal=? res=success'
Oct 08 12:45:19 dom0 qmemman.daemon.algo[2606]: balance_when_enough_memory(xen_free_memory=15042261, total_mem_pref=5657196211.2, total_available_memory=8893549010.8)
Oct 08 12:45:19 dom0 qmemman.daemon.algo[2606]: left_memory=893164460 acceptors_count=8
Oct 08 12:45:19 dom0 qmemman.systemstate[2606]: stat: dom '13' act=1253371881 pref=439370547.2 last_target=1253371881
Oct 08 12:45:19 dom0 qmemman.systemstate[2606]: stat: dom '14' act=2140028478 pref=822479257.6 last_target=2140028478
Oct 08 12:45:19 dom0 qmemman.systemstate[2606]: stat: dom '0' act=4294967296 pref=2019117132.8 last_target=4294967296
Oct 08 12:45:19 dom0 qmemman.systemstate[2606]: stat: dom '10' act=970898067 pref=330228121.6 last_target=970898067
Oct 08 12:45:19 dom0 qmemman.systemstate[2606]: stat: dom '7' act=1114126361 pref=385568768.0 last_target=1114126361
Oct 08 12:45:19 dom0 qmemman.systemstate[2606]: stat: dom '11' act=1433602771 pref=509008281.6 last_target=1433602771
Oct 08 12:45:19 dom0 qmemman.systemstate[2606]: stat: dom '8' act=1116606982 pref=386527232.0 last_target=1116606982
Oct 08 12:45:19 dom0 qmemman.systemstate[2606]: stat: dom '3' act=1079507919 pref=372192870.40000004 last_target=1079507919
Oct 08 12:45:19 dom0 qmemman.systemstate[2606]: stat: dom '9' act=1132593206 pref=392704000.0 last_target=1132593206
Oct 08 12:45:19 dom0 qmemman.systemstate[2606]: stat: xenfree=67471061 memset_reqs=[('13', 1240498769), ('9', 1120588839), ('7', 1102254824), ('11', 1419433282), ('10', 960056751), ('8', 1104717602), ('3', 1067885387), ('14', 2224898552), ('0', 4294967296)]
Oct 08 12:45:19 dom0 qmemman.systemstate[2606]: mem-set domain 13 to 1240498769
Oct 08 12:45:19 dom0 qmemman.systemstate[2606]: mem-set domain 9 to 1120588839
Oct 08 12:45:19 dom0 qmemman.systemstate[2606]: mem-set domain 7 to 1102254824
Oct 08 12:45:19 dom0 qmemman.systemstate[2606]: mem-set domain 11 to 1419433282
Oct 08 12:45:19 dom0 qmemman.systemstate[2606]: mem-set domain 10 to 960056751
Oct 08 12:45:19 dom0 qmemman.systemstate[2606]: mem-set domain 8 to 1104717602
Oct 08 12:45:19 dom0 qubesd[2604]: Activating the sd-export-usb VM
Oct 08 12:45:19 dom0 qmemman.systemstate[2606]: mem-set domain 3 to 1067885387
Oct 08 12:45:19 dom0 qmemman.systemstate[2606]: mem-set domain 14 to 2224898552
Oct 08 12:45:19 dom0 qmemman.systemstate[2606]: mem-set domain 0 to 4294967296
Oct 08 12:45:19 dom0 kernel: audit: type=1103 audit(1570553119.373:253): pid=7819 uid=0 auid=4294967295 ses=4294967295 msg='op=PAM:setcred grantors=pam_rootok acct="creviera" exe="/usr/sbin/runuser" hostname=? addr=? terminal=? res=success'
Oct 08 12:45:19 dom0 kernel: audit: type=1105 audit(1570553119.374:254): pid=7819 uid=0 auid=4294967295 ses=4294967295 msg='op=PAM:session_open grantors=pam_keyinit,pam_limits,pam_unix acct="creviera" exe="/usr/sbin/runuser" hostname=? addr=? terminal=? res=success'
Oct 08 12:45:19 dom0 audit[7819]: CRED_ACQ pid=7819 uid=0 auid=4294967295 ses=4294967295 msg='op=PAM:setcred grantors=pam_rootok acct="creviera" exe="/usr/sbin/runuser" hostname=? addr=? terminal=? res=success'
Oct 08 12:45:19 dom0 audit[7819]: USER_START pid=7819 uid=0 auid=4294967295 ses=4294967295 msg='op=PAM:session_open grantors=pam_keyinit,pam_limits,pam_unix acct="creviera" exe="/usr/sbin/runuser" hostname=? addr=? terminal=? res=success'
Oct 08 12:45:19 dom0 runuser[7819]: pam_unix(runuser:session): session opened for user creviera by (uid=0)
Oct 08 12:45:19 dom0 qmemman.daemon.algo[2606]: balance_when_enough_memory(xen_free_memory=21434298, total_mem_pref=5657196211.2, total_available_memory=8899539388.8)
Oct 08 12:45:19 dom0 qmemman.daemon.algo[2606]: left_memory=895300355 acceptors_count=8
Oct 08 12:45:22 dom0 qmemman.daemon.algo[2606]: balance_when_enough_memory(xen_free_memory=15392167, total_mem_pref=5697622092.799999, total_available_memory=8853071376.199999)
Oct 08 12:45:22 dom0 qmemman.daemon.algo[2606]: left_memory=856335202 acceptors_count=8
Oct 08 12:45:22 dom0 qmemman.systemstate[2606]: stat: dom '13' act=1240498769 pref=439370547.2 last_target=1240498769
Oct 08 12:45:22 dom0 qmemman.systemstate[2606]: stat: dom '14' act=2224898552 pref=862905139.2 last_target=2224898552
Oct 08 12:45:22 dom0 qmemman.systemstate[2606]: stat: dom '0' act=4294967296 pref=2019117132.8 last_target=4294967296
Oct 08 12:45:22 dom0 qmemman.systemstate[2606]: stat: dom '10' act=960056751 pref=330228121.6 last_target=960056751
Oct 08 12:45:22 dom0 qmemman.systemstate[2606]: stat: dom '7' act=1102254824 pref=385568768.0 last_target=1102254824
Oct 08 12:45:22 dom0 qmemman.systemstate[2606]: stat: dom '11' act=1419433282 pref=509008281.6 last_target=1419433282
Oct 08 12:45:22 dom0 qmemman.systemstate[2606]: stat: dom '8' act=1104717602 pref=386527232.0 last_target=1104717602
Oct 08 12:45:22 dom0 qmemman.systemstate[2606]: stat: dom '3' act=1067885387 pref=372192870.40000004 last_target=1067885387
Oct 08 12:45:22 dom0 qmemman.systemstate[2606]: stat: dom '9' act=1120588839 pref=392704000.0 last_target=1120588839
Oct 08 12:45:22 dom0 qmemman.systemstate[2606]: stat: xenfree=67820967 memset_reqs=[('13', 1227885475), ('9', 1108826755), ('7', 1090622889), ('11', 1405549777), ('10', 949434245), ('8', 1093068185), ('3', 1056497432), ('14', 2308434378), ('0', 4294967296)]
Oct 08 12:45:22 dom0 qmemman.systemstate[2606]: mem-set domain 13 to 1227885475
Oct 08 12:45:22 dom0 qmemman.systemstate[2606]: mem-set domain 9 to 1108826755
Oct 08 12:45:22 dom0 qmemman.systemstate[2606]: mem-set domain 7 to 1090622889
Oct 08 12:45:22 dom0 qmemman.systemstate[2606]: mem-set domain 11 to 1405549777
Oct 08 12:45:22 dom0 qmemman.systemstate[2606]: mem-set domain 10 to 949434245
Oct 08 12:45:22 dom0 qmemman.systemstate[2606]: mem-set domain 8 to 1093068185
Oct 08 12:45:22 dom0 qmemman.systemstate[2606]: mem-set domain 3 to 1056497432
Oct 08 12:45:22 dom0 qmemman.systemstate[2606]: mem-set domain 14 to 2308434378
Oct 08 12:45:22 dom0 qmemman.systemstate[2606]: mem-set domain 0 to 4294967296
Oct 08 12:45:25 dom0 qmemman.daemon.algo[2606]: balance_when_enough_memory(xen_free_memory=24632712, total_mem_pref=5738852019.2, total_available_memory=8821067124.8)
Oct 08 12:45:25 dom0 qmemman.daemon.algo[2606]: left_memory=822569088 acceptors_count=8
Oct 08 12:45:25 dom0 qmemman.systemstate[2606]: stat: dom '13' act=1227885475 pref=439370547.2 last_target=1227885475
Oct 08 12:45:25 dom0 qmemman.systemstate[2606]: stat: dom '14' act=2308434378 pref=904135065.6 last_target=2308434378
Oct 08 12:45:25 dom0 qmemman.systemstate[2606]: stat: dom '0' act=4294967296 pref=2019117132.8 last_target=4294967296
Oct 08 12:45:25 dom0 qmemman.systemstate[2606]: stat: dom '10' act=949434245 pref=330228121.6 last_target=949434245
Oct 08 12:45:25 dom0 qmemman.systemstate[2606]: stat: dom '7' act=1090622889 pref=385568768.0 last_target=1090622889
Oct 08 12:45:25 dom0 qmemman.systemstate[2606]: stat: dom '11' act=1405549777 pref=509008281.6 last_target=1405549777
Oct 08 12:45:25 dom0 qmemman.systemstate[2606]: stat: dom '8' act=1093068185 pref=386527232.0 last_target=1093068185
Oct 08 12:45:25 dom0 qmemman.systemstate[2606]: stat: dom '3' act=1056497432 pref=372192870.40000004 last_target=1056497432
Oct 08 12:45:25 dom0 qmemman.systemstate[2606]: stat: dom '9' act=1108826755 pref=392704000.0 last_target=1108826755
Oct 08 12:45:25 dom0 qmemman.systemstate[2606]: stat: xenfree=77061512 memset_reqs=[('13', 1216321247), ('9', 1098042941), ('7', 1079958399), ('11', 1392820982), ('10', 939695228), ('8', 1082387666), ('3', 1046056630), ('14', 2394286257), ('0', 4294967296)]
Oct 08 12:45:25 dom0 qmemman.systemstate[2606]: mem-set domain 13 to 1216321247
Oct 08 12:45:25 dom0 qmemman.systemstate[2606]: mem-set domain 9 to 1098042941
Oct 08 12:45:25 dom0 qmemman.systemstate[2606]: mem-set domain 7 to 1079958399
Oct 08 12:45:25 dom0 qmemman.systemstate[2606]: mem-set domain 11 to 1392820982
Oct 08 12:45:25 dom0 qmemman.systemstate[2606]: mem-set domain 10 to 939695228
Oct 08 12:45:25 dom0 qmemman.systemstate[2606]: mem-set domain 8 to 1082387666
Oct 08 12:45:25 dom0 qmemman.systemstate[2606]: mem-set domain 3 to 1046056630
Oct 08 12:45:25 dom0 qmemman.systemstate[2606]: mem-set domain 14 to 2394286257
Oct 08 12:45:25 dom0 qmemman.systemstate[2606]: mem-set domain 0 to 4294967296
Oct 08 12:45:27 dom0 qmemman.daemon.algo[2606]: balance_when_enough_memory(xen_free_memory=15451154, total_mem_pref=5783074483.2, total_available_memory=8776913316.8)
Oct 08 12:45:27 dom0 qmemman.daemon.algo[2606]: left_memory=783459859 acceptors_count=8
Oct 08 12:45:27 dom0 qmemman.systemstate[2606]: stat: dom '13' act=1216321247 pref=439370547.2 last_target=1216321247
Oct 08 12:45:27 dom0 qmemman.systemstate[2606]: stat: dom '14' act=2394286257 pref=948357529.6 last_target=2394286257
Oct 08 12:45:27 dom0 qmemman.systemstate[2606]: stat: dom '0' act=4294967296 pref=2019117132.8 last_target=4294967296
Oct 08 12:45:27 dom0 qmemman.systemstate[2606]: stat: dom '10' act=939695228 pref=330228121.6 last_target=939695228
Oct 08 12:45:27 dom0 qmemman.systemstate[2606]: stat: dom '7' act=1079958399 pref=385568768.0 last_target=1079958399
Oct 08 12:45:27 dom0 qmemman.systemstate[2606]: stat: dom '11' act=1392820982 pref=509008281.6 last_target=1392820982
Oct 08 12:45:27 dom0 qmemman.systemstate[2606]: stat: dom '8' act=1082387666 pref=386527232.0 last_target=1082387666
Oct 08 12:45:27 dom0 qmemman.systemstate[2606]: stat: dom '3' act=1046056630 pref=372192870.40000004 last_target=1046056630
Oct 08 12:45:27 dom0 qmemman.systemstate[2606]: stat: dom '9' act=1098042941 pref=392704000.0 last_target=1098042941
Oct 08 12:45:27 dom0 qmemman.systemstate[2606]: stat: xenfree=67879954 memset_reqs=[('13', 1202927107), ('9', 1085552708), ('7', 1067606371), ('11', 1378077996), ('10', 928415119), ('8', 1070017073), ('3', 1033963686), ('14', 2483116984), ('0', 4294967296)]
Oct 08 12:45:27 dom0 qmemman.systemstate[2606]: mem-set domain 13 to 1202927107
Oct 08 12:45:27 dom0 qmemman.systemstate[2606]: mem-set domain 9 to 1085552708
Oct 08 12:45:27 dom0 qmemman.systemstate[2606]: mem-set domain 7 to 1067606371
Oct 08 12:45:27 dom0 qmemman.systemstate[2606]: mem-set domain 11 to 1378077996
Oct 08 12:45:27 dom0 qmemman.systemstate[2606]: mem-set domain 10 to 928415119
Oct 08 12:45:27 dom0 qmemman.systemstate[2606]: mem-set domain 8 to 1070017073
Oct 08 12:45:27 dom0 qmemman.systemstate[2606]: mem-set domain 3 to 1033963686
Oct 08 12:45:27 dom0 qmemman.systemstate[2606]: mem-set domain 14 to 2483116984
Oct 08 12:45:27 dom0 qmemman.systemstate[2606]: mem-set domain 0 to 4294967296
Oct 08 12:45:27 dom0 qmemman.daemon.algo[2606]: balance_when_enough_memory(xen_free_memory=15348382, total_mem_pref=5700619955.2, total_available_memory=8859372766.8)
Oct 08 12:45:27 dom0 qmemman.daemon.algo[2606]: left_memory=856916649 acceptors_count=8
Oct 08 12:45:27 dom0 qmemman.systemstate[2606]: stat: dom '13' act=1202927107 pref=439370547.2 last_target=1202927107
Oct 08 12:45:27 dom0 qmemman.systemstate[2606]: stat: dom '14' act=2483116984 pref=865903001.6 last_target=2483116984
Oct 08 12:45:27 dom0 qmemman.systemstate[2606]: stat: dom '0' act=4294967296 pref=2019117132.8 last_target=4294967296
Oct 08 12:45:27 dom0 qmemman.systemstate[2606]: stat: dom '10' act=928415119 pref=330228121.6 last_target=928415119
Oct 08 12:45:27 dom0 qmemman.systemstate[2606]: stat: dom '7' act=1067606371 pref=385568768.0 last_target=1067606371
Oct 08 12:45:27 dom0 qmemman.systemstate[2606]: stat: dom '11' act=1378077996 pref=509008281.6 last_target=1378077996
Oct 08 12:45:27 dom0 qmemman.systemstate[2606]: stat: dom '8' act=1070017073 pref=386527232.0 last_target=1070017073
Oct 08 12:45:27 dom0 qmemman.systemstate[2606]: stat: dom '3' act=1033963686 pref=372192870.40000004 last_target=1033963686
Oct 08 12:45:27 dom0 qmemman.systemstate[2606]: stat: dom '9' act=1085552708 pref=392704000.0 last_target=1085552708
Oct 08 12:45:27 dom0 qmemman.systemstate[2606]: stat: xenfree=67777182 memset_reqs=[('14', 2316404682), ('13', 1228084609), ('0', 4294967296), ('9', 1109012451), ('7', 1090806530), ('11', 1405768965), ('10', 949601949), ('8', 1093252101), ('3', 1056677221)]
Oct 08 12:45:27 dom0 qmemman.systemstate[2606]: mem-set domain 14 to 2316404682
Oct 08 12:45:27 dom0 qmemman.systemstate[2606]: mem-set domain 13 to 1228084609
Oct 08 12:45:27 dom0 qmemman.systemstate[2606]: mem-set domain 0 to 4294967296
Oct 08 12:45:27 dom0 qmemman.systemstate[2606]: mem-set domain 9 to 1109012451
Oct 08 12:45:27 dom0 qmemman.systemstate[2606]: mem-set domain 7 to 1090806530
Oct 08 12:45:27 dom0 qmemman.systemstate[2606]: mem-set domain 11 to 1405768965
Oct 08 12:45:27 dom0 qmemman.systemstate[2606]: mem-set domain 10 to 949601949
Oct 08 12:45:27 dom0 qmemman.systemstate[2606]: mem-set domain 8 to 1093252101
Oct 08 12:45:27 dom0 qmemman.systemstate[2606]: mem-set domain 3 to 1056677221
Oct 08 12:45:36 dom0 kernel: xen-blkback: backend/vbd/17/51712: using 2 queues, protocol 1 (x86_64-abi) persistent grants
Oct 08 12:45:36 dom0 kernel: xen-blkback: backend/vbd/17/51728: using 2 queues, protocol 1 (x86_64-abi) persistent grants
Oct 08 12:45:36 dom0 kernel: xen-blkback: backend/vbd/17/51744: using 2 queues, protocol 1 (x86_64-abi) persistent grants
Oct 08 12:45:39 dom0 runuser[7819]: pam_unix(runuser:session): session closed for user creviera
Oct 08 12:45:39 dom0 audit[7819]: USER_END pid=7819 uid=0 auid=4294967295 ses=4294967295 msg='op=PAM:session_close grantors=pam_keyinit,pam_limits,pam_unix acct="creviera" exe="/usr/sbin/runuser" hostname=? addr=? terminal=? res=success'
Oct 08 12:45:39 dom0 audit[7819]: CRED_DISP pid=7819 uid=0 auid=4294967295 ses=4294967295 msg='op=PAM:setcred grantors=pam_rootok acct="creviera" exe="/usr/sbin/runuser" hostname=? addr=? terminal=? res=success'
Oct 08 12:45:39 dom0 kernel: audit: type=1106 audit(1570553139.146:255): pid=7819 uid=0 auid=4294967295 ses=4294967295 msg='op=PAM:session_close grantors=pam_keyinit,pam_limits,pam_unix acct="creviera" exe="/usr/sbin/runuser" hostname=? addr=? terminal=? res=success'
Oct 08 12:45:39 dom0 kernel: audit: type=1104 audit(1570553139.146:256): pid=7819 uid=0 auid=4294967295 ses=4294967295 msg='op=PAM:setcred grantors=pam_rootok acct="creviera" exe="/usr/sbin/runuser" hostname=? addr=? terminal=? res=success'
Oct 08 12:45:39 dom0 qmemman.daemon.algo[2606]: balance_when_enough_memory(xen_free_memory=33091269, total_mem_pref=5980395596.8, total_available_memory=8998917044.199999)
Oct 08 12:45:39 dom0 qmemman.daemon.algo[2606]: left_memory=757330886 acceptors_count=9
Oct 08 12:45:39 dom0 qmemman.systemstate[2606]: stat: dom '13' act=1228084609 pref=439370547.2 last_target=1228084609
Oct 08 12:45:39 dom0 qmemman.systemstate[2606]: stat: dom '14' act=2316404682 pref=865903001.6 last_target=2316404682
Oct 08 12:45:39 dom0 qmemman.systemstate[2606]: stat: dom '0' act=4294967296 pref=2019117132.8 last_target=4294967296
Oct 08 12:45:39 dom0 qmemman.systemstate[2606]: stat: dom '10' act=949601949 pref=330228121.6 last_target=949601949
Oct 08 12:45:39 dom0 qmemman.systemstate[2606]: stat: dom '7' act=1090806530 pref=385568768.0 last_target=1090806530
Oct 08 12:45:39 dom0 qmemman.systemstate[2606]: stat: dom '11' act=1405768965 pref=509008281.6 last_target=1405768965
Oct 08 12:45:39 dom0 qmemman.systemstate[2606]: stat: dom '8' act=1093252101 pref=386527232.0 last_target=1093252101
Oct 08 12:45:39 dom0 qmemman.systemstate[2606]: stat: dom '3' act=1056677221 pref=372192870.40000004 last_target=1056677221
Oct 08 12:45:39 dom0 qmemman.systemstate[2606]: stat: dom '17' act=401645568 pref=279775641.6 last_target=401604608
Oct 08 12:45:39 dom0 qmemman.systemstate[2606]: stat: dom '9' act=1109012451 pref=392704000.0 last_target=1109012451
Oct 08 12:45:39 dom0 qmemman.systemstate[2606]: stat: xenfree=85520069 memset_reqs=[('13', 1183470484), ('14', 2250753361), ('9', 1066699986), ('7', 1048845985), ('11', 1357720205), ('10', 910370885), ('8', 1051244284), ('3', 1015376395), ('0', 4294967296), ('17', 784127108)]
Oct 08 12:45:39 dom0 qmemman.systemstate[2606]: mem-set domain 13 to 1183470484
Oct 08 12:45:39 dom0 qmemman.systemstate[2606]: mem-set domain 14 to 2250753361
Oct 08 12:45:39 dom0 qmemman.systemstate[2606]: mem-set domain 9 to 1066699986
Oct 08 12:45:39 dom0 qmemman.systemstate[2606]: mem-set domain 7 to 1048845985
Oct 08 12:45:39 dom0 qmemman.systemstate[2606]: mem-set domain 11 to 1357720205
Oct 08 12:45:39 dom0 qmemman.systemstate[2606]: mem-set domain 10 to 910370885
Oct 08 12:45:39 dom0 qmemman.systemstate[2606]: mem-set domain 8 to 1051244284
Oct 08 12:45:39 dom0 qmemman.systemstate[2606]: mem-set domain 3 to 1015376395
Oct 08 12:45:39 dom0 qmemman.systemstate[2606]: mem-set domain 0 to 4294967296
Oct 08 12:45:39 dom0 qrexec[7836]: qubes.GetDate: sd-export-usb -> @default: allowed to dom0
Oct 08 12:45:39 dom0 qmemman.systemstate[2606]: mem-set domain 17 to 784127108
Oct 08 12:45:40 dom0 qrexec[7858]: qubes.USB+4-2: sd-export-usb -> sys-usb: allowed to sys-usb
Oct 08 12:45:40 dom0 qmemman.daemon.algo[2606]: balance_when_enough_memory(xen_free_memory=15736238, total_mem_pref=6021359283.2, total_available_memory=8957952943.8)
Oct 08 12:45:40 dom0 qmemman.daemon.algo[2606]: left_memory=722959645 acceptors_count=9
Oct 08 12:45:40 dom0 qmemman.systemstate[2606]: stat: dom '13' act=1183470484 pref=439370547.2 last_target=1183470484
Oct 08 12:45:40 dom0 qmemman.systemstate[2606]: stat: dom '14' act=2250753361 pref=865903001.6 last_target=2250753361
Oct 08 12:45:40 dom0 qmemman.systemstate[2606]: stat: dom '0' act=4294967296 pref=2019117132.8 last_target=4294967296
Oct 08 12:45:40 dom0 qmemman.systemstate[2606]: stat: dom '10' act=910370885 pref=330228121.6 last_target=910370885
Oct 08 12:45:40 dom0 qmemman.systemstate[2606]: stat: dom '7' act=1048845985 pref=385568768.0 last_target=1048845985
Oct 08 12:45:40 dom0 qmemman.systemstate[2606]: stat: dom '11' act=1357720205 pref=509008281.6 last_target=1357720205
Oct 08 12:45:40 dom0 qmemman.systemstate[2606]: stat: dom '8' act=1051244284 pref=386527232.0 last_target=1051244284
Oct 08 12:45:40 dom0 qmemman.systemstate[2606]: stat: dom '3' act=1015376395 pref=372192870.40000004 last_target=1015376395
Oct 08 12:45:40 dom0 qmemman.systemstate[2606]: stat: dom '17' act=784127108 pref=320739328.0 last_target=784127108
Oct 08 12:45:40 dom0 qmemman.systemstate[2606]: stat: dom '9' act=1066699986 pref=392704000.0 last_target=1066699986
Oct 08 12:45:40 dom0 qmemman.systemstate[2606]: stat: xenfree=68165038 memset_reqs=[('13', 1172175912), ('14', 2232197968), ('9', 1056199815), ('7', 1038467276), ('11', 1345240197), ('10', 900934235), ('8', 1040849259), ('3', 1005225383), ('0', 4294967296), ('17', 877352605)]
Oct 08 12:45:40 dom0 qmemman.systemstate[2606]: mem-set domain 13 to 1172175912
Oct 08 12:45:40 dom0 qmemman.systemstate[2606]: mem-set domain 14 to 2232197968
Oct 08 12:45:40 dom0 qmemman.systemstate[2606]: mem-set domain 9 to 1056199815
Oct 08 12:45:40 dom0 qmemman.systemstate[2606]: mem-set domain 7 to 1038467276
Oct 08 12:45:40 dom0 qmemman.systemstate[2606]: mem-set domain 11 to 1345240197
Oct 08 12:45:40 dom0 qmemman.systemstate[2606]: mem-set domain 10 to 900934235
Oct 08 12:45:40 dom0 qmemman.systemstate[2606]: mem-set domain 8 to 1040849259
Oct 08 12:45:40 dom0 qmemman.systemstate[2606]: mem-set domain 3 to 1005225383
Oct 08 12:45:40 dom0 qmemman.systemstate[2606]: mem-set domain 0 to 4294967296
Oct 08 12:45:40 dom0 qmemman.systemstate[2606]: mem-set domain 17 to 877352605
Oct 08 12:45:41 dom0 qubesd[2604]: Start failed: list.remove(x): x not in list
Oct 08 12:45:41 dom0 qmemman.daemon.algo[2606]: balance_when_enough_memory(xen_free_memory=165727544, total_mem_pref=6021359283.2, total_available_memory=9107978206.8)
Oct 08 12:45:41 dom0 qmemman.daemon.algo[2606]: left_memory=773216679 acceptors_count=9
Oct 08 12:45:41 dom0 qmemman.systemstate[2606]: stat: dom '13' act=1172175912 pref=439370547.2 last_target=1172175912
Oct 08 12:45:41 dom0 qmemman.systemstate[2606]: stat: dom '14' act=2232197968 pref=865903001.6 last_target=2232197968
Oct 08 12:45:41 dom0 qmemman.systemstate[2606]: stat: dom '0' act=4294967296 pref=2019117132.8 last_target=4294967296
Oct 08 12:45:41 dom0 qmemman.systemstate[2606]: stat: dom '10' act=900934235 pref=330228121.6 last_target=900934235
Oct 08 12:45:41 dom0 qmemman.systemstate[2606]: stat: dom '7' act=1038467276 pref=385568768.0 last_target=1038467276
Oct 08 12:45:41 dom0 qmemman.systemstate[2606]: stat: dom '11' act=1345240197 pref=509008281.6 last_target=1345240197
Oct 08 12:45:41 dom0 qmemman.systemstate[2606]: stat: dom '8' act=1040849259 pref=386527232.0 last_target=1040849259
Oct 08 12:45:41 dom0 qmemman.systemstate[2606]: stat: dom '3' act=1005225383 pref=372192870.40000004 last_target=1005225383
Oct 08 12:45:41 dom0 qmemman.systemstate[2606]: stat: dom '17' act=877352605 pref=320739328.0 last_target=877352605
Oct 08 12:45:41 dom0 qmemman.systemstate[2606]: stat: dom '9' act=1056199815 pref=392704000.0 last_target=1056199815
Oct 08 12:45:41 dom0 qmemman.systemstate[2606]: stat: xenfree=218156344 memset_reqs=[('13', 1188690639), ('14', 2259329343), ('0', 4294967296), ('9', 1071552984), ('17', 890914530), ('7', 1053642845), ('11', 1363488249), ('10', 914732341), ('8', 1056048684), ('3', 1020068017)]
Oct 08 12:45:41 dom0 qmemman.systemstate[2606]: mem-set domain 13 to 1188690639
Oct 08 12:45:41 dom0 qmemman.systemstate[2606]: mem-set domain 14 to 2259329343
Oct 08 12:45:41 dom0 qmemman.systemstate[2606]: mem-set domain 0 to 4294967296
Oct 08 12:45:41 dom0 qmemman.systemstate[2606]: mem-set domain 9 to 1071552984
Oct 08 12:45:41 dom0 qmemman.systemstate[2606]: mem-set domain 17 to 890914530
Oct 08 12:45:41 dom0 qmemman.systemstate[2606]: mem-set domain 7 to 1053642845
Oct 08 12:45:41 dom0 qmemman.systemstate[2606]: mem-set domain 11 to 1363488249
Oct 08 12:45:41 dom0 qmemman.systemstate[2606]: mem-set domain 10 to 914732341
Oct 08 12:45:41 dom0 qmemman.systemstate[2606]: mem-set domain 8 to 1056048684
Oct 08 12:45:41 dom0 qmemman.systemstate[2606]: mem-set domain 3 to 1020068017
Oct 08 12:45:41 dom0 qmemman.daemon.algo[2606]: balance_when_enough_memory(xen_free_memory=927273439, total_mem_pref=5700619955.2, total_available_memory=9449173881.8)
Oct 08 12:45:41 dom0 qmemman.daemon.algo[2606]: left_memory=1065610908 acceptors_count=8
Oct 08 12:45:41 dom0 qmemman.systemstate[2606]: stat: dom '13' act=1188690639 pref=439370547.2 last_target=1188690639
Oct 08 12:45:41 dom0 qmemman.systemstate[2606]: stat: dom '14' act=2259329343 pref=865903001.6 last_target=2259329343
Oct 08 12:45:41 dom0 qmemman.systemstate[2606]: stat: dom '0' act=4294967296 pref=2019117132.8 last_target=4294967296
Oct 08 12:45:41 dom0 qmemman.systemstate[2606]: stat: dom '10' act=914732341 pref=330228121.6 last_target=914732341
Oct 08 12:45:41 dom0 qmemman.systemstate[2606]: stat: dom '7' act=1053642845 pref=385568768.0 last_target=1053642845
Oct 08 12:45:41 dom0 qmemman.systemstate[2606]: stat: dom '11' act=1363488249 pref=509008281.6 last_target=1363488249
Oct 08 12:45:41 dom0 qmemman.systemstate[2606]: stat: dom '8' act=1056048684 pref=386527232.0 last_target=1056048684
Oct 08 12:45:41 dom0 qmemman.systemstate[2606]: stat: dom '3' act=1020068017 pref=372192870.40000004 last_target=1020068017
Oct 08 12:45:41 dom0 qmemman.systemstate[2606]: stat: dom '9' act=1071552984 pref=392704000.0 last_target=1071552984
Oct 08 12:45:41 dom0 qmemman.systemstate[2606]: stat: xenfree=979702239 memset_reqs=[('13', 1299558278), ('14', 2431964388), ('0', 4294967296), ('9', 1175662704), ('7', 1156719291), ('11', 1484440331), ('10', 1009794748), ('8', 1159263929), ('3', 1121207461)]
Oct 08 12:45:41 dom0 qmemman.systemstate[2606]: mem-set domain 13 to 1299558278
Oct 08 12:45:41 dom0 qmemman.systemstate[2606]: mem-set domain 14 to 2431964388
Oct 08 12:45:41 dom0 qmemman.systemstate[2606]: mem-set domain 0 to 4294967296
Oct 08 12:45:41 dom0 qmemman.systemstate[2606]: mem-set domain 9 to 1175662704
Oct 08 12:45:41 dom0 qmemman.systemstate[2606]: mem-set domain 7 to 1156719291
Oct 08 12:45:41 dom0 qmemman.systemstate[2606]: mem-set domain 11 to 1484440331
Oct 08 12:45:41 dom0 qmemman.systemstate[2606]: mem-set domain 10 to 1009794748
Oct 08 12:45:42 dom0 qmemman.systemstate[2606]: mem-set domain 8 to 1159263929
Oct 08 12:45:42 dom0 qmemman.systemstate[2606]: mem-set domain 3 to 1121207461
Oct 08 12:45:42 dom0 qmemman.daemon.algo[2606]: balance_when_enough_memory(xen_free_memory=23992394, total_mem_pref=5741674163.2, total_available_memory=9415896656.8)
Oct 08 12:45:42 dom0 qmemman.daemon.algo[2606]: left_memory=1030013738 acceptors_count=8
Oct 08 12:45:42 dom0 qmemman.systemstate[2606]: stat: dom '13' act=1299558278 pref=439370547.2 last_target=1299558278
Oct 08 12:45:42 dom0 qmemman.systemstate[2606]: stat: dom '14' act=2431964388 pref=906957209.6 last_target=2431964388
Oct 08 12:45:42 dom0 qmemman.systemstate[2606]: stat: dom '0' act=4294967296 pref=2019117132.8 last_target=4294967296
Oct 08 12:45:42 dom0 qmemman.systemstate[2606]: stat: dom '10' act=1009794748 pref=330228121.6 last_target=1009794748
Oct 08 12:45:42 dom0 qmemman.systemstate[2606]: stat: dom '7' act=1156719291 pref=385568768.0 last_target=1156719291
Oct 08 12:45:42 dom0 qmemman.systemstate[2606]: stat: dom '11' act=1484440331 pref=509008281.6 last_target=1484440331
Oct 08 12:45:42 dom0 qmemman.systemstate[2606]: stat: dom '8' act=1159263929 pref=386527232.0 last_target=1159263929
Oct 08 12:45:42 dom0 qmemman.systemstate[2606]: stat: dom '3' act=1121207461 pref=372192870.40000004 last_target=1121207461
Oct 08 12:45:42 dom0 qmemman.systemstate[2606]: stat: dom '9' act=1175662704 pref=392704000.0 last_target=1175662704
Oct 08 12:45:42 dom0 qmemman.systemstate[2606]: stat: xenfree=76421194 memset_reqs=[('13', 1287366949), ('9', 1164294109), ('7', 1145476491), ('11', 1471021284), ('10', 999527607), ('8', 1148004231), ('3', 1110200479), ('14', 2520524782), ('0', 4294967296)]
Oct 08 12:45:42 dom0 qmemman.systemstate[2606]: mem-set domain 13 to 1287366949
Oct 08 12:45:42 dom0 qmemman.systemstate[2606]: mem-set domain 9 to 1164294109
Oct 08 12:45:42 dom0 qmemman.systemstate[2606]: mem-set domain 7 to 1145476491
Oct 08 12:45:42 dom0 qmemman.systemstate[2606]: mem-set domain 11 to 1471021284
Oct 08 12:45:42 dom0 qmemman.systemstate[2606]: mem-set domain 10 to 999527607
Oct 08 12:45:42 dom0 qmemman.systemstate[2606]: mem-set domain 8 to 1148004231
Oct 08 12:45:42 dom0 qmemman.systemstate[2606]: mem-set domain 3 to 1110200479
Oct 08 12:45:42 dom0 qmemman.systemstate[2606]: mem-set domain 14 to 2520524782
Oct 08 12:45:42 dom0 qmemman.systemstate[2606]: mem-set domain 0 to 4294967296
Oct 08 12:45:43 dom0 qubesd[2604]: Starting sd-export-usb
Oct 08 12:45:43 dom0 qmemman.systemstate[2606]: do_balloon(memsize=577765376)
Oct 08 12:45:43 dom0 qubesd[2604]: unhandled exception while calling src=b'dom0' meth=b'admin.vm.Start' dest=b'sd-export-usb' arg=b'' len(untrusted_payload)=0
Oct 08 12:45:43 dom0 qubesd[2604]: Traceback (most recent call last):
Oct 08 12:45:43 dom0 qubesd[2604]:   File "/usr/lib/python3.5/site-packages/qubes/api/__init__.py", line 266, in respond
Oct 08 12:45:43 dom0 qubesd[2604]:     untrusted_payload=untrusted_payload)
Oct 08 12:45:43 dom0 qubesd[2604]:   File "/usr/lib64/python3.5/asyncio/futures.py", line 381, in __iter__
Oct 08 12:45:43 dom0 qubesd[2604]:     yield self  # This tells Task to wait for completion.
Oct 08 12:45:43 dom0 qubesd[2604]:   File "/usr/lib64/python3.5/asyncio/tasks.py", line 310, in _wakeup
Oct 08 12:45:43 dom0 qubesd[2604]:     future.result()
Oct 08 12:45:43 dom0 qubesd[2604]:   File "/usr/lib64/python3.5/asyncio/futures.py", line 294, in result
Oct 08 12:45:43 dom0 qubesd[2604]:     raise self._exception
Oct 08 12:45:43 dom0 qubesd[2604]:   File "/usr/lib64/python3.5/asyncio/tasks.py", line 240, in _step
Oct 08 12:45:43 dom0 qubesd[2604]:     result = coro.send(None)
Oct 08 12:45:43 dom0 qubesd[2604]:   File "/usr/lib/python3.5/site-packages/qubes/api/admin.py", line 834, in vm_start
Oct 08 12:45:43 dom0 qubesd[2604]:     yield from self.dest.start()
Oct 08 12:45:43 dom0 qubesd[2604]:   File "/usr/lib/python3.5/site-packages/qubes/vm/dispvm.py", line 220, in start
Oct 08 12:45:43 dom0 qubesd[2604]:     yield from super(DispVM, self).start(**kwargs)
Oct 08 12:45:43 dom0 qubesd[2604]:   File "/usr/lib/python3.5/site-packages/qubes/vm/qubesvm.py", line 1071, in start
Oct 08 12:45:43 dom0 qubesd[2604]:     start_guid=start_guid)
Oct 08 12:45:43 dom0 qubesd[2604]:   File "/usr/lib/python3.5/site-packages/qubes/events.py", line 236, in fire_event_async
Oct 08 12:45:43 dom0 qubesd[2604]:     effect = task.result()
Oct 08 12:45:43 dom0 qubesd[2604]:   File "/usr/lib64/python3.5/asyncio/futures.py", line 294, in result
Oct 08 12:45:43 dom0 qubesd[2604]:     raise self._exception
Oct 08 12:45:43 dom0 qubesd[2604]:   File "/usr/lib64/python3.5/asyncio/tasks.py", line 240, in _step
Oct 08 12:45:43 dom0 qubesd[2604]:     result = coro.send(None)
Oct 08 12:45:43 dom0 qubesd[2604]:   File "/usr/lib/python3.5/site-packages/qubesusbproxy/core3ext.py", line 323, in on_domain_start
Oct 08 12:45:43 dom0 qubesd[2604]:     yield from self.on_device_attach_usb(vm, '', device, options={})
Oct 08 12:45:43 dom0 qubesd[2604]:   File "/usr/lib/python3.5/site-packages/qubesusbproxy/core3ext.py", line 286, in on_device_attach_usb
Oct 08 12:45:43 dom0 qubesd[2604]:     device.backend_domain.name))
Oct 08 12:45:43 dom0 qubesd[2604]: ValueError: list.remove(x): x not in list
Oct 08 12:45:43 dom0 qmemman.systemstate[2606]: xenfree=68689180
Oct 08 12:45:43 dom0 qmemman.daemon.algo[2606]: balloon: dom 13 has actual memory 1287366949
Oct 08 12:45:43 dom0 qmemman.daemon.algo[2606]: balloon: dom 14 has actual memory 2520524782
Oct 08 12:45:43 dom0 qmemman.daemon.algo[2606]: balloon: dom 0 has actual memory 4294967296
Oct 08 12:45:43 dom0 qmemman.daemon.algo[2606]: balloon: dom 10 has actual memory 999527607
Oct 08 12:45:43 dom0 qmemman.daemon.algo[2606]: balloon: dom 7 has actual memory 1145476491
Oct 08 12:45:43 dom0 qmemman.daemon.algo[2606]: balloon: dom 11 has actual memory 1471021284
Oct 08 12:45:43 dom0 qmemman.daemon.algo[2606]: balloon: dom 8 has actual memory 1148004231
Oct 08 12:45:43 dom0 qmemman.daemon.algo[2606]: balloon: dom 3 has actual memory 1110200479
Oct 08 12:45:43 dom0 qmemman.daemon.algo[2606]: balloon: dom 9 has actual memory 1164294109
Oct 08 12:45:43 dom0 qmemman.daemon.algo[2606]: req=561504996 avail=9399709064.8 donors=[('13', 847996401.8), ('14', 1613567572.4), ('0', 2275850163.2), ('10', 669299485.4), ('7', 759907723.0), ('11', 962013002.4), ('8', 761476999.0), ('3', 738007608.5999999), ('9', 771590109.0)]
Oct 08 12:45:43 dom0 qmemman.daemon.algo[2606]: borrow 53189085.27531085 from 13
Oct 08 12:45:43 dom0 qmemman.daemon.algo[2606]: borrow 101208192.6570504 from 14
Oct 08 12:45:43 dom0 qmemman.daemon.algo[2606]: borrow 142748705.23899305 from 0
Oct 08 12:45:43 dom0 qmemman.daemon.algo[2606]: borrow 41980634.97450829 from 10
Oct 08 12:45:43 dom0 qmemman.daemon.algo[2606]: borrow 47663877.575682305 from 7
Oct 08 12:45:43 dom0 qmemman.daemon.algo[2606]: borrow 60340576.34206747 from 11
Oct 08 12:45:43 dom0 qmemman.daemon.algo[2606]: borrow 47762307.65723374 from 8
Oct 08 12:45:43 dom0 qmemman.daemon.algo[2606]: borrow 46290231.35514633 from 3
Oct 08 12:45:43 dom0 qmemman.daemon.algo[2606]: borrow 48396634.72400762 from 9
Oct 08 12:45:43 dom0 qmemman.systemstate[2606]: memset_reqs=[('13', 1234177863), ('14', 2419316589), ('0', 4152218590), ('10', 957546972), ('7', 1097812613), ('11', 1410680707), ('8', 1100241923), ('3', 1063910247), ('9', 1115897474)]
Oct 08 12:45:43 dom0 qmemman.systemstate[2606]: mem-set domain 13 to 1234177863
Oct 08 12:45:43 dom0 qmemman.systemstate[2606]: mem-set domain 14 to 2419316589
Oct 08 12:45:43 dom0 qmemman.systemstate[2606]: mem-set domain 0 to 4152218590
Oct 08 12:45:43 dom0 qmemman.systemstate[2606]: mem-set domain 10 to 957546972
Oct 08 12:45:43 dom0 qmemman.systemstate[2606]: mem-set domain 7 to 1097812613
Oct 08 12:45:43 dom0 qmemman.systemstate[2606]: mem-set domain 11 to 1410680707
Oct 08 12:45:43 dom0 qmemman.systemstate[2606]: mem-set domain 8 to 1100241923
Oct 08 12:45:43 dom0 qmemman.systemstate[2606]: mem-set domain 3 to 1063910247
Oct 08 12:45:43 dom0 qmemman.systemstate[2606]: mem-set domain 9 to 1115897474
Oct 08 12:45:43 dom0 qmemman.systemstate[2606]: xenfree=502620337
Oct 08 12:45:43 dom0 qmemman.daemon.algo[2606]: balloon: dom 13 has actual memory 1245925376
Oct 08 12:45:43 dom0 qmemman.daemon.algo[2606]: balloon: dom 14 has actual memory 2499485696
Oct 08 12:45:43 dom0 qmemman.daemon.algo[2606]: balloon: dom 0 has actual memory 4208447488
Oct 08 12:45:43 dom0 qmemman.daemon.algo[2606]: balloon: dom 10 has actual memory 958197760
Oct 08 12:45:43 dom0 qmemman.daemon.algo[2606]: balloon: dom 7 has actual memory 1111855104
Oct 08 12:45:43 dom0 qmemman.daemon.algo[2606]: balloon: dom 11 has actual memory 1419063296
Oct 08 12:45:43 dom0 qmemman.daemon.algo[2606]: balloon: dom 8 has actual memory 1100241923
Oct 08 12:45:43 dom0 qmemman.daemon.algo[2606]: balloon: dom 3 has actual memory 1072492544
Oct 08 12:45:43 dom0 qmemman.daemon.algo[2606]: balloon: dom 9 has actual memory 1115897474
Oct 08 12:45:43 dom0 qmemman.daemon.algo[2606]: req=127573839 avail=8989932497.8 donors=[('13', 806554828.8), ('14', 1592528486.4), ('0', 2189330355.2), ('10', 627969638.4), ('7', 726286336.0), ('11', 910055014.4), ('8', 713714691.0), ('3', 700299673.5999999), ('9', 723193474.0)]
Oct 08 12:45:43 dom0 qmemman.daemon.algo[2606]: borrow 12017894.53860119 from 13
Oct 08 12:45:43 dom0 qmemman.daemon.algo[2606]: borrow 23729123.818833657 from 14
Oct 08 12:45:43 dom0 qmemman.daemon.algo[2606]: borrow 32621640.06642654 from 0
Oct 08 12:45:43 dom0 qmemman.daemon.algo[2606]: borrow 9356924.809393346 from 10
Oct 08 12:45:43 dom0 qmemman.daemon.algo[2606]: borrow 10821871.346131936 from 7
Oct 08 12:45:43 dom0 qmemman.daemon.algo[2606]: borrow 13560076.509189684 from 11
Oct 08 12:45:43 dom0 qmemman.daemon.algo[2606]: borrow 10634550.288229996 from 8
Oct 08 12:45:43 dom0 qmemman.daemon.algo[2606]: borrow 10434662.743589584 from 3
Oct 08 12:45:43 dom0 qmemman.daemon.algo[2606]: borrow 10775786.829604091 from 9
Oct 08 12:45:43 dom0 qmemman.systemstate[2606]: memset_reqs=[('13', 1233907481), ('14', 2475756572), ('0', 4175825847), ('10', 948840835), ('7', 1101033232), ('11', 1405503219), ('8', 1089607372), ('3', 1062057881), ('9', 1105121687)]
Oct 08 12:45:43 dom0 qmemman.systemstate[2606]: mem-set domain 13 to 1233907481
Oct 08 12:45:43 dom0 qmemman.systemstate[2606]: mem-set domain 14 to 2475756572
Oct 08 12:45:43 dom0 qmemman.systemstate[2606]: mem-set domain 0 to 4175825847
Oct 08 12:45:43 dom0 qmemman.systemstate[2606]: mem-set domain 10 to 948840835
Oct 08 12:45:43 dom0 qmemman.systemstate[2606]: mem-set domain 7 to 1101033232
Oct 08 12:45:43 dom0 qmemman.systemstate[2606]: mem-set domain 11 to 1405503219
Oct 08 12:45:43 dom0 qmemman.systemstate[2606]: mem-set domain 8 to 1089607372
Oct 08 12:45:43 dom0 qmemman.systemstate[2606]: mem-set domain 3 to 1062057881
Oct 08 12:45:43 dom0 qmemman.systemstate[2606]: mem-set domain 9 to 1105121687
Oct 08 12:45:43 dom0 kernel: xen-blkback: Scheduled work from previous purge is still busy, cannot purge list
Oct 08 12:45:43 dom0 qmemman.systemstate[2606]: xenfree=595006824
Oct 08 12:45:43 dom0 qmemman.daemon.algo[2606]: balloon: dom 13 has actual memory 1233907481
Oct 08 12:45:43 dom0 qmemman.daemon.algo[2606]: balloon: dom 14 has actual memory 2475756572
Oct 08 12:45:43 dom0 qmemman.daemon.algo[2606]: balloon: dom 0 has actual memory 4189573120
Oct 08 12:45:43 dom0 qmemman.daemon.algo[2606]: balloon: dom 10 has actual memory 948840835
Oct 08 12:45:43 dom0 qmemman.daemon.algo[2606]: balloon: dom 7 has actual memory 1101033232
Oct 08 12:45:43 dom0 qmemman.daemon.algo[2606]: balloon: dom 11 has actual memory 1405503219
Oct 08 12:45:43 dom0 qmemman.daemon.algo[2606]: balloon: dom 8 has actual memory 1089607372
Oct 08 12:45:43 dom0 qmemman.daemon.algo[2606]: balloon: dom 3 has actual memory 1062057881
Oct 08 12:45:43 dom0 qmemman.daemon.algo[2606]: balloon: dom 9 has actual memory 1105121687
Oct 08 12:45:43 dom0 qmemman.daemon.algo[2606]: req=35187352 avail=8869727235.8 donors=[('13', 794536933.8), ('14', 1568799362.4), ('0', 2170455987.2), ('10', 618612713.4), ('7', 715464464.0), ('11', 896494937.4), ('8', 703080140.0), ('3', 689865010.5999999), ('9', 712417687.0)]
Oct 08 12:45:43 dom0 qmemman.daemon.algo[2606]: borrow 3309632.0241357074 from 13
Oct 08 12:45:43 dom0 qmemman.daemon.algo[2606]: borrow 6534810.892189036 from 14
Oct 08 12:45:43 dom0 qmemman.daemon.algo[2606]: borrow 9041002.798772853 from 0
Oct 08 12:45:43 dom0 qmemman.daemon.algo[2606]: borrow 2576822.2466565524 from 10
Oct 08 12:45:43 dom0 qmemman.daemon.algo[2606]: borrow 2980256.80299154 from 7
Oct 08 12:45:43 dom0 qmemman.daemon.algo[2606]: borrow 3734336.6029620515 from 11
Oct 08 12:45:43 dom0 qmemman.daemon.algo[2606]: borrow 2928670.0817655767 from 8
Oct 08 12:45:43 dom0 qmemman.daemon.algo[2606]: borrow 2873622.652747825 from 3
Oct 08 12:45:43 dom0 qmemman.daemon.algo[2606]: borrow 2967565.4977788636 from 9
Oct 08 12:45:43 dom0 qmemman.systemstate[2606]: memset_reqs=[('13', 1230597848), ('14', 2469221761), ('0', 4180532117), ('10', 946264012), ('7', 1098052975), ('11', 1401768882), ('8', 1086678701), ('3', 1059184258), ('9', 1102154121)]
Oct 08 12:45:43 dom0 qmemman.systemstate[2606]: mem-set domain 13 to 1230597848
Oct 08 12:45:43 dom0 qmemman.systemstate[2606]: mem-set domain 14 to 2469221761
Oct 08 12:45:43 dom0 qmemman.systemstate[2606]: mem-set domain 0 to 4180532117
Oct 08 12:45:43 dom0 qmemman.systemstate[2606]: mem-set domain 10 to 946264012
Oct 08 12:45:43 dom0 qmemman.systemstate[2606]: mem-set domain 7 to 1098052975
Oct 08 12:45:43 dom0 qmemman.systemstate[2606]: mem-set domain 11 to 1401768882
Oct 08 12:45:43 dom0 qmemman.systemstate[2606]: mem-set domain 8 to 1086678701
Oct 08 12:45:43 dom0 qmemman.systemstate[2606]: mem-set domain 3 to 1059184258
Oct 08 12:45:43 dom0 qmemman.systemstate[2606]: mem-set domain 9 to 1102154121
Oct 08 12:45:43 dom0 qmemman.systemstate[2606]: xenfree=631537190
Oct 08 12:45:43 dom0 dmeventd[1172]: No longer monitoring thin pool qubes_dom0-pool00-tpool.
Oct 08 12:45:43 dom0 lvm[1172]: Monitoring thin pool qubes_dom0-pool00-tpool.
Oct 08 12:45:44 dom0 dmeventd[1172]: No longer monitoring thin pool qubes_dom0-pool00-tpool.
Oct 08 12:45:44 dom0 lvm[1172]: Monitoring thin pool qubes_dom0-pool00-tpool.
Oct 08 12:45:46 dom0 kernel: xen-blkback: backend/vbd/20/51712: using 1 queues, protocol 1 (x86_64-abi) persistent grants
Oct 08 12:45:46 dom0 kernel: xen-blkback: backend/vbd/20/51728: using 1 queues, protocol 1 (x86_64-abi) persistent grants
Oct 08 12:45:46 dom0 kernel: xen-blkback: backend/vbd/20/51744: using 1 queues, protocol 1 (x86_64-abi) persistent grants
Oct 08 12:45:47 dom0 qmemman.daemon.reqhandler[2606]: EOF
Oct 08 12:45:47 dom0 qubesd[2604]: Setting Qubes DB info for the VM
Oct 08 12:45:47 dom0 qubesd[2604]: Starting Qubes DB
Oct 08 12:45:47 dom0 runuser[8251]: pam_unix(runuser:session): session opened for user creviera by (uid=0)
Oct 08 12:45:47 dom0 audit[8251]: CRED_ACQ pid=8251 uid=0 auid=4294967295 ses=4294967295 msg='op=PAM:setcred grantors=pam_rootok acct="creviera" exe="/usr/sbin/runuser" hostname=? addr=? terminal=? res=success'
Oct 08 12:45:47 dom0 audit[8251]: USER_START pid=8251 uid=0 auid=4294967295 ses=4294967295 msg='op=PAM:session_open grantors=pam_keyinit,pam_limits,pam_unix acct="creviera" exe="/usr/sbin/runuser" hostname=? addr=? terminal=? res=success'
Oct 08 12:45:47 dom0 kernel: audit: type=1103 audit(1570553147.168:257): pid=8251 uid=0 auid=4294967295 ses=4294967295 msg='op=PAM:setcred grantors=pam_rootok acct="creviera" exe="/usr/sbin/runuser" hostname=? addr=? terminal=? res=success'
Oct 08 12:45:47 dom0 kernel: audit: type=1105 audit(1570553147.169:258): pid=8251 uid=0 auid=4294967295 ses=4294967295 msg='op=PAM:session_open grantors=pam_keyinit,pam_limits,pam_unix acct="creviera" exe="/usr/sbin/runuser" hostname=? addr=? terminal=? res=success'
Oct 08 12:45:47 dom0 qmemman.daemon.algo[2606]: balance_when_enough_memory(xen_free_memory=-22987068, total_mem_pref=5741674163.2, total_available_memory=8809793443.8)
Oct 08 12:45:47 dom0 qmemman.daemon.algo[2606]: left_memory=817084624 acceptors_count=8
Oct 08 12:45:47 dom0 qmemman.systemstate[2606]: stat: dom '13' act=1230597848 pref=439370547.2 last_target=1230597848
Oct 08 12:45:47 dom0 qmemman.systemstate[2606]: stat: dom '14' act=2469221761 pref=906957209.6 last_target=2469221761
Oct 08 12:45:47 dom0 qmemman.systemstate[2606]: stat: dom '0' act=4180532117 pref=2019117132.8 last_target=4180532117
Oct 08 12:45:47 dom0 qmemman.systemstate[2606]: stat: dom '10' act=946264012 pref=330228121.6 last_target=946264012
Oct 08 12:45:47 dom0 qmemman.systemstate[2606]: stat: dom '7' act=1098052975 pref=385568768.0 last_target=1098052975
Oct 08 12:45:47 dom0 qmemman.systemstate[2606]: stat: dom '11' act=1401768882 pref=509008281.6 last_target=1401768882
Oct 08 12:45:47 dom0 qmemman.systemstate[2606]: stat: dom '8' act=1086678701 pref=386527232.0 last_target=1086678701
Oct 08 12:45:47 dom0 qmemman.systemstate[2606]: stat: dom '3' act=1059184258 pref=372192870.40000004 last_target=1059184258
Oct 08 12:45:47 dom0 qmemman.systemstate[2606]: stat: dom '9' act=1102154121 pref=392704000.0 last_target=1102154121
Oct 08 12:45:47 dom0 qmemman.systemstate[2606]: stat: xenfree=29441732 memset_reqs=[('13', 1214442926), ('14', 2398290687), ('9', 1096291380), ('7', 1078226219), ('11', 1390753507), ('10', 938113368), ('8', 1080652882), ('3', 1044360782), ('0', 4294967296)]
Oct 08 12:45:47 dom0 runuser[8251]: pam_unix(runuser:session): session closed for user creviera
Oct 08 12:45:47 dom0 audit[8251]: USER_END pid=8251 uid=0 auid=4294967295 ses=4294967295 msg='op=PAM:session_close grantors=pam_keyinit,pam_limits,pam_unix acct="creviera" exe="/usr/sbin/runuser" hostname=? addr=? terminal=? res=success'
Oct 08 12:45:47 dom0 audit[8251]: CRED_DISP pid=8251 uid=0 auid=4294967295 ses=4294967295 msg='op=PAM:setcred grantors=pam_rootok acct="creviera" exe="/usr/sbin/runuser" hostname=? addr=? terminal=? res=success'
Oct 08 12:45:47 dom0 kernel: audit: type=1106 audit(1570553147.193:259): pid=8251 uid=0 auid=4294967295 ses=4294967295 msg='op=PAM:session_close grantors=pam_keyinit,pam_limits,pam_unix acct="creviera" exe="/usr/sbin/runuser" hostname=? addr=? terminal=? res=success'
Oct 08 12:45:47 dom0 kernel: audit: type=1104 audit(1570553147.193:260): pid=8251 uid=0 auid=4294967295 ses=4294967295 msg='op=PAM:setcred grantors=pam_rootok acct="creviera" exe="/usr/sbin/runuser" hostname=? addr=? terminal=? res=success'
Oct 08 12:45:47 dom0 qubesd[2604]: Activating the sd-export-usb VM
Oct 08 12:45:47 dom0 runuser[8254]: pam_unix(runuser:session): session opened for user creviera by (uid=0)
Oct 08 12:45:47 dom0 audit[8254]: CRED_ACQ pid=8254 uid=0 auid=4294967295 ses=4294967295 msg='op=PAM:setcred grantors=pam_rootok acct="creviera" exe="/usr/sbin/runuser" hostname=? addr=? terminal=? res=success'
Oct 08 12:45:47 dom0 audit[8254]: USER_START pid=8254 uid=0 auid=4294967295 ses=4294967295 msg='op=PAM:session_open grantors=pam_keyinit,pam_limits,pam_unix acct="creviera" exe="/usr/sbin/runuser" hostname=? addr=? terminal=? res=success'
Oct 08 12:45:47 dom0 kernel: audit: type=1103 audit(1570553147.217:261): pid=8254 uid=0 auid=4294967295 ses=4294967295 msg='op=PAM:setcred grantors=pam_rootok acct="creviera" exe="/usr/sbin/runuser" hostname=? addr=? terminal=? res=success'
Oct 08 12:45:47 dom0 kernel: audit: type=1105 audit(1570553147.218:262): pid=8254 uid=0 auid=4294967295 ses=4294967295 msg='op=PAM:session_open grantors=pam_keyinit,pam_limits,pam_unix acct="creviera" exe="/usr/sbin/runuser" hostname=? addr=? terminal=? res=success'
Oct 08 12:45:47 dom0 qmemman.systemstate[2606]: mem-set domain 13 to 1207566073
Oct 08 12:45:47 dom0 qmemman.daemon.algo[2606]: balance_when_enough_memory(xen_free_memory=-31936, total_mem_pref=5741674163.2, total_available_memory=8812649799.8)
Oct 08 12:45:47 dom0 qmemman.daemon.algo[2606]: left_memory=818088086 acceptors_count=8
Oct 08 12:45:47 dom0 qmemman.systemstate[2606]: stat: dom '13' act=1210499072 pref=439370547.2 last_target=1207566073
Oct 08 12:45:47 dom0 qmemman.systemstate[2606]: stat: dom '14' act=2469221761 pref=906957209.6 last_target=2469221761
Oct 08 12:45:47 dom0 qmemman.systemstate[2606]: stat: dom '0' act=4180532117 pref=2019117132.8 last_target=4180532117
Oct 08 12:45:47 dom0 qmemman.systemstate[2606]: stat: dom '10' act=946264012 pref=330228121.6 last_target=946264012
Oct 08 12:45:47 dom0 qmemman.systemstate[2606]: stat: dom '7' act=1098052975 pref=385568768.0 last_target=1098052975
Oct 08 12:45:47 dom0 qmemman.systemstate[2606]: stat: dom '11' act=1401768882 pref=509008281.6 last_target=1401768882
Oct 08 12:45:47 dom0 qmemman.systemstate[2606]: stat: dom '8' act=1086678701 pref=386527232.0 last_target=1086678701
Oct 08 12:45:47 dom0 qmemman.systemstate[2606]: stat: dom '3' act=1059184258 pref=372192870.40000004 last_target=1059184258
Oct 08 12:45:47 dom0 qmemman.systemstate[2606]: stat: dom '9' act=1102154121 pref=392704000.0 last_target=1102154121
Oct 08 12:45:47 dom0 qmemman.systemstate[2606]: stat: xenfree=52396864 memset_reqs=[('14', 2398866734), ('9', 1096611853), ('7', 1078543146), ('11', 1391131781), ('10', 938402792), ('8', 1080970285), ('3', 1044671062), ('13', 1214786592), ('0', 4294967296)]
Oct 08 12:45:47 dom0 qmemman.systemstate[2606]: mem-set domain 14 to 2398866734
Oct 08 12:45:47 dom0 qmemman.systemstate[2606]: mem-set domain 9 to 1096611853
Oct 08 12:45:47 dom0 qmemman.systemstate[2606]: mem-set domain 7 to 1078543146
Oct 08 12:45:47 dom0 qmemman.systemstate[2606]: mem-set domain 11 to 1391131781
Oct 08 12:45:47 dom0 qmemman.systemstate[2606]: mem-set domain 10 to 938402792
Oct 08 12:45:47 dom0 qmemman.systemstate[2606]: mem-set domain 8 to 1080970285
Oct 08 12:45:47 dom0 qmemman.systemstate[2606]: mem-set domain 3 to 1044671062
Oct 08 12:45:47 dom0 qmemman.systemstate[2606]: mem-set domain 13 to 1214786592
Oct 08 12:45:47 dom0 qmemman.systemstate[2606]: mem-set domain 0 to 4294967296
Oct 08 12:45:52 dom0 sudo[8258]: creviera : TTY=pts/0 ; PWD=/home/creviera/securedrop-workstation ; USER=root ; COMMAND=/bin/journalctl -ef
Oct 08 12:45:52 dom0 audit[8258]: USER_CMD pid=8258 uid=1000 auid=1000 ses=2 msg='cwd="/home/creviera/securedrop-workstation" cmd=6A6F75726E616C63746C202D6566 terminal=pts/0 res=success'
Oct 08 12:45:52 dom0 audit[8258]: CRED_REFR pid=8258 uid=0 auid=1000 ses=2 msg='op=PAM:setcred grantors=pam_env,pam_unix acct="root" exe="/usr/bin/sudo" hostname=? addr=? terminal=/dev/pts/0 res=success'
Oct 08 12:45:52 dom0 kernel: audit: type=1123 audit(1570553152.712:263): pid=8258 uid=1000 auid=1000 ses=2 msg='cwd="/home/creviera/securedrop-workstation" cmd=6A6F75726E616C63746C202D6566 terminal=pts/0 res=success'
Oct 08 12:45:52 dom0 kernel: audit: type=1110 audit(1570553152.712:264): pid=8258 uid=0 auid=1000 ses=2 msg='op=PAM:setcred grantors=pam_env,pam_unix acct="root" exe="/usr/bin/sudo" hostname=? addr=? terminal=/dev/pts/0 res=success'
Oct 08 12:45:52 dom0 sudo[8258]: pam_systemd(sudo:session): Cannot create session: Already running in a session
Oct 08 12:45:52 dom0 audit[8258]: USER_START pid=8258 uid=0 auid=1000 ses=2 msg='op=PAM:session_open grantors=pam_keyinit,pam_limits,pam_keyinit,pam_limits,pam_systemd,pam_unix acct="root" exe="/usr/bin/sudo" hostname=? addr=? terminal=/dev/pts/0 res=success'
Oct 08 12:45:52 dom0 sudo[8258]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 08 12:45:52 dom0 kernel: audit: type=1105 audit(1570553152.715:265): pid=8258 uid=0 auid=1000 ses=2 msg='op=PAM:session_open grantors=pam_keyinit,pam_limits,pam_keyinit,pam_limits,pam_systemd,pam_unix acct="root" exe="/usr/bin/sudo" hostname=? addr=? terminal=/dev/pts/0 res=success'
Oct 08 12:45:57 dom0 qmemman.daemon.algo[2606]: balance_when_enough_memory(xen_free_memory=21001092, total_mem_pref=5781679385.599999, total_available_memory=8778273247.4)
Oct 08 12:45:57 dom0 qmemman.daemon.algo[2606]: left_memory=784672996 acceptors_count=8
Oct 08 12:45:57 dom0 qmemman.systemstate[2606]: stat: dom '13' act=1214786592 pref=439370547.2 last_target=1214786592
Oct 08 12:45:57 dom0 qmemman.systemstate[2606]: stat: dom '14' act=2398866734 pref=946962432.0 last_target=2398866734
Oct 08 12:45:57 dom0 qmemman.systemstate[2606]: stat: dom '0' act=4294967296 pref=2019117132.8 last_target=4294967296
Oct 08 12:45:57 dom0 qmemman.systemstate[2606]: stat: dom '10' act=938402792 pref=330228121.6 last_target=938402792
Oct 08 12:45:57 dom0 qmemman.systemstate[2606]: stat: dom '7' act=1078543146 pref=385568768.0 last_target=1078543146
Oct 08 12:45:57 dom0 qmemman.systemstate[2606]: stat: dom '11' act=1391131781 pref=509008281.6 last_target=1391131781
Oct 08 12:45:57 dom0 qmemman.systemstate[2606]: stat: dom '8' act=1080970285 pref=386527232.0 last_target=1080970285
Oct 08 12:45:57 dom0 qmemman.systemstate[2606]: stat: dom '3' act=1044671062 pref=372192870.40000004 last_target=1044671062
Oct 08 12:45:57 dom0 qmemman.systemstate[2606]: stat: dom '9' act=1096611853 pref=392704000.0 last_target=1096611853
Oct 08 12:45:57 dom0 qmemman.systemstate[2606]: stat: xenfree=73429892 memset_reqs=[('13', 1203342583), ('9', 1085940145), ('7', 1067989522), ('11', 1378535312), ('10', 928765020), ('8', 1070400799), ('3', 1034338800), ('14', 2480328523), ('0', 4294967296)]
Oct 08 12:45:57 dom0 qmemman.systemstate[2606]: mem-set domain 13 to 1203342583
Oct 08 12:45:57 dom0 qmemman.systemstate[2606]: mem-set domain 9 to 1085940145
Oct 08 12:45:57 dom0 qmemman.systemstate[2606]: mem-set domain 7 to 1067989522
Oct 08 12:45:57 dom0 qmemman.systemstate[2606]: mem-set domain 11 to 1378535312
Oct 08 12:45:57 dom0 qmemman.systemstate[2606]: mem-set domain 10 to 928765020
Oct 08 12:45:57 dom0 qmemman.systemstate[2606]: mem-set domain 8 to 1070400799
Oct 08 12:45:57 dom0 qmemman.systemstate[2606]: mem-set domain 3 to 1034338800
Oct 08 12:45:57 dom0 qmemman.systemstate[2606]: mem-set domain 14 to 2480328523
Oct 08 12:45:57 dom0 qmemman.systemstate[2606]: mem-set domain 0 to 4294967296
Oct 08 12:46:04 dom0 kernel: xen-blkback: backend/vbd/19/51712: using 2 queues, protocol 1 (x86_64-abi) persistent grants
Oct 08 12:46:04 dom0 kernel: xen-blkback: backend/vbd/19/51728: using 2 queues, protocol 1 (x86_64-abi) persistent grants
Oct 08 12:46:04 dom0 kernel: xen-blkback: backend/vbd/19/51744: using 2 queues, protocol 1 (x86_64-abi) persistent grants
Oct 08 12:46:06 dom0 runuser[8254]: pam_unix(runuser:session): session closed for user creviera
Oct 08 12:46:06 dom0 audit[8254]: USER_END pid=8254 uid=0 auid=4294967295 ses=4294967295 msg='op=PAM:session_close grantors=pam_keyinit,pam_limits,pam_unix acct="creviera" exe="/usr/sbin/runuser" hostname=? addr=? terminal=? res=success'
Oct 08 12:46:06 dom0 audit[8254]: CRED_DISP pid=8254 uid=0 auid=4294967295 ses=4294967295 msg='op=PAM:setcred grantors=pam_rootok acct="creviera" exe="/usr/sbin/runuser" hostname=? addr=? terminal=? res=success'
Oct 08 12:46:06 dom0 kernel: audit: type=1106 audit(1570553166.753:266): pid=8254 uid=0 auid=4294967295 ses=4294967295 msg='op=PAM:session_close grantors=pam_keyinit,pam_limits,pam_unix acct="creviera" exe="/usr/sbin/runuser" hostname=? addr=? terminal=? res=success'
Oct 08 12:46:06 dom0 kernel: audit: type=1104 audit(1570553166.753:267): pid=8254 uid=0 auid=4294967295 ses=4294967295 msg='op=PAM:setcred grantors=pam_rootok acct="creviera" exe="/usr/sbin/runuser" hostname=? addr=? terminal=? res=success'
Oct 08 12:46:06 dom0 qmemman.daemon.algo[2606]: balance_when_enough_memory(xen_free_memory=33059740, total_mem_pref=6061002419.2, total_available_memory=8918310888.8)
Oct 08 12:46:06 dom0 qmemman.daemon.algo[2606]: left_memory=690139301 acceptors_count=9
Oct 08 12:46:06 dom0 qmemman.systemstate[2606]: stat: dom '13' act=1203342583 pref=439370547.2 last_target=1203342583
Oct 08 12:46:06 dom0 qmemman.systemstate[2606]: stat: dom '14' act=2480328523 pref=946962432.0 last_target=2480328523
Oct 08 12:46:06 dom0 qmemman.systemstate[2606]: stat: dom '0' act=4294967296 pref=2019117132.8 last_target=4294967296
Oct 08 12:46:06 dom0 qmemman.systemstate[2606]: stat: dom '10' act=928765020 pref=330228121.6 last_target=928765020
Oct 08 12:46:06 dom0 qmemman.systemstate[2606]: stat: dom '7' act=1067989522 pref=385568768.0 last_target=1067989522
Oct 08 12:46:06 dom0 qmemman.systemstate[2606]: stat: dom '11' act=1378535312 pref=509008281.6 last_target=1378535312
Oct 08 12:46:06 dom0 qmemman.systemstate[2606]: stat: dom '8' act=1070400799 pref=386527232.0 last_target=1070400799
Oct 08 12:46:06 dom0 qmemman.systemstate[2606]: stat: dom '3' act=1034338800 pref=372192870.40000004 last_target=1034338800
Oct 08 12:46:06 dom0 qmemman.systemstate[2606]: stat: dom '9' act=1085940145 pref=392704000.0 last_target=1085940145
Oct 08 12:46:06 dom0 qmemman.systemstate[2606]: stat: dom '19' act=401645568 pref=279323033.6 last_target=401604608
Oct 08 12:46:06 dom0 qmemman.systemstate[2606]: stat: xenfree=85488540 memset_reqs=[('13', 1161390974), ('14', 2414611806), ('9', 1046173432), ('7', 1028556875), ('11', 1333323312), ('10', 891923385), ('8', 1030923279), ('3', 995532404), ('0', 4294967296), ('19', 766241082)]
Oct 08 12:46:06 dom0 qmemman.systemstate[2606]: mem-set domain 13 to 1161390974
Oct 08 12:46:06 dom0 qmemman.systemstate[2606]: mem-set domain 14 to 2414611806
Oct 08 12:46:06 dom0 qmemman.systemstate[2606]: mem-set domain 9 to 1046173432
Oct 08 12:46:06 dom0 qmemman.systemstate[2606]: mem-set domain 7 to 1028556875
Oct 08 12:46:06 dom0 qmemman.systemstate[2606]: mem-set domain 11 to 1333323312
Oct 08 12:46:06 dom0 qmemman.systemstate[2606]: mem-set domain 10 to 891923385
Oct 08 12:46:06 dom0 qmemman.systemstate[2606]: mem-set domain 8 to 1030923279
Oct 08 12:46:06 dom0 qmemman.systemstate[2606]: mem-set domain 3 to 995532404
Oct 08 12:46:06 dom0 qmemman.systemstate[2606]: mem-set domain 0 to 4294967296
Oct 08 12:46:06 dom0 qrexec[8266]: qubes.GetDate: sd-export-usb -> @default: allowed to dom0
Oct 08 12:46:06 dom0 qmemman.systemstate[2606]: mem-set domain 19 to 766241082
Oct 08 12:46:07 dom0 qrexec[8286]: qubes.USB+4-2: sd-export-usb -> sys-usb: allowed to sys-usb
Oct 08 12:46:08 dom0 qmemman.daemon.algo[2606]: balance_when_enough_memory(xen_free_memory=15669303, total_mem_pref=6104122649.599999, total_available_memory=8875190498.4)
Oct 08 12:46:08 dom0 qmemman.daemon.algo[2606]: left_memory=654923877 acceptors_count=9
Oct 08 12:46:08 dom0 qmemman.systemstate[2606]: stat: dom '13' act=1161390974 pref=439370547.2 last_target=1161390974
Oct 08 12:46:08 dom0 qmemman.systemstate[2606]: stat: dom '14' act=2414611806 pref=946962432.0 last_target=2414611806
Oct 08 12:46:08 dom0 qmemman.systemstate[2606]: stat: dom '0' act=4294967296 pref=2019117132.8 last_target=4294967296
Oct 08 12:46:08 dom0 qmemman.systemstate[2606]: stat: dom '10' act=891923385 pref=330228121.6 last_target=891923385
Oct 08 12:46:08 dom0 qmemman.systemstate[2606]: stat: dom '7' act=1028556875 pref=385568768.0 last_target=1028556875
Oct 08 12:46:08 dom0 qmemman.systemstate[2606]: stat: dom '11' act=1333323312 pref=509008281.6 last_target=1333323312
Oct 08 12:46:08 dom0 qmemman.systemstate[2606]: stat: dom '8' act=1030923279 pref=386527232.0 last_target=1030923279
Oct 08 12:46:08 dom0 qmemman.systemstate[2606]: stat: dom '3' act=995532404 pref=372192870.40000004 last_target=995532404
Oct 08 12:46:08 dom0 qmemman.systemstate[2606]: stat: dom '9' act=1046173432 pref=392704000.0 last_target=1046173432
Oct 08 12:46:08 dom0 qmemman.systemstate[2606]: stat: dom '19' act=766241082 pref=322443264.0 last_target=766241082
Oct 08 12:46:08 dom0 qmemman.systemstate[2606]: stat: xenfree=68098103 memset_reqs=[('13', 1149819000), ('14', 2394186922), ('9', 1035415369), ('7', 1017923258), ('11', 1320536786), ('10', 882254964), ('8', 1020272945), ('3', 985132076), ('0', 4294967296), ('19', 863170287)]
Oct 08 12:46:08 dom0 qmemman.systemstate[2606]: mem-set domain 13 to 1149819000
Oct 08 12:46:08 dom0 qmemman.systemstate[2606]: mem-set domain 14 to 2394186922
Oct 08 12:46:08 dom0 qmemman.systemstate[2606]: mem-set domain 9 to 1035415369
Oct 08 12:46:08 dom0 qmemman.systemstate[2606]: mem-set domain 7 to 1017923258
Oct 08 12:46:08 dom0 qmemman.systemstate[2606]: mem-set domain 11 to 1320536786
Oct 08 12:46:08 dom0 qmemman.systemstate[2606]: mem-set domain 10 to 882254964
Oct 08 12:46:08 dom0 qmemman.systemstate[2606]: mem-set domain 8 to 1020272945
Oct 08 12:46:08 dom0 qmemman.systemstate[2606]: mem-set domain 3 to 985132076
Oct 08 12:46:08 dom0 qmemman.systemstate[2606]: mem-set domain 0 to 4294967296
Oct 08 12:46:08 dom0 qmemman.systemstate[2606]: mem-set domain 19 to 863170287
Oct 08 12:46:09 dom0 qrexec[8288]: qubes.WindowIconUpdater: sd-export-usb -> dom0: allowed to dom0
Oct 08 12:46:09 dom0 qmemman.daemon.algo[2606]: balance_when_enough_memory(xen_free_memory=15634499, total_mem_pref=6149138508.799999, total_available_memory=8830174893.199999)
Oct 08 12:46:09 dom0 qmemman.daemon.algo[2606]: left_memory=618687404 acceptors_count=9
Oct 08 12:46:09 dom0 qmemman.systemstate[2606]: stat: dom '13' act=1149819000 pref=439370547.2 last_target=1149819000
Oct 08 12:46:09 dom0 qmemman.systemstate[2606]: stat: dom '14' act=2394186922 pref=946962432.0 last_target=2394186922
Oct 08 12:46:09 dom0 qmemman.systemstate[2606]: stat: dom '0' act=4294967296 pref=2019117132.8 last_target=4294967296
Oct 08 12:46:09 dom0 qmemman.systemstate[2606]: stat: dom '10' act=882254964 pref=330228121.6 last_target=882254964
Oct 08 12:46:09 dom0 qmemman.systemstate[2606]: stat: dom '7' act=1017923258 pref=385568768.0 last_target=1017923258
Oct 08 12:46:09 dom0 qmemman.systemstate[2606]: stat: dom '11' act=1320536786 pref=509008281.6 last_target=1320536786
Oct 08 12:46:09 dom0 qmemman.systemstate[2606]: stat: dom '8' act=1020272945 pref=386527232.0 last_target=1020272945
Oct 08 12:46:09 dom0 qmemman.systemstate[2606]: stat: dom '3' act=985132076 pref=372192870.40000004 last_target=985132076
Oct 08 12:46:09 dom0 qmemman.systemstate[2606]: stat: dom '9' act=1035415369 pref=392704000.0 last_target=1035415369
Oct 08 12:46:09 dom0 qmemman.systemstate[2606]: stat: dom '19' act=863170287 pref=367459123.2 last_target=863170287
Oct 08 12:46:09 dom0 qmemman.systemstate[2606]: stat: xenfree=68063299 memset_reqs=[('13', 1137911503), ('14', 2373169830), ('9', 1024345383), ('7', 1006981325), ('11', 1307379522), ('10', 872306213), ('8', 1009313811), ('3', 974430196), ('0', 4294967296), ('19', 962910310)]
Oct 08 12:46:09 dom0 qmemman.systemstate[2606]: mem-set domain 13 to 1137911503
Oct 08 12:46:09 dom0 qmemman.systemstate[2606]: mem-set domain 14 to 2373169830
Oct 08 12:46:09 dom0 qmemman.systemstate[2606]: mem-set domain 9 to 1024345383
Oct 08 12:46:09 dom0 qmemman.systemstate[2606]: mem-set domain 7 to 1006981325
Oct 08 12:46:09 dom0 qmemman.systemstate[2606]: mem-set domain 11 to 1307379522
Oct 08 12:46:09 dom0 qmemman.systemstate[2606]: mem-set domain 10 to 872306213
Oct 08 12:46:09 dom0 qmemman.systemstate[2606]: mem-set domain 8 to 1009313811
Oct 08 12:46:09 dom0 qmemman.systemstate[2606]: mem-set domain 3 to 974430196
Oct 08 12:46:09 dom0 qmemman.systemstate[2606]: mem-set domain 0 to 4294967296
Oct 08 12:46:09 dom0 qmemman.systemstate[2606]: mem-set domain 19 to 962910310
Oct 08 12:46:09 dom0 qubesd[2604]: Start failed: list.remove(x): x not in list
Oct 08 12:46:09 dom0 qmemman.daemon.algo[2606]: balance_when_enough_memory(xen_free_memory=182360697, total_mem_pref=5781679385.599999, total_available_memory=8401486390.4)
Oct 08 12:46:09 dom0 qmemman.daemon.algo[2606]: left_memory=653220528 acceptors_count=8
Oct 08 12:46:09 dom0 qmemman.systemstate[2606]: stat: dom '13' act=1137911503 pref=439370547.2 last_target=1137911503
Oct 08 12:46:09 dom0 qmemman.systemstate[2606]: stat: dom '14' act=2373169830 pref=946962432.0 last_target=2373169830
Oct 08 12:46:09 dom0 qmemman.systemstate[2606]: stat: dom '0' act=4294967296 pref=2019117132.8 last_target=4294967296
Oct 08 12:46:09 dom0 qmemman.systemstate[2606]: stat: dom '10' act=872306213 pref=330228121.6 last_target=872306213
Oct 08 12:46:09 dom0 qmemman.systemstate[2606]: stat: dom '7' act=1006981325 pref=385568768.0 last_target=1006981325
Oct 08 12:46:09 dom0 qmemman.systemstate[2606]: stat: dom '11' act=1307379522 pref=509008281.6 last_target=1307379522
Oct 08 12:46:09 dom0 qmemman.systemstate[2606]: stat: dom '8' act=1009313811 pref=386527232.0 last_target=1009313811
Oct 08 12:46:09 dom0 qmemman.systemstate[2606]: stat: dom '3' act=974430196 pref=372192870.40000004 last_target=974430196
Oct 08 12:46:09 dom0 qmemman.systemstate[2606]: stat: dom '9' act=1024345383 pref=392704000.0 last_target=1024345383
Oct 08 12:46:09 dom0 qmemman.systemstate[2606]: stat: xenfree=234789497 memset_reqs=[('13', 1158322705), ('14', 2402262417), ('0', 4294967296), ('9', 1043958443), ('7', 1026472351), ('11', 1328981744), ('10', 890850743), ('8', 1028821229), ('3', 993692453)]
Oct 08 12:46:09 dom0 qmemman.systemstate[2606]: mem-set domain 13 to 1158322705
Oct 08 12:46:09 dom0 qmemman.systemstate[2606]: mem-set domain 14 to 2402262417
Oct 08 12:46:09 dom0 qmemman.systemstate[2606]: mem-set domain 0 to 4294967296
Oct 08 12:46:09 dom0 qmemman.systemstate[2606]: mem-set domain 9 to 1043958443
Oct 08 12:46:09 dom0 qmemman.systemstate[2606]: mem-set domain 7 to 1026472351
Oct 08 12:46:09 dom0 qmemman.systemstate[2606]: mem-set domain 11 to 1328981744
Oct 08 12:46:09 dom0 qmemman.systemstate[2606]: mem-set domain 10 to 890850743
Oct 08 12:46:09 dom0 qmemman.systemstate[2606]: mem-set domain 8 to 1028821229
Oct 08 12:46:09 dom0 qmemman.systemstate[2606]: mem-set domain 3 to 993692453
Oct 08 12:46:09 dom0 qmemman.daemon.algo[2606]: balance_when_enough_memory(xen_free_memory=981548732, total_mem_pref=5781679385.599999, total_available_memory=9368198727.4)
Oct 08 12:46:09 dom0 qmemman.daemon.algo[2606]: left_memory=990484739 acceptors_count=8
Oct 08 12:46:09 dom0 qmemman.systemstate[2606]: stat: dom '13' act=1158322705 pref=439370547.2 last_target=1158322705
Oct 08 12:46:09 dom0 qmemman.systemstate[2606]: stat: dom '14' act=2402262417 pref=946962432.0 last_target=2402262417
Oct 08 12:46:09 dom0 qmemman.systemstate[2606]: stat: dom '0' act=4294967296 pref=2019117132.8 last_target=4294967296
Oct 08 12:46:09 dom0 qmemman.systemstate[2606]: stat: dom '10' act=890850743 pref=330228121.6 last_target=890850743
Oct 08 12:46:09 dom0 qmemman.systemstate[2606]: stat: dom '7' act=1026472351 pref=385568768.0 last_target=1026472351
Oct 08 12:46:09 dom0 qmemman.systemstate[2606]: stat: dom '11' act=1328981744 pref=509008281.6 last_target=1328981744
Oct 08 12:46:09 dom0 qmemman.systemstate[2606]: stat: dom '8' act=1028821229 pref=386527232.0 last_target=1028821229
Oct 08 12:46:09 dom0 qmemman.systemstate[2606]: stat: dom '3' act=993692453 pref=372192870.40000004 last_target=993692453
Oct 08 12:46:09 dom0 qmemman.systemstate[2606]: stat: dom '9' act=1043958443 pref=392704000.0 last_target=1043958443
Oct 08 12:46:09 dom0 qmemman.systemstate[2606]: stat: xenfree=1033977532 memset_reqs=[('13', 1273829046), ('14', 2602554615), ('0', 4294967296), ('9', 1151669815), ('7', 1132991886), ('11', 1456120058), ('10', 988126426), ('8', 1135500861), ('3', 1097977738)]
Oct 08 12:46:09 dom0 qmemman.systemstate[2606]: mem-set domain 13 to 1273829046
Oct 08 12:46:09 dom0 qmemman.systemstate[2606]: mem-set domain 14 to 2602554615
Oct 08 12:46:09 dom0 qmemman.systemstate[2606]: mem-set domain 0 to 4294967296
Oct 08 12:46:09 dom0 qmemman.systemstate[2606]: mem-set domain 9 to 1151669815
Oct 08 12:46:09 dom0 qmemman.systemstate[2606]: mem-set domain 7 to 1132991886
Oct 08 12:46:09 dom0 qmemman.systemstate[2606]: mem-set domain 11 to 1456120058
Oct 08 12:46:09 dom0 qmemman.systemstate[2606]: mem-set domain 10 to 988126426
Oct 08 12:46:09 dom0 qmemman.systemstate[2606]: mem-set domain 8 to 1135500861
Oct 08 12:46:09 dom0 qmemman.systemstate[2606]: mem-set domain 3 to 1097977738
Oct 08 12:46:10 dom0 qmemman.daemon.algo[2606]: balance_when_enough_memory(xen_free_memory=23634057, total_mem_pref=5828841139.2, total_available_memory=9328530658.8)
Oct 08 12:46:10 dom0 qmemman.daemon.algo[2606]: left_memory=1072830043 acceptors_count=8
Oct 08 12:46:10 dom0 qubesd[2604]: Starting sd-export-usb
Oct 08 12:46:10 dom0 qubesd[2604]: unhandled exception while calling src=b'dom0' meth=b'admin.vm.Start' dest=b'sd-export-usb' arg=b'' len(untrusted_payload)=0
Oct 08 12:46:10 dom0 qubesd[2604]: Traceback (most recent call last):
Oct 08 12:46:10 dom0 qubesd[2604]:   File "/usr/lib/python3.5/site-packages/qubes/api/__init__.py", line 266, in respond
Oct 08 12:46:10 dom0 qubesd[2604]:     untrusted_payload=untrusted_payload)
Oct 08 12:46:10 dom0 qubesd[2604]:   File "/usr/lib64/python3.5/asyncio/futures.py", line 381, in __iter__
Oct 08 12:46:10 dom0 qubesd[2604]:     yield self  # This tells Task to wait for completion.
Oct 08 12:46:10 dom0 qubesd[2604]:   File "/usr/lib64/python3.5/asyncio/tasks.py", line 310, in _wakeup
Oct 08 12:46:10 dom0 qubesd[2604]:     future.result()
Oct 08 12:46:10 dom0 qubesd[2604]:   File "/usr/lib64/python3.5/asyncio/futures.py", line 294, in result
Oct 08 12:46:10 dom0 qubesd[2604]:     raise self._exception
Oct 08 12:46:10 dom0 qubesd[2604]:   File "/usr/lib64/python3.5/asyncio/tasks.py", line 240, in _step
Oct 08 12:46:10 dom0 qubesd[2604]:     result = coro.send(None)
Oct 08 12:46:10 dom0 qubesd[2604]:   File "/usr/lib/python3.5/site-packages/qubes/api/admin.py", line 834, in vm_start
Oct 08 12:46:10 dom0 qubesd[2604]:     yield from self.dest.start()
Oct 08 12:46:10 dom0 qubesd[2604]:   File "/usr/lib/python3.5/site-packages/qubes/vm/dispvm.py", line 220, in start
Oct 08 12:46:10 dom0 qubesd[2604]:     yield from super(DispVM, self).start(**kwargs)
Oct 08 12:46:10 dom0 qubesd[2604]:   File "/usr/lib/python3.5/site-packages/qubes/vm/qubesvm.py", line 1071, in start
Oct 08 12:46:10 dom0 qubesd[2604]:     start_guid=start_guid)
Oct 08 12:46:10 dom0 qubesd[2604]:   File "/usr/lib/python3.5/site-packages/qubes/events.py", line 236, in fire_event_async
Oct 08 12:46:10 dom0 qubesd[2604]:     effect = task.result()
Oct 08 12:46:10 dom0 qubesd[2604]:   File "/usr/lib64/python3.5/asyncio/futures.py", line 294, in result
Oct 08 12:46:10 dom0 qubesd[2604]:     raise self._exception
Oct 08 12:46:10 dom0 qubesd[2604]:   File "/usr/lib64/python3.5/asyncio/tasks.py", line 240, in _step
Oct 08 12:46:10 dom0 qubesd[2604]:     result = coro.send(None)
Oct 08 12:46:10 dom0 qubesd[2604]:   File "/usr/lib/python3.5/site-packages/qubesusbproxy/core3ext.py", line 323, in on_domain_start
Oct 08 12:46:10 dom0 qubesd[2604]:     yield from self.on_device_attach_usb(vm, '', device, options={})
Oct 08 12:46:10 dom0 qubesd[2604]:   File "/usr/lib/python3.5/site-packages/qubesusbproxy/core3ext.py", line 286, in on_device_attach_usb
Oct 08 12:46:10 dom0 qubesd[2604]:     device.backend_domain.name))
Oct 08 12:46:10 dom0 qubesd[2604]: ValueError: list.remove(x): x not in list
Oct 08 12:46:10 dom0 qmemman.systemstate[2606]: do_balloon(memsize=577765376)
Oct 08 12:46:10 dom0 qmemman.systemstate[2606]: xenfree=76075050
Oct 08 12:46:10 dom0 qmemman.daemon.algo[2606]: balloon: dom 13 has actual memory 1273829046
Oct 08 12:46:10 dom0 qmemman.daemon.algo[2606]: balloon: dom 14 has actual memory 2602554615
Oct 08 12:46:10 dom0 qmemman.daemon.algo[2606]: balloon: dom 0 has actual memory 4294967296
Oct 08 12:46:10 dom0 qmemman.daemon.algo[2606]: balloon: dom 10 has actual memory 988126426
Oct 08 12:46:10 dom0 qmemman.daemon.algo[2606]: balloon: dom 7 has actual memory 1132991886
Oct 08 12:46:10 dom0 qmemman.daemon.algo[2606]: balloon: dom 11 has actual memory 1456120058
Oct 08 12:46:10 dom0 qmemman.daemon.algo[2606]: balloon: dom 8 has actual memory 1135500861
Oct 08 12:46:10 dom0 qmemman.daemon.algo[2606]: balloon: dom 3 has actual memory 1097977738
Oct 08 12:46:10 dom0 qmemman.daemon.algo[2606]: balloon: dom 9 has actual memory 1151669815
Oct 08 12:46:10 dom0 qmemman.daemon.algo[2606]: req=554119126 avail=9304896601.8 donors=[('13', 834458498.8), ('14', 1655592183.0), ('0', 2228688409.6), ('10', 657898304.4), ('7', 747423118.0), ('11', 947111776.4), ('8', 748973629.0), ('3', 725784867.5999999), ('9', 758965815.0)]
Oct 08 12:46:10 dom0 qmemman.daemon.algo[2606]: borrow 52177783.96874657 from 13
Oct 08 12:46:10 dom0 qmemman.daemon.algo[2606]: borrow 103522381.74714069 from 14
Oct 08 12:46:10 dom0 qmemman.daemon.algo[2606]: borrow 139357466.5929907 from 0
Oct 08 12:46:10 dom0 qmemman.daemon.algo[2606]: borrow 41137666.70212248 from 10
Oct 08 12:46:10 dom0 qmemman.daemon.algo[2606]: borrow 46735556.10055341 from 7
Oct 08 12:46:10 dom0 qmemman.daemon.algo[2606]: borrow 59221871.11081168 from 11
Oct 08 12:46:10 dom0 qmemman.daemon.algo[2606]: borrow 46832507.87001289 from 8
Oct 08 12:46:10 dom0 qmemman.daemon.algo[2606]: borrow 45382539.79542083 from 3
Oct 08 12:46:10 dom0 qmemman.daemon.algo[2606]: borrow 47457308.41220078 from 9
Oct 08 12:46:10 dom0 qmemman.systemstate[2606]: memset_reqs=[('13', 1221651262), ('14', 2499032233), ('0', 4155609829), ('10', 946988759), ('7', 1086256329), ('11', 1396898186), ('8', 1088668353), ('3', 1052595198), ('9', 1104212506)]
Oct 08 12:46:10 dom0 qmemman.systemstate[2606]: mem-set domain 13 to 1221651262
Oct 08 12:46:10 dom0 qmemman.systemstate[2606]: mem-set domain 14 to 2499032233
Oct 08 12:46:10 dom0 qmemman.systemstate[2606]: mem-set domain 0 to 4155609829
Oct 08 12:46:10 dom0 qmemman.systemstate[2606]: mem-set domain 10 to 946988759
Oct 08 12:46:10 dom0 qmemman.systemstate[2606]: mem-set domain 7 to 1086256329
Oct 08 12:46:10 dom0 qmemman.systemstate[2606]: mem-set domain 11 to 1396898186
Oct 08 12:46:10 dom0 qmemman.systemstate[2606]: mem-set domain 8 to 1088668353
Oct 08 12:46:10 dom0 qmemman.systemstate[2606]: mem-set domain 3 to 1052595198
Oct 08 12:46:10 dom0 qmemman.systemstate[2606]: mem-set domain 9 to 1104212506
Oct 08 12:46:10 dom0 qmemman.systemstate[2606]: xenfree=598123977
Oct 08 12:46:10 dom0 qmemman.daemon.algo[2606]: balloon: dom 13 has actual memory 1221651262
Oct 08 12:46:10 dom0 qmemman.daemon.algo[2606]: balloon: dom 14 has actual memory 2539573248
Oct 08 12:46:10 dom0 qmemman.daemon.algo[2606]: balloon: dom 0 has actual memory 4193615872
Oct 08 12:46:10 dom0 qmemman.daemon.algo[2606]: balloon: dom 10 has actual memory 946988759
Oct 08 12:46:10 dom0 qmemman.daemon.algo[2606]: balloon: dom 7 has actual memory 1086256329
Oct 08 12:46:10 dom0 qmemman.daemon.algo[2606]: balloon: dom 11 has actual memory 1401675776
Oct 08 12:46:10 dom0 qmemman.daemon.algo[2606]: balloon: dom 8 has actual memory 1088668353
Oct 08 12:46:10 dom0 qmemman.daemon.algo[2606]: balloon: dom 3 has actual memory 1052595198
Oct 08 12:46:10 dom0 qmemman.daemon.algo[2606]: balloon: dom 9 has actual memory 1104212506
Oct 08 12:46:10 dom0 qmemman.daemon.algo[2606]: req=32070199 avail=8806396163.8 donors=[('13', 782280714.8), ('14', 1592610816.0), ('0', 2127336985.6), ('10', 616760637.4), ('7', 700687561.0), ('11', 892667494.4), ('8', 702141121.0), ('3', 680402327.5999999), ('9', 711508506.0)]
Oct 08 12:46:10 dom0 qmemman.daemon.algo[2606]: borrow 2991268.2347470433 from 13
Oct 08 12:46:10 dom0 qmemman.daemon.algo[2606]: borrow 6089791.11217554 from 14
Oct 08 12:46:10 dom0 qmemman.daemon.algo[2606]: borrow 8134465.581520441 from 0
Oct 08 12:46:10 dom0 qmemman.daemon.algo[2606]: borrow 2358356.1095055635 from 10
Oct 08 12:46:10 dom0 qmemman.daemon.algo[2606]: borrow 2679274.0816032207 from 7
Oct 08 12:46:10 dom0 qmemman.daemon.algo[2606]: borrow 3413362.837242673 from 11
Oct 08 12:46:10 dom0 qmemman.daemon.algo[2606]: borrow 2684832.1731847203 from 8
Oct 08 12:46:10 dom0 qmemman.daemon.algo[2606]: borrow 2601707.840794941 from 3
Oct 08 12:46:11 dom0 qmemman.daemon.algo[2606]: borrow 2720650.9792258604 from 9
Oct 08 12:46:11 dom0 qmemman.systemstate[2606]: memset_reqs=[('13', 1218659993), ('14', 2533483456), ('0', 4185481406), ('10', 944630402), ('7', 1083577054), ('11', 1398262413), ('8', 1085983520), ('3', 1049993490), ('9', 1101491855)]
Oct 08 12:46:11 dom0 qmemman.systemstate[2606]: mem-set domain 13 to 1218659993
Oct 08 12:46:11 dom0 qmemman.systemstate[2606]: mem-set domain 14 to 2533483456
Oct 08 12:46:11 dom0 qmemman.systemstate[2606]: mem-set domain 0 to 4185481406
Oct 08 12:46:11 dom0 qmemman.systemstate[2606]: mem-set domain 10 to 944630402
Oct 08 12:46:11 dom0 qmemman.systemstate[2606]: mem-set domain 7 to 1083577054
Oct 08 12:46:11 dom0 qmemman.systemstate[2606]: mem-set domain 11 to 1398262413
Oct 08 12:46:11 dom0 qmemman.systemstate[2606]: mem-set domain 8 to 1085983520
Oct 08 12:46:11 dom0 qmemman.systemstate[2606]: mem-set domain 3 to 1049993490
Oct 08 12:46:11 dom0 qmemman.systemstate[2606]: mem-set domain 9 to 1101491855
Oct 08 12:46:11 dom0 qmemman.systemstate[2606]: xenfree=604439259
Oct 08 12:46:11 dom0 dmeventd[1172]: No longer monitoring thin pool qubes_dom0-pool00-tpool.
Oct 08 12:46:11 dom0 lvm[1172]: Monitoring thin pool qubes_dom0-pool00-tpool.
Oct 08 12:46:11 dom0 dmeventd[1172]: No longer monitoring thin pool qubes_dom0-pool00-tpool.
Oct 08 12:46:11 dom0 lvm[1172]: Monitoring thin pool qubes_dom0-pool00-tpool.
Oct 08 12:46:12 dom0 sudo[8258]: pam_unix(sudo:session): session closed for user root
Oct 08 12:46:12 dom0 audit[8258]: USER_END pid=8258 uid=0 auid=1000 ses=2 msg='op=PAM:session_close grantors=pam_keyinit,pam_limits,pam_keyinit,pam_limits,pam_systemd,pam_unix acct="root" exe="/usr/bin/sudo" hostname=? addr=? terminal=/dev/pts/0 res=success'
Oct 08 12:46:12 dom0 kernel: audit: type=1106 audit(1570553172.024:268): pid=8258 uid=0 auid=1000 ses=2 msg='op=PAM:session_close grantors=pam_keyinit,pam_limits,pam_keyinit,pam_limits,pam_systemd,pam_unix acct="root" exe="/usr/bin/sudo" hostname=? addr=? terminal=/dev/pts/0 res=success'
Oct 08 12:46:12 dom0 kernel: audit: type=1104 audit(1570553172.024:269): pid=8258 uid=0 auid=1000 ses=2 msg='op=PAM:setcred grantors=pam_env,pam_unix acct="root" exe="/usr/bin/sudo" hostname=? addr=? terminal=/dev/pts/0 res=success'
Oct 08 12:46:12 dom0 audit[8258]: CRED_DISP pid=8258 uid=0 auid=1000 ses=2 msg='op=PAM:setcred grantors=pam_env,pam_unix acct="root" exe="/usr/bin/sudo" hostname=? addr=? terminal=/dev/pts/0 res=success'
Oct 08 12:46:14 dom0 kernel: xen-blkback: backend/vbd/22/51712: using 1 queues, protocol 1 (x86_64-abi) persistent grants
Oct 08 12:46:14 dom0 kernel: xen-blkback: backend/vbd/22/51728: using 1 queues, protocol 1 (x86_64-abi) persistent grants
Oct 08 12:46:14 dom0 kernel: xen-blkback: backend/vbd/22/51744: using 1 queues, protocol 1 (x86_64-abi) persistent grants
Oct 08 12:46:14 dom0 qmemman.daemon.reqhandler[2606]: EOF
Oct 08 12:46:14 dom0 qubesd[2604]: Setting Qubes DB info for the VM
Oct 08 12:46:14 dom0 qubesd[2604]: Starting Qubes DB
Oct 08 12:46:14 dom0 audit[8696]: CRED_ACQ pid=8696 uid=0 auid=4294967295 ses=4294967295 msg='op=PAM:setcred grantors=pam_rootok acct="creviera" exe="/usr/sbin/runuser" hostname=? addr=? terminal=? res=success'
Oct 08 12:46:14 dom0 kernel: audit: type=1103 audit(1570553174.823:270): pid=8696 uid=0 auid=4294967295 ses=4294967295 msg='op=PAM:setcred grantors=pam_rootok acct="creviera" exe="/usr/sbin/runuser" hostname=? addr=? terminal=? res=success'
Oct 08 12:46:14 dom0 runuser[8696]: pam_unix(runuser:session): session opened for user creviera by (uid=0)
Oct 08 12:46:14 dom0 audit[8696]: USER_START pid=8696 uid=0 auid=4294967295 ses=4294967295 msg='op=PAM:session_open grantors=pam_keyinit,pam_limits,pam_unix acct="creviera" exe="/usr/sbin/runuser" hostname=? addr=? terminal=? res=success'
Oct 08 12:46:14 dom0 kernel: audit: type=1105 audit(1570553174.824:271): pid=8696 uid=0 auid=4294967295 ses=4294967295 msg='op=PAM:session_open grantors=pam_keyinit,pam_limits,pam_unix acct="creviera" exe="/usr/sbin/runuser" hostname=? addr=? terminal=? res=success'
Oct 08 12:46:14 dom0 qmemman.systemstate[2606]: Xen free = 153624893 too small for satisfy assignments! assigned_but_unused=151281093, domdict={'13': {'memory_actual': 1218659993, 'mem_used': 337977344, 'memory_maximum': 4194304000, 'last_target': 1218659993, 'slow_memset_react': False, 'memory_current': 1201815552, 'no_progress': False, 'id': '13'}, '0': {'memory_actual': 4185481406, 'mem_used': 1320214528, 'memory_maximum': 4294967296, 'last_target': 4185481406, 'slow_memset_react': False, 'memory_current': 4168704000, 'no_progress': False, 'id': '0'}, '6': {'memory_actual': 150994944, 'mem_used': None, 'memory_maximum': 150994944, 'last_target': 150994944, 'slow_memset_react': False, 'memory_current': 150994944, 'no_progress': False, 'id': '6'}, '7': {'memory_actual': 1083577054, 'mem_used': 296591360, 'memory_maximum': 4194304000, 'last_target': 1083577054, 'slow_memset_react': False, 'memory_current': 1066733568, 'no_progress': False, 'id': '7'}, '11': {'memory_actual': 1398262413, 'mem_used': 391544832, 'memory_maximum': 4194304000, 'last_target': 1398262413, 'slow_memset_react': False, 'memory_current': 1381564416, 'no_progress': False, 'id': '11'}, '10': {'memory_actual': 944630402, 'mem_used': 254021632, 'memory_maximum': 4194304000, 'last_target': 944630402, 'slow_memset_react': False, 'memory_current': 927784960, 'no_progress': False, 'id': '10'}, '8': {'memory_actual': 1085983520, 'mem_used': 297328640, 'memory_maximum': 4194304000, 'last_target': 1085983520, 'slow_memset_react': False, 'memory_current': 1069137920, 'no_progress': False, 'id': '8'}, '12': {'memory_actual': 150994944, 'mem_used': None, 'memory_maximum': 150994944, 'last_target': 150994944, 'slow_memset_react': False, 'memory_current': 150994944, 'no_progress': False, 'id': '12'}, '5': {'memory_actual': 297836544, 'mem_used': None, 'memory_maximum': 314572800, 'last_target': 297795584, 'slow_memset_react': False, 'memory_current': 297836544, 'no_progress': False, 'id': '5'}, '21': {'memory_actual': 419430400, 'mem_used': None, 'memory_maximum': 4194304000, 'last_target': 401604608, 'slow_memset_react': False, 'memory_current': 419430400, 'no_progress': False, 'id': '21'}, '4': {'memory_actual': 150994944, 'mem_used': None, 'memory_maximum': 150994944, 'last_target': 150994944, 'slow_memset_react': False, 'memory_current': 150994944, 'no_progress': False, 'id': '4'}, '14': {'memory_actual': 2533483456, 'mem_used': 728432640, 'memory_maximum': 4194304000, 'last_target': 2533483456, 'slow_memset_react': False, 'memory_current': 2516639744, 'no_progress': False, 'id': '14'}, '1': {'memory_actual': 402694144, 'mem_used': None, 'memory_maximum': 419430400, 'last_target': 402653184, 'slow_memset_react': False, 'memory_current': 402694144, 'no_progress': False, 'id': '1'}, '2': {'memory_actual': 150994944, 'mem_used': None, 'memory_maximum': 150994944, 'last_target': 150994944, 'slow_memset_react': False, 'memory_current': 150994944, 'no_progress': False, 'id': '2'}, '3': {'memory_actual': 1049993490, 'mem_used': 286302208, 'memory_maximum': 4194304000, 'last_target': 1049993490, 'slow_memset_react': False, 'memory_current': 1033256960, 'no_progress': False, 'id': '3'}, '9': {'memory_actual': 1101491855, 'mem_used': 302080000, 'memory_maximum': 4194304000, 'last_target': 1101491855, 'slow_memset_react': False, 'memory_current': 1084645376, 'no_progress': False, 'id': '9'}, '22': {'memory_actual': 150994944, 'mem_used': None, 'memory_maximum': 150994944, 'last_target': 150994944, 'slow_memset_react': False, 'memory_current': 150994944, 'no_progress': False, 'id': '22'}}
Oct 08 12:46:14 dom0 qmemman.daemon.algo[2606]: balance_when_enough_memory(xen_free_memory=-50085000, total_mem_pref=5828841139.199999, total_available_memory=8722637449.8)
Oct 08 12:46:14 dom0 qmemman.daemon.algo[2606]: left_memory=858260394 acceptors_count=8
Oct 08 12:46:14 dom0 qmemman.systemstate[2606]: stat: dom '13' act=1218659993 pref=439370547.2 last_target=1218659993
Oct 08 12:46:14 dom0 qmemman.systemstate[2606]: stat: dom '0' act=4185481406 pref=2066278886.4 last_target=4185481406
Oct 08 12:46:14 dom0 qmemman.systemstate[2606]: stat: dom '7' act=1083577054 pref=385568768.0 last_target=1083577054
Oct 08 12:46:14 dom0 qmemman.systemstate[2606]: stat: dom '11' act=1398262413 pref=509008281.6 last_target=1398262413
Oct 08 12:46:14 dom0 qmemman.systemstate[2606]: stat: dom '10' act=944630402 pref=330228121.6 last_target=944630402
Oct 08 12:46:14 dom0 qmemman.systemstate[2606]: stat: dom '8' act=1085983520 pref=386527232.0 last_target=1085983520
Oct 08 12:46:14 dom0 qmemman.systemstate[2606]: stat: dom '14' act=2533483456 pref=946962432.0 last_target=2533483456
Oct 08 12:46:14 dom0 qmemman.systemstate[2606]: stat: dom '3' act=1049993490 pref=372192870.40000004 last_target=1049993490
Oct 08 12:46:14 dom0 qmemman.systemstate[2606]: stat: dom '9' act=1101491855 pref=392704000.0 last_target=1101491855
Oct 08 12:46:14 dom0 qmemman.systemstate[2606]: stat: xenfree=2343800 memset_reqs=[('13', 1202950132), ('14', 2468866641), ('9', 1086565386), ('7', 1068770365), ('11', 1376624220), ('10', 930752717), ('8', 1071160741), ('3', 1035411342), ('0', 4294967296)]
Oct 08 12:46:14 dom0 qmemman.systemstate[2606]: Xen free = 153624893 too small for satisfy assignments! assigned_but_unused=151281093, domdict={'13': {'memory_actual': 1218659993, 'mem_used': 337977344, 'memory_maximum': 4194304000, 'last_target': 1218659993, 'slow_memset_react': False, 'memory_current': 1201815552, 'no_progress': False, 'id': '13'}, '0': {'memory_actual': 4185481406, 'mem_used': 1320214528, 'memory_maximum': 4294967296, 'last_target': 4185481406, 'slow_memset_react': False, 'memory_current': 4168704000, 'no_progress': False, 'id': '0'}, '6': {'memory_actual': 150994944, 'mem_used': None, 'memory_maximum': 150994944, 'last_target': 150994944, 'slow_memset_react': False, 'memory_current': 150994944, 'no_progress': False, 'id': '6'}, '7': {'memory_actual': 1083577054, 'mem_used': 296591360, 'memory_maximum': 4194304000, 'last_target': 1083577054, 'slow_memset_react': False, 'memory_current': 1066733568, 'no_progress': False, 'id': '7'}, '11': {'memory_actual': 1398262413, 'mem_used': 391544832, 'memory_maximum': 4194304000, 'last_target': 1398262413, 'slow_memset_react': False, 'memory_current': 1381564416, 'no_progress': False, 'id': '11'}, '10': {'memory_actual': 944630402, 'mem_used': 254021632, 'memory_maximum': 4194304000, 'last_target': 944630402, 'slow_memset_react': False, 'memory_current': 927784960, 'no_progress': False, 'id': '10'}, '8': {'memory_actual': 1085983520, 'mem_used': 297328640, 'memory_maximum': 4194304000, 'last_target': 1085983520, 'slow_memset_react': False, 'memory_current': 1069137920, 'no_progress': False, 'id': '8'}, '12': {'memory_actual': 150994944, 'mem_used': None, 'memory_maximum': 150994944, 'last_target': 150994944, 'slow_memset_react': False, 'memory_current': 150994944, 'no_progress': False, 'id': '12'}, '5': {'memory_actual': 297836544, 'mem_used': None, 'memory_maximum': 314572800, 'last_target': 297795584, 'slow_memset_react': False, 'memory_current': 297836544, 'no_progress': False, 'id': '5'}, '21': {'memory_actual': 419430400, 'mem_used': None, 'memory_maximum': 4194304000, 'last_target': 401604608, 'slow_memset_react': False, 'memory_current': 419430400, 'no_progress': False, 'id': '21'}, '4': {'memory_actual': 150994944, 'mem_used': None, 'memory_maximum': 150994944, 'last_target': 150994944, 'slow_memset_react': False, 'memory_current': 150994944, 'no_progress': False, 'id': '4'}, '14': {'memory_actual': 2533483456, 'mem_used': 728432640, 'memory_maximum': 4194304000, 'last_target': 2533483456, 'slow_memset_react': False, 'memory_current': 2516639744, 'no_progress': False, 'id': '14'}, '1': {'memory_actual': 402694144, 'mem_used': None, 'memory_maximum': 419430400, 'last_target': 402653184, 'slow_memset_react': False, 'memory_current': 402694144, 'no_progress': False, 'id': '1'}, '2': {'memory_actual': 150994944, 'mem_used': None, 'memory_maximum': 150994944, 'last_target': 150994944, 'slow_memset_react': False, 'memory_current': 150994944, 'no_progress': False, 'id': '2'}, '3': {'memory_actual': 1049993490, 'mem_used': 286302208, 'memory_maximum': 4194304000, 'last_target': 1049993490, 'slow_memset_react': False, 'memory_current': 1033256960, 'no_progress': False, 'id': '3'}, '9': {'memory_actual': 1101491855, 'mem_used': 302080000, 'memory_maximum': 4194304000, 'last_target': 1101491855, 'slow_memset_react': False, 'memory_current': 1084645376, 'no_progress': False, 'id': '9'}, '22': {'memory_actual': 150994944, 'mem_used': None, 'memory_maximum': 150994944, 'last_target': 150994944, 'slow_memset_react': False, 'memory_current': 150994944, 'no_progress': False, 'id': '22'}}
Oct 08 12:46:14 dom0 runuser[8696]: pam_unix(runuser:session): session closed for user creviera
Oct 08 12:46:14 dom0 audit[8696]: USER_END pid=8696 uid=0 auid=4294967295 ses=4294967295 msg='op=PAM:session_close grantors=pam_keyinit,pam_limits,pam_unix acct="creviera" exe="/usr/sbin/runuser" hostname=? addr=? terminal=? res=success'
Oct 08 12:46:14 dom0 audit[8696]: CRED_DISP pid=8696 uid=0 auid=4294967295 ses=4294967295 msg='op=PAM:setcred grantors=pam_rootok acct="creviera" exe="/usr/sbin/runuser" hostname=? addr=? terminal=? res=success'
Oct 08 12:46:14 dom0 kernel: audit: type=1106 audit(1570553174.870:272): pid=8696 uid=0 auid=4294967295 ses=4294967295 msg='op=PAM:session_close grantors=pam_keyinit,pam_limits,pam_unix acct="creviera" exe="/usr/sbin/runuser" hostname=? addr=? terminal=? res=success'
Oct 08 12:46:14 dom0 kernel: audit: type=1104 audit(1570553174.870:273): pid=8696 uid=0 auid=4294967295 ses=4294967295 msg='op=PAM:setcred grantors=pam_rootok acct="creviera" exe="/usr/sbin/runuser" hostname=? addr=? terminal=? res=success'
Oct 08 12:46:14 dom0 qubesd[2604]: Activating the sd-export-usb VM
Oct 08 12:46:14 dom0 kernel: audit: type=1103 audit(1570553174.893:274): pid=8699 uid=0 auid=4294967295 ses=4294967295 msg='op=PAM:setcred grantors=pam_rootok acct="creviera" exe="/usr/sbin/runuser" hostname=? addr=? terminal=? res=success'
Oct 08 12:46:14 dom0 kernel: audit: type=1105 audit(1570553174.893:275): pid=8699 uid=0 auid=4294967295 ses=4294967295 msg='op=PAM:session_open grantors=pam_keyinit,pam_limits,pam_unix acct="creviera" exe="/usr/sbin/runuser" hostname=? addr=? terminal=? res=success'
Oct 08 12:46:14 dom0 audit[8699]: CRED_ACQ pid=8699 uid=0 auid=4294967295 ses=4294967295 msg='op=PAM:setcred grantors=pam_rootok acct="creviera" exe="/usr/sbin/runuser" hostname=? addr=? terminal=? res=success'
Oct 08 12:46:14 dom0 audit[8699]: USER_START pid=8699 uid=0 auid=4294967295 ses=4294967295 msg='op=PAM:session_open grantors=pam_keyinit,pam_limits,pam_unix acct="creviera" exe="/usr/sbin/runuser" hostname=? addr=? terminal=? res=success'
Oct 08 12:46:14 dom0 runuser[8699]: pam_unix(runuser:session): session opened for user creviera by (uid=0)
Oct 08 12:46:14 dom0 qmemman.systemstate[2606]: Xen free = 153624893 too small for satisfy assignments! assigned_but_unused=151281093, domdict={'13': {'memory_actual': 1218659993, 'mem_used': 337977344, 'memory_maximum': 4194304000, 'last_target': 1218659993, 'slow_memset_react': False, 'memory_current': 1201815552, 'no_progress': False, 'id': '13'}, '0': {'memory_actual': 4185481406, 'mem_used': 1320214528, 'memory_maximum': 4294967296, 'last_target': 4185481406, 'slow_memset_react': False, 'memory_current': 4168704000, 'no_progress': False, 'id': '0'}, '6': {'memory_actual': 150994944, 'mem_used': None, 'memory_maximum': 150994944, 'last_target': 150994944, 'slow_memset_react': False, 'memory_current': 150994944, 'no_progress': False, 'id': '6'}, '7': {'memory_actual': 1083577054, 'mem_used': 296591360, 'memory_maximum': 4194304000, 'last_target': 1083577054, 'slow_memset_react': False, 'memory_current': 1066733568, 'no_progress': False, 'id': '7'}, '11': {'memory_actual': 1398262413, 'mem_used': 391544832, 'memory_maximum': 4194304000, 'last_target': 1398262413, 'slow_memset_react': False, 'memory_current': 1381564416, 'no_progress': False, 'id': '11'}, '10': {'memory_actual': 944630402, 'mem_used': 254021632, 'memory_maximum': 4194304000, 'last_target': 944630402, 'slow_memset_react': False, 'memory_current': 927784960, 'no_progress': False, 'id': '10'}, '8': {'memory_actual': 1085983520, 'mem_used': 297328640, 'memory_maximum': 4194304000, 'last_target': 1085983520, 'slow_memset_react': False, 'memory_current': 1069137920, 'no_progress': False, 'id': '8'}, '12': {'memory_actual': 150994944, 'mem_used': None, 'memory_maximum': 150994944, 'last_target': 150994944, 'slow_memset_react': False, 'memory_current': 150994944, 'no_progress': False, 'id': '12'}, '5': {'memory_actual': 297836544, 'mem_used': None, 'memory_maximum': 314572800, 'last_target': 297795584, 'slow_memset_react': False, 'memory_current': 297836544, 'no_progress': False, 'id': '5'}, '21': {'memory_actual': 419430400, 'mem_used': None, 'memory_maximum': 4194304000, 'last_target': 401604608, 'slow_memset_react': False, 'memory_current': 419430400, 'no_progress': False, 'id': '21'}, '4': {'memory_actual': 150994944, 'mem_used': None, 'memory_maximum': 150994944, 'last_target': 150994944, 'slow_memset_react': False, 'memory_current': 150994944, 'no_progress': False, 'id': '4'}, '14': {'memory_actual': 2533483456, 'mem_used': 728432640, 'memory_maximum': 4194304000, 'last_target': 2533483456, 'slow_memset_react': False, 'memory_current': 2516639744, 'no_progress': False, 'id': '14'}, '1': {'memory_actual': 402694144, 'mem_used': None, 'memory_maximum': 419430400, 'last_target': 402653184, 'slow_memset_react': False, 'memory_current': 402694144, 'no_progress': False, 'id': '1'}, '2': {'memory_actual': 150994944, 'mem_used': None, 'memory_maximum': 150994944, 'last_target': 150994944, 'slow_memset_react': False, 'memory_current': 150994944, 'no_progress': False, 'id': '2'}, '3': {'memory_actual': 1049993490, 'mem_used': 286302208, 'memory_maximum': 4194304000, 'last_target': 1049993490, 'slow_memset_react': False, 'memory_current': 1033256960, 'no_progress': False, 'id': '3'}, '9': {'memory_actual': 1101491855, 'mem_used': 302080000, 'memory_maximum': 4194304000, 'last_target': 1101491855, 'slow_memset_react': False, 'memory_current': 1084645376, 'no_progress': False, 'id': '9'}, '22': {'memory_actual': 150994944, 'mem_used': None, 'memory_maximum': 150994944, 'last_target': 150994944, 'slow_memset_react': False, 'memory_current': 150994944, 'no_progress': False, 'id': '22'}}
Oct 08 12:46:15 dom0 qmemman.systemstate[2606]: Xen free = 153580186 too small for satisfy assignments! assigned_but_unused=151281093, domdict={'13': {'memory_actual': 1218659993, 'mem_used': 337977344, 'memory_maximum': 4194304000, 'last_target': 1218659993, 'slow_memset_react': False, 'memory_current': 1201815552, 'no_progress': False, 'id': '13'}, '0': {'memory_actual': 4185481406, 'mem_used': 1320214528, 'memory_maximum': 4294967296, 'last_target': 4185481406, 'slow_memset_react': False, 'memory_current': 4168704000, 'no_progress': False, 'id': '0'}, '6': {'memory_actual': 150994944, 'mem_used': None, 'memory_maximum': 150994944, 'last_target': 150994944, 'slow_memset_react': False, 'memory_current': 150994944, 'no_progress': False, 'id': '6'}, '7': {'memory_actual': 1083577054, 'mem_used': 296591360, 'memory_maximum': 4194304000, 'last_target': 1083577054, 'slow_memset_react': False, 'memory_current': 1066733568, 'no_progress': False, 'id': '7'}, '11': {'memory_actual': 1398262413, 'mem_used': 391544832, 'memory_maximum': 4194304000, 'last_target': 1398262413, 'slow_memset_react': False, 'memory_current': 1381564416, 'no_progress': False, 'id': '11'}, '10': {'memory_actual': 944630402, 'mem_used': 254021632, 'memory_maximum': 4194304000, 'last_target': 944630402, 'slow_memset_react': False, 'memory_current': 927784960, 'no_progress': False, 'id': '10'}, '8': {'memory_actual': 1085983520, 'mem_used': 297328640, 'memory_maximum': 4194304000, 'last_target': 1085983520, 'slow_memset_react': False, 'memory_current': 1069137920, 'no_progress': False, 'id': '8'}, '12': {'memory_actual': 150994944, 'mem_used': None, 'memory_maximum': 150994944, 'last_target': 150994944, 'slow_memset_react': False, 'memory_current': 150994944, 'no_progress': False, 'id': '12'}, '5': {'memory_actual': 297836544, 'mem_used': None, 'memory_maximum': 314572800, 'last_target': 297795584, 'slow_memset_react': False, 'memory_current': 297836544, 'no_progress': False, 'id': '5'}, '21': {'memory_actual': 419475456, 'mem_used': None, 'memory_maximum': 4194304000, 'last_target': 401604608, 'slow_memset_react': False, 'memory_current': 419475456, 'no_progress': False, 'id': '21'}, '4': {'memory_actual': 150994944, 'mem_used': None, 'memory_maximum': 150994944, 'last_target': 150994944, 'slow_memset_react': False, 'memory_current': 150994944, 'no_progress': False, 'id': '4'}, '14': {'memory_actual': 2533483456, 'mem_used': 728432640, 'memory_maximum': 4194304000, 'last_target': 2533483456, 'slow_memset_react': False, 'memory_current': 2516639744, 'no_progress': False, 'id': '14'}, '1': {'memory_actual': 402694144, 'mem_used': None, 'memory_maximum': 419430400, 'last_target': 402653184, 'slow_memset_react': False, 'memory_current': 402694144, 'no_progress': False, 'id': '1'}, '2': {'memory_actual': 150994944, 'mem_used': None, 'memory_maximum': 150994944, 'last_target': 150994944, 'slow_memset_react': False, 'memory_current': 150994944, 'no_progress': False, 'id': '2'}, '3': {'memory_actual': 1049993490, 'mem_used': 286302208, 'memory_maximum': 4194304000, 'last_target': 1049993490, 'slow_memset_react': False, 'memory_current': 1033256960, 'no_progress': False, 'id': '3'}, '9': {'memory_actual': 1101491855, 'mem_used': 302080000, 'memory_maximum': 4194304000, 'last_target': 1101491855, 'slow_memset_react': False, 'memory_current': 1084645376, 'no_progress': False, 'id': '9'}, '22': {'memory_actual': 150994944, 'mem_used': None, 'memory_maximum': 150994944, 'last_target': 150994944, 'slow_memset_react': False, 'memory_current': 150994944, 'no_progress': False, 'id': '22'}}
Oct 08 12:46:15 dom0 qmemman.systemstate[2606]: Xen free = 153580186 too small for satisfy assignments! assigned_but_unused=151281093, domdict={'13': {'memory_actual': 1218659993, 'mem_used': 337977344, 'memory_maximum': 4194304000, 'last_target': 1218659993, 'slow_memset_react': False, 'memory_current': 1201815552, 'no_progress': False, 'id': '13'}, '0': {'memory_actual': 4185481406, 'mem_used': 1320214528, 'memory_maximum': 4294967296, 'last_target': 4185481406, 'slow_memset_react': False, 'memory_current': 4168704000, 'no_progress': False, 'id': '0'}, '6': {'memory_actual': 150994944, 'mem_used': None, 'memory_maximum': 150994944, 'last_target': 150994944, 'slow_memset_react': False, 'memory_current': 150994944, 'no_progress': False, 'id': '6'}, '7': {'memory_actual': 1083577054, 'mem_used': 296591360, 'memory_maximum': 4194304000, 'last_target': 1083577054, 'slow_memset_react': False, 'memory_current': 1066733568, 'no_progress': False, 'id': '7'}, '11': {'memory_actual': 1398262413, 'mem_used': 391544832, 'memory_maximum': 4194304000, 'last_target': 1398262413, 'slow_memset_react': False, 'memory_current': 1381564416, 'no_progress': False, 'id': '11'}, '10': {'memory_actual': 944630402, 'mem_used': 254021632, 'memory_maximum': 4194304000, 'last_target': 944630402, 'slow_memset_react': False, 'memory_current': 927784960, 'no_progress': False, 'id': '10'}, '8': {'memory_actual': 1085983520, 'mem_used': 297328640, 'memory_maximum': 4194304000, 'last_target': 1085983520, 'slow_memset_react': False, 'memory_current': 1069137920, 'no_progress': False, 'id': '8'}, '12': {'memory_actual': 150994944, 'mem_used': None, 'memory_maximum': 150994944, 'last_target': 150994944, 'slow_memset_react': False, 'memory_current': 150994944, 'no_progress': False, 'id': '12'}, '5': {'memory_actual': 297836544, 'mem_used': None, 'memory_maximum': 314572800, 'last_target': 297795584, 'slow_memset_react': False, 'memory_current': 297836544, 'no_progress': False, 'id': '5'}, '21': {'memory_actual': 419475456, 'mem_used': None, 'memory_maximum': 4194304000, 'last_target': 401604608, 'slow_memset_react': False, 'memory_current': 419475456, 'no_progress': False, 'id': '21'}, '4': {'memory_actual': 150994944, 'mem_used': None, 'memory_maximum': 150994944, 'last_target': 150994944, 'slow_memset_react': False, 'memory_current': 150994944, 'no_progress': False, 'id': '4'}, '14': {'memory_actual': 2533483456, 'mem_used': 728432640, 'memory_maximum': 4194304000, 'last_target': 2533483456, 'slow_memset_react': False, 'memory_current': 2516639744, 'no_progress': False, 'id': '14'}, '1': {'memory_actual': 402694144, 'mem_used': None, 'memory_maximum': 419430400, 'last_target': 402653184, 'slow_memset_react': False, 'memory_current': 402694144, 'no_progress': False, 'id': '1'}, '2': {'memory_actual': 150994944, 'mem_used': None, 'memory_maximum': 150994944, 'last_target': 150994944, 'slow_memset_react': False, 'memory_current': 150994944, 'no_progress': False, 'id': '2'}, '3': {'memory_actual': 1049993490, 'mem_used': 286302208, 'memory_maximum': 4194304000, 'last_target': 1049993490, 'slow_memset_react': False, 'memory_current': 1033256960, 'no_progress': False, 'id': '3'}, '9': {'memory_actual': 1101491855, 'mem_used': 302080000, 'memory_maximum': 4194304000, 'last_target': 1101491855, 'slow_memset_react': False, 'memory_current': 1084645376, 'no_progress': False, 'id': '9'}, '22': {'memory_actual': 150994944, 'mem_used': None, 'memory_maximum': 150994944, 'last_target': 150994944, 'slow_memset_react': False, 'memory_current': 150994944, 'no_progress': False, 'id': '22'}}
Oct 08 12:46:15 dom0 qmemman.systemstate[2606]: Xen free = 153580186 too small for satisfy assignments! assigned_but_unused=151281093, domdict={'13': {'memory_actual': 1218659993, 'mem_used': 337977344, 'memory_maximum': 4194304000, 'last_target': 1218659993, 'slow_memset_react': False, 'memory_current': 1201815552, 'no_progress': False, 'id': '13'}, '0': {'memory_actual': 4185481406, 'mem_used': 1320214528, 'memory_maximum': 4294967296, 'last_target': 4185481406, 'slow_memset_react': False, 'memory_current': 4168704000, 'no_progress': False, 'id': '0'}, '6': {'memory_actual': 150994944, 'mem_used': None, 'memory_maximum': 150994944, 'last_target': 150994944, 'slow_memset_react': False, 'memory_current': 150994944, 'no_progress': False, 'id': '6'}, '7': {'memory_actual': 1083577054, 'mem_used': 296591360, 'memory_maximum': 4194304000, 'last_target': 1083577054, 'slow_memset_react': False, 'memory_current': 1066733568, 'no_progress': False, 'id': '7'}, '11': {'memory_actual': 1398262413, 'mem_used': 391544832, 'memory_maximum': 4194304000, 'last_target': 1398262413, 'slow_memset_react': False, 'memory_current': 1381564416, 'no_progress': False, 'id': '11'}, '10': {'memory_actual': 944630402, 'mem_used': 254021632, 'memory_maximum': 4194304000, 'last_target': 944630402, 'slow_memset_react': False, 'memory_current': 927784960, 'no_progress': False, 'id': '10'}, '8': {'memory_actual': 1085983520, 'mem_used': 297328640, 'memory_maximum': 4194304000, 'last_target': 1085983520, 'slow_memset_react': False, 'memory_current': 1069137920, 'no_progress': False, 'id': '8'}, '12': {'memory_actual': 150994944, 'mem_used': None, 'memory_maximum': 150994944, 'last_target': 150994944, 'slow_memset_react': False, 'memory_current': 150994944, 'no_progress': False, 'id': '12'}, '5': {'memory_actual': 297836544, 'mem_used': None, 'memory_maximum': 314572800, 'last_target': 297795584, 'slow_memset_react': False, 'memory_current': 297836544, 'no_progress': False, 'id': '5'}, '21': {'memory_actual': 419475456, 'mem_used': None, 'memory_maximum': 4194304000, 'last_target': 401604608, 'slow_memset_react': False, 'memory_current': 419475456, 'no_progress': False, 'id': '21'}, '4': {'memory_actual': 150994944, 'mem_used': None, 'memory_maximum': 150994944, 'last_target': 150994944, 'slow_memset_react': False, 'memory_current': 150994944, 'no_progress': False, 'id': '4'}, '14': {'memory_actual': 2533483456, 'mem_used': 728432640, 'memory_maximum': 4194304000, 'last_target': 2533483456, 'slow_memset_react': False, 'memory_current': 2516639744, 'no_progress': False, 'id': '14'}, '1': {'memory_actual': 402694144, 'mem_used': None, 'memory_maximum': 419430400, 'last_target': 402653184, 'slow_memset_react': False, 'memory_current': 402694144, 'no_progress': False, 'id': '1'}, '2': {'memory_actual': 150994944, 'mem_used': None, 'memory_maximum': 150994944, 'last_target': 150994944, 'slow_memset_react': False, 'memory_current': 150994944, 'no_progress': False, 'id': '2'}, '3': {'memory_actual': 1049993490, 'mem_used': 286302208, 'memory_maximum': 4194304000, 'last_target': 1049993490, 'slow_memset_react': False, 'memory_current': 1033256960, 'no_progress': False, 'id': '3'}, '9': {'memory_actual': 1101491855, 'mem_used': 302080000, 'memory_maximum': 4194304000, 'last_target': 1101491855, 'slow_memset_react': False, 'memory_current': 1084645376, 'no_progress': False, 'id': '9'}, '22': {'memory_actual': 150994944, 'mem_used': None, 'memory_maximum': 150994944, 'last_target': 150994944, 'slow_memset_react': False, 'memory_current': 150994944, 'no_progress': False, 'id': '22'}}
Oct 08 12:46:15 dom0 qmemman.systemstate[2606]: Xen free = 153580186 too small for satisfy assignments! assigned_but_unused=151281093, domdict={'13': {'memory_actual': 1218659993, 'mem_used': 337977344, 'memory_maximum': 4194304000, 'last_target': 1218659993, 'slow_memset_react': False, 'memory_current': 1201815552, 'no_progress': False, 'id': '13'}, '0': {'memory_actual': 4185481406, 'mem_used': 1320214528, 'memory_maximum': 4294967296, 'last_target': 4185481406, 'slow_memset_react': False, 'memory_current': 4168704000, 'no_progress': False, 'id': '0'}, '6': {'memory_actual': 150994944, 'mem_used': None, 'memory_maximum': 150994944, 'last_target': 150994944, 'slow_memset_react': False, 'memory_current': 150994944, 'no_progress': False, 'id': '6'}, '7': {'memory_actual': 1083577054, 'mem_used': 296591360, 'memory_maximum': 4194304000, 'last_target': 1083577054, 'slow_memset_react': False, 'memory_current': 1066733568, 'no_progress': False, 'id': '7'}, '11': {'memory_actual': 1398262413, 'mem_used': 391544832, 'memory_maximum': 4194304000, 'last_target': 1398262413, 'slow_memset_react': False, 'memory_current': 1381564416, 'no_progress': False, 'id': '11'}, '10': {'memory_actual': 944630402, 'mem_used': 254021632, 'memory_maximum': 4194304000, 'last_target': 944630402, 'slow_memset_react': False, 'memory_current': 927784960, 'no_progress': False, 'id': '10'}, '8': {'memory_actual': 1085983520, 'mem_used': 297328640, 'memory_maximum': 4194304000, 'last_target': 1085983520, 'slow_memset_react': False, 'memory_current': 1069137920, 'no_progress': False, 'id': '8'}, '12': {'memory_actual': 150994944, 'mem_used': None, 'memory_maximum': 150994944, 'last_target': 150994944, 'slow_memset_react': False, 'memory_current': 150994944, 'no_progress': False, 'id': '12'}, '5': {'memory_actual': 297836544, 'mem_used': None, 'memory_maximum': 314572800, 'last_target': 297795584, 'slow_memset_react': False, 'memory_current': 297836544, 'no_progress': False, 'id': '5'}, '21': {'memory_actual': 419475456, 'mem_used': None, 'memory_maximum': 4194304000, 'last_target': 401604608, 'slow_memset_react': False, 'memory_current': 419475456, 'no_progress': False, 'id': '21'}, '4': {'memory_actual': 150994944, 'mem_used': None, 'memory_maximum': 150994944, 'last_target': 150994944, 'slow_memset_react': False, 'memory_current': 150994944, 'no_progress': False, 'id': '4'}, '14': {'memory_actual': 2533483456, 'mem_used': 728432640, 'memory_maximum': 4194304000, 'last_target': 2533483456, 'slow_memset_react': False, 'memory_current': 2516639744, 'no_progress': False, 'id': '14'}, '1': {'memory_actual': 402694144, 'mem_used': None, 'memory_maximum': 419430400, 'last_target': 402653184, 'slow_memset_react': False, 'memory_current': 402694144, 'no_progress': False, 'id': '1'}, '2': {'memory_actual': 150994944, 'mem_used': None, 'memory_maximum': 150994944, 'last_target': 150994944, 'slow_memset_react': False, 'memory_current': 150994944, 'no_progress': False, 'id': '2'}, '3': {'memory_actual': 1049993490, 'mem_used': 286302208, 'memory_maximum': 4194304000, 'last_target': 1049993490, 'slow_memset_react': False, 'memory_current': 1033256960, 'no_progress': False, 'id': '3'}, '9': {'memory_actual': 1101491855, 'mem_used': 302080000, 'memory_maximum': 4194304000, 'last_target': 1101491855, 'slow_memset_react': False, 'memory_current': 1084645376, 'no_progress': False, 'id': '9'}, '22': {'memory_actual': 150994944, 'mem_used': None, 'memory_maximum': 150994944, 'last_target': 150994944, 'slow_memset_react': False, 'memory_current': 150994944, 'no_progress': False, 'id': '22'}}
Oct 08 12:46:15 dom0 qmemman.systemstate[2606]: mem-set domain 13 to 1168530286
Oct 08 12:46:15 dom0 qmemman.systemstate[2606]: Xen free = 155661086 too small for satisfy assignments! assigned_but_unused=134436652, domdict={'13': {'memory_actual': 1200599040, 'mem_used': 337977344, 'memory_maximum': 4194304000, 'last_target': 1168530286, 'slow_memset_react': False, 'memory_current': 1200599040, 'no_progress': False, 'id': '13'}, '0': {'memory_actual': 4185481406, 'mem_used': 1320214528, 'memory_maximum': 4294967296, 'last_target': 4185481406, 'slow_memset_react': False, 'memory_current': 4168704000, 'no_progress': False, 'id': '0'}, '6': {'memory_actual': 150994944, 'mem_used': None, 'memory_maximum': 150994944, 'last_target': 150994944, 'slow_memset_react': False, 'memory_current': 150994944, 'no_progress': False, 'id': '6'}, '7': {'memory_actual': 1083577054, 'mem_used': 296591360, 'memory_maximum': 4194304000, 'last_target': 1083577054, 'slow_memset_react': False, 'memory_current': 1066733568, 'no_progress': False, 'id': '7'}, '11': {'memory_actual': 1398262413, 'mem_used': 391544832, 'memory_maximum': 4194304000, 'last_target': 1398262413, 'slow_memset_react': False, 'memory_current': 1381564416, 'no_progress': False, 'id': '11'}, '10': {'memory_actual': 944630402, 'mem_used': 254021632, 'memory_maximum': 4194304000, 'last_target': 944630402, 'slow_memset_react': False, 'memory_current': 927784960, 'no_progress': False, 'id': '10'}, '8': {'memory_actual': 1085983520, 'mem_used': 297328640, 'memory_maximum': 4194304000, 'last_target': 1085983520, 'slow_memset_react': False, 'memory_current': 1069137920, 'no_progress': False, 'id': '8'}, '12': {'memory_actual': 150994944, 'mem_used': None, 'memory_maximum': 150994944, 'last_target': 150994944, 'slow_memset_react': False, 'memory_current': 150994944, 'no_progress': False, 'id': '12'}, '5': {'memory_actual': 297836544, 'mem_used': None, 'memory_maximum': 314572800, 'last_target': 297795584, 'slow_memset_react': False, 'memory_current': 297836544, 'no_progress': False, 'id': '5'}, '21': {'memory_actual': 419475456, 'mem_used': None, 'memory_maximum': 4194304000, 'last_target': 401604608, 'slow_memset_react': False, 'memory_current': 419475456, 'no_progress': False, 'id': '21'}, '4': {'memory_actual': 150994944, 'mem_used': None, 'memory_maximum': 150994944, 'last_target': 150994944, 'slow_memset_react': False, 'memory_current': 150994944, 'no_progress': False, 'id': '4'}, '14': {'memory_actual': 2533483456, 'mem_used': 760205312, 'memory_maximum': 4194304000, 'last_target': 2533483456, 'slow_memset_react': False, 'memory_current': 2516639744, 'no_progress': False, 'id': '14'}, '1': {'memory_actual': 402694144, 'mem_used': None, 'memory_maximum': 419430400, 'last_target': 402653184, 'slow_memset_react': False, 'memory_current': 402694144, 'no_progress': False, 'id': '1'}, '2': {'memory_actual': 150994944, 'mem_used': None, 'memory_maximum': 150994944, 'last_target': 150994944, 'slow_memset_react': False, 'memory_current': 150994944, 'no_progress': False, 'id': '2'}, '3': {'memory_actual': 1049993490, 'mem_used': 286302208, 'memory_maximum': 4194304000, 'last_target': 1049993490, 'slow_memset_react': False, 'memory_current': 1033256960, 'no_progress': False, 'id': '3'}, '9': {'memory_actual': 1101491855, 'mem_used': 302080000, 'memory_maximum': 4194304000, 'last_target': 1101491855, 'slow_memset_react': False, 'memory_current': 1084645376, 'no_progress': False, 'id': '9'}, '22': {'memory_actual': 150994944, 'mem_used': None, 'memory_maximum': 150994944, 'last_target': 150994944, 'slow_memset_react': False, 'memory_current': 150994944, 'no_progress': False, 'id': '22'}}
Oct 08 12:46:15 dom0 qmemman.daemon.algo[2606]: balance_when_enough_memory(xen_free_memory=-31204366, total_mem_pref=5870145612.799999, total_available_memory=8682152657.199999)
Oct 08 12:46:15 dom0 qmemman.daemon.algo[2606]: left_memory=822288652 acceptors_count=8
Oct 08 12:46:15 dom0 qmemman.systemstate[2606]: stat: dom '13' act=1200599040 pref=439370547.2 last_target=1168530286
Oct 08 12:46:15 dom0 qmemman.systemstate[2606]: stat: dom '0' act=4185481406 pref=2066278886.4 last_target=4185481406
Oct 08 12:46:15 dom0 qmemman.systemstate[2606]: stat: dom '7' act=1083577054 pref=385568768.0 last_target=1083577054
Oct 08 12:46:15 dom0 qmemman.systemstate[2606]: stat: dom '11' act=1398262413 pref=509008281.6 last_target=1398262413
Oct 08 12:46:15 dom0 qmemman.systemstate[2606]: stat: dom '10' act=944630402 pref=330228121.6 last_target=944630402
Oct 08 12:46:15 dom0 qmemman.systemstate[2606]: stat: dom '8' act=1085983520 pref=386527232.0 last_target=1085983520
Oct 08 12:46:15 dom0 qmemman.systemstate[2606]: stat: dom '14' act=2533483456 pref=988266905.6 last_target=2533483456
Oct 08 12:46:15 dom0 qmemman.systemstate[2606]: stat: dom '3' act=1049993490 pref=372192870.40000004 last_target=1049993490
Oct 08 12:46:15 dom0 qmemman.systemstate[2606]: stat: dom '9' act=1101491855 pref=392704000.0 last_target=1101491855
Oct 08 12:46:15 dom0 qmemman.systemstate[2606]: stat: xenfree=21224434 memset_reqs=[('13', 1190809182), ('9', 1075236851), ('7', 1057566048), ('11', 1363270950), ('10', 920511822), ('8', 1059939738), ('3', 1024439884), ('14', 2550181904), ('0', 4294967296)]
Oct 08 12:46:15 dom0 qmemman.systemstate[2606]: Xen free = 155661086 too small for satisfy assignments! assigned_but_unused=134436652, domdict={'13': {'memory_actual': 1200599040, 'mem_used': 337977344, 'memory_maximum': 4194304000, 'last_target': 1168530286, 'slow_memset_react': False, 'memory_current': 1200599040, 'no_progress': False, 'id': '13'}, '0': {'memory_actual': 4185481406, 'mem_used': 1320214528, 'memory_maximum': 4294967296, 'last_target': 4185481406, 'slow_memset_react': False, 'memory_current': 4168704000, 'no_progress': False, 'id': '0'}, '6': {'memory_actual': 150994944, 'mem_used': None, 'memory_maximum': 150994944, 'last_target': 150994944, 'slow_memset_react': False, 'memory_current': 150994944, 'no_progress': False, 'id': '6'}, '7': {'memory_actual': 1083577054, 'mem_used': 296591360, 'memory_maximum': 4194304000, 'last_target': 1083577054, 'slow_memset_react': False, 'memory_current': 1066733568, 'no_progress': False, 'id': '7'}, '11': {'memory_actual': 1398262413, 'mem_used': 391544832, 'memory_maximum': 4194304000, 'last_target': 1398262413, 'slow_memset_react': False, 'memory_current': 1381564416, 'no_progress': False, 'id': '11'}, '10': {'memory_actual': 944630402, 'mem_used': 254021632, 'memory_maximum': 4194304000, 'last_target': 944630402, 'slow_memset_react': False, 'memory_current': 927784960, 'no_progress': False, 'id': '10'}, '8': {'memory_actual': 1085983520, 'mem_used': 297328640, 'memory_maximum': 4194304000, 'last_target': 1085983520, 'slow_memset_react': False, 'memory_current': 1069137920, 'no_progress': False, 'id': '8'}, '12': {'memory_actual': 150994944, 'mem_used': None, 'memory_maximum': 150994944, 'last_target': 150994944, 'slow_memset_react': False, 'memory_current': 150994944, 'no_progress': False, 'id': '12'}, '5': {'memory_actual': 297836544, 'mem_used': None, 'memory_maximum': 314572800, 'last_target': 297795584, 'slow_memset_react': False, 'memory_current': 297836544, 'no_progress': False, 'id': '5'}, '21': {'memory_actual': 419475456, 'mem_used': None, 'memory_maximum': 4194304000, 'last_target': 401604608, 'slow_memset_react': False, 'memory_current': 419475456, 'no_progress': False, 'id': '21'}, '4': {'memory_actual': 150994944, 'mem_used': None, 'memory_maximum': 150994944, 'last_target': 150994944, 'slow_memset_react': False, 'memory_current': 150994944, 'no_progress': False, 'id': '4'}, '14': {'memory_actual': 2533483456, 'mem_used': 760205312, 'memory_maximum': 4194304000, 'last_target': 2533483456, 'slow_memset_react': False, 'memory_current': 2516639744, 'no_progress': False, 'id': '14'}, '1': {'memory_actual': 402694144, 'mem_used': None, 'memory_maximum': 419430400, 'last_target': 402653184, 'slow_memset_react': False, 'memory_current': 402694144, 'no_progress': False, 'id': '1'}, '2': {'memory_actual': 150994944, 'mem_used': None, 'memory_maximum': 150994944, 'last_target': 150994944, 'slow_memset_react': False, 'memory_current': 150994944, 'no_progress': False, 'id': '2'}, '3': {'memory_actual': 1049993490, 'mem_used': 286302208, 'memory_maximum': 4194304000, 'last_target': 1049993490, 'slow_memset_react': False, 'memory_current': 1033256960, 'no_progress': False, 'id': '3'}, '9': {'memory_actual': 1101491855, 'mem_used': 302080000, 'memory_maximum': 4194304000, 'last_target': 1101491855, 'slow_memset_react': False, 'memory_current': 1084645376, 'no_progress': False, 'id': '9'}, '22': {'memory_actual': 150994944, 'mem_used': None, 'memory_maximum': 150994944, 'last_target': 150994944, 'slow_memset_react': False, 'memory_current': 150994944, 'no_progress': False, 'id': '22'}}
Oct 08 12:46:15 dom0 qmemman.systemstate[2606]: mem-set domain 13 to 1168141798
Oct 08 12:46:15 dom0 qmemman.daemon.algo[2606]: balance_when_enough_memory(xen_free_memory=-3016, total_mem_pref=5870145612.799999, total_available_memory=8680896765.199999)
Oct 08 12:46:15 dom0 qmemman.daemon.algo[2606]: left_memory=821847023 acceptors_count=8
Oct 08 12:46:15 dom0 qmemman.systemstate[2606]: stat: dom '13' act=1168141798 pref=439370547.2 last_target=1168141798
Oct 08 12:46:15 dom0 qmemman.systemstate[2606]: stat: dom '0' act=4185481406 pref=2066278886.4 last_target=4185481406
Oct 08 12:46:15 dom0 qmemman.systemstate[2606]: stat: dom '7' act=1083577054 pref=385568768.0 last_target=1083577054
Oct 08 12:46:15 dom0 qmemman.systemstate[2606]: stat: dom '11' act=1398262413 pref=509008281.6 last_target=1398262413
Oct 08 12:46:15 dom0 qmemman.systemstate[2606]: stat: dom '10' act=944630402 pref=330228121.6 last_target=944630402
Oct 08 12:46:15 dom0 qmemman.systemstate[2606]: stat: dom '8' act=1085983520 pref=386527232.0 last_target=1085983520
Oct 08 12:46:15 dom0 qmemman.systemstate[2606]: stat: dom '14' act=2533483456 pref=988266905.6 last_target=2533483456
Oct 08 12:46:15 dom0 qmemman.systemstate[2606]: stat: dom '3' act=1049993490 pref=372192870.40000004 last_target=1049993490
Oct 08 12:46:15 dom0 qmemman.systemstate[2606]: stat: dom '9' act=1101491855 pref=392704000.0 last_target=1101491855
Oct 08 12:46:15 dom0 qmemman.systemstate[2606]: stat: xenfree=52425784 memset_reqs=[('9', 1075097769), ('7', 1057428490), ('11', 1363107010), ('10', 920386093), ('8', 1059801976), ('3', 1024305186), ('13', 1190660125), ('14', 2549915531), ('0', 4294967296)]
Oct 08 12:46:15 dom0 qmemman.systemstate[2606]: mem-set domain 9 to 1075097769
Oct 08 12:46:15 dom0 qmemman.systemstate[2606]: mem-set domain 7 to 1057428490
Oct 08 12:46:15 dom0 qmemman.systemstate[2606]: mem-set domain 11 to 1363107010
Oct 08 12:46:15 dom0 qmemman.systemstate[2606]: mem-set domain 10 to 920386093
Oct 08 12:46:15 dom0 qmemman.systemstate[2606]: mem-set domain 8 to 1059801976
Oct 08 12:46:15 dom0 qmemman.systemstate[2606]: mem-set domain 3 to 1024305186
Oct 08 12:46:15 dom0 qmemman.systemstate[2606]: mem-set domain 13 to 1190660125
Oct 08 12:46:15 dom0 qmemman.systemstate[2606]: mem-set domain 14 to 2549915531
Oct 08 12:46:16 dom0 qmemman.systemstate[2606]: mem-set domain 0 to 4294967296
Oct 08 12:46:19 dom0 qmemman.daemon.algo[2606]: balance_when_enough_memory(xen_free_memory=24248588, total_mem_pref=5815779404.799999, total_available_memory=8744138659.199999)
Oct 08 12:46:19 dom0 qmemman.daemon.algo[2606]: left_memory=736858780 acceptors_count=8
Oct 08 12:46:20 dom0 sudo[8703]: creviera : TTY=pts/0 ; PWD=/home/creviera/securedrop-workstation ; USER=root ; COMMAND=/bin/journalctl -ef
Oct 08 12:46:20 dom0 audit[8703]: USER_CMD pid=8703 uid=1000 auid=1000 ses=2 msg='cwd="/home/creviera/securedrop-workstation" cmd=6A6F75726E616C63746C202D6566 terminal=pts/0 res=success'
Oct 08 12:46:20 dom0 audit[8703]: CRED_REFR pid=8703 uid=0 auid=1000 ses=2 msg='op=PAM:setcred grantors=pam_env,pam_unix acct="root" exe="/usr/bin/sudo" hostname=? addr=? terminal=/dev/pts/0 res=success'
Oct 08 12:46:20 dom0 kernel: audit: type=1123 audit(1570553180.925:276): pid=8703 uid=1000 auid=1000 ses=2 msg='cwd="/home/creviera/securedrop-workstation" cmd=6A6F75726E616C63746C202D6566 terminal=pts/0 res=success'
Oct 08 12:46:20 dom0 kernel: audit: type=1110 audit(1570553180.925:277): pid=8703 uid=0 auid=1000 ses=2 msg='op=PAM:setcred grantors=pam_env,pam_unix acct="root" exe="/usr/bin/sudo" hostname=? addr=? terminal=/dev/pts/0 res=success'
Oct 08 12:46:20 dom0 audit[8703]: USER_START pid=8703 uid=0 auid=1000 ses=2 msg='op=PAM:session_open grantors=pam_keyinit,pam_limits,pam_keyinit,pam_limits,pam_systemd,pam_unix acct="root" exe="/usr/bin/sudo" hostname=? addr=? terminal=/dev/pts/0 res=success'
Oct 08 12:46:20 dom0 sudo[8703]: pam_systemd(sudo:session): Cannot create session: Already running in a session
Oct 08 12:46:20 dom0 kernel: audit: type=1105 audit(1570553180.927:278): pid=8703 uid=0 auid=1000 ses=2 msg='op=PAM:session_open grantors=pam_keyinit,pam_limits,pam_keyinit,pam_limits,pam_systemd,pam_unix acct="root" exe="/usr/bin/sudo" hostname=? addr=? terminal=/dev/pts/0 res=success'
Oct 08 12:46:20 dom0 sudo[8703]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 08 12:46:21 dom0 qmemman.daemon.algo[2606]: balance_when_enough_memory(xen_free_memory=24248588, total_mem_pref=5865113676.799999, total_available_memory=8694804387.199999)
Oct 08 12:46:21 dom0 qmemman.daemon.algo[2606]: left_memory=694533688 acceptors_count=8
Oct 08 12:46:21 dom0 qmemman.systemstate[2606]: stat: dom '13' act=1190660125 pref=439370547.2 last_target=1190660125
Oct 08 12:46:21 dom0 qmemman.systemstate[2606]: stat: dom '0' act=4294967296 pref=2011912678.4 last_target=4294967296
Oct 08 12:46:21 dom0 qmemman.systemstate[2606]: stat: dom '7' act=1057428490 pref=385568768.0 last_target=1057428490
Oct 08 12:46:21 dom0 qmemman.systemstate[2606]: stat: dom '11' act=1363107010 pref=509008281.6 last_target=1363107010
Oct 08 12:46:21 dom0 qmemman.systemstate[2606]: stat: dom '10' act=920386093 pref=330228121.6 last_target=920386093
Oct 08 12:46:21 dom0 qmemman.systemstate[2606]: stat: dom '8' act=1059801976 pref=386527232.0 last_target=1059801976
Oct 08 12:46:21 dom0 qmemman.systemstate[2606]: stat: dom '14' act=2549915531 pref=1037601177.6 last_target=2549915531
Oct 08 12:46:21 dom0 qmemman.systemstate[2606]: stat: dom '3' act=1024305186 pref=372192870.40000004 last_target=1024305186
Oct 08 12:46:21 dom0 qmemman.systemstate[2606]: stat: dom '9' act=1075097769 pref=392704000.0 last_target=1075097769
Oct 08 12:46:21 dom0 qmemman.systemstate[2606]: stat: xenfree=76677388 memset_reqs=[('13', 1176359578), ('9', 1060627526), ('7', 1042932301), ('11', 1349059690), ('10', 905688666), ('8', 1045309272), ('3', 1009760358), ('14', 2659958917), ('0', 4294967296)]
Oct 08 12:46:21 dom0 qmemman.systemstate[2606]: mem-set domain 13 to 1176359578
Oct 08 12:46:21 dom0 qmemman.systemstate[2606]: mem-set domain 9 to 1060627526
Oct 08 12:46:21 dom0 qmemman.systemstate[2606]: mem-set domain 7 to 1042932301
Oct 08 12:46:21 dom0 qmemman.systemstate[2606]: mem-set domain 11 to 1349059690
Oct 08 12:46:21 dom0 qmemman.systemstate[2606]: mem-set domain 10 to 905688666
Oct 08 12:46:21 dom0 qmemman.systemstate[2606]: mem-set domain 8 to 1045309272
Oct 08 12:46:21 dom0 qmemman.systemstate[2606]: mem-set domain 3 to 1009760358
Oct 08 12:46:21 dom0 qmemman.systemstate[2606]: mem-set domain 14 to 2659958917
Oct 08 12:46:21 dom0 qmemman.systemstate[2606]: mem-set domain 0 to 4294967296
Oct 08 12:46:21 dom0 qmemman.daemon.algo[2606]: balance_when_enough_memory(xen_free_memory=15325244, total_mem_pref=5799789030.4, total_available_memory=8760199817.599998)
Oct 08 12:46:21 dom0 qmemman.daemon.algo[2606]: left_memory=750756364 acceptors_count=8
Oct 08 12:46:21 dom0 qmemman.systemstate[2606]: stat: dom '13' act=1176359578 pref=439370547.2 last_target=1176359578
Oct 08 12:46:21 dom0 qmemman.systemstate[2606]: stat: dom '0' act=4294967296 pref=2011912678.4 last_target=4294967296
Oct 08 12:46:21 dom0 qmemman.systemstate[2606]: stat: dom '7' act=1042932301 pref=385568768.0 last_target=1042932301
Oct 08 12:46:21 dom0 qmemman.systemstate[2606]: stat: dom '11' act=1349059690 pref=509008281.6 last_target=1349059690
Oct 08 12:46:21 dom0 qmemman.systemstate[2606]: stat: dom '10' act=905688666 pref=330228121.6 last_target=905688666
Oct 08 12:46:21 dom0 qmemman.systemstate[2606]: stat: dom '8' act=1045309272 pref=386527232.0 last_target=1045309272
Oct 08 12:46:21 dom0 qmemman.systemstate[2606]: stat: dom '14' act=2659958917 pref=972276531.2 last_target=2659958917
Oct 08 12:46:21 dom0 qmemman.systemstate[2606]: stat: dom '3' act=1009760358 pref=372192870.40000004 last_target=1009760358
Oct 08 12:46:21 dom0 qmemman.systemstate[2606]: stat: dom '9' act=1060627526 pref=392704000.0 last_target=1060627526
Oct 08 12:46:21 dom0 qmemman.systemstate[2606]: stat: xenfree=67754044 memset_reqs=[('14', 2532146138), ('13', 1195658545), ('0', 4294967296), ('9', 1078622402), ('7', 1060727784), ('11', 1370304676), ('10', 921937660), ('8', 1063131538), ('3', 1027182052)]
Oct 08 12:46:21 dom0 qmemman.systemstate[2606]: mem-set domain 14 to 2532146138
Oct 08 12:46:21 dom0 qmemman.systemstate[2606]: mem-set domain 13 to 1195658545
Oct 08 12:46:21 dom0 qmemman.systemstate[2606]: mem-set domain 0 to 4294967296
Oct 08 12:46:21 dom0 qmemman.systemstate[2606]: mem-set domain 9 to 1078622402
Oct 08 12:46:21 dom0 qmemman.systemstate[2606]: mem-set domain 7 to 1060727784
Oct 08 12:46:21 dom0 qmemman.systemstate[2606]: mem-set domain 11 to 1370304676
Oct 08 12:46:21 dom0 qmemman.systemstate[2606]: mem-set domain 10 to 921937660
Oct 08 12:46:21 dom0 qmemman.systemstate[2606]: mem-set domain 8 to 1063131538
Oct 08 12:46:21 dom0 qmemman.systemstate[2606]: mem-set domain 3 to 1027182052
Oct 08 12:46:31 dom0 kernel: xen-blkback: backend/vbd/21/51712: using 2 queues, protocol 1 (x86_64-abi) persistent grants
Oct 08 12:46:31 dom0 kernel: xen-blkback: backend/vbd/21/51728: using 2 queues, protocol 1 (x86_64-abi) persistent grants
Oct 08 12:46:31 dom0 kernel: xen-blkback: backend/vbd/21/51744: using 2 queues, protocol 1 (x86_64-abi) persistent grants
Oct 08 12:46:34 dom0 audit[8699]: USER_END pid=8699 uid=0 auid=4294967295 ses=4294967295 msg='op=PAM:session_close grantors=pam_keyinit,pam_limits,pam_unix acct="creviera" exe="/usr/sbin/runuser" hostname=? addr=? terminal=? res=success'
Oct 08 12:46:34 dom0 audit[8699]: CRED_DISP pid=8699 uid=0 auid=4294967295 ses=4294967295 msg='op=PAM:setcred grantors=pam_rootok acct="creviera" exe="/usr/sbin/runuser" hostname=? addr=? terminal=? res=success'
Oct 08 12:46:34 dom0 runuser[8699]: pam_unix(runuser:session): session closed for user creviera
Oct 08 12:46:34 dom0 kernel: audit: type=1106 audit(1570553194.750:279): pid=8699 uid=0 auid=4294967295 ses=4294967295 msg='op=PAM:session_close grantors=pam_keyinit,pam_limits,pam_unix acct="creviera" exe="/usr/sbin/runuser" hostname=? addr=? terminal=? res=success'
Oct 08 12:46:34 dom0 kernel: audit: type=1104 audit(1570553194.750:280): pid=8699 uid=0 auid=4294967295 ses=4294967295 msg='op=PAM:setcred grantors=pam_rootok acct="creviera" exe="/usr/sbin/runuser" hostname=? addr=? terminal=? res=success'
Oct 08 12:46:34 dom0 qmemman.daemon.algo[2606]: balance_when_enough_memory(xen_free_memory=32983617, total_mem_pref=6078675430.399999, total_available_memory=8900631845.599998)
Oct 08 12:46:34 dom0 qmemman.daemon.algo[2606]: left_memory=657907965 acceptors_count=9
Oct 08 12:46:34 dom0 qmemman.systemstate[2606]: stat: dom '13' act=1195658545 pref=439370547.2 last_target=1195658545
Oct 08 12:46:34 dom0 qmemman.systemstate[2606]: stat: dom '0' act=4294967296 pref=2011912678.4 last_target=4294967296
Oct 08 12:46:34 dom0 qmemman.systemstate[2606]: stat: dom '7' act=1060727784 pref=385568768.0 last_target=1060727784
Oct 08 12:46:34 dom0 qmemman.systemstate[2606]: stat: dom '11' act=1370304676 pref=509008281.6 last_target=1370304676
Oct 08 12:46:34 dom0 qmemman.systemstate[2606]: stat: dom '10' act=921937660 pref=330228121.6 last_target=921937660
Oct 08 12:46:34 dom0 qmemman.systemstate[2606]: stat: dom '8' act=1063131538 pref=386527232.0 last_target=1063131538
Oct 08 12:46:34 dom0 qmemman.systemstate[2606]: stat: dom '21' act=401645568 pref=278886400.0 last_target=401604608
Oct 08 12:46:34 dom0 qmemman.systemstate[2606]: stat: dom '14' act=2532146138 pref=972276531.2 last_target=2532146138
Oct 08 12:46:34 dom0 qmemman.systemstate[2606]: stat: dom '3' act=1027182052 pref=372192870.40000004 last_target=1027182052
Oct 08 12:46:34 dom0 qmemman.systemstate[2606]: stat: dom '9' act=1078622402 pref=392704000.0 last_target=1078622402
Oct 08 12:46:34 dom0 qmemman.systemstate[2606]: stat: xenfree=85412417 memset_reqs=[('13', 1154658978), ('14', 2466553323), ('9', 1039776464), ('7', 1022211132), ('11', 1326091375), ('10', 885974942), ('8', 1024570654), ('3', 989282689), ('0', 4294967296), ('21', 759583202)]
Oct 08 12:46:34 dom0 qmemman.systemstate[2606]: mem-set domain 13 to 1154658978
Oct 08 12:46:34 dom0 qmemman.systemstate[2606]: mem-set domain 14 to 2466553323
Oct 08 12:46:34 dom0 qmemman.systemstate[2606]: mem-set domain 9 to 1039776464
Oct 08 12:46:34 dom0 qmemman.systemstate[2606]: mem-set domain 7 to 1022211132
Oct 08 12:46:34 dom0 qmemman.systemstate[2606]: mem-set domain 11 to 1326091375
Oct 08 12:46:34 dom0 qmemman.systemstate[2606]: mem-set domain 10 to 885974942
Oct 08 12:46:34 dom0 qmemman.systemstate[2606]: mem-set domain 8 to 1024570654
Oct 08 12:46:34 dom0 qmemman.systemstate[2606]: mem-set domain 3 to 989282689
Oct 08 12:46:34 dom0 qmemman.systemstate[2606]: mem-set domain 0 to 4294967296
Oct 08 12:46:34 dom0 qrexec[8712]: qubes.GetDate: sd-export-usb -> @default: allowed to dom0
Oct 08 12:46:35 dom0 qmemman.systemstate[2606]: mem-set domain 21 to 759583202
Oct 08 12:46:35 dom0 qrexec[8731]: qubes.USB+4-2: sd-export-usb -> sys-usb: allowed to sys-usb
Oct 08 12:46:36 dom0 qmemman.daemon.algo[2606]: balance_when_enough_memory(xen_free_memory=15636807, total_mem_pref=6122844646.399999, total_available_memory=8856462215.599998)
Oct 08 12:46:36 dom0 qmemman.daemon.algo[2606]: left_memory=622178584 acceptors_count=9
Oct 08 12:46:36 dom0 qmemman.systemstate[2606]: stat: dom '13' act=1154658978 pref=439370547.2 last_target=1154658978
Oct 08 12:46:36 dom0 qmemman.systemstate[2606]: stat: dom '0' act=4294967296 pref=2011912678.4 last_target=4294967296
Oct 08 12:46:36 dom0 qmemman.systemstate[2606]: stat: dom '7' act=1022211132 pref=385568768.0 last_target=1022211132
Oct 08 12:46:36 dom0 qmemman.systemstate[2606]: stat: dom '11' act=1326091375 pref=509008281.6 last_target=1326091375
Oct 08 12:46:36 dom0 qmemman.systemstate[2606]: stat: dom '10' act=885974942 pref=330228121.6 last_target=885974942
Oct 08 12:46:36 dom0 qmemman.systemstate[2606]: stat: dom '8' act=1024570654 pref=386527232.0 last_target=1024570654
Oct 08 12:46:36 dom0 qmemman.systemstate[2606]: stat: dom '21' act=759583202 pref=323055616.0 last_target=759583202
Oct 08 12:46:36 dom0 qmemman.systemstate[2606]: stat: dom '14' act=2466553323 pref=972276531.2 last_target=2466553323
Oct 08 12:46:36 dom0 qmemman.systemstate[2606]: stat: dom '3' act=989282689 pref=372192870.40000004 last_target=989282689
Oct 08 12:46:36 dom0 qmemman.systemstate[2606]: stat: dom '9' act=1039776464 pref=392704000.0 last_target=1039776464
Oct 08 12:46:36 dom0 qmemman.systemstate[2606]: stat: xenfree=68065607 memset_reqs=[('13', 1142890273), ('14', 2445320787), ('9', 1028836506), ('7', 1011397888), ('11', 1313085979), ('10', 876144487), ('8', 1013740389), ('3', 978706986), ('0', 4294967296), ('21', 858614772)]
Oct 08 12:46:36 dom0 qmemman.systemstate[2606]: mem-set domain 13 to 1142890273
Oct 08 12:46:36 dom0 qmemman.systemstate[2606]: mem-set domain 14 to 2445320787
Oct 08 12:46:36 dom0 qmemman.systemstate[2606]: mem-set domain 9 to 1028836506
Oct 08 12:46:36 dom0 qmemman.systemstate[2606]: mem-set domain 7 to 1011397888
Oct 08 12:46:36 dom0 qmemman.systemstate[2606]: mem-set domain 11 to 1313085979
Oct 08 12:46:36 dom0 qmemman.systemstate[2606]: mem-set domain 10 to 876144487
Oct 08 12:46:36 dom0 qmemman.systemstate[2606]: mem-set domain 8 to 1013740389
Oct 08 12:46:36 dom0 qmemman.systemstate[2606]: mem-set domain 3 to 978706986
Oct 08 12:46:36 dom0 qmemman.systemstate[2606]: mem-set domain 0 to 4294967296
Oct 08 12:46:36 dom0 qmemman.systemstate[2606]: mem-set domain 21 to 858614772
Oct 08 12:46:37 dom0 qrexec[8733]: qubes.WindowIconUpdater: sd-export-usb -> dom0: allowed to dom0
Oct 08 12:46:37 dom0 qubesd[2604]: Start failed: list.remove(x): x not in list
Oct 08 12:46:37 dom0 qmemman.daemon.algo[2606]: balance_when_enough_memory(xen_free_memory=165626729, total_mem_pref=6122844646.399999, total_available_memory=9006487445.599998)
Oct 08 12:46:37 dom0 qmemman.daemon.algo[2606]: left_memory=671426253 acceptors_count=9
Oct 08 12:46:37 dom0 qmemman.systemstate[2606]: stat: dom '13' act=1142890273 pref=439370547.2 last_target=1142890273
Oct 08 12:46:37 dom0 qmemman.systemstate[2606]: stat: dom '0' act=4294967296 pref=2011912678.4 last_target=4294967296
Oct 08 12:46:37 dom0 qmemman.systemstate[2606]: stat: dom '7' act=1011397888 pref=385568768.0 last_target=1011397888
Oct 08 12:46:37 dom0 qmemman.systemstate[2606]: stat: dom '11' act=1313085979 pref=509008281.6 last_target=1313085979
Oct 08 12:46:37 dom0 qmemman.systemstate[2606]: stat: dom '10' act=876144487 pref=330228121.6 last_target=876144487
Oct 08 12:46:37 dom0 qmemman.systemstate[2606]: stat: dom '8' act=1013740389 pref=386527232.0 last_target=1013740389
Oct 08 12:46:37 dom0 qmemman.systemstate[2606]: stat: dom '21' act=858614772 pref=323055616.0 last_target=858614772
Oct 08 12:46:37 dom0 qmemman.systemstate[2606]: stat: dom '14' act=2445320787 pref=972276531.2 last_target=2445320787
Oct 08 12:46:37 dom0 qmemman.systemstate[2606]: stat: dom '3' act=978706986 pref=372192870.40000004 last_target=978706986
Oct 08 12:46:37 dom0 qmemman.systemstate[2606]: stat: dom '9' act=1028836506 pref=392704000.0 last_target=1028836506
Oct 08 12:46:37 dom0 qmemman.systemstate[2606]: stat: xenfree=218055529 memset_reqs=[('13', 1159111693), ('14', 2474586698), ('0', 4294967296), ('21', 871989031), ('9', 1043915620), ('7', 1026302346), ('11', 1331011994), ('10', 889694315), ('8', 1028668308), ('3', 993284028)]
Oct 08 12:46:37 dom0 qmemman.systemstate[2606]: mem-set domain 13 to 1159111693
Oct 08 12:46:37 dom0 qmemman.systemstate[2606]: mem-set domain 14 to 2474586698
Oct 08 12:46:37 dom0 qmemman.systemstate[2606]: mem-set domain 0 to 4294967296
Oct 08 12:46:37 dom0 qmemman.systemstate[2606]: mem-set domain 21 to 871989031
Oct 08 12:46:37 dom0 qmemman.systemstate[2606]: mem-set domain 9 to 1043915620
Oct 08 12:46:37 dom0 qmemman.systemstate[2606]: mem-set domain 7 to 1026302346
Oct 08 12:46:37 dom0 qmemman.systemstate[2606]: mem-set domain 11 to 1331011994
Oct 08 12:46:37 dom0 qmemman.systemstate[2606]: mem-set domain 10 to 889694315
Oct 08 12:46:37 dom0 qmemman.systemstate[2606]: mem-set domain 8 to 1028668308
Oct 08 12:46:37 dom0 qmemman.systemstate[2606]: mem-set domain 3 to 993284028
Oct 08 12:46:37 dom0 qmemman.daemon.algo[2606]: balance_when_enough_memory(xen_free_memory=908931682, total_mem_pref=5799789030.4, total_available_memory=9350684949.599998)
Oct 08 12:46:37 dom0 qmemman.daemon.algo[2606]: left_memory=955387345 acceptors_count=8
Oct 08 12:46:37 dom0 qmemman.systemstate[2606]: stat: dom '13' act=1159111693 pref=439370547.2 last_target=1159111693
Oct 08 12:46:37 dom0 qmemman.systemstate[2606]: stat: dom '0' act=4294967296 pref=2011912678.4 last_target=4294967296
Oct 08 12:46:37 dom0 qmemman.systemstate[2606]: stat: dom '7' act=1026302346 pref=385568768.0 last_target=1026302346
Oct 08 12:46:37 dom0 qmemman.systemstate[2606]: stat: dom '11' act=1331011994 pref=509008281.6 last_target=1331011994
Oct 08 12:46:37 dom0 qmemman.systemstate[2606]: stat: dom '10' act=889694315 pref=330228121.6 last_target=889694315
Oct 08 12:46:37 dom0 qmemman.systemstate[2606]: stat: dom '8' act=1028668308 pref=386527232.0 last_target=1028668308
Oct 08 12:46:37 dom0 qmemman.systemstate[2606]: stat: dom '14' act=2474586698 pref=972276531.2 last_target=2474586698
Oct 08 12:46:37 dom0 qmemman.systemstate[2606]: stat: dom '3' act=993284028 pref=372192870.40000004 last_target=993284028
Oct 08 12:46:37 dom0 qmemman.systemstate[2606]: stat: dom '9' act=1043915620 pref=392704000.0 last_target=1043915620
Oct 08 12:46:37 dom0 qmemman.systemstate[2606]: stat: xenfree=961360482 memset_reqs=[('13', 1265900074), ('14', 2656589360), ('0', 4294967296), ('9', 1144117491), ('7', 1125497151), ('11', 1447629036), ('10', 981078349), ('8', 1127998391), ('3', 1090590961)]
Oct 08 12:46:37 dom0 qmemman.systemstate[2606]: mem-set domain 13 to 1265900074
Oct 08 12:46:37 dom0 qmemman.systemstate[2606]: mem-set domain 14 to 2656589360
Oct 08 12:46:37 dom0 qmemman.systemstate[2606]: mem-set domain 0 to 4294967296
Oct 08 12:46:37 dom0 qmemman.systemstate[2606]: mem-set domain 9 to 1144117491
Oct 08 12:46:37 dom0 qmemman.systemstate[2606]: mem-set domain 7 to 1125497151
Oct 08 12:46:37 dom0 qmemman.systemstate[2606]: mem-set domain 11 to 1447629036
Oct 08 12:46:37 dom0 qmemman.systemstate[2606]: mem-set domain 10 to 981078349
Oct 08 12:46:37 dom0 qmemman.systemstate[2606]: mem-set domain 8 to 1127998391
Oct 08 12:46:37 dom0 qmemman.systemstate[2606]: mem-set domain 3 to 1090590961
Oct 08 12:46:37 dom0 qmemman.daemon.algo[2606]: balance_when_enough_memory(xen_free_memory=21651112, total_mem_pref=5846689868.799999, total_available_memory=9309329352.199999)
Oct 08 12:46:37 dom0 qmemman.daemon.algo[2606]: left_memory=1036633065 acceptors_count=8
Oct 08 12:46:38 dom0 qubesd[2604]: Starting sd-export-usb
Oct 08 12:46:38 dom0 qmemman.systemstate[2606]: do_balloon(memsize=577765376)
Oct 08 12:46:38 dom0 qubesd[2604]: unhandled exception while calling src=b'dom0' meth=b'admin.vm.Start' dest=b'sd-export-usb' arg=b'' len(untrusted_payload)=0
Oct 08 12:46:38 dom0 qubesd[2604]: Traceback (most recent call last):
Oct 08 12:46:38 dom0 qubesd[2604]:   File "/usr/lib/python3.5/site-packages/qubes/api/__init__.py", line 266, in respond
Oct 08 12:46:38 dom0 qubesd[2604]:     untrusted_payload=untrusted_payload)
Oct 08 12:46:38 dom0 qubesd[2604]:   File "/usr/lib64/python3.5/asyncio/futures.py", line 381, in __iter__
Oct 08 12:46:38 dom0 qubesd[2604]:     yield self  # This tells Task to wait for completion.
Oct 08 12:46:38 dom0 qubesd[2604]:   File "/usr/lib64/python3.5/asyncio/tasks.py", line 310, in _wakeup
Oct 08 12:46:38 dom0 qubesd[2604]:     future.result()
Oct 08 12:46:38 dom0 qubesd[2604]:   File "/usr/lib64/python3.5/asyncio/futures.py", line 294, in result
Oct 08 12:46:38 dom0 qubesd[2604]:     raise self._exception
Oct 08 12:46:38 dom0 qubesd[2604]:   File "/usr/lib64/python3.5/asyncio/tasks.py", line 240, in _step
Oct 08 12:46:38 dom0 qubesd[2604]:     result = coro.send(None)
Oct 08 12:46:38 dom0 qubesd[2604]:   File "/usr/lib/python3.5/site-packages/qubes/api/admin.py", line 834, in vm_start
Oct 08 12:46:38 dom0 qubesd[2604]:     yield from self.dest.start()
Oct 08 12:46:38 dom0 qubesd[2604]:   File "/usr/lib/python3.5/site-packages/qubes/vm/dispvm.py", line 220, in start
Oct 08 12:46:38 dom0 qubesd[2604]:     yield from super(DispVM, self).start(**kwargs)
Oct 08 12:46:38 dom0 qubesd[2604]:   File "/usr/lib/python3.5/site-packages/qubes/vm/qubesvm.py", line 1071, in start
Oct 08 12:46:38 dom0 qubesd[2604]:     start_guid=start_guid)
Oct 08 12:46:38 dom0 qubesd[2604]:   File "/usr/lib/python3.5/site-packages/qubes/events.py", line 236, in fire_event_async
Oct 08 12:46:38 dom0 qubesd[2604]:     effect = task.result()
Oct 08 12:46:38 dom0 qubesd[2604]:   File "/usr/lib64/python3.5/asyncio/futures.py", line 294, in result
Oct 08 12:46:38 dom0 qubesd[2604]:     raise self._exception
Oct 08 12:46:38 dom0 qubesd[2604]:   File "/usr/lib64/python3.5/asyncio/tasks.py", line 240, in _step
Oct 08 12:46:38 dom0 qubesd[2604]:     result = coro.send(None)
Oct 08 12:46:38 dom0 qubesd[2604]:   File "/usr/lib/python3.5/site-packages/qubesusbproxy/core3ext.py", line 323, in on_domain_start
Oct 08 12:46:38 dom0 qubesd[2604]:     yield from self.on_device_attach_usb(vm, '', device, options={})
Oct 08 12:46:38 dom0 qubesd[2604]:   File "/usr/lib/python3.5/site-packages/qubesusbproxy/core3ext.py", line 286, in on_device_attach_usb
Oct 08 12:46:38 dom0 qubesd[2604]:     device.backend_domain.name))
Oct 08 12:46:38 dom0 qubesd[2604]: ValueError: list.remove(x): x not in list
Oct 08 12:46:38 dom0 qmemman.systemstate[2606]: xenfree=75470018
Oct 08 12:46:38 dom0 qmemman.daemon.algo[2606]: balloon: dom 13 has actual memory 1265900074
Oct 08 12:46:38 dom0 qmemman.daemon.algo[2606]: balloon: dom 0 has actual memory 4294967296
Oct 08 12:46:38 dom0 qmemman.daemon.algo[2606]: balloon: dom 7 has actual memory 1125497151
Oct 08 12:46:38 dom0 qmemman.daemon.algo[2606]: balloon: dom 11 has actual memory 1447629036
Oct 08 12:46:38 dom0 qmemman.daemon.algo[2606]: balloon: dom 10 has actual memory 981078349
Oct 08 12:46:38 dom0 qmemman.daemon.algo[2606]: balloon: dom 8 has actual memory 1127998391
Oct 08 12:46:38 dom0 qmemman.daemon.algo[2606]: balloon: dom 14 has actual memory 2656589360
Oct 08 12:46:38 dom0 qmemman.daemon.algo[2606]: balloon: dom 3 has actual memory 1090590961
Oct 08 12:46:38 dom0 qmemman.daemon.algo[2606]: balloon: dom 9 has actual memory 1144117491
Oct 08 12:46:38 dom0 qmemman.daemon.algo[2606]: req=554724158 avail=9287678240.199999 donors=[('13', 826529526.8), ('0', 2236153779.2), ('7', 739928383.0), ('11', 938620754.4), ('10', 650850227.4), ('8', 741471159.0), ('14', 1684312828.8), ('3', 718398090.5999999), ('9', 751413491.0)]
Oct 08 12:46:38 dom0 qmemman.daemon.algo[2606]: borrow 51834342.03430319 from 13
Oct 08 12:46:38 dom0 qmemman.daemon.algo[2606]: borrow 140236441.74105805 from 0
Oct 08 12:46:38 dom0 qmemman.daemon.algo[2606]: borrow 46403303.98576499 from 7
Oct 08 12:46:38 dom0 qmemman.daemon.algo[2606]: borrow 58863945.74726195 from 11
Oct 08 12:46:38 dom0 qmemman.daemon.algo[2606]: borrow 40816924.509363584 from 10
Oct 08 12:46:38 dom0 qmemman.daemon.algo[2606]: borrow 46500056.46256509 from 8
Oct 08 12:46:38 dom0 qmemman.daemon.algo[2606]: borrow 105628709.47731997 from 14
Oct 08 12:46:38 dom0 qmemman.daemon.algo[2606]: borrow 45053069.657560274 from 3
Oct 08 12:46:38 dom0 qmemman.daemon.algo[2606]: borrow 47123572.28480298 from 9
Oct 08 12:46:38 dom0 qmemman.systemstate[2606]: memset_reqs=[('13', 1214065731), ('0', 4154730854), ('7', 1079093847), ('11', 1388765090), ('10', 940261424), ('8', 1081498334), ('14', 2550960650), ('3', 1045537891), ('9', 1096993918)]
Oct 08 12:46:38 dom0 qmemman.systemstate[2606]: mem-set domain 13 to 1214065731
Oct 08 12:46:38 dom0 qmemman.systemstate[2606]: mem-set domain 0 to 4154730854
Oct 08 12:46:38 dom0 qmemman.systemstate[2606]: mem-set domain 7 to 1079093847
Oct 08 12:46:38 dom0 qmemman.systemstate[2606]: mem-set domain 11 to 1388765090
Oct 08 12:46:38 dom0 qmemman.systemstate[2606]: mem-set domain 10 to 940261424
Oct 08 12:46:38 dom0 qmemman.systemstate[2606]: mem-set domain 8 to 1081498334
Oct 08 12:46:38 dom0 qmemman.systemstate[2606]: mem-set domain 14 to 2550960650
Oct 08 12:46:38 dom0 qmemman.systemstate[2606]: mem-set domain 3 to 1045537891
Oct 08 12:46:38 dom0 qmemman.systemstate[2606]: mem-set domain 9 to 1096993918
Oct 08 12:46:38 dom0 qmemman.systemstate[2606]: xenfree=527614263
Oct 08 12:46:38 dom0 qmemman.daemon.algo[2606]: balloon: dom 13 has actual memory 1216729088
Oct 08 12:46:38 dom0 qmemman.daemon.algo[2606]: balloon: dom 0 has actual memory 4170559488
Oct 08 12:46:38 dom0 qmemman.daemon.algo[2606]: balloon: dom 7 has actual memory 1089777664
Oct 08 12:46:38 dom0 qmemman.daemon.algo[2606]: balloon: dom 11 has actual memory 1405476864
Oct 08 12:46:38 dom0 qmemman.daemon.algo[2606]: balloon: dom 10 has actual memory 953749504
Oct 08 12:46:38 dom0 qmemman.daemon.algo[2606]: balloon: dom 8 has actual memory 1092878336
Oct 08 12:46:38 dom0 qmemman.daemon.algo[2606]: balloon: dom 14 has actual memory 2639745024
Oct 08 12:46:38 dom0 qmemman.daemon.algo[2606]: balloon: dom 3 has actual memory 1058390016
Oct 08 12:46:38 dom0 qmemman.daemon.algo[2606]: balloon: dom 9 has actual memory 1096993918
Oct 08 12:46:38 dom0 qmemman.daemon.algo[2606]: req=102579913 avail=8877610033.199999 donors=[('13', 777358540.8), ('0', 2111745971.2), ('7', 704208896.0), ('11', 896468582.4), ('10', 623521382.4), ('8', 706351104.0), ('14', 1667468492.8), ('3', 686197145.5999999), ('9', 704289918.0)]
Oct 08 12:46:38 dom0 qmemman.daemon.algo[2606]: borrow 9431416.760389503 from 13
Oct 08 12:46:38 dom0 qmemman.daemon.algo[2606]: borrow 25621068.401671946 from 0
Oct 08 12:46:38 dom0 qmemman.daemon.algo[2606]: borrow 8543917.942568246 from 7
Oct 08 12:46:38 dom0 qmemman.daemon.algo[2606]: borrow 10876536.84811741 from 11
Oct 08 12:46:38 dom0 qmemman.daemon.algo[2606]: borrow 7564964.823537697 from 10
Oct 08 12:46:38 dom0 qmemman.daemon.algo[2606]: borrow 8569908.596011955 from 8
Oct 08 12:46:38 dom0 qmemman.daemon.algo[2606]: borrow 20230806.59052218 from 14
Oct 08 12:46:38 dom0 qmemman.daemon.algo[2606]: borrow 8325387.733288382 from 3
Oct 08 12:46:38 dom0 qmemman.daemon.algo[2606]: borrow 8544900.953892691 from 9
Oct 08 12:46:38 dom0 qmemman.systemstate[2606]: memset_reqs=[('13', 1207297671), ('0', 4144938419), ('7', 1081233746), ('11', 1394600327), ('10', 946184539), ('8', 1084308427), ('14', 2619514217), ('3', 1050064628), ('9', 1088449017)]
Oct 08 12:46:38 dom0 qmemman.systemstate[2606]: mem-set domain 13 to 1207297671
Oct 08 12:46:38 dom0 qmemman.systemstate[2606]: mem-set domain 0 to 4144938419
Oct 08 12:46:38 dom0 qmemman.systemstate[2606]: mem-set domain 7 to 1081233746
Oct 08 12:46:38 dom0 qmemman.systemstate[2606]: mem-set domain 11 to 1394600327
Oct 08 12:46:38 dom0 qmemman.systemstate[2606]: mem-set domain 10 to 946184539
Oct 08 12:46:38 dom0 qmemman.systemstate[2606]: mem-set domain 8 to 1084308427
Oct 08 12:46:38 dom0 qmemman.systemstate[2606]: mem-set domain 14 to 2619514217
Oct 08 12:46:38 dom0 qmemman.systemstate[2606]: mem-set domain 3 to 1050064628
Oct 08 12:46:38 dom0 qmemman.systemstate[2606]: mem-set domain 9 to 1088449017
Oct 08 12:46:38 dom0 qmemman.systemstate[2606]: xenfree=571686027
Oct 08 12:46:38 dom0 qmemman.daemon.algo[2606]: balloon: dom 13 has actual memory 1207297671
Oct 08 12:46:38 dom0 qmemman.daemon.algo[2606]: balloon: dom 0 has actual memory 4144938419
Oct 08 12:46:38 dom0 qmemman.daemon.algo[2606]: balloon: dom 7 has actual memory 1081233746
Oct 08 12:46:38 dom0 qmemman.daemon.algo[2606]: balloon: dom 11 has actual memory 1394600327
Oct 08 12:46:38 dom0 qmemman.daemon.algo[2606]: balloon: dom 10 has actual memory 946184539
Oct 08 12:46:38 dom0 qmemman.daemon.algo[2606]: balloon: dom 8 has actual memory 1084308427
Oct 08 12:46:38 dom0 qmemman.daemon.algo[2606]: balloon: dom 14 has actual memory 2637647872
Oct 08 12:46:38 dom0 qmemman.daemon.algo[2606]: balloon: dom 3 has actual memory 1050064628
Oct 08 12:46:38 dom0 qmemman.daemon.algo[2606]: balloon: dom 9 has actual memory 1088449017
Oct 08 12:46:38 dom0 qmemman.daemon.algo[2606]: req=58508149 avail=8788034777.199999 donors=[('13', 767927123.8), ('0', 2086124902.2), ('7', 695664978.0), ('11', 885592045.4), ('10', 615956417.4), ('8', 697781195.0), ('14', 1665371340.8), ('3', 677871757.5999999), ('9', 695745017.0)]
Oct 08 12:46:38 dom0 qmemman.daemon.algo[2606]: borrow 5368264.407857132 from 13
Oct 08 12:46:38 dom0 qmemman.daemon.algo[2606]: borrow 14583245.878083283 from 0
Oct 08 12:46:38 dom0 qmemman.daemon.algo[2606]: borrow 4863109.30483911 from 7
Oct 08 12:46:38 dom0 qmemman.daemon.algo[2606]: borrow 6190811.74483997 from 11
Oct 08 12:46:38 dom0 qmemman.daemon.algo[2606]: borrow 4305899.361852456 from 10
Oct 08 12:46:38 dom0 qmemman.daemon.algo[2606]: borrow 4877902.912263975 from 8
Oct 08 12:46:38 dom0 qmemman.daemon.algo[2606]: borrow 11641929.836313916 from 14
Oct 08 12:46:38 dom0 qmemman.daemon.algo[2606]: borrow 4738724.179201388 from 3
Oct 08 12:46:38 dom0 qmemman.daemon.algo[2606]: borrow 4863668.824748779 from 9
Oct 08 12:46:38 dom0 qmemman.systemstate[2606]: memset_reqs=[('13', 1201929406), ('0', 4130355173), ('7', 1076370636), ('11', 1388409515), ('10', 941878639), ('8', 1079430524), ('14', 2626005942), ('3', 1045325903), ('9', 1083585348)]
Oct 08 12:46:38 dom0 qmemman.systemstate[2606]: mem-set domain 13 to 1201929406
Oct 08 12:46:38 dom0 qmemman.systemstate[2606]: mem-set domain 0 to 4130355173
Oct 08 12:46:38 dom0 qmemman.systemstate[2606]: mem-set domain 7 to 1076370636
Oct 08 12:46:38 dom0 qmemman.systemstate[2606]: mem-set domain 11 to 1388409515
Oct 08 12:46:38 dom0 qmemman.systemstate[2606]: mem-set domain 10 to 941878639
Oct 08 12:46:38 dom0 qmemman.systemstate[2606]: mem-set domain 8 to 1079430524
Oct 08 12:46:38 dom0 qmemman.systemstate[2606]: mem-set domain 14 to 2626005942
Oct 08 12:46:38 dom0 qmemman.systemstate[2606]: mem-set domain 3 to 1045325903
Oct 08 12:46:38 dom0 qmemman.systemstate[2606]: mem-set domain 9 to 1083585348
Oct 08 12:46:39 dom0 qmemman.systemstate[2606]: xenfree=632512937
Oct 08 12:46:39 dom0 dmeventd[1172]: No longer monitoring thin pool qubes_dom0-pool00-tpool.
Oct 08 12:46:39 dom0 lvm[1172]: Monitoring thin pool qubes_dom0-pool00-tpool.
Oct 08 12:46:39 dom0 dmeventd[1172]: No longer monitoring thin pool qubes_dom0-pool00-tpool.
Oct 08 12:46:39 dom0 lvm[1172]: Monitoring thin pool qubes_dom0-pool00-tpool.

Buried in these logs is a stacktrace:

Traceback
Oct 08 12:45:15 dom0 qubesd[2604]: unhandled exception while calling src=b'dom0' meth=b'admin.vm.Start' dest=b'sd-export-usb' arg=b'' len(untrusted_payload)=0
Oct 08 12:45:15 dom0 qubesd[2604]: Traceback (most recent call last):
Oct 08 12:45:15 dom0 qubesd[2604]:   File "/usr/lib/python3.5/site-packages/qubes/api/__init__.py", line 266, in respond
Oct 08 12:45:15 dom0 qubesd[2604]:     untrusted_payload=untrusted_payload)
Oct 08 12:45:15 dom0 qubesd[2604]:   File "/usr/lib64/python3.5/asyncio/futures.py", line 381, in __iter__
Oct 08 12:45:15 dom0 qubesd[2604]:     yield self  # This tells Task to wait for completion.
Oct 08 12:45:15 dom0 qubesd[2604]:   File "/usr/lib64/python3.5/asyncio/tasks.py", line 310, in _wakeup
Oct 08 12:45:15 dom0 qubesd[2604]:     future.result()
Oct 08 12:45:15 dom0 qubesd[2604]:   File "/usr/lib64/python3.5/asyncio/futures.py", line 294, in result
Oct 08 12:45:15 dom0 qubesd[2604]:     raise self._exception
Oct 08 12:45:15 dom0 qubesd[2604]:   File "/usr/lib64/python3.5/asyncio/tasks.py", line 240, in _step
Oct 08 12:45:15 dom0 qubesd[2604]:     result = coro.send(None)
Oct 08 12:45:15 dom0 qubesd[2604]:   File "/usr/lib/python3.5/site-packages/qubes/api/admin.py", line 834, in vm_start
Oct 08 12:45:15 dom0 qubesd[2604]:     yield from self.dest.start()
Oct 08 12:45:15 dom0 qubesd[2604]:   File "/usr/lib/python3.5/site-packages/qubes/vm/dispvm.py", line 220, in start
Oct 08 12:45:15 dom0 qubesd[2604]:     yield from super(DispVM, self).start(**kwargs)
Oct 08 12:45:15 dom0 qubesd[2604]:   File "/usr/lib/python3.5/site-packages/qubes/vm/qubesvm.py", line 1071, in start
Oct 08 12:45:15 dom0 qubesd[2604]:     start_guid=start_guid)
Oct 08 12:45:15 dom0 qubesd[2604]:   File "/usr/lib/python3.5/site-packages/qubes/events.py", line 236, in fire_event_async
Oct 08 12:45:15 dom0 qubesd[2604]:     effect = task.result()
Oct 08 12:45:15 dom0 qubesd[2604]:   File "/usr/lib64/python3.5/asyncio/futures.py", line 294, in result
Oct 08 12:45:15 dom0 qubesd[2604]:     raise self._exception
Oct 08 12:45:15 dom0 qubesd[2604]:   File "/usr/lib64/python3.5/asyncio/tasks.py", line 240, in _step
Oct 08 12:45:15 dom0 qubesd[2604]:     result = coro.send(None)
Oct 08 12:45:15 dom0 qubesd[2604]:   File "/usr/lib/python3.5/site-packages/qubesusbproxy/core3ext.py", line 323, in on_domain_start
Oct 08 12:45:15 dom0 qubesd[2604]:     yield from self.on_device_attach_usb(vm, '', device, options={})
Oct 08 12:45:15 dom0 qubesd[2604]:   File "/usr/lib/python3.5/site-packages/qubesusbproxy/core3ext.py", line 286, in on_device_attach_usb
Oct 08 12:45:15 dom0 qubesd[2604]:     device.backend_domain.name))
Oct 08 12:45:15 dom0 qubesd[2604]: ValueError: list.remove(x): x not in list

Root cause

This stacktrace points us to this line of code causing the x not in list error:

                    policy.remove(
                        '{} {} allow\n'.format(vm.name,
device.backend_domain.name))

You can see here that policy is the list containing the lines returned by readlines for the policy file (in my case, the file is /etc/qubes-rpc/policy/qubes.USB+4-2):

                with open(policy_path, 'r+') as f:
                    policy = f.readlines()
                    policy.remove(
                        '{} {} allow\n'.format(vm.name,
device.backend_domain.name))

On my system, I see the following Qubes policy files /etc/qubes-rpc/policy/qubes.USB and /etc/qubes-rpc/policy/qubes.USB+4-2. You can see in the qubes.USB+4-2 file that there are many attempts to set the device policy, but more importantly that user=root:

sd-export-usb sys-usb allow,user=root
sd-export-usb sys-usb allow,user=root
sd-export-usb sys-usb allow,user=root
sd-export-usb sys-usb allow,user=root
sd-export-usb sys-usb allow,user=root
sd-export-usb sys-usb allow,user=root
sd-export-usb sys-usb allow,user=root
...

So the bug is that Qubes tries to remove the policy sd-export-usb sys-usb allow instead of sd-export-usb sys-usb allow,user=root.

That's it.

The fix is to either write the policy to be sd-export-usb sys-usb allow or to remove sd-export-usb sys-usb allow,user=root.

@sssoleileraaa
Copy link
Contributor Author

This issue can be closed once upstream fix goes out in the next qubes update. (I tested the fix and verified that it works.)

@eloquence
Copy link
Member

Thanks for the update @creviera, keeping off the backlog for now under the assumption above, but we can revisit if needed.

@sssoleileraaa
Copy link
Contributor Author

This is an old issue and can be closed since it has not been seen in some time.

Sign up for free to subscribe to this conversation on GitHub. Already have an account? Sign in.
Labels
None yet
Projects
None yet
Development

No branches or pull requests

2 participants