Skip to content

Instantly share code, notes, and snippets.

@hoila

hoila/orbeon_log_form.txt

Created January 28, 2019 14:31
Show Gist options
  • Save hoila/2ee0a7d2be25b421155cc4e0243a1348 to your computer and use it in GitHub Desktop.
Save hoila/2ee0a7d2be25b421155cc4e0243a1348 to your computer and use it in GitHub Desktop.
Download ZIP
Orbeon log for the FORM authentication
Raw
orbeon_log_form.txt
2019-01-28 10:47:56,797 INFO ProcessorService - Context listener - Context initialized.
2019-01-28 10:47:56,837 INFO form-runner-auth - initializing
2019-01-28 10:47:56,842 INFO form-runner-auth - configuring: FilterSettings(None)
2019-01-28 10:47:56,850 INFO limiter - initializing
2019-01-28 10:47:56,874 INFO limiter - configuring: FilterSettings(java.util.concurrent.Semaphore@504e403b[Permits = 2],(/fr/.*)|(/xforms-server),(?!/([^/]+)/service/).+\.(gif|css|pdf|json|js|coffee|map|png|jpg|xsd|htc|ico|swf|html|htm|txt))
2019-01-28 10:48:45,722 INFO ProcessorService - Servlet initialized.
2019-01-28 10:48:45,817 INFO lifecycle - event: {"request": "1", "source": "limiter", "message": "start: nofilter", "path": "/home/", "method": "GET"}
2019-01-28 10:48:45,881 INFO ProcessorService - Session listener - Session created.
2019-01-28 10:48:45,923 WARN auth - not setting credentials headers because credentials are not found
2019-01-28 10:48:45,964 INFO lifecycle - event: {"request": "1", "session": "DBC42577A29073CAEB302ADBFD7F737C", "source": "service", "message": "start: handle"}
2019-01-28 10:48:45,965 INFO ProcessorService - /home/ - Received request
2019-01-28 10:48:49,406 INFO ProcessorService - /home/ - Timing: 3440
2019-01-28 10:48:49,420 INFO lifecycle - event: {"request": "1", "session": "DBC42577A29073CAEB302ADBFD7F737C", "source": "service", "message": "end: handle", "time": "3,444 ms"}
2019-01-28 10:48:49,422 INFO lifecycle - event: {"request": "1", "session": "DBC42577A29073CAEB302ADBFD7F737C", "source": "limiter", "message": "end: nofilter", "time": "3,646 ms"}
2019-01-28 10:48:49,551 INFO lifecycle - event: {"request": "2", "session": "DBC42577A29073CAEB302ADBFD7F737C", "source": "limiter", "message": "start: nofilter", "path": "/fr/style/bootstrap/css/bootstrap.css", "method": "GET"}
2019-01-28 10:48:49,554 WARN auth - not setting credentials headers because credentials are not found
2019-01-28 10:48:49,556 INFO lifecycle - event: {"request": "2", "session": "DBC42577A29073CAEB302ADBFD7F737C", "source": "service", "message": "start: handle"}
2019-01-28 10:48:49,556 INFO ProcessorService - /fr/style/bootstrap/css/bootstrap.css - Received request
2019-01-28 10:48:49,563 INFO lifecycle - event: {"request": "3", "session": "DBC42577A29073CAEB302ADBFD7F737C", "source": "limiter", "message": "start: nofilter", "path": "/home/home.css", "method": "GET"}
2019-01-28 10:48:49,564 WARN auth - not setting credentials headers because credentials are not found
2019-01-28 10:48:49,564 INFO lifecycle - event: {"request": "4", "session": "DBC42577A29073CAEB302ADBFD7F737C", "source": "limiter", "message": "start: nofilter", "path": "/fr/style/form-runner-bootstrap-override.css", "method": "GET"}
2019-01-28 10:48:49,573 INFO lifecycle - event: {"request": "5", "session": "DBC42577A29073CAEB302ADBFD7F737C", "source": "limiter", "message": "start: nofilter", "path": "/config/theme/examples.css", "method": "GET"}
2019-01-28 10:48:49,584 WARN auth - not setting credentials headers because credentials are not found
2019-01-28 10:48:49,585 INFO lifecycle - event: {"request": "5", "session": "DBC42577A29073CAEB302ADBFD7F737C", "source": "service", "message": "start: handle"}
2019-01-28 10:48:49,585 INFO ProcessorService - /config/theme/examples.css - Received request
2019-01-28 10:48:49,564 INFO lifecycle - event: {"request": "3", "session": "DBC42577A29073CAEB302ADBFD7F737C", "source": "service", "message": "start: handle"}
2019-01-28 10:48:49,580 WARN auth - not setting credentials headers because credentials are not found
2019-01-28 10:48:49,587 INFO lifecycle - event: {"request": "4", "session": "DBC42577A29073CAEB302ADBFD7F737C", "source": "service", "message": "start: handle"}
2019-01-28 10:48:49,587 INFO ProcessorService - /fr/style/form-runner-bootstrap-override.css - Received request
2019-01-28 10:48:49,582 INFO lifecycle - event: {"request": "6", "session": "DBC42577A29073CAEB302ADBFD7F737C", "source": "limiter", "message": "start: nofilter", "path": "/ops/jquery/jquery-3.3.1.min.js", "method": "GET"}
2019-01-28 10:48:49,586 INFO ProcessorService - /home/home.css - Received request
2019-01-28 10:48:49,589 WARN auth - not setting credentials headers because credentials are not found
2019-01-28 10:48:49,589 INFO lifecycle - event: {"request": "6", "session": "DBC42577A29073CAEB302ADBFD7F737C", "source": "service", "message": "start: handle"}
2019-01-28 10:48:49,590 INFO ProcessorService - /ops/jquery/jquery-3.3.1.min.js - Received request
2019-01-28 10:48:49,722 INFO ProcessorService - /ops/jquery/jquery-3.3.1.min.js - Timing: 133
2019-01-28 10:48:49,723 INFO lifecycle - event: {"request": "6", "session": "DBC42577A29073CAEB302ADBFD7F737C", "source": "service", "message": "end: handle", "time": "133 ms"}
2019-01-28 10:48:49,723 INFO lifecycle - event: {"request": "6", "session": "DBC42577A29073CAEB302ADBFD7F737C", "source": "limiter", "message": "end: nofilter", "time": "142 ms"}
2019-01-28 10:48:49,789 INFO ProcessorService - /config/theme/examples.css - Timing: 204
2019-01-28 10:48:49,789 INFO lifecycle - event: {"request": "5", "session": "DBC42577A29073CAEB302ADBFD7F737C", "source": "service", "message": "end: handle", "time": "204 ms"}
2019-01-28 10:48:49,790 INFO lifecycle - event: {"request": "5", "session": "DBC42577A29073CAEB302ADBFD7F737C", "source": "limiter", "message": "end: nofilter", "time": "216 ms"}
2019-01-28 10:48:49,791 INFO ProcessorService - /home/home.css - Timing: 205
2019-01-28 10:48:49,792 INFO lifecycle - event: {"request": "3", "session": "DBC42577A29073CAEB302ADBFD7F737C", "source": "service", "message": "end: handle", "time": "228 ms"}
2019-01-28 10:48:49,793 INFO lifecycle - event: {"request": "3", "session": "DBC42577A29073CAEB302ADBFD7F737C", "source": "limiter", "message": "end: nofilter", "time": "229 ms"}
2019-01-28 10:48:49,800 INFO ProcessorService - /fr/style/form-runner-bootstrap-override.css - Timing: 213
2019-01-28 10:48:49,801 INFO lifecycle - event: {"request": "4", "session": "DBC42577A29073CAEB302ADBFD7F737C", "source": "service", "message": "end: handle", "time": "213 ms"}
2019-01-28 10:48:49,801 INFO lifecycle - event: {"request": "4", "session": "DBC42577A29073CAEB302ADBFD7F737C", "source": "limiter", "message": "end: nofilter", "time": "238 ms"}
2019-01-28 10:48:49,835 INFO ProcessorService - /fr/style/bootstrap/css/bootstrap.css - Timing: 279
2019-01-28 10:48:49,835 INFO lifecycle - event: {"request": "2", "session": "DBC42577A29073CAEB302ADBFD7F737C", "source": "service", "message": "end: handle", "time": "280 ms"}
2019-01-28 10:48:49,836 INFO lifecycle - event: {"request": "2", "session": "DBC42577A29073CAEB302ADBFD7F737C", "source": "limiter", "message": "end: nofilter", "time": "285 ms"}
2019-01-28 10:48:49,841 INFO lifecycle - event: {"request": "7", "session": "DBC42577A29073CAEB302ADBFD7F737C", "source": "limiter", "message": "start: nofilter", "path": "/apps/fr/style/orbeon-navbar-logo.png", "method": "GET"}
2019-01-28 10:48:49,850 WARN auth - not setting credentials headers because credentials are not found
2019-01-28 10:48:49,851 INFO lifecycle - event: {"request": "7", "session": "DBC42577A29073CAEB302ADBFD7F737C", "source": "service", "message": "start: handle"}
2019-01-28 10:48:49,851 INFO ProcessorService - /apps/fr/style/orbeon-navbar-logo.png - Received request
2019-01-28 10:48:49,850 INFO lifecycle - event: {"request": "11", "session": "DBC42577A29073CAEB302ADBFD7F737C", "source": "limiter", "message": "start: nofilter", "path": "/home/images/form-builder-small.jpg", "method": "GET"}
2019-01-28 10:48:49,854 WARN auth - not setting credentials headers because credentials are not found
2019-01-28 10:48:49,855 INFO lifecycle - event: {"request": "11", "session": "DBC42577A29073CAEB302ADBFD7F737C", "source": "service", "message": "start: handle"}
2019-01-28 10:48:49,855 INFO ProcessorService - /home/images/form-builder-small.jpg - Received request
2019-01-28 10:48:49,848 INFO lifecycle - event: {"request": "10", "session": "DBC42577A29073CAEB302ADBFD7F737C", "source": "limiter", "message": "start: nofilter", "path": "/home/images/fr-home-small.jpg", "method": "GET"}
2019-01-28 10:48:49,856 WARN auth - not setting credentials headers because credentials are not found
2019-01-28 10:48:49,848 INFO lifecycle - event: {"request": "9", "session": "DBC42577A29073CAEB302ADBFD7F737C", "source": "limiter", "message": "start: nofilter", "path": "/home/images/form-w9-small.jpg", "method": "GET"}
2019-01-28 10:48:49,845 INFO lifecycle - event: {"request": "8", "session": "DBC42577A29073CAEB302ADBFD7F737C", "source": "limiter", "message": "start: nofilter", "path": "/home/images/form-bookshelf-small.jpg", "method": "GET"}
2019-01-28 10:48:49,860 WARN auth - not setting credentials headers because credentials are not found
2019-01-28 10:48:49,860 INFO lifecycle - event: {"request": "8", "session": "DBC42577A29073CAEB302ADBFD7F737C", "source": "service", "message": "start: handle"}
2019-01-28 10:48:49,861 INFO ProcessorService - /home/images/form-bookshelf-small.jpg - Received request
2019-01-28 10:48:49,858 WARN auth - not setting credentials headers because credentials are not found
2019-01-28 10:48:49,858 INFO lifecycle - event: {"request": "10", "session": "DBC42577A29073CAEB302ADBFD7F737C", "source": "service", "message": "start: handle"}
2019-01-28 10:48:49,863 INFO ProcessorService - /home/images/fr-home-small.jpg - Received request
2019-01-28 10:48:49,863 INFO lifecycle - event: {"request": "9", "session": "DBC42577A29073CAEB302ADBFD7F737C", "source": "service", "message": "start: handle"}
2019-01-28 10:48:49,873 INFO ProcessorService - /home/images/form-w9-small.jpg - Received request
2019-01-28 10:48:49,885 INFO lifecycle - event: {"request": "12", "session": "DBC42577A29073CAEB302ADBFD7F737C", "source": "limiter", "message": "start: nofilter", "path": "/home/images/form-dmv14-small.jpg", "method": "GET"}
2019-01-28 10:48:49,886 WARN auth - not setting credentials headers because credentials are not found
2019-01-28 10:48:49,886 INFO lifecycle - event: {"request": "12", "session": "DBC42577A29073CAEB302ADBFD7F737C", "source": "service", "message": "start: handle"}
2019-01-28 10:48:49,887 INFO ProcessorService - /home/images/form-dmv14-small.jpg - Received request
2019-01-28 10:48:49,891 INFO ProcessorService - /home/images/fr-home-small.jpg - Timing: 28
2019-01-28 10:48:49,891 INFO lifecycle - event: {"request": "10", "session": "DBC42577A29073CAEB302ADBFD7F737C", "source": "service", "message": "end: handle", "time": "35 ms"}
2019-01-28 10:48:49,892 INFO lifecycle - event: {"request": "10", "session": "DBC42577A29073CAEB302ADBFD7F737C", "source": "limiter", "message": "end: nofilter", "time": "44 ms"}
2019-01-28 10:48:49,895 INFO ProcessorService - /apps/fr/style/orbeon-navbar-logo.png - Timing: 44
2019-01-28 10:48:49,896 INFO lifecycle - event: {"request": "7", "session": "DBC42577A29073CAEB302ADBFD7F737C", "source": "service", "message": "end: handle", "time": "44 ms"}
2019-01-28 10:48:49,903 INFO lifecycle - event: {"request": "13", "session": "DBC42577A29073CAEB302ADBFD7F737C", "source": "limiter", "message": "start: nofilter", "path": "/home/images/form-controls-small.jpg", "method": "GET"}
2019-01-28 10:48:49,906 INFO lifecycle - event: {"request": "7", "session": "DBC42577A29073CAEB302ADBFD7F737C", "source": "limiter", "message": "end: nofilter", "time": "65 ms"}
2019-01-28 10:48:49,920 INFO ProcessorService - /home/images/form-builder-small.jpg - Timing: 65
2019-01-28 10:48:49,921 INFO lifecycle - event: {"request": "11", "session": "DBC42577A29073CAEB302ADBFD7F737C", "source": "service", "message": "end: handle", "time": "65 ms"}
2019-01-28 10:48:49,921 INFO lifecycle - event: {"request": "11", "session": "DBC42577A29073CAEB302ADBFD7F737C", "source": "limiter", "message": "end: nofilter", "time": "71 ms"}
2019-01-28 10:48:49,922 INFO ProcessorService - /home/images/form-w9-small.jpg - Timing: 49
2019-01-28 10:48:49,923 INFO lifecycle - event: {"request": "9", "session": "DBC42577A29073CAEB302ADBFD7F737C", "source": "service", "message": "end: handle", "time": "59 ms"}
2019-01-28 10:48:49,923 INFO lifecycle - event: {"request": "9", "session": "DBC42577A29073CAEB302ADBFD7F737C", "source": "limiter", "message": "end: nofilter", "time": "75 ms"}
2019-01-28 10:48:49,925 INFO lifecycle - event: {"request": "14", "session": "DBC42577A29073CAEB302ADBFD7F737C", "source": "limiter", "message": "start: nofilter", "path": "/home/images/form-contact-small.jpg", "method": "GET"}
2019-01-28 10:48:49,925 WARN auth - not setting credentials headers because credentials are not found
2019-01-28 10:48:49,926 INFO lifecycle - event: {"request": "14", "session": "DBC42577A29073CAEB302ADBFD7F737C", "source": "service", "message": "start: handle"}
2019-01-28 10:48:49,926 INFO ProcessorService - /home/images/form-contact-small.jpg - Received request
2019-01-28 10:48:49,929 INFO ProcessorService - /home/images/form-bookshelf-small.jpg - Timing: 68
2019-01-28 10:48:49,930 INFO lifecycle - event: {"request": "8", "session": "DBC42577A29073CAEB302ADBFD7F737C", "source": "service", "message": "end: handle", "time": "70 ms"}
2019-01-28 10:48:49,933 INFO lifecycle - event: {"request": "8", "session": "DBC42577A29073CAEB302ADBFD7F737C", "source": "limiter", "message": "end: nofilter", "time": "87 ms"}
2019-01-28 10:48:49,941 WARN auth - not setting credentials headers because credentials are not found
2019-01-28 10:48:49,942 INFO lifecycle - event: {"request": "13", "session": "DBC42577A29073CAEB302ADBFD7F737C", "source": "service", "message": "start: handle"}
2019-01-28 10:48:49,942 INFO ProcessorService - /home/images/form-controls-small.jpg - Received request
2019-01-28 10:48:49,943 INFO ProcessorService - /home/images/form-dmv14-small.jpg - Timing: 57
2019-01-28 10:48:49,944 INFO lifecycle - event: {"request": "12", "session": "DBC42577A29073CAEB302ADBFD7F737C", "source": "service", "message": "end: handle", "time": "57 ms"}
2019-01-28 10:48:49,944 INFO lifecycle - event: {"request": "12", "session": "DBC42577A29073CAEB302ADBFD7F737C", "source": "limiter", "message": "end: nofilter", "time": "59 ms"}
2019-01-28 10:48:49,958 INFO ProcessorService - /home/images/form-contact-small.jpg - Timing: 32
2019-01-28 10:48:49,961 INFO lifecycle - event: {"request": "14", "session": "DBC42577A29073CAEB302ADBFD7F737C", "source": "service", "message": "end: handle", "time": "33 ms"}
2019-01-28 10:48:49,961 INFO lifecycle - event: {"request": "14", "session": "DBC42577A29073CAEB302ADBFD7F737C", "source": "limiter", "message": "end: nofilter", "time": "46 ms"}
2019-01-28 10:48:49,988 INFO ProcessorService - /home/images/form-controls-small.jpg - Timing: 46
2019-01-28 10:48:49,989 INFO lifecycle - event: {"request": "13", "session": "DBC42577A29073CAEB302ADBFD7F737C", "source": "service", "message": "end: handle", "time": "48 ms"}
2019-01-28 10:48:49,989 INFO lifecycle - event: {"request": "13", "session": "DBC42577A29073CAEB302ADBFD7F737C", "source": "limiter", "message": "end: nofilter", "time": "86 ms"}
2019-01-28 10:48:50,022 INFO lifecycle - event: {"request": "15", "session": "DBC42577A29073CAEB302ADBFD7F737C", "source": "limiter", "message": "start: nofilter", "path": "/ops/images/orbeon-icon-16.ico", "method": "GET"}
2019-01-28 10:48:50,022 WARN auth - not setting credentials headers because credentials are not found
2019-01-28 10:48:50,023 INFO lifecycle - event: {"request": "15", "session": "DBC42577A29073CAEB302ADBFD7F737C", "source": "service", "message": "start: handle"}
2019-01-28 10:48:50,035 INFO ProcessorService - /ops/images/orbeon-icon-16.ico - Received request
2019-01-28 10:48:50,051 INFO ProcessorService - /ops/images/orbeon-icon-16.ico - Timing: 16
2019-01-28 10:48:50,052 INFO lifecycle - event: {"request": "15", "session": "DBC42577A29073CAEB302ADBFD7F737C", "source": "service", "message": "end: handle", "time": "29 ms"}
2019-01-28 10:48:50,052 INFO lifecycle - event: {"request": "15", "session": "DBC42577A29073CAEB302ADBFD7F737C", "source": "limiter", "message": "end: nofilter", "time": "31 ms"}
2019-01-28 10:48:54,438 WARN auth - not setting credentials headers because credentials are not found
2019-01-28 10:48:54,441 INFO lifecycle - event: {"request": "16", "session": "F3D6BB230E4535565E92EEC8E37821E3", "source": "service", "message": "start: handle", "path": "/fr/login", "method": "GET"}
2019-01-28 10:48:54,442 INFO ProcessorService - /fr/login - Received request
2019-01-28 10:49:00,533 WARN Version - Feature is not enabled in this version of the product: cached XForms instance
2019-01-28 10:49:02,274 WARN XFormsServer - unknown id {id: "fb-form-instance"}
2019-01-28 10:49:02,417 WARN Version - Feature is not enabled in this version of the product: xpath-analysis
2019-01-28 10:49:02,701 INFO lifecycle - event: {"request": "16", "session": "F3D6BB230E4535565E92EEC8E37821E3", "source": "xforms", "message": "new form session", "uuid": "6f55831b8b38db4c5c478a7e51ad6fcf0dc3f529"}
2019-01-28 10:49:03,238 WARN XFormsServer - xxf:instance() - instance not found {instance id: "fr-form-attachments"}
2019-01-28 10:49:03,340 WARN XFormsServer - xxf:instance() - instance not found {instance id: "fr-form-resources"}
2019-01-28 10:49:03,353 WARN XFormsServer - xxf:instance() - instance not found {instance id: "fr-form-resources"}
2019-01-28 10:49:03,470 WARN XFormsServer - xxf:instance() - instance not found {instance id: "fr-form-resources"}
2019-01-28 10:49:03,691 INFO lifecycle - event: {"request": "17", "session": "F3D6BB230E4535565E92EEC8E37821E3", "source": "service", "message": "start: handle", "path": "/fr/service/i18n/fr-resources/*/*", "method": "GET"}
2019-01-28 10:49:03,691 INFO ProcessorService - /fr/service/i18n/fr-resources/*/* - Received request
2019-01-28 10:49:04,615 INFO ProcessorService - /fr/service/i18n/fr-resources/*/* - Timing: 923
2019-01-28 10:49:04,616 INFO lifecycle - event: {"request": "17", "session": "F3D6BB230E4535565E92EEC8E37821E3", "source": "service", "message": "end: handle", "time": "930 ms"}
2019-01-28 10:49:04,898 WARN XFormsServer - xxf:instance() - instance not found {instance id: "fr-form-resources"}
2019-01-28 10:49:04,926 WARN XFormsServer - xxf:instance() - instance not found {instance id: "fr-form-resources"}
2019-01-28 10:49:05,028 WARN XFormsServer - xxf:instance() - instance not found {instance id: "fr-form-resources"}
2019-01-28 10:49:05,031 WARN XFormsServer - xxf:instance() - instance not found {instance id: "fr-form-resources"}
2019-01-28 10:49:06,131 WARN XFormsServer - xxf:instance() - instance not found {instance id: "fr-form-metadata"}
2019-01-28 10:49:06,211 WARN XFormsServer - xxf:instance() - instance not found {instance id: "fr-form-attachments"}
2019-01-28 10:49:06,483 WARN XFormsServer - xxf:instance() - instance not found {instance id: "fr-form-attachments"}
2019-01-28 10:49:08,626 INFO lifecycle - event: {"request": "16", "session": "F3D6BB230E4535565E92EEC8E37821E3", "source": "xforms", "message": "after cacheOrStore", "document cache current size": "1", "document cache max size": "50"}
2019-01-28 10:49:09,033 INFO ProcessorService - /fr/login - Timing: 14592
2019-01-28 10:49:09,034 INFO lifecycle - event: {"request": "16", "session": "F3D6BB230E4535565E92EEC8E37821E3", "source": "service", "message": "end: handle", "time": "14,593 ms"}
2019-01-28 10:49:09,093 INFO lifecycle - event: {"request": "18", "session": "F3D6BB230E4535565E92EEC8E37821E3", "source": "limiter", "message": "start: nofilter", "path": "/xforms-server/form/dynamic/6f55831b8b38db4c5c478a7e51ad6fcf0dc3f529.js", "method": "GET"}
2019-01-28 10:49:09,093 WARN auth - not setting credentials headers because credentials are not found
2019-01-28 10:49:09,094 INFO lifecycle - event: {"request": "18", "session": "F3D6BB230E4535565E92EEC8E37821E3", "source": "service", "message": "start: handle"}
2019-01-28 10:49:09,094 INFO ProcessorService - /xforms-server/form/dynamic/6f55831b8b38db4c5c478a7e51ad6fcf0dc3f529.js - Received request
2019-01-28 10:49:09,101 INFO lifecycle - event: {"request": "19", "session": "F3D6BB230E4535565E92EEC8E37821E3", "source": "limiter", "message": "start: nofilter", "path": "/xforms-server/orbeon-6223253ad3b290874a3b95740396c687134f4e3a.css", "method": "GET"}
2019-01-28 10:49:09,102 WARN auth - not setting credentials headers because credentials are not found
2019-01-28 10:49:09,102 INFO lifecycle - event: {"request": "19", "session": "F3D6BB230E4535565E92EEC8E37821E3", "source": "service", "message": "start: handle"}
2019-01-28 10:49:09,103 INFO ProcessorService - /xforms-server/orbeon-6223253ad3b290874a3b95740396c687134f4e3a.css - Received request
2019-01-28 10:49:09,117 INFO lifecycle - event: {"request": "20", "session": "F3D6BB230E4535565E92EEC8E37821E3", "source": "limiter", "message": "start: nofilter", "path": "/xforms-server/orbeon-545888a175584a08ed680406a53bc37e9cefe6bd.css", "method": "GET"}
2019-01-28 10:49:09,117 WARN auth - not setting credentials headers because credentials are not found
2019-01-28 10:49:09,118 INFO lifecycle - event: {"request": "20", "session": "F3D6BB230E4535565E92EEC8E37821E3", "source": "service", "message": "start: handle"}
2019-01-28 10:49:09,118 INFO ProcessorService - /xforms-server/orbeon-545888a175584a08ed680406a53bc37e9cefe6bd.css - Received request
2019-01-28 10:49:09,160 INFO lifecycle - event: {"request": "21", "session": "F3D6BB230E4535565E92EEC8E37821E3", "source": "limiter", "message": "start: nofilter", "path": "/xforms-server/form/static/c4066f544741fe0638c42a7f9af9e37a295a7e95.js", "method": "GET"}
2019-01-28 10:49:09,187 WARN auth - not setting credentials headers because credentials are not found
2019-01-28 10:49:09,187 INFO lifecycle - event: {"request": "21", "session": "F3D6BB230E4535565E92EEC8E37821E3", "source": "service", "message": "start: handle"}
2019-01-28 10:49:09,188 INFO ProcessorService - /xforms-server/form/static/c4066f544741fe0638c42a7f9af9e37a295a7e95.js - Received request
2019-01-28 10:49:09,241 INFO lifecycle - event: {"request": "22", "session": "F3D6BB230E4535565E92EEC8E37821E3", "source": "limiter", "message": "start: nofilter", "path": "/xforms-server/orbeon-cf293d64d9874600247415c1dc7f2f09c75e83ad.js", "method": "GET"}
2019-01-28 10:49:09,241 WARN auth - not setting credentials headers because credentials are not found
2019-01-28 10:49:09,242 INFO lifecycle - event: {"request": "22", "session": "F3D6BB230E4535565E92EEC8E37821E3", "source": "service", "message": "start: handle"}
2019-01-28 10:49:09,242 INFO ProcessorService - /xforms-server/orbeon-cf293d64d9874600247415c1dc7f2f09c75e83ad.js - Received request
2019-01-28 10:49:09,255 INFO lifecycle - event: {"request": "23", "session": "F3D6BB230E4535565E92EEC8E37821E3", "source": "limiter", "message": "start: nofilter", "path": "/xforms-server/orbeon-081562a10ec172c2b5364febba2f8b71fd194a1a.js", "method": "GET"}
2019-01-28 10:49:09,255 WARN auth - not setting credentials headers because credentials are not found
2019-01-28 10:49:09,255 INFO lifecycle - event: {"request": "23", "session": "F3D6BB230E4535565E92EEC8E37821E3", "source": "service", "message": "start: handle"}
2019-01-28 10:49:09,256 INFO ProcessorService - /xforms-server/orbeon-081562a10ec172c2b5364febba2f8b71fd194a1a.js - Received request
2019-01-28 10:49:09,482 INFO lifecycle - event: {"request": "18", "session": "F3D6BB230E4535565E92EEC8E37821E3", "source": "xforms", "message": "before document lock", "uuid": "6f55831b8b38db4c5c478a7e51ad6fcf0dc3f529"}
2019-01-28 10:49:09,498 INFO lifecycle - event: {"request": "18", "session": "F3D6BB230E4535565E92EEC8E37821E3", "source": "xforms", "message": "got document lock", "path": "/xforms-server/form/dynamic/6f55831b8b38db4c5c478a7e51ad6fcf0dc3f529.js", "method": "GET", "uuid": "6f55831b8b38db4c5c478a7e51ad6fcf0dc3f529", "wait": "0"}
2019-01-28 10:49:09,519 INFO ProcessorService - /xforms-server/form/static/c4066f544741fe0638c42a7f9af9e37a295a7e95.js - Timing: 331
2019-01-28 10:49:09,520 INFO lifecycle - event: {"request": "21", "session": "F3D6BB230E4535565E92EEC8E37821E3", "source": "service", "message": "end: handle", "time": "333 ms"}
2019-01-28 10:49:09,520 INFO lifecycle - event: {"request": "21", "session": "F3D6BB230E4535565E92EEC8E37821E3", "source": "limiter", "message": "end: nofilter", "time": "361 ms"}
2019-01-28 10:49:09,521 INFO ProcessorService - /xforms-server/orbeon-081562a10ec172c2b5364febba2f8b71fd194a1a.js - Timing: 265
2019-01-28 10:49:09,521 INFO lifecycle - event: {"request": "18", "session": "F3D6BB230E4535565E92EEC8E37821E3", "source": "xforms", "message": "after cacheOrStore", "document cache current size": "1", "document cache max size": "50"}
2019-01-28 10:49:09,538 INFO ProcessorService - /xforms-server/form/dynamic/6f55831b8b38db4c5c478a7e51ad6fcf0dc3f529.js - Timing: 444
2019-01-28 10:49:09,540 INFO lifecycle - event: {"request": "18", "session": "F3D6BB230E4535565E92EEC8E37821E3", "source": "service", "message": "end: handle", "time": "445 ms"}
2019-01-28 10:49:09,540 INFO lifecycle - event: {"request": "18", "session": "F3D6BB230E4535565E92EEC8E37821E3", "source": "limiter", "message": "end: nofilter", "time": "448 ms"}
2019-01-28 10:49:09,547 INFO lifecycle - event: {"request": "23", "session": "F3D6BB230E4535565E92EEC8E37821E3", "source": "service", "message": "end: handle", "time": "267 ms"}
2019-01-28 10:49:09,547 INFO lifecycle - event: {"request": "23", "session": "F3D6BB230E4535565E92EEC8E37821E3", "source": "limiter", "message": "end: nofilter", "time": "292 ms"}
2019-01-28 10:49:09,654 INFO ProcessorService - /xforms-server/orbeon-cf293d64d9874600247415c1dc7f2f09c75e83ad.js - Timing: 412
2019-01-28 10:49:09,655 INFO lifecycle - event: {"request": "22", "session": "F3D6BB230E4535565E92EEC8E37821E3", "source": "service", "message": "end: handle", "time": "414 ms"}
2019-01-28 10:49:09,663 INFO lifecycle - event: {"request": "22", "session": "F3D6BB230E4535565E92EEC8E37821E3", "source": "limiter", "message": "end: nofilter", "time": "415 ms"}
2019-01-28 10:49:10,032 INFO ProcessorService - /xforms-server/orbeon-545888a175584a08ed680406a53bc37e9cefe6bd.css - Timing: 914
2019-01-28 10:49:10,032 INFO lifecycle - event: {"request": "20", "session": "F3D6BB230E4535565E92EEC8E37821E3", "source": "service", "message": "end: handle", "time": "914 ms"}
2019-01-28 10:49:10,033 INFO lifecycle - event: {"request": "20", "session": "F3D6BB230E4535565E92EEC8E37821E3", "source": "limiter", "message": "end: nofilter", "time": "915 ms"}
2019-01-28 10:49:10,075 INFO lifecycle - event: {"request": "24", "session": "F3D6BB230E4535565E92EEC8E37821E3", "source": "limiter", "message": "start: nofilter", "path": "/apps/fr/style/images/pixelmixer/info_64.png", "method": "GET"}
2019-01-28 10:49:10,076 WARN auth - not setting credentials headers because credentials are not found
2019-01-28 10:49:10,076 INFO lifecycle - event: {"request": "24", "session": "F3D6BB230E4535565E92EEC8E37821E3", "source": "service", "message": "start: handle"}
2019-01-28 10:49:10,076 INFO ProcessorService - /apps/fr/style/images/pixelmixer/info_64.png - Received request
2019-01-28 10:49:10,083 INFO lifecycle - event: {"request": "25", "session": "F3D6BB230E4535565E92EEC8E37821E3", "source": "limiter", "message": "start: nofilter", "path": "/apps/fr/style/images/pixelmixer/bubble_64.png", "method": "GET"}
2019-01-28 10:49:10,083 INFO lifecycle - event: {"request": "26", "session": "F3D6BB230E4535565E92EEC8E37821E3", "source": "limiter", "message": "start: nofilter", "path": "/ops/images/xforms/section-opened.png", "method": "GET"}
2019-01-28 10:49:10,083 WARN auth - not setting credentials headers because credentials are not found
2019-01-28 10:49:10,084 INFO lifecycle - event: {"request": "26", "session": "F3D6BB230E4535565E92EEC8E37821E3", "source": "service", "message": "start: handle"}
2019-01-28 10:49:10,084 INFO ProcessorService - /ops/images/xforms/section-opened.png - Received request
2019-01-28 10:49:10,084 INFO lifecycle - event: {"request": "27", "session": "F3D6BB230E4535565E92EEC8E37821E3", "source": "limiter", "message": "start: nofilter", "path": "/ops/images/xforms/section-closed.png", "method": "GET"}
2019-01-28 10:49:10,084 WARN auth - not setting credentials headers because credentials are not found
2019-01-28 10:49:10,085 INFO lifecycle - event: {"request": "27", "session": "F3D6BB230E4535565E92EEC8E37821E3", "source": "service", "message": "start: handle"}
2019-01-28 10:49:10,085 INFO ProcessorService - /ops/images/xforms/section-closed.png - Received request
2019-01-28 10:49:10,083 WARN auth - not setting credentials headers because credentials are not found
2019-01-28 10:49:10,086 INFO lifecycle - event: {"request": "25", "session": "F3D6BB230E4535565E92EEC8E37821E3", "source": "service", "message": "start: handle"}
2019-01-28 10:49:10,086 INFO ProcessorService - /apps/fr/style/images/pixelmixer/bubble_64.png - Received request
2019-01-28 10:49:10,096 INFO ProcessorService - /ops/images/xforms/section-closed.png - Timing: 11
2019-01-28 10:49:10,097 INFO lifecycle - event: {"request": "27", "session": "F3D6BB230E4535565E92EEC8E37821E3", "source": "service", "message": "end: handle", "time": "12 ms"}
2019-01-28 10:49:10,097 INFO lifecycle - event: {"request": "27", "session": "F3D6BB230E4535565E92EEC8E37821E3", "source": "limiter", "message": "end: nofilter", "time": "13 ms"}
2019-01-28 10:49:10,103 INFO ProcessorService - /ops/images/xforms/section-opened.png - Timing: 19
2019-01-28 10:49:10,103 INFO lifecycle - event: {"request": "26", "session": "F3D6BB230E4535565E92EEC8E37821E3", "source": "service", "message": "end: handle", "time": "19 ms"}
2019-01-28 10:49:10,103 INFO lifecycle - event: {"request": "26", "session": "F3D6BB230E4535565E92EEC8E37821E3", "source": "limiter", "message": "end: nofilter", "time": "20 ms"}
2019-01-28 10:49:10,105 INFO ProcessorService - /xforms-server/orbeon-6223253ad3b290874a3b95740396c687134f4e3a.css - Timing: 1002
2019-01-28 10:49:10,107 INFO lifecycle - event: {"request": "19", "session": "F3D6BB230E4535565E92EEC8E37821E3", "source": "service", "message": "end: handle", "time": "1,004 ms"}
2019-01-28 10:49:10,107 INFO lifecycle - event: {"request": "19", "session": "F3D6BB230E4535565E92EEC8E37821E3", "source": "limiter", "message": "end: nofilter", "time": "1,006 ms"}
2019-01-28 10:49:10,108 INFO lifecycle - event: {"request": "28", "session": "F3D6BB230E4535565E92EEC8E37821E3", "source": "limiter", "message": "start: nofilter", "path": "/ops/images/xforms/warning_64.png", "method": "GET"}
2019-01-28 10:49:10,108 WARN auth - not setting credentials headers because credentials are not found
2019-01-28 10:49:10,109 INFO lifecycle - event: {"request": "28", "session": "F3D6BB230E4535565E92EEC8E37821E3", "source": "service", "message": "start: handle"}
2019-01-28 10:49:10,109 INFO ProcessorService - /ops/images/xforms/warning_64.png - Received request
2019-01-28 10:49:10,115 INFO ProcessorService - /apps/fr/style/images/pixelmixer/info_64.png - Timing: 39
2019-01-28 10:49:10,115 INFO ProcessorService - /apps/fr/style/images/pixelmixer/bubble_64.png - Timing: 29
2019-01-28 10:49:10,115 INFO lifecycle - event: {"request": "25", "session": "F3D6BB230E4535565E92EEC8E37821E3", "source": "service", "message": "end: handle", "time": "29 ms"}
2019-01-28 10:49:10,115 INFO lifecycle - event: {"request": "24", "session": "F3D6BB230E4535565E92EEC8E37821E3", "source": "service", "message": "end: handle", "time": "39 ms"}
2019-01-28 10:49:10,116 INFO lifecycle - event: {"request": "25", "session": "F3D6BB230E4535565E92EEC8E37821E3", "source": "limiter", "message": "end: nofilter", "time": "32 ms"}
2019-01-28 10:49:10,116 INFO lifecycle - event: {"request": "24", "session": "F3D6BB230E4535565E92EEC8E37821E3", "source": "limiter", "message": "end: nofilter", "time": "41 ms"}
2019-01-28 10:49:10,138 INFO ProcessorService - /ops/images/xforms/warning_64.png - Timing: 29
2019-01-28 10:49:10,139 INFO lifecycle - event: {"request": "28", "session": "F3D6BB230E4535565E92EEC8E37821E3", "source": "service", "message": "end: handle", "time": "30 ms"}
2019-01-28 10:49:10,141 INFO lifecycle - event: {"request": "28", "session": "F3D6BB230E4535565E92EEC8E37821E3", "source": "limiter", "message": "end: nofilter", "time": "32 ms"}
2019-01-28 10:49:10,708 INFO lifecycle - event: {"request": "29", "session": "F3D6BB230E4535565E92EEC8E37821E3", "source": "limiter", "message": "start: nofilter", "path": "/apps/fr/style/font-awesome/fonts/fontawesome-webfont.woff2", "method": "GET"}
2019-01-28 10:49:10,708 WARN auth - not setting credentials headers because credentials are not found
2019-01-28 10:49:10,709 INFO lifecycle - event: {"request": "29", "session": "F3D6BB230E4535565E92EEC8E37821E3", "source": "service", "message": "start: handle"}
2019-01-28 10:49:10,709 INFO ProcessorService - /apps/fr/style/font-awesome/fonts/fontawesome-webfont.woff2 - Received request
2019-01-28 10:49:10,748 INFO ProcessorService - /apps/fr/style/font-awesome/fonts/fontawesome-webfont.woff2 - Timing: 39
2019-01-28 10:49:10,748 INFO lifecycle - event: {"request": "29", "session": "F3D6BB230E4535565E92EEC8E37821E3", "source": "service", "message": "end: handle", "time": "40 ms"}
2019-01-28 10:49:10,748 INFO lifecycle - event: {"request": "29", "session": "F3D6BB230E4535565E92EEC8E37821E3", "source": "limiter", "message": "end: nofilter", "time": "41 ms"}
2019-01-28 10:51:57,991 INFO lifecycle - event: {"request": "30", "session": "F3D6BB230E4535565E92EEC8E37821E3", "source": "limiter", "message": "start: filter", "path": "/xforms-server", "method": "POST"}
2019-01-28 10:51:57,998 INFO lifecycle - event: {"request": "30", "session": "F3D6BB230E4535565E92EEC8E37821E3", "source": "limiter", "message": "start: chain", "path": "/xforms-server", "method": "POST", "wait": "0"}
2019-01-28 10:51:58,011 WARN auth - not setting credentials headers because credentials are not found
2019-01-28 10:51:58,011 INFO lifecycle - event: {"request": "30", "session": "F3D6BB230E4535565E92EEC8E37821E3", "source": "service", "message": "start: handle"}
2019-01-28 10:51:58,011 INFO ProcessorService - /xforms-server - Received request
2019-01-28 10:51:58,343 INFO lifecycle - event: {"request": "30", "session": "F3D6BB230E4535565E92EEC8E37821E3", "source": "xforms", "message": "ajax with update events", "uuid": "6f55831b8b38db4c5c478a7e51ad6fcf0dc3f529"}
2019-01-28 10:51:58,356 INFO lifecycle - event: {"request": "30", "session": "F3D6BB230E4535565E92EEC8E37821E3", "source": "xforms", "message": "before document lock", "uuid": "6f55831b8b38db4c5c478a7e51ad6fcf0dc3f529"}
2019-01-28 10:51:58,357 INFO lifecycle - event: {"request": "30", "session": "F3D6BB230E4535565E92EEC8E37821E3", "source": "xforms", "message": "got document lock", "path": "/xforms-server", "method": "POST", "uuid": "6f55831b8b38db4c5c478a7e51ad6fcf0dc3f529", "wait": "0"}
2019-01-28 10:51:59,029 INFO lifecycle - event: {"request": "30", "session": "F3D6BB230E4535565E92EEC8E37821E3", "source": "xforms", "message": "after cacheOrStore", "document cache current size": "1", "document cache max size": "50"}
2019-01-28 10:51:59,029 INFO ProcessorService - /xforms-server - Timing: 1018
2019-01-28 10:51:59,030 INFO lifecycle - event: {"request": "30", "session": "F3D6BB230E4535565E92EEC8E37821E3", "source": "service", "message": "end: handle", "time": "1,018 ms"}
2019-01-28 10:51:59,031 INFO lifecycle - event: {"request": "30", "session": "F3D6BB230E4535565E92EEC8E37821E3", "source": "limiter", "message": "end: chain", "time": "1,033 ms"}
2019-01-28 10:51:59,032 INFO lifecycle - event: {"request": "30", "session": "F3D6BB230E4535565E92EEC8E37821E3", "source": "limiter", "message": "end: filter", "time": "1,041 ms"}
2019-01-28 10:52:01,519 INFO lifecycle - event: {"request": "31", "session": "F3D6BB230E4535565E92EEC8E37821E3", "source": "limiter", "message": "start: filter", "path": "/xforms-server", "method": "POST"}
2019-01-28 10:52:01,520 INFO lifecycle - event: {"request": "31", "session": "F3D6BB230E4535565E92EEC8E37821E3", "source": "limiter", "message": "start: chain", "path": "/xforms-server", "method": "POST", "wait": "0"}
2019-01-28 10:52:01,520 WARN auth - not setting credentials headers because credentials are not found
2019-01-28 10:52:01,520 INFO lifecycle - event: {"request": "31", "session": "F3D6BB230E4535565E92EEC8E37821E3", "source": "service", "message": "start: handle"}
2019-01-28 10:52:01,521 INFO ProcessorService - /xforms-server - Received request
2019-01-28 10:52:01,547 INFO lifecycle - event: {"request": "31", "session": "F3D6BB230E4535565E92EEC8E37821E3", "source": "xforms", "message": "ajax with update events", "uuid": "6f55831b8b38db4c5c478a7e51ad6fcf0dc3f529"}
2019-01-28 10:52:01,548 INFO lifecycle - event: {"request": "31", "session": "F3D6BB230E4535565E92EEC8E37821E3", "source": "xforms", "message": "before document lock", "uuid": "6f55831b8b38db4c5c478a7e51ad6fcf0dc3f529"}
2019-01-28 10:52:01,548 INFO lifecycle - event: {"request": "31", "session": "F3D6BB230E4535565E92EEC8E37821E3", "source": "xforms", "message": "got document lock", "path": "/xforms-server", "method": "POST", "uuid": "6f55831b8b38db4c5c478a7e51ad6fcf0dc3f529", "wait": "0"}
2019-01-28 10:52:01,680 INFO lifecycle - event: {"request": "31", "session": "F3D6BB230E4535565E92EEC8E37821E3", "source": "xforms", "message": "after cacheOrStore", "document cache current size": "1", "document cache max size": "50"}
2019-01-28 10:52:01,680 INFO ProcessorService - /xforms-server - Timing: 159
2019-01-28 10:52:01,680 INFO lifecycle - event: {"request": "31", "session": "F3D6BB230E4535565E92EEC8E37821E3", "source": "service", "message": "end: handle", "time": "160 ms"}
2019-01-28 10:52:01,681 INFO lifecycle - event: {"request": "31", "session": "F3D6BB230E4535565E92EEC8E37821E3", "source": "limiter", "message": "end: chain", "time": "161 ms"}
2019-01-28 10:52:01,683 INFO lifecycle - event: {"request": "31", "session": "F3D6BB230E4535565E92EEC8E37821E3", "source": "limiter", "message": "end: filter", "time": "163 ms"}
2019-01-28 10:52:13,652 INFO lifecycle - event: {"request": "32", "session": "F3D6BB230E4535565E92EEC8E37821E3", "source": "limiter", "message": "start: filter", "path": "/xforms-server", "method": "POST"}
2019-01-28 10:52:13,652 INFO lifecycle - event: {"request": "32", "session": "F3D6BB230E4535565E92EEC8E37821E3", "source": "limiter", "message": "start: chain", "path": "/xforms-server", "method": "POST", "wait": "0"}
2019-01-28 10:52:13,705 WARN auth - not setting credentials headers because credentials are not found
2019-01-28 10:52:13,712 INFO lifecycle - event: {"request": "32", "session": "F3D6BB230E4535565E92EEC8E37821E3", "source": "service", "message": "start: handle"}
2019-01-28 10:52:13,713 INFO ProcessorService - /xforms-server - Received request
2019-01-28 10:52:13,753 INFO lifecycle - event: {"request": "32", "session": "F3D6BB230E4535565E92EEC8E37821E3", "source": "xforms", "message": "ajax with update events", "uuid": "6f55831b8b38db4c5c478a7e51ad6fcf0dc3f529"}
2019-01-28 10:52:13,753 INFO lifecycle - event: {"request": "32", "session": "F3D6BB230E4535565E92EEC8E37821E3", "source": "xforms", "message": "before document lock", "uuid": "6f55831b8b38db4c5c478a7e51ad6fcf0dc3f529"}
2019-01-28 10:52:13,754 INFO lifecycle - event: {"request": "32", "session": "F3D6BB230E4535565E92EEC8E37821E3", "source": "xforms", "message": "got document lock", "path": "/xforms-server", "method": "POST", "uuid": "6f55831b8b38db4c5c478a7e51ad6fcf0dc3f529", "wait": "0"}
2019-01-28 10:52:13,923 INFO lifecycle - event: {"request": "32", "session": "F3D6BB230E4535565E92EEC8E37821E3", "source": "xforms", "message": "after cacheOrStore", "document cache current size": "1", "document cache max size": "50"}
2019-01-28 10:52:13,923 INFO ProcessorService - /xforms-server - Timing: 211
2019-01-28 10:52:13,924 INFO lifecycle - event: {"request": "32", "session": "F3D6BB230E4535565E92EEC8E37821E3", "source": "service", "message": "end: handle", "time": "218 ms"}
2019-01-28 10:52:13,924 INFO lifecycle - event: {"request": "32", "session": "F3D6BB230E4535565E92EEC8E37821E3", "source": "limiter", "message": "end: chain", "time": "272 ms"}
2019-01-28 10:52:13,924 INFO lifecycle - event: {"request": "32", "session": "F3D6BB230E4535565E92EEC8E37821E3", "source": "limiter", "message": "end: filter", "time": "273 ms"}
2019-01-28 10:52:14,026 INFO lifecycle - event: {"request": "33", "session": "8547B101A7AF9EEE6DFDB8E45CB47568", "source": "limiter", "message": "start: filter", "path": "/fr/orbeon/bookshelf/summary", "method": "GET"}
2019-01-28 10:52:14,026 INFO lifecycle - event: {"request": "33", "session": "8547B101A7AF9EEE6DFDB8E45CB47568", "source": "limiter", "message": "start: chain", "path": "/fr/orbeon/bookshelf/summary", "method": "GET", "wait": "0"}
2019-01-28 10:52:14,068 INFO lifecycle - event: {"request": "33", "session": "8547B101A7AF9EEE6DFDB8E45CB47568", "source": "service", "message": "start: handle"}
2019-01-28 10:52:14,069 INFO ProcessorService - /fr/orbeon/bookshelf/summary - Received request
2019-01-28 10:52:15,862 WARN XFormsServer - unknown id {id: "fb-form-instance"}
2019-01-28 10:52:15,967 INFO lifecycle - event: {"request": "33", "session": "8547B101A7AF9EEE6DFDB8E45CB47568", "source": "xforms", "message": "new form session", "uuid": "19c5f15ce5e7bcaba67feef82bdb80a3e5a5a446"}
2019-01-28 10:52:16,013 WARN XFormsServer - xxf:instance() - instance not found {instance id: "fr-form-attachments"}
2019-01-28 10:52:16,211 INFO lifecycle - event: {"request": "34", "session": "8547B101A7AF9EEE6DFDB8E45CB47568", "source": "service", "message": "start: handle", "path": "/fr/service/i18n/fr-resources/orbeon/bookshelf", "method": "GET"}
2019-01-28 10:52:16,211 INFO ProcessorService - /fr/service/i18n/fr-resources/orbeon/bookshelf - Received request
2019-01-28 10:52:16,438 INFO ProcessorService - /fr/service/i18n/fr-resources/orbeon/bookshelf - Timing: 227
2019-01-28 10:52:16,442 INFO lifecycle - event: {"request": "34", "session": "8547B101A7AF9EEE6DFDB8E45CB47568", "source": "service", "message": "end: handle", "time": "231 ms"}
2019-01-28 10:52:16,550 INFO lifecycle - event: {"request": "35", "session": "8547B101A7AF9EEE6DFDB8E45CB47568", "source": "service", "message": "start: handle", "path": "/fr/service/persistence/form/orbeon/bookshelf", "method": "GET"}
2019-01-28 10:52:16,550 INFO ProcessorService - /fr/service/persistence/form/orbeon/bookshelf - Received request
2019-01-28 10:52:16,768 INFO lifecycle - event: {"request": "36", "session": "8547B101A7AF9EEE6DFDB8E45CB47568", "source": "service", "message": "start: handle", "path": "/fr/service/exist/form/orbeon/bookshelf", "method": "GET"}
2019-01-28 10:52:16,768 INFO ProcessorService - /fr/service/exist/form/orbeon/bookshelf - Received request
2019-01-28 10:52:18,260 INFO EXistServlet - EXistServlet: exist.home=/opt/tomcat-8.5.37_orbeon_default/webapps/orbeon/WEB-INF/
2019-01-28 10:52:18,260 INFO EXistServlet - reading configuration from /opt/tomcat-8.5.37_orbeon_default/webapps/orbeon/WEB-INF/exist-conf.xml
2019-01-28 10:52:18,340 INFO Configuration - Reading configuration from file /opt/tomcat-8.5.37_orbeon_default/webapps/orbeon/WEB-INF/exist-conf.xml
2019-01-28 10:52:18,983 WARN XQueryContext - Configuration problem: failed to load class for module http://exist-db.org/xquery/xqdoc; class: org.exist.xqdoc.xquery.XQDocModule; message: org.exist.xqdoc.xquery.XQDocModule
2019-01-28 10:52:18,983 INFO EXistServlet - configuring eXist instance
2019-01-28 10:52:19,116 INFO SimpleThreadPool - Job execution threads will use class loader of thread: http-nio-8080-exec-2
2019-01-28 10:52:19,170 INFO SchedulerSignalerImpl - Initialized Scheduler Signaller of type: class org.quartz.core.SchedulerSignalerImpl
2019-01-28 10:52:19,170 INFO QuartzScheduler - Quartz Scheduler v.1.6.6 created.
2019-01-28 10:52:19,171 INFO RAMJobStore - RAMJobStore initialized.
2019-01-28 10:52:19,171 INFO StdSchedulerFactory - Quartz scheduler 'exist_QuartzScheduler' initialized from an externally provided properties instance.
2019-01-28 10:52:19,171 INFO StdSchedulerFactory - Quartz scheduler version: 1.6.6
2019-01-28 10:52:19,199 INFO DefaultCacheManager - Cache settings: 20,480k; totalPages: 5,120; maxCacheSize: 4,608
2019-01-28 10:52:19,238 INFO XQueryPool - QueryPool: size = 128; maxStackSize = 5; timeout = 120,000; timeoutCheckInterval = 30,000
2019-01-28 10:52:19,502 INFO IndexManager - Registered index org.exist.indexing.ngram.NGramIndex as ngram-index
2019-01-28 10:52:19,811 INFO IndexManager - Registered index org.exist.indexing.lucene.LuceneIndex as lucene-index
2019-01-28 10:52:19,832 INFO IndexManager - Registered index org.exist.fulltext.FTIndex as ft-legacy-index
2019-01-28 10:52:20,360 INFO QuartzScheduler - Scheduler exist_QuartzScheduler_$_NON_CLUSTERED started.
2019-01-28 10:52:20,363 INFO EXistServlet - registering XMLDB driver
2019-01-28 10:52:20,399 INFO EXistServlet - Using default user guest for all unauthorized requests.
2019-01-28 10:52:20,425 WARN XmlLibraryChecker - Looking for a valid Parser...
Checking for Xerces, found version Xerces-J 2.11.0 needed version Xerces-J 2.9.0
Warning: Failed find a valid Parser!
Please add an appropriate Parser to the class-path, e.g. in the 'endorsed' folder of the servlet container or in the 'endorsed' folder of the JRE.
2019-01-28 10:52:20,427 INFO XmlLibraryChecker - Looking for a valid Transformer...
Checking for Saxon, found version 9.1.0.8
OK!
2019-01-28 10:52:20,428 INFO XmlLibraryChecker - Looking for a valid Resolver...
Checking for Resolver, found version XmlResolver 1.2
OK!
2019-01-28 10:52:20,430 INFO XmlLibraryChecker - Using parser org.orbeon.oxf.xml.xerces.XercesSAXParser
2019-01-28 10:52:20,464 INFO XmlLibraryChecker - Using transformer com.sun.org.apache.xalan.internal.xsltc.trax.TransformerImpl
2019-01-28 10:52:20,485 INFO lifecycle - event: {"request": "37", "source": "limiter", "message": "start: nofilter", "path": "/exist/rest/db/orbeon/fr", "method": "POST"}
2019-01-28 10:52:20,485 INFO ProcessorService - Session listener - Session created.
2019-01-28 10:52:20,498 WARN auth - not setting credentials headers because credentials are not found
2019-01-28 10:52:20,501 WARN Descriptor - Giving up unable to read descriptor file from /opt/tomcat-8.5.37_orbeon_default/webapps/orbeon/WEB-INF/descriptor.xml
2019-01-28 10:52:20,504 WARN Descriptor - Giving up unable to read descriptor.xml file from classloader in package org.exist.http
2019-01-28 10:52:21,211 INFO lifecycle - event: {"request": "37", "session": "4BE3E91834CA67B251446ED282EC4229", "source": "limiter", "message": "end: nofilter", "time": "725 ms"}
2019-01-28 10:52:21,544 INFO ProcessorService - /fr/service/exist/form/orbeon/bookshelf - Timing: 4776
2019-01-28 10:52:21,545 INFO lifecycle - event: {"request": "36", "session": "8547B101A7AF9EEE6DFDB8E45CB47568", "source": "service", "message": "end: handle", "time": "4,777 ms"}
2019-01-28 10:52:21,583 INFO ProcessorService - /fr/service/persistence/form/orbeon/bookshelf - Timing: 5033
2019-01-28 10:52:21,584 INFO lifecycle - event: {"request": "35", "session": "8547B101A7AF9EEE6DFDB8E45CB47568", "source": "service", "message": "end: handle", "time": "5,033 ms"}
2019-01-28 10:52:21,677 INFO lifecycle - event: {"request": "38", "session": "8547B101A7AF9EEE6DFDB8E45CB47568", "source": "service", "message": "start: handle", "path": "/fr/service/persistence/crud/orbeon/bookshelf/form/form.xhtml", "method": "GET"}
2019-01-28 10:52:21,677 INFO ProcessorService - /fr/service/persistence/crud/orbeon/bookshelf/form/form.xhtml - Received request
2019-01-28 10:52:21,735 INFO lifecycle - event: {"request": "39", "session": "8547B101A7AF9EEE6DFDB8E45CB47568", "source": "service", "message": "start: handle", "path": "/fr/service/exist/crud/orbeon/bookshelf/form/form.xhtml", "method": "GET"}
2019-01-28 10:52:21,735 INFO ProcessorService - /fr/service/exist/crud/orbeon/bookshelf/form/form.xhtml - Received request
2019-01-28 10:52:21,993 INFO lifecycle - event: {"request": "40", "session": "4BE3E91834CA67B251446ED282EC4229", "source": "limiter", "message": "start: nofilter", "path": "/exist/rest/db/orbeon/fr/orbeon/bookshelf/form/form.xhtml", "method": "GET"}
2019-01-28 10:52:21,993 WARN auth - not setting credentials headers because credentials are not found
2019-01-28 10:52:22,110 INFO ProcessorService - /fr/service/exist/crud/orbeon/bookshelf/form/form.xhtml - Timing: 374
2019-01-28 10:52:22,110 INFO lifecycle - event: {"request": "39", "session": "8547B101A7AF9EEE6DFDB8E45CB47568", "source": "service", "message": "end: handle", "time": "375 ms"}
2019-01-28 10:52:22,111 INFO lifecycle - event: {"request": "40", "session": "4BE3E91834CA67B251446ED282EC4229", "source": "limiter", "message": "end: nofilter", "time": "118 ms"}
2019-01-28 10:52:22,161 INFO ProcessorService - /fr/service/persistence/crud/orbeon/bookshelf/form/form.xhtml - Timing: 484
2019-01-28 10:52:22,161 INFO lifecycle - event: {"request": "38", "session": "8547B101A7AF9EEE6DFDB8E45CB47568", "source": "service", "message": "end: handle", "time": "484 ms"}
2019-01-28 10:52:22,606 INFO lifecycle - event: {"request": "41", "session": "8547B101A7AF9EEE6DFDB8E45CB47568", "source": "service", "message": "start: handle", "path": "/fr/service/persistence/search/orbeon/bookshelf", "method": "POST"}
2019-01-28 10:52:22,606 INFO ProcessorService - /fr/service/persistence/search/orbeon/bookshelf - Received request
2019-01-28 10:52:22,716 INFO lifecycle - event: {"request": "42", "session": "8547B101A7AF9EEE6DFDB8E45CB47568", "source": "service", "message": "start: handle", "path": "/fr/service/exist/search/orbeon/bookshelf", "method": "POST"}
2019-01-28 10:52:22,716 INFO ProcessorService - /fr/service/exist/search/orbeon/bookshelf - Received request
2019-01-28 10:52:23,215 INFO lifecycle - event: {"request": "43", "session": "8547B101A7AF9EEE6DFDB8E45CB47568", "source": "service", "message": "start: handle", "path": "/fr/service/persistence/form/orbeon/bookshelf", "method": "GET"}
2019-01-28 10:52:23,216 INFO ProcessorService - /fr/service/persistence/form/orbeon/bookshelf - Received request
2019-01-28 10:52:23,297 INFO lifecycle - event: {"request": "44", "session": "8547B101A7AF9EEE6DFDB8E45CB47568", "source": "service", "message": "start: handle", "path": "/fr/service/exist/form/orbeon/bookshelf", "method": "GET"}
2019-01-28 10:52:23,297 INFO ProcessorService - /fr/service/exist/form/orbeon/bookshelf - Received request
2019-01-28 10:52:23,449 INFO lifecycle - event: {"request": "45", "session": "4BE3E91834CA67B251446ED282EC4229", "source": "limiter", "message": "start: nofilter", "path": "/exist/rest/db/orbeon/fr", "method": "POST"}
2019-01-28 10:52:23,450 WARN auth - not setting credentials headers because credentials are not found
2019-01-28 10:52:23,501 INFO lifecycle - event: {"request": "45", "session": "4BE3E91834CA67B251446ED282EC4229", "source": "limiter", "message": "end: nofilter", "time": "52 ms"}
2019-01-28 10:52:23,666 INFO ProcessorService - /fr/service/exist/form/orbeon/bookshelf - Timing: 369
2019-01-28 10:52:23,666 INFO lifecycle - event: {"request": "44", "session": "8547B101A7AF9EEE6DFDB8E45CB47568", "source": "service", "message": "end: handle", "time": "370 ms"}
2019-01-28 10:52:23,673 INFO ProcessorService - /fr/service/persistence/form/orbeon/bookshelf - Timing: 458
2019-01-28 10:52:23,673 INFO lifecycle - event: {"request": "43", "session": "8547B101A7AF9EEE6DFDB8E45CB47568", "source": "service", "message": "end: handle", "time": "458 ms"}
2019-01-28 10:52:23,727 INFO lifecycle - event: {"request": "46", "session": "4BE3E91834CA67B251446ED282EC4229", "source": "limiter", "message": "start: nofilter", "path": "/exist/rest/db/orbeon/fr/orbeon/bookshelf/data/", "method": "POST"}
2019-01-28 10:52:23,727 WARN auth - not setting credentials headers because credentials are not found
2019-01-28 10:52:24,179 INFO lifecycle - event: {"request": "46", "session": "4BE3E91834CA67B251446ED282EC4229", "source": "limiter", "message": "end: nofilter", "time": "449 ms"}
2019-01-28 10:52:24,242 INFO ProcessorService - /fr/service/exist/search/orbeon/bookshelf - Timing: 1525
2019-01-28 10:52:24,243 INFO lifecycle - event: {"request": "42", "session": "8547B101A7AF9EEE6DFDB8E45CB47568", "source": "service", "message": "end: handle", "time": "1,526 ms"}
2019-01-28 10:52:24,245 INFO ProcessorService - /fr/service/persistence/search/orbeon/bookshelf - Timing: 1639
2019-01-28 10:52:24,246 INFO lifecycle - event: {"request": "41", "session": "8547B101A7AF9EEE6DFDB8E45CB47568", "source": "service", "message": "end: handle", "time": "1,640 ms"}
2019-01-28 10:52:24,285 WARN XFormsServer - xxf:instance() - instance not found {instance id: "fr-form-attachments"}
2019-01-28 10:52:25,222 WARN XFormsServer - xxf:instance() - instance not found {instance id: "fr-form-metadata"}
2019-01-28 10:52:25,242 WARN XFormsServer - xxf:instance() - instance not found {instance id: "fr-form-attachments"}
2019-01-28 10:52:25,609 WARN XFormsServer - xxf:instance() - instance not found {instance id: "fr-form-attachments"}
2019-01-28 10:52:27,086 INFO lifecycle - event: {"request": "33", "session": "8547B101A7AF9EEE6DFDB8E45CB47568", "source": "xforms", "message": "after cacheOrStore", "document cache current size": "2", "document cache max size": "50"}
2019-01-28 10:52:27,393 INFO lifecycle - event: {"request": "47", "session": "8547B101A7AF9EEE6DFDB8E45CB47568", "source": "limiter", "message": "start: nofilter", "path": "/xforms-server/form/dynamic/19c5f15ce5e7bcaba67feef82bdb80a3e5a5a446.js", "method": "GET"}
2019-01-28 10:52:27,393 INFO lifecycle - event: {"request": "47", "session": "8547B101A7AF9EEE6DFDB8E45CB47568", "source": "service", "message": "start: handle"}
2019-01-28 10:52:27,394 INFO ProcessorService - /xforms-server/form/dynamic/19c5f15ce5e7bcaba67feef82bdb80a3e5a5a446.js - Received request
2019-01-28 10:52:27,399 INFO lifecycle - event: {"request": "47", "session": "8547B101A7AF9EEE6DFDB8E45CB47568", "source": "xforms", "message": "before document lock", "uuid": "19c5f15ce5e7bcaba67feef82bdb80a3e5a5a446"}
2019-01-28 10:52:27,401 INFO lifecycle - event: {"request": "48", "session": "8547B101A7AF9EEE6DFDB8E45CB47568", "source": "limiter", "message": "start: nofilter", "path": "/xforms-server/orbeon-226d2d8e7828fa85b1a4760300704e75f05c7d06.css", "method": "GET"}
2019-01-28 10:52:27,405 INFO lifecycle - event: {"request": "48", "session": "8547B101A7AF9EEE6DFDB8E45CB47568", "source": "service", "message": "start: handle"}
2019-01-28 10:52:27,405 INFO ProcessorService - /xforms-server/orbeon-226d2d8e7828fa85b1a4760300704e75f05c7d06.css - Received request
2019-01-28 10:52:27,421 INFO lifecycle - event: {"request": "47", "session": "8547B101A7AF9EEE6DFDB8E45CB47568", "source": "xforms", "message": "got document lock", "path": "/xforms-server/form/dynamic/19c5f15ce5e7bcaba67feef82bdb80a3e5a5a446.js", "method": "GET", "uuid": "19c5f15ce5e7bcaba67feef82bdb80a3e5a5a446", "wait": "0"}
2019-01-28 10:52:27,423 INFO lifecycle - event: {"request": "47", "session": "8547B101A7AF9EEE6DFDB8E45CB47568", "source": "xforms", "message": "after cacheOrStore", "document cache current size": "2", "document cache max size": "50"}
2019-01-28 10:52:27,429 INFO ProcessorService - /xforms-server/form/dynamic/19c5f15ce5e7bcaba67feef82bdb80a3e5a5a446.js - Timing: 36
2019-01-28 10:52:27,430 INFO lifecycle - event: {"request": "47", "session": "8547B101A7AF9EEE6DFDB8E45CB47568", "source": "service", "message": "end: handle", "time": "36 ms"}
2019-01-28 10:52:27,430 INFO lifecycle - event: {"request": "47", "session": "8547B101A7AF9EEE6DFDB8E45CB47568", "source": "limiter", "message": "end: nofilter", "time": "38 ms"}
2019-01-28 10:52:27,546 INFO ProcessorService - /xforms-server/orbeon-226d2d8e7828fa85b1a4760300704e75f05c7d06.css - Timing: 141
2019-01-28 10:52:27,546 INFO lifecycle - event: {"request": "48", "session": "8547B101A7AF9EEE6DFDB8E45CB47568", "source": "service", "message": "end: handle", "time": "141 ms"}
2019-01-28 10:52:27,546 INFO lifecycle - event: {"request": "48", "session": "8547B101A7AF9EEE6DFDB8E45CB47568", "source": "limiter", "message": "end: nofilter", "time": "146 ms"}
2019-01-28 10:52:27,648 INFO ProcessorService - /fr/orbeon/bookshelf/summary - Timing: 13579
2019-01-28 10:52:27,649 INFO lifecycle - event: {"request": "33", "session": "8547B101A7AF9EEE6DFDB8E45CB47568", "source": "service", "message": "end: handle", "time": "13,580 ms"}
2019-01-28 10:52:27,649 INFO lifecycle - event: {"request": "33", "session": "8547B101A7AF9EEE6DFDB8E45CB47568", "source": "limiter", "message": "end: chain", "time": "13,623 ms"}
2019-01-28 10:52:27,649 INFO lifecycle - event: {"request": "33", "session": "8547B101A7AF9EEE6DFDB8E45CB47568", "source": "limiter", "message": "end: filter", "time": "13,624 ms"}
2019-01-28 10:52:27,968 INFO lifecycle - event: {"request": "49", "session": "8547B101A7AF9EEE6DFDB8E45CB47568", "source": "limiter", "message": "start: nofilter", "path": "/ops/yui/assets/skins/sam/sprite.png", "method": "GET"}
2019-01-28 10:52:27,969 INFO lifecycle - event: {"request": "49", "session": "8547B101A7AF9EEE6DFDB8E45CB47568", "source": "service", "message": "start: handle"}
2019-01-28 10:52:27,969 INFO ProcessorService - /ops/yui/assets/skins/sam/sprite.png - Received request
2019-01-28 10:52:27,990 INFO ProcessorService - /ops/yui/assets/skins/sam/sprite.png - Timing: 21
2019-01-28 10:52:27,990 INFO lifecycle - event: {"request": "49", "session": "8547B101A7AF9EEE6DFDB8E45CB47568", "source": "service", "message": "end: handle", "time": "21 ms"}
2019-01-28 10:52:27,991 INFO lifecycle - event: {"request": "49", "session": "8547B101A7AF9EEE6DFDB8E45CB47568", "source": "limiter", "message": "end: nofilter", "time": "23 ms"}
2019-01-28 10:52:32,591 INFO lifecycle - event: {"request": "50", "session": "8547B101A7AF9EEE6DFDB8E45CB47568", "source": "limiter", "message": "start: filter", "path": "/xforms-server", "method": "POST"}
2019-01-28 10:52:32,592 INFO lifecycle - event: {"request": "50", "session": "8547B101A7AF9EEE6DFDB8E45CB47568", "source": "limiter", "message": "start: chain", "path": "/xforms-server", "method": "POST", "wait": "0"}
2019-01-28 10:52:32,592 INFO lifecycle - event: {"request": "50", "session": "8547B101A7AF9EEE6DFDB8E45CB47568", "source": "service", "message": "start: handle"}
2019-01-28 10:52:32,593 INFO ProcessorService - /xforms-server - Received request
2019-01-28 10:52:32,791 INFO lifecycle - event: {"request": "50", "session": "8547B101A7AF9EEE6DFDB8E45CB47568", "source": "xforms", "message": "ajax with update events", "uuid": "19c5f15ce5e7bcaba67feef82bdb80a3e5a5a446"}
2019-01-28 10:52:32,791 INFO lifecycle - event: {"request": "50", "session": "8547B101A7AF9EEE6DFDB8E45CB47568", "source": "xforms", "message": "before document lock", "uuid": "19c5f15ce5e7bcaba67feef82bdb80a3e5a5a446"}
2019-01-28 10:52:32,791 INFO lifecycle - event: {"request": "50", "session": "8547B101A7AF9EEE6DFDB8E45CB47568", "source": "xforms", "message": "got document lock", "path": "/xforms-server", "method": "POST", "uuid": "19c5f15ce5e7bcaba67feef82bdb80a3e5a5a446", "wait": "0"}
2019-01-28 10:52:33,359 INFO lifecycle - event: {"request": "50", "session": "8547B101A7AF9EEE6DFDB8E45CB47568", "source": "xforms", "message": "after cacheOrStore", "document cache current size": "2", "document cache max size": "50"}
2019-01-28 10:52:33,360 INFO ProcessorService - /xforms-server - Timing: 767
2019-01-28 10:52:33,360 INFO lifecycle - event: {"request": "50", "session": "8547B101A7AF9EEE6DFDB8E45CB47568", "source": "service", "message": "end: handle", "time": "768 ms"}
2019-01-28 10:52:33,360 INFO lifecycle - event: {"request": "50", "session": "8547B101A7AF9EEE6DFDB8E45CB47568", "source": "limiter", "message": "end: chain", "time": "768 ms"}
2019-01-28 10:52:33,361 INFO lifecycle - event: {"request": "50", "session": "8547B101A7AF9EEE6DFDB8E45CB47568", "source": "limiter", "message": "end: filter", "time": "770 ms"}
2019-01-28 10:52:45,490 INFO lifecycle - event: {"request": "51", "session": "8547B101A7AF9EEE6DFDB8E45CB47568", "source": "limiter", "message": "start: filter", "path": "/fr/logout", "method": "GET"}
2019-01-28 10:52:45,490 INFO lifecycle - event: {"request": "51", "session": "8547B101A7AF9EEE6DFDB8E45CB47568", "source": "limiter", "message": "start: chain", "path": "/fr/logout", "method": "GET", "wait": "0"}
2019-01-28 10:52:45,491 INFO lifecycle - event: {"request": "51", "session": "8547B101A7AF9EEE6DFDB8E45CB47568", "source": "service", "message": "start: handle"}
2019-01-28 10:52:45,491 INFO ProcessorService - /fr/logout - Received request
2019-01-28 10:52:45,695 INFO ProcessorService - Session listener - Session destroyed.
2019-01-28 10:52:45,791 INFO ProcessorService - /fr/logout - Timing: 300
2019-01-28 10:52:45,791 INFO lifecycle - event: {"request": "51", "session": "8547B101A7AF9EEE6DFDB8E45CB47568", "source": "service", "message": "end: handle", "time": "300 ms"}
2019-01-28 10:52:45,791 INFO lifecycle - event: {"request": "51", "source": "limiter", "message": "end: chain", "time": "301 ms"}
2019-01-28 10:52:45,791 INFO lifecycle - event: {"request": "51", "source": "limiter", "message": "end: filter", "time": "301 ms"}
2019-01-28 10:52:45,828 INFO ProcessorService - Session listener - Session created.
2019-01-28 10:52:45,830 WARN auth - not setting credentials headers because credentials are not found
2019-01-28 10:52:45,830 INFO lifecycle - event: {"request": "52", "session": "6CAFDFF802B8A8D1AA658DA08565FA64", "source": "service", "message": "start: handle", "path": "/fr/login", "method": "GET"}
2019-01-28 10:52:45,830 INFO ProcessorService - /fr/login - Received request
2019-01-28 10:52:46,123 INFO lifecycle - event: {"request": "52", "session": "6CAFDFF802B8A8D1AA658DA08565FA64", "source": "xforms", "message": "new form session", "uuid": "e5cc68a4c70b7a8a84299c145ff3d64ed3a341ee"}
2019-01-28 10:52:46,142 WARN XFormsServer - xxf:instance() - instance not found {instance id: "fr-form-attachments"}
2019-01-28 10:52:46,156 WARN XFormsServer - xxf:instance() - instance not found {instance id: "fr-form-resources"}
2019-01-28 10:52:46,157 WARN XFormsServer - xxf:instance() - instance not found {instance id: "fr-form-resources"}
2019-01-28 10:52:46,175 WARN XFormsServer - xxf:instance() - instance not found {instance id: "fr-form-resources"}
2019-01-28 10:52:46,180 WARN XFormsServer - xxf:instance() - instance not found {instance id: "fr-form-resources"}
2019-01-28 10:52:46,182 WARN XFormsServer - xxf:instance() - instance not found {instance id: "fr-form-resources"}
2019-01-28 10:52:46,192 WARN XFormsServer - xxf:instance() - instance not found {instance id: "fr-form-resources"}
2019-01-28 10:52:46,193 WARN XFormsServer - xxf:instance() - instance not found {instance id: "fr-form-resources"}
2019-01-28 10:52:46,279 WARN XFormsServer - xxf:instance() - instance not found {instance id: "fr-form-metadata"}
2019-01-28 10:52:46,294 WARN XFormsServer - xxf:instance() - instance not found {instance id: "fr-form-attachments"}
2019-01-28 10:52:46,360 WARN XFormsServer - xxf:instance() - instance not found {instance id: "fr-form-attachments"}
2019-01-28 10:52:46,624 INFO lifecycle - event: {"request": "52", "session": "6CAFDFF802B8A8D1AA658DA08565FA64", "source": "xforms", "message": "after cacheOrStore", "document cache current size": "1", "document cache max size": "50"}
2019-01-28 10:52:46,788 INFO ProcessorService - /fr/login - Timing: 958
2019-01-28 10:52:46,800 INFO lifecycle - event: {"request": "52", "session": "6CAFDFF802B8A8D1AA658DA08565FA64", "source": "service", "message": "end: handle", "time": "970 ms"}
2019-01-28 10:52:46,976 INFO lifecycle - event: {"request": "53", "session": "6CAFDFF802B8A8D1AA658DA08565FA64", "source": "limiter", "message": "start: nofilter", "path": "/xforms-server/form/dynamic/e5cc68a4c70b7a8a84299c145ff3d64ed3a341ee.js", "method": "GET"}
2019-01-28 10:52:46,976 WARN auth - not setting credentials headers because credentials are not found
2019-01-28 10:52:46,976 INFO lifecycle - event: {"request": "53", "session": "6CAFDFF802B8A8D1AA658DA08565FA64", "source": "service", "message": "start: handle"}
2019-01-28 10:52:46,977 INFO ProcessorService - /xforms-server/form/dynamic/e5cc68a4c70b7a8a84299c145ff3d64ed3a341ee.js - Received request
2019-01-28 10:52:46,979 INFO lifecycle - event: {"request": "53", "session": "6CAFDFF802B8A8D1AA658DA08565FA64", "source": "xforms", "message": "before document lock", "uuid": "e5cc68a4c70b7a8a84299c145ff3d64ed3a341ee"}
2019-01-28 10:52:46,980 INFO lifecycle - event: {"request": "53", "session": "6CAFDFF802B8A8D1AA658DA08565FA64", "source": "xforms", "message": "got document lock", "path": "/xforms-server/form/dynamic/e5cc68a4c70b7a8a84299c145ff3d64ed3a341ee.js", "method": "GET", "uuid": "e5cc68a4c70b7a8a84299c145ff3d64ed3a341ee", "wait": "0"}
2019-01-28 10:52:46,980 INFO lifecycle - event: {"request": "53", "session": "6CAFDFF802B8A8D1AA658DA08565FA64", "source": "xforms", "message": "after cacheOrStore", "document cache current size": "1", "document cache max size": "50"}
2019-01-28 10:52:46,981 INFO ProcessorService - /xforms-server/form/dynamic/e5cc68a4c70b7a8a84299c145ff3d64ed3a341ee.js - Timing: 4
2019-01-28 10:52:46,981 INFO lifecycle - event: {"request": "53", "session": "6CAFDFF802B8A8D1AA658DA08565FA64", "source": "service", "message": "end: handle", "time": "5 ms"}
2019-01-28 10:52:46,982 INFO lifecycle - event: {"request": "53", "session": "6CAFDFF802B8A8D1AA658DA08565FA64", "source": "limiter", "message": "end: nofilter", "time": "6 ms"}
2019-01-28 10:53:08,513 INFO ProcessorService - Servlet destroyed.
2019-01-28 10:53:08,514 INFO QuartzScheduler - Scheduler exist_QuartzScheduler_$_NON_CLUSTERED shutting down.
2019-01-28 10:53:08,515 INFO QuartzScheduler - Scheduler exist_QuartzScheduler_$_NON_CLUSTERED paused.
2019-01-28 10:53:08,517 INFO QuartzScheduler - Scheduler exist_QuartzScheduler_$_NON_CLUSTERED shutdown complete.
2019-01-28 10:53:08,526 INFO FileLock - Deleting lock file: /opt/tomcat-8.5.37_orbeon_default/webapps/orbeon/WEB-INF/exist-data/journal.lck
2019-01-28 10:53:08,543 INFO FileLock - Deleting lock file: /opt/tomcat-8.5.37_orbeon_default/webapps/orbeon/WEB-INF/exist-data/dbx_dir.lck
2019-01-28 10:53:08,544 INFO form-runner-auth - destroying
2019-01-28 10:53:08,544 INFO limiter - destroying
2019-01-28 10:53:08,618 INFO ProcessorService - Context listener - Context destroyed.
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment