Mageia forum

by **banjo** » Jan 12th, '17, 19:16

I can no longer print a pdf file from Okular or qpdfview. I can open the file with either of those tools, but when I select File=>Print the programs hang.

Also, if I attempt to print a .txt file from Kwrite, it hangs in the same way. I have to kill the programs manually to make them stop.

I have tried logging out and back in again and rebooting the computer to no avail.

I tried printing a different .pdf file. Same result.

I finally read the pdf file into LibreOffice and was able to print it.

The symptoms imply that Kwrite, Okular and qpdfview are attempting to post the Printer Dialog, which never gets displayed. Hence the tools are waiting on a return from the modal dialog that I cannot provide because the dialog is not there. When I print from other tools, the dialog appears normally.

So here is what I know:

If anyone can help me figure out how this system hangs together or what might have gone awry I would appreciate it. I don't know where to start trouble shooting this.

I am running Mageia 5 with all updates up to date.

Thanks,
Banjo
(_)=='=~

by **doktor5000** » Jan 12th, '17, 20:53

How long did it hang, or how long did you wait until you stopped the processes ? Less then a minute, a minute or much longer?
Because there have been similar issues like e.g. https://bugs.mageia.org/show_bug.cgi?id=16330
You could also try if as root

Code: Select all: systemctl restart cups

helps with your issue, too.

Also, what printer do you use and how is it connected? Via USB, wireless ... ?

by **banjo** » Jan 12th, '17, 21:54

The printer is HPLaserJet1102W. It is connected through WiFi to a fixed IP address.

BTW I am running the 64 bit version of Mageia 5.

I ran systemctl restart cups as root and then tried printing again with the same outcome. Okular froze.

I have seen https://bugs.mageia.org/show_bug.cgi?id=16330 in the context of having Okular freeze up trying to do a second print job and also when I had multiple copies of Okular open simultaneously. Until recently it was working as long as I avoided those situations. This problem is different.

I had waited about a minute before killing the process. I just tried it again, and after about 4 minutes the dialog box showed up. I was able to print Page 1.

So I then tried to print Page 2. The dialog box appeared immediately, but when I clicked on Print, Okular froze again.

I opened the CUPS Manager and clicked on Jobs. It hung for a while "waiting on localhost" and then displayed "No Jobs" By that time, Okular had come back alive. No page came out of the printer.

I once again tried printing Page 2. The Dialog box appeared. CUPS Manager is "waiting on localhost" for about 20 seconds. then the job came out on the printer.

Tried printing Page 3. Okular hangs again. CUPS Manager is "waiting on localhost" again. Then, "transferring data from localhost". Page has printed after a 2 minute delay. Okular is back.

Printing Page 4. Same thing. Okular is frozen. CUPS Manager is "waiting on local host" then in about 10 seconds "transferring data from localhost". Page came out in about 1 minute.

Everything is running in SloMo. So it is hanging on something that needs to time out I guess.

Banjo
(_)=='=~

by **banjo** » Jan 14th, '17, 21:01

Update:

I just tried to print another pdf file using Okular. This was a different file from the one I was trying to print before.

The program froze. I waited 5 minutes and it was still frozen, so I terminated it. While Okular was frozen, the system was at idle; nothing was hogging the CPU or RAM. Okular was obviously waiting on something. KSysGuard shows Okular using no CPU at all. Okular was the only user application running other than a file browser.

Same thing happened with qpdfview.

So I installed evince. I was able to print the file normally using evince. I can also print by opening the file in LibreOffice. So, the workaround is to use one of those two tools to send PDF files to the printer. I can use the other tools for reading/viewing.

Banjo
(_)=='=~

by **isadora** » Jan 14th, '17, 21:13

Thanks banjo for the workaround. :idea:

by **banjo** » Jan 15th, '17, 22:56

Here is an interesting observation.

I shut down everything last night (including the printers). Today, I decided to look into this problem a little more, and I found that I cannot duplicate it. Everything appears to work. Okular can print. qpdfview can print. Kwrite can print.

So I created a pdf with two pages in it for testing. Using Okular I printed Page 1 OK. When I tried printing Page 2, Okular froze. This is the problem reported in bug 16330.

After a few minutes, I killed Okular. CUPS manager reports no jobs in the queue. Nothing came out of the printer. However, when I run Okular again, it posts the Printer dialog OK. This was the situation that I had before Okular totally stopped posting the Printer Dialog box.

This is a very strange bug. After Okular refused to print at all, I had tried rebooting the computer with no success. But apparently, today the problem I reported has resolved itself. The last time I rebooted the computer in an attempt to fix the problem, I did not reboot the printers. I don't see how that could cause a problem, but...

I did install an update to evince that came in today, but I don't see how that could have any effect on any of these other tools. I did not even have it installed when the printing problem showed up. Evince went from 3.14.0-1 to 3.14.2-1.

I am very confused.

Banjo
(_)=='=~

by **banjo** » Jan 16th, '17, 16:19

Just to keep beating on this dead horse, I thought I would give it another try this morning to see what the situation is.

After a clean boot, with no printers turned on I tried printing from Okular. The program hung up and was unresponsive. I killed it and turned on the default printer, which was confirmed to be connected to WiFi using ping. Tried to print from Okular, which hung.

So I did a complete reboot sequence again.

Okular hung up again.

I tried to kill Okular, and all of KDE hung up and became totally unresponsive. I could not remember the keyboard tricks to post a bash window or get out of KDE, so I forced a powerdown, rebooted, and logged in again.

I will keep trying to figure out what the pattern is that causes this problem to appear, but right now I have no clue. It was working yesterday; today it is not.

Banjo
(_)=='=~

by **isadora** » Jan 16th, '17, 16:48

Hello banjo, i follow your beating anyway.

by **isadora** » Jan 16th, '17, 17:13

Maybe this link can give you some assist (?):
https://forum.kde.org/viewtopic.php?f=2 ... gs#p369605

by **banjo** » Jan 16th, '17, 20:19

Thanks for the link. Those problems are not affecting me because I cannot get Okular to print at all.

I just tried it again, and it hangs. Okular has always done strange things for me, like hanging with more than one invocation open, preserving changes to a pdf document after deleting the document and downloading a new copy. I'm not sure how it did that unless it is saving the changes into a parallel file somewhere. Yesterday, it was all better, and I could print documents using Okular. Today, it hangs.

I used to run the Adobe reader on my linux, but when I went out to their web site I found no version that is compatible with Linux. There were just the Windows and Mac versions.

Maybe there is something wrong with my setup. There are not a whole lot of "Me Too!" responses to this thread. My setup is not special. I have not really customized this installation. It is a plain, 64 bit Mageia 5 installation with all the updates installed.

Another confusing thing is that Kwrite is having the same issue. If this is an Okular bug, why would KWrite be involved?

I have worked around the issue by using other tools that can, indeed, print the pdf files. But it is bothering me that I can't figure this out.

Thanks for the help.
The search goes on.
Banjo
(_)=='=~

by **doktor5000** » Jan 16th, '17, 21:59

banjo wrote:Okular has always done strange things for me, like hanging with more than one invocation open, preserving changes to a pdf document after deleting the document and downloading a new copy. I'm not sure how it did that unless it is saving the changes into a parallel file somewhere.

It saves the formular data into an external file. See e.g. https://okular.kde.org/faq.php#addedannotationsinpdf

by **banjo** » Jan 16th, '17, 22:58

Thanks, Doktor. I had guessed a separate file. It was a bit confusing when I ran into it.

The strangeness continues here. I logged in to see if Konqueror (being a KDE app) would hang as well.

It did not hang. The Print Dialog gets posted just fine.

Here is where it gets weird. I opened Okular again to see if that still has the problem. It works OK now. The dialog box pops up. Same thing with KWrite. As far as I can tell, I have not done anything to the system to fix this since I tested it earlier in the day. I logged out and logged back in. But in the past I have tried logging out/in to fix it, and there was no effect on the problem.

My printers are not on right now, but I have tried that scenario in the past as well with no good results.

Right now, every tool I try is OK with printing. Very strange.

Banjo
(_)=='=~

by **banjo** » Jan 17th, '17, 16:44

Here is another quick update on this problem.

I booted the computer. All printers are off. I tried to print from Okular and it hangs. So I tried Konqueror and it also hangs. Kwrite hangs when I attempt to print from it. KompoZer does not work, but it doesn't hang, it does nothing. Kdiff3 hangs when I try to print. Konsole hangs when I try to "Print Screen..."

So, it is becoming apparent that this problem is with all KDE apps, not just Okular. Sometimes it works, but I have no idea what changes before it starts working again.

The Google is not helping much when I search for "KDE apps hang on printing" or similar searches.

This seems like a bad bug. Is this just me? I am not seeing any other complaints about this. Maybe it is something in my setup.

Banjo
(_)=='=~

by **isadora** » Jan 17th, '17, 17:25

Banjo, i tested all your applications, and my HP CP1515n spits out all prints as expected.
It's not much of a help, but just to let you know.

by **banjo** » Jan 18th, '17, 02:49

OK. Thanks. It must be something in my setup here. The computer has been running all day now. Right after I booted it none if the KDE apps would post the Print dialog.

Out of curiosity I pulled up Okular just now and tried to print. The Print Dialog comes up normally. Same with Kwrite. Same with Konqueror. Etc.

This is very confusing. If I figure out what the sequence is that breaks/fixes the issue I will post. Perhaps it has something to do with printers being available/not available. I don't know at this point.

Thanks for the help. I do have workarounds for printing if I need them.

Banjo
(_)=='=~

by **jiml8** » Jan 18th, '17, 08:08

It happens that just yesterday I had need to print a filled-out PDF form. I printed from Okular to a Brother Laser on a different subnet. Printed fine, but blank (the filled-in form was not filled in). Tried with evince to the same printer. Printed but no filled in data. Finally printed it from a Windows 10 VM with Acrobat.

So, as you surmise, it must be something with your setup; my setup prints PDFs so long as I don't want to print any data.

One thing that occurs to me that might cause such odd symptoms is a library mismatch. Maybe you have two different versions of the same library, and depending on what gets loaded first based upon earlier need, you can print or you can't. Really, I can't think of much else that would cause this kind or problem.

You can find out what libs are needed using the ldd command. "ldd /usr/bin/okular" will give you okular's library requirements. You can find out what libraries a running process is using with the lsof command "lsof -p <pid>" will tell you that, where the <pid> is the process ID of the process you are interested in. You can find that many ways, but I usually use: "ps ax | grep okular" to identify it.

If the wrong library with the right name is already in memory, okular will use it rather than load the right library from disk. This could be your problem.

by **DiBosco** » Jan 18th, '17, 15:47

I had this exact problem a year or two ago. IIRC I had to use Libre Office when wanting to print documents. Then it just magically fixed itself.

viewtopic.php?f=7&t=10875

If I am printing out multiple documents I still have to shut the first one down before it will print the next one.

Glad it's not just me that was imagining this strange fault!

by **banjo** » Jan 18th, '17, 15:54

Good ideas. I dumped out the information. According to the online Qt documentation, QPrintDialog is in Qt 4.8 => QtGui.

Looking at the output from the commands, from ldd I get

Code: Select all: libQtGui.so.4 => /lib64/libQtGui.so.4 (0x00007f090a45a000)

and from lsof I get

Code: Select all: okular 7747 brian mem REG 8,2 11374808 2365350 /usr/lib64/libQtGui.so.4.8.6

Since ldd did not specify which lib64 the link refers to, I did a quick find in /usr

Code: Select all: [root@localhost usr]# find . -name lib64 -print ./share/gdb/auto-load/usr/lib64 ./lib64 ./local/lib64

It appears that there are three of them.

In directory /usr/lib64 I find the following libraries.

Code: Select all: [root@localhost usr]# ll lib64 | grep Gui lrwxrwxrwx 1 root root 24 Dec 16 2014 libKF5ConfigGui.so.5 -> libKF5ConfigGui.so.5.5.0* -rwxr-xr-x 1 root root 128936 Dec 16 2014 libKF5ConfigGui.so.5.5.0* lrwxrwxrwx 1 root root 24 Dec 16 2014 libKF5GuiAddons.so.5 -> libKF5GuiAddons.so.5.5.0* -rwxr-xr-x 1 root root 100456 Dec 16 2014 libKF5GuiAddons.so.5.5.0* lrwxrwxrwx 1 root root 18 Dec 2 2015 libQt5Gui.so.5 -> libQt5Gui.so.5.4.2* lrwxrwxrwx 1 root root 18 Dec 2 2015 libQt5Gui.so.5.4 -> libQt5Gui.so.5.4.2* -rwxr-xr-x 1 root root 5777112 Dec 2 2015 libQt5Gui.so.5.4.2* lrwxrwxrwx 1 root root 17 Apr 23 2015 libQtGui.so.4 -> libQtGui.so.4.8.6* lrwxrwxrwx 1 root root 17 Apr 23 2015 libQtGui.so.4.8 -> libQtGui.so.4.8.6* -rwxr-xr-x 1 root root 11374808 Apr 23 2015 libQtGui.so.4.8.6*

So libQtGui.so.4 eventually links to libQtGui.so.4.8.6, which is the library that is loaded.

There is nothing in the directory /usr/local/lib64. Empty directory.

/usr/share/gdb/auto-load/usr/lib64 has some python in it.

Code: Select all: [root@localhost lib64]# ls libglib-2.0.so.0.4200.1-gdb.py* libgobject-2.0.so.0.4200.1-gdb.py* libstdc++.so.6.0.20-gdb.py libglib-2.0.so.0.4200.1-gdb.pyc libgobject-2.0.so.0.4200.1-gdb.pyc libstdc++.so.6.0.20-gdb.pyc libglib-2.0.so.0.4200.1-gdb.pyo libgobject-2.0.so.0.4200.1-gdb.pyo libstdc++.so.6.0.20-gdb.pyo [root@localhost lib64]#

So far, I do not see any obvious versioning problems.

I tried Okular just now, and it is on the fritz again. When I try to print, it just hangs.

Thanks for the pointers. I always refer back to that old Unix quote:

As ye fool around, so shall ye learn

Banjo
(_)=='=~

by **banjo** » Jan 18th, '17, 16:00

DiBosco wrote:I had this exact problem a year or two ago. IIRC I had to use Libre Office when wanting to print documents. Then it just magically fixed itself.

viewtopic.php?f=7&t=10875

If I am printing out multiple documents I still have to shut the first one down before it will print the next one.

Glad it's not just me that was imagining this strange fault!

Very interesting. And the lack of any response to your post implies that it is very intermittent. Not everyone is being treated to this issue.

This problem on my computer will fix itself randomly within a day or two (sometimes)... or on the same day.... randomly.

Banjo
(_)=='=~

by **jiml8** » Jan 18th, '17, 19:48

The actions in QPrintDialog are likely to involve several other libraries.

by **jiml8** » Jan 18th, '17, 20:33

You are likely to gain some clues running okular under strace.

by **banjo** » Jan 18th, '17, 20:45

OK. I am not familiar with strace. I will look it up.

I was away from the computer for several hours to do some chores. When I came back, I logged in and tried Okular again. It works fine now. I didn't change anything. Neither of the printers is on. It just healed. Very strange problem.

Banjo
(_)=='=~

by **banjo** » Jan 19th, '17, 20:22

I tried Okular again today, and it stalls. So I ran it under strace until it got hung and then I killed the process. The last gasps from the program show it polling some file descriptors, which appear to be a pipe and a socket. The polling is consistently timing out. Here is the output from when the pipe and socket are created until the end.

Code: Select all: pipe([15, 16]) = 0 fcntl(15, F_GETFL) = 0 (flags O_RDONLY) fcntl(15, F_SETFL, O_RDONLY|O_NONBLOCK) = 0 fcntl(16, F_GETFL) = 0x1 (flags O_WRONLY) fcntl(16, F_SETFL, O_WRONLY|O_NONBLOCK) = 0 write(16, "W", 1) = 1 write(16, "W", 1) = 1 socket(PF_LOCAL, SOCK_STREAM|SOCK_CLOEXEC, 0) = 17 connect(17, {sa_family=AF_LOCAL, sun_path="/run/dbus/system_bus_socket"}, 29) = 0 fcntl(17, F_GETFL) = 0x2 (flags O_RDWR) fcntl(17, F_SETFL, O_RDWR|O_NONBLOCK) = 0 geteuid() = 1001 getsockname(17, {sa_family=AF_LOCAL, NULL}, [2]) = 0 poll([{fd=17, events=POLLOUT}], 1, 0) = 1 ([{fd=17, revents=POLLOUT}]) sendto(17, "\0", 1, MSG_NOSIGNAL, NULL, 0) = 1 sendto(17, "AUTH EXTERNAL 31303031\r\n", 24, MSG_NOSIGNAL, NULL, 0) = 24 poll([{fd=17, events=POLLIN}], 1, 4294967295) = 1 ([{fd=17, revents=POLLIN}]) read(17, "OK 868308ad98e493a54ac5d2785880f"..., 2048) = 37 poll([{fd=17, events=POLLOUT}], 1, 4294967295) = 1 ([{fd=17, revents=POLLOUT}]) sendto(17, "NEGOTIATE_UNIX_FD\r\n", 19, MSG_NOSIGNAL, NULL, 0) = 19 poll([{fd=17, events=POLLIN}], 1, 4294967295) = 1 ([{fd=17, revents=POLLIN}]) read(17, "AGREE_UNIX_FD\r\n", 2048) = 15 poll([{fd=17, events=POLLOUT}], 1, 4294967295) = 1 ([{fd=17, revents=POLLOUT}]) sendto(17, "BEGIN\r\n", 7, MSG_NOSIGNAL, NULL, 0) = 7 poll([{fd=17, events=POLLIN|POLLOUT}], 1, 4294967295) = 1 ([{fd=17, revents=POLLOUT}]) sendmsg(17, {msg_name(0)=NULL, msg_iov(2)=[{"l\1\0\1\0\0\0\0\1\0\0\0n\0\0\0\1\1o\0\25\0\0\0/org/fre"..., 128}, {"", 0}], msg_controllen=0, msg_flags=0}, MSG_NOSIGNAL) = 128 poll([{fd=17, events=POLLIN}], 1, 25000) = 1 ([{fd=17, revents=POLLIN}]) recvmsg(17, {msg_name(0)=NULL, msg_iov(1)=[{"l\2\1\1\v\0\0\0\1\0\0\0=\0\0\0\6\1s\0\6\0\0\0:1.207\0\0"..., 2048}], msg_controllen=0, msg_flags=MSG_CMSG_CLOEXEC}, MSG_CMSG_CLOEXEC) = 262 recvmsg(17, 0x7ffca1b19720, MSG_CMSG_CLOEXEC) = -1 EAGAIN (Resource temporarily unavailable) write(16, "W", 1) = 1 write(16, "W", 1) = 1 write(16, "W", 1) = 1 write(16, "W", 1) = 1 sendmsg(17, {msg_name(0)=NULL, msg_iov(2)=[{"l\1\0\1f\0\0\0\2\0\0\0\177\0\0\0\1\1o\0\25\0\0\0/org/fre"..., 144}, {"a\0\0\0type='signal', interface='or"..., 102}], msg_controllen=0, msg_flags=0}, MSG_NOSIGNAL) = 246 poll([{fd=17, events=POLLIN}], 1, 25000) = 1 ([{fd=17, revents=POLLIN}]) recvmsg(17, {msg_name(0)=NULL, msg_iov(1)=[{"l\2\1\1\0\0\0\0\3\0\0\0005\0\0\0\6\1s\0\6\0\0\0:1.207\0\0"..., 2048}], msg_controllen=0, msg_flags=MSG_CMSG_CLOEXEC}, MSG_CMSG_CLOEXEC) = 72 write(16, "W", 1) = 1 recvmsg(17, 0x7ffca1b197b0, MSG_CMSG_CLOEXEC) = -1 EAGAIN (Resource temporarily unavailable) write(16, "W", 1) = 1 sendmsg(17, {msg_name(0)=NULL, msg_iov(2)=[{"l\1\0\1q\0\0\0\3\0\0\0\177\0\0\0\1\1o\0\25\0\0\0/org/fre"..., 144}, {"l\0\0\0type='signal', interface='or"..., 113}], msg_controllen=0, msg_flags=0}, MSG_NOSIGNAL) = 257 poll([{fd=17, events=POLLIN}], 1, 25000) = 1 ([{fd=17, revents=POLLIN}]) recvmsg(17, {msg_name(0)=NULL, msg_iov(1)=[{"l\2\1\1\0\0\0\0\4\0\0\0005\0\0\0\6\1s\0\6\0\0\0:1.207\0\0"..., 2048}], msg_controllen=0, msg_flags=MSG_CMSG_CLOEXEC}, MSG_CMSG_CLOEXEC) = 72 write(16, "W", 1) = 1 recvmsg(17, 0x7ffca1b197b0, MSG_CMSG_CLOEXEC) = -1 EAGAIN (Resource temporarily unavailable) write(16, "W", 1) = 1 sendmsg(17, {msg_name(0)=NULL, msg_iov(2)=[{"l\1\0\1:\0\0\0\4\0\0\0\177\0\0\0\1\1o\0\25\0\0\0/org/fre"..., 144}, {"5\0\0\0type='signal', interface='or"..., 58}], msg_controllen=0, msg_flags=0}, MSG_NOSIGNAL) = 202 poll([{fd=17, events=POLLIN}], 1, 25000) = 1 ([{fd=17, revents=POLLIN}]) recvmsg(17, {msg_name(0)=NULL, msg_iov(1)=[{"l\2\1\1\0\0\0\0\5\0\0\0005\0\0\0\6\1s\0\6\0\0\0:1.207\0\0"..., 2048}], msg_controllen=0, msg_flags=MSG_CMSG_CLOEXEC}, MSG_CMSG_CLOEXEC) = 72 write(16, "W", 1) = 1 recvmsg(17, 0x7ffca1b197b0, MSG_CMSG_CLOEXEC) = -1 EAGAIN (Resource temporarily unavailable) write(16, "W", 1) = 1 sendmsg(17, {msg_name(0)=NULL, msg_iov(2)=[{"l\1\0\1\0\0\0\0\5\0\0\0e\0\0\0\1\1o\0\1\0\0\0/\0\0\0\0\0\0\0"..., 120}, {"", 0}], msg_controllen=0, msg_flags=0}, MSG_NOSIGNAL) = 120 poll([{fd=17, events=POLLIN}], 1, 25000) = 1 ([{fd=17, revents=POLLIN}]) recvmsg(17, {msg_name(0)=NULL, msg_iov(1)=[{"l\2\1\1\0\0\0\0t\0\0\0%\0\0\0\6\1s\0\6\0\0\0:1.207\0\0"..., 2048}], msg_controllen=0, msg_flags=MSG_CMSG_CLOEXEC}, MSG_CMSG_CLOEXEC) = 56 write(16, "W", 1) = 1 recvmsg(17, 0x7ffca1b19800, MSG_CMSG_CLOEXEC) = -1 EAGAIN (Resource temporarily unavailable) write(16, "W", 1) = 1 sendmsg(17, {msg_name(0)=NULL, msg_iov(2)=[{"l\1\0\1\0\0\0\0\6\0\0\0n\0\0\0\1\1o\0\1\0\0\0/\0\0\0\0\0\0\0"..., 128}, {"", 0}], msg_controllen=0, msg_flags=0}, MSG_NOSIGNAL) = 128 poll([{fd=17, events=POLLIN}], 1, 25000) = 1 ([{fd=17, revents=POLLIN}]) recvmsg(17, {msg_name(0)=NULL, msg_iov(1)=[{"l\2\1\1\4\0\0\0u\0\0\0-\0\0\0\6\1s\0\6\0\0\0:1.207\0\0"..., 2048}], msg_controllen=0, msg_flags=MSG_CMSG_CLOEXEC}, MSG_CMSG_CLOEXEC) = 68 write(16, "W", 1) = 1 recvmsg(17, 0x7ffca1b19790, MSG_CMSG_CLOEXEC) = -1 EAGAIN (Resource temporarily unavailable) write(16, "W", 1) = 1 sendmsg(17, {msg_name(0)=NULL, msg_iov(2)=[{"l\1\0\1\0\0\0\0\7\0\0\0i\0\0\0\1\1o\0\1\0\0\0/\0\0\0\0\0\0\0"..., 128}, {"", 0}], msg_controllen=0, msg_flags=0}, MSG_NOSIGNAL) = 128 poll([{fd=17, events=POLLIN}], 1, 25000) = 1 ([{fd=17, revents=POLLIN}]) recvmsg(17, {msg_name(0)=NULL, msg_iov(1)=[{"l\2\1\1\4\0\0\0v\0\0\0-\0\0\0\6\1s\0\6\0\0\0:1.207\0\0"..., 2048}], msg_controllen=0, msg_flags=MSG_CMSG_CLOEXEC}, MSG_CMSG_CLOEXEC) = 68 write(16, "W", 1) = 1 recvmsg(17, 0x7ffca1b19790, MSG_CMSG_CLOEXEC) = -1 EAGAIN (Resource temporarily unavailable) write(16, "W", 1) = 1 sendmsg(17, {msg_name(0)=NULL, msg_iov(2)=[{"l\1\0\1$\0\0\0\10\0\0\0\203\0\0\0\1\1o\0\1\0\0\0/\0\0\0\0\0\0\0"..., 152}, {"\377\377\377\377\377\377\377\377\t\0\0\0_ipp._tcp\0\0\0\0\0\0\0\0\0\0\0"..., 36}], msg_controllen=0, msg_flags=0}, MSG_NOSIGNAL) = 188 poll([{fd=17, events=POLLIN}], 1, 25000) = 1 ([{fd=17, revents=POLLIN}]) recvmsg(17, {msg_name(0)=NULL, msg_iov(1)=[{"l\2\1\1\35\0\0\0w\0\0\0-\0\0\0\6\1s\0\6\0\0\0:1.207\0\0"..., 2048}], msg_controllen=0, msg_flags=MSG_CMSG_CLOEXEC}, MSG_CMSG_CLOEXEC) = 337 write(16, "W", 1) = 1 recvmsg(17, {msg_name(0)=NULL, msg_iov(1)=[{"l\4\1\1X\0\0\0y\0\0\0\225\0\0\0\1\1o\0\30\0\0\0/Client8"..., 2048}], msg_controllen=0, msg_flags=MSG_CMSG_CLOEXEC}, MSG_CMSG_CLOEXEC) = 416 recvmsg(17, 0x7ffca1b197c0, MSG_CMSG_CLOEXEC) = -1 EAGAIN (Resource temporarily unavailable) write(16, "W", 1) = 1 sendmsg(17, {msg_name(0)=NULL, msg_iov(2)=[{"l\1\0\1$\0\0\0\t\0\0\0\203\0\0\0\1\1o\0\1\0\0\0/\0\0\0\0\0\0\0"..., 152}, {"\377\377\377\377\377\377\377\377\n\0\0\0_ipps._tcp\0\0\0\0\0\0\0\0\0\0"..., 36}], msg_controllen=0, msg_flags=0}, MSG_NOSIGNAL) = 188 poll([{fd=17, events=POLLIN}], 1, 25000) = 1 ([{fd=17, revents=POLLIN}]) recvmsg(17, {msg_name(0)=NULL, msg_iov(1)=[{"l\2\1\1\35\0\0\0{\0\0\0-\0\0\0\6\1s\0\6\0\0\0:1.207\0\0"..., 2048}], msg_controllen=0, msg_flags=MSG_CMSG_CLOEXEC}, MSG_CMSG_CLOEXEC) = 93 write(16, "W", 1) = 1 recvmsg(17, {msg_name(0)=NULL, msg_iov(1)=[{"l\4\1\1X\0\0\0|\0\0\0\225\0\0\0\1\1o\0\30\0\0\0/Client8"..., 2048}], msg_controllen=0, msg_flags=MSG_CMSG_CLOEXEC}, MSG_CMSG_CLOEXEC) = 256 recvmsg(17, {msg_name(0)=NULL, msg_iov(1)=[{"l\4\1\1L\0\0\0}\0\0\0\225\0\0\0\1\1o\0\30\0\0\0/Client8"..., 2048}], msg_controllen=0, msg_flags=MSG_CMSG_CLOEXEC}, MSG_CMSG_CLOEXEC) = 244 recvmsg(17, {msg_name(0)=NULL, msg_iov(1)=[{"l\4\1\1\0\0\0\0~\0\0\0\215\0\0\0\1\1o\0\30\0\0\0/Client8"..., 2048}], msg_controllen=0, msg_flags=MSG_CMSG_CLOEXEC}, MSG_CMSG_CLOEXEC) = 160 recvmsg(17, 0x7ffca1b197b0, MSG_CMSG_CLOEXEC) = -1 EAGAIN (Resource temporarily unavailable) read(15, "WWWWWWWWWW", 10) = 10 read(15, "WWWWWWWWWW", 10) = 10 read(15, "W", 10) = 1 poll([{fd=15, events=POLLIN}, {fd=17, events=POLLIN}], 2, 250) = 0 (Timeout) write(16, "W", 1) = 1 read(15, "W", 10) = 1 poll([{fd=15, events=POLLIN}, {fd=17, events=POLLIN}], 2, 250) = 0 (Timeout) write(16, "W", 1) = 1 read(15, "W", 10) = 1 poll([{fd=15, events=POLLIN}, {fd=17, events=POLLIN}], 2, 250) = 0 (Timeout) write(16, "W", 1) = 1 read(15, "W", 10) = 1 poll([{fd=15, events=POLLIN}, {fd=17, events=POLLIN}], 2, 250) = 0 (Timeout) write(16, "W", 1) = 1 read(15, "W", 10) = 1 poll([{fd=15, events=POLLIN}, {fd=17, events=POLLIN}], 2, 250) = 0 (Timeout) write(16, "W", 1) = 1 read(15, "W", 10) = 1 poll([{fd=15, events=POLLIN}, {fd=17, events=POLLIN}], 2, 250) = 0 (Timeout) write(16, "W", 1) = 1 read(15, "W", 10) = 1 poll([{fd=15, events=POLLIN}, {fd=17, events=POLLIN}], 2, 250) = 0 (Timeout) write(16, "W", 1) = 1 read(15, "W", 10) = 1 poll([{fd=15, events=POLLIN}, {fd=17, events=POLLIN}], 2, 250) = 0 (Timeout) poll([{fd=15, events=POLLIN}, {fd=17, events=POLLIN}], 2, 250) = 0 (Timeout) poll([{fd=15, events=POLLIN}, {fd=17, events=POLLIN}], 2, 250) = 0 (Timeout) poll([{fd=15, events=POLLIN}, {fd=17, events=POLLIN}], 2, 250) = 0 (Timeout) poll([{fd=15, events=POLLIN}, {fd=17, events=POLLIN}], 2, 250) = 0 (Timeout) poll([{fd=15, events=POLLIN}, {fd=17, events=POLLIN}], 2, 250) = 0 (Timeout) poll([{fd=15, events=POLLIN}, {fd=17, events=POLLIN}], 2, 250) = 0 (Timeout) poll([{fd=15, events=POLLIN}, {fd=17, events=POLLIN}], 2, 250) = 0 (Timeout) poll([{fd=15, events=POLLIN}, {fd=17, events=POLLIN}], 2, 250) = 0 (Timeout) poll([{fd=15, events=POLLIN}, {fd=17, events=POLLIN}], 2, 250) = 0 (Timeout) poll([{fd=15, events=POLLIN}, {fd=17, events=POLLIN}], 2, 250) = 0 (Timeout) poll([{fd=15, events=POLLIN}, {fd=17, events=POLLIN}], 2, 250) = 0 (Timeout) poll([{fd=15, events=POLLIN}, {fd=17, events=POLLIN}], 2, 250) = 0 (Timeout) poll([{fd=15, events=POLLIN}, {fd=17, events=POLLIN}], 2, 250) = 0 (Timeout) poll([{fd=15, events=POLLIN}, {fd=17, events=POLLIN}], 2, 250) = 0 (Timeout) poll([{fd=15, events=POLLIN}, {fd=17, events=POLLIN}], 2, 250) = 0 (Timeout) poll([{fd=15, events=POLLIN}, {fd=17, events=POLLIN}], 2, 250) = 0 (Timeout) poll([{fd=15, events=POLLIN}, {fd=17, events=POLLIN}], 2, 250) = 0 (Timeout) poll([{fd=15, events=POLLIN}, {fd=17, events=POLLIN}], 2, 250) = 0 (Timeout) poll([{fd=15, events=POLLIN}, {fd=17, events=POLLIN}], 2, 250) = 0 (Timeout) poll([{fd=15, events=POLLIN}, {fd=17, events=POLLIN}], 2, 250) = 0 (Timeout) poll([{fd=15, events=POLLIN}, {fd=17, events=POLLIN}], 2, 250) = 0 (Timeout) poll([{fd=15, events=POLLIN}, {fd=17, events=POLLIN}], 2, 250) = 0 (Timeout) poll([{fd=15, events=POLLIN}, {fd=17, events=POLLIN}], 2, 250) = 0 (Timeout) poll([{fd=15, events=POLLIN}, {fd=17, events=POLLIN}], 2, 250) = 0 (Timeout) poll([{fd=15, events=POLLIN}, {fd=17, events=POLLIN}], 2, 250) = 0 (Timeout) poll([{fd=15, events=POLLIN}, {fd=17, events=POLLIN}], 2, 250) = 0 (Timeout) poll([{fd=15, events=POLLIN}, {fd=17, events=POLLIN}], 2, 250) = 0 (Timeout) poll([{fd=15, events=POLLIN}, {fd=17, events=POLLIN}], 2, 250) = 0 (Timeout) poll([{fd=15, events=POLLIN}, {fd=17, events=POLLIN}], 2, 250) = 0 (Timeout) poll([{fd=15, events=POLLIN}, {fd=17, events=POLLIN}], 2, 250) = 0 (Timeout) poll([{fd=15, events=POLLIN}, {fd=17, events=POLLIN}], 2, 250) = 0 (Timeout) poll([{fd=15, events=POLLIN}, {fd=17, events=POLLIN}], 2, 250) = 0 (Timeout) poll([{fd=15, events=POLLIN}, {fd=17, events=POLLIN}], 2, 250) = 0 (Timeout) poll([{fd=15, events=POLLIN}, {fd=17, events=POLLIN}], 2, 250) = 0 (Timeout) poll([{fd=15, events=POLLIN}, {fd=17, events=POLLIN}], 2, 250) = 0 (Timeout) poll([{fd=15, events=POLLIN}, {fd=17, events=POLLIN}], 2, 250) = 0 (Timeout) poll([{fd=15, events=POLLIN}, {fd=17, events=POLLIN}], 2, 250) = 0 (Timeout) poll([{fd=15, events=POLLIN}, {fd=17, events=POLLIN}], 2, 250) = 0 (Timeout) poll([{fd=15, events=POLLIN}, {fd=17, events=POLLIN}], 2, 250) = 0 (Timeout) poll([{fd=15, events=POLLIN}, {fd=17, events=POLLIN}], 2, 250) = 0 (Timeout) poll([{fd=15, events=POLLIN}, {fd=17, events=POLLIN}], 2, 250) = 0 (Timeout) poll([{fd=15, events=POLLIN}, {fd=17, events=POLLIN}], 2, 250) = 0 (Timeout) poll([{fd=15, events=POLLIN}, {fd=17, events=POLLIN}], 2, 250) = 0 (Timeout) poll([{fd=15, events=POLLIN}, {fd=17, events=POLLIN}], 2, 250) = 0 (Timeout) poll([{fd=15, events=POLLIN}, {fd=17, events=POLLIN}], 2, 250) = 0 (Timeout) poll([{fd=15, events=POLLIN}, {fd=17, events=POLLIN}], 2, 250) = 0 (Timeout) poll([{fd=15, events=POLLIN}, {fd=17, events=POLLIN}], 2, 250) = 0 (Timeout) poll([{fd=15, events=POLLIN}, {fd=17, events=POLLIN}], 2, 250) = 0 (Timeout) poll([{fd=15, events=POLLIN}, {fd=17, events=POLLIN}], 2, 250) = 0 (Timeout) poll([{fd=15, events=POLLIN}, {fd=17, events=POLLIN}], 2, 250) = 0 (Timeout) poll([{fd=15, events=POLLIN}, {fd=17, events=POLLIN}], 2, 250) = 0 (Timeout) poll([{fd=15, events=POLLIN}, {fd=17, events=POLLIN}], 2, 250) = 0 (Timeout) poll([{fd=15, events=POLLIN}, {fd=17, events=POLLIN}], 2, 250) = 0 (Timeout) poll([{fd=15, events=POLLIN}, {fd=17, events=POLLIN}], 2, 250) = 0 (Timeout) poll([{fd=15, events=POLLIN}, {fd=17, events=POLLIN}], 2, 250) = 0 (Timeout) poll([{fd=15, events=POLLIN}, {fd=17, events=POLLIN}], 2, 250) = 0 (Timeout) poll([{fd=15, events=POLLIN}, {fd=17, events=POLLIN}], 2, 250) = 0 (Timeout) poll([{fd=15, events=POLLIN}, {fd=17, events=POLLIN}], 2, 250) = 0 (Timeout) poll([{fd=15, events=POLLIN}, {fd=17, events=POLLIN}], 2, 250) = 0 (Timeout) poll([{fd=15, events=POLLIN}, {fd=17, events=POLLIN}], 2, 250) = 0 (Timeout) poll([{fd=15, events=POLLIN}, {fd=17, events=POLLIN}], 2, 250) = 0 (Timeout) poll([{fd=15, events=POLLIN}, {fd=17, events=POLLIN}], 2, 250) = 0 (Timeout) poll([{fd=15, events=POLLIN}, {fd=17, events=POLLIN}], 2, 250) = 0 (Timeout) poll([{fd=15, events=POLLIN}, {fd=17, events=POLLIN}], 2, 250) = 0 (Timeout) poll([{fd=15, events=POLLIN}, {fd=17, events=POLLIN}], 2, 250) = 0 (Timeout) poll([{fd=15, events=POLLIN}, {fd=17, events=POLLIN}], 2, 250) = 0 (Timeout) poll([{fd=15, events=POLLIN}, {fd=17, events=POLLIN}], 2, 250) = 0 (Timeout) poll([{fd=15, events=POLLIN}, {fd=17, events=POLLIN}], 2, 250) = 0 (Timeout) poll([{fd=15, events=POLLIN}, {fd=17, events=POLLIN}], 2, 250) = 0 (Timeout) poll([{fd=15, events=POLLIN}, {fd=17, events=POLLIN}], 2, 250) = 0 (Timeout) poll([{fd=15, events=POLLIN}, {fd=17, events=POLLIN}], 2, 250) = 0 (Timeout) poll([{fd=15, events=POLLIN}, {fd=17, events=POLLIN}], 2, 250) = 0 (Timeout) poll([{fd=15, events=POLLIN}, {fd=17, events=POLLIN}], 2, 250) = 0 (Timeout) poll([{fd=15, events=POLLIN}, {fd=17, events=POLLIN}], 2, 250) = 0 (Timeout) poll([{fd=15, events=POLLIN}, {fd=17, events=POLLIN}], 2, 250) = 0 (Timeout) poll([{fd=15, events=POLLIN}, {fd=17, events=POLLIN}], 2, 250) = 0 (Timeout) poll([{fd=15, events=POLLIN}, {fd=17, events=POLLIN}], 2, 250) = 0 (Timeout) poll([{fd=15, events=POLLIN}, {fd=17, events=POLLIN}], 2, 250) = 0 (Timeout) poll([{fd=15, events=POLLIN}, {fd=17, events=POLLIN}], 2, 250) = 0 (Timeout) poll([{fd=15, events=POLLIN}, {fd=17, events=POLLIN}], 2, 250) = 0 (Timeout) poll([{fd=15, events=POLLIN}, {fd=17, events=POLLIN}], 2, 250) = 0 (Timeout) poll([{fd=15, events=POLLIN}, {fd=17, events=POLLIN}], 2, 250) = 0 (Timeout) poll([{fd=15, events=POLLIN}, {fd=17, events=POLLIN}], 2, 250) = 0 (Timeout) poll([{fd=15, events=POLLIN}, {fd=17, events=POLLIN}], 2, 250) = 0 (Timeout) poll([{fd=15, events=POLLIN}, {fd=17, events=POLLIN}], 2, 250) = 0 (Timeout) poll([{fd=15, events=POLLIN}, {fd=17, events=POLLIN}], 2, 250) = 0 (Timeout) poll([{fd=15, events=POLLIN}, {fd=17, events=POLLIN}], 2, 250) = 0 (Timeout) poll([{fd=15, events=POLLIN}, {fd=17, events=POLLIN}], 2, 250) = 0 (Timeout) poll([{fd=15, events=POLLIN}, {fd=17, events=POLLIN}], 2, 250) = 0 (Timeout) poll([{fd=15, events=POLLIN}, {fd=17, events=POLLIN}], 2, 250) = 0 (Timeout) poll([{fd=15, events=POLLIN}, {fd=17, events=POLLIN}], 2, 250) = 0 (Timeout) poll([{fd=15, events=POLLIN}, {fd=17, events=POLLIN}], 2, 250) = 0 (Timeout) poll([{fd=15, events=POLLIN}, {fd=17, events=POLLIN}], 2, 250) = 0 (Timeout) poll([{fd=15, events=POLLIN}, {fd=17, events=POLLIN}], 2, 250) = 0 (Timeout) poll([{fd=15, events=POLLIN}, {fd=17, events=POLLIN}], 2, 250) = 0 (Timeout) poll([{fd=15, events=POLLIN}, {fd=17, events=POLLIN}], 2, 250) = 0 (Timeout) poll([{fd=15, events=POLLIN}, {fd=17, events=POLLIN}], 2, 250) = 0 (Timeout) poll([{fd=15, events=POLLIN}, {fd=17, events=POLLIN}], 2, 250) = 0 (Timeout) poll([{fd=15, events=POLLIN}, {fd=17, events=POLLIN}], 2, 250) = 0 (Timeout) poll([{fd=15, events=POLLIN}, {fd=17, events=POLLIN}], 2, 250) = 0 (Timeout) poll([{fd=15, events=POLLIN}, {fd=17, events=POLLIN}], 2, 250) = 0 (Timeout) poll([{fd=15, events=POLLIN}, {fd=17, events=POLLIN}], 2, 250) = 0 (Timeout) poll([{fd=15, events=POLLIN}, {fd=17, events=POLLIN}], 2, 250) = 0 (Timeout) poll([{fd=15, events=POLLIN}, {fd=17, events=POLLIN}], 2, 250) = 0 (Timeout) poll([{fd=15, events=POLLIN}, {fd=17, events=POLLIN}], 2, 250) = 0 (Timeout) poll([{fd=15, events=POLLIN}, {fd=17, events=POLLIN}], 2, 250) = 0 (Timeout) poll([{fd=15, events=POLLIN}, {fd=17, events=POLLIN}], 2, 250) = 0 (Timeout) poll([{fd=15, events=POLLIN}, {fd=17, events=POLLIN}], 2, 250) = 0 (Timeout) poll([{fd=15, events=POLLIN}, {fd=17, events=POLLIN}], 2, 250) = 0 (Timeout) poll([{fd=15, events=POLLIN}, {fd=17, events=POLLIN}], 2, 250) = 0 (Timeout) poll([{fd=15, events=POLLIN}, {fd=17, events=POLLIN}], 2, 250) = 0 (Timeout) poll([{fd=15, events=POLLIN}, {fd=17, events=POLLIN}], 2, 250) = 0 (Timeout) poll([{fd=15, events=POLLIN}, {fd=17, events=POLLIN}], 2, 250) = 0 (Timeout) poll([{fd=15, events=POLLIN}, {fd=17, events=POLLIN}], 2, 250) = 0 (Timeout) poll([{fd=15, events=POLLIN}, {fd=17, events=POLLIN}], 2, 250) = 0 (Timeout) poll([{fd=15, events=POLLIN}, {fd=17, events=POLLIN}], 2, 250) = 0 (Timeout) poll([{fd=15, events=POLLIN}, {fd=17, events=POLLIN}], 2, 250) = 0 (Timeout) poll([{fd=15, events=POLLIN}, {fd=17, events=POLLIN}], 2, 250) = 0 (Timeout) poll([{fd=15, events=POLLIN}, {fd=17, events=POLLIN}], 2, 250) = 0 (Timeout) poll([{fd=15, events=POLLIN}, {fd=17, events=POLLIN}], 2, 250) = 0 (Timeout) poll([{fd=15, events=POLLIN}, {fd=17, events=POLLIN}], 2, 250) = 0 (Timeout) poll([{fd=15, events=POLLIN}, {fd=17, events=POLLIN}], 2, 250) = 0 (Timeout) poll([{fd=15, events=POLLIN}, {fd=17, events=POLLIN}], 2, 250) = 0 (Timeout) poll([{fd=15, events=POLLIN}, {fd=17, events=POLLIN}], 2, 250) = 0 (Timeout) poll([{fd=15, events=POLLIN}, {fd=17, events=POLLIN}], 2, 250) = 0 (Timeout) poll([{fd=15, events=POLLIN}, {fd=17, events=POLLIN}], 2, 250) = 0 (Timeout) poll([{fd=15, events=POLLIN}, {fd=17, events=POLLIN}], 2, 250) = 0 (Timeout) poll([{fd=15, events=POLLIN}, {fd=17, events=POLLIN}], 2, 250) = 0 (Timeout) poll([{fd=15, events=POLLIN}, {fd=17, events=POLLIN}], 2, 250) = 0 (Timeout) poll([{fd=15, events=POLLIN}, {fd=17, events=POLLIN}], 2, 250) = 0 (Timeout) poll([{fd=15, events=POLLIN}, {fd=17, events=POLLIN}], 2, 250) = 0 (Timeout) poll([{fd=15, events=POLLIN}, {fd=17, events=POLLIN}], 2, 250) = 0 (Timeout) poll([{fd=15, events=POLLIN}, {fd=17, events=POLLIN}], 2, 250) = 0 (Timeout) poll([{fd=15, events=POLLIN}, {fd=17, events=POLLIN}], 2, 250) = 0 (Timeout) poll([{fd=15, events=POLLIN}, {fd=17, events=POLLIN}], 2, 250) = 0 (Timeout) poll([{fd=15, events=POLLIN}, {fd=17, events=POLLIN}], 2, 250) = 0 (Timeout) poll([{fd=15, events=POLLIN}, {fd=17, events=POLLIN}], 2, 250) = 0 (Timeout) poll([{fd=15, events=POLLIN}, {fd=17, events=POLLIN}], 2, 250) = 0 (Timeout) poll([{fd=15, events=POLLIN}, {fd=17, events=POLLIN}], 2, 250) = 0 (Timeout) poll([{fd=15, events=POLLIN}, {fd=17, events=POLLIN}], 2, 250) = 0 (Timeout) poll([{fd=15, events=POLLIN}, {fd=17, events=POLLIN}], 2, 250) = 0 (Timeout) poll([{fd=15, events=POLLIN}, {fd=17, events=POLLIN}], 2, 250) = 0 (Timeout) poll([{fd=15, events=POLLIN}, {fd=17, events=POLLIN}], 2, 250) = 0 (Timeout) poll([{fd=15, events=POLLIN}, {fd=17, events=POLLIN}], 2, 250) = 0 (Timeout) poll([{fd=15, events=POLLIN}, {fd=17, events=POLLIN}], 2, 250) = 0 (Timeout) poll([{fd=15, events=POLLIN}, {fd=17, events=POLLIN}], 2, 250) = 0 (Timeout) poll([{fd=15, events=POLLIN}, {fd=17, events=POLLIN}], 2, 250) = 0 (Timeout) poll([{fd=15, events=POLLIN}, {fd=17, events=POLLIN}], 2, 250) = 0 (Timeout) poll([{fd=15, events=POLLIN}, {fd=17, events=POLLIN}], 2, 250) = 0 (Timeout) poll([{fd=15, events=POLLIN}, {fd=17, events=POLLIN}], 2, 250) = ? ERESTART_RESTARTBLOCK (Interrupted by signal) --- SIGTERM {si_signo=SIGTERM, si_code=SI_USER, si_pid=21375, si_uid=1001} --- +++ killed by SIGTERM +++

The socket, fd 17, appears to be connected to dbus, and it appears to be throwing errors toward the end (Resource temporarily unavailable). Could this be a dbus issue?

Just before it opens the pipe and the socket, it opens a socket on fd 14 and reads a bunch of information that appears to be about the print job. That appears to function normally.

Code: Select all: socket(PF_LOCAL, SOCK_STREAM, 0) = 14 setsockopt(14, SOL_SOCKET, SO_REUSEADDR, [1], 4) = 0 setsockopt(14, SOL_SOCKET, SO_REUSEPORT, [1], 4) = 0 setsockopt(14, SOL_TCP, TCP_NODELAY, [1], 4) = -1 EOPNOTSUPP (Operation not supported) fcntl(14, F_SETFD, FD_CLOEXEC) = 0 fcntl(14, F_GETFL) = 0x2 (flags O_RDWR) fcntl(14, F_SETFL, O_RDWR|O_NONBLOCK) = 0 connect(14, {sa_family=AF_LOCAL, sun_path="/var/run/cups/cups.sock"}, 26) = 0 fcntl(14, F_SETFL, O_RDWR) = 0 uname({sys="Linux", node="localhost", ...}) = 0 sendto(14, "POST / HTTP/1.1\r\nContent-Length:"..., 182, 0, NULL, 0) = 182 sendto(14, "\1\1@\2\0\0\0\1\1G\0\22attributes-charset\0\5"..., 673, 0, NULL, 0) = 673 poll([{fd=14, events=POLLIN}], 1, 1000) = 1 ([{fd=14, revents=POLLIN}]) poll([{fd=14, events=POLLIN}], 1, 60000) = 1 ([{fd=14, revents=POLLIN}]) recvfrom(14, "HTTP/1.1 100 Continue\r\n\r\n", 2048, 0, NULL, NULL) = 25 poll([{fd=14, events=POLLIN}], 1, 60000) = 1 ([{fd=14, revents=POLLIN}]) recvfrom(14, "HTTP/1.1 200 OK\r\nConnection: Kee"..., 2048, 0, NULL, NULL) = 2048 recvfrom(14, "mat-default", 11, 0, NULL, NULL) = 11 recvfrom(14, "\0\30", 2, 0, NULL, NULL) = 2 recvfrom(14, "application/octet-stream", 24, 0, NULL, NULL) = 24 recvfrom(14, "!", 1, 0, NULL, NULL) = 1 recvfrom(14, "\0\30", 2, 0, NULL, NULL) = 2 recvfrom(14, "job-cancel-after-default", 24, 0, NULL, NULL) = 24 recvfrom(14, "\0\4", 2, 0, NULL, NULL) = 2 recvfrom(14, "\0\0*0", 4, 0, NULL, NULL) = 4 recvfrom(14, "D", 1, 0, NULL, NULL) = 1 recvfrom(14, "\0\26", 2, 0, NULL, NULL) = 2 recvfrom(14, "job-hold-until-default", 22, 0, NULL, NULL) = 22 recvfrom(14, "\0\7", 2, 0, NULL, NULL) = 2 recvfrom(14, "no-hold", 7, 0, NULL, NULL) = 7 recvfrom(14, "!", 1, 0, NULL, NULL) = 1 recvfrom(14, "\0\24", 2, 0, NULL, NULL) = 2 recvfrom(14, "job-priority-default", 20, 0, NULL, NULL) = 20 recvfrom(14, "\0\4", 2, 0, NULL, NULL) = 2 recvfrom(14, "\0\0\0002", 4, 0, NULL, NULL) = 4 recvfrom(14, "!", 1, 0, NULL, NULL) = 1 recvfrom(14, "\0\21", 2, 0, NULL, NULL) = 2 recvfrom(14, "number-up-default", 17, 0, NULL, NULL) = 17 recvfrom(14, "\0\4", 2, 0, NULL, NULL) = 2 recvfrom(14, "\0\0\0\1", 4, 0, NULL, NULL) = 4 recvfrom(14, "\23", 1, 0, NULL, NULL) = 1 recvfrom(14, "\0\35", 2, 0, NULL, NULL) = 2 recvfrom(14, "orientation-requested-default", 29, 0, NULL, NULL) = 29 recvfrom(14, "\0\0", 2, 0, NULL, NULL) = 2 recvfrom(14, "A", 1, 0, NULL, NULL) = 1 recvfrom(14, "\0\26", 2, 0, NULL, NULL) = 2 recvfrom(14, "printer-make-and-model", 22, 0, NULL, NULL) = 22 recvfrom(14, "\0L", 2, 0, NULL, NULL) = 2 recvfrom(14, "HP LaserJet Professional P 1102w"..., 76, 0, NULL, NULL) = 76 recvfrom(14, "4", 1, 0, NULL, NULL) = 1 recvfrom(14, "\0\21", 2, 0, NULL, NULL) = 2 recvfrom(14, "media-col-default", 17, 0, NULL, NULL) = 17 recvfrom(14, "\0\0", 2, 0, NULL, NULL) = 2 recvfrom(14, "J", 1, 0, NULL, NULL) = 1 recvfrom(14, "\0\0", 2, 0, NULL, NULL) = 2 recvfrom(14, "\0\n", 2, 0, NULL, NULL) = 2 recvfrom(14, "media-size", 10, 0, NULL, NULL) = 10 recvfrom(14, "4", 1, 0, NULL, NULL) = 1 recvfrom(14, "\0\0", 2, 0, NULL, NULL) = 2 recvfrom(14, "\0\0", 2, 0, NULL, NULL) = 2 recvfrom(14, "J", 1, 0, NULL, NULL) = 1 recvfrom(14, "\0\0", 2, 0, NULL, NULL) = 2 recvfrom(14, "\0\v", 2, 0, NULL, NULL) = 2 recvfrom(14, "x-dimension", 11, 0, NULL, NULL) = 11 recvfrom(14, "!", 1, 0, NULL, NULL) = 1 recvfrom(14, "\0\0", 2, 0, NULL, NULL) = 2 recvfrom(14, "\0\4", 2, 0, NULL, NULL) = 2 recvfrom(14, "\0\0TV", 4, 0, NULL, NULL) = 4 recvfrom(14, "J", 1, 0, NULL, NULL) = 1 recvfrom(14, "\0\0", 2, 0, NULL, NULL) = 2 recvfrom(14, "\0\v", 2, 0, NULL, NULL) = 2 recvfrom(14, "y-dimension", 11, 0, NULL, NULL) = 11 recvfrom(14, "!", 1, 0, NULL, NULL) = 1 recvfrom(14, "\0\0", 2, 0, NULL, NULL) = 2 recvfrom(14, "\0\4", 2, 0, NULL, NULL) = 2 recvfrom(14, "\0\0m$", 4, 0, NULL, NULL) = 4 recvfrom(14, "7", 1, 0, NULL, NULL) = 1 recvfrom(14, "\0\0", 2, 0, NULL, NULL) = 2 recvfrom(14, "\0\0", 2, 0, NULL, NULL) = 2 recvfrom(14, "J", 1, 0, NULL, NULL) = 1 recvfrom(14, "\0\0", 2, 0, NULL, NULL) = 2 recvfrom(14, "\0\23", 2, 0, NULL, NULL) = 2 recvfrom(14, "media-bottom-margin", 19, 0, NULL, NULL) = 19 recvfrom(14, "!", 1, 0, NULL, NULL) = 1 recvfrom(14, "\0\0", 2, 0, NULL, NULL) = 2 recvfrom(14, "\0\4", 2, 0, NULL, NULL) = 2 recvfrom(14, "\0\0\1\247", 4, 0, NULL, NULL) = 4 recvfrom(14, "J", 1, 0, NULL, NULL) = 1 recvfrom(14, "\0\0", 2, 0, NULL, NULL) = 2 recvfrom(14, "\0\21", 2, 0, NULL, NULL) = 2 recvfrom(14, "media-left-margin", 17, 0, NULL, NULL) = 17 recvfrom(14, "!", 1, 0, NULL, NULL) = 1 recvfrom(14, "\0\0", 2, 0, NULL, NULL) = 2 recvfrom(14, "\0\4", 2, 0, NULL, NULL) = 2 recvfrom(14, "\0\0\1\247", 4, 0, NULL, NULL) = 4 recvfrom(14, "J", 1, 0, NULL, NULL) = 1 recvfrom(14, "\0\0", 2, 0, NULL, NULL) = 2 recvfrom(14, "\0\22", 2, 0, NULL, NULL) = 2 recvfrom(14, "media-right-margin", 18, 0, NULL, NULL) = 18 recvfrom(14, "!", 1, 0, NULL, NULL) = 1 recvfrom(14, "\0\0", 2, 0, NULL, NULL) = 2 recvfrom(14, "\0\4", 2, 0, NULL, NULL) = 2 recvfrom(14, "\0\0\1\247", 4, 0, NULL, NULL) = 4 recvfrom(14, "J", 1, 0, NULL, NULL) = 1 recvfrom(14, "\0\0", 2, 0, NULL, NULL) = 2 recvfrom(14, "\0\20", 2, 0, NULL, NULL) = 2 recvfrom(14, "media-top-margin", 16, 0, NULL, NULL) = 16 recvfrom(14, "!", 1, 0, NULL, NULL) = 1 recvfrom(14, "\0\0", 2, 0, NULL, NULL) = 2 recvfrom(14, "\0\4", 2, 0, NULL, NULL) = 2 recvfrom(14, "\0\0\1\247", 4, 0, NULL, NULL) = 4 recvfrom(14, "J", 1, 0, NULL, NULL) = 1 recvfrom(14, "\0\0", 2, 0, NULL, NULL) = 2 recvfrom(14, "\0\f", 2, 0, NULL, NULL) = 2 recvfrom(14, "media-source", 12, 0, NULL, NULL) = 12 recvfrom(14, "D", 1, 0, NULL, NULL) = 1 recvfrom(14, "\0\0", 2, 0, NULL, NULL) = 2 recvfrom(14, "\0\4", 2, 0, NULL, NULL) = 2 recvfrom(14, "auto", 4, 0, NULL, NULL) = 4 recvfrom(14, "J", 1, 0, NULL, NULL) = 1 recvfrom(14, "\0\0", 2, 0, NULL, NULL) = 2 recvfrom(14, "\0\n", 2, 0, NULL, NULL) = 2 recvfrom(14, "media-type", 10, 0, NULL, NULL) = 10 recvfrom(14, "D", 1, 0, NULL, NULL) = 1 recvfrom(14, "\0\0", 2, 0, NULL, NULL) = 2 recvfrom(14, "\0\n", 2, 0, NULL, NULL) = 2 recvfrom(14, "stationery", 10, 0, NULL, NULL) = 10 recvfrom(14, "7", 1, 0, NULL, NULL) = 1 recvfrom(14, "\0\0", 2, 0, NULL, NULL) = 2 recvfrom(14, "\0\0", 2, 0, NULL, NULL) = 2 recvfrom(14, "D", 1, 0, NULL, NULL) = 1 recvfrom(14, "\0\20", 2, 0, NULL, NULL) = 2 recvfrom(14, "printer-commands", 16, 0, NULL, NULL) = 16 recvfrom(14, "\0\4", 2, 0, NULL, NULL) = 2 recvfrom(14, "none", 4, 0, NULL, NULL) = 4 recvfrom(14, "#", 1, 0, NULL, NULL) = 1 recvfrom(14, "\0\22", 2, 0, NULL, NULL) = 2 recvfrom(14, "finishings-default", 18, 0, NULL, NULL) = 18 recvfrom(14, "\0\4", 2, 0, NULL, NULL) = 2 recvfrom(14, "\0\0\0\3", 4, 0, NULL, NULL) = 4 recvfrom(14, "\3", 1, 0, NULL, NULL) = 1 close(14) = 0

I have to admit that this level of information is in the weeds for me. I have not uploaded the entire trace file because it is > 37 Meg of stuff, and I don't know if that will blow out any size limitations here.

Thanks to all for reading this.
Banjo
(_)=='=~

by **banjo** » Jan 19th, '17, 20:52

I am back already. Okular started working again just now. LOL. So I ran it under strace again and grabbed the trace. This one is >65 Meg. Here is a copy of the same region where it stalled before. It still sees some timeouts, but then it seems to recover from them and continue.

Code: Select all: pipe([15, 16]) = 0 fcntl(15, F_GETFL) = 0 (flags O_RDONLY) fcntl(15, F_SETFL, O_RDONLY|O_NONBLOCK) = 0 fcntl(16, F_GETFL) = 0x1 (flags O_WRONLY) fcntl(16, F_SETFL, O_WRONLY|O_NONBLOCK) = 0 write(16, "W", 1) = 1 write(16, "W", 1) = 1 socket(PF_LOCAL, SOCK_STREAM|SOCK_CLOEXEC, 0) = 17 connect(17, {sa_family=AF_LOCAL, sun_path="/run/dbus/system_bus_socket"}, 29) = 0 fcntl(17, F_GETFL) = 0x2 (flags O_RDWR) fcntl(17, F_SETFL, O_RDWR|O_NONBLOCK) = 0 geteuid() = 1001 getsockname(17, {sa_family=AF_LOCAL, NULL}, [2]) = 0 poll([{fd=17, events=POLLOUT}], 1, 0) = 1 ([{fd=17, revents=POLLOUT}]) sendto(17, "\0", 1, MSG_NOSIGNAL, NULL, 0) = 1 sendto(17, "AUTH EXTERNAL 31303031\r\n", 24, MSG_NOSIGNAL, NULL, 0) = 24 poll([{fd=17, events=POLLIN}], 1, 4294967295) = 1 ([{fd=17, revents=POLLIN}]) read(17, "OK 868308ad98e493a54ac5d2785880f"..., 2048) = 37 poll([{fd=17, events=POLLOUT}], 1, 4294967295) = 1 ([{fd=17, revents=POLLOUT}]) sendto(17, "NEGOTIATE_UNIX_FD\r\n", 19, MSG_NOSIGNAL, NULL, 0) = 19 poll([{fd=17, events=POLLIN}], 1, 4294967295) = 1 ([{fd=17, revents=POLLIN}]) read(17, "AGREE_UNIX_FD\r\n", 2048) = 15 poll([{fd=17, events=POLLOUT}], 1, 4294967295) = 1 ([{fd=17, revents=POLLOUT}]) sendto(17, "BEGIN\r\n", 7, MSG_NOSIGNAL, NULL, 0) = 7 poll([{fd=17, events=POLLIN|POLLOUT}], 1, 4294967295) = 1 ([{fd=17, revents=POLLOUT}]) sendmsg(17, {msg_name(0)=NULL, msg_iov(2)=[{"l\1\0\1\0\0\0\0\1\0\0\0n\0\0\0\1\1o\0\25\0\0\0/org/fre"..., 128}, {"", 0}], msg_controllen=0, msg_flags=0}, MSG_NOSIGNAL) = 128 poll([{fd=17, events=POLLIN}], 1, 25000) = 1 ([{fd=17, revents=POLLIN}]) recvmsg(17, {msg_name(0)=NULL, msg_iov(1)=[{"l\2\1\1\v\0\0\0\1\0\0\0=\0\0\0\6\1s\0\6\0\0\0:1.269\0\0"..., 2048}], msg_controllen=0, msg_flags=MSG_CMSG_CLOEXEC}, MSG_CMSG_CLOEXEC) = 262 recvmsg(17, 0x7fffa1b97b10, MSG_CMSG_CLOEXEC) = -1 EAGAIN (Resource temporarily unavailable) write(16, "W", 1) = 1 write(16, "W", 1) = 1 write(16, "W", 1) = 1 write(16, "W", 1) = 1 sendmsg(17, {msg_name(0)=NULL, msg_iov(2)=[{"l\1\0\1f\0\0\0\2\0\0\0\177\0\0\0\1\1o\0\25\0\0\0/org/fre"..., 144}, {"a\0\0\0type='signal', interface='or"..., 102}], msg_controllen=0, msg_flags=0}, MSG_NOSIGNAL) = 246 poll([{fd=17, events=POLLIN}], 1, 25000) = 1 ([{fd=17, revents=POLLIN}]) recvmsg(17, {msg_name(0)=NULL, msg_iov(1)=[{"l\2\1\1\0\0\0\0\3\0\0\0005\0\0\0\6\1s\0\6\0\0\0:1.269\0\0"..., 2048}], msg_controllen=0, msg_flags=MSG_CMSG_CLOEXEC}, MSG_CMSG_CLOEXEC) = 72 write(16, "W", 1) = 1 recvmsg(17, 0x7fffa1b97ba0, MSG_CMSG_CLOEXEC) = -1 EAGAIN (Resource temporarily unavailable) write(16, "W", 1) = 1 sendmsg(17, {msg_name(0)=NULL, msg_iov(2)=[{"l\1\0\1q\0\0\0\3\0\0\0\177\0\0\0\1\1o\0\25\0\0\0/org/fre"..., 144}, {"l\0\0\0type='signal', interface='or"..., 113}], msg_controllen=0, msg_flags=0}, MSG_NOSIGNAL) = 257 poll([{fd=17, events=POLLIN}], 1, 25000) = 1 ([{fd=17, revents=POLLIN}]) recvmsg(17, {msg_name(0)=NULL, msg_iov(1)=[{"l\2\1\1\0\0\0\0\4\0\0\0005\0\0\0\6\1s\0\6\0\0\0:1.269\0\0"..., 2048}], msg_controllen=0, msg_flags=MSG_CMSG_CLOEXEC}, MSG_CMSG_CLOEXEC) = 72 write(16, "W", 1) = 1 recvmsg(17, 0x7fffa1b97ba0, MSG_CMSG_CLOEXEC) = -1 EAGAIN (Resource temporarily unavailable) write(16, "W", 1) = 1 sendmsg(17, {msg_name(0)=NULL, msg_iov(2)=[{"l\1\0\1:\0\0\0\4\0\0\0\177\0\0\0\1\1o\0\25\0\0\0/org/fre"..., 144}, {"5\0\0\0type='signal', interface='or"..., 58}], msg_controllen=0, msg_flags=0}, MSG_NOSIGNAL) = 202 poll([{fd=17, events=POLLIN}], 1, 25000) = 1 ([{fd=17, revents=POLLIN}]) recvmsg(17, {msg_name(0)=NULL, msg_iov(1)=[{"l\2\1\1\0\0\0\0\5\0\0\0005\0\0\0\6\1s\0\6\0\0\0:1.269\0\0"..., 2048}], msg_controllen=0, msg_flags=MSG_CMSG_CLOEXEC}, MSG_CMSG_CLOEXEC) = 72 write(16, "W", 1) = 1 recvmsg(17, 0x7fffa1b97ba0, MSG_CMSG_CLOEXEC) = -1 EAGAIN (Resource temporarily unavailable) write(16, "W", 1) = 1 sendmsg(17, {msg_name(0)=NULL, msg_iov(2)=[{"l\1\0\1\0\0\0\0\5\0\0\0e\0\0\0\1\1o\0\1\0\0\0/\0\0\0\0\0\0\0"..., 120}, {"", 0}], msg_controllen=0, msg_flags=0}, MSG_NOSIGNAL) = 120 poll([{fd=17, events=POLLIN}], 1, 25000) = 1 ([{fd=17, revents=POLLIN}]) recvmsg(17, {msg_name(0)=NULL, msg_iov(1)=[{"l\2\1\1\0\0\0\0\222\0\0\0%\0\0\0\6\1s\0\6\0\0\0:1.269\0\0"..., 2048}], msg_controllen=0, msg_flags=MSG_CMSG_CLOEXEC}, MSG_CMSG_CLOEXEC) = 56 write(16, "W", 1) = 1 recvmsg(17, 0x7fffa1b97bf0, MSG_CMSG_CLOEXEC) = -1 EAGAIN (Resource temporarily unavailable) write(16, "W", 1) = 1 sendmsg(17, {msg_name(0)=NULL, msg_iov(2)=[{"l\1\0\1\0\0\0\0\6\0\0\0n\0\0\0\1\1o\0\1\0\0\0/\0\0\0\0\0\0\0"..., 128}, {"", 0}], msg_controllen=0, msg_flags=0}, MSG_NOSIGNAL) = 128 poll([{fd=17, events=POLLIN}], 1, 25000) = 1 ([{fd=17, revents=POLLIN}]) recvmsg(17, {msg_name(0)=NULL, msg_iov(1)=[{"l\2\1\1\4\0\0\0\223\0\0\0-\0\0\0\6\1s\0\6\0\0\0:1.269\0\0"..., 2048}], msg_controllen=0, msg_flags=MSG_CMSG_CLOEXEC}, MSG_CMSG_CLOEXEC) = 68 write(16, "W", 1) = 1 recvmsg(17, 0x7fffa1b97b80, MSG_CMSG_CLOEXEC) = -1 EAGAIN (Resource temporarily unavailable) write(16, "W", 1) = 1 sendmsg(17, {msg_name(0)=NULL, msg_iov(2)=[{"l\1\0\1\0\0\0\0\7\0\0\0i\0\0\0\1\1o\0\1\0\0\0/\0\0\0\0\0\0\0"..., 128}, {"", 0}], msg_controllen=0, msg_flags=0}, MSG_NOSIGNAL) = 128 poll([{fd=17, events=POLLIN}], 1, 25000) = 1 ([{fd=17, revents=POLLIN}]) recvmsg(17, {msg_name(0)=NULL, msg_iov(1)=[{"l\2\1\1\4\0\0\0\224\0\0\0-\0\0\0\6\1s\0\6\0\0\0:1.269\0\0"..., 2048}], msg_controllen=0, msg_flags=MSG_CMSG_CLOEXEC}, MSG_CMSG_CLOEXEC) = 68 write(16, "W", 1) = 1 recvmsg(17, 0x7fffa1b97b80, MSG_CMSG_CLOEXEC) = -1 EAGAIN (Resource temporarily unavailable) write(16, "W", 1) = 1 sendmsg(17, {msg_name(0)=NULL, msg_iov(2)=[{"l\1\0\1$\0\0\0\10\0\0\0\203\0\0\0\1\1o\0\1\0\0\0/\0\0\0\0\0\0\0"..., 152}, {"\377\377\377\377\377\377\377\377\t\0\0\0_ipp._tcp\0\0\0\0\0\0\0\0\0\0\0"..., 36}], msg_controllen=0, msg_flags=0}, MSG_NOSIGNAL) = 188 poll([{fd=17, events=POLLIN}], 1, 25000) = 1 ([{fd=17, revents=POLLIN}]) recvmsg(17, {msg_name(0)=NULL, msg_iov(1)=[{"l\2\1\1\36\0\0\0\225\0\0\0-\0\0\0\6\1s\0\6\0\0\0:1.269\0\0"..., 2048}], msg_controllen=0, msg_flags=MSG_CMSG_CLOEXEC}, MSG_CMSG_CLOEXEC) = 914 write(16, "W", 1) = 1 recvmsg(17, 0x7fffa1b97bb0, MSG_CMSG_CLOEXEC) = -1 EAGAIN (Resource temporarily unavailable) write(16, "W", 1) = 1 sendmsg(17, {msg_name(0)=NULL, msg_iov(2)=[{"l\1\0\1$\0\0\0\t\0\0\0\203\0\0\0\1\1o\0\1\0\0\0/\0\0\0\0\0\0\0"..., 152}, {"\377\377\377\377\377\377\377\377\n\0\0\0_ipps._tcp\0\0\0\0\0\0\0\0\0\0"..., 36}], msg_controllen=0, msg_flags=0}, MSG_NOSIGNAL) = 188 poll([{fd=17, events=POLLIN}], 1, 25000) = 1 ([{fd=17, revents=POLLIN}]) recvmsg(17, {msg_name(0)=NULL, msg_iov(1)=[{"l\2\1\1\36\0\0\0\232\0\0\0-\0\0\0\6\1s\0\6\0\0\0:1.269\0\0"..., 2048}], msg_controllen=0, msg_flags=MSG_CMSG_CLOEXEC}, MSG_CMSG_CLOEXEC) = 914 write(16, "W", 1) = 1 recvmsg(17, 0x7fffa1b97ba0, MSG_CMSG_CLOEXEC) = -1 EAGAIN (Resource temporarily unavailable) read(15, "WWWWWWWWWW", 10) = 10 read(15, "WWWWWWWWWW", 10) = 10 read(15, "W", 10) = 1 poll([{fd=15, events=POLLIN}, {fd=17, events=POLLIN}], 2, 250) = 0 (Timeout) write(16, "W", 1) = 1 read(15, "W", 10) = 1 poll([{fd=15, events=POLLIN}, {fd=17, events=POLLIN}], 2, 250) = 0 (Timeout) write(16, "W", 1) = 1 read(15, "W", 10) = 1 poll([{fd=15, events=POLLIN}, {fd=17, events=POLLIN}], 2, 250) = 0 (Timeout) write(16, "W", 1) = 1 read(15, "W", 10) = 1 poll([{fd=15, events=POLLIN}, {fd=17, events=POLLIN}], 2, 250) = 0 (Timeout) write(16, "W", 1) = 1 read(15, "W", 10) = 1 poll([{fd=15, events=POLLIN}, {fd=17, events=POLLIN}], 2, 250) = 0 (Timeout) write(16, "W", 1) = 1 write(16, "W", 1) = 1 read(15, "WW", 10) = 2 write(16, "W", 1) = 1 sendmsg(17, {msg_name(0)=NULL, msg_iov(2)=[{"l\1\0\1\0\0\0\0\n\0\0\0\205\0\0\0\1\1o\0\31\0\0\0/Client1"..., 152}, {"", 0}], msg_controllen=0, msg_flags=0}, MSG_NOSIGNAL) = 152 poll([{fd=17, events=POLLIN}], 1, 25000) = 1 ([{fd=17, revents=POLLIN}]) recvmsg(17, {msg_name(0)=NULL, msg_iov(1)=[{"l\2\1\1\0\0\0\0\237\0\0\0%\0\0\0\6\1s\0\6\0\0\0:1.269\0\0"..., 2048}], msg_controllen=0, msg_flags=MSG_CMSG_CLOEXEC}, MSG_CMSG_CLOEXEC) = 56 write(16, "W", 1) = 1 recvmsg(17, 0x7fffa1b97bf0, MSG_CMSG_CLOEXEC) = -1 EAGAIN (Resource temporarily unavailable) write(16, "W", 1) = 1 sendmsg(17, {msg_name(0)=NULL, msg_iov(2)=[{"l\1\0\1\0\0\0\0\v\0\0\0\205\0\0\0\1\1o\0\31\0\0\0/Client1"..., 152}, {"", 0}], msg_controllen=0, msg_flags=0}, MSG_NOSIGNAL) = 152 poll([{fd=17, events=POLLIN}], 1, 25000) = 1 ([{fd=17, revents=POLLIN}]) recvmsg(17, {msg_name(0)=NULL, msg_iov(1)=[{"l\2\1\1\0\0\0\0\240\0\0\0%\0\0\0\6\1s\0\6\0\0\0:1.269\0\0"..., 2048}], msg_controllen=0, msg_flags=MSG_CMSG_CLOEXEC}, MSG_CMSG_CLOEXEC) = 56 write(16, "W", 1) = 1 recvmsg(17, 0x7fffa1b97bf0, MSG_CMSG_CLOEXEC) = -1 EAGAIN (Resource temporarily unavailable) write(16, "W", 1) = 1 close(17) = 0 write(16, "W", 1) = 1 close(15) = 0 close(16) = 0

Instead of being at the end of the file, this is somewhere in the middle. After it gets by that place there is lots of stuff that is too big to post. Here is a short snippet:

Code: Select all: socket(PF_INET6, SOCK_STREAM, IPPROTO_IP) = 15 setsockopt(15, SOL_SOCKET, SO_REUSEADDR, [1], 4) = 0 setsockopt(15, SOL_SOCKET, SO_REUSEPORT, [1], 4) = 0 setsockopt(15, SOL_TCP, TCP_NODELAY, [1], 4) = 0 fcntl(15, F_SETFD, FD_CLOEXEC) = 0 fcntl(15, F_GETFL) = 0x2 (flags O_RDWR) connect(15, {sa_family=AF_INET6, sin6_port=htons(631), inet_pton(AF_INET6, "::1", &sin6_addr), sin6_flowinfo=0, sin6_scope_id=0}, 28) = 0 fcntl(15, F_SETFL, O_RDWR) = 0 stat("/etc/cups/ppd/HP_LaserJet_Professional_P_1102w.ppd", {st_mode=S_IFREG|0644, st_size=11063, ...}) = 0 access("/etc/cups/ppd/HP_LaserJet_Professional_P_1102w.ppd", R_OK) = 0 symlink("/etc/cups/ppd/HP_LaserJet_Professional_P_1102w.ppd", "/tmp/588105d613392") = 0 open("/tmp/588105d613392", O_RDONLY) = 16 fcntl(16, F_GETFD) = 0 fcntl(16, F_SETFD, FD_CLOEXEC) = 0 read(16, "*PPD-Adobe: \"4.3\"\n*%%%% PPD file"..., 4096) = 4096 read(16, "70mm: \"<</cupsInteger0 34/PageSi"..., 4096) = 4096 read(16, "tSlot Manual/Manual Feeder: \"<</"..., 4096) = 2871 read(16, "", 4096) = 0 read(16, "", 4096) = 0 close(16) = 0 unlink("/tmp/588105d613392") = 0 socket(PF_INET6, SOCK_STREAM, IPPROTO_IP) = 16 setsockopt(16, SOL_SOCKET, SO_REUSEADDR, [1], 4) = 0 setsockopt(16, SOL_SOCKET, SO_REUSEPORT, [1], 4) = 0 setsockopt(16, SOL_TCP, TCP_NODELAY, [1], 4) = 0 fcntl(16, F_SETFD, FD_CLOEXEC) = 0 fcntl(16, F_GETFL) = 0x2 (flags O_RDWR) connect(16, {sa_family=AF_INET6, sin6_port=htons(631), inet_pton(AF_INET6, "::1", &sin6_addr), sin6_flowinfo=0, sin6_scope_id=0}, 28) = 0 fcntl(16, F_SETFL, O_RDWR) = 0 stat("/etc/cups/ppd/HP_LaserJet_Professional_P_1102w.ppd", {st_mode=S_IFREG|0644, st_size=11063, ...}) = 0 access("/etc/cups/ppd/HP_LaserJet_Professional_P_1102w.ppd", R_OK) = 0 symlink("/etc/cups/ppd/HP_LaserJet_Professional_P_1102w.ppd", "/tmp/588105d6136e1") = 0 open("/tmp/588105d6136e1", O_RDONLY) = 17 fcntl(17, F_GETFD) = 0 fcntl(17, F_SETFD, FD_CLOEXEC) = 0 read(17, "*PPD-Adobe: \"4.3\"\n*%%%% PPD file"..., 4096) = 4096 read(17, "70mm: \"<</cupsInteger0 34/PageSi"..., 4096) = 4096 read(17, "tSlot Manual/Manual Feeder: \"<</"..., 4096) = 2871 read(17, "", 4096) = 0 read(17, "", 4096) = 0 close(17) = 0 unlink("/tmp/588105d6136e1") = 0 socket(PF_INET6, SOCK_STREAM, IPPROTO_IP) = 17 setsockopt(17, SOL_SOCKET, SO_REUSEADDR, [1], 4) = 0 setsockopt(17, SOL_SOCKET, SO_REUSEPORT, [1], 4) = 0 setsockopt(17, SOL_TCP, TCP_NODELAY, [1], 4) = 0 fcntl(17, F_SETFD, FD_CLOEXEC) = 0 fcntl(17, F_GETFL) = 0x2 (flags O_RDWR) connect(17, {sa_family=AF_INET6, sin6_port=htons(631), inet_pton(AF_INET6, "::1", &sin6_addr), sin6_flowinfo=0, sin6_scope_id=0}, 28) = 0 fcntl(17, F_SETFL, O_RDWR) = 0 stat("/etc/cups/ppd/HP_LaserJet_Professional_P_1102w.ppd", {st_mode=S_IFREG|0644, st_size=11063, ...}) = 0 access("/etc/cups/ppd/HP_LaserJet_Professional_P_1102w.ppd", R_OK) = 0 symlink("/etc/cups/ppd/HP_LaserJet_Professional_P_1102w.ppd", "/tmp/588105d613a17") = 0 open("/tmp/588105d613a17", O_RDONLY) = 18 fcntl(18, F_GETFD) = 0 fcntl(18, F_SETFD, FD_CLOEXEC) = 0 read(18, "*PPD-Adobe: \"4.3\"\n*%%%% PPD file"..., 4096) = 4096 read(18, "70mm: \"<</cupsInteger0 34/PageSi"..., 4096) = 4096 read(18, "tSlot Manual/Manual Feeder: \"<</"..., 4096) = 2871 read(18, "", 4096) = 0 read(18, "", 4096) = 0 close(18) = 0 unlink("/tmp/588105d613a17") = 0 socket(PF_INET6, SOCK_STREAM, IPPROTO_IP) = 18 setsockopt(18, SOL_SOCKET, SO_REUSEADDR, [1], 4) = 0 setsockopt(18, SOL_SOCKET, SO_REUSEPORT, [1], 4) = 0 setsockopt(18, SOL_TCP, TCP_NODELAY, [1], 4) = 0 fcntl(18, F_SETFD, FD_CLOEXEC) = 0 fcntl(18, F_GETFL) = 0x2 (flags O_RDWR) connect(18, {sa_family=AF_INET6, sin6_port=htons(631), inet_pton(AF_INET6, "::1", &sin6_addr), sin6_flowinfo=0, sin6_scope_id=0}, 28) = 0 fcntl(18, F_SETFL, O_RDWR) = 0 stat("/etc/cups/ppd/HP_LaserJet_Professional_P_1102w.ppd", {st_mode=S_IFREG|0644, st_size=11063, ...}) = 0 access("/etc/cups/ppd/HP_LaserJet_Professional_P_1102w.ppd", R_OK) = 0 symlink("/etc/cups/ppd/HP_LaserJet_Professional_P_1102w.ppd", "/tmp/588105d613d45") = 0 open("/tmp/588105d613d45", O_RDONLY) = 19 fcntl(19, F_GETFD) = 0 fcntl(19, F_SETFD, FD_CLOEXEC) = 0 read(19, "*PPD-Adobe: \"4.3\"\n*%%%% PPD file"..., 4096) = 4096 read(19, "70mm: \"<</cupsInteger0 34/PageSi"..., 4096) = 4096 read(19, "tSlot Manual/Manual Feeder: \"<</"..., 4096) = 2871 read(19, "", 4096) = 0 read(19, "", 4096) = 0 close(19) = 0 . . . etc.

HP_LaserJet_Professional_P_1102w is my default printer. Looks like it has created a link to its ppd file.

The Print Dialog was posted, after which I shut down Okular normally (without actually printing).

Banjo
(_)=='=~

by **banjo** » Jan 22nd, '17, 19:00

I am still looking at this because it is an interesting problem. The workaround for printing pdf files is to use evince, which works fine. Evince appears to be using GTK instead of Qt. I do not know if that is relevant to this issue or not.

Okular (and other KDE apps) continue to freeze when attempting to print. What makes this interesting is that the KDE apps freeze in the morning, but then they work fine in the afternoon. That is a strange, time-sensitive behavior. I cut my testing to the bare minimum of user interaction by running:

Code: Select all: strace -oprintstraceout.txt /usr/bin/okular --print <filename>

The --print switch is supposed to run Okular with the print dialog directly. When I do that, Okular hangs immediately. The log file is showing me the same outcome that it has consistently shown during the hangup, that Okular is stalling on a connection to the "/run/dbus/system_bus_socket" (and/or the pipe) as shown in the log below.

Code: Select all: pipe([15, 16]) = 0 fcntl(15, F_GETFL) = 0 (flags O_RDONLY) fcntl(15, F_SETFL, O_RDONLY|O_NONBLOCK) = 0 fcntl(16, F_GETFL) = 0x1 (flags O_WRONLY) fcntl(16, F_SETFL, O_WRONLY|O_NONBLOCK) = 0 write(16, "W", 1) = 1 write(16, "W", 1) = 1 socket(PF_LOCAL, SOCK_STREAM|SOCK_CLOEXEC, 0) = 17 connect(17, {sa_family=AF_LOCAL, sun_path="/run/dbus/system_bus_socket"}, 29) = 0 fcntl(17, F_GETFL) = 0x2 (flags O_RDWR) fcntl(17, F_SETFL, O_RDWR|O_NONBLOCK) = 0 geteuid() = 1001 getsockname(17, {sa_family=AF_LOCAL, NULL}, [2]) = 0 poll([{fd=17, events=POLLOUT}], 1, 0) = 1 ([{fd=17, revents=POLLOUT}]) sendto(17, "\0", 1, MSG_NOSIGNAL, NULL, 0) = 1 sendto(17, "AUTH EXTERNAL 31303031\r\n", 24, MSG_NOSIGNAL, NULL, 0) = 24 poll([{fd=17, events=POLLIN}], 1, 4294967295) = 1 ([{fd=17, revents=POLLIN}]) read(17, "OK 10116abec8f56c4a9016a5e25884f"..., 2048) = 37 poll([{fd=17, events=POLLOUT}], 1, 4294967295) = 1 ([{fd=17, revents=POLLOUT}]) sendto(17, "NEGOTIATE_UNIX_FD\r\n", 19, MSG_NOSIGNAL, NULL, 0) = 19 poll([{fd=17, events=POLLIN}], 1, 4294967295) = 1 ([{fd=17, revents=POLLIN}]) read(17, "AGREE_UNIX_FD\r\n", 2048) = 15 poll([{fd=17, events=POLLOUT}], 1, 4294967295) = 1 ([{fd=17, revents=POLLOUT}]) sendto(17, "BEGIN\r\n", 7, MSG_NOSIGNAL, NULL, 0) = 7 poll([{fd=17, events=POLLIN|POLLOUT}], 1, 4294967295) = 1 ([{fd=17, revents=POLLOUT}]) sendmsg(17, {msg_name(0)=NULL, msg_iov(2)=[{"l\1\0\1\0\0\0\0\1\0\0\0n\0\0\0\1\1o\0\25\0\0\0/org/fre"..., 128}, {"", 0}], msg_controllen=0, msg_flags=0}, MSG_NOSIGNAL) = 128 poll([{fd=17, events=POLLIN}], 1, 25000) = 1 ([{fd=17, revents=POLLIN}]) recvmsg(17, {msg_name(0)=NULL, msg_iov(1)=[{"l\2\1\1\v\0\0\0\1\0\0\0=\0\0\0\6\1s\0\6\0\0\0:1.156\0\0"..., 2048}], msg_controllen=0, msg_flags=MSG_CMSG_CLOEXEC}, MSG_CMSG_CLOEXEC) = 262 recvmsg(17, 0x7ffc3962efb0, MSG_CMSG_CLOEXEC) = -1 EAGAIN (Resource temporarily unavailable) write(16, "W", 1) = 1 write(16, "W", 1) = 1 write(16, "W", 1) = 1 write(16, "W", 1) = 1 sendmsg(17, {msg_name(0)=NULL, msg_iov(2)=[{"l\1\0\1f\0\0\0\2\0\0\0\177\0\0\0\1\1o\0\25\0\0\0/org/fre"..., 144}, {"a\0\0\0type='signal', interface='or"..., 102}], msg_controllen=0, msg_flags=0}, MSG_NOSIGNAL) = 246 poll([{fd=17, events=POLLIN}], 1, 25000) = 1 ([{fd=17, revents=POLLIN}]) recvmsg(17, {msg_name(0)=NULL, msg_iov(1)=[{"l\2\1\1\0\0\0\0\3\0\0\0005\0\0\0\6\1s\0\6\0\0\0:1.156\0\0"..., 2048}], msg_controllen=0, msg_flags=MSG_CMSG_CLOEXEC}, MSG_CMSG_CLOEXEC) = 72 write(16, "W", 1) = 1 recvmsg(17, 0x7ffc3962f040, MSG_CMSG_CLOEXEC) = -1 EAGAIN (Resource temporarily unavailable) write(16, "W", 1) = 1 sendmsg(17, {msg_name(0)=NULL, msg_iov(2)=[{"l\1\0\1q\0\0\0\3\0\0\0\177\0\0\0\1\1o\0\25\0\0\0/org/fre"..., 144}, {"l\0\0\0type='signal', interface='or"..., 113}], msg_controllen=0, msg_flags=0}, MSG_NOSIGNAL) = 257 poll([{fd=17, events=POLLIN}], 1, 25000) = 1 ([{fd=17, revents=POLLIN}]) recvmsg(17, {msg_name(0)=NULL, msg_iov(1)=[{"l\2\1\1\0\0\0\0\4\0\0\0005\0\0\0\6\1s\0\6\0\0\0:1.156\0\0"..., 2048}], msg_controllen=0, msg_flags=MSG_CMSG_CLOEXEC}, MSG_CMSG_CLOEXEC) = 72 write(16, "W", 1) = 1 recvmsg(17, 0x7ffc3962f040, MSG_CMSG_CLOEXEC) = -1 EAGAIN (Resource temporarily unavailable) write(16, "W", 1) = 1 sendmsg(17, {msg_name(0)=NULL, msg_iov(2)=[{"l\1\0\1:\0\0\0\4\0\0\0\177\0\0\0\1\1o\0\25\0\0\0/org/fre"..., 144}, {"5\0\0\0type='signal', interface='or"..., 58}], msg_controllen=0, msg_flags=0}, MSG_NOSIGNAL) = 202 poll([{fd=17, events=POLLIN}], 1, 25000) = 1 ([{fd=17, revents=POLLIN}]) recvmsg(17, {msg_name(0)=NULL, msg_iov(1)=[{"l\2\1\1\0\0\0\0\5\0\0\0005\0\0\0\6\1s\0\6\0\0\0:1.156\0\0"..., 2048}], msg_controllen=0, msg_flags=MSG_CMSG_CLOEXEC}, MSG_CMSG_CLOEXEC) = 72 write(16, "W", 1) = 1 recvmsg(17, 0x7ffc3962f040, MSG_CMSG_CLOEXEC) = -1 EAGAIN (Resource temporarily unavailable) write(16, "W", 1) = 1 sendmsg(17, {msg_name(0)=NULL, msg_iov(2)=[{"l\1\0\1\0\0\0\0\5\0\0\0e\0\0\0\1\1o\0\1\0\0\0/\0\0\0\0\0\0\0"..., 120}, {"", 0}], msg_controllen=0, msg_flags=0}, MSG_NOSIGNAL) = 120 poll([{fd=17, events=POLLIN}], 1, 25000) = 1 ([{fd=17, revents=POLLIN}]) recvmsg(17, {msg_name(0)=NULL, msg_iov(1)=[{"l\2\1\1\0\0\0\0z\0\0\0%\0\0\0\6\1s\0\6\0\0\0:1.156\0\0"..., 2048}], msg_controllen=0, msg_flags=MSG_CMSG_CLOEXEC}, MSG_CMSG_CLOEXEC) = 56 write(16, "W", 1) = 1 recvmsg(17, 0x7ffc3962f090, MSG_CMSG_CLOEXEC) = -1 EAGAIN (Resource temporarily unavailable) write(16, "W", 1) = 1 sendmsg(17, {msg_name(0)=NULL, msg_iov(2)=[{"l\1\0\1\0\0\0\0\6\0\0\0n\0\0\0\1\1o\0\1\0\0\0/\0\0\0\0\0\0\0"..., 128}, {"", 0}], msg_controllen=0, msg_flags=0}, MSG_NOSIGNAL) = 128 poll([{fd=17, events=POLLIN}], 1, 25000) = 1 ([{fd=17, revents=POLLIN}]) recvmsg(17, {msg_name(0)=NULL, msg_iov(1)=[{"l\2\1\1\4\0\0\0{\0\0\0-\0\0\0\6\1s\0\6\0\0\0:1.156\0\0"..., 2048}], msg_controllen=0, msg_flags=MSG_CMSG_CLOEXEC}, MSG_CMSG_CLOEXEC) = 68 write(16, "W", 1) = 1 recvmsg(17, 0x7ffc3962f020, MSG_CMSG_CLOEXEC) = -1 EAGAIN (Resource temporarily unavailable) write(16, "W", 1) = 1 sendmsg(17, {msg_name(0)=NULL, msg_iov(2)=[{"l\1\0\1\0\0\0\0\7\0\0\0i\0\0\0\1\1o\0\1\0\0\0/\0\0\0\0\0\0\0"..., 128}, {"", 0}], msg_controllen=0, msg_flags=0}, MSG_NOSIGNAL) = 128 poll([{fd=17, events=POLLIN}], 1, 25000) = 1 ([{fd=17, revents=POLLIN}]) recvmsg(17, {msg_name(0)=NULL, msg_iov(1)=[{"l\2\1\1\4\0\0\0|\0\0\0-\0\0\0\6\1s\0\6\0\0\0:1.156\0\0"..., 2048}], msg_controllen=0, msg_flags=MSG_CMSG_CLOEXEC}, MSG_CMSG_CLOEXEC) = 68 write(16, "W", 1) = 1 recvmsg(17, 0x7ffc3962f020, MSG_CMSG_CLOEXEC) = -1 EAGAIN (Resource temporarily unavailable) write(16, "W", 1) = 1 sendmsg(17, {msg_name(0)=NULL, msg_iov(2)=[{"l\1\0\1$\0\0\0\10\0\0\0\203\0\0\0\1\1o\0\1\0\0\0/\0\0\0\0\0\0\0"..., 152}, {"\377\377\377\377\377\377\377\377\t\0\0\0_ipp._tcp\0\0\0\0\0\0\0\0\0\0\0"..., 36}], msg_controllen=0, msg_flags=0}, MSG_NOSIGNAL) = 188 poll([{fd=17, events=POLLIN}], 1, 25000) = 1 ([{fd=17, revents=POLLIN}]) recvmsg(17, {msg_name(0)=NULL, msg_iov(1)=[{"l\2\1\1\35\0\0\0}\0\0\0-\0\0\0\6\1s\0\6\0\0\0:1.156\0\0"..., 2048}], msg_controllen=0, msg_flags=MSG_CMSG_CLOEXEC}, MSG_CMSG_CLOEXEC) = 93 write(16, "W", 1) = 1 recvmsg(17, 0x7ffc3962f050, MSG_CMSG_CLOEXEC) = -1 EAGAIN (Resource temporarily unavailable) write(16, "W", 1) = 1 sendmsg(17, {msg_name(0)=NULL, msg_iov(2)=[{"l\1\0\1$\0\0\0\t\0\0\0\203\0\0\0\1\1o\0\1\0\0\0/\0\0\0\0\0\0\0"..., 152}, {"\377\377\377\377\377\377\377\377\n\0\0\0_ipps._tcp\0\0\0\0\0\0\0\0\0\0"..., 36}], msg_controllen=0, msg_flags=0}, MSG_NOSIGNAL) = 188 poll([{fd=17, events=POLLIN}], 1, 25000) = 1 ([{fd=17, revents=POLLIN}]) recvmsg(17, {msg_name(0)=NULL, msg_iov(1)=[{"l\4\1\1L\0\0\0~\0\0\0\225\0\0\0\1\1o\0\30\0\0\0/Client8"..., 2048}], msg_controllen=0, msg_flags=MSG_CMSG_CLOEXEC}, MSG_CMSG_CLOEXEC) = 1009 write(16, "W", 1) = 1 recvmsg(17, {msg_name(0)=NULL, msg_iov(1)=[{"l\4\1\1L\0\0\0\203\0\0\0\225\0\0\0\1\1o\0\30\0\0\0/Client8"..., 2048}], msg_controllen=0, msg_flags=MSG_CMSG_CLOEXEC}, MSG_CMSG_CLOEXEC) = 404 recvmsg(17, 0x7ffc3962f040, MSG_CMSG_CLOEXEC) = -1 EAGAIN (Resource temporarily unavailable) read(15, "WWWWWWWWWW", 10) = 10 read(15, "WWWWWWWWWW", 10) = 10 read(15, "W", 10) = 1 poll([{fd=15, events=POLLIN}, {fd=17, events=POLLIN}], 2, 250) = 0 (Timeout) write(16, "W", 1) = 1 read(15, "W", 10) = 1 poll([{fd=15, events=POLLIN}, {fd=17, events=POLLIN}], 2, 250) = 0 (Timeout) write(16, "W", 1) = 1 read(15, "W", 10) = 1 poll([{fd=15, events=POLLIN}, {fd=17, events=POLLIN}], 2, 250) = 0 (Timeout) write(16, "W", 1) = 1 read(15, "W", 10) = 1 poll([{fd=15, events=POLLIN}, {fd=17, events=POLLIN}], 2, 250) = 0 (Timeout) write(16, "W", 1) = 1 read(15, "W", 10) = 1 poll([{fd=15, events=POLLIN}, {fd=17, events=POLLIN}], 2, 250) = 0 (Timeout) write(16, "W", 1) = 1 read(15, "W", 10) = 1 poll([{fd=15, events=POLLIN}, {fd=17, events=POLLIN}], 2, 250) = 0 (Timeout) write(16, "W", 1) = 1 read(15, "W", 10) = 1 poll([{fd=15, events=POLLIN}, {fd=17, events=POLLIN}], 2, 250) = 0 (Timeout) write(16, "W", 1) = 1 read(15, "W", 10) = 1 poll([{fd=15, events=POLLIN}, {fd=17, events=POLLIN}], 2, 250) = 0 (Timeout) poll([{fd=15, events=POLLIN}, {fd=17, events=POLLIN}], 2, 250) = 0 (Timeout) poll([{fd=15, events=POLLIN}, {fd=17, events=POLLIN}], 2, 250) = 0 (Timeout) poll([{fd=15, events=POLLIN}, {fd=17, events=POLLIN}], 2, 250) = 0 (Timeout) poll([{fd=15, events=POLLIN}, {fd=17, events=POLLIN}], 2, 250) = 0 (Timeout) poll([{fd=15, events=POLLIN}, {fd=17, events=POLLIN}], 2, 250) = 0 (Timeout) poll([{fd=15, events=POLLIN}, {fd=17, events=POLLIN}], 2, 250) = 0 (Timeout) poll([{fd=15, events=POLLIN}, {fd=17, events=POLLIN}], 2, 250) = 0 (Timeout) poll([{fd=15, events=POLLIN}, {fd=17, events=POLLIN}], 2, 250) = 0 (Timeout) poll([{fd=15, events=POLLIN}, {fd=17, events=POLLIN}], 2, 250) = 0 (Timeout) poll([{fd=15, events=POLLIN}, {fd=17, events=POLLIN}], 2, 250) = 0 (Timeout) poll([{fd=15, events=POLLIN}, {fd=17, events=POLLIN}], 2, 250) = 0 (Timeout) poll([{fd=15, events=POLLIN}, {fd=17, events=POLLIN}], 2, 250) = 0 (Timeout) poll([{fd=15, events=POLLIN}, {fd=17, events=POLLIN}], 2, 250) = 0 (Timeout) poll([{fd=15, events=POLLIN}, {fd=17, events=POLLIN}], 2, 250) = 0 (Timeout) poll([{fd=15, events=POLLIN}, {fd=17, events=POLLIN}], 2, 250) = 0 (Timeout) poll([{fd=15, events=POLLIN}, {fd=17, events=POLLIN}], 2, 250) = 0 (Timeout) poll([{fd=15, events=POLLIN}, {fd=17, events=POLLIN}], 2, 250) = 0 (Timeout) poll([{fd=15, events=POLLIN}, {fd=17, events=POLLIN}], 2, 250) = 0 (Timeout) poll([{fd=15, events=POLLIN}, {fd=17, events=POLLIN}], 2, 250) = 0 (Timeout) poll([{fd=15, events=POLLIN}, {fd=17, events=POLLIN}], 2, 250) = 0 (Timeout) poll([{fd=15, events=POLLIN}, {fd=17, events=POLLIN}], 2, 250) = 0 (Timeout) poll([{fd=15, events=POLLIN}, {fd=17, events=POLLIN}], 2, 250) = 0 (Timeout) poll([{fd=15, events=POLLIN}, {fd=17, events=POLLIN}], 2, 250) = 0 (Timeout) poll([{fd=15, events=POLLIN}, {fd=17, events=POLLIN}], 2, 250) = 0 (Timeout) poll([{fd=15, events=POLLIN}, {fd=17, events=POLLIN}], 2, 250) = 0 (Timeout) poll([{fd=15, events=POLLIN}, {fd=17, events=POLLIN}], 2, 250) = 0 (Timeout) poll([{fd=15, events=POLLIN}, {fd=17, events=POLLIN}], 2, 250) = 0 (Timeout) poll([{fd=15, events=POLLIN}, {fd=17, events=POLLIN}], 2, 250) = 0 (Timeout) poll([{fd=15, events=POLLIN}, {fd=17, events=POLLIN}], 2, 250) = 0 (Timeout) poll([{fd=15, events=POLLIN}, {fd=17, events=POLLIN}], 2, 250) = 0 (Timeout) poll([{fd=15, events=POLLIN}, {fd=17, events=POLLIN}], 2, 250) = 0 (Timeout) poll([{fd=15, events=POLLIN}, {fd=17, events=POLLIN}], 2, 250) = 0 (Timeout) poll([{fd=15, events=POLLIN}, {fd=17, events=POLLIN}], 2, 250) = 0 (Timeout) poll([{fd=15, events=POLLIN}, {fd=17, events=POLLIN}], 2, 250) = 0 (Timeout) poll([{fd=15, events=POLLIN}, {fd=17, events=POLLIN}], 2, 250) = 0 (Timeout) poll([{fd=15, events=POLLIN}, {fd=17, events=POLLIN}], 2, 250) = 0 (Timeout) poll([{fd=15, events=POLLIN}, {fd=17, events=POLLIN}], 2, 250) = 0 (Timeout) poll([{fd=15, events=POLLIN}, {fd=17, events=POLLIN}], 2, 250) = 0 (Timeout) poll([{fd=15, events=POLLIN}, {fd=17, events=POLLIN}], 2, 250) = 0 (Timeout) poll([{fd=15, events=POLLIN}, {fd=17, events=POLLIN}], 2, 250) = 0 (Timeout) poll([{fd=15, events=POLLIN}, {fd=17, events=POLLIN}], 2, 250) = 0 (Timeout) poll([{fd=15, events=POLLIN}, {fd=17, events=POLLIN}], 2, 250) = 0 (Timeout) poll([{fd=15, events=POLLIN}, {fd=17, events=POLLIN}], 2, 250) = 0 (Timeout) poll([{fd=15, events=POLLIN}, {fd=17, events=POLLIN}], 2, 250) = 0 (Timeout) poll([{fd=15, events=POLLIN}, {fd=17, events=POLLIN}], 2, 250) = 0 (Timeout) poll([{fd=15, events=POLLIN}, {fd=17, events=POLLIN}], 2, 250) = 0 (Timeout) poll([{fd=15, events=POLLIN}, {fd=17, events=POLLIN}], 2, 250) = 0 (Timeout) poll([{fd=15, events=POLLIN}, {fd=17, events=POLLIN}], 2, 250) = 0 (Timeout) poll([{fd=15, events=POLLIN}, {fd=17, events=POLLIN}], 2, 250) = 0 (Timeout) poll([{fd=15, events=POLLIN}, {fd=17, events=POLLIN}], 2, 250) = 0 (Timeout) poll([{fd=15, events=POLLIN}, {fd=17, events=POLLIN}], 2, 250) = 0 (Timeout) poll([{fd=15, events=POLLIN}, {fd=17, events=POLLIN}], 2, 250) = 0 (Timeout) poll([{fd=15, events=POLLIN}, {fd=17, events=POLLIN}], 2, 250) = 0 (Timeout) poll([{fd=15, events=POLLIN}, {fd=17, events=POLLIN}], 2, 250) = 0 (Timeout) poll([{fd=15, events=POLLIN}, {fd=17, events=POLLIN}], 2, 250) = 0 (Timeout) poll([{fd=15, events=POLLIN}, {fd=17, events=POLLIN}], 2, 250) = 0 (Timeout) poll([{fd=15, events=POLLIN}, {fd=17, events=POLLIN}], 2, 250) = 0 (Timeout) poll([{fd=15, events=POLLIN}, {fd=17, events=POLLIN}], 2, 250) = 0 (Timeout) poll([{fd=15, events=POLLIN}, {fd=17, events=POLLIN}], 2, 250) = 0 (Timeout) poll([{fd=15, events=POLLIN}, {fd=17, events=POLLIN}], 2, 250) = 0 (Timeout) poll([{fd=15, events=POLLIN}, {fd=17, events=POLLIN}], 2, 250) = 0 (Timeout) poll([{fd=15, events=POLLIN}, {fd=17, events=POLLIN}], 2, 250) = 0 (Timeout) poll([{fd=15, events=POLLIN}, {fd=17, events=POLLIN}], 2, 250) = 0 (Timeout) poll([{fd=15, events=POLLIN}, {fd=17, events=POLLIN}], 2, 250) = 0 (Timeout) poll([{fd=15, events=POLLIN}, {fd=17, events=POLLIN}], 2, 250) = 0 (Timeout) poll([{fd=15, events=POLLIN}, {fd=17, events=POLLIN}], 2, 250) = 0 (Timeout) poll([{fd=15, events=POLLIN}, {fd=17, events=POLLIN}], 2, 250) = 0 (Timeout) poll([{fd=15, events=POLLIN}, {fd=17, events=POLLIN}], 2, 250) = 0 (Timeout) poll([{fd=15, events=POLLIN}, {fd=17, events=POLLIN}], 2, 250) = 0 (Timeout) poll([{fd=15, events=POLLIN}, {fd=17, events=POLLIN}], 2, 250) = 0 (Timeout) poll([{fd=15, events=POLLIN}, {fd=17, events=POLLIN}], 2, 250) = 0 (Timeout) poll([{fd=15, events=POLLIN}, {fd=17, events=POLLIN}], 2, 250) = 0 (Timeout) poll([{fd=15, events=POLLIN}, {fd=17, events=POLLIN}], 2, 250) = 0 (Timeout) poll([{fd=15, events=POLLIN}, {fd=17, events=POLLIN}], 2, 250) = 0 (Timeout) poll([{fd=15, events=POLLIN}, {fd=17, events=POLLIN}], 2, 250) = 0 (Timeout) poll([{fd=15, events=POLLIN}, {fd=17, events=POLLIN}], 2, 250) = 0 (Timeout) poll([{fd=15, events=POLLIN}, {fd=17, events=POLLIN}], 2, 250) = 0 (Timeout) poll([{fd=15, events=POLLIN}, {fd=17, events=POLLIN}], 2, 250) = 0 (Timeout) poll([{fd=15, events=POLLIN}, {fd=17, events=POLLIN}], 2, 250) = 0 (Timeout) poll([{fd=15, events=POLLIN}, {fd=17, events=POLLIN}], 2, 250) = 0 (Timeout) poll([{fd=15, events=POLLIN}, {fd=17, events=POLLIN}], 2, 250) = 0 (Timeout) poll([{fd=15, events=POLLIN}, {fd=17, events=POLLIN}], 2, 250) = 0 (Timeout) poll([{fd=15, events=POLLIN}, {fd=17, events=POLLIN}], 2, 250) = 0 (Timeout) poll([{fd=15, events=POLLIN}, {fd=17, events=POLLIN}], 2, 250) = 0 (Timeout) poll([{fd=15, events=POLLIN}, {fd=17, events=POLLIN}], 2, 250) = 0 (Timeout) poll([{fd=15, events=POLLIN}, {fd=17, events=POLLIN}], 2, 250) = 0 (Timeout) poll([{fd=15, events=POLLIN}, {fd=17, events=POLLIN}], 2, 250) = 0 (Timeout) poll([{fd=15, events=POLLIN}, {fd=17, events=POLLIN}], 2, 250) = 0 (Timeout) poll([{fd=15, events=POLLIN}, {fd=17, events=POLLIN}], 2, 250) = 0 (Timeout) poll([{fd=15, events=POLLIN}, {fd=17, events=POLLIN}], 2, 250) = 0 (Timeout) poll([{fd=15, events=POLLIN}, {fd=17, events=POLLIN}], 2, 250) = 0 (Timeout) poll([{fd=15, events=POLLIN}, {fd=17, events=POLLIN}], 2, 250) = 0 (Timeout) poll([{fd=15, events=POLLIN}, {fd=17, events=POLLIN}], 2, 250) = ? ERESTART_RESTARTBLOCK (Interrupted by signal) --- SIGTERM {si_signo=SIGTERM, si_code=SI_USER, si_pid=13697, si_uid=1001} --- +++ killed by SIGTERM +++

Some messages are successfully sent and received on the socket, but after that the poll() call continues to time out, indicating that there is no data available on the socket or on the pipe.

Just as a curiosity, the evince log has no reference to "/run/dbus/system_bus_socket" in it.

So I looked into the /var/log/boot.log, and it appears that the system_bus_socket was started with no errors.

Code: Select all: Starting D-Bus System Message Bus... [[32m OK [0m] Started D-Bus System Message Bus.

There are dbus daemons running:

Code: Select all: [brian@localhost okular-printing-problems]$ ps ax | grep dbus 1869 ? Ss 0:00 /usr/bin/dbus-daemon --system --address=systemd: --nofork --nopidfile --systemd-activation 5611 ? S 0:00 /usr/bin/dbus-launch --exit-with-session --sh-syntax 5612 ? Ss 0:00 /usr/bin/dbus-daemon --fork --print-pid 5 --print-address 7 --session 5635 ? S 0:01 /usr/bin/dbus-daemon --config-file=/etc/at-spi2/accessibility.conf --nofork --print-address 3

I don't know how dbus works, or what is supposed to be on the other end of the system_bus_socket, so the search goes on.

Pid 5612 looks suspicious. I wonder what would happen if I killed it.

Banjo
(_)=='=~

Mageia forum

Cannot print a pdf file anymore

Cannot print a pdf file anymore

Re: Cannot print a pdf file anymore

Re: Cannot print a pdf file anymore

Re: Cannot print a pdf file anymore

Re: Cannot print a pdf file anymore

Re: Cannot print a pdf file anymore

Re: Cannot print a pdf file anymore

Re: Cannot print a pdf file anymore

Re: Cannot print a pdf file anymore

Re: Cannot print a pdf file anymore

Re: Cannot print a pdf file anymore

Re: Cannot print a pdf file anymore

Re: Cannot print a pdf file anymore

Re: Cannot print a pdf file anymore

Re: Cannot print a pdf file anymore

Re: Cannot print a pdf file anymore

Re: Cannot print a pdf file anymore

Re: Cannot print a pdf file anymore

Re: Cannot print a pdf file anymore

Re: Cannot print a pdf file anymore

Re: Cannot print a pdf file anymore

Re: Cannot print a pdf file anymore

Re: Cannot print a pdf file anymore

Re: Cannot print a pdf file anymore

Re: Cannot print a pdf file anymore

Who is online