PKP PN behaves strange in version 3.1.2.4

zmandic · April 30, 2021, 7:22pm

We have multi-journal website under OJS 3.1.2.4 with only two journals. We want to preserve the content through PKP PN. We followed the procedure for both journals. This means we enabled PKP PN plugin for both journals and enabled Acron plugin and reloaded the scheduled task for both journals. However, after the whole month the deposit statuses are:

one journal has a list of all issues published but for all of them “checked” column is zero and “local status” is New. Processing and Lockss statuses are “unknown”.
another journal just has the message “Network Status: The PKP PLN can accept deposits from this journal.” and no list of issues at all.

Exporting the issue through the Native export plugin seems to work fine.

We can find "PKPPNDepositorTask-.log files in scheduledTaskLogs folder. These files seem to be generated every day since of enabling of the plugin. Typical content of these files are:
[2021-04-23 11:12:18] https://pub.iapchem.org/ojs
[2021-04-23 11:12:18] [Notice] Task process started.
[2021-04-23 11:12:18] [Notice] PLN Depositor executeActions started
[2021-04-23 11:12:18] [Notice] Depositor processing for ADMET and DMPK.
[2021-04-23 11:12:18] [Notice] Getting service document.
[2021-04-23 11:12:19] [Notice] Processing deposit status updates.
[2021-04-23 11:12:19] [Notice] Processing updated content.

Only log files with the “depositor processing for…” for one journal exist. No log files generated for the processing of the other journal.

I would appreciate any advice on this matter.

Kind regards,

Zoran

rcgillis · May 1, 2021, 6:48pm

Hi @zmandic,

Which version of the PKP PN plugin are you using?

-Roger
PKP Team

zmandic · May 1, 2021, 7:27pm

Hi,
it is v2.0.1.1. released on 21-07-2020.
Kind regards,
Zoran

NateWr · May 11, 2021, 8:09am

Hi @zmandic, I asked one of my colleagues who helps run the PN network and they said that there is an issue with one of the LOCKSS boxes that might be causing this. They are currently working to fix it. Until then, you’ll likely see these scheduled tasks failing. But give it some time to see if it resolves itself.

zmandic · May 12, 2021, 6:53am

Thank you very much @NateWr !
What might be the expected time for fixing this issue?

NateWr · May 12, 2021, 9:17am

I don’t know. But if it hasn’t resolved in a month let me know and I can check back in with my colleague for an update.

zmandic · June 18, 2021, 2:48pm

Hi @NateWr, just to let you know that this is issue has not been resolved. Could you please check?

Kind regards,
Zoran

NateWr · June 28, 2021, 9:41am

Thanks @zmandic, I’ve followed up to ask what the status is on the PN issue.

zmandic · August 30, 2021, 6:43pm

Hi @NateWr,
is there any news regarding this PN issue? Nothing has changed on our side.
Regards,
Zoran

jmacgreg · September 1, 2021, 1:43pm

Hi Zoran,

James here - interim managing director for PKP. I’ll be checking with the SFU team directly on this. Thank you for your patience.

Best,
James

jonasraoni · June 8, 2022, 6:43pm

Hi @zmandic,

Do you still have issues with the PKP PN?

Best,
Jonas

zmandic · June 9, 2022, 6:10pm

Hi @jonasraoni,
thank you very much for getting back to us.
Nothing has changed since then. The same issues appear.
Kind regards,
Zoran

jonasraoni · June 10, 2022, 9:45am

Ok! Could you please forward the contents of the latest log (located in the “{$FILES_DIR}/scheduledTaskLogs”, there should be files prefixed with “PKPPN…”)?

I just can say that on our side everything seems to be ok, I can see two journals registered, but there are no deposits.

Best,
Jonas

zmandic · June 14, 2022, 4:52pm

Hi Jonas,
I’ve tried to send the log file by email but it was bounced back so I am attaching it here.
From what I see, only one journal has been mentioned in the file without the trace of the other.
Kind regards,
Zoran

zmandic · June 14, 2022, 4:54pm

I do not see how to share the file here so I am copy/pasting the content of the file PKPPNDepositorTask-62a2444439761-20220609.log from a couple of days ago.

[2022-06-09 21:04:36] https://pub.iapchem.org/ojs
[2022-06-09 21:04:36] [Notice] Task process started.
[2022-06-09 21:04:36] [Notice] PLN Depositor executeActions started
[2022-06-09 21:04:36] [Notice] Depositor processing for ADMET and DMPK.
[2022-06-09 21:04:36] [Notice] Getting service document.
[2022-06-09 21:04:37] [Notice] Processing deposit status updates.
[2022-06-09 21:04:37] [Notice] Processing updated content.
[2022-06-09 21:04:37] [Notice] Processing new content.
[2022-06-09 21:04:37] [Notice] Packaging content for deposit.
[2022-06-09 21:04:37] [Notice] Trying package deposit 1 (Issue: 1) (Local Status: [New], Processing Status: [Unknown], Lockss Status: [Unknown])
[2022-06-09 21:04:38] [Notice] Packaging for deposit 1 succeeded

jonasraoni · June 17, 2022, 2:59pm

Hi @zmandic!

Don’t you have anything more after this text in the log?
After the last message you posted, there should be more data:

[Notice] Sending deposits to the PKP PN.
[Notice] Trying transfer deposit 1 (Issue: 1) (Local Status: [Packaged], Processing Status: [Unknown], Lockss Status: [Unknown])
(...)

And in the end of the file there should be a:
[Notice] Task process stopped.

If your file ends there, then I guess an error happened in the way and broke the process.

Could you please send a piece of the error log, the range could be from the time the process started (2022-06-09 21:04:38) until perhaps one minute later

Best,
Jonas

zmandic · June 18, 2022, 4:48pm

Hi Jonas,
every day the same log file is generated with exactly the same content.
I followed your instructions and found that the log file named: Usagestatisticsfileloadertask-62add9b5801e0-20220618.log is created approximately 19 hours after the PKPPN log file. The content of this file is always like this:

[2022-06-18 15:57:09] https://pub.iapchem.org/ojs
[2022-06-18 15:57:09] [Notice] Task process started.
[2022-06-18 15:57:11] [Notice] File /home/iapchemo/iapc_ojs3124_files/usageStats/processing/usage_events_20220617.log was processed and archived.
[2022-06-18 15:57:11] [Notice] Task process stopped.

I also found usage_events_20220617.log in archive folder. I would send it to you but for some reasons I do not know how to send the file here. I tried gmail but it was bounced back.

Kind regards,
Zoran

jonasraoni · June 18, 2022, 6:52pm

Hi @zmandic!

I need the data from the error log which is managed by PHP.

You can find the path of this file by typing this command in the shell php -i | grep error_log or through the administrative panel of OJS, probably at https://pub.iapchem.org/ojs/index.php/index/admin/phpinfo (after opening, just search for error_log).

The file will be probably full of messages, so I just need the ones that happened near to the deposit attempt.

Best,
Jonas

zmandic · June 19, 2022, 6:42am

Hi Jonas,
I found error_log in the root OJS folder.
You can pick it up from here: https://www.dropbox.com/s/bz5dzlbxgey1hh4/error_log.txt?dl=0.
Thank you very much for your support.
Kind regards,
Zoran

jonasraoni · June 19, 2022, 9:23am

I see your database is missing a field, which is bad and might be the source of the problem.

Your database should have these two tables (they are created by the plugin):

pln_deposit_objects with these fields:

deposit_object_id
journal_id
object_id
object_type
deposit_id
date_created
date_modified

And

pln_deposits with these fields:

deposit_id
journal_id
uuid
status
date_status
date_created
date_modified
export_deposit_error <<< This field is missing

This SQL statement should be enough to fix this issue:
ALTER TABLE pln_deposits ADD COLUMN export_deposit_error VARCHAR(1000) NULL

Once you apply it, you can wait 1-2 days to recheck (the time for the task to run again).

I’ve created an issue to fix it here: Database is missing a field · Issue #35 · pkp/pln · GitHub

Best,
Jonas