Repeated error found in apache error_log

Project:RUcore dlr/EDIT
Version:7.0
Component:Code
Category:bug report
Priority:normal
Assigned:triggs
Status:closed
Description

[Thu Dec 13 04:05:08 2012] [error] [client 128.6.218.101]
[Thu Dec 13 04:05:08 2012] [error] [client 128.6.218.101] no element found at line 1, column 0, byte -1:
[Thu Dec 13 04:05:08 2012] [error] [client 128.6.218.101] 23^
[Thu Dec 13 04:05:08 2012] [error] [client 128.6.218.101] 645848
[Thu Dec 13 04:05:08 2012] [error] [client 128.6.218.101] at /usr/lib/perl5/vendor_perl/5.10.0/x86_64-linux-thread-multi/XML/Parser.pm line 187
[Thu Dec 13 04:05:08 2012] [error] [client 128.6.218.101] Premature end of script headers: solrfilter-api.cgi
[Thu Dec 13 04:05:09 2012] [error] [client 128.6.218.101]
[Thu Dec 13 04:05:09 2012] [error] [client 128.6.218.101] no element found at line 1, column 0, byte -1:
[Thu Dec 13 04:05:09 2012] [error] [client 128.6.218.101] 23^
[Thu Dec 13 04:05:09 2012] [error] [client 128.6.218.101] 645960
[Thu Dec 13 04:05:09 2012] [error] [client 128.6.218.101] at /usr/lib/perl5/vendor_perl/5.10.0/x86_64-linux-thread-multi/XML/Parser.pm line 187
[Thu Dec 13 04:05:09 2012] [error] [client 128.6.218.101] Premature end of script headers: solrfilter-api.cgi
[Thu Dec 13 04:05:09 2012] [error] [client 128.6.218.101]
[Thu Dec 13 04:05:09 2012] [error] [client 128.6.218.101] no element found at line 1, column 0, byte -1:
[Thu Dec 13 04:05:09 2012] [error] [client 128.6.218.101] 23^
[Thu Dec 13 04:05:09 2012] [error] [client 128.6.218.101] 646040
[Thu Dec 13 04:05:09 2012] [error] [client 128.6.218.101] at /usr/lib/perl5/vendor_perl/5.10.0/x86_64-linux-thread-multi/XML/Parser.pm line 187
[Thu Dec 13 04:05:09 2012] [error] [client 128.6.218.101] Premature end of script headers: solrfilter-api.cgi
[Thu Dec 13 04:05:09 2012] [error] [client 128.6.218.101]
[Thu Dec 13 04:05:09 2012] [error] [client 128.6.218.101] no element found at line 1, column 0, byte -1:
[Thu Dec 13 04:05:09 2012] [error] [client 128.6.218.101] 23^
[Thu Dec 13 04:05:09 2012] [error] [client 128.6.218.101] 645960
[Thu Dec 13 04:05:09 2012] [error] [client 128.6.218.101] at /usr/lib/perl5/vendor_perl/5.10.0/x86_64-linux-thread-multi/XML/Parser.pm line 187
[Thu Dec 13 04:05:09 2012] [error] [client 128.6.218.101] Premature end of script headers: solrfilter-api.cgi
[Thu Dec 13 04:05:10 2012] [error] [client 128.6.218.101]
[Thu Dec 13 04:05:10 2012] [error] [client 128.6.218.101] no element found at line 1, column 0, byte -1:
[Thu Dec 13 04:05:10 2012] [error] [client 128.6.218.101] 23^
[Thu Dec 13 04:05:10 2012] [error] [client 128.6.218.101] 646056
[Thu Dec 13 04:05:10 2012] [error] [client 128.6.218.101] at /usr/lib/perl5/vendor_perl/5.10.0/x86_64-linux-thread-multi/XML/Parser.pm line 187
[Thu Dec 13 04:05:10 2012] [error] [client 128.6.218.101] Premature end of script headers: solrfilter-api.cgi
[Thu Dec 13 04:05:10 2012] [error] [client 128.6.218.101]
[Thu Dec 13 04:05:10 2012] [error] [client 128.6.218.101] no element found at line 1, column 0, byte -1:
[Thu Dec 13 04:05:10 2012] [error] [client 128.6.218.101] 23^
[Thu Dec 13 04:05:10 2012] [error] [client 128.6.218.101] 646088
[Thu Dec 13 04:05:10 2012] [error] [client 128.6.218.101] at /usr/lib/perl5/vendor_perl/5.10.0/x86_64-linux-thread-multi/XML/Parser.pm line 187
[Thu Dec 13 04:05:10 2012] [error] [client 128.6.218.101] Premature end of script headers: solrfilter-api.cgi
[Thu Dec 13 04:05:10 2012] [error] [client 128.6.218.101]
[Thu Dec 13 04:05:10 2012] [error] [client 128.6.218.101] no element found at line 1, column 0, byte -1:
[Thu Dec 13 04:05:10 2012] [error] [client 128.6.218.101] 23^
[Thu Dec 13 04:05:10 2012] [error] [client 128.6.218.101] 646392
[Thu Dec 13 04:05:10 2012] [error] [client 128.6.218.101] at /usr/lib/perl5/vendor_perl/5.10.0/x86_64-linux-thread-multi/XML/Parser.pm line 187
[Thu Dec 13 04:05:11 2012] [error] [client 128.6.218.101] Premature end of script headers: solrfilter-api.cgi
[Thu Dec 13 04:05:11 2012] [error] [client 128.6.218.101]
[Thu Dec 13 04:05:11 2012] [error] [client 128.6.218.101] no element found at line 1, column 0, byte -1:
[Thu Dec 13 04:05:11 2012] [error] [client 128.6.218.101] 23^
[Thu Dec 13 04:05:11 2012] [error] [client 128.6.218.101] 646440
[Thu Dec 13 04:05:11 2012] [error] [client 128.6.218.101] at /usr/lib/perl5/vendor_perl/5.10.0/x86_64-linux-thread-multi/XML/Parser.pm line 187
[Thu Dec 13 04:05:11 2012] [error] [client 128.6.218.101] Premature end of script headers: solrfilter-api.cgi
[Thu Dec 13 04:05:11 2012] [error] [client 128.6.218.101]
[Thu Dec 13 04:05:11 2012] [error] [client 128.6.218.101] no element found at line 1, column 0, byte -1:
[Thu Dec 13 04:05:11 2012] [error] [client 128.6.218.101] 23^
[Thu Dec 13 04:05:11 2012] [error] [client 128.6.218.101] 646456
[Thu Dec 13 04:05:11 2012] [error] [client 128.6.218.101] at /usr/lib/perl5/vendor_perl/5.10.0/x86_64-linux-thread-multi/XML/Parser.pm line 187
[Thu Dec 13 04:05:11 2012] [error] [client 128.6.218.101] Premature end of script headers: solrfilter-api.cgi
[Thu Dec 13 04:05:12 2012] [error] [client 128.6.218.101]
[Thu Dec 13 04:05:12 2012] [error] [client 128.6.218.101] no element found at line 1, column 0, byte -1:
[Thu Dec 13 04:05:12 2012] [error] [client 128.6.218.101] 23^
[Thu Dec 13 04:05:12 2012] [error] [client 128.6.218.101] 646392
[Thu Dec 13 04:05:12 2012] [error] [client 128.6.218.101] at /usr/lib/perl5/vendor_perl/5.10.0/x86_64-linux-thread-multi/XML/Parser.pm line 187
[Thu Dec 13 04:05:12 2012] [error] [client 128.6.218.101] Premature end of script headers: solrfilter-api.cgi
[Thu Dec 13 04:05:12 2012] [error] [client 128.6.218.101]
[Thu Dec 13 04:05:12 2012] [error] [client 128.6.218.101] no element found at line 1, column 0, byte -1:
[Thu Dec 13 04:05:12 2012] [error] [client 128.6.218.101] 23^
[Thu Dec 13 04:05:12 2012] [error] [client 128.6.218.101] 646504
[Thu Dec 13 04:05:12 2012] [error] [client 128.6.218.101] at /usr/lib/perl5/vendor_perl/5.10.0/x86_64-linux-thread-multi/XML/Parser.pm line 187
[Thu Dec 13 04:05:12 2012] [error] [client 128.6.218.101] Premature end of script headers: solrfilter-api.cgi
[Thu Dec 13 04:05:12 2012] [error] [client 128.6.218.101]
[Thu Dec 13 04:05:12 2012] [error] [client 128.6.218.101] no element found at line 1, column 0, byte -1:
[Thu Dec 13 04:05:12 2012] [error] [client 128.6.218.101] 23^
[Thu Dec 13 04:05:12 2012] [error] [client 128.6.218.101] 646392
[Thu Dec 13 04:05:12 2012] [error] [client 128.6.218.101] at /usr/lib/perl5/vendor_perl/5.10.0/x86_64-linux-thread-multi/XML/Parser.pm line 187
[Thu Dec 13 04:05:12 2012] [error] [client 128.6.218.101] Premature end of script headers: solrfilter-api.cgi
[Thu Dec 13 04:05:13 2012] [error] [client 128.6.218.101]
[Thu Dec 13 04:05:13 2012] [error] [client 128.6.218.101] no element found at line 1, column 0, byte -1:
[Thu Dec 13 04:05:13 2012] [error] [client 128.6.218.101] 23^
[Thu Dec 13 04:05:13 2012] [error] [client 128.6.218.101] 646536
[Thu Dec 13 04:05:13 2012] [error] [client 128.6.218.101] at /usr/lib/perl5/vendor_perl/5.10.0/x86_64-linux-thread-multi/XML/Parser.pm line 187
[Thu Dec 13 04:05:13 2012] [error] [client 128.6.218.101] Premature end of script headers: solrfilter-api.cgi
[Thu Dec 13 04:05:43 2012] [error] [client 128.6.218.101]
[Thu Dec 13 04:05:43 2012] [error] [client 128.6.218.101] no element found at line 1, column 0, byte -1:
[Thu Dec 13 04:05:43 2012] [error] [client 128.6.218.101] 23^
[Thu Dec 13 04:05:43 2012] [error] [client 128.6.218.101] 647384
[Thu Dec 13 04:05:43 2012] [error] [client 128.6.218.101] at /usr/lib/perl5/vendor_perl/5.10.0/x86_64-linux-thread-multi/XML/Parser.pm line 187
[Thu Dec 13 04:05:43 2012] [error] [client 128.6.218.101] Premature end of script headers: solrfilter-api.cgi

Comments

#1

These are caused by corrupted XML input of various kinds. Some (where I could grep a filesize) turn out to be multiple techmd sections withing TECHNICAL, which Fedora outputs without a wrapper element. Others objects have more obscure errors. To get a better idea, I'm suppressing the XML parsing errors and logging new warnings that include the pids of the questionable objects.

#2

Assigned to:triggs» chadmills
Status:active» test

I've been looking at the logs (with PIDs specified now), and the messages for the past days seem to have been only for the ancient bDef and bMech objects with no datastreams that can be indexed. These are now being caught with an error message indicating the reason.

#3

Assigned to:chadmills» triggs
Status:test» active

Please provide the pids and a suitable testing scenario.

#4

What is the status of this bug?

#5

These are still caused by trying to index the old disseminator definition objects, e.g., rutgers-lib:1149. I've tried blocking these in solrpost.php, but the cron seems to be calling solrpost.cgi directly. I think it might be better to block them there. I'll look into that.

#6

Status:active» test

I've set it so that an error:
Error with add action for rutgers-lib:1184. bDef, bMech, cmodel, and SDep1 objects cannot be indexed.
will be returned and the program will exit as it does with analytics or missing objects without attempting to run the indexer. We can test this by following the logs after the crons are run over the next few days.

#7

Assigned to:triggs» chadmills

Chad,

Would you be able to test this?

Thanks,
KA

#8

Assigned to:chadmills» triggs
Status:test» active

Still see errors in log.

[Mon Jun 10 11:59:07 2013] [error] [client 127.0.0.1] You must supply a valid pid...
[Mon Jun 10 11:59:07 2013] [error] [client 127.0.0.1] Premature end of script headers: solrpost.cgi
[Mon Jun 10 13:49:49 2013] [error] [client 128.6.218.101] Premature end of script headers: solrfilter-api.cgi
[Mon Jun 10 13:49:49 2013] [error] [client 127.0.0.1] Failed to read <a href="http://rep-test.libraries.rutgers.edu/cgi-bin/solr/solrfilter-api.cgi?mdstreams=MODS,TECHNICAL1,RIGHTS1,SOURCE1&amp;pid=rutgers-lib:39675" title="http://rep-test.libraries.rutgers.edu/cgi-bin/solr/solrfilter-api.cgi?mdstreams=MODS,TECHNICAL1,RIGHTS1,SOURCE1&amp;pid=rutgers-lib:39675">http://rep-test.libraries.rutgers.edu/cgi-bin/solr/solrfilter-api.cgi?md...</a>...

#9

Those are from the now-purged ETD that I hacked up on on devel Monday for Marty. I ran the indexer knowing that it would fail for lack on data. I don't think these are significant. The more important errors were caused by portalcron attempting to index old disseminator objects and content model objects. These seem to have disappeared in recent weeks.

#10

Status:active» fixed

OK. That was the only error I saw since the 6th of June so I am going to mark this fixed.

#11

Status:fixed» closed

Back to top