#14626 closed defect (fixed)
Docbuilder hangs if latex fails
Reported by:  jdemeyer  Owned by:  mvngu 

Priority:  blocker  Milestone:  sage5.10 
Component:  documentation  Keywords:  
Cc:  jhpalmieri, leif  Merged in:  sage5.10.beta5 
Authors:  Jeroen Demeyer  Reviewers:  John Palmieri 
Report Upstream:  Reported upstream. Developers acknowledge bug.  Work issues:  
Branch:  Commit:  
Dependencies:  Stopgaps: 
Description (last modified by )
When building the PDF documentation, if there is problem while running latex
, then the docbuilder just hangs forever after building all documentation. There is no obvious clue what the problem is apart from a message like the following (example from #9107) in the log file:
! LaTeX Error: Too deeply nested. See the LaTeX manual or LaTeX Companion for explanation. Type H <return> for immediate help. ... l.27819 \begin{Verbatim}[commandchars=\\\{\}] ? ! Emergency stop. ... l.27819 \begin{Verbatim}[commandchars=\\\{\}] ! ==> Fatal error occurred, no output PDF file produced! Transcript written on categories.log. make[1]: *** [categories.pdf] Error 1 make[1]: Leaving directory `/mazur/release/sage5.10.beta4boxenx86_64Linux/devel/sagemain/doc/output/latex/en/reference/categories' Exception in thread Thread6: Traceback (most recent call last): File "/mazur/release/sage5.10.beta4boxenx86_64Linux/local/lib/python/threading.py", line 810, in __bootstrap_inner self.run() File "/mazur/release/sage5.10.beta4boxenx86_64Linux/local/lib/python/threading.py", line 763, in run self.__target(*self.__args, **self.__kwargs) File "/mazur/release/sage5.10.beta4boxenx86_64Linux/local/lib/python/multiprocessing/pool.py", line 376, in _handle_results task = get() TypeError: ('__init__() takes at least 3 arguments (1 given)', <class 'subprocess.CalledProcessError'>, ())
This hang is http://bugs.python.org/issue9400
Also: the docbuilder should use $MAKE instead of make.
Attachments (1)
Change History (24)
comment:1 Changed 8 years ago by
 Description modified (diff)
comment:2 Changed 8 years ago by
 Description modified (diff)
comment:3 Changed 8 years ago by
 Description modified (diff)
comment:4 Changed 8 years ago by
comment:5 Changed 8 years ago by
 Cc jhpalmieri added
Changed 8 years ago by
comment:6 Changed 8 years ago by
 Priority changed from critical to blocker
 Status changed from new to needs_review
comment:7 Changed 8 years ago by
The patch makes a lot of sense at first glance, but I should test it to make sure. I'll try to get to it soon.
comment:8 Changed 8 years ago by
 Cc leif added
comment:9 Changed 8 years ago by
With the patch and with bad LaTeX code, I see the hang occur earlier (soon after trying to build the bad document), but it still hangs.
comment:10 Changed 8 years ago by
John, it seems to work for me, so could you please send me the docpdf.log
file?
comment:11 Changed 8 years ago by
When I apply this patch here and the patches from #9107 causing a LaTeX failure, then I get
! LaTeX Error: Too deeply nested. See the LaTeX manual or LaTeX Companion for explanation. Type H <return> for immediate help. ... l.27819 \begin{Verbatim}[commandchars=\\\{\}] ? ! Emergency stop. ... l.27819 \begin{Verbatim}[commandchars=\\\{\}] ! ==> Fatal error occurred, no output PDF file produced! Transcript written on categories.log. make[1]: *** [categories.pdf] Error 1 make[1]: Leaving directory `/mazur/release/sage5.10.beta4boxenx86_64Linux/devel/sagemain/doc/output/latex/en/reference/categories' Traceback (most recent call last): File "/mazur/release/sage5.10.beta4boxenx86_64Linux/devel/sage/doc/common/builder.py", line 1452, in <module> getattr(get_builder(name), type)() File "/mazur/release/sage5.10.beta4boxenx86_64Linux/devel/sage/doc/common/builder.py", line 273, in _wrapper getattr(get_builder(document), name)(*args, **kwds) File "/mazur/release/sage5.10.beta4boxenx86_64Linux/devel/sage/doc/common/builder.py", line 472, in _wrapper pool.map_async(build_ref_doc, L, 1).get(99999) File "/mazur/release/sage5.10.beta4boxenx86_64Linux/local/lib/python/multiprocessing/pool.py", line 554, in get raise self._value RuntimeError: failed to run $MAKE allpdf in /mazur/release/sage5.10.beta4boxenx86_64Linux/devel/sage/doc/output/latex/en/reference/categories make: *** [docpdf] Error 1
after which I get back into the shell as expected.
comment:12 Changed 8 years ago by
The cause of the crash seems to be a combination of:
subprocess.CalledProcessError
instances cannot be unpickled properly. The
multiprocessing
module uses pickles to transfer exceptions from the child process to the master process and apparently doesn't gracefully handle unpickling errors.
comment:13 Changed 8 years ago by
 Description modified (diff)
 Report Upstream changed from N/A to Reported upstream. Developers acknowledge bug.
comment:14 Changed 8 years ago by
I mistakenly thought that I wasn't getting an error from the patches at #9107, so I made this change and then build the documentation:

sage/algebras/steenrod/steenrod_algebra.py
diff git a/sage/algebras/steenrod/steenrod_algebra.py b/sage/algebras/steenrod/steenrod_algebra.py
a b 10 10 the Steenrod algebra using CombinatorialFreeModule; improved the 11 11 test suite. 12 12 13 Broken: `\aaaaaa` 14 13 15 This module defines the mod `p` Steenrod algebra `\mathcal{A}_p`, some 14 16 of its properties, and ways to define elements of it.
With the patch here, it hangs after trying to build reference/algebras. I agree that with just the patches at #9107, the hang is no longer present: once reference/categories fails, I get sent back to the shell.
comment:15 Changed 8 years ago by
John: your change still works for me:
! Undefined control sequence. <recently read> \aaaaaa l.4077 Broken: $\aaaaaa $ ? ! Emergency stop. <recently read> \aaaaaa l.4077 Broken: $\aaaaaa $ ! ==> Fatal error occurred, no output PDF file produced! Transcript written on algebras.log. make[1]: *** [algebras.pdf] Error 1 make[1]: Leaving directory `/mazur/release/sage5.10.beta4boxenx86_64Linux/devel/sagemain/doc/output/latex/en/reference/algebras' Traceback (most recent call last): File "/mazur/release/sage5.10.beta4boxenx86_64Linux/devel/sage/doc/common/builder.py", line 1452, in <module> getattr(get_builder(name), type)() File "/mazur/release/sage5.10.beta4boxenx86_64Linux/devel/sage/doc/common/builder.py", line 273, in _wrapper getattr(get_builder(document), name)(*args, **kwds) File "/mazur/release/sage5.10.beta4boxenx86_64Linux/devel/sage/doc/common/builder.py", line 472, in _wrapper pool.map_async(build_ref_doc, L, 1).get(99999) File "/mazur/release/sage5.10.beta4boxenx86_64Linux/local/lib/python/multiprocessing/pool.py", line 554, in get raise self._value RuntimeError: failed to run $MAKE allpdf in /mazur/release/sage5.10.beta4boxenx86_64Linux/devel/sage/doc/output/latex/en/reference/algebras make: *** [docpdf] Error 1
I correctly get a shell prompt after this.
Please attach your docpdf.log
such that I can maybe find out what is happening.
comment:16 Changed 8 years ago by
Sorry, once again I didn't communicate well enough. I've been running ./sage docbuild reference pdf
, which still exhibits the hang. I see now that running make docpdf
works as you say (so I'm not going to bother attaching docpdf.log).
comment:17 Changed 8 years ago by
 Reviewers set to John Palmieri
Also ./sage docbuild reference pdf
works for me...
! Undefined control sequence. <recently read> \aaaaaa l.4066 Broken: $\aaaaaa $ ? ! Emergency stop. <recently read> \aaaaaa l.4066 Broken: $\aaaaaa $ ! ==> Fatal error occurred, no output PDF file produced! Transcript written on algebras.log. make: *** [algebras.pdf] Error 1 Traceback (most recent call last): File "/mazur/release/sage5.10.beta4boxenx86_64Linux/devel/sage/doc/common/builder.py", line 1452, in <module> getattr(get_builder(name), type)() File "/mazur/release/sage5.10.beta4boxenx86_64Linux/devel/sage/doc/common/builder.py", line 472, in _wrapper pool.map_async(build_ref_doc, L, 1).get(99999) File "/mazur/release/sage5.10.beta4boxenx86_64Linux/local/lib/python/multiprocessing/pool.py", line 554, in get raise self._value RuntimeError: failed to run $MAKE allpdf in /mazur/release/sage5.10.beta4boxenx86_64Linux/devel/sage/doc/output/latex/en/reference/algebras
comment:18 Changed 8 years ago by
I've seen this repeatably while running ./sage docbuild reference pdf
on two different OS X machines (with two cores, with MAKE='make j2'
). Also, I just tried applying the patches at #9107 and the one here (without my change to steenrod_algebra.py) on sage.math (with MAKE='make j12'
), and it hangs after failing to compile categories.tex. (It finishes the compilations in progress, but then hangs).
comment:19 Changed 8 years ago by
John, I still cannot reproduce your problems, can you say the exact steps that you did.
I am doing the following on sage.math
:
 Extract a Sage 5.9 binary:
jdemeyer@sage:/release$ tar xzf /home/release/sage5.9/sage5.9boxenx86_64Linux.tar.gz jdemeyer@sage:/release$ cd sage5.9boxenx86_64Linux
 Apply the patch:
jdemeyer@sage:/release/sage5.9boxenx86_64Linux$ ./sage hg R devel/sage qimport P http://trac.sagemath.org/sage_trac/rawattachment/ticket/14626/14626_workaround.patch adding 14626_workaround.patch to series file applying 14626_workaround.patch now at: 14626_workaround.patch
 Break LaTeX:
jdemeyer@sage:/release/sage5.9boxenx86_64Linux$ ( cd devel/sage && patch p1 ) diff git a/sage/algebras/steenrod/steenrod_algebra.py b/sage/algebras/steenrod/steenrod_algebra.py Index: sage/algebras/steenrod/steenrod_algebra.py ===================================================================  a/sage/algebras/steenrod/steenrod_algebra.py +++ b/sage/algebras/steenrod/steenrod_algebra.py @@ 10,5 +10,7 @@ the Steenrod algebra using CombinatorialFreeModule; improved the test suite. +Broken: `\aaaaaa` + This module defines the mod `p` Steenrod algebra `\mathcal{A}_p`, some of its properties, and ways to define elements of it. patching file sage/algebras/steenrod/steenrod_algebra.py Hunk #1 succeeded at 10 with fuzz 1.
 Rebuild Sage:
jdemeyer@sage:/release/sage5.9boxenx86_64Linux$ ./sage b
[...]
 Build the PDF reference manual using 12 threads:
jdemeyer@sage:/release/sage5.9boxenx86_64Linux$ env MAKE="make j12" ./sage docbuild reference pdf 2>&1 tee docpdf.log
[...]
! Undefined control sequence. <recently read> \aaaaaa l.3809 Broken: $\aaaaaa $ ? ! Emergency stop. <recently read> \aaaaaa l.3809 Broken: $\aaaaaa $ ! ==> Fatal error occurred, no output PDF file produced! Transcript written on algebras.log. ] Adding blank page after the table of contents. pdfTeX warning (ext4): destination with the same identifier (name{page.i}) has been already used, duplicate ignored <to be read again> \relax l.129 \tableofcontents [1 [28]]pdfTeX warning (ext4): destination with the same iden tifier (name{page.ii}) has been already used, duplicate ignored <to be read again> \relax l.129 \tableofcontents [2make: *** [algebras.pdf] Error 1
[...]
Underfull \hbox (badness 10000) in paragraph at lines 27692772 []\T1/ptm/m/n/10 WalshCode  a binary linear $\OT1/cmr/m/n/10 [2[]\OML/cmm/m/ it/10 ; m; \OT1/cmr/m/n/10 2[]]$ \T1/ptm/m/n/10 code related to Hadamard matr ices. [30][constants] reading sources... [100%] sage/symbolic/constants_c [7] [31Traceback (most recent call last): File "/release/sage5.9boxenx86_64Linux/devel/sage/doc/common/builder.py", line 1452, in <module> ] getattr(get_builder(name), type)() File "/release/sage5.9boxenx86_64Linux/devel/sage/doc/common/builder.py", line 472, in _wrapper pool.map_async(build_ref_doc, L, 1).get(99999) File "/release/sage5.9boxenx86_64Linux/local/lib/python/multiprocessing/pool.py", line 528, in get raise self._value RuntimeError: failed to run $MAKE allpdf in /release/sage5.9boxenx86_64Linux/devel/sage/doc/output/latex/en/reference/algebras
[...]
Output written on arithgroup.pdf (85 pages, 458174 bytes). Transcript written on arithgroup.log.
 I get back to the shell as expected.
comment:20 followup: ↓ 21 Changed 8 years ago by
 Status changed from needs_review to positive_review
Okay, sorry, you're right. It looked to me as though it was hanging, but that's because the shell prompt was buried in output from the stillrunning processes. I stupidly didn't think to hit RET to see if I got a shell prompt.
At some point we might want to provide an error message at the very end, which won't get lost amidst the output from parallel processes, but that can go on another ticket.
comment:21 in reply to: ↑ 20 Changed 8 years ago by
 Merged in set to sage5.10.beta5
 Resolution set to fixed
 Status changed from positive_review to closed
Replying to jhpalmieri:
Okay, sorry, you're right. It looked to me as though it was hanging, but that's because the shell prompt was buried in output from the stillrunning processes.
Do you remember the shell command that you ran (in particular, did you use any unusual redirections or piping)? Because otherwise I don't see how it can happen what you describe.
comment:22 Changed 8 years ago by
I just logged into sage.math and did
$ cd /scratch/palmieri/sage5.10.beta4 $ ./sage docbuild reference pdf
Then I see, in the middle of a lot of output,
[32 [20 <pairing.png, id=620, 416.9979pt x 217.5327pt> <use pairing.png>]] [68] <use pairing.png> [69 <./pairing.png [33] [21] [34] Underfull \hbox (badness 10000) in paragraph at lines 28262827 [22][35]palmieri@boxen:sage5.10.beta4$ Underfull \hbox (badness 10000) in paragraph at lines 29502951 [23][36] [24]>] [70] Chapter 11.
palmieri@boxen:sage5.10.beta4$
is my shell prompt. At the end of the output:
Output written on homology.pdf (117 pages, 651759 bytes). Transcript written on homology.log.
but no shell prompt because it was already printed earlier. With make docpdf
, I see a proper error message at the end.
comment:23 Changed 8 years ago by
John: probably the "output after shell prompt" problem is caused by parallel docbuilding: it seems that, if one thread fails, the docbuilder master process exists and the other threads simply continue working...
Not really a bug, just a peculiarity of multiprocessing.Pool
I guess.
I have an idea, patch possibly coming up...