chroot into a snap
Roberto Mier Escandón
roberto.escandon at canonical.com
Thu Feb 9 10:35:41 UTC 2017
Hey Thomas,
You can find the snap at [1]
Atttached are traces for:
Devmode:
- service.txt are the logs of the service
- syslog.txt and snappy-debug.security.txt are the logs to see apparmor
denials (warnings in this case). I cannot see more than ptrace ones.
Classic:
- service.classic.txt
here i don't see any denial
The only error shown is that the document has not been found. But the
url is the same in classic or devmode, so that's not the reason of the
problem.
[1] https://github.com/rmescandon/loolwsd-snap
BR.
On 09/02/17 10:32, Thomas Voß wrote:
> Hey Roberto,
>
> On Wed, Feb 8, 2017 at 4:54 PM, Roberto Mier Escandón
> <roberto.escandon at canonical.com> wrote:
>> Hey engineers,
>>
>> I need some ideas to solve this: I'm trying to snap collaboraoffice
>> online but that's not being easy at all. FYI: this is a kind of Google
>> Drive stuff so that when you request in your browser certain document,
>> it is rendered and can be edit by many at the same time, etc..
>>
>> Though I've been able to build from sources a snap package, that is only
>> working in classic confinement but not in devmode or strict.
>>
>> The reason is because the way it works:
>> - There is a server listening for documents requests
>> - for every new document requested an instance of a document manager is
>> started in a chrooted environment
>> - If requested n documents there will be n different chroot jails based
>> in same certain template
>> - document manager has certain linux capabilities to create the needed
>> roots (cap_fowner,cap_mknod,cap_sys_chroot...)
>> - the way of packaging the snap, currently, is by setting those caps and
>> call mksquashfs skipping -no-attrs option set by default by snapcraft
>>
>
> Could you please elaborate what is not working and how it fails?
> System logs, apparmor denials
> and seccomp messages would be needed here for further debugging.
>
> What is going wrong in the devmode case?
>
> Thanks,
>
> Thomas
>
>> I thought about a solution of having server in a snap and document
>> manager in another, but still there would be needed calling chroot for
>> every new document... ideas?
>>
>> BR.
>>
>> --
>> Snapcraft mailing list
>> Snapcraft at lists.snapcraft.io
>> Modify settings or unsubscribe at: https://lists.ubuntu.com/mailman/listinfo/snapcraft
>
-------------- next part --------------
Feb 9 11:20:17 lohengrin loolwsd[11923]: cmd.go:105: DEBUG: restarting into "/snap/core/current/usr/bin/snap"
Feb 9 11:20:29 lohengrin kernel: [ 7751.981735] audit_printk_skb: 1536 callbacks suppressed
Feb 9 11:20:29 lohengrin kernel: [ 7751.981747] audit: type=1400 audit(1486635629.848:55032): apparmor="ALLOWED" operation="open" profile="snap.loolwsd.loolwsd" name="/snap/core/888/lib/x86_64-linux-gnu/libdl-2.23.so" pid=11946 comm="loolwsd" requested_mask="r" denied_mask="r" fsuid=0 ouid=0
Feb 9 11:20:29 lohengrin kernel: [ 7752.017977] audit: type=1400 audit(1486635629.884:55033): apparmor="ALLOWED" operation="open" profile="snap.loolwsd.loolwsd" name="/snap/core/888/usr/lib/x86_64-linux-gnu/libstdc++.so.6.0.21" pid=11946 comm="loolwsd" requested_mask="r" denied_mask="r" fsuid=0 ouid=0
Feb 9 11:20:29 lohengrin kernel: [ 7752.020319] audit: type=1400 audit(1486635629.884:55034): apparmor="ALLOWED" operation="open" profile="snap.loolwsd.loolwsd" name="/snap/core/888/lib/x86_64-linux-gnu/libgcc_s.so.1" pid=11946 comm="loolwsd" requested_mask="r" denied_mask="r" fsuid=0 ouid=0
Feb 9 11:20:29 lohengrin kernel: [ 7752.020377] audit: type=1400 audit(1486635629.884:55035): apparmor="ALLOWED" operation="open" profile="snap.loolwsd.loolwsd" name="/snap/core/888/lib/x86_64-linux-gnu/libpthread-2.23.so" pid=11946 comm="loolwsd" requested_mask="r" denied_mask="r" fsuid=0 ouid=0
Feb 9 11:20:29 lohengrin kernel: [ 7752.020418] audit: type=1400 audit(1486635629.884:55036): apparmor="ALLOWED" operation="open" profile="snap.loolwsd.loolwsd" name="/snap/core/888/lib/x86_64-linux-gnu/libc-2.23.so" pid=11946 comm="loolwsd" requested_mask="r" denied_mask="r" fsuid=0 ouid=0
Feb 9 11:20:29 lohengrin kernel: [ 7752.116685] audit: type=1400 audit(1486635629.980:55037): apparmor="ALLOWED" operation="open" profile="snap.loolwsd.loolwsd" name="/snap/core/888/lib/x86_64-linux-gnu/libcap.so.2.24" pid=11954 comm="loolforkit" requested_mask="r" denied_mask="r" fsuid=0 ouid=0
Feb 9 11:20:29 lohengrin kernel: [ 7752.122184] audit: type=1400 audit(1486635629.988:55038): apparmor="ALLOWED" operation="open" profile="snap.loolwsd.loolwsd" name="/snap/core/888/lib/x86_64-linux-gnu/libdl-2.23.so" pid=11954 comm="loolforkit" requested_mask="r" denied_mask="r" fsuid=0 ouid=0
Feb 9 11:20:29 lohengrin kernel: [ 7752.122321] audit: type=1400 audit(1486635629.988:55039): apparmor="ALLOWED" operation="open" profile="snap.loolwsd.loolwsd" name="/snap/core/888/usr/lib/x86_64-linux-gnu/libstdc++.so.6.0.21" pid=11954 comm="loolforkit" requested_mask="r" denied_mask="r" fsuid=0 ouid=0
Feb 9 11:20:29 lohengrin kernel: [ 7752.122377] audit: type=1400 audit(1486635629.988:55040): apparmor="ALLOWED" operation="open" profile="snap.loolwsd.loolwsd" name="/snap/core/888/lib/x86_64-linux-gnu/libgcc_s.so.1" pid=11954 comm="loolforkit" requested_mask="r" denied_mask="r" fsuid=0 ouid=0
Feb 9 11:20:29 lohengrin kernel: [ 7752.122423] audit: type=1400 audit(1486635629.988:55041): apparmor="ALLOWED" operation="open" profile="snap.loolwsd.loolwsd" name="/snap/core/888/lib/x86_64-linux-gnu/libpthread-2.23.so" pid=11954 comm="loolforkit" requested_mask="r" denied_mask="r" fsuid=0 ouid=0
Feb 9 11:20:35 lohengrin kernel: [ 7757.158476] audit_printk_skb: 3 callbacks suppressed
Feb 9 11:20:35 lohengrin kernel: [ 7757.158488] audit: type=1400 audit(1486635635.024:55043): apparmor="ALLOWED" operation="ptrace" profile="snap.loolwsd.loolwsd" pid=11969 comm="ps" requested_mask="trace" denied_mask="trace" peer="unconfined"
Feb 9 11:20:35 lohengrin kernel: [ 7757.158530] audit: type=1400 audit(1486635635.024:55044): apparmor="ALLOWED" operation="ptrace" profile="snap.loolwsd.loolwsd" pid=11969 comm="ps" requested_mask="trace" denied_mask="trace" peer="unconfined"
Feb 9 11:20:35 lohengrin kernel: [ 7757.158556] audit: type=1400 audit(1486635635.024:55045): apparmor="ALLOWED" operation="ptrace" profile="snap.loolwsd.loolwsd" pid=11969 comm="ps" requested_mask="trace" denied_mask="trace" peer="unconfined"
Feb 9 11:20:35 lohengrin kernel: [ 7757.158580] audit: type=1400 audit(1486635635.024:55046): apparmor="ALLOWED" operation="ptrace" profile="snap.loolwsd.loolwsd" pid=11969 comm="ps" requested_mask="trace" denied_mask="trace" peer="unconfined"
Feb 9 11:20:35 lohengrin kernel: [ 7757.158604] audit: type=1400 audit(1486635635.024:55047): apparmor="ALLOWED" operation="ptrace" profile="snap.loolwsd.loolwsd" pid=11969 comm="ps" requested_mask="trace" denied_mask="trace" peer="unconfined"
Feb 9 11:20:35 lohengrin kernel: [ 7757.158626] audit: type=1400 audit(1486635635.024:55048): apparmor="ALLOWED" operation="ptrace" profile="snap.loolwsd.loolwsd" pid=11969 comm="ps" requested_mask="trace" denied_mask="trace" peer="unconfined"
Feb 9 11:20:35 lohengrin kernel: [ 7757.158649] audit: type=1400 audit(1486635635.024:55049): apparmor="ALLOWED" operation="ptrace" profile="snap.loolwsd.loolwsd" pid=11969 comm="ps" requested_mask="trace" denied_mask="trace" peer="unconfined"
Feb 9 11:20:35 lohengrin kernel: [ 7757.158674] audit: type=1400 audit(1486635635.024:55050): apparmor="ALLOWED" operation="ptrace" profile="snap.loolwsd.loolwsd" pid=11969 comm="ps" requested_mask="trace" denied_mask="trace" peer="unconfined"
Feb 9 11:20:35 lohengrin kernel: [ 7757.158698] audit: type=1400 audit(1486635635.024:55051): apparmor="ALLOWED" operation="ptrace" profile="snap.loolwsd.loolwsd" pid=11969 comm="ps" requested_mask="trace" denied_mask="trace" peer="unconfined"
Feb 9 11:20:35 lohengrin kernel: [ 7757.158721] audit: type=1400 audit(1486635635.024:55052): apparmor="ALLOWED" operation="ptrace" profile="snap.loolwsd.loolwsd" pid=11969 comm="ps" requested_mask="trace" denied_mask="trace" peer="unconfined"
Feb 9 11:20:40 lohengrin kernel: [ 7762.179417] audit_printk_skb: 1476 callbacks suppressed
Feb 9 11:20:40 lohengrin kernel: [ 7762.179419] audit: type=1400 audit(1486635640.044:55545): apparmor="ALLOWED" operation="ptrace" profile="snap.loolwsd.loolwsd" pid=11973 comm="ps" requested_mask="trace" denied_mask="trace" peer="unconfined"
Feb 9 11:20:40 lohengrin kernel: [ 7762.179460] audit: type=1400 audit(1486635640.044:55546): apparmor="ALLOWED" operation="ptrace" profile="snap.loolwsd.loolwsd" pid=11973 comm="ps" requested_mask="trace" denied_mask="trace" peer="unconfined"
Feb 9 11:20:40 lohengrin kernel: [ 7762.179485] audit: type=1400 audit(1486635640.044:55547): apparmor="ALLOWED" operation="ptrace" profile="snap.loolwsd.loolwsd" pid=11973 comm="ps" requested_mask="trace" denied_mask="trace" peer="unconfined"
Feb 9 11:20:40 lohengrin kernel: [ 7762.179509] audit: type=1400 audit(1486635640.044:55548): apparmor="ALLOWED" operation="ptrace" profile="snap.loolwsd.loolwsd" pid=11973 comm="ps" requested_mask="trace" denied_mask="trace" peer="unconfined"
Feb 9 11:20:40 lohengrin kernel: [ 7762.179531] audit: type=1400 audit(1486635640.044:55549): apparmor="ALLOWED" operation="ptrace" profile="snap.loolwsd.loolwsd" pid=11973 comm="ps" requested_mask="trace" denied_mask="trace" peer="unconfined"
Feb 9 11:20:40 lohengrin kernel: [ 7762.179553] audit: type=1400 audit(1486635640.044:55550): apparmor="ALLOWED" operation="ptrace" profile="snap.loolwsd.loolwsd" pid=11973 comm="ps" requested_mask="trace" denied_mask="trace" peer="unconfined"
Feb 9 11:20:40 lohengrin kernel: [ 7762.179576] audit: type=1400 audit(1486635640.044:55551): apparmor="ALLOWED" operation="ptrace" profile="snap.loolwsd.loolwsd" pid=11973 comm="ps" requested_mask="trace" denied_mask="trace" peer="unconfined"
Feb 9 11:20:40 lohengrin kernel: [ 7762.179598] audit: type=1400 audit(1486635640.044:55552): apparmor="ALLOWED" operation="ptrace" profile="snap.loolwsd.loolwsd" pid=11973 comm="ps" requested_mask="trace" denied_mask="trace" peer="unconfined"
Feb 9 11:20:40 lohengrin kernel: [ 7762.179621] audit: type=1400 audit(1486635640.044:55553): apparmor="ALLOWED" operation="ptrace" profile="snap.loolwsd.loolwsd" pid=11973 comm="ps" requested_mask="trace" denied_mask="trace" peer="unconfined"
Feb 9 11:20:40 lohengrin kernel: [ 7762.179645] audit: type=1400 audit(1486635640.044:55554): apparmor="ALLOWED" operation="ptrace" profile="snap.loolwsd.loolwsd" pid=11973 comm="ps" requested_mask="trace" denied_mask="trace" peer="unconfined"
Feb 9 11:20:45 lohengrin kernel: [ 7767.198583] audit_printk_skb: 1476 callbacks suppressed
Feb 9 11:20:45 lohengrin kernel: [ 7767.198595] audit: type=1400 audit(1486635645.064:56047): apparmor="ALLOWED" operation="ptrace" profile="snap.loolwsd.loolwsd" pid=11979 comm="ps" requested_mask="trace" denied_mask="trace" peer="unconfined"
Feb 9 11:20:45 lohengrin kernel: [ 7767.198637] audit: type=1400 audit(1486635645.064:56048): apparmor="ALLOWED" operation="ptrace" profile="snap.loolwsd.loolwsd" pid=11979 comm="ps" requested_mask="trace" denied_mask="trace" peer="unconfined"
Feb 9 11:20:45 lohengrin kernel: [ 7767.198662] audit: type=1400 audit(1486635645.064:56049): apparmor="ALLOWED" operation="ptrace" profile="snap.loolwsd.loolwsd" pid=11979 comm="ps" requested_mask="trace" denied_mask="trace" peer="unconfined"
Feb 9 11:20:45 lohengrin kernel: [ 7767.198686] audit: type=1400 audit(1486635645.064:56050): apparmor="ALLOWED" operation="ptrace" profile="snap.loolwsd.loolwsd" pid=11979 comm="ps" requested_mask="trace" denied_mask="trace" peer="unconfined"
Feb 9 11:20:45 lohengrin kernel: [ 7767.198709] audit: type=1400 audit(1486635645.064:56051): apparmor="ALLOWED" operation="ptrace" profile="snap.loolwsd.loolwsd" pid=11979 comm="ps" requested_mask="trace" denied_mask="trace" peer="unconfined"
Feb 9 11:20:45 lohengrin kernel: [ 7767.198732] audit: type=1400 audit(1486635645.064:56052): apparmor="ALLOWED" operation="ptrace" profile="snap.loolwsd.loolwsd" pid=11979 comm="ps" requested_mask="trace" denied_mask="trace" peer="unconfined"
Feb 9 11:20:45 lohengrin kernel: [ 7767.198755] audit: type=1400 audit(1486635645.064:56053): apparmor="ALLOWED" operation="ptrace" profile="snap.loolwsd.loolwsd" pid=11979 comm="ps" requested_mask="trace" denied_mask="trace" peer="unconfined"
Feb 9 11:20:45 lohengrin kernel: [ 7767.198778] audit: type=1400 audit(1486635645.064:56054): apparmor="ALLOWED" operation="ptrace" profile="snap.loolwsd.loolwsd" pid=11979 comm="ps" requested_mask="trace" denied_mask="trace" peer="unconfined"
Feb 9 11:20:45 lohengrin kernel: [ 7767.198803] audit: type=1400 audit(1486635645.064:56055): apparmor="ALLOWED" operation="ptrace" profile="snap.loolwsd.loolwsd" pid=11979 comm="ps" requested_mask="trace" denied_mask="trace" peer="unconfined"
Feb 9 11:20:45 lohengrin kernel: [ 7767.198825] audit: type=1400 audit(1486635645.064:56056): apparmor="ALLOWED" operation="ptrace" profile="snap.loolwsd.loolwsd" pid=11979 comm="ps" requested_mask="trace" denied_mask="trace" peer="unconfined"
Feb 9 11:20:50 lohengrin kernel: [ 7772.223398] audit_printk_skb: 1476 callbacks suppressed
Feb 9 11:20:50 lohengrin kernel: [ 7772.223401] audit: type=1400 audit(1486635650.088:56549): apparmor="ALLOWED" operation="ptrace" profile="snap.loolwsd.loolwsd" pid=11983 comm="ps" requested_mask="trace" denied_mask="trace" peer="unconfined"
Feb 9 11:20:50 lohengrin kernel: [ 7772.223444] audit: type=1400 audit(1486635650.088:56550): apparmor="ALLOWED" operation="ptrace" profile="snap.loolwsd.loolwsd" pid=11983 comm="ps" requested_mask="trace" denied_mask="trace" peer="unconfined"
Feb 9 11:20:50 lohengrin kernel: [ 7772.223470] audit: type=1400 audit(1486635650.088:56551): apparmor="ALLOWED" operation="ptrace" profile="snap.loolwsd.loolwsd" pid=11983 comm="ps" requested_mask="trace" denied_mask="trace" peer="unconfined"
Feb 9 11:20:50 lohengrin kernel: [ 7772.223495] audit: type=1400 audit(1486635650.088:56552): apparmor="ALLOWED" operation="ptrace" profile="snap.loolwsd.loolwsd" pid=11983 comm="ps" requested_mask="trace" denied_mask="trace" peer="unconfined"
Feb 9 11:20:50 lohengrin kernel: [ 7772.223519] audit: type=1400 audit(1486635650.088:56553): apparmor="ALLOWED" operation="ptrace" profile="snap.loolwsd.loolwsd" pid=11983 comm="ps" requested_mask="trace" denied_mask="trace" peer="unconfined"
Feb 9 11:20:50 lohengrin kernel: [ 7772.223543] audit: type=1400 audit(1486635650.088:56554): apparmor="ALLOWED" operation="ptrace" profile="snap.loolwsd.loolwsd" pid=11983 comm="ps" requested_mask="trace" denied_mask="trace" peer="unconfined"
Feb 9 11:20:50 lohengrin kernel: [ 7772.223566] audit: type=1400 audit(1486635650.088:56555): apparmor="ALLOWED" operation="ptrace" profile="snap.loolwsd.loolwsd" pid=11983 comm="ps" requested_mask="trace" denied_mask="trace" peer="unconfined"
Feb 9 11:20:50 lohengrin kernel: [ 7772.223590] audit: type=1400 audit(1486635650.088:56556): apparmor="ALLOWED" operation="ptrace" profile="snap.loolwsd.loolwsd" pid=11983 comm="ps" requested_mask="trace" denied_mask="trace" peer="unconfined"
Feb 9 11:20:50 lohengrin kernel: [ 7772.223614] audit: type=1400 audit(1486635650.088:56557): apparmor="ALLOWED" operation="ptrace" profile="snap.loolwsd.loolwsd" pid=11983 comm="ps" requested_mask="trace" denied_mask="trace" peer="unconfined"
Feb 9 11:20:50 lohengrin kernel: [ 7772.223639] audit: type=1400 audit(1486635650.088:56558): apparmor="ALLOWED" operation="ptrace" profile="snap.loolwsd.loolwsd" pid=11983 comm="ps" requested_mask="trace" denied_mask="trace" peer="unconfined"
Feb 9 11:20:55 lohengrin kernel: [ 7777.243096] audit_printk_skb: 1476 callbacks suppressed
Feb 9 11:20:55 lohengrin kernel: [ 7777.243100] audit: type=1400 audit(1486635655.108:57051): apparmor="ALLOWED" operation="ptrace" profile="snap.loolwsd.loolwsd" pid=11987 comm="ps" requested_mask="trace" denied_mask="trace" peer="unconfined"
Feb 9 11:20:55 lohengrin kernel: [ 7777.243167] audit: type=1400 audit(1486635655.108:57052): apparmor="ALLOWED" operation="ptrace" profile="snap.loolwsd.loolwsd" pid=11987 comm="ps" requested_mask="trace" denied_mask="trace" peer="unconfined"
Feb 9 11:20:55 lohengrin kernel: [ 7777.243209] audit: type=1400 audit(1486635655.108:57053): apparmor="ALLOWED" operation="ptrace" profile="snap.loolwsd.loolwsd" pid=11987 comm="ps" requested_mask="trace" denied_mask="trace" peer="unconfined"
Feb 9 11:20:55 lohengrin kernel: [ 7777.243246] audit: type=1400 audit(1486635655.108:57054): apparmor="ALLOWED" operation="ptrace" profile="snap.loolwsd.loolwsd" pid=11987 comm="ps" requested_mask="trace" denied_mask="trace" peer="unconfined"
Feb 9 11:20:55 lohengrin kernel: [ 7777.243282] audit: type=1400 audit(1486635655.108:57055): apparmor="ALLOWED" operation="ptrace" profile="snap.loolwsd.loolwsd" pid=11987 comm="ps" requested_mask="trace" denied_mask="trace" peer="unconfined"
Feb 9 11:20:55 lohengrin kernel: [ 7777.243316] audit: type=1400 audit(1486635655.108:57056): apparmor="ALLOWED" operation="ptrace" profile="snap.loolwsd.loolwsd" pid=11987 comm="ps" requested_mask="trace" denied_mask="trace" peer="unconfined"
Feb 9 11:20:55 lohengrin kernel: [ 7777.243352] audit: type=1400 audit(1486635655.108:57057): apparmor="ALLOWED" operation="ptrace" profile="snap.loolwsd.loolwsd" pid=11987 comm="ps" requested_mask="trace" denied_mask="trace" peer="unconfined"
Feb 9 11:20:55 lohengrin kernel: [ 7777.243390] audit: type=1400 audit(1486635655.108:57058): apparmor="ALLOWED" operation="ptrace" profile="snap.loolwsd.loolwsd" pid=11987 comm="ps" requested_mask="trace" denied_mask="trace" peer="unconfined"
Feb 9 11:20:55 lohengrin kernel: [ 7777.243427] audit: type=1400 audit(1486635655.108:57059): apparmor="ALLOWED" operation="ptrace" profile="snap.loolwsd.loolwsd" pid=11987 comm="ps" requested_mask="trace" denied_mask="trace" peer="unconfined"
Feb 9 11:20:55 lohengrin kernel: [ 7777.243464] audit: type=1400 audit(1486635655.108:57060): apparmor="ALLOWED" operation="ptrace" profile="snap.loolwsd.loolwsd" pid=11987 comm="ps" requested_mask="trace" denied_mask="trace" peer="unconfined"
Feb 9 11:21:00 lohengrin kernel: [ 7782.271788] audit_printk_skb: 1476 callbacks suppressed
Feb 9 11:21:00 lohengrin kernel: [ 7782.271801] audit: type=1400 audit(1486635660.137:57553): apparmor="ALLOWED" operation="ptrace" profile="snap.loolwsd.loolwsd" pid=11994 comm="ps" requested_mask="trace" denied_mask="trace" peer="unconfined"
Feb 9 11:21:00 lohengrin kernel: [ 7782.271844] audit: type=1400 audit(1486635660.137:57554): apparmor="ALLOWED" operation="ptrace" profile="snap.loolwsd.loolwsd" pid=11994 comm="ps" requested_mask="trace" denied_mask="trace" peer="unconfined"
Feb 9 11:21:00 lohengrin kernel: [ 7782.271872] audit: type=1400 audit(1486635660.137:57555): apparmor="ALLOWED" operation="ptrace" profile="snap.loolwsd.loolwsd" pid=11994 comm="ps" requested_mask="trace" denied_mask="trace" peer="unconfined"
Feb 9 11:21:00 lohengrin kernel: [ 7782.271896] audit: type=1400 audit(1486635660.137:57556): apparmor="ALLOWED" operation="ptrace" profile="snap.loolwsd.loolwsd" pid=11994 comm="ps" requested_mask="trace" denied_mask="trace" peer="unconfined"
Feb 9 11:21:00 lohengrin kernel: [ 7782.271920] audit: type=1400 audit(1486635660.137:57557): apparmor="ALLOWED" operation="ptrace" profile="snap.loolwsd.loolwsd" pid=11994 comm="ps" requested_mask="trace" denied_mask="trace" peer="unconfined"
Feb 9 11:21:00 lohengrin kernel: [ 7782.271945] audit: type=1400 audit(1486635660.137:57558): apparmor="ALLOWED" operation="ptrace" profile="snap.loolwsd.loolwsd" pid=11994 comm="ps" requested_mask="trace" denied_mask="trace" peer="unconfined"
Feb 9 11:21:00 lohengrin kernel: [ 7782.271969] audit: type=1400 audit(1486635660.137:57559): apparmor="ALLOWED" operation="ptrace" profile="snap.loolwsd.loolwsd" pid=11994 comm="ps" requested_mask="trace" denied_mask="trace" peer="unconfined"
Feb 9 11:21:00 lohengrin kernel: [ 7782.271993] audit: type=1400 audit(1486635660.137:57560): apparmor="ALLOWED" operation="ptrace" profile="snap.loolwsd.loolwsd" pid=11994 comm="ps" requested_mask="trace" denied_mask="trace" peer="unconfined"
Feb 9 11:21:00 lohengrin kernel: [ 7782.272016] audit: type=1400 audit(1486635660.137:57561): apparmor="ALLOWED" operation="ptrace" profile="snap.loolwsd.loolwsd" pid=11994 comm="ps" requested_mask="trace" denied_mask="trace" peer="unconfined"
Feb 9 11:21:00 lohengrin kernel: [ 7782.272040] audit: type=1400 audit(1486635660.137:57562): apparmor="ALLOWED" operation="ptrace" profile="snap.loolwsd.loolwsd" pid=11994 comm="ps" requested_mask="trace" denied_mask="trace" peer="unconfined"
Feb 9 11:21:05 lohengrin kernel: [ 7787.292488] audit_printk_skb: 1476 callbacks suppressed
Feb 9 11:21:05 lohengrin kernel: [ 7787.292501] audit: type=1400 audit(1486635665.157:58055): apparmor="ALLOWED" operation="ptrace" profile="snap.loolwsd.loolwsd" pid=11998 comm="ps" requested_mask="trace" denied_mask="trace" peer="unconfined"
Feb 9 11:21:05 lohengrin kernel: [ 7787.292546] audit: type=1400 audit(1486635665.157:58056): apparmor="ALLOWED" operation="ptrace" profile="snap.loolwsd.loolwsd" pid=11998 comm="ps" requested_mask="trace" denied_mask="trace" peer="unconfined"
Feb 9 11:21:05 lohengrin kernel: [ 7787.292572] audit: type=1400 audit(1486635665.157:58057): apparmor="ALLOWED" operation="ptrace" profile="snap.loolwsd.loolwsd" pid=11998 comm="ps" requested_mask="trace" denied_mask="trace" peer="unconfined"
Feb 9 11:21:05 lohengrin kernel: [ 7787.292597] audit: type=1400 audit(1486635665.157:58058): apparmor="ALLOWED" operation="ptrace" profile="snap.loolwsd.loolwsd" pid=11998 comm="ps" requested_mask="trace" denied_mask="trace" peer="unconfined"
Feb 9 11:21:05 lohengrin kernel: [ 7787.292620] audit: type=1400 audit(1486635665.157:58059): apparmor="ALLOWED" operation="ptrace" profile="snap.loolwsd.loolwsd" pid=11998 comm="ps" requested_mask="trace" denied_mask="trace" peer="unconfined"
Feb 9 11:21:05 lohengrin kernel: [ 7787.292642] audit: type=1400 audit(1486635665.157:58060): apparmor="ALLOWED" operation="ptrace" profile="snap.loolwsd.loolwsd" pid=11998 comm="ps" requested_mask="trace" denied_mask="trace" peer="unconfined"
Feb 9 11:21:05 lohengrin kernel: [ 7787.292664] audit: type=1400 audit(1486635665.157:58061): apparmor="ALLOWED" operation="ptrace" profile="snap.loolwsd.loolwsd" pid=11998 comm="ps" requested_mask="trace" denied_mask="trace" peer="unconfined"
Feb 9 11:21:05 lohengrin kernel: [ 7787.292687] audit: type=1400 audit(1486635665.157:58062): apparmor="ALLOWED" operation="ptrace" profile="snap.loolwsd.loolwsd" pid=11998 comm="ps" requested_mask="trace" denied_mask="trace" peer="unconfined"
Feb 9 11:21:05 lohengrin kernel: [ 7787.292710] audit: type=1400 audit(1486635665.157:58063): apparmor="ALLOWED" operation="ptrace" profile="snap.loolwsd.loolwsd" pid=11998 comm="ps" requested_mask="trace" denied_mask="trace" peer="unconfined"
Feb 9 11:21:05 lohengrin kernel: [ 7787.292732] audit: type=1400 audit(1486635665.157:58064): apparmor="ALLOWED" operation="ptrace" profile="snap.loolwsd.loolwsd" pid=11998 comm="ps" requested_mask="trace" denied_mask="trace" peer="unconfined"
Feb 9 11:21:10 lohengrin kernel: [ 7792.316409] audit_printk_skb: 1476 callbacks suppressed
Feb 9 11:21:10 lohengrin kernel: [ 7792.316422] audit: type=1400 audit(1486635670.181:58557): apparmor="ALLOWED" operation="ptrace" profile="snap.loolwsd.loolwsd" pid=12003 comm="ps" requested_mask="trace" denied_mask="trace" peer="unconfined"
Feb 9 11:21:10 lohengrin kernel: [ 7792.316484] audit: type=1400 audit(1486635670.181:58558): apparmor="ALLOWED" operation="ptrace" profile="snap.loolwsd.loolwsd" pid=12003 comm="ps" requested_mask="trace" denied_mask="trace" peer="unconfined"
Feb 9 11:21:10 lohengrin kernel: [ 7792.316525] audit: type=1400 audit(1486635670.181:58559): apparmor="ALLOWED" operation="ptrace" profile="snap.loolwsd.loolwsd" pid=12003 comm="ps" requested_mask="trace" denied_mask="trace" peer="unconfined"
Feb 9 11:21:10 lohengrin kernel: [ 7792.316573] audit: type=1400 audit(1486635670.181:58560): apparmor="ALLOWED" operation="ptrace" profile="snap.loolwsd.loolwsd" pid=12003 comm="ps" requested_mask="trace" denied_mask="trace" peer="unconfined"
Feb 9 11:21:10 lohengrin kernel: [ 7792.316621] audit: type=1400 audit(1486635670.181:58561): apparmor="ALLOWED" operation="ptrace" profile="snap.loolwsd.loolwsd" pid=12003 comm="ps" requested_mask="trace" denied_mask="trace" peer="unconfined"
Feb 9 11:21:10 lohengrin kernel: [ 7792.316667] audit: type=1400 audit(1486635670.181:58562): apparmor="ALLOWED" operation="ptrace" profile="snap.loolwsd.loolwsd" pid=12003 comm="ps" requested_mask="trace" denied_mask="trace" peer="unconfined"
Feb 9 11:21:10 lohengrin kernel: [ 7792.316714] audit: type=1400 audit(1486635670.181:58563): apparmor="ALLOWED" operation="ptrace" profile="snap.loolwsd.loolwsd" pid=12003 comm="ps" requested_mask="trace" denied_mask="trace" peer="unconfined"
Feb 9 11:21:10 lohengrin kernel: [ 7792.316761] audit: type=1400 audit(1486635670.181:58564): apparmor="ALLOWED" operation="ptrace" profile="snap.loolwsd.loolwsd" pid=12003 comm="ps" requested_mask="trace" denied_mask="trace" peer="unconfined"
Feb 9 11:21:10 lohengrin kernel: [ 7792.316807] audit: type=1400 audit(1486635670.181:58565): apparmor="ALLOWED" operation="ptrace" profile="snap.loolwsd.loolwsd" pid=12003 comm="ps" requested_mask="trace" denied_mask="trace" peer="unconfined"
Feb 9 11:21:10 lohengrin kernel: [ 7792.316863] audit: type=1400 audit(1486635670.181:58566): apparmor="ALLOWED" operation="ptrace" profile="snap.loolwsd.loolwsd" pid=12003 comm="ps" requested_mask="trace" denied_mask="trace" peer="unconfined"
Feb 9 11:21:15 lohengrin kernel: [ 7797.339245] audit_printk_skb: 1476 callbacks suppressed
Feb 9 11:21:15 lohengrin kernel: [ 7797.339257] audit: type=1400 audit(1486635675.205:59059): apparmor="ALLOWED" operation="ptrace" profile="snap.loolwsd.loolwsd" pid=12015 comm="ps" requested_mask="trace" denied_mask="trace" peer="unconfined"
Feb 9 11:21:15 lohengrin kernel: [ 7797.339299] audit: type=1400 audit(1486635675.205:59060): apparmor="ALLOWED" operation="ptrace" profile="snap.loolwsd.loolwsd" pid=12015 comm="ps" requested_mask="trace" denied_mask="trace" peer="unconfined"
Feb 9 11:21:15 lohengrin kernel: [ 7797.339325] audit: type=1400 audit(1486635675.205:59061): apparmor="ALLOWED" operation="ptrace" profile="snap.loolwsd.loolwsd" pid=12015 comm="ps" requested_mask="trace" denied_mask="trace" peer="unconfined"
Feb 9 11:21:15 lohengrin kernel: [ 7797.339349] audit: type=1400 audit(1486635675.205:59062): apparmor="ALLOWED" operation="ptrace" profile="snap.loolwsd.loolwsd" pid=12015 comm="ps" requested_mask="trace" denied_mask="trace" peer="unconfined"
Feb 9 11:21:15 lohengrin kernel: [ 7797.339372] audit: type=1400 audit(1486635675.205:59063): apparmor="ALLOWED" operation="ptrace" profile="snap.loolwsd.loolwsd" pid=12015 comm="ps" requested_mask="trace" denied_mask="trace" peer="unconfined"
Feb 9 11:21:15 lohengrin kernel: [ 7797.339394] audit: type=1400 audit(1486635675.205:59064): apparmor="ALLOWED" operation="ptrace" profile="snap.loolwsd.loolwsd" pid=12015 comm="ps" requested_mask="trace" denied_mask="trace" peer="unconfined"
Feb 9 11:21:15 lohengrin kernel: [ 7797.339416] audit: type=1400 audit(1486635675.205:59065): apparmor="ALLOWED" operation="ptrace" profile="snap.loolwsd.loolwsd" pid=12015 comm="ps" requested_mask="trace" denied_mask="trace" peer="unconfined"
Feb 9 11:21:15 lohengrin kernel: [ 7797.339439] audit: type=1400 audit(1486635675.205:59066): apparmor="ALLOWED" operation="ptrace" profile="snap.loolwsd.loolwsd" pid=12015 comm="ps" requested_mask="trace" denied_mask="trace" peer="unconfined"
Feb 9 11:21:15 lohengrin kernel: [ 7797.339461] audit: type=1400 audit(1486635675.205:59067): apparmor="ALLOWED" operation="ptrace" profile="snap.loolwsd.loolwsd" pid=12015 comm="ps" requested_mask="trace" denied_mask="trace" peer="unconfined"
Feb 9 11:21:15 lohengrin kernel: [ 7797.339483] audit: type=1400 audit(1486635675.205:59068): apparmor="ALLOWED" operation="ptrace" profile="snap.loolwsd.loolwsd" pid=12015 comm="ps" requested_mask="trace" denied_mask="trace" peer="unconfined"
Feb 9 11:21:20 lohengrin kernel: [ 7802.365154] audit_printk_skb: 1494 callbacks suppressed
Feb 9 11:21:20 lohengrin kernel: [ 7802.365166] audit: type=1400 audit(1486635680.229:59567): apparmor="ALLOWED" operation="ptrace" profile="snap.loolwsd.loolwsd" pid=12019 comm="ps" requested_mask="trace" denied_mask="trace" peer="unconfined"
Feb 9 11:21:20 lohengrin kernel: [ 7802.365211] audit: type=1400 audit(1486635680.229:59568): apparmor="ALLOWED" operation="ptrace" profile="snap.loolwsd.loolwsd" pid=12019 comm="ps" requested_mask="trace" denied_mask="trace" peer="unconfined"
Feb 9 11:21:20 lohengrin kernel: [ 7802.365237] audit: type=1400 audit(1486635680.229:59569): apparmor="ALLOWED" operation="ptrace" profile="snap.loolwsd.loolwsd" pid=12019 comm="ps" requested_mask="trace" denied_mask="trace" peer="unconfined"
Feb 9 11:21:20 lohengrin kernel: [ 7802.365260] audit: type=1400 audit(1486635680.229:59570): apparmor="ALLOWED" operation="ptrace" profile="snap.loolwsd.loolwsd" pid=12019 comm="ps" requested_mask="trace" denied_mask="trace" peer="unconfined"
Feb 9 11:21:20 lohengrin kernel: [ 7802.365283] audit: type=1400 audit(1486635680.229:59571): apparmor="ALLOWED" operation="ptrace" profile="snap.loolwsd.loolwsd" pid=12019 comm="ps" requested_mask="trace" denied_mask="trace" peer="unconfined"
Feb 9 11:21:20 lohengrin kernel: [ 7802.365306] audit: type=1400 audit(1486635680.229:59572): apparmor="ALLOWED" operation="ptrace" profile="snap.loolwsd.loolwsd" pid=12019 comm="ps" requested_mask="trace" denied_mask="trace" peer="unconfined"
Feb 9 11:21:20 lohengrin kernel: [ 7802.365328] audit: type=1400 audit(1486635680.229:59573): apparmor="ALLOWED" operation="ptrace" profile="snap.loolwsd.loolwsd" pid=12019 comm="ps" requested_mask="trace" denied_mask="trace" peer="unconfined"
Feb 9 11:21:20 lohengrin kernel: [ 7802.365350] audit: type=1400 audit(1486635680.229:59574): apparmor="ALLOWED" operation="ptrace" profile="snap.loolwsd.loolwsd" pid=12019 comm="ps" requested_mask="trace" denied_mask="trace" peer="unconfined"
Feb 9 11:21:20 lohengrin kernel: [ 7802.365374] audit: type=1400 audit(1486635680.229:59575): apparmor="ALLOWED" operation="ptrace" profile="snap.loolwsd.loolwsd" pid=12019 comm="ps" requested_mask="trace" denied_mask="trace" peer="unconfined"
Feb 9 11:21:20 lohengrin kernel: [ 7802.365398] audit: type=1400 audit(1486635680.229:59576): apparmor="ALLOWED" operation="ptrace" profile="snap.loolwsd.loolwsd" pid=12019 comm="ps" requested_mask="trace" denied_mask="trace" peer="unconfined"
-------------- next part --------------
= AppArmor =
Time: Feb 9 11:20:29
Log: apparmor="ALLOWED" operation="open" profile="snap.loolwsd.loolwsd" name="/snap/core/888/lib/x86_64-linux-gnu/libdl-2.23.so" pid=11946 comm="loolwsd" requested_mask="r" denied_mask="r" fsuid=0 ouid=0
File: /snap/core/888/lib/x86_64-linux-gnu/libdl-2.23.so (read)
Suggestion:
* adjust program to read necessary files from $SNAP, $SNAP_DATA, $SNAP_COMMON, $SNAP_USER_DATA or $SNAP_USER_COMMON
= AppArmor =
Time: Feb 9 11:20:29
Log: apparmor="ALLOWED" operation="open" profile="snap.loolwsd.loolwsd" name="/snap/core/888/usr/lib/x86_64-linux-gnu/libstdc++.so.6.0.21" pid=11946 comm="loolwsd" requested_mask="r" denied_mask="r" fsuid=0 ouid=0
File: /snap/core/888/usr/lib/x86_64-linux-gnu/libstdc++.so.6.0.21 (read)
Suggestion:
* adjust program to read necessary files from $SNAP, $SNAP_DATA, $SNAP_COMMON, $SNAP_USER_DATA or $SNAP_USER_COMMON
= AppArmor =
Time: Feb 9 11:20:29
Log: apparmor="ALLOWED" operation="open" profile="snap.loolwsd.loolwsd" name="/snap/core/888/lib/x86_64-linux-gnu/libgcc_s.so.1" pid=11946 comm="loolwsd" requested_mask="r" denied_mask="r" fsuid=0 ouid=0
File: /snap/core/888/lib/x86_64-linux-gnu/libgcc_s.so.1 (read)
Suggestion:
* adjust program to read necessary files from $SNAP, $SNAP_DATA, $SNAP_COMMON, $SNAP_USER_DATA or $SNAP_USER_COMMON
= AppArmor =
Time: Feb 9 11:20:29
Log: apparmor="ALLOWED" operation="open" profile="snap.loolwsd.loolwsd" name="/snap/core/888/lib/x86_64-linux-gnu/libpthread-2.23.so" pid=11946 comm="loolwsd" requested_mask="r" denied_mask="r" fsuid=0 ouid=0
File: /snap/core/888/lib/x86_64-linux-gnu/libpthread-2.23.so (read)
Suggestion:
* adjust program to read necessary files from $SNAP, $SNAP_DATA, $SNAP_COMMON, $SNAP_USER_DATA or $SNAP_USER_COMMON
= AppArmor =
Time: Feb 9 11:20:29
Log: apparmor="ALLOWED" operation="open" profile="snap.loolwsd.loolwsd" name="/snap/core/888/lib/x86_64-linux-gnu/libc-2.23.so" pid=11946 comm="loolwsd" requested_mask="r" denied_mask="r" fsuid=0 ouid=0
File: /snap/core/888/lib/x86_64-linux-gnu/libc-2.23.so (read)
Suggestion:
* adjust program to read necessary files from $SNAP, $SNAP_DATA, $SNAP_COMMON, $SNAP_USER_DATA or $SNAP_USER_COMMON
= AppArmor =
Time: Feb 9 11:20:29
Log: apparmor="ALLOWED" operation="open" profile="snap.loolwsd.loolwsd" name="/snap/core/888/lib/x86_64-linux-gnu/libcap.so.2.24" pid=11954 comm="loolforkit" requested_mask="r" denied_mask="r" fsuid=0 ouid=0
File: /snap/core/888/lib/x86_64-linux-gnu/libcap.so.2.24 (read)
Suggestion:
* adjust program to read necessary files from $SNAP, $SNAP_DATA, $SNAP_COMMON, $SNAP_USER_DATA or $SNAP_USER_COMMON
= AppArmor =
Time: Feb 9 11:20:29
Log: apparmor="ALLOWED" operation="open" profile="snap.loolwsd.loolwsd" name="/snap/core/888/lib/x86_64-linux-gnu/libdl-2.23.so" pid=11954 comm="loolforkit" requested_mask="r" denied_mask="r" fsuid=0 ouid=0
File: /snap/core/888/lib/x86_64-linux-gnu/libdl-2.23.so (read)
Suggestion:
* adjust program to read necessary files from $SNAP, $SNAP_DATA, $SNAP_COMMON, $SNAP_USER_DATA or $SNAP_USER_COMMON
= AppArmor =
Time: Feb 9 11:20:29
Log: apparmor="ALLOWED" operation="open" profile="snap.loolwsd.loolwsd" name="/snap/core/888/usr/lib/x86_64-linux-gnu/libstdc++.so.6.0.21" pid=11954 comm="loolforkit" requested_mask="r" denied_mask="r" fsuid=0 ouid=0
File: /snap/core/888/usr/lib/x86_64-linux-gnu/libstdc++.so.6.0.21 (read)
Suggestion:
* adjust program to read necessary files from $SNAP, $SNAP_DATA, $SNAP_COMMON, $SNAP_USER_DATA or $SNAP_USER_COMMON
= AppArmor =
Time: Feb 9 11:20:29
Log: apparmor="ALLOWED" operation="open" profile="snap.loolwsd.loolwsd" name="/snap/core/888/lib/x86_64-linux-gnu/libgcc_s.so.1" pid=11954 comm="loolforkit" requested_mask="r" denied_mask="r" fsuid=0 ouid=0
File: /snap/core/888/lib/x86_64-linux-gnu/libgcc_s.so.1 (read)
Suggestion:
* adjust program to read necessary files from $SNAP, $SNAP_DATA, $SNAP_COMMON, $SNAP_USER_DATA or $SNAP_USER_COMMON
= AppArmor =
Time: Feb 9 11:20:29
Log: apparmor="ALLOWED" operation="open" profile="snap.loolwsd.loolwsd" name="/snap/core/888/lib/x86_64-linux-gnu/libpthread-2.23.so" pid=11954 comm="loolforkit" requested_mask="r" denied_mask="r" fsuid=0 ouid=0
File: /snap/core/888/lib/x86_64-linux-gnu/libpthread-2.23.so (read)
Suggestion:
* adjust program to read necessary files from $SNAP, $SNAP_DATA, $SNAP_COMMON, $SNAP_USER_DATA or $SNAP_USER_COMMON
= AppArmor =
Time: Feb 9 11:20:35
Log: apparmor="ALLOWED" operation="ptrace" profile="snap.loolwsd.loolwsd" pid=11969 comm="ps" requested_mask="trace" denied_mask="trace" peer="unconfined"
= AppArmor =
Time: Feb 9 11:20:35
Log: apparmor="ALLOWED" operation="ptrace" profile="snap.loolwsd.loolwsd" pid=11969 comm="ps" requested_mask="trace" denied_mask="trace" peer="unconfined"
= AppArmor =
Time: Feb 9 11:20:35
Log: apparmor="ALLOWED" operation="ptrace" profile="snap.loolwsd.loolwsd" pid=11969 comm="ps" requested_mask="trace" denied_mask="trace" peer="unconfined"
= AppArmor =
Time: Feb 9 11:20:35
Log: apparmor="ALLOWED" operation="ptrace" profile="snap.loolwsd.loolwsd" pid=11969 comm="ps" requested_mask="trace" denied_mask="trace" peer="unconfined"
= AppArmor =
Time: Feb 9 11:20:35
Log: apparmor="ALLOWED" operation="ptrace" profile="snap.loolwsd.loolwsd" pid=11969 comm="ps" requested_mask="trace" denied_mask="trace" peer="unconfined"
= AppArmor =
Time: Feb 9 11:20:35
Log: apparmor="ALLOWED" operation="ptrace" profile="snap.loolwsd.loolwsd" pid=11969 comm="ps" requested_mask="trace" denied_mask="trace" peer="unconfined"
= AppArmor =
Time: Feb 9 11:20:35
Log: apparmor="ALLOWED" operation="ptrace" profile="snap.loolwsd.loolwsd" pid=11969 comm="ps" requested_mask="trace" denied_mask="trace" peer="unconfined"
= AppArmor =
Time: Feb 9 11:20:35
Log: apparmor="ALLOWED" operation="ptrace" profile="snap.loolwsd.loolwsd" pid=11969 comm="ps" requested_mask="trace" denied_mask="trace" peer="unconfined"
= AppArmor =
Time: Feb 9 11:20:35
Log: apparmor="ALLOWED" operation="ptrace" profile="snap.loolwsd.loolwsd" pid=11969 comm="ps" requested_mask="trace" denied_mask="trace" peer="unconfined"
= AppArmor =
Time: Feb 9 11:20:35
Log: apparmor="ALLOWED" operation="ptrace" profile="snap.loolwsd.loolwsd" pid=11969 comm="ps" requested_mask="trace" denied_mask="trace" peer="unconfined"
= AppArmor =
Time: Feb 9 11:20:40
Log: apparmor="ALLOWED" operation="ptrace" profile="snap.loolwsd.loolwsd" pid=11973 comm="ps" requested_mask="trace" denied_mask="trace" peer="unconfined"
= AppArmor =
Time: Feb 9 11:20:40
Log: apparmor="ALLOWED" operation="ptrace" profile="snap.loolwsd.loolwsd" pid=11973 comm="ps" requested_mask="trace" denied_mask="trace" peer="unconfined"
= AppArmor =
Time: Feb 9 11:20:40
Log: apparmor="ALLOWED" operation="ptrace" profile="snap.loolwsd.loolwsd" pid=11973 comm="ps" requested_mask="trace" denied_mask="trace" peer="unconfined"
= AppArmor =
Time: Feb 9 11:20:40
Log: apparmor="ALLOWED" operation="ptrace" profile="snap.loolwsd.loolwsd" pid=11973 comm="ps" requested_mask="trace" denied_mask="trace" peer="unconfined"
= AppArmor =
Time: Feb 9 11:20:40
Log: apparmor="ALLOWED" operation="ptrace" profile="snap.loolwsd.loolwsd" pid=11973 comm="ps" requested_mask="trace" denied_mask="trace" peer="unconfined"
= AppArmor =
Time: Feb 9 11:20:40
Log: apparmor="ALLOWED" operation="ptrace" profile="snap.loolwsd.loolwsd" pid=11973 comm="ps" requested_mask="trace" denied_mask="trace" peer="unconfined"
= AppArmor =
Time: Feb 9 11:20:40
Log: apparmor="ALLOWED" operation="ptrace" profile="snap.loolwsd.loolwsd" pid=11973 comm="ps" requested_mask="trace" denied_mask="trace" peer="unconfined"
= AppArmor =
Time: Feb 9 11:20:40
Log: apparmor="ALLOWED" operation="ptrace" profile="snap.loolwsd.loolwsd" pid=11973 comm="ps" requested_mask="trace" denied_mask="trace" peer="unconfined"
= AppArmor =
Time: Feb 9 11:20:40
Log: apparmor="ALLOWED" operation="ptrace" profile="snap.loolwsd.loolwsd" pid=11973 comm="ps" requested_mask="trace" denied_mask="trace" peer="unconfined"
= AppArmor =
Time: Feb 9 11:20:40
Log: apparmor="ALLOWED" operation="ptrace" profile="snap.loolwsd.loolwsd" pid=11973 comm="ps" requested_mask="trace" denied_mask="trace" peer="unconfined"
= AppArmor =
Time: Feb 9 11:20:45
Log: apparmor="ALLOWED" operation="ptrace" profile="snap.loolwsd.loolwsd" pid=11979 comm="ps" requested_mask="trace" denied_mask="trace" peer="unconfined"
= AppArmor =
Time: Feb 9 11:20:45
Log: apparmor="ALLOWED" operation="ptrace" profile="snap.loolwsd.loolwsd" pid=11979 comm="ps" requested_mask="trace" denied_mask="trace" peer="unconfined"
= AppArmor =
Time: Feb 9 11:20:45
Log: apparmor="ALLOWED" operation="ptrace" profile="snap.loolwsd.loolwsd" pid=11979 comm="ps" requested_mask="trace" denied_mask="trace" peer="unconfined"
= AppArmor =
Time: Feb 9 11:20:45
Log: apparmor="ALLOWED" operation="ptrace" profile="snap.loolwsd.loolwsd" pid=11979 comm="ps" requested_mask="trace" denied_mask="trace" peer="unconfined"
= AppArmor =
Time: Feb 9 11:20:45
Log: apparmor="ALLOWED" operation="ptrace" profile="snap.loolwsd.loolwsd" pid=11979 comm="ps" requested_mask="trace" denied_mask="trace" peer="unconfined"
= AppArmor =
Time: Feb 9 11:20:45
Log: apparmor="ALLOWED" operation="ptrace" profile="snap.loolwsd.loolwsd" pid=11979 comm="ps" requested_mask="trace" denied_mask="trace" peer="unconfined"
= AppArmor =
Time: Feb 9 11:20:45
Log: apparmor="ALLOWED" operation="ptrace" profile="snap.loolwsd.loolwsd" pid=11979 comm="ps" requested_mask="trace" denied_mask="trace" peer="unconfined"
= AppArmor =
Time: Feb 9 11:20:45
Log: apparmor="ALLOWED" operation="ptrace" profile="snap.loolwsd.loolwsd" pid=11979 comm="ps" requested_mask="trace" denied_mask="trace" peer="unconfined"
= AppArmor =
Time: Feb 9 11:20:45
Log: apparmor="ALLOWED" operation="ptrace" profile="snap.loolwsd.loolwsd" pid=11979 comm="ps" requested_mask="trace" denied_mask="trace" peer="unconfined"
= AppArmor =
Time: Feb 9 11:20:45
Log: apparmor="ALLOWED" operation="ptrace" profile="snap.loolwsd.loolwsd" pid=11979 comm="ps" requested_mask="trace" denied_mask="trace" peer="unconfined"
= AppArmor =
Time: Feb 9 11:20:50
Log: apparmor="ALLOWED" operation="ptrace" profile="snap.loolwsd.loolwsd" pid=11983 comm="ps" requested_mask="trace" denied_mask="trace" peer="unconfined"
= AppArmor =
Time: Feb 9 11:20:50
Log: apparmor="ALLOWED" operation="ptrace" profile="snap.loolwsd.loolwsd" pid=11983 comm="ps" requested_mask="trace" denied_mask="trace" peer="unconfined"
= AppArmor =
Time: Feb 9 11:20:50
Log: apparmor="ALLOWED" operation="ptrace" profile="snap.loolwsd.loolwsd" pid=11983 comm="ps" requested_mask="trace" denied_mask="trace" peer="unconfined"
= AppArmor =
Time: Feb 9 11:20:50
Log: apparmor="ALLOWED" operation="ptrace" profile="snap.loolwsd.loolwsd" pid=11983 comm="ps" requested_mask="trace" denied_mask="trace" peer="unconfined"
= AppArmor =
Time: Feb 9 11:20:50
Log: apparmor="ALLOWED" operation="ptrace" profile="snap.loolwsd.loolwsd" pid=11983 comm="ps" requested_mask="trace" denied_mask="trace" peer="unconfined"
= AppArmor =
Time: Feb 9 11:20:50
Log: apparmor="ALLOWED" operation="ptrace" profile="snap.loolwsd.loolwsd" pid=11983 comm="ps" requested_mask="trace" denied_mask="trace" peer="unconfined"
= AppArmor =
Time: Feb 9 11:20:50
Log: apparmor="ALLOWED" operation="ptrace" profile="snap.loolwsd.loolwsd" pid=11983 comm="ps" requested_mask="trace" denied_mask="trace" peer="unconfined"
= AppArmor =
Time: Feb 9 11:20:50
Log: apparmor="ALLOWED" operation="ptrace" profile="snap.loolwsd.loolwsd" pid=11983 comm="ps" requested_mask="trace" denied_mask="trace" peer="unconfined"
= AppArmor =
Time: Feb 9 11:20:50
Log: apparmor="ALLOWED" operation="ptrace" profile="snap.loolwsd.loolwsd" pid=11983 comm="ps" requested_mask="trace" denied_mask="trace" peer="unconfined"
= AppArmor =
Time: Feb 9 11:20:50
Log: apparmor="ALLOWED" operation="ptrace" profile="snap.loolwsd.loolwsd" pid=11983 comm="ps" requested_mask="trace" denied_mask="trace" peer="unconfined"
= AppArmor =
Time: Feb 9 11:20:55
Log: apparmor="ALLOWED" operation="ptrace" profile="snap.loolwsd.loolwsd" pid=11987 comm="ps" requested_mask="trace" denied_mask="trace" peer="unconfined"
= AppArmor =
Time: Feb 9 11:20:55
Log: apparmor="ALLOWED" operation="ptrace" profile="snap.loolwsd.loolwsd" pid=11987 comm="ps" requested_mask="trace" denied_mask="trace" peer="unconfined"
= AppArmor =
Time: Feb 9 11:20:55
Log: apparmor="ALLOWED" operation="ptrace" profile="snap.loolwsd.loolwsd" pid=11987 comm="ps" requested_mask="trace" denied_mask="trace" peer="unconfined"
= AppArmor =
Time: Feb 9 11:20:55
Log: apparmor="ALLOWED" operation="ptrace" profile="snap.loolwsd.loolwsd" pid=11987 comm="ps" requested_mask="trace" denied_mask="trace" peer="unconfined"
= AppArmor =
Time: Feb 9 11:20:55
Log: apparmor="ALLOWED" operation="ptrace" profile="snap.loolwsd.loolwsd" pid=11987 comm="ps" requested_mask="trace" denied_mask="trace" peer="unconfined"
= AppArmor =
Time: Feb 9 11:20:55
Log: apparmor="ALLOWED" operation="ptrace" profile="snap.loolwsd.loolwsd" pid=11987 comm="ps" requested_mask="trace" denied_mask="trace" peer="unconfined"
= AppArmor =
Time: Feb 9 11:20:55
Log: apparmor="ALLOWED" operation="ptrace" profile="snap.loolwsd.loolwsd" pid=11987 comm="ps" requested_mask="trace" denied_mask="trace" peer="unconfined"
= AppArmor =
Time: Feb 9 11:20:55
Log: apparmor="ALLOWED" operation="ptrace" profile="snap.loolwsd.loolwsd" pid=11987 comm="ps" requested_mask="trace" denied_mask="trace" peer="unconfined"
= AppArmor =
Time: Feb 9 11:20:55
Log: apparmor="ALLOWED" operation="ptrace" profile="snap.loolwsd.loolwsd" pid=11987 comm="ps" requested_mask="trace" denied_mask="trace" peer="unconfined"
= AppArmor =
Time: Feb 9 11:20:55
Log: apparmor="ALLOWED" operation="ptrace" profile="snap.loolwsd.loolwsd" pid=11987 comm="ps" requested_mask="trace" denied_mask="trace" peer="unconfined"
= AppArmor =
Time: Feb 9 11:21:00
Log: apparmor="ALLOWED" operation="ptrace" profile="snap.loolwsd.loolwsd" pid=11994 comm="ps" requested_mask="trace" denied_mask="trace" peer="unconfined"
= AppArmor =
Time: Feb 9 11:21:00
Log: apparmor="ALLOWED" operation="ptrace" profile="snap.loolwsd.loolwsd" pid=11994 comm="ps" requested_mask="trace" denied_mask="trace" peer="unconfined"
= AppArmor =
Time: Feb 9 11:21:00
Log: apparmor="ALLOWED" operation="ptrace" profile="snap.loolwsd.loolwsd" pid=11994 comm="ps" requested_mask="trace" denied_mask="trace" peer="unconfined"
= AppArmor =
Time: Feb 9 11:21:00
Log: apparmor="ALLOWED" operation="ptrace" profile="snap.loolwsd.loolwsd" pid=11994 comm="ps" requested_mask="trace" denied_mask="trace" peer="unconfined"
= AppArmor =
Time: Feb 9 11:21:00
Log: apparmor="ALLOWED" operation="ptrace" profile="snap.loolwsd.loolwsd" pid=11994 comm="ps" requested_mask="trace" denied_mask="trace" peer="unconfined"
= AppArmor =
Time: Feb 9 11:21:00
Log: apparmor="ALLOWED" operation="ptrace" profile="snap.loolwsd.loolwsd" pid=11994 comm="ps" requested_mask="trace" denied_mask="trace" peer="unconfined"
= AppArmor =
Time: Feb 9 11:21:00
Log: apparmor="ALLOWED" operation="ptrace" profile="snap.loolwsd.loolwsd" pid=11994 comm="ps" requested_mask="trace" denied_mask="trace" peer="unconfined"
= AppArmor =
Time: Feb 9 11:21:00
Log: apparmor="ALLOWED" operation="ptrace" profile="snap.loolwsd.loolwsd" pid=11994 comm="ps" requested_mask="trace" denied_mask="trace" peer="unconfined"
= AppArmor =
Time: Feb 9 11:21:00
Log: apparmor="ALLOWED" operation="ptrace" profile="snap.loolwsd.loolwsd" pid=11994 comm="ps" requested_mask="trace" denied_mask="trace" peer="unconfined"
= AppArmor =
Time: Feb 9 11:21:00
Log: apparmor="ALLOWED" operation="ptrace" profile="snap.loolwsd.loolwsd" pid=11994 comm="ps" requested_mask="trace" denied_mask="trace" peer="unconfined"
= AppArmor =
Time: Feb 9 11:21:05
Log: apparmor="ALLOWED" operation="ptrace" profile="snap.loolwsd.loolwsd" pid=11998 comm="ps" requested_mask="trace" denied_mask="trace" peer="unconfined"
= AppArmor =
Time: Feb 9 11:21:05
Log: apparmor="ALLOWED" operation="ptrace" profile="snap.loolwsd.loolwsd" pid=11998 comm="ps" requested_mask="trace" denied_mask="trace" peer="unconfined"
= AppArmor =
Time: Feb 9 11:21:05
Log: apparmor="ALLOWED" operation="ptrace" profile="snap.loolwsd.loolwsd" pid=11998 comm="ps" requested_mask="trace" denied_mask="trace" peer="unconfined"
= AppArmor =
Time: Feb 9 11:21:05
Log: apparmor="ALLOWED" operation="ptrace" profile="snap.loolwsd.loolwsd" pid=11998 comm="ps" requested_mask="trace" denied_mask="trace" peer="unconfined"
= AppArmor =
Time: Feb 9 11:21:05
Log: apparmor="ALLOWED" operation="ptrace" profile="snap.loolwsd.loolwsd" pid=11998 comm="ps" requested_mask="trace" denied_mask="trace" peer="unconfined"
= AppArmor =
Time: Feb 9 11:21:05
Log: apparmor="ALLOWED" operation="ptrace" profile="snap.loolwsd.loolwsd" pid=11998 comm="ps" requested_mask="trace" denied_mask="trace" peer="unconfined"
= AppArmor =
Time: Feb 9 11:21:05
Log: apparmor="ALLOWED" operation="ptrace" profile="snap.loolwsd.loolwsd" pid=11998 comm="ps" requested_mask="trace" denied_mask="trace" peer="unconfined"
= AppArmor =
Time: Feb 9 11:21:05
Log: apparmor="ALLOWED" operation="ptrace" profile="snap.loolwsd.loolwsd" pid=11998 comm="ps" requested_mask="trace" denied_mask="trace" peer="unconfined"
= AppArmor =
Time: Feb 9 11:21:05
Log: apparmor="ALLOWED" operation="ptrace" profile="snap.loolwsd.loolwsd" pid=11998 comm="ps" requested_mask="trace" denied_mask="trace" peer="unconfined"
= AppArmor =
Time: Feb 9 11:21:05
Log: apparmor="ALLOWED" operation="ptrace" profile="snap.loolwsd.loolwsd" pid=11998 comm="ps" requested_mask="trace" denied_mask="trace" peer="unconfined"
= AppArmor =
Time: Feb 9 11:21:10
Log: apparmor="ALLOWED" operation="ptrace" profile="snap.loolwsd.loolwsd" pid=12003 comm="ps" requested_mask="trace" denied_mask="trace" peer="unconfined"
= AppArmor =
Time: Feb 9 11:21:10
Log: apparmor="ALLOWED" operation="ptrace" profile="snap.loolwsd.loolwsd" pid=12003 comm="ps" requested_mask="trace" denied_mask="trace" peer="unconfined"
= AppArmor =
Time: Feb 9 11:21:10
Log: apparmor="ALLOWED" operation="ptrace" profile="snap.loolwsd.loolwsd" pid=12003 comm="ps" requested_mask="trace" denied_mask="trace" peer="unconfined"
= AppArmor =
Time: Feb 9 11:21:10
Log: apparmor="ALLOWED" operation="ptrace" profile="snap.loolwsd.loolwsd" pid=12003 comm="ps" requested_mask="trace" denied_mask="trace" peer="unconfined"
= AppArmor =
Time: Feb 9 11:21:10
Log: apparmor="ALLOWED" operation="ptrace" profile="snap.loolwsd.loolwsd" pid=12003 comm="ps" requested_mask="trace" denied_mask="trace" peer="unconfined"
= AppArmor =
Time: Feb 9 11:21:10
Log: apparmor="ALLOWED" operation="ptrace" profile="snap.loolwsd.loolwsd" pid=12003 comm="ps" requested_mask="trace" denied_mask="trace" peer="unconfined"
= AppArmor =
Time: Feb 9 11:21:10
Log: apparmor="ALLOWED" operation="ptrace" profile="snap.loolwsd.loolwsd" pid=12003 comm="ps" requested_mask="trace" denied_mask="trace" peer="unconfined"
= AppArmor =
Time: Feb 9 11:21:10
Log: apparmor="ALLOWED" operation="ptrace" profile="snap.loolwsd.loolwsd" pid=12003 comm="ps" requested_mask="trace" denied_mask="trace" peer="unconfined"
= AppArmor =
Time: Feb 9 11:21:10
Log: apparmor="ALLOWED" operation="ptrace" profile="snap.loolwsd.loolwsd" pid=12003 comm="ps" requested_mask="trace" denied_mask="trace" peer="unconfined"
= AppArmor =
Time: Feb 9 11:21:10
Log: apparmor="ALLOWED" operation="ptrace" profile="snap.loolwsd.loolwsd" pid=12003 comm="ps" requested_mask="trace" denied_mask="trace" peer="unconfined"
= AppArmor =
Time: Feb 9 11:21:15
Log: apparmor="ALLOWED" operation="ptrace" profile="snap.loolwsd.loolwsd" pid=12015 comm="ps" requested_mask="trace" denied_mask="trace" peer="unconfined"
= AppArmor =
Time: Feb 9 11:21:15
Log: apparmor="ALLOWED" operation="ptrace" profile="snap.loolwsd.loolwsd" pid=12015 comm="ps" requested_mask="trace" denied_mask="trace" peer="unconfined"
= AppArmor =
Time: Feb 9 11:21:15
Log: apparmor="ALLOWED" operation="ptrace" profile="snap.loolwsd.loolwsd" pid=12015 comm="ps" requested_mask="trace" denied_mask="trace" peer="unconfined"
= AppArmor =
Time: Feb 9 11:21:15
Log: apparmor="ALLOWED" operation="ptrace" profile="snap.loolwsd.loolwsd" pid=12015 comm="ps" requested_mask="trace" denied_mask="trace" peer="unconfined"
= AppArmor =
Time: Feb 9 11:21:15
Log: apparmor="ALLOWED" operation="ptrace" profile="snap.loolwsd.loolwsd" pid=12015 comm="ps" requested_mask="trace" denied_mask="trace" peer="unconfined"
= AppArmor =
Time: Feb 9 11:21:15
Log: apparmor="ALLOWED" operation="ptrace" profile="snap.loolwsd.loolwsd" pid=12015 comm="ps" requested_mask="trace" denied_mask="trace" peer="unconfined"
= AppArmor =
Time: Feb 9 11:21:15
Log: apparmor="ALLOWED" operation="ptrace" profile="snap.loolwsd.loolwsd" pid=12015 comm="ps" requested_mask="trace" denied_mask="trace" peer="unconfined"
= AppArmor =
Time: Feb 9 11:21:15
Log: apparmor="ALLOWED" operation="ptrace" profile="snap.loolwsd.loolwsd" pid=12015 comm="ps" requested_mask="trace" denied_mask="trace" peer="unconfined"
= AppArmor =
Time: Feb 9 11:21:15
Log: apparmor="ALLOWED" operation="ptrace" profile="snap.loolwsd.loolwsd" pid=12015 comm="ps" requested_mask="trace" denied_mask="trace" peer="unconfined"
= AppArmor =
Time: Feb 9 11:21:15
Log: apparmor="ALLOWED" operation="ptrace" profile="snap.loolwsd.loolwsd" pid=12015 comm="ps" requested_mask="trace" denied_mask="trace" peer="unconfined"
= AppArmor =
Time: Feb 9 11:21:20
Log: apparmor="ALLOWED" operation="ptrace" profile="snap.loolwsd.loolwsd" pid=12019 comm="ps" requested_mask="trace" denied_mask="trace" peer="unconfined"
= AppArmor =
Time: Feb 9 11:21:20
Log: apparmor="ALLOWED" operation="ptrace" profile="snap.loolwsd.loolwsd" pid=12019 comm="ps" requested_mask="trace" denied_mask="trace" peer="unconfined"
= AppArmor =
Time: Feb 9 11:21:20
Log: apparmor="ALLOWED" operation="ptrace" profile="snap.loolwsd.loolwsd" pid=12019 comm="ps" requested_mask="trace" denied_mask="trace" peer="unconfined"
= AppArmor =
Time: Feb 9 11:21:20
Log: apparmor="ALLOWED" operation="ptrace" profile="snap.loolwsd.loolwsd" pid=12019 comm="ps" requested_mask="trace" denied_mask="trace" peer="unconfined"
= AppArmor =
Time: Feb 9 11:21:20
Log: apparmor="ALLOWED" operation="ptrace" profile="snap.loolwsd.loolwsd" pid=12019 comm="ps" requested_mask="trace" denied_mask="trace" peer="unconfined"
= AppArmor =
Time: Feb 9 11:21:20
Log: apparmor="ALLOWED" operation="ptrace" profile="snap.loolwsd.loolwsd" pid=12019 comm="ps" requested_mask="trace" denied_mask="trace" peer="unconfined"
= AppArmor =
Time: Feb 9 11:21:20
Log: apparmor="ALLOWED" operation="ptrace" profile="snap.loolwsd.loolwsd" pid=12019 comm="ps" requested_mask="trace" denied_mask="trace" peer="unconfined"
= AppArmor =
Time: Feb 9 11:21:20
Log: apparmor="ALLOWED" operation="ptrace" profile="snap.loolwsd.loolwsd" pid=12019 comm="ps" requested_mask="trace" denied_mask="trace" peer="unconfined"
= AppArmor =
Time: Feb 9 11:21:20
Log: apparmor="ALLOWED" operation="ptrace" profile="snap.loolwsd.loolwsd" pid=12019 comm="ps" requested_mask="trace" denied_mask="trace" peer="unconfined"
= AppArmor =
Time: Feb 9 11:21:20
Log: apparmor="ALLOWED" operation="ptrace" profile="snap.loolwsd.loolwsd" pid=12019 comm="ps" requested_mask="trace" denied_mask="trace" peer="unconfined"
= AppArmor =
Time: Feb 9 11:21:25
Log: apparmor="ALLOWED" operation="ptrace" profile="snap.loolwsd.loolwsd" pid=12023 comm="ps" requested_mask="trace" denied_mask="trace" peer="unconfined"
= AppArmor =
Time: Feb 9 11:21:25
Log: apparmor="ALLOWED" operation="ptrace" profile="snap.loolwsd.loolwsd" pid=12023 comm="ps" requested_mask="trace" denied_mask="trace" peer="unconfined"
= AppArmor =
Time: Feb 9 11:21:25
Log: apparmor="ALLOWED" operation="ptrace" profile="snap.loolwsd.loolwsd" pid=12023 comm="ps" requested_mask="trace" denied_mask="trace" peer="unconfined"
= AppArmor =
Time: Feb 9 11:21:25
Log: apparmor="ALLOWED" operation="ptrace" profile="snap.loolwsd.loolwsd" pid=12023 comm="ps" requested_mask="trace" denied_mask="trace" peer="unconfined"
= AppArmor =
Time: Feb 9 11:21:25
Log: apparmor="ALLOWED" operation="ptrace" profile="snap.loolwsd.loolwsd" pid=12023 comm="ps" requested_mask="trace" denied_mask="trace" peer="unconfined"
= AppArmor =
Time: Feb 9 11:21:25
Log: apparmor="ALLOWED" operation="ptrace" profile="snap.loolwsd.loolwsd" pid=12023 comm="ps" requested_mask="trace" denied_mask="trace" peer="unconfined"
= AppArmor =
Time: Feb 9 11:21:25
Log: apparmor="ALLOWED" operation="ptrace" profile="snap.loolwsd.loolwsd" pid=12023 comm="ps" requested_mask="trace" denied_mask="trace" peer="unconfined"
= AppArmor =
Time: Feb 9 11:21:25
Log: apparmor="ALLOWED" operation="ptrace" profile="snap.loolwsd.loolwsd" pid=12023 comm="ps" requested_mask="trace" denied_mask="trace" peer="unconfined"
= AppArmor =
Time: Feb 9 11:21:25
Log: apparmor="ALLOWED" operation="ptrace" profile="snap.loolwsd.loolwsd" pid=12023 comm="ps" requested_mask="trace" denied_mask="trace" peer="unconfined"
= AppArmor =
Time: Feb 9 11:21:25
Log: apparmor="ALLOWED" operation="ptrace" profile="snap.loolwsd.loolwsd" pid=12023 comm="ps" requested_mask="trace" denied_mask="trace" peer="unconfined"
^C
-------------- next part --------------
sudo loolwsd
+ mkdir -p -m 750 /var/snap/loolwsd/common/cache
+ rm -rf /var/snap/loolwsd/common/cache/*
+ mkdir -p -m 750 /var/snap/loolwsd/x2/log
+ rm -rf /var/snap/loolwsd/x2/opt/lool
+ mkdir -p -m 750 /var/snap/loolwsd/x2/opt/lool/child-roots
+ mkdir -p -m 750 /var/snap/loolwsd/x2/etc /var/snap/loolwsd/x2/usr/share
+ [ -d /var/snap/loolwsd/x2/etc/loolwsd ]
+ [ -d /var/snap/loolwsd/x2/usr/share/loolwsd ]
+ [ -d /var/snap/loolwsd/x2/opt/collaboraoffice5.1 ]
+ rm -rf /var/snap/loolwsd/x2/opt/collaboraoffice5.1
+ cp -r -p /snap/loolwsd/x2/opt/collaboraoffice5.1 /var/snap/loolwsd/x2/opt
+ [ -d /var/snap/loolwsd/x2/opt/lool/systemplate ]
+ cp -r -p /snap/loolwsd/x2/systemplate /var/snap/loolwsd/x2/opt/lool
+ export FONTCONFIG_PATH=/var/snap/loolwsd/x2/opt/lool/systemplate/etc/fonts
+ export FONTCONFIG_FILE=/var/snap/loolwsd/x2/opt/lool/systemplate/etc/fonts/fonts.conf
+ export LD_LIBRARY_PATH=/var/lib/snapd/lib/gl::/snap/loolwsd/x2/usr/lib:/snap/loolwsd/x2/usr/lib/x86_64-linux-gnu:/snap/loolwsd/x2/usr/lib/x86_64-linux-gnu/mesa::/snap/loolwsd/x2/lib:/snap/loolwsd/x2/usr/lib:/snap/loolwsd/x2/lib/x86_64-linux-gnu:/snap/loolwsd/x2/usr/lib/x86_64-linux-gnu:/snap/loolwsd/x2/usr/lib/x86_64-linux-gnu/mesa:/snap/loolwsd/x2/usr/lib/x86_64-linux-gnu/mesa-egl:/snap/loolwsd/x2/usr/lib/x86_64-linux-gnu/dri:/var/lib/snapd/lib/gl:/snap/loolwsd/x2/usr/lib/x86_64-linux-gnu/qt4:/snap/loolwsd/x2/usr/lib:/snap/loolwsd/x2/lib:/snap/loolwsd/x2/lib/x86_64-linux-gnu:/snap/loolwsd/x2/usr/lib/x86_64-linux-gnu
+ /snap/loolwsd/x2/usr/bin/loolwsd --o:sys_template_path=/var/snap/loolwsd/x2/opt/lool/systemplate --o:lo_template_path=/var/snap/loolwsd/x2/opt/collaboraoffice5.1 --o:child_root_path=/var/snap/loolwsd/x2/opt/lool/child-roots --o:file_server_root_path=/snap/loolwsd/x2/usr/share/loolwsd --config-file=/var/snap/loolwsd/x2/etc/loolwsd/loolwsd.xml
wsd-11946-11946 10:20:29.970550 [ loolwsd ] INF Initializing wsd
wsd-11946-11946 10:20:29.970615 [ loolwsd ] INF Log level is [7].
wsd-11946-11946 10:20:29.970646 [ loolwsd ] WRN SSL support: SSL is disabled.| wsd/LOOLWSD.cpp:1644
wsd-11946-11946 10:20:29.970704 [ loolwsd ] INF Open Documents Limit: 10| wsd/LOOLWSD.cpp:1674
wsd-11946-11946 10:20:29.970722 [ loolwsd ] INF Client Connections Limit: 20| wsd/LOOLWSD.cpp:1677
wsd-11946-11946 10:20:29.970847 [ loolwsd ] INF Command trace dumping enabled to file: /tmp/looltrace.gz| wsd/LOOLWSD.cpp:1701
wsd-11946-11946 10:20:29.970882 [ loolwsd ] INF Adding trusted WOPI host: [localhost].| wsd/Storage.cpp:82
wsd-11946-11946 10:20:29.970908 [ loolwsd ] INF Adding trusted WOPI host: [10\.[0-9]{1,3}\.[0-9]{1,3}\.[0-9]{1,3}].| wsd/Storage.cpp:82
wsd-11946-11946 10:20:29.970931 [ loolwsd ] INF Adding trusted WOPI host: [172\.1[6789]\.[0-9]{1,3}\.[0-9]{1,3}].| wsd/Storage.cpp:82
wsd-11946-11946 10:20:29.970953 [ loolwsd ] INF Adding trusted WOPI host: [172\.2[0-9]\.[0-9]{1,3}\.[0-9]{1,3}].| wsd/Storage.cpp:82
wsd-11946-11946 10:20:29.970976 [ loolwsd ] INF Adding trusted WOPI host: [172\.3[01]\.[0-9]{1,3}\.[0-9]{1,3}].| wsd/Storage.cpp:82
wsd-11946-11946 10:20:29.970999 [ loolwsd ] INF Adding trusted WOPI host: [192\.168\.[0-9]{1,3}\.[0-9]{1,3}].| wsd/Storage.cpp:82
wsd-11946-11946 10:20:29.971020 [ loolwsd ] INF Adding blocked WOPI host: [192\.168\.1\.1].| wsd/Storage.cpp:87
Launch this in your browser:
http://localhost:9980/loleaflet/ba37507/loleaflet.html?file_path=file:///snap/loolwsd/x2/usr/bin/test/data/hello-world.odt
wsd-11946-11946 10:20:29.971233 [ loolwsd ] DBG FileServerRoot: /snap/loolwsd/x2/usr/share/loolwsd| wsd/LOOLWSD.cpp:1992
wsd-11946-11946 10:20:29.971560 [ loolwsd ] INF Starting prisoner server listening on 9981| wsd/LOOLWSD.cpp:2024
wsd-11946-11946 10:20:29.971626 [ loolwsd ] INF Launching forkit process: /snap/loolwsd/x2/usr/bin/loolforkit --losubpath=lo --systemplate=/var/snap/loolwsd/x2/opt/lool/systemplate --lotemplate=/var/snap/loolwsd/x2/opt/collaboraoffice5.1 --childroot=/var/snap/loolwsd/x2/opt/lool/child-roots/ --clientport=9980 --masterport=9981| wsd/LOOLWSD.cpp:1912
wsd-11946-11946 10:20:29.971867 [ loolwsd ] INF Forkit process launched: 11954| wsd/LOOLWSD.cpp:1923
wsd-11946-11946 10:20:29.971905 [ loolwsd ] INF AdminModel ctor.
wsd-11946-11946 10:20:29.971967 [ loolwsd ] INF Admin ctor.
wsd-11946-11946 10:20:29.971986 [ loolwsd ] DBG Memory stat ctor
wsd-11946-11946 10:20:29.972011 [ loolwsd ] DBG Cpu stat ctor
frk-11954-11954 10:20:30.002904 [ loolforkit ] INF Initializing frk
frk-11954-11954 10:20:30.002964 [ loolforkit ] INF Log level is [7].
frk-11954-11954 10:20:30.002986 [ loolforkit ] INF Have capability cap_sys_chroot| kit/ForKit.cpp:157
frk-11954-11954 10:20:30.003001 [ loolforkit ] INF Have capability cap_mknod| kit/ForKit.cpp:157
frk-11954-11954 10:20:30.003013 [ loolforkit ] INF Have capability cap_fowner| kit/ForKit.cpp:157
frk-11954-11954 10:20:31.356837 [ loolforkit ] INF Preinit stage OK.| kit/ForKit.cpp:392
frk-11954-11954 10:20:31.356878 [ loolforkit ] DBG Forking a loolkit process.| kit/ForKit.cpp:209
frk-11954-11954 10:20:31.358394 [ loolforkit ] INF Forked kit [11961].| kit/ForKit.cpp:244
frk-11954-11954 10:20:31.358440 [ loolforkit ] INF ForKit process is ready.| kit/ForKit.cpp:403
kit-11961-11961 10:20:31.358602 [ loolkit ] INF Initializing kit
kit-11961-11961 10:20:31.358708 [ loolkit ] INF Log level is [7].
kit-11961-11961 10:20:31.358746 [ loolkit ] DBG Process started.| kit/Kit.cpp:1494
kit-11961-11961 10:20:31.358792 [ loolkit ] INF Jail path: /var/snap/loolwsd/x2/opt/lool/child-roots/11961/| kit/Kit.cpp:1508
kit-11961-11961 10:20:31.358957 [ loolkit ] DBG symlink("../../../../../lo","/var/snap/loolwsd/x2/opt/lool/child-roots/11961/var/snap/loolwsd/x2/opt/collaboraoffice5.1")| kit/Kit.cpp:265
kit-11961-11961 10:20:31.435275 [ loolkit ] DBG Initialized jail files.| kit/Kit.cpp:1565
kit-11961-11961 10:20:31.435387 [ loolkit ] INF chroot("/var/snap/loolwsd/x2/opt/lool/child-roots/11961/")| kit/Kit.cpp:1582
kit-11961-11961 10:20:31.435516 [ loolkit ] DBG Initialized jail nodes, dropped caps.| kit/Kit.cpp:1599
kit-11961-11961 10:20:31.473638 [ loolkit ] INF Process is ready.| kit/Kit.cpp:1626
wsd-11946-11947 10:20:31.474665 [ prsnr_req_hdl ] INF Request from 127.0.0.1:46964: GET /loolws/newchild?pid=11961&version=%7B%20%22ProductName%22:%20%22Collabora%20Office%22,%20%22ProductVersion%22:%20%225.1%22,%20%22ProductExtension%22:%20%22.10.18%22,%20%22BuildId%22:%20%22586626676b0aa697e153c6693a1e1407f7746989%22%20%7D HTTP/1.0 / Connection: Upgrade / Upgrade: websocket / Sec-WebSocket-Version: 13 / Sec-WebSocket-Key: SzlvDFCa9yZNBY8uvT8UKQ== / Host: 127.0.0.1:9981
wsd-11946-11947 10:20:31.474735 [ prsnr_req_hdl ] INF New child [11961].| wsd/LOOLWSD.cpp:1312
wsd-11946-11947 10:20:31.474837 [ prsnr_req_hdl ] INF Send buffer size for web socket set to minimum: 4608| ./wsd/LOOLWebSocket.hpp:57
kit-11961-11961 10:20:31.474905 [ loolkit ] INF Send buffer size for web socket set to minimum: 4608| ./wsd/LOOLWebSocket.hpp:57
[kit-11961-11961 10:20:31.474979 [ loolkit ] INF SocketProcessor [child_ws_11961] starting.| common/IoUtil.cpp:49
39mwsd-11946-11947 10:20:31.474944 [ prsnr_req_hdl ] INF ChildProcess ctor [11961].| wsd/DocumentBroker.hpp:51
wsd-11946-11966 10:20:31.475005 [ docbrk_ws_11961 ] INF SocketProcessor [docbrk_ws_11961] starting.| common/IoUtil.cpp:49
wsd-11946-11947 10:20:31.475171 [ prsnr_req_hdl ] INF Have 1 child.| wsd/LOOLWSD.cpp:401
wsd-11946-11946 10:20:31.475380 [ loolwsd ] INF Starting master server listening on 9980| wsd/LOOLWSD.cpp:2048
wsd-11946-11948 10:21:10.932088 [ client_req_hdl ] INF Request from 127.0.0.1:42408: GET /loleaflet/dist/loleaflet.html?file_path=file:///tmp/cv.odt HTTP/1.1 / Host: localhost:9980 / User-Agent: Mozilla/5.0 (X11; Ubuntu; Linux x86_64; rv:51.0) Gecko/20100101 Firefox/51.0 / Accept: text/html,application/xhtml+xml,application/xml;q=0.9,*/*;q=0.8 / Accept-Language: en-US,en;q=0.5 / Accept-Encoding: gzip, deflate / Cookie: nc_sameSiteCookielax=true; nc_sameSiteCookiestrict=true / Connection: keep-alive / Upgrade-Insecure-Requests: 1
wsd-11946-11948 10:21:10.932246 [ client_req_hdl ] DBG Preprocessing file: /snap/loolwsd/x2/usr/share/loolwsd//loleaflet/dist/loleaflet.html
wsd-11946-11949 10:21:11.871704 [ client_req_hdl ] INF Request from 127.0.0.1:42410: GET /lool/file%3A%2F%2F%2Ftmp%2Fcv.odt%3Fpermission%3Dedit/ws HTTP/1.1 / Host: localhost:9980 / User-Agent: Mozilla/5.0 (X11; Ubuntu; Linux x86_64; rv:51.0) Gecko/20100101 Firefox/51.0 / Accept: text/html,application/xhtml+xml,application/xml;q=0.9,*/*;q=0.8 / Accept-Language: en-US,en;q=0.5 / Accept-Encoding: gzip, deflate / Sec-WebSocket-Version: 13 / Origin: http://localhost:9980 / Sec-WebSocket-Extensions: permessage-deflate / Sec-WebSocket-Key: 6zCnjOwk/sT14X2RxOszCw== / Cookie: nc_sameSiteCookielax=true; nc_sameSiteCookiestrict=true / Connection: keep-alive, Upgrade / Pragma: no-cache / Cache-Control: no-cache / Upgrade: websocket
wsd-11946-11949 10:21:11.871800 [ client_ws_0001 ] DBG Thread started.| wsd/LOOLWSD.cpp:1141
wsd-11946-11949 10:21:11.871820 [ client_ws_0001 ] DBG Handling: /lool/file%3A%2F%2F%2Ftmp%2Fcv.odt%3Fpermission%3Dedit/ws| wsd/LOOLWSD.cpp:1144
wsd-11946-11949 10:21:11.871923 [ client_ws_0001 ] INF Send buffer size for web socket set to minimum: 4608| ./wsd/LOOLWebSocket.hpp:57
wsd-11946-11949 10:21:11.871954 [ client_ws_0001 ] INF Starting GET request handler for session [0001].| wsd/LOOLWSD.cpp:763
wsd-11946-11949 10:21:11.872029 [ client_ws_0001 ] DBG No DocumentBroker with docKey [/tmp/cv.odt] found. New Child and Document.| wsd/LOOLWSD.cpp:853
wsd-11946-11949 10:21:11.872066 [ client_ws_0001 ] DBG getNewChild: Have 1 children, forking 1| wsd/LOOLWSD.cpp:430
wsd-11946-11949 10:21:11.872103 [ client_ws_0001 ] DBG MasterToForKit: spawn 1| wsd/LOOLWSD.cpp:310
wsd-11946-11949 10:21:11.872135 [ client_ws_0001 ] DBG getNewChild: Returning new child [11961].| wsd/LOOLWSD.cpp:442
wsd-11946-11949 10:21:11.872147 [ client_ws_0001 ] DBG New DocumentBroker for docKey [/tmp/cv.odt].| wsd/LOOLWSD.cpp:885
wsd-11946-11949 10:21:11.872174 [ client_ws_0001 ] INF DocumentBroker [file:///tmp/cv.odt?permission=edit] created. DocKey: [/tmp/cv.odt]| wsd/DocumentBroker.cpp:172
wsd-11946-11949 10:21:11.872213 [ client_ws_0001 ] DBG Query param: permission, value: edit| wsd/LOOLWSD.cpp:908
wsd-11946-11949 10:21:11.872243 [ client_ws_0001 ] INF ClientSession ctor [ToClient-0001].
wsd-11946-11949 10:21:11.872363 [ client_ws_0001 ] INF Loading [/tmp/cv.odt] for session [0001] and jail [11961].| wsd/DocumentBroker.cpp:198
wsd-11946-12006 10:21:11.872361 [ client_ws_0001 ] DBG ToClient-0001 SenderThread started| wsd/ClientSession.cpp:468
wsd-11946-11949 10:21:11.872391 [ client_ws_0001 ] DBG Loading from URI: file:///tmp/cv.odt?permission=edit| wsd/DocumentBroker.cpp:214
wsd-11946-11949 10:21:11.872424 [ client_ws_0001 ] INF jailPath: /user/docs/11961, jailRoot: /var/snap/loolwsd/x2/opt/lool/child-roots/11961| wsd/DocumentBroker.cpp:229
wsd-11946-11949 10:21:11.872443 [ client_ws_0001 ] DBG Creating new storage instance for URI [file:///tmp/cv.odt?permission=edit].| wsd/DocumentBroker.cpp:236
wsd-11946-11949 10:21:11.872462 [ client_ws_0001 ] INF Public URI [file:///tmp/cv.odt?permission=edit] is a file.| wsd/Storage.cpp:156
wsd-11946-11949 10:21:11.872487 [ client_ws_0001 ] DBG Storage ctor: file:///tmp/cv.odt?permission=edit
wsd-11946-11949 10:21:11.872503 [ client_ws_0001 ] INF LocalStorage ctor with localStorePath: [/var/snap/loolwsd/x2/opt/lool/child-roots/11961], jailPath: [/user/docs/11961], uri: [file:///tmp/cv.odt?permission=edit].
wsd-11946-11949 10:21:11.872528 [ client_ws_0001 ] DBG Getting info for local uri [file:///tmp/cv.odt?permission=edit], path [/tmp/cv.odt].
frk-11954-11954 10:21:11.873006 [ loolforkit ] INF ForKit command: [spawn 1].| kit/ForKit.cpp:93
frk-11954-11954 10:21:11.873049 [ loolforkit ] INF Spawning 1 child per request.| kit/ForKit.cpp:101
frk-11954-11954 10:21:11.873066 [ loolforkit ] INF Creating 1 new child.| kit/ForKit.cpp:419
frk-11954-11954 10:21:11.873079 [ loolforkit ] DBG Forking a loolkit process.| kit/ForKit.cpp:209
frk-11954-11954 10:21:11.875637 [ loolforkit ] INF Forked kit [12007].| kit/ForKit.cpp:244
kit-12007-12007 10:21:11.875881 [ loolkit ] INF Initializing kit
kit-12007-12007 10:21:11.875954 [ loolkit ] INF Log level is [7].
kit-12007-12007 10:21:11.875995 [ loolkit ] DBG Process started.| kit/Kit.cpp:1494
kit-12007-12007 10:21:11.876024 [ loolkit ] INF Jail path: /var/snap/loolwsd/x2/opt/lool/child-roots/12007/| kit/Kit.cpp:1508
kit-12007-12007 10:21:11.876313 [ loolkit ] DBG symlink("../../../../../lo","/var/snap/loolwsd/x2/opt/lool/child-roots/12007/var/snap/loolwsd/x2/opt/collaboraoffice5.1")| kit/Kit.cpp:265
wsd-11946-11949 10:21:11.885162 [ client_ws_0001 ] ERR Error in client request handler: File not found| wsd/LOOLWSD.cpp:1038
wsd-11946-11949 10:21:11.885283 [ client_ws_0001 ] INF Finished GET request handler for session [0001].| wsd/LOOLWSD.cpp:1060
wsd-11946-11949 10:21:11.885319 [ client_ws_0001 ] INF ~ClientSession dtor [ToClient-0001].
wsd-11946-12006 10:21:11.885377 [ client_ws_0001 ] DBG SenderQueue: stopping| wsd/SenderQueue.hpp:177
wsd-11946-12006 10:21:11.885651 [ client_ws_0001 ] DBG ToClient-0001 SenderThread finished| wsd/ClientSession.cpp:495
wsd-11946-11949 10:21:11.885746 [ client_ws_0001 ] DBG Already sent response!?| wsd/LOOLWSD.cpp:1251
wsd-11946-11949 10:21:11.885922 [ client_ws_0001 ] DBG Thread finished.| wsd/LOOLWSD.cpp:1262
kit-12007-12007 10:21:11.983161 [ loolkit ] DBG Initialized jail files.| kit/Kit.cpp:1565
kit-12007-12007 10:21:11.983311 [ loolkit ] INF chroot("/var/snap/loolwsd/x2/opt/lool/child-roots/12007/")| kit/Kit.cpp:1582
kit-12007-12007 10:21:11.983450 [ loolkit ] DBG Initialized jail nodes, dropped caps.| kit/Kit.cpp:1599
kit-12007-12007 10:21:12.027157 [ loolkit ] INF Process is ready.| kit/Kit.cpp:1626
wsd-11946-11947 10:21:12.027467 [ prsnr_req_hdl ] INF Request from 127.0.0.1:46982: GET /loolws/newchild?pid=12007 HTTP/1.0 / Connection: Upgrade / Upgrade: websocket / Sec-WebSocket-Version: 13 / Sec-WebSocket-Key: ZMFATdFAmVUG2+VqurJiIQ== / Host: 127.0.0.1:9981
wsd-11946-11947 10:21:12.027508 [ prsnr_req_hdl ] INF New child [12007].| wsd/LOOLWSD.cpp:1312
wsd-11946-11947 10:21:12.027572 [ prsnr_req_hdl ] INF Send buffer size for web socket set to minimum: 4608| ./wsd/LOOLWebSocket.hpp:57
[mkit-12007-12007 10:21:12.027623 [ loolkit ] INF Send buffer size for web socket set to minimum: 4608| ./wsd/LOOLWebSocket.hpp:57wsd-11946-11947 10:21:12.027627 [ prsnr_req_hdl ] INF ChildProcess ctor [12007].| wsd/DocumentBroker.hpp:51[0m
wsd-11946-11947 10:21:12.027647 [ prsnr_req_hdl ] INF Have 1 child.| wsd/LOOLWSD.cpp:401
[wsd-11946-12012 10:21:12.027648 [ docbrk_ws_12007 ] INF SocketProcessor [docbrk_ws_12007] starting.| common/IoUtil.cpp:49390mkit-12007-12007 10:21:12.027658 [ loolkit ] INF SocketProcessor [child_ws_12007] starting.| common/IoUtil.cpp:49
-------------- next part --------------
Feb 9 11:28:36 lohengrin /usr/bin/snap[12798]: cmd.go:105: DEBUG: restarting into "/snap/core/current/usr/bin/snap"
Feb 9 11:28:36 lohengrin snap[12798]: + mkdir -p -m 750 /var/snap/loolwsd/common/cache
Feb 9 11:28:36 lohengrin snap[12798]: + rm -rf /var/snap/loolwsd/common/cache/*
Feb 9 11:28:36 lohengrin snap[12798]: + mkdir -p -m 750 /var/snap/loolwsd/x4/log
Feb 9 11:28:36 lohengrin snap[12798]: + rm -rf /var/snap/loolwsd/x4/opt/lool
Feb 9 11:28:36 lohengrin /usr/lib/snapd/snapd[1144]: taskrunner.go:353: DEBUG: Running task 2140 on Do: Remove data for snap "loolwsd" (x1)
Feb 9 11:28:36 lohengrin snap[12798]: + mkdir -p -m 750 /var/snap/loolwsd/x4/opt/lool/child-roots
Feb 9 11:28:36 lohengrin snap[12798]: + mkdir -p -m 750 /var/snap/loolwsd/x4/etc /var/snap/loolwsd/x4/usr/share
Feb 9 11:28:36 lohengrin snap[12798]: + [ -d /var/snap/loolwsd/x4/etc/loolwsd ]
Feb 9 11:28:36 lohengrin snap[12798]: + [ -d /var/snap/loolwsd/x4/usr/share/loolwsd ]
Feb 9 11:28:36 lohengrin snap[12798]: + [ -d /var/snap/loolwsd/x4/opt/collaboraoffice5.1 ]
Feb 9 11:28:36 lohengrin snap[12798]: + rm -rf /var/snap/loolwsd/x4/opt/collaboraoffice5.1
Feb 9 11:28:37 lohengrin snap[12798]: + cp -r -p /snap/loolwsd/x4/opt/collaboraoffice5.1 /var/snap/loolwsd/x4/opt
Feb 9 11:28:37 lohengrin /usr/lib/snapd/snapd[1144]: taskrunner.go:353: DEBUG: Running task 2141 on Do: Remove snap "loolwsd" (x1) from the system
Feb 9 11:28:37 lohengrin systemd[1]: Unmounted Mount unit for loolwsd.
Feb 9 11:28:37 lohengrin systemd[1]: Reloading.
Feb 9 11:28:37 lohengrin systemd[1]: apt-daily.timer: Adding 3h 16min 7.611454s random time.
Feb 9 11:28:37 lohengrin systemd[1]: snapd.refresh.timer: Adding 5h 49min 44.828983s random time.
Feb 9 11:28:37 lohengrin systemd[1]: Started CUPS Scheduler.
Feb 9 11:28:37 lohengrin systemd[1]: Started ACPI event daemon.
Feb 9 11:28:37 lohengrin systemd[1]: Reloading.
Feb 9 11:28:37 lohengrin systemd[1]: apt-daily.timer: Adding 11h 1min 43.433803s random time.
Feb 9 11:28:37 lohengrin systemd[1]: snapd.refresh.timer: Adding 4h 15min 57.134664s random time.
Feb 9 11:28:37 lohengrin systemd[1]: Started CUPS Scheduler.
Feb 9 11:28:37 lohengrin systemd[1]: Started ACPI event daemon.
Feb 9 11:28:37 lohengrin /usr/lib/snapd/snapd[1144]: taskrunner.go:353: DEBUG: Running task 2142 on Do: Clean up "loolwsd" (unset) install
Feb 9 11:28:37 lohengrin /usr/lib/snapd/snapd[1144]: taskrunner.go:353: DEBUG: Running task 2143 on Do: Run configure hook of "loolwsd" snap if present
Feb 9 11:28:37 lohengrin /usr/lib/snapd/snapd[1144]: daemon.go:176: DEBUG: uid=1000;@ GET /v2/snaps 1.512741ms 200
Feb 9 11:28:48 lohengrin snap[12798]: + [ -d /var/snap/loolwsd/x4/opt/lool/systemplate ]
Feb 9 11:28:48 lohengrin snap[12798]: + cp -r -p /snap/loolwsd/x4/systemplate /var/snap/loolwsd/x4/opt/lool
Feb 9 11:29:01 lohengrin snap[12798]: + export FONTCONFIG_PATH=/var/snap/loolwsd/x4/opt/lool/systemplate/etc/fonts
Feb 9 11:29:01 lohengrin snap[12798]: + export FONTCONFIG_FILE=/var/snap/loolwsd/x4/opt/lool/systemplate/etc/fonts/fonts.conf
Feb 9 11:29:01 lohengrin snap[12798]: + export LD_LIBRARY_PATH=/var/lib/snapd/lib/gl::/snap/loolwsd/x4/usr/lib:/snap/loolwsd/x4/usr/lib/x86_64-linux-gnu:/snap/loolwsd/x4/usr/lib/x86_64-linux-gnu/mesa:/snap/core/current/lib:/snap/core/current/usr/lib:/snap/core/current/lib/x86_64-linux-gnu:/snap/core/current/usr/lib/x86_64-linux-gnu:/snap/loolwsd/x4/lib:/snap/loolwsd/x4/usr/lib:/snap/loolwsd/x4/lib/x86_64-linux-gnu:/snap/loolwsd/x4/usr/lib/x86_64-linux-gnu:/snap/loolwsd/x4/usr/lib:/snap/loolwsd/x4/lib:/snap/loolwsd/x4/lib/x86_64-linux-gnu:/snap/loolwsd/x4/usr/lib/x86_64-linux-gnu
Feb 9 11:29:01 lohengrin snap[12798]: + /snap/loolwsd/x4/usr/bin/loolwsd --o:sys_template_path=/var/snap/loolwsd/x4/opt/lool/systemplate --o:lo_template_path=/var/snap/loolwsd/x4/opt/collaboraoffice5.1 --o:child_root_path=/var/snap/loolwsd/x4/opt/lool/child-roots --o:file_server_root_path=/snap/loolwsd/x4/usr/share/loolwsd --config-file=/var/snap/loolwsd/x4/etc/loolwsd/loolwsd.xml
Feb 9 11:29:01 lohengrin snap[12798]: wsd-12857-12857 10:29:01.255447 [ loolwsd ] INF Initializing wsd
Feb 9 11:29:01 lohengrin snap[12798]: wsd-12857-12857 10:29:01.255499 [ loolwsd ] INF Log level is [7].
Feb 9 11:29:01 lohengrin snap[12798]: wsd-12857-12857 10:29:01.255512 [ loolwsd ] WRN SSL support: SSL is disabled.| wsd/LOOLWSD.cpp:1644
Feb 9 11:29:01 lohengrin snap[12798]: wsd-12857-12857 10:29:01.255557 [ loolwsd ] INF Open Documents Limit: 10| wsd/LOOLWSD.cpp:1674
Feb 9 11:29:01 lohengrin snap[12798]: wsd-12857-12857 10:29:01.255567 [ loolwsd ] INF Client Connections Limit: 20| wsd/LOOLWSD.cpp:1677
Feb 9 11:29:01 lohengrin snap[12798]: wsd-12857-12857 10:29:01.256257 [ loolwsd ] INF Command trace dumping enabled to file: /tmp/looltrace.gz| wsd/LOOLWSD.cpp:1701
Feb 9 11:29:01 lohengrin snap[12798]: wsd-12857-12857 10:29:01.256294 [ loolwsd ] INF Adding trusted WOPI host: [localhost].| wsd/Storage.cpp:82
Feb 9 11:29:01 lohengrin snap[12798]: wsd-12857-12857 10:29:01.256315 [ loolwsd ] INF Adding trusted WOPI host: [10\.[0-9]{1,3}\.[0-9]{1,3}\.[0-9]{1,3}].| wsd/Storage.cpp:82
Feb 9 11:29:01 lohengrin snap[12798]: wsd-12857-12857 10:29:01.256333 [ loolwsd ] INF Adding trusted WOPI host: [172\.1[6789]\.[0-9]{1,3}\.[0-9]{1,3}].| wsd/Storage.cpp:82
Feb 9 11:29:01 lohengrin snap[12798]: wsd-12857-12857 10:29:01.256351 [ loolwsd ] INF Adding trusted WOPI host: [172\.2[0-9]\.[0-9]{1,3}\.[0-9]{1,3}].| wsd/Storage.cpp:82
Feb 9 11:29:01 lohengrin snap[12798]: wsd-12857-12857 10:29:01.256366 [ loolwsd ] INF Adding trusted WOPI host: [172\.3[01]\.[0-9]{1,3}\.[0-9]{1,3}].| wsd/Storage.cpp:82
Feb 9 11:29:01 lohengrin snap[12798]: wsd-12857-12857 10:29:01.256382 [ loolwsd ] INF Adding trusted WOPI host: [192\.168\.[0-9]{1,3}\.[0-9]{1,3}].| wsd/Storage.cpp:82
Feb 9 11:29:01 lohengrin snap[12798]: wsd-12857-12857 10:29:01.256398 [ loolwsd ] INF Adding blocked WOPI host: [192\.168\.1\.1].| wsd/Storage.cpp:87
Feb 9 11:29:01 lohengrin snap[12798]: Launch this in your browser:
Feb 9 11:29:01 lohengrin snap[12798]: http://localhost:9980/loleaflet/ba37507/loleaflet.html?file_path=file:///snap/loolwsd/x4/usr/bin/test/data/hello-world.odt
Feb 9 11:29:01 lohengrin snap[12798]: wsd-12857-12857 10:29:01.256750 [ loolwsd ] DBG FileServerRoot: /snap/loolwsd/x4/usr/share/loolwsd| wsd/LOOLWSD.cpp:1992
Feb 9 11:29:01 lohengrin snap[12798]: wsd-12857-12857 10:29:01.257107 [ loolwsd ] INF Starting prisoner server listening on 9981| wsd/LOOLWSD.cpp:2024
Feb 9 11:29:01 lohengrin snap[12798]: wsd-12857-12857 10:29:01.257171 [ loolwsd ] INF Launching forkit process: /snap/loolwsd/x4/usr/bin/loolforkit --losubpath=lo --systemplate=/var/snap/loolwsd/x4/opt/lool/systemplate --lotemplate=/var/snap/loolwsd/x4/opt/collaboraoffice5.1 --childroot=/var/snap/loolwsd/x4/opt/lool/child-roots/ --clientport=9980 --masterport=9981| wsd/LOOLWSD.cpp:1912
Feb 9 11:29:01 lohengrin snap[12798]: wsd-12857-12857 10:29:01.257398 [ loolwsd ] INF Forkit process launched: 12865| wsd/LOOLWSD.cpp:1923
Feb 9 11:29:01 lohengrin snap[12798]: wsd-12857-12857 10:29:01.257433 [ loolwsd ] INF AdminModel ctor.
Feb 9 11:29:01 lohengrin snap[12798]: wsd-12857-12857 10:29:01.257501 [ loolwsd ] INF Admin ctor.
Feb 9 11:29:01 lohengrin snap[12798]: wsd-12857-12857 10:29:01.257516 [ loolwsd ] DBG Memory stat ctor
Feb 9 11:29:01 lohengrin snap[12798]: wsd-12857-12857 10:29:01.257537 [ loolwsd ] DBG Cpu stat ctor
Feb 9 11:29:01 lohengrin snap[12798]: frk-12865-12865 10:29:01.311211 [ loolforkit ] INF Initializing frk
Feb 9 11:29:01 lohengrin snap[12798]: frk-12865-12865 10:29:01.311300 [ loolforkit ] INF Log level is [7].
Feb 9 11:29:01 lohengrin snap[12798]: frk-12865-12865 10:29:01.311345 [ loolforkit ] INF Have capability cap_sys_chroot| kit/ForKit.cpp:157
Feb 9 11:29:01 lohengrin snap[12798]: frk-12865-12865 10:29:01.311358 [ loolforkit ] INF Have capability cap_mknod| kit/ForKit.cpp:157
Feb 9 11:29:01 lohengrin snap[12798]: frk-12865-12865 10:29:01.311366 [ loolforkit ] INF Have capability cap_fowner| kit/ForKit.cpp:157
Feb 9 11:29:02 lohengrin snap[12798]: frk-12865-12865 10:29:02.210800 [ loolforkit ] INF Preinit stage OK.| kit/ForKit.cpp:392
Feb 9 11:29:02 lohengrin snap[12798]: frk-12865-12865 10:29:02.210838 [ loolforkit ] DBG Forking a loolkit process.| kit/ForKit.cpp:209
Feb 9 11:29:02 lohengrin snap[12798]: frk-12865-12865 10:29:02.212140 [ loolforkit ] INF Forked kit [12869].| kit/ForKit.cpp:244
Feb 9 11:29:02 lohengrin snap[12798]: frk-12865-12865 10:29:02.212190 [ loolforkit ] INF ForKit process is ready.| kit/ForKit.cpp:403
Feb 9 11:29:02 lohengrin snap[12798]: kit-12869-12869 10:29:02.212353 [ loolkit ] INF Initializing kit
Feb 9 11:29:02 lohengrin snap[12798]: kit-12869-12869 10:29:02.212421 [ loolkit ] INF Log level is [7].
Feb 9 11:29:02 lohengrin snap[12798]: kit-12869-12869 10:29:02.212455 [ loolkit ] DBG Process started.| kit/Kit.cpp:1494
Feb 9 11:29:02 lohengrin snap[12798]: kit-12869-12869 10:29:02.212493 [ loolkit ] INF Jail path: /var/snap/loolwsd/x4/opt/lool/child-roots/12869/| kit/Kit.cpp:1508
Feb 9 11:29:02 lohengrin snap[12798]: kit-12869-12869 10:29:02.212742 [ loolkit ] DBG symlink("../../../../../lo","/var/snap/loolwsd/x4/opt/lool/child-roots/12869/var/snap/loolwsd/x4/opt/collaboraoffice5.1")| kit/Kit.cpp:265
Feb 9 11:29:02 lohengrin snap[12798]: kit-12869-12869 10:29:02.283754 [ loolkit ] DBG Initialized jail files.| kit/Kit.cpp:1565
Feb 9 11:29:02 lohengrin snap[12798]: kit-12869-12869 10:29:02.283862 [ loolkit ] INF chroot("/var/snap/loolwsd/x4/opt/lool/child-roots/12869/")| kit/Kit.cpp:1582
Feb 9 11:29:02 lohengrin snap[12798]: kit-12869-12869 10:29:02.283989 [ loolkit ] DBG Initialized jail nodes, dropped caps.| kit/Kit.cpp:1599
Feb 9 11:29:02 lohengrin snap[12798]: kit-12869-12869 10:29:02.325303 [ loolkit ] INF Process is ready.| kit/Kit.cpp:1626
Feb 9 11:29:02 lohengrin snap[12798]: wsd-12857-12858 10:29:02.326106 [ prsnr_req_hdl ] INF Request from 127.0.0.1:47042: GET /loolws/newchild?pid=12869&version=%7B%20%22ProductName%22:%20%22Collabora%20Office%22,%20%22ProductVersion%22:%20%225.1%22,%20%22ProductExtension%22:%20%22.10.18%22,%20%22BuildId%22:%20%22586626676b0aa697e153c6693a1e1407f7746989%22%20%7D HTTP/1.0 / Connection: Upgrade / Upgrade: websocket / Sec-WebSocket-Version: 13 / Sec-WebSocket-Key: Fi5tHh6qD0LZQzUBVGMTew== / Host: 127.0.0.1:9981
Feb 9 11:29:02 lohengrin snap[12798]: wsd-12857-12858 10:29:02.326169 [ prsnr_req_hdl ] INF New child [12869].| wsd/LOOLWSD.cpp:1312
Feb 9 11:29:02 lohengrin snap[12798]: wsd-12857-12858 10:29:02.326279 [ prsnr_req_hdl ] INF Send buffer size for web socket set to minimum: 4608| ./wsd/LOOLWebSocket.hpp:57
Feb 9 11:29:02 lohengrin snap[12798]: wsd-12857-12858 10:29:02.326333 [ prsnr_req_hdl ] INF ChildProcess ctor [12869].| wsd/DocumentBroker.hpp:51
Feb 9 11:29:02 lohengrin snap[12798]: wsd-12857-12858 10:29:02.326346 [ prsnr_req_hdl ] INF Have 1 child.| wsd/LOOLWSD.cpp:401
Feb 9 11:29:02 lohengrin snap[12798]: wsd-12857-12857 10:29:02.326443 [ loolwsd ] INF Starting master server listening on 9980| wsd/LOOLWSD.cpp:2048
Feb 9 11:29:02 lohengrin snap[12798]: wsd-12857-12874 10:29:02.326517 [ docbrk_ws_12869 ] INF SocketProcessor [docbrk_ws_12869] starting.| common/IoUtil.cpp:49
Feb 9 11:29:02 lohengrin snap[12798]: kit-12869-12869 10:29:02.327038 [ loolkit ] INF Send buffer size for web socket set to minimum: 4608| ./wsd/LOOLWebSocket.hpp:57
Feb 9 11:29:02 lohengrin snap[12798]: kit-12869-12869 10:29:02.327074 [ loolkit ] INF SocketProcessor [child_ws_12869] starting.| common/IoUtil.cpp:49
Feb 9 11:29:04 lohengrin snap[12798]: wsd-12857-12859 10:29:04.507072 [ client_req_hdl ] INF Request from 127.0.0.1:42474: GET /loleaflet/dist/loleaflet.html?file_path=file:///tmp/cv.odt HTTP/1.1 / Host: localhost:9980 / User-Agent: Mozilla/5.0 (X11; Ubuntu; Linux x86_64; rv:51.0) Gecko/20100101 Firefox/51.0 / Accept: text/html,application/xhtml+xml,application/xml;q=0.9,*/*;q=0.8 / Accept-Language: en-US,en;q=0.5 / Accept-Encoding: gzip, deflate / Cookie: nc_sameSiteCookielax=true; nc_sameSiteCookiestrict=true / Connection: keep-alive / Upgrade-Insecure-Requests: 1 / Cache-Control: max-age=0
Feb 9 11:29:04 lohengrin snap[12798]: wsd-12857-12859 10:29:04.507217 [ client_req_hdl ] DBG Preprocessing file: /snap/loolwsd/x4/usr/share/loolwsd//loleaflet/dist/loleaflet.html
Feb 9 11:29:04 lohengrin snap[12798]: wsd-12857-12859 10:29:04.560455 [ client_req_hdl ] INF Request from 127.0.0.1:42474: GET /loleaflet/ba37507/branding.css HTTP/1.1 / Host: localhost:9980 / User-Agent: Mozilla/5.0 (X11; Ubuntu; Linux x86_64; rv:51.0) Gecko/20100101 Firefox/51.0 / Accept: text/css,*/*;q=0.1 / Accept-Language: en-US,en;q=0.5 / Accept-Encoding: gzip, deflate / Referer: http://localhost:9980/loleaflet/dist/loleaflet.html?file_path=file:///tmp/cv.odt / Cookie: nc_sameSiteCookielax=true; nc_sameSiteCookiestrict=true / Connection: keep-alive / If-Modified-Since: Fri, 13 Jan 2017 11:27:37 GMT / Cache-Control: max-age=0
Feb 9 11:29:04 lohengrin snap[12798]: wsd-12857-12860 10:29:04.561902 [ client_req_hdl ] INF Request from 127.0.0.1:42476: GET /loleaflet/ba37507/branding.js HTTP/1.1 / Host: localhost:9980 / User-Agent: Mozilla/5.0 (X11; Ubuntu; Linux x86_64; rv:51.0) Gecko/20100101 Firefox/51.0 / Accept: */* / Accept-Language: en-US,en;q=0.5 / Accept-Encoding: gzip, deflate / Referer: http://localhost:9980/loleaflet/dist/loleaflet.html?file_path=file:///tmp/cv.odt / Cookie: nc_sameSiteCookielax=true; nc_sameSiteCookiestrict=true / Connection: keep-alive / If-Modified-Since: Thu, 12 Jan 2017 10:19:23 GMT / Cache-Control: max-age=0
Feb 9 11:29:04 lohengrin snap[12798]: wsd-12857-12860 10:29:04.598192 [ client_req_hdl ] INF Request from 127.0.0.1:42476: GET /loleaflet/ba37507/toolbar/w2ui.min.js HTTP/1.1 / Host: localhost:9980 / User-Agent: Mozilla/5.0 (X11; Ubuntu; Linux x86_64; rv:51.0) Gecko/20100101 Firefox/51.0 / Accept: */* / Accept-Language: en-US,en;q=0.5 / Accept-Encoding: gzip, deflate / Referer: http://localhost:9980/loleaflet/dist/loleaflet.html?file_path=file:///tmp/cv.odt / Cookie: nc_sameSiteCookielax=true; nc_sameSiteCookiestrict=true / Connection: keep-alive / If-Modified-Since: Wed, 08 Feb 2017 13:43:20 GMT / Cache-Control: max-age=0
Feb 9 11:29:04 lohengrin snap[12798]: wsd-12857-12859 10:29:04.598193 [ client_req_hdl ] INF Request from 127.0.0.1:42474: GET /loleaflet/ba37507/bundle.js HTTP/1.1 / Host: localhost:9980 / User-Agent: Mozilla/5.0 (X11; Ubuntu; Linux x86_64; rv:51.0) Gecko/20100101 Firefox/51.0 / Accept: */* / Accept-Language: en-US,en;q=0.5 / Accept-Encoding: gzip, deflate / Referer: http://localhost:9980/loleaflet/dist/loleaflet.html?file_path=file:///tmp/cv.odt / Cookie: nc_sameSiteCookielax=true; nc_sameSiteCookiestrict=true / Connection: keep-alive / If-Modified-Since: Wed, 08 Feb 2017 13:46:56 GMT / Cache-Control: max-age=0
Feb 9 11:29:04 lohengrin snap[12798]: wsd-12857-12863 10:29:04.599092 [ client_req_hdl ] INF Request from 127.0.0.1:42482: GET /loleaflet/ba37507/images/toolbar-bg.png HTTP/1.1 / Host: localhost:9980 / User-Agent: Mozilla/5.0 (X11; Ubuntu; Linux x86_64; rv:51.0) Gecko/20100101 Firefox/51.0 / Accept: */* / Accept-Language: en-US,en;q=0.5 / Accept-Encoding: gzip, deflate / Referer: http://localhost:9980/loleaflet/ba37507/branding.css / Cookie: nc_sameSiteCookielax=true; nc_sameSiteCookiestrict=true / Connection: keep-alive / If-Modified-Since: Thu, 12 Jan 2017 10:19:23 GMT / Cache-Control: max-age=0
Feb 9 11:29:04 lohengrin snap[12798]: wsd-12857-12877 10:29:04.615133 [ client_req_hdl ] INF Request from 127.0.0.1:42484: GET /loleaflet/ba37507/toolbar/toolbar.js HTTP/1.1 / Host: localhost:9980 / User-Agent: Mozilla/5.0 (X11; Ubuntu; Linux x86_64; rv:51.0) Gecko/20100101 Firefox/51.0 / Accept: */* / Accept-Language: en-US,en;q=0.5 / Accept-Encoding: gzip, deflate / Referer: http://localhost:9980/loleaflet/dist/loleaflet.html?file_path=file:///tmp/cv.odt / Cookie: nc_sameSiteCookielax=true; nc_sameSiteCookiestrict=true / Connection: keep-alive / If-Modified-Since: Wed, 08 Feb 2017 13:43:20 GMT / Cache-Control: max-age=0
Feb 9 11:29:04 lohengrin snap[12798]: wsd-12857-12859 10:29:04.834664 [ client_req_hdl ] INF Request from 127.0.0.1:42474: GET /loleaflet/ba37507/l10n/help-localizations.json HTTP/1.1 / Host: localhost:9980 / User-Agent: Mozilla/5.0 (X11; Ubuntu; Linux x86_64; rv:51.0) Gecko/20100101 Firefox/51.0 / Accept: */* / Accept-Language: en-US,en;q=0.5 / Accept-Encoding: gzip, deflate / Referer: http://localhost:9980/loleaflet/dist/loleaflet.html?file_path=file:///tmp/cv.odt / Cookie: nc_sameSiteCookielax=true; nc_sameSiteCookiestrict=true / Connection: keep-alive / If-Modified-Since: Wed, 08 Feb 2017 13:43:20 GMT / Cache-Control: max-age=0
Feb 9 11:29:04 lohengrin snap[12798]: wsd-12857-12859 10:29:04.857226 [ client_req_hdl ] INF Request from 127.0.0.1:42474: GET /loleaflet/ba37507/l10n/uno-localizations.json HTTP/1.1 / Host: localhost:9980 / User-Agent: Mozilla/5.0 (X11; Ubuntu; Linux x86_64; rv:51.0) Gecko/20100101 Firefox/51.0 / Accept: */* / Accept-Language: en-US,en;q=0.5 / Accept-Encoding: gzip, deflate / Referer: http://localhost:9980/loleaflet/dist/loleaflet.html?file_path=file:///tmp/cv.odt / Cookie: nc_sameSiteCookielax=true; nc_sameSiteCookiestrict=true / Connection: keep-alive / If-Modified-Since: Wed, 08 Feb 2017 13:43:20 GMT / Cache-Control: max-age=0
Feb 9 11:29:04 lohengrin snap[12798]: wsd-12857-12859 10:29:04.874844 [ client_req_hdl ] INF Request from 127.0.0.1:42474: GET /loleaflet/ba37507/l10n/styles-localizations.json HTTP/1.1 / Host: localhost:9980 / User-Agent: Mozilla/5.0 (X11; Ubuntu; Linux x86_64; rv:51.0) Gecko/20100101 Firefox/51.0 / Accept: */* / Accept-Language: en-US,en;q=0.5 / Accept-Encoding: gzip, deflate / Referer: http://localhost:9980/loleaflet/dist/loleaflet.html?file_path=file:///tmp/cv.odt / Cookie: nc_sameSiteCookielax=true; nc_sameSiteCookiestrict=true / Connection: keep-alive / If-Modified-Since: Wed, 08 Feb 2017 13:43:20 GMT / Cache-Control: max-age=0
Feb 9 11:29:04 lohengrin snap[12798]: wsd-12857-12859 10:29:04.897072 [ client_req_hdl ] INF Request from 127.0.0.1:42474: GET /loleaflet/ba37507/l10n/localizations.json HTTP/1.1 / Host: localhost:9980 / User-Agent: Mozilla/5.0 (X11; Ubuntu; Linux x86_64; rv:51.0) Gecko/20100101 Firefox/51.0 / Accept: */* / Accept-Language: en-US,en;q=0.5 / Accept-Encoding: gzip, deflate / Referer: http://localhost:9980/loleaflet/dist/loleaflet.html?file_path=file:///tmp/cv.odt / Cookie: nc_sameSiteCookielax=true; nc_sameSiteCookiestrict=true / Connection: keep-alive / If-Modified-Since: Wed, 08 Feb 2017 13:43:20 GMT / Cache-Control: max-age=0
Feb 9 11:29:05 lohengrin snap[12798]: wsd-12857-12859 10:29:05.184229 [ client_req_hdl ] INF Request from 127.0.0.1:42474: GET /loleaflet/dist/images/lc_save.png HTTP/1.1 / Host: localhost:9980 / User-Agent: Mozilla/5.0 (X11; Ubuntu; Linux x86_64; rv:51.0) Gecko/20100101 Firefox/51.0 / Accept: */* / Accept-Language: en-US,en;q=0.5 / Accept-Encoding: gzip, deflate / Referer: http://localhost:9980/loleaflet/dist/loleaflet.html?file_path=file:///tmp/cv.odt / Cookie: nc_sameSiteCookielax=true; nc_sameSiteCookiestrict=true / Connection: keep-alive / If-Modified-Since: Wed, 08 Feb 2017 13:43:20 GMT / Cache-Control: max-age=0
Feb 9 11:29:05 lohengrin snap[12798]: wsd-12857-12860 10:29:05.185603 [ client_req_hdl ] INF Request from 127.0.0.1:42476: GET /loleaflet/dist/images/lc_undo.png HTTP/1.1 / Host: localhost:9980 / User-Agent: Mozilla/5.0 (X11; Ubuntu; Linux x86_64; rv:51.0) Gecko/20100101 Firefox/51.0 / Accept: */* / Accept-Language: en-US,en;q=0.5 / Accept-Encoding: gzip, deflate / Referer: http://localhost:9980/loleaflet/dist/loleaflet.html?file_path=file:///tmp/cv.odt / Cookie: nc_sameSiteCookielax=true; nc_sameSiteCookiestrict=true / Connection: keep-alive / If-Modified-Since: Wed, 08 Feb 2017 13:43:20 GMT / Cache-Control: max-age=0
Feb 9 11:29:05 lohengrin snap[12798]: wsd-12857-12877 10:29:05.186699 [ client_req_hdl ] INF Request from 127.0.0.1:42484: GET /loleaflet/dist/images/lc_redo.png HTTP/1.1 / Host: localhost:9980 / User-Agent: Mozilla/5.0 (X11; Ubuntu; Linux x86_64; rv:51.0) Gecko/20100101 Firefox/51.0 / Accept: */* / Accept-Language: en-US,en;q=0.5 / Accept-Encoding: gzip, deflate / Referer: http://localhost:9980/loleaflet/dist/loleaflet.html?file_path=file:///tmp/cv.odt / Cookie: nc_sameSiteCookielax=true; nc_sameSiteCookiestrict=true / Connection: keep-alive / If-Modified-Since: Wed, 08 Feb 2017 13:43:20 GMT / Cache-Control: max-age=0
Feb 9 11:29:05 lohengrin snap[12798]: wsd-12857-12863 10:29:05.188044 [ client_req_hdl ] INF Request from 127.0.0.1:42482: GET /loleaflet/dist/images/lc_backward.png HTTP/1.1 / Host: localhost:9980 / User-Agent: Mozilla/5.0 (X11; Ubuntu; Linux x86_64; rv:51.0) Gecko/20100101 Firefox/51.0 / Accept: */* / Accept-Language: en-US,en;q=0.5 / Accept-Encoding: gzip, deflate / Referer: http://localhost:9980/loleaflet/dist/loleaflet.html?file_path=file:///tmp/cv.odt / Cookie: nc_sameSiteCookielax=true; nc_sameSiteCookiestrict=true / Connection: keep-alive / If-Modified-Since: Wed, 08 Feb 2017 13:43:20 GMT / Cache-Control: max-age=0
Feb 9 11:29:05 lohengrin snap[12798]: wsd-12857-12860 10:29:05.241265 [ client_req_hdl ] INF Request from 127.0.0.1:42476: GET /loleaflet/dist/images/lc_italic.png HTTP/1.1 / Host: localhost:9980 / User-Agent: Mozilla/5.0 (X11; Ubuntu; Linux x86_64; rv:51.0) Gecko/20100101 Firefox/51.0 / Accept: */* / Accept-Language: en-US,en;q=0.5 / Accept-Encoding: gzip, deflate / Referer: http://localhost:9980/loleaflet/dist/loleaflet.html?file_path=file:///tmp/cv.odt / Cookie: nc_sameSiteCookielax=true; nc_sameSiteCookiestrict=true / Connection: keep-alive / If-Modified-Since: Wed, 08 Feb 2017 13:43:20 GMT / Cache-Control: max-age=0
Feb 9 11:29:05 lohengrin snap[12798]: wsd-12857-12862 10:29:05.241410 [ client_req_hdl ] INF Request from 127.0.0.1:42480: GET /loleaflet/dist/images/lc_strikeout.png HTTP/1.1 / Host: localhost:9980 / User-Agent: Mozilla/5.0 (X11; Ubuntu; Linux x86_64; rv:51.0) Gecko/20100101 Firefox/51.0 / Accept: */* / Accept-Language: en-US,en;q=0.5 / Accept-Encoding: gzip, deflate / Referer: http://localhost:9980/loleaflet/dist/loleaflet.html?file_path=file:///tmp/cv.odt / Cookie: nc_sameSiteCookielax=true; nc_sameSiteCookiestrict=true / Connection: keep-alive / If-Modified-Since: Wed, 08 Feb 2017 13:43:20 GMT / Cache-Control: max-age=0
Feb 9 11:29:05 lohengrin snap[12798]: wsd-12857-12861 10:29:05.241693 [ client_req_hdl ] INF Request from 127.0.0.1:42478: GET /loleaflet/dist/images/lc_color.png HTTP/1.1 / Host: localhost:9980 / User-Agent: Mozilla/5.0 (X11; Ubuntu; Linux x86_64; rv:51.0) Gecko/20100101 Firefox/51.0 / Accept: */* / Accept-Language: en-US,en;q=0.5 / Accept-Encoding: gzip, deflate / Referer: http://localhost:9980/loleaflet/dist/loleaflet.html?file_path=file:///tmp/cv.odt / Cookie: nc_sameSiteCookielax=true; nc_sameSiteCookiestrict=true / Connection: keep-alive / If-Modified-Since: Wed, 08 Feb 2017 13:43:20 GMT / Cache-Control: max-age=0
Feb 9 11:29:05 lohengrin snap[12798]: wsd-12857-12877 10:29:05.242073 [ client_req_hdl ] INF Request from 127.0.0.1:42484: GET /loleaflet/dist/images/lc_backcolor.png HTTP/1.1 / Host: localhost:9980 / User-Agent: Mozilla/5.0 (X11; Ubuntu; Linux x86_64; rv:51.0) Gecko/20100101 Firefox/51.0 / Accept: */* / Accept-Language: en-US,en;q=0.5 / Accept-Encoding: gzip, deflate / Referer: http://localhost:9980/loleaflet/dist/loleaflet.html?file_path=file:///tmp/cv.odt / Cookie: nc_sameSiteCookielax=true; nc_sameSiteCookiestrict=true / Connection: keep-alive / If-Modified-Since: Wed, 08 Feb 2017 13:43:20 GMT / Cache-Control: max-age=0
Feb 9 11:29:05 lohengrin snap[12798]: wsd-12857-12861 10:29:05.242324 [ client_req_hdl ] INF Request from 127.0.0.1:42478: GET /loleaflet/dist/images/lc_alignleft.png HTTP/1.1 / Host: localhost:9980 / User-Agent: Mozilla/5.0 (X11; Ubuntu; Linux x86_64; rv:51.0) Gecko/20100101 Firefox/51.0 / Accept: */* / Accept-Language: en-US,en;q=0.5 / Accept-Encoding: gzip, deflate / Referer: http://localhost:9980/loleaflet/dist/loleaflet.html?file_path=file:///tmp/cv.odt / Cookie: nc_sameSiteCookielax=true; nc_sameSiteCookiestrict=true / Connection: keep-alive / If-Modified-Since: Wed, 08 Feb 2017 13:43:20 GMT / Cache-Control: max-age=0
Feb 9 11:29:05 lohengrin snap[12798]: wsd-12857-12860 10:29:05.242359 [ client_req_hdl ] INF Request from 127.0.0.1:42476: GET /loleaflet/dist/images/lc_alignhorizontalcenter.png HTTP/1.1 / Host: localhost:9980 / User-Agent: Mozilla/5.0 (X11; Ubuntu; Linux x86_64; rv:51.0) Gecko/20100101 Firefox/51.0 / Accept: */* / Accept-Language: en-US,en;q=0.5 / Accept-Encoding: gzip, deflate / Referer: http://localhost:9980/loleaflet/dist/loleaflet.html?file_path=file:///tmp/cv.odt / Cookie: nc_sameSiteCookielax=true; nc_sameSiteCookiestrict=true / Connection: keep-alive / If-Modified-Since: Wed, 08 Feb 2017 13:43:20 GMT / Cache-Control: max-age=0
Feb 9 11:29:05 lohengrin snap[12798]: wsd-12857-12862 10:29:05.242560 [ client_req_hdl ] INF Request from 127.0.0.1:42480: GET /loleaflet/dist/images/lc_alignright.png HTTP/1.1 / Host: localhost:9980 / User-Agent: Mozilla/5.0 (X11; Ubuntu; Linux x86_64; rv:51.0) Gecko/20100101 Firefox/51.0 / Accept: */* / Accept-Language: en-US,en;q=0.5 / Accept-Encoding: gzip, deflate / Referer: http://localhost:9980/loleaflet/dist/loleaflet.html?file_path=file:///tmp/cv.odt / Cookie: nc_sameSiteCookielax=true; nc_sameSiteCookiestrict=true / Connection: keep-alive / If-Modified-Since: Wed, 08 Feb 2017 13:43:20 GMT / Cache-Control: max-age=0
Feb 9 11:29:05 lohengrin snap[12798]: wsd-12857-12859 10:29:05.241263 [ client_req_hdl ] INF Request from 127.0.0.1:42474: GET /loleaflet/dist/images/lc_bold.png HTTP/1.1 / Host: localhost:9980 / User-Agent: Mozilla/5.0 (X11; Ubuntu; Linux x86_64; rv:51.0) Gecko/20100101 Firefox/51.0 / Accept: */* / Accept-Language: en-US,en;q=0.5 / Accept-Encoding: gzip, deflate / Referer: http://localhost:9980/loleaflet/dist/loleaflet.html?file_path=file:///tmp/cv.odt / Cookie: nc_sameSiteCookielax=true; nc_sameSiteCookiestrict=true / Connection: keep-alive / If-Modified-Since: Wed, 08 Feb 2017 13:43:20 GMT / Cache-Control: max-age=0
Feb 9 11:29:05 lohengrin snap[12798]: wsd-12857-12863 10:29:05.241263 [ client_req_hdl ] INF Request from 127.0.0.1:42482: GET /loleaflet/dist/images/lc_underline.png HTTP/1.1 / Host: localhost:9980 / User-Agent: Mozilla/5.0 (X11; Ubuntu; Linux x86_64; rv:51.0) Gecko/20100101 Firefox/51.0 / Accept: */* / Accept-Language: en-US,en;q=0.5 / Accept-Encoding: gzip, deflate / Referer: http://localhost:9980/loleaflet/dist/loleaflet.html?file_path=file:///tmp/cv.odt / Cookie: nc_sameSiteCookielax=true; nc_sameSiteCookiestrict=true / Connection: keep-alive / If-Modified-Since: Wed, 08 Feb 2017 13:43:20 GMT / Cache-Control: max-age=0
Feb 9 11:29:05 lohengrin snap[12798]: wsd-12857-12861 10:29:05.243099 [ client_req_hdl ] INF Request from 127.0.0.1:42478: GET /loleaflet/dist/images/lc_alignblock.png HTTP/1.1 / Host: localhost:9980 / User-Agent: Mozilla/5.0 (X11; Ubuntu; Linux x86_64; rv:51.0) Gecko/20100101 Firefox/51.0 / Accept: */* / Accept-Language: en-US,en;q=0.5 / Accept-Encoding: gzip, deflate / Referer: http://localhost:9980/loleaflet/dist/loleaflet.html?file_path=file:///tmp/cv.odt / Cookie: nc_sameSiteCookielax=true; nc_sameSiteCookiestrict=true / Connection: keep-alive / If-Modified-Since: Wed, 08 Feb 2017 13:43:20 GMT / Cache-Control: max-age=0
Feb 9 11:29:05 lohengrin snap[12798]: wsd-12857-12860 10:29:05.243117 [ client_req_hdl ] INF Request from 127.0.0.1:42476: GET /loleaflet/dist/images/lc_wraptext.png HTTP/1.1 / Host: localhost:9980 / User-Agent: Mozilla/5.0 (X11; Ubuntu; Linux x86_64; rv:51.0) Gecko/20100101 Firefox/51.0 / Accept: */* / Accept-Language: en-US,en;q=0.5 / Accept-Encoding: gzip, deflate / Referer: http://localhost:9980/loleaflet/dist/loleaflet.html?file_path=file:///tmp/cv.odt / Cookie: nc_sameSiteCookielax=true; nc_sameSiteCookiestrict=true / Connection: keep-alive / If-Modified-Since: Wed, 08 Feb 2017 13:43:20 GMT / Cache-Control: max-age=0
Feb 9 11:29:05 lohengrin snap[12798]: wsd-12857-12877 10:29:05.243325 [ client_req_hdl ] INF Request from 127.0.0.1:42484: GET /loleaflet/dist/images/lc_togglemergecells.png HTTP/1.1 / Host: localhost:9980 / User-Agent: Mozilla/5.0 (X11; Ubuntu; Linux x86_64; rv:51.0) Gecko/20100101 Firefox/51.0 / Accept: */* / Accept-Language: en-US,en;q=0.5 / Accept-Encoding: gzip, deflate / Referer: http://localhost:9980/loleaflet/dist/loleaflet.html?file_path=file:///tmp/cv.odt / Cookie: nc_sameSiteCookielax=true; nc_sameSiteCookiestrict=true / Connection: keep-alive / If-Modified-Since: Wed, 08 Feb 2017 13:43:20 GMT / Cache-Control: max-age=0
Feb 9 11:29:05 lohengrin snap[12798]: wsd-12857-12863 10:29:05.243350 [ client_req_hdl ] INF Request from 127.0.0.1:42482: GET /loleaflet/dist/images/lc_currencyfield.png HTTP/1.1 / Host: localhost:9980 / User-Agent: Mozilla/5.0 (X11; Ubuntu; Linux x86_64; rv:51.0) Gecko/20100101 Firefox/51.0 / Accept: */* / Accept-Language: en-US,en;q=0.5 / Accept-Encoding: gzip, deflate / Referer: http://localhost:9980/loleaflet/dist/loleaflet.html?file_path=file:///tmp/cv.odt / Cookie: nc_sameSiteCookielax=true; nc_sameSiteCookiestrict=true / Connection: keep-alive / If-Modified-Since: Wed, 08 Feb 2017 13:43:20 GMT / Cache-Control: max-age=0
Feb 9 11:29:05 lohengrin snap[12798]: wsd-12857-12862 10:29:05.243540 [ client_req_hdl ] INF Request from 127.0.0.1:42480: GET /loleaflet/dist/images/lc_numberformatpercent.png HTTP/1.1 / Host: localhost:9980 / User-Agent: Mozilla/5.0 (X11; Ubuntu; Linux x86_64; rv:51.0) Gecko/20100101 Firefox/51.0 / Accept: */* / Accept-Language: en-US,en;q=0.5 / Accept-Encoding: gzip, deflate / Referer: http://localhost:9980/loleaflet/dist/loleaflet.html?file_path=file:///tmp/cv.odt / Cookie: nc_sameSiteCookielax=true; nc_sameSiteCookiestrict=true / Connection: keep-alive / If-Modified-Since: Wed, 08 Feb 2017 13:43:20 GMT / Cache-Control: max-age=0
Feb 9 11:29:05 lohengrin snap[12798]: wsd-12857-12859 10:29:05.243565 [ client_req_hdl ] INF Request from 127.0.0.1:42474: GET /loleaflet/dist/images/lc_numberformatdecimal.png HTTP/1.1 / Host: localhost:9980 / User-Agent: Mozilla/5.0 (X11; Ubuntu; Linux x86_64; rv:51.0) Gecko/20100101 Firefox/51.0 / Accept: */* / Accept-Language: en-US,en;q=0.5 / Accept-Encoding: gzip, deflate / Referer: http://localhost:9980/loleaflet/dist/loleaflet.html?file_path=file:///tmp/cv.odt / Cookie: nc_sameSiteCookielax=true; nc_sameSiteCookiestrict=true / Connection: keep-alive / If-Modified-Since: Wed, 08 Feb 2017 13:43:20 GMT / Cache-Control: max-age=0
Feb 9 11:29:05 lohengrin snap[12798]: wsd-12857-12861 10:29:05.244012 [ client_req_hdl ] INF Request from 127.0.0.1:42478: GET /loleaflet/dist/images/lc_datefield.png HTTP/1.1 / Host: localhost:9980 / User-Agent: Mozilla/5.0 (X11; Ubuntu; Linux x86_64; rv:51.0) Gecko/20100101 Firefox/51.0 / Accept: */* / Accept-Language: en-US,en;q=0.5 / Accept-Encoding: gzip, deflate / Referer: http://localhost:9980/loleaflet/dist/loleaflet.html?file_path=file:///tmp/cv.odt / Cookie: nc_sameSiteCookielax=true; nc_sameSiteCookiestrict=true / Connection: keep-alive / If-Modified-Since: Wed, 08 Feb 2017 13:43:20 GMT / Cache-Control: max-age=0
Feb 9 11:29:05 lohengrin snap[12798]: wsd-12857-12860 10:29:05.244030 [ client_req_hdl ] INF Request from 127.0.0.1:42476: GET /loleaflet/dist/images/lc_numberformatincdecimals.png HTTP/1.1 / Host: localhost:9980 / User-Agent: Mozilla/5.0 (X11; Ubuntu; Linux x86_64; rv:51.0) Gecko/20100101 Firefox/51.0 / Accept: */* / Accept-Language: en-US,en;q=0.5 / Accept-Encoding: gzip, deflate / Referer: http://localhost:9980/loleaflet/dist/loleaflet.html?file_path=file:///tmp/cv.odt / Cookie: nc_sameSiteCookielax=true; nc_sameSiteCookiestrict=true / Connection: keep-alive / If-Modified-Since: Wed, 08 Feb 2017 13:43:20 GMT / Cache-Control: max-age=0
Feb 9 11:29:05 lohengrin snap[12798]: wsd-12857-12877 10:29:05.244249 [ client_req_hdl ] INF Request from 127.0.0.1:42484: GET /loleaflet/dist/images/lc_numberformatdecdecimals.png HTTP/1.1 / Host: localhost:9980 / User-Agent: Mozilla/5.0 (X11; Ubuntu; Linux x86_64; rv:51.0) Gecko/20100101 Firefox/51.0 / Accept: */* / Accept-Language: en-US,en;q=0.5 / Accept-Encoding: gzip, deflate / Referer: http://localhost:9980/loleaflet/dist/loleaflet.html?file_path=file:///tmp/cv.odt / Cookie: nc_sameSiteCookielax=true; nc_sameSiteCookiestrict=true / Connection: keep-alive / If-Modified-Since: Wed, 08 Feb 2017 13:43:20 GMT / Cache-Control: max-age=0
Feb 9 11:29:05 lohengrin snap[12798]: wsd-12857-12863 10:29:05.244287 [ client_req_hdl ] INF Request from 127.0.0.1:42482: GET /loleaflet/dist/images/lc_sortascending.png HTTP/1.1 / Host: localhost:9980 / User-Agent: Mozilla/5.0 (X11; Ubuntu; Linux x86_64; rv:51.0) Gecko/20100101 Firefox/51.0 / Accept: */* / Accept-Language: en-US,en;q=0.5 / Accept-Encoding: gzip, deflate / Referer: http://localhost:9980/loleaflet/dist/loleaflet.html?file_path=file:///tmp/cv.odt / Cookie: nc_sameSiteCookielax=true; nc_sameSiteCookiestrict=true / Connection: keep-alive / If-Modified-Since: Wed, 08 Feb 2017 13:43:20 GMT / Cache-Control: max-age=0
Feb 9 11:29:05 lohengrin snap[12798]: wsd-12857-12862 10:29:05.244489 [ client_req_hdl ] INF Request from 127.0.0.1:42480: GET /loleaflet/dist/images/lc_sortdescending.png HTTP/1.1 / Host: localhost:9980 / User-Agent: Mozilla/5.0 (X11; Ubuntu; Linux x86_64; rv:51.0) Gecko/20100101 Firefox/51.0 / Accept: */* / Accept-Language: en-US,en;q=0.5 / Accept-Encoding: gzip, deflate / Referer: http://localhost:9980/loleaflet/dist/loleaflet.html?file_path=file:///tmp/cv.odt / Cookie: nc_sameSiteCookielax=true; nc_sameSiteCookiestrict=true / Connection: keep-alive / If-Modified-Since: Wed, 08 Feb 2017 13:43:20 GMT / Cache-Control: max-age=0
Feb 9 11:29:05 lohengrin snap[12798]: wsd-12857-12859 10:29:05.244513 [ client_req_hdl ] INF Request from 127.0.0.1:42474: GET /loleaflet/dist/images/lc_defaultbullet.png HTTP/1.1 / Host: localhost:9980 / User-Agent: Mozilla/5.0 (X11; Ubuntu; Linux x86_64; rv:51.0) Gecko/20100101 Firefox/51.0 / Accept: */* / Accept-Language: en-US,en;q=0.5 / Accept-Encoding: gzip, deflate / Referer: http://localhost:9980/loleaflet/dist/loleaflet.html?file_path=file:///tmp/cv.odt / Cookie: nc_sameSiteCookielax=true; nc_sameSiteCookiestrict=true / Connection: keep-alive / If-Modified-Since: Wed, 08 Feb 2017 13:43:20 GMT / Cache-Control: max-age=0
Feb 9 11:29:05 lohengrin snap[12798]: wsd-12857-12861 10:29:05.244924 [ client_req_hdl ] INF Request from 127.0.0.1:42478: GET /loleaflet/dist/images/lc_defaultnumbering.png HTTP/1.1 / Host: localhost:9980 / User-Agent: Mozilla/5.0 (X11; Ubuntu; Linux x86_64; rv:51.0) Gecko/20100101 Firefox/51.0 / Accept: */* / Accept-Language: en-US,en;q=0.5 / Accept-Encoding: gzip, deflate / Referer: http://localhost:9980/loleaflet/dist/loleaflet.html?file_path=file:///tmp/cv.odt / Cookie: nc_sameSiteCookielax=true; nc_sameSiteCookiestrict=true / Connection: keep-alive / If-Modified-Since: Wed, 08 Feb 2017 13:43:20 GMT / Cache-Control: max-age=0
Feb 9 11:29:05 lohengrin snap[12798]: wsd-12857-12860 10:29:05.244945 [ client_req_hdl ] INF Request from 127.0.0.1:42476: GET /loleaflet/dist/images/lc_decrementlevel.png HTTP/1.1 / Host: localhost:9980 / User-Agent: Mozilla/5.0 (X11; Ubuntu; Linux x86_64; rv:51.0) Gecko/20100101 Firefox/51.0 / Accept: */* / Accept-Language: en-US,en;q=0.5 / Accept-Encoding: gzip, deflate / Referer: http://localhost:9980/loleaflet/dist/loleaflet.html?file_path=file:///tmp/cv.odt / Cookie: nc_sameSiteCookielax=true; nc_sameSiteCookiestrict=true / Connection: keep-alive / If-Modified-Since: Wed, 08 Feb 2017 13:43:20 GMT / Cache-Control: max-age=0
Feb 9 11:29:05 lohengrin snap[12798]: wsd-12857-12877 10:29:05.245143 [ client_req_hdl ] INF Request from 127.0.0.1:42484: GET /loleaflet/dist/images/lc_decrementindent.png HTTP/1.1 / Host: localhost:9980 / User-Agent: Mozilla/5.0 (X11; Ubuntu; Linux x86_64; rv:51.0) Gecko/20100101 Firefox/51.0 / Accept: */* / Accept-Language: en-US,en;q=0.5 / Accept-Encoding: gzip, deflate / Referer: http://localhost:9980/loleaflet/dist/loleaflet.html?file_path=file:///tmp/cv.odt / Cookie: nc_sameSiteCookielax=true; nc_sameSiteCookiestrict=true / Connection: keep-alive / If-Modified-Since: Wed, 08 Feb 2017 13:43:20 GMT / Cache-Control: max-age=0
Feb 9 11:29:05 lohengrin snap[12798]: wsd-12857-12863 10:29:05.245171 [ client_req_hdl ] INF Request from 127.0.0.1:42482: GET /loleaflet/dist/images/lc_inserttable.png HTTP/1.1 / Host: localhost:9980 / User-Agent: Mozilla/5.0 (X11; Ubuntu; Linux x86_64; rv:51.0) Gecko/20100101 Firefox/51.0 / Accept: */* / Accept-Language: en-US,en;q=0.5 / Accept-Encoding: gzip, deflate / Referer: http://localhost:9980/loleaflet/dist/loleaflet.html?file_path=file:///tmp/cv.odt / Cookie: nc_sameSiteCookielax=true; nc_sameSiteCookiestrict=true / Connection: keep-alive / If-Modified-Since: Wed, 08 Feb 2017 13:43:20 GMT / Cache-Control: max-age=0
Feb 9 11:29:05 lohengrin snap[12798]: wsd-12857-12862 10:29:05.245365 [ client_req_hdl ] INF Request from 127.0.0.1:42480: GET /loleaflet/dist/images/lc_showannotations.png HTTP/1.1 / Host: localhost:9980 / User-Agent: Mozilla/5.0 (X11; Ubuntu; Linux x86_64; rv:51.0) Gecko/20100101 Firefox/51.0 / Accept: */* / Accept-Language: en-US,en;q=0.5 / Accept-Encoding: gzip, deflate / Referer: http://localhost:9980/loleaflet/dist/loleaflet.html?file_path=file:///tmp/cv.odt / Cookie: nc_sameSiteCookielax=true; nc_sameSiteCookiestrict=true / Connection: keep-alive / If-Modified-Since: Wed, 08 Feb 2017 13:43:20 GMT / Cache-Control: max-age=0
Feb 9 11:29:05 lohengrin snap[12798]: wsd-12857-12859 10:29:05.245389 [ client_req_hdl ] INF Request from 127.0.0.1:42474: GET /loleaflet/dist/images/lc_gallery.png HTTP/1.1 / Host: localhost:9980 / User-Agent: Mozilla/5.0 (X11; Ubuntu; Linux x86_64; rv:51.0) Gecko/20100101 Firefox/51.0 / Accept: */* / Accept-Language: en-US,en;q=0.5 / Accept-Encoding: gzip, deflate / Referer: http://localhost:9980/loleaflet/dist/loleaflet.html?file_path=file:///tmp/cv.odt / Cookie: nc_sameSiteCookielax=true; nc_sameSiteCookiestrict=true / Connection: keep-alive / If-Modified-Since: Wed, 08 Feb 2017 13:43:20 GMT / Cache-Control: max-age=0
Feb 9 11:29:05 lohengrin snap[12798]: wsd-12857-12861 10:29:05.245787 [ client_req_hdl ] INF Request from 127.0.0.1:42478: GET /loleaflet/dist/images/lc_insertsymbol.png HTTP/1.1 / Host: localhost:9980 / User-Agent: Mozilla/5.0 (X11; Ubuntu; Linux x86_64; rv:51.0) Gecko/20100101 Firefox/51.0 / Accept: */* / Accept-Language: en-US,en;q=0.5 / Accept-Encoding: gzip, deflate / Referer: http://localhost:9980/loleaflet/dist/loleaflet.html?file_path=file:///tmp/cv.odt / Cookie: nc_sameSiteCookielax=true; nc_sameSiteCookiestrict=true / Connection: keep-alive / If-Modified-Since: Wed, 08 Feb 2017 13:43:20 GMT / Cache-Control: max-age=0
Feb 9 11:29:05 lohengrin snap[12798]: wsd-12857-12860 10:29:05.245808 [ client_req_hdl ] INF Request from 127.0.0.1:42476: GET /loleaflet/dist/images/lc_downsearch.png HTTP/1.1 / Host: localhost:9980 / User-Agent: Mozilla/5.0 (X11; Ubuntu; Linux x86_64; rv:51.0) Gecko/20100101 Firefox/51.0 / Accept: */* / Accept-Language: en-US,en;q=0.5 / Accept-Encoding: gzip, deflate / Referer: http://localhost:9980/loleaflet/dist/loleaflet.html?file_path=file:///tmp/cv.odt / Cookie: nc_sameSiteCookielax=true; nc_sameSiteCookiestrict=true / Connection: keep-alive / If-Modified-Since: Wed, 08 Feb 2017 13:43:20 GMT / Cache-Control: max-age=0
Feb 9 11:29:05 lohengrin snap[12798]: wsd-12857-12877 10:29:05.245999 [ client_req_hdl ] INF Request from 127.0.0.1:42484: GET /loleaflet/dist/images/lc_autosum.png HTTP/1.1 / Host: localhost:9980 / User-Agent: Mozilla/5.0 (X11; Ubuntu; Linux x86_64; rv:51.0) Gecko/20100101 Firefox/51.0 / Accept: */* / Accept-Language: en-US,en;q=0.5 / Accept-Encoding: gzip, deflate / Referer: http://localhost:9980/loleaflet/dist/loleaflet.html?file_path=file:///tmp/cv.odt / Cookie: nc_sameSiteCookielax=true; nc_sameSiteCookiestrict=true / Connection: keep-alive / If-Modified-Since: Wed, 08 Feb 2017 13:43:20 GMT / Cache-Control: max-age=0
Feb 9 11:29:05 lohengrin snap[12798]: wsd-12857-12863 10:29:05.246035 [ client_req_hdl ] INF Request from 127.0.0.1:42482: GET /loleaflet/dist/images/lc26049.png HTTP/1.1 / Host: localhost:9980 / User-Agent: Mozilla/5.0 (X11; Ubuntu; Linux x86_64; rv:51.0) Gecko/20100101 Firefox/51.0 / Accept: */* / Accept-Language: en-US,en;q=0.5 / Accept-Encoding: gzip, deflate / Referer: http://localhost:9980/loleaflet/dist/loleaflet.html?file_path=file:///tmp/cv.odt / Cookie: nc_sameSiteCookielax=true; nc_sameSiteCookiestrict=true / Connection: keep-alive / If-Modified-Since: Wed, 08 Feb 2017 13:43:20 GMT / Cache-Control: max-age=0
Feb 9 11:29:05 lohengrin snap[12798]: wsd-12857-12862 10:29:05.246219 [ client_req_hdl ] INF Request from 127.0.0.1:42480: GET /loleaflet/dist/images/lc_upsearch.png HTTP/1.1 / Host: localhost:9980 / User-Agent: Mozilla/5.0 (X11; Ubuntu; Linux x86_64; rv:51.0) Gecko/20100101 Firefox/51.0 / Accept: */* / Accept-Language: en-US,en;q=0.5 / Accept-Encoding: gzip, deflate / Referer: http://localhost:9980/loleaflet/dist/loleaflet.html?file_path=file:///tmp/cv.odt / Cookie: nc_sameSiteCookielax=true; nc_sameSiteCookiestrict=true / Connection: keep-alive / If-Modified-Since: Wed, 08 Feb 2017 13:43:20 GMT / Cache-Control: max-age=0
Feb 9 11:29:05 lohengrin snap[12798]: wsd-12857-12859 10:29:05.246242 [ client_req_hdl ] INF Request from 127.0.0.1:42474: GET /loleaflet/dist/images/lc_view100.png HTTP/1.1 / Host: localhost:9980 / User-Agent: Mozilla/5.0 (X11; Ubuntu; Linux x86_64; rv:51.0) Gecko/20100101 Firefox/51.0 / Accept: */* / Accept-Language: en-US,en;q=0.5 / Accept-Encoding: gzip, deflate / Referer: http://localhost:9980/loleaflet/dist/loleaflet.html?file_path=file:///tmp/cv.odt / Cookie: nc_sameSiteCookielax=true; nc_sameSiteCookiestrict=true / Connection: keep-alive / If-Modified-Since: Wed, 08 Feb 2017 13:43:20 GMT / Cache-Control: max-age=0
Feb 9 11:29:05 lohengrin snap[12798]: wsd-12857-12860 10:29:05.246696 [ client_req_hdl ] INF Request from 127.0.0.1:42476: GET /loleaflet/dist/images/plus.png HTTP/1.1 / Host: localhost:9980 / User-Agent: Mozilla/5.0 (X11; Ubuntu; Linux x86_64; rv:51.0) Gecko/20100101 Firefox/51.0 / Accept: */* / Accept-Language: en-US,en;q=0.5 / Accept-Encoding: gzip, deflate / Referer: http://localhost:9980/loleaflet/dist/loleaflet.html?file_path=file:///tmp/cv.odt / Cookie: nc_sameSiteCookielax=true; nc_sameSiteCookiestrict=true / Connection: keep-alive / If-Modified-Since: Wed, 08 Feb 2017 13:43:20 GMT / Cache-Control: max-age=0
Feb 9 11:29:05 lohengrin snap[12798]: wsd-12857-12861 10:29:05.250738 [ client_req_hdl ] INF Request from 127.0.0.1:42478: GET /loleaflet/dist/images/minus.png HTTP/1.1 / Host: localhost:9980 / User-Agent: Mozilla/5.0 (X11; Ubuntu; Linux x86_64; rv:51.0) Gecko/20100101 Firefox/51.0 / Accept: */* / Accept-Language: en-US,en;q=0.5 / Accept-Encoding: gzip, deflate / Referer: http://localhost:9980/loleaflet/dist/loleaflet.html?file_path=file:///tmp/cv.odt / Cookie: nc_sameSiteCookielax=true; nc_sameSiteCookiestrict=true / Connection: keep-alive / If-Modified-Since: Wed, 08 Feb 2017 13:43:20 GMT / Cache-Control: max-age=0
Feb 9 11:29:05 lohengrin snap[12798]: wsd-12857-12878 10:29:05.352061 [ client_req_hdl ] INF Request from 127.0.0.1:42486: GET /lool/file%3A%2F%2F%2Ftmp%2Fcv.odt%3Fpermission%3Dedit/ws HTTP/1.1 / Host: localhost:9980 / User-Agent: Mozilla/5.0 (X11; Ubuntu; Linux x86_64; rv:51.0) Gecko/20100101 Firefox/51.0 / Accept: text/html,application/xhtml+xml,application/xml;q=0.9,*/*;q=0.8 / Accept-Language: en-US,en;q=0.5 / Accept-Encoding: gzip, deflate / Sec-WebSocket-Version: 13 / Origin: http://localhost:9980 / Sec-WebSocket-Extensions: permessage-deflate / Sec-WebSocket-Key: fuB6ZZEdHKGGFR7YKzNVhQ== / Cookie: nc_sameSiteCookielax=true; nc_sameSiteCookiestrict=true / Connection: keep-alive, Upgrade / Pragma: no-cache / Cache-Control: no-cache / Upgrade: websocket
Feb 9 11:29:05 lohengrin snap[12798]: wsd-12857-12878 10:29:05.352132 [ client_ws_0001 ] DBG Thread started.| wsd/LOOLWSD.cpp:1141
Feb 9 11:29:05 lohengrin snap[12798]: wsd-12857-12878 10:29:05.352147 [ client_ws_0001 ] DBG Handling: /lool/file%3A%2F%2F%2Ftmp%2Fcv.odt%3Fpermission%3Dedit/ws| wsd/LOOLWSD.cpp:1144
Feb 9 11:29:05 lohengrin snap[12798]: wsd-12857-12878 10:29:05.352244 [ client_ws_0001 ] INF Send buffer size for web socket set to minimum: 4608| ./wsd/LOOLWebSocket.hpp:57
Feb 9 11:29:05 lohengrin snap[12798]: wsd-12857-12878 10:29:05.352271 [ client_ws_0001 ] INF Starting GET request handler for session [0001].| wsd/LOOLWSD.cpp:763
Feb 9 11:29:05 lohengrin snap[12798]: wsd-12857-12878 10:29:05.352341 [ client_ws_0001 ] DBG No DocumentBroker with docKey [/tmp/cv.odt] found. New Child and Document.| wsd/LOOLWSD.cpp:853
Feb 9 11:29:05 lohengrin snap[12798]: wsd-12857-12878 10:29:05.352374 [ client_ws_0001 ] DBG getNewChild: Have 1 children, forking 1| wsd/LOOLWSD.cpp:430
Feb 9 11:29:05 lohengrin snap[12798]: wsd-12857-12878 10:29:05.352409 [ client_ws_0001 ] DBG MasterToForKit: spawn 1| wsd/LOOLWSD.cpp:310
Feb 9 11:29:05 lohengrin snap[12798]: frk-12865-12865 10:29:05.352457 [ loolforkit ] INF ForKit command: [spawn 1].| kit/ForKit.cpp:93
Feb 9 11:29:05 lohengrin snap[12798]: frk-12865-12865 10:29:05.352490 [ loolforkit ] INF Spawning 1 child per request.| kit/ForKit.cpp:101
Feb 9 11:29:05 lohengrin snap[12798]: frk-12865-12865 10:29:05.352501 [ loolforkit ] INF Creating 1 new child.| kit/ForKit.cpp:419
Feb 9 11:29:05 lohengrin snap[12798]: frk-12865-12865 10:29:05.352510 [ loolforkit ] DBG Forking a loolkit process.| kit/ForKit.cpp:209
Feb 9 11:29:05 lohengrin snap[12798]: frk-12865-12865 10:29:05.354315 [ loolforkit ] INF Forked kit [12879].| kit/ForKit.cpp:244
Feb 9 11:29:05 lohengrin snap[12798]: kit-12879-12879 10:29:05.354511 [ loolkit ] INF Initializing kit
Feb 9 11:29:05 lohengrin snap[12798]: wsd-12857-12878 10:29:05.354546 [ client_ws_0001 ] DBG getNewChild: Returning new child [12869].| wsd/LOOLWSD.cpp:442
Feb 9 11:29:05 lohengrin snap[12798]: kit-12879-12879 10:29:05.354563 [ loolkit ] INF Log level is [7].
Feb 9 11:29:05 lohengrin snap[12798]: wsd-12857-12878 10:29:05.354572 [ client_ws_0001 ] DBG New DocumentBroker for docKey [/tmp/cv.odt].| wsd/LOOLWSD.cpp:885
Feb 9 11:29:05 lohengrin snap[12798]: kit-12879-12879 10:29:05.354598 [ loolkit ] DBG Process started.| kit/Kit.cpp:1494
Feb 9 11:29:05 lohengrin snap[12798]: wsd-12857-12878 10:29:05.354613 [ client_ws_0001 ] INF DocumentBroker [file:///tmp/cv.odt?permission=edit] created. DocKey: [/tmp/cv.odt]| wsd/DocumentBroker.cpp:172
Feb 9 11:29:05 lohengrin snap[12798]: kit-12879-12879 10:29:05.354648 [ loolkit ] INF Jail path: /var/snap/loolwsd/x4/opt/lool/child-roots/12879/| kit/Kit.cpp:1508
Feb 9 11:29:05 lohengrin snap[12798]: wsd-12857-12878 10:29:05.354680 [ client_ws_0001 ] DBG Query param: permission, value: edit| wsd/LOOLWSD.cpp:908
Feb 9 11:29:05 lohengrin snap[12798]: wsd-12857-12878 10:29:05.354703 [ client_ws_0001 ] INF ClientSession ctor [ToClient-0001].
Feb 9 11:29:05 lohengrin snap[12798]: wsd-12857-12880 10:29:05.354767 [ client_ws_0001 ] DBG ToClient-0001 SenderThread started| wsd/ClientSession.cpp:468
Feb 9 11:29:05 lohengrin snap[12798]: wsd-12857-12878 10:29:05.354800 [ client_ws_0001 ] INF Loading [/tmp/cv.odt] for session [0001] and jail [12869].| wsd/DocumentBroker.cpp:198
Feb 9 11:29:05 lohengrin snap[12798]: wsd-12857-12878 10:29:05.354818 [ client_ws_0001 ] DBG Loading from URI: file:///tmp/cv.odt?permission=edit| wsd/DocumentBroker.cpp:214
Feb 9 11:29:05 lohengrin snap[12798]: wsd-12857-12878 10:29:05.354840 [ client_ws_0001 ] INF jailPath: /user/docs/12869, jailRoot: /var/snap/loolwsd/x4/opt/lool/child-roots/12869| wsd/DocumentBroker.cpp:229
Feb 9 11:29:05 lohengrin snap[12798]: wsd-12857-12878 10:29:05.354853 [ client_ws_0001 ] DBG Creating new storage instance for URI [file:///tmp/cv.odt?permission=edit].| wsd/DocumentBroker.cpp:236
Feb 9 11:29:05 lohengrin snap[12798]: wsd-12857-12878 10:29:05.354866 [ client_ws_0001 ] INF Public URI [file:///tmp/cv.odt?permission=edit] is a file.| wsd/Storage.cpp:156
Feb 9 11:29:05 lohengrin snap[12798]: wsd-12857-12878 10:29:05.354883 [ client_ws_0001 ] DBG Storage ctor: file:///tmp/cv.odt?permission=edit
Feb 9 11:29:05 lohengrin snap[12798]: kit-12879-12879 10:29:05.354881 [ loolkit ] DBG symlink("../../../../../lo","/var/snap/loolwsd/x4/opt/lool/child-roots/12879/var/snap/loolwsd/x4/opt/collaboraoffice5.1")| kit/Kit.cpp:265
Feb 9 11:29:05 lohengrin snap[12798]: wsd-12857-12878 10:29:05.354892 [ client_ws_0001 ] INF LocalStorage ctor with localStorePath: [/var/snap/loolwsd/x4/opt/lool/child-roots/12869], jailPath: [/user/docs/12869], uri: [file:///tmp/cv.odt?permission=edit].
Feb 9 11:29:05 lohengrin snap[12798]: wsd-12857-12878 10:29:05.354909 [ client_ws_0001 ] DBG Getting info for local uri [file:///tmp/cv.odt?permission=edit], path [/tmp/cv.odt].
Feb 9 11:29:05 lohengrin snap[12798]: wsd-12857-12878 10:29:05.354941 [ client_ws_0001 ] DBG Setting username [Local Host #0] and userId [localhost] for session [0001]| wsd/DocumentBroker.cpp:306
Feb 9 11:29:05 lohengrin snap[12798]: wsd-12857-12878 10:29:05.355049 [ client_ws_0001 ] INF Public URI [/tmp/cv.odt] jailed to [/var/snap/loolwsd/x4/opt/lool/child-roots/12869/user/docs/12869/cv.odt].| wsd/Storage.cpp:216
Feb 9 11:29:05 lohengrin snap[12798]: wsd-12857-12878 10:29:05.355068 [ client_ws_0001 ] INF Linking /tmp/cv.odt to /var/snap/loolwsd/x4/opt/lool/child-roots/12869/user/docs/12869/cv.odt| wsd/Storage.cpp:226
Feb 9 11:29:05 lohengrin snap[12798]: wsd-12857-12878 10:29:05.355160 [ client_ws_0001 ] INF TileCache ctor for uri [file:///tmp/cv.odt?permission=edit] modifiedTime=1486636145 getLastModified()=0
Feb 9 11:29:05 lohengrin snap[12798]: kit-12869-12869 10:29:05.355410 [ loolkit ] DBG child_ws_12869: recv [session 0001 /tmp/cv.odt].| kit/Kit.cpp:1663
Feb 9 11:29:05 lohengrin snap[12798]: kit-12869-12869 10:29:05.355433 [ loolkit ] INF New session [0001] request on url [/tmp/cv.odt].| kit/Kit.cpp:1678
Feb 9 11:29:05 lohengrin snap[12798]: kit-12869-12869 10:29:05.355463 [ loolkit ] INF Document ctor for url [/tmp/cv.odt] on child [12869].| kit/Kit.cpp:460
Feb 9 11:29:05 lohengrin snap[12798]: kit-12869-12869 10:29:05.355494 [ loolkit ] INF Creating first session for url: /tmp/cv.odt for sessionId: 0001 on jailId: 12869| kit/Kit.cpp:494
Feb 9 11:29:05 lohengrin snap[12798]: kit-12869-12869 10:29:05.355512 [ loolkit ] INF ChildSession ctor [ToMaster-0001].| kit/ChildSession.cpp:46
Feb 9 11:29:05 lohengrin snap[12798]: kit-12869-12869 10:29:05.355529 [ loolkit ] DBG Sessions: 1| kit/Kit.cpp:499
Feb 9 11:29:05 lohengrin snap[12798]: wsd-12857-12878 10:29:05.355565 [ client_ws_0001 ] DBG Added admin document [/tmp/cv.odt].| wsd/AdminModel.cpp:247
Feb 9 11:29:05 lohengrin snap[12798]: kit-12869-12881 10:29:05.361196 [ lok_handler ] DBG Thread started.| kit/Kit.cpp:1309
Feb 9 11:29:05 lohengrin snap[12798]: wsd-12857-12878 10:29:05.381858 [ client_ws_0001 ] INF PrisonerSession ctor [ToPrisoner-0001].| wsd/PrisonerSession.cpp:44
Feb 9 11:29:05 lohengrin snap[12798]: wsd-12857-12878 10:29:05.381993 [ client_ws_0001 ] INF SocketProcessor [client_ws_0001] starting.| common/IoUtil.cpp:49
Feb 9 11:29:05 lohengrin snap[12798]: kit-12879-12879 10:29:05.449716 [ loolkit ] DBG Initialized jail files.| kit/Kit.cpp:1565
Feb 9 11:29:05 lohengrin snap[12798]: kit-12879-12879 10:29:05.449863 [ loolkit ] INF chroot("/var/snap/loolwsd/x4/opt/lool/child-roots/12879/")| kit/Kit.cpp:1582
Feb 9 11:29:05 lohengrin snap[12798]: kit-12879-12879 10:29:05.449988 [ loolkit ] DBG Initialized jail nodes, dropped caps.| kit/Kit.cpp:1599
Feb 9 11:29:05 lohengrin snap[12798]: kit-12879-12879 10:29:05.487716 [ loolkit ] INF Process is ready.| kit/Kit.cpp:1626
Feb 9 11:29:05 lohengrin snap[12798]: wsd-12857-12858 10:29:05.488360 [ prsnr_req_hdl ] INF Request from 127.0.0.1:47058: GET /loolws/newchild?pid=12879 HTTP/1.0 / Connection: Upgrade / Upgrade: websocket / Sec-WebSocket-Version: 13 / Sec-WebSocket-Key: wi6SF6XTa1xaNHkJZ3ffQA== / Host: 127.0.0.1:9981
Feb 9 11:29:05 lohengrin snap[12798]: wsd-12857-12858 10:29:05.488414 [ prsnr_req_hdl ] INF New child [12879].| wsd/LOOLWSD.cpp:1312
Feb 9 11:29:05 lohengrin snap[12798]: wsd-12857-12858 10:29:05.488487 [ prsnr_req_hdl ] INF Send buffer size for web socket set to minimum: 4608| ./wsd/LOOLWebSocket.hpp:57
Feb 9 11:29:05 lohengrin snap[12798]: wsd-12857-12858 10:29:05.488539 [ prsnr_req_hdl ] INF ChildProcess ctor [12879].| wsd/DocumentBroker.hpp:51
Feb 9 11:29:05 lohengrin snap[12798]: wsd-12857-12858 10:29:05.488561 [ prsnr_req_hdl ] INF Have 1 child.| wsd/LOOLWSD.cpp:401
Feb 9 11:29:05 lohengrin snap[12798]: kit-12879-12879 10:29:05.488564 [ loolkit ] INF Send buffer size for web socket set to minimum: 4608| ./wsd/LOOLWebSocket.hpp:57
Feb 9 11:29:05 lohengrin snap[12798]: kit-12879-12879 10:29:05.488616 [ loolkit ] INF SocketProcessor [child_ws_12879] starting.| common/IoUtil.cpp:49
Feb 9 11:29:05 lohengrin snap[12798]: wsd-12857-12889 10:29:05.488688 [ docbrk_ws_12879 ] INF SocketProcessor [docbrk_ws_12879] starting.| common/IoUtil.cpp:49
Feb 9 11:29:05 lohengrin snap[12798]: wsd-12857-12878 10:29:05.499479 [ client_ws_0001 ] INF Requesting document load from child.
Feb 9 11:29:05 lohengrin snap[12798]: wsd-12857-12878 10:29:05.499608 [ client_ws_0001 ] INF Could not open /var/snap/loolwsd/common/cache/5/1/3/1c53ab8305e70bd41237d24b701fb708ae811/partpagerectangles.txt
Feb 9 11:29:05 lohengrin snap[12798]: kit-12869-12869 10:29:05.499708 [ loolkit ] DBG child_ws_12869: recv [child-0001 load url=file:///tmp/cv.odt?permission=edit jail=file:///user/docs/12869/cv.odt authorid=localhost author=Loc...].| kit/Kit.cpp:1663
Feb 9 11:29:05 lohengrin snap[12798]: kit-12869-12869 10:29:05.499812 [ loolkit ] DBG child_ws_12869: recv [child-0001 partpagerectangles].| kit/Kit.cpp:1663
Feb 9 11:29:05 lohengrin snap[12798]: kit-12869-12881 10:29:05.499898 [ lok_handler ] INF Loading session [0001] on url [file:///user/docs/12869/cv.odt] is loading. 1 views loaded.| kit/Kit.cpp:912
Feb 9 11:29:05 lohengrin snap[12798]: kit-12869-12881 10:29:05.499916 [ lok_handler ] INF Loading new document from URI: [file:///user/docs/12869/cv.odt] for session [0001].| kit/Kit.cpp:1126
Feb 9 11:29:05 lohengrin snap[12798]: kit-12869-12881 10:29:05.499933 [ lok_handler ] DBG Calling lokit::documentLoad.| kit/Kit.cpp:1141
Feb 9 11:29:05 lohengrin snap[12798]: kit-12869-12881 10:29:05.770878 [ lok_handler ] DBG Returned lokit::documentLoad.| kit/Kit.cpp:1143
Feb 9 11:29:05 lohengrin snap[12798]: kit-12869-12881 10:29:05.842933 [ lok_handler ] INF Document [/tmp/cv.odt] view [0] loaded. Have 1 view.| kit/Kit.cpp:1247
Feb 9 11:29:05 lohengrin snap[12798]: kit-12869-12881 10:29:05.842983 [ lok_handler ] INF Created new view with viewid: [0] for username: [Local Host #0] in session: [0001].| kit/ChildSession.cpp:339
Feb 9 11:29:05 lohengrin snap[12798]: kit-12869-12881 10:29:05.845928 [ lok_handler ] DBG Sending status after loading view 0.| kit/ChildSession.cpp:352
Feb 9 11:29:05 lohengrin snap[12798]: kit-12869-12881 10:29:05.846361 [ lok_handler ] INF Loaded session 0001| kit/ChildSession.cpp:370
Feb 9 11:29:05 lohengrin snap[12798]: wsd-12857-12878 10:29:05.849976 [ client_ws_0001 ] INF Could not open /var/snap/loolwsd/common/cache/5/1/3/1c53ab8305e70bd41237d24b701fb708ae811/cmdValues.uno:StyleApply.txt
Feb 9 11:29:05 lohengrin snap[12798]: wsd-12857-12878 10:29:05.850107 [ client_ws_0001 ] INF Could not open /var/snap/loolwsd/common/cache/5/1/3/1c53ab8305e70bd41237d24b701fb708ae811/cmdValues.uno:CharFontName.txt
Feb 9 11:29:05 lohengrin snap[12798]: kit-12869-12869 10:29:05.850287 [ loolkit ] DBG child_ws_12869: recv [child-0001 commandvalues command=.uno:StyleApply].| kit/Kit.cpp:1663
Feb 9 11:29:05 lohengrin snap[12798]: kit-12869-12869 10:29:05.850388 [ loolkit ] DBG child_ws_12869: recv [child-0001 commandvalues command=.uno:CharFontName].| kit/Kit.cpp:1663
Feb 9 11:29:05 lohengrin snap[12798]: wsd-12857-12874 10:29:05.853347 [ docbrk_ws_12869 ] INF Saving 'commandvalues: {...' to /var/snap/loolwsd/common/cache/5/1/3/1c53ab8305e70bd41237d24b701fb708ae811/cmdValues.uno:StyleApply.txt
Feb 9 11:29:05 lohengrin snap[12798]: wsd-12857-12874 10:29:05.882045 [ docbrk_ws_12869 ] INF Saving 'commandvalues: {...' to /var/snap/loolwsd/common/cache/5/1/3/1c53ab8305e70bd41237d24b701fb708ae811/cmdValues.uno:CharFontName.txt
Feb 9 11:29:06 lohengrin snap[12798]: wsd-12857-12878 10:29:06.168001 [ client_ws_0001 ] DBG Subscribing to tile (0,0,0) which has no subscribers. Subscribing for ver: 1.
Feb 9 11:29:06 lohengrin snap[12798]: wsd-12857-12878 10:29:06.168057 [ client_ws_0001 ] DBG Subscribing to tile (0,3840,0) which has no subscribers. Subscribing for ver: 2.
Feb 9 11:29:06 lohengrin snap[12798]: wsd-12857-12878 10:29:06.168092 [ client_ws_0001 ] DBG Subscribing to tile (0,7680,0) which has no subscribers. Subscribing for ver: 3.
Feb 9 11:29:06 lohengrin snap[12798]: wsd-12857-12878 10:29:06.168126 [ client_ws_0001 ] DBG Subscribing to tile (0,11520,0) which has no subscribers. Subscribing for ver: 4.
Feb 9 11:29:06 lohengrin snap[12798]: wsd-12857-12878 10:29:06.168158 [ client_ws_0001 ] DBG Subscribing to tile (0,0,3840) which has no subscribers. Subscribing for ver: 5.
Feb 9 11:29:06 lohengrin snap[12798]: wsd-12857-12878 10:29:06.168194 [ client_ws_0001 ] DBG Subscribing to tile (0,3840,3840) which has no subscribers. Subscribing for ver: 6.
Feb 9 11:29:06 lohengrin snap[12798]: wsd-12857-12878 10:29:06.168228 [ client_ws_0001 ] DBG Subscribing to tile (0,7680,3840) which has no subscribers. Subscribing for ver: 7.
Feb 9 11:29:06 lohengrin snap[12798]: wsd-12857-12878 10:29:06.168261 [ client_ws_0001 ] DBG Subscribing to tile (0,11520,3840) which has no subscribers. Subscribing for ver: 8.
Feb 9 11:29:06 lohengrin snap[12798]: wsd-12857-12878 10:29:06.168290 [ client_ws_0001 ] DBG Subscribing to tile (0,0,7680) which has no subscribers. Subscribing for ver: 9.
Feb 9 11:29:06 lohengrin snap[12798]: wsd-12857-12878 10:29:06.168325 [ client_ws_0001 ] DBG Subscribing to tile (0,3840,7680) which has no subscribers. Subscribing for ver: 10.
Feb 9 11:29:06 lohengrin snap[12798]: wsd-12857-12878 10:29:06.168360 [ client_ws_0001 ] DBG Subscribing to tile (0,7680,7680) which has no subscribers. Subscribing for ver: 11.
Feb 9 11:29:06 lohengrin snap[12798]: wsd-12857-12878 10:29:06.168397 [ client_ws_0001 ] DBG Subscribing to tile (0,11520,7680) which has no subscribers. Subscribing for ver: 12.
Feb 9 11:29:06 lohengrin snap[12798]: wsd-12857-12878 10:29:06.168432 [ client_ws_0001 ] DBG Subscribing to tile (0,0,11520) which has no subscribers. Subscribing for ver: 13.
Feb 9 11:29:06 lohengrin snap[12798]: wsd-12857-12878 10:29:06.168467 [ client_ws_0001 ] DBG Subscribing to tile (0,3840,11520) which has no subscribers. Subscribing for ver: 14.
Feb 9 11:29:06 lohengrin snap[12798]: wsd-12857-12878 10:29:06.168503 [ client_ws_0001 ] DBG Subscribing to tile (0,7680,11520) which has no subscribers. Subscribing for ver: 15.
Feb 9 11:29:06 lohengrin snap[12798]: wsd-12857-12878 10:29:06.168540 [ client_ws_0001 ] DBG Subscribing to tile (0,11520,11520) which has no subscribers. Subscribing for ver: 16.
Feb 9 11:29:06 lohengrin snap[12798]: wsd-12857-12878 10:29:06.168575 [ client_ws_0001 ] DBG Subscribing to tile (0,0,15360) which has no subscribers. Subscribing for ver: 17.
Feb 9 11:29:06 lohengrin snap[12798]: wsd-12857-12878 10:29:06.168621 [ client_ws_0001 ] DBG Subscribing to tile (0,3840,15360) which has no subscribers. Subscribing for ver: 18.
Feb 9 11:29:06 lohengrin snap[12798]: wsd-12857-12878 10:29:06.168660 [ client_ws_0001 ] DBG Subscribing to tile (0,7680,15360) which has no subscribers. Subscribing for ver: 19.
Feb 9 11:29:06 lohengrin snap[12798]: wsd-12857-12878 10:29:06.168696 [ client_ws_0001 ] DBG Subscribing to tile (0,11520,15360) which has no subscribers. Subscribing for ver: 20.
Feb 9 11:29:06 lohengrin snap[12798]: wsd-12857-12878 10:29:06.168786 [ client_ws_0001 ] DBG Sending residual tilecombine: tilecombine part=0 width=256 height=256 tileposx=0,3840,7680,11520,0,3840,7680,11520,0,3840,7680,11520,0,3840,7680,11520,0,3840,7680,11520 tileposy=0,0,0,0,3840,3840,3840,3840,7680,7680,7680,7680,11520,11520,11520,11520,15360,15360,15360,15360 imgsize=0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0 tilewidth=3840 tileheight=3840 ver=21| wsd/DocumentBroker.cpp:800
Feb 9 11:29:06 lohengrin snap[12798]: kit-12869-12869 10:29:06.168936 [ loolkit ] DBG child_ws_12869: recv [tilecombine part=0 width=256 height=256 tileposx=0,3840,7680,11520,0,3840,7680,11520,0,3840,7680,11520,0,3840,7680,11520...].| kit/Kit.cpp:1663
Feb 9 11:29:06 lohengrin snap[12798]: kit-12869-12881 10:29:06.190392 [ lok_handler ] DBG paintTile (combined) at (0, 0), (15360, 7680) ver: 21 rendered in 19.637 ms (26.699 MP/s).| kit/Kit.cpp:729
Feb 9 11:29:06 lohengrin snap[12798]: kit-12869-12881 10:29:06.190887 [ lok_handler ] DBG PNG cache with hash 9712167148528605515 missed.| kit/Kit.cpp:365
Feb 9 11:29:06 lohengrin snap[12798]: kit-12869-12881 10:29:06.202662 [ lok_handler ] DBG PNG cache with hash 5375204921831389920 missed.| kit/Kit.cpp:365
Feb 9 11:29:06 lohengrin snap[12798]: kit-12869-12881 10:29:06.212194 [ lok_handler ] DBG PNG cache with hash 8838533397317759422 missed.| kit/Kit.cpp:365
Feb 9 11:29:06 lohengrin snap[12798]: kit-12869-12881 10:29:06.220192 [ lok_handler ] DBG PNG cache with hash 10692850087684826188 missed.| kit/Kit.cpp:365
Feb 9 11:29:06 lohengrin snap[12798]: kit-12869-12881 10:29:06.226422 [ lok_handler ] DBG PNG cache with hash 15330085162921022575 missed.| kit/Kit.cpp:365
Feb 9 11:29:06 lohengrin snap[12798]: kit-12869-12881 10:29:06.235903 [ lok_handler ] DBG PNG cache with hash 1906680050116964276 missed.| kit/Kit.cpp:365
Feb 9 11:29:06 lohengrin snap[12798]: kit-12869-12881 10:29:06.246365 [ lok_handler ] DBG PNG cache with hash 11809193835048112672 missed.| kit/Kit.cpp:365
Feb 9 11:29:06 lohengrin snap[12798]: kit-12869-12881 10:29:06.253729 [ lok_handler ] DBG PNG cache with hash 2170911108350901704 missed.| kit/Kit.cpp:365
Feb 9 11:29:06 lohengrin snap[12798]: wsd-12857-12874 10:29:06.260345 [ docbrk_ws_12869 ] DBG Handling tile combined: tilecombine: part=0 width=256 height=256 tileposx=0,3840,7680,11520,0,3840,7680,11520 tileposy=0,0,0,0,3840,3840,3840,3840 imgsize=26466,10021,919,1324,15599,17295,11018,896 tilewidth=3840 tileheight=3840 ver=21 renderid=12869/0| wsd/DocumentBroker.cpp:847
Feb 9 11:29:06 lohengrin snap[12798]: wsd-12857-12874 10:29:06.260588 [ docbrk_ws_12869 ] DBG Renaming /var/snap/loolwsd/common/cache/5/1/3/1c53ab8305e70bd41237d24b701fb708ae811/0_256x256.0,0.3840x3840.png.temp to /var/snap/loolwsd/common/cache/5/1/3/1c53ab8305e70bd41237d24b701fb708ae811/0_256x256.0,0.3840x3840.png OK.
Feb 9 11:29:06 lohengrin snap[12798]: wsd-12857-12874 10:29:06.260610 [ docbrk_ws_12869 ] DBG Sending tile message to 1 subscribers: tile: part=0 width=256 height=256 tileposx=0 tileposy=0 tilewidth=3840 tileheight=3840 ver=21 imgsize=26466| wsd/TileCache.cpp:175
Feb 9 11:29:06 lohengrin snap[12798]: wsd-12857-12874 10:29:06.260803 [ docbrk_ws_12869 ] DBG STATISTICS: tile 21 internal roundtrip 92 ms.
Feb 9 11:29:06 lohengrin snap[12798]: wsd-12857-12874 10:29:06.260934 [ docbrk_ws_12869 ] DBG Renaming /var/snap/loolwsd/common/cache/5/1/3/1c53ab8305e70bd41237d24b701fb708ae811/0_256x256.3840,0.3840x3840.png.temp to /var/snap/loolwsd/common/cache/5/1/3/1c53ab8305e70bd41237d24b701fb708ae811/0_256x256.3840,0.3840x3840.png OK.
Feb 9 11:29:06 lohengrin snap[12798]: wsd-12857-12874 10:29:06.260956 [ docbrk_ws_12869 ] DBG Sending tile message to 1 subscribers: tile: part=0 width=256 height=256 tileposx=3840 tileposy=0 tilewidth=3840 tileheight=3840 ver=21 imgsize=10021| wsd/TileCache.cpp:175
Feb 9 11:29:06 lohengrin snap[12798]: wsd-12857-12874 10:29:06.261028 [ docbrk_ws_12869 ] DBG STATISTICS: tile 21 internal roundtrip 92 ms.
Feb 9 11:29:06 lohengrin snap[12798]: wsd-12857-12874 10:29:06.261112 [ docbrk_ws_12869 ] DBG Renaming /var/snap/loolwsd/common/cache/5/1/3/1c53ab8305e70bd41237d24b701fb708ae811/0_256x256.7680,0.3840x3840.png.temp to /var/snap/loolwsd/common/cache/5/1/3/1c53ab8305e70bd41237d24b701fb708ae811/0_256x256.7680,0.3840x3840.png OK.
Feb 9 11:29:06 lohengrin snap[12798]: wsd-12857-12874 10:29:06.261132 [ docbrk_ws_12869 ] DBG Sending tile message to 1 subscribers: tile: part=0 width=256 height=256 tileposx=7680 tileposy=0 tilewidth=3840 tileheight=3840 ver=21 imgsize=919| wsd/TileCache.cpp:175
Feb 9 11:29:06 lohengrin snap[12798]: wsd-12857-12874 10:29:06.261160 [ docbrk_ws_12869 ] DBG STATISTICS: tile 21 internal roundtrip 93 ms.
Feb 9 11:29:06 lohengrin snap[12798]: wsd-12857-12874 10:29:06.261231 [ docbrk_ws_12869 ] DBG Renaming /var/snap/loolwsd/common/cache/5/1/3/1c53ab8305e70bd41237d24b701fb708ae811/0_256x256.11520,0.3840x3840.png.temp to /var/snap/loolwsd/common/cache/5/1/3/1c53ab8305e70bd41237d24b701fb708ae811/0_256x256.11520,0.3840x3840.png OK.
Feb 9 11:29:06 lohengrin snap[12798]: wsd-12857-12874 10:29:06.261250 [ docbrk_ws_12869 ] DBG Sending tile message to 1 subscribers: tile: part=0 width=256 height=256 tileposx=11520 tileposy=0 tilewidth=3840 tileheight=3840 ver=21 imgsize=1324| wsd/TileCache.cpp:175
Feb 9 11:29:06 lohengrin snap[12798]: wsd-12857-12874 10:29:06.261276 [ docbrk_ws_12869 ] DBG STATISTICS: tile 21 internal roundtrip 93 ms.
Feb 9 11:29:06 lohengrin snap[12798]: wsd-12857-12874 10:29:06.261355 [ docbrk_ws_12869 ] DBG Renaming /var/snap/loolwsd/common/cache/5/1/3/1c53ab8305e70bd41237d24b701fb708ae811/0_256x256.0,3840.3840x3840.png.temp to /var/snap/loolwsd/common/cache/5/1/3/1c53ab8305e70bd41237d24b701fb708ae811/0_256x256.0,3840.3840x3840.png OK.
Feb 9 11:29:06 lohengrin snap[12798]: wsd-12857-12874 10:29:06.261375 [ docbrk_ws_12869 ] DBG Sending tile message to 1 subscribers: tile: part=0 width=256 height=256 tileposx=0 tileposy=3840 tilewidth=3840 tileheight=3840 ver=21 imgsize=15599| wsd/TileCache.cpp:175
Feb 9 11:29:06 lohengrin snap[12798]: wsd-12857-12874 10:29:06.261535 [ docbrk_ws_12869 ] DBG STATISTICS: tile 21 internal roundtrip 93 ms.
Feb 9 11:29:06 lohengrin snap[12798]: wsd-12857-12874 10:29:06.261645 [ docbrk_ws_12869 ] DBG Renaming /var/snap/loolwsd/common/cache/5/1/3/1c53ab8305e70bd41237d24b701fb708ae811/0_256x256.3840,3840.3840x3840.png.temp to /var/snap/loolwsd/common/cache/5/1/3/1c53ab8305e70bd41237d24b701fb708ae811/0_256x256.3840,3840.3840x3840.png OK.
Feb 9 11:29:06 lohengrin snap[12798]: wsd-12857-12874 10:29:06.261668 [ docbrk_ws_12869 ] DBG Sending tile message to 1 subscribers: tile: part=0 width=256 height=256 tileposx=3840 tileposy=3840 tilewidth=3840 tileheight=3840 ver=21 imgsize=17295| wsd/TileCache.cpp:175
Feb 9 11:29:06 lohengrin snap[12798]: wsd-12857-12874 10:29:06.261769 [ docbrk_ws_12869 ] DBG STATISTICS: tile 21 internal roundtrip 93 ms.
Feb 9 11:29:06 lohengrin snap[12798]: wsd-12857-12874 10:29:06.261851 [ docbrk_ws_12869 ] DBG Renaming /var/snap/loolwsd/common/cache/5/1/3/1c53ab8305e70bd41237d24b701fb708ae811/0_256x256.7680,3840.3840x3840.png.temp to /var/snap/loolwsd/common/cache/5/1/3/1c53ab8305e70bd41237d24b701fb708ae811/0_256x256.7680,3840.3840x3840.png OK.
Feb 9 11:29:06 lohengrin snap[12798]: wsd-12857-12874 10:29:06.261871 [ docbrk_ws_12869 ] DBG Sending tile message to 1 subscribers: tile: part=0 width=256 height=256 tileposx=7680 tileposy=3840 tilewidth=3840 tileheight=3840 ver=21 imgsize=11018| wsd/TileCache.cpp:175
Feb 9 11:29:06 lohengrin snap[12798]: wsd-12857-12874 10:29:06.261959 [ docbrk_ws_12869 ] DBG STATISTICS: tile 21 internal roundtrip 93 ms.
Feb 9 11:29:06 lohengrin snap[12798]: wsd-12857-12874 10:29:06.262033 [ docbrk_ws_12869 ] DBG Renaming /var/snap/loolwsd/common/cache/5/1/3/1c53ab8305e70bd41237d24b701fb708ae811/0_256x256.11520,3840.3840x3840.png.temp to /var/snap/loolwsd/common/cache/5/1/3/1c53ab8305e70bd41237d24b701fb708ae811/0_256x256.11520,3840.3840x3840.png OK.
Feb 9 11:29:06 lohengrin snap[12798]: wsd-12857-12874 10:29:06.262054 [ docbrk_ws_12869 ] DBG Sending tile message to 1 subscribers: tile: part=0 width=256 height=256 tileposx=11520 tileposy=3840 tilewidth=3840 tileheight=3840 ver=21 imgsize=896| wsd/TileCache.cpp:175
Feb 9 11:29:06 lohengrin snap[12798]: wsd-12857-12874 10:29:06.262080 [ docbrk_ws_12869 ] DBG STATISTICS: tile 21 internal roundtrip 93 ms.
Feb 9 11:29:06 lohengrin snap[12798]: kit-12869-12881 10:29:06.288255 [ lok_handler ] DBG paintTile (combined) at (0, 7680), (15360, 7680) ver: 21 rendered in 25.165 ms (20.834 MP/s).| kit/Kit.cpp:729
Feb 9 11:29:06 lohengrin snap[12798]: kit-12869-12881 10:29:06.288813 [ lok_handler ] DBG PNG cache with hash 2646321053188888293 missed.| kit/Kit.cpp:365
Feb 9 11:29:06 lohengrin snap[12798]: kit-12869-12881 10:29:06.295717 [ lok_handler ] DBG PNG cache with hash 3757904219815825003 missed.| kit/Kit.cpp:365
Feb 9 11:29:06 lohengrin snap[12798]: kit-12869-12881 10:29:06.305291 [ lok_handler ] DBG PNG cache with hash 10909034931695436007 missed.| kit/Kit.cpp:365
Feb 9 11:29:06 lohengrin snap[12798]: kit-12869-12881 10:29:06.313637 [ lok_handler ] DBG PNG cache with hash 2170911108350901704 hit.| kit/Kit.cpp:347
Feb 9 11:29:06 lohengrin snap[12798]: kit-12869-12881 10:29:06.314130 [ lok_handler ] DBG PNG cache with hash 14394097024650258994 missed.| kit/Kit.cpp:365
Feb 9 11:29:06 lohengrin snap[12798]: kit-12869-12881 10:29:06.320337 [ lok_handler ] DBG PNG cache with hash 5107058110142708513 missed.| kit/Kit.cpp:365
Feb 9 11:29:06 lohengrin snap[12798]: kit-12869-12881 10:29:06.327945 [ lok_handler ] DBG PNG cache with hash 15795988371708573852 missed.| kit/Kit.cpp:365
Feb 9 11:29:06 lohengrin snap[12798]: kit-12869-12881 10:29:06.336448 [ lok_handler ] DBG PNG cache with hash 2170911108350901704 hit.| kit/Kit.cpp:347
Feb 9 11:29:06 lohengrin snap[12798]: wsd-12857-12874 10:29:06.336914 [ docbrk_ws_12869 ] DBG Handling tile combined: tilecombine: part=0 width=256 height=256 tileposx=0,3840,7680,11520,0,3840,7680,11520 tileposy=7680,7680,7680,7680,11520,11520,11520,11520 imgsize=2710,23372,15890,896,4399,19888,9919,896 tilewidth=3840 tileheight=3840 ver=21 renderid=12869/1| wsd/DocumentBroker.cpp:847
Feb 9 11:29:06 lohengrin snap[12798]: wsd-12857-12874 10:29:06.337115 [ docbrk_ws_12869 ] DBG Renaming /var/snap/loolwsd/common/cache/5/1/3/1c53ab8305e70bd41237d24b701fb708ae811/0_256x256.0,7680.3840x3840.png.temp to /var/snap/loolwsd/common/cache/5/1/3/1c53ab8305e70bd41237d24b701fb708ae811/0_256x256.0,7680.3840x3840.png OK.
Feb 9 11:29:06 lohengrin snap[12798]: wsd-12857-12874 10:29:06.337144 [ docbrk_ws_12869 ] DBG Sending tile message to 1 subscribers: tile: part=0 width=256 height=256 tileposx=0 tileposy=7680 tilewidth=3840 tileheight=3840 ver=21 imgsize=2710| wsd/TileCache.cpp:175
Feb 9 11:29:06 lohengrin snap[12798]: wsd-12857-12874 10:29:06.337218 [ docbrk_ws_12869 ] DBG STATISTICS: tile 21 internal roundtrip 168 ms.
Feb 9 11:29:06 lohengrin snap[12798]: wsd-12857-12874 10:29:06.337324 [ docbrk_ws_12869 ] DBG Renaming /var/snap/loolwsd/common/cache/5/1/3/1c53ab8305e70bd41237d24b701fb708ae811/0_256x256.3840,7680.3840x3840.png.temp to /var/snap/loolwsd/common/cache/5/1/3/1c53ab8305e70bd41237d24b701fb708ae811/0_256x256.3840,7680.3840x3840.png OK.
Feb 9 11:29:06 lohengrin snap[12798]: wsd-12857-12874 10:29:06.337346 [ docbrk_ws_12869 ] DBG Sending tile message to 1 subscribers: tile: part=0 width=256 height=256 tileposx=3840 tileposy=7680 tilewidth=3840 tileheight=3840 ver=21 imgsize=23372| wsd/TileCache.cpp:175
Feb 9 11:29:06 lohengrin snap[12798]: wsd-12857-12874 10:29:06.337504 [ docbrk_ws_12869 ] DBG STATISTICS: tile 21 internal roundtrip 169 ms.
Feb 9 11:29:06 lohengrin snap[12798]: wsd-12857-12874 10:29:06.337588 [ docbrk_ws_12869 ] DBG Renaming /var/snap/loolwsd/common/cache/5/1/3/1c53ab8305e70bd41237d24b701fb708ae811/0_256x256.7680,7680.3840x3840.png.temp to /var/snap/loolwsd/common/cache/5/1/3/1c53ab8305e70bd41237d24b701fb708ae811/0_256x256.7680,7680.3840x3840.png OK.
Feb 9 11:29:06 lohengrin snap[12798]: wsd-12857-12874 10:29:06.337608 [ docbrk_ws_12869 ] DBG Sending tile message to 1 subscribers: tile: part=0 width=256 height=256 tileposx=7680 tileposy=7680 tilewidth=3840 tileheight=3840 ver=21 imgsize=15890| wsd/TileCache.cpp:175
Feb 9 11:29:06 lohengrin snap[12798]: wsd-12857-12874 10:29:06.337715 [ docbrk_ws_12869 ] DBG STATISTICS: tile 21 internal roundtrip 169 ms.
Feb 9 11:29:06 lohengrin snap[12798]: wsd-12857-12874 10:29:06.337791 [ docbrk_ws_12869 ] DBG Renaming /var/snap/loolwsd/common/cache/5/1/3/1c53ab8305e70bd41237d24b701fb708ae811/0_256x256.11520,7680.3840x3840.png.temp to /var/snap/loolwsd/common/cache/5/1/3/1c53ab8305e70bd41237d24b701fb708ae811/0_256x256.11520,7680.3840x3840.png OK.
Feb 9 11:29:06 lohengrin snap[12798]: wsd-12857-12874 10:29:06.337811 [ docbrk_ws_12869 ] DBG Sending tile message to 1 subscribers: tile: part=0 width=256 height=256 tileposx=11520 tileposy=7680 tilewidth=3840 tileheight=3840 ver=21 imgsize=896| wsd/TileCache.cpp:175
Feb 9 11:29:06 lohengrin snap[12798]: wsd-12857-12874 10:29:06.337837 [ docbrk_ws_12869 ] DBG STATISTICS: tile 21 internal roundtrip 169 ms.
Feb 9 11:29:06 lohengrin snap[12798]: wsd-12857-12874 10:29:06.337909 [ docbrk_ws_12869 ] DBG Renaming /var/snap/loolwsd/common/cache/5/1/3/1c53ab8305e70bd41237d24b701fb708ae811/0_256x256.0,11520.3840x3840.png.temp to /var/snap/loolwsd/common/cache/5/1/3/1c53ab8305e70bd41237d24b701fb708ae811/0_256x256.0,11520.3840x3840.png OK.
Feb 9 11:29:06 lohengrin snap[12798]: wsd-12857-12874 10:29:06.337929 [ docbrk_ws_12869 ] DBG Sending tile message to 1 subscribers: tile: part=0 width=256 height=256 tileposx=0 tileposy=11520 tilewidth=3840 tileheight=3840 ver=21 imgsize=4399| wsd/TileCache.cpp:175
Feb 9 11:29:06 lohengrin snap[12798]: wsd-12857-12874 10:29:06.337973 [ docbrk_ws_12869 ] DBG STATISTICS: tile 21 internal roundtrip 169 ms.
Feb 9 11:29:06 lohengrin snap[12798]: wsd-12857-12874 10:29:06.338056 [ docbrk_ws_12869 ] DBG Renaming /var/snap/loolwsd/common/cache/5/1/3/1c53ab8305e70bd41237d24b701fb708ae811/0_256x256.3840,11520.3840x3840.png.temp to /var/snap/loolwsd/common/cache/5/1/3/1c53ab8305e70bd41237d24b701fb708ae811/0_256x256.3840,11520.3840x3840.png OK.
Feb 9 11:29:06 lohengrin snap[12798]: wsd-12857-12874 10:29:06.338075 [ docbrk_ws_12869 ] DBG Sending tile message to 1 subscribers: tile: part=0 width=256 height=256 tileposx=3840 tileposy=11520 tilewidth=3840 tileheight=3840 ver=21 imgsize=19888| wsd/TileCache.cpp:175
Feb 9 11:29:06 lohengrin snap[12798]: wsd-12857-12874 10:29:06.338212 [ docbrk_ws_12869 ] DBG STATISTICS: tile 21 internal roundtrip 169 ms.
Feb 9 11:29:06 lohengrin snap[12798]: wsd-12857-12874 10:29:06.338292 [ docbrk_ws_12869 ] DBG Renaming /var/snap/loolwsd/common/cache/5/1/3/1c53ab8305e70bd41237d24b701fb708ae811/0_256x256.7680,11520.3840x3840.png.temp to /var/snap/loolwsd/common/cache/5/1/3/1c53ab8305e70bd41237d24b701fb708ae811/0_256x256.7680,11520.3840x3840.png OK.
Feb 9 11:29:06 lohengrin snap[12798]: wsd-12857-12874 10:29:06.338312 [ docbrk_ws_12869 ] DBG Sending tile message to 1 subscribers: tile: part=0 width=256 height=256 tileposx=7680 tileposy=11520 tilewidth=3840 tileheight=3840 ver=21 imgsize=9919| wsd/TileCache.cpp:175
Feb 9 11:29:06 lohengrin snap[12798]: wsd-12857-12874 10:29:06.338386 [ docbrk_ws_12869 ] DBG STATISTICS: tile 21 internal roundtrip 169 ms.
Feb 9 11:29:06 lohengrin snap[12798]: wsd-12857-12874 10:29:06.338456 [ docbrk_ws_12869 ] DBG Renaming /var/snap/loolwsd/common/cache/5/1/3/1c53ab8305e70bd41237d24b701fb708ae811/0_256x256.11520,11520.3840x3840.png.temp to /var/snap/loolwsd/common/cache/5/1/3/1c53ab8305e70bd41237d24b701fb708ae811/0_256x256.11520,11520.3840x3840.png OK.
Feb 9 11:29:06 lohengrin snap[12798]: wsd-12857-12874 10:29:06.338476 [ docbrk_ws_12869 ] DBG Sending tile message to 1 subscribers: tile: part=0 width=256 height=256 tileposx=11520 tileposy=11520 tilewidth=3840 tileheight=3840 ver=21 imgsize=896| wsd/TileCache.cpp:175
Feb 9 11:29:06 lohengrin snap[12798]: wsd-12857-12874 10:29:06.338501 [ docbrk_ws_12869 ] DBG STATISTICS: tile 21 internal roundtrip 169 ms.
Feb 9 11:29:06 lohengrin snap[12798]: kit-12869-12881 10:29:06.345535 [ lok_handler ] DBG paintTile (combined) at (0, 15360), (15360, 3840) ver: 21 rendered in 8.538 ms (30.7032 MP/s).| kit/Kit.cpp:729
Feb 9 11:29:06 lohengrin snap[12798]: kit-12869-12881 10:29:06.346031 [ lok_handler ] DBG PNG cache with hash 10505744066348076843 missed.| kit/Kit.cpp:365
Feb 9 11:29:06 lohengrin snap[12798]: kit-12869-12881 10:29:06.352227 [ lok_handler ] DBG PNG cache with hash 16900252638374234549 missed.| kit/Kit.cpp:365
Feb 9 11:29:06 lohengrin snap[12798]: kit-12869-12881 10:29:06.360388 [ lok_handler ] DBG PNG cache with hash 6229477877247138204 missed.| kit/Kit.cpp:365
Feb 9 11:29:06 lohengrin snap[12798]: kit-12869-12881 10:29:06.369404 [ lok_handler ] DBG PNG cache with hash 15724308129877544220 missed.| kit/Kit.cpp:365
Feb 9 11:29:06 lohengrin snap[12798]: wsd-12857-12874 10:29:06.374597 [ docbrk_ws_12869 ] DBG Handling tile combined: tilecombine: part=0 width=256 height=256 tileposx=0,3840,7680,11520 tileposy=15360,15360,15360,15360 imgsize=7320,5331,4046,1800 tilewidth=3840 tileheight=3840 ver=21 renderid=12869/2| wsd/DocumentBroker.cpp:847
Feb 9 11:29:06 lohengrin snap[12798]: wsd-12857-12874 10:29:06.374797 [ docbrk_ws_12869 ] DBG Renaming /var/snap/loolwsd/common/cache/5/1/3/1c53ab8305e70bd41237d24b701fb708ae811/0_256x256.0,15360.3840x3840.png.temp to /var/snap/loolwsd/common/cache/5/1/3/1c53ab8305e70bd41237d24b701fb708ae811/0_256x256.0,15360.3840x3840.png OK.
Feb 9 11:29:06 lohengrin snap[12798]: wsd-12857-12874 10:29:06.374822 [ docbrk_ws_12869 ] DBG Sending tile message to 1 subscribers: tile: part=0 width=256 height=256 tileposx=0 tileposy=15360 tilewidth=3840 tileheight=3840 ver=21 imgsize=7320| wsd/TileCache.cpp:175
Feb 9 11:29:06 lohengrin snap[12798]: wsd-12857-12874 10:29:06.374886 [ docbrk_ws_12869 ] DBG STATISTICS: tile 21 internal roundtrip 206 ms.
Feb 9 11:29:06 lohengrin snap[12798]: wsd-12857-12874 10:29:06.374967 [ docbrk_ws_12869 ] DBG Renaming /var/snap/loolwsd/common/cache/5/1/3/1c53ab8305e70bd41237d24b701fb708ae811/0_256x256.3840,15360.3840x3840.png.temp to /var/snap/loolwsd/common/cache/5/1/3/1c53ab8305e70bd41237d24b701fb708ae811/0_256x256.3840,15360.3840x3840.png OK.
Feb 9 11:29:06 lohengrin snap[12798]: wsd-12857-12874 10:29:06.374989 [ docbrk_ws_12869 ] DBG Sending tile message to 1 subscribers: tile: part=0 width=256 height=256 tileposx=3840 tileposy=15360 tilewidth=3840 tileheight=3840 ver=21 imgsize=5331| wsd/TileCache.cpp:175
Feb 9 11:29:06 lohengrin snap[12798]: wsd-12857-12874 10:29:06.375037 [ docbrk_ws_12869 ] DBG STATISTICS: tile 21 internal roundtrip 206 ms.
Feb 9 11:29:06 lohengrin snap[12798]: wsd-12857-12874 10:29:06.375110 [ docbrk_ws_12869 ] DBG Renaming /var/snap/loolwsd/common/cache/5/1/3/1c53ab8305e70bd41237d24b701fb708ae811/0_256x256.7680,15360.3840x3840.png.temp to /var/snap/loolwsd/common/cache/5/1/3/1c53ab8305e70bd41237d24b701fb708ae811/0_256x256.7680,15360.3840x3840.png OK.
Feb 9 11:29:06 lohengrin snap[12798]: wsd-12857-12874 10:29:06.375131 [ docbrk_ws_12869 ] DBG Sending tile message to 1 subscribers: tile: part=0 width=256 height=256 tileposx=7680 tileposy=15360 tilewidth=3840 tileheight=3840 ver=21 imgsize=4046| wsd/TileCache.cpp:175
Feb 9 11:29:06 lohengrin snap[12798]: wsd-12857-12874 10:29:06.375173 [ docbrk_ws_12869 ] DBG STATISTICS: tile 21 internal roundtrip 206 ms.
Feb 9 11:29:06 lohengrin snap[12798]: wsd-12857-12874 10:29:06.375245 [ docbrk_ws_12869 ] DBG Renaming /var/snap/loolwsd/common/cache/5/1/3/1c53ab8305e70bd41237d24b701fb708ae811/0_256x256.11520,15360.3840x3840.png.temp to /var/snap/loolwsd/common/cache/5/1/3/1c53ab8305e70bd41237d24b701fb708ae811/0_256x256.11520,15360.3840x3840.png OK.
Feb 9 11:29:06 lohengrin snap[12798]: wsd-12857-12874 10:29:06.375265 [ docbrk_ws_12869 ] DBG Sending tile message to 1 subscribers: tile: part=0 width=256 height=256 tileposx=11520 tileposy=15360 tilewidth=3840 tileheight=3840 ver=21 imgsize=1800| wsd/TileCache.cpp:175
Feb 9 11:29:06 lohengrin snap[12798]: wsd-12857-12874 10:29:06.375297 [ docbrk_ws_12869 ] DBG STATISTICS: tile 21 internal roundtrip 206 ms.
Feb 9 11:29:08 lohengrin snap[12798]: kit-12869-12869 10:29:08.818935 [ loolkit ] DBG child_ws_12869: recv [child-0001 clientzoom tilepixelwidth=256 tilepixelheight=256 tiletwipwidth=3840 tiletwipheight=3840].| kit/Kit.cpp:1663
Feb 9 11:29:08 lohengrin snap[12798]: kit-12869-12869 10:29:08.819017 [ loolkit ] DBG child_ws_12869: recv [child-0001 mouse type=move x=60 y=7410 count=1 buttons=0 modifier=0].| kit/Kit.cpp:1663
Feb 9 11:29:13 lohengrin snap[12798]: wsd-12857-12878 10:29:13.149211 [ client_ws_0001 ] DBG Subscribing to tile (0,0,19200) which has no subscribers. Subscribing for ver: 22.
Feb 9 11:29:13 lohengrin snap[12798]: wsd-12857-12878 10:29:13.149331 [ client_ws_0001 ] DBG Subscribing to tile (0,3840,19200) which has no subscribers. Subscribing for ver: 23.
Feb 9 11:29:13 lohengrin snap[12798]: wsd-12857-12878 10:29:13.149404 [ client_ws_0001 ] DBG Subscribing to tile (0,7680,19200) which has no subscribers. Subscribing for ver: 24.
Feb 9 11:29:13 lohengrin snap[12798]: wsd-12857-12878 10:29:13.149472 [ client_ws_0001 ] DBG Subscribing to tile (0,11520,19200) which has no subscribers. Subscribing for ver: 25.
Feb 9 11:29:13 lohengrin snap[12798]: wsd-12857-12878 10:29:13.149537 [ client_ws_0001 ] DBG Subscribing to tile (0,0,23040) which has no subscribers. Subscribing for ver: 26.
Feb 9 11:29:13 lohengrin snap[12798]: wsd-12857-12878 10:29:13.149606 [ client_ws_0001 ] DBG Sending residual tilecombine: tilecombine part=0 width=256 height=256 tileposx=0,3840,7680,11520,0 tileposy=19200,19200,19200,19200,23040 imgsize=0,0,0,0,0 tilewidth=3840 tileheight=3840 ver=27| wsd/DocumentBroker.cpp:800
Feb 9 11:29:13 lohengrin snap[12798]: kit-12869-12869 10:29:13.149796 [ loolkit ] DBG child_ws_12869: recv [tilecombine part=0 width=256 height=256 tileposx=0,3840,7680,11520,0 tileposy=19200,19200,19200,19200,23040 imgsize=0,0,...].| kit/Kit.cpp:1663
Feb 9 11:29:13 lohengrin snap[12798]: kit-12869-12881 10:29:13.185756 [ lok_handler ] DBG paintTile (combined) at (0, 19200), (15360, 7680) ver: 27 rendered in 34.6 ms (15.1528 MP/s).| kit/Kit.cpp:729
Feb 9 11:29:13 lohengrin snap[12798]: kit-12869-12881 10:29:13.186210 [ lok_handler ] DBG PNG cache with hash 11119669237860120804 missed.| kit/Kit.cpp:365
Feb 9 11:29:13 lohengrin snap[12798]: kit-12869-12881 10:29:13.191555 [ lok_handler ] DBG PNG cache with hash 11094071415730301235 missed.| kit/Kit.cpp:365
Feb 9 11:29:13 lohengrin snap[12798]: kit-12869-12881 10:29:13.197106 [ lok_handler ] DBG PNG cache with hash 1102989368401265608 missed.| kit/Kit.cpp:365
Feb 9 11:29:13 lohengrin snap[12798]: kit-12869-12881 10:29:13.201989 [ lok_handler ] DBG PNG cache with hash 2170911108350901704 hit.| kit/Kit.cpp:347
Feb 9 11:29:13 lohengrin snap[12798]: kit-12869-12881 10:29:13.202353 [ lok_handler ] DBG PNG cache with hash 10686991279997590558 missed.| kit/Kit.cpp:365
Feb 9 11:29:13 lohengrin snap[12798]: wsd-12857-12874 10:29:13.207534 [ docbrk_ws_12869 ] DBG Handling tile combined: tilecombine: part=0 width=256 height=256 tileposx=0,3840,7680,11520,0 tileposy=19200,19200,19200,19200,23040 imgsize=6457,14152,4840,896,7747 tilewidth=3840 tileheight=3840 ver=27 renderid=12869/3| wsd/DocumentBroker.cpp:847
Feb 9 11:29:13 lohengrin snap[12798]: wsd-12857-12874 10:29:13.207713 [ docbrk_ws_12869 ] DBG Renaming /var/snap/loolwsd/common/cache/5/1/3/1c53ab8305e70bd41237d24b701fb708ae811/0_256x256.0,19200.3840x3840.png.temp to /var/snap/loolwsd/common/cache/5/1/3/1c53ab8305e70bd41237d24b701fb708ae811/0_256x256.0,19200.3840x3840.png OK.
Feb 9 11:29:13 lohengrin snap[12798]: wsd-12857-12874 10:29:13.207736 [ docbrk_ws_12869 ] DBG Sending tile message to 1 subscribers: tile: part=0 width=256 height=256 tileposx=0 tileposy=19200 tilewidth=3840 tileheight=3840 ver=27 imgsize=6457| wsd/TileCache.cpp:175
Feb 9 11:29:13 lohengrin snap[12798]: wsd-12857-12874 10:29:13.207803 [ docbrk_ws_12869 ] DBG STATISTICS: tile 27 internal roundtrip 58 ms.
Feb 9 11:29:13 lohengrin snap[12798]: wsd-12857-12874 10:29:13.207894 [ docbrk_ws_12869 ] DBG Renaming /var/snap/loolwsd/common/cache/5/1/3/1c53ab8305e70bd41237d24b701fb708ae811/0_256x256.3840,19200.3840x3840.png.temp to /var/snap/loolwsd/common/cache/5/1/3/1c53ab8305e70bd41237d24b701fb708ae811/0_256x256.3840,19200.3840x3840.png OK.
Feb 9 11:29:13 lohengrin snap[12798]: wsd-12857-12874 10:29:13.207915 [ docbrk_ws_12869 ] DBG Sending tile message to 1 subscribers: tile: part=0 width=256 height=256 tileposx=3840 tileposy=19200 tilewidth=3840 tileheight=3840 ver=27 imgsize=14152| wsd/TileCache.cpp:175
Feb 9 11:29:13 lohengrin snap[12798]: wsd-12857-12874 10:29:13.208015 [ docbrk_ws_12869 ] DBG STATISTICS: tile 27 internal roundtrip 58 ms.
Feb 9 11:29:13 lohengrin snap[12798]: wsd-12857-12874 10:29:13.208093 [ docbrk_ws_12869 ] DBG Renaming /var/snap/loolwsd/common/cache/5/1/3/1c53ab8305e70bd41237d24b701fb708ae811/0_256x256.7680,19200.3840x3840.png.temp to /var/snap/loolwsd/common/cache/5/1/3/1c53ab8305e70bd41237d24b701fb708ae811/0_256x256.7680,19200.3840x3840.png OK.
Feb 9 11:29:13 lohengrin snap[12798]: wsd-12857-12874 10:29:13.208113 [ docbrk_ws_12869 ] DBG Sending tile message to 1 subscribers: tile: part=0 width=256 height=256 tileposx=7680 tileposy=19200 tilewidth=3840 tileheight=3840 ver=27 imgsize=4840| wsd/TileCache.cpp:175
Feb 9 11:29:13 lohengrin snap[12798]: wsd-12857-12874 10:29:13.208158 [ docbrk_ws_12869 ] DBG STATISTICS: tile 27 internal roundtrip 58 ms.
Feb 9 11:29:13 lohengrin snap[12798]: wsd-12857-12874 10:29:13.208230 [ docbrk_ws_12869 ] DBG Renaming /var/snap/loolwsd/common/cache/5/1/3/1c53ab8305e70bd41237d24b701fb708ae811/0_256x256.11520,19200.3840x3840.png.temp to /var/snap/loolwsd/common/cache/5/1/3/1c53ab8305e70bd41237d24b701fb708ae811/0_256x256.11520,19200.3840x3840.png OK.
Feb 9 11:29:13 lohengrin snap[12798]: wsd-12857-12874 10:29:13.208250 [ docbrk_ws_12869 ] DBG Sending tile message to 1 subscribers: tile: part=0 width=256 height=256 tileposx=11520 tileposy=19200 tilewidth=3840 tileheight=3840 ver=27 imgsize=896| wsd/TileCache.cpp:175
Feb 9 11:29:13 lohengrin snap[12798]: wsd-12857-12874 10:29:13.208275 [ docbrk_ws_12869 ] DBG STATISTICS: tile 27 internal roundtrip 58 ms.
Feb 9 11:29:13 lohengrin snap[12798]: wsd-12857-12874 10:29:13.208347 [ docbrk_ws_12869 ] DBG Renaming /var/snap/loolwsd/common/cache/5/1/3/1c53ab8305e70bd41237d24b701fb708ae811/0_256x256.0,23040.3840x3840.png.temp to /var/snap/loolwsd/common/cache/5/1/3/1c53ab8305e70bd41237d24b701fb708ae811/0_256x256.0,23040.3840x3840.png OK.
Feb 9 11:29:13 lohengrin snap[12798]: wsd-12857-12874 10:29:13.208366 [ docbrk_ws_12869 ] DBG Sending tile message to 1 subscribers: tile: part=0 width=256 height=256 tileposx=0 tileposy=23040 tilewidth=3840 tileheight=3840 ver=27 imgsize=7747| wsd/TileCache.cpp:175
Feb 9 11:29:13 lohengrin snap[12798]: wsd-12857-12874 10:29:13.208428 [ docbrk_ws_12869 ] DBG STATISTICS: tile 27 internal roundtrip 58 ms.
Feb 9 11:29:13 lohengrin snap[12798]: wsd-12857-12878 10:29:13.896214 [ client_ws_0001 ] DBG Subscribing to tile (0,3840,23040) which has no subscribers. Subscribing for ver: 28.
Feb 9 11:29:13 lohengrin snap[12798]: wsd-12857-12878 10:29:13.896269 [ client_ws_0001 ] DBG Subscribing to tile (0,7680,23040) which has no subscribers. Subscribing for ver: 29.
Feb 9 11:29:13 lohengrin snap[12798]: wsd-12857-12878 10:29:13.896307 [ client_ws_0001 ] DBG Subscribing to tile (0,11520,23040) which has no subscribers. Subscribing for ver: 30.
Feb 9 11:29:13 lohengrin snap[12798]: wsd-12857-12878 10:29:13.896342 [ client_ws_0001 ] DBG Subscribing to tile (0,0,26880) which has no subscribers. Subscribing for ver: 31.
Feb 9 11:29:13 lohengrin snap[12798]: wsd-12857-12878 10:29:13.896377 [ client_ws_0001 ] DBG Subscribing to tile (0,3840,26880) which has no subscribers. Subscribing for ver: 32.
Feb 9 11:29:13 lohengrin snap[12798]: wsd-12857-12878 10:29:13.896417 [ client_ws_0001 ] DBG Sending residual tilecombine: tilecombine part=0 width=256 height=256 tileposx=3840,7680,11520,0,3840 tileposy=23040,23040,23040,26880,26880 imgsize=0,0,0,0,0 tilewidth=3840 tileheight=3840 ver=33| wsd/DocumentBroker.cpp:800
Feb 9 11:29:13 lohengrin snap[12798]: kit-12869-12869 10:29:13.896529 [ loolkit ] DBG child_ws_12869: recv [tilecombine part=0 width=256 height=256 tileposx=3840,7680,11520,0,3840 tileposy=23040,23040,23040,26880,26880 imgsize=0...].| kit/Kit.cpp:1663
Feb 9 11:29:13 lohengrin snap[12798]: kit-12869-12881 10:29:13.917192 [ lok_handler ] DBG paintTile (combined) at (0, 23040), (15360, 7680) ver: 33 rendered in 19.566 ms (26.7959 MP/s).| kit/Kit.cpp:729
Feb 9 11:29:13 lohengrin snap[12798]: kit-12869-12881 10:29:13.917996 [ lok_handler ] DBG PNG cache with hash 17569713208664305702 missed.| kit/Kit.cpp:365
Feb 9 11:29:13 lohengrin snap[12798]: kit-12869-12881 10:29:13.925859 [ lok_handler ] DBG PNG cache with hash 129086385362671159 missed.| kit/Kit.cpp:365
Feb 9 11:29:13 lohengrin snap[12798]: kit-12869-12881 10:29:13.933154 [ lok_handler ] DBG PNG cache with hash 2170911108350901704 hit.| kit/Kit.cpp:347
Feb 9 11:29:13 lohengrin snap[12798]: kit-12869-12881 10:29:13.933603 [ lok_handler ] DBG PNG cache with hash 3152672712916257042 missed.| kit/Kit.cpp:365
Feb 9 11:29:13 lohengrin snap[12798]: kit-12869-12881 10:29:13.938903 [ lok_handler ] DBG PNG cache with hash 12228791994959456585 missed.| kit/Kit.cpp:365
Feb 9 11:29:13 lohengrin snap[12798]: wsd-12857-12874 10:29:13.945204 [ docbrk_ws_12869 ] DBG Handling tile combined: tilecombine: part=0 width=256 height=256 tileposx=3840,7680,11520,0,3840 tileposy=23040,23040,23040,26880,26880 imgsize=15413,10781,896,911,17437 tilewidth=3840 tileheight=3840 ver=33 renderid=12869/4| wsd/DocumentBroker.cpp:847
Feb 9 11:29:13 lohengrin snap[12798]: wsd-12857-12874 10:29:13.945366 [ docbrk_ws_12869 ] DBG Renaming /var/snap/loolwsd/common/cache/5/1/3/1c53ab8305e70bd41237d24b701fb708ae811/0_256x256.3840,23040.3840x3840.png.temp to /var/snap/loolwsd/common/cache/5/1/3/1c53ab8305e70bd41237d24b701fb708ae811/0_256x256.3840,23040.3840x3840.png OK.
Feb 9 11:29:13 lohengrin snap[12798]: wsd-12857-12874 10:29:13.945383 [ docbrk_ws_12869 ] DBG Sending tile message to 1 subscribers: tile: part=0 width=256 height=256 tileposx=3840 tileposy=23040 tilewidth=3840 tileheight=3840 ver=33 imgsize=15413| wsd/TileCache.cpp:175
Feb 9 11:29:13 lohengrin snap[12798]: wsd-12857-12874 10:29:13.945458 [ docbrk_ws_12869 ] DBG STATISTICS: tile 33 internal roundtrip 49 ms.
Feb 9 11:29:13 lohengrin snap[12798]: wsd-12857-12874 10:29:13.945539 [ docbrk_ws_12869 ] DBG Renaming /var/snap/loolwsd/common/cache/5/1/3/1c53ab8305e70bd41237d24b701fb708ae811/0_256x256.7680,23040.3840x3840.png.temp to /var/snap/loolwsd/common/cache/5/1/3/1c53ab8305e70bd41237d24b701fb708ae811/0_256x256.7680,23040.3840x3840.png OK.
Feb 9 11:29:13 lohengrin snap[12798]: wsd-12857-12874 10:29:13.945554 [ docbrk_ws_12869 ] DBG Sending tile message to 1 subscribers: tile: part=0 width=256 height=256 tileposx=7680 tileposy=23040 tilewidth=3840 tileheight=3840 ver=33 imgsize=10781| wsd/TileCache.cpp:175
Feb 9 11:29:13 lohengrin snap[12798]: wsd-12857-12874 10:29:13.945607 [ docbrk_ws_12869 ] DBG STATISTICS: tile 33 internal roundtrip 49 ms.
Feb 9 11:29:13 lohengrin snap[12798]: wsd-12857-12874 10:29:13.945773 [ docbrk_ws_12869 ] DBG Renaming /var/snap/loolwsd/common/cache/5/1/3/1c53ab8305e70bd41237d24b701fb708ae811/0_256x256.11520,23040.3840x3840.png.temp to /var/snap/loolwsd/common/cache/5/1/3/1c53ab8305e70bd41237d24b701fb708ae811/0_256x256.11520,23040.3840x3840.png OK.
Feb 9 11:29:13 lohengrin snap[12798]: wsd-12857-12874 10:29:13.945789 [ docbrk_ws_12869 ] DBG Sending tile message to 1 subscribers: tile: part=0 width=256 height=256 tileposx=11520 tileposy=23040 tilewidth=3840 tileheight=3840 ver=33 imgsize=896| wsd/TileCache.cpp:175
Feb 9 11:29:13 lohengrin snap[12798]: wsd-12857-12874 10:29:13.945809 [ docbrk_ws_12869 ] DBG STATISTICS: tile 33 internal roundtrip 49 ms.
Feb 9 11:29:13 lohengrin snap[12798]: wsd-12857-12874 10:29:13.945869 [ docbrk_ws_12869 ] DBG Renaming /var/snap/loolwsd/common/cache/5/1/3/1c53ab8305e70bd41237d24b701fb708ae811/0_256x256.0,26880.3840x3840.png.temp to /var/snap/loolwsd/common/cache/5/1/3/1c53ab8305e70bd41237d24b701fb708ae811/0_256x256.0,26880.3840x3840.png OK.
Feb 9 11:29:13 lohengrin snap[12798]: wsd-12857-12874 10:29:13.945882 [ docbrk_ws_12869 ] DBG Sending tile message to 1 subscribers: tile: part=0 width=256 height=256 tileposx=0 tileposy=26880 tilewidth=3840 tileheight=3840 ver=33 imgsize=911| wsd/TileCache.cpp:175
Feb 9 11:29:13 lohengrin snap[12798]: wsd-12857-12874 10:29:13.945901 [ docbrk_ws_12869 ] DBG STATISTICS: tile 33 internal roundtrip 49 ms.
Feb 9 11:29:13 lohengrin snap[12798]: wsd-12857-12874 10:29:13.945967 [ docbrk_ws_12869 ] DBG Renaming /var/snap/loolwsd/common/cache/5/1/3/1c53ab8305e70bd41237d24b701fb708ae811/0_256x256.3840,26880.3840x3840.png.temp to /var/snap/loolwsd/common/cache/5/1/3/1c53ab8305e70bd41237d24b701fb708ae811/0_256x256.3840,26880.3840x3840.png OK.
Feb 9 11:29:13 lohengrin snap[12798]: wsd-12857-12874 10:29:13.945981 [ docbrk_ws_12869 ] DBG Sending tile message to 1 subscribers: tile: part=0 width=256 height=256 tileposx=3840 tileposy=26880 tilewidth=3840 tileheight=3840 ver=33 imgsize=17437| wsd/TileCache.cpp:175
Feb 9 11:29:13 lohengrin snap[12798]: wsd-12857-12874 10:29:13.946056 [ docbrk_ws_12869 ] DBG STATISTICS: tile 33 internal roundtrip 49 ms.
Feb 9 11:29:14 lohengrin snap[12798]: wsd-12857-12878 10:29:14.643472 [ client_ws_0001 ] DBG Subscribing to tile (0,7680,26880) which has no subscribers. Subscribing for ver: 34.
Feb 9 11:29:14 lohengrin snap[12798]: wsd-12857-12878 10:29:14.643525 [ client_ws_0001 ] DBG Subscribing to tile (0,11520,26880) which has no subscribers. Subscribing for ver: 35.
Feb 9 11:29:14 lohengrin snap[12798]: wsd-12857-12878 10:29:14.643560 [ client_ws_0001 ] DBG Subscribing to tile (0,0,30720) which has no subscribers. Subscribing for ver: 36.
Feb 9 11:29:14 lohengrin snap[12798]: wsd-12857-12878 10:29:14.643595 [ client_ws_0001 ] DBG Subscribing to tile (0,3840,30720) which has no subscribers. Subscribing for ver: 37.
Feb 9 11:29:14 lohengrin snap[12798]: wsd-12857-12878 10:29:14.643637 [ client_ws_0001 ] DBG Subscribing to tile (0,7680,30720) which has no subscribers. Subscribing for ver: 38.
Feb 9 11:29:14 lohengrin snap[12798]: wsd-12857-12878 10:29:14.643675 [ client_ws_0001 ] DBG Sending residual tilecombine: tilecombine part=0 width=256 height=256 tileposx=7680,11520,0,3840,7680 tileposy=26880,26880,30720,30720,30720 imgsize=0,0,0,0,0 tilewidth=3840 tileheight=3840 ver=39| wsd/DocumentBroker.cpp:800
Feb 9 11:29:14 lohengrin snap[12798]: kit-12869-12869 10:29:14.643776 [ loolkit ] DBG child_ws_12869: recv [tilecombine part=0 width=256 height=256 tileposx=7680,11520,0,3840,7680 tileposy=26880,26880,30720,30720,30720 imgsize=0...].| kit/Kit.cpp:1663
Feb 9 11:29:14 lohengrin snap[12798]: kit-12869-12881 10:29:14.649898 [ lok_handler ] DBG paintTile (combined) at (0, 26880), (15360, 7680) ver: 39 rendered in 5.706 ms (91.8836 MP/s).| kit/Kit.cpp:729
Feb 9 11:29:14 lohengrin snap[12798]: kit-12869-12881 10:29:14.650274 [ lok_handler ] DBG PNG cache with hash 15658114225871157833 missed.| kit/Kit.cpp:365
Feb 9 11:29:14 lohengrin snap[12798]: kit-12869-12881 10:29:14.656003 [ lok_handler ] DBG PNG cache with hash 2170911108350901704 hit.| kit/Kit.cpp:347
Feb 9 11:29:14 lohengrin snap[12798]: kit-12869-12881 10:29:14.656602 [ lok_handler ] DBG PNG cache with hash 13039921347291818375 missed.| kit/Kit.cpp:365
Feb 9 11:29:14 lohengrin snap[12798]: kit-12869-12881 10:29:14.663555 [ lok_handler ] DBG PNG cache with hash 12534685607669096278 missed.| kit/Kit.cpp:365
Feb 9 11:29:14 lohengrin snap[12798]: kit-12869-12881 10:29:14.668295 [ lok_handler ] DBG cache 29 items total size 267925 current hits 34, total hit rate 14.7059% at balance start| kit/Kit.cpp:310
Feb 9 11:29:14 lohengrin snap[12798]: kit-12869-12881 10:29:14.668332 [ lok_handler ] DBG cache 28 items total size 257144 after balance| kit/Kit.cpp:332
Feb 9 11:29:14 lohengrin snap[12798]: kit-12869-12881 10:29:14.668624 [ lok_handler ] DBG PNG cache with hash 16123289684237073737 missed.| kit/Kit.cpp:365
Feb 9 11:29:14 lohengrin snap[12798]: wsd-12857-12874 10:29:14.673857 [ docbrk_ws_12869 ] DBG Handling tile combined: tilecombine: part=0 width=256 height=256 tileposx=7680,11520,0,3840,7680 tileposy=26880,26880,30720,30720,30720 imgsize=3477,896,1838,6659,2111 tilewidth=3840 tileheight=3840 ver=39 renderid=12869/5| wsd/DocumentBroker.cpp:847
Feb 9 11:29:14 lohengrin snap[12798]: wsd-12857-12874 10:29:14.674072 [ docbrk_ws_12869 ] DBG Renaming /var/snap/loolwsd/common/cache/5/1/3/1c53ab8305e70bd41237d24b701fb708ae811/0_256x256.7680,26880.3840x3840.png.temp to /var/snap/loolwsd/common/cache/5/1/3/1c53ab8305e70bd41237d24b701fb708ae811/0_256x256.7680,26880.3840x3840.png OK.
Feb 9 11:29:14 lohengrin snap[12798]: wsd-12857-12874 10:29:14.674095 [ docbrk_ws_12869 ] DBG Sending tile message to 1 subscribers: tile: part=0 width=256 height=256 tileposx=7680 tileposy=26880 tilewidth=3840 tileheight=3840 ver=39 imgsize=3477| wsd/TileCache.cpp:175
Feb 9 11:29:14 lohengrin snap[12798]: wsd-12857-12874 10:29:14.674150 [ docbrk_ws_12869 ] DBG STATISTICS: tile 39 internal roundtrip 30 ms.
Feb 9 11:29:14 lohengrin snap[12798]: wsd-12857-12874 10:29:14.674229 [ docbrk_ws_12869 ] DBG Renaming /var/snap/loolwsd/common/cache/5/1/3/1c53ab8305e70bd41237d24b701fb708ae811/0_256x256.11520,26880.3840x3840.png.temp to /var/snap/loolwsd/common/cache/5/1/3/1c53ab8305e70bd41237d24b701fb708ae811/0_256x256.11520,26880.3840x3840.png OK.
Feb 9 11:29:14 lohengrin snap[12798]: wsd-12857-12874 10:29:14.674248 [ docbrk_ws_12869 ] DBG Sending tile message to 1 subscribers: tile: part=0 width=256 height=256 tileposx=11520 tileposy=26880 tilewidth=3840 tileheight=3840 ver=39 imgsize=896| wsd/TileCache.cpp:175
Feb 9 11:29:14 lohengrin snap[12798]: wsd-12857-12874 10:29:14.674278 [ docbrk_ws_12869 ] DBG STATISTICS: tile 39 internal roundtrip 30 ms.
Feb 9 11:29:14 lohengrin snap[12798]: wsd-12857-12874 10:29:14.674346 [ docbrk_ws_12869 ] DBG Renaming /var/snap/loolwsd/common/cache/5/1/3/1c53ab8305e70bd41237d24b701fb708ae811/0_256x256.0,30720.3840x3840.png.temp to /var/snap/loolwsd/common/cache/5/1/3/1c53ab8305e70bd41237d24b701fb708ae811/0_256x256.0,30720.3840x3840.png OK.
Feb 9 11:29:14 lohengrin snap[12798]: wsd-12857-12874 10:29:14.674366 [ docbrk_ws_12869 ] DBG Sending tile message to 1 subscribers: tile: part=0 width=256 height=256 tileposx=0 tileposy=30720 tilewidth=3840 tileheight=3840 ver=39 imgsize=1838| wsd/TileCache.cpp:175
Feb 9 11:29:14 lohengrin snap[12798]: wsd-12857-12874 10:29:14.674401 [ docbrk_ws_12869 ] DBG STATISTICS: tile 39 internal roundtrip 30 ms.
Feb 9 11:29:14 lohengrin snap[12798]: wsd-12857-12874 10:29:14.674473 [ docbrk_ws_12869 ] DBG Renaming /var/snap/loolwsd/common/cache/5/1/3/1c53ab8305e70bd41237d24b701fb708ae811/0_256x256.3840,30720.3840x3840.png.temp to /var/snap/loolwsd/common/cache/5/1/3/1c53ab8305e70bd41237d24b701fb708ae811/0_256x256.3840,30720.3840x3840.png OK.
Feb 9 11:29:14 lohengrin snap[12798]: wsd-12857-12874 10:29:14.674492 [ docbrk_ws_12869 ] DBG Sending tile message to 1 subscribers: tile: part=0 width=256 height=256 tileposx=3840 tileposy=30720 tilewidth=3840 tileheight=3840 ver=39 imgsize=6659| wsd/TileCache.cpp:175
Feb 9 11:29:14 lohengrin snap[12798]: wsd-12857-12874 10:29:14.674561 [ docbrk_ws_12869 ] DBG STATISTICS: tile 39 internal roundtrip 30 ms.
Feb 9 11:29:14 lohengrin snap[12798]: wsd-12857-12874 10:29:14.674647 [ docbrk_ws_12869 ] DBG Renaming /var/snap/loolwsd/common/cache/5/1/3/1c53ab8305e70bd41237d24b701fb708ae811/0_256x256.7680,30720.3840x3840.png.temp to /var/snap/loolwsd/common/cache/5/1/3/1c53ab8305e70bd41237d24b701fb708ae811/0_256x256.7680,30720.3840x3840.png OK.
Feb 9 11:29:14 lohengrin snap[12798]: wsd-12857-12874 10:29:14.674666 [ docbrk_ws_12869 ] DBG Sending tile message to 1 subscribers: tile: part=0 width=256 height=256 tileposx=7680 tileposy=30720 tilewidth=3840 tileheight=3840 ver=39 imgsize=2111| wsd/TileCache.cpp:175
Feb 9 11:29:14 lohengrin snap[12798]: wsd-12857-12874 10:29:14.674699 [ docbrk_ws_12869 ] DBG STATISTICS: tile 39 internal roundtrip 31 ms.
Feb 9 11:29:15 lohengrin snap[12798]: wsd-12857-12878 10:29:15.391557 [ client_ws_0001 ] DBG Subscribing to tile (0,11520,30720) which has no subscribers. Subscribing for ver: 40.
Feb 9 11:29:15 lohengrin snap[12798]: wsd-12857-12878 10:29:15.391610 [ client_ws_0001 ] DBG Sending render request for tile (0,11520,30720).| wsd/DocumentBroker.cpp:740
Feb 9 11:29:15 lohengrin snap[12798]: kit-12869-12869 10:29:15.391744 [ loolkit ] DBG child_ws_12869: recv [tile part=0 width=256 height=256 tileposx=11520 tileposy=30720 tilewidth=3840 tileheight=3840 ver=40].| kit/Kit.cpp:1663
Feb 9 11:29:15 lohengrin snap[12798]: kit-12869-12881 10:29:15.393131 [ lok_handler ] DBG PNG cache with hash 14922582126874601880 missed.| kit/Kit.cpp:365
Feb 9 11:29:15 lohengrin snap[12798]: wsd-12857-12874 10:29:15.397666 [ docbrk_ws_12869 ] DBG Handling tile: tile: part=0 width=256 height=256 tileposx=11520 tileposy=30720 tilewidth=3840 tileheight=3840 ver=40 renderid=12869/6| wsd/DocumentBroker.cpp:820
Feb 9 11:29:15 lohengrin snap[12798]: wsd-12857-12874 10:29:15.397803 [ docbrk_ws_12869 ] DBG Renaming /var/snap/loolwsd/common/cache/5/1/3/1c53ab8305e70bd41237d24b701fb708ae811/0_256x256.11520,30720.3840x3840.png.temp to /var/snap/loolwsd/common/cache/5/1/3/1c53ab8305e70bd41237d24b701fb708ae811/0_256x256.11520,30720.3840x3840.png OK.
Feb 9 11:29:15 lohengrin snap[12798]: wsd-12857-12874 10:29:15.397820 [ docbrk_ws_12869 ] DBG Sending tile message to 1 subscribers: tile: part=0 width=256 height=256 tileposx=11520 tileposy=30720 tilewidth=3840 tileheight=3840 ver=40| wsd/TileCache.cpp:175
Feb 9 11:29:15 lohengrin snap[12798]: wsd-12857-12874 10:29:15.397850 [ docbrk_ws_12869 ] DBG STATISTICS: tile 40 internal roundtrip 6 ms.
More information about the Snapcraft
mailing list